{"level":"info","ts":"2026-05-19T19:06:43.959151Z","caller":"traceutil/trace.go:171","msg":"trace[374005480] linearizableReadLoop","detail":"{readStateIndex:32380; appliedIndex:32379; }","duration":"164.452182ms","start":"2026-05-19T19:06:43.794677Z","end":"2026-05-19T19:06:43.95913Z","steps":["trace[374005480] 'read index received'  (duration: 164.304138ms)","trace[374005480] 'applied index is now lower than readState.Index'  (duration: 147.104µs)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:06:43.959207Z","caller":"traceutil/trace.go:171","msg":"trace[608914680] transaction","detail":"{read_only:false; response_revision:31305; number_of_response:1; }","duration":"176.353811ms","start":"2026-05-19T19:06:43.782821Z","end":"2026-05-19T19:06:43.959175Z","steps":["trace[608914680] 'process raft request'  (duration: 176.155106ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:06:43.959352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.665856ms","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-05-19T19:06:43.959397Z","caller":"traceutil/trace.go:171","msg":"trace[799086928] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:31305; }","duration":"164.755348ms","start":"2026-05-19T19:06:43.794628Z","end":"2026-05-19T19:06:43.959384Z","steps":["trace[799086928] 'agreement among raft nodes before linearized reading'  (duration: 164.617275ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:06:43.966598Z","caller":"traceutil/trace.go:171","msg":"trace[1130940070] transaction","detail":"{read_only:false; response_revision:31306; number_of_response:1; }","duration":"132.103417ms","start":"2026-05-19T19:06:43.834476Z","end":"2026-05-19T19:06:43.966579Z","steps":["trace[1130940070] 'process raft request'  (duration: 131.821391ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:06:54.139029Z","caller":"traceutil/trace.go:171","msg":"trace[738251844] linearizableReadLoop","detail":"{readStateIndex:32461; appliedIndex:32460; }","duration":"100.674005ms","start":"2026-05-19T19:06:54.038328Z","end":"2026-05-19T19:06:54.139002Z","steps":["trace[738251844] 'read index received'  (duration: 52.833239ms)","trace[738251844] 'applied index is now lower than readState.Index'  (duration: 47.839665ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:06:54.139158Z","caller":"traceutil/trace.go:171","msg":"trace[822469811] transaction","detail":"{read_only:false; response_revision:31384; number_of_response:1; }","duration":"117.23773ms","start":"2026-05-19T19:06:54.021906Z","end":"2026-05-19T19:06:54.139144Z","steps":["trace[822469811] 'process raft request'  (duration: 69.21778ms)","trace[822469811] 'compare'  (duration: 47.708613ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:06:54.139287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.95538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4843"}
{"level":"info","ts":"2026-05-19T19:06:54.139349Z","caller":"traceutil/trace.go:171","msg":"trace[875708272] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:31384; }","duration":"101.057463ms","start":"2026-05-19T19:06:54.03828Z","end":"2026-05-19T19:06:54.139337Z","steps":["trace[875708272] 'agreement among raft nodes before linearized reading'  (duration: 100.90932ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:06:54.148509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.177589ms","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-05-19T19:06:54.148588Z","caller":"traceutil/trace.go:171","msg":"trace[107096171] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:31385; }","duration":"106.303632ms","start":"2026-05-19T19:06:54.042267Z","end":"2026-05-19T19:06:54.148571Z","steps":["trace[107096171] 'agreement among raft nodes before linearized reading'  (duration: 106.101228ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:06:54.148922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.008943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"info","ts":"2026-05-19T19:06:54.14905Z","caller":"traceutil/trace.go:171","msg":"trace[430164076] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:31385; }","duration":"105.151826ms","start":"2026-05-19T19:06:54.043872Z","end":"2026-05-19T19:06:54.149023Z","steps":["trace[430164076] 'agreement among raft nodes before linearized reading'  (duration: 104.616043ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:06:54.752745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.111301ms","expected-duration":"100ms","prefix":"","request":"header:<ID:6432440171452002712 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:31378 > success:<request_put:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" value_size:441 >> failure:<request_range:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-19T19:06:54.752905Z","caller":"traceutil/trace.go:171","msg":"trace[1370366735] transaction","detail":"{read_only:false; response_revision:31394; number_of_response:1; }","duration":"150.284619ms","start":"2026-05-19T19:06:54.602558Z","end":"2026-05-19T19:06:54.752843Z","steps":["trace[1370366735] 'process raft request'  (duration: 19.979444ms)","trace[1370366735] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/orc-system/f35396c5.k-orc.cloud; req_size:494; } (duration: 129.795714ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:06:55.045693Z","caller":"traceutil/trace.go:171","msg":"trace[1286897435] linearizableReadLoop","detail":"{readStateIndex:32472; appliedIndex:32471; }","duration":"173.946086ms","start":"2026-05-19T19:06:54.871707Z","end":"2026-05-19T19:06:55.045653Z","steps":["trace[1286897435] 'read index received'  (duration: 124.797291ms)","trace[1286897435] 'applied index is now lower than readState.Index'  (duration: 49.147085ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:06:55.0457Z","caller":"traceutil/trace.go:171","msg":"trace[1070665434] transaction","detail":"{read_only:false; response_revision:31395; number_of_response:1; }","duration":"391.536593ms","start":"2026-05-19T19:06:54.654121Z","end":"2026-05-19T19:06:55.045658Z","steps":["trace[1070665434] 'process raft request'  (duration: 342.47877ms)","trace[1070665434] 'compare'  (duration: 48.855109ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:06:55.045838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.123051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:06:55.045909Z","caller":"traceutil/trace.go:171","msg":"trace[1592416321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:31395; }","duration":"174.242812ms","start":"2026-05-19T19:06:54.871651Z","end":"2026-05-19T19:06:55.045894Z","steps":["trace[1592416321] 'agreement among raft nodes before linearized reading'  (duration: 174.12224ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:06:55.045925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.424258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"warn","ts":"2026-05-19T19:06:55.045975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:06:54.654084Z","time spent":"391.763728ms","remote":"127.0.0.1:36832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/capi-system/controller-leader-election-capi\" mod_revision:31379 > success:<request_put:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" > >"}
{"level":"info","ts":"2026-05-19T19:06:55.046029Z","caller":"traceutil/trace.go:171","msg":"trace[1508917736] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:31395; }","duration":"126.570171ms","start":"2026-05-19T19:06:54.919439Z","end":"2026-05-19T19:06:55.046009Z","steps":["trace[1508917736] 'agreement among raft nodes before linearized reading'  (duration: 126.419158ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:07:04.926364Z","caller":"traceutil/trace.go:171","msg":"trace[1171121902] transaction","detail":"{read_only:false; response_revision:31537; number_of_response:1; }","duration":"116.859111ms","start":"2026-05-19T19:07:04.809459Z","end":"2026-05-19T19:07:04.926318Z","steps":["trace[1171121902] 'process raft request'  (duration: 116.604876ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:07:08.602316Z","caller":"traceutil/trace.go:171","msg":"trace[1780043435] linearizableReadLoop","detail":"{readStateIndex:32643; appliedIndex:32642; }","duration":"352.105458ms","start":"2026-05-19T19:07:08.250191Z","end":"2026-05-19T19:07:08.602297Z","steps":["trace[1780043435] 'read index received'  (duration: 165.002393ms)","trace[1780043435] 'applied index is now lower than readState.Index'  (duration: 187.102455ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:07:08.602408Z","caller":"traceutil/trace.go:171","msg":"trace[1380679069] transaction","detail":"{read_only:false; response_revision:31561; number_of_response:1; }","duration":"279.369208ms","start":"2026-05-19T19:07:08.323007Z","end":"2026-05-19T19:07:08.602376Z","steps":["trace[1380679069] 'process raft request'  (duration: 279.247775ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:07:08.60241Z","caller":"traceutil/trace.go:171","msg":"trace[559875298] transaction","detail":"{read_only:false; response_revision:31560; number_of_response:1; }","duration":"361.02374ms","start":"2026-05-19T19:07:08.241322Z","end":"2026-05-19T19:07:08.602346Z","steps":["trace[559875298] 'process raft request'  (duration: 360.808635ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:07:08.60259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.387164ms","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-05-19T19:07:08.60262Z","caller":"traceutil/trace.go:171","msg":"trace[773864023] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:31561; }","duration":"352.453336ms","start":"2026-05-19T19:07:08.250159Z","end":"2026-05-19T19:07:08.602612Z","steps":["trace[773864023] 'agreement among raft nodes before linearized reading'  (duration: 352.274482ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:07:08.602645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:07:08.250145Z","time spent":"352.493907ms","remote":"127.0.0.1:36832","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-05-19T19:07:08.602667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:07:08.241303Z","time spent":"361.241926ms","remote":"127.0.0.1:36832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:31543 > success:<request_put:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" > >"}
{"level":"info","ts":"2026-05-19T19:07:08.60358Z","caller":"traceutil/trace.go:171","msg":"trace[1834283170] transaction","detail":"{read_only:false; response_revision:31562; number_of_response:1; }","duration":"187.440873ms","start":"2026-05-19T19:07:08.416106Z","end":"2026-05-19T19:07:08.603547Z","steps":["trace[1834283170] 'process raft request'  (duration: 187.373142ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:07:08.603673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.085677ms","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-05-19T19:07:08.603698Z","caller":"traceutil/trace.go:171","msg":"trace[38102251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:31562; }","duration":"125.151218ms","start":"2026-05-19T19:07:08.478539Z","end":"2026-05-19T19:07:08.60369Z","steps":["trace[38102251] 'agreement among raft nodes before linearized reading'  (duration: 125.091047ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:07:08.603611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.99577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/manila-api-5854495464-8mq4z.18b10cf40ad99ff0\" ","response":"range_response_count:1 size:1542"}
{"level":"info","ts":"2026-05-19T19:07:08.603906Z","caller":"traceutil/trace.go:171","msg":"trace[873494004] range","detail":"{range_begin:/registry/events/openstack/manila-api-5854495464-8mq4z.18b10cf40ad99ff0; range_end:; response_count:1; response_revision:31562; }","duration":"313.304147ms","start":"2026-05-19T19:07:08.29058Z","end":"2026-05-19T19:07:08.603884Z","steps":["trace[873494004] 'agreement among raft nodes before linearized reading'  (duration: 312.958529ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:07:08.603999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:07:08.290568Z","time spent":"313.396881ms","remote":"127.0.0.1:36624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1565,"request content":"key:\"/registry/events/openstack/manila-api-5854495464-8mq4z.18b10cf40ad99ff0\" "}
{"level":"warn","ts":"2026-05-19T19:07:08.604418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.115076ms","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-05-19T19:07:08.604448Z","caller":"traceutil/trace.go:171","msg":"trace[570746871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:31562; }","duration":"250.212677ms","start":"2026-05-19T19:07:08.354228Z","end":"2026-05-19T19:07:08.60444Z","steps":["trace[570746871] 'agreement among raft nodes before linearized reading'  (duration: 250.141306ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:07:08.604551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.532301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/nova-db-api-user\" ","response":"range_response_count:1 size:714"}
{"level":"warn","ts":"2026-05-19T19:07:08.604548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.73701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openstack-exporter-566fdb67d-xql5f\" ","response":"range_response_count:1 size:6350"}
{"level":"info","ts":"2026-05-19T19:07:08.604574Z","caller":"traceutil/trace.go:171","msg":"trace[1647072799] range","detail":"{range_begin:/registry/secrets/openstack/nova-db-api-user; range_end:; response_count:1; response_revision:31562; }","duration":"193.603662ms","start":"2026-05-19T19:07:08.410965Z","end":"2026-05-19T19:07:08.604568Z","steps":["trace[1647072799] 'agreement among raft nodes before linearized reading'  (duration: 193.53145ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:07:08.604591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.100148ms","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-05-19T19:07:08.604604Z","caller":"traceutil/trace.go:171","msg":"trace[906037799] range","detail":"{range_begin:/registry/pods/openstack/openstack-exporter-566fdb67d-xql5f; range_end:; response_count:1; response_revision:31562; }","duration":"288.845454ms","start":"2026-05-19T19:07:08.315743Z","end":"2026-05-19T19:07:08.604588Z","steps":["trace[906037799] 'agreement among raft nodes before linearized reading'  (duration: 288.649899ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:07:08.604633Z","caller":"traceutil/trace.go:171","msg":"trace[1663611287] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:31562; }","duration":"270.20161ms","start":"2026-05-19T19:07:08.33442Z","end":"2026-05-19T19:07:08.604621Z","steps":["trace[1663611287] 'agreement among raft nodes before linearized reading'  (duration: 270.091308ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:07:08.604702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.743477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"}
{"level":"info","ts":"2026-05-19T19:07:08.604766Z","caller":"traceutil/trace.go:171","msg":"trace[1058277750] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:31562; }","duration":"130.853109ms","start":"2026-05-19T19:07:08.473894Z","end":"2026-05-19T19:07:08.604747Z","steps":["trace[1058277750] 'agreement among raft nodes before linearized reading'  (duration: 130.626514ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:07:08.733025Z","caller":"traceutil/trace.go:171","msg":"trace[234886086] linearizableReadLoop","detail":"{readStateIndex:32649; appliedIndex:32646; }","duration":"118.402817ms","start":"2026-05-19T19:07:08.614601Z","end":"2026-05-19T19:07:08.733004Z","steps":["trace[234886086] 'read index received'  (duration: 840.37µs)","trace[234886086] 'applied index is now lower than readState.Index'  (duration: 117.561377ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:07:08.733063Z","caller":"traceutil/trace.go:171","msg":"trace[804093841] transaction","detail":"{read_only:false; response_revision:31567; number_of_response:1; }","duration":"118.08196ms","start":"2026-05-19T19:07:08.614954Z","end":"2026-05-19T19:07:08.733035Z","steps":["trace[804093841] 'process raft request'  (duration: 117.931666ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:07:08.733122Z","caller":"traceutil/trace.go:171","msg":"trace[1456510781] transaction","detail":"{read_only:false; response_revision:31568; number_of_response:1; }","duration":"118.09195ms","start":"2026-05-19T19:07:08.615018Z","end":"2026-05-19T19:07:08.73311Z","steps":["trace[1456510781] 'process raft request'  (duration: 117.925897ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:07:08.733261Z","caller":"traceutil/trace.go:171","msg":"trace[605627063] transaction","detail":"{read_only:false; response_revision:31566; number_of_response:1; }","duration":"119.191544ms","start":"2026-05-19T19:07:08.614058Z","end":"2026-05-19T19:07:08.73325Z","steps":["trace[605627063] 'process raft request'  (duration: 112.077642ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:07:08.733532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.912398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:139"}
{"level":"info","ts":"2026-05-19T19:07:08.733568Z","caller":"traceutil/trace.go:171","msg":"trace[984435001] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:31568; }","duration":"118.965769ms","start":"2026-05-19T19:07:08.614595Z","end":"2026-05-19T19:07:08.733561Z","steps":["trace[984435001] 'agreement among raft nodes before linearized reading'  (duration: 118.638722ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:07:08.741484Z","caller":"traceutil/trace.go:171","msg":"trace[1144662951] transaction","detail":"{read_only:false; response_revision:31569; number_of_response:1; }","duration":"125.771163ms","start":"2026-05-19T19:07:08.615673Z","end":"2026-05-19T19:07:08.741445Z","steps":["trace[1144662951] 'process raft request'  (duration: 125.140998ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:07:29.245623Z","caller":"traceutil/trace.go:171","msg":"trace[1156714102] transaction","detail":"{read_only:false; response_revision:31757; number_of_response:1; }","duration":"148.008358ms","start":"2026-05-19T19:07:29.097596Z","end":"2026-05-19T19:07:29.245604Z","steps":["trace[1156714102] 'process raft request'  (duration: 147.834424ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:07:29.24797Z","caller":"traceutil/trace.go:171","msg":"trace[1241897422] linearizableReadLoop","detail":"{readStateIndex:32845; appliedIndex:32843; }","duration":"107.429588ms","start":"2026-05-19T19:07:29.140525Z","end":"2026-05-19T19:07:29.247955Z","steps":["trace[1241897422] 'read index received'  (duration: 104.977713ms)","trace[1241897422] 'applied index is now lower than readState.Index'  (duration: 2.451245ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:07:29.248068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.534691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:07:29.248096Z","caller":"traceutil/trace.go:171","msg":"trace[1066099879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:31758; }","duration":"107.597011ms","start":"2026-05-19T19:07:29.140491Z","end":"2026-05-19T19:07:29.248088Z","steps":["trace[1066099879] 'agreement among raft nodes before linearized reading'  (duration: 107.52108ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:07:29.24809Z","caller":"traceutil/trace.go:171","msg":"trace[199123282] transaction","detail":"{read_only:false; response_revision:31758; number_of_response:1; }","duration":"141.00644ms","start":"2026-05-19T19:07:29.107067Z","end":"2026-05-19T19:07:29.248074Z","steps":["trace[199123282] 'process raft request'  (duration: 140.795655ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:07:30.954958Z","caller":"traceutil/trace.go:171","msg":"trace[841746365] linearizableReadLoop","detail":"{readStateIndex:32849; appliedIndex:32848; }","duration":"190.691056ms","start":"2026-05-19T19:07:30.764243Z","end":"2026-05-19T19:07:30.954934Z","steps":["trace[841746365] 'read index received'  (duration: 190.593634ms)","trace[841746365] 'applied index is now lower than readState.Index'  (duration: 96.232µs)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:07:30.955284Z","caller":"traceutil/trace.go:171","msg":"trace[624934111] transaction","detail":"{read_only:false; response_revision:31762; number_of_response:1; }","duration":"199.447615ms","start":"2026-05-19T19:07:30.755821Z","end":"2026-05-19T19:07:30.955268Z","steps":["trace[624934111] 'process raft request'  (duration: 198.965874ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:07:30.955468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.109445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:07:30.955645Z","caller":"traceutil/trace.go:171","msg":"trace[2119531235] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:31762; }","duration":"191.398482ms","start":"2026-05-19T19:07:30.764193Z","end":"2026-05-19T19:07:30.955592Z","steps":["trace[2119531235] 'agreement among raft nodes before linearized reading'  (duration: 191.082235ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:07:31.019713Z","caller":"traceutil/trace.go:171","msg":"trace[1825030766] transaction","detail":"{read_only:false; response_revision:31764; number_of_response:1; }","duration":"161.34339ms","start":"2026-05-19T19:07:30.858339Z","end":"2026-05-19T19:07:31.019683Z","steps":["trace[1825030766] 'process raft request'  (duration: 161.203557ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:07:31.01992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.86561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"}
{"level":"info","ts":"2026-05-19T19:07:31.020024Z","caller":"traceutil/trace.go:171","msg":"trace[1694722844] transaction","detail":"{read_only:false; response_revision:31763; number_of_response:1; }","duration":"210.305971ms","start":"2026-05-19T19:07:30.809693Z","end":"2026-05-19T19:07:31.019999Z","steps":["trace[1694722844] 'process raft request'  (duration: 202.204777ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:07:31.020053Z","caller":"traceutil/trace.go:171","msg":"trace[1703791000] transaction","detail":"{read_only:false; response_revision:31765; number_of_response:1; }","duration":"158.449924ms","start":"2026-05-19T19:07:30.86158Z","end":"2026-05-19T19:07:31.02003Z","steps":["trace[1703791000] 'process raft request'  (duration: 158.043535ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:07:31.020117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.398625ms","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-05-19T19:07:31.02003Z","caller":"traceutil/trace.go:171","msg":"trace[2116120214] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:31765; }","duration":"202.064354ms","start":"2026-05-19T19:07:30.817942Z","end":"2026-05-19T19:07:31.020007Z","steps":["trace[2116120214] 'agreement among raft nodes before linearized reading'  (duration: 201.812989ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:07:31.020162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.382886ms","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-05-19T19:07:31.020189Z","caller":"traceutil/trace.go:171","msg":"trace[180571607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:31765; }","duration":"140.494337ms","start":"2026-05-19T19:07:30.879678Z","end":"2026-05-19T19:07:31.020172Z","steps":["trace[180571607] 'agreement among raft nodes before linearized reading'  (duration: 140.321093ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:07:31.020213Z","caller":"traceutil/trace.go:171","msg":"trace[1107345176] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:31765; }","duration":"200.432877ms","start":"2026-05-19T19:07:30.819765Z","end":"2026-05-19T19:07:31.020198Z","steps":["trace[1107345176] 'agreement among raft nodes before linearized reading'  (duration: 200.306614ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:07:31.020301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.21135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-05-19T19:07:31.020416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.84873ms","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-05-19T19:07:31.020381Z","caller":"traceutil/trace.go:171","msg":"trace[217394523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:31765; }","duration":"151.424725ms","start":"2026-05-19T19:07:30.868936Z","end":"2026-05-19T19:07:31.020361Z","steps":["trace[217394523] 'agreement among raft nodes before linearized reading'  (duration: 151.078658ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:07:31.020481Z","caller":"traceutil/trace.go:171","msg":"trace[1620438189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:31765; }","duration":"116.911872ms","start":"2026-05-19T19:07:30.903553Z","end":"2026-05-19T19:07:31.020465Z","steps":["trace[1620438189] 'agreement among raft nodes before linearized reading'  (duration: 116.774869ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:07:31.020434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.877612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"}
{"level":"info","ts":"2026-05-19T19:07:31.020546Z","caller":"traceutil/trace.go:171","msg":"trace[150605992] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:31765; }","duration":"146.997225ms","start":"2026-05-19T19:07:30.873539Z","end":"2026-05-19T19:07:31.020537Z","steps":["trace[150605992] 'agreement among raft nodes before linearized reading'  (duration: 146.8151ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:10:27.899461Z","caller":"traceutil/trace.go:171","msg":"trace[898363081] transaction","detail":"{read_only:false; response_revision:33152; number_of_response:1; }","duration":"135.973055ms","start":"2026-05-19T19:10:27.763457Z","end":"2026-05-19T19:10:27.89943Z","steps":["trace[898363081] 'process raft request'  (duration: 85.325995ms)","trace[898363081] 'compare'  (duration: 50.500977ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:10:28.195724Z","caller":"traceutil/trace.go:171","msg":"trace[2102670597] linearizableReadLoop","detail":"{readStateIndex:34282; appliedIndex:34281; }","duration":"153.306847ms","start":"2026-05-19T19:10:28.042392Z","end":"2026-05-19T19:10:28.195698Z","steps":["trace[2102670597] 'read index received'  (duration: 153.120653ms)","trace[2102670597] 'applied index is now lower than readState.Index'  (duration: 185.044µs)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:10:28.195808Z","caller":"traceutil/trace.go:171","msg":"trace[957930504] transaction","detail":"{read_only:false; response_revision:33156; number_of_response:1; }","duration":"171.643004ms","start":"2026-05-19T19:10:28.024133Z","end":"2026-05-19T19:10:28.195776Z","steps":["trace[957930504] 'process raft request'  (duration: 171.351677ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:10:28.195944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.534322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"}
{"level":"info","ts":"2026-05-19T19:10:28.196Z","caller":"traceutil/trace.go:171","msg":"trace[252651696] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:33156; }","duration":"153.636114ms","start":"2026-05-19T19:10:28.042347Z","end":"2026-05-19T19:10:28.195983Z","steps":["trace[252651696] 'agreement among raft nodes before linearized reading'  (duration: 153.477301ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:10:28.200653Z","caller":"traceutil/trace.go:171","msg":"trace[1983812091] transaction","detail":"{read_only:false; response_revision:33157; number_of_response:1; }","duration":"102.988627ms","start":"2026-05-19T19:10:28.097646Z","end":"2026-05-19T19:10:28.200635Z","steps":["trace[1983812091] 'process raft request'  (duration: 102.818003ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:10:28.20071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.236319ms","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-05-19T19:10:28.200757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.592284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"info","ts":"2026-05-19T19:10:28.200758Z","caller":"traceutil/trace.go:171","msg":"trace[156157972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:33157; }","duration":"158.315241ms","start":"2026-05-19T19:10:28.04243Z","end":"2026-05-19T19:10:28.200745Z","steps":["trace[156157972] 'agreement among raft nodes before linearized reading'  (duration: 158.155557ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:10:28.200806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.956069ms","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-05-19T19:10:28.200811Z","caller":"traceutil/trace.go:171","msg":"trace[538251311] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:33157; }","duration":"127.678456ms","start":"2026-05-19T19:10:28.073118Z","end":"2026-05-19T19:10:28.200797Z","steps":["trace[538251311] 'agreement among raft nodes before linearized reading'  (duration: 127.491582ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:10:28.200827Z","caller":"traceutil/trace.go:171","msg":"trace[1165152152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:33157; }","duration":"144.999399ms","start":"2026-05-19T19:10:28.055822Z","end":"2026-05-19T19:10:28.200821Z","steps":["trace[1165152152] 'agreement among raft nodes before linearized reading'  (duration: 144.927768ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:10:28.200883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.826426ms","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-05-19T19:10:28.200963Z","caller":"traceutil/trace.go:171","msg":"trace[1434874473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:33157; }","duration":"144.931798ms","start":"2026-05-19T19:10:28.056011Z","end":"2026-05-19T19:10:28.200942Z","steps":["trace[1434874473] 'agreement among raft nodes before linearized reading'  (duration: 144.660472ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:10:29.884684Z","caller":"traceutil/trace.go:171","msg":"trace[1993223139] transaction","detail":"{read_only:false; response_revision:33170; number_of_response:1; }","duration":"105.458322ms","start":"2026-05-19T19:10:29.779193Z","end":"2026-05-19T19:10:29.884652Z","steps":["trace[1993223139] 'process raft request'  (duration: 104.92014ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:10:30.01359Z","caller":"traceutil/trace.go:171","msg":"trace[1750203387] linearizableReadLoop","detail":"{readStateIndex:34299; appliedIndex:34297; }","duration":"101.020062ms","start":"2026-05-19T19:10:29.912542Z","end":"2026-05-19T19:10:30.013562Z","steps":["trace[1750203387] 'read index received'  (duration: 5.050005ms)","trace[1750203387] 'applied index is now lower than readState.Index'  (duration: 95.968787ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:10:30.013815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.255907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"}
{"level":"info","ts":"2026-05-19T19:10:30.01386Z","caller":"traceutil/trace.go:171","msg":"trace[292929045] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:33172; }","duration":"101.355269ms","start":"2026-05-19T19:10:29.912489Z","end":"2026-05-19T19:10:30.013845Z","steps":["trace[292929045] 'agreement among raft nodes before linearized reading'  (duration: 101.173175ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:10:30.01407Z","caller":"traceutil/trace.go:171","msg":"trace[992497880] transaction","detail":"{read_only:false; response_revision:33172; number_of_response:1; }","duration":"106.832983ms","start":"2026-05-19T19:10:29.907208Z","end":"2026-05-19T19:10:30.014041Z","steps":["trace[992497880] 'process raft request'  (duration: 93.41096ms)","trace[992497880] 'compare'  (duration: 12.781149ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:11:12.496974Z","caller":"traceutil/trace.go:171","msg":"trace[1328101855] transaction","detail":"{read_only:false; response_revision:33480; number_of_response:1; }","duration":"113.252309ms","start":"2026-05-19T19:11:12.383686Z","end":"2026-05-19T19:11:12.496938Z","steps":["trace[1328101855] 'process raft request'  (duration: 113.082845ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:11:14.059185Z","caller":"traceutil/trace.go:171","msg":"trace[2002660370] compact","detail":"{revision:30956; response_revision:33489; }","duration":"121.964357ms","start":"2026-05-19T19:11:13.937193Z","end":"2026-05-19T19:11:14.059157Z","steps":["trace[2002660370] 'process raft request'  (duration: 51.664132ms)","trace[2002660370] 'check and update compact revision'  (duration: 70.119761ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:11:14.059591Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30956}
{"level":"warn","ts":"2026-05-19T19:11:14.709601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.951344ms","expected-duration":"100ms","prefix":"","request":"header:<ID:6432440171452007811 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:33484 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-19T19:11:14.709994Z","caller":"traceutil/trace.go:171","msg":"trace[106907729] transaction","detail":"{read_only:false; response_revision:33496; number_of_response:1; }","duration":"151.938246ms","start":"2026-05-19T19:11:14.557806Z","end":"2026-05-19T19:11:14.709744Z","steps":["trace[106907729] 'process raft request'  (duration: 19.747368ms)","trace[106907729] 'compare'  (duration: 45.961403ms)","trace[106907729] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/cilium-operator-resource-lock; req_size:473; } (duration: 85.786765ms)"],"step_count":3}
{"level":"info","ts":"2026-05-19T19:11:14.79466Z","caller":"traceutil/trace.go:171","msg":"trace[1109429359] transaction","detail":"{read_only:false; response_revision:33498; number_of_response:1; }","duration":"236.574878ms","start":"2026-05-19T19:11:14.558066Z","end":"2026-05-19T19:11:14.794641Z","steps":["trace[1109429359] 'process raft request'  (duration: 236.487396ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:11:14.794738Z","caller":"traceutil/trace.go:171","msg":"trace[1133464537] transaction","detail":"{read_only:false; response_revision:33497; number_of_response:1; }","duration":"236.67624ms","start":"2026-05-19T19:11:14.558011Z","end":"2026-05-19T19:11:14.794688Z","steps":["trace[1133464537] 'process raft request'  (duration: 151.718682ms)","trace[1133464537] 'compare'  (duration: 10.875527ms)","trace[1133464537] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:461; } (duration: 73.761464ms)"],"step_count":3}
{"level":"info","ts":"2026-05-19T19:11:14.794985Z","caller":"traceutil/trace.go:171","msg":"trace[602595883] transaction","detail":"{read_only:false; response_revision:33499; number_of_response:1; }","duration":"236.842034ms","start":"2026-05-19T19:11:14.558099Z","end":"2026-05-19T19:11:14.794941Z","steps":["trace[602595883] 'process raft request'  (duration: 236.512076ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:11:14.795011Z","caller":"traceutil/trace.go:171","msg":"trace[945054850] transaction","detail":"{read_only:false; response_revision:33500; number_of_response:1; }","duration":"231.761408ms","start":"2026-05-19T19:11:14.563212Z","end":"2026-05-19T19:11:14.794973Z","steps":["trace[945054850] 'process raft request'  (duration: 231.603095ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:11:14.795193Z","caller":"traceutil/trace.go:171","msg":"trace[125411590] linearizableReadLoop","detail":"{readStateIndex:34636; appliedIndex:34632; }","duration":"234.297266ms","start":"2026-05-19T19:11:14.560849Z","end":"2026-05-19T19:11:14.795146Z","steps":["trace[125411590] 'read index received'  (duration: 16.72885ms)","trace[125411590] 'applied index is now lower than readState.Index'  (duration: 217.564706ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:11:14.79541Z","caller":"traceutil/trace.go:171","msg":"trace[922012799] transaction","detail":"{read_only:false; response_revision:33501; number_of_response:1; }","duration":"160.497101ms","start":"2026-05-19T19:11:14.634902Z","end":"2026-05-19T19:11:14.795399Z","steps":["trace[922012799] 'process raft request'  (duration: 160.122923ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:11:14.795567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.689655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"}
{"level":"info","ts":"2026-05-19T19:11:14.795621Z","caller":"traceutil/trace.go:171","msg":"trace[2132230135] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:33501; }","duration":"234.781307ms","start":"2026-05-19T19:11:14.560824Z","end":"2026-05-19T19:11:14.795605Z","steps":["trace[2132230135] 'agreement among raft nodes before linearized reading'  (duration: 234.515261ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:11:14.795673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.067269ms","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-05-19T19:11:14.795722Z","caller":"traceutil/trace.go:171","msg":"trace[212065156] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:33502; }","duration":"103.159391ms","start":"2026-05-19T19:11:14.692551Z","end":"2026-05-19T19:11:14.795711Z","steps":["trace[212065156] 'agreement among raft nodes before linearized reading'  (duration: 102.976707ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:11:14.86896Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30956,"took":"805.019333ms","hash":3128906858,"current-db-size-bytes":59461632,"current-db-size":"60 MB","current-db-size-in-use-bytes":35221504,"current-db-size-in-use":"35 MB"}
{"level":"info","ts":"2026-05-19T19:11:14.869034Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3128906858,"revision":30956,"compact-revision":24686}
{"level":"info","ts":"2026-05-19T19:12:18.683133Z","caller":"traceutil/trace.go:171","msg":"trace[489830426] transaction","detail":"{read_only:false; response_revision:33962; number_of_response:1; }","duration":"124.27156ms","start":"2026-05-19T19:12:18.558835Z","end":"2026-05-19T19:12:18.683107Z","steps":["trace[489830426] 'process raft request'  (duration: 124.120667ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:13:46.918023Z","caller":"traceutil/trace.go:171","msg":"trace[2046449256] transaction","detail":"{read_only:false; response_revision:34605; number_of_response:1; }","duration":"131.994734ms","start":"2026-05-19T19:13:46.786008Z","end":"2026-05-19T19:13:46.918003Z","steps":["trace[2046449256] 'process raft request'  (duration: 124.687338ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:13:47.966163Z","caller":"traceutil/trace.go:171","msg":"trace[317977290] transaction","detail":"{read_only:false; response_revision:34615; number_of_response:1; }","duration":"135.715018ms","start":"2026-05-19T19:13:47.830426Z","end":"2026-05-19T19:13:47.966141Z","steps":["trace[317977290] 'process raft request'  (duration: 135.565905ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:13:47.96638Z","caller":"traceutil/trace.go:171","msg":"trace[38925375] transaction","detail":"{read_only:false; response_revision:34616; number_of_response:1; }","duration":"134.814138ms","start":"2026-05-19T19:13:47.831551Z","end":"2026-05-19T19:13:47.966365Z","steps":["trace[38925375] 'process raft request'  (duration: 134.551032ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:13:48.416169Z","caller":"traceutil/trace.go:171","msg":"trace[810979924] transaction","detail":"{read_only:false; response_revision:34618; number_of_response:1; }","duration":"100.06279ms","start":"2026-05-19T19:13:48.316079Z","end":"2026-05-19T19:13:48.416141Z","steps":["trace[810979924] 'process raft request'  (duration: 48.728685ms)","trace[810979924] 'compare'  (duration: 51.216352ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:13:49.060169Z","caller":"traceutil/trace.go:171","msg":"trace[671067015] transaction","detail":"{read_only:false; response_revision:34621; number_of_response:1; }","duration":"133.827386ms","start":"2026-05-19T19:13:48.926313Z","end":"2026-05-19T19:13:49.06014Z","steps":["trace[671067015] 'process raft request'  (duration: 133.679112ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:13:49.330905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.047678ms","expected-duration":"100ms","prefix":"","request":"header:<ID:6432440171452010880 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:34607 > success:<request_put:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-19T19:13:49.33123Z","caller":"traceutil/trace.go:171","msg":"trace[742329720] transaction","detail":"{read_only:false; response_revision:34622; number_of_response:1; }","duration":"235.216116ms","start":"2026-05-19T19:13:49.095986Z","end":"2026-05-19T19:13:49.331202Z","steps":["trace[742329720] 'process raft request'  (duration: 105.740939ms)","trace[742329720] 'compare'  (duration: 128.862404ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:13:49.3313Z","caller":"traceutil/trace.go:171","msg":"trace[1413256616] transaction","detail":"{read_only:false; response_revision:34624; number_of_response:1; }","duration":"145.500851ms","start":"2026-05-19T19:13:49.185778Z","end":"2026-05-19T19:13:49.331279Z","steps":["trace[1413256616] 'process raft request'  (duration: 145.43389ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:13:49.331293Z","caller":"traceutil/trace.go:171","msg":"trace[13385922] linearizableReadLoop","detail":"{readStateIndex:35792; appliedIndex:35791; }","duration":"225.333782ms","start":"2026-05-19T19:13:49.105901Z","end":"2026-05-19T19:13:49.331235Z","steps":["trace[13385922] 'read index received'  (duration: 95.820194ms)","trace[13385922] 'applied index is now lower than readState.Index'  (duration: 129.507368ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:13:49.33148Z","caller":"traceutil/trace.go:171","msg":"trace[1875692358] transaction","detail":"{read_only:false; response_revision:34623; number_of_response:1; }","duration":"212.549392ms","start":"2026-05-19T19:13:49.118745Z","end":"2026-05-19T19:13:49.331294Z","steps":["trace[1875692358] 'process raft request'  (duration: 212.337298ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:13:49.331531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.209946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:13:49.331637Z","caller":"traceutil/trace.go:171","msg":"trace[1390190867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:34624; }","duration":"187.493284ms","start":"2026-05-19T19:13:49.144122Z","end":"2026-05-19T19:13:49.331616Z","steps":["trace[1390190867] 'agreement among raft nodes before linearized reading'  (duration: 187.34083ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:13:49.331556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.17544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:13:49.33184Z","caller":"traceutil/trace.go:171","msg":"trace[1461405627] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:34624; }","duration":"207.528158ms","start":"2026-05-19T19:13:49.124288Z","end":"2026-05-19T19:13:49.331816Z","steps":["trace[1461405627] 'agreement among raft nodes before linearized reading'  (duration: 207.16792ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:13:49.331563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.655689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/openstack/identity\" ","response":"range_response_count:1 size:854"}
{"level":"info","ts":"2026-05-19T19:13:49.332042Z","caller":"traceutil/trace.go:171","msg":"trace[30834736] range","detail":"{range_begin:/registry/ingress/openstack/identity; range_end:; response_count:1; response_revision:34624; }","duration":"226.172022ms","start":"2026-05-19T19:13:49.105857Z","end":"2026-05-19T19:13:49.332029Z","steps":["trace[30834736] 'agreement among raft nodes before linearized reading'  (duration: 225.507626ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:13:50.148011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.621824ms","expected-duration":"100ms","prefix":"","request":"header:<ID:6432440171452010890 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:34612 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-19T19:13:50.148165Z","caller":"traceutil/trace.go:171","msg":"trace[2053270905] linearizableReadLoop","detail":"{readStateIndex:35796; appliedIndex:35795; }","duration":"317.219017ms","start":"2026-05-19T19:13:49.830898Z","end":"2026-05-19T19:13:50.148117Z","steps":["trace[2053270905] 'read index received'  (duration: 108.332858ms)","trace[2053270905] 'applied index is now lower than readState.Index'  (duration: 208.884349ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:13:50.148166Z","caller":"traceutil/trace.go:171","msg":"trace[662598907] transaction","detail":"{read_only:false; response_revision:34626; number_of_response:1; }","duration":"317.842101ms","start":"2026-05-19T19:13:49.830294Z","end":"2026-05-19T19:13:50.148136Z","steps":["trace[662598907] 'process raft request'  (duration: 108.981923ms)","trace[662598907] 'compare'  (duration: 208.519591ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:13:50.148293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:13:49.830263Z","time spent":"317.966345ms","remote":"127.0.0.1:36832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:34612 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-05-19T19:13:50.14837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.471322ms","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-05-19T19:13:50.148437Z","caller":"traceutil/trace.go:171","msg":"trace[413698816] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:34626; }","duration":"317.559995ms","start":"2026-05-19T19:13:49.830853Z","end":"2026-05-19T19:13:50.148413Z","steps":["trace[413698816] 'agreement among raft nodes before linearized reading'  (duration: 317.381841ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:13:50.148496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:13:49.830836Z","time spent":"317.646396ms","remote":"127.0.0.1:36832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"info","ts":"2026-05-19T19:13:50.201094Z","caller":"traceutil/trace.go:171","msg":"trace[1231219428] transaction","detail":"{read_only:false; response_revision:34627; number_of_response:1; }","duration":"117.954945ms","start":"2026-05-19T19:13:50.083114Z","end":"2026-05-19T19:13:50.201069Z","steps":["trace[1231219428] 'process raft request'  (duration: 117.739151ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:13:50.201101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.398288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:13:50.20134Z","caller":"traceutil/trace.go:171","msg":"trace[906743682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:34627; }","duration":"331.680995ms","start":"2026-05-19T19:13:49.86964Z","end":"2026-05-19T19:13:50.201321Z","steps":["trace[906743682] 'agreement among raft nodes before linearized reading'  (duration: 331.375158ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:13:50.201406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.051873ms","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-05-19T19:13:50.20143Z","caller":"traceutil/trace.go:171","msg":"trace[1387314346] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:34627; }","duration":"291.117504ms","start":"2026-05-19T19:13:49.910306Z","end":"2026-05-19T19:13:50.201423Z","steps":["trace[1387314346] 'agreement among raft nodes before linearized reading'  (duration: 291.049023ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:13:50.201336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.082999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"}
{"level":"warn","ts":"2026-05-19T19:13:50.201424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.157473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"}
{"level":"warn","ts":"2026-05-19T19:13:50.20147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:13:49.869619Z","time spent":"331.799408ms","remote":"127.0.0.1:36560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-05-19T19:13:50.201547Z","caller":"traceutil/trace.go:171","msg":"trace[933329831] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:34627; }","duration":"125.323433ms","start":"2026-05-19T19:13:50.076184Z","end":"2026-05-19T19:13:50.201507Z","steps":["trace[933329831] 'agreement among raft nodes before linearized reading'  (duration: 124.963145ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:13:50.20157Z","caller":"traceutil/trace.go:171","msg":"trace[1486481810] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:34627; }","duration":"290.383168ms","start":"2026-05-19T19:13:49.911159Z","end":"2026-05-19T19:13:50.201542Z","steps":["trace[1486481810] 'agreement among raft nodes before linearized reading'  (duration: 289.936308ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:13:50.201525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.893968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"}
{"level":"info","ts":"2026-05-19T19:13:50.201699Z","caller":"traceutil/trace.go:171","msg":"trace[728348691] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:34627; }","duration":"231.079663ms","start":"2026-05-19T19:13:49.970588Z","end":"2026-05-19T19:13:50.201668Z","steps":["trace[728348691] 'agreement among raft nodes before linearized reading'  (duration: 230.898169ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:13:50.201443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.854057ms","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-05-19T19:13:50.202032Z","caller":"traceutil/trace.go:171","msg":"trace[340221299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:34627; }","duration":"231.536363ms","start":"2026-05-19T19:13:49.970482Z","end":"2026-05-19T19:13:50.202019Z","steps":["trace[340221299] 'agreement among raft nodes before linearized reading'  (duration: 230.759385ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:13:50.298379Z","caller":"traceutil/trace.go:171","msg":"trace[998712932] transaction","detail":"{read_only:false; response_revision:34628; number_of_response:1; }","duration":"144.818145ms","start":"2026-05-19T19:13:50.153531Z","end":"2026-05-19T19:13:50.298349Z","steps":["trace[998712932] 'process raft request'  (duration: 129.280433ms)","trace[998712932] 'compare'  (duration: 15.211014ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:13:50.353286Z","caller":"traceutil/trace.go:171","msg":"trace[375119483] transaction","detail":"{read_only:false; response_revision:34630; number_of_response:1; }","duration":"145.988842ms","start":"2026-05-19T19:13:50.207268Z","end":"2026-05-19T19:13:50.353257Z","steps":["trace[375119483] 'process raft request'  (duration: 145.843249ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:13:50.353273Z","caller":"traceutil/trace.go:171","msg":"trace[627632406] transaction","detail":"{read_only:false; response_revision:34629; number_of_response:1; }","duration":"146.738319ms","start":"2026-05-19T19:13:50.206507Z","end":"2026-05-19T19:13:50.353245Z","steps":["trace[627632406] 'process raft request'  (duration: 146.452272ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:13:50.353575Z","caller":"traceutil/trace.go:171","msg":"trace[862735258] transaction","detail":"{read_only:false; response_revision:34631; number_of_response:1; }","duration":"145.941951ms","start":"2026-05-19T19:13:50.207611Z","end":"2026-05-19T19:13:50.353553Z","steps":["trace[862735258] 'process raft request'  (duration: 145.597343ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:13:50.353635Z","caller":"traceutil/trace.go:171","msg":"trace[1314372369] transaction","detail":"{read_only:false; response_revision:34632; number_of_response:1; }","duration":"145.929261ms","start":"2026-05-19T19:13:50.207689Z","end":"2026-05-19T19:13:50.353618Z","steps":["trace[1314372369] 'process raft request'  (duration: 145.736157ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:13:50.530668Z","caller":"traceutil/trace.go:171","msg":"trace[1342053684] transaction","detail":"{read_only:false; response_revision:34633; number_of_response:1; }","duration":"172.598605ms","start":"2026-05-19T19:13:50.358022Z","end":"2026-05-19T19:13:50.53062Z","steps":["trace[1342053684] 'process raft request'  (duration: 73.243612ms)","trace[1342053684] 'compare'  (duration: 99.007786ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:14:30.499709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.284603ms","expected-duration":"100ms","prefix":"","request":"header:<ID:6432440171452011624 > lease_revoke:<id:59449e41625867e3>","response":"size:29"}
{"level":"warn","ts":"2026-05-19T19:14:37.742455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.983181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-05-19T19:14:37.742559Z","caller":"traceutil/trace.go:171","msg":"trace[86892446] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:34973; }","duration":"209.114925ms","start":"2026-05-19T19:14:37.533402Z","end":"2026-05-19T19:14:37.742517Z","steps":["trace[86892446] 'count revisions from in-memory index tree'  (duration: 208.904339ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:14:37.742779Z","caller":"traceutil/trace.go:171","msg":"trace[1764148056] transaction","detail":"{read_only:false; response_revision:34974; number_of_response:1; }","duration":"165.225968ms","start":"2026-05-19T19:14:37.577524Z","end":"2026-05-19T19:14:37.74275Z","steps":["trace[1764148056] 'process raft request'  (duration: 151.20858ms)","trace[1764148056] 'compare'  (duration: 13.118307ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:14:37.958708Z","caller":"traceutil/trace.go:171","msg":"trace[370605612] linearizableReadLoop","detail":"{readStateIndex:36156; appliedIndex:36153; }","duration":"325.213508ms","start":"2026-05-19T19:14:37.633472Z","end":"2026-05-19T19:14:37.958686Z","steps":["trace[370605612] 'read index received'  (duration: 95.272182ms)","trace[370605612] 'applied index is now lower than readState.Index'  (duration: 229.940226ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:14:37.958752Z","caller":"traceutil/trace.go:171","msg":"trace[1940746595] transaction","detail":"{read_only:false; response_revision:34975; number_of_response:1; }","duration":"328.922193ms","start":"2026-05-19T19:14:37.629771Z","end":"2026-05-19T19:14:37.958694Z","steps":["trace[1940746595] 'process raft request'  (duration: 327.884348ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:14:37.958874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.381382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-05-19T19:14:37.9589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:14:37.629741Z","time spent":"329.081967ms","remote":"127.0.0.1:36832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:34962 > success:<request_put:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" value_size:441 >> failure:<request_range:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" > >"}
{"level":"info","ts":"2026-05-19T19:14:37.958948Z","caller":"traceutil/trace.go:171","msg":"trace[888484884] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:34976; }","duration":"325.488375ms","start":"2026-05-19T19:14:37.633439Z","end":"2026-05-19T19:14:37.958927Z","steps":["trace[888484884] 'agreement among raft nodes before linearized reading'  (duration: 325.376302ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:14:37.959001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:14:37.633427Z","time spent":"325.563166ms","remote":"127.0.0.1:56736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true "}
{"level":"info","ts":"2026-05-19T19:14:37.959156Z","caller":"traceutil/trace.go:171","msg":"trace[1555926425] transaction","detail":"{read_only:false; response_revision:34976; number_of_response:1; }","duration":"329.076107ms","start":"2026-05-19T19:14:37.630068Z","end":"2026-05-19T19:14:37.959144Z","steps":["trace[1555926425] 'process raft request'  (duration: 328.525314ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:14:37.959222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:14:37.630034Z","time spent":"329.154889ms","remote":"127.0.0.1:36832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:34963 > success:<request_put:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" > >"}
{"level":"info","ts":"2026-05-19T19:14:38.70092Z","caller":"traceutil/trace.go:171","msg":"trace[320441133] transaction","detail":"{read_only:false; response_revision:34980; number_of_response:1; }","duration":"107.433828ms","start":"2026-05-19T19:14:38.593442Z","end":"2026-05-19T19:14:38.700876Z","steps":["trace[320441133] 'process raft request'  (duration: 107.143792ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:14:38.965388Z","caller":"traceutil/trace.go:171","msg":"trace[327843867] transaction","detail":"{read_only:false; response_revision:34982; number_of_response:1; }","duration":"202.998725ms","start":"2026-05-19T19:14:38.762353Z","end":"2026-05-19T19:14:38.965351Z","steps":["trace[327843867] 'process raft request'  (duration: 202.821361ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:14:39.188475Z","caller":"traceutil/trace.go:171","msg":"trace[1277797678] transaction","detail":"{read_only:false; response_revision:34984; number_of_response:1; }","duration":"100.676194ms","start":"2026-05-19T19:14:39.087774Z","end":"2026-05-19T19:14:39.18845Z","steps":["trace[1277797678] 'process raft request'  (duration: 100.625483ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:14:39.188698Z","caller":"traceutil/trace.go:171","msg":"trace[589393017] linearizableReadLoop","detail":"{readStateIndex:36164; appliedIndex:36162; }","duration":"335.809089ms","start":"2026-05-19T19:14:38.852866Z","end":"2026-05-19T19:14:39.188675Z","steps":["trace[589393017] 'read index received'  (duration: 112.421051ms)","trace[589393017] 'applied index is now lower than readState.Index'  (duration: 223.386468ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:14:39.188704Z","caller":"traceutil/trace.go:171","msg":"trace[1047636984] transaction","detail":"{read_only:false; response_revision:34983; number_of_response:1; }","duration":"425.183467ms","start":"2026-05-19T19:14:38.763478Z","end":"2026-05-19T19:14:39.188662Z","steps":["trace[1047636984] 'process raft request'  (duration: 337.164669ms)","trace[1047636984] 'compare'  (duration: 87.558987ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:14:39.188838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.765174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:14:39.188867Z","caller":"traceutil/trace.go:171","msg":"trace[845335228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:34984; }","duration":"319.845556ms","start":"2026-05-19T19:14:38.869014Z","end":"2026-05-19T19:14:39.188859Z","steps":["trace[845335228] 'agreement among raft nodes before linearized reading'  (duration: 319.781315ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:14:39.188892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:14:38.868995Z","time spent":"319.888867ms","remote":"127.0.0.1:36552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-05-19T19:14:39.188909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:14:38.763457Z","time spent":"425.35626ms","remote":"127.0.0.1:36832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:34968 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-05-19T19:14:39.188922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.048004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"}
{"level":"warn","ts":"2026-05-19T19:14:39.188974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.777609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:14:39.189001Z","caller":"traceutil/trace.go:171","msg":"trace[2129266166] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:34984; }","duration":"336.176767ms","start":"2026-05-19T19:14:38.852803Z","end":"2026-05-19T19:14:39.18898Z","steps":["trace[2129266166] 'agreement among raft nodes before linearized reading'  (duration: 335.956512ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:14:39.189026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.744721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"}
{"level":"warn","ts":"2026-05-19T19:14:39.189051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:14:38.85278Z","time spent":"336.261989ms","remote":"127.0.0.1:36832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"info","ts":"2026-05-19T19:14:39.189059Z","caller":"traceutil/trace.go:171","msg":"trace[1601326648] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:34984; }","duration":"101.912692ms","start":"2026-05-19T19:14:39.087122Z","end":"2026-05-19T19:14:39.189035Z","steps":["trace[1601326648] 'agreement among raft nodes before linearized reading'  (duration: 101.721878ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:14:39.189086Z","caller":"traceutil/trace.go:171","msg":"trace[312214297] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:34984; }","duration":"184.841784ms","start":"2026-05-19T19:14:39.004226Z","end":"2026-05-19T19:14:39.189067Z","steps":["trace[312214297] 'agreement among raft nodes before linearized reading'  (duration: 184.628779ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:14:40.936046Z","caller":"traceutil/trace.go:171","msg":"trace[1433694471] transaction","detail":"{read_only:false; response_revision:34996; number_of_response:1; }","duration":"169.444674ms","start":"2026-05-19T19:14:40.766562Z","end":"2026-05-19T19:14:40.936007Z","steps":["trace[1433694471] 'process raft request'  (duration: 108.648075ms)","trace[1433694471] 'compare'  (duration: 60.352089ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:14:46.181395Z","caller":"traceutil/trace.go:171","msg":"trace[1340715990] transaction","detail":"{read_only:false; response_revision:35035; number_of_response:1; }","duration":"119.666845ms","start":"2026-05-19T19:14:46.061704Z","end":"2026-05-19T19:14:46.181371Z","steps":["trace[1340715990] 'process raft request'  (duration: 119.538752ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:15:10.510769Z","caller":"traceutil/trace.go:171","msg":"trace[1584574813] transaction","detail":"{read_only:false; response_revision:35258; number_of_response:1; }","duration":"125.872136ms","start":"2026-05-19T19:15:10.384871Z","end":"2026-05-19T19:15:10.510744Z","steps":["trace[1584574813] 'process raft request'  (duration: 125.63224ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:15:10.515205Z","caller":"traceutil/trace.go:171","msg":"trace[1860536237] transaction","detail":"{read_only:false; response_revision:35259; number_of_response:1; }","duration":"128.827182ms","start":"2026-05-19T19:15:10.386339Z","end":"2026-05-19T19:15:10.515166Z","steps":["trace[1860536237] 'process raft request'  (duration: 128.494195ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:15:10.515532Z","caller":"traceutil/trace.go:171","msg":"trace[1013156879] linearizableReadLoop","detail":"{readStateIndex:36447; appliedIndex:36446; }","duration":"126.49532ms","start":"2026-05-19T19:15:10.389012Z","end":"2026-05-19T19:15:10.515507Z","steps":["trace[1013156879] 'read index received'  (duration: 121.936957ms)","trace[1013156879] 'applied index is now lower than readState.Index'  (duration: 4.557333ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:15:10.5158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.765927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:1 size:5129"}
{"level":"info","ts":"2026-05-19T19:15:10.515869Z","caller":"traceutil/trace.go:171","msg":"trace[861918008] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:1; response_revision:35261; }","duration":"126.869159ms","start":"2026-05-19T19:15:10.388984Z","end":"2026-05-19T19:15:10.515853Z","steps":["trace[861918008] 'agreement among raft nodes before linearized reading'  (duration: 126.641903ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:15:10.629539Z","caller":"traceutil/trace.go:171","msg":"trace[294037054] linearizableReadLoop","detail":"{readStateIndex:36450; appliedIndex:36449; }","duration":"113.905125ms","start":"2026-05-19T19:15:10.515612Z","end":"2026-05-19T19:15:10.629517Z","steps":["trace[294037054] 'read index received'  (duration: 110.17442ms)","trace[294037054] 'applied index is now lower than readState.Index'  (duration: 3.729705ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:15:10.629677Z","caller":"traceutil/trace.go:171","msg":"trace[2024862648] transaction","detail":"{read_only:false; number_of_response:1; response_revision:35262; }","duration":"117.24396ms","start":"2026-05-19T19:15:10.51241Z","end":"2026-05-19T19:15:10.629654Z","steps":["trace[2024862648] 'process raft request'  (duration: 113.440254ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:15:10.629993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.64782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:895"}
{"level":"warn","ts":"2026-05-19T19:15:10.630041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.25794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:1 size:21561"}
{"level":"info","ts":"2026-05-19T19:15:10.630098Z","caller":"traceutil/trace.go:171","msg":"trace[1064864398] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:1; response_revision:35262; }","duration":"240.344332ms","start":"2026-05-19T19:15:10.389742Z","end":"2026-05-19T19:15:10.630086Z","steps":["trace[1064864398] 'agreement among raft nodes before linearized reading'  (duration: 240.120887ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:15:10.630075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.438613ms","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-05-19T19:15:10.630112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.298871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:1 size:11169"}
{"level":"info","ts":"2026-05-19T19:15:10.630032Z","caller":"traceutil/trace.go:171","msg":"trace[1317503949] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:35262; }","duration":"240.749482ms","start":"2026-05-19T19:15:10.389273Z","end":"2026-05-19T19:15:10.630022Z","steps":["trace[1317503949] 'agreement among raft nodes before linearized reading'  (duration: 240.322163ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:15:10.630172Z","caller":"traceutil/trace.go:171","msg":"trace[459986315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:35262; }","duration":"187.589986ms","start":"2026-05-19T19:15:10.442559Z","end":"2026-05-19T19:15:10.630149Z","steps":["trace[459986315] 'agreement among raft nodes before linearized reading'  (duration: 187.133836ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:15:10.630154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.492806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:675"}
{"level":"info","ts":"2026-05-19T19:15:10.630221Z","caller":"traceutil/trace.go:171","msg":"trace[1149709325] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:1; response_revision:35262; }","duration":"240.425164ms","start":"2026-05-19T19:15:10.389764Z","end":"2026-05-19T19:15:10.63019Z","steps":["trace[1149709325] 'agreement among raft nodes before linearized reading'  (duration: 240.021535ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:15:10.63026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.410351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:1 size:7564"}
{"level":"info","ts":"2026-05-19T19:15:10.630295Z","caller":"traceutil/trace.go:171","msg":"trace[1348013266] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:1; response_revision:35262; }","duration":"239.458992ms","start":"2026-05-19T19:15:10.390828Z","end":"2026-05-19T19:15:10.630287Z","steps":["trace[1348013266] 'agreement among raft nodes before linearized reading'  (duration: 238.747926ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:15:10.630269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.23717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:1149"}
{"level":"info","ts":"2026-05-19T19:15:10.630302Z","caller":"traceutil/trace.go:171","msg":"trace[194470997] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:35262; }","duration":"240.64683ms","start":"2026-05-19T19:15:10.389599Z","end":"2026-05-19T19:15:10.630245Z","steps":["trace[194470997] 'agreement among raft nodes before linearized reading'  (duration: 240.270602ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:15:10.63036Z","caller":"traceutil/trace.go:171","msg":"trace[1056110681] range","detail":"{range_begin:/registry/services/endpoints/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:35262; }","duration":"240.383953ms","start":"2026-05-19T19:15:10.389952Z","end":"2026-05-19T19:15:10.630336Z","steps":["trace[1056110681] 'agreement among raft nodes before linearized reading'  (duration: 239.862122ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:16:14.066383Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33489}
{"level":"info","ts":"2026-05-19T19:16:14.233016Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33489,"took":"160.84291ms","hash":458947972,"current-db-size-bytes":59461632,"current-db-size":"60 MB","current-db-size-in-use-bytes":33222656,"current-db-size-in-use":"33 MB"}
{"level":"info","ts":"2026-05-19T19:16:14.233097Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":458947972,"revision":33489,"compact-revision":30956}
{"level":"warn","ts":"2026-05-19T19:17:13.480542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.203264ms","expected-duration":"100ms","prefix":"","request":"header:<ID:6432440171452014798 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:36173 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-19T19:17:13.481172Z","caller":"traceutil/trace.go:171","msg":"trace[878753063] linearizableReadLoop","detail":"{readStateIndex:37402; appliedIndex:37400; }","duration":"138.30402ms","start":"2026-05-19T19:17:13.342852Z","end":"2026-05-19T19:17:13.481156Z","steps":["trace[878753063] 'read index received'  (duration: 19.413031ms)","trace[878753063] 'applied index is now lower than readState.Index'  (duration: 118.889799ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:17:13.481386Z","caller":"traceutil/trace.go:171","msg":"trace[1691024816] transaction","detail":"{read_only:false; response_revision:36187; number_of_response:1; }","duration":"174.632715ms","start":"2026-05-19T19:17:13.306736Z","end":"2026-05-19T19:17:13.481369Z","steps":["trace[1691024816] 'process raft request'  (duration: 55.538021ms)","trace[1691024816] 'compare'  (duration: 118.098281ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:17:13.481368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.502314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:17:13.481865Z","caller":"traceutil/trace.go:171","msg":"trace[102785003] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:36188; }","duration":"139.032957ms","start":"2026-05-19T19:17:13.342817Z","end":"2026-05-19T19:17:13.48185Z","steps":["trace[102785003] 'agreement among raft nodes before linearized reading'  (duration: 138.503175ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:17:13.481619Z","caller":"traceutil/trace.go:171","msg":"trace[800447459] transaction","detail":"{read_only:false; response_revision:36188; number_of_response:1; }","duration":"142.561237ms","start":"2026-05-19T19:17:13.339012Z","end":"2026-05-19T19:17:13.481573Z","steps":["trace[800447459] 'process raft request'  (duration: 142.079317ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:17:14.068866Z","caller":"traceutil/trace.go:171","msg":"trace[520386155] linearizableReadLoop","detail":"{readStateIndex:37405; appliedIndex:37404; }","duration":"230.426122ms","start":"2026-05-19T19:17:13.838415Z","end":"2026-05-19T19:17:14.068842Z","steps":["trace[520386155] 'read index received'  (duration: 230.297719ms)","trace[520386155] 'applied index is now lower than readState.Index'  (duration: 127.163µs)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:17:14.069008Z","caller":"traceutil/trace.go:171","msg":"trace[2069499314] transaction","detail":"{read_only:false; response_revision:36190; number_of_response:1; }","duration":"253.445284ms","start":"2026-05-19T19:17:13.815524Z","end":"2026-05-19T19:17:14.068969Z","steps":["trace[2069499314] 'process raft request'  (duration: 253.158067ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:17:14.069133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.676017ms","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-05-19T19:17:14.069175Z","caller":"traceutil/trace.go:171","msg":"trace[163151528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:36190; }","duration":"230.80429ms","start":"2026-05-19T19:17:13.838359Z","end":"2026-05-19T19:17:14.069163Z","steps":["trace[163151528] 'agreement among raft nodes before linearized reading'  (duration: 230.572475ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:17:14.193972Z","caller":"traceutil/trace.go:171","msg":"trace[2073142521] transaction","detail":"{read_only:false; response_revision:36191; number_of_response:1; }","duration":"328.775445ms","start":"2026-05-19T19:17:13.865158Z","end":"2026-05-19T19:17:14.193934Z","steps":["trace[2073142521] 'process raft request'  (duration: 328.573221ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:17:14.194127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:17:13.865133Z","time spent":"328.906057ms","remote":"127.0.0.1:36832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:36178 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-05-19T19:17:14.194256Z","caller":"traceutil/trace.go:171","msg":"trace[969933557] transaction","detail":"{read_only:false; response_revision:36192; number_of_response:1; }","duration":"278.580045ms","start":"2026-05-19T19:17:13.915668Z","end":"2026-05-19T19:17:14.194248Z","steps":["trace[969933557] 'process raft request'  (duration: 278.216947ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:17:14.194325Z","caller":"traceutil/trace.go:171","msg":"trace[1897723491] transaction","detail":"{read_only:false; response_revision:36193; number_of_response:1; }","duration":"121.648542ms","start":"2026-05-19T19:17:14.07265Z","end":"2026-05-19T19:17:14.194298Z","steps":["trace[1897723491] 'process raft request'  (duration: 121.501159ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:17:14.194377Z","caller":"traceutil/trace.go:171","msg":"trace[493163419] transaction","detail":"{read_only:false; response_revision:36194; number_of_response:1; }","duration":"120.414004ms","start":"2026-05-19T19:17:14.073925Z","end":"2026-05-19T19:17:14.194339Z","steps":["trace[493163419] 'process raft request'  (duration: 120.319632ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:17:14.850208Z","caller":"traceutil/trace.go:171","msg":"trace[1963739155] linearizableReadLoop","detail":"{readStateIndex:37413; appliedIndex:37412; }","duration":"107.375947ms","start":"2026-05-19T19:17:14.742815Z","end":"2026-05-19T19:17:14.850191Z","steps":["trace[1963739155] 'read index received'  (duration: 107.337107ms)","trace[1963739155] 'applied index is now lower than readState.Index'  (duration: 38.11µs)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:17:14.850246Z","caller":"traceutil/trace.go:171","msg":"trace[987614538] transaction","detail":"{read_only:false; response_revision:36198; number_of_response:1; }","duration":"114.002719ms","start":"2026-05-19T19:17:14.736216Z","end":"2026-05-19T19:17:14.850219Z","steps":["trace[987614538] 'process raft request'  (duration: 113.865546ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:17:14.850382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.533102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:17:14.85047Z","caller":"traceutil/trace.go:171","msg":"trace[1069139387] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:36198; }","duration":"107.677634ms","start":"2026-05-19T19:17:14.742773Z","end":"2026-05-19T19:17:14.85045Z","steps":["trace[1069139387] 'agreement among raft nodes before linearized reading'  (duration: 107.49384ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:17:14.987831Z","caller":"traceutil/trace.go:171","msg":"trace[329283130] linearizableReadLoop","detail":"{readStateIndex:37414; appliedIndex:37413; }","duration":"137.548312ms","start":"2026-05-19T19:17:14.850256Z","end":"2026-05-19T19:17:14.987804Z","steps":["trace[329283130] 'read index received'  (duration: 130.39671ms)","trace[329283130] 'applied index is now lower than readState.Index'  (duration: 7.150652ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:17:14.987945Z","caller":"traceutil/trace.go:171","msg":"trace[1050164153] transaction","detail":"{read_only:false; response_revision:36199; number_of_response:1; }","duration":"187.702131ms","start":"2026-05-19T19:17:14.800232Z","end":"2026-05-19T19:17:14.987934Z","steps":["trace[1050164153] 'process raft request'  (duration: 180.408515ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:17:14.988044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.474045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:17:14.988116Z","caller":"traceutil/trace.go:171","msg":"trace[568829905] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:36199; }","duration":"142.589627ms","start":"2026-05-19T19:17:14.845509Z","end":"2026-05-19T19:17:14.988099Z","steps":["trace[568829905] 'agreement among raft nodes before linearized reading'  (duration: 142.484245ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:17:14.991502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.474055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"}
{"level":"info","ts":"2026-05-19T19:17:14.991568Z","caller":"traceutil/trace.go:171","msg":"trace[597791286] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:36200; }","duration":"131.582897ms","start":"2026-05-19T19:17:14.859967Z","end":"2026-05-19T19:17:14.99155Z","steps":["trace[597791286] 'agreement among raft nodes before linearized reading'  (duration: 131.413613ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:17:14.991708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.978592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:17:14.991733Z","caller":"traceutil/trace.go:171","msg":"trace[310033659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:36200; }","duration":"123.037422ms","start":"2026-05-19T19:17:14.86869Z","end":"2026-05-19T19:17:14.991727Z","steps":["trace[310033659] 'agreement among raft nodes before linearized reading'  (duration: 122.999181ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:17:16.348679Z","caller":"traceutil/trace.go:171","msg":"trace[294864020] transaction","detail":"{read_only:false; response_revision:36207; number_of_response:1; }","duration":"148.072872ms","start":"2026-05-19T19:17:16.200577Z","end":"2026-05-19T19:17:16.34865Z","steps":["trace[294864020] 'process raft request'  (duration: 147.790476ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:17:16.34893Z","caller":"traceutil/trace.go:171","msg":"trace[1726722577] linearizableReadLoop","detail":"{readStateIndex:37422; appliedIndex:37420; }","duration":"148.057002ms","start":"2026-05-19T19:17:16.200846Z","end":"2026-05-19T19:17:16.348903Z","steps":["trace[1726722577] 'read index received'  (duration: 6.111038ms)","trace[1726722577] 'applied index is now lower than readState.Index'  (duration: 141.944783ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:17:16.349276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.488429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:573"}
{"level":"info","ts":"2026-05-19T19:17:16.349393Z","caller":"traceutil/trace.go:171","msg":"trace[613845759] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:36208; }","duration":"140.647943ms","start":"2026-05-19T19:17:16.208731Z","end":"2026-05-19T19:17:16.349379Z","steps":["trace[613845759] 'agreement among raft nodes before linearized reading'  (duration: 140.419488ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:17:16.349423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.559103ms","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-05-19T19:17:16.349467Z","caller":"traceutil/trace.go:171","msg":"trace[852209726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:36208; }","duration":"148.654795ms","start":"2026-05-19T19:17:16.2008Z","end":"2026-05-19T19:17:16.349455Z","steps":["trace[852209726] 'agreement among raft nodes before linearized reading'  (duration: 148.43939ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:17:16.349584Z","caller":"traceutil/trace.go:171","msg":"trace[1395708270] transaction","detail":"{read_only:false; response_revision:36208; number_of_response:1; }","duration":"148.617505ms","start":"2026-05-19T19:17:16.200956Z","end":"2026-05-19T19:17:16.349574Z","steps":["trace[1395708270] 'process raft request'  (duration: 147.640273ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:17:16.349737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.5048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"}
{"level":"info","ts":"2026-05-19T19:17:16.349798Z","caller":"traceutil/trace.go:171","msg":"trace[1996354767] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:36208; }","duration":"125.589691ms","start":"2026-05-19T19:17:16.224197Z","end":"2026-05-19T19:17:16.349786Z","steps":["trace[1996354767] 'agreement among raft nodes before linearized reading'  (duration: 125.449378ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:17:16.523179Z","caller":"traceutil/trace.go:171","msg":"trace[2011084244] transaction","detail":"{read_only:false; response_revision:36212; number_of_response:1; }","duration":"130.152074ms","start":"2026-05-19T19:17:16.392996Z","end":"2026-05-19T19:17:16.523148Z","steps":["trace[2011084244] 'process raft request'  (duration: 130.072173ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:17:16.523265Z","caller":"traceutil/trace.go:171","msg":"trace[1569644145] transaction","detail":"{read_only:false; response_revision:36210; number_of_response:1; }","duration":"168.615168ms","start":"2026-05-19T19:17:16.354608Z","end":"2026-05-19T19:17:16.523223Z","steps":["trace[1569644145] 'process raft request'  (duration: 87.138448ms)","trace[1569644145] 'compare'  (duration: 81.108162ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:17:16.523273Z","caller":"traceutil/trace.go:171","msg":"trace[1998688100] transaction","detail":"{read_only:false; response_revision:36211; number_of_response:1; }","duration":"168.349052ms","start":"2026-05-19T19:17:16.354891Z","end":"2026-05-19T19:17:16.52324Z","steps":["trace[1998688100] 'process raft request'  (duration: 168.113167ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:18:14.532586Z","caller":"traceutil/trace.go:171","msg":"trace[74601299] transaction","detail":"{read_only:false; response_revision:36632; number_of_response:1; }","duration":"114.202483ms","start":"2026-05-19T19:18:14.418358Z","end":"2026-05-19T19:18:14.53256Z","steps":["trace[74601299] 'process raft request'  (duration: 114.035139ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:18:34.135308Z","caller":"traceutil/trace.go:171","msg":"trace[1578740208] transaction","detail":"{read_only:false; response_revision:36775; number_of_response:1; }","duration":"108.513973ms","start":"2026-05-19T19:18:34.026765Z","end":"2026-05-19T19:18:34.135279Z","steps":["trace[1578740208] 'process raft request'  (duration: 108.214937ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:18:52.425514Z","caller":"traceutil/trace.go:171","msg":"trace[1115130443] transaction","detail":"{read_only:false; response_revision:36910; number_of_response:1; }","duration":"117.296384ms","start":"2026-05-19T19:18:52.308185Z","end":"2026-05-19T19:18:52.425482Z","steps":["trace[1115130443] 'process raft request'  (duration: 114.529771ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:18:57.872731Z","caller":"traceutil/trace.go:171","msg":"trace[384270356] transaction","detail":"{read_only:false; response_revision:36947; number_of_response:1; }","duration":"189.135704ms","start":"2026-05-19T19:18:57.68357Z","end":"2026-05-19T19:18:57.872706Z","steps":["trace[384270356] 'process raft request'  (duration: 188.97482ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:18:57.878118Z","caller":"traceutil/trace.go:171","msg":"trace[2074806982] transaction","detail":"{read_only:false; response_revision:36948; number_of_response:1; }","duration":"191.828656ms","start":"2026-05-19T19:18:57.686265Z","end":"2026-05-19T19:18:57.878094Z","steps":["trace[2074806982] 'process raft request'  (duration: 191.451336ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:20:00.570586Z","caller":"traceutil/trace.go:171","msg":"trace[290052251] linearizableReadLoop","detail":"{readStateIndex:38699; appliedIndex:38696; }","duration":"310.663764ms","start":"2026-05-19T19:20:00.259905Z","end":"2026-05-19T19:20:00.570569Z","steps":["trace[290052251] 'read index received'  (duration: 14.940319ms)","trace[290052251] 'applied index is now lower than readState.Index'  (duration: 295.722565ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:20:00.570602Z","caller":"traceutil/trace.go:171","msg":"trace[842764315] transaction","detail":"{read_only:false; response_revision:37448; number_of_response:1; }","duration":"345.224398ms","start":"2026-05-19T19:20:00.22534Z","end":"2026-05-19T19:20:00.570564Z","steps":["trace[842764315] 'process raft request'  (duration: 344.952412ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:20:00.570594Z","caller":"traceutil/trace.go:171","msg":"trace[922815425] transaction","detail":"{read_only:false; response_revision:37449; number_of_response:1; }","duration":"342.454245ms","start":"2026-05-19T19:20:00.22812Z","end":"2026-05-19T19:20:00.570574Z","steps":["trace[922815425] 'process raft request'  (duration: 342.323982ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:20:00.570775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:20:00.22532Z","time spent":"345.370461ms","remote":"127.0.0.1:36624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/events/openstack/senlin-engine-cleaner-29653640-kmgf9.18b10db55300ecb7\" mod_revision:0 > success:<request_put:<key:\"/registry/events/openstack/senlin-engine-cleaner-29653640-kmgf9.18b10db55300ecb7\" value_size:718 lease:6432440171452017092 >> failure:<>"}
{"level":"warn","ts":"2026-05-19T19:20:00.570797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.325981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-05-19T19:20:00.570833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.870592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-05-19T19:20:00.57083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:20:00.228096Z","time spent":"342.671089ms","remote":"127.0.0.1:36756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5594,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/pods/openstack/senlin-engine-cleaner-29653640-kmgf9\" mod_revision:37436 > success:<request_put:<key:\"/registry/pods/openstack/senlin-engine-cleaner-29653640-kmgf9\" value_size:5525 >> failure:<request_range:<key:\"/registry/pods/openstack/senlin-engine-cleaner-29653640-kmgf9\" > >"}
{"level":"info","ts":"2026-05-19T19:20:00.57086Z","caller":"traceutil/trace.go:171","msg":"trace[1287941056] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:37450; }","duration":"145.934283ms","start":"2026-05-19T19:20:00.424918Z","end":"2026-05-19T19:20:00.570853Z","steps":["trace[1287941056] 'agreement among raft nodes before linearized reading'  (duration: 145.893832ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:20:00.570854Z","caller":"traceutil/trace.go:171","msg":"trace[649107378] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:37450; }","duration":"186.431573ms","start":"2026-05-19T19:20:00.384406Z","end":"2026-05-19T19:20:00.570838Z","steps":["trace[649107378] 'agreement among raft nodes before linearized reading'  (duration: 186.328041ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:20:00.570804Z","caller":"traceutil/trace.go:171","msg":"trace[599910632] transaction","detail":"{read_only:false; response_revision:37450; number_of_response:1; }","duration":"309.032087ms","start":"2026-05-19T19:20:00.261601Z","end":"2026-05-19T19:20:00.570633Z","steps":["trace[599910632] 'process raft request'  (duration: 308.927535ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:20:00.57079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.881789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/tempest-ks-user-9rwkz\" ","response":"range_response_count:1 size:9148"}
{"level":"info","ts":"2026-05-19T19:20:00.57104Z","caller":"traceutil/trace.go:171","msg":"trace[200317802] range","detail":"{range_begin:/registry/pods/openstack/tempest-ks-user-9rwkz; range_end:; response_count:1; response_revision:37450; }","duration":"311.183325ms","start":"2026-05-19T19:20:00.25985Z","end":"2026-05-19T19:20:00.571034Z","steps":["trace[200317802] 'agreement among raft nodes before linearized reading'  (duration: 310.854108ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:20:00.571099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:20:00.259827Z","time spent":"311.263297ms","remote":"127.0.0.1:36756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9171,"request content":"key:\"/registry/pods/openstack/tempest-ks-user-9rwkz\" "}
{"level":"warn","ts":"2026-05-19T19:20:00.571195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:20:00.261591Z","time spent":"309.433486ms","remote":"127.0.0.1:36624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":859,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/events/openstack/tempest-ks-user-9rwkz.18b10db555484013\" mod_revision:0 > success:<request_put:<key:\"/registry/events/openstack/tempest-ks-user-9rwkz.18b10db555484013\" value_size:776 lease:6432440171452017092 >> failure:<>"}
{"level":"info","ts":"2026-05-19T19:20:41.085662Z","caller":"traceutil/trace.go:171","msg":"trace[708242356] linearizableReadLoop","detail":"{readStateIndex:39098; appliedIndex:39097; }","duration":"154.854595ms","start":"2026-05-19T19:20:40.930771Z","end":"2026-05-19T19:20:41.085626Z","steps":["trace[708242356] 'read index received'  (duration: 154.574109ms)","trace[708242356] 'applied index is now lower than readState.Index'  (duration: 278.796µs)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:20:41.085743Z","caller":"traceutil/trace.go:171","msg":"trace[48624246] transaction","detail":"{read_only:false; response_revision:37838; number_of_response:1; }","duration":"179.308601ms","start":"2026-05-19T19:20:40.9064Z","end":"2026-05-19T19:20:41.085709Z","steps":["trace[48624246] 'process raft request'  (duration: 178.971393ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:20:41.086007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.213314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"}
{"level":"info","ts":"2026-05-19T19:20:41.086058Z","caller":"traceutil/trace.go:171","msg":"trace[1566059705] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:37838; }","duration":"155.317075ms","start":"2026-05-19T19:20:40.93073Z","end":"2026-05-19T19:20:41.086047Z","steps":["trace[1566059705] 'agreement among raft nodes before linearized reading'  (duration: 155.0907ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:20:41.086063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.207905ms","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-05-19T19:20:41.086122Z","caller":"traceutil/trace.go:171","msg":"trace[1936638770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:37838; }","duration":"152.310959ms","start":"2026-05-19T19:20:40.933793Z","end":"2026-05-19T19:20:41.086104Z","steps":["trace[1936638770] 'agreement among raft nodes before linearized reading'  (duration: 152.058062ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:20:41.395019Z","caller":"traceutil/trace.go:171","msg":"trace[1688716534] transaction","detail":"{read_only:false; response_revision:37842; number_of_response:1; }","duration":"196.02325ms","start":"2026-05-19T19:20:41.198969Z","end":"2026-05-19T19:20:41.394992Z","steps":["trace[1688716534] 'process raft request'  (duration: 194.788612ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:20:47.235014Z","caller":"traceutil/trace.go:171","msg":"trace[2143579083] linearizableReadLoop","detail":"{readStateIndex:39148; appliedIndex:39147; }","duration":"103.844378ms","start":"2026-05-19T19:20:47.131142Z","end":"2026-05-19T19:20:47.234987Z","steps":["trace[2143579083] 'read index received'  (duration: 103.611613ms)","trace[2143579083] 'applied index is now lower than readState.Index'  (duration: 231.815µs)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:20:47.235084Z","caller":"traceutil/trace.go:171","msg":"trace[118141996] transaction","detail":"{read_only:false; response_revision:37887; number_of_response:1; }","duration":"112.696248ms","start":"2026-05-19T19:20:47.122352Z","end":"2026-05-19T19:20:47.235048Z","steps":["trace[118141996] 'process raft request'  (duration: 112.463693ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:20:47.235403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.68961ms","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-05-19T19:20:47.235415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.274548ms","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-05-19T19:20:47.235527Z","caller":"traceutil/trace.go:171","msg":"trace[2057599428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:37887; }","duration":"101.855293ms","start":"2026-05-19T19:20:47.133652Z","end":"2026-05-19T19:20:47.235508Z","steps":["trace[2057599428] 'agreement among raft nodes before linearized reading'  (duration: 101.532085ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:20:47.23598Z","caller":"traceutil/trace.go:171","msg":"trace[69014025] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:37887; }","duration":"104.435952ms","start":"2026-05-19T19:20:47.131086Z","end":"2026-05-19T19:20:47.235522Z","steps":["trace[69014025] 'agreement among raft nodes before linearized reading'  (duration: 104.052593ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:20:47.407648Z","caller":"traceutil/trace.go:171","msg":"trace[1896519078] linearizableReadLoop","detail":"{readStateIndex:39149; appliedIndex:39148; }","duration":"166.566711ms","start":"2026-05-19T19:20:47.241056Z","end":"2026-05-19T19:20:47.407622Z","steps":["trace[1896519078] 'read index received'  (duration: 135.082997ms)","trace[1896519078] 'applied index is now lower than readState.Index'  (duration: 31.482584ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:20:47.407681Z","caller":"traceutil/trace.go:171","msg":"trace[1763699324] transaction","detail":"{read_only:false; response_revision:37888; number_of_response:1; }","duration":"167.377861ms","start":"2026-05-19T19:20:47.240273Z","end":"2026-05-19T19:20:47.407651Z","steps":["trace[1763699324] 'process raft request'  (duration: 135.815655ms)","trace[1763699324] 'compare'  (duration: 31.30808ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:20:47.407911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.836058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"}
{"level":"info","ts":"2026-05-19T19:20:47.407996Z","caller":"traceutil/trace.go:171","msg":"trace[1285161636] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:37888; }","duration":"166.985551ms","start":"2026-05-19T19:20:47.240989Z","end":"2026-05-19T19:20:47.407974Z","steps":["trace[1285161636] 'agreement among raft nodes before linearized reading'  (duration: 166.714605ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:20:47.457301Z","caller":"traceutil/trace.go:171","msg":"trace[1341379826] transaction","detail":"{read_only:false; response_revision:37889; number_of_response:1; }","duration":"215.38368ms","start":"2026-05-19T19:20:47.241884Z","end":"2026-05-19T19:20:47.457267Z","steps":["trace[1341379826] 'process raft request'  (duration: 215.183666ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:20:47.637209Z","caller":"traceutil/trace.go:171","msg":"trace[1084717183] linearizableReadLoop","detail":"{readStateIndex:39153; appliedIndex:39151; }","duration":"124.425954ms","start":"2026-05-19T19:20:47.512764Z","end":"2026-05-19T19:20:47.63719Z","steps":["trace[1084717183] 'read index received'  (duration: 53.627257ms)","trace[1084717183] 'applied index is now lower than readState.Index'  (duration: 70.797927ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:20:47.637242Z","caller":"traceutil/trace.go:171","msg":"trace[1909129142] transaction","detail":"{read_only:false; response_revision:37893; number_of_response:1; }","duration":"123.069464ms","start":"2026-05-19T19:20:47.514141Z","end":"2026-05-19T19:20:47.637211Z","steps":["trace[1909129142] 'process raft request'  (duration: 122.941061ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:20:47.637254Z","caller":"traceutil/trace.go:171","msg":"trace[184860504] transaction","detail":"{read_only:false; response_revision:37892; number_of_response:1; }","duration":"126.375919ms","start":"2026-05-19T19:20:47.510839Z","end":"2026-05-19T19:20:47.637215Z","steps":["trace[184860504] 'process raft request'  (duration: 126.048131ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:20:47.636957Z","caller":"traceutil/trace.go:171","msg":"trace[1520928767] transaction","detail":"{read_only:false; response_revision:37891; number_of_response:1; }","duration":"126.138274ms","start":"2026-05-19T19:20:47.510794Z","end":"2026-05-19T19:20:47.636932Z","steps":["trace[1520928767] 'process raft request'  (duration: 55.592583ms)","trace[1520928767] 'compare'  (duration: 70.316666ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:20:47.637521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.716181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:20:47.637595Z","caller":"traceutil/trace.go:171","msg":"trace[1309697197] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:37893; }","duration":"124.837444ms","start":"2026-05-19T19:20:47.512739Z","end":"2026-05-19T19:20:47.637576Z","steps":["trace[1309697197] 'agreement among raft nodes before linearized reading'  (duration: 124.567458ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:20:47.832535Z","caller":"traceutil/trace.go:171","msg":"trace[348695464] linearizableReadLoop","detail":"{readStateIndex:39155; appliedIndex:39154; }","duration":"188.693765ms","start":"2026-05-19T19:20:47.64381Z","end":"2026-05-19T19:20:47.832503Z","steps":["trace[348695464] 'read index received'  (duration: 112.397023ms)","trace[348695464] 'applied index is now lower than readState.Index'  (duration: 76.295312ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:20:47.832838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.006172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"}
{"level":"info","ts":"2026-05-19T19:20:47.832905Z","caller":"traceutil/trace.go:171","msg":"trace[1019775887] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:37894; }","duration":"189.122664ms","start":"2026-05-19T19:20:47.643767Z","end":"2026-05-19T19:20:47.83289Z","steps":["trace[1019775887] 'agreement among raft nodes before linearized reading'  (duration: 188.894289ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:20:47.832611Z","caller":"traceutil/trace.go:171","msg":"trace[1387485156] transaction","detail":"{read_only:false; response_revision:37894; number_of_response:1; }","duration":"189.36194ms","start":"2026-05-19T19:20:47.643174Z","end":"2026-05-19T19:20:47.832536Z","steps":["trace[1387485156] 'process raft request'  (duration: 113.129159ms)","trace[1387485156] 'compare'  (duration: 75.979475ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:21:04.01963Z","caller":"traceutil/trace.go:171","msg":"trace[910621633] transaction","detail":"{read_only:false; response_revision:38018; number_of_response:1; }","duration":"110.869927ms","start":"2026-05-19T19:21:03.908729Z","end":"2026-05-19T19:21:04.019599Z","steps":["trace[910621633] 'process raft request'  (duration: 110.589541ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:21:04.663355Z","caller":"traceutil/trace.go:171","msg":"trace[331698160] transaction","detail":"{read_only:false; response_revision:38023; number_of_response:1; }","duration":"124.296392ms","start":"2026-05-19T19:21:04.539025Z","end":"2026-05-19T19:21:04.663321Z","steps":["trace[331698160] 'process raft request'  (duration: 86.291989ms)","trace[331698160] 'compare'  (duration: 37.85456ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:21:05.888012Z","caller":"traceutil/trace.go:171","msg":"trace[1481554730] transaction","detail":"{read_only:false; response_revision:38027; number_of_response:1; }","duration":"190.011554ms","start":"2026-05-19T19:21:05.697969Z","end":"2026-05-19T19:21:05.887981Z","steps":["trace[1481554730] 'process raft request'  (duration: 110.014208ms)","trace[1481554730] 'compare'  (duration: 79.876874ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:21:05.94769Z","caller":"traceutil/trace.go:171","msg":"trace[813981264] transaction","detail":"{read_only:false; response_revision:38029; number_of_response:1; }","duration":"234.402321ms","start":"2026-05-19T19:21:05.713262Z","end":"2026-05-19T19:21:05.947664Z","steps":["trace[813981264] 'process raft request'  (duration: 234.32774ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:21:05.947728Z","caller":"traceutil/trace.go:171","msg":"trace[1460473251] transaction","detail":"{read_only:false; response_revision:38028; number_of_response:1; }","duration":"240.344457ms","start":"2026-05-19T19:21:05.707349Z","end":"2026-05-19T19:21:05.947693Z","steps":["trace[1460473251] 'process raft request'  (duration: 240.106802ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:21:05.947897Z","caller":"traceutil/trace.go:171","msg":"trace[523431109] linearizableReadLoop","detail":"{readStateIndex:39295; appliedIndex:39291; }","duration":"193.564565ms","start":"2026-05-19T19:21:05.754297Z","end":"2026-05-19T19:21:05.947861Z","steps":["trace[523431109] 'read index received'  (duration: 53.980796ms)","trace[523431109] 'applied index is now lower than readState.Index'  (duration: 139.581609ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:21:05.947935Z","caller":"traceutil/trace.go:171","msg":"trace[1427505971] transaction","detail":"{read_only:false; response_revision:38030; number_of_response:1; }","duration":"226.859341ms","start":"2026-05-19T19:21:05.721065Z","end":"2026-05-19T19:21:05.947924Z","steps":["trace[1427505971] 'process raft request'  (duration: 226.565374ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:21:05.948036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.717138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:21:05.94809Z","caller":"traceutil/trace.go:171","msg":"trace[2100524860] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:38030; }","duration":"193.801879ms","start":"2026-05-19T19:21:05.75427Z","end":"2026-05-19T19:21:05.948071Z","steps":["trace[2100524860] 'agreement among raft nodes before linearized reading'  (duration: 193.702467ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:21:05.948112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.716433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:21:05.948157Z","caller":"traceutil/trace.go:171","msg":"trace[609787101] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:38030; }","duration":"136.812435ms","start":"2026-05-19T19:21:05.811333Z","end":"2026-05-19T19:21:05.948146Z","steps":["trace[609787101] 'agreement among raft nodes before linearized reading'  (duration: 136.701352ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:21:05.948451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.494563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:21:05.948491Z","caller":"traceutil/trace.go:171","msg":"trace[1371644504] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:38030; }","duration":"108.567565ms","start":"2026-05-19T19:21:05.839913Z","end":"2026-05-19T19:21:05.948481Z","steps":["trace[1371644504] 'agreement among raft nodes before linearized reading'  (duration: 108.500854ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:21:06.127696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.080174ms","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-05-19T19:21:06.127761Z","caller":"traceutil/trace.go:171","msg":"trace[697897165] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:38031; }","duration":"104.188226ms","start":"2026-05-19T19:21:06.023558Z","end":"2026-05-19T19:21:06.127746Z","steps":["trace[697897165] 'range keys from in-memory index tree'  (duration: 103.89397ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:21:06.263257Z","caller":"traceutil/trace.go:171","msg":"trace[2132214427] transaction","detail":"{read_only:false; response_revision:38032; number_of_response:1; }","duration":"131.621978ms","start":"2026-05-19T19:21:06.131612Z","end":"2026-05-19T19:21:06.263234Z","steps":["trace[2132214427] 'process raft request'  (duration: 131.462204ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:21:06.272076Z","caller":"traceutil/trace.go:171","msg":"trace[1939807827] transaction","detail":"{read_only:false; response_revision:38033; number_of_response:1; }","duration":"139.580649ms","start":"2026-05-19T19:21:06.132474Z","end":"2026-05-19T19:21:06.272055Z","steps":["trace[1939807827] 'process raft request'  (duration: 139.328334ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:21:06.382278Z","caller":"traceutil/trace.go:171","msg":"trace[1631351497] transaction","detail":"{read_only:false; response_revision:38034; number_of_response:1; }","duration":"106.496598ms","start":"2026-05-19T19:21:06.275746Z","end":"2026-05-19T19:21:06.382243Z","steps":["trace[1631351497] 'process raft request'  (duration: 106.305773ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:21:14.072805Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35751}
{"level":"info","ts":"2026-05-19T19:21:14.19306Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35751,"took":"116.695199ms","hash":2428424486,"current-db-size-bytes":59461632,"current-db-size":"60 MB","current-db-size-in-use-bytes":32567296,"current-db-size-in-use":"33 MB"}
{"level":"info","ts":"2026-05-19T19:21:14.193141Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2428424486,"revision":35751,"compact-revision":33489}
{"level":"info","ts":"2026-05-19T19:21:21.032445Z","caller":"traceutil/trace.go:171","msg":"trace[1816027096] transaction","detail":"{read_only:false; response_revision:38141; number_of_response:1; }","duration":"377.88828ms","start":"2026-05-19T19:21:20.654525Z","end":"2026-05-19T19:21:21.032413Z","steps":["trace[1816027096] 'process raft request'  (duration: 377.739036ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:21:21.032591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:21:20.654427Z","time spent":"378.087574ms","remote":"127.0.0.1:36832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:38127 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"info","ts":"2026-05-19T19:21:21.184221Z","caller":"traceutil/trace.go:171","msg":"trace[799457637] linearizableReadLoop","detail":"{readStateIndex:39411; appliedIndex:39409; }","duration":"210.261894ms","start":"2026-05-19T19:21:20.973936Z","end":"2026-05-19T19:21:21.184198Z","steps":["trace[799457637] 'read index received'  (duration: 58.428466ms)","trace[799457637] 'applied index is now lower than readState.Index'  (duration: 151.832138ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:21:21.184288Z","caller":"traceutil/trace.go:171","msg":"trace[1782006019] transaction","detail":"{read_only:false; response_revision:38142; number_of_response:1; }","duration":"460.702129ms","start":"2026-05-19T19:21:20.723553Z","end":"2026-05-19T19:21:21.184255Z","steps":["trace[1782006019] 'process raft request'  (duration: 441.035592ms)","trace[1782006019] 'compare'  (duration: 19.35955ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:21:21.184475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:21:20.723532Z","time spent":"460.844903ms","remote":"127.0.0.1:36832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/capo-system/controller-leader-election-capo\" mod_revision:38128 > success:<request_put:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/capo-system/controller-leader-election-capo\" > >"}
{"level":"warn","ts":"2026-05-19T19:21:21.184555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.486529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"}
{"level":"info","ts":"2026-05-19T19:21:21.184592Z","caller":"traceutil/trace.go:171","msg":"trace[827065002] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:38142; }","duration":"210.674433ms","start":"2026-05-19T19:21:20.973909Z","end":"2026-05-19T19:21:21.184583Z","steps":["trace[827065002] 'agreement among raft nodes before linearized reading'  (duration: 210.391007ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:21:52.282805Z","caller":"traceutil/trace.go:171","msg":"trace[826462884] transaction","detail":"{read_only:false; response_revision:38369; number_of_response:1; }","duration":"103.184662ms","start":"2026-05-19T19:21:52.179598Z","end":"2026-05-19T19:21:52.282782Z","steps":["trace[826462884] 'process raft request'  (duration: 101.811202ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:21:52.282866Z","caller":"traceutil/trace.go:171","msg":"trace[1446806934] transaction","detail":"{read_only:false; response_revision:38370; number_of_response:1; }","duration":"102.469236ms","start":"2026-05-19T19:21:52.180377Z","end":"2026-05-19T19:21:52.282847Z","steps":["trace[1446806934] 'process raft request'  (duration: 102.246001ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:21:58.934743Z","caller":"traceutil/trace.go:171","msg":"trace[127755166] transaction","detail":"{read_only:false; response_revision:38419; number_of_response:1; }","duration":"120.340172ms","start":"2026-05-19T19:21:58.814374Z","end":"2026-05-19T19:21:58.934714Z","steps":["trace[127755166] 'process raft request'  (duration: 98.024766ms)","trace[127755166] 'compare'  (duration: 21.675052ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:22:01.15013Z","caller":"traceutil/trace.go:171","msg":"trace[691584840] linearizableReadLoop","detail":"{readStateIndex:39711; appliedIndex:39709; }","duration":"192.222915ms","start":"2026-05-19T19:22:00.957836Z","end":"2026-05-19T19:22:01.150059Z","steps":["trace[691584840] 'read index received'  (duration: 20.560107ms)","trace[691584840] 'applied index is now lower than readState.Index'  (duration: 171.661548ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:22:01.150215Z","caller":"traceutil/trace.go:171","msg":"trace[1228299207] transaction","detail":"{read_only:false; response_revision:38433; number_of_response:1; }","duration":"207.748447ms","start":"2026-05-19T19:22:00.942446Z","end":"2026-05-19T19:22:01.150194Z","steps":["trace[1228299207] 'process raft request'  (duration: 156.713378ms)","trace[1228299207] 'compare'  (duration: 50.593259ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:22:01.150613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.762877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"}
{"level":"info","ts":"2026-05-19T19:22:01.150704Z","caller":"traceutil/trace.go:171","msg":"trace[592428493] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:38433; }","duration":"192.861449ms","start":"2026-05-19T19:22:00.957819Z","end":"2026-05-19T19:22:01.150681Z","steps":["trace[592428493] 'agreement among raft nodes before linearized reading'  (duration: 192.377758ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:22:01.682123Z","caller":"traceutil/trace.go:171","msg":"trace[1218586434] linearizableReadLoop","detail":"{readStateIndex:39713; appliedIndex:39712; }","duration":"158.780295ms","start":"2026-05-19T19:22:01.523314Z","end":"2026-05-19T19:22:01.682094Z","steps":["trace[1218586434] 'read index received'  (duration: 158.432477ms)","trace[1218586434] 'applied index is now lower than readState.Index'  (duration: 346.078µs)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:22:01.682249Z","caller":"traceutil/trace.go:171","msg":"trace[1716544804] transaction","detail":"{read_only:false; response_revision:38435; number_of_response:1; }","duration":"177.322426ms","start":"2026-05-19T19:22:01.504876Z","end":"2026-05-19T19:22:01.682199Z","steps":["trace[1716544804] 'process raft request'  (duration: 176.961808ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:22:01.68299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.623494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:22:01.683069Z","caller":"traceutil/trace.go:171","msg":"trace[1243052577] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:38435; }","duration":"159.739276ms","start":"2026-05-19T19:22:01.523307Z","end":"2026-05-19T19:22:01.683046Z","steps":["trace[1243052577] 'agreement among raft nodes before linearized reading'  (duration: 159.595363ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:22:02.39858Z","caller":"traceutil/trace.go:171","msg":"trace[2111872405] transaction","detail":"{read_only:false; response_revision:38440; number_of_response:1; }","duration":"107.254635ms","start":"2026-05-19T19:22:02.291301Z","end":"2026-05-19T19:22:02.398555Z","steps":["trace[2111872405] 'process raft request'  (duration: 107.107901ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:22:03.013934Z","caller":"traceutil/trace.go:171","msg":"trace[433660113] transaction","detail":"{read_only:false; response_revision:38445; number_of_response:1; }","duration":"153.421824ms","start":"2026-05-19T19:22:02.860491Z","end":"2026-05-19T19:22:03.013913Z","steps":["trace[433660113] 'process raft request'  (duration: 153.313001ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:22:03.101382Z","caller":"traceutil/trace.go:171","msg":"trace[1431849230] transaction","detail":"{read_only:false; response_revision:38447; number_of_response:1; }","duration":"115.133564ms","start":"2026-05-19T19:22:02.986229Z","end":"2026-05-19T19:22:03.101363Z","steps":["trace[1431849230] 'process raft request'  (duration: 115.100303ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:22:03.101621Z","caller":"traceutil/trace.go:171","msg":"trace[1530684588] transaction","detail":"{read_only:false; response_revision:38446; number_of_response:1; }","duration":"232.661072ms","start":"2026-05-19T19:22:02.868952Z","end":"2026-05-19T19:22:03.101613Z","steps":["trace[1530684588] 'process raft request'  (duration: 232.281273ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:22:03.804147Z","caller":"traceutil/trace.go:171","msg":"trace[445207912] transaction","detail":"{read_only:false; response_revision:38452; number_of_response:1; }","duration":"116.631657ms","start":"2026-05-19T19:22:03.687486Z","end":"2026-05-19T19:22:03.804117Z","steps":["trace[445207912] 'process raft request'  (duration: 116.384742ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:22:04.627572Z","caller":"traceutil/trace.go:171","msg":"trace[1560130289] transaction","detail":"{read_only:false; response_revision:38458; number_of_response:1; }","duration":"111.787088ms","start":"2026-05-19T19:22:04.515747Z","end":"2026-05-19T19:22:04.627534Z","steps":["trace[1560130289] 'process raft request'  (duration: 111.628374ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:22:04.849909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.457885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"}
{"level":"info","ts":"2026-05-19T19:22:04.850051Z","caller":"traceutil/trace.go:171","msg":"trace[112643926] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:38460; }","duration":"109.63474ms","start":"2026-05-19T19:22:04.740392Z","end":"2026-05-19T19:22:04.850026Z","steps":["trace[112643926] 'range keys from in-memory index tree'  (duration: 109.199819ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:22:05.259991Z","caller":"traceutil/trace.go:171","msg":"trace[82250840] transaction","detail":"{read_only:false; response_revision:38461; number_of_response:1; }","duration":"237.199346ms","start":"2026-05-19T19:22:05.022773Z","end":"2026-05-19T19:22:05.259972Z","steps":["trace[82250840] 'process raft request'  (duration: 237.078063ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:22:05.261454Z","caller":"traceutil/trace.go:171","msg":"trace[698304900] transaction","detail":"{read_only:false; response_revision:38462; number_of_response:1; }","duration":"153.936175ms","start":"2026-05-19T19:22:05.107491Z","end":"2026-05-19T19:22:05.261427Z","steps":["trace[698304900] 'process raft request'  (duration: 153.581378ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:22:09.456973Z","caller":"traceutil/trace.go:171","msg":"trace[1043236057] transaction","detail":"{read_only:false; response_revision:38492; number_of_response:1; }","duration":"126.097994ms","start":"2026-05-19T19:22:09.330844Z","end":"2026-05-19T19:22:09.456942Z","steps":["trace[1043236057] 'process raft request'  (duration: 103.377298ms)","trace[1043236057] 'compare'  (duration: 22.493481ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:22:21.145017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.245865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:22:21.145093Z","caller":"traceutil/trace.go:171","msg":"trace[1042178884] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:38576; }","duration":"122.377548ms","start":"2026-05-19T19:22:21.022698Z","end":"2026-05-19T19:22:21.145076Z","steps":["trace[1042178884] 'count revisions from in-memory index tree'  (duration: 122.167863ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:22:40.750912Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"355195d5aae85944","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-05-19T19:22:40.758152Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004}
{"level":"info","ts":"2026-05-19T19:22:40.758263Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}
{"level":"info","ts":"2026-05-19T19:24:39.486246Z","caller":"traceutil/trace.go:171","msg":"trace[1330986790] transaction","detail":"{read_only:false; response_revision:39587; number_of_response:1; }","duration":"121.066379ms","start":"2026-05-19T19:24:39.365152Z","end":"2026-05-19T19:24:39.486218Z","steps":["trace[1330986790] 'process raft request'  (duration: 120.879185ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:24:40.681456Z","caller":"traceutil/trace.go:171","msg":"trace[968619105] linearizableReadLoop","detail":"{readStateIndex:40908; appliedIndex:40906; }","duration":"116.582796ms","start":"2026-05-19T19:24:40.564842Z","end":"2026-05-19T19:24:40.681424Z","steps":["trace[968619105] 'read index received'  (duration: 34.028302ms)","trace[968619105] 'applied index is now lower than readState.Index'  (duration: 82.552894ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:24:40.681468Z","caller":"traceutil/trace.go:171","msg":"trace[1970450193] transaction","detail":"{read_only:false; response_revision:39596; number_of_response:1; }","duration":"138.479634ms","start":"2026-05-19T19:24:40.542961Z","end":"2026-05-19T19:24:40.681441Z","steps":["trace[1970450193] 'process raft request'  (duration: 85.216455ms)","trace[1970450193] 'compare'  (duration: 53.008083ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:24:40.681587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.720639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:24:40.681654Z","caller":"traceutil/trace.go:171","msg":"trace[606873214] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:39596; }","duration":"116.836202ms","start":"2026-05-19T19:24:40.5648Z","end":"2026-05-19T19:24:40.681636Z","steps":["trace[606873214] 'agreement among raft nodes before linearized reading'  (duration: 116.72909ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:25:24.885453Z","caller":"traceutil/trace.go:171","msg":"trace[685230039] transaction","detail":"{read_only:false; response_revision:39932; number_of_response:1; }","duration":"127.587806ms","start":"2026-05-19T19:25:24.757829Z","end":"2026-05-19T19:25:24.885416Z","steps":["trace[685230039] 'process raft request'  (duration: 70.583712ms)","trace[685230039] 'compare'  (duration: 56.554504ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:25:29.007482Z","caller":"traceutil/trace.go:171","msg":"trace[1653218414] transaction","detail":"{read_only:false; response_revision:39965; number_of_response:1; }","duration":"102.552059ms","start":"2026-05-19T19:25:28.904721Z","end":"2026-05-19T19:25:29.007273Z","steps":["trace[1653218414] 'process raft request'  (duration: 102.369165ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:25:31.969219Z","caller":"traceutil/trace.go:171","msg":"trace[670089023] transaction","detail":"{read_only:false; response_revision:39986; number_of_response:1; }","duration":"116.401162ms","start":"2026-05-19T19:25:31.852793Z","end":"2026-05-19T19:25:31.969194Z","steps":["trace[670089023] 'process raft request'  (duration: 70.730066ms)","trace[670089023] 'compare'  (duration: 45.279548ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:25:32.681574Z","caller":"traceutil/trace.go:171","msg":"trace[420052931] transaction","detail":"{read_only:false; response_revision:39992; number_of_response:1; }","duration":"121.717154ms","start":"2026-05-19T19:25:32.559834Z","end":"2026-05-19T19:25:32.681551Z","steps":["trace[420052931] 'process raft request'  (duration: 121.588911ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:25:34.254536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.054966ms","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-05-19T19:25:34.254648Z","caller":"traceutil/trace.go:171","msg":"trace[606327158] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:40002; }","duration":"113.20433ms","start":"2026-05-19T19:25:34.141418Z","end":"2026-05-19T19:25:34.254622Z","steps":["trace[606327158] 'range keys from in-memory index tree'  (duration: 112.891233ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:25:36.523616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.482142ms","expected-duration":"100ms","prefix":"","request":"header:<ID:6432440171452024434 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:40005 > success:<request_put:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" value_size:441 >> failure:<request_range:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-19T19:25:36.523722Z","caller":"traceutil/trace.go:171","msg":"trace[1465237345] transaction","detail":"{read_only:false; response_revision:40018; number_of_response:1; }","duration":"171.141696ms","start":"2026-05-19T19:25:36.352565Z","end":"2026-05-19T19:25:36.523707Z","steps":["trace[1465237345] 'process raft request'  (duration: 40.49103ms)","trace[1465237345] 'compare'  (duration: 130.336929ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:25:41.649771Z","caller":"traceutil/trace.go:171","msg":"trace[635819254] linearizableReadLoop","detail":"{readStateIndex:41381; appliedIndex:41380; }","duration":"119.564315ms","start":"2026-05-19T19:25:41.530182Z","end":"2026-05-19T19:25:41.649747Z","steps":["trace[635819254] 'read index received'  (duration: 119.391531ms)","trace[635819254] 'applied index is now lower than readState.Index'  (duration: 171.644µs)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:25:41.649929Z","caller":"traceutil/trace.go:171","msg":"trace[642203008] transaction","detail":"{read_only:false; response_revision:40056; number_of_response:1; }","duration":"122.899691ms","start":"2026-05-19T19:25:41.526963Z","end":"2026-05-19T19:25:41.649863Z","steps":["trace[642203008] 'process raft request'  (duration: 122.595984ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:25:41.650039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.057456ms","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-05-19T19:25:41.650172Z","caller":"traceutil/trace.go:171","msg":"trace[232442728] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:40056; }","duration":"120.25027ms","start":"2026-05-19T19:25:41.529905Z","end":"2026-05-19T19:25:41.650156Z","steps":["trace[232442728] 'agreement among raft nodes before linearized reading'  (duration: 119.937663ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:25:42.323713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.321441ms","expected-duration":"100ms","prefix":"","request":"header:<ID:6432440171452024544 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:40046 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-19T19:25:42.323818Z","caller":"traceutil/trace.go:171","msg":"trace[1658507202] transaction","detail":"{read_only:false; response_revision:40060; number_of_response:1; }","duration":"160.27687ms","start":"2026-05-19T19:25:42.163524Z","end":"2026-05-19T19:25:42.323801Z","steps":["trace[1658507202] 'compare'  (duration: 153.058355ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:25:42.522665Z","caller":"traceutil/trace.go:171","msg":"trace[1945366783] linearizableReadLoop","detail":"{readStateIndex:41386; appliedIndex:41385; }","duration":"170.355918ms","start":"2026-05-19T19:25:42.352277Z","end":"2026-05-19T19:25:42.522633Z","steps":["trace[1945366783] 'read index received'  (duration: 170.147533ms)","trace[1945366783] 'applied index is now lower than readState.Index'  (duration: 207.285µs)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:25:42.522814Z","caller":"traceutil/trace.go:171","msg":"trace[1906588134] transaction","detail":"{read_only:false; response_revision:40061; number_of_response:1; }","duration":"274.643155ms","start":"2026-05-19T19:25:42.248135Z","end":"2026-05-19T19:25:42.522778Z","steps":["trace[1906588134] 'process raft request'  (duration: 274.305047ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:25:42.523024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.733516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"}
{"level":"info","ts":"2026-05-19T19:25:42.523069Z","caller":"traceutil/trace.go:171","msg":"trace[388502599] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:40061; }","duration":"170.824178ms","start":"2026-05-19T19:25:42.352234Z","end":"2026-05-19T19:25:42.523058Z","steps":["trace[388502599] 'agreement among raft nodes before linearized reading'  (duration: 170.607283ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:25:42.523151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.169042ms","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-05-19T19:25:42.523258Z","caller":"traceutil/trace.go:171","msg":"trace[2136291116] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:40061; }","duration":"129.386337ms","start":"2026-05-19T19:25:42.393849Z","end":"2026-05-19T19:25:42.523236Z","steps":["trace[2136291116] 'agreement among raft nodes before linearized reading'  (duration: 129.091301ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:25:47.639413Z","caller":"traceutil/trace.go:171","msg":"trace[239343789] transaction","detail":"{read_only:false; response_revision:40097; number_of_response:1; }","duration":"111.695876ms","start":"2026-05-19T19:25:47.527697Z","end":"2026-05-19T19:25:47.639393Z","steps":["trace[239343789] 'process raft request'  (duration: 26.156034ms)","trace[239343789] 'compare'  (duration: 85.44523ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:25:47.844709Z","caller":"traceutil/trace.go:171","msg":"trace[468367329] transaction","detail":"{read_only:false; response_revision:40099; number_of_response:1; }","duration":"150.793832ms","start":"2026-05-19T19:25:47.693891Z","end":"2026-05-19T19:25:47.844685Z","steps":["trace[468367329] 'process raft request'  (duration: 150.180149ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:25:47.844715Z","caller":"traceutil/trace.go:171","msg":"trace[1439790014] linearizableReadLoop","detail":"{readStateIndex:41425; appliedIndex:41424; }","duration":"126.43406ms","start":"2026-05-19T19:25:47.718247Z","end":"2026-05-19T19:25:47.844681Z","steps":["trace[1439790014] 'read index received'  (duration: 125.844897ms)","trace[1439790014] 'applied index is now lower than readState.Index'  (duration: 587.793µs)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:25:47.844851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.574763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:25:47.84491Z","caller":"traceutil/trace.go:171","msg":"trace[1695346841] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:40099; }","duration":"126.688907ms","start":"2026-05-19T19:25:47.718207Z","end":"2026-05-19T19:25:47.844896Z","steps":["trace[1695346841] 'agreement among raft nodes before linearized reading'  (duration: 126.555703ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:26:01.850224Z","caller":"traceutil/trace.go:171","msg":"trace[1859259646] transaction","detail":"{read_only:false; response_revision:40201; number_of_response:1; }","duration":"127.192917ms","start":"2026-05-19T19:26:01.723005Z","end":"2026-05-19T19:26:01.850198Z","steps":["trace[1859259646] 'process raft request'  (duration: 127.043514ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:26:14.078183Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":38091}
{"level":"info","ts":"2026-05-19T19:26:14.206097Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":38091,"took":"122.579833ms","hash":3194248459,"current-db-size-bytes":59461632,"current-db-size":"60 MB","current-db-size-in-use-bytes":33157120,"current-db-size-in-use":"33 MB"}
{"level":"info","ts":"2026-05-19T19:26:14.206225Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3194248459,"revision":38091,"compact-revision":35751}
{"level":"info","ts":"2026-05-19T19:27:27.193878Z","caller":"traceutil/trace.go:171","msg":"trace[396656538] linearizableReadLoop","detail":"{readStateIndex:42171; appliedIndex:42170; }","duration":"161.023717ms","start":"2026-05-19T19:27:27.032833Z","end":"2026-05-19T19:27:27.193856Z","steps":["trace[396656538] 'read index received'  (duration: 160.786441ms)","trace[396656538] 'applied index is now lower than readState.Index'  (duration: 236.146µs)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:27:27.193992Z","caller":"traceutil/trace.go:171","msg":"trace[141053575] transaction","detail":"{read_only:false; response_revision:40823; number_of_response:1; }","duration":"171.701008ms","start":"2026-05-19T19:27:27.022216Z","end":"2026-05-19T19:27:27.193917Z","steps":["trace[141053575] 'process raft request'  (duration: 171.487143ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:27:27.194149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.305503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:27:27.194204Z","caller":"traceutil/trace.go:171","msg":"trace[1549579373] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:40823; }","duration":"161.411055ms","start":"2026-05-19T19:27:27.032779Z","end":"2026-05-19T19:27:27.19419Z","steps":["trace[1549579373] 'agreement among raft nodes before linearized reading'  (duration: 161.320893ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:27:27.19426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.76861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:27:27.194349Z","caller":"traceutil/trace.go:171","msg":"trace[615892502] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:40823; }","duration":"112.908483ms","start":"2026-05-19T19:27:27.081421Z","end":"2026-05-19T19:27:27.19433Z","steps":["trace[615892502] 'agreement among raft nodes before linearized reading'  (duration: 112.76553ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:27:27.214837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.639753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:27:27.214873Z","caller":"traceutil/trace.go:171","msg":"trace[1908669746] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:40823; }","duration":"129.721475ms","start":"2026-05-19T19:27:27.08514Z","end":"2026-05-19T19:27:27.214861Z","steps":["trace[1908669746] 'agreement among raft nodes before linearized reading'  (duration: 109.143878ms)","trace[1908669746] 'count revisions from in-memory index tree'  (duration: 20.532056ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:27:27.510764Z","caller":"traceutil/trace.go:171","msg":"trace[882387107] transaction","detail":"{read_only:false; response_revision:40826; number_of_response:1; }","duration":"170.966662ms","start":"2026-05-19T19:27:27.339762Z","end":"2026-05-19T19:27:27.510729Z","steps":["trace[882387107] 'process raft request'  (duration: 170.805458ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:27:27.683166Z","caller":"traceutil/trace.go:171","msg":"trace[1184344922] transaction","detail":"{read_only:false; response_revision:40827; number_of_response:1; }","duration":"134.773641ms","start":"2026-05-19T19:27:27.54836Z","end":"2026-05-19T19:27:27.683134Z","steps":["trace[1184344922] 'process raft request'  (duration: 134.121475ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:27:35.42933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.963787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"}
{"level":"info","ts":"2026-05-19T19:27:35.429443Z","caller":"traceutil/trace.go:171","msg":"trace[412066103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:40884; }","duration":"136.128151ms","start":"2026-05-19T19:27:35.293288Z","end":"2026-05-19T19:27:35.429416Z","steps":["trace[412066103] 'range keys from in-memory index tree'  (duration: 135.737452ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:28:06.864986Z","caller":"traceutil/trace.go:171","msg":"trace[838679838] transaction","detail":"{read_only:false; response_revision:41114; number_of_response:1; }","duration":"226.492282ms","start":"2026-05-19T19:28:06.63847Z","end":"2026-05-19T19:28:06.864962Z","steps":["trace[838679838] 'process raft request'  (duration: 226.157135ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:28:08.080243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.794734ms","expected-duration":"100ms","prefix":"","request":"header:<ID:6432440171452027153 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:41108 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-19T19:28:08.080525Z","caller":"traceutil/trace.go:171","msg":"trace[428349662] transaction","detail":"{read_only:false; response_revision:41123; number_of_response:1; }","duration":"149.058804ms","start":"2026-05-19T19:28:07.931447Z","end":"2026-05-19T19:28:08.080506Z","steps":["trace[428349662] 'process raft request'  (duration: 148.985323ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:28:08.080541Z","caller":"traceutil/trace.go:171","msg":"trace[83475832] transaction","detail":"{read_only:false; response_revision:41122; number_of_response:1; }","duration":"157.071816ms","start":"2026-05-19T19:28:07.923426Z","end":"2026-05-19T19:28:08.080498Z","steps":["trace[83475832] 'process raft request'  (duration: 42.932884ms)","trace[83475832] 'compare'  (duration: 113.623251ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:28:08.416948Z","caller":"traceutil/trace.go:171","msg":"trace[1497855882] transaction","detail":"{read_only:false; response_revision:41125; number_of_response:1; }","duration":"120.765772ms","start":"2026-05-19T19:28:08.296154Z","end":"2026-05-19T19:28:08.41692Z","steps":["trace[1497855882] 'process raft request'  (duration: 90.508425ms)","trace[1497855882] 'compare'  (duration: 30.054053ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:28:08.417061Z","caller":"traceutil/trace.go:171","msg":"trace[1548729568] linearizableReadLoop","detail":"{readStateIndex:42483; appliedIndex:42481; }","duration":"111.4306ms","start":"2026-05-19T19:28:08.305607Z","end":"2026-05-19T19:28:08.417037Z","steps":["trace[1548729568] 'read index received'  (duration: 81.06684ms)","trace[1548729568] 'applied index is now lower than readState.Index'  (duration: 30.3624ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:28:08.417175Z","caller":"traceutil/trace.go:171","msg":"trace[1928004928] transaction","detail":"{read_only:false; response_revision:41126; number_of_response:1; }","duration":"118.668254ms","start":"2026-05-19T19:28:08.298462Z","end":"2026-05-19T19:28:08.417131Z","steps":["trace[1928004928] 'process raft request'  (duration: 118.407858ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:28:08.417322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.691156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:141"}
{"level":"info","ts":"2026-05-19T19:28:08.417449Z","caller":"traceutil/trace.go:171","msg":"trace[92815954] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:41126; }","duration":"111.794718ms","start":"2026-05-19T19:28:08.305579Z","end":"2026-05-19T19:28:08.417374Z","steps":["trace[92815954] 'agreement among raft nodes before linearized reading'  (duration: 111.558873ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:28:09.708531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.443175ms","expected-duration":"100ms","prefix":"","request":"header:<ID:6432440171452027183 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:41118 > success:<request_put:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" value_size:441 >> failure:<request_range:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-19T19:28:09.708639Z","caller":"traceutil/trace.go:171","msg":"trace[327515662] linearizableReadLoop","detail":"{readStateIndex:42491; appliedIndex:42490; }","duration":"203.172682ms","start":"2026-05-19T19:28:09.50545Z","end":"2026-05-19T19:28:09.708623Z","steps":["trace[327515662] 'read index received'  (duration: 45.591µs)","trace[327515662] 'applied index is now lower than readState.Index'  (duration: 203.125671ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:28:09.708662Z","caller":"traceutil/trace.go:171","msg":"trace[1887745842] transaction","detail":"{read_only:false; response_revision:41133; number_of_response:1; }","duration":"320.889435ms","start":"2026-05-19T19:28:09.38775Z","end":"2026-05-19T19:28:09.708639Z","steps":["trace[1887745842] 'process raft request'  (duration: 52.253006ms)","trace[1887745842] 'compare'  (duration: 268.307761ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:28:09.708754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:28:09.387718Z","time spent":"320.987967ms","remote":"127.0.0.1:36832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:41118 > success:<request_put:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" value_size:441 >> failure:<request_range:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" > >"}
{"level":"warn","ts":"2026-05-19T19:28:09.708813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.358636ms","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-05-19T19:28:09.708897Z","caller":"traceutil/trace.go:171","msg":"trace[331330102] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:41133; }","duration":"203.487169ms","start":"2026-05-19T19:28:09.50539Z","end":"2026-05-19T19:28:09.708877Z","steps":["trace[331330102] 'agreement among raft nodes before linearized reading'  (duration: 203.309625ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:28:09.70903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.149653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"}
{"level":"info","ts":"2026-05-19T19:28:09.709131Z","caller":"traceutil/trace.go:171","msg":"trace[1175333556] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:41133; }","duration":"111.322636ms","start":"2026-05-19T19:28:09.597785Z","end":"2026-05-19T19:28:09.709108Z","steps":["trace[1175333556] 'agreement among raft nodes before linearized reading'  (duration: 110.963288ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:28:54.062066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.605784ms","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-05-19T19:28:54.063013Z","caller":"traceutil/trace.go:171","msg":"trace[2030800748] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:41457; }","duration":"101.693948ms","start":"2026-05-19T19:28:53.961296Z","end":"2026-05-19T19:28:54.06299Z","steps":["trace[2030800748] 'range keys from in-memory index tree'  (duration: 100.042962ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:28:54.985749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.979048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:28:54.985835Z","caller":"traceutil/trace.go:171","msg":"trace[399566373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:41465; }","duration":"114.10564ms","start":"2026-05-19T19:28:54.87171Z","end":"2026-05-19T19:28:54.985816Z","steps":["trace[399566373] 'range keys from in-memory index tree'  (duration: 113.928027ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:28:55.445341Z","caller":"traceutil/trace.go:171","msg":"trace[142746966] transaction","detail":"{read_only:false; response_revision:41466; number_of_response:1; }","duration":"362.328397ms","start":"2026-05-19T19:28:55.082789Z","end":"2026-05-19T19:28:55.445117Z","steps":["trace[142746966] 'process raft request'  (duration: 362.142303ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:28:55.445538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T19:28:55.082763Z","time spent":"362.686945ms","remote":"127.0.0.1:36832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:41452 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-05-19T19:28:55.487443Z","caller":"traceutil/trace.go:171","msg":"trace[1887305504] linearizableReadLoop","detail":"{readStateIndex:42834; appliedIndex:42832; }","duration":"216.402153ms","start":"2026-05-19T19:28:55.271013Z","end":"2026-05-19T19:28:55.487415Z","steps":["trace[1887305504] 'read index received'  (duration: 173.971599ms)","trace[1887305504] 'applied index is now lower than readState.Index'  (duration: 42.429344ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:28:55.487773Z","caller":"traceutil/trace.go:171","msg":"trace[140605795] transaction","detail":"{read_only:false; response_revision:41467; number_of_response:1; }","duration":"286.345281ms","start":"2026-05-19T19:28:55.201414Z","end":"2026-05-19T19:28:55.48776Z","steps":["trace[140605795] 'process raft request'  (duration: 285.82357ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:28:55.488031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.991706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:28:55.48882Z","caller":"traceutil/trace.go:171","msg":"trace[284790882] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:41467; }","duration":"217.791144ms","start":"2026-05-19T19:28:55.271007Z","end":"2026-05-19T19:28:55.488798Z","steps":["trace[284790882] 'agreement among raft nodes before linearized reading'  (duration: 216.963505ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:28:55.55561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.222314ms","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-05-19T19:28:55.555728Z","caller":"traceutil/trace.go:171","msg":"trace[993538766] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:41467; }","duration":"170.396758ms","start":"2026-05-19T19:28:55.3853Z","end":"2026-05-19T19:28:55.555697Z","steps":["trace[993538766] 'agreement among raft nodes before linearized reading'  (duration: 103.953831ms)","trace[993538766] 'range keys from in-memory index tree'  (duration: 66.08028ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:28:56.517088Z","caller":"traceutil/trace.go:171","msg":"trace[1253558271] transaction","detail":"{read_only:false; response_revision:41475; number_of_response:1; }","duration":"142.562346ms","start":"2026-05-19T19:28:56.374491Z","end":"2026-05-19T19:28:56.517054Z","steps":["trace[1253558271] 'process raft request'  (duration: 108.262677ms)","trace[1253558271] 'compare'  (duration: 34.184166ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:29:03.618742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.081707ms","expected-duration":"100ms","prefix":"","request":"header:<ID:6432440171452028146 > lease_revoke:<id:59449e416259d3f0>","response":"size:29"}
{"level":"info","ts":"2026-05-19T19:29:03.620874Z","caller":"traceutil/trace.go:171","msg":"trace[1368505509] transaction","detail":"{read_only:false; response_revision:41525; number_of_response:1; }","duration":"118.457019ms","start":"2026-05-19T19:29:03.502369Z","end":"2026-05-19T19:29:03.620826Z","steps":["trace[1368505509] 'process raft request'  (duration: 118.256704ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:29:03.620934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.043721ms","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-05-19T19:29:03.620995Z","caller":"traceutil/trace.go:171","msg":"trace[730122519] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:41525; }","duration":"100.151683ms","start":"2026-05-19T19:29:03.520824Z","end":"2026-05-19T19:29:03.620976Z","steps":["trace[730122519] 'agreement among raft nodes before linearized reading'  (duration: 100.0042ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:29:04.886152Z","caller":"traceutil/trace.go:171","msg":"trace[663109049] transaction","detail":"{read_only:false; response_revision:41536; number_of_response:1; }","duration":"105.104757ms","start":"2026-05-19T19:29:04.781017Z","end":"2026-05-19T19:29:04.886122Z","steps":["trace[663109049] 'process raft request'  (duration: 67.953494ms)","trace[663109049] 'compare'  (duration: 36.969439ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:29:14.552766Z","caller":"traceutil/trace.go:171","msg":"trace[299895339] transaction","detail":"{read_only:false; response_revision:41604; number_of_response:1; }","duration":"184.268223ms","start":"2026-05-19T19:29:14.368479Z","end":"2026-05-19T19:29:14.552747Z","steps":["trace[299895339] 'process raft request'  (duration: 184.12715ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:29:14.63249Z","caller":"traceutil/trace.go:171","msg":"trace[2058582513] transaction","detail":"{read_only:false; response_revision:41605; number_of_response:1; }","duration":"228.382954ms","start":"2026-05-19T19:29:14.40406Z","end":"2026-05-19T19:29:14.632443Z","steps":["trace[2058582513] 'process raft request'  (duration: 170.696024ms)","trace[2058582513] 'compare'  (duration: 57.469355ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:29:14.632477Z","caller":"traceutil/trace.go:171","msg":"trace[638961289] linearizableReadLoop","detail":"{readStateIndex:42977; appliedIndex:42975; }","duration":"188.267904ms","start":"2026-05-19T19:29:14.444163Z","end":"2026-05-19T19:29:14.632431Z","steps":["trace[638961289] 'read index received'  (duration: 108.474703ms)","trace[638961289] 'applied index is now lower than readState.Index'  (duration: 79.791181ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:29:14.632809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.617952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"}
{"level":"info","ts":"2026-05-19T19:29:14.632905Z","caller":"traceutil/trace.go:171","msg":"trace[1298145248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:41605; }","duration":"188.774425ms","start":"2026-05-19T19:29:14.444106Z","end":"2026-05-19T19:29:14.63288Z","steps":["trace[1298145248] 'agreement among raft nodes before linearized reading'  (duration: 188.446248ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:29:14.632971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.104355ms","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-05-19T19:29:14.633045Z","caller":"traceutil/trace.go:171","msg":"trace[161454691] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:41605; }","duration":"112.233528ms","start":"2026-05-19T19:29:14.52079Z","end":"2026-05-19T19:29:14.633024Z","steps":["trace[161454691] 'agreement among raft nodes before linearized reading'  (duration: 112.005543ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:31:14.08975Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":40291}
{"level":"info","ts":"2026-05-19T19:31:14.231225Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":40291,"took":"137.003321ms","hash":2651408058,"current-db-size-bytes":59461632,"current-db-size":"60 MB","current-db-size-in-use-bytes":31875072,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-05-19T19:31:14.231277Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2651408058,"revision":40291,"compact-revision":38091}
{"level":"info","ts":"2026-05-19T19:31:36.505539Z","caller":"traceutil/trace.go:171","msg":"trace[474099901] transaction","detail":"{read_only:false; response_revision:42801; number_of_response:1; }","duration":"128.450106ms","start":"2026-05-19T19:31:36.377061Z","end":"2026-05-19T19:31:36.505511Z","steps":["trace[474099901] 'process raft request'  (duration: 124.584429ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:31:36.999471Z","caller":"traceutil/trace.go:171","msg":"trace[206480088] transaction","detail":"{read_only:false; response_revision:42809; number_of_response:1; }","duration":"178.720527ms","start":"2026-05-19T19:31:36.820712Z","end":"2026-05-19T19:31:36.999433Z","steps":["trace[206480088] 'process raft request'  (duration: 154.478537ms)","trace[206480088] 'compare'  (duration: 23.986694ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:31:36.999702Z","caller":"traceutil/trace.go:171","msg":"trace[1460188893] linearizableReadLoop","detail":"{readStateIndex:44214; appliedIndex:44212; }","duration":"168.917456ms","start":"2026-05-19T19:31:36.830757Z","end":"2026-05-19T19:31:36.999675Z","steps":["trace[1460188893] 'read index received'  (duration: 144.44862ms)","trace[1460188893] 'applied index is now lower than readState.Index'  (duration: 24.467576ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:31:36.999805Z","caller":"traceutil/trace.go:171","msg":"trace[137410914] transaction","detail":"{read_only:false; response_revision:42810; number_of_response:1; }","duration":"178.009371ms","start":"2026-05-19T19:31:36.821768Z","end":"2026-05-19T19:31:36.999778Z","steps":["trace[137410914] 'process raft request'  (duration: 177.575991ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:31:36.999975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.194022ms","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-05-19T19:31:37.000048Z","caller":"traceutil/trace.go:171","msg":"trace[1070358358] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:42810; }","duration":"169.309985ms","start":"2026-05-19T19:31:36.830717Z","end":"2026-05-19T19:31:37.000027Z","steps":["trace[1070358358] 'agreement among raft nodes before linearized reading'  (duration: 169.10202ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:31:37.000068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.64616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:31:37.000124Z","caller":"traceutil/trace.go:171","msg":"trace[814340959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:42810; }","duration":"124.737062ms","start":"2026-05-19T19:31:36.87537Z","end":"2026-05-19T19:31:37.000107Z","steps":["trace[814340959] 'agreement among raft nodes before linearized reading'  (duration: 124.64302ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:31:37.000132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.553764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"}
{"level":"info","ts":"2026-05-19T19:31:37.000193Z","caller":"traceutil/trace.go:171","msg":"trace[400119463] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:42810; }","duration":"141.671916ms","start":"2026-05-19T19:31:36.858508Z","end":"2026-05-19T19:31:37.00018Z","steps":["trace[400119463] 'agreement among raft nodes before linearized reading'  (duration: 141.38363ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:31:37.137268Z","caller":"traceutil/trace.go:171","msg":"trace[57486075] transaction","detail":"{read_only:false; response_revision:42812; number_of_response:1; }","duration":"131.880144ms","start":"2026-05-19T19:31:37.005364Z","end":"2026-05-19T19:31:37.137244Z","steps":["trace[57486075] 'process raft request'  (duration: 131.65989ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:31:37.137492Z","caller":"traceutil/trace.go:171","msg":"trace[1151500271] transaction","detail":"{read_only:false; response_revision:42813; number_of_response:1; }","duration":"130.064423ms","start":"2026-05-19T19:31:37.007393Z","end":"2026-05-19T19:31:37.137458Z","steps":["trace[1151500271] 'process raft request'  (duration: 129.809428ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:31:37.137574Z","caller":"traceutil/trace.go:171","msg":"trace[1930846703] linearizableReadLoop","detail":"{readStateIndex:44216; appliedIndex:44214; }","duration":"130.655697ms","start":"2026-05-19T19:31:37.006739Z","end":"2026-05-19T19:31:37.137394Z","steps":["trace[1930846703] 'read index received'  (duration: 19.506373ms)","trace[1930846703] 'applied index is now lower than readState.Index'  (duration: 111.147974ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:31:37.138053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.018186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"}
{"level":"info","ts":"2026-05-19T19:31:37.138102Z","caller":"traceutil/trace.go:171","msg":"trace[1701489477] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:42813; }","duration":"113.107617ms","start":"2026-05-19T19:31:37.024981Z","end":"2026-05-19T19:31:37.138089Z","steps":["trace[1701489477] 'agreement among raft nodes before linearized reading'  (duration: 112.893723ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:31:37.14104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.333922ms","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:390333"}
{"level":"info","ts":"2026-05-19T19:31:37.141107Z","caller":"traceutil/trace.go:171","msg":"trace[1935302868] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:500; response_revision:42813; }","duration":"131.430575ms","start":"2026-05-19T19:31:37.009663Z","end":"2026-05-19T19:31:37.141093Z","steps":["trace[1935302868] 'agreement among raft nodes before linearized reading'  (duration: 128.210001ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:31:37.14124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.479773ms","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:390333"}
{"level":"info","ts":"2026-05-19T19:31:37.141301Z","caller":"traceutil/trace.go:171","msg":"trace[1326802491] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:500; response_revision:42813; }","duration":"134.556875ms","start":"2026-05-19T19:31:37.00673Z","end":"2026-05-19T19:31:37.141287Z","steps":["trace[1326802491] 'agreement among raft nodes before linearized reading'  (duration: 131.017105ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:31:37.995861Z","caller":"traceutil/trace.go:171","msg":"trace[1115406753] transaction","detail":"{read_only:false; response_revision:42826; number_of_response:1; }","duration":"120.642129ms","start":"2026-05-19T19:31:37.875199Z","end":"2026-05-19T19:31:37.995841Z","steps":["trace[1115406753] 'process raft request'  (duration: 120.297112ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:31:39.186972Z","caller":"traceutil/trace.go:171","msg":"trace[1489640333] linearizableReadLoop","detail":"{readStateIndex:44245; appliedIndex:44244; }","duration":"108.283158ms","start":"2026-05-19T19:31:39.078663Z","end":"2026-05-19T19:31:39.186946Z","steps":["trace[1489640333] 'read index received'  (duration: 97.79774ms)","trace[1489640333] 'applied index is now lower than readState.Index'  (duration: 10.484368ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:31:39.187035Z","caller":"traceutil/trace.go:171","msg":"trace[1194229397] transaction","detail":"{read_only:false; number_of_response:1; response_revision:42840; }","duration":"112.351691ms","start":"2026-05-19T19:31:39.074649Z","end":"2026-05-19T19:31:39.187001Z","steps":["trace[1194229397] 'process raft request'  (duration: 101.768501ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:31:39.189419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.732273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-ks-user-7b4vl.18b10c5b75f26e21\\000\" range_end:\"/registry/events/openstack0\" limit:1000 revision:42839 ","response":"range_response_count:1000 size:780690"}
{"level":"info","ts":"2026-05-19T19:31:39.189478Z","caller":"traceutil/trace.go:171","msg":"trace[1175013746] range","detail":"{range_begin:/registry/events/openstack/magnum-ks-user-7b4vl.18b10c5b75f26e21\u0000; range_end:/registry/events/openstack0; response_count:1000; response_revision:42840; }","duration":"110.809775ms","start":"2026-05-19T19:31:39.078655Z","end":"2026-05-19T19:31:39.189465Z","steps":["trace[1175013746] 'agreement among raft nodes before linearized reading'  (duration: 108.41581ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:31:39.382639Z","caller":"traceutil/trace.go:171","msg":"trace[1283374365] transaction","detail":"{read_only:false; response_revision:42843; number_of_response:1; }","duration":"104.175905ms","start":"2026-05-19T19:31:39.278427Z","end":"2026-05-19T19:31:39.382603Z","steps":["trace[1283374365] 'process raft request'  (duration: 103.95857ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:32:21.955513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.424376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-05-19T19:32:21.955632Z","caller":"traceutil/trace.go:171","msg":"trace[731243059] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:43220; }","duration":"106.58431ms","start":"2026-05-19T19:32:21.84902Z","end":"2026-05-19T19:32:21.955605Z","steps":["trace[731243059] 'count revisions from in-memory index tree'  (duration: 106.329904ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:32:21.955645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.910121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/cinder-cinder-volume-usage-audit\" ","response":"range_response_count:1 size:781"}
{"level":"info","ts":"2026-05-19T19:32:21.955761Z","caller":"traceutil/trace.go:171","msg":"trace[1420610537] range","detail":"{range_begin:/registry/rolebindings/openstack/cinder-cinder-volume-usage-audit; range_end:; response_count:1; response_revision:43220; }","duration":"105.064775ms","start":"2026-05-19T19:32:21.850667Z","end":"2026-05-19T19:32:21.955732Z","steps":["trace[1420610537] 'range keys from in-memory index tree'  (duration: 104.740897ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:32:22.173457Z","caller":"traceutil/trace.go:171","msg":"trace[1275339776] transaction","detail":"{read_only:false; response_revision:43223; number_of_response:1; }","duration":"178.693117ms","start":"2026-05-19T19:32:21.994738Z","end":"2026-05-19T19:32:22.173431Z","steps":["trace[1275339776] 'process raft request'  (duration: 178.506743ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:32:22.204405Z","caller":"traceutil/trace.go:171","msg":"trace[26969270] linearizableReadLoop","detail":"{readStateIndex:44637; appliedIndex:44635; }","duration":"154.955188ms","start":"2026-05-19T19:32:22.049425Z","end":"2026-05-19T19:32:22.20438Z","steps":["trace[26969270] 'read index received'  (duration: 123.889712ms)","trace[26969270] 'applied index is now lower than readState.Index'  (duration: 31.064085ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:32:22.204517Z","caller":"traceutil/trace.go:171","msg":"trace[1016844882] transaction","detail":"{read_only:false; response_revision:43224; number_of_response:1; }","duration":"207.559042ms","start":"2026-05-19T19:32:21.996927Z","end":"2026-05-19T19:32:22.204486Z","steps":["trace[1016844882] 'process raft request'  (duration: 207.293306ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:32:22.20473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.469539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/glance-glance-api\" ","response":"range_response_count:1 size:721"}
{"level":"warn","ts":"2026-05-19T19:32:22.204724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.268904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:531"}
{"level":"info","ts":"2026-05-19T19:32:22.204814Z","caller":"traceutil/trace.go:171","msg":"trace[2113476808] range","detail":"{range_begin:/registry/rolebindings/openstack/glance-glance-api; range_end:; response_count:1; response_revision:43224; }","duration":"151.619382ms","start":"2026-05-19T19:32:22.053178Z","end":"2026-05-19T19:32:22.204797Z","steps":["trace[2113476808] 'agreement among raft nodes before linearized reading'  (duration: 151.335926ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:32:22.204802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.595726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/glance-glance-db-init\" ","response":"range_response_count:1 size:737"}
{"level":"info","ts":"2026-05-19T19:32:22.204822Z","caller":"traceutil/trace.go:171","msg":"trace[45777221] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:43224; }","duration":"155.410098ms","start":"2026-05-19T19:32:22.049387Z","end":"2026-05-19T19:32:22.204797Z","steps":["trace[45777221] 'agreement among raft nodes before linearized reading'  (duration: 155.132632ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:32:22.204881Z","caller":"traceutil/trace.go:171","msg":"trace[2020145757] range","detail":"{range_begin:/registry/rolebindings/openstack/glance-glance-db-init; range_end:; response_count:1; response_revision:43224; }","duration":"145.698978ms","start":"2026-05-19T19:32:22.05916Z","end":"2026-05-19T19:32:22.204859Z","steps":["trace[2020145757] 'agreement among raft nodes before linearized reading'  (duration: 145.477993ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:32:31.505936Z","caller":"traceutil/trace.go:171","msg":"trace[329459488] transaction","detail":"{read_only:false; response_revision:43290; number_of_response:1; }","duration":"117.038568ms","start":"2026-05-19T19:32:31.388872Z","end":"2026-05-19T19:32:31.505911Z","steps":["trace[329459488] 'process raft request'  (duration: 116.830642ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:32:31.942557Z","caller":"traceutil/trace.go:171","msg":"trace[1685646909] linearizableReadLoop","detail":"{readStateIndex:44708; appliedIndex:44707; }","duration":"109.734971ms","start":"2026-05-19T19:32:31.832794Z","end":"2026-05-19T19:32:31.942529Z","steps":["trace[1685646909] 'read index received'  (duration: 38.176027ms)","trace[1685646909] 'applied index is now lower than readState.Index'  (duration: 71.557784ms)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:32:31.942635Z","caller":"traceutil/trace.go:171","msg":"trace[322946533] transaction","detail":"{read_only:false; response_revision:43293; number_of_response:1; }","duration":"140.364777ms","start":"2026-05-19T19:32:31.802241Z","end":"2026-05-19T19:32:31.942606Z","steps":["trace[322946533] 'process raft request'  (duration: 68.811093ms)","trace[322946533] 'compare'  (duration: 71.335019ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-19T19:32:31.942811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.646182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/heat-openstack-heat-engine-cleaner\" ","response":"range_response_count:1 size:689"}
{"level":"info","ts":"2026-05-19T19:32:31.942855Z","caller":"traceutil/trace.go:171","msg":"trace[2036810349] range","detail":"{range_begin:/registry/roles/openstack/heat-openstack-heat-engine-cleaner; range_end:; response_count:1; response_revision:43293; }","duration":"106.737023ms","start":"2026-05-19T19:32:31.836105Z","end":"2026-05-19T19:32:31.942842Z","steps":["trace[2036810349] 'agreement among raft nodes before linearized reading'  (duration: 106.576839ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:32:31.943183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.356986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/openstack/manila-manila-db-init\" ","response":"range_response_count:1 size:739"}
{"level":"info","ts":"2026-05-19T19:32:31.943249Z","caller":"traceutil/trace.go:171","msg":"trace[967883931] range","detail":"{range_begin:/registry/rolebindings/openstack/manila-manila-db-init; range_end:; response_count:1; response_revision:43293; }","duration":"110.475139ms","start":"2026-05-19T19:32:31.832759Z","end":"2026-05-19T19:32:31.943234Z","steps":["trace[967883931] 'agreement among raft nodes before linearized reading'  (duration: 109.881114ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:32:32.185074Z","caller":"traceutil/trace.go:171","msg":"trace[1214818077] transaction","detail":"{read_only:false; response_revision:43294; number_of_response:1; }","duration":"102.405835ms","start":"2026-05-19T19:32:32.082638Z","end":"2026-05-19T19:32:32.185043Z","steps":["trace[1214818077] 'process raft request'  (duration: 95.044598ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:32:40.005823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.928739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-19T19:32:40.005911Z","caller":"traceutil/trace.go:171","msg":"trace[364238463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:43353; }","duration":"137.056962ms","start":"2026-05-19T19:32:39.868834Z","end":"2026-05-19T19:32:40.005891Z","steps":["trace[364238463] 'range keys from in-memory index tree'  (duration: 136.864057ms)"],"step_count":1}
{"level":"info","ts":"2026-05-19T19:32:40.124427Z","caller":"traceutil/trace.go:171","msg":"trace[730977582] linearizableReadLoop","detail":"{readStateIndex:44771; appliedIndex:44770; }","duration":"114.710164ms","start":"2026-05-19T19:32:40.009691Z","end":"2026-05-19T19:32:40.124401Z","steps":["trace[730977582] 'read index received'  (duration: 114.53178ms)","trace[730977582] 'applied index is now lower than readState.Index'  (duration: 177.114µs)"],"step_count":2}
{"level":"info","ts":"2026-05-19T19:32:40.124489Z","caller":"traceutil/trace.go:171","msg":"trace[887638594] transaction","detail":"{read_only:false; response_revision:43354; number_of_response:1; }","duration":"135.651369ms","start":"2026-05-19T19:32:39.988795Z","end":"2026-05-19T19:32:40.124446Z","steps":["trace[887638594] 'process raft request'  (duration: 135.459625ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:32:40.12465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.943129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/manila-openstack-manila-db-init\" ","response":"range_response_count:1 size:673"}
{"level":"info","ts":"2026-05-19T19:32:40.124692Z","caller":"traceutil/trace.go:171","msg":"trace[1108382454] range","detail":"{range_begin:/registry/roles/openstack/manila-openstack-manila-db-init; range_end:; response_count:1; response_revision:43354; }","duration":"115.012481ms","start":"2026-05-19T19:32:40.009668Z","end":"2026-05-19T19:32:40.124681Z","steps":["trace[1108382454] 'agreement among raft nodes before linearized reading'  (duration: 114.865948ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-19T19:32:40.124788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.394591ms","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":"info","ts":"2026-05-19T19:32:40.124874Z","caller":"traceutil/trace.go:171","msg":"trace[1987138292] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:43354; }","duration":"108.489563ms","start":"2026-05-19T19:32:40.016369Z","end":"2026-05-19T19:32:40.124858Z","steps":["trace[1987138292] 'agreement among raft nodes before linearized reading'  (duration: 108.300959ms)"],"step_count":1}