{"level":"warn","ts":"2026-07-28T09:57:51.571194Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-28T09:57:51.571277Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-07-28T09:57:51.571338Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-28T09:57:51.571348Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-07-28T09:57:51.571373Z","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-07-28T09:57:51.571969Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-07-28T09:57:51.572055Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-07-28T09:57:51.575299Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.064935ms"} {"level":"info","ts":"2026-07-28T09:57:51.579874Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-07-28T09:57:51.580439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-07-28T09:57:51.580479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-07-28T09:57:51.580515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-28T09:57:51.580529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-07-28T09:57:51.580745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-07-28T09:57:51.585612Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-28T09:57:51.587062Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-28T09:57:51.588231Z","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-07-28T09:57:51.590599Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-28T09:57:51.590793Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-28T09:57:51.590773Z","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-07-28T09:57:51.590907Z","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-07-28T09:57:51.590934Z","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-07-28T09:57:51.595618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-07-28T09:57:51.595778Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-07-28T09:57:51.600089Z","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-07-28T09:57:51.600261Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-07-28T09:57:51.600375Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-07-28T09:57:51.60051Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-28T09:57:51.600559Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-28T09:57:52.08117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-07-28T09:57:52.081242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-28T09:57:52.081283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-07-28T09:57:52.08131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-07-28T09:57:52.081327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-07-28T09:57:52.081349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-07-28T09:57:52.081369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-07-28T09:57:52.082435Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T09:57:52.08333Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-07-28T09:57:52.083593Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-28T09:57:52.083701Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T09:57:52.083878Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T09:57:52.083693Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-28T09:57:52.083929Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-28T09:57:52.087559Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-07-28T09:57:52.087815Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-28T09:57:52.09311Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-28T09:57:52.093135Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-28T09:58:27.598939Z","caller":"traceutil/trace.go:171","msg":"trace[1555152599] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"141.958523ms","start":"2026-07-28T09:58:27.456945Z","end":"2026-07-28T09:58:27.598903Z","steps":["trace[1555152599] 'read index received' (duration: 141.687305ms)","trace[1555152599] 'applied index is now lower than readState.Index' (duration: 269.848µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:58:27.599004Z","caller":"traceutil/trace.go:171","msg":"trace[863909775] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"218.256292ms","start":"2026-07-28T09:58:27.380705Z","end":"2026-07-28T09:58:27.598961Z","steps":["trace[863909775] 'process raft request' (duration: 217.978125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:58:27.599149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.182101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:58:27.5993Z","caller":"traceutil/trace.go:171","msg":"trace[612814990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"142.378746ms","start":"2026-07-28T09:58:27.45689Z","end":"2026-07-28T09:58:27.599269Z","steps":["trace[612814990] 'agreement among raft nodes before linearized reading' (duration: 142.16569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:58:27.938246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.508125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-28T09:58:27.938333Z","caller":"traceutil/trace.go:171","msg":"trace[1754990713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:523; }","duration":"152.613108ms","start":"2026-07-28T09:58:27.785697Z","end":"2026-07-28T09:58:27.93831Z","steps":["trace[1754990713] 'range keys from in-memory index tree' (duration: 152.325401ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:58:27.939967Z","caller":"traceutil/trace.go:171","msg":"trace[1231571414] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"104.492218ms","start":"2026-07-28T09:58:27.835445Z","end":"2026-07-28T09:58:27.939937Z","steps":["trace[1231571414] 'process raft request' (duration: 104.336834ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:58:27.946036Z","caller":"traceutil/trace.go:171","msg":"trace[1584256603] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"107.944035ms","start":"2026-07-28T09:58:27.838062Z","end":"2026-07-28T09:58:27.946006Z","steps":["trace[1584256603] 'process raft request' (duration: 107.658256ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:58:27.946103Z","caller":"traceutil/trace.go:171","msg":"trace[2005433429] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"107.939024ms","start":"2026-07-28T09:58:27.838138Z","end":"2026-07-28T09:58:27.946077Z","steps":["trace[2005433429] 'process raft request' (duration: 107.75091ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:58:34.337133Z","caller":"traceutil/trace.go:171","msg":"trace[1396082432] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"183.414109ms","start":"2026-07-28T09:58:34.153659Z","end":"2026-07-28T09:58:34.337073Z","steps":["trace[1396082432] 'process raft request' (duration: 177.584568ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:58:34.957819Z","caller":"traceutil/trace.go:171","msg":"trace[216495461] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"559.205447ms","start":"2026-07-28T09:58:34.398584Z","end":"2026-07-28T09:58:34.95779Z","steps":["trace[216495461] 'process raft request' (duration: 547.512284ms)","trace[216495461] 'compare' (duration: 11.369654ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T09:58:34.957906Z","caller":"traceutil/trace.go:171","msg":"trace[765689201] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"501.03132ms","start":"2026-07-28T09:58:34.456864Z","end":"2026-07-28T09:58:34.957896Z","steps":["trace[765689201] 'read index received' (duration: 489.313286ms)","trace[765689201] 'applied index is now lower than readState.Index' (duration: 11.717124ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T09:58:34.958082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.690422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5wz6f\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-28T09:58:34.958127Z","caller":"traceutil/trace.go:171","msg":"trace[720222056] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5wz6f; range_end:; response_count:1; response_revision:632; }","duration":"236.785925ms","start":"2026-07-28T09:58:34.721328Z","end":"2026-07-28T09:58:34.958113Z","steps":["trace[720222056] 'agreement among raft nodes before linearized reading' (duration: 236.654851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:58:34.958132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.259046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T09:58:34.958235Z","caller":"traceutil/trace.go:171","msg":"trace[1843522277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"501.39331ms","start":"2026-07-28T09:58:34.456815Z","end":"2026-07-28T09:58:34.958208Z","steps":["trace[1843522277] 'agreement among raft nodes before linearized reading' (duration: 501.157443ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:58:34.95787Z","caller":"traceutil/trace.go:171","msg":"trace[574778950] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"221.644857ms","start":"2026-07-28T09:58:34.736212Z","end":"2026-07-28T09:58:34.957857Z","steps":["trace[574778950] 'process raft request' (duration: 221.572875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:58:34.958287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:58:34.456796Z","time spent":"501.482311ms","remote":"127.0.0.1:49030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-28T09:58:34.958351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T09:58:34.398558Z","time spent":"559.351072ms","remote":"127.0.0.1:49528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-28T09:58:58.500909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.245618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-07-28T09:58:58.500928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.049705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T09:58:58.501024Z","caller":"traceutil/trace.go:171","msg":"trace[690417406] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:821; }","duration":"224.428913ms","start":"2026-07-28T09:58:58.276575Z","end":"2026-07-28T09:58:58.501004Z","steps":["trace[690417406] 'range keys from in-memory index tree' (duration: 224.026402ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:58:58.501083Z","caller":"traceutil/trace.go:171","msg":"trace[1510771963] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:821; }","duration":"299.287772ms","start":"2026-07-28T09:58:58.201758Z","end":"2026-07-28T09:58:58.501046Z","steps":["trace[1510771963] 'count revisions from in-memory index tree' (duration: 298.921883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T09:59:25.360668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.955361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-28T09:59:25.360784Z","caller":"traceutil/trace.go:171","msg":"trace[169946446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1210; }","duration":"146.122175ms","start":"2026-07-28T09:59:25.21464Z","end":"2026-07-28T09:59:25.360762Z","steps":["trace[169946446] 'range keys from in-memory index tree' (duration: 145.794907ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T09:59:40.83764Z","caller":"traceutil/trace.go:171","msg":"trace[1249770827] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"116.322015ms","start":"2026-07-28T09:59:40.721288Z","end":"2026-07-28T09:59:40.83761Z","steps":["trace[1249770827] 'process raft request' (duration: 103.326793ms)","trace[1249770827] 'compare' (duration: 12.880148ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:00:21.372498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.988606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-43bfd5c7-1704-4337-abc5-eb5cfd1f4136\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-28T10:00:21.372607Z","caller":"traceutil/trace.go:171","msg":"trace[2091891890] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-43bfd5c7-1704-4337-abc5-eb5cfd1f4136; range_end:; response_count:1; response_revision:1929; }","duration":"148.160672ms","start":"2026-07-28T10:00:21.224424Z","end":"2026-07-28T10:00:21.372585Z","steps":["trace[2091891890] 'range keys from in-memory index tree' (duration: 147.76509ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:00:21.773811Z","caller":"traceutil/trace.go:171","msg":"trace[1459244080] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"110.609632ms","start":"2026-07-28T10:00:21.663148Z","end":"2026-07-28T10:00:21.773758Z","steps":["trace[1459244080] 'process raft request' (duration: 102.440394ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:00:37.113372Z","caller":"traceutil/trace.go:171","msg":"trace[84584343] transaction","detail":"{read_only:false; response_revision:2061; number_of_response:1; }","duration":"105.654224ms","start":"2026-07-28T10:00:37.007689Z","end":"2026-07-28T10:00:37.113343Z","steps":["trace[84584343] 'process raft request' (duration: 105.442478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:00:37.342039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.512769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-28T10:00:37.342144Z","caller":"traceutil/trace.go:171","msg":"trace[807407538] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2061; }","duration":"149.695105ms","start":"2026-07-28T10:00:37.192427Z","end":"2026-07-28T10:00:37.342123Z","steps":["trace[807407538] 'range keys from in-memory index tree' (duration: 149.300753ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:00:37.342288Z","caller":"traceutil/trace.go:171","msg":"trace[1824025675] transaction","detail":"{read_only:false; response_revision:2062; number_of_response:1; }","duration":"127.028632ms","start":"2026-07-28T10:00:37.215212Z","end":"2026-07-28T10:00:37.34224Z","steps":["trace[1824025675] 'process raft request' (duration: 97.327231ms)","trace[1824025675] 'compare' (duration: 29.287829ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:00:40.737472Z","caller":"traceutil/trace.go:171","msg":"trace[1984221196] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"131.211639ms","start":"2026-07-28T10:00:40.606228Z","end":"2026-07-28T10:00:40.73744Z","steps":["trace[1984221196] 'process raft request' (duration: 131.019433ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:00:40.901677Z","caller":"traceutil/trace.go:171","msg":"trace[1395257742] transaction","detail":"{read_only:false; response_revision:2082; number_of_response:1; }","duration":"158.276503ms","start":"2026-07-28T10:00:40.743367Z","end":"2026-07-28T10:00:40.901644Z","steps":["trace[1395257742] 'process raft request' (duration: 157.817311ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:01:20.044306Z","caller":"traceutil/trace.go:171","msg":"trace[1222158767] linearizableReadLoop","detail":"{readStateIndex:2379; appliedIndex:2378; }","duration":"111.376342ms","start":"2026-07-28T10:01:19.932893Z","end":"2026-07-28T10:01:20.04427Z","steps":["trace[1222158767] 'read index received' (duration: 110.98077ms)","trace[1222158767] 'applied index is now lower than readState.Index' (duration: 390.241µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:01:20.044547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.620518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:01:20.044608Z","caller":"traceutil/trace.go:171","msg":"trace[1599919177] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:2295; }","duration":"111.749933ms","start":"2026-07-28T10:01:19.932841Z","end":"2026-07-28T10:01:20.04459Z","steps":["trace[1599919177] 'agreement among raft nodes before linearized reading' (duration: 111.612809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:01:20.045199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.361111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:01:20.04511Z","caller":"traceutil/trace.go:171","msg":"trace[2900267] transaction","detail":"{read_only:false; response_revision:2295; number_of_response:1; }","duration":"111.67966ms","start":"2026-07-28T10:01:19.932692Z","end":"2026-07-28T10:01:20.044372Z","steps":["trace[2900267] 'process raft request' (duration: 111.281839ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:01:20.045291Z","caller":"traceutil/trace.go:171","msg":"trace[2124306009] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:2295; }","duration":"111.497095ms","start":"2026-07-28T10:01:19.933774Z","end":"2026-07-28T10:01:20.045271Z","steps":["trace[2124306009] 'agreement among raft nodes before linearized reading' (duration: 110.764546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:01:20.320443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.779918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-28T10:01:20.320531Z","caller":"traceutil/trace.go:171","msg":"trace[226307584] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2295; }","duration":"141.909372ms","start":"2026-07-28T10:01:20.178598Z","end":"2026-07-28T10:01:20.320507Z","steps":["trace[226307584] 'range keys from in-memory index tree' (duration: 141.597214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:01:20.32073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.871227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-28T10:01:20.320821Z","caller":"traceutil/trace.go:171","msg":"trace[1744530356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2295; }","duration":"232.030532ms","start":"2026-07-28T10:01:20.088767Z","end":"2026-07-28T10:01:20.320798Z","steps":["trace[1744530356] 'range keys from in-memory index tree' (duration: 231.64104ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:01:20.580436Z","caller":"traceutil/trace.go:171","msg":"trace[204600676] transaction","detail":"{read_only:false; response_revision:2296; number_of_response:1; }","duration":"252.831541ms","start":"2026-07-28T10:01:20.327579Z","end":"2026-07-28T10:01:20.58041Z","steps":["trace[204600676] 'process raft request' (duration: 252.661007ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:02:31.887905Z","caller":"traceutil/trace.go:171","msg":"trace[1289832938] transaction","detail":"{read_only:false; response_revision:2878; number_of_response:1; }","duration":"120.546943ms","start":"2026-07-28T10:02:31.767325Z","end":"2026-07-28T10:02:31.887872Z","steps":["trace[1289832938] 'process raft request' (duration: 44.549747ms)","trace[1289832938] 'compare' (duration: 75.863213ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:02:37.947291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.11656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-28T10:02:37.947394Z","caller":"traceutil/trace.go:171","msg":"trace[352452364] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2910; }","duration":"240.281964ms","start":"2026-07-28T10:02:37.707091Z","end":"2026-07-28T10:02:37.947373Z","steps":["trace[352452364] 'range keys from in-memory index tree' (duration: 239.905404ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:02:43.262046Z","caller":"traceutil/trace.go:171","msg":"trace[471210684] transaction","detail":"{read_only:false; response_revision:2941; number_of_response:1; }","duration":"107.542269ms","start":"2026-07-28T10:02:43.154478Z","end":"2026-07-28T10:02:43.26202Z","steps":["trace[471210684] 'process raft request' (duration: 107.358074ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:04:57.939713Z","caller":"traceutil/trace.go:171","msg":"trace[1853993450] transaction","detail":"{read_only:false; response_revision:3856; number_of_response:1; }","duration":"167.543159ms","start":"2026-07-28T10:04:57.772146Z","end":"2026-07-28T10:04:57.939689Z","steps":["trace[1853993450] 'process raft request' (duration: 167.384275ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:06:19.390158Z","caller":"traceutil/trace.go:171","msg":"trace[1558280881] linearizableReadLoop","detail":"{readStateIndex:5082; appliedIndex:5081; }","duration":"122.126152ms","start":"2026-07-28T10:06:19.268009Z","end":"2026-07-28T10:06:19.390135Z","steps":["trace[1558280881] 'read index received' (duration: 100.477127ms)","trace[1558280881] 'applied index is now lower than readState.Index' (duration: 21.648395ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:06:19.390269Z","caller":"traceutil/trace.go:171","msg":"trace[375365617] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"125.345891ms","start":"2026-07-28T10:06:19.264913Z","end":"2026-07-28T10:06:19.390259Z","steps":["trace[375365617] 'process raft request' (duration: 103.698757ms)","trace[375365617] 'compare' (duration: 21.331265ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:06:19.390491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.455531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:06:19.390593Z","caller":"traceutil/trace.go:171","msg":"trace[472632183] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4900; }","duration":"122.579834ms","start":"2026-07-28T10:06:19.267994Z","end":"2026-07-28T10:06:19.390574Z","steps":["trace[472632183] 'agreement among raft nodes before linearized reading' (duration: 122.371048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:06:19.390814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.95192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:23 size:531296"} {"level":"info","ts":"2026-07-28T10:06:19.390913Z","caller":"traceutil/trace.go:171","msg":"trace[1410446381] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:4900; }","duration":"106.118615ms","start":"2026-07-28T10:06:19.284776Z","end":"2026-07-28T10:06:19.390894Z","steps":["trace[1410446381] 'agreement among raft nodes before linearized reading' (duration: 105.56071ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:06:23.045929Z","caller":"traceutil/trace.go:171","msg":"trace[62941534] linearizableReadLoop","detail":"{readStateIndex:5213; appliedIndex:5212; }","duration":"142.47031ms","start":"2026-07-28T10:06:22.903432Z","end":"2026-07-28T10:06:23.045902Z","steps":["trace[62941534] 'read index received' (duration: 71.59116ms)","trace[62941534] 'applied index is now lower than readState.Index' (duration: 70.8781ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:06:23.046068Z","caller":"traceutil/trace.go:171","msg":"trace[687162115] transaction","detail":"{read_only:false; response_revision:5028; number_of_response:1; }","duration":"240.875369ms","start":"2026-07-28T10:06:22.80512Z","end":"2026-07-28T10:06:23.045995Z","steps":["trace[687162115] 'process raft request' (duration: 169.832814ms)","trace[687162115] 'compare' (duration: 70.819839ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:06:23.046422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.972565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728955"} {"level":"info","ts":"2026-07-28T10:06:23.046477Z","caller":"traceutil/trace.go:171","msg":"trace[399231986] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5028; }","duration":"143.090567ms","start":"2026-07-28T10:06:22.903373Z","end":"2026-07-28T10:06:23.046464Z","steps":["trace[399231986] 'agreement among raft nodes before linearized reading' (duration: 142.657555ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:06:37.313362Z","caller":"traceutil/trace.go:171","msg":"trace[421878469] transaction","detail":"{read_only:false; response_revision:5296; number_of_response:1; }","duration":"105.174117ms","start":"2026-07-28T10:06:37.208162Z","end":"2026-07-28T10:06:37.313336Z","steps":["trace[421878469] 'process raft request' (duration: 105.071024ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:06:37.313491Z","caller":"traceutil/trace.go:171","msg":"trace[228817186] transaction","detail":"{read_only:false; response_revision:5295; number_of_response:1; }","duration":"105.492646ms","start":"2026-07-28T10:06:37.207958Z","end":"2026-07-28T10:06:37.313451Z","steps":["trace[228817186] 'process raft request' (duration: 69.416348ms)","trace[228817186] 'compare' (duration: 35.651917ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:06:37.955598Z","caller":"traceutil/trace.go:171","msg":"trace[2024844207] linearizableReadLoop","detail":"{readStateIndex:5506; appliedIndex:5505; }","duration":"152.884521ms","start":"2026-07-28T10:06:37.802678Z","end":"2026-07-28T10:06:37.955562Z","steps":["trace[2024844207] 'read index received' (duration: 152.682526ms)","trace[2024844207] 'applied index is now lower than readState.Index' (duration: 200.825µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:06:37.955734Z","caller":"traceutil/trace.go:171","msg":"trace[141555442] transaction","detail":"{read_only:false; response_revision:5312; number_of_response:1; }","duration":"246.242119ms","start":"2026-07-28T10:06:37.709466Z","end":"2026-07-28T10:06:37.955708Z","steps":["trace[141555442] 'process raft request' (duration: 245.90805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:06:37.955915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.220531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-28T10:06:37.956081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.252318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T10:06:37.956168Z","caller":"traceutil/trace.go:171","msg":"trace[1836960914] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:5312; }","duration":"145.426362ms","start":"2026-07-28T10:06:37.810718Z","end":"2026-07-28T10:06:37.956145Z","steps":["trace[1836960914] 'agreement among raft nodes before linearized reading' (duration: 145.242838ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:06:37.956166Z","caller":"traceutil/trace.go:171","msg":"trace[1321004942] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:5312; }","duration":"153.527158ms","start":"2026-07-28T10:06:37.802611Z","end":"2026-07-28T10:06:37.956138Z","steps":["trace[1321004942] 'agreement among raft nodes before linearized reading' (duration: 153.176149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:06:37.956423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.285143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:06:37.956473Z","caller":"traceutil/trace.go:171","msg":"trace[525164123] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:5312; }","duration":"143.373015ms","start":"2026-07-28T10:06:37.813084Z","end":"2026-07-28T10:06:37.956457Z","steps":["trace[525164123] 'agreement among raft nodes before linearized reading' (duration: 143.311164ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:06:46.369134Z","caller":"traceutil/trace.go:171","msg":"trace[1051193173] transaction","detail":"{read_only:false; response_revision:5500; number_of_response:1; }","duration":"163.773803ms","start":"2026-07-28T10:06:46.205317Z","end":"2026-07-28T10:06:46.36909Z","steps":["trace[1051193173] 'process raft request' (duration: 123.692661ms)","trace[1051193173] 'compare' (duration: 39.168306ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:07:52.117386Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2991} {"level":"info","ts":"2026-07-28T10:07:52.192844Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2991,"took":"74.054382ms","hash":535594077,"current-db-size-bytes":59289600,"current-db-size":"59 MB","current-db-size-in-use-bytes":34287616,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-28T10:07:52.192925Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":535594077,"revision":2991,"compact-revision":-1} {"level":"info","ts":"2026-07-28T10:12:52.123085Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6080} {"level":"info","ts":"2026-07-28T10:12:52.217023Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6080,"took":"91.852175ms","hash":2200697603,"current-db-size-bytes":59289600,"current-db-size":"59 MB","current-db-size-in-use-bytes":22085632,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-28T10:12:52.217105Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2200697603,"revision":6080,"compact-revision":2991} {"level":"warn","ts":"2026-07-28T10:13:05.394958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:13:05.075275Z","time spent":"319.673797ms","remote":"127.0.0.1:49062","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-28T10:13:05.395Z","caller":"traceutil/trace.go:171","msg":"trace[1047701865] linearizableReadLoop","detail":"{readStateIndex:8659; appliedIndex:8658; }","duration":"102.437063ms","start":"2026-07-28T10:13:05.292467Z","end":"2026-07-28T10:13:05.394904Z","steps":["trace[1047701865] 'read index received' (duration: 102.237557ms)","trace[1047701865] 'applied index is now lower than readState.Index' (duration: 197.976µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:13:05.395128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.633118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:13:05.395234Z","caller":"traceutil/trace.go:171","msg":"trace[1883438554] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:8368; }","duration":"102.756601ms","start":"2026-07-28T10:13:05.292445Z","end":"2026-07-28T10:13:05.395201Z","steps":["trace[1883438554] 'agreement among raft nodes before linearized reading' (duration: 102.551795ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:13:05.563269Z","caller":"traceutil/trace.go:171","msg":"trace[534902299] transaction","detail":"{read_only:false; response_revision:8369; number_of_response:1; }","duration":"166.939088ms","start":"2026-07-28T10:13:05.396291Z","end":"2026-07-28T10:13:05.56323Z","steps":["trace[534902299] 'process raft request' (duration: 160.795475ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:13:05.563626Z","caller":"traceutil/trace.go:171","msg":"trace[292176404] linearizableReadLoop","detail":"{readStateIndex:8660; appliedIndex:8659; }","duration":"164.785297ms","start":"2026-07-28T10:13:05.398789Z","end":"2026-07-28T10:13:05.563575Z","steps":["trace[292176404] 'read index received' (duration: 158.425288ms)","trace[292176404] 'applied index is now lower than readState.Index' (duration: 6.340579ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:13:05.563749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.941692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:13:05.563804Z","caller":"traceutil/trace.go:171","msg":"trace[1447942474] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:8370; }","duration":"165.050914ms","start":"2026-07-28T10:13:05.398741Z","end":"2026-07-28T10:13:05.563792Z","steps":["trace[1447942474] 'agreement among raft nodes before linearized reading' (duration: 164.914941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:13:05.563913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.835468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:13:05.563972Z","caller":"traceutil/trace.go:171","msg":"trace[533913110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8370; }","duration":"105.931992ms","start":"2026-07-28T10:13:05.458024Z","end":"2026-07-28T10:13:05.563956Z","steps":["trace[533913110] 'agreement among raft nodes before linearized reading' (duration: 105.810198ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:13:05.563567Z","caller":"traceutil/trace.go:171","msg":"trace[19639936] transaction","detail":"{read_only:false; response_revision:8370; number_of_response:1; }","duration":"127.148619ms","start":"2026-07-28T10:13:05.436361Z","end":"2026-07-28T10:13:05.56351Z","steps":["trace[19639936] 'process raft request' (duration: 126.879961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:13:17.611962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.258335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:13:17.612164Z","caller":"traceutil/trace.go:171","msg":"trace[698233832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8430; }","duration":"156.513273ms","start":"2026-07-28T10:13:17.455597Z","end":"2026-07-28T10:13:17.61211Z","steps":["trace[698233832] 'range keys from in-memory index tree' (duration: 156.113752ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:13:19.223861Z","caller":"traceutil/trace.go:171","msg":"trace[1430361939] transaction","detail":"{read_only:false; response_revision:8439; number_of_response:1; }","duration":"104.671605ms","start":"2026-07-28T10:13:19.119157Z","end":"2026-07-28T10:13:19.223828Z","steps":["trace[1430361939] 'process raft request' (duration: 104.452239ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:13:19.754772Z","caller":"traceutil/trace.go:171","msg":"trace[1863779074] transaction","detail":"{read_only:false; response_revision:8440; number_of_response:1; }","duration":"130.248213ms","start":"2026-07-28T10:13:19.624482Z","end":"2026-07-28T10:13:19.75473Z","steps":["trace[1863779074] 'process raft request' (duration: 130.041948ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:13:39.950966Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002298.wal"} {"level":"info","ts":"2026-07-28T10:13:47.795057Z","caller":"traceutil/trace.go:171","msg":"trace[2093619475] transaction","detail":"{read_only:false; response_revision:8646; number_of_response:1; }","duration":"465.282839ms","start":"2026-07-28T10:13:47.329732Z","end":"2026-07-28T10:13:47.795015Z","steps":["trace[2093619475] 'process raft request' (duration: 465.021062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:13:47.795485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:13:47.329701Z","time spent":"465.476855ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T10:13:47.873371Z","caller":"traceutil/trace.go:171","msg":"trace[1046777844] linearizableReadLoop","detail":"{readStateIndex:8949; appliedIndex:8947; }","duration":"535.719001ms","start":"2026-07-28T10:13:47.337634Z","end":"2026-07-28T10:13:47.873353Z","steps":["trace[1046777844] 'read index received' (duration: 457.079898ms)","trace[1046777844] 'applied index is now lower than readState.Index' (duration: 78.638243ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:13:47.873515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.930294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T10:13:47.873567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.926646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T10:13:47.87362Z","caller":"traceutil/trace.go:171","msg":"trace[1710162075] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:8648; }","duration":"536.014449ms","start":"2026-07-28T10:13:47.337593Z","end":"2026-07-28T10:13:47.873608Z","steps":["trace[1710162075] 'agreement among raft nodes before linearized reading' (duration: 535.825824ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:13:47.87356Z","caller":"traceutil/trace.go:171","msg":"trace[483761833] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8648; }","duration":"409.034567ms","start":"2026-07-28T10:13:47.464511Z","end":"2026-07-28T10:13:47.873546Z","steps":["trace[483761833] 'agreement among raft nodes before linearized reading' (duration: 408.956315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:13:47.873655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:13:47.337567Z","time spent":"536.077801ms","remote":"127.0.0.1:34268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-07-28T10:13:47.873702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:13:47.464471Z","time spent":"409.216453ms","remote":"127.0.0.1:49814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-07-28T10:13:47.873365Z","caller":"traceutil/trace.go:171","msg":"trace[2102547535] transaction","detail":"{read_only:false; response_revision:8647; number_of_response:1; }","duration":"538.679904ms","start":"2026-07-28T10:13:47.334656Z","end":"2026-07-28T10:13:47.873336Z","steps":["trace[2102547535] 'process raft request' (duration: 481.352272ms)","trace[2102547535] 'compare' (duration: 57.194708ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:13:47.874908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:13:47.334639Z","time spent":"540.139875ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:14:00.724908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.707618ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-28T10:14:00.725174Z","caller":"traceutil/trace.go:171","msg":"trace[1688499136] linearizableReadLoop","detail":"{readStateIndex:9066; appliedIndex:9065; }","duration":"328.06211ms","start":"2026-07-28T10:14:00.397077Z","end":"2026-07-28T10:14:00.725139Z","steps":["trace[1688499136] 'read index received' (duration: 14.948441ms)","trace[1688499136] 'applied index is now lower than readState.Index' (duration: 313.108899ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:14:00.725475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.390449ms","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-07-28T10:14:00.725517Z","caller":"traceutil/trace.go:171","msg":"trace[619126902] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8759; }","duration":"328.489481ms","start":"2026-07-28T10:14:00.397017Z","end":"2026-07-28T10:14:00.725506Z","steps":["trace[619126902] 'agreement among raft nodes before linearized reading' (duration: 328.292625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:14:00.725557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:14:00.396993Z","time spent":"328.555962ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-28T10:14:00.725731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.587075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-phhjb\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-07-28T10:14:00.725766Z","caller":"traceutil/trace.go:171","msg":"trace[1648495851] range","detail":"{range_begin:/registry/pods/openstack/keepalived-phhjb; range_end:; response_count:1; response_revision:8759; }","duration":"284.666198ms","start":"2026-07-28T10:14:00.441092Z","end":"2026-07-28T10:14:00.725758Z","steps":["trace[1648495851] 'agreement among raft nodes before linearized reading' (duration: 284.575156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:14:00.726323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.605086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:14:00.726394Z","caller":"traceutil/trace.go:171","msg":"trace[1960288997] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:8759; }","duration":"129.707808ms","start":"2026-07-28T10:14:00.59666Z","end":"2026-07-28T10:14:00.726367Z","steps":["trace[1960288997] 'agreement among raft nodes before linearized reading' (duration: 129.617836ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:14:01.577919Z","caller":"traceutil/trace.go:171","msg":"trace[995391461] linearizableReadLoop","detail":"{readStateIndex:9071; appliedIndex:9070; }","duration":"120.562502ms","start":"2026-07-28T10:14:01.457326Z","end":"2026-07-28T10:14:01.577889Z","steps":["trace[995391461] 'read index received' (duration: 120.321316ms)","trace[995391461] 'applied index is now lower than readState.Index' (duration: 239.536µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:14:01.578064Z","caller":"traceutil/trace.go:171","msg":"trace[67409903] transaction","detail":"{read_only:false; response_revision:8763; number_of_response:1; }","duration":"185.049487ms","start":"2026-07-28T10:14:01.392937Z","end":"2026-07-28T10:14:01.577986Z","steps":["trace[67409903] 'process raft request' (duration: 184.744718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:14:01.578333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.890741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:14:01.57852Z","caller":"traceutil/trace.go:171","msg":"trace[314193076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8763; }","duration":"121.148668ms","start":"2026-07-28T10:14:01.457265Z","end":"2026-07-28T10:14:01.578414Z","steps":["trace[314193076] 'agreement among raft nodes before linearized reading' (duration: 120.741767ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:14:02.901908Z","caller":"traceutil/trace.go:171","msg":"trace[2114311711] transaction","detail":"{read_only:false; response_revision:8775; number_of_response:1; }","duration":"150.461063ms","start":"2026-07-28T10:14:02.751414Z","end":"2026-07-28T10:14:02.901875Z","steps":["trace[2114311711] 'process raft request' (duration: 150.197015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:14:03.269852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.31689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:14:03.270481Z","caller":"traceutil/trace.go:171","msg":"trace[1904723627] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:8777; }","duration":"171.999769ms","start":"2026-07-28T10:14:03.098446Z","end":"2026-07-28T10:14:03.270446Z","steps":["trace[1904723627] 'count revisions from in-memory index tree' (duration: 171.212497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:14:03.270487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.938627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-8z4x6\" ","response":"range_response_count:1 size:4838"} {"level":"info","ts":"2026-07-28T10:14:03.270904Z","caller":"traceutil/trace.go:171","msg":"trace[999294870] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-8z4x6; range_end:; response_count:1; response_revision:8777; }","duration":"129.402341ms","start":"2026-07-28T10:14:03.14147Z","end":"2026-07-28T10:14:03.270872Z","steps":["trace[999294870] 'range keys from in-memory index tree' (duration: 128.707932ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:15:38.358207Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-28T10:15:38.36506Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-28T10:15:38.365181Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-07-28T10:16:01.172793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.182242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-28T10:16:01.172922Z","caller":"traceutil/trace.go:171","msg":"trace[1104910086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9813; }","duration":"134.344736ms","start":"2026-07-28T10:16:01.038553Z","end":"2026-07-28T10:16:01.172898Z","steps":["trace[1104910086] 'range keys from in-memory index tree' (duration: 133.960355ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:16:01.337152Z","caller":"traceutil/trace.go:171","msg":"trace[646551822] transaction","detail":"{read_only:false; response_revision:9814; number_of_response:1; }","duration":"153.051603ms","start":"2026-07-28T10:16:01.184068Z","end":"2026-07-28T10:16:01.33712Z","steps":["trace[646551822] 'process raft request' (duration: 152.864947ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:16:01.338515Z","caller":"traceutil/trace.go:171","msg":"trace[830409327] transaction","detail":"{read_only:false; response_revision:9815; number_of_response:1; }","duration":"133.363789ms","start":"2026-07-28T10:16:01.205129Z","end":"2026-07-28T10:16:01.338493Z","steps":["trace[830409327] 'process raft request' (duration: 132.983488ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:17:52.129471Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8162} {"level":"info","ts":"2026-07-28T10:17:52.203769Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8162,"took":"70.390389ms","hash":4117110410,"current-db-size-bytes":59289600,"current-db-size":"59 MB","current-db-size-in-use-bytes":28557312,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-28T10:17:52.203856Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4117110410,"revision":8162,"compact-revision":6080} {"level":"warn","ts":"2026-07-28T10:20:59.431242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.61898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-volume-5559d98cf9-tq5pv.18c66ce3b439254f\" ","response":"range_response_count:1 size:804"} {"level":"info","ts":"2026-07-28T10:20:59.431345Z","caller":"traceutil/trace.go:171","msg":"trace[660452975] range","detail":"{range_begin:/registry/events/openstack/cinder-volume-5559d98cf9-tq5pv.18c66ce3b439254f; range_end:; response_count:1; response_revision:11955; }","duration":"163.769195ms","start":"2026-07-28T10:20:59.267556Z","end":"2026-07-28T10:20:59.431325Z","steps":["trace[660452975] 'range keys from in-memory index tree' (duration: 163.351073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:20:59.431558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.286297ms","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-07-28T10:20:59.431629Z","caller":"traceutil/trace.go:171","msg":"trace[1553086042] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11955; }","duration":"117.411481ms","start":"2026-07-28T10:20:59.3142Z","end":"2026-07-28T10:20:59.431611Z","steps":["trace[1553086042] 'range keys from in-memory index tree' (duration: 117.117212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:20:59.432341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.183414ms","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-07-28T10:20:59.432421Z","caller":"traceutil/trace.go:171","msg":"trace[1010672508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11955; }","duration":"117.315318ms","start":"2026-07-28T10:20:59.315088Z","end":"2026-07-28T10:20:59.432403Z","steps":["trace[1010672508] 'range keys from in-memory index tree' (duration: 116.535386ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:20:59.567666Z","caller":"traceutil/trace.go:171","msg":"trace[1185430872] transaction","detail":"{read_only:false; response_revision:11958; number_of_response:1; }","duration":"132.136365ms","start":"2026-07-28T10:20:59.435482Z","end":"2026-07-28T10:20:59.567618Z","steps":["trace[1185430872] 'process raft request' (duration: 109.998172ms)","trace[1185430872] 'compare' (duration: 21.847004ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:20:59.568148Z","caller":"traceutil/trace.go:171","msg":"trace[860388789] transaction","detail":"{read_only:false; response_revision:11959; number_of_response:1; }","duration":"131.102006ms","start":"2026-07-28T10:20:59.437029Z","end":"2026-07-28T10:20:59.568131Z","steps":["trace[860388789] 'process raft request' (duration: 130.512459ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:22.077646Z","caller":"traceutil/trace.go:171","msg":"trace[118876596] transaction","detail":"{read_only:false; response_revision:12501; number_of_response:1; }","duration":"111.849004ms","start":"2026-07-28T10:22:21.965755Z","end":"2026-07-28T10:22:22.077604Z","steps":["trace[118876596] 'process raft request' (duration: 84.476264ms)","trace[118876596] 'compare' (duration: 27.226866ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:22:22.077873Z","caller":"traceutil/trace.go:171","msg":"trace[903861268] transaction","detail":"{read_only:false; response_revision:12502; number_of_response:1; }","duration":"111.950267ms","start":"2026-07-28T10:22:21.965878Z","end":"2026-07-28T10:22:22.077829Z","steps":["trace[903861268] 'process raft request' (duration: 111.68265ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:23.921927Z","caller":"traceutil/trace.go:171","msg":"trace[532207307] linearizableReadLoop","detail":"{readStateIndex:12932; appliedIndex:12931; }","duration":"155.332898ms","start":"2026-07-28T10:22:23.76657Z","end":"2026-07-28T10:22:23.921903Z","steps":["trace[532207307] 'read index received' (duration: 155.258655ms)","trace[532207307] 'applied index is now lower than readState.Index' (duration: 72.812µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:22:23.922076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.488141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T10:22:23.922112Z","caller":"traceutil/trace.go:171","msg":"trace[985387088] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:12508; }","duration":"155.581783ms","start":"2026-07-28T10:22:23.766519Z","end":"2026-07-28T10:22:23.9221Z","steps":["trace[985387088] 'agreement among raft nodes before linearized reading' (duration: 155.479401ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:23.922419Z","caller":"traceutil/trace.go:171","msg":"trace[754414581] transaction","detail":"{read_only:false; response_revision:12508; number_of_response:1; }","duration":"161.127589ms","start":"2026-07-28T10:22:23.76128Z","end":"2026-07-28T10:22:23.922407Z","steps":["trace[754414581] 'process raft request' (duration: 160.483691ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:26.119418Z","caller":"traceutil/trace.go:171","msg":"trace[1196301573] transaction","detail":"{read_only:false; response_revision:12520; number_of_response:1; }","duration":"191.086102ms","start":"2026-07-28T10:22:25.928279Z","end":"2026-07-28T10:22:26.119365Z","steps":["trace[1196301573] 'process raft request' (duration: 190.866605ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:26.174096Z","caller":"traceutil/trace.go:171","msg":"trace[1633381449] transaction","detail":"{read_only:false; response_revision:12521; number_of_response:1; }","duration":"219.645985ms","start":"2026-07-28T10:22:25.954426Z","end":"2026-07-28T10:22:26.174072Z","steps":["trace[1633381449] 'process raft request' (duration: 219.47798ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:26.174295Z","caller":"traceutil/trace.go:171","msg":"trace[96734367] transaction","detail":"{read_only:false; response_revision:12522; number_of_response:1; }","duration":"138.224756ms","start":"2026-07-28T10:22:26.03605Z","end":"2026-07-28T10:22:26.174274Z","steps":["trace[96734367] 'process raft request' (duration: 137.99008ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:27.970942Z","caller":"traceutil/trace.go:171","msg":"trace[1767299945] transaction","detail":"{read_only:false; response_revision:12529; number_of_response:1; }","duration":"110.708712ms","start":"2026-07-28T10:22:27.860203Z","end":"2026-07-28T10:22:27.970912Z","steps":["trace[1767299945] 'process raft request' (duration: 110.30831ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:28.366628Z","caller":"traceutil/trace.go:171","msg":"trace[537324730] transaction","detail":"{read_only:false; response_revision:12535; number_of_response:1; }","duration":"100.591168ms","start":"2026-07-28T10:22:28.266015Z","end":"2026-07-28T10:22:28.366606Z","steps":["trace[537324730] 'process raft request' (duration: 100.445474ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:29.312229Z","caller":"traceutil/trace.go:171","msg":"trace[400614647] transaction","detail":"{read_only:false; response_revision:12536; number_of_response:1; }","duration":"116.267429ms","start":"2026-07-28T10:22:29.195926Z","end":"2026-07-28T10:22:29.312193Z","steps":["trace[400614647] 'process raft request' (duration: 116.086553ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:29.966549Z","caller":"traceutil/trace.go:171","msg":"trace[709288250] transaction","detail":"{read_only:false; response_revision:12542; number_of_response:1; }","duration":"114.232962ms","start":"2026-07-28T10:22:29.852271Z","end":"2026-07-28T10:22:29.966504Z","steps":["trace[709288250] 'process raft request' (duration: 84.876486ms)","trace[709288250] 'compare' (duration: 29.073628ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:22:30.240229Z","caller":"traceutil/trace.go:171","msg":"trace[298247155] transaction","detail":"{read_only:false; response_revision:12543; number_of_response:1; }","duration":"116.068653ms","start":"2026-07-28T10:22:30.124142Z","end":"2026-07-28T10:22:30.240211Z","steps":["trace[298247155] 'process raft request' (duration: 115.94482ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:30.458997Z","caller":"traceutil/trace.go:171","msg":"trace[798486984] transaction","detail":"{read_only:false; response_revision:12547; number_of_response:1; }","duration":"137.022002ms","start":"2026-07-28T10:22:30.321947Z","end":"2026-07-28T10:22:30.458969Z","steps":["trace[798486984] 'process raft request' (duration: 136.839598ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:30.55355Z","caller":"traceutil/trace.go:171","msg":"trace[2032806927] linearizableReadLoop","detail":"{readStateIndex:12973; appliedIndex:12971; }","duration":"194.038434ms","start":"2026-07-28T10:22:30.359479Z","end":"2026-07-28T10:22:30.553517Z","steps":["trace[2032806927] 'read index received' (duration: 99.402085ms)","trace[2032806927] 'applied index is now lower than readState.Index' (duration: 94.634989ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:22:30.553842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.342544ms","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-07-28T10:22:30.553924Z","caller":"traceutil/trace.go:171","msg":"trace[6424225] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12547; }","duration":"194.481628ms","start":"2026-07-28T10:22:30.359426Z","end":"2026-07-28T10:22:30.553907Z","steps":["trace[6424225] 'agreement among raft nodes before linearized reading' (duration: 194.19902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:22:30.585322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.799478ms","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-07-28T10:22:30.585435Z","caller":"traceutil/trace.go:171","msg":"trace[1622082676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12548; }","duration":"214.952953ms","start":"2026-07-28T10:22:30.370457Z","end":"2026-07-28T10:22:30.58541Z","steps":["trace[1622082676] 'agreement among raft nodes before linearized reading' (duration: 214.676435ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:30.585656Z","caller":"traceutil/trace.go:171","msg":"trace[64440825] transaction","detail":"{read_only:false; response_revision:12548; number_of_response:1; }","duration":"117.956207ms","start":"2026-07-28T10:22:30.467677Z","end":"2026-07-28T10:22:30.585633Z","steps":["trace[64440825] 'process raft request' (duration: 117.243786ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:30.72859Z","caller":"traceutil/trace.go:171","msg":"trace[498333007] transaction","detail":"{read_only:false; response_revision:12550; number_of_response:1; }","duration":"137.376342ms","start":"2026-07-28T10:22:30.59119Z","end":"2026-07-28T10:22:30.728566Z","steps":["trace[498333007] 'process raft request' (duration: 133.735139ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:30.728855Z","caller":"traceutil/trace.go:171","msg":"trace[1759355069] transaction","detail":"{read_only:false; response_revision:12551; number_of_response:1; }","duration":"132.933927ms","start":"2026-07-28T10:22:30.595862Z","end":"2026-07-28T10:22:30.728795Z","steps":["trace[1759355069] 'process raft request' (duration: 132.641649ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:31.050992Z","caller":"traceutil/trace.go:171","msg":"trace[1405062467] transaction","detail":"{read_only:false; response_revision:12554; number_of_response:1; }","duration":"137.733092ms","start":"2026-07-28T10:22:30.913234Z","end":"2026-07-28T10:22:31.050967Z","steps":["trace[1405062467] 'process raft request' (duration: 79.612929ms)","trace[1405062467] 'compare' (duration: 57.63998ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:22:31.260101Z","caller":"traceutil/trace.go:171","msg":"trace[1783928371] transaction","detail":"{read_only:false; response_revision:12557; number_of_response:1; }","duration":"168.869598ms","start":"2026-07-28T10:22:31.091211Z","end":"2026-07-28T10:22:31.260081Z","steps":["trace[1783928371] 'process raft request' (duration: 168.588789ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:31.260221Z","caller":"traceutil/trace.go:171","msg":"trace[1902753228] linearizableReadLoop","detail":"{readStateIndex:12985; appliedIndex:12982; }","duration":"153.028693ms","start":"2026-07-28T10:22:31.107164Z","end":"2026-07-28T10:22:31.260193Z","steps":["trace[1902753228] 'read index received' (duration: 32.266598ms)","trace[1902753228] 'applied index is now lower than readState.Index' (duration: 120.760415ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:22:31.260384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.188836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:22:31.260373Z","caller":"traceutil/trace.go:171","msg":"trace[1474744550] transaction","detail":"{read_only:false; response_revision:12558; number_of_response:1; }","duration":"165.293806ms","start":"2026-07-28T10:22:31.095039Z","end":"2026-07-28T10:22:31.260332Z","steps":["trace[1474744550] 'process raft request' (duration: 164.987999ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:31.260429Z","caller":"traceutil/trace.go:171","msg":"trace[1934123991] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:12558; }","duration":"153.295789ms","start":"2026-07-28T10:22:31.107116Z","end":"2026-07-28T10:22:31.260412Z","steps":["trace[1934123991] 'agreement among raft nodes before linearized reading' (duration: 153.175666ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:31.49603Z","caller":"traceutil/trace.go:171","msg":"trace[1875056925] transaction","detail":"{read_only:false; response_revision:12560; number_of_response:1; }","duration":"151.089148ms","start":"2026-07-28T10:22:31.344915Z","end":"2026-07-28T10:22:31.496004Z","steps":["trace[1875056925] 'process raft request' (duration: 150.954965ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:31.517456Z","caller":"traceutil/trace.go:171","msg":"trace[482125462] transaction","detail":"{read_only:false; response_revision:12561; number_of_response:1; }","duration":"127.617608ms","start":"2026-07-28T10:22:31.389819Z","end":"2026-07-28T10:22:31.517436Z","steps":["trace[482125462] 'process raft request' (duration: 127.326189ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:38.082933Z","caller":"traceutil/trace.go:171","msg":"trace[1970946754] transaction","detail":"{read_only:false; response_revision:12614; number_of_response:1; }","duration":"102.875782ms","start":"2026-07-28T10:22:37.980027Z","end":"2026-07-28T10:22:38.082903Z","steps":["trace[1970946754] 'process raft request' (duration: 102.590334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:22:38.740964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.65869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-28T10:22:38.741277Z","caller":"traceutil/trace.go:171","msg":"trace[1265494686] linearizableReadLoop","detail":"{readStateIndex:13046; appliedIndex:13044; }","duration":"284.53638ms","start":"2026-07-28T10:22:38.456724Z","end":"2026-07-28T10:22:38.741261Z","steps":["trace[1265494686] 'read index received' (duration: 77.53856ms)","trace[1265494686] 'applied index is now lower than readState.Index' (duration: 206.99704ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:22:38.741339Z","caller":"traceutil/trace.go:171","msg":"trace[115838872] transaction","detail":"{read_only:false; response_revision:12617; number_of_response:1; }","duration":"442.11663ms","start":"2026-07-28T10:22:38.29916Z","end":"2026-07-28T10:22:38.741277Z","steps":["trace[115838872] 'process raft request' (duration: 235.080469ms)","trace[115838872] 'compare' (duration: 206.569757ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:22:38.74142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.672754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:22:38.741397Z","caller":"traceutil/trace.go:171","msg":"trace[2126596365] transaction","detail":"{read_only:false; response_revision:12618; number_of_response:1; }","duration":"441.981845ms","start":"2026-07-28T10:22:38.299374Z","end":"2026-07-28T10:22:38.741356Z","steps":["trace[2126596365] 'process raft request' (duration: 441.675027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:22:38.741517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:22:38.299134Z","time spent":"442.291775ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T10:22:38.741495Z","caller":"traceutil/trace.go:171","msg":"trace[1646055735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12618; }","duration":"284.785085ms","start":"2026-07-28T10:22:38.456689Z","end":"2026-07-28T10:22:38.741474Z","steps":["trace[1646055735] 'agreement among raft nodes before linearized reading' (duration: 284.649542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:22:38.741697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.650614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-07-28T10:22:38.741534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.401472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:22:38.741732Z","caller":"traceutil/trace.go:171","msg":"trace[1229746350] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:12618; }","duration":"135.682414ms","start":"2026-07-28T10:22:38.606039Z","end":"2026-07-28T10:22:38.741721Z","steps":["trace[1229746350] 'agreement among raft nodes before linearized reading' (duration: 135.534719ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:38.741761Z","caller":"traceutil/trace.go:171","msg":"trace[1698173641] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12618; }","duration":"137.647769ms","start":"2026-07-28T10:22:38.604092Z","end":"2026-07-28T10:22:38.74174Z","steps":["trace[1698173641] 'agreement among raft nodes before linearized reading' (duration: 137.412153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:22:38.741852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:22:38.299356Z","time spent":"442.296195ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T10:22:39.023687Z","caller":"traceutil/trace.go:171","msg":"trace[972427700] linearizableReadLoop","detail":"{readStateIndex:13047; appliedIndex:13046; }","duration":"262.177872ms","start":"2026-07-28T10:22:38.761484Z","end":"2026-07-28T10:22:39.023662Z","steps":["trace[972427700] 'read index received' (duration: 261.939015ms)","trace[972427700] 'applied index is now lower than readState.Index' (duration: 237.536µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:22:39.023726Z","caller":"traceutil/trace.go:171","msg":"trace[537484470] transaction","detail":"{read_only:false; response_revision:12619; number_of_response:1; }","duration":"275.336091ms","start":"2026-07-28T10:22:38.748349Z","end":"2026-07-28T10:22:39.023685Z","steps":["trace[537484470] 'process raft request' (duration: 275.146065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:22:39.023931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.436948ms","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-07-28T10:22:39.023975Z","caller":"traceutil/trace.go:171","msg":"trace[1016012577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12619; }","duration":"262.549561ms","start":"2026-07-28T10:22:38.761413Z","end":"2026-07-28T10:22:39.023963Z","steps":["trace[1016012577] 'agreement among raft nodes before linearized reading' (duration: 262.369326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:22:39.024278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.230004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:22:39.024573Z","caller":"traceutil/trace.go:171","msg":"trace[355262417] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:12619; }","duration":"240.569363ms","start":"2026-07-28T10:22:38.783987Z","end":"2026-07-28T10:22:39.024557Z","steps":["trace[355262417] 'agreement among raft nodes before linearized reading' (duration: 240.212744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:22:39.024438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.224448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-phhjb\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-07-28T10:22:39.024777Z","caller":"traceutil/trace.go:171","msg":"trace[1919626893] range","detail":"{range_begin:/registry/pods/openstack/keepalived-phhjb; range_end:; response_count:1; response_revision:12619; }","duration":"180.536125ms","start":"2026-07-28T10:22:38.844164Z","end":"2026-07-28T10:22:39.024701Z","steps":["trace[1919626893] 'agreement among raft nodes before linearized reading' (duration: 180.088403ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:40.558039Z","caller":"traceutil/trace.go:171","msg":"trace[954171371] linearizableReadLoop","detail":"{readStateIndex:13054; appliedIndex:13053; }","duration":"100.831335ms","start":"2026-07-28T10:22:40.457172Z","end":"2026-07-28T10:22:40.558003Z","steps":["trace[954171371] 'read index received' (duration: 100.443864ms)","trace[954171371] 'applied index is now lower than readState.Index' (duration: 385.991µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:22:40.558094Z","caller":"traceutil/trace.go:171","msg":"trace[1058914466] transaction","detail":"{read_only:false; response_revision:12625; number_of_response:1; }","duration":"215.278202ms","start":"2026-07-28T10:22:40.342773Z","end":"2026-07-28T10:22:40.558051Z","steps":["trace[1058914466] 'process raft request' (duration: 214.880081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:22:40.558205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.01548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:22:40.558279Z","caller":"traceutil/trace.go:171","msg":"trace[941104730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12625; }","duration":"101.123693ms","start":"2026-07-28T10:22:40.457121Z","end":"2026-07-28T10:22:40.558245Z","steps":["trace[941104730] 'agreement among raft nodes before linearized reading' (duration: 101.01635ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:40.859764Z","caller":"traceutil/trace.go:171","msg":"trace[2088729500] transaction","detail":"{read_only:false; response_revision:12627; number_of_response:1; }","duration":"111.403733ms","start":"2026-07-28T10:22:40.748334Z","end":"2026-07-28T10:22:40.859738Z","steps":["trace[2088729500] 'process raft request' (duration: 79.524547ms)","trace[2088729500] 'compare' (duration: 31.716231ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:22:41.71877Z","caller":"traceutil/trace.go:171","msg":"trace[2127468076] transaction","detail":"{read_only:false; response_revision:12630; number_of_response:1; }","duration":"112.798461ms","start":"2026-07-28T10:22:41.605947Z","end":"2026-07-28T10:22:41.718745Z","steps":["trace[2127468076] 'process raft request' (duration: 112.659197ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:22:52.137034Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10528} {"level":"info","ts":"2026-07-28T10:22:52.215303Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10528,"took":"73.3005ms","hash":2261377993,"current-db-size-bytes":59289600,"current-db-size":"59 MB","current-db-size-in-use-bytes":26243072,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-28T10:22:52.215378Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2261377993,"revision":10528,"compact-revision":8162} {"level":"info","ts":"2026-07-28T10:27:38.842558Z","caller":"traceutil/trace.go:171","msg":"trace[1351645481] transaction","detail":"{read_only:false; response_revision:14605; number_of_response:1; }","duration":"124.080727ms","start":"2026-07-28T10:27:38.718452Z","end":"2026-07-28T10:27:38.842532Z","steps":["trace[1351645481] 'process raft request' (duration: 123.932863ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:27:38.867798Z","caller":"traceutil/trace.go:171","msg":"trace[865885055] transaction","detail":"{read_only:false; response_revision:14606; number_of_response:1; }","duration":"146.920739ms","start":"2026-07-28T10:27:38.720835Z","end":"2026-07-28T10:27:38.867755Z","steps":["trace[865885055] 'process raft request' (duration: 146.2196ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:27:45.860804Z","caller":"traceutil/trace.go:171","msg":"trace[58101440] transaction","detail":"{read_only:false; response_revision:14656; number_of_response:1; }","duration":"138.481752ms","start":"2026-07-28T10:27:45.722269Z","end":"2026-07-28T10:27:45.86075Z","steps":["trace[58101440] 'process raft request' (duration: 121.274199ms)","trace[58101440] 'compare' (duration: 16.944915ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:27:45.883578Z","caller":"traceutil/trace.go:171","msg":"trace[2041230902] transaction","detail":"{read_only:false; response_revision:14657; number_of_response:1; }","duration":"125.392893ms","start":"2026-07-28T10:27:45.758166Z","end":"2026-07-28T10:27:45.883558Z","steps":["trace[2041230902] 'process raft request' (duration: 125.27153ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:27:47.265704Z","caller":"traceutil/trace.go:171","msg":"trace[1344609877] transaction","detail":"{read_only:false; response_revision:14663; number_of_response:1; }","duration":"348.160075ms","start":"2026-07-28T10:27:46.917504Z","end":"2026-07-28T10:27:47.265664Z","steps":["trace[1344609877] 'process raft request' (duration: 347.975169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:27:47.265884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:27:46.917477Z","time spent":"348.311799ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T10:27:47.266883Z","caller":"traceutil/trace.go:171","msg":"trace[1632547067] transaction","detail":"{read_only:false; response_revision:14664; number_of_response:1; }","duration":"285.157014ms","start":"2026-07-28T10:27:46.981606Z","end":"2026-07-28T10:27:47.266763Z","steps":["trace[1632547067] 'process raft request' (duration: 284.98023ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:27:48.445367Z","caller":"traceutil/trace.go:171","msg":"trace[1968333131] transaction","detail":"{read_only:false; response_revision:14675; number_of_response:1; }","duration":"102.960343ms","start":"2026-07-28T10:27:48.342371Z","end":"2026-07-28T10:27:48.445331Z","steps":["trace[1968333131] 'process raft request' (duration: 102.743937ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:27:52.143565Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12711} {"level":"info","ts":"2026-07-28T10:27:52.224427Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12711,"took":"78.430944ms","hash":490894916,"current-db-size-bytes":59289600,"current-db-size":"59 MB","current-db-size-in-use-bytes":27545600,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-28T10:27:52.224498Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":490894916,"revision":12711,"compact-revision":10528} {"level":"warn","ts":"2026-07-28T10:28:09.840475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.961658ms","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-07-28T10:28:09.840569Z","caller":"traceutil/trace.go:171","msg":"trace[1770767874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14977; }","duration":"291.108891ms","start":"2026-07-28T10:28:09.549437Z","end":"2026-07-28T10:28:09.840546Z","steps":["trace[1770767874] 'range keys from in-memory index tree' (duration: 290.748312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:28:09.841046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.365544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2f006949-a25d-45f2-8f3e-9be5d8af669b\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-07-28T10:28:09.841313Z","caller":"traceutil/trace.go:171","msg":"trace[1188052107] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2f006949-a25d-45f2-8f3e-9be5d8af669b; range_end:; response_count:1; response_revision:14977; }","duration":"214.649442ms","start":"2026-07-28T10:28:09.626629Z","end":"2026-07-28T10:28:09.841279Z","steps":["trace[1188052107] 'range keys from in-memory index tree' (duration: 214.225891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:28:09.84148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.767621ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:28:09.841694Z","caller":"traceutil/trace.go:171","msg":"trace[845171034] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:14977; }","duration":"223.015838ms","start":"2026-07-28T10:28:09.618641Z","end":"2026-07-28T10:28:09.841657Z","steps":["trace[845171034] 'range keys from in-memory index tree' (duration: 222.72729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:28:09.842029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.306494ms","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:5"} {"level":"info","ts":"2026-07-28T10:28:09.842086Z","caller":"traceutil/trace.go:171","msg":"trace[830155076] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:14977; }","duration":"271.393486ms","start":"2026-07-28T10:28:09.570676Z","end":"2026-07-28T10:28:09.842069Z","steps":["trace[830155076] 'count revisions from in-memory index tree' (duration: 271.092988ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:28:10.147479Z","caller":"traceutil/trace.go:171","msg":"trace[1169477873] linearizableReadLoop","detail":"{readStateIndex:15495; appliedIndex:15494; }","duration":"288.5581ms","start":"2026-07-28T10:28:09.858893Z","end":"2026-07-28T10:28:10.147451Z","steps":["trace[1169477873] 'read index received' (duration: 288.231051ms)","trace[1169477873] 'applied index is now lower than readState.Index' (duration: 325.669µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:28:10.147736Z","caller":"traceutil/trace.go:171","msg":"trace[2004886708] transaction","detail":"{read_only:false; response_revision:14978; number_of_response:1; }","duration":"302.473281ms","start":"2026-07-28T10:28:09.845244Z","end":"2026-07-28T10:28:10.147717Z","steps":["trace[2004886708] 'process raft request' (duration: 302.012089ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:28:10.147846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:28:09.845216Z","time spent":"302.564864ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:28:10.147875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.988311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-phhjb\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-07-28T10:28:10.147942Z","caller":"traceutil/trace.go:171","msg":"trace[593706643] range","detail":"{range_begin:/registry/pods/openstack/keepalived-phhjb; range_end:; response_count:1; response_revision:14978; }","duration":"289.068163ms","start":"2026-07-28T10:28:09.858859Z","end":"2026-07-28T10:28:10.147927Z","steps":["trace[593706643] 'agreement among raft nodes before linearized reading' (duration: 288.874308ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:28:10.157094Z","caller":"traceutil/trace.go:171","msg":"trace[2130844419] transaction","detail":"{read_only:false; response_revision:14979; number_of_response:1; }","duration":"197.894823ms","start":"2026-07-28T10:28:09.959185Z","end":"2026-07-28T10:28:10.157079Z","steps":["trace[2130844419] 'process raft request' (duration: 197.626806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:28:10.157377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.090061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-28T10:28:10.157407Z","caller":"traceutil/trace.go:171","msg":"trace[279035562] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:14981; }","duration":"270.154013ms","start":"2026-07-28T10:28:09.887246Z","end":"2026-07-28T10:28:10.1574Z","steps":["trace[279035562] 'agreement among raft nodes before linearized reading' (duration: 270.07565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:28:10.15749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.36308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-28T10:28:10.157559Z","caller":"traceutil/trace.go:171","msg":"trace[1996010772] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14981; }","duration":"190.467493ms","start":"2026-07-28T10:28:09.967077Z","end":"2026-07-28T10:28:10.157544Z","steps":["trace[1996010772] 'agreement among raft nodes before linearized reading' (duration: 190.244446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:28:10.15767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.523426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-2ckpt\" ","response":"range_response_count:1 size:20643"} {"level":"info","ts":"2026-07-28T10:28:10.157701Z","caller":"traceutil/trace.go:171","msg":"trace[1163255209] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-2ckpt; range_end:; response_count:1; response_revision:14981; }","duration":"184.556227ms","start":"2026-07-28T10:28:09.973138Z","end":"2026-07-28T10:28:10.157694Z","steps":["trace[1163255209] 'agreement among raft nodes before linearized reading' (duration: 184.449084ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:29:30.52999Z","caller":"traceutil/trace.go:171","msg":"trace[602508077] linearizableReadLoop","detail":"{readStateIndex:16295; appliedIndex:16294; }","duration":"218.430489ms","start":"2026-07-28T10:29:30.31154Z","end":"2026-07-28T10:29:30.529971Z","steps":["trace[602508077] 'read index received' (duration: 218.281844ms)","trace[602508077] 'applied index is now lower than readState.Index' (duration: 147.835µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:29:30.530088Z","caller":"traceutil/trace.go:171","msg":"trace[276651810] transaction","detail":"{read_only:false; response_revision:15756; number_of_response:1; }","duration":"271.396477ms","start":"2026-07-28T10:29:30.258678Z","end":"2026-07-28T10:29:30.530074Z","steps":["trace[276651810] 'process raft request' (duration: 271.15201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:30.530232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.664207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:29:30.530337Z","caller":"traceutil/trace.go:171","msg":"trace[504378980] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:15756; }","duration":"218.841641ms","start":"2026-07-28T10:29:30.31147Z","end":"2026-07-28T10:29:30.530312Z","steps":["trace[504378980] 'agreement among raft nodes before linearized reading' (duration: 218.646175ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:29:30.723623Z","caller":"traceutil/trace.go:171","msg":"trace[613299205] transaction","detail":"{read_only:false; response_revision:15757; number_of_response:1; }","duration":"243.71612ms","start":"2026-07-28T10:29:30.479868Z","end":"2026-07-28T10:29:30.723584Z","steps":["trace[613299205] 'process raft request' (duration: 243.434592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:30.723764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.160474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:29:30.72385Z","caller":"traceutil/trace.go:171","msg":"trace[1674600162] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15758; }","duration":"196.298987ms","start":"2026-07-28T10:29:30.527535Z","end":"2026-07-28T10:29:30.723834Z","steps":["trace[1674600162] 'agreement among raft nodes before linearized reading' (duration: 196.128411ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:29:30.723835Z","caller":"traceutil/trace.go:171","msg":"trace[1619312329] transaction","detail":"{read_only:false; response_revision:15758; number_of_response:1; }","duration":"233.922894ms","start":"2026-07-28T10:29:30.489786Z","end":"2026-07-28T10:29:30.723709Z","steps":["trace[1619312329] 'process raft request' (duration: 233.721398ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:29:30.723607Z","caller":"traceutil/trace.go:171","msg":"trace[1700777924] linearizableReadLoop","detail":"{readStateIndex:16297; appliedIndex:16295; }","duration":"193.522619ms","start":"2026-07-28T10:29:30.530046Z","end":"2026-07-28T10:29:30.723569Z","steps":["trace[1700777924] 'read index received' (duration: 193.185799ms)","trace[1700777924] 'applied index is now lower than readState.Index' (duration: 335.24µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:29:30.724016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.954071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-07-28T10:29:30.724044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.918324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T10:29:30.724022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.006181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:665"} {"level":"info","ts":"2026-07-28T10:29:30.724096Z","caller":"traceutil/trace.go:171","msg":"trace[1932624767] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15758; }","duration":"378.091456ms","start":"2026-07-28T10:29:30.345986Z","end":"2026-07-28T10:29:30.724078Z","steps":["trace[1932624767] 'agreement among raft nodes before linearized reading' (duration: 377.86957ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:29:30.724104Z","caller":"traceutil/trace.go:171","msg":"trace[193691708] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15758; }","duration":"197.007046ms","start":"2026-07-28T10:29:30.52708Z","end":"2026-07-28T10:29:30.724087Z","steps":["trace[193691708] 'agreement among raft nodes before linearized reading' (duration: 196.921044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:30.724078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.826306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-28T10:29:30.724142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:30.345963Z","time spent":"378.170538ms","remote":"127.0.0.1:49244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5543,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-07-28T10:29:30.724148Z","caller":"traceutil/trace.go:171","msg":"trace[2054876438] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15758; }","duration":"379.192007ms","start":"2026-07-28T10:29:30.344913Z","end":"2026-07-28T10:29:30.724105Z","steps":["trace[2054876438] 'agreement among raft nodes before linearized reading' (duration: 378.791256ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:29:30.724189Z","caller":"traceutil/trace.go:171","msg":"trace[1743855386] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15758; }","duration":"379.005331ms","start":"2026-07-28T10:29:30.345159Z","end":"2026-07-28T10:29:30.724165Z","steps":["trace[1743855386] 'agreement among raft nodes before linearized reading' (duration: 378.796365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:30.724242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:30.345125Z","time spent":"379.106445ms","remote":"127.0.0.1:49244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-07-28T10:29:30.724267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:30.344893Z","time spent":"379.334411ms","remote":"127.0.0.1:49180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":687,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-07-28T10:29:30.724191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.092872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:29:30.724415Z","caller":"traceutil/trace.go:171","msg":"trace[1730347480] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15758; }","duration":"195.529715ms","start":"2026-07-28T10:29:30.528864Z","end":"2026-07-28T10:29:30.724394Z","steps":["trace[1730347480] 'agreement among raft nodes before linearized reading' (duration: 195.062922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:30.855495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.755925ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-28T10:29:30.856242Z","caller":"traceutil/trace.go:171","msg":"trace[1534503484] linearizableReadLoop","detail":"{readStateIndex:16299; appliedIndex:16297; }","duration":"132.163665ms","start":"2026-07-28T10:29:30.724034Z","end":"2026-07-28T10:29:30.856197Z","steps":["trace[1534503484] 'read index received' (duration: 5.627488ms)","trace[1534503484] 'applied index is now lower than readState.Index' (duration: 126.532937ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:29:30.856321Z","caller":"traceutil/trace.go:171","msg":"trace[785524053] transaction","detail":"{read_only:false; response_revision:15759; number_of_response:1; }","duration":"299.887488ms","start":"2026-07-28T10:29:30.55639Z","end":"2026-07-28T10:29:30.856277Z","steps":["trace[785524053] 'process raft request' (duration: 299.218209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:30.85665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:30.556359Z","time spent":"300.157346ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:29:30.885893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.342692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-v8j4j\" ","response":"range_response_count:1 size:9788"} {"level":"info","ts":"2026-07-28T10:29:30.885983Z","caller":"traceutil/trace.go:171","msg":"trace[772126869] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-v8j4j; range_end:; response_count:1; response_revision:15759; }","duration":"136.487736ms","start":"2026-07-28T10:29:30.749472Z","end":"2026-07-28T10:29:30.88596Z","steps":["trace[772126869] 'agreement among raft nodes before linearized reading' (duration: 106.984427ms)","trace[772126869] 'range keys from in-memory index tree' (duration: 29.189991ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:29:30.88599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.273011ms","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-07-28T10:29:30.886095Z","caller":"traceutil/trace.go:171","msg":"trace[1018974503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15759; }","duration":"304.441457ms","start":"2026-07-28T10:29:30.581624Z","end":"2026-07-28T10:29:30.886066Z","steps":["trace[1018974503] 'agreement among raft nodes before linearized reading' (duration: 274.627129ms)","trace[1018974503] 'range keys from in-memory index tree' (duration: 29.512289ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:29:30.886145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:30.581598Z","time spent":"304.536839ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-28T10:29:30.886156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.234411ms","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-07-28T10:29:30.88626Z","caller":"traceutil/trace.go:171","msg":"trace[1589462184] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15759; }","duration":"304.368514ms","start":"2026-07-28T10:29:30.581865Z","end":"2026-07-28T10:29:30.886233Z","steps":["trace[1589462184] 'agreement among raft nodes before linearized reading' (duration: 274.365ms)","trace[1589462184] 'range keys from in-memory index tree' (duration: 29.744756ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:29:30.886314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.580074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-07-28T10:29:30.88637Z","caller":"traceutil/trace.go:171","msg":"trace[937964473] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15759; }","duration":"333.691509ms","start":"2026-07-28T10:29:30.552657Z","end":"2026-07-28T10:29:30.886349Z","steps":["trace[937964473] 'agreement among raft nodes before linearized reading' (duration: 303.671845ms)","trace[937964473] 'range keys from in-memory index tree' (duration: 29.904931ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:29:30.886362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:30.581857Z","time spent":"304.491397ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-28T10:29:30.886423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:30.552628Z","time spent":"333.7829ms","remote":"127.0.0.1:49244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5543,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-07-28T10:29:30.886503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.875798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-28T10:29:30.886559Z","caller":"traceutil/trace.go:171","msg":"trace[645594079] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15759; }","duration":"204.96406ms","start":"2026-07-28T10:29:30.68158Z","end":"2026-07-28T10:29:30.886544Z","steps":["trace[645594079] 'agreement among raft nodes before linearized reading' (duration: 174.624597ms)","trace[645594079] 'range keys from in-memory index tree' (duration: 30.195119ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:29:31.282063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.08605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-phhjb\" ","response":"range_response_count:1 size:6530"} {"level":"warn","ts":"2026-07-28T10:29:31.282094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.59473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-2ckpt\" ","response":"range_response_count:1 size:20643"} {"level":"info","ts":"2026-07-28T10:29:31.282158Z","caller":"traceutil/trace.go:171","msg":"trace[748566752] range","detail":"{range_begin:/registry/pods/openstack/keepalived-phhjb; range_end:; response_count:1; response_revision:15762; }","duration":"117.216324ms","start":"2026-07-28T10:29:31.164921Z","end":"2026-07-28T10:29:31.282137Z","steps":["trace[748566752] 'range keys from in-memory index tree' (duration: 116.942116ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:29:31.282167Z","caller":"traceutil/trace.go:171","msg":"trace[1302659186] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-2ckpt; range_end:; response_count:1; response_revision:15762; }","duration":"215.735103ms","start":"2026-07-28T10:29:31.066414Z","end":"2026-07-28T10:29:31.282149Z","steps":["trace[1302659186] 'range keys from in-memory index tree' (duration: 215.377863ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:29:31.742159Z","caller":"traceutil/trace.go:171","msg":"trace[2122810099] transaction","detail":"{read_only:false; response_revision:15763; number_of_response:1; }","duration":"242.088394ms","start":"2026-07-28T10:29:31.500023Z","end":"2026-07-28T10:29:31.742111Z","steps":["trace[2122810099] 'process raft request' (duration: 241.820977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:32.527079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.405178ms","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-07-28T10:29:32.527194Z","caller":"traceutil/trace.go:171","msg":"trace[1635352647] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15765; }","duration":"492.578394ms","start":"2026-07-28T10:29:32.034592Z","end":"2026-07-28T10:29:32.527171Z","steps":["trace[1635352647] 'range keys from in-memory index tree' (duration: 492.292935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:32.527243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:32.034572Z","time spent":"492.662145ms","remote":"127.0.0.1:49244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-07-28T10:29:32.527391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.765876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-28T10:29:32.527535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.77ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:29:32.527556Z","caller":"traceutil/trace.go:171","msg":"trace[1098266147] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:15765; }","duration":"552.991591ms","start":"2026-07-28T10:29:31.97453Z","end":"2026-07-28T10:29:32.527521Z","steps":["trace[1098266147] 'count revisions from in-memory index tree' (duration: 552.684733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:32.527692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:31.974514Z","time spent":"553.117614ms","remote":"127.0.0.1:58406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":4,"response size":29,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-07-28T10:29:32.527835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.438215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:665"} {"level":"warn","ts":"2026-07-28T10:29:32.527967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.249372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-28T10:29:32.527987Z","caller":"traceutil/trace.go:171","msg":"trace[357380914] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15765; }","duration":"432.679051ms","start":"2026-07-28T10:29:32.095278Z","end":"2026-07-28T10:29:32.527957Z","steps":["trace[357380914] 'range keys from in-memory index tree' (duration: 432.071233ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:29:32.528012Z","caller":"traceutil/trace.go:171","msg":"trace[1925245498] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15765; }","duration":"493.332333ms","start":"2026-07-28T10:29:32.034662Z","end":"2026-07-28T10:29:32.527995Z","steps":["trace[1925245498] 'range keys from in-memory index tree' (duration: 493.16811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:32.528052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:32.034637Z","time spent":"493.406737ms","remote":"127.0.0.1:49244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" "} {"level":"warn","ts":"2026-07-28T10:29:32.527972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.499506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-07-28T10:29:32.528049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:32.095257Z","time spent":"432.778013ms","remote":"127.0.0.1:49180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":687,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-07-28T10:29:32.528144Z","caller":"traceutil/trace.go:171","msg":"trace[2121756598] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15765; }","duration":"432.708161ms","start":"2026-07-28T10:29:32.095421Z","end":"2026-07-28T10:29:32.528129Z","steps":["trace[2121756598] 'range keys from in-memory index tree' (duration: 432.090003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:32.528182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:32.095395Z","time spent":"432.774063ms","remote":"127.0.0.1:49244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5543,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-07-28T10:29:32.527579Z","caller":"traceutil/trace.go:171","msg":"trace[1043431240] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15765; }","duration":"497.853721ms","start":"2026-07-28T10:29:32.029714Z","end":"2026-07-28T10:29:32.527568Z","steps":["trace[1043431240] 'range keys from in-memory index tree' (duration: 497.682366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:32.528273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:32.029692Z","time spent":"498.563012ms","remote":"127.0.0.1:49244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-ks-user\" "} {"level":"warn","ts":"2026-07-28T10:29:32.528337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.01959ms","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-07-28T10:29:32.528371Z","caller":"traceutil/trace.go:171","msg":"trace[1632631697] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15765; }","duration":"433.084682ms","start":"2026-07-28T10:29:32.095278Z","end":"2026-07-28T10:29:32.528362Z","steps":["trace[1632631697] 'range keys from in-memory index tree' (duration: 432.931288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:32.528404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:32.095264Z","time spent":"433.132453ms","remote":"127.0.0.1:49244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-07-28T10:29:32.527904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.805093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-07-28T10:29:32.528502Z","caller":"traceutil/trace.go:171","msg":"trace[901715281] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15765; }","duration":"491.449592ms","start":"2026-07-28T10:29:32.037043Z","end":"2026-07-28T10:29:32.528493Z","steps":["trace[901715281] 'range keys from in-memory index tree' (duration: 490.67944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:32.529419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.078962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:665"} {"level":"warn","ts":"2026-07-28T10:29:32.529452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:32.037027Z","time spent":"492.405588ms","remote":"127.0.0.1:49244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5543,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-07-28T10:29:32.529483Z","caller":"traceutil/trace.go:171","msg":"trace[1969862580] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15765; }","duration":"559.193575ms","start":"2026-07-28T10:29:31.97027Z","end":"2026-07-28T10:29:32.529464Z","steps":["trace[1969862580] 'range keys from in-memory index tree' (duration: 556.792458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:32.529547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:31.970245Z","time spent":"559.287239ms","remote":"127.0.0.1:49180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":687,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-07-28T10:29:33.052935Z","caller":"traceutil/trace.go:171","msg":"trace[2107895804] transaction","detail":"{read_only:false; response_revision:15769; number_of_response:1; }","duration":"105.880185ms","start":"2026-07-28T10:29:32.947025Z","end":"2026-07-28T10:29:33.052905Z","steps":["trace[2107895804] 'process raft request' (duration: 105.212887ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:29:33.198821Z","caller":"traceutil/trace.go:171","msg":"trace[174433885] linearizableReadLoop","detail":"{readStateIndex:16312; appliedIndex:16310; }","duration":"133.262595ms","start":"2026-07-28T10:29:33.065527Z","end":"2026-07-28T10:29:33.19879Z","steps":["trace[174433885] 'read index received' (duration: 28.989984ms)","trace[174433885] 'applied index is now lower than readState.Index' (duration: 104.270921ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:29:33.199013Z","caller":"traceutil/trace.go:171","msg":"trace[2130694572] transaction","detail":"{read_only:false; response_revision:15772; number_of_response:1; }","duration":"134.488979ms","start":"2026-07-28T10:29:33.064498Z","end":"2026-07-28T10:29:33.198987Z","steps":["trace[2130694572] 'process raft request' (duration: 70.312466ms)","trace[2130694572] 'compare' (duration: 63.534625ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:29:33.200586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.036654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737377"} {"level":"info","ts":"2026-07-28T10:29:33.200673Z","caller":"traceutil/trace.go:171","msg":"trace[323232125] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15772; }","duration":"135.138507ms","start":"2026-07-28T10:29:33.065515Z","end":"2026-07-28T10:29:33.200653Z","steps":["trace[323232125] 'agreement among raft nodes before linearized reading' (duration: 133.584343ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:34.003421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.402931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-28T10:29:34.003493Z","caller":"traceutil/trace.go:171","msg":"trace[941335106] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15772; }","duration":"554.560546ms","start":"2026-07-28T10:29:33.448918Z","end":"2026-07-28T10:29:34.003478Z","steps":["trace[941335106] 'range keys from in-memory index tree' (duration: 554.224496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:34.003523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:33.448882Z","time spent":"554.634067ms","remote":"127.0.0.1:49188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-28T10:29:34.003882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.407015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-2ckpt\" ","response":"range_response_count:1 size:20643"} {"level":"info","ts":"2026-07-28T10:29:34.003912Z","caller":"traceutil/trace.go:171","msg":"trace[920943663] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-2ckpt; range_end:; response_count:1; response_revision:15772; }","duration":"700.465626ms","start":"2026-07-28T10:29:33.303438Z","end":"2026-07-28T10:29:34.003904Z","steps":["trace[920943663] 'range keys from in-memory index tree' (duration: 700.303681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:34.003931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:33.303424Z","time spent":"700.502607ms","remote":"127.0.0.1:49188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20665,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-2ckpt\" "} {"level":"warn","ts":"2026-07-28T10:29:34.003464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.601976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-phhjb\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-07-28T10:29:34.004052Z","caller":"traceutil/trace.go:171","msg":"trace[1107380443] range","detail":"{range_begin:/registry/pods/openstack/keepalived-phhjb; range_end:; response_count:1; response_revision:15772; }","duration":"703.267154ms","start":"2026-07-28T10:29:33.300761Z","end":"2026-07-28T10:29:34.004028Z","steps":["trace[1107380443] 'range keys from in-memory index tree' (duration: 702.398011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:34.004131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:33.300733Z","time spent":"703.382668ms","remote":"127.0.0.1:49188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6552,"request content":"key:\"/registry/pods/openstack/keepalived-phhjb\" "} {"level":"info","ts":"2026-07-28T10:29:34.004959Z","caller":"traceutil/trace.go:171","msg":"trace[1888665763] transaction","detail":"{read_only:false; response_revision:15773; number_of_response:1; }","duration":"528.516964ms","start":"2026-07-28T10:29:33.476424Z","end":"2026-07-28T10:29:34.004941Z","steps":["trace[1888665763] 'process raft request' (duration: 522.553766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:34.005118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:33.476389Z","time spent":"528.655857ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T10:29:34.525941Z","caller":"traceutil/trace.go:171","msg":"trace[1702195036] transaction","detail":"{read_only:false; response_revision:15774; number_of_response:1; }","duration":"1.01230399s","start":"2026-07-28T10:29:33.513595Z","end":"2026-07-28T10:29:34.525899Z","steps":["trace[1702195036] 'process raft request' (duration: 1.003191883s)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:29:34.525973Z","caller":"traceutil/trace.go:171","msg":"trace[1064512057] linearizableReadLoop","detail":"{readStateIndex:16314; appliedIndex:16312; }","duration":"779.122307ms","start":"2026-07-28T10:29:33.746819Z","end":"2026-07-28T10:29:34.525941Z","steps":["trace[1064512057] 'read index received' (duration: 252.175827ms)","trace[1064512057] 'applied index is now lower than readState.Index' (duration: 526.94515ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:29:34.526043Z","caller":"traceutil/trace.go:171","msg":"trace[310097109] transaction","detail":"{read_only:false; response_revision:15775; number_of_response:1; }","duration":"758.449616ms","start":"2026-07-28T10:29:33.76756Z","end":"2026-07-28T10:29:34.52601Z","steps":["trace[310097109] 'process raft request' (duration: 758.24732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:34.526107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:33.513564Z","time spent":"1.012450534s","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:29:34.526228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:33.767534Z","time spent":"758.604669ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:29:34.526252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.414235ms","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-07-28T10:29:34.526295Z","caller":"traceutil/trace.go:171","msg":"trace[1818507485] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15775; }","duration":"779.520298ms","start":"2026-07-28T10:29:33.74676Z","end":"2026-07-28T10:29:34.52628Z","steps":["trace[1818507485] 'agreement among raft nodes before linearized reading' (duration: 779.332993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:34.526348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:33.746739Z","time spent":"779.60006ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-28T10:29:34.526453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.306377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-k62lg\" ","response":"range_response_count:1 size:22100"} {"level":"info","ts":"2026-07-28T10:29:34.526541Z","caller":"traceutil/trace.go:171","msg":"trace[361907138] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-k62lg; range_end:; response_count:1; response_revision:15775; }","duration":"734.537144ms","start":"2026-07-28T10:29:33.791983Z","end":"2026-07-28T10:29:34.52652Z","steps":["trace[361907138] 'agreement among raft nodes before linearized reading' (duration: 734.193444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:34.526608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:33.791963Z","time spent":"734.625716ms","remote":"127.0.0.1:49188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22122,"request content":"key:\"/registry/pods/openstack/nova-compute-default-k62lg\" "} {"level":"warn","ts":"2026-07-28T10:29:34.526733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.042886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:665"} {"level":"info","ts":"2026-07-28T10:29:34.526805Z","caller":"traceutil/trace.go:171","msg":"trace[64438027] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15775; }","duration":"689.151298ms","start":"2026-07-28T10:29:33.837637Z","end":"2026-07-28T10:29:34.526789Z","steps":["trace[64438027] 'agreement among raft nodes before linearized reading' (duration: 688.933662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:34.526847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:33.837613Z","time spent":"689.225721ms","remote":"127.0.0.1:49180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":687,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-07-28T10:29:34.52691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.538007ms","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-07-28T10:29:34.526951Z","caller":"traceutil/trace.go:171","msg":"trace[1360123836] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15775; }","duration":"690.612409ms","start":"2026-07-28T10:29:33.836327Z","end":"2026-07-28T10:29:34.52694Z","steps":["trace[1360123836] 'agreement among raft nodes before linearized reading' (duration: 690.545197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:34.526979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:33.83631Z","time spent":"690.66322ms","remote":"127.0.0.1:49244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-07-28T10:29:34.526494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.071798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-07-28T10:29:34.527129Z","caller":"traceutil/trace.go:171","msg":"trace[1011983424] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15775; }","duration":"624.717278ms","start":"2026-07-28T10:29:33.902397Z","end":"2026-07-28T10:29:34.527114Z","steps":["trace[1011983424] 'agreement among raft nodes before linearized reading' (duration: 623.985037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:34.527134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.257891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-07-28T10:29:34.527175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:33.90239Z","time spent":"624.77062ms","remote":"127.0.0.1:49244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5543,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-07-28T10:29:34.527269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.214642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-k62lg\" ","response":"range_response_count:1 size:22100"} {"level":"warn","ts":"2026-07-28T10:29:34.527289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.911813ms","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-07-28T10:29:34.527315Z","caller":"traceutil/trace.go:171","msg":"trace[1678906546] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-k62lg; range_end:; response_count:1; response_revision:15775; }","duration":"735.257693ms","start":"2026-07-28T10:29:33.792044Z","end":"2026-07-28T10:29:34.527301Z","steps":["trace[1678906546] 'agreement among raft nodes before linearized reading' (duration: 735.097998ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:29:34.527333Z","caller":"traceutil/trace.go:171","msg":"trace[1252532066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15775; }","duration":"624.989365ms","start":"2026-07-28T10:29:33.902329Z","end":"2026-07-28T10:29:34.527319Z","steps":["trace[1252532066] 'agreement among raft nodes before linearized reading' (duration: 624.858091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:34.527352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:33.791985Z","time spent":"735.360036ms","remote":"127.0.0.1:49188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22122,"request content":"key:\"/registry/pods/openstack/nova-compute-default-k62lg\" "} {"level":"warn","ts":"2026-07-28T10:29:34.527364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:33.902309Z","time spent":"625.047355ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-28T10:29:34.52718Z","caller":"traceutil/trace.go:171","msg":"trace[2009065374] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15775; }","duration":"689.408926ms","start":"2026-07-28T10:29:33.837757Z","end":"2026-07-28T10:29:34.527166Z","steps":["trace[2009065374] 'agreement among raft nodes before linearized reading' (duration: 689.252311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:34.527506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:33.837738Z","time spent":"689.752046ms","remote":"127.0.0.1:49244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5543,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-07-28T10:29:34.527565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.62218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737377"} {"level":"warn","ts":"2026-07-28T10:29:34.527572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.728953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-28T10:29:34.527611Z","caller":"traceutil/trace.go:171","msg":"trace[1228131574] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15775; }","duration":"505.676362ms","start":"2026-07-28T10:29:34.021924Z","end":"2026-07-28T10:29:34.527601Z","steps":["trace[1228131574] 'agreement among raft nodes before linearized reading' (duration: 504.717305ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:29:34.527616Z","caller":"traceutil/trace.go:171","msg":"trace[1732813046] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:15775; }","duration":"505.800526ms","start":"2026-07-28T10:29:34.021804Z","end":"2026-07-28T10:29:34.527605Z","steps":["trace[1732813046] 'agreement among raft nodes before linearized reading' (duration: 505.371334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:34.527678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:34.02192Z","time spent":"505.747173ms","remote":"127.0.0.1:49188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":76,"response size":737399,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-07-28T10:29:34.527687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:34.0218Z","time spent":"505.879617ms","remote":"127.0.0.1:49188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-28T10:29:34.529474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.834853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737377"} {"level":"info","ts":"2026-07-28T10:29:34.530472Z","caller":"traceutil/trace.go:171","msg":"trace[842407840] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15775; }","duration":"508.866342ms","start":"2026-07-28T10:29:34.021585Z","end":"2026-07-28T10:29:34.530451Z","steps":["trace[842407840] 'agreement among raft nodes before linearized reading' (duration: 505.416115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:29:34.530591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:29:34.021568Z","time spent":"509.002285ms","remote":"127.0.0.1:49188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":76,"response size":737399,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-07-28T10:32:07.9922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.029362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-07-28T10:32:07.992316Z","caller":"traceutil/trace.go:171","msg":"trace[1414244427] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:16853; }","duration":"105.180097ms","start":"2026-07-28T10:32:07.887111Z","end":"2026-07-28T10:32:07.992292Z","steps":["trace[1414244427] 'agreement among raft nodes before linearized reading' (duration: 104.912389ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:32:07.992485Z","caller":"traceutil/trace.go:171","msg":"trace[1184281951] transaction","detail":"{read_only:false; response_revision:16853; number_of_response:1; }","duration":"100.494195ms","start":"2026-07-28T10:32:07.891953Z","end":"2026-07-28T10:32:07.992447Z","steps":["trace[1184281951] 'process raft request' (duration: 57.65183ms)","trace[1184281951] 'compare' (duration: 42.135945ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:32:07.992591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.86339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:994"} {"level":"info","ts":"2026-07-28T10:32:07.992695Z","caller":"traceutil/trace.go:171","msg":"trace[1196517803] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:16853; }","duration":"108.985773ms","start":"2026-07-28T10:32:07.883686Z","end":"2026-07-28T10:32:07.992672Z","steps":["trace[1196517803] 'agreement among raft nodes before linearized reading' (duration: 108.663624ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:32:08.014184Z","caller":"traceutil/trace.go:171","msg":"trace[2014509689] transaction","detail":"{read_only:false; response_revision:16854; number_of_response:1; }","duration":"113.53549ms","start":"2026-07-28T10:32:07.900629Z","end":"2026-07-28T10:32:08.014165Z","steps":["trace[2014509689] 'process raft request' (duration: 113.339064ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:08.014664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.544849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18684"} {"level":"info","ts":"2026-07-28T10:32:08.014745Z","caller":"traceutil/trace.go:171","msg":"trace[202547889] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:16855; }","duration":"114.664453ms","start":"2026-07-28T10:32:07.900062Z","end":"2026-07-28T10:32:08.014726Z","steps":["trace[202547889] 'agreement among raft nodes before linearized reading' (duration: 114.291273ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:32:08.014823Z","caller":"traceutil/trace.go:171","msg":"trace[1689327904] transaction","detail":"{read_only:false; response_revision:16855; number_of_response:1; }","duration":"114.073427ms","start":"2026-07-28T10:32:07.900709Z","end":"2026-07-28T10:32:08.014782Z","steps":["trace[1689327904] 'process raft request' (duration: 113.381237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:08.015124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.757147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-neutron\" ","response":"range_response_count:1 size:1453"} {"level":"info","ts":"2026-07-28T10:32:08.01532Z","caller":"traceutil/trace.go:171","msg":"trace[170177445] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-neutron; range_end:; response_count:1; response_revision:16855; }","duration":"113.944482ms","start":"2026-07-28T10:32:07.901333Z","end":"2026-07-28T10:32:08.015277Z","steps":["trace[170177445] 'agreement among raft nodes before linearized reading' (duration: 113.482239ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:32:09.073622Z","caller":"traceutil/trace.go:171","msg":"trace[1044955366] transaction","detail":"{read_only:false; response_revision:16871; number_of_response:1; }","duration":"103.845678ms","start":"2026-07-28T10:32:08.96975Z","end":"2026-07-28T10:32:09.073595Z","steps":["trace[1044955366] 'process raft request' (duration: 103.667803ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:32:13.278542Z","caller":"traceutil/trace.go:171","msg":"trace[1329572450] transaction","detail":"{read_only:false; response_revision:16904; number_of_response:1; }","duration":"193.395425ms","start":"2026-07-28T10:32:13.085127Z","end":"2026-07-28T10:32:13.278523Z","steps":["trace[1329572450] 'process raft request' (duration: 193.239672ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:32:13.278645Z","caller":"traceutil/trace.go:171","msg":"trace[1071097390] linearizableReadLoop","detail":"{readStateIndex:17485; appliedIndex:17485; }","duration":"137.539985ms","start":"2026-07-28T10:32:13.14107Z","end":"2026-07-28T10:32:13.27861Z","steps":["trace[1071097390] 'read index received' (duration: 137.526455ms)","trace[1071097390] 'applied index is now lower than readState.Index' (duration: 10.85µs)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:32:13.278793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.70318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-28T10:32:13.27886Z","caller":"traceutil/trace.go:171","msg":"trace[491941936] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:16904; }","duration":"137.822564ms","start":"2026-07-28T10:32:13.141021Z","end":"2026-07-28T10:32:13.278844Z","steps":["trace[491941936] 'agreement among raft nodes before linearized reading' (duration: 137.68946ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:32:13.29476Z","caller":"traceutil/trace.go:171","msg":"trace[127957805] transaction","detail":"{read_only:false; response_revision:16905; number_of_response:1; }","duration":"102.816149ms","start":"2026-07-28T10:32:13.191922Z","end":"2026-07-28T10:32:13.294738Z","steps":["trace[127957805] 'process raft request' (duration: 102.487519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:13.295215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.254218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-07-28T10:32:13.295266Z","caller":"traceutil/trace.go:171","msg":"trace[808976973] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:16905; }","duration":"116.351719ms","start":"2026-07-28T10:32:13.178899Z","end":"2026-07-28T10:32:13.295251Z","steps":["trace[808976973] 'agreement among raft nodes before linearized reading' (duration: 116.207775ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:32:50.668663Z","caller":"traceutil/trace.go:171","msg":"trace[1987553891] linearizableReadLoop","detail":"{readStateIndex:17908; appliedIndex:17907; }","duration":"211.963469ms","start":"2026-07-28T10:32:50.456664Z","end":"2026-07-28T10:32:50.668627Z","steps":["trace[1987553891] 'read index received' (duration: 150.10596ms)","trace[1987553891] 'applied index is now lower than readState.Index' (duration: 61.856189ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:32:50.668715Z","caller":"traceutil/trace.go:171","msg":"trace[91678026] transaction","detail":"{read_only:false; response_revision:17318; number_of_response:1; }","duration":"254.250578ms","start":"2026-07-28T10:32:50.414409Z","end":"2026-07-28T10:32:50.66866Z","steps":["trace[91678026] 'process raft request' (duration: 192.43803ms)","trace[91678026] 'compare' (duration: 61.533949ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:32:50.668855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.165955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-28T10:32:50.66894Z","caller":"traceutil/trace.go:171","msg":"trace[1194912607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17318; }","duration":"212.341209ms","start":"2026-07-28T10:32:50.45657Z","end":"2026-07-28T10:32:50.668912Z","steps":["trace[1194912607] 'agreement among raft nodes before linearized reading' (duration: 212.189325ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:32:50.683265Z","caller":"traceutil/trace.go:171","msg":"trace[1098002726] transaction","detail":"{read_only:false; response_revision:17319; number_of_response:1; }","duration":"138.989878ms","start":"2026-07-28T10:32:50.544248Z","end":"2026-07-28T10:32:50.683238Z","steps":["trace[1098002726] 'process raft request' (duration: 138.756702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:50.683302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.143576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-28T10:32:50.683292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.698039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-28T10:32:50.683345Z","caller":"traceutil/trace.go:171","msg":"trace[711564486] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-endpoints; range_end:; response_count:0; response_revision:17319; }","duration":"116.228757ms","start":"2026-07-28T10:32:50.567106Z","end":"2026-07-28T10:32:50.683334Z","steps":["trace[711564486] 'agreement among raft nodes before linearized reading' (duration: 116.104784ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:32:50.683365Z","caller":"traceutil/trace.go:171","msg":"trace[2031771749] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17319; }","duration":"117.813612ms","start":"2026-07-28T10:32:50.565532Z","end":"2026-07-28T10:32:50.683346Z","steps":["trace[2031771749] 'agreement among raft nodes before linearized reading' (duration: 117.671519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:50.68336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.176076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-28T10:32:50.683426Z","caller":"traceutil/trace.go:171","msg":"trace[1842058525] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:0; response_revision:17319; }","duration":"116.319869ms","start":"2026-07-28T10:32:50.567091Z","end":"2026-07-28T10:32:50.683411Z","steps":["trace[1842058525] 'agreement among raft nodes before linearized reading' (duration: 116.221577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:50.683558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.173291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-phhjb\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-07-28T10:32:50.683588Z","caller":"traceutil/trace.go:171","msg":"trace[132527408] range","detail":"{range_begin:/registry/pods/openstack/keepalived-phhjb; range_end:; response_count:1; response_revision:17319; }","duration":"177.243103ms","start":"2026-07-28T10:32:50.506337Z","end":"2026-07-28T10:32:50.68358Z","steps":["trace[132527408] 'agreement among raft nodes before linearized reading' (duration: 177.14793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:50.683827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.740883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-28T10:32:50.683913Z","caller":"traceutil/trace.go:171","msg":"trace[1737984631] range","detail":"{range_begin:/registry/jobs/openstack/neutron-db-sync; range_end:; response_count:0; response_revision:17319; }","duration":"115.839906ms","start":"2026-07-28T10:32:50.568055Z","end":"2026-07-28T10:32:50.683895Z","steps":["trace[1737984631] 'agreement among raft nodes before linearized reading' (duration: 115.221708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:50.982097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.623278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-28T10:32:50.982198Z","caller":"traceutil/trace.go:171","msg":"trace[1346154025] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:17319; }","duration":"164.744292ms","start":"2026-07-28T10:32:50.817428Z","end":"2026-07-28T10:32:50.982172Z","steps":["trace[1346154025] 'count revisions from in-memory index tree' (duration: 164.550036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:50.98247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.039209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-2ckpt\" ","response":"range_response_count:1 size:20644"} {"level":"info","ts":"2026-07-28T10:32:50.982865Z","caller":"traceutil/trace.go:171","msg":"trace[1021530387] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-2ckpt; range_end:; response_count:1; response_revision:17319; }","duration":"128.312987ms","start":"2026-07-28T10:32:50.854352Z","end":"2026-07-28T10:32:50.982665Z","steps":["trace[1021530387] 'range keys from in-memory index tree' (duration: 127.631709ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:32:51.7861Z","caller":"traceutil/trace.go:171","msg":"trace[2031217494] linearizableReadLoop","detail":"{readStateIndex:17910; appliedIndex:17909; }","duration":"312.924577ms","start":"2026-07-28T10:32:51.473149Z","end":"2026-07-28T10:32:51.786074Z","steps":["trace[2031217494] 'read index received' (duration: 312.738702ms)","trace[2031217494] 'applied index is now lower than readState.Index' (duration: 184.685µs)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:32:51.786275Z","caller":"traceutil/trace.go:171","msg":"trace[1415581323] transaction","detail":"{read_only:false; response_revision:17320; number_of_response:1; }","duration":"315.679004ms","start":"2026-07-28T10:32:51.470578Z","end":"2026-07-28T10:32:51.786257Z","steps":["trace[1415581323] 'process raft request' (duration: 315.314544ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:51.786418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:51.470559Z","time spent":"315.768488ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:32:51.78637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.161284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-28T10:32:51.786666Z","caller":"traceutil/trace.go:171","msg":"trace[10492660] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17320; }","duration":"313.524924ms","start":"2026-07-28T10:32:51.473128Z","end":"2026-07-28T10:32:51.786653Z","steps":["trace[10492660] 'agreement among raft nodes before linearized reading' (duration: 313.084321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:51.786716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:51.473119Z","time spent":"313.583305ms","remote":"127.0.0.1:49244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" "} {"level":"warn","ts":"2026-07-28T10:32:52.255699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.789124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-28T10:32:52.255939Z","caller":"traceutil/trace.go:171","msg":"trace[494027608] transaction","detail":"{read_only:false; response_revision:17321; number_of_response:1; }","duration":"617.051037ms","start":"2026-07-28T10:32:51.638844Z","end":"2026-07-28T10:32:52.255895Z","steps":["trace[494027608] 'process raft request' (duration: 415.893442ms)","trace[494027608] 'compare' (duration: 200.59103ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:32:52.256113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.954006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-07-28T10:32:52.256114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:51.638828Z","time spent":"617.194831ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T10:32:52.256155Z","caller":"traceutil/trace.go:171","msg":"trace[1659487706] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17323; }","duration":"780.007268ms","start":"2026-07-28T10:32:51.476136Z","end":"2026-07-28T10:32:52.256143Z","steps":["trace[1659487706] 'agreement among raft nodes before linearized reading' (duration: 779.867084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.256191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:51.476129Z","time spent":"780.053901ms","remote":"127.0.0.1:49180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-07-28T10:32:52.256196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.399675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-07-28T10:32:52.255968Z","caller":"traceutil/trace.go:171","msg":"trace[754258596] linearizableReadLoop","detail":"{readStateIndex:17913; appliedIndex:17910; }","duration":"469.779406ms","start":"2026-07-28T10:32:51.786168Z","end":"2026-07-28T10:32:52.255948Z","steps":["trace[754258596] 'read index received' (duration: 268.536659ms)","trace[754258596] 'applied index is now lower than readState.Index' (duration: 201.241857ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:32:52.256261Z","caller":"traceutil/trace.go:171","msg":"trace[1157996076] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17323; }","duration":"592.497797ms","start":"2026-07-28T10:32:51.663741Z","end":"2026-07-28T10:32:52.256239Z","steps":["trace[1157996076] 'agreement among raft nodes before linearized reading' (duration: 592.299981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.256304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:51.663725Z","time spent":"592.56884ms","remote":"127.0.0.1:49180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-07-28T10:32:52.256362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.426729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-28T10:32:52.256361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.236362ms","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-07-28T10:32:52.256359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.347849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-28T10:32:52.256421Z","caller":"traceutil/trace.go:171","msg":"trace[1817814294] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:17323; }","duration":"627.503701ms","start":"2026-07-28T10:32:51.628898Z","end":"2026-07-28T10:32:52.256402Z","steps":["trace[1817814294] 'agreement among raft nodes before linearized reading' (duration: 627.388508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.256408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.444018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-8tmw9\" ","response":"range_response_count:1 size:14278"} {"level":"info","ts":"2026-07-28T10:32:52.256427Z","caller":"traceutil/trace.go:171","msg":"trace[1159710102] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17323; }","duration":"538.364495ms","start":"2026-07-28T10:32:51.718046Z","end":"2026-07-28T10:32:52.25641Z","steps":["trace[1159710102] 'agreement among raft nodes before linearized reading' (duration: 538.19105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.256395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.45887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-28T10:32:52.256453Z","caller":"traceutil/trace.go:171","msg":"trace[640695732] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:17323; }","duration":"721.469652ms","start":"2026-07-28T10:32:51.534958Z","end":"2026-07-28T10:32:52.256428Z","steps":["trace[640695732] 'agreement among raft nodes before linearized reading' (duration: 721.269917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.256466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:51.628884Z","time spent":"627.570643ms","remote":"127.0.0.1:58390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":22,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-07-28T10:32:52.256482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:51.718011Z","time spent":"538.459468ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-28T10:32:52.256483Z","caller":"traceutil/trace.go:171","msg":"trace[2002003017] range","detail":"{range_begin:/registry/pods/openstack/neutron-metadata-agent-default-8tmw9; range_end:; response_count:1; response_revision:17323; }","duration":"533.57562ms","start":"2026-07-28T10:32:51.722886Z","end":"2026-07-28T10:32:52.256462Z","steps":["trace[2002003017] 'agreement among raft nodes before linearized reading' (duration: 533.396216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.256489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.664142ms","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-07-28T10:32:52.256478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.588309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-26v6d\" ","response":"range_response_count:1 size:18647"} {"level":"warn","ts":"2026-07-28T10:32:52.256509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:51.534926Z","time spent":"721.566887ms","remote":"127.0.0.1:49648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-28T10:32:52.256532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:51.722863Z","time spent":"533.656492ms","remote":"127.0.0.1:49188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":14300,"request content":"key:\"/registry/pods/openstack/neutron-metadata-agent-default-8tmw9\" "} {"level":"info","ts":"2026-07-28T10:32:52.256552Z","caller":"traceutil/trace.go:171","msg":"trace[434915772] range","detail":"{range_begin:/registry/pods/openstack/neutron-dhcp-agent-default-26v6d; range_end:; response_count:1; response_revision:17323; }","duration":"709.687271ms","start":"2026-07-28T10:32:51.546847Z","end":"2026-07-28T10:32:52.256534Z","steps":["trace[434915772] 'agreement among raft nodes before linearized reading' (duration: 709.489016ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:32:52.256558Z","caller":"traceutil/trace.go:171","msg":"trace[2098152262] transaction","detail":"{read_only:false; response_revision:17322; number_of_response:1; }","duration":"589.913445ms","start":"2026-07-28T10:32:51.666634Z","end":"2026-07-28T10:32:52.256548Z","steps":["trace[2098152262] 'process raft request' (duration: 589.163693ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:32:52.256555Z","caller":"traceutil/trace.go:171","msg":"trace[512470700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17323; }","duration":"538.735866ms","start":"2026-07-28T10:32:51.717786Z","end":"2026-07-28T10:32:52.256522Z","steps":["trace[512470700] 'agreement among raft nodes before linearized reading' (duration: 538.609153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.256597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:51.546835Z","time spent":"709.749802ms","remote":"127.0.0.1:49188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":18669,"request content":"key:\"/registry/pods/openstack/neutron-dhcp-agent-default-26v6d\" "} {"level":"warn","ts":"2026-07-28T10:32:52.256574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.739288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"info","ts":"2026-07-28T10:32:52.256506Z","caller":"traceutil/trace.go:171","msg":"trace[1403363173] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17323; }","duration":"775.592363ms","start":"2026-07-28T10:32:51.480888Z","end":"2026-07-28T10:32:52.25648Z","steps":["trace[1403363173] 'agreement among raft nodes before linearized reading' (duration: 775.387698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.256609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:51.717775Z","time spent":"538.822267ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-28T10:32:52.256668Z","caller":"traceutil/trace.go:171","msg":"trace[1044367336] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17323; }","duration":"775.843191ms","start":"2026-07-28T10:32:51.480799Z","end":"2026-07-28T10:32:52.256642Z","steps":["trace[1044367336] 'agreement among raft nodes before linearized reading' (duration: 775.549663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.256674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:51.480884Z","time spent":"775.768879ms","remote":"127.0.0.1:49244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" "} {"level":"warn","ts":"2026-07-28T10:32:52.256674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:51.666616Z","time spent":"589.978595ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T10:32:52.256503Z","caller":"traceutil/trace.go:171","msg":"trace[832138831] transaction","detail":"{read_only:false; response_revision:17323; number_of_response:1; }","duration":"495.255272ms","start":"2026-07-28T10:32:51.761227Z","end":"2026-07-28T10:32:52.256483Z","steps":["trace[832138831] 'process raft request' (duration: 494.663456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.256714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:51.480779Z","time spent":"775.926314ms","remote":"127.0.0.1:49180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-07-28T10:32:52.256716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.97243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-28T10:32:52.256815Z","caller":"traceutil/trace.go:171","msg":"trace[169097335] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17323; }","duration":"593.651889ms","start":"2026-07-28T10:32:51.663102Z","end":"2026-07-28T10:32:52.256754Z","steps":["trace[169097335] 'agreement among raft nodes before linearized reading' (duration: 593.571047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.256824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:51.761201Z","time spent":"495.526701ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:32:52.256878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.637424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:702"} {"level":"warn","ts":"2026-07-28T10:32:52.256888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:51.663068Z","time spent":"593.807234ms","remote":"127.0.0.1:49244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" "} {"level":"info","ts":"2026-07-28T10:32:52.256926Z","caller":"traceutil/trace.go:171","msg":"trace[137101580] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17323; }","duration":"490.732715ms","start":"2026-07-28T10:32:51.76618Z","end":"2026-07-28T10:32:52.256913Z","steps":["trace[137101580] 'agreement among raft nodes before linearized reading' (duration: 490.561501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.256966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:51.766156Z","time spent":"490.798527ms","remote":"127.0.0.1:49180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":724,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-07-28T10:32:52.259309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.825437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-v8j4j\" ","response":"range_response_count:1 size:9789"} {"level":"info","ts":"2026-07-28T10:32:52.259368Z","caller":"traceutil/trace.go:171","msg":"trace[1312112704] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-v8j4j; range_end:; response_count:1; response_revision:17323; }","duration":"750.911209ms","start":"2026-07-28T10:32:51.508443Z","end":"2026-07-28T10:32:52.259354Z","steps":["trace[1312112704] 'agreement among raft nodes before linearized reading' (duration: 748.09782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.259402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:51.508432Z","time spent":"750.961211ms","remote":"127.0.0.1:49188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9811,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-v8j4j\" "} {"level":"warn","ts":"2026-07-28T10:32:52.259845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.667716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-28T10:32:52.259903Z","caller":"traceutil/trace.go:171","msg":"trace[475646286] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:17323; }","duration":"515.729078ms","start":"2026-07-28T10:32:51.744157Z","end":"2026-07-28T10:32:52.259886Z","steps":["trace[475646286] 'agreement among raft nodes before linearized reading' (duration: 511.970553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.259936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:51.744113Z","time spent":"515.81349ms","remote":"127.0.0.1:49326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":18,"response size":30,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-07-28T10:32:52.333389Z","caller":"traceutil/trace.go:171","msg":"trace[804525309] transaction","detail":"{read_only:false; response_revision:17324; number_of_response:1; }","duration":"320.799919ms","start":"2026-07-28T10:32:52.012496Z","end":"2026-07-28T10:32:52.333296Z","steps":["trace[804525309] 'process raft request' (duration: 320.638084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.333553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:52.012471Z","time spent":"321.009974ms","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":949,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-28T10:32:52.502828Z","caller":"traceutil/trace.go:171","msg":"trace[1085322034] linearizableReadLoop","detail":"{readStateIndex:17915; appliedIndex:17913; }","duration":"246.557342ms","start":"2026-07-28T10:32:52.256249Z","end":"2026-07-28T10:32:52.502806Z","steps":["trace[1085322034] 'read index received' (duration: 76.947924ms)","trace[1085322034] 'applied index is now lower than readState.Index' (duration: 169.608538ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:32:52.502972Z","caller":"traceutil/trace.go:171","msg":"trace[1101312335] transaction","detail":"{read_only:false; response_revision:17327; number_of_response:1; }","duration":"241.622784ms","start":"2026-07-28T10:32:52.261313Z","end":"2026-07-28T10:32:52.502935Z","steps":["trace[1101312335] 'process raft request' (duration: 241.436648ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:32:52.503005Z","caller":"traceutil/trace.go:171","msg":"trace[1048507080] transaction","detail":"{read_only:false; response_revision:17325; number_of_response:1; }","duration":"353.418965ms","start":"2026-07-28T10:32:52.14955Z","end":"2026-07-28T10:32:52.502969Z","steps":["trace[1048507080] 'process raft request' (duration: 352.996153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.503062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.987377ms","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-07-28T10:32:52.503095Z","caller":"traceutil/trace.go:171","msg":"trace[644194241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17327; }","duration":"373.074889ms","start":"2026-07-28T10:32:52.13001Z","end":"2026-07-28T10:32:52.503085Z","steps":["trace[644194241] 'agreement among raft nodes before linearized reading' (duration: 372.935145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.503136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:52.129988Z","time spent":"373.14014ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-28T10:32:52.503194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:52.149529Z","time spent":"353.55316ms","remote":"127.0.0.1:49046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":26,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T10:32:52.503272Z","caller":"traceutil/trace.go:171","msg":"trace[1375506672] transaction","detail":"{read_only:false; response_revision:17326; number_of_response:1; }","duration":"242.040065ms","start":"2026-07-28T10:32:52.261221Z","end":"2026-07-28T10:32:52.503261Z","steps":["trace[1375506672] 'process raft request' (duration: 241.470909ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.503389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.654603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-28T10:32:52.503424Z","caller":"traceutil/trace.go:171","msg":"trace[700997285] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:17327; }","duration":"151.721556ms","start":"2026-07-28T10:32:52.351691Z","end":"2026-07-28T10:32:52.503412Z","steps":["trace[700997285] 'agreement among raft nodes before linearized reading' (duration: 151.666655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.503712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.659822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-28T10:32:52.503769Z","caller":"traceutil/trace.go:171","msg":"trace[341071630] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:17327; }","duration":"507.752764ms","start":"2026-07-28T10:32:51.996004Z","end":"2026-07-28T10:32:52.503756Z","steps":["trace[341071630] 'agreement among raft nodes before linearized reading' (duration: 507.58871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.503761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.244284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:89 size:898682"} {"level":"warn","ts":"2026-07-28T10:32:52.503805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:32:51.995982Z","time spent":"507.814216ms","remote":"127.0.0.1:49180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":74,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-07-28T10:32:52.503817Z","caller":"traceutil/trace.go:171","msg":"trace[2078546152] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:89; response_revision:17327; }","duration":"240.324307ms","start":"2026-07-28T10:32:52.263481Z","end":"2026-07-28T10:32:52.503805Z","steps":["trace[2078546152] 'agreement among raft nodes before linearized reading' (duration: 239.561626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.504035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.813527ms","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-07-28T10:32:52.504095Z","caller":"traceutil/trace.go:171","msg":"trace[2074151748] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17327; }","duration":"141.906309ms","start":"2026-07-28T10:32:52.362173Z","end":"2026-07-28T10:32:52.50408Z","steps":["trace[2074151748] 'agreement among raft nodes before linearized reading' (duration: 141.681533ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.504631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.299328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:89 size:898682"} {"level":"info","ts":"2026-07-28T10:32:52.504684Z","caller":"traceutil/trace.go:171","msg":"trace[1576248956] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:89; response_revision:17327; }","duration":"238.379181ms","start":"2026-07-28T10:32:52.266292Z","end":"2026-07-28T10:32:52.504671Z","steps":["trace[1576248956] 'agreement among raft nodes before linearized reading' (duration: 237.376344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.504833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.387345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-28T10:32:52.50489Z","caller":"traceutil/trace.go:171","msg":"trace[228671353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17327; }","duration":"215.470618ms","start":"2026-07-28T10:32:52.289406Z","end":"2026-07-28T10:32:52.504876Z","steps":["trace[228671353] 'agreement among raft nodes before linearized reading' (duration: 215.326915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:32:52.505022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.78915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:89 size:898682"} {"level":"info","ts":"2026-07-28T10:32:52.505068Z","caller":"traceutil/trace.go:171","msg":"trace[1628126849] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:89; response_revision:17327; }","duration":"240.848442ms","start":"2026-07-28T10:32:52.264209Z","end":"2026-07-28T10:32:52.505058Z","steps":["trace[1628126849] 'agreement among raft nodes before linearized reading' (duration: 239.916015ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:32:52.536416Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14710} {"level":"info","ts":"2026-07-28T10:32:52.707859Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14710,"took":"168.483748ms","hash":253318011,"current-db-size-bytes":59289600,"current-db-size":"59 MB","current-db-size-in-use-bytes":33939456,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-28T10:32:52.707907Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":253318011,"revision":14710,"compact-revision":12711} {"level":"info","ts":"2026-07-28T10:34:47.23191Z","caller":"traceutil/trace.go:171","msg":"trace[1819625592] transaction","detail":"{read_only:false; response_revision:18058; number_of_response:1; }","duration":"100.282559ms","start":"2026-07-28T10:34:47.131605Z","end":"2026-07-28T10:34:47.231888Z","steps":["trace[1819625592] 'process raft request' (duration: 100.154626ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:34:47.254213Z","caller":"traceutil/trace.go:171","msg":"trace[1058451634] transaction","detail":"{read_only:false; response_revision:18059; number_of_response:1; }","duration":"120.985762ms","start":"2026-07-28T10:34:47.1332Z","end":"2026-07-28T10:34:47.254186Z","steps":["trace[1058451634] 'process raft request' (duration: 120.803287ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:35:33.162701Z","caller":"traceutil/trace.go:171","msg":"trace[247501304] transaction","detail":"{read_only:false; response_revision:18359; number_of_response:1; }","duration":"185.038032ms","start":"2026-07-28T10:35:32.97764Z","end":"2026-07-28T10:35:33.162678Z","steps":["trace[247501304] 'process raft request' (duration: 98.259263ms)","trace[247501304] 'compare' (duration: 86.394938ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:35:33.169822Z","caller":"traceutil/trace.go:171","msg":"trace[700208600] transaction","detail":"{read_only:false; response_revision:18360; number_of_response:1; }","duration":"175.68788ms","start":"2026-07-28T10:35:32.994111Z","end":"2026-07-28T10:35:33.169799Z","steps":["trace[700208600] 'process raft request' (duration: 175.525145ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:35:33.169934Z","caller":"traceutil/trace.go:171","msg":"trace[1431283370] transaction","detail":"{read_only:false; response_revision:18361; number_of_response:1; }","duration":"163.728864ms","start":"2026-07-28T10:35:33.00618Z","end":"2026-07-28T10:35:33.169909Z","steps":["trace[1431283370] 'process raft request' (duration: 163.566839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:35:57.767555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.583212ms","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-07-28T10:35:57.767674Z","caller":"traceutil/trace.go:171","msg":"trace[2114889863] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18518; }","duration":"167.718354ms","start":"2026-07-28T10:35:57.599909Z","end":"2026-07-28T10:35:57.767628Z","steps":["trace[2114889863] 'range keys from in-memory index tree' (duration: 167.416227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:35:57.767955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.429211ms","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-07-28T10:35:57.768003Z","caller":"traceutil/trace.go:171","msg":"trace[649875954] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18518; }","duration":"138.503243ms","start":"2026-07-28T10:35:57.629486Z","end":"2026-07-28T10:35:57.767989Z","steps":["trace[649875954] 'range keys from in-memory index tree' (duration: 138.278517ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:35:57.97839Z","caller":"traceutil/trace.go:171","msg":"trace[1464022754] transaction","detail":"{read_only:false; response_revision:18519; number_of_response:1; }","duration":"204.88161ms","start":"2026-07-28T10:35:57.773246Z","end":"2026-07-28T10:35:57.978128Z","steps":["trace[1464022754] 'process raft request' (duration: 204.678694ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:35:58.025221Z","caller":"traceutil/trace.go:171","msg":"trace[850685426] transaction","detail":"{read_only:false; response_revision:18520; number_of_response:1; }","duration":"251.782219ms","start":"2026-07-28T10:35:57.773411Z","end":"2026-07-28T10:35:58.025193Z","steps":["trace[850685426] 'process raft request' (duration: 251.525982ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:35:58.025366Z","caller":"traceutil/trace.go:171","msg":"trace[741571152] transaction","detail":"{read_only:false; response_revision:18521; number_of_response:1; }","duration":"251.785719ms","start":"2026-07-28T10:35:57.773564Z","end":"2026-07-28T10:35:58.02535Z","steps":["trace[741571152] 'process raft request' (duration: 251.524321ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:35:58.025553Z","caller":"traceutil/trace.go:171","msg":"trace[1542946536] linearizableReadLoop","detail":"{readStateIndex:19157; appliedIndex:19152; }","duration":"217.626018ms","start":"2026-07-28T10:35:57.807918Z","end":"2026-07-28T10:35:58.025545Z","steps":["trace[1542946536] 'read index received' (duration: 169.891046ms)","trace[1542946536] 'applied index is now lower than readState.Index' (duration: 47.734042ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:35:58.025548Z","caller":"traceutil/trace.go:171","msg":"trace[115282562] transaction","detail":"{read_only:false; response_revision:18522; number_of_response:1; }","duration":"251.088518ms","start":"2026-07-28T10:35:57.774437Z","end":"2026-07-28T10:35:58.025526Z","steps":["trace[115282562] 'process raft request' (duration: 250.857682ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:35:58.025622Z","caller":"traceutil/trace.go:171","msg":"trace[1887224924] transaction","detail":"{read_only:false; response_revision:18524; number_of_response:1; }","duration":"100.64485ms","start":"2026-07-28T10:35:57.924968Z","end":"2026-07-28T10:35:58.025613Z","steps":["trace[1887224924] 'process raft request' (duration: 100.532177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:35:58.025718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.52148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-28T10:35:58.025759Z","caller":"traceutil/trace.go:171","msg":"trace[1816041820] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:18524; }","duration":"114.646542ms","start":"2026-07-28T10:35:57.911101Z","end":"2026-07-28T10:35:58.025748Z","steps":["trace[1816041820] 'agreement among raft nodes before linearized reading' (duration: 114.533509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:35:58.025997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.035119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-28T10:35:58.026088Z","caller":"traceutil/trace.go:171","msg":"trace[1389637152] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18524; }","duration":"218.181184ms","start":"2026-07-28T10:35:57.807894Z","end":"2026-07-28T10:35:58.026075Z","steps":["trace[1389637152] 'agreement among raft nodes before linearized reading' (duration: 217.816694ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:35:58.025629Z","caller":"traceutil/trace.go:171","msg":"trace[677042774] transaction","detail":"{read_only:false; response_revision:18523; number_of_response:1; }","duration":"249.304479ms","start":"2026-07-28T10:35:57.776262Z","end":"2026-07-28T10:35:58.025566Z","steps":["trace[677042774] 'process raft request' (duration: 249.202976ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:36:07.022834Z","caller":"traceutil/trace.go:171","msg":"trace[1428491880] transaction","detail":"{read_only:false; response_revision:18582; number_of_response:1; }","duration":"200.899638ms","start":"2026-07-28T10:36:06.821901Z","end":"2026-07-28T10:36:07.022801Z","steps":["trace[1428491880] 'process raft request' (duration: 200.687752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:36:07.702401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.246889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-28T10:36:07.702497Z","caller":"traceutil/trace.go:171","msg":"trace[1502655605] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:18583; }","duration":"423.386242ms","start":"2026-07-28T10:36:07.279089Z","end":"2026-07-28T10:36:07.702475Z","steps":["trace[1502655605] 'count revisions from in-memory index tree' (duration: 423.168105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:36:07.702547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:36:07.279073Z","time spent":"423.460174ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true "} {"level":"warn","ts":"2026-07-28T10:36:07.703468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.60671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-28T10:36:07.703701Z","caller":"traceutil/trace.go:171","msg":"trace[1055369355] transaction","detail":"{read_only:false; response_revision:18584; number_of_response:1; }","duration":"369.904928ms","start":"2026-07-28T10:36:07.333767Z","end":"2026-07-28T10:36:07.703672Z","steps":["trace[1055369355] 'process raft request' (duration: 216.97862ms)","trace[1055369355] 'compare' (duration: 151.668074ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:36:07.703824Z","caller":"traceutil/trace.go:171","msg":"trace[497680863] linearizableReadLoop","detail":"{readStateIndex:19221; appliedIndex:19219; }","duration":"244.633178ms","start":"2026-07-28T10:36:07.459156Z","end":"2026-07-28T10:36:07.703789Z","steps":["trace[497680863] 'read index received' (duration: 91.601276ms)","trace[497680863] 'applied index is now lower than readState.Index' (duration: 153.030722ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:36:07.7039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:36:07.333744Z","time spent":"370.048603ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T10:36:07.703921Z","caller":"traceutil/trace.go:171","msg":"trace[1283878856] transaction","detail":"{read_only:false; response_revision:18585; number_of_response:1; }","duration":"366.199744ms","start":"2026-07-28T10:36:07.337687Z","end":"2026-07-28T10:36:07.703887Z","steps":["trace[1283878856] 'process raft request' (duration: 365.912757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:36:07.704064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:36:07.337661Z","time spent":"366.316747ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:36:07.704624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.43233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-28T10:36:07.704753Z","caller":"traceutil/trace.go:171","msg":"trace[273994708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18585; }","duration":"245.554883ms","start":"2026-07-28T10:36:07.459118Z","end":"2026-07-28T10:36:07.704672Z","steps":["trace[273994708] 'agreement among raft nodes before linearized reading' (duration: 244.998098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:36:07.705313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.419945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-28T10:36:07.705371Z","caller":"traceutil/trace.go:171","msg":"trace[267680261] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:18585; }","duration":"131.515577ms","start":"2026-07-28T10:36:07.573839Z","end":"2026-07-28T10:36:07.705354Z","steps":["trace[267680261] 'agreement among raft nodes before linearized reading' (duration: 131.425204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:36:08.398206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.658844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-07-28T10:36:08.398264Z","caller":"traceutil/trace.go:171","msg":"trace[2123516556] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18585; }","duration":"156.743826ms","start":"2026-07-28T10:36:08.241507Z","end":"2026-07-28T10:36:08.398251Z","steps":["trace[2123516556] 'range keys from in-memory index tree' (duration: 156.544652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:36:08.398298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.923851ms","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-07-28T10:36:08.39834Z","caller":"traceutil/trace.go:171","msg":"trace[1517051109] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18585; }","duration":"326.973472ms","start":"2026-07-28T10:36:08.07135Z","end":"2026-07-28T10:36:08.398323Z","steps":["trace[1517051109] 'range keys from in-memory index tree' (duration: 326.849239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:36:08.39835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.986503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-28T10:36:08.398449Z","caller":"traceutil/trace.go:171","msg":"trace[977346311] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18585; }","duration":"311.117646ms","start":"2026-07-28T10:36:08.087308Z","end":"2026-07-28T10:36:08.398426Z","steps":["trace[977346311] 'range keys from in-memory index tree' (duration: 310.789747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:36:08.398188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.292091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-28T10:36:08.398372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.000855ms","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-07-28T10:36:08.398581Z","caller":"traceutil/trace.go:171","msg":"trace[1201434460] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18585; }","duration":"327.731503ms","start":"2026-07-28T10:36:08.070819Z","end":"2026-07-28T10:36:08.398551Z","steps":["trace[1201434460] 'range keys from in-memory index tree' (duration: 327.088476ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:36:08.398643Z","caller":"traceutil/trace.go:171","msg":"trace[1941254761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18585; }","duration":"369.309613ms","start":"2026-07-28T10:36:08.029314Z","end":"2026-07-28T10:36:08.398624Z","steps":["trace[1941254761] 'range keys from in-memory index tree' (duration: 368.83746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:36:08.398723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:36:08.029298Z","time spent":"369.412145ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-28T10:36:08.398785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:36:08.070792Z","time spent":"327.940679ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-28T10:36:08.398385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:36:08.071341Z","time spent":"327.035104ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-28T10:36:08.398497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:36:08.087296Z","time spent":"311.191988ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-28T10:36:08.39959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.113952ms","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-07-28T10:36:08.399667Z","caller":"traceutil/trace.go:171","msg":"trace[750994217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18585; }","duration":"313.242306ms","start":"2026-07-28T10:36:08.086411Z","end":"2026-07-28T10:36:08.399653Z","steps":["trace[750994217] 'range keys from in-memory index tree' (duration: 311.755844ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:36:08.399725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:36:08.086393Z","time spent":"313.297738ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-28T10:36:08.503538Z","caller":"traceutil/trace.go:171","msg":"trace[1780858759] transaction","detail":"{read_only:false; response_revision:18586; number_of_response:1; }","duration":"100.500225ms","start":"2026-07-28T10:36:08.403018Z","end":"2026-07-28T10:36:08.503518Z","steps":["trace[1780858759] 'process raft request' (duration: 100.368011ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:36:08.517331Z","caller":"traceutil/trace.go:171","msg":"trace[1623300189] transaction","detail":"{read_only:false; response_revision:18588; number_of_response:1; }","duration":"113.058688ms","start":"2026-07-28T10:36:08.404253Z","end":"2026-07-28T10:36:08.517312Z","steps":["trace[1623300189] 'process raft request' (duration: 113.008506ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:36:08.517348Z","caller":"traceutil/trace.go:171","msg":"trace[319361913] transaction","detail":"{read_only:false; response_revision:18587; number_of_response:1; }","duration":"114.258663ms","start":"2026-07-28T10:36:08.403062Z","end":"2026-07-28T10:36:08.517321Z","steps":["trace[319361913] 'process raft request' (duration: 114.091907ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:36:08.517505Z","caller":"traceutil/trace.go:171","msg":"trace[809822793] transaction","detail":"{read_only:false; response_revision:18589; number_of_response:1; }","duration":"112.161463ms","start":"2026-07-28T10:36:08.405337Z","end":"2026-07-28T10:36:08.517499Z","steps":["trace[809822793] 'process raft request' (duration: 111.949667ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:36:08.51764Z","caller":"traceutil/trace.go:171","msg":"trace[1069415863] linearizableReadLoop","detail":"{readStateIndex:19223; appliedIndex:19222; }","duration":"113.859001ms","start":"2026-07-28T10:36:08.403775Z","end":"2026-07-28T10:36:08.517634Z","steps":["trace[1069415863] 'read index received' (duration: 100.580277ms)","trace[1069415863] 'applied index is now lower than readState.Index' (duration: 13.278024ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:36:08.51775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.960423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18601"} {"level":"info","ts":"2026-07-28T10:36:08.517771Z","caller":"traceutil/trace.go:171","msg":"trace[188901789] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:18590; }","duration":"114.025286ms","start":"2026-07-28T10:36:08.40374Z","end":"2026-07-28T10:36:08.517766Z","steps":["trace[188901789] 'agreement among raft nodes before linearized reading' (duration: 113.918513ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:36:08.517755Z","caller":"traceutil/trace.go:171","msg":"trace[171007906] transaction","detail":"{read_only:false; response_revision:18590; number_of_response:1; }","duration":"112.317258ms","start":"2026-07-28T10:36:08.405428Z","end":"2026-07-28T10:36:08.517745Z","steps":["trace[171007906] 'process raft request' (duration: 112.046799ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:37:13.977029Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-28T10:37:13.982787Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-28T10:37:13.983073Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-28T10:37:52.559891Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17325} {"level":"info","ts":"2026-07-28T10:37:52.667494Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17325,"took":"102.597727ms","hash":1986763042,"current-db-size-bytes":59289600,"current-db-size":"59 MB","current-db-size-in-use-bytes":34160640,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-28T10:37:52.667589Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1986763042,"revision":17325,"compact-revision":14710} {"level":"info","ts":"2026-07-28T10:42:40.832394Z","caller":"traceutil/trace.go:171","msg":"trace[686275258] transaction","detail":"{read_only:false; response_revision:21516; number_of_response:1; }","duration":"360.968875ms","start":"2026-07-28T10:42:40.471395Z","end":"2026-07-28T10:42:40.832364Z","steps":["trace[686275258] 'process raft request' (duration: 360.754299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:42:40.832633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:42:40.471376Z","time spent":"361.129729ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:42:41.40412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.804382ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-28T10:42:41.40469Z","caller":"traceutil/trace.go:171","msg":"trace[1502710583] transaction","detail":"{read_only:false; response_revision:21517; number_of_response:1; }","duration":"780.860945ms","start":"2026-07-28T10:42:40.623809Z","end":"2026-07-28T10:42:41.40467Z","steps":["trace[1502710583] 'process raft request' (duration: 780.631439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:42:41.404853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:42:40.623782Z","time spent":"780.960338ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T10:42:41.40507Z","caller":"traceutil/trace.go:171","msg":"trace[1919465911] transaction","detail":"{read_only:false; response_revision:21519; number_of_response:1; }","duration":"104.810238ms","start":"2026-07-28T10:42:41.300237Z","end":"2026-07-28T10:42:41.405047Z","steps":["trace[1919465911] 'process raft request' (duration: 104.730786ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:42:41.405208Z","caller":"traceutil/trace.go:171","msg":"trace[884619931] linearizableReadLoop","detail":"{readStateIndex:22253; appliedIndex:22249; }","duration":"695.566667ms","start":"2026-07-28T10:42:40.709619Z","end":"2026-07-28T10:42:41.405186Z","steps":["trace[884619931] 'read index received' (duration: 122.62057ms)","trace[884619931] 'applied index is now lower than readState.Index' (duration: 572.943517ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:42:41.40522Z","caller":"traceutil/trace.go:171","msg":"trace[250301713] transaction","detail":"{read_only:false; response_revision:21518; number_of_response:1; }","duration":"760.846203ms","start":"2026-07-28T10:42:40.644333Z","end":"2026-07-28T10:42:41.405179Z","steps":["trace[250301713] 'process raft request' (duration: 760.279387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:42:41.40539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:42:40.644307Z","time spent":"760.986956ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:42:41.405413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.792753ms","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-07-28T10:42:41.405473Z","caller":"traceutil/trace.go:171","msg":"trace[670008110] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21519; }","duration":"695.892066ms","start":"2026-07-28T10:42:40.709563Z","end":"2026-07-28T10:42:41.405455Z","steps":["trace[670008110] 'agreement among raft nodes before linearized reading' (duration: 695.70856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:42:41.405519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:42:40.70954Z","time spent":"695.970847ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-28T10:42:41.474357Z","caller":"traceutil/trace.go:171","msg":"trace[1090193554] transaction","detail":"{read_only:false; response_revision:21520; number_of_response:1; }","duration":"169.527109ms","start":"2026-07-28T10:42:41.304798Z","end":"2026-07-28T10:42:41.474325Z","steps":["trace[1090193554] 'process raft request' (duration: 169.281742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:42:41.474451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.818099ms","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-07-28T10:42:41.47445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.035152ms","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-07-28T10:42:41.47447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.186622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-28T10:42:41.474516Z","caller":"traceutil/trace.go:171","msg":"trace[947414826] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21520; }","duration":"751.905321ms","start":"2026-07-28T10:42:40.722592Z","end":"2026-07-28T10:42:41.474497Z","steps":["trace[947414826] 'agreement among raft nodes before linearized reading' (duration: 751.746616ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:42:41.474519Z","caller":"traceutil/trace.go:171","msg":"trace[1311648702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21520; }","duration":"764.160406ms","start":"2026-07-28T10:42:40.710342Z","end":"2026-07-28T10:42:41.474502Z","steps":["trace[1311648702] 'agreement among raft nodes before linearized reading' (duration: 763.92993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:42:41.47455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:42:40.722578Z","time spent":"751.966493ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-28T10:42:41.474556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:42:40.710319Z","time spent":"764.228818ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-28T10:42:41.474554Z","caller":"traceutil/trace.go:171","msg":"trace[1693629900] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:21520; }","duration":"531.317366ms","start":"2026-07-28T10:42:40.943216Z","end":"2026-07-28T10:42:41.474533Z","steps":["trace[1693629900] 'agreement among raft nodes before linearized reading' (duration: 531.197733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:42:41.474598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:42:40.943189Z","time spent":"531.398679ms","remote":"127.0.0.1:39840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"warn","ts":"2026-07-28T10:42:41.474558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.811898ms","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-07-28T10:42:41.474722Z","caller":"traceutil/trace.go:171","msg":"trace[738092807] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21520; }","duration":"763.135557ms","start":"2026-07-28T10:42:40.711559Z","end":"2026-07-28T10:42:41.474695Z","steps":["trace[738092807] 'agreement among raft nodes before linearized reading' (duration: 762.691074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:42:41.474801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:42:40.711546Z","time spent":"763.2404ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-28T10:42:41.475026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.294573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-28T10:42:41.475083Z","caller":"traceutil/trace.go:171","msg":"trace[94797121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21520; }","duration":"752.384735ms","start":"2026-07-28T10:42:40.722683Z","end":"2026-07-28T10:42:41.475068Z","steps":["trace[94797121] 'agreement among raft nodes before linearized reading' (duration: 751.541831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:42:41.475116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:42:40.722663Z","time spent":"752.444726ms","remote":"127.0.0.1:49268","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-07-28T10:42:41.792347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.591848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-28T10:42:41.792477Z","caller":"traceutil/trace.go:171","msg":"trace[1489308163] linearizableReadLoop","detail":"{readStateIndex:22256; appliedIndex:22255; }","duration":"318.269143ms","start":"2026-07-28T10:42:41.474185Z","end":"2026-07-28T10:42:41.792454Z","steps":["trace[1489308163] 'read index received' (duration: 104.463419ms)","trace[1489308163] 'applied index is now lower than readState.Index' (duration: 213.803844ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:42:41.7925Z","caller":"traceutil/trace.go:171","msg":"trace[1714212507] transaction","detail":"{read_only:false; response_revision:21521; number_of_response:1; }","duration":"382.207711ms","start":"2026-07-28T10:42:41.41027Z","end":"2026-07-28T10:42:41.792478Z","steps":["trace[1714212507] 'process raft request' (duration: 168.361136ms)","trace[1714212507] 'compare' (duration: 213.29954ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:42:41.792607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:42:41.410251Z","time spent":"382.297415ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:42:41.792612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.339683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-28T10:42:41.792663Z","caller":"traceutil/trace.go:171","msg":"trace[625389433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21521; }","duration":"336.390003ms","start":"2026-07-28T10:42:41.45626Z","end":"2026-07-28T10:42:41.79265Z","steps":["trace[625389433] 'agreement among raft nodes before linearized reading' (duration: 336.28276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:42:41.792701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:42:41.456215Z","time spent":"336.476445ms","remote":"127.0.0.1:49018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-28T10:42:41.800296Z","caller":"traceutil/trace.go:171","msg":"trace[1575272972] transaction","detail":"{read_only:false; response_revision:21523; number_of_response:1; }","duration":"317.966016ms","start":"2026-07-28T10:42:41.482316Z","end":"2026-07-28T10:42:41.800282Z","steps":["trace[1575272972] 'process raft request' (duration: 317.716779ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:42:41.800303Z","caller":"traceutil/trace.go:171","msg":"trace[1155220233] transaction","detail":"{read_only:false; response_revision:21525; number_of_response:1; }","duration":"317.150792ms","start":"2026-07-28T10:42:41.483115Z","end":"2026-07-28T10:42:41.800266Z","steps":["trace[1155220233] 'process raft request' (duration: 316.990937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:42:41.800372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:42:41.482286Z","time spent":"318.051387ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T10:42:41.800695Z","caller":"traceutil/trace.go:171","msg":"trace[1672119324] transaction","detail":"{read_only:false; response_revision:21522; number_of_response:1; }","duration":"318.47707ms","start":"2026-07-28T10:42:41.482208Z","end":"2026-07-28T10:42:41.800685Z","steps":["trace[1672119324] 'process raft request' (duration: 317.731799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:42:41.800756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:42:41.48219Z","time spent":"318.537801ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:42:41.800909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:42:41.483104Z","time spent":"317.261745ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T10:42:41.80106Z","caller":"traceutil/trace.go:171","msg":"trace[1439571283] transaction","detail":"{read_only:false; response_revision:21524; number_of_response:1; }","duration":"318.722325ms","start":"2026-07-28T10:42:41.482331Z","end":"2026-07-28T10:42:41.801053Z","steps":["trace[1439571283] 'process raft request' (duration: 317.739438ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:42:41.801109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:42:41.482311Z","time spent":"318.774218ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:42:42.067978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.396845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-28T10:42:42.06806Z","caller":"traceutil/trace.go:171","msg":"trace[1865451834] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21525; }","duration":"142.524659ms","start":"2026-07-28T10:42:41.925517Z","end":"2026-07-28T10:42:42.068041Z","steps":["trace[1865451834] 'range keys from in-memory index tree' (duration: 142.324303ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:42:52.566215Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19628} {"level":"info","ts":"2026-07-28T10:42:52.707428Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19628,"took":"137.534189ms","hash":4172642209,"current-db-size-bytes":59289600,"current-db-size":"59 MB","current-db-size-in-use-bytes":28971008,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-28T10:42:52.707511Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4172642209,"revision":19628,"compact-revision":17325} {"level":"info","ts":"2026-07-28T10:43:11.271893Z","caller":"traceutil/trace.go:171","msg":"trace[2084229175] transaction","detail":"{read_only:false; response_revision:21688; number_of_response:1; }","duration":"178.251604ms","start":"2026-07-28T10:43:11.093617Z","end":"2026-07-28T10:43:11.271868Z","steps":["trace[2084229175] 'process raft request' (duration: 96.096293ms)","trace[2084229175] 'compare' (duration: 82.019828ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:43:11.329701Z","caller":"traceutil/trace.go:171","msg":"trace[1587907801] transaction","detail":"{read_only:false; response_revision:21689; number_of_response:1; }","duration":"235.623498ms","start":"2026-07-28T10:43:11.094046Z","end":"2026-07-28T10:43:11.329669Z","steps":["trace[1587907801] 'process raft request' (duration: 235.435083ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:43:11.329762Z","caller":"traceutil/trace.go:171","msg":"trace[838118460] linearizableReadLoop","detail":"{readStateIndex:22437; appliedIndex:22430; }","duration":"227.614943ms","start":"2026-07-28T10:43:11.102132Z","end":"2026-07-28T10:43:11.329747Z","steps":["trace[838118460] 'read index received' (duration: 87.656276ms)","trace[838118460] 'applied index is now lower than readState.Index' (duration: 139.956077ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:43:11.329767Z","caller":"traceutil/trace.go:171","msg":"trace[616773043] transaction","detail":"{read_only:false; response_revision:21691; number_of_response:1; }","duration":"235.592007ms","start":"2026-07-28T10:43:11.094144Z","end":"2026-07-28T10:43:11.329736Z","steps":["trace[616773043] 'process raft request' (duration: 235.468593ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:43:11.329847Z","caller":"traceutil/trace.go:171","msg":"trace[1570083047] transaction","detail":"{read_only:false; response_revision:21694; number_of_response:1; }","duration":"235.260687ms","start":"2026-07-28T10:43:11.094553Z","end":"2026-07-28T10:43:11.329814Z","steps":["trace[1570083047] 'process raft request' (duration: 235.124034ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:43:11.329976Z","caller":"traceutil/trace.go:171","msg":"trace[777607453] transaction","detail":"{read_only:false; response_revision:21692; number_of_response:1; }","duration":"235.798653ms","start":"2026-07-28T10:43:11.09417Z","end":"2026-07-28T10:43:11.329969Z","steps":["trace[777607453] 'process raft request' (duration: 235.465774ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:43:11.329979Z","caller":"traceutil/trace.go:171","msg":"trace[67496993] transaction","detail":"{read_only:false; response_revision:21695; number_of_response:1; }","duration":"227.582322ms","start":"2026-07-28T10:43:11.102374Z","end":"2026-07-28T10:43:11.329956Z","steps":["trace[67496993] 'process raft request' (duration: 227.327485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:43:11.330042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.894241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1344"} {"level":"info","ts":"2026-07-28T10:43:11.330078Z","caller":"traceutil/trace.go:171","msg":"trace[798624729] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:21695; }","duration":"227.966652ms","start":"2026-07-28T10:43:11.102101Z","end":"2026-07-28T10:43:11.330068Z","steps":["trace[798624729] 'agreement among raft nodes before linearized reading' (duration: 227.854299ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:43:11.330033Z","caller":"traceutil/trace.go:171","msg":"trace[2103725575] transaction","detail":"{read_only:false; response_revision:21690; number_of_response:1; }","duration":"235.676491ms","start":"2026-07-28T10:43:11.094111Z","end":"2026-07-28T10:43:11.329787Z","steps":["trace[2103725575] 'process raft request' (duration: 235.477745ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:43:11.330045Z","caller":"traceutil/trace.go:171","msg":"trace[1763665551] transaction","detail":"{read_only:false; response_revision:21693; number_of_response:1; }","duration":"235.620409ms","start":"2026-07-28T10:43:11.094382Z","end":"2026-07-28T10:43:11.330003Z","steps":["trace[1763665551] 'process raft request' (duration: 235.276619ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:43:11.369378Z","caller":"traceutil/trace.go:171","msg":"trace[2067186325] transaction","detail":"{read_only:false; response_revision:21696; number_of_response:1; }","duration":"114.583093ms","start":"2026-07-28T10:43:11.254769Z","end":"2026-07-28T10:43:11.369352Z","steps":["trace[2067186325] 'process raft request' (duration: 114.386138ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:43:11.475057Z","caller":"traceutil/trace.go:171","msg":"trace[2038713690] transaction","detail":"{read_only:false; response_revision:21697; number_of_response:1; }","duration":"137.788066ms","start":"2026-07-28T10:43:11.337233Z","end":"2026-07-28T10:43:11.475021Z","steps":["trace[2038713690] 'process raft request' (duration: 94.840598ms)","trace[2038713690] 'compare' (duration: 42.66347ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:43:11.475152Z","caller":"traceutil/trace.go:171","msg":"trace[869477568] transaction","detail":"{read_only:false; response_revision:21699; number_of_response:1; }","duration":"132.869437ms","start":"2026-07-28T10:43:11.34226Z","end":"2026-07-28T10:43:11.47513Z","steps":["trace[869477568] 'process raft request' (duration: 132.754153ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:43:11.475136Z","caller":"traceutil/trace.go:171","msg":"trace[1600105722] linearizableReadLoop","detail":"{readStateIndex:22442; appliedIndex:22439; }","duration":"105.803806ms","start":"2026-07-28T10:43:11.369305Z","end":"2026-07-28T10:43:11.475109Z","steps":["trace[1600105722] 'read index received' (duration: 62.774876ms)","trace[1600105722] 'applied index is now lower than readState.Index' (duration: 43.02742ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:43:11.475121Z","caller":"traceutil/trace.go:171","msg":"trace[1214322125] transaction","detail":"{read_only:false; response_revision:21698; number_of_response:1; }","duration":"135.903303ms","start":"2026-07-28T10:43:11.339192Z","end":"2026-07-28T10:43:11.475095Z","steps":["trace[1214322125] 'process raft request' (duration: 135.754639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:43:11.475379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.619099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1344"} {"level":"info","ts":"2026-07-28T10:43:11.475408Z","caller":"traceutil/trace.go:171","msg":"trace[1104155364] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:21699; }","duration":"138.673412ms","start":"2026-07-28T10:43:11.336724Z","end":"2026-07-28T10:43:11.475398Z","steps":["trace[1104155364] 'agreement among raft nodes before linearized reading' (duration: 138.503946ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:43:11.641666Z","caller":"traceutil/trace.go:171","msg":"trace[311187681] transaction","detail":"{read_only:false; response_revision:21703; number_of_response:1; }","duration":"102.160603ms","start":"2026-07-28T10:43:11.539476Z","end":"2026-07-28T10:43:11.641637Z","steps":["trace[311187681] 'process raft request' (duration: 58.895987ms)","trace[311187681] 'compare' (duration: 43.079002ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:43:11.642144Z","caller":"traceutil/trace.go:171","msg":"trace[1761786303] transaction","detail":"{read_only:false; number_of_response:1; response_revision:21703; }","duration":"101.147175ms","start":"2026-07-28T10:43:11.540982Z","end":"2026-07-28T10:43:11.642129Z","steps":["trace[1761786303] 'process raft request' (duration: 100.62055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:43:11.642563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.403621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-07-28T10:43:11.642649Z","caller":"traceutil/trace.go:171","msg":"trace[821500738] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:21703; }","duration":"102.498804ms","start":"2026-07-28T10:43:11.540119Z","end":"2026-07-28T10:43:11.642618Z","steps":["trace[821500738] 'agreement among raft nodes before linearized reading' (duration: 102.309398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:45:44.613642Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227816331139081,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-28T10:45:44.754284Z","caller":"traceutil/trace.go:171","msg":"trace[281623193] transaction","detail":"{read_only:false; response_revision:22932; number_of_response:1; }","duration":"650.775295ms","start":"2026-07-28T10:45:44.103486Z","end":"2026-07-28T10:45:44.754262Z","steps":["trace[281623193] 'process raft request' (duration: 650.631852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:45:44.754439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:45:44.103461Z","time spent":"650.896589ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T10:45:44.816606Z","caller":"traceutil/trace.go:171","msg":"trace[474668437] transaction","detail":"{read_only:false; response_revision:22933; number_of_response:1; }","duration":"641.314049ms","start":"2026-07-28T10:45:44.175259Z","end":"2026-07-28T10:45:44.816573Z","steps":["trace[474668437] 'process raft request' (duration: 625.688029ms)","trace[474668437] 'compare' (duration: 15.435935ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:45:44.816711Z","caller":"traceutil/trace.go:171","msg":"trace[628810496] transaction","detail":"{read_only:false; response_revision:22935; number_of_response:1; }","duration":"453.804307ms","start":"2026-07-28T10:45:44.362888Z","end":"2026-07-28T10:45:44.816692Z","steps":["trace[628810496] 'process raft request' (duration: 453.672563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:45:44.816858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:45:44.175239Z","time spent":"641.461444ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-28T10:45:44.816924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:45:44.362857Z","time spent":"453.98275ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T10:45:44.816626Z","caller":"traceutil/trace.go:171","msg":"trace[1076097161] linearizableReadLoop","detail":"{readStateIndex:23720; appliedIndex:23717; }","duration":"703.303766ms","start":"2026-07-28T10:45:44.113298Z","end":"2026-07-28T10:45:44.816602Z","steps":["trace[1076097161] 'read index received' (duration: 640.961579ms)","trace[1076097161] 'applied index is now lower than readState.Index' (duration: 62.341117ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:45:44.817229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.340232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-07-28T10:45:44.817305Z","caller":"traceutil/trace.go:171","msg":"trace[1454250066] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:22935; }","duration":"586.447373ms","start":"2026-07-28T10:45:44.230837Z","end":"2026-07-28T10:45:44.817285Z","steps":["trace[1454250066] 'agreement among raft nodes before linearized reading' (duration: 586.253928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:45:44.817374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:45:44.230817Z","time spent":"586.541116ms","remote":"127.0.0.1:49180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":695,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-07-28T10:45:44.817405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.110879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-07-28T10:45:44.817471Z","caller":"traceutil/trace.go:171","msg":"trace[527430732] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:22935; }","duration":"583.228253ms","start":"2026-07-28T10:45:44.234227Z","end":"2026-07-28T10:45:44.817455Z","steps":["trace[527430732] 'agreement among raft nodes before linearized reading' (duration: 583.054298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:45:44.817508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:45:44.234205Z","time spent":"583.297465ms","remote":"127.0.0.1:49180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":695,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-07-28T10:45:44.817654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.279954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-07-28T10:45:44.817695Z","caller":"traceutil/trace.go:171","msg":"trace[1429728223] transaction","detail":"{read_only:false; response_revision:22934; number_of_response:1; }","duration":"491.681994ms","start":"2026-07-28T10:45:44.324986Z","end":"2026-07-28T10:45:44.816668Z","steps":["trace[1429728223] 'process raft request' (duration: 491.528739ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:45:44.817812Z","caller":"traceutil/trace.go:171","msg":"trace[1740082087] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:22935; }","duration":"704.517011ms","start":"2026-07-28T10:45:44.113248Z","end":"2026-07-28T10:45:44.817765Z","steps":["trace[1740082087] 'agreement among raft nodes before linearized reading' (duration: 703.654847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-28T10:45:44.817895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:45:44.113226Z","time spent":"704.625804ms","remote":"127.0.0.1:49180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":695,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-07-28T10:45:44.817958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-28T10:45:44.324961Z","time spent":"492.873917ms","remote":"127.0.0.1:49268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-28T10:47:02.080922Z","caller":"traceutil/trace.go:171","msg":"trace[1889976486] transaction","detail":"{read_only:false; response_revision:24128; number_of_response:1; }","duration":"247.909466ms","start":"2026-07-28T10:47:01.832972Z","end":"2026-07-28T10:47:02.080882Z","steps":["trace[1889976486] 'process raft request' (duration: 247.622537ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:47:52.572402Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21579} {"level":"info","ts":"2026-07-28T10:47:52.678388Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21579,"took":"101.671205ms","hash":1396300960,"current-db-size-bytes":59289600,"current-db-size":"59 MB","current-db-size-in-use-bytes":42438656,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-07-28T10:47:52.678468Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1396300960,"revision":21579,"compact-revision":19628} {"level":"info","ts":"2026-07-28T10:50:56.133483Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069b0.wal"} {"level":"info","ts":"2026-07-28T10:52:09.720706Z","caller":"traceutil/trace.go:171","msg":"trace[1146536021] transaction","detail":"{read_only:false; response_revision:26728; number_of_response:1; }","duration":"103.986411ms","start":"2026-07-28T10:52:09.616678Z","end":"2026-07-28T10:52:09.720664Z","steps":["trace[1146536021] 'process raft request' (duration: 103.742954ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:52:37.385933Z","caller":"traceutil/trace.go:171","msg":"trace[961148048] transaction","detail":"{read_only:false; response_revision:26926; number_of_response:1; }","duration":"118.388076ms","start":"2026-07-28T10:52:37.267511Z","end":"2026-07-28T10:52:37.385899Z","steps":["trace[961148048] 'process raft request' (duration: 117.987305ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:52:38.662872Z","caller":"traceutil/trace.go:171","msg":"trace[2090984051] linearizableReadLoop","detail":"{readStateIndex:27823; appliedIndex:27822; }","duration":"101.618023ms","start":"2026-07-28T10:52:38.561227Z","end":"2026-07-28T10:52:38.662845Z","steps":["trace[2090984051] 'read index received' (duration: 41.724116ms)","trace[2090984051] 'applied index is now lower than readState.Index' (duration: 59.892597ms)"],"step_count":2} {"level":"info","ts":"2026-07-28T10:52:38.662987Z","caller":"traceutil/trace.go:171","msg":"trace[1595045778] transaction","detail":"{read_only:false; response_revision:26934; number_of_response:1; }","duration":"161.636584ms","start":"2026-07-28T10:52:38.501309Z","end":"2026-07-28T10:52:38.662945Z","steps":["trace[1595045778] 'process raft request' (duration: 101.798508ms)","trace[1595045778] 'compare' (duration: 59.61674ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:52:38.663026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.785648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-28T10:52:38.663073Z","caller":"traceutil/trace.go:171","msg":"trace[1790373500] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:26934; }","duration":"101.891461ms","start":"2026-07-28T10:52:38.561167Z","end":"2026-07-28T10:52:38.663059Z","steps":["trace[1790373500] 'agreement among raft nodes before linearized reading' (duration: 101.784788ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:52:38.88756Z","caller":"traceutil/trace.go:171","msg":"trace[1318603252] transaction","detail":"{read_only:false; response_revision:26937; number_of_response:1; }","duration":"107.754855ms","start":"2026-07-28T10:52:38.779752Z","end":"2026-07-28T10:52:38.887507Z","steps":["trace[1318603252] 'process raft request' (duration: 107.450847ms)"],"step_count":1} {"level":"info","ts":"2026-07-28T10:52:52.578559Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24605} {"level":"info","ts":"2026-07-28T10:52:52.726726Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24605,"took":"144.863911ms","hash":2459188671,"current-db-size-bytes":59289600,"current-db-size":"59 MB","current-db-size-in-use-bytes":31350784,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-28T10:52:52.726783Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2459188671,"revision":24605,"compact-revision":21579} {"level":"info","ts":"2026-07-28T10:56:03.739081Z","caller":"traceutil/trace.go:171","msg":"trace[1399320406] transaction","detail":"{read_only:false; response_revision:28468; number_of_response:1; }","duration":"117.544141ms","start":"2026-07-28T10:56:03.621505Z","end":"2026-07-28T10:56:03.739049Z","steps":["trace[1399320406] 'process raft request' (duration: 76.642312ms)","trace[1399320406] 'compare' (duration: 40.702644ms)"],"step_count":2} {"level":"warn","ts":"2026-07-28T10:56:14.107489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.394184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/heat-db-init\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-07-28T10:56:14.107574Z","caller":"traceutil/trace.go:171","msg":"trace[7341274] range","detail":"{range_begin:/registry/serviceaccounts/openstack/heat-db-init; range_end:; response_count:1; response_revision:28543; }","duration":"113.526007ms","start":"2026-07-28T10:56:13.994026Z","end":"2026-07-28T10:56:14.107552Z","steps":["trace[7341274] 'range keys from in-memory index tree' (duration: 113.201989ms)"],"step_count":1}