{"level":"warn","ts":"2026-07-27T20:08:28.233766Z","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-27T20:08:28.233868Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.236:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.236:2380","--initial-cluster=instance=https://199.19.213.236:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.236:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.236: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-27T20:08:28.233941Z","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-27T20:08:28.233957Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.236:2380"]} {"level":"info","ts":"2026-07-27T20:08:28.233976Z","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-27T20:08:28.234695Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.236:2379"]} {"level":"info","ts":"2026-07-27T20:08:28.234782Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.236:2380"],"listen-peer-urls":["https://199.19.213.236:2380"],"advertise-client-urls":["https://199.19.213.236:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.236:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.236: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-27T20:08:28.237339Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.309728ms"} {"level":"info","ts":"2026-07-27T20:08:28.245859Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f20dfa43a2e4607","cluster-id":"85a3671ea9bffea4"} {"level":"info","ts":"2026-07-27T20:08:28.245987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 switched to configuration voters=()"} {"level":"info","ts":"2026-07-27T20:08:28.246026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became follower at term 0"} {"level":"info","ts":"2026-07-27T20:08:28.246067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f20dfa43a2e4607 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-27T20:08:28.246088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became follower at term 1"} {"level":"info","ts":"2026-07-27T20:08:28.24615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 switched to configuration voters=(6854724529301636615)"} {"level":"warn","ts":"2026-07-27T20:08:28.251256Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-27T20:08:28.252717Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-27T20:08:28.253936Z","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-27T20:08:28.256241Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f20dfa43a2e4607","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-27T20:08:28.256973Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f20dfa43a2e4607","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-27T20:08:28.257073Z","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-27T20:08:28.257168Z","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-27T20:08:28.257186Z","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-27T20:08:28.261035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 switched to configuration voters=(6854724529301636615)"} {"level":"info","ts":"2026-07-27T20:08:28.261133Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"85a3671ea9bffea4","local-member-id":"5f20dfa43a2e4607","added-peer-id":"5f20dfa43a2e4607","added-peer-peer-urls":["https://199.19.213.236:2380"]} {"level":"info","ts":"2026-07-27T20:08:28.261708Z","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-27T20:08:28.261977Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f20dfa43a2e4607","initial-advertise-peer-urls":["https://199.19.213.236:2380"],"listen-peer-urls":["https://199.19.213.236:2380"],"advertise-client-urls":["https://199.19.213.236:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.236:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-27T20:08:28.262006Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-27T20:08:28.262177Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.236:2380"} {"level":"info","ts":"2026-07-27T20:08:28.262197Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.236:2380"} {"level":"info","ts":"2026-07-27T20:08:29.14671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 is starting a new election at term 1"} {"level":"info","ts":"2026-07-27T20:08:29.146793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-27T20:08:29.146833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 received MsgPreVoteResp from 5f20dfa43a2e4607 at term 1"} {"level":"info","ts":"2026-07-27T20:08:29.146857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became candidate at term 2"} {"level":"info","ts":"2026-07-27T20:08:29.14687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 received MsgVoteResp from 5f20dfa43a2e4607 at term 2"} {"level":"info","ts":"2026-07-27T20:08:29.146889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f20dfa43a2e4607 became leader at term 2"} {"level":"info","ts":"2026-07-27T20:08:29.146905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f20dfa43a2e4607 elected leader 5f20dfa43a2e4607 at term 2"} {"level":"info","ts":"2026-07-27T20:08:29.148183Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f20dfa43a2e4607","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.236:2379]}","request-path":"/0/members/5f20dfa43a2e4607/attributes","cluster-id":"85a3671ea9bffea4","publish-timeout":"7s"} {"level":"info","ts":"2026-07-27T20:08:29.148233Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-27T20:08:29.148321Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-27T20:08:29.148541Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-27T20:08:29.148578Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-27T20:08:29.148699Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:08:29.149832Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"85a3671ea9bffea4","local-member-id":"5f20dfa43a2e4607","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:08:29.149932Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:08:29.149975Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:08:29.151952Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-27T20:08:29.152077Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.236:2379"} {"level":"info","ts":"2026-07-27T20:08:52.964387Z","caller":"traceutil/trace.go:171","msg":"trace[2082985375] linearizableReadLoop","detail":"{readStateIndex:452; appliedIndex:451; }","duration":"196.333231ms","start":"2026-07-27T20:08:52.768013Z","end":"2026-07-27T20:08:52.964346Z","steps":["trace[2082985375] 'read index received' (duration: 191.130536ms)","trace[2082985375] 'applied index is now lower than readState.Index' (duration: 5.201345ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:08:52.964528Z","caller":"traceutil/trace.go:171","msg":"trace[1868403093] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"205.808903ms","start":"2026-07-27T20:08:52.75867Z","end":"2026-07-27T20:08:52.964479Z","steps":["trace[1868403093] 'process raft request' (duration: 200.568597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:08:52.96477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.869055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-27T20:08:52.96472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.658048ms","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-27T20:08:52.964839Z","caller":"traceutil/trace.go:171","msg":"trace[1812023492] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:435; }","duration":"147.011048ms","start":"2026-07-27T20:08:52.817809Z","end":"2026-07-27T20:08:52.96482Z","steps":["trace[1812023492] 'agreement among raft nodes before linearized reading' (duration: 146.789693ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:08:52.964878Z","caller":"traceutil/trace.go:171","msg":"trace[1326045331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:435; }","duration":"196.876702ms","start":"2026-07-27T20:08:52.767973Z","end":"2026-07-27T20:08:52.96485Z","steps":["trace[1326045331] 'agreement among raft nodes before linearized reading' (duration: 196.542096ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:09:12.766714Z","caller":"traceutil/trace.go:171","msg":"trace[626099405] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"209.06169ms","start":"2026-07-27T20:09:12.557615Z","end":"2026-07-27T20:09:12.766677Z","steps":["trace[626099405] 'read index received' (duration: 208.159171ms)","trace[626099405] 'applied index is now lower than readState.Index' (duration: 901.389µs)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:09:12.766863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.231273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:09:12.766911Z","caller":"traceutil/trace.go:171","msg":"trace[322509848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"209.342765ms","start":"2026-07-27T20:09:12.557555Z","end":"2026-07-27T20:09:12.766898Z","steps":["trace[322509848] 'agreement among raft nodes before linearized reading' (duration: 209.245203ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:09:12.766895Z","caller":"traceutil/trace.go:171","msg":"trace[1202452746] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"367.092551ms","start":"2026-07-27T20:09:12.399752Z","end":"2026-07-27T20:09:12.766845Z","steps":["trace[1202452746] 'process raft request' (duration: 366.293175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:09:12.76792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:09:12.399706Z","time spent":"367.571531ms","remote":"127.0.0.1:38414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036245,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:09:13.328658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.203924ms","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-27T20:09:13.328743Z","caller":"traceutil/trace.go:171","msg":"trace[487459294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:634; }","duration":"248.377368ms","start":"2026-07-27T20:09:13.080347Z","end":"2026-07-27T20:09:13.328724Z","steps":["trace[487459294] 'range keys from in-memory index tree' (duration: 248.113033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:09:13.328807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.225135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-27T20:09:13.328912Z","caller":"traceutil/trace.go:171","msg":"trace[199305519] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"247.378497ms","start":"2026-07-27T20:09:13.081509Z","end":"2026-07-27T20:09:13.328887Z","steps":["trace[199305519] 'range keys from in-memory index tree' (duration: 246.98426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:09:13.32904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.014512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4wmz7\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-27T20:09:13.329143Z","caller":"traceutil/trace.go:171","msg":"trace[1431333696] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4wmz7; range_end:; response_count:1; response_revision:634; }","duration":"314.162165ms","start":"2026-07-27T20:09:13.014961Z","end":"2026-07-27T20:09:13.329124Z","steps":["trace[1431333696] 'range keys from in-memory index tree' (duration: 313.693856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:09:13.329181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:09:13.014934Z","time spent":"314.234997ms","remote":"127.0.0.1:38524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-4wmz7\" "} {"level":"warn","ts":"2026-07-27T20:09:28.781114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.746807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-27T20:09:28.781237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.572095ms","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-27T20:09:28.781336Z","caller":"traceutil/trace.go:171","msg":"trace[1879034263] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"162.747758ms","start":"2026-07-27T20:09:28.618571Z","end":"2026-07-27T20:09:28.781318Z","steps":["trace[1879034263] 'range keys from in-memory index tree' (duration: 162.325848ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:09:28.781331Z","caller":"traceutil/trace.go:171","msg":"trace[973364394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"224.020873ms","start":"2026-07-27T20:09:28.557286Z","end":"2026-07-27T20:09:28.781307Z","steps":["trace[973364394] 'range keys from in-memory index tree' (duration: 223.680226ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:10:41.511133Z","caller":"traceutil/trace.go:171","msg":"trace[563521261] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1925; }","duration":"164.467279ms","start":"2026-07-27T20:10:41.346629Z","end":"2026-07-27T20:10:41.511096Z","steps":["trace[563521261] 'read index received' (duration: 164.271924ms)","trace[563521261] 'applied index is now lower than readState.Index' (duration: 193.945µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:10:41.511183Z","caller":"traceutil/trace.go:171","msg":"trace[1551716855] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"204.378319ms","start":"2026-07-27T20:10:41.306762Z","end":"2026-07-27T20:10:41.511141Z","steps":["trace[1551716855] 'process raft request' (duration: 204.107122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:10:41.51146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.806155ms","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-27T20:10:41.511512Z","caller":"traceutil/trace.go:171","msg":"trace[46598178] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1851; }","duration":"164.934897ms","start":"2026-07-27T20:10:41.346564Z","end":"2026-07-27T20:10:41.511499Z","steps":["trace[46598178] 'agreement among raft nodes before linearized reading' (duration: 164.706973ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:11:26.066293Z","caller":"traceutil/trace.go:171","msg":"trace[565483245] linearizableReadLoop","detail":"{readStateIndex:2224; appliedIndex:2222; }","duration":"221.554149ms","start":"2026-07-27T20:11:25.844705Z","end":"2026-07-27T20:11:26.06626Z","steps":["trace[565483245] 'read index received' (duration: 14.237221ms)","trace[565483245] 'applied index is now lower than readState.Index' (duration: 207.315518ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:11:26.066314Z","caller":"traceutil/trace.go:171","msg":"trace[1812230702] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"224.261464ms","start":"2026-07-27T20:11:25.842025Z","end":"2026-07-27T20:11:26.066286Z","steps":["trace[1812230702] 'process raft request' (duration: 134.526195ms)","trace[1812230702] 'compare' (duration: 89.473803ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:11:26.06679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.080116ms","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":"warn","ts":"2026-07-27T20:11:26.06697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.995184ms","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-27T20:11:26.066854Z","caller":"traceutil/trace.go:171","msg":"trace[658072895] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2140; }","duration":"134.165658ms","start":"2026-07-27T20:11:25.932672Z","end":"2026-07-27T20:11:26.066837Z","steps":["trace[658072895] 'agreement among raft nodes before linearized reading' (duration: 134.021506ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:11:26.067107Z","caller":"traceutil/trace.go:171","msg":"trace[1828308772] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2140; }","duration":"193.196748ms","start":"2026-07-27T20:11:25.873878Z","end":"2026-07-27T20:11:26.067074Z","steps":["trace[1828308772] 'agreement among raft nodes before linearized reading' (duration: 192.801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:26.067264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.881576ms","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-27T20:11:26.067379Z","caller":"traceutil/trace.go:171","msg":"trace[335477492] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2140; }","duration":"222.686771ms","start":"2026-07-27T20:11:25.844673Z","end":"2026-07-27T20:11:26.06736Z","steps":["trace[335477492] 'agreement among raft nodes before linearized reading' (duration: 221.708723ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:11:32.030322Z","caller":"traceutil/trace.go:171","msg":"trace[1319209872] linearizableReadLoop","detail":"{readStateIndex:2254; appliedIndex:2253; }","duration":"132.27439ms","start":"2026-07-27T20:11:31.898018Z","end":"2026-07-27T20:11:32.030293Z","steps":["trace[1319209872] 'read index received' (duration: 131.959534ms)","trace[1319209872] 'applied index is now lower than readState.Index' (duration: 313.646µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:11:32.030454Z","caller":"traceutil/trace.go:171","msg":"trace[1588502258] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"148.617105ms","start":"2026-07-27T20:11:31.881808Z","end":"2026-07-27T20:11:32.030425Z","steps":["trace[1588502258] 'process raft request' (duration: 148.277328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:32.030816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.736248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:11:32.030892Z","caller":"traceutil/trace.go:171","msg":"trace[1168331915] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:2169; }","duration":"132.875802ms","start":"2026-07-27T20:11:31.897996Z","end":"2026-07-27T20:11:32.030872Z","steps":["trace[1168331915] 'agreement among raft nodes before linearized reading' (duration: 132.446853ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:11:32.245563Z","caller":"traceutil/trace.go:171","msg":"trace[1120375041] linearizableReadLoop","detail":"{readStateIndex:2255; appliedIndex:2254; }","duration":"131.845822ms","start":"2026-07-27T20:11:32.113687Z","end":"2026-07-27T20:11:32.245533Z","steps":["trace[1120375041] 'read index received' (duration: 58.07685ms)","trace[1120375041] 'applied index is now lower than readState.Index' (duration: 73.767532ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:11:32.245662Z","caller":"traceutil/trace.go:171","msg":"trace[874948869] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"158.748175ms","start":"2026-07-27T20:11:32.086857Z","end":"2026-07-27T20:11:32.245605Z","steps":["trace[874948869] 'process raft request' (duration: 84.910622ms)","trace[874948869] 'compare' (duration: 73.633259ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:11:32.245918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.206969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-27T20:11:32.246039Z","caller":"traceutil/trace.go:171","msg":"trace[550505793] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2170; }","duration":"132.393123ms","start":"2026-07-27T20:11:32.113622Z","end":"2026-07-27T20:11:32.246015Z","steps":["trace[550505793] 'agreement among raft nodes before linearized reading' (duration: 132.054356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:32.246075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.018387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-27T20:11:32.246315Z","caller":"traceutil/trace.go:171","msg":"trace[2082624952] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2170; }","duration":"126.296302ms","start":"2026-07-27T20:11:32.119989Z","end":"2026-07-27T20:11:32.246285Z","steps":["trace[2082624952] 'agreement among raft nodes before linearized reading' (duration: 125.871094ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:32.24633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.440234ms","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-27T20:11:32.24644Z","caller":"traceutil/trace.go:171","msg":"trace[902149334] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2170; }","duration":"127.604308ms","start":"2026-07-27T20:11:32.118821Z","end":"2026-07-27T20:11:32.246425Z","steps":["trace[902149334] 'agreement among raft nodes before linearized reading' (duration: 126.901814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:32.529351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.139475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:11:32.529676Z","caller":"traceutil/trace.go:171","msg":"trace[285349843] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"278.086869ms","start":"2026-07-27T20:11:32.251531Z","end":"2026-07-27T20:11:32.529618Z","steps":["trace[285349843] 'process raft request' (duration: 131.180099ms)","trace[285349843] 'compare' (duration: 145.859079ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:11:32.529846Z","caller":"traceutil/trace.go:171","msg":"trace[799499319] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"278.250283ms","start":"2026-07-27T20:11:32.251567Z","end":"2026-07-27T20:11:32.529817Z","steps":["trace[799499319] 'process raft request' (duration: 277.972018ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:11:32.529921Z","caller":"traceutil/trace.go:171","msg":"trace[855552532] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"277.785983ms","start":"2026-07-27T20:11:32.252101Z","end":"2026-07-27T20:11:32.529887Z","steps":["trace[855552532] 'process raft request' (duration: 277.574319ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:11:32.530192Z","caller":"traceutil/trace.go:171","msg":"trace[41707174] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"277.733152ms","start":"2026-07-27T20:11:32.252416Z","end":"2026-07-27T20:11:32.530149Z","steps":["trace[41707174] 'process raft request' (duration: 277.349404ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:11:32.53019Z","caller":"traceutil/trace.go:171","msg":"trace[89257871] linearizableReadLoop","detail":"{readStateIndex:2259; appliedIndex:2255; }","duration":"238.648367ms","start":"2026-07-27T20:11:32.291493Z","end":"2026-07-27T20:11:32.530141Z","steps":["trace[89257871] 'read index received' (duration: 91.224237ms)","trace[89257871] 'applied index is now lower than readState.Index' (duration: 147.42036ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:11:32.530492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.961323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.236\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-27T20:11:32.530546Z","caller":"traceutil/trace.go:171","msg":"trace[846878606] range","detail":"{range_begin:/registry/masterleases/199.19.213.236; range_end:; response_count:1; response_revision:2174; }","duration":"239.075866ms","start":"2026-07-27T20:11:32.291457Z","end":"2026-07-27T20:11:32.530533Z","steps":["trace[846878606] 'agreement among raft nodes before linearized reading' (duration: 238.806581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:32.530556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.783591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:11:32.530636Z","caller":"traceutil/trace.go:171","msg":"trace[2077403980] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:2174; }","duration":"135.894043ms","start":"2026-07-27T20:11:32.394708Z","end":"2026-07-27T20:11:32.530602Z","steps":["trace[2077403980] 'agreement among raft nodes before linearized reading' (duration: 135.752029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:11:32.531308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.032746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-27T20:11:32.531472Z","caller":"traceutil/trace.go:171","msg":"trace[1785100071] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2174; }","duration":"133.23519ms","start":"2026-07-27T20:11:32.398173Z","end":"2026-07-27T20:11:32.531409Z","steps":["trace[1785100071] 'agreement among raft nodes before linearized reading' (duration: 132.443214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:55.23779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.34748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-27T20:12:55.237844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.058223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-27T20:12:55.237839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.539573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-07-27T20:12:55.237899Z","caller":"traceutil/trace.go:171","msg":"trace[1259041164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2813; }","duration":"148.515864ms","start":"2026-07-27T20:12:55.089361Z","end":"2026-07-27T20:12:55.237876Z","steps":["trace[1259041164] 'range keys from in-memory index tree' (duration: 148.135756ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:55.237916Z","caller":"traceutil/trace.go:171","msg":"trace[2010486279] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2813; }","duration":"151.155214ms","start":"2026-07-27T20:12:55.086743Z","end":"2026-07-27T20:12:55.237899Z","steps":["trace[2010486279] 'range keys from in-memory index tree' (duration: 150.913219ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:55.237962Z","caller":"traceutil/trace.go:171","msg":"trace[666358185] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2813; }","duration":"151.711717ms","start":"2026-07-27T20:12:55.086218Z","end":"2026-07-27T20:12:55.23793Z","steps":["trace[666358185] 'range keys from in-memory index tree' (duration: 151.328878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:55.238008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.125626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-27T20:12:55.238038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.52539ms","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-27T20:12:55.238083Z","caller":"traceutil/trace.go:171","msg":"trace[1350204472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2813; }","duration":"149.220608ms","start":"2026-07-27T20:12:55.088847Z","end":"2026-07-27T20:12:55.238067Z","steps":["trace[1350204472] 'range keys from in-memory index tree' (duration: 149.010393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:12:55.238119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.386866ms","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-27T20:12:55.238196Z","caller":"traceutil/trace.go:171","msg":"trace[1454008869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2813; }","duration":"138.528599ms","start":"2026-07-27T20:12:55.099653Z","end":"2026-07-27T20:12:55.238181Z","steps":["trace[1454008869] 'range keys from in-memory index tree' (duration: 138.206902ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:55.238087Z","caller":"traceutil/trace.go:171","msg":"trace[46701932] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2813; }","duration":"158.628992ms","start":"2026-07-27T20:12:55.079445Z","end":"2026-07-27T20:12:55.238074Z","steps":["trace[46701932] 'range keys from in-memory index tree' (duration: 158.366378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:16:58.990652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.635508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-07-27T20:16:58.990778Z","caller":"traceutil/trace.go:171","msg":"trace[1420470693] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4955; }","duration":"129.797181ms","start":"2026-07-27T20:16:58.860953Z","end":"2026-07-27T20:16:58.99075Z","steps":["trace[1420470693] 'range keys from in-memory index tree' (duration: 129.416734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:16:58.990994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.345977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:23 size:531284"} {"level":"info","ts":"2026-07-27T20:16:58.99112Z","caller":"traceutil/trace.go:171","msg":"trace[2002648131] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:4955; }","duration":"135.522631ms","start":"2026-07-27T20:16:58.855567Z","end":"2026-07-27T20:16:58.991089Z","steps":["trace[2002648131] 'range keys from in-memory index tree' (duration: 134.926259ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:16:59.173784Z","caller":"traceutil/trace.go:171","msg":"trace[1816574415] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"133.36193ms","start":"2026-07-27T20:16:59.040384Z","end":"2026-07-27T20:16:59.173746Z","steps":["trace[1816574415] 'process raft request' (duration: 133.129716ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:17:16.322827Z","caller":"traceutil/trace.go:171","msg":"trace[564406022] transaction","detail":"{read_only:false; response_revision:5351; number_of_response:1; }","duration":"131.065293ms","start":"2026-07-27T20:17:16.191716Z","end":"2026-07-27T20:17:16.322781Z","steps":["trace[564406022] 'process raft request' (duration: 114.232818ms)","trace[564406022] 'compare' (duration: 16.55159ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:17:16.339177Z","caller":"traceutil/trace.go:171","msg":"trace[1039306075] transaction","detail":"{read_only:false; response_revision:5352; number_of_response:1; }","duration":"135.815505ms","start":"2026-07-27T20:17:16.203337Z","end":"2026-07-27T20:17:16.339153Z","steps":["trace[1039306075] 'process raft request' (duration: 135.723182ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:17:17.013399Z","caller":"traceutil/trace.go:171","msg":"trace[1585730101] transaction","detail":"{read_only:false; response_revision:5357; number_of_response:1; }","duration":"259.726518ms","start":"2026-07-27T20:17:16.753641Z","end":"2026-07-27T20:17:17.013368Z","steps":["trace[1585730101] 'process raft request' (duration: 259.566125ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:17:17.013643Z","caller":"traceutil/trace.go:171","msg":"trace[1538831746] linearizableReadLoop","detail":"{readStateIndex:5560; appliedIndex:5560; }","duration":"196.858584ms","start":"2026-07-27T20:17:16.816729Z","end":"2026-07-27T20:17:17.013587Z","steps":["trace[1538831746] 'read index received' (duration: 196.841763ms)","trace[1538831746] 'applied index is now lower than readState.Index' (duration: 13.221µs)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:17:17.013824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.084268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-2q7jp\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-07-27T20:17:17.013899Z","caller":"traceutil/trace.go:171","msg":"trace[1848406695] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-2q7jp; range_end:; response_count:1; response_revision:5357; }","duration":"197.20988ms","start":"2026-07-27T20:17:16.816672Z","end":"2026-07-27T20:17:17.013882Z","steps":["trace[1848406695] 'agreement among raft nodes before linearized reading' (duration: 197.021927ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:17:17.013922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.192047ms","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-27T20:17:17.013969Z","caller":"traceutil/trace.go:171","msg":"trace[97795756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5357; }","duration":"141.306961ms","start":"2026-07-27T20:17:16.87265Z","end":"2026-07-27T20:17:17.013957Z","steps":["trace[97795756] 'agreement among raft nodes before linearized reading' (duration: 141.121866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:17:25.080217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.166141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-90f6e97b-e24d-414d-8fd0-994ffaf90e1d\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-27T20:17:25.080531Z","caller":"traceutil/trace.go:171","msg":"trace[1727218969] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-90f6e97b-e24d-414d-8fd0-994ffaf90e1d; range_end:; response_count:1; response_revision:5564; }","duration":"154.538458ms","start":"2026-07-27T20:17:24.925973Z","end":"2026-07-27T20:17:25.080511Z","steps":["trace[1727218969] 'range keys from in-memory index tree' (duration: 153.954194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:17:25.080389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.166403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-07-27T20:17:25.080694Z","caller":"traceutil/trace.go:171","msg":"trace[1607389562] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5564; }","duration":"133.469779ms","start":"2026-07-27T20:17:24.947162Z","end":"2026-07-27T20:17:25.080632Z","steps":["trace[1607389562] 'range keys from in-memory index tree' (duration: 132.927459ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:18:18.259055Z","caller":"traceutil/trace.go:171","msg":"trace[1270638949] transaction","detail":"{read_only:false; response_revision:6031; number_of_response:1; }","duration":"100.91601ms","start":"2026-07-27T20:18:18.158106Z","end":"2026-07-27T20:18:18.259022Z","steps":["trace[1270638949] 'process raft request' (duration: 100.487212ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:18:29.186439Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3001} {"level":"info","ts":"2026-07-27T20:18:29.273373Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3001,"took":"85.352739ms","hash":1023294957,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":31809536,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-27T20:18:29.273532Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1023294957,"revision":3001,"compact-revision":-1} {"level":"info","ts":"2026-07-27T20:23:29.193363Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6117} {"level":"info","ts":"2026-07-27T20:23:29.309094Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6117,"took":"113.091457ms","hash":3569434246,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":21946368,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-27T20:23:29.309186Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3569434246,"revision":6117,"compact-revision":3001} {"level":"warn","ts":"2026-07-27T20:23:35.551705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.448379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-27T20:23:35.551665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.959724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-07-27T20:23:35.551804Z","caller":"traceutil/trace.go:171","msg":"trace[1107966106] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:8381; }","duration":"255.683514ms","start":"2026-07-27T20:23:35.2961Z","end":"2026-07-27T20:23:35.551784Z","steps":["trace[1107966106] 'count revisions from in-memory index tree' (duration: 255.331807ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:23:35.551843Z","caller":"traceutil/trace.go:171","msg":"trace[837033989] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8381; }","duration":"172.212229ms","start":"2026-07-27T20:23:35.379597Z","end":"2026-07-27T20:23:35.551809Z","steps":["trace[837033989] 'range keys from in-memory index tree' (duration: 171.71657ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:23:35.63346Z","caller":"traceutil/trace.go:171","msg":"trace[1097520913] linearizableReadLoop","detail":"{readStateIndex:8681; appliedIndex:8680; }","duration":"135.71952ms","start":"2026-07-27T20:23:35.497703Z","end":"2026-07-27T20:23:35.633423Z","steps":["trace[1097520913] 'read index received' (duration: 135.546347ms)","trace[1097520913] 'applied index is now lower than readState.Index' (duration: 171.233µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:23:35.633928Z","caller":"traceutil/trace.go:171","msg":"trace[1425318923] transaction","detail":"{read_only:false; response_revision:8382; number_of_response:1; }","duration":"143.624813ms","start":"2026-07-27T20:23:35.490284Z","end":"2026-07-27T20:23:35.633909Z","steps":["trace[1425318923] 'process raft request' (duration: 142.90018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:23:35.634392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.672629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tlqsd\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-07-27T20:23:35.634452Z","caller":"traceutil/trace.go:171","msg":"trace[1573601368] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tlqsd; range_end:; response_count:1; response_revision:8382; }","duration":"136.796071ms","start":"2026-07-27T20:23:35.497642Z","end":"2026-07-27T20:23:35.634438Z","steps":["trace[1573601368] 'agreement among raft nodes before linearized reading' (duration: 136.565677ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:23:46.979372Z","caller":"traceutil/trace.go:171","msg":"trace[967429516] transaction","detail":"{read_only:false; response_revision:8436; number_of_response:1; }","duration":"148.985168ms","start":"2026-07-27T20:23:46.83035Z","end":"2026-07-27T20:23:46.979336Z","steps":["trace[967429516] 'process raft request' (duration: 148.759583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:23:47.242871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.346611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:23:47.24304Z","caller":"traceutil/trace.go:171","msg":"trace[24261351] linearizableReadLoop","detail":"{readStateIndex:8738; appliedIndex:8737; }","duration":"107.027112ms","start":"2026-07-27T20:23:47.135983Z","end":"2026-07-27T20:23:47.24301Z","steps":["trace[24261351] 'read index received' (duration: 55.222µs)","trace[24261351] 'applied index is now lower than readState.Index' (duration: 106.97024ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:23:47.243181Z","caller":"traceutil/trace.go:171","msg":"trace[1557573992] transaction","detail":"{read_only:false; response_revision:8437; number_of_response:1; }","duration":"328.311236ms","start":"2026-07-27T20:23:46.914818Z","end":"2026-07-27T20:23:47.243129Z","steps":["trace[1557573992] 'process raft request' (duration: 96.569288ms)","trace[1557573992] 'compare' (duration: 231.141066ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:23:47.24341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:23:46.914796Z","time spent":"328.493009ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:23:47.243401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.329878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-27T20:23:47.243599Z","caller":"traceutil/trace.go:171","msg":"trace[995103316] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:8437; }","duration":"107.634144ms","start":"2026-07-27T20:23:47.13592Z","end":"2026-07-27T20:23:47.243554Z","steps":["trace[995103316] 'agreement among raft nodes before linearized reading' (duration: 107.197655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:23:47.267785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.319346ms","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-27T20:23:47.267897Z","caller":"traceutil/trace.go:171","msg":"trace[442448282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8438; }","duration":"129.463908ms","start":"2026-07-27T20:23:47.138414Z","end":"2026-07-27T20:23:47.267878Z","steps":["trace[442448282] 'agreement among raft nodes before linearized reading' (duration: 129.229744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:23:47.268095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.32977ms","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-27T20:23:47.268731Z","caller":"traceutil/trace.go:171","msg":"trace[2007323359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8438; }","duration":"122.981892ms","start":"2026-07-27T20:23:47.145732Z","end":"2026-07-27T20:23:47.268714Z","steps":["trace[2007323359] 'agreement among raft nodes before linearized reading' (duration: 122.308289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:23:47.268076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.451949ms","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-27T20:23:47.268872Z","caller":"traceutil/trace.go:171","msg":"trace[1734755904] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8438; }","duration":"108.282806ms","start":"2026-07-27T20:23:47.160579Z","end":"2026-07-27T20:23:47.268862Z","steps":["trace[1734755904] 'agreement among raft nodes before linearized reading' (duration: 107.295517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:23:47.268152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.482437ms","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-27T20:23:47.269004Z","caller":"traceutil/trace.go:171","msg":"trace[1417547583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8438; }","duration":"115.372574ms","start":"2026-07-27T20:23:47.153603Z","end":"2026-07-27T20:23:47.268976Z","steps":["trace[1417547583] 'agreement among raft nodes before linearized reading' (duration: 114.309054ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:23:47.268263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.603192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-27T20:23:47.269102Z","caller":"traceutil/trace.go:171","msg":"trace[852260565] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8438; }","duration":"109.50245ms","start":"2026-07-27T20:23:47.159557Z","end":"2026-07-27T20:23:47.269059Z","steps":["trace[852260565] 'agreement among raft nodes before linearized reading' (duration: 108.44436ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:23:49.097775Z","caller":"traceutil/trace.go:171","msg":"trace[756357220] transaction","detail":"{read_only:false; response_revision:8445; number_of_response:1; }","duration":"109.113402ms","start":"2026-07-27T20:23:48.988631Z","end":"2026-07-27T20:23:49.097745Z","steps":["trace[756357220] 'process raft request' (duration: 108.929218ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:23:49.382577Z","caller":"traceutil/trace.go:171","msg":"trace[1112767233] transaction","detail":"{read_only:false; response_revision:8446; number_of_response:1; }","duration":"132.934376ms","start":"2026-07-27T20:23:49.249612Z","end":"2026-07-27T20:23:49.382547Z","steps":["trace[1112767233] 'process raft request' (duration: 132.780123ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:23:49.421123Z","caller":"traceutil/trace.go:171","msg":"trace[74849739] linearizableReadLoop","detail":"{readStateIndex:8749; appliedIndex:8747; }","duration":"122.754578ms","start":"2026-07-27T20:23:49.29834Z","end":"2026-07-27T20:23:49.421094Z","steps":["trace[74849739] 'read index received' (duration: 84.088046ms)","trace[74849739] 'applied index is now lower than readState.Index' (duration: 38.665342ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:23:49.421309Z","caller":"traceutil/trace.go:171","msg":"trace[950351332] transaction","detail":"{read_only:false; response_revision:8447; number_of_response:1; }","duration":"147.06167ms","start":"2026-07-27T20:23:49.274187Z","end":"2026-07-27T20:23:49.421248Z","steps":["trace[950351332] 'process raft request' (duration: 146.740973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:23:49.421404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.341911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-27T20:23:49.421861Z","caller":"traceutil/trace.go:171","msg":"trace[871136008] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8447; }","duration":"100.844441ms","start":"2026-07-27T20:23:49.320992Z","end":"2026-07-27T20:23:49.421837Z","steps":["trace[871136008] 'agreement among raft nodes before linearized reading' (duration: 100.27126ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:23:49.421398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.035913ms","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-27T20:23:49.422012Z","caller":"traceutil/trace.go:171","msg":"trace[1820032587] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8447; }","duration":"123.699026ms","start":"2026-07-27T20:23:49.298273Z","end":"2026-07-27T20:23:49.421972Z","steps":["trace[1820032587] 'agreement among raft nodes before linearized reading' (duration: 122.962592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:23:49.421452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.21433ms","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-27T20:23:49.422133Z","caller":"traceutil/trace.go:171","msg":"trace[467528767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8447; }","duration":"100.954993ms","start":"2026-07-27T20:23:49.321153Z","end":"2026-07-27T20:23:49.422108Z","steps":["trace[467528767] 'agreement among raft nodes before linearized reading' (duration: 100.202829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:23:49.421482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.342222ms","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-27T20:23:49.422265Z","caller":"traceutil/trace.go:171","msg":"trace[1242359744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8447; }","duration":"101.131217ms","start":"2026-07-27T20:23:49.321115Z","end":"2026-07-27T20:23:49.422246Z","steps":["trace[1242359744] 'agreement among raft nodes before linearized reading' (duration: 100.312641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:23:49.421526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.414313ms","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-27T20:23:49.422354Z","caller":"traceutil/trace.go:171","msg":"trace[349231647] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8447; }","duration":"101.226308ms","start":"2026-07-27T20:23:49.321084Z","end":"2026-07-27T20:23:49.422311Z","steps":["trace[349231647] 'agreement among raft nodes before linearized reading' (duration: 100.375712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:24:19.982929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.167474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:24:19.983168Z","caller":"traceutil/trace.go:171","msg":"trace[435517418] transaction","detail":"{read_only:false; response_revision:8669; number_of_response:1; }","duration":"166.620971ms","start":"2026-07-27T20:24:19.816492Z","end":"2026-07-27T20:24:19.983113Z","steps":["trace[435517418] 'process raft request' (duration: 40.165152ms)","trace[435517418] 'compare' (duration: 125.938569ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:24:28.765134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.606928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:24:28.765262Z","caller":"traceutil/trace.go:171","msg":"trace[508356070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8754; }","duration":"207.810632ms","start":"2026-07-27T20:24:28.557425Z","end":"2026-07-27T20:24:28.765235Z","steps":["trace[508356070] 'range keys from in-memory index tree' (duration: 207.440865ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:24:28.846065Z","caller":"traceutil/trace.go:171","msg":"trace[1907012594] transaction","detail":"{read_only:false; response_revision:8755; number_of_response:1; }","duration":"111.972927ms","start":"2026-07-27T20:24:28.734042Z","end":"2026-07-27T20:24:28.846015Z","steps":["trace[1907012594] 'process raft request' (duration: 111.672721ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:24:28.95235Z","caller":"traceutil/trace.go:171","msg":"trace[702432811] transaction","detail":"{read_only:false; response_revision:8756; number_of_response:1; }","duration":"177.001103ms","start":"2026-07-27T20:24:28.775326Z","end":"2026-07-27T20:24:28.952327Z","steps":["trace[702432811] 'process raft request' (duration: 176.84452ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:24:29.519159Z","caller":"traceutil/trace.go:171","msg":"trace[1643973452] transaction","detail":"{read_only:false; response_revision:8759; number_of_response:1; }","duration":"153.316941ms","start":"2026-07-27T20:24:29.365795Z","end":"2026-07-27T20:24:29.519112Z","steps":["trace[1643973452] 'process raft request' (duration: 153.086597ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:24:29.743742Z","caller":"traceutil/trace.go:171","msg":"trace[1115337104] transaction","detail":"{read_only:false; response_revision:8762; number_of_response:1; }","duration":"100.338992ms","start":"2026-07-27T20:24:29.643365Z","end":"2026-07-27T20:24:29.743704Z","steps":["trace[1115337104] 'process raft request' (duration: 73.385117ms)","trace[1115337104] 'compare' (duration: 26.574987ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:24:45.688736Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002409.wal"} {"level":"info","ts":"2026-07-27T20:26:04.268588Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f20dfa43a2e4607","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-27T20:26:04.274604Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-27T20:26:04.274689Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-27T20:28:29.200628Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8212} {"level":"info","ts":"2026-07-27T20:28:29.269118Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8212,"took":"65.581707ms","hash":787114410,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":29773824,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-27T20:28:29.269196Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":787114410,"revision":8212,"compact-revision":6117} {"level":"warn","ts":"2026-07-27T20:29:22.333971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.907837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:29:22.334127Z","caller":"traceutil/trace.go:171","msg":"trace[1234992980] transaction","detail":"{read_only:false; response_revision:11324; number_of_response:1; }","duration":"129.585664ms","start":"2026-07-27T20:29:22.204516Z","end":"2026-07-27T20:29:22.334102Z","steps":["trace[1234992980] 'process raft request' (duration: 24.445883ms)","trace[1234992980] 'compare' (duration: 104.766244ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:33:29.209132Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10835} {"level":"info","ts":"2026-07-27T20:33:29.302013Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10835,"took":"87.430083ms","hash":803237368,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":27496448,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-27T20:33:29.302118Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":803237368,"revision":10835,"compact-revision":8212} {"level":"info","ts":"2026-07-27T20:35:09.35876Z","caller":"traceutil/trace.go:171","msg":"trace[771903077] transaction","detail":"{read_only:false; response_revision:13706; number_of_response:1; }","duration":"185.556841ms","start":"2026-07-27T20:35:09.173182Z","end":"2026-07-27T20:35:09.358739Z","steps":["trace[771903077] 'process raft request' (duration: 168.581755ms)","trace[771903077] 'compare' (duration: 16.877135ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:35:09.360629Z","caller":"traceutil/trace.go:171","msg":"trace[1061775457] linearizableReadLoop","detail":"{readStateIndex:14180; appliedIndex:14177; }","duration":"100.492844ms","start":"2026-07-27T20:35:09.260118Z","end":"2026-07-27T20:35:09.360611Z","steps":["trace[1061775457] 'read index received' (duration: 81.644192ms)","trace[1061775457] 'applied index is now lower than readState.Index' (duration: 18.847722ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:35:09.360648Z","caller":"traceutil/trace.go:171","msg":"trace[1782209487] transaction","detail":"{read_only:false; response_revision:13708; number_of_response:1; }","duration":"185.4815ms","start":"2026-07-27T20:35:09.175151Z","end":"2026-07-27T20:35:09.360633Z","steps":["trace[1782209487] 'process raft request' (duration: 185.422899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:35:09.360784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.639187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:35:09.360819Z","caller":"traceutil/trace.go:171","msg":"trace[1802445926] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:13708; }","duration":"100.733359ms","start":"2026-07-27T20:35:09.260077Z","end":"2026-07-27T20:35:09.36081Z","steps":["trace[1802445926] 'agreement among raft nodes before linearized reading' (duration: 100.647577ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:35:09.361265Z","caller":"traceutil/trace.go:171","msg":"trace[963712659] transaction","detail":"{read_only:false; response_revision:13707; number_of_response:1; }","duration":"187.58279ms","start":"2026-07-27T20:35:09.173648Z","end":"2026-07-27T20:35:09.361231Z","steps":["trace[963712659] 'process raft request' (duration: 186.831925ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:35:28.320824Z","caller":"traceutil/trace.go:171","msg":"trace[2133729047] linearizableReadLoop","detail":"{readStateIndex:14280; appliedIndex:14278; }","duration":"143.141464ms","start":"2026-07-27T20:35:28.17766Z","end":"2026-07-27T20:35:28.320802Z","steps":["trace[2133729047] 'read index received' (duration: 58.394683ms)","trace[2133729047] 'applied index is now lower than readState.Index' (duration: 84.746091ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:35:28.320893Z","caller":"traceutil/trace.go:171","msg":"trace[1637842996] transaction","detail":"{read_only:false; response_revision:13804; number_of_response:1; }","duration":"157.873418ms","start":"2026-07-27T20:35:28.162988Z","end":"2026-07-27T20:35:28.320861Z","steps":["trace[1637842996] 'process raft request' (duration: 157.685815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:35:28.320944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.264776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-27T20:35:28.32105Z","caller":"traceutil/trace.go:171","msg":"trace[420740820] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:13804; }","duration":"143.40457ms","start":"2026-07-27T20:35:28.177633Z","end":"2026-07-27T20:35:28.321038Z","steps":["trace[420740820] 'agreement among raft nodes before linearized reading' (duration: 143.264407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:37:20.422217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.860781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tlqsd\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-07-27T20:37:20.42233Z","caller":"traceutil/trace.go:171","msg":"trace[1008567049] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tlqsd; range_end:; response_count:1; response_revision:15091; }","duration":"103.014253ms","start":"2026-07-27T20:37:20.319292Z","end":"2026-07-27T20:37:20.422306Z","steps":["trace[1008567049] 'range keys from in-memory index tree' (duration: 102.583726ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:38:29.218608Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12910} {"level":"info","ts":"2026-07-27T20:38:29.303575Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12910,"took":"82.47933ms","hash":615581225,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":31191040,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-27T20:38:29.303637Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":615581225,"revision":12910,"compact-revision":10835} {"level":"warn","ts":"2026-07-27T20:43:08.163022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.568336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-27T20:43:08.163221Z","caller":"traceutil/trace.go:171","msg":"trace[69396485] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17724; }","duration":"112.865762ms","start":"2026-07-27T20:43:08.050328Z","end":"2026-07-27T20:43:08.163193Z","steps":["trace[69396485] 'range keys from in-memory index tree' (duration: 112.355813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:43:14.637935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.752983ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:43:14.638062Z","caller":"traceutil/trace.go:171","msg":"trace[1789549365] transaction","detail":"{read_only:false; response_revision:17777; number_of_response:1; }","duration":"313.324871ms","start":"2026-07-27T20:43:14.324717Z","end":"2026-07-27T20:43:14.638042Z","steps":["trace[1789549365] 'process raft request' (duration: 110.382025ms)","trace[1789549365] 'compare' (duration: 202.166951ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:43:14.638509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:43:14.324681Z","time spent":"313.690278ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:43:15.065881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.614996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:43:15.065962Z","caller":"traceutil/trace.go:171","msg":"trace[2069336566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17777; }","duration":"327.7458ms","start":"2026-07-27T20:43:14.738194Z","end":"2026-07-27T20:43:15.06594Z","steps":["trace[2069336566] 'range keys from in-memory index tree' (duration: 327.432612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:43:15.066002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:43:14.73817Z","time spent":"327.823812ms","remote":"127.0.0.1:38328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-27T20:43:15.066406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.394939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0\" ","response":"range_response_count:1 size:1566"} {"level":"warn","ts":"2026-07-27T20:43:15.066398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.178247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-6kl2r\" ","response":"range_response_count:1 size:20756"} {"level":"info","ts":"2026-07-27T20:43:15.06645Z","caller":"traceutil/trace.go:171","msg":"trace[1500888514] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0; range_end:; response_count:1; response_revision:17777; }","duration":"131.474451ms","start":"2026-07-27T20:43:14.934962Z","end":"2026-07-27T20:43:15.066436Z","steps":["trace[1500888514] 'range keys from in-memory index tree' (duration: 131.193396ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:43:15.066476Z","caller":"traceutil/trace.go:171","msg":"trace[1725766769] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-6kl2r; range_end:; response_count:1; response_revision:17777; }","duration":"170.309628ms","start":"2026-07-27T20:43:14.896145Z","end":"2026-07-27T20:43:15.066454Z","steps":["trace[1725766769] 'range keys from in-memory index tree' (duration: 169.960092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:43:15.066896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.697055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-27T20:43:15.066934Z","caller":"traceutil/trace.go:171","msg":"trace[579371435] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17777; }","duration":"277.767457ms","start":"2026-07-27T20:43:14.789157Z","end":"2026-07-27T20:43:15.066924Z","steps":["trace[579371435] 'range keys from in-memory index tree' (duration: 277.577603ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:43:15.202539Z","caller":"traceutil/trace.go:171","msg":"trace[366602124] transaction","detail":"{read_only:false; response_revision:17778; number_of_response:1; }","duration":"116.771007ms","start":"2026-07-27T20:43:15.08568Z","end":"2026-07-27T20:43:15.202451Z","steps":["trace[366602124] 'process raft request' (duration: 116.487142ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:43:29.226125Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15508} {"level":"info","ts":"2026-07-27T20:43:29.324425Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15508,"took":"92.96145ms","hash":164283042,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":34148352,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-27T20:43:29.324504Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":164283042,"revision":15508,"compact-revision":12910} {"level":"info","ts":"2026-07-27T20:46:22.944345Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f20dfa43a2e4607","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-27T20:46:22.952129Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-27T20:46:22.952193Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-27T20:48:29.233348Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18088} {"level":"info","ts":"2026-07-27T20:48:29.3485Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18088,"took":"111.556535ms","hash":1107422483,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":30507008,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-27T20:48:29.348588Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1107422483,"revision":18088,"compact-revision":15508} {"level":"info","ts":"2026-07-27T20:48:30.051823Z","caller":"traceutil/trace.go:171","msg":"trace[1091314286] linearizableReadLoop","detail":"{readStateIndex:20891; appliedIndex:20890; }","duration":"180.115202ms","start":"2026-07-27T20:48:29.871667Z","end":"2026-07-27T20:48:30.051782Z","steps":["trace[1091314286] 'read index received' (duration: 148.323611ms)","trace[1091314286] 'applied index is now lower than readState.Index' (duration: 31.789751ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:48:30.051846Z","caller":"traceutil/trace.go:171","msg":"trace[957321663] transaction","detail":"{read_only:false; response_revision:20208; number_of_response:1; }","duration":"231.268695ms","start":"2026-07-27T20:48:29.82055Z","end":"2026-07-27T20:48:30.051819Z","steps":["trace[957321663] 'process raft request' (duration: 199.395222ms)","trace[957321663] 'compare' (duration: 31.526246ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:48:30.051982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.298855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:48:30.052036Z","caller":"traceutil/trace.go:171","msg":"trace[1315174254] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:20208; }","duration":"180.423518ms","start":"2026-07-27T20:48:29.871602Z","end":"2026-07-27T20:48:30.052025Z","steps":["trace[1315174254] 'agreement among raft nodes before linearized reading' (duration: 180.313766ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:48:30.214872Z","caller":"traceutil/trace.go:171","msg":"trace[2045155339] linearizableReadLoop","detail":"{readStateIndex:20892; appliedIndex:20891; }","duration":"106.491517ms","start":"2026-07-27T20:48:30.108347Z","end":"2026-07-27T20:48:30.214839Z","steps":["trace[2045155339] 'read index received' (duration: 93.897315ms)","trace[2045155339] 'applied index is now lower than readState.Index' (duration: 12.593022ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:48:30.214954Z","caller":"traceutil/trace.go:171","msg":"trace[474993617] transaction","detail":"{read_only:false; response_revision:20209; number_of_response:1; }","duration":"156.968247ms","start":"2026-07-27T20:48:30.057955Z","end":"2026-07-27T20:48:30.214923Z","steps":["trace[474993617] 'process raft request' (duration: 144.360924ms)","trace[474993617] 'compare' (duration: 12.398169ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:48:30.215133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.791234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-27T20:48:30.215239Z","caller":"traceutil/trace.go:171","msg":"trace[1827216814] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20209; }","duration":"106.979347ms","start":"2026-07-27T20:48:30.108234Z","end":"2026-07-27T20:48:30.215214Z","steps":["trace[1827216814] 'agreement among raft nodes before linearized reading' (duration: 106.749132ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:48:30.706558Z","caller":"traceutil/trace.go:171","msg":"trace[2079353552] transaction","detail":"{read_only:false; response_revision:20210; number_of_response:1; }","duration":"129.021121ms","start":"2026-07-27T20:48:30.57751Z","end":"2026-07-27T20:48:30.706531Z","steps":["trace[2079353552] 'process raft request' (duration: 128.846506ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:48:31.037258Z","caller":"traceutil/trace.go:171","msg":"trace[590025566] transaction","detail":"{read_only:false; response_revision:20211; number_of_response:1; }","duration":"141.52355ms","start":"2026-07-27T20:48:30.895708Z","end":"2026-07-27T20:48:31.037232Z","steps":["trace[590025566] 'process raft request' (duration: 140.875237ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:48:31.244556Z","caller":"traceutil/trace.go:171","msg":"trace[1520434908] transaction","detail":"{read_only:false; response_revision:20212; number_of_response:1; }","duration":"196.254252ms","start":"2026-07-27T20:48:31.048272Z","end":"2026-07-27T20:48:31.244526Z","steps":["trace[1520434908] 'process raft request' (duration: 134.169958ms)","trace[1520434908] 'compare' (duration: 61.776118ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:52:14.169058Z","caller":"traceutil/trace.go:171","msg":"trace[1052796643] transaction","detail":"{read_only:false; response_revision:22600; number_of_response:1; }","duration":"247.998819ms","start":"2026-07-27T20:52:13.921033Z","end":"2026-07-27T20:52:14.169031Z","steps":["trace[1052796643] 'process raft request' (duration: 247.835016ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:53:29.240626Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20202} {"level":"info","ts":"2026-07-27T20:53:29.347177Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20202,"took":"103.142751ms","hash":90900405,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":43532288,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-07-27T20:53:29.347255Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":90900405,"revision":20202,"compact-revision":18088} {"level":"info","ts":"2026-07-27T20:54:59.13737Z","caller":"traceutil/trace.go:171","msg":"trace[2146621345] transaction","detail":"{read_only:false; response_revision:24182; number_of_response:1; }","duration":"106.73163ms","start":"2026-07-27T20:54:59.030601Z","end":"2026-07-27T20:54:59.137333Z","steps":["trace[2146621345] 'process raft request' (duration: 106.557286ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:03.515079Z","caller":"traceutil/trace.go:171","msg":"trace[1392978624] transaction","detail":"{read_only:false; response_revision:24242; number_of_response:1; }","duration":"215.521827ms","start":"2026-07-27T20:55:03.299527Z","end":"2026-07-27T20:55:03.515049Z","steps":["trace[1392978624] 'process raft request' (duration: 215.314803ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:04.007198Z","caller":"traceutil/trace.go:171","msg":"trace[812544674] linearizableReadLoop","detail":"{readStateIndex:25042; appliedIndex:25040; }","duration":"360.536337ms","start":"2026-07-27T20:55:03.64663Z","end":"2026-07-27T20:55:04.007166Z","steps":["trace[812544674] 'read index received' (duration: 33.037582ms)","trace[812544674] 'applied index is now lower than readState.Index' (duration: 327.496865ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:55:04.007285Z","caller":"traceutil/trace.go:171","msg":"trace[884351700] transaction","detail":"{read_only:false; response_revision:24245; number_of_response:1; }","duration":"362.720898ms","start":"2026-07-27T20:55:03.644499Z","end":"2026-07-27T20:55:04.00722Z","steps":["trace[884351700] 'process raft request' (duration: 362.464944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:04.007471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:55:03.644453Z","time spent":"362.924772ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:55:04.007482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.812512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-27T20:55:04.007522Z","caller":"traceutil/trace.go:171","msg":"trace[1803296369] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24245; }","duration":"360.929875ms","start":"2026-07-27T20:55:03.646577Z","end":"2026-07-27T20:55:04.007506Z","steps":["trace[1803296369] 'agreement among raft nodes before linearized reading' (duration: 360.739761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:04.007554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:55:03.646556Z","time spent":"360.991605ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-27T20:55:04.013024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.262843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-27T20:55:04.013081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.38945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:55:04.013118Z","caller":"traceutil/trace.go:171","msg":"trace[1882114292] range","detail":"{range_begin:/registry/monitoring.coreos.com/scrapeconfigs/; range_end:/registry/monitoring.coreos.com/scrapeconfigs0; response_count:0; response_revision:24246; }","duration":"347.409515ms","start":"2026-07-27T20:55:03.665686Z","end":"2026-07-27T20:55:04.013095Z","steps":["trace[1882114292] 'agreement among raft nodes before linearized reading' (duration: 347.236132ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:04.013167Z","caller":"traceutil/trace.go:171","msg":"trace[697130756] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:24246; }","duration":"119.517422ms","start":"2026-07-27T20:55:03.89363Z","end":"2026-07-27T20:55:04.013148Z","steps":["trace[697130756] 'agreement among raft nodes before linearized reading' (duration: 119.355489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:04.013184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:55:03.665664Z","time spent":"347.504768ms","remote":"127.0.0.1:53124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/scrapeconfigs/\" range_end:\"/registry/monitoring.coreos.com/scrapeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:55:04.013411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.747697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-27T20:55:04.013435Z","caller":"traceutil/trace.go:171","msg":"trace[1830608849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24246; }","duration":"297.801867ms","start":"2026-07-27T20:55:03.715627Z","end":"2026-07-27T20:55:04.013429Z","steps":["trace[1830608849] 'agreement among raft nodes before linearized reading' (duration: 297.745206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:04.013073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.643184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:1 size:12669"} {"level":"info","ts":"2026-07-27T20:55:04.013509Z","caller":"traceutil/trace.go:171","msg":"trace[1072052291] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:1; response_revision:24246; }","duration":"110.102953ms","start":"2026-07-27T20:55:03.903383Z","end":"2026-07-27T20:55:04.013486Z","steps":["trace[1072052291] 'agreement among raft nodes before linearized reading' (duration: 109.581273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:04.013528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.51834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:55:04.013585Z","caller":"traceutil/trace.go:171","msg":"trace[892126623] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:24246; }","duration":"188.663323ms","start":"2026-07-27T20:55:03.824906Z","end":"2026-07-27T20:55:04.01357Z","steps":["trace[892126623] 'agreement among raft nodes before linearized reading' (duration: 188.52156ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:55:04.013183Z","caller":"traceutil/trace.go:171","msg":"trace[683259178] transaction","detail":"{read_only:false; response_revision:24246; number_of_response:1; }","duration":"123.521159ms","start":"2026-07-27T20:55:03.889653Z","end":"2026-07-27T20:55:04.013174Z","steps":["trace[683259178] 'process raft request' (duration: 123.083471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:55:04.01345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.542413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:55:04.013749Z","caller":"traceutil/trace.go:171","msg":"trace[49588518] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:24246; }","duration":"119.839408ms","start":"2026-07-27T20:55:03.893897Z","end":"2026-07-27T20:55:04.013736Z","steps":["trace[49588518] 'agreement among raft nodes before linearized reading' (duration: 119.523392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:16.518457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.449112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:56:16.518544Z","caller":"traceutil/trace.go:171","msg":"trace[810108273] transaction","detail":"{read_only:false; response_revision:24965; number_of_response:1; }","duration":"225.973497ms","start":"2026-07-27T20:56:16.292557Z","end":"2026-07-27T20:56:16.51853Z","steps":["trace[810108273] 'process raft request' (duration: 106.388002ms)","trace[810108273] 'compare' (duration: 119.370631ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:16.519027Z","caller":"traceutil/trace.go:171","msg":"trace[633885195] transaction","detail":"{read_only:false; response_revision:24966; number_of_response:1; }","duration":"198.009552ms","start":"2026-07-27T20:56:16.32099Z","end":"2026-07-27T20:56:16.518999Z","steps":["trace[633885195] 'process raft request' (duration: 197.744717ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:16.520086Z","caller":"traceutil/trace.go:171","msg":"trace[456779976] linearizableReadLoop","detail":"{readStateIndex:25785; appliedIndex:25782; }","duration":"121.718315ms","start":"2026-07-27T20:56:16.398354Z","end":"2026-07-27T20:56:16.520073Z","steps":["trace[456779976] 'read index received' (duration: 603.891µs)","trace[456779976] 'applied index is now lower than readState.Index' (duration: 121.113534ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:16.520493Z","caller":"traceutil/trace.go:171","msg":"trace[2063454861] transaction","detail":"{read_only:false; response_revision:24967; number_of_response:1; }","duration":"152.136436ms","start":"2026-07-27T20:56:16.368346Z","end":"2026-07-27T20:56:16.520482Z","steps":["trace[2063454861] 'process raft request' (duration: 150.430704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:16.521226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.854347ms","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-27T20:56:16.52126Z","caller":"traceutil/trace.go:171","msg":"trace[78546429] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24967; }","duration":"122.942489ms","start":"2026-07-27T20:56:16.398309Z","end":"2026-07-27T20:56:16.521251Z","steps":["trace[78546429] 'agreement among raft nodes before linearized reading' (duration: 122.474769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:21.689041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.858688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:56:21.689563Z","caller":"traceutil/trace.go:171","msg":"trace[1368724355] transaction","detail":"{read_only:false; response_revision:25012; number_of_response:1; }","duration":"386.504812ms","start":"2026-07-27T20:56:21.302993Z","end":"2026-07-27T20:56:21.689497Z","steps":["trace[1368724355] 'process raft request' (duration: 275.064293ms)","trace[1368724355] 'compare' (duration: 110.093794ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:21.689799Z","caller":"traceutil/trace.go:171","msg":"trace[1461227791] linearizableReadLoop","detail":"{readStateIndex:25832; appliedIndex:25830; }","duration":"316.75051ms","start":"2026-07-27T20:56:21.373025Z","end":"2026-07-27T20:56:21.689776Z","steps":["trace[1461227791] 'read index received' (duration: 205.199749ms)","trace[1461227791] 'applied index is now lower than readState.Index' (duration: 111.549071ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:21.689917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:21.302965Z","time spent":"386.724476ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:56:21.68993Z","caller":"traceutil/trace.go:171","msg":"trace[272376717] transaction","detail":"{read_only:false; response_revision:25013; number_of_response:1; }","duration":"359.052117ms","start":"2026-07-27T20:56:21.330824Z","end":"2026-07-27T20:56:21.689876Z","steps":["trace[272376717] 'process raft request' (duration: 358.848694ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:21.690054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.001795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-27T20:56:21.690138Z","caller":"traceutil/trace.go:171","msg":"trace[283048601] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:25013; }","duration":"317.148657ms","start":"2026-07-27T20:56:21.37297Z","end":"2026-07-27T20:56:21.690118Z","steps":["trace[283048601] 'agreement among raft nodes before linearized reading' (duration: 316.991214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:21.690156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:21.33079Z","time spent":"359.243691ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:56:21.6902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:21.372948Z","time spent":"317.234179ms","remote":"127.0.0.1:56808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:56:21.690788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.873329ms","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-27T20:56:21.690834Z","caller":"traceutil/trace.go:171","msg":"trace[1416101778] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25013; }","duration":"131.968771ms","start":"2026-07-27T20:56:21.558852Z","end":"2026-07-27T20:56:21.690821Z","steps":["trace[1416101778] 'agreement among raft nodes before linearized reading' (duration: 131.798588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:21.690795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.845397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:56:21.690983Z","caller":"traceutil/trace.go:171","msg":"trace[1731941425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25013; }","duration":"135.084601ms","start":"2026-07-27T20:56:21.555867Z","end":"2026-07-27T20:56:21.690952Z","steps":["trace[1731941425] 'agreement among raft nodes before linearized reading' (duration: 134.796696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:21.691205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.788414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-27T20:56:21.691259Z","caller":"traceutil/trace.go:171","msg":"trace[1527605697] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25013; }","duration":"146.880985ms","start":"2026-07-27T20:56:21.544364Z","end":"2026-07-27T20:56:21.691245Z","steps":["trace[1527605697] 'agreement among raft nodes before linearized reading' (duration: 146.246414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:22.018876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.570393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:56:22.019086Z","caller":"traceutil/trace.go:171","msg":"trace[1336860740] transaction","detail":"{read_only:false; response_revision:25015; number_of_response:1; }","duration":"179.113361ms","start":"2026-07-27T20:56:21.839954Z","end":"2026-07-27T20:56:22.019067Z","steps":["trace[1336860740] 'process raft request' (duration: 179.0359ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:22.019431Z","caller":"traceutil/trace.go:171","msg":"trace[1611915385] linearizableReadLoop","detail":"{readStateIndex:25833; appliedIndex:25832; }","duration":"323.577981ms","start":"2026-07-27T20:56:21.695828Z","end":"2026-07-27T20:56:22.019406Z","steps":["trace[1611915385] 'read index received' (duration: 158.408526ms)","trace[1611915385] 'applied index is now lower than readState.Index' (duration: 165.166875ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:22.019489Z","caller":"traceutil/trace.go:171","msg":"trace[696578699] transaction","detail":"{read_only:false; response_revision:25014; number_of_response:1; }","duration":"324.241614ms","start":"2026-07-27T20:56:21.695194Z","end":"2026-07-27T20:56:22.019435Z","steps":["trace[696578699] 'process raft request' (duration: 159.014568ms)","trace[696578699] 'compare' (duration: 164.39836ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:22.019628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.780325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18434"} {"level":"info","ts":"2026-07-27T20:56:22.019664Z","caller":"traceutil/trace.go:171","msg":"trace[1347396037] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25015; }","duration":"323.843296ms","start":"2026-07-27T20:56:21.695811Z","end":"2026-07-27T20:56:22.019654Z","steps":["trace[1347396037] 'agreement among raft nodes before linearized reading' (duration: 323.681903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:22.019667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:21.695179Z","time spent":"324.393956ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:56:22.019694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:21.695804Z","time spent":"323.884467ms","remote":"127.0.0.1:38512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18457,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-27T20:56:22.29023Z","caller":"traceutil/trace.go:171","msg":"trace[64053334] transaction","detail":"{read_only:false; response_revision:25016; number_of_response:1; }","duration":"321.246016ms","start":"2026-07-27T20:56:21.968956Z","end":"2026-07-27T20:56:22.290202Z","steps":["trace[64053334] 'process raft request' (duration: 321.089593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:22.290595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:21.96893Z","time spent":"321.588723ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:56:22.448381Z","caller":"traceutil/trace.go:171","msg":"trace[2028734830] linearizableReadLoop","detail":"{readStateIndex:25836; appliedIndex:25834; }","duration":"387.41359ms","start":"2026-07-27T20:56:22.060941Z","end":"2026-07-27T20:56:22.448355Z","steps":["trace[2028734830] 'read index received' (duration: 229.130867ms)","trace[2028734830] 'applied index is now lower than readState.Index' (duration: 158.281533ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:22.448497Z","caller":"traceutil/trace.go:171","msg":"trace[1585550296] transaction","detail":"{read_only:false; response_revision:25017; number_of_response:1; }","duration":"468.280214ms","start":"2026-07-27T20:56:21.980172Z","end":"2026-07-27T20:56:22.448452Z","steps":["trace[1585550296] 'process raft request' (duration: 459.20337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:22.44858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.713739ms","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-27T20:56:22.44861Z","caller":"traceutil/trace.go:171","msg":"trace[1798968439] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25017; }","duration":"372.805671ms","start":"2026-07-27T20:56:22.075796Z","end":"2026-07-27T20:56:22.448602Z","steps":["trace[1798968439] 'agreement among raft nodes before linearized reading' (duration: 372.711699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:22.448639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:22.075779Z","time spent":"372.853081ms","remote":"127.0.0.1:38788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-07-27T20:56:22.44862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.639564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-27T20:56:22.448686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:21.980147Z","time spent":"468.439828ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:56:22.448701Z","caller":"traceutil/trace.go:171","msg":"trace[1523674152] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25017; }","duration":"387.797027ms","start":"2026-07-27T20:56:22.060883Z","end":"2026-07-27T20:56:22.44868Z","steps":["trace[1523674152] 'agreement among raft nodes before linearized reading' (duration: 387.570993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:22.448744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:22.060822Z","time spent":"387.912259ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-27T20:56:22.448854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.646149ms","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-27T20:56:22.448952Z","caller":"traceutil/trace.go:171","msg":"trace[703309840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25017; }","duration":"293.780642ms","start":"2026-07-27T20:56:22.155146Z","end":"2026-07-27T20:56:22.448927Z","steps":["trace[703309840] 'agreement among raft nodes before linearized reading' (duration: 293.463866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:22.748082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.106407ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-07-27T20:56:22.748154Z","caller":"traceutil/trace.go:171","msg":"trace[1909648516] linearizableReadLoop","detail":"{readStateIndex:25837; appliedIndex:25836; }","duration":"295.768399ms","start":"2026-07-27T20:56:22.452372Z","end":"2026-07-27T20:56:22.74814Z","steps":["trace[1909648516] 'read index received' (duration: 123.663972ms)","trace[1909648516] 'applied index is now lower than readState.Index' (duration: 172.103437ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:22.748539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.156476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:56:22.748622Z","caller":"traceutil/trace.go:171","msg":"trace[2136680017] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25017; }","duration":"296.35183ms","start":"2026-07-27T20:56:22.452248Z","end":"2026-07-27T20:56:22.7486Z","steps":["trace[2136680017] 'agreement among raft nodes before linearized reading' (duration: 296.202267ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:23.079452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.844722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:56:23.079618Z","caller":"traceutil/trace.go:171","msg":"trace[846954509] transaction","detail":"{read_only:false; response_revision:25020; number_of_response:1; }","duration":"536.888256ms","start":"2026-07-27T20:56:22.542715Z","end":"2026-07-27T20:56:23.079603Z","steps":["trace[846954509] 'process raft request' (duration: 536.839725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:23.079656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:22.542675Z","time spent":"536.963407ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:56:23.079709Z","caller":"traceutil/trace.go:171","msg":"trace[1974535969] transaction","detail":"{read_only:false; response_revision:25019; number_of_response:1; }","duration":"626.530098ms","start":"2026-07-27T20:56:22.453153Z","end":"2026-07-27T20:56:23.079683Z","steps":["trace[1974535969] 'process raft request' (duration: 626.364275ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:23.079775Z","caller":"traceutil/trace.go:171","msg":"trace[72510582] transaction","detail":"{read_only:false; response_revision:25021; number_of_response:1; }","duration":"535.088091ms","start":"2026-07-27T20:56:22.544669Z","end":"2026-07-27T20:56:23.079757Z","steps":["trace[72510582] 'process raft request' (duration: 534.903837ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:23.079817Z","caller":"traceutil/trace.go:171","msg":"trace[1747453278] transaction","detail":"{read_only:false; response_revision:25018; number_of_response:1; }","duration":"626.907485ms","start":"2026-07-27T20:56:22.452844Z","end":"2026-07-27T20:56:23.079751Z","steps":["trace[1747453278] 'process raft request' (duration: 452.707217ms)","trace[1747453278] 'compare' (duration: 173.722589ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:23.079875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:22.453134Z","time spent":"626.6616ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:56:23.079873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:22.544642Z","time spent":"535.182642ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:56:23.079972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:22.452821Z","time spent":"627.059868ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:56:23.080007Z","caller":"traceutil/trace.go:171","msg":"trace[616093744] transaction","detail":"{read_only:false; response_revision:25022; number_of_response:1; }","duration":"532.514861ms","start":"2026-07-27T20:56:22.547479Z","end":"2026-07-27T20:56:23.079993Z","steps":["trace[616093744] 'process raft request' (duration: 532.197955ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:23.079991Z","caller":"traceutil/trace.go:171","msg":"trace[815312254] linearizableReadLoop","detail":"{readStateIndex:25842; appliedIndex:25837; }","duration":"331.812698ms","start":"2026-07-27T20:56:22.748168Z","end":"2026-07-27T20:56:23.079981Z","steps":["trace[815312254] 'read index received' (duration: 157.394956ms)","trace[815312254] 'applied index is now lower than readState.Index' (duration: 174.416912ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:23.080089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:22.547451Z","time spent":"532.601033ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:56:23.080098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.590074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-27T20:56:23.080124Z","caller":"traceutil/trace.go:171","msg":"trace[1991952777] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25022; }","duration":"609.638254ms","start":"2026-07-27T20:56:22.47048Z","end":"2026-07-27T20:56:23.080118Z","steps":["trace[1991952777] 'agreement among raft nodes before linearized reading' (duration: 609.525962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:23.080142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:22.470468Z","time spent":"609.670265ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-07-27T20:56:23.080235Z","caller":"traceutil/trace.go:171","msg":"trace[1327359173] transaction","detail":"{read_only:false; response_revision:25023; number_of_response:1; }","duration":"329.406051ms","start":"2026-07-27T20:56:22.750815Z","end":"2026-07-27T20:56:23.080221Z","steps":["trace[1327359173] 'process raft request' (duration: 329.120326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:23.080341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.521793ms","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-27T20:56:23.08033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.473352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-27T20:56:23.080362Z","caller":"traceutil/trace.go:171","msg":"trace[1126897559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25023; }","duration":"519.589115ms","start":"2026-07-27T20:56:22.560767Z","end":"2026-07-27T20:56:23.080356Z","steps":["trace[1126897559] 'agreement among raft nodes before linearized reading' (duration: 519.373171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:23.080379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:22.560745Z","time spent":"519.629715ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-27T20:56:23.080363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:22.750783Z","time spent":"329.528424ms","remote":"127.0.0.1:38362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:56:23.080375Z","caller":"traceutil/trace.go:171","msg":"trace[1093671602] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25023; }","duration":"519.528433ms","start":"2026-07-27T20:56:22.560835Z","end":"2026-07-27T20:56:23.080364Z","steps":["trace[1093671602] 'agreement among raft nodes before linearized reading' (duration: 519.281158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:23.080415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:22.560831Z","time spent":"519.576324ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-27T20:56:23.511988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.249299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:56:23.512089Z","caller":"traceutil/trace.go:171","msg":"trace[814060121] linearizableReadLoop","detail":"{readStateIndex:25844; appliedIndex:25843; }","duration":"431.984652ms","start":"2026-07-27T20:56:23.080088Z","end":"2026-07-27T20:56:23.512072Z","steps":["trace[814060121] 'read index received' (duration: 125.62839ms)","trace[814060121] 'applied index is now lower than readState.Index' (duration: 306.354441ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:23.51231Z","caller":"traceutil/trace.go:171","msg":"trace[351333476] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"599.254137ms","start":"2026-07-27T20:56:22.913045Z","end":"2026-07-27T20:56:23.5123Z","steps":["trace[351333476] 'process raft request' (duration: 292.577089ms)","trace[351333476] 'compare' (duration: 305.916003ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:23.512358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:22.913013Z","time spent":"599.319177ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:56:23.512568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.486867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-27T20:56:23.512659Z","caller":"traceutil/trace.go:171","msg":"trace[616538289] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25024; }","duration":"570.61514ms","start":"2026-07-27T20:56:22.942022Z","end":"2026-07-27T20:56:23.512637Z","steps":["trace[616538289] 'agreement among raft nodes before linearized reading' (duration: 570.391736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:23.512702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:22.942009Z","time spent":"570.683401ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-27T20:56:23.512837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.429526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:56:23.512892Z","caller":"traceutil/trace.go:171","msg":"trace[813161684] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:25024; }","duration":"547.512478ms","start":"2026-07-27T20:56:22.965366Z","end":"2026-07-27T20:56:23.512879Z","steps":["trace[813161684] 'agreement among raft nodes before linearized reading' (duration: 547.419407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:23.512887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.651056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-27T20:56:23.512927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:22.965355Z","time spent":"547.563449ms","remote":"127.0.0.1:38472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:56:23.512962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.573015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-07-27T20:56:23.512991Z","caller":"traceutil/trace.go:171","msg":"trace[881545454] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:25024; }","duration":"573.666107ms","start":"2026-07-27T20:56:22.939315Z","end":"2026-07-27T20:56:23.512981Z","steps":["trace[881545454] 'agreement among raft nodes before linearized reading' (duration: 573.167028ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:23.512961Z","caller":"traceutil/trace.go:171","msg":"trace[1476185449] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:25024; }","duration":"531.771148ms","start":"2026-07-27T20:56:22.981172Z","end":"2026-07-27T20:56:23.512944Z","steps":["trace[1476185449] 'agreement among raft nodes before linearized reading' (duration: 531.608495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:23.512976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.803873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-07-27T20:56:23.513019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:22.939289Z","time spent":"573.723368ms","remote":"127.0.0.1:38494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1885,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" "} {"level":"warn","ts":"2026-07-27T20:56:23.513041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:22.981162Z","time spent":"531.868099ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-07-27T20:56:23.513042Z","caller":"traceutil/trace.go:171","msg":"trace[83722369] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25024; }","duration":"759.903234ms","start":"2026-07-27T20:56:22.753122Z","end":"2026-07-27T20:56:23.513025Z","steps":["trace[83722369] 'agreement among raft nodes before linearized reading' (duration: 759.71089ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:23.513091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:22.75311Z","time spent":"759.973025ms","remote":"127.0.0.1:38524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-27T20:56:23.821194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.117625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:56:23.821747Z","caller":"traceutil/trace.go:171","msg":"trace[1175825017] transaction","detail":"{read_only:false; response_revision:25028; number_of_response:1; }","duration":"304.124349ms","start":"2026-07-27T20:56:23.5176Z","end":"2026-07-27T20:56:23.821724Z","steps":["trace[1175825017] 'process raft request' (duration: 304.084618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:23.821837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:23.517572Z","time spent":"304.226811ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:56:23.821952Z","caller":"traceutil/trace.go:171","msg":"trace[92823592] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"734.822316ms","start":"2026-07-27T20:56:23.087114Z","end":"2026-07-27T20:56:23.821936Z","steps":["trace[92823592] 'process raft request' (duration: 581.870294ms)","trace[92823592] 'compare' (duration: 151.723378ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:23.821947Z","caller":"traceutil/trace.go:171","msg":"trace[1803395658] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"730.86315ms","start":"2026-07-27T20:56:23.091053Z","end":"2026-07-27T20:56:23.821916Z","steps":["trace[1803395658] 'process raft request' (duration: 730.488533ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:23.822008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:23.087099Z","time spent":"734.881097ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:56:23.822074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:23.091019Z","time spent":"730.982682ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:56:23.822102Z","caller":"traceutil/trace.go:171","msg":"trace[2065397531] linearizableReadLoop","detail":"{readStateIndex:25847; appliedIndex:25844; }","duration":"309.982461ms","start":"2026-07-27T20:56:23.512107Z","end":"2026-07-27T20:56:23.822089Z","steps":["trace[2065397531] 'read index received' (duration: 156.894897ms)","trace[2065397531] 'applied index is now lower than readState.Index' (duration: 153.085874ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:23.822119Z","caller":"traceutil/trace.go:171","msg":"trace[799293291] transaction","detail":"{read_only:false; response_revision:25027; number_of_response:1; }","duration":"729.566845ms","start":"2026-07-27T20:56:23.092528Z","end":"2026-07-27T20:56:23.822095Z","steps":["trace[799293291] 'process raft request' (duration: 729.111846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:23.822193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.810681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-27T20:56:23.82221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.504288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-07-27T20:56:23.82223Z","caller":"traceutil/trace.go:171","msg":"trace[1146665015] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25028; }","duration":"650.891133ms","start":"2026-07-27T20:56:23.171328Z","end":"2026-07-27T20:56:23.822219Z","steps":["trace[1146665015] 'agreement among raft nodes before linearized reading' (duration: 650.794481ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:23.822244Z","caller":"traceutil/trace.go:171","msg":"trace[448346028] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25028; }","duration":"737.586779ms","start":"2026-07-27T20:56:23.084647Z","end":"2026-07-27T20:56:23.822233Z","steps":["trace[448346028] 'agreement among raft nodes before linearized reading' (duration: 737.502608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:23.822245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:23.09251Z","time spent":"729.664497ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:56:23.822254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:23.171303Z","time spent":"650.944324ms","remote":"127.0.0.1:44474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:56:23.822277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:23.084622Z","time spent":"737.64785ms","remote":"127.0.0.1:38502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-07-27T20:56:23.841123Z","caller":"traceutil/trace.go:171","msg":"trace[294467789] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"144.226545ms","start":"2026-07-27T20:56:23.696873Z","end":"2026-07-27T20:56:23.841099Z","steps":["trace[294467789] 'process raft request' (duration: 144.170884ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:23.841287Z","caller":"traceutil/trace.go:171","msg":"trace[1154055671] transaction","detail":"{read_only:false; response_revision:25029; number_of_response:1; }","duration":"145.446529ms","start":"2026-07-27T20:56:23.695829Z","end":"2026-07-27T20:56:23.841276Z","steps":["trace[1154055671] 'process raft request' (duration: 145.082151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:23.852686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.752627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-27T20:56:23.852747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.534867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18434"} {"level":"info","ts":"2026-07-27T20:56:23.852796Z","caller":"traceutil/trace.go:171","msg":"trace[1018862039] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25031; }","duration":"337.608479ms","start":"2026-07-27T20:56:23.515175Z","end":"2026-07-27T20:56:23.852783Z","steps":["trace[1018862039] 'agreement among raft nodes before linearized reading' (duration: 337.453366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:23.85284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:23.515164Z","time spent":"337.66746ms","remote":"127.0.0.1:38512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18457,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-27T20:56:23.852762Z","caller":"traceutil/trace.go:171","msg":"trace[457945117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25031; }","duration":"297.87297ms","start":"2026-07-27T20:56:23.554872Z","end":"2026-07-27T20:56:23.852745Z","steps":["trace[457945117] 'agreement among raft nodes before linearized reading' (duration: 297.693767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:23.852773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.1689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-de59c484-14ed-4087-b139-b98a2e896a83\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-27T20:56:23.853085Z","caller":"traceutil/trace.go:171","msg":"trace[193554310] range","detail":"{range_begin:/registry/persistentvolumes/pvc-de59c484-14ed-4087-b139-b98a2e896a83; range_end:; response_count:1; response_revision:25031; }","duration":"337.513746ms","start":"2026-07-27T20:56:23.515559Z","end":"2026-07-27T20:56:23.853073Z","steps":["trace[193554310] 'agreement among raft nodes before linearized reading' (duration: 337.040417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:23.85312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:23.515555Z","time spent":"337.554828ms","remote":"127.0.0.1:38482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-de59c484-14ed-4087-b139-b98a2e896a83\" "} {"level":"info","ts":"2026-07-27T20:56:24.159539Z","caller":"traceutil/trace.go:171","msg":"trace[1345719685] transaction","detail":"{read_only:false; response_revision:25032; number_of_response:1; }","duration":"131.490661ms","start":"2026-07-27T20:56:24.028007Z","end":"2026-07-27T20:56:24.159497Z","steps":["trace[1345719685] 'process raft request' (duration: 131.217056ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:24.679763Z","caller":"traceutil/trace.go:171","msg":"trace[750022806] linearizableReadLoop","detail":"{readStateIndex:25853; appliedIndex:25852; }","duration":"280.348096ms","start":"2026-07-27T20:56:24.399396Z","end":"2026-07-27T20:56:24.679744Z","steps":["trace[750022806] 'read index received' (duration: 280.160171ms)","trace[750022806] 'applied index is now lower than readState.Index' (duration: 187.115µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:24.679993Z","caller":"traceutil/trace.go:171","msg":"trace[531234323] transaction","detail":"{read_only:false; response_revision:25033; number_of_response:1; }","duration":"383.636397ms","start":"2026-07-27T20:56:24.296345Z","end":"2026-07-27T20:56:24.679981Z","steps":["trace[531234323] 'process raft request' (duration: 383.267919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:24.680081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:24.296313Z","time spent":"383.708389ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:56:24.680181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.782044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:56:24.680205Z","caller":"traceutil/trace.go:171","msg":"trace[1223417601] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25033; }","duration":"280.843615ms","start":"2026-07-27T20:56:24.399352Z","end":"2026-07-27T20:56:24.680196Z","steps":["trace[1223417601] 'agreement among raft nodes before linearized reading' (duration: 280.802994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:24.68037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.241646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:56:24.680389Z","caller":"traceutil/trace.go:171","msg":"trace[457674598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25033; }","duration":"121.298038ms","start":"2026-07-27T20:56:24.559085Z","end":"2026-07-27T20:56:24.680383Z","steps":["trace[457674598] 'agreement among raft nodes before linearized reading' (duration: 121.267727ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:25.27904Z","caller":"traceutil/trace.go:171","msg":"trace[620315690] transaction","detail":"{read_only:false; response_revision:25038; number_of_response:1; }","duration":"174.968232ms","start":"2026-07-27T20:56:25.104037Z","end":"2026-07-27T20:56:25.279005Z","steps":["trace[620315690] 'process raft request' (duration: 174.243158ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:25.640884Z","caller":"traceutil/trace.go:171","msg":"trace[992255174] transaction","detail":"{read_only:false; response_revision:25039; number_of_response:1; }","duration":"121.441479ms","start":"2026-07-27T20:56:25.519406Z","end":"2026-07-27T20:56:25.640848Z","steps":["trace[992255174] 'process raft request' (duration: 121.245866ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:26.059103Z","caller":"traceutil/trace.go:171","msg":"trace[1265959437] linearizableReadLoop","detail":"{readStateIndex:25864; appliedIndex:25860; }","duration":"153.37989ms","start":"2026-07-27T20:56:25.905698Z","end":"2026-07-27T20:56:26.059078Z","steps":["trace[1265959437] 'read index received' (duration: 153.105665ms)","trace[1265959437] 'applied index is now lower than readState.Index' (duration: 273.275µs)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:26.059284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.557343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-07-27T20:56:26.059333Z","caller":"traceutil/trace.go:171","msg":"trace[1771268875] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25044; }","duration":"153.676516ms","start":"2026-07-27T20:56:25.905643Z","end":"2026-07-27T20:56:26.05932Z","steps":["trace[1771268875] 'agreement among raft nodes before linearized reading' (duration: 153.534423ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:26.059457Z","caller":"traceutil/trace.go:171","msg":"trace[984190501] transaction","detail":"{read_only:false; response_revision:25042; number_of_response:1; }","duration":"225.701171ms","start":"2026-07-27T20:56:25.833727Z","end":"2026-07-27T20:56:26.059428Z","steps":["trace[984190501] 'process raft request' (duration: 225.234522ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:26.05947Z","caller":"traceutil/trace.go:171","msg":"trace[1202015135] transaction","detail":"{read_only:false; response_revision:25043; number_of_response:1; }","duration":"214.037488ms","start":"2026-07-27T20:56:25.845408Z","end":"2026-07-27T20:56:26.059445Z","steps":["trace[1202015135] 'process raft request' (duration: 213.59511ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:26.059486Z","caller":"traceutil/trace.go:171","msg":"trace[351308303] transaction","detail":"{read_only:false; response_revision:25044; number_of_response:1; }","duration":"213.764263ms","start":"2026-07-27T20:56:25.845692Z","end":"2026-07-27T20:56:26.059457Z","steps":["trace[351308303] 'process raft request' (duration: 213.345145ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:26.059556Z","caller":"traceutil/trace.go:171","msg":"trace[1107059613] transaction","detail":"{read_only:false; response_revision:25041; number_of_response:1; }","duration":"226.670729ms","start":"2026-07-27T20:56:25.832846Z","end":"2026-07-27T20:56:26.059517Z","steps":["trace[1107059613] 'process raft request' (duration: 225.967846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:26.324141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.898544ms","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-27T20:56:26.324235Z","caller":"traceutil/trace.go:171","msg":"trace[1767884202] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25044; }","duration":"160.073007ms","start":"2026-07-27T20:56:26.164141Z","end":"2026-07-27T20:56:26.324214Z","steps":["trace[1767884202] 'range keys from in-memory index tree' (duration: 159.635399ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:26.472999Z","caller":"traceutil/trace.go:171","msg":"trace[807874394] transaction","detail":"{read_only:false; response_revision:25045; number_of_response:1; }","duration":"141.991902ms","start":"2026-07-27T20:56:26.330944Z","end":"2026-07-27T20:56:26.472936Z","steps":["trace[807874394] 'process raft request' (duration: 141.718267ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:26.787499Z","caller":"traceutil/trace.go:171","msg":"trace[720854163] transaction","detail":"{read_only:false; response_revision:25046; number_of_response:1; }","duration":"100.589242ms","start":"2026-07-27T20:56:26.686885Z","end":"2026-07-27T20:56:26.787475Z","steps":["trace[720854163] 'process raft request' (duration: 100.400298ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:27.142303Z","caller":"traceutil/trace.go:171","msg":"trace[1744289632] transaction","detail":"{read_only:false; response_revision:25047; number_of_response:1; }","duration":"117.937503ms","start":"2026-07-27T20:56:27.024338Z","end":"2026-07-27T20:56:27.142275Z","steps":["trace[1744289632] 'process raft request' (duration: 117.660457ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:27.458443Z","caller":"traceutil/trace.go:171","msg":"trace[1404048031] transaction","detail":"{read_only:false; response_revision:25048; number_of_response:1; }","duration":"354.419629ms","start":"2026-07-27T20:56:27.103999Z","end":"2026-07-27T20:56:27.458418Z","steps":["trace[1404048031] 'process raft request' (duration: 354.211645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:27.458611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:27.103975Z","time spent":"354.552282ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:56:27.458774Z","caller":"traceutil/trace.go:171","msg":"trace[1536708135] transaction","detail":"{read_only:false; response_revision:25049; number_of_response:1; }","duration":"354.055303ms","start":"2026-07-27T20:56:27.104697Z","end":"2026-07-27T20:56:27.458753Z","steps":["trace[1536708135] 'process raft request' (duration: 353.669485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:27.458897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:27.104678Z","time spent":"354.150034ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:56:27.459037Z","caller":"traceutil/trace.go:171","msg":"trace[1081675235] transaction","detail":"{read_only:false; response_revision:25050; number_of_response:1; }","duration":"353.774348ms","start":"2026-07-27T20:56:27.105255Z","end":"2026-07-27T20:56:27.459029Z","steps":["trace[1081675235] 'process raft request' (duration: 353.42347ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:27.459088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:27.105227Z","time spent":"353.832359ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:56:27.471075Z","caller":"traceutil/trace.go:171","msg":"trace[1176109667] linearizableReadLoop","detail":"{readStateIndex:25871; appliedIndex:25868; }","duration":"188.346716ms","start":"2026-07-27T20:56:27.282701Z","end":"2026-07-27T20:56:27.471048Z","steps":["trace[1176109667] 'read index received' (duration: 175.665774ms)","trace[1176109667] 'applied index is now lower than readState.Index' (duration: 12.679892ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:27.471105Z","caller":"traceutil/trace.go:171","msg":"trace[1256465973] transaction","detail":"{read_only:false; response_revision:25051; number_of_response:1; }","duration":"323.816845ms","start":"2026-07-27T20:56:27.147264Z","end":"2026-07-27T20:56:27.471081Z","steps":["trace[1256465973] 'process raft request' (duration: 323.641002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:27.471243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.544871ms","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-27T20:56:27.471277Z","caller":"traceutil/trace.go:171","msg":"trace[226038477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25051; }","duration":"188.626602ms","start":"2026-07-27T20:56:27.282643Z","end":"2026-07-27T20:56:27.47127Z","steps":["trace[226038477] 'agreement among raft nodes before linearized reading' (duration: 188.49926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:27.47127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:27.147247Z","time spent":"323.947307ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:56:27.471392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.193325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:56:27.471554Z","caller":"traceutil/trace.go:171","msg":"trace[56532126] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:25051; }","duration":"187.369259ms","start":"2026-07-27T20:56:27.284164Z","end":"2026-07-27T20:56:27.471533Z","steps":["trace[56532126] 'agreement among raft nodes before linearized reading' (duration: 187.087883ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:27.715261Z","caller":"traceutil/trace.go:171","msg":"trace[2074382518] linearizableReadLoop","detail":"{readStateIndex:25872; appliedIndex:25871; }","duration":"157.799354ms","start":"2026-07-27T20:56:27.557434Z","end":"2026-07-27T20:56:27.715233Z","steps":["trace[2074382518] 'read index received' (duration: 110.404499ms)","trace[2074382518] 'applied index is now lower than readState.Index' (duration: 47.393405ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:27.715397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.942577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:56:27.715434Z","caller":"traceutil/trace.go:171","msg":"trace[275576537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25052; }","duration":"158.030019ms","start":"2026-07-27T20:56:27.557394Z","end":"2026-07-27T20:56:27.715424Z","steps":["trace[275576537] 'agreement among raft nodes before linearized reading' (duration: 157.937937ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:27.715478Z","caller":"traceutil/trace.go:171","msg":"trace[1384333551] transaction","detail":"{read_only:false; response_revision:25052; number_of_response:1; }","duration":"240.804149ms","start":"2026-07-27T20:56:27.474638Z","end":"2026-07-27T20:56:27.715442Z","steps":["trace[1384333551] 'process raft request' (duration: 193.174399ms)","trace[1384333551] 'compare' (duration: 47.171981ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:27.855641Z","caller":"traceutil/trace.go:171","msg":"trace[1204734553] transaction","detail":"{read_only:false; response_revision:25053; number_of_response:1; }","duration":"208.475932ms","start":"2026-07-27T20:56:27.647131Z","end":"2026-07-27T20:56:27.855607Z","steps":["trace[1204734553] 'process raft request' (duration: 182.544697ms)","trace[1204734553] 'compare' (duration: 25.729861ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:27.855668Z","caller":"traceutil/trace.go:171","msg":"trace[15321247] linearizableReadLoop","detail":"{readStateIndex:25873; appliedIndex:25872; }","duration":"138.827472ms","start":"2026-07-27T20:56:27.716811Z","end":"2026-07-27T20:56:27.855639Z","steps":["trace[15321247] 'read index received' (duration: 112.879206ms)","trace[15321247] 'applied index is now lower than readState.Index' (duration: 25.945376ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:28.034237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.696533ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-27T20:56:28.034352Z","caller":"traceutil/trace.go:171","msg":"trace[1316834303] linearizableReadLoop","detail":"{readStateIndex:25874; appliedIndex:25873; }","duration":"148.463515ms","start":"2026-07-27T20:56:27.885869Z","end":"2026-07-27T20:56:28.034333Z","steps":["trace[1316834303] 'read index received' (duration: 51.561µs)","trace[1316834303] 'applied index is now lower than readState.Index' (duration: 148.410484ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:28.034572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.698421ms","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-27T20:56:28.034572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.741779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:56:28.034612Z","caller":"traceutil/trace.go:171","msg":"trace[1435446086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25053; }","duration":"148.776872ms","start":"2026-07-27T20:56:27.885822Z","end":"2026-07-27T20:56:28.034599Z","steps":["trace[1435446086] 'agreement among raft nodes before linearized reading' (duration: 148.616999ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:28.034668Z","caller":"traceutil/trace.go:171","msg":"trace[1578462357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25053; }","duration":"317.901722ms","start":"2026-07-27T20:56:27.716736Z","end":"2026-07-27T20:56:28.034637Z","steps":["trace[1578462357] 'agreement among raft nodes before linearized reading' (duration: 138.991004ms)","trace[1578462357] 'range keys from in-memory index tree' (duration: 178.778665ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:28.034697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.024845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"warn","ts":"2026-07-27T20:56:28.034737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:27.716709Z","time spent":"318.010355ms","remote":"127.0.0.1:38336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-27T20:56:28.034786Z","caller":"traceutil/trace.go:171","msg":"trace[56851965] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:25053; }","duration":"118.171417ms","start":"2026-07-27T20:56:27.916594Z","end":"2026-07-27T20:56:28.034765Z","steps":["trace[56851965] 'agreement among raft nodes before linearized reading' (duration: 117.886942ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:28.207923Z","caller":"traceutil/trace.go:171","msg":"trace[1534566135] transaction","detail":"{read_only:false; response_revision:25055; number_of_response:1; }","duration":"143.45685ms","start":"2026-07-27T20:56:28.064447Z","end":"2026-07-27T20:56:28.207904Z","steps":["trace[1534566135] 'process raft request' (duration: 143.342728ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:28.207949Z","caller":"traceutil/trace.go:171","msg":"trace[1171779360] linearizableReadLoop","detail":"{readStateIndex:25876; appliedIndex:25876; }","duration":"143.160324ms","start":"2026-07-27T20:56:28.064758Z","end":"2026-07-27T20:56:28.207918Z","steps":["trace[1171779360] 'read index received' (duration: 143.147564ms)","trace[1171779360] 'applied index is now lower than readState.Index' (duration: 10.08µs)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:28.208322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.469001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-27T20:56:28.208393Z","caller":"traceutil/trace.go:171","msg":"trace[660146771] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25055; }","duration":"143.632514ms","start":"2026-07-27T20:56:28.064744Z","end":"2026-07-27T20:56:28.208376Z","steps":["trace[660146771] 'agreement among raft nodes before linearized reading' (duration: 143.269447ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:28.209852Z","caller":"traceutil/trace.go:171","msg":"trace[2085952067] transaction","detail":"{read_only:false; response_revision:25057; number_of_response:1; }","duration":"142.625554ms","start":"2026-07-27T20:56:28.067204Z","end":"2026-07-27T20:56:28.209829Z","steps":["trace[2085952067] 'process raft request' (duration: 142.557893ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:28.210135Z","caller":"traceutil/trace.go:171","msg":"trace[882114734] transaction","detail":"{read_only:false; response_revision:25056; number_of_response:1; }","duration":"144.952838ms","start":"2026-07-27T20:56:28.06515Z","end":"2026-07-27T20:56:28.210102Z","steps":["trace[882114734] 'process raft request' (duration: 144.423529ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:28.619974Z","caller":"traceutil/trace.go:171","msg":"trace[44930171] transaction","detail":"{read_only:false; response_revision:25059; number_of_response:1; }","duration":"136.462718ms","start":"2026-07-27T20:56:28.483234Z","end":"2026-07-27T20:56:28.619697Z","steps":["trace[44930171] 'process raft request' (duration: 136.302064ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:29.005025Z","caller":"traceutil/trace.go:171","msg":"trace[249368026] linearizableReadLoop","detail":"{readStateIndex:25881; appliedIndex:25880; }","duration":"133.401789ms","start":"2026-07-27T20:56:28.871599Z","end":"2026-07-27T20:56:29.005001Z","steps":["trace[249368026] 'read index received' (duration: 133.169815ms)","trace[249368026] 'applied index is now lower than readState.Index' (duration: 231.084µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:29.005109Z","caller":"traceutil/trace.go:171","msg":"trace[845864206] transaction","detail":"{read_only:false; response_revision:25060; number_of_response:1; }","duration":"212.222464ms","start":"2026-07-27T20:56:28.792847Z","end":"2026-07-27T20:56:29.00507Z","steps":["trace[845864206] 'process raft request' (duration: 211.99079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:29.005254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.638153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-27T20:56:29.005294Z","caller":"traceutil/trace.go:171","msg":"trace[596515339] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25060; }","duration":"133.701944ms","start":"2026-07-27T20:56:28.871582Z","end":"2026-07-27T20:56:29.005284Z","steps":["trace[596515339] 'agreement among raft nodes before linearized reading' (duration: 133.516031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:29.199502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.46856ms","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-27T20:56:29.199595Z","caller":"traceutil/trace.go:171","msg":"trace[335986835] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25060; }","duration":"122.611172ms","start":"2026-07-27T20:56:29.076959Z","end":"2026-07-27T20:56:29.19957Z","steps":["trace[335986835] 'range keys from in-memory index tree' (duration: 122.319287ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:34.006972Z","caller":"traceutil/trace.go:171","msg":"trace[687985281] transaction","detail":"{read_only:false; response_revision:25096; number_of_response:1; }","duration":"260.983815ms","start":"2026-07-27T20:56:33.745959Z","end":"2026-07-27T20:56:34.006943Z","steps":["trace[687985281] 'process raft request' (duration: 260.795991ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:34.398813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.701549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:56:34.398952Z","caller":"traceutil/trace.go:171","msg":"trace[2098846622] linearizableReadLoop","detail":"{readStateIndex:25919; appliedIndex:25918; }","duration":"312.880376ms","start":"2026-07-27T20:56:34.086049Z","end":"2026-07-27T20:56:34.398929Z","steps":["trace[2098846622] 'read index received' (duration: 38.231µs)","trace[2098846622] 'applied index is now lower than readState.Index' (duration: 312.840265ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:34.399043Z","caller":"traceutil/trace.go:171","msg":"trace[1272916215] transaction","detail":"{read_only:false; response_revision:25097; number_of_response:1; }","duration":"524.455027ms","start":"2026-07-27T20:56:33.874572Z","end":"2026-07-27T20:56:34.399027Z","steps":["trace[1272916215] 'process raft request' (duration: 206.433942ms)","trace[1272916215] 'compare' (duration: 317.438164ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:34.399131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:33.874546Z","time spent":"524.539729ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:56:34.399241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.163911ms","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-27T20:56:34.399352Z","caller":"traceutil/trace.go:171","msg":"trace[366934104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25097; }","duration":"313.322504ms","start":"2026-07-27T20:56:34.086004Z","end":"2026-07-27T20:56:34.399326Z","steps":["trace[366934104] 'agreement among raft nodes before linearized reading' (duration: 313.036039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:34.399421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:34.085984Z","time spent":"313.422157ms","remote":"127.0.0.1:38638","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-27T20:56:34.700304Z","caller":"traceutil/trace.go:171","msg":"trace[1110698693] transaction","detail":"{read_only:false; response_revision:25098; number_of_response:1; }","duration":"473.785979ms","start":"2026-07-27T20:56:34.226457Z","end":"2026-07-27T20:56:34.700243Z","steps":["trace[1110698693] 'process raft request' (duration: 473.579265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:34.700473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:34.226425Z","time spent":"473.966194ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:56:34.761398Z","caller":"traceutil/trace.go:171","msg":"trace[906037855] linearizableReadLoop","detail":"{readStateIndex:25921; appliedIndex:25919; }","duration":"362.384942ms","start":"2026-07-27T20:56:34.398989Z","end":"2026-07-27T20:56:34.761374Z","steps":["trace[906037855] 'read index received' (duration: 301.156632ms)","trace[906037855] 'applied index is now lower than readState.Index' (duration: 61.22721ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:34.761471Z","caller":"traceutil/trace.go:171","msg":"trace[328569117] transaction","detail":"{read_only:false; response_revision:25100; number_of_response:1; }","duration":"357.087061ms","start":"2026-07-27T20:56:34.404315Z","end":"2026-07-27T20:56:34.761402Z","steps":["trace[328569117] 'process raft request' (duration: 357.003829ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:34.761506Z","caller":"traceutil/trace.go:171","msg":"trace[1038004917] transaction","detail":"{read_only:false; response_revision:25099; number_of_response:1; }","duration":"512.456019ms","start":"2026-07-27T20:56:34.249012Z","end":"2026-07-27T20:56:34.761468Z","steps":["trace[1038004917] 'process raft request' (duration: 512.158823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:34.761633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.029807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-27T20:56:34.761671Z","caller":"traceutil/trace.go:171","msg":"trace[358263177] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25100; }","duration":"481.105779ms","start":"2026-07-27T20:56:34.280552Z","end":"2026-07-27T20:56:34.761658Z","steps":["trace[358263177] 'agreement among raft nodes before linearized reading' (duration: 480.962857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:34.761693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:34.248995Z","time spent":"512.595671ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:56:34.761705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:34.404275Z","time spent":"357.299385ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:56:34.76173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:34.280534Z","time spent":"481.16325ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-27T20:56:34.761904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.721896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-27T20:56:34.761918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.1125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-27T20:56:34.761943Z","caller":"traceutil/trace.go:171","msg":"trace[563222140] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:25100; }","duration":"342.800116ms","start":"2026-07-27T20:56:34.419133Z","end":"2026-07-27T20:56:34.761933Z","steps":["trace[563222140] 'agreement among raft nodes before linearized reading' (duration: 342.734485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:34.761973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:34.419092Z","time spent":"342.874398ms","remote":"127.0.0.1:47008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true "} {"level":"info","ts":"2026-07-27T20:56:34.761979Z","caller":"traceutil/trace.go:171","msg":"trace[647059215] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25100; }","duration":"536.205631ms","start":"2026-07-27T20:56:34.225756Z","end":"2026-07-27T20:56:34.761961Z","steps":["trace[647059215] 'agreement among raft nodes before linearized reading' (duration: 536.001087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:34.762025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:34.22574Z","time spent":"536.275273ms","remote":"127.0.0.1:38524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-27T20:56:34.762087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.960562ms","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-27T20:56:34.762302Z","caller":"traceutil/trace.go:171","msg":"trace[911177302] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25100; }","duration":"509.136795ms","start":"2026-07-27T20:56:34.253092Z","end":"2026-07-27T20:56:34.762228Z","steps":["trace[911177302] 'agreement among raft nodes before linearized reading' (duration: 508.822988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:34.762582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:34.253081Z","time spent":"509.483882ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-27T20:56:34.762366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.25157ms","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-27T20:56:34.762695Z","caller":"traceutil/trace.go:171","msg":"trace[1173748840] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25100; }","duration":"117.615367ms","start":"2026-07-27T20:56:34.64506Z","end":"2026-07-27T20:56:34.762676Z","steps":["trace[1173748840] 'agreement among raft nodes before linearized reading' (duration: 117.23213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:34.762394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.446461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-27T20:56:34.762823Z","caller":"traceutil/trace.go:171","msg":"trace[1808870885] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25100; }","duration":"265.90287ms","start":"2026-07-27T20:56:34.496905Z","end":"2026-07-27T20:56:34.762808Z","steps":["trace[1808870885] 'agreement among raft nodes before linearized reading' (duration: 265.426081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:34.762451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.838314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-27T20:56:34.762993Z","caller":"traceutil/trace.go:171","msg":"trace[1333544089] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:25100; }","duration":"181.384974ms","start":"2026-07-27T20:56:34.581582Z","end":"2026-07-27T20:56:34.762967Z","steps":["trace[1333544089] 'agreement among raft nodes before linearized reading' (duration: 180.843304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:34.76249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.530092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-27T20:56:34.763098Z","caller":"traceutil/trace.go:171","msg":"trace[309987892] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25100; }","duration":"189.169394ms","start":"2026-07-27T20:56:34.573913Z","end":"2026-07-27T20:56:34.763082Z","steps":["trace[309987892] 'agreement among raft nodes before linearized reading' (duration: 188.559502ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:35.132675Z","caller":"traceutil/trace.go:171","msg":"trace[1921854396] transaction","detail":"{read_only:false; response_revision:25101; number_of_response:1; }","duration":"351.902752ms","start":"2026-07-27T20:56:34.780753Z","end":"2026-07-27T20:56:35.132655Z","steps":["trace[1921854396] 'process raft request' (duration: 330.159527ms)","trace[1921854396] 'compare' (duration: 21.651453ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:35.13277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:34.780731Z","time spent":"351.990383ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:56:35.280405Z","caller":"traceutil/trace.go:171","msg":"trace[233647172] transaction","detail":"{read_only:false; response_revision:25103; number_of_response:1; }","duration":"494.123388ms","start":"2026-07-27T20:56:34.786259Z","end":"2026-07-27T20:56:35.280383Z","steps":["trace[233647172] 'process raft request' (duration: 494.049497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:35.280566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:34.786239Z","time spent":"494.233461ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:56:35.280412Z","caller":"traceutil/trace.go:171","msg":"trace[1092950080] transaction","detail":"{read_only:false; response_revision:25102; number_of_response:1; }","duration":"498.080724ms","start":"2026-07-27T20:56:34.782295Z","end":"2026-07-27T20:56:35.280376Z","steps":["trace[1092950080] 'process raft request' (duration: 497.85071ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:35.280597Z","caller":"traceutil/trace.go:171","msg":"trace[1248954880] linearizableReadLoop","detail":"{readStateIndex:25924; appliedIndex:25922; }","duration":"497.38274ms","start":"2026-07-27T20:56:34.783177Z","end":"2026-07-27T20:56:35.28056Z","steps":["trace[1248954880] 'read index received' (duration: 327.702709ms)","trace[1248954880] 'applied index is now lower than readState.Index' (duration: 169.677601ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:35.280727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.539323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:56:35.280765Z","caller":"traceutil/trace.go:171","msg":"trace[1410627870] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25104; }","duration":"497.602424ms","start":"2026-07-27T20:56:34.783153Z","end":"2026-07-27T20:56:35.280755Z","steps":["trace[1410627870] 'agreement among raft nodes before linearized reading' (duration: 497.536013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:35.280786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:34.783145Z","time spent":"497.635875ms","remote":"127.0.0.1:38534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-07-27T20:56:35.280775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:34.782281Z","time spent":"498.37807ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:56:35.280747Z","caller":"traceutil/trace.go:171","msg":"trace[319489686] transaction","detail":"{read_only:false; response_revision:25104; number_of_response:1; }","duration":"244.671814ms","start":"2026-07-27T20:56:35.036017Z","end":"2026-07-27T20:56:35.280689Z","steps":["trace[319489686] 'process raft request' (duration: 244.332337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:35.280888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.512433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-27T20:56:35.280927Z","caller":"traceutil/trace.go:171","msg":"trace[722958272] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25104; }","duration":"497.602925ms","start":"2026-07-27T20:56:34.783311Z","end":"2026-07-27T20:56:35.280914Z","steps":["trace[722958272] 'agreement among raft nodes before linearized reading' (duration: 497.411281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:35.280965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:34.783296Z","time spent":"497.659686ms","remote":"127.0.0.1:38524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-27T20:56:44.790974Z","caller":"traceutil/trace.go:171","msg":"trace[450634412] linearizableReadLoop","detail":"{readStateIndex:26052; appliedIndex:26051; }","duration":"234.218954ms","start":"2026-07-27T20:56:44.556725Z","end":"2026-07-27T20:56:44.790944Z","steps":["trace[450634412] 'read index received' (duration: 234.02977ms)","trace[450634412] 'applied index is now lower than readState.Index' (duration: 187.794µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:44.791029Z","caller":"traceutil/trace.go:171","msg":"trace[1178478534] transaction","detail":"{read_only:false; response_revision:25228; number_of_response:1; }","duration":"357.59037ms","start":"2026-07-27T20:56:44.433406Z","end":"2026-07-27T20:56:44.790996Z","steps":["trace[1178478534] 'process raft request' (duration: 357.349525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:44.791139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.398267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-27T20:56:44.791351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:44.43339Z","time spent":"357.858375ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:56:44.791383Z","caller":"traceutil/trace.go:171","msg":"trace[311224477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25228; }","duration":"234.673293ms","start":"2026-07-27T20:56:44.556677Z","end":"2026-07-27T20:56:44.79135Z","steps":["trace[311224477] 'agreement among raft nodes before linearized reading' (duration: 234.405937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:44.977414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.036355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:56:44.977554Z","caller":"traceutil/trace.go:171","msg":"trace[1498200590] transaction","detail":"{read_only:false; response_revision:25230; number_of_response:1; }","duration":"164.931121ms","start":"2026-07-27T20:56:44.81261Z","end":"2026-07-27T20:56:44.977541Z","steps":["trace[1498200590] 'process raft request' (duration: 164.89365ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:44.977538Z","caller":"traceutil/trace.go:171","msg":"trace[345216943] transaction","detail":"{read_only:false; response_revision:25229; number_of_response:1; }","duration":"230.601245ms","start":"2026-07-27T20:56:44.746905Z","end":"2026-07-27T20:56:44.977507Z","steps":["trace[345216943] 'process raft request' (duration: 58.408696ms)","trace[345216943] 'compare' (duration: 171.923103ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:44.977605Z","caller":"traceutil/trace.go:171","msg":"trace[1564509198] linearizableReadLoop","detail":"{readStateIndex:26053; appliedIndex:26052; }","duration":"184.804521ms","start":"2026-07-27T20:56:44.792771Z","end":"2026-07-27T20:56:44.977576Z","steps":["trace[1564509198] 'read index received' (duration: 12.489689ms)","trace[1564509198] 'applied index is now lower than readState.Index' (duration: 172.311502ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:44.977791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.888417ms","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-27T20:56:44.977775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.980984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-27T20:56:44.977786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.33475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:56:44.977817Z","caller":"traceutil/trace.go:171","msg":"trace[320694332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25230; }","duration":"157.014809ms","start":"2026-07-27T20:56:44.820795Z","end":"2026-07-27T20:56:44.977809Z","steps":["trace[320694332] 'agreement among raft nodes before linearized reading' (duration: 156.914647ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:44.97783Z","caller":"traceutil/trace.go:171","msg":"trace[25716675] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:25230; }","duration":"152.417012ms","start":"2026-07-27T20:56:44.825406Z","end":"2026-07-27T20:56:44.977823Z","steps":["trace[25716675] 'agreement among raft nodes before linearized reading' (duration: 152.338521ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:44.977841Z","caller":"traceutil/trace.go:171","msg":"trace[1070926220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25230; }","duration":"185.091416ms","start":"2026-07-27T20:56:44.792728Z","end":"2026-07-27T20:56:44.97782Z","steps":["trace[1070926220] 'agreement among raft nodes before linearized reading' (duration: 184.969944ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:45.251642Z","caller":"traceutil/trace.go:171","msg":"trace[2001996943] transaction","detail":"{read_only:false; response_revision:25232; number_of_response:1; }","duration":"172.709909ms","start":"2026-07-27T20:56:45.078886Z","end":"2026-07-27T20:56:45.251596Z","steps":["trace[2001996943] 'process raft request' (duration: 172.412463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:45.785193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.850397ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:56:45.785281Z","caller":"traceutil/trace.go:171","msg":"trace[1988260351] linearizableReadLoop","detail":"{readStateIndex:26057; appliedIndex:26056; }","duration":"478.723014ms","start":"2026-07-27T20:56:45.306544Z","end":"2026-07-27T20:56:45.785267Z","steps":["trace[1988260351] 'read index received' (duration: 33.682733ms)","trace[1988260351] 'applied index is now lower than readState.Index' (duration: 445.039091ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:45.78534Z","caller":"traceutil/trace.go:171","msg":"trace[1802751164] transaction","detail":"{read_only:false; response_revision:25233; number_of_response:1; }","duration":"528.808051ms","start":"2026-07-27T20:56:45.256486Z","end":"2026-07-27T20:56:45.785294Z","steps":["trace[1802751164] 'process raft request' (duration: 83.794021ms)","trace[1802751164] 'compare' (duration: 444.622822ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:45.785371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.797965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:56:45.78544Z","caller":"traceutil/trace.go:171","msg":"trace[39753421] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25233; }","duration":"478.891727ms","start":"2026-07-27T20:56:45.306534Z","end":"2026-07-27T20:56:45.785425Z","steps":["trace[39753421] 'agreement among raft nodes before linearized reading' (duration: 478.768955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:45.785477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:45.306519Z","time spent":"478.951448ms","remote":"127.0.0.1:59164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-07-27T20:56:45.785496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:45.256465Z","time spent":"528.954754ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:56:45.9781Z","caller":"traceutil/trace.go:171","msg":"trace[2145589294] transaction","detail":"{read_only:false; response_revision:25234; number_of_response:1; }","duration":"665.497051ms","start":"2026-07-27T20:56:45.312573Z","end":"2026-07-27T20:56:45.97807Z","steps":["trace[2145589294] 'process raft request' (duration: 612.049731ms)","trace[2145589294] 'compare' (duration: 53.251597ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:45.978278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:45.31255Z","time spent":"665.630763ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:56:45.978307Z","caller":"traceutil/trace.go:171","msg":"trace[507925032] transaction","detail":"{read_only:false; response_revision:25235; number_of_response:1; }","duration":"442.121344ms","start":"2026-07-27T20:56:45.536162Z","end":"2026-07-27T20:56:45.978284Z","steps":["trace[507925032] 'process raft request' (duration: 441.861749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:45.978444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:45.536146Z","time spent":"442.230597ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:56:45.97858Z","caller":"traceutil/trace.go:171","msg":"trace[1584837457] transaction","detail":"{read_only:false; response_revision:25236; number_of_response:1; }","duration":"441.162477ms","start":"2026-07-27T20:56:45.537409Z","end":"2026-07-27T20:56:45.978572Z","steps":["trace[1584837457] 'process raft request' (duration: 440.80232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:45.978621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:45.537383Z","time spent":"441.216119ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:56:45.978595Z","caller":"traceutil/trace.go:171","msg":"trace[1053825489] linearizableReadLoop","detail":"{readStateIndex:26061; appliedIndex:26057; }","duration":"193.269041ms","start":"2026-07-27T20:56:45.785303Z","end":"2026-07-27T20:56:45.978572Z","steps":["trace[1053825489] 'read index received' (duration: 139.319351ms)","trace[1053825489] 'applied index is now lower than readState.Index' (duration: 53.94874ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:45.978754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.097936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-07-27T20:56:45.978766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.351598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-27T20:56:45.97881Z","caller":"traceutil/trace.go:171","msg":"trace[1929309177] transaction","detail":"{read_only:false; response_revision:25237; number_of_response:1; }","duration":"439.707549ms","start":"2026-07-27T20:56:45.539094Z","end":"2026-07-27T20:56:45.978801Z","steps":["trace[1929309177] 'process raft request' (duration: 439.410163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:45.978824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.30037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-12eae2f9-3a25-4ac2-bdaf-86b6780cc3de\" ","response":"range_response_count:1 size:3761"} {"level":"warn","ts":"2026-07-27T20:56:45.978874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:45.53908Z","time spent":"439.76274ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:56:45.978876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.207478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-07-27T20:56:45.978863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.194824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:56:45.97891Z","caller":"traceutil/trace.go:171","msg":"trace[448227501] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25237; }","duration":"641.252188ms","start":"2026-07-27T20:56:45.33765Z","end":"2026-07-27T20:56:45.978902Z","steps":["trace[448227501] 'agreement among raft nodes before linearized reading' (duration: 641.166957ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:45.978905Z","caller":"traceutil/trace.go:171","msg":"trace[1388841288] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-12eae2f9-3a25-4ac2-bdaf-86b6780cc3de; range_end:; response_count:1; response_revision:25237; }","duration":"608.414822ms","start":"2026-07-27T20:56:45.37047Z","end":"2026-07-27T20:56:45.978885Z","steps":["trace[1388841288] 'agreement among raft nodes before linearized reading' (duration: 608.273819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:45.978944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:45.337641Z","time spent":"641.294889ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-27T20:56:45.978945Z","caller":"traceutil/trace.go:171","msg":"trace[1175100925] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25237; }","duration":"189.284376ms","start":"2026-07-27T20:56:45.789637Z","end":"2026-07-27T20:56:45.978921Z","steps":["trace[1175100925] 'agreement among raft nodes before linearized reading' (duration: 189.157743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:45.978962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:45.37045Z","time spent":"608.498673ms","remote":"127.0.0.1:38524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3784,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-12eae2f9-3a25-4ac2-bdaf-86b6780cc3de\" "} {"level":"warn","ts":"2026-07-27T20:56:45.979018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.384039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-27T20:56:45.979041Z","caller":"traceutil/trace.go:171","msg":"trace[601468559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25237; }","duration":"432.489081ms","start":"2026-07-27T20:56:45.546545Z","end":"2026-07-27T20:56:45.979034Z","steps":["trace[601468559] 'agreement among raft nodes before linearized reading' (duration: 432.393399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:45.979059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:45.546529Z","time spent":"432.526381ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-27T20:56:45.978832Z","caller":"traceutil/trace.go:171","msg":"trace[1715696500] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25237; }","duration":"455.46183ms","start":"2026-07-27T20:56:45.523356Z","end":"2026-07-27T20:56:45.978818Z","steps":["trace[1715696500] 'agreement among raft nodes before linearized reading' (duration: 455.228476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:45.979142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:45.523334Z","time spent":"455.797277ms","remote":"127.0.0.1:38524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-27T20:56:45.978797Z","caller":"traceutil/trace.go:171","msg":"trace[545410802] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25237; }","duration":"641.170927ms","start":"2026-07-27T20:56:45.337613Z","end":"2026-07-27T20:56:45.978784Z","steps":["trace[545410802] 'agreement among raft nodes before linearized reading' (duration: 641.029055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:45.979243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:45.337607Z","time spent":"641.628556ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-27T20:56:46.205268Z","caller":"traceutil/trace.go:171","msg":"trace[2139527621] transaction","detail":"{read_only:false; response_revision:25238; number_of_response:1; }","duration":"220.769837ms","start":"2026-07-27T20:56:45.984463Z","end":"2026-07-27T20:56:46.205233Z","steps":["trace[2139527621] 'process raft request' (duration: 149.012336ms)","trace[2139527621] 'compare' (duration: 71.520546ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:46.468273Z","caller":"traceutil/trace.go:171","msg":"trace[1518046077] linearizableReadLoop","detail":"{readStateIndex:26063; appliedIndex:26061; }","duration":"481.702121ms","start":"2026-07-27T20:56:45.986526Z","end":"2026-07-27T20:56:46.468229Z","steps":["trace[1518046077] 'read index received' (duration: 146.942227ms)","trace[1518046077] 'applied index is now lower than readState.Index' (duration: 334.758924ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:46.468383Z","caller":"traceutil/trace.go:171","msg":"trace[1679582154] transaction","detail":"{read_only:false; response_revision:25240; number_of_response:1; }","duration":"481.769902ms","start":"2026-07-27T20:56:45.986573Z","end":"2026-07-27T20:56:46.468343Z","steps":["trace[1679582154] 'process raft request' (duration: 481.613179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:46.468563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:45.986561Z","time spent":"481.918735ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:56:46.468557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.007248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18434"} {"level":"info","ts":"2026-07-27T20:56:46.468639Z","caller":"traceutil/trace.go:171","msg":"trace[1697516449] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25240; }","duration":"482.118859ms","start":"2026-07-27T20:56:45.986499Z","end":"2026-07-27T20:56:46.468618Z","steps":["trace[1697516449] 'agreement among raft nodes before linearized reading' (duration: 481.878434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:46.468693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:45.986487Z","time spent":"482.195911ms","remote":"127.0.0.1:38512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18457,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-27T20:56:46.468926Z","caller":"traceutil/trace.go:171","msg":"trace[1304149636] transaction","detail":"{read_only:false; response_revision:25239; number_of_response:1; }","duration":"483.114198ms","start":"2026-07-27T20:56:45.985789Z","end":"2026-07-27T20:56:46.468903Z","steps":["trace[1304149636] 'process raft request' (duration: 482.238751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:46.469043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:45.985764Z","time spent":"483.20644ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:56:46.474311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.681016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:56:46.474384Z","caller":"traceutil/trace.go:171","msg":"trace[1195555629] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25241; }","duration":"487.780318ms","start":"2026-07-27T20:56:45.986585Z","end":"2026-07-27T20:56:46.474365Z","steps":["trace[1195555629] 'agreement among raft nodes before linearized reading' (duration: 487.640466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:46.474426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:45.986577Z","time spent":"487.838929ms","remote":"127.0.0.1:38642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-07-27T20:56:46.474466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.296569ms","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-27T20:56:46.474497Z","caller":"traceutil/trace.go:171","msg":"trace[1317162806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25241; }","duration":"408.362961ms","start":"2026-07-27T20:56:46.066127Z","end":"2026-07-27T20:56:46.474489Z","steps":["trace[1317162806] 'agreement among raft nodes before linearized reading' (duration: 408.275999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:56:46.474521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:56:46.06611Z","time spent":"408.407061ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-27T20:56:52.079089Z","caller":"traceutil/trace.go:171","msg":"trace[1131924173] linearizableReadLoop","detail":"{readStateIndex:26120; appliedIndex:26119; }","duration":"126.492236ms","start":"2026-07-27T20:56:51.95256Z","end":"2026-07-27T20:56:52.079053Z","steps":["trace[1131924173] 'read index received' (duration: 53.779178ms)","trace[1131924173] 'applied index is now lower than readState.Index' (duration: 72.711378ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:56:52.079114Z","caller":"traceutil/trace.go:171","msg":"trace[1928877232] transaction","detail":"{read_only:false; response_revision:25294; number_of_response:1; }","duration":"196.400812ms","start":"2026-07-27T20:56:51.882673Z","end":"2026-07-27T20:56:52.079074Z","steps":["trace[1928877232] 'process raft request' (duration: 123.675313ms)","trace[1928877232] 'compare' (duration: 72.528395ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:56:52.079468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.881904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-27T20:56:52.079528Z","caller":"traceutil/trace.go:171","msg":"trace[1571886416] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:25294; }","duration":"127.025667ms","start":"2026-07-27T20:56:51.952485Z","end":"2026-07-27T20:56:52.079511Z","steps":["trace[1571886416] 'agreement among raft nodes before linearized reading' (duration: 126.695621ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:52.100449Z","caller":"traceutil/trace.go:171","msg":"trace[350186159] transaction","detail":"{read_only:false; response_revision:25295; number_of_response:1; }","duration":"103.312434ms","start":"2026-07-27T20:56:51.997105Z","end":"2026-07-27T20:56:52.100417Z","steps":["trace[350186159] 'process raft request' (duration: 102.985397ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:52.100449Z","caller":"traceutil/trace.go:171","msg":"trace[981532521] transaction","detail":"{read_only:false; response_revision:25296; number_of_response:1; }","duration":"100.397278ms","start":"2026-07-27T20:56:52.000023Z","end":"2026-07-27T20:56:52.10042Z","steps":["trace[981532521] 'process raft request' (duration: 100.289736ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:56:54.002671Z","caller":"traceutil/trace.go:171","msg":"trace[799513742] transaction","detail":"{read_only:false; response_revision:25320; number_of_response:1; }","duration":"159.384035ms","start":"2026-07-27T20:56:53.843269Z","end":"2026-07-27T20:56:54.002653Z","steps":["trace[799513742] 'process raft request' (duration: 159.273562ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:57:02.421752Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006668.wal"} {"level":"info","ts":"2026-07-27T20:57:02.873435Z","caller":"traceutil/trace.go:171","msg":"trace[2038253152] transaction","detail":"{read_only:false; response_revision:25392; number_of_response:1; }","duration":"234.037281ms","start":"2026-07-27T20:57:02.639357Z","end":"2026-07-27T20:57:02.873394Z","steps":["trace[2038253152] 'process raft request' (duration: 191.861405ms)","trace[2038253152] 'compare' (duration: 41.206097ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:57:16.322866Z","caller":"traceutil/trace.go:171","msg":"trace[1597843569] linearizableReadLoop","detail":"{readStateIndex:26330; appliedIndex:26329; }","duration":"131.485241ms","start":"2026-07-27T20:57:16.191354Z","end":"2026-07-27T20:57:16.322839Z","steps":["trace[1597843569] 'read index received' (duration: 131.330808ms)","trace[1597843569] 'applied index is now lower than readState.Index' (duration: 153.303µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:57:16.322965Z","caller":"traceutil/trace.go:171","msg":"trace[675856075] transaction","detail":"{read_only:false; response_revision:25498; number_of_response:1; }","duration":"136.872984ms","start":"2026-07-27T20:57:16.186073Z","end":"2026-07-27T20:57:16.322946Z","steps":["trace[675856075] 'process raft request' (duration: 136.551508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:57:16.323165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.798607ms","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-27T20:57:16.323212Z","caller":"traceutil/trace.go:171","msg":"trace[510978818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25498; }","duration":"131.9127ms","start":"2026-07-27T20:57:16.191288Z","end":"2026-07-27T20:57:16.323201Z","steps":["trace[510978818] 'agreement among raft nodes before linearized reading' (duration: 131.661795ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:57:16.463889Z","caller":"traceutil/trace.go:171","msg":"trace[1326145341] transaction","detail":"{read_only:false; response_revision:25499; number_of_response:1; }","duration":"269.265244ms","start":"2026-07-27T20:57:16.194594Z","end":"2026-07-27T20:57:16.463859Z","steps":["trace[1326145341] 'process raft request' (duration: 221.096454ms)","trace[1326145341] 'compare' (duration: 47.974127ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:57:16.464131Z","caller":"traceutil/trace.go:171","msg":"trace[1095776975] transaction","detail":"{read_only:false; response_revision:25501; number_of_response:1; }","duration":"264.576464ms","start":"2026-07-27T20:57:16.199526Z","end":"2026-07-27T20:57:16.464102Z","steps":["trace[1095776975] 'process raft request' (duration: 264.433461ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:57:16.464192Z","caller":"traceutil/trace.go:171","msg":"trace[1529545863] transaction","detail":"{read_only:false; response_revision:25500; number_of_response:1; }","duration":"268.740493ms","start":"2026-07-27T20:57:16.195423Z","end":"2026-07-27T20:57:16.464163Z","steps":["trace[1529545863] 'process raft request' (duration: 268.381586ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:57:16.466019Z","caller":"traceutil/trace.go:171","msg":"trace[1815427206] transaction","detail":"{read_only:false; response_revision:25502; number_of_response:1; }","duration":"137.924563ms","start":"2026-07-27T20:57:16.328012Z","end":"2026-07-27T20:57:16.465936Z","steps":["trace[1815427206] 'process raft request' (duration: 137.639268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:57:16.698423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.61793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:57:16.698625Z","caller":"traceutil/trace.go:171","msg":"trace[328588808] linearizableReadLoop","detail":"{readStateIndex:26336; appliedIndex:26334; }","duration":"142.516591ms","start":"2026-07-27T20:57:16.556086Z","end":"2026-07-27T20:57:16.698603Z","steps":["trace[328588808] 'read index received' (duration: 16.635337ms)","trace[328588808] 'applied index is now lower than readState.Index' (duration: 125.879794ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:57:16.698827Z","caller":"traceutil/trace.go:171","msg":"trace[1468024344] transaction","detail":"{read_only:false; response_revision:25503; number_of_response:1; }","duration":"184.580996ms","start":"2026-07-27T20:57:16.514213Z","end":"2026-07-27T20:57:16.698794Z","steps":["trace[1468024344] 'process raft request' (duration: 58.495056ms)","trace[1468024344] 'compare' (duration: 125.463247ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:57:16.698858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.758927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:57:16.698917Z","caller":"traceutil/trace.go:171","msg":"trace[1541211913] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25504; }","duration":"142.831108ms","start":"2026-07-27T20:57:16.556071Z","end":"2026-07-27T20:57:16.698902Z","steps":["trace[1541211913] 'agreement among raft nodes before linearized reading' (duration: 142.676874ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:57:16.698892Z","caller":"traceutil/trace.go:171","msg":"trace[407137628] transaction","detail":"{read_only:false; response_revision:25504; number_of_response:1; }","duration":"174.742258ms","start":"2026-07-27T20:57:16.524117Z","end":"2026-07-27T20:57:16.698859Z","steps":["trace[407137628] 'process raft request' (duration: 174.425561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:57:16.698904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.331211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:57:16.698976Z","caller":"traceutil/trace.go:171","msg":"trace[1078922054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25504; }","duration":"128.440144ms","start":"2026-07-27T20:57:16.570517Z","end":"2026-07-27T20:57:16.698957Z","steps":["trace[1078922054] 'agreement among raft nodes before linearized reading' (duration: 128.308941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:57:16.698973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.442371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-27T20:57:16.699064Z","caller":"traceutil/trace.go:171","msg":"trace[1851991984] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:25504; }","duration":"108.584954ms","start":"2026-07-27T20:57:16.590458Z","end":"2026-07-27T20:57:16.699043Z","steps":["trace[1851991984] 'agreement among raft nodes before linearized reading' (duration: 108.383691ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:57:16.944745Z","caller":"traceutil/trace.go:171","msg":"trace[1481454614] linearizableReadLoop","detail":"{readStateIndex:26337; appliedIndex:26336; }","duration":"144.008311ms","start":"2026-07-27T20:57:16.800713Z","end":"2026-07-27T20:57:16.944721Z","steps":["trace[1481454614] 'read index received' (duration: 100.657652ms)","trace[1481454614] 'applied index is now lower than readState.Index' (duration: 43.346289ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:57:16.944812Z","caller":"traceutil/trace.go:171","msg":"trace[1852270566] transaction","detail":"{read_only:false; response_revision:25505; number_of_response:1; }","duration":"167.54845ms","start":"2026-07-27T20:57:16.777215Z","end":"2026-07-27T20:57:16.944763Z","steps":["trace[1852270566] 'process raft request' (duration: 124.209101ms)","trace[1852270566] 'compare' (duration: 43.196776ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:57:16.944953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.229755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-27T20:57:16.944982Z","caller":"traceutil/trace.go:171","msg":"trace[817961048] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25505; }","duration":"144.294286ms","start":"2026-07-27T20:57:16.800678Z","end":"2026-07-27T20:57:16.944972Z","steps":["trace[817961048] 'agreement among raft nodes before linearized reading' (duration: 144.168194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:57:22.945576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.573412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:57:22.945817Z","caller":"traceutil/trace.go:171","msg":"trace[44127071] linearizableReadLoop","detail":"{readStateIndex:26382; appliedIndex:26381; }","duration":"223.208672ms","start":"2026-07-27T20:57:22.722561Z","end":"2026-07-27T20:57:22.94577Z","steps":["trace[44127071] 'read index received' (duration: 57.262053ms)","trace[44127071] 'applied index is now lower than readState.Index' (duration: 165.944959ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:57:22.945815Z","caller":"traceutil/trace.go:171","msg":"trace[1670791761] transaction","detail":"{read_only:false; response_revision:25548; number_of_response:1; }","duration":"293.372993ms","start":"2026-07-27T20:57:22.652404Z","end":"2026-07-27T20:57:22.945777Z","steps":["trace[1670791761] 'process raft request' (duration: 127.522375ms)","trace[1670791761] 'compare' (duration: 165.288557ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:57:22.945991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.426467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-27T20:57:22.946051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.838981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:57:22.946126Z","caller":"traceutil/trace.go:171","msg":"trace[916552394] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:25548; }","duration":"105.972924ms","start":"2026-07-27T20:57:22.840139Z","end":"2026-07-27T20:57:22.946112Z","steps":["trace[916552394] 'agreement among raft nodes before linearized reading' (duration: 105.852901ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:57:22.946064Z","caller":"traceutil/trace.go:171","msg":"trace[997180108] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25548; }","duration":"223.561869ms","start":"2026-07-27T20:57:22.722483Z","end":"2026-07-27T20:57:22.946045Z","steps":["trace[997180108] 'agreement among raft nodes before linearized reading' (duration: 223.378766ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:57:23.239848Z","caller":"traceutil/trace.go:171","msg":"trace[630775022] linearizableReadLoop","detail":"{readStateIndex:26383; appliedIndex:26382; }","duration":"272.538105ms","start":"2026-07-27T20:57:22.967279Z","end":"2026-07-27T20:57:23.239817Z","steps":["trace[630775022] 'read index received' (duration: 176.187224ms)","trace[630775022] 'applied index is now lower than readState.Index' (duration: 96.349221ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:57:23.240037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.748749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-27T20:57:23.240104Z","caller":"traceutil/trace.go:171","msg":"trace[1298637657] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25549; }","duration":"272.865181ms","start":"2026-07-27T20:57:22.967225Z","end":"2026-07-27T20:57:23.24009Z","steps":["trace[1298637657] 'agreement among raft nodes before linearized reading' (duration: 272.670617ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:57:23.240038Z","caller":"traceutil/trace.go:171","msg":"trace[1531162256] transaction","detail":"{read_only:false; response_revision:25549; number_of_response:1; }","duration":"289.308505ms","start":"2026-07-27T20:57:22.950519Z","end":"2026-07-27T20:57:23.239828Z","steps":["trace[1531162256] 'process raft request' (duration: 192.901504ms)","trace[1531162256] 'compare' (duration: 95.963903ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:57:23.60904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.729243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:57:23.609242Z","caller":"traceutil/trace.go:171","msg":"trace[1736941022] transaction","detail":"{read_only:false; response_revision:25550; number_of_response:1; }","duration":"564.963241ms","start":"2026-07-27T20:57:23.044244Z","end":"2026-07-27T20:57:23.609207Z","steps":["trace[1736941022] 'process raft request' (duration: 405.970023ms)","trace[1736941022] 'compare' (duration: 158.58945ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:57:23.609297Z","caller":"traceutil/trace.go:171","msg":"trace[980007118] transaction","detail":"{read_only:false; response_revision:25553; number_of_response:1; }","duration":"364.694675ms","start":"2026-07-27T20:57:23.244584Z","end":"2026-07-27T20:57:23.609278Z","steps":["trace[980007118] 'process raft request' (duration: 364.652464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:57:23.609392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:57:23.244567Z","time spent":"364.762657ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:57:23.609403Z","caller":"traceutil/trace.go:171","msg":"trace[1292174696] transaction","detail":"{read_only:false; response_revision:25551; number_of_response:1; }","duration":"484.018096ms","start":"2026-07-27T20:57:23.125347Z","end":"2026-07-27T20:57:23.609365Z","steps":["trace[1292174696] 'process raft request' (duration: 483.803692ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:57:23.609502Z","caller":"traceutil/trace.go:171","msg":"trace[1620189384] transaction","detail":"{read_only:false; response_revision:25552; number_of_response:1; }","duration":"479.102131ms","start":"2026-07-27T20:57:23.130393Z","end":"2026-07-27T20:57:23.609495Z","steps":["trace[1620189384] 'process raft request' (duration: 478.793885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:57:23.609537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:57:23.13036Z","time spent":"479.155742ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:57:23.609574Z","caller":"traceutil/trace.go:171","msg":"trace[869046556] linearizableReadLoop","detail":"{readStateIndex:26386; appliedIndex:26383; }","duration":"369.62063ms","start":"2026-07-27T20:57:23.239914Z","end":"2026-07-27T20:57:23.609535Z","steps":["trace[869046556] 'read index received' (duration: 210.284366ms)","trace[869046556] 'applied index is now lower than readState.Index' (duration: 159.328794ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:57:23.609595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:57:23.125308Z","time spent":"484.185278ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:57:23.609759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.90604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-27T20:57:23.609744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.487348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-27T20:57:23.609791Z","caller":"traceutil/trace.go:171","msg":"trace[773339185] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:25553; }","duration":"342.007002ms","start":"2026-07-27T20:57:23.267773Z","end":"2026-07-27T20:57:23.60978Z","steps":["trace[773339185] 'agreement among raft nodes before linearized reading' (duration: 341.922581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:57:23.609792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.057109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:57:23.609801Z","caller":"traceutil/trace.go:171","msg":"trace[1747932718] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25553; }","duration":"557.62099ms","start":"2026-07-27T20:57:23.052167Z","end":"2026-07-27T20:57:23.609788Z","steps":["trace[1747932718] 'agreement among raft nodes before linearized reading' (duration: 557.410087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:57:23.609814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:57:23.267741Z","time spent":"342.067873ms","remote":"127.0.0.1:59074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true "} {"level":"info","ts":"2026-07-27T20:57:23.609821Z","caller":"traceutil/trace.go:171","msg":"trace[87214534] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:25553; }","duration":"135.139941ms","start":"2026-07-27T20:57:23.47467Z","end":"2026-07-27T20:57:23.60981Z","steps":["trace[87214534] 'agreement among raft nodes before linearized reading' (duration: 135.08428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:57:23.609837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:57:23.052158Z","time spent":"557.671791ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-27T20:57:23.609886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.637966ms","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-27T20:57:23.609975Z","caller":"traceutil/trace.go:171","msg":"trace[477956397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25553; }","duration":"608.740808ms","start":"2026-07-27T20:57:23.001214Z","end":"2026-07-27T20:57:23.609955Z","steps":["trace[477956397] 'agreement among raft nodes before linearized reading' (duration: 608.457151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:57:23.609979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.348111ms","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-27T20:57:23.609995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.529924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-27T20:57:23.610021Z","caller":"traceutil/trace.go:171","msg":"trace[1666752058] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25553; }","duration":"307.624116ms","start":"2026-07-27T20:57:23.30239Z","end":"2026-07-27T20:57:23.610014Z","steps":["trace[1666752058] 'agreement among raft nodes before linearized reading' (duration: 307.568235ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:57:23.610024Z","caller":"traceutil/trace.go:171","msg":"trace[1069013950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25553; }","duration":"240.418303ms","start":"2026-07-27T20:57:23.369594Z","end":"2026-07-27T20:57:23.610012Z","steps":["trace[1069013950] 'agreement among raft nodes before linearized reading' (duration: 240.26297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:57:23.610027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:57:23.001194Z","time spent":"608.82322ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-27T20:57:23.610044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:57:23.30237Z","time spent":"307.668687ms","remote":"127.0.0.1:44466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:57:23.610357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:57:23.044214Z","time spent":"565.099084ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:57:23.81907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.699294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:57:23.819243Z","caller":"traceutil/trace.go:171","msg":"trace[648674450] linearizableReadLoop","detail":"{readStateIndex:26388; appliedIndex:26387; }","duration":"204.470606ms","start":"2026-07-27T20:57:23.614705Z","end":"2026-07-27T20:57:23.819176Z","steps":["trace[648674450] 'read index received' (duration: 103.592899ms)","trace[648674450] 'applied index is now lower than readState.Index' (duration: 100.876457ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:57:23.819271Z","caller":"traceutil/trace.go:171","msg":"trace[1186190262] transaction","detail":"{read_only:false; response_revision:25554; number_of_response:1; }","duration":"205.402883ms","start":"2026-07-27T20:57:23.613816Z","end":"2026-07-27T20:57:23.819219Z","steps":["trace[1186190262] 'process raft request' (duration: 104.466796ms)","trace[1186190262] 'compare' (duration: 99.590062ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:57:23.819368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.633778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:57:23.819493Z","caller":"traceutil/trace.go:171","msg":"trace[1760700992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25554; }","duration":"204.809162ms","start":"2026-07-27T20:57:23.614654Z","end":"2026-07-27T20:57:23.819463Z","steps":["trace[1760700992] 'agreement among raft nodes before linearized reading' (duration: 204.623608ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:57:23.840746Z","caller":"traceutil/trace.go:171","msg":"trace[856850199] transaction","detail":"{read_only:false; response_revision:25555; number_of_response:1; }","duration":"225.805603ms","start":"2026-07-27T20:57:23.614917Z","end":"2026-07-27T20:57:23.840723Z","steps":["trace[856850199] 'process raft request' (duration: 225.630199ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:57:23.840919Z","caller":"traceutil/trace.go:171","msg":"trace[1887614594] transaction","detail":"{read_only:false; response_revision:25556; number_of_response:1; }","duration":"224.54479ms","start":"2026-07-27T20:57:23.616344Z","end":"2026-07-27T20:57:23.840889Z","steps":["trace[1887614594] 'process raft request' (duration: 224.339605ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:57:23.979985Z","caller":"traceutil/trace.go:171","msg":"trace[302006403] transaction","detail":"{read_only:false; response_revision:25557; number_of_response:1; }","duration":"230.375921ms","start":"2026-07-27T20:57:23.749574Z","end":"2026-07-27T20:57:23.97995Z","steps":["trace[302006403] 'process raft request' (duration: 226.15322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:57:44.804427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.844465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:57:44.804497Z","caller":"traceutil/trace.go:171","msg":"trace[428312038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25716; }","duration":"246.955657ms","start":"2026-07-27T20:57:44.557527Z","end":"2026-07-27T20:57:44.804482Z","steps":["trace[428312038] 'range keys from in-memory index tree' (duration: 246.752242ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:57:44.805592Z","caller":"traceutil/trace.go:171","msg":"trace[81619116] transaction","detail":"{read_only:false; response_revision:25717; number_of_response:1; }","duration":"204.514346ms","start":"2026-07-27T20:57:44.601055Z","end":"2026-07-27T20:57:44.80557Z","steps":["trace[81619116] 'process raft request' (duration: 196.729707ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:57:45.100668Z","caller":"traceutil/trace.go:171","msg":"trace[1151059281] linearizableReadLoop","detail":"{readStateIndex:26557; appliedIndex:26555; }","duration":"294.233911ms","start":"2026-07-27T20:57:44.806412Z","end":"2026-07-27T20:57:45.100646Z","steps":["trace[1151059281] 'read index received' (duration: 259.224632ms)","trace[1151059281] 'applied index is now lower than readState.Index' (duration: 35.008249ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:57:45.100874Z","caller":"traceutil/trace.go:171","msg":"trace[591564504] transaction","detail":"{read_only:false; response_revision:25719; number_of_response:1; }","duration":"371.133948ms","start":"2026-07-27T20:57:44.729725Z","end":"2026-07-27T20:57:45.100859Z","steps":["trace[591564504] 'process raft request' (duration: 370.859643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:57:45.101009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:57:44.729699Z","time spent":"371.233261ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:57:45.100857Z","caller":"traceutil/trace.go:171","msg":"trace[1935493444] transaction","detail":"{read_only:false; response_revision:25718; number_of_response:1; }","duration":"431.472762ms","start":"2026-07-27T20:57:44.669346Z","end":"2026-07-27T20:57:45.100819Z","steps":["trace[1935493444] 'process raft request' (duration: 396.336051ms)","trace[1935493444] 'compare' (duration: 34.530879ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:57:45.101426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:57:44.669319Z","time spent":"432.067022ms","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:57:45.101578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.144387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:57:45.101636Z","caller":"traceutil/trace.go:171","msg":"trace[574625364] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25719; }","duration":"295.233569ms","start":"2026-07-27T20:57:44.806386Z","end":"2026-07-27T20:57:45.10162Z","steps":["trace[574625364] 'agreement among raft nodes before linearized reading' (duration: 295.139848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:57:45.387938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.384751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:57:45.388118Z","caller":"traceutil/trace.go:171","msg":"trace[1417732597] transaction","detail":"{read_only:false; response_revision:25720; number_of_response:1; }","duration":"277.052902ms","start":"2026-07-27T20:57:45.111041Z","end":"2026-07-27T20:57:45.388093Z","steps":["trace[1417732597] 'process raft request' (duration: 135.406576ms)","trace[1417732597] 'compare' (duration: 141.167817ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:57:45.543629Z","caller":"traceutil/trace.go:171","msg":"trace[1344602328] linearizableReadLoop","detail":"{readStateIndex:26559; appliedIndex:26558; }","duration":"103.930885ms","start":"2026-07-27T20:57:45.439673Z","end":"2026-07-27T20:57:45.543604Z","steps":["trace[1344602328] 'read index received' (duration: 88.687464ms)","trace[1344602328] 'applied index is now lower than readState.Index' (duration: 15.242451ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:57:45.543803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.102288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:57:45.543846Z","caller":"traceutil/trace.go:171","msg":"trace[1773273176] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:25721; }","duration":"104.167439ms","start":"2026-07-27T20:57:45.439666Z","end":"2026-07-27T20:57:45.543833Z","steps":["trace[1773273176] 'agreement among raft nodes before linearized reading' (duration: 104.030647ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:57:45.54391Z","caller":"traceutil/trace.go:171","msg":"trace[1022842045] transaction","detail":"{read_only:false; response_revision:25721; number_of_response:1; }","duration":"151.746108ms","start":"2026-07-27T20:57:45.392127Z","end":"2026-07-27T20:57:45.543873Z","steps":["trace[1022842045] 'process raft request' (duration: 136.272203ms)","trace[1022842045] 'compare' (duration: 14.889814ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:58:29.247133Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23396} {"level":"info","ts":"2026-07-27T20:58:29.378845Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23396,"took":"127.146762ms","hash":3188735896,"current-db-size-bytes":58347520,"current-db-size":"58 MB","current-db-size-in-use-bytes":36200448,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-27T20:58:29.378919Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3188735896,"revision":23396,"compact-revision":20202} {"level":"info","ts":"2026-07-27T21:03:29.254294Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26217} {"level":"info","ts":"2026-07-27T21:03:29.406178Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26217,"took":"148.202004ms","hash":1799728593,"current-db-size-bytes":58769408,"current-db-size":"59 MB","current-db-size-in-use-bytes":35160064,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-27T21:03:29.406249Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1799728593,"revision":26217,"compact-revision":23396} {"level":"info","ts":"2026-07-27T21:03:42.469859Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f20dfa43a2e4607","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-27T21:03:42.474789Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-27T21:03:42.474888Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-27T21:08:29.261148Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28978} {"level":"info","ts":"2026-07-27T21:08:29.409607Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28978,"took":"144.109092ms","hash":1548002977,"current-db-size-bytes":58769408,"current-db-size":"59 MB","current-db-size-in-use-bytes":31698944,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-27T21:08:29.409698Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1548002977,"revision":28978,"compact-revision":26217} {"level":"info","ts":"2026-07-27T21:13:29.267845Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31231} {"level":"info","ts":"2026-07-27T21:13:29.397826Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31231,"took":"125.274409ms","hash":1758529960,"current-db-size-bytes":58769408,"current-db-size":"59 MB","current-db-size-in-use-bytes":31621120,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-27T21:13:29.397891Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1758529960,"revision":31231,"compact-revision":28978} {"level":"info","ts":"2026-07-27T21:18:29.274045Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33500} {"level":"info","ts":"2026-07-27T21:18:29.422626Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33500,"took":"143.80338ms","hash":97973758,"current-db-size-bytes":60235776,"current-db-size":"60 MB","current-db-size-in-use-bytes":32509952,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-27T21:18:29.422687Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":97973758,"revision":33500,"compact-revision":31231} {"level":"info","ts":"2026-07-27T21:23:29.28233Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35793} {"level":"info","ts":"2026-07-27T21:23:29.427557Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35793,"took":"140.29354ms","hash":3923451741,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":31928320,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-27T21:23:29.427653Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3923451741,"revision":35793,"compact-revision":33500} {"level":"info","ts":"2026-07-27T21:25:06.515834Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f20dfa43a2e4607","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-27T21:25:06.520488Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-27T21:25:06.520728Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-27T21:28:29.288699Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38023} {"level":"info","ts":"2026-07-27T21:28:29.404931Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38023,"took":"110.921157ms","hash":2442299446,"current-db-size-bytes":61542400,"current-db-size":"62 MB","current-db-size-in-use-bytes":32673792,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-27T21:28:29.404986Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2442299446,"revision":38023,"compact-revision":35793}