{"level":"warn","ts":"2026-07-15T14:30:48.159476Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T14:30:48.159588Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.231: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.19.213.231:2380","--initial-cluster=instance=https://199.19.213.231:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.231:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.231: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-07-15T14:30:48.159676Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T14:30:48.15969Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.231:2380"]} {"level":"info","ts":"2026-07-15T14:30:48.159714Z","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-07-15T14:30:48.16073Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.231:2379"]} {"level":"info","ts":"2026-07-15T14:30:48.160827Z","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.19.213.231:2380"],"listen-peer-urls":["https://199.19.213.231:2380"],"advertise-client-urls":["https://199.19.213.231:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.231:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.231: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-07-15T14:30:48.164294Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.194486ms"} {"level":"info","ts":"2026-07-15T14:30:48.169192Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8bb149ac537fafcb","cluster-id":"6a60f8f1d4e49112"} {"level":"info","ts":"2026-07-15T14:30:48.169283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb switched to configuration voters=()"} {"level":"info","ts":"2026-07-15T14:30:48.169302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb became follower at term 0"} {"level":"info","ts":"2026-07-15T14:30:48.16931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8bb149ac537fafcb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-15T14:30:48.169319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb became follower at term 1"} {"level":"info","ts":"2026-07-15T14:30:48.169352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb switched to configuration voters=(10065907646633848779)"} {"level":"warn","ts":"2026-07-15T14:30:48.178886Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-15T14:30:48.180357Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-15T14:30:48.181252Z","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-07-15T14:30:48.183451Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8bb149ac537fafcb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-15T14:30:48.184002Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8bb149ac537fafcb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-15T14:30:48.1841Z","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-07-15T14:30:48.184142Z","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-07-15T14:30:48.18415Z","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-07-15T14:30:48.184692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb switched to configuration voters=(10065907646633848779)"} {"level":"info","ts":"2026-07-15T14:30:48.184816Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6a60f8f1d4e49112","local-member-id":"8bb149ac537fafcb","added-peer-id":"8bb149ac537fafcb","added-peer-peer-urls":["https://199.19.213.231:2380"]} {"level":"info","ts":"2026-07-15T14:30:48.188055Z","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-07-15T14:30:48.188174Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.231:2380"} {"level":"info","ts":"2026-07-15T14:30:48.188224Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.231:2380"} {"level":"info","ts":"2026-07-15T14:30:48.188272Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8bb149ac537fafcb","initial-advertise-peer-urls":["https://199.19.213.231:2380"],"listen-peer-urls":["https://199.19.213.231:2380"],"advertise-client-urls":["https://199.19.213.231:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.231:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-15T14:30:48.188314Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-15T14:30:48.570213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb is starting a new election at term 1"} {"level":"info","ts":"2026-07-15T14:30:48.570277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb became pre-candidate at term 1"} {"level":"info","ts":"2026-07-15T14:30:48.570301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb received MsgPreVoteResp from 8bb149ac537fafcb at term 1"} {"level":"info","ts":"2026-07-15T14:30:48.570321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb became candidate at term 2"} {"level":"info","ts":"2026-07-15T14:30:48.570333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb received MsgVoteResp from 8bb149ac537fafcb at term 2"} {"level":"info","ts":"2026-07-15T14:30:48.57035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb became leader at term 2"} {"level":"info","ts":"2026-07-15T14:30:48.570364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8bb149ac537fafcb elected leader 8bb149ac537fafcb at term 2"} {"level":"info","ts":"2026-07-15T14:30:48.589828Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8bb149ac537fafcb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.231:2379]}","request-path":"/0/members/8bb149ac537fafcb/attributes","cluster-id":"6a60f8f1d4e49112","publish-timeout":"7s"} {"level":"info","ts":"2026-07-15T14:30:48.59205Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T14:30:48.592134Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T14:30:48.592276Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T14:30:48.592692Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-15T14:30:48.592774Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-15T14:30:48.593947Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6a60f8f1d4e49112","local-member-id":"8bb149ac537fafcb","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T14:30:48.594033Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T14:30:48.594065Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T14:30:48.597064Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.231:2379"} {"level":"info","ts":"2026-07-15T14:30:48.597299Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-15T14:31:03.958573Z","caller":"traceutil/trace.go:171","msg":"trace[1255626583] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"283.011123ms","start":"2026-07-15T14:31:03.67554Z","end":"2026-07-15T14:31:03.958551Z","steps":["trace[1255626583] 'process raft request' (duration: 282.786719ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:31:28.127925Z","caller":"traceutil/trace.go:171","msg":"trace[1687493705] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"193.664152ms","start":"2026-07-15T14:31:27.934232Z","end":"2026-07-15T14:31:28.127896Z","steps":["trace[1687493705] 'process raft request' (duration: 193.06482ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:31:28.23336Z","caller":"traceutil/trace.go:171","msg":"trace[508907294] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"212.577358ms","start":"2026-07-15T14:31:28.02075Z","end":"2026-07-15T14:31:28.233328Z","steps":["trace[508907294] 'process raft request' (duration: 209.821198ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:31:36.135673Z","caller":"traceutil/trace.go:171","msg":"trace[270878475] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"321.586593ms","start":"2026-07-15T14:31:35.814069Z","end":"2026-07-15T14:31:36.135655Z","steps":["trace[270878475] 'process raft request' (duration: 321.456161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:31:36.136054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:31:35.814047Z","time spent":"321.692925ms","remote":"127.0.0.1:43684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:31:52.1151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.380948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9kvzz\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-15T14:31:52.115235Z","caller":"traceutil/trace.go:171","msg":"trace[979576160] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9kvzz; range_end:; response_count:1; response_revision:811; }","duration":"216.566442ms","start":"2026-07-15T14:31:51.898644Z","end":"2026-07-15T14:31:52.115211Z","steps":["trace[979576160] 'range keys from in-memory index tree' (duration: 216.205965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:33:08.992969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.376207ms","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-07-15T14:33:08.993042Z","caller":"traceutil/trace.go:171","msg":"trace[426518435] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1889; }","duration":"191.501119ms","start":"2026-07-15T14:33:08.801524Z","end":"2026-07-15T14:33:08.993025Z","steps":["trace[426518435] 'range keys from in-memory index tree' (duration: 191.188613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:33:08.99318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.567466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a7ffb98a-5bb9-44b1-a11b-b3291e3c7776\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-07-15T14:33:08.993219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.48771ms","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-07-15T14:33:08.993257Z","caller":"traceutil/trace.go:171","msg":"trace[536801405] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a7ffb98a-5bb9-44b1-a11b-b3291e3c7776; range_end:; response_count:1; response_revision:1889; }","duration":"143.658097ms","start":"2026-07-15T14:33:08.849581Z","end":"2026-07-15T14:33:08.993239Z","steps":["trace[536801405] 'range keys from in-memory index tree' (duration: 143.386822ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:33:08.993289Z","caller":"traceutil/trace.go:171","msg":"trace[1603271395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1889; }","duration":"175.622823ms","start":"2026-07-15T14:33:08.81765Z","end":"2026-07-15T14:33:08.993273Z","steps":["trace[1603271395] 'range keys from in-memory index tree' (duration: 175.315488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:33:09.424076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.930628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a7ffb98a-5bb9-44b1-a11b-b3291e3c7776\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-15T14:33:09.424155Z","caller":"traceutil/trace.go:171","msg":"trace[592609854] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a7ffb98a-5bb9-44b1-a11b-b3291e3c7776; range_end:; response_count:1; response_revision:1894; }","duration":"162.048921ms","start":"2026-07-15T14:33:09.262087Z","end":"2026-07-15T14:33:09.424136Z","steps":["trace[592609854] 'range keys from in-memory index tree' (duration: 161.737145ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:33:24.341772Z","caller":"traceutil/trace.go:171","msg":"trace[301964268] transaction","detail":"{read_only:false; response_revision:2018; number_of_response:1; }","duration":"114.856631ms","start":"2026-07-15T14:33:24.226868Z","end":"2026-07-15T14:33:24.341725Z","steps":["trace[301964268] 'process raft request' (duration: 113.296013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:34:03.663932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.406797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T14:34:03.664178Z","caller":"traceutil/trace.go:171","msg":"trace[969433628] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"238.7243ms","start":"2026-07-15T14:34:03.425408Z","end":"2026-07-15T14:34:03.664132Z","steps":["trace[969433628] 'process raft request' (duration: 104.678461ms)","trace[969433628] 'compare' (duration: 133.130542ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:34:03.664303Z","caller":"traceutil/trace.go:171","msg":"trace[247453006] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"222.359765ms","start":"2026-07-15T14:34:03.441909Z","end":"2026-07-15T14:34:03.664269Z","steps":["trace[247453006] 'process raft request' (duration: 222.238732ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:34:03.664291Z","caller":"traceutil/trace.go:171","msg":"trace[2074690610] linearizableReadLoop","detail":"{readStateIndex:2320; appliedIndex:2319; }","duration":"236.016149ms","start":"2026-07-15T14:34:03.428229Z","end":"2026-07-15T14:34:03.664245Z","steps":["trace[2074690610] 'read index received' (duration: 101.872169ms)","trace[2074690610] 'applied index is now lower than readState.Index' (duration: 134.14107ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:34:03.664546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.288834ms","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-07-15T14:34:03.664605Z","caller":"traceutil/trace.go:171","msg":"trace[720449496] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2238; }","duration":"236.390546ms","start":"2026-07-15T14:34:03.428202Z","end":"2026-07-15T14:34:03.664593Z","steps":["trace[720449496] 'agreement among raft nodes before linearized reading' (duration: 236.149761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:34:03.664743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.621894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T14:34:03.664791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.212887ms","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-07-15T14:34:03.664868Z","caller":"traceutil/trace.go:171","msg":"trace[1323509102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2238; }","duration":"211.293088ms","start":"2026-07-15T14:34:03.453552Z","end":"2026-07-15T14:34:03.664845Z","steps":["trace[1323509102] 'agreement among raft nodes before linearized reading' (duration: 211.088754ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:34:03.664863Z","caller":"traceutil/trace.go:171","msg":"trace[1196938222] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2238; }","duration":"211.768427ms","start":"2026-07-15T14:34:03.453058Z","end":"2026-07-15T14:34:03.664827Z","steps":["trace[1196938222] 'agreement among raft nodes before linearized reading' (duration: 211.567983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:34:03.664747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.188165ms","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-07-15T14:34:03.664977Z","caller":"traceutil/trace.go:171","msg":"trace[2044347209] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2238; }","duration":"168.465611ms","start":"2026-07-15T14:34:03.496501Z","end":"2026-07-15T14:34:03.664967Z","steps":["trace[2044347209] 'agreement among raft nodes before linearized reading' (duration: 168.122974ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:34:40.156312Z","caller":"traceutil/trace.go:171","msg":"trace[864838860] linearizableReadLoop","detail":"{readStateIndex:2711; appliedIndex:2710; }","duration":"119.054166ms","start":"2026-07-15T14:34:40.037236Z","end":"2026-07-15T14:34:40.156291Z","steps":["trace[864838860] 'read index received' (duration: 114.146064ms)","trace[864838860] 'applied index is now lower than readState.Index' (duration: 4.907322ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:34:40.156403Z","caller":"traceutil/trace.go:171","msg":"trace[1318626735] transaction","detail":"{read_only:false; response_revision:2613; number_of_response:1; }","duration":"205.375726ms","start":"2026-07-15T14:34:39.950993Z","end":"2026-07-15T14:34:40.156369Z","steps":["trace[1318626735] 'process raft request' (duration: 200.383582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:34:40.156481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.233829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-15T14:34:40.156509Z","caller":"traceutil/trace.go:171","msg":"trace[639673404] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2613; }","duration":"119.2985ms","start":"2026-07-15T14:34:40.037203Z","end":"2026-07-15T14:34:40.156502Z","steps":["trace[639673404] 'agreement among raft nodes before linearized reading' (duration: 119.179108ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:37:12.645091Z","caller":"traceutil/trace.go:171","msg":"trace[1911357247] transaction","detail":"{read_only:false; response_revision:3492; number_of_response:1; }","duration":"245.982623ms","start":"2026-07-15T14:37:12.399074Z","end":"2026-07-15T14:37:12.645056Z","steps":["trace[1911357247] 'process raft request' (duration: 161.247128ms)","trace[1911357247] 'compare' (duration: 84.434178ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:37:12.64516Z","caller":"traceutil/trace.go:171","msg":"trace[1437853763] transaction","detail":"{read_only:false; response_revision:3493; number_of_response:1; }","duration":"208.438673ms","start":"2026-07-15T14:37:12.436697Z","end":"2026-07-15T14:37:12.645136Z","steps":["trace[1437853763] 'process raft request' (duration: 208.330341ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:37:12.911631Z","caller":"traceutil/trace.go:171","msg":"trace[1462789105] transaction","detail":"{read_only:false; response_revision:3495; number_of_response:1; }","duration":"111.920124ms","start":"2026-07-15T14:37:12.799691Z","end":"2026-07-15T14:37:12.911611Z","steps":["trace[1462789105] 'process raft request' (duration: 111.798732ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:37:12.926375Z","caller":"traceutil/trace.go:171","msg":"trace[1721776655] transaction","detail":"{read_only:false; response_revision:3496; number_of_response:1; }","duration":"126.013844ms","start":"2026-07-15T14:37:12.800334Z","end":"2026-07-15T14:37:12.926348Z","steps":["trace[1721776655] 'process raft request' (duration: 125.859871ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:39:38.931417Z","caller":"traceutil/trace.go:171","msg":"trace[1029420967] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"104.799018ms","start":"2026-07-15T14:39:38.8266Z","end":"2026-07-15T14:39:38.931399Z","steps":["trace[1029420967] 'process raft request' (duration: 104.597114ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:39:38.931585Z","caller":"traceutil/trace.go:171","msg":"trace[280404666] transaction","detail":"{read_only:false; response_revision:5021; number_of_response:1; }","duration":"103.947591ms","start":"2026-07-15T14:39:38.827625Z","end":"2026-07-15T14:39:38.931573Z","steps":["trace[280404666] 'process raft request' (duration: 103.710947ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:39:41.706041Z","caller":"traceutil/trace.go:171","msg":"trace[383176284] linearizableReadLoop","detail":"{readStateIndex:5335; appliedIndex:5334; }","duration":"123.494666ms","start":"2026-07-15T14:39:41.582515Z","end":"2026-07-15T14:39:41.706009Z","steps":["trace[383176284] 'read index received' (duration: 123.374574ms)","trace[383176284] 'applied index is now lower than readState.Index' (duration: 117.602µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:39:41.706077Z","caller":"traceutil/trace.go:171","msg":"trace[1307089953] transaction","detail":"{read_only:false; response_revision:5141; number_of_response:1; }","duration":"131.764435ms","start":"2026-07-15T14:39:41.574279Z","end":"2026-07-15T14:39:41.706044Z","steps":["trace[1307089953] 'process raft request' (duration: 131.550331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:39:41.70625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.71829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T14:39:41.70633Z","caller":"traceutil/trace.go:171","msg":"trace[1978527116] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:5141; }","duration":"123.875863ms","start":"2026-07-15T14:39:41.58244Z","end":"2026-07-15T14:39:41.706316Z","steps":["trace[1978527116] 'agreement among raft nodes before linearized reading' (duration: 123.671459ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:39:41.771663Z","caller":"traceutil/trace.go:171","msg":"trace[1761820322] transaction","detail":"{read_only:false; response_revision:5143; number_of_response:1; }","duration":"123.391925ms","start":"2026-07-15T14:39:41.648246Z","end":"2026-07-15T14:39:41.771638Z","steps":["trace[1761820322] 'process raft request' (duration: 123.306243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:39:41.771848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.319068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.231\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-15T14:39:41.771884Z","caller":"traceutil/trace.go:171","msg":"trace[983719688] transaction","detail":"{read_only:false; response_revision:5142; number_of_response:1; }","duration":"176.548653ms","start":"2026-07-15T14:39:41.595305Z","end":"2026-07-15T14:39:41.771854Z","steps":["trace[983719688] 'process raft request' (duration: 176.123615ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:39:41.771927Z","caller":"traceutil/trace.go:171","msg":"trace[280333593] range","detail":"{range_begin:/registry/masterleases/199.19.213.231; range_end:; response_count:1; response_revision:5143; }","duration":"116.444851ms","start":"2026-07-15T14:39:41.655465Z","end":"2026-07-15T14:39:41.77191Z","steps":["trace[280333593] 'agreement among raft nodes before linearized reading' (duration: 116.208506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:39:41.771915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.366433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-07-15T14:39:41.77193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.150082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f2ee890e-bda8-4df5-a565-2410a6eb8e3b\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-07-15T14:39:41.772015Z","caller":"traceutil/trace.go:171","msg":"trace[1029204270] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5143; }","duration":"135.475525ms","start":"2026-07-15T14:39:41.636521Z","end":"2026-07-15T14:39:41.771997Z","steps":["trace[1029204270] 'agreement among raft nodes before linearized reading' (duration: 135.241551ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:39:41.772036Z","caller":"traceutil/trace.go:171","msg":"trace[1244410682] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f2ee890e-bda8-4df5-a565-2410a6eb8e3b; range_end:; response_count:1; response_revision:5143; }","duration":"107.307046ms","start":"2026-07-15T14:39:41.664707Z","end":"2026-07-15T14:39:41.772014Z","steps":["trace[1244410682] 'agreement among raft nodes before linearized reading' (duration: 107.02519ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:39:55.443218Z","caller":"traceutil/trace.go:171","msg":"trace[1869499277] transaction","detail":"{read_only:false; response_revision:5415; number_of_response:1; }","duration":"192.119511ms","start":"2026-07-15T14:39:55.251071Z","end":"2026-07-15T14:39:55.44319Z","steps":["trace[1869499277] 'process raft request' (duration: 191.927367ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:39:55.443364Z","caller":"traceutil/trace.go:171","msg":"trace[407223683] linearizableReadLoop","detail":"{readStateIndex:5617; appliedIndex:5617; }","duration":"175.704527ms","start":"2026-07-15T14:39:55.267598Z","end":"2026-07-15T14:39:55.443303Z","steps":["trace[407223683] 'read index received' (duration: 175.651016ms)","trace[407223683] 'applied index is now lower than readState.Index' (duration: 48.541µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:39:55.443548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.921511ms","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-07-15T14:39:55.443635Z","caller":"traceutil/trace.go:171","msg":"trace[1851883717] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5415; }","duration":"176.021834ms","start":"2026-07-15T14:39:55.267592Z","end":"2026-07-15T14:39:55.443613Z","steps":["trace[1851883717] 'agreement among raft nodes before linearized reading' (duration: 175.798979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:39:55.465291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.772814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:39:55.46535Z","caller":"traceutil/trace.go:171","msg":"trace[255947795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5417; }","duration":"132.868715ms","start":"2026-07-15T14:39:55.33247Z","end":"2026-07-15T14:39:55.465339Z","steps":["trace[255947795] 'agreement among raft nodes before linearized reading' (duration: 132.748803ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:39:55.465284Z","caller":"traceutil/trace.go:171","msg":"trace[1566877298] transaction","detail":"{read_only:false; response_revision:5416; number_of_response:1; }","duration":"185.855331ms","start":"2026-07-15T14:39:55.279397Z","end":"2026-07-15T14:39:55.465252Z","steps":["trace[1566877298] 'process raft request' (duration: 185.671318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:39:55.465445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.068248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-v7bg5\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-07-15T14:39:55.465612Z","caller":"traceutil/trace.go:171","msg":"trace[1347716336] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-v7bg5; range_end:; response_count:1; response_revision:5417; }","duration":"111.23552ms","start":"2026-07-15T14:39:55.354341Z","end":"2026-07-15T14:39:55.465577Z","steps":["trace[1347716336] 'agreement among raft nodes before linearized reading' (duration: 110.995106ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:40:04.0352Z","caller":"traceutil/trace.go:171","msg":"trace[1454032445] transaction","detail":"{read_only:false; response_revision:5582; number_of_response:1; }","duration":"176.074084ms","start":"2026-07-15T14:40:03.859101Z","end":"2026-07-15T14:40:04.035175Z","steps":["trace[1454032445] 'process raft request' (duration: 175.908961ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:40:04.068718Z","caller":"traceutil/trace.go:171","msg":"trace[952141306] linearizableReadLoop","detail":"{readStateIndex:5789; appliedIndex:5787; }","duration":"156.669162ms","start":"2026-07-15T14:40:03.912019Z","end":"2026-07-15T14:40:04.068689Z","steps":["trace[952141306] 'read index received' (duration: 122.973176ms)","trace[952141306] 'applied index is now lower than readState.Index' (duration: 33.694636ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:40:04.068845Z","caller":"traceutil/trace.go:171","msg":"trace[2083673844] transaction","detail":"{read_only:false; response_revision:5583; number_of_response:1; }","duration":"203.966858ms","start":"2026-07-15T14:40:03.864847Z","end":"2026-07-15T14:40:04.068813Z","steps":["trace[2083673844] 'process raft request' (duration: 203.658321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:40:04.068999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.706365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-15T14:40:04.069057Z","caller":"traceutil/trace.go:171","msg":"trace[313958442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5583; }","duration":"153.819918ms","start":"2026-07-15T14:40:03.915223Z","end":"2026-07-15T14:40:04.069042Z","steps":["trace[313958442] 'agreement among raft nodes before linearized reading' (duration: 153.631704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:40:04.069168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.10458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b1b33ac7-2de8-4e12-8770-2bf38fbc9fb5\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-15T14:40:04.069258Z","caller":"traceutil/trace.go:171","msg":"trace[672751519] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b1b33ac7-2de8-4e12-8770-2bf38fbc9fb5; range_end:; response_count:1; response_revision:5583; }","duration":"157.307024ms","start":"2026-07-15T14:40:03.911934Z","end":"2026-07-15T14:40:04.069241Z","steps":["trace[672751519] 'agreement among raft nodes before linearized reading' (duration: 156.912137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:40:04.324456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.211989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T14:40:04.324669Z","caller":"traceutil/trace.go:171","msg":"trace[980045927] linearizableReadLoop","detail":"{readStateIndex:5792; appliedIndex:5790; }","duration":"169.634081ms","start":"2026-07-15T14:40:04.155013Z","end":"2026-07-15T14:40:04.324647Z","steps":["trace[980045927] 'read index received' (duration: 59.822µs)","trace[980045927] 'applied index is now lower than readState.Index' (duration: 169.573059ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:40:04.324743Z","caller":"traceutil/trace.go:171","msg":"trace[1041980170] transaction","detail":"{read_only:false; response_revision:5585; number_of_response:1; }","duration":"250.768415ms","start":"2026-07-15T14:40:04.07394Z","end":"2026-07-15T14:40:04.324708Z","steps":["trace[1041980170] 'process raft request' (duration: 77.20753ms)","trace[1041980170] 'compare' (duration: 173.053536ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:40:04.324799Z","caller":"traceutil/trace.go:171","msg":"trace[1769255259] transaction","detail":"{read_only:false; response_revision:5586; number_of_response:1; }","duration":"250.630893ms","start":"2026-07-15T14:40:04.074151Z","end":"2026-07-15T14:40:04.324782Z","steps":["trace[1769255259] 'process raft request' (duration: 250.414309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:40:04.324971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.962387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-15T14:40:04.325034Z","caller":"traceutil/trace.go:171","msg":"trace[1439204253] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5586; }","duration":"170.065429ms","start":"2026-07-15T14:40:04.154951Z","end":"2026-07-15T14:40:04.325017Z","steps":["trace[1439204253] 'agreement among raft nodes before linearized reading' (duration: 169.888056ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:40:48.632687Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3009} {"level":"info","ts":"2026-07-15T14:40:48.71761Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3009,"took":"83.691624ms","hash":769572690,"current-db-size-bytes":58421248,"current-db-size":"58 MB","current-db-size-in-use-bytes":31105024,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T14:40:48.717834Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":769572690,"revision":3009,"compact-revision":-1} {"level":"info","ts":"2026-07-15T14:45:48.660239Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6038} {"level":"info","ts":"2026-07-15T14:45:48.826179Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6038,"took":"163.925537ms","hash":2295716075,"current-db-size-bytes":58421248,"current-db-size":"58 MB","current-db-size-in-use-bytes":21843968,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-15T14:45:48.826289Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2295716075,"revision":6038,"compact-revision":3009} {"level":"info","ts":"2026-07-15T14:46:22.593009Z","caller":"traceutil/trace.go:171","msg":"trace[1256670408] linearizableReadLoop","detail":"{readStateIndex:8773; appliedIndex:8772; }","duration":"370.359373ms","start":"2026-07-15T14:46:22.222609Z","end":"2026-07-15T14:46:22.592968Z","steps":["trace[1256670408] 'read index received' (duration: 370.21166ms)","trace[1256670408] 'applied index is now lower than readState.Index' (duration: 146.863µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:46:22.593054Z","caller":"traceutil/trace.go:171","msg":"trace[1717365600] transaction","detail":"{read_only:false; response_revision:8473; number_of_response:1; }","duration":"403.615174ms","start":"2026-07-15T14:46:22.189391Z","end":"2026-07-15T14:46:22.593007Z","steps":["trace[1717365600] 'process raft request' (duration: 403.38651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:46:22.593245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:46:22.189359Z","time spent":"403.792347ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T14:46:22.59342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.592265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T14:46:22.593398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.730261ms","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-07-15T14:46:22.593491Z","caller":"traceutil/trace.go:171","msg":"trace[1974318604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8473; }","duration":"257.737318ms","start":"2026-07-15T14:46:22.335736Z","end":"2026-07-15T14:46:22.593473Z","steps":["trace[1974318604] 'agreement among raft nodes before linearized reading' (duration: 257.607425ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:46:22.59352Z","caller":"traceutil/trace.go:171","msg":"trace[1312296182] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8473; }","duration":"370.943405ms","start":"2026-07-15T14:46:22.22255Z","end":"2026-07-15T14:46:22.593494Z","steps":["trace[1312296182] 'agreement among raft nodes before linearized reading' (duration: 370.584758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:46:22.593578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:46:22.222523Z","time spent":"371.044097ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-15T14:46:22.593582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.855069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-15T14:46:22.593678Z","caller":"traceutil/trace.go:171","msg":"trace[450215113] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8473; }","duration":"206.009732ms","start":"2026-07-15T14:46:22.387645Z","end":"2026-07-15T14:46:22.593655Z","steps":["trace[450215113] 'agreement among raft nodes before linearized reading' (duration: 205.727427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:46:22.594026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.829242ms","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-07-15T14:46:22.594074Z","caller":"traceutil/trace.go:171","msg":"trace[949306056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8473; }","duration":"370.898024ms","start":"2026-07-15T14:46:22.223164Z","end":"2026-07-15T14:46:22.594062Z","steps":["trace[949306056] 'agreement among raft nodes before linearized reading' (duration: 370.132239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:46:22.594111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:46:22.223155Z","time spent":"370.947795ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T14:46:35.437998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.683952ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T14:46:35.438157Z","caller":"traceutil/trace.go:171","msg":"trace[1206804169] linearizableReadLoop","detail":"{readStateIndex:8843; appliedIndex:8841; }","duration":"309.82872ms","start":"2026-07-15T14:46:35.128306Z","end":"2026-07-15T14:46:35.438135Z","steps":["trace[1206804169] 'read index received' (duration: 15.874305ms)","trace[1206804169] 'applied index is now lower than readState.Index' (duration: 293.953005ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:46:35.438373Z","caller":"traceutil/trace.go:171","msg":"trace[1562085424] transaction","detail":"{read_only:false; response_revision:8541; number_of_response:1; }","duration":"337.257757ms","start":"2026-07-15T14:46:35.101012Z","end":"2026-07-15T14:46:35.438269Z","steps":["trace[1562085424] 'process raft request' (duration: 113.173887ms)","trace[1562085424] 'compare' (duration: 223.53148ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:46:35.438593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.256738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T14:46:35.4386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:46:35.100989Z","time spent":"337.477991ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T14:46:35.438687Z","caller":"traceutil/trace.go:171","msg":"trace[230118139] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8541; }","duration":"310.387281ms","start":"2026-07-15T14:46:35.128281Z","end":"2026-07-15T14:46:35.438668Z","steps":["trace[230118139] 'agreement among raft nodes before linearized reading' (duration: 310.208376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:46:35.438731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T14:46:35.128273Z","time spent":"310.449532ms","remote":"127.0.0.1:43910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-07-15T14:46:59.043008Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000232e.wal"} {"level":"warn","ts":"2026-07-15T14:47:03.527994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.803902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T14:47:03.528038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.196637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:47:03.528113Z","caller":"traceutil/trace.go:171","msg":"trace[736350321] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:8748; }","duration":"176.008906ms","start":"2026-07-15T14:47:03.35208Z","end":"2026-07-15T14:47:03.528089Z","steps":["trace[736350321] 'count revisions from in-memory index tree' (duration: 175.719051ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:47:03.52821Z","caller":"traceutil/trace.go:171","msg":"trace[414735889] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8748; }","duration":"193.347419ms","start":"2026-07-15T14:47:03.334767Z","end":"2026-07-15T14:47:03.528114Z","steps":["trace[414735889] 'range keys from in-memory index tree' (duration: 193.034053ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:47:03.74089Z","caller":"traceutil/trace.go:171","msg":"trace[1758309978] transaction","detail":"{read_only:false; response_revision:8749; number_of_response:1; }","duration":"185.648762ms","start":"2026-07-15T14:47:03.555214Z","end":"2026-07-15T14:47:03.740863Z","steps":["trace[1758309978] 'process raft request' (duration: 185.496558ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:47:06.929738Z","caller":"traceutil/trace.go:171","msg":"trace[1445710854] transaction","detail":"{read_only:false; response_revision:8763; number_of_response:1; }","duration":"103.596823ms","start":"2026-07-15T14:47:06.826074Z","end":"2026-07-15T14:47:06.92967Z","steps":["trace[1445710854] 'process raft request' (duration: 103.394769ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:47:06.942191Z","caller":"traceutil/trace.go:171","msg":"trace[1062959447] linearizableReadLoop","detail":"{readStateIndex:9073; appliedIndex:9071; }","duration":"109.644699ms","start":"2026-07-15T14:47:06.832511Z","end":"2026-07-15T14:47:06.942156Z","steps":["trace[1062959447] 'read index received' (duration: 90.088202ms)","trace[1062959447] 'applied index is now lower than readState.Index' (duration: 19.533456ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T14:47:06.942467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.937174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-15T14:47:06.942518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.790757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-15T14:47:06.942569Z","caller":"traceutil/trace.go:171","msg":"trace[800020547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8763; }","duration":"110.055377ms","start":"2026-07-15T14:47:06.832474Z","end":"2026-07-15T14:47:06.942529Z","steps":["trace[800020547] 'agreement among raft nodes before linearized reading' (duration: 109.840803ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:47:06.942613Z","caller":"traceutil/trace.go:171","msg":"trace[308558513] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8763; }","duration":"103.884638ms","start":"2026-07-15T14:47:06.838666Z","end":"2026-07-15T14:47:06.942551Z","steps":["trace[308558513] 'agreement among raft nodes before linearized reading' (duration: 103.679274ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:47:07.360751Z","caller":"traceutil/trace.go:171","msg":"trace[1066416570] transaction","detail":"{read_only:false; response_revision:8766; number_of_response:1; }","duration":"101.89608ms","start":"2026-07-15T14:47:07.258812Z","end":"2026-07-15T14:47:07.360708Z","steps":["trace[1066416570] 'process raft request' (duration: 101.587134ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:47:16.255399Z","caller":"traceutil/trace.go:171","msg":"trace[725477794] transaction","detail":"{read_only:false; response_revision:8858; number_of_response:1; }","duration":"103.176655ms","start":"2026-07-15T14:47:16.152187Z","end":"2026-07-15T14:47:16.255364Z","steps":["trace[725477794] 'process raft request' (duration: 102.812958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:47:16.501423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.851401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:47:16.501547Z","caller":"traceutil/trace.go:171","msg":"trace[1059329044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8858; }","duration":"166.041345ms","start":"2026-07-15T14:47:16.335479Z","end":"2026-07-15T14:47:16.501521Z","steps":["trace[1059329044] 'range keys from in-memory index tree' (duration: 165.720068ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:47:17.492971Z","caller":"traceutil/trace.go:171","msg":"trace[1916781253] transaction","detail":"{read_only:false; response_revision:8864; number_of_response:1; }","duration":"101.566445ms","start":"2026-07-15T14:47:17.391377Z","end":"2026-07-15T14:47:17.492944Z","steps":["trace[1916781253] 'process raft request' (duration: 101.103765ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:48:35.578161Z","caller":"traceutil/trace.go:171","msg":"trace[1530468826] transaction","detail":"{read_only:false; response_revision:9623; number_of_response:1; }","duration":"119.118482ms","start":"2026-07-15T14:48:35.45901Z","end":"2026-07-15T14:48:35.578128Z","steps":["trace[1530468826] 'process raft request' (duration: 103.98279ms)","trace[1530468826] 'compare' (duration: 14.945028ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:48:40.117797Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8bb149ac537fafcb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T14:48:40.122329Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-15T14:48:40.122463Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-15T14:50:48.667174Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8118} {"level":"info","ts":"2026-07-15T14:50:48.732907Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8118,"took":"63.717833ms","hash":3618492921,"current-db-size-bytes":58421248,"current-db-size":"58 MB","current-db-size-in-use-bytes":28409856,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-15T14:50:48.732971Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3618492921,"revision":8118,"compact-revision":6038} {"level":"info","ts":"2026-07-15T14:53:55.308949Z","caller":"traceutil/trace.go:171","msg":"trace[489525090] linearizableReadLoop","detail":"{readStateIndex:12361; appliedIndex:12360; }","duration":"137.157004ms","start":"2026-07-15T14:53:55.171767Z","end":"2026-07-15T14:53:55.308924Z","steps":["trace[489525090] 'read index received' (duration: 136.995231ms)","trace[489525090] 'applied index is now lower than readState.Index' (duration: 160.753µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T14:53:55.309087Z","caller":"traceutil/trace.go:171","msg":"trace[689397468] transaction","detail":"{read_only:false; response_revision:11949; number_of_response:1; }","duration":"209.985273ms","start":"2026-07-15T14:53:55.099062Z","end":"2026-07-15T14:53:55.309047Z","steps":["trace[689397468] 'process raft request' (duration: 209.698017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:53:55.309236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.401989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-15T14:53:55.309539Z","caller":"traceutil/trace.go:171","msg":"trace[1401575114] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11949; }","duration":"137.749055ms","start":"2026-07-15T14:53:55.171733Z","end":"2026-07-15T14:53:55.309482Z","steps":["trace[1401575114] 'agreement among raft nodes before linearized reading' (duration: 137.328957ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:53:55.317616Z","caller":"traceutil/trace.go:171","msg":"trace[1448630437] transaction","detail":"{read_only:false; response_revision:11950; number_of_response:1; }","duration":"109.523223ms","start":"2026-07-15T14:53:55.208075Z","end":"2026-07-15T14:53:55.317598Z","steps":["trace[1448630437] 'process raft request' (duration: 109.36217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:53:55.317917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.858851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:53:55.31797Z","caller":"traceutil/trace.go:171","msg":"trace[1019243088] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11952; }","duration":"145.921242ms","start":"2026-07-15T14:53:55.172037Z","end":"2026-07-15T14:53:55.317958Z","steps":["trace[1019243088] 'agreement among raft nodes before linearized reading' (duration: 145.82092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:53:55.318036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.887692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:53:55.31807Z","caller":"traceutil/trace.go:171","msg":"trace[1559304113] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11952; }","duration":"145.971494ms","start":"2026-07-15T14:53:55.172089Z","end":"2026-07-15T14:53:55.31806Z","steps":["trace[1559304113] 'agreement among raft nodes before linearized reading' (duration: 145.914563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:53:55.318136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.725039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:53:55.318161Z","caller":"traceutil/trace.go:171","msg":"trace[281964758] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11952; }","duration":"145.766249ms","start":"2026-07-15T14:53:55.172388Z","end":"2026-07-15T14:53:55.318154Z","steps":["trace[281964758] 'agreement among raft nodes before linearized reading' (duration: 145.731279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:53:55.318098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.946613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T14:53:55.318203Z","caller":"traceutil/trace.go:171","msg":"trace[1414765564] transaction","detail":"{read_only:false; response_revision:11951; number_of_response:1; }","duration":"107.703099ms","start":"2026-07-15T14:53:55.21049Z","end":"2026-07-15T14:53:55.318193Z","steps":["trace[1414765564] 'process raft request' (duration: 107.070837ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:53:55.318231Z","caller":"traceutil/trace.go:171","msg":"trace[1135407254] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11952; }","duration":"146.101355ms","start":"2026-07-15T14:53:55.172099Z","end":"2026-07-15T14:53:55.3182Z","steps":["trace[1135407254] 'agreement among raft nodes before linearized reading' (duration: 145.939832ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:53:55.318162Z","caller":"traceutil/trace.go:171","msg":"trace[1727240245] transaction","detail":"{read_only:false; response_revision:11952; number_of_response:1; }","duration":"105.421405ms","start":"2026-07-15T14:53:55.212724Z","end":"2026-07-15T14:53:55.318146Z","steps":["trace[1727240245] 'process raft request' (duration: 105.052268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T14:53:55.318673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.414823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-07-15T14:53:55.318724Z","caller":"traceutil/trace.go:171","msg":"trace[1374505747] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11952; }","duration":"144.495595ms","start":"2026-07-15T14:53:55.174213Z","end":"2026-07-15T14:53:55.318709Z","steps":["trace[1374505747] 'agreement among raft nodes before linearized reading' (duration: 143.72279ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:54:15.116461Z","caller":"traceutil/trace.go:171","msg":"trace[1091557219] transaction","detail":"{read_only:false; response_revision:12059; number_of_response:1; }","duration":"106.008425ms","start":"2026-07-15T14:54:15.010407Z","end":"2026-07-15T14:54:15.116416Z","steps":["trace[1091557219] 'process raft request' (duration: 105.656629ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:54:15.732481Z","caller":"traceutil/trace.go:171","msg":"trace[2044784541] transaction","detail":"{read_only:false; response_revision:12067; number_of_response:1; }","duration":"243.287873ms","start":"2026-07-15T14:54:15.489137Z","end":"2026-07-15T14:54:15.732425Z","steps":["trace[2044784541] 'process raft request' (duration: 235.655166ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:54:16.064228Z","caller":"traceutil/trace.go:171","msg":"trace[927487002] transaction","detail":"{read_only:false; response_revision:12068; number_of_response:1; }","duration":"131.258541ms","start":"2026-07-15T14:54:15.932897Z","end":"2026-07-15T14:54:16.064155Z","steps":["trace[927487002] 'process raft request' (duration: 130.974835ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T14:55:48.673718Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10532} {"level":"info","ts":"2026-07-15T14:55:48.753794Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10532,"took":"78.035769ms","hash":2847105429,"current-db-size-bytes":58421248,"current-db-size":"58 MB","current-db-size-in-use-bytes":26669056,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-15T14:55:48.753856Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2847105429,"revision":10532,"compact-revision":8118} {"level":"info","ts":"2026-07-15T15:00:20.863834Z","caller":"traceutil/trace.go:171","msg":"trace[1857549627] transaction","detail":"{read_only:false; response_revision:14532; number_of_response:1; }","duration":"128.880383ms","start":"2026-07-15T15:00:20.734922Z","end":"2026-07-15T15:00:20.863802Z","steps":["trace[1857549627] 'process raft request' (duration: 49.208693ms)","trace[1857549627] 'compare' (duration: 79.421295ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:00:20.863906Z","caller":"traceutil/trace.go:171","msg":"trace[1488547554] transaction","detail":"{read_only:false; response_revision:14533; number_of_response:1; }","duration":"102.960156ms","start":"2026-07-15T15:00:20.760909Z","end":"2026-07-15T15:00:20.863869Z","steps":["trace[1488547554] 'process raft request' (duration: 102.807763ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:00:48.862303Z","caller":"traceutil/trace.go:171","msg":"trace[443983322] linearizableReadLoop","detail":"{readStateIndex:15190; appliedIndex:15189; }","duration":"145.510172ms","start":"2026-07-15T15:00:48.716764Z","end":"2026-07-15T15:00:48.862274Z","steps":["trace[443983322] 'read index received' (duration: 45.774299ms)","trace[443983322] 'applied index is now lower than readState.Index' (duration: 99.734833ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:00:48.862326Z","caller":"traceutil/trace.go:171","msg":"trace[689033092] transaction","detail":"{read_only:false; response_revision:14682; number_of_response:1; }","duration":"186.799463ms","start":"2026-07-15T15:00:48.675485Z","end":"2026-07-15T15:00:48.862284Z","steps":["trace[689033092] 'process raft request' (duration: 86.977629ms)","trace[689033092] 'compare' (duration: 99.688342ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:00:48.862506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.722405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T15:00:48.862542Z","caller":"traceutil/trace.go:171","msg":"trace[28143756] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:14682; }","duration":"145.825398ms","start":"2026-07-15T15:00:48.716705Z","end":"2026-07-15T15:00:48.86253Z","steps":["trace[28143756] 'agreement among raft nodes before linearized reading' (duration: 145.686705ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:00:48.863639Z","caller":"traceutil/trace.go:171","msg":"trace[323212976] transaction","detail":"{read_only:false; response_revision:14683; number_of_response:1; }","duration":"112.358315ms","start":"2026-07-15T15:00:48.751258Z","end":"2026-07-15T15:00:48.863617Z","steps":["trace[323212976] 'process raft request' (duration: 112.225313ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:00:48.866145Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12693} {"level":"info","ts":"2026-07-15T15:00:48.9424Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12693,"took":"73.629232ms","hash":1206891945,"current-db-size-bytes":58421248,"current-db-size":"58 MB","current-db-size-in-use-bytes":27611136,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-15T15:00:48.942459Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1206891945,"revision":12693,"compact-revision":10532} {"level":"info","ts":"2026-07-15T15:01:20.96855Z","caller":"traceutil/trace.go:171","msg":"trace[34471373] linearizableReadLoop","detail":"{readStateIndex:15378; appliedIndex:15377; }","duration":"186.997466ms","start":"2026-07-15T15:01:20.781523Z","end":"2026-07-15T15:01:20.96852Z","steps":["trace[34471373] 'read index received' (duration: 186.12746ms)","trace[34471373] 'applied index is now lower than readState.Index' (duration: 868.286µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:01:20.968731Z","caller":"traceutil/trace.go:171","msg":"trace[1627100339] transaction","detail":"{read_only:false; response_revision:14861; number_of_response:1; }","duration":"300.867931ms","start":"2026-07-15T15:01:20.66782Z","end":"2026-07-15T15:01:20.968688Z","steps":["trace[1627100339] 'process raft request' (duration: 299.954874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:01:20.969098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:01:20.667789Z","time spent":"301.047725ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T15:01:20.970028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.479696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:415370"} {"level":"info","ts":"2026-07-15T15:01:20.970475Z","caller":"traceutil/trace.go:171","msg":"trace[811999723] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:14861; }","duration":"188.665309ms","start":"2026-07-15T15:01:20.781458Z","end":"2026-07-15T15:01:20.970123Z","steps":["trace[811999723] 'agreement among raft nodes before linearized reading' (duration: 187.226051ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:03:08.271499Z","caller":"traceutil/trace.go:171","msg":"trace[726332902] linearizableReadLoop","detail":"{readStateIndex:16528; appliedIndex:16527; }","duration":"107.864259ms","start":"2026-07-15T15:03:08.163612Z","end":"2026-07-15T15:03:08.271477Z","steps":["trace[726332902] 'read index received' (duration: 107.741956ms)","trace[726332902] 'applied index is now lower than readState.Index' (duration: 121.443µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:03:08.271841Z","caller":"traceutil/trace.go:171","msg":"trace[1116480209] transaction","detail":"{read_only:false; response_revision:15975; number_of_response:1; }","duration":"110.58109ms","start":"2026-07-15T15:03:08.161251Z","end":"2026-07-15T15:03:08.271832Z","steps":["trace[1116480209] 'process raft request' (duration: 110.100781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:03:08.272163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.535591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-d9sf2\" ","response":"range_response_count:1 size:22100"} {"level":"info","ts":"2026-07-15T15:03:08.272245Z","caller":"traceutil/trace.go:171","msg":"trace[875111420] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-d9sf2; range_end:; response_count:1; response_revision:15975; }","duration":"108.619612ms","start":"2026-07-15T15:03:08.163585Z","end":"2026-07-15T15:03:08.272205Z","steps":["trace[875111420] 'agreement among raft nodes before linearized reading' (duration: 108.48364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:03:08.503988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.581494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T15:03:08.504407Z","caller":"traceutil/trace.go:171","msg":"trace[2124628950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15976; }","duration":"169.059483ms","start":"2026-07-15T15:03:08.335321Z","end":"2026-07-15T15:03:08.504381Z","steps":["trace[2124628950] 'range keys from in-memory index tree' (duration: 168.474583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:03:08.504052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.006931ms","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-07-15T15:03:08.504686Z","caller":"traceutil/trace.go:171","msg":"trace[986045322] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15976; }","duration":"147.685013ms","start":"2026-07-15T15:03:08.356986Z","end":"2026-07-15T15:03:08.504671Z","steps":["trace[986045322] 'range keys from in-memory index tree' (duration: 146.882988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:03:08.504151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.481221ms","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-07-15T15:03:08.504764Z","caller":"traceutil/trace.go:171","msg":"trace[885055244] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15976; }","duration":"147.132022ms","start":"2026-07-15T15:03:08.357612Z","end":"2026-07-15T15:03:08.504744Z","steps":["trace[885055244] 'range keys from in-memory index tree' (duration: 146.315377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:03:08.50388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.725707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-07-15T15:03:08.504939Z","caller":"traceutil/trace.go:171","msg":"trace[299707055] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15976; }","duration":"144.825629ms","start":"2026-07-15T15:03:08.360102Z","end":"2026-07-15T15:03:08.504928Z","steps":["trace[299707055] 'range keys from in-memory index tree' (duration: 143.548284ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:03:08.848065Z","caller":"traceutil/trace.go:171","msg":"trace[1573648341] linearizableReadLoop","detail":"{readStateIndex:16530; appliedIndex:16529; }","duration":"108.097424ms","start":"2026-07-15T15:03:08.73995Z","end":"2026-07-15T15:03:08.848047Z","steps":["trace[1573648341] 'read index received' (duration: 107.985642ms)","trace[1573648341] 'applied index is now lower than readState.Index' (duration: 110.932µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:03:08.848164Z","caller":"traceutil/trace.go:171","msg":"trace[891073911] transaction","detail":"{read_only:false; response_revision:15977; number_of_response:1; }","duration":"119.85575ms","start":"2026-07-15T15:03:08.728266Z","end":"2026-07-15T15:03:08.848121Z","steps":["trace[891073911] 'process raft request' (duration: 119.656446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:03:08.84828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.305868ms","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-07-15T15:03:08.848318Z","caller":"traceutil/trace.go:171","msg":"trace[85714962] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:15977; }","duration":"108.38016ms","start":"2026-07-15T15:03:08.739927Z","end":"2026-07-15T15:03:08.848307Z","steps":["trace[85714962] 'agreement among raft nodes before linearized reading' (duration: 108.197747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:03:09.079986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.696028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T15:03:09.080073Z","caller":"traceutil/trace.go:171","msg":"trace[1948294194] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:15977; }","duration":"155.868061ms","start":"2026-07-15T15:03:08.924187Z","end":"2026-07-15T15:03:09.080055Z","steps":["trace[1948294194] 'count revisions from in-memory index tree' (duration: 155.613046ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:05:48.873376Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14682} {"level":"info","ts":"2026-07-15T15:05:48.953971Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14682,"took":"76.272754ms","hash":3383205998,"current-db-size-bytes":58421248,"current-db-size":"58 MB","current-db-size-in-use-bytes":31997952,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-15T15:05:48.954032Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3383205998,"revision":14682,"compact-revision":12693} {"level":"warn","ts":"2026-07-15T15:06:10.333276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.55585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T15:06:10.333299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.177503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:06:10.333363Z","caller":"traceutil/trace.go:171","msg":"trace[589556929] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-endpoints; range_end:; response_count:0; response_revision:17470; }","duration":"100.703003ms","start":"2026-07-15T15:06:10.232643Z","end":"2026-07-15T15:06:10.333346Z","steps":["trace[589556929] 'agreement among raft nodes before linearized reading' (duration: 100.560931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:06:10.333388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.618901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:06:10.33339Z","caller":"traceutil/trace.go:171","msg":"trace[1652912465] range","detail":"{range_begin:/registry/jobs/openstack/neutron-db-sync; range_end:; response_count:0; response_revision:17470; }","duration":"100.285715ms","start":"2026-07-15T15:06:10.233082Z","end":"2026-07-15T15:06:10.333368Z","steps":["trace[1652912465] 'agreement among raft nodes before linearized reading' (duration: 100.144072ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:06:10.33343Z","caller":"traceutil/trace.go:171","msg":"trace[1641203026] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:0; response_revision:17470; }","duration":"100.701893ms","start":"2026-07-15T15:06:10.232717Z","end":"2026-07-15T15:06:10.333419Z","steps":["trace[1641203026] 'agreement among raft nodes before linearized reading' (duration: 100.626581ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:06:10.33329Z","caller":"traceutil/trace.go:171","msg":"trace[1206787179] transaction","detail":"{read_only:false; response_revision:17470; number_of_response:1; }","duration":"115.239303ms","start":"2026-07-15T15:06:10.218023Z","end":"2026-07-15T15:06:10.333263Z","steps":["trace[1206787179] 'process raft request' (duration: 115.0845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:06:10.333449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.342026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:06:10.333544Z","caller":"traceutil/trace.go:171","msg":"trace[315015881] range","detail":"{range_begin:/registry/jobs/openstack/neutron-rabbit-init; range_end:; response_count:0; response_revision:17470; }","duration":"100.470009ms","start":"2026-07-15T15:06:10.233056Z","end":"2026-07-15T15:06:10.333526Z","steps":["trace[315015881] 'agreement among raft nodes before linearized reading' (duration: 100.287075ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:09:19.573659Z","caller":"traceutil/trace.go:171","msg":"trace[1410687688] transaction","detail":"{read_only:false; response_revision:18712; number_of_response:1; }","duration":"150.617962ms","start":"2026-07-15T15:09:19.423012Z","end":"2026-07-15T15:09:19.57363Z","steps":["trace[1410687688] 'process raft request' (duration: 150.449378ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:09:20.337856Z","caller":"traceutil/trace.go:171","msg":"trace[587850098] linearizableReadLoop","detail":"{readStateIndex:19360; appliedIndex:19359; }","duration":"211.332647ms","start":"2026-07-15T15:09:20.126496Z","end":"2026-07-15T15:09:20.337829Z","steps":["trace[587850098] 'read index received' (duration: 186.337097ms)","trace[587850098] 'applied index is now lower than readState.Index' (duration: 24.99444ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:09:20.337927Z","caller":"traceutil/trace.go:171","msg":"trace[1824026672] transaction","detail":"{read_only:false; response_revision:18714; number_of_response:1; }","duration":"304.939335ms","start":"2026-07-15T15:09:20.032945Z","end":"2026-07-15T15:09:20.337885Z","steps":["trace[1824026672] 'process raft request' (duration: 279.884403ms)","trace[1824026672] 'compare' (duration: 24.817957ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:09:20.338053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:09:20.032928Z","time spent":"305.051447ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T15:09:20.338107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.940211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-15T15:09:20.338139Z","caller":"traceutil/trace.go:171","msg":"trace[753921899] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18714; }","duration":"185.001852ms","start":"2026-07-15T15:09:20.153127Z","end":"2026-07-15T15:09:20.338128Z","steps":["trace[753921899] 'agreement among raft nodes before linearized reading' (duration: 184.8707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:09:20.33813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.632153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-15T15:09:20.33821Z","caller":"traceutil/trace.go:171","msg":"trace[1951611631] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18714; }","duration":"211.756955ms","start":"2026-07-15T15:09:20.12643Z","end":"2026-07-15T15:09:20.338187Z","steps":["trace[1951611631] 'agreement among raft nodes before linearized reading' (duration: 211.549121ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:09:20.576344Z","caller":"traceutil/trace.go:171","msg":"trace[1629591486] linearizableReadLoop","detail":"{readStateIndex:19362; appliedIndex:19360; }","duration":"183.933411ms","start":"2026-07-15T15:09:20.392369Z","end":"2026-07-15T15:09:20.576302Z","steps":["trace[1629591486] 'read index received' (duration: 13.834996ms)","trace[1629591486] 'applied index is now lower than readState.Index' (duration: 170.096405ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:09:20.576679Z","caller":"traceutil/trace.go:171","msg":"trace[1438524058] transaction","detail":"{read_only:false; response_revision:18716; number_of_response:1; }","duration":"234.688956ms","start":"2026-07-15T15:09:20.341951Z","end":"2026-07-15T15:09:20.576639Z","steps":["trace[1438524058] 'process raft request' (duration: 208.038494ms)","trace[1438524058] 'compare' (duration: 26.127462ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:09:20.576777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.35683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T15:09:20.576865Z","caller":"traceutil/trace.go:171","msg":"trace[322938624] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:18716; }","duration":"184.536633ms","start":"2026-07-15T15:09:20.392307Z","end":"2026-07-15T15:09:20.576844Z","steps":["trace[322938624] 'agreement among raft nodes before linearized reading' (duration: 184.335309ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:10:09.066685Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8bb149ac537fafcb","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T15:10:09.07563Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-15T15:10:09.075746Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-15T15:10:48.884055Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17166} {"level":"info","ts":"2026-07-15T15:10:48.979717Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17166,"took":"90.399787ms","hash":837277583,"current-db-size-bytes":58421248,"current-db-size":"58 MB","current-db-size-in-use-bytes":33869824,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-15T15:10:48.979777Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":837277583,"revision":17166,"compact-revision":14682} {"level":"info","ts":"2026-07-15T15:15:08.206826Z","caller":"traceutil/trace.go:171","msg":"trace[587742488] transaction","detail":"{read_only:false; response_revision:21432; number_of_response:1; }","duration":"161.409119ms","start":"2026-07-15T15:15:08.045383Z","end":"2026-07-15T15:15:08.206792Z","steps":["trace[587742488] 'process raft request' (duration: 137.238075ms)","trace[587742488] 'compare' (duration: 24.029252ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:15:30.176062Z","caller":"traceutil/trace.go:171","msg":"trace[1672076823] transaction","detail":"{read_only:false; response_revision:21554; number_of_response:1; }","duration":"102.010068ms","start":"2026-07-15T15:15:30.074027Z","end":"2026-07-15T15:15:30.176037Z","steps":["trace[1672076823] 'process raft request' (duration: 101.817374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:15:30.463659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.139283ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T15:15:30.46377Z","caller":"traceutil/trace.go:171","msg":"trace[1261889720] linearizableReadLoop","detail":"{readStateIndex:22293; appliedIndex:22291; }","duration":"298.820587ms","start":"2026-07-15T15:15:30.164932Z","end":"2026-07-15T15:15:30.463752Z","steps":["trace[1261889720] 'read index received' (duration: 10.994381ms)","trace[1261889720] 'applied index is now lower than readState.Index' (duration: 287.825096ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:15:30.463935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.009661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-15T15:15:30.463885Z","caller":"traceutil/trace.go:171","msg":"trace[1414572851] transaction","detail":"{read_only:false; response_revision:21555; number_of_response:1; }","duration":"383.135177ms","start":"2026-07-15T15:15:30.080696Z","end":"2026-07-15T15:15:30.463832Z","steps":["trace[1414572851] 'process raft request' (duration: 121.726088ms)","trace[1414572851] 'compare' (duration: 261.013791ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:15:30.463999Z","caller":"traceutil/trace.go:171","msg":"trace[186638285] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21555; }","duration":"299.108383ms","start":"2026-07-15T15:15:30.164881Z","end":"2026-07-15T15:15:30.463989Z","steps":["trace[186638285] 'agreement among raft nodes before linearized reading' (duration: 298.942489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:15:30.464156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:15:30.08068Z","time spent":"383.359651ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T15:15:30.739215Z","caller":"traceutil/trace.go:171","msg":"trace[1859495899] transaction","detail":"{read_only:false; response_revision:21556; number_of_response:1; }","duration":"403.462326ms","start":"2026-07-15T15:15:30.335728Z","end":"2026-07-15T15:15:30.739191Z","steps":["trace[1859495899] 'process raft request' (duration: 303.732172ms)","trace[1859495899] 'compare' (duration: 99.625152ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:15:30.739357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:15:30.335707Z","time spent":"403.572819ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T15:15:30.754074Z","caller":"traceutil/trace.go:171","msg":"trace[2078598695] linearizableReadLoop","detail":"{readStateIndex:22295; appliedIndex:22293; }","duration":"290.255462ms","start":"2026-07-15T15:15:30.463795Z","end":"2026-07-15T15:15:30.75405Z","steps":["trace[2078598695] 'read index received' (duration: 175.643593ms)","trace[2078598695] 'applied index is now lower than readState.Index' (duration: 114.610599ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:15:30.754229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.818774ms","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":"warn","ts":"2026-07-15T15:15:30.754294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.475735ms","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-07-15T15:15:30.754302Z","caller":"traceutil/trace.go:171","msg":"trace[1614101370] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:21558; }","duration":"427.934456ms","start":"2026-07-15T15:15:30.326344Z","end":"2026-07-15T15:15:30.754278Z","steps":["trace[1614101370] 'agreement among raft nodes before linearized reading' (duration: 427.790514ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:15:30.754315Z","caller":"traceutil/trace.go:171","msg":"trace[1632422502] transaction","detail":"{read_only:false; response_revision:21558; number_of_response:1; }","duration":"285.626874ms","start":"2026-07-15T15:15:30.468658Z","end":"2026-07-15T15:15:30.754285Z","steps":["trace[1632422502] 'process raft request' (duration: 285.341987ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:15:30.754353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:15:30.326317Z","time spent":"428.023168ms","remote":"127.0.0.1:57312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-07-15T15:15:30.754339Z","caller":"traceutil/trace.go:171","msg":"trace[1658249694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21558; }","duration":"443.571436ms","start":"2026-07-15T15:15:30.310756Z","end":"2026-07-15T15:15:30.754328Z","steps":["trace[1658249694] 'agreement among raft nodes before linearized reading' (duration: 443.422794ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:15:30.754358Z","caller":"traceutil/trace.go:171","msg":"trace[1578955998] transaction","detail":"{read_only:false; response_revision:21557; number_of_response:1; }","duration":"406.169049ms","start":"2026-07-15T15:15:30.348075Z","end":"2026-07-15T15:15:30.754244Z","steps":["trace[1578955998] 'process raft request' (duration: 405.791761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:15:30.754543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.805509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:15:30.75459Z","caller":"traceutil/trace.go:171","msg":"trace[657065376] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:21558; }","duration":"183.85638ms","start":"2026-07-15T15:15:30.570721Z","end":"2026-07-15T15:15:30.754577Z","steps":["trace[657065376] 'agreement among raft nodes before linearized reading' (duration: 183.658156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:15:30.754342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.176972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-15T15:15:30.7547Z","caller":"traceutil/trace.go:171","msg":"trace[1165641663] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21558; }","duration":"352.577129ms","start":"2026-07-15T15:15:30.402105Z","end":"2026-07-15T15:15:30.754682Z","steps":["trace[1165641663] 'agreement among raft nodes before linearized reading' (duration: 352.133681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:15:30.754756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:15:30.402091Z","time spent":"352.643701ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-15T15:15:30.754801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.657404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T15:15:30.754839Z","caller":"traceutil/trace.go:171","msg":"trace[510560905] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21558; }","duration":"322.745346ms","start":"2026-07-15T15:15:30.432083Z","end":"2026-07-15T15:15:30.754828Z","steps":["trace[510560905] 'agreement among raft nodes before linearized reading' (duration: 321.990041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:15:30.754872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:15:30.432057Z","time spent":"322.808058ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-15T15:15:30.754985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:15:30.34806Z","time spent":"406.498615ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T15:15:30.755028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.875091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:15:30.755293Z","caller":"traceutil/trace.go:171","msg":"trace[1824587288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21558; }","duration":"420.85694ms","start":"2026-07-15T15:15:30.334422Z","end":"2026-07-15T15:15:30.755279Z","steps":["trace[1824587288] 'agreement among raft nodes before linearized reading' (duration: 419.678947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:15:30.755345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:15:30.334408Z","time spent":"420.926881ms","remote":"127.0.0.1:43478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-15T15:15:30.755306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:15:30.310733Z","time spent":"443.859032ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-15T15:15:30.893935Z","caller":"traceutil/trace.go:171","msg":"trace[1473326232] transaction","detail":"{read_only:false; response_revision:21559; number_of_response:1; }","duration":"134.305028ms","start":"2026-07-15T15:15:30.759601Z","end":"2026-07-15T15:15:30.893906Z","steps":["trace[1473326232] 'process raft request' (duration: 134.125105ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:15:31.05875Z","caller":"traceutil/trace.go:171","msg":"trace[291982828] transaction","detail":"{read_only:false; response_revision:21560; number_of_response:1; }","duration":"299.089373ms","start":"2026-07-15T15:15:30.759625Z","end":"2026-07-15T15:15:31.058714Z","steps":["trace[291982828] 'process raft request' (duration: 244.551356ms)","trace[291982828] 'compare' (duration: 54.16409ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:15:31.059476Z","caller":"traceutil/trace.go:171","msg":"trace[1599948702] linearizableReadLoop","detail":"{readStateIndex:22299; appliedIndex:22296; }","duration":"186.222085ms","start":"2026-07-15T15:15:30.873234Z","end":"2026-07-15T15:15:31.059456Z","steps":["trace[1599948702] 'read index received' (duration: 20.616405ms)","trace[1599948702] 'applied index is now lower than readState.Index' (duration: 165.60481ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:15:31.060646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.388487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T15:15:31.060744Z","caller":"traceutil/trace.go:171","msg":"trace[2018237427] transaction","detail":"{read_only:false; response_revision:21561; number_of_response:1; }","duration":"300.955598ms","start":"2026-07-15T15:15:30.759747Z","end":"2026-07-15T15:15:31.060702Z","steps":["trace[2018237427] 'process raft request' (duration: 299.587241ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:15:31.060948Z","caller":"traceutil/trace.go:171","msg":"trace[787946125] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:21561; }","duration":"187.641942ms","start":"2026-07-15T15:15:30.873163Z","end":"2026-07-15T15:15:31.060805Z","steps":["trace[787946125] 'agreement among raft nodes before linearized reading' (duration: 186.428289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:15:31.060964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:15:30.759738Z","time spent":"301.121051ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T15:15:31.424478Z","caller":"traceutil/trace.go:171","msg":"trace[1756443717] linearizableReadLoop","detail":"{readStateIndex:22300; appliedIndex:22299; }","duration":"179.157049ms","start":"2026-07-15T15:15:31.2453Z","end":"2026-07-15T15:15:31.424457Z","steps":["trace[1756443717] 'read index received' (duration: 178.896684ms)","trace[1756443717] 'applied index is now lower than readState.Index' (duration: 259.455µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:15:31.424578Z","caller":"traceutil/trace.go:171","msg":"trace[1118924701] transaction","detail":"{read_only:false; response_revision:21562; number_of_response:1; }","duration":"289.770205ms","start":"2026-07-15T15:15:31.134755Z","end":"2026-07-15T15:15:31.424525Z","steps":["trace[1118924701] 'process raft request' (duration: 289.4991ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:15:31.424638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.332823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18261"} {"level":"info","ts":"2026-07-15T15:15:31.424667Z","caller":"traceutil/trace.go:171","msg":"trace[158769809] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:21562; }","duration":"179.423465ms","start":"2026-07-15T15:15:31.245235Z","end":"2026-07-15T15:15:31.424659Z","steps":["trace[158769809] 'agreement among raft nodes before linearized reading' (duration: 179.300783ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:15:48.889823Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19643} {"level":"info","ts":"2026-07-15T15:15:49.003474Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19643,"took":"110.044893ms","hash":2194376309,"current-db-size-bytes":58421248,"current-db-size":"58 MB","current-db-size-in-use-bytes":29184000,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-15T15:15:49.003545Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2194376309,"revision":19643,"compact-revision":17166} {"level":"info","ts":"2026-07-15T15:19:13.944857Z","caller":"traceutil/trace.go:171","msg":"trace[974890694] transaction","detail":"{read_only:false; response_revision:23429; number_of_response:1; }","duration":"119.018356ms","start":"2026-07-15T15:19:13.825813Z","end":"2026-07-15T15:19:13.944832Z","steps":["trace[974890694] 'process raft request' (duration: 118.865443ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:20:48.895807Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21658} {"level":"info","ts":"2026-07-15T15:20:49.00019Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21658,"took":"101.767412ms","hash":2117436183,"current-db-size-bytes":58421248,"current-db-size":"58 MB","current-db-size-in-use-bytes":43589632,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-07-15T15:20:49.000256Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2117436183,"revision":21658,"compact-revision":19643} {"level":"info","ts":"2026-07-15T15:21:41.737845Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000666d.wal"} {"level":"info","ts":"2026-07-15T15:23:06.380941Z","caller":"traceutil/trace.go:171","msg":"trace[88677068] linearizableReadLoop","detail":"{readStateIndex:27068; appliedIndex:27067; }","duration":"112.218682ms","start":"2026-07-15T15:23:06.268676Z","end":"2026-07-15T15:23:06.380894Z","steps":["trace[88677068] 'read index received' (duration: 96.201965ms)","trace[88677068] 'applied index is now lower than readState.Index' (duration: 16.014377ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:23:06.38131Z","caller":"traceutil/trace.go:171","msg":"trace[223892027] transaction","detail":"{read_only:false; response_revision:26201; number_of_response:1; }","duration":"245.908675ms","start":"2026-07-15T15:23:06.135383Z","end":"2026-07-15T15:23:06.381292Z","steps":["trace[223892027] 'process raft request' (duration: 229.455909ms)","trace[223892027] 'compare' (duration: 15.282774ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:23:06.381638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.931615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:23:06.38169Z","caller":"traceutil/trace.go:171","msg":"trace[1058437894] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:26201; }","duration":"113.536887ms","start":"2026-07-15T15:23:06.268134Z","end":"2026-07-15T15:23:06.381671Z","steps":["trace[1058437894] 'agreement among raft nodes before linearized reading' (duration: 113.410804ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:07.450499Z","caller":"traceutil/trace.go:171","msg":"trace[470434213] transaction","detail":"{read_only:false; response_revision:26213; number_of_response:1; }","duration":"105.613825ms","start":"2026-07-15T15:23:07.344852Z","end":"2026-07-15T15:23:07.450466Z","steps":["trace[470434213] 'process raft request' (duration: 105.472342ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:12.730503Z","caller":"traceutil/trace.go:171","msg":"trace[1028422066] transaction","detail":"{read_only:false; response_revision:26258; number_of_response:1; }","duration":"157.042822ms","start":"2026-07-15T15:23:12.573434Z","end":"2026-07-15T15:23:12.730476Z","steps":["trace[1028422066] 'process raft request' (duration: 156.872338ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:12.97823Z","caller":"traceutil/trace.go:171","msg":"trace[1869365596] transaction","detail":"{read_only:false; response_revision:26260; number_of_response:1; }","duration":"400.574112ms","start":"2026-07-15T15:23:12.577634Z","end":"2026-07-15T15:23:12.978208Z","steps":["trace[1869365596] 'process raft request' (duration: 400.519931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:12.978383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:12.57762Z","time spent":"400.687334ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T15:23:12.978696Z","caller":"traceutil/trace.go:171","msg":"trace[599452724] transaction","detail":"{read_only:false; response_revision:26259; number_of_response:1; }","duration":"404.630249ms","start":"2026-07-15T15:23:12.574055Z","end":"2026-07-15T15:23:12.978685Z","steps":["trace[599452724] 'process raft request' (duration: 403.976757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:12.979126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:12.574036Z","time spent":"404.693631ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T15:23:13.034006Z","caller":"traceutil/trace.go:171","msg":"trace[1573698539] transaction","detail":"{read_only:false; response_revision:26261; number_of_response:1; }","duration":"268.786384ms","start":"2026-07-15T15:23:12.765176Z","end":"2026-07-15T15:23:13.033962Z","steps":["trace[1573698539] 'process raft request' (duration: 268.592591ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:13.034329Z","caller":"traceutil/trace.go:171","msg":"trace[1221195767] transaction","detail":"{read_only:false; response_revision:26262; number_of_response:1; }","duration":"216.40916ms","start":"2026-07-15T15:23:12.817844Z","end":"2026-07-15T15:23:13.034254Z","steps":["trace[1221195767] 'process raft request' (duration: 216.063253ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:13.229426Z","caller":"traceutil/trace.go:171","msg":"trace[1553739591] transaction","detail":"{read_only:false; response_revision:26264; number_of_response:1; }","duration":"116.79403ms","start":"2026-07-15T15:23:13.112612Z","end":"2026-07-15T15:23:13.229406Z","steps":["trace[1553739591] 'process raft request' (duration: 116.663307ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:13.274687Z","caller":"traceutil/trace.go:171","msg":"trace[451082432] transaction","detail":"{read_only:false; response_revision:26265; number_of_response:1; }","duration":"158.619023ms","start":"2026-07-15T15:23:13.116047Z","end":"2026-07-15T15:23:13.274666Z","steps":["trace[451082432] 'process raft request' (duration: 158.512351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:13.469196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.244023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:23:13.469267Z","caller":"traceutil/trace.go:171","msg":"trace[801500138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26265; }","duration":"135.358736ms","start":"2026-07-15T15:23:13.333893Z","end":"2026-07-15T15:23:13.469252Z","steps":["trace[801500138] 'range keys from in-memory index tree' (duration: 135.170522ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:13.592673Z","caller":"traceutil/trace.go:171","msg":"trace[1960492131] transaction","detail":"{read_only:false; response_revision:26266; number_of_response:1; }","duration":"118.795039ms","start":"2026-07-15T15:23:13.473843Z","end":"2026-07-15T15:23:13.592638Z","steps":["trace[1960492131] 'process raft request' (duration: 118.576224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:13.592804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.285964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-15T15:23:13.592788Z","caller":"traceutil/trace.go:171","msg":"trace[1411462799] linearizableReadLoop","detail":"{readStateIndex:27135; appliedIndex:27134; }","duration":"112.047168ms","start":"2026-07-15T15:23:13.480504Z","end":"2026-07-15T15:23:13.592551Z","steps":["trace[1411462799] 'read index received' (duration: 111.850173ms)","trace[1411462799] 'applied index is now lower than readState.Index' (duration: 196.125µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:23:13.592842Z","caller":"traceutil/trace.go:171","msg":"trace[1309355339] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26266; }","duration":"112.367305ms","start":"2026-07-15T15:23:13.480465Z","end":"2026-07-15T15:23:13.592832Z","steps":["trace[1309355339] 'agreement among raft nodes before linearized reading' (duration: 112.250903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:13.868089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.946817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T15:23:13.868334Z","caller":"traceutil/trace.go:171","msg":"trace[1800795711] transaction","detail":"{read_only:false; response_revision:26267; number_of_response:1; }","duration":"271.400715ms","start":"2026-07-15T15:23:13.596912Z","end":"2026-07-15T15:23:13.868313Z","steps":["trace[1800795711] 'process raft request' (duration: 122.108102ms)","trace[1800795711] 'compare' (duration: 148.497758ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:23:13.868614Z","caller":"traceutil/trace.go:171","msg":"trace[823492971] transaction","detail":"{read_only:false; response_revision:26268; number_of_response:1; }","duration":"195.617892ms","start":"2026-07-15T15:23:13.67295Z","end":"2026-07-15T15:23:13.868568Z","steps":["trace[823492971] 'process raft request' (duration: 195.237345ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:14.313066Z","caller":"traceutil/trace.go:171","msg":"trace[1520051621] transaction","detail":"{read_only:false; response_revision:26270; number_of_response:1; }","duration":"250.589666ms","start":"2026-07-15T15:23:14.062458Z","end":"2026-07-15T15:23:14.313048Z","steps":["trace[1520051621] 'process raft request' (duration: 250.487485ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:14.324847Z","caller":"traceutil/trace.go:171","msg":"trace[367854498] transaction","detail":"{read_only:false; response_revision:26272; number_of_response:1; }","duration":"169.851888ms","start":"2026-07-15T15:23:14.154975Z","end":"2026-07-15T15:23:14.324827Z","steps":["trace[367854498] 'process raft request' (duration: 169.619183ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:14.324875Z","caller":"traceutil/trace.go:171","msg":"trace[418744185] transaction","detail":"{read_only:false; response_revision:26271; number_of_response:1; }","duration":"219.248754ms","start":"2026-07-15T15:23:14.105572Z","end":"2026-07-15T15:23:14.32482Z","steps":["trace[418744185] 'process raft request' (duration: 218.815546ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:15.164776Z","caller":"traceutil/trace.go:171","msg":"trace[843265889] transaction","detail":"{read_only:false; response_revision:26275; number_of_response:1; }","duration":"180.274808ms","start":"2026-07-15T15:23:14.984475Z","end":"2026-07-15T15:23:15.16475Z","steps":["trace[843265889] 'process raft request' (duration: 180.102844ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:15.189554Z","caller":"traceutil/trace.go:171","msg":"trace[518656306] transaction","detail":"{read_only:false; response_revision:26278; number_of_response:1; }","duration":"150.257702ms","start":"2026-07-15T15:23:15.039271Z","end":"2026-07-15T15:23:15.189529Z","steps":["trace[518656306] 'process raft request' (duration: 150.220522ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:15.189579Z","caller":"traceutil/trace.go:171","msg":"trace[1835448649] transaction","detail":"{read_only:false; response_revision:26277; number_of_response:1; }","duration":"150.63641ms","start":"2026-07-15T15:23:15.038916Z","end":"2026-07-15T15:23:15.189552Z","steps":["trace[1835448649] 'process raft request' (duration: 150.522548ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:15.189594Z","caller":"traceutil/trace.go:171","msg":"trace[870798835] transaction","detail":"{read_only:false; response_revision:26276; number_of_response:1; }","duration":"203.091046ms","start":"2026-07-15T15:23:14.986452Z","end":"2026-07-15T15:23:15.189543Z","steps":["trace[870798835] 'process raft request' (duration: 202.83667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:20.493991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.050257ms","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-07-15T15:23:20.494057Z","caller":"traceutil/trace.go:171","msg":"trace[1321104220] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:26325; }","duration":"332.17338ms","start":"2026-07-15T15:23:20.161871Z","end":"2026-07-15T15:23:20.494044Z","steps":["trace[1321104220] 'count revisions from in-memory index tree' (duration: 331.968586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:20.494082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:20.161848Z","time spent":"332.228201ms","remote":"127.0.0.1:54230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true "} {"level":"warn","ts":"2026-07-15T15:23:20.49455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.345411ms","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-07-15T15:23:20.494584Z","caller":"traceutil/trace.go:171","msg":"trace[1085598437] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:26325; }","duration":"165.418003ms","start":"2026-07-15T15:23:20.32916Z","end":"2026-07-15T15:23:20.494578Z","steps":["trace[1085598437] 'count revisions from in-memory index tree' (duration: 165.251599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:20.494664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.804142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:23:20.494676Z","caller":"traceutil/trace.go:171","msg":"trace[714626054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26325; }","duration":"161.840212ms","start":"2026-07-15T15:23:20.332832Z","end":"2026-07-15T15:23:20.494672Z","steps":["trace[714626054] 'range keys from in-memory index tree' (duration: 161.753471ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:20.495272Z","caller":"traceutil/trace.go:171","msg":"trace[1375506521] transaction","detail":"{read_only:false; response_revision:26326; number_of_response:1; }","duration":"146.969779ms","start":"2026-07-15T15:23:20.348296Z","end":"2026-07-15T15:23:20.495266Z","steps":["trace[1375506521] 'process raft request' (duration: 146.895317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:20.931906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.812803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T15:23:20.932143Z","caller":"traceutil/trace.go:171","msg":"trace[1046491982] transaction","detail":"{read_only:false; response_revision:26327; number_of_response:1; }","duration":"583.047002ms","start":"2026-07-15T15:23:20.349057Z","end":"2026-07-15T15:23:20.932104Z","steps":["trace[1046491982] 'process raft request' (duration: 299.84528ms)","trace[1046491982] 'compare' (duration: 282.688571ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:23:20.932248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.143824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:23:20.93228Z","caller":"traceutil/trace.go:171","msg":"trace[1606288254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26328; }","duration":"425.204414ms","start":"2026-07-15T15:23:20.507068Z","end":"2026-07-15T15:23:20.932272Z","steps":["trace[1606288254] 'agreement among raft nodes before linearized reading' (duration: 425.149703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:20.932298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:20.50705Z","time spent":"425.244315ms","remote":"127.0.0.1:43486","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-07-15T15:23:20.932158Z","caller":"traceutil/trace.go:171","msg":"trace[1985071493] transaction","detail":"{read_only:false; response_revision:26328; number_of_response:1; }","duration":"392.12738ms","start":"2026-07-15T15:23:20.540013Z","end":"2026-07-15T15:23:20.932141Z","steps":["trace[1985071493] 'process raft request' (duration: 392.020457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:20.932294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:20.349043Z","time spent":"583.176744ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T15:23:20.932356Z","caller":"traceutil/trace.go:171","msg":"trace[312236348] linearizableReadLoop","detail":"{readStateIndex:27197; appliedIndex:27196; }","duration":"425.041942ms","start":"2026-07-15T15:23:20.507097Z","end":"2026-07-15T15:23:20.932139Z","steps":["trace[312236348] 'read index received' (duration: 141.918771ms)","trace[312236348] 'applied index is now lower than readState.Index' (duration: 283.117271ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:23:20.932423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:20.539992Z","time spent":"392.347945ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T15:23:21.309603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.224201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T15:23:21.309779Z","caller":"traceutil/trace.go:171","msg":"trace[842329180] linearizableReadLoop","detail":"{readStateIndex:27199; appliedIndex:27198; }","duration":"377.300705ms","start":"2026-07-15T15:23:20.932463Z","end":"2026-07-15T15:23:21.309763Z","steps":["trace[842329180] 'read index received' (duration: 142.782048ms)","trace[842329180] 'applied index is now lower than readState.Index' (duration: 234.517717ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:23:21.309785Z","caller":"traceutil/trace.go:171","msg":"trace[125831745] transaction","detail":"{read_only:false; response_revision:26329; number_of_response:1; }","duration":"496.623954ms","start":"2026-07-15T15:23:20.813128Z","end":"2026-07-15T15:23:21.309751Z","steps":["trace[125831745] 'process raft request' (duration: 262.165108ms)","trace[125831745] 'compare' (duration: 233.979937ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:23:21.310087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:20.813108Z","time spent":"496.881328ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T15:23:21.310194Z","caller":"traceutil/trace.go:171","msg":"trace[1892665188] transaction","detail":"{read_only:false; response_revision:26330; number_of_response:1; }","duration":"126.699129ms","start":"2026-07-15T15:23:21.183443Z","end":"2026-07-15T15:23:21.310142Z","steps":["trace[1892665188] 'process raft request' (duration: 126.245681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:21.310381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.307571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T15:23:21.310642Z","caller":"traceutil/trace.go:171","msg":"trace[494043445] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26330; }","duration":"457.530024ms","start":"2026-07-15T15:23:20.852943Z","end":"2026-07-15T15:23:21.310473Z","steps":["trace[494043445] 'agreement among raft nodes before linearized reading' (duration: 456.897231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:21.310532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.491352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T15:23:21.310811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.112967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-07-15T15:23:21.310726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:20.852922Z","time spent":"457.78479ms","remote":"127.0.0.1:57258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-07-15T15:23:21.310862Z","caller":"traceutil/trace.go:171","msg":"trace[1993818446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26330; }","duration":"784.180028ms","start":"2026-07-15T15:23:20.526668Z","end":"2026-07-15T15:23:21.310848Z","steps":["trace[1993818446] 'agreement among raft nodes before linearized reading' (duration: 783.201189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:21.311007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.212575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418324"} {"level":"info","ts":"2026-07-15T15:23:21.311038Z","caller":"traceutil/trace.go:171","msg":"trace[245869096] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:26330; }","duration":"534.279997ms","start":"2026-07-15T15:23:20.776749Z","end":"2026-07-15T15:23:21.311029Z","steps":["trace[245869096] 'agreement among raft nodes before linearized reading' (duration: 533.103844ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:21.311044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:20.526656Z","time spent":"784.372183ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T15:23:21.311072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:20.776726Z","time spent":"534.339667ms","remote":"127.0.0.1:50478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":418347,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"info","ts":"2026-07-15T15:23:21.310913Z","caller":"traceutil/trace.go:171","msg":"trace[621707441] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:26330; }","duration":"741.009282ms","start":"2026-07-15T15:23:20.5698Z","end":"2026-07-15T15:23:21.31081Z","steps":["trace[621707441] 'agreement among raft nodes before linearized reading' (duration: 740.060723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:21.311167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:20.56977Z","time spent":"741.378598ms","remote":"127.0.0.1:57362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"info","ts":"2026-07-15T15:23:21.339831Z","caller":"traceutil/trace.go:171","msg":"trace[574889723] transaction","detail":"{read_only:false; response_revision:26331; number_of_response:1; }","duration":"128.81595ms","start":"2026-07-15T15:23:21.210998Z","end":"2026-07-15T15:23:21.339814Z","steps":["trace[574889723] 'process raft request' (duration: 128.616107ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:21.340049Z","caller":"traceutil/trace.go:171","msg":"trace[272590557] transaction","detail":"{read_only:false; response_revision:26332; number_of_response:1; }","duration":"128.581876ms","start":"2026-07-15T15:23:21.21145Z","end":"2026-07-15T15:23:21.340032Z","steps":["trace[272590557] 'process raft request' (duration: 128.27224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:21.340074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.404329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T15:23:21.3401Z","caller":"traceutil/trace.go:171","msg":"trace[126353709] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:26333; }","duration":"229.474231ms","start":"2026-07-15T15:23:21.110618Z","end":"2026-07-15T15:23:21.340092Z","steps":["trace[126353709] 'agreement among raft nodes before linearized reading' (duration: 229.345008ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:21.340131Z","caller":"traceutil/trace.go:171","msg":"trace[731368669] transaction","detail":"{read_only:false; response_revision:26333; number_of_response:1; }","duration":"124.106789ms","start":"2026-07-15T15:23:21.216017Z","end":"2026-07-15T15:23:21.340123Z","steps":["trace[731368669] 'process raft request' (duration: 123.745862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:21.340122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.650666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T15:23:21.340179Z","caller":"traceutil/trace.go:171","msg":"trace[1926396294] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:26333; }","duration":"276.749198ms","start":"2026-07-15T15:23:21.063415Z","end":"2026-07-15T15:23:21.340164Z","steps":["trace[1926396294] 'agreement among raft nodes before linearized reading' (duration: 276.628156ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:21.733397Z","caller":"traceutil/trace.go:171","msg":"trace[2041235853] linearizableReadLoop","detail":"{readStateIndex:27204; appliedIndex:27203; }","duration":"393.575518ms","start":"2026-07-15T15:23:21.339789Z","end":"2026-07-15T15:23:21.733365Z","steps":["trace[2041235853] 'read index received' (duration: 393.399534ms)","trace[2041235853] 'applied index is now lower than readState.Index' (duration: 174.564µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:23:21.733482Z","caller":"traceutil/trace.go:171","msg":"trace[930215988] transaction","detail":"{read_only:false; response_revision:26334; number_of_response:1; }","duration":"418.21874ms","start":"2026-07-15T15:23:21.315216Z","end":"2026-07-15T15:23:21.733435Z","steps":["trace[930215988] 'process raft request' (duration: 417.923814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:21.733564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.91464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:23:21.733638Z","caller":"traceutil/trace.go:171","msg":"trace[1066861214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26334; }","duration":"399.028363ms","start":"2026-07-15T15:23:21.334591Z","end":"2026-07-15T15:23:21.733619Z","steps":["trace[1066861214] 'agreement among raft nodes before linearized reading' (duration: 398.85191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:21.733679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:21.334573Z","time spent":"399.098614ms","remote":"127.0.0.1:43478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-15T15:23:21.733724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.951131ms","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-07-15T15:23:21.733756Z","caller":"traceutil/trace.go:171","msg":"trace[1894801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26334; }","duration":"423.052463ms","start":"2026-07-15T15:23:21.310696Z","end":"2026-07-15T15:23:21.733748Z","steps":["trace[1894801] 'agreement among raft nodes before linearized reading' (duration: 422.824269ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:21.73379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:21.31068Z","time spent":"423.103394ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-15T15:23:21.73394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:21.3152Z","time spent":"418.360414ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T15:23:21.899073Z","caller":"traceutil/trace.go:171","msg":"trace[1231023323] transaction","detail":"{read_only:false; response_revision:26335; number_of_response:1; }","duration":"554.85195ms","start":"2026-07-15T15:23:21.34419Z","end":"2026-07-15T15:23:21.899042Z","steps":["trace[1231023323] 'process raft request' (duration: 529.958623ms)","trace[1231023323] 'compare' (duration: 24.626912ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:23:21.89922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:21.344176Z","time spent":"554.972842ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T15:23:21.899793Z","caller":"traceutil/trace.go:171","msg":"trace[875131205] linearizableReadLoop","detail":"{readStateIndex:27206; appliedIndex:27204; }","duration":"166.289719ms","start":"2026-07-15T15:23:21.733483Z","end":"2026-07-15T15:23:21.899772Z","steps":["trace[875131205] 'read index received' (duration: 140.76713ms)","trace[875131205] 'applied index is now lower than readState.Index' (duration: 25.521449ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:23:21.899849Z","caller":"traceutil/trace.go:171","msg":"trace[2099125099] transaction","detail":"{read_only:false; response_revision:26336; number_of_response:1; }","duration":"242.062091ms","start":"2026-07-15T15:23:21.657775Z","end":"2026-07-15T15:23:21.899837Z","steps":["trace[2099125099] 'process raft request' (duration: 241.748185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:21.900033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.230505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T15:23:21.900091Z","caller":"traceutil/trace.go:171","msg":"trace[641161119] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26336; }","duration":"268.328686ms","start":"2026-07-15T15:23:21.63175Z","end":"2026-07-15T15:23:21.900078Z","steps":["trace[641161119] 'agreement among raft nodes before linearized reading' (duration: 268.139222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:21.900127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.537353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:23:21.900206Z","caller":"traceutil/trace.go:171","msg":"trace[121721322] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:26336; }","duration":"543.667556ms","start":"2026-07-15T15:23:21.356521Z","end":"2026-07-15T15:23:21.900188Z","steps":["trace[121721322] 'agreement among raft nodes before linearized reading' (duration: 543.511053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:21.900252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:21.356501Z","time spent":"543.741397ms","remote":"127.0.0.1:36102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-15T15:23:21.979394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.715673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:23:21.979602Z","caller":"traceutil/trace.go:171","msg":"trace[29024349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26337; }","duration":"244.970928ms","start":"2026-07-15T15:23:21.734604Z","end":"2026-07-15T15:23:21.979575Z","steps":["trace[29024349] 'agreement among raft nodes before linearized reading' (duration: 244.661902ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:21.979682Z","caller":"traceutil/trace.go:171","msg":"trace[145264339] transaction","detail":"{read_only:false; response_revision:26337; number_of_response:1; }","duration":"242.359288ms","start":"2026-07-15T15:23:21.737292Z","end":"2026-07-15T15:23:21.979651Z","steps":["trace[145264339] 'process raft request' (duration: 241.709745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:21.979826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.084023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/securitygroups/\" range_end:\"/registry/openstack.k-orc.cloud/securitygroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:23:21.979953Z","caller":"traceutil/trace.go:171","msg":"trace[1988825484] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/securitygroups/; range_end:/registry/openstack.k-orc.cloud/securitygroups0; response_count:0; response_revision:26337; }","duration":"132.298608ms","start":"2026-07-15T15:23:21.847634Z","end":"2026-07-15T15:23:21.979932Z","steps":["trace[1988825484] 'agreement among raft nodes before linearized reading' (duration: 131.904029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:21.980072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.895958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.231\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-15T15:23:21.980106Z","caller":"traceutil/trace.go:171","msg":"trace[211680328] range","detail":"{range_begin:/registry/masterleases/199.19.213.231; range_end:; response_count:1; response_revision:26337; }","duration":"182.98304ms","start":"2026-07-15T15:23:21.797115Z","end":"2026-07-15T15:23:21.980098Z","steps":["trace[211680328] 'agreement among raft nodes before linearized reading' (duration: 182.884408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:22.27425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.925123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T15:23:22.274584Z","caller":"traceutil/trace.go:171","msg":"trace[1510249270] transaction","detail":"{read_only:false; response_revision:26339; number_of_response:1; }","duration":"290.614194ms","start":"2026-07-15T15:23:21.983948Z","end":"2026-07-15T15:23:22.274562Z","steps":["trace[1510249270] 'process raft request' (duration: 290.515832ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:22.274545Z","caller":"traceutil/trace.go:171","msg":"trace[871942106] transaction","detail":"{read_only:false; response_revision:26338; number_of_response:1; }","duration":"370.428595ms","start":"2026-07-15T15:23:21.904064Z","end":"2026-07-15T15:23:22.274493Z","steps":["trace[871942106] 'process raft request' (duration: 108.170765ms)","trace[871942106] 'compare' (duration: 261.569286ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:23:22.274896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:21.904038Z","time spent":"370.720571ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T15:23:22.300717Z","caller":"traceutil/trace.go:171","msg":"trace[1596738826] transaction","detail":"{read_only:false; response_revision:26340; number_of_response:1; }","duration":"247.904814ms","start":"2026-07-15T15:23:22.05279Z","end":"2026-07-15T15:23:22.300695Z","steps":["trace[1596738826] 'process raft request' (duration: 247.585998ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:31.538474Z","caller":"traceutil/trace.go:171","msg":"trace[927320051] transaction","detail":"{read_only:false; response_revision:26417; number_of_response:1; }","duration":"195.015771ms","start":"2026-07-15T15:23:31.34342Z","end":"2026-07-15T15:23:31.538435Z","steps":["trace[927320051] 'process raft request' (duration: 148.329515ms)","trace[927320051] 'compare' (duration: 46.39581ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:23:31.783862Z","caller":"traceutil/trace.go:171","msg":"trace[1378054285] linearizableReadLoop","detail":"{readStateIndex:27292; appliedIndex:27289; }","duration":"205.198055ms","start":"2026-07-15T15:23:31.578647Z","end":"2026-07-15T15:23:31.783845Z","steps":["trace[1378054285] 'read index received' (duration: 168.91931ms)","trace[1378054285] 'applied index is now lower than readState.Index' (duration: 36.278075ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:23:31.783866Z","caller":"traceutil/trace.go:171","msg":"trace[1020830343] transaction","detail":"{read_only:false; response_revision:26418; number_of_response:1; }","duration":"409.507033ms","start":"2026-07-15T15:23:31.374329Z","end":"2026-07-15T15:23:31.783836Z","steps":["trace[1020830343] 'process raft request' (duration: 373.212417ms)","trace[1020830343] 'compare' (duration: 36.044632ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:23:31.784026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:31.374298Z","time spent":"409.633196ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T15:23:31.784297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.645543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:23:31.784332Z","caller":"traceutil/trace.go:171","msg":"trace[145403886] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:26420; }","duration":"205.737735ms","start":"2026-07-15T15:23:31.578586Z","end":"2026-07-15T15:23:31.784324Z","steps":["trace[145403886] 'agreement among raft nodes before linearized reading' (duration: 205.659274ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:31.784465Z","caller":"traceutil/trace.go:171","msg":"trace[1510117269] transaction","detail":"{read_only:false; response_revision:26420; number_of_response:1; }","duration":"405.438305ms","start":"2026-07-15T15:23:31.378996Z","end":"2026-07-15T15:23:31.784435Z","steps":["trace[1510117269] 'process raft request' (duration: 404.810353ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:31.784079Z","caller":"traceutil/trace.go:171","msg":"trace[1429839089] transaction","detail":"{read_only:false; response_revision:26419; number_of_response:1; }","duration":"406.457585ms","start":"2026-07-15T15:23:31.377612Z","end":"2026-07-15T15:23:31.784069Z","steps":["trace[1429839089] 'process raft request' (duration: 406.110408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:31.784654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:31.377599Z","time spent":"406.999936ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T15:23:31.784714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:31.378985Z","time spent":"405.526866ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T15:23:31.844596Z","caller":"traceutil/trace.go:171","msg":"trace[346840962] transaction","detail":"{read_only:false; response_revision:26421; number_of_response:1; }","duration":"180.687616ms","start":"2026-07-15T15:23:31.663884Z","end":"2026-07-15T15:23:31.844572Z","steps":["trace[346840962] 'process raft request' (duration: 180.517613ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:32.079625Z","caller":"traceutil/trace.go:171","msg":"trace[1152579621] linearizableReadLoop","detail":"{readStateIndex:27296; appliedIndex:27295; }","duration":"134.022701ms","start":"2026-07-15T15:23:31.945578Z","end":"2026-07-15T15:23:32.079601Z","steps":["trace[1152579621] 'read index received' (duration: 133.814827ms)","trace[1152579621] 'applied index is now lower than readState.Index' (duration: 206.784µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:23:32.079751Z","caller":"traceutil/trace.go:171","msg":"trace[695410893] transaction","detail":"{read_only:false; response_revision:26423; number_of_response:1; }","duration":"146.931848ms","start":"2026-07-15T15:23:31.932808Z","end":"2026-07-15T15:23:32.07974Z","steps":["trace[695410893] 'process raft request' (duration: 146.653263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:32.080372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.773455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-15T15:23:32.080424Z","caller":"traceutil/trace.go:171","msg":"trace[650489914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26423; }","duration":"134.861586ms","start":"2026-07-15T15:23:31.945549Z","end":"2026-07-15T15:23:32.080411Z","steps":["trace[650489914] 'agreement among raft nodes before linearized reading' (duration: 134.736343ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:32.267544Z","caller":"traceutil/trace.go:171","msg":"trace[90962523] transaction","detail":"{read_only:false; response_revision:26424; number_of_response:1; }","duration":"183.341506ms","start":"2026-07-15T15:23:32.084165Z","end":"2026-07-15T15:23:32.267506Z","steps":["trace[90962523] 'process raft request' (duration: 97.746564ms)","trace[90962523] 'compare' (duration: 85.413518ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:23:32.405575Z","caller":"traceutil/trace.go:171","msg":"trace[440911130] linearizableReadLoop","detail":"{readStateIndex:27298; appliedIndex:27296; }","duration":"320.605438ms","start":"2026-07-15T15:23:32.08494Z","end":"2026-07-15T15:23:32.405546Z","steps":["trace[440911130] 'read index received' (duration: 96.948129ms)","trace[440911130] 'applied index is now lower than readState.Index' (duration: 223.655899ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:23:32.405733Z","caller":"traceutil/trace.go:171","msg":"trace[12817015] transaction","detail":"{read_only:false; response_revision:26425; number_of_response:1; }","duration":"321.088427ms","start":"2026-07-15T15:23:32.0846Z","end":"2026-07-15T15:23:32.405688Z","steps":["trace[12817015] 'process raft request' (duration: 320.712189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:32.405839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.861773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-15T15:23:32.405872Z","caller":"traceutil/trace.go:171","msg":"trace[1358975563] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:26425; }","duration":"320.927754ms","start":"2026-07-15T15:23:32.084934Z","end":"2026-07-15T15:23:32.405862Z","steps":["trace[1358975563] 'agreement among raft nodes before linearized reading' (duration: 320.74689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:32.40591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:32.084893Z","time spent":"321.009986ms","remote":"127.0.0.1:43538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-07-15T15:23:32.405919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:32.08457Z","time spent":"321.25548ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T15:23:32.690526Z","caller":"traceutil/trace.go:171","msg":"trace[1553224210] linearizableReadLoop","detail":"{readStateIndex:27299; appliedIndex:27298; }","duration":"284.841573ms","start":"2026-07-15T15:23:32.40565Z","end":"2026-07-15T15:23:32.690492Z","steps":["trace[1553224210] 'read index received' (duration: 224.795521ms)","trace[1553224210] 'applied index is now lower than readState.Index' (duration: 60.045042ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:23:32.690602Z","caller":"traceutil/trace.go:171","msg":"trace[1242039400] transaction","detail":"{read_only:false; response_revision:26426; number_of_response:1; }","duration":"353.035141ms","start":"2026-07-15T15:23:32.337498Z","end":"2026-07-15T15:23:32.690533Z","steps":["trace[1242039400] 'process raft request' (duration: 292.976908ms)","trace[1242039400] 'compare' (duration: 59.684606ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:23:32.690663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.79874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-07-15T15:23:32.690707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.028024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T15:23:32.690733Z","caller":"traceutil/trace.go:171","msg":"trace[889966980] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26426; }","duration":"374.095164ms","start":"2026-07-15T15:23:32.31663Z","end":"2026-07-15T15:23:32.690725Z","steps":["trace[889966980] 'agreement among raft nodes before linearized reading' (duration: 373.968342ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:32.690735Z","caller":"traceutil/trace.go:171","msg":"trace[1672283854] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26426; }","duration":"372.914371ms","start":"2026-07-15T15:23:32.317802Z","end":"2026-07-15T15:23:32.690716Z","steps":["trace[1672283854] 'agreement among raft nodes before linearized reading' (duration: 372.733658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:32.690763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:32.316613Z","time spent":"374.144456ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-15T15:23:32.690769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:32.317774Z","time spent":"372.989773ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-15T15:23:32.690858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:32.337479Z","time spent":"353.248734ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T15:23:32.712446Z","caller":"traceutil/trace.go:171","msg":"trace[1732731827] transaction","detail":"{read_only:false; response_revision:26427; number_of_response:1; }","duration":"161.048439ms","start":"2026-07-15T15:23:32.55136Z","end":"2026-07-15T15:23:32.712408Z","steps":["trace[1732731827] 'process raft request' (duration: 160.832245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:32.712488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.404748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T15:23:32.712546Z","caller":"traceutil/trace.go:171","msg":"trace[1519165582] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:26427; }","duration":"304.49044ms","start":"2026-07-15T15:23:32.408042Z","end":"2026-07-15T15:23:32.712532Z","steps":["trace[1519165582] 'agreement among raft nodes before linearized reading' (duration: 304.298736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:32.712581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:32.408024Z","time spent":"304.54959ms","remote":"127.0.0.1:43766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-07-15T15:23:32.712621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.342876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:23:32.712743Z","caller":"traceutil/trace.go:171","msg":"trace[1943873558] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:26427; }","duration":"232.54515ms","start":"2026-07-15T15:23:32.48017Z","end":"2026-07-15T15:23:32.712715Z","steps":["trace[1943873558] 'agreement among raft nodes before linearized reading' (duration: 232.320546ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:33.632144Z","caller":"traceutil/trace.go:171","msg":"trace[681857364] transaction","detail":"{read_only:false; response_revision:26431; number_of_response:1; }","duration":"264.054464ms","start":"2026-07-15T15:23:33.368057Z","end":"2026-07-15T15:23:33.632112Z","steps":["trace[681857364] 'process raft request' (duration: 263.85185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:33.883623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.840314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T15:23:33.883814Z","caller":"traceutil/trace.go:171","msg":"trace[828410938] transaction","detail":"{read_only:false; response_revision:26432; number_of_response:1; }","duration":"340.710294ms","start":"2026-07-15T15:23:33.54308Z","end":"2026-07-15T15:23:33.883791Z","steps":["trace[828410938] 'process raft request' (duration: 203.613205ms)","trace[828410938] 'compare' (duration: 136.640091ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:23:33.883867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:33.543056Z","time spent":"340.790096ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T15:23:43.160353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.090665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T15:23:43.160435Z","caller":"traceutil/trace.go:171","msg":"trace[1149388317] transaction","detail":"{read_only:false; response_revision:26565; number_of_response:1; }","duration":"341.601271ms","start":"2026-07-15T15:23:42.818812Z","end":"2026-07-15T15:23:43.160414Z","steps":["trace[1149388317] 'process raft request' (duration: 212.398483ms)","trace[1149388317] 'compare' (duration: 128.743109ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:23:43.160478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:42.818801Z","time spent":"341.661262ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T15:23:43.164329Z","caller":"traceutil/trace.go:171","msg":"trace[1089312501] linearizableReadLoop","detail":"{readStateIndex:27442; appliedIndex:27440; }","duration":"304.437679ms","start":"2026-07-15T15:23:42.859871Z","end":"2026-07-15T15:23:43.164309Z","steps":["trace[1089312501] 'read index received' (duration: 171.351846ms)","trace[1089312501] 'applied index is now lower than readState.Index' (duration: 133.084793ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:23:43.164424Z","caller":"traceutil/trace.go:171","msg":"trace[450897168] transaction","detail":"{read_only:false; response_revision:26566; number_of_response:1; }","duration":"343.467547ms","start":"2026-07-15T15:23:42.820901Z","end":"2026-07-15T15:23:43.164368Z","steps":["trace[450897168] 'process raft request' (duration: 343.307494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:43.164604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:42.820892Z","time spent":"343.61828ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T15:23:43.165115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.231144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:23:43.165167Z","caller":"traceutil/trace.go:171","msg":"trace[861161379] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:26566; }","duration":"305.306435ms","start":"2026-07-15T15:23:42.859846Z","end":"2026-07-15T15:23:43.165153Z","steps":["trace[861161379] 'agreement among raft nodes before linearized reading' (duration: 304.546281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:43.165201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:42.85981Z","time spent":"305.383107ms","remote":"127.0.0.1:39884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true "} {"level":"warn","ts":"2026-07-15T15:23:43.185697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.969548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-20bfd025-8e01-42fe-a6a7-30b097457f7b\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-07-15T15:23:43.185756Z","caller":"traceutil/trace.go:171","msg":"trace[46655948] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-20bfd025-8e01-42fe-a6a7-30b097457f7b; range_end:; response_count:1; response_revision:26567; }","duration":"294.05586ms","start":"2026-07-15T15:23:42.891684Z","end":"2026-07-15T15:23:43.18574Z","steps":["trace[46655948] 'agreement among raft nodes before linearized reading' (duration: 293.855946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:43.185922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.967262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-20bfd025-8e01-42fe-a6a7-30b097457f7b\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-07-15T15:23:43.185985Z","caller":"traceutil/trace.go:171","msg":"trace[1347942351] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-20bfd025-8e01-42fe-a6a7-30b097457f7b; range_end:; response_count:1; response_revision:26567; }","duration":"288.222107ms","start":"2026-07-15T15:23:42.897749Z","end":"2026-07-15T15:23:43.185971Z","steps":["trace[1347942351] 'agreement among raft nodes before linearized reading' (duration: 287.85507ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:43.341557Z","caller":"traceutil/trace.go:171","msg":"trace[1805760655] linearizableReadLoop","detail":"{readStateIndex:27444; appliedIndex:27443; }","duration":"112.60517ms","start":"2026-07-15T15:23:43.228924Z","end":"2026-07-15T15:23:43.341529Z","steps":["trace[1805760655] 'read index received' (duration: 112.216322ms)","trace[1805760655] 'applied index is now lower than readState.Index' (duration: 387.688µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:23:43.341629Z","caller":"traceutil/trace.go:171","msg":"trace[330623500] transaction","detail":"{read_only:false; response_revision:26568; number_of_response:1; }","duration":"148.57917ms","start":"2026-07-15T15:23:43.193022Z","end":"2026-07-15T15:23:43.341601Z","steps":["trace[330623500] 'process raft request' (duration: 148.271444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:43.341726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.791923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:23:43.341771Z","caller":"traceutil/trace.go:171","msg":"trace[1288962260] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:26568; }","duration":"112.880615ms","start":"2026-07-15T15:23:43.228879Z","end":"2026-07-15T15:23:43.34176Z","steps":["trace[1288962260] 'agreement among raft nodes before linearized reading' (duration: 112.790483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:44.55523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.85379ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T15:23:44.555501Z","caller":"traceutil/trace.go:171","msg":"trace[1857269728] transaction","detail":"{read_only:false; response_revision:26572; number_of_response:1; }","duration":"638.343031ms","start":"2026-07-15T15:23:43.917118Z","end":"2026-07-15T15:23:44.555461Z","steps":["trace[1857269728] 'process raft request' (duration: 325.106424ms)","trace[1857269728] 'compare' (duration: 312.29642ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:23:44.555612Z","caller":"traceutil/trace.go:171","msg":"trace[2041423468] transaction","detail":"{read_only:false; response_revision:26576; number_of_response:1; }","duration":"225.643087ms","start":"2026-07-15T15:23:44.329953Z","end":"2026-07-15T15:23:44.555596Z","steps":["trace[2041423468] 'process raft request' (duration: 225.606566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:44.55573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:43.917091Z","time spent":"638.545336ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T15:23:44.555812Z","caller":"traceutil/trace.go:171","msg":"trace[70197618] transaction","detail":"{read_only:false; response_revision:26573; number_of_response:1; }","duration":"638.060906ms","start":"2026-07-15T15:23:43.917742Z","end":"2026-07-15T15:23:44.555803Z","steps":["trace[70197618] 'process raft request' (duration: 637.634588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:44.555873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:43.917702Z","time spent":"638.135887ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T15:23:44.555957Z","caller":"traceutil/trace.go:171","msg":"trace[1570863993] transaction","detail":"{read_only:false; response_revision:26574; number_of_response:1; }","duration":"635.70007ms","start":"2026-07-15T15:23:43.920249Z","end":"2026-07-15T15:23:44.555949Z","steps":["trace[1570863993] 'process raft request' (duration: 635.21208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:44.556046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:43.920239Z","time spent":"635.781983ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T15:23:44.556269Z","caller":"traceutil/trace.go:171","msg":"trace[437601977] linearizableReadLoop","detail":"{readStateIndex:27450; appliedIndex:27446; }","duration":"623.493897ms","start":"2026-07-15T15:23:43.932762Z","end":"2026-07-15T15:23:44.556256Z","steps":["trace[437601977] 'read index received' (duration: 66.994005ms)","trace[437601977] 'applied index is now lower than readState.Index' (duration: 556.496182ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:23:44.556327Z","caller":"traceutil/trace.go:171","msg":"trace[1989774199] transaction","detail":"{read_only:false; response_revision:26575; number_of_response:1; }","duration":"579.926991ms","start":"2026-07-15T15:23:43.976367Z","end":"2026-07-15T15:23:44.556294Z","steps":["trace[1989774199] 'process raft request' (duration: 579.129935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:44.556434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.467745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:23:44.556468Z","caller":"traceutil/trace.go:171","msg":"trace[1360084491] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0; response_count:0; response_revision:26576; }","duration":"540.547956ms","start":"2026-07-15T15:23:44.015909Z","end":"2026-07-15T15:23:44.556457Z","steps":["trace[1360084491] 'agreement among raft nodes before linearized reading' (duration: 540.435224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:44.556479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:43.97632Z","time spent":"580.078304ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T15:23:44.5565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:44.015888Z","time spent":"540.603638ms","remote":"127.0.0.1:54558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusteridentities0\" count_only:true "} {"level":"warn","ts":"2026-07-15T15:23:44.556521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.748493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-07-15T15:23:44.556587Z","caller":"traceutil/trace.go:171","msg":"trace[578982128] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:26576; }","duration":"623.845454ms","start":"2026-07-15T15:23:43.932726Z","end":"2026-07-15T15:23:44.556572Z","steps":["trace[578982128] 'agreement among raft nodes before linearized reading' (duration: 623.59227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:44.556555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.438346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-15T15:23:44.55662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:43.932713Z","time spent":"623.900445ms","remote":"127.0.0.1:43614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1335,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"warn","ts":"2026-07-15T15:23:44.556598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.04982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-15T15:23:44.556603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.621443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-20bfd025-8e01-42fe-a6a7-30b097457f7b\" ","response":"range_response_count:1 size:3764"} {"level":"warn","ts":"2026-07-15T15:23:44.556635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.934845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-15T15:23:44.556654Z","caller":"traceutil/trace.go:171","msg":"trace[633317686] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:26576; }","duration":"538.57983ms","start":"2026-07-15T15:23:44.018053Z","end":"2026-07-15T15:23:44.556632Z","steps":["trace[633317686] 'agreement among raft nodes before linearized reading' (duration: 538.403346ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:44.556671Z","caller":"traceutil/trace.go:171","msg":"trace[1467120721] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-20bfd025-8e01-42fe-a6a7-30b097457f7b; range_end:; response_count:1; response_revision:26576; }","duration":"365.728104ms","start":"2026-07-15T15:23:44.190926Z","end":"2026-07-15T15:23:44.556654Z","steps":["trace[1467120721] 'agreement among raft nodes before linearized reading' (duration: 365.50844ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:44.55667Z","caller":"traceutil/trace.go:171","msg":"trace[1813479317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26576; }","duration":"524.146512ms","start":"2026-07-15T15:23:44.032502Z","end":"2026-07-15T15:23:44.556648Z","steps":["trace[1813479317] 'agreement among raft nodes before linearized reading' (duration: 523.946198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:44.556706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:44.018035Z","time spent":"538.660131ms","remote":"127.0.0.1:43968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":8,"response size":31,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-15T15:23:44.556714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:44.190904Z","time spent":"365.802046ms","remote":"127.0.0.1:43684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3787,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-20bfd025-8e01-42fe-a6a7-30b097457f7b\" "} {"level":"info","ts":"2026-07-15T15:23:44.556708Z","caller":"traceutil/trace.go:171","msg":"trace[484642330] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26576; }","duration":"139.046197ms","start":"2026-07-15T15:23:44.417641Z","end":"2026-07-15T15:23:44.556687Z","steps":["trace[484642330] 'agreement among raft nodes before linearized reading' (duration: 138.804012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:44.556721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:44.032488Z","time spent":"524.222123ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-15T15:23:50.804774Z","caller":"traceutil/trace.go:171","msg":"trace[1280571394] transaction","detail":"{read_only:false; response_revision:26656; number_of_response:1; }","duration":"240.592174ms","start":"2026-07-15T15:23:50.564157Z","end":"2026-07-15T15:23:50.80475Z","steps":["trace[1280571394] 'process raft request' (duration: 240.244927ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:51.098777Z","caller":"traceutil/trace.go:171","msg":"trace[1731837818] transaction","detail":"{read_only:false; response_revision:26657; number_of_response:1; }","duration":"522.625073ms","start":"2026-07-15T15:23:50.576128Z","end":"2026-07-15T15:23:51.098753Z","steps":["trace[1731837818] 'process raft request' (duration: 522.454369ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:51.099073Z","caller":"traceutil/trace.go:171","msg":"trace[408330137] linearizableReadLoop","detail":"{readStateIndex:27536; appliedIndex:27532; }","duration":"512.06374ms","start":"2026-07-15T15:23:50.586997Z","end":"2026-07-15T15:23:51.099061Z","steps":["trace[408330137] 'read index received' (duration: 217.612633ms)","trace[408330137] 'applied index is now lower than readState.Index' (duration: 294.450237ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:23:51.099139Z","caller":"traceutil/trace.go:171","msg":"trace[1500212269] transaction","detail":"{read_only:false; response_revision:26659; number_of_response:1; }","duration":"517.861472ms","start":"2026-07-15T15:23:50.581269Z","end":"2026-07-15T15:23:51.09913Z","steps":["trace[1500212269] 'process raft request' (duration: 517.650318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:51.099217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:50.581245Z","time spent":"517.911432ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T15:23:51.099273Z","caller":"traceutil/trace.go:171","msg":"trace[788051946] transaction","detail":"{read_only:false; response_revision:26661; number_of_response:1; }","duration":"305.069261ms","start":"2026-07-15T15:23:50.794176Z","end":"2026-07-15T15:23:51.099245Z","steps":["trace[788051946] 'process raft request' (duration: 304.845267ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:51.099276Z","caller":"traceutil/trace.go:171","msg":"trace[1491178037] transaction","detail":"{read_only:false; response_revision:26660; number_of_response:1; }","duration":"351.807876ms","start":"2026-07-15T15:23:50.747449Z","end":"2026-07-15T15:23:51.099257Z","steps":["trace[1491178037] 'process raft request' (duration: 351.529491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:51.099341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:50.794167Z","time spent":"305.137472ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T15:23:51.099385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.393456ms","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-07-15T15:23:51.099051Z","caller":"traceutil/trace.go:171","msg":"trace[1991563135] transaction","detail":"{read_only:false; response_revision:26658; number_of_response:1; }","duration":"518.979983ms","start":"2026-07-15T15:23:50.580042Z","end":"2026-07-15T15:23:51.099022Z","steps":["trace[1991563135] 'process raft request' (duration: 518.679337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:51.099404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:50.747424Z","time spent":"351.905968ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T15:23:51.099426Z","caller":"traceutil/trace.go:171","msg":"trace[2061397003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26661; }","duration":"512.476328ms","start":"2026-07-15T15:23:50.586939Z","end":"2026-07-15T15:23:51.099416Z","steps":["trace[2061397003] 'agreement among raft nodes before linearized reading' (duration: 512.365606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:51.099457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:50.586926Z","time spent":"512.523599ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-15T15:23:51.09949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:50.580014Z","time spent":"519.427452ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T15:23:51.099627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:50.576113Z","time spent":"522.732885ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T15:23:51.128982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.577707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:23:51.129024Z","caller":"traceutil/trace.go:171","msg":"trace[926524919] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:26662; }","duration":"272.659719ms","start":"2026-07-15T15:23:50.856349Z","end":"2026-07-15T15:23:51.129009Z","steps":["trace[926524919] 'agreement among raft nodes before linearized reading' (duration: 272.588878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:51.12909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.571268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-15T15:23:51.129141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.104917ms","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-07-15T15:23:51.129191Z","caller":"traceutil/trace.go:171","msg":"trace[85656100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26662; }","duration":"541.183798ms","start":"2026-07-15T15:23:50.587996Z","end":"2026-07-15T15:23:51.12918Z","steps":["trace[85656100] 'agreement among raft nodes before linearized reading' (duration: 541.001485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:51.129222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:50.587989Z","time spent":"541.227769ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T15:23:51.129236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.88119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:23:51.129296Z","caller":"traceutil/trace.go:171","msg":"trace[295693523] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:26662; }","duration":"325.946201ms","start":"2026-07-15T15:23:50.803341Z","end":"2026-07-15T15:23:51.129288Z","steps":["trace[295693523] 'agreement among raft nodes before linearized reading' (duration: 325.574584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:51.129327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:50.803262Z","time spent":"326.057333ms","remote":"127.0.0.1:33344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true "} {"level":"info","ts":"2026-07-15T15:23:51.129471Z","caller":"traceutil/trace.go:171","msg":"trace[1297503385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26662; }","duration":"539.643139ms","start":"2026-07-15T15:23:50.589489Z","end":"2026-07-15T15:23:51.129132Z","steps":["trace[1297503385] 'agreement among raft nodes before linearized reading' (duration: 539.482726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:51.129564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:50.589485Z","time spent":"540.067937ms","remote":"127.0.0.1:43754","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":"info","ts":"2026-07-15T15:23:51.256285Z","caller":"traceutil/trace.go:171","msg":"trace[1222765313] transaction","detail":"{read_only:false; response_revision:26663; number_of_response:1; }","duration":"151.17257ms","start":"2026-07-15T15:23:51.105093Z","end":"2026-07-15T15:23:51.256266Z","steps":["trace[1222765313] 'process raft request' (duration: 137.593179ms)","trace[1222765313] 'compare' (duration: 13.429478ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:23:51.25647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.308106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-07-15T15:23:51.256535Z","caller":"traceutil/trace.go:171","msg":"trace[724508573] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26663; }","duration":"133.411188ms","start":"2026-07-15T15:23:51.123108Z","end":"2026-07-15T15:23:51.256519Z","steps":["trace[724508573] 'agreement among raft nodes before linearized reading' (duration: 133.223514ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:51.256275Z","caller":"traceutil/trace.go:171","msg":"trace[1129160133] linearizableReadLoop","detail":"{readStateIndex:27540; appliedIndex:27539; }","duration":"127.391713ms","start":"2026-07-15T15:23:51.128859Z","end":"2026-07-15T15:23:51.25625Z","steps":["trace[1129160133] 'read index received' (duration: 113.832712ms)","trace[1129160133] 'applied index is now lower than readState.Index' (duration: 13.557701ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:23:51.256691Z","caller":"traceutil/trace.go:171","msg":"trace[2021388807] transaction","detail":"{read_only:false; response_revision:26665; number_of_response:1; }","duration":"122.866655ms","start":"2026-07-15T15:23:51.133802Z","end":"2026-07-15T15:23:51.256669Z","steps":["trace[2021388807] 'process raft request' (duration: 122.800214ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:51.256722Z","caller":"traceutil/trace.go:171","msg":"trace[1128354903] transaction","detail":"{read_only:false; response_revision:26664; number_of_response:1; }","duration":"123.179602ms","start":"2026-07-15T15:23:51.133517Z","end":"2026-07-15T15:23:51.256696Z","steps":["trace[1128354903] 'process raft request' (duration: 123.027229ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:52.305715Z","caller":"traceutil/trace.go:171","msg":"trace[369919382] linearizableReadLoop","detail":"{readStateIndex:27550; appliedIndex:27549; }","duration":"227.899791ms","start":"2026-07-15T15:23:52.077793Z","end":"2026-07-15T15:23:52.305693Z","steps":["trace[369919382] 'read index received' (duration: 227.800599ms)","trace[369919382] 'applied index is now lower than readState.Index' (duration: 98.262µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:23:52.305816Z","caller":"traceutil/trace.go:171","msg":"trace[413893170] transaction","detail":"{read_only:false; response_revision:26672; number_of_response:1; }","duration":"231.45115ms","start":"2026-07-15T15:23:52.074342Z","end":"2026-07-15T15:23:52.305793Z","steps":["trace[413893170] 'process raft request' (duration: 231.198955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:52.305954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.141435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-07-15T15:23:52.305997Z","caller":"traceutil/trace.go:171","msg":"trace[987136376] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26672; }","duration":"228.212406ms","start":"2026-07-15T15:23:52.077772Z","end":"2026-07-15T15:23:52.305984Z","steps":["trace[987136376] 'agreement among raft nodes before linearized reading' (duration: 228.035493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:52.400474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.947763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:23:52.400489Z","caller":"traceutil/trace.go:171","msg":"trace[558296063] transaction","detail":"{read_only:false; response_revision:26673; number_of_response:1; }","duration":"312.736638ms","start":"2026-07-15T15:23:52.087713Z","end":"2026-07-15T15:23:52.400449Z","steps":["trace[558296063] 'process raft request' (duration: 312.449833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:52.400531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.107059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T15:23:52.400561Z","caller":"traceutil/trace.go:171","msg":"trace[84689490] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:26673; }","duration":"130.087165ms","start":"2026-07-15T15:23:52.270455Z","end":"2026-07-15T15:23:52.400542Z","steps":["trace[84689490] 'agreement among raft nodes before linearized reading' (duration: 129.927212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:52.400543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.162482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:23:52.400585Z","caller":"traceutil/trace.go:171","msg":"trace[978195294] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26673; }","duration":"284.212401ms","start":"2026-07-15T15:23:52.116359Z","end":"2026-07-15T15:23:52.400572Z","steps":["trace[978195294] 'agreement among raft nodes before linearized reading' (duration: 284.106689ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:52.400626Z","caller":"traceutil/trace.go:171","msg":"trace[1748362924] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:26673; }","duration":"123.270274ms","start":"2026-07-15T15:23:52.277335Z","end":"2026-07-15T15:23:52.400605Z","steps":["trace[1748362924] 'agreement among raft nodes before linearized reading' (duration: 123.04264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:52.400667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:23:52.087683Z","time spent":"312.888161ms","remote":"127.0.0.1:43684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10631,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T15:23:52.400789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.18114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:23:52.401231Z","caller":"traceutil/trace.go:171","msg":"trace[437506124] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:26673; }","duration":"235.678229ms","start":"2026-07-15T15:23:52.165531Z","end":"2026-07-15T15:23:52.401209Z","steps":["trace[437506124] 'agreement among raft nodes before linearized reading' (duration: 235.18543ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:53.221836Z","caller":"traceutil/trace.go:171","msg":"trace[1641006457] transaction","detail":"{read_only:false; response_revision:26677; number_of_response:1; }","duration":"116.495804ms","start":"2026-07-15T15:23:53.105307Z","end":"2026-07-15T15:23:53.221802Z","steps":["trace[1641006457] 'process raft request' (duration: 115.963264ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:53.222161Z","caller":"traceutil/trace.go:171","msg":"trace[1375065091] transaction","detail":"{read_only:false; response_revision:26679; number_of_response:1; }","duration":"115.393822ms","start":"2026-07-15T15:23:53.106747Z","end":"2026-07-15T15:23:53.222141Z","steps":["trace[1375065091] 'process raft request' (duration: 115.201799ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:53.222074Z","caller":"traceutil/trace.go:171","msg":"trace[516314252] linearizableReadLoop","detail":"{readStateIndex:27558; appliedIndex:27553; }","duration":"105.737098ms","start":"2026-07-15T15:23:53.116312Z","end":"2026-07-15T15:23:53.222049Z","steps":["trace[516314252] 'read index received' (duration: 2.678611ms)","trace[516314252] 'applied index is now lower than readState.Index' (duration: 103.057397ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:23:53.222223Z","caller":"traceutil/trace.go:171","msg":"trace[1576277987] transaction","detail":"{read_only:false; response_revision:26680; number_of_response:1; }","duration":"115.044816ms","start":"2026-07-15T15:23:53.107149Z","end":"2026-07-15T15:23:53.222194Z","steps":["trace[1576277987] 'process raft request' (duration: 114.845273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:53.222387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.059125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-cluster-api-proxy-rg8pb\" ","response":"range_response_count:1 size:4966"} {"level":"info","ts":"2026-07-15T15:23:53.222194Z","caller":"traceutil/trace.go:171","msg":"trace[643755759] transaction","detail":"{read_only:false; response_revision:26678; number_of_response:1; }","duration":"116.717899ms","start":"2026-07-15T15:23:53.105447Z","end":"2026-07-15T15:23:53.222165Z","steps":["trace[643755759] 'process raft request' (duration: 116.404213ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:53.22244Z","caller":"traceutil/trace.go:171","msg":"trace[1434910555] range","detail":"{range_begin:/registry/pods/openstack/magnum-cluster-api-proxy-rg8pb; range_end:; response_count:1; response_revision:26680; }","duration":"106.163126ms","start":"2026-07-15T15:23:53.116267Z","end":"2026-07-15T15:23:53.22243Z","steps":["trace[1434910555] 'agreement among raft nodes before linearized reading' (duration: 105.991633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:53.222496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.996873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-cluster-api-proxy-rg8pb.18c27fdc6d6a6ed8\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-07-15T15:23:53.222564Z","caller":"traceutil/trace.go:171","msg":"trace[381510704] range","detail":"{range_begin:/registry/events/openstack/magnum-cluster-api-proxy-rg8pb.18c27fdc6d6a6ed8; range_end:; response_count:1; response_revision:26680; }","duration":"106.120655ms","start":"2026-07-15T15:23:53.116426Z","end":"2026-07-15T15:23:53.222546Z","steps":["trace[381510704] 'agreement among raft nodes before linearized reading' (duration: 105.917231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:53.222627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.791389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T15:23:53.222698Z","caller":"traceutil/trace.go:171","msg":"trace[291661899] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:26680; }","duration":"105.888161ms","start":"2026-07-15T15:23:53.116795Z","end":"2026-07-15T15:23:53.222683Z","steps":["trace[291661899] 'agreement among raft nodes before linearized reading' (duration: 105.774688ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:57.283312Z","caller":"traceutil/trace.go:171","msg":"trace[765410309] transaction","detail":"{read_only:false; response_revision:26709; number_of_response:1; }","duration":"146.966868ms","start":"2026-07-15T15:23:57.136325Z","end":"2026-07-15T15:23:57.283292Z","steps":["trace[765410309] 'process raft request' (duration: 146.817746ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:57.444347Z","caller":"traceutil/trace.go:171","msg":"trace[985478094] transaction","detail":"{read_only:false; response_revision:26710; number_of_response:1; }","duration":"246.577389ms","start":"2026-07-15T15:23:57.197739Z","end":"2026-07-15T15:23:57.444316Z","steps":["trace[985478094] 'process raft request' (duration: 246.303894ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:57.444376Z","caller":"traceutil/trace.go:171","msg":"trace[513740366] transaction","detail":"{read_only:false; response_revision:26711; number_of_response:1; }","duration":"211.033976ms","start":"2026-07-15T15:23:57.233313Z","end":"2026-07-15T15:23:57.444347Z","steps":["trace[513740366] 'process raft request' (duration: 210.926674ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:57.444678Z","caller":"traceutil/trace.go:171","msg":"trace[1485040284] transaction","detail":"{read_only:false; response_revision:26712; number_of_response:1; }","duration":"210.333463ms","start":"2026-07-15T15:23:57.234323Z","end":"2026-07-15T15:23:57.444656Z","steps":["trace[1485040284] 'process raft request' (duration: 209.975526ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:57.444726Z","caller":"traceutil/trace.go:171","msg":"trace[948404309] transaction","detail":"{read_only:false; response_revision:26714; number_of_response:1; }","duration":"207.55887ms","start":"2026-07-15T15:23:57.237146Z","end":"2026-07-15T15:23:57.444705Z","steps":["trace[948404309] 'process raft request' (duration: 207.510089ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:57.444905Z","caller":"traceutil/trace.go:171","msg":"trace[1846057126] transaction","detail":"{read_only:false; response_revision:26713; number_of_response:1; }","duration":"209.491217ms","start":"2026-07-15T15:23:57.235407Z","end":"2026-07-15T15:23:57.444898Z","steps":["trace[1846057126] 'process raft request' (duration: 209.14625ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:57.474441Z","caller":"traceutil/trace.go:171","msg":"trace[123818630] linearizableReadLoop","detail":"{readStateIndex:27594; appliedIndex:27588; }","duration":"182.907148ms","start":"2026-07-15T15:23:57.291518Z","end":"2026-07-15T15:23:57.474425Z","steps":["trace[123818630] 'read index received' (duration: 152.589426ms)","trace[123818630] 'applied index is now lower than readState.Index' (duration: 30.316882ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:23:57.47453Z","caller":"traceutil/trace.go:171","msg":"trace[545413327] transaction","detail":"{read_only:false; response_revision:26715; number_of_response:1; }","duration":"187.504556ms","start":"2026-07-15T15:23:57.28698Z","end":"2026-07-15T15:23:57.474485Z","steps":["trace[545413327] 'process raft request' (duration: 187.332392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:57.474723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.176103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-15T15:23:57.474755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.096906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T15:23:57.474758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.448303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-15T15:23:57.47479Z","caller":"traceutil/trace.go:171","msg":"trace[163323870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26715; }","duration":"140.162627ms","start":"2026-07-15T15:23:57.334617Z","end":"2026-07-15T15:23:57.47478Z","steps":["trace[163323870] 'agreement among raft nodes before linearized reading' (duration: 140.096546ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:57.474791Z","caller":"traceutil/trace.go:171","msg":"trace[1523611685] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26715; }","duration":"183.293474ms","start":"2026-07-15T15:23:57.291483Z","end":"2026-07-15T15:23:57.474776Z","steps":["trace[1523611685] 'agreement among raft nodes before linearized reading' (duration: 183.05291ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:57.474814Z","caller":"traceutil/trace.go:171","msg":"trace[1510152655] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26715; }","duration":"176.513585ms","start":"2026-07-15T15:23:57.298283Z","end":"2026-07-15T15:23:57.474796Z","steps":["trace[1510152655] 'agreement among raft nodes before linearized reading' (duration: 176.2922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:57.474825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.027221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-07-15T15:23:57.474754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.384327ms","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-07-15T15:23:57.474877Z","caller":"traceutil/trace.go:171","msg":"trace[237911465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26715; }","duration":"182.089152ms","start":"2026-07-15T15:23:57.292774Z","end":"2026-07-15T15:23:57.474863Z","steps":["trace[237911465] 'agreement among raft nodes before linearized reading' (duration: 181.911358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:23:57.47494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.958971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T15:23:57.475034Z","caller":"traceutil/trace.go:171","msg":"trace[503936015] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26715; }","duration":"180.074713ms","start":"2026-07-15T15:23:57.294943Z","end":"2026-07-15T15:23:57.475018Z","steps":["trace[503936015] 'agreement among raft nodes before linearized reading' (duration: 179.715256ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:57.47494Z","caller":"traceutil/trace.go:171","msg":"trace[710217808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26715; }","duration":"182.579551ms","start":"2026-07-15T15:23:57.292321Z","end":"2026-07-15T15:23:57.4749Z","steps":["trace[710217808] 'agreement among raft nodes before linearized reading' (duration: 182.216865ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:57.626683Z","caller":"traceutil/trace.go:171","msg":"trace[744992161] transaction","detail":"{read_only:false; response_revision:26716; number_of_response:1; }","duration":"148.5708ms","start":"2026-07-15T15:23:57.478088Z","end":"2026-07-15T15:23:57.626659Z","steps":["trace[744992161] 'process raft request' (duration: 69.984823ms)","trace[744992161] 'compare' (duration: 78.473385ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:23:57.672522Z","caller":"traceutil/trace.go:171","msg":"trace[698531261] transaction","detail":"{read_only:false; response_revision:26717; number_of_response:1; }","duration":"194.044553ms","start":"2026-07-15T15:23:57.478451Z","end":"2026-07-15T15:23:57.672495Z","steps":["trace[698531261] 'process raft request' (duration: 193.701726ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:57.67279Z","caller":"traceutil/trace.go:171","msg":"trace[49025857] transaction","detail":"{read_only:false; response_revision:26718; number_of_response:1; }","duration":"194.159475ms","start":"2026-07-15T15:23:57.478622Z","end":"2026-07-15T15:23:57.672782Z","steps":["trace[49025857] 'process raft request' (duration: 193.740227ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:57.672894Z","caller":"traceutil/trace.go:171","msg":"trace[1145186938] transaction","detail":"{read_only:false; response_revision:26719; number_of_response:1; }","duration":"193.720425ms","start":"2026-07-15T15:23:57.479167Z","end":"2026-07-15T15:23:57.672888Z","steps":["trace[1145186938] 'process raft request' (duration: 193.516981ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:57.672946Z","caller":"traceutil/trace.go:171","msg":"trace[1989131463] transaction","detail":"{read_only:false; response_revision:26720; number_of_response:1; }","duration":"193.590923ms","start":"2026-07-15T15:23:57.479329Z","end":"2026-07-15T15:23:57.67292Z","steps":["trace[1989131463] 'process raft request' (duration: 193.416979ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:23:57.67338Z","caller":"traceutil/trace.go:171","msg":"trace[971350528] linearizableReadLoop","detail":"{readStateIndex:27599; appliedIndex:27594; }","duration":"147.56374ms","start":"2026-07-15T15:23:57.525277Z","end":"2026-07-15T15:23:57.672841Z","steps":["trace[971350528] 'read index received' (duration: 22.857188ms)","trace[971350528] 'applied index is now lower than readState.Index' (duration: 124.705052ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:23:57.673975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.742503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-15T15:23:57.674031Z","caller":"traceutil/trace.go:171","msg":"trace[1605862841] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:26720; }","duration":"148.849114ms","start":"2026-07-15T15:23:57.525169Z","end":"2026-07-15T15:23:57.674018Z","steps":["trace[1605862841] 'agreement among raft nodes before linearized reading' (duration: 147.783194ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:06.821181Z","caller":"traceutil/trace.go:171","msg":"trace[149823941] transaction","detail":"{read_only:false; response_revision:26797; number_of_response:1; }","duration":"110.960338ms","start":"2026-07-15T15:24:06.710194Z","end":"2026-07-15T15:24:06.821154Z","steps":["trace[149823941] 'process raft request' (duration: 110.774614ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:07.149286Z","caller":"traceutil/trace.go:171","msg":"trace[946417761] linearizableReadLoop","detail":"{readStateIndex:27678; appliedIndex:27676; }","duration":"415.493869ms","start":"2026-07-15T15:24:06.733771Z","end":"2026-07-15T15:24:07.149265Z","steps":["trace[946417761] 'read index received' (duration: 87.235943ms)","trace[946417761] 'applied index is now lower than readState.Index' (duration: 328.256846ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:24:07.149382Z","caller":"traceutil/trace.go:171","msg":"trace[53571113] transaction","detail":"{read_only:false; response_revision:26798; number_of_response:1; }","duration":"434.916311ms","start":"2026-07-15T15:24:06.714431Z","end":"2026-07-15T15:24:07.149347Z","steps":["trace[53571113] 'process raft request' (duration: 334.75336ms)","trace[53571113] 'compare' (duration: 99.759963ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:24:07.149606Z","caller":"traceutil/trace.go:171","msg":"trace[362080188] transaction","detail":"{read_only:false; response_revision:26799; number_of_response:1; }","duration":"405.573588ms","start":"2026-07-15T15:24:06.744018Z","end":"2026-07-15T15:24:07.149592Z","steps":["trace[362080188] 'process raft request' (duration: 405.187491ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:24:07.149776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:24:06.744003Z","time spent":"405.66752ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T15:24:07.14992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:24:06.714414Z","time spent":"435.194576ms","remote":"127.0.0.1:43684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T15:24:07.150008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.211042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-07-15T15:24:07.150135Z","caller":"traceutil/trace.go:171","msg":"trace[287504666] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26799; }","duration":"416.364776ms","start":"2026-07-15T15:24:06.733746Z","end":"2026-07-15T15:24:07.15011Z","steps":["trace[287504666] 'agreement among raft nodes before linearized reading' (duration: 416.04345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:24:07.150186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:24:06.733734Z","time spent":"416.443017ms","remote":"127.0.0.1:57210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3332,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-07-15T15:24:07.345654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.219599ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-15T15:24:07.345768Z","caller":"traceutil/trace.go:171","msg":"trace[1374586884] linearizableReadLoop","detail":"{readStateIndex:27680; appliedIndex:27679; }","duration":"196.393016ms","start":"2026-07-15T15:24:07.149357Z","end":"2026-07-15T15:24:07.34575Z","steps":["trace[1374586884] 'read index received' (duration: 7.041884ms)","trace[1374586884] 'applied index is now lower than readState.Index' (duration: 189.349742ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:24:07.345932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.307506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:24:07.346012Z","caller":"traceutil/trace.go:171","msg":"trace[1754795846] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:26799; }","duration":"523.409717ms","start":"2026-07-15T15:24:06.822582Z","end":"2026-07-15T15:24:07.345991Z","steps":["trace[1754795846] 'agreement among raft nodes before linearized reading' (duration: 523.283405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:24:07.346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.547621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T15:24:07.346059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:24:06.822571Z","time spent":"523.477688ms","remote":"127.0.0.1:33292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true "} {"level":"info","ts":"2026-07-15T15:24:07.346093Z","caller":"traceutil/trace.go:171","msg":"trace[1610536960] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:26799; }","duration":"547.683774ms","start":"2026-07-15T15:24:06.798387Z","end":"2026-07-15T15:24:07.346071Z","steps":["trace[1610536960] 'agreement among raft nodes before linearized reading' (duration: 547.531431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:24:07.346024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.737075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"warn","ts":"2026-07-15T15:24:07.346196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:24:06.79837Z","time spent":"547.813816ms","remote":"127.0.0.1:33970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-15T15:24:07.34621Z","caller":"traceutil/trace.go:171","msg":"trace[702161056] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:26799; }","duration":"607.923999ms","start":"2026-07-15T15:24:06.738247Z","end":"2026-07-15T15:24:07.346171Z","steps":["trace[702161056] 'agreement among raft nodes before linearized reading' (duration: 607.643763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:24:07.346248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:24:06.738239Z","time spent":"607.99962ms","remote":"127.0.0.1:43676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" "} {"level":"warn","ts":"2026-07-15T15:24:07.401159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.23825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/floatingips/\" range_end:\"/registry/openstack.k-orc.cloud/floatingips0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:24:07.401218Z","caller":"traceutil/trace.go:171","msg":"trace[1224161641] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/floatingips/; range_end:/registry/openstack.k-orc.cloud/floatingips0; response_count:0; response_revision:26800; }","duration":"140.330171ms","start":"2026-07-15T15:24:07.260874Z","end":"2026-07-15T15:24:07.401204Z","steps":["trace[1224161641] 'agreement among raft nodes before linearized reading' (duration: 140.24675ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:07.631742Z","caller":"traceutil/trace.go:171","msg":"trace[1330554284] transaction","detail":"{read_only:false; response_revision:26801; number_of_response:1; }","duration":"154.623905ms","start":"2026-07-15T15:24:07.47709Z","end":"2026-07-15T15:24:07.631714Z","steps":["trace[1330554284] 'process raft request' (duration: 82.227456ms)","trace[1330554284] 'compare' (duration: 72.116494ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:24:07.647669Z","caller":"traceutil/trace.go:171","msg":"trace[1666933351] transaction","detail":"{read_only:false; response_revision:26802; number_of_response:1; }","duration":"168.3433ms","start":"2026-07-15T15:24:07.479295Z","end":"2026-07-15T15:24:07.647638Z","steps":["trace[1666933351] 'process raft request' (duration: 168.058473ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:07.647919Z","caller":"traceutil/trace.go:171","msg":"trace[2129126709] transaction","detail":"{read_only:false; response_revision:26803; number_of_response:1; }","duration":"168.40325ms","start":"2026-07-15T15:24:07.479495Z","end":"2026-07-15T15:24:07.647898Z","steps":["trace[2129126709] 'process raft request' (duration: 167.993932ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:07.648101Z","caller":"traceutil/trace.go:171","msg":"trace[2082125495] transaction","detail":"{read_only:false; response_revision:26804; number_of_response:1; }","duration":"167.615155ms","start":"2026-07-15T15:24:07.480469Z","end":"2026-07-15T15:24:07.648084Z","steps":["trace[2082125495] 'process raft request' (duration: 167.300489ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:07.648294Z","caller":"traceutil/trace.go:171","msg":"trace[1238302535] transaction","detail":"{read_only:false; response_revision:26805; number_of_response:1; }","duration":"135.620732ms","start":"2026-07-15T15:24:07.512657Z","end":"2026-07-15T15:24:07.648277Z","steps":["trace[1238302535] 'process raft request' (duration: 135.247205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:24:07.887432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.062089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T15:24:07.887708Z","caller":"traceutil/trace.go:171","msg":"trace[1291452243] transaction","detail":"{read_only:false; response_revision:26806; number_of_response:1; }","duration":"213.407172ms","start":"2026-07-15T15:24:07.674271Z","end":"2026-07-15T15:24:07.887678Z","steps":["trace[1291452243] 'process raft request' (duration: 88.023248ms)","trace[1291452243] 'compare' (duration: 124.805343ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:24:07.887791Z","caller":"traceutil/trace.go:171","msg":"trace[662010349] linearizableReadLoop","detail":"{readStateIndex:27687; appliedIndex:27686; }","duration":"176.808821ms","start":"2026-07-15T15:24:07.710966Z","end":"2026-07-15T15:24:07.887775Z","steps":["trace[662010349] 'read index received' (duration: 51.274084ms)","trace[662010349] 'applied index is now lower than readState.Index' (duration: 125.533517ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:24:07.888034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.063336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-15T15:24:07.888078Z","caller":"traceutil/trace.go:171","msg":"trace[1903915387] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26807; }","duration":"177.135087ms","start":"2026-07-15T15:24:07.710929Z","end":"2026-07-15T15:24:07.888064Z","steps":["trace[1903915387] 'agreement among raft nodes before linearized reading' (duration: 176.987564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:24:07.888237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.127127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:24:07.888272Z","caller":"traceutil/trace.go:171","msg":"trace[886833761] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:26807; }","duration":"177.183518ms","start":"2026-07-15T15:24:07.711081Z","end":"2026-07-15T15:24:07.888265Z","steps":["trace[886833761] 'agreement among raft nodes before linearized reading' (duration: 177.124867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:24:07.888739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.433476ms","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-07-15T15:24:07.888784Z","caller":"traceutil/trace.go:171","msg":"trace[2048708534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26807; }","duration":"174.519488ms","start":"2026-07-15T15:24:07.714254Z","end":"2026-07-15T15:24:07.888774Z","steps":["trace[2048708534] 'agreement among raft nodes before linearized reading' (duration: 174.370525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:24:07.888841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.006198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-15T15:24:07.888921Z","caller":"traceutil/trace.go:171","msg":"trace[948252692] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26807; }","duration":"174.10137ms","start":"2026-07-15T15:24:07.714798Z","end":"2026-07-15T15:24:07.888899Z","steps":["trace[948252692] 'agreement among raft nodes before linearized reading' (duration: 173.873046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:24:07.888971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.113062ms","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-07-15T15:24:07.88909Z","caller":"traceutil/trace.go:171","msg":"trace[767950674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26807; }","duration":"171.288014ms","start":"2026-07-15T15:24:07.717773Z","end":"2026-07-15T15:24:07.889061Z","steps":["trace[767950674] 'agreement among raft nodes before linearized reading' (duration: 170.867916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:24:08.15627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.92349ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T15:24:08.156573Z","caller":"traceutil/trace.go:171","msg":"trace[188349573] transaction","detail":"{read_only:false; response_revision:26810; number_of_response:1; }","duration":"262.476264ms","start":"2026-07-15T15:24:07.894075Z","end":"2026-07-15T15:24:08.156551Z","steps":["trace[188349573] 'process raft request' (duration: 130.168166ms)","trace[188349573] 'compare' (duration: 131.604254ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:24:08.15666Z","caller":"traceutil/trace.go:171","msg":"trace[447849116] transaction","detail":"{read_only:false; response_revision:26811; number_of_response:1; }","duration":"262.113816ms","start":"2026-07-15T15:24:07.894528Z","end":"2026-07-15T15:24:08.156642Z","steps":["trace[447849116] 'process raft request' (duration: 261.846441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:24:14.823303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.460879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"info","ts":"2026-07-15T15:24:14.823369Z","caller":"traceutil/trace.go:171","msg":"trace[1012501673] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26871; }","duration":"134.561341ms","start":"2026-07-15T15:24:14.688791Z","end":"2026-07-15T15:24:14.823352Z","steps":["trace[1012501673] 'range keys from in-memory index tree' (duration: 134.327096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:24:17.379016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.713679ms","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-07-15T15:24:17.37917Z","caller":"traceutil/trace.go:171","msg":"trace[657934897] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26887; }","duration":"103.893892ms","start":"2026-07-15T15:24:17.275254Z","end":"2026-07-15T15:24:17.379148Z","steps":["trace[657934897] 'range keys from in-memory index tree' (duration: 103.692939ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:27.77289Z","caller":"traceutil/trace.go:171","msg":"trace[1481806051] linearizableReadLoop","detail":"{readStateIndex:27852; appliedIndex:27851; }","duration":"183.54372ms","start":"2026-07-15T15:24:27.589324Z","end":"2026-07-15T15:24:27.772867Z","steps":["trace[1481806051] 'read index received' (duration: 183.372337ms)","trace[1481806051] 'applied index is now lower than readState.Index' (duration: 170.453µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:24:27.772961Z","caller":"traceutil/trace.go:171","msg":"trace[116703759] transaction","detail":"{read_only:false; response_revision:26967; number_of_response:1; }","duration":"227.194437ms","start":"2026-07-15T15:24:27.545745Z","end":"2026-07-15T15:24:27.77294Z","steps":["trace[116703759] 'process raft request' (duration: 226.938092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:24:27.773041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.698964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/users/\" range_end:\"/registry/openstack.k-orc.cloud/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:24:27.773445Z","caller":"traceutil/trace.go:171","msg":"trace[924109991] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/users/; range_end:/registry/openstack.k-orc.cloud/users0; response_count:0; response_revision:26967; }","duration":"183.892757ms","start":"2026-07-15T15:24:27.589266Z","end":"2026-07-15T15:24:27.773159Z","steps":["trace[924109991] 'agreement among raft nodes before linearized reading' (duration: 183.703453ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:27.946904Z","caller":"traceutil/trace.go:171","msg":"trace[1535561238] transaction","detail":"{read_only:false; response_revision:26968; number_of_response:1; }","duration":"175.499746ms","start":"2026-07-15T15:24:27.771383Z","end":"2026-07-15T15:24:27.946883Z","steps":["trace[1535561238] 'process raft request' (duration: 157.381348ms)","trace[1535561238] 'compare' (duration: 17.936115ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:24:27.947177Z","caller":"traceutil/trace.go:171","msg":"trace[318274391] transaction","detail":"{read_only:false; response_revision:26969; number_of_response:1; }","duration":"175.654679ms","start":"2026-07-15T15:24:27.771488Z","end":"2026-07-15T15:24:27.947143Z","steps":["trace[318274391] 'process raft request' (duration: 175.351684ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:27.947219Z","caller":"traceutil/trace.go:171","msg":"trace[269130248] transaction","detail":"{read_only:false; response_revision:26970; number_of_response:1; }","duration":"175.376533ms","start":"2026-07-15T15:24:27.771818Z","end":"2026-07-15T15:24:27.947194Z","steps":["trace[269130248] 'process raft request' (duration: 175.19097ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:24:27.94749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.668083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-registry-c45778976-fqvfc\" ","response":"range_response_count:1 size:3397"} {"level":"info","ts":"2026-07-15T15:24:27.947522Z","caller":"traceutil/trace.go:171","msg":"trace[374550741] range","detail":"{range_begin:/registry/pods/openstack/magnum-registry-c45778976-fqvfc; range_end:; response_count:1; response_revision:26972; }","duration":"145.753505ms","start":"2026-07-15T15:24:27.801762Z","end":"2026-07-15T15:24:27.947516Z","steps":["trace[374550741] 'agreement among raft nodes before linearized reading' (duration: 145.630223ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:27.947318Z","caller":"traceutil/trace.go:171","msg":"trace[553481342] transaction","detail":"{read_only:false; response_revision:26971; number_of_response:1; }","duration":"174.786633ms","start":"2026-07-15T15:24:27.772524Z","end":"2026-07-15T15:24:27.94731Z","steps":["trace[553481342] 'process raft request' (duration: 174.625019ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:27.947645Z","caller":"traceutil/trace.go:171","msg":"trace[1378800516] transaction","detail":"{read_only:false; response_revision:26972; number_of_response:1; }","duration":"174.837333ms","start":"2026-07-15T15:24:27.7728Z","end":"2026-07-15T15:24:27.947638Z","steps":["trace[1378800516] 'process raft request' (duration: 174.475736ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:27.947374Z","caller":"traceutil/trace.go:171","msg":"trace[989036307] linearizableReadLoop","detail":"{readStateIndex:27857; appliedIndex:27852; }","duration":"145.552972ms","start":"2026-07-15T15:24:27.801814Z","end":"2026-07-15T15:24:27.947367Z","steps":["trace[989036307] 'read index received' (duration: 127.182219ms)","trace[989036307] 'applied index is now lower than readState.Index' (duration: 18.370113ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:24:28.088478Z","caller":"traceutil/trace.go:171","msg":"trace[517385299] transaction","detail":"{read_only:false; response_revision:26973; number_of_response:1; }","duration":"136.779242ms","start":"2026-07-15T15:24:27.95167Z","end":"2026-07-15T15:24:28.088449Z","steps":["trace[517385299] 'process raft request' (duration: 122.55568ms)","trace[517385299] 'compare' (duration: 14.10335ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:24:28.313042Z","caller":"traceutil/trace.go:171","msg":"trace[1324034478] transaction","detail":"{read_only:false; response_revision:26974; number_of_response:1; }","duration":"357.843972ms","start":"2026-07-15T15:24:27.955156Z","end":"2026-07-15T15:24:28.313Z","steps":["trace[1324034478] 'process raft request' (duration: 347.058616ms)","trace[1324034478] 'compare' (duration: 10.387348ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:24:28.313134Z","caller":"traceutil/trace.go:171","msg":"trace[705151481] linearizableReadLoop","detail":"{readStateIndex:27859; appliedIndex:27857; }","duration":"347.183657ms","start":"2026-07-15T15:24:27.965927Z","end":"2026-07-15T15:24:28.31311Z","steps":["trace[705151481] 'read index received' (duration: 108.313376ms)","trace[705151481] 'applied index is now lower than readState.Index' (duration: 238.868961ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:24:28.313198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:24:27.955136Z","time spent":"357.982124ms","remote":"127.0.0.1:43684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3702,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T15:24:28.3134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.440085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-15T15:24:28.313413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.01043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-15T15:24:28.313371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.424552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-15T15:24:28.313465Z","caller":"traceutil/trace.go:171","msg":"trace[907640072] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:26975; }","duration":"115.546666ms","start":"2026-07-15T15:24:28.197902Z","end":"2026-07-15T15:24:28.313448Z","steps":["trace[907640072] 'agreement among raft nodes before linearized reading' (duration: 115.422134ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:28.313489Z","caller":"traceutil/trace.go:171","msg":"trace[1095718164] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26975; }","duration":"347.582807ms","start":"2026-07-15T15:24:27.965894Z","end":"2026-07-15T15:24:28.313477Z","steps":["trace[1095718164] 'agreement among raft nodes before linearized reading' (duration: 347.330371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:24:28.313722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T15:24:27.96588Z","time spent":"347.830251ms","remote":"127.0.0.1:43754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-15T15:24:28.313728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.96383ms","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-07-15T15:24:28.313763Z","caller":"traceutil/trace.go:171","msg":"trace[363004954] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:26975; }","duration":"279.025241ms","start":"2026-07-15T15:24:28.034728Z","end":"2026-07-15T15:24:28.313753Z","steps":["trace[363004954] 'agreement among raft nodes before linearized reading' (duration: 278.788887ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:28.313489Z","caller":"traceutil/trace.go:171","msg":"trace[982490100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26975; }","duration":"281.115472ms","start":"2026-07-15T15:24:28.032353Z","end":"2026-07-15T15:24:28.313468Z","steps":["trace[982490100] 'agreement among raft nodes before linearized reading' (duration: 280.920107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:24:28.314017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.5409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-15T15:24:28.314342Z","caller":"traceutil/trace.go:171","msg":"trace[1705550846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26975; }","duration":"280.896537ms","start":"2026-07-15T15:24:28.033429Z","end":"2026-07-15T15:24:28.314326Z","steps":["trace[1705550846] 'agreement among raft nodes before linearized reading' (duration: 280.51303ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:28.510971Z","caller":"traceutil/trace.go:171","msg":"trace[1545604665] transaction","detail":"{read_only:false; response_revision:26977; number_of_response:1; }","duration":"192.018672ms","start":"2026-07-15T15:24:28.318919Z","end":"2026-07-15T15:24:28.510938Z","steps":["trace[1545604665] 'process raft request' (duration: 191.742097ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:28.510993Z","caller":"traceutil/trace.go:171","msg":"trace[1987067980] transaction","detail":"{read_only:false; response_revision:26979; number_of_response:1; }","duration":"191.90551ms","start":"2026-07-15T15:24:28.319061Z","end":"2026-07-15T15:24:28.510967Z","steps":["trace[1987067980] 'process raft request' (duration: 191.823258ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:28.511024Z","caller":"traceutil/trace.go:171","msg":"trace[682199365] transaction","detail":"{read_only:false; response_revision:26978; number_of_response:1; }","duration":"191.966781ms","start":"2026-07-15T15:24:28.319023Z","end":"2026-07-15T15:24:28.51099Z","steps":["trace[682199365] 'process raft request' (duration: 191.811148ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:28.511415Z","caller":"traceutil/trace.go:171","msg":"trace[1163739758] transaction","detail":"{read_only:false; response_revision:26980; number_of_response:1; }","duration":"191.561853ms","start":"2026-07-15T15:24:28.319749Z","end":"2026-07-15T15:24:28.511311Z","steps":["trace[1163739758] 'process raft request' (duration: 191.171996ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:28.51143Z","caller":"traceutil/trace.go:171","msg":"trace[1600206478] transaction","detail":"{read_only:false; response_revision:26981; number_of_response:1; }","duration":"190.798739ms","start":"2026-07-15T15:24:28.320604Z","end":"2026-07-15T15:24:28.511403Z","steps":["trace[1600206478] 'process raft request' (duration: 190.592085ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:28.511615Z","caller":"traceutil/trace.go:171","msg":"trace[625502268] transaction","detail":"{read_only:false; response_revision:26982; number_of_response:1; }","duration":"190.790289ms","start":"2026-07-15T15:24:28.320803Z","end":"2026-07-15T15:24:28.511594Z","steps":["trace[625502268] 'process raft request' (duration: 190.516883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:24:28.511896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.469963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-15T15:24:28.511942Z","caller":"traceutil/trace.go:171","msg":"trace[575510010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26982; }","duration":"177.566855ms","start":"2026-07-15T15:24:28.334364Z","end":"2026-07-15T15:24:28.511931Z","steps":["trace[575510010] 'agreement among raft nodes before linearized reading' (duration: 177.474783ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:28.511584Z","caller":"traceutil/trace.go:171","msg":"trace[1270165495] linearizableReadLoop","detail":"{readStateIndex:27867; appliedIndex:27861; }","duration":"177.151117ms","start":"2026-07-15T15:24:28.334413Z","end":"2026-07-15T15:24:28.511564Z","steps":["trace[1270165495] 'read index received' (duration: 176.24172ms)","trace[1270165495] 'applied index is now lower than readState.Index' (duration: 907.877µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:24:33.900313Z","caller":"traceutil/trace.go:171","msg":"trace[215776033] transaction","detail":"{read_only:false; response_revision:27022; number_of_response:1; }","duration":"107.4519ms","start":"2026-07-15T15:24:33.792832Z","end":"2026-07-15T15:24:33.900284Z","steps":["trace[215776033] 'process raft request' (duration: 107.299687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T15:24:34.124681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.841025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-15T15:24:34.124942Z","caller":"traceutil/trace.go:171","msg":"trace[1263367403] transaction","detail":"{read_only:false; response_revision:27025; number_of_response:1; }","duration":"159.747814ms","start":"2026-07-15T15:24:33.965163Z","end":"2026-07-15T15:24:34.124911Z","steps":["trace[1263367403] 'process raft request' (duration: 159.676502ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:34.124954Z","caller":"traceutil/trace.go:171","msg":"trace[606015879] transaction","detail":"{read_only:false; response_revision:27023; number_of_response:1; }","duration":"160.447027ms","start":"2026-07-15T15:24:33.964473Z","end":"2026-07-15T15:24:34.12492Z","steps":["trace[606015879] 'process raft request' (duration: 36.288516ms)","trace[606015879] 'compare' (duration: 123.655361ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:24:34.125145Z","caller":"traceutil/trace.go:171","msg":"trace[169506436] transaction","detail":"{read_only:false; response_revision:27024; number_of_response:1; }","duration":"160.0942ms","start":"2026-07-15T15:24:33.965034Z","end":"2026-07-15T15:24:34.125128Z","steps":["trace[169506436] 'process raft request' (duration: 159.747483ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:34.125277Z","caller":"traceutil/trace.go:171","msg":"trace[63368502] transaction","detail":"{read_only:false; response_revision:27026; number_of_response:1; }","duration":"158.04463ms","start":"2026-07-15T15:24:33.967219Z","end":"2026-07-15T15:24:34.125264Z","steps":["trace[63368502] 'process raft request' (duration: 157.659653ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:34.125336Z","caller":"traceutil/trace.go:171","msg":"trace[1945669945] transaction","detail":"{read_only:false; response_revision:27027; number_of_response:1; }","duration":"157.227565ms","start":"2026-07-15T15:24:33.968085Z","end":"2026-07-15T15:24:34.125313Z","steps":["trace[1945669945] 'process raft request' (duration: 157.083373ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:34.125336Z","caller":"traceutil/trace.go:171","msg":"trace[359096780] linearizableReadLoop","detail":"{readStateIndex:27913; appliedIndex:27908; }","duration":"102.550596ms","start":"2026-07-15T15:24:34.022759Z","end":"2026-07-15T15:24:34.12531Z","steps":["trace[359096780] 'read index received' (duration: 31.26973ms)","trace[359096780] 'applied index is now lower than readState.Index' (duration: 71.279916ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:24:34.125472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.695439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-15T15:24:34.125505Z","caller":"traceutil/trace.go:171","msg":"trace[1656081325] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:27027; }","duration":"102.785771ms","start":"2026-07-15T15:24:34.022709Z","end":"2026-07-15T15:24:34.125495Z","steps":["trace[1656081325] 'agreement among raft nodes before linearized reading' (duration: 102.695729ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:34.22001Z","caller":"traceutil/trace.go:171","msg":"trace[965058460] transaction","detail":"{read_only:false; response_revision:27028; number_of_response:1; }","duration":"112.679691ms","start":"2026-07-15T15:24:34.107303Z","end":"2026-07-15T15:24:34.219983Z","steps":["trace[965058460] 'process raft request' (duration: 112.517128ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:34.671472Z","caller":"traceutil/trace.go:171","msg":"trace[1815807341] transaction","detail":"{read_only:false; response_revision:27032; number_of_response:1; }","duration":"126.353413ms","start":"2026-07-15T15:24:34.545086Z","end":"2026-07-15T15:24:34.671439Z","steps":["trace[1815807341] 'process raft request' (duration: 126.098828ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:34.671656Z","caller":"traceutil/trace.go:171","msg":"trace[734091803] transaction","detail":"{read_only:false; response_revision:27033; number_of_response:1; }","duration":"125.096468ms","start":"2026-07-15T15:24:34.546543Z","end":"2026-07-15T15:24:34.671639Z","steps":["trace[734091803] 'process raft request' (duration: 124.826403ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:34.6717Z","caller":"traceutil/trace.go:171","msg":"trace[464897936] transaction","detail":"{read_only:false; response_revision:27034; number_of_response:1; }","duration":"125.029377ms","start":"2026-07-15T15:24:34.546656Z","end":"2026-07-15T15:24:34.671685Z","steps":["trace[464897936] 'process raft request' (duration: 124.921135ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:34.822277Z","caller":"traceutil/trace.go:171","msg":"trace[1538940471] linearizableReadLoop","detail":"{readStateIndex:27921; appliedIndex:27920; }","duration":"144.985381ms","start":"2026-07-15T15:24:34.677259Z","end":"2026-07-15T15:24:34.822245Z","steps":["trace[1538940471] 'read index received' (duration: 115.952175ms)","trace[1538940471] 'applied index is now lower than readState.Index' (duration: 29.032016ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T15:24:34.822549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.266026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-5db5df4c4f-p85nw.18c27fde562a56c0\" ","response":"range_response_count:1 size:855"} {"level":"info","ts":"2026-07-15T15:24:34.822585Z","caller":"traceutil/trace.go:171","msg":"trace[1215704822] range","detail":"{range_begin:/registry/events/openstack/magnum-api-5db5df4c4f-p85nw.18c27fde562a56c0; range_end:; response_count:1; response_revision:27035; }","duration":"145.359468ms","start":"2026-07-15T15:24:34.67721Z","end":"2026-07-15T15:24:34.822569Z","steps":["trace[1215704822] 'agreement among raft nodes before linearized reading' (duration: 145.156204ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T15:24:34.822561Z","caller":"traceutil/trace.go:171","msg":"trace[1289576117] transaction","detail":"{read_only:false; response_revision:27035; number_of_response:1; }","duration":"153.130487ms","start":"2026-07-15T15:24:34.669395Z","end":"2026-07-15T15:24:34.822526Z","steps":["trace[1289576117] 'process raft request' (duration: 123.929568ms)","trace[1289576117] 'compare' (duration: 28.761091ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T15:25:48.902999Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24912} {"level":"info","ts":"2026-07-15T15:25:49.053414Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24912,"took":"143.492842ms","hash":2127101235,"current-db-size-bytes":58421248,"current-db-size":"58 MB","current-db-size-in-use-bytes":37298176,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-07-15T15:25:49.053481Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2127101235,"revision":24912,"compact-revision":21658} {"level":"info","ts":"2026-07-15T15:27:55.729079Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8bb149ac537fafcb","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T15:27:55.734446Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-15T15:27:55.734582Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-15T15:30:48.910411Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27841} {"level":"info","ts":"2026-07-15T15:30:49.051385Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27841,"took":"136.557604ms","hash":1423806139,"current-db-size-bytes":58421248,"current-db-size":"58 MB","current-db-size-in-use-bytes":35119104,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-15T15:30:49.051447Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1423806139,"revision":27841,"compact-revision":24912} {"level":"info","ts":"2026-07-15T15:35:48.917205Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30547} {"level":"info","ts":"2026-07-15T15:35:49.062753Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30547,"took":"138.802368ms","hash":3534965321,"current-db-size-bytes":58421248,"current-db-size":"58 MB","current-db-size-in-use-bytes":30937088,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T15:35:49.062818Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3534965321,"revision":30547,"compact-revision":27841} {"level":"info","ts":"2026-07-15T15:40:48.924009Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32821} {"level":"info","ts":"2026-07-15T15:40:49.076556Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32821,"took":"145.957927ms","hash":1131438037,"current-db-size-bytes":58421248,"current-db-size":"58 MB","current-db-size-in-use-bytes":30633984,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T15:40:49.076631Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1131438037,"revision":32821,"compact-revision":30547} {"level":"info","ts":"2026-07-15T15:45:48.929799Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35068} {"level":"info","ts":"2026-07-15T15:45:49.075065Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35068,"took":"141.570713ms","hash":213152094,"current-db-size-bytes":58421248,"current-db-size":"58 MB","current-db-size-in-use-bytes":30941184,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T15:45:49.075141Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":213152094,"revision":35068,"compact-revision":32821} {"level":"info","ts":"2026-07-15T15:48:55.125239Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8bb149ac537fafcb","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-15T15:48:55.131531Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-15T15:48:55.131651Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-15T15:50:48.937144Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37381} {"level":"info","ts":"2026-07-15T15:50:49.064165Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37381,"took":"121.73204ms","hash":517154077,"current-db-size-bytes":58421248,"current-db-size":"58 MB","current-db-size-in-use-bytes":30883840,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-15T15:50:49.064248Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":517154077,"revision":37381,"compact-revision":35068}