{"level":"warn","ts":"2026-07-02T01:49:01.05967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.048772ms","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-07-02T01:49:01.059732Z","caller":"traceutil/trace.go:171","msg":"trace[406363641] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:40341; }","duration":"554.162575ms","start":"2026-07-02T01:49:00.505556Z","end":"2026-07-02T01:49:01.059718Z","steps":["trace[406363641] 'range keys from in-memory index tree' (duration: 553.899208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T01:49:01.059762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T01:49:00.505534Z","time spent":"554.220115ms","remote":"127.0.0.1:58994","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":"info","ts":"2026-07-02T01:49:01.059775Z","caller":"traceutil/trace.go:171","msg":"trace[762780922] transaction","detail":"{read_only:false; response_revision:40342; number_of_response:1; }","duration":"546.08528ms","start":"2026-07-02T01:49:00.513668Z","end":"2026-07-02T01:49:01.059753Z","steps":["trace[762780922] 'process raft request' (duration: 469.839197ms)","trace[762780922] 'compare' (duration: 76.10228ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T01:49:01.060943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T01:49:00.513658Z","time spent":"546.209383ms","remote":"127.0.0.1:58994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T01:49:01.422313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.499566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T01:49:01.42284Z","caller":"traceutil/trace.go:171","msg":"trace[1430977662] transaction","detail":"{read_only:false; response_revision:40343; number_of_response:1; }","duration":"812.174803ms","start":"2026-07-02T01:49:00.610628Z","end":"2026-07-02T01:49:01.422802Z","steps":["trace[1430977662] 'process raft request' (duration: 638.077473ms)","trace[1430977662] 'compare' (duration: 173.221849ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T01:49:01.422853Z","caller":"traceutil/trace.go:171","msg":"trace[1031178834] transaction","detail":"{read_only:false; response_revision:40344; number_of_response:1; }","duration":"619.03949ms","start":"2026-07-02T01:49:00.803785Z","end":"2026-07-02T01:49:01.422824Z","steps":["trace[1031178834] 'process raft request' (duration: 618.827836ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T01:49:01.42291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T01:49:00.610611Z","time spent":"812.266516ms","remote":"127.0.0.1:58994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T01:49:01.422993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T01:49:00.803759Z","time spent":"619.156783ms","remote":"127.0.0.1:58994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T01:49:01.768344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.590151ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-02T01:49:01.768892Z","caller":"traceutil/trace.go:171","msg":"trace[2027705439] linearizableReadLoop","detail":"{readStateIndex:41667; appliedIndex:41664; }","duration":"942.808794ms","start":"2026-07-02T01:49:00.826052Z","end":"2026-07-02T01:49:01.768861Z","steps":["trace[2027705439] 'read index received' (duration: 157.451931ms)","trace[2027705439] 'applied index is now lower than readState.Index' (duration: 785.344123ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T01:49:01.768991Z","caller":"traceutil/trace.go:171","msg":"trace[1908597278] transaction","detail":"{read_only:false; response_revision:40345; number_of_response:1; }","duration":"705.888966ms","start":"2026-07-02T01:49:01.063083Z","end":"2026-07-02T01:49:01.768972Z","steps":["trace[1908597278] 'process raft request' (duration: 705.374815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T01:49:01.769104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T01:49:01.063051Z","time spent":"705.990319ms","remote":"127.0.0.1:58994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T01:49:01.769888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.812017ms","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-07-02T01:49:01.770015Z","caller":"traceutil/trace.go:171","msg":"trace[1545169187] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:40345; }","duration":"943.93637ms","start":"2026-07-02T01:49:00.826015Z","end":"2026-07-02T01:49:01.769951Z","steps":["trace[1545169187] 'agreement among raft nodes before linearized reading' (duration: 942.916816ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:49:01.77002Z","caller":"traceutil/trace.go:171","msg":"trace[367580771] transaction","detail":"{read_only:false; response_revision:40346; number_of_response:1; }","duration":"472.975658ms","start":"2026-07-02T01:49:01.297024Z","end":"2026-07-02T01:49:01.77Z","steps":["trace[367580771] 'process raft request' (duration: 471.904283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T01:49:01.770093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T01:49:00.825996Z","time spent":"944.057434ms","remote":"127.0.0.1:58994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":596,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-07-02T01:49:01.770184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T01:49:01.297Z","time spent":"473.058971ms","remote":"127.0.0.1:58994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T01:49:01.770206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.27011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-07-02T01:49:01.770514Z","caller":"traceutil/trace.go:171","msg":"trace[1652939334] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:40346; }","duration":"715.635599ms","start":"2026-07-02T01:49:01.054865Z","end":"2026-07-02T01:49:01.770501Z","steps":["trace[1652939334] 'agreement among raft nodes before linearized reading' (duration: 715.098266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T01:49:01.770557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T01:49:01.05484Z","time spent":"715.70485ms","remote":"127.0.0.1:58846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":379,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-07-02T01:49:01.770253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.468511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T01:49:01.770716Z","caller":"traceutil/trace.go:171","msg":"trace[1165279700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:40346; }","duration":"652.981793ms","start":"2026-07-02T01:49:01.117706Z","end":"2026-07-02T01:49:01.770688Z","steps":["trace[1165279700] 'agreement among raft nodes before linearized reading' (duration: 652.39915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T01:49:01.770785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T01:49:01.117683Z","time spent":"653.086355ms","remote":"127.0.0.1:58770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T01:49:02.216221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.284046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T01:49:02.216286Z","caller":"traceutil/trace.go:171","msg":"trace[1824274225] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:40347; }","duration":"295.417029ms","start":"2026-07-02T01:49:01.920855Z","end":"2026-07-02T01:49:02.216272Z","steps":["trace[1824274225] 'count revisions from in-memory index tree' (duration: 295.174884ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:49:02.312182Z","caller":"traceutil/trace.go:171","msg":"trace[333507260] transaction","detail":"{read_only:false; response_revision:40348; number_of_response:1; }","duration":"252.854772ms","start":"2026-07-02T01:49:02.05931Z","end":"2026-07-02T01:49:02.312165Z","steps":["trace[333507260] 'process raft request' (duration: 252.7502ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:49:02.312445Z","caller":"traceutil/trace.go:171","msg":"trace[205434900] linearizableReadLoop","detail":"{readStateIndex:41672; appliedIndex:41672; }","duration":"194.530674ms","start":"2026-07-02T01:49:02.117884Z","end":"2026-07-02T01:49:02.312414Z","steps":["trace[205434900] 'read index received' (duration: 194.36013ms)","trace[205434900] 'applied index is now lower than readState.Index' (duration: 166.754µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T01:49:02.312626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.707669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T01:49:02.312658Z","caller":"traceutil/trace.go:171","msg":"trace[256239528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:40348; }","duration":"194.79512ms","start":"2026-07-02T01:49:02.117854Z","end":"2026-07-02T01:49:02.312649Z","steps":["trace[256239528] 'agreement among raft nodes before linearized reading' (duration: 194.691478ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:49:02.443603Z","caller":"traceutil/trace.go:171","msg":"trace[578685110] linearizableReadLoop","detail":"{readStateIndex:41675; appliedIndex:41672; }","duration":"131.086502ms","start":"2026-07-02T01:49:02.312501Z","end":"2026-07-02T01:49:02.443587Z","steps":["trace[578685110] 'read index received' (duration: 125.962725ms)","trace[578685110] 'applied index is now lower than readState.Index' (duration: 5.123097ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T01:49:02.443997Z","caller":"traceutil/trace.go:171","msg":"trace[1580263157] transaction","detail":"{read_only:false; response_revision:40349; number_of_response:1; }","duration":"286.583478ms","start":"2026-07-02T01:49:02.157397Z","end":"2026-07-02T01:49:02.44398Z","steps":["trace[1580263157] 'process raft request' (duration: 281.119844ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:49:02.444061Z","caller":"traceutil/trace.go:171","msg":"trace[670405043] transaction","detail":"{read_only:false; response_revision:40351; number_of_response:1; }","duration":"286.472536ms","start":"2026-07-02T01:49:02.157561Z","end":"2026-07-02T01:49:02.444033Z","steps":["trace[670405043] 'process raft request' (duration: 285.991205ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:49:02.444117Z","caller":"traceutil/trace.go:171","msg":"trace[1574507470] transaction","detail":"{read_only:false; response_revision:40350; number_of_response:1; }","duration":"286.572548ms","start":"2026-07-02T01:49:02.157538Z","end":"2026-07-02T01:49:02.44411Z","steps":["trace[1574507470] 'process raft request' (duration: 285.968035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T01:49:02.444207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.478853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-02T01:49:02.445683Z","caller":"traceutil/trace.go:171","msg":"trace[1026940982] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:40351; }","duration":"196.013758ms","start":"2026-07-02T01:49:02.249655Z","end":"2026-07-02T01:49:02.445668Z","steps":["trace[1026940982] 'agreement among raft nodes before linearized reading' (duration: 194.377261ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T01:49:02.444258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.559946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T01:49:02.445863Z","caller":"traceutil/trace.go:171","msg":"trace[322256298] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:40351; }","duration":"229.195523ms","start":"2026-07-02T01:49:02.216657Z","end":"2026-07-02T01:49:02.445852Z","steps":["trace[322256298] 'agreement among raft nodes before linearized reading' (duration: 227.556896ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:49:05.635151Z","caller":"traceutil/trace.go:171","msg":"trace[1134751372] transaction","detail":"{read_only:false; response_revision:40372; number_of_response:1; }","duration":"188.954648ms","start":"2026-07-02T01:49:05.446177Z","end":"2026-07-02T01:49:05.635132Z","steps":["trace[1134751372] 'process raft request' (duration: 188.851515ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:49:05.636469Z","caller":"traceutil/trace.go:171","msg":"trace[589570656] linearizableReadLoop","detail":"{readStateIndex:41697; appliedIndex:41696; }","duration":"155.786554ms","start":"2026-07-02T01:49:05.480662Z","end":"2026-07-02T01:49:05.636448Z","steps":["trace[589570656] 'read index received' (duration: 154.461903ms)","trace[589570656] 'applied index is now lower than readState.Index' (duration: 1.323641ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T01:49:05.636515Z","caller":"traceutil/trace.go:171","msg":"trace[1074388575] transaction","detail":"{read_only:false; response_revision:40373; number_of_response:1; }","duration":"181.252694ms","start":"2026-07-02T01:49:05.455238Z","end":"2026-07-02T01:49:05.636491Z","steps":["trace[1074388575] 'process raft request' (duration: 181.042169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T01:49:05.636678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.003109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:141"} {"level":"info","ts":"2026-07-02T01:49:05.636718Z","caller":"traceutil/trace.go:171","msg":"trace[939936593] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:40374; }","duration":"156.087781ms","start":"2026-07-02T01:49:05.48062Z","end":"2026-07-02T01:49:05.636708Z","steps":["trace[939936593] 'agreement among raft nodes before linearized reading' (duration: 155.915257ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:49:07.396138Z","caller":"traceutil/trace.go:171","msg":"trace[1607780975] transaction","detail":"{read_only:false; response_revision:40389; number_of_response:1; }","duration":"129.015744ms","start":"2026-07-02T01:49:07.267103Z","end":"2026-07-02T01:49:07.396119Z","steps":["trace[1607780975] 'process raft request' (duration: 128.419911ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:49:09.950312Z","caller":"traceutil/trace.go:171","msg":"trace[1357949204] linearizableReadLoop","detail":"{readStateIndex:41730; appliedIndex:41729; }","duration":"103.510505ms","start":"2026-07-02T01:49:09.846784Z","end":"2026-07-02T01:49:09.950295Z","steps":["trace[1357949204] 'read index received' (duration: 103.29897ms)","trace[1357949204] 'applied index is now lower than readState.Index' (duration: 209.005µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T01:49:09.950488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.695208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-07-02T01:49:09.950517Z","caller":"traceutil/trace.go:171","msg":"trace[705658462] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:40405; }","duration":"103.77345ms","start":"2026-07-02T01:49:09.846736Z","end":"2026-07-02T01:49:09.950509Z","steps":["trace[705658462] 'agreement among raft nodes before linearized reading' (duration: 103.636737ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:49:09.950796Z","caller":"traceutil/trace.go:171","msg":"trace[172955726] transaction","detail":"{read_only:false; response_revision:40405; number_of_response:1; }","duration":"107.827743ms","start":"2026-07-02T01:49:09.842958Z","end":"2026-07-02T01:49:09.950785Z","steps":["trace[172955726] 'process raft request' (duration: 107.190898ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:49:10.076425Z","caller":"traceutil/trace.go:171","msg":"trace[5006302] transaction","detail":"{read_only:false; response_revision:40406; number_of_response:1; }","duration":"108.820435ms","start":"2026-07-02T01:49:09.967579Z","end":"2026-07-02T01:49:10.0764Z","steps":["trace[5006302] 'process raft request' (duration: 108.118989ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:49:10.760831Z","caller":"traceutil/trace.go:171","msg":"trace[196995799] transaction","detail":"{read_only:false; response_revision:40411; number_of_response:1; }","duration":"142.89702ms","start":"2026-07-02T01:49:10.617906Z","end":"2026-07-02T01:49:10.760803Z","steps":["trace[196995799] 'process raft request' (duration: 142.501181ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:49:10.793272Z","caller":"traceutil/trace.go:171","msg":"trace[1976581215] transaction","detail":"{read_only:false; response_revision:40415; number_of_response:1; }","duration":"172.833381ms","start":"2026-07-02T01:49:10.62042Z","end":"2026-07-02T01:49:10.793253Z","steps":["trace[1976581215] 'process raft request' (duration: 172.80349ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:49:10.793367Z","caller":"traceutil/trace.go:171","msg":"trace[2072768425] transaction","detail":"{read_only:false; response_revision:40413; number_of_response:1; }","duration":"173.919096ms","start":"2026-07-02T01:49:10.619414Z","end":"2026-07-02T01:49:10.793333Z","steps":["trace[2072768425] 'process raft request' (duration: 173.755042ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:49:10.793275Z","caller":"traceutil/trace.go:171","msg":"trace[1306520919] transaction","detail":"{read_only:false; response_revision:40412; number_of_response:1; }","duration":"174.674474ms","start":"2026-07-02T01:49:10.61857Z","end":"2026-07-02T01:49:10.793244Z","steps":["trace[1306520919] 'process raft request' (duration: 174.5017ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:49:10.79334Z","caller":"traceutil/trace.go:171","msg":"trace[579412205] transaction","detail":"{read_only:false; response_revision:40414; number_of_response:1; }","duration":"172.980075ms","start":"2026-07-02T01:49:10.620353Z","end":"2026-07-02T01:49:10.793333Z","steps":["trace[579412205] 'process raft request' (duration: 172.838302ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:49:12.60762Z","caller":"traceutil/trace.go:171","msg":"trace[984307979] transaction","detail":"{read_only:false; response_revision:40424; number_of_response:1; }","duration":"102.887899ms","start":"2026-07-02T01:49:12.504705Z","end":"2026-07-02T01:49:12.607593Z","steps":["trace[984307979] 'process raft request' (duration: 88.694677ms)","trace[984307979] 'compare' (duration: 14.08578ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T01:49:27.303533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.60359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T01:49:27.303756Z","caller":"traceutil/trace.go:171","msg":"trace[1303184959] transaction","detail":"{read_only:false; response_revision:40532; number_of_response:1; }","duration":"173.466565ms","start":"2026-07-02T01:49:27.130251Z","end":"2026-07-02T01:49:27.303718Z","steps":["trace[1303184959] 'process raft request' (duration: 64.305052ms)","trace[1303184959] 'compare' (duration: 108.439747ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T01:49:27.303798Z","caller":"traceutil/trace.go:171","msg":"trace[1588871799] transaction","detail":"{read_only:false; response_revision:40533; number_of_response:1; }","duration":"172.80406ms","start":"2026-07-02T01:49:27.130975Z","end":"2026-07-02T01:49:27.303779Z","steps":["trace[1588871799] 'process raft request' (duration: 172.662677ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:49:27.303889Z","caller":"traceutil/trace.go:171","msg":"trace[1185030835] transaction","detail":"{read_only:false; response_revision:40534; number_of_response:1; }","duration":"172.81563ms","start":"2026-07-02T01:49:27.131048Z","end":"2026-07-02T01:49:27.303864Z","steps":["trace[1185030835] 'process raft request' (duration: 172.650976ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:49:27.305092Z","caller":"traceutil/trace.go:171","msg":"trace[1923171280] linearizableReadLoop","detail":"{readStateIndex:41864; appliedIndex:41860; }","duration":"170.801425ms","start":"2026-07-02T01:49:27.134276Z","end":"2026-07-02T01:49:27.305077Z","steps":["trace[1923171280] 'read index received' (duration: 60.268511ms)","trace[1923171280] 'applied index is now lower than readState.Index' (duration: 110.531894ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T01:49:27.305272Z","caller":"traceutil/trace.go:171","msg":"trace[219609910] transaction","detail":"{read_only:false; response_revision:40535; number_of_response:1; }","duration":"174.071169ms","start":"2026-07-02T01:49:27.131137Z","end":"2026-07-02T01:49:27.305249Z","steps":["trace[219609910] 'process raft request' (duration: 172.560344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T01:49:27.305441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.926218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T01:49:27.305513Z","caller":"traceutil/trace.go:171","msg":"trace[690717450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:40535; }","duration":"171.209034ms","start":"2026-07-02T01:49:27.134253Z","end":"2026-07-02T01:49:27.305462Z","steps":["trace[690717450] 'agreement among raft nodes before linearized reading' (duration: 170.883427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T01:52:31.514914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.248127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T01:52:31.515008Z","caller":"traceutil/trace.go:171","msg":"trace[1280362594] transaction","detail":"{read_only:false; response_revision:41979; number_of_response:1; }","duration":"121.617966ms","start":"2026-07-02T01:52:31.393376Z","end":"2026-07-02T01:52:31.514994Z","steps":["trace[1280362594] 'process raft request' (duration: 15.222526ms)","trace[1280362594] 'compare' (duration: 106.007902ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T01:52:31.707161Z","caller":"traceutil/trace.go:171","msg":"trace[754861780] transaction","detail":"{read_only:false; response_revision:41981; number_of_response:1; }","duration":"125.121926ms","start":"2026-07-02T01:52:31.582012Z","end":"2026-07-02T01:52:31.707134Z","steps":["trace[754861780] 'process raft request' (duration: 96.550956ms)","trace[754861780] 'compare' (duration: 28.18305ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T01:52:31.707278Z","caller":"traceutil/trace.go:171","msg":"trace[1488566491] transaction","detail":"{read_only:false; response_revision:41982; number_of_response:1; }","duration":"125.182748ms","start":"2026-07-02T01:52:31.582075Z","end":"2026-07-02T01:52:31.707258Z","steps":["trace[1488566491] 'process raft request' (duration: 124.986403ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:52:33.577389Z","caller":"traceutil/trace.go:171","msg":"trace[705945158] linearizableReadLoop","detail":"{readStateIndex:43362; appliedIndex:43361; }","duration":"105.192612ms","start":"2026-07-02T01:52:33.472156Z","end":"2026-07-02T01:52:33.577349Z","steps":["trace[705945158] 'read index received' (duration: 104.939737ms)","trace[705945158] 'applied index is now lower than readState.Index' (duration: 251.975µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T01:52:33.577434Z","caller":"traceutil/trace.go:171","msg":"trace[1959706975] transaction","detail":"{read_only:false; response_revision:41992; number_of_response:1; }","duration":"213.794213ms","start":"2026-07-02T01:52:33.363617Z","end":"2026-07-02T01:52:33.577411Z","steps":["trace[1959706975] 'process raft request' (duration: 213.562118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T01:52:33.577542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.370816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T01:52:33.577575Z","caller":"traceutil/trace.go:171","msg":"trace[831250464] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:41992; }","duration":"105.426147ms","start":"2026-07-02T01:52:33.47214Z","end":"2026-07-02T01:52:33.577566Z","steps":["trace[831250464] 'agreement among raft nodes before linearized reading' (duration: 105.347826ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:52:33.843966Z","caller":"traceutil/trace.go:171","msg":"trace[349359743] transaction","detail":"{read_only:false; response_revision:41995; number_of_response:1; }","duration":"130.797454ms","start":"2026-07-02T01:52:33.713153Z","end":"2026-07-02T01:52:33.84395Z","steps":["trace[349359743] 'process raft request' (duration: 130.747253ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:52:33.84399Z","caller":"traceutil/trace.go:171","msg":"trace[1850424574] transaction","detail":"{read_only:false; response_revision:41993; number_of_response:1; }","duration":"261.13362ms","start":"2026-07-02T01:52:33.582823Z","end":"2026-07-02T01:52:33.843956Z","steps":["trace[1850424574] 'process raft request' (duration: 254.632722ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:52:33.844128Z","caller":"traceutil/trace.go:171","msg":"trace[827408001] transaction","detail":"{read_only:false; response_revision:41996; number_of_response:1; }","duration":"130.401606ms","start":"2026-07-02T01:52:33.713709Z","end":"2026-07-02T01:52:33.844111Z","steps":["trace[827408001] 'process raft request' (duration: 130.210452ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:52:33.844066Z","caller":"traceutil/trace.go:171","msg":"trace[1177197744] transaction","detail":"{read_only:false; response_revision:41994; number_of_response:1; }","duration":"233.666295ms","start":"2026-07-02T01:52:33.610344Z","end":"2026-07-02T01:52:33.844011Z","steps":["trace[1177197744] 'process raft request' (duration: 233.522152ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:52:43.888628Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39791} {"level":"info","ts":"2026-07-02T01:52:44.018718Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39791,"took":"125.907924ms","hash":323744769,"current-db-size-bytes":54652928,"current-db-size":"55 MB","current-db-size-in-use-bytes":33337344,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-02T01:52:44.018844Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":323744769,"revision":39791,"compact-revision":37537} {"level":"warn","ts":"2026-07-02T01:52:50.575738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.505519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T01:52:50.575835Z","caller":"traceutil/trace.go:171","msg":"trace[737776142] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:42116; }","duration":"185.658642ms","start":"2026-07-02T01:52:50.390155Z","end":"2026-07-02T01:52:50.575814Z","steps":["trace[737776142] 'count revisions from in-memory index tree' (duration: 185.419217ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:53:12.157799Z","caller":"traceutil/trace.go:171","msg":"trace[1361598240] transaction","detail":"{read_only:false; response_revision:42272; number_of_response:1; }","duration":"108.705603ms","start":"2026-07-02T01:53:12.049069Z","end":"2026-07-02T01:53:12.157775Z","steps":["trace[1361598240] 'process raft request' (duration: 93.254252ms)","trace[1361598240] 'compare' (duration: 15.276267ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T01:53:12.158091Z","caller":"traceutil/trace.go:171","msg":"trace[1651726085] transaction","detail":"{read_only:false; response_revision:42274; number_of_response:1; }","duration":"106.706077ms","start":"2026-07-02T01:53:12.051361Z","end":"2026-07-02T01:53:12.158067Z","steps":["trace[1651726085] 'process raft request' (duration: 106.573944ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:53:12.158068Z","caller":"traceutil/trace.go:171","msg":"trace[166565190] transaction","detail":"{read_only:false; response_revision:42273; number_of_response:1; }","duration":"107.092056ms","start":"2026-07-02T01:53:12.050951Z","end":"2026-07-02T01:53:12.158043Z","steps":["trace[166565190] 'process raft request' (duration: 106.781999ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:53:17.166853Z","caller":"traceutil/trace.go:171","msg":"trace[73618772] linearizableReadLoop","detail":"{readStateIndex:43693; appliedIndex:43691; }","duration":"114.832532ms","start":"2026-07-02T01:53:17.052Z","end":"2026-07-02T01:53:17.166832Z","steps":["trace[73618772] 'read index received' (duration: 47.48684ms)","trace[73618772] 'applied index is now lower than readState.Index' (duration: 67.344822ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T01:53:17.167203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.18085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T01:53:17.16728Z","caller":"traceutil/trace.go:171","msg":"trace[301046540] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:42313; }","duration":"115.271992ms","start":"2026-07-02T01:53:17.051992Z","end":"2026-07-02T01:53:17.167264Z","steps":["trace[301046540] 'agreement among raft nodes before linearized reading' (duration: 115.145489ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:53:17.167273Z","caller":"traceutil/trace.go:171","msg":"trace[1174533978] transaction","detail":"{read_only:false; response_revision:42312; number_of_response:1; }","duration":"164.395999ms","start":"2026-07-02T01:53:17.002842Z","end":"2026-07-02T01:53:17.167238Z","steps":["trace[1174533978] 'process raft request' (duration: 96.669879ms)","trace[1174533978] 'compare' (duration: 67.167928ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T01:53:17.167294Z","caller":"traceutil/trace.go:171","msg":"trace[1884297638] transaction","detail":"{read_only:false; response_revision:42313; number_of_response:1; }","duration":"156.558181ms","start":"2026-07-02T01:53:17.010718Z","end":"2026-07-02T01:53:17.167276Z","steps":["trace[1884297638] 'process raft request' (duration: 156.07631ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:53:19.126951Z","caller":"traceutil/trace.go:171","msg":"trace[78007183] transaction","detail":"{read_only:false; response_revision:42323; number_of_response:1; }","duration":"117.969633ms","start":"2026-07-02T01:53:19.008944Z","end":"2026-07-02T01:53:19.126914Z","steps":["trace[78007183] 'process raft request' (duration: 61.796826ms)","trace[78007183] 'compare' (duration: 55.890401ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T01:53:19.127063Z","caller":"traceutil/trace.go:171","msg":"trace[2038054798] transaction","detail":"{read_only:false; response_revision:42324; number_of_response:1; }","duration":"117.84663ms","start":"2026-07-02T01:53:19.009199Z","end":"2026-07-02T01:53:19.127046Z","steps":["trace[2038054798] 'process raft request' (duration: 117.564724ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:53:19.127158Z","caller":"traceutil/trace.go:171","msg":"trace[230156245] transaction","detail":"{read_only:false; response_revision:42325; number_of_response:1; }","duration":"117.79798ms","start":"2026-07-02T01:53:19.009351Z","end":"2026-07-02T01:53:19.127149Z","steps":["trace[230156245] 'process raft request' (duration: 117.457442ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:53:19.346658Z","caller":"traceutil/trace.go:171","msg":"trace[1466915999] transaction","detail":"{read_only:false; response_revision:42327; number_of_response:1; }","duration":"171.887929ms","start":"2026-07-02T01:53:19.174738Z","end":"2026-07-02T01:53:19.346626Z","steps":["trace[1466915999] 'process raft request' (duration: 171.233055ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:53:21.240052Z","caller":"traceutil/trace.go:171","msg":"trace[2072381959] transaction","detail":"{read_only:false; response_revision:42339; number_of_response:1; }","duration":"103.586496ms","start":"2026-07-02T01:53:21.136436Z","end":"2026-07-02T01:53:21.240022Z","steps":["trace[2072381959] 'process raft request' (duration: 74.571217ms)","trace[2072381959] 'compare' (duration: 28.824805ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T01:53:21.240327Z","caller":"traceutil/trace.go:171","msg":"trace[214387678] transaction","detail":"{read_only:false; response_revision:42340; number_of_response:1; }","duration":"102.500091ms","start":"2026-07-02T01:53:21.1378Z","end":"2026-07-02T01:53:21.2403Z","steps":["trace[214387678] 'process raft request' (duration: 102.172353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T01:53:22.55637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.905113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T01:53:22.556645Z","caller":"traceutil/trace.go:171","msg":"trace[1440557355] transaction","detail":"{read_only:false; response_revision:42347; number_of_response:1; }","duration":"112.462548ms","start":"2026-07-02T01:53:22.44416Z","end":"2026-07-02T01:53:22.556623Z","steps":["trace[1440557355] 'process raft request' (duration: 112.376026ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:53:22.556824Z","caller":"traceutil/trace.go:171","msg":"trace[1008424228] linearizableReadLoop","detail":"{readStateIndex:43727; appliedIndex:43726; }","duration":"129.327002ms","start":"2026-07-02T01:53:22.427483Z","end":"2026-07-02T01:53:22.55681Z","steps":["trace[1008424228] 'read index received' (duration: 17.882497ms)","trace[1008424228] 'applied index is now lower than readState.Index' (duration: 111.443795ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T01:53:22.556906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.414534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T01:53:22.556936Z","caller":"traceutil/trace.go:171","msg":"trace[1238543899] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:42347; }","duration":"129.472974ms","start":"2026-07-02T01:53:22.427452Z","end":"2026-07-02T01:53:22.556924Z","steps":["trace[1238543899] 'agreement among raft nodes before linearized reading' (duration: 129.420953ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:53:22.556896Z","caller":"traceutil/trace.go:171","msg":"trace[187034394] transaction","detail":"{read_only:false; response_revision:42346; number_of_response:1; }","duration":"175.106232ms","start":"2026-07-02T01:53:22.381718Z","end":"2026-07-02T01:53:22.556824Z","steps":["trace[187034394] 'process raft request' (duration: 63.672117ms)","trace[187034394] 'compare' (duration: 110.785951ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T01:53:22.781195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.715017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T01:53:22.781307Z","caller":"traceutil/trace.go:171","msg":"trace[13891940] transaction","detail":"{read_only:false; response_revision:42350; number_of_response:1; }","duration":"125.889103ms","start":"2026-07-02T01:53:22.655392Z","end":"2026-07-02T01:53:22.781281Z","steps":["trace[13891940] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/cilium-operator-resource-lock; req_size:474; } (duration: 123.790036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T01:53:53.0156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.448622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T01:53:53.015722Z","caller":"traceutil/trace.go:171","msg":"trace[571756425] transaction","detail":"{read_only:false; response_revision:42568; number_of_response:1; }","duration":"297.127208ms","start":"2026-07-02T01:53:52.718576Z","end":"2026-07-02T01:53:53.015703Z","steps":["trace[571756425] 'process raft request' (duration: 59.484783ms)","trace[571756425] 'compare' (duration: 237.290237ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T01:53:53.017881Z","caller":"traceutil/trace.go:171","msg":"trace[745372580] transaction","detail":"{read_only:false; response_revision:42569; number_of_response:1; }","duration":"222.807538ms","start":"2026-07-02T01:53:52.795042Z","end":"2026-07-02T01:53:53.017849Z","steps":["trace[745372580] 'process raft request' (duration: 222.631964ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:53:58.674781Z","caller":"traceutil/trace.go:171","msg":"trace[269957380] linearizableReadLoop","detail":"{readStateIndex:43998; appliedIndex:43997; }","duration":"290.207ms","start":"2026-07-02T01:53:58.384547Z","end":"2026-07-02T01:53:58.674754Z","steps":["trace[269957380] 'read index received' (duration: 289.979265ms)","trace[269957380] 'applied index is now lower than readState.Index' (duration: 226.725µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T01:53:58.674992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.422006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T01:53:58.67508Z","caller":"traceutil/trace.go:171","msg":"trace[1001941911] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:42609; }","duration":"290.582889ms","start":"2026-07-02T01:53:58.384479Z","end":"2026-07-02T01:53:58.675061Z","steps":["trace[1001941911] 'agreement among raft nodes before linearized reading' (duration: 290.422015ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:53:58.674843Z","caller":"traceutil/trace.go:171","msg":"trace[2075957276] transaction","detail":"{read_only:false; response_revision:42609; number_of_response:1; }","duration":"308.21582ms","start":"2026-07-02T01:53:58.366592Z","end":"2026-07-02T01:53:58.674808Z","steps":["trace[2075957276] 'process raft request' (duration: 307.968695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T01:53:58.675533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.944236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T01:53:58.675607Z","caller":"traceutil/trace.go:171","msg":"trace[157762360] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:42609; }","duration":"141.600351ms","start":"2026-07-02T01:53:58.533987Z","end":"2026-07-02T01:53:58.675587Z","steps":["trace[157762360] 'agreement among raft nodes before linearized reading' (duration: 140.949656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T01:53:58.676071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T01:53:58.366559Z","time spent":"308.783083ms","remote":"127.0.0.1:58994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T01:54:24.368445Z","caller":"traceutil/trace.go:171","msg":"trace[502610135] transaction","detail":"{read_only:false; response_revision:42808; number_of_response:1; }","duration":"148.400045ms","start":"2026-07-02T01:54:24.219994Z","end":"2026-07-02T01:54:24.368394Z","steps":["trace[502610135] 'process raft request' (duration: 148.127159ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:54:29.442805Z","caller":"traceutil/trace.go:171","msg":"trace[1355814566] transaction","detail":"{read_only:false; response_revision:42861; number_of_response:1; }","duration":"198.798342ms","start":"2026-07-02T01:54:29.243973Z","end":"2026-07-02T01:54:29.442772Z","steps":["trace[1355814566] 'process raft request' (duration: 198.622478ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:54:40.060049Z","caller":"traceutil/trace.go:171","msg":"trace[1075844988] transaction","detail":"{read_only:false; response_revision:42978; number_of_response:1; }","duration":"117.965983ms","start":"2026-07-02T01:54:39.942058Z","end":"2026-07-02T01:54:40.060024Z","steps":["trace[1075844988] 'process raft request' (duration: 75.205471ms)","trace[1075844988] 'compare' (duration: 42.599998ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T01:54:40.060173Z","caller":"traceutil/trace.go:171","msg":"trace[1242017854] linearizableReadLoop","detail":"{readStateIndex:44377; appliedIndex:44375; }","duration":"117.752798ms","start":"2026-07-02T01:54:39.942393Z","end":"2026-07-02T01:54:40.060146Z","steps":["trace[1242017854] 'read index received' (duration: 45.892104ms)","trace[1242017854] 'applied index is now lower than readState.Index' (duration: 71.857884ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T01:54:40.060402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.976464ms","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-07-02T01:54:40.060475Z","caller":"traceutil/trace.go:171","msg":"trace[1690752754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:42978; }","duration":"118.084667ms","start":"2026-07-02T01:54:39.942375Z","end":"2026-07-02T01:54:40.06046Z","steps":["trace[1690752754] 'agreement among raft nodes before linearized reading' (duration: 117.861001ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T01:56:20.725129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.411172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-octavia-server-0.18be53c720044c4e\\000\" range_end:\"/registry/events/openstack0\" limit:2000 revision:43875 ","response":"range_response_count:324 size:249319"} {"level":"info","ts":"2026-07-02T01:56:20.725223Z","caller":"traceutil/trace.go:171","msg":"trace[1559166368] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-octavia-server-0.18be53c720044c4e\u0000; range_end:/registry/events/openstack0; response_count:324; response_revision:43875; }","duration":"103.546085ms","start":"2026-07-02T01:56:20.621659Z","end":"2026-07-02T01:56:20.725205Z","steps":["trace[1559166368] 'range keys from in-memory index tree' (duration: 102.141262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T01:57:12.739781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.948589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"warn","ts":"2026-07-02T01:57:12.739807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.99091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/placement-openstack-placement-db-init\" ","response":"range_response_count:1 size:686"} {"level":"info","ts":"2026-07-02T01:57:12.73987Z","caller":"traceutil/trace.go:171","msg":"trace[513880318] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:44251; }","duration":"102.065382ms","start":"2026-07-02T01:57:12.637784Z","end":"2026-07-02T01:57:12.739849Z","steps":["trace[513880318] 'range keys from in-memory index tree' (duration: 101.784885ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:57:12.739885Z","caller":"traceutil/trace.go:171","msg":"trace[183970028] range","detail":"{range_begin:/registry/roles/openstack/placement-openstack-placement-db-init; range_end:; response_count:1; response_revision:44251; }","duration":"102.078142ms","start":"2026-07-02T01:57:12.637785Z","end":"2026-07-02T01:57:12.739863Z","steps":["trace[183970028] 'range keys from in-memory index tree' (duration: 101.788585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T01:57:13.871766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.459454ms","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-07-02T01:57:13.871845Z","caller":"traceutil/trace.go:171","msg":"trace[1387171343] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:44258; }","duration":"118.592457ms","start":"2026-07-02T01:57:13.753235Z","end":"2026-07-02T01:57:13.871828Z","steps":["trace[1387171343] 'range keys from in-memory index tree' (duration: 118.096846ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:57:14.026002Z","caller":"traceutil/trace.go:171","msg":"trace[973501348] transaction","detail":"{read_only:false; response_revision:44259; number_of_response:1; }","duration":"148.59335ms","start":"2026-07-02T01:57:13.877382Z","end":"2026-07-02T01:57:14.025975Z","steps":["trace[973501348] 'process raft request' (duration: 148.439217ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:57:14.045553Z","caller":"traceutil/trace.go:171","msg":"trace[849133422] transaction","detail":"{read_only:false; response_revision:44260; number_of_response:1; }","duration":"168.114574ms","start":"2026-07-02T01:57:13.877412Z","end":"2026-07-02T01:57:14.045526Z","steps":["trace[849133422] 'process raft request' (duration: 167.844448ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:57:14.045776Z","caller":"traceutil/trace.go:171","msg":"trace[914903504] linearizableReadLoop","detail":"{readStateIndex:45693; appliedIndex:45691; }","duration":"167.640094ms","start":"2026-07-02T01:57:13.878047Z","end":"2026-07-02T01:57:14.045687Z","steps":["trace[914903504] 'read index received' (duration: 147.897455ms)","trace[914903504] 'applied index is now lower than readState.Index' (duration: 19.741249ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T01:57:14.045944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.890179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/rabbitmq-cinder-peer-discovery\" ","response":"range_response_count:1 size:807"} {"level":"info","ts":"2026-07-02T01:57:14.045989Z","caller":"traceutil/trace.go:171","msg":"trace[1721843672] range","detail":"{range_begin:/registry/roles/openstack/rabbitmq-cinder-peer-discovery; range_end:; response_count:1; response_revision:44262; }","duration":"167.94207ms","start":"2026-07-02T01:57:13.87803Z","end":"2026-07-02T01:57:14.045972Z","steps":["trace[1721843672] 'agreement among raft nodes before linearized reading' (duration: 167.774027ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T01:57:43.894257Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":42068} {"level":"info","ts":"2026-07-02T01:57:44.137602Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":42068,"took":"240.094862ms","hash":4204910960,"current-db-size-bytes":54652928,"current-db-size":"55 MB","current-db-size-in-use-bytes":33013760,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-02T01:57:44.137653Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4204910960,"revision":42068,"compact-revision":39791}