{"level":"warn","ts":"2026-04-09T17:30:35.584586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.335951ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-04-09T17:30:35.584725Z","caller":"traceutil/trace.go:171","msg":"trace[195032997] linearizableReadLoop","detail":"{readStateIndex:44437; appliedIndex:44436; }","duration":"233.222111ms","start":"2026-04-09T17:30:35.351479Z","end":"2026-04-09T17:30:35.584701Z","steps":["trace[195032997] 'read index received' (duration: 47.727416ms)","trace[195032997] 'applied index is now lower than readState.Index' (duration: 185.492655ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T17:30:35.585103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.523925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-09T17:30:35.585149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.581313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/rook-ceph-mgr-cluster\" ","response":"range_response_count:1 size:731"} {"level":"warn","ts":"2026-04-09T17:30:35.585169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.174495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-09T17:30:35.585188Z","caller":"traceutil/trace.go:171","msg":"trace[1723358724] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:43002; }","duration":"179.654527ms","start":"2026-04-09T17:30:35.405514Z","end":"2026-04-09T17:30:35.585168Z","steps":["trace[1723358724] 'agreement among raft nodes before linearized reading' (duration: 179.502294ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:30:35.585224Z","caller":"traceutil/trace.go:171","msg":"trace[1645041572] range","detail":"{range_begin:/registry/clusterrolebindings/rook-ceph-mgr-cluster; range_end:; response_count:1; response_revision:43002; }","duration":"211.689446ms","start":"2026-04-09T17:30:35.373514Z","end":"2026-04-09T17:30:35.585203Z","steps":["trace[1645041572] 'agreement among raft nodes before linearized reading' (duration: 211.507652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:35.585211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.37736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-04-09T17:30:35.585229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.763575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replicaset-controller\" ","response":"range_response_count:1 size:842"} {"level":"warn","ts":"2026-04-09T17:30:35.585317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.327442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-09T17:30:35.585332Z","caller":"traceutil/trace.go:171","msg":"trace[1113348718] range","detail":"{range_begin:/registry/clusterroles/system:controller:replicaset-controller; range_end:; response_count:1; response_revision:43002; }","duration":"117.920469ms","start":"2026-04-09T17:30:35.467386Z","end":"2026-04-09T17:30:35.585307Z","steps":["trace[1113348718] 'agreement among raft nodes before linearized reading' (duration: 117.665362ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:30:35.585323Z","caller":"traceutil/trace.go:171","msg":"trace[790487450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:43002; }","duration":"217.512393ms","start":"2026-04-09T17:30:35.367776Z","end":"2026-04-09T17:30:35.585289Z","steps":["trace[790487450] 'agreement among raft nodes before linearized reading' (duration: 217.212196ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:30:35.585374Z","caller":"traceutil/trace.go:171","msg":"trace[325334283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:43002; }","duration":"172.63449ms","start":"2026-04-09T17:30:35.412721Z","end":"2026-04-09T17:30:35.585356Z","steps":["trace[325334283] 'agreement among raft nodes before linearized reading' (duration: 172.489776ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:30:35.585229Z","caller":"traceutil/trace.go:171","msg":"trace[1269171340] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:43002; }","duration":"217.247566ms","start":"2026-04-09T17:30:35.367962Z","end":"2026-04-09T17:30:35.58521Z","steps":["trace[1269171340] 'agreement among raft nodes before linearized reading' (duration: 217.056572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:35.585226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.735563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-09T17:30:35.585525Z","caller":"traceutil/trace.go:171","msg":"trace[1106127594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:43002; }","duration":"234.080792ms","start":"2026-04-09T17:30:35.35143Z","end":"2026-04-09T17:30:35.585511Z","steps":["trace[1106127594] 'agreement among raft nodes before linearized reading' (duration: 233.555629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:35.585153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.317378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-09T17:30:35.585618Z","caller":"traceutil/trace.go:171","msg":"trace[170502576] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:43002; }","duration":"217.836201ms","start":"2026-04-09T17:30:35.36777Z","end":"2026-04-09T17:30:35.585606Z","steps":["trace[170502576] 'agreement among raft nodes before linearized reading' (duration: 217.287428ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:30:36.15966Z","caller":"traceutil/trace.go:171","msg":"trace[1207948328] linearizableReadLoop","detail":"{readStateIndex:44442; appliedIndex:44441; }","duration":"453.977866ms","start":"2026-04-09T17:30:35.705656Z","end":"2026-04-09T17:30:36.159633Z","steps":["trace[1207948328] 'read index received' (duration: 358.194257ms)","trace[1207948328] 'applied index is now lower than readState.Index' (duration: 95.782749ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T17:30:36.159828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.161111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-09T17:30:36.159798Z","caller":"traceutil/trace.go:171","msg":"trace[654627098] transaction","detail":"{read_only:false; response_revision:43007; number_of_response:1; }","duration":"463.788823ms","start":"2026-04-09T17:30:35.695975Z","end":"2026-04-09T17:30:36.159764Z","steps":["trace[654627098] 'process raft request' (duration: 367.861021ms)","trace[654627098] 'compare' (duration: 95.683896ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:30:36.159863Z","caller":"traceutil/trace.go:171","msg":"trace[970177195] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:43007; }","duration":"454.215952ms","start":"2026-04-09T17:30:35.705635Z","end":"2026-04-09T17:30:36.159851Z","steps":["trace[970177195] 'agreement among raft nodes before linearized reading' (duration: 454.11904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:36.159903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:30:35.705567Z","time spent":"454.325335ms","remote":"127.0.0.1:34482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":30,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"warn","ts":"2026-04-09T17:30:36.16003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:30:35.695956Z","time spent":"463.938477ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T17:30:36.193917Z","caller":"traceutil/trace.go:171","msg":"trace[1974914753] transaction","detail":"{read_only:false; response_revision:43008; number_of_response:1; }","duration":"452.862077ms","start":"2026-04-09T17:30:35.741029Z","end":"2026-04-09T17:30:36.193891Z","steps":["trace[1974914753] 'process raft request' (duration: 452.644512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:36.194071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:30:35.741004Z","time spent":"452.990591ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T17:30:36.1941Z","caller":"traceutil/trace.go:171","msg":"trace[1470198193] transaction","detail":"{read_only:false; response_revision:43009; number_of_response:1; }","duration":"431.221702ms","start":"2026-04-09T17:30:35.762867Z","end":"2026-04-09T17:30:36.194089Z","steps":["trace[1470198193] 'process raft request' (duration: 430.981935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:36.194163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:30:35.762829Z","time spent":"431.300664ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T17:30:36.194303Z","caller":"traceutil/trace.go:171","msg":"trace[762192707] transaction","detail":"{read_only:false; response_revision:43010; number_of_response:1; }","duration":"431.363355ms","start":"2026-04-09T17:30:35.76291Z","end":"2026-04-09T17:30:36.194273Z","steps":["trace[762192707] 'process raft request' (duration: 431.136069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:36.194405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.82906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-04-09T17:30:36.194477Z","caller":"traceutil/trace.go:171","msg":"trace[965524393] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:43010; }","duration":"479.962113ms","start":"2026-04-09T17:30:35.714498Z","end":"2026-04-09T17:30:36.19446Z","steps":["trace[965524393] 'agreement among raft nodes before linearized reading' (duration: 479.771178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:36.194477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.551625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replication-controller\" ","response":"range_response_count:1 size:829"} {"level":"warn","ts":"2026-04-09T17:30:36.19451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:30:35.714467Z","time spent":"480.037545ms","remote":"127.0.0.1:44480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":143,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-04-09T17:30:36.194518Z","caller":"traceutil/trace.go:171","msg":"trace[458678488] range","detail":"{range_begin:/registry/clusterroles/system:controller:replication-controller; range_end:; response_count:1; response_revision:43010; }","duration":"390.622256ms","start":"2026-04-09T17:30:35.803884Z","end":"2026-04-09T17:30:36.194506Z","steps":["trace[458678488] 'agreement among raft nodes before linearized reading' (duration: 390.541874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:36.194526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.16534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-09T17:30:36.194553Z","caller":"traceutil/trace.go:171","msg":"trace[925498142] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/securitygroups/; range_end:/registry/openstack.k-orc.cloud/securitygroups0; response_count:0; response_revision:43010; }","duration":"330.234671ms","start":"2026-04-09T17:30:35.864312Z","end":"2026-04-09T17:30:36.194547Z","steps":["trace[925498142] 'agreement among raft nodes before linearized reading' (duration: 330.18711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:36.194552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:30:35.803869Z","time spent":"390.676128ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":853,"request content":"key:\"/registry/clusterroles/system:controller:replication-controller\" "} {"level":"warn","ts":"2026-04-09T17:30:36.194579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:30:35.864289Z","time spent":"330.284922ms","remote":"127.0.0.1:48850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true "} {"level":"warn","ts":"2026-04-09T17:30:36.194495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:30:35.762879Z","time spent":"431.51731ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T17:30:36.194865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.116635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130873"} {"level":"warn","ts":"2026-04-09T17:30:36.194871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.612332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/rook-ceph-mgr-cluster-openstack\" ","response":"range_response_count:1 size:746"} {"level":"info","ts":"2026-04-09T17:30:36.194918Z","caller":"traceutil/trace.go:171","msg":"trace[366189429] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:43010; }","duration":"474.189327ms","start":"2026-04-09T17:30:35.720715Z","end":"2026-04-09T17:30:36.194905Z","steps":["trace[366189429] 'agreement among raft nodes before linearized reading' (duration: 474.003683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:36.194951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:30:35.7207Z","time spent":"474.243238ms","remote":"127.0.0.1:44468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":130897,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-04-09T17:30:36.194974Z","caller":"traceutil/trace.go:171","msg":"trace[188923814] range","detail":"{range_begin:/registry/clusterrolebindings/rook-ceph-mgr-cluster-openstack; range_end:; response_count:1; response_revision:43010; }","duration":"414.747475ms","start":"2026-04-09T17:30:35.780193Z","end":"2026-04-09T17:30:36.194941Z","steps":["trace[188923814] 'agreement among raft nodes before linearized reading' (duration: 414.408997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:36.195043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:30:35.780182Z","time spent":"414.848578ms","remote":"127.0.0.1:44732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":770,"request content":"key:\"/registry/clusterrolebindings/rook-ceph-mgr-cluster-openstack\" "} {"level":"info","ts":"2026-04-09T17:30:37.439593Z","caller":"traceutil/trace.go:171","msg":"trace[1336499776] linearizableReadLoop","detail":"{readStateIndex:44447; appliedIndex:44445; }","duration":"489.125104ms","start":"2026-04-09T17:30:36.950444Z","end":"2026-04-09T17:30:37.439569Z","steps":["trace[1336499776] 'read index received' (duration: 59.125043ms)","trace[1336499776] 'applied index is now lower than readState.Index' (duration: 429.998661ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:30:37.439724Z","caller":"traceutil/trace.go:171","msg":"trace[1239788368] transaction","detail":"{read_only:false; response_revision:43012; number_of_response:1; }","duration":"498.010469ms","start":"2026-04-09T17:30:36.941676Z","end":"2026-04-09T17:30:37.439687Z","steps":["trace[1239788368] 'process raft request' (duration: 497.742262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:37.4398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.343781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:root-ca-cert-publisher\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-04-09T17:30:37.439845Z","caller":"traceutil/trace.go:171","msg":"trace[1258180804] range","detail":"{range_begin:/registry/clusterroles/system:controller:root-ca-cert-publisher; range_end:; response_count:1; response_revision:43012; }","duration":"489.455123ms","start":"2026-04-09T17:30:36.950378Z","end":"2026-04-09T17:30:37.439833Z","steps":["trace[1258180804] 'agreement among raft nodes before linearized reading' (duration: 489.32572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:37.439871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:30:36.950353Z","time spent":"489.512464ms","remote":"127.0.0.1:44726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":697,"request content":"key:\"/registry/clusterroles/system:controller:root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-04-09T17:30:37.439918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:30:36.94165Z","time spent":"498.188853ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T17:30:37.453039Z","caller":"traceutil/trace.go:171","msg":"trace[21050015] transaction","detail":"{read_only:false; response_revision:43013; number_of_response:1; }","duration":"414.370026ms","start":"2026-04-09T17:30:37.038649Z","end":"2026-04-09T17:30:37.453019Z","steps":["trace[21050015] 'process raft request' (duration: 414.232783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:37.453167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:30:37.038625Z","time spent":"414.475028ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T17:30:37.453314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.4346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-09T17:30:37.45335Z","caller":"traceutil/trace.go:171","msg":"trace[233348241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:43015; }","duration":"249.506072ms","start":"2026-04-09T17:30:37.203834Z","end":"2026-04-09T17:30:37.45334Z","steps":["trace[233348241] 'agreement among raft nodes before linearized reading' (duration: 249.439001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:37.45341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.048856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/rook-ceph-system\" ","response":"range_response_count:1 size:1014"} {"level":"info","ts":"2026-04-09T17:30:37.453458Z","caller":"traceutil/trace.go:171","msg":"trace[620937215] range","detail":"{range_begin:/registry/clusterrolebindings/rook-ceph-system; range_end:; response_count:1; response_revision:43015; }","duration":"501.131928ms","start":"2026-04-09T17:30:36.952316Z","end":"2026-04-09T17:30:37.453448Z","steps":["trace[620937215] 'agreement among raft nodes before linearized reading' (duration: 500.960274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:37.453488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:30:36.952299Z","time spent":"501.183889ms","remote":"127.0.0.1:44732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":1038,"request content":"key:\"/registry/clusterrolebindings/rook-ceph-system\" "} {"level":"info","ts":"2026-04-09T17:30:37.453427Z","caller":"traceutil/trace.go:171","msg":"trace[428827520] transaction","detail":"{read_only:false; response_revision:43014; number_of_response:1; }","duration":"340.819058ms","start":"2026-04-09T17:30:37.112594Z","end":"2026-04-09T17:30:37.453413Z","steps":["trace[428827520] 'process raft request' (duration: 340.387997ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:30:37.453535Z","caller":"traceutil/trace.go:171","msg":"trace[1408841672] transaction","detail":"{read_only:false; response_revision:43015; number_of_response:1; }","duration":"111.156678ms","start":"2026-04-09T17:30:37.342355Z","end":"2026-04-09T17:30:37.453512Z","steps":["trace[1408841672] 'process raft request' (duration: 110.797129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:37.453647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:30:37.112568Z","time spent":"341.003373ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T17:30:37.453687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.518453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-09T17:30:37.453322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.10437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-09T17:30:37.453787Z","caller":"traceutil/trace.go:171","msg":"trace[799116823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:43015; }","duration":"236.651197ms","start":"2026-04-09T17:30:37.217114Z","end":"2026-04-09T17:30:37.453766Z","steps":["trace[799116823] 'agreement among raft nodes before linearized reading' (duration: 236.436742ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:30:37.453839Z","caller":"traceutil/trace.go:171","msg":"trace[525346434] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:43015; }","duration":"207.601393ms","start":"2026-04-09T17:30:37.246172Z","end":"2026-04-09T17:30:37.453774Z","steps":["trace[525346434] 'agreement among raft nodes before linearized reading' (duration: 207.09532ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:30:37.745168Z","caller":"traceutil/trace.go:171","msg":"trace[1555352535] linearizableReadLoop","detail":"{readStateIndex:44451; appliedIndex:44450; }","duration":"201.736385ms","start":"2026-04-09T17:30:37.543399Z","end":"2026-04-09T17:30:37.745135Z","steps":["trace[1555352535] 'read index received' (duration: 199.975711ms)","trace[1555352535] 'applied index is now lower than readState.Index' (duration: 1.758704ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:30:37.745242Z","caller":"traceutil/trace.go:171","msg":"trace[2141507156] transaction","detail":"{read_only:false; response_revision:43016; number_of_response:1; }","duration":"285.091651ms","start":"2026-04-09T17:30:37.460117Z","end":"2026-04-09T17:30:37.745208Z","steps":["trace[2141507156] 'process raft request' (duration: 283.338657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:37.745422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.997342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:route-controller\" ","response":"range_response_count:1 size:676"} {"level":"info","ts":"2026-04-09T17:30:37.745475Z","caller":"traceutil/trace.go:171","msg":"trace[1888547353] range","detail":"{range_begin:/registry/clusterroles/system:controller:route-controller; range_end:; response_count:1; response_revision:43016; }","duration":"202.098674ms","start":"2026-04-09T17:30:37.543362Z","end":"2026-04-09T17:30:37.74546Z","steps":["trace[1888547353] 'agreement among raft nodes before linearized reading' (duration: 201.963301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:37.745592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.080874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-09T17:30:37.745627Z","caller":"traceutil/trace.go:171","msg":"trace[1150890817] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:43016; }","duration":"126.149516ms","start":"2026-04-09T17:30:37.619467Z","end":"2026-04-09T17:30:37.745617Z","steps":["trace[1150890817] 'agreement among raft nodes before linearized reading' (duration: 126.028663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:37.745667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.634898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-04-09T17:30:37.745686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.516498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/secretgen-controller-cluster-role-binding\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-04-09T17:30:37.745717Z","caller":"traceutil/trace.go:171","msg":"trace[280779428] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:43016; }","duration":"126.724091ms","start":"2026-04-09T17:30:37.618982Z","end":"2026-04-09T17:30:37.745706Z","steps":["trace[280779428] 'agreement among raft nodes before linearized reading' (duration: 126.594177ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:30:37.745737Z","caller":"traceutil/trace.go:171","msg":"trace[639982824] range","detail":"{range_begin:/registry/clusterrolebindings/secretgen-controller-cluster-role-binding; range_end:; response_count:1; response_revision:43016; }","duration":"178.614611ms","start":"2026-04-09T17:30:37.567105Z","end":"2026-04-09T17:30:37.74572Z","steps":["trace[639982824] 'agreement among raft nodes before linearized reading' (duration: 178.231521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:37.745811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.665258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-09T17:30:37.745844Z","caller":"traceutil/trace.go:171","msg":"trace[1709418743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:43016; }","duration":"141.700089ms","start":"2026-04-09T17:30:37.604136Z","end":"2026-04-09T17:30:37.745836Z","steps":["trace[1709418743] 'agreement among raft nodes before linearized reading' (duration: 141.606476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:37.745908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.422403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-09T17:30:37.745947Z","caller":"traceutil/trace.go:171","msg":"trace[194935723] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:43016; }","duration":"126.475114ms","start":"2026-04-09T17:30:37.619461Z","end":"2026-04-09T17:30:37.745936Z","steps":["trace[194935723] 'agreement among raft nodes before linearized reading' (duration: 126.373392ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:30:39.949226Z","caller":"traceutil/trace.go:171","msg":"trace[683565352] transaction","detail":"{read_only:false; response_revision:43031; number_of_response:1; }","duration":"130.990278ms","start":"2026-04-09T17:30:39.818204Z","end":"2026-04-09T17:30:39.949194Z","steps":["trace[683565352] 'process raft request' (duration: 51.85383ms)","trace[683565352] 'compare' (duration: 78.940784ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:30:39.951138Z","caller":"traceutil/trace.go:171","msg":"trace[1595565469] transaction","detail":"{read_only:false; response_revision:43032; number_of_response:1; }","duration":"132.829685ms","start":"2026-04-09T17:30:39.818282Z","end":"2026-04-09T17:30:39.951112Z","steps":["trace[1595565469] 'process raft request' (duration: 132.671141ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:30:39.951415Z","caller":"traceutil/trace.go:171","msg":"trace[864798263] transaction","detail":"{read_only:false; response_revision:43033; number_of_response:1; }","duration":"133.02113ms","start":"2026-04-09T17:30:39.81837Z","end":"2026-04-09T17:30:39.951391Z","steps":["trace[864798263] 'process raft request' (duration: 132.689732ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:30:39.951939Z","caller":"traceutil/trace.go:171","msg":"trace[2055203768] transaction","detail":"{read_only:false; response_revision:43034; number_of_response:1; }","duration":"133.116982ms","start":"2026-04-09T17:30:39.818771Z","end":"2026-04-09T17:30:39.951888Z","steps":["trace[2055203768] 'process raft request' (duration: 132.536468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:40.188973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.308517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-09T17:30:40.189042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.125204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-dns\" ","response":"range_response_count:1 size:577"} {"level":"info","ts":"2026-04-09T17:30:40.18907Z","caller":"traceutil/trace.go:171","msg":"trace[390013378] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:43035; }","duration":"114.453051ms","start":"2026-04-09T17:30:40.074594Z","end":"2026-04-09T17:30:40.189047Z","steps":["trace[390013378] 'count revisions from in-memory index tree' (duration: 114.244046ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:30:40.189123Z","caller":"traceutil/trace.go:171","msg":"trace[1462316760] range","detail":"{range_begin:/registry/clusterroles/system:kube-dns; range_end:; response_count:1; response_revision:43035; }","duration":"120.233546ms","start":"2026-04-09T17:30:40.068869Z","end":"2026-04-09T17:30:40.189103Z","steps":["trace[1462316760] 'range keys from in-memory index tree' (duration: 120.052562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:40.189049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.639404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpointslicemirroring-controller\" ","response":"range_response_count:1 size:808"} {"level":"info","ts":"2026-04-09T17:30:40.190027Z","caller":"traceutil/trace.go:171","msg":"trace[916308430] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpointslicemirroring-controller; range_end:; response_count:1; response_revision:43035; }","duration":"124.721381ms","start":"2026-04-09T17:30:40.065278Z","end":"2026-04-09T17:30:40.189999Z","steps":["trace[916308430] 'range keys from in-memory index tree' (duration: 123.48674ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:30:40.287111Z","caller":"traceutil/trace.go:171","msg":"trace[1846680679] transaction","detail":"{read_only:false; response_revision:43036; number_of_response:1; }","duration":"115.281602ms","start":"2026-04-09T17:30:40.171806Z","end":"2026-04-09T17:30:40.287088Z","steps":["trace[1846680679] 'process raft request' (duration: 115.025515ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:30:40.470348Z","caller":"traceutil/trace.go:171","msg":"trace[630357679] transaction","detail":"{read_only:false; response_revision:43037; number_of_response:1; }","duration":"264.342506ms","start":"2026-04-09T17:30:40.205978Z","end":"2026-04-09T17:30:40.470321Z","steps":["trace[630357679] 'process raft request' (duration: 168.578547ms)","trace[630357679] 'compare' (duration: 95.579855ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:30:40.470817Z","caller":"traceutil/trace.go:171","msg":"trace[1820273153] linearizableReadLoop","detail":"{readStateIndex:44475; appliedIndex:44472; }","duration":"183.72118ms","start":"2026-04-09T17:30:40.287058Z","end":"2026-04-09T17:30:40.470779Z","steps":["trace[1820273153] 'read index received' (duration: 87.51577ms)","trace[1820273153] 'applied index is now lower than readState.Index' (duration: 96.20382ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:30:40.471253Z","caller":"traceutil/trace.go:171","msg":"trace[1216518182] transaction","detail":"{read_only:false; response_revision:43039; number_of_response:1; }","duration":"259.369091ms","start":"2026-04-09T17:30:40.211832Z","end":"2026-04-09T17:30:40.471201Z","steps":["trace[1216518182] 'process raft request' (duration: 258.763346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:40.471317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.434408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpointslicemirroring-controller\" ","response":"range_response_count:1 size:808"} {"level":"info","ts":"2026-04-09T17:30:40.471426Z","caller":"traceutil/trace.go:171","msg":"trace[27536218] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpointslicemirroring-controller; range_end:; response_count:1; response_revision:43039; }","duration":"277.584701ms","start":"2026-04-09T17:30:40.193815Z","end":"2026-04-09T17:30:40.4714Z","steps":["trace[27536218] 'agreement among raft nodes before linearized reading' (duration: 277.284234ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:30:40.471709Z","caller":"traceutil/trace.go:171","msg":"trace[230319295] transaction","detail":"{read_only:false; response_revision:43038; number_of_response:1; }","duration":"260.486179ms","start":"2026-04-09T17:30:40.210513Z","end":"2026-04-09T17:30:40.470999Z","steps":["trace[230319295] 'process raft request' (duration: 259.755521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:40.839495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.565173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-scheduler\" ","response":"range_response_count:1 size:1382"} {"level":"info","ts":"2026-04-09T17:30:40.839552Z","caller":"traceutil/trace.go:171","msg":"trace[1100938538] range","detail":"{range_begin:/registry/clusterroles/system:kube-scheduler; range_end:; response_count:1; response_revision:43039; }","duration":"266.673705ms","start":"2026-04-09T17:30:40.572867Z","end":"2026-04-09T17:30:40.839541Z","steps":["trace[1100938538] 'range keys from in-memory index tree' (duration: 266.44546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:40.839652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.521232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ephemeral-volume-controller\" ","response":"range_response_count:1 size:784"} {"level":"info","ts":"2026-04-09T17:30:40.839714Z","caller":"traceutil/trace.go:171","msg":"trace[1181275367] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ephemeral-volume-controller; range_end:; response_count:1; response_revision:43039; }","duration":"264.622344ms","start":"2026-04-09T17:30:40.575078Z","end":"2026-04-09T17:30:40.839701Z","steps":["trace[1181275367] 'range keys from in-memory index tree' (duration: 264.407689ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:30:41.182291Z","caller":"traceutil/trace.go:171","msg":"trace[1839693034] linearizableReadLoop","detail":"{readStateIndex:44476; appliedIndex:44475; }","duration":"142.375066ms","start":"2026-04-09T17:30:41.039888Z","end":"2026-04-09T17:30:41.182263Z","steps":["trace[1839693034] 'read index received' (duration: 142.13059ms)","trace[1839693034] 'applied index is now lower than readState.Index' (duration: 243.136µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T17:30:41.182564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.668243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kubelet-api-admin\" ","response":"range_response_count:1 size:663"} {"level":"info","ts":"2026-04-09T17:30:41.182964Z","caller":"traceutil/trace.go:171","msg":"trace[1135061947] range","detail":"{range_begin:/registry/clusterroles/system:kubelet-api-admin; range_end:; response_count:1; response_revision:43040; }","duration":"143.111885ms","start":"2026-04-09T17:30:41.039846Z","end":"2026-04-09T17:30:41.182958Z","steps":["trace[1135061947] 'agreement among raft nodes before linearized reading' (duration: 142.621392ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:30:41.182889Z","caller":"traceutil/trace.go:171","msg":"trace[921481642] transaction","detail":"{read_only:false; response_revision:43040; number_of_response:1; }","duration":"162.390312ms","start":"2026-04-09T17:30:41.020486Z","end":"2026-04-09T17:30:41.182877Z","steps":["trace[921481642] 'process raft request' (duration: 161.612672ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:30:51.741349Z","caller":"traceutil/trace.go:171","msg":"trace[437064089] transaction","detail":"{read_only:false; response_revision:43115; number_of_response:1; }","duration":"233.727663ms","start":"2026-04-09T17:30:51.507598Z","end":"2026-04-09T17:30:51.741326Z","steps":["trace[437064089] 'process raft request' (duration: 233.544708ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:30:51.741598Z","caller":"traceutil/trace.go:171","msg":"trace[1558314921] linearizableReadLoop","detail":"{readStateIndex:44553; appliedIndex:44551; }","duration":"232.4158ms","start":"2026-04-09T17:30:51.509164Z","end":"2026-04-09T17:30:51.74158Z","steps":["trace[1558314921] 'read index received' (duration: 38.611295ms)","trace[1558314921] 'applied index is now lower than readState.Index' (duration: 193.803575ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:30:51.741627Z","caller":"traceutil/trace.go:171","msg":"trace[767349445] transaction","detail":"{read_only:false; response_revision:43116; number_of_response:1; }","duration":"220.597981ms","start":"2026-04-09T17:30:51.521017Z","end":"2026-04-09T17:30:51.741615Z","steps":["trace[767349445] 'process raft request' (duration: 220.269213ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:30:51.741715Z","caller":"traceutil/trace.go:171","msg":"trace[1450888187] transaction","detail":"{read_only:false; response_revision:43117; number_of_response:1; }","duration":"220.246623ms","start":"2026-04-09T17:30:51.52144Z","end":"2026-04-09T17:30:51.741687Z","steps":["trace[1450888187] 'process raft request' (duration: 220.044258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:51.74178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.601996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/capi-system/capi-controller-manager\" ","response":"range_response_count:1 size:5058"} {"level":"info","ts":"2026-04-09T17:30:51.741806Z","caller":"traceutil/trace.go:171","msg":"trace[1542029924] range","detail":"{range_begin:/registry/deployments/capi-system/capi-controller-manager; range_end:; response_count:1; response_revision:43117; }","duration":"232.639298ms","start":"2026-04-09T17:30:51.509158Z","end":"2026-04-09T17:30:51.741798Z","steps":["trace[1542029924] 'agreement among raft nodes before linearized reading' (duration: 232.524214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:51.741821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.440325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/capi-kubeadm-control-plane-system/\" range_end:\"/registry/statefulsets/capi-kubeadm-control-plane-system0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-09T17:30:51.741862Z","caller":"traceutil/trace.go:171","msg":"trace[937706281] range","detail":"{range_begin:/registry/statefulsets/capi-kubeadm-control-plane-system/; range_end:/registry/statefulsets/capi-kubeadm-control-plane-system0; response_count:0; response_revision:43117; }","duration":"227.516198ms","start":"2026-04-09T17:30:51.514334Z","end":"2026-04-09T17:30:51.74185Z","steps":["trace[937706281] 'agreement among raft nodes before linearized reading' (duration: 227.432006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:51.742091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.523552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/capo-system/\" range_end:\"/registry/cronjobs/capo-system0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-09T17:30:51.742128Z","caller":"traceutil/trace.go:171","msg":"trace[1702955419] range","detail":"{range_begin:/registry/cronjobs/capo-system/; range_end:/registry/cronjobs/capo-system0; response_count:0; response_revision:43117; }","duration":"148.589293ms","start":"2026-04-09T17:30:51.593527Z","end":"2026-04-09T17:30:51.742116Z","steps":["trace[1702955419] 'agreement among raft nodes before linearized reading' (duration: 148.536731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:30:51.742188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.98056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/capi-system/capi-webhook-service\" ","response":"range_response_count:1 size:617"} {"level":"info","ts":"2026-04-09T17:30:51.742233Z","caller":"traceutil/trace.go:171","msg":"trace[192050093] range","detail":"{range_begin:/registry/services/endpoints/capi-system/capi-webhook-service; range_end:; response_count:1; response_revision:43117; }","duration":"135.063452ms","start":"2026-04-09T17:30:51.607158Z","end":"2026-04-09T17:30:51.742222Z","steps":["trace[192050093] 'agreement among raft nodes before linearized reading' (duration: 134.859197ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:02.023599Z","caller":"traceutil/trace.go:171","msg":"trace[2091365231] linearizableReadLoop","detail":"{readStateIndex:44631; appliedIndex:44630; }","duration":"149.671651ms","start":"2026-04-09T17:31:01.873895Z","end":"2026-04-09T17:31:02.023566Z","steps":["trace[2091365231] 'read index received' (duration: 149.426654ms)","trace[2091365231] 'applied index is now lower than readState.Index' (duration: 243.537µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:31:02.023727Z","caller":"traceutil/trace.go:171","msg":"trace[1913304299] transaction","detail":"{read_only:false; response_revision:43191; number_of_response:1; }","duration":"156.586065ms","start":"2026-04-09T17:31:01.867112Z","end":"2026-04-09T17:31:02.023698Z","steps":["trace[1913304299] 'process raft request' (duration: 156.236316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:31:02.023929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.00386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/\" range_end:\"/registry/persistentvolumeclaims/monitoring0\" limit:500 ","response":"range_response_count:3 size:5785"} {"level":"info","ts":"2026-04-09T17:31:02.024059Z","caller":"traceutil/trace.go:171","msg":"trace[83969899] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/; range_end:/registry/persistentvolumeclaims/monitoring0; response_count:3; response_revision:43191; }","duration":"150.191034ms","start":"2026-04-09T17:31:01.873844Z","end":"2026-04-09T17:31:02.024035Z","steps":["trace[83969899] 'agreement among raft nodes before linearized reading' (duration: 149.878596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:31:02.024079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.616856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-09T17:31:02.024147Z","caller":"traceutil/trace.go:171","msg":"trace[1520372449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:43191; }","duration":"118.721949ms","start":"2026-04-09T17:31:01.905407Z","end":"2026-04-09T17:31:02.024129Z","steps":["trace[1520372449] 'agreement among raft nodes before linearized reading' (duration: 118.471522ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:10.102247Z","caller":"traceutil/trace.go:171","msg":"trace[501268184] transaction","detail":"{read_only:false; response_revision:43248; number_of_response:1; }","duration":"302.014188ms","start":"2026-04-09T17:31:09.800209Z","end":"2026-04-09T17:31:10.102223Z","steps":["trace[501268184] 'process raft request' (duration: 301.853264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:31:10.102473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:31:09.800191Z","time spent":"302.198313ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T17:31:10.160075Z","caller":"traceutil/trace.go:171","msg":"trace[620289244] linearizableReadLoop","detail":"{readStateIndex:44690; appliedIndex:44688; }","duration":"345.603099ms","start":"2026-04-09T17:31:09.814448Z","end":"2026-04-09T17:31:10.160051Z","steps":["trace[620289244] 'read index received' (duration: 287.681127ms)","trace[620289244] 'applied index is now lower than readState.Index' (duration: 57.920602ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T17:31:10.160239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.983687ms","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:6"} {"level":"info","ts":"2026-04-09T17:31:10.160381Z","caller":"traceutil/trace.go:171","msg":"trace[1717864476] transaction","detail":"{read_only:false; response_revision:43249; number_of_response:1; }","duration":"346.112502ms","start":"2026-04-09T17:31:09.814199Z","end":"2026-04-09T17:31:10.160312Z","steps":["trace[1717864476] 'process raft request' (duration: 345.730422ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:10.160281Z","caller":"traceutil/trace.go:171","msg":"trace[453439845] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:43249; }","duration":"340.072ms","start":"2026-04-09T17:31:09.820196Z","end":"2026-04-09T17:31:10.160268Z","steps":["trace[453439845] 'agreement among raft nodes before linearized reading' (duration: 339.986888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:31:10.160524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.145603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-humdhe\" ","response":"range_response_count:1 size:693"} {"level":"warn","ts":"2026-04-09T17:31:10.16055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:31:09.820177Z","time spent":"340.359306ms","remote":"127.0.0.1:40004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"warn","ts":"2026-04-09T17:31:10.160478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.870626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/magnum-system/kube-root-ca.crt\" ","response":"range_response_count:1 size:1753"} {"level":"info","ts":"2026-04-09T17:31:10.160611Z","caller":"traceutil/trace.go:171","msg":"trace[819092531] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-humdhe; range_end:; response_count:1; response_revision:43249; }","duration":"253.269197ms","start":"2026-04-09T17:31:09.907318Z","end":"2026-04-09T17:31:10.160587Z","steps":["trace[819092531] 'agreement among raft nodes before linearized reading' (duration: 253.049131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:31:10.160446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.233071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.200\" ","response":"range_response_count:1 size:141"} {"level":"info","ts":"2026-04-09T17:31:10.160667Z","caller":"traceutil/trace.go:171","msg":"trace[742739948] range","detail":"{range_begin:/registry/configmaps/magnum-system/kube-root-ca.crt; range_end:; response_count:1; response_revision:43249; }","duration":"346.242325ms","start":"2026-04-09T17:31:09.814406Z","end":"2026-04-09T17:31:10.160648Z","steps":["trace[742739948] 'agreement among raft nodes before linearized reading' (duration: 345.765833ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:10.160689Z","caller":"traceutil/trace.go:171","msg":"trace[1410554893] range","detail":"{range_begin:/registry/masterleases/162.253.55.200; range_end:; response_count:1; response_revision:43249; }","duration":"233.495938ms","start":"2026-04-09T17:31:09.927171Z","end":"2026-04-09T17:31:10.160667Z","steps":["trace[1410554893] 'agreement among raft nodes before linearized reading' (duration: 233.167959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:31:10.160714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:31:09.81439Z","time spent":"346.311706ms","remote":"127.0.0.1:44512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":1777,"request content":"key:\"/registry/configmaps/magnum-system/kube-root-ca.crt\" "} {"level":"warn","ts":"2026-04-09T17:31:10.160856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:31:09.814179Z","time spent":"346.439769ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T17:31:15.843355Z","caller":"traceutil/trace.go:171","msg":"trace[659862184] linearizableReadLoop","detail":"{readStateIndex:44735; appliedIndex:44734; }","duration":"106.212763ms","start":"2026-04-09T17:31:15.737121Z","end":"2026-04-09T17:31:15.843334Z","steps":["trace[659862184] 'read index received' (duration: 105.902735ms)","trace[659862184] 'applied index is now lower than readState.Index' (duration: 309.258µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:31:15.843484Z","caller":"traceutil/trace.go:171","msg":"trace[1241824544] transaction","detail":"{read_only:false; response_revision:43292; number_of_response:1; }","duration":"150.683006ms","start":"2026-04-09T17:31:15.692777Z","end":"2026-04-09T17:31:15.84346Z","steps":["trace[1241824544] 'process raft request' (duration: 150.345998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:31:15.843588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.446199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/kube-prometheus-stack-kubelet\" ","response":"range_response_count:1 size:1137"} {"level":"info","ts":"2026-04-09T17:31:15.843662Z","caller":"traceutil/trace.go:171","msg":"trace[2055308195] range","detail":"{range_begin:/registry/services/specs/kube-system/kube-prometheus-stack-kubelet; range_end:; response_count:1; response_revision:43292; }","duration":"106.551122ms","start":"2026-04-09T17:31:15.737093Z","end":"2026-04-09T17:31:15.843644Z","steps":["trace[2055308195] 'agreement among raft nodes before linearized reading' (duration: 106.373497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:31:16.046726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.984334ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-04-09T17:31:17.443407Z","caller":"traceutil/trace.go:171","msg":"trace[2028761353] transaction","detail":"{read_only:false; response_revision:43306; number_of_response:1; }","duration":"122.113514ms","start":"2026-04-09T17:31:17.321267Z","end":"2026-04-09T17:31:17.443381Z","steps":["trace[2028761353] 'process raft request' (duration: 121.759775ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:17.609297Z","caller":"traceutil/trace.go:171","msg":"trace[1371771241] transaction","detail":"{read_only:false; response_revision:43307; number_of_response:1; }","duration":"105.264478ms","start":"2026-04-09T17:31:17.50401Z","end":"2026-04-09T17:31:17.609274Z","steps":["trace[1371771241] 'process raft request' (duration: 104.722125ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:18.470609Z","caller":"traceutil/trace.go:171","msg":"trace[720271643] transaction","detail":"{read_only:false; response_revision:43310; number_of_response:1; }","duration":"230.04347ms","start":"2026-04-09T17:31:18.240524Z","end":"2026-04-09T17:31:18.470567Z","steps":["trace[720271643] 'process raft request' (duration: 158.882762ms)","trace[720271643] 'compare' (duration: 70.82487ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T17:31:18.470886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.12594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-pool-overview\" ","response":"range_response_count:1 size:49606"} {"level":"info","ts":"2026-04-09T17:31:18.470711Z","caller":"traceutil/trace.go:171","msg":"trace[1089295872] linearizableReadLoop","detail":"{readStateIndex:44753; appliedIndex:44751; }","duration":"219.923476ms","start":"2026-04-09T17:31:18.250746Z","end":"2026-04-09T17:31:18.470669Z","steps":["trace[1089295872] 'read index received' (duration: 82.741091ms)","trace[1089295872] 'applied index is now lower than readState.Index' (duration: 137.176475ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:31:18.470967Z","caller":"traceutil/trace.go:171","msg":"trace[537302074] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-pool-overview; range_end:; response_count:1; response_revision:43311; }","duration":"220.239534ms","start":"2026-04-09T17:31:18.250706Z","end":"2026-04-09T17:31:18.470945Z","steps":["trace[537302074] 'agreement among raft nodes before linearized reading' (duration: 220.062799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:31:18.471189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.461929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-rbd-details\" ","response":"range_response_count:1 size:13451"} {"level":"info","ts":"2026-04-09T17:31:18.471295Z","caller":"traceutil/trace.go:171","msg":"trace[314247302] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-rbd-details; range_end:; response_count:1; response_revision:43311; }","duration":"205.607213ms","start":"2026-04-09T17:31:18.265667Z","end":"2026-04-09T17:31:18.471274Z","steps":["trace[314247302] 'agreement among raft nodes before linearized reading' (duration: 205.368137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:31:18.471436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.400242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-04-09T17:31:18.471431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.888091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-09T17:31:18.471475Z","caller":"traceutil/trace.go:171","msg":"trace[79986196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:43311; }","duration":"183.458594ms","start":"2026-04-09T17:31:18.288006Z","end":"2026-04-09T17:31:18.471464Z","steps":["trace[79986196] 'agreement among raft nodes before linearized reading' (duration: 183.362281ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:18.47149Z","caller":"traceutil/trace.go:171","msg":"trace[534128969] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:43311; }","duration":"173.966104ms","start":"2026-04-09T17:31:18.297505Z","end":"2026-04-09T17:31:18.471471Z","steps":["trace[534128969] 'agreement among raft nodes before linearized reading' (duration: 173.845941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:31:18.471503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.228605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-04-09T17:31:18.471473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.320248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-09T17:31:18.471549Z","caller":"traceutil/trace.go:171","msg":"trace[292626826] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:43311; }","duration":"173.283906ms","start":"2026-04-09T17:31:18.298253Z","end":"2026-04-09T17:31:18.471537Z","steps":["trace[292626826] 'agreement among raft nodes before linearized reading' (duration: 173.184214ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:18.471581Z","caller":"traceutil/trace.go:171","msg":"trace[677222387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:43311; }","duration":"173.464362ms","start":"2026-04-09T17:31:18.298091Z","end":"2026-04-09T17:31:18.471555Z","steps":["trace[677222387] 'agreement among raft nodes before linearized reading' (duration: 173.189635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:31:18.471553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.126685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-04-09T17:31:18.471591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.125548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-04-09T17:31:18.471477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.769621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18501"} {"level":"info","ts":"2026-04-09T17:31:18.471677Z","caller":"traceutil/trace.go:171","msg":"trace[1149740832] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:43311; }","duration":"104.011607ms","start":"2026-04-09T17:31:18.367652Z","end":"2026-04-09T17:31:18.471663Z","steps":["trace[1149740832] 'agreement among raft nodes before linearized reading' (duration: 103.622827ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:18.471682Z","caller":"traceutil/trace.go:171","msg":"trace[1756397752] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:43311; }","duration":"117.284772ms","start":"2026-04-09T17:31:18.354372Z","end":"2026-04-09T17:31:18.471657Z","steps":["trace[1756397752] 'agreement among raft nodes before linearized reading' (duration: 116.975975ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:18.471696Z","caller":"traceutil/trace.go:171","msg":"trace[1606857738] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:43311; }","duration":"166.243489ms","start":"2026-04-09T17:31:18.305394Z","end":"2026-04-09T17:31:18.471638Z","steps":["trace[1606857738] 'agreement among raft nodes before linearized reading' (duration: 165.87245ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:18.632779Z","caller":"traceutil/trace.go:171","msg":"trace[1892682628] transaction","detail":"{read_only:false; response_revision:43313; number_of_response:1; }","duration":"154.927714ms","start":"2026-04-09T17:31:18.477826Z","end":"2026-04-09T17:31:18.632754Z","steps":["trace[1892682628] 'process raft request' (duration: 96.428007ms)","trace[1892682628] 'compare' (duration: 58.328513ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:31:18.63331Z","caller":"traceutil/trace.go:171","msg":"trace[748181599] transaction","detail":"{read_only:false; response_revision:43314; number_of_response:1; }","duration":"155.400566ms","start":"2026-04-09T17:31:18.477896Z","end":"2026-04-09T17:31:18.633297Z","steps":["trace[748181599] 'process raft request' (duration: 154.810511ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:18.633413Z","caller":"traceutil/trace.go:171","msg":"trace[488900357] transaction","detail":"{read_only:false; response_revision:43315; number_of_response:1; }","duration":"155.156989ms","start":"2026-04-09T17:31:18.478221Z","end":"2026-04-09T17:31:18.633378Z","steps":["trace[488900357] 'process raft request' (duration: 154.864771ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:18.633636Z","caller":"traceutil/trace.go:171","msg":"trace[627311701] linearizableReadLoop","detail":"{readStateIndex:44759; appliedIndex:44754; }","duration":"152.107662ms","start":"2026-04-09T17:31:18.481516Z","end":"2026-04-09T17:31:18.633624Z","steps":["trace[627311701] 'read index received' (duration: 8.545476ms)","trace[627311701] 'applied index is now lower than readState.Index' (duration: 143.561136ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:31:18.633731Z","caller":"traceutil/trace.go:171","msg":"trace[99719284] transaction","detail":"{read_only:false; response_revision:43316; number_of_response:1; }","duration":"155.281802ms","start":"2026-04-09T17:31:18.478439Z","end":"2026-04-09T17:31:18.633721Z","steps":["trace[99719284] 'process raft request' (duration: 154.853151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:31:18.633852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.315577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-09T17:31:18.633894Z","caller":"traceutil/trace.go:171","msg":"trace[470663517] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:43316; }","duration":"152.403419ms","start":"2026-04-09T17:31:18.481477Z","end":"2026-04-09T17:31:18.63388Z","steps":["trace[470663517] 'agreement among raft nodes before linearized reading' (duration: 152.324677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:31:18.634125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.08302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-rbd-details\" ","response":"range_response_count:1 size:13451"} {"level":"info","ts":"2026-04-09T17:31:18.634208Z","caller":"traceutil/trace.go:171","msg":"trace[1728809820] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-dashboard-rbd-details; range_end:; response_count:1; response_revision:43316; }","duration":"146.205393ms","start":"2026-04-09T17:31:18.487974Z","end":"2026-04-09T17:31:18.63418Z","steps":["trace[1728809820] 'agreement among raft nodes before linearized reading' (duration: 145.927266ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:18.783535Z","caller":"traceutil/trace.go:171","msg":"trace[50809409] transaction","detail":"{read_only:false; response_revision:43317; number_of_response:1; }","duration":"122.268469ms","start":"2026-04-09T17:31:18.661243Z","end":"2026-04-09T17:31:18.783512Z","steps":["trace[50809409] 'process raft request' (duration: 83.732686ms)","trace[50809409] 'compare' (duration: 38.302806ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:31:18.78359Z","caller":"traceutil/trace.go:171","msg":"trace[2101960861] transaction","detail":"{read_only:false; response_revision:43318; number_of_response:1; }","duration":"120.099113ms","start":"2026-04-09T17:31:18.663467Z","end":"2026-04-09T17:31:18.783567Z","steps":["trace[2101960861] 'process raft request' (duration: 119.948709ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:18.783789Z","caller":"traceutil/trace.go:171","msg":"trace[1126866656] transaction","detail":"{read_only:false; response_revision:43319; number_of_response:1; }","duration":"120.143405ms","start":"2026-04-09T17:31:18.663633Z","end":"2026-04-09T17:31:18.783776Z","steps":["trace[1126866656] 'process raft request' (duration: 119.836947ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:20.495936Z","caller":"traceutil/trace.go:171","msg":"trace[956002621] transaction","detail":"{read_only:false; response_revision:43323; number_of_response:1; }","duration":"155.708173ms","start":"2026-04-09T17:31:20.340194Z","end":"2026-04-09T17:31:20.495902Z","steps":["trace[956002621] 'process raft request' (duration: 149.62117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:31:20.496286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.58163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-kubelet\" ","response":"range_response_count:1 size:19968"} {"level":"info","ts":"2026-04-09T17:31:20.496325Z","caller":"traceutil/trace.go:171","msg":"trace[551691911] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-kubelet; range_end:; response_count:1; response_revision:43323; }","duration":"100.657712ms","start":"2026-04-09T17:31:20.395653Z","end":"2026-04-09T17:31:20.496311Z","steps":["trace[551691911] 'agreement among raft nodes before linearized reading' (duration: 100.501299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:31:20.501761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.430588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-namespace-by-pod\" ","response":"range_response_count:1 size:10989"} {"level":"info","ts":"2026-04-09T17:31:20.501826Z","caller":"traceutil/trace.go:171","msg":"trace[1367412286] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-namespace-by-pod; range_end:; response_count:1; response_revision:43325; }","duration":"104.511399ms","start":"2026-04-09T17:31:20.397296Z","end":"2026-04-09T17:31:20.501807Z","steps":["trace[1367412286] 'agreement among raft nodes before linearized reading' (duration: 104.348925ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:20.757639Z","caller":"traceutil/trace.go:171","msg":"trace[1860624457] transaction","detail":"{read_only:false; response_revision:43327; number_of_response:1; }","duration":"116.729588ms","start":"2026-04-09T17:31:20.640882Z","end":"2026-04-09T17:31:20.757611Z","steps":["trace[1860624457] 'process raft request' (duration: 100.460847ms)","trace[1860624457] 'compare' (duration: 16.065106ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:31:20.757936Z","caller":"traceutil/trace.go:171","msg":"trace[1741357580] transaction","detail":"{read_only:false; response_revision:43328; number_of_response:1; }","duration":"115.105677ms","start":"2026-04-09T17:31:20.642797Z","end":"2026-04-09T17:31:20.757902Z","steps":["trace[1741357580] 'process raft request' (duration: 114.770789ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:20.757997Z","caller":"traceutil/trace.go:171","msg":"trace[1583813320] transaction","detail":"{read_only:false; response_revision:43329; number_of_response:1; }","duration":"115.169909ms","start":"2026-04-09T17:31:20.642801Z","end":"2026-04-09T17:31:20.757971Z","steps":["trace[1583813320] 'process raft request' (duration: 114.961024ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:20.75824Z","caller":"traceutil/trace.go:171","msg":"trace[1523127564] transaction","detail":"{read_only:false; response_revision:43330; number_of_response:1; }","duration":"114.880731ms","start":"2026-04-09T17:31:20.643343Z","end":"2026-04-09T17:31:20.758223Z","steps":["trace[1523127564] 'process raft request' (duration: 114.571264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:31:23.236752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.702743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-09T17:31:23.236831Z","caller":"traceutil/trace.go:171","msg":"trace[1054126227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:43349; }","duration":"102.818796ms","start":"2026-04-09T17:31:23.133994Z","end":"2026-04-09T17:31:23.236813Z","steps":["trace[1054126227] 'range keys from in-memory index tree' (duration: 102.631722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:31:25.098967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.982624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/monitoring/goldpinger\" ","response":"range_response_count:1 size:935"} {"level":"warn","ts":"2026-04-09T17:31:25.099098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.433075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:921"} {"level":"info","ts":"2026-04-09T17:31:25.09916Z","caller":"traceutil/trace.go:171","msg":"trace[154041465] range","detail":"{range_begin:/registry/services/endpoints/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:43363; }","duration":"147.530166ms","start":"2026-04-09T17:31:24.951614Z","end":"2026-04-09T17:31:25.099144Z","steps":["trace[154041465] 'range keys from in-memory index tree' (duration: 147.066555ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:25.099069Z","caller":"traceutil/trace.go:171","msg":"trace[657124877] range","detail":"{range_begin:/registry/services/endpoints/monitoring/goldpinger; range_end:; response_count:1; response_revision:43363; }","duration":"134.128388ms","start":"2026-04-09T17:31:24.964914Z","end":"2026-04-09T17:31:25.099043Z","steps":["trace[657124877] 'range keys from in-memory index tree' (duration: 133.744318ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:31.149174Z","caller":"traceutil/trace.go:171","msg":"trace[1173022960] linearizableReadLoop","detail":"{readStateIndex:44852; appliedIndex:44851; }","duration":"268.991934ms","start":"2026-04-09T17:31:30.880159Z","end":"2026-04-09T17:31:31.149151Z","steps":["trace[1173022960] 'read index received' (duration: 238.335949ms)","trace[1173022960] 'applied index is now lower than readState.Index' (duration: 30.654995ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:31:31.149318Z","caller":"traceutil/trace.go:171","msg":"trace[739637667] transaction","detail":"{read_only:false; response_revision:43405; number_of_response:1; }","duration":"279.591202ms","start":"2026-04-09T17:31:30.869715Z","end":"2026-04-09T17:31:31.149306Z","steps":["trace[739637667] 'process raft request' (duration: 248.696711ms)","trace[739637667] 'compare' (duration: 30.428559ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:31:31.149486Z","caller":"traceutil/trace.go:171","msg":"trace[121870379] transaction","detail":"{read_only:false; response_revision:43406; number_of_response:1; }","duration":"266.281985ms","start":"2026-04-09T17:31:30.883176Z","end":"2026-04-09T17:31:31.149458Z","steps":["trace[121870379] 'process raft request' (duration: 265.918016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:31:31.149875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.565954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/horizon-bin\" ","response":"range_response_count:1 size:22058"} {"level":"info","ts":"2026-04-09T17:31:31.149922Z","caller":"traceutil/trace.go:171","msg":"trace[1421403634] range","detail":"{range_begin:/registry/configmaps/openstack/horizon-bin; range_end:; response_count:1; response_revision:43406; }","duration":"270.649807ms","start":"2026-04-09T17:31:30.87926Z","end":"2026-04-09T17:31:31.149909Z","steps":["trace[1421403634] 'agreement among raft nodes before linearized reading' (duration: 270.239366ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:31.150141Z","caller":"traceutil/trace.go:171","msg":"trace[72688256] transaction","detail":"{read_only:false; response_revision:43407; number_of_response:1; }","duration":"261.29748ms","start":"2026-04-09T17:31:30.888817Z","end":"2026-04-09T17:31:31.150114Z","steps":["trace[72688256] 'process raft request' (duration: 260.548851ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:31:31.15039Z","caller":"traceutil/trace.go:171","msg":"trace[1538179183] transaction","detail":"{read_only:false; response_revision:43408; number_of_response:1; }","duration":"259.818112ms","start":"2026-04-09T17:31:30.890559Z","end":"2026-04-09T17:31:31.150377Z","steps":["trace[1538179183] 'process raft request' (duration: 259.484124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:31:31.150548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.505566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/heat-bin\" ","response":"range_response_count:1 size:28721"} {"level":"info","ts":"2026-04-09T17:31:31.151812Z","caller":"traceutil/trace.go:171","msg":"trace[1226800274] range","detail":"{range_begin:/registry/configmaps/openstack/heat-bin; range_end:; response_count:1; response_revision:43408; }","duration":"266.802709ms","start":"2026-04-09T17:31:30.88498Z","end":"2026-04-09T17:31:31.151783Z","steps":["trace[1226800274] 'agreement among raft nodes before linearized reading' (duration: 265.405613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:31:31.150655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.903634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-04-09T17:31:31.15249Z","caller":"traceutil/trace.go:171","msg":"trace[69470262] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:43408; }","duration":"143.764421ms","start":"2026-04-09T17:31:31.008709Z","end":"2026-04-09T17:31:31.152473Z","steps":["trace[69470262] 'agreement among raft nodes before linearized reading' (duration: 141.884913ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:32:03.769866Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41329} {"level":"info","ts":"2026-04-09T17:32:03.892508Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41329,"took":"117.731124ms","hash":4276818433,"current-db-size-bytes":55934976,"current-db-size":"56 MB","current-db-size-in-use-bytes":31211520,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-09T17:32:03.892591Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4276818433,"revision":41329,"compact-revision":39206} {"level":"info","ts":"2026-04-09T17:32:15.592113Z","caller":"traceutil/trace.go:171","msg":"trace[2016141784] transaction","detail":"{read_only:false; response_revision:43732; number_of_response:1; }","duration":"273.781624ms","start":"2026-04-09T17:32:15.318301Z","end":"2026-04-09T17:32:15.592083Z","steps":["trace[2016141784] 'process raft request' (duration: 273.63105ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:32:16.195824Z","caller":"traceutil/trace.go:171","msg":"trace[2113218579] linearizableReadLoop","detail":"{readStateIndex:45191; appliedIndex:45188; }","duration":"807.996099ms","start":"2026-04-09T17:32:15.387802Z","end":"2026-04-09T17:32:16.195798Z","steps":["trace[2113218579] 'read index received' (duration: 204.173627ms)","trace[2113218579] 'applied index is now lower than readState.Index' (duration: 603.821132ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:32:16.195948Z","caller":"traceutil/trace.go:171","msg":"trace[2070528080] transaction","detail":"{read_only:false; response_revision:43734; number_of_response:1; }","duration":"868.803454ms","start":"2026-04-09T17:32:15.327132Z","end":"2026-04-09T17:32:16.195935Z","steps":["trace[2070528080] 'process raft request' (duration: 868.620639ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:32:16.195934Z","caller":"traceutil/trace.go:171","msg":"trace[274900127] transaction","detail":"{read_only:false; response_revision:43733; number_of_response:1; }","duration":"871.534713ms","start":"2026-04-09T17:32:15.324367Z","end":"2026-04-09T17:32:16.195902Z","steps":["trace[274900127] 'process raft request' (duration: 871.251166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:16.1961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:15.324346Z","time spent":"871.673936ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T17:32:16.196154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:15.327121Z","time spent":"868.853975ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T17:32:16.1965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.693767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/glance-rabbit-init-s6v6r\" ","response":"range_response_count:1 size:6685"} {"level":"info","ts":"2026-04-09T17:32:16.196538Z","caller":"traceutil/trace.go:171","msg":"trace[1044321730] range","detail":"{range_begin:/registry/pods/openstack/glance-rabbit-init-s6v6r; range_end:; response_count:1; response_revision:43734; }","duration":"808.755958ms","start":"2026-04-09T17:32:15.387771Z","end":"2026-04-09T17:32:16.196527Z","steps":["trace[1044321730] 'agreement among raft nodes before linearized reading' (duration: 808.652765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:16.19656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:15.387757Z","time spent":"808.795359ms","remote":"127.0.0.1:44576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":6709,"request content":"key:\"/registry/pods/openstack/glance-rabbit-init-s6v6r\" "} {"level":"warn","ts":"2026-04-09T17:32:16.252953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.578707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/barbican-barbican-db-init\" ","response":"range_response_count:1 size:753"} {"level":"info","ts":"2026-04-09T17:32:16.25303Z","caller":"traceutil/trace.go:171","msg":"trace[703592926] range","detail":"{range_begin:/registry/rolebindings/openstack/barbican-barbican-db-init; range_end:; response_count:1; response_revision:43736; }","duration":"710.71396ms","start":"2026-04-09T17:32:15.542298Z","end":"2026-04-09T17:32:16.253012Z","steps":["trace[703592926] 'agreement among raft nodes before linearized reading' (duration: 710.459284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:16.253062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:15.542279Z","time spent":"710.775292ms","remote":"127.0.0.1:44720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":777,"request content":"key:\"/registry/rolebindings/openstack/barbican-barbican-db-init\" "} {"level":"warn","ts":"2026-04-09T17:32:16.253108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.347135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-04-09T17:32:16.252964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.157527ms","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:9"} {"level":"warn","ts":"2026-04-09T17:32:16.253138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.172793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-09T17:32:16.253155Z","caller":"traceutil/trace.go:171","msg":"trace[489811253] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:43736; }","duration":"494.469658ms","start":"2026-04-09T17:32:15.758677Z","end":"2026-04-09T17:32:16.253147Z","steps":["trace[489811253] 'agreement among raft nodes before linearized reading' (duration: 494.304594ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:32:16.253262Z","caller":"traceutil/trace.go:171","msg":"trace[1174824466] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:43736; }","duration":"208.492895ms","start":"2026-04-09T17:32:16.044725Z","end":"2026-04-09T17:32:16.253218Z","steps":["trace[1174824466] 'agreement among raft nodes before linearized reading' (duration: 208.016193ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:32:16.253307Z","caller":"traceutil/trace.go:171","msg":"trace[758981423] transaction","detail":"{read_only:false; response_revision:43735; number_of_response:1; }","duration":"496.362157ms","start":"2026-04-09T17:32:15.756689Z","end":"2026-04-09T17:32:16.253051Z","steps":["trace[758981423] 'process raft request' (duration: 495.828204ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:32:16.253419Z","caller":"traceutil/trace.go:171","msg":"trace[1526797487] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:43736; }","duration":"344.402589ms","start":"2026-04-09T17:32:15.908889Z","end":"2026-04-09T17:32:16.253291Z","steps":["trace[1526797487] 'agreement among raft nodes before linearized reading' (duration: 344.067631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:16.253461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:15.908865Z","time spent":"344.583503ms","remote":"127.0.0.1:48798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":4,"response size":32,"request content":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true "} {"level":"warn","ts":"2026-04-09T17:32:16.253561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:15.756637Z","time spent":"496.809818ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T17:32:16.253377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:15.758655Z","time spent":"494.685364ms","remote":"127.0.0.1:44576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-09T17:32:16.254088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.739869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-09T17:32:16.254167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.487586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-09T17:32:16.254195Z","caller":"traceutil/trace.go:171","msg":"trace[842212118] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:43736; }","duration":"812.916763ms","start":"2026-04-09T17:32:15.441261Z","end":"2026-04-09T17:32:16.254178Z","steps":["trace[842212118] 'agreement among raft nodes before linearized reading' (duration: 811.506607ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:32:16.254223Z","caller":"traceutil/trace.go:171","msg":"trace[1648505712] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:43736; }","duration":"752.563698ms","start":"2026-04-09T17:32:15.501628Z","end":"2026-04-09T17:32:16.254192Z","steps":["trace[1648505712] 'agreement among raft nodes before linearized reading' (duration: 751.132402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:16.254244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:15.441242Z","time spent":"812.990685ms","remote":"127.0.0.1:44526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":30,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-09T17:32:16.254258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:15.50161Z","time spent":"752.63909ms","remote":"127.0.0.1:35504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-09T17:32:54.429132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.717939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-09T17:32:54.429203Z","caller":"traceutil/trace.go:171","msg":"trace[420740875] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:44011; }","duration":"103.812312ms","start":"2026-04-09T17:32:54.325376Z","end":"2026-04-09T17:32:54.429188Z","steps":["trace[420740875] 'agreement among raft nodes before linearized reading' (duration: 103.686259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:54.923095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.240291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/manila-openstack-manila-ks-user\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-04-09T17:32:54.923423Z","caller":"traceutil/trace.go:171","msg":"trace[2118133323] range","detail":"{range_begin:/registry/roles/openstack/manila-openstack-manila-ks-user; range_end:; response_count:1; response_revision:44011; }","duration":"579.579489ms","start":"2026-04-09T17:32:54.343799Z","end":"2026-04-09T17:32:54.923379Z","steps":["trace[2118133323] 'agreement among raft nodes before linearized reading' (duration: 85.292945ms)","trace[2118133323] 'range keys from in-memory index tree' (duration: 493.854694ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T17:32:54.923581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:54.343782Z","time spent":"579.781645ms","remote":"127.0.0.1:44716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":697,"request content":"key:\"/registry/roles/openstack/manila-openstack-manila-ks-user\" "} {"level":"info","ts":"2026-04-09T17:32:55.010034Z","caller":"traceutil/trace.go:171","msg":"trace[991869163] transaction","detail":"{read_only:false; response_revision:44012; number_of_response:1; }","duration":"603.759389ms","start":"2026-04-09T17:32:54.406258Z","end":"2026-04-09T17:32:55.010018Z","steps":["trace[991869163] 'process raft request' (duration: 603.599426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:55.010145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:54.406226Z","time spent":"603.864642ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T17:32:55.010238Z","caller":"traceutil/trace.go:171","msg":"trace[117614639] transaction","detail":"{read_only:false; response_revision:44013; number_of_response:1; }","duration":"587.781097ms","start":"2026-04-09T17:32:54.422444Z","end":"2026-04-09T17:32:55.010225Z","steps":["trace[117614639] 'process raft request' (duration: 587.52937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:55.010315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:54.422419Z","time spent":"587.861968ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T17:32:55.010397Z","caller":"traceutil/trace.go:171","msg":"trace[2082816640] transaction","detail":"{read_only:false; response_revision:44014; number_of_response:1; }","duration":"584.986515ms","start":"2026-04-09T17:32:54.425383Z","end":"2026-04-09T17:32:55.010369Z","steps":["trace[2082816640] 'process raft request' (duration: 584.795961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:55.010882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:54.425369Z","time spent":"585.360244ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T17:32:55.173025Z","caller":"traceutil/trace.go:171","msg":"trace[175512237] transaction","detail":"{read_only:false; response_revision:44015; number_of_response:1; }","duration":"663.270102ms","start":"2026-04-09T17:32:54.509716Z","end":"2026-04-09T17:32:55.172986Z","steps":["trace[175512237] 'process raft request' (duration: 662.730029ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:32:55.173143Z","caller":"traceutil/trace.go:171","msg":"trace[348206512] transaction","detail":"{read_only:false; response_revision:44016; number_of_response:1; }","duration":"355.248232ms","start":"2026-04-09T17:32:54.817866Z","end":"2026-04-09T17:32:55.173115Z","steps":["trace[348206512] 'process raft request' (duration: 355.087308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:55.173194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:54.509692Z","time spent":"663.417985ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T17:32:55.173325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:54.817849Z","time spent":"355.384665ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T17:32:55.173434Z","caller":"traceutil/trace.go:171","msg":"trace[970695796] transaction","detail":"{read_only:false; response_revision:44017; number_of_response:1; }","duration":"222.178392ms","start":"2026-04-09T17:32:54.951243Z","end":"2026-04-09T17:32:55.173421Z","steps":["trace[970695796] 'process raft request' (duration: 221.809692ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:32:55.173462Z","caller":"traceutil/trace.go:171","msg":"trace[580018213] linearizableReadLoop","detail":"{readStateIndex:45480; appliedIndex:45476; }","duration":"661.582572ms","start":"2026-04-09T17:32:54.511859Z","end":"2026-04-09T17:32:55.173441Z","steps":["trace[580018213] 'read index received' (duration: 497.963509ms)","trace[580018213] 'applied index is now lower than readState.Index' (duration: 163.618173ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:32:55.173546Z","caller":"traceutil/trace.go:171","msg":"trace[1058792278] transaction","detail":"{read_only:false; response_revision:44018; number_of_response:1; }","duration":"194.948404ms","start":"2026-04-09T17:32:54.978582Z","end":"2026-04-09T17:32:55.173531Z","steps":["trace[1058792278] 'process raft request' (duration: 194.77274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:55.173624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.755706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/manila-openstack-manila-ks-service\" ","response":"range_response_count:1 size:679"} {"level":"info","ts":"2026-04-09T17:32:55.173652Z","caller":"traceutil/trace.go:171","msg":"trace[1183118461] range","detail":"{range_begin:/registry/roles/openstack/manila-openstack-manila-ks-service; range_end:; response_count:1; response_revision:44018; }","duration":"661.828398ms","start":"2026-04-09T17:32:54.511816Z","end":"2026-04-09T17:32:55.173644Z","steps":["trace[1183118461] 'agreement among raft nodes before linearized reading' (duration: 661.713045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:55.173677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:54.511799Z","time spent":"661.873228ms","remote":"127.0.0.1:44716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":703,"request content":"key:\"/registry/roles/openstack/manila-openstack-manila-ks-service\" "} {"level":"warn","ts":"2026-04-09T17:32:55.379588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.792867ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-04-09T17:32:55.379669Z","caller":"traceutil/trace.go:171","msg":"trace[2030742196] linearizableReadLoop","detail":"{readStateIndex:45484; appliedIndex:45483; }","duration":"206.147606ms","start":"2026-04-09T17:32:55.173508Z","end":"2026-04-09T17:32:55.379656Z","steps":["trace[2030742196] 'read index received' (duration: 21.322459ms)","trace[2030742196] 'applied index is now lower than readState.Index' (duration: 184.823877ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T17:32:55.379966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.494711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/manila-openstack-manila-ks-user\" ","response":"range_response_count:1 size:673"} {"level":"warn","ts":"2026-04-09T17:32:55.379973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.247056ms","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-04-09T17:32:55.379999Z","caller":"traceutil/trace.go:171","msg":"trace[163173503] range","detail":"{range_begin:/registry/roles/openstack/manila-openstack-manila-ks-user; range_end:; response_count:1; response_revision:44018; }","duration":"380.565222ms","start":"2026-04-09T17:32:54.999427Z","end":"2026-04-09T17:32:55.379992Z","steps":["trace[163173503] 'agreement among raft nodes before linearized reading' (duration: 380.445199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:55.380024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:54.999413Z","time spent":"380.605373ms","remote":"127.0.0.1:44716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":697,"request content":"key:\"/registry/roles/openstack/manila-openstack-manila-ks-user\" "} {"level":"info","ts":"2026-04-09T17:32:55.380025Z","caller":"traceutil/trace.go:171","msg":"trace[2031341745] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:44018; }","duration":"327.327937ms","start":"2026-04-09T17:32:55.052684Z","end":"2026-04-09T17:32:55.380012Z","steps":["trace[2031341745] 'agreement among raft nodes before linearized reading' (duration: 327.175754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:55.38005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:55.052669Z","time spent":"327.375279ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-09T17:32:55.380183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.848917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-09T17:32:55.38021Z","caller":"traceutil/trace.go:171","msg":"trace[1163156102] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:44018; }","duration":"570.906629ms","start":"2026-04-09T17:32:54.809297Z","end":"2026-04-09T17:32:55.380203Z","steps":["trace[1163156102] 'agreement among raft nodes before linearized reading' (duration: 570.857448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:55.380232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:54.80928Z","time spent":"570.94678ms","remote":"127.0.0.1:44728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":32,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-09T17:32:55.380347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.131018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-09T17:32:55.380373Z","caller":"traceutil/trace.go:171","msg":"trace[1947570772] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:44018; }","duration":"737.194539ms","start":"2026-04-09T17:32:54.643171Z","end":"2026-04-09T17:32:55.380365Z","steps":["trace[1947570772] 'agreement among raft nodes before linearized reading' (duration: 737.155258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:55.380395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:54.64315Z","time spent":"737.238981ms","remote":"127.0.0.1:35900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":30,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-09T17:32:55.380494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.95975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/manila-openstack-manila-ks-service\" ","response":"range_response_count:1 size:679"} {"level":"info","ts":"2026-04-09T17:32:55.380524Z","caller":"traceutil/trace.go:171","msg":"trace[169094973] range","detail":"{range_begin:/registry/roles/openstack/manila-openstack-manila-ks-service; range_end:; response_count:1; response_revision:44018; }","duration":"204.017922ms","start":"2026-04-09T17:32:55.176497Z","end":"2026-04-09T17:32:55.380514Z","steps":["trace[169094973] 'agreement among raft nodes before linearized reading' (duration: 203.94243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:55.380723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.822012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-09T17:32:55.380751Z","caller":"traceutil/trace.go:171","msg":"trace[1601326201] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:44018; }","duration":"118.894464ms","start":"2026-04-09T17:32:55.26185Z","end":"2026-04-09T17:32:55.380745Z","steps":["trace[1601326201] 'agreement among raft nodes before linearized reading' (duration: 118.819782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:55.380877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.689412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-09T17:32:55.3809Z","caller":"traceutil/trace.go:171","msg":"trace[392057828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:44018; }","duration":"113.744963ms","start":"2026-04-09T17:32:55.26715Z","end":"2026-04-09T17:32:55.380895Z","steps":["trace[392057828] 'agreement among raft nodes before linearized reading' (duration: 113.684932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:55.380909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.813416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-09T17:32:55.380937Z","caller":"traceutil/trace.go:171","msg":"trace[1735845157] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:44018; }","duration":"117.906947ms","start":"2026-04-09T17:32:55.263023Z","end":"2026-04-09T17:32:55.38093Z","steps":["trace[1735845157] 'agreement among raft nodes before linearized reading' (duration: 117.839886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:55.380728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.530115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-09T17:32:55.380983Z","caller":"traceutil/trace.go:171","msg":"trace[1168430257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:44018; }","duration":"177.815111ms","start":"2026-04-09T17:32:55.203162Z","end":"2026-04-09T17:32:55.380977Z","steps":["trace[1168430257] 'agreement among raft nodes before linearized reading' (duration: 177.549265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:55.381064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.205754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-09T17:32:55.381087Z","caller":"traceutil/trace.go:171","msg":"trace[1483469259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:44018; }","duration":"114.240605ms","start":"2026-04-09T17:32:55.26684Z","end":"2026-04-09T17:32:55.381081Z","steps":["trace[1483469259] 'agreement among raft nodes before linearized reading' (duration: 114.179233ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:32:55.488836Z","caller":"traceutil/trace.go:171","msg":"trace[1532823075] transaction","detail":"{read_only:false; response_revision:44020; number_of_response:1; }","duration":"102.429516ms","start":"2026-04-09T17:32:55.386379Z","end":"2026-04-09T17:32:55.488808Z","steps":["trace[1532823075] 'process raft request' (duration: 102.243712ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:32:55.489039Z","caller":"traceutil/trace.go:171","msg":"trace[73627529] transaction","detail":"{read_only:false; response_revision:44023; number_of_response:1; }","duration":"101.275648ms","start":"2026-04-09T17:32:55.387745Z","end":"2026-04-09T17:32:55.489021Z","steps":["trace[73627529] 'process raft request' (duration: 101.242097ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:32:55.489064Z","caller":"traceutil/trace.go:171","msg":"trace[1251057081] transaction","detail":"{read_only:false; response_revision:44021; number_of_response:1; }","duration":"102.638321ms","start":"2026-04-09T17:32:55.386403Z","end":"2026-04-09T17:32:55.489042Z","steps":["trace[1251057081] 'process raft request' (duration: 102.360034ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:32:55.489068Z","caller":"traceutil/trace.go:171","msg":"trace[874850169] transaction","detail":"{read_only:false; response_revision:44022; number_of_response:1; }","duration":"101.959615ms","start":"2026-04-09T17:32:55.387082Z","end":"2026-04-09T17:32:55.489042Z","steps":["trace[874850169] 'process raft request' (duration: 101.850083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:55.929307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.540794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-09T17:32:55.929368Z","caller":"traceutil/trace.go:171","msg":"trace[1896492561] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:44023; }","duration":"215.641217ms","start":"2026-04-09T17:32:55.713713Z","end":"2026-04-09T17:32:55.929355Z","steps":["trace[1896492561] 'count revisions from in-memory index tree' (duration: 215.486262ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:32:55.980445Z","caller":"traceutil/trace.go:171","msg":"trace[1590573906] transaction","detail":"{read_only:false; response_revision:44024; number_of_response:1; }","duration":"257.305569ms","start":"2026-04-09T17:32:55.723098Z","end":"2026-04-09T17:32:55.980403Z","steps":["trace[1590573906] 'process raft request' (duration: 257.096884ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:32:55.980546Z","caller":"traceutil/trace.go:171","msg":"trace[1565193498] linearizableReadLoop","detail":"{readStateIndex:45490; appliedIndex:45489; }","duration":"252.311062ms","start":"2026-04-09T17:32:55.728187Z","end":"2026-04-09T17:32:55.980498Z","steps":["trace[1565193498] 'read index received' (duration: 251.908361ms)","trace[1565193498] 'applied index is now lower than readState.Index' (duration: 392.751µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T17:32:55.984232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.020747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/manila-openstack-manila-scheduler\" ","response":"range_response_count:1 size:689"} {"level":"info","ts":"2026-04-09T17:32:55.984317Z","caller":"traceutil/trace.go:171","msg":"trace[720278359] range","detail":"{range_begin:/registry/roles/openstack/manila-openstack-manila-scheduler; range_end:; response_count:1; response_revision:44024; }","duration":"256.113818ms","start":"2026-04-09T17:32:55.728161Z","end":"2026-04-09T17:32:55.984275Z","steps":["trace[720278359] 'agreement among raft nodes before linearized reading' (duration: 252.433966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:55.984622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.052124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/manila-openstack-manila-rabbit-init\" ","response":"range_response_count:1 size:681"} {"level":"info","ts":"2026-04-09T17:32:55.984683Z","caller":"traceutil/trace.go:171","msg":"trace[1085410360] range","detail":"{range_begin:/registry/roles/openstack/manila-openstack-manila-rabbit-init; range_end:; response_count:1; response_revision:44024; }","duration":"221.152286ms","start":"2026-04-09T17:32:55.763517Z","end":"2026-04-09T17:32:55.984669Z","steps":["trace[1085410360] 'agreement among raft nodes before linearized reading' (duration: 221.030253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:56.477892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.415739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-09T17:32:56.477972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.214956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-09T17:32:56.478008Z","caller":"traceutil/trace.go:171","msg":"trace[279429988] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:44024; }","duration":"325.568953ms","start":"2026-04-09T17:32:56.152417Z","end":"2026-04-09T17:32:56.477986Z","steps":["trace[279429988] 'count revisions from in-memory index tree' (duration: 325.354468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:56.478062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:56.152404Z","time spent":"325.645405ms","remote":"127.0.0.1:35914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":30,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-09T17:32:56.477905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.151661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-09T17:32:56.478171Z","caller":"traceutil/trace.go:171","msg":"trace[356993951] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:44024; }","duration":"281.471489ms","start":"2026-04-09T17:32:56.196668Z","end":"2026-04-09T17:32:56.478139Z","steps":["trace[356993951] 'count revisions from in-memory index tree' (duration: 281.030117ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:32:56.478057Z","caller":"traceutil/trace.go:171","msg":"trace[1745328831] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:44024; }","duration":"120.35832ms","start":"2026-04-09T17:32:56.357676Z","end":"2026-04-09T17:32:56.478034Z","steps":["trace[1745328831] 'count revisions from in-memory index tree' (duration: 120.158105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:56.478341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.428683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/neutron-openstack-neutron-db-init\" ","response":"range_response_count:1 size:678"} {"level":"warn","ts":"2026-04-09T17:32:56.478379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.91232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-09T17:32:56.478411Z","caller":"traceutil/trace.go:171","msg":"trace[365412402] range","detail":"{range_begin:/registry/roles/openstack/neutron-openstack-neutron-db-init; range_end:; response_count:1; response_revision:44024; }","duration":"322.518886ms","start":"2026-04-09T17:32:56.155873Z","end":"2026-04-09T17:32:56.478392Z","steps":["trace[365412402] 'range keys from in-memory index tree' (duration: 322.254479ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:32:56.478444Z","caller":"traceutil/trace.go:171","msg":"trace[140223913] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:44024; }","duration":"323.998893ms","start":"2026-04-09T17:32:56.154426Z","end":"2026-04-09T17:32:56.478425Z","steps":["trace[140223913] 'count revisions from in-memory index tree' (duration: 323.876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:56.478501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:56.155864Z","time spent":"322.580418ms","remote":"127.0.0.1:44716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":702,"request content":"key:\"/registry/roles/openstack/neutron-openstack-neutron-db-init\" "} {"level":"warn","ts":"2026-04-09T17:32:56.478542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:56.154423Z","time spent":"324.102706ms","remote":"127.0.0.1:44500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":255,"response size":33,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-04-09T17:32:56.478579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.484604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/manila-openstack-manila-share\" ","response":"range_response_count:1 size:687"} {"level":"info","ts":"2026-04-09T17:32:56.478645Z","caller":"traceutil/trace.go:171","msg":"trace[1033155763] range","detail":"{range_begin:/registry/roles/openstack/manila-openstack-manila-share; range_end:; response_count:1; response_revision:44024; }","duration":"320.559396ms","start":"2026-04-09T17:32:56.158065Z","end":"2026-04-09T17:32:56.478624Z","steps":["trace[1033155763] 'range keys from in-memory index tree' (duration: 320.383461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:32:56.4787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:32:56.158059Z","time spent":"320.627388ms","remote":"127.0.0.1:44716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":711,"request content":"key:\"/registry/roles/openstack/manila-openstack-manila-share\" "} {"level":"warn","ts":"2026-04-09T17:33:07.883795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.213985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/heat-rabbitmq-admin\" ","response":"range_response_count:1 size:743"} {"level":"info","ts":"2026-04-09T17:33:07.883893Z","caller":"traceutil/trace.go:171","msg":"trace[780277735] range","detail":"{range_begin:/registry/secrets/openstack/heat-rabbitmq-admin; range_end:; response_count:1; response_revision:44109; }","duration":"232.352148ms","start":"2026-04-09T17:33:07.651519Z","end":"2026-04-09T17:33:07.883872Z","steps":["trace[780277735] 'range keys from in-memory index tree' (duration: 232.047401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:07.883975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.369671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/heat-rabbitmq-user\" ","response":"range_response_count:1 size:712"} {"level":"info","ts":"2026-04-09T17:33:07.884042Z","caller":"traceutil/trace.go:171","msg":"trace[935993695] range","detail":"{range_begin:/registry/secrets/openstack/heat-rabbitmq-user; range_end:; response_count:1; response_revision:44109; }","duration":"126.466904ms","start":"2026-04-09T17:33:07.757558Z","end":"2026-04-09T17:33:07.884025Z","steps":["trace[935993695] 'range keys from in-memory index tree' (duration: 126.249859ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:33:07.987775Z","caller":"traceutil/trace.go:171","msg":"trace[1188419086] transaction","detail":"{read_only:false; response_revision:44110; number_of_response:1; }","duration":"212.059306ms","start":"2026-04-09T17:33:07.775678Z","end":"2026-04-09T17:33:07.987738Z","steps":["trace[1188419086] 'process raft request' (duration: 211.280627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:08.277929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.47823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/horizon-db-user\" ","response":"range_response_count:1 size:719"} {"level":"info","ts":"2026-04-09T17:33:08.278004Z","caller":"traceutil/trace.go:171","msg":"trace[90486316] range","detail":"{range_begin:/registry/secrets/openstack/horizon-db-user; range_end:; response_count:1; response_revision:44111; }","duration":"169.602703ms","start":"2026-04-09T17:33:08.108382Z","end":"2026-04-09T17:33:08.277985Z","steps":["trace[90486316] 'range keys from in-memory index tree' (duration: 169.388658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:08.278109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.135905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-09T17:33:08.278221Z","caller":"traceutil/trace.go:171","msg":"trace[178131707] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:44111; }","duration":"105.31594ms","start":"2026-04-09T17:33:08.172881Z","end":"2026-04-09T17:33:08.278197Z","steps":["trace[178131707] 'count revisions from in-memory index tree' (duration: 105.059743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:08.278317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.943643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/horizon-db-admin\" ","response":"range_response_count:1 size:718"} {"level":"info","ts":"2026-04-09T17:33:08.278407Z","caller":"traceutil/trace.go:171","msg":"trace[1774753940] range","detail":"{range_begin:/registry/secrets/openstack/horizon-db-admin; range_end:; response_count:1; response_revision:44111; }","duration":"193.077927ms","start":"2026-04-09T17:33:08.085313Z","end":"2026-04-09T17:33:08.278391Z","steps":["trace[1774753940] 'range keys from in-memory index tree' (duration: 192.846051ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:33:08.381776Z","caller":"traceutil/trace.go:171","msg":"trace[327645222] transaction","detail":"{read_only:false; response_revision:44112; number_of_response:1; }","duration":"113.083356ms","start":"2026-04-09T17:33:08.268669Z","end":"2026-04-09T17:33:08.381753Z","steps":["trace[327645222] 'process raft request' (duration: 112.937552ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:33:28.304703Z","caller":"traceutil/trace.go:171","msg":"trace[1137264218] linearizableReadLoop","detail":"{readStateIndex:45732; appliedIndex:45731; }","duration":"124.287359ms","start":"2026-04-09T17:33:28.180382Z","end":"2026-04-09T17:33:28.30467Z","steps":["trace[1137264218] 'read index received' (duration: 121.185431ms)","trace[1137264218] 'applied index is now lower than readState.Index' (duration: 3.100878ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:33:28.304789Z","caller":"traceutil/trace.go:171","msg":"trace[1887160895] transaction","detail":"{read_only:false; response_revision:44260; number_of_response:1; }","duration":"167.419699ms","start":"2026-04-09T17:33:28.137323Z","end":"2026-04-09T17:33:28.304743Z","steps":["trace[1887160895] 'process raft request' (duration: 164.29767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:28.305054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.655178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/magnum-api\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-04-09T17:33:28.305144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.102749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-09T17:33:28.305168Z","caller":"traceutil/trace.go:171","msg":"trace[815331407] range","detail":"{range_begin:/registry/serviceaccounts/openstack/magnum-api; range_end:; response_count:1; response_revision:44260; }","duration":"124.822832ms","start":"2026-04-09T17:33:28.18032Z","end":"2026-04-09T17:33:28.305142Z","steps":["trace[815331407] 'agreement among raft nodes before linearized reading' (duration: 124.527455ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:33:28.305334Z","caller":"traceutil/trace.go:171","msg":"trace[1364841976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:44260; }","duration":"102.343226ms","start":"2026-04-09T17:33:28.202957Z","end":"2026-04-09T17:33:28.3053Z","steps":["trace[1364841976] 'agreement among raft nodes before linearized reading' (duration: 101.988747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:28.308024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.872143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:500 size:388651"} {"level":"info","ts":"2026-04-09T17:33:28.308099Z","caller":"traceutil/trace.go:171","msg":"trace[1014298642] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:500; response_revision:44260; }","duration":"106.004657ms","start":"2026-04-09T17:33:28.202079Z","end":"2026-04-09T17:33:28.308084Z","steps":["trace[1014298642] 'agreement among raft nodes before linearized reading' (duration: 102.793856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:28.537378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.948999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/magnum-conductor\" ","response":"range_response_count:1 size:537"} {"level":"info","ts":"2026-04-09T17:33:28.537435Z","caller":"traceutil/trace.go:171","msg":"trace[1000852517] range","detail":"{range_begin:/registry/serviceaccounts/openstack/magnum-conductor; range_end:; response_count:1; response_revision:44260; }","duration":"118.054702ms","start":"2026-04-09T17:33:28.419367Z","end":"2026-04-09T17:33:28.537421Z","steps":["trace[1000852517] 'range keys from in-memory index tree' (duration: 117.805095ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:33:35.504264Z","caller":"traceutil/trace.go:171","msg":"trace[794390820] linearizableReadLoop","detail":"{readStateIndex:45783; appliedIndex:45782; }","duration":"128.746112ms","start":"2026-04-09T17:33:35.375498Z","end":"2026-04-09T17:33:35.504244Z","steps":["trace[794390820] 'read index received' (duration: 52.946927ms)","trace[794390820] 'applied index is now lower than readState.Index' (duration: 75.798165ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:33:35.504302Z","caller":"traceutil/trace.go:171","msg":"trace[367906629] transaction","detail":"{read_only:false; response_revision:44309; number_of_response:1; }","duration":"132.403144ms","start":"2026-04-09T17:33:35.371866Z","end":"2026-04-09T17:33:35.504269Z","steps":["trace[367906629] 'process raft request' (duration: 56.61506ms)","trace[367906629] 'compare' (duration: 75.671141ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T17:33:35.504778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.278205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/sh.helm.release.v1.libvirt.v1\" ","response":"range_response_count:1 size:30494"} {"level":"info","ts":"2026-04-09T17:33:35.504818Z","caller":"traceutil/trace.go:171","msg":"trace[1369944235] range","detail":"{range_begin:/registry/secrets/openstack/sh.helm.release.v1.libvirt.v1; range_end:; response_count:1; response_revision:44309; }","duration":"129.353478ms","start":"2026-04-09T17:33:35.375454Z","end":"2026-04-09T17:33:35.504808Z","steps":["trace[1369944235] 'agreement among raft nodes before linearized reading' (duration: 128.906145ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:33:35.515808Z","caller":"traceutil/trace.go:171","msg":"trace[336540014] transaction","detail":"{read_only:false; response_revision:44310; number_of_response:1; }","duration":"127.195762ms","start":"2026-04-09T17:33:35.388597Z","end":"2026-04-09T17:33:35.515793Z","steps":["trace[336540014] 'process raft request' (duration: 127.058989ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:33:35.516072Z","caller":"traceutil/trace.go:171","msg":"trace[930680330] transaction","detail":"{read_only:false; response_revision:44311; number_of_response:1; }","duration":"127.103162ms","start":"2026-04-09T17:33:35.388942Z","end":"2026-04-09T17:33:35.516045Z","steps":["trace[930680330] 'process raft request' (duration: 126.804413ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:33:41.410292Z","caller":"traceutil/trace.go:171","msg":"trace[1428976705] transaction","detail":"{read_only:false; response_revision:44347; number_of_response:1; }","duration":"188.542013ms","start":"2026-04-09T17:33:41.221712Z","end":"2026-04-09T17:33:41.410254Z","steps":["trace[1428976705] 'process raft request' (duration: 188.239374ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:33:41.705547Z","caller":"traceutil/trace.go:171","msg":"trace[962976812] transaction","detail":"{read_only:false; response_revision:44349; number_of_response:1; }","duration":"465.262931ms","start":"2026-04-09T17:33:41.240234Z","end":"2026-04-09T17:33:41.705496Z","steps":["trace[962976812] 'process raft request' (duration: 465.155758ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:33:41.705598Z","caller":"traceutil/trace.go:171","msg":"trace[1562983953] transaction","detail":"{read_only:false; response_revision:44348; number_of_response:1; }","duration":"473.390376ms","start":"2026-04-09T17:33:41.232153Z","end":"2026-04-09T17:33:41.705544Z","steps":["trace[1562983953] 'process raft request' (duration: 472.969736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:41.7058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:33:41.240224Z","time spent":"465.444616ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T17:33:41.705866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:33:41.232119Z","time spent":"473.580651ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T17:33:41.705817Z","caller":"traceutil/trace.go:171","msg":"trace[1111260676] linearizableReadLoop","detail":"{readStateIndex:45824; appliedIndex:45821; }","duration":"437.031698ms","start":"2026-04-09T17:33:41.268742Z","end":"2026-04-09T17:33:41.705773Z","steps":["trace[1111260676] 'read index received' (duration: 141.236207ms)","trace[1111260676] 'applied index is now lower than readState.Index' (duration: 295.793991ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:33:41.706054Z","caller":"traceutil/trace.go:171","msg":"trace[1693723264] transaction","detail":"{read_only:false; response_revision:44350; number_of_response:1; }","duration":"362.072015ms","start":"2026-04-09T17:33:41.343969Z","end":"2026-04-09T17:33:41.706041Z","steps":["trace[1693723264] 'process raft request' (duration: 361.47211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:41.706121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:33:41.343945Z","time spent":"362.142007ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T17:33:41.706207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.469489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-04-09T17:33:41.70626Z","caller":"traceutil/trace.go:171","msg":"trace[704594382] range","detail":"{range_begin:/registry/serviceaccounts/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:44350; }","duration":"437.523081ms","start":"2026-04-09T17:33:41.268724Z","end":"2026-04-09T17:33:41.706248Z","steps":["trace[704594382] 'agreement among raft nodes before linearized reading' (duration: 437.325546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:41.706296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:33:41.268707Z","time spent":"437.581652ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":560,"request content":"key:\"/registry/serviceaccounts/openstack/neutron-ks-user\" "} {"level":"info","ts":"2026-04-09T17:33:41.89824Z","caller":"traceutil/trace.go:171","msg":"trace[752626206] transaction","detail":"{read_only:false; response_revision:44351; number_of_response:1; }","duration":"372.089948ms","start":"2026-04-09T17:33:41.526123Z","end":"2026-04-09T17:33:41.898213Z","steps":["trace[752626206] 'process raft request' (duration: 298.042737ms)","trace[752626206] 'compare' (duration: 73.859146ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T17:33:41.8984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:33:41.526084Z","time spent":"372.231141ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T17:33:41.898538Z","caller":"traceutil/trace.go:171","msg":"trace[409825238] transaction","detail":"{read_only:false; response_revision:44352; number_of_response:1; }","duration":"363.098841ms","start":"2026-04-09T17:33:41.535407Z","end":"2026-04-09T17:33:41.898506Z","steps":["trace[409825238] 'process raft request' (duration: 362.760253ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:33:41.898712Z","caller":"traceutil/trace.go:171","msg":"trace[1412673779] linearizableReadLoop","detail":"{readStateIndex:45828; appliedIndex:45825; }","duration":"192.773539ms","start":"2026-04-09T17:33:41.705927Z","end":"2026-04-09T17:33:41.8987Z","steps":["trace[1412673779] 'read index received' (duration: 118.108323ms)","trace[1412673779] 'applied index is now lower than readState.Index' (duration: 74.664296ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T17:33:41.898716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:33:41.535393Z","time spent":"363.232215ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T17:33:41.898926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.377871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/neutron-netns-cleanup-cron\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-09T17:33:41.898971Z","caller":"traceutil/trace.go:171","msg":"trace[467675465] range","detail":"{range_begin:/registry/serviceaccounts/openstack/neutron-netns-cleanup-cron; range_end:; response_count:1; response_revision:44353; }","duration":"569.445952ms","start":"2026-04-09T17:33:41.329512Z","end":"2026-04-09T17:33:41.898958Z","steps":["trace[467675465] 'agreement among raft nodes before linearized reading' (duration: 569.282518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:41.898992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.459864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"warn","ts":"2026-04-09T17:33:41.89901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:33:41.329498Z","time spent":"569.503994ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":582,"request content":"key:\"/registry/serviceaccounts/openstack/neutron-netns-cleanup-cron\" "} {"level":"warn","ts":"2026-04-09T17:33:41.898963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.079377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-09T17:33:41.899064Z","caller":"traceutil/trace.go:171","msg":"trace[58778583] transaction","detail":"{read_only:false; response_revision:44353; number_of_response:1; }","duration":"362.934977ms","start":"2026-04-09T17:33:41.536122Z","end":"2026-04-09T17:33:41.899057Z","steps":["trace[58778583] 'process raft request' (duration: 362.25346ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:33:41.899091Z","caller":"traceutil/trace.go:171","msg":"trace[66551789] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:44353; }","duration":"486.281413ms","start":"2026-04-09T17:33:41.412778Z","end":"2026-04-09T17:33:41.89906Z","steps":["trace[66551789] 'agreement among raft nodes before linearized reading' (duration: 486.010206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:41.899118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:33:41.536069Z","time spent":"363.02114ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T17:33:41.899157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:33:41.412752Z","time spent":"486.393075ms","remote":"127.0.0.1:34634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-04-09T17:33:41.899215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.747635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-09T17:33:41.899254Z","caller":"traceutil/trace.go:171","msg":"trace[1334613724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:44353; }","duration":"123.840298ms","start":"2026-04-09T17:33:41.775407Z","end":"2026-04-09T17:33:41.899247Z","steps":["trace[1334613724] 'agreement among raft nodes before linearized reading' (duration: 123.764366ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:33:41.899027Z","caller":"traceutil/trace.go:171","msg":"trace[25466046] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:44353; }","duration":"609.524616ms","start":"2026-04-09T17:33:41.289492Z","end":"2026-04-09T17:33:41.899017Z","steps":["trace[25466046] 'agreement among raft nodes before linearized reading' (duration: 609.417953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:41.899269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.837339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:536"} {"level":"warn","ts":"2026-04-09T17:33:41.899361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:33:41.289477Z","time spent":"609.852574ms","remote":"127.0.0.1:44560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1890,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-04-09T17:33:41.899399Z","caller":"traceutil/trace.go:171","msg":"trace[940732988] range","detail":"{range_begin:/registry/serviceaccounts/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:44353; }","duration":"189.042655ms","start":"2026-04-09T17:33:41.710318Z","end":"2026-04-09T17:33:41.899361Z","steps":["trace[940732988] 'agreement among raft nodes before linearized reading' (duration: 188.625394ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:33:42.213025Z","caller":"traceutil/trace.go:171","msg":"trace[204796894] linearizableReadLoop","detail":"{readStateIndex:45829; appliedIndex:45828; }","duration":"307.915427ms","start":"2026-04-09T17:33:41.905075Z","end":"2026-04-09T17:33:42.21299Z","steps":["trace[204796894] 'read index received' (duration: 257.087744ms)","trace[204796894] 'applied index is now lower than readState.Index' (duration: 50.826063ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:33:42.213117Z","caller":"traceutil/trace.go:171","msg":"trace[1155501409] transaction","detail":"{read_only:false; response_revision:44354; number_of_response:1; }","duration":"309.582088ms","start":"2026-04-09T17:33:41.903497Z","end":"2026-04-09T17:33:42.213079Z","steps":["trace[1155501409] 'process raft request' (duration: 258.700324ms)","trace[1155501409] 'compare' (duration: 50.512385ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T17:33:42.213327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.228555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-5cb8e934-a762-47c0-9793-655ae4153f5b\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-04-09T17:33:42.213396Z","caller":"traceutil/trace.go:171","msg":"trace[325075817] range","detail":"{range_begin:/registry/persistentvolumes/pvc-5cb8e934-a762-47c0-9793-655ae4153f5b; range_end:; response_count:1; response_revision:44354; }","duration":"308.329978ms","start":"2026-04-09T17:33:41.905049Z","end":"2026-04-09T17:33:42.213379Z","steps":["trace[325075817] 'agreement among raft nodes before linearized reading' (duration: 308.101752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:42.213397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:33:41.903481Z","time spent":"309.711492ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T17:33:42.213442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:33:41.905041Z","time spent":"308.388149ms","remote":"127.0.0.1:44548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1347,"request content":"key:\"/registry/persistentvolumes/pvc-5cb8e934-a762-47c0-9793-655ae4153f5b\" "} {"level":"info","ts":"2026-04-09T17:33:42.31243Z","caller":"traceutil/trace.go:171","msg":"trace[700020878] transaction","detail":"{read_only:false; response_revision:44355; number_of_response:1; }","duration":"407.252547ms","start":"2026-04-09T17:33:41.905147Z","end":"2026-04-09T17:33:42.3124Z","steps":["trace[700020878] 'process raft request' (duration: 407.013761ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:33:42.312485Z","caller":"traceutil/trace.go:171","msg":"trace[2080191395] transaction","detail":"{read_only:false; response_revision:44356; number_of_response:1; }","duration":"407.172315ms","start":"2026-04-09T17:33:41.905272Z","end":"2026-04-09T17:33:42.312445Z","steps":["trace[2080191395] 'process raft request' (duration: 407.037412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:42.312549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:33:41.905141Z","time spent":"407.349729ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T17:33:42.312501Z","caller":"traceutil/trace.go:171","msg":"trace[289540475] transaction","detail":"{read_only:false; response_revision:44357; number_of_response:1; }","duration":"407.091033ms","start":"2026-04-09T17:33:41.905381Z","end":"2026-04-09T17:33:42.312472Z","steps":["trace[289540475] 'process raft request' (duration: 406.97535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:42.312675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:33:41.905371Z","time spent":"407.224427ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T17:33:42.31271Z","caller":"traceutil/trace.go:171","msg":"trace[1364133890] transaction","detail":"{read_only:false; response_revision:44358; number_of_response:1; }","duration":"407.191126ms","start":"2026-04-09T17:33:41.905489Z","end":"2026-04-09T17:33:42.31268Z","steps":["trace[1364133890] 'process raft request' (duration: 406.907069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:42.312746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:33:41.905249Z","time spent":"407.312039ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T17:33:42.312843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:33:41.905465Z","time spent":"407.314148ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T17:33:42.421338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.828707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/neutron-netns-cleanup-cron\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-09T17:33:42.421441Z","caller":"traceutil/trace.go:171","msg":"trace[288814887] range","detail":"{range_begin:/registry/serviceaccounts/openstack/neutron-netns-cleanup-cron; range_end:; response_count:1; response_revision:44358; }","duration":"436.005583ms","start":"2026-04-09T17:33:41.985407Z","end":"2026-04-09T17:33:42.421413Z","steps":["trace[288814887] 'agreement among raft nodes before linearized reading' (duration: 327.253816ms)","trace[288814887] 'range keys from in-memory index tree' (duration: 108.477029ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T17:33:42.421467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.240918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-09T17:33:42.421496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:33:41.985378Z","time spent":"436.104615ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":582,"request content":"key:\"/registry/serviceaccounts/openstack/neutron-netns-cleanup-cron\" "} {"level":"info","ts":"2026-04-09T17:33:42.421546Z","caller":"traceutil/trace.go:171","msg":"trace[1103184730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:44358; }","duration":"219.376372ms","start":"2026-04-09T17:33:42.20215Z","end":"2026-04-09T17:33:42.421526Z","steps":["trace[1103184730] 'agreement among raft nodes before linearized reading' (duration: 110.495672ms)","trace[1103184730] 'range keys from in-memory index tree' (duration: 108.767308ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T17:33:42.421355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.345585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-09T17:33:42.421718Z","caller":"traceutil/trace.go:171","msg":"trace[528085377] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:44358; }","duration":"471.770786ms","start":"2026-04-09T17:33:41.949915Z","end":"2026-04-09T17:33:42.421686Z","steps":["trace[528085377] 'agreement among raft nodes before linearized reading' (duration: 362.817204ms)","trace[528085377] 'count revisions from in-memory index tree' (duration: 108.564132ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T17:33:42.421847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:33:41.949885Z","time spent":"471.940021ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"warn","ts":"2026-04-09T17:33:42.42305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.752727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" limit:500 ","response":"range_response_count:202 size:2137808"} {"level":"info","ts":"2026-04-09T17:33:42.423158Z","caller":"traceutil/trace.go:171","msg":"trace[33088001] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:202; response_revision:44358; }","duration":"517.918522ms","start":"2026-04-09T17:33:41.905209Z","end":"2026-04-09T17:33:42.423128Z","steps":["trace[33088001] 'agreement among raft nodes before linearized reading' (duration: 407.238267ms)","trace[33088001] 'range keys from in-memory index tree' (duration: 108.90934ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T17:33:42.423284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:33:41.905184Z","time spent":"518.022304ms","remote":"127.0.0.1:44500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":202,"response size":2137832,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" limit:500 "} {"level":"info","ts":"2026-04-09T17:33:43.818989Z","caller":"traceutil/trace.go:171","msg":"trace[241255040] transaction","detail":"{read_only:false; response_revision:44364; number_of_response:1; }","duration":"107.495535ms","start":"2026-04-09T17:33:43.711471Z","end":"2026-04-09T17:33:43.818966Z","steps":["trace[241255040] 'process raft request' (duration: 107.345721ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:33:43.888002Z","caller":"traceutil/trace.go:171","msg":"trace[1510136791] transaction","detail":"{read_only:false; response_revision:44365; number_of_response:1; }","duration":"175.77913ms","start":"2026-04-09T17:33:43.712192Z","end":"2026-04-09T17:33:43.887971Z","steps":["trace[1510136791] 'process raft request' (duration: 173.519072ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:33:43.88806Z","caller":"traceutil/trace.go:171","msg":"trace[962810897] transaction","detail":"{read_only:false; response_revision:44366; number_of_response:1; }","duration":"175.329409ms","start":"2026-04-09T17:33:43.712693Z","end":"2026-04-09T17:33:43.888022Z","steps":["trace[962810897] 'process raft request' (duration: 174.849997ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:33:45.462724Z","caller":"traceutil/trace.go:171","msg":"trace[1884231583] linearizableReadLoop","detail":"{readStateIndex:45852; appliedIndex:45851; }","duration":"170.274211ms","start":"2026-04-09T17:33:45.292421Z","end":"2026-04-09T17:33:45.462695Z","steps":["trace[1884231583] 'read index received' (duration: 170.078326ms)","trace[1884231583] 'applied index is now lower than readState.Index' (duration: 194.935µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:33:45.462865Z","caller":"traceutil/trace.go:171","msg":"trace[950815371] transaction","detail":"{read_only:false; response_revision:44376; number_of_response:1; }","duration":"213.896043ms","start":"2026-04-09T17:33:45.248952Z","end":"2026-04-09T17:33:45.462848Z","steps":["trace[950815371] 'process raft request' (duration: 213.493063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:45.463144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.697731ms","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:1936"} {"level":"info","ts":"2026-04-09T17:33:45.46321Z","caller":"traceutil/trace.go:171","msg":"trace[2081302092] 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:44376; }","duration":"170.776643ms","start":"2026-04-09T17:33:45.292413Z","end":"2026-04-09T17:33:45.463189Z","steps":["trace[2081302092] 'agreement among raft nodes before linearized reading' (duration: 170.599139ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:33:45.480646Z","caller":"traceutil/trace.go:171","msg":"trace[1856605181] transaction","detail":"{read_only:false; response_revision:44377; number_of_response:1; }","duration":"181.722461ms","start":"2026-04-09T17:33:45.298908Z","end":"2026-04-09T17:33:45.480631Z","steps":["trace[1856605181] 'process raft request' (duration: 181.363471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:45.480642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.957361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-09T17:33:45.480751Z","caller":"traceutil/trace.go:171","msg":"trace[962489463] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:44378; }","duration":"160.131196ms","start":"2026-04-09T17:33:45.320593Z","end":"2026-04-09T17:33:45.480724Z","steps":["trace[962489463] 'agreement among raft nodes before linearized reading' (duration: 159.909869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:45.480996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.876693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/nova-db-sync\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-09T17:33:45.48103Z","caller":"traceutil/trace.go:171","msg":"trace[211840076] range","detail":"{range_begin:/registry/serviceaccounts/openstack/nova-db-sync; range_end:; response_count:1; response_revision:44378; }","duration":"158.936255ms","start":"2026-04-09T17:33:45.322086Z","end":"2026-04-09T17:33:45.481022Z","steps":["trace[211840076] 'agreement among raft nodes before linearized reading' (duration: 158.843013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:45.48296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.348472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:500 size:388651"} {"level":"info","ts":"2026-04-09T17:33:45.483019Z","caller":"traceutil/trace.go:171","msg":"trace[1586633953] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:500; response_revision:44378; }","duration":"151.478076ms","start":"2026-04-09T17:33:45.331528Z","end":"2026-04-09T17:33:45.483006Z","steps":["trace[1586633953] 'agreement among raft nodes before linearized reading' (duration: 148.916331ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:33:46.011957Z","caller":"traceutil/trace.go:171","msg":"trace[217375619] linearizableReadLoop","detail":"{readStateIndex:45857; appliedIndex:45855; }","duration":"112.629225ms","start":"2026-04-09T17:33:45.899306Z","end":"2026-04-09T17:33:46.011935Z","steps":["trace[217375619] 'read index received' (duration: 5.357216ms)","trace[217375619] 'applied index is now lower than readState.Index' (duration: 107.270849ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:33:46.012071Z","caller":"traceutil/trace.go:171","msg":"trace[1644162629] transaction","detail":"{read_only:false; response_revision:44381; number_of_response:1; }","duration":"118.101743ms","start":"2026-04-09T17:33:45.893957Z","end":"2026-04-09T17:33:46.012059Z","steps":["trace[1644162629] 'process raft request' (duration: 116.524864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:46.014827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.499827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:500 size:388651"} {"level":"info","ts":"2026-04-09T17:33:46.014917Z","caller":"traceutil/trace.go:171","msg":"trace[1354903151] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:500; response_revision:44381; }","duration":"115.648461ms","start":"2026-04-09T17:33:45.899249Z","end":"2026-04-09T17:33:46.014898Z","steps":["trace[1354903151] 'agreement among raft nodes before linearized reading' (duration: 112.819839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:46.015096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.785911ms","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:6"} {"level":"info","ts":"2026-04-09T17:33:46.015156Z","caller":"traceutil/trace.go:171","msg":"trace[1476248419] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:44384; }","duration":"103.891204ms","start":"2026-04-09T17:33:45.911251Z","end":"2026-04-09T17:33:46.015142Z","steps":["trace[1476248419] 'agreement among raft nodes before linearized reading' (duration: 103.765911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:46.56564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.174775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/nova-novncproxy\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-09T17:33:46.565746Z","caller":"traceutil/trace.go:171","msg":"trace[1394378116] range","detail":"{range_begin:/registry/serviceaccounts/openstack/nova-novncproxy; range_end:; response_count:1; response_revision:44390; }","duration":"120.328909ms","start":"2026-04-09T17:33:46.445394Z","end":"2026-04-09T17:33:46.565723Z","steps":["trace[1394378116] 'range keys from in-memory index tree' (duration: 120.009362ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:33:48.057594Z","caller":"traceutil/trace.go:171","msg":"trace[726433756] linearizableReadLoop","detail":"{readStateIndex:45870; appliedIndex:45869; }","duration":"141.980376ms","start":"2026-04-09T17:33:47.915589Z","end":"2026-04-09T17:33:48.057569Z","steps":["trace[726433756] 'read index received' (duration: 141.858183ms)","trace[726433756] 'applied index is now lower than readState.Index' (duration: 121.193µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:33:48.05762Z","caller":"traceutil/trace.go:171","msg":"trace[376667428] transaction","detail":"{read_only:false; response_revision:44394; number_of_response:1; }","duration":"146.789468ms","start":"2026-04-09T17:33:47.910809Z","end":"2026-04-09T17:33:48.057598Z","steps":["trace[376667428] 'process raft request' (duration: 146.568292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:33:48.059834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.221113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:500 size:388651"} {"level":"info","ts":"2026-04-09T17:33:48.059879Z","caller":"traceutil/trace.go:171","msg":"trace[1284686475] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:500; response_revision:44394; }","duration":"144.284674ms","start":"2026-04-09T17:33:47.915583Z","end":"2026-04-09T17:33:48.059868Z","steps":["trace[1284686475] 'agreement among raft nodes before linearized reading' (duration: 142.080428ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:34:02.263512Z","caller":"traceutil/trace.go:171","msg":"trace[1522571107] transaction","detail":"{read_only:false; response_revision:44496; number_of_response:1; }","duration":"157.089808ms","start":"2026-04-09T17:34:02.106403Z","end":"2026-04-09T17:34:02.263492Z","steps":["trace[1522571107] 'process raft request' (duration: 156.958295ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:34:02.610314Z","caller":"traceutil/trace.go:171","msg":"trace[524490154] transaction","detail":"{read_only:false; response_revision:44498; number_of_response:1; }","duration":"497.391063ms","start":"2026-04-09T17:34:02.112899Z","end":"2026-04-09T17:34:02.61029Z","steps":["trace[524490154] 'process raft request' (duration: 497.254519ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:34:02.610342Z","caller":"traceutil/trace.go:171","msg":"trace[985838671] transaction","detail":"{read_only:false; response_revision:44497; number_of_response:1; }","duration":"500.83885ms","start":"2026-04-09T17:34:02.109461Z","end":"2026-04-09T17:34:02.6103Z","steps":["trace[985838671] 'process raft request' (duration: 500.483891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:02.610463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:34:02.112878Z","time spent":"497.508225ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T17:34:02.610508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:34:02.109442Z","time spent":"500.976874ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T17:34:02.610581Z","caller":"traceutil/trace.go:171","msg":"trace[982492502] linearizableReadLoop","detail":"{readStateIndex:45979; appliedIndex:45974; }","duration":"429.688922ms","start":"2026-04-09T17:34:02.180867Z","end":"2026-04-09T17:34:02.610556Z","steps":["trace[982492502] 'read index received' (duration: 82.556534ms)","trace[982492502] 'applied index is now lower than readState.Index' (duration: 347.131308ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:34:02.610651Z","caller":"traceutil/trace.go:171","msg":"trace[648640760] transaction","detail":"{read_only:false; response_revision:44500; number_of_response:1; }","duration":"468.858732ms","start":"2026-04-09T17:34:02.14178Z","end":"2026-04-09T17:34:02.610639Z","steps":["trace[648640760] 'process raft request' (duration: 468.713879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:02.610725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:34:02.141771Z","time spent":"468.915714ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T17:34:02.610792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.914819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:714"} {"level":"info","ts":"2026-04-09T17:34:02.610822Z","caller":"traceutil/trace.go:171","msg":"trace[1111638508] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:44500; }","duration":"429.98382ms","start":"2026-04-09T17:34:02.180826Z","end":"2026-04-09T17:34:02.61081Z","steps":["trace[1111638508] 'agreement among raft nodes before linearized reading' (duration: 429.849366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:02.61087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:34:02.180806Z","time spent":"430.050342ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":738,"request content":"key:\"/registry/serviceaccounts/openstack/rabbitmq-keystone-server\" "} {"level":"warn","ts":"2026-04-09T17:34:02.610878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.826491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-04-09T17:34:02.610875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.951552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-magnum-server\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-04-09T17:34:02.61093Z","caller":"traceutil/trace.go:171","msg":"trace[1355320187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:44500; }","duration":"156.918163ms","start":"2026-04-09T17:34:02.453999Z","end":"2026-04-09T17:34:02.610917Z","steps":["trace[1355320187] 'agreement among raft nodes before linearized reading' (duration: 156.766219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:02.61093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.99741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-09T17:34:02.61095Z","caller":"traceutil/trace.go:171","msg":"trace[1487399033] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-magnum-server; range_end:; response_count:1; response_revision:44500; }","duration":"381.071305ms","start":"2026-04-09T17:34:02.229864Z","end":"2026-04-09T17:34:02.610936Z","steps":["trace[1487399033] 'agreement among raft nodes before linearized reading' (duration: 380.859389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:02.610935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.638105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-04-09T17:34:02.61094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.847224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-09T17:34:02.610978Z","caller":"traceutil/trace.go:171","msg":"trace[635704819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:44500; }","duration":"276.083234ms","start":"2026-04-09T17:34:02.334884Z","end":"2026-04-09T17:34:02.610968Z","steps":["trace[635704819] 'agreement among raft nodes before linearized reading' (duration: 275.957899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:02.610987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:34:02.229848Z","time spent":"381.132448ms","remote":"127.0.0.1:44606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":732,"request content":"key:\"/registry/serviceaccounts/openstack/rabbitmq-magnum-server\" "} {"level":"info","ts":"2026-04-09T17:34:02.611Z","caller":"traceutil/trace.go:171","msg":"trace[354190041] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:44500; }","duration":"154.742369ms","start":"2026-04-09T17:34:02.45624Z","end":"2026-04-09T17:34:02.610982Z","steps":["trace[354190041] 'agreement among raft nodes before linearized reading' (duration: 154.504462ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:34:02.611016Z","caller":"traceutil/trace.go:171","msg":"trace[391720153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:44500; }","duration":"128.949778ms","start":"2026-04-09T17:34:02.482043Z","end":"2026-04-09T17:34:02.610993Z","steps":["trace[391720153] 'agreement among raft nodes before linearized reading' (duration: 128.755442ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:34:02.611088Z","caller":"traceutil/trace.go:171","msg":"trace[730418870] transaction","detail":"{read_only:false; response_revision:44499; number_of_response:1; }","duration":"480.589727ms","start":"2026-04-09T17:34:02.130055Z","end":"2026-04-09T17:34:02.610644Z","steps":["trace[730418870] 'process raft request' (duration: 480.192658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:02.611164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:34:02.130038Z","time spent":"481.078371ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T17:34:02.611288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.797367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-09T17:34:02.611382Z","caller":"traceutil/trace.go:171","msg":"trace[788427196] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:44500; }","duration":"142.93803ms","start":"2026-04-09T17:34:02.468425Z","end":"2026-04-09T17:34:02.611363Z","steps":["trace[788427196] 'agreement among raft nodes before linearized reading' (duration: 142.52943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:02.924538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.362226ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-09T17:34:02.924634Z","caller":"traceutil/trace.go:171","msg":"trace[77465056] transaction","detail":"{read_only:false; response_revision:44501; number_of_response:1; }","duration":"306.04311ms","start":"2026-04-09T17:34:02.618574Z","end":"2026-04-09T17:34:02.924617Z","steps":["trace[77465056] 'process raft request' (duration: 119.508999ms)","trace[77465056] 'compare' (duration: 186.141371ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T17:34:02.924688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:34:02.618545Z","time spent":"306.117962ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T17:34:02.988245Z","caller":"traceutil/trace.go:171","msg":"trace[2016443885] transaction","detail":"{read_only:false; response_revision:44502; number_of_response:1; }","duration":"369.595705ms","start":"2026-04-09T17:34:02.61862Z","end":"2026-04-09T17:34:02.988216Z","steps":["trace[2016443885] 'process raft request' (duration: 369.362139ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:34:02.988386Z","caller":"traceutil/trace.go:171","msg":"trace[437942087] transaction","detail":"{read_only:false; response_revision:44503; number_of_response:1; }","duration":"369.721638ms","start":"2026-04-09T17:34:02.618649Z","end":"2026-04-09T17:34:02.98837Z","steps":["trace[437942087] 'process raft request' (duration: 369.452451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:02.988392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:34:02.618591Z","time spent":"369.721739ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T17:34:02.98838Z","caller":"traceutil/trace.go:171","msg":"trace[1113567767] transaction","detail":"{read_only:false; response_revision:44504; number_of_response:1; }","duration":"369.38657ms","start":"2026-04-09T17:34:02.618894Z","end":"2026-04-09T17:34:02.988281Z","steps":["trace[1113567767] 'process raft request' (duration: 369.244396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:02.988456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.077078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-magnum-server\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-04-09T17:34:02.988257Z","caller":"traceutil/trace.go:171","msg":"trace[1674675828] linearizableReadLoop","detail":"{readStateIndex:45985; appliedIndex:45979; }","duration":"276.861613ms","start":"2026-04-09T17:34:02.711383Z","end":"2026-04-09T17:34:02.988245Z","steps":["trace[1674675828] 'read index received' (duration: 26.710755ms)","trace[1674675828] 'applied index is now lower than readState.Index' (duration: 250.150128ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T17:34:02.988489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:34:02.618632Z","time spent":"369.810131ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T17:34:02.988529Z","caller":"traceutil/trace.go:171","msg":"trace[1198650095] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-magnum-server; range_end:; response_count:1; response_revision:44506; }","duration":"277.15377ms","start":"2026-04-09T17:34:02.711335Z","end":"2026-04-09T17:34:02.988489Z","steps":["trace[1198650095] 'agreement among raft nodes before linearized reading' (duration: 276.953495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:02.988609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:34:02.61887Z","time spent":"369.596295ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T17:34:02.988662Z","caller":"traceutil/trace.go:171","msg":"trace[238930333] transaction","detail":"{read_only:false; response_revision:44505; number_of_response:1; }","duration":"369.744399ms","start":"2026-04-09T17:34:02.61889Z","end":"2026-04-09T17:34:02.988635Z","steps":["trace[238930333] 'process raft request' (duration: 369.281537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:02.988705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.593421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:714"} {"level":"warn","ts":"2026-04-09T17:34:02.988725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:34:02.618882Z","time spent":"369.80333ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T17:34:02.988751Z","caller":"traceutil/trace.go:171","msg":"trace[1672738725] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:44506; }","duration":"220.666453ms","start":"2026-04-09T17:34:02.768071Z","end":"2026-04-09T17:34:02.988738Z","steps":["trace[1672738725] 'agreement among raft nodes before linearized reading' (duration: 220.561981ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:34:02.988763Z","caller":"traceutil/trace.go:171","msg":"trace[571409146] transaction","detail":"{read_only:false; response_revision:44506; number_of_response:1; }","duration":"288.233371ms","start":"2026-04-09T17:34:02.700518Z","end":"2026-04-09T17:34:02.988751Z","steps":["trace[571409146] 'process raft request' (duration: 287.685267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:02.988864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.17108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-09T17:34:02.988902Z","caller":"traceutil/trace.go:171","msg":"trace[939982540] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:44506; }","duration":"159.237941ms","start":"2026-04-09T17:34:02.829653Z","end":"2026-04-09T17:34:02.988891Z","steps":["trace[939982540] 'agreement among raft nodes before linearized reading' (duration: 159.17861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:03.630118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.333865ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-09T17:34:03.630325Z","caller":"traceutil/trace.go:171","msg":"trace[257594144] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:44507; }","duration":"264.552492ms","start":"2026-04-09T17:34:03.365753Z","end":"2026-04-09T17:34:03.630306Z","steps":["trace[257594144] 'range keys from in-memory index tree' (duration: 264.317635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:03.630142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.053637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-04-09T17:34:03.630692Z","caller":"traceutil/trace.go:171","msg":"trace[1834332010] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:44507; }","duration":"275.637633ms","start":"2026-04-09T17:34:03.355037Z","end":"2026-04-09T17:34:03.630675Z","steps":["trace[1834332010] 'range keys from in-memory index tree' (duration: 274.915814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:03.630612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.095696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-09T17:34:03.631277Z","caller":"traceutil/trace.go:171","msg":"trace[967407802] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:44507; }","duration":"286.744773ms","start":"2026-04-09T17:34:03.344447Z","end":"2026-04-09T17:34:03.631192Z","steps":["trace[967407802] 'count revisions from in-memory index tree' (duration: 286.047575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:03.630892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.325965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/octavia-api\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-04-09T17:34:03.631354Z","caller":"traceutil/trace.go:171","msg":"trace[2143956812] range","detail":"{range_begin:/registry/serviceaccounts/openstack/octavia-api; range_end:; response_count:1; response_revision:44507; }","duration":"298.799527ms","start":"2026-04-09T17:34:03.33253Z","end":"2026-04-09T17:34:03.63133Z","steps":["trace[2143956812] 'range keys from in-memory index tree' (duration: 298.208272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:03.630963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.50894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/octavia-housekeeping\" ","response":"range_response_count:1 size:546"} {"level":"warn","ts":"2026-04-09T17:34:03.631005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.10231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-04-09T17:34:03.631534Z","caller":"traceutil/trace.go:171","msg":"trace[153741428] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:44507; }","duration":"106.674374ms","start":"2026-04-09T17:34:03.524843Z","end":"2026-04-09T17:34:03.631517Z","steps":["trace[153741428] 'range keys from in-memory index tree' (duration: 105.987937ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:34:03.631578Z","caller":"traceutil/trace.go:171","msg":"trace[1959623952] range","detail":"{range_begin:/registry/serviceaccounts/openstack/octavia-housekeeping; range_end:; response_count:1; response_revision:44507; }","duration":"299.040333ms","start":"2026-04-09T17:34:03.332406Z","end":"2026-04-09T17:34:03.631446Z","steps":["trace[1959623952] 'range keys from in-memory index tree' (duration: 298.290654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:03.632929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.547886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-ks-service.18a4be615823a863\\000\" range_end:\"/registry/events/openstack0\" limit:1000 revision:44507 ","response":"range_response_count:769 size:596299"} {"level":"info","ts":"2026-04-09T17:34:03.63297Z","caller":"traceutil/trace.go:171","msg":"trace[1646565924] range","detail":"{range_begin:/registry/events/openstack/magnum-ks-service.18a4be615823a863\u0000; range_end:/registry/events/openstack0; response_count:769; response_revision:44507; }","duration":"360.616698ms","start":"2026-04-09T17:34:03.272344Z","end":"2026-04-09T17:34:03.63296Z","steps":["trace[1646565924] 'range keys from in-memory index tree' (duration: 357.806976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:03.633011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:34:03.272331Z","time spent":"360.654638ms","remote":"127.0.0.1:44482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":769,"response size":596323,"request content":"key:\"/registry/events/openstack/magnum-ks-service.18a4be615823a863\\000\" range_end:\"/registry/events/openstack0\" limit:1000 revision:44507 "} {"level":"info","ts":"2026-04-09T17:34:03.942767Z","caller":"traceutil/trace.go:171","msg":"trace[1835124864] linearizableReadLoop","detail":"{readStateIndex:45987; appliedIndex:45986; }","duration":"381.696771ms","start":"2026-04-09T17:34:03.561045Z","end":"2026-04-09T17:34:03.942742Z","steps":["trace[1835124864] 'read index received' (duration: 381.484416ms)","trace[1835124864] 'applied index is now lower than readState.Index' (duration: 211.305µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:34:03.94282Z","caller":"traceutil/trace.go:171","msg":"trace[931131756] transaction","detail":"{read_only:false; response_revision:44508; number_of_response:1; }","duration":"391.637542ms","start":"2026-04-09T17:34:03.551148Z","end":"2026-04-09T17:34:03.942785Z","steps":["trace[931131756] 'process raft request' (duration: 391.451887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:03.94295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.876835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-09T17:34:03.943012Z","caller":"traceutil/trace.go:171","msg":"trace[1125318107] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:44508; }","duration":"381.959977ms","start":"2026-04-09T17:34:03.561035Z","end":"2026-04-09T17:34:03.942995Z","steps":["trace[1125318107] 'agreement among raft nodes before linearized reading' (duration: 381.845734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:03.943001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:34:03.551132Z","time spent":"391.763286ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T17:34:03.94306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:34:03.561011Z","time spent":"382.0368ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":142,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-09T17:34:03.943251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.801902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:1115"} {"level":"warn","ts":"2026-04-09T17:34:03.943278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.334219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/octavia-api-5bfdd667db-zlvkb\" ","response":"range_response_count:1 size:8677"} {"level":"warn","ts":"2026-04-09T17:34:03.943428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.974746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:708"} {"level":"warn","ts":"2026-04-09T17:34:03.943258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.902562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-04-09T17:34:03.943459Z","caller":"traceutil/trace.go:171","msg":"trace[1754538922] range","detail":"{range_begin:/registry/pods/openstack/octavia-api-5bfdd667db-zlvkb; range_end:; response_count:1; response_revision:44508; }","duration":"310.602835ms","start":"2026-04-09T17:34:03.632832Z","end":"2026-04-09T17:34:03.943435Z","steps":["trace[1754538922] 'agreement among raft nodes before linearized reading' (duration: 310.198585ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:34:03.943496Z","caller":"traceutil/trace.go:171","msg":"trace[484234957] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:44508; }","duration":"159.050077ms","start":"2026-04-09T17:34:03.784425Z","end":"2026-04-09T17:34:03.943475Z","steps":["trace[484234957] 'agreement among raft nodes before linearized reading' (duration: 158.857172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:03.943521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:34:03.632809Z","time spent":"310.695588ms","remote":"127.0.0.1:44576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":8701,"request content":"key:\"/registry/pods/openstack/octavia-api-5bfdd667db-zlvkb\" "} {"level":"info","ts":"2026-04-09T17:34:03.943527Z","caller":"traceutil/trace.go:171","msg":"trace[1892996495] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:44508; }","duration":"307.227739ms","start":"2026-04-09T17:34:03.636287Z","end":"2026-04-09T17:34:03.943515Z","steps":["trace[1892996495] 'agreement among raft nodes before linearized reading' (duration: 306.797419ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:34:03.943389Z","caller":"traceutil/trace.go:171","msg":"trace[606208556] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:44508; }","duration":"158.924704ms","start":"2026-04-09T17:34:03.7844Z","end":"2026-04-09T17:34:03.943324Z","steps":["trace[606208556] 'agreement among raft nodes before linearized reading' (duration: 158.655908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:03.943622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:34:03.636279Z","time spent":"307.28313ms","remote":"127.0.0.1:44576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-09T17:34:03.943703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.698337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/octavia-housekeeping-784b9fb79c-rxfr2\" ","response":"range_response_count:1 size:8320"} {"level":"info","ts":"2026-04-09T17:34:03.943817Z","caller":"traceutil/trace.go:171","msg":"trace[1153226733] range","detail":"{range_begin:/registry/pods/openstack/octavia-housekeeping-784b9fb79c-rxfr2; range_end:; response_count:1; response_revision:44508; }","duration":"310.865992ms","start":"2026-04-09T17:34:03.63293Z","end":"2026-04-09T17:34:03.943796Z","steps":["trace[1153226733] 'agreement among raft nodes before linearized reading' (duration: 310.37976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:03.94391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:34:03.63292Z","time spent":"310.971365ms","remote":"127.0.0.1:44576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":8344,"request content":"key:\"/registry/pods/openstack/octavia-housekeeping-784b9fb79c-rxfr2\" "} {"level":"warn","ts":"2026-04-09T17:34:04.615812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.787394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-nova-server\" ","response":"range_response_count:1 size:698"} {"level":"info","ts":"2026-04-09T17:34:04.615985Z","caller":"traceutil/trace.go:171","msg":"trace[1759563874] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-nova-server; range_end:; response_count:1; response_revision:44510; }","duration":"151.999049ms","start":"2026-04-09T17:34:04.463965Z","end":"2026-04-09T17:34:04.615964Z","steps":["trace[1759563874] 'range keys from in-memory index tree' (duration: 151.584359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:04.616445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.779349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-04-09T17:34:04.6165Z","caller":"traceutil/trace.go:171","msg":"trace[334767151] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:44510; }","duration":"190.83825ms","start":"2026-04-09T17:34:04.425649Z","end":"2026-04-09T17:34:04.616487Z","steps":["trace[334767151] 'range keys from in-memory index tree' (duration: 190.635675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:04.619914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.283348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:500 size:388651"} {"level":"info","ts":"2026-04-09T17:34:04.620019Z","caller":"traceutil/trace.go:171","msg":"trace[1346902889] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:500; response_revision:44510; }","duration":"116.437882ms","start":"2026-04-09T17:34:04.50356Z","end":"2026-04-09T17:34:04.619998Z","steps":["trace[1346902889] 'range keys from in-memory index tree' (duration: 113.074127ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:34:04.923332Z","caller":"traceutil/trace.go:171","msg":"trace[1506808855] transaction","detail":"{read_only:false; response_revision:44511; number_of_response:1; }","duration":"305.517467ms","start":"2026-04-09T17:34:04.617797Z","end":"2026-04-09T17:34:04.923314Z","steps":["trace[1506808855] 'process raft request' (duration: 305.386593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:04.923438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:34:04.617761Z","time spent":"305.623889ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T17:34:04.991572Z","caller":"traceutil/trace.go:171","msg":"trace[828922346] transaction","detail":"{read_only:false; response_revision:44512; number_of_response:1; }","duration":"373.670878ms","start":"2026-04-09T17:34:04.617851Z","end":"2026-04-09T17:34:04.991522Z","steps":["trace[828922346] 'process raft request' (duration: 373.231977ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:34:04.991632Z","caller":"traceutil/trace.go:171","msg":"trace[1637445584] transaction","detail":"{read_only:false; response_revision:44513; number_of_response:1; }","duration":"373.680248ms","start":"2026-04-09T17:34:04.617922Z","end":"2026-04-09T17:34:04.991602Z","steps":["trace[1637445584] 'process raft request' (duration: 373.466413ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:34:04.991679Z","caller":"traceutil/trace.go:171","msg":"trace[1734733090] transaction","detail":"{read_only:false; response_revision:44514; number_of_response:1; }","duration":"373.589246ms","start":"2026-04-09T17:34:04.618077Z","end":"2026-04-09T17:34:04.991666Z","steps":["trace[1734733090] 'process raft request' (duration: 373.380271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:04.991757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:34:04.617826Z","time spent":"373.838392ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T17:34:04.991728Z","caller":"traceutil/trace.go:171","msg":"trace[409442207] linearizableReadLoop","detail":"{readStateIndex:45993; appliedIndex:45989; }","duration":"364.913176ms","start":"2026-04-09T17:34:04.626611Z","end":"2026-04-09T17:34:04.991524Z","steps":["trace[409442207] 'read index received' (duration: 296.639322ms)","trace[409442207] 'applied index is now lower than readState.Index' (duration: 68.272324ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T17:34:04.99178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:34:04.617881Z","time spent":"373.834832ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T17:34:04.991769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:34:04.618063Z","time spent":"373.649537ms","remote":"127.0.0.1:44662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T17:34:04.992343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.285688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-nova-server\" ","response":"range_response_count:1 size:698"} {"level":"info","ts":"2026-04-09T17:34:04.992403Z","caller":"traceutil/trace.go:171","msg":"trace[492250127] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-nova-server; range_end:; response_count:1; response_revision:44514; }","duration":"289.428481ms","start":"2026-04-09T17:34:04.70296Z","end":"2026-04-09T17:34:04.992388Z","steps":["trace[492250127] 'agreement among raft nodes before linearized reading' (duration: 289.199315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:04.994626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.006345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-ks-service.18a4be615823a863\\000\" range_end:\"/registry/events/openstack0\" limit:1000 revision:44510 ","response":"range_response_count:769 size:596299"} {"level":"info","ts":"2026-04-09T17:34:04.994666Z","caller":"traceutil/trace.go:171","msg":"trace[510285852] range","detail":"{range_begin:/registry/events/openstack/magnum-ks-service.18a4be615823a863\u0000; range_end:/registry/events/openstack0; response_count:769; response_revision:44514; }","duration":"368.083786ms","start":"2026-04-09T17:34:04.626574Z","end":"2026-04-09T17:34:04.994657Z","steps":["trace[510285852] 'agreement among raft nodes before linearized reading' (duration: 365.397258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:04.994701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T17:34:04.626557Z","time spent":"368.125488ms","remote":"127.0.0.1:44482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":769,"response size":596323,"request content":"key:\"/registry/events/openstack/magnum-ks-service.18a4be615823a863\\000\" range_end:\"/registry/events/openstack0\" limit:1000 revision:44510 "} {"level":"info","ts":"2026-04-09T17:34:05.340793Z","caller":"traceutil/trace.go:171","msg":"trace[1724328357] linearizableReadLoop","detail":"{readStateIndex:46000; appliedIndex:45999; }","duration":"114.735938ms","start":"2026-04-09T17:34:05.226007Z","end":"2026-04-09T17:34:05.340743Z","steps":["trace[1724328357] 'read index received' (duration: 81.871478ms)","trace[1724328357] 'applied index is now lower than readState.Index' (duration: 32.86248ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T17:34:05.341317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.273391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-octavia-server\" ","response":"range_response_count:1 size:712"} {"level":"info","ts":"2026-04-09T17:34:05.341485Z","caller":"traceutil/trace.go:171","msg":"trace[426109936] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-octavia-server; range_end:; response_count:1; response_revision:44520; }","duration":"115.461357ms","start":"2026-04-09T17:34:05.225946Z","end":"2026-04-09T17:34:05.341408Z","steps":["trace[426109936] 'agreement among raft nodes before linearized reading' (duration: 114.955504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:05.3452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.730887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:500 size:388651"} {"level":"info","ts":"2026-04-09T17:34:05.345366Z","caller":"traceutil/trace.go:171","msg":"trace[1835886323] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:500; response_revision:44520; }","duration":"106.960852ms","start":"2026-04-09T17:34:05.238365Z","end":"2026-04-09T17:34:05.345326Z","steps":["trace[1835886323] 'agreement among raft nodes before linearized reading' (duration: 102.691094ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:34:06.2045Z","caller":"traceutil/trace.go:171","msg":"trace[1907150525] transaction","detail":"{read_only:false; response_revision:44522; number_of_response:1; }","duration":"234.74925ms","start":"2026-04-09T17:34:05.969725Z","end":"2026-04-09T17:34:06.204475Z","steps":["trace[1907150525] 'process raft request' (duration: 234.506624ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:34:06.204712Z","caller":"traceutil/trace.go:171","msg":"trace[1032548941] linearizableReadLoop","detail":"{readStateIndex:46002; appliedIndex:46000; }","duration":"233.59563ms","start":"2026-04-09T17:34:05.97108Z","end":"2026-04-09T17:34:06.204676Z","steps":["trace[1032548941] 'read index received' (duration: 39.897697ms)","trace[1032548941] 'applied index is now lower than readState.Index' (duration: 193.695283ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:34:06.204786Z","caller":"traceutil/trace.go:171","msg":"trace[178837478] transaction","detail":"{read_only:false; response_revision:44523; number_of_response:1; }","duration":"202.895884ms","start":"2026-04-09T17:34:06.001863Z","end":"2026-04-09T17:34:06.204759Z","steps":["trace[178837478] 'process raft request' (duration: 202.567246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:06.204999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.893437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rook-ceph-mgr\" ","response":"range_response_count:1 size:830"} {"level":"info","ts":"2026-04-09T17:34:06.205043Z","caller":"traceutil/trace.go:171","msg":"trace[2089569685] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rook-ceph-mgr; range_end:; response_count:1; response_revision:44523; }","duration":"233.98006ms","start":"2026-04-09T17:34:05.971051Z","end":"2026-04-09T17:34:06.205031Z","steps":["trace[2089569685] 'agreement among raft nodes before linearized reading' (duration: 233.776594ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:34:06.216905Z","caller":"traceutil/trace.go:171","msg":"trace[966176875] transaction","detail":"{read_only:false; response_revision:44524; number_of_response:1; }","duration":"193.545609ms","start":"2026-04-09T17:34:06.023328Z","end":"2026-04-09T17:34:06.216874Z","steps":["trace[966176875] 'process raft request' (duration: 193.325613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:06.220068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.170774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:500 size:388651"} {"level":"info","ts":"2026-04-09T17:34:06.220163Z","caller":"traceutil/trace.go:171","msg":"trace[1381810465] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:500; response_revision:44524; }","duration":"211.335569ms","start":"2026-04-09T17:34:06.008807Z","end":"2026-04-09T17:34:06.220142Z","steps":["trace[1381810465] 'agreement among raft nodes before linearized reading' (duration: 208.002045ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:34:07.062118Z","caller":"traceutil/trace.go:171","msg":"trace[816460410] linearizableReadLoop","detail":"{readStateIndex:46006; appliedIndex:46005; }","duration":"128.803293ms","start":"2026-04-09T17:34:06.93329Z","end":"2026-04-09T17:34:07.062093Z","steps":["trace[816460410] 'read index received' (duration: 128.579377ms)","trace[816460410] 'applied index is now lower than readState.Index' (duration: 222.686µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T17:34:07.062187Z","caller":"traceutil/trace.go:171","msg":"trace[2110486255] transaction","detail":"{read_only:false; response_revision:44526; number_of_response:1; }","duration":"130.964737ms","start":"2026-04-09T17:34:06.931183Z","end":"2026-04-09T17:34:07.062148Z","steps":["trace[2110486255] 'process raft request' (duration: 130.68045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:07.065531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.21739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-ks-service.18a4be615823a863\\000\" range_end:\"/registry/events/openstack0\" limit:1000 revision:44525 ","response":"range_response_count:769 size:596299"} {"level":"info","ts":"2026-04-09T17:34:07.065605Z","caller":"traceutil/trace.go:171","msg":"trace[1370334921] range","detail":"{range_begin:/registry/events/openstack/magnum-ks-service.18a4be615823a863\u0000; range_end:/registry/events/openstack0; response_count:769; response_revision:44526; }","duration":"132.311142ms","start":"2026-04-09T17:34:06.933277Z","end":"2026-04-09T17:34:07.065588Z","steps":["trace[1370334921] 'agreement among raft nodes before linearized reading' (duration: 128.979487ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:34:07.145733Z","caller":"traceutil/trace.go:171","msg":"trace[1989944192] transaction","detail":"{read_only:false; response_revision:44529; number_of_response:1; }","duration":"149.131126ms","start":"2026-04-09T17:34:06.99658Z","end":"2026-04-09T17:34:07.145711Z","steps":["trace[1989944192] 'process raft request' (duration: 149.070805ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:34:07.145757Z","caller":"traceutil/trace.go:171","msg":"trace[1795332965] transaction","detail":"{read_only:false; response_revision:44527; number_of_response:1; }","duration":"149.449145ms","start":"2026-04-09T17:34:06.996266Z","end":"2026-04-09T17:34:07.145715Z","steps":["trace[1795332965] 'process raft request' (duration: 149.230979ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:34:07.145779Z","caller":"traceutil/trace.go:171","msg":"trace[1737006777] transaction","detail":"{read_only:false; response_revision:44528; number_of_response:1; }","duration":"149.210628ms","start":"2026-04-09T17:34:06.996533Z","end":"2026-04-09T17:34:07.145744Z","steps":["trace[1737006777] 'process raft request' (duration: 149.086255ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T17:34:07.145879Z","caller":"traceutil/trace.go:171","msg":"trace[1023963055] transaction","detail":"{read_only:false; response_revision:44530; number_of_response:1; }","duration":"128.727541ms","start":"2026-04-09T17:34:07.017135Z","end":"2026-04-09T17:34:07.145863Z","steps":["trace[1023963055] 'process raft request' (duration: 128.544587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T17:34:07.151462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.707576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/\" range_end:\"/registry/events/openstack0\" limit:500 ","response":"range_response_count:500 size:388651"} {"level":"info","ts":"2026-04-09T17:34:07.151556Z","caller":"traceutil/trace.go:171","msg":"trace[286822607] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:500; response_revision:44530; }","duration":"165.861879ms","start":"2026-04-09T17:34:06.985673Z","end":"2026-04-09T17:34:07.151534Z","steps":["trace[286822607] 'agreement among raft nodes before linearized reading' (duration: 160.252788ms)"],"step_count":1}