{"level":"info","ts":"2026-07-06T13:51:40.77957Z","caller":"traceutil/trace.go:171","msg":"trace[456905545] linearizableReadLoop","detail":"{readStateIndex:45731; appliedIndex:45730; }","duration":"240.270869ms","start":"2026-07-06T13:51:40.539276Z","end":"2026-07-06T13:51:40.779547Z","steps":["trace[456905545] 'read index received' (duration: 236.562534ms)","trace[456905545] 'applied index is now lower than readState.Index' (duration: 3.707185ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:51:40.779678Z","caller":"traceutil/trace.go:171","msg":"trace[1013898276] transaction","detail":"{read_only:false; response_revision:44227; number_of_response:1; }","duration":"302.631799ms","start":"2026-07-06T13:51:40.477032Z","end":"2026-07-06T13:51:40.779663Z","steps":["trace[1013898276] 'process raft request' (duration: 298.795291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:40.779815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.528704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1318"} {"level":"info","ts":"2026-07-06T13:51:40.779859Z","caller":"traceutil/trace.go:171","msg":"trace[938481711] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:44227; }","duration":"240.610976ms","start":"2026-07-06T13:51:40.539238Z","end":"2026-07-06T13:51:40.779849Z","steps":["trace[938481711] 'agreement among raft nodes before linearized reading' (duration: 240.424181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:40.78025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:40.477009Z","time spent":"302.702441ms","remote":"127.0.0.1:43964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T13:51:41.101991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.609284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-06T13:51:41.102126Z","caller":"traceutil/trace.go:171","msg":"trace[1194064445] linearizableReadLoop","detail":"{readStateIndex:45732; appliedIndex:45731; }","duration":"322.471882ms","start":"2026-07-06T13:51:40.779631Z","end":"2026-07-06T13:51:41.102102Z","steps":["trace[1194064445] 'read index received' (duration: 125.692034ms)","trace[1194064445] 'applied index is now lower than readState.Index' (duration: 196.777848ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:51:41.102227Z","caller":"traceutil/trace.go:171","msg":"trace[639935552] transaction","detail":"{read_only:false; response_revision:44228; number_of_response:1; }","duration":"562.802892ms","start":"2026-07-06T13:51:40.539407Z","end":"2026-07-06T13:51:41.10221Z","steps":["trace[639935552] 'process raft request' (duration: 365.898772ms)","trace[639935552] 'compare' (duration: 196.45601ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T13:51:41.102321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:40.539377Z","time spent":"562.893044ms","remote":"127.0.0.1:55316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7897,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T13:51:41.102505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.465086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/capi-system/\" range_end:\"/registry/persistentvolumeclaims/capi-system0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-06T13:51:41.102544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.746961ms","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-06T13:51:41.102622Z","caller":"traceutil/trace.go:171","msg":"trace[2035803704] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:44228; }","duration":"380.821693ms","start":"2026-07-06T13:51:40.721774Z","end":"2026-07-06T13:51:41.102596Z","steps":["trace[2035803704] 'agreement among raft nodes before linearized reading' (duration: 380.68176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:41.10267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.960917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1370"} {"level":"info","ts":"2026-07-06T13:51:41.102727Z","caller":"traceutil/trace.go:171","msg":"trace[1050667315] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:44228; }","duration":"542.046769ms","start":"2026-07-06T13:51:40.560666Z","end":"2026-07-06T13:51:41.102712Z","steps":["trace[1050667315] 'agreement among raft nodes before linearized reading' (duration: 541.903825ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:41.102771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:40.560649Z","time spent":"542.111621ms","remote":"127.0.0.1:43882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-07-06T13:51:41.102681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:40.721717Z","time spent":"380.947976ms","remote":"127.0.0.1:55760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true "} {"level":"info","ts":"2026-07-06T13:51:41.102569Z","caller":"traceutil/trace.go:171","msg":"trace[315216122] range","detail":"{range_begin:/registry/persistentvolumeclaims/capi-system/; range_end:/registry/persistentvolumeclaims/capi-system0; response_count:0; response_revision:44228; }","duration":"534.587639ms","start":"2026-07-06T13:51:40.567967Z","end":"2026-07-06T13:51:41.102554Z","steps":["trace[315216122] 'agreement among raft nodes before linearized reading' (duration: 534.464476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:41.103072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:40.567947Z","time spent":"535.09369ms","remote":"127.0.0.1:43856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/capi-system/\" range_end:\"/registry/persistentvolumeclaims/capi-system0\" limit:500 "} {"level":"warn","ts":"2026-07-06T13:51:41.102545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.532959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/capi-kubeadm-control-plane-system/\" range_end:\"/registry/roles/capi-kubeadm-control-plane-system0\" limit:500 ","response":"range_response_count:1 size:697"} {"level":"info","ts":"2026-07-06T13:51:41.103395Z","caller":"traceutil/trace.go:171","msg":"trace[1842102875] range","detail":"{range_begin:/registry/roles/capi-kubeadm-control-plane-system/; range_end:/registry/roles/capi-kubeadm-control-plane-system0; response_count:1; response_revision:44228; }","duration":"525.428489ms","start":"2026-07-06T13:51:40.577938Z","end":"2026-07-06T13:51:41.103367Z","steps":["trace[1842102875] 'agreement among raft nodes before linearized reading' (duration: 524.356665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:41.103469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:40.577922Z","time spent":"525.523671ms","remote":"127.0.0.1:44008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":107,"response count":1,"response size":720,"request content":"key:\"/registry/roles/capi-kubeadm-control-plane-system/\" range_end:\"/registry/roles/capi-kubeadm-control-plane-system0\" limit:500 "} {"level":"info","ts":"2026-07-06T13:51:41.400415Z","caller":"traceutil/trace.go:171","msg":"trace[1622591380] transaction","detail":"{read_only:false; response_revision:44229; number_of_response:1; }","duration":"557.617194ms","start":"2026-07-06T13:51:40.842761Z","end":"2026-07-06T13:51:41.400378Z","steps":["trace[1622591380] 'process raft request' (duration: 548.597099ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:41.400491Z","caller":"traceutil/trace.go:171","msg":"trace[254567736] transaction","detail":"{read_only:false; response_revision:44230; number_of_response:1; }","duration":"165.78135ms","start":"2026-07-06T13:51:41.234644Z","end":"2026-07-06T13:51:41.400426Z","steps":["trace[254567736] 'process raft request' (duration: 165.702658ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:41.400504Z","caller":"traceutil/trace.go:171","msg":"trace[698662101] linearizableReadLoop","detail":"{readStateIndex:45733; appliedIndex:45732; }","duration":"298.309971ms","start":"2026-07-06T13:51:41.10217Z","end":"2026-07-06T13:51:41.40048Z","steps":["trace[698662101] 'read index received' (duration: 289.610873ms)","trace[698662101] 'applied index is now lower than readState.Index' (duration: 8.697848ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T13:51:41.400703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.613476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-06T13:51:41.400738Z","caller":"traceutil/trace.go:171","msg":"trace[1402017412] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:44230; }","duration":"337.692179ms","start":"2026-07-06T13:51:41.063036Z","end":"2026-07-06T13:51:41.400728Z","steps":["trace[1402017412] 'agreement among raft nodes before linearized reading' (duration: 337.560096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:41.400773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:41.063019Z","time spent":"337.744809ms","remote":"127.0.0.1:44050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-06T13:51:41.400875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.748491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18154"} {"level":"info","ts":"2026-07-06T13:51:41.400911Z","caller":"traceutil/trace.go:171","msg":"trace[649649455] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:44230; }","duration":"291.828063ms","start":"2026-07-06T13:51:41.109075Z","end":"2026-07-06T13:51:41.400903Z","steps":["trace[649649455] 'agreement among raft nodes before linearized reading' (duration: 291.719521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:41.401068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.415056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1318"} {"level":"info","ts":"2026-07-06T13:51:41.40109Z","caller":"traceutil/trace.go:171","msg":"trace[852825201] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:44230; }","duration":"617.453338ms","start":"2026-07-06T13:51:40.783632Z","end":"2026-07-06T13:51:41.401085Z","steps":["trace[852825201] 'agreement among raft nodes before linearized reading' (duration: 617.387266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:41.401108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:40.783619Z","time spent":"617.484028ms","remote":"127.0.0.1:43882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1341,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"warn","ts":"2026-07-06T13:51:41.401327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.993515ms","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-07-06T13:51:41.40137Z","caller":"traceutil/trace.go:171","msg":"trace[424384767] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:44230; }","duration":"109.071157ms","start":"2026-07-06T13:51:41.292287Z","end":"2026-07-06T13:51:41.401358Z","steps":["trace[424384767] 'agreement among raft nodes before linearized reading' (duration: 108.910412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:41.40143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:40.842736Z","time spent":"557.795227ms","remote":"127.0.0.1:43964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T13:51:41.602615Z","caller":"traceutil/trace.go:171","msg":"trace[1769749858] transaction","detail":"{read_only:false; response_revision:44231; number_of_response:1; }","duration":"155.25568ms","start":"2026-07-06T13:51:41.447331Z","end":"2026-07-06T13:51:41.602586Z","steps":["trace[1769749858] 'process raft request' (duration: 111.880052ms)","trace[1769749858] 'compare' (duration: 43.192254ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T13:51:41.896706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.690172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-06T13:51:41.896782Z","caller":"traceutil/trace.go:171","msg":"trace[640675220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:44231; }","duration":"174.813135ms","start":"2026-07-06T13:51:41.721951Z","end":"2026-07-06T13:51:41.896764Z","steps":["trace[640675220] 'range keys from in-memory index tree' (duration: 174.510249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:41.897196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.074126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-06T13:51:41.897257Z","caller":"traceutil/trace.go:171","msg":"trace[1908189448] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:44231; }","duration":"130.177139ms","start":"2026-07-06T13:51:41.767063Z","end":"2026-07-06T13:51:41.897241Z","steps":["trace[1908189448] 'count revisions from in-memory index tree' (duration: 129.984994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:41.897393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.505269ms","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-07-06T13:51:41.897468Z","caller":"traceutil/trace.go:171","msg":"trace[325583839] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:44231; }","duration":"156.655552ms","start":"2026-07-06T13:51:41.740794Z","end":"2026-07-06T13:51:41.897449Z","steps":["trace[325583839] 'range keys from in-memory index tree' (duration: 156.258793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:41.897452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.983732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/\" range_end:\"/registry/events/auth-system0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-06T13:51:41.897615Z","caller":"traceutil/trace.go:171","msg":"trace[1621644392] range","detail":"{range_begin:/registry/events/auth-system/; range_end:/registry/events/auth-system0; response_count:0; response_revision:44231; }","duration":"183.164427ms","start":"2026-07-06T13:51:41.714371Z","end":"2026-07-06T13:51:41.897536Z","steps":["trace[1621644392] 'range keys from in-memory index tree' (duration: 182.8877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:41.897444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.479186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7509"} {"level":"info","ts":"2026-07-06T13:51:41.897747Z","caller":"traceutil/trace.go:171","msg":"trace[1053205311] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:44231; }","duration":"184.837084ms","start":"2026-07-06T13:51:41.712899Z","end":"2026-07-06T13:51:41.897736Z","steps":["trace[1053205311] 'range keys from in-memory index tree' (duration: 184.329773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:41.900573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.388864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/capi-system/\" range_end:\"/registry/secrets/capi-system0\" limit:500 ","response":"range_response_count:1 size:5440"} {"level":"info","ts":"2026-07-06T13:51:41.900647Z","caller":"traceutil/trace.go:171","msg":"trace[397282651] range","detail":"{range_begin:/registry/secrets/capi-system/; range_end:/registry/secrets/capi-system0; response_count:1; response_revision:44231; }","duration":"166.509687ms","start":"2026-07-06T13:51:41.73412Z","end":"2026-07-06T13:51:41.900629Z","steps":["trace[397282651] 'range keys from in-memory index tree' (duration: 162.605227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:42.190803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.497596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-06T13:51:42.190886Z","caller":"traceutil/trace.go:171","msg":"trace[1865041502] linearizableReadLoop","detail":"{readStateIndex:45738; appliedIndex:45737; }","duration":"212.209067ms","start":"2026-07-06T13:51:41.978663Z","end":"2026-07-06T13:51:42.190872Z","steps":["trace[1865041502] 'read index received' (duration: 59.479126ms)","trace[1865041502] 'applied index is now lower than readState.Index' (duration: 152.728941ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:51:42.190949Z","caller":"traceutil/trace.go:171","msg":"trace[128180691] transaction","detail":"{read_only:false; response_revision:44233; number_of_response:1; }","duration":"215.233246ms","start":"2026-07-06T13:51:41.975672Z","end":"2026-07-06T13:51:42.190905Z","steps":["trace[128180691] 'process raft request' (duration: 62.562656ms)","trace[128180691] 'compare' (duration: 152.392124ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T13:51:42.191365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.085513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/capi-system/kube-root-ca.crt\" ","response":"range_response_count:1 size:1749"} {"level":"warn","ts":"2026-07-06T13:51:42.191646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.027896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/capi-system/\" range_end:\"/registry/serviceaccounts/capi-system0\" limit:500 ","response":"range_response_count:2 size:602"} {"level":"warn","ts":"2026-07-06T13:51:42.191639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.630195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/capi-system/\" range_end:\"/registry/services/specs/capi-system0\" limit:500 ","response":"range_response_count:1 size:817"} {"level":"info","ts":"2026-07-06T13:51:42.191703Z","caller":"traceutil/trace.go:171","msg":"trace[1025318207] range","detail":"{range_begin:/registry/serviceaccounts/capi-system/; range_end:/registry/serviceaccounts/capi-system0; response_count:2; response_revision:44233; }","duration":"170.15015ms","start":"2026-07-06T13:51:42.021537Z","end":"2026-07-06T13:51:42.191687Z","steps":["trace[1025318207] 'agreement among raft nodes before linearized reading' (duration: 169.912584ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:42.191728Z","caller":"traceutil/trace.go:171","msg":"trace[2112857476] range","detail":"{range_begin:/registry/services/specs/capi-system/; range_end:/registry/services/specs/capi-system0; response_count:1; response_revision:44233; }","duration":"182.820509ms","start":"2026-07-06T13:51:42.008885Z","end":"2026-07-06T13:51:42.191705Z","steps":["trace[2112857476] 'agreement among raft nodes before linearized reading' (duration: 182.157703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:42.192233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.564489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/\" range_end:\"/registry/events/auth-system0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-06T13:51:42.192402Z","caller":"traceutil/trace.go:171","msg":"trace[1852864829] range","detail":"{range_begin:/registry/events/auth-system/; range_end:/registry/events/auth-system0; response_count:0; response_revision:44233; }","duration":"213.763324ms","start":"2026-07-06T13:51:41.978624Z","end":"2026-07-06T13:51:42.192388Z","steps":["trace[1852864829] 'agreement among raft nodes before linearized reading' (duration: 212.3124ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:42.191453Z","caller":"traceutil/trace.go:171","msg":"trace[1104882818] range","detail":"{range_begin:/registry/configmaps/capi-system/kube-root-ca.crt; range_end:; response_count:1; response_revision:44233; }","duration":"161.222847ms","start":"2026-07-06T13:51:42.030198Z","end":"2026-07-06T13:51:42.191421Z","steps":["trace[1104882818] 'agreement among raft nodes before linearized reading' (duration: 160.885048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:42.538582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.827257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/capo-system/\" range_end:\"/registry/cronjobs/capo-system0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-06T13:51:42.538618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.394456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-06T13:51:42.538638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.757275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-06T13:51:42.538673Z","caller":"traceutil/trace.go:171","msg":"trace[609083355] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:44238; }","duration":"110.48625ms","start":"2026-07-06T13:51:42.428171Z","end":"2026-07-06T13:51:42.538657Z","steps":["trace[609083355] 'agreement among raft nodes before linearized reading' (duration: 110.389056ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:42.538675Z","caller":"traceutil/trace.go:171","msg":"trace[1031171662] range","detail":"{range_begin:/registry/cronjobs/capo-system/; range_end:/registry/cronjobs/capo-system0; response_count:0; response_revision:44238; }","duration":"103.971682ms","start":"2026-07-06T13:51:42.434679Z","end":"2026-07-06T13:51:42.538651Z","steps":["trace[1031171662] 'agreement among raft nodes before linearized reading' (duration: 103.790096ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:42.53871Z","caller":"traceutil/trace.go:171","msg":"trace[113617946] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:44238; }","duration":"139.872059ms","start":"2026-07-06T13:51:42.398821Z","end":"2026-07-06T13:51:42.538693Z","steps":["trace[113617946] 'agreement among raft nodes before linearized reading' (duration: 139.748036ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:42.943751Z","caller":"traceutil/trace.go:171","msg":"trace[1403147677] transaction","detail":"{read_only:false; response_revision:44239; number_of_response:1; }","duration":"398.393263ms","start":"2026-07-06T13:51:42.545331Z","end":"2026-07-06T13:51:42.943725Z","steps":["trace[1403147677] 'process raft request' (duration: 359.512476ms)","trace[1403147677] 'compare' (duration: 38.541749ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T13:51:42.943895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:42.545306Z","time spent":"398.511295ms","remote":"127.0.0.1:43964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T13:51:42.94397Z","caller":"traceutil/trace.go:171","msg":"trace[2007336279] transaction","detail":"{read_only:false; response_revision:44240; number_of_response:1; }","duration":"398.104666ms","start":"2026-07-06T13:51:42.54584Z","end":"2026-07-06T13:51:42.943944Z","steps":["trace[2007336279] 'process raft request' (duration: 397.796819ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:42.944021Z","caller":"traceutil/trace.go:171","msg":"trace[937885997] linearizableReadLoop","detail":"{readStateIndex:45745; appliedIndex:45743; }","duration":"397.146394ms","start":"2026-07-06T13:51:42.546861Z","end":"2026-07-06T13:51:42.944008Z","steps":["trace[937885997] 'read index received' (duration: 357.967091ms)","trace[937885997] 'applied index is now lower than readState.Index' (duration: 39.178423ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T13:51:42.944099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:42.545821Z","time spent":"398.206639ms","remote":"127.0.0.1:43964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T13:51:42.944215Z","caller":"traceutil/trace.go:171","msg":"trace[51992465] transaction","detail":"{read_only:false; response_revision:44241; number_of_response:1; }","duration":"393.86546ms","start":"2026-07-06T13:51:42.550339Z","end":"2026-07-06T13:51:42.944205Z","steps":["trace[51992465] 'process raft request' (duration: 393.521642ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:42.94425Z","caller":"traceutil/trace.go:171","msg":"trace[18212667] transaction","detail":"{read_only:false; response_revision:44242; number_of_response:1; }","duration":"158.875082ms","start":"2026-07-06T13:51:42.785352Z","end":"2026-07-06T13:51:42.944227Z","steps":["trace[18212667] 'process raft request' (duration: 158.610136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:42.944268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:42.550321Z","time spent":"393.91657ms","remote":"127.0.0.1:43964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T13:51:42.944338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.971922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/capo-system/\" range_end:\"/registry/deployments/capo-system0\" limit:500 ","response":"range_response_count:1 size:4673"} {"level":"info","ts":"2026-07-06T13:51:42.944382Z","caller":"traceutil/trace.go:171","msg":"trace[1918608569] range","detail":"{range_begin:/registry/deployments/capo-system/; range_end:/registry/deployments/capo-system0; response_count:1; response_revision:44242; }","duration":"312.020593ms","start":"2026-07-06T13:51:42.632351Z","end":"2026-07-06T13:51:42.944371Z","steps":["trace[1918608569] 'agreement among raft nodes before linearized reading' (duration: 311.88777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:42.944409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:42.632304Z","time spent":"312.097345ms","remote":"127.0.0.1:44154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":4696,"request content":"key:\"/registry/deployments/capo-system/\" range_end:\"/registry/deployments/capo-system0\" limit:500 "} {"level":"warn","ts":"2026-07-06T13:51:42.944505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.9601ms","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-07-06T13:51:42.94457Z","caller":"traceutil/trace.go:171","msg":"trace[1650557344] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:44242; }","duration":"272.062452ms","start":"2026-07-06T13:51:42.672489Z","end":"2026-07-06T13:51:42.944552Z","steps":["trace[1650557344] 'agreement among raft nodes before linearized reading' (duration: 271.854508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:42.944623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.215621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/cert-manager/\" range_end:\"/registry/configmaps/cert-manager0\" limit:500 ","response":"range_response_count:1 size:1749"} {"level":"info","ts":"2026-07-06T13:51:42.944711Z","caller":"traceutil/trace.go:171","msg":"trace[1990878859] range","detail":"{range_begin:/registry/configmaps/cert-manager/; range_end:/registry/configmaps/cert-manager0; response_count:1; response_revision:44242; }","duration":"306.332114ms","start":"2026-07-06T13:51:42.638358Z","end":"2026-07-06T13:51:42.94469Z","steps":["trace[1990878859] 'agreement among raft nodes before linearized reading' (duration: 306.072628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:42.944764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:42.638346Z","time spent":"306.402345ms","remote":"127.0.0.1:43828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1772,"request content":"key:\"/registry/configmaps/cert-manager/\" range_end:\"/registry/configmaps/cert-manager0\" limit:500 "} {"level":"warn","ts":"2026-07-06T13:51:42.945516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.65272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/capi-kubeadm-control-plane-system/\" range_end:\"/registry/events/capi-kubeadm-control-plane-system0\" limit:500 ","response":"range_response_count:33 size:31801"} {"level":"info","ts":"2026-07-06T13:51:42.94558Z","caller":"traceutil/trace.go:171","msg":"trace[1158829750] range","detail":"{range_begin:/registry/events/capi-kubeadm-control-plane-system/; range_end:/registry/events/capi-kubeadm-control-plane-system0; response_count:33; response_revision:44242; }","duration":"398.751412ms","start":"2026-07-06T13:51:42.546812Z","end":"2026-07-06T13:51:42.945564Z","steps":["trace[1158829750] 'agreement among raft nodes before linearized reading' (duration: 397.392551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:42.945639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:42.546792Z","time spent":"398.831623ms","remote":"127.0.0.1:43814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":33,"response size":31824,"request content":"key:\"/registry/events/capi-kubeadm-control-plane-system/\" range_end:\"/registry/events/capi-kubeadm-control-plane-system0\" limit:500 "} {"level":"info","ts":"2026-07-06T13:51:43.638536Z","caller":"traceutil/trace.go:171","msg":"trace[1205891953] linearizableReadLoop","detail":"{readStateIndex:45749; appliedIndex:45747; }","duration":"229.876551ms","start":"2026-07-06T13:51:43.40863Z","end":"2026-07-06T13:51:43.638507Z","steps":["trace[1205891953] 'read index received' (duration: 33.605046ms)","trace[1205891953] 'applied index is now lower than readState.Index' (duration: 196.270145ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:51:43.638668Z","caller":"traceutil/trace.go:171","msg":"trace[695226584] transaction","detail":"{read_only:false; response_revision:44244; number_of_response:1; }","duration":"232.081201ms","start":"2026-07-06T13:51:43.406562Z","end":"2026-07-06T13:51:43.638643Z","steps":["trace[695226584] 'process raft request' (duration: 231.5828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:43.63877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.115086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager-6b6ccbf6b8-fv4fz\" ","response":"range_response_count:1 size:7711"} {"level":"info","ts":"2026-07-06T13:51:43.638814Z","caller":"traceutil/trace.go:171","msg":"trace[933212114] range","detail":"{range_begin:/registry/pods/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager-6b6ccbf6b8-fv4fz; range_end:; response_count:1; response_revision:44244; }","duration":"230.196548ms","start":"2026-07-06T13:51:43.408605Z","end":"2026-07-06T13:51:43.638802Z","steps":["trace[933212114] 'agreement among raft nodes before linearized reading' (duration: 230.037854ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:43.660298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.169913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/capo-system/\" range_end:\"/registry/networkpolicies/capo-system0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-06T13:51:43.660443Z","caller":"traceutil/trace.go:171","msg":"trace[805364501] range","detail":"{range_begin:/registry/networkpolicies/capo-system/; range_end:/registry/networkpolicies/capo-system0; response_count:0; response_revision:44246; }","duration":"207.349867ms","start":"2026-07-06T13:51:43.453076Z","end":"2026-07-06T13:51:43.660426Z","steps":["trace[805364501] 'agreement among raft nodes before linearized reading' (duration: 207.111271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:43.660894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.447626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/cert-manager/\" range_end:\"/registry/services/endpoints/cert-manager0\" limit:500 ","response":"range_response_count:2 size:2171"} {"level":"info","ts":"2026-07-06T13:51:43.660969Z","caller":"traceutil/trace.go:171","msg":"trace[1712191501] range","detail":"{range_begin:/registry/services/endpoints/cert-manager/; range_end:/registry/services/endpoints/cert-manager0; response_count:2; response_revision:44246; }","duration":"195.551309ms","start":"2026-07-06T13:51:43.465406Z","end":"2026-07-06T13:51:43.660957Z","steps":["trace[1712191501] 'agreement among raft nodes before linearized reading' (duration: 195.388585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:43.661035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.487311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/capi-system/capi-webhook-service\" ","response":"range_response_count:1 size:775"} {"level":"info","ts":"2026-07-06T13:51:43.661097Z","caller":"traceutil/trace.go:171","msg":"trace[1423218174] range","detail":"{range_begin:/registry/services/endpoints/capi-system/capi-webhook-service; range_end:; response_count:1; response_revision:44246; }","duration":"161.592933ms","start":"2026-07-06T13:51:43.49949Z","end":"2026-07-06T13:51:43.661083Z","steps":["trace[1423218174] 'agreement among raft nodes before linearized reading' (duration: 161.44159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:44.274347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.359732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager-6b6ccbf6b8-fv4fz\" ","response":"range_response_count:1 size:7711"} {"level":"info","ts":"2026-07-06T13:51:44.274425Z","caller":"traceutil/trace.go:171","msg":"trace[1754097086] range","detail":"{range_begin:/registry/pods/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager-6b6ccbf6b8-fv4fz; range_end:; response_count:1; response_revision:44246; }","duration":"516.474615ms","start":"2026-07-06T13:51:43.757931Z","end":"2026-07-06T13:51:44.274406Z","steps":["trace[1754097086] 'range keys from in-memory index tree' (duration: 516.26933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:44.274408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.180443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/cert-manager/\" range_end:\"/registry/ingress/cert-manager0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-06T13:51:44.27446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.768392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-06T13:51:44.274478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:43.757916Z","time spent":"516.536986ms","remote":"127.0.0.1:43878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":1,"response size":7734,"request content":"key:\"/registry/pods/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager-6b6ccbf6b8-fv4fz\" "} {"level":"info","ts":"2026-07-06T13:51:44.274505Z","caller":"traceutil/trace.go:171","msg":"trace[1161775435] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:44246; }","duration":"298.848123ms","start":"2026-07-06T13:51:43.975644Z","end":"2026-07-06T13:51:44.274492Z","steps":["trace[1161775435] 'range keys from in-memory index tree' (duration: 298.626527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:44.274515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.61007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/capi-system/capi-controller-manager-5647546676-mtbm7\" ","response":"range_response_count:1 size:7541"} {"level":"info","ts":"2026-07-06T13:51:44.274657Z","caller":"traceutil/trace.go:171","msg":"trace[1590347918] range","detail":"{range_begin:/registry/pods/capi-system/capi-controller-manager-5647546676-mtbm7; range_end:; response_count:1; response_revision:44246; }","duration":"381.704932ms","start":"2026-07-06T13:51:43.892855Z","end":"2026-07-06T13:51:44.27456Z","steps":["trace[1590347918] 'range keys from in-memory index tree' (duration: 381.449026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:44.274744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:43.892839Z","time spent":"381.886877ms","remote":"127.0.0.1:43878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":7564,"request content":"key:\"/registry/pods/capi-system/capi-controller-manager-5647546676-mtbm7\" "} {"level":"info","ts":"2026-07-06T13:51:44.274778Z","caller":"traceutil/trace.go:171","msg":"trace[333518248] range","detail":"{range_begin:/registry/ingress/cert-manager/; range_end:/registry/ingress/cert-manager0; response_count:0; response_revision:44246; }","duration":"464.294526ms","start":"2026-07-06T13:51:43.810168Z","end":"2026-07-06T13:51:44.274462Z","steps":["trace[333518248] 'range keys from in-memory index tree' (duration: 464.120382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:44.27483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:43.810151Z","time spent":"464.664854ms","remote":"127.0.0.1:43988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/cert-manager/\" range_end:\"/registry/ingress/cert-manager0\" limit:500 "} {"level":"warn","ts":"2026-07-06T13:51:44.275119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.915983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/capo-system/\" range_end:\"/registry/pods/capo-system0\" limit:500 ","response":"range_response_count:1 size:7025"} {"level":"info","ts":"2026-07-06T13:51:44.275177Z","caller":"traceutil/trace.go:171","msg":"trace[930124486] range","detail":"{range_begin:/registry/pods/capo-system/; range_end:/registry/pods/capo-system0; response_count:1; response_revision:44246; }","duration":"481.001206ms","start":"2026-07-06T13:51:43.794162Z","end":"2026-07-06T13:51:44.275163Z","steps":["trace[930124486] 'range keys from in-memory index tree' (duration: 480.812201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:44.275218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:43.794148Z","time spent":"481.059457ms","remote":"127.0.0.1:43878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":7048,"request content":"key:\"/registry/pods/capo-system/\" range_end:\"/registry/pods/capo-system0\" limit:500 "} {"level":"warn","ts":"2026-07-06T13:51:44.732114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.223245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-leader-election-rolebinding\" ","response":"range_response_count:1 size:820"} {"level":"info","ts":"2026-07-06T13:51:44.732224Z","caller":"traceutil/trace.go:171","msg":"trace[1915840154] range","detail":"{range_begin:/registry/rolebindings/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-leader-election-rolebinding; range_end:; response_count:1; response_revision:44248; }","duration":"229.38348ms","start":"2026-07-06T13:51:44.502815Z","end":"2026-07-06T13:51:44.732198Z","steps":["trace[1915840154] 'range keys from in-memory index tree' (duration: 228.933569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:44.732211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.005954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/cert-manager/\" range_end:\"/registry/jobs/cert-manager0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-06T13:51:44.73232Z","caller":"traceutil/trace.go:171","msg":"trace[151510914] range","detail":"{range_begin:/registry/jobs/cert-manager/; range_end:/registry/jobs/cert-manager0; response_count:0; response_revision:44248; }","duration":"337.157188ms","start":"2026-07-06T13:51:44.39514Z","end":"2026-07-06T13:51:44.732297Z","steps":["trace[151510914] 'range keys from in-memory index tree' (duration: 336.935003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:44.732318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.166162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx\" ","response":"range_response_count:1 size:1021"} {"level":"warn","ts":"2026-07-06T13:51:44.732349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.612207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/capi-system/capi-controller-manager-5647546676-mtbm7\" ","response":"range_response_count:1 size:7541"} {"level":"info","ts":"2026-07-06T13:51:44.732377Z","caller":"traceutil/trace.go:171","msg":"trace[1540693770] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx; range_end:; response_count:1; response_revision:44248; }","duration":"335.277054ms","start":"2026-07-06T13:51:44.397086Z","end":"2026-07-06T13:51:44.732363Z","steps":["trace[1540693770] 'range keys from in-memory index tree' (duration: 335.052869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:44.732377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:44.395118Z","time spent":"337.243239ms","remote":"127.0.0.1:43926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/cert-manager/\" range_end:\"/registry/jobs/cert-manager0\" limit:500 "} {"level":"warn","ts":"2026-07-06T13:51:44.732407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:44.397063Z","time spent":"335.338066ms","remote":"127.0.0.1:43888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1044,"request content":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx\" "} {"level":"info","ts":"2026-07-06T13:51:44.73241Z","caller":"traceutil/trace.go:171","msg":"trace[350758243] range","detail":"{range_begin:/registry/pods/capi-system/capi-controller-manager-5647546676-mtbm7; range_end:; response_count:1; response_revision:44248; }","duration":"326.74059ms","start":"2026-07-06T13:51:44.405649Z","end":"2026-07-06T13:51:44.732389Z","steps":["trace[350758243] 'range keys from in-memory index tree' (duration: 326.416263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:44.732491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:44.405628Z","time spent":"326.849602ms","remote":"127.0.0.1:43878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":7564,"request content":"key:\"/registry/pods/capi-system/capi-controller-manager-5647546676-mtbm7\" "} {"level":"warn","ts":"2026-07-06T13:51:44.732944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.500164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/capo-system/\" range_end:\"/registry/persistentvolumeclaims/capo-system0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-06T13:51:44.733039Z","caller":"traceutil/trace.go:171","msg":"trace[1515005538] range","detail":"{range_begin:/registry/persistentvolumeclaims/capo-system/; range_end:/registry/persistentvolumeclaims/capo-system0; response_count:0; response_revision:44248; }","duration":"201.631437ms","start":"2026-07-06T13:51:44.531392Z","end":"2026-07-06T13:51:44.733023Z","steps":["trace[1515005538] 'range keys from in-memory index tree' (duration: 201.404142ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:44.733054Z","caller":"traceutil/trace.go:171","msg":"trace[774826985] transaction","detail":"{read_only:false; response_revision:44249; number_of_response:1; }","duration":"189.325167ms","start":"2026-07-06T13:51:44.543719Z","end":"2026-07-06T13:51:44.733044Z","steps":["trace[774826985] 'process raft request' (duration: 182.187833ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:44.934562Z","caller":"traceutil/trace.go:171","msg":"trace[1793035852] transaction","detail":"{read_only:false; response_revision:44251; number_of_response:1; }","duration":"390.166015ms","start":"2026-07-06T13:51:44.544375Z","end":"2026-07-06T13:51:44.934541Z","steps":["trace[1793035852] 'process raft request' (duration: 390.103594ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:44.934618Z","caller":"traceutil/trace.go:171","msg":"trace[467324172] transaction","detail":"{read_only:false; response_revision:44250; number_of_response:1; }","duration":"390.569155ms","start":"2026-07-06T13:51:44.544011Z","end":"2026-07-06T13:51:44.93458Z","steps":["trace[467324172] 'process raft request' (duration: 390.303159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:44.934703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:44.544369Z","time spent":"390.264938ms","remote":"127.0.0.1:43964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T13:51:44.934778Z","caller":"traceutil/trace.go:171","msg":"trace[285462125] transaction","detail":"{read_only:false; response_revision:44252; number_of_response:1; }","duration":"389.837907ms","start":"2026-07-06T13:51:44.544923Z","end":"2026-07-06T13:51:44.934761Z","steps":["trace[285462125] 'process raft request' (duration: 389.587822ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:44.934753Z","caller":"traceutil/trace.go:171","msg":"trace[1844067783] linearizableReadLoop","detail":"{readStateIndex:45757; appliedIndex:45756; }","duration":"200.919691ms","start":"2026-07-06T13:51:44.733762Z","end":"2026-07-06T13:51:44.934682Z","steps":["trace[1844067783] 'read index received' (duration: 200.822318ms)","trace[1844067783] 'applied index is now lower than readState.Index' (duration: 95.483µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T13:51:44.934867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:44.5449Z","time spent":"389.92374ms","remote":"127.0.0.1:43964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T13:51:44.934829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:44.544002Z","time spent":"390.697468ms","remote":"127.0.0.1:43964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T13:51:44.935058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.693478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/capi-system/capi-controller-manager-5647546676-mtbm7\" ","response":"range_response_count:1 size:7541"} {"level":"info","ts":"2026-07-06T13:51:44.935095Z","caller":"traceutil/trace.go:171","msg":"trace[610608376] range","detail":"{range_begin:/registry/pods/capi-system/capi-controller-manager-5647546676-mtbm7; range_end:; response_count:1; response_revision:44252; }","duration":"197.729529ms","start":"2026-07-06T13:51:44.737357Z","end":"2026-07-06T13:51:44.935086Z","steps":["trace[610608376] 'agreement among raft nodes before linearized reading' (duration: 197.620826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:44.936537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.745411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-controller-7rchw\" ","response":"range_response_count:1 size:7121"} {"level":"info","ts":"2026-07-06T13:51:44.936616Z","caller":"traceutil/trace.go:171","msg":"trace[633843113] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-controller-7rchw; range_end:; response_count:1; response_revision:44252; }","duration":"202.863606ms","start":"2026-07-06T13:51:44.733734Z","end":"2026-07-06T13:51:44.936597Z","steps":["trace[633843113] 'agreement among raft nodes before linearized reading' (duration: 201.167437ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:46.589762Z","caller":"traceutil/trace.go:171","msg":"trace[818732820] transaction","detail":"{read_only:false; response_revision:44261; number_of_response:1; }","duration":"268.049581ms","start":"2026-07-06T13:51:46.321688Z","end":"2026-07-06T13:51:46.589737Z","steps":["trace[818732820] 'process raft request' (duration: 267.709903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:46.5902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.961413ms","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-07-06T13:51:46.590274Z","caller":"traceutil/trace.go:171","msg":"trace[559686209] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:44261; }","duration":"227.073136ms","start":"2026-07-06T13:51:46.363185Z","end":"2026-07-06T13:51:46.590258Z","steps":["trace[559686209] 'agreement among raft nodes before linearized reading' (duration: 226.900632ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:46.591959Z","caller":"traceutil/trace.go:171","msg":"trace[1440925003] linearizableReadLoop","detail":"{readStateIndex:45767; appliedIndex:45767; }","duration":"226.79877ms","start":"2026-07-06T13:51:46.363221Z","end":"2026-07-06T13:51:46.59002Z","steps":["trace[1440925003] 'read index received' (duration: 226.79283ms)","trace[1440925003] 'applied index is now lower than readState.Index' (duration: 4.66µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T13:51:46.592054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.268734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-06T13:51:46.592074Z","caller":"traceutil/trace.go:171","msg":"trace[1431084901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:44261; }","duration":"225.318716ms","start":"2026-07-06T13:51:46.366749Z","end":"2026-07-06T13:51:46.592068Z","steps":["trace[1431084901] 'agreement among raft nodes before linearized reading' (duration: 225.278495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:46.592206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.771529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/default/\" range_end:\"/registry/jobs/default0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-06T13:51:46.592234Z","caller":"traceutil/trace.go:171","msg":"trace[136050152] range","detail":"{range_begin:/registry/jobs/default/; range_end:/registry/jobs/default0; response_count:0; response_revision:44261; }","duration":"172.82629ms","start":"2026-07-06T13:51:46.419399Z","end":"2026-07-06T13:51:46.592226Z","steps":["trace[136050152] 'agreement among raft nodes before linearized reading' (duration: 172.784998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:46.592423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.678304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/capo-system/kube-root-ca.crt\" ","response":"range_response_count:1 size:1749"} {"level":"info","ts":"2026-07-06T13:51:46.592476Z","caller":"traceutil/trace.go:171","msg":"trace[364663193] range","detail":"{range_begin:/registry/configmaps/capo-system/kube-root-ca.crt; range_end:; response_count:1; response_revision:44261; }","duration":"192.750075ms","start":"2026-07-06T13:51:46.399717Z","end":"2026-07-06T13:51:46.592468Z","steps":["trace[364663193] 'agreement among raft nodes before linearized reading' (duration: 192.645643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:46.592662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.116228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/capi-system/default\" ","response":"range_response_count:1 size:182"} {"level":"warn","ts":"2026-07-06T13:51:46.592743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.145494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/envoy-gateway-system/\" range_end:\"/registry/daemonsets/envoy-gateway-system0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-06T13:51:46.592767Z","caller":"traceutil/trace.go:171","msg":"trace[427898217] range","detail":"{range_begin:/registry/daemonsets/envoy-gateway-system/; range_end:/registry/daemonsets/envoy-gateway-system0; response_count:0; response_revision:44261; }","duration":"211.196345ms","start":"2026-07-06T13:51:46.381564Z","end":"2026-07-06T13:51:46.59276Z","steps":["trace[427898217] 'agreement among raft nodes before linearized reading' (duration: 211.156915ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:46.592688Z","caller":"traceutil/trace.go:171","msg":"trace[39364999] range","detail":"{range_begin:/registry/serviceaccounts/capi-system/default; range_end:; response_count:1; response_revision:44261; }","duration":"209.155399ms","start":"2026-07-06T13:51:46.383526Z","end":"2026-07-06T13:51:46.592681Z","steps":["trace[39364999] 'agreement among raft nodes before linearized reading' (duration: 209.070657ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:47.602487Z","caller":"traceutil/trace.go:171","msg":"trace[2039966605] transaction","detail":"{read_only:false; response_revision:44272; number_of_response:1; }","duration":"124.1381ms","start":"2026-07-06T13:51:47.47833Z","end":"2026-07-06T13:51:47.602468Z","steps":["trace[2039966605] 'process raft request' (duration: 108.422112ms)","trace[2039966605] 'compare' (duration: 15.597185ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T13:51:48.069384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.017306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:1083"} {"level":"info","ts":"2026-07-06T13:51:48.06944Z","caller":"traceutil/trace.go:171","msg":"trace[1930963335] range","detail":"{range_begin:/registry/services/endpoints/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:44274; }","duration":"209.107018ms","start":"2026-07-06T13:51:47.860321Z","end":"2026-07-06T13:51:48.069428Z","steps":["trace[1930963335] 'range keys from in-memory index tree' (duration: 208.913583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:48.069642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.32079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/\" range_end:\"/registry/roles/envoy-gateway-system0\" limit:500 ","response":"range_response_count:3 size:3334"} {"level":"info","ts":"2026-07-06T13:51:48.069664Z","caller":"traceutil/trace.go:171","msg":"trace[1436018860] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/; range_end:/registry/roles/envoy-gateway-system0; response_count:3; response_revision:44274; }","duration":"201.379911ms","start":"2026-07-06T13:51:47.868276Z","end":"2026-07-06T13:51:48.069656Z","steps":["trace[1436018860] 'range keys from in-memory index tree' (duration: 201.221108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:48.069777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.23669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/cert-manager/cert-manager\" ","response":"range_response_count:1 size:1096"} {"level":"info","ts":"2026-07-06T13:51:48.069793Z","caller":"traceutil/trace.go:171","msg":"trace[1744774348] range","detail":"{range_begin:/registry/services/endpoints/cert-manager/cert-manager; range_end:; response_count:1; response_revision:44274; }","duration":"198.285281ms","start":"2026-07-06T13:51:47.871504Z","end":"2026-07-06T13:51:48.069789Z","steps":["trace[1744774348] 'range keys from in-memory index tree' (duration: 198.149757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:48.069881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.948947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/\" range_end:\"/registry/serviceaccounts/default0\" limit:500 ","response":"range_response_count:1 size:172"} {"level":"info","ts":"2026-07-06T13:51:48.069897Z","caller":"traceutil/trace.go:171","msg":"trace[562903335] range","detail":"{range_begin:/registry/serviceaccounts/default/; range_end:/registry/serviceaccounts/default0; response_count:1; response_revision:44274; }","duration":"178.005948ms","start":"2026-07-06T13:51:47.891887Z","end":"2026-07-06T13:51:48.069893Z","steps":["trace[562903335] 'range keys from in-memory index tree' (duration: 177.846506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:48.070042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.702299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-06T13:51:48.070062Z","caller":"traceutil/trace.go:171","msg":"trace[1723864301] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:44274; }","duration":"108.75271ms","start":"2026-07-06T13:51:47.961303Z","end":"2026-07-06T13:51:48.070056Z","steps":["trace[1723864301] 'count revisions from in-memory index tree' (duration: 108.630807ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:49.75928Z","caller":"traceutil/trace.go:171","msg":"trace[377326582] transaction","detail":"{read_only:false; response_revision:44287; number_of_response:1; }","duration":"162.579206ms","start":"2026-07-06T13:51:49.596686Z","end":"2026-07-06T13:51:49.759265Z","steps":["trace[377326582] 'process raft request' (duration: 162.484614ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:49.75938Z","caller":"traceutil/trace.go:171","msg":"trace[1568074618] linearizableReadLoop","detail":"{readStateIndex:45793; appliedIndex:45793; }","duration":"152.591938ms","start":"2026-07-06T13:51:49.606766Z","end":"2026-07-06T13:51:49.759357Z","steps":["trace[1568074618] 'read index received' (duration: 152.582708ms)","trace[1568074618] 'applied index is now lower than readState.Index' (duration: 7.25µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T13:51:49.759561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.781533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/capo-system/default\" ","response":"range_response_count:1 size:182"} {"level":"info","ts":"2026-07-06T13:51:49.759578Z","caller":"traceutil/trace.go:171","msg":"trace[536874934] range","detail":"{range_begin:/registry/serviceaccounts/capo-system/default; range_end:; response_count:1; response_revision:44287; }","duration":"152.828864ms","start":"2026-07-06T13:51:49.606744Z","end":"2026-07-06T13:51:49.759573Z","steps":["trace[536874934] 'agreement among raft nodes before linearized reading' (duration: 152.709411ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:50.012768Z","caller":"traceutil/trace.go:171","msg":"trace[1025462930] transaction","detail":"{read_only:false; response_revision:44291; number_of_response:1; }","duration":"194.354272ms","start":"2026-07-06T13:51:49.818395Z","end":"2026-07-06T13:51:50.01275Z","steps":["trace[1025462930] 'process raft request' (duration: 194.325041ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:50.013065Z","caller":"traceutil/trace.go:171","msg":"trace[666624686] transaction","detail":"{read_only:false; response_revision:44290; number_of_response:1; }","duration":"262.693479ms","start":"2026-07-06T13:51:49.75035Z","end":"2026-07-06T13:51:50.013043Z","steps":["trace[666624686] 'process raft request' (duration: 262.340811ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:50.013095Z","caller":"traceutil/trace.go:171","msg":"trace[1178657840] transaction","detail":"{read_only:false; response_revision:44289; number_of_response:1; }","duration":"266.169218ms","start":"2026-07-06T13:51:49.746915Z","end":"2026-07-06T13:51:50.013084Z","steps":["trace[1178657840] 'process raft request' (duration: 265.730707ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:50.013091Z","caller":"traceutil/trace.go:171","msg":"trace[482713788] linearizableReadLoop","detail":"{readStateIndex:45796; appliedIndex:45793; }","duration":"253.632683ms","start":"2026-07-06T13:51:49.759432Z","end":"2026-07-06T13:51:50.013065Z","steps":["trace[482713788] 'read index received' (duration: 162.377332ms)","trace[482713788] 'applied index is now lower than readState.Index' (duration: 91.252431ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T13:51:50.013238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.191999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/cert-manager/cert-manager-cainjector:leaderelection\" ","response":"range_response_count:1 size:1190"} {"level":"info","ts":"2026-07-06T13:51:50.013272Z","caller":"traceutil/trace.go:171","msg":"trace[2055591059] range","detail":"{range_begin:/registry/rolebindings/cert-manager/cert-manager-cainjector:leaderelection; range_end:; response_count:1; response_revision:44291; }","duration":"266.27982ms","start":"2026-07-06T13:51:49.746982Z","end":"2026-07-06T13:51:50.013262Z","steps":["trace[2055591059] 'agreement among raft nodes before linearized reading' (duration: 266.207248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:50.013353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.229795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/\" range_end:\"/registry/pods/ingress-nginx0\" limit:500 ","response":"range_response_count:2 size:11423"} {"level":"info","ts":"2026-07-06T13:51:50.013385Z","caller":"traceutil/trace.go:171","msg":"trace[45632085] range","detail":"{range_begin:/registry/pods/ingress-nginx/; range_end:/registry/pods/ingress-nginx0; response_count:2; response_revision:44291; }","duration":"239.304837ms","start":"2026-07-06T13:51:49.774071Z","end":"2026-07-06T13:51:50.013376Z","steps":["trace[45632085] 'agreement among raft nodes before linearized reading' (duration: 239.190534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:50.013396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.283441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/kube-node-lease/\" range_end:\"/registry/jobs/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-06T13:51:50.013426Z","caller":"traceutil/trace.go:171","msg":"trace[557540464] range","detail":"{range_begin:/registry/jobs/kube-node-lease/; range_end:/registry/jobs/kube-node-lease0; response_count:0; response_revision:44291; }","duration":"248.339282ms","start":"2026-07-06T13:51:49.765077Z","end":"2026-07-06T13:51:50.013417Z","steps":["trace[557540464] 'agreement among raft nodes before linearized reading' (duration: 248.29405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:50.013352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.151196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/capo-system/\" range_end:\"/registry/secrets/capo-system0\" limit:500 ","response":"range_response_count:1 size:5145"} {"level":"info","ts":"2026-07-06T13:51:50.013513Z","caller":"traceutil/trace.go:171","msg":"trace[1308277701] range","detail":"{range_begin:/registry/secrets/capo-system/; range_end:/registry/secrets/capo-system0; response_count:1; response_revision:44291; }","duration":"251.336131ms","start":"2026-07-06T13:51:49.762171Z","end":"2026-07-06T13:51:50.013507Z","steps":["trace[1308277701] 'agreement among raft nodes before linearized reading' (duration: 251.041814ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:50.014358Z","caller":"traceutil/trace.go:171","msg":"trace[1684555718] transaction","detail":"{read_only:false; response_revision:44288; number_of_response:1; }","duration":"405.603298ms","start":"2026-07-06T13:51:49.608724Z","end":"2026-07-06T13:51:50.014327Z","steps":["trace[1684555718] 'process raft request' (duration: 313.073338ms)","trace[1684555718] 'compare' (duration: 90.419542ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T13:51:50.016498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:51:49.608711Z","time spent":"405.926555ms","remote":"127.0.0.1:43964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T13:51:50.78543Z","caller":"traceutil/trace.go:171","msg":"trace[230676018] linearizableReadLoop","detail":"{readStateIndex:45798; appliedIndex:45797; }","duration":"162.430113ms","start":"2026-07-06T13:51:50.622984Z","end":"2026-07-06T13:51:50.785414Z","steps":["trace[230676018] 'read index received' (duration: 162.28048ms)","trace[230676018] 'applied index is now lower than readState.Index' (duration: 149.083µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:51:50.786009Z","caller":"traceutil/trace.go:171","msg":"trace[1224456516] transaction","detail":"{read_only:false; response_revision:44292; number_of_response:1; }","duration":"168.567824ms","start":"2026-07-06T13:51:50.617431Z","end":"2026-07-06T13:51:50.785999Z","steps":["trace[1224456516] 'process raft request' (duration: 167.882308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:50.786178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.180881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/ingress-nginx/\" range_end:\"/registry/roles/ingress-nginx0\" limit:500 ","response":"range_response_count:1 size:1479"} {"level":"info","ts":"2026-07-06T13:51:50.786206Z","caller":"traceutil/trace.go:171","msg":"trace[1558359214] range","detail":"{range_begin:/registry/roles/ingress-nginx/; range_end:/registry/roles/ingress-nginx0; response_count:1; response_revision:44292; }","duration":"163.246213ms","start":"2026-07-06T13:51:50.622952Z","end":"2026-07-06T13:51:50.786198Z","steps":["trace[1558359214] 'agreement among raft nodes before linearized reading' (duration: 163.153231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:50.787085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.540049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/cert-manager/cert-manager-webhook:dynamic-serving\" ","response":"range_response_count:1 size:1096"} {"level":"info","ts":"2026-07-06T13:51:50.78711Z","caller":"traceutil/trace.go:171","msg":"trace[1890617390] range","detail":"{range_begin:/registry/roles/cert-manager/cert-manager-webhook:dynamic-serving; range_end:; response_count:1; response_revision:44292; }","duration":"149.59419ms","start":"2026-07-06T13:51:50.637511Z","end":"2026-07-06T13:51:50.787105Z","steps":["trace[1890617390] 'agreement among raft nodes before linearized reading' (duration: 148.877934ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:50.976592Z","caller":"traceutil/trace.go:171","msg":"trace[552992394] transaction","detail":"{read_only:false; response_revision:44294; number_of_response:1; }","duration":"141.524887ms","start":"2026-07-06T13:51:50.835042Z","end":"2026-07-06T13:51:50.976567Z","steps":["trace[552992394] 'process raft request' (duration: 141.162498ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:51.188986Z","caller":"traceutil/trace.go:171","msg":"trace[1344440562] transaction","detail":"{read_only:false; response_revision:44295; number_of_response:1; }","duration":"140.692258ms","start":"2026-07-06T13:51:51.048269Z","end":"2026-07-06T13:51:51.188961Z","steps":["trace[1344440562] 'process raft request' (duration: 41.774053ms)","trace[1344440562] 'compare' (duration: 98.002774ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:51:51.189309Z","caller":"traceutil/trace.go:171","msg":"trace[438526387] transaction","detail":"{read_only:false; response_revision:44296; number_of_response:1; }","duration":"134.886676ms","start":"2026-07-06T13:51:51.054388Z","end":"2026-07-06T13:51:51.189275Z","steps":["trace[438526387] 'process raft request' (duration: 134.504506ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:51.189362Z","caller":"traceutil/trace.go:171","msg":"trace[854025773] transaction","detail":"{read_only:false; response_revision:44297; number_of_response:1; }","duration":"133.898702ms","start":"2026-07-06T13:51:51.055413Z","end":"2026-07-06T13:51:51.189311Z","steps":["trace[854025773] 'process raft request' (duration: 133.775109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:51.223674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.337557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:1 size:987"} {"level":"info","ts":"2026-07-06T13:51:51.22377Z","caller":"traceutil/trace.go:171","msg":"trace[1680760401] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:1; response_revision:44299; }","duration":"114.46952ms","start":"2026-07-06T13:51:51.109274Z","end":"2026-07-06T13:51:51.223744Z","steps":["trace[1680760401] 'agreement among raft nodes before linearized reading' (duration: 114.281166ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:51.372979Z","caller":"traceutil/trace.go:171","msg":"trace[444332784] transaction","detail":"{read_only:false; response_revision:44301; number_of_response:1; }","duration":"102.038956ms","start":"2026-07-06T13:51:51.270914Z","end":"2026-07-06T13:51:51.372952Z","steps":["trace[444332784] 'process raft request' (duration: 47.074962ms)","trace[444332784] 'compare' (duration: 54.77752ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:51:51.37337Z","caller":"traceutil/trace.go:171","msg":"trace[350365595] transaction","detail":"{read_only:false; response_revision:44302; number_of_response:1; }","duration":"101.981244ms","start":"2026-07-06T13:51:51.271357Z","end":"2026-07-06T13:51:51.373338Z","steps":["trace[350365595] 'process raft request' (duration: 101.549985ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:52.663382Z","caller":"traceutil/trace.go:171","msg":"trace[2113277190] linearizableReadLoop","detail":"{readStateIndex:45815; appliedIndex:45814; }","duration":"153.06479ms","start":"2026-07-06T13:51:52.510292Z","end":"2026-07-06T13:51:52.663357Z","steps":["trace[2113277190] 'read index received' (duration: 152.866156ms)","trace[2113277190] 'applied index is now lower than readState.Index' (duration: 197.734µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T13:51:52.663858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.552341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:1388"} {"level":"info","ts":"2026-07-06T13:51:52.663937Z","caller":"traceutil/trace.go:171","msg":"trace[1030844953] range","detail":"{range_begin:/registry/services/specs/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:44307; }","duration":"153.674994ms","start":"2026-07-06T13:51:52.510243Z","end":"2026-07-06T13:51:52.663918Z","steps":["trace[1030844953] 'agreement among raft nodes before linearized reading' (duration: 153.530231ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:52.663935Z","caller":"traceutil/trace.go:171","msg":"trace[1059433754] transaction","detail":"{read_only:false; response_revision:44307; number_of_response:1; }","duration":"184.880075ms","start":"2026-07-06T13:51:52.479016Z","end":"2026-07-06T13:51:52.663896Z","steps":["trace[1059433754] 'process raft request' (duration: 184.20406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:52.664005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.386484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/\" range_end:\"/registry/pods/kube-system0\" limit:500 ","response":"range_response_count:10 size:67754"} {"level":"info","ts":"2026-07-06T13:51:52.664084Z","caller":"traceutil/trace.go:171","msg":"trace[1384644227] range","detail":"{range_begin:/registry/pods/kube-system/; range_end:/registry/pods/kube-system0; response_count:10; response_revision:44307; }","duration":"152.470686ms","start":"2026-07-06T13:51:52.511595Z","end":"2026-07-06T13:51:52.664065Z","steps":["trace[1384644227] 'agreement among raft nodes before linearized reading' (duration: 152.168169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:52.664021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.616887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/cert-manager/cert-manager\" ","response":"range_response_count:1 size:1416"} {"level":"info","ts":"2026-07-06T13:51:52.664203Z","caller":"traceutil/trace.go:171","msg":"trace[1780690597] range","detail":"{range_begin:/registry/services/specs/cert-manager/cert-manager; range_end:; response_count:1; response_revision:44307; }","duration":"133.830972ms","start":"2026-07-06T13:51:52.530358Z","end":"2026-07-06T13:51:52.664189Z","steps":["trace[1780690597] 'agreement among raft nodes before linearized reading' (duration: 133.555746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:52.897985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.157938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/cert-manager/cert-manager\" ","response":"range_response_count:1 size:1416"} {"level":"info","ts":"2026-07-06T13:51:52.898087Z","caller":"traceutil/trace.go:171","msg":"trace[1620212090] range","detail":"{range_begin:/registry/services/specs/cert-manager/cert-manager; range_end:; response_count:1; response_revision:44307; }","duration":"227.302642ms","start":"2026-07-06T13:51:52.670759Z","end":"2026-07-06T13:51:52.898062Z","steps":["trace[1620212090] 'range keys from in-memory index tree' (duration: 226.984514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:52.898318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.877886ms","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-06T13:51:52.898716Z","caller":"traceutil/trace.go:171","msg":"trace[1417114285] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:44307; }","duration":"103.306585ms","start":"2026-07-06T13:51:52.795391Z","end":"2026-07-06T13:51:52.898698Z","steps":["trace[1417114285] 'range keys from in-memory index tree' (duration: 102.685951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:52.899275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.020467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-06T13:51:52.899374Z","caller":"traceutil/trace.go:171","msg":"trace[1308204531] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:44307; }","duration":"156.150431ms","start":"2026-07-06T13:51:52.743208Z","end":"2026-07-06T13:51:52.899359Z","steps":["trace[1308204531] 'count revisions from in-memory index tree' (duration: 155.964366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:52.899448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.058907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-06T13:51:52.899484Z","caller":"traceutil/trace.go:171","msg":"trace[1840053517] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:44307; }","duration":"145.123829ms","start":"2026-07-06T13:51:52.754351Z","end":"2026-07-06T13:51:52.899475Z","steps":["trace[1840053517] 'count revisions from in-memory index tree' (duration: 145.003236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:52.89845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.084948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-06T13:51:52.899642Z","caller":"traceutil/trace.go:171","msg":"trace[908714901] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:44307; }","duration":"103.314385ms","start":"2026-07-06T13:51:52.79632Z","end":"2026-07-06T13:51:52.899634Z","steps":["trace[908714901] 'count revisions from in-memory index tree' (duration: 102.041577ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:53.172236Z","caller":"traceutil/trace.go:171","msg":"trace[1972137544] linearizableReadLoop","detail":"{readStateIndex:45816; appliedIndex:45815; }","duration":"267.651471ms","start":"2026-07-06T13:51:52.904562Z","end":"2026-07-06T13:51:53.172213Z","steps":["trace[1972137544] 'read index received' (duration: 267.451487ms)","trace[1972137544] 'applied index is now lower than readState.Index' (duration: 198.814µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:51:53.17236Z","caller":"traceutil/trace.go:171","msg":"trace[289398754] transaction","detail":"{read_only:false; response_revision:44308; number_of_response:1; }","duration":"268.923541ms","start":"2026-07-06T13:51:52.903404Z","end":"2026-07-06T13:51:53.172327Z","steps":["trace[289398754] 'process raft request' (duration: 268.617264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:53.172405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.825775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/cert-manager/cert-manager-webhook\" ","response":"range_response_count:1 size:1388"} {"level":"info","ts":"2026-07-06T13:51:53.172445Z","caller":"traceutil/trace.go:171","msg":"trace[913675736] range","detail":"{range_begin:/registry/services/specs/cert-manager/cert-manager-webhook; range_end:; response_count:1; response_revision:44308; }","duration":"267.904497ms","start":"2026-07-06T13:51:52.904524Z","end":"2026-07-06T13:51:53.172428Z","steps":["trace[913675736] 'agreement among raft nodes before linearized reading' (duration: 267.799455ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:53.177176Z","caller":"traceutil/trace.go:171","msg":"trace[369442804] transaction","detail":"{read_only:false; response_revision:44309; number_of_response:1; }","duration":"272.093903ms","start":"2026-07-06T13:51:52.905055Z","end":"2026-07-06T13:51:53.177149Z","steps":["trace[369442804] 'process raft request' (duration: 271.929789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:53.177579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.070777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/cert-manager/cert-manager\" ","response":"range_response_count:1 size:1096"} {"level":"info","ts":"2026-07-06T13:51:53.177613Z","caller":"traceutil/trace.go:171","msg":"trace[994981565] range","detail":"{range_begin:/registry/services/endpoints/cert-manager/cert-manager; range_end:; response_count:1; response_revision:44310; }","duration":"270.146368ms","start":"2026-07-06T13:51:52.907455Z","end":"2026-07-06T13:51:53.177601Z","steps":["trace[994981565] 'agreement among raft nodes before linearized reading' (duration: 269.968114ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:53.177598Z","caller":"traceutil/trace.go:171","msg":"trace[1782746923] transaction","detail":"{read_only:false; response_revision:44310; number_of_response:1; }","duration":"196.894738ms","start":"2026-07-06T13:51:52.98066Z","end":"2026-07-06T13:51:53.177554Z","steps":["trace[1782746923] 'process raft request' (duration: 196.442088ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:58.274834Z","caller":"traceutil/trace.go:171","msg":"trace[318766032] transaction","detail":"{read_only:false; response_revision:44347; number_of_response:1; }","duration":"159.805804ms","start":"2026-07-06T13:51:58.115003Z","end":"2026-07-06T13:51:58.274809Z","steps":["trace[318766032] 'process raft request' (duration: 156.144931ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:58.275652Z","caller":"traceutil/trace.go:171","msg":"trace[1784267287] linearizableReadLoop","detail":"{readStateIndex:45857; appliedIndex:45855; }","duration":"113.173961ms","start":"2026-07-06T13:51:58.162459Z","end":"2026-07-06T13:51:58.275633Z","steps":["trace[1784267287] 'read index received' (duration: 108.711419ms)","trace[1784267287] 'applied index is now lower than readState.Index' (duration: 4.461802ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:51:58.275705Z","caller":"traceutil/trace.go:171","msg":"trace[1354406109] transaction","detail":"{read_only:false; response_revision:44348; number_of_response:1; }","duration":"150.245656ms","start":"2026-07-06T13:51:58.125443Z","end":"2026-07-06T13:51:58.275688Z","steps":["trace[1354406109] 'process raft request' (duration: 150.091782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:58.275869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.393295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/sh.helm.release.v1.ingress-nginx.v1\" ","response":"range_response_count:1 size:105452"} {"level":"warn","ts":"2026-07-06T13:51:58.27588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.197531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/default\" ","response":"range_response_count:1 size:184"} {"level":"info","ts":"2026-07-06T13:51:58.275912Z","caller":"traceutil/trace.go:171","msg":"trace[1765794419] range","detail":"{range_begin:/registry/secrets/ingress-nginx/sh.helm.release.v1.ingress-nginx.v1; range_end:; response_count:1; response_revision:44348; }","duration":"113.473047ms","start":"2026-07-06T13:51:58.162429Z","end":"2026-07-06T13:51:58.275902Z","steps":["trace[1765794419] 'agreement among raft nodes before linearized reading' (duration: 113.306043ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:58.275935Z","caller":"traceutil/trace.go:171","msg":"trace[1072655548] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/default; range_end:; response_count:1; response_revision:44348; }","duration":"113.260422ms","start":"2026-07-06T13:51:58.162663Z","end":"2026-07-06T13:51:58.275924Z","steps":["trace[1072655548] 'agreement among raft nodes before linearized reading' (duration: 113.15767ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:59.164997Z","caller":"traceutil/trace.go:171","msg":"trace[71249809] transaction","detail":"{read_only:false; response_revision:44350; number_of_response:1; }","duration":"108.647957ms","start":"2026-07-06T13:51:59.056331Z","end":"2026-07-06T13:51:59.164979Z","steps":["trace[71249809] 'process raft request' (duration: 108.152765ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:59.444909Z","caller":"traceutil/trace.go:171","msg":"trace[1103631317] transaction","detail":"{read_only:false; response_revision:44352; number_of_response:1; }","duration":"221.837676ms","start":"2026-07-06T13:51:59.223043Z","end":"2026-07-06T13:51:59.444881Z","steps":["trace[1103631317] 'process raft request' (duration: 190.433221ms)","trace[1103631317] 'compare' (duration: 31.293763ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:51:59.445443Z","caller":"traceutil/trace.go:171","msg":"trace[997416125] transaction","detail":"{read_only:false; response_revision:44353; number_of_response:1; }","duration":"199.482548ms","start":"2026-07-06T13:51:59.245941Z","end":"2026-07-06T13:51:59.445423Z","steps":["trace[997416125] 'process raft request' (duration: 199.067629ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:59.445554Z","caller":"traceutil/trace.go:171","msg":"trace[2001707591] transaction","detail":"{read_only:false; response_revision:44354; number_of_response:1; }","duration":"154.356249ms","start":"2026-07-06T13:51:59.291185Z","end":"2026-07-06T13:51:59.445541Z","steps":["trace[2001707591] 'process raft request' (duration: 154.169715ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:59.445575Z","caller":"traceutil/trace.go:171","msg":"trace[378338919] transaction","detail":"{read_only:false; response_revision:44356; number_of_response:1; }","duration":"119.27518ms","start":"2026-07-06T13:51:59.32629Z","end":"2026-07-06T13:51:59.445565Z","steps":["trace[378338919] 'process raft request' (duration: 119.171087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:59.445714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.706994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-06T13:51:59.445758Z","caller":"traceutil/trace.go:171","msg":"trace[1210726080] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:44356; }","duration":"199.794436ms","start":"2026-07-06T13:51:59.245955Z","end":"2026-07-06T13:51:59.445749Z","steps":["trace[1210726080] 'agreement among raft nodes before linearized reading' (duration: 199.647472ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:59.445574Z","caller":"traceutil/trace.go:171","msg":"trace[1212740155] linearizableReadLoop","detail":"{readStateIndex:45862; appliedIndex:45860; }","duration":"199.567801ms","start":"2026-07-06T13:51:59.245991Z","end":"2026-07-06T13:51:59.445559Z","steps":["trace[1212740155] 'read index received' (duration: 167.711734ms)","trace[1212740155] 'applied index is now lower than readState.Index' (duration: 31.855276ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:51:59.445537Z","caller":"traceutil/trace.go:171","msg":"trace[780948709] transaction","detail":"{read_only:false; response_revision:44355; number_of_response:1; }","duration":"119.618847ms","start":"2026-07-06T13:51:59.325884Z","end":"2026-07-06T13:51:59.445503Z","steps":["trace[780948709] 'process raft request' (duration: 119.535105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:59.445901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.549624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6532"} {"level":"info","ts":"2026-07-06T13:51:59.445937Z","caller":"traceutil/trace.go:171","msg":"trace[450580837] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:44356; }","duration":"172.622626ms","start":"2026-07-06T13:51:59.273306Z","end":"2026-07-06T13:51:59.445929Z","steps":["trace[450580837] 'agreement among raft nodes before linearized reading' (duration: 172.531334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:51:59.446012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.82121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-07-06T13:51:59.446017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.936605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-defaultbackend\" ","response":"range_response_count:1 size:1510"} {"level":"info","ts":"2026-07-06T13:51:59.446069Z","caller":"traceutil/trace.go:171","msg":"trace[63094214] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:44356; }","duration":"115.916713ms","start":"2026-07-06T13:51:59.330136Z","end":"2026-07-06T13:51:59.446053Z","steps":["trace[63094214] 'agreement among raft nodes before linearized reading' (duration: 115.738859ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:51:59.446089Z","caller":"traceutil/trace.go:171","msg":"trace[1244521818] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-defaultbackend; range_end:; response_count:1; response_revision:44356; }","duration":"163.018897ms","start":"2026-07-06T13:51:59.283042Z","end":"2026-07-06T13:51:59.44606Z","steps":["trace[1244521818] 'agreement among raft nodes before linearized reading' (duration: 162.845173ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:00.390234Z","caller":"traceutil/trace.go:171","msg":"trace[510189478] transaction","detail":"{read_only:false; response_revision:44363; number_of_response:1; }","duration":"109.123307ms","start":"2026-07-06T13:52:00.281092Z","end":"2026-07-06T13:52:00.390215Z","steps":["trace[510189478] 'process raft request' (duration: 109.008295ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:00.493796Z","caller":"traceutil/trace.go:171","msg":"trace[2026727712] linearizableReadLoop","detail":"{readStateIndex:45873; appliedIndex:45872; }","duration":"160.406596ms","start":"2026-07-06T13:52:00.333368Z","end":"2026-07-06T13:52:00.493775Z","steps":["trace[2026727712] 'read index received' (duration: 56.872796ms)","trace[2026727712] 'applied index is now lower than readState.Index' (duration: 103.53292ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:52:00.493861Z","caller":"traceutil/trace.go:171","msg":"trace[1925945999] transaction","detail":"{read_only:false; response_revision:44364; number_of_response:1; }","duration":"211.308337ms","start":"2026-07-06T13:52:00.282529Z","end":"2026-07-06T13:52:00.493837Z","steps":["trace[1925945999] 'process raft request' (duration: 202.078357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:00.494061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.357421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/default\" ","response":"range_response_count:1 size:176"} {"level":"info","ts":"2026-07-06T13:52:00.494093Z","caller":"traceutil/trace.go:171","msg":"trace[881670749] range","detail":"{range_begin:/registry/serviceaccounts/openstack/default; range_end:; response_count:1; response_revision:44364; }","duration":"133.460453ms","start":"2026-07-06T13:52:00.360625Z","end":"2026-07-06T13:52:00.494085Z","steps":["trace[881670749] 'agreement among raft nodes before linearized reading' (duration: 133.267119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:00.494326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.804839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-root-ca.crt\" ","response":"range_response_count:1 size:1747"} {"level":"warn","ts":"2026-07-06T13:52:00.494367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.9684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1084"} {"level":"info","ts":"2026-07-06T13:52:00.494426Z","caller":"traceutil/trace.go:171","msg":"trace[40405786] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-root-ca.crt; range_end:; response_count:1; response_revision:44364; }","duration":"105.092876ms","start":"2026-07-06T13:52:00.389317Z","end":"2026-07-06T13:52:00.49441Z","steps":["trace[40405786] 'agreement among raft nodes before linearized reading' (duration: 104.653506ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:00.494472Z","caller":"traceutil/trace.go:171","msg":"trace[1377440295] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:44364; }","duration":"161.120002ms","start":"2026-07-06T13:52:00.333328Z","end":"2026-07-06T13:52:00.494448Z","steps":["trace[1377440295] 'agreement among raft nodes before linearized reading' (duration: 160.536739ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:01.001608Z","caller":"traceutil/trace.go:171","msg":"trace[1050379435] linearizableReadLoop","detail":"{readStateIndex:45874; appliedIndex:45873; }","duration":"156.395895ms","start":"2026-07-06T13:52:00.845181Z","end":"2026-07-06T13:52:01.001577Z","steps":["trace[1050379435] 'read index received' (duration: 152.996078ms)","trace[1050379435] 'applied index is now lower than readState.Index' (duration: 3.398487ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T13:52:01.001841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.841362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-public/cluster-info\" ","response":"range_response_count:1 size:2178"} {"level":"warn","ts":"2026-07-06T13:52:01.001831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.631481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-public/kube-root-ca.crt\" ","response":"range_response_count:1 size:1747"} {"level":"info","ts":"2026-07-06T13:52:01.001882Z","caller":"traceutil/trace.go:171","msg":"trace[1280092311] range","detail":"{range_begin:/registry/configmaps/kube-public/cluster-info; range_end:; response_count:1; response_revision:44364; }","duration":"151.929733ms","start":"2026-07-06T13:52:00.849942Z","end":"2026-07-06T13:52:01.001872Z","steps":["trace[1280092311] 'agreement among raft nodes before linearized reading' (duration: 151.762039ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:01.001897Z","caller":"traceutil/trace.go:171","msg":"trace[1451739529] range","detail":"{range_begin:/registry/configmaps/kube-public/kube-root-ca.crt; range_end:; response_count:1; response_revision:44364; }","duration":"156.734723ms","start":"2026-07-06T13:52:00.845146Z","end":"2026-07-06T13:52:01.001881Z","steps":["trace[1451739529] 'agreement among raft nodes before linearized reading' (duration: 156.536669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:01.909808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.701479ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-06T13:52:01.909901Z","caller":"traceutil/trace.go:171","msg":"trace[357658444] linearizableReadLoop","detail":"{readStateIndex:45884; appliedIndex:45883; }","duration":"289.344416ms","start":"2026-07-06T13:52:01.620541Z","end":"2026-07-06T13:52:01.909885Z","steps":["trace[357658444] 'read index received' (duration: 112.459404ms)","trace[357658444] 'applied index is now lower than readState.Index' (duration: 176.883962ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:52:01.909924Z","caller":"traceutil/trace.go:171","msg":"trace[1758543821] transaction","detail":"{read_only:false; response_revision:44374; number_of_response:1; }","duration":"293.581523ms","start":"2026-07-06T13:52:01.616312Z","end":"2026-07-06T13:52:01.909894Z","steps":["trace[1758543821] 'process raft request' (duration: 116.71052ms)","trace[1758543821] 'compare' (duration: 175.890691ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T13:52:01.910162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.063829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4220"} {"level":"info","ts":"2026-07-06T13:52:01.910443Z","caller":"traceutil/trace.go:171","msg":"trace[359135729] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:44374; }","duration":"167.373116ms","start":"2026-07-06T13:52:01.743045Z","end":"2026-07-06T13:52:01.910418Z","steps":["trace[359135729] 'agreement among raft nodes before linearized reading' (duration: 166.946636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:01.910368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.815078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:36 size:28830"} {"level":"info","ts":"2026-07-06T13:52:01.910612Z","caller":"traceutil/trace.go:171","msg":"trace[1459101908] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:36; response_revision:44374; }","duration":"290.056853ms","start":"2026-07-06T13:52:01.620472Z","end":"2026-07-06T13:52:01.910529Z","steps":["trace[1459101908] 'agreement among raft nodes before linearized reading' (duration: 289.486029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:02.745139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.951465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/kube-prometheus-stack-kube-scheduler\" ","response":"range_response_count:1 size:1284"} {"level":"info","ts":"2026-07-06T13:52:02.745279Z","caller":"traceutil/trace.go:171","msg":"trace[162640481] range","detail":"{range_begin:/registry/services/endpoints/kube-system/kube-prometheus-stack-kube-scheduler; range_end:; response_count:1; response_revision:44376; }","duration":"284.112398ms","start":"2026-07-06T13:52:02.461123Z","end":"2026-07-06T13:52:02.745236Z","steps":["trace[162640481] 'range keys from in-memory index tree' (duration: 283.763811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:02.745569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.119489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/kube-prometheus-stack-kube-proxy\" ","response":"range_response_count:1 size:1261"} {"level":"info","ts":"2026-07-06T13:52:02.745615Z","caller":"traceutil/trace.go:171","msg":"trace[11253836] range","detail":"{range_begin:/registry/services/endpoints/kube-system/kube-prometheus-stack-kube-proxy; range_end:; response_count:1; response_revision:44376; }","duration":"284.1994ms","start":"2026-07-06T13:52:02.461403Z","end":"2026-07-06T13:52:02.745602Z","steps":["trace[11253836] 'range keys from in-memory index tree' (duration: 283.994166ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:02.745762Z","caller":"traceutil/trace.go:171","msg":"trace[878678927] transaction","detail":"{read_only:false; response_revision:44377; number_of_response:1; }","duration":"247.269407ms","start":"2026-07-06T13:52:02.49848Z","end":"2026-07-06T13:52:02.745749Z","steps":["trace[878678927] 'process raft request' (duration: 231.690871ms)","trace[878678927] 'compare' (duration: 15.278909ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:52:02.745767Z","caller":"traceutil/trace.go:171","msg":"trace[1843774869] linearizableReadLoop","detail":"{readStateIndex:45887; appliedIndex:45886; }","duration":"243.357508ms","start":"2026-07-06T13:52:02.502371Z","end":"2026-07-06T13:52:02.745729Z","steps":["trace[1843774869] 'read index received' (duration: 227.796963ms)","trace[1843774869] 'applied index is now lower than readState.Index' (duration: 15.559185ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T13:52:02.745909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.513552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-06T13:52:02.745968Z","caller":"traceutil/trace.go:171","msg":"trace[1069881862] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:44377; }","duration":"243.581253ms","start":"2026-07-06T13:52:02.502362Z","end":"2026-07-06T13:52:02.745943Z","steps":["trace[1069881862] 'agreement among raft nodes before linearized reading' (duration: 243.472691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:02.989852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.405025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/kube-prometheus-stack-kube-proxy\" ","response":"range_response_count:1 size:1261"} {"level":"info","ts":"2026-07-06T13:52:02.989919Z","caller":"traceutil/trace.go:171","msg":"trace[791318073] range","detail":"{range_begin:/registry/services/endpoints/kube-system/kube-prometheus-stack-kube-proxy; range_end:; response_count:1; response_revision:44378; }","duration":"141.514907ms","start":"2026-07-06T13:52:02.848388Z","end":"2026-07-06T13:52:02.989903Z","steps":["trace[791318073] 'range keys from in-memory index tree' (duration: 141.2266ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:03.413267Z","caller":"traceutil/trace.go:171","msg":"trace[1751856801] linearizableReadLoop","detail":"{readStateIndex:45889; appliedIndex:45888; }","duration":"177.66803ms","start":"2026-07-06T13:52:03.235576Z","end":"2026-07-06T13:52:03.413244Z","steps":["trace[1751856801] 'read index received' (duration: 177.488016ms)","trace[1751856801] 'applied index is now lower than readState.Index' (duration: 179.044µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:52:03.413433Z","caller":"traceutil/trace.go:171","msg":"trace[1765880697] transaction","detail":"{read_only:false; response_revision:44379; number_of_response:1; }","duration":"178.11732ms","start":"2026-07-06T13:52:03.23523Z","end":"2026-07-06T13:52:03.413347Z","steps":["trace[1765880697] 'process raft request' (duration: 177.835564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:03.414049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.451878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/orc-system/\" range_end:\"/registry/daemonsets/orc-system0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-06T13:52:03.414103Z","caller":"traceutil/trace.go:171","msg":"trace[1433027667] range","detail":"{range_begin:/registry/daemonsets/orc-system/; range_end:/registry/daemonsets/orc-system0; response_count:0; response_revision:44379; }","duration":"178.548159ms","start":"2026-07-06T13:52:03.235541Z","end":"2026-07-06T13:52:03.414089Z","steps":["trace[1433027667] 'agreement among raft nodes before linearized reading' (duration: 177.881945ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:05.769966Z","caller":"traceutil/trace.go:171","msg":"trace[327267246] transaction","detail":"{read_only:false; response_revision:44401; number_of_response:1; }","duration":"101.100535ms","start":"2026-07-06T13:52:05.668844Z","end":"2026-07-06T13:52:05.769944Z","steps":["trace[327267246] 'process raft request' (duration: 100.966852ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:05.773874Z","caller":"traceutil/trace.go:171","msg":"trace[293902586] transaction","detail":"{read_only:false; response_revision:44402; number_of_response:1; }","duration":"101.447173ms","start":"2026-07-06T13:52:05.67241Z","end":"2026-07-06T13:52:05.773857Z","steps":["trace[293902586] 'process raft request' (duration: 101.31825ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:08.175051Z","caller":"traceutil/trace.go:171","msg":"trace[1687235147] transaction","detail":"{read_only:false; response_revision:44417; number_of_response:1; }","duration":"366.846664ms","start":"2026-07-06T13:52:07.808164Z","end":"2026-07-06T13:52:08.175011Z","steps":["trace[1687235147] 'process raft request' (duration: 365.518953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:08.175264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:52:07.808146Z","time spent":"367.009037ms","remote":"127.0.0.1:43964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T13:52:08.176615Z","caller":"traceutil/trace.go:171","msg":"trace[1222371456] linearizableReadLoop","detail":"{readStateIndex:45929; appliedIndex:45927; }","duration":"347.653306ms","start":"2026-07-06T13:52:07.828941Z","end":"2026-07-06T13:52:08.176595Z","steps":["trace[1222371456] 'read index received' (duration: 344.633357ms)","trace[1222371456] 'applied index is now lower than readState.Index' (duration: 3.019419ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:52:08.176709Z","caller":"traceutil/trace.go:171","msg":"trace[883091427] transaction","detail":"{read_only:false; response_revision:44418; number_of_response:1; }","duration":"368.507701ms","start":"2026-07-06T13:52:07.80819Z","end":"2026-07-06T13:52:08.176698Z","steps":["trace[883091427] 'process raft request' (duration: 368.258515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:08.17682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:52:07.808172Z","time spent":"368.557762ms","remote":"127.0.0.1:43964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T13:52:08.176922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.619165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-0ov1te\" ","response":"range_response_count:1 size:541"} {"level":"warn","ts":"2026-07-06T13:52:08.176958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.008205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-tcpd8c\" ","response":"range_response_count:1 size:693"} {"level":"info","ts":"2026-07-06T13:52:08.177003Z","caller":"traceutil/trace.go:171","msg":"trace[1158747675] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-0ov1te; range_end:; response_count:1; response_revision:44418; }","duration":"343.733337ms","start":"2026-07-06T13:52:07.833247Z","end":"2026-07-06T13:52:08.17698Z","steps":["trace[1158747675] 'agreement among raft nodes before linearized reading' (duration: 343.503231ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:08.177024Z","caller":"traceutil/trace.go:171","msg":"trace[1384048235] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-tcpd8c; range_end:; response_count:1; response_revision:44418; }","duration":"348.100517ms","start":"2026-07-06T13:52:07.828902Z","end":"2026-07-06T13:52:08.177003Z","steps":["trace[1384048235] 'agreement among raft nodes before linearized reading' (duration: 347.877462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:08.177059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:52:07.833233Z","time spent":"343.81758ms","remote":"127.0.0.1:43826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":564,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-0ov1te\" "} {"level":"warn","ts":"2026-07-06T13:52:08.177082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:52:07.828887Z","time spent":"348.184429ms","remote":"127.0.0.1:43826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":716,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-tcpd8c\" "} {"level":"warn","ts":"2026-07-06T13:52:08.177117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.560396ms","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-06T13:52:08.177207Z","caller":"traceutil/trace.go:171","msg":"trace[1129113259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:44418; }","duration":"208.670749ms","start":"2026-07-06T13:52:07.968515Z","end":"2026-07-06T13:52:08.177186Z","steps":["trace[1129113259] 'agreement among raft nodes before linearized reading' (duration: 208.260668ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:08.970252Z","caller":"traceutil/trace.go:171","msg":"trace[142180945] linearizableReadLoop","detail":"{readStateIndex:45933; appliedIndex:45932; }","duration":"124.212432ms","start":"2026-07-06T13:52:08.846006Z","end":"2026-07-06T13:52:08.970218Z","steps":["trace[142180945] 'read index received' (duration: 123.858444ms)","trace[142180945] 'applied index is now lower than readState.Index' (duration: 352.578µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:52:08.97044Z","caller":"traceutil/trace.go:171","msg":"trace[166435680] transaction","detail":"{read_only:false; response_revision:44422; number_of_response:1; }","duration":"146.851948ms","start":"2026-07-06T13:52:08.823549Z","end":"2026-07-06T13:52:08.970401Z","steps":["trace[166435680] 'process raft request' (duration: 146.428269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:08.970525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.504529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:198"} {"level":"info","ts":"2026-07-06T13:52:08.970573Z","caller":"traceutil/trace.go:171","msg":"trace[387448945] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:44422; }","duration":"124.606012ms","start":"2026-07-06T13:52:08.845954Z","end":"2026-07-06T13:52:08.97056Z","steps":["trace[387448945] 'agreement among raft nodes before linearized reading' (duration: 124.451498ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:11.810653Z","caller":"traceutil/trace.go:171","msg":"trace[23390324] linearizableReadLoop","detail":"{readStateIndex:45950; appliedIndex:45949; }","duration":"206.024648ms","start":"2026-07-06T13:52:11.604603Z","end":"2026-07-06T13:52:11.810627Z","steps":["trace[23390324] 'read index received' (duration: 205.894765ms)","trace[23390324] 'applied index is now lower than readState.Index' (duration: 128.843µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:52:11.810765Z","caller":"traceutil/trace.go:171","msg":"trace[832958565] transaction","detail":"{read_only:false; response_revision:44438; number_of_response:1; }","duration":"313.706212ms","start":"2026-07-06T13:52:11.497045Z","end":"2026-07-06T13:52:11.810751Z","steps":["trace[832958565] 'process raft request' (duration: 313.421606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:11.810899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:52:11.497015Z","time spent":"313.778594ms","remote":"127.0.0.1:43964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T13:52:11.81119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.58322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:194"} {"level":"info","ts":"2026-07-06T13:52:11.811251Z","caller":"traceutil/trace.go:171","msg":"trace[372594932] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:44438; }","duration":"206.670922ms","start":"2026-07-06T13:52:11.604564Z","end":"2026-07-06T13:52:11.811235Z","steps":["trace[372594932] 'agreement among raft nodes before linearized reading' (duration: 206.495608ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:11.822545Z","caller":"traceutil/trace.go:171","msg":"trace[2138589089] transaction","detail":"{read_only:false; response_revision:44439; number_of_response:1; }","duration":"137.393272ms","start":"2026-07-06T13:52:11.68513Z","end":"2026-07-06T13:52:11.822523Z","steps":["trace[2138589089] 'process raft request' (duration: 136.897311ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:11.822749Z","caller":"traceutil/trace.go:171","msg":"trace[1619188387] transaction","detail":"{read_only:false; response_revision:44440; number_of_response:1; }","duration":"133.567965ms","start":"2026-07-06T13:52:11.68917Z","end":"2026-07-06T13:52:11.822738Z","steps":["trace[1619188387] 'process raft request' (duration: 132.970532ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:11.82287Z","caller":"traceutil/trace.go:171","msg":"trace[395097281] transaction","detail":"{read_only:false; response_revision:44441; number_of_response:1; }","duration":"129.701697ms","start":"2026-07-06T13:52:11.693159Z","end":"2026-07-06T13:52:11.82286Z","steps":["trace[395097281] 'process raft request' (duration: 129.025552ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:11.822986Z","caller":"traceutil/trace.go:171","msg":"trace[402988834] transaction","detail":"{read_only:false; response_revision:44442; number_of_response:1; }","duration":"128.328326ms","start":"2026-07-06T13:52:11.69465Z","end":"2026-07-06T13:52:11.822978Z","steps":["trace[402988834] 'process raft request' (duration: 127.564958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:11.82334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.782493ms","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-06T13:52:11.823381Z","caller":"traceutil/trace.go:171","msg":"trace[721759389] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:44442; }","duration":"120.863375ms","start":"2026-07-06T13:52:11.702506Z","end":"2026-07-06T13:52:11.82337Z","steps":["trace[721759389] 'agreement among raft nodes before linearized reading' (duration: 120.719872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:11.823546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.81355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/kube-prometheus-stack-kube-controller-manager\" ","response":"range_response_count:1 size:1487"} {"level":"info","ts":"2026-07-06T13:52:11.823578Z","caller":"traceutil/trace.go:171","msg":"trace[1559156260] range","detail":"{range_begin:/registry/services/specs/kube-system/kube-prometheus-stack-kube-controller-manager; range_end:; response_count:1; response_revision:44442; }","duration":"215.844261ms","start":"2026-07-06T13:52:11.607724Z","end":"2026-07-06T13:52:11.823568Z","steps":["trace[1559156260] 'agreement among raft nodes before linearized reading' (duration: 215.759669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:12.190548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.202834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-06T13:52:12.190856Z","caller":"traceutil/trace.go:171","msg":"trace[1121069146] linearizableReadLoop","detail":"{readStateIndex:45956; appliedIndex:45955; }","duration":"245.356874ms","start":"2026-07-06T13:52:11.945481Z","end":"2026-07-06T13:52:12.190838Z","steps":["trace[1121069146] 'read index received' (duration: 130.861714ms)","trace[1121069146] 'applied index is now lower than readState.Index' (duration: 114.49359ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:52:12.190922Z","caller":"traceutil/trace.go:171","msg":"trace[695733257] transaction","detail":"{read_only:false; response_revision:44444; number_of_response:1; }","duration":"361.174725ms","start":"2026-07-06T13:52:11.829713Z","end":"2026-07-06T13:52:12.190887Z","steps":["trace[695733257] 'process raft request' (duration: 246.556111ms)","trace[695733257] 'compare' (duration: 113.621001ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T13:52:12.191036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.538198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:194"} {"level":"warn","ts":"2026-07-06T13:52:12.19107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:52:11.829691Z","time spent":"361.305528ms","remote":"127.0.0.1:43964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T13:52:12.191088Z","caller":"traceutil/trace.go:171","msg":"trace[2057380592] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:44444; }","duration":"245.62542ms","start":"2026-07-06T13:52:11.945449Z","end":"2026-07-06T13:52:12.191074Z","steps":["trace[2057380592] 'agreement among raft nodes before linearized reading' (duration: 245.458236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:12.191605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.307035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/kube-prometheus-stack-kube-controller-manager\" ","response":"range_response_count:1 size:1487"} {"level":"info","ts":"2026-07-06T13:52:12.191719Z","caller":"traceutil/trace.go:171","msg":"trace[1460509483] range","detail":"{range_begin:/registry/services/specs/kube-system/kube-prometheus-stack-kube-controller-manager; range_end:; response_count:1; response_revision:44444; }","duration":"235.472969ms","start":"2026-07-06T13:52:11.956231Z","end":"2026-07-06T13:52:12.191704Z","steps":["trace[1460509483] 'agreement among raft nodes before linearized reading' (duration: 234.795613ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:12.369701Z","caller":"traceutil/trace.go:171","msg":"trace[731417902] linearizableReadLoop","detail":"{readStateIndex:45957; appliedIndex:45956; }","duration":"147.467641ms","start":"2026-07-06T13:52:12.222213Z","end":"2026-07-06T13:52:12.36968Z","steps":["trace[731417902] 'read index received' (duration: 140.216786ms)","trace[731417902] 'applied index is now lower than readState.Index' (duration: 7.250075ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:52:12.369821Z","caller":"traceutil/trace.go:171","msg":"trace[816076096] transaction","detail":"{read_only:false; response_revision:44446; number_of_response:1; }","duration":"121.591912ms","start":"2026-07-06T13:52:12.248204Z","end":"2026-07-06T13:52:12.369796Z","steps":["trace[816076096] 'process raft request' (duration: 121.412038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:12.370172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.959553ms","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-07-06T13:52:12.37022Z","caller":"traceutil/trace.go:171","msg":"trace[1508366687] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:44446; }","duration":"148.054125ms","start":"2026-07-06T13:52:12.222148Z","end":"2026-07-06T13:52:12.370202Z","steps":["trace[1508366687] 'agreement among raft nodes before linearized reading' (duration: 147.899841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:12.37041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.882602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:36 size:28830"} {"level":"warn","ts":"2026-07-06T13:52:12.370562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.652167ms","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-07-06T13:52:12.370619Z","caller":"traceutil/trace.go:171","msg":"trace[493970068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:44446; }","duration":"140.727279ms","start":"2026-07-06T13:52:12.22988Z","end":"2026-07-06T13:52:12.370607Z","steps":["trace[493970068] 'agreement among raft nodes before linearized reading' (duration: 140.613546ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:12.370758Z","caller":"traceutil/trace.go:171","msg":"trace[1544824010] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:36; response_revision:44446; }","duration":"145.119549ms","start":"2026-07-06T13:52:12.225476Z","end":"2026-07-06T13:52:12.370596Z","steps":["trace[1544824010] 'agreement among raft nodes before linearized reading' (duration: 144.573745ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:12.371591Z","caller":"traceutil/trace.go:171","msg":"trace[476526926] transaction","detail":"{read_only:false; response_revision:44445; number_of_response:1; }","duration":"148.091096ms","start":"2026-07-06T13:52:12.22178Z","end":"2026-07-06T13:52:12.369871Z","steps":["trace[476526926] 'process raft request' (duration: 140.636657ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:13.115206Z","caller":"traceutil/trace.go:171","msg":"trace[173078918] linearizableReadLoop","detail":"{readStateIndex:45963; appliedIndex:45962; }","duration":"106.503588ms","start":"2026-07-06T13:52:13.008678Z","end":"2026-07-06T13:52:13.115181Z","steps":["trace[173078918] 'read index received' (duration: 106.268462ms)","trace[173078918] 'applied index is now lower than readState.Index' (duration: 234.156µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:52:13.115586Z","caller":"traceutil/trace.go:171","msg":"trace[150874654] transaction","detail":"{read_only:false; response_revision:44451; number_of_response:1; }","duration":"122.317058ms","start":"2026-07-06T13:52:12.993256Z","end":"2026-07-06T13:52:13.115573Z","steps":["trace[150874654] 'process raft request' (duration: 121.742975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:13.115873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.181295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:196"} {"level":"info","ts":"2026-07-06T13:52:13.115915Z","caller":"traceutil/trace.go:171","msg":"trace[1263720297] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:44451; }","duration":"107.267936ms","start":"2026-07-06T13:52:13.008637Z","end":"2026-07-06T13:52:13.115905Z","steps":["trace[1263720297] 'agreement among raft nodes before linearized reading' (duration: 107.128483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:13.896315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.828976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:208"} {"level":"info","ts":"2026-07-06T13:52:13.896383Z","caller":"traceutil/trace.go:171","msg":"trace[324826852] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:44457; }","duration":"106.932868ms","start":"2026-07-06T13:52:13.789434Z","end":"2026-07-06T13:52:13.896367Z","steps":["trace[324826852] 'agreement among raft nodes before linearized reading' (duration: 106.720043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:13.896581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.26682ms","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-07-06T13:52:13.896669Z","caller":"traceutil/trace.go:171","msg":"trace[1830482255] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:44457; }","duration":"116.396933ms","start":"2026-07-06T13:52:13.780253Z","end":"2026-07-06T13:52:13.89665Z","steps":["trace[1830482255] 'agreement among raft nodes before linearized reading' (duration: 116.010314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:13.896661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.014596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-06T13:52:13.89677Z","caller":"traceutil/trace.go:171","msg":"trace[1404945758] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:44457; }","duration":"131.18286ms","start":"2026-07-06T13:52:13.765562Z","end":"2026-07-06T13:52:13.896745Z","steps":["trace[1404945758] 'agreement among raft nodes before linearized reading' (duration: 130.807372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:14.644677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.949084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:210"} {"level":"info","ts":"2026-07-06T13:52:14.644729Z","caller":"traceutil/trace.go:171","msg":"trace[1290508642] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:44463; }","duration":"116.009535ms","start":"2026-07-06T13:52:14.528708Z","end":"2026-07-06T13:52:14.644718Z","steps":["trace[1290508642] 'range keys from in-memory index tree' (duration: 115.81562ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:16.068114Z","caller":"traceutil/trace.go:171","msg":"trace[1984211989] transaction","detail":"{read_only:false; response_revision:44468; number_of_response:1; }","duration":"166.525096ms","start":"2026-07-06T13:52:15.901569Z","end":"2026-07-06T13:52:16.068094Z","steps":["trace[1984211989] 'process raft request' (duration: 152.534377ms)","trace[1984211989] 'compare' (duration: 13.834986ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:52:16.068387Z","caller":"traceutil/trace.go:171","msg":"trace[177992280] transaction","detail":"{read_only:false; response_revision:44469; number_of_response:1; }","duration":"166.477476ms","start":"2026-07-06T13:52:15.901875Z","end":"2026-07-06T13:52:16.068353Z","steps":["trace[177992280] 'process raft request' (duration: 166.183198ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:16.068485Z","caller":"traceutil/trace.go:171","msg":"trace[1989082412] transaction","detail":"{read_only:false; response_revision:44470; number_of_response:1; }","duration":"166.546067ms","start":"2026-07-06T13:52:15.901918Z","end":"2026-07-06T13:52:16.068464Z","steps":["trace[1989082412] 'process raft request' (duration: 166.319191ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:16.06868Z","caller":"traceutil/trace.go:171","msg":"trace[1737392832] transaction","detail":"{read_only:false; response_revision:44471; number_of_response:1; }","duration":"141.728592ms","start":"2026-07-06T13:52:15.926938Z","end":"2026-07-06T13:52:16.068666Z","steps":["trace[1737392832] 'process raft request' (duration: 141.382304ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:19.450794Z","caller":"traceutil/trace.go:171","msg":"trace[160654968] linearizableReadLoop","detail":"{readStateIndex:46007; appliedIndex:46006; }","duration":"278.365876ms","start":"2026-07-06T13:52:19.172407Z","end":"2026-07-06T13:52:19.450773Z","steps":["trace[160654968] 'read index received' (duration: 278.116341ms)","trace[160654968] 'applied index is now lower than readState.Index' (duration: 248.585µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:52:19.450864Z","caller":"traceutil/trace.go:171","msg":"trace[2123423348] transaction","detail":"{read_only:false; response_revision:44494; number_of_response:1; }","duration":"296.631913ms","start":"2026-07-06T13:52:19.154188Z","end":"2026-07-06T13:52:19.45082Z","steps":["trace[2123423348] 'process raft request' (duration: 296.443549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:19.451018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.391847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-grafana\" ","response":"range_response_count:1 size:2425"} {"level":"info","ts":"2026-07-06T13:52:19.451056Z","caller":"traceutil/trace.go:171","msg":"trace[1568455933] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-grafana; range_end:; response_count:1; response_revision:44494; }","duration":"260.48584ms","start":"2026-07-06T13:52:19.19056Z","end":"2026-07-06T13:52:19.451046Z","steps":["trace[1568455933] 'agreement among raft nodes before linearized reading' (duration: 260.340806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:19.451124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.686873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-grafana-config-dashboards\" ","response":"range_response_count:1 size:1216"} {"level":"info","ts":"2026-07-06T13:52:19.451222Z","caller":"traceutil/trace.go:171","msg":"trace[486627771] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-grafana-config-dashboards; range_end:; response_count:1; response_revision:44494; }","duration":"278.826947ms","start":"2026-07-06T13:52:19.17237Z","end":"2026-07-06T13:52:19.451197Z","steps":["trace[486627771] 'agreement among raft nodes before linearized reading' (duration: 278.49882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:33.257927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.714599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-06T13:52:33.258049Z","caller":"traceutil/trace.go:171","msg":"trace[447517928] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:44597; }","duration":"158.885773ms","start":"2026-07-06T13:52:33.099137Z","end":"2026-07-06T13:52:33.258023Z","steps":["trace[447517928] 'count revisions from in-memory index tree' (duration: 158.631497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:33.258188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.246718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-nova-server-conf\" ","response":"range_response_count:1 size:1540"} {"level":"info","ts":"2026-07-06T13:52:33.258266Z","caller":"traceutil/trace.go:171","msg":"trace[1510202781] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-nova-server-conf; range_end:; response_count:1; response_revision:44597; }","duration":"126.360711ms","start":"2026-07-06T13:52:33.131884Z","end":"2026-07-06T13:52:33.258244Z","steps":["trace[1510202781] 'range keys from in-memory index tree' (duration: 126.037684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:33.258194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.578224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-octavia-plugins-conf\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-07-06T13:52:33.258393Z","caller":"traceutil/trace.go:171","msg":"trace[1879890892] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-octavia-plugins-conf; range_end:; response_count:1; response_revision:44597; }","duration":"143.79595ms","start":"2026-07-06T13:52:33.114568Z","end":"2026-07-06T13:52:33.258364Z","steps":["trace[1879890892] 'range keys from in-memory index tree' (duration: 143.450491ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:40.534967Z","caller":"traceutil/trace.go:171","msg":"trace[1749361076] transaction","detail":"{read_only:false; response_revision:44648; number_of_response:1; }","duration":"109.215453ms","start":"2026-07-06T13:52:40.425724Z","end":"2026-07-06T13:52:40.53494Z","steps":["trace[1749361076] 'process raft request' (duration: 108.978898ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:40.534779Z","caller":"traceutil/trace.go:171","msg":"trace[309122109] linearizableReadLoop","detail":"{readStateIndex:46165; appliedIndex:46163; }","duration":"106.627435ms","start":"2026-07-06T13:52:40.428124Z","end":"2026-07-06T13:52:40.534752Z","steps":["trace[309122109] 'read index received' (duration: 87.517519ms)","trace[309122109] 'applied index is now lower than readState.Index' (duration: 19.108966ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T13:52:40.535157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.013144ms","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-06T13:52:40.535223Z","caller":"traceutil/trace.go:171","msg":"trace[1296978850] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:44648; }","duration":"107.120066ms","start":"2026-07-06T13:52:40.428089Z","end":"2026-07-06T13:52:40.535209Z","steps":["trace[1296978850] 'agreement among raft nodes before linearized reading' (duration: 106.911091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:40.535304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.5991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/magnum-api\" ","response":"range_response_count:1 size:7779"} {"level":"info","ts":"2026-07-06T13:52:40.535334Z","caller":"traceutil/trace.go:171","msg":"trace[938162435] range","detail":"{range_begin:/registry/deployments/openstack/magnum-api; range_end:; response_count:1; response_revision:44648; }","duration":"105.661782ms","start":"2026-07-06T13:52:40.429661Z","end":"2026-07-06T13:52:40.535323Z","steps":["trace[938162435] 'agreement among raft nodes before linearized reading' (duration: 105.508649ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:40.535414Z","caller":"traceutil/trace.go:171","msg":"trace[116023545] transaction","detail":"{read_only:false; response_revision:44647; number_of_response:1; }","duration":"111.403733ms","start":"2026-07-06T13:52:40.423407Z","end":"2026-07-06T13:52:40.534811Z","steps":["trace[116023545] 'process raft request' (duration: 92.074281ms)","trace[116023545] 'compare' (duration: 19.074416ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:52:41.284502Z","caller":"traceutil/trace.go:171","msg":"trace[1593185444] transaction","detail":"{read_only:false; response_revision:44656; number_of_response:1; }","duration":"133.688203ms","start":"2026-07-06T13:52:41.150774Z","end":"2026-07-06T13:52:41.284462Z","steps":["trace[1593185444] 'process raft request' (duration: 133.346795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:43.101905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.775826ms","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:388006"} {"level":"info","ts":"2026-07-06T13:52:43.101987Z","caller":"traceutil/trace.go:171","msg":"trace[1357645331] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:500; response_revision:44670; }","duration":"105.880998ms","start":"2026-07-06T13:52:42.99609Z","end":"2026-07-06T13:52:43.101971Z","steps":["trace[1357645331] 'range keys from in-memory index tree' (duration: 103.769179ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:48.222742Z","caller":"traceutil/trace.go:171","msg":"trace[1878590145] linearizableReadLoop","detail":"{readStateIndex:46220; appliedIndex:46219; }","duration":"106.510912ms","start":"2026-07-06T13:52:48.116212Z","end":"2026-07-06T13:52:48.222723Z","steps":["trace[1878590145] 'read index received' (duration: 106.347618ms)","trace[1878590145] 'applied index is now lower than readState.Index' (duration: 162.644µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T13:52:48.222931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.709166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-glance\" ","response":"range_response_count:1 size:900"} {"level":"info","ts":"2026-07-06T13:52:48.222978Z","caller":"traceutil/trace.go:171","msg":"trace[537878817] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-glance; range_end:; response_count:1; response_revision:44701; }","duration":"106.793109ms","start":"2026-07-06T13:52:48.116171Z","end":"2026-07-06T13:52:48.222965Z","steps":["trace[537878817] 'agreement among raft nodes before linearized reading' (duration: 106.651755ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:48.222838Z","caller":"traceutil/trace.go:171","msg":"trace[528256566] transaction","detail":"{read_only:false; response_revision:44701; number_of_response:1; }","duration":"116.48503ms","start":"2026-07-06T13:52:48.106322Z","end":"2026-07-06T13:52:48.222807Z","steps":["trace[528256566] 'process raft request' (duration: 116.279085ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:48.503321Z","caller":"traceutil/trace.go:171","msg":"trace[1065616297] transaction","detail":"{read_only:false; response_revision:44703; number_of_response:1; }","duration":"124.597966ms","start":"2026-07-06T13:52:48.378699Z","end":"2026-07-06T13:52:48.503297Z","steps":["trace[1065616297] 'process raft request' (duration: 124.427672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:48.817221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.852869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-06T13:52:48.817458Z","caller":"traceutil/trace.go:171","msg":"trace[1255110797] transaction","detail":"{read_only:false; response_revision:44706; number_of_response:1; }","duration":"242.963589ms","start":"2026-07-06T13:52:48.574462Z","end":"2026-07-06T13:52:48.817426Z","steps":["trace[1255110797] 'process raft request' (duration: 40.804614ms)","trace[1255110797] 'compare' (duration: 201.713005ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:52:48.817533Z","caller":"traceutil/trace.go:171","msg":"trace[734859089] linearizableReadLoop","detail":"{readStateIndex:46225; appliedIndex:46224; }","duration":"227.408123ms","start":"2026-07-06T13:52:48.5901Z","end":"2026-07-06T13:52:48.817508Z","steps":["trace[734859089] 'read index received' (duration: 25.093564ms)","trace[734859089] 'applied index is now lower than readState.Index' (duration: 202.311468ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T13:52:48.817681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.568606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/rook-ceph/\" range_end:\"/registry/jobs/rook-ceph0\" limit:500 ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-06T13:52:48.817711Z","caller":"traceutil/trace.go:171","msg":"trace[1104498373] range","detail":"{range_begin:/registry/jobs/rook-ceph/; range_end:/registry/jobs/rook-ceph0; response_count:0; response_revision:44707; }","duration":"227.644998ms","start":"2026-07-06T13:52:48.590059Z","end":"2026-07-06T13:52:48.817704Z","steps":["trace[1104498373] 'agreement among raft nodes before linearized reading' (duration: 227.566226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:52:48.817813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.232949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-heat-nodes\" ","response":"range_response_count:1 size:948"} {"level":"warn","ts":"2026-07-06T13:52:48.81784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.668986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-07-06T13:52:48.817945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.955551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:909"} {"level":"info","ts":"2026-07-06T13:52:48.818024Z","caller":"traceutil/trace.go:171","msg":"trace[693420291] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:44707; }","duration":"177.064173ms","start":"2026-07-06T13:52:48.640943Z","end":"2026-07-06T13:52:48.818007Z","steps":["trace[693420291] 'agreement among raft nodes before linearized reading' (duration: 176.773426ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:48.817904Z","caller":"traceutil/trace.go:171","msg":"trace[1959950468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:44707; }","duration":"187.766798ms","start":"2026-07-06T13:52:48.630116Z","end":"2026-07-06T13:52:48.817882Z","steps":["trace[1959950468] 'agreement among raft nodes before linearized reading' (duration: 187.576314ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:52:48.818323Z","caller":"traceutil/trace.go:171","msg":"trace[716376128] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-heat-nodes; range_end:; response_count:1; response_revision:44707; }","duration":"160.29499ms","start":"2026-07-06T13:52:48.657557Z","end":"2026-07-06T13:52:48.817852Z","steps":["trace[716376128] 'agreement among raft nodes before linearized reading' (duration: 160.145157ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:54:27.344839Z","caller":"traceutil/trace.go:171","msg":"trace[1220920417] linearizableReadLoop","detail":"{readStateIndex:46964; appliedIndex:46963; }","duration":"119.837786ms","start":"2026-07-06T13:54:27.22498Z","end":"2026-07-06T13:54:27.344818Z","steps":["trace[1220920417] 'read index received' (duration: 119.678503ms)","trace[1220920417] 'applied index is now lower than readState.Index' (duration: 158.673µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:54:27.344938Z","caller":"traceutil/trace.go:171","msg":"trace[749877422] transaction","detail":"{read_only:false; response_revision:45424; number_of_response:1; }","duration":"120.42676ms","start":"2026-07-06T13:54:27.224494Z","end":"2026-07-06T13:54:27.344921Z","steps":["trace[749877422] 'process raft request' (duration: 120.182524ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:54:27.345026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.053592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/magnum-db-init\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-06T13:54:27.345055Z","caller":"traceutil/trace.go:171","msg":"trace[803408026] range","detail":"{range_begin:/registry/serviceaccounts/openstack/magnum-db-init; range_end:; response_count:1; response_revision:45424; }","duration":"120.133264ms","start":"2026-07-06T13:54:27.224914Z","end":"2026-07-06T13:54:27.345047Z","steps":["trace[803408026] 'agreement among raft nodes before linearized reading' (duration: 120.015041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:54:27.350108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.890409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-06T13:54:27.350158Z","caller":"traceutil/trace.go:171","msg":"trace[762277363] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:45425; }","duration":"117.002822ms","start":"2026-07-06T13:54:27.233143Z","end":"2026-07-06T13:54:27.350146Z","steps":["trace[762277363] 'agreement among raft nodes before linearized reading' (duration: 116.848799ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:54:27.350284Z","caller":"traceutil/trace.go:171","msg":"trace[1958466554] transaction","detail":"{read_only:false; response_revision:45425; number_of_response:1; }","duration":"104.593398ms","start":"2026-07-06T13:54:27.245666Z","end":"2026-07-06T13:54:27.350259Z","steps":["trace[1958466554] 'process raft request' (duration: 104.181289ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:54:57.154033Z","caller":"traceutil/trace.go:171","msg":"trace[1038132635] transaction","detail":"{read_only:false; response_revision:45642; number_of_response:1; }","duration":"171.26419ms","start":"2026-07-06T13:54:56.98274Z","end":"2026-07-06T13:54:57.154004Z","steps":["trace[1038132635] 'process raft request' (duration: 133.659242ms)","trace[1038132635] 'compare' (duration: 37.293841ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:54:57.691613Z","caller":"traceutil/trace.go:171","msg":"trace[1048165390] linearizableReadLoop","detail":"{readStateIndex:47195; appliedIndex:47194; }","duration":"131.875931ms","start":"2026-07-06T13:54:57.559709Z","end":"2026-07-06T13:54:57.691585Z","steps":["trace[1048165390] 'read index received' (duration: 126.385186ms)","trace[1048165390] 'applied index is now lower than readState.Index' (duration: 5.489605ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:54:57.691634Z","caller":"traceutil/trace.go:171","msg":"trace[1452201672] transaction","detail":"{read_only:false; response_revision:45649; number_of_response:1; }","duration":"133.366046ms","start":"2026-07-06T13:54:57.558243Z","end":"2026-07-06T13:54:57.691609Z","steps":["trace[1452201672] 'process raft request' (duration: 127.955012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:54:57.694244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.524052ms","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:387419"} {"level":"info","ts":"2026-07-06T13:54:57.6943Z","caller":"traceutil/trace.go:171","msg":"trace[593168560] range","detail":"{range_begin:/registry/events/openstack/; range_end:/registry/events/openstack0; response_count:500; response_revision:45649; }","duration":"134.619604ms","start":"2026-07-06T13:54:57.559669Z","end":"2026-07-06T13:54:57.694289Z","steps":["trace[593168560] 'agreement among raft nodes before linearized reading' (duration: 132.017024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:54:58.654315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.571343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/sh.helm.release.v1.neutron.v1\" ","response":"range_response_count:1 size:227236"} {"level":"info","ts":"2026-07-06T13:54:58.654463Z","caller":"traceutil/trace.go:171","msg":"trace[564321859] range","detail":"{range_begin:/registry/secrets/openstack/sh.helm.release.v1.neutron.v1; range_end:; response_count:1; response_revision:45653; }","duration":"120.759768ms","start":"2026-07-06T13:54:58.533683Z","end":"2026-07-06T13:54:58.654443Z","steps":["trace[564321859] 'range keys from in-memory index tree' (duration: 120.364228ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:54:59.068944Z","caller":"traceutil/trace.go:171","msg":"trace[677064893] linearizableReadLoop","detail":"{readStateIndex:47200; appliedIndex:47199; }","duration":"188.568395ms","start":"2026-07-06T13:54:58.880357Z","end":"2026-07-06T13:54:59.068925Z","steps":["trace[677064893] 'read index received' (duration: 188.459603ms)","trace[677064893] 'applied index is now lower than readState.Index' (duration: 108.222µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:54:59.069044Z","caller":"traceutil/trace.go:171","msg":"trace[837385937] transaction","detail":"{read_only:false; response_revision:45654; number_of_response:1; }","duration":"223.080895ms","start":"2026-07-06T13:54:58.845945Z","end":"2026-07-06T13:54:59.069026Z","steps":["trace[837385937] 'process raft request' (duration: 222.860709ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:54:59.069219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.854413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/sh.helm.release.v1.nova.v1\" ","response":"range_response_count:1 size:171806"} {"level":"info","ts":"2026-07-06T13:54:59.069243Z","caller":"traceutil/trace.go:171","msg":"trace[780848965] range","detail":"{range_begin:/registry/secrets/openstack/sh.helm.release.v1.nova.v1; range_end:; response_count:1; response_revision:45654; }","duration":"188.920134ms","start":"2026-07-06T13:54:58.880317Z","end":"2026-07-06T13:54:59.069237Z","steps":["trace[780848965] 'agreement among raft nodes before linearized reading' (duration: 188.697739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:54:59.467936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.225743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-06T13:54:59.468093Z","caller":"traceutil/trace.go:171","msg":"trace[145060006] transaction","detail":"{read_only:false; response_revision:45655; number_of_response:1; }","duration":"419.093071ms","start":"2026-07-06T13:54:59.048957Z","end":"2026-07-06T13:54:59.46805Z","steps":["trace[145060006] 'process raft request' (duration: 313.650443ms)","trace[145060006] 'compare' (duration: 104.577098ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:54:59.46816Z","caller":"traceutil/trace.go:171","msg":"trace[1609667043] linearizableReadLoop","detail":"{readStateIndex:47201; appliedIndex:47200; }","duration":"399.130623ms","start":"2026-07-06T13:54:59.068996Z","end":"2026-07-06T13:54:59.468127Z","steps":["trace[1609667043] 'read index received' (duration: 293.628954ms)","trace[1609667043] 'applied index is now lower than readState.Index' (duration: 105.498109ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T13:54:59.468745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.150256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/sh.helm.release.v1.octavia.v1\" ","response":"range_response_count:1 size:47168"} {"level":"warn","ts":"2026-07-06T13:54:59.468247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:54:59.04893Z","time spent":"419.234943ms","remote":"127.0.0.1:43964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T13:54:59.468815Z","caller":"traceutil/trace.go:171","msg":"trace[2101443052] range","detail":"{range_begin:/registry/secrets/openstack/sh.helm.release.v1.octavia.v1; range_end:; response_count:1; response_revision:45658; }","duration":"484.249727ms","start":"2026-07-06T13:54:58.984549Z","end":"2026-07-06T13:54:59.468799Z","steps":["trace[2101443052] 'agreement among raft nodes before linearized reading' (duration: 484.029383ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:54:59.468518Z","caller":"traceutil/trace.go:171","msg":"trace[93819815] transaction","detail":"{read_only:false; response_revision:45656; number_of_response:1; }","duration":"308.670988ms","start":"2026-07-06T13:54:59.159813Z","end":"2026-07-06T13:54:59.468484Z","steps":["trace[93819815] 'process raft request' (duration: 308.247549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:54:59.468864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:54:58.984515Z","time spent":"484.33726ms","remote":"127.0.0.1:43826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":47191,"request content":"key:\"/registry/secrets/openstack/sh.helm.release.v1.octavia.v1\" "} {"level":"warn","ts":"2026-07-06T13:54:59.468928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:54:59.159793Z","time spent":"309.080347ms","remote":"127.0.0.1:43964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T13:54:59.468581Z","caller":"traceutil/trace.go:171","msg":"trace[386051839] transaction","detail":"{read_only:false; response_revision:45657; number_of_response:1; }","duration":"307.492371ms","start":"2026-07-06T13:54:59.161071Z","end":"2026-07-06T13:54:59.468563Z","steps":["trace[386051839] 'process raft request' (duration: 307.268476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:54:59.46908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:54:59.161043Z","time spent":"307.996553ms","remote":"127.0.0.1:43964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T13:54:59.46911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.40078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/sh.helm.release.v1.nova.v1\" ","response":"range_response_count:1 size:171806"} {"level":"info","ts":"2026-07-06T13:54:59.468555Z","caller":"traceutil/trace.go:171","msg":"trace[1603042058] transaction","detail":"{read_only:false; response_revision:45658; number_of_response:1; }","duration":"302.456897ms","start":"2026-07-06T13:54:59.166081Z","end":"2026-07-06T13:54:59.468538Z","steps":["trace[1603042058] 'process raft request' (duration: 302.342324ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:54:59.469195Z","caller":"traceutil/trace.go:171","msg":"trace[2058022067] range","detail":"{range_begin:/registry/secrets/openstack/sh.helm.release.v1.nova.v1; range_end:; response_count:1; response_revision:45658; }","duration":"385.514442ms","start":"2026-07-06T13:54:59.083654Z","end":"2026-07-06T13:54:59.469169Z","steps":["trace[2058022067] 'agreement among raft nodes before linearized reading' (duration: 385.249336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:54:59.469229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:54:59.166072Z","time spent":"303.118701ms","remote":"127.0.0.1:43964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T13:54:59.469249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T13:54:59.083637Z","time spent":"385.598334ms","remote":"127.0.0.1:43826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":171829,"request content":"key:\"/registry/secrets/openstack/sh.helm.release.v1.nova.v1\" "} {"level":"warn","ts":"2026-07-06T13:54:59.469318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.504277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-06T13:54:59.469348Z","caller":"traceutil/trace.go:171","msg":"trace[1884782505] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:45658; }","duration":"210.563838ms","start":"2026-07-06T13:54:59.258773Z","end":"2026-07-06T13:54:59.469337Z","steps":["trace[1884782505] 'agreement among raft nodes before linearized reading' (duration: 210.516007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:54:59.469327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.092793ms","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-07-06T13:54:59.469398Z","caller":"traceutil/trace.go:171","msg":"trace[1130528679] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:45658; }","duration":"198.165474ms","start":"2026-07-06T13:54:59.271209Z","end":"2026-07-06T13:54:59.469375Z","steps":["trace[1130528679] 'agreement among raft nodes before linearized reading' (duration: 197.991541ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:54:59.620177Z","caller":"traceutil/trace.go:171","msg":"trace[1670027672] linearizableReadLoop","detail":"{readStateIndex:47205; appliedIndex:47204; }","duration":"147.017017ms","start":"2026-07-06T13:54:59.473133Z","end":"2026-07-06T13:54:59.62015Z","steps":["trace[1670027672] 'read index received' (duration: 122.682452ms)","trace[1670027672] 'applied index is now lower than readState.Index' (duration: 24.332945ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T13:54:59.620297Z","caller":"traceutil/trace.go:171","msg":"trace[621241448] transaction","detail":"{read_only:false; response_revision:45659; number_of_response:1; }","duration":"148.332458ms","start":"2026-07-06T13:54:59.471769Z","end":"2026-07-06T13:54:59.620101Z","steps":["trace[621241448] 'process raft request' (duration: 124.038823ms)","trace[621241448] 'compare' (duration: 24.08628ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T13:54:59.620577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.274392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-fe2ac374-de2c-4f3a-81f7-cadd38a89b32\" ","response":"range_response_count:1 size:1276"} {"level":"info","ts":"2026-07-06T13:54:59.620649Z","caller":"traceutil/trace.go:171","msg":"trace[428449302] range","detail":"{range_begin:/registry/persistentvolumes/pvc-fe2ac374-de2c-4f3a-81f7-cadd38a89b32; range_end:; response_count:1; response_revision:45659; }","duration":"147.545589ms","start":"2026-07-06T13:54:59.473087Z","end":"2026-07-06T13:54:59.620633Z","steps":["trace[428449302] 'agreement among raft nodes before linearized reading' (duration: 147.17255ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T13:54:59.620638Z","caller":"traceutil/trace.go:171","msg":"trace[198520422] transaction","detail":"{read_only:false; response_revision:45660; number_of_response:1; }","duration":"141.540632ms","start":"2026-07-06T13:54:59.478884Z","end":"2026-07-06T13:54:59.620425Z","steps":["trace[198520422] 'process raft request' (duration: 141.136733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T13:54:59.621016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.864116ms","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-06T13:54:59.621083Z","caller":"traceutil/trace.go:171","msg":"trace[2064307844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:45661; }","duration":"129.957218ms","start":"2026-07-06T13:54:59.491085Z","end":"2026-07-06T13:54:59.621042Z","steps":["trace[2064307844] 'agreement among raft nodes before linearized reading' (duration: 129.827175ms)"],"step_count":1}