{"level":"warn","ts":"2026-08-15T04:59:40.907177Z","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-15T04:59:40.90726Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74: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.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74: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-15T04:59:40.907318Z","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-15T04:59:40.907327Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-08-15T04:59:40.907346Z","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-15T04:59:40.90787Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-08-15T04:59:40.907936Z","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.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74: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-15T04:59:40.910827Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.524233ms"} {"level":"info","ts":"2026-08-15T04:59:40.916059Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-08-15T04:59:40.916119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-08-15T04:59:40.91615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-08-15T04:59:40.916173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-15T04:59:40.916184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-08-15T04:59:40.916228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-08-15T04:59:40.920841Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-15T04:59:40.923335Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-15T04:59:40.925481Z","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-15T04:59:40.927815Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-15T04:59:40.927921Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-15T04:59:40.928268Z","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-15T04:59:40.92833Z","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-15T04:59:40.92839Z","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-15T04:59:40.929351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-08-15T04:59:40.929514Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-08-15T04:59:40.931454Z","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-15T04:59:40.931634Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-08-15T04:59:40.931708Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-08-15T04:59:40.931682Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-15T04:59:40.931733Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-15T04:59:41.117062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-08-15T04:59:41.117139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-15T04:59:41.117164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-08-15T04:59:41.117183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-08-15T04:59:41.117194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-08-15T04:59:41.11721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-08-15T04:59:41.117229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-08-15T04:59:41.118429Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-08-15T04:59:41.118632Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-15T04:59:41.118697Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-15T04:59:41.118756Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T04:59:41.119389Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-15T04:59:41.119424Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-15T04:59:41.122948Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-08-15T04:59:41.123044Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-15T04:59:41.124465Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T04:59:41.124537Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T04:59:41.124564Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T04:59:58.813972Z","caller":"traceutil/trace.go:171","msg":"trace[136708981] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"103.556389ms","start":"2026-08-15T04:59:58.710391Z","end":"2026-08-15T04:59:58.813947Z","steps":["trace[136708981] 'process raft request' (duration: 103.335773ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T04:59:58.933439Z","caller":"traceutil/trace.go:171","msg":"trace[2050459629] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"115.337725ms","start":"2026-08-15T04:59:58.818079Z","end":"2026-08-15T04:59:58.933416Z","steps":["trace[2050459629] 'process raft request' (duration: 114.404492ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:15.50684Z","caller":"traceutil/trace.go:171","msg":"trace[1610795190] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"130.80862ms","start":"2026-08-15T05:00:15.376006Z","end":"2026-08-15T05:00:15.506815Z","steps":["trace[1610795190] 'process raft request' (duration: 130.459781ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:15.506941Z","caller":"traceutil/trace.go:171","msg":"trace[1449133222] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"128.876423ms","start":"2026-08-15T05:00:15.378014Z","end":"2026-08-15T05:00:15.50689Z","steps":["trace[1449133222] 'process raft request' (duration: 128.613567ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:15.506956Z","caller":"traceutil/trace.go:171","msg":"trace[2094158934] transaction","detail":"{read_only:false; number_of_response:1; response_revision:483; }","duration":"128.843672ms","start":"2026-08-15T05:00:15.378074Z","end":"2026-08-15T05:00:15.506918Z","steps":["trace[2094158934] 'process raft request' (duration: 128.631617ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:15.507159Z","caller":"traceutil/trace.go:171","msg":"trace[1272170153] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"128.181196ms","start":"2026-08-15T05:00:15.378971Z","end":"2026-08-15T05:00:15.507152Z","steps":["trace[1272170153] 'process raft request' (duration: 127.862108ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:15.507259Z","caller":"traceutil/trace.go:171","msg":"trace[291997195] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"118.270597ms","start":"2026-08-15T05:00:15.388978Z","end":"2026-08-15T05:00:15.507249Z","steps":["trace[291997195] 'process raft request' (duration: 118.215566ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:15.507353Z","caller":"traceutil/trace.go:171","msg":"trace[1395282516] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"125.087702ms","start":"2026-08-15T05:00:15.382258Z","end":"2026-08-15T05:00:15.507345Z","steps":["trace[1395282516] 'process raft request' (duration: 124.812295ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:15.507393Z","caller":"traceutil/trace.go:171","msg":"trace[1925138184] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"118.442581ms","start":"2026-08-15T05:00:15.388929Z","end":"2026-08-15T05:00:15.507372Z","steps":["trace[1925138184] 'process raft request' (duration: 118.221085ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:15.513819Z","caller":"traceutil/trace.go:171","msg":"trace[144393104] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:498; }","duration":"100.212459ms","start":"2026-08-15T05:00:15.413587Z","end":"2026-08-15T05:00:15.513799Z","steps":["trace[144393104] 'read index received' (duration: 93.001054ms)","trace[144393104] 'applied index is now lower than readState.Index' (duration: 7.210635ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:00:15.513856Z","caller":"traceutil/trace.go:171","msg":"trace[1425440281] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"109.565075ms","start":"2026-08-15T05:00:15.40426Z","end":"2026-08-15T05:00:15.513825Z","steps":["trace[1425440281] 'process raft request' (duration: 109.37197ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:15.513944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.341612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:00:15.513986Z","caller":"traceutil/trace.go:171","msg":"trace[568988024] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:494; }","duration":"100.434054ms","start":"2026-08-15T05:00:15.413545Z","end":"2026-08-15T05:00:15.513979Z","steps":["trace[568988024] 'agreement among raft nodes before linearized reading' (duration: 100.338372ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:15.514217Z","caller":"traceutil/trace.go:171","msg":"trace[879916264] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"109.36994ms","start":"2026-08-15T05:00:15.404837Z","end":"2026-08-15T05:00:15.514207Z","steps":["trace[879916264] 'process raft request' (duration: 108.818697ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:15.514232Z","caller":"traceutil/trace.go:171","msg":"trace[26760742] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"110.705103ms","start":"2026-08-15T05:00:15.403519Z","end":"2026-08-15T05:00:15.514224Z","steps":["trace[26760742] 'process raft request' (duration: 109.933065ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:15.514333Z","caller":"traceutil/trace.go:171","msg":"trace[1337733535] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"110.725953ms","start":"2026-08-15T05:00:15.403601Z","end":"2026-08-15T05:00:15.514327Z","steps":["trace[1337733535] 'process raft request' (duration: 109.999335ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:15.514347Z","caller":"traceutil/trace.go:171","msg":"trace[1969237000] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"101.757926ms","start":"2026-08-15T05:00:15.41258Z","end":"2026-08-15T05:00:15.514338Z","steps":["trace[1969237000] 'process raft request' (duration: 101.10332ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:21.609687Z","caller":"traceutil/trace.go:171","msg":"trace[821662713] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"267.170224ms","start":"2026-08-15T05:00:21.342498Z","end":"2026-08-15T05:00:21.609668Z","steps":["trace[821662713] 'read index received' (duration: 267.00195ms)","trace[821662713] 'applied index is now lower than readState.Index' (duration: 167.444µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:00:21.609801Z","caller":"traceutil/trace.go:171","msg":"trace[1158862541] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"279.244568ms","start":"2026-08-15T05:00:21.330545Z","end":"2026-08-15T05:00:21.609789Z","steps":["trace[1158862541] 'process raft request' (duration: 279.002272ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:21.610101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.781887ms","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\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T05:00:21.61018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.890079ms","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:5"} {"level":"info","ts":"2026-08-15T05:00:21.610191Z","caller":"traceutil/trace.go:171","msg":"trace[1797874036] 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:585; }","duration":"153.906209ms","start":"2026-08-15T05:00:21.456265Z","end":"2026-08-15T05:00:21.610171Z","steps":["trace[1797874036] 'agreement among raft nodes before linearized reading' (duration: 153.739925ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:21.610224Z","caller":"traceutil/trace.go:171","msg":"trace[891213886] 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:585; }","duration":"153.93097ms","start":"2026-08-15T05:00:21.456279Z","end":"2026-08-15T05:00:21.61021Z","steps":["trace[891213886] 'agreement among raft nodes before linearized reading' (duration: 153.878469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:21.610261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.588635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T05:00:21.610277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.680668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:00:21.610351Z","caller":"traceutil/trace.go:171","msg":"trace[1767906569] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:585; }","duration":"175.717609ms","start":"2026-08-15T05:00:21.434613Z","end":"2026-08-15T05:00:21.61033Z","steps":["trace[1767906569] 'agreement among raft nodes before linearized reading' (duration: 175.563805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:21.61038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.390568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T05:00:21.610402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.033185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:00:21.610421Z","caller":"traceutil/trace.go:171","msg":"trace[1522951551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"191.451829ms","start":"2026-08-15T05:00:21.418959Z","end":"2026-08-15T05:00:21.610411Z","steps":["trace[1522951551] 'agreement among raft nodes before linearized reading' (duration: 191.389767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:21.610392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.663027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T05:00:21.610444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.946282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T05:00:21.610366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.913979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:00:21.610503Z","caller":"traceutil/trace.go:171","msg":"trace[375324419] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:585; }","duration":"175.80995ms","start":"2026-08-15T05:00:21.43466Z","end":"2026-08-15T05:00:21.61047Z","steps":["trace[375324419] 'agreement among raft nodes before linearized reading' (duration: 175.597665ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:21.610146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.591845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:00:21.610481Z","caller":"traceutil/trace.go:171","msg":"trace[165165937] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:585; }","duration":"268.018204ms","start":"2026-08-15T05:00:21.342451Z","end":"2026-08-15T05:00:21.610469Z","steps":["trace[165165937] 'agreement among raft nodes before linearized reading' (duration: 267.963413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:21.610529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.341699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T05:00:21.610577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.691429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:00:21.610614Z","caller":"traceutil/trace.go:171","msg":"trace[723813468] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:585; }","duration":"194.472952ms","start":"2026-08-15T05:00:21.416124Z","end":"2026-08-15T05:00:21.610597Z","steps":["trace[723813468] 'agreement among raft nodes before linearized reading' (duration: 194.315659ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:21.610511Z","caller":"traceutil/trace.go:171","msg":"trace[33383453] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:585; }","duration":"239.899834ms","start":"2026-08-15T05:00:21.370583Z","end":"2026-08-15T05:00:21.610483Z","steps":["trace[33383453] 'agreement among raft nodes before linearized reading' (duration: 239.658648ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:21.610675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.009688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:00:21.610719Z","caller":"traceutil/trace.go:171","msg":"trace[420137923] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:585; }","duration":"127.09381ms","start":"2026-08-15T05:00:21.483615Z","end":"2026-08-15T05:00:21.610709Z","steps":["trace[420137923] 'agreement among raft nodes before linearized reading' (duration: 127.032528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:21.610727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.223487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T05:00:21.610691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.941961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:00:21.610766Z","caller":"traceutil/trace.go:171","msg":"trace[350538652] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:585; }","duration":"195.22222ms","start":"2026-08-15T05:00:21.415336Z","end":"2026-08-15T05:00:21.610558Z","steps":["trace[350538652] 'agreement among raft nodes before linearized reading' (duration: 194.868332ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:21.610802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.979681ms","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\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T05:00:21.610731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.069337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:00:21.610842Z","caller":"traceutil/trace.go:171","msg":"trace[1479058220] 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:585; }","duration":"154.028183ms","start":"2026-08-15T05:00:21.456803Z","end":"2026-08-15T05:00:21.610831Z","steps":["trace[1479058220] 'agreement among raft nodes before linearized reading' (duration: 153.976252ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:21.610836Z","caller":"traceutil/trace.go:171","msg":"trace[323918039] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:585; }","duration":"239.193316ms","start":"2026-08-15T05:00:21.371613Z","end":"2026-08-15T05:00:21.610807Z","steps":["trace[323918039] 'agreement among raft nodes before linearized reading' (duration: 238.765516ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:21.610121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.646956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:00:21.610601Z","caller":"traceutil/trace.go:171","msg":"trace[145780732] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:585; }","duration":"268.096847ms","start":"2026-08-15T05:00:21.342479Z","end":"2026-08-15T05:00:21.610576Z","steps":["trace[145780732] 'agreement among raft nodes before linearized reading' (duration: 267.431101ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:21.610919Z","caller":"traceutil/trace.go:171","msg":"trace[1215687737] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:585; }","duration":"240.309513ms","start":"2026-08-15T05:00:21.370575Z","end":"2026-08-15T05:00:21.610884Z","steps":["trace[1215687737] 'agreement among raft nodes before linearized reading' (duration: 239.905133ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:21.610675Z","caller":"traceutil/trace.go:171","msg":"trace[235785967] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:585; }","duration":"220.828551ms","start":"2026-08-15T05:00:21.389816Z","end":"2026-08-15T05:00:21.610645Z","steps":["trace[235785967] 'agreement among raft nodes before linearized reading' (duration: 220.596516ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:21.61076Z","caller":"traceutil/trace.go:171","msg":"trace[75399839] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:585; }","duration":"128.275839ms","start":"2026-08-15T05:00:21.482475Z","end":"2026-08-15T05:00:21.610751Z","steps":["trace[75399839] 'agreement among raft nodes before linearized reading' (duration: 128.233348ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:21.610907Z","caller":"traceutil/trace.go:171","msg":"trace[113323293] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:585; }","duration":"195.490916ms","start":"2026-08-15T05:00:21.415405Z","end":"2026-08-15T05:00:21.610896Z","steps":["trace[113323293] 'agreement among raft nodes before linearized reading' (duration: 194.660517ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:21.610548Z","caller":"traceutil/trace.go:171","msg":"trace[343574072] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:585; }","duration":"220.102814ms","start":"2026-08-15T05:00:21.390421Z","end":"2026-08-15T05:00:21.610524Z","steps":["trace[343574072] 'agreement among raft nodes before linearized reading' (duration: 219.923979ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:21.610309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.76164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:00:21.6112Z","caller":"traceutil/trace.go:171","msg":"trace[547201869] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:585; }","duration":"221.679081ms","start":"2026-08-15T05:00:21.389508Z","end":"2026-08-15T05:00:21.611187Z","steps":["trace[547201869] 'agreement among raft nodes before linearized reading' (duration: 220.728479ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:21.610688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.134374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:00:21.611288Z","caller":"traceutil/trace.go:171","msg":"trace[1316743087] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:585; }","duration":"128.745669ms","start":"2026-08-15T05:00:21.482522Z","end":"2026-08-15T05:00:21.611267Z","steps":["trace[1316743087] 'agreement among raft nodes before linearized reading' (duration: 128.135725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:21.611263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.208429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:00:21.611339Z","caller":"traceutil/trace.go:171","msg":"trace[334359885] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:585; }","duration":"176.341353ms","start":"2026-08-15T05:00:21.434986Z","end":"2026-08-15T05:00:21.611327Z","steps":["trace[334359885] 'agreement among raft nodes before linearized reading' (duration: 175.216386ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:22.128516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.454312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T05:00:22.128749Z","caller":"traceutil/trace.go:171","msg":"trace[20942538] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"479.09091ms","start":"2026-08-15T05:00:21.649587Z","end":"2026-08-15T05:00:22.128678Z","steps":["trace[20942538] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:420; } (duration: 467.923609ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:22.128787Z","caller":"traceutil/trace.go:171","msg":"trace[1266527786] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"425.584324ms","start":"2026-08-15T05:00:21.703162Z","end":"2026-08-15T05:00:22.128746Z","steps":["trace[1266527786] 'read index received' (duration: 38.681µs)","trace[1266527786] 'applied index is now lower than readState.Index' (duration: 425.541213ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:00:22.128946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.748198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:00:22.129029Z","caller":"traceutil/trace.go:171","msg":"trace[1979953075] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:586; }","duration":"425.87398ms","start":"2026-08-15T05:00:21.70313Z","end":"2026-08-15T05:00:22.129004Z","steps":["trace[1979953075] 'agreement among raft nodes before linearized reading' (duration: 425.714686ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:22.128992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:00:21.649562Z","time spent":"479.293114ms","remote":"127.0.0.1:58140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T05:00:22.129078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:00:21.703109Z","time spent":"425.958252ms","remote":"127.0.0.1:45724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-08-15T05:00:22.129393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.016764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T05:00:22.129436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.14795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:00:22.129499Z","caller":"traceutil/trace.go:171","msg":"trace[786853543] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:587; }","duration":"375.234342ms","start":"2026-08-15T05:00:21.754246Z","end":"2026-08-15T05:00:22.12948Z","steps":["trace[786853543] 'agreement among raft nodes before linearized reading' (duration: 375.160431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:22.129506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.408718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qwrv2\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-08-15T05:00:22.129466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.83592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T05:00:22.129569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:00:21.754231Z","time spent":"375.318405ms","remote":"127.0.0.1:45728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 "} {"level":"info","ts":"2026-08-15T05:00:22.129587Z","caller":"traceutil/trace.go:171","msg":"trace[704469196] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qwrv2; range_end:; response_count:1; response_revision:587; }","duration":"179.53376ms","start":"2026-08-15T05:00:21.950024Z","end":"2026-08-15T05:00:22.129558Z","steps":["trace[704469196] 'agreement among raft nodes before linearized reading' (duration: 179.319925ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:22.129608Z","caller":"traceutil/trace.go:171","msg":"trace[935035648] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:587; }","duration":"131.026964ms","start":"2026-08-15T05:00:21.998562Z","end":"2026-08-15T05:00:22.129589Z","steps":["trace[935035648] 'agreement among raft nodes before linearized reading' (duration: 130.807219ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:22.129621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.970591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T05:00:22.129601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.005874ms","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:5"} {"level":"info","ts":"2026-08-15T05:00:22.129661Z","caller":"traceutil/trace.go:171","msg":"trace[1358963074] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:587; }","duration":"376.010573ms","start":"2026-08-15T05:00:21.753637Z","end":"2026-08-15T05:00:22.129648Z","steps":["trace[1358963074] 'agreement among raft nodes before linearized reading' (duration: 375.89889ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:22.129706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:00:21.753627Z","time spent":"376.065514ms","remote":"127.0.0.1:45728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 "} {"level":"info","ts":"2026-08-15T05:00:22.129714Z","caller":"traceutil/trace.go:171","msg":"trace[1581794343] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:587; }","duration":"131.177389ms","start":"2026-08-15T05:00:21.998501Z","end":"2026-08-15T05:00:22.129678Z","steps":["trace[1581794343] 'agreement among raft nodes before linearized reading' (duration: 130.951863ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:22.129696Z","caller":"traceutil/trace.go:171","msg":"trace[463207713] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"116.029932ms","start":"2026-08-15T05:00:22.013616Z","end":"2026-08-15T05:00:22.129646Z","steps":["trace[463207713] 'process raft request' (duration: 115.187651ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:22.129449Z","caller":"traceutil/trace.go:171","msg":"trace[1372082971] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:587; }","duration":"426.104756ms","start":"2026-08-15T05:00:21.703328Z","end":"2026-08-15T05:00:22.129433Z","steps":["trace[1372082971] 'agreement among raft nodes before linearized reading' (duration: 426.004154ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:22.129809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.326413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T05:00:22.129505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.822238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:00:22.129856Z","caller":"traceutil/trace.go:171","msg":"trace[1880435172] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:587; }","duration":"275.391524ms","start":"2026-08-15T05:00:21.854453Z","end":"2026-08-15T05:00:22.129845Z","steps":["trace[1880435172] 'agreement among raft nodes before linearized reading' (duration: 275.321892ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:22.12983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.782988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:00:22.129917Z","caller":"traceutil/trace.go:171","msg":"trace[919468786] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:587; }","duration":"376.255808ms","start":"2026-08-15T05:00:21.753642Z","end":"2026-08-15T05:00:22.129898Z","steps":["trace[919468786] 'agreement among raft nodes before linearized reading' (duration: 375.832497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:22.129933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.932107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:00:22.129946Z","caller":"traceutil/trace.go:171","msg":"trace[425870784] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:587; }","duration":"426.044074ms","start":"2026-08-15T05:00:21.703884Z","end":"2026-08-15T05:00:22.129929Z","steps":["trace[425870784] 'agreement among raft nodes before linearized reading' (duration: 425.726466ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:22.129972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:00:21.753622Z","time spent":"376.331431ms","remote":"127.0.0.1:45728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-15T05:00:22.12985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:00:21.703305Z","time spent":"426.501676ms","remote":"127.0.0.1:45724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-15T05:00:22.130037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.143503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:00:22.130074Z","caller":"traceutil/trace.go:171","msg":"trace[1986218317] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:587; }","duration":"276.218724ms","start":"2026-08-15T05:00:21.853847Z","end":"2026-08-15T05:00:22.130066Z","steps":["trace[1986218317] 'agreement among raft nodes before linearized reading' (duration: 276.146833ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:00:22.129973Z","caller":"traceutil/trace.go:171","msg":"trace[318361443] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:587; }","duration":"276.02292ms","start":"2026-08-15T05:00:21.853938Z","end":"2026-08-15T05:00:22.129961Z","steps":["trace[318361443] 'agreement among raft nodes before linearized reading' (duration: 275.943218ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:00:22.13004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:00:21.703877Z","time spent":"426.147656ms","remote":"127.0.0.1:45724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 "} {"level":"warn","ts":"2026-08-15T05:01:53.52556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.468712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:01:53.525648Z","caller":"traceutil/trace.go:171","msg":"trace[350115882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1806; }","duration":"108.585196ms","start":"2026-08-15T05:01:53.417044Z","end":"2026-08-15T05:01:53.525629Z","steps":["trace[350115882] 'range keys from in-memory index tree' (duration: 108.412431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:01:53.525762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.397225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c44a06aa-72bd-47b7-954d-0ebc0b58e6cc\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-08-15T05:01:53.525857Z","caller":"traceutil/trace.go:171","msg":"trace[2146748857] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c44a06aa-72bd-47b7-954d-0ebc0b58e6cc; range_end:; response_count:1; response_revision:1806; }","duration":"109.542289ms","start":"2026-08-15T05:01:53.416296Z","end":"2026-08-15T05:01:53.525838Z","steps":["trace[2146748857] 'range keys from in-memory index tree' (duration: 109.103668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:02:37.93077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.638416ms","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-15T05:02:37.930866Z","caller":"traceutil/trace.go:171","msg":"trace[1385093686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2104; }","duration":"154.766119ms","start":"2026-08-15T05:02:37.776067Z","end":"2026-08-15T05:02:37.930833Z","steps":["trace[1385093686] 'range keys from in-memory index tree' (duration: 154.484243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:02:37.93088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.087734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-15T05:02:37.93099Z","caller":"traceutil/trace.go:171","msg":"trace[514583054] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2104; }","duration":"159.238458ms","start":"2026-08-15T05:02:37.771736Z","end":"2026-08-15T05:02:37.930975Z","steps":["trace[514583054] 'range keys from in-memory index tree' (duration: 158.991881ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:02:43.043044Z","caller":"traceutil/trace.go:171","msg":"trace[221462206] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"108.381686ms","start":"2026-08-15T05:02:42.934629Z","end":"2026-08-15T05:02:43.043011Z","steps":["trace[221462206] 'process raft request' (duration: 108.090849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:07:55.164265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.03528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:1219"} {"level":"info","ts":"2026-08-15T05:07:55.164336Z","caller":"traceutil/trace.go:171","msg":"trace[1409821624] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4772; }","duration":"157.146663ms","start":"2026-08-15T05:07:55.007176Z","end":"2026-08-15T05:07:55.164322Z","steps":["trace[1409821624] 'range keys from in-memory index tree' (duration: 156.816055ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:07:55.164601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.198288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-15T05:07:55.164676Z","caller":"traceutil/trace.go:171","msg":"trace[1902409688] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4772; }","duration":"136.319861ms","start":"2026-08-15T05:07:55.028339Z","end":"2026-08-15T05:07:55.164658Z","steps":["trace[1902409688] 'range keys from in-memory index tree' (duration: 136.005893ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:07:55.285818Z","caller":"traceutil/trace.go:171","msg":"trace[1128118223] transaction","detail":"{read_only:false; response_revision:4775; number_of_response:1; }","duration":"109.317821ms","start":"2026-08-15T05:07:55.176473Z","end":"2026-08-15T05:07:55.285791Z","steps":["trace[1128118223] 'process raft request' (duration: 108.138393ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:07:55.285842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.188587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-d7gjr.18cbe24752909627\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-08-15T05:07:55.285916Z","caller":"traceutil/trace.go:171","msg":"trace[642238296] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-d7gjr.18cbe24752909627; range_end:; response_count:1; response_revision:4775; }","duration":"104.28858ms","start":"2026-08-15T05:07:55.181611Z","end":"2026-08-15T05:07:55.285899Z","steps":["trace[642238296] 'agreement among raft nodes before linearized reading' (duration: 104.080335ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:08:09.544659Z","caller":"traceutil/trace.go:171","msg":"trace[1756463948] transaction","detail":"{read_only:false; response_revision:5052; number_of_response:1; }","duration":"206.813907ms","start":"2026-08-15T05:08:09.337815Z","end":"2026-08-15T05:08:09.544628Z","steps":["trace[1756463948] 'process raft request' (duration: 206.642742ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:08:09.544852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.782617ms","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-15T05:08:09.544915Z","caller":"traceutil/trace.go:171","msg":"trace[1932734496] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5052; }","duration":"167.88781ms","start":"2026-08-15T05:08:09.377011Z","end":"2026-08-15T05:08:09.544898Z","steps":["trace[1932734496] 'agreement among raft nodes before linearized reading' (duration: 167.699145ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:08:09.544644Z","caller":"traceutil/trace.go:171","msg":"trace[219015294] linearizableReadLoop","detail":"{readStateIndex:5237; appliedIndex:5236; }","duration":"167.536782ms","start":"2026-08-15T05:08:09.377055Z","end":"2026-08-15T05:08:09.544591Z","steps":["trace[219015294] 'read index received' (duration: 167.417779ms)","trace[219015294] 'applied index is now lower than readState.Index' (duration: 118.273µs)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:08:09.608975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.717312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-15T05:08:09.60906Z","caller":"traceutil/trace.go:171","msg":"trace[1061294156] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5052; }","duration":"144.850196ms","start":"2026-08-15T05:08:09.46419Z","end":"2026-08-15T05:08:09.609041Z","steps":["trace[1061294156] 'agreement among raft nodes before linearized reading' (duration: 144.430786ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:08:18.689492Z","caller":"traceutil/trace.go:171","msg":"trace[353166817] linearizableReadLoop","detail":"{readStateIndex:5451; appliedIndex:5450; }","duration":"272.686674ms","start":"2026-08-15T05:08:18.416779Z","end":"2026-08-15T05:08:18.689465Z","steps":["trace[353166817] 'read index received' (duration: 175.957155ms)","trace[353166817] 'applied index is now lower than readState.Index' (duration: 96.728519ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:08:18.689515Z","caller":"traceutil/trace.go:171","msg":"trace[856737003] transaction","detail":"{read_only:false; response_revision:5262; number_of_response:1; }","duration":"280.709626ms","start":"2026-08-15T05:08:18.408774Z","end":"2026-08-15T05:08:18.689483Z","steps":["trace[856737003] 'process raft request' (duration: 184.021089ms)","trace[856737003] 'compare' (duration: 96.472852ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:08:18.689861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.059282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:08:18.689941Z","caller":"traceutil/trace.go:171","msg":"trace[2009972301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5263; }","duration":"273.182186ms","start":"2026-08-15T05:08:18.416741Z","end":"2026-08-15T05:08:18.689923Z","steps":["trace[2009972301] 'agreement among raft nodes before linearized reading' (duration: 273.033752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:08:18.690155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.798185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-116e7b70-8af1-4069-b3b0-7b244ceb4c1d\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-08-15T05:08:18.690254Z","caller":"traceutil/trace.go:171","msg":"trace[1311169875] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-116e7b70-8af1-4069-b3b0-7b244ceb4c1d; range_end:; response_count:1; response_revision:5263; }","duration":"262.963259ms","start":"2026-08-15T05:08:18.427271Z","end":"2026-08-15T05:08:18.690234Z","steps":["trace[1311169875] 'agreement among raft nodes before linearized reading' (duration: 262.461117ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:09:05.467306Z","caller":"traceutil/trace.go:171","msg":"trace[1388754379] transaction","detail":"{read_only:false; response_revision:5697; number_of_response:1; }","duration":"106.973027ms","start":"2026-08-15T05:09:05.360301Z","end":"2026-08-15T05:09:05.467274Z","steps":["trace[1388754379] 'process raft request' (duration: 88.229443ms)","trace[1388754379] 'compare' (duration: 18.365415ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:09:41.32695Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2820} {"level":"info","ts":"2026-08-15T05:09:41.393727Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2820,"took":"64.752175ms","hash":1487170604,"current-db-size-bytes":58134528,"current-db-size":"58 MB","current-db-size-in-use-bytes":33378304,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-15T05:09:41.393799Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1487170604,"revision":2820,"compact-revision":-1} {"level":"info","ts":"2026-08-15T05:14:13.788771Z","caller":"traceutil/trace.go:171","msg":"trace[923099261] linearizableReadLoop","detail":"{readStateIndex:8267; appliedIndex:8266; }","duration":"373.471164ms","start":"2026-08-15T05:14:13.415272Z","end":"2026-08-15T05:14:13.788743Z","steps":["trace[923099261] 'read index received' (duration: 373.197638ms)","trace[923099261] 'applied index is now lower than readState.Index' (duration: 272.106µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:14:13.788929Z","caller":"traceutil/trace.go:171","msg":"trace[682913187] transaction","detail":"{read_only:false; response_revision:7992; number_of_response:1; }","duration":"423.994274ms","start":"2026-08-15T05:14:13.364921Z","end":"2026-08-15T05:14:13.788916Z","steps":["trace[682913187] 'process raft request' (duration: 423.636856ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:14:13.789058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:14:13.364898Z","time spent":"424.069127ms","remote":"127.0.0.1:58140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T05:14:13.789205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.202986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-15T05:14:13.789304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.272032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T05:14:13.78931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.373483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-15T05:14:13.789321Z","caller":"traceutil/trace.go:171","msg":"trace[539969557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7992; }","duration":"166.355479ms","start":"2026-08-15T05:14:13.622941Z","end":"2026-08-15T05:14:13.789297Z","steps":["trace[539969557] 'agreement among raft nodes before linearized reading' (duration: 166.165975ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:14:13.789344Z","caller":"traceutil/trace.go:171","msg":"trace[1792293717] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:7992; }","duration":"172.350364ms","start":"2026-08-15T05:14:13.616983Z","end":"2026-08-15T05:14:13.789333Z","steps":["trace[1792293717] 'agreement among raft nodes before linearized reading' (duration: 172.295183ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:14:13.78934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.93023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-08-15T05:14:13.789359Z","caller":"traceutil/trace.go:171","msg":"trace[952461330] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7992; }","duration":"157.432464ms","start":"2026-08-15T05:14:13.631911Z","end":"2026-08-15T05:14:13.789343Z","steps":["trace[952461330] 'agreement among raft nodes before linearized reading' (duration: 157.369852ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:14:13.789374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.083409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:14:13.789409Z","caller":"traceutil/trace.go:171","msg":"trace[153207709] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:7992; }","duration":"152.023173ms","start":"2026-08-15T05:14:13.63737Z","end":"2026-08-15T05:14:13.789393Z","steps":["trace[153207709] 'agreement among raft nodes before linearized reading' (duration: 151.89658ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:14:13.789253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.628965ms","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-15T05:14:13.789466Z","caller":"traceutil/trace.go:171","msg":"trace[705751800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7992; }","duration":"374.218232ms","start":"2026-08-15T05:14:13.415226Z","end":"2026-08-15T05:14:13.789444Z","steps":["trace[705751800] 'agreement among raft nodes before linearized reading' (duration: 374.070488ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:14:13.789421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.398987ms","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-15T05:14:13.78952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:14:13.415212Z","time spent":"374.300084ms","remote":"127.0.0.1:57882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-15T05:14:13.789564Z","caller":"traceutil/trace.go:171","msg":"trace[4147362] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7992; }","duration":"182.617362ms","start":"2026-08-15T05:14:13.606921Z","end":"2026-08-15T05:14:13.789539Z","steps":["trace[4147362] 'agreement among raft nodes before linearized reading' (duration: 182.312355ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:14:13.789574Z","caller":"traceutil/trace.go:171","msg":"trace[804611214] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7992; }","duration":"180.930512ms","start":"2026-08-15T05:14:13.608591Z","end":"2026-08-15T05:14:13.789521Z","steps":["trace[804611214] 'agreement among raft nodes before linearized reading' (duration: 180.611744ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:14:13.789232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.013127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-15T05:14:13.789718Z","caller":"traceutil/trace.go:171","msg":"trace[1527392984] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:7992; }","duration":"184.544549ms","start":"2026-08-15T05:14:13.605123Z","end":"2026-08-15T05:14:13.789667Z","steps":["trace[1527392984] 'agreement among raft nodes before linearized reading' (duration: 183.948735ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:14:14.466894Z","caller":"traceutil/trace.go:171","msg":"trace[861014579] transaction","detail":"{read_only:false; response_revision:8000; number_of_response:1; }","duration":"133.054215ms","start":"2026-08-15T05:14:14.333812Z","end":"2026-08-15T05:14:14.466866Z","steps":["trace[861014579] 'process raft request' (duration: 110.225594ms)","trace[861014579] 'compare' (duration: 22.3639ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:14:41.384895Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5974} {"level":"info","ts":"2026-08-15T05:14:41.481489Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5974,"took":"94.009342ms","hash":1705318853,"current-db-size-bytes":58134528,"current-db-size":"58 MB","current-db-size-in-use-bytes":22806528,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-15T05:14:41.481566Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1705318853,"revision":5974,"compact-revision":2820} {"level":"info","ts":"2026-08-15T05:14:53.853411Z","caller":"traceutil/trace.go:171","msg":"trace[240553510] transaction","detail":"{read_only:false; response_revision:8300; number_of_response:1; }","duration":"105.582961ms","start":"2026-08-15T05:14:53.74779Z","end":"2026-08-15T05:14:53.853373Z","steps":["trace[240553510] 'process raft request' (duration: 105.373326ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:14:53.874435Z","caller":"traceutil/trace.go:171","msg":"trace[123382348] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8301; }","duration":"118.486982ms","start":"2026-08-15T05:14:53.755914Z","end":"2026-08-15T05:14:53.874401Z","steps":["trace[123382348] 'process raft request' (duration: 104.061024ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:14:54.517168Z","caller":"traceutil/trace.go:171","msg":"trace[349979261] transaction","detail":"{read_only:false; response_revision:8310; number_of_response:1; }","duration":"100.650472ms","start":"2026-08-15T05:14:54.416473Z","end":"2026-08-15T05:14:54.517124Z","steps":["trace[349979261] 'process raft request' (duration: 55.146253ms)","trace[349979261] 'compare' (duration: 45.304394ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:14:54.517264Z","caller":"traceutil/trace.go:171","msg":"trace[2033729096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8310; }","duration":"100.010736ms","start":"2026-08-15T05:14:54.417225Z","end":"2026-08-15T05:14:54.517236Z","steps":["trace[2033729096] 'agreement among raft nodes before linearized reading' (duration: 99.910753ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:14:54.775296Z","caller":"traceutil/trace.go:171","msg":"trace[1359271913] transaction","detail":"{read_only:false; response_revision:8311; number_of_response:1; }","duration":"228.452079ms","start":"2026-08-15T05:14:54.546818Z","end":"2026-08-15T05:14:54.775271Z","steps":["trace[1359271913] 'process raft request' (duration: 228.206043ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:14:54.782801Z","caller":"traceutil/trace.go:171","msg":"trace[821249374] linearizableReadLoop","detail":"{readStateIndex:8601; appliedIndex:8599; }","duration":"188.040294ms","start":"2026-08-15T05:14:54.594732Z","end":"2026-08-15T05:14:54.782772Z","steps":["trace[821249374] 'read index received' (duration: 180.289546ms)","trace[821249374] 'applied index is now lower than readState.Index' (duration: 7.749528ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:14:54.783288Z","caller":"traceutil/trace.go:171","msg":"trace[1308498797] transaction","detail":"{read_only:false; response_revision:8312; number_of_response:1; }","duration":"227.425954ms","start":"2026-08-15T05:14:54.555842Z","end":"2026-08-15T05:14:54.783268Z","steps":["trace[1308498797] 'process raft request' (duration: 226.773629ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:14:54.783571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.809102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T05:14:54.783638Z","caller":"traceutil/trace.go:171","msg":"trace[1940511379] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:8312; }","duration":"188.919414ms","start":"2026-08-15T05:14:54.594697Z","end":"2026-08-15T05:14:54.783617Z","steps":["trace[1940511379] 'agreement among raft nodes before linearized reading' (duration: 188.806501ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:14:54.784095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.263295ms","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-15T05:14:54.784155Z","caller":"traceutil/trace.go:171","msg":"trace[1293800529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8312; }","duration":"103.375578ms","start":"2026-08-15T05:14:54.680765Z","end":"2026-08-15T05:14:54.784141Z","steps":["trace[1293800529] 'agreement among raft nodes before linearized reading' (duration: 103.191844ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:14:55.718228Z","caller":"traceutil/trace.go:171","msg":"trace[1253089012] transaction","detail":"{read_only:false; response_revision:8315; number_of_response:1; }","duration":"112.945609ms","start":"2026-08-15T05:14:55.605255Z","end":"2026-08-15T05:14:55.7182Z","steps":["trace[1253089012] 'process raft request' (duration: 97.503987ms)","trace[1253089012] 'compare' (duration: 15.319299ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:16:02.668084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.139173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-15T05:16:02.668225Z","caller":"traceutil/trace.go:171","msg":"trace[2015417656] transaction","detail":"{read_only:false; response_revision:8954; number_of_response:1; }","duration":"179.631582ms","start":"2026-08-15T05:16:02.488579Z","end":"2026-08-15T05:16:02.668211Z","steps":["trace[2015417656] 'process raft request' (duration: 32.302015ms)","trace[2015417656] 'compare' (duration: 146.987889ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:16:05.926829Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002443.wal"} {"level":"info","ts":"2026-08-15T05:17:28.98432Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-15T05:17:28.993054Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-15T05:17:28.993142Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-15T05:19:41.391587Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8194} {"level":"info","ts":"2026-08-15T05:19:41.469998Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8194,"took":"76.157628ms","hash":1621060997,"current-db-size-bytes":58134528,"current-db-size":"58 MB","current-db-size-in-use-bytes":26300416,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-15T05:19:41.470052Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1621060997,"revision":8194,"compact-revision":5974} {"level":"info","ts":"2026-08-15T05:24:41.402055Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10870} {"level":"info","ts":"2026-08-15T05:24:41.48592Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10870,"took":"81.703103ms","hash":4201563206,"current-db-size-bytes":58134528,"current-db-size":"58 MB","current-db-size-in-use-bytes":27561984,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-15T05:24:41.485997Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4201563206,"revision":10870,"compact-revision":8194} {"level":"info","ts":"2026-08-15T05:26:23.480419Z","caller":"traceutil/trace.go:171","msg":"trace[1839148184] transaction","detail":"{read_only:false; response_revision:13537; number_of_response:1; }","duration":"113.935321ms","start":"2026-08-15T05:26:23.366435Z","end":"2026-08-15T05:26:23.48037Z","steps":["trace[1839148184] 'process raft request' (duration: 113.726996ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:26:39.10293Z","caller":"traceutil/trace.go:171","msg":"trace[257855275] linearizableReadLoop","detail":"{readStateIndex:14092; appliedIndex:14091; }","duration":"142.241463ms","start":"2026-08-15T05:26:38.960664Z","end":"2026-08-15T05:26:39.102905Z","steps":["trace[257855275] 'read index received' (duration: 141.988867ms)","trace[257855275] 'applied index is now lower than readState.Index' (duration: 251.216µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:26:39.102973Z","caller":"traceutil/trace.go:171","msg":"trace[1541289383] transaction","detail":"{read_only:false; response_revision:13632; number_of_response:1; }","duration":"226.553548ms","start":"2026-08-15T05:26:38.876388Z","end":"2026-08-15T05:26:39.102941Z","steps":["trace[1541289383] 'process raft request' (duration: 226.260031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:26:39.103123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.438578ms","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-15T05:26:39.103164Z","caller":"traceutil/trace.go:171","msg":"trace[2093890271] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13632; }","duration":"142.524201ms","start":"2026-08-15T05:26:38.960629Z","end":"2026-08-15T05:26:39.103153Z","steps":["trace[2093890271] 'agreement among raft nodes before linearized reading' (duration: 142.375536ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:26:40.617624Z","caller":"traceutil/trace.go:171","msg":"trace[448023765] transaction","detail":"{read_only:false; response_revision:13648; number_of_response:1; }","duration":"136.790392ms","start":"2026-08-15T05:26:40.4808Z","end":"2026-08-15T05:26:40.617591Z","steps":["trace[448023765] 'process raft request' (duration: 99.00093ms)","trace[448023765] 'compare' (duration: 37.479785ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:26:48.774489Z","caller":"traceutil/trace.go:171","msg":"trace[65943328] transaction","detail":"{read_only:false; response_revision:13796; number_of_response:1; }","duration":"298.860575ms","start":"2026-08-15T05:26:48.475598Z","end":"2026-08-15T05:26:48.774458Z","steps":["trace[65943328] 'process raft request' (duration: 298.680121ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:26:48.841193Z","caller":"traceutil/trace.go:171","msg":"trace[436891973] linearizableReadLoop","detail":"{readStateIndex:14261; appliedIndex:14259; }","duration":"202.616833ms","start":"2026-08-15T05:26:48.638557Z","end":"2026-08-15T05:26:48.841174Z","steps":["trace[436891973] 'read index received' (duration: 135.783269ms)","trace[436891973] 'applied index is now lower than readState.Index' (duration: 66.832504ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:26:48.84129Z","caller":"traceutil/trace.go:171","msg":"trace[1083411411] transaction","detail":"{read_only:false; response_revision:13797; number_of_response:1; }","duration":"362.260276ms","start":"2026-08-15T05:26:48.479002Z","end":"2026-08-15T05:26:48.841263Z","steps":["trace[1083411411] 'process raft request' (duration: 362.03106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:26:48.841494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.842938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T05:26:48.84155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:26:48.478988Z","time spent":"362.40104ms","remote":"127.0.0.1:58140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T05:26:48.841548Z","caller":"traceutil/trace.go:171","msg":"trace[1826879660] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:13797; }","duration":"203.035652ms","start":"2026-08-15T05:26:48.638498Z","end":"2026-08-15T05:26:48.841533Z","steps":["trace[1826879660] 'agreement among raft nodes before linearized reading' (duration: 202.797496ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:28:28.527412Z","caller":"traceutil/trace.go:171","msg":"trace[1202523663] transaction","detail":"{read_only:false; response_revision:14887; number_of_response:1; }","duration":"104.38302ms","start":"2026-08-15T05:28:28.423009Z","end":"2026-08-15T05:28:28.527392Z","steps":["trace[1202523663] 'process raft request' (duration: 104.263398ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:29:41.408554Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12908} {"level":"info","ts":"2026-08-15T05:29:41.484761Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12908,"took":"73.438013ms","hash":452539955,"current-db-size-bytes":58134528,"current-db-size":"58 MB","current-db-size-in-use-bytes":29839360,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-15T05:29:41.484809Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":452539955,"revision":12908,"compact-revision":10870} {"level":"info","ts":"2026-08-15T05:34:13.874663Z","caller":"traceutil/trace.go:171","msg":"trace[2134992468] transaction","detail":"{read_only:false; response_revision:17478; number_of_response:1; }","duration":"178.054171ms","start":"2026-08-15T05:34:13.696564Z","end":"2026-08-15T05:34:13.874618Z","steps":["trace[2134992468] 'process raft request' (duration: 119.437411ms)","trace[2134992468] 'compare' (duration: 58.435155ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:34:14.26405Z","caller":"traceutil/trace.go:171","msg":"trace[803374072] transaction","detail":"{read_only:false; response_revision:17482; number_of_response:1; }","duration":"227.945309ms","start":"2026-08-15T05:34:14.036087Z","end":"2026-08-15T05:34:14.264032Z","steps":["trace[803374072] 'process raft request' (duration: 227.804226ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:34:14.616956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.453719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:34:14.617065Z","caller":"traceutil/trace.go:171","msg":"trace[698106818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17482; }","duration":"201.606551ms","start":"2026-08-15T05:34:14.415438Z","end":"2026-08-15T05:34:14.617045Z","steps":["trace[698106818] 'range keys from in-memory index tree' (duration: 201.341355ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:34:14.61739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.580639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-72bvn\" ","response":"range_response_count:1 size:22122"} {"level":"info","ts":"2026-08-15T05:34:14.617475Z","caller":"traceutil/trace.go:171","msg":"trace[359849466] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-72bvn; range_end:; response_count:1; response_revision:17482; }","duration":"184.697932ms","start":"2026-08-15T05:34:14.432756Z","end":"2026-08-15T05:34:14.617454Z","steps":["trace[359849466] 'range keys from in-memory index tree' (duration: 184.423405ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:34:14.617521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.36091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-08-15T05:34:14.617568Z","caller":"traceutil/trace.go:171","msg":"trace[1235970989] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:17482; }","duration":"228.432251ms","start":"2026-08-15T05:34:14.389123Z","end":"2026-08-15T05:34:14.617555Z","steps":["trace[1235970989] 'range keys from in-memory index tree' (duration: 228.199225ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:34:14.631224Z","caller":"traceutil/trace.go:171","msg":"trace[677414606] transaction","detail":"{read_only:false; response_revision:17483; number_of_response:1; }","duration":"191.339133ms","start":"2026-08-15T05:34:14.439864Z","end":"2026-08-15T05:34:14.631203Z","steps":["trace[677414606] 'process raft request' (duration: 191.180309ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:34:14.64398Z","caller":"traceutil/trace.go:171","msg":"trace[175247088] transaction","detail":"{read_only:false; response_revision:17484; number_of_response:1; }","duration":"202.877413ms","start":"2026-08-15T05:34:14.44109Z","end":"2026-08-15T05:34:14.643967Z","steps":["trace[175247088] 'process raft request' (duration: 202.604126ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:34:14.644103Z","caller":"traceutil/trace.go:171","msg":"trace[1659694143] linearizableReadLoop","detail":"{readStateIndex:18068; appliedIndex:18066; }","duration":"193.642889ms","start":"2026-08-15T05:34:14.450443Z","end":"2026-08-15T05:34:14.644086Z","steps":["trace[1659694143] 'read index received' (duration: 180.591402ms)","trace[1659694143] 'applied index is now lower than readState.Index' (duration: 13.050807ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:34:14.644689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.231834ms","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-15T05:34:14.644806Z","caller":"traceutil/trace.go:171","msg":"trace[817339096] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17485; }","duration":"194.388267ms","start":"2026-08-15T05:34:14.450407Z","end":"2026-08-15T05:34:14.644795Z","steps":["trace[817339096] 'agreement among raft nodes before linearized reading' (duration: 193.859064ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:34:41.416438Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15356} {"level":"info","ts":"2026-08-15T05:34:41.510708Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15356,"took":"90.702526ms","hash":1569108141,"current-db-size-bytes":58134528,"current-db-size":"58 MB","current-db-size-in-use-bytes":32292864,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-15T05:34:41.510773Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1569108141,"revision":15356,"compact-revision":12908} {"level":"info","ts":"2026-08-15T05:37:26.558716Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"89ba38f964b95aa0","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-15T05:37:26.566007Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-15T05:37:26.566132Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-15T05:39:41.422452Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17875} {"level":"info","ts":"2026-08-15T05:39:41.53161Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17875,"took":"106.289666ms","hash":218614937,"current-db-size-bytes":58134528,"current-db-size":"58 MB","current-db-size-in-use-bytes":31457280,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-15T05:39:41.531692Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":218614937,"revision":17875,"compact-revision":15356} {"level":"info","ts":"2026-08-15T05:41:18.445317Z","caller":"traceutil/trace.go:171","msg":"trace[503792513] transaction","detail":"{read_only:false; response_revision:21003; number_of_response:1; }","duration":"291.215377ms","start":"2026-08-15T05:41:18.154072Z","end":"2026-08-15T05:41:18.445287Z","steps":["trace[503792513] 'process raft request' (duration: 291.037943ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:41:18.563845Z","caller":"traceutil/trace.go:171","msg":"trace[960602016] linearizableReadLoop","detail":"{readStateIndex:21698; appliedIndex:21696; }","duration":"405.744793ms","start":"2026-08-15T05:41:18.158065Z","end":"2026-08-15T05:41:18.56381Z","steps":["trace[960602016] 'read index received' (duration: 287.090988ms)","trace[960602016] 'applied index is now lower than readState.Index' (duration: 118.652755ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:41:18.563878Z","caller":"traceutil/trace.go:171","msg":"trace[2072276716] transaction","detail":"{read_only:false; response_revision:21004; number_of_response:1; }","duration":"409.208327ms","start":"2026-08-15T05:41:18.154624Z","end":"2026-08-15T05:41:18.563832Z","steps":["trace[2072276716] 'process raft request' (duration: 343.471594ms)","trace[2072276716] 'compare' (duration: 65.584849ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:41:18.564078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:41:18.154591Z","time spent":"409.376211ms","remote":"127.0.0.1:58140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T05:41:18.564115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.03401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-08-15T05:41:18.564153Z","caller":"traceutil/trace.go:171","msg":"trace[1559804134] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21004; }","duration":"406.129442ms","start":"2026-08-15T05:41:18.15801Z","end":"2026-08-15T05:41:18.564139Z","steps":["trace[1559804134] 'agreement among raft nodes before linearized reading' (duration: 405.970638ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:41:18.564163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.960459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-08-15T05:41:18.564195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:41:18.157988Z","time spent":"406.198764ms","remote":"127.0.0.1:58140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-15T05:41:18.564217Z","caller":"traceutil/trace.go:171","msg":"trace[1381786024] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21004; }","duration":"392.040431ms","start":"2026-08-15T05:41:18.17216Z","end":"2026-08-15T05:41:18.5642Z","steps":["trace[1381786024] 'agreement among raft nodes before linearized reading' (duration: 391.869947ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:41:18.564293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:41:18.172152Z","time spent":"392.130324ms","remote":"127.0.0.1:58140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-15T05:41:18.564477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.238089ms","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-15T05:41:18.56461Z","caller":"traceutil/trace.go:171","msg":"trace[605041321] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21004; }","duration":"400.457154ms","start":"2026-08-15T05:41:18.164131Z","end":"2026-08-15T05:41:18.564588Z","steps":["trace[605041321] 'agreement among raft nodes before linearized reading' (duration: 399.88978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:41:18.564705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:41:18.164123Z","time spent":"400.565197ms","remote":"127.0.0.1:58056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-15T05:41:18.807781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.806123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-15T05:41:18.807928Z","caller":"traceutil/trace.go:171","msg":"trace[1160448000] linearizableReadLoop","detail":"{readStateIndex:21700; appliedIndex:21699; }","duration":"142.310208ms","start":"2026-08-15T05:41:18.665598Z","end":"2026-08-15T05:41:18.807909Z","steps":["trace[1160448000] 'read index received' (duration: 36.30725ms)","trace[1160448000] 'applied index is now lower than readState.Index' (duration: 106.001368ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:41:18.807981Z","caller":"traceutil/trace.go:171","msg":"trace[357492389] transaction","detail":"{read_only:false; response_revision:21006; number_of_response:1; }","duration":"238.431088ms","start":"2026-08-15T05:41:18.569505Z","end":"2026-08-15T05:41:18.807936Z","steps":["trace[357492389] 'process raft request' (duration: 132.375699ms)","trace[357492389] 'compare' (duration: 105.6658ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:41:18.808111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.496112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18421"} {"level":"info","ts":"2026-08-15T05:41:18.808172Z","caller":"traceutil/trace.go:171","msg":"trace[587621952] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:21006; }","duration":"142.603005ms","start":"2026-08-15T05:41:18.665555Z","end":"2026-08-15T05:41:18.808158Z","steps":["trace[587621952] 'agreement among raft nodes before linearized reading' (duration: 142.40386ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:41:19.6857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.99897ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-15T05:44:41.431238Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20310} {"level":"info","ts":"2026-08-15T05:44:41.539513Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20310,"took":"105.012568ms","hash":2249543834,"current-db-size-bytes":58134528,"current-db-size":"58 MB","current-db-size-in-use-bytes":31899648,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-15T05:44:41.5397Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2249543834,"revision":20310,"compact-revision":17875} {"level":"info","ts":"2026-08-15T05:44:54.254737Z","caller":"traceutil/trace.go:171","msg":"trace[590372704] linearizableReadLoop","detail":"{readStateIndex:23847; appliedIndex:23845; }","duration":"175.568583ms","start":"2026-08-15T05:44:54.079144Z","end":"2026-08-15T05:44:54.254713Z","steps":["trace[590372704] 'read index received' (duration: 6.101617ms)","trace[590372704] 'applied index is now lower than readState.Index' (duration: 169.465846ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:44:54.255072Z","caller":"traceutil/trace.go:171","msg":"trace[1290948704] transaction","detail":"{read_only:false; response_revision:23084; number_of_response:1; }","duration":"182.803737ms","start":"2026-08-15T05:44:54.07225Z","end":"2026-08-15T05:44:54.255054Z","steps":["trace[1290948704] 'process raft request' (duration: 94.105353ms)","trace[1290948704] 'compare' (duration: 88.252043ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:44:54.255262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.098665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclusters.infrastructure.cluster.x-k8s.io\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:44:54.255381Z","caller":"traceutil/trace.go:171","msg":"trace[1448015282] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/openstackclusters.infrastructure.cluster.x-k8s.io; range_end:; response_count:0; response_revision:23084; }","duration":"176.222547ms","start":"2026-08-15T05:44:54.079109Z","end":"2026-08-15T05:44:54.255331Z","steps":["trace[1448015282] 'agreement among raft nodes before linearized reading' (duration: 176.081114ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:44:54.261822Z","caller":"traceutil/trace.go:171","msg":"trace[598996868] transaction","detail":"{read_only:false; response_revision:23086; number_of_response:1; }","duration":"111.737549ms","start":"2026-08-15T05:44:54.150059Z","end":"2026-08-15T05:44:54.261796Z","steps":["trace[598996868] 'process raft request' (duration: 111.668828ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:44:54.262058Z","caller":"traceutil/trace.go:171","msg":"trace[599643438] transaction","detail":"{read_only:false; response_revision:23085; number_of_response:1; }","duration":"166.38357ms","start":"2026-08-15T05:44:54.095644Z","end":"2026-08-15T05:44:54.262027Z","steps":["trace[599643438] 'process raft request' (duration: 165.916419ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:44:54.268436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.453256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" limit:10000 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:44:54.268547Z","caller":"traceutil/trace.go:171","msg":"trace[2144641772] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:23087; }","duration":"148.631981ms","start":"2026-08-15T05:44:54.119897Z","end":"2026-08-15T05:44:54.268529Z","steps":["trace[2144641772] 'agreement among raft nodes before linearized reading' (duration: 148.452356ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:44:54.268551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.019092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:44:54.268619Z","caller":"traceutil/trace.go:171","msg":"trace[334133720] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:23087; }","duration":"111.128125ms","start":"2026-08-15T05:44:54.157476Z","end":"2026-08-15T05:44:54.268604Z","steps":["trace[334133720] 'agreement among raft nodes before linearized reading' (duration: 110.984482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:44:54.268819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.646781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:44:54.268877Z","caller":"traceutil/trace.go:171","msg":"trace[1016238966] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:23087; }","duration":"131.721403ms","start":"2026-08-15T05:44:54.137147Z","end":"2026-08-15T05:44:54.268868Z","steps":["trace[1016238966] 'agreement among raft nodes before linearized reading' (duration: 131.643421ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:44:54.269028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.894897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" limit:10000 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:44:54.26909Z","caller":"traceutil/trace.go:171","msg":"trace[632453858] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:23087; }","duration":"131.977679ms","start":"2026-08-15T05:44:54.137104Z","end":"2026-08-15T05:44:54.269081Z","steps":["trace[632453858] 'agreement among raft nodes before linearized reading' (duration: 131.905277ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:44:54.269232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.343437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:44:54.269285Z","caller":"traceutil/trace.go:171","msg":"trace[133264401] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:23087; }","duration":"132.416789ms","start":"2026-08-15T05:44:54.13686Z","end":"2026-08-15T05:44:54.269277Z","steps":["trace[133264401] 'agreement among raft nodes before linearized reading' (duration: 132.343908ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:44:54.26938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.497811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-15T05:44:54.268848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.874169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" limit:10000 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:44:54.269506Z","caller":"traceutil/trace.go:171","msg":"trace[31879706] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:23087; }","duration":"188.553136ms","start":"2026-08-15T05:44:54.080942Z","end":"2026-08-15T05:44:54.269495Z","steps":["trace[31879706] 'agreement among raft nodes before linearized reading' (duration: 187.289165ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:44:54.269435Z","caller":"traceutil/trace.go:171","msg":"trace[487274012] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:23087; }","duration":"149.580484ms","start":"2026-08-15T05:44:54.119838Z","end":"2026-08-15T05:44:54.269419Z","steps":["trace[487274012] 'agreement among raft nodes before linearized reading' (duration: 149.273246ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:44:54.269592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.171236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2380604"} {"level":"warn","ts":"2026-08-15T05:44:54.269608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.361991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:44:54.269629Z","caller":"traceutil/trace.go:171","msg":"trace[977623820] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:23087; }","duration":"111.402222ms","start":"2026-08-15T05:44:54.158218Z","end":"2026-08-15T05:44:54.26962Z","steps":["trace[977623820] 'agreement among raft nodes before linearized reading' (duration: 111.377721ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:44:54.269626Z","caller":"traceutil/trace.go:171","msg":"trace[1943936229] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:23087; }","duration":"106.228696ms","start":"2026-08-15T05:44:54.163385Z","end":"2026-08-15T05:44:54.269614Z","steps":["trace[1943936229] 'agreement among raft nodes before linearized reading' (duration: 105.427747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:44:54.269665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.248029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" limit:10000 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:44:54.269772Z","caller":"traceutil/trace.go:171","msg":"trace[442051174] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:23087; }","duration":"169.402403ms","start":"2026-08-15T05:44:54.100348Z","end":"2026-08-15T05:44:54.269751Z","steps":["trace[442051174] 'agreement among raft nodes before linearized reading' (duration: 169.267019ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:44:54.269826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.161796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:44:54.26989Z","caller":"traceutil/trace.go:171","msg":"trace[1945899347] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:23087; }","duration":"188.251599ms","start":"2026-08-15T05:44:54.081623Z","end":"2026-08-15T05:44:54.269875Z","steps":["trace[1945899347] 'agreement among raft nodes before linearized reading' (duration: 188.160646ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:44:54.270002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.052064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:44:54.270045Z","caller":"traceutil/trace.go:171","msg":"trace[1550131694] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:23087; }","duration":"169.108195ms","start":"2026-08-15T05:44:54.100927Z","end":"2026-08-15T05:44:54.270035Z","steps":["trace[1550131694] 'agreement among raft nodes before linearized reading' (duration: 169.045234ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:44:54.27004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.965115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:44:54.270385Z","caller":"traceutil/trace.go:171","msg":"trace[1761907378] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:23087; }","duration":"189.355425ms","start":"2026-08-15T05:44:54.081023Z","end":"2026-08-15T05:44:54.270378Z","steps":["trace[1761907378] 'agreement among raft nodes before linearized reading' (duration: 188.978696ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:44:54.270067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.071067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/machines.cluster.x-k8s.io\" ","response":"range_response_count:1 size:58264"} {"level":"info","ts":"2026-08-15T05:44:54.270445Z","caller":"traceutil/trace.go:171","msg":"trace[1504932803] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/machines.cluster.x-k8s.io; range_end:; response_count:1; response_revision:23087; }","duration":"184.469566ms","start":"2026-08-15T05:44:54.085968Z","end":"2026-08-15T05:44:54.270438Z","steps":["trace[1504932803] 'agreement among raft nodes before linearized reading' (duration: 184.016015ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:44:54.270175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.26096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:44:54.270524Z","caller":"traceutil/trace.go:171","msg":"trace[1478077386] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:23087; }","duration":"169.643909ms","start":"2026-08-15T05:44:54.100867Z","end":"2026-08-15T05:44:54.270511Z","steps":["trace[1478077386] 'agreement among raft nodes before linearized reading' (duration: 169.28838ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:44:54.269579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.047937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" limit:10000 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:44:54.270607Z","caller":"traceutil/trace.go:171","msg":"trace[219869765] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:23087; }","duration":"113.096002ms","start":"2026-08-15T05:44:54.1575Z","end":"2026-08-15T05:44:54.270596Z","steps":["trace[219869765] 'agreement among raft nodes before linearized reading' (duration: 112.055087ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:27.64652Z","caller":"traceutil/trace.go:171","msg":"trace[1260166037] transaction","detail":"{read_only:false; response_revision:25488; number_of_response:1; }","duration":"119.498788ms","start":"2026-08-15T05:48:27.526996Z","end":"2026-08-15T05:48:27.646495Z","steps":["trace[1260166037] 'process raft request' (duration: 117.910699ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:28.249273Z","caller":"traceutil/trace.go:171","msg":"trace[835426848] transaction","detail":"{read_only:false; response_revision:25492; number_of_response:1; }","duration":"169.774293ms","start":"2026-08-15T05:48:28.079458Z","end":"2026-08-15T05:48:28.249233Z","steps":["trace[835426848] 'process raft request' (duration: 77.187516ms)","trace[835426848] 'compare' (duration: 92.260589ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:48:28.558694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.343829ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-15T05:48:28.558795Z","caller":"traceutil/trace.go:171","msg":"trace[782938124] linearizableReadLoop","detail":"{readStateIndex:26312; appliedIndex:26311; }","duration":"178.157365ms","start":"2026-08-15T05:48:28.380624Z","end":"2026-08-15T05:48:28.558781Z","steps":["trace[782938124] 'read index received' (duration: 47.57542ms)","trace[782938124] 'applied index is now lower than readState.Index' (duration: 130.580575ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:48:28.558866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.353579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-08-15T05:48:28.558843Z","caller":"traceutil/trace.go:171","msg":"trace[1963917254] transaction","detail":"{read_only:false; response_revision:25493; number_of_response:1; }","duration":"211.90761ms","start":"2026-08-15T05:48:28.346897Z","end":"2026-08-15T05:48:28.558805Z","steps":["trace[1963917254] 'process raft request' (duration: 81.353376ms)","trace[1963917254] 'compare' (duration: 130.119554ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:28.558895Z","caller":"traceutil/trace.go:171","msg":"trace[1934865227] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25493; }","duration":"178.409051ms","start":"2026-08-15T05:48:28.380475Z","end":"2026-08-15T05:48:28.558884Z","steps":["trace[1934865227] 'agreement among raft nodes before linearized reading' (duration: 178.342149ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:28.846108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.094691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-15T05:48:28.846243Z","caller":"traceutil/trace.go:171","msg":"trace[1437337730] transaction","detail":"{read_only:false; response_revision:25494; number_of_response:1; }","duration":"282.906086ms","start":"2026-08-15T05:48:28.563319Z","end":"2026-08-15T05:48:28.846225Z","steps":["trace[1437337730] 'process raft request' (duration: 138.732113ms)","trace[1437337730] 'compare' (duration: 136.739633ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:28.854463Z","caller":"traceutil/trace.go:171","msg":"trace[1084520663] linearizableReadLoop","detail":"{readStateIndex:26315; appliedIndex:26313; }","duration":"110.897ms","start":"2026-08-15T05:48:28.743553Z","end":"2026-08-15T05:48:28.854451Z","steps":["trace[1084520663] 'read index received' (duration: 110.767937ms)","trace[1084520663] 'applied index is now lower than readState.Index' (duration: 127.823µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:28.854527Z","caller":"traceutil/trace.go:171","msg":"trace[1536486609] transaction","detail":"{read_only:false; response_revision:25495; number_of_response:1; }","duration":"270.420645ms","start":"2026-08-15T05:48:28.58408Z","end":"2026-08-15T05:48:28.854501Z","steps":["trace[1536486609] 'process raft request' (duration: 270.239421ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:28.854641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.089214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-08-15T05:48:28.854667Z","caller":"traceutil/trace.go:171","msg":"trace[117189861] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25496; }","duration":"111.182307ms","start":"2026-08-15T05:48:28.743476Z","end":"2026-08-15T05:48:28.854659Z","steps":["trace[117189861] 'agreement among raft nodes before linearized reading' (duration: 111.020853ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:28.854735Z","caller":"traceutil/trace.go:171","msg":"trace[1681285318] transaction","detail":"{read_only:false; response_revision:25496; number_of_response:1; }","duration":"269.078953ms","start":"2026-08-15T05:48:28.585649Z","end":"2026-08-15T05:48:28.854727Z","steps":["trace[1681285318] 'process raft request' (duration: 268.767165ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:33.044154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.362543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1985"} {"level":"info","ts":"2026-08-15T05:48:33.044245Z","caller":"traceutil/trace.go:171","msg":"trace[320441188] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:25526; }","duration":"121.485976ms","start":"2026-08-15T05:48:32.922734Z","end":"2026-08-15T05:48:33.04422Z","steps":["trace[320441188] 'range keys from in-memory index tree' (duration: 121.185048ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:33.300621Z","caller":"traceutil/trace.go:171","msg":"trace[622695379] linearizableReadLoop","detail":"{readStateIndex:26348; appliedIndex:26346; }","duration":"247.595343ms","start":"2026-08-15T05:48:33.052726Z","end":"2026-08-15T05:48:33.300321Z","steps":["trace[622695379] 'read index received' (duration: 8.132056ms)","trace[622695379] 'applied index is now lower than readState.Index' (duration: 239.462157ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:33.300535Z","caller":"traceutil/trace.go:171","msg":"trace[706684358] transaction","detail":"{read_only:false; response_revision:25528; number_of_response:1; }","duration":"248.493996ms","start":"2026-08-15T05:48:33.052009Z","end":"2026-08-15T05:48:33.300503Z","steps":["trace[706684358] 'process raft request' (duration: 248.138207ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:33.300791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.043974ms","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-15T05:48:33.300875Z","caller":"traceutil/trace.go:171","msg":"trace[1928541919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25528; }","duration":"248.165416ms","start":"2026-08-15T05:48:33.052692Z","end":"2026-08-15T05:48:33.300857Z","steps":["trace[1928541919] 'agreement among raft nodes before linearized reading' (duration: 247.91243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:33.317676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.121346ms","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-15T05:48:33.317706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.999924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra-registry.18cbe47c0a1d2490\" ","response":"range_response_count:1 size:722"} {"level":"info","ts":"2026-08-15T05:48:33.317744Z","caller":"traceutil/trace.go:171","msg":"trace[501110195] range","detail":"{range_begin:/registry/events/openstack/container-infra-registry.18cbe47c0a1d2490; range_end:; response_count:1; response_revision:25529; }","duration":"253.058095ms","start":"2026-08-15T05:48:33.064676Z","end":"2026-08-15T05:48:33.317734Z","steps":["trace[501110195] 'agreement among raft nodes before linearized reading' (duration: 252.966213ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:33.317829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.021509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-15T05:48:33.317743Z","caller":"traceutil/trace.go:171","msg":"trace[2122678562] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25529; }","duration":"260.198878ms","start":"2026-08-15T05:48:33.057528Z","end":"2026-08-15T05:48:33.317726Z","steps":["trace[2122678562] 'agreement among raft nodes before linearized reading' (duration: 260.030524ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:33.317891Z","caller":"traceutil/trace.go:171","msg":"trace[1432744551] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25529; }","duration":"259.09265ms","start":"2026-08-15T05:48:33.05878Z","end":"2026-08-15T05:48:33.317873Z","steps":["trace[1432744551] 'agreement among raft nodes before linearized reading' (duration: 258.961737ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:33.45996Z","caller":"traceutil/trace.go:171","msg":"trace[1328246822] transaction","detail":"{read_only:false; response_revision:25531; number_of_response:1; }","duration":"137.734068ms","start":"2026-08-15T05:48:33.322201Z","end":"2026-08-15T05:48:33.459935Z","steps":["trace[1328246822] 'process raft request' (duration: 137.490783ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:33.460243Z","caller":"traceutil/trace.go:171","msg":"trace[115914520] transaction","detail":"{read_only:false; response_revision:25532; number_of_response:1; }","duration":"136.198951ms","start":"2026-08-15T05:48:33.324029Z","end":"2026-08-15T05:48:33.460228Z","steps":["trace[115914520] 'process raft request' (duration: 135.839753ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:33.460268Z","caller":"traceutil/trace.go:171","msg":"trace[1827950382] transaction","detail":"{read_only:false; response_revision:25533; number_of_response:1; }","duration":"135.436283ms","start":"2026-08-15T05:48:33.324806Z","end":"2026-08-15T05:48:33.460243Z","steps":["trace[1827950382] 'process raft request' (duration: 135.31855ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:33.598885Z","caller":"traceutil/trace.go:171","msg":"trace[1399355439] transaction","detail":"{read_only:false; response_revision:25534; number_of_response:1; }","duration":"132.241277ms","start":"2026-08-15T05:48:33.46662Z","end":"2026-08-15T05:48:33.598862Z","steps":["trace[1399355439] 'process raft request' (duration: 117.990792ms)","trace[1399355439] 'compare' (duration: 13.628089ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:33.599027Z","caller":"traceutil/trace.go:171","msg":"trace[979361421] linearizableReadLoop","detail":"{readStateIndex:26354; appliedIndex:26353; }","duration":"113.538994ms","start":"2026-08-15T05:48:33.485451Z","end":"2026-08-15T05:48:33.59899Z","steps":["trace[979361421] 'read index received' (duration: 99.273438ms)","trace[979361421] 'applied index is now lower than readState.Index' (duration: 14.259066ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:33.599134Z","caller":"traceutil/trace.go:171","msg":"trace[1335916367] transaction","detail":"{read_only:false; response_revision:25535; number_of_response:1; }","duration":"103.952223ms","start":"2026-08-15T05:48:33.495146Z","end":"2026-08-15T05:48:33.599098Z","steps":["trace[1335916367] 'process raft request' (duration: 103.651105ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:33.5993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.843252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"warn","ts":"2026-08-15T05:48:33.59934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.025442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-15T05:48:33.599384Z","caller":"traceutil/trace.go:171","msg":"trace[1496580984] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25535; }","duration":"113.936784ms","start":"2026-08-15T05:48:33.485429Z","end":"2026-08-15T05:48:33.599366Z","steps":["trace[1496580984] 'agreement among raft nodes before linearized reading' (duration: 113.718899ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:33.59938Z","caller":"traceutil/trace.go:171","msg":"trace[330790026] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25535; }","duration":"106.125025ms","start":"2026-08-15T05:48:33.493245Z","end":"2026-08-15T05:48:33.59937Z","steps":["trace[330790026] 'agreement among raft nodes before linearized reading' (duration: 105.92069ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:34.051216Z","caller":"traceutil/trace.go:171","msg":"trace[2045304956] transaction","detail":"{read_only:false; response_revision:25537; number_of_response:1; }","duration":"124.882099ms","start":"2026-08-15T05:48:33.926301Z","end":"2026-08-15T05:48:34.051183Z","steps":["trace[2045304956] 'process raft request' (duration: 124.670784ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:34.203218Z","caller":"traceutil/trace.go:171","msg":"trace[1510072249] transaction","detail":"{read_only:false; response_revision:25538; number_of_response:1; }","duration":"173.676457ms","start":"2026-08-15T05:48:34.029498Z","end":"2026-08-15T05:48:34.203174Z","steps":["trace[1510072249] 'process raft request' (duration: 172.906849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:34.203325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.843448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-15T05:48:34.203526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.259161ms","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-15T05:48:34.20339Z","caller":"traceutil/trace.go:171","msg":"trace[162063053] linearizableReadLoop","detail":"{readStateIndex:26358; appliedIndex:26357; }","duration":"151.971712ms","start":"2026-08-15T05:48:34.051147Z","end":"2026-08-15T05:48:34.203118Z","steps":["trace[162063053] 'read index received' (duration: 151.07008ms)","trace[162063053] 'applied index is now lower than readState.Index' (duration: 899.172µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:34.203726Z","caller":"traceutil/trace.go:171","msg":"trace[2022172641] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25538; }","duration":"251.523817ms","start":"2026-08-15T05:48:33.952187Z","end":"2026-08-15T05:48:34.203711Z","steps":["trace[2022172641] 'agreement among raft nodes before linearized reading' (duration: 251.180779ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:34.203855Z","caller":"traceutil/trace.go:171","msg":"trace[1390005578] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:25538; }","duration":"250.174536ms","start":"2026-08-15T05:48:33.953442Z","end":"2026-08-15T05:48:34.203617Z","steps":["trace[1390005578] 'agreement among raft nodes before linearized reading' (duration: 249.759966ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:34.252871Z","caller":"traceutil/trace.go:171","msg":"trace[359676923] transaction","detail":"{read_only:false; response_revision:25539; number_of_response:1; }","duration":"196.464248ms","start":"2026-08-15T05:48:34.056385Z","end":"2026-08-15T05:48:34.252849Z","steps":["trace[359676923] 'process raft request' (duration: 196.236332ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:34.252911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.487997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:48:34.253017Z","caller":"traceutil/trace.go:171","msg":"trace[1886926622] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25539; }","duration":"102.644821ms","start":"2026-08-15T05:48:34.150345Z","end":"2026-08-15T05:48:34.25299Z","steps":["trace[1886926622] 'agreement among raft nodes before linearized reading' (duration: 102.440866ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:34.498136Z","caller":"traceutil/trace.go:171","msg":"trace[445253569] linearizableReadLoop","detail":"{readStateIndex:26360; appliedIndex:26359; }","duration":"236.957316ms","start":"2026-08-15T05:48:34.261157Z","end":"2026-08-15T05:48:34.498115Z","steps":["trace[445253569] 'read index received' (duration: 211.355677ms)","trace[445253569] 'applied index is now lower than readState.Index' (duration: 25.600399ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:34.498251Z","caller":"traceutil/trace.go:171","msg":"trace[1911398277] transaction","detail":"{read_only:false; response_revision:25540; number_of_response:1; }","duration":"290.941371ms","start":"2026-08-15T05:48:34.207216Z","end":"2026-08-15T05:48:34.498157Z","steps":["trace[1911398277] 'process raft request' (duration: 265.28047ms)","trace[1911398277] 'compare' (duration: 25.520847ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:48:34.498292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.10688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:48:34.498332Z","caller":"traceutil/trace.go:171","msg":"trace[651973684] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25540; }","duration":"237.172212ms","start":"2026-08-15T05:48:34.261147Z","end":"2026-08-15T05:48:34.498319Z","steps":["trace[651973684] 'agreement among raft nodes before linearized reading' (duration: 237.07751ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:34.500153Z","caller":"traceutil/trace.go:171","msg":"trace[860120280] transaction","detail":"{read_only:false; response_revision:25541; number_of_response:1; }","duration":"230.737586ms","start":"2026-08-15T05:48:34.269405Z","end":"2026-08-15T05:48:34.500142Z","steps":["trace[860120280] 'process raft request' (duration: 230.404678ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:34.684446Z","caller":"traceutil/trace.go:171","msg":"trace[1479694954] linearizableReadLoop","detail":"{readStateIndex:26363; appliedIndex:26362; }","duration":"154.220547ms","start":"2026-08-15T05:48:34.530206Z","end":"2026-08-15T05:48:34.684427Z","steps":["trace[1479694954] 'read index received' (duration: 130.306759ms)","trace[1479694954] 'applied index is now lower than readState.Index' (duration: 23.913248ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:34.684467Z","caller":"traceutil/trace.go:171","msg":"trace[1424675830] transaction","detail":"{read_only:false; response_revision:25542; number_of_response:1; }","duration":"159.667908ms","start":"2026-08-15T05:48:34.524774Z","end":"2026-08-15T05:48:34.684442Z","steps":["trace[1424675830] 'process raft request' (duration: 135.75172ms)","trace[1424675830] 'compare' (duration: 23.796425ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:48:34.684626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.406201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:48:34.684683Z","caller":"traceutil/trace.go:171","msg":"trace[506883690] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25542; }","duration":"154.518674ms","start":"2026-08-15T05:48:34.530151Z","end":"2026-08-15T05:48:34.68467Z","steps":["trace[506883690] 'agreement among raft nodes before linearized reading' (duration: 154.341679ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:35.114141Z","caller":"traceutil/trace.go:171","msg":"trace[53991834] transaction","detail":"{read_only:false; response_revision:25543; number_of_response:1; }","duration":"242.235703ms","start":"2026-08-15T05:48:34.871879Z","end":"2026-08-15T05:48:35.114115Z","steps":["trace[53991834] 'process raft request' (duration: 242.034698ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:35.159376Z","caller":"traceutil/trace.go:171","msg":"trace[346553721] linearizableReadLoop","detail":"{readStateIndex:26365; appliedIndex:26363; }","duration":"254.263164ms","start":"2026-08-15T05:48:34.905089Z","end":"2026-08-15T05:48:35.159352Z","steps":["trace[346553721] 'read index received' (duration: 208.849976ms)","trace[346553721] 'applied index is now lower than readState.Index' (duration: 45.412028ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:35.159401Z","caller":"traceutil/trace.go:171","msg":"trace[805922794] transaction","detail":"{read_only:false; response_revision:25544; number_of_response:1; }","duration":"284.853853ms","start":"2026-08-15T05:48:34.874518Z","end":"2026-08-15T05:48:35.159372Z","steps":["trace[805922794] 'process raft request' (duration: 282.716371ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:35.159453Z","caller":"traceutil/trace.go:171","msg":"trace[1428024455] transaction","detail":"{read_only:false; response_revision:25545; number_of_response:1; }","duration":"201.231133ms","start":"2026-08-15T05:48:34.958198Z","end":"2026-08-15T05:48:35.159429Z","steps":["trace[1428024455] 'process raft request' (duration: 201.10204ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:35.159623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.452557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:48:35.159674Z","caller":"traceutil/trace.go:171","msg":"trace[875124267] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:25545; }","duration":"102.565229ms","start":"2026-08-15T05:48:35.057095Z","end":"2026-08-15T05:48:35.159661Z","steps":["trace[875124267] 'agreement among raft nodes before linearized reading' (duration: 102.461687ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:35.160193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.094185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-08-15T05:48:35.160239Z","caller":"traceutil/trace.go:171","msg":"trace[1585724189] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:25545; }","duration":"255.141086ms","start":"2026-08-15T05:48:34.905085Z","end":"2026-08-15T05:48:35.160226Z","steps":["trace[1585724189] 'agreement among raft nodes before linearized reading' (duration: 255.003772ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:35.465305Z","caller":"traceutil/trace.go:171","msg":"trace[1178375034] linearizableReadLoop","detail":"{readStateIndex:26367; appliedIndex:26366; }","duration":"129.854698ms","start":"2026-08-15T05:48:35.335417Z","end":"2026-08-15T05:48:35.465272Z","steps":["trace[1178375034] 'read index received' (duration: 124.185161ms)","trace[1178375034] 'applied index is now lower than readState.Index' (duration: 5.668067ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:35.465432Z","caller":"traceutil/trace.go:171","msg":"trace[1259564815] transaction","detail":"{read_only:false; response_revision:25546; number_of_response:1; }","duration":"140.190888ms","start":"2026-08-15T05:48:35.325215Z","end":"2026-08-15T05:48:35.465406Z","steps":["trace[1259564815] 'process raft request' (duration: 134.402898ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:35.465649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.207847ms","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-15T05:48:35.465727Z","caller":"traceutil/trace.go:171","msg":"trace[454092258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25546; }","duration":"130.348881ms","start":"2026-08-15T05:48:35.335359Z","end":"2026-08-15T05:48:35.465708Z","steps":["trace[454092258] 'agreement among raft nodes before linearized reading' (duration: 130.057024ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:35.768987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.27705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-15T05:48:35.769279Z","caller":"traceutil/trace.go:171","msg":"trace[664555063] transaction","detail":"{read_only:false; response_revision:25548; number_of_response:1; }","duration":"298.83484ms","start":"2026-08-15T05:48:35.47041Z","end":"2026-08-15T05:48:35.769245Z","steps":["trace[664555063] 'process raft request' (duration: 194.195282ms)","trace[664555063] 'compare' (duration: 104.028024ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:35.769316Z","caller":"traceutil/trace.go:171","msg":"trace[992327364] transaction","detail":"{read_only:false; response_revision:25549; number_of_response:1; }","duration":"297.879848ms","start":"2026-08-15T05:48:35.471419Z","end":"2026-08-15T05:48:35.769299Z","steps":["trace[992327364] 'process raft request' (duration: 297.756635ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:35.769549Z","caller":"traceutil/trace.go:171","msg":"trace[1091408377] transaction","detail":"{read_only:false; response_revision:25550; number_of_response:1; }","duration":"221.739388ms","start":"2026-08-15T05:48:35.547797Z","end":"2026-08-15T05:48:35.769536Z","steps":["trace[1091408377] 'process raft request' (duration: 221.455592ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:35.769819Z","caller":"traceutil/trace.go:171","msg":"trace[1849831508] linearizableReadLoop","detail":"{readStateIndex:26371; appliedIndex:26368; }","duration":"165.322845ms","start":"2026-08-15T05:48:35.604422Z","end":"2026-08-15T05:48:35.769745Z","steps":["trace[1849831508] 'read index received' (duration: 60.200276ms)","trace[1849831508] 'applied index is now lower than readState.Index' (duration: 105.121179ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:35.769845Z","caller":"traceutil/trace.go:171","msg":"trace[887665939] transaction","detail":"{read_only:false; response_revision:25551; number_of_response:1; }","duration":"162.710722ms","start":"2026-08-15T05:48:35.607086Z","end":"2026-08-15T05:48:35.769797Z","steps":["trace[887665939] 'process raft request' (duration: 162.409334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:35.770066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.632653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-15T05:48:35.770116Z","caller":"traceutil/trace.go:171","msg":"trace[984966862] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25551; }","duration":"165.737206ms","start":"2026-08-15T05:48:35.604366Z","end":"2026-08-15T05:48:35.770104Z","steps":["trace[984966862] 'agreement among raft nodes before linearized reading' (duration: 165.532021ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:35.770325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.943206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-15T05:48:35.770403Z","caller":"traceutil/trace.go:171","msg":"trace[1426993728] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25551; }","duration":"165.043879ms","start":"2026-08-15T05:48:35.605345Z","end":"2026-08-15T05:48:35.770389Z","steps":["trace[1426993728] 'agreement among raft nodes before linearized reading' (duration: 164.717351ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:35.770537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.333745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-15T05:48:35.770625Z","caller":"traceutil/trace.go:171","msg":"trace[335344444] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25551; }","duration":"160.627283ms","start":"2026-08-15T05:48:35.609979Z","end":"2026-08-15T05:48:35.770606Z","steps":["trace[335344444] 'agreement among raft nodes before linearized reading' (duration: 160.356966ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:36.562265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.812898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-15T05:48:36.562394Z","caller":"traceutil/trace.go:171","msg":"trace[381121165] linearizableReadLoop","detail":"{readStateIndex:26376; appliedIndex:26375; }","duration":"147.221337ms","start":"2026-08-15T05:48:36.41513Z","end":"2026-08-15T05:48:36.562352Z","steps":["trace[381121165] 'read index received' (duration: 18.08µs)","trace[381121165] 'applied index is now lower than readState.Index' (duration: 147.201977ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:36.562481Z","caller":"traceutil/trace.go:171","msg":"trace[1369146442] transaction","detail":"{read_only:false; response_revision:25555; number_of_response:1; }","duration":"282.240919ms","start":"2026-08-15T05:48:36.280228Z","end":"2026-08-15T05:48:36.562469Z","steps":["trace[1369146442] 'process raft request' (duration: 130.139624ms)","trace[1369146442] 'compare' (duration: 151.483131ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:48:36.562535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.396902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:48:36.562594Z","caller":"traceutil/trace.go:171","msg":"trace[1797544529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25555; }","duration":"147.487083ms","start":"2026-08-15T05:48:36.415095Z","end":"2026-08-15T05:48:36.562582Z","steps":["trace[1797544529] 'agreement among raft nodes before linearized reading' (duration: 147.390821ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:51.922893Z","caller":"traceutil/trace.go:171","msg":"trace[1081068779] linearizableReadLoop","detail":"{readStateIndex:26548; appliedIndex:26547; }","duration":"119.034757ms","start":"2026-08-15T05:48:51.803832Z","end":"2026-08-15T05:48:51.922867Z","steps":["trace[1081068779] 'read index received' (duration: 118.897153ms)","trace[1081068779] 'applied index is now lower than readState.Index' (duration: 136.314µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:51.922949Z","caller":"traceutil/trace.go:171","msg":"trace[1207652445] transaction","detail":"{read_only:false; response_revision:25724; number_of_response:1; }","duration":"173.610625ms","start":"2026-08-15T05:48:51.749318Z","end":"2026-08-15T05:48:51.922929Z","steps":["trace[1207652445] 'process raft request' (duration: 173.38549ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:51.923144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.290773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-15T05:48:51.923208Z","caller":"traceutil/trace.go:171","msg":"trace[1906578657] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25724; }","duration":"119.410476ms","start":"2026-08-15T05:48:51.80378Z","end":"2026-08-15T05:48:51.923191Z","steps":["trace[1906578657] 'agreement among raft nodes before linearized reading' (duration: 119.232142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:52.150677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.361417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-15T05:48:52.150766Z","caller":"traceutil/trace.go:171","msg":"trace[95782430] linearizableReadLoop","detail":"{readStateIndex:26549; appliedIndex:26548; }","duration":"227.765683ms","start":"2026-08-15T05:48:51.922987Z","end":"2026-08-15T05:48:52.150753Z","steps":["trace[95782430] 'read index received' (duration: 81.276214ms)","trace[95782430] 'applied index is now lower than readState.Index' (duration: 146.488179ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:52.150836Z","caller":"traceutil/trace.go:171","msg":"trace[1083424938] transaction","detail":"{read_only:false; response_revision:25725; number_of_response:1; }","duration":"326.342706ms","start":"2026-08-15T05:48:51.824484Z","end":"2026-08-15T05:48:52.150826Z","steps":["trace[1083424938] 'process raft request' (duration: 179.742704ms)","trace[1083424938] 'compare' (duration: 146.08266ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:48:52.150875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:48:51.82446Z","time spent":"326.398528ms","remote":"127.0.0.1:58140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T05:48:52.151103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.239237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-15T05:48:52.151124Z","caller":"traceutil/trace.go:171","msg":"trace[1153672736] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25725; }","duration":"327.275529ms","start":"2026-08-15T05:48:51.823843Z","end":"2026-08-15T05:48:52.151119Z","steps":["trace[1153672736] 'agreement among raft nodes before linearized reading' (duration: 327.163286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:52.15114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:48:51.823838Z","time spent":"327.298569ms","remote":"127.0.0.1:58052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":697,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-15T05:48:52.151092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.612489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-15T05:48:52.151211Z","caller":"traceutil/trace.go:171","msg":"trace[960069614] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25725; }","duration":"301.761362ms","start":"2026-08-15T05:48:51.849417Z","end":"2026-08-15T05:48:52.151179Z","steps":["trace[960069614] 'agreement among raft nodes before linearized reading' (duration: 301.519297ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:52.151256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.433272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-15T05:48:52.151272Z","caller":"traceutil/trace.go:171","msg":"trace[1827929042] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25725; }","duration":"327.487334ms","start":"2026-08-15T05:48:51.82378Z","end":"2026-08-15T05:48:52.151268Z","steps":["trace[1827929042] 'agreement among raft nodes before linearized reading' (duration: 327.438022ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:52.151287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:48:51.82376Z","time spent":"327.524055ms","remote":"127.0.0.1:58052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":697,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-15T05:48:52.151274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:48:51.8494Z","time spent":"301.860614ms","remote":"127.0.0.1:58140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-15T05:48:52.151371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.54316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:48:52.151395Z","caller":"traceutil/trace.go:171","msg":"trace[753345577] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25725; }","duration":"220.600071ms","start":"2026-08-15T05:48:51.930789Z","end":"2026-08-15T05:48:52.151389Z","steps":["trace[753345577] 'agreement among raft nodes before linearized reading' (duration: 220.55515ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:52.151605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.82313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:48:52.151662Z","caller":"traceutil/trace.go:171","msg":"trace[2066473388] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25725; }","duration":"233.904362ms","start":"2026-08-15T05:48:51.917743Z","end":"2026-08-15T05:48:52.151647Z","steps":["trace[2066473388] 'agreement among raft nodes before linearized reading' (duration: 233.809459ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:52.151977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.059135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-15T05:48:52.152005Z","caller":"traceutil/trace.go:171","msg":"trace[326228707] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25725; }","duration":"270.110317ms","start":"2026-08-15T05:48:51.881889Z","end":"2026-08-15T05:48:52.152Z","steps":["trace[326228707] 'agreement among raft nodes before linearized reading' (duration: 270.046655ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:52.152093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.551127ms","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-15T05:48:52.152113Z","caller":"traceutil/trace.go:171","msg":"trace[969297185] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25725; }","duration":"282.603239ms","start":"2026-08-15T05:48:51.869506Z","end":"2026-08-15T05:48:52.152109Z","steps":["trace[969297185] 'agreement among raft nodes before linearized reading' (duration: 282.557857ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:52.151972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.659396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-08-15T05:48:52.152185Z","caller":"traceutil/trace.go:171","msg":"trace[2000419878] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:25725; }","duration":"240.903281ms","start":"2026-08-15T05:48:51.911274Z","end":"2026-08-15T05:48:52.152177Z","steps":["trace[2000419878] 'agreement among raft nodes before linearized reading' (duration: 240.588434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:52.419193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.456304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-15T05:48:52.419363Z","caller":"traceutil/trace.go:171","msg":"trace[1883259372] transaction","detail":"{read_only:false; response_revision:25728; number_of_response:1; }","duration":"254.182643ms","start":"2026-08-15T05:48:52.165166Z","end":"2026-08-15T05:48:52.419348Z","steps":["trace[1883259372] 'process raft request' (duration: 254.121901ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:52.419392Z","caller":"traceutil/trace.go:171","msg":"trace[786607416] transaction","detail":"{read_only:false; response_revision:25727; number_of_response:1; }","duration":"254.235694ms","start":"2026-08-15T05:48:52.165122Z","end":"2026-08-15T05:48:52.419358Z","steps":["trace[786607416] 'process raft request' (duration: 125.537354ms)","trace[786607416] 'compare' (duration: 128.327351ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:52.567901Z","caller":"traceutil/trace.go:171","msg":"trace[1497229493] transaction","detail":"{read_only:false; response_revision:25729; number_of_response:1; }","duration":"226.724609ms","start":"2026-08-15T05:48:52.341144Z","end":"2026-08-15T05:48:52.567869Z","steps":["trace[1497229493] 'process raft request' (duration: 226.283138ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:52.838685Z","caller":"traceutil/trace.go:171","msg":"trace[1202574008] linearizableReadLoop","detail":"{readStateIndex:26554; appliedIndex:26553; }","duration":"195.910863ms","start":"2026-08-15T05:48:52.642722Z","end":"2026-08-15T05:48:52.838632Z","steps":["trace[1202574008] 'read index received' (duration: 192.4692ms)","trace[1202574008] 'applied index is now lower than readState.Index' (duration: 3.439853ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:48:52.839311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.694144ms","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-15T05:48:52.839407Z","caller":"traceutil/trace.go:171","msg":"trace[337369860] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25730; }","duration":"160.824807ms","start":"2026-08-15T05:48:52.678559Z","end":"2026-08-15T05:48:52.839384Z","steps":["trace[337369860] 'agreement among raft nodes before linearized reading' (duration: 160.576111ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:52.839389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.641692ms","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-15T05:48:52.83947Z","caller":"traceutil/trace.go:171","msg":"trace[121086210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25730; }","duration":"196.773615ms","start":"2026-08-15T05:48:52.642678Z","end":"2026-08-15T05:48:52.839451Z","steps":["trace[121086210] 'agreement among raft nodes before linearized reading' (duration: 196.530759ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:52.839449Z","caller":"traceutil/trace.go:171","msg":"trace[1746640433] transaction","detail":"{read_only:false; response_revision:25730; number_of_response:1; }","duration":"215.142859ms","start":"2026-08-15T05:48:52.624282Z","end":"2026-08-15T05:48:52.839425Z","steps":["trace[1746640433] 'process raft request' (duration: 210.867175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:52.83968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.781649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-77e2fd09-ba16-4a91-8ec5-c4f121ecf58b\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-08-15T05:48:52.839718Z","caller":"traceutil/trace.go:171","msg":"trace[1903625914] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-77e2fd09-ba16-4a91-8ec5-c4f121ecf58b; range_end:; response_count:1; response_revision:25730; }","duration":"144.858651ms","start":"2026-08-15T05:48:52.694847Z","end":"2026-08-15T05:48:52.839706Z","steps":["trace[1903625914] 'agreement among raft nodes before linearized reading' (duration: 144.754958ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:53.228652Z","caller":"traceutil/trace.go:171","msg":"trace[184517956] transaction","detail":"{read_only:false; response_revision:25733; number_of_response:1; }","duration":"166.490774ms","start":"2026-08-15T05:48:53.062138Z","end":"2026-08-15T05:48:53.228628Z","steps":["trace[184517956] 'process raft request' (duration: 166.3011ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:53.286276Z","caller":"traceutil/trace.go:171","msg":"trace[559335274] transaction","detail":"{read_only:false; response_revision:25734; number_of_response:1; }","duration":"101.062822ms","start":"2026-08-15T05:48:53.185182Z","end":"2026-08-15T05:48:53.286245Z","steps":["trace[559335274] 'process raft request' (duration: 100.790315ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:53.594473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.424824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-15T05:48:53.594583Z","caller":"traceutil/trace.go:171","msg":"trace[2112861049] linearizableReadLoop","detail":"{readStateIndex:26560; appliedIndex:26559; }","duration":"303.449502ms","start":"2026-08-15T05:48:53.291116Z","end":"2026-08-15T05:48:53.594565Z","steps":["trace[2112861049] 'read index received' (duration: 145.756752ms)","trace[2112861049] 'applied index is now lower than readState.Index' (duration: 157.69123ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:53.594655Z","caller":"traceutil/trace.go:171","msg":"trace[1279040489] transaction","detail":"{read_only:false; response_revision:25736; number_of_response:1; }","duration":"303.586986ms","start":"2026-08-15T05:48:53.291056Z","end":"2026-08-15T05:48:53.594643Z","steps":["trace[1279040489] 'process raft request' (duration: 145.910276ms)","trace[1279040489] 'compare' (duration: 157.186369ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:48:53.594722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:48:53.29103Z","time spent":"303.658977ms","remote":"127.0.0.1:58140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T05:48:53.594778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.641627ms","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-15T05:48:53.594856Z","caller":"traceutil/trace.go:171","msg":"trace[652029771] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25736; }","duration":"303.76076ms","start":"2026-08-15T05:48:53.291078Z","end":"2026-08-15T05:48:53.594838Z","steps":["trace[652029771] 'agreement among raft nodes before linearized reading' (duration: 303.565715ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:53.594902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:48:53.291061Z","time spent":"303.832362ms","remote":"127.0.0.1:58056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-15T05:48:53.617082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.244174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:48:53.617146Z","caller":"traceutil/trace.go:171","msg":"trace[1981608487] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:25737; }","duration":"187.368218ms","start":"2026-08-15T05:48:53.429763Z","end":"2026-08-15T05:48:53.617131Z","steps":["trace[1981608487] 'agreement among raft nodes before linearized reading' (duration: 187.223254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:53.617158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.1398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:48:53.617237Z","caller":"traceutil/trace.go:171","msg":"trace[227622480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25737; }","duration":"201.272803ms","start":"2026-08-15T05:48:53.415946Z","end":"2026-08-15T05:48:53.617219Z","steps":["trace[227622480] 'agreement among raft nodes before linearized reading' (duration: 201.117869ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:53.782661Z","caller":"traceutil/trace.go:171","msg":"trace[810527873] transaction","detail":"{read_only:false; response_revision:25738; number_of_response:1; }","duration":"162.113967ms","start":"2026-08-15T05:48:53.620506Z","end":"2026-08-15T05:48:53.782619Z","steps":["trace[810527873] 'process raft request' (duration: 114.846255ms)","trace[810527873] 'compare' (duration: 46.699688ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:53.782787Z","caller":"traceutil/trace.go:171","msg":"trace[1105469906] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25738; }","duration":"100.005666ms","start":"2026-08-15T05:48:53.68259Z","end":"2026-08-15T05:48:53.782595Z","steps":["trace[1105469906] 'agreement among raft nodes before linearized reading' (duration: 99.916844ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:54.05725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.907128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-15T05:48:54.057336Z","caller":"traceutil/trace.go:171","msg":"trace[1327214068] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25738; }","duration":"130.029823ms","start":"2026-08-15T05:48:53.927287Z","end":"2026-08-15T05:48:54.057317Z","steps":["trace[1327214068] 'range keys from in-memory index tree' (duration: 129.54203ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:54.503692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.130589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-15T05:48:54.503797Z","caller":"traceutil/trace.go:171","msg":"trace[524197353] linearizableReadLoop","detail":"{readStateIndex:26564; appliedIndex:26563; }","duration":"344.74047ms","start":"2026-08-15T05:48:54.159038Z","end":"2026-08-15T05:48:54.503778Z","steps":["trace[524197353] 'read index received' (duration: 16.126699ms)","trace[524197353] 'applied index is now lower than readState.Index' (duration: 328.612391ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:54.503886Z","caller":"traceutil/trace.go:171","msg":"trace[1247824325] transaction","detail":"{read_only:false; response_revision:25740; number_of_response:1; }","duration":"345.17053ms","start":"2026-08-15T05:48:54.158704Z","end":"2026-08-15T05:48:54.503874Z","steps":["trace[1247824325] 'process raft request' (duration: 184.745854ms)","trace[1247824325] 'compare' (duration: 159.988816ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:48:54.503942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:48:54.158677Z","time spent":"345.234812ms","remote":"127.0.0.1:58140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T05:48:54.504131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.085879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"warn","ts":"2026-08-15T05:48:54.504134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.000118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-15T05:48:54.5042Z","caller":"traceutil/trace.go:171","msg":"trace[751405221] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25740; }","duration":"345.188761ms","start":"2026-08-15T05:48:54.158996Z","end":"2026-08-15T05:48:54.504185Z","steps":["trace[751405221] 'agreement among raft nodes before linearized reading' (duration: 345.002296ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:54.504214Z","caller":"traceutil/trace.go:171","msg":"trace[1644313018] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25740; }","duration":"302.137311ms","start":"2026-08-15T05:48:54.202058Z","end":"2026-08-15T05:48:54.504195Z","steps":["trace[1644313018] 'agreement among raft nodes before linearized reading' (duration: 301.921556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:54.504228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:48:54.158987Z","time spent":"345.235423ms","remote":"127.0.0.1:58052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":697,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-15T05:48:54.504255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:48:54.202035Z","time spent":"302.211513ms","remote":"127.0.0.1:58140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-15T05:48:54.774037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.358729ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-15T05:48:54.774273Z","caller":"traceutil/trace.go:171","msg":"trace[67796678] transaction","detail":"{read_only:false; response_revision:25742; number_of_response:1; }","duration":"265.399613ms","start":"2026-08-15T05:48:54.508856Z","end":"2026-08-15T05:48:54.774256Z","steps":["trace[67796678] 'process raft request' (duration: 265.28882ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:54.774259Z","caller":"traceutil/trace.go:171","msg":"trace[603664633] transaction","detail":"{read_only:false; response_revision:25741; number_of_response:1; }","duration":"266.032629ms","start":"2026-08-15T05:48:54.508193Z","end":"2026-08-15T05:48:54.774225Z","steps":["trace[603664633] 'process raft request' (duration: 140.374063ms)","trace[603664633] 'compare' (duration: 124.944799ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:54.774464Z","caller":"traceutil/trace.go:171","msg":"trace[609374336] transaction","detail":"{read_only:false; response_revision:25743; number_of_response:1; }","duration":"265.319662ms","start":"2026-08-15T05:48:54.509126Z","end":"2026-08-15T05:48:54.774446Z","steps":["trace[609374336] 'process raft request' (duration: 265.088956ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:54.774617Z","caller":"traceutil/trace.go:171","msg":"trace[67470283] transaction","detail":"{read_only:false; response_revision:25744; number_of_response:1; }","duration":"247.510002ms","start":"2026-08-15T05:48:54.527094Z","end":"2026-08-15T05:48:54.774604Z","steps":["trace[67470283] 'process raft request' (duration: 247.269125ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:54.774616Z","caller":"traceutil/trace.go:171","msg":"trace[725615610] transaction","detail":"{read_only:false; response_revision:25745; number_of_response:1; }","duration":"201.53391ms","start":"2026-08-15T05:48:54.57307Z","end":"2026-08-15T05:48:54.774604Z","steps":["trace[725615610] 'process raft request' (duration: 201.433698ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:55.168762Z","caller":"traceutil/trace.go:171","msg":"trace[1681733495] linearizableReadLoop","detail":"{readStateIndex:26571; appliedIndex:26570; }","duration":"318.67236ms","start":"2026-08-15T05:48:54.850069Z","end":"2026-08-15T05:48:55.168741Z","steps":["trace[1681733495] 'read index received' (duration: 318.596149ms)","trace[1681733495] 'applied index is now lower than readState.Index' (duration: 72.331µs)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:48:55.168966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.886936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-77e2fd09-ba16-4a91-8ec5-c4f121ecf58b\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-08-15T05:48:55.168997Z","caller":"traceutil/trace.go:171","msg":"trace[1323168125] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-77e2fd09-ba16-4a91-8ec5-c4f121ecf58b; range_end:; response_count:1; response_revision:25746; }","duration":"318.976318ms","start":"2026-08-15T05:48:54.850006Z","end":"2026-08-15T05:48:55.168983Z","steps":["trace[1323168125] 'agreement among raft nodes before linearized reading' (duration: 318.852655ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:55.169022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:48:54.849982Z","time spent":"319.03519ms","remote":"127.0.0.1:58056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3786,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-77e2fd09-ba16-4a91-8ec5-c4f121ecf58b\" "} {"level":"warn","ts":"2026-08-15T05:48:55.169095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.262275ms","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-15T05:48:55.169182Z","caller":"traceutil/trace.go:171","msg":"trace[1811529435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25746; }","duration":"295.405517ms","start":"2026-08-15T05:48:54.873759Z","end":"2026-08-15T05:48:55.169164Z","steps":["trace[1811529435] 'agreement among raft nodes before linearized reading' (duration: 295.131571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:55.169186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.853954ms","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-15T05:48:55.169262Z","caller":"traceutil/trace.go:171","msg":"trace[950669238] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25746; }","duration":"294.962667ms","start":"2026-08-15T05:48:54.874282Z","end":"2026-08-15T05:48:55.169245Z","steps":["trace[950669238] 'agreement among raft nodes before linearized reading' (duration: 294.686791ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:55.169722Z","caller":"traceutil/trace.go:171","msg":"trace[1797661833] transaction","detail":"{read_only:false; response_revision:25746; number_of_response:1; }","duration":"322.580914ms","start":"2026-08-15T05:48:54.846245Z","end":"2026-08-15T05:48:55.168826Z","steps":["trace[1797661833] 'process raft request' (duration: 322.360299ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:55.170043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:48:54.846226Z","time spent":"323.636641ms","remote":"127.0.0.1:58140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T05:48:55.414889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.203897ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-15T05:48:55.415073Z","caller":"traceutil/trace.go:171","msg":"trace[1470328036] transaction","detail":"{read_only:false; response_revision:25748; number_of_response:1; }","duration":"241.752662ms","start":"2026-08-15T05:48:55.173302Z","end":"2026-08-15T05:48:55.415055Z","steps":["trace[1470328036] 'process raft request' (duration: 241.69809ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:55.415202Z","caller":"traceutil/trace.go:171","msg":"trace[1650535050] transaction","detail":"{read_only:false; response_revision:25747; number_of_response:1; }","duration":"242.035239ms","start":"2026-08-15T05:48:55.173058Z","end":"2026-08-15T05:48:55.415093Z","steps":["trace[1650535050] 'process raft request' (duration: 99.534435ms)","trace[1650535050] 'compare' (duration: 141.91561ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:55.436559Z","caller":"traceutil/trace.go:171","msg":"trace[2046605636] transaction","detail":"{read_only:false; response_revision:25749; number_of_response:1; }","duration":"144.649625ms","start":"2026-08-15T05:48:55.291888Z","end":"2026-08-15T05:48:55.436538Z","steps":["trace[2046605636] 'process raft request' (duration: 144.486381ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:55.436762Z","caller":"traceutil/trace.go:171","msg":"trace[1227507236] linearizableReadLoop","detail":"{readStateIndex:26575; appliedIndex:26571; }","duration":"114.330723ms","start":"2026-08-15T05:48:55.322397Z","end":"2026-08-15T05:48:55.436727Z","steps":["trace[1227507236] 'read index received' (duration: 92.28649ms)","trace[1227507236] 'applied index is now lower than readState.Index' (duration: 22.040353ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:55.436919Z","caller":"traceutil/trace.go:171","msg":"trace[68411326] transaction","detail":"{read_only:false; response_revision:25750; number_of_response:1; }","duration":"143.859496ms","start":"2026-08-15T05:48:55.293029Z","end":"2026-08-15T05:48:55.436889Z","steps":["trace[68411326] 'process raft request' (duration: 143.462356ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:55.43697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.542538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:48:55.437016Z","caller":"traceutil/trace.go:171","msg":"trace[1413416475] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:25750; }","duration":"114.65003ms","start":"2026-08-15T05:48:55.32235Z","end":"2026-08-15T05:48:55.437001Z","steps":["trace[1413416475] 'agreement among raft nodes before linearized reading' (duration: 114.534298ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:55.961261Z","caller":"traceutil/trace.go:171","msg":"trace[1929249412] transaction","detail":"{read_only:false; response_revision:25751; number_of_response:1; }","duration":"356.052394ms","start":"2026-08-15T05:48:55.605186Z","end":"2026-08-15T05:48:55.961238Z","steps":["trace[1929249412] 'process raft request' (duration: 355.901821ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:55.961404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:48:55.605158Z","time spent":"356.175687ms","remote":"127.0.0.1:58140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T05:48:56.043272Z","caller":"traceutil/trace.go:171","msg":"trace[1999188895] transaction","detail":"{read_only:false; response_revision:25752; number_of_response:1; }","duration":"421.648649ms","start":"2026-08-15T05:48:55.621559Z","end":"2026-08-15T05:48:56.043207Z","steps":["trace[1999188895] 'process raft request' (duration: 405.432868ms)","trace[1999188895] 'compare' (duration: 15.981805ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:56.043345Z","caller":"traceutil/trace.go:171","msg":"trace[2032797425] linearizableReadLoop","detail":"{readStateIndex:26577; appliedIndex:26575; }","duration":"375.268318ms","start":"2026-08-15T05:48:55.668048Z","end":"2026-08-15T05:48:56.043316Z","steps":["trace[2032797425] 'read index received' (duration: 293.090073ms)","trace[2032797425] 'applied index is now lower than readState.Index' (duration: 82.175945ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:48:56.043534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:48:55.621531Z","time spent":"421.864073ms","remote":"127.0.0.1:58140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T05:48:56.043525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.438273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:48:56.043621Z","caller":"traceutil/trace.go:171","msg":"trace[1374424160] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:25753; }","duration":"375.608648ms","start":"2026-08-15T05:48:55.667991Z","end":"2026-08-15T05:48:56.0436Z","steps":["trace[1374424160] 'agreement among raft nodes before linearized reading' (duration: 375.432493ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:56.043354Z","caller":"traceutil/trace.go:171","msg":"trace[1015386655] transaction","detail":"{read_only:false; response_revision:25753; number_of_response:1; }","duration":"245.00464ms","start":"2026-08-15T05:48:55.798328Z","end":"2026-08-15T05:48:56.043333Z","steps":["trace[1015386655] 'process raft request' (duration: 244.828375ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:56.043681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:48:55.667971Z","time spent":"375.69831ms","remote":"127.0.0.1:58798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-15T05:48:56.043882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.622441ms","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-15T05:48:56.04392Z","caller":"traceutil/trace.go:171","msg":"trace[419022734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25753; }","duration":"256.729224ms","start":"2026-08-15T05:48:55.787182Z","end":"2026-08-15T05:48:56.043911Z","steps":["trace[419022734] 'agreement among raft nodes before linearized reading' (duration: 256.52227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:58.27989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.390146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-15T05:48:58.280056Z","caller":"traceutil/trace.go:171","msg":"trace[1598208007] linearizableReadLoop","detail":"{readStateIndex:26592; appliedIndex:26591; }","duration":"227.772124ms","start":"2026-08-15T05:48:58.052261Z","end":"2026-08-15T05:48:58.280033Z","steps":["trace[1598208007] 'read index received' (duration: 72.186944ms)","trace[1598208007] 'applied index is now lower than readState.Index' (duration: 155.58269ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:48:58.280116Z","caller":"traceutil/trace.go:171","msg":"trace[719311102] transaction","detail":"{read_only:false; response_revision:25767; number_of_response:1; }","duration":"230.449759ms","start":"2026-08-15T05:48:58.049619Z","end":"2026-08-15T05:48:58.280069Z","steps":["trace[719311102] 'process raft request' (duration: 74.766076ms)","trace[719311102] 'compare' (duration: 154.987816ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:48:58.280337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.676808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-48qs5\" ","response":"range_response_count:1 size:4908"} {"level":"warn","ts":"2026-08-15T05:48:58.280338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.840197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-15T05:48:58.28045Z","caller":"traceutil/trace.go:171","msg":"trace[1959172507] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25767; }","duration":"112.933698ms","start":"2026-08-15T05:48:58.16745Z","end":"2026-08-15T05:48:58.280384Z","steps":["trace[1959172507] 'agreement among raft nodes before linearized reading' (duration: 112.761584ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:58.280453Z","caller":"traceutil/trace.go:171","msg":"trace[1998651755] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-48qs5; range_end:; response_count:1; response_revision:25767; }","duration":"226.76785ms","start":"2026-08-15T05:48:58.053611Z","end":"2026-08-15T05:48:58.280379Z","steps":["trace[1998651755] 'agreement among raft nodes before linearized reading' (duration: 226.603016ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:58.280663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.28706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"warn","ts":"2026-08-15T05:48:58.280711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.552024ms","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-15T05:48:58.280752Z","caller":"traceutil/trace.go:171","msg":"trace[1839582996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25767; }","duration":"219.662348ms","start":"2026-08-15T05:48:58.061079Z","end":"2026-08-15T05:48:58.280741Z","steps":["trace[1839582996] 'agreement among raft nodes before linearized reading' (duration: 219.439713ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:48:58.280746Z","caller":"traceutil/trace.go:171","msg":"trace[860666211] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25767; }","duration":"212.408543ms","start":"2026-08-15T05:48:58.068319Z","end":"2026-08-15T05:48:58.280727Z","steps":["trace[860666211] 'agreement among raft nodes before linearized reading' (duration: 212.052614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:58.280682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.657758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:48:58.280862Z","caller":"traceutil/trace.go:171","msg":"trace[764303263] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25767; }","duration":"195.948935ms","start":"2026-08-15T05:48:58.084856Z","end":"2026-08-15T05:48:58.280804Z","steps":["trace[764303263] 'agreement among raft nodes before linearized reading' (duration: 195.579977ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:48:58.280979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.111745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:35 size:293205"} {"level":"info","ts":"2026-08-15T05:48:58.281052Z","caller":"traceutil/trace.go:171","msg":"trace[1865851415] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:35; response_revision:25767; }","duration":"272.224758ms","start":"2026-08-15T05:48:58.008809Z","end":"2026-08-15T05:48:58.281033Z","steps":["trace[1865851415] 'agreement among raft nodes before linearized reading' (duration: 271.358217ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:01.411381Z","caller":"traceutil/trace.go:171","msg":"trace[343257689] transaction","detail":"{read_only:false; response_revision:25813; number_of_response:1; }","duration":"221.975235ms","start":"2026-08-15T05:49:01.18938Z","end":"2026-08-15T05:49:01.411355Z","steps":["trace[343257689] 'process raft request' (duration: 221.73463ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:03.424621Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006823.wal"} {"level":"info","ts":"2026-08-15T05:49:37.783189Z","caller":"traceutil/trace.go:171","msg":"trace[1397765742] transaction","detail":"{read_only:false; response_revision:26102; number_of_response:1; }","duration":"136.18632ms","start":"2026-08-15T05:49:37.646982Z","end":"2026-08-15T05:49:37.783168Z","steps":["trace[1397765742] 'process raft request' (duration: 136.018976ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:37.817269Z","caller":"traceutil/trace.go:171","msg":"trace[1061114931] linearizableReadLoop","detail":"{readStateIndex:26938; appliedIndex:26936; }","duration":"152.649868ms","start":"2026-08-15T05:49:37.664587Z","end":"2026-08-15T05:49:37.817237Z","steps":["trace[1061114931] 'read index received' (duration: 118.468603ms)","trace[1061114931] 'applied index is now lower than readState.Index' (duration: 34.179825ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:49:37.817363Z","caller":"traceutil/trace.go:171","msg":"trace[839133585] transaction","detail":"{read_only:false; response_revision:26103; number_of_response:1; }","duration":"168.83424ms","start":"2026-08-15T05:49:37.648501Z","end":"2026-08-15T05:49:37.817335Z","steps":["trace[839133585] 'process raft request' (duration: 168.575684ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:49:37.817521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.715334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-15T05:49:37.817578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.510577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:49:37.817574Z","caller":"traceutil/trace.go:171","msg":"trace[47504577] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26103; }","duration":"148.805276ms","start":"2026-08-15T05:49:37.668751Z","end":"2026-08-15T05:49:37.817556Z","steps":["trace[47504577] 'agreement among raft nodes before linearized reading' (duration: 148.641402ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:37.817608Z","caller":"traceutil/trace.go:171","msg":"trace[1750799621] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:26103; }","duration":"138.597139ms","start":"2026-08-15T05:49:37.679003Z","end":"2026-08-15T05:49:37.8176Z","steps":["trace[1750799621] 'agreement among raft nodes before linearized reading' (duration: 138.513306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:49:37.817581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.977036ms","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-15T05:49:37.817659Z","caller":"traceutil/trace.go:171","msg":"trace[1872170905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26103; }","duration":"153.10325ms","start":"2026-08-15T05:49:37.664539Z","end":"2026-08-15T05:49:37.817642Z","steps":["trace[1872170905] 'agreement among raft nodes before linearized reading' (duration: 152.852853ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:49:38.112225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.529036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-15T05:49:38.11242Z","caller":"traceutil/trace.go:171","msg":"trace[998018122] transaction","detail":"{read_only:false; response_revision:26105; number_of_response:1; }","duration":"289.510796ms","start":"2026-08-15T05:49:37.822868Z","end":"2026-08-15T05:49:38.112379Z","steps":["trace[998018122] 'process raft request' (duration: 289.451455ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:38.112514Z","caller":"traceutil/trace.go:171","msg":"trace[144863719] transaction","detail":"{read_only:false; response_revision:26104; number_of_response:1; }","duration":"289.719541ms","start":"2026-08-15T05:49:37.822762Z","end":"2026-08-15T05:49:38.112481Z","steps":["trace[144863719] 'process raft request' (duration: 124.862488ms)","trace[144863719] 'compare' (duration: 164.420453ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:49:41.437495Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22618} {"level":"info","ts":"2026-08-15T05:49:41.547325Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22618,"took":"105.685224ms","hash":123072314,"current-db-size-bytes":60243968,"current-db-size":"60 MB","current-db-size-in-use-bytes":46235648,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-08-15T05:49:41.547451Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":123072314,"revision":22618,"compact-revision":20310} {"level":"info","ts":"2026-08-15T05:49:43.703215Z","caller":"traceutil/trace.go:171","msg":"trace[626460569] transaction","detail":"{read_only:false; response_revision:26142; number_of_response:1; }","duration":"151.264345ms","start":"2026-08-15T05:49:43.551932Z","end":"2026-08-15T05:49:43.703196Z","steps":["trace[626460569] 'process raft request' (duration: 151.134182ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:43.873832Z","caller":"traceutil/trace.go:171","msg":"trace[1018066809] linearizableReadLoop","detail":"{readStateIndex:26980; appliedIndex:26978; }","duration":"187.004298ms","start":"2026-08-15T05:49:43.686794Z","end":"2026-08-15T05:49:43.873798Z","steps":["trace[1018066809] 'read index received' (duration: 16.336474ms)","trace[1018066809] 'applied index is now lower than readState.Index' (duration: 170.666414ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:49:43.873895Z","caller":"traceutil/trace.go:171","msg":"trace[2132742994] transaction","detail":"{read_only:false; response_revision:26143; number_of_response:1; }","duration":"238.986245ms","start":"2026-08-15T05:49:43.634869Z","end":"2026-08-15T05:49:43.873855Z","steps":["trace[2132742994] 'process raft request' (duration: 198.081947ms)","trace[2132742994] 'compare' (duration: 40.661553ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:49:43.874024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.206543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-15T05:49:43.874102Z","caller":"traceutil/trace.go:171","msg":"trace[1985300436] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:26143; }","duration":"187.364857ms","start":"2026-08-15T05:49:43.68672Z","end":"2026-08-15T05:49:43.874085Z","steps":["trace[1985300436] 'agreement among raft nodes before linearized reading' (duration: 187.222513ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:43.947774Z","caller":"traceutil/trace.go:171","msg":"trace[1819828700] transaction","detail":"{read_only:false; response_revision:26144; number_of_response:1; }","duration":"144.372159ms","start":"2026-08-15T05:49:43.803378Z","end":"2026-08-15T05:49:43.94775Z","steps":["trace[1819828700] 'process raft request' (duration: 144.229226ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:44.210395Z","caller":"traceutil/trace.go:171","msg":"trace[1345492671] linearizableReadLoop","detail":"{readStateIndex:26983; appliedIndex:26980; }","duration":"309.196562ms","start":"2026-08-15T05:49:43.901176Z","end":"2026-08-15T05:49:44.210373Z","steps":["trace[1345492671] 'read index received' (duration: 46.475633ms)","trace[1345492671] 'applied index is now lower than readState.Index' (duration: 262.719719ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:49:44.210543Z","caller":"traceutil/trace.go:171","msg":"trace[600321448] transaction","detail":"{read_only:false; response_revision:26145; number_of_response:1; }","duration":"373.975298ms","start":"2026-08-15T05:49:43.836552Z","end":"2026-08-15T05:49:44.210528Z","steps":["trace[600321448] 'process raft request' (duration: 373.601978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:49:44.210648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:49:43.836525Z","time spent":"374.04734ms","remote":"127.0.0.1:58140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T05:49:44.210774Z","caller":"traceutil/trace.go:171","msg":"trace[1721009590] transaction","detail":"{read_only:false; response_revision:26146; number_of_response:1; }","duration":"341.01975ms","start":"2026-08-15T05:49:43.869746Z","end":"2026-08-15T05:49:44.210765Z","steps":["trace[1721009590] 'process raft request' (duration: 340.573919ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:49:44.210833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:49:43.869718Z","time spent":"341.085532ms","remote":"127.0.0.1:58140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T05:49:44.210946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.775406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:49:44.210977Z","caller":"traceutil/trace.go:171","msg":"trace[1411820714] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:26146; }","duration":"309.846237ms","start":"2026-08-15T05:49:43.901121Z","end":"2026-08-15T05:49:44.210967Z","steps":["trace[1411820714] 'agreement among raft nodes before linearized reading' (duration: 309.777536ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:49:44.211009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:49:43.9011Z","time spent":"309.900789ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"warn","ts":"2026-08-15T05:49:44.211269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.466468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-08-15T05:49:44.211298Z","caller":"traceutil/trace.go:171","msg":"trace[450674246] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:26146; }","duration":"285.497329ms","start":"2026-08-15T05:49:43.925792Z","end":"2026-08-15T05:49:44.21129Z","steps":["trace[450674246] 'agreement among raft nodes before linearized reading' (duration: 285.386336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:49:44.211367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.422535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:847"} {"level":"info","ts":"2026-08-15T05:49:44.211502Z","caller":"traceutil/trace.go:171","msg":"trace[289201542] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:26146; }","duration":"198.593329ms","start":"2026-08-15T05:49:44.012887Z","end":"2026-08-15T05:49:44.21148Z","steps":["trace[289201542] 'agreement among raft nodes before linearized reading' (duration: 198.331343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:49:44.21155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.248779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-08-15T05:49:44.211587Z","caller":"traceutil/trace.go:171","msg":"trace[293775199] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26146; }","duration":"284.325141ms","start":"2026-08-15T05:49:43.927254Z","end":"2026-08-15T05:49:44.211579Z","steps":["trace[293775199] 'agreement among raft nodes before linearized reading' (duration: 284.224338ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:44.34853Z","caller":"traceutil/trace.go:171","msg":"trace[1938577288] linearizableReadLoop","detail":"{readStateIndex:26984; appliedIndex:26983; }","duration":"132.891211ms","start":"2026-08-15T05:49:44.215614Z","end":"2026-08-15T05:49:44.348506Z","steps":["trace[1938577288] 'read index received' (duration: 63.402042ms)","trace[1938577288] 'applied index is now lower than readState.Index' (duration: 69.487799ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:49:44.348603Z","caller":"traceutil/trace.go:171","msg":"trace[2120200994] transaction","detail":"{read_only:false; response_revision:26147; number_of_response:1; }","duration":"132.986854ms","start":"2026-08-15T05:49:44.215578Z","end":"2026-08-15T05:49:44.348565Z","steps":["trace[2120200994] 'process raft request' (duration: 63.475044ms)","trace[2120200994] 'compare' (duration: 69.125441ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:49:44.348693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.063805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-d30828ac-d0f4-43bf-b9f2-bb8241512bc6\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-08-15T05:49:44.348723Z","caller":"traceutil/trace.go:171","msg":"trace[1500878008] range","detail":"{range_begin:/registry/persistentvolumes/pvc-d30828ac-d0f4-43bf-b9f2-bb8241512bc6; range_end:; response_count:1; response_revision:26147; }","duration":"133.141907ms","start":"2026-08-15T05:49:44.215571Z","end":"2026-08-15T05:49:44.348713Z","steps":["trace[1500878008] 'agreement among raft nodes before linearized reading' (duration: 133.015404ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:44.388459Z","caller":"traceutil/trace.go:171","msg":"trace[439351940] transaction","detail":"{read_only:false; response_revision:26149; number_of_response:1; }","duration":"171.570826ms","start":"2026-08-15T05:49:44.216864Z","end":"2026-08-15T05:49:44.388435Z","steps":["trace[439351940] 'process raft request' (duration: 171.481914ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:44.388878Z","caller":"traceutil/trace.go:171","msg":"trace[259172183] transaction","detail":"{read_only:false; response_revision:26148; number_of_response:1; }","duration":"172.081508ms","start":"2026-08-15T05:49:44.216784Z","end":"2026-08-15T05:49:44.388866Z","steps":["trace[259172183] 'process raft request' (duration: 171.444193ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:49.904678Z","caller":"traceutil/trace.go:171","msg":"trace[11197339] linearizableReadLoop","detail":"{readStateIndex:27028; appliedIndex:27027; }","duration":"138.595919ms","start":"2026-08-15T05:49:49.766067Z","end":"2026-08-15T05:49:49.904663Z","steps":["trace[11197339] 'read index received' (duration: 138.463336ms)","trace[11197339] 'applied index is now lower than readState.Index' (duration: 132.073µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:49:49.904714Z","caller":"traceutil/trace.go:171","msg":"trace[1741745526] transaction","detail":"{read_only:false; response_revision:26189; number_of_response:1; }","duration":"181.613539ms","start":"2026-08-15T05:49:49.72308Z","end":"2026-08-15T05:49:49.904694Z","steps":["trace[1741745526] 'process raft request' (duration: 181.471205ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:49:49.904804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.728262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:49:49.904833Z","caller":"traceutil/trace.go:171","msg":"trace[589293216] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:26189; }","duration":"138.807514ms","start":"2026-08-15T05:49:49.766017Z","end":"2026-08-15T05:49:49.904825Z","steps":["trace[589293216] 'agreement among raft nodes before linearized reading' (duration: 138.741252ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:49:50.143072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.963011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-15T05:49:50.143142Z","caller":"traceutil/trace.go:171","msg":"trace[1996778808] linearizableReadLoop","detail":"{readStateIndex:27029; appliedIndex:27028; }","duration":"175.874369ms","start":"2026-08-15T05:49:49.967256Z","end":"2026-08-15T05:49:50.14313Z","steps":["trace[1996778808] 'read index received' (duration: 28.616491ms)","trace[1996778808] 'applied index is now lower than readState.Index' (duration: 147.257278ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:49:50.143184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.937221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:49:50.143209Z","caller":"traceutil/trace.go:171","msg":"trace[1600941340] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26190; }","duration":"176.000492ms","start":"2026-08-15T05:49:49.967204Z","end":"2026-08-15T05:49:50.143204Z","steps":["trace[1600941340] 'agreement among raft nodes before linearized reading' (duration: 175.952791ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:50.143259Z","caller":"traceutil/trace.go:171","msg":"trace[1769377584] transaction","detail":"{read_only:false; response_revision:26190; number_of_response:1; }","duration":"177.694343ms","start":"2026-08-15T05:49:49.965521Z","end":"2026-08-15T05:49:50.143215Z","steps":["trace[1769377584] 'process raft request' (duration: 30.402875ms)","trace[1769377584] 'compare' (duration: 146.702334ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:49:50.376517Z","caller":"traceutil/trace.go:171","msg":"trace[200852942] transaction","detail":"{read_only:false; response_revision:26191; number_of_response:1; }","duration":"139.565693ms","start":"2026-08-15T05:49:50.236915Z","end":"2026-08-15T05:49:50.376481Z","steps":["trace[200852942] 'process raft request' (duration: 123.829063ms)","trace[200852942] 'compare' (duration: 15.583137ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:49:53.091879Z","caller":"traceutil/trace.go:171","msg":"trace[2047740618] transaction","detail":"{read_only:false; response_revision:26213; number_of_response:1; }","duration":"117.757905ms","start":"2026-08-15T05:49:52.9741Z","end":"2026-08-15T05:49:53.091858Z","steps":["trace[2047740618] 'process raft request' (duration: 117.445438ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:49:53.353823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.297877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-15T05:49:53.353948Z","caller":"traceutil/trace.go:171","msg":"trace[235715601] transaction","detail":"{read_only:false; response_revision:26214; number_of_response:1; }","duration":"258.768762ms","start":"2026-08-15T05:49:53.095157Z","end":"2026-08-15T05:49:53.353926Z","steps":["trace[235715601] 'process raft request' (duration: 126.270901ms)","trace[235715601] 'compare' (duration: 132.03054ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:49:53.356798Z","caller":"traceutil/trace.go:171","msg":"trace[1740808427] transaction","detail":"{read_only:false; response_revision:26215; number_of_response:1; }","duration":"260.603198ms","start":"2026-08-15T05:49:53.096178Z","end":"2026-08-15T05:49:53.356781Z","steps":["trace[1740808427] 'process raft request' (duration: 260.447334ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:53.357011Z","caller":"traceutil/trace.go:171","msg":"trace[1179382825] transaction","detail":"{read_only:false; response_revision:26216; number_of_response:1; }","duration":"260.6889ms","start":"2026-08-15T05:49:53.096295Z","end":"2026-08-15T05:49:53.356983Z","steps":["trace[1179382825] 'process raft request' (duration: 260.446664ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:54.280932Z","caller":"traceutil/trace.go:171","msg":"trace[2023672889] transaction","detail":"{read_only:false; response_revision:26218; number_of_response:1; }","duration":"123.537635ms","start":"2026-08-15T05:49:54.157369Z","end":"2026-08-15T05:49:54.280906Z","steps":["trace[2023672889] 'process raft request' (duration: 123.367161ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:54.551479Z","caller":"traceutil/trace.go:171","msg":"trace[948520834] linearizableReadLoop","detail":"{readStateIndex:27059; appliedIndex:27058; }","duration":"135.922066ms","start":"2026-08-15T05:49:54.415535Z","end":"2026-08-15T05:49:54.551457Z","steps":["trace[948520834] 'read index received' (duration: 123.213088ms)","trace[948520834] 'applied index is now lower than readState.Index' (duration: 12.707898ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:49:54.551789Z","caller":"traceutil/trace.go:171","msg":"trace[1351815596] transaction","detail":"{read_only:false; response_revision:26220; number_of_response:1; }","duration":"163.864849ms","start":"2026-08-15T05:49:54.387908Z","end":"2026-08-15T05:49:54.551772Z","steps":["trace[1351815596] 'process raft request' (duration: 150.900335ms)","trace[1351815596] 'compare' (duration: 11.94144ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:49:54.551979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.440207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-15T05:49:54.55201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.55911ms","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-15T05:49:54.552079Z","caller":"traceutil/trace.go:171","msg":"trace[1075872112] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26220; }","duration":"119.694743ms","start":"2026-08-15T05:49:54.432368Z","end":"2026-08-15T05:49:54.552063Z","steps":["trace[1075872112] 'agreement among raft nodes before linearized reading' (duration: 119.485828ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:54.552085Z","caller":"traceutil/trace.go:171","msg":"trace[347104216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26220; }","duration":"136.610102ms","start":"2026-08-15T05:49:54.415444Z","end":"2026-08-15T05:49:54.552054Z","steps":["trace[347104216] 'agreement among raft nodes before linearized reading' (duration: 136.441918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:49:54.551992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.351421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-08-15T05:49:54.552202Z","caller":"traceutil/trace.go:171","msg":"trace[43957586] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:26220; }","duration":"106.596626ms","start":"2026-08-15T05:49:54.445591Z","end":"2026-08-15T05:49:54.552187Z","steps":["trace[43957586] 'agreement among raft nodes before linearized reading' (duration: 106.243528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:49:54.551992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.734887ms","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-15T05:49:54.552296Z","caller":"traceutil/trace.go:171","msg":"trace[294469230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26220; }","duration":"123.080334ms","start":"2026-08-15T05:49:54.429206Z","end":"2026-08-15T05:49:54.552287Z","steps":["trace[294469230] 'agreement among raft nodes before linearized reading' (duration: 122.589613ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:54.682763Z","caller":"traceutil/trace.go:171","msg":"trace[73039055] transaction","detail":"{read_only:false; response_revision:26221; number_of_response:1; }","duration":"126.108638ms","start":"2026-08-15T05:49:54.556625Z","end":"2026-08-15T05:49:54.682734Z","steps":["trace[73039055] 'process raft request' (duration: 94.606877ms)","trace[73039055] 'compare' (duration: 31.279256ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:49:54.682785Z","caller":"traceutil/trace.go:171","msg":"trace[375219609] transaction","detail":"{read_only:false; response_revision:26222; number_of_response:1; }","duration":"126.137648ms","start":"2026-08-15T05:49:54.556623Z","end":"2026-08-15T05:49:54.682761Z","steps":["trace[375219609] 'process raft request' (duration: 126.039356ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:54.683127Z","caller":"traceutil/trace.go:171","msg":"trace[1578158195] transaction","detail":"{read_only:false; response_revision:26223; number_of_response:1; }","duration":"126.113567ms","start":"2026-08-15T05:49:54.556981Z","end":"2026-08-15T05:49:54.683095Z","steps":["trace[1578158195] 'process raft request' (duration: 125.731528ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:54.683247Z","caller":"traceutil/trace.go:171","msg":"trace[1434609614] transaction","detail":"{read_only:false; response_revision:26224; number_of_response:1; }","duration":"100.14005ms","start":"2026-08-15T05:49:54.583094Z","end":"2026-08-15T05:49:54.683234Z","steps":["trace[1434609614] 'process raft request' (duration: 99.883384ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:54.970695Z","caller":"traceutil/trace.go:171","msg":"trace[1472006650] transaction","detail":"{read_only:false; response_revision:26227; number_of_response:1; }","duration":"227.330995ms","start":"2026-08-15T05:49:54.743324Z","end":"2026-08-15T05:49:54.970655Z","steps":["trace[1472006650] 'process raft request' (duration: 227.12648ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:49:55.364819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.560692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-15T05:49:55.364901Z","caller":"traceutil/trace.go:171","msg":"trace[1472215335] linearizableReadLoop","detail":"{readStateIndex:27068; appliedIndex:27067; }","duration":"197.366539ms","start":"2026-08-15T05:49:55.167522Z","end":"2026-08-15T05:49:55.364889Z","steps":["trace[1472215335] 'read index received' (duration: 33.001µs)","trace[1472215335] 'applied index is now lower than readState.Index' (duration: 197.332488ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:49:55.36501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.341898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:49:55.365039Z","caller":"traceutil/trace.go:171","msg":"trace[748733470] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:26228; }","duration":"197.381579ms","start":"2026-08-15T05:49:55.167646Z","end":"2026-08-15T05:49:55.365028Z","steps":["trace[748733470] 'agreement among raft nodes before linearized reading' (duration: 197.327628ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:55.365001Z","caller":"traceutil/trace.go:171","msg":"trace[1693380008] transaction","detail":"{read_only:false; response_revision:26228; number_of_response:1; }","duration":"267.35797ms","start":"2026-08-15T05:49:55.097602Z","end":"2026-08-15T05:49:55.36496Z","steps":["trace[1693380008] 'process raft request' (duration: 55.582783ms)","trace[1693380008] 'compare' (duration: 211.237815ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:49:55.365105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.354977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-15T05:49:55.365144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.598104ms","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-15T05:49:55.365184Z","caller":"traceutil/trace.go:171","msg":"trace[1454524760] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:26228; }","duration":"175.48629ms","start":"2026-08-15T05:49:55.189679Z","end":"2026-08-15T05:49:55.365166Z","steps":["trace[1454524760] 'agreement among raft nodes before linearized reading' (duration: 175.338517ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:55.365225Z","caller":"traceutil/trace.go:171","msg":"trace[1288235597] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26228; }","duration":"197.690746ms","start":"2026-08-15T05:49:55.167515Z","end":"2026-08-15T05:49:55.365206Z","steps":["trace[1288235597] 'agreement among raft nodes before linearized reading' (duration: 197.43504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:49:55.679241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.609679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-15T05:49:55.679435Z","caller":"traceutil/trace.go:171","msg":"trace[277958259] linearizableReadLoop","detail":"{readStateIndex:27071; appliedIndex:27068; }","duration":"245.608826ms","start":"2026-08-15T05:49:55.433808Z","end":"2026-08-15T05:49:55.679416Z","steps":["trace[277958259] 'read index received' (duration: 4.335845ms)","trace[277958259] 'applied index is now lower than readState.Index' (duration: 241.271341ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:49:55.679512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.711078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:49:55.679543Z","caller":"traceutil/trace.go:171","msg":"trace[1533156768] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26231; }","duration":"245.78798ms","start":"2026-08-15T05:49:55.433746Z","end":"2026-08-15T05:49:55.679534Z","steps":["trace[1533156768] 'agreement among raft nodes before linearized reading' (duration: 245.725918ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:55.679707Z","caller":"traceutil/trace.go:171","msg":"trace[1685612053] transaction","detail":"{read_only:false; response_revision:26230; number_of_response:1; }","duration":"309.895869ms","start":"2026-08-15T05:49:55.369768Z","end":"2026-08-15T05:49:55.679664Z","steps":["trace[1685612053] 'process raft request' (duration: 177.764296ms)","trace[1685612053] 'compare' (duration: 130.983254ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:49:55.679897Z","caller":"traceutil/trace.go:171","msg":"trace[1657686938] transaction","detail":"{read_only:false; response_revision:26231; number_of_response:1; }","duration":"308.71702ms","start":"2026-08-15T05:49:55.371125Z","end":"2026-08-15T05:49:55.679842Z","steps":["trace[1657686938] 'process raft request' (duration: 308.216738ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:49:55.679953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:49:55.369755Z","time spent":"310.055153ms","remote":"127.0.0.1:58140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T05:49:55.679979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T05:49:55.371101Z","time spent":"308.837793ms","remote":"127.0.0.1:58140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T05:49:56.284895Z","caller":"traceutil/trace.go:171","msg":"trace[2058360147] transaction","detail":"{read_only:false; response_revision:26233; number_of_response:1; }","duration":"271.89214ms","start":"2026-08-15T05:49:56.012968Z","end":"2026-08-15T05:49:56.28486Z","steps":["trace[2058360147] 'process raft request' (duration: 271.670755ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:56.847042Z","caller":"traceutil/trace.go:171","msg":"trace[1185520462] linearizableReadLoop","detail":"{readStateIndex:27077; appliedIndex:27076; }","duration":"159.104154ms","start":"2026-08-15T05:49:56.687913Z","end":"2026-08-15T05:49:56.847017Z","steps":["trace[1185520462] 'read index received' (duration: 159.032522ms)","trace[1185520462] 'applied index is now lower than readState.Index' (duration: 70.502µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:49:56.847149Z","caller":"traceutil/trace.go:171","msg":"trace[343531792] transaction","detail":"{read_only:false; response_revision:26237; number_of_response:1; }","duration":"159.292918ms","start":"2026-08-15T05:49:56.687846Z","end":"2026-08-15T05:49:56.847139Z","steps":["trace[343531792] 'process raft request' (duration: 158.997711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:49:56.847452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.521725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-08-15T05:49:56.84757Z","caller":"traceutil/trace.go:171","msg":"trace[149920883] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:26237; }","duration":"159.705069ms","start":"2026-08-15T05:49:56.687845Z","end":"2026-08-15T05:49:56.847551Z","steps":["trace[149920883] 'agreement among raft nodes before linearized reading' (duration: 159.479712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:49:56.852275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.484613ms","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-15T05:49:56.852352Z","caller":"traceutil/trace.go:171","msg":"trace[173750209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26240; }","duration":"118.585125ms","start":"2026-08-15T05:49:56.73375Z","end":"2026-08-15T05:49:56.852335Z","steps":["trace[173750209] 'agreement among raft nodes before linearized reading' (duration: 118.38015ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:49:57.068612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.535076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-15T05:49:57.068767Z","caller":"traceutil/trace.go:171","msg":"trace[1719027140] transaction","detail":"{read_only:false; response_revision:26241; number_of_response:1; }","duration":"204.866572ms","start":"2026-08-15T05:49:56.863872Z","end":"2026-08-15T05:49:57.068738Z","steps":["trace[1719027140] 'process raft request' (duration: 64.11804ms)","trace[1719027140] 'compare' (duration: 140.427013ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:49:57.068971Z","caller":"traceutil/trace.go:171","msg":"trace[869590194] linearizableReadLoop","detail":"{readStateIndex:27081; appliedIndex:27080; }","duration":"175.85806ms","start":"2026-08-15T05:49:56.893102Z","end":"2026-08-15T05:49:57.06896Z","steps":["trace[869590194] 'read index received' (duration: 34.898583ms)","trace[869590194] 'applied index is now lower than readState.Index' (duration: 140.958347ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:49:57.069135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.021674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-15T05:49:57.069171Z","caller":"traceutil/trace.go:171","msg":"trace[1952583263] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:26242; }","duration":"176.073335ms","start":"2026-08-15T05:49:56.893086Z","end":"2026-08-15T05:49:57.069159Z","steps":["trace[1952583263] 'agreement among raft nodes before linearized reading' (duration: 175.947022ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:49:57.069249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.711153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:49:57.069363Z","caller":"traceutil/trace.go:171","msg":"trace[1320664418] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:26242; }","duration":"102.898247ms","start":"2026-08-15T05:49:56.966439Z","end":"2026-08-15T05:49:57.069337Z","steps":["trace[1320664418] 'agreement among raft nodes before linearized reading' (duration: 102.670882ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:57.646306Z","caller":"traceutil/trace.go:171","msg":"trace[1991944463] linearizableReadLoop","detail":"{readStateIndex:27083; appliedIndex:27082; }","duration":"215.882427ms","start":"2026-08-15T05:49:57.430396Z","end":"2026-08-15T05:49:57.646279Z","steps":["trace[1991944463] 'read index received' (duration: 215.703453ms)","trace[1991944463] 'applied index is now lower than readState.Index' (duration: 177.664µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:49:57.646464Z","caller":"traceutil/trace.go:171","msg":"trace[1331932534] transaction","detail":"{read_only:false; response_revision:26243; number_of_response:1; }","duration":"276.536493ms","start":"2026-08-15T05:49:57.369897Z","end":"2026-08-15T05:49:57.646434Z","steps":["trace[1331932534] 'process raft request' (duration: 276.193675ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:49:57.646508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.087542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-15T05:49:57.646621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.225505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-15T05:49:57.646668Z","caller":"traceutil/trace.go:171","msg":"trace[1149695668] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26243; }","duration":"204.317487ms","start":"2026-08-15T05:49:57.442343Z","end":"2026-08-15T05:49:57.64666Z","steps":["trace[1149695668] 'agreement among raft nodes before linearized reading' (duration: 204.199994ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:57.646666Z","caller":"traceutil/trace.go:171","msg":"trace[1464956246] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:26243; }","duration":"216.261546ms","start":"2026-08-15T05:49:57.430379Z","end":"2026-08-15T05:49:57.646641Z","steps":["trace[1464956246] 'agreement among raft nodes before linearized reading' (duration: 216.060561ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:49:57.647027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.565154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-15T05:49:57.647079Z","caller":"traceutil/trace.go:171","msg":"trace[922183016] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:26243; }","duration":"185.667746ms","start":"2026-08-15T05:49:57.461399Z","end":"2026-08-15T05:49:57.647067Z","steps":["trace[922183016] 'agreement among raft nodes before linearized reading' (duration: 185.572074ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:57.869454Z","caller":"traceutil/trace.go:171","msg":"trace[165224751] transaction","detail":"{read_only:false; response_revision:26246; number_of_response:1; }","duration":"121.886185ms","start":"2026-08-15T05:49:57.747548Z","end":"2026-08-15T05:49:57.869435Z","steps":["trace[165224751] 'process raft request' (duration: 121.804773ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:49:57.869698Z","caller":"traceutil/trace.go:171","msg":"trace[1337386739] transaction","detail":"{read_only:false; response_revision:26245; number_of_response:1; }","duration":"123.295829ms","start":"2026-08-15T05:49:57.746368Z","end":"2026-08-15T05:49:57.869664Z","steps":["trace[1337386739] 'process raft request' (duration: 71.480258ms)","trace[1337386739] 'compare' (duration: 51.246518ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:50:01.472289Z","caller":"traceutil/trace.go:171","msg":"trace[532717980] linearizableReadLoop","detail":"{readStateIndex:27153; appliedIndex:27152; }","duration":"108.235545ms","start":"2026-08-15T05:50:01.364021Z","end":"2026-08-15T05:50:01.472257Z","steps":["trace[532717980] 'read index received' (duration: 108.00997ms)","trace[532717980] 'applied index is now lower than readState.Index' (duration: 224.085µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:50:01.472424Z","caller":"traceutil/trace.go:171","msg":"trace[1552572973] transaction","detail":"{read_only:false; response_revision:26312; number_of_response:1; }","duration":"166.482602ms","start":"2026-08-15T05:50:01.305873Z","end":"2026-08-15T05:50:01.472356Z","steps":["trace[1552572973] 'process raft request' (duration: 166.148694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:50:01.473193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.154068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:29 size:864535"} {"level":"info","ts":"2026-08-15T05:50:01.473279Z","caller":"traceutil/trace.go:171","msg":"trace[1554915390] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:29; response_revision:26312; }","duration":"109.297142ms","start":"2026-08-15T05:50:01.363959Z","end":"2026-08-15T05:50:01.473256Z","steps":["trace[1554915390] 'agreement among raft nodes before linearized reading' (duration: 108.456971ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T05:50:01.698752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.511828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:29 size:864535"} {"level":"info","ts":"2026-08-15T05:50:01.698878Z","caller":"traceutil/trace.go:171","msg":"trace[2003779141] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:29; response_revision:26312; }","duration":"160.707854ms","start":"2026-08-15T05:50:01.538142Z","end":"2026-08-15T05:50:01.69885Z","steps":["trace[2003779141] 'range keys from in-memory index tree' (duration: 159.799691ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T05:50:01.876976Z","caller":"traceutil/trace.go:171","msg":"trace[539366875] linearizableReadLoop","detail":"{readStateIndex:27155; appliedIndex:27154; }","duration":"109.446305ms","start":"2026-08-15T05:50:01.767506Z","end":"2026-08-15T05:50:01.876952Z","steps":["trace[539366875] 'read index received' (duration: 89.971604ms)","trace[539366875] 'applied index is now lower than readState.Index' (duration: 19.473471ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T05:50:01.877113Z","caller":"traceutil/trace.go:171","msg":"trace[912672723] transaction","detail":"{read_only:false; response_revision:26314; number_of_response:1; }","duration":"119.103119ms","start":"2026-08-15T05:50:01.757966Z","end":"2026-08-15T05:50:01.877069Z","steps":["trace[912672723] 'process raft request' (duration: 99.571227ms)","trace[912672723] 'compare' (duration: 18.716032ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T05:50:01.877502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.988358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:29 size:864535"} {"level":"info","ts":"2026-08-15T05:50:01.877539Z","caller":"traceutil/trace.go:171","msg":"trace[565766974] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:29; response_revision:26314; }","duration":"110.06472ms","start":"2026-08-15T05:50:01.767465Z","end":"2026-08-15T05:50:01.877529Z","steps":["trace[565766974] 'agreement among raft nodes before linearized reading' (duration: 109.571088ms)"],"step_count":1}