{"level":"warn","ts":"2026-08-05T19:28:45.118293Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T19:28:45.118374Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.61:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.61:2380","--initial-cluster=instance=https://199.204.45.61:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.61:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.61: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-08-05T19:28:45.118458Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-05T19:28:45.118473Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.61:2380"]} {"level":"info","ts":"2026-08-05T19:28:45.118506Z","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-08-05T19:28:45.119116Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.61:2379"]} {"level":"info","ts":"2026-08-05T19:28:45.119207Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.61:2380"],"listen-peer-urls":["https://199.204.45.61:2380"],"advertise-client-urls":["https://199.204.45.61:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.61:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.61: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-08-05T19:28:45.122018Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.574831ms"} {"level":"info","ts":"2026-08-05T19:28:45.126866Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ca256638ea38eb43","cluster-id":"dfd7ac400ae20102"} {"level":"info","ts":"2026-08-05T19:28:45.126966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 switched to configuration voters=()"} {"level":"info","ts":"2026-08-05T19:28:45.126999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became follower at term 0"} {"level":"info","ts":"2026-08-05T19:28:45.127019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ca256638ea38eb43 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-05T19:28:45.127043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became follower at term 1"} {"level":"info","ts":"2026-08-05T19:28:45.127086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 switched to configuration voters=(14566160964433537859)"} {"level":"warn","ts":"2026-08-05T19:28:45.131656Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-05T19:28:45.132962Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-05T19:28:45.13417Z","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-08-05T19:28:45.137783Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ca256638ea38eb43","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-05T19:28:45.13797Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ca256638ea38eb43","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-05T19:28:45.138076Z","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-08-05T19:28:45.138162Z","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-08-05T19:28:45.138175Z","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-08-05T19:28:45.140192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 switched to configuration voters=(14566160964433537859)"} {"level":"info","ts":"2026-08-05T19:28:45.140761Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfd7ac400ae20102","local-member-id":"ca256638ea38eb43","added-peer-id":"ca256638ea38eb43","added-peer-peer-urls":["https://199.204.45.61:2380"]} {"level":"info","ts":"2026-08-05T19:28:45.145108Z","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-08-05T19:28:45.145411Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ca256638ea38eb43","initial-advertise-peer-urls":["https://199.204.45.61:2380"],"listen-peer-urls":["https://199.204.45.61:2380"],"advertise-client-urls":["https://199.204.45.61:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.61:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-05T19:28:45.145443Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-05T19:28:45.145505Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.61:2380"} {"level":"info","ts":"2026-08-05T19:28:45.145514Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.61:2380"} {"level":"info","ts":"2026-08-05T19:28:45.827812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 is starting a new election at term 1"} {"level":"info","ts":"2026-08-05T19:28:45.8279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-05T19:28:45.828031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 received MsgPreVoteResp from ca256638ea38eb43 at term 1"} {"level":"info","ts":"2026-08-05T19:28:45.828074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became candidate at term 2"} {"level":"info","ts":"2026-08-05T19:28:45.828141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 received MsgVoteResp from ca256638ea38eb43 at term 2"} {"level":"info","ts":"2026-08-05T19:28:45.828228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became leader at term 2"} {"level":"info","ts":"2026-08-05T19:28:45.828256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ca256638ea38eb43 elected leader ca256638ea38eb43 at term 2"} {"level":"info","ts":"2026-08-05T19:28:45.830506Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ca256638ea38eb43","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.61:2379]}","request-path":"/0/members/ca256638ea38eb43/attributes","cluster-id":"dfd7ac400ae20102","publish-timeout":"7s"} {"level":"info","ts":"2026-08-05T19:28:45.830513Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T19:28:45.830567Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T19:28:45.830515Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T19:28:45.83085Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-05T19:28:45.830901Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-05T19:28:45.831447Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfd7ac400ae20102","local-member-id":"ca256638ea38eb43","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T19:28:45.831519Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T19:28:45.831544Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T19:28:45.832714Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-05T19:28:45.834094Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.61:2379"} {"level":"info","ts":"2026-08-05T19:29:01.795159Z","caller":"traceutil/trace.go:171","msg":"trace[96233766] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"183.135288ms","start":"2026-08-05T19:29:01.611997Z","end":"2026-08-05T19:29:01.795133Z","steps":["trace[96233766] 'process raft request' (duration: 182.824639ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:29:07.246742Z","caller":"traceutil/trace.go:171","msg":"trace[1400461155] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"140.685914ms","start":"2026-08-05T19:29:07.10603Z","end":"2026-08-05T19:29:07.246716Z","steps":["trace[1400461155] 'process raft request' (duration: 140.532739ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:29:07.392512Z","caller":"traceutil/trace.go:171","msg":"trace[1688749690] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"125.802659ms","start":"2026-08-05T19:29:07.26668Z","end":"2026-08-05T19:29:07.392483Z","steps":["trace[1688749690] 'process raft request' (duration: 125.541821ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:29:12.530795Z","caller":"traceutil/trace.go:171","msg":"trace[905497531] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"118.046392ms","start":"2026-08-05T19:29:12.412723Z","end":"2026-08-05T19:29:12.53077Z","steps":["trace[905497531] 'process raft request' (duration: 117.906008ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:29:21.495081Z","caller":"traceutil/trace.go:171","msg":"trace[1861002502] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"178.811297ms","start":"2026-08-05T19:29:21.316243Z","end":"2026-08-05T19:29:21.495054Z","steps":["trace[1861002502] 'process raft request' (duration: 178.675813ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:29:21.566013Z","caller":"traceutil/trace.go:171","msg":"trace[1589626615] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"247.303377ms","start":"2026-08-05T19:29:21.31865Z","end":"2026-08-05T19:29:21.565953Z","steps":["trace[1589626615] 'process raft request' (duration: 246.939617ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:29:21.566282Z","caller":"traceutil/trace.go:171","msg":"trace[1045001294] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"148.309466ms","start":"2026-08-05T19:29:21.417956Z","end":"2026-08-05T19:29:21.566265Z","steps":["trace[1045001294] 'process raft request' (duration: 148.214793ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:29:21.566318Z","caller":"traceutil/trace.go:171","msg":"trace[422372207] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"247.554684ms","start":"2026-08-05T19:29:21.318729Z","end":"2026-08-05T19:29:21.566284Z","steps":["trace[422372207] 'process raft request' (duration: 247.121042ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:29:27.558201Z","caller":"traceutil/trace.go:171","msg":"trace[1094037120] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"118.465624ms","start":"2026-08-05T19:29:27.43971Z","end":"2026-08-05T19:29:27.558176Z","steps":["trace[1094037120] 'process raft request' (duration: 118.294619ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:29:27.667323Z","caller":"traceutil/trace.go:171","msg":"trace[1203828194] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"117.61283ms","start":"2026-08-05T19:29:27.549685Z","end":"2026-08-05T19:29:27.667298Z","steps":["trace[1203828194] 'process raft request' (duration: 116.52987ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:29:28.019859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.898988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T19:29:28.020121Z","caller":"traceutil/trace.go:171","msg":"trace[1027580319] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"281.998433ms","start":"2026-08-05T19:29:27.738105Z","end":"2026-08-05T19:29:28.020103Z","steps":["trace[1027580319] 'process raft request' (duration: 281.933492ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:29:28.020143Z","caller":"traceutil/trace.go:171","msg":"trace[1509628934] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"448.206239ms","start":"2026-08-05T19:29:27.571874Z","end":"2026-08-05T19:29:28.02008Z","steps":["trace[1509628934] 'process raft request' (duration: 225.453587ms)","trace[1509628934] 'compare' (duration: 221.775974ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T19:29:28.020197Z","caller":"traceutil/trace.go:171","msg":"trace[963967424] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"314.213072ms","start":"2026-08-05T19:29:27.705949Z","end":"2026-08-05T19:29:28.020162Z","steps":["trace[963967424] 'read index received' (duration: 91.371048ms)","trace[963967424] 'applied index is now lower than readState.Index' (duration: 222.837694ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T19:29:28.0204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.440889ms","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-08-05T19:29:28.02036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T19:29:27.571853Z","time spent":"448.393494ms","remote":"127.0.0.1:36950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T19:29:28.020441Z","caller":"traceutil/trace.go:171","msg":"trace[799434402] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"314.531971ms","start":"2026-08-05T19:29:27.705899Z","end":"2026-08-05T19:29:28.020431Z","steps":["trace[799434402] 'agreement among raft nodes before linearized reading' (duration: 314.359387ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:29:28.020473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T19:29:27.705877Z","time spent":"314.587462ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-05T19:29:28.692631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.649315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T19:29:28.692771Z","caller":"traceutil/trace.go:171","msg":"trace[1058666280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"494.804159ms","start":"2026-08-05T19:29:28.197942Z","end":"2026-08-05T19:29:28.692746Z","steps":["trace[1058666280] 'range keys from in-memory index tree' (duration: 494.562003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:29:28.692826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T19:29:28.197888Z","time spent":"494.923842ms","remote":"127.0.0.1:36774","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-08-05T19:29:28.693195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.698941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T19:29:28.693457Z","caller":"traceutil/trace.go:171","msg":"trace[388816680] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"337.998876ms","start":"2026-08-05T19:29:28.355425Z","end":"2026-08-05T19:29:28.693424Z","steps":["trace[388816680] 'process raft request' (duration: 105.978176ms)","trace[388816680] 'compare' (duration: 231.533057ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T19:29:28.693654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T19:29:28.355407Z","time spent":"338.151691ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T19:29:28.694248Z","caller":"traceutil/trace.go:171","msg":"trace[1638984754] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"246.640758ms","start":"2026-08-05T19:29:28.447573Z","end":"2026-08-05T19:29:28.694214Z","steps":["trace[1638984754] 'process raft request' (duration: 246.011631ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:30:34.08429Z","caller":"traceutil/trace.go:171","msg":"trace[1575338428] linearizableReadLoop","detail":"{readStateIndex:1488; appliedIndex:1487; }","duration":"129.175676ms","start":"2026-08-05T19:30:33.955088Z","end":"2026-08-05T19:30:34.084264Z","steps":["trace[1575338428] 'read index received' (duration: 128.906398ms)","trace[1575338428] 'applied index is now lower than readState.Index' (duration: 268.408µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T19:30:34.084522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.418182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-05T19:30:34.084573Z","caller":"traceutil/trace.go:171","msg":"trace[206349326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1443; }","duration":"129.523465ms","start":"2026-08-05T19:30:33.95504Z","end":"2026-08-05T19:30:34.084563Z","steps":["trace[206349326] 'agreement among raft nodes before linearized reading' (duration: 129.33907ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:30:34.084913Z","caller":"traceutil/trace.go:171","msg":"trace[518898299] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"142.640096ms","start":"2026-08-05T19:30:33.942258Z","end":"2026-08-05T19:30:34.084898Z","steps":["trace[518898299] 'process raft request' (duration: 141.845604ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:30:57.868329Z","caller":"traceutil/trace.go:171","msg":"trace[745712485] linearizableReadLoop","detail":"{readStateIndex:1929; appliedIndex:1928; }","duration":"102.514521ms","start":"2026-08-05T19:30:57.76579Z","end":"2026-08-05T19:30:57.868305Z","steps":["trace[745712485] 'read index received' (duration: 102.355987ms)","trace[745712485] 'applied index is now lower than readState.Index' (duration: 157.124µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T19:30:57.868475Z","caller":"traceutil/trace.go:171","msg":"trace[401112337] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"235.0811ms","start":"2026-08-05T19:30:57.633341Z","end":"2026-08-05T19:30:57.868422Z","steps":["trace[401112337] 'process raft request' (duration: 234.807933ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:30:57.868643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.8359ms","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-08-05T19:30:57.868705Z","caller":"traceutil/trace.go:171","msg":"trace[925051565] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1857; }","duration":"102.963614ms","start":"2026-08-05T19:30:57.765726Z","end":"2026-08-05T19:30:57.86869Z","steps":["trace[925051565] 'agreement among raft nodes before linearized reading' (duration: 102.681606ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:30:58.24995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.775057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-48ee9210-a55f-436c-86fd-c45dbffa66f0\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-08-05T19:30:58.249968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.544574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T19:30:58.250051Z","caller":"traceutil/trace.go:171","msg":"trace[1383818540] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-48ee9210-a55f-436c-86fd-c45dbffa66f0; range_end:; response_count:1; response_revision:1858; }","duration":"228.89916ms","start":"2026-08-05T19:30:58.021131Z","end":"2026-08-05T19:30:58.25003Z","steps":["trace[1383818540] 'range keys from in-memory index tree' (duration: 228.574332ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:30:58.250066Z","caller":"traceutil/trace.go:171","msg":"trace[838295948] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1858; }","duration":"259.717279ms","start":"2026-08-05T19:30:57.990329Z","end":"2026-08-05T19:30:58.250046Z","steps":["trace[838295948] 'count revisions from in-memory index tree' (duration: 259.430901ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:30:58.249989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.607124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T19:30:58.250196Z","caller":"traceutil/trace.go:171","msg":"trace[542976109] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1858; }","duration":"179.849701ms","start":"2026-08-05T19:30:58.070308Z","end":"2026-08-05T19:30:58.250158Z","steps":["trace[542976109] 'count revisions from in-memory index tree' (duration: 179.484101ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:30:58.336952Z","caller":"traceutil/trace.go:171","msg":"trace[539427143] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"126.323167ms","start":"2026-08-05T19:30:58.210596Z","end":"2026-08-05T19:30:58.33692Z","steps":["trace[539427143] 'process raft request' (duration: 125.932266ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:31:04.388835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.588159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T19:31:04.388949Z","caller":"traceutil/trace.go:171","msg":"trace[1503805150] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1925; }","duration":"100.735712ms","start":"2026-08-05T19:31:04.288191Z","end":"2026-08-05T19:31:04.388927Z","steps":["trace[1503805150] 'agreement among raft nodes before linearized reading' (duration: 100.534847ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:31:48.318039Z","caller":"traceutil/trace.go:171","msg":"trace[2035933509] linearizableReadLoop","detail":"{readStateIndex:2261; appliedIndex:2260; }","duration":"120.212823ms","start":"2026-08-05T19:31:48.197793Z","end":"2026-08-05T19:31:48.318006Z","steps":["trace[2035933509] 'read index received' (duration: 119.886224ms)","trace[2035933509] 'applied index is now lower than readState.Index' (duration: 325.019µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T19:31:48.318113Z","caller":"traceutil/trace.go:171","msg":"trace[1353550886] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"188.145052ms","start":"2026-08-05T19:31:48.129932Z","end":"2026-08-05T19:31:48.318077Z","steps":["trace[1353550886] 'process raft request' (duration: 187.866394ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:31:48.318309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.489451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T19:31:48.318387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.787931ms","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-08-05T19:31:48.318436Z","caller":"traceutil/trace.go:171","msg":"trace[1240573158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2178; }","duration":"114.882615ms","start":"2026-08-05T19:31:48.20354Z","end":"2026-08-05T19:31:48.318423Z","steps":["trace[1240573158] 'agreement among raft nodes before linearized reading' (duration: 114.671739ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:31:48.318465Z","caller":"traceutil/trace.go:171","msg":"trace[1787710021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2178; }","duration":"120.639095ms","start":"2026-08-05T19:31:48.197767Z","end":"2026-08-05T19:31:48.318406Z","steps":["trace[1787710021] 'agreement among raft nodes before linearized reading' (duration: 120.405899ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:31:48.721027Z","caller":"traceutil/trace.go:171","msg":"trace[28680705] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"152.85346ms","start":"2026-08-05T19:31:48.568146Z","end":"2026-08-05T19:31:48.721Z","steps":["trace[28680705] 'process raft request' (duration: 152.659935ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:31:48.917446Z","caller":"traceutil/trace.go:171","msg":"trace[2021874588] linearizableReadLoop","detail":"{readStateIndex:2264; appliedIndex:2262; }","duration":"322.374423ms","start":"2026-08-05T19:31:48.59504Z","end":"2026-08-05T19:31:48.917415Z","steps":["trace[2021874588] 'read index received' (duration: 125.792777ms)","trace[2021874588] 'applied index is now lower than readState.Index' (duration: 196.580396ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T19:31:48.917607Z","caller":"traceutil/trace.go:171","msg":"trace[1505370902] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"342.557835ms","start":"2026-08-05T19:31:48.575037Z","end":"2026-08-05T19:31:48.917595Z","steps":["trace[1505370902] 'process raft request' (duration: 313.60668ms)","trace[1505370902] 'compare' (duration: 28.359999ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T19:31:48.917726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T19:31:48.575022Z","time spent":"342.615976ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T19:31:48.917883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.65783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.61\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-08-05T19:31:48.917878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.829786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-05T19:31:48.917939Z","caller":"traceutil/trace.go:171","msg":"trace[412696930] range","detail":"{range_begin:/registry/masterleases/199.204.45.61; range_end:; response_count:1; response_revision:2181; }","duration":"100.775182ms","start":"2026-08-05T19:31:48.817151Z","end":"2026-08-05T19:31:48.917926Z","steps":["trace[412696930] 'agreement among raft nodes before linearized reading' (duration: 100.582367ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:31:48.917952Z","caller":"traceutil/trace.go:171","msg":"trace[999701814] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2181; }","duration":"322.913189ms","start":"2026-08-05T19:31:48.595022Z","end":"2026-08-05T19:31:48.917935Z","steps":["trace[999701814] 'agreement among raft nodes before linearized reading' (duration: 322.723953ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:31:48.917991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T19:31:48.594946Z","time spent":"323.037112ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-05T19:31:48.917997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.169143ms","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-08-05T19:31:48.918093Z","caller":"traceutil/trace.go:171","msg":"trace[770466912] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2181; }","duration":"256.317256ms","start":"2026-08-05T19:31:48.661752Z","end":"2026-08-05T19:31:48.91807Z","steps":["trace[770466912] 'agreement among raft nodes before linearized reading' (duration: 255.962066ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:31:48.918132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.47484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-05T19:31:48.918155Z","caller":"traceutil/trace.go:171","msg":"trace[304325248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2181; }","duration":"179.530822ms","start":"2026-08-05T19:31:48.738617Z","end":"2026-08-05T19:31:48.918147Z","steps":["trace[304325248] 'agreement among raft nodes before linearized reading' (duration: 179.47085ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:33:17.286628Z","caller":"traceutil/trace.go:171","msg":"trace[298019697] transaction","detail":"{read_only:false; response_revision:2852; number_of_response:1; }","duration":"119.783703ms","start":"2026-08-05T19:33:17.166817Z","end":"2026-08-05T19:33:17.286601Z","steps":["trace[298019697] 'process raft request' (duration: 119.433314ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:33:17.352444Z","caller":"traceutil/trace.go:171","msg":"trace[599314039] transaction","detail":"{read_only:false; response_revision:2853; number_of_response:1; }","duration":"131.969123ms","start":"2026-08-05T19:33:17.220461Z","end":"2026-08-05T19:33:17.35243Z","steps":["trace[599314039] 'process raft request' (duration: 114.538191ms)","trace[599314039] 'compare' (duration: 17.179675ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T19:33:17.824022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.701717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-05T19:33:17.824129Z","caller":"traceutil/trace.go:171","msg":"trace[1042246578] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2857; }","duration":"151.853052ms","start":"2026-08-05T19:33:17.672257Z","end":"2026-08-05T19:33:17.82411Z","steps":["trace[1042246578] 'range keys from in-memory index tree' (duration: 151.520063ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:35:48.259486Z","caller":"traceutil/trace.go:171","msg":"trace[1057779026] transaction","detail":"{read_only:false; response_revision:3851; number_of_response:1; }","duration":"134.862514ms","start":"2026-08-05T19:35:48.124599Z","end":"2026-08-05T19:35:48.259461Z","steps":["trace[1057779026] 'process raft request' (duration: 134.634328ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:35:48.259524Z","caller":"traceutil/trace.go:171","msg":"trace[52781506] transaction","detail":"{read_only:false; response_revision:3852; number_of_response:1; }","duration":"134.891574ms","start":"2026-08-05T19:35:48.124609Z","end":"2026-08-05T19:35:48.2595Z","steps":["trace[52781506] 'process raft request' (duration: 134.777011ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:37:13.180417Z","caller":"traceutil/trace.go:171","msg":"trace[438020247] linearizableReadLoop","detail":"{readStateIndex:5268; appliedIndex:5266; }","duration":"123.875595ms","start":"2026-08-05T19:37:13.056514Z","end":"2026-08-05T19:37:13.180389Z","steps":["trace[438020247] 'read index received' (duration: 24.120274ms)","trace[438020247] 'applied index is now lower than readState.Index' (duration: 99.753761ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T19:37:13.180643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.106021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-ce633ac9-fa4d-4882-8bca-9a28c0dada35\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-08-05T19:37:13.180594Z","caller":"traceutil/trace.go:171","msg":"trace[2143101812] transaction","detail":"{read_only:false; response_revision:5080; number_of_response:1; }","duration":"126.805674ms","start":"2026-08-05T19:37:13.053741Z","end":"2026-08-05T19:37:13.180546Z","steps":["trace[2143101812] 'process raft request' (duration: 121.981654ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:37:13.180696Z","caller":"traceutil/trace.go:171","msg":"trace[1955920940] range","detail":"{range_begin:/registry/persistentvolumes/pvc-ce633ac9-fa4d-4882-8bca-9a28c0dada35; range_end:; response_count:1; response_revision:5081; }","duration":"124.184233ms","start":"2026-08-05T19:37:13.056496Z","end":"2026-08-05T19:37:13.180681Z","steps":["trace[1955920940] 'agreement among raft nodes before linearized reading' (duration: 124.005018ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:37:13.180716Z","caller":"traceutil/trace.go:171","msg":"trace[1238853856] transaction","detail":"{read_only:false; response_revision:5081; number_of_response:1; }","duration":"117.348968ms","start":"2026-08-05T19:37:13.063333Z","end":"2026-08-05T19:37:13.180682Z","steps":["trace[1238853856] 'process raft request' (duration: 116.967148ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:37:25.270989Z","caller":"traceutil/trace.go:171","msg":"trace[1881016614] transaction","detail":"{read_only:false; response_revision:5285; number_of_response:1; }","duration":"150.236049ms","start":"2026-08-05T19:37:25.120694Z","end":"2026-08-05T19:37:25.27093Z","steps":["trace[1881016614] 'process raft request' (duration: 149.849599ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:37:32.293208Z","caller":"traceutil/trace.go:171","msg":"trace[232080752] transaction","detail":"{read_only:false; response_revision:5441; number_of_response:1; }","duration":"108.15132ms","start":"2026-08-05T19:37:32.185026Z","end":"2026-08-05T19:37:32.293178Z","steps":["trace[232080752] 'process raft request' (duration: 107.905834ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:37:34.393543Z","caller":"traceutil/trace.go:171","msg":"trace[1925840106] transaction","detail":"{read_only:false; response_revision:5481; number_of_response:1; }","duration":"112.895128ms","start":"2026-08-05T19:37:34.280609Z","end":"2026-08-05T19:37:34.393504Z","steps":["trace[1925840106] 'process raft request' (duration: 108.667433ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:37:34.394093Z","caller":"traceutil/trace.go:171","msg":"trace[1834921012] transaction","detail":"{read_only:false; response_revision:5482; number_of_response:1; }","duration":"113.345361ms","start":"2026-08-05T19:37:34.280709Z","end":"2026-08-05T19:37:34.394054Z","steps":["trace[1834921012] 'process raft request' (duration: 112.730884ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:37:34.394148Z","caller":"traceutil/trace.go:171","msg":"trace[1587601823] transaction","detail":"{read_only:false; response_revision:5483; number_of_response:1; }","duration":"112.709923ms","start":"2026-08-05T19:37:34.281427Z","end":"2026-08-05T19:37:34.394137Z","steps":["trace[1587601823] 'process raft request' (duration: 112.529018ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:37:34.394361Z","caller":"traceutil/trace.go:171","msg":"trace[796820622] transaction","detail":"{read_only:false; response_revision:5484; number_of_response:1; }","duration":"112.678182ms","start":"2026-08-05T19:37:34.281628Z","end":"2026-08-05T19:37:34.394306Z","steps":["trace[796820622] 'process raft request' (duration: 112.444246ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:38:45.864678Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3013} {"level":"info","ts":"2026-08-05T19:38:45.949418Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3013,"took":"81.188197ms","hash":790607211,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":32092160,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-05T19:38:45.949536Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":790607211,"revision":3013,"compact-revision":-1} {"level":"info","ts":"2026-08-05T19:43:38.679051Z","caller":"traceutil/trace.go:171","msg":"trace[1803500577] transaction","detail":"{read_only:false; response_revision:8269; number_of_response:1; }","duration":"318.466503ms","start":"2026-08-05T19:43:38.360553Z","end":"2026-08-05T19:43:38.679019Z","steps":["trace[1803500577] 'process raft request' (duration: 318.291358ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:43:38.679231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T19:43:38.360541Z","time spent":"318.604017ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T19:43:38.91965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.538006ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T19:43:38.919757Z","caller":"traceutil/trace.go:171","msg":"trace[1791852761] linearizableReadLoop","detail":"{readStateIndex:8559; appliedIndex:8558; }","duration":"189.61874ms","start":"2026-08-05T19:43:38.730122Z","end":"2026-08-05T19:43:38.91974Z","steps":["trace[1791852761] 'read index received' (duration: 52.795967ms)","trace[1791852761] 'applied index is now lower than readState.Index' (duration: 136.821693ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T19:43:38.919841Z","caller":"traceutil/trace.go:171","msg":"trace[2047914331] transaction","detail":"{read_only:false; response_revision:8271; number_of_response:1; }","duration":"217.55505ms","start":"2026-08-05T19:43:38.702243Z","end":"2026-08-05T19:43:38.919798Z","steps":["trace[2047914331] 'process raft request' (duration: 80.770957ms)","trace[2047914331] 'compare' (duration: 136.424783ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T19:43:38.919977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.841656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-05T19:43:38.920059Z","caller":"traceutil/trace.go:171","msg":"trace[391404733] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8271; }","duration":"189.975279ms","start":"2026-08-05T19:43:38.730066Z","end":"2026-08-05T19:43:38.920041Z","steps":["trace[391404733] 'agreement among raft nodes before linearized reading' (duration: 189.732723ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:43:45.871036Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6113} {"level":"info","ts":"2026-08-05T19:43:45.970767Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6113,"took":"97.635403ms","hash":4092684564,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":23597056,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-08-05T19:43:45.970838Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4092684564,"revision":6113,"compact-revision":3013} {"level":"info","ts":"2026-08-05T19:43:51.918207Z","caller":"traceutil/trace.go:171","msg":"trace[1946549880] transaction","detail":"{read_only:false; response_revision:8341; number_of_response:1; }","duration":"103.023541ms","start":"2026-08-05T19:43:51.815153Z","end":"2026-08-05T19:43:51.918177Z","steps":["trace[1946549880] 'process raft request' (duration: 102.801695ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:44:19.625583Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569152656021091,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-05T19:44:19.799682Z","caller":"traceutil/trace.go:171","msg":"trace[1061918165] linearizableReadLoop","detail":"{readStateIndex:8838; appliedIndex:8837; }","duration":"675.003658ms","start":"2026-08-05T19:44:19.124656Z","end":"2026-08-05T19:44:19.799659Z","steps":["trace[1061918165] 'read index received' (duration: 674.650268ms)","trace[1061918165] 'applied index is now lower than readState.Index' (duration: 352.5µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T19:44:19.799885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.217464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-05T19:44:19.799923Z","caller":"traceutil/trace.go:171","msg":"trace[168691894] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8538; }","duration":"675.264995ms","start":"2026-08-05T19:44:19.124642Z","end":"2026-08-05T19:44:19.799907Z","steps":["trace[168691894] 'agreement among raft nodes before linearized reading' (duration: 675.128301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:44:19.799949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T19:44:19.124623Z","time spent":"675.320357ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-05T19:44:19.800072Z","caller":"traceutil/trace.go:171","msg":"trace[959316963] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"678.33433ms","start":"2026-08-05T19:44:19.121395Z","end":"2026-08-05T19:44:19.79973Z","steps":["trace[959316963] 'process raft request' (duration: 678.055282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:44:19.800145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.582742ms","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-08-05T19:44:19.800216Z","caller":"traceutil/trace.go:171","msg":"trace[876723230] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8538; }","duration":"672.694264ms","start":"2026-08-05T19:44:19.127506Z","end":"2026-08-05T19:44:19.8002Z","steps":["trace[876723230] 'agreement among raft nodes before linearized reading' (duration: 672.500979ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:44:19.800266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T19:44:19.127496Z","time spent":"672.761247ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-05T19:44:19.80035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.550194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T19:44:19.800383Z","caller":"traceutil/trace.go:171","msg":"trace[31094836] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:8538; }","duration":"457.620716ms","start":"2026-08-05T19:44:19.342755Z","end":"2026-08-05T19:44:19.800375Z","steps":["trace[31094836] 'agreement among raft nodes before linearized reading' (duration: 457.503003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:44:19.800405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T19:44:19.342734Z","time spent":"457.665156ms","remote":"127.0.0.1:36888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":103,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-08-05T19:44:19.800497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.284226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T19:44:19.800546Z","caller":"traceutil/trace.go:171","msg":"trace[1186271056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8538; }","duration":"605.372668ms","start":"2026-08-05T19:44:19.195162Z","end":"2026-08-05T19:44:19.800535Z","steps":["trace[1186271056] 'agreement among raft nodes before linearized reading' (duration: 605.298496ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:44:19.800574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T19:44:19.195143Z","time spent":"605.42503ms","remote":"127.0.0.1:36774","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-08-05T19:44:19.800703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T19:44:19.12137Z","time spent":"678.822754ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T19:44:19.800803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.877232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-89ss4\" ","response":"range_response_count:1 size:6526"} {"level":"info","ts":"2026-08-05T19:44:19.800839Z","caller":"traceutil/trace.go:171","msg":"trace[522483826] range","detail":"{range_begin:/registry/pods/openstack/keepalived-89ss4; range_end:; response_count:1; response_revision:8538; }","duration":"650.949515ms","start":"2026-08-05T19:44:19.149881Z","end":"2026-08-05T19:44:19.800831Z","steps":["trace[522483826] 'agreement among raft nodes before linearized reading' (duration: 650.831692ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:44:19.800871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T19:44:19.149864Z","time spent":"651.000716ms","remote":"127.0.0.1:36950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-89ss4\" "} {"level":"info","ts":"2026-08-05T19:44:23.215669Z","caller":"traceutil/trace.go:171","msg":"trace[1087475422] transaction","detail":"{read_only:false; response_revision:8560; number_of_response:1; }","duration":"229.133307ms","start":"2026-08-05T19:44:22.986506Z","end":"2026-08-05T19:44:23.215639Z","steps":["trace[1087475422] 'process raft request' (duration: 175.090467ms)","trace[1087475422] 'compare' (duration: 53.882465ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T19:44:23.240033Z","caller":"traceutil/trace.go:171","msg":"trace[421901803] transaction","detail":"{read_only:false; response_revision:8561; number_of_response:1; }","duration":"166.88506ms","start":"2026-08-05T19:44:23.073112Z","end":"2026-08-05T19:44:23.239997Z","steps":["trace[421901803] 'process raft request' (duration: 166.359495ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:44:32.0998Z","caller":"traceutil/trace.go:171","msg":"trace[1180027539] transaction","detail":"{read_only:false; response_revision:8649; number_of_response:1; }","duration":"152.181615ms","start":"2026-08-05T19:44:31.947588Z","end":"2026-08-05T19:44:32.09977Z","steps":["trace[1180027539] 'process raft request' (duration: 135.767202ms)","trace[1180027539] 'compare' (duration: 15.709043ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T19:44:32.294422Z","caller":"traceutil/trace.go:171","msg":"trace[757906566] linearizableReadLoop","detail":"{readStateIndex:8954; appliedIndex:8952; }","duration":"344.943699ms","start":"2026-08-05T19:44:31.949458Z","end":"2026-08-05T19:44:32.294401Z","steps":["trace[757906566] 'read index received' (duration: 133.912152ms)","trace[757906566] 'applied index is now lower than readState.Index' (duration: 211.030377ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T19:44:32.294455Z","caller":"traceutil/trace.go:171","msg":"trace[135776490] transaction","detail":"{read_only:false; response_revision:8651; number_of_response:1; }","duration":"243.321828ms","start":"2026-08-05T19:44:32.051101Z","end":"2026-08-05T19:44:32.294423Z","steps":["trace[135776490] 'process raft request' (duration: 243.212035ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:44:32.294469Z","caller":"traceutil/trace.go:171","msg":"trace[1084907644] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"230.71165ms","start":"2026-08-05T19:44:32.063723Z","end":"2026-08-05T19:44:32.294434Z","steps":["trace[1084907644] 'process raft request' (duration: 230.631688ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:44:32.294571Z","caller":"traceutil/trace.go:171","msg":"trace[2070982049] transaction","detail":"{read_only:false; response_revision:8650; number_of_response:1; }","duration":"345.410052ms","start":"2026-08-05T19:44:31.94913Z","end":"2026-08-05T19:44:32.29454Z","steps":["trace[2070982049] 'process raft request' (duration: 267.899715ms)","trace[2070982049] 'compare' (duration: 77.156377ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T19:44:32.294646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.227616ms","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-08-05T19:44:32.294717Z","caller":"traceutil/trace.go:171","msg":"trace[1937063454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8652; }","duration":"169.338979ms","start":"2026-08-05T19:44:32.12536Z","end":"2026-08-05T19:44:32.294699Z","steps":["trace[1937063454] 'agreement among raft nodes before linearized reading' (duration: 169.186734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:44:32.294725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T19:44:31.949112Z","time spent":"345.523435ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T19:44:32.294883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.419392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228751"} {"level":"info","ts":"2026-08-05T19:44:32.295155Z","caller":"traceutil/trace.go:171","msg":"trace[1592708226] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8652; }","duration":"345.69073ms","start":"2026-08-05T19:44:31.949422Z","end":"2026-08-05T19:44:32.295113Z","steps":["trace[1592708226] 'agreement among raft nodes before linearized reading' (duration: 345.075343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:44:32.295204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T19:44:31.949406Z","time spent":"345.784332ms","remote":"127.0.0.1:36950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":26,"response size":228775,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-08-05T19:44:33.082512Z","caller":"traceutil/trace.go:171","msg":"trace[858114479] transaction","detail":"{read_only:false; response_revision:8655; number_of_response:1; }","duration":"108.360397ms","start":"2026-08-05T19:44:32.974128Z","end":"2026-08-05T19:44:33.082488Z","steps":["trace[858114479] 'process raft request' (duration: 108.197863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:44:33.482216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.020889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T19:44:33.482306Z","caller":"traceutil/trace.go:171","msg":"trace[1434941868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8657; }","duration":"288.152993ms","start":"2026-08-05T19:44:33.194133Z","end":"2026-08-05T19:44:33.482286Z","steps":["trace[1434941868] 'range keys from in-memory index tree' (duration: 287.953917ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:44:33.482294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.126057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4725"} {"level":"info","ts":"2026-08-05T19:44:33.482373Z","caller":"traceutil/trace.go:171","msg":"trace[1324156244] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:8657; }","duration":"298.269692ms","start":"2026-08-05T19:44:33.184081Z","end":"2026-08-05T19:44:33.482351Z","steps":["trace[1324156244] 'range keys from in-memory index tree' (duration: 297.981673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:44:33.482385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.292204ms","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-08-05T19:44:33.482474Z","caller":"traceutil/trace.go:171","msg":"trace[2032099225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8657; }","duration":"203.419516ms","start":"2026-08-05T19:44:33.279034Z","end":"2026-08-05T19:44:33.482453Z","steps":["trace[2032099225] 'range keys from in-memory index tree' (duration: 203.123669ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:44:33.482658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.81492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225401"} {"level":"info","ts":"2026-08-05T19:44:33.482718Z","caller":"traceutil/trace.go:171","msg":"trace[1271006599] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8657; }","duration":"124.900332ms","start":"2026-08-05T19:44:33.357803Z","end":"2026-08-05T19:44:33.482703Z","steps":["trace[1271006599] 'range keys from in-memory index tree' (duration: 123.941366ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:44:40.329828Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002341.wal"} {"level":"info","ts":"2026-08-05T19:45:48.773052Z","caller":"traceutil/trace.go:171","msg":"trace[307588899] transaction","detail":"{read_only:false; response_revision:9398; number_of_response:1; }","duration":"105.424577ms","start":"2026-08-05T19:45:48.667604Z","end":"2026-08-05T19:45:48.773028Z","steps":["trace[307588899] 'process raft request' (duration: 105.274493ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:45:48.78275Z","caller":"traceutil/trace.go:171","msg":"trace[828445820] linearizableReadLoop","detail":"{readStateIndex:9724; appliedIndex:9723; }","duration":"100.049578ms","start":"2026-08-05T19:45:48.682679Z","end":"2026-08-05T19:45:48.782729Z","steps":["trace[828445820] 'read index received' (duration: 90.421933ms)","trace[828445820] 'applied index is now lower than readState.Index' (duration: 9.602484ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T19:45:48.782843Z","caller":"traceutil/trace.go:171","msg":"trace[507347869] transaction","detail":"{read_only:false; response_revision:9399; number_of_response:1; }","duration":"112.527072ms","start":"2026-08-05T19:45:48.670293Z","end":"2026-08-05T19:45:48.78282Z","steps":["trace[507347869] 'process raft request' (duration: 112.301816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:45:48.783162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.45956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-05T19:45:48.783287Z","caller":"traceutil/trace.go:171","msg":"trace[2040454412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9399; }","duration":"100.637615ms","start":"2026-08-05T19:45:48.682624Z","end":"2026-08-05T19:45:48.783262Z","steps":["trace[2040454412] 'agreement among raft nodes before linearized reading' (duration: 100.225073ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:46:25.373714Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca256638ea38eb43","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T19:46:25.378347Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-05T19:46:25.37844Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-05T19:48:45.880559Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8310} {"level":"info","ts":"2026-08-05T19:48:45.958272Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8310,"took":"75.096539ms","hash":3573678969,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":25038848,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-05T19:48:45.958344Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3573678969,"revision":8310,"compact-revision":6113} {"level":"info","ts":"2026-08-05T19:50:02.169607Z","caller":"traceutil/trace.go:171","msg":"trace[1657552078] transaction","detail":"{read_only:false; response_revision:11034; number_of_response:1; }","duration":"110.960298ms","start":"2026-08-05T19:50:02.058616Z","end":"2026-08-05T19:50:02.169577Z","steps":["trace[1657552078] 'process raft request' (duration: 79.081539ms)","trace[1657552078] 'compare' (duration: 31.752105ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T19:51:25.368781Z","caller":"traceutil/trace.go:171","msg":"trace[639983110] linearizableReadLoop","detail":"{readStateIndex:12223; appliedIndex:12222; }","duration":"171.394563ms","start":"2026-08-05T19:51:25.19736Z","end":"2026-08-05T19:51:25.368754Z","steps":["trace[639983110] 'read index received' (duration: 171.237449ms)","trace[639983110] 'applied index is now lower than readState.Index' (duration: 155.714µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T19:51:25.368928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.560998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T19:51:25.368905Z","caller":"traceutil/trace.go:171","msg":"trace[1928466151] transaction","detail":"{read_only:false; response_revision:11821; number_of_response:1; }","duration":"212.255339ms","start":"2026-08-05T19:51:25.156612Z","end":"2026-08-05T19:51:25.368867Z","steps":["trace[1928466151] 'process raft request' (duration: 211.94448ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:51:25.368971Z","caller":"traceutil/trace.go:171","msg":"trace[327623172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11821; }","duration":"171.687782ms","start":"2026-08-05T19:51:25.197271Z","end":"2026-08-05T19:51:25.368959Z","steps":["trace[327623172] 'agreement among raft nodes before linearized reading' (duration: 171.597129ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:51:26.107489Z","caller":"traceutil/trace.go:171","msg":"trace[1414946773] transaction","detail":"{read_only:false; response_revision:11828; number_of_response:1; }","duration":"110.33663ms","start":"2026-08-05T19:51:25.997118Z","end":"2026-08-05T19:51:26.107455Z","steps":["trace[1414946773] 'process raft request' (duration: 110.124755ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:51:26.259671Z","caller":"traceutil/trace.go:171","msg":"trace[2000737113] transaction","detail":"{read_only:false; response_revision:11830; number_of_response:1; }","duration":"125.769656ms","start":"2026-08-05T19:51:26.13386Z","end":"2026-08-05T19:51:26.259629Z","steps":["trace[2000737113] 'process raft request' (duration: 125.429957ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:51:26.752617Z","caller":"traceutil/trace.go:171","msg":"trace[475604899] linearizableReadLoop","detail":"{readStateIndex:12233; appliedIndex:12232; }","duration":"258.086493ms","start":"2026-08-05T19:51:26.494507Z","end":"2026-08-05T19:51:26.752593Z","steps":["trace[475604899] 'read index received' (duration: 257.933238ms)","trace[475604899] 'applied index is now lower than readState.Index' (duration: 152.105µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T19:51:26.752658Z","caller":"traceutil/trace.go:171","msg":"trace[17408329] transaction","detail":"{read_only:false; response_revision:11831; number_of_response:1; }","duration":"334.836868ms","start":"2026-08-05T19:51:26.417791Z","end":"2026-08-05T19:51:26.752628Z","steps":["trace[17408329] 'process raft request' (duration: 334.641402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:51:26.752829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T19:51:26.417775Z","time spent":"334.965992ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T19:51:26.752844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.664035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-05T19:51:26.752888Z","caller":"traceutil/trace.go:171","msg":"trace[549175584] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11831; }","duration":"149.759957ms","start":"2026-08-05T19:51:26.603115Z","end":"2026-08-05T19:51:26.752875Z","steps":["trace[549175584] 'agreement among raft nodes before linearized reading' (duration: 149.619134ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:51:26.753142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.627947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-05T19:51:26.753219Z","caller":"traceutil/trace.go:171","msg":"trace[1331079906] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:11831; }","duration":"258.754221ms","start":"2026-08-05T19:51:26.494446Z","end":"2026-08-05T19:51:26.7532Z","steps":["trace[1331079906] 'agreement among raft nodes before linearized reading' (duration: 258.273078ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:53:45.88642Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10531} {"level":"info","ts":"2026-08-05T19:53:45.976217Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10531,"took":"86.092968ms","hash":1958463904,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":26456064,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-05T19:53:45.976327Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1958463904,"revision":10531,"compact-revision":8310} {"level":"info","ts":"2026-08-05T19:57:53.658294Z","caller":"traceutil/trace.go:171","msg":"trace[1903590216] transaction","detail":"{read_only:false; response_revision:14417; number_of_response:1; }","duration":"183.544041ms","start":"2026-08-05T19:57:53.474725Z","end":"2026-08-05T19:57:53.658269Z","steps":["trace[1903590216] 'process raft request' (duration: 183.359746ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:57:53.823539Z","caller":"traceutil/trace.go:171","msg":"trace[2046476676] linearizableReadLoop","detail":"{readStateIndex:14910; appliedIndex:14908; }","duration":"220.595932ms","start":"2026-08-05T19:57:53.60291Z","end":"2026-08-05T19:57:53.823506Z","steps":["trace[2046476676] 'read index received' (duration: 55.221163ms)","trace[2046476676] 'applied index is now lower than readState.Index' (duration: 165.373869ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T19:57:53.823606Z","caller":"traceutil/trace.go:171","msg":"trace[2059765603] transaction","detail":"{read_only:false; response_revision:14418; number_of_response:1; }","duration":"296.635487ms","start":"2026-08-05T19:57:53.526928Z","end":"2026-08-05T19:57:53.823563Z","steps":["trace[2059765603] 'process raft request' (duration: 278.924899ms)","trace[2059765603] 'compare' (duration: 17.468292ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T19:57:53.823738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.822668ms","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-08-05T19:57:53.823769Z","caller":"traceutil/trace.go:171","msg":"trace[140909135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14418; }","duration":"220.90178ms","start":"2026-08-05T19:57:53.602859Z","end":"2026-08-05T19:57:53.823761Z","steps":["trace[140909135] 'agreement among raft nodes before linearized reading' (duration: 220.760006ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:58:01.657244Z","caller":"traceutil/trace.go:171","msg":"trace[151513136] transaction","detail":"{read_only:false; response_revision:14456; number_of_response:1; }","duration":"176.810394ms","start":"2026-08-05T19:58:01.480403Z","end":"2026-08-05T19:58:01.657214Z","steps":["trace[151513136] 'process raft request' (duration: 176.602079ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:58:01.67063Z","caller":"traceutil/trace.go:171","msg":"trace[380264177] transaction","detail":"{read_only:false; response_revision:14457; number_of_response:1; }","duration":"159.689192ms","start":"2026-08-05T19:58:01.510907Z","end":"2026-08-05T19:58:01.670596Z","steps":["trace[380264177] 'process raft request' (duration: 159.488187ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:58:10.061973Z","caller":"traceutil/trace.go:171","msg":"trace[1477948857] linearizableReadLoop","detail":"{readStateIndex:15013; appliedIndex:15012; }","duration":"166.750237ms","start":"2026-08-05T19:58:09.895199Z","end":"2026-08-05T19:58:10.061949Z","steps":["trace[1477948857] 'read index received' (duration: 164.820874ms)","trace[1477948857] 'applied index is now lower than readState.Index' (duration: 1.928043ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T19:58:10.062189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.970903ms","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-08-05T19:58:10.062219Z","caller":"traceutil/trace.go:171","msg":"trace[1804003630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14516; }","duration":"167.059085ms","start":"2026-08-05T19:58:09.895151Z","end":"2026-08-05T19:58:10.06221Z","steps":["trace[1804003630] 'agreement among raft nodes before linearized reading' (duration: 166.889711ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:58:10.06219Z","caller":"traceutil/trace.go:171","msg":"trace[1694993841] transaction","detail":"{read_only:false; response_revision:14516; number_of_response:1; }","duration":"182.680626ms","start":"2026-08-05T19:58:09.879349Z","end":"2026-08-05T19:58:10.062029Z","steps":["trace[1694993841] 'process raft request' (duration: 180.785134ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:58:10.4172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.248914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T19:58:10.4172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.350056ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T19:58:10.417294Z","caller":"traceutil/trace.go:171","msg":"trace[2037669951] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:14517; }","duration":"236.459129ms","start":"2026-08-05T19:58:10.180824Z","end":"2026-08-05T19:58:10.417283Z","steps":["trace[2037669951] 'range keys from in-memory index tree' (duration: 236.336056ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:58:10.417293Z","caller":"traceutil/trace.go:171","msg":"trace[767051375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14517; }","duration":"219.383428ms","start":"2026-08-05T19:58:10.197889Z","end":"2026-08-05T19:58:10.417272Z","steps":["trace[767051375] 'range keys from in-memory index tree' (duration: 219.173833ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:58:32.333668Z","caller":"traceutil/trace.go:171","msg":"trace[1018424497] linearizableReadLoop","detail":"{readStateIndex:15332; appliedIndex:15331; }","duration":"465.831512ms","start":"2026-08-05T19:58:31.867796Z","end":"2026-08-05T19:58:32.333628Z","steps":["trace[1018424497] 'read index received' (duration: 390.235888ms)","trace[1018424497] 'applied index is now lower than readState.Index' (duration: 75.593554ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T19:58:32.333819Z","caller":"traceutil/trace.go:171","msg":"trace[1466583420] transaction","detail":"{read_only:false; response_revision:14821; number_of_response:1; }","duration":"492.22953ms","start":"2026-08-05T19:58:31.841553Z","end":"2026-08-05T19:58:32.333782Z","steps":["trace[1466583420] 'process raft request' (duration: 416.459911ms)","trace[1466583420] 'compare' (duration: 75.223154ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T19:58:32.334019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T19:58:31.841495Z","time spent":"492.436786ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T19:58:32.334026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.208552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-abf71eec-f3ba-487a-84f1-537ca5cc3aee\" ","response":"range_response_count:1 size:3636"} {"level":"info","ts":"2026-08-05T19:58:32.334125Z","caller":"traceutil/trace.go:171","msg":"trace[1275797561] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-abf71eec-f3ba-487a-84f1-537ca5cc3aee; range_end:; response_count:1; response_revision:14821; }","duration":"466.359596ms","start":"2026-08-05T19:58:31.86774Z","end":"2026-08-05T19:58:32.3341Z","steps":["trace[1275797561] 'agreement among raft nodes before linearized reading' (duration: 466.034967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:58:32.334169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T19:58:31.86772Z","time spent":"466.441469ms","remote":"127.0.0.1:36950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3660,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-abf71eec-f3ba-487a-84f1-537ca5cc3aee\" "} {"level":"info","ts":"2026-08-05T19:58:32.412736Z","caller":"traceutil/trace.go:171","msg":"trace[495243318] transaction","detail":"{read_only:false; response_revision:14822; number_of_response:1; }","duration":"272.620736ms","start":"2026-08-05T19:58:32.140073Z","end":"2026-08-05T19:58:32.412693Z","steps":["trace[495243318] 'process raft request' (duration: 272.353759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:58:32.412856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.624387ms","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-08-05T19:58:32.412986Z","caller":"traceutil/trace.go:171","msg":"trace[338939524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14822; }","duration":"148.807372ms","start":"2026-08-05T19:58:32.26415Z","end":"2026-08-05T19:58:32.412957Z","steps":["trace[338939524] 'agreement among raft nodes before linearized reading' (duration: 148.481013ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:58:32.452689Z","caller":"traceutil/trace.go:171","msg":"trace[424930190] transaction","detail":"{read_only:false; response_revision:14823; number_of_response:1; }","duration":"112.704527ms","start":"2026-08-05T19:58:32.339962Z","end":"2026-08-05T19:58:32.452667Z","steps":["trace[424930190] 'process raft request' (duration: 112.512262ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:58:45.894931Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12778} {"level":"info","ts":"2026-08-05T19:58:45.977198Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12778,"took":"79.42351ms","hash":711735946,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":28934144,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-05T19:58:45.977259Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":711735946,"revision":12778,"compact-revision":10531} {"level":"info","ts":"2026-08-05T19:59:22.689016Z","caller":"traceutil/trace.go:171","msg":"trace[1185898805] transaction","detail":"{read_only:false; response_revision:15445; number_of_response:1; }","duration":"105.323944ms","start":"2026-08-05T19:59:22.583661Z","end":"2026-08-05T19:59:22.688985Z","steps":["trace[1185898805] 'process raft request' (duration: 105.148909ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:59:22.689043Z","caller":"traceutil/trace.go:171","msg":"trace[1578687895] linearizableReadLoop","detail":"{readStateIndex:15973; appliedIndex:15972; }","duration":"104.549012ms","start":"2026-08-05T19:59:22.584448Z","end":"2026-08-05T19:59:22.688997Z","steps":["trace[1578687895] 'read index received' (duration: 104.440439ms)","trace[1578687895] 'applied index is now lower than readState.Index' (duration: 105.573µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T19:59:22.689264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.794069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-08-05T19:59:22.689366Z","caller":"traceutil/trace.go:171","msg":"trace[123155873] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15445; }","duration":"104.924903ms","start":"2026-08-05T19:59:22.584418Z","end":"2026-08-05T19:59:22.689342Z","steps":["trace[123155873] 'agreement among raft nodes before linearized reading' (duration: 104.685096ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:59:56.896943Z","caller":"traceutil/trace.go:171","msg":"trace[189661412] transaction","detail":"{read_only:false; response_revision:15625; number_of_response:1; }","duration":"109.703513ms","start":"2026-08-05T19:59:56.787222Z","end":"2026-08-05T19:59:56.896925Z","steps":["trace[189661412] 'process raft request' (duration: 109.604431ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:59:57.216126Z","caller":"traceutil/trace.go:171","msg":"trace[1168470339] linearizableReadLoop","detail":"{readStateIndex:16161; appliedIndex:16160; }","duration":"208.492477ms","start":"2026-08-05T19:59:57.007612Z","end":"2026-08-05T19:59:57.216105Z","steps":["trace[1168470339] 'read index received' (duration: 200.314562ms)","trace[1168470339] 'applied index is now lower than readState.Index' (duration: 8.177305ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T19:59:57.216276Z","caller":"traceutil/trace.go:171","msg":"trace[497824769] transaction","detail":"{read_only:false; response_revision:15626; number_of_response:1; }","duration":"293.915993ms","start":"2026-08-05T19:59:56.922354Z","end":"2026-08-05T19:59:57.21627Z","steps":["trace[497824769] 'process raft request' (duration: 285.636455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:59:57.216401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.780396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-05T19:59:57.216491Z","caller":"traceutil/trace.go:171","msg":"trace[176749257] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15626; }","duration":"208.9167ms","start":"2026-08-05T19:59:57.007554Z","end":"2026-08-05T19:59:57.21647Z","steps":["trace[176749257] 'agreement among raft nodes before linearized reading' (duration: 208.764775ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:59:57.216729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.753777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-4crzc\" ","response":"range_response_count:1 size:9790"} {"level":"info","ts":"2026-08-05T19:59:57.216782Z","caller":"traceutil/trace.go:171","msg":"trace[1309750117] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-4crzc; range_end:; response_count:1; response_revision:15626; }","duration":"125.85963ms","start":"2026-08-05T19:59:57.090913Z","end":"2026-08-05T19:59:57.216772Z","steps":["trace[1309750117] 'agreement among raft nodes before linearized reading' (duration: 125.384357ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:59:57.403056Z","caller":"traceutil/trace.go:171","msg":"trace[44749433] transaction","detail":"{read_only:false; response_revision:15627; number_of_response:1; }","duration":"183.353305ms","start":"2026-08-05T19:59:57.219678Z","end":"2026-08-05T19:59:57.403031Z","steps":["trace[44749433] 'process raft request' (duration: 146.655133ms)","trace[44749433] 'compare' (duration: 36.506276ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T19:59:57.403619Z","caller":"traceutil/trace.go:171","msg":"trace[1611117691] linearizableReadLoop","detail":"{readStateIndex:16162; appliedIndex:16161; }","duration":"181.479354ms","start":"2026-08-05T19:59:57.221512Z","end":"2026-08-05T19:59:57.402991Z","steps":["trace[1611117691] 'read index received' (duration: 144.750241ms)","trace[1611117691] 'applied index is now lower than readState.Index' (duration: 36.728083ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T19:59:57.403961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.42596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737228"} {"level":"info","ts":"2026-08-05T19:59:57.404093Z","caller":"traceutil/trace.go:171","msg":"trace[195002025] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15627; }","duration":"182.544722ms","start":"2026-08-05T19:59:57.221486Z","end":"2026-08-05T19:59:57.404031Z","steps":["trace[195002025] 'agreement among raft nodes before linearized reading' (duration: 181.640717ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:59:57.658428Z","caller":"traceutil/trace.go:171","msg":"trace[822718520] transaction","detail":"{read_only:false; response_revision:15630; number_of_response:1; }","duration":"132.253866ms","start":"2026-08-05T19:59:57.526144Z","end":"2026-08-05T19:59:57.658398Z","steps":["trace[822718520] 'process raft request' (duration: 127.984588ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:59:57.748961Z","caller":"traceutil/trace.go:171","msg":"trace[223718221] linearizableReadLoop","detail":"{readStateIndex:16166; appliedIndex:16164; }","duration":"120.823321ms","start":"2026-08-05T19:59:57.628113Z","end":"2026-08-05T19:59:57.748937Z","steps":["trace[223718221] 'read index received' (duration: 26.016137ms)","trace[223718221] 'applied index is now lower than readState.Index' (duration: 94.805994ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T19:59:57.749099Z","caller":"traceutil/trace.go:171","msg":"trace[1340560344] transaction","detail":"{read_only:false; response_revision:15631; number_of_response:1; }","duration":"221.554698ms","start":"2026-08-05T19:59:57.527533Z","end":"2026-08-05T19:59:57.749087Z","steps":["trace[1340560344] 'process raft request' (duration: 221.26219ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:59:57.749328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.195051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-08-05T19:59:57.749408Z","caller":"traceutil/trace.go:171","msg":"trace[1945300239] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15631; }","duration":"121.334376ms","start":"2026-08-05T19:59:57.628057Z","end":"2026-08-05T19:59:57.749391Z","steps":["trace[1945300239] 'agreement among raft nodes before linearized reading' (duration: 121.108219ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:59:57.749442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.767506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-rb29q\" ","response":"range_response_count:1 size:20639"} {"level":"warn","ts":"2026-08-05T19:59:57.749358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.464191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"warn","ts":"2026-08-05T19:59:57.749471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.751339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-05T19:59:57.749584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.915624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T19:59:57.74962Z","caller":"traceutil/trace.go:171","msg":"trace[1524711390] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15631; }","duration":"120.953495ms","start":"2026-08-05T19:59:57.628658Z","end":"2026-08-05T19:59:57.749611Z","steps":["trace[1524711390] 'agreement among raft nodes before linearized reading' (duration: 120.856112ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:59:57.749739Z","caller":"traceutil/trace.go:171","msg":"trace[1828366620] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15631; }","duration":"121.035677ms","start":"2026-08-05T19:59:57.628681Z","end":"2026-08-05T19:59:57.749717Z","steps":["trace[1828366620] 'agreement among raft nodes before linearized reading' (duration: 120.741928ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:59:57.749483Z","caller":"traceutil/trace.go:171","msg":"trace[326575760] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-rb29q; range_end:; response_count:1; response_revision:15631; }","duration":"105.850049ms","start":"2026-08-05T19:59:57.64362Z","end":"2026-08-05T19:59:57.74947Z","steps":["trace[326575760] 'agreement among raft nodes before linearized reading' (duration: 105.761026ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:59:57.749715Z","caller":"traceutil/trace.go:171","msg":"trace[1895806165] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15631; }","duration":"120.768479ms","start":"2026-08-05T19:59:57.628829Z","end":"2026-08-05T19:59:57.749598Z","steps":["trace[1895806165] 'agreement among raft nodes before linearized reading' (duration: 120.400259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T19:59:57.750437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.862912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-08-05T19:59:57.750565Z","caller":"traceutil/trace.go:171","msg":"trace[897420046] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15631; }","duration":"122.055445ms","start":"2026-08-05T19:59:57.628464Z","end":"2026-08-05T19:59:57.750519Z","steps":["trace[897420046] 'agreement among raft nodes before linearized reading' (duration: 120.732658ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T19:59:58.004356Z","caller":"traceutil/trace.go:171","msg":"trace[16778495] transaction","detail":"{read_only:false; response_revision:15632; number_of_response:1; }","duration":"103.448682ms","start":"2026-08-05T19:59:57.900877Z","end":"2026-08-05T19:59:58.004325Z","steps":["trace[16778495] 'process raft request' (duration: 103.245036ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:03:45.90592Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14923} {"level":"info","ts":"2026-08-05T20:03:45.999199Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14923,"took":"89.130371ms","hash":1757552692,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":30892032,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-05T20:03:45.999274Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1757552692,"revision":14923,"compact-revision":12778} {"level":"info","ts":"2026-08-05T20:05:58.085345Z","caller":"traceutil/trace.go:171","msg":"trace[1294741585] transaction","detail":"{read_only:false; response_revision:18356; number_of_response:1; }","duration":"239.90853ms","start":"2026-08-05T20:05:57.845415Z","end":"2026-08-05T20:05:58.085323Z","steps":["trace[1294741585] 'process raft request' (duration: 239.765416ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:06:04.855817Z","caller":"traceutil/trace.go:171","msg":"trace[990132836] transaction","detail":"{read_only:false; response_revision:18395; number_of_response:1; }","duration":"110.375042ms","start":"2026-08-05T20:06:04.745407Z","end":"2026-08-05T20:06:04.855782Z","steps":["trace[990132836] 'process raft request' (duration: 110.125955ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:06:05.434296Z","caller":"traceutil/trace.go:171","msg":"trace[127427174] transaction","detail":"{read_only:false; response_revision:18399; number_of_response:1; }","duration":"119.899024ms","start":"2026-08-05T20:06:05.31437Z","end":"2026-08-05T20:06:05.434269Z","steps":["trace[127427174] 'process raft request' (duration: 119.664748ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:06:05.510157Z","caller":"traceutil/trace.go:171","msg":"trace[961493467] transaction","detail":"{read_only:false; response_revision:18400; number_of_response:1; }","duration":"195.63639ms","start":"2026-08-05T20:06:05.314493Z","end":"2026-08-05T20:06:05.51013Z","steps":["trace[961493467] 'process raft request' (duration: 195.379023ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:06:05.510355Z","caller":"traceutil/trace.go:171","msg":"trace[1322937953] transaction","detail":"{read_only:false; response_revision:18401; number_of_response:1; }","duration":"195.031733ms","start":"2026-08-05T20:06:05.315303Z","end":"2026-08-05T20:06:05.510335Z","steps":["trace[1322937953] 'process raft request' (duration: 194.733745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:06:06.083474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.123831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-05T20:06:06.083565Z","caller":"traceutil/trace.go:171","msg":"trace[406106412] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:18402; }","duration":"332.306006ms","start":"2026-08-05T20:06:05.751241Z","end":"2026-08-05T20:06:06.083547Z","steps":["trace[406106412] 'count revisions from in-memory index tree' (duration: 331.953867ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:06:06.083609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:06:05.751214Z","time spent":"332.383909ms","remote":"127.0.0.1:37024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":4,"response size":32,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-05T20:06:06.084323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.383546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-4crzc\" ","response":"range_response_count:1 size:9791"} {"level":"info","ts":"2026-08-05T20:06:06.084402Z","caller":"traceutil/trace.go:171","msg":"trace[1093660411] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-4crzc; range_end:; response_count:1; response_revision:18402; }","duration":"423.549761ms","start":"2026-08-05T20:06:05.660836Z","end":"2026-08-05T20:06:06.084385Z","steps":["trace[1093660411] 'range keys from in-memory index tree' (duration: 423.228301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:06:06.084451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:06:05.660805Z","time spent":"423.638643ms","remote":"127.0.0.1:36950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9815,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-4crzc\" "} {"level":"info","ts":"2026-08-05T20:06:14.062146Z","caller":"traceutil/trace.go:171","msg":"trace[924069120] transaction","detail":"{read_only:false; response_revision:18544; number_of_response:1; }","duration":"115.624436ms","start":"2026-08-05T20:06:13.946487Z","end":"2026-08-05T20:06:14.062112Z","steps":["trace[924069120] 'process raft request' (duration: 115.41626ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:06:14.092789Z","caller":"traceutil/trace.go:171","msg":"trace[1805485568] transaction","detail":"{read_only:false; response_revision:18546; number_of_response:1; }","duration":"115.291706ms","start":"2026-08-05T20:06:13.977475Z","end":"2026-08-05T20:06:14.092766Z","steps":["trace[1805485568] 'process raft request' (duration: 115.252275ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:06:14.093118Z","caller":"traceutil/trace.go:171","msg":"trace[544167804] transaction","detail":"{read_only:false; response_revision:18545; number_of_response:1; }","duration":"117.516258ms","start":"2026-08-05T20:06:13.97559Z","end":"2026-08-05T20:06:14.093106Z","steps":["trace[544167804] 'process raft request' (duration: 117.035014ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:06:14.57723Z","caller":"traceutil/trace.go:171","msg":"trace[375135638] transaction","detail":"{read_only:false; response_revision:18586; number_of_response:1; }","duration":"218.315786ms","start":"2026-08-05T20:06:14.358888Z","end":"2026-08-05T20:06:14.577204Z","steps":["trace[375135638] 'process raft request' (duration: 218.070479ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:06:14.57726Z","caller":"traceutil/trace.go:171","msg":"trace[605453905] transaction","detail":"{read_only:false; response_revision:18588; number_of_response:1; }","duration":"132.752378ms","start":"2026-08-05T20:06:14.444489Z","end":"2026-08-05T20:06:14.577241Z","steps":["trace[605453905] 'process raft request' (duration: 132.712477ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:06:14.577364Z","caller":"traceutil/trace.go:171","msg":"trace[1404398455] transaction","detail":"{read_only:false; response_revision:18587; number_of_response:1; }","duration":"211.504548ms","start":"2026-08-05T20:06:14.365806Z","end":"2026-08-05T20:06:14.57731Z","steps":["trace[1404398455] 'process raft request' (duration: 211.281951ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:06:14.577387Z","caller":"traceutil/trace.go:171","msg":"trace[726233643] linearizableReadLoop","detail":"{readStateIndex:19219; appliedIndex:19216; }","duration":"142.6161ms","start":"2026-08-05T20:06:14.434731Z","end":"2026-08-05T20:06:14.577347Z","steps":["trace[726233643] 'read index received' (duration: 134.581028ms)","trace[726233643] 'applied index is now lower than readState.Index' (duration: 8.032361ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T20:06:14.577694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.776038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/libvirt-libvirt-default-rb29q-api-nstq8\" ","response":"range_response_count:1 size:2533"} {"level":"warn","ts":"2026-08-05T20:06:14.577718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.976726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/libvirt-libvirt-default-rb29q-api\" ","response":"range_response_count:1 size:4280"} {"level":"info","ts":"2026-08-05T20:06:14.577751Z","caller":"traceutil/trace.go:171","msg":"trace[405093713] range","detail":"{range_begin:/registry/secrets/openstack/libvirt-libvirt-default-rb29q-api-nstq8; range_end:; response_count:1; response_revision:18588; }","duration":"218.883431ms","start":"2026-08-05T20:06:14.358851Z","end":"2026-08-05T20:06:14.577734Z","steps":["trace[405093713] 'agreement among raft nodes before linearized reading' (duration: 218.614654ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:06:14.577762Z","caller":"traceutil/trace.go:171","msg":"trace[45203844] range","detail":"{range_begin:/registry/secrets/openstack/libvirt-libvirt-default-rb29q-api; range_end:; response_count:1; response_revision:18588; }","duration":"218.041188ms","start":"2026-08-05T20:06:14.359704Z","end":"2026-08-05T20:06:14.577745Z","steps":["trace[45203844] 'agreement among raft nodes before linearized reading' (duration: 217.891113ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:06:14.577904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.917692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:709"} {"level":"warn","ts":"2026-08-05T20:06:14.577916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.474158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/libvirt-libvirt-default-rb29q-vnc-dr5x9\" ","response":"range_response_count:1 size:2533"} {"level":"info","ts":"2026-08-05T20:06:14.577968Z","caller":"traceutil/trace.go:171","msg":"trace[2130335149] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:18588; }","duration":"219.046746ms","start":"2026-08-05T20:06:14.358899Z","end":"2026-08-05T20:06:14.577946Z","steps":["trace[2130335149] 'agreement among raft nodes before linearized reading' (duration: 218.773879ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:06:14.578002Z","caller":"traceutil/trace.go:171","msg":"trace[894897940] range","detail":"{range_begin:/registry/secrets/openstack/libvirt-libvirt-default-rb29q-vnc-dr5x9; range_end:; response_count:1; response_revision:18588; }","duration":"137.671284ms","start":"2026-08-05T20:06:14.440305Z","end":"2026-08-05T20:06:14.577977Z","steps":["trace[894897940] 'agreement among raft nodes before linearized reading' (duration: 137.402277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:06:14.578162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.108097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T20:06:14.578208Z","caller":"traceutil/trace.go:171","msg":"trace[923720371] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:18588; }","duration":"129.17396ms","start":"2026-08-05T20:06:14.449019Z","end":"2026-08-05T20:06:14.578193Z","steps":["trace[923720371] 'agreement among raft nodes before linearized reading' (duration: 129.095948ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:06:14.578221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.92448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/libvirt-libvirt-default-rb29q-vnc\" ","response":"range_response_count:1 size:4288"} {"level":"info","ts":"2026-08-05T20:06:14.578257Z","caller":"traceutil/trace.go:171","msg":"trace[570621854] range","detail":"{range_begin:/registry/secrets/openstack/libvirt-libvirt-default-rb29q-vnc; range_end:; response_count:1; response_revision:18588; }","duration":"134.020592ms","start":"2026-08-05T20:06:14.444226Z","end":"2026-08-05T20:06:14.578247Z","steps":["trace[570621854] 'agreement among raft nodes before linearized reading' (duration: 133.92011ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:06:14.578771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.036737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225402"} {"level":"info","ts":"2026-08-05T20:06:14.578808Z","caller":"traceutil/trace.go:171","msg":"trace[162247198] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:18588; }","duration":"112.075299ms","start":"2026-08-05T20:06:14.466723Z","end":"2026-08-05T20:06:14.578798Z","steps":["trace[162247198] 'agreement among raft nodes before linearized reading' (duration: 111.163543ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:07:26.016392Z","caller":"traceutil/trace.go:171","msg":"trace[1701800327] transaction","detail":"{read_only:false; response_revision:19231; number_of_response:1; }","duration":"702.565359ms","start":"2026-08-05T20:07:25.313774Z","end":"2026-08-05T20:07:26.01634Z","steps":["trace[1701800327] 'process raft request' (duration: 702.354483ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:26.016615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:25.313748Z","time spent":"702.756034ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T20:07:26.017317Z","caller":"traceutil/trace.go:171","msg":"trace[1559032320] linearizableReadLoop","detail":"{readStateIndex:19880; appliedIndex:19878; }","duration":"412.234999ms","start":"2026-08-05T20:07:25.605053Z","end":"2026-08-05T20:07:26.017288Z","steps":["trace[1559032320] 'read index received' (duration: 411.044296ms)","trace[1559032320] 'applied index is now lower than readState.Index' (duration: 1.189773ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T20:07:26.017739Z","caller":"traceutil/trace.go:171","msg":"trace[537034255] transaction","detail":"{read_only:false; response_revision:19232; number_of_response:1; }","duration":"466.481264ms","start":"2026-08-05T20:07:25.551203Z","end":"2026-08-05T20:07:26.017684Z","steps":["trace[537034255] 'process raft request' (duration: 465.943939ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:26.017852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.115991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T20:07:26.017898Z","caller":"traceutil/trace.go:171","msg":"trace[454652008] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:19232; }","duration":"406.240333ms","start":"2026-08-05T20:07:25.611646Z","end":"2026-08-05T20:07:26.017887Z","steps":["trace[454652008] 'agreement among raft nodes before linearized reading' (duration: 406.143711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:26.017932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:25.611616Z","time spent":"406.305605ms","remote":"127.0.0.1:37062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":30,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-05T20:07:26.017942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:25.551182Z","time spent":"466.640428ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T20:07:26.018063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.452148ms","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-08-05T20:07:26.018117Z","caller":"traceutil/trace.go:171","msg":"trace[1967470287] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19232; }","duration":"104.52133ms","start":"2026-08-05T20:07:25.91358Z","end":"2026-08-05T20:07:26.018101Z","steps":["trace[1967470287] 'agreement among raft nodes before linearized reading' (duration: 104.340105ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:26.018206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.541776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-05T20:07:26.018235Z","caller":"traceutil/trace.go:171","msg":"trace[277754594] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19232; }","duration":"110.607208ms","start":"2026-08-05T20:07:25.907622Z","end":"2026-08-05T20:07:26.018229Z","steps":["trace[277754594] 'agreement among raft nodes before linearized reading' (duration: 110.480684ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:26.018354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.983687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-mm6cz\" ","response":"range_response_count:1 size:22097"} {"level":"info","ts":"2026-08-05T20:07:26.018423Z","caller":"traceutil/trace.go:171","msg":"trace[1469004753] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-mm6cz; range_end:; response_count:1; response_revision:19232; }","duration":"369.09086ms","start":"2026-08-05T20:07:25.649315Z","end":"2026-08-05T20:07:26.018406Z","steps":["trace[1469004753] 'agreement among raft nodes before linearized reading' (duration: 368.899334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:26.018473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:25.649294Z","time spent":"369.167072ms","remote":"127.0.0.1:36950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22121,"request content":"key:\"/registry/pods/openstack/nova-compute-default-mm6cz\" "} {"level":"warn","ts":"2026-08-05T20:07:26.018477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.094834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-05T20:07:26.018377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.333109ms","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-08-05T20:07:26.018525Z","caller":"traceutil/trace.go:171","msg":"trace[1725282707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19232; }","duration":"155.204307ms","start":"2026-08-05T20:07:25.863307Z","end":"2026-08-05T20:07:26.018511Z","steps":["trace[1725282707] 'agreement among raft nodes before linearized reading' (duration: 155.110334ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:07:26.018556Z","caller":"traceutil/trace.go:171","msg":"trace[460084223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19232; }","duration":"413.555705ms","start":"2026-08-05T20:07:25.604992Z","end":"2026-08-05T20:07:26.018548Z","steps":["trace[460084223] 'agreement among raft nodes before linearized reading' (duration: 413.280047ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:26.018591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:25.604954Z","time spent":"413.628627ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-05T20:07:30.419962Z","caller":"traceutil/trace.go:171","msg":"trace[2117589775] transaction","detail":"{read_only:false; response_revision:19261; number_of_response:1; }","duration":"381.533723ms","start":"2026-08-05T20:07:30.038412Z","end":"2026-08-05T20:07:30.419945Z","steps":["trace[2117589775] 'process raft request' (duration: 381.41753ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:30.420093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.038383Z","time spent":"381.645206ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T20:07:30.420412Z","caller":"traceutil/trace.go:171","msg":"trace[287336554] linearizableReadLoop","detail":"{readStateIndex:19910; appliedIndex:19910; }","duration":"372.498844ms","start":"2026-08-05T20:07:30.047905Z","end":"2026-08-05T20:07:30.420404Z","steps":["trace[287336554] 'read index received' (duration: 372.495664ms)","trace[287336554] 'applied index is now lower than readState.Index' (duration: 2.73µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T20:07:30.420514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.611027ms","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-08-05T20:07:30.420531Z","caller":"traceutil/trace.go:171","msg":"trace[1739696566] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19261; }","duration":"372.674829ms","start":"2026-08-05T20:07:30.047851Z","end":"2026-08-05T20:07:30.420526Z","steps":["trace[1739696566] 'agreement among raft nodes before linearized reading' (duration: 372.595327ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:30.420545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.047829Z","time spent":"372.71243ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-05T20:07:30.920907Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569152656054930,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T20:07:31.421236Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569152656054930,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T20:07:32.404762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000122671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-05T20:07:32.404912Z","caller":"traceutil/trace.go:171","msg":"trace[513148913] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000307306s","start":"2026-08-05T20:07:30.404571Z","end":"2026-08-05T20:07:32.404879Z","steps":["trace[513148913] 'agreement among raft nodes before linearized reading' (duration: 2.000117881s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:32.40497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.404542Z","time spent":"2.000418229s","remote":"127.0.0.1:36772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T20:07:32.949979Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569152656054930,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T20:07:32.95074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.548159923s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-05T20:07:32.95087Z","caller":"traceutil/trace.go:171","msg":"trace[360481278] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.548365948s","start":"2026-08-05T20:07:30.402471Z","end":"2026-08-05T20:07:32.950837Z","steps":["trace[360481278] 'agreement among raft nodes before linearized reading' (duration: 2.548199534s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:32.950949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.402446Z","time spent":"2.548491461s","remote":"127.0.0.1:36774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T20:07:34.317076Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569152656054930,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T20:07:34.41473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000315267s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-05T20:07:34.414801Z","caller":"traceutil/trace.go:171","msg":"trace[953215742] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000453181s","start":"2026-08-05T20:07:32.414332Z","end":"2026-08-05T20:07:34.414785Z","steps":["trace[953215742] 'agreement among raft nodes before linearized reading' (duration: 2.000344108s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.414842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:32.41431Z","time spent":"2.000524262s","remote":"127.0.0.1:36774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T20:07:34.93255Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569152656054930,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-05T20:07:34.941708Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.533778863s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-05T20:07:34.945092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:34.378124Z","time spent":"566.958242ms","remote":"127.0.0.1:47426","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-05T20:07:34.946167Z","caller":"traceutil/trace.go:171","msg":"trace[1235464053] transaction","detail":"{read_only:false; response_revision:19262; number_of_response:1; }","duration":"4.542242607s","start":"2026-08-05T20:07:30.40387Z","end":"2026-08-05T20:07:34.946113Z","steps":["trace[1235464053] 'process raft request' (duration: 4.541410384s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.946401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.403851Z","time spent":"4.542430842s","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T20:07:34.952094Z","caller":"traceutil/trace.go:171","msg":"trace[273339958] transaction","detail":"{read_only:false; response_revision:19263; number_of_response:1; }","duration":"4.529898497s","start":"2026-08-05T20:07:30.422173Z","end":"2026-08-05T20:07:34.952071Z","steps":["trace[273339958] 'process raft request' (duration: 4.529532807s)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:07:34.952101Z","caller":"traceutil/trace.go:171","msg":"trace[1414979363] transaction","detail":"{read_only:false; response_revision:19265; number_of_response:1; }","duration":"4.422625971s","start":"2026-08-05T20:07:30.529447Z","end":"2026-08-05T20:07:34.952073Z","steps":["trace[1414979363] 'process raft request' (duration: 4.422428995s)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:07:34.952077Z","caller":"traceutil/trace.go:171","msg":"trace[1890206658] transaction","detail":"{read_only:false; response_revision:19264; number_of_response:1; }","duration":"4.527167081s","start":"2026-08-05T20:07:30.424846Z","end":"2026-08-05T20:07:34.952013Z","steps":["trace[1890206658] 'process raft request' (duration: 4.526977806s)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:07:34.952078Z","caller":"traceutil/trace.go:171","msg":"trace[1312652291] transaction","detail":"{read_only:false; response_revision:19266; number_of_response:1; }","duration":"4.327564412s","start":"2026-08-05T20:07:30.624443Z","end":"2026-08-05T20:07:34.952008Z","steps":["trace[1312652291] 'process raft request' (duration: 4.327469679s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.952191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.422164Z","time spent":"4.529978619s","remote":"127.0.0.1:36848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":890,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-05T20:07:34.95224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.529427Z","time spent":"4.422722663s","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T20:07:34.952294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.424839Z","time spent":"4.527340486s","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T20:07:34.952325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.624429Z","time spent":"4.327765127s","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T20:07:34.952474Z","caller":"traceutil/trace.go:171","msg":"trace[1624636832] transaction","detail":"{read_only:false; response_revision:19269; number_of_response:1; }","duration":"2.527803091s","start":"2026-08-05T20:07:32.42466Z","end":"2026-08-05T20:07:34.952464Z","steps":["trace[1624636832] 'process raft request' (duration: 2.527336909s)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:07:34.952493Z","caller":"traceutil/trace.go:171","msg":"trace[1532045703] transaction","detail":"{read_only:false; response_revision:19268; number_of_response:1; }","duration":"2.681546778s","start":"2026-08-05T20:07:32.270926Z","end":"2026-08-05T20:07:34.952473Z","steps":["trace[1532045703] 'process raft request' (duration: 2.681040654s)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:07:34.952524Z","caller":"traceutil/trace.go:171","msg":"trace[650532462] transaction","detail":"{read_only:false; response_revision:19267; number_of_response:1; }","duration":"2.995138049s","start":"2026-08-05T20:07:31.957373Z","end":"2026-08-05T20:07:34.952511Z","steps":["trace[650532462] 'process raft request' (duration: 2.994567543s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.952527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:32.424641Z","time spent":"2.527857633s","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T20:07:34.952557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:32.270894Z","time spent":"2.6816266s","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T20:07:34.952575Z","caller":"traceutil/trace.go:171","msg":"trace[1833346311] transaction","detail":"{read_only:false; response_revision:19270; number_of_response:1; }","duration":"635.562813ms","start":"2026-08-05T20:07:34.31699Z","end":"2026-08-05T20:07:34.952552Z","steps":["trace[1833346311] 'process raft request' (duration: 635.036638ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.952579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:31.957342Z","time spent":"2.995209461s","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T20:07:34.952658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:34.316972Z","time spent":"635.629545ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T20:07:34.955887Z","caller":"traceutil/trace.go:171","msg":"trace[1716926344] linearizableReadLoop","detail":"{readStateIndex:19920; appliedIndex:19910; }","duration":"4.535428159s","start":"2026-08-05T20:07:30.420433Z","end":"2026-08-05T20:07:34.955861Z","steps":["trace[1716926344] 'read index received' (duration: 4.524775566s)","trace[1716926344] 'applied index is now lower than readState.Index' (duration: 10.651273ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T20:07:34.956209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.536401615s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-mm6cz\" ","response":"range_response_count:1 size:22421"} {"level":"info","ts":"2026-08-05T20:07:34.956248Z","caller":"traceutil/trace.go:171","msg":"trace[1077610699] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-mm6cz; range_end:; response_count:1; response_revision:19270; }","duration":"4.536452477s","start":"2026-08-05T20:07:30.419782Z","end":"2026-08-05T20:07:34.956234Z","steps":["trace[1077610699] 'agreement among raft nodes before linearized reading' (duration: 4.536359745s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.956273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.419776Z","time spent":"4.536488118s","remote":"127.0.0.1:36950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22445,"request content":"key:\"/registry/pods/openstack/nova-compute-default-mm6cz\" "} {"level":"warn","ts":"2026-08-05T20:07:34.956393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.328063195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T20:07:34.95642Z","caller":"traceutil/trace.go:171","msg":"trace[1427130031] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:19271; }","duration":"4.328092316s","start":"2026-08-05T20:07:30.62832Z","end":"2026-08-05T20:07:34.956413Z","steps":["trace[1427130031] 'agreement among raft nodes before linearized reading' (duration: 4.328012604s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.956439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.628288Z","time spent":"4.328146247s","remote":"127.0.0.1:37000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" "} {"level":"warn","ts":"2026-08-05T20:07:34.956588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.553158848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-08-05T20:07:34.9566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.826083933s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-08-05T20:07:34.956584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.770290286s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-4crzc\" ","response":"range_response_count:1 size:9791"} {"level":"warn","ts":"2026-08-05T20:07:34.956648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.061237343s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T20:07:34.956664Z","caller":"traceutil/trace.go:171","msg":"trace[1046054313] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-4crzc; range_end:; response_count:1; response_revision:19271; }","duration":"3.77041861s","start":"2026-08-05T20:07:31.186227Z","end":"2026-08-05T20:07:34.956646Z","steps":["trace[1046054313] 'agreement among raft nodes before linearized reading' (duration: 3.770206704s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.956703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.062670552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T20:07:34.956722Z","caller":"traceutil/trace.go:171","msg":"trace[1566838278] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:19271; }","duration":"4.062722244s","start":"2026-08-05T20:07:30.893995Z","end":"2026-08-05T20:07:34.956717Z","steps":["trace[1566838278] 'agreement among raft nodes before linearized reading' (duration: 4.062692243s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.956706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:31.186177Z","time spent":"3.770521832s","remote":"127.0.0.1:36950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9815,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-4crzc\" "} {"level":"warn","ts":"2026-08-05T20:07:34.95671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.553509937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29766000-dbxx4\" ","response":"range_response_count:1 size:7036"} {"level":"warn","ts":"2026-08-05T20:07:34.956743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.893973Z","time spent":"4.062765405s","remote":"127.0.0.1:37000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" "} {"level":"info","ts":"2026-08-05T20:07:34.956757Z","caller":"traceutil/trace.go:171","msg":"trace[1991137576] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29766000-dbxx4; range_end:; response_count:1; response_revision:19271; }","duration":"4.5536163s","start":"2026-08-05T20:07:30.403127Z","end":"2026-08-05T20:07:34.956743Z","steps":["trace[1991137576] 'agreement among raft nodes before linearized reading' (duration: 4.553514317s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.956783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.4031Z","time spent":"4.553677371s","remote":"127.0.0.1:36950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":7060,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-29766000-dbxx4\" "} {"level":"warn","ts":"2026-08-05T20:07:34.956845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.062843977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T20:07:34.956878Z","caller":"traceutil/trace.go:171","msg":"trace[1847401487] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:19271; }","duration":"4.062879248s","start":"2026-08-05T20:07:30.893979Z","end":"2026-08-05T20:07:34.956858Z","steps":["trace[1847401487] 'agreement among raft nodes before linearized reading' (duration: 4.062845978s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.9569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.893971Z","time spent":"4.062923549s","remote":"127.0.0.1:37000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" "} {"level":"warn","ts":"2026-08-05T20:07:34.956927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.907635685s","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-08-05T20:07:34.956953Z","caller":"traceutil/trace.go:171","msg":"trace[899674104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19271; }","duration":"4.907671546s","start":"2026-08-05T20:07:30.049276Z","end":"2026-08-05T20:07:34.956947Z","steps":["trace[899674104] 'agreement among raft nodes before linearized reading' (duration: 4.907574273s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.95698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.049271Z","time spent":"4.907703647s","remote":"127.0.0.1:37006","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-08-05T20:07:34.956998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.063332191s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T20:07:34.957019Z","caller":"traceutil/trace.go:171","msg":"trace[309861869] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:19271; }","duration":"4.063409663s","start":"2026-08-05T20:07:30.893601Z","end":"2026-08-05T20:07:34.957011Z","steps":["trace[309861869] 'agreement among raft nodes before linearized reading' (duration: 4.063376912s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.957035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.893577Z","time spent":"4.063453974s","remote":"127.0.0.1:37000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/heat-bootstrap\" "} {"level":"warn","ts":"2026-08-05T20:07:34.95705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.399319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T20:07:34.957079Z","caller":"traceutil/trace.go:171","msg":"trace[2009468876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19271; }","duration":"541.442199ms","start":"2026-08-05T20:07:34.415628Z","end":"2026-08-05T20:07:34.95707Z","steps":["trace[2009468876] 'agreement among raft nodes before linearized reading' (duration: 541.396058ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.957095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:34.415614Z","time spent":"541.47653ms","remote":"127.0.0.1:36772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T20:07:34.957131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.137451273s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T20:07:34.957152Z","caller":"traceutil/trace.go:171","msg":"trace[2095328688] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:19271; }","duration":"4.137523004s","start":"2026-08-05T20:07:30.819623Z","end":"2026-08-05T20:07:34.957147Z","steps":["trace[2095328688] 'agreement among raft nodes before linearized reading' (duration: 4.137487543s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.95717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.819592Z","time spent":"4.137573566s","remote":"127.0.0.1:37000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" "} {"level":"warn","ts":"2026-08-05T20:07:34.957181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.907919202s","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-08-05T20:07:34.95721Z","caller":"traceutil/trace.go:171","msg":"trace[473758682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19271; }","duration":"4.907965734s","start":"2026-08-05T20:07:30.049238Z","end":"2026-08-05T20:07:34.957204Z","steps":["trace[473758682] 'agreement among raft nodes before linearized reading' (duration: 4.907880402s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.957236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.049233Z","time spent":"4.907998225s","remote":"127.0.0.1:37006","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-08-05T20:07:34.95725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.578044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T20:07:34.957275Z","caller":"traceutil/trace.go:171","msg":"trace[1166488014] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:19271; }","duration":"639.606465ms","start":"2026-08-05T20:07:34.317659Z","end":"2026-08-05T20:07:34.957265Z","steps":["trace[1166488014] 'agreement among raft nodes before linearized reading' (duration: 639.569094ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.957275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.138492402s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T20:07:34.957297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:34.317631Z","time spent":"639.658695ms","remote":"127.0.0.1:54998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-05T20:07:34.957304Z","caller":"traceutil/trace.go:171","msg":"trace[1419384857] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:19271; }","duration":"4.138556433s","start":"2026-08-05T20:07:30.81874Z","end":"2026-08-05T20:07:34.957296Z","steps":["trace[1419384857] 'agreement among raft nodes before linearized reading' (duration: 4.138512922s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.957326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.818676Z","time spent":"4.138644876s","remote":"127.0.0.1:37000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/heat-ks-user\" "} {"level":"warn","ts":"2026-08-05T20:07:34.957425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.138651726s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T20:07:34.957431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.645803ms","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-08-05T20:07:34.957445Z","caller":"traceutil/trace.go:171","msg":"trace[63548102] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:0; response_revision:19271; }","duration":"4.138701247s","start":"2026-08-05T20:07:30.818738Z","end":"2026-08-05T20:07:34.957439Z","steps":["trace[63548102] 'agreement among raft nodes before linearized reading' (duration: 4.138664266s)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:07:34.957453Z","caller":"traceutil/trace.go:171","msg":"trace[1678051981] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:19271; }","duration":"640.712415ms","start":"2026-08-05T20:07:34.316733Z","end":"2026-08-05T20:07:34.957445Z","steps":["trace[1678051981] 'agreement among raft nodes before linearized reading' (duration: 640.667713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.957464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.818731Z","time spent":"4.138729278s","remote":"127.0.0.1:37000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" "} {"level":"warn","ts":"2026-08-05T20:07:34.957472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:34.316713Z","time spent":"640.753446ms","remote":"127.0.0.1:48604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-05T20:07:34.957215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.883126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T20:07:34.957548Z","caller":"traceutil/trace.go:171","msg":"trace[376840868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19271; }","duration":"577.201014ms","start":"2026-08-05T20:07:34.380309Z","end":"2026-08-05T20:07:34.95751Z","steps":["trace[376840868] 'agreement among raft nodes before linearized reading' (duration: 576.888696ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.957583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.138850241s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T20:07:34.957583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:34.380298Z","time spent":"577.277726ms","remote":"127.0.0.1:36774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-05T20:07:34.957605Z","caller":"traceutil/trace.go:171","msg":"trace[1030875147] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:19271; }","duration":"4.138902473s","start":"2026-08-05T20:07:30.818696Z","end":"2026-08-05T20:07:34.957599Z","steps":["trace[1030875147] 'agreement among raft nodes before linearized reading' (duration: 4.138865912s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.957614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.539767097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T20:07:34.957622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.818676Z","time spent":"4.138940854s","remote":"127.0.0.1:37000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/heat-bootstrap\" "} {"level":"info","ts":"2026-08-05T20:07:34.957635Z","caller":"traceutil/trace.go:171","msg":"trace[1564000173] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:19271; }","duration":"1.539798278s","start":"2026-08-05T20:07:33.417831Z","end":"2026-08-05T20:07:34.957629Z","steps":["trace[1564000173] 'agreement among raft nodes before linearized reading' (duration: 1.539757217s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.957652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:33.417826Z","time spent":"1.539821268s","remote":"127.0.0.1:36860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":30,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-08-05T20:07:34.957744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.298074399s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-05T20:07:34.957766Z","caller":"traceutil/trace.go:171","msg":"trace[144662740] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19271; }","duration":"4.298114029s","start":"2026-08-05T20:07:30.659645Z","end":"2026-08-05T20:07:34.957759Z","steps":["trace[144662740] 'agreement among raft nodes before linearized reading' (duration: 4.298049817s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.957774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.540018883s","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":"warn","ts":"2026-08-05T20:07:34.957787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.65963Z","time spent":"4.298151001s","remote":"127.0.0.1:37006","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":"info","ts":"2026-08-05T20:07:34.957794Z","caller":"traceutil/trace.go:171","msg":"trace[1483930996] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:19271; }","duration":"1.540327572s","start":"2026-08-05T20:07:33.41746Z","end":"2026-08-05T20:07:34.957787Z","steps":["trace[1483930996] 'agreement among raft nodes before linearized reading' (duration: 1.540292111s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.957814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:33.417442Z","time spent":"1.540365724s","remote":"127.0.0.1:55106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-05T20:07:34.957887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.327369656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-bootstrap\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T20:07:34.957908Z","caller":"traceutil/trace.go:171","msg":"trace[2082587881] range","detail":"{range_begin:/registry/jobs/openstack/heat-bootstrap; range_end:; response_count:0; response_revision:19271; }","duration":"4.327442467s","start":"2026-08-05T20:07:30.63046Z","end":"2026-08-05T20:07:34.957902Z","steps":["trace[2082587881] 'agreement among raft nodes before linearized reading' (duration: 4.327408016s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.957926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.630435Z","time spent":"4.327487069s","remote":"127.0.0.1:37000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/heat-bootstrap\" "} {"level":"warn","ts":"2026-08-05T20:07:34.95794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.394319257s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T20:07:34.95796Z","caller":"traceutil/trace.go:171","msg":"trace[841849593] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:19271; }","duration":"3.394369469s","start":"2026-08-05T20:07:31.563584Z","end":"2026-08-05T20:07:34.957953Z","steps":["trace[841849593] 'agreement among raft nodes before linearized reading' (duration: 3.394334988s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.957984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:31.563537Z","time spent":"3.394438691s","remote":"127.0.0.1:36986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":30,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-08-05T20:07:34.958012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.327652003s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T20:07:34.958043Z","caller":"traceutil/trace.go:171","msg":"trace[982504254] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:19271; }","duration":"4.327682664s","start":"2026-08-05T20:07:30.63035Z","end":"2026-08-05T20:07:34.958033Z","steps":["trace[982504254] 'agreement among raft nodes before linearized reading' (duration: 4.327614182s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.958061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.630335Z","time spent":"4.327722295s","remote":"127.0.0.1:37000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/heat-ks-user\" "} {"level":"warn","ts":"2026-08-05T20:07:34.958093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.659533624s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T20:07:34.958112Z","caller":"traceutil/trace.go:171","msg":"trace[1710278339] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:19271; }","duration":"3.659587416s","start":"2026-08-05T20:07:31.298518Z","end":"2026-08-05T20:07:34.958105Z","steps":["trace[1710278339] 'agreement among raft nodes before linearized reading' (duration: 3.659552035s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.958121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.329673999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T20:07:34.958129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:31.298495Z","time spent":"3.659628817s","remote":"127.0.0.1:48588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"info","ts":"2026-08-05T20:07:34.958137Z","caller":"traceutil/trace.go:171","msg":"trace[1393861497] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:0; response_revision:19271; }","duration":"4.329730011s","start":"2026-08-05T20:07:30.628402Z","end":"2026-08-05T20:07:34.958132Z","steps":["trace[1393861497] 'agreement among raft nodes before linearized reading' (duration: 4.32970342s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.958154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.628398Z","time spent":"4.329752721s","remote":"127.0.0.1:37000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" "} {"level":"info","ts":"2026-08-05T20:07:34.956619Z","caller":"traceutil/trace.go:171","msg":"trace[487306580] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19271; }","duration":"4.55323522s","start":"2026-08-05T20:07:30.403376Z","end":"2026-08-05T20:07:34.956611Z","steps":["trace[487306580] 'agreement among raft nodes before linearized reading' (duration: 4.553105816s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.958267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.403358Z","time spent":"4.554903455s","remote":"127.0.0.1:37006","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-08-05T20:07:34.958288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.66009884s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T20:07:34.958311Z","caller":"traceutil/trace.go:171","msg":"trace[359238754] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:19271; }","duration":"3.660135831s","start":"2026-08-05T20:07:31.298168Z","end":"2026-08-05T20:07:34.958303Z","steps":["trace[359238754] 'agreement among raft nodes before linearized reading' (duration: 3.66009284s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.958331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:31.298161Z","time spent":"3.660162152s","remote":"127.0.0.1:46618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"warn","ts":"2026-08-05T20:07:34.95845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.660406888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T20:07:34.958469Z","caller":"traceutil/trace.go:171","msg":"trace[1097648148] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:19271; }","duration":"3.66044652s","start":"2026-08-05T20:07:31.298016Z","end":"2026-08-05T20:07:34.958462Z","steps":["trace[1097648148] 'agreement among raft nodes before linearized reading' (duration: 3.660411289s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.958488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:31.298002Z","time spent":"3.66048013s","remote":"127.0.0.1:55082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-08-05T20:07:34.958499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.660762878s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-05T20:07:34.958525Z","caller":"traceutil/trace.go:171","msg":"trace[135359231] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:19271; }","duration":"3.66081644s","start":"2026-08-05T20:07:31.297697Z","end":"2026-08-05T20:07:34.958514Z","steps":["trace[135359231] 'agreement among raft nodes before linearized reading' (duration: 3.660770479s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.958545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:31.297687Z","time spent":"3.660853331s","remote":"127.0.0.1:37004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":32,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-08-05T20:07:34.95855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.727836856s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T20:07:34.958573Z","caller":"traceutil/trace.go:171","msg":"trace[515144277] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:19271; }","duration":"3.727882537s","start":"2026-08-05T20:07:31.230684Z","end":"2026-08-05T20:07:34.958567Z","steps":["trace[515144277] 'agreement among raft nodes before linearized reading' (duration: 3.727836816s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.958592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:31.230673Z","time spent":"3.727912898s","remote":"127.0.0.1:37184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":30,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-08-05T20:07:34.956619Z","caller":"traceutil/trace.go:171","msg":"trace[66957711] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19271; }","duration":"3.826102944s","start":"2026-08-05T20:07:31.130511Z","end":"2026-08-05T20:07:34.956614Z","steps":["trace[66957711] 'agreement among raft nodes before linearized reading' (duration: 3.826035812s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.9587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:31.130427Z","time spent":"3.828266674s","remote":"127.0.0.1:36950","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-08-05T20:07:34.956672Z","caller":"traceutil/trace.go:171","msg":"trace[355551783] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:0; response_revision:19271; }","duration":"4.061271344s","start":"2026-08-05T20:07:30.895395Z","end":"2026-08-05T20:07:34.956666Z","steps":["trace[355551783] 'agreement among raft nodes before linearized reading' (duration: 4.061238583s)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:07:34.958812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:07:30.895387Z","time spent":"4.063418033s","remote":"127.0.0.1:37000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/heat-ks-user\" "} {"level":"info","ts":"2026-08-05T20:07:42.461469Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ca256638ea38eb43","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T20:07:42.467668Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-05T20:07:42.467873Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-05T20:08:45.913169Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17422} {"level":"info","ts":"2026-08-05T20:08:46.015034Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17422,"took":"96.653323ms","hash":2974606133,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":34615296,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-05T20:08:46.015113Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2974606133,"revision":17422,"compact-revision":14923} {"level":"info","ts":"2026-08-05T20:12:16.330306Z","caller":"traceutil/trace.go:171","msg":"trace[1998326714] transaction","detail":"{read_only:false; response_revision:21203; number_of_response:1; }","duration":"129.619411ms","start":"2026-08-05T20:12:16.200638Z","end":"2026-08-05T20:12:16.330258Z","steps":["trace[1998326714] 'process raft request' (duration: 129.424076ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:12:16.684101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.575515ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T20:12:16.684327Z","caller":"traceutil/trace.go:171","msg":"trace[1502550319] linearizableReadLoop","detail":"{readStateIndex:21922; appliedIndex:21919; }","duration":"194.307904ms","start":"2026-08-05T20:12:16.490006Z","end":"2026-08-05T20:12:16.684314Z","steps":["trace[1502550319] 'read index received' (duration: 66.341998ms)","trace[1502550319] 'applied index is now lower than readState.Index' (duration: 127.965256ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T20:12:16.684463Z","caller":"traceutil/trace.go:171","msg":"trace[641223650] transaction","detail":"{read_only:false; response_revision:21205; number_of_response:1; }","duration":"480.584592ms","start":"2026-08-05T20:12:16.203814Z","end":"2026-08-05T20:12:16.684399Z","steps":["trace[641223650] 'process raft request' (duration: 480.375186ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:12:16.684559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.558281ms","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-08-05T20:12:16.684587Z","caller":"traceutil/trace.go:171","msg":"trace[56859073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21206; }","duration":"194.645033ms","start":"2026-08-05T20:12:16.489934Z","end":"2026-08-05T20:12:16.68458Z","steps":["trace[56859073] 'agreement among raft nodes before linearized reading' (duration: 194.471288ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:12:16.684573Z","caller":"traceutil/trace.go:171","msg":"trace[1421813480] transaction","detail":"{read_only:false; response_revision:21204; number_of_response:1; }","duration":"482.353701ms","start":"2026-08-05T20:12:16.202182Z","end":"2026-08-05T20:12:16.684536Z","steps":["trace[1421813480] 'process raft request' (duration: 354.258891ms)","trace[1421813480] 'compare' (duration: 127.332209ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T20:12:16.684713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:12:16.202154Z","time spent":"482.480184ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T20:12:16.684719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:12:16.203796Z","time spent":"480.785107ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T20:12:16.684758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.641813ms","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-08-05T20:12:16.684858Z","caller":"traceutil/trace.go:171","msg":"trace[1503750078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21206; }","duration":"194.812288ms","start":"2026-08-05T20:12:16.490022Z","end":"2026-08-05T20:12:16.684834Z","steps":["trace[1503750078] 'agreement among raft nodes before linearized reading' (duration: 194.51681ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:12:16.68509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.369601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-05T20:12:16.685127Z","caller":"traceutil/trace.go:171","msg":"trace[1664618474] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21206; }","duration":"192.438763ms","start":"2026-08-05T20:12:16.492679Z","end":"2026-08-05T20:12:16.685118Z","steps":["trace[1664618474] 'agreement among raft nodes before linearized reading' (duration: 192.34715ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:12:16.685217Z","caller":"traceutil/trace.go:171","msg":"trace[58442903] transaction","detail":"{read_only:false; response_revision:21206; number_of_response:1; }","duration":"476.006546ms","start":"2026-08-05T20:12:16.208396Z","end":"2026-08-05T20:12:16.684402Z","steps":["trace[58442903] 'process raft request' (duration: 475.882813ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:12:16.685303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:12:16.208358Z","time spent":"476.903121ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T20:12:16.685435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.368675ms","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-08-05T20:12:16.685485Z","caller":"traceutil/trace.go:171","msg":"trace[89933309] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21206; }","duration":"194.498519ms","start":"2026-08-05T20:12:16.490968Z","end":"2026-08-05T20:12:16.685467Z","steps":["trace[89933309] 'agreement among raft nodes before linearized reading' (duration: 194.386766ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:12:16.685983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.736353ms","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-08-05T20:12:16.686045Z","caller":"traceutil/trace.go:171","msg":"trace[1958309669] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21206; }","duration":"195.815136ms","start":"2026-08-05T20:12:16.490217Z","end":"2026-08-05T20:12:16.686033Z","steps":["trace[1958309669] 'agreement among raft nodes before linearized reading' (duration: 195.60502ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:12:16.979335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.453435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T20:12:16.979526Z","caller":"traceutil/trace.go:171","msg":"trace[651750772] transaction","detail":"{read_only:false; response_revision:21207; number_of_response:1; }","duration":"289.731603ms","start":"2026-08-05T20:12:16.689765Z","end":"2026-08-05T20:12:16.979497Z","steps":["trace[651750772] 'process raft request' (duration: 109.941469ms)","trace[651750772] 'compare' (duration: 179.026083ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T20:12:16.990828Z","caller":"traceutil/trace.go:171","msg":"trace[886325935] transaction","detail":"{read_only:false; response_revision:21208; number_of_response:1; }","duration":"299.847552ms","start":"2026-08-05T20:12:16.690928Z","end":"2026-08-05T20:12:16.990776Z","steps":["trace[886325935] 'process raft request' (duration: 299.572384ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:12:16.990855Z","caller":"traceutil/trace.go:171","msg":"trace[492064158] transaction","detail":"{read_only:false; response_revision:21210; number_of_response:1; }","duration":"298.976057ms","start":"2026-08-05T20:12:16.69182Z","end":"2026-08-05T20:12:16.990796Z","steps":["trace[492064158] 'process raft request' (duration: 298.861284ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:12:16.991073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:12:16.690907Z","time spent":"300.023176ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T20:12:16.991235Z","caller":"traceutil/trace.go:171","msg":"trace[1566286812] transaction","detail":"{read_only:false; response_revision:21209; number_of_response:1; }","duration":"300.180631ms","start":"2026-08-05T20:12:16.69104Z","end":"2026-08-05T20:12:16.991221Z","steps":["trace[1566286812] 'process raft request' (duration: 299.603085ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:12:16.991079Z","caller":"traceutil/trace.go:171","msg":"trace[1664299555] transaction","detail":"{read_only:false; response_revision:21211; number_of_response:1; }","duration":"299.153373ms","start":"2026-08-05T20:12:16.691909Z","end":"2026-08-05T20:12:16.991063Z","steps":["trace[1664299555] 'process raft request' (duration: 298.814893ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:12:16.991359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T20:12:16.690985Z","time spent":"300.278523ms","remote":"127.0.0.1:37006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T20:13:45.920396Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19734} {"level":"info","ts":"2026-08-05T20:13:46.044162Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19734,"took":"119.7686ms","hash":2256056971,"current-db-size-bytes":58945536,"current-db-size":"59 MB","current-db-size-in-use-bytes":30142464,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-05T20:13:46.044251Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2256056971,"revision":19734,"compact-revision":17422} {"level":"warn","ts":"2026-08-05T20:16:23.707498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.894985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-08-05T20:16:23.70759Z","caller":"traceutil/trace.go:171","msg":"trace[675688521] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:23749; }","duration":"200.053909ms","start":"2026-08-05T20:16:23.507518Z","end":"2026-08-05T20:16:23.707572Z","steps":["trace[675688521] 'range keys from in-memory index tree' (duration: 199.697689ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:16:23.748469Z","caller":"traceutil/trace.go:171","msg":"trace[1216015927] transaction","detail":"{read_only:false; response_revision:23752; number_of_response:1; }","duration":"109.415964ms","start":"2026-08-05T20:16:23.639036Z","end":"2026-08-05T20:16:23.748452Z","steps":["trace[1216015927] 'process raft request' (duration: 109.370643ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:16:23.748573Z","caller":"traceutil/trace.go:171","msg":"trace[891406037] transaction","detail":"{read_only:false; response_revision:23751; number_of_response:1; }","duration":"110.615356ms","start":"2026-08-05T20:16:23.63793Z","end":"2026-08-05T20:16:23.748545Z","steps":["trace[891406037] 'process raft request' (duration: 110.36542ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:17:55.006225Z","caller":"traceutil/trace.go:171","msg":"trace[439829524] transaction","detail":"{read_only:false; response_revision:24703; number_of_response:1; }","duration":"126.01467ms","start":"2026-08-05T20:17:54.88019Z","end":"2026-08-05T20:17:55.006204Z","steps":["trace[439829524] 'process raft request' (duration: 125.914608ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:17:55.00634Z","caller":"traceutil/trace.go:171","msg":"trace[1136206766] transaction","detail":"{read_only:false; response_revision:24705; number_of_response:1; }","duration":"124.647713ms","start":"2026-08-05T20:17:54.881674Z","end":"2026-08-05T20:17:55.006322Z","steps":["trace[1136206766] 'process raft request' (duration: 124.573271ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:17:55.006585Z","caller":"traceutil/trace.go:171","msg":"trace[1724512333] transaction","detail":"{read_only:false; response_revision:24702; number_of_response:1; }","duration":"126.399281ms","start":"2026-08-05T20:17:54.880156Z","end":"2026-08-05T20:17:55.006555Z","steps":["trace[1724512333] 'process raft request' (duration: 97.334471ms)","trace[1724512333] 'compare' (duration: 28.458223ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T20:17:55.00706Z","caller":"traceutil/trace.go:171","msg":"trace[1650651783] transaction","detail":"{read_only:false; response_revision:24704; number_of_response:1; }","duration":"126.221446ms","start":"2026-08-05T20:17:54.880234Z","end":"2026-08-05T20:17:55.006455Z","steps":["trace[1650651783] 'process raft request' (duration: 125.943939ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:18:45.92603Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21993} {"level":"info","ts":"2026-08-05T20:18:46.039857Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21993,"took":"105.967819ms","hash":1779078292,"current-db-size-bytes":60633088,"current-db-size":"61 MB","current-db-size-in-use-bytes":44453888,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-05T20:18:46.039914Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1779078292,"revision":21993,"compact-revision":19734} {"level":"info","ts":"2026-08-05T20:21:14.085345Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069d1.wal"} {"level":"info","ts":"2026-08-05T20:23:45.934507Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25099} {"level":"info","ts":"2026-08-05T20:23:46.085762Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25099,"took":"147.870774ms","hash":3291298533,"current-db-size-bytes":60633088,"current-db-size":"61 MB","current-db-size-in-use-bytes":30789632,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-05T20:23:46.085828Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3291298533,"revision":25099,"compact-revision":21993} {"level":"warn","ts":"2026-08-05T20:25:39.471042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.149567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T20:25:39.471115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.341213ms","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-08-05T20:25:39.47115Z","caller":"traceutil/trace.go:171","msg":"trace[457495276] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:28207; }","duration":"101.279631ms","start":"2026-08-05T20:25:39.369848Z","end":"2026-08-05T20:25:39.471128Z","steps":["trace[457495276] 'agreement among raft nodes before linearized reading' (duration: 101.120906ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:25:39.471199Z","caller":"traceutil/trace.go:171","msg":"trace[1078291358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28207; }","duration":"101.427255ms","start":"2026-08-05T20:25:39.369739Z","end":"2026-08-05T20:25:39.471166Z","steps":["trace[1078291358] 'agreement among raft nodes before linearized reading' (duration: 101.202198ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:25:41.529201Z","caller":"traceutil/trace.go:171","msg":"trace[2111726084] linearizableReadLoop","detail":"{readStateIndex:29137; appliedIndex:29136; }","duration":"160.264596ms","start":"2026-08-05T20:25:41.368906Z","end":"2026-08-05T20:25:41.52917Z","steps":["trace[2111726084] 'read index received' (duration: 159.448423ms)","trace[2111726084] 'applied index is now lower than readState.Index' (duration: 814.843µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T20:25:41.529326Z","caller":"traceutil/trace.go:171","msg":"trace[392306684] transaction","detail":"{read_only:false; response_revision:28219; number_of_response:1; }","duration":"160.375499ms","start":"2026-08-05T20:25:41.368887Z","end":"2026-08-05T20:25:41.529262Z","steps":["trace[392306684] 'process raft request' (duration: 159.453114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:25:41.533895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.956686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/heat-engine-cleaner-29766010-trmn6.18c901b509f67870\\000\" range_end:\"/registry/events/openstack0\" limit:1000 revision:28218 ","response":"range_response_count:1000 size:782191"} {"level":"info","ts":"2026-08-05T20:25:41.533976Z","caller":"traceutil/trace.go:171","msg":"trace[737594019] range","detail":"{range_begin:/registry/events/openstack/heat-engine-cleaner-29766010-trmn6.18c901b509f67870\u0000; range_end:/registry/events/openstack0; response_count:1000; response_revision:28219; }","duration":"165.054439ms","start":"2026-08-05T20:25:41.368901Z","end":"2026-08-05T20:25:41.533956Z","steps":["trace[737594019] 'agreement among raft nodes before linearized reading' (duration: 160.39466ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:25:41.534195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.323466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-05T20:25:41.534311Z","caller":"traceutil/trace.go:171","msg":"trace[1708317578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28220; }","duration":"128.462249ms","start":"2026-08-05T20:25:41.405815Z","end":"2026-08-05T20:25:41.534277Z","steps":["trace[1708317578] 'agreement among raft nodes before linearized reading' (duration: 128.211723ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:25:41.534659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.631168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/keystone-api-certs\" ","response":"range_response_count:1 size:4315"} {"level":"info","ts":"2026-08-05T20:25:41.534753Z","caller":"traceutil/trace.go:171","msg":"trace[898720935] range","detail":"{range_begin:/registry/secrets/openstack/keystone-api-certs; range_end:; response_count:1; response_revision:28220; }","duration":"148.153632ms","start":"2026-08-05T20:25:41.386575Z","end":"2026-08-05T20:25:41.534729Z","steps":["trace[898720935] 'agreement among raft nodes before linearized reading' (duration: 147.338799ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:25:43.954098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.582687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/keystone-keystone-test\" ","response":"range_response_count:1 size:1077"} {"level":"info","ts":"2026-08-05T20:25:43.954197Z","caller":"traceutil/trace.go:171","msg":"trace[835279920] range","detail":"{range_begin:/registry/secrets/openstack/keystone-keystone-test; range_end:; response_count:1; response_revision:28238; }","duration":"118.738041ms","start":"2026-08-05T20:25:43.83544Z","end":"2026-08-05T20:25:43.954178Z","steps":["trace[835279920] 'range keys from in-memory index tree' (duration: 118.412863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:25:45.292121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.381976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/libvirt-api-ca\" ","response":"range_response_count:1 size:2419"} {"level":"info","ts":"2026-08-05T20:25:45.292208Z","caller":"traceutil/trace.go:171","msg":"trace[668160518] range","detail":"{range_begin:/registry/secrets/openstack/libvirt-api-ca; range_end:; response_count:1; response_revision:28244; }","duration":"100.524699ms","start":"2026-08-05T20:25:45.191667Z","end":"2026-08-05T20:25:45.292192Z","steps":["trace[668160518] 'range keys from in-memory index tree' (duration: 100.211531ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:25:45.45918Z","caller":"traceutil/trace.go:171","msg":"trace[1550243124] linearizableReadLoop","detail":"{readStateIndex:29164; appliedIndex:29163; }","duration":"107.916784ms","start":"2026-08-05T20:25:45.351235Z","end":"2026-08-05T20:25:45.459152Z","steps":["trace[1550243124] 'read index received' (duration: 107.741689ms)","trace[1550243124] 'applied index is now lower than readState.Index' (duration: 174.045µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T20:25:45.459291Z","caller":"traceutil/trace.go:171","msg":"trace[2001263386] transaction","detail":"{read_only:false; response_revision:28245; number_of_response:1; }","duration":"112.525851ms","start":"2026-08-05T20:25:45.34673Z","end":"2026-08-05T20:25:45.459256Z","steps":["trace[2001263386] 'process raft request' (duration: 112.264624ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:25:45.459329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.81649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-05T20:25:45.459326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.072728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T20:25:45.459371Z","caller":"traceutil/trace.go:171","msg":"trace[756363746] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:28245; }","duration":"107.882163ms","start":"2026-08-05T20:25:45.351477Z","end":"2026-08-05T20:25:45.459359Z","steps":["trace[756363746] 'agreement among raft nodes before linearized reading' (duration: 107.817031ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:25:45.459386Z","caller":"traceutil/trace.go:171","msg":"trace[1767821566] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:28245; }","duration":"108.14881ms","start":"2026-08-05T20:25:45.351221Z","end":"2026-08-05T20:25:45.45937Z","steps":["trace[1767821566] 'agreement among raft nodes before linearized reading' (duration: 108.037287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:25:45.459476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.923834ms","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-08-05T20:25:45.459537Z","caller":"traceutil/trace.go:171","msg":"trace[1089677131] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:28245; }","duration":"108.042797ms","start":"2026-08-05T20:25:45.351479Z","end":"2026-08-05T20:25:45.459522Z","steps":["trace[1089677131] 'agreement among raft nodes before linearized reading' (duration: 107.910443ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:25:45.459555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.63743ms","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-08-05T20:25:45.459619Z","caller":"traceutil/trace.go:171","msg":"trace[1707371119] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28245; }","duration":"101.759924ms","start":"2026-08-05T20:25:45.357838Z","end":"2026-08-05T20:25:45.459598Z","steps":["trace[1707371119] 'agreement among raft nodes before linearized reading' (duration: 101.527978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T20:26:26.031546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.783168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-08-05T20:26:26.031561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.237241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/rook-ceph/cephfs-csi-provisioner-role-cfg\" ","response":"range_response_count:1 size:776"} {"level":"info","ts":"2026-08-05T20:26:26.031628Z","caller":"traceutil/trace.go:171","msg":"trace[1016936944] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28542; }","duration":"144.903862ms","start":"2026-08-05T20:26:25.886709Z","end":"2026-08-05T20:26:26.031613Z","steps":["trace[1016936944] 'range keys from in-memory index tree' (duration: 144.628024ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T20:26:26.031643Z","caller":"traceutil/trace.go:171","msg":"trace[1581614926] range","detail":"{range_begin:/registry/rolebindings/rook-ceph/cephfs-csi-provisioner-role-cfg; range_end:; response_count:1; response_revision:28542; }","duration":"129.347553ms","start":"2026-08-05T20:26:25.902273Z","end":"2026-08-05T20:26:26.031621Z","steps":["trace[1581614926] 'range keys from in-memory index tree' (duration: 129.081466ms)"],"step_count":1}