{"level":"warn","ts":"2026-04-07T00:07:26.419302Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:07:26.419615Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T00:07:26.419692Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:07:26.419708Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-07T00:07:26.419733Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T00:07:26.420758Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-04-07T00:07:26.42086Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T00:07:26.425336Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.177743ms"} {"level":"info","ts":"2026-04-07T00:07:26.432303Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-04-07T00:07:26.432374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T00:07:26.432395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-04-07T00:07:26.432411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T00:07:26.43242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-04-07T00:07:26.432459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-04-07T00:07:26.437153Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T00:07:26.439471Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T00:07:26.441223Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-07T00:07:26.444881Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T00:07:26.445075Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T00:07:26.445216Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T00:07:26.445145Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T00:07:26.445234Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T00:07:26.446976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-04-07T00:07:26.447696Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-07T00:07:26.451477Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T00:07:26.451974Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T00:07:26.452023Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T00:07:26.452262Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-07T00:07:26.452291Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-07T00:07:27.033503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T00:07:27.033613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T00:07:27.033655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-04-07T00:07:27.03368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-04-07T00:07:27.033705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-07T00:07:27.033722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-04-07T00:07:27.033806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-07T00:07:27.035007Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:07:27.035711Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T00:07:27.03572Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:07:27.035864Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:07:27.036027Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T00:07:27.036104Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T00:07:27.036141Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:07:27.036283Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:07:27.036339Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:07:27.039076Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-04-07T00:07:27.039247Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T00:07:56.613024Z","caller":"traceutil/trace.go:171","msg":"trace[1468198635] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"103.88322ms","start":"2026-04-07T00:07:56.509116Z","end":"2026-04-07T00:07:56.612999Z","steps":["trace[1468198635] 'process raft request' (duration: 102.615636ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:56.619065Z","caller":"traceutil/trace.go:171","msg":"trace[1521939493] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"103.559621ms","start":"2026-04-07T00:07:56.515485Z","end":"2026-04-07T00:07:56.619045Z","steps":["trace[1521939493] 'process raft request' (duration: 103.394927ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:56.619076Z","caller":"traceutil/trace.go:171","msg":"trace[1725581832] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"100.726564ms","start":"2026-04-07T00:07:56.518321Z","end":"2026-04-07T00:07:56.619048Z","steps":["trace[1725581832] 'process raft request' (duration: 100.613601ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:56.619204Z","caller":"traceutil/trace.go:171","msg":"trace[1810378517] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"105.025501ms","start":"2026-04-07T00:07:56.514113Z","end":"2026-04-07T00:07:56.619139Z","steps":["trace[1810378517] 'process raft request' (duration: 104.663121ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:56.619398Z","caller":"traceutil/trace.go:171","msg":"trace[777680719] transaction","detail":"{read_only:false; number_of_response:1; response_revision:481; }","duration":"100.654772ms","start":"2026-04-07T00:07:56.518732Z","end":"2026-04-07T00:07:56.619387Z","steps":["trace[777680719] 'process raft request' (duration: 100.249361ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:07:56.619524Z","caller":"traceutil/trace.go:171","msg":"trace[1109621492] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"101.059673ms","start":"2026-04-07T00:07:56.518456Z","end":"2026-04-07T00:07:56.619516Z","steps":["trace[1109621492] 'process raft request' (duration: 100.499158ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:02.940659Z","caller":"traceutil/trace.go:171","msg":"trace[1887734222] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"279.214809ms","start":"2026-04-07T00:08:02.661405Z","end":"2026-04-07T00:08:02.94062Z","steps":["trace[1887734222] 'read index received' (duration: 278.19152ms)","trace[1887734222] 'applied index is now lower than readState.Index' (duration: 1.022009ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:02.940801Z","caller":"traceutil/trace.go:171","msg":"trace[1474406163] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"335.300597ms","start":"2026-04-07T00:08:02.605443Z","end":"2026-04-07T00:08:02.940744Z","steps":["trace[1474406163] 'process raft request' (duration: 334.560037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:02.940929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.498417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T00:08:02.94105Z","caller":"traceutil/trace.go:171","msg":"trace[2102564461] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:573; }","duration":"279.668392ms","start":"2026-04-07T00:08:02.661364Z","end":"2026-04-07T00:08:02.941033Z","steps":["trace[2102564461] 'agreement among raft nodes before linearized reading' (duration: 279.406145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:02.941797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:02.605415Z","time spent":"335.523033ms","remote":"127.0.0.1:49736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:08:03.36134Z","caller":"traceutil/trace.go:171","msg":"trace[1107298871] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"361.843711ms","start":"2026-04-07T00:08:02.999471Z","end":"2026-04-07T00:08:03.361315Z","steps":["trace[1107298871] 'process raft request' (duration: 361.666656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:03.361517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:02.999437Z","time spent":"361.986445ms","remote":"127.0.0.1:50140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:08:03.383129Z","caller":"traceutil/trace.go:171","msg":"trace[327452939] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:599; }","duration":"205.598043ms","start":"2026-04-07T00:08:03.177492Z","end":"2026-04-07T00:08:03.38309Z","steps":["trace[327452939] 'read index received' (duration: 183.752798ms)","trace[327452939] 'applied index is now lower than readState.Index' (duration: 21.844455ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:03.383188Z","caller":"traceutil/trace.go:171","msg":"trace[2047853504] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"312.779484ms","start":"2026-04-07T00:08:03.070374Z","end":"2026-04-07T00:08:03.383153Z","steps":["trace[2047853504] 'process raft request' (duration: 312.606509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:03.383336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.830989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2c5p5\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-04-07T00:08:03.383359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:03.070337Z","time spent":"312.945778ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:08:03.38338Z","caller":"traceutil/trace.go:171","msg":"trace[318681320] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2c5p5; range_end:; response_count:1; response_revision:576; }","duration":"205.914082ms","start":"2026-04-07T00:08:03.17745Z","end":"2026-04-07T00:08:03.383364Z","steps":["trace[318681320] 'agreement among raft nodes before linearized reading' (duration: 205.769928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:03.948504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.952147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:08:03.948552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.61312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T00:08:03.948577Z","caller":"traceutil/trace.go:171","msg":"trace[2018226449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"314.045839ms","start":"2026-04-07T00:08:03.634513Z","end":"2026-04-07T00:08:03.948559Z","steps":["trace[2018226449] 'range keys from in-memory index tree' (duration: 313.811932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:03.948615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:03.634451Z","time spent":"314.156782ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T00:08:03.948621Z","caller":"traceutil/trace.go:171","msg":"trace[179444727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:577; }","duration":"290.718013ms","start":"2026-04-07T00:08:03.65788Z","end":"2026-04-07T00:08:03.948598Z","steps":["trace[179444727] 'range keys from in-memory index tree' (duration: 290.382742ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:03.981265Z","caller":"traceutil/trace.go:171","msg":"trace[429846087] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"179.217114ms","start":"2026-04-07T00:08:03.802024Z","end":"2026-04-07T00:08:03.981241Z","steps":["trace[429846087] 'process raft request' (duration: 178.442133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:19.416543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.404523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ns8tt\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-07T00:08:19.416654Z","caller":"traceutil/trace.go:171","msg":"trace[6097813] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ns8tt; range_end:; response_count:1; response_revision:738; }","duration":"145.598198ms","start":"2026-04-07T00:08:19.271032Z","end":"2026-04-07T00:08:19.416631Z","steps":["trace[6097813] 'range keys from in-memory index tree' (duration: 145.179536ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:46.710168Z","caller":"traceutil/trace.go:171","msg":"trace[1306258190] linearizableReadLoop","detail":"{readStateIndex:1168; appliedIndex:1167; }","duration":"426.05181ms","start":"2026-04-07T00:08:46.284086Z","end":"2026-04-07T00:08:46.710138Z","steps":["trace[1306258190] 'read index received' (duration: 332.092649ms)","trace[1306258190] 'applied index is now lower than readState.Index' (duration: 93.957701ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:46.710498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.389249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T00:08:46.710231Z","caller":"traceutil/trace.go:171","msg":"trace[1000726828] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"438.930412ms","start":"2026-04-07T00:08:46.271239Z","end":"2026-04-07T00:08:46.710169Z","steps":["trace[1000726828] 'process raft request' (duration: 345.054283ms)","trace[1000726828] 'compare' (duration: 93.578561ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:08:46.710602Z","caller":"traceutil/trace.go:171","msg":"trace[1939883096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1132; }","duration":"426.559344ms","start":"2026-04-07T00:08:46.28402Z","end":"2026-04-07T00:08:46.710579Z","steps":["trace[1939883096] 'agreement among raft nodes before linearized reading' (duration: 426.262256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:46.710662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:46.283995Z","time spent":"426.654827ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T00:08:46.710691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.438544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:46.710781Z","caller":"traceutil/trace.go:171","msg":"trace[278816910] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1132; }","duration":"112.582648ms","start":"2026-04-07T00:08:46.598178Z","end":"2026-04-07T00:08:46.710761Z","steps":["trace[278816910] 'agreement among raft nodes before linearized reading' (duration: 112.439974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:46.710803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:46.271208Z","time spent":"439.439986ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:09:33.103615Z","caller":"traceutil/trace.go:171","msg":"trace[660833966] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"110.49069ms","start":"2026-04-07T00:09:32.993086Z","end":"2026-04-07T00:09:33.103577Z","steps":["trace[660833966] 'process raft request' (duration: 102.869131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:33.422226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.632815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-04-07T00:09:33.422344Z","caller":"traceutil/trace.go:171","msg":"trace[1846735849] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1799; }","duration":"147.816511ms","start":"2026-04-07T00:09:33.274499Z","end":"2026-04-07T00:09:33.422315Z","steps":["trace[1846735849] 'range keys from in-memory index tree' (duration: 147.44985ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:09:45.303573Z","caller":"traceutil/trace.go:171","msg":"trace[1436358930] linearizableReadLoop","detail":"{readStateIndex:1974; appliedIndex:1972; }","duration":"136.796124ms","start":"2026-04-07T00:09:45.166745Z","end":"2026-04-07T00:09:45.303542Z","steps":["trace[1436358930] 'read index received' (duration: 55.985486ms)","trace[1436358930] 'applied index is now lower than readState.Index' (duration: 80.809178ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:09:45.303611Z","caller":"traceutil/trace.go:171","msg":"trace[1823524970] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"137.525494ms","start":"2026-04-07T00:09:45.166054Z","end":"2026-04-07T00:09:45.303579Z","steps":["trace[1823524970] 'process raft request' (duration: 137.310338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:45.303894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.429807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-07T00:09:45.30399Z","caller":"traceutil/trace.go:171","msg":"trace[735359220] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1897; }","duration":"133.563881ms","start":"2026-04-07T00:09:45.170409Z","end":"2026-04-07T00:09:45.303973Z","steps":["trace[735359220] 'agreement among raft nodes before linearized reading' (duration: 133.326395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:45.303973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.150554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T00:09:45.304074Z","caller":"traceutil/trace.go:171","msg":"trace[81234520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1897; }","duration":"137.36916ms","start":"2026-04-07T00:09:45.166682Z","end":"2026-04-07T00:09:45.304051Z","steps":["trace[81234520] 'agreement among raft nodes before linearized reading' (duration: 137.000349ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:11.717834Z","caller":"traceutil/trace.go:171","msg":"trace[1756911394] transaction","detail":"{read_only:false; response_revision:2058; number_of_response:1; }","duration":"119.128968ms","start":"2026-04-07T00:10:11.59867Z","end":"2026-04-07T00:10:11.717799Z","steps":["trace[1756911394] 'process raft request' (duration: 48.57885ms)","trace[1756911394] 'compare' (duration: 70.323621ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:17.324469Z","caller":"traceutil/trace.go:171","msg":"trace[2126371616] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"162.021185ms","start":"2026-04-07T00:10:17.162416Z","end":"2026-04-07T00:10:17.324438Z","steps":["trace[2126371616] 'process raft request' (duration: 161.811839ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:23.469665Z","caller":"traceutil/trace.go:171","msg":"trace[1257533402] linearizableReadLoop","detail":"{readStateIndex:2199; appliedIndex:2198; }","duration":"113.90205ms","start":"2026-04-07T00:10:23.355737Z","end":"2026-04-07T00:10:23.469639Z","steps":["trace[1257533402] 'read index received' (duration: 113.771766ms)","trace[1257533402] 'applied index is now lower than readState.Index' (duration: 129.264µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:10:23.469975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.224608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-07T00:10:23.470057Z","caller":"traceutil/trace.go:171","msg":"trace[343584813] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2115; }","duration":"114.357652ms","start":"2026-04-07T00:10:23.35568Z","end":"2026-04-07T00:10:23.470038Z","steps":["trace[343584813] 'agreement among raft nodes before linearized reading' (duration: 114.073664ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:23.470334Z","caller":"traceutil/trace.go:171","msg":"trace[1526998079] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"127.231745ms","start":"2026-04-07T00:10:23.342492Z","end":"2026-04-07T00:10:23.469724Z","steps":["trace[1526998079] 'process raft request' (duration: 127.009529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:23.795697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.279958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:10:23.795769Z","caller":"traceutil/trace.go:171","msg":"trace[732293473] linearizableReadLoop","detail":"{readStateIndex:2200; appliedIndex:2199; }","duration":"326.002136ms","start":"2026-04-07T00:10:23.469754Z","end":"2026-04-07T00:10:23.795757Z","steps":["trace[732293473] 'read index received' (duration: 155.407059ms)","trace[732293473] 'applied index is now lower than readState.Index' (duration: 170.594137ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:23.795862Z","caller":"traceutil/trace.go:171","msg":"trace[1520914978] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"345.319151ms","start":"2026-04-07T00:10:23.450534Z","end":"2026-04-07T00:10:23.795853Z","steps":["trace[1520914978] 'process raft request' (duration: 174.642011ms)","trace[1520914978] 'compare' (duration: 170.201316ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:10:23.795959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:23.45051Z","time spent":"345.393922ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:10:23.796177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.152264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-07T00:10:23.796256Z","caller":"traceutil/trace.go:171","msg":"trace[1657688836] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2116; }","duration":"428.277237ms","start":"2026-04-07T00:10:23.367957Z","end":"2026-04-07T00:10:23.796234Z","steps":["trace[1657688836] 'agreement among raft nodes before linearized reading' (duration: 428.040311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:23.796297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:23.36794Z","time spent":"428.349009ms","remote":"127.0.0.1:42738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-07T00:10:23.808558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.598622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:10:23.808607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.181527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T00:10:23.808617Z","caller":"traceutil/trace.go:171","msg":"trace[716612638] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"334.63879ms","start":"2026-04-07T00:10:23.473953Z","end":"2026-04-07T00:10:23.808591Z","steps":["trace[716612638] 'process raft request' (duration: 334.340132ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:23.808646Z","caller":"traceutil/trace.go:171","msg":"trace[125529212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2117; }","duration":"173.729276ms","start":"2026-04-07T00:10:23.634899Z","end":"2026-04-07T00:10:23.808628Z","steps":["trace[125529212] 'agreement among raft nodes before linearized reading' (duration: 173.577952ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:23.808687Z","caller":"traceutil/trace.go:171","msg":"trace[538874459] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2117; }","duration":"328.29689ms","start":"2026-04-07T00:10:23.48037Z","end":"2026-04-07T00:10:23.808667Z","steps":["trace[538874459] 'agreement among raft nodes before linearized reading' (duration: 328.082634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:23.808687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.43182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T00:10:23.808749Z","caller":"traceutil/trace.go:171","msg":"trace[184467354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2117; }","duration":"325.517482ms","start":"2026-04-07T00:10:23.483217Z","end":"2026-04-07T00:10:23.808734Z","steps":["trace[184467354] 'agreement among raft nodes before linearized reading' (duration: 325.348377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:23.808752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:23.473934Z","time spent":"334.745693ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:10:23.80876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:23.480356Z","time spent":"328.378493ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T00:10:23.808794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:10:23.483208Z","time spent":"325.577454ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T00:11:17.854518Z","caller":"traceutil/trace.go:171","msg":"trace[1453075330] transaction","detail":"{read_only:false; response_revision:2578; number_of_response:1; }","duration":"204.587859ms","start":"2026-04-07T00:11:17.649911Z","end":"2026-04-07T00:11:17.854499Z","steps":["trace[1453075330] 'process raft request' (duration: 204.486546ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:11:52.304736Z","caller":"traceutil/trace.go:171","msg":"trace[91339379] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"152.372455ms","start":"2026-04-07T00:11:52.152332Z","end":"2026-04-07T00:11:52.304705Z","steps":["trace[91339379] 'process raft request' (duration: 152.20691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:15:47.922794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.482362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:15:47.922944Z","caller":"traceutil/trace.go:171","msg":"trace[1337454649] linearizableReadLoop","detail":"{readStateIndex:5164; appliedIndex:5163; }","duration":"125.334924ms","start":"2026-04-07T00:15:47.797592Z","end":"2026-04-07T00:15:47.922926Z","steps":["trace[1337454649] 'read index received' (duration: 7.630465ms)","trace[1337454649] 'applied index is now lower than readState.Index' (duration: 117.703039ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:15:47.923049Z","caller":"traceutil/trace.go:171","msg":"trace[236859852] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"239.320279ms","start":"2026-04-07T00:15:47.683711Z","end":"2026-04-07T00:15:47.923031Z","steps":["trace[236859852] 'process raft request' (duration: 121.480816ms)","trace[236859852] 'compare' (duration: 117.203664ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:15:47.923158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.538659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T00:15:47.923268Z","caller":"traceutil/trace.go:171","msg":"trace[1632177584] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:4973; }","duration":"125.656843ms","start":"2026-04-07T00:15:47.797585Z","end":"2026-04-07T00:15:47.923241Z","steps":["trace[1632177584] 'agreement among raft nodes before linearized reading' (duration: 125.495368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:15:47.923743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.419773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:413130"} {"level":"info","ts":"2026-04-07T00:15:47.923889Z","caller":"traceutil/trace.go:171","msg":"trace[813897083] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:4973; }","duration":"106.578317ms","start":"2026-04-07T00:15:47.817291Z","end":"2026-04-07T00:15:47.923869Z","steps":["trace[813897083] 'agreement among raft nodes before linearized reading' (duration: 106.196337ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:52.379423Z","caller":"traceutil/trace.go:171","msg":"trace[1657299107] linearizableReadLoop","detail":"{readStateIndex:5269; appliedIndex:5268; }","duration":"101.045052ms","start":"2026-04-07T00:15:52.278348Z","end":"2026-04-07T00:15:52.379393Z","steps":["trace[1657299107] 'read index received' (duration: 100.817596ms)","trace[1657299107] 'applied index is now lower than readState.Index' (duration: 226.176µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:15:52.379524Z","caller":"traceutil/trace.go:171","msg":"trace[248390243] transaction","detail":"{read_only:false; response_revision:5074; number_of_response:1; }","duration":"168.756931ms","start":"2026-04-07T00:15:52.210694Z","end":"2026-04-07T00:15:52.379451Z","steps":["trace[248390243] 'process raft request' (duration: 168.531584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:15:52.380633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.270208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210982"} {"level":"info","ts":"2026-04-07T00:15:52.380697Z","caller":"traceutil/trace.go:171","msg":"trace[690994028] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5074; }","duration":"102.35241ms","start":"2026-04-07T00:15:52.278329Z","end":"2026-04-07T00:15:52.380682Z","steps":["trace[690994028] 'agreement among raft nodes before linearized reading' (duration: 101.181266ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:15:58.332324Z","caller":"traceutil/trace.go:171","msg":"trace[617099480] transaction","detail":"{read_only:false; response_revision:5158; number_of_response:1; }","duration":"127.697229ms","start":"2026-04-07T00:15:58.204589Z","end":"2026-04-07T00:15:58.332287Z","steps":["trace[617099480] 'process raft request' (duration: 67.716868ms)","trace[617099480] 'compare' (duration: 59.543189ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:15:58.332584Z","caller":"traceutil/trace.go:171","msg":"trace[1176175857] transaction","detail":"{read_only:false; response_revision:5159; number_of_response:1; }","duration":"127.438142ms","start":"2026-04-07T00:15:58.205118Z","end":"2026-04-07T00:15:58.332556Z","steps":["trace[1176175857] 'process raft request' (duration: 127.102433ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:06.494953Z","caller":"traceutil/trace.go:171","msg":"trace[2100461414] transaction","detail":"{read_only:false; response_revision:5292; number_of_response:1; }","duration":"101.514776ms","start":"2026-04-07T00:16:06.393407Z","end":"2026-04-07T00:16:06.494921Z","steps":["trace[2100461414] 'process raft request' (duration: 101.30965ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:20.414235Z","caller":"traceutil/trace.go:171","msg":"trace[1312792581] linearizableReadLoop","detail":"{readStateIndex:5694; appliedIndex:5693; }","duration":"121.149315ms","start":"2026-04-07T00:16:20.293058Z","end":"2026-04-07T00:16:20.414207Z","steps":["trace[1312792581] 'read index received' (duration: 120.887768ms)","trace[1312792581] 'applied index is now lower than readState.Index' (duration: 260.377µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:16:20.414288Z","caller":"traceutil/trace.go:171","msg":"trace[470279916] transaction","detail":"{read_only:false; response_revision:5488; number_of_response:1; }","duration":"121.97593ms","start":"2026-04-07T00:16:20.292284Z","end":"2026-04-07T00:16:20.41426Z","steps":["trace[470279916] 'process raft request' (duration: 121.747903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:20.414531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.448864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1453"} {"level":"info","ts":"2026-04-07T00:16:20.414575Z","caller":"traceutil/trace.go:171","msg":"trace[821473137] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5488; }","duration":"121.537036ms","start":"2026-04-07T00:16:20.293027Z","end":"2026-04-07T00:16:20.414564Z","steps":["trace[821473137] 'agreement among raft nodes before linearized reading' (duration: 121.350211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:20.594858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.722923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:16:20.595042Z","caller":"traceutil/trace.go:171","msg":"trace[374338332] linearizableReadLoop","detail":"{readStateIndex:5695; appliedIndex:5694; }","duration":"170.468307ms","start":"2026-04-07T00:16:20.424548Z","end":"2026-04-07T00:16:20.595016Z","steps":["trace[374338332] 'read index received' (duration: 18.288162ms)","trace[374338332] 'applied index is now lower than readState.Index' (duration: 152.177775ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:16:20.59507Z","caller":"traceutil/trace.go:171","msg":"trace[991626151] transaction","detail":"{read_only:false; response_revision:5489; number_of_response:1; }","duration":"173.415061ms","start":"2026-04-07T00:16:20.421621Z","end":"2026-04-07T00:16:20.595036Z","steps":["trace[991626151] 'process raft request' (duration: 21.378489ms)","trace[991626151] 'compare' (duration: 151.501297ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:16:20.595394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.818329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-07T00:16:20.595492Z","caller":"traceutil/trace.go:171","msg":"trace[691724129] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5489; }","duration":"170.954102ms","start":"2026-04-07T00:16:20.424521Z","end":"2026-04-07T00:16:20.595475Z","steps":["trace[691724129] 'agreement among raft nodes before linearized reading' (duration: 170.57451ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:20.74064Z","caller":"traceutil/trace.go:171","msg":"trace[194880986] linearizableReadLoop","detail":"{readStateIndex:5697; appliedIndex:5696; }","duration":"105.589549ms","start":"2026-04-07T00:16:20.635026Z","end":"2026-04-07T00:16:20.740615Z","steps":["trace[194880986] 'read index received' (duration: 105.412244ms)","trace[194880986] 'applied index is now lower than readState.Index' (duration: 176.115µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:16:20.740776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.734433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:16:20.740731Z","caller":"traceutil/trace.go:171","msg":"trace[350209880] transaction","detail":"{read_only:false; response_revision:5491; number_of_response:1; }","duration":"120.438906ms","start":"2026-04-07T00:16:20.620262Z","end":"2026-04-07T00:16:20.740701Z","steps":["trace[350209880] 'process raft request' (duration: 120.182029ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:20.740814Z","caller":"traceutil/trace.go:171","msg":"trace[1941501547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5491; }","duration":"105.823405ms","start":"2026-04-07T00:16:20.63498Z","end":"2026-04-07T00:16:20.740803Z","steps":["trace[1941501547] 'agreement among raft nodes before linearized reading' (duration: 105.743503ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:17:27.073464Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2982} {"level":"info","ts":"2026-04-07T00:17:27.150413Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2982,"took":"75.106799ms","hash":2478171694,"current-db-size-bytes":58404864,"current-db-size":"58 MB","current-db-size-in-use-bytes":33579008,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-07T00:17:27.150519Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2478171694,"revision":2982,"compact-revision":-1} {"level":"warn","ts":"2026-04-07T00:22:22.269493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.546271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:22:22.269617Z","caller":"traceutil/trace.go:171","msg":"trace[407311848] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:8231; }","duration":"126.716617ms","start":"2026-04-07T00:22:22.142874Z","end":"2026-04-07T00:22:22.269591Z","steps":["trace[407311848] 'count revisions from in-memory index tree' (duration: 126.503581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:22:22.269636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.998659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:22:22.269746Z","caller":"traceutil/trace.go:171","msg":"trace[910426473] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:8231; }","duration":"132.184633ms","start":"2026-04-07T00:22:22.137541Z","end":"2026-04-07T00:22:22.269725Z","steps":["trace[910426473] 'count revisions from in-memory index tree' (duration: 131.920087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:22:22.269901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.687115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-07T00:22:22.270024Z","caller":"traceutil/trace.go:171","msg":"trace[699140412] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8231; }","duration":"314.91715ms","start":"2026-04-07T00:22:21.955077Z","end":"2026-04-07T00:22:22.269994Z","steps":["trace[699140412] 'range keys from in-memory index tree' (duration: 314.423908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:22:22.27008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:22:21.955046Z","time spent":"315.024583ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-07T00:22:27.081774Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6062} {"level":"info","ts":"2026-04-07T00:22:27.178724Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6062,"took":"94.823467ms","hash":2097666776,"current-db-size-bytes":58404864,"current-db-size":"58 MB","current-db-size-in-use-bytes":23601152,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-07T00:22:27.17879Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2097666776,"revision":6062,"compact-revision":2982} {"level":"info","ts":"2026-04-07T00:22:34.912899Z","caller":"traceutil/trace.go:171","msg":"trace[327195220] transaction","detail":"{read_only:false; response_revision:8298; number_of_response:1; }","duration":"115.988257ms","start":"2026-04-07T00:22:34.796879Z","end":"2026-04-07T00:22:34.912867Z","steps":["trace[327195220] 'process raft request' (duration: 42.457705ms)","trace[327195220] 'compare' (duration: 73.378968ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:22:35.112799Z","caller":"traceutil/trace.go:171","msg":"trace[671705371] transaction","detail":"{read_only:false; response_revision:8300; number_of_response:1; }","duration":"100.409297ms","start":"2026-04-07T00:22:35.012348Z","end":"2026-04-07T00:22:35.112757Z","steps":["trace[671705371] 'process raft request' (duration: 23.210675ms)","trace[671705371] 'compare' (duration: 76.912043ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:23:03.14402Z","caller":"traceutil/trace.go:171","msg":"trace[1431671625] transaction","detail":"{read_only:false; response_revision:8502; number_of_response:1; }","duration":"191.395541ms","start":"2026-04-07T00:23:02.952594Z","end":"2026-04-07T00:23:03.14399Z","steps":["trace[1431671625] 'process raft request' (duration: 143.088498ms)","trace[1431671625] 'compare' (duration: 47.967964ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:23:03.144384Z","caller":"traceutil/trace.go:171","msg":"trace[1152147678] transaction","detail":"{read_only:false; response_revision:8503; number_of_response:1; }","duration":"127.824207ms","start":"2026-04-07T00:23:03.016532Z","end":"2026-04-07T00:23:03.144356Z","steps":["trace[1152147678] 'process raft request' (duration: 127.342934ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:23:06.27Z","caller":"traceutil/trace.go:171","msg":"trace[1782772508] linearizableReadLoop","detail":"{readStateIndex:8823; appliedIndex:8822; }","duration":"145.546654ms","start":"2026-04-07T00:23:06.12441Z","end":"2026-04-07T00:23:06.269957Z","steps":["trace[1782772508] 'read index received' (duration: 145.328938ms)","trace[1782772508] 'applied index is now lower than readState.Index' (duration: 216.706µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:23:06.270145Z","caller":"traceutil/trace.go:171","msg":"trace[1921438602] transaction","detail":"{read_only:false; response_revision:8517; number_of_response:1; }","duration":"171.649949ms","start":"2026-04-07T00:23:06.098481Z","end":"2026-04-07T00:23:06.270131Z","steps":["trace[1921438602] 'process raft request' (duration: 171.30532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:23:06.270466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.625129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-n4m44\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-07T00:23:06.270562Z","caller":"traceutil/trace.go:171","msg":"trace[1505874525] range","detail":"{range_begin:/registry/pods/openstack/keepalived-n4m44; range_end:; response_count:1; response_revision:8517; }","duration":"108.798474ms","start":"2026-04-07T00:23:06.161743Z","end":"2026-04-07T00:23:06.270542Z","steps":["trace[1505874525] 'agreement among raft nodes before linearized reading' (duration: 108.560527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:23:06.270658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.233872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T00:23:06.270712Z","caller":"traceutil/trace.go:171","msg":"trace[411559827] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8517; }","duration":"146.346805ms","start":"2026-04-07T00:23:06.124353Z","end":"2026-04-07T00:23:06.2707Z","steps":["trace[411559827] 'agreement among raft nodes before linearized reading' (duration: 146.17256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:23:15.773392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.271269ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-07T00:23:15.773705Z","caller":"traceutil/trace.go:171","msg":"trace[2024814030] linearizableReadLoop","detail":"{readStateIndex:8923; appliedIndex:8922; }","duration":"184.418523ms","start":"2026-04-07T00:23:15.589248Z","end":"2026-04-07T00:23:15.773667Z","steps":["trace[2024814030] 'read index received' (duration: 65.917428ms)","trace[2024814030] 'applied index is now lower than readState.Index' (duration: 118.497435ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:23:15.773871Z","caller":"traceutil/trace.go:171","msg":"trace[332881928] transaction","detail":"{read_only:false; response_revision:8613; number_of_response:1; }","duration":"183.550718ms","start":"2026-04-07T00:23:15.59025Z","end":"2026-04-07T00:23:15.773801Z","steps":["trace[332881928] 'process raft request' (duration: 183.25191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:23:15.854919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.634552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-9glq4\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-04-07T00:23:15.855022Z","caller":"traceutil/trace.go:171","msg":"trace[530881152] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-9glq4; range_end:; response_count:1; response_revision:8613; }","duration":"265.760526ms","start":"2026-04-07T00:23:15.589235Z","end":"2026-04-07T00:23:15.854995Z","steps":["trace[530881152] 'agreement among raft nodes before linearized reading' (duration: 184.573457ms)","trace[530881152] 'range keys from in-memory index tree' (duration: 80.901791ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:23:16.430082Z","caller":"traceutil/trace.go:171","msg":"trace[899293099] transaction","detail":"{read_only:false; response_revision:8618; number_of_response:1; }","duration":"105.444112ms","start":"2026-04-07T00:23:16.3246Z","end":"2026-04-07T00:23:16.430044Z","steps":["trace[899293099] 'process raft request' (duration: 105.231736ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:23:48.246356Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000242a.wal"} {"level":"info","ts":"2026-04-07T00:24:31.653395Z","caller":"traceutil/trace.go:171","msg":"trace[1250041832] transaction","detail":"{read_only:false; response_revision:9358; number_of_response:1; }","duration":"107.710022ms","start":"2026-04-07T00:24:31.545657Z","end":"2026-04-07T00:24:31.653368Z","steps":["trace[1250041832] 'process raft request' (duration: 107.485716ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:25:11.532935Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T00:25:11.541618Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T00:25:11.541822Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T00:27:27.090043Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8259} {"level":"info","ts":"2026-04-07T00:27:27.172881Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8259,"took":"80.341952ms","hash":3255797846,"current-db-size-bytes":58404864,"current-db-size":"58 MB","current-db-size-in-use-bytes":25280512,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-07T00:27:27.17298Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3255797846,"revision":8259,"compact-revision":6062} {"level":"info","ts":"2026-04-07T00:32:27.096569Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10744} {"level":"info","ts":"2026-04-07T00:32:27.180842Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10744,"took":"80.718739ms","hash":3221394611,"current-db-size-bytes":58404864,"current-db-size":"58 MB","current-db-size-in-use-bytes":26800128,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-07T00:32:27.180913Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3221394611,"revision":10744,"compact-revision":8259} {"level":"warn","ts":"2026-04-07T00:34:37.984144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.687479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-lz5nh\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-04-07T00:34:37.98427Z","caller":"traceutil/trace.go:171","msg":"trace[612929120] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-lz5nh; range_end:; response_count:1; response_revision:13784; }","duration":"142.880824ms","start":"2026-04-07T00:34:37.841364Z","end":"2026-04-07T00:34:37.984245Z","steps":["trace[612929120] 'range keys from in-memory index tree' (duration: 142.35011ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:34:38.830639Z","caller":"traceutil/trace.go:171","msg":"trace[131806778] transaction","detail":"{read_only:false; response_revision:13790; number_of_response:1; }","duration":"206.447581ms","start":"2026-04-07T00:34:38.624163Z","end":"2026-04-07T00:34:38.83061Z","steps":["trace[131806778] 'process raft request' (duration: 206.309657ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:34:38.831076Z","caller":"traceutil/trace.go:171","msg":"trace[933996756] linearizableReadLoop","detail":"{readStateIndex:14269; appliedIndex:14269; }","duration":"196.397029ms","start":"2026-04-07T00:34:38.634671Z","end":"2026-04-07T00:34:38.831068Z","steps":["trace[933996756] 'read index received' (duration: 196.393419ms)","trace[933996756] 'applied index is now lower than readState.Index' (duration: 2.89µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:34:38.83116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.482632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:34:38.831184Z","caller":"traceutil/trace.go:171","msg":"trace[358530787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13790; }","duration":"196.557664ms","start":"2026-04-07T00:34:38.634618Z","end":"2026-04-07T00:34:38.831176Z","steps":["trace[358530787] 'agreement among raft nodes before linearized reading' (duration: 196.494292ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:34:48.207977Z","caller":"traceutil/trace.go:171","msg":"trace[1593264823] transaction","detail":"{read_only:false; response_revision:13957; number_of_response:1; }","duration":"172.384847ms","start":"2026-04-07T00:34:48.035562Z","end":"2026-04-07T00:34:48.207947Z","steps":["trace[1593264823] 'process raft request' (duration: 92.552976ms)","trace[1593264823] 'compare' (duration: 79.587914ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:34:48.461378Z","caller":"traceutil/trace.go:171","msg":"trace[1078954231] transaction","detail":"{read_only:false; response_revision:13959; number_of_response:1; }","duration":"290.327041ms","start":"2026-04-07T00:34:48.171Z","end":"2026-04-07T00:34:48.461327Z","steps":["trace[1078954231] 'process raft request' (duration: 232.772407ms)","trace[1078954231] 'compare' (duration: 56.705622ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:34:48.461654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.639398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fdfd0085-e535-4298-81f2-5587ef578f80\" ","response":"range_response_count:1 size:3606"} {"level":"info","ts":"2026-04-07T00:34:48.461725Z","caller":"traceutil/trace.go:171","msg":"trace[700509458] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fdfd0085-e535-4298-81f2-5587ef578f80; range_end:; response_count:1; response_revision:13959; }","duration":"248.763222ms","start":"2026-04-07T00:34:48.212942Z","end":"2026-04-07T00:34:48.461706Z","steps":["trace[700509458] 'agreement among raft nodes before linearized reading' (duration: 248.465804ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:34:48.461726Z","caller":"traceutil/trace.go:171","msg":"trace[1532506044] linearizableReadLoop","detail":"{readStateIndex:14441; appliedIndex:14440; }","duration":"248.101904ms","start":"2026-04-07T00:34:48.213012Z","end":"2026-04-07T00:34:48.461114Z","steps":["trace[1532506044] 'read index received' (duration: 190.58072ms)","trace[1532506044] 'applied index is now lower than readState.Index' (duration: 57.520164ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:34:48.462689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.734443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T00:34:48.462751Z","caller":"traceutil/trace.go:171","msg":"trace[1408546102] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13959; }","duration":"244.826066ms","start":"2026-04-07T00:34:48.217909Z","end":"2026-04-07T00:34:48.462735Z","steps":["trace[1408546102] 'agreement among raft nodes before linearized reading' (duration: 244.627581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:34:48.463251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.676508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:67 size:629881"} {"level":"info","ts":"2026-04-07T00:34:48.463322Z","caller":"traceutil/trace.go:171","msg":"trace[1744576694] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:67; response_revision:13959; }","duration":"249.80037ms","start":"2026-04-07T00:34:48.213506Z","end":"2026-04-07T00:34:48.463306Z","steps":["trace[1744576694] 'agreement among raft nodes before linearized reading' (duration: 248.971788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:34:48.463456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.805577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T00:34:48.463507Z","caller":"traceutil/trace.go:171","msg":"trace[1537346872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13959; }","duration":"243.857929ms","start":"2026-04-07T00:34:48.219637Z","end":"2026-04-07T00:34:48.463494Z","steps":["trace[1537346872] 'agreement among raft nodes before linearized reading' (duration: 242.854782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:36:28.651697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.76235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-04-07T00:36:28.651829Z","caller":"traceutil/trace.go:171","msg":"trace[39096413] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15045; }","duration":"100.953394ms","start":"2026-04-07T00:36:28.550848Z","end":"2026-04-07T00:36:28.651802Z","steps":["trace[39096413] 'range keys from in-memory index tree' (duration: 100.611205ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:36:28.676696Z","caller":"traceutil/trace.go:171","msg":"trace[627914593] transaction","detail":"{read_only:false; response_revision:15046; number_of_response:1; }","duration":"114.616266ms","start":"2026-04-07T00:36:28.562062Z","end":"2026-04-07T00:36:28.676679Z","steps":["trace[627914593] 'process raft request' (duration: 114.431111ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:36:28.794988Z","caller":"traceutil/trace.go:171","msg":"trace[263145686] linearizableReadLoop","detail":"{readStateIndex:15561; appliedIndex:15560; }","duration":"172.397007ms","start":"2026-04-07T00:36:28.622559Z","end":"2026-04-07T00:36:28.794956Z","steps":["trace[263145686] 'read index received' (duration: 54.43396ms)","trace[263145686] 'applied index is now lower than readState.Index' (duration: 117.961557ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:36:28.795146Z","caller":"traceutil/trace.go:171","msg":"trace[1051782814] transaction","detail":"{read_only:false; response_revision:15047; number_of_response:1; }","duration":"183.639213ms","start":"2026-04-07T00:36:28.611495Z","end":"2026-04-07T00:36:28.795134Z","steps":["trace[1051782814] 'process raft request' (duration: 160.508634ms)","trace[1051782814] 'compare' (duration: 22.81986ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:36:28.795379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.773037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-04-07T00:36:28.795493Z","caller":"traceutil/trace.go:171","msg":"trace[489287267] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15047; }","duration":"172.921271ms","start":"2026-04-07T00:36:28.622545Z","end":"2026-04-07T00:36:28.795467Z","steps":["trace[489287267] 'agreement among raft nodes before linearized reading' (duration: 172.637193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:36:28.795589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.87812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:36:28.795645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.252133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-04-07T00:36:28.795667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.516344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:36:28.795704Z","caller":"traceutil/trace.go:171","msg":"trace[314136897] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15047; }","duration":"116.024454ms","start":"2026-04-07T00:36:28.679647Z","end":"2026-04-07T00:36:28.795671Z","steps":["trace[314136897] 'agreement among raft nodes before linearized reading' (duration: 115.807448ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:36:28.795719Z","caller":"traceutil/trace.go:171","msg":"trace[510723088] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15047; }","duration":"154.361966ms","start":"2026-04-07T00:36:28.641339Z","end":"2026-04-07T00:36:28.795701Z","steps":["trace[510723088] 'agreement among raft nodes before linearized reading' (duration: 154.168951ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:36:28.795731Z","caller":"traceutil/trace.go:171","msg":"trace[1736935019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15047; }","duration":"163.593067ms","start":"2026-04-07T00:36:28.632116Z","end":"2026-04-07T00:36:28.79571Z","steps":["trace[1736935019] 'agreement among raft nodes before linearized reading' (duration: 163.460904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:36:28.795741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.189647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-04-07T00:36:28.795801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.947162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:36:28.795849Z","caller":"traceutil/trace.go:171","msg":"trace[917358287] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15047; }","duration":"113.370812ms","start":"2026-04-07T00:36:28.682452Z","end":"2026-04-07T00:36:28.795823Z","steps":["trace[917358287] 'agreement among raft nodes before linearized reading' (duration: 113.068474ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:36:28.795903Z","caller":"traceutil/trace.go:171","msg":"trace[614214522] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15047; }","duration":"116.143797ms","start":"2026-04-07T00:36:28.679735Z","end":"2026-04-07T00:36:28.795879Z","steps":["trace[614214522] 'agreement among raft nodes before linearized reading' (duration: 115.919441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:36:28.795946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.311131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T00:36:28.796034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.409255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:36:28.796074Z","caller":"traceutil/trace.go:171","msg":"trace[134078000] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15047; }","duration":"116.449836ms","start":"2026-04-07T00:36:28.679613Z","end":"2026-04-07T00:36:28.796063Z","steps":["trace[134078000] 'agreement among raft nodes before linearized reading' (duration: 116.387534ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:36:28.79608Z","caller":"traceutil/trace.go:171","msg":"trace[503113835] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15047; }","duration":"173.544018ms","start":"2026-04-07T00:36:28.622503Z","end":"2026-04-07T00:36:28.796047Z","steps":["trace[503113835] 'agreement among raft nodes before linearized reading' (duration: 173.080916ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:36:29.650487Z","caller":"traceutil/trace.go:171","msg":"trace[1247947637] linearizableReadLoop","detail":"{readStateIndex:15564; appliedIndex:15563; }","duration":"106.124545ms","start":"2026-04-07T00:36:29.54433Z","end":"2026-04-07T00:36:29.650455Z","steps":["trace[1247947637] 'read index received' (duration: 105.852548ms)","trace[1247947637] 'applied index is now lower than readState.Index' (duration: 270.197µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:36:29.650673Z","caller":"traceutil/trace.go:171","msg":"trace[1216401837] transaction","detail":"{read_only:false; response_revision:15050; number_of_response:1; }","duration":"182.986664ms","start":"2026-04-07T00:36:29.467672Z","end":"2026-04-07T00:36:29.650658Z","steps":["trace[1216401837] 'process raft request' (duration: 182.535532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:36:29.651104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.753683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-n4m44\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-07T00:36:29.651234Z","caller":"traceutil/trace.go:171","msg":"trace[467866598] range","detail":"{range_begin:/registry/pods/openstack/keepalived-n4m44; range_end:; response_count:1; response_revision:15050; }","duration":"106.931687ms","start":"2026-04-07T00:36:29.544254Z","end":"2026-04-07T00:36:29.651186Z","steps":["trace[467866598] 'agreement among raft nodes before linearized reading' (duration: 106.681221ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:37:27.104258Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12854} {"level":"info","ts":"2026-04-07T00:37:27.188944Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12854,"took":"80.766253ms","hash":1691532004,"current-db-size-bytes":58404864,"current-db-size":"58 MB","current-db-size-in-use-bytes":30334976,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-07T00:37:27.189013Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1691532004,"revision":12854,"compact-revision":10744} {"level":"warn","ts":"2026-04-07T00:42:11.563047Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095031741522094,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-07T00:42:11.742039Z","caller":"traceutil/trace.go:171","msg":"trace[1044365733] transaction","detail":"{read_only:false; response_revision:17632; number_of_response:1; }","duration":"696.550076ms","start":"2026-04-07T00:42:11.045451Z","end":"2026-04-07T00:42:11.742001Z","steps":["trace[1044365733] 'process raft request' (duration: 696.305439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:42:11.742374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:42:11.045425Z","time spent":"696.71755ms","remote":"127.0.0.1:49824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20977,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:42:11.774537Z","caller":"traceutil/trace.go:171","msg":"trace[1887519824] transaction","detail":"{read_only:false; response_revision:17634; number_of_response:1; }","duration":"458.170277ms","start":"2026-04-07T00:42:11.31634Z","end":"2026-04-07T00:42:11.774511Z","steps":["trace[1887519824] 'process raft request' (duration: 458.102005ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:42:11.774571Z","caller":"traceutil/trace.go:171","msg":"trace[107222545] transaction","detail":"{read_only:false; response_revision:17633; number_of_response:1; }","duration":"727.360102ms","start":"2026-04-07T00:42:11.047156Z","end":"2026-04-07T00:42:11.774516Z","steps":["trace[107222545] 'process raft request' (duration: 704.037996ms)","trace[107222545] 'compare' (duration: 23.123531ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:42:11.7747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:42:11.316276Z","time spent":"458.33235ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:42:11.774743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:42:11.047121Z","time spent":"727.528757ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:42:11.774896Z","caller":"traceutil/trace.go:171","msg":"trace[114816021] transaction","detail":"{read_only:false; response_revision:17635; number_of_response:1; }","duration":"297.091814ms","start":"2026-04-07T00:42:11.477788Z","end":"2026-04-07T00:42:11.77488Z","steps":["trace[114816021] 'process raft request' (duration: 296.690203ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:42:11.774877Z","caller":"traceutil/trace.go:171","msg":"trace[471854902] linearizableReadLoop","detail":"{readStateIndex:18235; appliedIndex:18231; }","duration":"712.610937ms","start":"2026-04-07T00:42:11.062229Z","end":"2026-04-07T00:42:11.77484Z","steps":["trace[471854902] 'read index received' (duration: 679.513248ms)","trace[471854902] 'applied index is now lower than readState.Index' (duration: 33.096239ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:42:11.775248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.378414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-07T00:42:11.775278Z","caller":"traceutil/trace.go:171","msg":"trace[1141949228] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17635; }","duration":"236.446846ms","start":"2026-04-07T00:42:11.538824Z","end":"2026-04-07T00:42:11.775271Z","steps":["trace[1141949228] 'agreement among raft nodes before linearized reading' (duration: 236.296652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:42:11.775273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.028085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"warn","ts":"2026-04-07T00:42:11.775272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.018295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-07T00:42:11.775325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.025367ms","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-04-07T00:42:11.775591Z","caller":"traceutil/trace.go:171","msg":"trace[260256102] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17635; }","duration":"713.384897ms","start":"2026-04-07T00:42:11.06219Z","end":"2026-04-07T00:42:11.775575Z","steps":["trace[260256102] 'agreement among raft nodes before linearized reading' (duration: 712.893844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:42:11.775599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.85754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-07T00:42:11.775715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:42:11.06218Z","time spent":"713.488541ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T00:42:11.775383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.342232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-07T00:42:11.775711Z","caller":"traceutil/trace.go:171","msg":"trace[763579693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17635; }","duration":"239.018446ms","start":"2026-04-07T00:42:11.536667Z","end":"2026-04-07T00:42:11.775685Z","steps":["trace[763579693] 'agreement among raft nodes before linearized reading' (duration: 238.545372ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:42:11.775813Z","caller":"traceutil/trace.go:171","msg":"trace[1307720934] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17635; }","duration":"154.824096ms","start":"2026-04-07T00:42:11.620979Z","end":"2026-04-07T00:42:11.775803Z","steps":["trace[1307720934] 'agreement among raft nodes before linearized reading' (duration: 154.369913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:42:11.775337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.768858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:42:11.775393Z","caller":"traceutil/trace.go:171","msg":"trace[447325903] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:17635; }","duration":"350.204049ms","start":"2026-04-07T00:42:11.425156Z","end":"2026-04-07T00:42:11.77536Z","steps":["trace[447325903] 'agreement among raft nodes before linearized reading' (duration: 350.013194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:42:11.776028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:42:11.425131Z","time spent":"350.886379ms","remote":"127.0.0.1:42738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:42:11.775323Z","caller":"traceutil/trace.go:171","msg":"trace[294384225] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:17635; }","duration":"619.078836ms","start":"2026-04-07T00:42:11.156227Z","end":"2026-04-07T00:42:11.775305Z","steps":["trace[294384225] 'agreement among raft nodes before linearized reading' (duration: 618.880641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:42:11.776215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:42:11.156199Z","time spent":"619.986661ms","remote":"127.0.0.1:49800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1960,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-04-07T00:42:11.776224Z","caller":"traceutil/trace.go:171","msg":"trace[2008311378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17635; }","duration":"141.627061ms","start":"2026-04-07T00:42:11.63453Z","end":"2026-04-07T00:42:11.776157Z","steps":["trace[2008311378] 'agreement among raft nodes before linearized reading' (duration: 140.791078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:42:11.775382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.915122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1991"} {"level":"info","ts":"2026-04-07T00:42:11.776357Z","caller":"traceutil/trace.go:171","msg":"trace[1127024248] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:17635; }","duration":"242.958302ms","start":"2026-04-07T00:42:11.53339Z","end":"2026-04-07T00:42:11.776348Z","steps":["trace[1127024248] 'agreement among raft nodes before linearized reading' (duration: 241.765639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:42:11.775417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.694707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-stf82\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-04-07T00:42:11.776462Z","caller":"traceutil/trace.go:171","msg":"trace[799066883] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-stf82; range_end:; response_count:1; response_revision:17635; }","duration":"590.788397ms","start":"2026-04-07T00:42:11.185667Z","end":"2026-04-07T00:42:11.776456Z","steps":["trace[799066883] 'agreement among raft nodes before linearized reading' (duration: 589.679517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:42:11.776479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:42:11.18564Z","time spent":"590.832778ms","remote":"127.0.0.1:49824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9823,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-stf82\" "} {"level":"warn","ts":"2026-04-07T00:42:11.775427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.879436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-07T00:42:11.776681Z","caller":"traceutil/trace.go:171","msg":"trace[332467325] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:17635; }","duration":"197.116881ms","start":"2026-04-07T00:42:11.579544Z","end":"2026-04-07T00:42:11.77666Z","steps":["trace[332467325] 'agreement among raft nodes before linearized reading' (duration: 195.853666ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:42:27.11401Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15414} {"level":"info","ts":"2026-04-07T00:42:27.217583Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15414,"took":"99.441479ms","hash":3626223392,"current-db-size-bytes":58404864,"current-db-size":"58 MB","current-db-size-in-use-bytes":31879168,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-07T00:42:27.21767Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3626223392,"revision":15414,"compact-revision":12854} {"level":"info","ts":"2026-04-07T00:45:44.964309Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T00:45:44.969763Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-07T00:45:44.969906Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-07T00:47:27.121155Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17805} {"level":"info","ts":"2026-04-07T00:47:27.228991Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17805,"took":"103.523224ms","hash":97926734,"current-db-size-bytes":58404864,"current-db-size":"58 MB","current-db-size-in-use-bytes":33320960,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-07T00:47:27.229065Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":97926734,"revision":17805,"compact-revision":15414} {"level":"info","ts":"2026-04-07T00:47:32.288431Z","caller":"traceutil/trace.go:171","msg":"trace[152143511] transaction","detail":"{read_only:false; response_revision:20096; number_of_response:1; }","duration":"198.069205ms","start":"2026-04-07T00:47:32.090307Z","end":"2026-04-07T00:47:32.288376Z","steps":["trace[152143511] 'process raft request' (duration: 197.487409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:47:33.767694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.060405ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:47:33.767854Z","caller":"traceutil/trace.go:171","msg":"trace[1997978194] transaction","detail":"{read_only:false; response_revision:20100; number_of_response:1; }","duration":"250.268243ms","start":"2026-04-07T00:47:33.51756Z","end":"2026-04-07T00:47:33.767828Z","steps":["trace[1997978194] 'process raft request' (duration: 114.938151ms)","trace[1997978194] 'compare' (duration: 134.854969ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:47:33.774599Z","caller":"traceutil/trace.go:171","msg":"trace[1363605360] linearizableReadLoop","detail":"{readStateIndex:20785; appliedIndex:20782; }","duration":"195.611798ms","start":"2026-04-07T00:47:33.578961Z","end":"2026-04-07T00:47:33.774572Z","steps":["trace[1363605360] 'read index received' (duration: 53.553636ms)","trace[1363605360] 'applied index is now lower than readState.Index' (duration: 142.056832ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:47:33.774931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.940148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:47:33.774912Z","caller":"traceutil/trace.go:171","msg":"trace[1433077018] transaction","detail":"{read_only:false; response_revision:20101; number_of_response:1; }","duration":"256.925252ms","start":"2026-04-07T00:47:33.51793Z","end":"2026-04-07T00:47:33.774856Z","steps":["trace[1433077018] 'process raft request' (duration: 256.439539ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:47:33.775025Z","caller":"traceutil/trace.go:171","msg":"trace[211142161] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:20102; }","duration":"196.059621ms","start":"2026-04-07T00:47:33.578942Z","end":"2026-04-07T00:47:33.775001Z","steps":["trace[211142161] 'agreement among raft nodes before linearized reading' (duration: 195.854095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:47:33.7752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.687167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-07T00:47:33.775288Z","caller":"traceutil/trace.go:171","msg":"trace[1712392277] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:20102; }","duration":"133.836012ms","start":"2026-04-07T00:47:33.641431Z","end":"2026-04-07T00:47:33.775267Z","steps":["trace[1712392277] 'agreement among raft nodes before linearized reading' (duration: 133.497442ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:47:33.775216Z","caller":"traceutil/trace.go:171","msg":"trace[366949956] transaction","detail":"{read_only:false; response_revision:20102; number_of_response:1; }","duration":"256.457431ms","start":"2026-04-07T00:47:33.518741Z","end":"2026-04-07T00:47:33.775199Z","steps":["trace[366949956] 'process raft request' (duration: 255.777752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:47:33.775387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.520068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-07T00:47:33.775437Z","caller":"traceutil/trace.go:171","msg":"trace[803904953] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20102; }","duration":"128.623661ms","start":"2026-04-07T00:47:33.646799Z","end":"2026-04-07T00:47:33.775423Z","steps":["trace[803904953] 'agreement among raft nodes before linearized reading' (duration: 128.446996ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:47:33.977298Z","caller":"traceutil/trace.go:171","msg":"trace[1971663482] transaction","detail":"{read_only:false; response_revision:20104; number_of_response:1; }","duration":"197.062829ms","start":"2026-04-07T00:47:33.780197Z","end":"2026-04-07T00:47:33.97726Z","steps":["trace[1971663482] 'process raft request' (duration: 154.211742ms)","trace[1971663482] 'compare' (duration: 42.648031ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:51:27.274888Z","caller":"traceutil/trace.go:171","msg":"trace[1892550995] transaction","detail":"{read_only:false; response_revision:22500; number_of_response:1; }","duration":"122.818834ms","start":"2026-04-07T00:51:27.152037Z","end":"2026-04-07T00:51:27.274856Z","steps":["trace[1892550995] 'process raft request' (duration: 122.043284ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:51:27.275637Z","caller":"traceutil/trace.go:171","msg":"trace[235448601] transaction","detail":"{read_only:false; response_revision:22501; number_of_response:1; }","duration":"123.524613ms","start":"2026-04-07T00:51:27.152075Z","end":"2026-04-07T00:51:27.2756Z","steps":["trace[235448601] 'process raft request' (duration: 123.247556ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:51:27.276082Z","caller":"traceutil/trace.go:171","msg":"trace[957355013] transaction","detail":"{read_only:false; response_revision:22502; number_of_response:1; }","duration":"123.896963ms","start":"2026-04-07T00:51:27.152173Z","end":"2026-04-07T00:51:27.27607Z","steps":["trace[957355013] 'process raft request' (duration: 123.355928ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:52:27.128559Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20068} {"level":"info","ts":"2026-04-07T00:52:27.24526Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20068,"took":"111.927351ms","hash":2746260813,"current-db-size-bytes":58404864,"current-db-size":"58 MB","current-db-size-in-use-bytes":41111552,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-04-07T00:52:27.245371Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2746260813,"revision":20068,"compact-revision":17805} {"level":"info","ts":"2026-04-07T00:54:46.190892Z","caller":"traceutil/trace.go:171","msg":"trace[646827163] transaction","detail":"{read_only:false; response_revision:24516; number_of_response:1; }","duration":"129.587312ms","start":"2026-04-07T00:54:46.061261Z","end":"2026-04-07T00:54:46.190849Z","steps":["trace[646827163] 'process raft request' (duration: 129.335085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:50.585837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.594736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-07T00:54:50.585933Z","caller":"traceutil/trace.go:171","msg":"trace[2120388388] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24553; }","duration":"188.73768ms","start":"2026-04-07T00:54:50.397174Z","end":"2026-04-07T00:54:50.585912Z","steps":["trace[2120388388] 'range keys from in-memory index tree' (duration: 188.39216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:50.586287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.115798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:54:50.586488Z","caller":"traceutil/trace.go:171","msg":"trace[609131260] transaction","detail":"{read_only:false; response_revision:24554; number_of_response:1; }","duration":"181.646848ms","start":"2026-04-07T00:54:50.404814Z","end":"2026-04-07T00:54:50.586461Z","steps":["trace[609131260] 'process raft request' (duration: 62.072467ms)","trace[609131260] 'compare' (duration: 118.959344ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:50.615112Z","caller":"traceutil/trace.go:171","msg":"trace[776478397] linearizableReadLoop","detail":"{readStateIndex:25365; appliedIndex:25363; }","duration":"208.210167ms","start":"2026-04-07T00:54:50.406874Z","end":"2026-04-07T00:54:50.615084Z","steps":["trace[776478397] 'read index received' (duration: 59.983431ms)","trace[776478397] 'applied index is now lower than readState.Index' (duration: 148.225686ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:50.615218Z","caller":"traceutil/trace.go:171","msg":"trace[2040387332] transaction","detail":"{read_only:false; response_revision:24555; number_of_response:1; }","duration":"209.512462ms","start":"2026-04-07T00:54:50.405637Z","end":"2026-04-07T00:54:50.615149Z","steps":["trace[2040387332] 'process raft request' (duration: 209.298226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:50.615389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.180456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T00:54:50.615431Z","caller":"traceutil/trace.go:171","msg":"trace[282180075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24555; }","duration":"208.256619ms","start":"2026-04-07T00:54:50.407164Z","end":"2026-04-07T00:54:50.61542Z","steps":["trace[282180075] 'agreement among raft nodes before linearized reading' (duration: 208.101895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:50.615421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.526286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-07T00:54:50.615519Z","caller":"traceutil/trace.go:171","msg":"trace[823114] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24555; }","duration":"208.6813ms","start":"2026-04-07T00:54:50.406814Z","end":"2026-04-07T00:54:50.615496Z","steps":["trace[823114] 'agreement among raft nodes before linearized reading' (duration: 208.420573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:50.615489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.108114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:54:50.615523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.164106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:50.615606Z","caller":"traceutil/trace.go:171","msg":"trace[1987530980] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:24555; }","duration":"161.376191ms","start":"2026-04-07T00:54:50.454208Z","end":"2026-04-07T00:54:50.615585Z","steps":["trace[1987530980] 'agreement among raft nodes before linearized reading' (duration: 161.118404ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:50.615603Z","caller":"traceutil/trace.go:171","msg":"trace[1314546318] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:24555; }","duration":"208.253118ms","start":"2026-04-07T00:54:50.407324Z","end":"2026-04-07T00:54:50.615578Z","steps":["trace[1314546318] 'agreement among raft nodes before linearized reading' (duration: 208.062423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:50.615747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.927668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-07T00:54:50.615807Z","caller":"traceutil/trace.go:171","msg":"trace[24960070] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24555; }","duration":"158.027291ms","start":"2026-04-07T00:54:50.457763Z","end":"2026-04-07T00:54:50.615791Z","steps":["trace[24960070] 'agreement among raft nodes before linearized reading' (duration: 157.779284ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:50.739778Z","caller":"traceutil/trace.go:171","msg":"trace[757923235] linearizableReadLoop","detail":"{readStateIndex:25369; appliedIndex:25365; }","duration":"108.07642ms","start":"2026-04-07T00:54:50.631681Z","end":"2026-04-07T00:54:50.739757Z","steps":["trace[757923235] 'read index received' (duration: 94.38327ms)","trace[757923235] 'applied index is now lower than readState.Index' (duration: 13.692ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:50.740097Z","caller":"traceutil/trace.go:171","msg":"trace[543300839] transaction","detail":"{read_only:false; response_revision:24556; number_of_response:1; }","duration":"117.230488ms","start":"2026-04-07T00:54:50.622854Z","end":"2026-04-07T00:54:50.740085Z","steps":["trace[543300839] 'process raft request' (duration: 103.272321ms)","trace[543300839] 'compare' (duration: 13.3134ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:50.740274Z","caller":"traceutil/trace.go:171","msg":"trace[486048395] transaction","detail":"{read_only:false; response_revision:24557; number_of_response:1; }","duration":"117.097403ms","start":"2026-04-07T00:54:50.623172Z","end":"2026-04-07T00:54:50.740269Z","steps":["trace[486048395] 'process raft request' (duration: 116.487347ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:50.740367Z","caller":"traceutil/trace.go:171","msg":"trace[597779007] transaction","detail":"{read_only:false; response_revision:24558; number_of_response:1; }","duration":"117.123264ms","start":"2026-04-07T00:54:50.623232Z","end":"2026-04-07T00:54:50.740355Z","steps":["trace[597779007] 'process raft request' (duration: 116.475237ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:50.740472Z","caller":"traceutil/trace.go:171","msg":"trace[1501242279] transaction","detail":"{read_only:false; response_revision:24559; number_of_response:1; }","duration":"117.145384ms","start":"2026-04-07T00:54:50.62332Z","end":"2026-04-07T00:54:50.740466Z","steps":["trace[1501242279] 'process raft request' (duration: 116.411745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:50.740636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.928693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:50.74067Z","caller":"traceutil/trace.go:171","msg":"trace[1986737634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24559; }","duration":"109.012385ms","start":"2026-04-07T00:54:50.631649Z","end":"2026-04-07T00:54:50.740662Z","steps":["trace[1986737634] 'agreement among raft nodes before linearized reading' (duration: 108.926612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:50.991519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.956675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:54:50.9917Z","caller":"traceutil/trace.go:171","msg":"trace[450018555] transaction","detail":"{read_only:false; response_revision:24560; number_of_response:1; }","duration":"215.397221ms","start":"2026-04-07T00:54:50.776279Z","end":"2026-04-07T00:54:50.991677Z","steps":["trace[450018555] 'process raft request' (duration: 103.174688ms)","trace[450018555] 'compare' (duration: 111.460922ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:51.085244Z","caller":"traceutil/trace.go:171","msg":"trace[475954814] transaction","detail":"{read_only:false; response_revision:24561; number_of_response:1; }","duration":"168.666758ms","start":"2026-04-07T00:54:50.916545Z","end":"2026-04-07T00:54:51.085212Z","steps":["trace[475954814] 'process raft request' (duration: 168.462152ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:51.194921Z","caller":"traceutil/trace.go:171","msg":"trace[266207465] transaction","detail":"{read_only:false; response_revision:24562; number_of_response:1; }","duration":"276.189084ms","start":"2026-04-07T00:54:50.918696Z","end":"2026-04-07T00:54:51.194885Z","steps":["trace[266207465] 'process raft request' (duration: 274.623632ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:51.195127Z","caller":"traceutil/trace.go:171","msg":"trace[1304920214] linearizableReadLoop","detail":"{readStateIndex:25372; appliedIndex:25370; }","duration":"250.889971ms","start":"2026-04-07T00:54:50.9442Z","end":"2026-04-07T00:54:51.19509Z","steps":["trace[1304920214] 'read index received' (duration: 140.793495ms)","trace[1304920214] 'applied index is now lower than readState.Index' (duration: 110.094956ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:51.195332Z","caller":"traceutil/trace.go:171","msg":"trace[775446894] transaction","detail":"{read_only:false; response_revision:24563; number_of_response:1; }","duration":"111.657727ms","start":"2026-04-07T00:54:51.083661Z","end":"2026-04-07T00:54:51.195319Z","steps":["trace[775446894] 'process raft request' (duration: 111.158694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:51.195599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.984855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:51.19567Z","caller":"traceutil/trace.go:171","msg":"trace[1650822137] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:24563; }","duration":"156.115829ms","start":"2026-04-07T00:54:51.039533Z","end":"2026-04-07T00:54:51.195649Z","steps":["trace[1650822137] 'agreement among raft nodes before linearized reading' (duration: 155.987165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:51.195885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.661011ms","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-04-07T00:54:51.195966Z","caller":"traceutil/trace.go:171","msg":"trace[497177421] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:24563; }","duration":"251.820195ms","start":"2026-04-07T00:54:50.944134Z","end":"2026-04-07T00:54:51.195954Z","steps":["trace[497177421] 'agreement among raft nodes before linearized reading' (duration: 251.581008ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:51.447758Z","caller":"traceutil/trace.go:171","msg":"trace[1450955560] transaction","detail":"{read_only:false; response_revision:24564; number_of_response:1; }","duration":"141.610418ms","start":"2026-04-07T00:54:51.306106Z","end":"2026-04-07T00:54:51.447716Z","steps":["trace[1450955560] 'process raft request' (duration: 97.828074ms)","trace[1450955560] 'compare' (duration: 43.621909ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:52.559811Z","caller":"traceutil/trace.go:171","msg":"trace[1395162014] linearizableReadLoop","detail":"{readStateIndex:25376; appliedIndex:25375; }","duration":"280.1362ms","start":"2026-04-07T00:54:52.279632Z","end":"2026-04-07T00:54:52.559768Z","steps":["trace[1395162014] 'read index received' (duration: 278.354952ms)","trace[1395162014] 'applied index is now lower than readState.Index' (duration: 1.779648ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:52.560124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.396007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:52.560594Z","caller":"traceutil/trace.go:171","msg":"trace[1680924520] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24566; }","duration":"280.908391ms","start":"2026-04-07T00:54:52.279622Z","end":"2026-04-07T00:54:52.560531Z","steps":["trace[1680924520] 'agreement among raft nodes before linearized reading' (duration: 280.287374ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:52.560388Z","caller":"traceutil/trace.go:171","msg":"trace[1578653409] transaction","detail":"{read_only:false; response_revision:24566; number_of_response:1; }","duration":"283.627794ms","start":"2026-04-07T00:54:52.276707Z","end":"2026-04-07T00:54:52.560335Z","steps":["trace[1578653409] 'process raft request' (duration: 281.375604ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:52.56775Z","caller":"traceutil/trace.go:171","msg":"trace[647265419] transaction","detail":"{read_only:false; response_revision:24567; number_of_response:1; }","duration":"174.001772ms","start":"2026-04-07T00:54:52.393734Z","end":"2026-04-07T00:54:52.567736Z","steps":["trace[647265419] 'process raft request' (duration: 173.683344ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:52.710998Z","caller":"traceutil/trace.go:171","msg":"trace[2104378517] transaction","detail":"{read_only:false; response_revision:24568; number_of_response:1; }","duration":"117.249068ms","start":"2026-04-07T00:54:52.593714Z","end":"2026-04-07T00:54:52.710963Z","steps":["trace[2104378517] 'process raft request' (duration: 114.768261ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:52.905336Z","caller":"traceutil/trace.go:171","msg":"trace[1209462354] transaction","detail":"{read_only:false; response_revision:24570; number_of_response:1; }","duration":"153.90274ms","start":"2026-04-07T00:54:52.751395Z","end":"2026-04-07T00:54:52.905298Z","steps":["trace[1209462354] 'process raft request' (duration: 113.509929ms)","trace[1209462354] 'compare' (duration: 40.257817ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:53.100833Z","caller":"traceutil/trace.go:171","msg":"trace[1741558726] transaction","detail":"{read_only:false; response_revision:24571; number_of_response:1; }","duration":"349.065533ms","start":"2026-04-07T00:54:52.751741Z","end":"2026-04-07T00:54:53.100807Z","steps":["trace[1741558726] 'process raft request' (duration: 348.655022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:53.10108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:52.751719Z","time spent":"349.202207ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:53.101179Z","caller":"traceutil/trace.go:171","msg":"trace[1109509649] transaction","detail":"{read_only:false; response_revision:24572; number_of_response:1; }","duration":"349.006541ms","start":"2026-04-07T00:54:52.752145Z","end":"2026-04-07T00:54:53.101152Z","steps":["trace[1109509649] 'process raft request' (duration: 348.605251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:53.101365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:52.752125Z","time spent":"349.136145ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:53.101525Z","caller":"traceutil/trace.go:171","msg":"trace[489128596] linearizableReadLoop","detail":"{readStateIndex:25383; appliedIndex:25380; }","duration":"105.667376ms","start":"2026-04-07T00:54:52.995839Z","end":"2026-04-07T00:54:53.101506Z","steps":["trace[489128596] 'read index received' (duration: 104.508695ms)","trace[489128596] 'applied index is now lower than readState.Index' (duration: 1.157041ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:53.101613Z","caller":"traceutil/trace.go:171","msg":"trace[454180230] transaction","detail":"{read_only:false; response_revision:24573; number_of_response:1; }","duration":"349.564056ms","start":"2026-04-07T00:54:52.752036Z","end":"2026-04-07T00:54:53.1016Z","steps":["trace[454180230] 'process raft request' (duration: 349.026772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:53.10168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:52.752024Z","time spent":"349.624099ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:53.101692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.84761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-07T00:54:53.101736Z","caller":"traceutil/trace.go:171","msg":"trace[808682856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24573; }","duration":"105.937293ms","start":"2026-04-07T00:54:52.995787Z","end":"2026-04-07T00:54:53.101725Z","steps":["trace[808682856] 'agreement among raft nodes before linearized reading' (duration: 105.785929ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:53.350738Z","caller":"traceutil/trace.go:171","msg":"trace[1786604616] transaction","detail":"{read_only:false; response_revision:24574; number_of_response:1; }","duration":"259.431351ms","start":"2026-04-07T00:54:53.091278Z","end":"2026-04-07T00:54:53.35071Z","steps":["trace[1786604616] 'process raft request' (duration: 175.05221ms)","trace[1786604616] 'compare' (duration: 83.961679ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:53.528823Z","caller":"traceutil/trace.go:171","msg":"trace[1365531583] transaction","detail":"{read_only:false; response_revision:24575; number_of_response:1; }","duration":"421.170622ms","start":"2026-04-07T00:54:53.107625Z","end":"2026-04-07T00:54:53.528795Z","steps":["trace[1365531583] 'process raft request' (duration: 420.680889ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:53.529092Z","caller":"traceutil/trace.go:171","msg":"trace[1365215783] transaction","detail":"{read_only:false; response_revision:24576; number_of_response:1; }","duration":"327.432208ms","start":"2026-04-07T00:54:53.201622Z","end":"2026-04-07T00:54:53.529054Z","steps":["trace[1365215783] 'process raft request' (duration: 327.038128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:53.529194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:53.107604Z","time spent":"421.304805ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:53.529294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:53.201588Z","time spent":"327.600803ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:53.529533Z","caller":"traceutil/trace.go:171","msg":"trace[638628040] linearizableReadLoop","detail":"{readStateIndex:25387; appliedIndex:25387; }","duration":"200.958651ms","start":"2026-04-07T00:54:53.32856Z","end":"2026-04-07T00:54:53.529519Z","steps":["trace[638628040] 'read index received' (duration: 200.951711ms)","trace[638628040] 'applied index is now lower than readState.Index' (duration: 5.9µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:53.529659Z","caller":"traceutil/trace.go:171","msg":"trace[608292804] transaction","detail":"{read_only:false; response_revision:24577; number_of_response:1; }","duration":"327.910361ms","start":"2026-04-07T00:54:53.201734Z","end":"2026-04-07T00:54:53.529644Z","steps":["trace[608292804] 'process raft request' (duration: 327.236663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:53.529743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:53.201725Z","time spent":"327.976133ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:53.529778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.208398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:53.529914Z","caller":"traceutil/trace.go:171","msg":"trace[917181127] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:24577; }","duration":"201.347242ms","start":"2026-04-07T00:54:53.328512Z","end":"2026-04-07T00:54:53.529859Z","steps":["trace[917181127] 'agreement among raft nodes before linearized reading' (duration: 201.168467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:53.53108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.018979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:53.531193Z","caller":"traceutil/trace.go:171","msg":"trace[1388670818] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:24578; }","duration":"199.159553ms","start":"2026-04-07T00:54:53.331983Z","end":"2026-04-07T00:54:53.531142Z","steps":["trace[1388670818] 'agreement among raft nodes before linearized reading' (duration: 198.972578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:53.825205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.209288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:53.825306Z","caller":"traceutil/trace.go:171","msg":"trace[1807769783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24578; }","duration":"191.373793ms","start":"2026-04-07T00:54:53.63391Z","end":"2026-04-07T00:54:53.825283Z","steps":["trace[1807769783] 'range keys from in-memory index tree' (duration: 191.116035ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:54.380816Z","caller":"traceutil/trace.go:171","msg":"trace[1897287342] transaction","detail":"{read_only:false; response_revision:24580; number_of_response:1; }","duration":"105.898882ms","start":"2026-04-07T00:54:54.274883Z","end":"2026-04-07T00:54:54.380781Z","steps":["trace[1897287342] 'process raft request' (duration: 105.648405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:54.709662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.387734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-07T00:54:54.709751Z","caller":"traceutil/trace.go:171","msg":"trace[1508614034] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24580; }","duration":"143.537659ms","start":"2026-04-07T00:54:54.566193Z","end":"2026-04-07T00:54:54.70973Z","steps":["trace[1508614034] 'range keys from in-memory index tree' (duration: 143.123307ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:55.021834Z","caller":"traceutil/trace.go:171","msg":"trace[1204850179] linearizableReadLoop","detail":"{readStateIndex:25394; appliedIndex:25391; }","duration":"111.883704ms","start":"2026-04-07T00:54:54.909919Z","end":"2026-04-07T00:54:55.021803Z","steps":["trace[1204850179] 'read index received' (duration: 111.521845ms)","trace[1204850179] 'applied index is now lower than readState.Index' (duration: 360.669µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:55.021979Z","caller":"traceutil/trace.go:171","msg":"trace[146529056] transaction","detail":"{read_only:false; response_revision:24582; number_of_response:1; }","duration":"306.882404ms","start":"2026-04-07T00:54:54.715051Z","end":"2026-04-07T00:54:55.021933Z","steps":["trace[146529056] 'process raft request' (duration: 306.486943ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:55.022075Z","caller":"traceutil/trace.go:171","msg":"trace[1417417007] transaction","detail":"{read_only:false; response_revision:24583; number_of_response:1; }","duration":"305.378563ms","start":"2026-04-07T00:54:54.716635Z","end":"2026-04-07T00:54:55.022014Z","steps":["trace[1417417007] 'process raft request' (duration: 305.064654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:55.022154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:54.715022Z","time spent":"307.047478ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:55.022114Z","caller":"traceutil/trace.go:171","msg":"trace[823927693] transaction","detail":"{read_only:false; response_revision:24584; number_of_response:1; }","duration":"303.718198ms","start":"2026-04-07T00:54:54.718358Z","end":"2026-04-07T00:54:55.022077Z","steps":["trace[823927693] 'process raft request' (duration: 303.3997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:55.022339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.387967ms","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-04-07T00:54:55.022363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:54.716605Z","time spent":"305.601869ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:55.022411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:54.718347Z","time spent":"303.943424ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:55.022455Z","caller":"traceutil/trace.go:171","msg":"trace[490416377] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24584; }","duration":"112.553932ms","start":"2026-04-07T00:54:54.909841Z","end":"2026-04-07T00:54:55.022395Z","steps":["trace[490416377] 'agreement among raft nodes before linearized reading' (duration: 112.078149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:55.283451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.979961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:54:55.283561Z","caller":"traceutil/trace.go:171","msg":"trace[665649969] linearizableReadLoop","detail":"{readStateIndex:25395; appliedIndex:25394; }","duration":"175.265606ms","start":"2026-04-07T00:54:55.108277Z","end":"2026-04-07T00:54:55.283543Z","steps":["trace[665649969] 'read index received' (duration: 41.10157ms)","trace[665649969] 'applied index is now lower than readState.Index' (duration: 134.162676ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:55.283628Z","caller":"traceutil/trace.go:171","msg":"trace[2096278017] transaction","detail":"{read_only:false; response_revision:24585; number_of_response:1; }","duration":"256.165653ms","start":"2026-04-07T00:54:55.027409Z","end":"2026-04-07T00:54:55.283574Z","steps":["trace[2096278017] 'process raft request' (duration: 121.952385ms)","trace[2096278017] 'compare' (duration: 133.441387ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:55.283764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.485891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T00:54:55.283787Z","caller":"traceutil/trace.go:171","msg":"trace[1737519343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24585; }","duration":"175.551333ms","start":"2026-04-07T00:54:55.108227Z","end":"2026-04-07T00:54:55.283779Z","steps":["trace[1737519343] 'agreement among raft nodes before linearized reading' (duration: 175.404709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:55.283813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.506242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-07T00:54:55.283942Z","caller":"traceutil/trace.go:171","msg":"trace[1697812947] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24585; }","duration":"175.659766ms","start":"2026-04-07T00:54:55.108228Z","end":"2026-04-07T00:54:55.283887Z","steps":["trace[1697812947] 'agreement among raft nodes before linearized reading' (duration: 175.334417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:55.283994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.368368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-07T00:54:55.284102Z","caller":"traceutil/trace.go:171","msg":"trace[1736092344] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24585; }","duration":"175.547733ms","start":"2026-04-07T00:54:55.108525Z","end":"2026-04-07T00:54:55.284073Z","steps":["trace[1736092344] 'agreement among raft nodes before linearized reading' (duration: 175.167023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:03.668051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.062289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:03.668187Z","caller":"traceutil/trace.go:171","msg":"trace[1466640273] transaction","detail":"{read_only:false; response_revision:24660; number_of_response:1; }","duration":"272.34952ms","start":"2026-04-07T00:55:03.395814Z","end":"2026-04-07T00:55:03.668164Z","steps":["trace[1466640273] 'process raft request' (duration: 140.027844ms)","trace[1466640273] 'compare' (duration: 131.307479ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:03.748672Z","caller":"traceutil/trace.go:171","msg":"trace[1255535180] transaction","detail":"{read_only:false; response_revision:24663; number_of_response:1; }","duration":"349.239188ms","start":"2026-04-07T00:55:03.399411Z","end":"2026-04-07T00:55:03.74865Z","steps":["trace[1255535180] 'process raft request' (duration: 349.188617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:03.748788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:03.399376Z","time spent":"349.356732ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:03.749125Z","caller":"traceutil/trace.go:171","msg":"trace[1236430417] transaction","detail":"{read_only:false; response_revision:24661; number_of_response:1; }","duration":"352.766013ms","start":"2026-04-07T00:55:03.396348Z","end":"2026-04-07T00:55:03.749114Z","steps":["trace[1236430417] 'process raft request' (duration: 352.031773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:03.749186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:03.39632Z","time spent":"352.830035ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:03.749322Z","caller":"traceutil/trace.go:171","msg":"trace[394925021] transaction","detail":"{read_only:false; response_revision:24662; number_of_response:1; }","duration":"349.989938ms","start":"2026-04-07T00:55:03.399321Z","end":"2026-04-07T00:55:03.749311Z","steps":["trace[394925021] 'process raft request' (duration: 349.227378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:03.749372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:03.3993Z","time spent":"350.04232ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:03.819746Z","caller":"traceutil/trace.go:171","msg":"trace[1168393636] transaction","detail":"{read_only:false; response_revision:24665; number_of_response:1; }","duration":"249.732518ms","start":"2026-04-07T00:55:03.569982Z","end":"2026-04-07T00:55:03.819714Z","steps":["trace[1168393636] 'process raft request' (duration: 249.396689ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:03.81987Z","caller":"traceutil/trace.go:171","msg":"trace[1893525811] linearizableReadLoop","detail":"{readStateIndex:25478; appliedIndex:25475; }","duration":"240.112178ms","start":"2026-04-07T00:55:03.579738Z","end":"2026-04-07T00:55:03.81985Z","steps":["trace[1893525811] 'read index received' (duration: 170.105326ms)","trace[1893525811] 'applied index is now lower than readState.Index' (duration: 70.005762ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:03.820197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.456517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-07T00:55:03.820245Z","caller":"traceutil/trace.go:171","msg":"trace[213145325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24666; }","duration":"240.55722ms","start":"2026-04-07T00:55:03.579673Z","end":"2026-04-07T00:55:03.82023Z","steps":["trace[213145325] 'agreement among raft nodes before linearized reading' (duration: 240.325874ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:03.820327Z","caller":"traceutil/trace.go:171","msg":"trace[607342113] transaction","detail":"{read_only:false; response_revision:24666; number_of_response:1; }","duration":"248.70045ms","start":"2026-04-07T00:55:03.571612Z","end":"2026-04-07T00:55:03.820312Z","steps":["trace[607342113] 'process raft request' (duration: 248.005852ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:03.82064Z","caller":"traceutil/trace.go:171","msg":"trace[451621869] transaction","detail":"{read_only:false; response_revision:24664; number_of_response:1; }","duration":"252.237956ms","start":"2026-04-07T00:55:03.56724Z","end":"2026-04-07T00:55:03.819478Z","steps":["trace[451621869] 'process raft request' (duration: 250.735686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:04.148843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.513627ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:04.149Z","caller":"traceutil/trace.go:171","msg":"trace[540144790] transaction","detail":"{read_only:false; response_revision:24667; number_of_response:1; }","duration":"323.377939ms","start":"2026-04-07T00:55:03.825588Z","end":"2026-04-07T00:55:04.148966Z","steps":["trace[540144790] 'process raft request' (duration: 182.622946ms)","trace[540144790] 'compare' (duration: 140.23792ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:04.149083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:03.825561Z","time spent":"323.486173ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:04.43634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.024847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:04.436942Z","caller":"traceutil/trace.go:171","msg":"trace[1537231441] transaction","detail":"{read_only:false; response_revision:24668; number_of_response:1; }","duration":"262.449023ms","start":"2026-04-07T00:55:04.174469Z","end":"2026-04-07T00:55:04.436918Z","steps":["trace[1537231441] 'process raft request' (duration: 129.751237ms)","trace[1537231441] 'compare' (duration: 131.631947ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:04.437079Z","caller":"traceutil/trace.go:171","msg":"trace[1196599121] linearizableReadLoop","detail":"{readStateIndex:25480; appliedIndex:25479; }","duration":"180.264492ms","start":"2026-04-07T00:55:04.256803Z","end":"2026-04-07T00:55:04.437068Z","steps":["trace[1196599121] 'read index received' (duration: 47.452983ms)","trace[1196599121] 'applied index is now lower than readState.Index' (duration: 132.809979ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:04.437182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.364985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:04.437253Z","caller":"traceutil/trace.go:171","msg":"trace[1033959296] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:24670; }","duration":"180.510229ms","start":"2026-04-07T00:55:04.256731Z","end":"2026-04-07T00:55:04.437241Z","steps":["trace[1033959296] 'agreement among raft nodes before linearized reading' (duration: 180.375385ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:04.437514Z","caller":"traceutil/trace.go:171","msg":"trace[873384694] transaction","detail":"{read_only:false; response_revision:24669; number_of_response:1; }","duration":"121.838093ms","start":"2026-04-07T00:55:04.315666Z","end":"2026-04-07T00:55:04.437504Z","steps":["trace[873384694] 'process raft request' (duration: 120.808904ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:04.761657Z","caller":"traceutil/trace.go:171","msg":"trace[2011006653] linearizableReadLoop","detail":"{readStateIndex:25483; appliedIndex:25482; }","duration":"126.480198ms","start":"2026-04-07T00:55:04.635114Z","end":"2026-04-07T00:55:04.761594Z","steps":["trace[2011006653] 'read index received' (duration: 66.918869ms)","trace[2011006653] 'applied index is now lower than readState.Index' (duration: 59.557809ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:04.761831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.638133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:04.762455Z","caller":"traceutil/trace.go:171","msg":"trace[2038512860] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24671; }","duration":"127.364772ms","start":"2026-04-07T00:55:04.635043Z","end":"2026-04-07T00:55:04.762408Z","steps":["trace[2038512860] 'agreement among raft nodes before linearized reading' (duration: 126.625871ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:04.762625Z","caller":"traceutil/trace.go:171","msg":"trace[1434032074] transaction","detail":"{read_only:false; response_revision:24671; number_of_response:1; }","duration":"308.636791ms","start":"2026-04-07T00:55:04.453877Z","end":"2026-04-07T00:55:04.762513Z","steps":["trace[1434032074] 'process raft request' (duration: 248.199267ms)","trace[1434032074] 'compare' (duration: 58.521912ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:04.762882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:04.453857Z","time spent":"308.906187ms","remote":"127.0.0.1:47876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1873,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:05.0397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.190452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:05.040099Z","caller":"traceutil/trace.go:171","msg":"trace[192204694] transaction","detail":"{read_only:false; response_revision:24672; number_of_response:1; }","duration":"286.570604ms","start":"2026-04-07T00:55:04.753488Z","end":"2026-04-07T00:55:05.040059Z","steps":["trace[192204694] 'process raft request' (duration: 146.899369ms)","trace[192204694] 'compare' (duration: 139.052939ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:05.040079Z","caller":"traceutil/trace.go:171","msg":"trace[279384302] linearizableReadLoop","detail":"{readStateIndex:25484; appliedIndex:25483; }","duration":"276.074081ms","start":"2026-04-07T00:55:04.763981Z","end":"2026-04-07T00:55:05.040055Z","steps":["trace[279384302] 'read index received' (duration: 136.374125ms)","trace[279384302] 'applied index is now lower than readState.Index' (duration: 139.698036ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:05.040328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.320278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:05.040455Z","caller":"traceutil/trace.go:171","msg":"trace[96225358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24672; }","duration":"276.542374ms","start":"2026-04-07T00:55:04.763889Z","end":"2026-04-07T00:55:05.040431Z","steps":["trace[96225358] 'agreement among raft nodes before linearized reading' (duration: 276.295467ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:05.04291Z","caller":"traceutil/trace.go:171","msg":"trace[892187740] transaction","detail":"{read_only:false; response_revision:24673; number_of_response:1; }","duration":"271.257131ms","start":"2026-04-07T00:55:04.771634Z","end":"2026-04-07T00:55:05.042891Z","steps":["trace[892187740] 'process raft request' (duration: 271.135547ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:05.250088Z","caller":"traceutil/trace.go:171","msg":"trace[238463782] transaction","detail":"{read_only:false; response_revision:24676; number_of_response:1; }","duration":"180.244332ms","start":"2026-04-07T00:55:05.069794Z","end":"2026-04-07T00:55:05.250039Z","steps":["trace[238463782] 'process raft request' (duration: 179.993085ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:05.250274Z","caller":"traceutil/trace.go:171","msg":"trace[486285806] transaction","detail":"{read_only:false; response_revision:24679; number_of_response:1; }","duration":"172.018729ms","start":"2026-04-07T00:55:05.078243Z","end":"2026-04-07T00:55:05.250261Z","steps":["trace[486285806] 'process raft request' (duration: 171.819194ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:05.250122Z","caller":"traceutil/trace.go:171","msg":"trace[376122698] transaction","detail":"{read_only:false; response_revision:24677; number_of_response:1; }","duration":"180.135328ms","start":"2026-04-07T00:55:05.069949Z","end":"2026-04-07T00:55:05.250084Z","steps":["trace[376122698] 'process raft request' (duration: 179.971273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:05.250532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.634371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130957"} {"level":"info","ts":"2026-04-07T00:55:05.250182Z","caller":"traceutil/trace.go:171","msg":"trace[2121436146] transaction","detail":"{read_only:false; response_revision:24678; number_of_response:1; }","duration":"179.068799ms","start":"2026-04-07T00:55:05.071085Z","end":"2026-04-07T00:55:05.250154Z","steps":["trace[2121436146] 'process raft request' (duration: 178.891134ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:05.250575Z","caller":"traceutil/trace.go:171","msg":"trace[672183598] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:24679; }","duration":"180.746165ms","start":"2026-04-07T00:55:05.069821Z","end":"2026-04-07T00:55:05.250567Z","steps":["trace[672183598] 'agreement among raft nodes before linearized reading' (duration: 180.468267ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:05.250265Z","caller":"traceutil/trace.go:171","msg":"trace[634795681] linearizableReadLoop","detail":"{readStateIndex:25488; appliedIndex:25486; }","duration":"180.377914ms","start":"2026-04-07T00:55:05.06987Z","end":"2026-04-07T00:55:05.250248Z","steps":["trace[634795681] 'read index received' (duration: 91.904663ms)","trace[634795681] 'applied index is now lower than readState.Index' (duration: 88.470951ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:05.251007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.870738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:55:05.251064Z","caller":"traceutil/trace.go:171","msg":"trace[1642167452] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:24679; }","duration":"134.001711ms","start":"2026-04-07T00:55:05.11705Z","end":"2026-04-07T00:55:05.251051Z","steps":["trace[1642167452] 'agreement among raft nodes before linearized reading' (duration: 133.792796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:05.250994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.351044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-07T00:55:05.25117Z","caller":"traceutil/trace.go:171","msg":"trace[247300499] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24679; }","duration":"163.6041ms","start":"2026-04-07T00:55:05.087558Z","end":"2026-04-07T00:55:05.251162Z","steps":["trace[247300499] 'agreement among raft nodes before linearized reading' (duration: 163.265931ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:05.396599Z","caller":"traceutil/trace.go:171","msg":"trace[335157567] transaction","detail":"{read_only:false; response_revision:24680; number_of_response:1; }","duration":"142.852691ms","start":"2026-04-07T00:55:05.253702Z","end":"2026-04-07T00:55:05.396555Z","steps":["trace[335157567] 'process raft request' (duration: 142.45704ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:05.405695Z","caller":"traceutil/trace.go:171","msg":"trace[2004369918] transaction","detail":"{read_only:false; response_revision:24681; number_of_response:1; }","duration":"147.859845ms","start":"2026-04-07T00:55:05.257806Z","end":"2026-04-07T00:55:05.405666Z","steps":["trace[2004369918] 'process raft request' (duration: 147.708421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:05.667517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.033954ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:05.668032Z","caller":"traceutil/trace.go:171","msg":"trace[1392100053] transaction","detail":"{read_only:false; response_revision:24684; number_of_response:1; }","duration":"256.451231ms","start":"2026-04-07T00:55:05.41153Z","end":"2026-04-07T00:55:05.667981Z","steps":["trace[1392100053] 'process raft request' (duration: 145.860923ms)","trace[1392100053] 'compare' (duration: 109.863408ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:05.668129Z","caller":"traceutil/trace.go:171","msg":"trace[906447701] transaction","detail":"{read_only:false; response_revision:24685; number_of_response:1; }","duration":"255.642938ms","start":"2026-04-07T00:55:05.412431Z","end":"2026-04-07T00:55:05.668074Z","steps":["trace[906447701] 'process raft request' (duration: 255.195006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:05.79386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.127338ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-04-07T00:55:05.794412Z","caller":"traceutil/trace.go:171","msg":"trace[959205364] transaction","detail":"{read_only:false; response_revision:24686; number_of_response:1; }","duration":"381.205592ms","start":"2026-04-07T00:55:05.413181Z","end":"2026-04-07T00:55:05.794387Z","steps":["trace[959205364] 'process raft request' (duration: 380.794371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:05.794562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:05.413152Z","time spent":"381.330295ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":718,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:55:05.967768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.17564ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:05.968954Z","caller":"traceutil/trace.go:171","msg":"trace[1964030304] transaction","detail":"{read_only:false; response_revision:24688; number_of_response:1; }","duration":"295.173556ms","start":"2026-04-07T00:55:05.673751Z","end":"2026-04-07T00:55:05.968925Z","steps":["trace[1964030304] 'process raft request' (duration: 295.030122ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:05.969294Z","caller":"traceutil/trace.go:171","msg":"trace[1005519770] transaction","detail":"{read_only:false; response_revision:24687; number_of_response:1; }","duration":"555.708228ms","start":"2026-04-07T00:55:05.413565Z","end":"2026-04-07T00:55:05.969273Z","steps":["trace[1005519770] 'process raft request' (duration: 380.811001ms)","trace[1005519770] 'compare' (duration: 172.759059ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:05.969377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:05.413551Z","time spent":"555.78395ms","remote":"127.0.0.1:49736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":800,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:55:05.969569Z","caller":"traceutil/trace.go:171","msg":"trace[116468989] linearizableReadLoop","detail":"{readStateIndex:25500; appliedIndex:25495; }","duration":"381.184431ms","start":"2026-04-07T00:55:05.588351Z","end":"2026-04-07T00:55:05.969535Z","steps":["trace[116468989] 'read index received' (duration: 56.732µs)","trace[116468989] 'applied index is now lower than readState.Index' (duration: 381.124679ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:05.969775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.389386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:05.969817Z","caller":"traceutil/trace.go:171","msg":"trace[1101344714] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:24688; }","duration":"381.53872ms","start":"2026-04-07T00:55:05.588266Z","end":"2026-04-07T00:55:05.969804Z","steps":["trace[1101344714] 'agreement among raft nodes before linearized reading' (duration: 381.416537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:05.969858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:05.58823Z","time spent":"381.613362ms","remote":"127.0.0.1:57678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:55:06.225041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.954841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:06.22532Z","caller":"traceutil/trace.go:171","msg":"trace[595721236] transaction","detail":"{read_only:false; response_revision:24689; number_of_response:1; }","duration":"398.92949ms","start":"2026-04-07T00:55:05.826355Z","end":"2026-04-07T00:55:06.225284Z","steps":["trace[595721236] 'process raft request' (duration: 281.587739ms)","trace[595721236] 'compare' (duration: 116.637112ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:06.225497Z","caller":"traceutil/trace.go:171","msg":"trace[1189663093] linearizableReadLoop","detail":"{readStateIndex:25504; appliedIndex:25501; }","duration":"255.824913ms","start":"2026-04-07T00:55:05.969644Z","end":"2026-04-07T00:55:06.225469Z","steps":["trace[1189663093] 'read index received' (duration: 138.322898ms)","trace[1189663093] 'applied index is now lower than readState.Index' (duration: 117.499665ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:06.225534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:05.826325Z","time spent":"399.091875ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:06.225666Z","caller":"traceutil/trace.go:171","msg":"trace[527045952] transaction","detail":"{read_only:false; response_revision:24690; number_of_response:1; }","duration":"398.700124ms","start":"2026-04-07T00:55:05.826948Z","end":"2026-04-07T00:55:06.225648Z","steps":["trace[527045952] 'process raft request' (duration: 398.250422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:06.225784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:05.826914Z","time spent":"398.810667ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:06.225884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.884994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T00:55:06.225932Z","caller":"traceutil/trace.go:171","msg":"trace[1120391756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24692; }","duration":"460.992107ms","start":"2026-04-07T00:55:05.764929Z","end":"2026-04-07T00:55:06.225921Z","steps":["trace[1120391756] 'agreement among raft nodes before linearized reading' (duration: 460.760751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:06.225968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:05.764902Z","time spent":"461.059609ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-07T00:55:06.226084Z","caller":"traceutil/trace.go:171","msg":"trace[377691135] transaction","detail":"{read_only:false; response_revision:24691; number_of_response:1; }","duration":"399.122115ms","start":"2026-04-07T00:55:05.82695Z","end":"2026-04-07T00:55:06.226072Z","steps":["trace[377691135] 'process raft request' (duration: 398.336264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:06.226161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:05.826929Z","time spent":"399.184946ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:06.226167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.084661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-07T00:55:06.226225Z","caller":"traceutil/trace.go:171","msg":"trace[1574189879] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24692; }","duration":"467.159494ms","start":"2026-04-07T00:55:05.759043Z","end":"2026-04-07T00:55:06.226203Z","steps":["trace[1574189879] 'agreement among raft nodes before linearized reading' (duration: 466.939428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:06.226473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:05.759033Z","time spent":"467.427191ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T00:55:06.226691Z","caller":"traceutil/trace.go:171","msg":"trace[141869843] transaction","detail":"{read_only:false; response_revision:24692; number_of_response:1; }","duration":"250.156169ms","start":"2026-04-07T00:55:05.976522Z","end":"2026-04-07T00:55:06.226679Z","steps":["trace[141869843] 'process raft request' (duration: 248.844274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:06.226804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.265794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1125"} {"level":"info","ts":"2026-04-07T00:55:06.226877Z","caller":"traceutil/trace.go:171","msg":"trace[208226045] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:24692; }","duration":"253.394008ms","start":"2026-04-07T00:55:05.973462Z","end":"2026-04-07T00:55:06.226856Z","steps":["trace[208226045] 'agreement among raft nodes before linearized reading' (duration: 253.187402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:06.226883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.690774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:55:06.226926Z","caller":"traceutil/trace.go:171","msg":"trace[1428226310] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:24692; }","duration":"459.747265ms","start":"2026-04-07T00:55:05.767165Z","end":"2026-04-07T00:55:06.226912Z","steps":["trace[1428226310] 'agreement among raft nodes before linearized reading' (duration: 459.669473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:06.226958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:05.767158Z","time spent":"459.791277ms","remote":"127.0.0.1:50140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":6,"response size":32,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:55:06.22706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.702726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-07T00:55:06.227122Z","caller":"traceutil/trace.go:171","msg":"trace[895075302] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24692; }","duration":"206.788269ms","start":"2026-04-07T00:55:06.020316Z","end":"2026-04-07T00:55:06.227105Z","steps":["trace[895075302] 'agreement among raft nodes before linearized reading' (duration: 206.600914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:06.227163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.896699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:55:06.227195Z","caller":"traceutil/trace.go:171","msg":"trace[1882757356] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:24692; }","duration":"227.966691ms","start":"2026-04-07T00:55:05.999219Z","end":"2026-04-07T00:55:06.227186Z","steps":["trace[1882757356] 'agreement among raft nodes before linearized reading' (duration: 227.873958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:06.227334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.733558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1125"} {"level":"info","ts":"2026-04-07T00:55:06.227369Z","caller":"traceutil/trace.go:171","msg":"trace[124097158] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:24692; }","duration":"253.81744ms","start":"2026-04-07T00:55:05.973541Z","end":"2026-04-07T00:55:06.227358Z","steps":["trace[124097158] 'agreement among raft nodes before linearized reading' (duration: 253.704887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:06.227452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.77707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-04-07T00:55:06.227485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.635294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:06.22751Z","caller":"traceutil/trace.go:171","msg":"trace[89592028] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24692; }","duration":"471.875402ms","start":"2026-04-07T00:55:05.755616Z","end":"2026-04-07T00:55:06.227491Z","steps":["trace[89592028] 'agreement among raft nodes before linearized reading' (duration: 471.735808ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:06.227514Z","caller":"traceutil/trace.go:171","msg":"trace[730772489] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:24692; }","duration":"250.665654ms","start":"2026-04-07T00:55:05.976838Z","end":"2026-04-07T00:55:06.227504Z","steps":["trace[730772489] 'agreement among raft nodes before linearized reading' (duration: 250.608963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:06.227555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:05.755602Z","time spent":"471.940594ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T00:55:06.227722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.523775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:06.227814Z","caller":"traceutil/trace.go:171","msg":"trace[535773479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24692; }","duration":"591.610677ms","start":"2026-04-07T00:55:05.636136Z","end":"2026-04-07T00:55:06.227747Z","steps":["trace[535773479] 'agreement among raft nodes before linearized reading' (duration: 591.556145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:06.227847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:05.63611Z","time spent":"591.72894ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T00:55:06.432132Z","caller":"traceutil/trace.go:171","msg":"trace[1451543969] linearizableReadLoop","detail":"{readStateIndex:25512; appliedIndex:25510; }","duration":"134.398232ms","start":"2026-04-07T00:55:06.297708Z","end":"2026-04-07T00:55:06.432107Z","steps":["trace[1451543969] 'read index received' (duration: 15.963161ms)","trace[1451543969] 'applied index is now lower than readState.Index' (duration: 118.434261ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:06.432265Z","caller":"traceutil/trace.go:171","msg":"trace[2095634549] transaction","detail":"{read_only:false; response_revision:24699; number_of_response:1; }","duration":"148.263466ms","start":"2026-04-07T00:55:06.283944Z","end":"2026-04-07T00:55:06.432207Z","steps":["trace[2095634549] 'process raft request' (duration: 147.995919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:06.432465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.742891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1132"} {"level":"info","ts":"2026-04-07T00:55:06.432515Z","caller":"traceutil/trace.go:171","msg":"trace[995708460] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:24699; }","duration":"134.861504ms","start":"2026-04-07T00:55:06.297642Z","end":"2026-04-07T00:55:06.432504Z","steps":["trace[995708460] 'agreement among raft nodes before linearized reading' (duration: 134.604057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:06.816499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.16668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:06.816643Z","caller":"traceutil/trace.go:171","msg":"trace[2092762448] linearizableReadLoop","detail":"{readStateIndex:25518; appliedIndex:25517; }","duration":"195.134793ms","start":"2026-04-07T00:55:06.621484Z","end":"2026-04-07T00:55:06.816619Z","steps":["trace[2092762448] 'read index received' (duration: 38.670535ms)","trace[2092762448] 'applied index is now lower than readState.Index' (duration: 156.460878ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:06.81689Z","caller":"traceutil/trace.go:171","msg":"trace[1743941241] transaction","detail":"{read_only:false; response_revision:24705; number_of_response:1; }","duration":"196.13342ms","start":"2026-04-07T00:55:06.620739Z","end":"2026-04-07T00:55:06.816873Z","steps":["trace[1743941241] 'process raft request' (duration: 39.502057ms)","trace[1743941241] 'compare' (duration: 155.800751ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:06.817505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.657863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:709"} {"level":"info","ts":"2026-04-07T00:55:06.817598Z","caller":"traceutil/trace.go:171","msg":"trace[1734860569] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:24705; }","duration":"191.008702ms","start":"2026-04-07T00:55:06.626567Z","end":"2026-04-07T00:55:06.817576Z","steps":["trace[1734860569] 'agreement among raft nodes before linearized reading' (duration: 190.493148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:06.817703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.202411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:basic-user\" ","response":"range_response_count:1 size:679"} {"level":"info","ts":"2026-04-07T00:55:06.817769Z","caller":"traceutil/trace.go:171","msg":"trace[875014046] range","detail":"{range_begin:/registry/clusterroles/system:basic-user; range_end:; response_count:1; response_revision:24705; }","duration":"196.314455ms","start":"2026-04-07T00:55:06.621443Z","end":"2026-04-07T00:55:06.817757Z","steps":["trace[875014046] 'agreement among raft nodes before linearized reading' (duration: 196.105289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:06.817712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.717726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3566"} {"level":"info","ts":"2026-04-07T00:55:06.817951Z","caller":"traceutil/trace.go:171","msg":"trace[1394803312] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:24705; }","duration":"177.008154ms","start":"2026-04-07T00:55:06.640933Z","end":"2026-04-07T00:55:06.817941Z","steps":["trace[1394803312] 'agreement among raft nodes before linearized reading' (duration: 176.613173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:06.817682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.359457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:06.81805Z","caller":"traceutil/trace.go:171","msg":"trace[1527063396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24705; }","duration":"179.80194ms","start":"2026-04-07T00:55:06.638239Z","end":"2026-04-07T00:55:06.818041Z","steps":["trace[1527063396] 'agreement among raft nodes before linearized reading' (duration: 179.319566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:09.740849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.593519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:09.740984Z","caller":"traceutil/trace.go:171","msg":"trace[1814998781] linearizableReadLoop","detail":"{readStateIndex:25583; appliedIndex:25582; }","duration":"104.105803ms","start":"2026-04-07T00:55:09.636859Z","end":"2026-04-07T00:55:09.740965Z","steps":["trace[1814998781] 'read index received' (duration: 39.761µs)","trace[1814998781] 'applied index is now lower than readState.Index' (duration: 104.064582ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:09.741074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.217656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:09.741124Z","caller":"traceutil/trace.go:171","msg":"trace[1607723314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24770; }","duration":"104.316259ms","start":"2026-04-07T00:55:09.636794Z","end":"2026-04-07T00:55:09.74111Z","steps":["trace[1607723314] 'agreement among raft nodes before linearized reading' (duration: 104.239487ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:09.741348Z","caller":"traceutil/trace.go:171","msg":"trace[1988095484] transaction","detail":"{read_only:false; response_revision:24770; number_of_response:1; }","duration":"285.755962ms","start":"2026-04-07T00:55:09.455578Z","end":"2026-04-07T00:55:09.741334Z","steps":["trace[1988095484] 'process raft request' (duration: 117.610728ms)","trace[1988095484] 'compare' (duration: 167.284281ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:12.762372Z","caller":"traceutil/trace.go:171","msg":"trace[1962003307] linearizableReadLoop","detail":"{readStateIndex:25631; appliedIndex:25630; }","duration":"152.116941ms","start":"2026-04-07T00:55:12.610221Z","end":"2026-04-07T00:55:12.762338Z","steps":["trace[1962003307] 'read index received' (duration: 94.767861ms)","trace[1962003307] 'applied index is now lower than readState.Index' (duration: 57.34768ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:12.762516Z","caller":"traceutil/trace.go:171","msg":"trace[1442341638] transaction","detail":"{read_only:false; response_revision:24817; number_of_response:1; }","duration":"208.543296ms","start":"2026-04-07T00:55:12.553926Z","end":"2026-04-07T00:55:12.762469Z","steps":["trace[1442341638] 'process raft request' (duration: 151.188646ms)","trace[1442341638] 'compare' (duration: 57.083442ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:12.762606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.352238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:12.762672Z","caller":"traceutil/trace.go:171","msg":"trace[851758] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24817; }","duration":"152.494671ms","start":"2026-04-07T00:55:12.61016Z","end":"2026-04-07T00:55:12.762654Z","steps":["trace[851758] 'agreement among raft nodes before linearized reading' (duration: 152.319166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:12.763094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.380615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:12.763134Z","caller":"traceutil/trace.go:171","msg":"trace[805541400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24817; }","duration":"126.497458ms","start":"2026-04-07T00:55:12.636627Z","end":"2026-04-07T00:55:12.763125Z","steps":["trace[805541400] 'agreement among raft nodes before linearized reading' (duration: 125.878332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:41.939389Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095031741542481,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T00:55:42.206864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.029738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:55:42.206985Z","caller":"traceutil/trace.go:171","msg":"trace[1654527149] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:25057; }","duration":"935.199063ms","start":"2026-04-07T00:55:41.271762Z","end":"2026-04-07T00:55:42.206961Z","steps":["trace[1654527149] 'count revisions from in-memory index tree' (duration: 934.958986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:42.207037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:41.271725Z","time spent":"935.298015ms","remote":"127.0.0.1:49918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":32,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:55:42.206893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.892699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:42.207205Z","caller":"traceutil/trace.go:171","msg":"trace[1156435893] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:25057; }","duration":"890.239098ms","start":"2026-04-07T00:55:41.316956Z","end":"2026-04-07T00:55:42.207195Z","steps":["trace[1156435893] 'count revisions from in-memory index tree' (duration: 889.835866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:42.20725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:41.316922Z","time spent":"890.30293ms","remote":"127.0.0.1:55880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:55:42.20803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.55172ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:42.208153Z","caller":"traceutil/trace.go:171","msg":"trace[219617215] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25057; }","duration":"445.681264ms","start":"2026-04-07T00:55:41.76245Z","end":"2026-04-07T00:55:42.208131Z","steps":["trace[219617215] 'range keys from in-memory index tree' (duration: 445.53308ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:42.209259Z","caller":"traceutil/trace.go:171","msg":"trace[679145523] transaction","detail":"{read_only:false; response_revision:25058; number_of_response:1; }","duration":"799.830055ms","start":"2026-04-07T00:55:41.40938Z","end":"2026-04-07T00:55:42.20921Z","steps":["trace[679145523] 'process raft request' (duration: 769.419583ms)","trace[679145523] 'compare' (duration: 28.772797ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:42.20942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:41.409353Z","time spent":"799.984129ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:42.526531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.944807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:42.526782Z","caller":"traceutil/trace.go:171","msg":"trace[1084698207] linearizableReadLoop","detail":"{readStateIndex:25881; appliedIndex:25878; }","duration":"1.088210147s","start":"2026-04-07T00:55:41.438558Z","end":"2026-04-07T00:55:42.526768Z","steps":["trace[1084698207] 'read index received' (duration: 740.282085ms)","trace[1084698207] 'applied index is now lower than readState.Index' (duration: 347.926992ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:42.526886Z","caller":"traceutil/trace.go:171","msg":"trace[1698290225] transaction","detail":"{read_only:false; response_revision:25060; number_of_response:1; }","duration":"1.041846245s","start":"2026-04-07T00:55:41.485028Z","end":"2026-04-07T00:55:42.526875Z","steps":["trace[1698290225] 'process raft request' (duration: 1.041590368s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:42.526942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:41.485017Z","time spent":"1.041898136s","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:42.526766Z","caller":"traceutil/trace.go:171","msg":"trace[846549538] transaction","detail":"{read_only:false; response_revision:25059; number_of_response:1; }","duration":"1.093852659s","start":"2026-04-07T00:55:41.432885Z","end":"2026-04-07T00:55:42.526738Z","steps":["trace[846549538] 'process raft request' (duration: 891.599464ms)","trace[846549538] 'compare' (duration: 201.572477ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:42.527126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:41.432871Z","time spent":"1.09423147s","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:42.527137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088561596s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:42.52717Z","caller":"traceutil/trace.go:171","msg":"trace[2064369825] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:25063; }","duration":"1.088637949s","start":"2026-04-07T00:55:41.438523Z","end":"2026-04-07T00:55:42.527161Z","steps":["trace[2064369825] 'agreement among raft nodes before linearized reading' (duration: 1.088563887s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:42.527197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:41.438514Z","time spent":"1.08867824s","remote":"127.0.0.1:49770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":30,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:55:42.527307Z","caller":"traceutil/trace.go:171","msg":"trace[307041319] transaction","detail":"{read_only:false; response_revision:25062; number_of_response:1; }","duration":"393.622418ms","start":"2026-04-07T00:55:42.133582Z","end":"2026-04-07T00:55:42.527205Z","steps":["trace[307041319] 'process raft request' (duration: 393.120353ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:42.52737Z","caller":"traceutil/trace.go:171","msg":"trace[1414635514] transaction","detail":"{read_only:false; response_revision:25063; number_of_response:1; }","duration":"356.239458ms","start":"2026-04-07T00:55:42.171122Z","end":"2026-04-07T00:55:42.527362Z","steps":["trace[1414635514] 'process raft request' (duration: 355.6096ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:42.527336Z","caller":"traceutil/trace.go:171","msg":"trace[1535700081] transaction","detail":"{read_only:false; response_revision:25061; number_of_response:1; }","duration":"581.022312ms","start":"2026-04-07T00:55:41.946244Z","end":"2026-04-07T00:55:42.527266Z","steps":["trace[1535700081] 'process raft request' (duration: 580.415745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:42.527458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:42.171093Z","time spent":"356.3148ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:42.527547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:40.876763Z","time spent":"1.650649847s","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:42.527577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:41.946227Z","time spent":"581.222717ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1016,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:55:42.530507Z","caller":"traceutil/trace.go:171","msg":"trace[648608393] transaction","detail":"{read_only:false; response_revision:25064; number_of_response:1; }","duration":"154.719862ms","start":"2026-04-07T00:55:42.375774Z","end":"2026-04-07T00:55:42.530494Z","steps":["trace[648608393] 'process raft request' (duration: 154.61823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:42.857879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.351109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:42.858098Z","caller":"traceutil/trace.go:171","msg":"trace[1586546987] transaction","detail":"{read_only:false; response_revision:25067; number_of_response:1; }","duration":"326.254467ms","start":"2026-04-07T00:55:42.531829Z","end":"2026-04-07T00:55:42.858083Z","steps":["trace[1586546987] 'process raft request' (duration: 326.203186ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:42.858132Z","caller":"traceutil/trace.go:171","msg":"trace[92056512] transaction","detail":"{read_only:false; response_revision:25065; number_of_response:1; }","duration":"482.114819ms","start":"2026-04-07T00:55:42.375998Z","end":"2026-04-07T00:55:42.858113Z","steps":["trace[92056512] 'process raft request' (duration: 262.437382ms)","trace[92056512] 'compare' (duration: 219.135371ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:42.858188Z","caller":"traceutil/trace.go:171","msg":"trace[1954475047] transaction","detail":"{read_only:false; response_revision:25066; number_of_response:1; }","duration":"480.27609ms","start":"2026-04-07T00:55:42.377905Z","end":"2026-04-07T00:55:42.858181Z","steps":["trace[1954475047] 'process raft request' (duration: 480.067394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:42.858233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:42.377891Z","time spent":"480.32063ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:42.858348Z","caller":"traceutil/trace.go:171","msg":"trace[306152647] linearizableReadLoop","detail":"{readStateIndex:25887; appliedIndex:25884; }","duration":"331.52391ms","start":"2026-04-07T00:55:42.526816Z","end":"2026-04-07T00:55:42.85834Z","steps":["trace[306152647] 'read index received' (duration: 3.609958ms)","trace[306152647] 'applied index is now lower than readState.Index' (duration: 327.913122ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:42.858486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.484952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:42.858514Z","caller":"traceutil/trace.go:171","msg":"trace[188306898] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25067; }","duration":"343.537324ms","start":"2026-04-07T00:55:42.514968Z","end":"2026-04-07T00:55:42.858506Z","steps":["trace[188306898] 'agreement among raft nodes before linearized reading' (duration: 343.470342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:42.858537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:42.514956Z","time spent":"343.575555ms","remote":"127.0.0.1:56270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:55:42.858798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.001031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-07T00:55:42.85883Z","caller":"traceutil/trace.go:171","msg":"trace[1181024807] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25067; }","duration":"359.056683ms","start":"2026-04-07T00:55:42.499766Z","end":"2026-04-07T00:55:42.858823Z","steps":["trace[1181024807] 'agreement among raft nodes before linearized reading' (duration: 358.93705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:42.858852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:42.499759Z","time spent":"359.088813ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T00:55:42.858979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.466157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-07T00:55:42.859005Z","caller":"traceutil/trace.go:171","msg":"trace[37495466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25067; }","duration":"365.540018ms","start":"2026-04-07T00:55:42.493459Z","end":"2026-04-07T00:55:42.858999Z","steps":["trace[37495466] 'agreement among raft nodes before linearized reading' (duration: 365.474137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:42.859022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:42.493436Z","time spent":"365.58249ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T00:55:42.859137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.961063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-07T00:55:42.859163Z","caller":"traceutil/trace.go:171","msg":"trace[2107812885] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25067; }","duration":"367.997365ms","start":"2026-04-07T00:55:42.49116Z","end":"2026-04-07T00:55:42.859157Z","steps":["trace[2107812885] 'agreement among raft nodes before linearized reading' (duration: 367.929493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:42.859182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:42.491154Z","time spent":"368.022585ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T00:55:42.859308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.359144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T00:55:42.859332Z","caller":"traceutil/trace.go:171","msg":"trace[1092307045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25067; }","duration":"368.442546ms","start":"2026-04-07T00:55:42.490885Z","end":"2026-04-07T00:55:42.859327Z","steps":["trace[1092307045] 'agreement among raft nodes before linearized reading' (duration: 368.383055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:42.859348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:42.490857Z","time spent":"368.487608ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T00:55:42.859472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.194668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-07T00:55:42.859506Z","caller":"traceutil/trace.go:171","msg":"trace[2131855435] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25067; }","duration":"389.229749ms","start":"2026-04-07T00:55:42.470269Z","end":"2026-04-07T00:55:42.859499Z","steps":["trace[2131855435] 'agreement among raft nodes before linearized reading' (duration: 389.153267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:42.859531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:42.470238Z","time spent":"389.285109ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-07T00:55:42.859686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.429668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-07T00:55:42.859717Z","caller":"traceutil/trace.go:171","msg":"trace[132781128] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25067; }","duration":"569.485529ms","start":"2026-04-07T00:55:42.290224Z","end":"2026-04-07T00:55:42.859709Z","steps":["trace[132781128] 'agreement among raft nodes before linearized reading' (duration: 569.402617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:42.859739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:42.290204Z","time spent":"569.53064ms","remote":"127.0.0.1:49824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-07T00:55:42.859947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.582875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-04-07T00:55:42.859984Z","caller":"traceutil/trace.go:171","msg":"trace[1774325715] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:25067; }","duration":"733.671016ms","start":"2026-04-07T00:55:42.126302Z","end":"2026-04-07T00:55:42.859973Z","steps":["trace[1774325715] 'agreement among raft nodes before linearized reading' (duration: 733.550463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:42.860009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:42.12629Z","time spent":"733.712858ms","remote":"127.0.0.1:49800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1890,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" "} {"level":"warn","ts":"2026-04-07T00:55:42.860148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.706449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:42.860179Z","caller":"traceutil/trace.go:171","msg":"trace[1591073260] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:25067; }","duration":"813.778151ms","start":"2026-04-07T00:55:42.046394Z","end":"2026-04-07T00:55:42.860172Z","steps":["trace[1591073260] 'agreement among raft nodes before linearized reading' (duration: 813.724089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:42.860198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:42.046379Z","time spent":"813.814291ms","remote":"127.0.0.1:55962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:55:42.86019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:42.375973Z","time spent":"482.213911ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:42.858157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:42.531805Z","time spent":"326.328369ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1016,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T00:55:42.860709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.620919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-07T00:55:42.860802Z","caller":"traceutil/trace.go:171","msg":"trace[2042808665] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25067; }","duration":"927.875195ms","start":"2026-04-07T00:55:41.932906Z","end":"2026-04-07T00:55:42.860781Z","steps":["trace[2042808665] 'agreement among raft nodes before linearized reading' (duration: 927.467103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:42.86088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201344445s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:42.860907Z","caller":"traceutil/trace.go:171","msg":"trace[133734895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25067; }","duration":"1.201381796s","start":"2026-04-07T00:55:41.659518Z","end":"2026-04-07T00:55:42.860899Z","steps":["trace[133734895] 'agreement among raft nodes before linearized reading' (duration: 1.201337095s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:42.860927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:41.659512Z","time spent":"1.201410217s","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T00:55:42.861025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:41.932886Z","time spent":"928.127012ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T00:55:42.861012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.225855128s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:42.861118Z","caller":"traceutil/trace.go:171","msg":"trace[965408074] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25067; }","duration":"1.226039902s","start":"2026-04-07T00:55:41.635051Z","end":"2026-04-07T00:55:42.86109Z","steps":["trace[965408074] 'agreement among raft nodes before linearized reading' (duration: 1.225790156s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:42.861123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243306889s","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-04-07T00:55:42.861184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:41.635044Z","time spent":"1.226129104s","remote":"127.0.0.1:56200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":30,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:55:42.861185Z","caller":"traceutil/trace.go:171","msg":"trace[613263909] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25067; }","duration":"1.243393081s","start":"2026-04-07T00:55:41.617771Z","end":"2026-04-07T00:55:42.861164Z","steps":["trace[613263909] 'agreement among raft nodes before linearized reading' (duration: 1.243186516s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:42.861271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:41.617751Z","time spent":"1.243511515s","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T00:55:43.059342Z","caller":"traceutil/trace.go:171","msg":"trace[1748721454] transaction","detail":"{read_only:false; response_revision:25068; number_of_response:1; }","duration":"189.185152ms","start":"2026-04-07T00:55:42.870122Z","end":"2026-04-07T00:55:43.059307Z","steps":["trace[1748721454] 'process raft request' (duration: 129.226482ms)","trace[1748721454] 'compare' (duration: 59.653232ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:43.32902Z","caller":"traceutil/trace.go:171","msg":"trace[993449604] transaction","detail":"{read_only:false; response_revision:25069; number_of_response:1; }","duration":"458.842569ms","start":"2026-04-07T00:55:42.870138Z","end":"2026-04-07T00:55:43.32898Z","steps":["trace[993449604] 'process raft request' (duration: 390.79876ms)","trace[993449604] 'compare' (duration: 67.479234ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:43.329353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:42.870124Z","time spent":"458.990254ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:43.329476Z","caller":"traceutil/trace.go:171","msg":"trace[1962882779] transaction","detail":"{read_only:false; response_revision:25070; number_of_response:1; }","duration":"459.172658ms","start":"2026-04-07T00:55:42.870213Z","end":"2026-04-07T00:55:43.329386Z","steps":["trace[1962882779] 'process raft request' (duration: 458.657524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:43.329745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:42.870205Z","time spent":"459.416225ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:43.329913Z","caller":"traceutil/trace.go:171","msg":"trace[1397667774] transaction","detail":"{read_only:false; response_revision:25071; number_of_response:1; }","duration":"459.671062ms","start":"2026-04-07T00:55:42.870232Z","end":"2026-04-07T00:55:43.329903Z","steps":["trace[1397667774] 'process raft request' (duration: 459.010094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:43.329981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:42.870204Z","time spent":"459.740604ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:43.330199Z","caller":"traceutil/trace.go:171","msg":"trace[482685568] transaction","detail":"{read_only:false; response_revision:25072; number_of_response:1; }","duration":"459.516728ms","start":"2026-04-07T00:55:42.870674Z","end":"2026-04-07T00:55:43.330191Z","steps":["trace[482685568] 'process raft request' (duration: 459.193189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:43.330251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:42.870665Z","time spent":"459.561439ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:43.330512Z","caller":"traceutil/trace.go:171","msg":"trace[1124056119] transaction","detail":"{read_only:false; response_revision:25073; number_of_response:1; }","duration":"459.771325ms","start":"2026-04-07T00:55:42.870726Z","end":"2026-04-07T00:55:43.330497Z","steps":["trace[1124056119] 'process raft request' (duration: 459.431666ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:43.33059Z","caller":"traceutil/trace.go:171","msg":"trace[1778632012] linearizableReadLoop","detail":"{readStateIndex:25894; appliedIndex:25888; }","duration":"458.585262ms","start":"2026-04-07T00:55:42.871996Z","end":"2026-04-07T00:55:43.330582Z","steps":["trace[1778632012] 'read index received' (duration: 127.454154ms)","trace[1778632012] 'applied index is now lower than readState.Index' (duration: 331.130298ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:43.330711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:42.870721Z","time spent":"459.833016ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:43.330755Z","caller":"traceutil/trace.go:171","msg":"trace[1291675044] transaction","detail":"{read_only:false; response_revision:25074; number_of_response:1; }","duration":"458.609802ms","start":"2026-04-07T00:55:42.872138Z","end":"2026-04-07T00:55:43.330748Z","steps":["trace[1291675044] 'process raft request' (duration: 458.291934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:43.330798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:42.872129Z","time spent":"458.644053ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:43.330935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.928641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:55:43.331019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.269021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:55:43.331013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.545077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:43.331105Z","caller":"traceutil/trace.go:171","msg":"trace[2078915626] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:25074; }","duration":"217.379794ms","start":"2026-04-07T00:55:43.113709Z","end":"2026-04-07T00:55:43.331089Z","steps":["trace[2078915626] 'agreement among raft nodes before linearized reading' (duration: 217.210609ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:43.331123Z","caller":"traceutil/trace.go:171","msg":"trace[1806139890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25074; }","duration":"456.690332ms","start":"2026-04-07T00:55:42.874411Z","end":"2026-04-07T00:55:43.331101Z","steps":["trace[1806139890] 'agreement among raft nodes before linearized reading' (duration: 456.481706ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:43.331141Z","caller":"traceutil/trace.go:171","msg":"trace[386484461] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25074; }","duration":"458.989222ms","start":"2026-04-07T00:55:42.871982Z","end":"2026-04-07T00:55:43.330972Z","steps":["trace[386484461] 'agreement among raft nodes before linearized reading' (duration: 458.88888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:43.331392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:42.874406Z","time spent":"456.97593ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T00:55:43.331379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.674301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:43.331484Z","caller":"traceutil/trace.go:171","msg":"trace[18984557] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25074; }","duration":"373.031152ms","start":"2026-04-07T00:55:42.958431Z","end":"2026-04-07T00:55:43.331462Z","steps":["trace[18984557] 'agreement among raft nodes before linearized reading' (duration: 372.573818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:43.331546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:42.958408Z","time spent":"373.127904ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":30,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-04-07T00:55:43.332024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.660412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-04-07T00:55:43.332079Z","caller":"traceutil/trace.go:171","msg":"trace[120476563] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:25074; }","duration":"153.743525ms","start":"2026-04-07T00:55:43.17832Z","end":"2026-04-07T00:55:43.332063Z","steps":["trace[120476563] 'agreement among raft nodes before linearized reading' (duration: 152.713656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:43.332603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:42.871977Z","time spent":"460.609978ms","remote":"127.0.0.1:49838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-04-07T00:55:44.355282Z","caller":"traceutil/trace.go:171","msg":"trace[410529173] transaction","detail":"{read_only:false; response_revision:25075; number_of_response:1; }","duration":"139.262264ms","start":"2026-04-07T00:55:44.215979Z","end":"2026-04-07T00:55:44.355241Z","steps":["trace[410529173] 'process raft request' (duration: 139.097189ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:44.659153Z","caller":"traceutil/trace.go:171","msg":"trace[187270570] transaction","detail":"{read_only:false; response_revision:25076; number_of_response:1; }","duration":"124.977648ms","start":"2026-04-07T00:55:44.534145Z","end":"2026-04-07T00:55:44.659123Z","steps":["trace[187270570] 'process raft request' (duration: 124.740871ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:44.706051Z","caller":"traceutil/trace.go:171","msg":"trace[1791873330] transaction","detail":"{read_only:false; response_revision:25077; number_of_response:1; }","duration":"171.527805ms","start":"2026-04-07T00:55:44.534503Z","end":"2026-04-07T00:55:44.70603Z","steps":["trace[1791873330] 'process raft request' (duration: 171.32799ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:44.706318Z","caller":"traceutil/trace.go:171","msg":"trace[1101544247] transaction","detail":"{read_only:false; response_revision:25078; number_of_response:1; }","duration":"170.834316ms","start":"2026-04-07T00:55:44.535469Z","end":"2026-04-07T00:55:44.706303Z","steps":["trace[1101544247] 'process raft request' (duration: 170.491987ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:44.706538Z","caller":"traceutil/trace.go:171","msg":"trace[843386912] transaction","detail":"{read_only:false; response_revision:25079; number_of_response:1; }","duration":"170.852857ms","start":"2026-04-07T00:55:44.535643Z","end":"2026-04-07T00:55:44.706496Z","steps":["trace[843386912] 'process raft request' (duration: 170.59401ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:45.095572Z","caller":"traceutil/trace.go:171","msg":"trace[768889071] transaction","detail":"{read_only:false; response_revision:25081; number_of_response:1; }","duration":"221.456644ms","start":"2026-04-07T00:55:44.874084Z","end":"2026-04-07T00:55:45.09554Z","steps":["trace[768889071] 'process raft request' (duration: 214.871076ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:45.095672Z","caller":"traceutil/trace.go:171","msg":"trace[2014326020] linearizableReadLoop","detail":"{readStateIndex:25902; appliedIndex:25901; }","duration":"147.944147ms","start":"2026-04-07T00:55:44.947687Z","end":"2026-04-07T00:55:45.095631Z","steps":["trace[2014326020] 'read index received' (duration: 141.218576ms)","trace[2014326020] 'applied index is now lower than readState.Index' (duration: 6.720581ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:45.095796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.07482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:45.095887Z","caller":"traceutil/trace.go:171","msg":"trace[1473960981] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25081; }","duration":"148.231094ms","start":"2026-04-07T00:55:44.947633Z","end":"2026-04-07T00:55:45.095864Z","steps":["trace[1473960981] 'agreement among raft nodes before linearized reading' (duration: 148.05949ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:45.251497Z","caller":"traceutil/trace.go:171","msg":"trace[670460] transaction","detail":"{read_only:false; response_revision:25084; number_of_response:1; }","duration":"132.653974ms","start":"2026-04-07T00:55:45.11881Z","end":"2026-04-07T00:55:45.251464Z","steps":["trace[670460] 'process raft request' (duration: 132.594232ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:45.251762Z","caller":"traceutil/trace.go:171","msg":"trace[1363643158] transaction","detail":"{read_only:false; response_revision:25082; number_of_response:1; }","duration":"150.124767ms","start":"2026-04-07T00:55:45.101597Z","end":"2026-04-07T00:55:45.251722Z","steps":["trace[1363643158] 'process raft request' (duration: 145.07468ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:45.251846Z","caller":"traceutil/trace.go:171","msg":"trace[169258505] transaction","detail":"{read_only:false; response_revision:25083; number_of_response:1; }","duration":"147.170046ms","start":"2026-04-07T00:55:45.104641Z","end":"2026-04-07T00:55:45.251811Z","steps":["trace[169258505] 'process raft request' (duration: 146.631262ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:45.438809Z","caller":"traceutil/trace.go:171","msg":"trace[1996805433] transaction","detail":"{read_only:false; response_revision:25085; number_of_response:1; }","duration":"101.287617ms","start":"2026-04-07T00:55:45.337491Z","end":"2026-04-07T00:55:45.438779Z","steps":["trace[1996805433] 'process raft request' (duration: 38.49839ms)","trace[1996805433] 'compare' (duration: 62.52616ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:45.715833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.868304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:45.716079Z","caller":"traceutil/trace.go:171","msg":"trace[253757237] transaction","detail":"{read_only:false; response_revision:25088; number_of_response:1; }","duration":"377.699237ms","start":"2026-04-07T00:55:45.338364Z","end":"2026-04-07T00:55:45.716064Z","steps":["trace[253757237] 'process raft request' (duration: 377.628255ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:45.716104Z","caller":"traceutil/trace.go:171","msg":"trace[574939500] transaction","detail":"{read_only:false; response_revision:25086; number_of_response:1; }","duration":"377.711867ms","start":"2026-04-07T00:55:45.33836Z","end":"2026-04-07T00:55:45.716072Z","steps":["trace[574939500] 'process raft request' (duration: 245.523855ms)","trace[574939500] 'compare' (duration: 131.72852ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:45.71614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:45.338349Z","time spent":"377.766549ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:45.716123Z","caller":"traceutil/trace.go:171","msg":"trace[1075595880] transaction","detail":"{read_only:false; response_revision:25087; number_of_response:1; }","duration":"377.708747ms","start":"2026-04-07T00:55:45.33836Z","end":"2026-04-07T00:55:45.716068Z","steps":["trace[1075595880] 'process raft request' (duration: 377.583554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:45.716264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:45.338352Z","time spent":"377.82365ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:45.716341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:45.338348Z","time spent":"377.891512ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:45.716383Z","caller":"traceutil/trace.go:171","msg":"trace[936566910] transaction","detail":"{read_only:false; response_revision:25089; number_of_response:1; }","duration":"377.833351ms","start":"2026-04-07T00:55:45.338519Z","end":"2026-04-07T00:55:45.716352Z","steps":["trace[936566910] 'process raft request' (duration: 377.510522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:45.716543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:45.33851Z","time spent":"377.972114ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:45.988549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.298229ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-04-07T00:55:45.988931Z","caller":"traceutil/trace.go:171","msg":"trace[1552536736] linearizableReadLoop","detail":"{readStateIndex:25912; appliedIndex:25906; }","duration":"463.215797ms","start":"2026-04-07T00:55:45.525693Z","end":"2026-04-07T00:55:45.988909Z","steps":["trace[1552536736] 'read index received' (duration: 58.147461ms)","trace[1552536736] 'applied index is now lower than readState.Index' (duration: 405.066846ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:45.988959Z","caller":"traceutil/trace.go:171","msg":"trace[732106098] transaction","detail":"{read_only:false; response_revision:25090; number_of_response:1; }","duration":"507.683469ms","start":"2026-04-07T00:55:45.481246Z","end":"2026-04-07T00:55:45.98893Z","steps":["trace[732106098] 'process raft request' (duration: 507.460243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:45.989108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:45.481218Z","time spent":"507.796212ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:45.989156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.445743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:55:45.98916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.393446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:55:45.989162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.821773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:45.989215Z","caller":"traceutil/trace.go:171","msg":"trace[1748047878] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25090; }","duration":"463.555567ms","start":"2026-04-07T00:55:45.525643Z","end":"2026-04-07T00:55:45.989199Z","steps":["trace[1748047878] 'agreement among raft nodes before linearized reading' (duration: 463.424453ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:45.989234Z","caller":"traceutil/trace.go:171","msg":"trace[1572119197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25090; }","duration":"354.5208ms","start":"2026-04-07T00:55:45.634695Z","end":"2026-04-07T00:55:45.989216Z","steps":["trace[1572119197] 'agreement among raft nodes before linearized reading' (duration: 354.394527ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:45.989246Z","caller":"traceutil/trace.go:171","msg":"trace[307036013] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25090; }","duration":"282.970487ms","start":"2026-04-07T00:55:45.70626Z","end":"2026-04-07T00:55:45.98923Z","steps":["trace[307036013] 'agreement among raft nodes before linearized reading' (duration: 282.832293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:45.989275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:45.634674Z","time spent":"354.595722ms","remote":"127.0.0.1:49626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T00:55:45.989268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:45.52563Z","time spent":"463.620478ms","remote":"127.0.0.1:56238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":30,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:55:46.812599Z","caller":"traceutil/trace.go:171","msg":"trace[985664478] transaction","detail":"{read_only:false; response_revision:25092; number_of_response:1; }","duration":"146.963331ms","start":"2026-04-07T00:55:46.665595Z","end":"2026-04-07T00:55:46.812558Z","steps":["trace[985664478] 'process raft request' (duration: 146.658523ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:46.831752Z","caller":"traceutil/trace.go:171","msg":"trace[252823855] linearizableReadLoop","detail":"{readStateIndex:25917; appliedIndex:25913; }","duration":"116.812347ms","start":"2026-04-07T00:55:46.714842Z","end":"2026-04-07T00:55:46.831654Z","steps":["trace[252823855] 'read index received' (duration: 97.473964ms)","trace[252823855] 'applied index is now lower than readState.Index' (duration: 19.337033ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:46.832135Z","caller":"traceutil/trace.go:171","msg":"trace[1628181135] transaction","detail":"{read_only:false; response_revision:25093; number_of_response:1; }","duration":"120.554678ms","start":"2026-04-07T00:55:46.711526Z","end":"2026-04-07T00:55:46.832081Z","steps":["trace[1628181135] 'process raft request' (duration: 119.744857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:46.832169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.3027ms","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-04-07T00:55:46.832326Z","caller":"traceutil/trace.go:171","msg":"trace[1584894931] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:25095; }","duration":"117.443484ms","start":"2026-04-07T00:55:46.714805Z","end":"2026-04-07T00:55:46.832249Z","steps":["trace[1584894931] 'agreement among raft nodes before linearized reading' (duration: 117.097384ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:46.83246Z","caller":"traceutil/trace.go:171","msg":"trace[404349572] transaction","detail":"{read_only:false; response_revision:25094; number_of_response:1; }","duration":"119.827337ms","start":"2026-04-07T00:55:46.712603Z","end":"2026-04-07T00:55:46.832431Z","steps":["trace[404349572] 'process raft request' (duration: 118.873892ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:46.832636Z","caller":"traceutil/trace.go:171","msg":"trace[11076252] transaction","detail":"{read_only:false; response_revision:25095; number_of_response:1; }","duration":"118.486462ms","start":"2026-04-07T00:55:46.71411Z","end":"2026-04-07T00:55:46.832597Z","steps":["trace[11076252] 'process raft request' (duration: 117.495715ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:47.239132Z","caller":"traceutil/trace.go:171","msg":"trace[1858719745] transaction","detail":"{read_only:false; response_revision:25097; number_of_response:1; }","duration":"136.991902ms","start":"2026-04-07T00:55:47.102111Z","end":"2026-04-07T00:55:47.239103Z","steps":["trace[1858719745] 'process raft request' (duration: 136.778735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:48.060288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.002531ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:48.060577Z","caller":"traceutil/trace.go:171","msg":"trace[1416961692] transaction","detail":"{read_only:false; response_revision:25102; number_of_response:1; }","duration":"335.029444ms","start":"2026-04-07T00:55:47.725521Z","end":"2026-04-07T00:55:48.06055Z","steps":["trace[1416961692] 'process raft request' (duration: 334.901251ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:48.060562Z","caller":"traceutil/trace.go:171","msg":"trace[1752416711] transaction","detail":"{read_only:false; response_revision:25101; number_of_response:1; }","duration":"335.132717ms","start":"2026-04-07T00:55:47.725394Z","end":"2026-04-07T00:55:48.060526Z","steps":["trace[1752416711] 'process raft request' (duration: 183.757156ms)","trace[1752416711] 'compare' (duration: 150.844307ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:48.060683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:47.725514Z","time spent":"335.118187ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:48.06076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:47.725381Z","time spent":"335.270651ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:48.060767Z","caller":"traceutil/trace.go:171","msg":"trace[1004694290] linearizableReadLoop","detail":"{readStateIndex:25925; appliedIndex:25922; }","duration":"258.502106ms","start":"2026-04-07T00:55:47.802246Z","end":"2026-04-07T00:55:48.060748Z","steps":["trace[1004694290] 'read index received' (duration: 106.917269ms)","trace[1004694290] 'applied index is now lower than readState.Index' (duration: 151.582867ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:48.060847Z","caller":"traceutil/trace.go:171","msg":"trace[136810206] transaction","detail":"{read_only:false; response_revision:25103; number_of_response:1; }","duration":"334.49151ms","start":"2026-04-07T00:55:47.72632Z","end":"2026-04-07T00:55:48.060812Z","steps":["trace[136810206] 'process raft request' (duration: 334.174012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:48.061026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.782623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418304"} {"level":"warn","ts":"2026-04-07T00:55:48.061029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:47.726286Z","time spent":"334.643685ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:48.061067Z","caller":"traceutil/trace.go:171","msg":"trace[1296911540] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25103; }","duration":"258.867165ms","start":"2026-04-07T00:55:47.80219Z","end":"2026-04-07T00:55:48.061057Z","steps":["trace[1296911540] 'agreement among raft nodes before linearized reading' (duration: 258.604138ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:48.723706Z","caller":"traceutil/trace.go:171","msg":"trace[346707074] transaction","detail":"{read_only:false; response_revision:25105; number_of_response:1; }","duration":"161.141234ms","start":"2026-04-07T00:55:48.562475Z","end":"2026-04-07T00:55:48.723616Z","steps":["trace[346707074] 'process raft request' (duration: 160.450026ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:48.954618Z","caller":"traceutil/trace.go:171","msg":"trace[850303435] transaction","detail":"{read_only:false; response_revision:25107; number_of_response:1; }","duration":"117.098744ms","start":"2026-04-07T00:55:48.837484Z","end":"2026-04-07T00:55:48.954583Z","steps":["trace[850303435] 'process raft request' (duration: 116.869778ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:48.954982Z","caller":"traceutil/trace.go:171","msg":"trace[1804854998] transaction","detail":"{read_only:false; response_revision:25109; number_of_response:1; }","duration":"115.591543ms","start":"2026-04-07T00:55:48.839367Z","end":"2026-04-07T00:55:48.954958Z","steps":["trace[1804854998] 'process raft request' (duration: 115.446319ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:48.954981Z","caller":"traceutil/trace.go:171","msg":"trace[2006532176] transaction","detail":"{read_only:false; response_revision:25108; number_of_response:1; }","duration":"116.663843ms","start":"2026-04-07T00:55:48.838278Z","end":"2026-04-07T00:55:48.954942Z","steps":["trace[2006532176] 'process raft request' (duration: 116.257302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:49.478298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.893533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18571"} {"level":"info","ts":"2026-04-07T00:55:49.478394Z","caller":"traceutil/trace.go:171","msg":"trace[1015624474] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25111; }","duration":"136.038747ms","start":"2026-04-07T00:55:49.342334Z","end":"2026-04-07T00:55:49.478372Z","steps":["trace[1015624474] 'range keys from in-memory index tree' (duration: 135.695568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:49.478695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.549214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-07T00:55:49.478739Z","caller":"traceutil/trace.go:171","msg":"trace[670439141] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25111; }","duration":"118.613555ms","start":"2026-04-07T00:55:49.360114Z","end":"2026-04-07T00:55:49.478728Z","steps":["trace[670439141] 'range keys from in-memory index tree' (duration: 118.42124ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:53.068814Z","caller":"traceutil/trace.go:171","msg":"trace[1291037049] transaction","detail":"{read_only:false; response_revision:25136; number_of_response:1; }","duration":"159.654884ms","start":"2026-04-07T00:55:52.909131Z","end":"2026-04-07T00:55:53.068786Z","steps":["trace[1291037049] 'process raft request' (duration: 158.471551ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:53.090384Z","caller":"traceutil/trace.go:171","msg":"trace[836920915] transaction","detail":"{read_only:false; response_revision:25139; number_of_response:1; }","duration":"117.433013ms","start":"2026-04-07T00:55:52.972921Z","end":"2026-04-07T00:55:53.090354Z","steps":["trace[836920915] 'process raft request' (duration: 117.382312ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:53.090807Z","caller":"traceutil/trace.go:171","msg":"trace[1034086045] transaction","detail":"{read_only:false; response_revision:25137; number_of_response:1; }","duration":"121.74851ms","start":"2026-04-07T00:55:52.969046Z","end":"2026-04-07T00:55:53.090794Z","steps":["trace[1034086045] 'process raft request' (duration: 121.025511ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:53.090944Z","caller":"traceutil/trace.go:171","msg":"trace[1442474960] transaction","detail":"{read_only:false; response_revision:25138; number_of_response:1; }","duration":"119.244162ms","start":"2026-04-07T00:55:52.971689Z","end":"2026-04-07T00:55:53.090933Z","steps":["trace[1442474960] 'process raft request' (duration: 118.555033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:21.370399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.817413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:56:21.370528Z","caller":"traceutil/trace.go:171","msg":"trace[1399332252] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:25497; }","duration":"128.03301ms","start":"2026-04-07T00:56:21.242472Z","end":"2026-04-07T00:56:21.370505Z","steps":["trace[1399332252] 'count revisions from in-memory index tree' (duration: 127.710351ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:21.821605Z","caller":"traceutil/trace.go:171","msg":"trace[660869117] transaction","detail":"{read_only:false; response_revision:25500; number_of_response:1; }","duration":"100.703071ms","start":"2026-04-07T00:56:21.720866Z","end":"2026-04-07T00:56:21.821569Z","steps":["trace[660869117] 'process raft request' (duration: 100.476805ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:56:22.048592Z","caller":"traceutil/trace.go:171","msg":"trace[458241418] transaction","detail":"{read_only:false; response_revision:25501; number_of_response:1; }","duration":"325.026144ms","start":"2026-04-07T00:56:21.723539Z","end":"2026-04-07T00:56:22.048565Z","steps":["trace[458241418] 'process raft request' (duration: 324.410207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:56:22.048763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:56:21.723525Z","time spent":"325.149427ms","remote":"127.0.0.1:49922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:57:27.135757Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23207} {"level":"info","ts":"2026-04-07T00:57:27.281695Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23207,"took":"141.059062ms","hash":4014345398,"current-db-size-bytes":58404864,"current-db-size":"58 MB","current-db-size-in-use-bytes":35889152,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-04-07T00:57:27.281762Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4014345398,"revision":23207,"compact-revision":20068} {"level":"info","ts":"2026-04-07T00:57:58.396876Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a57.wal"} {"level":"info","ts":"2026-04-07T01:02:27.142692Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26067} {"level":"info","ts":"2026-04-07T01:02:27.297079Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26067,"took":"148.638447ms","hash":3004557339,"current-db-size-bytes":58404864,"current-db-size":"58 MB","current-db-size-in-use-bytes":34119680,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-07T01:02:27.297169Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3004557339,"revision":26067,"compact-revision":23207} {"level":"info","ts":"2026-04-07T01:03:07.16585Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T01:03:07.172965Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-07T01:03:07.173182Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-07T01:07:27.153231Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28782} {"level":"info","ts":"2026-04-07T01:07:27.297234Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28782,"took":"139.043236ms","hash":2996777324,"current-db-size-bytes":58404864,"current-db-size":"58 MB","current-db-size-in-use-bytes":30048256,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-07T01:07:27.297324Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2996777324,"revision":28782,"compact-revision":26067} {"level":"info","ts":"2026-04-07T01:12:27.158968Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31028} {"level":"info","ts":"2026-04-07T01:12:27.288217Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31028,"took":"124.613274ms","hash":802107557,"current-db-size-bytes":58404864,"current-db-size":"58 MB","current-db-size-in-use-bytes":29990912,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-07T01:12:27.288305Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":802107557,"revision":31028,"compact-revision":28782} {"level":"info","ts":"2026-04-07T01:17:27.166371Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33295} {"level":"info","ts":"2026-04-07T01:17:27.319632Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33295,"took":"148.048187ms","hash":1009039796,"current-db-size-bytes":58404864,"current-db-size":"58 MB","current-db-size-in-use-bytes":31965184,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-07T01:17:27.319698Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1009039796,"revision":33295,"compact-revision":31028} {"level":"info","ts":"2026-04-07T01:22:27.173739Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35594} {"level":"info","ts":"2026-04-07T01:22:27.328012Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35594,"took":"148.224502ms","hash":332328856,"current-db-size-bytes":59273216,"current-db-size":"59 MB","current-db-size-in-use-bytes":31293440,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-07T01:22:27.328099Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":332328856,"revision":35594,"compact-revision":33295} {"level":"info","ts":"2026-04-07T01:22:31.172633Z","caller":"traceutil/trace.go:171","msg":"trace[455059196] transaction","detail":"{read_only:false; response_revision:37851; number_of_response:1; }","duration":"112.395365ms","start":"2026-04-07T01:22:31.060207Z","end":"2026-04-07T01:22:31.172602Z","steps":["trace[455059196] 'process raft request' (duration: 112.162589ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:33.896786Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c3b8446219deb8c1","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T01:24:33.902842Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-07T01:24:33.903105Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-04-07T01:27:27.182336Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37825} {"level":"info","ts":"2026-04-07T01:27:27.307972Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37825,"took":"121.49218ms","hash":3095590832,"current-db-size-bytes":59273216,"current-db-size":"59 MB","current-db-size-in-use-bytes":31195136,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-07T01:27:27.308059Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3095590832,"revision":37825,"compact-revision":35594}