{"level":"warn","ts":"2026-06-05T23:24:29.031327Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T23:24:29.031408Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242: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.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242: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-06-05T23:24:29.031473Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T23:24:29.031485Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-06-05T23:24:29.031503Z","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-06-05T23:24:29.032182Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-06-05T23:24:29.032256Z","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":8,"max-cpu-available":8,"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.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242: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-06-05T23:24:29.036851Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.39785ms"} {"level":"info","ts":"2026-06-05T23:24:29.04302Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-06-05T23:24:29.043093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-06-05T23:24:29.043116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-06-05T23:24:29.043136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-05T23:24:29.043152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-06-05T23:24:29.043183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-06-05T23:24:29.049098Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-05T23:24:29.051003Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-05T23:24:29.052132Z","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-06-05T23:24:29.055991Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-05T23:24:29.056118Z","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-06-05T23:24:29.05621Z","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-06-05T23:24:29.056226Z","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-06-05T23:24:29.05619Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-05T23:24:29.062976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-06-05T23:24:29.06303Z","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-06-05T23:24:29.063084Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-06-05T23:24:29.063118Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-06-05T23:24:29.063146Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-06-05T23:24:29.063468Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-05T23:24:29.063504Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-05T23:24:29.492888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-06-05T23:24:29.492955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-05T23:24:29.493191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-06-05T23:24:29.493207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-06-05T23:24:29.493216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-06-05T23:24:29.493224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-06-05T23:24:29.493234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-06-05T23:24:29.49844Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T23:24:29.502668Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T23:24:29.502669Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-06-05T23:24:29.503062Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T23:24:29.503159Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T23:24:29.503182Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T23:24:29.503303Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T23:24:29.50464Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-05T23:24:29.50466Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-05T23:24:29.505184Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-05T23:24:29.506081Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-06-05T23:24:38.622248Z","caller":"traceutil/trace.go:171","msg":"trace[651366863] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:382; }","duration":"120.093548ms","start":"2026-06-05T23:24:38.502125Z","end":"2026-06-05T23:24:38.622219Z","steps":["trace[651366863] 'read index received' (duration: 119.203339ms)","trace[651366863] 'applied index is now lower than readState.Index' (duration: 889.189µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T23:24:38.622476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.291491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-06-05T23:24:38.622548Z","caller":"traceutil/trace.go:171","msg":"trace[439479086] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:371; }","duration":"120.448995ms","start":"2026-06-05T23:24:38.502083Z","end":"2026-06-05T23:24:38.622532Z","steps":["trace[439479086] 'agreement among raft nodes before linearized reading' (duration: 120.287201ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:24:38.622505Z","caller":"traceutil/trace.go:171","msg":"trace[603594808] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"141.008448ms","start":"2026-06-05T23:24:38.481443Z","end":"2026-06-05T23:24:38.622452Z","steps":["trace[603594808] 'process raft request' (duration: 139.915296ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:24:38.62259Z","caller":"traceutil/trace.go:171","msg":"trace[1188691468] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"140.186142ms","start":"2026-06-05T23:24:38.482191Z","end":"2026-06-05T23:24:38.622377Z","steps":["trace[1188691468] 'process raft request' (duration: 139.986598ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:24:38.790449Z","caller":"traceutil/trace.go:171","msg":"trace[1563669041] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"158.337426ms","start":"2026-06-05T23:24:38.632088Z","end":"2026-06-05T23:24:38.790426Z","steps":["trace[1563669041] 'process raft request' (duration: 147.755138ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:24:38.820983Z","caller":"traceutil/trace.go:171","msg":"trace[2140602191] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"187.67505ms","start":"2026-06-05T23:24:38.633278Z","end":"2026-06-05T23:24:38.820953Z","steps":["trace[2140602191] 'process raft request' (duration: 187.449426ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:24:38.821186Z","caller":"traceutil/trace.go:171","msg":"trace[1984260037] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"183.143728ms","start":"2026-06-05T23:24:38.638032Z","end":"2026-06-05T23:24:38.821176Z","steps":["trace[1984260037] 'process raft request' (duration: 182.844851ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:24:43.055993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:24:42.557224Z","time spent":"498.759217ms","remote":"127.0.0.1:48906","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-05T23:25:07.819761Z","caller":"traceutil/trace.go:171","msg":"trace[1946460452] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"202.250849ms","start":"2026-06-05T23:25:07.617484Z","end":"2026-06-05T23:25:07.819735Z","steps":["trace[1946460452] 'process raft request' (duration: 201.842685ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:25:07.820026Z","caller":"traceutil/trace.go:171","msg":"trace[1722302237] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"197.94488ms","start":"2026-06-05T23:25:07.622012Z","end":"2026-06-05T23:25:07.819957Z","steps":["trace[1722302237] 'process raft request' (duration: 197.586217ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:25:07.820161Z","caller":"traceutil/trace.go:171","msg":"trace[1889135017] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"178.371003ms","start":"2026-06-05T23:25:07.641594Z","end":"2026-06-05T23:25:07.819965Z","steps":["trace[1889135017] 'process raft request' (duration: 178.217128ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:25:07.820298Z","caller":"traceutil/trace.go:171","msg":"trace[1219477965] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"194.813021ms","start":"2026-06-05T23:25:07.625158Z","end":"2026-06-05T23:25:07.819971Z","steps":["trace[1219477965] 'process raft request' (duration: 194.577893ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:25:07.820408Z","caller":"traceutil/trace.go:171","msg":"trace[354235336] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"179.99439ms","start":"2026-06-05T23:25:07.639981Z","end":"2026-06-05T23:25:07.819975Z","steps":["trace[354235336] 'process raft request' (duration: 179.794343ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:25:07.825976Z","caller":"traceutil/trace.go:171","msg":"trace[1558430440] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"178.381123ms","start":"2026-06-05T23:25:07.647577Z","end":"2026-06-05T23:25:07.825958Z","steps":["trace[1558430440] 'process raft request' (duration: 177.98068ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:25:07.826245Z","caller":"traceutil/trace.go:171","msg":"trace[826543463] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"177.011997ms","start":"2026-06-05T23:25:07.649221Z","end":"2026-06-05T23:25:07.826233Z","steps":["trace[826543463] 'process raft request' (duration: 176.430377ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:25:07.826532Z","caller":"traceutil/trace.go:171","msg":"trace[2107708692] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"175.081149ms","start":"2026-06-05T23:25:07.651439Z","end":"2026-06-05T23:25:07.826521Z","steps":["trace[2107708692] 'process raft request' (duration: 174.643574ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:25:07.82724Z","caller":"traceutil/trace.go:171","msg":"trace[481333786] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"160.480975ms","start":"2026-06-05T23:25:07.666747Z","end":"2026-06-05T23:25:07.827228Z","steps":["trace[481333786] 'process raft request' (duration: 159.711798ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:25:07.827801Z","caller":"traceutil/trace.go:171","msg":"trace[356982572] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"157.874565ms","start":"2026-06-05T23:25:07.669915Z","end":"2026-06-05T23:25:07.82779Z","steps":["trace[356982572] 'process raft request' (duration: 157.074197ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:25:07.829497Z","caller":"traceutil/trace.go:171","msg":"trace[633432092] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"151.453864ms","start":"2026-06-05T23:25:07.678029Z","end":"2026-06-05T23:25:07.829483Z","steps":["trace[633432092] 'process raft request' (duration: 150.74525ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:25:13.918156Z","caller":"traceutil/trace.go:171","msg":"trace[836640614] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"172.634664ms","start":"2026-06-05T23:25:13.745495Z","end":"2026-06-05T23:25:13.918129Z","steps":["trace[836640614] 'process raft request' (duration: 172.433438ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:25:14.605996Z","caller":"traceutil/trace.go:171","msg":"trace[1444031560] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"276.302831ms","start":"2026-06-05T23:25:14.329662Z","end":"2026-06-05T23:25:14.605965Z","steps":["trace[1444031560] 'process raft request' (duration: 275.517695ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:25:15.124296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.553505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-05T23:25:15.124499Z","caller":"traceutil/trace.go:171","msg":"trace[421665026] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"462.238259ms","start":"2026-06-05T23:25:14.662225Z","end":"2026-06-05T23:25:15.124463Z","steps":["trace[421665026] 'process raft request' (duration: 306.033332ms)","trace[421665026] 'compare' (duration: 155.368549ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T23:25:15.124736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:25:14.662198Z","time spent":"462.423484ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T23:25:15.12488Z","caller":"traceutil/trace.go:171","msg":"trace[752835205] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"149.462853ms","start":"2026-06-05T23:25:14.975367Z","end":"2026-06-05T23:25:15.12483Z","steps":["trace[752835205] 'process raft request' (duration: 149.04735ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:25:29.80059Z","caller":"traceutil/trace.go:171","msg":"trace[1557868822] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"301.147655ms","start":"2026-06-05T23:25:29.499412Z","end":"2026-06-05T23:25:29.800559Z","steps":["trace[1557868822] 'process raft request' (duration: 252.641134ms)","trace[1557868822] 'compare' (duration: 48.432628ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T23:25:29.801258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:25:29.499384Z","time spent":"301.257878ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:25:41.994962Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375023776,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T23:25:42.495517Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375023776,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T23:25:43.19139Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375023776,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T23:25:43.254961Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.761763665s","expected-duration":"1s"} {"level":"info","ts":"2026-06-05T23:25:43.25626Z","caller":"traceutil/trace.go:171","msg":"trace[979291831] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"1.763149136s","start":"2026-06-05T23:25:41.493081Z","end":"2026-06-05T23:25:43.25623Z","steps":["trace[979291831] 'process raft request' (duration: 1.762994742s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:25:43.256426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:25:41.493056Z","time spent":"1.76327316s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:25:43.262987Z","caller":"traceutil/trace.go:171","msg":"trace[1274042039] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"1.767140773s","start":"2026-06-05T23:25:41.495815Z","end":"2026-06-05T23:25:43.262955Z","steps":["trace[1274042039] 'process raft request' (duration: 1.766935597s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:25:43.263168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:25:41.495801Z","time spent":"1.767280148s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:25:43.263382Z","caller":"traceutil/trace.go:171","msg":"trace[2067813202] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"1.39974606s","start":"2026-06-05T23:25:41.863593Z","end":"2026-06-05T23:25:43.263339Z","steps":["trace[2067813202] 'process raft request' (duration: 1.399307007s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:25:43.263598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:25:41.863573Z","time spent":"1.399940945s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:25:43.263722Z","caller":"traceutil/trace.go:171","msg":"trace[1259766743] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"1.203319621s","start":"2026-06-05T23:25:42.060362Z","end":"2026-06-05T23:25:43.263682Z","steps":["trace[1259766743] 'process raft request' (duration: 1.202865677s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:25:43.26391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:25:42.060347Z","time spent":"1.203478435s","remote":"127.0.0.1:48964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":813,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-05T23:25:43.264357Z","caller":"traceutil/trace.go:171","msg":"trace[1786390780] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:956; }","duration":"1.770284146s","start":"2026-06-05T23:25:41.494047Z","end":"2026-06-05T23:25:43.264331Z","steps":["trace[1786390780] 'read index received' (duration: 1.762002912s)","trace[1786390780] 'applied index is now lower than readState.Index' (duration: 8.279974ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:25:43.264522Z","caller":"traceutil/trace.go:171","msg":"trace[1911562360] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"1.060193304s","start":"2026-06-05T23:25:42.20429Z","end":"2026-06-05T23:25:43.264483Z","steps":["trace[1911562360] 'process raft request' (duration: 1.059300448s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:25:43.264701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.770636627s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-05T23:25:43.264747Z","caller":"traceutil/trace.go:171","msg":"trace[479791380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:927; }","duration":"1.77071147s","start":"2026-06-05T23:25:41.494024Z","end":"2026-06-05T23:25:43.264735Z","steps":["trace[479791380] 'agreement among raft nodes before linearized reading' (duration: 1.770546375s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:25:43.26479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:25:41.494013Z","time spent":"1.770769571s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-05T23:25:43.26493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.769788802s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:25:43.265386Z","caller":"traceutil/trace.go:171","msg":"trace[741170602] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:0; response_revision:927; }","duration":"1.770268106s","start":"2026-06-05T23:25:41.495104Z","end":"2026-06-05T23:25:43.265372Z","steps":["trace[741170602] 'agreement among raft nodes before linearized reading' (duration: 1.769766171s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:25:43.265069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:25:42.204274Z","time spent":"1.060607346s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:25:43.265515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:25:41.495096Z","time spent":"1.770366838s","remote":"127.0.0.1:49244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"warn","ts":"2026-06-05T23:25:43.265142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.578303ms","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-06-05T23:25:43.265729Z","caller":"traceutil/trace.go:171","msg":"trace[352543448] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:927; }","duration":"715.165021ms","start":"2026-06-05T23:25:42.550508Z","end":"2026-06-05T23:25:43.265673Z","steps":["trace[352543448] 'agreement among raft nodes before linearized reading' (duration: 714.513512ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:25:43.265244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.578917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-05T23:25:43.265893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:25:42.550487Z","time spent":"715.345976ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-05T23:25:43.265268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007955787s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:25:43.265968Z","caller":"traceutil/trace.go:171","msg":"trace[850060117] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:927; }","duration":"707.240367ms","start":"2026-06-05T23:25:42.558615Z","end":"2026-06-05T23:25:43.265855Z","steps":["trace[850060117] 'agreement among raft nodes before linearized reading' (duration: 706.562717ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:25:43.266073Z","caller":"traceutil/trace.go:171","msg":"trace[86073424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:927; }","duration":"1.008748331s","start":"2026-06-05T23:25:42.257271Z","end":"2026-06-05T23:25:43.266019Z","steps":["trace[86073424] 'agreement among raft nodes before linearized reading' (duration: 1.007862514s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:25:43.26616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:25:42.257259Z","time spent":"1.008886334s","remote":"127.0.0.1:48850","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-06-05T23:25:43.265329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403007866s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-05T23:25:43.266078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:25:42.558586Z","time spent":"707.469154ms","remote":"127.0.0.1:48906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.242\" "} {"level":"info","ts":"2026-06-05T23:25:43.26679Z","caller":"traceutil/trace.go:171","msg":"trace[28069341] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:927; }","duration":"1.4045011s","start":"2026-06-05T23:25:41.862275Z","end":"2026-06-05T23:25:43.266776Z","steps":["trace[28069341] 'agreement among raft nodes before linearized reading' (duration: 1.403000046s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:25:43.26691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:25:41.862253Z","time spent":"1.404643494s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-05T23:26:04.267285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.112687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/cert-manager/cert-manager-startupapicheck-rlkzx\" ","response":"range_response_count:1 size:4165"} {"level":"info","ts":"2026-06-05T23:26:04.267374Z","caller":"traceutil/trace.go:171","msg":"trace[2042340802] range","detail":"{range_begin:/registry/pods/cert-manager/cert-manager-startupapicheck-rlkzx; range_end:; response_count:1; response_revision:1220; }","duration":"201.275861ms","start":"2026-06-05T23:26:04.066078Z","end":"2026-06-05T23:26:04.267354Z","steps":["trace[2042340802] 'range keys from in-memory index tree' (duration: 200.85836ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:26:06.61983Z","caller":"traceutil/trace.go:171","msg":"trace[1020091780] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1281; }","duration":"350.550082ms","start":"2026-06-05T23:26:06.269255Z","end":"2026-06-05T23:26:06.619805Z","steps":["trace[1020091780] 'read index received' (duration: 350.324856ms)","trace[1020091780] 'applied index is now lower than readState.Index' (duration: 224.396µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T23:26:06.61997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.687637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:26:06.619939Z","caller":"traceutil/trace.go:171","msg":"trace[839064992] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"432.269538ms","start":"2026-06-05T23:26:06.187609Z","end":"2026-06-05T23:26:06.619878Z","steps":["trace[839064992] 'process raft request' (duration: 432.033232ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:26:06.620036Z","caller":"traceutil/trace.go:171","msg":"trace[325896081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1241; }","duration":"350.81438ms","start":"2026-06-05T23:26:06.269208Z","end":"2026-06-05T23:26:06.620023Z","steps":["trace[325896081] 'agreement among raft nodes before linearized reading' (duration: 350.702176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:26:06.620077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:26:06.269189Z","time spent":"350.879632ms","remote":"127.0.0.1:48850","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-06-05T23:26:06.62017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:26:06.187583Z","time spent":"432.467443ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:26:23.748238Z","caller":"traceutil/trace.go:171","msg":"trace[1390556282] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"474.996237ms","start":"2026-06-05T23:26:23.273217Z","end":"2026-06-05T23:26:23.748213Z","steps":["trace[1390556282] 'process raft request' (duration: 474.846753ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:26:23.748388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:26:23.27318Z","time spent":"475.134191ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3649,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:26:23.748992Z","caller":"traceutil/trace.go:171","msg":"trace[859231585] linearizableReadLoop","detail":"{readStateIndex:1413; appliedIndex:1411; }","duration":"314.661276ms","start":"2026-06-05T23:26:23.434312Z","end":"2026-06-05T23:26:23.748973Z","steps":["trace[859231585] 'read index received' (duration: 313.69883ms)","trace[859231585] 'applied index is now lower than readState.Index' (duration: 961.716µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:26:23.749102Z","caller":"traceutil/trace.go:171","msg":"trace[211994107] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"319.545134ms","start":"2026-06-05T23:26:23.429548Z","end":"2026-06-05T23:26:23.749093Z","steps":["trace[211994107] 'process raft request' (duration: 319.359818ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:26:23.749175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:26:23.429532Z","time spent":"319.600635ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:26:23.749401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.093847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:26:23.749442Z","caller":"traceutil/trace.go:171","msg":"trace[248096972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1364; }","duration":"315.170719ms","start":"2026-06-05T23:26:23.43426Z","end":"2026-06-05T23:26:23.749431Z","steps":["trace[248096972] 'agreement among raft nodes before linearized reading' (duration: 315.080836ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:26:23.749649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:26:23.43424Z","time spent":"315.397825ms","remote":"127.0.0.1:48850","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-06-05T23:26:23.749956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.47964ms","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-06-05T23:26:23.750046Z","caller":"traceutil/trace.go:171","msg":"trace[233477362] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1364; }","duration":"267.632214ms","start":"2026-06-05T23:26:23.482391Z","end":"2026-06-05T23:26:23.750023Z","steps":["trace[233477362] 'agreement among raft nodes before linearized reading' (duration: 267.364017ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:26:23.75007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.977999ms","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-06-05T23:26:23.750107Z","caller":"traceutil/trace.go:171","msg":"trace[84821523] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1364; }","duration":"222.03145ms","start":"2026-06-05T23:26:23.528063Z","end":"2026-06-05T23:26:23.750094Z","steps":["trace[84821523] 'agreement among raft nodes before linearized reading' (duration: 221.952128ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:26:23.749982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.254636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-05T23:26:23.750214Z","caller":"traceutil/trace.go:171","msg":"trace[626790823] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1364; }","duration":"222.510293ms","start":"2026-06-05T23:26:23.527695Z","end":"2026-06-05T23:26:23.750205Z","steps":["trace[626790823] 'agreement among raft nodes before linearized reading' (duration: 222.181164ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:26:23.75023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.679869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-05T23:26:23.750238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.562395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-05T23:26:23.750321Z","caller":"traceutil/trace.go:171","msg":"trace[1800863565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1364; }","duration":"222.670147ms","start":"2026-06-05T23:26:23.527635Z","end":"2026-06-05T23:26:23.750305Z","steps":["trace[1800863565] 'agreement among raft nodes before linearized reading' (duration: 222.451102ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:26:23.750259Z","caller":"traceutil/trace.go:171","msg":"trace[1387630449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1364; }","duration":"266.749611ms","start":"2026-06-05T23:26:23.483501Z","end":"2026-06-05T23:26:23.75025Z","steps":["trace[1387630449] 'agreement among raft nodes before linearized reading' (duration: 266.666129ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:26:29.870092Z","caller":"traceutil/trace.go:171","msg":"trace[1662131661] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"569.415368ms","start":"2026-06-05T23:26:29.300646Z","end":"2026-06-05T23:26:29.870061Z","steps":["trace[1662131661] 'process raft request' (duration: 569.076999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:26:29.870335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:26:29.300628Z","time spent":"569.582912ms","remote":"127.0.0.1:49120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3637,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:26:31.186494Z","caller":"traceutil/trace.go:171","msg":"trace[406867475] transaction","detail":"{read_only:false; response_revision:1506; number_of_response:1; }","duration":"230.48983ms","start":"2026-06-05T23:26:30.955972Z","end":"2026-06-05T23:26:31.186461Z","steps":["trace[406867475] 'process raft request' (duration: 229.73217ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:26:42.077152Z","caller":"traceutil/trace.go:171","msg":"trace[1749593353] linearizableReadLoop","detail":"{readStateIndex:1804; appliedIndex:1803; }","duration":"103.427047ms","start":"2026-06-05T23:26:41.973701Z","end":"2026-06-05T23:26:42.077128Z","steps":["trace[1749593353] 'read index received' (duration: 103.149489ms)","trace[1749593353] 'applied index is now lower than readState.Index' (duration: 276.538µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T23:26:42.077425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.699453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-05T23:26:42.077523Z","caller":"traceutil/trace.go:171","msg":"trace[146205292] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1731; }","duration":"103.842307ms","start":"2026-06-05T23:26:41.973659Z","end":"2026-06-05T23:26:42.077501Z","steps":["trace[146205292] 'agreement among raft nodes before linearized reading' (duration: 103.608351ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:26:42.077502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.139159ms","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-06-05T23:26:42.077926Z","caller":"traceutil/trace.go:171","msg":"trace[465777457] transaction","detail":"{read_only:false; response_revision:1731; number_of_response:1; }","duration":"161.689754ms","start":"2026-06-05T23:26:41.91553Z","end":"2026-06-05T23:26:42.07722Z","steps":["trace[465777457] 'process raft request' (duration: 161.410886ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:26:42.077933Z","caller":"traceutil/trace.go:171","msg":"trace[3212064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1731; }","duration":"103.619351ms","start":"2026-06-05T23:26:41.974291Z","end":"2026-06-05T23:26:42.07791Z","steps":["trace[3212064] 'agreement among raft nodes before linearized reading' (duration: 103.043617ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:26:52.264867Z","caller":"traceutil/trace.go:171","msg":"trace[886132766] linearizableReadLoop","detail":"{readStateIndex:1965; appliedIndex:1964; }","duration":"105.853096ms","start":"2026-06-05T23:26:52.158987Z","end":"2026-06-05T23:26:52.26484Z","steps":["trace[886132766] 'read index received' (duration: 105.59561ms)","trace[886132766] 'applied index is now lower than readState.Index' (duration: 255.926µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:26:52.264964Z","caller":"traceutil/trace.go:171","msg":"trace[1415084016] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"146.889236ms","start":"2026-06-05T23:26:52.118052Z","end":"2026-06-05T23:26:52.264942Z","steps":["trace[1415084016] 'process raft request' (duration: 146.61564ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:26:52.265302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.293409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-05T23:26:52.26541Z","caller":"traceutil/trace.go:171","msg":"trace[1364255531] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1889; }","duration":"106.454742ms","start":"2026-06-05T23:26:52.158928Z","end":"2026-06-05T23:26:52.265383Z","steps":["trace[1364255531] 'agreement among raft nodes before linearized reading' (duration: 106.049452ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:26:52.265469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.176569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-06-05T23:26:52.265476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.667292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-05T23:26:52.265523Z","caller":"traceutil/trace.go:171","msg":"trace[1627217350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1889; }","duration":"101.273821ms","start":"2026-06-05T23:26:52.164235Z","end":"2026-06-05T23:26:52.265508Z","steps":["trace[1627217350] 'agreement among raft nodes before linearized reading' (duration: 101.112598ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:26:52.265581Z","caller":"traceutil/trace.go:171","msg":"trace[1205642221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1889; }","duration":"105.814556ms","start":"2026-06-05T23:26:52.159739Z","end":"2026-06-05T23:26:52.265554Z","steps":["trace[1205642221] 'agreement among raft nodes before linearized reading' (duration: 105.522999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:26:52.265704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.191141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-05T23:26:52.265738Z","caller":"traceutil/trace.go:171","msg":"trace[1270084266] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1889; }","duration":"105.271153ms","start":"2026-06-05T23:26:52.160459Z","end":"2026-06-05T23:26:52.26573Z","steps":["trace[1270084266] 'agreement among raft nodes before linearized reading' (duration: 105.19145ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:26:52.568813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.421823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T23:26:52.569051Z","caller":"traceutil/trace.go:171","msg":"trace[1534437076] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"299.475457ms","start":"2026-06-05T23:26:52.269554Z","end":"2026-06-05T23:26:52.56903Z","steps":["trace[1534437076] 'process raft request' (duration: 70.693785ms)","trace[1534437076] 'compare' (duration: 228.106585ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:26:52.569475Z","caller":"traceutil/trace.go:171","msg":"trace[1481570474] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"298.910953ms","start":"2026-06-05T23:26:52.270555Z","end":"2026-06-05T23:26:52.569466Z","steps":["trace[1481570474] 'process raft request' (duration: 298.39474ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:26:52.571286Z","caller":"traceutil/trace.go:171","msg":"trace[754602704] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"300.203824ms","start":"2026-06-05T23:26:52.271026Z","end":"2026-06-05T23:26:52.57123Z","steps":["trace[754602704] 'process raft request' (duration: 299.998689ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:26:52.571401Z","caller":"traceutil/trace.go:171","msg":"trace[1216266960] linearizableReadLoop","detail":"{readStateIndex:1970; appliedIndex:1965; }","duration":"170.56001ms","start":"2026-06-05T23:26:52.400761Z","end":"2026-06-05T23:26:52.571321Z","steps":["trace[1216266960] 'read index received' (duration: 158.731644ms)","trace[1216266960] 'applied index is now lower than readState.Index' (duration: 11.826986ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:26:52.571612Z","caller":"traceutil/trace.go:171","msg":"trace[223190903] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"298.904563ms","start":"2026-06-05T23:26:52.272675Z","end":"2026-06-05T23:26:52.57158Z","steps":["trace[223190903] 'process raft request' (duration: 298.483782ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:26:52.572169Z","caller":"traceutil/trace.go:171","msg":"trace[1013191251] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"301.2443ms","start":"2026-06-05T23:26:52.270901Z","end":"2026-06-05T23:26:52.572145Z","steps":["trace[1013191251] 'process raft request' (duration: 298.513782ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:26:52.572276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:26:52.270888Z","time spent":"301.314103ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:26:52.572304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.533065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3bce69cf-4a6d-459a-933f-c32de5c5d2cf\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-05T23:26:52.572402Z","caller":"traceutil/trace.go:171","msg":"trace[1862879682] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3bce69cf-4a6d-459a-933f-c32de5c5d2cf; range_end:; response_count:1; response_revision:1894; }","duration":"171.649998ms","start":"2026-06-05T23:26:52.40071Z","end":"2026-06-05T23:26:52.57236Z","steps":["trace[1862879682] 'agreement among raft nodes before linearized reading' (duration: 171.104754ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:26:52.572919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:26:52.271016Z","time spent":"300.337459ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:26:52.614876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.766249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18b6530b1319a8c2\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-06-05T23:26:52.615719Z","caller":"traceutil/trace.go:171","msg":"trace[1659913937] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18b6530b1319a8c2; range_end:; response_count:1; response_revision:1897; }","duration":"148.637471ms","start":"2026-06-05T23:26:52.46705Z","end":"2026-06-05T23:26:52.615688Z","steps":["trace[1659913937] 'agreement among raft nodes before linearized reading' (duration: 147.704238ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:26:54.566023Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375025669,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T23:26:54.638495Z","caller":"traceutil/trace.go:171","msg":"trace[337912557] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"596.933072ms","start":"2026-06-05T23:26:54.041534Z","end":"2026-06-05T23:26:54.638467Z","steps":["trace[337912557] 'process raft request' (duration: 596.759517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:26:54.63999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:26:54.041517Z","time spent":"597.69775ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:26:54.643609Z","caller":"traceutil/trace.go:171","msg":"trace[1173811636] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"372.461773ms","start":"2026-06-05T23:26:54.271116Z","end":"2026-06-05T23:26:54.643577Z","steps":["trace[1173811636] 'process raft request' (duration: 372.31015ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:26:54.643748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:26:54.271082Z","time spent":"372.595357ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:26:54.643767Z","caller":"traceutil/trace.go:171","msg":"trace[513478662] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"173.03467ms","start":"2026-06-05T23:26:54.470717Z","end":"2026-06-05T23:26:54.643751Z","steps":["trace[513478662] 'process raft request' (duration: 172.828975ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:26:54.643832Z","caller":"traceutil/trace.go:171","msg":"trace[918901851] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"224.440447ms","start":"2026-06-05T23:26:54.419377Z","end":"2026-06-05T23:26:54.643818Z","steps":["trace[918901851] 'process raft request' (duration: 224.12857ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:26:54.643944Z","caller":"traceutil/trace.go:171","msg":"trace[848231649] linearizableReadLoop","detail":"{readStateIndex:1982; appliedIndex:1978; }","duration":"578.604633ms","start":"2026-06-05T23:26:54.06533Z","end":"2026-06-05T23:26:54.643935Z","steps":["trace[848231649] 'read index received' (duration: 572.965252ms)","trace[848231649] 'applied index is now lower than readState.Index' (duration: 5.638361ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T23:26:54.644254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.876428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-05T23:26:54.644295Z","caller":"traceutil/trace.go:171","msg":"trace[798669096] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1907; }","duration":"361.95989ms","start":"2026-06-05T23:26:54.282321Z","end":"2026-06-05T23:26:54.644281Z","steps":["trace[798669096] 'agreement among raft nodes before linearized reading' (duration: 361.696384ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:26:54.644317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:26:54.282306Z","time spent":"362.004911ms","remote":"127.0.0.1:58334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-05T23:26:54.646007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.684604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3bce69cf-4a6d-459a-933f-c32de5c5d2cf\" ","response":"range_response_count:1 size:3930"} {"level":"info","ts":"2026-06-05T23:26:54.646048Z","caller":"traceutil/trace.go:171","msg":"trace[624335057] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3bce69cf-4a6d-459a-933f-c32de5c5d2cf; range_end:; response_count:1; response_revision:1907; }","duration":"580.781006ms","start":"2026-06-05T23:26:54.065256Z","end":"2026-06-05T23:26:54.646037Z","steps":["trace[624335057] 'agreement among raft nodes before linearized reading' (duration: 578.733006ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:26:54.646085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:26:54.065186Z","time spent":"580.89391ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3953,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3bce69cf-4a6d-459a-933f-c32de5c5d2cf\" "} {"level":"info","ts":"2026-06-05T23:26:57.62814Z","caller":"traceutil/trace.go:171","msg":"trace[1834059734] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"298.587274ms","start":"2026-06-05T23:26:57.329528Z","end":"2026-06-05T23:26:57.628116Z","steps":["trace[1834059734] 'process raft request' (duration: 298.236175ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:26:57.629516Z","caller":"traceutil/trace.go:171","msg":"trace[488403470] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"281.321403ms","start":"2026-06-05T23:26:57.348139Z","end":"2026-06-05T23:26:57.62946Z","steps":["trace[488403470] 'process raft request' (duration: 280.712689ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:26:59.383485Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375025765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T23:26:59.883989Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375025765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T23:27:00.384697Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375025765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T23:27:00.885242Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375025765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T23:27:01.018609Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.313527605s","expected-duration":"1s"} {"level":"info","ts":"2026-06-05T23:27:01.021568Z","caller":"traceutil/trace.go:171","msg":"trace[1977221869] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"2.316537058s","start":"2026-06-05T23:26:58.705Z","end":"2026-06-05T23:27:01.021537Z","steps":["trace[1977221869] 'process raft request' (duration: 2.313958854s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:01.021747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:26:58.704988Z","time spent":"2.316655392s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:27:01.023211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.780352ms","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-06-05T23:27:01.023553Z","caller":"traceutil/trace.go:171","msg":"trace[530124987] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1950; }","duration":"864.509459ms","start":"2026-06-05T23:27:00.158972Z","end":"2026-06-05T23:27:01.023482Z","steps":["trace[530124987] 'range keys from in-memory index tree' (duration: 861.759201ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:27:01.034941Z","caller":"traceutil/trace.go:171","msg":"trace[1609916733] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"2.328124086s","start":"2026-06-05T23:26:58.706787Z","end":"2026-06-05T23:27:01.034911Z","steps":["trace[1609916733] 'process raft request' (duration: 2.328038603s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:27:01.03503Z","caller":"traceutil/trace.go:171","msg":"trace[989850182] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"2.329241105s","start":"2026-06-05T23:26:58.705714Z","end":"2026-06-05T23:27:01.034955Z","steps":["trace[989850182] 'process raft request' (duration: 2.328880856s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:27:01.034994Z","caller":"traceutil/trace.go:171","msg":"trace[1211646329] transaction","detail":"{read_only:false; response_revision:1953; number_of_response:1; }","duration":"2.328861775s","start":"2026-06-05T23:26:58.706117Z","end":"2026-06-05T23:27:01.034979Z","steps":["trace[1211646329] 'process raft request' (duration: 2.328661669s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:01.035243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:26:58.706779Z","time spent":"2.328333701s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:27:01.0354Z","caller":"traceutil/trace.go:171","msg":"trace[1150751] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"2.32550508s","start":"2026-06-05T23:26:58.709882Z","end":"2026-06-05T23:27:01.035387Z","steps":["trace[1150751] 'process raft request' (duration: 2.324983708s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:27:01.035493Z","caller":"traceutil/trace.go:171","msg":"trace[1403996975] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"1.951243956s","start":"2026-06-05T23:26:59.084229Z","end":"2026-06-05T23:27:01.035473Z","steps":["trace[1403996975] 'process raft request' (duration: 1.950912368s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:27:01.035544Z","caller":"traceutil/trace.go:171","msg":"trace[191784133] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"328.449316ms","start":"2026-06-05T23:27:00.707062Z","end":"2026-06-05T23:27:01.035511Z","steps":["trace[191784133] 'process raft request' (duration: 328.296503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:01.035633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:26:59.084219Z","time spent":"1.951343738s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:27:01.035554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:26:58.706109Z","time spent":"2.329200243s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:27:01.035634Z","caller":"traceutil/trace.go:171","msg":"trace[1377263965] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"325.346839ms","start":"2026-06-05T23:27:00.710236Z","end":"2026-06-05T23:27:01.035583Z","steps":["trace[1377263965] 'process raft request' (duration: 325.168915ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:27:01.035705Z","caller":"traceutil/trace.go:171","msg":"trace[2025623442] linearizableReadLoop","detail":"{readStateIndex:2038; appliedIndex:2029; }","duration":"2.153204562s","start":"2026-06-05T23:26:58.882488Z","end":"2026-06-05T23:27:01.035693Z","steps":["trace[2025623442] 'read index received' (duration: 2.136487557s)","trace[2025623442] 'applied index is now lower than readState.Index' (duration: 16.715485ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T23:27:01.035496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:26:58.709862Z","time spent":"2.325570963s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:27:01.035706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:27:00.707026Z","time spent":"328.598709ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:27:01.035842Z","caller":"traceutil/trace.go:171","msg":"trace[383694822] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"324.382905ms","start":"2026-06-05T23:27:00.711452Z","end":"2026-06-05T23:27:01.035835Z","steps":["trace[383694822] 'process raft request' (duration: 324.076217ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:01.035896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:27:00.711425Z","time spent":"324.439337ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:27:01.035913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.205104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-05T23:27:01.035836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.153336585s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-05T23:27:01.036033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.779138437s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:27:01.036075Z","caller":"traceutil/trace.go:171","msg":"trace[823388462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1959; }","duration":"1.779214078s","start":"2026-06-05T23:26:59.256851Z","end":"2026-06-05T23:27:01.036065Z","steps":["trace[823388462] 'agreement among raft nodes before linearized reading' (duration: 1.779161297s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:01.036077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.351487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T23:27:01.036106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:26:59.25683Z","time spent":"1.779271219s","remote":"127.0.0.1:48850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T23:27:01.036111Z","caller":"traceutil/trace.go:171","msg":"trace[236294761] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1959; }","duration":"670.399498ms","start":"2026-06-05T23:27:00.365702Z","end":"2026-06-05T23:27:01.036101Z","steps":["trace[236294761] 'agreement among raft nodes before linearized reading' (duration: 670.351267ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:27:01.035976Z","caller":"traceutil/trace.go:171","msg":"trace[1296476426] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1959; }","duration":"749.305998ms","start":"2026-06-05T23:27:00.286655Z","end":"2026-06-05T23:27:01.035961Z","steps":["trace[1296476426] 'agreement among raft nodes before linearized reading' (duration: 749.195835ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:01.036158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:27:00.286639Z","time spent":"749.508912ms","remote":"127.0.0.1:59814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-05T23:27:01.036146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.12827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11665"} {"level":"warn","ts":"2026-06-05T23:27:01.036157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:27:00.365694Z","time spent":"670.449449ms","remote":"127.0.0.1:34086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-06-05T23:27:01.036201Z","caller":"traceutil/trace.go:171","msg":"trace[1371803731] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1959; }","duration":"949.230852ms","start":"2026-06-05T23:27:00.086957Z","end":"2026-06-05T23:27:01.036188Z","steps":["trace[1371803731] 'agreement among raft nodes before linearized reading' (duration: 949.007357ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:01.036254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:27:00.086926Z","time spent":"949.318605ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11688,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-05T23:27:01.035874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:26:58.70569Z","time spent":"2.329514571s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:27:01.03589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:27:00.710218Z","time spent":"325.522794ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:27:01.035986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.785090414s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:27:01.036537Z","caller":"traceutil/trace.go:171","msg":"trace[1373463359] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1959; }","duration":"1.78569818s","start":"2026-06-05T23:26:59.250824Z","end":"2026-06-05T23:27:01.036522Z","steps":["trace[1373463359] 'agreement among raft nodes before linearized reading' (duration: 1.784966142s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:27:01.036175Z","caller":"traceutil/trace.go:171","msg":"trace[1679424328] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1959; }","duration":"2.153596551s","start":"2026-06-05T23:26:58.882458Z","end":"2026-06-05T23:27:01.036054Z","steps":["trace[1679424328] 'agreement among raft nodes before linearized reading' (duration: 2.153320485s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:01.036663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:26:58.882445Z","time spent":"2.154201736s","remote":"127.0.0.1:49342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":2,"response size":30,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-06-05T23:27:01.036598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:26:59.250796Z","time spent":"1.785786032s","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-05T23:27:01.036041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327909487s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-05T23:27:01.036862Z","caller":"traceutil/trace.go:171","msg":"trace[1001501006] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1959; }","duration":"1.328769448s","start":"2026-06-05T23:26:59.70808Z","end":"2026-06-05T23:27:01.03685Z","steps":["trace[1001501006] 'agreement among raft nodes before linearized reading' (duration: 1.327875326s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:01.036909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:26:59.708051Z","time spent":"1.328846649s","remote":"127.0.0.1:58334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-05T23:27:01.036032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.493834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:27:01.036963Z","caller":"traceutil/trace.go:171","msg":"trace[1336591522] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1959; }","duration":"587.482368ms","start":"2026-06-05T23:27:00.449471Z","end":"2026-06-05T23:27:01.036954Z","steps":["trace[1336591522] 'agreement among raft nodes before linearized reading' (duration: 586.529795ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:01.037035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:27:00.449444Z","time spent":"587.54735ms","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-06-05T23:27:31.254115Z","caller":"traceutil/trace.go:171","msg":"trace[1887479072] linearizableReadLoop","detail":"{readStateIndex:2230; appliedIndex:2228; }","duration":"101.77585ms","start":"2026-06-05T23:27:31.152314Z","end":"2026-06-05T23:27:31.25409Z","steps":["trace[1887479072] 'read index received' (duration: 55.876195ms)","trace[1887479072] 'applied index is now lower than readState.Index' (duration: 45.898455ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:27:31.254432Z","caller":"traceutil/trace.go:171","msg":"trace[704175599] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"115.8364ms","start":"2026-06-05T23:27:31.138582Z","end":"2026-06-05T23:27:31.254418Z","steps":["trace[704175599] 'process raft request' (duration: 115.288706ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:27:31.254612Z","caller":"traceutil/trace.go:171","msg":"trace[602077550] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"115.774687ms","start":"2026-06-05T23:27:31.138828Z","end":"2026-06-05T23:27:31.254602Z","steps":["trace[602077550] 'process raft request' (duration: 115.189182ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:31.254891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.567989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-05T23:27:31.254927Z","caller":"traceutil/trace.go:171","msg":"trace[1517508854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2146; }","duration":"102.648592ms","start":"2026-06-05T23:27:31.152267Z","end":"2026-06-05T23:27:31.254915Z","steps":["trace[1517508854] 'agreement among raft nodes before linearized reading' (duration: 102.517548ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:31.793656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.64871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T23:27:31.794328Z","caller":"traceutil/trace.go:171","msg":"trace[974097625] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"531.914084ms","start":"2026-06-05T23:27:31.262385Z","end":"2026-06-05T23:27:31.794299Z","steps":["trace[974097625] 'process raft request' (duration: 195.495446ms)","trace[974097625] 'compare' (duration: 335.224699ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:27:31.794586Z","caller":"traceutil/trace.go:171","msg":"trace[1743647847] linearizableReadLoop","detail":"{readStateIndex:2236; appliedIndex:2232; }","duration":"523.40922ms","start":"2026-06-05T23:27:31.271151Z","end":"2026-06-05T23:27:31.79456Z","steps":["trace[1743647847] 'read index received' (duration: 7.169772ms)","trace[1743647847] 'applied index is now lower than readState.Index' (duration: 516.238318ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:27:31.794625Z","caller":"traceutil/trace.go:171","msg":"trace[100469228] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"530.434269ms","start":"2026-06-05T23:27:31.264173Z","end":"2026-06-05T23:27:31.794607Z","steps":["trace[100469228] 'process raft request' (duration: 530.251034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:31.794631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:27:31.262312Z","time spent":"532.082629ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:27:31.794598Z","caller":"traceutil/trace.go:171","msg":"trace[1113534129] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"530.845778ms","start":"2026-06-05T23:27:31.263702Z","end":"2026-06-05T23:27:31.794547Z","steps":["trace[1113534129] 'process raft request' (duration: 530.07691ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:31.794707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:27:31.264165Z","time spent":"530.49947ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:27:31.794805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.279976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:27:31.795103Z","caller":"traceutil/trace.go:171","msg":"trace[394233944] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2151; }","duration":"120.619113ms","start":"2026-06-05T23:27:31.674463Z","end":"2026-06-05T23:27:31.795083Z","steps":["trace[394233944] 'agreement among raft nodes before linearized reading' (duration: 120.237894ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:31.794816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.640345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:27:31.795325Z","caller":"traceutil/trace.go:171","msg":"trace[725410931] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2151; }","duration":"524.169237ms","start":"2026-06-05T23:27:31.27112Z","end":"2026-06-05T23:27:31.795289Z","steps":["trace[725410931] 'agreement among raft nodes before linearized reading' (duration: 523.570693ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:31.795429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:27:31.271103Z","time spent":"524.27267ms","remote":"127.0.0.1:49340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-06-05T23:27:31.794876Z","caller":"traceutil/trace.go:171","msg":"trace[1222737896] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"530.47128ms","start":"2026-06-05T23:27:31.264389Z","end":"2026-06-05T23:27:31.794861Z","steps":["trace[1222737896] 'process raft request' (duration: 530.126712ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:31.795613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:27:31.264379Z","time spent":"531.183707ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:27:31.795691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:27:31.263689Z","time spent":"531.32207ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:27:36.006052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.704177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-05T23:27:36.006131Z","caller":"traceutil/trace.go:171","msg":"trace[23260844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2169; }","duration":"195.84211ms","start":"2026-06-05T23:27:35.810272Z","end":"2026-06-05T23:27:36.006115Z","steps":["trace[23260844] 'range keys from in-memory index tree' (duration: 195.506522ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:36.00643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.40487ms","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-06-05T23:27:36.006466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.512088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-05T23:27:36.006497Z","caller":"traceutil/trace.go:171","msg":"trace[391068153] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2169; }","duration":"195.517783ms","start":"2026-06-05T23:27:35.810964Z","end":"2026-06-05T23:27:36.006482Z","steps":["trace[391068153] 'range keys from in-memory index tree' (duration: 195.171125ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:27:36.006534Z","caller":"traceutil/trace.go:171","msg":"trace[484025006] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2169; }","duration":"194.59729ms","start":"2026-06-05T23:27:35.811919Z","end":"2026-06-05T23:27:36.006516Z","steps":["trace[484025006] 'range keys from in-memory index tree' (duration: 194.383934ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:36.006537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.955388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-05T23:27:36.006608Z","caller":"traceutil/trace.go:171","msg":"trace[245491092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2169; }","duration":"195.075841ms","start":"2026-06-05T23:27:35.811514Z","end":"2026-06-05T23:27:36.006589Z","steps":["trace[245491092] 'range keys from in-memory index tree' (duration: 194.796775ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:36.007054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.080548ms","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-06-05T23:27:36.007213Z","caller":"traceutil/trace.go:171","msg":"trace[1663234701] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2169; }","duration":"389.905086ms","start":"2026-06-05T23:27:35.617243Z","end":"2026-06-05T23:27:36.007148Z","steps":["trace[1663234701] 'count revisions from in-memory index tree' (duration: 389.000805ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:36.007465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:27:35.617226Z","time spent":"390.219294ms","remote":"127.0.0.1:49110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-06-05T23:27:50.700724Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375026451,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T23:27:51.201503Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375026451,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T23:27:51.457572Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.317366433s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-05T23:27:51.458403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260087161s","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-06-05T23:27:51.458584Z","caller":"traceutil/trace.go:171","msg":"trace[33206040] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2245; }","duration":"1.260284146s","start":"2026-06-05T23:27:50.198275Z","end":"2026-06-05T23:27:51.458559Z","steps":["trace[33206040] 'range keys from in-memory index tree' (duration: 1.260066891s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:27:51.459527Z","caller":"traceutil/trace.go:171","msg":"trace[1223469613] transaction","detail":"{read_only:false; response_revision:2250; number_of_response:1; }","duration":"141.262513ms","start":"2026-06-05T23:27:51.318245Z","end":"2026-06-05T23:27:51.459507Z","steps":["trace[1223469613] 'process raft request' (duration: 141.211472ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:27:51.459845Z","caller":"traceutil/trace.go:171","msg":"trace[1986125515] transaction","detail":"{read_only:false; response_revision:2248; number_of_response:1; }","duration":"144.641004ms","start":"2026-06-05T23:27:51.315189Z","end":"2026-06-05T23:27:51.45983Z","steps":["trace[1986125515] 'process raft request' (duration: 144.138912ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:27:51.459854Z","caller":"traceutil/trace.go:171","msg":"trace[21188318] transaction","detail":"{read_only:false; response_revision:2247; number_of_response:1; }","duration":"188.067226ms","start":"2026-06-05T23:27:51.271732Z","end":"2026-06-05T23:27:51.459799Z","steps":["trace[21188318] 'process raft request' (duration: 187.475322ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:27:51.459993Z","caller":"traceutil/trace.go:171","msg":"trace[1310790495] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"143.191061ms","start":"2026-06-05T23:27:51.316793Z","end":"2026-06-05T23:27:51.459984Z","steps":["trace[1310790495] 'process raft request' (duration: 142.587716ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:27:51.460305Z","caller":"traceutil/trace.go:171","msg":"trace[262168013] transaction","detail":"{read_only:false; response_revision:2246; number_of_response:1; }","duration":"1.320227661s","start":"2026-06-05T23:27:50.140053Z","end":"2026-06-05T23:27:51.460281Z","steps":["trace[262168013] 'process raft request' (duration: 1.317871545s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:27:51.460347Z","caller":"traceutil/trace.go:171","msg":"trace[137138832] linearizableReadLoop","detail":"{readStateIndex:2336; appliedIndex:2335; }","duration":"1.260248946s","start":"2026-06-05T23:27:50.200077Z","end":"2026-06-05T23:27:51.460326Z","steps":["trace[137138832] 'read index received' (duration: 1.257774367s)","trace[137138832] 'applied index is now lower than readState.Index' (duration: 2.471669ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T23:27:51.460472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:27:50.140032Z","time spent":"1.320324802s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:27:51.460548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.2604593s","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-06-05T23:27:51.4607Z","caller":"traceutil/trace.go:171","msg":"trace[1906050204] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2250; }","duration":"1.260642555s","start":"2026-06-05T23:27:50.200046Z","end":"2026-06-05T23:27:51.460688Z","steps":["trace[1906050204] 'agreement among raft nodes before linearized reading' (duration: 1.260379749s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:51.460746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:27:50.200035Z","time spent":"1.260697066s","remote":"127.0.0.1:49152","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-06-05T23:27:51.460767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.981509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:27:51.46086Z","caller":"traceutil/trace.go:171","msg":"trace[2041305608] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:2250; }","duration":"331.098142ms","start":"2026-06-05T23:27:51.129743Z","end":"2026-06-05T23:27:51.460842Z","steps":["trace[2041305608] 'agreement among raft nodes before linearized reading' (duration: 330.972509ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:51.460951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:27:51.129728Z","time spent":"331.207484ms","remote":"127.0.0.1:35270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"warn","ts":"2026-06-05T23:27:51.461305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.093265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:27:51.461355Z","caller":"traceutil/trace.go:171","msg":"trace[1507183813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2250; }","duration":"204.154368ms","start":"2026-06-05T23:27:51.257187Z","end":"2026-06-05T23:27:51.461341Z","steps":["trace[1507183813] 'agreement among raft nodes before linearized reading' (duration: 204.086356ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:51.461522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.157476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T23:27:51.461557Z","caller":"traceutil/trace.go:171","msg":"trace[544111045] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:2250; }","duration":"827.208937ms","start":"2026-06-05T23:27:50.634339Z","end":"2026-06-05T23:27:51.461548Z","steps":["trace[544111045] 'agreement among raft nodes before linearized reading' (duration: 827.149176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:51.461568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039966963s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-05T23:27:51.461576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.275514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:27:51.461625Z","caller":"traceutil/trace.go:171","msg":"trace[1136947769] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2250; }","duration":"1.040047624s","start":"2026-06-05T23:27:50.421562Z","end":"2026-06-05T23:27:51.461609Z","steps":["trace[1136947769] 'agreement among raft nodes before linearized reading' (duration: 1.03990003s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:51.461632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.809841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T23:27:51.46166Z","caller":"traceutil/trace.go:171","msg":"trace[374994847] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:2250; }","duration":"828.379496ms","start":"2026-06-05T23:27:50.633259Z","end":"2026-06-05T23:27:51.461639Z","steps":["trace[374994847] 'agreement among raft nodes before linearized reading' (duration: 828.275233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:51.461666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:27:50.421548Z","time spent":"1.040110156s","remote":"127.0.0.1:58334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-05T23:27:51.461687Z","caller":"traceutil/trace.go:171","msg":"trace[27660807] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2250; }","duration":"465.878263ms","start":"2026-06-05T23:27:50.995781Z","end":"2026-06-05T23:27:51.461659Z","steps":["trace[27660807] 'agreement among raft nodes before linearized reading' (duration: 465.826282ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:51.461723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:27:50.995762Z","time spent":"465.953475ms","remote":"127.0.0.1:36066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":2,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-06-05T23:27:51.461731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:27:50.633246Z","time spent":"828.442488ms","remote":"127.0.0.1:35140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"warn","ts":"2026-06-05T23:27:51.461586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:27:50.634329Z","time spent":"827.249028ms","remote":"127.0.0.1:36054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":30,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"warn","ts":"2026-06-05T23:27:51.461927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.222230713s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b6531347244ef3\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-06-05T23:27:51.462074Z","caller":"traceutil/trace.go:171","msg":"trace[247284618] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18b6531347244ef3; range_end:; response_count:1; response_revision:2250; }","duration":"1.222380697s","start":"2026-06-05T23:27:50.239661Z","end":"2026-06-05T23:27:51.462042Z","steps":["trace[247284618] 'agreement among raft nodes before linearized reading' (duration: 1.22086297s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:27:51.462688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:27:50.239654Z","time spent":"1.22294545s","remote":"127.0.0.1:48964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18b6531347244ef3\" "} {"level":"warn","ts":"2026-06-05T23:27:51.46361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.562555ms","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-06-05T23:27:51.463665Z","caller":"traceutil/trace.go:171","msg":"trace[1789935333] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2250; }","duration":"107.654046ms","start":"2026-06-05T23:27:51.356Z","end":"2026-06-05T23:27:51.463655Z","steps":["trace[1789935333] 'agreement among raft nodes before linearized reading' (duration: 105.31208ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:29:10.322622Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375027708,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T23:29:10.38834Z","caller":"traceutil/trace.go:171","msg":"trace[175050607] transaction","detail":"{read_only:false; response_revision:2778; number_of_response:1; }","duration":"611.417125ms","start":"2026-06-05T23:29:09.776887Z","end":"2026-06-05T23:29:10.388304Z","steps":["trace[175050607] 'process raft request' (duration: 611.152079ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:29:10.388564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:29:09.776857Z","time spent":"611.589459ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:29:10.389162Z","caller":"traceutil/trace.go:171","msg":"trace[1103255296] transaction","detail":"{read_only:false; response_revision:2781; number_of_response:1; }","duration":"585.445594ms","start":"2026-06-05T23:29:09.803689Z","end":"2026-06-05T23:29:10.389135Z","steps":["trace[1103255296] 'process raft request' (duration: 585.374112ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:29:10.389244Z","caller":"traceutil/trace.go:171","msg":"trace[1727423714] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"588.221088ms","start":"2026-06-05T23:29:09.800992Z","end":"2026-06-05T23:29:10.389213Z","steps":["trace[1727423714] 'process raft request' (duration: 587.826639ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:29:10.38931Z","caller":"traceutil/trace.go:171","msg":"trace[23441715] transaction","detail":"{read_only:false; response_revision:2780; number_of_response:1; }","duration":"586.134069ms","start":"2026-06-05T23:29:09.803166Z","end":"2026-06-05T23:29:10.3893Z","steps":["trace[23441715] 'process raft request' (duration: 585.852783ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:29:10.389844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:29:09.803152Z","time spent":"586.589171ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:29:10.390062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:29:09.800961Z","time spent":"588.72434ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:29:10.389499Z","caller":"traceutil/trace.go:171","msg":"trace[561022097] transaction","detail":"{read_only:false; response_revision:2782; number_of_response:1; }","duration":"493.338045ms","start":"2026-06-05T23:29:09.896142Z","end":"2026-06-05T23:29:10.38948Z","steps":["trace[561022097] 'process raft request' (duration: 492.957036ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:29:10.390324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:29:09.803676Z","time spent":"585.825842ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:29:10.390428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:29:09.896128Z","time spent":"494.235096ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:29:10.390449Z","caller":"traceutil/trace.go:171","msg":"trace[1562205584] linearizableReadLoop","detail":"{readStateIndex:2891; appliedIndex:2885; }","duration":"572.000004ms","start":"2026-06-05T23:29:09.818426Z","end":"2026-06-05T23:29:10.390426Z","steps":["trace[1562205584] 'read index received' (duration: 569.67032ms)","trace[1562205584] 'applied index is now lower than readState.Index' (duration: 2.328794ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:29:10.390553Z","caller":"traceutil/trace.go:171","msg":"trace[1269590100] transaction","detail":"{read_only:false; response_revision:2783; number_of_response:1; }","duration":"293.182258ms","start":"2026-06-05T23:29:10.097359Z","end":"2026-06-05T23:29:10.390541Z","steps":["trace[1269590100] 'process raft request' (duration: 292.131293ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:29:10.390678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.672914ms","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-06-05T23:29:10.390727Z","caller":"traceutil/trace.go:171","msg":"trace[1069578124] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2783; }","duration":"551.740975ms","start":"2026-06-05T23:29:09.838973Z","end":"2026-06-05T23:29:10.390714Z","steps":["trace[1069578124] 'agreement among raft nodes before linearized reading' (duration: 551.605192ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:29:10.390759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.671389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-05T23:29:10.390744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.308701ms","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":"warn","ts":"2026-06-05T23:29:10.390767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.032804ms","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-06-05T23:29:10.390809Z","caller":"traceutil/trace.go:171","msg":"trace[2081737116] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2783; }","duration":"498.762791ms","start":"2026-06-05T23:29:09.892029Z","end":"2026-06-05T23:29:10.390792Z","steps":["trace[2081737116] 'agreement among raft nodes before linearized reading' (duration: 498.683309ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:29:10.390822Z","caller":"traceutil/trace.go:171","msg":"trace[351164398] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2783; }","duration":"572.414174ms","start":"2026-06-05T23:29:09.818387Z","end":"2026-06-05T23:29:10.390801Z","steps":["trace[351164398] 'agreement among raft nodes before linearized reading' (duration: 572.208589ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:29:10.390861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:29:09.818375Z","time spent":"572.477915ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-06-05T23:29:10.39086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:29:09.892007Z","time spent":"498.840272ms","remote":"127.0.0.1:51326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":19,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-06-05T23:29:10.390874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.441584ms","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-06-05T23:29:10.390898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.957055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b6532839ae014c\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-06-05T23:29:10.390911Z","caller":"traceutil/trace.go:171","msg":"trace[116630533] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2783; }","duration":"537.497395ms","start":"2026-06-05T23:29:09.853402Z","end":"2026-06-05T23:29:10.3909Z","steps":["trace[116630533] 'agreement among raft nodes before linearized reading' (duration: 537.396953ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:29:10.390817Z","caller":"traceutil/trace.go:171","msg":"trace[243513501] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2783; }","duration":"277.147667ms","start":"2026-06-05T23:29:10.113659Z","end":"2026-06-05T23:29:10.390806Z","steps":["trace[243513501] 'agreement among raft nodes before linearized reading' (duration: 276.987663ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:29:10.390772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:29:09.838962Z","time spent":"551.796397ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T23:29:10.390961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:29:09.853392Z","time spent":"537.559327ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-05T23:29:10.390677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.462035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-05T23:29:10.391051Z","caller":"traceutil/trace.go:171","msg":"trace[2055413383] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2783; }","duration":"399.881134ms","start":"2026-06-05T23:29:09.991158Z","end":"2026-06-05T23:29:10.39104Z","steps":["trace[2055413383] 'agreement among raft nodes before linearized reading' (duration: 399.416574ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:29:10.39105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.315736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-05T23:29:10.391087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:29:09.99114Z","time spent":"399.934336ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-06-05T23:29:10.391117Z","caller":"traceutil/trace.go:171","msg":"trace[2085543583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2783; }","duration":"535.403838ms","start":"2026-06-05T23:29:09.8557Z","end":"2026-06-05T23:29:10.391104Z","steps":["trace[2085543583] 'agreement among raft nodes before linearized reading' (duration: 535.05611ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:29:10.391208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:29:09.85569Z","time spent":"535.50422ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-05T23:29:10.390926Z","caller":"traceutil/trace.go:171","msg":"trace[347646012] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b6532839ae014c; range_end:; response_count:1; response_revision:2783; }","duration":"486.018966ms","start":"2026-06-05T23:29:09.9049Z","end":"2026-06-05T23:29:10.390919Z","steps":["trace[347646012] 'agreement among raft nodes before linearized reading' (duration: 485.937924ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:29:10.391331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:29:09.904881Z","time spent":"486.436055ms","remote":"127.0.0.1:48964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":802,"request content":"key:\"/registry/events/auth-system/keycloak-0.18b6532839ae014c\" "} {"level":"warn","ts":"2026-06-05T23:29:12.805293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.550933ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-06-05T23:33:00.262742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.96781ms","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-06-05T23:33:00.262871Z","caller":"traceutil/trace.go:171","msg":"trace[1702259643] transaction","detail":"{read_only:false; response_revision:4706; number_of_response:1; }","duration":"267.966518ms","start":"2026-06-05T23:32:59.994873Z","end":"2026-06-05T23:33:00.26284Z","steps":["trace[1702259643] 'process raft request' (duration: 267.751423ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:33:00.262955Z","caller":"traceutil/trace.go:171","msg":"trace[1931585463] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4705; }","duration":"104.238957ms","start":"2026-06-05T23:33:00.158688Z","end":"2026-06-05T23:33:00.262927Z","steps":["trace[1931585463] 'range keys from in-memory index tree' (duration: 103.93643ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:33:00.263351Z","caller":"traceutil/trace.go:171","msg":"trace[592977860] transaction","detail":"{read_only:false; response_revision:4707; number_of_response:1; }","duration":"267.613188ms","start":"2026-06-05T23:32:59.995628Z","end":"2026-06-05T23:33:00.263241Z","steps":["trace[592977860] 'process raft request' (duration: 267.143767ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:33:06.190713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.013778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:33:06.191028Z","caller":"traceutil/trace.go:171","msg":"trace[2069504361] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:4812; }","duration":"128.403887ms","start":"2026-06-05T23:33:06.062593Z","end":"2026-06-05T23:33:06.190997Z","steps":["trace[2069504361] 'count revisions from in-memory index tree' (duration: 127.892346ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:33:06.707421Z","caller":"traceutil/trace.go:171","msg":"trace[1141253319] linearizableReadLoop","detail":"{readStateIndex:5004; appliedIndex:5003; }","duration":"105.160605ms","start":"2026-06-05T23:33:06.602235Z","end":"2026-06-05T23:33:06.707395Z","steps":["trace[1141253319] 'read index received' (duration: 104.959201ms)","trace[1141253319] 'applied index is now lower than readState.Index' (duration: 200.064µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:33:06.707487Z","caller":"traceutil/trace.go:171","msg":"trace[2102799638] transaction","detail":"{read_only:false; response_revision:4815; number_of_response:1; }","duration":"171.755796ms","start":"2026-06-05T23:33:06.535684Z","end":"2026-06-05T23:33:06.70744Z","steps":["trace[2102799638] 'process raft request' (duration: 171.542101ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:33:06.707633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.373801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-06-05T23:33:06.707672Z","caller":"traceutil/trace.go:171","msg":"trace[194568357] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4815; }","duration":"105.469913ms","start":"2026-06-05T23:33:06.60219Z","end":"2026-06-05T23:33:06.70766Z","steps":["trace[194568357] 'agreement among raft nodes before linearized reading' (duration: 105.290278ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:33:06.829287Z","caller":"traceutil/trace.go:171","msg":"trace[1454966046] transaction","detail":"{read_only:false; response_revision:4817; number_of_response:1; }","duration":"111.876036ms","start":"2026-06-05T23:33:06.717387Z","end":"2026-06-05T23:33:06.829263Z","steps":["trace[1454966046] 'process raft request' (duration: 106.349505ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:33:06.829647Z","caller":"traceutil/trace.go:171","msg":"trace[807811560] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"112.152492ms","start":"2026-06-05T23:33:06.717461Z","end":"2026-06-05T23:33:06.829613Z","steps":["trace[807811560] 'process raft request' (duration: 111.731202ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:33:06.829867Z","caller":"traceutil/trace.go:171","msg":"trace[820744799] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"109.337905ms","start":"2026-06-05T23:33:06.720513Z","end":"2026-06-05T23:33:06.829851Z","steps":["trace[820744799] 'process raft request' (duration: 109.081739ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:33:06.829981Z","caller":"traceutil/trace.go:171","msg":"trace[1691700629] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"112.066689ms","start":"2026-06-05T23:33:06.717811Z","end":"2026-06-05T23:33:06.829878Z","steps":["trace[1691700629] 'process raft request' (duration: 111.67261ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:33:20.875635Z","caller":"traceutil/trace.go:171","msg":"trace[882699079] transaction","detail":"{read_only:false; response_revision:4996; number_of_response:1; }","duration":"145.915855ms","start":"2026-06-05T23:33:20.729684Z","end":"2026-06-05T23:33:20.8756Z","steps":["trace[882699079] 'process raft request' (duration: 145.124267ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:34:29.540019Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2885} {"level":"info","ts":"2026-06-05T23:34:29.622387Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2885,"took":"80.769307ms","hash":3193541389,"current-db-size-bytes":53788672,"current-db-size":"54 MB","current-db-size-in-use-bytes":29134848,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-05T23:34:29.622448Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3193541389,"revision":2885,"compact-revision":-1} {"level":"info","ts":"2026-06-05T23:39:29.547406Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5574} {"level":"info","ts":"2026-06-05T23:39:29.641225Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5574,"took":"90.857155ms","hash":3582350406,"current-db-size-bytes":53788672,"current-db-size":"54 MB","current-db-size-in-use-bytes":18341888,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-05T23:39:29.64134Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3582350406,"revision":5574,"compact-revision":2885} {"level":"info","ts":"2026-06-05T23:40:54.008979Z","caller":"traceutil/trace.go:171","msg":"trace[1636039663] transaction","detail":"{read_only:false; response_revision:7851; number_of_response:1; }","duration":"284.180702ms","start":"2026-06-05T23:40:53.724758Z","end":"2026-06-05T23:40:54.008938Z","steps":["trace[1636039663] 'process raft request' (duration: 283.909994ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:55.717181Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.708672623s","expected-duration":"1s"} {"level":"info","ts":"2026-06-05T23:40:55.720093Z","caller":"traceutil/trace.go:171","msg":"trace[1701235934] transaction","detail":"{read_only:false; response_revision:7852; number_of_response:1; }","duration":"1.994569666s","start":"2026-06-05T23:40:53.725472Z","end":"2026-06-05T23:40:55.720041Z","steps":["trace[1701235934] 'process raft request' (duration: 1.991970821s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:55.720316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:53.725461Z","time spent":"1.9947349s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:40:55.720375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.984391841s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/pxc-operator\" ","response":"range_response_count:1 size:1806"} {"level":"info","ts":"2026-06-05T23:40:55.720116Z","caller":"traceutil/trace.go:171","msg":"trace[1783924135] linearizableReadLoop","detail":"{readStateIndex:8151; appliedIndex:8147; }","duration":"1.983548411s","start":"2026-06-05T23:40:53.735957Z","end":"2026-06-05T23:40:55.719505Z","steps":["trace[1783924135] 'read index received' (duration: 272.723185ms)","trace[1783924135] 'applied index is now lower than readState.Index' (duration: 1.710823866s)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:40:55.720456Z","caller":"traceutil/trace.go:171","msg":"trace[1078800880] range","detail":"{range_begin:/registry/roles/openstack/pxc-operator; range_end:; response_count:1; response_revision:7854; }","duration":"1.984488694s","start":"2026-06-05T23:40:53.73595Z","end":"2026-06-05T23:40:55.720438Z","steps":["trace[1078800880] 'agreement among raft nodes before linearized reading' (duration: 1.984229898s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:55.720497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:53.735939Z","time spent":"1.984549035s","remote":"127.0.0.1:49202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":1829,"request content":"key:\"/registry/roles/openstack/pxc-operator\" "} {"level":"info","ts":"2026-06-05T23:40:55.720498Z","caller":"traceutil/trace.go:171","msg":"trace[236908382] transaction","detail":"{read_only:false; response_revision:7853; number_of_response:1; }","duration":"1.986607236s","start":"2026-06-05T23:40:53.733854Z","end":"2026-06-05T23:40:55.720461Z","steps":["trace[236908382] 'process raft request' (duration: 1.985485119s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:40:55.720634Z","caller":"traceutil/trace.go:171","msg":"trace[1191400377] transaction","detail":"{read_only:false; response_revision:7854; number_of_response:1; }","duration":"1.985063818s","start":"2026-06-05T23:40:53.735558Z","end":"2026-06-05T23:40:55.720622Z","steps":["trace[1191400377] 'process raft request' (duration: 1.983829698s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:55.720739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:53.735539Z","time spent":"1.98512871s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:40:55.720755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:53.733827Z","time spent":"1.986827082s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:40:55.721084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.739023ms","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-06-05T23:40:55.721129Z","caller":"traceutil/trace.go:171","msg":"trace[1926883329] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:7855; }","duration":"881.918607ms","start":"2026-06-05T23:40:54.839198Z","end":"2026-06-05T23:40:55.721117Z","steps":["trace[1926883329] 'agreement among raft nodes before linearized reading' (duration: 881.849005ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:55.721167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:54.83917Z","time spent":"881.988229ms","remote":"127.0.0.1:49110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-06-05T23:40:55.721327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.075114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:40:55.721363Z","caller":"traceutil/trace.go:171","msg":"trace[255812108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7855; }","duration":"462.159286ms","start":"2026-06-05T23:40:55.259195Z","end":"2026-06-05T23:40:55.721355Z","steps":["trace[255812108] 'agreement among raft nodes before linearized reading' (duration: 462.103305ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:55.721395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:55.259174Z","time spent":"462.216248ms","remote":"127.0.0.1:48850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T23:40:55.721441Z","caller":"traceutil/trace.go:171","msg":"trace[1757174482] transaction","detail":"{read_only:false; response_revision:7855; number_of_response:1; }","duration":"1.101221137s","start":"2026-06-05T23:40:54.620208Z","end":"2026-06-05T23:40:55.721429Z","steps":["trace[1757174482] 'process raft request' (duration: 1.100299394s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:55.721502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:54.620183Z","time spent":"1.10129056s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:40:55.721594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.223484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-05T23:40:55.721634Z","caller":"traceutil/trace.go:171","msg":"trace[2067473304] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7855; }","duration":"621.315156ms","start":"2026-06-05T23:40:55.10031Z","end":"2026-06-05T23:40:55.721625Z","steps":["trace[2067473304] 'agreement among raft nodes before linearized reading' (duration: 621.206963ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:55.721666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:55.10028Z","time spent":"621.380948ms","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-05T23:40:55.721308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.919878292s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/rabbitmq-cluster-operator\" ","response":"range_response_count:1 size:1189"} {"level":"info","ts":"2026-06-05T23:40:55.72206Z","caller":"traceutil/trace.go:171","msg":"trace[1589325300] range","detail":"{range_begin:/registry/roles/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:7855; }","duration":"1.920695082s","start":"2026-06-05T23:40:53.801334Z","end":"2026-06-05T23:40:55.722029Z","steps":["trace[1589325300] 'agreement among raft nodes before linearized reading' (duration: 1.919610576s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:55.72216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:53.801312Z","time spent":"1.920837315s","remote":"127.0.0.1:49202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":1212,"request content":"key:\"/registry/roles/openstack/rabbitmq-cluster-operator\" "} {"level":"warn","ts":"2026-06-05T23:40:55.722473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093146287s","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-06-05T23:40:55.722562Z","caller":"traceutil/trace.go:171","msg":"trace[1217387951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7855; }","duration":"1.093263909s","start":"2026-06-05T23:40:54.629282Z","end":"2026-06-05T23:40:55.722545Z","steps":["trace[1217387951] 'agreement among raft nodes before linearized reading' (duration: 1.093008643s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:55.722631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:54.629269Z","time spent":"1.093351341s","remote":"127.0.0.1:49152","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-06-05T23:40:55.722678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092766167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-05T23:40:55.722739Z","caller":"traceutil/trace.go:171","msg":"trace[1072748665] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7855; }","duration":"1.09289708s","start":"2026-06-05T23:40:54.629827Z","end":"2026-06-05T23:40:55.722724Z","steps":["trace[1072748665] 'agreement among raft nodes before linearized reading' (duration: 1.092733906s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:55.72278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:54.629815Z","time spent":"1.092957182s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-05T23:40:55.722857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093111435s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-05T23:40:55.722895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.479538825s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:40:55.722895Z","caller":"traceutil/trace.go:171","msg":"trace[826918553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7855; }","duration":"1.093194116s","start":"2026-06-05T23:40:54.629691Z","end":"2026-06-05T23:40:55.722886Z","steps":["trace[826918553] 'agreement among raft nodes before linearized reading' (duration: 1.093045033s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:40:55.722922Z","caller":"traceutil/trace.go:171","msg":"trace[1334976544] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:7855; }","duration":"1.479612277s","start":"2026-06-05T23:40:54.243301Z","end":"2026-06-05T23:40:55.722914Z","steps":["trace[1334976544] 'agreement among raft nodes before linearized reading' (duration: 1.479564856s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:55.722937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106410007s","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-06-05T23:40:55.722958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:54.629682Z","time spent":"1.093266649s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-05T23:40:55.723017Z","caller":"traceutil/trace.go:171","msg":"trace[2038402740] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7855; }","duration":"1.10653651s","start":"2026-06-05T23:40:54.61646Z","end":"2026-06-05T23:40:55.722996Z","steps":["trace[2038402740] 'agreement among raft nodes before linearized reading' (duration: 1.106250302s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:55.72307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:54.61644Z","time spent":"1.106619742s","remote":"127.0.0.1:49152","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-06-05T23:40:55.722958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:54.243236Z","time spent":"1.47971484s","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-06-05T23:40:55.723291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102766657s","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-06-05T23:40:55.723346Z","caller":"traceutil/trace.go:171","msg":"trace[1791266300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7855; }","duration":"1.10292132s","start":"2026-06-05T23:40:54.620412Z","end":"2026-06-05T23:40:55.723334Z","steps":["trace[1791266300] 'agreement among raft nodes before linearized reading' (duration: 1.102419797s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:55.723396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:54.620345Z","time spent":"1.103037143s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T23:40:56.301655Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375042964,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T23:40:56.802224Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375042964,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T23:40:57.302642Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375042964,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T23:40:57.803845Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375042964,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T23:40:58.305049Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375042964,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T23:40:58.805975Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375042964,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T23:40:59.01352Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.282343332s","expected-duration":"1s"} {"level":"info","ts":"2026-06-05T23:40:59.022346Z","caller":"traceutil/trace.go:171","msg":"trace[1602451558] transaction","detail":"{read_only:false; response_revision:7860; number_of_response:1; }","duration":"3.29223549s","start":"2026-06-05T23:40:55.730086Z","end":"2026-06-05T23:40:59.022321Z","steps":["trace[1602451558] 'process raft request' (duration: 3.292190869s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:59.022514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:55.730074Z","time spent":"3.292349462s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:40:59.022697Z","caller":"traceutil/trace.go:171","msg":"trace[1138245233] transaction","detail":"{read_only:false; response_revision:7859; number_of_response:1; }","duration":"3.292624759s","start":"2026-06-05T23:40:55.730063Z","end":"2026-06-05T23:40:59.022688Z","steps":["trace[1138245233] 'process raft request' (duration: 3.292116646s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:59.022744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:55.730054Z","time spent":"3.292667691s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:40:59.022816Z","caller":"traceutil/trace.go:171","msg":"trace[854313562] transaction","detail":"{read_only:false; response_revision:7858; number_of_response:1; }","duration":"3.292918567s","start":"2026-06-05T23:40:55.729874Z","end":"2026-06-05T23:40:59.022792Z","steps":["trace[854313562] 'process raft request' (duration: 3.283914263s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:59.023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:55.729863Z","time spent":"3.293032909s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:40:59.024936Z","caller":"traceutil/trace.go:171","msg":"trace[75420090] transaction","detail":"{read_only:false; response_revision:7862; number_of_response:1; }","duration":"2.837392347s","start":"2026-06-05T23:40:56.187511Z","end":"2026-06-05T23:40:59.024903Z","steps":["trace[75420090] 'process raft request' (duration: 2.836172966s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:59.025123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:56.187492Z","time spent":"2.837528809s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:40:59.025069Z","caller":"traceutil/trace.go:171","msg":"trace[337116632] transaction","detail":"{read_only:false; response_revision:7861; number_of_response:1; }","duration":"3.00953934s","start":"2026-06-05T23:40:56.015495Z","end":"2026-06-05T23:40:59.025035Z","steps":["trace[337116632] 'process raft request' (duration: 3.008005002s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:59.026438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:56.015469Z","time spent":"3.009999981s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:40:59.027058Z","caller":"traceutil/trace.go:171","msg":"trace[724547783] linearizableReadLoop","detail":"{readStateIndex:8163; appliedIndex:8154; }","duration":"3.225515876s","start":"2026-06-05T23:40:55.801525Z","end":"2026-06-05T23:40:59.027041Z","steps":["trace[724547783] 'read index received' (duration: 3.212280066s)","trace[724547783] 'applied index is now lower than readState.Index' (duration: 13.2343ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:40:59.027087Z","caller":"traceutil/trace.go:171","msg":"trace[1306333077] transaction","detail":"{read_only:false; response_revision:7865; number_of_response:1; }","duration":"1.297097862s","start":"2026-06-05T23:40:57.72998Z","end":"2026-06-05T23:40:59.027078Z","steps":["trace[1306333077] 'process raft request' (duration: 1.29701476s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:40:59.02708Z","caller":"traceutil/trace.go:171","msg":"trace[703257042] transaction","detail":"{read_only:false; response_revision:7864; number_of_response:1; }","duration":"1.297241426s","start":"2026-06-05T23:40:57.729822Z","end":"2026-06-05T23:40:59.027063Z","steps":["trace[703257042] 'process raft request' (duration: 1.297110221s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:59.027152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:57.729972Z","time spent":"1.297149553s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:40:59.027295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:57.729787Z","time spent":"1.29741878s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:40:59.027332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.225811113s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:40:59.027365Z","caller":"traceutil/trace.go:171","msg":"trace[1369846844] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:7865; }","duration":"3.225873905s","start":"2026-06-05T23:40:55.801484Z","end":"2026-06-05T23:40:59.027358Z","steps":["trace[1369846844] 'agreement among raft nodes before linearized reading' (duration: 3.225745241s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:59.027389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:55.801468Z","time spent":"3.225917095s","remote":"127.0.0.1:49340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-06-05T23:40:59.027561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.770041443s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:40:59.027629Z","caller":"traceutil/trace.go:171","msg":"trace[963903657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7865; }","duration":"1.770158866s","start":"2026-06-05T23:40:57.257454Z","end":"2026-06-05T23:40:59.027613Z","steps":["trace[963903657] 'agreement among raft nodes before linearized reading' (duration: 1.770029772s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:59.027617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.902867949s","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":"warn","ts":"2026-06-05T23:40:59.027672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:57.257427Z","time spent":"1.770235108s","remote":"127.0.0.1:48850","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-06-05T23:40:59.027602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.073140584s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-06-05T23:40:59.027644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.203528907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/pxc-operator\" ","response":"range_response_count:1 size:1806"} {"level":"warn","ts":"2026-06-05T23:40:59.027789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.559376991s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T23:40:59.027806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.291307258s","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-06-05T23:40:59.027827Z","caller":"traceutil/trace.go:171","msg":"trace[1537232868] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:7865; }","duration":"1.559439141s","start":"2026-06-05T23:40:57.468378Z","end":"2026-06-05T23:40:59.027818Z","steps":["trace[1537232868] 'agreement among raft nodes before linearized reading' (duration: 1.55937929s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:59.027819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.077623187s","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-06-05T23:40:59.027841Z","caller":"traceutil/trace.go:171","msg":"trace[520860974] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7865; }","duration":"1.29138469s","start":"2026-06-05T23:40:57.73645Z","end":"2026-06-05T23:40:59.027835Z","steps":["trace[520860974] 'agreement among raft nodes before linearized reading' (duration: 1.291298488s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:40:59.027829Z","caller":"traceutil/trace.go:171","msg":"trace[637890738] range","detail":"{range_begin:/registry/roles/openstack/pxc-operator; range_end:; response_count:1; response_revision:7865; }","duration":"3.203732522s","start":"2026-06-05T23:40:55.82408Z","end":"2026-06-05T23:40:59.027812Z","steps":["trace[637890738] 'agreement among raft nodes before linearized reading' (duration: 3.203392783s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:59.02786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:57.46836Z","time spent":"1.559494073s","remote":"127.0.0.1:59842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-06-05T23:40:59.027873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:57.736437Z","time spent":"1.291430081s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T23:40:59.027886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:55.824071Z","time spent":"3.203804355s","remote":"127.0.0.1:49202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":1829,"request content":"key:\"/registry/roles/openstack/pxc-operator\" "} {"level":"warn","ts":"2026-06-05T23:40:59.027722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.200261075s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/rabbitmq-cluster-operator\" ","response":"range_response_count:1 size:1189"} {"level":"info","ts":"2026-06-05T23:40:59.027878Z","caller":"traceutil/trace.go:171","msg":"trace[2044396539] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:7865; }","duration":"3.077803311s","start":"2026-06-05T23:40:55.950057Z","end":"2026-06-05T23:40:59.02786Z","steps":["trace[2044396539] 'agreement among raft nodes before linearized reading' (duration: 3.077449462s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:40:59.02796Z","caller":"traceutil/trace.go:171","msg":"trace[190760235] range","detail":"{range_begin:/registry/roles/openstack/rabbitmq-cluster-operator; range_end:; response_count:1; response_revision:7865; }","duration":"3.200557552s","start":"2026-06-05T23:40:55.827391Z","end":"2026-06-05T23:40:59.027949Z","steps":["trace[190760235] 'agreement among raft nodes before linearized reading' (duration: 3.200202233s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:59.027999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:55.827361Z","time spent":"3.200627704s","remote":"127.0.0.1:49202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":1212,"request content":"key:\"/registry/roles/openstack/rabbitmq-cluster-operator\" "} {"level":"warn","ts":"2026-06-05T23:40:59.027971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:55.950026Z","time spent":"3.077933225s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-06-05T23:40:59.02778Z","caller":"traceutil/trace.go:171","msg":"trace[238438646] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:7865; }","duration":"3.073319319s","start":"2026-06-05T23:40:55.954451Z","end":"2026-06-05T23:40:59.02777Z","steps":["trace[238438646] 'agreement among raft nodes before linearized reading' (duration: 3.073041252s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:59.028124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:55.954405Z","time spent":"3.073709158s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-06-05T23:40:59.027693Z","caller":"traceutil/trace.go:171","msg":"trace[763411652] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:7865; }","duration":"2.902989192s","start":"2026-06-05T23:40:56.12469Z","end":"2026-06-05T23:40:59.027679Z","steps":["trace[763411652] 'agreement among raft nodes before linearized reading' (duration: 2.902852999s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:59.03129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:56.124676Z","time spent":"2.906600402s","remote":"127.0.0.1:48988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":12,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-06-05T23:40:59.027046Z","caller":"traceutil/trace.go:171","msg":"trace[2136461204] transaction","detail":"{read_only:false; response_revision:7863; number_of_response:1; }","duration":"1.300695822s","start":"2026-06-05T23:40:57.726332Z","end":"2026-06-05T23:40:59.027028Z","steps":["trace[2136461204] 'process raft request' (duration: 1.300489496s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:59.031497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:57.726303Z","time spent":"1.305125761s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:40:59.028375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.291714297s","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-06-05T23:40:59.031652Z","caller":"traceutil/trace.go:171","msg":"trace[1855626398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7865; }","duration":"1.29503769s","start":"2026-06-05T23:40:57.736596Z","end":"2026-06-05T23:40:59.031634Z","steps":["trace[1855626398] 'agreement among raft nodes before linearized reading' (duration: 1.291206385s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:59.031714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:40:57.736564Z","time spent":"1.295132843s","remote":"127.0.0.1:49152","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-06-05T23:40:59.287234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.321686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:1286"} {"level":"info","ts":"2026-06-05T23:40:59.287544Z","caller":"traceutil/trace.go:171","msg":"trace[635993422] range","detail":"{range_begin:/registry/roles/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:7869; }","duration":"162.678135ms","start":"2026-06-05T23:40:59.124831Z","end":"2026-06-05T23:40:59.28751Z","steps":["trace[635993422] 'range keys from in-memory index tree' (duration: 162.10873ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:40:59.287234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.827367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/rabbitmq-keystone-peer-discovery\" ","response":"range_response_count:1 size:814"} {"level":"info","ts":"2026-06-05T23:40:59.287871Z","caller":"traceutil/trace.go:171","msg":"trace[238410480] range","detail":"{range_begin:/registry/roles/openstack/rabbitmq-keystone-peer-discovery; range_end:; response_count:1; response_revision:7869; }","duration":"106.541724ms","start":"2026-06-05T23:40:59.181282Z","end":"2026-06-05T23:40:59.287824Z","steps":["trace[238410480] 'range keys from in-memory index tree' (duration: 105.611481ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:01.545711Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375043054,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T23:41:01.794345Z","caller":"traceutil/trace.go:171","msg":"trace[1863944168] transaction","detail":"{read_only:false; response_revision:7870; number_of_response:1; }","duration":"764.80669ms","start":"2026-06-05T23:41:01.029513Z","end":"2026-06-05T23:41:01.79432Z","steps":["trace[1863944168] 'process raft request' (duration: 764.605385ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:01.794522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:01.029504Z","time spent":"764.915983ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:41:03.255593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999368965s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-05T23:41:03.255764Z","caller":"traceutil/trace.go:171","msg":"trace[700585587] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999594461s","start":"2026-06-05T23:41:01.256147Z","end":"2026-06-05T23:41:03.255742Z","steps":["trace[700585587] 'agreement among raft nodes before linearized reading' (duration: 1.999402366s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:03.255859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:01.256126Z","time spent":"1.999717685s","remote":"127.0.0.1:48850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/05 23:41:03 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-05T23:41:03.746465Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.952319378s","expected-duration":"1s"} {"level":"info","ts":"2026-06-05T23:41:03.74699Z","caller":"traceutil/trace.go:171","msg":"trace[725096605] transaction","detail":"{read_only:false; response_revision:7873; number_of_response:1; }","duration":"2.71431841s","start":"2026-06-05T23:41:01.03264Z","end":"2026-06-05T23:41:03.746959Z","steps":["trace[725096605] 'process raft request' (duration: 2.714238968s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:41:03.747038Z","caller":"traceutil/trace.go:171","msg":"trace[797405174] transaction","detail":"{read_only:false; response_revision:7872; number_of_response:1; }","duration":"2.714823752s","start":"2026-06-05T23:41:01.032182Z","end":"2026-06-05T23:41:03.747006Z","steps":["trace[797405174] 'process raft request' (duration: 2.714656608s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:41:03.747041Z","caller":"traceutil/trace.go:171","msg":"trace[182303306] transaction","detail":"{read_only:false; response_revision:7871; number_of_response:1; }","duration":"2.716465423s","start":"2026-06-05T23:41:01.030503Z","end":"2026-06-05T23:41:03.746968Z","steps":["trace[182303306] 'process raft request' (duration: 2.716149365s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:03.74719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:01.032635Z","time spent":"2.714444903s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:41:03.747221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:01.032174Z","time spent":"2.714925245s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:41:03.747252Z","caller":"traceutil/trace.go:171","msg":"trace[1327853990] transaction","detail":"{read_only:false; response_revision:7874; number_of_response:1; }","duration":"2.713950649s","start":"2026-06-05T23:41:01.033285Z","end":"2026-06-05T23:41:03.747236Z","steps":["trace[1327853990] 'process raft request' (duration: 2.713631942s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:03.747343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:01.033278Z","time spent":"2.714030072s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:41:03.747569Z","caller":"traceutil/trace.go:171","msg":"trace[1006405936] linearizableReadLoop","detail":"{readStateIndex:8174; appliedIndex:8167; }","duration":"2.702616279s","start":"2026-06-05T23:41:01.044934Z","end":"2026-06-05T23:41:03.747551Z","steps":["trace[1006405936] 'read index received' (duration: 749.237433ms)","trace[1006405936] 'applied index is now lower than readState.Index' (duration: 1.953377516s)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:41:03.747595Z","caller":"traceutil/trace.go:171","msg":"trace[717601685] transaction","detail":"{read_only:false; response_revision:7876; number_of_response:1; }","duration":"2.710942076s","start":"2026-06-05T23:41:01.036634Z","end":"2026-06-05T23:41:03.747576Z","steps":["trace[717601685] 'process raft request' (duration: 2.710786652s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:41:03.747549Z","caller":"traceutil/trace.go:171","msg":"trace[367294235] transaction","detail":"{read_only:false; response_revision:7875; number_of_response:1; }","duration":"2.714182936s","start":"2026-06-05T23:41:01.03333Z","end":"2026-06-05T23:41:03.747513Z","steps":["trace[367294235] 'process raft request' (duration: 2.713868158s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:03.747667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:01.036618Z","time spent":"2.711009458s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:41:03.747807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.702880185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-05T23:41:03.747816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:01.030478Z","time spent":"2.716628767s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:41:03.747851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:01.03332Z","time spent":"2.714378981s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:41:03.747884Z","caller":"traceutil/trace.go:171","msg":"trace[1652639135] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7876; }","duration":"2.702993057s","start":"2026-06-05T23:41:01.044879Z","end":"2026-06-05T23:41:03.747872Z","steps":["trace[1652639135] 'agreement among raft nodes before linearized reading' (duration: 2.702760902s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:03.747972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:01.044865Z","time spent":"2.703095251s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T23:41:03.759727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.149581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-05T23:41:03.759743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.712294609s","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-06-05T23:41:03.7599Z","caller":"traceutil/trace.go:171","msg":"trace[804001577] transaction","detail":"{read_only:false; response_revision:7877; number_of_response:1; }","duration":"1.615236113s","start":"2026-06-05T23:41:02.144654Z","end":"2026-06-05T23:41:03.759891Z","steps":["trace[804001577] 'process raft request' (duration: 1.614808083s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:03.759959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:02.144618Z","time spent":"1.615314585s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:41:03.759909Z","caller":"traceutil/trace.go:171","msg":"trace[2147268542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7877; }","duration":"2.712379371s","start":"2026-06-05T23:41:01.047404Z","end":"2026-06-05T23:41:03.759783Z","steps":["trace[2147268542] 'agreement among raft nodes before linearized reading' (duration: 2.712204167s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:03.760078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.178919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:41:03.760101Z","caller":"traceutil/trace.go:171","msg":"trace[735225587] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:7877; }","duration":"113.24257ms","start":"2026-06-05T23:41:03.646848Z","end":"2026-06-05T23:41:03.760091Z","steps":["trace[735225587] 'agreement among raft nodes before linearized reading' (duration: 113.200088ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:03.760144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116909873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-05T23:41:03.760197Z","caller":"traceutil/trace.go:171","msg":"trace[37272951] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:7877; }","duration":"1.117031286s","start":"2026-06-05T23:41:02.643153Z","end":"2026-06-05T23:41:03.760184Z","steps":["trace[37272951] 'agreement among raft nodes before linearized reading' (duration: 1.116464911s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:03.760236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:02.643122Z","time spent":"1.117105678s","remote":"127.0.0.1:48964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":688,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-06-05T23:41:03.760281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:01.047388Z","time spent":"2.712839383s","remote":"127.0.0.1:49152","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-06-05T23:41:03.76038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.164556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:41:03.760415Z","caller":"traceutil/trace.go:171","msg":"trace[1153434414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7877; }","duration":"325.214468ms","start":"2026-06-05T23:41:03.435189Z","end":"2026-06-05T23:41:03.760403Z","steps":["trace[1153434414] 'agreement among raft nodes before linearized reading' (duration: 325.176157ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:03.760445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:03.435178Z","time spent":"325.259129ms","remote":"127.0.0.1:48850","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-06-05T23:41:03.76045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.398128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:41:03.760485Z","caller":"traceutil/trace.go:171","msg":"trace[2124930184] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:7877; }","duration":"514.466829ms","start":"2026-06-05T23:41:03.246005Z","end":"2026-06-05T23:41:03.760472Z","steps":["trace[2124930184] 'agreement among raft nodes before linearized reading' (duration: 514.410858ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:03.760522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:03.245985Z","time spent":"514.529041ms","remote":"127.0.0.1:35154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"warn","ts":"2026-06-05T23:41:03.760638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.837238912s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:41:03.76067Z","caller":"traceutil/trace.go:171","msg":"trace[1446149012] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:7877; }","duration":"1.837270443s","start":"2026-06-05T23:41:01.92339Z","end":"2026-06-05T23:41:03.76066Z","steps":["trace[1446149012] 'agreement among raft nodes before linearized reading' (duration: 1.837226412s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:03.760676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.864138372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T23:41:03.760725Z","caller":"traceutil/trace.go:171","msg":"trace[851020232] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:7877; }","duration":"1.864232914s","start":"2026-06-05T23:41:01.896476Z","end":"2026-06-05T23:41:03.760709Z","steps":["trace[851020232] 'agreement among raft nodes before linearized reading' (duration: 1.864176583s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:03.760761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.146409206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-05T23:41:03.760768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:01.896443Z","time spent":"1.864314816s","remote":"127.0.0.1:35198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"info","ts":"2026-06-05T23:41:03.760785Z","caller":"traceutil/trace.go:171","msg":"trace[767264210] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:7877; }","duration":"1.146472969s","start":"2026-06-05T23:41:02.614306Z","end":"2026-06-05T23:41:03.760779Z","steps":["trace[767264210] 'agreement among raft nodes before linearized reading' (duration: 1.146397997s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:41:03.759769Z","caller":"traceutil/trace.go:171","msg":"trace[180541773] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:7877; }","duration":"275.240024ms","start":"2026-06-05T23:41:03.48452Z","end":"2026-06-05T23:41:03.75976Z","steps":["trace[180541773] 'agreement among raft nodes before linearized reading' (duration: 275.118991ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:03.760344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.247205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:41:03.760852Z","caller":"traceutil/trace.go:171","msg":"trace[1073980315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7877; }","duration":"504.944123ms","start":"2026-06-05T23:41:03.255902Z","end":"2026-06-05T23:41:03.760846Z","steps":["trace[1073980315] 'agreement among raft nodes before linearized reading' (duration: 504.4095ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:03.760877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:03.255891Z","time spent":"504.982074ms","remote":"127.0.0.1:48860","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-06-05T23:41:03.76069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:01.923329Z","time spent":"1.837356074s","remote":"127.0.0.1:53298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-06-05T23:41:03.760961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.871002684s","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:7"} {"level":"info","ts":"2026-06-05T23:41:03.760996Z","caller":"traceutil/trace.go:171","msg":"trace[1452653482] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7877; }","duration":"1.871036824s","start":"2026-06-05T23:41:01.889947Z","end":"2026-06-05T23:41:03.760984Z","steps":["trace[1452653482] 'agreement among raft nodes before linearized reading' (duration: 1.870984423s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:03.761037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:01.889864Z","time spent":"1.871163738s","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-06-05T23:41:03.761032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.665057052s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-erlang-cookie\" ","response":"range_response_count:1 size:823"} {"level":"info","ts":"2026-06-05T23:41:03.761089Z","caller":"traceutil/trace.go:171","msg":"trace[1788431063] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-erlang-cookie; range_end:; response_count:1; response_revision:7877; }","duration":"2.665143865s","start":"2026-06-05T23:41:01.095929Z","end":"2026-06-05T23:41:03.761073Z","steps":["trace[1788431063] 'agreement among raft nodes before linearized reading' (duration: 2.664982531s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:03.761111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.656106899s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-messaging-topology-operator-webhook\" ","response":"range_response_count:1 size:5714"} {"level":"info","ts":"2026-06-05T23:41:03.761135Z","caller":"traceutil/trace.go:171","msg":"trace[1657211872] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-messaging-topology-operator-webhook; range_end:; response_count:1; response_revision:7877; }","duration":"2.656168021s","start":"2026-06-05T23:41:01.104958Z","end":"2026-06-05T23:41:03.761126Z","steps":["trace[1657211872] 'agreement among raft nodes before linearized reading' (duration: 2.656095579s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:03.761132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:01.095915Z","time spent":"2.665209546s","remote":"127.0.0.1:48978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":846,"request content":"key:\"/registry/secrets/openstack/rabbitmq-keystone-erlang-cookie\" "} {"level":"warn","ts":"2026-06-05T23:41:03.761155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:01.104936Z","time spent":"2.656214812s","remote":"127.0.0.1:48978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5737,"request content":"key:\"/registry/secrets/openstack/rabbitmq-messaging-topology-operator-webhook\" "} {"level":"warn","ts":"2026-06-05T23:41:03.760631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.571175184s","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-06-05T23:41:03.761302Z","caller":"traceutil/trace.go:171","msg":"trace[978524373] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:7877; }","duration":"2.571912712s","start":"2026-06-05T23:41:01.189378Z","end":"2026-06-05T23:41:03.761291Z","steps":["trace[978524373] 'agreement among raft nodes before linearized reading' (duration: 2.571065401s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:03.761347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:01.189354Z","time spent":"2.571980614s","remote":"127.0.0.1:49038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":4,"response size":30,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-06-05T23:41:03.760814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.62362932s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-06-05T23:41:03.761399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:02.614284Z","time spent":"1.14651557s","remote":"127.0.0.1:48906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.242\" "} {"level":"info","ts":"2026-06-05T23:41:03.761484Z","caller":"traceutil/trace.go:171","msg":"trace[1000894312] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:7877; }","duration":"2.624264336s","start":"2026-06-05T23:41:01.137133Z","end":"2026-06-05T23:41:03.761397Z","steps":["trace[1000894312] 'agreement among raft nodes before linearized reading' (duration: 2.623555739s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:03.761649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:01.137114Z","time spent":"2.62445958s","remote":"127.0.0.1:49046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-06-05T23:41:10.287294Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375043369,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T23:41:10.787681Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375043369,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T23:41:11.209855Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.4418114s","expected-duration":"1s"} {"level":"info","ts":"2026-06-05T23:41:11.210443Z","caller":"traceutil/trace.go:171","msg":"trace[1116404040] transaction","detail":"{read_only:false; response_revision:7903; number_of_response:1; }","duration":"1.442512176s","start":"2026-06-05T23:41:09.767884Z","end":"2026-06-05T23:41:11.210396Z","steps":["trace[1116404040] 'process raft request' (duration: 1.4422781s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:11.210706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:09.767848Z","time spent":"1.442721192s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:41:11.676712Z","caller":"traceutil/trace.go:171","msg":"trace[1070414470] linearizableReadLoop","detail":"{readStateIndex:8207; appliedIndex:8203; }","duration":"1.890049322s","start":"2026-06-05T23:41:09.786641Z","end":"2026-06-05T23:41:11.67669Z","steps":["trace[1070414470] 'read index received' (duration: 1.423511944s)","trace[1070414470] 'applied index is now lower than readState.Index' (duration: 466.536268ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T23:41:11.676872Z","caller":"traceutil/trace.go:171","msg":"trace[241247510] transaction","detail":"{read_only:false; response_revision:7906; number_of_response:1; }","duration":"1.907016793s","start":"2026-06-05T23:41:09.769838Z","end":"2026-06-05T23:41:11.676855Z","steps":["trace[241247510] 'process raft request' (duration: 1.906805028s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:11.676992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:09.769819Z","time spent":"1.907096844s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:41:11.677046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.89040332s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-05T23:41:11.677079Z","caller":"traceutil/trace.go:171","msg":"trace[879417606] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7906; }","duration":"1.890467122s","start":"2026-06-05T23:41:09.786603Z","end":"2026-06-05T23:41:11.67707Z","steps":["trace[879417606] 'agreement among raft nodes before linearized reading' (duration: 1.890347549s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:11.677107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:09.786587Z","time spent":"1.890511532s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-05T23:41:11.677223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.889827976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-05T23:41:11.677248Z","caller":"traceutil/trace.go:171","msg":"trace[399900721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7906; }","duration":"1.889861868s","start":"2026-06-05T23:41:09.787379Z","end":"2026-06-05T23:41:11.677241Z","steps":["trace[399900721] 'agreement among raft nodes before linearized reading' (duration: 1.889796376s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:11.677286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:09.787375Z","time spent":"1.889890468s","remote":"127.0.0.1:49152","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-06-05T23:41:11.677308Z","caller":"traceutil/trace.go:171","msg":"trace[608453217] transaction","detail":"{read_only:false; response_revision:7904; number_of_response:1; }","duration":"1.90892117s","start":"2026-06-05T23:41:09.768375Z","end":"2026-06-05T23:41:11.677296Z","steps":["trace[608453217] 'process raft request' (duration: 1.902371997s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:11.677366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:09.768362Z","time spent":"1.908970482s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:41:11.677367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.383416014s","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-06-05T23:41:11.677408Z","caller":"traceutil/trace.go:171","msg":"trace[313393975] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:7906; }","duration":"1.383504515s","start":"2026-06-05T23:41:10.293893Z","end":"2026-06-05T23:41:11.677397Z","steps":["trace[313393975] 'agreement among raft nodes before linearized reading' (duration: 1.383424684s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:11.67743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.552069167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-05T23:41:11.677451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:10.29387Z","time spent":"1.383558987s","remote":"127.0.0.1:49116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-06-05T23:41:11.677461Z","caller":"traceutil/trace.go:171","msg":"trace[996654436] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:7906; }","duration":"1.552151069s","start":"2026-06-05T23:41:10.1253Z","end":"2026-06-05T23:41:11.677451Z","steps":["trace[996654436] 'agreement among raft nodes before linearized reading' (duration: 1.552093437s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:11.677488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:10.125279Z","time spent":"1.5522007s","remote":"127.0.0.1:51558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":30,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-06-05T23:41:11.677637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.586235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:41:11.677668Z","caller":"traceutil/trace.go:171","msg":"trace[326520213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7906; }","duration":"420.656017ms","start":"2026-06-05T23:41:11.257001Z","end":"2026-06-05T23:41:11.677657Z","steps":["trace[326520213] 'agreement among raft nodes before linearized reading' (duration: 420.607835ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:11.677687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.751780822s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-05T23:41:11.677708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:11.25698Z","time spent":"420.720868ms","remote":"127.0.0.1:48850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T23:41:11.677717Z","caller":"traceutil/trace.go:171","msg":"trace[577169173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7906; }","duration":"1.751852854s","start":"2026-06-05T23:41:09.925855Z","end":"2026-06-05T23:41:11.677708Z","steps":["trace[577169173] 'agreement among raft nodes before linearized reading' (duration: 1.751757021s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:11.677807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:09.925831Z","time spent":"1.751966567s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-05T23:41:11.677488Z","caller":"traceutil/trace.go:171","msg":"trace[847248487] transaction","detail":"{read_only:false; response_revision:7905; number_of_response:1; }","duration":"1.908557651s","start":"2026-06-05T23:41:09.768918Z","end":"2026-06-05T23:41:11.677476Z","steps":["trace[847248487] 'process raft request' (duration: 1.90769022s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:11.67789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.847195466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-05T23:41:11.677898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:09.768887Z","time spent":"1.90898058s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:41:11.677921Z","caller":"traceutil/trace.go:171","msg":"trace[1242935977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7906; }","duration":"1.847271818s","start":"2026-06-05T23:41:09.83064Z","end":"2026-06-05T23:41:11.677912Z","steps":["trace[1242935977] 'agreement among raft nodes before linearized reading' (duration: 1.847181694s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:11.67795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:09.83062Z","time spent":"1.847323098s","remote":"127.0.0.1:49152","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-06-05T23:41:11.677989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.403043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:12 size:4389"} {"level":"info","ts":"2026-06-05T23:41:11.678022Z","caller":"traceutil/trace.go:171","msg":"trace[1443977443] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:12; response_revision:7906; }","duration":"912.495765ms","start":"2026-06-05T23:41:10.765517Z","end":"2026-06-05T23:41:11.678013Z","steps":["trace[1443977443] 'agreement among raft nodes before linearized reading' (duration: 912.33061ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:11.678048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:10.765473Z","time spent":"912.566237ms","remote":"127.0.0.1:48988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":12,"response size":4412,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 "} {"level":"warn","ts":"2026-06-05T23:41:11.678128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.879620621s","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-06-05T23:41:11.678174Z","caller":"traceutil/trace.go:171","msg":"trace[1610239206] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7906; }","duration":"1.879707483s","start":"2026-06-05T23:41:09.798452Z","end":"2026-06-05T23:41:11.67816Z","steps":["trace[1610239206] 'agreement among raft nodes before linearized reading' (duration: 1.879586781s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:11.678216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:09.798434Z","time spent":"1.879770135s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T23:41:12.260862Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375043376,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T23:41:13.09186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.411974ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T23:41:13.092103Z","caller":"traceutil/trace.go:171","msg":"trace[466960443] transaction","detail":"{read_only:false; response_revision:7907; number_of_response:1; }","duration":"1.408316093s","start":"2026-06-05T23:41:11.683756Z","end":"2026-06-05T23:41:13.092072Z","steps":["trace[466960443] 'process raft request' (duration: 859.315849ms)","trace[466960443] 'compare' (duration: 547.051176ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T23:41:13.09226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:11.683732Z","time spent":"1.408472306s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:41:13.498283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.102355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T23:41:13.49858Z","caller":"traceutil/trace.go:171","msg":"trace[1325466780] transaction","detail":"{read_only:false; response_revision:7912; number_of_response:1; }","duration":"282.310664ms","start":"2026-06-05T23:41:13.216252Z","end":"2026-06-05T23:41:13.498563Z","steps":["trace[1325466780] 'process raft request' (duration: 282.272253ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:41:13.498771Z","caller":"traceutil/trace.go:171","msg":"trace[1341582727] transaction","detail":"{read_only:false; response_revision:7910; number_of_response:1; }","duration":"1.814205348s","start":"2026-06-05T23:41:11.684534Z","end":"2026-06-05T23:41:13.498739Z","steps":["trace[1341582727] 'process raft request' (duration: 1.813918961s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:41:13.499024Z","caller":"traceutil/trace.go:171","msg":"trace[790440392] transaction","detail":"{read_only:false; response_revision:7908; number_of_response:1; }","duration":"1.815120532s","start":"2026-06-05T23:41:11.683888Z","end":"2026-06-05T23:41:13.499009Z","steps":["trace[790440392] 'process raft request' (duration: 1.560203667s)","trace[790440392] 'compare' (duration: 253.88807ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T23:41:13.499124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:11.683873Z","time spent":"1.815193783s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:41:13.499124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:11.684521Z","time spent":"1.814515637s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:41:13.499169Z","caller":"traceutil/trace.go:171","msg":"trace[918772443] transaction","detail":"{read_only:false; response_revision:7909; number_of_response:1; }","duration":"1.815132201s","start":"2026-06-05T23:41:11.684026Z","end":"2026-06-05T23:41:13.499158Z","steps":["trace[918772443] 'process raft request' (duration: 1.814345122s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:13.499287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:11.683995Z","time spent":"1.815228294s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:41:13.498874Z","caller":"traceutil/trace.go:171","msg":"trace[1039848117] transaction","detail":"{read_only:false; response_revision:7911; number_of_response:1; }","duration":"1.814163047s","start":"2026-06-05T23:41:11.6847Z","end":"2026-06-05T23:41:13.498863Z","steps":["trace[1039848117] 'process raft request' (duration: 1.813789668s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:41:13.498766Z","caller":"traceutil/trace.go:171","msg":"trace[987002577] linearizableReadLoop","detail":"{readStateIndex:8212; appliedIndex:8207; }","duration":"1.738074466s","start":"2026-06-05T23:41:11.760661Z","end":"2026-06-05T23:41:13.498736Z","steps":["trace[987002577] 'read index received' (duration: 782.425028ms)","trace[987002577] 'applied index is now lower than readState.Index' (duration: 955.647168ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T23:41:13.499389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:11.684692Z","time spent":"1.81467451s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:41:13.498965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.738281601s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/auth-system/\" range_end:\"/registry/pods/auth-system0\" limit:500 ","response":"range_response_count:1 size:7961"} {"level":"warn","ts":"2026-06-05T23:41:13.499473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294025528s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:41:13.499521Z","caller":"traceutil/trace.go:171","msg":"trace[1400069124] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:7912; }","duration":"1.294118241s","start":"2026-06-05T23:41:12.205389Z","end":"2026-06-05T23:41:13.499507Z","steps":["trace[1400069124] 'agreement among raft nodes before linearized reading' (duration: 1.294033149s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:13.499562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:12.205357Z","time spent":"1.294195414s","remote":"127.0.0.1:59744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-06-05T23:41:13.499567Z","caller":"traceutil/trace.go:171","msg":"trace[925477193] range","detail":"{range_begin:/registry/pods/auth-system/; range_end:/registry/pods/auth-system0; response_count:1; response_revision:7912; }","duration":"1.738874866s","start":"2026-06-05T23:41:11.760626Z","end":"2026-06-05T23:41:13.499501Z","steps":["trace[925477193] 'agreement among raft nodes before linearized reading' (duration: 1.738190089s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:13.499745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.833257ms","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":"warn","ts":"2026-06-05T23:41:13.499764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:11.760615Z","time spent":"1.739071961s","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":7984,"request content":"key:\"/registry/pods/auth-system/\" range_end:\"/registry/pods/auth-system0\" limit:500 "} {"level":"info","ts":"2026-06-05T23:41:13.499783Z","caller":"traceutil/trace.go:171","msg":"trace[1341552930] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:7912; }","duration":"859.872008ms","start":"2026-06-05T23:41:12.639902Z","end":"2026-06-05T23:41:13.499774Z","steps":["trace[1341552930] 'agreement among raft nodes before linearized reading' (duration: 859.796076ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:13.499812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:12.639866Z","time spent":"859.93883ms","remote":"127.0.0.1:49208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":42,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-06-05T23:41:13.499485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.649703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T23:41:13.500039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.242077997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T23:41:13.500056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.367411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-05T23:41:13.50006Z","caller":"traceutil/trace.go:171","msg":"trace[1271652466] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:7912; }","duration":"619.246537ms","start":"2026-06-05T23:41:12.880786Z","end":"2026-06-05T23:41:13.500033Z","steps":["trace[1271652466] 'agreement among raft nodes before linearized reading' (duration: 618.665803ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:41:13.500086Z","caller":"traceutil/trace.go:171","msg":"trace[196936865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7912; }","duration":"1.242159609s","start":"2026-06-05T23:41:12.257912Z","end":"2026-06-05T23:41:13.500072Z","steps":["trace[196936865] 'agreement among raft nodes before linearized reading' (duration: 1.242099257s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:41:13.500088Z","caller":"traceutil/trace.go:171","msg":"trace[856196724] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:7912; }","duration":"885.399422ms","start":"2026-06-05T23:41:12.61468Z","end":"2026-06-05T23:41:13.500079Z","steps":["trace[856196724] 'agreement among raft nodes before linearized reading' (duration: 885.3071ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:13.500121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:12.257889Z","time spent":"1.242226131s","remote":"127.0.0.1:48850","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-06-05T23:41:13.500124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:12.614614Z","time spent":"885.504045ms","remote":"127.0.0.1:48906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.242\" "} {"level":"warn","ts":"2026-06-05T23:41:13.50014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:12.880764Z","time spent":"619.35351ms","remote":"127.0.0.1:51552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-06-05T23:41:14.195411Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375043394,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T23:41:14.696071Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375043394,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T23:41:15.197121Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375043394,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T23:41:15.697732Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114837375043394,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T23:41:15.741312Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.047023294s","expected-duration":"1s"} {"level":"info","ts":"2026-06-05T23:41:15.743942Z","caller":"traceutil/trace.go:171","msg":"trace[978598390] transaction","detail":"{read_only:false; response_revision:7915; number_of_response:1; }","duration":"2.061200306s","start":"2026-06-05T23:41:13.682711Z","end":"2026-06-05T23:41:15.743911Z","steps":["trace[978598390] 'process raft request' (duration: 2.058795146s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:41:15.744013Z","caller":"traceutil/trace.go:171","msg":"trace[1277730001] transaction","detail":"{read_only:false; response_revision:7917; number_of_response:1; }","duration":"1.861268769s","start":"2026-06-05T23:41:13.882719Z","end":"2026-06-05T23:41:15.743988Z","steps":["trace[1277730001] 'process raft request' (duration: 1.861186407s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:41:15.744022Z","caller":"traceutil/trace.go:171","msg":"trace[1188112041] transaction","detail":"{read_only:false; response_revision:7916; number_of_response:1; }","duration":"2.059929385s","start":"2026-06-05T23:41:13.684045Z","end":"2026-06-05T23:41:15.743974Z","steps":["trace[1188112041] 'process raft request' (duration: 2.059799872s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:15.744116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:13.682687Z","time spent":"2.061333769s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:41:15.744192Z","caller":"traceutil/trace.go:171","msg":"trace[11017362] transaction","detail":"{read_only:false; response_revision:7918; number_of_response:1; }","duration":"1.705476927s","start":"2026-06-05T23:41:14.038694Z","end":"2026-06-05T23:41:15.744171Z","steps":["trace[11017362] 'process raft request' (duration: 1.705259542s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:41:15.744176Z","caller":"traceutil/trace.go:171","msg":"trace[1710006571] linearizableReadLoop","detail":"{readStateIndex:8221; appliedIndex:8216; }","duration":"2.049490194s","start":"2026-06-05T23:41:13.694662Z","end":"2026-06-05T23:41:15.744152Z","steps":["trace[1710006571] 'read index received' (duration: 2.046841099s)","trace[1710006571] 'applied index is now lower than readState.Index' (duration: 2.648155ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T23:41:15.744215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:13.8827Z","time spent":"1.861400781s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:41:15.744265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:14.038676Z","time spent":"1.705549639s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T23:41:15.744289Z","caller":"traceutil/trace.go:171","msg":"trace[424264970] transaction","detail":"{read_only:false; response_revision:7919; number_of_response:1; }","duration":"238.807771ms","start":"2026-06-05T23:41:15.505468Z","end":"2026-06-05T23:41:15.744275Z","steps":["trace[424264970] 'process raft request' (duration: 238.580356ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:15.744119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:13.684037Z","time spent":"2.060031227s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T23:41:15.744509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.744426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T23:41:15.744549Z","caller":"traceutil/trace.go:171","msg":"trace[1962261957] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:7920; }","duration":"539.840199ms","start":"2026-06-05T23:41:15.204697Z","end":"2026-06-05T23:41:15.744538Z","steps":["trace[1962261957] 'agreement among raft nodes before linearized reading' (duration: 539.757257ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:15.744566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.516450021s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-06-05T23:41:15.74458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:15.204676Z","time spent":"539.89446ms","remote":"127.0.0.1:49046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":4,"response size":30,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-06-05T23:41:15.744619Z","caller":"traceutil/trace.go:171","msg":"trace[1393217952] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7920; }","duration":"1.516526593s","start":"2026-06-05T23:41:14.228078Z","end":"2026-06-05T23:41:15.744604Z","steps":["trace[1393217952] 'agreement among raft nodes before linearized reading' (duration: 1.51638403s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:15.74463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.622118256s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-05T23:41:15.744661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:14.228065Z","time spent":"1.516585864s","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-05T23:41:15.744675Z","caller":"traceutil/trace.go:171","msg":"trace[2040144314] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:7920; }","duration":"1.622171667s","start":"2026-06-05T23:41:14.122487Z","end":"2026-06-05T23:41:15.744658Z","steps":["trace[2040144314] 'agreement among raft nodes before linearized reading' (duration: 1.622108666s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:15.744717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:14.12248Z","time spent":"1.622223838s","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-05T23:41:15.744763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.05009486s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/\" range_end:\"/registry/pods/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:41:15.744794Z","caller":"traceutil/trace.go:171","msg":"trace[382461456] range","detail":"{range_begin:/registry/pods/default/; range_end:/registry/pods/default0; response_count:0; response_revision:7920; }","duration":"2.05012778s","start":"2026-06-05T23:41:13.694658Z","end":"2026-06-05T23:41:15.744785Z","steps":["trace[382461456] 'agreement among raft nodes before linearized reading' (duration: 2.050076619s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:15.744825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:13.694629Z","time spent":"2.050188542s","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/pods/default/\" range_end:\"/registry/pods/default0\" limit:500 "} {"level":"warn","ts":"2026-06-05T23:41:15.744987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.696131734s","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":"warn","ts":"2026-06-05T23:41:15.745026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.58648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-05T23:41:15.745033Z","caller":"traceutil/trace.go:171","msg":"trace[1720245788] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:7920; }","duration":"1.696197656s","start":"2026-06-05T23:41:14.048821Z","end":"2026-06-05T23:41:15.745019Z","steps":["trace[1720245788] 'agreement among raft nodes before linearized reading' (duration: 1.696075693s)"],"step_count":1} {"level":"info","ts":"2026-06-05T23:41:15.745054Z","caller":"traceutil/trace.go:171","msg":"trace[1501103960] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7920; }","duration":"239.637362ms","start":"2026-06-05T23:41:15.505408Z","end":"2026-06-05T23:41:15.745046Z","steps":["trace[1501103960] 'agreement among raft nodes before linearized reading' (duration: 239.524549ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:15.745077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:14.048814Z","time spent":"1.696247488s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-06-05T23:41:15.745166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.806252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-05T23:41:15.74519Z","caller":"traceutil/trace.go:171","msg":"trace[236050902] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7920; }","duration":"240.859754ms","start":"2026-06-05T23:41:15.504323Z","end":"2026-06-05T23:41:15.745183Z","steps":["trace[236050902] 'agreement among raft nodes before linearized reading' (duration: 240.791361ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:15.745234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.697480339s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-05T23:41:15.745272Z","caller":"traceutil/trace.go:171","msg":"trace[889761686] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:7920; }","duration":"1.6975174s","start":"2026-06-05T23:41:14.047743Z","end":"2026-06-05T23:41:15.74526Z","steps":["trace[889761686] 'agreement among raft nodes before linearized reading' (duration: 1.697409106s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:15.745314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:14.047682Z","time spent":"1.697622082s","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-06-05T23:41:15.745382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.995111ms","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-06-05T23:41:15.745412Z","caller":"traceutil/trace.go:171","msg":"trace[602093594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7920; }","duration":"242.062383ms","start":"2026-06-05T23:41:15.503339Z","end":"2026-06-05T23:41:15.745401Z","steps":["trace[602093594] 'agreement among raft nodes before linearized reading' (duration: 241.996191ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:15.745491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.425922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:41:15.745513Z","caller":"traceutil/trace.go:171","msg":"trace[1820680440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7920; }","duration":"488.496283ms","start":"2026-06-05T23:41:15.257009Z","end":"2026-06-05T23:41:15.745505Z","steps":["trace[1820680440] 'agreement among raft nodes before linearized reading' (duration: 488.464763ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:15.745535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:15.25699Z","time spent":"488.539465ms","remote":"127.0.0.1:48850","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-06-05T23:41:15.745318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.891959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-05T23:41:15.745602Z","caller":"traceutil/trace.go:171","msg":"trace[1099521571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7920; }","duration":"242.203566ms","start":"2026-06-05T23:41:15.503383Z","end":"2026-06-05T23:41:15.745587Z","steps":["trace[1099521571] 'agreement among raft nodes before linearized reading' (duration: 241.885189ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:15.745976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.728833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T23:41:15.746014Z","caller":"traceutil/trace.go:171","msg":"trace[1787785646] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:7920; }","duration":"776.809225ms","start":"2026-06-05T23:41:14.969194Z","end":"2026-06-05T23:41:15.746003Z","steps":["trace[1787785646] 'agreement among raft nodes before linearized reading' (duration: 776.735613ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:15.746023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.40786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-06-05T23:41:15.746047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:14.969173Z","time spent":"776.865507ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true "} {"level":"info","ts":"2026-06-05T23:41:15.746074Z","caller":"traceutil/trace.go:171","msg":"trace[475070983] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7920; }","duration":"649.500612ms","start":"2026-06-05T23:41:15.09656Z","end":"2026-06-05T23:41:15.746061Z","steps":["trace[475070983] 'agreement among raft nodes before linearized reading' (duration: 649.367159ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:15.746123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:15.096539Z","time spent":"649.572195ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T23:41:15.744566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.992880649s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/auth-system/keycloak-0\" ","response":"range_response_count:1 size:7961"} {"level":"info","ts":"2026-06-05T23:41:15.74666Z","caller":"traceutil/trace.go:171","msg":"trace[1284259411] range","detail":"{range_begin:/registry/pods/auth-system/keycloak-0; range_end:; response_count:1; response_revision:7920; }","duration":"1.9950095s","start":"2026-06-05T23:41:13.751641Z","end":"2026-06-05T23:41:15.746651Z","steps":["trace[1284259411] 'agreement among raft nodes before linearized reading' (duration: 1.992796686s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T23:41:15.746716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T23:41:13.751634Z","time spent":"1.995071803s","remote":"127.0.0.1:49072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":7984,"request content":"key:\"/registry/pods/auth-system/keycloak-0\" "}