{"level":"warn","ts":"2026-05-18T00:41:18.371978Z","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-18T00:41:18.372113Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-18T00:41:18.372213Z","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-18T00:41:18.372228Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-05-18T00:41:18.372287Z","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-18T00:41:18.373035Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-05-18T00:41:18.373132Z","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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-18T00:41:18.377029Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.558454ms"} {"level":"info","ts":"2026-05-18T00:41:18.384926Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-05-18T00:41:18.38508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-05-18T00:41:18.385148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-05-18T00:41:18.385185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-18T00:41:18.385212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-05-18T00:41:18.385283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-05-18T00:41:18.390116Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-18T00:41:18.393127Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-18T00:41:18.394665Z","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-18T00:41:18.397292Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-18T00:41:18.397426Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-18T00:41:18.397587Z","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-18T00:41:18.397677Z","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-18T00:41:18.397688Z","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-18T00:41:18.398354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-05-18T00:41:18.398522Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-05-18T00:41:18.400296Z","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-18T00:41:18.400451Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-05-18T00:41:18.400542Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-05-18T00:41:18.400635Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-18T00:41:18.400706Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-18T00:41:18.685714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-05-18T00:41:18.685821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-18T00:41:18.685859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-05-18T00:41:18.685884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-05-18T00:41:18.685895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-05-18T00:41:18.68591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-05-18T00:41:18.685924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-05-18T00:41:18.68798Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-05-18T00:41:18.688306Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T00:41:18.688577Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-18T00:41:18.688805Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-18T00:41:18.68883Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-18T00:41:18.688919Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-18T00:41:18.689145Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T00:41:18.689268Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T00:41:18.689304Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-18T00:41:18.690387Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-18T00:41:18.691808Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-05-18T00:41:55.021147Z","caller":"traceutil/trace.go:171","msg":"trace[2112701115] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"122.106454ms","start":"2026-05-18T00:41:54.899014Z","end":"2026-05-18T00:41:55.02112Z","steps":["trace[2112701115] 'process raft request' (duration: 121.972661ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:55.413591Z","caller":"traceutil/trace.go:171","msg":"trace[739697781] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"203.422644ms","start":"2026-05-18T00:41:55.210139Z","end":"2026-05-18T00:41:55.413562Z","steps":["trace[739697781] 'process raft request' (duration: 202.78287ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:41:55.844078Z","caller":"traceutil/trace.go:171","msg":"trace[634429092] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"363.59305ms","start":"2026-05-18T00:41:55.480461Z","end":"2026-05-18T00:41:55.844054Z","steps":["trace[634429092] 'process raft request' (duration: 363.456486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:55.844532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:55.480434Z","time spent":"363.713292ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-18T00:41:55.877357Z","caller":"traceutil/trace.go:171","msg":"trace[655545150] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:600; }","duration":"282.240367ms","start":"2026-05-18T00:41:55.595081Z","end":"2026-05-18T00:41:55.877321Z","steps":["trace[655545150] 'read index received' (duration: 248.808793ms)","trace[655545150] 'applied index is now lower than readState.Index' (duration: 33.430614ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:41:55.877434Z","caller":"traceutil/trace.go:171","msg":"trace[1562373225] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"385.252865ms","start":"2026-05-18T00:41:55.492158Z","end":"2026-05-18T00:41:55.877411Z","steps":["trace[1562373225] 'process raft request' (duration: 385.038569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:55.877729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.455743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8s4fj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-18T00:41:55.877842Z","caller":"traceutil/trace.go:171","msg":"trace[2064732892] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8s4fj; range_end:; response_count:1; response_revision:582; }","duration":"282.790231ms","start":"2026-05-18T00:41:55.595032Z","end":"2026-05-18T00:41:55.877823Z","steps":["trace[2064732892] 'agreement among raft nodes before linearized reading' (duration: 282.441453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:41:55.877838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:41:55.492134Z","time spent":"385.351357ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:42:15.843013Z","caller":"traceutil/trace.go:171","msg":"trace[526462583] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"122.634586ms","start":"2026-05-18T00:42:15.720355Z","end":"2026-05-18T00:42:15.84299Z","steps":["trace[526462583] 'process raft request' (duration: 122.480233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:27.957563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.605308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T00:43:27.957682Z","caller":"traceutil/trace.go:171","msg":"trace[1486934929] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1807; }","duration":"257.745541ms","start":"2026-05-18T00:43:27.699916Z","end":"2026-05-18T00:43:27.957662Z","steps":["trace[1486934929] 'count revisions from in-memory index tree' (duration: 257.481675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:27.957704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.448012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2c51b213-f18e-4839-9754-149d08be2fc2\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-18T00:43:27.957803Z","caller":"traceutil/trace.go:171","msg":"trace[208726627] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2c51b213-f18e-4839-9754-149d08be2fc2; range_end:; response_count:1; response_revision:1807; }","duration":"192.612196ms","start":"2026-05-18T00:43:27.765173Z","end":"2026-05-18T00:43:27.957785Z","steps":["trace[208726627] 'range keys from in-memory index tree' (duration: 192.243327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:43:27.958056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.683808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-18T00:43:27.958185Z","caller":"traceutil/trace.go:171","msg":"trace[399148699] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1807; }","duration":"216.830481ms","start":"2026-05-18T00:43:27.741298Z","end":"2026-05-18T00:43:27.958128Z","steps":["trace[399148699] 'range keys from in-memory index tree' (duration: 216.471194ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:44:17.131792Z","caller":"traceutil/trace.go:171","msg":"trace[1691362816] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"168.770389ms","start":"2026-05-18T00:44:16.962998Z","end":"2026-05-18T00:44:17.131768Z","steps":["trace[1691362816] 'process raft request' (duration: 168.640116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:44:17.342443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.672419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:44:17.342716Z","caller":"traceutil/trace.go:171","msg":"trace[1776259371] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"377.085954ms","start":"2026-05-18T00:44:16.965559Z","end":"2026-05-18T00:44:17.342645Z","steps":["trace[1776259371] 'process raft request' (duration: 250.004297ms)","trace[1776259371] 'compare' (duration: 126.554656ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:44:17.342926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:44:16.965537Z","time spent":"377.285529ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:44:17.630355Z","caller":"traceutil/trace.go:171","msg":"trace[2054789943] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"194.720344ms","start":"2026-05-18T00:44:17.435605Z","end":"2026-05-18T00:44:17.630326Z","steps":["trace[2054789943] 'process raft request' (duration: 126.670128ms)","trace[2054789943] 'compare' (duration: 67.657408ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:45:32.454877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.670989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-18T00:45:32.454963Z","caller":"traceutil/trace.go:171","msg":"trace[1117388657] linearizableReadLoop","detail":"{readStateIndex:2828; appliedIndex:2827; }","duration":"280.241613ms","start":"2026-05-18T00:45:32.17471Z","end":"2026-05-18T00:45:32.454952Z","steps":["trace[1117388657] 'read index received' (duration: 137.38699ms)","trace[1117388657] 'applied index is now lower than readState.Index' (duration: 142.853733ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:45:32.455025Z","caller":"traceutil/trace.go:171","msg":"trace[482073436] transaction","detail":"{read_only:false; response_revision:2726; number_of_response:1; }","duration":"350.972054ms","start":"2026-05-18T00:45:32.104047Z","end":"2026-05-18T00:45:32.455019Z","steps":["trace[482073436] 'process raft request' (duration: 208.083721ms)","trace[482073436] 'compare' (duration: 142.288261ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:45:32.45506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:45:32.104023Z","time spent":"351.018405ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:45:32.455077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.673977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:45:32.455135Z","caller":"traceutil/trace.go:171","msg":"trace[1164635206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2726; }","duration":"139.76514ms","start":"2026-05-18T00:45:32.315354Z","end":"2026-05-18T00:45:32.455119Z","steps":["trace[1164635206] 'agreement among raft nodes before linearized reading' (duration: 139.677878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:45:32.455337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.60159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-18T00:45:32.455445Z","caller":"traceutil/trace.go:171","msg":"trace[1252828458] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2726; }","duration":"280.771774ms","start":"2026-05-18T00:45:32.174648Z","end":"2026-05-18T00:45:32.45542Z","steps":["trace[1252828458] 'agreement among raft nodes before linearized reading' (duration: 280.465037ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:49:23.988795Z","caller":"traceutil/trace.go:171","msg":"trace[958013162] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"122.338578ms","start":"2026-05-18T00:49:23.866421Z","end":"2026-05-18T00:49:23.98876Z","steps":["trace[958013162] 'process raft request' (duration: 122.023101ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:49:40.720387Z","caller":"traceutil/trace.go:171","msg":"trace[413948220] transaction","detail":"{read_only:false; response_revision:5254; number_of_response:1; }","duration":"117.191007ms","start":"2026-05-18T00:49:40.60317Z","end":"2026-05-18T00:49:40.720361Z","steps":["trace[413948220] 'process raft request' (duration: 117.020963ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:49:40.720562Z","caller":"traceutil/trace.go:171","msg":"trace[1518284242] transaction","detail":"{read_only:false; response_revision:5255; number_of_response:1; }","duration":"116.655036ms","start":"2026-05-18T00:49:40.60388Z","end":"2026-05-18T00:49:40.720535Z","steps":["trace[1518284242] 'process raft request' (duration: 116.444421ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:49:41.235981Z","caller":"traceutil/trace.go:171","msg":"trace[232723271] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"176.125008ms","start":"2026-05-18T00:49:41.059834Z","end":"2026-05-18T00:49:41.235959Z","steps":["trace[232723271] 'process raft request' (duration: 175.913684ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:49:41.235947Z","caller":"traceutil/trace.go:171","msg":"trace[307108542] linearizableReadLoop","detail":"{readStateIndex:5447; appliedIndex:5446; }","duration":"168.189308ms","start":"2026-05-18T00:49:41.067725Z","end":"2026-05-18T00:49:41.235915Z","steps":["trace[307108542] 'read index received' (duration: 167.955833ms)","trace[307108542] 'applied index is now lower than readState.Index' (duration: 229.375µs)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:49:41.237278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.523256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2213919"} {"level":"info","ts":"2026-05-18T00:49:41.238181Z","caller":"traceutil/trace.go:171","msg":"trace[20598768] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5257; }","duration":"170.441256ms","start":"2026-05-18T00:49:41.067681Z","end":"2026-05-18T00:49:41.238122Z","steps":["trace[20598768] 'agreement among raft nodes before linearized reading' (duration: 168.432603ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:49:51.529317Z","caller":"traceutil/trace.go:171","msg":"trace[630452488] transaction","detail":"{read_only:false; response_revision:5407; number_of_response:1; }","duration":"107.361096ms","start":"2026-05-18T00:49:51.42192Z","end":"2026-05-18T00:49:51.529281Z","steps":["trace[630452488] 'process raft request' (duration: 107.09015ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:51:18.747867Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2980} {"level":"info","ts":"2026-05-18T00:51:18.832609Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2980,"took":"81.907519ms","hash":1164881810,"current-db-size-bytes":58556416,"current-db-size":"59 MB","current-db-size-in-use-bytes":34127872,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-18T00:51:18.832689Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1164881810,"revision":2980,"compact-revision":-1} {"level":"info","ts":"2026-05-18T00:55:46.325958Z","caller":"traceutil/trace.go:171","msg":"trace[1757495780] transaction","detail":"{read_only:false; response_revision:8086; number_of_response:1; }","duration":"322.060348ms","start":"2026-05-18T00:55:46.003857Z","end":"2026-05-18T00:55:46.325917Z","steps":["trace[1757495780] 'process raft request' (duration: 234.30191ms)","trace[1757495780] 'compare' (duration: 87.481412ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:55:46.326117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.02928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T00:55:46.326145Z","caller":"traceutil/trace.go:171","msg":"trace[937412520] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:8087; }","duration":"273.09545ms","start":"2026-05-18T00:55:46.053043Z","end":"2026-05-18T00:55:46.326139Z","steps":["trace[937412520] 'agreement among raft nodes before linearized reading' (duration: 273.000118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:55:46.326146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:55:46.00383Z","time spent":"322.231471ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":507,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T00:55:46.326168Z","caller":"traceutil/trace.go:171","msg":"trace[1695515591] transaction","detail":"{read_only:false; response_revision:8087; number_of_response:1; }","duration":"319.035752ms","start":"2026-05-18T00:55:46.007103Z","end":"2026-05-18T00:55:46.326138Z","steps":["trace[1695515591] 'process raft request' (duration: 318.778037ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:55:46.326023Z","caller":"traceutil/trace.go:171","msg":"trace[182719778] linearizableReadLoop","detail":"{readStateIndex:8364; appliedIndex:8362; }","duration":"272.923497ms","start":"2026-05-18T00:55:46.053087Z","end":"2026-05-18T00:55:46.326011Z","steps":["trace[182719778] 'read index received' (duration: 185.085998ms)","trace[182719778] 'applied index is now lower than readState.Index' (duration: 87.836919ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:55:46.326308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:55:46.007087Z","time spent":"319.147286ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T00:55:46.326457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.780798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-18T00:55:46.326501Z","caller":"traceutil/trace.go:171","msg":"trace[836369113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8087; }","duration":"249.885941ms","start":"2026-05-18T00:55:46.076602Z","end":"2026-05-18T00:55:46.326488Z","steps":["trace[836369113] 'agreement among raft nodes before linearized reading' (duration: 249.715616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:55:46.326652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.476042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T00:55:46.326683Z","caller":"traceutil/trace.go:171","msg":"trace[1887515539] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:8087; }","duration":"164.548154ms","start":"2026-05-18T00:55:46.162126Z","end":"2026-05-18T00:55:46.326675Z","steps":["trace[1887515539] 'agreement among raft nodes before linearized reading' (duration: 164.461112ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:55:48.334111Z","caller":"traceutil/trace.go:171","msg":"trace[434438440] transaction","detail":"{read_only:false; response_revision:8097; number_of_response:1; }","duration":"110.140221ms","start":"2026-05-18T00:55:48.223948Z","end":"2026-05-18T00:55:48.334088Z","steps":["trace[434438440] 'process raft request' (duration: 66.108819ms)","trace[434438440] 'compare' (duration: 43.90842ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:56:14.656893Z","caller":"traceutil/trace.go:171","msg":"trace[1658654762] linearizableReadLoop","detail":"{readStateIndex:8580; appliedIndex:8578; }","duration":"212.960618ms","start":"2026-05-18T00:56:14.443911Z","end":"2026-05-18T00:56:14.656871Z","steps":["trace[1658654762] 'read index received' (duration: 17.219965ms)","trace[1658654762] 'applied index is now lower than readState.Index' (duration: 195.739623ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:56:14.657021Z","caller":"traceutil/trace.go:171","msg":"trace[293825265] transaction","detail":"{read_only:false; response_revision:8294; number_of_response:1; }","duration":"215.111544ms","start":"2026-05-18T00:56:14.441897Z","end":"2026-05-18T00:56:14.657008Z","steps":["trace[293825265] 'process raft request' (duration: 200.189368ms)","trace[293825265] 'compare' (duration: 14.506766ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:56:14.65717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.241754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:56:14.657209Z","caller":"traceutil/trace.go:171","msg":"trace[1323391553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8294; }","duration":"213.314055ms","start":"2026-05-18T00:56:14.443885Z","end":"2026-05-18T00:56:14.657199Z","steps":["trace[1323391553] 'agreement among raft nodes before linearized reading' (duration: 213.240063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:56:14.657537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.829633ms","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-18T00:56:14.65758Z","caller":"traceutil/trace.go:171","msg":"trace[409420832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8294; }","duration":"141.917805ms","start":"2026-05-18T00:56:14.515653Z","end":"2026-05-18T00:56:14.657571Z","steps":["trace[409420832] 'agreement among raft nodes before linearized reading' (duration: 141.69625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:56:17.249772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.899034ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-18T00:56:18.753904Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6123} {"level":"info","ts":"2026-05-18T00:56:18.849627Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6123,"took":"94.266455ms","hash":113106554,"current-db-size-bytes":58556416,"current-db-size":"59 MB","current-db-size-in-use-bytes":23060480,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-18T00:56:18.849733Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":113106554,"revision":6123,"compact-revision":2980} {"level":"info","ts":"2026-05-18T00:56:26.698654Z","caller":"traceutil/trace.go:171","msg":"trace[1091161038] linearizableReadLoop","detail":"{readStateIndex:8693; appliedIndex:8691; }","duration":"106.405592ms","start":"2026-05-18T00:56:26.592226Z","end":"2026-05-18T00:56:26.698632Z","steps":["trace[1091161038] 'read index received' (duration: 26.549972ms)","trace[1091161038] 'applied index is now lower than readState.Index' (duration: 79.8543ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T00:56:26.698676Z","caller":"traceutil/trace.go:171","msg":"trace[1494164906] transaction","detail":"{read_only:false; response_revision:8400; number_of_response:1; }","duration":"127.639171ms","start":"2026-05-18T00:56:26.570995Z","end":"2026-05-18T00:56:26.698634Z","steps":["trace[1494164906] 'process raft request' (duration: 47.678509ms)","trace[1494164906] 'compare' (duration: 79.780618ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:56:26.698797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.549136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T00:56:26.698838Z","caller":"traceutil/trace.go:171","msg":"trace[971239979] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8401; }","duration":"106.635407ms","start":"2026-05-18T00:56:26.592192Z","end":"2026-05-18T00:56:26.698828Z","steps":["trace[971239979] 'agreement among raft nodes before linearized reading' (duration: 106.545685ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:56:26.698829Z","caller":"traceutil/trace.go:171","msg":"trace[2028398775] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8401; }","duration":"127.308875ms","start":"2026-05-18T00:56:26.571505Z","end":"2026-05-18T00:56:26.698813Z","steps":["trace[2028398775] 'process raft request' (duration: 127.06001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:56:27.342294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.165618ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-18T00:56:27.3424Z","caller":"traceutil/trace.go:171","msg":"trace[1038647170] linearizableReadLoop","detail":"{readStateIndex:8697; appliedIndex:8696; }","duration":"300.174194ms","start":"2026-05-18T00:56:27.042208Z","end":"2026-05-18T00:56:27.342382Z","steps":["trace[1038647170] 'read index received' (duration: 64.865063ms)","trace[1038647170] 'applied index is now lower than readState.Index' (duration: 235.307731ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T00:56:27.342496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.276886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-18T00:56:27.342524Z","caller":"traceutil/trace.go:171","msg":"trace[1396911204] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:8404; }","duration":"300.360628ms","start":"2026-05-18T00:56:27.042155Z","end":"2026-05-18T00:56:27.342515Z","steps":["trace[1396911204] 'agreement among raft nodes before linearized reading' (duration: 300.274106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T00:56:27.342559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T00:56:27.042134Z","time spent":"300.416289ms","remote":"127.0.0.1:39194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":11,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-05-18T00:56:28.38367Z","caller":"traceutil/trace.go:171","msg":"trace[934451913] transaction","detail":"{read_only:false; response_revision:8416; number_of_response:1; }","duration":"127.903188ms","start":"2026-05-18T00:56:28.255746Z","end":"2026-05-18T00:56:28.383649Z","steps":["trace[934451913] 'process raft request' (duration: 127.420297ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:57:26.145441Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002468.wal"} {"level":"info","ts":"2026-05-18T00:57:27.402656Z","caller":"traceutil/trace.go:171","msg":"trace[1753603611] transaction","detail":"{read_only:false; response_revision:9042; number_of_response:1; }","duration":"161.136041ms","start":"2026-05-18T00:57:27.241503Z","end":"2026-05-18T00:57:27.402639Z","steps":["trace[1753603611] 'process raft request' (duration: 161.026598ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T00:58:54.308514Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-18T00:58:54.313809Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-18T00:58:54.313977Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-18T00:59:54.727024Z","caller":"traceutil/trace.go:171","msg":"trace[1909044965] transaction","detail":"{read_only:false; response_revision:10170; number_of_response:1; }","duration":"105.82677ms","start":"2026-05-18T00:59:54.621163Z","end":"2026-05-18T00:59:54.72699Z","steps":["trace[1909044965] 'process raft request' (duration: 86.730469ms)","trace[1909044965] 'compare' (duration: 18.832515ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:01:18.760522Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8316} {"level":"info","ts":"2026-05-18T01:01:18.836323Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8316,"took":"73.690805ms","hash":1283166141,"current-db-size-bytes":58556416,"current-db-size":"59 MB","current-db-size-in-use-bytes":26546176,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-18T01:01:18.836408Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1283166141,"revision":8316,"compact-revision":6123} {"level":"info","ts":"2026-05-18T01:06:18.766098Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10958} {"level":"info","ts":"2026-05-18T01:06:18.845805Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10958,"took":"77.281936ms","hash":3984791414,"current-db-size-bytes":58556416,"current-db-size":"59 MB","current-db-size-in-use-bytes":28368896,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-18T01:06:18.845862Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3984791414,"revision":10958,"compact-revision":8316} {"level":"info","ts":"2026-05-18T01:07:23.069115Z","caller":"traceutil/trace.go:171","msg":"trace[154339532] transaction","detail":"{read_only:false; response_revision:13469; number_of_response:1; }","duration":"114.963472ms","start":"2026-05-18T01:07:22.954134Z","end":"2026-05-18T01:07:23.069098Z","steps":["trace[154339532] 'process raft request' (duration: 92.466774ms)","trace[154339532] 'compare' (duration: 22.13285ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:07:39.269089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.478465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-blklq\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-18T01:07:39.269192Z","caller":"traceutil/trace.go:171","msg":"trace[1019677064] range","detail":"{range_begin:/registry/pods/openstack/keepalived-blklq; range_end:; response_count:1; response_revision:13563; }","duration":"284.660218ms","start":"2026-05-18T01:07:38.984512Z","end":"2026-05-18T01:07:39.269172Z","steps":["trace[1019677064] 'range keys from in-memory index tree' (duration: 284.172677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:07:39.269162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.332758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-18T01:07:39.269223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.381203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-rp664\" ","response":"range_response_count:1 size:12364"} {"level":"info","ts":"2026-05-18T01:07:39.269382Z","caller":"traceutil/trace.go:171","msg":"trace[2139987335] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-rp664; range_end:; response_count:1; response_revision:13563; }","duration":"356.590287ms","start":"2026-05-18T01:07:38.912755Z","end":"2026-05-18T01:07:39.269346Z","steps":["trace[2139987335] 'range keys from in-memory index tree' (duration: 356.153826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:07:39.269413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.338772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-05-18T01:07:39.269441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:07:38.91274Z","time spent":"356.687309ms","remote":"127.0.0.1:38916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":12386,"request content":"key:\"/registry/pods/openstack/openvswitch-rp664\" "} {"level":"info","ts":"2026-05-18T01:07:39.269469Z","caller":"traceutil/trace.go:171","msg":"trace[95548451] transaction","detail":"{read_only:false; response_revision:13564; number_of_response:1; }","duration":"262.809144ms","start":"2026-05-18T01:07:39.006651Z","end":"2026-05-18T01:07:39.26946Z","steps":["trace[95548451] 'process raft request' (duration: 122.36483ms)","trace[95548451] 'compare' (duration: 139.886261ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:07:39.269274Z","caller":"traceutil/trace.go:171","msg":"trace[189920302] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:13563; }","duration":"329.497972ms","start":"2026-05-18T01:07:38.939753Z","end":"2026-05-18T01:07:39.269251Z","steps":["trace[189920302] 'count revisions from in-memory index tree' (duration: 329.270586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:07:39.269593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:07:38.939741Z","time spent":"329.803848ms","remote":"127.0.0.1:38960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":39,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-05-18T01:07:48.258044Z","caller":"traceutil/trace.go:171","msg":"trace[1976827361] transaction","detail":"{read_only:false; response_revision:13727; number_of_response:1; }","duration":"101.950756ms","start":"2026-05-18T01:07:48.156047Z","end":"2026-05-18T01:07:48.257998Z","steps":["trace[1976827361] 'process raft request' (duration: 101.566198ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:07:48.396512Z","caller":"traceutil/trace.go:171","msg":"trace[1034005882] transaction","detail":"{read_only:false; response_revision:13728; number_of_response:1; }","duration":"133.941096ms","start":"2026-05-18T01:07:48.262509Z","end":"2026-05-18T01:07:48.39645Z","steps":["trace[1034005882] 'process raft request' (duration: 132.77651ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:07:48.398398Z","caller":"traceutil/trace.go:171","msg":"trace[448512453] transaction","detail":"{read_only:false; response_revision:13729; number_of_response:1; }","duration":"129.138814ms","start":"2026-05-18T01:07:48.269241Z","end":"2026-05-18T01:07:48.39838Z","steps":["trace[448512453] 'process raft request' (duration: 128.994091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:09:18.082287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.987248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-05-18T01:09:18.082302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.368625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-05-18T01:09:18.082302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.027069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-05-18T01:09:18.082347Z","caller":"traceutil/trace.go:171","msg":"trace[1968930990] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14767; }","duration":"111.0995ms","start":"2026-05-18T01:09:17.971229Z","end":"2026-05-18T01:09:18.082329Z","steps":["trace[1968930990] 'range keys from in-memory index tree' (duration: 110.801424ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:09:18.082368Z","caller":"traceutil/trace.go:171","msg":"trace[2112602085] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14767; }","duration":"110.477208ms","start":"2026-05-18T01:09:17.971875Z","end":"2026-05-18T01:09:18.082352Z","steps":["trace[2112602085] 'range keys from in-memory index tree' (duration: 110.206122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:09:18.082345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.026509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T01:09:18.082393Z","caller":"traceutil/trace.go:171","msg":"trace[2005758910] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:14767; }","duration":"111.150092ms","start":"2026-05-18T01:09:17.97121Z","end":"2026-05-18T01:09:18.08236Z","steps":["trace[2005758910] 'range keys from in-memory index tree' (duration: 110.798244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:09:18.08263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.587813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-18T01:09:18.082662Z","caller":"traceutil/trace.go:171","msg":"trace[156656740] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14767; }","duration":"105.632355ms","start":"2026-05-18T01:09:17.977019Z","end":"2026-05-18T01:09:18.082651Z","steps":["trace[156656740] 'range keys from in-memory index tree' (duration: 105.548143ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:09:18.084185Z","caller":"traceutil/trace.go:171","msg":"trace[843718801] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:14767; }","duration":"111.144302ms","start":"2026-05-18T01:09:17.971258Z","end":"2026-05-18T01:09:18.082402Z","steps":["trace[843718801] 'range keys from in-memory index tree' (duration: 110.911207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:09:18.081986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.299464ms","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-18T01:09:18.087599Z","caller":"traceutil/trace.go:171","msg":"trace[62872072] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14767; }","duration":"115.954614ms","start":"2026-05-18T01:09:17.971613Z","end":"2026-05-18T01:09:18.087567Z","steps":["trace[62872072] 'range keys from in-memory index tree' (duration: 110.244773ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:09:19.128552Z","caller":"traceutil/trace.go:171","msg":"trace[101005962] transaction","detail":"{read_only:false; response_revision:14769; number_of_response:1; }","duration":"119.023819ms","start":"2026-05-18T01:09:19.009504Z","end":"2026-05-18T01:09:19.128528Z","steps":["trace[101005962] 'process raft request' (duration: 118.220052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:09:19.440362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.288879ms","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-18T01:09:19.440443Z","caller":"traceutil/trace.go:171","msg":"trace[607679854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14770; }","duration":"147.407522ms","start":"2026-05-18T01:09:19.293018Z","end":"2026-05-18T01:09:19.440425Z","steps":["trace[607679854] 'range keys from in-memory index tree' (duration: 147.125685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:09:19.440786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.779966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-857w5\" ","response":"range_response_count:1 size:9796"} {"level":"info","ts":"2026-05-18T01:09:19.440865Z","caller":"traceutil/trace.go:171","msg":"trace[1694640454] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-857w5; range_end:; response_count:1; response_revision:14770; }","duration":"192.9304ms","start":"2026-05-18T01:09:19.247917Z","end":"2026-05-18T01:09:19.440847Z","steps":["trace[1694640454] 'range keys from in-memory index tree' (duration: 192.457189ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:11:18.772495Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13053} {"level":"info","ts":"2026-05-18T01:11:18.846353Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13053,"took":"71.073377ms","hash":3122043614,"current-db-size-bytes":58556416,"current-db-size":"59 MB","current-db-size-in-use-bytes":32014336,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-18T01:11:18.846414Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3122043614,"revision":13053,"compact-revision":10958} {"level":"info","ts":"2026-05-18T01:15:05.736866Z","caller":"traceutil/trace.go:171","msg":"trace[806448256] transaction","detail":"{read_only:false; response_revision:17390; number_of_response:1; }","duration":"105.116947ms","start":"2026-05-18T01:15:05.631728Z","end":"2026-05-18T01:15:05.736845Z","steps":["trace[806448256] 'process raft request' (duration: 104.733259ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:15:12.008159Z","caller":"traceutil/trace.go:171","msg":"trace[1829873054] transaction","detail":"{read_only:false; response_revision:17431; number_of_response:1; }","duration":"172.359262ms","start":"2026-05-18T01:15:11.835765Z","end":"2026-05-18T01:15:12.008124Z","steps":["trace[1829873054] 'process raft request' (duration: 172.013695ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:15:12.72909Z","caller":"traceutil/trace.go:171","msg":"trace[1369724315] linearizableReadLoop","detail":"{readStateIndex:18011; appliedIndex:18010; }","duration":"202.86368ms","start":"2026-05-18T01:15:12.526196Z","end":"2026-05-18T01:15:12.72906Z","steps":["trace[1369724315] 'read index received' (duration: 202.596394ms)","trace[1369724315] 'applied index is now lower than readState.Index' (duration: 266.306µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:15:12.729184Z","caller":"traceutil/trace.go:171","msg":"trace[1696935105] transaction","detail":"{read_only:false; response_revision:17433; number_of_response:1; }","duration":"256.882358ms","start":"2026-05-18T01:15:12.472257Z","end":"2026-05-18T01:15:12.72914Z","steps":["trace[1696935105] 'process raft request' (duration: 256.613293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:15:12.729463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.239648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"info","ts":"2026-05-18T01:15:12.729546Z","caller":"traceutil/trace.go:171","msg":"trace[2027711898] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:17433; }","duration":"203.343861ms","start":"2026-05-18T01:15:12.526155Z","end":"2026-05-18T01:15:12.729499Z","steps":["trace[2027711898] 'agreement among raft nodes before linearized reading' (duration: 203.041984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:15:12.892579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.939484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-dngjs\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-05-18T01:15:12.892695Z","caller":"traceutil/trace.go:171","msg":"trace[905837039] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-dngjs; range_end:; response_count:1; response_revision:17433; }","duration":"127.082957ms","start":"2026-05-18T01:15:12.765595Z","end":"2026-05-18T01:15:12.892678Z","steps":["trace[905837039] 'range keys from in-memory index tree' (duration: 126.817571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:15:27.54623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.305528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:15:27.546353Z","caller":"traceutil/trace.go:171","msg":"trace[830083042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17744; }","duration":"101.449841ms","start":"2026-05-18T01:15:27.444876Z","end":"2026-05-18T01:15:27.546325Z","steps":["trace[830083042] 'range keys from in-memory index tree' (duration: 101.214006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:15:27.546345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.113751ms","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-18T01:15:27.546518Z","caller":"traceutil/trace.go:171","msg":"trace[39363453] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:17744; }","duration":"109.315115ms","start":"2026-05-18T01:15:27.437179Z","end":"2026-05-18T01:15:27.546494Z","steps":["trace[39363453] 'range keys from in-memory index tree' (duration: 109.08511ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:16:18.778778Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15597} {"level":"info","ts":"2026-05-18T01:16:18.875883Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15597,"took":"93.25196ms","hash":519926120,"current-db-size-bytes":58556416,"current-db-size":"59 MB","current-db-size-in-use-bytes":33837056,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-18T01:16:18.875956Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":519926120,"revision":15597,"compact-revision":13053} {"level":"info","ts":"2026-05-18T01:19:03.915827Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-18T01:19:03.920795Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-18T01:19:03.920881Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-05-18T01:20:08.089494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.992841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-18T01:20:08.089565Z","caller":"traceutil/trace.go:171","msg":"trace[1532959706] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:19743; }","duration":"110.096194ms","start":"2026-05-18T01:20:07.979455Z","end":"2026-05-18T01:20:08.089551Z","steps":["trace[1532959706] 'range keys from in-memory index tree' (duration: 109.864769ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:20:08.318402Z","caller":"traceutil/trace.go:171","msg":"trace[1821350669] transaction","detail":"{read_only:false; response_revision:19744; number_of_response:1; }","duration":"281.158769ms","start":"2026-05-18T01:20:08.037217Z","end":"2026-05-18T01:20:08.318376Z","steps":["trace[1821350669] 'process raft request' (duration: 281.013966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:20:08.567575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.14057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:20:08.567813Z","caller":"traceutil/trace.go:171","msg":"trace[835375504] linearizableReadLoop","detail":"{readStateIndex:20401; appliedIndex:20399; }","duration":"493.375596ms","start":"2026-05-18T01:20:08.074419Z","end":"2026-05-18T01:20:08.567795Z","steps":["trace[835375504] 'read index received' (duration: 243.891704ms)","trace[835375504] 'applied index is now lower than readState.Index' (duration: 249.482572ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:20:08.567933Z","caller":"traceutil/trace.go:171","msg":"trace[1293220737] transaction","detail":"{read_only:false; response_revision:19745; number_of_response:1; }","duration":"527.829843ms","start":"2026-05-18T01:20:08.040066Z","end":"2026-05-18T01:20:08.567896Z","steps":["trace[1293220737] 'process raft request' (duration: 392.10896ms)","trace[1293220737] 'compare' (duration: 134.983357ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:20:08.568143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:20:08.040043Z","time spent":"527.998187ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:20:08.568215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.772436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1991"} {"level":"info","ts":"2026-05-18T01:20:08.568109Z","caller":"traceutil/trace.go:171","msg":"trace[1379975761] transaction","detail":"{read_only:false; response_revision:19746; number_of_response:1; }","duration":"474.351226ms","start":"2026-05-18T01:20:08.093684Z","end":"2026-05-18T01:20:08.568036Z","steps":["trace[1379975761] 'process raft request' (duration: 474.038138ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:20:08.568332Z","caller":"traceutil/trace.go:171","msg":"trace[1817982700] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:19746; }","duration":"493.927459ms","start":"2026-05-18T01:20:08.074375Z","end":"2026-05-18T01:20:08.568303Z","steps":["trace[1817982700] 'agreement among raft nodes before linearized reading' (duration: 493.730004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:20:08.568396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:20:08.074361Z","time spent":"494.02037ms","remote":"127.0.0.1:38926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2013,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-05-18T01:20:08.56843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:20:08.093667Z","time spent":"474.655482ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:20:08.568677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.343882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T01:20:08.568754Z","caller":"traceutil/trace.go:171","msg":"trace[1690999035] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:19746; }","duration":"388.502494ms","start":"2026-05-18T01:20:08.180232Z","end":"2026-05-18T01:20:08.568734Z","steps":["trace[1690999035] 'agreement among raft nodes before linearized reading' (duration: 388.345221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:20:08.568804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:20:08.180186Z","time spent":"388.604747ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true "} {"level":"info","ts":"2026-05-18T01:20:08.897287Z","caller":"traceutil/trace.go:171","msg":"trace[1493759016] linearizableReadLoop","detail":"{readStateIndex:20404; appliedIndex:20402; }","duration":"321.696116ms","start":"2026-05-18T01:20:08.575564Z","end":"2026-05-18T01:20:08.89726Z","steps":["trace[1493759016] 'read index received' (duration: 90.009139ms)","trace[1493759016] 'applied index is now lower than readState.Index' (duration: 231.685597ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:20:08.89747Z","caller":"traceutil/trace.go:171","msg":"trace[2035488785] transaction","detail":"{read_only:false; response_revision:19748; number_of_response:1; }","duration":"322.628075ms","start":"2026-05-18T01:20:08.574822Z","end":"2026-05-18T01:20:08.89745Z","steps":["trace[2035488785] 'process raft request' (duration: 228.566421ms)","trace[2035488785] 'compare' (duration: 93.551133ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:20:08.89761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:20:08.574805Z","time spent":"322.712277ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:20:08.897857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.276648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-05-18T01:20:08.897936Z","caller":"traceutil/trace.go:171","msg":"trace[63472947] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:19748; }","duration":"322.39169ms","start":"2026-05-18T01:20:08.575526Z","end":"2026-05-18T01:20:08.897918Z","steps":["trace[63472947] 'agreement among raft nodes before linearized reading' (duration: 322.139645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:20:08.897977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:20:08.575513Z","time spent":"322.455182ms","remote":"127.0.0.1:39182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11859,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-05-18T01:20:08.897984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.203704ms","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-18T01:20:08.898099Z","caller":"traceutil/trace.go:171","msg":"trace[84765352] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19748; }","duration":"147.369868ms","start":"2026-05-18T01:20:08.750705Z","end":"2026-05-18T01:20:08.898075Z","steps":["trace[84765352] 'agreement among raft nodes before linearized reading' (duration: 147.113592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:20:08.898369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.350943ms","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-18T01:20:08.898594Z","caller":"traceutil/trace.go:171","msg":"trace[83402977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19748; }","duration":"153.62814ms","start":"2026-05-18T01:20:08.744952Z","end":"2026-05-18T01:20:08.89858Z","steps":["trace[83402977] 'agreement among raft nodes before linearized reading' (duration: 152.582328ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:20:09.122991Z","caller":"traceutil/trace.go:171","msg":"trace[1371536884] transaction","detail":"{read_only:false; response_revision:19749; number_of_response:1; }","duration":"220.025081ms","start":"2026-05-18T01:20:08.902935Z","end":"2026-05-18T01:20:09.12296Z","steps":["trace[1371536884] 'process raft request' (duration: 219.833907ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:20:09.122993Z","caller":"traceutil/trace.go:171","msg":"trace[1290608344] transaction","detail":"{read_only:false; response_revision:19750; number_of_response:1; }","duration":"220.00563ms","start":"2026-05-18T01:20:08.902935Z","end":"2026-05-18T01:20:09.122941Z","steps":["trace[1290608344] 'process raft request' (duration: 219.95937ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:21:18.784734Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18154} {"level":"info","ts":"2026-05-18T01:21:18.90259Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18154,"took":"114.081577ms","hash":945085416,"current-db-size-bytes":58556416,"current-db-size":"59 MB","current-db-size-in-use-bytes":30089216,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-18T01:21:18.902651Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":945085416,"revision":18154,"compact-revision":15597} {"level":"info","ts":"2026-05-18T01:24:01.774828Z","caller":"traceutil/trace.go:171","msg":"trace[302975667] transaction","detail":"{read_only:false; response_revision:22142; number_of_response:1; }","duration":"183.783677ms","start":"2026-05-18T01:24:01.591016Z","end":"2026-05-18T01:24:01.7748Z","steps":["trace[302975667] 'process raft request' (duration: 183.569102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:24:02.046459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.61797ms","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-18T01:24:02.046541Z","caller":"traceutil/trace.go:171","msg":"trace[1056371480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22142; }","duration":"190.750543ms","start":"2026-05-18T01:24:01.855772Z","end":"2026-05-18T01:24:02.046523Z","steps":["trace[1056371480] 'range keys from in-memory index tree' (duration: 190.412696ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:24:02.049137Z","caller":"traceutil/trace.go:171","msg":"trace[484749222] transaction","detail":"{read_only:false; response_revision:22143; number_of_response:1; }","duration":"145.138552ms","start":"2026-05-18T01:24:01.903974Z","end":"2026-05-18T01:24:02.049113Z","steps":["trace[484749222] 'process raft request' (duration: 144.982318ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:26:18.791248Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20385} {"level":"info","ts":"2026-05-18T01:26:18.89483Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20385,"took":"99.811335ms","hash":1132368477,"current-db-size-bytes":58556416,"current-db-size":"59 MB","current-db-size-in-use-bytes":42438656,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-18T01:26:18.894902Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1132368477,"revision":20385,"compact-revision":18154} {"level":"info","ts":"2026-05-18T01:27:24.081757Z","caller":"traceutil/trace.go:171","msg":"trace[6368381] transaction","detail":"{read_only:false; response_revision:24215; number_of_response:1; }","duration":"503.877188ms","start":"2026-05-18T01:27:23.577851Z","end":"2026-05-18T01:27:24.081728Z","steps":["trace[6368381] 'process raft request' (duration: 503.639913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:24.081977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:23.57783Z","time spent":"504.041601ms","remote":"127.0.0.1:38916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8447,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:27:24.454715Z","caller":"traceutil/trace.go:171","msg":"trace[1105067841] transaction","detail":"{read_only:false; response_revision:24218; number_of_response:1; }","duration":"520.549839ms","start":"2026-05-18T01:27:23.934139Z","end":"2026-05-18T01:27:24.454689Z","steps":["trace[1105067841] 'process raft request' (duration: 520.469987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:24.45489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:23.934132Z","time spent":"520.664501ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:27:24.454907Z","caller":"traceutil/trace.go:171","msg":"trace[1677680160] linearizableReadLoop","detail":"{readStateIndex:24998; appliedIndex:24996; }","duration":"870.458189ms","start":"2026-05-18T01:27:23.584427Z","end":"2026-05-18T01:27:24.454886Z","steps":["trace[1677680160] 'read index received' (duration: 497.212376ms)","trace[1677680160] 'applied index is now lower than readState.Index' (duration: 373.244953ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:24.455047Z","caller":"traceutil/trace.go:171","msg":"trace[1844223691] transaction","detail":"{read_only:false; response_revision:24219; number_of_response:1; }","duration":"421.773316ms","start":"2026-05-18T01:27:24.033266Z","end":"2026-05-18T01:27:24.455039Z","steps":["trace[1844223691] 'process raft request' (duration: 421.382428ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:24.455162Z","caller":"traceutil/trace.go:171","msg":"trace[1865759277] transaction","detail":"{read_only:false; response_revision:24216; number_of_response:1; }","duration":"876.921117ms","start":"2026-05-18T01:27:23.578226Z","end":"2026-05-18T01:27:24.455147Z","steps":["trace[1865759277] 'process raft request' (duration: 876.236572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:24.45517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:24.033244Z","time spent":"421.857797ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:27:24.455235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:23.578205Z","time spent":"876.993028ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:27:24.455221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.770287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-18T01:27:24.455302Z","caller":"traceutil/trace.go:171","msg":"trace[1860965003] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24219; }","duration":"870.892619ms","start":"2026-05-18T01:27:23.584389Z","end":"2026-05-18T01:27:24.455282Z","steps":["trace[1860965003] 'agreement among raft nodes before linearized reading' (duration: 870.671364ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:24.45533Z","caller":"traceutil/trace.go:171","msg":"trace[582265670] transaction","detail":"{read_only:false; response_revision:24217; number_of_response:1; }","duration":"525.103276ms","start":"2026-05-18T01:27:23.930217Z","end":"2026-05-18T01:27:24.45532Z","steps":["trace[582265670] 'process raft request' (duration: 524.36625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:24.455354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:23.584377Z","time spent":"870.96801ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-18T01:27:24.455386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:23.930196Z","time spent":"525.161268ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:27:24.754956Z","caller":"traceutil/trace.go:171","msg":"trace[1490360001] transaction","detail":"{read_only:false; response_revision:24224; number_of_response:1; }","duration":"567.725686ms","start":"2026-05-18T01:27:24.187208Z","end":"2026-05-18T01:27:24.754934Z","steps":["trace[1490360001] 'process raft request' (duration: 567.676494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:24.755102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:24.187184Z","time spent":"567.844718ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:27:24.755426Z","caller":"traceutil/trace.go:171","msg":"trace[740961131] transaction","detail":"{read_only:false; response_revision:24220; number_of_response:1; }","duration":"666.026289ms","start":"2026-05-18T01:27:24.089388Z","end":"2026-05-18T01:27:24.755415Z","steps":["trace[740961131] 'process raft request' (duration: 665.205341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:24.75549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:24.089359Z","time spent":"666.10061ms","remote":"127.0.0.1:39030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:27:24.755719Z","caller":"traceutil/trace.go:171","msg":"trace[42847891] transaction","detail":"{read_only:false; response_revision:24221; number_of_response:1; }","duration":"666.08509ms","start":"2026-05-18T01:27:24.089624Z","end":"2026-05-18T01:27:24.755709Z","steps":["trace[42847891] 'process raft request' (duration: 665.112799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:24.755778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:24.089547Z","time spent":"666.203583ms","remote":"127.0.0.1:38896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":652,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:27:24.755978Z","caller":"traceutil/trace.go:171","msg":"trace[493955868] transaction","detail":"{read_only:false; response_revision:24222; number_of_response:1; }","duration":"666.269953ms","start":"2026-05-18T01:27:24.089698Z","end":"2026-05-18T01:27:24.755968Z","steps":["trace[493955868] 'process raft request' (duration: 665.088908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:24.756034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:24.089677Z","time spent":"666.330335ms","remote":"127.0.0.1:38996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1167,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:27:24.756246Z","caller":"traceutil/trace.go:171","msg":"trace[264644635] transaction","detail":"{read_only:false; response_revision:24223; number_of_response:1; }","duration":"663.72523ms","start":"2026-05-18T01:27:24.092511Z","end":"2026-05-18T01:27:24.756236Z","steps":["trace[264644635] 'process raft request' (duration: 662.32338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:24.756304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:24.092499Z","time spent":"663.77737ms","remote":"127.0.0.1:39202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7536,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:27:24.757112Z","caller":"traceutil/trace.go:171","msg":"trace[2117028609] linearizableReadLoop","detail":"{readStateIndex:25006; appliedIndex:25006; }","duration":"302.139903ms","start":"2026-05-18T01:27:24.45496Z","end":"2026-05-18T01:27:24.7571Z","steps":["trace[2117028609] 'read index received' (duration: 302.133673ms)","trace[2117028609] 'applied index is now lower than readState.Index' (duration: 5.38µs)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:27:24.757248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.390486ms","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-18T01:27:24.757279Z","caller":"traceutil/trace.go:171","msg":"trace[1041155210] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24224; }","duration":"978.455358ms","start":"2026-05-18T01:27:23.778813Z","end":"2026-05-18T01:27:24.757268Z","steps":["trace[1041155210] 'agreement among raft nodes before linearized reading' (duration: 978.341126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:24.75731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:23.778796Z","time spent":"978.507879ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-18T01:27:24.757441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.47285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:27:24.757465Z","caller":"traceutil/trace.go:171","msg":"trace[1801667159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24224; }","duration":"312.496671ms","start":"2026-05-18T01:27:24.444961Z","end":"2026-05-18T01:27:24.757457Z","steps":["trace[1801667159] 'agreement among raft nodes before linearized reading' (duration: 312.45637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:24.757489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:24.444872Z","time spent":"312.611613ms","remote":"127.0.0.1:38706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-18T01:27:24.757666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.815199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-18T01:27:24.757712Z","caller":"traceutil/trace.go:171","msg":"trace[175542517] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24224; }","duration":"505.907372ms","start":"2026-05-18T01:27:24.251795Z","end":"2026-05-18T01:27:24.757702Z","steps":["trace[175542517] 'agreement among raft nodes before linearized reading' (duration: 505.808149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:24.757737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:24.251778Z","time spent":"505.953323ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-18T01:27:24.757813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.267336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:27:24.757842Z","caller":"traceutil/trace.go:171","msg":"trace[641606958] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:24224; }","duration":"568.338208ms","start":"2026-05-18T01:27:24.189495Z","end":"2026-05-18T01:27:24.757833Z","steps":["trace[641606958] 'agreement among raft nodes before linearized reading' (duration: 568.294817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:24.757867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:24.18947Z","time spent":"568.390779ms","remote":"127.0.0.1:52428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true "} {"level":"info","ts":"2026-05-18T01:27:24.78082Z","caller":"traceutil/trace.go:171","msg":"trace[791374343] transaction","detail":"{read_only:false; response_revision:24225; number_of_response:1; }","duration":"321.812298ms","start":"2026-05-18T01:27:24.458994Z","end":"2026-05-18T01:27:24.780807Z","steps":["trace[791374343] 'process raft request' (duration: 321.675905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:24.780906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:24.458977Z","time spent":"321.884209ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:27:24.781044Z","caller":"traceutil/trace.go:171","msg":"trace[632192703] transaction","detail":"{read_only:false; response_revision:24226; number_of_response:1; }","duration":"225.877185ms","start":"2026-05-18T01:27:24.555161Z","end":"2026-05-18T01:27:24.781038Z","steps":["trace[632192703] 'process raft request' (duration: 225.622259ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:24.935001Z","caller":"traceutil/trace.go:171","msg":"trace[698338245] transaction","detail":"{read_only:false; response_revision:24227; number_of_response:1; }","duration":"173.266415ms","start":"2026-05-18T01:27:24.761715Z","end":"2026-05-18T01:27:24.934982Z","steps":["trace[698338245] 'process raft request' (duration: 173.091621ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:24.935215Z","caller":"traceutil/trace.go:171","msg":"trace[984974137] transaction","detail":"{read_only:false; response_revision:24228; number_of_response:1; }","duration":"172.898827ms","start":"2026-05-18T01:27:24.762297Z","end":"2026-05-18T01:27:24.935196Z","steps":["trace[984974137] 'process raft request' (duration: 172.633442ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:24.935321Z","caller":"traceutil/trace.go:171","msg":"trace[1592544854] transaction","detail":"{read_only:false; response_revision:24229; number_of_response:1; }","duration":"167.995173ms","start":"2026-05-18T01:27:24.767294Z","end":"2026-05-18T01:27:24.935289Z","steps":["trace[1592544854] 'process raft request' (duration: 167.84545ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:30.731162Z","caller":"traceutil/trace.go:171","msg":"trace[1820471368] transaction","detail":"{read_only:false; response_revision:24266; number_of_response:1; }","duration":"257.537292ms","start":"2026-05-18T01:27:30.473606Z","end":"2026-05-18T01:27:30.731143Z","steps":["trace[1820471368] 'process raft request' (duration: 257.327688ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:30.731543Z","caller":"traceutil/trace.go:171","msg":"trace[698776075] transaction","detail":"{read_only:false; response_revision:24267; number_of_response:1; }","duration":"257.567773ms","start":"2026-05-18T01:27:30.473967Z","end":"2026-05-18T01:27:30.731534Z","steps":["trace[698776075] 'process raft request' (duration: 257.136184ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:30.731601Z","caller":"traceutil/trace.go:171","msg":"trace[1900492797] transaction","detail":"{read_only:false; response_revision:24268; number_of_response:1; }","duration":"256.94195ms","start":"2026-05-18T01:27:30.474628Z","end":"2026-05-18T01:27:30.73157Z","steps":["trace[1900492797] 'process raft request' (duration: 256.748256ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:30.875644Z","caller":"traceutil/trace.go:171","msg":"trace[2068852496] transaction","detail":"{read_only:false; response_revision:24269; number_of_response:1; }","duration":"102.200055ms","start":"2026-05-18T01:27:30.773419Z","end":"2026-05-18T01:27:30.875619Z","steps":["trace[2068852496] 'process raft request' (duration: 69.596438ms)","trace[2068852496] 'compare' (duration: 32.347442ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:30.999995Z","caller":"traceutil/trace.go:171","msg":"trace[201674062] transaction","detail":"{read_only:false; response_revision:24270; number_of_response:1; }","duration":"118.621712ms","start":"2026-05-18T01:27:30.881336Z","end":"2026-05-18T01:27:30.999958Z","steps":["trace[201674062] 'process raft request' (duration: 102.212776ms)","trace[201674062] 'compare' (duration: 16.165661ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:31.347504Z","caller":"traceutil/trace.go:171","msg":"trace[1876163060] linearizableReadLoop","detail":"{readStateIndex:25056; appliedIndex:25055; }","duration":"258.199905ms","start":"2026-05-18T01:27:31.089281Z","end":"2026-05-18T01:27:31.347481Z","steps":["trace[1876163060] 'read index received' (duration: 257.96136ms)","trace[1876163060] 'applied index is now lower than readState.Index' (duration: 237.365µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:31.347623Z","caller":"traceutil/trace.go:171","msg":"trace[1250961039] transaction","detail":"{read_only:false; response_revision:24273; number_of_response:1; }","duration":"309.914338ms","start":"2026-05-18T01:27:31.037675Z","end":"2026-05-18T01:27:31.347589Z","steps":["trace[1250961039] 'process raft request' (duration: 309.648232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:31.347783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:31.037654Z","time spent":"310.04109ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:27:31.347793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.906733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-18T01:27:31.347786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.486612ms","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-18T01:27:31.34784Z","caller":"traceutil/trace.go:171","msg":"trace[870011292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24273; }","duration":"253.047076ms","start":"2026-05-18T01:27:31.09478Z","end":"2026-05-18T01:27:31.347827Z","steps":["trace[870011292] 'agreement among raft nodes before linearized reading' (duration: 252.848382ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:31.347863Z","caller":"traceutil/trace.go:171","msg":"trace[61538307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24273; }","duration":"258.618085ms","start":"2026-05-18T01:27:31.089227Z","end":"2026-05-18T01:27:31.347845Z","steps":["trace[61538307] 'agreement among raft nodes before linearized reading' (duration: 258.37211ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:31.493944Z","caller":"traceutil/trace.go:171","msg":"trace[319572395] transaction","detail":"{read_only:false; response_revision:24275; number_of_response:1; }","duration":"141.187958ms","start":"2026-05-18T01:27:31.352733Z","end":"2026-05-18T01:27:31.493921Z","steps":["trace[319572395] 'process raft request' (duration: 138.348498ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:31.494117Z","caller":"traceutil/trace.go:171","msg":"trace[2046883702] transaction","detail":"{read_only:false; response_revision:24276; number_of_response:1; }","duration":"140.707937ms","start":"2026-05-18T01:27:31.353393Z","end":"2026-05-18T01:27:31.494101Z","steps":["trace[2046883702] 'process raft request' (duration: 140.447592ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:31.494118Z","caller":"traceutil/trace.go:171","msg":"trace[101279472] transaction","detail":"{read_only:false; response_revision:24277; number_of_response:1; }","duration":"140.559064ms","start":"2026-05-18T01:27:31.35354Z","end":"2026-05-18T01:27:31.494099Z","steps":["trace[101279472] 'process raft request' (duration: 140.465283ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:32.3855Z","caller":"traceutil/trace.go:171","msg":"trace[544430094] transaction","detail":"{read_only:false; response_revision:24278; number_of_response:1; }","duration":"236.762124ms","start":"2026-05-18T01:27:32.148708Z","end":"2026-05-18T01:27:32.38547Z","steps":["trace[544430094] 'process raft request' (duration: 146.544041ms)","trace[544430094] 'compare' (duration: 89.844805ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:32.774984Z","caller":"traceutil/trace.go:171","msg":"trace[168123073] linearizableReadLoop","detail":"{readStateIndex:25063; appliedIndex:25062; }","duration":"133.315621ms","start":"2026-05-18T01:27:32.641645Z","end":"2026-05-18T01:27:32.77496Z","steps":["trace[168123073] 'read index received' (duration: 133.075506ms)","trace[168123073] 'applied index is now lower than readState.Index' (duration: 238.865µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:32.775322Z","caller":"traceutil/trace.go:171","msg":"trace[1878878007] transaction","detail":"{read_only:false; response_revision:24279; number_of_response:1; }","duration":"259.533825ms","start":"2026-05-18T01:27:32.515774Z","end":"2026-05-18T01:27:32.775307Z","steps":["trace[1878878007] 'process raft request' (duration: 258.331049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:32.775482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.804052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T01:27:32.775566Z","caller":"traceutil/trace.go:171","msg":"trace[326938671] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:24279; }","duration":"133.940876ms","start":"2026-05-18T01:27:32.641603Z","end":"2026-05-18T01:27:32.775544Z","steps":["trace[326938671] 'agreement among raft nodes before linearized reading' (duration: 133.733891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:33.116142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.902123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:27:33.116262Z","caller":"traceutil/trace.go:171","msg":"trace[1183419371] transaction","detail":"{read_only:false; response_revision:24283; number_of_response:1; }","duration":"235.67078ms","start":"2026-05-18T01:27:32.88057Z","end":"2026-05-18T01:27:33.116241Z","steps":["trace[1183419371] 'process raft request' (duration: 75.559783ms)","trace[1183419371] 'compare' (duration: 159.544506ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:33.33749Z","caller":"traceutil/trace.go:171","msg":"trace[2002933662] linearizableReadLoop","detail":"{readStateIndex:25068; appliedIndex:25067; }","duration":"331.899331ms","start":"2026-05-18T01:27:33.005569Z","end":"2026-05-18T01:27:33.337469Z","steps":["trace[2002933662] 'read index received' (duration: 297.958875ms)","trace[2002933662] 'applied index is now lower than readState.Index' (duration: 33.939556ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:33.337632Z","caller":"traceutil/trace.go:171","msg":"trace[1185933558] transaction","detail":"{read_only:false; response_revision:24284; number_of_response:1; }","duration":"400.274163ms","start":"2026-05-18T01:27:32.937296Z","end":"2026-05-18T01:27:33.33757Z","steps":["trace[1185933558] 'process raft request' (duration: 366.240215ms)","trace[1185933558] 'compare' (duration: 33.789203ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:27:33.337713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:32.937278Z","time spent":"400.374305ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:27:33.337738Z","caller":"traceutil/trace.go:171","msg":"trace[842971875] transaction","detail":"{read_only:false; response_revision:24285; number_of_response:1; }","duration":"331.433241ms","start":"2026-05-18T01:27:33.00628Z","end":"2026-05-18T01:27:33.337714Z","steps":["trace[842971875] 'process raft request' (duration: 331.156665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:33.337922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:33.006269Z","time spent":"331.533173ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:27:33.338241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.620745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-18T01:27:33.338347Z","caller":"traceutil/trace.go:171","msg":"trace[17729916] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24285; }","duration":"332.82135ms","start":"2026-05-18T01:27:33.005504Z","end":"2026-05-18T01:27:33.338325Z","steps":["trace[17729916] 'agreement among raft nodes before linearized reading' (duration: 332.142915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:33.33842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:33.005479Z","time spent":"332.902523ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-18T01:27:33.349627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.107483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T01:27:33.349693Z","caller":"traceutil/trace.go:171","msg":"trace[1682952467] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:24286; }","duration":"221.193755ms","start":"2026-05-18T01:27:33.128483Z","end":"2026-05-18T01:27:33.349677Z","steps":["trace[1682952467] 'agreement among raft nodes before linearized reading' (duration: 221.089703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:33.349627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.039574ms","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-18T01:27:33.349924Z","caller":"traceutil/trace.go:171","msg":"trace[857741047] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24286; }","duration":"323.353801ms","start":"2026-05-18T01:27:33.026534Z","end":"2026-05-18T01:27:33.349888Z","steps":["trace[857741047] 'agreement among raft nodes before linearized reading' (duration: 322.906781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:33.350007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:33.026522Z","time spent":"323.457033ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-18T01:27:33.536539Z","caller":"traceutil/trace.go:171","msg":"trace[1241449164] linearizableReadLoop","detail":"{readStateIndex:25073; appliedIndex:25071; }","duration":"179.509447ms","start":"2026-05-18T01:27:33.357007Z","end":"2026-05-18T01:27:33.536516Z","steps":["trace[1241449164] 'read index received' (duration: 14.159769ms)","trace[1241449164] 'applied index is now lower than readState.Index' (duration: 165.348238ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:33.536783Z","caller":"traceutil/trace.go:171","msg":"trace[8135263] transaction","detail":"{read_only:false; response_revision:24289; number_of_response:1; }","duration":"182.297386ms","start":"2026-05-18T01:27:33.354449Z","end":"2026-05-18T01:27:33.536747Z","steps":["trace[8135263] 'process raft request' (duration: 181.887827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:33.536957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.925425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:27:33.537023Z","caller":"traceutil/trace.go:171","msg":"trace[1010500266] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:24289; }","duration":"180.081658ms","start":"2026-05-18T01:27:33.356923Z","end":"2026-05-18T01:27:33.537005Z","steps":["trace[1010500266] 'agreement among raft nodes before linearized reading' (duration: 179.941165ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:33.552228Z","caller":"traceutil/trace.go:171","msg":"trace[848190361] transaction","detail":"{read_only:false; response_revision:24290; number_of_response:1; }","duration":"135.156621ms","start":"2026-05-18T01:27:33.416968Z","end":"2026-05-18T01:27:33.552125Z","steps":["trace[848190361] 'process raft request' (duration: 134.65624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:33.552763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.076571ms","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-18T01:27:33.552877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.503053ms","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-18T01:27:33.553061Z","caller":"traceutil/trace.go:171","msg":"trace[590332141] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24290; }","duration":"176.747539ms","start":"2026-05-18T01:27:33.376288Z","end":"2026-05-18T01:27:33.553036Z","steps":["trace[590332141] 'agreement among raft nodes before linearized reading' (duration: 175.744828ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:33.552942Z","caller":"traceutil/trace.go:171","msg":"trace[1885578917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24290; }","duration":"173.300775ms","start":"2026-05-18T01:27:33.379624Z","end":"2026-05-18T01:27:33.552924Z","steps":["trace[1885578917] 'agreement among raft nodes before linearized reading' (duration: 172.454278ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:44.936701Z","caller":"traceutil/trace.go:171","msg":"trace[921828881] transaction","detail":"{read_only:false; response_revision:24425; number_of_response:1; }","duration":"115.871775ms","start":"2026-05-18T01:27:44.820809Z","end":"2026-05-18T01:27:44.936681Z","steps":["trace[921828881] 'process raft request' (duration: 115.736552ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:45.261104Z","caller":"traceutil/trace.go:171","msg":"trace[991564688] transaction","detail":"{read_only:false; response_revision:24426; number_of_response:1; }","duration":"423.772699ms","start":"2026-05-18T01:27:44.837314Z","end":"2026-05-18T01:27:45.261087Z","steps":["trace[991564688] 'process raft request' (duration: 423.604956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:45.261218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:44.837287Z","time spent":"423.869541ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:27:45.261447Z","caller":"traceutil/trace.go:171","msg":"trace[1138769251] transaction","detail":"{read_only:false; response_revision:24428; number_of_response:1; }","duration":"417.884583ms","start":"2026-05-18T01:27:44.843555Z","end":"2026-05-18T01:27:45.26144Z","steps":["trace[1138769251] 'process raft request' (duration: 417.69688ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:45.26142Z","caller":"traceutil/trace.go:171","msg":"trace[59161735] transaction","detail":"{read_only:false; response_revision:24427; number_of_response:1; }","duration":"417.844283ms","start":"2026-05-18T01:27:44.84353Z","end":"2026-05-18T01:27:45.261374Z","steps":["trace[59161735] 'process raft request' (duration: 417.517806ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:45.261458Z","caller":"traceutil/trace.go:171","msg":"trace[327752851] linearizableReadLoop","detail":"{readStateIndex:25215; appliedIndex:25211; }","duration":"122.509835ms","start":"2026-05-18T01:27:45.138933Z","end":"2026-05-18T01:27:45.261443Z","steps":["trace[327752851] 'read index received' (duration: 122.049935ms)","trace[327752851] 'applied index is now lower than readState.Index' (duration: 459.129µs)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:27:45.261488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:44.843524Z","time spent":"417.946065ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:27:45.26154Z","caller":"traceutil/trace.go:171","msg":"trace[1685167619] transaction","detail":"{read_only:false; response_revision:24429; number_of_response:1; }","duration":"342.472654ms","start":"2026-05-18T01:27:44.919057Z","end":"2026-05-18T01:27:45.26153Z","steps":["trace[1685167619] 'process raft request' (duration: 342.319681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:45.261574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.640577ms","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-18T01:27:45.261591Z","caller":"traceutil/trace.go:171","msg":"trace[829948007] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24429; }","duration":"122.698228ms","start":"2026-05-18T01:27:45.138887Z","end":"2026-05-18T01:27:45.261585Z","steps":["trace[829948007] 'agreement among raft nodes before linearized reading' (duration: 122.645747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:45.261601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:44.919025Z","time spent":"342.534776ms","remote":"127.0.0.1:38986","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-18T01:27:45.261606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:44.843514Z","time spent":"417.986926ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:27:45.540068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.22525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:27:45.540167Z","caller":"traceutil/trace.go:171","msg":"trace[306969527] linearizableReadLoop","detail":"{readStateIndex:25216; appliedIndex:25215; }","duration":"278.651457ms","start":"2026-05-18T01:27:45.261498Z","end":"2026-05-18T01:27:45.54015Z","steps":["trace[306969527] 'read index received' (duration: 145.252004ms)","trace[306969527] 'applied index is now lower than readState.Index' (duration: 133.397973ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:45.540246Z","caller":"traceutil/trace.go:171","msg":"trace[225911438] transaction","detail":"{read_only:false; response_revision:24430; number_of_response:1; }","duration":"379.715449ms","start":"2026-05-18T01:27:45.160487Z","end":"2026-05-18T01:27:45.540202Z","steps":["trace[225911438] 'process raft request' (duration: 246.245364ms)","trace[225911438] 'compare' (duration: 133.057977ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:27:45.540289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.889861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:27:45.540338Z","caller":"traceutil/trace.go:171","msg":"trace[349684535] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:24430; }","duration":"286.979483ms","start":"2026-05-18T01:27:45.253348Z","end":"2026-05-18T01:27:45.540328Z","steps":["trace[349684535] 'agreement among raft nodes before linearized reading' (duration: 286.87109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:45.540452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:45.160379Z","time spent":"379.961734ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:27:45.561505Z","caller":"traceutil/trace.go:171","msg":"trace[1995122974] transaction","detail":"{read_only:false; response_revision:24431; number_of_response:1; }","duration":"180.113778ms","start":"2026-05-18T01:27:45.381375Z","end":"2026-05-18T01:27:45.561488Z","steps":["trace[1995122974] 'process raft request' (duration: 180.009956ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:45.674563Z","caller":"traceutil/trace.go:171","msg":"trace[497673058] linearizableReadLoop","detail":"{readStateIndex:25218; appliedIndex:25217; }","duration":"134.346494ms","start":"2026-05-18T01:27:45.540193Z","end":"2026-05-18T01:27:45.67454Z","steps":["trace[497673058] 'read index received' (duration: 21.870611ms)","trace[497673058] 'applied index is now lower than readState.Index' (duration: 112.474953ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:27:45.674931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.30785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-18T01:27:45.675002Z","caller":"traceutil/trace.go:171","msg":"trace[1517022113] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24432; }","duration":"269.392562ms","start":"2026-05-18T01:27:45.405597Z","end":"2026-05-18T01:27:45.674989Z","steps":["trace[1517022113] 'agreement among raft nodes before linearized reading' (duration: 269.041565ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:45.675523Z","caller":"traceutil/trace.go:171","msg":"trace[730585268] transaction","detail":"{read_only:false; response_revision:24432; number_of_response:1; }","duration":"264.103841ms","start":"2026-05-18T01:27:45.411377Z","end":"2026-05-18T01:27:45.675481Z","steps":["trace[730585268] 'process raft request' (duration: 262.961997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:45.675812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.117261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T01:27:45.675877Z","caller":"traceutil/trace.go:171","msg":"trace[1764085067] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:24432; }","duration":"209.218083ms","start":"2026-05-18T01:27:45.466649Z","end":"2026-05-18T01:27:45.675867Z","steps":["trace[1764085067] 'agreement among raft nodes before linearized reading' (duration: 209.07636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:45.676119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.044284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:27:45.676294Z","caller":"traceutil/trace.go:171","msg":"trace[2049487592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24432; }","duration":"232.112276ms","start":"2026-05-18T01:27:45.444034Z","end":"2026-05-18T01:27:45.676146Z","steps":["trace[2049487592] 'agreement among raft nodes before linearized reading' (duration: 232.020094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:45.676917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.918252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-05-18T01:27:45.677029Z","caller":"traceutil/trace.go:171","msg":"trace[1233176123] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24432; }","duration":"269.024114ms","start":"2026-05-18T01:27:45.407968Z","end":"2026-05-18T01:27:45.676992Z","steps":["trace[1233176123] 'agreement among raft nodes before linearized reading' (duration: 268.734298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:45.94442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.323001ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:27:45.944758Z","caller":"traceutil/trace.go:171","msg":"trace[1379771877] transaction","detail":"{read_only:false; response_revision:24433; number_of_response:1; }","duration":"262.058318ms","start":"2026-05-18T01:27:45.682668Z","end":"2026-05-18T01:27:45.944727Z","steps":["trace[1379771877] 'process raft request' (duration: 129.351198ms)","trace[1379771877] 'compare' (duration: 131.835681ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:45.944948Z","caller":"traceutil/trace.go:171","msg":"trace[1341949952] transaction","detail":"{read_only:false; response_revision:24434; number_of_response:1; }","duration":"261.924765ms","start":"2026-05-18T01:27:45.683001Z","end":"2026-05-18T01:27:45.944926Z","steps":["trace[1341949952] 'process raft request' (duration: 261.517827ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:45.945106Z","caller":"traceutil/trace.go:171","msg":"trace[41611677] transaction","detail":"{read_only:false; response_revision:24435; number_of_response:1; }","duration":"260.349411ms","start":"2026-05-18T01:27:45.684743Z","end":"2026-05-18T01:27:45.945092Z","steps":["trace[41611677] 'process raft request' (duration: 259.940833ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:45.945463Z","caller":"traceutil/trace.go:171","msg":"trace[1897008532] transaction","detail":"{read_only:false; response_revision:24437; number_of_response:1; }","duration":"257.775667ms","start":"2026-05-18T01:27:45.687674Z","end":"2026-05-18T01:27:45.945449Z","steps":["trace[1897008532] 'process raft request' (duration: 257.330108ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:45.945637Z","caller":"traceutil/trace.go:171","msg":"trace[1430902297] transaction","detail":"{read_only:false; response_revision:24438; number_of_response:1; }","duration":"257.681096ms","start":"2026-05-18T01:27:45.687943Z","end":"2026-05-18T01:27:45.945624Z","steps":["trace[1430902297] 'process raft request' (duration: 257.39256ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:45.945196Z","caller":"traceutil/trace.go:171","msg":"trace[830121145] transaction","detail":"{read_only:false; response_revision:24436; number_of_response:1; }","duration":"259.689947ms","start":"2026-05-18T01:27:45.685487Z","end":"2026-05-18T01:27:45.945177Z","steps":["trace[830121145] 'process raft request' (duration: 259.36556ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:45.945857Z","caller":"traceutil/trace.go:171","msg":"trace[512951453] linearizableReadLoop","detail":"{readStateIndex:25222; appliedIndex:25218; }","duration":"259.485003ms","start":"2026-05-18T01:27:45.68636Z","end":"2026-05-18T01:27:45.945845Z","steps":["trace[512951453] 'read index received' (duration: 125.63349ms)","trace[512951453] 'applied index is now lower than readState.Index' (duration: 133.850533ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:45.945982Z","caller":"traceutil/trace.go:171","msg":"trace[683163596] transaction","detail":"{read_only:false; response_revision:24439; number_of_response:1; }","duration":"257.063973ms","start":"2026-05-18T01:27:45.688908Z","end":"2026-05-18T01:27:45.945972Z","steps":["trace[683163596] 'process raft request' (duration: 256.649804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:45.946163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.78061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:27:45.946647Z","caller":"traceutil/trace.go:171","msg":"trace[50960577] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:24439; }","duration":"260.28404ms","start":"2026-05-18T01:27:45.686333Z","end":"2026-05-18T01:27:45.946617Z","steps":["trace[50960577] 'agreement among raft nodes before linearized reading' (duration: 259.759619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:45.946254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.922951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-05-18T01:27:45.947821Z","caller":"traceutil/trace.go:171","msg":"trace[1968472553] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24439; }","duration":"260.494735ms","start":"2026-05-18T01:27:45.687301Z","end":"2026-05-18T01:27:45.947796Z","steps":["trace[1968472553] 'agreement among raft nodes before linearized reading' (duration: 258.842989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:45.94631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.286947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ed0a28f2-0823-45db-b39a-29503667ffc2\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-05-18T01:27:45.948047Z","caller":"traceutil/trace.go:171","msg":"trace[681783554] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ed0a28f2-0823-45db-b39a-29503667ffc2; range_end:; response_count:1; response_revision:24439; }","duration":"177.054715ms","start":"2026-05-18T01:27:45.77098Z","end":"2026-05-18T01:27:45.948035Z","steps":["trace[681783554] 'agreement among raft nodes before linearized reading' (duration: 175.299717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:46.248503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.008533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-05-18T01:27:46.248602Z","caller":"traceutil/trace.go:171","msg":"trace[1430754065] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24439; }","duration":"204.145576ms","start":"2026-05-18T01:27:46.044436Z","end":"2026-05-18T01:27:46.248582Z","steps":["trace[1430754065] 'range keys from in-memory index tree' (duration: 203.805179ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:47.074814Z","caller":"traceutil/trace.go:171","msg":"trace[422171185] linearizableReadLoop","detail":"{readStateIndex:25226; appliedIndex:25225; }","duration":"115.746471ms","start":"2026-05-18T01:27:46.959049Z","end":"2026-05-18T01:27:47.074795Z","steps":["trace[422171185] 'read index received' (duration: 115.614928ms)","trace[422171185] 'applied index is now lower than readState.Index' (duration: 130.583µs)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:47.074921Z","caller":"traceutil/trace.go:171","msg":"trace[92196619] transaction","detail":"{read_only:false; response_revision:24440; number_of_response:1; }","duration":"132.421823ms","start":"2026-05-18T01:27:46.942491Z","end":"2026-05-18T01:27:47.074913Z","steps":["trace[92196619] 'process raft request' (duration: 132.160107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:47.075175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.114269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ed0a28f2-0823-45db-b39a-29503667ffc2\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-05-18T01:27:47.075225Z","caller":"traceutil/trace.go:171","msg":"trace[1150644158] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ed0a28f2-0823-45db-b39a-29503667ffc2; range_end:; response_count:1; response_revision:24440; }","duration":"116.201281ms","start":"2026-05-18T01:27:46.959011Z","end":"2026-05-18T01:27:47.075212Z","steps":["trace[1150644158] 'agreement among raft nodes before linearized reading' (duration: 116.090648ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:47.387547Z","caller":"traceutil/trace.go:171","msg":"trace[1325753401] transaction","detail":"{read_only:false; response_revision:24441; number_of_response:1; }","duration":"120.050512ms","start":"2026-05-18T01:27:47.267475Z","end":"2026-05-18T01:27:47.387526Z","steps":["trace[1325753401] 'process raft request' (duration: 119.914629ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:47.389546Z","caller":"traceutil/trace.go:171","msg":"trace[798251563] transaction","detail":"{read_only:false; response_revision:24442; number_of_response:1; }","duration":"121.950731ms","start":"2026-05-18T01:27:47.267579Z","end":"2026-05-18T01:27:47.38953Z","steps":["trace[798251563] 'process raft request' (duration: 121.669666ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:47.389623Z","caller":"traceutil/trace.go:171","msg":"trace[176305297] transaction","detail":"{read_only:false; response_revision:24443; number_of_response:1; }","duration":"119.679974ms","start":"2026-05-18T01:27:47.269903Z","end":"2026-05-18T01:27:47.389583Z","steps":["trace[176305297] 'process raft request' (duration: 119.435858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:47.549997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.502884ms","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-18T01:27:47.550096Z","caller":"traceutil/trace.go:171","msg":"trace[532755660] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24443; }","duration":"112.612847ms","start":"2026-05-18T01:27:47.437473Z","end":"2026-05-18T01:27:47.550086Z","steps":["trace[532755660] 'range keys from in-memory index tree' (duration: 112.492454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:47.550056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.634289ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-18T01:27:47.550168Z","caller":"traceutil/trace.go:171","msg":"trace[1069500274] linearizableReadLoop","detail":"{readStateIndex:25230; appliedIndex:25229; }","duration":"122.627187ms","start":"2026-05-18T01:27:47.427523Z","end":"2026-05-18T01:27:47.55015Z","steps":["trace[1069500274] 'read index received' (duration: 36.531µs)","trace[1069500274] 'applied index is now lower than readState.Index' (duration: 122.589126ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:27:47.55067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.019625ms","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-18T01:27:47.550722Z","caller":"traceutil/trace.go:171","msg":"trace[1943708311] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:24443; }","duration":"123.2173ms","start":"2026-05-18T01:27:47.427489Z","end":"2026-05-18T01:27:47.550706Z","steps":["trace[1943708311] 'agreement among raft nodes before linearized reading' (duration: 123.017685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:47.570449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.077309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:27:47.570488Z","caller":"traceutil/trace.go:171","msg":"trace[1742758150] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24444; }","duration":"126.14542ms","start":"2026-05-18T01:27:47.444334Z","end":"2026-05-18T01:27:47.570479Z","steps":["trace[1742758150] 'agreement among raft nodes before linearized reading' (duration: 126.071369ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:47.992406Z","caller":"traceutil/trace.go:171","msg":"trace[919099652] linearizableReadLoop","detail":"{readStateIndex:25233; appliedIndex:25232; }","duration":"243.303332ms","start":"2026-05-18T01:27:47.749073Z","end":"2026-05-18T01:27:47.992377Z","steps":["trace[919099652] 'read index received' (duration: 231.600734ms)","trace[919099652] 'applied index is now lower than readState.Index' (duration: 11.701288ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:47.992475Z","caller":"traceutil/trace.go:171","msg":"trace[1391751926] transaction","detail":"{read_only:false; response_revision:24446; number_of_response:1; }","duration":"307.273082ms","start":"2026-05-18T01:27:47.68516Z","end":"2026-05-18T01:27:47.992433Z","steps":["trace[1391751926] 'process raft request' (duration: 295.502503ms)","trace[1391751926] 'compare' (duration: 11.277888ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:27:47.992595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:27:47.685139Z","time spent":"307.384484ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:27:47.992596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.494356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T01:27:47.992665Z","caller":"traceutil/trace.go:171","msg":"trace[1748689137] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:24446; }","duration":"243.618998ms","start":"2026-05-18T01:27:47.74903Z","end":"2026-05-18T01:27:47.992649Z","steps":["trace[1748689137] 'agreement among raft nodes before linearized reading' (duration: 243.478556ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:48.212165Z","caller":"traceutil/trace.go:171","msg":"trace[2060042303] transaction","detail":"{read_only:false; response_revision:24447; number_of_response:1; }","duration":"213.965133ms","start":"2026-05-18T01:27:47.998174Z","end":"2026-05-18T01:27:48.21214Z","steps":["trace[2060042303] 'process raft request' (duration: 213.007583ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:48.215734Z","caller":"traceutil/trace.go:171","msg":"trace[1676495585] linearizableReadLoop","detail":"{readStateIndex:25239; appliedIndex:25233; }","duration":"134.945637ms","start":"2026-05-18T01:27:48.080772Z","end":"2026-05-18T01:27:48.215718Z","steps":["trace[1676495585] 'read index received' (duration: 130.421972ms)","trace[1676495585] 'applied index is now lower than readState.Index' (duration: 4.522855ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:48.215924Z","caller":"traceutil/trace.go:171","msg":"trace[2039701871] transaction","detail":"{read_only:false; response_revision:24448; number_of_response:1; }","duration":"217.345484ms","start":"2026-05-18T01:27:47.998571Z","end":"2026-05-18T01:27:48.215917Z","steps":["trace[2039701871] 'process raft request' (duration: 216.957346ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:48.216044Z","caller":"traceutil/trace.go:171","msg":"trace[615614859] transaction","detail":"{read_only:false; response_revision:24449; number_of_response:1; }","duration":"217.436726ms","start":"2026-05-18T01:27:47.998601Z","end":"2026-05-18T01:27:48.216038Z","steps":["trace[615614859] 'process raft request' (duration: 217.013537ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:48.216143Z","caller":"traceutil/trace.go:171","msg":"trace[102408379] transaction","detail":"{read_only:false; response_revision:24450; number_of_response:1; }","duration":"217.491867ms","start":"2026-05-18T01:27:47.998644Z","end":"2026-05-18T01:27:48.216136Z","steps":["trace[102408379] 'process raft request' (duration: 217.001227ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:48.216199Z","caller":"traceutil/trace.go:171","msg":"trace[1866250908] transaction","detail":"{read_only:false; response_revision:24451; number_of_response:1; }","duration":"216.601558ms","start":"2026-05-18T01:27:47.999594Z","end":"2026-05-18T01:27:48.216195Z","steps":["trace[1866250908] 'process raft request' (duration: 216.071068ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:48.216258Z","caller":"traceutil/trace.go:171","msg":"trace[92666107] transaction","detail":"{read_only:false; response_revision:24452; number_of_response:1; }","duration":"216.481026ms","start":"2026-05-18T01:27:47.999771Z","end":"2026-05-18T01:27:48.216252Z","steps":["trace[92666107] 'process raft request' (duration: 215.918774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:48.216441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.663472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ed0a28f2-0823-45db-b39a-29503667ffc2\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-05-18T01:27:48.216467Z","caller":"traceutil/trace.go:171","msg":"trace[1927301441] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ed0a28f2-0823-45db-b39a-29503667ffc2; range_end:; response_count:1; response_revision:24452; }","duration":"135.739424ms","start":"2026-05-18T01:27:48.080721Z","end":"2026-05-18T01:27:48.216461Z","steps":["trace[1927301441] 'agreement among raft nodes before linearized reading' (duration: 135.622931ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:50.449974Z","caller":"traceutil/trace.go:171","msg":"trace[1046964653] transaction","detail":"{read_only:false; response_revision:24465; number_of_response:1; }","duration":"201.270875ms","start":"2026-05-18T01:27:50.248677Z","end":"2026-05-18T01:27:50.449948Z","steps":["trace[1046964653] 'process raft request' (duration: 201.162373ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:50.449994Z","caller":"traceutil/trace.go:171","msg":"trace[1304114204] transaction","detail":"{read_only:false; response_revision:24464; number_of_response:1; }","duration":"201.340078ms","start":"2026-05-18T01:27:50.248613Z","end":"2026-05-18T01:27:50.449953Z","steps":["trace[1304114204] 'process raft request' (duration: 201.081562ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:50.450616Z","caller":"traceutil/trace.go:171","msg":"trace[1515009846] transaction","detail":"{read_only:false; response_revision:24466; number_of_response:1; }","duration":"201.853068ms","start":"2026-05-18T01:27:50.248739Z","end":"2026-05-18T01:27:50.450592Z","steps":["trace[1515009846] 'process raft request' (duration: 201.163143ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:50.452488Z","caller":"traceutil/trace.go:171","msg":"trace[915948043] transaction","detail":"{read_only:false; response_revision:24467; number_of_response:1; }","duration":"203.712467ms","start":"2026-05-18T01:27:50.248757Z","end":"2026-05-18T01:27:50.45247Z","steps":["trace[915948043] 'process raft request' (duration: 201.780477ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:50.452574Z","caller":"traceutil/trace.go:171","msg":"trace[94630349] transaction","detail":"{read_only:false; response_revision:24468; number_of_response:1; }","duration":"203.679907ms","start":"2026-05-18T01:27:50.248855Z","end":"2026-05-18T01:27:50.452535Z","steps":["trace[94630349] 'process raft request' (duration: 203.519853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:50.452777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.018471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T01:27:50.452832Z","caller":"traceutil/trace.go:171","msg":"trace[1891144761] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:24468; }","duration":"166.128044ms","start":"2026-05-18T01:27:50.286692Z","end":"2026-05-18T01:27:50.45282Z","steps":["trace[1891144761] 'agreement among raft nodes before linearized reading' (duration: 165.94264ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:50.452434Z","caller":"traceutil/trace.go:171","msg":"trace[153553300] linearizableReadLoop","detail":"{readStateIndex:25255; appliedIndex:25249; }","duration":"165.685174ms","start":"2026-05-18T01:27:50.286736Z","end":"2026-05-18T01:27:50.452421Z","steps":["trace[153553300] 'read index received' (duration: 54.101131ms)","trace[153553300] 'applied index is now lower than readState.Index' (duration: 111.579583ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:27:50.453282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.826608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:27:50.453325Z","caller":"traceutil/trace.go:171","msg":"trace[432334358] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:24468; }","duration":"156.95812ms","start":"2026-05-18T01:27:50.296356Z","end":"2026-05-18T01:27:50.453314Z","steps":["trace[432334358] 'agreement among raft nodes before linearized reading' (duration: 156.770906ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:54.462066Z","caller":"traceutil/trace.go:171","msg":"trace[2079959968] transaction","detail":"{read_only:false; response_revision:24510; number_of_response:1; }","duration":"107.458778ms","start":"2026-05-18T01:27:54.354567Z","end":"2026-05-18T01:27:54.462026Z","steps":["trace[2079959968] 'process raft request' (duration: 107.237943ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:54.633084Z","caller":"traceutil/trace.go:171","msg":"trace[910504465] transaction","detail":"{read_only:false; response_revision:24511; number_of_response:1; }","duration":"166.145783ms","start":"2026-05-18T01:27:54.466895Z","end":"2026-05-18T01:27:54.63304Z","steps":["trace[910504465] 'process raft request' (duration: 129.874754ms)","trace[910504465] 'compare' (duration: 36.092375ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:27:54.633123Z","caller":"traceutil/trace.go:171","msg":"trace[1246459335] transaction","detail":"{read_only:false; response_revision:24512; number_of_response:1; }","duration":"163.596248ms","start":"2026-05-18T01:27:54.469496Z","end":"2026-05-18T01:27:54.633092Z","steps":["trace[1246459335] 'process raft request' (duration: 163.528936ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:54.633296Z","caller":"traceutil/trace.go:171","msg":"trace[648526771] linearizableReadLoop","detail":"{readStateIndex:25299; appliedIndex:25298; }","duration":"165.784054ms","start":"2026-05-18T01:27:54.467473Z","end":"2026-05-18T01:27:54.633257Z","steps":["trace[648526771] 'read index received' (duration: 129.320471ms)","trace[648526771] 'applied index is now lower than readState.Index' (duration: 36.456903ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:27:54.633591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.389687ms","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":"warn","ts":"2026-05-18T01:27:54.633588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.091751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-18T01:27:54.633624Z","caller":"traceutil/trace.go:171","msg":"trace[1003366657] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24512; }","duration":"165.456448ms","start":"2026-05-18T01:27:54.468156Z","end":"2026-05-18T01:27:54.633612Z","steps":["trace[1003366657] 'agreement among raft nodes before linearized reading' (duration: 165.267164ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:54.63368Z","caller":"traceutil/trace.go:171","msg":"trace[2041475189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24512; }","duration":"166.249504ms","start":"2026-05-18T01:27:54.467408Z","end":"2026-05-18T01:27:54.633658Z","steps":["trace[2041475189] 'agreement among raft nodes before linearized reading' (duration: 165.946668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:27:54.633854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.143591ms","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-18T01:27:54.633915Z","caller":"traceutil/trace.go:171","msg":"trace[1871145194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24512; }","duration":"165.236294ms","start":"2026-05-18T01:27:54.468665Z","end":"2026-05-18T01:27:54.633901Z","steps":["trace[1871145194] 'agreement among raft nodes before linearized reading' (duration: 164.905167ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:54.866992Z","caller":"traceutil/trace.go:171","msg":"trace[1983553397] transaction","detail":"{read_only:false; response_revision:24513; number_of_response:1; }","duration":"227.909173ms","start":"2026-05-18T01:27:54.639047Z","end":"2026-05-18T01:27:54.866956Z","steps":["trace[1983553397] 'process raft request' (duration: 227.623146ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:54.867432Z","caller":"traceutil/trace.go:171","msg":"trace[244942013] transaction","detail":"{read_only:false; response_revision:24515; number_of_response:1; }","duration":"228.350322ms","start":"2026-05-18T01:27:54.639051Z","end":"2026-05-18T01:27:54.867401Z","steps":["trace[244942013] 'process raft request' (duration: 228.141907ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:27:54.867524Z","caller":"traceutil/trace.go:171","msg":"trace[278165257] transaction","detail":"{read_only:false; response_revision:24514; number_of_response:1; }","duration":"228.445214ms","start":"2026-05-18T01:27:54.639043Z","end":"2026-05-18T01:27:54.867488Z","steps":["trace[278165257] 'process raft request' (duration: 227.860831ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:23.901561Z","caller":"traceutil/trace.go:171","msg":"trace[406841556] transaction","detail":"{read_only:false; response_revision:24753; number_of_response:1; }","duration":"375.361579ms","start":"2026-05-18T01:28:23.526167Z","end":"2026-05-18T01:28:23.901529Z","steps":["trace[406841556] 'process raft request' (duration: 375.140894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:23.901722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:23.526126Z","time spent":"375.516852ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:24.319732Z","caller":"traceutil/trace.go:171","msg":"trace[703339972] transaction","detail":"{read_only:false; response_revision:24754; number_of_response:1; }","duration":"790.233094ms","start":"2026-05-18T01:28:23.529466Z","end":"2026-05-18T01:28:24.319699Z","steps":["trace[703339972] 'process raft request' (duration: 740.390277ms)","trace[703339972] 'compare' (duration: 49.387327ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:28:24.319959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.506615ms","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":"warn","ts":"2026-05-18T01:28:24.319992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:23.529434Z","time spent":"790.377997ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:24.320044Z","caller":"traceutil/trace.go:171","msg":"trace[1072351279] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:24758; }","duration":"720.583546ms","start":"2026-05-18T01:28:23.599429Z","end":"2026-05-18T01:28:24.320012Z","steps":["trace[1072351279] 'agreement among raft nodes before linearized reading' (duration: 720.362832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:24.320092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:23.5994Z","time spent":"720.678369ms","remote":"127.0.0.1:38880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1880,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" "} {"level":"info","ts":"2026-05-18T01:28:24.319745Z","caller":"traceutil/trace.go:171","msg":"trace[324880037] linearizableReadLoop","detail":"{readStateIndex:25550; appliedIndex:25548; }","duration":"720.27527ms","start":"2026-05-18T01:28:23.599445Z","end":"2026-05-18T01:28:24.31972Z","steps":["trace[324880037] 'read index received' (duration: 302.063124ms)","trace[324880037] 'applied index is now lower than readState.Index' (duration: 418.210586ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:28:24.320349Z","caller":"traceutil/trace.go:171","msg":"trace[340659232] transaction","detail":"{read_only:false; response_revision:24755; number_of_response:1; }","duration":"789.837594ms","start":"2026-05-18T01:28:23.530498Z","end":"2026-05-18T01:28:24.320335Z","steps":["trace[340659232] 'process raft request' (duration: 788.990866ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:24.320384Z","caller":"traceutil/trace.go:171","msg":"trace[2096135625] transaction","detail":"{read_only:false; response_revision:24757; number_of_response:1; }","duration":"629.494025ms","start":"2026-05-18T01:28:23.690855Z","end":"2026-05-18T01:28:24.320349Z","steps":["trace[2096135625] 'process raft request' (duration: 628.73845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:24.32042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:23.530492Z","time spent":"789.888216ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:24.320513Z","caller":"traceutil/trace.go:171","msg":"trace[845437444] transaction","detail":"{read_only:false; response_revision:24758; number_of_response:1; }","duration":"574.310626ms","start":"2026-05-18T01:28:23.746168Z","end":"2026-05-18T01:28:24.320478Z","steps":["trace[845437444] 'process raft request' (duration: 573.464287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:24.320538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:23.690831Z","time spent":"629.616018ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:24.320598Z","caller":"traceutil/trace.go:171","msg":"trace[540230869] transaction","detail":"{read_only:false; response_revision:24756; number_of_response:1; }","duration":"674.394009ms","start":"2026-05-18T01:28:23.646159Z","end":"2026-05-18T01:28:24.320553Z","steps":["trace[540230869] 'process raft request' (duration: 673.387167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:24.320713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:23.646131Z","time spent":"674.534321ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:28:24.320721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:23.74614Z","time spent":"574.451038ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:28:24.650514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.357061ms","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-18T01:28:24.650507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.714421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T01:28:24.650597Z","caller":"traceutil/trace.go:171","msg":"trace[57890467] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24759; }","duration":"228.481344ms","start":"2026-05-18T01:28:24.422094Z","end":"2026-05-18T01:28:24.650576Z","steps":["trace[57890467] 'count revisions from in-memory index tree' (duration: 228.28429ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:24.650603Z","caller":"traceutil/trace.go:171","msg":"trace[1981488621] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:24759; }","duration":"163.879854ms","start":"2026-05-18T01:28:24.486703Z","end":"2026-05-18T01:28:24.650583Z","steps":["trace[1981488621] 'count revisions from in-memory index tree' (duration: 163.637669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:24.650615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.830586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:28:24.650699Z","caller":"traceutil/trace.go:171","msg":"trace[670053128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24759; }","duration":"207.940708ms","start":"2026-05-18T01:28:24.442742Z","end":"2026-05-18T01:28:24.650682Z","steps":["trace[670053128] 'range keys from in-memory index tree' (duration: 207.772265ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:24.665878Z","caller":"traceutil/trace.go:171","msg":"trace[151330727] transaction","detail":"{read_only:false; response_revision:24760; number_of_response:1; }","duration":"151.622044ms","start":"2026-05-18T01:28:24.514232Z","end":"2026-05-18T01:28:24.665854Z","steps":["trace[151330727] 'process raft request' (duration: 151.39525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:25.248452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.129361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:28:25.248589Z","caller":"traceutil/trace.go:171","msg":"trace[1196661596] linearizableReadLoop","detail":"{readStateIndex:25556; appliedIndex:25555; }","duration":"477.493753ms","start":"2026-05-18T01:28:24.771077Z","end":"2026-05-18T01:28:25.24857Z","steps":["trace[1196661596] 'read index received' (duration: 62.066706ms)","trace[1196661596] 'applied index is now lower than readState.Index' (duration: 415.425107ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:28:25.248845Z","caller":"traceutil/trace.go:171","msg":"trace[2116744969] transaction","detail":"{read_only:false; response_revision:24761; number_of_response:1; }","duration":"574.560641ms","start":"2026-05-18T01:28:24.674028Z","end":"2026-05-18T01:28:25.248589Z","steps":["trace[2116744969] 'process raft request' (duration: 159.178535ms)","trace[2116744969] 'compare' (duration: 414.850635ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:28:25.248998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.920033ms","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-18T01:28:25.249019Z","caller":"traceutil/trace.go:171","msg":"trace[1299836885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24761; }","duration":"477.981834ms","start":"2026-05-18T01:28:24.77103Z","end":"2026-05-18T01:28:25.249012Z","steps":["trace[1299836885] 'agreement among raft nodes before linearized reading' (duration: 477.836741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:25.249019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:24.673988Z","time spent":"574.936869ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:28:25.249044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:24.771008Z","time spent":"478.029295ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-18T01:28:25.249257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.453628ms","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-18T01:28:25.249282Z","caller":"traceutil/trace.go:171","msg":"trace[625652234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24761; }","duration":"140.5858ms","start":"2026-05-18T01:28:25.108691Z","end":"2026-05-18T01:28:25.249276Z","steps":["trace[625652234] 'agreement among raft nodes before linearized reading' (duration: 140.488238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:25.249349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.155208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-18T01:28:25.249462Z","caller":"traceutil/trace.go:171","msg":"trace[1357450546] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24761; }","duration":"478.310141ms","start":"2026-05-18T01:28:24.771118Z","end":"2026-05-18T01:28:25.249428Z","steps":["trace[1357450546] 'agreement among raft nodes before linearized reading' (duration: 477.996055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:25.249472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.907647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-18T01:28:25.249515Z","caller":"traceutil/trace.go:171","msg":"trace[1377183776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24761; }","duration":"207.988199ms","start":"2026-05-18T01:28:25.041514Z","end":"2026-05-18T01:28:25.249502Z","steps":["trace[1377183776] 'agreement among raft nodes before linearized reading' (duration: 207.828116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:25.249513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:24.771094Z","time spent":"478.408553ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-18T01:28:25.249668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.632503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-18T01:28:25.249697Z","caller":"traceutil/trace.go:171","msg":"trace[21521664] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24761; }","duration":"275.716105ms","start":"2026-05-18T01:28:24.97397Z","end":"2026-05-18T01:28:25.249686Z","steps":["trace[21521664] 'agreement among raft nodes before linearized reading' (duration: 275.625453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:25.249815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.965498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-18T01:28:25.249851Z","caller":"traceutil/trace.go:171","msg":"trace[757906945] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24761; }","duration":"141.04955ms","start":"2026-05-18T01:28:25.108788Z","end":"2026-05-18T01:28:25.249837Z","steps":["trace[757906945] 'agreement among raft nodes before linearized reading' (duration: 140.967028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:25.654557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.519825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-18T01:28:25.654736Z","caller":"traceutil/trace.go:171","msg":"trace[1942103784] transaction","detail":"{read_only:false; response_revision:24762; number_of_response:1; }","duration":"399.439879ms","start":"2026-05-18T01:28:25.255267Z","end":"2026-05-18T01:28:25.654706Z","steps":["trace[1942103784] 'process raft request' (duration: 137.545396ms)","trace[1942103784] 'compare' (duration: 260.919242ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:28:25.654828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:25.255235Z","time spent":"399.552931ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:25.965947Z","caller":"traceutil/trace.go:171","msg":"trace[1986081860] transaction","detail":"{read_only:false; response_revision:24763; number_of_response:1; }","duration":"710.631196ms","start":"2026-05-18T01:28:25.255287Z","end":"2026-05-18T01:28:25.965919Z","steps":["trace[1986081860] 'process raft request' (duration: 688.62346ms)","trace[1986081860] 'compare' (duration: 21.825723ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:28:25.966099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:25.255256Z","time spent":"710.764479ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:25.966296Z","caller":"traceutil/trace.go:171","msg":"trace[1968406323] transaction","detail":"{read_only:false; response_revision:24764; number_of_response:1; }","duration":"710.550455ms","start":"2026-05-18T01:28:25.255681Z","end":"2026-05-18T01:28:25.966232Z","steps":["trace[1968406323] 'process raft request' (duration: 710.198317ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:25.966493Z","caller":"traceutil/trace.go:171","msg":"trace[1664492345] transaction","detail":"{read_only:false; response_revision:24765; number_of_response:1; }","duration":"710.34691ms","start":"2026-05-18T01:28:25.256136Z","end":"2026-05-18T01:28:25.966483Z","steps":["trace[1664492345] 'process raft request' (duration: 710.155986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:25.966539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:25.256126Z","time spent":"710.390691ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:28:25.966531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:25.255667Z","time spent":"710.735838ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:25.966565Z","caller":"traceutil/trace.go:171","msg":"trace[902764048] transaction","detail":"{read_only:false; response_revision:24766; number_of_response:1; }","duration":"709.618535ms","start":"2026-05-18T01:28:25.256902Z","end":"2026-05-18T01:28:25.96652Z","steps":["trace[902764048] 'process raft request' (duration: 709.472342ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:25.966724Z","caller":"traceutil/trace.go:171","msg":"trace[710077505] transaction","detail":"{read_only:false; response_revision:24767; number_of_response:1; }","duration":"709.274907ms","start":"2026-05-18T01:28:25.257404Z","end":"2026-05-18T01:28:25.966679Z","steps":["trace[710077505] 'process raft request' (duration: 709.048312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:25.969054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:25.257396Z","time spent":"711.558306ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:25.966798Z","caller":"traceutil/trace.go:171","msg":"trace[35933682] linearizableReadLoop","detail":"{readStateIndex:25563; appliedIndex:25557; }","duration":"521.256381ms","start":"2026-05-18T01:28:25.445516Z","end":"2026-05-18T01:28:25.966772Z","steps":["trace[35933682] 'read index received' (duration: 498.414507ms)","trace[35933682] 'applied index is now lower than readState.Index' (duration: 22.840604ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:28:25.966914Z","caller":"traceutil/trace.go:171","msg":"trace[1873646183] transaction","detail":"{read_only:false; response_revision:24768; number_of_response:1; }","duration":"699.9662ms","start":"2026-05-18T01:28:25.266937Z","end":"2026-05-18T01:28:25.966903Z","steps":["trace[1873646183] 'process raft request' (duration: 699.683714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:25.969408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:25.266923Z","time spent":"702.455393ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-18T01:28:25.966909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.374923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:28:25.969549Z","caller":"traceutil/trace.go:171","msg":"trace[1142064679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24768; }","duration":"524.02614ms","start":"2026-05-18T01:28:25.445508Z","end":"2026-05-18T01:28:25.969534Z","steps":["trace[1142064679] 'agreement among raft nodes before linearized reading' (duration: 521.350113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:25.969586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:25.445471Z","time spent":"524.106852ms","remote":"127.0.0.1:38706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-18T01:28:25.970549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:25.256896Z","time spent":"711.991085ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:26.195204Z","caller":"traceutil/trace.go:171","msg":"trace[1348150307] transaction","detail":"{read_only:false; response_revision:24769; number_of_response:1; }","duration":"502.78612ms","start":"2026-05-18T01:28:25.692383Z","end":"2026-05-18T01:28:26.195169Z","steps":["trace[1348150307] 'process raft request' (duration: 490.219953ms)","trace[1348150307] 'compare' (duration: 12.012995ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:28:26.195232Z","caller":"traceutil/trace.go:171","msg":"trace[786041426] linearizableReadLoop","detail":"{readStateIndex:25566; appliedIndex:25563; }","duration":"225.90263ms","start":"2026-05-18T01:28:25.969301Z","end":"2026-05-18T01:28:26.195203Z","steps":["trace[786041426] 'read index received' (duration: 213.279411ms)","trace[786041426] 'applied index is now lower than readState.Index' (duration: 12.622149ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:28:26.195254Z","caller":"traceutil/trace.go:171","msg":"trace[2136217515] transaction","detail":"{read_only:false; response_revision:24770; number_of_response:1; }","duration":"294.698868ms","start":"2026-05-18T01:28:25.900518Z","end":"2026-05-18T01:28:26.195216Z","steps":["trace[2136217515] 'process raft request' (duration: 294.483893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:26.195361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:25.692353Z","time spent":"502.927903ms","remote":"127.0.0.1:38986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-18T01:28:26.195394Z","caller":"traceutil/trace.go:171","msg":"trace[1530122946] transaction","detail":"{read_only:false; response_revision:24771; number_of_response:1; }","duration":"279.835113ms","start":"2026-05-18T01:28:25.91554Z","end":"2026-05-18T01:28:26.195375Z","steps":["trace[1530122946] 'process raft request' (duration: 279.585988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:26.195567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.361364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-05-18T01:28:26.195613Z","caller":"traceutil/trace.go:171","msg":"trace[1452027988] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24771; }","duration":"638.435186ms","start":"2026-05-18T01:28:25.557163Z","end":"2026-05-18T01:28:26.195598Z","steps":["trace[1452027988] 'agreement among raft nodes before linearized reading' (duration: 638.331974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:26.195642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:25.557144Z","time spent":"638.492138ms","remote":"127.0.0.1:38880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1876,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-18T01:28:26.195753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.399106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-18T01:28:26.195808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.035991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:28:26.195815Z","caller":"traceutil/trace.go:171","msg":"trace[1942523980] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:24771; }","duration":"456.528629ms","start":"2026-05-18T01:28:25.739271Z","end":"2026-05-18T01:28:26.195799Z","steps":["trace[1942523980] 'agreement among raft nodes before linearized reading' (duration: 456.385146ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:26.195851Z","caller":"traceutil/trace.go:171","msg":"trace[1783216805] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24771; }","duration":"225.130213ms","start":"2026-05-18T01:28:25.970709Z","end":"2026-05-18T01:28:26.195839Z","steps":["trace[1783216805] 'agreement among raft nodes before linearized reading' (duration: 225.048491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:26.195899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T01:28:25.739252Z","time spent":"456.59868ms","remote":"127.0.0.1:46970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-05-18T01:28:26.196073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.379256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-18T01:28:26.19612Z","caller":"traceutil/trace.go:171","msg":"trace[1049847507] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:24771; }","duration":"115.478588ms","start":"2026-05-18T01:28:26.080626Z","end":"2026-05-18T01:28:26.196104Z","steps":["trace[1049847507] 'agreement among raft nodes before linearized reading' (duration: 115.411887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:26.714364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.189517ms","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":"warn","ts":"2026-05-18T01:28:26.714386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.200265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:28:26.714472Z","caller":"traceutil/trace.go:171","msg":"trace[885683110] 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:24776; }","duration":"203.337671ms","start":"2026-05-18T01:28:26.511105Z","end":"2026-05-18T01:28:26.714443Z","steps":["trace[885683110] 'range keys from in-memory index tree' (duration: 203.018744ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:26.714497Z","caller":"traceutil/trace.go:171","msg":"trace[2111364205] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:24776; }","duration":"117.386478ms","start":"2026-05-18T01:28:26.597084Z","end":"2026-05-18T01:28:26.71447Z","steps":["trace[2111364205] 'count revisions from in-memory index tree' (duration: 117.118553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:27.509843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.064493ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-18T01:28:28.15573Z","caller":"traceutil/trace.go:171","msg":"trace[1331014656] transaction","detail":"{read_only:false; response_revision:24780; number_of_response:1; }","duration":"179.391303ms","start":"2026-05-18T01:28:27.976316Z","end":"2026-05-18T01:28:28.155707Z","steps":["trace[1331014656] 'process raft request' (duration: 179.308111ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:28.155764Z","caller":"traceutil/trace.go:171","msg":"trace[1363060165] transaction","detail":"{read_only:false; response_revision:24779; number_of_response:1; }","duration":"180.289753ms","start":"2026-05-18T01:28:27.975459Z","end":"2026-05-18T01:28:28.155749Z","steps":["trace[1363060165] 'process raft request' (duration: 179.995556ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:28.156003Z","caller":"traceutil/trace.go:171","msg":"trace[497903607] transaction","detail":"{read_only:false; response_revision:24781; number_of_response:1; }","duration":"178.537386ms","start":"2026-05-18T01:28:27.977449Z","end":"2026-05-18T01:28:28.155986Z","steps":["trace[497903607] 'process raft request' (duration: 178.224259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:28.156029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.197991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-18T01:28:28.156093Z","caller":"traceutil/trace.go:171","msg":"trace[598667522] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24781; }","duration":"180.272803ms","start":"2026-05-18T01:28:27.975806Z","end":"2026-05-18T01:28:28.156079Z","steps":["trace[598667522] 'agreement among raft nodes before linearized reading' (duration: 180.049598ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:28.342022Z","caller":"traceutil/trace.go:171","msg":"trace[1151861431] transaction","detail":"{read_only:false; response_revision:24782; number_of_response:1; }","duration":"266.382448ms","start":"2026-05-18T01:28:28.075617Z","end":"2026-05-18T01:28:28.341999Z","steps":["trace[1151861431] 'process raft request' (duration: 188.917426ms)","trace[1151861431] 'compare' (duration: 77.295649ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:28:28.34226Z","caller":"traceutil/trace.go:171","msg":"trace[1993275780] transaction","detail":"{read_only:false; response_revision:24783; number_of_response:1; }","duration":"181.779754ms","start":"2026-05-18T01:28:28.16047Z","end":"2026-05-18T01:28:28.34225Z","steps":["trace[1993275780] 'process raft request' (duration: 181.486228ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:28.342325Z","caller":"traceutil/trace.go:171","msg":"trace[1791015786] transaction","detail":"{read_only:false; response_revision:24784; number_of_response:1; }","duration":"141.101651ms","start":"2026-05-18T01:28:28.201199Z","end":"2026-05-18T01:28:28.342301Z","steps":["trace[1791015786] 'process raft request' (duration: 140.979469ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:28.384784Z","caller":"traceutil/trace.go:171","msg":"trace[404797627] transaction","detail":"{read_only:false; response_revision:24785; number_of_response:1; }","duration":"119.680336ms","start":"2026-05-18T01:28:28.265075Z","end":"2026-05-18T01:28:28.384755Z","steps":["trace[404797627] 'process raft request' (duration: 119.166595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:28.385002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.149889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:28:28.385123Z","caller":"traceutil/trace.go:171","msg":"trace[1651520795] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:24785; }","duration":"114.307442ms","start":"2026-05-18T01:28:28.27079Z","end":"2026-05-18T01:28:28.385098Z","steps":["trace[1651520795] 'agreement among raft nodes before linearized reading' (duration: 114.068947ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:28.385201Z","caller":"traceutil/trace.go:171","msg":"trace[1317844104] linearizableReadLoop","detail":"{readStateIndex:25581; appliedIndex:25577; }","duration":"113.796142ms","start":"2026-05-18T01:28:28.270802Z","end":"2026-05-18T01:28:28.384598Z","steps":["trace[1317844104] 'read index received' (duration: 2.122134ms)","trace[1317844104] 'applied index is now lower than readState.Index' (duration: 111.673278ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:28:29.456564Z","caller":"traceutil/trace.go:171","msg":"trace[1018548038] transaction","detail":"{read_only:false; response_revision:24791; number_of_response:1; }","duration":"150.628714ms","start":"2026-05-18T01:28:29.305908Z","end":"2026-05-18T01:28:29.456537Z","steps":["trace[1018548038] 'process raft request' (duration: 150.44402ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:29.59484Z","caller":"traceutil/trace.go:171","msg":"trace[1461109729] linearizableReadLoop","detail":"{readStateIndex:25588; appliedIndex:25586; }","duration":"150.624093ms","start":"2026-05-18T01:28:29.444187Z","end":"2026-05-18T01:28:29.594811Z","steps":["trace[1461109729] 'read index received' (duration: 12.149837ms)","trace[1461109729] 'applied index is now lower than readState.Index' (duration: 138.473336ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:28:29.594916Z","caller":"traceutil/trace.go:171","msg":"trace[1149672330] transaction","detail":"{read_only:false; response_revision:24792; number_of_response:1; }","duration":"267.642714ms","start":"2026-05-18T01:28:29.327241Z","end":"2026-05-18T01:28:29.594883Z","steps":["trace[1149672330] 'process raft request' (duration: 200.02335ms)","trace[1149672330] 'compare' (duration: 67.388819ms)"],"step_count":2} {"level":"warn","ts":"2026-05-18T01:28:29.595035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.835847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-18T01:28:29.595082Z","caller":"traceutil/trace.go:171","msg":"trace[110925509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24792; }","duration":"150.9441ms","start":"2026-05-18T01:28:29.444127Z","end":"2026-05-18T01:28:29.595071Z","steps":["trace[110925509] 'agreement among raft nodes before linearized reading' (duration: 150.844998ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:30.54612Z","caller":"traceutil/trace.go:171","msg":"trace[715711961] transaction","detail":"{read_only:false; response_revision:24800; number_of_response:1; }","duration":"131.841235ms","start":"2026-05-18T01:28:30.414265Z","end":"2026-05-18T01:28:30.546106Z","steps":["trace[715711961] 'process raft request' (duration: 131.705502ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:30.546399Z","caller":"traceutil/trace.go:171","msg":"trace[892445645] transaction","detail":"{read_only:false; response_revision:24801; number_of_response:1; }","duration":"132.123061ms","start":"2026-05-18T01:28:30.414268Z","end":"2026-05-18T01:28:30.546391Z","steps":["trace[892445645] 'process raft request' (duration: 131.807505ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:30.546461Z","caller":"traceutil/trace.go:171","msg":"trace[1478559648] transaction","detail":"{read_only:false; response_revision:24802; number_of_response:1; }","duration":"132.167952ms","start":"2026-05-18T01:28:30.414272Z","end":"2026-05-18T01:28:30.54644Z","steps":["trace[1478559648] 'process raft request' (duration: 132.019409ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:30.546703Z","caller":"traceutil/trace.go:171","msg":"trace[1651019450] transaction","detail":"{read_only:false; response_revision:24803; number_of_response:1; }","duration":"132.0894ms","start":"2026-05-18T01:28:30.414603Z","end":"2026-05-18T01:28:30.546692Z","steps":["trace[1651019450] 'process raft request' (duration: 131.799894ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:30.546769Z","caller":"traceutil/trace.go:171","msg":"trace[1738399460] transaction","detail":"{read_only:false; response_revision:24804; number_of_response:1; }","duration":"132.06758ms","start":"2026-05-18T01:28:30.414679Z","end":"2026-05-18T01:28:30.546747Z","steps":["trace[1738399460] 'process raft request' (duration: 131.920397ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:32.579805Z","caller":"traceutil/trace.go:171","msg":"trace[177883712] transaction","detail":"{read_only:false; response_revision:24813; number_of_response:1; }","duration":"142.147454ms","start":"2026-05-18T01:28:32.437628Z","end":"2026-05-18T01:28:32.579776Z","steps":["trace[177883712] 'process raft request' (duration: 141.936059ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:58.934714Z","caller":"traceutil/trace.go:171","msg":"trace[768948794] transaction","detail":"{read_only:false; response_revision:25155; number_of_response:1; }","duration":"231.920377ms","start":"2026-05-18T01:28:58.70277Z","end":"2026-05-18T01:28:58.93469Z","steps":["trace[768948794] 'process raft request' (duration: 231.732213ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:58.935011Z","caller":"traceutil/trace.go:171","msg":"trace[1780632619] transaction","detail":"{read_only:false; response_revision:25156; number_of_response:1; }","duration":"227.111915ms","start":"2026-05-18T01:28:58.707888Z","end":"2026-05-18T01:28:58.935Z","steps":["trace[1780632619] 'process raft request' (duration: 226.761407ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:58.935026Z","caller":"traceutil/trace.go:171","msg":"trace[1469704622] linearizableReadLoop","detail":"{readStateIndex:25963; appliedIndex:25957; }","duration":"222.417196ms","start":"2026-05-18T01:28:58.712591Z","end":"2026-05-18T01:28:58.935009Z","steps":["trace[1469704622] 'read index received' (duration: 12.72705ms)","trace[1469704622] 'applied index is now lower than readState.Index' (duration: 209.689106ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:28:58.935118Z","caller":"traceutil/trace.go:171","msg":"trace[471930285] transaction","detail":"{read_only:false; response_revision:25157; number_of_response:1; }","duration":"226.541583ms","start":"2026-05-18T01:28:58.70857Z","end":"2026-05-18T01:28:58.935111Z","steps":["trace[471930285] 'process raft request' (duration: 226.302228ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:58.935234Z","caller":"traceutil/trace.go:171","msg":"trace[214753926] transaction","detail":"{read_only:false; response_revision:25159; number_of_response:1; }","duration":"225.670195ms","start":"2026-05-18T01:28:58.709558Z","end":"2026-05-18T01:28:58.935228Z","steps":["trace[214753926] 'process raft request' (duration: 225.41981ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:58.935213Z","caller":"traceutil/trace.go:171","msg":"trace[479291631] transaction","detail":"{read_only:false; response_revision:25158; number_of_response:1; }","duration":"226.104984ms","start":"2026-05-18T01:28:58.709077Z","end":"2026-05-18T01:28:58.935182Z","steps":["trace[479291631] 'process raft request' (duration: 225.870079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:58.9353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.118704ms","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-18T01:28:58.935354Z","caller":"traceutil/trace.go:171","msg":"trace[664211252] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25159; }","duration":"217.197725ms","start":"2026-05-18T01:28:58.718128Z","end":"2026-05-18T01:28:58.935326Z","steps":["trace[664211252] 'agreement among raft nodes before linearized reading' (duration: 217.022182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-18T01:28:58.935497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.876965ms","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-18T01:28:58.935611Z","caller":"traceutil/trace.go:171","msg":"trace[34487472] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25159; }","duration":"223.046649ms","start":"2026-05-18T01:28:58.712543Z","end":"2026-05-18T01:28:58.93559Z","steps":["trace[34487472] 'agreement among raft nodes before linearized reading' (duration: 222.584669ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:28:59.093444Z","caller":"traceutil/trace.go:171","msg":"trace[300398687] transaction","detail":"{read_only:false; response_revision:25160; number_of_response:1; }","duration":"153.649486ms","start":"2026-05-18T01:28:58.939765Z","end":"2026-05-18T01:28:59.093414Z","steps":["trace[300398687] 'process raft request' (duration: 63.71933ms)","trace[300398687] 'compare' (duration: 89.541798ms)"],"step_count":2} {"level":"info","ts":"2026-05-18T01:28:59.093605Z","caller":"traceutil/trace.go:171","msg":"trace[434019226] transaction","detail":"{read_only:false; response_revision:25161; number_of_response:1; }","duration":"152.382421ms","start":"2026-05-18T01:28:58.941193Z","end":"2026-05-18T01:28:59.093575Z","steps":["trace[434019226] 'process raft request' (duration: 152.156996ms)"],"step_count":1} {"level":"info","ts":"2026-05-18T01:31:18.798451Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23547} {"level":"info","ts":"2026-05-18T01:31:18.938478Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23547,"took":"135.219247ms","hash":835762117,"current-db-size-bytes":58978304,"current-db-size":"59 MB","current-db-size-in-use-bytes":37179392,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-18T01:31:18.938538Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":835762117,"revision":23547,"compact-revision":20385} {"level":"info","ts":"2026-05-18T01:31:27.434422Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006afb.wal"} {"level":"info","ts":"2026-05-18T01:36:18.806033Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26441} {"level":"info","ts":"2026-05-18T01:36:18.952744Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26441,"took":"140.748584ms","hash":2745446465,"current-db-size-bytes":58978304,"current-db-size":"59 MB","current-db-size-in-use-bytes":32100352,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-18T01:36:18.952858Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2745446465,"revision":26441,"compact-revision":23547} {"level":"info","ts":"2026-05-18T01:36:39.47713Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-18T01:36:39.486349Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-18T01:36:39.486551Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-18T01:41:18.812179Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28944} {"level":"info","ts":"2026-05-18T01:41:18.942843Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28944,"took":"127.680133ms","hash":3645353032,"current-db-size-bytes":58978304,"current-db-size":"59 MB","current-db-size-in-use-bytes":29917184,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-18T01:41:18.942911Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3645353032,"revision":28944,"compact-revision":26441} {"level":"info","ts":"2026-05-18T01:46:18.819657Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31160} {"level":"info","ts":"2026-05-18T01:46:18.93607Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31160,"took":"111.968046ms","hash":249153560,"current-db-size-bytes":58978304,"current-db-size":"59 MB","current-db-size-in-use-bytes":31866880,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-18T01:46:18.936155Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":249153560,"revision":31160,"compact-revision":28944} {"level":"info","ts":"2026-05-18T01:51:18.827549Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33493} {"level":"info","ts":"2026-05-18T01:51:18.999878Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33493,"took":"168.483626ms","hash":3203866839,"current-db-size-bytes":60862464,"current-db-size":"61 MB","current-db-size-in-use-bytes":32264192,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-18T01:51:18.999947Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3203866839,"revision":33493,"compact-revision":31160} {"level":"info","ts":"2026-05-18T01:56:18.833952Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35722} {"level":"info","ts":"2026-05-18T01:56:18.975449Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35722,"took":"135.389247ms","hash":686581703,"current-db-size-bytes":61718528,"current-db-size":"62 MB","current-db-size-in-use-bytes":30945280,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-18T01:56:18.975514Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":686581703,"revision":35722,"compact-revision":33493} {"level":"info","ts":"2026-05-18T01:58:09.474625Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-18T01:58:09.480676Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-18T01:58:09.480853Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}