{"level":"warn","ts":"2026-04-07T00:10:43.200862Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:10:43.201034Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.35:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.35:2380","--initial-cluster=instance=https://199.19.213.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.35:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T00:10:43.201154Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:10:43.201175Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.35:2380"]} {"level":"info","ts":"2026-04-07T00:10:43.201199Z","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-04-07T00:10:43.202199Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.35:2379"]} {"level":"info","ts":"2026-04-07T00:10:43.202306Z","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.19.213.35:2380"],"listen-peer-urls":["https://199.19.213.35:2380"],"advertise-client-urls":["https://199.19.213.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.35:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T00:10:43.206448Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.835412ms"} {"level":"info","ts":"2026-04-07T00:10:43.211585Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d1606b245347dc08","cluster-id":"e68e205b85faf98a"} {"level":"info","ts":"2026-04-07T00:10:43.21177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T00:10:43.211801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 became follower at term 0"} {"level":"info","ts":"2026-04-07T00:10:43.211813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d1606b245347dc08 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T00:10:43.211833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 became follower at term 1"} {"level":"info","ts":"2026-04-07T00:10:43.211878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 switched to configuration voters=(15087176555451374600)"} {"level":"warn","ts":"2026-04-07T00:10:43.222512Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T00:10:43.223907Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T00:10:43.225079Z","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-04-07T00:10:43.227138Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d1606b245347dc08","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T00:10:43.22736Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d1606b245347dc08","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T00:10:43.227475Z","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-04-07T00:10:43.227631Z","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-04-07T00:10:43.227653Z","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-04-07T00:10:43.229123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 switched to configuration voters=(15087176555451374600)"} {"level":"info","ts":"2026-04-07T00:10:43.229636Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e68e205b85faf98a","local-member-id":"d1606b245347dc08","added-peer-id":"d1606b245347dc08","added-peer-peer-urls":["https://199.19.213.35:2380"]} {"level":"info","ts":"2026-04-07T00:10:43.232059Z","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-04-07T00:10:43.232323Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d1606b245347dc08","initial-advertise-peer-urls":["https://199.19.213.35:2380"],"listen-peer-urls":["https://199.19.213.35:2380"],"advertise-client-urls":["https://199.19.213.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T00:10:43.232368Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T00:10:43.232456Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.35:2380"} {"level":"info","ts":"2026-04-07T00:10:43.232487Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.35:2380"} {"level":"info","ts":"2026-04-07T00:10:44.01306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T00:10:44.013116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T00:10:44.013144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 received MsgPreVoteResp from d1606b245347dc08 at term 1"} {"level":"info","ts":"2026-04-07T00:10:44.013158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 became candidate at term 2"} {"level":"info","ts":"2026-04-07T00:10:44.013165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 received MsgVoteResp from d1606b245347dc08 at term 2"} {"level":"info","ts":"2026-04-07T00:10:44.013175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1606b245347dc08 became leader at term 2"} {"level":"info","ts":"2026-04-07T00:10:44.013186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d1606b245347dc08 elected leader d1606b245347dc08 at term 2"} {"level":"info","ts":"2026-04-07T00:10:44.013929Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:10:44.014674Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d1606b245347dc08","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.35:2379]}","request-path":"/0/members/d1606b245347dc08/attributes","cluster-id":"e68e205b85faf98a","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T00:10:44.014709Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:10:44.014677Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:10:44.015028Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T00:10:44.015057Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T00:10:44.015274Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e68e205b85faf98a","local-member-id":"d1606b245347dc08","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:10:44.015383Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:10:44.015413Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:10:44.017006Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.35:2379"} {"level":"info","ts":"2026-04-07T00:10:44.017108Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T00:11:01.739952Z","caller":"traceutil/trace.go:171","msg":"trace[1670852430] linearizableReadLoop","detail":"{readStateIndex:459; appliedIndex:458; }","duration":"273.694822ms","start":"2026-04-07T00:11:01.466231Z","end":"2026-04-07T00:11:01.739926Z","steps":["trace[1670852430] 'read index received' (duration: 273.457867ms)","trace[1670852430] 'applied index is now lower than readState.Index' (duration: 235.845µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:11:01.740036Z","caller":"traceutil/trace.go:171","msg":"trace[1189341017] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"357.224829ms","start":"2026-04-07T00:11:01.38277Z","end":"2026-04-07T00:11:01.739995Z","steps":["trace[1189341017] 'process raft request' (duration: 357.011874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:01.740253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.933128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T00:11:01.740377Z","caller":"traceutil/trace.go:171","msg":"trace[648226237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:444; }","duration":"274.169684ms","start":"2026-04-07T00:11:01.466187Z","end":"2026-04-07T00:11:01.740356Z","steps":["trace[648226237] 'agreement among raft nodes before linearized reading' (duration: 273.864596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:01.740804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:01.382752Z","time spent":"357.375933ms","remote":"127.0.0.1:45254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5311,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:11:01.904561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.350167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:11:01.904682Z","caller":"traceutil/trace.go:171","msg":"trace[128564788] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"159.818771ms","start":"2026-04-07T00:11:01.744842Z","end":"2026-04-07T00:11:01.904661Z","steps":["trace[128564788] 'process raft request' (duration: 21.153356ms)","trace[128564788] 'compare' (duration: 138.194352ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:11:01.951347Z","caller":"traceutil/trace.go:171","msg":"trace[985910732] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"200.542193ms","start":"2026-04-07T00:11:01.750773Z","end":"2026-04-07T00:11:01.951315Z","steps":["trace[985910732] 'process raft request' (duration: 200.311118ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:12.762392Z","caller":"traceutil/trace.go:171","msg":"trace[1072490836] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"184.605092ms","start":"2026-04-07T00:11:12.577759Z","end":"2026-04-07T00:11:12.762364Z","steps":["trace[1072490836] 'process raft request' (duration: 184.450119ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:12.762474Z","caller":"traceutil/trace.go:171","msg":"trace[154874801] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"184.696525ms","start":"2026-04-07T00:11:12.577654Z","end":"2026-04-07T00:11:12.762351Z","steps":["trace[154874801] 'process raft request' (duration: 184.445089ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:12.762539Z","caller":"traceutil/trace.go:171","msg":"trace[1844897874] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"172.446772ms","start":"2026-04-07T00:11:12.590051Z","end":"2026-04-07T00:11:12.762497Z","steps":["trace[1844897874] 'process raft request' (duration: 172.206646ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:12.762899Z","caller":"traceutil/trace.go:171","msg":"trace[818201278] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"172.75254ms","start":"2026-04-07T00:11:12.590133Z","end":"2026-04-07T00:11:12.762886Z","steps":["trace[818201278] 'process raft request' (duration: 172.165095ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:12.763032Z","caller":"traceutil/trace.go:171","msg":"trace[1288935460] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"172.809851ms","start":"2026-04-07T00:11:12.590198Z","end":"2026-04-07T00:11:12.763008Z","steps":["trace[1288935460] 'process raft request' (duration: 172.579206ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:12.763409Z","caller":"traceutil/trace.go:171","msg":"trace[562695407] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"173.091948ms","start":"2026-04-07T00:11:12.590286Z","end":"2026-04-07T00:11:12.763378Z","steps":["trace[562695407] 'process raft request' (duration: 172.900694ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:12.763496Z","caller":"traceutil/trace.go:171","msg":"trace[787383213] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"170.300521ms","start":"2026-04-07T00:11:12.593156Z","end":"2026-04-07T00:11:12.763456Z","steps":["trace[787383213] 'process raft request' (duration: 170.234089ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:12.763525Z","caller":"traceutil/trace.go:171","msg":"trace[1795753350] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"172.976215ms","start":"2026-04-07T00:11:12.590527Z","end":"2026-04-07T00:11:12.763503Z","steps":["trace[1795753350] 'process raft request' (duration: 172.77125ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:12.779002Z","caller":"traceutil/trace.go:171","msg":"trace[736197086] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"179.42754ms","start":"2026-04-07T00:11:12.599553Z","end":"2026-04-07T00:11:12.77898Z","steps":["trace[736197086] 'process raft request' (duration: 179.126682ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:12.779055Z","caller":"traceutil/trace.go:171","msg":"trace[1823050953] transaction","detail":"{read_only:false; number_of_response:1; response_revision:517; }","duration":"178.275881ms","start":"2026-04-07T00:11:12.600738Z","end":"2026-04-07T00:11:12.779014Z","steps":["trace[1823050953] 'process raft request' (duration: 178.092347ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:12.779076Z","caller":"traceutil/trace.go:171","msg":"trace[1565260422] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"171.888099ms","start":"2026-04-07T00:11:12.607178Z","end":"2026-04-07T00:11:12.779067Z","steps":["trace[1565260422] 'process raft request' (duration: 171.686184ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:12.779019Z","caller":"traceutil/trace.go:171","msg":"trace[1362214489] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"178.281381ms","start":"2026-04-07T00:11:12.600706Z","end":"2026-04-07T00:11:12.778987Z","steps":["trace[1362214489] 'process raft request' (duration: 178.076866ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:12.779202Z","caller":"traceutil/trace.go:171","msg":"trace[91886122] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"139.382211ms","start":"2026-04-07T00:11:12.639806Z","end":"2026-04-07T00:11:12.779188Z","steps":["trace[91886122] 'process raft request' (duration: 139.102575ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:12.779304Z","caller":"traceutil/trace.go:171","msg":"trace[868686940] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:526; }","duration":"163.097848ms","start":"2026-04-07T00:11:12.61603Z","end":"2026-04-07T00:11:12.779127Z","steps":["trace[868686940] 'read index received' (duration: 146.171434ms)","trace[868686940] 'applied index is now lower than readState.Index' (duration: 16.925734ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:12.779461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.391186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T00:11:12.779493Z","caller":"traceutil/trace.go:171","msg":"trace[1628274872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:520; }","duration":"163.504669ms","start":"2026-04-07T00:11:12.615979Z","end":"2026-04-07T00:11:12.779484Z","steps":["trace[1628274872] 'agreement among raft nodes before linearized reading' (duration: 163.356875ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:18.921651Z","caller":"traceutil/trace.go:171","msg":"trace[635504490] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"186.328493ms","start":"2026-04-07T00:11:18.735294Z","end":"2026-04-07T00:11:18.921623Z","steps":["trace[635504490] 'process raft request' (duration: 185.547605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:11:19.11278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.496915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:11:19.112939Z","caller":"traceutil/trace.go:171","msg":"trace[1176269364] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"164.192525ms","start":"2026-04-07T00:11:18.948724Z","end":"2026-04-07T00:11:19.112917Z","steps":["trace[1176269364] 'read index received' (duration: 29.452314ms)","trace[1176269364] 'applied index is now lower than readState.Index' (duration: 134.738301ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:11:19.112978Z","caller":"traceutil/trace.go:171","msg":"trace[178047621] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"186.714152ms","start":"2026-04-07T00:11:18.926227Z","end":"2026-04-07T00:11:19.112941Z","steps":["trace[178047621] 'process raft request' (duration: 51.93368ms)","trace[178047621] 'compare' (duration: 134.325061ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:19.113026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.293717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:11:19.113083Z","caller":"traceutil/trace.go:171","msg":"trace[540213954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"164.368808ms","start":"2026-04-07T00:11:18.948697Z","end":"2026-04-07T00:11:19.113066Z","steps":["trace[540213954] 'agreement among raft nodes before linearized reading' (duration: 164.282186ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:19.325983Z","caller":"traceutil/trace.go:171","msg":"trace[214233818] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"334.999657ms","start":"2026-04-07T00:11:18.990958Z","end":"2026-04-07T00:11:19.325958Z","steps":["trace[214233818] 'process raft request' (duration: 334.948795ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:19.326115Z","caller":"traceutil/trace.go:171","msg":"trace[1877995480] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"374.214504ms","start":"2026-04-07T00:11:18.951807Z","end":"2026-04-07T00:11:19.326022Z","steps":["trace[1877995480] 'process raft request' (duration: 295.576035ms)","trace[1877995480] 'compare' (duration: 78.334752ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:11:19.326306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:18.990928Z","time spent":"335.241913ms","remote":"127.0.0.1:59748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:11:19.326621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:11:18.951792Z","time spent":"374.681747ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:11:19.745868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.549036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ft857\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-07T00:11:19.745943Z","caller":"traceutil/trace.go:171","msg":"trace[1746671251] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ft857; range_end:; response_count:1; response_revision:617; }","duration":"134.6799ms","start":"2026-04-07T00:11:19.611248Z","end":"2026-04-07T00:11:19.745928Z","steps":["trace[1746671251] 'range keys from in-memory index tree' (duration: 134.377213ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:05.694976Z","caller":"traceutil/trace.go:171","msg":"trace[997223685] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"137.766114ms","start":"2026-04-07T00:12:05.557165Z","end":"2026-04-07T00:12:05.694931Z","steps":["trace[997223685] 'process raft request' (duration: 137.489868ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:53.215587Z","caller":"traceutil/trace.go:171","msg":"trace[1914549027] linearizableReadLoop","detail":"{readStateIndex:1950; appliedIndex:1949; }","duration":"110.032505ms","start":"2026-04-07T00:12:53.105508Z","end":"2026-04-07T00:12:53.215541Z","steps":["trace[1914549027] 'read index received' (duration: 91.059052ms)","trace[1914549027] 'applied index is now lower than readState.Index' (duration: 18.972633ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:12:53.215683Z","caller":"traceutil/trace.go:171","msg":"trace[1078196293] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"128.881205ms","start":"2026-04-07T00:12:53.086759Z","end":"2026-04-07T00:12:53.21564Z","steps":["trace[1078196293] 'process raft request' (duration: 109.867491ms)","trace[1078196293] 'compare' (duration: 18.801399ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:12:53.215845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.320902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:10948"} {"level":"info","ts":"2026-04-07T00:12:53.215901Z","caller":"traceutil/trace.go:171","msg":"trace[1417213753] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1892; }","duration":"110.416834ms","start":"2026-04-07T00:12:53.10547Z","end":"2026-04-07T00:12:53.215887Z","steps":["trace[1417213753] 'agreement among raft nodes before linearized reading' (duration: 110.23916ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:53.337998Z","caller":"traceutil/trace.go:171","msg":"trace[1295969846] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"110.306923ms","start":"2026-04-07T00:12:53.227579Z","end":"2026-04-07T00:12:53.337886Z","steps":["trace[1295969846] 'process raft request' (duration: 110.044166ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:13:29.884648Z","caller":"traceutil/trace.go:171","msg":"trace[292474753] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"262.190683ms","start":"2026-04-07T00:13:29.622426Z","end":"2026-04-07T00:13:29.884617Z","steps":["trace[292474753] 'process raft request' (duration: 200.817217ms)","trace[292474753] 'compare' (duration: 61.141481ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:30.006044Z","caller":"traceutil/trace.go:171","msg":"trace[1939451271] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"116.983746ms","start":"2026-04-07T00:13:29.889036Z","end":"2026-04-07T00:13:30.00602Z","steps":["trace[1939451271] 'process raft request' (duration: 105.527465ms)","trace[1939451271] 'compare' (duration: 11.296858ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:30.006164Z","caller":"traceutil/trace.go:171","msg":"trace[2105942733] linearizableReadLoop","detail":"{readStateIndex:2232; appliedIndex:2230; }","duration":"114.400185ms","start":"2026-04-07T00:13:29.891742Z","end":"2026-04-07T00:13:30.006142Z","steps":["trace[2105942733] 'read index received' (duration: 102.80678ms)","trace[2105942733] 'applied index is now lower than readState.Index' (duration: 11.592245ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:13:30.006235Z","caller":"traceutil/trace.go:171","msg":"trace[939047503] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"116.118955ms","start":"2026-04-07T00:13:29.890106Z","end":"2026-04-07T00:13:30.006225Z","steps":["trace[939047503] 'process raft request' (duration: 115.88067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:13:30.006357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.586719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:13:30.006416Z","caller":"traceutil/trace.go:171","msg":"trace[2096107505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2160; }","duration":"114.696221ms","start":"2026-04-07T00:13:29.891705Z","end":"2026-04-07T00:13:30.006401Z","steps":["trace[2096107505] 'agreement among raft nodes before linearized reading' (duration: 114.574039ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:59.335101Z","caller":"traceutil/trace.go:171","msg":"trace[1062114730] transaction","detail":"{read_only:false; response_revision:4399; number_of_response:1; }","duration":"104.466444ms","start":"2026-04-07T00:16:59.23061Z","end":"2026-04-07T00:16:59.335076Z","steps":["trace[1062114730] 'process raft request' (duration: 104.223868ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:59.362634Z","caller":"traceutil/trace.go:171","msg":"trace[1114665388] transaction","detail":"{read_only:false; response_revision:4401; number_of_response:1; }","duration":"118.301269ms","start":"2026-04-07T00:16:59.244302Z","end":"2026-04-07T00:16:59.362603Z","steps":["trace[1114665388] 'process raft request' (duration: 118.133055ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:17:14.826949Z","caller":"traceutil/trace.go:171","msg":"trace[1908790441] linearizableReadLoop","detail":"{readStateIndex:4948; appliedIndex:4947; }","duration":"240.938374ms","start":"2026-04-07T00:17:14.585986Z","end":"2026-04-07T00:17:14.826925Z","steps":["trace[1908790441] 'read index received' (duration: 240.74259ms)","trace[1908790441] 'applied index is now lower than readState.Index' (duration: 194.804µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:17:14.827008Z","caller":"traceutil/trace.go:171","msg":"trace[303167265] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"279.796767ms","start":"2026-04-07T00:17:14.547179Z","end":"2026-04-07T00:17:14.826975Z","steps":["trace[303167265] 'process raft request' (duration: 279.603432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:17:14.827248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.224981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-04-07T00:17:14.827298Z","caller":"traceutil/trace.go:171","msg":"trace[329596612] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4747; }","duration":"241.346374ms","start":"2026-04-07T00:17:14.585932Z","end":"2026-04-07T00:17:14.827279Z","steps":["trace[329596612] 'agreement among raft nodes before linearized reading' (duration: 241.147049ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:21.597811Z","caller":"traceutil/trace.go:171","msg":"trace[1635371689] transaction","detail":"{read_only:false; response_revision:5554; number_of_response:1; }","duration":"113.459826ms","start":"2026-04-07T00:18:21.48433Z","end":"2026-04-07T00:18:21.59779Z","steps":["trace[1635371689] 'process raft request' (duration: 65.462424ms)","trace[1635371689] 'compare' (duration: 47.594302ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:20:44.045548Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3294} {"level":"info","ts":"2026-04-07T00:20:44.141432Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3294,"took":"94.145227ms","hash":3553560809,"current-db-size-bytes":61378560,"current-db-size":"61 MB","current-db-size-in-use-bytes":34029568,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-07T00:20:44.141503Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3553560809,"revision":3294,"compact-revision":-1} {"level":"info","ts":"2026-04-07T00:23:08.043173Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000020f1.wal"} {"level":"info","ts":"2026-04-07T00:23:12.344243Z","caller":"traceutil/trace.go:171","msg":"trace[1387009371] linearizableReadLoop","detail":"{readStateIndex:8466; appliedIndex:8465; }","duration":"215.561988ms","start":"2026-04-07T00:23:12.128658Z","end":"2026-04-07T00:23:12.34422Z","steps":["trace[1387009371] 'read index received' (duration: 189.501504ms)","trace[1387009371] 'applied index is now lower than readState.Index' (duration: 26.059464ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:23:12.344416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.743932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fdkgb\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-07T00:23:12.344441Z","caller":"traceutil/trace.go:171","msg":"trace[1175222460] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fdkgb; range_end:; response_count:1; response_revision:8108; }","duration":"215.823064ms","start":"2026-04-07T00:23:12.12861Z","end":"2026-04-07T00:23:12.344433Z","steps":["trace[1175222460] 'agreement among raft nodes before linearized reading' (duration: 215.690091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:23:23.632281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.009312ms","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-04-07T00:23:23.632383Z","caller":"traceutil/trace.go:171","msg":"trace[403649298] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:8185; }","duration":"153.142485ms","start":"2026-04-07T00:23:23.479221Z","end":"2026-04-07T00:23:23.632363Z","steps":["trace[403649298] 'count revisions from in-memory index tree' (duration: 152.887288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:23:23.632301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.162653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"warn","ts":"2026-04-07T00:23:23.632436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.669889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-07T00:23:23.632468Z","caller":"traceutil/trace.go:171","msg":"trace[785625582] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8185; }","duration":"211.366798ms","start":"2026-04-07T00:23:23.421074Z","end":"2026-04-07T00:23:23.632441Z","steps":["trace[785625582] 'range keys from in-memory index tree' (duration: 210.934308ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:23:23.632494Z","caller":"traceutil/trace.go:171","msg":"trace[1260962024] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8185; }","duration":"100.781842ms","start":"2026-04-07T00:23:23.5317Z","end":"2026-04-07T00:23:23.632482Z","steps":["trace[1260962024] 'range keys from in-memory index tree' (duration: 100.517126ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:23:23.884763Z","caller":"traceutil/trace.go:171","msg":"trace[1856551114] linearizableReadLoop","detail":"{readStateIndex:8550; appliedIndex:8549; }","duration":"251.13513ms","start":"2026-04-07T00:23:23.6336Z","end":"2026-04-07T00:23:23.884735Z","steps":["trace[1856551114] 'read index received' (duration: 250.990196ms)","trace[1856551114] 'applied index is now lower than readState.Index' (duration: 143.694µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:23:23.88488Z","caller":"traceutil/trace.go:171","msg":"trace[1391074655] transaction","detail":"{read_only:false; response_revision:8186; number_of_response:1; }","duration":"256.301383ms","start":"2026-04-07T00:23:23.628569Z","end":"2026-04-07T00:23:23.88487Z","steps":["trace[1391074655] 'process raft request' (duration: 255.980995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:23:23.885075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.459127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18201"} {"level":"info","ts":"2026-04-07T00:23:23.885137Z","caller":"traceutil/trace.go:171","msg":"trace[1950679004] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8186; }","duration":"251.536839ms","start":"2026-04-07T00:23:23.633585Z","end":"2026-04-07T00:23:23.885122Z","steps":["trace[1950679004] 'agreement among raft nodes before linearized reading' (duration: 251.422436ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:23:23.896085Z","caller":"traceutil/trace.go:171","msg":"trace[1795417184] transaction","detail":"{read_only:false; response_revision:8187; number_of_response:1; }","duration":"260.33814ms","start":"2026-04-07T00:23:23.635725Z","end":"2026-04-07T00:23:23.896063Z","steps":["trace[1795417184] 'process raft request' (duration: 260.242228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:23:23.896112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.030733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:23:23.896166Z","caller":"traceutil/trace.go:171","msg":"trace[709481366] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:8187; }","duration":"156.139426ms","start":"2026-04-07T00:23:23.740013Z","end":"2026-04-07T00:23:23.896153Z","steps":["trace[709481366] 'agreement among raft nodes before linearized reading' (duration: 156.029633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:23:24.616983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.374791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fdkgb\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-07T00:23:24.617074Z","caller":"traceutil/trace.go:171","msg":"trace[1762813145] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fdkgb; range_end:; response_count:1; response_revision:8190; }","duration":"209.519884ms","start":"2026-04-07T00:23:24.407532Z","end":"2026-04-07T00:23:24.617052Z","steps":["trace[1762813145] 'range keys from in-memory index tree' (duration: 209.124645ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:23:52.049482Z","caller":"traceutil/trace.go:171","msg":"trace[1956194054] transaction","detail":"{read_only:false; response_revision:8427; number_of_response:1; }","duration":"307.861297ms","start":"2026-04-07T00:23:51.741601Z","end":"2026-04-07T00:23:52.049462Z","steps":["trace[1956194054] 'process raft request' (duration: 307.718924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:23:52.049613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:23:51.741575Z","time spent":"307.977851ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:24:04.653265Z","caller":"traceutil/trace.go:171","msg":"trace[518662769] linearizableReadLoop","detail":"{readStateIndex:8935; appliedIndex:8934; }","duration":"123.031725ms","start":"2026-04-07T00:24:04.530181Z","end":"2026-04-07T00:24:04.653213Z","steps":["trace[518662769] 'read index received' (duration: 122.697827ms)","trace[518662769] 'applied index is now lower than readState.Index' (duration: 332.888µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:24:04.653337Z","caller":"traceutil/trace.go:171","msg":"trace[582202294] transaction","detail":"{read_only:false; response_revision:8553; number_of_response:1; }","duration":"159.573979ms","start":"2026-04-07T00:24:04.493726Z","end":"2026-04-07T00:24:04.6533Z","steps":["trace[582202294] 'process raft request' (duration: 159.278962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:24:04.653548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.356732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-04-07T00:24:04.653619Z","caller":"traceutil/trace.go:171","msg":"trace[1585411376] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8553; }","duration":"123.491965ms","start":"2026-04-07T00:24:04.530114Z","end":"2026-04-07T00:24:04.653606Z","steps":["trace[1585411376] 'agreement among raft nodes before linearized reading' (duration: 123.248399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:24:05.637123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.063264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:24:05.637224Z","caller":"traceutil/trace.go:171","msg":"trace[1879194985] transaction","detail":"{read_only:false; response_revision:8564; number_of_response:1; }","duration":"298.094803ms","start":"2026-04-07T00:24:05.33911Z","end":"2026-04-07T00:24:05.637204Z","steps":["trace[1879194985] 'process raft request' (duration: 62.865975ms)","trace[1879194985] 'compare' (duration: 234.866579ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:24:06.375155Z","caller":"traceutil/trace.go:171","msg":"trace[1032808181] linearizableReadLoop","detail":"{readStateIndex:8948; appliedIndex:8947; }","duration":"103.834285ms","start":"2026-04-07T00:24:06.271288Z","end":"2026-04-07T00:24:06.375122Z","steps":["trace[1032808181] 'read index received' (duration: 103.557939ms)","trace[1032808181] 'applied index is now lower than readState.Index' (duration: 274.896µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:24:06.375234Z","caller":"traceutil/trace.go:171","msg":"trace[231030010] transaction","detail":"{read_only:false; response_revision:8565; number_of_response:1; }","duration":"148.476483ms","start":"2026-04-07T00:24:06.226728Z","end":"2026-04-07T00:24:06.375204Z","steps":["trace[231030010] 'process raft request' (duration: 148.131985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:24:06.375516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.093831ms","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-04-07T00:24:06.375589Z","caller":"traceutil/trace.go:171","msg":"trace[2139137150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8565; }","duration":"104.321587ms","start":"2026-04-07T00:24:06.271248Z","end":"2026-04-07T00:24:06.375569Z","steps":["trace[2139137150] 'agreement among raft nodes before linearized reading' (duration: 104.004689ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:25:42.911963Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d1606b245347dc08","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T00:25:42.918785Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T00:25:42.918872Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T00:25:44.051593Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6654} {"level":"info","ts":"2026-04-07T00:25:44.150251Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6654,"took":"95.572909ms","hash":1821213406,"current-db-size-bytes":61378560,"current-db-size":"61 MB","current-db-size-in-use-bytes":27332608,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-07T00:25:44.150344Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1821213406,"revision":6654,"compact-revision":3294} {"level":"info","ts":"2026-04-07T00:28:41.651432Z","caller":"traceutil/trace.go:171","msg":"trace[735668053] transaction","detail":"{read_only:false; response_revision:11403; number_of_response:1; }","duration":"134.977715ms","start":"2026-04-07T00:28:41.51637Z","end":"2026-04-07T00:28:41.651347Z","steps":["trace[735668053] 'process raft request' (duration: 125.004978ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:30:44.057688Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9584} {"level":"info","ts":"2026-04-07T00:30:44.140446Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9584,"took":"80.252122ms","hash":2937956331,"current-db-size-bytes":61378560,"current-db-size":"61 MB","current-db-size-in-use-bytes":29261824,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-07T00:30:44.140524Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2937956331,"revision":9584,"compact-revision":6654} {"level":"info","ts":"2026-04-07T00:34:28.124482Z","caller":"traceutil/trace.go:171","msg":"trace[723076308] transaction","detail":"{read_only:false; response_revision:14250; number_of_response:1; }","duration":"118.778632ms","start":"2026-04-07T00:34:28.005658Z","end":"2026-04-07T00:34:28.124436Z","steps":["trace[723076308] 'process raft request' (duration: 118.522896ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:34:31.138172Z","caller":"traceutil/trace.go:171","msg":"trace[272754203] transaction","detail":"{read_only:false; response_revision:14274; number_of_response:1; }","duration":"125.529472ms","start":"2026-04-07T00:34:31.012617Z","end":"2026-04-07T00:34:31.138146Z","steps":["trace[272754203] 'process raft request' (duration: 125.297217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:35:06.146357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.613159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:35:06.146451Z","caller":"traceutil/trace.go:171","msg":"trace[1808857599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14525; }","duration":"257.729352ms","start":"2026-04-07T00:35:05.888701Z","end":"2026-04-07T00:35:06.14643Z","steps":["trace[1808857599] 'range keys from in-memory index tree' (duration: 257.467236ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:35:06.539922Z","caller":"traceutil/trace.go:171","msg":"trace[752165938] linearizableReadLoop","detail":"{readStateIndex:15198; appliedIndex:15197; }","duration":"216.493276ms","start":"2026-04-07T00:35:06.323401Z","end":"2026-04-07T00:35:06.539895Z","steps":["trace[752165938] 'read index received' (duration: 216.286521ms)","trace[752165938] 'applied index is now lower than readState.Index' (duration: 205.805µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:35:06.540012Z","caller":"traceutil/trace.go:171","msg":"trace[1076851346] transaction","detail":"{read_only:false; response_revision:14526; number_of_response:1; }","duration":"223.196744ms","start":"2026-04-07T00:35:06.3168Z","end":"2026-04-07T00:35:06.539997Z","steps":["trace[1076851346] 'process raft request' (duration: 222.875107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:35:06.54014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.718201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-07T00:35:06.540205Z","caller":"traceutil/trace.go:171","msg":"trace[830121553] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:14526; }","duration":"216.821014ms","start":"2026-04-07T00:35:06.323371Z","end":"2026-04-07T00:35:06.540192Z","steps":["trace[830121553] 'agreement among raft nodes before linearized reading' (duration: 216.65306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:35:06.566206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.543728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-07T00:35:06.566215Z","caller":"traceutil/trace.go:171","msg":"trace[481188242] transaction","detail":"{read_only:false; response_revision:14527; number_of_response:1; }","duration":"239.727877ms","start":"2026-04-07T00:35:06.326452Z","end":"2026-04-07T00:35:06.56618Z","steps":["trace[481188242] 'process raft request' (duration: 239.419049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:35:06.566263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.229305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T00:35:06.566279Z","caller":"traceutil/trace.go:171","msg":"trace[1049721166] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14527; }","duration":"159.655251ms","start":"2026-04-07T00:35:06.406604Z","end":"2026-04-07T00:35:06.566259Z","steps":["trace[1049721166] 'agreement among raft nodes before linearized reading' (duration: 159.433245ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:35:06.566318Z","caller":"traceutil/trace.go:171","msg":"trace[1191272172] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:14527; }","duration":"125.329618ms","start":"2026-04-07T00:35:06.440975Z","end":"2026-04-07T00:35:06.566305Z","steps":["trace[1191272172] 'agreement among raft nodes before linearized reading' (duration: 125.211185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:35:06.566521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.453937ms","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-04-07T00:35:06.566826Z","caller":"traceutil/trace.go:171","msg":"trace[1587504061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14527; }","duration":"154.832956ms","start":"2026-04-07T00:35:06.411976Z","end":"2026-04-07T00:35:06.566809Z","steps":["trace[1587504061] 'agreement among raft nodes before linearized reading' (duration: 154.237052ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:35:44.0637Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12447} {"level":"info","ts":"2026-04-07T00:35:44.16325Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12447,"took":"97.53682ms","hash":2990342757,"current-db-size-bytes":61378560,"current-db-size":"61 MB","current-db-size-in-use-bytes":26361856,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-07T00:35:44.163319Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2990342757,"revision":12447,"compact-revision":9584} {"level":"info","ts":"2026-04-07T00:36:49.305153Z","caller":"traceutil/trace.go:171","msg":"trace[89006176] linearizableReadLoop","detail":"{readStateIndex:16477; appliedIndex:16476; }","duration":"188.665274ms","start":"2026-04-07T00:36:49.116442Z","end":"2026-04-07T00:36:49.305108Z","steps":["trace[89006176] 'read index received' (duration: 188.423928ms)","trace[89006176] 'applied index is now lower than readState.Index' (duration: 240.196µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:36:49.305197Z","caller":"traceutil/trace.go:171","msg":"trace[1342618268] transaction","detail":"{read_only:false; response_revision:15751; number_of_response:1; }","duration":"192.577517ms","start":"2026-04-07T00:36:49.11258Z","end":"2026-04-07T00:36:49.305158Z","steps":["trace[1342618268] 'process raft request' (duration: 192.330301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:36:49.305376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.90991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:36:49.305464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.452039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:36:49.305415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.560531ms","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-04-07T00:36:49.305515Z","caller":"traceutil/trace.go:171","msg":"trace[1321075753] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15751; }","duration":"188.699964ms","start":"2026-04-07T00:36:49.116806Z","end":"2026-04-07T00:36:49.305506Z","steps":["trace[1321075753] 'agreement among raft nodes before linearized reading' (duration: 188.545831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:36:49.305595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.095411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5520"} {"level":"info","ts":"2026-04-07T00:36:49.305691Z","caller":"traceutil/trace.go:171","msg":"trace[1095783408] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15751; }","duration":"188.211763ms","start":"2026-04-07T00:36:49.117455Z","end":"2026-04-07T00:36:49.305667Z","steps":["trace[1095783408] 'agreement among raft nodes before linearized reading' (duration: 187.950127ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:36:49.305473Z","caller":"traceutil/trace.go:171","msg":"trace[406659667] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15751; }","duration":"189.053863ms","start":"2026-04-07T00:36:49.116395Z","end":"2026-04-07T00:36:49.305449Z","steps":["trace[406659667] 'agreement among raft nodes before linearized reading' (duration: 188.852528ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:36:49.305516Z","caller":"traceutil/trace.go:171","msg":"trace[1233574483] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15751; }","duration":"188.542431ms","start":"2026-04-07T00:36:49.11696Z","end":"2026-04-07T00:36:49.305502Z","steps":["trace[1233574483] 'agreement among raft nodes before linearized reading' (duration: 188.48201ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:36:49.45492Z","caller":"traceutil/trace.go:171","msg":"trace[1891814624] transaction","detail":"{read_only:false; response_revision:15752; number_of_response:1; }","duration":"102.941582ms","start":"2026-04-07T00:36:49.35195Z","end":"2026-04-07T00:36:49.454891Z","steps":["trace[1891814624] 'process raft request' (duration: 102.719165ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:36:49.545765Z","caller":"traceutil/trace.go:171","msg":"trace[1229816210] transaction","detail":"{read_only:false; response_revision:15753; number_of_response:1; }","duration":"161.88285ms","start":"2026-04-07T00:36:49.383852Z","end":"2026-04-07T00:36:49.545735Z","steps":["trace[1229816210] 'process raft request' (duration: 152.137259ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:36:49.70688Z","caller":"traceutil/trace.go:171","msg":"trace[245527230] transaction","detail":"{read_only:false; response_revision:15754; number_of_response:1; }","duration":"136.685412ms","start":"2026-04-07T00:36:49.570167Z","end":"2026-04-07T00:36:49.706852Z","steps":["trace[245527230] 'process raft request' (duration: 136.500407ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:40:44.07141Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14995} {"level":"info","ts":"2026-04-07T00:40:44.160854Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14995,"took":"86.330593ms","hash":4018860150,"current-db-size-bytes":61378560,"current-db-size":"61 MB","current-db-size-in-use-bytes":32641024,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-07T00:40:44.160933Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4018860150,"revision":14995,"compact-revision":12447} {"level":"info","ts":"2026-04-07T00:41:44.492549Z","caller":"traceutil/trace.go:171","msg":"trace[443582485] transaction","detail":"{read_only:false; response_revision:18463; number_of_response:1; }","duration":"123.2272ms","start":"2026-04-07T00:41:44.369301Z","end":"2026-04-07T00:41:44.492528Z","steps":["trace[443582485] 'process raft request' (duration: 98.702488ms)","trace[443582485] 'compare' (duration: 23.60421ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:41:49.969741Z","caller":"traceutil/trace.go:171","msg":"trace[130747034] linearizableReadLoop","detail":"{readStateIndex:19364; appliedIndex:19363; }","duration":"252.972906ms","start":"2026-04-07T00:41:49.716752Z","end":"2026-04-07T00:41:49.969725Z","steps":["trace[130747034] 'read index received' (duration: 252.841993ms)","trace[130747034] 'applied index is now lower than readState.Index' (duration: 129.923µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:41:49.96984Z","caller":"traceutil/trace.go:171","msg":"trace[38789053] transaction","detail":"{read_only:false; response_revision:18502; number_of_response:1; }","duration":"254.125742ms","start":"2026-04-07T00:41:49.715707Z","end":"2026-04-07T00:41:49.969833Z","steps":["trace[38789053] 'process raft request' (duration: 253.899447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:41:49.97021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.433937ms","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-04-07T00:41:49.970278Z","caller":"traceutil/trace.go:171","msg":"trace[1990359262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18502; }","duration":"253.516069ms","start":"2026-04-07T00:41:49.716748Z","end":"2026-04-07T00:41:49.970264Z","steps":["trace[1990359262] 'agreement among raft nodes before linearized reading' (duration: 253.365025ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:42:32.896953Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d1606b245347dc08","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T00:42:32.901835Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-07T00:42:32.901935Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-07T00:45:44.07811Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17932} {"level":"info","ts":"2026-04-07T00:45:44.184934Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17932,"took":"104.458457ms","hash":910796058,"current-db-size-bytes":61378560,"current-db-size":"61 MB","current-db-size-in-use-bytes":33140736,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-07T00:45:44.184997Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":910796058,"revision":17932,"compact-revision":14995} {"level":"info","ts":"2026-04-07T00:49:13.706993Z","caller":"traceutil/trace.go:171","msg":"trace[1957416176] transaction","detail":"{read_only:false; response_revision:22802; number_of_response:1; }","duration":"104.100017ms","start":"2026-04-07T00:49:13.602856Z","end":"2026-04-07T00:49:13.706956Z","steps":["trace[1957416176] 'process raft request' (duration: 103.865602ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:49:24.123414Z","caller":"traceutil/trace.go:171","msg":"trace[1148440502] transaction","detail":"{read_only:false; response_revision:22869; number_of_response:1; }","duration":"108.509283ms","start":"2026-04-07T00:49:24.014857Z","end":"2026-04-07T00:49:24.123367Z","steps":["trace[1148440502] 'process raft request' (duration: 97.590683ms)","trace[1148440502] 'compare' (duration: 10.496059ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:49:29.922547Z","caller":"traceutil/trace.go:171","msg":"trace[425177517] transaction","detail":"{read_only:false; response_revision:22906; number_of_response:1; }","duration":"233.056257ms","start":"2026-04-07T00:49:29.689458Z","end":"2026-04-07T00:49:29.922515Z","steps":["trace[425177517] 'process raft request' (duration: 232.781691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:49:30.173165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.646942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:49:30.173482Z","caller":"traceutil/trace.go:171","msg":"trace[1704816337] transaction","detail":"{read_only:false; response_revision:22907; number_of_response:1; }","duration":"208.126473ms","start":"2026-04-07T00:49:29.965322Z","end":"2026-04-07T00:49:30.173449Z","steps":["trace[1704816337] 'process raft request' (duration: 71.109052ms)","trace[1704816337] 'compare' (duration: 136.52819ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:49:30.329667Z","caller":"traceutil/trace.go:171","msg":"trace[595725383] transaction","detail":"{read_only:false; response_revision:22908; number_of_response:1; }","duration":"148.673559ms","start":"2026-04-07T00:49:30.180954Z","end":"2026-04-07T00:49:30.329628Z","steps":["trace[595725383] 'process raft request' (duration: 120.810605ms)","trace[595725383] 'compare' (duration: 27.685059ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:49:30.596246Z","caller":"traceutil/trace.go:171","msg":"trace[2070851035] transaction","detail":"{read_only:false; response_revision:22909; number_of_response:1; }","duration":"261.867342ms","start":"2026-04-07T00:49:30.334353Z","end":"2026-04-07T00:49:30.596221Z","steps":["trace[2070851035] 'process raft request' (duration: 261.673578ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:49:30.604379Z","caller":"traceutil/trace.go:171","msg":"trace[1739127541] linearizableReadLoop","detail":"{readStateIndex:23980; appliedIndex:23977; }","duration":"198.572016ms","start":"2026-04-07T00:49:30.405782Z","end":"2026-04-07T00:49:30.604354Z","steps":["trace[1739127541] 'read index received' (duration: 190.153286ms)","trace[1739127541] 'applied index is now lower than readState.Index' (duration: 8.4176ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:49:30.604492Z","caller":"traceutil/trace.go:171","msg":"trace[1216146094] transaction","detail":"{read_only:false; response_revision:22911; number_of_response:1; }","duration":"265.106629ms","start":"2026-04-07T00:49:30.339377Z","end":"2026-04-07T00:49:30.604484Z","steps":["trace[1216146094] 'process raft request' (duration: 264.917985ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:49:30.604429Z","caller":"traceutil/trace.go:171","msg":"trace[1482079744] transaction","detail":"{read_only:false; response_revision:22910; number_of_response:1; }","duration":"269.947165ms","start":"2026-04-07T00:49:30.334443Z","end":"2026-04-07T00:49:30.60439Z","steps":["trace[1482079744] 'process raft request' (duration: 269.707679ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:49:30.604677Z","caller":"traceutil/trace.go:171","msg":"trace[1355959724] transaction","detail":"{read_only:false; response_revision:22912; number_of_response:1; }","duration":"174.078863ms","start":"2026-04-07T00:49:30.430591Z","end":"2026-04-07T00:49:30.60467Z","steps":["trace[1355959724] 'process raft request' (duration: 173.731845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:49:30.604843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.062848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:49:30.604876Z","caller":"traceutil/trace.go:171","msg":"trace[2015313938] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:22912; }","duration":"199.17638ms","start":"2026-04-07T00:49:30.40569Z","end":"2026-04-07T00:49:30.604866Z","steps":["trace[2015313938] 'agreement among raft nodes before linearized reading' (duration: 199.070587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:49:30.605001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.463503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:49:30.605027Z","caller":"traceutil/trace.go:171","msg":"trace[1501209408] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:22912; }","duration":"140.530465ms","start":"2026-04-07T00:49:30.464489Z","end":"2026-04-07T00:49:30.605019Z","steps":["trace[1501209408] 'agreement among raft nodes before linearized reading' (duration: 140.487894ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:50:44.083972Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21089} {"level":"info","ts":"2026-04-07T00:50:44.210486Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21089,"took":"123.656123ms","hash":761445589,"current-db-size-bytes":61378560,"current-db-size":"61 MB","current-db-size-in-use-bytes":33505280,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-07T00:50:44.210563Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":761445589,"revision":21089,"compact-revision":17932} {"level":"info","ts":"2026-04-07T00:52:13.26156Z","caller":"traceutil/trace.go:171","msg":"trace[1456900797] transaction","detail":"{read_only:false; response_revision:24473; number_of_response:1; }","duration":"206.439713ms","start":"2026-04-07T00:52:13.055097Z","end":"2026-04-07T00:52:13.261537Z","steps":["trace[1456900797] 'process raft request' (duration: 206.31651ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:13.383676Z","caller":"traceutil/trace.go:171","msg":"trace[1936281170] transaction","detail":"{read_only:false; number_of_response:0; response_revision:24474; }","duration":"114.602408ms","start":"2026-04-07T00:52:13.269048Z","end":"2026-04-07T00:52:13.38365Z","steps":["trace[1936281170] 'process raft request' (duration: 114.300401ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:13.60799Z","caller":"traceutil/trace.go:171","msg":"trace[1698143881] linearizableReadLoop","detail":"{readStateIndex:25622; appliedIndex:25621; }","duration":"169.111525ms","start":"2026-04-07T00:52:13.438852Z","end":"2026-04-07T00:52:13.607964Z","steps":["trace[1698143881] 'read index received' (duration: 168.890089ms)","trace[1698143881] 'applied index is now lower than readState.Index' (duration: 220.676µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:52:13.608075Z","caller":"traceutil/trace.go:171","msg":"trace[1528827541] transaction","detail":"{read_only:false; response_revision:24475; number_of_response:1; }","duration":"207.121849ms","start":"2026-04-07T00:52:13.400922Z","end":"2026-04-07T00:52:13.608043Z","steps":["trace[1528827541] 'process raft request' (duration: 206.883484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:13.608215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.3433ms","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-04-07T00:52:13.608255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.910748ms","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-04-07T00:52:13.608279Z","caller":"traceutil/trace.go:171","msg":"trace[763546850] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24475; }","duration":"169.460263ms","start":"2026-04-07T00:52:13.438804Z","end":"2026-04-07T00:52:13.608265Z","steps":["trace[763546850] 'agreement among raft nodes before linearized reading' (duration: 169.263758ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:13.60829Z","caller":"traceutil/trace.go:171","msg":"trace[1979976692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24475; }","duration":"166.000981ms","start":"2026-04-07T00:52:13.442279Z","end":"2026-04-07T00:52:13.60828Z","steps":["trace[1979976692] 'agreement among raft nodes before linearized reading' (duration: 165.826867ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:13.906275Z","caller":"traceutil/trace.go:171","msg":"trace[1274629724] linearizableReadLoop","detail":"{readStateIndex:25626; appliedIndex:25624; }","duration":"196.470877ms","start":"2026-04-07T00:52:13.709769Z","end":"2026-04-07T00:52:13.90624Z","steps":["trace[1274629724] 'read index received' (duration: 50.039901ms)","trace[1274629724] 'applied index is now lower than readState.Index' (duration: 146.429546ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:52:13.906519Z","caller":"traceutil/trace.go:171","msg":"trace[153648983] transaction","detail":"{read_only:false; response_revision:24479; number_of_response:1; }","duration":"202.129701ms","start":"2026-04-07T00:52:13.704314Z","end":"2026-04-07T00:52:13.906444Z","steps":["trace[153648983] 'process raft request' (duration: 163.251115ms)","trace[153648983] 'compare' (duration: 38.478417ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:52:13.906573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.650982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:52:13.906694Z","caller":"traceutil/trace.go:171","msg":"trace[559572756] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:24479; }","duration":"141.819245ms","start":"2026-04-07T00:52:13.764844Z","end":"2026-04-07T00:52:13.906663Z","steps":["trace[559572756] 'agreement among raft nodes before linearized reading' (duration: 141.6112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:52:13.907227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.45001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4831"} {"level":"info","ts":"2026-04-07T00:52:13.907286Z","caller":"traceutil/trace.go:171","msg":"trace[324612993] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24479; }","duration":"197.565612ms","start":"2026-04-07T00:52:13.709704Z","end":"2026-04-07T00:52:13.90727Z","steps":["trace[324612993] 'agreement among raft nodes before linearized reading' (duration: 197.385758ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:23.74894Z","caller":"traceutil/trace.go:171","msg":"trace[1023866066] transaction","detail":"{read_only:false; response_revision:24634; number_of_response:1; }","duration":"142.922052ms","start":"2026-04-07T00:52:23.605967Z","end":"2026-04-07T00:52:23.748889Z","steps":["trace[1023866066] 'process raft request' (duration: 142.739067ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:17.699615Z","caller":"traceutil/trace.go:171","msg":"trace[193911546] transaction","detail":"{read_only:false; response_revision:25615; number_of_response:1; }","duration":"109.786663ms","start":"2026-04-07T00:53:17.589778Z","end":"2026-04-07T00:53:17.699564Z","steps":["trace[193911546] 'process raft request' (duration: 109.481205ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:36.365214Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006980.wal"} {"level":"info","ts":"2026-04-07T00:55:05.490205Z","caller":"traceutil/trace.go:171","msg":"trace[1205932620] transaction","detail":"{read_only:false; response_revision:26898; number_of_response:1; }","duration":"161.605305ms","start":"2026-04-07T00:55:05.328576Z","end":"2026-04-07T00:55:05.490181Z","steps":["trace[1205932620] 'process raft request' (duration: 161.41459ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:44.090838Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23720} {"level":"info","ts":"2026-04-07T00:55:44.213059Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23720,"took":"118.219502ms","hash":855019243,"current-db-size-bytes":61378560,"current-db-size":"61 MB","current-db-size-in-use-bytes":41967616,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-04-07T00:55:44.213135Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":855019243,"revision":23720,"compact-revision":21089} {"level":"warn","ts":"2026-04-07T00:56:47.210733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.061176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:56:47.210909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.837282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:56:47.211856Z","caller":"traceutil/trace.go:171","msg":"trace[591615211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28069; }","duration":"321.202374ms","start":"2026-04-07T00:56:46.890613Z","end":"2026-04-07T00:56:47.211816Z","steps":["trace[591615211] 'range keys from in-memory index tree' (duration: 319.912252ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:47.211908Z","caller":"traceutil/trace.go:171","msg":"trace[949905098] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:28069; }","duration":"260.863997ms","start":"2026-04-07T00:56:46.951014Z","end":"2026-04-07T00:56:47.211878Z","steps":["trace[949905098] 'count revisions from in-memory index tree' (duration: 259.718889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:47.210974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.273524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.35\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-04-07T00:56:47.211969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:56:46.890593Z","time spent":"321.323666ms","remote":"127.0.0.1:45098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T00:56:47.211982Z","caller":"traceutil/trace.go:171","msg":"trace[1253753990] range","detail":"{range_begin:/registry/masterleases/199.19.213.35; range_end:; response_count:1; response_revision:28069; }","duration":"106.31373ms","start":"2026-04-07T00:56:47.10564Z","end":"2026-04-07T00:56:47.211953Z","steps":["trace[1253753990] 'range keys from in-memory index tree' (duration: 105.08018ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:47.659799Z","caller":"traceutil/trace.go:171","msg":"trace[975238722] transaction","detail":"{read_only:false; response_revision:28075; number_of_response:1; }","duration":"216.290168ms","start":"2026-04-07T00:56:47.443478Z","end":"2026-04-07T00:56:47.659768Z","steps":["trace[975238722] 'process raft request' (duration: 216.140444ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:52.237362Z","caller":"traceutil/trace.go:171","msg":"trace[1103400859] linearizableReadLoop","detail":"{readStateIndex:29398; appliedIndex:29397; }","duration":"201.258349ms","start":"2026-04-07T00:56:52.036075Z","end":"2026-04-07T00:56:52.237334Z","steps":["trace[1103400859] 'read index received' (duration: 201.153716ms)","trace[1103400859] 'applied index is now lower than readState.Index' (duration: 103.523µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:56:52.237426Z","caller":"traceutil/trace.go:171","msg":"trace[778655644] transaction","detail":"{read_only:false; response_revision:28115; number_of_response:1; }","duration":"204.882285ms","start":"2026-04-07T00:56:52.032503Z","end":"2026-04-07T00:56:52.237385Z","steps":["trace[778655644] 'process raft request' (duration: 204.6622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:52.23752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.422213ms","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:6"} {"level":"info","ts":"2026-04-07T00:56:52.237585Z","caller":"traceutil/trace.go:171","msg":"trace[1247032352] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:28115; }","duration":"201.529215ms","start":"2026-04-07T00:56:52.036033Z","end":"2026-04-07T00:56:52.237562Z","steps":["trace[1247032352] 'agreement among raft nodes before linearized reading' (duration: 201.418403ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:52.297486Z","caller":"traceutil/trace.go:171","msg":"trace[1277976278] transaction","detail":"{read_only:false; response_revision:28116; number_of_response:1; }","duration":"188.649319ms","start":"2026-04-07T00:56:52.108804Z","end":"2026-04-07T00:56:52.297453Z","steps":["trace[1277976278] 'process raft request' (duration: 180.479214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:52.420225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.768871ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-04-07T00:56:52.420405Z","caller":"traceutil/trace.go:171","msg":"trace[210233582] linearizableReadLoop","detail":"{readStateIndex:29400; appliedIndex:29398; }","duration":"126.672454ms","start":"2026-04-07T00:56:52.293708Z","end":"2026-04-07T00:56:52.420381Z","steps":["trace[210233582] 'read index received' (duration: 54.632µs)","trace[210233582] 'applied index is now lower than readState.Index' (duration: 126.615652ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:56:52.420685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.971601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-04-07T00:56:52.42073Z","caller":"traceutil/trace.go:171","msg":"trace[606163748] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28116; }","duration":"127.070773ms","start":"2026-04-07T00:56:52.293644Z","end":"2026-04-07T00:56:52.420714Z","steps":["trace[606163748] 'agreement among raft nodes before linearized reading' (duration: 126.791116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:52.710538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.323097ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:56:52.710639Z","caller":"traceutil/trace.go:171","msg":"trace[476229045] linearizableReadLoop","detail":"{readStateIndex:29402; appliedIndex:29401; }","duration":"112.978889ms","start":"2026-04-07T00:56:52.597642Z","end":"2026-04-07T00:56:52.710621Z","steps":["trace[476229045] 'read index received' (duration: 69.002µs)","trace[476229045] 'applied index is now lower than readState.Index' (duration: 112.908417ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:56:52.710713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.064221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:56:52.710712Z","caller":"traceutil/trace.go:171","msg":"trace[1678406709] transaction","detail":"{read_only:false; response_revision:28118; number_of_response:1; }","duration":"269.688688ms","start":"2026-04-07T00:56:52.440993Z","end":"2026-04-07T00:56:52.710682Z","steps":["trace[1678406709] 'process raft request' (duration: 127.133856ms)","trace[1678406709] 'compare' (duration: 141.698261ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:56:52.71075Z","caller":"traceutil/trace.go:171","msg":"trace[1370190547] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:28118; }","duration":"113.137393ms","start":"2026-04-07T00:56:52.597602Z","end":"2026-04-07T00:56:52.710739Z","steps":["trace[1370190547] 'agreement among raft nodes before linearized reading' (duration: 113.065291ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:52.874539Z","caller":"traceutil/trace.go:171","msg":"trace[1938233439] transaction","detail":"{read_only:false; response_revision:28119; number_of_response:1; }","duration":"276.645893ms","start":"2026-04-07T00:56:52.597867Z","end":"2026-04-07T00:56:52.874513Z","steps":["trace[1938233439] 'process raft request' (duration: 276.491799ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:52.948871Z","caller":"traceutil/trace.go:171","msg":"trace[819105530] transaction","detail":"{read_only:false; response_revision:28120; number_of_response:1; }","duration":"272.513475ms","start":"2026-04-07T00:56:52.676321Z","end":"2026-04-07T00:56:52.948834Z","steps":["trace[819105530] 'process raft request' (duration: 272.28698ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:52.948935Z","caller":"traceutil/trace.go:171","msg":"trace[87034898] transaction","detail":"{read_only:false; response_revision:28121; number_of_response:1; }","duration":"186.224592ms","start":"2026-04-07T00:56:52.76268Z","end":"2026-04-07T00:56:52.948905Z","steps":["trace[87034898] 'process raft request' (duration: 186.076428ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:53.522328Z","caller":"traceutil/trace.go:171","msg":"trace[1475272774] linearizableReadLoop","detail":"{readStateIndex:29407; appliedIndex:29406; }","duration":"131.685613ms","start":"2026-04-07T00:56:53.390615Z","end":"2026-04-07T00:56:53.522301Z","steps":["trace[1475272774] 'read index received' (duration: 119.417122ms)","trace[1475272774] 'applied index is now lower than readState.Index' (duration: 12.267551ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:56:53.522406Z","caller":"traceutil/trace.go:171","msg":"trace[526294910] transaction","detail":"{read_only:false; response_revision:28123; number_of_response:1; }","duration":"137.528823ms","start":"2026-04-07T00:56:53.384842Z","end":"2026-04-07T00:56:53.522371Z","steps":["trace[526294910] 'process raft request' (duration: 125.331743ms)","trace[526294910] 'compare' (duration: 11.887032ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:56:53.522647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.016945ms","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-04-07T00:56:53.522734Z","caller":"traceutil/trace.go:171","msg":"trace[1537039155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28123; }","duration":"125.127557ms","start":"2026-04-07T00:56:53.397582Z","end":"2026-04-07T00:56:53.522709Z","steps":["trace[1537039155] 'agreement among raft nodes before linearized reading' (duration: 124.942223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:53.5228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.177396ms","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-04-07T00:56:53.522838Z","caller":"traceutil/trace.go:171","msg":"trace[589330649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28123; }","duration":"132.268018ms","start":"2026-04-07T00:56:53.390563Z","end":"2026-04-07T00:56:53.522831Z","steps":["trace[589330649] 'agreement among raft nodes before linearized reading' (duration: 132.126305ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:53.720093Z","caller":"traceutil/trace.go:171","msg":"trace[1445379508] transaction","detail":"{read_only:false; response_revision:28124; number_of_response:1; }","duration":"194.059087ms","start":"2026-04-07T00:56:53.52601Z","end":"2026-04-07T00:56:53.720069Z","steps":["trace[1445379508] 'process raft request' (duration: 125.475776ms)","trace[1445379508] 'compare' (duration: 68.291375ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:56:53.755246Z","caller":"traceutil/trace.go:171","msg":"trace[157485588] transaction","detail":"{read_only:false; response_revision:28125; number_of_response:1; }","duration":"228.487397ms","start":"2026-04-07T00:56:53.526741Z","end":"2026-04-07T00:56:53.755228Z","steps":["trace[157485588] 'process raft request' (duration: 228.339054ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:53.928404Z","caller":"traceutil/trace.go:171","msg":"trace[1811605146] linearizableReadLoop","detail":"{readStateIndex:29413; appliedIndex:29409; }","duration":"120.858636ms","start":"2026-04-07T00:56:53.807516Z","end":"2026-04-07T00:56:53.928375Z","steps":["trace[1811605146] 'read index received' (duration: 3.013112ms)","trace[1811605146] 'applied index is now lower than readState.Index' (duration: 117.844404ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:56:53.928565Z","caller":"traceutil/trace.go:171","msg":"trace[1432705927] transaction","detail":"{read_only:false; response_revision:28129; number_of_response:1; }","duration":"166.359858ms","start":"2026-04-07T00:56:53.762185Z","end":"2026-04-07T00:56:53.928545Z","steps":["trace[1432705927] 'process raft request' (duration: 166.057781ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:53.928669Z","caller":"traceutil/trace.go:171","msg":"trace[1913421560] transaction","detail":"{read_only:false; response_revision:28128; number_of_response:1; }","duration":"168.485769ms","start":"2026-04-07T00:56:53.760155Z","end":"2026-04-07T00:56:53.928641Z","steps":["trace[1913421560] 'process raft request' (duration: 166.472582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:53.928799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.263126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:56:53.928869Z","caller":"traceutil/trace.go:171","msg":"trace[1509748747] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:28129; }","duration":"121.385329ms","start":"2026-04-07T00:56:53.807466Z","end":"2026-04-07T00:56:53.928851Z","steps":["trace[1509748747] 'agreement among raft nodes before linearized reading' (duration: 121.260716ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:54.251887Z","caller":"traceutil/trace.go:171","msg":"trace[1561164675] transaction","detail":"{read_only:false; response_revision:28130; number_of_response:1; }","duration":"315.644721ms","start":"2026-04-07T00:56:53.936224Z","end":"2026-04-07T00:56:54.251869Z","steps":["trace[1561164675] 'process raft request' (duration: 315.486297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:54.252048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:56:53.936199Z","time spent":"315.743514ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:56:54.26794Z","caller":"traceutil/trace.go:171","msg":"trace[1063895091] transaction","detail":"{read_only:false; response_revision:28131; number_of_response:1; }","duration":"254.6484ms","start":"2026-04-07T00:56:54.013256Z","end":"2026-04-07T00:56:54.267905Z","steps":["trace[1063895091] 'process raft request' (duration: 254.315051ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:54.427347Z","caller":"traceutil/trace.go:171","msg":"trace[586359273] transaction","detail":"{read_only:false; response_revision:28133; number_of_response:1; }","duration":"124.788948ms","start":"2026-04-07T00:56:54.302498Z","end":"2026-04-07T00:56:54.427287Z","steps":["trace[586359273] 'process raft request' (duration: 124.637305ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:54.949305Z","caller":"traceutil/trace.go:171","msg":"trace[2043067995] transaction","detail":"{read_only:false; response_revision:28135; number_of_response:1; }","duration":"193.205617ms","start":"2026-04-07T00:56:54.756071Z","end":"2026-04-07T00:56:54.949277Z","steps":["trace[2043067995] 'process raft request' (duration: 193.023553ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:55.046679Z","caller":"traceutil/trace.go:171","msg":"trace[2046218301] transaction","detail":"{read_only:false; response_revision:28136; number_of_response:1; }","duration":"166.877382ms","start":"2026-04-07T00:56:54.879753Z","end":"2026-04-07T00:56:55.046631Z","steps":["trace[2046218301] 'process raft request' (duration: 165.087819ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:55.046783Z","caller":"traceutil/trace.go:171","msg":"trace[292122356] linearizableReadLoop","detail":"{readStateIndex:29420; appliedIndex:29418; }","duration":"156.521534ms","start":"2026-04-07T00:56:54.890226Z","end":"2026-04-07T00:56:55.046747Z","steps":["trace[292122356] 'read index received' (duration: 58.868271ms)","trace[292122356] 'applied index is now lower than readState.Index' (duration: 97.648283ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:56:55.046967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.721549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:56:55.047048Z","caller":"traceutil/trace.go:171","msg":"trace[827701019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28137; }","duration":"156.844482ms","start":"2026-04-07T00:56:54.890183Z","end":"2026-04-07T00:56:55.047028Z","steps":["trace[827701019] 'agreement among raft nodes before linearized reading' (duration: 156.701699ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:55.51618Z","caller":"traceutil/trace.go:171","msg":"trace[608950873] transaction","detail":"{read_only:false; response_revision:28139; number_of_response:1; }","duration":"131.214342ms","start":"2026-04-07T00:56:55.38494Z","end":"2026-04-07T00:56:55.516155Z","steps":["trace[608950873] 'process raft request' (duration: 131.049858ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:55.762527Z","caller":"traceutil/trace.go:171","msg":"trace[898581578] transaction","detail":"{read_only:false; response_revision:28140; number_of_response:1; }","duration":"232.892671ms","start":"2026-04-07T00:56:55.529608Z","end":"2026-04-07T00:56:55.762501Z","steps":["trace[898581578] 'process raft request' (duration: 218.855567ms)","trace[898581578] 'compare' (duration: 13.246176ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:56:55.93003Z","caller":"traceutil/trace.go:171","msg":"trace[1063934309] linearizableReadLoop","detail":"{readStateIndex:29426; appliedIndex:29425; }","duration":"115.291973ms","start":"2026-04-07T00:56:55.81471Z","end":"2026-04-07T00:56:55.930002Z","steps":["trace[1063934309] 'read index received' (duration: 90.584465ms)","trace[1063934309] 'applied index is now lower than readState.Index' (duration: 24.706188ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:56:55.930203Z","caller":"traceutil/trace.go:171","msg":"trace[204278859] transaction","detail":"{read_only:false; response_revision:28142; number_of_response:1; }","duration":"163.45695ms","start":"2026-04-07T00:56:55.766731Z","end":"2026-04-07T00:56:55.930188Z","steps":["trace[204278859] 'process raft request' (duration: 138.628159ms)","trace[204278859] 'compare' (duration: 24.286108ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:56:55.930455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.61182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-04-07T00:56:55.930524Z","caller":"traceutil/trace.go:171","msg":"trace[674935318] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28142; }","duration":"115.718363ms","start":"2026-04-07T00:56:55.814787Z","end":"2026-04-07T00:56:55.930505Z","steps":["trace[674935318] 'agreement among raft nodes before linearized reading' (duration: 115.539639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:55.930557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.828286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-07T00:56:55.930648Z","caller":"traceutil/trace.go:171","msg":"trace[2006718498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28142; }","duration":"115.933168ms","start":"2026-04-07T00:56:55.814699Z","end":"2026-04-07T00:56:55.930632Z","steps":["trace[2006718498] 'agreement among raft nodes before linearized reading' (duration: 115.723013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:56.207393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.666748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:56:56.207667Z","caller":"traceutil/trace.go:171","msg":"trace[468277759] transaction","detail":"{read_only:false; response_revision:28145; number_of_response:1; }","duration":"270.972188ms","start":"2026-04-07T00:56:55.936676Z","end":"2026-04-07T00:56:56.207648Z","steps":["trace[468277759] 'process raft request' (duration: 270.924137ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:56.207671Z","caller":"traceutil/trace.go:171","msg":"trace[747070685] transaction","detail":"{read_only:false; response_revision:28143; number_of_response:1; }","duration":"272.328019ms","start":"2026-04-07T00:56:55.93531Z","end":"2026-04-07T00:56:56.207638Z","steps":["trace[747070685] 'process raft request' (duration: 123.332015ms)","trace[747070685] 'compare' (duration: 148.412491ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:56:56.207843Z","caller":"traceutil/trace.go:171","msg":"trace[1333823572] transaction","detail":"{read_only:false; response_revision:28144; number_of_response:1; }","duration":"271.294636ms","start":"2026-04-07T00:56:55.936537Z","end":"2026-04-07T00:56:56.207832Z","steps":["trace[1333823572] 'process raft request' (duration: 270.976259ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:56.545826Z","caller":"traceutil/trace.go:171","msg":"trace[1360989884] transaction","detail":"{read_only:false; response_revision:28146; number_of_response:1; }","duration":"282.840651ms","start":"2026-04-07T00:56:56.262962Z","end":"2026-04-07T00:56:56.545802Z","steps":["trace[1360989884] 'process raft request' (duration: 282.710247ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:56.586735Z","caller":"traceutil/trace.go:171","msg":"trace[1218261801] transaction","detail":"{read_only:false; response_revision:28147; number_of_response:1; }","duration":"314.7633ms","start":"2026-04-07T00:56:56.271931Z","end":"2026-04-07T00:56:56.586694Z","steps":["trace[1218261801] 'process raft request' (duration: 314.553035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:56.586905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:56:56.271903Z","time spent":"314.916593ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:56:56.586967Z","caller":"traceutil/trace.go:171","msg":"trace[1713189765] linearizableReadLoop","detail":"{readStateIndex:29431; appliedIndex:29430; }","duration":"173.268642ms","start":"2026-04-07T00:56:56.413641Z","end":"2026-04-07T00:56:56.58691Z","steps":["trace[1713189765] 'read index received' (duration: 132.156684ms)","trace[1713189765] 'applied index is now lower than readState.Index' (duration: 41.107298ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:56:56.587052Z","caller":"traceutil/trace.go:171","msg":"trace[1970139876] transaction","detail":"{read_only:false; response_revision:28148; number_of_response:1; }","duration":"155.130331ms","start":"2026-04-07T00:56:56.431875Z","end":"2026-04-07T00:56:56.587006Z","steps":["trace[1970139876] 'process raft request' (duration: 154.760563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:56.587187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.517808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:56:56.587252Z","caller":"traceutil/trace.go:171","msg":"trace[803397697] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:28148; }","duration":"173.603241ms","start":"2026-04-07T00:56:56.413634Z","end":"2026-04-07T00:56:56.587238Z","steps":["trace[803397697] 'agreement among raft nodes before linearized reading' (duration: 173.438186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:56.79795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.893315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:56:56.798023Z","caller":"traceutil/trace.go:171","msg":"trace[233544921] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:28149; }","duration":"102.013238ms","start":"2026-04-07T00:56:56.695992Z","end":"2026-04-07T00:56:56.798005Z","steps":["trace[233544921] 'count revisions from in-memory index tree' (duration: 101.806533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:56.798281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.143873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-04-07T00:56:56.79838Z","caller":"traceutil/trace.go:171","msg":"trace[1836649126] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28149; }","duration":"166.256346ms","start":"2026-04-07T00:56:56.632102Z","end":"2026-04-07T00:56:56.798358Z","steps":["trace[1836649126] 'range keys from in-memory index tree' (duration: 165.902967ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:57.162343Z","caller":"traceutil/trace.go:171","msg":"trace[1663171935] transaction","detail":"{read_only:false; response_revision:28150; number_of_response:1; }","duration":"307.578589ms","start":"2026-04-07T00:56:56.854748Z","end":"2026-04-07T00:56:57.162327Z","steps":["trace[1663171935] 'process raft request' (duration: 307.459196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:57.162458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:56:56.854726Z","time spent":"307.674281ms","remote":"127.0.0.1:59688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:56:57.445821Z","caller":"traceutil/trace.go:171","msg":"trace[545785847] linearizableReadLoop","detail":"{readStateIndex:29436; appliedIndex:29434; }","duration":"369.79668ms","start":"2026-04-07T00:56:57.075998Z","end":"2026-04-07T00:56:57.445795Z","steps":["trace[545785847] 'read index received' (duration: 86.447417ms)","trace[545785847] 'applied index is now lower than readState.Index' (duration: 283.348013ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:56:57.446604Z","caller":"traceutil/trace.go:171","msg":"trace[665582921] transaction","detail":"{read_only:false; response_revision:28152; number_of_response:1; }","duration":"391.941306ms","start":"2026-04-07T00:56:57.054636Z","end":"2026-04-07T00:56:57.446578Z","steps":["trace[665582921] 'process raft request' (duration: 391.080336ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:57.44662Z","caller":"traceutil/trace.go:171","msg":"trace[1839339963] transaction","detail":"{read_only:false; response_revision:28151; number_of_response:1; }","duration":"393.745419ms","start":"2026-04-07T00:56:57.052527Z","end":"2026-04-07T00:56:57.446272Z","steps":["trace[1839339963] 'process raft request' (duration: 384.986451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:57.446735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.710441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"warn","ts":"2026-04-07T00:56:57.44677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:56:57.054628Z","time spent":"392.044258ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:56:57.446821Z","caller":"traceutil/trace.go:171","msg":"trace[1557585746] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28152; }","duration":"370.825833ms","start":"2026-04-07T00:56:57.075969Z","end":"2026-04-07T00:56:57.446795Z","steps":["trace[1557585746] 'agreement among raft nodes before linearized reading' (duration: 370.584247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:57.446889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:56:57.075959Z","time spent":"370.915106ms","remote":"127.0.0.1:45190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":671,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T00:56:57.446891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:56:57.052501Z","time spent":"394.281281ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:56:57.629495Z","caller":"traceutil/trace.go:171","msg":"trace[1306093251] transaction","detail":"{read_only:false; number_of_response:0; response_revision:28152; }","duration":"460.008977ms","start":"2026-04-07T00:56:57.169463Z","end":"2026-04-07T00:56:57.629472Z","steps":["trace[1306093251] 'process raft request' (duration: 459.915485ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:57.629541Z","caller":"traceutil/trace.go:171","msg":"trace[699299710] linearizableReadLoop","detail":"{readStateIndex:29437; appliedIndex:29437; }","duration":"183.545808ms","start":"2026-04-07T00:56:57.44596Z","end":"2026-04-07T00:56:57.629506Z","steps":["trace[699299710] 'read index received' (duration: 183.534078ms)","trace[699299710] 'applied index is now lower than readState.Index' (duration: 9.59µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:56:57.629633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:56:57.169442Z","time spent":"460.12505ms","remote":"127.0.0.1:59688","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":30,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:56:57.629936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.670241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.35\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-04-07T00:56:57.630018Z","caller":"traceutil/trace.go:171","msg":"trace[1487717046] range","detail":"{range_begin:/registry/masterleases/199.19.213.35; range_end:; response_count:1; response_revision:28152; }","duration":"523.798044ms","start":"2026-04-07T00:56:57.106199Z","end":"2026-04-07T00:56:57.629997Z","steps":["trace[1487717046] 'agreement among raft nodes before linearized reading' (duration: 523.438726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:57.63005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:56:57.106178Z","time spent":"523.864895ms","remote":"127.0.0.1:45122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":161,"request content":"key:\"/registry/masterleases/199.19.213.35\" "} {"level":"info","ts":"2026-04-07T00:56:57.835669Z","caller":"traceutil/trace.go:171","msg":"trace[2035504064] linearizableReadLoop","detail":"{readStateIndex:29438; appliedIndex:29437; }","duration":"206.030212ms","start":"2026-04-07T00:56:57.629617Z","end":"2026-04-07T00:56:57.835647Z","steps":["trace[2035504064] 'read index received' (duration: 205.895729ms)","trace[2035504064] 'applied index is now lower than readState.Index' (duration: 133.423µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:56:57.835888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.338423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-07T00:56:57.835953Z","caller":"traceutil/trace.go:171","msg":"trace[410963021] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28153; }","duration":"386.438965ms","start":"2026-04-07T00:56:57.449501Z","end":"2026-04-07T00:56:57.83594Z","steps":["trace[410963021] 'agreement among raft nodes before linearized reading' (duration: 386.24201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:57.835986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:56:57.449486Z","time spent":"386.494417ms","remote":"127.0.0.1:45314","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-04-07T00:56:57.836265Z","caller":"traceutil/trace.go:171","msg":"trace[855264826] transaction","detail":"{read_only:false; response_revision:28153; number_of_response:1; }","duration":"314.039143ms","start":"2026-04-07T00:56:57.521668Z","end":"2026-04-07T00:56:57.835707Z","steps":["trace[855264826] 'process raft request' (duration: 313.833668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:57.8364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:56:57.521652Z","time spent":"314.641806ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:56:58.075332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.316435ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-04-07T00:56:58.075571Z","caller":"traceutil/trace.go:171","msg":"trace[903561103] linearizableReadLoop","detail":"{readStateIndex:29439; appliedIndex:29438; }","duration":"239.801267ms","start":"2026-04-07T00:56:57.835738Z","end":"2026-04-07T00:56:58.075539Z","steps":["trace[903561103] 'read index received' (duration: 100.257686ms)","trace[903561103] 'applied index is now lower than readState.Index' (duration: 139.541361ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:56:58.075585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:56:57.631027Z","time spent":"444.551189ms","remote":"127.0.0.1:45122","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-07T00:56:58.075595Z","caller":"traceutil/trace.go:171","msg":"trace[1003291705] transaction","detail":"{read_only:false; response_revision:28154; number_of_response:1; }","duration":"233.650231ms","start":"2026-04-07T00:56:57.841931Z","end":"2026-04-07T00:56:58.075581Z","steps":["trace[1003291705] 'process raft request' (duration: 233.491767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:58.07581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.16857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:56:58.075832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.219273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:13486"} {"level":"warn","ts":"2026-04-07T00:56:58.07581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.041174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:56:58.075809Z","caller":"traceutil/trace.go:171","msg":"trace[1231498836] transaction","detail":"{read_only:false; response_revision:28155; number_of_response:1; }","duration":"119.508654ms","start":"2026-04-07T00:56:57.956267Z","end":"2026-04-07T00:56:58.075776Z","steps":["trace[1231498836] 'process raft request' (duration: 119.266099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:58.075825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.461091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:56:58.075849Z","caller":"traceutil/trace.go:171","msg":"trace[658914339] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/securitygroups/; range_end:/registry/openstack.k-orc.cloud/securitygroups0; response_count:0; response_revision:28155; }","duration":"236.253982ms","start":"2026-04-07T00:56:57.839584Z","end":"2026-04-07T00:56:58.075838Z","steps":["trace[658914339] 'agreement among raft nodes before linearized reading' (duration: 236.133549ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:58.075858Z","caller":"traceutil/trace.go:171","msg":"trace[528359812] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:28155; }","duration":"445.278905ms","start":"2026-04-07T00:56:57.630571Z","end":"2026-04-07T00:56:58.07585Z","steps":["trace[528359812] 'agreement among raft nodes before linearized reading' (duration: 445.05595ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:58.075878Z","caller":"traceutil/trace.go:171","msg":"trace[546648597] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:28155; }","duration":"425.126586ms","start":"2026-04-07T00:56:57.650735Z","end":"2026-04-07T00:56:58.075862Z","steps":["trace[546648597] 'agreement among raft nodes before linearized reading' (duration: 424.880271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:58.075888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:56:57.630555Z","time spent":"445.326627ms","remote":"127.0.0.1:59688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":13510,"request content":"key:\"/registry/statefulsets/openstack/percona-xtradb-haproxy\" "} {"level":"info","ts":"2026-04-07T00:56:58.075888Z","caller":"traceutil/trace.go:171","msg":"trace[1936661643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28155; }","duration":"187.559723ms","start":"2026-04-07T00:56:57.888311Z","end":"2026-04-07T00:56:58.07587Z","steps":["trace[1936661643] 'agreement among raft nodes before linearized reading' (duration: 187.45821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:58.075916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:56:57.650692Z","time spent":"425.216098ms","remote":"127.0.0.1:51908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:56:58.075918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.622156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-07T00:56:58.075816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.420763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:56:58.07596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.646024ms","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-04-07T00:56:58.075952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.423153ms","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-04-07T00:56:58.075968Z","caller":"traceutil/trace.go:171","msg":"trace[879193139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28155; }","duration":"140.713568ms","start":"2026-04-07T00:56:57.935242Z","end":"2026-04-07T00:56:58.075955Z","steps":["trace[879193139] 'agreement among raft nodes before linearized reading' (duration: 140.517403ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:58.075994Z","caller":"traceutil/trace.go:171","msg":"trace[288999640] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28155; }","duration":"292.713285ms","start":"2026-04-07T00:56:57.783268Z","end":"2026-04-07T00:56:58.075981Z","steps":["trace[288999640] 'agreement among raft nodes before linearized reading' (duration: 292.337876ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:58.07598Z","caller":"traceutil/trace.go:171","msg":"trace[1899276361] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:28155; }","duration":"272.637537ms","start":"2026-04-07T00:56:57.803331Z","end":"2026-04-07T00:56:58.075969Z","steps":["trace[1899276361] 'agreement among raft nodes before linearized reading' (duration: 272.248418ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:58.076011Z","caller":"traceutil/trace.go:171","msg":"trace[1240859396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28155; }","duration":"309.512255ms","start":"2026-04-07T00:56:57.766482Z","end":"2026-04-07T00:56:58.075994Z","steps":["trace[1240859396] 'agreement among raft nodes before linearized reading' (duration: 309.094216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:58.076057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:56:57.766464Z","time spent":"309.584427ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T00:56:58.077164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.012267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:56:58.077234Z","caller":"traceutil/trace.go:171","msg":"trace[1794891992] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:28155; }","duration":"376.106929ms","start":"2026-04-07T00:56:57.701103Z","end":"2026-04-07T00:56:58.077209Z","steps":["trace[1794891992] 'agreement among raft nodes before linearized reading' (duration: 374.509681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:58.077272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:56:57.701085Z","time spent":"376.176171ms","remote":"127.0.0.1:45270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":30,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:57:04.907918Z","caller":"traceutil/trace.go:171","msg":"trace[2034912902] transaction","detail":"{read_only:false; response_revision:28217; number_of_response:1; }","duration":"296.753081ms","start":"2026-04-07T00:57:04.61114Z","end":"2026-04-07T00:57:04.907893Z","steps":["trace[2034912902] 'process raft request' (duration: 296.618838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:05.46479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.072143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:57:05.464984Z","caller":"traceutil/trace.go:171","msg":"trace[467697205] transaction","detail":"{read_only:false; response_revision:28218; number_of_response:1; }","duration":"850.428026ms","start":"2026-04-07T00:57:04.614515Z","end":"2026-04-07T00:57:05.464943Z","steps":["trace[467697205] 'process raft request' (duration: 362.060085ms)","trace[467697205] 'compare' (duration: 487.725275ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:05.465082Z","caller":"traceutil/trace.go:171","msg":"trace[2087642926] transaction","detail":"{read_only:false; response_revision:28219; number_of_response:1; }","duration":"518.327294ms","start":"2026-04-07T00:57:04.946728Z","end":"2026-04-07T00:57:05.465055Z","steps":["trace[2087642926] 'process raft request' (duration: 518.203501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:05.465164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:04.614494Z","time spent":"850.57338ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:57:05.465211Z","caller":"traceutil/trace.go:171","msg":"trace[1866874864] linearizableReadLoop","detail":"{readStateIndex:29506; appliedIndex:29504; }","duration":"779.99747ms","start":"2026-04-07T00:57:04.685201Z","end":"2026-04-07T00:57:05.465199Z","steps":["trace[1866874864] 'read index received' (duration: 222.575276ms)","trace[1866874864] 'applied index is now lower than readState.Index' (duration: 557.420634ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:57:05.465166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:04.946695Z","time spent":"518.436377ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:57:05.465591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.3943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-04-07T00:57:05.465632Z","caller":"traceutil/trace.go:171","msg":"trace[540736849] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28219; }","duration":"780.469082ms","start":"2026-04-07T00:57:04.685151Z","end":"2026-04-07T00:57:05.46562Z","steps":["trace[540736849] 'agreement among raft nodes before linearized reading' (duration: 780.307358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:05.465669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:04.68513Z","time spent":"780.527943ms","remote":"127.0.0.1:45190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":686,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T00:57:05.466201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.67615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-07T00:57:05.466242Z","caller":"traceutil/trace.go:171","msg":"trace[1841550492] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:28219; }","duration":"689.755742ms","start":"2026-04-07T00:57:04.776477Z","end":"2026-04-07T00:57:05.466232Z","steps":["trace[1841550492] 'agreement among raft nodes before linearized reading' (duration: 689.411784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:05.466273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:04.776458Z","time spent":"689.807594ms","remote":"127.0.0.1:45174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":236,"response size":33,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:57:05.466421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.054451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:57:05.46646Z","caller":"traceutil/trace.go:171","msg":"trace[1976140715] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:28219; }","duration":"419.153664ms","start":"2026-04-07T00:57:05.047297Z","end":"2026-04-07T00:57:05.466451Z","steps":["trace[1976140715] 'agreement among raft nodes before linearized reading' (duration: 419.092633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:05.466488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:05.047266Z","time spent":"419.215085ms","remote":"127.0.0.1:41586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":30,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:57:05.466578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.065872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:57:05.466568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.479246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:57:05.466641Z","caller":"traceutil/trace.go:171","msg":"trace[1000785599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28219; }","duration":"577.176304ms","start":"2026-04-07T00:57:04.889449Z","end":"2026-04-07T00:57:05.466625Z","steps":["trace[1000785599] 'agreement among raft nodes before linearized reading' (duration: 577.060201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:05.466671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:04.889431Z","time spent":"577.234266ms","remote":"127.0.0.1:45098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T00:57:05.466679Z","caller":"traceutil/trace.go:171","msg":"trace[2040304515] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:28219; }","duration":"615.64934ms","start":"2026-04-07T00:57:04.850989Z","end":"2026-04-07T00:57:05.466639Z","steps":["trace[2040304515] 'agreement among raft nodes before linearized reading' (duration: 615.445935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:05.466739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:04.850964Z","time spent":"615.757872ms","remote":"127.0.0.1:41466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:57:06.002374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.430496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:57:06.002544Z","caller":"traceutil/trace.go:171","msg":"trace[1520586060] transaction","detail":"{read_only:false; response_revision:28220; number_of_response:1; }","duration":"531.67269ms","start":"2026-04-07T00:57:05.470852Z","end":"2026-04-07T00:57:06.002524Z","steps":["trace[1520586060] 'process raft request' (duration: 263.018339ms)","trace[1520586060] 'compare' (duration: 268.294443ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:57:06.002643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:05.470839Z","time spent":"531.743672ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:57:06.003168Z","caller":"traceutil/trace.go:171","msg":"trace[1901796092] linearizableReadLoop","detail":"{readStateIndex:29510; appliedIndex:29507; }","duration":"451.692479ms","start":"2026-04-07T00:57:05.551446Z","end":"2026-04-07T00:57:06.003139Z","steps":["trace[1901796092] 'read index received' (duration: 182.395311ms)","trace[1901796092] 'applied index is now lower than readState.Index' (duration: 269.295948ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:06.003249Z","caller":"traceutil/trace.go:171","msg":"trace[1929470796] transaction","detail":"{read_only:false; response_revision:28221; number_of_response:1; }","duration":"531.203581ms","start":"2026-04-07T00:57:05.472029Z","end":"2026-04-07T00:57:06.003233Z","steps":["trace[1929470796] 'process raft request' (duration: 530.445702ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:06.003248Z","caller":"traceutil/trace.go:171","msg":"trace[1943398705] transaction","detail":"{read_only:false; response_revision:28222; number_of_response:1; }","duration":"531.053277ms","start":"2026-04-07T00:57:05.472159Z","end":"2026-04-07T00:57:06.003212Z","steps":["trace[1943398705] 'process raft request' (duration: 530.879453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:06.003326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:05.47202Z","time spent":"531.264012ms","remote":"127.0.0.1:45190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:57:06.003348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.885464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:57:06.003409Z","caller":"traceutil/trace.go:171","msg":"trace[1663052695] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:28222; }","duration":"451.999506ms","start":"2026-04-07T00:57:05.551386Z","end":"2026-04-07T00:57:06.003385Z","steps":["trace[1663052695] 'agreement among raft nodes before linearized reading' (duration: 451.897434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:06.003446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:05.472153Z","time spent":"531.19552ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:57:06.003467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:05.551363Z","time spent":"452.089418ms","remote":"127.0.0.1:46240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:57:06.04881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.156063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:57:06.048901Z","caller":"traceutil/trace.go:171","msg":"trace[1664021066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28223; }","duration":"158.288106ms","start":"2026-04-07T00:57:05.890589Z","end":"2026-04-07T00:57:06.048877Z","steps":["trace[1664021066] 'agreement among raft nodes before linearized reading' (duration: 158.130042ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:06.048967Z","caller":"traceutil/trace.go:171","msg":"trace[1078888875] transaction","detail":"{read_only:false; response_revision:28223; number_of_response:1; }","duration":"192.450859ms","start":"2026-04-07T00:57:05.856497Z","end":"2026-04-07T00:57:06.048948Z","steps":["trace[1078888875] 'process raft request' (duration: 191.996669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:06.049049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.923849ms","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-04-07T00:57:06.049133Z","caller":"traceutil/trace.go:171","msg":"trace[1474592376] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:28223; }","duration":"232.054842ms","start":"2026-04-07T00:57:05.81706Z","end":"2026-04-07T00:57:06.049115Z","steps":["trace[1474592376] 'agreement among raft nodes before linearized reading' (duration: 231.916639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:06.609166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.108888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-07T00:57:06.609243Z","caller":"traceutil/trace.go:171","msg":"trace[1631665466] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28224; }","duration":"344.234731ms","start":"2026-04-07T00:57:06.264991Z","end":"2026-04-07T00:57:06.609226Z","steps":["trace[1631665466] 'range keys from in-memory index tree' (duration: 343.76464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:06.609276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:06.26497Z","time spent":"344.297643ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T00:57:06.609569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.512047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-07T00:57:06.609598Z","caller":"traceutil/trace.go:171","msg":"trace[1971816685] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:28224; }","duration":"473.571129ms","start":"2026-04-07T00:57:06.136017Z","end":"2026-04-07T00:57:06.609589Z","steps":["trace[1971816685] 'count revisions from in-memory index tree' (duration: 473.450126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:06.609622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:06.136002Z","time spent":"473.613069ms","remote":"127.0.0.1:45190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":144,"response size":33,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:57:06.609867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.81029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:57:06.609891Z","caller":"traceutil/trace.go:171","msg":"trace[155973061] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:28224; }","duration":"456.871812ms","start":"2026-04-07T00:57:06.153012Z","end":"2026-04-07T00:57:06.609884Z","steps":["trace[155973061] 'count revisions from in-memory index tree' (duration: 456.735198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:06.609908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:06.152994Z","time spent":"456.908362ms","remote":"127.0.0.1:45226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":14,"response size":32,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:57:06.610032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.202188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-07T00:57:06.610061Z","caller":"traceutil/trace.go:171","msg":"trace[1047515439] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28224; }","duration":"433.275089ms","start":"2026-04-07T00:57:06.176777Z","end":"2026-04-07T00:57:06.610052Z","steps":["trace[1047515439] 'range keys from in-memory index tree' (duration: 433.041424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:06.610103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:06.176743Z","time spent":"433.354171ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T00:57:06.610177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.102835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-04-07T00:57:06.610198Z","caller":"traceutil/trace.go:171","msg":"trace[1134189998] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28224; }","duration":"354.124626ms","start":"2026-04-07T00:57:06.256067Z","end":"2026-04-07T00:57:06.610191Z","steps":["trace[1134189998] 'range keys from in-memory index tree' (duration: 353.997152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:06.610215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:06.256009Z","time spent":"354.200828ms","remote":"127.0.0.1:45190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":671,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T00:57:06.610236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.197276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-07T00:57:06.610255Z","caller":"traceutil/trace.go:171","msg":"trace[1307780789] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28224; }","duration":"430.216196ms","start":"2026-04-07T00:57:06.180032Z","end":"2026-04-07T00:57:06.610248Z","steps":["trace[1307780789] 'range keys from in-memory index tree' (duration: 430.112514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:06.610272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:06.179983Z","time spent":"430.284278ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T00:57:06.610341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.352551ms","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-04-07T00:57:06.61036Z","caller":"traceutil/trace.go:171","msg":"trace[246002535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28224; }","duration":"346.390092ms","start":"2026-04-07T00:57:06.263964Z","end":"2026-04-07T00:57:06.610354Z","steps":["trace[246002535] 'range keys from in-memory index tree' (duration: 346.289199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:06.610377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:06.263957Z","time spent":"346.414272ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T00:57:06.610395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.008671ms","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-04-07T00:57:06.610413Z","caller":"traceutil/trace.go:171","msg":"trace[1283057264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28224; }","duration":"430.049553ms","start":"2026-04-07T00:57:06.180358Z","end":"2026-04-07T00:57:06.610408Z","steps":["trace[1283057264] 'range keys from in-memory index tree' (duration: 429.92152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:06.61043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:06.180345Z","time spent":"430.081023ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T00:57:06.610499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.597643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-07T00:57:06.610517Z","caller":"traceutil/trace.go:171","msg":"trace[1569590399] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28224; }","duration":"345.627465ms","start":"2026-04-07T00:57:06.264884Z","end":"2026-04-07T00:57:06.610511Z","steps":["trace[1569590399] 'range keys from in-memory index tree' (duration: 345.543452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:06.610532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:06.264878Z","time spent":"345.650386ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":550,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-04-07T00:57:06.756168Z","caller":"traceutil/trace.go:171","msg":"trace[1632015290] linearizableReadLoop","detail":"{readStateIndex:29517; appliedIndex:29512; }","duration":"141.62577ms","start":"2026-04-07T00:57:06.614523Z","end":"2026-04-07T00:57:06.756149Z","steps":["trace[1632015290] 'read index received' (duration: 57.037267ms)","trace[1632015290] 'applied index is now lower than readState.Index' (duration: 84.587513ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:06.756209Z","caller":"traceutil/trace.go:171","msg":"trace[1798332507] transaction","detail":"{read_only:false; response_revision:28226; number_of_response:1; }","duration":"141.748532ms","start":"2026-04-07T00:57:06.614416Z","end":"2026-04-07T00:57:06.756164Z","steps":["trace[1798332507] 'process raft request' (duration: 141.442345ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:06.756217Z","caller":"traceutil/trace.go:171","msg":"trace[51524920] transaction","detail":"{read_only:false; response_revision:28228; number_of_response:1; }","duration":"141.725362ms","start":"2026-04-07T00:57:06.614459Z","end":"2026-04-07T00:57:06.756184Z","steps":["trace[51524920] 'process raft request' (duration: 141.557838ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:06.75628Z","caller":"traceutil/trace.go:171","msg":"trace[541213900] transaction","detail":"{read_only:false; response_revision:28227; number_of_response:1; }","duration":"141.812765ms","start":"2026-04-07T00:57:06.614429Z","end":"2026-04-07T00:57:06.756242Z","steps":["trace[541213900] 'process raft request' (duration: 141.550828ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:06.756315Z","caller":"traceutil/trace.go:171","msg":"trace[132163655] transaction","detail":"{read_only:false; response_revision:28230; number_of_response:1; }","duration":"141.459207ms","start":"2026-04-07T00:57:06.614812Z","end":"2026-04-07T00:57:06.756271Z","steps":["trace[132163655] 'process raft request' (duration: 141.290442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:06.75638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.980171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-8f2327d0-65fe-405e-9361-b9757d667d49\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-04-07T00:57:06.75639Z","caller":"traceutil/trace.go:171","msg":"trace[684105971] transaction","detail":"{read_only:false; response_revision:28229; number_of_response:1; }","duration":"141.861535ms","start":"2026-04-07T00:57:06.614491Z","end":"2026-04-07T00:57:06.756353Z","steps":["trace[684105971] 'process raft request' (duration: 141.574278ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:06.756429Z","caller":"traceutil/trace.go:171","msg":"trace[574168941] range","detail":"{range_begin:/registry/persistentvolumes/pvc-8f2327d0-65fe-405e-9361-b9757d667d49; range_end:; response_count:1; response_revision:28230; }","duration":"140.039882ms","start":"2026-04-07T00:57:06.616372Z","end":"2026-04-07T00:57:06.756412Z","steps":["trace[574168941] 'agreement among raft nodes before linearized reading' (duration: 139.914919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:06.756364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.819945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-07T00:57:06.756483Z","caller":"traceutil/trace.go:171","msg":"trace[396859600] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28230; }","duration":"141.979208ms","start":"2026-04-07T00:57:06.614489Z","end":"2026-04-07T00:57:06.756468Z","steps":["trace[396859600] 'agreement among raft nodes before linearized reading' (duration: 141.759563ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:10.790632Z","caller":"traceutil/trace.go:171","msg":"trace[1166945855] transaction","detail":"{read_only:false; response_revision:28316; number_of_response:1; }","duration":"102.5454ms","start":"2026-04-07T00:57:10.688065Z","end":"2026-04-07T00:57:10.790611Z","steps":["trace[1166945855] 'process raft request' (duration: 102.232073ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:11.023001Z","caller":"traceutil/trace.go:171","msg":"trace[139164086] linearizableReadLoop","detail":"{readStateIndex:29608; appliedIndex:29607; }","duration":"224.193494ms","start":"2026-04-07T00:57:10.79878Z","end":"2026-04-07T00:57:11.022974Z","steps":["trace[139164086] 'read index received' (duration: 152.205581ms)","trace[139164086] 'applied index is now lower than readState.Index' (duration: 71.986723ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:11.023144Z","caller":"traceutil/trace.go:171","msg":"trace[791657274] transaction","detail":"{read_only:false; response_revision:28317; number_of_response:1; }","duration":"224.447141ms","start":"2026-04-07T00:57:10.798663Z","end":"2026-04-07T00:57:11.02311Z","steps":["trace[791657274] 'process raft request' (duration: 152.310014ms)","trace[791657274] 'compare' (duration: 71.84097ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:57:11.023322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.516012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-07T00:57:11.023374Z","caller":"traceutil/trace.go:171","msg":"trace[132576577] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28317; }","duration":"224.606264ms","start":"2026-04-07T00:57:10.798753Z","end":"2026-04-07T00:57:11.023359Z","steps":["trace[132576577] 'agreement among raft nodes before linearized reading' (duration: 224.346288ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:11.121504Z","caller":"traceutil/trace.go:171","msg":"trace[1911544492] transaction","detail":"{read_only:false; response_revision:28319; number_of_response:1; }","duration":"322.591456ms","start":"2026-04-07T00:57:10.79889Z","end":"2026-04-07T00:57:11.121481Z","steps":["trace[1911544492] 'process raft request' (duration: 322.535225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:11.121623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:10.798865Z","time spent":"322.696878ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:57:11.121602Z","caller":"traceutil/trace.go:171","msg":"trace[1507623311] transaction","detail":"{read_only:false; response_revision:28318; number_of_response:1; }","duration":"322.716689ms","start":"2026-04-07T00:57:10.798844Z","end":"2026-04-07T00:57:11.121561Z","steps":["trace[1507623311] 'process raft request' (duration: 322.466503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:11.121815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:10.798835Z","time spent":"322.860972ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:57:11.121895Z","caller":"traceutil/trace.go:171","msg":"trace[399408834] transaction","detail":"{read_only:false; response_revision:28320; number_of_response:1; }","duration":"322.945825ms","start":"2026-04-07T00:57:10.798927Z","end":"2026-04-07T00:57:11.121873Z","steps":["trace[399408834] 'process raft request' (duration: 322.525635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:11.122015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:10.79892Z","time spent":"323.020656ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:57:11.122605Z","caller":"traceutil/trace.go:171","msg":"trace[1721652117] transaction","detail":"{read_only:false; response_revision:28321; number_of_response:1; }","duration":"323.61057ms","start":"2026-04-07T00:57:10.798967Z","end":"2026-04-07T00:57:11.122578Z","steps":["trace[1721652117] 'process raft request' (duration: 323.418526ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:11.122822Z","caller":"traceutil/trace.go:171","msg":"trace[586669582] transaction","detail":"{read_only:false; response_revision:28322; number_of_response:1; }","duration":"196.037594ms","start":"2026-04-07T00:57:10.926759Z","end":"2026-04-07T00:57:11.122796Z","steps":["trace[586669582] 'process raft request' (duration: 195.759318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:11.123057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:10.79896Z","time spent":"323.748233ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:57:11.166816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.787926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-59499bdc-6543-4fa4-8f02-8982cf8caf1d\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-04-07T00:57:11.166895Z","caller":"traceutil/trace.go:171","msg":"trace[676196342] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-59499bdc-6543-4fa4-8f02-8982cf8caf1d; range_end:; response_count:1; response_revision:28322; }","duration":"355.907228ms","start":"2026-04-07T00:57:10.810971Z","end":"2026-04-07T00:57:11.166878Z","steps":["trace[676196342] 'agreement among raft nodes before linearized reading' (duration: 311.830859ms)","trace[676196342] 'range keys from in-memory index tree' (duration: 43.856024ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:57:11.166931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:10.810956Z","time spent":"355.967661ms","remote":"127.0.0.1:45254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-59499bdc-6543-4fa4-8f02-8982cf8caf1d\" "} {"level":"info","ts":"2026-04-07T00:57:11.167031Z","caller":"traceutil/trace.go:171","msg":"trace[472491687] transaction","detail":"{read_only:false; response_revision:28323; number_of_response:1; }","duration":"140.216836ms","start":"2026-04-07T00:57:11.026788Z","end":"2026-04-07T00:57:11.167005Z","steps":["trace[472491687] 'process raft request' (duration: 132.038121ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:13.155496Z","caller":"traceutil/trace.go:171","msg":"trace[1627051110] transaction","detail":"{read_only:false; response_revision:28337; number_of_response:1; }","duration":"124.405971ms","start":"2026-04-07T00:57:13.031067Z","end":"2026-04-07T00:57:13.155473Z","steps":["trace[1627051110] 'process raft request' (duration: 124.268208ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:13.287178Z","caller":"traceutil/trace.go:171","msg":"trace[356004728] transaction","detail":"{read_only:false; response_revision:28340; number_of_response:1; }","duration":"122.625878ms","start":"2026-04-07T00:57:13.164523Z","end":"2026-04-07T00:57:13.287149Z","steps":["trace[356004728] 'process raft request' (duration: 56.224348ms)","trace[356004728] 'compare' (duration: 66.247957ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:13.292935Z","caller":"traceutil/trace.go:171","msg":"trace[52957097] transaction","detail":"{read_only:false; response_revision:28341; number_of_response:1; }","duration":"124.642367ms","start":"2026-04-07T00:57:13.168269Z","end":"2026-04-07T00:57:13.292912Z","steps":["trace[52957097] 'process raft request' (duration: 123.614652ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:13.293243Z","caller":"traceutil/trace.go:171","msg":"trace[700132911] transaction","detail":"{read_only:false; response_revision:28342; number_of_response:1; }","duration":"124.925643ms","start":"2026-04-07T00:57:13.168309Z","end":"2026-04-07T00:57:13.293235Z","steps":["trace[700132911] 'process raft request' (duration: 123.658203ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:13.293427Z","caller":"traceutil/trace.go:171","msg":"trace[799256423] linearizableReadLoop","detail":"{readStateIndex:29635; appliedIndex:29630; }","duration":"111.064183ms","start":"2026-04-07T00:57:13.182339Z","end":"2026-04-07T00:57:13.293403Z","steps":["trace[799256423] 'read index received' (duration: 38.364213ms)","trace[799256423] 'applied index is now lower than readState.Index' (duration: 72.69836ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:13.293487Z","caller":"traceutil/trace.go:171","msg":"trace[319496533] transaction","detail":"{read_only:false; response_revision:28343; number_of_response:1; }","duration":"125.114657ms","start":"2026-04-07T00:57:13.168341Z","end":"2026-04-07T00:57:13.293455Z","steps":["trace[319496533] 'process raft request' (duration: 123.677764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:13.293575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.217677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:57:13.293625Z","caller":"traceutil/trace.go:171","msg":"trace[1797152144] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:28343; }","duration":"111.317619ms","start":"2026-04-07T00:57:13.182294Z","end":"2026-04-07T00:57:13.293611Z","steps":["trace[1797152144] 'agreement among raft nodes before linearized reading' (duration: 111.221457ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:16.890619Z","caller":"traceutil/trace.go:171","msg":"trace[1026872588] transaction","detail":"{read_only:false; response_revision:28389; number_of_response:1; }","duration":"103.196176ms","start":"2026-04-07T00:57:16.787397Z","end":"2026-04-07T00:57:16.890593Z","steps":["trace[1026872588] 'process raft request' (duration: 103.015282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:17.157167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.469524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-04-07T00:57:17.157229Z","caller":"traceutil/trace.go:171","msg":"trace[750634450] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:28390; }","duration":"143.568556ms","start":"2026-04-07T00:57:17.013648Z","end":"2026-04-07T00:57:17.157217Z","steps":["trace[750634450] 'range keys from in-memory index tree' (duration: 143.33852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:17.157345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.007746ms","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-04-07T00:57:17.157453Z","caller":"traceutil/trace.go:171","msg":"trace[1257370617] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:28390; }","duration":"120.255802ms","start":"2026-04-07T00:57:17.037177Z","end":"2026-04-07T00:57:17.157432Z","steps":["trace[1257370617] 'count revisions from in-memory index tree' (duration: 119.951763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:17.1571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.46941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10482"} {"level":"info","ts":"2026-04-07T00:57:17.157922Z","caller":"traceutil/trace.go:171","msg":"trace[1901738209] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:28390; }","duration":"204.333892ms","start":"2026-04-07T00:57:16.953575Z","end":"2026-04-07T00:57:17.157909Z","steps":["trace[1901738209] 'range keys from in-memory index tree' (duration: 203.327767ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:17.340234Z","caller":"traceutil/trace.go:171","msg":"trace[462433103] transaction","detail":"{read_only:false; response_revision:28391; number_of_response:1; }","duration":"170.876126ms","start":"2026-04-07T00:57:17.169332Z","end":"2026-04-07T00:57:17.340208Z","steps":["trace[462433103] 'process raft request' (duration: 170.61193ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:17.340294Z","caller":"traceutil/trace.go:171","msg":"trace[314192834] transaction","detail":"{read_only:false; response_revision:28392; number_of_response:1; }","duration":"168.695195ms","start":"2026-04-07T00:57:17.171572Z","end":"2026-04-07T00:57:17.340268Z","steps":["trace[314192834] 'process raft request' (duration: 168.516161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:17.340633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.224517ms","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-04-07T00:57:17.34069Z","caller":"traceutil/trace.go:171","msg":"trace[843753937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28392; }","duration":"169.286039ms","start":"2026-04-07T00:57:17.171374Z","end":"2026-04-07T00:57:17.34066Z","steps":["trace[843753937] 'agreement among raft nodes before linearized reading' (duration: 169.118925ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:17.34085Z","caller":"traceutil/trace.go:171","msg":"trace[557910608] linearizableReadLoop","detail":"{readStateIndex:29686; appliedIndex:29684; }","duration":"129.637846ms","start":"2026-04-07T00:57:17.210577Z","end":"2026-04-07T00:57:17.340215Z","steps":["trace[557910608] 'read index received' (duration: 129.269477ms)","trace[557910608] 'applied index is now lower than readState.Index' (duration: 367.129µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:17.341253Z","caller":"traceutil/trace.go:171","msg":"trace[852438662] transaction","detail":"{read_only:false; response_revision:28393; number_of_response:1; }","duration":"129.672596ms","start":"2026-04-07T00:57:17.211507Z","end":"2026-04-07T00:57:17.341179Z","steps":["trace[852438662] 'process raft request' (duration: 129.480931ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:47.400224Z","caller":"traceutil/trace.go:171","msg":"trace[227534143] transaction","detail":"{read_only:false; response_revision:28683; number_of_response:1; }","duration":"266.100286ms","start":"2026-04-07T00:57:47.134098Z","end":"2026-04-07T00:57:47.400198Z","steps":["trace[227534143] 'process raft request' (duration: 257.613625ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:47.400759Z","caller":"traceutil/trace.go:171","msg":"trace[1662374846] transaction","detail":"{read_only:false; response_revision:28684; number_of_response:1; }","duration":"176.504733ms","start":"2026-04-07T00:57:47.224223Z","end":"2026-04-07T00:57:47.400727Z","steps":["trace[1662374846] 'process raft request' (duration: 176.196286ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:48.207998Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d1606b245347dc08","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T00:57:48.212004Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-07T00:57:48.212076Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-07T00:57:49.535358Z","caller":"traceutil/trace.go:171","msg":"trace[363313577] transaction","detail":"{read_only:false; response_revision:28700; number_of_response:1; }","duration":"127.190124ms","start":"2026-04-07T00:57:49.408143Z","end":"2026-04-07T00:57:49.535334Z","steps":["trace[363313577] 'process raft request' (duration: 127.037281ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:49.811635Z","caller":"traceutil/trace.go:171","msg":"trace[2068054112] transaction","detail":"{read_only:false; response_revision:28702; number_of_response:1; }","duration":"330.657766ms","start":"2026-04-07T00:57:49.480934Z","end":"2026-04-07T00:57:49.811592Z","steps":["trace[2068054112] 'process raft request' (duration: 330.280287ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:49.81165Z","caller":"traceutil/trace.go:171","msg":"trace[1187588675] transaction","detail":"{read_only:false; response_revision:28703; number_of_response:1; }","duration":"205.132652ms","start":"2026-04-07T00:57:49.606492Z","end":"2026-04-07T00:57:49.811625Z","steps":["trace[1187588675] 'process raft request' (duration: 204.832645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:49.811842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:49.480905Z","time spent":"330.83236ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:57:49.811906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.033808ms","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-04-07T00:57:49.81164Z","caller":"traceutil/trace.go:171","msg":"trace[1051276506] transaction","detail":"{read_only:false; response_revision:28701; number_of_response:1; }","duration":"386.555141ms","start":"2026-04-07T00:57:49.42505Z","end":"2026-04-07T00:57:49.811606Z","steps":["trace[1051276506] 'process raft request' (duration: 366.490976ms)","trace[1051276506] 'compare' (duration: 19.002071ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:49.811426Z","caller":"traceutil/trace.go:171","msg":"trace[568681297] linearizableReadLoop","detail":"{readStateIndex:30008; appliedIndex:30006; }","duration":"309.543407ms","start":"2026-04-07T00:57:49.501861Z","end":"2026-04-07T00:57:49.811405Z","steps":["trace[568681297] 'read index received' (duration: 33.416472ms)","trace[568681297] 'applied index is now lower than readState.Index' (duration: 276.125975ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:49.811988Z","caller":"traceutil/trace.go:171","msg":"trace[630087285] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:28703; }","duration":"310.170761ms","start":"2026-04-07T00:57:49.501805Z","end":"2026-04-07T00:57:49.811976Z","steps":["trace[630087285] 'agreement among raft nodes before linearized reading' (duration: 309.900255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:49.812323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:49.501783Z","time spent":"310.490239ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T00:57:49.812401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.133029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-04-07T00:57:49.812366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.084452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:647"} {"level":"warn","ts":"2026-04-07T00:57:49.812593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.119494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-07T00:57:49.812464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.159962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-07T00:57:49.812637Z","caller":"traceutil/trace.go:171","msg":"trace[1502711109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28703; }","duration":"302.442438ms","start":"2026-04-07T00:57:49.510183Z","end":"2026-04-07T00:57:49.812625Z","steps":["trace[1502711109] 'agreement among raft nodes before linearized reading' (duration: 302.10962ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:49.812638Z","caller":"traceutil/trace.go:171","msg":"trace[310754280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28703; }","duration":"303.176485ms","start":"2026-04-07T00:57:49.509444Z","end":"2026-04-07T00:57:49.812621Z","steps":["trace[310754280] 'agreement among raft nodes before linearized reading' (duration: 303.077683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:49.812669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:49.509432Z","time spent":"303.230746ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T00:57:49.812677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:49.510159Z","time spent":"302.502209ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T00:57:49.812591Z","caller":"traceutil/trace.go:171","msg":"trace[2063500405] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28703; }","duration":"154.331698ms","start":"2026-04-07T00:57:49.658238Z","end":"2026-04-07T00:57:49.81257Z","steps":["trace[2063500405] 'agreement among raft nodes before linearized reading' (duration: 154.023821ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:49.812476Z","caller":"traceutil/trace.go:171","msg":"trace[409582253] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28703; }","duration":"299.225292ms","start":"2026-04-07T00:57:49.513228Z","end":"2026-04-07T00:57:49.812453Z","steps":["trace[409582253] 'agreement among raft nodes before linearized reading' (duration: 299.030757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:49.812482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.392139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-04-07T00:57:49.812834Z","caller":"traceutil/trace.go:171","msg":"trace[11071888] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:28703; }","duration":"300.784878ms","start":"2026-04-07T00:57:49.512019Z","end":"2026-04-07T00:57:49.812804Z","steps":["trace[11071888] 'agreement among raft nodes before linearized reading' (duration: 300.286457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:49.812466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.436325ms","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-04-07T00:57:49.812888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:49.511999Z","time spent":"300.872731ms","remote":"127.0.0.1:45226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1884,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" "} {"level":"info","ts":"2026-04-07T00:57:49.812895Z","caller":"traceutil/trace.go:171","msg":"trace[1592512666] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:28703; }","duration":"242.932357ms","start":"2026-04-07T00:57:49.569939Z","end":"2026-04-07T00:57:49.812872Z","steps":["trace[1592512666] 'agreement among raft nodes before linearized reading' (duration: 242.378004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:49.813076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:49.42502Z","time spent":"387.064203ms","remote":"127.0.0.1:59688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:57:49.813129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.713328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-04-07T00:57:49.813699Z","caller":"traceutil/trace.go:171","msg":"trace[815650757] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:28703; }","duration":"307.297813ms","start":"2026-04-07T00:57:49.506384Z","end":"2026-04-07T00:57:49.813682Z","steps":["trace[815650757] 'agreement among raft nodes before linearized reading' (duration: 305.826107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:49.813748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:49.506373Z","time spent":"307.362935ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":551,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-07T00:57:50.188178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.7065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:57:50.188278Z","caller":"traceutil/trace.go:171","msg":"trace[117396047] transaction","detail":"{read_only:false; response_revision:28704; number_of_response:1; }","duration":"370.694475ms","start":"2026-04-07T00:57:49.817565Z","end":"2026-04-07T00:57:50.188259Z","steps":["trace[117396047] 'process raft request' (duration: 149.749479ms)","trace[117396047] 'compare' (duration: 220.494326ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:57:50.188338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:49.817543Z","time spent":"370.765066ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:57:50.202708Z","caller":"traceutil/trace.go:171","msg":"trace[1014990712] linearizableReadLoop","detail":"{readStateIndex:30012; appliedIndex:30009; }","duration":"381.651115ms","start":"2026-04-07T00:57:49.821036Z","end":"2026-04-07T00:57:50.202687Z","steps":["trace[1014990712] 'read index received' (duration: 146.406871ms)","trace[1014990712] 'applied index is now lower than readState.Index' (duration: 235.243424ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:50.203041Z","caller":"traceutil/trace.go:171","msg":"trace[404068943] transaction","detail":"{read_only:false; response_revision:28705; number_of_response:1; }","duration":"383.723694ms","start":"2026-04-07T00:57:49.819307Z","end":"2026-04-07T00:57:50.203031Z","steps":["trace[404068943] 'process raft request' (duration: 383.196191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:50.203119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:49.819284Z","time spent":"383.779766ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:57:50.203642Z","caller":"traceutil/trace.go:171","msg":"trace[761356140] transaction","detail":"{read_only:false; response_revision:28706; number_of_response:1; }","duration":"384.205305ms","start":"2026-04-07T00:57:49.819426Z","end":"2026-04-07T00:57:50.203631Z","steps":["trace[761356140] 'process raft request' (duration: 383.18533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:50.203702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:49.819413Z","time spent":"384.261447ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:57:50.203805Z","caller":"traceutil/trace.go:171","msg":"trace[709384380] transaction","detail":"{read_only:false; response_revision:28707; number_of_response:1; }","duration":"382.762162ms","start":"2026-04-07T00:57:49.821034Z","end":"2026-04-07T00:57:50.203796Z","steps":["trace[709384380] 'process raft request' (duration: 381.603914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:50.203848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:49.82101Z","time spent":"382.814062ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:57:50.203912Z","caller":"traceutil/trace.go:171","msg":"trace[1962364953] transaction","detail":"{read_only:false; response_revision:28708; number_of_response:1; }","duration":"382.776002ms","start":"2026-04-07T00:57:49.821129Z","end":"2026-04-07T00:57:50.203905Z","steps":["trace[1962364953] 'process raft request' (duration: 381.532882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:50.203966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:49.821122Z","time spent":"382.815503ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:57:50.204191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.1247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-07T00:57:50.204285Z","caller":"traceutil/trace.go:171","msg":"trace[2137847686] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28708; }","duration":"383.247053ms","start":"2026-04-07T00:57:49.821015Z","end":"2026-04-07T00:57:50.204262Z","steps":["trace[2137847686] 'agreement among raft nodes before linearized reading' (duration: 382.987217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:50.204336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:49.821001Z","time spent":"383.325385ms","remote":"127.0.0.1:45314","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-04-07T00:57:50.508165Z","caller":"traceutil/trace.go:171","msg":"trace[1183979007] linearizableReadLoop","detail":"{readStateIndex:30017; appliedIndex:30014; }","duration":"305.357047ms","start":"2026-04-07T00:57:50.202791Z","end":"2026-04-07T00:57:50.508148Z","steps":["trace[1183979007] 'read index received' (duration: 260.153105ms)","trace[1183979007] 'applied index is now lower than readState.Index' (duration: 45.203122ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:50.508235Z","caller":"traceutil/trace.go:171","msg":"trace[398519430] transaction","detail":"{read_only:false; response_revision:28710; number_of_response:1; }","duration":"353.798865ms","start":"2026-04-07T00:57:50.154387Z","end":"2026-04-07T00:57:50.508186Z","steps":["trace[398519430] 'process raft request' (duration: 353.693763ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:50.508432Z","caller":"traceutil/trace.go:171","msg":"trace[1809365098] transaction","detail":"{read_only:false; response_revision:28711; number_of_response:1; }","duration":"331.727022ms","start":"2026-04-07T00:57:50.176681Z","end":"2026-04-07T00:57:50.508408Z","steps":["trace[1809365098] 'process raft request' (duration: 331.433885ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:50.508464Z","caller":"traceutil/trace.go:171","msg":"trace[1264189924] transaction","detail":"{read_only:false; response_revision:28709; number_of_response:1; }","duration":"359.728886ms","start":"2026-04-07T00:57:50.148719Z","end":"2026-04-07T00:57:50.508448Z","steps":["trace[1264189924] 'process raft request' (duration: 314.211537ms)","trace[1264189924] 'compare' (duration: 44.936805ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:57:50.508505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.642788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:57:50.508551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:50.17666Z","time spent":"331.834494ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:57:50.50857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:50.148694Z","time spent":"359.805897ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:57:50.50858Z","caller":"traceutil/trace.go:171","msg":"trace[1743224754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28711; }","duration":"617.771091ms","start":"2026-04-07T00:57:49.890795Z","end":"2026-04-07T00:57:50.508566Z","steps":["trace[1743224754] 'agreement among raft nodes before linearized reading' (duration: 617.609997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:50.508614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:49.890774Z","time spent":"617.833993ms","remote":"127.0.0.1:45098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T00:57:50.508417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:50.154363Z","time spent":"353.961279ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:57:50.508871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.917419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:57:50.508923Z","caller":"traceutil/trace.go:171","msg":"trace[34694359] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:28711; }","duration":"686.975801ms","start":"2026-04-07T00:57:49.821923Z","end":"2026-04-07T00:57:50.508899Z","steps":["trace[34694359] 'agreement among raft nodes before linearized reading' (duration: 686.296655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:50.508947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:49.821874Z","time spent":"687.067703ms","remote":"127.0.0.1:45168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":30,"request content":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" "} {"level":"info","ts":"2026-04-07T00:57:50.550507Z","caller":"traceutil/trace.go:171","msg":"trace[1831780252] transaction","detail":"{read_only:false; response_revision:28712; number_of_response:1; }","duration":"340.449679ms","start":"2026-04-07T00:57:50.21003Z","end":"2026-04-07T00:57:50.55048Z","steps":["trace[1831780252] 'process raft request' (duration: 340.300306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:50.550677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:50.210009Z","time spent":"340.575762ms","remote":"127.0.0.1:45190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:57:50.793502Z","caller":"traceutil/trace.go:171","msg":"trace[507190342] transaction","detail":"{read_only:false; number_of_response:0; response_revision:28712; }","duration":"278.603712ms","start":"2026-04-07T00:57:50.514871Z","end":"2026-04-07T00:57:50.793475Z","steps":["trace[507190342] 'process raft request' (duration: 278.524861ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:50.793519Z","caller":"traceutil/trace.go:171","msg":"trace[1706966047] linearizableReadLoop","detail":"{readStateIndex:30019; appliedIndex:30019; }","duration":"242.277802ms","start":"2026-04-07T00:57:50.55121Z","end":"2026-04-07T00:57:50.793488Z","steps":["trace[1706966047] 'read index received' (duration: 242.266571ms)","trace[1706966047] 'applied index is now lower than readState.Index' (duration: 8.561µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:57:50.793751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.991411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-04-07T00:57:50.793826Z","caller":"traceutil/trace.go:171","msg":"trace[945052162] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28712; }","duration":"252.125355ms","start":"2026-04-07T00:57:50.541683Z","end":"2026-04-07T00:57:50.793809Z","steps":["trace[945052162] 'agreement among raft nodes before linearized reading' (duration: 251.888409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:50.793903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.757989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-07T00:57:50.793913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.467127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:57:50.794019Z","caller":"traceutil/trace.go:171","msg":"trace[2140996280] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:28712; }","duration":"112.59459ms","start":"2026-04-07T00:57:50.681399Z","end":"2026-04-07T00:57:50.793994Z","steps":["trace[2140996280] 'agreement among raft nodes before linearized reading' (duration: 112.397045ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:50.79396Z","caller":"traceutil/trace.go:171","msg":"trace[916734794] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28712; }","duration":"238.882072ms","start":"2026-04-07T00:57:50.55506Z","end":"2026-04-07T00:57:50.793942Z","steps":["trace[916734794] 'agreement among raft nodes before linearized reading' (duration: 238.689057ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:51.091553Z","caller":"traceutil/trace.go:171","msg":"trace[1825297475] transaction","detail":"{read_only:false; response_revision:28714; number_of_response:1; }","duration":"289.059501ms","start":"2026-04-07T00:57:50.802467Z","end":"2026-04-07T00:57:51.091526Z","steps":["trace[1825297475] 'process raft request' (duration: 253.795425ms)","trace[1825297475] 'compare' (duration: 34.633061ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:51.09156Z","caller":"traceutil/trace.go:171","msg":"trace[1392931789] transaction","detail":"{read_only:false; response_revision:28715; number_of_response:1; }","duration":"284.184926ms","start":"2026-04-07T00:57:50.807347Z","end":"2026-04-07T00:57:51.091532Z","steps":["trace[1392931789] 'process raft request' (duration: 283.887459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:51.091678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.116791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:57:51.091741Z","caller":"traceutil/trace.go:171","msg":"trace[2034333956] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:28715; }","duration":"243.256095ms","start":"2026-04-07T00:57:50.848471Z","end":"2026-04-07T00:57:51.091727Z","steps":["trace[2034333956] 'agreement among raft nodes before linearized reading' (duration: 243.126752ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:51.091558Z","caller":"traceutil/trace.go:171","msg":"trace[2061572630] linearizableReadLoop","detail":"{readStateIndex:30022; appliedIndex:30020; }","duration":"242.803804ms","start":"2026-04-07T00:57:50.848539Z","end":"2026-04-07T00:57:51.091342Z","steps":["trace[2061572630] 'read index received' (duration: 207.697782ms)","trace[2061572630] 'applied index is now lower than readState.Index' (duration: 35.105182ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:57:51.372869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.36803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:57:51.372973Z","caller":"traceutil/trace.go:171","msg":"trace[2012860466] linearizableReadLoop","detail":"{readStateIndex:30023; appliedIndex:30022; }","duration":"227.635835ms","start":"2026-04-07T00:57:51.14532Z","end":"2026-04-07T00:57:51.372956Z","steps":["trace[2012860466] 'read index received' (duration: 86.006519ms)","trace[2012860466] 'applied index is now lower than readState.Index' (duration: 141.627976ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:51.373014Z","caller":"traceutil/trace.go:171","msg":"trace[1176940670] transaction","detail":"{read_only:false; response_revision:28716; number_of_response:1; }","duration":"262.46668ms","start":"2026-04-07T00:57:51.110521Z","end":"2026-04-07T00:57:51.372987Z","steps":["trace[1176940670] 'process raft request' (duration: 120.898165ms)","trace[1176940670] 'compare' (duration: 141.253017ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:57:51.373156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.830989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-04-07T00:57:51.373191Z","caller":"traceutil/trace.go:171","msg":"trace[1102326964] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28716; }","duration":"227.89099ms","start":"2026-04-07T00:57:51.14529Z","end":"2026-04-07T00:57:51.373181Z","steps":["trace[1102326964] 'agreement among raft nodes before linearized reading' (duration: 227.750447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:51.373439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.030985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:57:51.373482Z","caller":"traceutil/trace.go:171","msg":"trace[1904050324] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:28716; }","duration":"223.111717ms","start":"2026-04-07T00:57:51.150357Z","end":"2026-04-07T00:57:51.373468Z","steps":["trace[1904050324] 'agreement among raft nodes before linearized reading' (duration: 223.052955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:51.373495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.199592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:57:51.373534Z","caller":"traceutil/trace.go:171","msg":"trace[1570360390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28716; }","duration":"102.287143ms","start":"2026-04-07T00:57:51.271237Z","end":"2026-04-07T00:57:51.373525Z","steps":["trace[1570360390] 'agreement among raft nodes before linearized reading' (duration: 102.207001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:51.373519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.688539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:57:51.373584Z","caller":"traceutil/trace.go:171","msg":"trace[683121182] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:28716; }","duration":"190.782601ms","start":"2026-04-07T00:57:51.182779Z","end":"2026-04-07T00:57:51.373562Z","steps":["trace[683121182] 'agreement among raft nodes before linearized reading' (duration: 190.668498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:51.67824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.734067ms","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-04-07T00:57:51.678356Z","caller":"traceutil/trace.go:171","msg":"trace[2032226059] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28716; }","duration":"138.870241ms","start":"2026-04-07T00:57:51.539447Z","end":"2026-04-07T00:57:51.678317Z","steps":["trace[2032226059] 'range keys from in-memory index tree' (duration: 138.536343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:51.678277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.921852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-04-07T00:57:51.67847Z","caller":"traceutil/trace.go:171","msg":"trace[1438057380] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:28716; }","duration":"120.143947ms","start":"2026-04-07T00:57:51.558318Z","end":"2026-04-07T00:57:51.678462Z","steps":["trace[1438057380] 'range keys from in-memory index tree' (duration: 119.84257ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:51.859213Z","caller":"traceutil/trace.go:171","msg":"trace[74979873] transaction","detail":"{read_only:false; response_revision:28717; number_of_response:1; }","duration":"177.814075ms","start":"2026-04-07T00:57:51.68138Z","end":"2026-04-07T00:57:51.859195Z","steps":["trace[74979873] 'process raft request' (duration: 177.282513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:52.00271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.941656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:57:52.002776Z","caller":"traceutil/trace.go:171","msg":"trace[664927642] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28719; }","duration":"113.047578ms","start":"2026-04-07T00:57:51.889712Z","end":"2026-04-07T00:57:52.002759Z","steps":["trace[664927642] 'range keys from in-memory index tree' (duration: 112.865614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:52.542316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.948911ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-04-07T00:57:52.542612Z","caller":"traceutil/trace.go:171","msg":"trace[146546552] transaction","detail":"{read_only:false; response_revision:28721; number_of_response:1; }","duration":"257.156685ms","start":"2026-04-07T00:57:52.285434Z","end":"2026-04-07T00:57:52.542591Z","steps":["trace[146546552] 'process raft request' (duration: 256.994681ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:52.542618Z","caller":"traceutil/trace.go:171","msg":"trace[1642668933] linearizableReadLoop","detail":"{readStateIndex:30028; appliedIndex:30027; }","duration":"262.040141ms","start":"2026-04-07T00:57:52.280549Z","end":"2026-04-07T00:57:52.542589Z","steps":["trace[1642668933] 'read index received' (duration: 17.777923ms)","trace[1642668933] 'applied index is now lower than readState.Index' (duration: 244.259818ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:57:52.542794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.19089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-04-07T00:57:52.542834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.948608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-04-07T00:57:52.542807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.169649ms","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-04-07T00:57:52.542865Z","caller":"traceutil/trace.go:171","msg":"trace[1158244319] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28721; }","duration":"335.00461ms","start":"2026-04-07T00:57:52.207848Z","end":"2026-04-07T00:57:52.542852Z","steps":["trace[1158244319] 'agreement among raft nodes before linearized reading' (duration: 334.845446ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:52.542872Z","caller":"traceutil/trace.go:171","msg":"trace[421079043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28721; }","duration":"334.291942ms","start":"2026-04-07T00:57:52.208558Z","end":"2026-04-07T00:57:52.54285Z","steps":["trace[421079043] 'agreement among raft nodes before linearized reading' (duration: 334.056006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:52.542888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:52.20784Z","time spent":"335.042781ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T00:57:52.542885Z","caller":"traceutil/trace.go:171","msg":"trace[120831797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28721; }","duration":"334.278721ms","start":"2026-04-07T00:57:52.208586Z","end":"2026-04-07T00:57:52.542865Z","steps":["trace[120831797] 'agreement among raft nodes before linearized reading' (duration: 334.011515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:52.542913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:52.208554Z","time spent":"334.349823ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T00:57:52.542924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:52.208575Z","time spent":"334.341014ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T00:57:52.80852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.864607ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:57:52.808786Z","caller":"traceutil/trace.go:171","msg":"trace[1419724017] transaction","detail":"{read_only:false; response_revision:28722; number_of_response:1; }","duration":"295.484733ms","start":"2026-04-07T00:57:52.513262Z","end":"2026-04-07T00:57:52.808746Z","steps":["trace[1419724017] 'process raft request' (duration: 141.279738ms)","trace[1419724017] 'compare' (duration: 153.643082ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:52.808933Z","caller":"traceutil/trace.go:171","msg":"trace[8009176] transaction","detail":"{read_only:false; response_revision:28724; number_of_response:1; }","duration":"293.24398ms","start":"2026-04-07T00:57:52.515672Z","end":"2026-04-07T00:57:52.808916Z","steps":["trace[8009176] 'process raft request' (duration: 293.136507ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:52.808927Z","caller":"traceutil/trace.go:171","msg":"trace[2059574665] transaction","detail":"{read_only:false; response_revision:28723; number_of_response:1; }","duration":"293.365222ms","start":"2026-04-07T00:57:52.515526Z","end":"2026-04-07T00:57:52.808891Z","steps":["trace[2059574665] 'process raft request' (duration: 293.137577ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:52.809072Z","caller":"traceutil/trace.go:171","msg":"trace[956291071] transaction","detail":"{read_only:false; response_revision:28727; number_of_response:1; }","duration":"261.811515ms","start":"2026-04-07T00:57:52.547243Z","end":"2026-04-07T00:57:52.809055Z","steps":["trace[956291071] 'process raft request' (duration: 261.777634ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:52.809088Z","caller":"traceutil/trace.go:171","msg":"trace[1725352026] transaction","detail":"{read_only:false; response_revision:28725; number_of_response:1; }","duration":"262.339228ms","start":"2026-04-07T00:57:52.546706Z","end":"2026-04-07T00:57:52.809045Z","steps":["trace[1725352026] 'process raft request' (duration: 262.166834ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:52.809099Z","caller":"traceutil/trace.go:171","msg":"trace[184838906] transaction","detail":"{read_only:false; response_revision:28726; number_of_response:1; }","duration":"262.323347ms","start":"2026-04-07T00:57:52.546742Z","end":"2026-04-07T00:57:52.809066Z","steps":["trace[184838906] 'process raft request' (duration: 262.238835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:52.809174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.124996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7350"} {"level":"info","ts":"2026-04-07T00:57:52.809187Z","caller":"traceutil/trace.go:171","msg":"trace[986818622] linearizableReadLoop","detail":"{readStateIndex:30032; appliedIndex:30029; }","duration":"266.378823ms","start":"2026-04-07T00:57:52.542667Z","end":"2026-04-07T00:57:52.809046Z","steps":["trace[986818622] 'read index received' (duration: 111.920972ms)","trace[986818622] 'applied index is now lower than readState.Index' (duration: 154.457181ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:52.809223Z","caller":"traceutil/trace.go:171","msg":"trace[837024674] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:28727; }","duration":"420.216449ms","start":"2026-04-07T00:57:52.388994Z","end":"2026-04-07T00:57:52.80921Z","steps":["trace[837024674] 'agreement among raft nodes before linearized reading' (duration: 420.056095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:52.809247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:52.388974Z","time spent":"420.26852ms","remote":"127.0.0.1:50282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7374,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"info","ts":"2026-04-07T00:57:52.811453Z","caller":"traceutil/trace.go:171","msg":"trace[1557778419] transaction","detail":"{read_only:false; response_revision:28728; number_of_response:1; }","duration":"156.744175ms","start":"2026-04-07T00:57:52.654678Z","end":"2026-04-07T00:57:52.811422Z","steps":["trace[1557778419] 'process raft request' (duration: 156.580501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:52.81148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.217357ms","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-04-07T00:57:52.811541Z","caller":"traceutil/trace.go:171","msg":"trace[1576788858] 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:28728; }","duration":"260.32061ms","start":"2026-04-07T00:57:52.551205Z","end":"2026-04-07T00:57:52.811526Z","steps":["trace[1576788858] 'agreement among raft nodes before linearized reading' (duration: 260.172246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:54.221085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.231094ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:57:54.221254Z","caller":"traceutil/trace.go:171","msg":"trace[1461889529] transaction","detail":"{read_only:false; response_revision:28736; number_of_response:1; }","duration":"351.408008ms","start":"2026-04-07T00:57:53.869833Z","end":"2026-04-07T00:57:54.221241Z","steps":["trace[1461889529] 'process raft request' (duration: 351.329556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:54.221313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:53.86982Z","time spent":"351.463129ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:57:54.221404Z","caller":"traceutil/trace.go:171","msg":"trace[984779991] transaction","detail":"{read_only:false; response_revision:28735; number_of_response:1; }","duration":"351.942842ms","start":"2026-04-07T00:57:53.869454Z","end":"2026-04-07T00:57:54.221397Z","steps":["trace[984779991] 'process raft request' (duration: 165.307038ms)","trace[984779991] 'compare' (duration: 186.005209ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:57:54.221441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:53.869431Z","time spent":"351.993162ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:57:54.307127Z","caller":"traceutil/trace.go:171","msg":"trace[932146716] transaction","detail":"{read_only:false; response_revision:28737; number_of_response:1; }","duration":"324.90672ms","start":"2026-04-07T00:57:53.98218Z","end":"2026-04-07T00:57:54.307087Z","steps":["trace[932146716] 'process raft request' (duration: 324.705955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:54.307437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:53.98215Z","time spent":"325.072434ms","remote":"127.0.0.1:50282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7333,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:57:54.516652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.954929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:57:54.51674Z","caller":"traceutil/trace.go:171","msg":"trace[254832389] linearizableReadLoop","detail":"{readStateIndex:30046; appliedIndex:30045; }","duration":"204.849184ms","start":"2026-04-07T00:57:54.311878Z","end":"2026-04-07T00:57:54.516727Z","steps":["trace[254832389] 'read index received' (duration: 66.741382ms)","trace[254832389] 'applied index is now lower than readState.Index' (duration: 138.106672ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:54.51683Z","caller":"traceutil/trace.go:171","msg":"trace[1256598862] transaction","detail":"{read_only:false; response_revision:28738; number_of_response:1; }","duration":"205.455369ms","start":"2026-04-07T00:57:54.311343Z","end":"2026-04-07T00:57:54.516798Z","steps":["trace[1256598862] 'process raft request' (duration: 67.288715ms)","trace[1256598862] 'compare' (duration: 137.401616ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:57:54.516936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.03848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:17029"} {"level":"info","ts":"2026-04-07T00:57:54.517Z","caller":"traceutil/trace.go:171","msg":"trace[725581447] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:28738; }","duration":"205.133162ms","start":"2026-04-07T00:57:54.311851Z","end":"2026-04-07T00:57:54.516985Z","steps":["trace[725581447] 'agreement among raft nodes before linearized reading' (duration: 204.962197ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:54.529383Z","caller":"traceutil/trace.go:171","msg":"trace[1777829426] transaction","detail":"{read_only:false; response_revision:28739; number_of_response:1; }","duration":"105.134032ms","start":"2026-04-07T00:57:54.424214Z","end":"2026-04-07T00:57:54.529348Z","steps":["trace[1777829426] 'process raft request' (duration: 104.954808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:54.529465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.525216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:57:54.529498Z","caller":"traceutil/trace.go:171","msg":"trace[1775383992] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:28739; }","duration":"144.612888ms","start":"2026-04-07T00:57:54.384876Z","end":"2026-04-07T00:57:54.529489Z","steps":["trace[1775383992] 'agreement among raft nodes before linearized reading' (duration: 144.414634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:54.529533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.437417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11828"} {"level":"info","ts":"2026-04-07T00:57:54.529619Z","caller":"traceutil/trace.go:171","msg":"trace[1995130608] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:28739; }","duration":"118.57182ms","start":"2026-04-07T00:57:54.411011Z","end":"2026-04-07T00:57:54.529583Z","steps":["trace[1995130608] 'agreement among raft nodes before linearized reading' (duration: 118.346774ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:56.326505Z","caller":"traceutil/trace.go:171","msg":"trace[84694879] transaction","detail":"{read_only:false; response_revision:28752; number_of_response:1; }","duration":"100.917382ms","start":"2026-04-07T00:57:56.225564Z","end":"2026-04-07T00:57:56.326481Z","steps":["trace[84694879] 'process raft request' (duration: 100.764468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:56.588326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.352649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:57:56.588402Z","caller":"traceutil/trace.go:171","msg":"trace[82793366] linearizableReadLoop","detail":"{readStateIndex:30061; appliedIndex:30060; }","duration":"197.701776ms","start":"2026-04-07T00:57:56.390688Z","end":"2026-04-07T00:57:56.58839Z","steps":["trace[82793366] 'read index received' (duration: 78.118852ms)","trace[82793366] 'applied index is now lower than readState.Index' (duration: 119.581964ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:56.588448Z","caller":"traceutil/trace.go:171","msg":"trace[1719684835] transaction","detail":"{read_only:false; response_revision:28753; number_of_response:1; }","duration":"362.707316ms","start":"2026-04-07T00:57:56.225719Z","end":"2026-04-07T00:57:56.588426Z","steps":["trace[1719684835] 'process raft request' (duration: 243.181693ms)","trace[1719684835] 'compare' (duration: 118.906038ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:57:56.588551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:56.2257Z","time spent":"362.797107ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:57:56.588556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.87707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"warn","ts":"2026-04-07T00:57:56.588651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.020307ms","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-04-07T00:57:56.588667Z","caller":"traceutil/trace.go:171","msg":"trace[1566207457] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28753; }","duration":"198.024773ms","start":"2026-04-07T00:57:56.390636Z","end":"2026-04-07T00:57:56.588661Z","steps":["trace[1566207457] 'agreement among raft nodes before linearized reading' (duration: 197.817608ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:56.588673Z","caller":"traceutil/trace.go:171","msg":"trace[1127750398] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:28753; }","duration":"145.041947ms","start":"2026-04-07T00:57:56.443625Z","end":"2026-04-07T00:57:56.588666Z","steps":["trace[1127750398] 'agreement among raft nodes before linearized reading' (duration: 144.949665ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:56.843607Z","caller":"traceutil/trace.go:171","msg":"trace[1366655609] linearizableReadLoop","detail":"{readStateIndex:30063; appliedIndex:30061; }","duration":"249.498393ms","start":"2026-04-07T00:57:56.594082Z","end":"2026-04-07T00:57:56.84358Z","steps":["trace[1366655609] 'read index received' (duration: 84.956663ms)","trace[1366655609] 'applied index is now lower than readState.Index' (duration: 164.54067ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:56.843726Z","caller":"traceutil/trace.go:171","msg":"trace[281697279] transaction","detail":"{read_only:false; response_revision:28755; number_of_response:1; }","duration":"250.136328ms","start":"2026-04-07T00:57:56.593552Z","end":"2026-04-07T00:57:56.843688Z","steps":["trace[281697279] 'process raft request' (duration: 249.859232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:56.843872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.7849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-04-07T00:57:56.843948Z","caller":"traceutil/trace.go:171","msg":"trace[788349934] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:28755; }","duration":"249.893002ms","start":"2026-04-07T00:57:56.594044Z","end":"2026-04-07T00:57:56.843937Z","steps":["trace[788349934] 'agreement among raft nodes before linearized reading' (duration: 249.678087ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:56.956447Z","caller":"traceutil/trace.go:171","msg":"trace[1191332083] linearizableReadLoop","detail":"{readStateIndex:30066; appliedIndex:30063; }","duration":"112.757512ms","start":"2026-04-07T00:57:56.843676Z","end":"2026-04-07T00:57:56.956433Z","steps":["trace[1191332083] 'read index received' (duration: 103.79755ms)","trace[1191332083] 'applied index is now lower than readState.Index' (duration: 8.959182ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:56.956508Z","caller":"traceutil/trace.go:171","msg":"trace[822859789] transaction","detail":"{read_only:false; response_revision:28756; number_of_response:1; }","duration":"135.352247ms","start":"2026-04-07T00:57:56.821132Z","end":"2026-04-07T00:57:56.956484Z","steps":["trace[822859789] 'process raft request' (duration: 126.373505ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:56.956526Z","caller":"traceutil/trace.go:171","msg":"trace[1265637182] transaction","detail":"{read_only:false; response_revision:28758; number_of_response:1; }","duration":"132.877629ms","start":"2026-04-07T00:57:56.823596Z","end":"2026-04-07T00:57:56.956474Z","steps":["trace[1265637182] 'process raft request' (duration: 132.812497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:56.956619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.644327ms","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-04-07T00:57:56.956651Z","caller":"traceutil/trace.go:171","msg":"trace[1622816715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28758; }","duration":"125.696888ms","start":"2026-04-07T00:57:56.830944Z","end":"2026-04-07T00:57:56.956641Z","steps":["trace[1622816715] 'agreement among raft nodes before linearized reading' (duration: 125.579975ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:56.956723Z","caller":"traceutil/trace.go:171","msg":"trace[461904314] transaction","detail":"{read_only:false; response_revision:28757; number_of_response:1; }","duration":"133.641116ms","start":"2026-04-07T00:57:56.823067Z","end":"2026-04-07T00:57:56.956709Z","steps":["trace[461904314] 'process raft request' (duration: 133.310299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:56.956767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.185254ms","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-04-07T00:57:56.956792Z","caller":"traceutil/trace.go:171","msg":"trace[785899749] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28758; }","duration":"127.250145ms","start":"2026-04-07T00:57:56.829534Z","end":"2026-04-07T00:57:56.956784Z","steps":["trace[785899749] 'agreement among raft nodes before linearized reading' (duration: 127.193914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:56.95687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.532067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-07T00:57:56.95689Z","caller":"traceutil/trace.go:171","msg":"trace[834249946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28758; }","duration":"131.585468ms","start":"2026-04-07T00:57:56.8253Z","end":"2026-04-07T00:57:56.956885Z","steps":["trace[834249946] 'agreement among raft nodes before linearized reading' (duration: 131.537127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:56.956953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.812139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:57:56.956973Z","caller":"traceutil/trace.go:171","msg":"trace[526289473] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:28758; }","duration":"273.86768ms","start":"2026-04-07T00:57:56.6831Z","end":"2026-04-07T00:57:56.956968Z","steps":["trace[526289473] 'agreement among raft nodes before linearized reading' (duration: 273.83464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:57.031525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.264454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17029"} {"level":"info","ts":"2026-04-07T00:57:57.031588Z","caller":"traceutil/trace.go:171","msg":"trace[1010244816] transaction","detail":"{read_only:false; response_revision:28759; number_of_response:1; }","duration":"152.873033ms","start":"2026-04-07T00:57:56.878684Z","end":"2026-04-07T00:57:57.031557Z","steps":["trace[1010244816] 'process raft request' (duration: 152.509024ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:57.031608Z","caller":"traceutil/trace.go:171","msg":"trace[1792354223] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:28759; }","duration":"186.383956ms","start":"2026-04-07T00:57:56.845206Z","end":"2026-04-07T00:57:57.03159Z","steps":["trace[1792354223] 'agreement among raft nodes before linearized reading' (duration: 186.162451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:57.032005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.642193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:36 size:288284"} {"level":"info","ts":"2026-04-07T00:57:57.03206Z","caller":"traceutil/trace.go:171","msg":"trace[337338585] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:36; response_revision:28759; }","duration":"167.727005ms","start":"2026-04-07T00:57:56.864321Z","end":"2026-04-07T00:57:57.032048Z","steps":["trace[337338585] 'agreement among raft nodes before linearized reading' (duration: 167.154052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:57.035485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.383073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-07T00:57:57.035543Z","caller":"traceutil/trace.go:171","msg":"trace[1943048487] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:28759; }","duration":"136.462624ms","start":"2026-04-07T00:57:56.899066Z","end":"2026-04-07T00:57:57.035528Z","steps":["trace[1943048487] 'agreement among raft nodes before linearized reading' (duration: 132.378397ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:57.326293Z","caller":"traceutil/trace.go:171","msg":"trace[598973163] linearizableReadLoop","detail":"{readStateIndex:30070; appliedIndex:30067; }","duration":"289.661214ms","start":"2026-04-07T00:57:57.036608Z","end":"2026-04-07T00:57:57.326269Z","steps":["trace[598973163] 'read index received' (duration: 289.438029ms)","trace[598973163] 'applied index is now lower than readState.Index' (duration: 221.965µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:57.326606Z","caller":"traceutil/trace.go:171","msg":"trace[708581253] transaction","detail":"{read_only:false; response_revision:28762; number_of_response:1; }","duration":"365.951073ms","start":"2026-04-07T00:57:56.960638Z","end":"2026-04-07T00:57:57.326589Z","steps":["trace[708581253] 'process raft request' (duration: 365.591304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:57.326735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:56.960632Z","time spent":"366.016225ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:57:57.326787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.155467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-07T00:57:57.326892Z","caller":"traceutil/trace.go:171","msg":"trace[1692463133] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28762; }","duration":"290.302231ms","start":"2026-04-07T00:57:57.036553Z","end":"2026-04-07T00:57:57.326855Z","steps":["trace[1692463133] 'agreement among raft nodes before linearized reading' (duration: 290.047304ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:57.326943Z","caller":"traceutil/trace.go:171","msg":"trace[374420992] transaction","detail":"{read_only:false; response_revision:28761; number_of_response:1; }","duration":"366.324753ms","start":"2026-04-07T00:57:56.960608Z","end":"2026-04-07T00:57:57.326932Z","steps":["trace[374420992] 'process raft request' (duration: 365.579624ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:57.326935Z","caller":"traceutil/trace.go:171","msg":"trace[592407899] transaction","detail":"{read_only:false; response_revision:28760; number_of_response:1; }","duration":"366.333163ms","start":"2026-04-07T00:57:56.960565Z","end":"2026-04-07T00:57:57.326898Z","steps":["trace[592407899] 'process raft request' (duration: 365.454711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:57.327023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:56.960596Z","time spent":"366.378734ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:57:57.327091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:56.960546Z","time spent":"366.461105ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:57:57.749871Z","caller":"traceutil/trace.go:171","msg":"trace[822744645] linearizableReadLoop","detail":"{readStateIndex:30071; appliedIndex:30070; }","duration":"423.484317ms","start":"2026-04-07T00:57:57.326363Z","end":"2026-04-07T00:57:57.749847Z","steps":["trace[822744645] 'read index received' (duration: 423.325924ms)","trace[822744645] 'applied index is now lower than readState.Index' (duration: 157.113µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:57.749913Z","caller":"traceutil/trace.go:171","msg":"trace[161214639] transaction","detail":"{read_only:false; response_revision:28763; number_of_response:1; }","duration":"649.474312ms","start":"2026-04-07T00:57:57.100413Z","end":"2026-04-07T00:57:57.749888Z","steps":["trace[161214639] 'process raft request' (duration: 649.253137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:57.750019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:57.100396Z","time spent":"649.565414ms","remote":"127.0.0.1:59688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:57:57.750115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.349427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.35\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-04-07T00:57:57.750183Z","caller":"traceutil/trace.go:171","msg":"trace[436317518] range","detail":"{range_begin:/registry/masterleases/199.19.213.35; range_end:; response_count:1; response_revision:28763; }","duration":"640.454829ms","start":"2026-04-07T00:57:57.10969Z","end":"2026-04-07T00:57:57.750145Z","steps":["trace[436317518] 'agreement among raft nodes before linearized reading' (duration: 640.273685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:57.750232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:57.109664Z","time spent":"640.555361ms","remote":"127.0.0.1:45122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":161,"request content":"key:\"/registry/masterleases/199.19.213.35\" "} {"level":"info","ts":"2026-04-07T00:57:57.759648Z","caller":"traceutil/trace.go:171","msg":"trace[518722407] transaction","detail":"{read_only:false; response_revision:28764; number_of_response:1; }","duration":"427.476442ms","start":"2026-04-07T00:57:57.332162Z","end":"2026-04-07T00:57:57.759638Z","steps":["trace[518722407] 'process raft request' (duration: 427.317868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:57.759696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:57.33214Z","time spent":"427.534393ms","remote":"127.0.0.1:45190","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:57:57.759744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.493728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:57:57.759809Z","caller":"traceutil/trace.go:171","msg":"trace[57661702] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:28764; }","duration":"307.584989ms","start":"2026-04-07T00:57:57.452207Z","end":"2026-04-07T00:57:57.759792Z","steps":["trace[57661702] 'agreement among raft nodes before linearized reading' (duration: 307.453236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:57.759848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:57.452196Z","time spent":"307.643161ms","remote":"127.0.0.1:40904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:57:57.759944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.583929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-04-07T00:57:57.759974Z","caller":"traceutil/trace.go:171","msg":"trace[1769279033] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28764; }","duration":"334.61266ms","start":"2026-04-07T00:57:57.425353Z","end":"2026-04-07T00:57:57.759965Z","steps":["trace[1769279033] 'agreement among raft nodes before linearized reading' (duration: 334.21127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:57.759993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:57.425317Z","time spent":"334.670861ms","remote":"127.0.0.1:45190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":686,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-04-07T00:57:58.123701Z","caller":"traceutil/trace.go:171","msg":"trace[563755262] linearizableReadLoop","detail":"{readStateIndex:30074; appliedIndex:30072; }","duration":"357.963454ms","start":"2026-04-07T00:57:57.765715Z","end":"2026-04-07T00:57:58.123678Z","steps":["trace[563755262] 'read index received' (duration: 357.717908ms)","trace[563755262] 'applied index is now lower than readState.Index' (duration: 244.746µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:57:58.123736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:57.753122Z","time spent":"370.606073ms","remote":"127.0.0.1:45122","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-07T00:57:58.123794Z","caller":"traceutil/trace.go:171","msg":"trace[1363610194] transaction","detail":"{read_only:false; number_of_response:0; response_revision:28764; }","duration":"364.928719ms","start":"2026-04-07T00:57:57.758832Z","end":"2026-04-07T00:57:58.123761Z","steps":["trace[1363610194] 'process raft request' (duration: 364.783555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:58.123921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:57.758818Z","time spent":"365.042941ms","remote":"127.0.0.1:59688","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":30,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:57:58.123965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.655212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-07T00:57:58.124007Z","caller":"traceutil/trace.go:171","msg":"trace[319699793] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:28764; }","duration":"353.730143ms","start":"2026-04-07T00:57:57.770265Z","end":"2026-04-07T00:57:58.123995Z","steps":["trace[319699793] 'agreement among raft nodes before linearized reading' (duration: 353.536908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:58.124043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:57.770259Z","time spent":"353.775974ms","remote":"127.0.0.1:45314","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-04-07T00:57:58.124062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.315463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-07T00:57:58.12415Z","caller":"traceutil/trace.go:171","msg":"trace[1079250237] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:28764; }","duration":"358.457745ms","start":"2026-04-07T00:57:57.765669Z","end":"2026-04-07T00:57:58.124127Z","steps":["trace[1079250237] 'agreement among raft nodes before linearized reading' (duration: 358.091677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:58.124205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:57.765651Z","time spent":"358.541358ms","remote":"127.0.0.1:46360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":171,"response size":33,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:57:58.124357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.456933ms","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-04-07T00:57:58.124415Z","caller":"traceutil/trace.go:171","msg":"trace[1979507431] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28764; }","duration":"217.609166ms","start":"2026-04-07T00:57:57.906792Z","end":"2026-04-07T00:57:58.124401Z","steps":["trace[1979507431] 'agreement among raft nodes before linearized reading' (duration: 217.476093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:58.124549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.224354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-04-07T00:57:58.124608Z","caller":"traceutil/trace.go:171","msg":"trace[672377231] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:28764; }","duration":"354.292116ms","start":"2026-04-07T00:57:57.770298Z","end":"2026-04-07T00:57:58.12459Z","steps":["trace[672377231] 'agreement among raft nodes before linearized reading' (duration: 354.086891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:58.124649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:57:57.770294Z","time spent":"354.347157ms","remote":"127.0.0.1:45314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":594,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T00:57:58.124741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.803238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:57:58.124792Z","caller":"traceutil/trace.go:171","msg":"trace[1734581198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28764; }","duration":"235.88934ms","start":"2026-04-07T00:57:57.888887Z","end":"2026-04-07T00:57:58.124776Z","steps":["trace[1734581198] 'agreement among raft nodes before linearized reading' (duration: 235.814518ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:58.317215Z","caller":"traceutil/trace.go:171","msg":"trace[1221423683] transaction","detail":"{read_only:false; response_revision:28766; number_of_response:1; }","duration":"185.463665ms","start":"2026-04-07T00:57:58.131718Z","end":"2026-04-07T00:57:58.317182Z","steps":["trace[1221423683] 'process raft request' (duration: 185.170549ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:58.317489Z","caller":"traceutil/trace.go:171","msg":"trace[436475372] transaction","detail":"{read_only:false; response_revision:28767; number_of_response:1; }","duration":"185.687331ms","start":"2026-04-07T00:57:58.131781Z","end":"2026-04-07T00:57:58.317468Z","steps":["trace[436475372] 'process raft request' (duration: 185.312222ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:58.317676Z","caller":"traceutil/trace.go:171","msg":"trace[1767382669] transaction","detail":"{read_only:false; response_revision:28768; number_of_response:1; }","duration":"185.799864ms","start":"2026-04-07T00:57:58.131867Z","end":"2026-04-07T00:57:58.317667Z","steps":["trace[1767382669] 'process raft request' (duration: 185.512877ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:58.317859Z","caller":"traceutil/trace.go:171","msg":"trace[330752735] linearizableReadLoop","detail":"{readStateIndex:30079; appliedIndex:30075; }","duration":"161.145288ms","start":"2026-04-07T00:57:58.156677Z","end":"2026-04-07T00:57:58.317822Z","steps":["trace[330752735] 'read index received' (duration: 160.191396ms)","trace[330752735] 'applied index is now lower than readState.Index' (duration: 951.002µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:58.317931Z","caller":"traceutil/trace.go:171","msg":"trace[1751618160] transaction","detail":"{read_only:false; response_revision:28769; number_of_response:1; }","duration":"182.682759ms","start":"2026-04-07T00:57:58.135212Z","end":"2026-04-07T00:57:58.317895Z","steps":["trace[1751618160] 'process raft request' (duration: 182.392462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:57:58.318242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.388705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-07T00:57:58.318272Z","caller":"traceutil/trace.go:171","msg":"trace[1532853259] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:28769; }","duration":"161.642421ms","start":"2026-04-07T00:57:58.156622Z","end":"2026-04-07T00:57:58.318265Z","steps":["trace[1532853259] 'agreement among raft nodes before linearized reading' (duration: 161.342294ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:58.350583Z","caller":"traceutil/trace.go:171","msg":"trace[77028543] transaction","detail":"{read_only:false; response_revision:28770; number_of_response:1; }","duration":"129.718014ms","start":"2026-04-07T00:57:58.220842Z","end":"2026-04-07T00:57:58.35056Z","steps":["trace[77028543] 'process raft request' (duration: 129.55187ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:59.078791Z","caller":"traceutil/trace.go:171","msg":"trace[818015646] transaction","detail":"{read_only:false; response_revision:28782; number_of_response:1; }","duration":"116.562973ms","start":"2026-04-07T00:57:58.962194Z","end":"2026-04-07T00:57:59.078757Z","steps":["trace[818015646] 'process raft request' (duration: 71.779982ms)","trace[818015646] 'compare' (duration: 44.656818ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:57:59.105325Z","caller":"traceutil/trace.go:171","msg":"trace[543253543] transaction","detail":"{read_only:false; response_revision:28784; number_of_response:1; }","duration":"140.398317ms","start":"2026-04-07T00:57:58.964899Z","end":"2026-04-07T00:57:59.105297Z","steps":["trace[543253543] 'process raft request' (duration: 140.338265ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:57:59.105431Z","caller":"traceutil/trace.go:171","msg":"trace[2045287269] transaction","detail":"{read_only:false; response_revision:28783; number_of_response:1; }","duration":"143.04529ms","start":"2026-04-07T00:57:58.96236Z","end":"2026-04-07T00:57:59.105405Z","steps":["trace[2045287269] 'process raft request' (duration: 142.674632ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:00:44.096715Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27317} {"level":"info","ts":"2026-04-07T01:00:44.258546Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27317,"took":"155.619749ms","hash":3770250272,"current-db-size-bytes":61378560,"current-db-size":"61 MB","current-db-size-in-use-bytes":37154816,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-04-07T01:00:44.258648Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3770250272,"revision":27317,"compact-revision":23720} {"level":"info","ts":"2026-04-07T01:05:44.103312Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30764} {"level":"info","ts":"2026-04-07T01:05:44.249037Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30764,"took":"141.362ms","hash":1016550030,"current-db-size-bytes":61378560,"current-db-size":"61 MB","current-db-size-in-use-bytes":36605952,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-04-07T01:05:44.249102Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1016550030,"revision":30764,"compact-revision":27317} {"level":"info","ts":"2026-04-07T01:10:44.109958Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33704} {"level":"info","ts":"2026-04-07T01:10:44.252711Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33704,"took":"137.896879ms","hash":4097308460,"current-db-size-bytes":61378560,"current-db-size":"61 MB","current-db-size-in-use-bytes":34607104,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-04-07T01:10:44.252796Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4097308460,"revision":33704,"compact-revision":30764} {"level":"info","ts":"2026-04-07T01:14:18.794349Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d1606b245347dc08","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T01:14:18.799266Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-07T01:14:18.799353Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-04-07T01:15:44.11717Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36378} {"level":"info","ts":"2026-04-07T01:15:44.242636Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36378,"took":"121.249224ms","hash":1276971868,"current-db-size-bytes":61378560,"current-db-size":"61 MB","current-db-size-in-use-bytes":35024896,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-04-07T01:15:44.242693Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1276971868,"revision":36378,"compact-revision":33704} {"level":"info","ts":"2026-04-07T01:20:44.123587Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39161} {"level":"info","ts":"2026-04-07T01:20:44.282312Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39161,"took":"152.844894ms","hash":3616489085,"current-db-size-bytes":61378560,"current-db-size":"61 MB","current-db-size-in-use-bytes":32989184,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-07T01:20:44.282378Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3616489085,"revision":39161,"compact-revision":36378} {"level":"info","ts":"2026-04-07T01:25:44.12937Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41835} {"level":"info","ts":"2026-04-07T01:25:44.27232Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41835,"took":"137.454814ms","hash":2038819000,"current-db-size-bytes":61378560,"current-db-size":"61 MB","current-db-size-in-use-bytes":34058240,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-07T01:25:44.272393Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2038819000,"revision":41835,"compact-revision":39161}