{"level":"warn","ts":"2026-05-09T16:55:45.79198Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T16:55:45.792059Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49: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.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-09T16:55:45.792118Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-09T16:55:45.792131Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-05-09T16:55:45.79215Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T16:55:45.792726Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-05-09T16:55:45.792797Z","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.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-09T16:55:45.797606Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.617892ms"} {"level":"info","ts":"2026-05-09T16:55:45.816636Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-05-09T16:55:45.816761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-05-09T16:55:45.81679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-05-09T16:55:45.816813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-09T16:55:45.816831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-05-09T16:55:45.816891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-05-09T16:55:45.824137Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-09T16:55:45.826155Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-09T16:55:45.827951Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-09T16:55:45.830661Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-09T16:55:45.830819Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-09T16:55:45.830981Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T16:55:45.831039Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T16:55:45.831053Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-09T16:55:45.833257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-05-09T16:55:45.8335Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-05-09T16:55:45.834845Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-09T16:55:45.835023Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-05-09T16:55:45.83507Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-05-09T16:55:45.835094Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-09T16:55:45.835149Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-09T16:55:46.217742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-05-09T16:55:46.217814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-09T16:55:46.217887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-05-09T16:55:46.217914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-05-09T16:55:46.217954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-05-09T16:55:46.217998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-05-09T16:55:46.218017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-05-09T16:55:46.219325Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-05-09T16:55:46.219346Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T16:55:46.219381Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-09T16:55:46.21935Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:55:46.219696Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-09T16:55:46.219762Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-09T16:55:46.222811Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-05-09T16:55:46.223093Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-09T16:55:46.230099Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:55:46.230394Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:55:46.230467Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-09T16:56:11.822831Z","caller":"traceutil/trace.go:171","msg":"trace[887809022] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"154.360417ms","start":"2026-05-09T16:56:11.668445Z","end":"2026-05-09T16:56:11.822805Z","steps":["trace[887809022] 'process raft request' (duration: 154.234924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:56:12.045212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.022497ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:56:12.04562Z","caller":"traceutil/trace.go:171","msg":"trace[2030832273] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"175.270218ms","start":"2026-05-09T16:56:11.870329Z","end":"2026-05-09T16:56:12.045599Z","steps":["trace[2030832273] 'process raft request' (duration: 70.611568ms)","trace[2030832273] 'compare' (duration: 99.897783ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:56:21.311263Z","caller":"traceutil/trace.go:171","msg":"trace[693044392] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"104.903317ms","start":"2026-05-09T16:56:21.206336Z","end":"2026-05-09T16:56:21.311239Z","steps":["trace[693044392] 'process raft request' (duration: 97.66826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:56:21.64559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.946899ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:56:21.64567Z","caller":"traceutil/trace.go:171","msg":"trace[1274255365] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"185.865197ms","start":"2026-05-09T16:56:21.459792Z","end":"2026-05-09T16:56:21.645657Z","steps":["trace[1274255365] 'read index received' (duration: 34.261µs)","trace[1274255365] 'applied index is now lower than readState.Index' (duration: 185.830036ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:56:21.645789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.99013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-09T16:56:21.645827Z","caller":"traceutil/trace.go:171","msg":"trace[311580736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"186.041102ms","start":"2026-05-09T16:56:21.459762Z","end":"2026-05-09T16:56:21.645803Z","steps":["trace[311580736] 'agreement among raft nodes before linearized reading' (duration: 185.926419ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:56:21.645735Z","caller":"traceutil/trace.go:171","msg":"trace[749883765] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"249.164906ms","start":"2026-05-09T16:56:21.396509Z","end":"2026-05-09T16:56:21.645674Z","steps":["trace[749883765] 'process raft request' (duration: 25.069183ms)","trace[749883765] 'compare' (duration: 223.832567ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:56:22.098749Z","caller":"traceutil/trace.go:171","msg":"trace[765597435] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"375.916427ms","start":"2026-05-09T16:56:21.722806Z","end":"2026-05-09T16:56:22.098723Z","steps":["trace[765597435] 'read index received' (duration: 375.200928ms)","trace[765597435] 'applied index is now lower than readState.Index' (duration: 714.448µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:56:22.098949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.122232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j8kgl\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-09T16:56:22.09894Z","caller":"traceutil/trace.go:171","msg":"trace[1072096270] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"380.578061ms","start":"2026-05-09T16:56:21.718223Z","end":"2026-05-09T16:56:22.098801Z","steps":["trace[1072096270] 'process raft request' (duration: 379.981787ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:56:22.099021Z","caller":"traceutil/trace.go:171","msg":"trace[1259702466] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j8kgl; range_end:; response_count:1; response_revision:579; }","duration":"376.234544ms","start":"2026-05-09T16:56:21.722767Z","end":"2026-05-09T16:56:22.099001Z","steps":["trace[1259702466] 'agreement among raft nodes before linearized reading' (duration: 376.102581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:56:22.099414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:56:21.722753Z","time spent":"376.648505ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-j8kgl\" "} {"level":"warn","ts":"2026-05-09T16:56:22.099106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.399194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-09T16:56:22.099493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T16:56:21.718187Z","time spent":"381.089224ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T16:56:22.099534Z","caller":"traceutil/trace.go:171","msg":"trace[664514795] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:579; }","duration":"236.870626ms","start":"2026-05-09T16:56:21.862633Z","end":"2026-05-09T16:56:22.099504Z","steps":["trace[664514795] 'agreement among raft nodes before linearized reading' (duration: 236.325193ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:57:50.873715Z","caller":"traceutil/trace.go:171","msg":"trace[1189152626] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"108.843419ms","start":"2026-05-09T16:57:50.764855Z","end":"2026-05-09T16:57:50.873698Z","steps":["trace[1189152626] 'process raft request' (duration: 108.748497ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:57:51.030245Z","caller":"traceutil/trace.go:171","msg":"trace[839861501] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"127.940853ms","start":"2026-05-09T16:57:50.90228Z","end":"2026-05-09T16:57:51.030221Z","steps":["trace[839861501] 'process raft request' (duration: 127.80361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:57:51.271843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.536073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-09T16:57:51.27194Z","caller":"traceutil/trace.go:171","msg":"trace[519422848] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1806; }","duration":"155.667836ms","start":"2026-05-09T16:57:51.116246Z","end":"2026-05-09T16:57:51.271914Z","steps":["trace[519422848] 'range keys from in-memory index tree' (duration: 155.340699ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:40.890628Z","caller":"traceutil/trace.go:171","msg":"trace[1442595312] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"108.872269ms","start":"2026-05-09T16:58:40.781731Z","end":"2026-05-09T16:58:40.890603Z","steps":["trace[1442595312] 'process raft request' (duration: 108.735346ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:58:40.898138Z","caller":"traceutil/trace.go:171","msg":"trace[348767726] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"114.666898ms","start":"2026-05-09T16:58:40.783442Z","end":"2026-05-09T16:58:40.898109Z","steps":["trace[348767726] 'process raft request' (duration: 114.460943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:46.755287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.354281ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T16:58:46.755498Z","caller":"traceutil/trace.go:171","msg":"trace[1438916477] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"294.129642ms","start":"2026-05-09T16:58:46.461334Z","end":"2026-05-09T16:58:46.755464Z","steps":["trace[1438916477] 'process raft request' (duration: 65.496903ms)","trace[1438916477] 'compare' (duration: 227.973393ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:58:47.068452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.93791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-09T16:58:47.068517Z","caller":"traceutil/trace.go:171","msg":"trace[2061183641] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2153; }","duration":"153.027373ms","start":"2026-05-09T16:58:46.915473Z","end":"2026-05-09T16:58:47.0685Z","steps":["trace[2061183641] 'range keys from in-memory index tree' (duration: 152.543041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:58:47.068448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.002687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-09T16:58:47.068629Z","caller":"traceutil/trace.go:171","msg":"trace[1440372088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2153; }","duration":"154.242162ms","start":"2026-05-09T16:58:46.914372Z","end":"2026-05-09T16:58:47.068614Z","steps":["trace[1440372088] 'range keys from in-memory index tree' (duration: 153.787471ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:59:21.609552Z","caller":"traceutil/trace.go:171","msg":"trace[1594241516] transaction","detail":"{read_only:false; response_revision:2520; number_of_response:1; }","duration":"133.829344ms","start":"2026-05-09T16:59:21.475689Z","end":"2026-05-09T16:59:21.609519Z","steps":["trace[1594241516] 'process raft request' (duration: 133.681031ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:59:55.099937Z","caller":"traceutil/trace.go:171","msg":"trace[1967567237] transaction","detail":"{read_only:false; response_revision:2720; number_of_response:1; }","duration":"114.688307ms","start":"2026-05-09T16:59:54.985207Z","end":"2026-05-09T16:59:55.099895Z","steps":["trace[1967567237] 'process raft request' (duration: 114.41985ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T16:59:55.647664Z","caller":"traceutil/trace.go:171","msg":"trace[1929249501] linearizableReadLoop","detail":"{readStateIndex:2824; appliedIndex:2822; }","duration":"225.483831ms","start":"2026-05-09T16:59:55.422131Z","end":"2026-05-09T16:59:55.647615Z","steps":["trace[1929249501] 'read index received' (duration: 36.549382ms)","trace[1929249501] 'applied index is now lower than readState.Index' (duration: 188.933329ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T16:59:55.647775Z","caller":"traceutil/trace.go:171","msg":"trace[1935953152] transaction","detail":"{read_only:false; response_revision:2722; number_of_response:1; }","duration":"254.78455ms","start":"2026-05-09T16:59:55.392954Z","end":"2026-05-09T16:59:55.647739Z","steps":["trace[1935953152] 'process raft request' (duration: 210.130234ms)","trace[1935953152] 'compare' (duration: 44.148634ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T16:59:55.647963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.810459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-09T16:59:55.648012Z","caller":"traceutil/trace.go:171","msg":"trace[872447145] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2722; }","duration":"225.897301ms","start":"2026-05-09T16:59:55.422096Z","end":"2026-05-09T16:59:55.647993Z","steps":["trace[872447145] 'agreement among raft nodes before linearized reading' (duration: 225.726637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:59:55.648059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.546041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-09T16:59:55.648134Z","caller":"traceutil/trace.go:171","msg":"trace[57464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2722; }","duration":"222.641553ms","start":"2026-05-09T16:59:55.425474Z","end":"2026-05-09T16:59:55.648115Z","steps":["trace[57464] 'agreement among raft nodes before linearized reading' (duration: 222.449229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T16:59:55.64828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.873616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-05-09T16:59:55.648327Z","caller":"traceutil/trace.go:171","msg":"trace[488690961] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2722; }","duration":"193.956248ms","start":"2026-05-09T16:59:55.45436Z","end":"2026-05-09T16:59:55.648316Z","steps":["trace[488690961] 'agreement among raft nodes before linearized reading' (duration: 193.792644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:02:16.167866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.974276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-09T17:02:16.168012Z","caller":"traceutil/trace.go:171","msg":"trace[1633014633] linearizableReadLoop","detail":"{readStateIndex:3803; appliedIndex:3802; }","duration":"199.775344ms","start":"2026-05-09T17:02:15.968222Z","end":"2026-05-09T17:02:16.167998Z","steps":["trace[1633014633] 'read index received' (duration: 96.549172ms)","trace[1633014633] 'applied index is now lower than readState.Index' (duration: 103.225092ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:02:16.168033Z","caller":"traceutil/trace.go:171","msg":"trace[1698052497] transaction","detail":"{read_only:false; response_revision:3666; number_of_response:1; }","duration":"225.075598ms","start":"2026-05-09T17:02:15.942907Z","end":"2026-05-09T17:02:16.167982Z","steps":["trace[1698052497] 'process raft request' (duration: 121.894477ms)","trace[1698052497] 'compare' (duration: 102.750901ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:02:16.168362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.791196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T17:02:16.16839Z","caller":"traceutil/trace.go:171","msg":"trace[156707408] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:3667; }","duration":"103.861947ms","start":"2026-05-09T17:02:16.064522Z","end":"2026-05-09T17:02:16.168384Z","steps":["trace[156707408] 'agreement among raft nodes before linearized reading' (duration: 103.788796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:02:16.168645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.399629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-f8qz6\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-05-09T17:02:16.168727Z","caller":"traceutil/trace.go:171","msg":"trace[413729428] range","detail":"{range_begin:/registry/pods/openstack/keepalived-f8qz6; range_end:; response_count:1; response_revision:3667; }","duration":"200.514072ms","start":"2026-05-09T17:02:15.968196Z","end":"2026-05-09T17:02:16.16871Z","steps":["trace[413729428] 'agreement among raft nodes before linearized reading' (duration: 199.878847ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:03:35.044561Z","caller":"traceutil/trace.go:171","msg":"trace[587029603] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4671; }","duration":"120.328885ms","start":"2026-05-09T17:03:34.924197Z","end":"2026-05-09T17:03:35.044526Z","steps":["trace[587029603] 'process raft request' (duration: 120.07659ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:03:37.323541Z","caller":"traceutil/trace.go:171","msg":"trace[1455917116] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"106.657549ms","start":"2026-05-09T17:03:37.216854Z","end":"2026-05-09T17:03:37.323512Z","steps":["trace[1455917116] 'process raft request' (duration: 106.485195ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:03:42.077496Z","caller":"traceutil/trace.go:171","msg":"trace[918952013] linearizableReadLoop","detail":"{readStateIndex:5069; appliedIndex:5068; }","duration":"180.037247ms","start":"2026-05-09T17:03:41.89743Z","end":"2026-05-09T17:03:42.077468Z","steps":["trace[918952013] 'read index received' (duration: 175.495018ms)","trace[918952013] 'applied index is now lower than readState.Index' (duration: 4.540989ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:03:42.077672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.231511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T17:03:42.077728Z","caller":"traceutil/trace.go:171","msg":"trace[593623472] range","detail":"{range_begin:/registry/configmaps/monitoring/vector; range_end:; response_count:0; response_revision:4886; }","duration":"180.333284ms","start":"2026-05-09T17:03:41.897382Z","end":"2026-05-09T17:03:42.077716Z","steps":["trace[593623472] 'agreement among raft nodes before linearized reading' (duration: 180.239662ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:03:42.077595Z","caller":"traceutil/trace.go:171","msg":"trace[528417034] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"238.246612ms","start":"2026-05-09T17:03:41.839319Z","end":"2026-05-09T17:03:42.077565Z","steps":["trace[528417034] 'process raft request' (duration: 233.658603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:03:42.07799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.472386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-028a0a9f-4732-4280-970d-e226d633b29d\" ","response":"range_response_count:1 size:3600"} {"level":"warn","ts":"2026-05-09T17:03:42.078007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.397446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-028a0a9f-4732-4280-970d-e226d633b29d\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-05-09T17:03:42.078094Z","caller":"traceutil/trace.go:171","msg":"trace[629228905] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-028a0a9f-4732-4280-970d-e226d633b29d; range_end:; response_count:1; response_revision:4886; }","duration":"161.591718ms","start":"2026-05-09T17:03:41.916481Z","end":"2026-05-09T17:03:42.078072Z","steps":["trace[629228905] 'agreement among raft nodes before linearized reading' (duration: 161.22837ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:03:42.078165Z","caller":"traceutil/trace.go:171","msg":"trace[347381887] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-028a0a9f-4732-4280-970d-e226d633b29d; range_end:; response_count:1; response_revision:4886; }","duration":"159.58215ms","start":"2026-05-09T17:03:41.918552Z","end":"2026-05-09T17:03:42.078135Z","steps":["trace[347381887] 'agreement among raft nodes before linearized reading' (duration: 159.263422ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:03:42.505169Z","caller":"traceutil/trace.go:171","msg":"trace[1503278136] transaction","detail":"{read_only:false; response_revision:4897; number_of_response:1; }","duration":"142.677697ms","start":"2026-05-09T17:03:42.362466Z","end":"2026-05-09T17:03:42.505144Z","steps":["trace[1503278136] 'process raft request' (duration: 142.626616ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:03:42.505221Z","caller":"traceutil/trace.go:171","msg":"trace[1524138294] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"143.940437ms","start":"2026-05-09T17:03:42.361257Z","end":"2026-05-09T17:03:42.505197Z","steps":["trace[1524138294] 'process raft request' (duration: 143.66603ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:03:52.932945Z","caller":"traceutil/trace.go:171","msg":"trace[1228211405] linearizableReadLoop","detail":"{readStateIndex:5314; appliedIndex:5313; }","duration":"238.063509ms","start":"2026-05-09T17:03:52.694857Z","end":"2026-05-09T17:03:52.93292Z","steps":["trace[1228211405] 'read index received' (duration: 225.731296ms)","trace[1228211405] 'applied index is now lower than readState.Index' (duration: 12.331223ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:03:52.933075Z","caller":"traceutil/trace.go:171","msg":"trace[417361448] transaction","detail":"{read_only:false; response_revision:5124; number_of_response:1; }","duration":"239.644958ms","start":"2026-05-09T17:03:52.693418Z","end":"2026-05-09T17:03:52.933063Z","steps":["trace[417361448] 'process raft request' (duration: 227.213292ms)","trace[417361448] 'compare' (duration: 12.19051ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:03:52.933593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.720675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-09T17:03:52.933701Z","caller":"traceutil/trace.go:171","msg":"trace[301331490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5124; }","duration":"238.855418ms","start":"2026-05-09T17:03:52.69483Z","end":"2026-05-09T17:03:52.933686Z","steps":["trace[301331490] 'agreement among raft nodes before linearized reading' (duration: 238.666804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:03:52.933597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.60758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T17:03:52.933902Z","caller":"traceutil/trace.go:171","msg":"trace[2076739449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5124; }","duration":"114.928627ms","start":"2026-05-09T17:03:52.818939Z","end":"2026-05-09T17:03:52.933867Z","steps":["trace[2076739449] 'agreement among raft nodes before linearized reading' (duration: 114.571758ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:04:00.222836Z","caller":"traceutil/trace.go:171","msg":"trace[370860461] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"111.307201ms","start":"2026-05-09T17:04:00.1115Z","end":"2026-05-09T17:04:00.222808Z","steps":["trace[370860461] 'process raft request' (duration: 107.239285ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:05:46.250669Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3002} {"level":"info","ts":"2026-05-09T17:05:46.336627Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3002,"took":"84.286291ms","hash":2476008151,"current-db-size-bytes":59092992,"current-db-size":"59 MB","current-db-size-in-use-bytes":32272384,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T17:05:46.336694Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2476008151,"revision":3002,"compact-revision":-1} {"level":"info","ts":"2026-05-09T17:09:59.349785Z","caller":"traceutil/trace.go:171","msg":"trace[1317867618] linearizableReadLoop","detail":"{readStateIndex:8353; appliedIndex:8352; }","duration":"187.090964ms","start":"2026-05-09T17:09:59.162645Z","end":"2026-05-09T17:09:59.349736Z","steps":["trace[1317867618] 'read index received' (duration: 186.897949ms)","trace[1317867618] 'applied index is now lower than readState.Index' (duration: 192.135µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:09:59.349834Z","caller":"traceutil/trace.go:171","msg":"trace[1176719863] transaction","detail":"{read_only:false; response_revision:8072; number_of_response:1; }","duration":"262.751131ms","start":"2026-05-09T17:09:59.087051Z","end":"2026-05-09T17:09:59.349802Z","steps":["trace[1176719863] 'process raft request' (duration: 262.536296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:09:59.350003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.34472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-09T17:09:59.350063Z","caller":"traceutil/trace.go:171","msg":"trace[937016013] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:8072; }","duration":"187.450682ms","start":"2026-05-09T17:09:59.162599Z","end":"2026-05-09T17:09:59.35005Z","steps":["trace[937016013] 'agreement among raft nodes before linearized reading' (duration: 187.301139ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:09:59.532785Z","caller":"traceutil/trace.go:171","msg":"trace[1775526584] transaction","detail":"{read_only:false; response_revision:8073; number_of_response:1; }","duration":"165.417279ms","start":"2026-05-09T17:09:59.367341Z","end":"2026-05-09T17:09:59.532759Z","steps":["trace[1775526584] 'process raft request' (duration: 110.264039ms)","trace[1775526584] 'compare' (duration: 54.885793ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:10:11.001905Z","caller":"traceutil/trace.go:171","msg":"trace[1041303351] linearizableReadLoop","detail":"{readStateIndex:8417; appliedIndex:8416; }","duration":"121.22893ms","start":"2026-05-09T17:10:10.880645Z","end":"2026-05-09T17:10:11.001874Z","steps":["trace[1041303351] 'read index received' (duration: 100.662842ms)","trace[1041303351] 'applied index is now lower than readState.Index' (duration: 20.564688ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:10:11.001931Z","caller":"traceutil/trace.go:171","msg":"trace[349527869] transaction","detail":"{read_only:false; response_revision:8132; number_of_response:1; }","duration":"136.403299ms","start":"2026-05-09T17:10:10.865498Z","end":"2026-05-09T17:10:11.001901Z","steps":["trace[349527869] 'process raft request' (duration: 115.861332ms)","trace[349527869] 'compare' (duration: 20.259581ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:10:11.002134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.462835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-09T17:10:11.002191Z","caller":"traceutil/trace.go:171","msg":"trace[854641349] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8132; }","duration":"121.556247ms","start":"2026-05-09T17:10:10.880621Z","end":"2026-05-09T17:10:11.002177Z","steps":["trace[854641349] 'agreement among raft nodes before linearized reading' (duration: 121.365712ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:10:12.091806Z","caller":"traceutil/trace.go:171","msg":"trace[1859164875] transaction","detail":"{read_only:false; response_revision:8138; number_of_response:1; }","duration":"191.741884ms","start":"2026-05-09T17:10:11.90004Z","end":"2026-05-09T17:10:12.091782Z","steps":["trace[1859164875] 'process raft request' (duration: 148.17982ms)","trace[1859164875] 'compare' (duration: 42.898839ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:10:12.091815Z","caller":"traceutil/trace.go:171","msg":"trace[759345703] transaction","detail":"{read_only:false; response_revision:8139; number_of_response:1; }","duration":"149.793867ms","start":"2026-05-09T17:10:11.941992Z","end":"2026-05-09T17:10:12.091786Z","steps":["trace[759345703] 'process raft request' (duration: 149.702155ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:10:39.310311Z","caller":"traceutil/trace.go:171","msg":"trace[1236904054] linearizableReadLoop","detail":"{readStateIndex:8629; appliedIndex:8628; }","duration":"424.549074ms","start":"2026-05-09T17:10:38.885719Z","end":"2026-05-09T17:10:39.310268Z","steps":["trace[1236904054] 'read index received' (duration: 424.155455ms)","trace[1236904054] 'applied index is now lower than readState.Index' (duration: 391.879µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:10:39.310527Z","caller":"traceutil/trace.go:171","msg":"trace[894644434] transaction","detail":"{read_only:false; response_revision:8337; number_of_response:1; }","duration":"461.927243ms","start":"2026-05-09T17:10:38.848564Z","end":"2026-05-09T17:10:39.310491Z","steps":["trace[894644434] 'process raft request' (duration: 461.454272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:10:39.310703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:10:38.848551Z","time spent":"462.060567ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:10:39.310766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.997905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-f8qz6\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-05-09T17:10:39.310817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.107082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-09T17:10:39.310887Z","caller":"traceutil/trace.go:171","msg":"trace[1260988906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8337; }","duration":"312.210814ms","start":"2026-05-09T17:10:38.998659Z","end":"2026-05-09T17:10:39.31087Z","steps":["trace[1260988906] 'agreement among raft nodes before linearized reading' (duration: 311.973148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:10:39.310915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:10:38.99865Z","time spent":"312.260055ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-09T17:10:39.310832Z","caller":"traceutil/trace.go:171","msg":"trace[717549852] range","detail":"{range_begin:/registry/pods/openstack/keepalived-f8qz6; range_end:; response_count:1; response_revision:8337; }","duration":"425.114489ms","start":"2026-05-09T17:10:38.885696Z","end":"2026-05-09T17:10:39.31081Z","steps":["trace[717549852] 'agreement among raft nodes before linearized reading' (duration: 424.809671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:10:39.31108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:10:38.885686Z","time spent":"425.387265ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6544,"request content":"key:\"/registry/pods/openstack/keepalived-f8qz6\" "} {"level":"warn","ts":"2026-05-09T17:10:39.311094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.877287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-09T17:10:39.311131Z","caller":"traceutil/trace.go:171","msg":"trace[640314525] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8337; }","duration":"163.931159ms","start":"2026-05-09T17:10:39.147188Z","end":"2026-05-09T17:10:39.311119Z","steps":["trace[640314525] 'agreement among raft nodes before linearized reading' (duration: 163.802366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:10:39.311592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.627736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-09T17:10:39.311622Z","caller":"traceutil/trace.go:171","msg":"trace[1989121893] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:8337; }","duration":"146.693577ms","start":"2026-05-09T17:10:39.164921Z","end":"2026-05-09T17:10:39.311615Z","steps":["trace[1989121893] 'agreement among raft nodes before linearized reading' (duration: 146.270918ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:10:46.257398Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6201} {"level":"info","ts":"2026-05-09T17:10:46.351768Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6201,"took":"92.995121ms","hash":3415086513,"current-db-size-bytes":59092992,"current-db-size":"59 MB","current-db-size-in-use-bytes":23191552,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-09T17:10:46.351821Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3415086513,"revision":6201,"compact-revision":3002} {"level":"warn","ts":"2026-05-09T17:10:52.502007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.785868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-09T17:10:52.502117Z","caller":"traceutil/trace.go:171","msg":"trace[1288430328] transaction","detail":"{read_only:false; response_revision:8454; number_of_response:1; }","duration":"195.783344ms","start":"2026-05-09T17:10:52.306316Z","end":"2026-05-09T17:10:52.502099Z","steps":["trace[1288430328] 'process raft request' (duration: 83.815972ms)","trace[1288430328] 'compare' (duration: 111.600224ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:10:52.516127Z","caller":"traceutil/trace.go:171","msg":"trace[215135127] transaction","detail":"{read_only:false; response_revision:8455; number_of_response:1; }","duration":"204.591335ms","start":"2026-05-09T17:10:52.311518Z","end":"2026-05-09T17:10:52.516109Z","steps":["trace[215135127] 'process raft request' (duration: 204.503372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:10:53.766669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.520364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-09T17:10:53.76677Z","caller":"traceutil/trace.go:171","msg":"trace[2112672554] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8462; }","duration":"154.657498ms","start":"2026-05-09T17:10:53.61209Z","end":"2026-05-09T17:10:53.766748Z","steps":["trace[2112672554] 'range keys from in-memory index tree' (duration: 154.332039ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:10:53.963629Z","caller":"traceutil/trace.go:171","msg":"trace[1080914598] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"110.445216ms","start":"2026-05-09T17:10:53.853157Z","end":"2026-05-09T17:10:53.963602Z","steps":["trace[1080914598] 'process raft request' (duration: 110.388275ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:10:53.963791Z","caller":"traceutil/trace.go:171","msg":"trace[1910392124] transaction","detail":"{read_only:false; response_revision:8465; number_of_response:1; }","duration":"122.187805ms","start":"2026-05-09T17:10:53.841575Z","end":"2026-05-09T17:10:53.963763Z","steps":["trace[1910392124] 'process raft request' (duration: 53.682866ms)","trace[1910392124] 'compare' (duration: 68.1431ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:10:54.165739Z","caller":"traceutil/trace.go:171","msg":"trace[866936827] transaction","detail":"{read_only:false; response_revision:8468; number_of_response:1; }","duration":"126.233421ms","start":"2026-05-09T17:10:54.039475Z","end":"2026-05-09T17:10:54.165708Z","steps":["trace[866936827] 'process raft request' (duration: 86.872726ms)","trace[866936827] 'compare' (duration: 39.222902ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:11:42.603552Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002426.wal"} {"level":"info","ts":"2026-05-09T17:13:14.626125Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d0ba3c4de8c6ef7a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T17:13:14.631271Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-09T17:13:14.631369Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-09T17:15:46.266694Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8381} {"level":"info","ts":"2026-05-09T17:15:46.34902Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8381,"took":"79.8653ms","hash":30864282,"current-db-size-bytes":59092992,"current-db-size":"59 MB","current-db-size-in-use-bytes":26644480,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-09T17:15:46.349105Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":30864282,"revision":8381,"compact-revision":6201} {"level":"info","ts":"2026-05-09T17:15:49.02119Z","caller":"traceutil/trace.go:171","msg":"trace[423679480] linearizableReadLoop","detail":"{readStateIndex:11408; appliedIndex:11407; }","duration":"242.845729ms","start":"2026-05-09T17:15:48.778313Z","end":"2026-05-09T17:15:49.021159Z","steps":["trace[423679480] 'read index received' (duration: 241.826415ms)","trace[423679480] 'applied index is now lower than readState.Index' (duration: 1.017804ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:15:49.021263Z","caller":"traceutil/trace.go:171","msg":"trace[1625354002] transaction","detail":"{read_only:false; response_revision:11034; number_of_response:1; }","duration":"245.577654ms","start":"2026-05-09T17:15:48.775637Z","end":"2026-05-09T17:15:49.021214Z","steps":["trace[1625354002] 'process raft request' (duration: 244.631702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:15:49.021391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.076815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T17:15:49.021438Z","caller":"traceutil/trace.go:171","msg":"trace[614062125] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11034; }","duration":"243.166576ms","start":"2026-05-09T17:15:48.778258Z","end":"2026-05-09T17:15:49.021425Z","steps":["trace[614062125] 'agreement among raft nodes before linearized reading' (duration: 243.072274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:15:49.021612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.012928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T17:15:49.021679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.913837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-09T17:15:49.021684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.941082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T17:15:49.021711Z","caller":"traceutil/trace.go:171","msg":"trace[354308956] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11034; }","duration":"239.174021ms","start":"2026-05-09T17:15:48.782516Z","end":"2026-05-09T17:15:49.02169Z","steps":["trace[354308956] 'agreement among raft nodes before linearized reading' (duration: 239.000527ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:15:49.021741Z","caller":"traceutil/trace.go:171","msg":"trace[1139415769] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11034; }","duration":"149.979738ms","start":"2026-05-09T17:15:48.871746Z","end":"2026-05-09T17:15:49.021725Z","steps":["trace[1139415769] 'agreement among raft nodes before linearized reading' (duration: 149.876706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:15:49.021722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.569033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T17:15:49.021783Z","caller":"traceutil/trace.go:171","msg":"trace[1803580045] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11034; }","duration":"238.041745ms","start":"2026-05-09T17:15:48.783695Z","end":"2026-05-09T17:15:49.021737Z","steps":["trace[1803580045] 'agreement among raft nodes before linearized reading' (duration: 237.876651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:15:49.021844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.219752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-09T17:15:49.021842Z","caller":"traceutil/trace.go:171","msg":"trace[387298030] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11034; }","duration":"237.721307ms","start":"2026-05-09T17:15:48.784097Z","end":"2026-05-09T17:15:49.021818Z","steps":["trace[387298030] 'agreement among raft nodes before linearized reading' (duration: 237.507662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:15:49.021744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.547545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-05-09T17:15:49.022341Z","caller":"traceutil/trace.go:171","msg":"trace[742205453] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11034; }","duration":"236.184701ms","start":"2026-05-09T17:15:48.786141Z","end":"2026-05-09T17:15:49.022325Z","steps":["trace[742205453] 'agreement among raft nodes before linearized reading' (duration: 235.411342ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:15:49.021877Z","caller":"traceutil/trace.go:171","msg":"trace[1032471332] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11034; }","duration":"181.265963ms","start":"2026-05-09T17:15:48.840599Z","end":"2026-05-09T17:15:49.021865Z","steps":["trace[1032471332] 'agreement among raft nodes before linearized reading' (duration: 181.193081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:15:49.021931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.279446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T17:15:49.022645Z","caller":"traceutil/trace.go:171","msg":"trace[1649766376] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11034; }","duration":"151.010324ms","start":"2026-05-09T17:15:48.871622Z","end":"2026-05-09T17:15:49.022632Z","steps":["trace[1649766376] 'agreement among raft nodes before linearized reading' (duration: 150.279615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:15:49.021929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.47476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T17:15:49.022786Z","caller":"traceutil/trace.go:171","msg":"trace[1957146846] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11034; }","duration":"151.358492ms","start":"2026-05-09T17:15:48.871419Z","end":"2026-05-09T17:15:49.022778Z","steps":["trace[1957146846] 'agreement among raft nodes before linearized reading' (duration: 150.48422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:15:49.022039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.530018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-05-09T17:15:49.022915Z","caller":"traceutil/trace.go:171","msg":"trace[998356836] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11034; }","duration":"137.44316ms","start":"2026-05-09T17:15:48.885463Z","end":"2026-05-09T17:15:49.022906Z","steps":["trace[998356836] 'agreement among raft nodes before linearized reading' (duration: 136.414595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:15:49.022057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.003947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-05-09T17:15:49.023011Z","caller":"traceutil/trace.go:171","msg":"trace[1454159578] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11034; }","duration":"240.008311ms","start":"2026-05-09T17:15:48.782996Z","end":"2026-05-09T17:15:49.023005Z","steps":["trace[1454159578] 'agreement among raft nodes before linearized reading' (duration: 238.906384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:15:49.022046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.120066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-09T17:15:49.023188Z","caller":"traceutil/trace.go:171","msg":"trace[1774763339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11034; }","duration":"231.282004ms","start":"2026-05-09T17:15:48.791865Z","end":"2026-05-09T17:15:49.023147Z","steps":["trace[1774763339] 'agreement among raft nodes before linearized reading' (duration: 229.989612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:15:49.022253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.180251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T17:15:49.023298Z","caller":"traceutil/trace.go:171","msg":"trace[1485769642] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11034; }","duration":"158.905302ms","start":"2026-05-09T17:15:48.864383Z","end":"2026-05-09T17:15:49.023288Z","steps":["trace[1485769642] 'agreement among raft nodes before linearized reading' (duration: 157.136939ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:20:46.273554Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11019} {"level":"info","ts":"2026-05-09T17:20:46.356929Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11019,"took":"80.642022ms","hash":830626578,"current-db-size-bytes":59092992,"current-db-size":"59 MB","current-db-size-in-use-bytes":28073984,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-09T17:20:46.356989Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":830626578,"revision":11019,"compact-revision":8381} {"level":"info","ts":"2026-05-09T17:21:28.256005Z","caller":"traceutil/trace.go:171","msg":"trace[1302047361] transaction","detail":"{read_only:false; response_revision:13368; number_of_response:1; }","duration":"103.907987ms","start":"2026-05-09T17:21:28.152079Z","end":"2026-05-09T17:21:28.255986Z","steps":["trace[1302047361] 'process raft request' (duration: 103.788194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:21:30.935966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.422698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T17:21:30.936055Z","caller":"traceutil/trace.go:171","msg":"trace[78769327] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13387; }","duration":"114.549021ms","start":"2026-05-09T17:21:30.821487Z","end":"2026-05-09T17:21:30.936036Z","steps":["trace[78769327] 'range keys from in-memory index tree' (duration: 114.356607ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:22:05.268746Z","caller":"traceutil/trace.go:171","msg":"trace[894117652] transaction","detail":"{read_only:false; response_revision:13584; number_of_response:1; }","duration":"165.323751ms","start":"2026-05-09T17:22:05.103382Z","end":"2026-05-09T17:22:05.268705Z","steps":["trace[894117652] 'process raft request' (duration: 165.049175ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:22:05.42591Z","caller":"traceutil/trace.go:171","msg":"trace[1329411890] transaction","detail":"{read_only:false; response_revision:13586; number_of_response:1; }","duration":"152.337682ms","start":"2026-05-09T17:22:05.273543Z","end":"2026-05-09T17:22:05.425881Z","steps":["trace[1329411890] 'process raft request' (duration: 152.231479ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:22:05.425917Z","caller":"traceutil/trace.go:171","msg":"trace[273876088] transaction","detail":"{read_only:false; response_revision:13585; number_of_response:1; }","duration":"152.70879ms","start":"2026-05-09T17:22:05.273145Z","end":"2026-05-09T17:22:05.425854Z","steps":["trace[273876088] 'process raft request' (duration: 143.201434ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:22:14.500221Z","caller":"traceutil/trace.go:171","msg":"trace[1406778872] linearizableReadLoop","detail":"{readStateIndex:14212; appliedIndex:14210; }","duration":"147.347953ms","start":"2026-05-09T17:22:14.352781Z","end":"2026-05-09T17:22:14.500129Z","steps":["trace[1406778872] 'read index received' (duration: 86.681087ms)","trace[1406778872] 'applied index is now lower than readState.Index' (duration: 60.664246ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:22:14.500373Z","caller":"traceutil/trace.go:171","msg":"trace[1287939995] transaction","detail":"{read_only:false; response_revision:13745; number_of_response:1; }","duration":"225.355413ms","start":"2026-05-09T17:22:14.274974Z","end":"2026-05-09T17:22:14.50033Z","steps":["trace[1287939995] 'process raft request' (duration: 224.768978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:22:14.500755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.919307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-f8qz6\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-05-09T17:22:14.500841Z","caller":"traceutil/trace.go:171","msg":"trace[591028721] range","detail":"{range_begin:/registry/pods/openstack/keepalived-f8qz6; range_end:; response_count:1; response_revision:13745; }","duration":"148.094671ms","start":"2026-05-09T17:22:14.352726Z","end":"2026-05-09T17:22:14.500821Z","steps":["trace[591028721] 'agreement among raft nodes before linearized reading' (duration: 147.728402ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:23:53.151667Z","caller":"traceutil/trace.go:171","msg":"trace[1543782697] transaction","detail":"{read_only:false; response_revision:14828; number_of_response:1; }","duration":"123.559119ms","start":"2026-05-09T17:23:53.028075Z","end":"2026-05-09T17:23:53.151635Z","steps":["trace[1543782697] 'process raft request' (duration: 123.290623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:23:53.330323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.043556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-09T17:23:53.330419Z","caller":"traceutil/trace.go:171","msg":"trace[802998483] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14828; }","duration":"102.147418ms","start":"2026-05-09T17:23:53.228254Z","end":"2026-05-09T17:23:53.330401Z","steps":["trace[802998483] 'range keys from in-memory index tree' (duration: 101.989645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:23:53.330737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.81397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-05-09T17:23:53.330776Z","caller":"traceutil/trace.go:171","msg":"trace[1267290635] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14828; }","duration":"101.870932ms","start":"2026-05-09T17:23:53.228896Z","end":"2026-05-09T17:23:53.330767Z","steps":["trace[1267290635] 'range keys from in-memory index tree' (duration: 101.694847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:23:53.331239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.02136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-05-09T17:23:53.331298Z","caller":"traceutil/trace.go:171","msg":"trace[89388567] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14828; }","duration":"103.121261ms","start":"2026-05-09T17:23:53.228164Z","end":"2026-05-09T17:23:53.331285Z","steps":["trace[89388567] 'range keys from in-memory index tree' (duration: 102.816644ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:23:53.603836Z","caller":"traceutil/trace.go:171","msg":"trace[1202452780] linearizableReadLoop","detail":"{readStateIndex:15329; appliedIndex:15328; }","duration":"113.879307ms","start":"2026-05-09T17:23:53.489935Z","end":"2026-05-09T17:23:53.603815Z","steps":["trace[1202452780] 'read index received' (duration: 113.811606ms)","trace[1202452780] 'applied index is now lower than readState.Index' (duration: 66.971µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:23:53.604031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.088592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-09T17:23:53.604101Z","caller":"traceutil/trace.go:171","msg":"trace[1182470308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14829; }","duration":"114.181105ms","start":"2026-05-09T17:23:53.489905Z","end":"2026-05-09T17:23:53.604086Z","steps":["trace[1182470308] 'agreement among raft nodes before linearized reading' (duration: 114.00151ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:23:53.603922Z","caller":"traceutil/trace.go:171","msg":"trace[459346517] transaction","detail":"{read_only:false; response_revision:14829; number_of_response:1; }","duration":"123.662622ms","start":"2026-05-09T17:23:53.480221Z","end":"2026-05-09T17:23:53.603884Z","steps":["trace[459346517] 'process raft request' (duration: 123.486428ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:25:46.279016Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13072} {"level":"info","ts":"2026-05-09T17:25:46.35069Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13072,"took":"68.800841ms","hash":1559601884,"current-db-size-bytes":59092992,"current-db-size":"59 MB","current-db-size-in-use-bytes":31825920,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T17:25:46.350767Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1559601884,"revision":13072,"compact-revision":11019} {"level":"warn","ts":"2026-05-09T17:29:37.421422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.184159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-4p9lk\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-05-09T17:29:37.421504Z","caller":"traceutil/trace.go:171","msg":"trace[248154910] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-4p9lk; range_end:; response_count:1; response_revision:17596; }","duration":"128.297601ms","start":"2026-05-09T17:29:37.29319Z","end":"2026-05-09T17:29:37.421487Z","steps":["trace[248154910] 'range keys from in-memory index tree' (duration: 128.015745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:29:37.744575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.198483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T17:29:37.744655Z","caller":"traceutil/trace.go:171","msg":"trace[871045851] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17597; }","duration":"168.342916ms","start":"2026-05-09T17:29:37.576292Z","end":"2026-05-09T17:29:37.744635Z","steps":["trace[871045851] 'range keys from in-memory index tree' (duration: 168.024769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:29:37.744718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.758295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-09T17:29:37.744895Z","caller":"traceutil/trace.go:171","msg":"trace[508113936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17597; }","duration":"165.96194ms","start":"2026-05-09T17:29:37.578913Z","end":"2026-05-09T17:29:37.744875Z","steps":["trace[508113936] 'range keys from in-memory index tree' (duration: 165.617521ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:30:46.287272Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15634} {"level":"info","ts":"2026-05-09T17:30:46.379847Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15634,"took":"89.157487ms","hash":45500865,"current-db-size-bytes":59092992,"current-db-size":"59 MB","current-db-size-in-use-bytes":34652160,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-09T17:30:46.379902Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":45500865,"revision":15634,"compact-revision":13072} {"level":"info","ts":"2026-05-09T17:33:18.82475Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d0ba3c4de8c6ef7a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T17:33:18.829417Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-09T17:33:18.829488Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-09T17:34:08.678532Z","caller":"traceutil/trace.go:171","msg":"trace[619179052] transaction","detail":"{read_only:false; response_revision:19633; number_of_response:1; }","duration":"206.312912ms","start":"2026-05-09T17:34:08.472189Z","end":"2026-05-09T17:34:08.678502Z","steps":["trace[619179052] 'process raft request' (duration: 206.053246ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:34:08.711374Z","caller":"traceutil/trace.go:171","msg":"trace[574611549] linearizableReadLoop","detail":"{readStateIndex:20293; appliedIndex:20290; }","duration":"168.306665ms","start":"2026-05-09T17:34:08.543049Z","end":"2026-05-09T17:34:08.711356Z","steps":["trace[574611549] 'read index received' (duration: 135.299127ms)","trace[574611549] 'applied index is now lower than readState.Index' (duration: 33.003548ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:34:08.711415Z","caller":"traceutil/trace.go:171","msg":"trace[1118412753] transaction","detail":"{read_only:false; response_revision:19634; number_of_response:1; }","duration":"237.102986ms","start":"2026-05-09T17:34:08.47428Z","end":"2026-05-09T17:34:08.711383Z","steps":["trace[1118412753] 'process raft request' (duration: 236.954693ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:34:08.711443Z","caller":"traceutil/trace.go:171","msg":"trace[1048916808] transaction","detail":"{read_only:false; response_revision:19635; number_of_response:1; }","duration":"236.949023ms","start":"2026-05-09T17:34:08.474457Z","end":"2026-05-09T17:34:08.711406Z","steps":["trace[1048916808] 'process raft request' (duration: 236.852161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:34:08.711549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.476718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T17:34:08.711585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.484575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-09T17:34:08.711628Z","caller":"traceutil/trace.go:171","msg":"trace[592834779] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19635; }","duration":"102.567857ms","start":"2026-05-09T17:34:08.609048Z","end":"2026-05-09T17:34:08.711616Z","steps":["trace[592834779] 'agreement among raft nodes before linearized reading' (duration: 102.421803ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:34:08.711625Z","caller":"traceutil/trace.go:171","msg":"trace[730280408] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:19635; }","duration":"168.600511ms","start":"2026-05-09T17:34:08.543004Z","end":"2026-05-09T17:34:08.711604Z","steps":["trace[730280408] 'agreement among raft nodes before linearized reading' (duration: 168.472558ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:34:19.149311Z","caller":"traceutil/trace.go:171","msg":"trace[1638617730] transaction","detail":"{read_only:false; response_revision:19689; number_of_response:1; }","duration":"123.023615ms","start":"2026-05-09T17:34:19.026262Z","end":"2026-05-09T17:34:19.149286Z","steps":["trace[1638617730] 'process raft request' (duration: 122.860211ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:34:19.162955Z","caller":"traceutil/trace.go:171","msg":"trace[1280172844] transaction","detail":"{read_only:false; response_revision:19690; number_of_response:1; }","duration":"134.898549ms","start":"2026-05-09T17:34:19.02803Z","end":"2026-05-09T17:34:19.162928Z","steps":["trace[1280172844] 'process raft request' (duration: 134.059138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:34:24.830225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.906898ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-05-09T17:34:25.094015Z","caller":"traceutil/trace.go:171","msg":"trace[97663498] transaction","detail":"{read_only:false; response_revision:19715; number_of_response:1; }","duration":"259.584863ms","start":"2026-05-09T17:34:24.834388Z","end":"2026-05-09T17:34:25.093973Z","steps":["trace[97663498] 'process raft request' (duration: 259.348477ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:34:25.094435Z","caller":"traceutil/trace.go:171","msg":"trace[1963096254] transaction","detail":"{read_only:false; response_revision:19717; number_of_response:1; }","duration":"259.414569ms","start":"2026-05-09T17:34:24.834994Z","end":"2026-05-09T17:34:25.094409Z","steps":["trace[1963096254] 'process raft request' (duration: 259.319927ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:34:25.09461Z","caller":"traceutil/trace.go:171","msg":"trace[397587840] transaction","detail":"{read_only:false; response_revision:19716; number_of_response:1; }","duration":"259.630354ms","start":"2026-05-09T17:34:24.834907Z","end":"2026-05-09T17:34:25.094538Z","steps":["trace[397587840] 'process raft request' (duration: 259.006429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:34:25.56264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.270127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:34:25.562745Z","caller":"traceutil/trace.go:171","msg":"trace[699520295] transaction","detail":"{read_only:false; response_revision:19721; number_of_response:1; }","duration":"276.64941ms","start":"2026-05-09T17:34:25.286077Z","end":"2026-05-09T17:34:25.562727Z","steps":["trace[699520295] 'process raft request' (duration: 133.201398ms)","trace[699520295] 'compare' (duration: 143.154805ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:34:25.619591Z","caller":"traceutil/trace.go:171","msg":"trace[523373700] linearizableReadLoop","detail":"{readStateIndex:20385; appliedIndex:20383; }","duration":"173.726334ms","start":"2026-05-09T17:34:25.445831Z","end":"2026-05-09T17:34:25.619557Z","steps":["trace[523373700] 'read index received' (duration: 173.517679ms)","trace[523373700] 'applied index is now lower than readState.Index' (duration: 207.545µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:34:25.619676Z","caller":"traceutil/trace.go:171","msg":"trace[1250856294] transaction","detail":"{read_only:false; response_revision:19723; number_of_response:1; }","duration":"260.924514ms","start":"2026-05-09T17:34:25.358715Z","end":"2026-05-09T17:34:25.61964Z","steps":["trace[1250856294] 'process raft request' (duration: 260.795051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:34:25.619802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.942179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:34:25.619917Z","caller":"traceutil/trace.go:171","msg":"trace[709069366] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:19723; }","duration":"174.153864ms","start":"2026-05-09T17:34:25.445738Z","end":"2026-05-09T17:34:25.619892Z","steps":["trace[709069366] 'agreement among raft nodes before linearized reading' (duration: 173.95551ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:34:25.619923Z","caller":"traceutil/trace.go:171","msg":"trace[1291513038] transaction","detail":"{read_only:false; response_revision:19722; number_of_response:1; }","duration":"332.432289ms","start":"2026-05-09T17:34:25.287167Z","end":"2026-05-09T17:34:25.6196Z","steps":["trace[1291513038] 'process raft request' (duration: 332.206594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:34:25.620073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:34:25.287151Z","time spent":"332.81628ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:35:46.292543Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18229} {"level":"info","ts":"2026-05-09T17:35:46.40809Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18229,"took":"111.989282ms","hash":4161421577,"current-db-size-bytes":59092992,"current-db-size":"59 MB","current-db-size-in-use-bytes":30486528,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-09T17:35:46.408228Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4161421577,"revision":18229,"compact-revision":15634} {"level":"info","ts":"2026-05-09T17:38:02.18305Z","caller":"traceutil/trace.go:171","msg":"trace[310672734] transaction","detail":"{read_only:false; response_revision:22064; number_of_response:1; }","duration":"305.071487ms","start":"2026-05-09T17:38:01.877938Z","end":"2026-05-09T17:38:02.18301Z","steps":["trace[310672734] 'process raft request' (duration: 261.896067ms)","trace[310672734] 'compare' (duration: 42.936764ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:38:02.183207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:38:01.877915Z","time spent":"305.21573ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:38:02.23471Z","caller":"traceutil/trace.go:171","msg":"trace[2123709628] transaction","detail":"{read_only:false; response_revision:22065; number_of_response:1; }","duration":"354.864746ms","start":"2026-05-09T17:38:01.879818Z","end":"2026-05-09T17:38:02.234683Z","steps":["trace[2123709628] 'process raft request' (duration: 354.669061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:38:02.23488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:38:01.879795Z","time spent":"354.997578ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:38:02.235031Z","caller":"traceutil/trace.go:171","msg":"trace[1770433263] transaction","detail":"{read_only:false; response_revision:22066; number_of_response:1; }","duration":"236.298226ms","start":"2026-05-09T17:38:01.998708Z","end":"2026-05-09T17:38:02.235006Z","steps":["trace[1770433263] 'process raft request' (duration: 235.930337ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:38:02.235047Z","caller":"traceutil/trace.go:171","msg":"trace[1323284129] linearizableReadLoop","detail":"{readStateIndex:22798; appliedIndex:22793; }","duration":"170.135679ms","start":"2026-05-09T17:38:02.064887Z","end":"2026-05-09T17:38:02.235023Z","steps":["trace[1323284129] 'read index received' (duration: 74.948298ms)","trace[1323284129] 'applied index is now lower than readState.Index' (duration: 95.186161ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:38:02.235177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.272863ms","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-05-09T17:38:02.23523Z","caller":"traceutil/trace.go:171","msg":"trace[1292488455] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:22068; }","duration":"170.333794ms","start":"2026-05-09T17:38:02.064875Z","end":"2026-05-09T17:38:02.235209Z","steps":["trace[1292488455] 'agreement among raft nodes before linearized reading' (duration: 170.209391ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:38:02.23521Z","caller":"traceutil/trace.go:171","msg":"trace[1972148357] transaction","detail":"{read_only:false; response_revision:22068; number_of_response:1; }","duration":"212.640493ms","start":"2026-05-09T17:38:02.022515Z","end":"2026-05-09T17:38:02.235155Z","steps":["trace[1972148357] 'process raft request' (duration: 212.464679ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:38:02.235484Z","caller":"traceutil/trace.go:171","msg":"trace[93089077] transaction","detail":"{read_only:false; response_revision:22067; number_of_response:1; }","duration":"230.6182ms","start":"2026-05-09T17:38:02.004438Z","end":"2026-05-09T17:38:02.235056Z","steps":["trace[93089077] 'process raft request' (duration: 230.468637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:38:02.236347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.605647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-09T17:38:02.236418Z","caller":"traceutil/trace.go:171","msg":"trace[361240873] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22069; }","duration":"102.735411ms","start":"2026-05-09T17:38:02.133663Z","end":"2026-05-09T17:38:02.236399Z","steps":["trace[361240873] 'agreement among raft nodes before linearized reading' (duration: 102.428123ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:40:46.298303Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20493} {"level":"info","ts":"2026-05-09T17:40:46.400438Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20493,"took":"99.155776ms","hash":3302475994,"current-db-size-bytes":59092992,"current-db-size":"59 MB","current-db-size-in-use-bytes":42967040,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-05-09T17:40:46.400495Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3302475994,"revision":20493,"compact-revision":18229} {"level":"info","ts":"2026-05-09T17:41:07.18447Z","caller":"traceutil/trace.go:171","msg":"trace[1837647372] transaction","detail":"{read_only:false; response_revision:23978; number_of_response:1; }","duration":"323.774297ms","start":"2026-05-09T17:41:06.86067Z","end":"2026-05-09T17:41:07.184445Z","steps":["trace[1837647372] 'process raft request' (duration: 323.611773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:07.184633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:06.860651Z","time spent":"323.90017ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:41:07.190638Z","caller":"traceutil/trace.go:171","msg":"trace[2124331220] transaction","detail":"{read_only:false; response_revision:23980; number_of_response:1; }","duration":"327.985647ms","start":"2026-05-09T17:41:06.862632Z","end":"2026-05-09T17:41:07.190618Z","steps":["trace[2124331220] 'process raft request' (duration: 327.889475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:07.190745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:06.86262Z","time spent":"328.071159ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:41:07.190804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.216487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-09T17:41:07.190866Z","caller":"traceutil/trace.go:171","msg":"trace[1218782065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23981; }","duration":"123.317699ms","start":"2026-05-09T17:41:07.067531Z","end":"2026-05-09T17:41:07.190848Z","steps":["trace[1218782065] 'agreement among raft nodes before linearized reading' (duration: 123.150265ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:07.1909Z","caller":"traceutil/trace.go:171","msg":"trace[577812205] transaction","detail":"{read_only:false; response_revision:23981; number_of_response:1; }","duration":"174.101189ms","start":"2026-05-09T17:41:07.016793Z","end":"2026-05-09T17:41:07.190895Z","steps":["trace[577812205] 'process raft request' (duration: 173.772792ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:07.190828Z","caller":"traceutil/trace.go:171","msg":"trace[2062411729] transaction","detail":"{read_only:false; response_revision:23979; number_of_response:1; }","duration":"328.473449ms","start":"2026-05-09T17:41:06.862348Z","end":"2026-05-09T17:41:07.190822Z","steps":["trace[2062411729] 'process raft request' (duration: 328.041739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:07.191065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:06.862297Z","time spent":"328.686123ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:41:07.190628Z","caller":"traceutil/trace.go:171","msg":"trace[1116951311] linearizableReadLoop","detail":"{readStateIndex:24761; appliedIndex:24757; }","duration":"123.024833ms","start":"2026-05-09T17:41:07.067577Z","end":"2026-05-09T17:41:07.190602Z","steps":["trace[1116951311] 'read index received' (duration: 116.804894ms)","trace[1116951311] 'applied index is now lower than readState.Index' (duration: 6.218649ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:41:07.191353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.083879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-09T17:41:07.191402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.796649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-09T17:41:07.191465Z","caller":"traceutil/trace.go:171","msg":"trace[904066185] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23981; }","duration":"120.87217ms","start":"2026-05-09T17:41:07.070578Z","end":"2026-05-09T17:41:07.191451Z","steps":["trace[904066185] 'agreement among raft nodes before linearized reading' (duration: 120.720357ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:07.191411Z","caller":"traceutil/trace.go:171","msg":"trace[1654579759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23981; }","duration":"122.172441ms","start":"2026-05-09T17:41:07.069223Z","end":"2026-05-09T17:41:07.191396Z","steps":["trace[1654579759] 'agreement among raft nodes before linearized reading' (duration: 122.007967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:07.349639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.666501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:41:07.349731Z","caller":"traceutil/trace.go:171","msg":"trace[1712606237] transaction","detail":"{read_only:false; response_revision:23982; number_of_response:1; }","duration":"154.056072ms","start":"2026-05-09T17:41:07.195658Z","end":"2026-05-09T17:41:07.349714Z","steps":["trace[1712606237] 'process raft request' (duration: 21.245027ms)","trace[1712606237] 'compare' (duration: 132.576349ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:41:07.446407Z","caller":"traceutil/trace.go:171","msg":"trace[466314786] transaction","detail":"{read_only:false; response_revision:23983; number_of_response:1; }","duration":"250.339336ms","start":"2026-05-09T17:41:07.196028Z","end":"2026-05-09T17:41:07.446367Z","steps":["trace[466314786] 'process raft request' (duration: 249.65403ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:07.447739Z","caller":"traceutil/trace.go:171","msg":"trace[892384482] transaction","detail":"{read_only:false; response_revision:23984; number_of_response:1; }","duration":"251.125506ms","start":"2026-05-09T17:41:07.196589Z","end":"2026-05-09T17:41:07.447714Z","steps":["trace[892384482] 'process raft request' (duration: 249.326972ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:07.448137Z","caller":"traceutil/trace.go:171","msg":"trace[719162507] linearizableReadLoop","detail":"{readStateIndex:24764; appliedIndex:24761; }","duration":"234.993421ms","start":"2026-05-09T17:41:07.212643Z","end":"2026-05-09T17:41:07.447637Z","steps":["trace[719162507] 'read index received' (duration: 4.450396ms)","trace[719162507] 'applied index is now lower than readState.Index' (duration: 230.541405ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:41:07.448234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.531373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-09T17:41:07.448358Z","caller":"traceutil/trace.go:171","msg":"trace[289615449] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23984; }","duration":"235.737438ms","start":"2026-05-09T17:41:07.212594Z","end":"2026-05-09T17:41:07.448331Z","steps":["trace[289615449] 'agreement among raft nodes before linearized reading' (duration: 235.38686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:07.606514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.778167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:41:07.607011Z","caller":"traceutil/trace.go:171","msg":"trace[1423650712] transaction","detail":"{read_only:false; response_revision:23987; number_of_response:1; }","duration":"146.129123ms","start":"2026-05-09T17:41:07.460862Z","end":"2026-05-09T17:41:07.606991Z","steps":["trace[1423650712] 'process raft request' (duration: 146.056851ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:07.607135Z","caller":"traceutil/trace.go:171","msg":"trace[132712431] transaction","detail":"{read_only:false; response_revision:23986; number_of_response:1; }","duration":"243.36167ms","start":"2026-05-09T17:41:07.363764Z","end":"2026-05-09T17:41:07.607125Z","steps":["trace[132712431] 'process raft request' (duration: 243.062723ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:07.607287Z","caller":"traceutil/trace.go:171","msg":"trace[583247641] linearizableReadLoop","detail":"{readStateIndex:24766; appliedIndex:24764; }","duration":"159.104392ms","start":"2026-05-09T17:41:07.448161Z","end":"2026-05-09T17:41:07.607266Z","steps":["trace[583247641] 'read index received' (duration: 14.522336ms)","trace[583247641] 'applied index is now lower than readState.Index' (duration: 144.580286ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:41:07.606929Z","caller":"traceutil/trace.go:171","msg":"trace[396605618] transaction","detail":"{read_only:false; response_revision:23985; number_of_response:1; }","duration":"244.103137ms","start":"2026-05-09T17:41:07.362801Z","end":"2026-05-09T17:41:07.606904Z","steps":["trace[396605618] 'process raft request' (duration: 99.850109ms)","trace[396605618] 'compare' (duration: 143.50253ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:41:07.607463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.75257ms","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":"info","ts":"2026-05-09T17:41:07.607526Z","caller":"traceutil/trace.go:171","msg":"trace[100755254] 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:23987; }","duration":"165.887444ms","start":"2026-05-09T17:41:07.441617Z","end":"2026-05-09T17:41:07.607505Z","steps":["trace[100755254] 'agreement among raft nodes before linearized reading' (duration: 165.782731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:07.607518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.276489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:41:07.60758Z","caller":"traceutil/trace.go:171","msg":"trace[1607060213] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:23987; }","duration":"233.382582ms","start":"2026-05-09T17:41:07.374182Z","end":"2026-05-09T17:41:07.607565Z","steps":["trace[1607060213] 'agreement among raft nodes before linearized reading' (duration: 233.250869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:07.965458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.712985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:41:07.965597Z","caller":"traceutil/trace.go:171","msg":"trace[658960510] linearizableReadLoop","detail":"{readStateIndex:24768; appliedIndex:24767; }","duration":"358.234278ms","start":"2026-05-09T17:41:07.607353Z","end":"2026-05-09T17:41:07.965587Z","steps":["trace[658960510] 'read index received' (duration: 206.462921ms)","trace[658960510] 'applied index is now lower than readState.Index' (duration: 151.770327ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:41:07.965976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.698126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-09T17:41:07.965673Z","caller":"traceutil/trace.go:171","msg":"trace[114001620] transaction","detail":"{read_only:false; response_revision:23988; number_of_response:1; }","duration":"502.843295ms","start":"2026-05-09T17:41:07.462698Z","end":"2026-05-09T17:41:07.965542Z","steps":["trace[114001620] 'process raft request' (duration: 350.954125ms)","trace[114001620] 'compare' (duration: 151.337537ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:41:07.96594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.989227ms","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":"warn","ts":"2026-05-09T17:41:07.966009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.176164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:41:07.966058Z","caller":"traceutil/trace.go:171","msg":"trace[1954501630] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:23989; }","duration":"255.163651ms","start":"2026-05-09T17:41:07.710866Z","end":"2026-05-09T17:41:07.96603Z","steps":["trace[1954501630] 'agreement among raft nodes before linearized reading' (duration: 254.948766ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:07.966083Z","caller":"traceutil/trace.go:171","msg":"trace[1392956336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23989; }","duration":"146.315817ms","start":"2026-05-09T17:41:07.819749Z","end":"2026-05-09T17:41:07.966065Z","steps":["trace[1392956336] 'agreement among raft nodes before linearized reading' (duration: 146.152273ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:07.966005Z","caller":"traceutil/trace.go:171","msg":"trace[1116925762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23989; }","duration":"511.746206ms","start":"2026-05-09T17:41:07.454252Z","end":"2026-05-09T17:41:07.965998Z","steps":["trace[1116925762] 'agreement among raft nodes before linearized reading' (duration: 511.403428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:07.966149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.790923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-05-09T17:41:07.966167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:07.454245Z","time spent":"511.90103ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T17:41:07.966102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:07.46269Z","time spent":"503.332176ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:41:07.966215Z","caller":"traceutil/trace.go:171","msg":"trace[291891329] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:23989; }","duration":"244.931457ms","start":"2026-05-09T17:41:07.721264Z","end":"2026-05-09T17:41:07.966196Z","steps":["trace[291891329] 'agreement among raft nodes before linearized reading' (duration: 244.679101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:07.966095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.844759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T17:41:07.966238Z","caller":"traceutil/trace.go:171","msg":"trace[1992028774] transaction","detail":"{read_only:false; response_revision:23989; number_of_response:1; }","duration":"120.328088ms","start":"2026-05-09T17:41:07.845801Z","end":"2026-05-09T17:41:07.966129Z","steps":["trace[1992028774] 'process raft request' (duration: 119.741554ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:07.96667Z","caller":"traceutil/trace.go:171","msg":"trace[1052379532] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23989; }","duration":"512.398701ms","start":"2026-05-09T17:41:07.454193Z","end":"2026-05-09T17:41:07.966592Z","steps":["trace[1052379532] 'agreement among raft nodes before linearized reading' (duration: 511.47355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:07.966807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:07.454175Z","time spent":"512.621897ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T17:41:07.966974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.094906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-05-09T17:41:07.967059Z","caller":"traceutil/trace.go:171","msg":"trace[2125228788] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:23989; }","duration":"249.305162ms","start":"2026-05-09T17:41:07.717731Z","end":"2026-05-09T17:41:07.967036Z","steps":["trace[2125228788] 'agreement among raft nodes before linearized reading' (duration: 248.236947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:08.334106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.098798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:41:08.334204Z","caller":"traceutil/trace.go:171","msg":"trace[1838549821] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:23991; }","duration":"252.205741ms","start":"2026-05-09T17:41:08.081969Z","end":"2026-05-09T17:41:08.334175Z","steps":["trace[1838549821] 'count revisions from in-memory index tree' (duration: 252.007526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:08.581335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.592595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:41:08.581447Z","caller":"traceutil/trace.go:171","msg":"trace[1218866114] transaction","detail":"{read_only:false; response_revision:23993; number_of_response:1; }","duration":"151.367717ms","start":"2026-05-09T17:41:08.430061Z","end":"2026-05-09T17:41:08.581429Z","steps":["trace[1218866114] 'compare' (duration: 145.755394ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:08.583807Z","caller":"traceutil/trace.go:171","msg":"trace[1452769345] transaction","detail":"{read_only:false; response_revision:23995; number_of_response:1; }","duration":"152.784291ms","start":"2026-05-09T17:41:08.430997Z","end":"2026-05-09T17:41:08.583781Z","steps":["trace[1452769345] 'process raft request' (duration: 152.376501ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:08.583814Z","caller":"traceutil/trace.go:171","msg":"trace[1557199971] transaction","detail":"{read_only:false; response_revision:23994; number_of_response:1; }","duration":"153.377465ms","start":"2026-05-09T17:41:08.430411Z","end":"2026-05-09T17:41:08.583788Z","steps":["trace[1557199971] 'process raft request' (duration: 152.825152ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:08.583808Z","caller":"traceutil/trace.go:171","msg":"trace[1442324902] transaction","detail":"{read_only:false; response_revision:23996; number_of_response:1; }","duration":"150.63643ms","start":"2026-05-09T17:41:08.433157Z","end":"2026-05-09T17:41:08.583793Z","steps":["trace[1442324902] 'process raft request' (duration: 150.284362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:08.886503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.495073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:41:08.88661Z","caller":"traceutil/trace.go:171","msg":"trace[1686784532] linearizableReadLoop","detail":"{readStateIndex:24777; appliedIndex:24776; }","duration":"233.089546ms","start":"2026-05-09T17:41:08.6535Z","end":"2026-05-09T17:41:08.88659Z","steps":["trace[1686784532] 'read index received' (duration: 88.370167ms)","trace[1686784532] 'applied index is now lower than readState.Index' (duration: 144.718029ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:41:08.887073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.387103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-05-09T17:41:08.887163Z","caller":"traceutil/trace.go:171","msg":"trace[667063862] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:23997; }","duration":"233.68985ms","start":"2026-05-09T17:41:08.653447Z","end":"2026-05-09T17:41:08.887137Z","steps":["trace[667063862] 'agreement among raft nodes before linearized reading' (duration: 233.24276ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:08.88722Z","caller":"traceutil/trace.go:171","msg":"trace[1568297844] transaction","detail":"{read_only:false; response_revision:23997; number_of_response:1; }","duration":"293.950796ms","start":"2026-05-09T17:41:08.593229Z","end":"2026-05-09T17:41:08.88718Z","steps":["trace[1568297844] 'process raft request' (duration: 148.694954ms)","trace[1568297844] 'compare' (duration: 144.113944ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:41:09.412698Z","caller":"traceutil/trace.go:171","msg":"trace[843987298] transaction","detail":"{read_only:false; response_revision:23999; number_of_response:1; }","duration":"217.541724ms","start":"2026-05-09T17:41:09.195125Z","end":"2026-05-09T17:41:09.412666Z","steps":["trace[843987298] 'process raft request' (duration: 217.289758ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:09.413041Z","caller":"traceutil/trace.go:171","msg":"trace[46367442] transaction","detail":"{read_only:false; response_revision:24000; number_of_response:1; }","duration":"216.991441ms","start":"2026-05-09T17:41:09.196009Z","end":"2026-05-09T17:41:09.413001Z","steps":["trace[46367442] 'process raft request' (duration: 216.598842ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:09.413111Z","caller":"traceutil/trace.go:171","msg":"trace[1826806183] linearizableReadLoop","detail":"{readStateIndex:24781; appliedIndex:24778; }","duration":"130.603683ms","start":"2026-05-09T17:41:09.282484Z","end":"2026-05-09T17:41:09.413087Z","steps":["trace[1826806183] 'read index received' (duration: 129.918876ms)","trace[1826806183] 'applied index is now lower than readState.Index' (duration: 682.787µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:41:09.413206Z","caller":"traceutil/trace.go:171","msg":"trace[1885596392] transaction","detail":"{read_only:false; response_revision:24001; number_of_response:1; }","duration":"215.645029ms","start":"2026-05-09T17:41:09.197523Z","end":"2026-05-09T17:41:09.413168Z","steps":["trace[1885596392] 'process raft request' (duration: 215.380932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:09.413406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.90554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-09T17:41:09.413479Z","caller":"traceutil/trace.go:171","msg":"trace[626626689] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:24001; }","duration":"131.029513ms","start":"2026-05-09T17:41:09.282431Z","end":"2026-05-09T17:41:09.41346Z","steps":["trace[626626689] 'agreement among raft nodes before linearized reading' (duration: 130.818188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:09.413416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.614918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-09T17:41:09.413644Z","caller":"traceutil/trace.go:171","msg":"trace[1300882984] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24001; }","duration":"113.839563ms","start":"2026-05-09T17:41:09.299759Z","end":"2026-05-09T17:41:09.413599Z","steps":["trace[1300882984] 'agreement among raft nodes before linearized reading' (duration: 113.503175ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:09.588623Z","caller":"traceutil/trace.go:171","msg":"trace[1589163463] linearizableReadLoop","detail":"{readStateIndex:24784; appliedIndex:24783; }","duration":"137.870396ms","start":"2026-05-09T17:41:09.450718Z","end":"2026-05-09T17:41:09.588589Z","steps":["trace[1589163463] 'read index received' (duration: 128.232197ms)","trace[1589163463] 'applied index is now lower than readState.Index' (duration: 9.636929ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:41:09.588653Z","caller":"traceutil/trace.go:171","msg":"trace[690758944] transaction","detail":"{read_only:false; response_revision:24003; number_of_response:1; }","duration":"154.062552ms","start":"2026-05-09T17:41:09.434551Z","end":"2026-05-09T17:41:09.588614Z","steps":["trace[690758944] 'process raft request' (duration: 144.353991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:09.588893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.172273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-09T17:41:09.588942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.749649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-09T17:41:09.588979Z","caller":"traceutil/trace.go:171","msg":"trace[395592898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24003; }","duration":"136.832681ms","start":"2026-05-09T17:41:09.452135Z","end":"2026-05-09T17:41:09.588968Z","steps":["trace[395592898] 'agreement among raft nodes before linearized reading' (duration: 136.648887ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:09.588975Z","caller":"traceutil/trace.go:171","msg":"trace[32314323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24003; }","duration":"138.316647ms","start":"2026-05-09T17:41:09.450639Z","end":"2026-05-09T17:41:09.588956Z","steps":["trace[32314323] 'agreement among raft nodes before linearized reading' (duration: 138.04272ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:09.743219Z","caller":"traceutil/trace.go:171","msg":"trace[2065464063] transaction","detail":"{read_only:false; response_revision:24004; number_of_response:1; }","duration":"148.728454ms","start":"2026-05-09T17:41:09.594465Z","end":"2026-05-09T17:41:09.743194Z","steps":["trace[2065464063] 'process raft request' (duration: 142.6451ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:09.887174Z","caller":"traceutil/trace.go:171","msg":"trace[1666895775] transaction","detail":"{read_only:false; response_revision:24005; number_of_response:1; }","duration":"292.183905ms","start":"2026-05-09T17:41:09.594953Z","end":"2026-05-09T17:41:09.887136Z","steps":["trace[1666895775] 'process raft request' (duration: 291.360444ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:09.887222Z","caller":"traceutil/trace.go:171","msg":"trace[1902984596] linearizableReadLoop","detail":"{readStateIndex:24786; appliedIndex:24784; }","duration":"291.086348ms","start":"2026-05-09T17:41:09.596104Z","end":"2026-05-09T17:41:09.88719Z","steps":["trace[1902984596] 'read index received' (duration: 141.032652ms)","trace[1902984596] 'applied index is now lower than readState.Index' (duration: 150.051156ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:41:09.887271Z","caller":"traceutil/trace.go:171","msg":"trace[1396741096] transaction","detail":"{read_only:false; response_revision:24006; number_of_response:1; }","duration":"273.250912ms","start":"2026-05-09T17:41:09.613998Z","end":"2026-05-09T17:41:09.887248Z","steps":["trace[1396741096] 'process raft request' (duration: 272.980566ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:09.887393Z","caller":"traceutil/trace.go:171","msg":"trace[1557392656] transaction","detail":"{read_only:false; response_revision:24007; number_of_response:1; }","duration":"273.385015ms","start":"2026-05-09T17:41:09.613989Z","end":"2026-05-09T17:41:09.887374Z","steps":["trace[1557392656] 'process raft request' (duration: 273.123789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:09.887682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.889506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-09T17:41:09.887723Z","caller":"traceutil/trace.go:171","msg":"trace[1500723607] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24007; }","duration":"275.994888ms","start":"2026-05-09T17:41:09.611713Z","end":"2026-05-09T17:41:09.887708Z","steps":["trace[1500723607] 'agreement among raft nodes before linearized reading' (duration: 275.869245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:09.887729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.6015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-09T17:41:09.887779Z","caller":"traceutil/trace.go:171","msg":"trace[958757989] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24007; }","duration":"291.677311ms","start":"2026-05-09T17:41:09.596087Z","end":"2026-05-09T17:41:09.887764Z","steps":["trace[958757989] 'agreement among raft nodes before linearized reading' (duration: 291.448016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:10.185424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.457292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:41:10.185548Z","caller":"traceutil/trace.go:171","msg":"trace[1378366951] linearizableReadLoop","detail":"{readStateIndex:24790; appliedIndex:24789; }","duration":"143.547352ms","start":"2026-05-09T17:41:10.04198Z","end":"2026-05-09T17:41:10.185527Z","steps":["trace[1378366951] 'read index received' (duration: 49.722µs)","trace[1378366951] 'applied index is now lower than readState.Index' (duration: 143.49581ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:41:10.185557Z","caller":"traceutil/trace.go:171","msg":"trace[1107112658] transaction","detail":"{read_only:false; response_revision:24009; number_of_response:1; }","duration":"213.013957ms","start":"2026-05-09T17:41:09.972514Z","end":"2026-05-09T17:41:10.185528Z","steps":["trace[1107112658] 'process raft request' (duration: 63.35466ms)","trace[1107112658] 'compare' (duration: 149.003741ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:41:10.185762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.759176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-09T17:41:10.185826Z","caller":"traceutil/trace.go:171","msg":"trace[1529924346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24009; }","duration":"143.879249ms","start":"2026-05-09T17:41:10.041929Z","end":"2026-05-09T17:41:10.185808Z","steps":["trace[1529924346] 'agreement among raft nodes before linearized reading' (duration: 143.656994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:10.185885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.239331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T17:41:10.185937Z","caller":"traceutil/trace.go:171","msg":"trace[1970997732] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24009; }","duration":"119.331504ms","start":"2026-05-09T17:41:10.066589Z","end":"2026-05-09T17:41:10.18592Z","steps":["trace[1970997732] 'agreement among raft nodes before linearized reading' (duration: 119.063427ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:10.369358Z","caller":"traceutil/trace.go:171","msg":"trace[708385839] transaction","detail":"{read_only:false; response_revision:24011; number_of_response:1; }","duration":"178.77961ms","start":"2026-05-09T17:41:10.19055Z","end":"2026-05-09T17:41:10.36933Z","steps":["trace[708385839] 'process raft request' (duration: 178.726449ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:10.369382Z","caller":"traceutil/trace.go:171","msg":"trace[899819895] transaction","detail":"{read_only:false; response_revision:24010; number_of_response:1; }","duration":"179.059408ms","start":"2026-05-09T17:41:10.190288Z","end":"2026-05-09T17:41:10.369348Z","steps":["trace[899819895] 'process raft request' (duration: 138.527462ms)","trace[899819895] 'compare' (duration: 40.28685ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:41:10.616957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.427935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:41:10.617039Z","caller":"traceutil/trace.go:171","msg":"trace[404974067] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:24011; }","duration":"142.524747ms","start":"2026-05-09T17:41:10.474494Z","end":"2026-05-09T17:41:10.617019Z","steps":["trace[404974067] 'count revisions from in-memory index tree' (duration: 142.335003ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:10.893869Z","caller":"traceutil/trace.go:171","msg":"trace[1195414154] transaction","detail":"{read_only:false; response_revision:24012; number_of_response:1; }","duration":"217.181005ms","start":"2026-05-09T17:41:10.67664Z","end":"2026-05-09T17:41:10.893821Z","steps":["trace[1195414154] 'process raft request' (duration: 216.850257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:21.755272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.152639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:41:21.755345Z","caller":"traceutil/trace.go:171","msg":"trace[1135131992] linearizableReadLoop","detail":"{readStateIndex:24873; appliedIndex:24872; }","duration":"260.349145ms","start":"2026-05-09T17:41:21.494984Z","end":"2026-05-09T17:41:21.755333Z","steps":["trace[1135131992] 'read index received' (duration: 149.080014ms)","trace[1135131992] 'applied index is now lower than readState.Index' (duration: 111.268481ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:41:21.755432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.43023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:41:21.755453Z","caller":"traceutil/trace.go:171","msg":"trace[584848676] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:24090; }","duration":"133.485461ms","start":"2026-05-09T17:41:21.62196Z","end":"2026-05-09T17:41:21.755446Z","steps":["trace[584848676] 'agreement among raft nodes before linearized reading' (duration: 133.44656ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:21.755428Z","caller":"traceutil/trace.go:171","msg":"trace[615721259] transaction","detail":"{read_only:false; response_revision:24090; number_of_response:1; }","duration":"260.499719ms","start":"2026-05-09T17:41:21.494888Z","end":"2026-05-09T17:41:21.755388Z","steps":["trace[615721259] 'process raft request' (duration: 149.172396ms)","trace[615721259] 'compare' (duration: 111.073337ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:41:21.75549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.483068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:41:21.755573Z","caller":"traceutil/trace.go:171","msg":"trace[550926394] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:24090; }","duration":"260.589581ms","start":"2026-05-09T17:41:21.494968Z","end":"2026-05-09T17:41:21.755558Z","steps":["trace[550926394] 'agreement among raft nodes before linearized reading' (duration: 260.436167ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:23.095643Z","caller":"traceutil/trace.go:171","msg":"trace[736701216] transaction","detail":"{read_only:false; response_revision:24100; number_of_response:1; }","duration":"112.913712ms","start":"2026-05-09T17:41:22.982695Z","end":"2026-05-09T17:41:23.095609Z","steps":["trace[736701216] 'process raft request' (duration: 112.670716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:23.955752Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278703757762568,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T17:41:23.984594Z","caller":"traceutil/trace.go:171","msg":"trace[1936516859] transaction","detail":"{read_only:false; response_revision:24102; number_of_response:1; }","duration":"617.773273ms","start":"2026-05-09T17:41:23.366799Z","end":"2026-05-09T17:41:23.984572Z","steps":["trace[1936516859] 'process raft request' (duration: 617.64097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:23.984719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:23.366777Z","time spent":"617.885766ms","remote":"127.0.0.1:48784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1560,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-09T17:41:24.82227Z","caller":"traceutil/trace.go:171","msg":"trace[995939125] transaction","detail":"{read_only:false; response_revision:24103; number_of_response:1; }","duration":"1.327639312s","start":"2026-05-09T17:41:23.494609Z","end":"2026-05-09T17:41:24.822249Z","steps":["trace[995939125] 'process raft request' (duration: 1.327466768s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:24.822494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:23.494593Z","time spent":"1.327828746s","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:41:24.822626Z","caller":"traceutil/trace.go:171","msg":"trace[148188178] transaction","detail":"{read_only:false; response_revision:24104; number_of_response:1; }","duration":"1.326562765s","start":"2026-05-09T17:41:23.496031Z","end":"2026-05-09T17:41:24.822594Z","steps":["trace[148188178] 'process raft request' (duration: 1.326178246s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:24.822799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:23.496013Z","time spent":"1.326689268s","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:41:24.822879Z","caller":"traceutil/trace.go:171","msg":"trace[1159288170] transaction","detail":"{read_only:false; response_revision:24105; number_of_response:1; }","duration":"1.324760662s","start":"2026-05-09T17:41:23.498066Z","end":"2026-05-09T17:41:24.822827Z","steps":["trace[1159288170] 'process raft request' (duration: 1.324539627s)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:24.822941Z","caller":"traceutil/trace.go:171","msg":"trace[1075325779] transaction","detail":"{read_only:false; response_revision:24106; number_of_response:1; }","duration":"888.095126ms","start":"2026-05-09T17:41:23.934835Z","end":"2026-05-09T17:41:24.82293Z","steps":["trace[1075325779] 'process raft request' (duration: 887.922432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:24.82302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:23.934801Z","time spent":"888.183398ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:41:24.823026Z","caller":"traceutil/trace.go:171","msg":"trace[1860591943] transaction","detail":"{read_only:false; response_revision:24107; number_of_response:1; }","duration":"886.16695ms","start":"2026-05-09T17:41:23.936818Z","end":"2026-05-09T17:41:24.822985Z","steps":["trace[1860591943] 'process raft request' (duration: 885.993946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:24.823042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.367478841s","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-05-09T17:41:24.823081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:23.498052Z","time spent":"1.324926767s","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:41:24.823129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:23.936804Z","time spent":"886.277442ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:41:24.822902Z","caller":"traceutil/trace.go:171","msg":"trace[1405897677] linearizableReadLoop","detail":"{readStateIndex:24890; appliedIndex:24884; }","duration":"1.367301597s","start":"2026-05-09T17:41:23.455549Z","end":"2026-05-09T17:41:24.82285Z","steps":["trace[1405897677] 'read index received' (duration: 528.887316ms)","trace[1405897677] 'applied index is now lower than readState.Index' (duration: 838.413351ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:41:24.823128Z","caller":"traceutil/trace.go:171","msg":"trace[206937655] 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:24107; }","duration":"1.367542852s","start":"2026-05-09T17:41:23.455542Z","end":"2026-05-09T17:41:24.823085Z","steps":["trace[206937655] 'agreement among raft nodes before linearized reading' (duration: 1.367402499s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:24.823327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:23.455506Z","time spent":"1.367780388s","remote":"127.0.0.1:48974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-05-09T17:41:25.117514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.428463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:41:25.11848Z","caller":"traceutil/trace.go:171","msg":"trace[476193595] transaction","detail":"{read_only:false; response_revision:24108; number_of_response:1; }","duration":"880.470405ms","start":"2026-05-09T17:41:24.237959Z","end":"2026-05-09T17:41:25.11843Z","steps":["trace[476193595] 'process raft request' (duration: 607.030167ms)","trace[476193595] 'compare' (duration: 272.115236ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:41:25.118871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:24.237923Z","time spent":"880.653239ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:41:25.339946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.936999ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-05-09T17:41:25.340053Z","caller":"traceutil/trace.go:171","msg":"trace[597989526] linearizableReadLoop","detail":"{readStateIndex:24892; appliedIndex:24890; }","duration":"516.812687ms","start":"2026-05-09T17:41:24.823222Z","end":"2026-05-09T17:41:25.340035Z","steps":["trace[597989526] 'read index received' (duration: 21.637995ms)","trace[597989526] 'applied index is now lower than readState.Index' (duration: 495.173242ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:41:25.340378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.519899584s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T17:41:25.340456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.17780192s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T17:41:25.340479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.216770879s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T17:41:25.340518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.608763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T17:41:25.34054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.763463918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:41:25.340558Z","caller":"traceutil/trace.go:171","msg":"trace[46041101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24108; }","duration":"1.520070538s","start":"2026-05-09T17:41:23.820416Z","end":"2026-05-09T17:41:25.340486Z","steps":["trace[46041101] 'agreement among raft nodes before linearized reading' (duration: 1.519923604s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.340571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.5407635s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-09T17:41:25.340624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.933399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T17:41:25.340636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:23.820398Z","time spent":"1.520226252s","remote":"127.0.0.1:56244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T17:41:25.340668Z","caller":"traceutil/trace.go:171","msg":"trace[861473876] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:24108; }","duration":"209.010931ms","start":"2026-05-09T17:41:25.131648Z","end":"2026-05-09T17:41:25.340659Z","steps":["trace[861473876] 'agreement among raft nodes before linearized reading' (duration: 208.952319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.340677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.22287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-05-09T17:41:25.340717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.377544701s","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-05-09T17:41:25.340749Z","caller":"traceutil/trace.go:171","msg":"trace[840248626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24108; }","duration":"1.377602083s","start":"2026-05-09T17:41:23.963138Z","end":"2026-05-09T17:41:25.34074Z","steps":["trace[840248626] 'agreement among raft nodes before linearized reading' (duration: 1.377471859s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.340497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.348060678s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1578"} {"level":"warn","ts":"2026-05-09T17:41:25.34075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.365023231s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-05-09T17:41:25.340781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:23.963124Z","time spent":"1.377650553s","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-09T17:41:25.340791Z","caller":"traceutil/trace.go:171","msg":"trace[1676267450] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24108; }","duration":"1.348381595s","start":"2026-05-09T17:41:23.992395Z","end":"2026-05-09T17:41:25.340776Z","steps":["trace[1676267450] 'agreement among raft nodes before linearized reading' (duration: 1.347864493s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.340783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.58186792s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-09T17:41:25.340799Z","caller":"traceutil/trace.go:171","msg":"trace[1984683267] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24108; }","duration":"1.365104804s","start":"2026-05-09T17:41:23.975679Z","end":"2026-05-09T17:41:25.340784Z","steps":["trace[1984683267] 'agreement among raft nodes before linearized reading' (duration: 1.364924759s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.340831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:23.992381Z","time spent":"1.348439966s","remote":"127.0.0.1:48784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1602,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-05-09T17:41:25.340831Z","caller":"traceutil/trace.go:171","msg":"trace[1035354518] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24108; }","duration":"1.581917251s","start":"2026-05-09T17:41:23.758902Z","end":"2026-05-09T17:41:25.340819Z","steps":["trace[1035354518] 'agreement among raft nodes before linearized reading' (duration: 1.581516262s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.340856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:23.975661Z","time spent":"1.365183286s","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-09T17:41:25.340882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:23.758853Z","time spent":"1.582016595s","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-09T17:41:25.340746Z","caller":"traceutil/trace.go:171","msg":"trace[2064798711] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24108; }","duration":"163.295151ms","start":"2026-05-09T17:41:25.177434Z","end":"2026-05-09T17:41:25.340729Z","steps":["trace[2064798711] 'agreement among raft nodes before linearized reading' (duration: 163.111927ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:25.340516Z","caller":"traceutil/trace.go:171","msg":"trace[1327756827] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:24108; }","duration":"1.177884062s","start":"2026-05-09T17:41:24.162615Z","end":"2026-05-09T17:41:25.340499Z","steps":["trace[1327756827] 'agreement among raft nodes before linearized reading' (duration: 1.17781886s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.340389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.104912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-09T17:41:25.341029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:24.162598Z","time spent":"1.178419934s","remote":"127.0.0.1:48988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true "} {"level":"info","ts":"2026-05-09T17:41:25.341063Z","caller":"traceutil/trace.go:171","msg":"trace[1459322368] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:24108; }","duration":"707.808689ms","start":"2026-05-09T17:41:24.633234Z","end":"2026-05-09T17:41:25.341043Z","steps":["trace[1459322368] 'agreement among raft nodes before linearized reading' (duration: 707.068431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.341127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:24.633168Z","time spent":"707.937941ms","remote":"127.0.0.1:55258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":32,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-05-09T17:41:25.340561Z","caller":"traceutil/trace.go:171","msg":"trace[1540913432] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:24108; }","duration":"686.683305ms","start":"2026-05-09T17:41:24.653865Z","end":"2026-05-09T17:41:25.340548Z","steps":["trace[1540913432] 'agreement among raft nodes before linearized reading' (duration: 686.631594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.340387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.678711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T17:41:25.341198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:24.653847Z","time spent":"687.333021ms","remote":"127.0.0.1:48898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"info","ts":"2026-05-09T17:41:25.341228Z","caller":"traceutil/trace.go:171","msg":"trace[1211207736] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:24108; }","duration":"858.565482ms","start":"2026-05-09T17:41:24.482649Z","end":"2026-05-09T17:41:25.341214Z","steps":["trace[1211207736] 'agreement among raft nodes before linearized reading' (duration: 857.674731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.340424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.350441555s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1578"} {"level":"warn","ts":"2026-05-09T17:41:25.341275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:24.482638Z","time spent":"858.619863ms","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-09T17:41:25.340475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.363156ms","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-05-09T17:41:25.341277Z","caller":"traceutil/trace.go:171","msg":"trace[1154431575] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24108; }","duration":"1.351339376s","start":"2026-05-09T17:41:23.989924Z","end":"2026-05-09T17:41:25.341264Z","steps":["trace[1154431575] 'agreement among raft nodes before linearized reading' (duration: 1.350360673s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.341326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:23.989904Z","time spent":"1.351411257s","remote":"127.0.0.1:48784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1602,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-05-09T17:41:25.341324Z","caller":"traceutil/trace.go:171","msg":"trace[1785991627] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:24108; }","duration":"590.257467ms","start":"2026-05-09T17:41:24.751054Z","end":"2026-05-09T17:41:25.341311Z","steps":["trace[1785991627] 'agreement among raft nodes before linearized reading' (duration: 589.383566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.341392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:24.751035Z","time spent":"590.339898ms","remote":"127.0.0.1:36636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":22,"response size":32,"request content":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true "} {"level":"info","ts":"2026-05-09T17:41:25.340555Z","caller":"traceutil/trace.go:171","msg":"trace[2027136953] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:24108; }","duration":"1.216879362s","start":"2026-05-09T17:41:24.12365Z","end":"2026-05-09T17:41:25.34053Z","steps":["trace[2027136953] 'agreement among raft nodes before linearized reading' (duration: 1.216790069s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.341477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:24.123628Z","time spent":"1.217837634s","remote":"127.0.0.1:48854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":30,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-09T17:41:25.34058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.681209528s","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-05-09T17:41:25.341549Z","caller":"traceutil/trace.go:171","msg":"trace[1711219820] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:24108; }","duration":"1.682204261s","start":"2026-05-09T17:41:23.659334Z","end":"2026-05-09T17:41:25.341539Z","steps":["trace[1711219820] 'agreement among raft nodes before linearized reading' (duration: 1.681209288s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.341593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:23.659319Z","time spent":"1.682261522s","remote":"127.0.0.1:56664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":32,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-05-09T17:41:25.340579Z","caller":"traceutil/trace.go:171","msg":"trace[1980144235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24108; }","duration":"1.763522059s","start":"2026-05-09T17:41:23.577045Z","end":"2026-05-09T17:41:25.340567Z","steps":["trace[1980144235] 'agreement among raft nodes before linearized reading' (duration: 1.763473988s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.341651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:23.577027Z","time spent":"1.764614175s","remote":"127.0.0.1:56242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T17:41:25.340677Z","caller":"traceutil/trace.go:171","msg":"trace[1591540906] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24108; }","duration":"1.540889824s","start":"2026-05-09T17:41:23.799764Z","end":"2026-05-09T17:41:25.340654Z","steps":["trace[1591540906] 'agreement among raft nodes before linearized reading' (duration: 1.540613737s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.34172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:23.799757Z","time spent":"1.541952929s","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-09T17:41:25.340385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.663775792s","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-05-09T17:41:25.341872Z","caller":"traceutil/trace.go:171","msg":"trace[2133039145] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:24108; }","duration":"1.665302579s","start":"2026-05-09T17:41:23.676556Z","end":"2026-05-09T17:41:25.341859Z","steps":["trace[2133039145] 'agreement among raft nodes before linearized reading' (duration: 1.663742511s)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.341915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:23.676545Z","time spent":"1.665359861s","remote":"127.0.0.1:49038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true "} {"level":"warn","ts":"2026-05-09T17:41:25.340491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.701805ms","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":"warn","ts":"2026-05-09T17:41:25.342053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.248048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T17:41:25.342082Z","caller":"traceutil/trace.go:171","msg":"trace[2042030549] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24108; }","duration":"513.343785ms","start":"2026-05-09T17:41:24.828729Z","end":"2026-05-09T17:41:25.342073Z","steps":["trace[2042030549] 'agreement among raft nodes before linearized reading' (duration: 511.663504ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:25.342116Z","caller":"traceutil/trace.go:171","msg":"trace[1036014253] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24108; }","duration":"911.33302ms","start":"2026-05-09T17:41:24.430767Z","end":"2026-05-09T17:41:25.3421Z","steps":["trace[1036014253] 'agreement among raft nodes before linearized reading' (duration: 909.623629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.342166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:24.430751Z","time spent":"911.403102ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T17:41:25.342173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:24.828714Z","time spent":"513.423356ms","remote":"127.0.0.1:56466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-05-09T17:41:25.342465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.109654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-09T17:41:25.342509Z","caller":"traceutil/trace.go:171","msg":"trace[744726564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24108; }","duration":"913.107792ms","start":"2026-05-09T17:41:24.429389Z","end":"2026-05-09T17:41:25.342497Z","steps":["trace[744726564] 'agreement among raft nodes before linearized reading' (duration: 910.93944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.342541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:24.429352Z","time spent":"913.178094ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-09T17:41:25.710561Z","caller":"traceutil/trace.go:171","msg":"trace[1449228027] transaction","detail":"{read_only:false; response_revision:24111; number_of_response:1; }","duration":"365.05882ms","start":"2026-05-09T17:41:25.345448Z","end":"2026-05-09T17:41:25.710507Z","steps":["trace[1449228027] 'process raft request' (duration: 364.968668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.710787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:25.345443Z","time spent":"365.267745ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:41:25.710806Z","caller":"traceutil/trace.go:171","msg":"trace[1739411195] transaction","detail":"{read_only:false; response_revision:24110; number_of_response:1; }","duration":"365.373847ms","start":"2026-05-09T17:41:25.345395Z","end":"2026-05-09T17:41:25.710769Z","steps":["trace[1739411195] 'process raft request' (duration: 364.873516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.711015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:25.345385Z","time spent":"365.535801ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:41:25.711259Z","caller":"traceutil/trace.go:171","msg":"trace[40512457] transaction","detail":"{read_only:false; response_revision:24112; number_of_response:1; }","duration":"365.572272ms","start":"2026-05-09T17:41:25.345659Z","end":"2026-05-09T17:41:25.711231Z","steps":["trace[40512457] 'process raft request' (duration: 364.807854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.711489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:25.345651Z","time spent":"365.682775ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:41:25.711713Z","caller":"traceutil/trace.go:171","msg":"trace[652087994] transaction","detail":"{read_only:false; response_revision:24113; number_of_response:1; }","duration":"365.86535ms","start":"2026-05-09T17:41:25.345794Z","end":"2026-05-09T17:41:25.71166Z","steps":["trace[652087994] 'process raft request' (duration: 365.191333ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:25.711951Z","caller":"traceutil/trace.go:171","msg":"trace[920299901] transaction","detail":"{read_only:false; response_revision:24115; number_of_response:1; }","duration":"363.442423ms","start":"2026-05-09T17:41:25.348487Z","end":"2026-05-09T17:41:25.71193Z","steps":["trace[920299901] 'process raft request' (duration: 363.303239ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:25.712007Z","caller":"traceutil/trace.go:171","msg":"trace[389237544] transaction","detail":"{read_only:false; response_revision:24116; number_of_response:1; }","duration":"362.545231ms","start":"2026-05-09T17:41:25.349443Z","end":"2026-05-09T17:41:25.711988Z","steps":["trace[389237544] 'process raft request' (duration: 362.413388ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:25.712037Z","caller":"traceutil/trace.go:171","msg":"trace[623297040] transaction","detail":"{read_only:false; response_revision:24114; number_of_response:1; }","duration":"365.585364ms","start":"2026-05-09T17:41:25.346427Z","end":"2026-05-09T17:41:25.712013Z","steps":["trace[623297040] 'process raft request' (duration: 365.090212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.712059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:25.348482Z","time spent":"363.520354ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:41:25.71212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:25.349435Z","time spent":"362.630293ms","remote":"127.0.0.1:48784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1873,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:41:25.712125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:25.34642Z","time spent":"365.659525ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:41:25.712183Z","caller":"traceutil/trace.go:171","msg":"trace[1416534969] linearizableReadLoop","detail":"{readStateIndex:24895; appliedIndex:24892; }","duration":"366.592627ms","start":"2026-05-09T17:41:25.345566Z","end":"2026-05-09T17:41:25.712159Z","steps":["trace[1416534969] 'read index received' (duration: 65.653584ms)","trace[1416534969] 'applied index is now lower than readState.Index' (duration: 300.937243ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:41:25.712305Z","caller":"traceutil/trace.go:171","msg":"trace[1657679968] transaction","detail":"{read_only:false; response_revision:24117; number_of_response:1; }","duration":"352.611534ms","start":"2026-05-09T17:41:25.359676Z","end":"2026-05-09T17:41:25.712288Z","steps":["trace[1657679968] 'process raft request' (duration: 352.253986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.712403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:25.359668Z","time spent":"352.687356ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:41:25.712506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.927395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-09T17:41:25.712568Z","caller":"traceutil/trace.go:171","msg":"trace[366020114] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24117; }","duration":"367.005067ms","start":"2026-05-09T17:41:25.345548Z","end":"2026-05-09T17:41:25.712553Z","steps":["trace[366020114] 'agreement among raft nodes before linearized reading' (duration: 366.741521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.712634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:25.345541Z","time spent":"367.080619ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-09T17:41:25.712694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.497263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-09T17:41:25.711916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:25.345788Z","time spent":"366.019574ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:41:25.712738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.68858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-09T17:41:25.712692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.443056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:41:25.712759Z","caller":"traceutil/trace.go:171","msg":"trace[1217179495] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24117; }","duration":"222.598646ms","start":"2026-05-09T17:41:25.490142Z","end":"2026-05-09T17:41:25.712741Z","steps":["trace[1217179495] 'agreement among raft nodes before linearized reading' (duration: 222.493953ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:25.712799Z","caller":"traceutil/trace.go:171","msg":"trace[166565390] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:24117; }","duration":"108.779773ms","start":"2026-05-09T17:41:25.604005Z","end":"2026-05-09T17:41:25.712784Z","steps":["trace[166565390] 'agreement among raft nodes before linearized reading' (duration: 108.67709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.712871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.198572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:41:25.712917Z","caller":"traceutil/trace.go:171","msg":"trace[1344613407] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24117; }","duration":"364.584389ms","start":"2026-05-09T17:41:25.348212Z","end":"2026-05-09T17:41:25.712796Z","steps":["trace[1344613407] 'agreement among raft nodes before linearized reading' (duration: 364.18565ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:25.712978Z","caller":"traceutil/trace.go:171","msg":"trace[1819252951] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24117; }","duration":"362.361516ms","start":"2026-05-09T17:41:25.350598Z","end":"2026-05-09T17:41:25.712959Z","steps":["trace[1819252951] 'agreement among raft nodes before linearized reading' (duration: 362.051518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:25.713034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:25.348207Z","time spent":"364.808474ms","remote":"127.0.0.1:56534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-05-09T17:41:25.713174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:25.350583Z","time spent":"362.523439ms","remote":"127.0.0.1:56242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-09T17:41:26.10569Z","caller":"traceutil/trace.go:171","msg":"trace[1026855392] linearizableReadLoop","detail":"{readStateIndex:24903; appliedIndex:24902; }","duration":"335.681611ms","start":"2026-05-09T17:41:25.769981Z","end":"2026-05-09T17:41:26.105663Z","steps":["trace[1026855392] 'read index received' (duration: 262.089457ms)","trace[1026855392] 'applied index is now lower than readState.Index' (duration: 73.590703ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:41:26.10589Z","caller":"traceutil/trace.go:171","msg":"trace[1789750956] transaction","detail":"{read_only:false; response_revision:24119; number_of_response:1; }","duration":"365.171122ms","start":"2026-05-09T17:41:25.740701Z","end":"2026-05-09T17:41:26.105872Z","steps":["trace[1789750956] 'process raft request' (duration: 291.276112ms)","trace[1789750956] 'compare' (duration: 73.158693ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:41:26.105995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:25.740688Z","time spent":"365.250914ms","remote":"127.0.0.1:56466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1240,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T17:41:26.10628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.295095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:41:26.106323Z","caller":"traceutil/trace.go:171","msg":"trace[1666365788] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24119; }","duration":"336.375987ms","start":"2026-05-09T17:41:25.769937Z","end":"2026-05-09T17:41:26.106313Z","steps":["trace[1666365788] 'agreement among raft nodes before linearized reading' (duration: 335.847004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:26.106402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:25.769919Z","time spent":"336.474589ms","remote":"127.0.0.1:56688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":30,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-09T17:41:26.180993Z","caller":"traceutil/trace.go:171","msg":"trace[1393439491] transaction","detail":"{read_only:false; response_revision:24120; number_of_response:1; }","duration":"274.908501ms","start":"2026-05-09T17:41:25.906049Z","end":"2026-05-09T17:41:26.180958Z","steps":["trace[1393439491] 'process raft request' (duration: 274.678706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:26.681175Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278703757762603,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-09T17:41:26.7259Z","caller":"traceutil/trace.go:171","msg":"trace[994289802] transaction","detail":"{read_only:false; response_revision:24121; number_of_response:1; }","duration":"615.243923ms","start":"2026-05-09T17:41:26.110625Z","end":"2026-05-09T17:41:26.725869Z","steps":["trace[994289802] 'process raft request' (duration: 615.000018ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:26.725952Z","caller":"traceutil/trace.go:171","msg":"trace[1407098495] linearizableReadLoop","detail":"{readStateIndex:24907; appliedIndex:24904; }","duration":"545.061641ms","start":"2026-05-09T17:41:26.180869Z","end":"2026-05-09T17:41:26.72593Z","steps":["trace[1407098495] 'read index received' (duration: 544.742563ms)","trace[1407098495] 'applied index is now lower than readState.Index' (duration: 318.208µs)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:41:26.725996Z","caller":"traceutil/trace.go:171","msg":"trace[191233636] transaction","detail":"{read_only:false; response_revision:24122; number_of_response:1; }","duration":"615.139091ms","start":"2026-05-09T17:41:26.110831Z","end":"2026-05-09T17:41:26.72597Z","steps":["trace[191233636] 'process raft request' (duration: 614.950806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:26.726081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:26.110829Z","time spent":"615.250053ms","remote":"127.0.0.1:56342","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-09T17:41:26.726109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:26.110826Z","time spent":"615.235113ms","remote":"127.0.0.1:56534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1237,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T17:41:26.726077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:26.110616Z","time spent":"615.352416ms","remote":"127.0.0.1:56432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-09T17:41:26.72624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.917026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-05-09T17:41:26.726279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.752106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:126760"} {"level":"info","ts":"2026-05-09T17:41:26.726315Z","caller":"traceutil/trace.go:171","msg":"trace[1302502510] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24122; }","duration":"542.031059ms","start":"2026-05-09T17:41:26.184267Z","end":"2026-05-09T17:41:26.726298Z","steps":["trace[1302502510] 'agreement among raft nodes before linearized reading' (duration: 541.822374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:26.72635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:26.184251Z","time spent":"542.09075ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-09T17:41:26.726356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.270397ms","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-05-09T17:41:26.72638Z","caller":"traceutil/trace.go:171","msg":"trace[512370832] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24122; }","duration":"107.335298ms","start":"2026-05-09T17:41:26.619038Z","end":"2026-05-09T17:41:26.726373Z","steps":["trace[512370832] 'agreement among raft nodes before linearized reading' (duration: 107.216886ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:26.726316Z","caller":"traceutil/trace.go:171","msg":"trace[123130328] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:24122; }","duration":"615.805987ms","start":"2026-05-09T17:41:26.1105Z","end":"2026-05-09T17:41:26.726306Z","steps":["trace[123130328] 'agreement among raft nodes before linearized reading' (duration: 615.624822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:26.726424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:26.110494Z","time spent":"615.918139ms","remote":"127.0.0.1:56324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":126784,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-05-09T17:41:26.726458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.783374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:41:26.726495Z","caller":"traceutil/trace.go:171","msg":"trace[241350933] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:24122; }","duration":"317.875367ms","start":"2026-05-09T17:41:26.408607Z","end":"2026-05-09T17:41:26.726482Z","steps":["trace[241350933] 'agreement among raft nodes before linearized reading' (duration: 317.807405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:26.726518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:26.408585Z","time spent":"317.926328ms","remote":"127.0.0.1:55068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-05-09T17:41:28.028676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.457864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T17:41:28.028792Z","caller":"traceutil/trace.go:171","msg":"trace[767948214] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:24185; }","duration":"105.583216ms","start":"2026-05-09T17:41:27.923156Z","end":"2026-05-09T17:41:28.028739Z","steps":["trace[767948214] 'count revisions from in-memory index tree' (duration: 105.380282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:28.028861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.810352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-453d4171-d885-4aa3-bac3-3a3376d8220c\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-05-09T17:41:28.02894Z","caller":"traceutil/trace.go:171","msg":"trace[230721064] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-453d4171-d885-4aa3-bac3-3a3376d8220c; range_end:; response_count:1; response_revision:24185; }","duration":"105.927664ms","start":"2026-05-09T17:41:27.922991Z","end":"2026-05-09T17:41:28.028919Z","steps":["trace[230721064] 'range keys from in-memory index tree' (duration: 105.575956ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:29.66315Z","caller":"traceutil/trace.go:171","msg":"trace[163375176] linearizableReadLoop","detail":"{readStateIndex:24995; appliedIndex:24994; }","duration":"169.54654ms","start":"2026-05-09T17:41:29.49358Z","end":"2026-05-09T17:41:29.663127Z","steps":["trace[163375176] 'read index received' (duration: 15.411536ms)","trace[163375176] 'applied index is now lower than readState.Index' (duration: 154.133624ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:41:29.663343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.744545ms","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-05-09T17:41:29.663382Z","caller":"traceutil/trace.go:171","msg":"trace[629177437] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:24209; }","duration":"169.832636ms","start":"2026-05-09T17:41:29.49354Z","end":"2026-05-09T17:41:29.663373Z","steps":["trace[629177437] 'agreement among raft nodes before linearized reading' (duration: 169.679623ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:29.663406Z","caller":"traceutil/trace.go:171","msg":"trace[472011943] transaction","detail":"{read_only:false; response_revision:24209; number_of_response:1; }","duration":"220.917495ms","start":"2026-05-09T17:41:29.442462Z","end":"2026-05-09T17:41:29.66338Z","steps":["trace[472011943] 'process raft request' (duration: 220.508955ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:33.445451Z","caller":"traceutil/trace.go:171","msg":"trace[2081813396] transaction","detail":"{read_only:false; response_revision:24265; number_of_response:1; }","duration":"307.773885ms","start":"2026-05-09T17:41:33.13765Z","end":"2026-05-09T17:41:33.445424Z","steps":["trace[2081813396] 'process raft request' (duration: 307.593811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:33.445605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:33.137626Z","time spent":"307.902787ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:41:33.714314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.070708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:41:33.714416Z","caller":"traceutil/trace.go:171","msg":"trace[520896747] linearizableReadLoop","detail":"{readStateIndex:25053; appliedIndex:25052; }","duration":"312.856206ms","start":"2026-05-09T17:41:33.401543Z","end":"2026-05-09T17:41:33.714399Z","steps":["trace[520896747] 'read index received' (duration: 43.914866ms)","trace[520896747] 'applied index is now lower than readState.Index' (duration: 268.94ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:41:33.714496Z","caller":"traceutil/trace.go:171","msg":"trace[1810502672] transaction","detail":"{read_only:false; response_revision:24266; number_of_response:1; }","duration":"572.647527ms","start":"2026-05-09T17:41:33.141835Z","end":"2026-05-09T17:41:33.714483Z","steps":["trace[1810502672] 'process raft request' (duration: 393.326034ms)","trace[1810502672] 'compare' (duration: 178.529135ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:41:33.714559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:33.141813Z","time spent":"572.71429ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:41:33.714701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.134212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10358"} {"level":"info","ts":"2026-05-09T17:41:33.714829Z","caller":"traceutil/trace.go:171","msg":"trace[123668524] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24266; }","duration":"313.307236ms","start":"2026-05-09T17:41:33.4015Z","end":"2026-05-09T17:41:33.714807Z","steps":["trace[123668524] 'agreement among raft nodes before linearized reading' (duration: 312.999939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:33.714896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:41:33.401479Z","time spent":"313.402249ms","remote":"127.0.0.1:56462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10382,"request content":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" "} {"level":"warn","ts":"2026-05-09T17:41:33.742409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.361834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:41:33.742486Z","caller":"traceutil/trace.go:171","msg":"trace[731604672] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:24267; }","duration":"200.477877ms","start":"2026-05-09T17:41:33.541991Z","end":"2026-05-09T17:41:33.742469Z","steps":["trace[731604672] 'agreement among raft nodes before linearized reading' (duration: 200.356594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:33.742597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.404477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-09T17:41:33.742671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.992871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:41:33.742677Z","caller":"traceutil/trace.go:171","msg":"trace[248783725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24267; }","duration":"221.52497ms","start":"2026-05-09T17:41:33.521133Z","end":"2026-05-09T17:41:33.742658Z","steps":["trace[248783725] 'agreement among raft nodes before linearized reading' (duration: 221.302804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:33.742719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.021256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-09T17:41:33.742744Z","caller":"traceutil/trace.go:171","msg":"trace[2112113513] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:24267; }","duration":"102.111613ms","start":"2026-05-09T17:41:33.64062Z","end":"2026-05-09T17:41:33.742732Z","steps":["trace[2112113513] 'agreement among raft nodes before linearized reading' (duration: 102.016831ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:41:33.742757Z","caller":"traceutil/trace.go:171","msg":"trace[289537344] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24267; }","duration":"260.119138ms","start":"2026-05-09T17:41:33.482628Z","end":"2026-05-09T17:41:33.742747Z","steps":["trace[289537344] 'agreement among raft nodes before linearized reading' (duration: 259.972144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:59.551102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.816004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T17:41:59.55117Z","caller":"traceutil/trace.go:171","msg":"trace[1171960295] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24485; }","duration":"171.940718ms","start":"2026-05-09T17:41:59.379216Z","end":"2026-05-09T17:41:59.551157Z","steps":["trace[1171960295] 'count revisions from in-memory index tree' (duration: 171.752773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:41:59.551299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.603628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:41:59.551326Z","caller":"traceutil/trace.go:171","msg":"trace[1737345299] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:24485; }","duration":"168.654089ms","start":"2026-05-09T17:41:59.382664Z","end":"2026-05-09T17:41:59.551318Z","steps":["trace[1737345299] 'count revisions from in-memory index tree' (duration: 168.434425ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:03.866078Z","caller":"traceutil/trace.go:171","msg":"trace[2072494892] transaction","detail":"{read_only:false; response_revision:24514; number_of_response:1; }","duration":"381.897472ms","start":"2026-05-09T17:42:03.484158Z","end":"2026-05-09T17:42:03.866055Z","steps":["trace[2072494892] 'process raft request' (duration: 381.752598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:03.866217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:03.484137Z","time spent":"382.011264ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:42:03.86668Z","caller":"traceutil/trace.go:171","msg":"trace[462476028] linearizableReadLoop","detail":"{readStateIndex:25307; appliedIndex:25307; }","duration":"226.171971ms","start":"2026-05-09T17:42:03.6405Z","end":"2026-05-09T17:42:03.866672Z","steps":["trace[462476028] 'read index received' (duration: 226.167791ms)","trace[462476028] 'applied index is now lower than readState.Index' (duration: 3.5µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:03.866747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.241903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:42:03.866767Z","caller":"traceutil/trace.go:171","msg":"trace[1024072765] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:24514; }","duration":"226.293144ms","start":"2026-05-09T17:42:03.640467Z","end":"2026-05-09T17:42:03.86676Z","steps":["trace[1024072765] 'agreement among raft nodes before linearized reading' (duration: 226.248743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:04.158119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.151359ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:42:04.158263Z","caller":"traceutil/trace.go:171","msg":"trace[1249389491] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24514; }","duration":"244.305553ms","start":"2026-05-09T17:42:03.913935Z","end":"2026-05-09T17:42:04.158241Z","steps":["trace[1249389491] 'range keys from in-memory index tree' (duration: 244.135189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:04.159053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.493467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:42:04.159211Z","caller":"traceutil/trace.go:171","msg":"trace[128970016] linearizableReadLoop","detail":"{readStateIndex:25309; appliedIndex:25307; }","duration":"292.50304ms","start":"2026-05-09T17:42:03.866696Z","end":"2026-05-09T17:42:04.159199Z","steps":["trace[128970016] 'read index received' (duration: 73.814698ms)","trace[128970016] 'applied index is now lower than readState.Index' (duration: 218.687362ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:04.159394Z","caller":"traceutil/trace.go:171","msg":"trace[1440644363] transaction","detail":"{read_only:false; response_revision:24515; number_of_response:1; }","duration":"392.800221ms","start":"2026-05-09T17:42:03.766525Z","end":"2026-05-09T17:42:04.159325Z","steps":["trace[1440644363] 'process raft request' (duration: 173.970776ms)","trace[1440644363] 'compare' (duration: 218.093968ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:04.159444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:03.766498Z","time spent":"392.920744ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:42:04.159486Z","caller":"traceutil/trace.go:171","msg":"trace[1000602913] transaction","detail":"{read_only:false; response_revision:24517; number_of_response:1; }","duration":"284.487219ms","start":"2026-05-09T17:42:03.874978Z","end":"2026-05-09T17:42:04.159465Z","steps":["trace[1000602913] 'process raft request' (duration: 284.198383ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:04.159556Z","caller":"traceutil/trace.go:171","msg":"trace[167822117] transaction","detail":"{read_only:false; response_revision:24516; number_of_response:1; }","duration":"321.231635ms","start":"2026-05-09T17:42:03.838317Z","end":"2026-05-09T17:42:04.159548Z","steps":["trace[167822117] 'process raft request' (duration: 320.809716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:04.159599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:03.838295Z","time spent":"321.283936ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:42:04.159701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.838206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:42:04.159722Z","caller":"traceutil/trace.go:171","msg":"trace[1351507563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24517; }","duration":"320.887577ms","start":"2026-05-09T17:42:03.838829Z","end":"2026-05-09T17:42:04.159717Z","steps":["trace[1351507563] 'agreement among raft nodes before linearized reading' (duration: 320.820086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:04.159736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:03.838818Z","time spent":"320.914858ms","remote":"127.0.0.1:56242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-09T17:42:04.159888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.959378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-09T17:42:04.159906Z","caller":"traceutil/trace.go:171","msg":"trace[1940311912] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24517; }","duration":"257.992849ms","start":"2026-05-09T17:42:03.901909Z","end":"2026-05-09T17:42:04.159902Z","steps":["trace[1940311912] 'agreement among raft nodes before linearized reading' (duration: 257.914637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:04.160094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.604295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-09T17:42:04.160115Z","caller":"traceutil/trace.go:171","msg":"trace[1324329889] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24517; }","duration":"222.644396ms","start":"2026-05-09T17:42:03.937465Z","end":"2026-05-09T17:42:04.160109Z","steps":["trace[1324329889] 'agreement among raft nodes before linearized reading' (duration: 222.595734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:04.160183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.267017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-09T17:42:04.160197Z","caller":"traceutil/trace.go:171","msg":"trace[2115469394] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24517; }","duration":"230.288689ms","start":"2026-05-09T17:42:03.929904Z","end":"2026-05-09T17:42:04.160193Z","steps":["trace[2115469394] 'agreement among raft nodes before linearized reading' (duration: 230.248868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:04.160252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.194933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T17:42:04.160268Z","caller":"traceutil/trace.go:171","msg":"trace[1493718209] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24517; }","duration":"232.236794ms","start":"2026-05-09T17:42:03.928025Z","end":"2026-05-09T17:42:04.160262Z","steps":["trace[1493718209] 'agreement among raft nodes before linearized reading' (duration: 232.200263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:04.16036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.284654ms","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-05-09T17:42:04.160376Z","caller":"traceutil/trace.go:171","msg":"trace[612810351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24517; }","duration":"250.305625ms","start":"2026-05-09T17:42:03.910066Z","end":"2026-05-09T17:42:04.160372Z","steps":["trace[612810351] 'agreement among raft nodes before linearized reading' (duration: 250.262084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:04.16044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.380158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-09T17:42:04.160457Z","caller":"traceutil/trace.go:171","msg":"trace[431966645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24517; }","duration":"250.403219ms","start":"2026-05-09T17:42:03.910048Z","end":"2026-05-09T17:42:04.160452Z","steps":["trace[431966645] 'agreement among raft nodes before linearized reading' (duration: 250.361538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:04.16051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.561952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:42:04.160524Z","caller":"traceutil/trace.go:171","msg":"trace[401770719] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:24517; }","duration":"250.587063ms","start":"2026-05-09T17:42:03.909933Z","end":"2026-05-09T17:42:04.16052Z","steps":["trace[401770719] 'agreement among raft nodes before linearized reading' (duration: 250.560313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:04.466253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.43381ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:42:04.466335Z","caller":"traceutil/trace.go:171","msg":"trace[926808321] transaction","detail":"{read_only:false; response_revision:24518; number_of_response:1; }","duration":"301.221749ms","start":"2026-05-09T17:42:04.165101Z","end":"2026-05-09T17:42:04.466323Z","steps":["trace[926808321] 'process raft request' (duration: 94.661396ms)","trace[926808321] 'compare' (duration: 206.115823ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:04.466368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:04.165069Z","time spent":"301.283441ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:42:04.546702Z","caller":"traceutil/trace.go:171","msg":"trace[1590034307] transaction","detail":"{read_only:false; response_revision:24523; number_of_response:1; }","duration":"380.899997ms","start":"2026-05-09T17:42:04.165782Z","end":"2026-05-09T17:42:04.546682Z","steps":["trace[1590034307] 'process raft request' (duration: 380.873447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:04.546842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:04.165751Z","time spent":"381.01141ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:42:04.547101Z","caller":"traceutil/trace.go:171","msg":"trace[704330814] transaction","detail":"{read_only:false; response_revision:24519; number_of_response:1; }","duration":"381.891642ms","start":"2026-05-09T17:42:04.165198Z","end":"2026-05-09T17:42:04.54709Z","steps":["trace[704330814] 'process raft request' (duration: 381.186674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:04.547154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:04.16518Z","time spent":"381.946543ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:42:04.547249Z","caller":"traceutil/trace.go:171","msg":"trace[1325283563] transaction","detail":"{read_only:false; response_revision:24520; number_of_response:1; }","duration":"381.912253ms","start":"2026-05-09T17:42:04.165331Z","end":"2026-05-09T17:42:04.547243Z","steps":["trace[1325283563] 'process raft request' (duration: 381.237016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:04.54729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:04.16532Z","time spent":"381.951803ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:42:04.547452Z","caller":"traceutil/trace.go:171","msg":"trace[1575142273] transaction","detail":"{read_only:false; response_revision:24521; number_of_response:1; }","duration":"382.023905ms","start":"2026-05-09T17:42:04.165422Z","end":"2026-05-09T17:42:04.547446Z","steps":["trace[1575142273] 'process raft request' (duration: 381.183845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:04.547494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:04.165413Z","time spent":"382.060436ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:42:04.54761Z","caller":"traceutil/trace.go:171","msg":"trace[332175228] transaction","detail":"{read_only:false; response_revision:24522; number_of_response:1; }","duration":"381.917572ms","start":"2026-05-09T17:42:04.165685Z","end":"2026-05-09T17:42:04.547602Z","steps":["trace[332175228] 'process raft request' (duration: 380.948968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:04.547652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:04.165674Z","time spent":"381.958744ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:42:04.983321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.781327ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-05-09T17:42:04.983437Z","caller":"traceutil/trace.go:171","msg":"trace[231573567] linearizableReadLoop","detail":"{readStateIndex:25317; appliedIndex:25311; }","duration":"545.048999ms","start":"2026-05-09T17:42:04.438365Z","end":"2026-05-09T17:42:04.983414Z","steps":["trace[231573567] 'read index received' (duration: 107.969832ms)","trace[231573567] 'applied index is now lower than readState.Index' (duration: 437.077167ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:04.983573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.187802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:42:04.983612Z","caller":"traceutil/trace.go:171","msg":"trace[1050991075] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:24523; }","duration":"545.282185ms","start":"2026-05-09T17:42:04.438318Z","end":"2026-05-09T17:42:04.983601Z","steps":["trace[1050991075] 'agreement among raft nodes before linearized reading' (duration: 545.191972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:04.983651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:04.4383Z","time spent":"545.341656ms","remote":"127.0.0.1:36660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"info","ts":"2026-05-09T17:42:05.193444Z","caller":"traceutil/trace.go:171","msg":"trace[131946172] transaction","detail":"{read_only:false; response_revision:24525; number_of_response:1; }","duration":"239.508598ms","start":"2026-05-09T17:42:04.953916Z","end":"2026-05-09T17:42:05.193425Z","steps":["trace[131946172] 'process raft request' (duration: 211.07879ms)","trace[131946172] 'compare' (duration: 28.271744ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:05.193864Z","caller":"traceutil/trace.go:171","msg":"trace[1475162917] transaction","detail":"{read_only:false; response_revision:24526; number_of_response:1; }","duration":"236.67153ms","start":"2026-05-09T17:42:04.957167Z","end":"2026-05-09T17:42:05.193838Z","steps":["trace[1475162917] 'process raft request' (duration: 236.207989ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:05.19426Z","caller":"traceutil/trace.go:171","msg":"trace[867673429] linearizableReadLoop","detail":"{readStateIndex:25322; appliedIndex:25317; }","duration":"210.750914ms","start":"2026-05-09T17:42:04.983488Z","end":"2026-05-09T17:42:05.194239Z","steps":["trace[867673429] 'read index received' (duration: 26.41391ms)","trace[867673429] 'applied index is now lower than readState.Index' (duration: 184.336074ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:05.194286Z","caller":"traceutil/trace.go:171","msg":"trace[2096715351] transaction","detail":"{read_only:false; response_revision:24528; number_of_response:1; }","duration":"235.101793ms","start":"2026-05-09T17:42:04.959161Z","end":"2026-05-09T17:42:05.194262Z","steps":["trace[2096715351] 'process raft request' (duration: 234.995631ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:05.194311Z","caller":"traceutil/trace.go:171","msg":"trace[41398001] transaction","detail":"{read_only:false; response_revision:24527; number_of_response:1; }","duration":"236.305602ms","start":"2026-05-09T17:42:04.957957Z","end":"2026-05-09T17:42:05.194262Z","steps":["trace[41398001] 'process raft request' (duration: 235.651276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:05.194473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.344768ms","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:1936"} {"level":"info","ts":"2026-05-09T17:42:05.194532Z","caller":"traceutil/trace.go:171","msg":"trace[66769996] 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:24528; }","duration":"480.41896ms","start":"2026-05-09T17:42:04.714097Z","end":"2026-05-09T17:42:05.194516Z","steps":["trace[66769996] 'agreement among raft nodes before linearized reading' (duration: 480.248736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:05.194572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:04.714066Z","time spent":"480.497072ms","remote":"127.0.0.1:56430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1960,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-05-09T17:42:06.313448Z","caller":"traceutil/trace.go:171","msg":"trace[415020884] transaction","detail":"{read_only:false; response_revision:24529; number_of_response:1; }","duration":"147.258489ms","start":"2026-05-09T17:42:06.166161Z","end":"2026-05-09T17:42:06.313419Z","steps":["trace[415020884] 'process raft request' (duration: 147.027574ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:06.339798Z","caller":"traceutil/trace.go:171","msg":"trace[607842002] transaction","detail":"{read_only:false; response_revision:24530; number_of_response:1; }","duration":"172.229465ms","start":"2026-05-09T17:42:06.16755Z","end":"2026-05-09T17:42:06.33978Z","steps":["trace[607842002] 'process raft request' (duration: 172.107062ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:06.685713Z","caller":"traceutil/trace.go:171","msg":"trace[101828012] transaction","detail":"{read_only:false; response_revision:24531; number_of_response:1; }","duration":"210.071457ms","start":"2026-05-09T17:42:06.47562Z","end":"2026-05-09T17:42:06.685691Z","steps":["trace[101828012] 'process raft request' (duration: 209.929493ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:06.686557Z","caller":"traceutil/trace.go:171","msg":"trace[1511488753] linearizableReadLoop","detail":"{readStateIndex:25325; appliedIndex:25325; }","duration":"153.98803ms","start":"2026-05-09T17:42:06.53255Z","end":"2026-05-09T17:42:06.686538Z","steps":["trace[1511488753] 'read index received' (duration: 153.97707ms)","trace[1511488753] 'applied index is now lower than readState.Index' (duration: 9.03µs)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:06.686765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.191465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T17:42:06.686855Z","caller":"traceutil/trace.go:171","msg":"trace[1509007498] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:24531; }","duration":"154.318538ms","start":"2026-05-09T17:42:06.532513Z","end":"2026-05-09T17:42:06.686832Z","steps":["trace[1509007498] 'agreement among raft nodes before linearized reading' (duration: 154.114744ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:06.887434Z","caller":"traceutil/trace.go:171","msg":"trace[1928388542] linearizableReadLoop","detail":"{readStateIndex:25326; appliedIndex:25325; }","duration":"200.804216ms","start":"2026-05-09T17:42:06.686607Z","end":"2026-05-09T17:42:06.887412Z","steps":["trace[1928388542] 'read index received' (duration: 133.258486ms)","trace[1928388542] 'applied index is now lower than readState.Index' (duration: 67.54465ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:06.887675Z","caller":"traceutil/trace.go:171","msg":"trace[1553845972] transaction","detail":"{read_only:false; response_revision:24532; number_of_response:1; }","duration":"295.863682ms","start":"2026-05-09T17:42:06.591797Z","end":"2026-05-09T17:42:06.887661Z","steps":["trace[1553845972] 'process raft request' (duration: 228.307302ms)","trace[1553845972] 'compare' (duration: 67.192151ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:06.887948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.308637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-05-09T17:42:06.88799Z","caller":"traceutil/trace.go:171","msg":"trace[1932005909] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:24532; }","duration":"248.39765ms","start":"2026-05-09T17:42:06.639579Z","end":"2026-05-09T17:42:06.887976Z","steps":["trace[1932005909] 'agreement among raft nodes before linearized reading' (duration: 248.262967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:06.888069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.355007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-09T17:42:06.888131Z","caller":"traceutil/trace.go:171","msg":"trace[1295017269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24532; }","duration":"336.433918ms","start":"2026-05-09T17:42:06.551682Z","end":"2026-05-09T17:42:06.888116Z","steps":["trace[1295017269] 'agreement among raft nodes before linearized reading' (duration: 336.224024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:06.888159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:06.551603Z","time spent":"336.55208ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-09T17:42:06.888187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.51431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-09T17:42:06.888218Z","caller":"traceutil/trace.go:171","msg":"trace[245074819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24532; }","duration":"336.584392ms","start":"2026-05-09T17:42:06.551625Z","end":"2026-05-09T17:42:06.888209Z","steps":["trace[245074819] 'agreement among raft nodes before linearized reading' (duration: 336.49523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:06.888244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:06.551605Z","time spent":"336.630902ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-09T17:42:06.888403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.63421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:42:06.888459Z","caller":"traceutil/trace.go:171","msg":"trace[1562882617] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:24532; }","duration":"304.702531ms","start":"2026-05-09T17:42:06.583721Z","end":"2026-05-09T17:42:06.888423Z","steps":["trace[1562882617] 'agreement among raft nodes before linearized reading' (duration: 304.646129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:06.888489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:06.583698Z","time spent":"304.781783ms","remote":"127.0.0.1:48556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true "} {"level":"warn","ts":"2026-05-09T17:42:06.888507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.28805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-09T17:42:06.888549Z","caller":"traceutil/trace.go:171","msg":"trace[50425030] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24532; }","duration":"335.333461ms","start":"2026-05-09T17:42:06.553204Z","end":"2026-05-09T17:42:06.888537Z","steps":["trace[50425030] 'agreement among raft nodes before linearized reading' (duration: 335.229149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:06.888574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:06.553176Z","time spent":"335.390342ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-09T17:42:06.888578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.860758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-09T17:42:06.888633Z","caller":"traceutil/trace.go:171","msg":"trace[949726409] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24532; }","duration":"336.95378ms","start":"2026-05-09T17:42:06.551667Z","end":"2026-05-09T17:42:06.88862Z","steps":["trace[949726409] 'agreement among raft nodes before linearized reading' (duration: 336.733135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:06.888573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.922836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-05-09T17:42:06.88866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:06.551656Z","time spent":"336.999871ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-09T17:42:06.888704Z","caller":"traceutil/trace.go:171","msg":"trace[593426617] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24532; }","duration":"336.10205ms","start":"2026-05-09T17:42:06.552569Z","end":"2026-05-09T17:42:06.888671Z","steps":["trace[593426617] 'agreement among raft nodes before linearized reading' (duration: 335.740692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:06.888754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:06.552552Z","time spent":"336.192862ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-09T17:42:07.240698Z","caller":"traceutil/trace.go:171","msg":"trace[653848094] transaction","detail":"{read_only:false; response_revision:24536; number_of_response:1; }","duration":"348.183348ms","start":"2026-05-09T17:42:06.892496Z","end":"2026-05-09T17:42:07.240679Z","steps":["trace[653848094] 'process raft request' (duration: 348.089946ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:07.240753Z","caller":"traceutil/trace.go:171","msg":"trace[243978379] transaction","detail":"{read_only:false; response_revision:24535; number_of_response:1; }","duration":"348.346502ms","start":"2026-05-09T17:42:06.892372Z","end":"2026-05-09T17:42:07.240719Z","steps":["trace[243978379] 'process raft request' (duration: 264.76881ms)","trace[243978379] 'compare' (duration: 82.815234ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:07.240819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:06.892478Z","time spent":"348.27397ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:42:07.240876Z","caller":"traceutil/trace.go:171","msg":"trace[1503151408] transaction","detail":"{read_only:false; response_revision:24537; number_of_response:1; }","duration":"347.759348ms","start":"2026-05-09T17:42:06.893095Z","end":"2026-05-09T17:42:07.240854Z","steps":["trace[1503151408] 'process raft request' (duration: 347.561234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:07.240983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:06.893085Z","time spent":"347.84383ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:42:07.241139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:06.892358Z","time spent":"348.473045ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-09T17:42:07.290523Z","caller":"traceutil/trace.go:171","msg":"trace[686777390] transaction","detail":"{read_only:false; response_revision:24538; number_of_response:1; }","duration":"267.321482ms","start":"2026-05-09T17:42:07.023175Z","end":"2026-05-09T17:42:07.290497Z","steps":["trace[686777390] 'process raft request' (duration: 267.192749ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:07.471178Z","caller":"traceutil/trace.go:171","msg":"trace[301266167] transaction","detail":"{read_only:false; response_revision:24540; number_of_response:1; }","duration":"271.802888ms","start":"2026-05-09T17:42:07.199356Z","end":"2026-05-09T17:42:07.471159Z","steps":["trace[301266167] 'process raft request' (duration: 271.745877ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:07.471187Z","caller":"traceutil/trace.go:171","msg":"trace[1897485530] transaction","detail":"{read_only:false; response_revision:24539; number_of_response:1; }","duration":"271.86773ms","start":"2026-05-09T17:42:07.19929Z","end":"2026-05-09T17:42:07.471158Z","steps":["trace[1897485530] 'process raft request' (duration: 271.695306ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:07.471456Z","caller":"traceutil/trace.go:171","msg":"trace[252410526] transaction","detail":"{read_only:false; response_revision:24541; number_of_response:1; }","duration":"271.323786ms","start":"2026-05-09T17:42:07.200121Z","end":"2026-05-09T17:42:07.471444Z","steps":["trace[252410526] 'process raft request' (duration: 271.007178ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:07.471511Z","caller":"traceutil/trace.go:171","msg":"trace[579234181] transaction","detail":"{read_only:false; response_revision:24542; number_of_response:1; }","duration":"271.301386ms","start":"2026-05-09T17:42:07.20018Z","end":"2026-05-09T17:42:07.471482Z","steps":["trace[579234181] 'process raft request' (duration: 271.148712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:07.739299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.25392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:42:07.739362Z","caller":"traceutil/trace.go:171","msg":"trace[1920596908] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:24542; }","duration":"181.355523ms","start":"2026-05-09T17:42:07.557992Z","end":"2026-05-09T17:42:07.739348Z","steps":["trace[1920596908] 'count revisions from in-memory index tree' (duration: 181.193438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:07.739492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.620769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-09T17:42:07.739839Z","caller":"traceutil/trace.go:171","msg":"trace[130263475] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:24542; }","duration":"130.015108ms","start":"2026-05-09T17:42:07.609791Z","end":"2026-05-09T17:42:07.739806Z","steps":["trace[130263475] 'count revisions from in-memory index tree' (duration: 129.550677ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:08.517993Z","caller":"traceutil/trace.go:171","msg":"trace[229730410] transaction","detail":"{read_only:false; response_revision:24543; number_of_response:1; }","duration":"198.46763ms","start":"2026-05-09T17:42:08.319499Z","end":"2026-05-09T17:42:08.517967Z","steps":["trace[229730410] 'process raft request' (duration: 198.300886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:08.677307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.822519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:42:08.677406Z","caller":"traceutil/trace.go:171","msg":"trace[2063514950] linearizableReadLoop","detail":"{readStateIndex:25338; appliedIndex:25337; }","duration":"117.190183ms","start":"2026-05-09T17:42:08.560199Z","end":"2026-05-09T17:42:08.677389Z","steps":["trace[2063514950] 'read index received' (duration: 34.041µs)","trace[2063514950] 'applied index is now lower than readState.Index' (duration: 117.155082ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:08.677593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.394978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-09T17:42:08.677557Z","caller":"traceutil/trace.go:171","msg":"trace[170934247] transaction","detail":"{read_only:false; response_revision:24544; number_of_response:1; }","duration":"332.945115ms","start":"2026-05-09T17:42:08.344455Z","end":"2026-05-09T17:42:08.6774Z","steps":["trace[170934247] 'process raft request' (duration: 198.948752ms)","trace[170934247] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/capi-system/controller-leader-election-capi; req_size:518; } (duration: 133.105293ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:08.677629Z","caller":"traceutil/trace.go:171","msg":"trace[1645612073] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:24544; }","duration":"117.47341ms","start":"2026-05-09T17:42:08.560146Z","end":"2026-05-09T17:42:08.67762Z","steps":["trace[1645612073] 'agreement among raft nodes before linearized reading' (duration: 117.284765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:08.677721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-09T17:42:08.344431Z","time spent":"333.213602ms","remote":"127.0.0.1:56532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-09T17:42:08.946298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.59668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:42:08.946633Z","caller":"traceutil/trace.go:171","msg":"trace[573847095] transaction","detail":"{read_only:false; response_revision:24545; number_of_response:1; }","duration":"252.016836ms","start":"2026-05-09T17:42:08.694599Z","end":"2026-05-09T17:42:08.946616Z","steps":["trace[573847095] 'process raft request' (duration: 119.023017ms)","trace[573847095] 'compare' (duration: 132.375695ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:08.946748Z","caller":"traceutil/trace.go:171","msg":"trace[1548670293] linearizableReadLoop","detail":"{readStateIndex:25339; appliedIndex:25338; }","duration":"128.779959ms","start":"2026-05-09T17:42:08.817955Z","end":"2026-05-09T17:42:08.946735Z","steps":["trace[1548670293] 'read index received' (duration: 40.221µs)","trace[1548670293] 'applied index is now lower than readState.Index' (duration: 128.738778ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:08.946825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.874652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:42:08.946851Z","caller":"traceutil/trace.go:171","msg":"trace[2074135924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24546; }","duration":"128.938243ms","start":"2026-05-09T17:42:08.817905Z","end":"2026-05-09T17:42:08.946844Z","steps":["trace[2074135924] 'agreement among raft nodes before linearized reading' (duration: 128.865831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:09.195531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.580278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:42:09.195639Z","caller":"traceutil/trace.go:171","msg":"trace[2073255997] linearizableReadLoop","detail":"{readStateIndex:25342; appliedIndex:25341; }","duration":"105.243379ms","start":"2026-05-09T17:42:09.090376Z","end":"2026-05-09T17:42:09.195619Z","steps":["trace[2073255997] 'read index received' (duration: 78.192µs)","trace[2073255997] 'applied index is now lower than readState.Index' (duration: 105.163587ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:09.195717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.336361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-09T17:42:09.195747Z","caller":"traceutil/trace.go:171","msg":"trace[936056346] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:24548; }","duration":"105.409533ms","start":"2026-05-09T17:42:09.090327Z","end":"2026-05-09T17:42:09.195736Z","steps":["trace[936056346] 'agreement among raft nodes before linearized reading' (duration: 105.340171ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:09.195783Z","caller":"traceutil/trace.go:171","msg":"trace[618549599] transaction","detail":"{read_only:false; response_revision:24548; number_of_response:1; }","duration":"223.832324ms","start":"2026-05-09T17:42:08.971922Z","end":"2026-05-09T17:42:09.195754Z","steps":["trace[618549599] 'process raft request' (duration: 93.926898ms)","trace[618549599] 'compare' (duration: 129.302562ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:09.395784Z","caller":"traceutil/trace.go:171","msg":"trace[1675180793] transaction","detail":"{read_only:false; response_revision:24549; number_of_response:1; }","duration":"142.033084ms","start":"2026-05-09T17:42:09.253709Z","end":"2026-05-09T17:42:09.395742Z","steps":["trace[1675180793] 'process raft request' (duration: 113.127105ms)","trace[1675180793] 'compare' (duration: 28.739125ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:09.395956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.9493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-09T17:42:09.396Z","caller":"traceutil/trace.go:171","msg":"trace[857268088] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:24552; }","duration":"110.051483ms","start":"2026-05-09T17:42:09.285939Z","end":"2026-05-09T17:42:09.395991Z","steps":["trace[857268088] 'agreement among raft nodes before linearized reading' (duration: 109.900669ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:09.396034Z","caller":"traceutil/trace.go:171","msg":"trace[1548477373] transaction","detail":"{read_only:false; response_revision:24551; number_of_response:1; }","duration":"141.452091ms","start":"2026-05-09T17:42:09.254559Z","end":"2026-05-09T17:42:09.396011Z","steps":["trace[1548477373] 'process raft request' (duration: 141.171495ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:09.396062Z","caller":"traceutil/trace.go:171","msg":"trace[846390944] transaction","detail":"{read_only:false; response_revision:24550; number_of_response:1; }","duration":"141.580074ms","start":"2026-05-09T17:42:09.254458Z","end":"2026-05-09T17:42:09.396038Z","steps":["trace[846390944] 'process raft request' (duration: 141.235116ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:09.3958Z","caller":"traceutil/trace.go:171","msg":"trace[473589606] linearizableReadLoop","detail":"{readStateIndex:25345; appliedIndex:25342; }","duration":"109.785846ms","start":"2026-05-09T17:42:09.285996Z","end":"2026-05-09T17:42:09.395782Z","steps":["trace[473589606] 'read index received' (duration: 80.930518ms)","trace[473589606] 'applied index is now lower than readState.Index' (duration: 28.854628ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:09.636479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.22857ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-05-09T17:42:09.636678Z","caller":"traceutil/trace.go:171","msg":"trace[2023321710] linearizableReadLoop","detail":"{readStateIndex:25347; appliedIndex:25346; }","duration":"215.433925ms","start":"2026-05-09T17:42:09.421219Z","end":"2026-05-09T17:42:09.636653Z","steps":["trace[2023321710] 'read index received' (duration: 91.055941ms)","trace[2023321710] 'applied index is now lower than readState.Index' (duration: 124.375134ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:09.636864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.627969ms","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-05-09T17:42:09.636901Z","caller":"traceutil/trace.go:171","msg":"trace[1630689303] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24552; }","duration":"215.718521ms","start":"2026-05-09T17:42:09.42117Z","end":"2026-05-09T17:42:09.636889Z","steps":["trace[1630689303] 'agreement among raft nodes before linearized reading' (duration: 215.537887ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:09.685022Z","caller":"traceutil/trace.go:171","msg":"trace[1102623568] transaction","detail":"{read_only:false; response_revision:24553; number_of_response:1; }","duration":"207.546237ms","start":"2026-05-09T17:42:09.477455Z","end":"2026-05-09T17:42:09.685002Z","steps":["trace[1102623568] 'process raft request' (duration: 207.343112ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:09.685273Z","caller":"traceutil/trace.go:171","msg":"trace[2088848988] transaction","detail":"{read_only:false; response_revision:24554; number_of_response:1; }","duration":"207.710131ms","start":"2026-05-09T17:42:09.477547Z","end":"2026-05-09T17:42:09.685257Z","steps":["trace[2088848988] 'process raft request' (duration: 207.410603ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:09.685328Z","caller":"traceutil/trace.go:171","msg":"trace[1786478558] transaction","detail":"{read_only:false; response_revision:24555; number_of_response:1; }","duration":"204.696589ms","start":"2026-05-09T17:42:09.480614Z","end":"2026-05-09T17:42:09.685311Z","steps":["trace[1786478558] 'process raft request' (duration: 204.546705ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:09.685506Z","caller":"traceutil/trace.go:171","msg":"trace[1244895256] transaction","detail":"{read_only:false; response_revision:24556; number_of_response:1; }","duration":"204.691499ms","start":"2026-05-09T17:42:09.480784Z","end":"2026-05-09T17:42:09.685476Z","steps":["trace[1244895256] 'process raft request' (duration: 204.473464ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:09.844212Z","caller":"traceutil/trace.go:171","msg":"trace[2075149441] transaction","detail":"{read_only:false; response_revision:24557; number_of_response:1; }","duration":"206.299526ms","start":"2026-05-09T17:42:09.637879Z","end":"2026-05-09T17:42:09.844178Z","steps":["trace[2075149441] 'process raft request' (duration: 137.304802ms)","trace[2075149441] 'compare' (duration: 68.854111ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:18.884257Z","caller":"traceutil/trace.go:171","msg":"trace[1823210704] transaction","detail":"{read_only:false; response_revision:24617; number_of_response:1; }","duration":"180.279496ms","start":"2026-05-09T17:42:18.70395Z","end":"2026-05-09T17:42:18.88423Z","steps":["trace[1823210704] 'process raft request' (duration: 180.0178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-09T17:42:19.118469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.748603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-09T17:42:19.118571Z","caller":"traceutil/trace.go:171","msg":"trace[1080008681] linearizableReadLoop","detail":"{readStateIndex:25414; appliedIndex:25413; }","duration":"132.691672ms","start":"2026-05-09T17:42:18.985865Z","end":"2026-05-09T17:42:19.118557Z","steps":["trace[1080008681] 'read index received' (duration: 54.921µs)","trace[1080008681] 'applied index is now lower than readState.Index' (duration: 132.635631ms)"],"step_count":2} {"level":"info","ts":"2026-05-09T17:42:19.118813Z","caller":"traceutil/trace.go:171","msg":"trace[806187541] transaction","detail":"{read_only:false; response_revision:24618; number_of_response:1; }","duration":"159.996403ms","start":"2026-05-09T17:42:18.958807Z","end":"2026-05-09T17:42:19.118803Z","steps":["trace[806187541] 'process raft request' (duration: 26.83143ms)","trace[806187541] 'compare' (duration: 132.535118ms)"],"step_count":2} {"level":"warn","ts":"2026-05-09T17:42:19.118904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.0377ms","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-05-09T17:42:19.118959Z","caller":"traceutil/trace.go:171","msg":"trace[1809167740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24618; }","duration":"133.132182ms","start":"2026-05-09T17:42:18.985813Z","end":"2026-05-09T17:42:19.118945Z","steps":["trace[1809167740] 'agreement among raft nodes before linearized reading' (duration: 132.994569ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:42:42.753926Z","caller":"traceutil/trace.go:171","msg":"trace[1894242208] transaction","detail":"{read_only:false; response_revision:24944; number_of_response:1; }","duration":"105.853813ms","start":"2026-05-09T17:42:42.648047Z","end":"2026-05-09T17:42:42.753901Z","steps":["trace[1894242208] 'process raft request' (duration: 105.711539ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:43:35.878375Z","caller":"traceutil/trace.go:171","msg":"trace[1176365086] transaction","detail":"{read_only:false; response_revision:25412; number_of_response:1; }","duration":"107.912552ms","start":"2026-05-09T17:43:35.770435Z","end":"2026-05-09T17:43:35.878348Z","steps":["trace[1176365086] 'process raft request' (duration: 102.826481ms)"],"step_count":1} {"level":"info","ts":"2026-05-09T17:44:47.248379Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006918.wal"} {"level":"info","ts":"2026-05-09T17:45:46.304492Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23712} {"level":"info","ts":"2026-05-09T17:45:46.430516Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23712,"took":"121.466625ms","hash":1214687796,"current-db-size-bytes":59514880,"current-db-size":"60 MB","current-db-size-in-use-bytes":38211584,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-05-09T17:45:46.430588Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1214687796,"revision":23712,"compact-revision":20493} {"level":"info","ts":"2026-05-09T17:50:46.311318Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26770} {"level":"info","ts":"2026-05-09T17:50:46.456852Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26770,"took":"141.902334ms","hash":3839481506,"current-db-size-bytes":59514880,"current-db-size":"60 MB","current-db-size-in-use-bytes":31637504,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T17:50:46.456954Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3839481506,"revision":26770,"compact-revision":23712} {"level":"info","ts":"2026-05-09T17:50:49.861596Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d0ba3c4de8c6ef7a","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T17:50:49.86946Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-09T17:50:49.869582Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-09T17:55:46.317089Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29058} {"level":"info","ts":"2026-05-09T17:55:46.44817Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29058,"took":"127.372349ms","hash":2938041344,"current-db-size-bytes":59514880,"current-db-size":"60 MB","current-db-size-in-use-bytes":29945856,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-09T17:55:46.448237Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2938041344,"revision":29058,"compact-revision":26770} {"level":"info","ts":"2026-05-09T18:00:46.322233Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31281} {"level":"info","ts":"2026-05-09T18:00:46.435873Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31281,"took":"108.718653ms","hash":503898376,"current-db-size-bytes":59514880,"current-db-size":"60 MB","current-db-size-in-use-bytes":32702464,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-09T18:00:46.435938Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":503898376,"revision":31281,"compact-revision":29058} {"level":"info","ts":"2026-05-09T18:05:46.32766Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33672} {"level":"info","ts":"2026-05-09T18:05:46.470937Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33672,"took":"136.490082ms","hash":4117090751,"current-db-size-bytes":60588032,"current-db-size":"61 MB","current-db-size-in-use-bytes":32075776,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T18:05:46.471008Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4117090751,"revision":33672,"compact-revision":31281} {"level":"info","ts":"2026-05-09T18:10:46.337696Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35944} {"level":"info","ts":"2026-05-09T18:10:46.48659Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35944,"took":"144.341764ms","hash":2924910032,"current-db-size-bytes":61902848,"current-db-size":"62 MB","current-db-size-in-use-bytes":31506432,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-09T18:10:46.486671Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2924910032,"revision":35944,"compact-revision":33672} {"level":"info","ts":"2026-05-09T18:12:05.624508Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d0ba3c4de8c6ef7a","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-09T18:12:05.629714Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-09T18:12:05.629881Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}