{"level":"warn","ts":"2026-08-21T02:30:19.95249Z","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-21T02:30:19.952592Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-21T02:30:19.952664Z","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-21T02:30:19.952677Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-08-21T02:30:19.952694Z","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-21T02:30:19.953456Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-08-21T02:30:19.953536Z","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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-21T02:30:19.956338Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.53521ms"} {"level":"info","ts":"2026-08-21T02:30:19.962577Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-08-21T02:30:19.96272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-08-21T02:30:19.962756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-08-21T02:30:19.962771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-21T02:30:19.962781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-08-21T02:30:19.962836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-08-21T02:30:19.970226Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-21T02:30:19.972301Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-21T02:30:19.974272Z","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-21T02:30:19.978789Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-21T02:30:19.982997Z","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-21T02:30:19.983237Z","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-21T02:30:19.983273Z","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-21T02:30:19.983356Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-21T02:30:19.984784Z","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-21T02:30:19.984959Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-08-21T02:30:19.984966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-08-21T02:30:19.984984Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-08-21T02:30:19.98538Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-21T02:30:19.98545Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-21T02:30:19.985676Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-08-21T02:30:20.163682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-08-21T02:30:20.163792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-08-21T02:30:20.163819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-08-21T02:30:20.163838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-08-21T02:30:20.163847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-08-21T02:30:20.163864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-08-21T02:30:20.163878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-08-21T02:30:20.165077Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-21T02:30:20.165773Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-08-21T02:30:20.166038Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-21T02:30:20.166159Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-08-21T02:30:20.16612Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-21T02:30:20.166314Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-21T02:30:20.166353Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-21T02:30:20.171465Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-21T02:30:20.171468Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-08-21T02:30:20.171632Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-21T02:30:20.171707Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-21T02:31:04.337085Z","caller":"traceutil/trace.go:171","msg":"trace[895025076] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"137.033099ms","start":"2026-08-21T02:31:04.200015Z","end":"2026-08-21T02:31:04.337048Z","steps":["trace[895025076] 'process raft request' (duration: 100.953871ms)","trace[895025076] 'compare' (duration: 35.81507ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:31:04.337612Z","caller":"traceutil/trace.go:171","msg":"trace[1396601010] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"119.395061ms","start":"2026-08-21T02:31:04.218205Z","end":"2026-08-21T02:31:04.3376Z","steps":["trace[1396601010] 'process raft request' (duration: 119.102293ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:31:04.33776Z","caller":"traceutil/trace.go:171","msg":"trace[378432317] transaction","detail":"{read_only:false; number_of_response:1; response_revision:558; }","duration":"135.914617ms","start":"2026-08-21T02:31:04.201808Z","end":"2026-08-21T02:31:04.337723Z","steps":["trace[378432317] 'process raft request' (duration: 135.186807ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:31:04.338049Z","caller":"traceutil/trace.go:171","msg":"trace[1319364757] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"117.510189ms","start":"2026-08-21T02:31:04.220527Z","end":"2026-08-21T02:31:04.338037Z","steps":["trace[1319364757] 'process raft request' (duration: 116.862271ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:31:04.338028Z","caller":"traceutil/trace.go:171","msg":"trace[110082786] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"103.732527ms","start":"2026-08-21T02:31:04.234141Z","end":"2026-08-21T02:31:04.337874Z","steps":["trace[110082786] 'process raft request' (duration: 103.270575ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:31:04.340254Z","caller":"traceutil/trace.go:171","msg":"trace[2092867878] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"105.444065ms","start":"2026-08-21T02:31:04.234399Z","end":"2026-08-21T02:31:04.339843Z","steps":["trace[2092867878] 'process raft request' (duration: 103.043508ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:31:04.341359Z","caller":"traceutil/trace.go:171","msg":"trace[800140286] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"101.251178ms","start":"2026-08-21T02:31:04.236846Z","end":"2026-08-21T02:31:04.338097Z","steps":["trace[800140286] 'process raft request' (duration: 100.633701ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:31:04.344678Z","caller":"traceutil/trace.go:171","msg":"trace[1991715228] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"104.950942ms","start":"2026-08-21T02:31:04.2397Z","end":"2026-08-21T02:31:04.344651Z","steps":["trace[1991715228] 'process raft request' (duration: 98.348949ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:31:04.345101Z","caller":"traceutil/trace.go:171","msg":"trace[377851143] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"102.408181ms","start":"2026-08-21T02:31:04.242665Z","end":"2026-08-21T02:31:04.345073Z","steps":["trace[377851143] 'process raft request' (duration: 100.088667ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:31:11.868488Z","caller":"traceutil/trace.go:171","msg":"trace[934160660] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"161.184846ms","start":"2026-08-21T02:31:11.707275Z","end":"2026-08-21T02:31:11.86846Z","steps":["trace[934160660] 'process raft request' (duration: 160.214329ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:31:12.107096Z","caller":"traceutil/trace.go:171","msg":"trace[816833258] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"177.368003ms","start":"2026-08-21T02:31:11.929701Z","end":"2026-08-21T02:31:12.107069Z","steps":["trace[816833258] 'process raft request' (duration: 177.313961ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:31:12.107179Z","caller":"traceutil/trace.go:171","msg":"trace[377742624] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"199.896656ms","start":"2026-08-21T02:31:11.907212Z","end":"2026-08-21T02:31:12.107109Z","steps":["trace[377742624] 'process raft request' (duration: 177.503547ms)","trace[377742624] 'compare' (duration: 22.148912ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:31:12.275674Z","caller":"traceutil/trace.go:171","msg":"trace[2122606992] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"145.505102ms","start":"2026-08-21T02:31:12.130111Z","end":"2026-08-21T02:31:12.275616Z","steps":["trace[2122606992] 'process raft request' (duration: 137.789349ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:31:12.752428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.856544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-21T02:31:12.752722Z","caller":"traceutil/trace.go:171","msg":"trace[1576983052] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"472.557253ms","start":"2026-08-21T02:31:12.280112Z","end":"2026-08-21T02:31:12.752669Z","steps":["trace[1576983052] 'process raft request' (duration: 211.667281ms)","trace[1576983052] 'compare' (duration: 259.599047ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:31:12.752823Z","caller":"traceutil/trace.go:171","msg":"trace[902136534] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"472.587635ms","start":"2026-08-21T02:31:12.280205Z","end":"2026-08-21T02:31:12.752793Z","steps":["trace[902136534] 'process raft request' (duration: 472.378249ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:31:12.752899Z","caller":"traceutil/trace.go:171","msg":"trace[705970262] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"471.804003ms","start":"2026-08-21T02:31:12.281056Z","end":"2026-08-21T02:31:12.75286Z","steps":["trace[705970262] 'process raft request' (duration: 471.588937ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:31:12.752914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:31:12.28016Z","time spent":"472.711468ms","remote":"127.0.0.1:58318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-21T02:31:12.752918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:31:12.280033Z","time spent":"472.784329ms","remote":"127.0.0.1:58318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-21T02:31:12.753167Z","caller":"traceutil/trace.go:171","msg":"trace[1277431398] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"470.207678ms","start":"2026-08-21T02:31:12.28289Z","end":"2026-08-21T02:31:12.753098Z","steps":["trace[1277431398] 'process raft request' (duration: 469.857938ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:31:12.753467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:31:12.281033Z","time spent":"471.972598ms","remote":"127.0.0.1:58452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:31:12.75358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:31:12.282872Z","time spent":"470.399404ms","remote":"127.0.0.1:58414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:31:28.473514Z","caller":"traceutil/trace.go:171","msg":"trace[107799433] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"219.483327ms","start":"2026-08-21T02:31:28.254004Z","end":"2026-08-21T02:31:28.473487Z","steps":["trace[107799433] 'process raft request' (duration: 219.308792ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:32:42.999051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.212363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-21T02:32:42.999393Z","caller":"traceutil/trace.go:171","msg":"trace[1958282979] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"177.753116ms","start":"2026-08-21T02:32:42.821595Z","end":"2026-08-21T02:32:42.999348Z","steps":["trace[1958282979] 'process raft request' (duration: 41.151503ms)","trace[1958282979] 'compare' (duration: 135.77903ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:32:43.521293Z","caller":"traceutil/trace.go:171","msg":"trace[1115024666] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"103.364277ms","start":"2026-08-21T02:32:43.417884Z","end":"2026-08-21T02:32:43.521249Z","steps":["trace[1115024666] 'process raft request' (duration: 102.930785ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:32:51.678462Z","caller":"traceutil/trace.go:171","msg":"trace[881468992] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"103.560953ms","start":"2026-08-21T02:32:51.57487Z","end":"2026-08-21T02:32:51.678431Z","steps":["trace[881468992] 'process raft request' (duration: 103.366277ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:28.146919Z","caller":"traceutil/trace.go:171","msg":"trace[1856862198] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"209.860176ms","start":"2026-08-21T02:33:27.937028Z","end":"2026-08-21T02:33:28.146888Z","steps":["trace[1856862198] 'process raft request' (duration: 151.66678ms)","trace[1856862198] 'compare' (duration: 57.999891ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:33:28.147244Z","caller":"traceutil/trace.go:171","msg":"trace[402287419] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"102.031859ms","start":"2026-08-21T02:33:28.045169Z","end":"2026-08-21T02:33:28.147201Z","steps":["trace[402287419] 'process raft request' (duration: 101.677739ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:28.699863Z","caller":"traceutil/trace.go:171","msg":"trace[1022462827] linearizableReadLoop","detail":"{readStateIndex:2266; appliedIndex:2265; }","duration":"266.004506ms","start":"2026-08-21T02:33:28.433798Z","end":"2026-08-21T02:33:28.699802Z","steps":["trace[1022462827] 'read index received' (duration: 265.748439ms)","trace[1022462827] 'applied index is now lower than readState.Index' (duration: 254.587µs)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:33:28.699916Z","caller":"traceutil/trace.go:171","msg":"trace[1066146814] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"291.912096ms","start":"2026-08-21T02:33:28.407952Z","end":"2026-08-21T02:33:28.699864Z","steps":["trace[1066146814] 'process raft request' (duration: 291.530346ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:33:28.700122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.305285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-21T02:33:28.700178Z","caller":"traceutil/trace.go:171","msg":"trace[595745827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2189; }","duration":"266.380947ms","start":"2026-08-21T02:33:28.433783Z","end":"2026-08-21T02:33:28.700164Z","steps":["trace[595745827] 'agreement among raft nodes before linearized reading' (duration: 266.1474ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:33:28.70026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.317907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-21T02:33:28.700328Z","caller":"traceutil/trace.go:171","msg":"trace[1301667053] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2189; }","duration":"195.458401ms","start":"2026-08-21T02:33:28.504852Z","end":"2026-08-21T02:33:28.70031Z","steps":["trace[1301667053] 'agreement among raft nodes before linearized reading' (duration: 195.193283ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:33:29.118788Z","caller":"traceutil/trace.go:171","msg":"trace[465914570] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"414.315925ms","start":"2026-08-21T02:33:28.704442Z","end":"2026-08-21T02:33:29.118758Z","steps":["trace[465914570] 'process raft request' (duration: 381.847754ms)","trace[465914570] 'compare' (duration: 32.130121ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:33:29.118785Z","caller":"traceutil/trace.go:171","msg":"trace[1255714624] linearizableReadLoop","detail":"{readStateIndex:2267; appliedIndex:2266; }","duration":"392.950118ms","start":"2026-08-21T02:33:28.725797Z","end":"2026-08-21T02:33:29.118748Z","steps":["trace[1255714624] 'read index received' (duration: 360.508318ms)","trace[1255714624] 'applied index is now lower than readState.Index' (duration: 32.44022ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:33:29.11916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.337609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-21T02:33:29.118943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:33:28.704414Z","time spent":"414.445247ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:33:29.119357Z","caller":"traceutil/trace.go:171","msg":"trace[1392411088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2190; }","duration":"393.513964ms","start":"2026-08-21T02:33:28.725689Z","end":"2026-08-21T02:33:29.119203Z","steps":["trace[1392411088] 'agreement among raft nodes before linearized reading' (duration: 393.182965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:33:29.119445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:33:28.725656Z","time spent":"393.776531ms","remote":"127.0.0.1:58244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-21T02:33:29.298435Z","caller":"traceutil/trace.go:171","msg":"trace[780204741] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"488.367426ms","start":"2026-08-21T02:33:28.810029Z","end":"2026-08-21T02:33:29.298396Z","steps":["trace[780204741] 'process raft request' (duration: 415.60374ms)","trace[780204741] 'compare' (duration: 72.080947ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:33:29.298613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:33:28.809992Z","time spent":"488.52859ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:34:51.992738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.967441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-21T02:34:51.992864Z","caller":"traceutil/trace.go:171","msg":"trace[1356134428] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2835; }","duration":"122.153006ms","start":"2026-08-21T02:34:51.870688Z","end":"2026-08-21T02:34:51.992841Z","steps":["trace[1356134428] 'count revisions from in-memory index tree' (duration: 121.792177ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:34:57.732555Z","caller":"traceutil/trace.go:171","msg":"trace[237740299] transaction","detail":"{read_only:false; response_revision:2862; number_of_response:1; }","duration":"253.645501ms","start":"2026-08-21T02:34:57.478875Z","end":"2026-08-21T02:34:57.73252Z","steps":["trace[237740299] 'process raft request' (duration: 253.373223ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:34:57.849122Z","caller":"traceutil/trace.go:171","msg":"trace[1620707581] transaction","detail":"{read_only:false; response_revision:2863; number_of_response:1; }","duration":"299.529989ms","start":"2026-08-21T02:34:57.549542Z","end":"2026-08-21T02:34:57.849072Z","steps":["trace[1620707581] 'process raft request' (duration: 259.495862ms)","trace[1620707581] 'compare' (duration: 39.729519ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:34:57.849116Z","caller":"traceutil/trace.go:171","msg":"trace[1591722025] linearizableReadLoop","detail":"{readStateIndex:2968; appliedIndex:2966; }","duration":"216.744611ms","start":"2026-08-21T02:34:57.632339Z","end":"2026-08-21T02:34:57.849084Z","steps":["trace[1591722025] 'read index received' (duration: 100.064425ms)","trace[1591722025] 'applied index is now lower than readState.Index' (duration: 116.678665ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:34:57.849355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.978478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-21T02:34:57.849415Z","caller":"traceutil/trace.go:171","msg":"trace[503977900] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2864; }","duration":"217.10513ms","start":"2026-08-21T02:34:57.632301Z","end":"2026-08-21T02:34:57.849406Z","steps":["trace[503977900] 'agreement among raft nodes before linearized reading' (duration: 216.891114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:34:57.849596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.47655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-21T02:34:57.849672Z","caller":"traceutil/trace.go:171","msg":"trace[80194597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2864; }","duration":"201.610743ms","start":"2026-08-21T02:34:57.648044Z","end":"2026-08-21T02:34:57.849654Z","steps":["trace[80194597] 'agreement among raft nodes before linearized reading' (duration: 201.383347ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:34:57.849141Z","caller":"traceutil/trace.go:171","msg":"trace[999886747] transaction","detail":"{read_only:false; response_revision:2864; number_of_response:1; }","duration":"166.41868ms","start":"2026-08-21T02:34:57.682669Z","end":"2026-08-21T02:34:57.849087Z","steps":["trace[999886747] 'process raft request' (duration: 166.285976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:34:58.38488Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085746853109453587,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-21T02:34:58.508353Z","caller":"traceutil/trace.go:171","msg":"trace[121365535] transaction","detail":"{read_only:false; response_revision:2866; number_of_response:1; }","duration":"654.160741ms","start":"2026-08-21T02:34:57.854167Z","end":"2026-08-21T02:34:58.508327Z","steps":["trace[121365535] 'process raft request' (duration: 653.650867ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:34:58.508504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:34:57.854155Z","time spent":"654.269004ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:34:58.545849Z","caller":"traceutil/trace.go:171","msg":"trace[1947103668] transaction","detail":"{read_only:false; response_revision:2867; number_of_response:1; }","duration":"298.45682ms","start":"2026-08-21T02:34:58.247353Z","end":"2026-08-21T02:34:58.54581Z","steps":["trace[1947103668] 'process raft request' (duration: 298.220223ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:34:58.545926Z","caller":"traceutil/trace.go:171","msg":"trace[875452461] transaction","detail":"{read_only:false; response_revision:2868; number_of_response:1; }","duration":"298.006247ms","start":"2026-08-21T02:34:58.24787Z","end":"2026-08-21T02:34:58.545877Z","steps":["trace[875452461] 'process raft request' (duration: 297.845853ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:34:58.546041Z","caller":"traceutil/trace.go:171","msg":"trace[702911967] linearizableReadLoop","detail":"{readStateIndex:2973; appliedIndex:2970; }","duration":"661.919795ms","start":"2026-08-21T02:34:57.884104Z","end":"2026-08-21T02:34:58.546024Z","steps":["trace[702911967] 'read index received' (duration: 624.135631ms)","trace[702911967] 'applied index is now lower than readState.Index' (duration: 37.525617ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:34:58.546426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.445094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-21T02:34:58.546426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.622305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-21T02:34:58.546483Z","caller":"traceutil/trace.go:171","msg":"trace[1413481272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2868; }","duration":"317.549298ms","start":"2026-08-21T02:34:58.228919Z","end":"2026-08-21T02:34:58.546468Z","steps":["trace[1413481272] 'agreement among raft nodes before linearized reading' (duration: 317.450815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:34:58.546507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.982158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1319"} {"level":"warn","ts":"2026-08-21T02:34:58.546513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:34:58.228895Z","time spent":"317.609729ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-21T02:34:58.546539Z","caller":"traceutil/trace.go:171","msg":"trace[1495008762] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2868; }","duration":"658.061779ms","start":"2026-08-21T02:34:57.888467Z","end":"2026-08-21T02:34:58.546528Z","steps":["trace[1495008762] 'agreement among raft nodes before linearized reading' (duration: 657.72772ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:34:58.54652Z","caller":"traceutil/trace.go:171","msg":"trace[726815609] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2868; }","duration":"671.780079ms","start":"2026-08-21T02:34:57.874718Z","end":"2026-08-21T02:34:58.546498Z","steps":["trace[726815609] 'agreement among raft nodes before linearized reading' (duration: 671.362717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:34:58.546573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:34:57.888442Z","time spent":"658.123041ms","remote":"127.0.0.1:58422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1341,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"warn","ts":"2026-08-21T02:34:58.546666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:34:57.874689Z","time spent":"671.885192ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-21T02:35:04.078761Z","caller":"traceutil/trace.go:171","msg":"trace[1852836236] transaction","detail":"{read_only:false; response_revision:2898; number_of_response:1; }","duration":"162.283296ms","start":"2026-08-21T02:35:03.916445Z","end":"2026-08-21T02:35:04.078728Z","steps":["trace[1852836236] 'process raft request' (duration: 145.897733ms)","trace[1852836236] 'compare' (duration: 16.267529ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:35:30.660623Z","caller":"traceutil/trace.go:171","msg":"trace[478525966] transaction","detail":"{read_only:false; response_revision:3041; number_of_response:1; }","duration":"726.90846ms","start":"2026-08-21T02:35:29.93369Z","end":"2026-08-21T02:35:30.660598Z","steps":["trace[478525966] 'process raft request' (duration: 726.765606ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:35:30.660766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:35:29.933672Z","time spent":"727.018862ms","remote":"127.0.0.1:58318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":764,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:35:30.663812Z","caller":"traceutil/trace.go:171","msg":"trace[730280150] linearizableReadLoop","detail":"{readStateIndex:3153; appliedIndex:3153; }","duration":"396.012405ms","start":"2026-08-21T02:35:30.266706Z","end":"2026-08-21T02:35:30.662719Z","steps":["trace[730280150] 'read index received' (duration: 395.270254ms)","trace[730280150] 'applied index is now lower than readState.Index' (duration: 736.851µs)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:35:30.690105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.362124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-21T02:35:30.690216Z","caller":"traceutil/trace.go:171","msg":"trace[1747533183] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:3041; }","duration":"423.519489ms","start":"2026-08-21T02:35:30.266672Z","end":"2026-08-21T02:35:30.690191Z","steps":["trace[1747533183] 'agreement among raft nodes before linearized reading' (duration: 396.473438ms)","trace[1747533183] 'count revisions from in-memory index tree' (duration: 26.877367ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:35:30.690261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:35:30.266633Z","time spent":"423.616191ms","remote":"127.0.0.1:58388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":2,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-08-21T02:35:30.691428Z","caller":"traceutil/trace.go:171","msg":"trace[1114916340] transaction","detail":"{read_only:false; response_revision:3042; number_of_response:1; }","duration":"424.426054ms","start":"2026-08-21T02:35:30.266963Z","end":"2026-08-21T02:35:30.691389Z","steps":["trace[1114916340] 'process raft request' (duration: 420.518306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:35:30.691617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:35:30.26695Z","time spent":"424.570248ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:35:30.691794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.953798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-21T02:35:30.69184Z","caller":"traceutil/trace.go:171","msg":"trace[1761128708] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3042; }","duration":"425.01092ms","start":"2026-08-21T02:35:30.266817Z","end":"2026-08-21T02:35:30.691828Z","steps":["trace[1761128708] 'agreement among raft nodes before linearized reading' (duration: 424.767334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:35:30.69188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:35:30.266813Z","time spent":"425.056761ms","remote":"127.0.0.1:58414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-21T02:35:30.692302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.94337ms","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-21T02:35:30.692318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.400511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:35:30.692385Z","caller":"traceutil/trace.go:171","msg":"trace[1389022352] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:3043; }","duration":"424.061773ms","start":"2026-08-21T02:35:30.268306Z","end":"2026-08-21T02:35:30.692368Z","steps":["trace[1389022352] 'agreement among raft nodes before linearized reading' (duration: 423.363784ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:35:30.692405Z","caller":"traceutil/trace.go:171","msg":"trace[1714204718] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:3043; }","duration":"425.513394ms","start":"2026-08-21T02:35:30.266871Z","end":"2026-08-21T02:35:30.692385Z","steps":["trace[1714204718] 'agreement among raft nodes before linearized reading' (duration: 424.840515ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:35:30.692423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:35:30.268253Z","time spent":"424.163407ms","remote":"127.0.0.1:58588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-08-21T02:35:30.69244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:35:30.266855Z","time spent":"425.577346ms","remote":"127.0.0.1:58456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-08-21T02:39:42.074859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.557566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:39:42.074983Z","caller":"traceutil/trace.go:171","msg":"trace[2006540937] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:5296; }","duration":"173.70302ms","start":"2026-08-21T02:39:41.901258Z","end":"2026-08-21T02:39:42.074961Z","steps":["trace[2006540937] 'range keys from in-memory index tree' (duration: 173.458354ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:39:42.236149Z","caller":"traceutil/trace.go:171","msg":"trace[503816282] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5297; }","duration":"135.953437ms","start":"2026-08-21T02:39:42.10016Z","end":"2026-08-21T02:39:42.236114Z","steps":["trace[503816282] 'process raft request' (duration: 108.458387ms)","trace[503816282] 'compare' (duration: 27.413148ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:39:44.106549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.129913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-21T02:39:44.106816Z","caller":"traceutil/trace.go:171","msg":"trace[212868478] transaction","detail":"{read_only:false; response_revision:5327; number_of_response:1; }","duration":"268.443339ms","start":"2026-08-21T02:39:43.838303Z","end":"2026-08-21T02:39:44.106746Z","steps":["trace[212868478] 'process raft request' (duration: 119.987326ms)","trace[212868478] 'compare' (duration: 147.961809ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:39:44.106816Z","caller":"traceutil/trace.go:171","msg":"trace[929393720] linearizableReadLoop","detail":"{readStateIndex:5522; appliedIndex:5521; }","duration":"227.871867ms","start":"2026-08-21T02:39:43.878915Z","end":"2026-08-21T02:39:44.106787Z","steps":["trace[929393720] 'read index received' (duration: 79.350882ms)","trace[929393720] 'applied index is now lower than readState.Index' (duration: 148.518155ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:39:44.106834Z","caller":"traceutil/trace.go:171","msg":"trace[133304844] transaction","detail":"{read_only:false; response_revision:5328; number_of_response:1; }","duration":"116.555671ms","start":"2026-08-21T02:39:43.990249Z","end":"2026-08-21T02:39:44.106805Z","steps":["trace[133304844] 'process raft request' (duration: 116.456909ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:39:44.107121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.189986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-21T02:39:44.107177Z","caller":"traceutil/trace.go:171","msg":"trace[1248143263] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:5328; }","duration":"228.267458ms","start":"2026-08-21T02:39:43.878897Z","end":"2026-08-21T02:39:44.107164Z","steps":["trace[1248143263] 'agreement among raft nodes before linearized reading' (duration: 227.98586ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:39:44.123012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.894388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/storage-loki-0.18cdb1ac54effb2d\" ","response":"range_response_count:1 size:943"} {"level":"warn","ts":"2026-08-21T02:39:44.123034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.378023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1e5596f1-1965-4964-8a41-aa6b24935c33\" ","response":"range_response_count:1 size:3894"} {"level":"info","ts":"2026-08-21T02:39:44.12309Z","caller":"traceutil/trace.go:171","msg":"trace[298750882] range","detail":"{range_begin:/registry/events/monitoring/storage-loki-0.18cdb1ac54effb2d; range_end:; response_count:1; response_revision:5329; }","duration":"190.009941ms","start":"2026-08-21T02:39:43.933055Z","end":"2026-08-21T02:39:44.123064Z","steps":["trace[298750882] 'agreement among raft nodes before linearized reading' (duration: 189.804096ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:39:44.123129Z","caller":"traceutil/trace.go:171","msg":"trace[2093409284] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1e5596f1-1965-4964-8a41-aa6b24935c33; range_end:; response_count:1; response_revision:5329; }","duration":"236.540377ms","start":"2026-08-21T02:39:43.886567Z","end":"2026-08-21T02:39:44.123107Z","steps":["trace[2093409284] 'agreement among raft nodes before linearized reading' (duration: 236.221888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:39:44.123674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.428258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786944"} {"level":"info","ts":"2026-08-21T02:39:44.123784Z","caller":"traceutil/trace.go:171","msg":"trace[1565532789] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5329; }","duration":"188.648284ms","start":"2026-08-21T02:39:43.935112Z","end":"2026-08-21T02:39:44.12376Z","steps":["trace[1565532789] 'agreement among raft nodes before linearized reading' (duration: 187.714528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:39:57.564284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.194746ms","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-21T02:39:57.564388Z","caller":"traceutil/trace.go:171","msg":"trace[867825440] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5585; }","duration":"207.34258ms","start":"2026-08-21T02:39:57.35702Z","end":"2026-08-21T02:39:57.564362Z","steps":["trace[867825440] 'range keys from in-memory index tree' (duration: 206.997641ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:39:58.133301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.446811ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-21T02:39:58.133514Z","caller":"traceutil/trace.go:171","msg":"trace[1824677839] transaction","detail":"{read_only:false; response_revision:5590; number_of_response:1; }","duration":"153.615145ms","start":"2026-08-21T02:39:57.97987Z","end":"2026-08-21T02:39:58.133486Z","steps":["trace[1824677839] 'process raft request' (duration: 33.902737ms)","trace[1824677839] 'compare' (duration: 119.297177ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:39:58.133578Z","caller":"traceutil/trace.go:171","msg":"trace[1045451168] transaction","detail":"{read_only:false; response_revision:5593; number_of_response:1; }","duration":"152.812653ms","start":"2026-08-21T02:39:57.980755Z","end":"2026-08-21T02:39:58.133568Z","steps":["trace[1045451168] 'process raft request' (duration: 152.774802ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:39:58.13383Z","caller":"traceutil/trace.go:171","msg":"trace[408834577] transaction","detail":"{read_only:false; response_revision:5592; number_of_response:1; }","duration":"153.44271ms","start":"2026-08-21T02:39:57.980363Z","end":"2026-08-21T02:39:58.133806Z","steps":["trace[408834577] 'process raft request' (duration: 153.129422ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:39:58.13392Z","caller":"traceutil/trace.go:171","msg":"trace[99438259] transaction","detail":"{read_only:false; response_revision:5591; number_of_response:1; }","duration":"153.899113ms","start":"2026-08-21T02:39:57.97987Z","end":"2026-08-21T02:39:58.13377Z","steps":["trace[99438259] 'process raft request' (duration: 153.538083ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:40:20.568378Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2987} {"level":"info","ts":"2026-08-21T02:40:20.645123Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2987,"took":"75.041334ms","hash":2320931474,"current-db-size-bytes":55951360,"current-db-size":"56 MB","current-db-size-in-use-bytes":32370688,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-21T02:40:20.645583Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2320931474,"revision":2987,"compact-revision":-1} {"level":"info","ts":"2026-08-21T02:45:20.574059Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5904} {"level":"info","ts":"2026-08-21T02:45:20.664712Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5904,"took":"86.564254ms","hash":3877386779,"current-db-size-bytes":55951360,"current-db-size":"56 MB","current-db-size-in-use-bytes":26206208,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-21T02:45:20.664798Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3877386779,"revision":5904,"compact-revision":2987} {"level":"info","ts":"2026-08-21T02:46:27.718747Z","caller":"traceutil/trace.go:171","msg":"trace[1175730150] transaction","detail":"{read_only:false; response_revision:8631; number_of_response:1; }","duration":"172.851869ms","start":"2026-08-21T02:46:27.545859Z","end":"2026-08-21T02:46:27.718711Z","steps":["trace[1175730150] 'process raft request' (duration: 139.863504ms)","trace[1175730150] 'compare' (duration: 32.8161ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:46:30.57382Z","caller":"traceutil/trace.go:171","msg":"trace[533608259] linearizableReadLoop","detail":"{readStateIndex:8943; appliedIndex:8942; }","duration":"421.693921ms","start":"2026-08-21T02:46:30.152084Z","end":"2026-08-21T02:46:30.573778Z","steps":["trace[533608259] 'read index received' (duration: 421.420153ms)","trace[533608259] 'applied index is now lower than readState.Index' (duration: 272.938µs)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:46:30.574202Z","caller":"traceutil/trace.go:171","msg":"trace[1206793655] transaction","detail":"{read_only:false; response_revision:8643; number_of_response:1; }","duration":"675.279523ms","start":"2026-08-21T02:46:29.898914Z","end":"2026-08-21T02:46:30.574194Z","steps":["trace[1206793655] 'process raft request' (duration: 674.677376ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:30.57433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:29.898885Z","time spent":"675.343745ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:46:30.574625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.781595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6g77c\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-21T02:46:30.574715Z","caller":"traceutil/trace.go:171","msg":"trace[1538803320] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6g77c; range_end:; response_count:1; response_revision:8643; }","duration":"374.930209ms","start":"2026-08-21T02:46:30.199767Z","end":"2026-08-21T02:46:30.574697Z","steps":["trace[1538803320] 'agreement among raft nodes before linearized reading' (duration: 374.657891ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:30.57475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:30.199735Z","time spent":"375.007001ms","remote":"127.0.0.1:58414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-6g77c\" "} {"level":"warn","ts":"2026-08-21T02:46:30.574784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.502815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-21T02:46:30.57482Z","caller":"traceutil/trace.go:171","msg":"trace[2037898231] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:8643; }","duration":"367.565397ms","start":"2026-08-21T02:46:30.207248Z","end":"2026-08-21T02:46:30.574813Z","steps":["trace[2037898231] 'agreement among raft nodes before linearized reading' (duration: 367.452324ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:30.574846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:30.207234Z","time spent":"367.606678ms","remote":"127.0.0.1:58422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":54,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-08-21T02:46:30.575191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.100099ms","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-21T02:46:30.576659Z","caller":"traceutil/trace.go:171","msg":"trace[913242823] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8643; }","duration":"423.886161ms","start":"2026-08-21T02:46:30.15203Z","end":"2026-08-21T02:46:30.575916Z","steps":["trace[913242823] 'agreement among raft nodes before linearized reading' (duration: 423.074109ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:30.576856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:30.152004Z","time spent":"424.803916ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-21T02:46:31.787322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.954779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-21T02:46:31.787403Z","caller":"traceutil/trace.go:171","msg":"trace[508491325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8644; }","duration":"979.094823ms","start":"2026-08-21T02:46:30.808289Z","end":"2026-08-21T02:46:31.787384Z","steps":["trace[508491325] 'range keys from in-memory index tree' (duration: 978.792034ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:31.787415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.463837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-21T02:46:31.787442Z","caller":"traceutil/trace.go:171","msg":"trace[1665006073] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8644; }","duration":"977.507509ms","start":"2026-08-21T02:46:30.809927Z","end":"2026-08-21T02:46:31.787435Z","steps":["trace[1665006073] 'range keys from in-memory index tree' (duration: 977.395535ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:31.787442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:30.808265Z","time spent":"979.167234ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-21T02:46:31.787462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:30.80992Z","time spent":"977.53755ms","remote":"127.0.0.1:58414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-21T02:46:31.787696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.07457005s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-21T02:46:31.78772Z","caller":"traceutil/trace.go:171","msg":"trace[48488275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8644; }","duration":"1.074696084s","start":"2026-08-21T02:46:30.713016Z","end":"2026-08-21T02:46:31.787713Z","steps":["trace[48488275] 'range keys from in-memory index tree' (duration: 1.074421505s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:31.78774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:30.712991Z","time spent":"1.074744035s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-21T02:46:31.787319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.599369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-21T02:46:31.787929Z","caller":"traceutil/trace.go:171","msg":"trace[531899412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8644; }","duration":"979.253616ms","start":"2026-08-21T02:46:30.808667Z","end":"2026-08-21T02:46:31.78792Z","steps":["trace[531899412] 'range keys from in-memory index tree' (duration: 978.499596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:31.787957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:30.80866Z","time spent":"979.288858ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-21T02:46:31.787989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.04522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-21T02:46:31.787972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014140633s","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-21T02:46:31.788059Z","caller":"traceutil/trace.go:171","msg":"trace[2096621491] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8644; }","duration":"1.014291047s","start":"2026-08-21T02:46:30.773744Z","end":"2026-08-21T02:46:31.788035Z","steps":["trace[2096621491] 'range keys from in-memory index tree' (duration: 1.013963399s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:31.788102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:30.773718Z","time spent":"1.01437152s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-21T02:46:31.788015Z","caller":"traceutil/trace.go:171","msg":"trace[507942161] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8644; }","duration":"933.117123ms","start":"2026-08-21T02:46:30.854891Z","end":"2026-08-21T02:46:31.788008Z","steps":["trace[507942161] 'range keys from in-memory index tree' (duration: 932.913157ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:31.788218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:30.85486Z","time spent":"933.348478ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-21T02:46:31.788194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061902193s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:46:31.788286Z","caller":"traceutil/trace.go:171","msg":"trace[867789231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8644; }","duration":"1.062059167s","start":"2026-08-21T02:46:30.726204Z","end":"2026-08-21T02:46:31.788263Z","steps":["trace[867789231] 'range keys from in-memory index tree' (duration: 1.06178049s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:31.788323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:30.726174Z","time spent":"1.062141359s","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-21T02:46:35.748776Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085746853109468493,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T02:46:36.354755Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085746853109468493,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-21T02:46:36.577411Z","caller":"traceutil/trace.go:171","msg":"trace[1786094937] transaction","detail":"{read_only:false; response_revision:8654; number_of_response:1; }","duration":"1.329653663s","start":"2026-08-21T02:46:35.247729Z","end":"2026-08-21T02:46:36.577383Z","steps":["trace[1786094937] 'process raft request' (duration: 1.329466508s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:36.577796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:35.247719Z","time spent":"1.329791227s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:46:36.593788Z","caller":"traceutil/trace.go:171","msg":"trace[1788883300] transaction","detail":"{read_only:false; response_revision:8656; number_of_response:1; }","duration":"1.295022464s","start":"2026-08-21T02:46:35.298722Z","end":"2026-08-21T02:46:36.593744Z","steps":["trace[1788883300] 'process raft request' (duration: 1.294781867s)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:46:36.593801Z","caller":"traceutil/trace.go:171","msg":"trace[2074633132] linearizableReadLoop","detail":"{readStateIndex:8959; appliedIndex:8953; }","duration":"1.345797496s","start":"2026-08-21T02:46:35.24798Z","end":"2026-08-21T02:46:36.593777Z","steps":["trace[2074633132] 'read index received' (duration: 1.329202021s)","trace[2074633132] 'applied index is now lower than readState.Index' (duration: 16.594525ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:46:36.593927Z","caller":"traceutil/trace.go:171","msg":"trace[524653269] transaction","detail":"{read_only:false; response_revision:8657; number_of_response:1; }","duration":"1.288727871s","start":"2026-08-21T02:46:35.305189Z","end":"2026-08-21T02:46:36.593917Z","steps":["trace[524653269] 'process raft request' (duration: 1.288355921s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:36.593964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:35.298712Z","time spent":"1.295162048s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:46:36.594053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:35.30518Z","time spent":"1.288780433s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T02:46:36.594308Z","caller":"traceutil/trace.go:171","msg":"trace[1684460579] transaction","detail":"{read_only:false; response_revision:8655; number_of_response:1; }","duration":"1.346507265s","start":"2026-08-21T02:46:35.24779Z","end":"2026-08-21T02:46:36.594297Z","steps":["trace[1684460579] 'process raft request' (duration: 1.345528638s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:36.594386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:35.247785Z","time spent":"1.346548056s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:46:36.594356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346540656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:46:36.594451Z","caller":"traceutil/trace.go:171","msg":"trace[1349503523] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:8660; }","duration":"1.34668368s","start":"2026-08-21T02:46:35.247745Z","end":"2026-08-21T02:46:36.594429Z","steps":["trace[1349503523] 'agreement among raft nodes before linearized reading' (duration: 1.346468884s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:36.594493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:35.247742Z","time spent":"1.346743801s","remote":"127.0.0.1:35574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"warn","ts":"2026-08-21T02:46:36.5945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346772913s","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-21T02:46:36.594534Z","caller":"traceutil/trace.go:171","msg":"trace[999912928] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8660; }","duration":"1.346816113s","start":"2026-08-21T02:46:35.24771Z","end":"2026-08-21T02:46:36.594526Z","steps":["trace[999912928] 'agreement among raft nodes before linearized reading' (duration: 1.346660309s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:36.594558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:35.247705Z","time spent":"1.346847294s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-21T02:46:36.594675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346506795s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-21T02:46:36.594693Z","caller":"traceutil/trace.go:171","msg":"trace[1262876144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8660; }","duration":"1.346532626s","start":"2026-08-21T02:46:35.248154Z","end":"2026-08-21T02:46:36.594687Z","steps":["trace[1262876144] 'agreement among raft nodes before linearized reading' (duration: 1.346470654s)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:46:36.594735Z","caller":"traceutil/trace.go:171","msg":"trace[1630161217] transaction","detail":"{read_only:false; response_revision:8658; number_of_response:1; }","duration":"1.289465301s","start":"2026-08-21T02:46:35.30526Z","end":"2026-08-21T02:46:36.594725Z","steps":["trace[1630161217] 'process raft request' (duration: 1.28834877s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:36.594815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:35.305256Z","time spent":"1.289500123s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:46:36.594888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296086093s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-21T02:46:36.594927Z","caller":"traceutil/trace.go:171","msg":"trace[109401628] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8660; }","duration":"1.296182215s","start":"2026-08-21T02:46:35.298736Z","end":"2026-08-21T02:46:36.594918Z","steps":["trace[109401628] 'agreement among raft nodes before linearized reading' (duration: 1.296049452s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:36.594951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:35.298732Z","time spent":"1.296213026s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-21T02:46:36.594961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296253057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-21T02:46:36.59499Z","caller":"traceutil/trace.go:171","msg":"trace[1657706721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8660; }","duration":"1.296292789s","start":"2026-08-21T02:46:35.298691Z","end":"2026-08-21T02:46:36.594983Z","steps":["trace[1657706721] 'agreement among raft nodes before linearized reading' (duration: 1.296222907s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:36.595012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:35.298687Z","time spent":"1.29631647s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-21T02:46:36.595079Z","caller":"traceutil/trace.go:171","msg":"trace[1829789813] transaction","detail":"{read_only:false; response_revision:8659; number_of_response:1; }","duration":"1.13005873s","start":"2026-08-21T02:46:35.465013Z","end":"2026-08-21T02:46:36.595072Z","steps":["trace[1829789813] 'process raft request' (duration: 1.128624151s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:36.595128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:35.46499Z","time spent":"1.130118632s","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19394,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:46:36.595143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296454313s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-21T02:46:36.595166Z","caller":"traceutil/trace.go:171","msg":"trace[924520997] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8660; }","duration":"1.296486194s","start":"2026-08-21T02:46:35.298674Z","end":"2026-08-21T02:46:36.59516Z","steps":["trace[924520997] 'agreement among raft nodes before linearized reading' (duration: 1.296418342s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:36.595188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:35.29867Z","time spent":"1.296513774s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-21T02:46:36.595204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.783789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-21T02:46:36.595215Z","caller":"traceutil/trace.go:171","msg":"trace[1837331074] transaction","detail":"{read_only:false; response_revision:8660; number_of_response:1; }","duration":"224.416773ms","start":"2026-08-21T02:46:36.37079Z","end":"2026-08-21T02:46:36.595207Z","steps":["trace[1837331074] 'process raft request' (duration: 222.916372ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:46:36.595235Z","caller":"traceutil/trace.go:171","msg":"trace[379026672] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8660; }","duration":"183.845251ms","start":"2026-08-21T02:46:36.41138Z","end":"2026-08-21T02:46:36.595226Z","steps":["trace[379026672] 'agreement among raft nodes before linearized reading' (duration: 183.763718ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:36.595371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.098348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-21T02:46:36.595388Z","caller":"traceutil/trace.go:171","msg":"trace[1113312027] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:8660; }","duration":"238.15275ms","start":"2026-08-21T02:46:36.35723Z","end":"2026-08-21T02:46:36.595383Z","steps":["trace[1113312027] 'agreement among raft nodes before linearized reading' (duration: 238.065658ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:36.595428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296744721s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-08-21T02:46:36.595452Z","caller":"traceutil/trace.go:171","msg":"trace[274942702] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8660; }","duration":"1.296787402s","start":"2026-08-21T02:46:35.298658Z","end":"2026-08-21T02:46:36.595446Z","steps":["trace[274942702] 'agreement among raft nodes before linearized reading' (duration: 1.296669198s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:36.595482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:35.298654Z","time spent":"1.296822484s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":551,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-08-21T02:46:36.595514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.493057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-21T02:46:36.595535Z","caller":"traceutil/trace.go:171","msg":"trace[1784142768] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:8660; }","duration":"742.513548ms","start":"2026-08-21T02:46:35.853015Z","end":"2026-08-21T02:46:36.595528Z","steps":["trace[1784142768] 'agreement among raft nodes before linearized reading' (duration: 742.470627ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:36.59555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:35.852969Z","time spent":"742.577439ms","remote":"127.0.0.1:39806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":29,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"warn","ts":"2026-08-21T02:46:36.595864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.347626566s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-21T02:46:36.595888Z","caller":"traceutil/trace.go:171","msg":"trace[1420539271] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:8660; }","duration":"1.347678658s","start":"2026-08-21T02:46:35.248204Z","end":"2026-08-21T02:46:36.595882Z","steps":["trace[1420539271] 'agreement among raft nodes before linearized reading' (duration: 1.347573175s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:36.595903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:35.248186Z","time spent":"1.347714438s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.162\" "} {"level":"warn","ts":"2026-08-21T02:46:36.595998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.297894943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:46:36.596018Z","caller":"traceutil/trace.go:171","msg":"trace[1677294878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8660; }","duration":"1.297943784s","start":"2026-08-21T02:46:35.298071Z","end":"2026-08-21T02:46:36.596014Z","steps":["trace[1677294878] 'agreement among raft nodes before linearized reading' (duration: 1.297910973s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:36.596031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:35.298058Z","time spent":"1.297969575s","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-21T02:46:36.596128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.347836111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19408"} {"level":"info","ts":"2026-08-21T02:46:36.596146Z","caller":"traceutil/trace.go:171","msg":"trace[1748096688] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8660; }","duration":"1.347862323s","start":"2026-08-21T02:46:35.24828Z","end":"2026-08-21T02:46:36.596142Z","steps":["trace[1748096688] 'agreement among raft nodes before linearized reading' (duration: 1.347824312s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:36.596159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:35.248274Z","time spent":"1.347881713s","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19430,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-21T02:46:36.594709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:35.248151Z","time spent":"1.346554376s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-21T02:46:36.596699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.291682732s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223839"} {"level":"info","ts":"2026-08-21T02:46:36.596718Z","caller":"traceutil/trace.go:171","msg":"trace[1500854722] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8660; }","duration":"1.291718143s","start":"2026-08-21T02:46:35.304995Z","end":"2026-08-21T02:46:36.596713Z","steps":["trace[1500854722] 'agreement among raft nodes before linearized reading' (duration: 1.291468237s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:36.596732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:35.304981Z","time spent":"1.291747864s","remote":"127.0.0.1:58414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":25,"response size":223861,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-08-21T02:46:36.600072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301922043s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-21T02:46:36.600184Z","caller":"traceutil/trace.go:171","msg":"trace[1921775483] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:8660; }","duration":"1.302084208s","start":"2026-08-21T02:46:35.298071Z","end":"2026-08-21T02:46:36.600155Z","steps":["trace[1921775483] 'agreement among raft nodes before linearized reading' (duration: 1.297515323s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:46:36.60024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:46:35.29805Z","time spent":"1.3021781s","remote":"127.0.0.1:58402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2104,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-08-21T02:47:14.96187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.890646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-21T02:47:14.961971Z","caller":"traceutil/trace.go:171","msg":"trace[1044635644] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8915; }","duration":"105.04275ms","start":"2026-08-21T02:47:14.856908Z","end":"2026-08-21T02:47:14.961951Z","steps":["trace[1044635644] 'range keys from in-memory index tree' (duration: 104.470714ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:47:21.787675Z","caller":"traceutil/trace.go:171","msg":"trace[1959713965] transaction","detail":"{read_only:false; response_revision:8953; number_of_response:1; }","duration":"134.744475ms","start":"2026-08-21T02:47:21.652871Z","end":"2026-08-21T02:47:21.787616Z","steps":["trace[1959713965] 'process raft request' (duration: 134.506428ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:47:21.903503Z","caller":"traceutil/trace.go:171","msg":"trace[1046868196] linearizableReadLoop","detail":"{readStateIndex:9267; appliedIndex:9266; }","duration":"213.559145ms","start":"2026-08-21T02:47:21.689911Z","end":"2026-08-21T02:47:21.90347Z","steps":["trace[1046868196] 'read index received' (duration: 98.730177ms)","trace[1046868196] 'applied index is now lower than readState.Index' (duration: 114.827978ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:47:21.903602Z","caller":"traceutil/trace.go:171","msg":"trace[1704899450] transaction","detail":"{read_only:false; response_revision:8954; number_of_response:1; }","duration":"250.417876ms","start":"2026-08-21T02:47:21.653141Z","end":"2026-08-21T02:47:21.903559Z","steps":["trace[1704899450] 'process raft request' (duration: 250.078486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:47:21.903781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.829072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:47:21.903831Z","caller":"traceutil/trace.go:171","msg":"trace[710428507] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:8954; }","duration":"213.957837ms","start":"2026-08-21T02:47:21.689856Z","end":"2026-08-21T02:47:21.903814Z","steps":["trace[710428507] 'agreement among raft nodes before linearized reading' (duration: 213.833103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:47:21.903946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.037244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:47:21.904147Z","caller":"traceutil/trace.go:171","msg":"trace[1070175733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8954; }","duration":"177.29121ms","start":"2026-08-21T02:47:21.726808Z","end":"2026-08-21T02:47:21.904099Z","steps":["trace[1070175733] 'agreement among raft nodes before linearized reading' (duration: 177.047854ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:47:27.549638Z","caller":"traceutil/trace.go:171","msg":"trace[1902239804] linearizableReadLoop","detail":"{readStateIndex:9335; appliedIndex:9334; }","duration":"123.28091ms","start":"2026-08-21T02:47:27.426339Z","end":"2026-08-21T02:47:27.54962Z","steps":["trace[1902239804] 'read index received' (duration: 41.186989ms)","trace[1902239804] 'applied index is now lower than readState.Index' (duration: 82.092661ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:47:27.549885Z","caller":"traceutil/trace.go:171","msg":"trace[318783442] transaction","detail":"{read_only:false; response_revision:9019; number_of_response:1; }","duration":"127.836075ms","start":"2026-08-21T02:47:27.422031Z","end":"2026-08-21T02:47:27.549867Z","steps":["trace[318783442] 'process raft request' (duration: 45.548758ms)","trace[318783442] 'compare' (duration: 81.853265ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T02:47:27.550305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.951938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-08-21T02:47:27.550345Z","caller":"traceutil/trace.go:171","msg":"trace[740323459] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:9019; }","duration":"124.01607ms","start":"2026-08-21T02:47:27.426319Z","end":"2026-08-21T02:47:27.550335Z","steps":["trace[740323459] 'agreement among raft nodes before linearized reading' (duration: 123.907137ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:47:27.710627Z","caller":"traceutil/trace.go:171","msg":"trace[450843939] linearizableReadLoop","detail":"{readStateIndex:9336; appliedIndex:9335; }","duration":"140.007148ms","start":"2026-08-21T02:47:27.570591Z","end":"2026-08-21T02:47:27.710598Z","steps":["trace[450843939] 'read index received' (duration: 139.802132ms)","trace[450843939] 'applied index is now lower than readState.Index' (duration: 204.216µs)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:47:27.710697Z","caller":"traceutil/trace.go:171","msg":"trace[1515507715] transaction","detail":"{read_only:false; response_revision:9020; number_of_response:1; }","duration":"140.533642ms","start":"2026-08-21T02:47:27.570107Z","end":"2026-08-21T02:47:27.710641Z","steps":["trace[1515507715] 'process raft request' (duration: 140.331027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:47:27.710895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.274055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-08-21T02:47:27.710932Z","caller":"traceutil/trace.go:171","msg":"trace[1421354837] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:9020; }","duration":"140.364548ms","start":"2026-08-21T02:47:27.570558Z","end":"2026-08-21T02:47:27.710923Z","steps":["trace[1421354837] 'agreement among raft nodes before linearized reading' (duration: 140.159542ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:47:27.711384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.381596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-08-21T02:47:27.711475Z","caller":"traceutil/trace.go:171","msg":"trace[485299994] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:9021; }","duration":"118.534639ms","start":"2026-08-21T02:47:27.592924Z","end":"2026-08-21T02:47:27.711458Z","steps":["trace[485299994] 'agreement among raft nodes before linearized reading' (duration: 118.288173ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:47:27.933183Z","caller":"traceutil/trace.go:171","msg":"trace[1966817872] transaction","detail":"{read_only:false; response_revision:9022; number_of_response:1; }","duration":"198.957514ms","start":"2026-08-21T02:47:27.734199Z","end":"2026-08-21T02:47:27.933156Z","steps":["trace[1966817872] 'process raft request' (duration: 198.774239ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:47:28.068041Z","caller":"traceutil/trace.go:171","msg":"trace[602825423] transaction","detail":"{read_only:false; number_of_response:1; response_revision:9022; }","duration":"332.392363ms","start":"2026-08-21T02:47:27.735594Z","end":"2026-08-21T02:47:28.067987Z","steps":["trace[602825423] 'process raft request' (duration: 268.697347ms)","trace[602825423] 'compare' (duration: 63.492871ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:47:28.068081Z","caller":"traceutil/trace.go:171","msg":"trace[731763870] linearizableReadLoop","detail":"{readStateIndex:9339; appliedIndex:9337; }","duration":"328.923728ms","start":"2026-08-21T02:47:27.739126Z","end":"2026-08-21T02:47:28.06805Z","steps":["trace[731763870] 'read index received' (duration: 193.823354ms)","trace[731763870] 'applied index is now lower than readState.Index' (duration: 135.099094ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:47:28.068222Z","caller":"traceutil/trace.go:171","msg":"trace[1749322988] transaction","detail":"{read_only:false; response_revision:9023; number_of_response:1; }","duration":"155.315478ms","start":"2026-08-21T02:47:27.91287Z","end":"2026-08-21T02:47:28.068186Z","steps":["trace[1749322988] 'process raft request' (duration: 155.020769ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:47:28.068278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:47:27.735568Z","time spent":"332.578107ms","remote":"127.0.0.1:60086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":77,"response count":0,"response size":2384,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T02:47:28.068419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.267897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-21T02:47:28.068519Z","caller":"traceutil/trace.go:171","msg":"trace[597017172] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:9023; }","duration":"329.406951ms","start":"2026-08-21T02:47:27.739092Z","end":"2026-08-21T02:47:28.068499Z","steps":["trace[597017172] 'agreement among raft nodes before linearized reading' (duration: 329.129294ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:47:28.06879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:47:27.739076Z","time spent":"329.482753ms","remote":"127.0.0.1:58582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1146,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"warn","ts":"2026-08-21T02:47:28.070792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.17607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:112202"} {"level":"info","ts":"2026-08-21T02:47:28.070836Z","caller":"traceutil/trace.go:171","msg":"trace[800627597] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:9025; }","duration":"331.240931ms","start":"2026-08-21T02:47:27.739585Z","end":"2026-08-21T02:47:28.070826Z","steps":["trace[800627597] 'agreement among raft nodes before linearized reading' (duration: 331.004544ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:47:28.070861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:47:27.739575Z","time spent":"331.280283ms","remote":"127.0.0.1:58414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":16,"response size":112224,"request content":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" "} {"level":"info","ts":"2026-08-21T02:47:28.071193Z","caller":"traceutil/trace.go:171","msg":"trace[1203070741] transaction","detail":"{read_only:false; response_revision:9024; number_of_response:1; }","duration":"138.168799ms","start":"2026-08-21T02:47:27.93301Z","end":"2026-08-21T02:47:28.071179Z","steps":["trace[1203070741] 'process raft request' (duration: 137.420118ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:47:28.071411Z","caller":"traceutil/trace.go:171","msg":"trace[872491215] transaction","detail":"{read_only:false; response_revision:9025; number_of_response:1; }","duration":"130.074437ms","start":"2026-08-21T02:47:27.941327Z","end":"2026-08-21T02:47:28.071402Z","steps":["trace[872491215] 'process raft request' (duration: 129.206863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:47:28.071575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.710401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-rgw-ceph-certs\" ","response":"range_response_count:1 size:4366"} {"level":"info","ts":"2026-08-21T02:47:28.071604Z","caller":"traceutil/trace.go:171","msg":"trace[1057640231] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-rgw-ceph-certs; range_end:; response_count:1; response_revision:9025; }","duration":"127.786603ms","start":"2026-08-21T02:47:27.94381Z","end":"2026-08-21T02:47:28.071597Z","steps":["trace[1057640231] 'agreement among raft nodes before linearized reading' (duration: 127.706731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:47:28.071786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.564362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rook-ceph-rgw-ceph-certs-q86cf\" ","response":"range_response_count:1 size:2494"} {"level":"info","ts":"2026-08-21T02:47:28.071819Z","caller":"traceutil/trace.go:171","msg":"trace[2043796440] range","detail":"{range_begin:/registry/secrets/openstack/rook-ceph-rgw-ceph-certs-q86cf; range_end:; response_count:1; response_revision:9025; }","duration":"129.638514ms","start":"2026-08-21T02:47:27.942171Z","end":"2026-08-21T02:47:28.07181Z","steps":["trace[2043796440] 'agreement among raft nodes before linearized reading' (duration: 129.555732ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:47:28.071956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.750842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-qjzrm\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-08-21T02:47:28.071988Z","caller":"traceutil/trace.go:171","msg":"trace[1897825231] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-qjzrm; range_end:; response_count:1; response_revision:9025; }","duration":"158.821764ms","start":"2026-08-21T02:47:27.913157Z","end":"2026-08-21T02:47:28.071979Z","steps":["trace[1897825231] 'agreement among raft nodes before linearized reading' (duration: 158.742371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:47:28.072105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.727525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T02:47:28.072132Z","caller":"traceutil/trace.go:171","msg":"trace[1194975571] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:9025; }","duration":"242.799767ms","start":"2026-08-21T02:47:27.829324Z","end":"2026-08-21T02:47:28.072123Z","steps":["trace[1194975571] 'agreement among raft nodes before linearized reading' (duration: 242.760025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:47:28.072277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.933389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-08-21T02:47:28.072304Z","caller":"traceutil/trace.go:171","msg":"trace[1979653996] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:9025; }","duration":"324.99253ms","start":"2026-08-21T02:47:27.747305Z","end":"2026-08-21T02:47:28.072298Z","steps":["trace[1979653996] 'agreement among raft nodes before linearized reading' (duration: 324.921808ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T02:47:28.072323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T02:47:27.747279Z","time spent":"325.039741ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3706,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"info","ts":"2026-08-21T02:47:47.666015Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002532.wal"} {"level":"info","ts":"2026-08-21T02:48:36.557319Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-21T02:48:36.562515Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-21T02:48:36.562585Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-21T02:50:20.580829Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8045} {"level":"info","ts":"2026-08-21T02:50:20.643283Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8045,"took":"60.084721ms","hash":910419755,"current-db-size-bytes":55951360,"current-db-size":"56 MB","current-db-size-in-use-bytes":27983872,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-21T02:50:20.643349Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":910419755,"revision":8045,"compact-revision":5904} {"level":"info","ts":"2026-08-21T02:54:23.575759Z","caller":"traceutil/trace.go:171","msg":"trace[1782525447] transaction","detail":"{read_only:false; response_revision:12238; number_of_response:1; }","duration":"135.607405ms","start":"2026-08-21T02:54:23.44012Z","end":"2026-08-21T02:54:23.575728Z","steps":["trace[1782525447] 'process raft request' (duration: 112.078527ms)","trace[1782525447] 'compare' (duration: 23.276121ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T02:54:23.582033Z","caller":"traceutil/trace.go:171","msg":"trace[449909178] transaction","detail":"{read_only:false; response_revision:12239; number_of_response:1; }","duration":"141.632592ms","start":"2026-08-21T02:54:23.440358Z","end":"2026-08-21T02:54:23.58199Z","steps":["trace[449909178] 'process raft request' (duration: 141.510348ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T02:55:20.590051Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10455} {"level":"info","ts":"2026-08-21T02:55:20.673712Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10455,"took":"81.206107ms","hash":938918723,"current-db-size-bytes":55951360,"current-db-size":"56 MB","current-db-size-in-use-bytes":26238976,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-21T02:55:20.673779Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":938918723,"revision":10455,"compact-revision":8045} {"level":"info","ts":"2026-08-21T03:00:20.596052Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12648} {"level":"info","ts":"2026-08-21T03:00:20.67025Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12648,"took":"72.326524ms","hash":2805526765,"current-db-size-bytes":55951360,"current-db-size":"56 MB","current-db-size-in-use-bytes":27287552,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-21T03:00:20.670325Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2805526765,"revision":12648,"compact-revision":10455} {"level":"info","ts":"2026-08-21T03:00:24.269204Z","caller":"traceutil/trace.go:171","msg":"trace[1649384315] transaction","detail":"{read_only:false; response_revision:14691; number_of_response:1; }","duration":"125.101949ms","start":"2026-08-21T03:00:24.144073Z","end":"2026-08-21T03:00:24.269175Z","steps":["trace[1649384315] 'process raft request' (duration: 123.458364ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:00:39.955031Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085746853109485767,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:00:41.556392Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085746853109485767,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-21T03:00:42.009147Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.581514717s","expected-duration":"1s"} {"level":"info","ts":"2026-08-21T03:00:42.017761Z","caller":"traceutil/trace.go:171","msg":"trace[1792956383] linearizableReadLoop","detail":"{readStateIndex:15281; appliedIndex:15278; }","duration":"2.563202192s","start":"2026-08-21T03:00:39.454538Z","end":"2026-08-21T03:00:42.01774Z","steps":["trace[1792956383] 'read index received' (duration: 2.555753027s)","trace[1792956383] 'applied index is now lower than readState.Index' (duration: 7.448455ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:00:42.018157Z","caller":"traceutil/trace.go:171","msg":"trace[1275316635] transaction","detail":"{read_only:false; response_revision:14770; number_of_response:1; }","duration":"1.978456162s","start":"2026-08-21T03:00:40.039691Z","end":"2026-08-21T03:00:42.018147Z","steps":["trace[1275316635] 'process raft request' (duration: 1.977827904s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:00:42.018249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:00:40.039677Z","time spent":"1.978509544s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T03:00:42.018391Z","caller":"traceutil/trace.go:171","msg":"trace[924992448] transaction","detail":"{read_only:false; response_revision:14771; number_of_response:1; }","duration":"1.745931851s","start":"2026-08-21T03:00:40.272451Z","end":"2026-08-21T03:00:42.018383Z","steps":["trace[924992448] 'process raft request' (duration: 1.745222282s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:00:42.018434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:00:40.272434Z","time spent":"1.745974043s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T03:00:42.018522Z","caller":"traceutil/trace.go:171","msg":"trace[2062934338] transaction","detail":"{read_only:false; response_revision:14772; number_of_response:1; }","duration":"454.459868ms","start":"2026-08-21T03:00:41.564054Z","end":"2026-08-21T03:00:42.018514Z","steps":["trace[2062934338] 'process raft request' (duration: 453.650095ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:00:42.018565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:00:41.564036Z","time spent":"454.50933ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T03:00:42.018713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.56417598s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-21T03:00:42.018744Z","caller":"traceutil/trace.go:171","msg":"trace[2147017368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14772; }","duration":"2.564205001s","start":"2026-08-21T03:00:39.45453Z","end":"2026-08-21T03:00:42.018735Z","steps":["trace[2147017368] 'agreement among raft nodes before linearized reading' (duration: 2.564115318s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:00:42.018763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:00:39.454508Z","time spent":"2.564250961s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-21T03:00:42.018888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.524723471s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-21T03:00:42.018918Z","caller":"traceutil/trace.go:171","msg":"trace[1841027716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14772; }","duration":"2.524781593s","start":"2026-08-21T03:00:39.494131Z","end":"2026-08-21T03:00:42.018913Z","steps":["trace[1841027716] 'agreement among raft nodes before linearized reading' (duration: 2.524716851s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:00:42.018935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:00:39.494116Z","time spent":"2.524814813s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-21T03:00:42.019171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.263046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-08-21T03:00:42.019199Z","caller":"traceutil/trace.go:171","msg":"trace[1521046191] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:14772; }","duration":"206.323847ms","start":"2026-08-21T03:00:41.812868Z","end":"2026-08-21T03:00:42.019192Z","steps":["trace[1521046191] 'agreement among raft nodes before linearized reading' (duration: 206.237935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:00:42.019338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113471545s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-21T03:00:42.019362Z","caller":"traceutil/trace.go:171","msg":"trace[1191748229] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:14772; }","duration":"1.113507876s","start":"2026-08-21T03:00:40.905848Z","end":"2026-08-21T03:00:42.019356Z","steps":["trace[1191748229] 'agreement among raft nodes before linearized reading' (duration: 1.113455354s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:00:42.019378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:00:40.905839Z","time spent":"1.113533596s","remote":"127.0.0.1:58348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":13,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-08-21T03:00:42.019492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.120246502s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T03:00:42.019518Z","caller":"traceutil/trace.go:171","msg":"trace[1772454539] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:14772; }","duration":"1.120324004s","start":"2026-08-21T03:00:40.899185Z","end":"2026-08-21T03:00:42.019509Z","steps":["trace[1772454539] 'agreement among raft nodes before linearized reading' (duration: 1.120280943s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:00:42.019599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:00:40.89916Z","time spent":"1.120431717s","remote":"127.0.0.1:41530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true "} {"level":"warn","ts":"2026-08-21T03:00:42.019754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.474068017s","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:5"} {"level":"info","ts":"2026-08-21T03:00:42.019776Z","caller":"traceutil/trace.go:171","msg":"trace[223515102] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:14772; }","duration":"1.474153329s","start":"2026-08-21T03:00:40.545617Z","end":"2026-08-21T03:00:42.019771Z","steps":["trace[223515102] 'agreement among raft nodes before linearized reading' (duration: 1.474112938s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:00:42.019796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:00:40.545592Z","time spent":"1.4741977s","remote":"127.0.0.1:35604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"warn","ts":"2026-08-21T03:00:42.020234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.318001412s","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-21T03:00:42.020345Z","caller":"traceutil/trace.go:171","msg":"trace[1493525240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14772; }","duration":"2.318135205s","start":"2026-08-21T03:00:39.702184Z","end":"2026-08-21T03:00:42.020319Z","steps":["trace[1493525240] 'agreement among raft nodes before linearized reading' (duration: 2.317747245s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:00:42.020385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:00:39.702144Z","time spent":"2.318234118s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-21T03:00:42.020573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.981020413s","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":"info","ts":"2026-08-21T03:00:42.020591Z","caller":"traceutil/trace.go:171","msg":"trace[551292627] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:14772; }","duration":"1.981081855s","start":"2026-08-21T03:00:40.039503Z","end":"2026-08-21T03:00:42.020585Z","steps":["trace[551292627] 'agreement among raft nodes before linearized reading' (duration: 1.981038063s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:00:42.020607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:00:40.039484Z","time spent":"1.981117785s","remote":"127.0.0.1:36172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-21T03:00:42.02062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.748731828s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-08-21T03:00:42.020654Z","caller":"traceutil/trace.go:171","msg":"trace[994583896] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14772; }","duration":"1.7488129s","start":"2026-08-21T03:00:40.271833Z","end":"2026-08-21T03:00:42.020646Z","steps":["trace[994583896] 'agreement among raft nodes before linearized reading' (duration: 1.748724008s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:00:42.020679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:00:40.271793Z","time spent":"1.748879022s","remote":"127.0.0.1:58414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-21T03:00:42.020757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.33712069s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-21T03:00:42.020872Z","caller":"traceutil/trace.go:171","msg":"trace[277851106] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14772; }","duration":"2.337295645s","start":"2026-08-21T03:00:39.68355Z","end":"2026-08-21T03:00:42.020845Z","steps":["trace[277851106] 'agreement among raft nodes before linearized reading' (duration: 2.336978736s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:00:42.020914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:00:39.683527Z","time spent":"2.337377107s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-21T03:00:42.020917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.969159266s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T03:00:42.020941Z","caller":"traceutil/trace.go:171","msg":"trace[634724837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14772; }","duration":"1.969211987s","start":"2026-08-21T03:00:40.051723Z","end":"2026-08-21T03:00:42.020935Z","steps":["trace[634724837] 'agreement among raft nodes before linearized reading' (duration: 1.969174356s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:00:42.020956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:00:40.051707Z","time spent":"1.969246928s","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-21T03:00:42.020892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.981157056s","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":"warn","ts":"2026-08-21T03:00:42.020988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.521460552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-21T03:00:42.021019Z","caller":"traceutil/trace.go:171","msg":"trace[860975028] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14772; }","duration":"2.521523232s","start":"2026-08-21T03:00:39.499488Z","end":"2026-08-21T03:00:42.021012Z","steps":["trace[860975028] 'agreement among raft nodes before linearized reading' (duration: 2.52144726s)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:00:42.02103Z","caller":"traceutil/trace.go:171","msg":"trace[1623649061] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14772; }","duration":"1.981375843s","start":"2026-08-21T03:00:40.039612Z","end":"2026-08-21T03:00:42.020988Z","steps":["trace[1623649061] 'agreement among raft nodes before linearized reading' (duration: 1.98090464s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:00:42.021103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:00:40.039581Z","time spent":"1.981495086s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-21T03:00:42.021038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:00:39.499478Z","time spent":"2.521555784s","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-21T03:00:42.02116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.487823783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6g77c\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-21T03:00:42.021179Z","caller":"traceutil/trace.go:171","msg":"trace[106010924] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6g77c; range_end:; response_count:1; response_revision:14772; }","duration":"2.487875445s","start":"2026-08-21T03:00:39.533299Z","end":"2026-08-21T03:00:42.021174Z","steps":["trace[106010924] 'agreement among raft nodes before linearized reading' (duration: 2.487804913s)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:00:42.021195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:00:39.53328Z","time spent":"2.487911846s","remote":"127.0.0.1:58414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-6g77c\" "} {"level":"info","ts":"2026-08-21T03:01:14.292453Z","caller":"traceutil/trace.go:171","msg":"trace[1057295940] transaction","detail":"{read_only:false; response_revision:15062; number_of_response:1; }","duration":"315.112487ms","start":"2026-08-21T03:01:13.97731Z","end":"2026-08-21T03:01:14.292422Z","steps":["trace[1057295940] 'process raft request' (duration: 271.019261ms)","trace[1057295940] 'compare' (duration: 43.922091ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:01:14.292435Z","caller":"traceutil/trace.go:171","msg":"trace[1870327053] linearizableReadLoop","detail":"{readStateIndex:15582; appliedIndex:15581; }","duration":"294.87976ms","start":"2026-08-21T03:01:13.99753Z","end":"2026-08-21T03:01:14.292409Z","steps":["trace[1870327053] 'read index received' (duration: 250.818905ms)","trace[1870327053] 'applied index is now lower than readState.Index' (duration: 44.059695ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T03:01:14.292685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.132756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/data-ovn-ovsdb-sb-0.18cdb2d914c296ca\" ","response":"range_response_count:1 size:956"} {"level":"info","ts":"2026-08-21T03:01:14.292839Z","caller":"traceutil/trace.go:171","msg":"trace[1655372875] range","detail":"{range_begin:/registry/events/openstack/data-ovn-ovsdb-sb-0.18cdb2d914c296ca; range_end:; response_count:1; response_revision:15062; }","duration":"295.27256ms","start":"2026-08-21T03:01:13.997482Z","end":"2026-08-21T03:01:14.292755Z","steps":["trace[1655372875] 'agreement among raft nodes before linearized reading' (duration: 295.019933ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:01:14.292869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:01:13.977279Z","time spent":"315.246941ms","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T03:01:14.326Z","caller":"traceutil/trace.go:171","msg":"trace[852074479] transaction","detail":"{read_only:false; response_revision:15063; number_of_response:1; }","duration":"163.265241ms","start":"2026-08-21T03:01:14.162713Z","end":"2026-08-21T03:01:14.325978Z","steps":["trace[852074479] 'process raft request' (duration: 163.067556ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:01:14.326381Z","caller":"traceutil/trace.go:171","msg":"trace[1378597098] transaction","detail":"{read_only:false; response_revision:15066; number_of_response:1; }","duration":"128.576385ms","start":"2026-08-21T03:01:14.197785Z","end":"2026-08-21T03:01:14.326361Z","steps":["trace[1378597098] 'process raft request' (duration: 128.474842ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:01:14.326415Z","caller":"traceutil/trace.go:171","msg":"trace[1886747713] transaction","detail":"{read_only:false; response_revision:15065; number_of_response:1; }","duration":"161.206364ms","start":"2026-08-21T03:01:14.165133Z","end":"2026-08-21T03:01:14.326339Z","steps":["trace[1886747713] 'process raft request' (duration: 161.017319ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:01:14.326434Z","caller":"traceutil/trace.go:171","msg":"trace[2010051142] transaction","detail":"{read_only:false; response_revision:15064; number_of_response:1; }","duration":"162.625033ms","start":"2026-08-21T03:01:14.163769Z","end":"2026-08-21T03:01:14.326394Z","steps":["trace[2010051142] 'process raft request' (duration: 162.164941ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:01:14.326724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.842526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-21T03:01:14.326701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.743298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-21T03:01:14.326752Z","caller":"traceutil/trace.go:171","msg":"trace[932239334] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15066; }","duration":"110.886717ms","start":"2026-08-21T03:01:14.215858Z","end":"2026-08-21T03:01:14.326745Z","steps":["trace[932239334] 'agreement among raft nodes before linearized reading' (duration: 110.759354ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:01:14.326783Z","caller":"traceutil/trace.go:171","msg":"trace[40979382] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15066; }","duration":"108.851161ms","start":"2026-08-21T03:01:14.21791Z","end":"2026-08-21T03:01:14.326761Z","steps":["trace[40979382] 'agreement among raft nodes before linearized reading' (duration: 108.625404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:01:14.326854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.252131ms","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-21T03:01:14.326914Z","caller":"traceutil/trace.go:171","msg":"trace[668026914] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15066; }","duration":"105.347264ms","start":"2026-08-21T03:01:14.221547Z","end":"2026-08-21T03:01:14.326894Z","steps":["trace[668026914] 'agreement among raft nodes before linearized reading' (duration: 105.118838ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:01:14.326919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.848983ms","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-21T03:01:14.326951Z","caller":"traceutil/trace.go:171","msg":"trace[540298899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15066; }","duration":"107.904265ms","start":"2026-08-21T03:01:14.219042Z","end":"2026-08-21T03:01:14.326946Z","steps":["trace[540298899] 'agreement among raft nodes before linearized reading' (duration: 107.834213ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:01:14.32689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.977422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-08-21T03:01:14.327001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.622727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-21T03:01:14.327025Z","caller":"traceutil/trace.go:171","msg":"trace[1548388770] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15066; }","duration":"114.156737ms","start":"2026-08-21T03:01:14.212835Z","end":"2026-08-21T03:01:14.326991Z","steps":["trace[1548388770] 'agreement among raft nodes before linearized reading' (duration: 113.829548ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:01:14.327054Z","caller":"traceutil/trace.go:171","msg":"trace[546334021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15066; }","duration":"111.779231ms","start":"2026-08-21T03:01:14.215265Z","end":"2026-08-21T03:01:14.327044Z","steps":["trace[546334021] 'agreement among raft nodes before linearized reading' (duration: 111.667238ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:02:56.882382Z","caller":"traceutil/trace.go:171","msg":"trace[1497982396] linearizableReadLoop","detail":"{readStateIndex:16719; appliedIndex:16718; }","duration":"154.244782ms","start":"2026-08-21T03:02:56.728088Z","end":"2026-08-21T03:02:56.882333Z","steps":["trace[1497982396] 'read index received' (duration: 154.081197ms)","trace[1497982396] 'applied index is now lower than readState.Index' (duration: 162.315µs)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:02:56.882401Z","caller":"traceutil/trace.go:171","msg":"trace[242012035] transaction","detail":"{read_only:false; response_revision:16167; number_of_response:1; }","duration":"155.288331ms","start":"2026-08-21T03:02:56.727074Z","end":"2026-08-21T03:02:56.882362Z","steps":["trace[242012035] 'process raft request' (duration: 155.072025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.882558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.443857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T03:02:56.882649Z","caller":"traceutil/trace.go:171","msg":"trace[180862726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16167; }","duration":"154.553651ms","start":"2026-08-21T03:02:56.728062Z","end":"2026-08-21T03:02:56.882616Z","steps":["trace[180862726] 'agreement among raft nodes before linearized reading' (duration: 154.404867ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.946635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.982593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-21T03:02:56.946697Z","caller":"traceutil/trace.go:171","msg":"trace[8279799] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16169; }","duration":"159.081176ms","start":"2026-08-21T03:02:56.787601Z","end":"2026-08-21T03:02:56.946682Z","steps":["trace[8279799] 'agreement among raft nodes before linearized reading' (duration: 158.940422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.946718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.331794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-f8q2k\" ","response":"range_response_count:1 size:20653"} {"level":"info","ts":"2026-08-21T03:02:56.946778Z","caller":"traceutil/trace.go:171","msg":"trace[1042883274] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-f8q2k; range_end:; response_count:1; response_revision:16169; }","duration":"105.454157ms","start":"2026-08-21T03:02:56.84131Z","end":"2026-08-21T03:02:56.946764Z","steps":["trace[1042883274] 'agreement among raft nodes before linearized reading' (duration: 105.228251ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.946898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.923123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-lr6k7\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-08-21T03:02:56.946927Z","caller":"traceutil/trace.go:171","msg":"trace[1380398637] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-lr6k7; range_end:; response_count:1; response_revision:16169; }","duration":"182.978535ms","start":"2026-08-21T03:02:56.763942Z","end":"2026-08-21T03:02:56.94692Z","steps":["trace[1380398637] 'agreement among raft nodes before linearized reading' (duration: 182.902053ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:56.947025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.585619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-lr6k7\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-08-21T03:02:56.94706Z","caller":"traceutil/trace.go:171","msg":"trace[794774408] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-lr6k7; range_end:; response_count:1; response_revision:16169; }","duration":"192.647331ms","start":"2026-08-21T03:02:56.754405Z","end":"2026-08-21T03:02:56.947052Z","steps":["trace[794774408] 'agreement among raft nodes before linearized reading' (duration: 192.5839ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:02:56.947079Z","caller":"traceutil/trace.go:171","msg":"trace[1427584044] transaction","detail":"{read_only:false; response_revision:16168; number_of_response:1; }","duration":"218.814723ms","start":"2026-08-21T03:02:56.728253Z","end":"2026-08-21T03:02:56.947068Z","steps":["trace[1427584044] 'process raft request' (duration: 197.807633ms)","trace[1427584044] 'compare' (duration: 20.329781ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:02:56.947141Z","caller":"traceutil/trace.go:171","msg":"trace[1325777618] transaction","detail":"{read_only:false; response_revision:16169; number_of_response:1; }","duration":"216.994903ms","start":"2026-08-21T03:02:56.730133Z","end":"2026-08-21T03:02:56.947128Z","steps":["trace[1325777618] 'process raft request' (duration: 216.341014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:57.258039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.204058ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T03:02:57.258197Z","caller":"traceutil/trace.go:171","msg":"trace[1190151835] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:16175; }","duration":"143.370092ms","start":"2026-08-21T03:02:57.114802Z","end":"2026-08-21T03:02:57.258172Z","steps":["trace[1190151835] 'range keys from in-memory index tree' (duration: 143.187888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:02:57.25854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.730937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-21T03:02:57.258675Z","caller":"traceutil/trace.go:171","msg":"trace[1669841837] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:16175; }","duration":"133.900802ms","start":"2026-08-21T03:02:57.124725Z","end":"2026-08-21T03:02:57.258626Z","steps":["trace[1669841837] 'count revisions from in-memory index tree' (duration: 133.606733ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:05:20.60242Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14668} {"level":"info","ts":"2026-08-21T03:05:20.68498Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14668,"took":"79.538349ms","hash":3417784929,"current-db-size-bytes":55951360,"current-db-size":"56 MB","current-db-size-in-use-bytes":32043008,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-21T03:05:20.685083Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3417784929,"revision":14668,"compact-revision":12648} {"level":"info","ts":"2026-08-21T03:08:34.310042Z","caller":"traceutil/trace.go:171","msg":"trace[1114775756] transaction","detail":"{read_only:false; response_revision:18641; number_of_response:1; }","duration":"153.904246ms","start":"2026-08-21T03:08:34.156109Z","end":"2026-08-21T03:08:34.310014Z","steps":["trace[1114775756] 'process raft request' (duration: 153.729571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:08:34.931845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.084694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-08-21T03:08:34.931925Z","caller":"traceutil/trace.go:171","msg":"trace[175224237] linearizableReadLoop","detail":"{readStateIndex:19281; appliedIndex:19280; }","duration":"205.379329ms","start":"2026-08-21T03:08:34.726534Z","end":"2026-08-21T03:08:34.931914Z","steps":["trace[175224237] 'read index received' (duration: 63.122µs)","trace[175224237] 'applied index is now lower than readState.Index' (duration: 205.314997ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:08:34.932041Z","caller":"traceutil/trace.go:171","msg":"trace[1124753111] transaction","detail":"{read_only:false; response_revision:18644; number_of_response:1; }","duration":"495.125043ms","start":"2026-08-21T03:08:34.436909Z","end":"2026-08-21T03:08:34.932034Z","steps":["trace[1124753111] 'process raft request' (duration: 226.794312ms)","trace[1124753111] 'compare' (duration: 267.823707ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T03:08:34.932074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:08:34.436891Z","time spent":"495.170813ms","remote":"127.0.0.1:58318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":917,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-21T03:08:34.932174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.625366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:08:34.932244Z","caller":"traceutil/trace.go:171","msg":"trace[1276518200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18644; }","duration":"205.74417ms","start":"2026-08-21T03:08:34.726482Z","end":"2026-08-21T03:08:34.932226Z","steps":["trace[1276518200] 'agreement among raft nodes before linearized reading' (duration: 205.529194ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:08:35.059302Z","caller":"traceutil/trace.go:171","msg":"trace[278893049] transaction","detail":"{read_only:false; response_revision:18646; number_of_response:1; }","duration":"121.85272ms","start":"2026-08-21T03:08:34.937425Z","end":"2026-08-21T03:08:35.059278Z","steps":["trace[278893049] 'process raft request' (duration: 96.811017ms)","trace[278893049] 'compare' (duration: 24.873488ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:08:35.0595Z","caller":"traceutil/trace.go:171","msg":"trace[1984786074] transaction","detail":"{read_only:false; response_revision:18647; number_of_response:1; }","duration":"121.225682ms","start":"2026-08-21T03:08:34.938259Z","end":"2026-08-21T03:08:35.059484Z","steps":["trace[1984786074] 'process raft request' (duration: 120.977926ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:08:35.059774Z","caller":"traceutil/trace.go:171","msg":"trace[1506629743] transaction","detail":"{read_only:false; response_revision:18648; number_of_response:1; }","duration":"121.5027ms","start":"2026-08-21T03:08:34.938258Z","end":"2026-08-21T03:08:35.059761Z","steps":["trace[1506629743] 'process raft request' (duration: 121.160531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:08:49.349562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.588396ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-21T03:08:49.349825Z","caller":"traceutil/trace.go:171","msg":"trace[552753641] linearizableReadLoop","detail":"{readStateIndex:19506; appliedIndex:19505; }","duration":"147.472828ms","start":"2026-08-21T03:08:49.202306Z","end":"2026-08-21T03:08:49.349779Z","steps":["trace[552753641] 'read index received' (duration: 5.662007ms)","trace[552753641] 'applied index is now lower than readState.Index' (duration: 141.804311ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T03:08:49.34999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.669244ms","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-21T03:08:49.350063Z","caller":"traceutil/trace.go:171","msg":"trace[1447370238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18860; }","duration":"147.787187ms","start":"2026-08-21T03:08:49.20226Z","end":"2026-08-21T03:08:49.350047Z","steps":["trace[1447370238] 'agreement among raft nodes before linearized reading' (duration: 147.613362ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:08:49.350273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.638636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-21T03:08:49.350329Z","caller":"traceutil/trace.go:171","msg":"trace[956892187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18861; }","duration":"120.700228ms","start":"2026-08-21T03:08:49.229614Z","end":"2026-08-21T03:08:49.350314Z","steps":["trace[956892187] 'agreement among raft nodes before linearized reading' (duration: 120.492352ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:08:49.350264Z","caller":"traceutil/trace.go:171","msg":"trace[1521409993] transaction","detail":"{read_only:false; response_revision:18861; number_of_response:1; }","duration":"144.332621ms","start":"2026-08-21T03:08:49.205903Z","end":"2026-08-21T03:08:49.350235Z","steps":["trace[1521409993] 'process raft request' (duration: 143.782226ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:09:33.465543Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-21T03:09:33.470299Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-21T03:09:33.470423Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-21T03:10:20.608789Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17189} {"level":"info","ts":"2026-08-21T03:10:20.710102Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17189,"took":"97.162597ms","hash":2239202080,"current-db-size-bytes":55951360,"current-db-size":"56 MB","current-db-size-in-use-bytes":33624064,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-21T03:10:20.71016Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2239202080,"revision":17189,"compact-revision":14668} {"level":"warn","ts":"2026-08-21T03:14:19.203788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.355958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-21T03:14:19.203974Z","caller":"traceutil/trace.go:171","msg":"trace[1483686013] transaction","detail":"{read_only:false; response_revision:21318; number_of_response:1; }","duration":"252.512123ms","start":"2026-08-21T03:14:18.951422Z","end":"2026-08-21T03:14:19.203934Z","steps":["trace[1483686013] 'process raft request' (duration: 96.916988ms)","trace[1483686013] 'compare' (duration: 155.06404ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:14:19.204043Z","caller":"traceutil/trace.go:171","msg":"trace[1872764927] linearizableReadLoop","detail":"{readStateIndex:22043; appliedIndex:22042; }","duration":"147.81402ms","start":"2026-08-21T03:14:19.056203Z","end":"2026-08-21T03:14:19.204017Z","steps":["trace[1872764927] 'read index received' (duration: 38.771µs)","trace[1872764927] 'applied index is now lower than readState.Index' (duration: 147.771888ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T03:14:19.217215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.975704ms","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":"warn","ts":"2026-08-21T03:14:19.217279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.154582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-21T03:14:19.217288Z","caller":"traceutil/trace.go:171","msg":"trace[2105874722] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:21318; }","duration":"161.098988ms","start":"2026-08-21T03:14:19.056171Z","end":"2026-08-21T03:14:19.21727Z","steps":["trace[2105874722] 'agreement among raft nodes before linearized reading' (duration: 147.954934ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:14:19.21731Z","caller":"traceutil/trace.go:171","msg":"trace[1277257985] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21318; }","duration":"120.246655ms","start":"2026-08-21T03:14:19.097054Z","end":"2026-08-21T03:14:19.217301Z","steps":["trace[1277257985] 'agreement among raft nodes before linearized reading' (duration: 120.07455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:14:19.21766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.059065ms","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-21T03:14:19.217756Z","caller":"traceutil/trace.go:171","msg":"trace[1510202083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21318; }","duration":"118.222249ms","start":"2026-08-21T03:14:19.099512Z","end":"2026-08-21T03:14:19.217734Z","steps":["trace[1510202083] 'agreement among raft nodes before linearized reading' (duration: 117.806157ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:14:19.514125Z","caller":"traceutil/trace.go:171","msg":"trace[1953313101] transaction","detail":"{read_only:false; response_revision:21320; number_of_response:1; }","duration":"292.652206ms","start":"2026-08-21T03:14:19.221447Z","end":"2026-08-21T03:14:19.514099Z","steps":["trace[1953313101] 'process raft request' (duration: 292.596395ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:14:19.514511Z","caller":"traceutil/trace.go:171","msg":"trace[646646909] transaction","detail":"{read_only:false; response_revision:21319; number_of_response:1; }","duration":"292.844382ms","start":"2026-08-21T03:14:19.221428Z","end":"2026-08-21T03:14:19.514272Z","steps":["trace[646646909] 'process raft request' (duration: 292.41988ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:14:19.714414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.578768ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-21T03:14:19.714495Z","caller":"traceutil/trace.go:171","msg":"trace[980357043] linearizableReadLoop","detail":"{readStateIndex:22047; appliedIndex:22046; }","duration":"409.59394ms","start":"2026-08-21T03:14:19.304888Z","end":"2026-08-21T03:14:19.714482Z","steps":["trace[980357043] 'read index received' (duration: 209.239053ms)","trace[980357043] 'applied index is now lower than readState.Index' (duration: 200.352937ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:14:19.714541Z","caller":"traceutil/trace.go:171","msg":"trace[1320815444] transaction","detail":"{read_only:false; response_revision:21321; number_of_response:1; }","duration":"491.674706ms","start":"2026-08-21T03:14:19.222859Z","end":"2026-08-21T03:14:19.714534Z","steps":["trace[1320815444] 'process raft request' (duration: 385.774469ms)","trace[1320815444] 'compare' (duration: 105.221309ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T03:14:19.71459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:14:19.222833Z","time spent":"491.724428ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T03:14:19.714746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.859567ms","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-21T03:14:19.714766Z","caller":"traceutil/trace.go:171","msg":"trace[1348404930] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:21321; }","duration":"409.915459ms","start":"2026-08-21T03:14:19.304844Z","end":"2026-08-21T03:14:19.71476Z","steps":["trace[1348404930] 'agreement among raft nodes before linearized reading' (duration: 409.834716ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:14:19.714782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:14:19.304822Z","time spent":"409.95646ms","remote":"127.0.0.1:58474","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-21T03:14:19.743621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.745461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-21T03:14:19.743735Z","caller":"traceutil/trace.go:171","msg":"trace[2018029347] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:21325; }","duration":"247.889365ms","start":"2026-08-21T03:14:19.495825Z","end":"2026-08-21T03:14:19.743714Z","steps":["trace[2018029347] 'agreement among raft nodes before linearized reading' (duration: 247.672789ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:15:20.615207Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19713} {"level":"info","ts":"2026-08-21T03:15:20.731262Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19713,"took":"112.883601ms","hash":3003503733,"current-db-size-bytes":55951360,"current-db-size":"56 MB","current-db-size-in-use-bytes":29806592,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-21T03:15:20.731336Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3003503733,"revision":19713,"compact-revision":17189} {"level":"warn","ts":"2026-08-21T03:18:07.321859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.665055ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:18:07.322002Z","caller":"traceutil/trace.go:171","msg":"trace[17945892] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:23752; }","duration":"206.827581ms","start":"2026-08-21T03:18:07.115155Z","end":"2026-08-21T03:18:07.321983Z","steps":["trace[17945892] 'range keys from in-memory index tree' (duration: 206.645175ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:18:07.342362Z","caller":"traceutil/trace.go:171","msg":"trace[1800745649] linearizableReadLoop","detail":"{readStateIndex:24553; appliedIndex:24552; }","duration":"219.861649ms","start":"2026-08-21T03:18:07.122466Z","end":"2026-08-21T03:18:07.342328Z","steps":["trace[1800745649] 'read index received' (duration: 219.53355ms)","trace[1800745649] 'applied index is now lower than readState.Index' (duration: 326.559µs)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:18:07.342433Z","caller":"traceutil/trace.go:171","msg":"trace[351371128] transaction","detail":"{read_only:false; response_revision:23753; number_of_response:1; }","duration":"234.971825ms","start":"2026-08-21T03:18:07.107406Z","end":"2026-08-21T03:18:07.342377Z","steps":["trace[351371128] 'process raft request' (duration: 234.687437ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:18:07.342528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.028224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:18:07.342584Z","caller":"traceutil/trace.go:171","msg":"trace[1919951047] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:23753; }","duration":"220.136997ms","start":"2026-08-21T03:18:07.122431Z","end":"2026-08-21T03:18:07.342568Z","steps":["trace[1919951047] 'agreement among raft nodes before linearized reading' (duration: 220.022344ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:20:20.627279Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21938} {"level":"info","ts":"2026-08-21T03:20:20.737833Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21938,"took":"107.352434ms","hash":2195697021,"current-db-size-bytes":55951360,"current-db-size":"56 MB","current-db-size-in-use-bytes":43540480,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-21T03:20:20.737896Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2195697021,"revision":21938,"compact-revision":19713} {"level":"info","ts":"2026-08-21T03:25:20.635266Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25059} {"level":"info","ts":"2026-08-21T03:25:20.780248Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25059,"took":"139.796629ms","hash":1113628358,"current-db-size-bytes":55951360,"current-db-size":"56 MB","current-db-size-in-use-bytes":30380032,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-21T03:25:20.780301Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1113628358,"revision":25059,"compact-revision":21938} {"level":"warn","ts":"2026-08-21T03:27:37.602339Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085746853109534300,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-21T03:27:37.741573Z","caller":"traceutil/trace.go:171","msg":"trace[512181269] transaction","detail":"{read_only:false; response_revision:28278; number_of_response:1; }","duration":"996.277993ms","start":"2026-08-21T03:27:36.745267Z","end":"2026-08-21T03:27:37.741545Z","steps":["trace[512181269] 'process raft request' (duration: 996.124539ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:37.741715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:36.745237Z","time spent":"996.402077ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T03:27:37.748352Z","caller":"traceutil/trace.go:171","msg":"trace[1524902611] transaction","detail":"{read_only:false; response_revision:28279; number_of_response:1; }","duration":"825.676333ms","start":"2026-08-21T03:27:36.922654Z","end":"2026-08-21T03:27:37.74833Z","steps":["trace[1524902611] 'process raft request' (duration: 825.368045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:37.748495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:36.922629Z","time spent":"825.798187ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T03:27:37.748993Z","caller":"traceutil/trace.go:171","msg":"trace[2097386797] transaction","detail":"{read_only:false; response_revision:28280; number_of_response:1; }","duration":"524.079373ms","start":"2026-08-21T03:27:37.224882Z","end":"2026-08-21T03:27:37.748962Z","steps":["trace[2097386797] 'process raft request' (duration: 523.403454ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:27:37.748995Z","caller":"traceutil/trace.go:171","msg":"trace[1638749824] transaction","detail":"{read_only:false; response_revision:28281; number_of_response:1; }","duration":"484.628357ms","start":"2026-08-21T03:27:37.264339Z","end":"2026-08-21T03:27:37.748968Z","steps":["trace[1638749824] 'process raft request' (duration: 484.505354ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:27:37.749009Z","caller":"traceutil/trace.go:171","msg":"trace[762123300] linearizableReadLoop","detail":"{readStateIndex:29205; appliedIndex:29199; }","duration":"647.05686ms","start":"2026-08-21T03:27:37.101934Z","end":"2026-08-21T03:27:37.748991Z","steps":["trace[762123300] 'read index received' (duration: 639.561161ms)","trace[762123300] 'applied index is now lower than readState.Index' (duration: 7.494859ms)"],"step_count":2} {"level":"warn","ts":"2026-08-21T03:27:37.749055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:37.224861Z","time spent":"524.165696ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T03:27:37.749113Z","caller":"traceutil/trace.go:171","msg":"trace[1104260423] transaction","detail":"{read_only:false; response_revision:28282; number_of_response:1; }","duration":"391.801338ms","start":"2026-08-21T03:27:37.357297Z","end":"2026-08-21T03:27:37.749098Z","steps":["trace[1104260423] 'process raft request' (duration: 391.628713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:37.749112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:37.264324Z","time spent":"484.722049ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T03:27:37.749201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:37.357274Z","time spent":"391.858449ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T03:27:37.749234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.384215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:27:37.749253Z","caller":"traceutil/trace.go:171","msg":"trace[1301889096] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:28283; }","duration":"251.440257ms","start":"2026-08-21T03:27:37.497807Z","end":"2026-08-21T03:27:37.749247Z","steps":["trace[1301889096] 'agreement among raft nodes before linearized reading' (duration: 251.403296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:37.749255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.319028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-21T03:27:37.749283Z","caller":"traceutil/trace.go:171","msg":"trace[448910603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28283; }","duration":"647.379609ms","start":"2026-08-21T03:27:37.101895Z","end":"2026-08-21T03:27:37.749274Z","steps":["trace[448910603] 'agreement among raft nodes before linearized reading' (duration: 647.260595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:37.749305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:37.101878Z","time spent":"647.42205ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-21T03:27:37.749309Z","caller":"traceutil/trace.go:171","msg":"trace[1160141406] transaction","detail":"{read_only:false; response_revision:28283; number_of_response:1; }","duration":"347.330301ms","start":"2026-08-21T03:27:37.401972Z","end":"2026-08-21T03:27:37.749302Z","steps":["trace[1160141406] 'process raft request' (duration: 346.985552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:37.749355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:37.401947Z","time spent":"347.377783ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T03:27:37.749411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.807316ms","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-21T03:27:37.749429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.807689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:27:37.749458Z","caller":"traceutil/trace.go:171","msg":"trace[424998686] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:28283; }","duration":"237.86323ms","start":"2026-08-21T03:27:37.511588Z","end":"2026-08-21T03:27:37.749451Z","steps":["trace[424998686] 'agreement among raft nodes before linearized reading' (duration: 237.827339ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:37.749482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.732818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-08-21T03:27:37.749518Z","caller":"traceutil/trace.go:171","msg":"trace[1369311512] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:28283; }","duration":"236.78995ms","start":"2026-08-21T03:27:37.512721Z","end":"2026-08-21T03:27:37.749511Z","steps":["trace[1369311512] 'agreement among raft nodes before linearized reading' (duration: 236.679237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:37.749564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.257898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"warn","ts":"2026-08-21T03:27:37.749586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.394305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-21T03:27:37.749587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.623115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:27:37.749606Z","caller":"traceutil/trace.go:171","msg":"trace[2140050200] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:28283; }","duration":"125.425056ms","start":"2026-08-21T03:27:37.624176Z","end":"2026-08-21T03:27:37.749601Z","steps":["trace[2140050200] 'agreement among raft nodes before linearized reading' (duration: 125.394115ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:37.749613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.811656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-21T03:27:37.749582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.551443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:27:37.749624Z","caller":"traceutil/trace.go:171","msg":"trace[112325933] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:28283; }","duration":"236.678797ms","start":"2026-08-21T03:27:37.512934Z","end":"2026-08-21T03:27:37.749613Z","steps":["trace[112325933] 'agreement among raft nodes before linearized reading' (duration: 236.608775ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:27:37.749631Z","caller":"traceutil/trace.go:171","msg":"trace[938353354] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:28283; }","duration":"125.850417ms","start":"2026-08-21T03:27:37.623775Z","end":"2026-08-21T03:27:37.749625Z","steps":["trace[938353354] 'agreement among raft nodes before linearized reading' (duration: 125.824767ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:27:37.749588Z","caller":"traceutil/trace.go:171","msg":"trace[934505190] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:28283; }","duration":"113.295068ms","start":"2026-08-21T03:27:37.636286Z","end":"2026-08-21T03:27:37.749581Z","steps":["trace[934505190] 'agreement among raft nodes before linearized reading' (duration: 113.234846ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:27:37.749434Z","caller":"traceutil/trace.go:171","msg":"trace[297727769] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28283; }","duration":"125.95364ms","start":"2026-08-21T03:27:37.623472Z","end":"2026-08-21T03:27:37.749426Z","steps":["trace[297727769] 'agreement among raft nodes before linearized reading' (duration: 125.886328ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:27:37.749653Z","caller":"traceutil/trace.go:171","msg":"trace[227433354] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:28283; }","duration":"236.648796ms","start":"2026-08-21T03:27:37.51299Z","end":"2026-08-21T03:27:37.749639Z","steps":["trace[227433354] 'agreement among raft nodes before linearized reading' (duration: 236.520072ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:27:40.092822Z","caller":"traceutil/trace.go:171","msg":"trace[54379794] linearizableReadLoop","detail":"{readStateIndex:29217; appliedIndex:29216; }","duration":"366.141694ms","start":"2026-08-21T03:27:39.72665Z","end":"2026-08-21T03:27:40.092792Z","steps":["trace[54379794] 'read index received' (duration: 360.834696ms)","trace[54379794] 'applied index is now lower than readState.Index' (duration: 5.305448ms)"],"step_count":2} {"level":"info","ts":"2026-08-21T03:27:40.09302Z","caller":"traceutil/trace.go:171","msg":"trace[609364135] transaction","detail":"{read_only:false; response_revision:28295; number_of_response:1; }","duration":"341.097169ms","start":"2026-08-21T03:27:39.751881Z","end":"2026-08-21T03:27:40.092979Z","steps":["trace[609364135] 'process raft request' (duration: 340.668377ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:27:40.093093Z","caller":"traceutil/trace.go:171","msg":"trace[1392100273] transaction","detail":"{read_only:false; response_revision:28296; number_of_response:1; }","duration":"331.866342ms","start":"2026-08-21T03:27:39.761218Z","end":"2026-08-21T03:27:40.093084Z","steps":["trace[1392100273] 'process raft request' (duration: 331.542283ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:40.093102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.425923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:27:40.093147Z","caller":"traceutil/trace.go:171","msg":"trace[1856368480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:28299; }","duration":"366.549415ms","start":"2026-08-21T03:27:39.726585Z","end":"2026-08-21T03:27:40.093135Z","steps":["trace[1856368480] 'agreement among raft nodes before linearized reading' (duration: 366.388711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:40.09317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:39.751848Z","time spent":"341.243533ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T03:27:40.093181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:39.726266Z","time spent":"366.906035ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-21T03:27:40.093255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:39.761183Z","time spent":"331.953414ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T03:27:40.093017Z","caller":"traceutil/trace.go:171","msg":"trace[2014423899] transaction","detail":"{read_only:false; response_revision:28299; number_of_response:1; }","duration":"317.168172ms","start":"2026-08-21T03:27:39.77582Z","end":"2026-08-21T03:27:40.092989Z","steps":["trace[2014423899] 'process raft request' (duration: 317.121561ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:40.093491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:39.775814Z","time spent":"317.651666ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T03:27:40.093552Z","caller":"traceutil/trace.go:171","msg":"trace[1419488681] transaction","detail":"{read_only:false; response_revision:28297; number_of_response:1; }","duration":"331.40581ms","start":"2026-08-21T03:27:39.762136Z","end":"2026-08-21T03:27:40.093542Z","steps":["trace[1419488681] 'process raft request' (duration: 330.730271ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:40.093603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:39.762124Z","time spent":"331.452011ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-21T03:27:40.09363Z","caller":"traceutil/trace.go:171","msg":"trace[636889644] transaction","detail":"{read_only:false; response_revision:28298; number_of_response:1; }","duration":"326.552354ms","start":"2026-08-21T03:27:39.767064Z","end":"2026-08-21T03:27:40.093616Z","steps":["trace[636889644] 'process raft request' (duration: 325.841304ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:40.093739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:39.767052Z","time spent":"326.607505ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-21T03:27:40.09679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.798984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:27:40.096877Z","caller":"traceutil/trace.go:171","msg":"trace[659428859] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:28301; }","duration":"334.919187ms","start":"2026-08-21T03:27:39.761938Z","end":"2026-08-21T03:27:40.096857Z","steps":["trace[659428859] 'agreement among raft nodes before linearized reading' (duration: 334.67742ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:40.096921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:39.761934Z","time spent":"334.978668ms","remote":"127.0.0.1:58452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" "} {"level":"warn","ts":"2026-08-21T03:27:40.096957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.960338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-08-21T03:27:40.097076Z","caller":"traceutil/trace.go:171","msg":"trace[710464297] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:28301; }","duration":"335.122452ms","start":"2026-08-21T03:27:39.761926Z","end":"2026-08-21T03:27:40.097049Z","steps":["trace[710464297] 'agreement among raft nodes before linearized reading' (duration: 334.69282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:40.097119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:39.76191Z","time spent":"335.200765ms","remote":"127.0.0.1:58452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5104,"request content":"key:\"/registry/jobs/openstack/magnum-db-sync\" "} {"level":"warn","ts":"2026-08-21T03:27:40.097182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.471533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:27:40.097213Z","caller":"traceutil/trace.go:171","msg":"trace[113549638] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:28301; }","duration":"330.521954ms","start":"2026-08-21T03:27:39.766683Z","end":"2026-08-21T03:27:40.097204Z","steps":["trace[113549638] 'agreement among raft nodes before linearized reading' (duration: 330.476873ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:40.097232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:39.766671Z","time spent":"330.556705ms","remote":"127.0.0.1:58452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" "} {"level":"warn","ts":"2026-08-21T03:27:40.097261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.499953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-21T03:27:40.09727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.472792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-21T03:27:40.0973Z","caller":"traceutil/trace.go:171","msg":"trace[1818579695] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:28301; }","duration":"335.537684ms","start":"2026-08-21T03:27:39.76175Z","end":"2026-08-21T03:27:40.097287Z","steps":["trace[1818579695] 'agreement among raft nodes before linearized reading' (duration: 335.460532ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:40.097309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.494843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-21T03:27:40.097283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.620793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-21T03:27:40.097329Z","caller":"traceutil/trace.go:171","msg":"trace[82735109] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:28301; }","duration":"335.524964ms","start":"2026-08-21T03:27:39.761798Z","end":"2026-08-21T03:27:40.097323Z","steps":["trace[82735109] 'agreement among raft nodes before linearized reading' (duration: 335.489743ms)"],"step_count":1} {"level":"info","ts":"2026-08-21T03:27:40.097325Z","caller":"traceutil/trace.go:171","msg":"trace[585176769] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:28301; }","duration":"321.545683ms","start":"2026-08-21T03:27:39.775765Z","end":"2026-08-21T03:27:40.09731Z","steps":["trace[585176769] 'agreement among raft nodes before linearized reading' (duration: 321.393619ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:40.097355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:39.761793Z","time spent":"335.558225ms","remote":"127.0.0.1:58452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" "} {"level":"warn","ts":"2026-08-21T03:27:40.097361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.556025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:27:40.097361Z","caller":"traceutil/trace.go:171","msg":"trace[2000904882] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:28301; }","duration":"318.728985ms","start":"2026-08-21T03:27:39.778614Z","end":"2026-08-21T03:27:40.097343Z","steps":["trace[2000904882] 'agreement among raft nodes before linearized reading' (duration: 318.567721ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:40.097367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:39.775757Z","time spent":"321.602666ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-21T03:27:40.097383Z","caller":"traceutil/trace.go:171","msg":"trace[2074341213] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:28301; }","duration":"335.587195ms","start":"2026-08-21T03:27:39.761788Z","end":"2026-08-21T03:27:40.097375Z","steps":["trace[2074341213] 'agreement among raft nodes before linearized reading' (duration: 335.553374ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:40.097401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:39.778608Z","time spent":"318.785767ms","remote":"127.0.0.1:58620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-08-21T03:27:40.097409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:39.761779Z","time spent":"335.625077ms","remote":"127.0.0.1:58452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-ks-user\" "} {"level":"warn","ts":"2026-08-21T03:27:40.097417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.581965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:27:40.097434Z","caller":"traceutil/trace.go:171","msg":"trace[1261003603] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:28301; }","duration":"335.606696ms","start":"2026-08-21T03:27:39.761822Z","end":"2026-08-21T03:27:40.097428Z","steps":["trace[1261003603] 'agreement among raft nodes before linearized reading' (duration: 335.579135ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:40.097449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:39.761818Z","time spent":"335.627496ms","remote":"127.0.0.1:58452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" "} {"level":"warn","ts":"2026-08-21T03:27:40.097488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.695568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:27:40.097509Z","caller":"traceutil/trace.go:171","msg":"trace[483062450] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:28301; }","duration":"335.72666ms","start":"2026-08-21T03:27:39.761775Z","end":"2026-08-21T03:27:40.097501Z","steps":["trace[483062450] 'agreement among raft nodes before linearized reading' (duration: 335.689849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:40.097325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:39.761727Z","time spent":"335.592786ms","remote":"127.0.0.1:58452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-ks-user\" "} {"level":"warn","ts":"2026-08-21T03:27:40.097526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:39.761769Z","time spent":"335.75346ms","remote":"127.0.0.1:58452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" "} {"level":"warn","ts":"2026-08-21T03:27:40.097641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.263834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-21T03:27:40.097666Z","caller":"traceutil/trace.go:171","msg":"trace[837040927] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:28301; }","duration":"336.315276ms","start":"2026-08-21T03:27:39.761344Z","end":"2026-08-21T03:27:40.097659Z","steps":["trace[837040927] 'agreement among raft nodes before linearized reading' (duration: 336.268125ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:40.097686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:39.761336Z","time spent":"336.346137ms","remote":"127.0.0.1:58452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" "} {"level":"warn","ts":"2026-08-21T03:27:40.097713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.966066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-08-21T03:27:40.097799Z","caller":"traceutil/trace.go:171","msg":"trace[972981275] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:28301; }","duration":"336.101849ms","start":"2026-08-21T03:27:39.761675Z","end":"2026-08-21T03:27:40.097777Z","steps":["trace[972981275] 'agreement among raft nodes before linearized reading' (duration: 335.837122ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:40.097848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:39.761643Z","time spent":"336.195522ms","remote":"127.0.0.1:58452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5104,"request content":"key:\"/registry/jobs/openstack/magnum-db-sync\" "} {"level":"warn","ts":"2026-08-21T03:27:40.098172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.289146ms","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-21T03:27:40.098216Z","caller":"traceutil/trace.go:171","msg":"trace[123314754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:28301; }","duration":"186.361058ms","start":"2026-08-21T03:27:39.911842Z","end":"2026-08-21T03:27:40.098203Z","steps":["trace[123314754] 'agreement among raft nodes before linearized reading' (duration: 185.277798ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:40.098814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.94656ms","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-21T03:27:40.098859Z","caller":"traceutil/trace.go:171","msg":"trace[2130288053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:28301; }","duration":"306.009362ms","start":"2026-08-21T03:27:39.79284Z","end":"2026-08-21T03:27:40.09885Z","steps":["trace[2130288053] 'agreement among raft nodes before linearized reading' (duration: 304.290524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-21T03:27:40.098884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-21T03:27:39.792833Z","time spent":"306.045633ms","remote":"127.0.0.1:58474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}