{"level":"warn","ts":"2026-08-08T00:43:51.931908Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-08T00:43:51.932011Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-08-08T00:43:51.932564Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-08T00:43:51.932601Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-08-08T00:43:51.932635Z","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-08-08T00:43:51.934455Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-08-08T00:43:51.934704Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-08-08T00:43:51.93773Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.584404ms"} {"level":"info","ts":"2026-08-08T00:43:51.944287Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-08-08T00:43:51.944429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-08-08T00:43:51.944472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-08-08T00:43:51.944488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-08T00:43:51.944516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-08-08T00:43:51.944575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-08-08T00:43:51.949023Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-08T00:43:51.95123Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-08T00:43:51.952613Z","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-08-08T00:43:51.956525Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-08T00:43:51.957392Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-08T00:43:51.957349Z","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-08-08T00:43:51.957877Z","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-08-08T00:43:51.957895Z","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-08-08T00:43:51.958237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-08-08T00:43:51.958814Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-08-08T00:43:51.965039Z","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-08-08T00:43:51.965203Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-08-08T00:43:51.96524Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-08-08T00:43:51.965338Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-08T00:43:51.965415Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-08T00:43:52.845577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-08-08T00:43:52.845728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-08-08T00:43:52.845758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-08-08T00:43:52.845783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-08-08T00:43:52.845796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-08-08T00:43:52.84585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-08-08T00:43:52.845869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-08-08T00:43:52.84699Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-08T00:43:52.847978Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-08T00:43:52.848004Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-08-08T00:43:52.848098Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-08T00:43:52.848473Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-08T00:43:52.848506Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-08T00:43:52.848698Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-08-08T00:43:52.848824Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-08T00:43:52.848853Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-08T00:43:52.851639Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-08-08T00:43:52.85194Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-08T00:44:36.773568Z","caller":"traceutil/trace.go:171","msg":"trace[1353949097] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"115.050785ms","start":"2026-08-08T00:44:36.658484Z","end":"2026-08-08T00:44:36.773535Z","steps":["trace[1353949097] 'process raft request' (duration: 114.873672ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:44:36.794258Z","caller":"traceutil/trace.go:171","msg":"trace[1805004889] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"134.902316ms","start":"2026-08-08T00:44:36.659331Z","end":"2026-08-08T00:44:36.794233Z","steps":["trace[1805004889] 'process raft request' (duration: 134.62946ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:37.271545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.623268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-08T00:44:37.271695Z","caller":"traceutil/trace.go:171","msg":"trace[1558122799] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"425.120042ms","start":"2026-08-08T00:44:36.846559Z","end":"2026-08-08T00:44:37.271679Z","steps":["trace[1558122799] 'read index received' (duration: 27.241µs)","trace[1558122799] 'applied index is now lower than readState.Index' (duration: 425.091161ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:44:37.271743Z","caller":"traceutil/trace.go:171","msg":"trace[58285607] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"470.582454ms","start":"2026-08-08T00:44:36.801134Z","end":"2026-08-08T00:44:37.271716Z","steps":["trace[58285607] 'process raft request' (duration: 38.098559ms)","trace[58285607] 'compare' (duration: 431.28999ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T00:44:37.271892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.318016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dqz7z\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-08-08T00:44:37.271869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:36.801116Z","time spent":"470.703796ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T00:44:37.271929Z","caller":"traceutil/trace.go:171","msg":"trace[1386216557] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dqz7z; range_end:; response_count:1; response_revision:584; }","duration":"425.410068ms","start":"2026-08-08T00:44:36.84651Z","end":"2026-08-08T00:44:37.27192Z","steps":["trace[1386216557] 'agreement among raft nodes before linearized reading' (duration: 425.212344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:37.271957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:36.846491Z","time spent":"425.460899ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-dqz7z\" "} {"level":"info","ts":"2026-08-08T00:44:37.297705Z","caller":"traceutil/trace.go:171","msg":"trace[1853073532] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"427.817788ms","start":"2026-08-08T00:44:36.869857Z","end":"2026-08-08T00:44:37.297675Z","steps":["trace[1853073532] 'process raft request' (duration: 426.45356ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:44:37.297895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:44:36.869819Z","time spent":"427.972801ms","remote":"127.0.0.1:33544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T00:46:10.204891Z","caller":"traceutil/trace.go:171","msg":"trace[1877887459] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"142.859409ms","start":"2026-08-08T00:46:10.061994Z","end":"2026-08-08T00:46:10.204854Z","steps":["trace[1877887459] 'process raft request' (duration: 111.974051ms)","trace[1877887459] 'compare' (duration: 30.712674ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:46:22.034508Z","caller":"traceutil/trace.go:171","msg":"trace[760368808] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"147.064236ms","start":"2026-08-08T00:46:21.887401Z","end":"2026-08-08T00:46:22.034465Z","steps":["trace[760368808] 'process raft request' (duration: 146.704529ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:58.75678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.199859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-08T00:46:58.756998Z","caller":"traceutil/trace.go:171","msg":"trace[926749864] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2139; }","duration":"162.512615ms","start":"2026-08-08T00:46:58.594464Z","end":"2026-08-08T00:46:58.756976Z","steps":["trace[926749864] 'count revisions from in-memory index tree' (duration: 162.093445ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:58.756787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.330387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-08T00:46:58.757143Z","caller":"traceutil/trace.go:171","msg":"trace[1260074503] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2139; }","duration":"142.766747ms","start":"2026-08-08T00:46:58.614319Z","end":"2026-08-08T00:46:58.757086Z","steps":["trace[1260074503] 'range keys from in-memory index tree' (duration: 142.103823ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:46:59.03068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.485564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-08T00:46:59.030809Z","caller":"traceutil/trace.go:171","msg":"trace[1321143424] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2140; }","duration":"147.686698ms","start":"2026-08-08T00:46:58.883097Z","end":"2026-08-08T00:46:59.030784Z","steps":["trace[1321143424] 'range keys from in-memory index tree' (duration: 147.204258ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:48:11.425186Z","caller":"traceutil/trace.go:171","msg":"trace[1026369796] transaction","detail":"{read_only:false; response_revision:2585; number_of_response:1; }","duration":"123.815427ms","start":"2026-08-08T00:48:11.301338Z","end":"2026-08-08T00:48:11.425154Z","steps":["trace[1026369796] 'process raft request' (duration: 123.629613ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:50:56.79285Z","caller":"traceutil/trace.go:171","msg":"trace[987020712] transaction","detail":"{read_only:false; response_revision:3643; number_of_response:1; }","duration":"163.755721ms","start":"2026-08-08T00:50:56.629053Z","end":"2026-08-08T00:50:56.792809Z","steps":["trace[987020712] 'process raft request' (duration: 163.457215ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:52:23.990227Z","caller":"traceutil/trace.go:171","msg":"trace[938754317] transaction","detail":"{read_only:false; response_revision:4895; number_of_response:1; }","duration":"100.732735ms","start":"2026-08-08T00:52:23.889448Z","end":"2026-08-08T00:52:23.990181Z","steps":["trace[938754317] 'process raft request' (duration: 25.469895ms)","trace[938754317] 'compare' (duration: 74.759199ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:52:36.328349Z","caller":"traceutil/trace.go:171","msg":"trace[926969241] linearizableReadLoop","detail":"{readStateIndex:5293; appliedIndex:5292; }","duration":"181.157215ms","start":"2026-08-08T00:52:36.147151Z","end":"2026-08-08T00:52:36.328308Z","steps":["trace[926969241] 'read index received' (duration: 159.382827ms)","trace[926969241] 'applied index is now lower than readState.Index' (duration: 21.772408ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:52:36.328365Z","caller":"traceutil/trace.go:171","msg":"trace[609335285] transaction","detail":"{read_only:false; response_revision:5096; number_of_response:1; }","duration":"246.465138ms","start":"2026-08-08T00:52:36.081846Z","end":"2026-08-08T00:52:36.328311Z","steps":["trace[609335285] 'process raft request' (duration: 224.721331ms)","trace[609335285] 'compare' (duration: 21.40425ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T00:52:36.328853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.660906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T00:52:36.328916Z","caller":"traceutil/trace.go:171","msg":"trace[838711437] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:5096; }","duration":"181.791648ms","start":"2026-08-08T00:52:36.147106Z","end":"2026-08-08T00:52:36.328898Z","steps":["trace[838711437] 'agreement among raft nodes before linearized reading' (duration: 181.426691ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:52:36.328911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.439996ms","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-08-08T00:52:36.329012Z","caller":"traceutil/trace.go:171","msg":"trace[41453113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5096; }","duration":"175.57655ms","start":"2026-08-08T00:52:36.153416Z","end":"2026-08-08T00:52:36.328993Z","steps":["trace[41453113] 'agreement among raft nodes before linearized reading' (duration: 175.216213ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:52:36.328976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.295515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T00:52:36.329087Z","caller":"traceutil/trace.go:171","msg":"trace[1249866089] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:5096; }","duration":"106.482349ms","start":"2026-08-08T00:52:36.222582Z","end":"2026-08-08T00:52:36.329064Z","steps":["trace[1249866089] 'agreement among raft nodes before linearized reading' (duration: 106.253145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:52:43.079413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.368004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-08-08T00:52:43.079533Z","caller":"traceutil/trace.go:171","msg":"trace[137659631] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5268; }","duration":"171.541446ms","start":"2026-08-08T00:52:42.907967Z","end":"2026-08-08T00:52:43.079508Z","steps":["trace[137659631] 'range keys from in-memory index tree' (duration: 171.097458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:52:43.079522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.555476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dde9939f-22d4-4f2c-a550-978f7da364ca\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-08-08T00:52:43.079625Z","caller":"traceutil/trace.go:171","msg":"trace[717882445] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-dde9939f-22d4-4f2c-a550-978f7da364ca; range_end:; response_count:1; response_revision:5268; }","duration":"168.720238ms","start":"2026-08-08T00:52:42.91088Z","end":"2026-08-08T00:52:43.0796Z","steps":["trace[717882445] 'range keys from in-memory index tree' (duration: 168.322991ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:52:43.363073Z","caller":"traceutil/trace.go:171","msg":"trace[1227130190] transaction","detail":"{read_only:false; response_revision:5269; number_of_response:1; }","duration":"214.892399ms","start":"2026-08-08T00:52:43.148152Z","end":"2026-08-08T00:52:43.363044Z","steps":["trace[1227130190] 'process raft request' (duration: 214.664094ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:53:52.878559Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2804} {"level":"info","ts":"2026-08-08T00:53:52.94633Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2804,"took":"66.120489ms","hash":732495387,"current-db-size-bytes":58093568,"current-db-size":"58 MB","current-db-size-in-use-bytes":32964608,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-08T00:53:52.946501Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":732495387,"revision":2804,"compact-revision":-1} {"level":"info","ts":"2026-08-08T00:58:37.582369Z","caller":"traceutil/trace.go:171","msg":"trace[2075533789] linearizableReadLoop","detail":"{readStateIndex:8302; appliedIndex:8301; }","duration":"380.801846ms","start":"2026-08-08T00:58:37.201538Z","end":"2026-08-08T00:58:37.58234Z","steps":["trace[2075533789] 'read index received' (duration: 380.549211ms)","trace[2075533789] 'applied index is now lower than readState.Index' (duration: 251.705µs)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:58:37.582457Z","caller":"traceutil/trace.go:171","msg":"trace[485945494] transaction","detail":"{read_only:false; response_revision:8015; number_of_response:1; }","duration":"393.235141ms","start":"2026-08-08T00:58:37.189206Z","end":"2026-08-08T00:58:37.582441Z","steps":["trace[485945494] 'process raft request' (duration: 392.970996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:58:37.582562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:58:37.189188Z","time spent":"393.307052ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T00:58:37.582685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.993203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T00:58:37.582712Z","caller":"traceutil/trace.go:171","msg":"trace[343851263] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:8015; }","duration":"127.055964ms","start":"2026-08-08T00:58:37.45565Z","end":"2026-08-08T00:58:37.582706Z","steps":["trace[343851263] 'agreement among raft nodes before linearized reading' (duration: 127.012793ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:58:37.582812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.509804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-08T00:58:37.582867Z","caller":"traceutil/trace.go:171","msg":"trace[398158200] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8015; }","duration":"131.600127ms","start":"2026-08-08T00:58:37.451253Z","end":"2026-08-08T00:58:37.582853Z","steps":["trace[398158200] 'agreement among raft nodes before linearized reading' (duration: 131.480064ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:58:37.583389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.27244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-08T00:58:37.583447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.992421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T00:58:37.58347Z","caller":"traceutil/trace.go:171","msg":"trace[1228231990] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8015; }","duration":"268.402663ms","start":"2026-08-08T00:58:37.315052Z","end":"2026-08-08T00:58:37.583454Z","steps":["trace[1228231990] 'agreement among raft nodes before linearized reading' (duration: 267.494783ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:58:37.58349Z","caller":"traceutil/trace.go:171","msg":"trace[1574141361] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:8015; }","duration":"381.952781ms","start":"2026-08-08T00:58:37.201524Z","end":"2026-08-08T00:58:37.583477Z","steps":["trace[1574141361] 'agreement among raft nodes before linearized reading' (duration: 380.96707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:58:37.583531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:58:37.201491Z","time spent":"382.026892ms","remote":"127.0.0.1:45420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"warn","ts":"2026-08-08T00:58:48.866347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.134414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-08T00:58:48.866546Z","caller":"traceutil/trace.go:171","msg":"trace[1889528450] linearizableReadLoop","detail":"{readStateIndex:8367; appliedIndex:8365; }","duration":"298.881906ms","start":"2026-08-08T00:58:48.567643Z","end":"2026-08-08T00:58:48.866525Z","steps":["trace[1889528450] 'read index received' (duration: 197.476797ms)","trace[1889528450] 'applied index is now lower than readState.Index' (duration: 101.403919ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:58:48.866665Z","caller":"traceutil/trace.go:171","msg":"trace[448360774] transaction","detail":"{read_only:false; response_revision:8077; number_of_response:1; }","duration":"366.462282ms","start":"2026-08-08T00:58:48.500188Z","end":"2026-08-08T00:58:48.86665Z","steps":["trace[448360774] 'process raft request' (duration: 366.281608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:58:48.866734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:58:48.500166Z","time spent":"366.532723ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T00:58:48.866694Z","caller":"traceutil/trace.go:171","msg":"trace[566360781] transaction","detail":"{read_only:false; response_revision:8076; number_of_response:1; }","duration":"368.437733ms","start":"2026-08-08T00:58:48.498147Z","end":"2026-08-08T00:58:48.866585Z","steps":["trace[566360781] 'process raft request' (duration: 266.977833ms)","trace[566360781] 'compare' (duration: 100.99304ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T00:58:48.866892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.238155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T00:58:48.866935Z","caller":"traceutil/trace.go:171","msg":"trace[262648234] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8077; }","duration":"299.332227ms","start":"2026-08-08T00:58:48.567589Z","end":"2026-08-08T00:58:48.866921Z","steps":["trace[262648234] 'agreement among raft nodes before linearized reading' (duration: 299.263816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:58:48.867013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:58:48.498124Z","time spent":"368.72462ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T00:58:48.86729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.016046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-08T00:58:48.867389Z","caller":"traceutil/trace.go:171","msg":"trace[245806285] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:8077; }","duration":"131.129638ms","start":"2026-08-08T00:58:48.736232Z","end":"2026-08-08T00:58:48.867362Z","steps":["trace[245806285] 'agreement among raft nodes before linearized reading' (duration: 130.778551ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:58:49.779686Z","caller":"traceutil/trace.go:171","msg":"trace[701216021] linearizableReadLoop","detail":"{readStateIndex:8369; appliedIndex:8368; }","duration":"145.472242ms","start":"2026-08-08T00:58:49.634189Z","end":"2026-08-08T00:58:49.779661Z","steps":["trace[701216021] 'read index received' (duration: 145.260418ms)","trace[701216021] 'applied index is now lower than readState.Index' (duration: 210.634µs)"],"step_count":2} {"level":"warn","ts":"2026-08-08T00:58:49.77993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.730508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-08T00:58:49.779972Z","caller":"traceutil/trace.go:171","msg":"trace[1219532918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8079; }","duration":"145.84539ms","start":"2026-08-08T00:58:49.634116Z","end":"2026-08-08T00:58:49.779961Z","steps":["trace[1219532918] 'agreement among raft nodes before linearized reading' (duration: 145.681277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:58:49.78014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.717353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-08T00:58:49.780143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.441103ms","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-08-08T00:58:49.78018Z","caller":"traceutil/trace.go:171","msg":"trace[1038182692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8079; }","duration":"135.811865ms","start":"2026-08-08T00:58:49.644359Z","end":"2026-08-08T00:58:49.78017Z","steps":["trace[1038182692] 'agreement among raft nodes before linearized reading' (duration: 135.709193ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:58:49.780166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.534587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-08T00:58:49.780228Z","caller":"traceutil/trace.go:171","msg":"trace[658206374] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8079; }","duration":"108.550166ms","start":"2026-08-08T00:58:49.671647Z","end":"2026-08-08T00:58:49.780197Z","steps":["trace[658206374] 'agreement among raft nodes before linearized reading' (duration: 108.332161ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:58:49.780253Z","caller":"traceutil/trace.go:171","msg":"trace[1293500804] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8079; }","duration":"131.756301ms","start":"2026-08-08T00:58:49.648475Z","end":"2026-08-08T00:58:49.780231Z","steps":["trace[1293500804] 'agreement among raft nodes before linearized reading' (duration: 131.501135ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:58:49.780298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.312115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-08T00:58:49.780423Z","caller":"traceutil/trace.go:171","msg":"trace[1629391871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8079; }","duration":"136.474318ms","start":"2026-08-08T00:58:49.643909Z","end":"2026-08-08T00:58:49.780383Z","steps":["trace[1629391871] 'agreement among raft nodes before linearized reading' (duration: 136.1115ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:58:49.780979Z","caller":"traceutil/trace.go:171","msg":"trace[2002828546] transaction","detail":"{read_only:false; response_revision:8079; number_of_response:1; }","duration":"151.334733ms","start":"2026-08-08T00:58:49.629624Z","end":"2026-08-08T00:58:49.780959Z","steps":["trace[2002828546] 'process raft request' (duration: 149.880812ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:58:52.888247Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5904} {"level":"info","ts":"2026-08-08T00:58:52.972509Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5904,"took":"82.280857ms","hash":3993422088,"current-db-size-bytes":58093568,"current-db-size":"58 MB","current-db-size-in-use-bytes":25952256,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-08T00:58:52.972571Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3993422088,"revision":5904,"compact-revision":2804} {"level":"info","ts":"2026-08-08T00:59:33.120507Z","caller":"traceutil/trace.go:171","msg":"trace[132951065] transaction","detail":"{read_only:false; response_revision:8405; number_of_response:1; }","duration":"108.021724ms","start":"2026-08-08T00:59:33.012463Z","end":"2026-08-08T00:59:33.120484Z","steps":["trace[132951065] 'process raft request' (duration: 107.860711ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:59:33.275909Z","caller":"traceutil/trace.go:171","msg":"trace[81309308] linearizableReadLoop","detail":"{readStateIndex:8710; appliedIndex:8708; }","duration":"166.063773ms","start":"2026-08-08T00:59:33.109786Z","end":"2026-08-08T00:59:33.275849Z","steps":["trace[81309308] 'read index received' (duration: 10.545756ms)","trace[81309308] 'applied index is now lower than readState.Index' (duration: 155.515667ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:59:33.276123Z","caller":"traceutil/trace.go:171","msg":"trace[660594900] transaction","detail":"{read_only:false; response_revision:8406; number_of_response:1; }","duration":"257.290394ms","start":"2026-08-08T00:59:33.018758Z","end":"2026-08-08T00:59:33.276048Z","steps":["trace[660594900] 'process raft request' (duration: 256.868335ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:59:33.277628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.278969ms","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-08-08T00:59:33.277713Z","caller":"traceutil/trace.go:171","msg":"trace[79879863] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8406; }","duration":"167.428002ms","start":"2026-08-08T00:59:33.110265Z","end":"2026-08-08T00:59:33.277693Z","steps":["trace[79879863] 'agreement among raft nodes before linearized reading' (duration: 165.8803ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:59:33.277518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.712058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-08T00:59:33.278204Z","caller":"traceutil/trace.go:171","msg":"trace[1891292859] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8406; }","duration":"168.485983ms","start":"2026-08-08T00:59:33.109694Z","end":"2026-08-08T00:59:33.27818Z","steps":["trace[1891292859] 'agreement among raft nodes before linearized reading' (duration: 166.41418ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:59:33.424596Z","caller":"traceutil/trace.go:171","msg":"trace[290479841] transaction","detail":"{read_only:false; response_revision:8407; number_of_response:1; }","duration":"141.703404ms","start":"2026-08-08T00:59:33.282867Z","end":"2026-08-08T00:59:33.42457Z","steps":["trace[290479841] 'process raft request' (duration: 99.409447ms)","trace[290479841] 'compare' (duration: 42.078612ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:59:33.424949Z","caller":"traceutil/trace.go:171","msg":"trace[499405656] transaction","detail":"{read_only:false; response_revision:8408; number_of_response:1; }","duration":"140.853506ms","start":"2026-08-08T00:59:33.284059Z","end":"2026-08-08T00:59:33.424912Z","steps":["trace[499405656] 'process raft request' (duration: 140.444118ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:59:34.401014Z","caller":"traceutil/trace.go:171","msg":"trace[1562684670] linearizableReadLoop","detail":"{readStateIndex:8721; appliedIndex:8720; }","duration":"293.372153ms","start":"2026-08-08T00:59:34.107611Z","end":"2026-08-08T00:59:34.400983Z","steps":["trace[1562684670] 'read index received' (duration: 283.181183ms)","trace[1562684670] 'applied index is now lower than readState.Index' (duration: 10.18931ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T00:59:34.401063Z","caller":"traceutil/trace.go:171","msg":"trace[1073950039] transaction","detail":"{read_only:false; response_revision:8417; number_of_response:1; }","duration":"305.063853ms","start":"2026-08-08T00:59:34.095969Z","end":"2026-08-08T00:59:34.401033Z","steps":["trace[1073950039] 'process raft request' (duration: 294.69887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:59:34.401348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T00:59:34.095955Z","time spent":"305.251126ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T00:59:34.401381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.720879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-08T00:59:34.40145Z","caller":"traceutil/trace.go:171","msg":"trace[1858408910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8417; }","duration":"293.840252ms","start":"2026-08-08T00:59:34.107572Z","end":"2026-08-08T00:59:34.401412Z","steps":["trace[1858408910] 'agreement among raft nodes before linearized reading' (duration: 293.544526ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:59:34.40602Z","caller":"traceutil/trace.go:171","msg":"trace[497177684] transaction","detail":"{read_only:false; response_revision:8418; number_of_response:1; }","duration":"294.542588ms","start":"2026-08-08T00:59:34.111453Z","end":"2026-08-08T00:59:34.405996Z","steps":["trace[497177684] 'process raft request' (duration: 294.354924ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T00:59:34.406243Z","caller":"traceutil/trace.go:171","msg":"trace[637736835] transaction","detail":"{read_only:false; response_revision:8419; number_of_response:1; }","duration":"205.566764ms","start":"2026-08-08T00:59:34.200661Z","end":"2026-08-08T00:59:34.406228Z","steps":["trace[637736835] 'process raft request' (duration: 205.295569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:59:34.406451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.135747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-08T00:59:34.406506Z","caller":"traceutil/trace.go:171","msg":"trace[1475792450] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8419; }","duration":"291.252369ms","start":"2026-08-08T00:59:34.11524Z","end":"2026-08-08T00:59:34.406493Z","steps":["trace[1475792450] 'agreement among raft nodes before linearized reading' (duration: 291.036714ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T00:59:34.406621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.75656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-08T00:59:34.406666Z","caller":"traceutil/trace.go:171","msg":"trace[1164915439] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8419; }","duration":"291.848211ms","start":"2026-08-08T00:59:34.114805Z","end":"2026-08-08T00:59:34.406654Z","steps":["trace[1164915439] 'agreement among raft nodes before linearized reading' (duration: 291.381322ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:00:25.638111Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002432.wal"} {"level":"info","ts":"2026-08-08T01:01:56.514113Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-08T01:01:56.522987Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-08T01:01:56.523133Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-08T01:03:52.894647Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8101} {"level":"info","ts":"2026-08-08T01:03:52.962429Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8101,"took":"65.369265ms","hash":3028724572,"current-db-size-bytes":58093568,"current-db-size":"58 MB","current-db-size-in-use-bytes":29835264,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-08T01:03:52.962491Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3028724572,"revision":8101,"compact-revision":5904} {"level":"info","ts":"2026-08-08T01:04:23.09983Z","caller":"traceutil/trace.go:171","msg":"trace[1429178565] transaction","detail":"{read_only:false; response_revision:10983; number_of_response:1; }","duration":"105.07083ms","start":"2026-08-08T01:04:22.994701Z","end":"2026-08-08T01:04:23.099796Z","steps":["trace[1429178565] 'process raft request' (duration: 104.747663ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:08:52.90055Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10633} {"level":"info","ts":"2026-08-08T01:08:52.984447Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10633,"took":"81.238768ms","hash":4233710423,"current-db-size-bytes":58093568,"current-db-size":"58 MB","current-db-size-in-use-bytes":27729920,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-08T01:08:52.984509Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4233710423,"revision":10633,"compact-revision":8101} {"level":"info","ts":"2026-08-08T01:10:41.037264Z","caller":"traceutil/trace.go:171","msg":"trace[336615236] transaction","detail":"{read_only:false; response_revision:13554; number_of_response:1; }","duration":"112.269175ms","start":"2026-08-08T01:10:40.924969Z","end":"2026-08-08T01:10:41.037238Z","steps":["trace[336615236] 'process raft request' (duration: 111.988479ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:11:15.215768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.155547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-pfll2\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-08-08T01:11:15.215876Z","caller":"traceutil/trace.go:171","msg":"trace[1668258204] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-pfll2; range_end:; response_count:1; response_revision:13744; }","duration":"140.308401ms","start":"2026-08-08T01:11:15.075547Z","end":"2026-08-08T01:11:15.215856Z","steps":["trace[1668258204] 'range keys from in-memory index tree' (duration: 139.950903ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:11:38.924692Z","caller":"traceutil/trace.go:171","msg":"trace[772298569] linearizableReadLoop","detail":"{readStateIndex:14556; appliedIndex:14555; }","duration":"144.992557ms","start":"2026-08-08T01:11:38.779676Z","end":"2026-08-08T01:11:38.924669Z","steps":["trace[772298569] 'read index received' (duration: 144.773472ms)","trace[772298569] 'applied index is now lower than readState.Index' (duration: 218.175µs)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:11:38.924846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.1556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T01:11:38.924878Z","caller":"traceutil/trace.go:171","msg":"trace[608687393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14066; }","duration":"145.211812ms","start":"2026-08-08T01:11:38.779657Z","end":"2026-08-08T01:11:38.924869Z","steps":["trace[608687393] 'agreement among raft nodes before linearized reading' (duration: 145.12809ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:11:38.924911Z","caller":"traceutil/trace.go:171","msg":"trace[119787077] transaction","detail":"{read_only:false; response_revision:14066; number_of_response:1; }","duration":"226.859478ms","start":"2026-08-08T01:11:38.698005Z","end":"2026-08-08T01:11:38.924864Z","steps":["trace[119787077] 'process raft request' (duration: 226.515651ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:13:03.320499Z","caller":"traceutil/trace.go:171","msg":"trace[169631432] linearizableReadLoop","detail":"{readStateIndex:15378; appliedIndex:15377; }","duration":"127.950477ms","start":"2026-08-08T01:13:03.192516Z","end":"2026-08-08T01:13:03.320467Z","steps":["trace[169631432] 'read index received' (duration: 127.014437ms)","trace[169631432] 'applied index is now lower than readState.Index' (duration: 934.93µs)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:13:03.320514Z","caller":"traceutil/trace.go:171","msg":"trace[475353527] transaction","detail":"{read_only:false; response_revision:14866; number_of_response:1; }","duration":"129.52667ms","start":"2026-08-08T01:13:03.190953Z","end":"2026-08-08T01:13:03.32048Z","steps":["trace[475353527] 'process raft request' (duration: 128.59402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:13:03.320843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.296894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T01:13:03.320958Z","caller":"traceutil/trace.go:171","msg":"trace[25822481] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14866; }","duration":"128.452377ms","start":"2026-08-08T01:13:03.192477Z","end":"2026-08-08T01:13:03.320929Z","steps":["trace[25822481] 'agreement among raft nodes before linearized reading' (duration: 128.11204ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:13:03.328224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.569524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T01:13:03.328305Z","caller":"traceutil/trace.go:171","msg":"trace[228954943] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:14867; }","duration":"100.653765ms","start":"2026-08-08T01:13:03.227628Z","end":"2026-08-08T01:13:03.328282Z","steps":["trace[228954943] 'agreement among raft nodes before linearized reading' (duration: 100.522183ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:13:03.328307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.925278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-08-08T01:13:03.328352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.313367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-08-08T01:13:03.328425Z","caller":"traceutil/trace.go:171","msg":"trace[1364851010] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14867; }","duration":"135.394109ms","start":"2026-08-08T01:13:03.193013Z","end":"2026-08-08T01:13:03.328407Z","steps":["trace[1364851010] 'agreement among raft nodes before linearized reading' (duration: 134.996671ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:13:03.32837Z","caller":"traceutil/trace.go:171","msg":"trace[1094141530] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14867; }","duration":"133.061051ms","start":"2026-08-08T01:13:03.195294Z","end":"2026-08-08T01:13:03.328355Z","steps":["trace[1094141530] 'agreement among raft nodes before linearized reading' (duration: 132.879587ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:13:03.571157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.428155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T01:13:03.571261Z","caller":"traceutil/trace.go:171","msg":"trace[1032098685] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14867; }","duration":"107.580248ms","start":"2026-08-08T01:13:03.463655Z","end":"2026-08-08T01:13:03.571236Z","steps":["trace[1032098685] 'range keys from in-memory index tree' (duration: 107.328503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:13:03.571342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.434875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T01:13:03.571447Z","caller":"traceutil/trace.go:171","msg":"trace[1998990702] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14867; }","duration":"107.587838ms","start":"2026-08-08T01:13:03.463836Z","end":"2026-08-08T01:13:03.571424Z","steps":["trace[1998990702] 'range keys from in-memory index tree' (duration: 107.325693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:13:03.571599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.968317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-08T01:13:03.5717Z","caller":"traceutil/trace.go:171","msg":"trace[1959436644] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14867; }","duration":"109.159521ms","start":"2026-08-08T01:13:03.462519Z","end":"2026-08-08T01:13:03.571678Z","steps":["trace[1959436644] 'range keys from in-memory index tree' (duration: 108.854105ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:13:03.571704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.524386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-08T01:13:03.571801Z","caller":"traceutil/trace.go:171","msg":"trace[280885414] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14867; }","duration":"105.660418ms","start":"2026-08-08T01:13:03.466117Z","end":"2026-08-08T01:13:03.571777Z","steps":["trace[280885414] 'range keys from in-memory index tree' (duration: 105.347472ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:13:52.948418Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12858} {"level":"info","ts":"2026-08-08T01:13:53.029513Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12858,"took":"76.864638ms","hash":540459450,"current-db-size-bytes":58093568,"current-db-size":"58 MB","current-db-size-in-use-bytes":29884416,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-08T01:13:53.029581Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":540459450,"revision":12858,"compact-revision":10633} {"level":"warn","ts":"2026-08-08T01:18:29.093562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.622172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T01:18:29.093739Z","caller":"traceutil/trace.go:171","msg":"trace[1981996735] transaction","detail":"{read_only:false; response_revision:17333; number_of_response:1; }","duration":"304.719133ms","start":"2026-08-08T01:18:28.788991Z","end":"2026-08-08T01:18:29.09371Z","steps":["trace[1981996735] 'process raft request' (duration: 55.821135ms)","trace[1981996735] 'compare' (duration: 248.428808ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:18:29.093823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:18:28.788978Z","time spent":"304.806416ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T01:18:29.33697Z","caller":"traceutil/trace.go:171","msg":"trace[1768259555] linearizableReadLoop","detail":"{readStateIndex:17930; appliedIndex:17926; }","duration":"534.742185ms","start":"2026-08-08T01:18:28.802201Z","end":"2026-08-08T01:18:29.336943Z","steps":["trace[1768259555] 'read index received' (duration: 42.616875ms)","trace[1768259555] 'applied index is now lower than readState.Index' (duration: 492.1245ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:18:29.337079Z","caller":"traceutil/trace.go:171","msg":"trace[38731001] transaction","detail":"{read_only:false; response_revision:17335; number_of_response:1; }","duration":"546.19543ms","start":"2026-08-08T01:18:28.790874Z","end":"2026-08-08T01:18:29.33707Z","steps":["trace[38731001] 'process raft request' (duration: 546.001776ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:18:29.337176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:18:28.790847Z","time spent":"546.252401ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T01:18:29.33727Z","caller":"traceutil/trace.go:171","msg":"trace[165387445] transaction","detail":"{read_only:false; response_revision:17336; number_of_response:1; }","duration":"546.333673ms","start":"2026-08-08T01:18:28.790909Z","end":"2026-08-08T01:18:29.337242Z","steps":["trace[165387445] 'process raft request' (duration: 545.998526ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:18:29.337397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:18:28.790893Z","time spent":"546.436115ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T01:18:29.337542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.305397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-7b4mz\" ","response":"range_response_count:1 size:22129"} {"level":"info","ts":"2026-08-08T01:18:29.337631Z","caller":"traceutil/trace.go:171","msg":"trace[1355622390] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-7b4mz; range_end:; response_count:1; response_revision:17336; }","duration":"535.4483ms","start":"2026-08-08T01:18:28.802157Z","end":"2026-08-08T01:18:29.337605Z","steps":["trace[1355622390] 'agreement among raft nodes before linearized reading' (duration: 535.125843ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:18:29.337683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:18:28.802146Z","time spent":"535.525772ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22152,"request content":"key:\"/registry/pods/openstack/nova-compute-default-7b4mz\" "} {"level":"info","ts":"2026-08-08T01:18:29.337019Z","caller":"traceutil/trace.go:171","msg":"trace[1949283176] transaction","detail":"{read_only:false; response_revision:17334; number_of_response:1; }","duration":"547.125929ms","start":"2026-08-08T01:18:28.789839Z","end":"2026-08-08T01:18:29.336965Z","steps":["trace[1949283176] 'process raft request' (duration: 499.474881ms)","trace[1949283176] 'compare' (duration: 47.427053ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:18:29.338753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:18:28.789832Z","time spent":"548.63212ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T01:18:29.343351Z","caller":"traceutil/trace.go:171","msg":"trace[1603798363] transaction","detail":"{read_only:false; response_revision:17337; number_of_response:1; }","duration":"314.827652ms","start":"2026-08-08T01:18:29.0285Z","end":"2026-08-08T01:18:29.343327Z","steps":["trace[1603798363] 'process raft request' (duration: 314.681489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:18:29.343457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:18:29.028482Z","time spent":"314.916963ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T01:18:29.343825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.905471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-08-08T01:18:29.343896Z","caller":"traceutil/trace.go:171","msg":"trace[1908051125] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:17337; }","duration":"405.016303ms","start":"2026-08-08T01:18:28.938861Z","end":"2026-08-08T01:18:29.343877Z","steps":["trace[1908051125] 'agreement among raft nodes before linearized reading' (duration: 404.8634ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:18:29.343909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.690143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-08-08T01:18:29.343939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:18:28.938836Z","time spent":"405.090724ms","remote":"127.0.0.1:33550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-08-08T01:18:29.343977Z","caller":"traceutil/trace.go:171","msg":"trace[990127729] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:17337; }","duration":"360.796185ms","start":"2026-08-08T01:18:28.983165Z","end":"2026-08-08T01:18:29.343961Z","steps":["trace[990127729] 'agreement among raft nodes before linearized reading' (duration: 360.57614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:18:29.344012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:18:28.983155Z","time spent":"360.850087ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-08-08T01:18:52.954793Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15181} {"level":"info","ts":"2026-08-08T01:18:53.052466Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15181,"took":"93.861707ms","hash":514763760,"current-db-size-bytes":58093568,"current-db-size":"58 MB","current-db-size-in-use-bytes":32088064,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-08T01:18:53.052562Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":514763760,"revision":15181,"compact-revision":12858} {"level":"info","ts":"2026-08-08T01:21:53.393141Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-08T01:21:53.397955Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-08T01:21:53.398053Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-08T01:23:52.959694Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17618} {"level":"info","ts":"2026-08-08T01:23:53.058153Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17618,"took":"92.271449ms","hash":280337217,"current-db-size-bytes":58093568,"current-db-size":"58 MB","current-db-size-in-use-bytes":35033088,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-08T01:23:53.058225Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":280337217,"revision":17618,"compact-revision":15181} {"level":"warn","ts":"2026-08-08T01:26:03.632054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.306053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T01:26:03.632619Z","caller":"traceutil/trace.go:171","msg":"trace[104648922] transaction","detail":"{read_only:false; response_revision:21108; number_of_response:1; }","duration":"333.818522ms","start":"2026-08-08T01:26:03.298749Z","end":"2026-08-08T01:26:03.632568Z","steps":["trace[104648922] 'process raft request' (duration: 121.864316ms)","trace[104648922] 'compare' (duration: 211.147949ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:26:03.632852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:26:03.298725Z","time spent":"333.988205ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T01:26:03.933788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.839724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T01:26:03.933981Z","caller":"traceutil/trace.go:171","msg":"trace[1558450338] transaction","detail":"{read_only:false; response_revision:21109; number_of_response:1; }","duration":"290.948565ms","start":"2026-08-08T01:26:03.64299Z","end":"2026-08-08T01:26:03.933938Z","steps":["trace[1558450338] 'process raft request' (duration: 152.824135ms)","trace[1558450338] 'compare' (duration: 137.499228ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:26:03.933921Z","caller":"traceutil/trace.go:171","msg":"trace[1513045299] linearizableReadLoop","detail":"{readStateIndex:21819; appliedIndex:21818; }","duration":"154.221242ms","start":"2026-08-08T01:26:03.77968Z","end":"2026-08-08T01:26:03.933901Z","steps":["trace[1513045299] 'read index received' (duration: 16.125433ms)","trace[1513045299] 'applied index is now lower than readState.Index' (duration: 138.094379ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:26:03.934518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.813513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:26:03.934657Z","caller":"traceutil/trace.go:171","msg":"trace[1244221019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21109; }","duration":"154.987997ms","start":"2026-08-08T01:26:03.779608Z","end":"2026-08-08T01:26:03.934596Z","steps":["trace[1244221019] 'agreement among raft nodes before linearized reading' (duration: 154.753342ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:26:04.234853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.678402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T01:26:04.234967Z","caller":"traceutil/trace.go:171","msg":"trace[1335933489] linearizableReadLoop","detail":"{readStateIndex:21820; appliedIndex:21819; }","duration":"261.776842ms","start":"2026-08-08T01:26:03.97317Z","end":"2026-08-08T01:26:04.234947Z","steps":["trace[1335933489] 'read index received' (duration: 97.892376ms)","trace[1335933489] 'applied index is now lower than readState.Index' (duration: 163.883016ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:26:04.23509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.912795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-08T01:26:04.235065Z","caller":"traceutil/trace.go:171","msg":"trace[608363942] transaction","detail":"{read_only:false; response_revision:21110; number_of_response:1; }","duration":"270.978665ms","start":"2026-08-08T01:26:03.964037Z","end":"2026-08-08T01:26:04.235016Z","steps":["trace[608363942] 'process raft request' (duration: 107.031648ms)","trace[608363942] 'compare' (duration: 163.538389ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:26:04.235129Z","caller":"traceutil/trace.go:171","msg":"trace[1190420793] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:21110; }","duration":"261.963335ms","start":"2026-08-08T01:26:03.973152Z","end":"2026-08-08T01:26:04.235116Z","steps":["trace[1190420793] 'agreement among raft nodes before linearized reading' (duration: 261.892314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:26:04.235448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.620969ms","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-08-08T01:26:04.235475Z","caller":"traceutil/trace.go:171","msg":"trace[600715723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21110; }","duration":"234.749521ms","start":"2026-08-08T01:26:04.000718Z","end":"2026-08-08T01:26:04.235467Z","steps":["trace[600715723] 'agreement among raft nodes before linearized reading' (duration: 234.627899ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:26:04.235779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.226861ms","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-08-08T01:26:04.235876Z","caller":"traceutil/trace.go:171","msg":"trace[1341583579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21110; }","duration":"232.374544ms","start":"2026-08-08T01:26:04.00348Z","end":"2026-08-08T01:26:04.235854Z","steps":["trace[1341583579] 'agreement among raft nodes before linearized reading' (duration: 231.973226ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:26:04.63089Z","caller":"traceutil/trace.go:171","msg":"trace[529344582] transaction","detail":"{read_only:false; response_revision:21114; number_of_response:1; }","duration":"185.941376ms","start":"2026-08-08T01:26:04.444914Z","end":"2026-08-08T01:26:04.630855Z","steps":["trace[529344582] 'process raft request' (duration: 185.725452ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:26:04.758268Z","caller":"traceutil/trace.go:171","msg":"trace[860583192] transaction","detail":"{read_only:false; response_revision:21115; number_of_response:1; }","duration":"311.682609ms","start":"2026-08-08T01:26:04.446538Z","end":"2026-08-08T01:26:04.758221Z","steps":["trace[860583192] 'process raft request' (duration: 310.535636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:26:04.758531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:26:04.446504Z","time spent":"311.880094ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T01:26:04.758727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.427746ms","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-08-08T01:26:04.75889Z","caller":"traceutil/trace.go:171","msg":"trace[1998396281] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21115; }","duration":"225.65494ms","start":"2026-08-08T01:26:04.533206Z","end":"2026-08-08T01:26:04.758861Z","steps":["trace[1998396281] 'agreement among raft nodes before linearized reading' (duration: 225.107989ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:26:04.758027Z","caller":"traceutil/trace.go:171","msg":"trace[1766971217] linearizableReadLoop","detail":"{readStateIndex:21825; appliedIndex:21823; }","duration":"224.68636ms","start":"2026-08-08T01:26:04.533273Z","end":"2026-08-08T01:26:04.757959Z","steps":["trace[1766971217] 'read index received' (duration: 97.410236ms)","trace[1766971217] 'applied index is now lower than readState.Index' (duration: 127.274244ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:28:52.966162Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20129} {"level":"info","ts":"2026-08-08T01:28:53.082277Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20129,"took":"109.557793ms","hash":3228469634,"current-db-size-bytes":58093568,"current-db-size":"58 MB","current-db-size-in-use-bytes":31354880,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-08T01:28:53.082347Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3228469634,"revision":20129,"compact-revision":17618} {"level":"info","ts":"2026-08-08T01:29:44.156688Z","caller":"traceutil/trace.go:171","msg":"trace[608944183] transaction","detail":"{read_only:false; response_revision:23302; number_of_response:1; }","duration":"112.546133ms","start":"2026-08-08T01:29:44.044121Z","end":"2026-08-08T01:29:44.156667Z","steps":["trace[608944183] 'process raft request' (duration: 111.996492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:29:44.157401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.220974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/openstackfloatingippools.infrastructure.cluster.x-k8s.io\" ","response":"range_response_count:1 size:10419"} {"level":"info","ts":"2026-08-08T01:29:44.157456Z","caller":"traceutil/trace.go:171","msg":"trace[943054249] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/openstackfloatingippools.infrastructure.cluster.x-k8s.io; range_end:; response_count:1; response_revision:23303; }","duration":"104.346466ms","start":"2026-08-08T01:29:44.053096Z","end":"2026-08-08T01:29:44.157443Z","steps":["trace[943054249] 'agreement among raft nodes before linearized reading' (duration: 104.174073ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:29:44.157667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.105926ms","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-08-08T01:29:44.157705Z","caller":"traceutil/trace.go:171","msg":"trace[1981424554] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23303; }","duration":"116.181308ms","start":"2026-08-08T01:29:44.041515Z","end":"2026-08-08T01:29:44.157696Z","steps":["trace[1981424554] 'agreement among raft nodes before linearized reading' (duration: 116.081736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:29:44.158063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.651626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:111931"} {"level":"info","ts":"2026-08-08T01:29:44.15809Z","caller":"traceutil/trace.go:171","msg":"trace[373752706] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:23303; }","duration":"134.716108ms","start":"2026-08-08T01:29:44.023368Z","end":"2026-08-08T01:29:44.158084Z","steps":["trace[373752706] 'agreement among raft nodes before linearized reading' (duration: 134.517624ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:32:42.460423Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000065d0.wal"} {"level":"warn","ts":"2026-08-08T01:33:14.968634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.51473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T01:33:14.968691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.228735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-08T01:33:14.968691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.294026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-08-08T01:33:14.968771Z","caller":"traceutil/trace.go:171","msg":"trace[164153108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25598; }","duration":"189.653362ms","start":"2026-08-08T01:33:14.779073Z","end":"2026-08-08T01:33:14.968727Z","steps":["trace[164153108] 'range keys from in-memory index tree' (duration: 189.456529ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:33:14.968823Z","caller":"traceutil/trace.go:171","msg":"trace[1938352988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25598; }","duration":"214.395829ms","start":"2026-08-08T01:33:14.754398Z","end":"2026-08-08T01:33:14.968794Z","steps":["trace[1938352988] 'range keys from in-memory index tree' (duration: 214.039531ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:33:14.968823Z","caller":"traceutil/trace.go:171","msg":"trace[437670836] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:25598; }","duration":"190.474009ms","start":"2026-08-08T01:33:14.778325Z","end":"2026-08-08T01:33:14.968799Z","steps":["trace[437670836] 'range keys from in-memory index tree' (duration: 190.118182ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:33:14.96878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.459798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:33:14.968931Z","caller":"traceutil/trace.go:171","msg":"trace[444218135] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:25598; }","duration":"252.728933ms","start":"2026-08-08T01:33:14.716172Z","end":"2026-08-08T01:33:14.968901Z","steps":["trace[444218135] 'count revisions from in-memory index tree' (duration: 252.296395ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:33:14.969085Z","caller":"traceutil/trace.go:171","msg":"trace[1575423384] transaction","detail":"{read_only:false; response_revision:25599; number_of_response:1; }","duration":"123.669102ms","start":"2026-08-08T01:33:14.845369Z","end":"2026-08-08T01:33:14.969038Z","steps":["trace[1575423384] 'process raft request' (duration: 66.080323ms)","trace[1575423384] 'compare' (duration: 56.987587ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:33:14.969244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.801748ms","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-08-08T01:33:14.969314Z","caller":"traceutil/trace.go:171","msg":"trace[1331946876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25598; }","duration":"217.932851ms","start":"2026-08-08T01:33:14.751367Z","end":"2026-08-08T01:33:14.9693Z","steps":["trace[1331946876] 'range keys from in-memory index tree' (duration: 216.812619ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:33:15.20914Z","caller":"traceutil/trace.go:171","msg":"trace[741674384] transaction","detail":"{read_only:false; response_revision:25600; number_of_response:1; }","duration":"234.939409ms","start":"2026-08-08T01:33:14.974165Z","end":"2026-08-08T01:33:15.209105Z","steps":["trace[741674384] 'process raft request' (duration: 211.435778ms)","trace[741674384] 'compare' (duration: 23.315278ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:33:15.228485Z","caller":"traceutil/trace.go:171","msg":"trace[488201538] transaction","detail":"{read_only:false; response_revision:25601; number_of_response:1; }","duration":"254.248565ms","start":"2026-08-08T01:33:14.974206Z","end":"2026-08-08T01:33:15.228455Z","steps":["trace[488201538] 'process raft request' (duration: 254.054081ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:33:15.22897Z","caller":"traceutil/trace.go:171","msg":"trace[1152822499] transaction","detail":"{read_only:false; response_revision:25602; number_of_response:1; }","duration":"223.281682ms","start":"2026-08-08T01:33:15.005677Z","end":"2026-08-08T01:33:15.228959Z","steps":["trace[1152822499] 'process raft request' (duration: 222.733661ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:33:15.229014Z","caller":"traceutil/trace.go:171","msg":"trace[1726911897] linearizableReadLoop","detail":"{readStateIndex:26436; appliedIndex:26433; }","duration":"200.132846ms","start":"2026-08-08T01:33:15.028838Z","end":"2026-08-08T01:33:15.228971Z","steps":["trace[1726911897] 'read index received' (duration: 156.82427ms)","trace[1726911897] 'applied index is now lower than readState.Index' (duration: 43.307076ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:33:15.229146Z","caller":"traceutil/trace.go:171","msg":"trace[2080398016] transaction","detail":"{read_only:false; response_revision:25603; number_of_response:1; }","duration":"134.805729ms","start":"2026-08-08T01:33:15.09433Z","end":"2026-08-08T01:33:15.229136Z","steps":["trace[2080398016] 'process raft request' (duration: 134.460653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:33:15.229322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.524902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:33:15.229361Z","caller":"traceutil/trace.go:171","msg":"trace[1872062169] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:25603; }","duration":"109.678475ms","start":"2026-08-08T01:33:15.11967Z","end":"2026-08-08T01:33:15.229348Z","steps":["trace[1872062169] 'agreement among raft nodes before linearized reading' (duration: 109.552592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:33:15.22942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.547505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-08-08T01:33:15.229477Z","caller":"traceutil/trace.go:171","msg":"trace[610348861] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25603; }","duration":"200.666917ms","start":"2026-08-08T01:33:15.028794Z","end":"2026-08-08T01:33:15.229461Z","steps":["trace[610348861] 'agreement among raft nodes before linearized reading' (duration: 200.360301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:33:15.229673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.437699ms","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-08-08T01:33:15.229754Z","caller":"traceutil/trace.go:171","msg":"trace[897683033] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25603; }","duration":"107.544642ms","start":"2026-08-08T01:33:15.122192Z","end":"2026-08-08T01:33:15.229737Z","steps":["trace[897683033] 'agreement among raft nodes before linearized reading' (duration: 107.301647ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:33:25.126589Z","caller":"traceutil/trace.go:171","msg":"trace[1765777228] transaction","detail":"{read_only:false; response_revision:25674; number_of_response:1; }","duration":"121.391974ms","start":"2026-08-08T01:33:25.005167Z","end":"2026-08-08T01:33:25.126559Z","steps":["trace[1765777228] 'process raft request' (duration: 121.074308ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:33:25.501114Z","caller":"traceutil/trace.go:171","msg":"trace[2089507660] transaction","detail":"{read_only:false; response_revision:25678; number_of_response:1; }","duration":"168.277875ms","start":"2026-08-08T01:33:25.332807Z","end":"2026-08-08T01:33:25.501085Z","steps":["trace[2089507660] 'process raft request' (duration: 168.194203ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:33:25.501376Z","caller":"traceutil/trace.go:171","msg":"trace[132247329] transaction","detail":"{read_only:false; response_revision:25677; number_of_response:1; }","duration":"225.640849ms","start":"2026-08-08T01:33:25.275721Z","end":"2026-08-08T01:33:25.501362Z","steps":["trace[132247329] 'process raft request' (duration: 133.138156ms)","trace[132247329] 'compare' (duration: 91.971682ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:33:25.501413Z","caller":"traceutil/trace.go:171","msg":"trace[1354964395] linearizableReadLoop","detail":"{readStateIndex:26514; appliedIndex:26512; }","duration":"142.65301ms","start":"2026-08-08T01:33:25.358724Z","end":"2026-08-08T01:33:25.501377Z","steps":["trace[1354964395] 'read index received' (duration: 50.152177ms)","trace[1354964395] 'applied index is now lower than readState.Index' (duration: 92.498853ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:33:25.501695Z","caller":"traceutil/trace.go:171","msg":"trace[1802232623] transaction","detail":"{read_only:false; response_revision:25679; number_of_response:1; }","duration":"134.426462ms","start":"2026-08-08T01:33:25.367254Z","end":"2026-08-08T01:33:25.50168Z","steps":["trace[1802232623] 'process raft request' (duration: 133.783469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:33:25.501905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.16376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:33:25.501964Z","caller":"traceutil/trace.go:171","msg":"trace[1839870776] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:25679; }","duration":"143.248402ms","start":"2026-08-08T01:33:25.358701Z","end":"2026-08-08T01:33:25.501949Z","steps":["trace[1839870776] 'agreement among raft nodes before linearized reading' (duration: 143.13476ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:33:25.502262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.366792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-08-08T01:33:25.502298Z","caller":"traceutil/trace.go:171","msg":"trace[613954219] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25679; }","duration":"137.431003ms","start":"2026-08-08T01:33:25.364859Z","end":"2026-08-08T01:33:25.50229Z","steps":["trace[613954219] 'agreement among raft nodes before linearized reading' (duration: 137.29876ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:33:25.852423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.094714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:33:25.852531Z","caller":"traceutil/trace.go:171","msg":"trace[361426296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25681; }","duration":"197.32112ms","start":"2026-08-08T01:33:25.655186Z","end":"2026-08-08T01:33:25.852508Z","steps":["trace[361426296] 'range keys from in-memory index tree' (duration: 196.705777ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:33:26.277862Z","caller":"traceutil/trace.go:171","msg":"trace[1305620653] linearizableReadLoop","detail":"{readStateIndex:26518; appliedIndex:26517; }","duration":"145.177743ms","start":"2026-08-08T01:33:26.132646Z","end":"2026-08-08T01:33:26.277823Z","steps":["trace[1305620653] 'read index received' (duration: 144.662072ms)","trace[1305620653] 'applied index is now lower than readState.Index' (duration: 513.941µs)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:33:26.278065Z","caller":"traceutil/trace.go:171","msg":"trace[109460517] transaction","detail":"{read_only:false; response_revision:25682; number_of_response:1; }","duration":"188.692112ms","start":"2026-08-08T01:33:26.089316Z","end":"2026-08-08T01:33:26.278008Z","steps":["trace[109460517] 'process raft request' (duration: 188.254924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:33:26.278237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.54613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:33:26.27842Z","caller":"traceutil/trace.go:171","msg":"trace[1803452711] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:25682; }","duration":"145.774634ms","start":"2026-08-08T01:33:26.132623Z","end":"2026-08-08T01:33:26.278397Z","steps":["trace[1803452711] 'agreement among raft nodes before linearized reading' (duration: 145.386746ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:33:26.504634Z","caller":"traceutil/trace.go:171","msg":"trace[2100480055] transaction","detail":"{read_only:false; response_revision:25685; number_of_response:1; }","duration":"141.197631ms","start":"2026-08-08T01:33:26.363365Z","end":"2026-08-08T01:33:26.504563Z","steps":["trace[2100480055] 'process raft request' (duration: 74.650378ms)","trace[2100480055] 'compare' (duration: 66.362749ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:33:26.785759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.585654ms","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-08-08T01:33:26.785854Z","caller":"traceutil/trace.go:171","msg":"trace[1934267594] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25685; }","duration":"109.754197ms","start":"2026-08-08T01:33:26.676079Z","end":"2026-08-08T01:33:26.785833Z","steps":["trace[1934267594] 'range keys from in-memory index tree' (duration: 109.367729ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:33:26.785872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.930618ms","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-08-08T01:33:26.785991Z","caller":"traceutil/trace.go:171","msg":"trace[1804632031] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25685; }","duration":"105.150893ms","start":"2026-08-08T01:33:26.680814Z","end":"2026-08-08T01:33:26.785965Z","steps":["trace[1804632031] 'range keys from in-memory index tree' (duration: 104.638442ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:33:36.221337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.331206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:33:36.221471Z","caller":"traceutil/trace.go:171","msg":"trace[1564996772] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:25811; }","duration":"131.5224ms","start":"2026-08-08T01:33:36.089904Z","end":"2026-08-08T01:33:36.221426Z","steps":["trace[1564996772] 'count revisions from in-memory index tree' (duration: 131.225674ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:33:36.22194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.375405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:671"} {"level":"info","ts":"2026-08-08T01:33:36.221982Z","caller":"traceutil/trace.go:171","msg":"trace[2075044675] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25811; }","duration":"178.477167ms","start":"2026-08-08T01:33:36.043495Z","end":"2026-08-08T01:33:36.221972Z","steps":["trace[2075044675] 'range keys from in-memory index tree' (duration: 178.192341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:33:36.222001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.914344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:671"} {"level":"info","ts":"2026-08-08T01:33:36.222208Z","caller":"traceutil/trace.go:171","msg":"trace[2077487382] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25811; }","duration":"174.144328ms","start":"2026-08-08T01:33:36.048009Z","end":"2026-08-08T01:33:36.222153Z","steps":["trace[2077487382] 'range keys from in-memory index tree' (duration: 173.776701ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:33:36.222467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.037128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:671"} {"level":"info","ts":"2026-08-08T01:33:36.222596Z","caller":"traceutil/trace.go:171","msg":"trace[756027014] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25811; }","duration":"179.183921ms","start":"2026-08-08T01:33:36.043383Z","end":"2026-08-08T01:33:36.222567Z","steps":["trace[756027014] 'range keys from in-memory index tree' (duration: 177.808804ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:33:36.222796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.181321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3cda1031-f268-4fb3-a03a-5f94e9d4a85e\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-08-08T01:33:36.22308Z","caller":"traceutil/trace.go:171","msg":"trace[301469148] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3cda1031-f268-4fb3-a03a-5f94e9d4a85e; range_end:; response_count:1; response_revision:25811; }","duration":"208.484898ms","start":"2026-08-08T01:33:36.014572Z","end":"2026-08-08T01:33:36.223056Z","steps":["trace[301469148] 'range keys from in-memory index tree' (duration: 207.979088ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:33:36.400819Z","caller":"traceutil/trace.go:171","msg":"trace[571012739] transaction","detail":"{read_only:false; response_revision:25812; number_of_response:1; }","duration":"157.567952ms","start":"2026-08-08T01:33:36.243206Z","end":"2026-08-08T01:33:36.400774Z","steps":["trace[571012739] 'process raft request' (duration: 111.584624ms)","trace[571012739] 'compare' (duration: 45.657111ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:33:52.974517Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22412} {"level":"info","ts":"2026-08-08T01:33:53.094449Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22412,"took":"115.624116ms","hash":3642807532,"current-db-size-bytes":58515456,"current-db-size":"58 MB","current-db-size-in-use-bytes":46780416,"current-db-size-in-use":"47 MB"} {"level":"info","ts":"2026-08-08T01:33:53.094531Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3642807532,"revision":22412,"compact-revision":20129} {"level":"info","ts":"2026-08-08T01:34:12.696854Z","caller":"traceutil/trace.go:171","msg":"trace[128700794] transaction","detail":"{read_only:false; response_revision:26124; number_of_response:1; }","duration":"129.731604ms","start":"2026-08-08T01:34:12.567085Z","end":"2026-08-08T01:34:12.696817Z","steps":["trace[128700794] 'process raft request' (duration: 129.414387ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:34:12.982955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.749788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T01:34:12.983419Z","caller":"traceutil/trace.go:171","msg":"trace[1837245548] transaction","detail":"{read_only:false; response_revision:26125; number_of_response:1; }","duration":"298.473712ms","start":"2026-08-08T01:34:12.68491Z","end":"2026-08-08T01:34:12.983384Z","steps":["trace[1837245548] 'process raft request' (duration: 158.171452ms)","trace[1837245548] 'compare' (duration: 139.436352ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:34:12.983441Z","caller":"traceutil/trace.go:171","msg":"trace[1608266065] transaction","detail":"{read_only:false; response_revision:26126; number_of_response:1; }","duration":"281.835583ms","start":"2026-08-08T01:34:12.701566Z","end":"2026-08-08T01:34:12.983401Z","steps":["trace[1608266065] 'process raft request' (duration: 281.518475ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:34:13.280507Z","caller":"traceutil/trace.go:171","msg":"trace[2061386990] transaction","detail":"{read_only:false; response_revision:26127; number_of_response:1; }","duration":"126.064319ms","start":"2026-08-08T01:34:13.154411Z","end":"2026-08-08T01:34:13.280475Z","steps":["trace[2061386990] 'process raft request' (duration: 125.849564ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:34:13.289323Z","caller":"traceutil/trace.go:171","msg":"trace[887988982] transaction","detail":"{read_only:false; response_revision:26129; number_of_response:1; }","duration":"124.519927ms","start":"2026-08-08T01:34:13.164781Z","end":"2026-08-08T01:34:13.289301Z","steps":["trace[887988982] 'process raft request' (duration: 124.469906ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:34:13.289496Z","caller":"traceutil/trace.go:171","msg":"trace[98451604] transaction","detail":"{read_only:false; response_revision:26128; number_of_response:1; }","duration":"125.756042ms","start":"2026-08-08T01:34:13.163693Z","end":"2026-08-08T01:34:13.289449Z","steps":["trace[98451604] 'process raft request' (duration: 125.470887ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:34:13.498365Z","caller":"traceutil/trace.go:171","msg":"trace[214926331] transaction","detail":"{read_only:false; response_revision:26130; number_of_response:1; }","duration":"168.947962ms","start":"2026-08-08T01:34:13.329379Z","end":"2026-08-08T01:34:13.498327Z","steps":["trace[214926331] 'process raft request' (duration: 168.682276ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:34:13.737411Z","caller":"traceutil/trace.go:171","msg":"trace[1113788333] transaction","detail":"{read_only:false; response_revision:26131; number_of_response:1; }","duration":"265.51835ms","start":"2026-08-08T01:34:13.471863Z","end":"2026-08-08T01:34:13.737381Z","steps":["trace[1113788333] 'process raft request' (duration: 265.357397ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:34:13.870742Z","caller":"traceutil/trace.go:171","msg":"trace[6753119] linearizableReadLoop","detail":"{readStateIndex:26981; appliedIndex:26979; }","duration":"138.543792ms","start":"2026-08-08T01:34:13.732162Z","end":"2026-08-08T01:34:13.870706Z","steps":["trace[6753119] 'read index received' (duration: 4.981171ms)","trace[6753119] 'applied index is now lower than readState.Index' (duration: 133.561451ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:34:13.870909Z","caller":"traceutil/trace.go:171","msg":"trace[1798901760] transaction","detail":"{read_only:false; response_revision:26132; number_of_response:1; }","duration":"362.126078ms","start":"2026-08-08T01:34:13.508769Z","end":"2026-08-08T01:34:13.870895Z","steps":["trace[1798901760] 'process raft request' (duration: 346.898168ms)","trace[1798901760] 'compare' (duration: 14.859583ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:34:13.871062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:34:13.508721Z","time spent":"362.22453ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T01:34:13.871214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.021173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:34:13.871324Z","caller":"traceutil/trace.go:171","msg":"trace[1405218938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26132; }","duration":"139.182055ms","start":"2026-08-08T01:34:13.732116Z","end":"2026-08-08T01:34:13.871298Z","steps":["trace[1405218938] 'agreement among raft nodes before linearized reading' (duration: 139.013082ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:34:13.887761Z","caller":"traceutil/trace.go:171","msg":"trace[2019903009] transaction","detail":"{read_only:false; response_revision:26133; number_of_response:1; }","duration":"113.506713ms","start":"2026-08-08T01:34:13.774214Z","end":"2026-08-08T01:34:13.887721Z","steps":["trace[2019903009] 'process raft request' (duration: 113.211057ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:34:13.88799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.651293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:34:13.888088Z","caller":"traceutil/trace.go:171","msg":"trace[1561080829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26133; }","duration":"107.790425ms","start":"2026-08-08T01:34:13.780274Z","end":"2026-08-08T01:34:13.888064Z","steps":["trace[1561080829] 'agreement among raft nodes before linearized reading' (duration: 107.660813ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:34:21.516343Z","caller":"traceutil/trace.go:171","msg":"trace[2073302009] transaction","detail":"{read_only:false; response_revision:26188; number_of_response:1; }","duration":"174.936794ms","start":"2026-08-08T01:34:21.341074Z","end":"2026-08-08T01:34:21.516011Z","steps":["trace[2073302009] 'process raft request' (duration: 174.695079ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:34:21.541411Z","caller":"traceutil/trace.go:171","msg":"trace[1120466497] linearizableReadLoop","detail":"{readStateIndex:27039; appliedIndex:27037; }","duration":"176.27495ms","start":"2026-08-08T01:34:21.365099Z","end":"2026-08-08T01:34:21.541373Z","steps":["trace[1120466497] 'read index received' (duration: 150.641768ms)","trace[1120466497] 'applied index is now lower than readState.Index' (duration: 25.631252ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:34:21.541527Z","caller":"traceutil/trace.go:171","msg":"trace[465031047] transaction","detail":"{read_only:false; response_revision:26189; number_of_response:1; }","duration":"200.281022ms","start":"2026-08-08T01:34:21.341211Z","end":"2026-08-08T01:34:21.541492Z","steps":["trace[465031047] 'process raft request' (duration: 199.937604ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:34:21.541626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.492717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-08T01:34:21.541676Z","caller":"traceutil/trace.go:171","msg":"trace[1960219055] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:26189; }","duration":"176.622198ms","start":"2026-08-08T01:34:21.365042Z","end":"2026-08-08T01:34:21.541664Z","steps":["trace[1960219055] 'agreement among raft nodes before linearized reading' (duration: 176.488726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:34:21.542762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.344994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-08T01:34:21.542817Z","caller":"traceutil/trace.go:171","msg":"trace[1255401179] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:26190; }","duration":"127.451147ms","start":"2026-08-08T01:34:21.41535Z","end":"2026-08-08T01:34:21.542801Z","steps":["trace[1255401179] 'agreement among raft nodes before linearized reading' (duration: 127.335535ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:34:35.300805Z","caller":"traceutil/trace.go:171","msg":"trace[1590965805] transaction","detail":"{read_only:false; response_revision:26289; number_of_response:1; }","duration":"141.025123ms","start":"2026-08-08T01:34:35.159753Z","end":"2026-08-08T01:34:35.300778Z","steps":["trace[1590965805] 'process raft request' (duration: 140.836149ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:34:35.300833Z","caller":"traceutil/trace.go:171","msg":"trace[1624366136] linearizableReadLoop","detail":"{readStateIndex:27142; appliedIndex:27141; }","duration":"132.611282ms","start":"2026-08-08T01:34:35.168164Z","end":"2026-08-08T01:34:35.300775Z","steps":["trace[1624366136] 'read index received' (duration: 132.55347ms)","trace[1624366136] 'applied index is now lower than readState.Index' (duration: 53.612µs)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:34:35.301695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.276285ms","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:8"} {"level":"info","ts":"2026-08-08T01:34:35.301823Z","caller":"traceutil/trace.go:171","msg":"trace[163603871] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:26289; }","duration":"133.679944ms","start":"2026-08-08T01:34:35.168115Z","end":"2026-08-08T01:34:35.301795Z","steps":["trace[163603871] 'agreement among raft nodes before linearized reading' (duration: 133.189153ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:34:35.466056Z","caller":"traceutil/trace.go:171","msg":"trace[1727729711] transaction","detail":"{read_only:false; response_revision:26290; number_of_response:1; }","duration":"158.611981ms","start":"2026-08-08T01:34:35.307399Z","end":"2026-08-08T01:34:35.466011Z","steps":["trace[1727729711] 'process raft request' (duration: 133.470299ms)","trace[1727729711] 'compare' (duration: 24.945738ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:34:53.562495Z","caller":"traceutil/trace.go:171","msg":"trace[818484138] linearizableReadLoop","detail":"{readStateIndex:27422; appliedIndex:27421; }","duration":"121.2336ms","start":"2026-08-08T01:34:53.44122Z","end":"2026-08-08T01:34:53.562454Z","steps":["trace[818484138] 'read index received' (duration: 120.884383ms)","trace[818484138] 'applied index is now lower than readState.Index' (duration: 347.557µs)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:34:53.562536Z","caller":"traceutil/trace.go:171","msg":"trace[1145025081] transaction","detail":"{read_only:false; response_revision:26564; number_of_response:1; }","duration":"185.810086ms","start":"2026-08-08T01:34:53.376672Z","end":"2026-08-08T01:34:53.562482Z","steps":["trace[1145025081] 'process raft request' (duration: 185.558181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:34:53.562686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.439134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-08T01:34:53.562791Z","caller":"traceutil/trace.go:171","msg":"trace[898711795] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:26564; }","duration":"121.615438ms","start":"2026-08-08T01:34:53.441148Z","end":"2026-08-08T01:34:53.562763Z","steps":["trace[898711795] 'agreement among raft nodes before linearized reading' (duration: 121.424264ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:34:53.82952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.23115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T01:34:53.829773Z","caller":"traceutil/trace.go:171","msg":"trace[674622066] transaction","detail":"{read_only:false; response_revision:26565; number_of_response:1; }","duration":"262.468067ms","start":"2026-08-08T01:34:53.567266Z","end":"2026-08-08T01:34:53.829734Z","steps":["trace[674622066] 'process raft request' (duration: 113.869909ms)","trace[674622066] 'compare' (duration: 147.675109ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:34:53.830458Z","caller":"traceutil/trace.go:171","msg":"trace[850589642] linearizableReadLoop","detail":"{readStateIndex:27424; appliedIndex:27422; }","duration":"122.230441ms","start":"2026-08-08T01:34:53.708196Z","end":"2026-08-08T01:34:53.830427Z","steps":["trace[850589642] 'read index received' (duration: 19.846774ms)","trace[850589642] 'applied index is now lower than readState.Index' (duration: 102.382427ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:34:53.830549Z","caller":"traceutil/trace.go:171","msg":"trace[1162916120] transaction","detail":"{read_only:false; response_revision:26566; number_of_response:1; }","duration":"167.450972ms","start":"2026-08-08T01:34:53.663064Z","end":"2026-08-08T01:34:53.830515Z","steps":["trace[1162916120] 'process raft request' (duration: 166.612014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:34:53.83072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.518407ms","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-08-08T01:34:53.830757Z","caller":"traceutil/trace.go:171","msg":"trace[2354212] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26566; }","duration":"122.631099ms","start":"2026-08-08T01:34:53.708118Z","end":"2026-08-08T01:34:53.830749Z","steps":["trace[2354212] 'agreement among raft nodes before linearized reading' (duration: 122.437995ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:34:53.830797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.775988ms","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-08-08T01:34:53.830865Z","caller":"traceutil/trace.go:171","msg":"trace[2034636564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26566; }","duration":"112.891491ms","start":"2026-08-08T01:34:53.717947Z","end":"2026-08-08T01:34:53.830838Z","steps":["trace[2034636564] 'agreement among raft nodes before linearized reading' (duration: 112.684857ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:34:54.057739Z","caller":"traceutil/trace.go:171","msg":"trace[993818882] transaction","detail":"{read_only:false; response_revision:26567; number_of_response:1; }","duration":"222.033034ms","start":"2026-08-08T01:34:53.835678Z","end":"2026-08-08T01:34:54.057711Z","steps":["trace[993818882] 'process raft request' (duration: 135.46113ms)","trace[993818882] 'compare' (duration: 86.346519ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:34:54.0581Z","caller":"traceutil/trace.go:171","msg":"trace[758347355] transaction","detail":"{read_only:false; response_revision:26568; number_of_response:1; }","duration":"221.743498ms","start":"2026-08-08T01:34:53.836322Z","end":"2026-08-08T01:34:54.058065Z","steps":["trace[758347355] 'process raft request' (duration: 221.34037ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:34:54.058387Z","caller":"traceutil/trace.go:171","msg":"trace[416973542] transaction","detail":"{read_only:false; response_revision:26569; number_of_response:1; }","duration":"148.507806ms","start":"2026-08-08T01:34:53.909861Z","end":"2026-08-08T01:34:54.058369Z","steps":["trace[416973542] 'process raft request' (duration: 148.092598ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:35:01.659185Z","caller":"traceutil/trace.go:171","msg":"trace[1407963157] transaction","detail":"{read_only:false; response_revision:26677; number_of_response:1; }","duration":"157.262524ms","start":"2026-08-08T01:35:01.501889Z","end":"2026-08-08T01:35:01.659152Z","steps":["trace[1407963157] 'process raft request' (duration: 96.702341ms)","trace[1407963157] 'compare' (duration: 60.38721ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:35:01.659243Z","caller":"traceutil/trace.go:171","msg":"trace[1233097968] linearizableReadLoop","detail":"{readStateIndex:27537; appliedIndex:27536; }","duration":"129.304525ms","start":"2026-08-08T01:35:01.52992Z","end":"2026-08-08T01:35:01.659225Z","steps":["trace[1233097968] 'read index received' (duration: 68.68466ms)","trace[1233097968] 'applied index is now lower than readState.Index' (duration: 60.618285ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:35:01.659397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.458938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:35:01.659441Z","caller":"traceutil/trace.go:171","msg":"trace[287295830] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:26678; }","duration":"129.57ms","start":"2026-08-08T01:35:01.529861Z","end":"2026-08-08T01:35:01.659431Z","steps":["trace[287295830] 'agreement among raft nodes before linearized reading' (duration: 129.456908ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:35:42.291176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.720188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T01:35:42.292007Z","caller":"traceutil/trace.go:171","msg":"trace[218880489] transaction","detail":"{read_only:false; response_revision:27015; number_of_response:1; }","duration":"203.175071ms","start":"2026-08-08T01:35:42.088806Z","end":"2026-08-08T01:35:42.291981Z","steps":["trace[218880489] 'process raft request' (duration: 202.61574ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:35:42.29202Z","caller":"traceutil/trace.go:171","msg":"trace[508293241] transaction","detail":"{read_only:false; response_revision:27014; number_of_response:1; }","duration":"209.142172ms","start":"2026-08-08T01:35:42.082339Z","end":"2026-08-08T01:35:42.291481Z","steps":["trace[508293241] 'process raft request' (duration: 64.731589ms)","trace[508293241] 'compare' (duration: 143.33154ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:35:42.463173Z","caller":"traceutil/trace.go:171","msg":"trace[1676062607] transaction","detail":"{read_only:false; response_revision:27019; number_of_response:1; }","duration":"105.98948ms","start":"2026-08-08T01:35:42.357144Z","end":"2026-08-08T01:35:42.463134Z","steps":["trace[1676062607] 'process raft request' (duration: 104.243954ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:35:42.463336Z","caller":"traceutil/trace.go:171","msg":"trace[944905631] transaction","detail":"{read_only:false; response_revision:27020; number_of_response:1; }","duration":"105.728465ms","start":"2026-08-08T01:35:42.35756Z","end":"2026-08-08T01:35:42.463289Z","steps":["trace[944905631] 'process raft request' (duration: 105.087092ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:35:42.675029Z","caller":"traceutil/trace.go:171","msg":"trace[184403242] transaction","detail":"{read_only:false; response_revision:27022; number_of_response:1; }","duration":"206.517348ms","start":"2026-08-08T01:35:42.468487Z","end":"2026-08-08T01:35:42.675004Z","steps":["trace[184403242] 'process raft request' (duration: 206.267553ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:35:42.675558Z","caller":"traceutil/trace.go:171","msg":"trace[2025983130] transaction","detail":"{read_only:false; response_revision:27023; number_of_response:1; }","duration":"204.357804ms","start":"2026-08-08T01:35:42.471167Z","end":"2026-08-08T01:35:42.675524Z","steps":["trace[2025983130] 'process raft request' (duration: 203.801752ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:35:42.675626Z","caller":"traceutil/trace.go:171","msg":"trace[1359600566] linearizableReadLoop","detail":"{readStateIndex:27893; appliedIndex:27890; }","duration":"203.845384ms","start":"2026-08-08T01:35:42.471741Z","end":"2026-08-08T01:35:42.675586Z","steps":["trace[1359600566] 'read index received' (duration: 23.341155ms)","trace[1359600566] 'applied index is now lower than readState.Index' (duration: 180.503079ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:35:42.675678Z","caller":"traceutil/trace.go:171","msg":"trace[1312557547] transaction","detail":"{read_only:false; response_revision:27024; number_of_response:1; }","duration":"201.75573ms","start":"2026-08-08T01:35:42.473911Z","end":"2026-08-08T01:35:42.675667Z","steps":["trace[1312557547] 'process raft request' (duration: 201.522096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:35:42.675771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.011117ms","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-08-08T01:35:42.675805Z","caller":"traceutil/trace.go:171","msg":"trace[814675573] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:27024; }","duration":"204.101859ms","start":"2026-08-08T01:35:42.471692Z","end":"2026-08-08T01:35:42.675794Z","steps":["trace[814675573] 'agreement among raft nodes before linearized reading' (duration: 204.022937ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:35:42.675969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.114329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:35:42.676Z","caller":"traceutil/trace.go:171","msg":"trace[954401566] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27024; }","duration":"151.221911ms","start":"2026-08-08T01:35:42.524768Z","end":"2026-08-08T01:35:42.67599Z","steps":["trace[954401566] 'agreement among raft nodes before linearized reading' (duration: 151.141139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:35:42.676156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.952926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T01:35:42.676161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.170759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-08-08T01:35:42.676174Z","caller":"traceutil/trace.go:171","msg":"trace[159129815] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27024; }","duration":"150.986306ms","start":"2026-08-08T01:35:42.525182Z","end":"2026-08-08T01:35:42.676168Z","steps":["trace[159129815] 'agreement among raft nodes before linearized reading' (duration: 150.954386ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:35:42.676184Z","caller":"traceutil/trace.go:171","msg":"trace[1894367463] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27024; }","duration":"148.21317ms","start":"2026-08-08T01:35:42.527964Z","end":"2026-08-08T01:35:42.676177Z","steps":["trace[1894367463] 'agreement among raft nodes before linearized reading' (duration: 148.110718ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:35:42.676199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.675707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:671"} {"level":"warn","ts":"2026-08-08T01:35:42.676178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.68712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:35:42.676245Z","caller":"traceutil/trace.go:171","msg":"trace[595256226] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:27024; }","duration":"140.764978ms","start":"2026-08-08T01:35:42.535468Z","end":"2026-08-08T01:35:42.676233Z","steps":["trace[595256226] 'agreement among raft nodes before linearized reading' (duration: 140.580804ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:35:42.676278Z","caller":"traceutil/trace.go:171","msg":"trace[956161715] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27024; }","duration":"150.830153ms","start":"2026-08-08T01:35:42.525425Z","end":"2026-08-08T01:35:42.676256Z","steps":["trace[956161715] 'agreement among raft nodes before linearized reading' (duration: 150.628929ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:35:42.676374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.066117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:671"} {"level":"info","ts":"2026-08-08T01:35:42.676396Z","caller":"traceutil/trace.go:171","msg":"trace[1038224326] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:27024; }","duration":"120.111278ms","start":"2026-08-08T01:35:42.556278Z","end":"2026-08-08T01:35:42.676389Z","steps":["trace[1038224326] 'agreement among raft nodes before linearized reading' (duration: 119.988695ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:35:42.676499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.512376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:671"} {"level":"info","ts":"2026-08-08T01:35:42.676531Z","caller":"traceutil/trace.go:171","msg":"trace[1542222222] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:27024; }","duration":"120.561507ms","start":"2026-08-08T01:35:42.555959Z","end":"2026-08-08T01:35:42.67652Z","steps":["trace[1542222222] 'agreement among raft nodes before linearized reading' (duration: 120.449804ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:02.604775Z","caller":"traceutil/trace.go:171","msg":"trace[781319343] linearizableReadLoop","detail":"{readStateIndex:28035; appliedIndex:28034; }","duration":"205.526467ms","start":"2026-08-08T01:36:02.399193Z","end":"2026-08-08T01:36:02.60472Z","steps":["trace[781319343] 'read index received' (duration: 205.264831ms)","trace[781319343] 'applied index is now lower than readState.Index' (duration: 260.226µs)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:36:02.604934Z","caller":"traceutil/trace.go:171","msg":"trace[1755734274] transaction","detail":"{read_only:false; response_revision:27161; number_of_response:1; }","duration":"238.52033ms","start":"2026-08-08T01:36:02.366379Z","end":"2026-08-08T01:36:02.604899Z","steps":["trace[1755734274] 'process raft request' (duration: 238.112452ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:02.605053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.840464ms","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-08-08T01:36:02.605093Z","caller":"traceutil/trace.go:171","msg":"trace[1997834319] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27161; }","duration":"205.959786ms","start":"2026-08-08T01:36:02.399122Z","end":"2026-08-08T01:36:02.605081Z","steps":["trace[1997834319] 'agreement among raft nodes before linearized reading' (duration: 205.780282ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:02.991393Z","caller":"traceutil/trace.go:171","msg":"trace[1345747079] transaction","detail":"{read_only:false; response_revision:27162; number_of_response:1; }","duration":"554.523279ms","start":"2026-08-08T01:36:02.436837Z","end":"2026-08-08T01:36:02.99136Z","steps":["trace[1345747079] 'process raft request' (duration: 554.279464ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:02.991585Z","caller":"traceutil/trace.go:171","msg":"trace[663460667] linearizableReadLoop","detail":"{readStateIndex:28037; appliedIndex:28035; }","duration":"386.681479ms","start":"2026-08-08T01:36:02.604867Z","end":"2026-08-08T01:36:02.991549Z","steps":["trace[663460667] 'read index received' (duration: 386.26505ms)","trace[663460667] 'applied index is now lower than readState.Index' (duration: 414.049µs)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:36:02.991636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:36:02.436808Z","time spent":"554.673562ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T01:36:02.991712Z","caller":"traceutil/trace.go:171","msg":"trace[1041024734] transaction","detail":"{read_only:false; response_revision:27163; number_of_response:1; }","duration":"553.553689ms","start":"2026-08-08T01:36:02.438129Z","end":"2026-08-08T01:36:02.991683Z","steps":["trace[1041024734] 'process raft request' (duration: 553.187932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:02.991761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:36:02.438117Z","time spent":"553.61873ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T01:36:02.991811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.564973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"warn","ts":"2026-08-08T01:36:02.991874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.96529ms","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-08-08T01:36:02.991918Z","caller":"traceutil/trace.go:171","msg":"trace[1371633339] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:27163; }","duration":"402.706107ms","start":"2026-08-08T01:36:02.589182Z","end":"2026-08-08T01:36:02.991888Z","steps":["trace[1371633339] 'agreement among raft nodes before linearized reading' (duration: 402.431821ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:02.991813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.820631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T01:36:02.991961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:36:02.589171Z","time spent":"402.781908ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":597,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-08-08T01:36:02.991958Z","caller":"traceutil/trace.go:171","msg":"trace[2084890644] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27163; }","duration":"427.114233ms","start":"2026-08-08T01:36:02.564827Z","end":"2026-08-08T01:36:02.991941Z","steps":["trace[2084890644] 'agreement among raft nodes before linearized reading' (duration: 426.860177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:02.991906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.929776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:02.991987Z","caller":"traceutil/trace.go:171","msg":"trace[1052596232] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:27163; }","duration":"519.070497ms","start":"2026-08-08T01:36:02.472888Z","end":"2026-08-08T01:36:02.991958Z","steps":["trace[1052596232] 'agreement among raft nodes before linearized reading' (duration: 518.816932ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:02.99208Z","caller":"traceutil/trace.go:171","msg":"trace[1519295690] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:27163; }","duration":"393.159231ms","start":"2026-08-08T01:36:02.598886Z","end":"2026-08-08T01:36:02.992045Z","steps":["trace[1519295690] 'agreement among raft nodes before linearized reading' (duration: 392.846534ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:02.992002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:36:02.564795Z","time spent":"427.200824ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-08T01:36:02.992137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.683802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-08-08T01:36:02.992148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:36:02.598877Z","time spent":"393.255952ms","remote":"127.0.0.1:47890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true "} {"level":"warn","ts":"2026-08-08T01:36:02.992137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:36:02.472858Z","time spent":"519.228629ms","remote":"127.0.0.1:58008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"info","ts":"2026-08-08T01:36:02.992187Z","caller":"traceutil/trace.go:171","msg":"trace[2093895894] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27163; }","duration":"422.781704ms","start":"2026-08-08T01:36:02.569386Z","end":"2026-08-08T01:36:02.992168Z","steps":["trace[2093895894] 'agreement among raft nodes before linearized reading' (duration: 422.660002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:02.992215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:36:02.569358Z","time spent":"422.852506ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-08T01:36:03.016933Z","caller":"traceutil/trace.go:171","msg":"trace[275609598] transaction","detail":"{read_only:false; response_revision:27164; number_of_response:1; }","duration":"406.758277ms","start":"2026-08-08T01:36:02.610158Z","end":"2026-08-08T01:36:03.016916Z","steps":["trace[275609598] 'process raft request' (duration: 406.450181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:03.017065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:36:02.61013Z","time spent":"406.846389ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T01:36:03.017115Z","caller":"traceutil/trace.go:171","msg":"trace[914382554] transaction","detail":"{read_only:false; response_revision:27165; number_of_response:1; }","duration":"333.377193ms","start":"2026-08-08T01:36:02.683705Z","end":"2026-08-08T01:36:03.017082Z","steps":["trace[914382554] 'process raft request' (duration: 333.153978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:03.01718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.410662ms","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-08-08T01:36:03.017237Z","caller":"traceutil/trace.go:171","msg":"trace[534016467] 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:27165; }","duration":"278.495304ms","start":"2026-08-08T01:36:02.738725Z","end":"2026-08-08T01:36:03.01722Z","steps":["trace[534016467] 'agreement among raft nodes before linearized reading' (duration: 278.378022ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:03.017209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.77803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T01:36:03.017284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.209848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:03.017293Z","caller":"traceutil/trace.go:171","msg":"trace[451105384] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27165; }","duration":"278.866541ms","start":"2026-08-08T01:36:02.738409Z","end":"2026-08-08T01:36:03.017275Z","steps":["trace[451105384] 'agreement among raft nodes before linearized reading' (duration: 278.737259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:03.017299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.185918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:03.01733Z","caller":"traceutil/trace.go:171","msg":"trace[253392296] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27165; }","duration":"279.255779ms","start":"2026-08-08T01:36:02.738063Z","end":"2026-08-08T01:36:03.017319Z","steps":["trace[253392296] 'agreement among raft nodes before linearized reading' (duration: 279.167888ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:03.017345Z","caller":"traceutil/trace.go:171","msg":"trace[936787716] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27165; }","duration":"279.26756ms","start":"2026-08-08T01:36:02.738064Z","end":"2026-08-08T01:36:03.017332Z","steps":["trace[936787716] 'agreement among raft nodes before linearized reading' (duration: 279.201378ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:03.017334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.131353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-08-08T01:36:03.017379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.933113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"warn","ts":"2026-08-08T01:36:03.01727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.604669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:671"} {"level":"info","ts":"2026-08-08T01:36:03.0174Z","caller":"traceutil/trace.go:171","msg":"trace[418365454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27165; }","duration":"242.224425ms","start":"2026-08-08T01:36:02.775159Z","end":"2026-08-08T01:36:03.017384Z","steps":["trace[418365454] 'agreement among raft nodes before linearized reading' (duration: 242.050531ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:03.017427Z","caller":"traceutil/trace.go:171","msg":"trace[860393355] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:27165; }","duration":"282.838643ms","start":"2026-08-08T01:36:02.734579Z","end":"2026-08-08T01:36:03.017418Z","steps":["trace[860393355] 'agreement among raft nodes before linearized reading' (duration: 282.499117ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:03.017438Z","caller":"traceutil/trace.go:171","msg":"trace[1989693215] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27165; }","duration":"279.987395ms","start":"2026-08-08T01:36:02.737423Z","end":"2026-08-08T01:36:03.01741Z","steps":["trace[1989693215] 'agreement among raft nodes before linearized reading' (duration: 279.903863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:03.017435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.87279ms","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-08-08T01:36:03.017479Z","caller":"traceutil/trace.go:171","msg":"trace[1132554183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27165; }","duration":"245.930411ms","start":"2026-08-08T01:36:02.771533Z","end":"2026-08-08T01:36:03.017463Z","steps":["trace[1132554183] 'agreement among raft nodes before linearized reading' (duration: 245.740437ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:03.017472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.728842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T01:36:03.017468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.050495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:671"} {"level":"info","ts":"2026-08-08T01:36:03.017511Z","caller":"traceutil/trace.go:171","msg":"trace[910325787] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:27165; }","duration":"207.791934ms","start":"2026-08-08T01:36:02.809707Z","end":"2026-08-08T01:36:03.017499Z","steps":["trace[910325787] 'agreement among raft nodes before linearized reading' (duration: 207.726503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:03.017488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.77877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:03.017536Z","caller":"traceutil/trace.go:171","msg":"trace[845610395] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:27165; }","duration":"279.111536ms","start":"2026-08-08T01:36:02.738402Z","end":"2026-08-08T01:36:03.017514Z","steps":["trace[845610395] 'agreement among raft nodes before linearized reading' (duration: 278.902612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:03.017569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:36:02.683674Z","time spent":"333.512736ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T01:36:03.017556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.307926ms","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-08-08T01:36:03.017649Z","caller":"traceutil/trace.go:171","msg":"trace[1573020921] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:27165; }","duration":"238.419117ms","start":"2026-08-08T01:36:02.779205Z","end":"2026-08-08T01:36:03.017624Z","steps":["trace[1573020921] 'agreement among raft nodes before linearized reading' (duration: 238.175293ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:03.017245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.667153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T01:36:03.017688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.471474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:671"} {"level":"info","ts":"2026-08-08T01:36:03.017583Z","caller":"traceutil/trace.go:171","msg":"trace[66653970] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:27165; }","duration":"198.899123ms","start":"2026-08-08T01:36:02.818659Z","end":"2026-08-08T01:36:03.017558Z","steps":["trace[66653970] 'agreement among raft nodes before linearized reading' (duration: 198.683199ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:03.017712Z","caller":"traceutil/trace.go:171","msg":"trace[2078050074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27165; }","duration":"238.160033ms","start":"2026-08-08T01:36:02.77953Z","end":"2026-08-08T01:36:03.017691Z","steps":["trace[2078050074] 'agreement among raft nodes before linearized reading' (duration: 237.657503ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:03.018047Z","caller":"traceutil/trace.go:171","msg":"trace[1968889614] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:27165; }","duration":"279.838481ms","start":"2026-08-08T01:36:02.738195Z","end":"2026-08-08T01:36:03.018034Z","steps":["trace[1968889614] 'agreement among raft nodes before linearized reading' (duration: 278.948923ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:32.358157Z","caller":"traceutil/trace.go:171","msg":"trace[1285295980] transaction","detail":"{read_only:false; response_revision:27381; number_of_response:1; }","duration":"124.483966ms","start":"2026-08-08T01:36:32.23364Z","end":"2026-08-08T01:36:32.358124Z","steps":["trace[1285295980] 'process raft request' (duration: 124.271772ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:33.150916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.124456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:33.151022Z","caller":"traceutil/trace.go:171","msg":"trace[917658285] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:27382; }","duration":"197.297859ms","start":"2026-08-08T01:36:32.9537Z","end":"2026-08-08T01:36:33.150997Z","steps":["trace[917658285] 'count revisions from in-memory index tree' (duration: 196.978393ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:33.314142Z","caller":"traceutil/trace.go:171","msg":"trace[950686255] transaction","detail":"{read_only:false; response_revision:27383; number_of_response:1; }","duration":"166.760087ms","start":"2026-08-08T01:36:33.147351Z","end":"2026-08-08T01:36:33.314111Z","steps":["trace[950686255] 'process raft request' (duration: 166.556043ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:33.428099Z","caller":"traceutil/trace.go:171","msg":"trace[1844503997] linearizableReadLoop","detail":"{readStateIndex:28265; appliedIndex:28263; }","duration":"277.121717ms","start":"2026-08-08T01:36:33.150943Z","end":"2026-08-08T01:36:33.428064Z","steps":["trace[1844503997] 'read index received' (duration: 163.077323ms)","trace[1844503997] 'applied index is now lower than readState.Index' (duration: 114.042604ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:36:33.428517Z","caller":"traceutil/trace.go:171","msg":"trace[1337541323] transaction","detail":"{read_only:false; response_revision:27384; number_of_response:1; }","duration":"280.978194ms","start":"2026-08-08T01:36:33.147519Z","end":"2026-08-08T01:36:33.428497Z","steps":["trace[1337541323] 'process raft request' (duration: 279.492984ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:33.42883Z","caller":"traceutil/trace.go:171","msg":"trace[1043001327] transaction","detail":"{read_only:false; response_revision:27385; number_of_response:1; }","duration":"163.496871ms","start":"2026-08-08T01:36:33.265295Z","end":"2026-08-08T01:36:33.428791Z","steps":["trace[1043001327] 'process raft request' (duration: 162.676545ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:33.429363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.594644ms","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-08-08T01:36:33.429614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.894341ms","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-08-08T01:36:33.429746Z","caller":"traceutil/trace.go:171","msg":"trace[1167131059] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:27385; }","duration":"170.058655ms","start":"2026-08-08T01:36:33.259653Z","end":"2026-08-08T01:36:33.429712Z","steps":["trace[1167131059] 'agreement among raft nodes before linearized reading' (duration: 169.605165ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:33.430004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.93842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T01:36:33.430029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.113604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:33.430118Z","caller":"traceutil/trace.go:171","msg":"trace[2103182966] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27385; }","duration":"243.072413ms","start":"2026-08-08T01:36:33.187019Z","end":"2026-08-08T01:36:33.430092Z","steps":["trace[2103182966] 'agreement among raft nodes before linearized reading' (duration: 242.91173ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:33.430145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.877458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-08T01:36:33.430205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.42387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-08-08T01:36:33.430234Z","caller":"traceutil/trace.go:171","msg":"trace[658153619] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27385; }","duration":"190.035352ms","start":"2026-08-08T01:36:33.24018Z","end":"2026-08-08T01:36:33.430215Z","steps":["trace[658153619] 'agreement among raft nodes before linearized reading' (duration: 189.743536ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:33.430157Z","caller":"traceutil/trace.go:171","msg":"trace[196239640] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27385; }","duration":"137.280467ms","start":"2026-08-08T01:36:33.292848Z","end":"2026-08-08T01:36:33.430128Z","steps":["trace[196239640] 'agreement among raft nodes before linearized reading' (duration: 137.001581ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:33.430261Z","caller":"traceutil/trace.go:171","msg":"trace[2113078665] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27385; }","duration":"137.548232ms","start":"2026-08-08T01:36:33.292696Z","end":"2026-08-08T01:36:33.430245Z","steps":["trace[2113078665] 'agreement among raft nodes before linearized reading' (duration: 137.42059ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:33.430315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.104711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T01:36:33.430356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.710558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-08-08T01:36:33.430425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.410236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-08-08T01:36:33.430426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.409489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:33.430453Z","caller":"traceutil/trace.go:171","msg":"trace[956740732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27385; }","duration":"179.451876ms","start":"2026-08-08T01:36:33.250996Z","end":"2026-08-08T01:36:33.430448Z","steps":["trace[956740732] 'agreement among raft nodes before linearized reading' (duration: 179.384355ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:33.430477Z","caller":"traceutil/trace.go:171","msg":"trace[631773484] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27385; }","duration":"243.483912ms","start":"2026-08-08T01:36:33.186978Z","end":"2026-08-08T01:36:33.430462Z","steps":["trace[631773484] 'agreement among raft nodes before linearized reading' (duration: 243.378769ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:33.430465Z","caller":"traceutil/trace.go:171","msg":"trace[2078474567] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27385; }","duration":"170.846161ms","start":"2026-08-08T01:36:33.259598Z","end":"2026-08-08T01:36:33.430444Z","steps":["trace[2078474567] 'agreement among raft nodes before linearized reading' (duration: 170.645397ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:33.430362Z","caller":"traceutil/trace.go:171","msg":"trace[1900660697] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:27385; }","duration":"130.163662ms","start":"2026-08-08T01:36:33.300184Z","end":"2026-08-08T01:36:33.430348Z","steps":["trace[1900660697] 'agreement among raft nodes before linearized reading' (duration: 130.05834ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:33.430606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.412049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-08-08T01:36:33.430648Z","caller":"traceutil/trace.go:171","msg":"trace[919383792] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27385; }","duration":"241.47246ms","start":"2026-08-08T01:36:33.189166Z","end":"2026-08-08T01:36:33.430638Z","steps":["trace[919383792] 'agreement among raft nodes before linearized reading' (duration: 241.374018ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:33.430203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.475795ms","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-08-08T01:36:33.430716Z","caller":"traceutil/trace.go:171","msg":"trace[706065513] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:27385; }","duration":"172.005925ms","start":"2026-08-08T01:36:33.258693Z","end":"2026-08-08T01:36:33.430699Z","steps":["trace[706065513] 'agreement among raft nodes before linearized reading' (duration: 171.421914ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:33.429815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.919378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:33.430814Z","caller":"traceutil/trace.go:171","msg":"trace[862990397] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27385; }","duration":"137.97094ms","start":"2026-08-08T01:36:33.292835Z","end":"2026-08-08T01:36:33.430806Z","steps":["trace[862990397] 'agreement among raft nodes before linearized reading' (duration: 136.871637ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:33.430901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.886146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-08T01:36:33.430926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.120455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T01:36:33.430082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.256157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:33.430999Z","caller":"traceutil/trace.go:171","msg":"trace[1730150110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27385; }","duration":"180.031908ms","start":"2026-08-08T01:36:33.250947Z","end":"2026-08-08T01:36:33.430979Z","steps":["trace[1730150110] 'agreement among raft nodes before linearized reading' (duration: 179.722382ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:33.43104Z","caller":"traceutil/trace.go:171","msg":"trace[541297102] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27385; }","duration":"138.224936ms","start":"2026-08-08T01:36:33.2928Z","end":"2026-08-08T01:36:33.431025Z","steps":["trace[541297102] 'agreement among raft nodes before linearized reading' (duration: 137.235536ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:33.431006Z","caller":"traceutil/trace.go:171","msg":"trace[484523403] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27385; }","duration":"246.235977ms","start":"2026-08-08T01:36:33.184753Z","end":"2026-08-08T01:36:33.430989Z","steps":["trace[484523403] 'agreement among raft nodes before linearized reading' (duration: 246.088984ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:33.42946Z","caller":"traceutil/trace.go:171","msg":"trace[1198161879] 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:27385; }","duration":"247.778698ms","start":"2026-08-08T01:36:33.181656Z","end":"2026-08-08T01:36:33.429434Z","steps":["trace[1198161879] 'agreement among raft nodes before linearized reading' (duration: 247.562014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:33.437266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.315954ms","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-08-08T01:36:33.437376Z","caller":"traceutil/trace.go:171","msg":"trace[1727614878] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27385; }","duration":"286.467107ms","start":"2026-08-08T01:36:33.150895Z","end":"2026-08-08T01:36:33.437362Z","steps":["trace[1727614878] 'agreement among raft nodes before linearized reading' (duration: 277.913682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:33.76261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.892251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-08T01:36:33.762721Z","caller":"traceutil/trace.go:171","msg":"trace[756785602] transaction","detail":"{read_only:false; response_revision:27386; number_of_response:1; }","duration":"324.517972ms","start":"2026-08-08T01:36:33.438186Z","end":"2026-08-08T01:36:33.762704Z","steps":["trace[756785602] 'process raft request' (duration: 127.450717ms)","trace[756785602] 'compare' (duration: 196.354611ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:36:33.762771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:36:33.438157Z","time spent":"324.588834ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T01:36:33.811064Z","caller":"traceutil/trace.go:171","msg":"trace[990652290] transaction","detail":"{read_only:false; response_revision:27388; number_of_response:1; }","duration":"372.382668ms","start":"2026-08-08T01:36:33.438664Z","end":"2026-08-08T01:36:33.811047Z","steps":["trace[990652290] 'process raft request' (duration: 372.294366ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:33.811159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:36:33.438643Z","time spent":"372.47928ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T01:36:33.811319Z","caller":"traceutil/trace.go:171","msg":"trace[1362056487] transaction","detail":"{read_only:false; response_revision:27389; number_of_response:1; }","duration":"372.146903ms","start":"2026-08-08T01:36:33.439162Z","end":"2026-08-08T01:36:33.811309Z","steps":["trace[1362056487] 'process raft request' (duration: 371.829746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:33.811379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:36:33.439146Z","time spent":"372.207215ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T01:36:33.811699Z","caller":"traceutil/trace.go:171","msg":"trace[299892323] transaction","detail":"{read_only:false; response_revision:27390; number_of_response:1; }","duration":"372.474599ms","start":"2026-08-08T01:36:33.439209Z","end":"2026-08-08T01:36:33.811684Z","steps":["trace[299892323] 'process raft request' (duration: 372.01131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:33.811852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:36:33.439181Z","time spent":"372.585602ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T01:36:33.811975Z","caller":"traceutil/trace.go:171","msg":"trace[2094521224] transaction","detail":"{read_only:false; response_revision:27391; number_of_response:1; }","duration":"372.687073ms","start":"2026-08-08T01:36:33.439255Z","end":"2026-08-08T01:36:33.811942Z","steps":["trace[2094521224] 'process raft request' (duration: 372.385537ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:33.812015Z","caller":"traceutil/trace.go:171","msg":"trace[728356678] transaction","detail":"{read_only:false; response_revision:27392; number_of_response:1; }","duration":"370.953918ms","start":"2026-08-08T01:36:33.44105Z","end":"2026-08-08T01:36:33.812004Z","steps":["trace[728356678] 'process raft request' (duration: 370.760964ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:33.812079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:36:33.441037Z","time spent":"371.0058ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T01:36:33.812072Z","caller":"traceutil/trace.go:171","msg":"trace[255186067] linearizableReadLoop","detail":"{readStateIndex:28273; appliedIndex:28266; }","duration":"368.692583ms","start":"2026-08-08T01:36:33.443359Z","end":"2026-08-08T01:36:33.812052Z","steps":["trace[255186067] 'read index received' (duration: 122.299332ms)","trace[255186067] 'applied index is now lower than readState.Index' (duration: 246.391861ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:36:33.812114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:36:33.439245Z","time spent":"372.794406ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T01:36:33.812106Z","caller":"traceutil/trace.go:171","msg":"trace[1243288358] transaction","detail":"{read_only:false; response_revision:27393; number_of_response:1; }","duration":"359.396492ms","start":"2026-08-08T01:36:33.452636Z","end":"2026-08-08T01:36:33.812032Z","steps":["trace[1243288358] 'process raft request' (duration: 359.27396ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:33.812188Z","caller":"traceutil/trace.go:171","msg":"trace[403294562] transaction","detail":"{read_only:false; response_revision:27394; number_of_response:1; }","duration":"250.454653ms","start":"2026-08-08T01:36:33.561722Z","end":"2026-08-08T01:36:33.812176Z","steps":["trace[403294562] 'process raft request' (duration: 250.230069ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:33.812319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.952978ms","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-08-08T01:36:33.812369Z","caller":"traceutil/trace.go:171","msg":"trace[1963351493] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:27394; }","duration":"369.01843ms","start":"2026-08-08T01:36:33.443336Z","end":"2026-08-08T01:36:33.812355Z","steps":["trace[1963351493] 'agreement among raft nodes before linearized reading' (duration: 368.937178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:33.812367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:36:33.452622Z","time spent":"359.588547ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T01:36:33.812407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:36:33.443328Z","time spent":"369.069721ms","remote":"127.0.0.1:33626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-08-08T01:36:33.811109Z","caller":"traceutil/trace.go:171","msg":"trace[1992403365] transaction","detail":"{read_only:false; response_revision:27387; number_of_response:1; }","duration":"372.802927ms","start":"2026-08-08T01:36:33.438242Z","end":"2026-08-08T01:36:33.811045Z","steps":["trace[1992403365] 'process raft request' (duration: 372.606023ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:33.812838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:36:33.438213Z","time spent":"374.577471ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-08T01:36:35.452485Z","caller":"traceutil/trace.go:171","msg":"trace[1767481585] transaction","detail":"{read_only:false; response_revision:27398; number_of_response:1; }","duration":"131.214404ms","start":"2026-08-08T01:36:35.321239Z","end":"2026-08-08T01:36:35.452454Z","steps":["trace[1767481585] 'process raft request' (duration: 131.03347ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:35.603699Z","caller":"traceutil/trace.go:171","msg":"trace[1628840107] linearizableReadLoop","detail":"{readStateIndex:28280; appliedIndex:28279; }","duration":"150.517927ms","start":"2026-08-08T01:36:35.453147Z","end":"2026-08-08T01:36:35.603665Z","steps":["trace[1628840107] 'read index received' (duration: 140.802199ms)","trace[1628840107] 'applied index is now lower than readState.Index' (duration: 9.713978ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:36:35.603803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.607242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T01:36:35.603819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.647234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:35.603879Z","caller":"traceutil/trace.go:171","msg":"trace[2062307501] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27399; }","duration":"159.713714ms","start":"2026-08-08T01:36:35.444147Z","end":"2026-08-08T01:36:35.603861Z","steps":["trace[2062307501] 'agreement among raft nodes before linearized reading' (duration: 159.537971ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:35.603883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.953409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T01:36:35.603887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.132075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T01:36:35.603909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.692946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T01:36:35.603893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.736107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:35.603912Z","caller":"traceutil/trace.go:171","msg":"trace[779640522] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27399; }","duration":"164.764748ms","start":"2026-08-08T01:36:35.439126Z","end":"2026-08-08T01:36:35.603891Z","steps":["trace[779640522] 'agreement among raft nodes before linearized reading' (duration: 164.592404ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:35.604005Z","caller":"traceutil/trace.go:171","msg":"trace[657547169] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27399; }","duration":"159.125162ms","start":"2026-08-08T01:36:35.444849Z","end":"2026-08-08T01:36:35.603975Z","steps":["trace[657547169] 'agreement among raft nodes before linearized reading' (duration: 158.918408ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:35.604026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.241685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:35.604022Z","caller":"traceutil/trace.go:171","msg":"trace[1496208316] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27399; }","duration":"163.806938ms","start":"2026-08-08T01:36:35.440193Z","end":"2026-08-08T01:36:35.604Z","steps":["trace[1496208316] 'agreement among raft nodes before linearized reading' (duration: 163.693226ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:35.60405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.218284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-08-08T01:36:35.604063Z","caller":"traceutil/trace.go:171","msg":"trace[1880898847] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27399; }","duration":"159.300606ms","start":"2026-08-08T01:36:35.444752Z","end":"2026-08-08T01:36:35.604053Z","steps":["trace[1880898847] 'agreement among raft nodes before linearized reading' (duration: 159.236955ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:35.604092Z","caller":"traceutil/trace.go:171","msg":"trace[290173262] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27399; }","duration":"159.280895ms","start":"2026-08-08T01:36:35.444799Z","end":"2026-08-08T01:36:35.60408Z","steps":["trace[290173262] 'agreement among raft nodes before linearized reading' (duration: 159.162473ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:35.603823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.748307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T01:36:35.603963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.90199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-08-08T01:36:35.60416Z","caller":"traceutil/trace.go:171","msg":"trace[1921053525] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27399; }","duration":"164.171266ms","start":"2026-08-08T01:36:35.439981Z","end":"2026-08-08T01:36:35.604152Z","steps":["trace[1921053525] 'agreement among raft nodes before linearized reading' (duration: 163.721877ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:35.604187Z","caller":"traceutil/trace.go:171","msg":"trace[1742463846] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27399; }","duration":"164.159485ms","start":"2026-08-08T01:36:35.440016Z","end":"2026-08-08T01:36:35.604176Z","steps":["trace[1742463846] 'agreement among raft nodes before linearized reading' (duration: 163.724787ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:35.604046Z","caller":"traceutil/trace.go:171","msg":"trace[1261117544] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27399; }","duration":"164.9098ms","start":"2026-08-08T01:36:35.439114Z","end":"2026-08-08T01:36:35.604024Z","steps":["trace[1261117544] 'agreement among raft nodes before linearized reading' (duration: 164.693796ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:35.603995Z","caller":"traceutil/trace.go:171","msg":"trace[946067366] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27399; }","duration":"163.278427ms","start":"2026-08-08T01:36:35.440702Z","end":"2026-08-08T01:36:35.603981Z","steps":["trace[946067366] 'agreement among raft nodes before linearized reading' (duration: 163.123354ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:35.604225Z","caller":"traceutil/trace.go:171","msg":"trace[1491091298] transaction","detail":"{read_only:false; response_revision:27399; number_of_response:1; }","duration":"166.668616ms","start":"2026-08-08T01:36:35.437538Z","end":"2026-08-08T01:36:35.604207Z","steps":["trace[1491091298] 'process raft request' (duration: 156.367786ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:35.604284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.161855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-08-08T01:36:35.604322Z","caller":"traceutil/trace.go:171","msg":"trace[918132149] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27399; }","duration":"165.236877ms","start":"2026-08-08T01:36:35.439074Z","end":"2026-08-08T01:36:35.604311Z","steps":["trace[918132149] 'agreement among raft nodes before linearized reading' (duration: 164.712897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:35.86702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.56006ms","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-08-08T01:36:35.867109Z","caller":"traceutil/trace.go:171","msg":"trace[387237667] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:27399; }","duration":"101.718123ms","start":"2026-08-08T01:36:35.765368Z","end":"2026-08-08T01:36:35.867086Z","steps":["trace[387237667] 'count revisions from in-memory index tree' (duration: 101.473487ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:36.097893Z","caller":"traceutil/trace.go:171","msg":"trace[1233603576] transaction","detail":"{read_only:false; response_revision:27402; number_of_response:1; }","duration":"223.655757ms","start":"2026-08-08T01:36:35.874201Z","end":"2026-08-08T01:36:36.097857Z","steps":["trace[1233603576] 'process raft request' (duration: 223.398812ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:36.098288Z","caller":"traceutil/trace.go:171","msg":"trace[2116700080] transaction","detail":"{read_only:false; response_revision:27404; number_of_response:1; }","duration":"223.802ms","start":"2026-08-08T01:36:35.874452Z","end":"2026-08-08T01:36:36.098254Z","steps":["trace[2116700080] 'process raft request' (duration: 223.590106ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:36.098348Z","caller":"traceutil/trace.go:171","msg":"trace[158360862] transaction","detail":"{read_only:false; response_revision:27405; number_of_response:1; }","duration":"223.743469ms","start":"2026-08-08T01:36:35.874554Z","end":"2026-08-08T01:36:36.098298Z","steps":["trace[158360862] 'process raft request' (duration: 223.641797ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:36.098395Z","caller":"traceutil/trace.go:171","msg":"trace[37923574] transaction","detail":"{read_only:false; response_revision:27406; number_of_response:1; }","duration":"221.957391ms","start":"2026-08-08T01:36:35.876418Z","end":"2026-08-08T01:36:36.098375Z","steps":["trace[37923574] 'process raft request' (duration: 221.822269ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:36.098278Z","caller":"traceutil/trace.go:171","msg":"trace[1873699371] transaction","detail":"{read_only:false; response_revision:27403; number_of_response:1; }","duration":"223.896811ms","start":"2026-08-08T01:36:35.874324Z","end":"2026-08-08T01:36:36.098221Z","steps":["trace[1873699371] 'process raft request' (duration: 223.480483ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:36.09836Z","caller":"traceutil/trace.go:171","msg":"trace[928066249] transaction","detail":"{read_only:false; response_revision:27407; number_of_response:1; }","duration":"220.652725ms","start":"2026-08-08T01:36:35.877676Z","end":"2026-08-08T01:36:36.098328Z","steps":["trace[928066249] 'process raft request' (duration: 220.601734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:36.41623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.238444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-08-08T01:36:36.416315Z","caller":"traceutil/trace.go:171","msg":"trace[1624720792] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:27408; }","duration":"180.392366ms","start":"2026-08-08T01:36:36.235902Z","end":"2026-08-08T01:36:36.416294Z","steps":["trace[1624720792] 'range keys from in-memory index tree' (duration: 180.024339ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:36.739044Z","caller":"traceutil/trace.go:171","msg":"trace[614811213] transaction","detail":"{read_only:false; response_revision:27410; number_of_response:1; }","duration":"230.029017ms","start":"2026-08-08T01:36:36.508989Z","end":"2026-08-08T01:36:36.739018Z","steps":["trace[614811213] 'process raft request' (duration: 229.810982ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:36.972349Z","caller":"traceutil/trace.go:171","msg":"trace[1545571892] transaction","detail":"{read_only:false; response_revision:27412; number_of_response:1; }","duration":"103.485139ms","start":"2026-08-08T01:36:36.868816Z","end":"2026-08-08T01:36:36.972301Z","steps":["trace[1545571892] 'process raft request' (duration: 103.347916ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:37.715782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.977578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:37.71589Z","caller":"traceutil/trace.go:171","msg":"trace[517604257] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27413; }","duration":"106.116321ms","start":"2026-08-08T01:36:37.609746Z","end":"2026-08-08T01:36:37.715862Z","steps":["trace[517604257] 'range keys from in-memory index tree' (duration: 105.895597ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:37.716274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.47119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T01:36:37.716283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.710894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:37.716358Z","caller":"traceutil/trace.go:171","msg":"trace[1590389351] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27413; }","duration":"106.809856ms","start":"2026-08-08T01:36:37.609533Z","end":"2026-08-08T01:36:37.716343Z","steps":["trace[1590389351] 'range keys from in-memory index tree' (duration: 106.622262ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:37.716473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.469411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:37.716496Z","caller":"traceutil/trace.go:171","msg":"trace[1137744480] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27413; }","duration":"107.537772ms","start":"2026-08-08T01:36:37.608953Z","end":"2026-08-08T01:36:37.716491Z","steps":["trace[1137744480] 'range keys from in-memory index tree' (duration: 107.41176ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:37.716628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.039031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-08-08T01:36:37.716651Z","caller":"traceutil/trace.go:171","msg":"trace[1014328955] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27413; }","duration":"107.069401ms","start":"2026-08-08T01:36:37.609575Z","end":"2026-08-08T01:36:37.716645Z","steps":["trace[1014328955] 'range keys from in-memory index tree' (duration: 106.934148ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:37.716315Z","caller":"traceutil/trace.go:171","msg":"trace[807249639] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27413; }","duration":"106.52138ms","start":"2026-08-08T01:36:37.609785Z","end":"2026-08-08T01:36:37.716307Z","steps":["trace[807249639] 'range keys from in-memory index tree' (duration: 106.449288ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:37.716872Z","caller":"traceutil/trace.go:171","msg":"trace[793187720] linearizableReadLoop","detail":"{readStateIndex:28296; appliedIndex:28295; }","duration":"106.335536ms","start":"2026-08-08T01:36:37.610525Z","end":"2026-08-08T01:36:37.716861Z","steps":["trace[793187720] 'read index received' (duration: 63.606196ms)","trace[793187720] 'applied index is now lower than readState.Index' (duration: 42.72811ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:36:37.717165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.573681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"warn","ts":"2026-08-08T01:36:37.717175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.344027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:37.717197Z","caller":"traceutil/trace.go:171","msg":"trace[1679210031] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27414; }","duration":"106.615452ms","start":"2026-08-08T01:36:37.610575Z","end":"2026-08-08T01:36:37.71719Z","steps":["trace[1679210031] 'agreement among raft nodes before linearized reading' (duration: 106.538791ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:37.717209Z","caller":"traceutil/trace.go:171","msg":"trace[1966650577] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27414; }","duration":"105.390247ms","start":"2026-08-08T01:36:37.611808Z","end":"2026-08-08T01:36:37.717198Z","steps":["trace[1966650577] 'agreement among raft nodes before linearized reading' (duration: 105.331296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:37.717242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.405988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:37.717333Z","caller":"traceutil/trace.go:171","msg":"trace[1842928771] transaction","detail":"{read_only:false; response_revision:27414; number_of_response:1; }","duration":"107.357817ms","start":"2026-08-08T01:36:37.609966Z","end":"2026-08-08T01:36:37.717324Z","steps":["trace[1842928771] 'process raft request' (duration: 64.229149ms)","trace[1842928771] 'compare' (duration: 41.515074ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:36:37.717332Z","caller":"traceutil/trace.go:171","msg":"trace[1999918664] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27414; }","duration":"106.523551ms","start":"2026-08-08T01:36:37.610788Z","end":"2026-08-08T01:36:37.717311Z","steps":["trace[1999918664] 'agreement among raft nodes before linearized reading' (duration: 106.346897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:37.717291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.51445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:37.717469Z","caller":"traceutil/trace.go:171","msg":"trace[1367732882] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27414; }","duration":"106.703984ms","start":"2026-08-08T01:36:37.610759Z","end":"2026-08-08T01:36:37.717463Z","steps":["trace[1367732882] 'agreement among raft nodes before linearized reading' (duration: 106.51461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:37.717483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.814135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"warn","ts":"2026-08-08T01:36:37.71755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.02969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:37.717577Z","caller":"traceutil/trace.go:171","msg":"trace[2118790693] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27414; }","duration":"107.103822ms","start":"2026-08-08T01:36:37.610466Z","end":"2026-08-08T01:36:37.717569Z","steps":["trace[2118790693] 'agreement among raft nodes before linearized reading' (duration: 107.069801ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:37.717594Z","caller":"traceutil/trace.go:171","msg":"trace[38342022] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27414; }","duration":"104.975069ms","start":"2026-08-08T01:36:37.612593Z","end":"2026-08-08T01:36:37.717568Z","steps":["trace[38342022] 'agreement among raft nodes before linearized reading' (duration: 104.53958ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:38.116639Z","caller":"traceutil/trace.go:171","msg":"trace[780329286] transaction","detail":"{read_only:false; response_revision:27416; number_of_response:1; }","duration":"166.040864ms","start":"2026-08-08T01:36:37.950569Z","end":"2026-08-08T01:36:38.11661Z","steps":["trace[780329286] 'process raft request' (duration: 165.695836ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:38.289332Z","caller":"traceutil/trace.go:171","msg":"trace[358684501] transaction","detail":"{read_only:false; response_revision:27418; number_of_response:1; }","duration":"164.439491ms","start":"2026-08-08T01:36:38.124782Z","end":"2026-08-08T01:36:38.289221Z","steps":["trace[358684501] 'process raft request' (duration: 164.116105ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:38.28954Z","caller":"traceutil/trace.go:171","msg":"trace[1769385066] transaction","detail":"{read_only:false; response_revision:27419; number_of_response:1; }","duration":"164.640004ms","start":"2026-08-08T01:36:38.124877Z","end":"2026-08-08T01:36:38.289517Z","steps":["trace[1769385066] 'process raft request' (duration: 164.298417ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:38.290243Z","caller":"traceutil/trace.go:171","msg":"trace[2065192457] transaction","detail":"{read_only:false; response_revision:27423; number_of_response:1; }","duration":"164.711097ms","start":"2026-08-08T01:36:38.125502Z","end":"2026-08-08T01:36:38.290213Z","steps":["trace[2065192457] 'process raft request' (duration: 164.563073ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:38.290338Z","caller":"traceutil/trace.go:171","msg":"trace[82245941] transaction","detail":"{read_only:false; response_revision:27420; number_of_response:1; }","duration":"165.276537ms","start":"2026-08-08T01:36:38.125006Z","end":"2026-08-08T01:36:38.290283Z","steps":["trace[82245941] 'process raft request' (duration: 164.429089ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:38.290578Z","caller":"traceutil/trace.go:171","msg":"trace[1504817951] transaction","detail":"{read_only:false; response_revision:27421; number_of_response:1; }","duration":"165.276668ms","start":"2026-08-08T01:36:38.125289Z","end":"2026-08-08T01:36:38.290565Z","steps":["trace[1504817951] 'process raft request' (duration: 164.535452ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:38.290583Z","caller":"traceutil/trace.go:171","msg":"trace[1130370326] transaction","detail":"{read_only:false; response_revision:27422; number_of_response:1; }","duration":"165.225567ms","start":"2026-08-08T01:36:38.125327Z","end":"2026-08-08T01:36:38.290553Z","steps":["trace[1130370326] 'process raft request' (duration: 164.671755ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:38.91956Z","caller":"traceutil/trace.go:171","msg":"trace[1910002518] transaction","detail":"{read_only:false; response_revision:27426; number_of_response:1; }","duration":"101.000368ms","start":"2026-08-08T01:36:38.818532Z","end":"2026-08-08T01:36:38.919533Z","steps":["trace[1910002518] 'process raft request' (duration: 100.546189ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:39.865843Z","caller":"traceutil/trace.go:171","msg":"trace[1111880808] linearizableReadLoop","detail":"{readStateIndex:28311; appliedIndex:28310; }","duration":"141.372481ms","start":"2026-08-08T01:36:39.724449Z","end":"2026-08-08T01:36:39.865822Z","steps":["trace[1111880808] 'read index received' (duration: 141.169837ms)","trace[1111880808] 'applied index is now lower than readState.Index' (duration: 201.994µs)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:36:39.866231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.763459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:39.866271Z","caller":"traceutil/trace.go:171","msg":"trace[1864879802] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27429; }","duration":"141.860271ms","start":"2026-08-08T01:36:39.724401Z","end":"2026-08-08T01:36:39.866262Z","steps":["trace[1864879802] 'agreement among raft nodes before linearized reading' (duration: 141.775259ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:39.86624Z","caller":"traceutil/trace.go:171","msg":"trace[549797529] transaction","detail":"{read_only:false; response_revision:27429; number_of_response:1; }","duration":"141.83127ms","start":"2026-08-08T01:36:39.724351Z","end":"2026-08-08T01:36:39.866182Z","steps":["trace[549797529] 'process raft request' (duration: 141.32056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:39.866369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.733667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"warn","ts":"2026-08-08T01:36:39.866231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.601825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T01:36:39.866376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.669986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:39.86643Z","caller":"traceutil/trace.go:171","msg":"trace[1358287582] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27429; }","duration":"139.804449ms","start":"2026-08-08T01:36:39.726616Z","end":"2026-08-08T01:36:39.86642Z","steps":["trace[1358287582] 'agreement among raft nodes before linearized reading' (duration: 139.640046ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:39.866474Z","caller":"traceutil/trace.go:171","msg":"trace[1919821205] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27429; }","duration":"139.797479ms","start":"2026-08-08T01:36:39.726652Z","end":"2026-08-08T01:36:39.866449Z","steps":["trace[1919821205] 'agreement among raft nodes before linearized reading' (duration: 139.587434ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:39.866437Z","caller":"traceutil/trace.go:171","msg":"trace[113482165] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27429; }","duration":"141.840379ms","start":"2026-08-08T01:36:39.72459Z","end":"2026-08-08T01:36:39.86643Z","steps":["trace[113482165] 'agreement among raft nodes before linearized reading' (duration: 141.601035ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:39.866613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.931712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-08-08T01:36:39.866723Z","caller":"traceutil/trace.go:171","msg":"trace[1239387154] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27429; }","duration":"142.086715ms","start":"2026-08-08T01:36:39.72461Z","end":"2026-08-08T01:36:39.866697Z","steps":["trace[1239387154] 'agreement among raft nodes before linearized reading' (duration: 141.730328ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:39.866659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.969332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:39.866854Z","caller":"traceutil/trace.go:171","msg":"trace[299504859] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27429; }","duration":"140.166106ms","start":"2026-08-08T01:36:39.726673Z","end":"2026-08-08T01:36:39.86684Z","steps":["trace[299504859] 'agreement among raft nodes before linearized reading' (duration: 139.958112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:42.853757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.308346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-08-08T01:36:42.853871Z","caller":"traceutil/trace.go:171","msg":"trace[153068379] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:27455; }","duration":"102.470359ms","start":"2026-08-08T01:36:42.751374Z","end":"2026-08-08T01:36:42.853844Z","steps":["trace[153068379] 'range keys from in-memory index tree' (duration: 102.0777ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:43.170692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.359168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/rook-ceph/rook-ceph-operator-config\" ","response":"range_response_count:1 size:6825"} {"level":"info","ts":"2026-08-08T01:36:43.170794Z","caller":"traceutil/trace.go:171","msg":"trace[1311501187] range","detail":"{range_begin:/registry/configmaps/rook-ceph/rook-ceph-operator-config; range_end:; response_count:1; response_revision:27456; }","duration":"215.522251ms","start":"2026-08-08T01:36:42.955246Z","end":"2026-08-08T01:36:43.170768Z","steps":["trace[1311501187] 'range keys from in-memory index tree' (duration: 215.151814ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:43.171087Z","caller":"traceutil/trace.go:171","msg":"trace[713359482] linearizableReadLoop","detail":"{readStateIndex:28340; appliedIndex:28339; }","duration":"139.88202ms","start":"2026-08-08T01:36:43.031121Z","end":"2026-08-08T01:36:43.171003Z","steps":["trace[713359482] 'read index received' (duration: 42.960705ms)","trace[713359482] 'applied index is now lower than readState.Index' (duration: 96.918265ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:36:43.171153Z","caller":"traceutil/trace.go:171","msg":"trace[1509260137] transaction","detail":"{read_only:false; response_revision:27457; number_of_response:1; }","duration":"140.427581ms","start":"2026-08-08T01:36:43.030674Z","end":"2026-08-08T01:36:43.171102Z","steps":["trace[1509260137] 'process raft request' (duration: 43.392163ms)","trace[1509260137] 'compare' (duration: 96.65717ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:36:43.171309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.147666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:43.171384Z","caller":"traceutil/trace.go:171","msg":"trace[795560661] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:27457; }","duration":"140.315239ms","start":"2026-08-08T01:36:43.031045Z","end":"2026-08-08T01:36:43.17136Z","steps":["trace[795560661] 'agreement among raft nodes before linearized reading' (duration: 140.147035ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:43.995333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.438843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T01:36:43.995423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.67114ms","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-08-08T01:36:43.995507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.476664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:43.995549Z","caller":"traceutil/trace.go:171","msg":"trace[2088196571] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27460; }","duration":"118.538435ms","start":"2026-08-08T01:36:43.877Z","end":"2026-08-08T01:36:43.995539Z","steps":["trace[2088196571] 'range keys from in-memory index tree' (duration: 118.429263ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:43.995457Z","caller":"traceutil/trace.go:171","msg":"trace[213253567] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27460; }","duration":"116.632126ms","start":"2026-08-08T01:36:43.878793Z","end":"2026-08-08T01:36:43.995425Z","steps":["trace[213253567] 'range keys from in-memory index tree' (duration: 115.37186ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:43.995562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.81484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:43.995643Z","caller":"traceutil/trace.go:171","msg":"trace[1455375243] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27460; }","duration":"115.959992ms","start":"2026-08-08T01:36:43.879665Z","end":"2026-08-08T01:36:43.995625Z","steps":["trace[1455375243] 'range keys from in-memory index tree' (duration: 115.604705ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:43.995542Z","caller":"traceutil/trace.go:171","msg":"trace[2002556357] 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:27460; }","duration":"152.870334ms","start":"2026-08-08T01:36:43.842643Z","end":"2026-08-08T01:36:43.995513Z","steps":["trace[2002556357] 'range keys from in-memory index tree' (duration: 152.563218ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:43.995877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.360381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-08-08T01:36:43.995913Z","caller":"traceutil/trace.go:171","msg":"trace[1526628813] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27460; }","duration":"117.411282ms","start":"2026-08-08T01:36:43.878492Z","end":"2026-08-08T01:36:43.995903Z","steps":["trace[1526628813] 'range keys from in-memory index tree' (duration: 117.215219ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:43.9959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.080785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:43.995994Z","caller":"traceutil/trace.go:171","msg":"trace[316063086] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27460; }","duration":"117.243949ms","start":"2026-08-08T01:36:43.87873Z","end":"2026-08-08T01:36:43.995974Z","steps":["trace[316063086] 'range keys from in-memory index tree' (duration: 116.016623ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:43.996067Z","caller":"traceutil/trace.go:171","msg":"trace[1948826741] linearizableReadLoop","detail":"{readStateIndex:28344; appliedIndex:28343; }","duration":"114.734138ms","start":"2026-08-08T01:36:43.88132Z","end":"2026-08-08T01:36:43.996054Z","steps":["trace[1948826741] 'read index received' (duration: 48.364426ms)","trace[1948826741] 'applied index is now lower than readState.Index' (duration: 66.368402ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:36:43.996177Z","caller":"traceutil/trace.go:171","msg":"trace[955304025] transaction","detail":"{read_only:false; response_revision:27461; number_of_response:1; }","duration":"115.880471ms","start":"2026-08-08T01:36:43.880281Z","end":"2026-08-08T01:36:43.996161Z","steps":["trace[955304025] 'process raft request' (duration: 49.419828ms)","trace[955304025] 'compare' (duration: 65.834261ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:36:43.996274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.979002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-08-08T01:36:43.996311Z","caller":"traceutil/trace.go:171","msg":"trace[1533469283] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27461; }","duration":"115.077825ms","start":"2026-08-08T01:36:43.881224Z","end":"2026-08-08T01:36:43.996302Z","steps":["trace[1533469283] 'agreement among raft nodes before linearized reading' (duration: 114.888901ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:49.933339Z","caller":"traceutil/trace.go:171","msg":"trace[358179963] linearizableReadLoop","detail":"{readStateIndex:28390; appliedIndex:28389; }","duration":"188.967041ms","start":"2026-08-08T01:36:49.744339Z","end":"2026-08-08T01:36:49.933306Z","steps":["trace[358179963] 'read index received' (duration: 188.698785ms)","trace[358179963] 'applied index is now lower than readState.Index' (duration: 266.646µs)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:36:49.93351Z","caller":"traceutil/trace.go:171","msg":"trace[684874093] transaction","detail":"{read_only:false; response_revision:27506; number_of_response:1; }","duration":"354.82269ms","start":"2026-08-08T01:36:49.578672Z","end":"2026-08-08T01:36:49.933494Z","steps":["trace[684874093] 'process raft request' (duration: 354.404741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:49.933658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:36:49.578628Z","time spent":"354.921412ms","remote":"127.0.0.1:33686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-08T01:36:49.93378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.111479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T01:36:49.933857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.494761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:49.933906Z","caller":"traceutil/trace.go:171","msg":"trace[1580619000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27506; }","duration":"155.295203ms","start":"2026-08-08T01:36:49.77858Z","end":"2026-08-08T01:36:49.933875Z","steps":["trace[1580619000] 'agreement among raft nodes before linearized reading' (duration: 155.083629ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:49.933903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.117787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:49.933966Z","caller":"traceutil/trace.go:171","msg":"trace[351632928] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27506; }","duration":"189.655904ms","start":"2026-08-08T01:36:49.744279Z","end":"2026-08-08T01:36:49.933935Z","steps":["trace[351632928] 'agreement among raft nodes before linearized reading' (duration: 189.483281ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:49.933963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.038495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:49.934088Z","caller":"traceutil/trace.go:171","msg":"trace[1858614034] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27506; }","duration":"143.237519ms","start":"2026-08-08T01:36:49.790822Z","end":"2026-08-08T01:36:49.934059Z","steps":["trace[1858614034] 'agreement among raft nodes before linearized reading' (duration: 142.989654ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:49.933812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.763949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:49.934163Z","caller":"traceutil/trace.go:171","msg":"trace[1005956089] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27506; }","duration":"143.137917ms","start":"2026-08-08T01:36:49.791005Z","end":"2026-08-08T01:36:49.934143Z","steps":["trace[1005956089] 'agreement among raft nodes before linearized reading' (duration: 142.733609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:49.934007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.929173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-08-08T01:36:49.934316Z","caller":"traceutil/trace.go:171","msg":"trace[916156426] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27506; }","duration":"143.242579ms","start":"2026-08-08T01:36:49.791057Z","end":"2026-08-08T01:36:49.934299Z","steps":["trace[916156426] 'agreement among raft nodes before linearized reading' (duration: 142.80284ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:49.934099Z","caller":"traceutil/trace.go:171","msg":"trace[362543843] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27506; }","duration":"142.389461ms","start":"2026-08-08T01:36:49.791682Z","end":"2026-08-08T01:36:49.934072Z","steps":["trace[362543843] 'agreement among raft nodes before linearized reading' (duration: 142.068055ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:50.139744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.965297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T01:36:50.139779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.031377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:50.139893Z","caller":"traceutil/trace.go:171","msg":"trace[1038941939] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27506; }","duration":"125.16734ms","start":"2026-08-08T01:36:50.014694Z","end":"2026-08-08T01:36:50.139862Z","steps":["trace[1038941939] 'range keys from in-memory index tree' (duration: 124.911044ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:50.139828Z","caller":"traceutil/trace.go:171","msg":"trace[1936297964] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27506; }","duration":"126.062359ms","start":"2026-08-08T01:36:50.013747Z","end":"2026-08-08T01:36:50.139809Z","steps":["trace[1936297964] 'range keys from in-memory index tree' (duration: 125.840953ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:50.140141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.15699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-08-08T01:36:50.140184Z","caller":"traceutil/trace.go:171","msg":"trace[783492855] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27506; }","duration":"124.214151ms","start":"2026-08-08T01:36:50.015956Z","end":"2026-08-08T01:36:50.14017Z","steps":["trace[783492855] 'range keys from in-memory index tree' (duration: 123.978237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:50.140183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.540138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:50.140298Z","caller":"traceutil/trace.go:171","msg":"trace[769254359] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27506; }","duration":"125.691741ms","start":"2026-08-08T01:36:50.014578Z","end":"2026-08-08T01:36:50.140269Z","steps":["trace[769254359] 'range keys from in-memory index tree' (duration: 125.455096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:50.140196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.582629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-08T01:36:50.140372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.764062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-08-08T01:36:50.140436Z","caller":"traceutil/trace.go:171","msg":"trace[1900321034] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27506; }","duration":"125.862634ms","start":"2026-08-08T01:36:50.014542Z","end":"2026-08-08T01:36:50.140405Z","steps":["trace[1900321034] 'range keys from in-memory index tree' (duration: 125.507257ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:50.140501Z","caller":"traceutil/trace.go:171","msg":"trace[2066083504] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27506; }","duration":"125.936936ms","start":"2026-08-08T01:36:50.014531Z","end":"2026-08-08T01:36:50.140468Z","steps":["trace[2066083504] 'range keys from in-memory index tree' (duration: 125.513817ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:50.144006Z","caller":"traceutil/trace.go:171","msg":"trace[26253399] transaction","detail":"{read_only:false; response_revision:27507; number_of_response:1; }","duration":"127.212951ms","start":"2026-08-08T01:36:50.016773Z","end":"2026-08-08T01:36:50.143986Z","steps":["trace[26253399] 'process raft request' (duration: 127.040068ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:50.159977Z","caller":"traceutil/trace.go:171","msg":"trace[480723961] transaction","detail":"{read_only:false; response_revision:27508; number_of_response:1; }","duration":"141.546225ms","start":"2026-08-08T01:36:50.018394Z","end":"2026-08-08T01:36:50.15994Z","steps":["trace[480723961] 'process raft request' (duration: 141.271339ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:52.3962Z","caller":"traceutil/trace.go:171","msg":"trace[905951191] linearizableReadLoop","detail":"{readStateIndex:28407; appliedIndex:28406; }","duration":"150.16162ms","start":"2026-08-08T01:36:52.246001Z","end":"2026-08-08T01:36:52.396163Z","steps":["trace[905951191] 'read index received' (duration: 50.978948ms)","trace[905951191] 'applied index is now lower than readState.Index' (duration: 99.181552ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:36:52.396527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.494796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-08T01:36:52.396264Z","caller":"traceutil/trace.go:171","msg":"trace[720446698] transaction","detail":"{read_only:false; response_revision:27522; number_of_response:1; }","duration":"230.22032ms","start":"2026-08-08T01:36:52.165965Z","end":"2026-08-08T01:36:52.396186Z","steps":["trace[720446698] 'process raft request' (duration: 131.154752ms)","trace[720446698] 'compare' (duration: 98.893555ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:36:52.39663Z","caller":"traceutil/trace.go:171","msg":"trace[1664668731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27522; }","duration":"150.66035ms","start":"2026-08-08T01:36:52.245944Z","end":"2026-08-08T01:36:52.396605Z","steps":["trace[1664668731] 'agreement among raft nodes before linearized reading' (duration: 150.352174ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:54.681218Z","caller":"traceutil/trace.go:171","msg":"trace[162396022] linearizableReadLoop","detail":"{readStateIndex:28423; appliedIndex:28422; }","duration":"221.093195ms","start":"2026-08-08T01:36:54.46009Z","end":"2026-08-08T01:36:54.681183Z","steps":["trace[162396022] 'read index received' (duration: 220.903961ms)","trace[162396022] 'applied index is now lower than readState.Index' (duration: 187.654µs)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:36:54.681348Z","caller":"traceutil/trace.go:171","msg":"trace[1442650360] transaction","detail":"{read_only:false; response_revision:27538; number_of_response:1; }","duration":"253.783132ms","start":"2026-08-08T01:36:54.427512Z","end":"2026-08-08T01:36:54.681295Z","steps":["trace[1442650360] 'process raft request' (duration: 253.436585ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:54.681574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.529172ms","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-08-08T01:36:54.681652Z","caller":"traceutil/trace.go:171","msg":"trace[1524821097] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27538; }","duration":"242.664715ms","start":"2026-08-08T01:36:54.438966Z","end":"2026-08-08T01:36:54.681631Z","steps":["trace[1524821097] 'agreement among raft nodes before linearized reading' (duration: 242.333298ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:54.681728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.458313ms","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-08-08T01:36:54.681784Z","caller":"traceutil/trace.go:171","msg":"trace[2109035785] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27538; }","duration":"247.570106ms","start":"2026-08-08T01:36:54.4342Z","end":"2026-08-08T01:36:54.68177Z","steps":["trace[2109035785] 'agreement among raft nodes before linearized reading' (duration: 247.344321ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:54.681756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.715377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-08-08T01:36:54.681793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.935381ms","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-08-08T01:36:54.681861Z","caller":"traceutil/trace.go:171","msg":"trace[1223307662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27538; }","duration":"245.85712ms","start":"2026-08-08T01:36:54.435976Z","end":"2026-08-08T01:36:54.681834Z","steps":["trace[1223307662] 'agreement among raft nodes before linearized reading' (duration: 245.257478ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:54.681889Z","caller":"traceutil/trace.go:171","msg":"trace[2007485801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27538; }","duration":"246.081684ms","start":"2026-08-08T01:36:54.435779Z","end":"2026-08-08T01:36:54.681861Z","steps":["trace[2007485801] 'agreement among raft nodes before linearized reading' (duration: 245.830409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:54.681971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.560854ms","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-08-08T01:36:54.682027Z","caller":"traceutil/trace.go:171","msg":"trace[315280834] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:27538; }","duration":"273.679716ms","start":"2026-08-08T01:36:54.408326Z","end":"2026-08-08T01:36:54.682006Z","steps":["trace[315280834] 'agreement among raft nodes before linearized reading' (duration: 273.449322ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:54.682075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.157035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-08-08T01:36:54.682115Z","caller":"traceutil/trace.go:171","msg":"trace[1280490331] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:27538; }","duration":"246.201627ms","start":"2026-08-08T01:36:54.435898Z","end":"2026-08-08T01:36:54.6821Z","steps":["trace[1280490331] 'agreement among raft nodes before linearized reading' (duration: 245.469292ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:54.743198Z","caller":"traceutil/trace.go:171","msg":"trace[82115392] transaction","detail":"{read_only:false; response_revision:27540; number_of_response:1; }","duration":"158.259485ms","start":"2026-08-08T01:36:54.584909Z","end":"2026-08-08T01:36:54.743168Z","steps":["trace[82115392] 'process raft request' (duration: 158.194113ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:54.743741Z","caller":"traceutil/trace.go:171","msg":"trace[769025357] transaction","detail":"{read_only:false; response_revision:27539; number_of_response:1; }","duration":"279.588467ms","start":"2026-08-08T01:36:54.46414Z","end":"2026-08-08T01:36:54.743728Z","steps":["trace[769025357] 'process raft request' (duration: 278.76584ms)"],"step_count":1} {"level":"warn","ts":"2026-08-08T01:36:54.848837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.606149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:54.84893Z","caller":"traceutil/trace.go:171","msg":"trace[1065634669] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:27540; }","duration":"284.799622ms","start":"2026-08-08T01:36:54.564107Z","end":"2026-08-08T01:36:54.848906Z","steps":["trace[1065634669] 'agreement among raft nodes before linearized reading' (duration: 182.368276ms)","trace[1065634669] 'count revisions from in-memory index tree' (duration: 102.126451ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:36:54.848965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.411598ms","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-08-08T01:36:54.849082Z","caller":"traceutil/trace.go:171","msg":"trace[1169017215] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27540; }","duration":"321.595482ms","start":"2026-08-08T01:36:54.527455Z","end":"2026-08-08T01:36:54.84905Z","steps":["trace[1169017215] 'agreement among raft nodes before linearized reading' (duration: 219.053203ms)","trace[1169017215] 'range keys from in-memory index tree' (duration: 102.184991ms)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:36:54.849143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-08T01:36:54.527432Z","time spent":"321.698095ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-08T01:36:54.856372Z","caller":"traceutil/trace.go:171","msg":"trace[1738427607] transaction","detail":"{read_only:false; response_revision:27541; number_of_response:1; }","duration":"169.395191ms","start":"2026-08-08T01:36:54.686945Z","end":"2026-08-08T01:36:54.85634Z","steps":["trace[1738427607] 'process raft request' (duration: 169.104735ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:54.856372Z","caller":"traceutil/trace.go:171","msg":"trace[1310620666] transaction","detail":"{read_only:false; response_revision:27542; number_of_response:1; }","duration":"169.235788ms","start":"2026-08-08T01:36:54.687105Z","end":"2026-08-08T01:36:54.856341Z","steps":["trace[1310620666] 'process raft request' (duration: 169.140336ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:54.85661Z","caller":"traceutil/trace.go:171","msg":"trace[17712841] transaction","detail":"{read_only:false; response_revision:27543; number_of_response:1; }","duration":"167.962482ms","start":"2026-08-08T01:36:54.688618Z","end":"2026-08-08T01:36:54.856581Z","steps":["trace[17712841] 'process raft request' (duration: 167.669796ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:54.856828Z","caller":"traceutil/trace.go:171","msg":"trace[1235321389] transaction","detail":"{read_only:false; response_revision:27544; number_of_response:1; }","duration":"168.098235ms","start":"2026-08-08T01:36:54.68871Z","end":"2026-08-08T01:36:54.856808Z","steps":["trace[1235321389] 'process raft request' (duration: 167.811599ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:54.856952Z","caller":"traceutil/trace.go:171","msg":"trace[305221020] transaction","detail":"{read_only:false; response_revision:27545; number_of_response:1; }","duration":"168.127645ms","start":"2026-08-08T01:36:54.688815Z","end":"2026-08-08T01:36:54.856942Z","steps":["trace[305221020] 'process raft request' (duration: 167.86367ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:54.856967Z","caller":"traceutil/trace.go:171","msg":"trace[723461233] transaction","detail":"{read_only:false; response_revision:27546; number_of_response:1; }","duration":"168.078055ms","start":"2026-08-08T01:36:54.688878Z","end":"2026-08-08T01:36:54.856956Z","steps":["trace[723461233] 'process raft request' (duration: 167.886771ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:54.856923Z","caller":"traceutil/trace.go:171","msg":"trace[684596166] linearizableReadLoop","detail":"{readStateIndex:28431; appliedIndex:28425; }","duration":"110.46251ms","start":"2026-08-08T01:36:54.746434Z","end":"2026-08-08T01:36:54.856897Z","steps":["trace[684596166] 'read index received' (duration: 109.516401ms)","trace[684596166] 'applied index is now lower than readState.Index' (duration: 944.489µs)"],"step_count":2} {"level":"warn","ts":"2026-08-08T01:36:54.857051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.450639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-08T01:36:54.857094Z","caller":"traceutil/trace.go:171","msg":"trace[1252581203] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:27546; }","duration":"133.553631ms","start":"2026-08-08T01:36:54.723528Z","end":"2026-08-08T01:36:54.857082Z","steps":["trace[1252581203] 'agreement among raft nodes before linearized reading' (duration: 133.460749ms)"],"step_count":1} {"level":"info","ts":"2026-08-08T01:36:56.865633Z","caller":"traceutil/trace.go:171","msg":"trace[29036588] transaction","detail":"{read_only:false; response_revision:27560; number_of_response:1; }","duration":"110.547302ms","start":"2026-08-08T01:36:56.755041Z","end":"2026-08-08T01:36:56.865588Z","steps":["trace[29036588] 'process raft request' (duration: 74.907836ms)","trace[29036588] 'compare' (duration: 35.460892ms)"],"step_count":2} {"level":"info","ts":"2026-08-08T01:38:52.982031Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25970} {"level":"info","ts":"2026-08-08T01:38:53.152226Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25970,"took":"163.210556ms","hash":4202141359,"current-db-size-bytes":59359232,"current-db-size":"59 MB","current-db-size-in-use-bytes":34820096,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-08T01:38:53.152302Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4202141359,"revision":25970,"compact-revision":22412} {"level":"info","ts":"2026-08-08T01:39:37.421156Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-08T01:39:37.426341Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-08T01:39:37.426471Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-08T01:43:52.991051Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28609} {"level":"info","ts":"2026-08-08T01:43:53.137281Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28609,"took":"141.455842ms","hash":2996312929,"current-db-size-bytes":59359232,"current-db-size":"59 MB","current-db-size-in-use-bytes":33931264,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-08T01:43:53.137372Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2996312929,"revision":28609,"compact-revision":25970} {"level":"info","ts":"2026-08-08T01:48:52.997799Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31148} {"level":"info","ts":"2026-08-08T01:48:53.13634Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31148,"took":"133.875535ms","hash":3121968544,"current-db-size-bytes":59359232,"current-db-size":"59 MB","current-db-size-in-use-bytes":31457280,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-08T01:48:53.136426Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3121968544,"revision":31148,"compact-revision":28609} {"level":"info","ts":"2026-08-08T01:53:53.004812Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33451} {"level":"info","ts":"2026-08-08T01:53:53.16973Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33451,"took":"158.11298ms","hash":2987835696,"current-db-size-bytes":60403712,"current-db-size":"60 MB","current-db-size-in-use-bytes":31191040,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-08T01:53:53.1698Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2987835696,"revision":33451,"compact-revision":31148} {"level":"info","ts":"2026-08-08T01:58:53.011456Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35728} {"level":"info","ts":"2026-08-08T01:58:53.156595Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35728,"took":"139.389143ms","hash":3137077597,"current-db-size-bytes":60403712,"current-db-size":"60 MB","current-db-size-in-use-bytes":32518144,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-08T01:58:53.156702Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3137077597,"revision":35728,"compact-revision":33451} {"level":"info","ts":"2026-08-08T02:00:22.642016Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-08T02:00:22.647229Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-08T02:00:22.647341Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-08T02:03:53.018315Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38065} {"level":"info","ts":"2026-08-08T02:03:53.137871Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38065,"took":"114.865187ms","hash":3462672944,"current-db-size-bytes":61247488,"current-db-size":"61 MB","current-db-size-in-use-bytes":32305152,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-08T02:03:53.137941Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3462672944,"revision":38065,"compact-revision":35728} {"level":"info","ts":"2026-08-08T02:08:53.023954Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":40346} {"level":"info","ts":"2026-08-08T02:08:53.159659Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":40346,"took":"123.585284ms","hash":2775219008,"current-db-size-bytes":61247488,"current-db-size":"61 MB","current-db-size-in-use-bytes":29990912,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-08T02:08:53.15977Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2775219008,"revision":40346,"compact-revision":38065} {"level":"info","ts":"2026-08-08T02:13:53.030864Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":42551} {"level":"info","ts":"2026-08-08T02:13:53.141545Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":42551,"took":"107.192589ms","hash":101784782,"current-db-size-bytes":61247488,"current-db-size":"61 MB","current-db-size-in-use-bytes":31539200,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-08T02:13:53.141606Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":101784782,"revision":42551,"compact-revision":40346}