{"level":"warn","ts":"2026-05-12T16:33:29.169121Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T16:33:29.169266Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.153:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.153:2380","--initial-cluster=instance=https://199.204.45.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.153: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-05-12T16:33:29.169351Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T16:33:29.169362Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-05-12T16:33:29.169391Z","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-05-12T16:33:29.170156Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"]} {"level":"info","ts":"2026-05-12T16:33:29.17023Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.153: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-05-12T16:33:29.173872Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.436263ms"} {"level":"info","ts":"2026-05-12T16:33:29.179044Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"355195d5aae85944","cluster-id":"e424441517bc0b32"} {"level":"info","ts":"2026-05-12T16:33:29.179121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=()"} {"level":"info","ts":"2026-05-12T16:33:29.179142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 0"} {"level":"info","ts":"2026-05-12T16:33:29.179157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 355195d5aae85944 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-12T16:33:29.179166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 1"} {"level":"info","ts":"2026-05-12T16:33:29.179206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"warn","ts":"2026-05-12T16:33:29.184807Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-12T16:33:29.186293Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-12T16:33:29.187567Z","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-05-12T16:33:29.190611Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"355195d5aae85944","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-12T16:33:29.190783Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"355195d5aae85944","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-12T16:33:29.190973Z","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-05-12T16:33:29.191041Z","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-05-12T16:33:29.191053Z","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-05-12T16:33:29.193121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"info","ts":"2026-05-12T16:33:29.193271Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","added-peer-id":"355195d5aae85944","added-peer-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-05-12T16:33:29.196957Z","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-05-12T16:33:29.197114Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-05-12T16:33:29.19715Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-05-12T16:33:29.199006Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"355195d5aae85944","initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-12T16:33:29.19906Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-12T16:33:30.179868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 is starting a new election at term 1"} {"level":"info","ts":"2026-05-12T16:33:30.179939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-12T16:33:30.179981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgPreVoteResp from 355195d5aae85944 at term 1"} {"level":"info","ts":"2026-05-12T16:33:30.180047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became candidate at term 2"} {"level":"info","ts":"2026-05-12T16:33:30.180058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgVoteResp from 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-05-12T16:33:30.180075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became leader at term 2"} {"level":"info","ts":"2026-05-12T16:33:30.180096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 355195d5aae85944 elected leader 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-05-12T16:33:30.181095Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:33:30.181857Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"355195d5aae85944","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.153:2379]}","request-path":"/0/members/355195d5aae85944/attributes","cluster-id":"e424441517bc0b32","publish-timeout":"7s"} {"level":"info","ts":"2026-05-12T16:33:30.18189Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T16:33:30.181873Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T16:33:30.18343Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:33:30.183603Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:33:30.183656Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T16:33:30.183711Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-12T16:33:30.18373Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-12T16:33:30.184109Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.153:2379"} {"level":"info","ts":"2026-05-12T16:33:30.185034Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-12T16:33:44.168476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.988911ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T16:33:44.168817Z","caller":"traceutil/trace.go:171","msg":"trace[814970515] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"273.083339ms","start":"2026-05-12T16:33:43.895687Z","end":"2026-05-12T16:33:44.16877Z","steps":["trace[814970515] 'process raft request' (duration: 83.298397ms)","trace[814970515] 'compare' (duration: 188.914489ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:33:44.357352Z","caller":"traceutil/trace.go:171","msg":"trace[896929297] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"180.199041ms","start":"2026-05-12T16:33:44.177121Z","end":"2026-05-12T16:33:44.35732Z","steps":["trace[896929297] 'process raft request' (duration: 179.965315ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:34:00.292457Z","caller":"traceutil/trace.go:171","msg":"trace[1284206355] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"184.433217ms","start":"2026-05-12T16:34:00.107983Z","end":"2026-05-12T16:34:00.292416Z","steps":["trace[1284206355] 'process raft request' (duration: 175.327239ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:34:00.292854Z","caller":"traceutil/trace.go:171","msg":"trace[574702321] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"171.706333ms","start":"2026-05-12T16:34:00.12113Z","end":"2026-05-12T16:34:00.292836Z","steps":["trace[574702321] 'process raft request' (duration: 171.229581ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:34:00.293106Z","caller":"traceutil/trace.go:171","msg":"trace[882674727] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"157.377084ms","start":"2026-05-12T16:34:00.135685Z","end":"2026-05-12T16:34:00.293062Z","steps":["trace[882674727] 'process raft request' (duration: 156.951683ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:34:00.293249Z","caller":"traceutil/trace.go:171","msg":"trace[2131219304] transaction","detail":"{read_only:false; number_of_response:1; response_revision:516; }","duration":"130.803355ms","start":"2026-05-12T16:34:00.162352Z","end":"2026-05-12T16:34:00.293156Z","steps":["trace[2131219304] 'process raft request' (duration: 130.498017ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:34:06.387402Z","caller":"traceutil/trace.go:171","msg":"trace[838819751] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"318.452317ms","start":"2026-05-12T16:34:06.06891Z","end":"2026-05-12T16:34:06.387362Z","steps":["trace[838819751] 'process raft request' (duration: 317.589714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:34:06.387685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:34:06.068883Z","time spent":"318.667062ms","remote":"127.0.0.1:40494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:34:06.563532Z","caller":"traceutil/trace.go:171","msg":"trace[1389893198] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:596; }","duration":"367.214278ms","start":"2026-05-12T16:34:06.196291Z","end":"2026-05-12T16:34:06.563505Z","steps":["trace[1389893198] 'read index received' (duration: 189.806723ms)","trace[1389893198] 'applied index is now lower than readState.Index' (duration: 177.406215ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:34:06.563641Z","caller":"traceutil/trace.go:171","msg":"trace[1652344510] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"492.644667ms","start":"2026-05-12T16:34:06.070957Z","end":"2026-05-12T16:34:06.563602Z","steps":["trace[1652344510] 'process raft request' (duration: 492.38904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:34:06.563794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:34:06.070908Z","time spent":"492.803741ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:34:06.563858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.511976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rqj9n\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-12T16:34:06.56404Z","caller":"traceutil/trace.go:171","msg":"trace[557609734] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rqj9n; range_end:; response_count:1; response_revision:576; }","duration":"367.703552ms","start":"2026-05-12T16:34:06.196228Z","end":"2026-05-12T16:34:06.563932Z","steps":["trace[557609734] 'agreement among raft nodes before linearized reading' (duration: 367.449385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:34:06.5642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:34:06.196207Z","time spent":"367.873136ms","remote":"127.0.0.1:40560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-rqj9n\" "} {"level":"info","ts":"2026-05-12T16:34:06.574958Z","caller":"traceutil/trace.go:171","msg":"trace[1462452864] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"130.030902ms","start":"2026-05-12T16:34:06.444904Z","end":"2026-05-12T16:34:06.574934Z","steps":["trace[1462452864] 'process raft request' (duration: 129.799936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:34:06.574971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.180176ms","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-05-12T16:34:06.575044Z","caller":"traceutil/trace.go:171","msg":"trace[1285125518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"254.264569ms","start":"2026-05-12T16:34:06.320761Z","end":"2026-05-12T16:34:06.575026Z","steps":["trace[1285125518] 'agreement among raft nodes before linearized reading' (duration: 254.057633ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:34:07.027715Z","caller":"traceutil/trace.go:171","msg":"trace[1990045269] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"448.337236ms","start":"2026-05-12T16:34:06.579351Z","end":"2026-05-12T16:34:07.027688Z","steps":["trace[1990045269] 'process raft request' (duration: 448.192762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:34:07.027922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:34:06.579327Z","time spent":"448.46249ms","remote":"127.0.0.1:40560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:34:07.028552Z","caller":"traceutil/trace.go:171","msg":"trace[1884907392] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:599; }","duration":"441.14402ms","start":"2026-05-12T16:34:06.58738Z","end":"2026-05-12T16:34:07.028524Z","steps":["trace[1884907392] 'read index received' (duration: 440.194095ms)","trace[1884907392] 'applied index is now lower than readState.Index' (duration: 948.805µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:34:07.028714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.307645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:34:07.02872Z","caller":"traceutil/trace.go:171","msg":"trace[226299630] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"449.112787ms","start":"2026-05-12T16:34:06.579565Z","end":"2026-05-12T16:34:07.028678Z","steps":["trace[226299630] 'process raft request' (duration: 448.825189ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:34:07.028759Z","caller":"traceutil/trace.go:171","msg":"trace[1212191793] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:579; }","duration":"441.381147ms","start":"2026-05-12T16:34:06.587363Z","end":"2026-05-12T16:34:07.028744Z","steps":["trace[1212191793] 'agreement among raft nodes before linearized reading' (duration: 441.300755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:34:07.028893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:34:06.587354Z","time spent":"441.528221ms","remote":"127.0.0.1:40616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-12T16:34:07.028897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.933561ms","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-05-12T16:34:07.028917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:34:06.579543Z","time spent":"449.264101ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:34:07.028983Z","caller":"traceutil/trace.go:171","msg":"trace[1333095383] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:579; }","duration":"353.064005ms","start":"2026-05-12T16:34:06.675894Z","end":"2026-05-12T16:34:07.028958Z","steps":["trace[1333095383] 'agreement among raft nodes before linearized reading' (duration: 352.781018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:34:07.029029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:34:06.675876Z","time spent":"353.144497ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-12T16:34:46.709051Z","caller":"traceutil/trace.go:171","msg":"trace[1466933132] linearizableReadLoop","detail":"{readStateIndex:1156; appliedIndex:1155; }","duration":"133.678384ms","start":"2026-05-12T16:34:46.575346Z","end":"2026-05-12T16:34:46.709025Z","steps":["trace[1466933132] 'read index received' (duration: 132.273635ms)","trace[1466933132] 'applied index is now lower than readState.Index' (duration: 1.403569ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:34:46.709191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.825988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T16:34:46.709172Z","caller":"traceutil/trace.go:171","msg":"trace[1968899428] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"146.42042ms","start":"2026-05-12T16:34:46.562712Z","end":"2026-05-12T16:34:46.709133Z","steps":["trace[1968899428] 'process raft request' (duration: 144.790755ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:34:46.709228Z","caller":"traceutil/trace.go:171","msg":"trace[181167970] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1124; }","duration":"133.90581ms","start":"2026-05-12T16:34:46.57531Z","end":"2026-05-12T16:34:46.709216Z","steps":["trace[181167970] 'agreement among raft nodes before linearized reading' (duration: 133.825128ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:34:46.709495Z","caller":"traceutil/trace.go:171","msg":"trace[672274568] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"101.54925ms","start":"2026-05-12T16:34:46.607922Z","end":"2026-05-12T16:34:46.709472Z","steps":["trace[672274568] 'process raft request' (duration: 101.18195ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:44.663909Z","caller":"traceutil/trace.go:171","msg":"trace[845348928] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"181.496459ms","start":"2026-05-12T16:35:44.482387Z","end":"2026-05-12T16:35:44.663884Z","steps":["trace[845348928] 'process raft request' (duration: 181.336445ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:44.690316Z","caller":"traceutil/trace.go:171","msg":"trace[1389125882] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"130.352857ms","start":"2026-05-12T16:35:44.559947Z","end":"2026-05-12T16:35:44.6903Z","steps":["trace[1389125882] 'process raft request' (duration: 130.235464ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:35:44.690518Z","caller":"traceutil/trace.go:171","msg":"trace[1438872908] linearizableReadLoop","detail":"{readStateIndex:1968; appliedIndex:1966; }","duration":"115.963385ms","start":"2026-05-12T16:35:44.574537Z","end":"2026-05-12T16:35:44.6905Z","steps":["trace[1438872908] 'read index received' (duration: 89.30046ms)","trace[1438872908] 'applied index is now lower than readState.Index' (duration: 26.659565ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T16:35:44.690749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.194662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-12T16:35:44.690845Z","caller":"traceutil/trace.go:171","msg":"trace[668125384] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1900; }","duration":"116.253474ms","start":"2026-05-12T16:35:44.574532Z","end":"2026-05-12T16:35:44.690786Z","steps":["trace[668125384] 'agreement among raft nodes before linearized reading' (duration: 116.082939ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:36:22.027904Z","caller":"traceutil/trace.go:171","msg":"trace[1625055356] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"161.002322ms","start":"2026-05-12T16:36:21.866869Z","end":"2026-05-12T16:36:22.027872Z","steps":["trace[1625055356] 'process raft request' (duration: 160.789746ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:41:31.613643Z","caller":"traceutil/trace.go:171","msg":"trace[1994089630] transaction","detail":"{read_only:false; response_revision:4698; number_of_response:1; }","duration":"121.138294ms","start":"2026-05-12T16:41:31.492472Z","end":"2026-05-12T16:41:31.61361Z","steps":["trace[1994089630] 'process raft request' (duration: 120.532128ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:41:39.533193Z","caller":"traceutil/trace.go:171","msg":"trace[1419490636] transaction","detail":"{read_only:false; response_revision:4936; number_of_response:1; }","duration":"102.916748ms","start":"2026-05-12T16:41:39.430248Z","end":"2026-05-12T16:41:39.533165Z","steps":["trace[1419490636] 'process raft request' (duration: 102.716192ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:41:49.766681Z","caller":"traceutil/trace.go:171","msg":"trace[720149110] transaction","detail":"{read_only:false; response_revision:5145; number_of_response:1; }","duration":"125.615959ms","start":"2026-05-12T16:41:49.641041Z","end":"2026-05-12T16:41:49.766657Z","steps":["trace[720149110] 'process raft request' (duration: 125.401804ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:41:49.766973Z","caller":"traceutil/trace.go:171","msg":"trace[1694398567] transaction","detail":"{read_only:false; response_revision:5148; number_of_response:1; }","duration":"125.351452ms","start":"2026-05-12T16:41:49.641595Z","end":"2026-05-12T16:41:49.766946Z","steps":["trace[1694398567] 'process raft request' (duration: 125.28507ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:41:49.76703Z","caller":"traceutil/trace.go:171","msg":"trace[171136010] transaction","detail":"{read_only:false; response_revision:5147; number_of_response:1; }","duration":"125.702152ms","start":"2026-05-12T16:41:49.641273Z","end":"2026-05-12T16:41:49.766975Z","steps":["trace[171136010] 'process raft request' (duration: 125.499806ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:41:49.767057Z","caller":"traceutil/trace.go:171","msg":"trace[1055056983] transaction","detail":"{read_only:false; response_revision:5146; number_of_response:1; }","duration":"125.896187ms","start":"2026-05-12T16:41:49.641135Z","end":"2026-05-12T16:41:49.767031Z","steps":["trace[1055056983] 'process raft request' (duration: 125.464426ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:41:49.812545Z","caller":"traceutil/trace.go:171","msg":"trace[253153429] linearizableReadLoop","detail":"{readStateIndex:5341; appliedIndex:5336; }","duration":"105.768917ms","start":"2026-05-12T16:41:49.706741Z","end":"2026-05-12T16:41:49.81251Z","steps":["trace[253153429] 'read index received' (duration: 59.758121ms)","trace[253153429] 'applied index is now lower than readState.Index' (duration: 46.009326ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:41:49.812619Z","caller":"traceutil/trace.go:171","msg":"trace[409803500] transaction","detail":"{read_only:false; response_revision:5149; number_of_response:1; }","duration":"158.788364ms","start":"2026-05-12T16:41:49.653786Z","end":"2026-05-12T16:41:49.812574Z","steps":["trace[409803500] 'process raft request' (duration: 158.531987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:41:49.812819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.043764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-12T16:41:49.812867Z","caller":"traceutil/trace.go:171","msg":"trace[624557577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5149; }","duration":"106.143927ms","start":"2026-05-12T16:41:49.70671Z","end":"2026-05-12T16:41:49.812854Z","steps":["trace[624557577] 'agreement among raft nodes before linearized reading' (duration: 105.946022ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:43:30.206704Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3007} {"level":"info","ts":"2026-05-12T16:43:30.285589Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3007,"took":"76.129212ms","hash":3341885087,"current-db-size-bytes":58130432,"current-db-size":"58 MB","current-db-size-in-use-bytes":32071680,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-12T16:43:30.285671Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3341885087,"revision":3007,"compact-revision":-1} {"level":"warn","ts":"2026-05-12T16:45:27.118635Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432440015339165939,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-12T16:45:27.150839Z","caller":"traceutil/trace.go:171","msg":"trace[861846096] transaction","detail":"{read_only:false; response_revision:6837; number_of_response:1; }","duration":"553.766676ms","start":"2026-05-12T16:45:26.597043Z","end":"2026-05-12T16:45:27.15081Z","steps":["trace[861846096] 'process raft request' (duration: 553.602911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:45:27.150994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:45:26.597025Z","time spent":"553.875158ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:45:27.161083Z","caller":"traceutil/trace.go:171","msg":"trace[2028894854] linearizableReadLoop","detail":"{readStateIndex:7087; appliedIndex:7082; }","duration":"542.905587ms","start":"2026-05-12T16:45:26.61816Z","end":"2026-05-12T16:45:27.161065Z","steps":["trace[2028894854] 'read index received' (duration: 532.534285ms)","trace[2028894854] 'applied index is now lower than readState.Index' (duration: 10.370192ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:45:27.1614Z","caller":"traceutil/trace.go:171","msg":"trace[1429395531] transaction","detail":"{read_only:false; response_revision:6838; number_of_response:1; }","duration":"358.31287ms","start":"2026-05-12T16:45:26.803073Z","end":"2026-05-12T16:45:27.161386Z","steps":["trace[1429395531] 'process raft request' (duration: 357.780775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:45:27.161481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:45:26.803059Z","time spent":"358.376001ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T16:45:27.161629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.469403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T16:45:27.161672Z","caller":"traceutil/trace.go:171","msg":"trace[1414760066] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:6841; }","duration":"543.563216ms","start":"2026-05-12T16:45:26.618097Z","end":"2026-05-12T16:45:27.16166Z","steps":["trace[1414760066] 'agreement among raft nodes before linearized reading' (duration: 543.486984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:45:27.161697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:45:26.618079Z","time spent":"543.611537ms","remote":"127.0.0.1:55616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-12T16:45:27.161712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.139455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-12T16:45:27.161743Z","caller":"traceutil/trace.go:171","msg":"trace[1712028642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6841; }","duration":"362.210676ms","start":"2026-05-12T16:45:26.799522Z","end":"2026-05-12T16:45:27.161732Z","steps":["trace[1712028642] 'agreement among raft nodes before linearized reading' (duration: 362.086403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:45:27.161805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:45:26.799506Z","time spent":"362.265938ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-12T16:45:27.161836Z","caller":"traceutil/trace.go:171","msg":"trace[421983554] transaction","detail":"{read_only:false; response_revision:6839; number_of_response:1; }","duration":"269.722747ms","start":"2026-05-12T16:45:26.892103Z","end":"2026-05-12T16:45:27.161826Z","steps":["trace[421983554] 'process raft request' (duration: 268.854052ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:45:27.161896Z","caller":"traceutil/trace.go:171","msg":"trace[1629885072] transaction","detail":"{read_only:false; response_revision:6840; number_of_response:1; }","duration":"269.454569ms","start":"2026-05-12T16:45:26.892436Z","end":"2026-05-12T16:45:27.161891Z","steps":["trace[1629885072] 'process raft request' (duration: 268.562414ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:45:27.161988Z","caller":"traceutil/trace.go:171","msg":"trace[721406665] transaction","detail":"{read_only:false; response_revision:6841; number_of_response:1; }","duration":"176.659861ms","start":"2026-05-12T16:45:26.985322Z","end":"2026-05-12T16:45:27.161982Z","steps":["trace[721406665] 'process raft request' (duration: 175.710244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:45:27.162011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.630337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-12T16:45:27.16204Z","caller":"traceutil/trace.go:171","msg":"trace[685154863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6841; }","duration":"234.694588ms","start":"2026-05-12T16:45:26.927337Z","end":"2026-05-12T16:45:27.162031Z","steps":["trace[685154863] 'agreement among raft nodes before linearized reading' (duration: 234.603456ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:48:30.215109Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6145} {"level":"info","ts":"2026-05-12T16:48:30.321105Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6145,"took":"103.567792ms","hash":2166191013,"current-db-size-bytes":58130432,"current-db-size":"58 MB","current-db-size-in-use-bytes":22822912,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-12T16:48:30.321198Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2166191013,"revision":6145,"compact-revision":3007} {"level":"info","ts":"2026-05-12T16:48:31.140831Z","caller":"traceutil/trace.go:171","msg":"trace[790385736] transaction","detail":"{read_only:false; response_revision:8288; number_of_response:1; }","duration":"324.710205ms","start":"2026-05-12T16:48:30.816092Z","end":"2026-05-12T16:48:31.140803Z","steps":["trace[790385736] 'process raft request' (duration: 324.248223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:48:31.14103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T16:48:30.816062Z","time spent":"324.840259ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T16:48:31.879823Z","caller":"traceutil/trace.go:171","msg":"trace[1389328279] linearizableReadLoop","detail":"{readStateIndex:8581; appliedIndex:8580; }","duration":"217.866215ms","start":"2026-05-12T16:48:31.661928Z","end":"2026-05-12T16:48:31.879795Z","steps":["trace[1389328279] 'read index received' (duration: 217.626039ms)","trace[1389328279] 'applied index is now lower than readState.Index' (duration: 239.016µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:48:31.880121Z","caller":"traceutil/trace.go:171","msg":"trace[766929108] transaction","detail":"{read_only:false; response_revision:8290; number_of_response:1; }","duration":"218.41355ms","start":"2026-05-12T16:48:31.661655Z","end":"2026-05-12T16:48:31.880069Z","steps":["trace[766929108] 'process raft request' (duration: 217.981168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:48:31.880213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.246146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q5pg8\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-12T16:48:31.880397Z","caller":"traceutil/trace.go:171","msg":"trace[424104647] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q5pg8; range_end:; response_count:1; response_revision:8290; }","duration":"218.463161ms","start":"2026-05-12T16:48:31.661905Z","end":"2026-05-12T16:48:31.880368Z","steps":["trace[424104647] 'agreement among raft nodes before linearized reading' (duration: 218.07479ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:49:01.06741Z","caller":"traceutil/trace.go:171","msg":"trace[567775245] transaction","detail":"{read_only:false; response_revision:8503; number_of_response:1; }","duration":"111.359043ms","start":"2026-05-12T16:49:00.956016Z","end":"2026-05-12T16:49:01.067375Z","steps":["trace[567775245] 'process raft request' (duration: 111.149738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:49:13.660278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.44275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T16:49:13.660419Z","caller":"traceutil/trace.go:171","msg":"trace[572441018] transaction","detail":"{read_only:false; response_revision:8616; number_of_response:1; }","duration":"145.970417ms","start":"2026-05-12T16:49:13.514427Z","end":"2026-05-12T16:49:13.660398Z","steps":["trace[572441018] 'process raft request' (duration: 21.309821ms)","trace[572441018] 'compare' (duration: 124.285135ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:49:43.950637Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000241b.wal"} {"level":"info","ts":"2026-05-12T16:51:10.644362Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"355195d5aae85944","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-12T16:51:10.650061Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002} {"level":"info","ts":"2026-05-12T16:51:10.650173Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002} {"level":"info","ts":"2026-05-12T16:53:30.223628Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8286} {"level":"info","ts":"2026-05-12T16:53:30.306586Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8286,"took":"80.121521ms","hash":1549389345,"current-db-size-bytes":58130432,"current-db-size":"58 MB","current-db-size-in-use-bytes":25268224,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-12T16:53:30.306672Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1549389345,"revision":8286,"compact-revision":6145} {"level":"info","ts":"2026-05-12T16:53:56.119852Z","caller":"traceutil/trace.go:171","msg":"trace[1855919630] transaction","detail":"{read_only:false; response_revision:11116; number_of_response:1; }","duration":"177.423458ms","start":"2026-05-12T16:53:55.942394Z","end":"2026-05-12T16:53:56.119817Z","steps":["trace[1855919630] 'process raft request' (duration: 177.218601ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:56:46.25043Z","caller":"traceutil/trace.go:171","msg":"trace[1894249845] linearizableReadLoop","detail":"{readStateIndex:12651; appliedIndex:12650; }","duration":"227.039416ms","start":"2026-05-12T16:56:46.023365Z","end":"2026-05-12T16:56:46.250405Z","steps":["trace[1894249845] 'read index received' (duration: 226.955053ms)","trace[1894249845] 'applied index is now lower than readState.Index' (duration: 83.013µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T16:56:46.250489Z","caller":"traceutil/trace.go:171","msg":"trace[923811106] transaction","detail":"{read_only:false; response_revision:12237; number_of_response:1; }","duration":"263.459657ms","start":"2026-05-12T16:56:45.98699Z","end":"2026-05-12T16:56:46.25045Z","steps":["trace[923811106] 'process raft request' (duration: 263.239731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T16:56:46.250627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.247572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-12T16:56:46.250658Z","caller":"traceutil/trace.go:171","msg":"trace[1304292492] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12237; }","duration":"227.302953ms","start":"2026-05-12T16:56:46.023343Z","end":"2026-05-12T16:56:46.250646Z","steps":["trace[1304292492] 'agreement among raft nodes before linearized reading' (duration: 227.155399ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:56:46.251702Z","caller":"traceutil/trace.go:171","msg":"trace[1954676825] transaction","detail":"{read_only:false; response_revision:12238; number_of_response:1; }","duration":"186.799342ms","start":"2026-05-12T16:56:46.064869Z","end":"2026-05-12T16:56:46.251668Z","steps":["trace[1954676825] 'process raft request' (duration: 186.443762ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:56:46.251689Z","caller":"traceutil/trace.go:171","msg":"trace[553654196] transaction","detail":"{read_only:false; response_revision:12239; number_of_response:1; }","duration":"183.80277ms","start":"2026-05-12T16:56:46.067849Z","end":"2026-05-12T16:56:46.251652Z","steps":["trace[553654196] 'process raft request' (duration: 183.682117ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:56:46.252017Z","caller":"traceutil/trace.go:171","msg":"trace[551470903] transaction","detail":"{read_only:false; response_revision:12241; number_of_response:1; }","duration":"183.285106ms","start":"2026-05-12T16:56:46.068718Z","end":"2026-05-12T16:56:46.252003Z","steps":["trace[551470903] 'process raft request' (duration: 183.197063ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:56:46.252131Z","caller":"traceutil/trace.go:171","msg":"trace[775047990] transaction","detail":"{read_only:false; response_revision:12240; number_of_response:1; }","duration":"184.105288ms","start":"2026-05-12T16:56:46.067959Z","end":"2026-05-12T16:56:46.252064Z","steps":["trace[775047990] 'process raft request' (duration: 183.635466ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T16:58:30.229362Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10776} {"level":"info","ts":"2026-05-12T16:58:30.314419Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10776,"took":"81.27603ms","hash":3413980646,"current-db-size-bytes":58130432,"current-db-size":"58 MB","current-db-size-in-use-bytes":27250688,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-12T16:58:30.314557Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3413980646,"revision":10776,"compact-revision":8286} {"level":"info","ts":"2026-05-12T17:00:14.217548Z","caller":"traceutil/trace.go:171","msg":"trace[188869884] linearizableReadLoop","detail":"{readStateIndex:14127; appliedIndex:14126; }","duration":"105.369325ms","start":"2026-05-12T17:00:14.112153Z","end":"2026-05-12T17:00:14.217523Z","steps":["trace[188869884] 'read index received' (duration: 105.16465ms)","trace[188869884] 'applied index is now lower than readState.Index' (duration: 203.585µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:00:14.217621Z","caller":"traceutil/trace.go:171","msg":"trace[556775162] transaction","detail":"{read_only:false; response_revision:13660; number_of_response:1; }","duration":"111.211855ms","start":"2026-05-12T17:00:14.106365Z","end":"2026-05-12T17:00:14.217577Z","steps":["trace[556775162] 'process raft request' (duration: 110.989109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:00:14.217776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.601162ms","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-05-12T17:00:14.217822Z","caller":"traceutil/trace.go:171","msg":"trace[42742671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13660; }","duration":"105.717805ms","start":"2026-05-12T17:00:14.112093Z","end":"2026-05-12T17:00:14.217811Z","steps":["trace[42742671] 'agreement among raft nodes before linearized reading' (duration: 105.55382ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:00:34.460457Z","caller":"traceutil/trace.go:171","msg":"trace[774487552] transaction","detail":"{read_only:false; response_revision:13767; number_of_response:1; }","duration":"126.458128ms","start":"2026-05-12T17:00:34.333948Z","end":"2026-05-12T17:00:34.460406Z","steps":["trace[774487552] 'process raft request' (duration: 124.069783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:00:58.218878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.100187ms","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-05-12T17:00:58.218986Z","caller":"traceutil/trace.go:171","msg":"trace[500075755] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13904; }","duration":"123.288762ms","start":"2026-05-12T17:00:58.095673Z","end":"2026-05-12T17:00:58.218962Z","steps":["trace[500075755] 'range keys from in-memory index tree' (duration: 122.81762ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:01:08.880032Z","caller":"traceutil/trace.go:171","msg":"trace[1726464830] transaction","detail":"{read_only:false; response_revision:14080; number_of_response:1; }","duration":"248.226799ms","start":"2026-05-12T17:01:08.631761Z","end":"2026-05-12T17:01:08.879988Z","steps":["trace[1726464830] 'process raft request' (duration: 238.211927ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:01:08.880019Z","caller":"traceutil/trace.go:171","msg":"trace[42613396] linearizableReadLoop","detail":"{readStateIndex:14561; appliedIndex:14560; }","duration":"231.362611ms","start":"2026-05-12T17:01:08.648623Z","end":"2026-05-12T17:01:08.879986Z","steps":["trace[42613396] 'read index received' (duration: 221.311468ms)","trace[42613396] 'applied index is now lower than readState.Index' (duration: 10.049923ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:01:08.880288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.638508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-12T17:01:08.880335Z","caller":"traceutil/trace.go:171","msg":"trace[1822218639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14080; }","duration":"231.763801ms","start":"2026-05-12T17:01:08.648563Z","end":"2026-05-12T17:01:08.880326Z","steps":["trace[1822218639] 'agreement among raft nodes before linearized reading' (duration: 231.544495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:02:39.368852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.565062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-05-12T17:02:39.368976Z","caller":"traceutil/trace.go:171","msg":"trace[106027836] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15120; }","duration":"215.708126ms","start":"2026-05-12T17:02:39.153239Z","end":"2026-05-12T17:02:39.368947Z","steps":["trace[106027836] 'range keys from in-memory index tree' (duration: 215.312015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:02:39.369026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.176491ms","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-05-12T17:02:39.369137Z","caller":"traceutil/trace.go:171","msg":"trace[1258840396] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15120; }","duration":"215.363576ms","start":"2026-05-12T17:02:39.153747Z","end":"2026-05-12T17:02:39.36911Z","steps":["trace[1258840396] 'range keys from in-memory index tree' (duration: 215.036138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:02:39.369439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.696936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-05-12T17:02:39.36958Z","caller":"traceutil/trace.go:171","msg":"trace[1524183695] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15120; }","duration":"215.86674ms","start":"2026-05-12T17:02:39.153678Z","end":"2026-05-12T17:02:39.369545Z","steps":["trace[1524183695] 'range keys from in-memory index tree' (duration: 215.411308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:02:39.369497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.37488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-12T17:02:39.369712Z","caller":"traceutil/trace.go:171","msg":"trace[960406334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15120; }","duration":"235.621097ms","start":"2026-05-12T17:02:39.134063Z","end":"2026-05-12T17:02:39.369684Z","steps":["trace[960406334] 'range keys from in-memory index tree' (duration: 235.146805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:02:39.369683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.49048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q5pg8\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-12T17:02:39.369915Z","caller":"traceutil/trace.go:171","msg":"trace[857748451] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q5pg8; range_end:; response_count:1; response_revision:15120; }","duration":"286.741347ms","start":"2026-05-12T17:02:39.083143Z","end":"2026-05-12T17:02:39.369884Z","steps":["trace[857748451] 'range keys from in-memory index tree' (duration: 285.800011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:02:39.73175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.408732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-kgc6n\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-05-12T17:02:39.731843Z","caller":"traceutil/trace.go:171","msg":"trace[612446307] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-kgc6n; range_end:; response_count:1; response_revision:15122; }","duration":"206.569336ms","start":"2026-05-12T17:02:39.525251Z","end":"2026-05-12T17:02:39.731821Z","steps":["trace[612446307] 'range keys from in-memory index tree' (duration: 206.115705ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:02:39.765026Z","caller":"traceutil/trace.go:171","msg":"trace[891290106] linearizableReadLoop","detail":"{readStateIndex:15638; appliedIndex:15637; }","duration":"236.159142ms","start":"2026-05-12T17:02:39.528819Z","end":"2026-05-12T17:02:39.764978Z","steps":["trace[891290106] 'read index received' (duration: 235.867644ms)","trace[891290106] 'applied index is now lower than readState.Index' (duration: 289.588µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:02:39.765201Z","caller":"traceutil/trace.go:171","msg":"trace[1761186421] transaction","detail":"{read_only:false; response_revision:15123; number_of_response:1; }","duration":"237.808296ms","start":"2026-05-12T17:02:39.527337Z","end":"2026-05-12T17:02:39.765145Z","steps":["trace[1761186421] 'process raft request' (duration: 237.340314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:02:39.765468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.429649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T17:02:39.765494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.017286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-05-12T17:02:39.765552Z","caller":"traceutil/trace.go:171","msg":"trace[1348655166] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15123; }","duration":"236.755788ms","start":"2026-05-12T17:02:39.528774Z","end":"2026-05-12T17:02:39.765529Z","steps":["trace[1348655166] 'agreement among raft nodes before linearized reading' (duration: 236.424699ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:02:39.765573Z","caller":"traceutil/trace.go:171","msg":"trace[1480660723] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15123; }","duration":"233.119159ms","start":"2026-05-12T17:02:39.532432Z","end":"2026-05-12T17:02:39.765552Z","steps":["trace[1480660723] 'agreement among raft nodes before linearized reading' (duration: 232.915154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:02:39.765588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.216754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T17:02:39.765692Z","caller":"traceutil/trace.go:171","msg":"trace[1954012526] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:15123; }","duration":"111.387398ms","start":"2026-05-12T17:02:39.654279Z","end":"2026-05-12T17:02:39.765667Z","steps":["trace[1954012526] 'agreement among raft nodes before linearized reading' (duration: 111.221104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:02:39.765927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.032939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-05-12T17:02:39.765935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.102445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-05-12T17:02:39.765962Z","caller":"traceutil/trace.go:171","msg":"trace[1189772024] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:15123; }","duration":"136.125622ms","start":"2026-05-12T17:02:39.629827Z","end":"2026-05-12T17:02:39.765953Z","steps":["trace[1189772024] 'agreement among raft nodes before linearized reading' (duration: 136.039649ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:02:39.765982Z","caller":"traceutil/trace.go:171","msg":"trace[359523235] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15123; }","duration":"231.202287ms","start":"2026-05-12T17:02:39.534766Z","end":"2026-05-12T17:02:39.765968Z","steps":["trace[359523235] 'agreement among raft nodes before linearized reading' (duration: 230.982091ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:03:30.236915Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12915} {"level":"info","ts":"2026-05-12T17:03:30.31738Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12915,"took":"76.777838ms","hash":4239737533,"current-db-size-bytes":58130432,"current-db-size":"58 MB","current-db-size-in-use-bytes":30318592,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-12T17:03:30.317539Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4239737533,"revision":12915,"compact-revision":10776} {"level":"info","ts":"2026-05-12T17:08:30.244981Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15444} {"level":"info","ts":"2026-05-12T17:08:30.349623Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15444,"took":"100.890683ms","hash":2486171631,"current-db-size-bytes":58130432,"current-db-size":"58 MB","current-db-size-in-use-bytes":32215040,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-12T17:08:30.349701Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2486171631,"revision":15444,"compact-revision":12915} {"level":"info","ts":"2026-05-12T17:08:31.167545Z","caller":"traceutil/trace.go:171","msg":"trace[1964468282] transaction","detail":"{read_only:false; response_revision:17808; number_of_response:1; }","duration":"422.484778ms","start":"2026-05-12T17:08:30.744917Z","end":"2026-05-12T17:08:31.167401Z","steps":["trace[1964468282] 'process raft request' (duration: 421.824611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:08:31.168168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:08:30.744889Z","time spent":"423.026903ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:08:31.272683Z","caller":"traceutil/trace.go:171","msg":"trace[1959654130] transaction","detail":"{read_only:false; response_revision:17809; number_of_response:1; }","duration":"101.805859ms","start":"2026-05-12T17:08:31.170844Z","end":"2026-05-12T17:08:31.27265Z","steps":["trace[1959654130] 'process raft request' (duration: 101.557371ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:11:43.22465Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"355195d5aae85944","local-member-applied-index":20003,"local-member-snapshot-index":10002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-12T17:11:43.230364Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-05-12T17:11:43.23063Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-05-12T17:13:30.257532Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17798} {"level":"info","ts":"2026-05-12T17:13:30.365378Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17798,"took":"103.572076ms","hash":3245716685,"current-db-size-bytes":58130432,"current-db-size":"58 MB","current-db-size-in-use-bytes":34291712,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-12T17:13:30.365464Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3245716685,"revision":17798,"compact-revision":15444} {"level":"info","ts":"2026-05-12T17:16:10.826755Z","caller":"traceutil/trace.go:171","msg":"trace[1068773010] linearizableReadLoop","detail":"{readStateIndex:21746; appliedIndex:21745; }","duration":"127.26372ms","start":"2026-05-12T17:16:10.699462Z","end":"2026-05-12T17:16:10.826725Z","steps":["trace[1068773010] 'read index received' (duration: 127.059485ms)","trace[1068773010] 'applied index is now lower than readState.Index' (duration: 203.025µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:16:10.826841Z","caller":"traceutil/trace.go:171","msg":"trace[1341023292] transaction","detail":"{read_only:false; response_revision:21029; number_of_response:1; }","duration":"129.584813ms","start":"2026-05-12T17:16:10.6972Z","end":"2026-05-12T17:16:10.826785Z","steps":["trace[1341023292] 'process raft request' (duration: 129.343256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:16:10.826975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.497707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-12T17:16:10.827026Z","caller":"traceutil/trace.go:171","msg":"trace[665872414] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21029; }","duration":"127.61286ms","start":"2026-05-12T17:16:10.699397Z","end":"2026-05-12T17:16:10.827009Z","steps":["trace[665872414] 'agreement among raft nodes before linearized reading' (duration: 127.446295ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:16:16.243571Z","caller":"traceutil/trace.go:171","msg":"trace[1250230925] transaction","detail":"{read_only:false; response_revision:21059; number_of_response:1; }","duration":"121.452932ms","start":"2026-05-12T17:16:16.122035Z","end":"2026-05-12T17:16:16.243488Z","steps":["trace[1250230925] 'process raft request' (duration: 121.267717ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:16:16.382874Z","caller":"traceutil/trace.go:171","msg":"trace[727597211] transaction","detail":"{read_only:false; response_revision:21060; number_of_response:1; }","duration":"260.452631ms","start":"2026-05-12T17:16:16.122364Z","end":"2026-05-12T17:16:16.382817Z","steps":["trace[727597211] 'process raft request' (duration: 187.183979ms)","trace[727597211] 'compare' (duration: 72.987625ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:16:17.033841Z","caller":"traceutil/trace.go:171","msg":"trace[968395161] transaction","detail":"{read_only:false; response_revision:21062; number_of_response:1; }","duration":"154.643475ms","start":"2026-05-12T17:16:16.879152Z","end":"2026-05-12T17:16:17.033795Z","steps":["trace[968395161] 'process raft request' (duration: 133.052258ms)","trace[968395161] 'compare' (duration: 20.898888ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:18:30.26468Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20180} {"level":"info","ts":"2026-05-12T17:18:30.381897Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20180,"took":"112.201341ms","hash":865060324,"current-db-size-bytes":58130432,"current-db-size":"58 MB","current-db-size-in-use-bytes":28356608,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-12T17:18:30.381981Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":865060324,"revision":20180,"compact-revision":17798} {"level":"info","ts":"2026-05-12T17:20:03.374344Z","caller":"traceutil/trace.go:171","msg":"trace[1854222011] transaction","detail":"{read_only:false; response_revision:23348; number_of_response:1; }","duration":"106.870836ms","start":"2026-05-12T17:20:03.267434Z","end":"2026-05-12T17:20:03.374305Z","steps":["trace[1854222011] 'process raft request' (duration: 34.777666ms)","trace[1854222011] 'compare' (duration: 71.922045ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:20:30.585897Z","caller":"traceutil/trace.go:171","msg":"trace[342557319] transaction","detail":"{read_only:false; response_revision:23615; number_of_response:1; }","duration":"217.239956ms","start":"2026-05-12T17:20:30.368578Z","end":"2026-05-12T17:20:30.585818Z","steps":["trace[342557319] 'process raft request' (duration: 215.673594ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:20:30.591126Z","caller":"traceutil/trace.go:171","msg":"trace[1846186787] transaction","detail":"{read_only:false; response_revision:23616; number_of_response:1; }","duration":"221.330918ms","start":"2026-05-12T17:20:30.369761Z","end":"2026-05-12T17:20:30.591092Z","steps":["trace[1846186787] 'process raft request' (duration: 221.03093ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:20:30.591329Z","caller":"traceutil/trace.go:171","msg":"trace[1483618788] linearizableReadLoop","detail":"{readStateIndex:24415; appliedIndex:24411; }","duration":"129.237623ms","start":"2026-05-12T17:20:30.461955Z","end":"2026-05-12T17:20:30.591193Z","steps":["trace[1483618788] 'read index received' (duration: 122.314896ms)","trace[1483618788] 'applied index is now lower than readState.Index' (duration: 6.921387ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:20:30.591442Z","caller":"traceutil/trace.go:171","msg":"trace[1508133332] transaction","detail":"{read_only:false; response_revision:23618; number_of_response:1; }","duration":"220.520326ms","start":"2026-05-12T17:20:30.370908Z","end":"2026-05-12T17:20:30.591428Z","steps":["trace[1508133332] 'process raft request' (duration: 220.218868ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:20:30.591479Z","caller":"traceutil/trace.go:171","msg":"trace[1165497337] transaction","detail":"{read_only:false; response_revision:23617; number_of_response:1; }","duration":"221.274197ms","start":"2026-05-12T17:20:30.370113Z","end":"2026-05-12T17:20:30.591387Z","steps":["trace[1165497337] 'process raft request' (duration: 220.885036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:20:30.591626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.634255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-12T17:20:30.5917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.51462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-12T17:20:30.591743Z","caller":"traceutil/trace.go:171","msg":"trace[1542671681] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23618; }","duration":"126.613273ms","start":"2026-05-12T17:20:30.465116Z","end":"2026-05-12T17:20:30.591729Z","steps":["trace[1542671681] 'agreement among raft nodes before linearized reading' (duration: 126.430078ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:20:30.591828Z","caller":"traceutil/trace.go:171","msg":"trace[1063237764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23618; }","duration":"129.853301ms","start":"2026-05-12T17:20:30.461872Z","end":"2026-05-12T17:20:30.591726Z","steps":["trace[1063237764] 'agreement among raft nodes before linearized reading' (duration: 129.590334ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:23:30.271965Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22126} {"level":"info","ts":"2026-05-12T17:23:30.37997Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22126,"took":"102.675095ms","hash":3241451680,"current-db-size-bytes":58130432,"current-db-size":"58 MB","current-db-size-in-use-bytes":42491904,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-12T17:23:30.380081Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3241451680,"revision":22126,"compact-revision":20180} {"level":"info","ts":"2026-05-12T17:24:08.920054Z","caller":"traceutil/trace.go:171","msg":"trace[1505387380] linearizableReadLoop","detail":"{readStateIndex:26608; appliedIndex:26606; }","duration":"127.029778ms","start":"2026-05-12T17:24:08.79298Z","end":"2026-05-12T17:24:08.920009Z","steps":["trace[1505387380] 'read index received' (duration: 108.904365ms)","trace[1505387380] 'applied index is now lower than readState.Index' (duration: 18.123853ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:08.92026Z","caller":"traceutil/trace.go:171","msg":"trace[714731133] transaction","detail":"{read_only:false; response_revision:25754; number_of_response:1; }","duration":"205.359529ms","start":"2026-05-12T17:24:08.714889Z","end":"2026-05-12T17:24:08.920249Z","steps":["trace[714731133] 'process raft request' (duration: 204.895897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:08.920462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.456889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:24:08.920941Z","caller":"traceutil/trace.go:171","msg":"trace[774190535] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:25754; }","duration":"127.583922ms","start":"2026-05-12T17:24:08.792962Z","end":"2026-05-12T17:24:08.920546Z","steps":["trace[774190535] 'agreement among raft nodes before linearized reading' (duration: 127.404367ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:09.282211Z","caller":"traceutil/trace.go:171","msg":"trace[716479430] transaction","detail":"{read_only:false; response_revision:25755; number_of_response:1; }","duration":"139.927108ms","start":"2026-05-12T17:24:09.142245Z","end":"2026-05-12T17:24:09.282172Z","steps":["trace[716479430] 'process raft request' (duration: 139.63108ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:09.302581Z","caller":"traceutil/trace.go:171","msg":"trace[2046301978] linearizableReadLoop","detail":"{readStateIndex:26610; appliedIndex:26608; }","duration":"152.755179ms","start":"2026-05-12T17:24:09.149797Z","end":"2026-05-12T17:24:09.302552Z","steps":["trace[2046301978] 'read index received' (duration: 132.151647ms)","trace[2046301978] 'applied index is now lower than readState.Index' (duration: 20.602272ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:24:09.302792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.966594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-05-12T17:24:09.302824Z","caller":"traceutil/trace.go:171","msg":"trace[210015922] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:25756; }","duration":"153.046056ms","start":"2026-05-12T17:24:09.149765Z","end":"2026-05-12T17:24:09.302811Z","steps":["trace[210015922] 'agreement among raft nodes before linearized reading' (duration: 152.888921ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:09.302821Z","caller":"traceutil/trace.go:171","msg":"trace[151044718] transaction","detail":"{read_only:false; response_revision:25756; number_of_response:1; }","duration":"160.444498ms","start":"2026-05-12T17:24:09.142343Z","end":"2026-05-12T17:24:09.302787Z","steps":["trace[151044718] 'process raft request' (duration: 159.974294ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:13.640104Z","caller":"traceutil/trace.go:171","msg":"trace[286721113] transaction","detail":"{read_only:false; response_revision:25799; number_of_response:1; }","duration":"103.397824ms","start":"2026-05-12T17:24:13.536673Z","end":"2026-05-12T17:24:13.640071Z","steps":["trace[286721113] 'process raft request' (duration: 103.188088ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:13.640355Z","caller":"traceutil/trace.go:171","msg":"trace[1889837322] linearizableReadLoop","detail":"{readStateIndex:26655; appliedIndex:26654; }","duration":"100.872865ms","start":"2026-05-12T17:24:13.539467Z","end":"2026-05-12T17:24:13.640339Z","steps":["trace[1889837322] 'read index received' (duration: 100.544806ms)","trace[1889837322] 'applied index is now lower than readState.Index' (duration: 326.449µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:24:13.64048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.004389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:24:13.640523Z","caller":"traceutil/trace.go:171","msg":"trace[2000645003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25799; }","duration":"101.122492ms","start":"2026-05-12T17:24:13.539388Z","end":"2026-05-12T17:24:13.640511Z","steps":["trace[2000645003] 'agreement among raft nodes before linearized reading' (duration: 101.030349ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:13.909666Z","caller":"traceutil/trace.go:171","msg":"trace[300543793] transaction","detail":"{read_only:false; response_revision:25800; number_of_response:1; }","duration":"370.173306ms","start":"2026-05-12T17:24:13.539456Z","end":"2026-05-12T17:24:13.909629Z","steps":["trace[300543793] 'process raft request' (duration: 332.605334ms)","trace[300543793] 'compare' (duration: 36.37191ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:24:13.909999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:13.539428Z","time spent":"370.387451ms","remote":"127.0.0.1:40634","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-05-12T17:24:13.910196Z","caller":"traceutil/trace.go:171","msg":"trace[258208802] transaction","detail":"{read_only:false; response_revision:25801; number_of_response:1; }","duration":"367.907294ms","start":"2026-05-12T17:24:13.542263Z","end":"2026-05-12T17:24:13.91017Z","steps":["trace[258208802] 'process raft request' (duration: 367.222885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:13.910369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:13.542238Z","time spent":"368.042888ms","remote":"127.0.0.1:40634","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-05-12T17:24:13.910617Z","caller":"traceutil/trace.go:171","msg":"trace[1020937904] transaction","detail":"{read_only:false; response_revision:25802; number_of_response:1; }","duration":"366.850304ms","start":"2026-05-12T17:24:13.543748Z","end":"2026-05-12T17:24:13.910598Z","steps":["trace[1020937904] 'process raft request' (duration: 366.372491ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:13.910597Z","caller":"traceutil/trace.go:171","msg":"trace[671991962] linearizableReadLoop","detail":"{readStateIndex:26659; appliedIndex:26655; }","duration":"270.174023ms","start":"2026-05-12T17:24:13.640388Z","end":"2026-05-12T17:24:13.910562Z","steps":["trace[671991962] 'read index received' (duration: 231.583443ms)","trace[671991962] 'applied index is now lower than readState.Index' (duration: 38.58888ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:24:13.9107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:13.543735Z","time spent":"366.921577ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:24:13.910688Z","caller":"traceutil/trace.go:171","msg":"trace[89224753] transaction","detail":"{read_only:false; response_revision:25803; number_of_response:1; }","duration":"366.884945ms","start":"2026-05-12T17:24:13.54376Z","end":"2026-05-12T17:24:13.910645Z","steps":["trace[89224753] 'process raft request' (duration: 366.602658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:13.910903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:13.54373Z","time spent":"367.067741ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:24:13.910946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.021111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-12T17:24:13.911051Z","caller":"traceutil/trace.go:171","msg":"trace[2040714194] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25803; }","duration":"356.194156ms","start":"2026-05-12T17:24:13.554837Z","end":"2026-05-12T17:24:13.911031Z","steps":["trace[2040714194] 'agreement among raft nodes before linearized reading' (duration: 355.880827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:13.911105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:13.554803Z","time spent":"356.293588ms","remote":"127.0.0.1:40634","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-05-12T17:24:14.178946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.108239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:24:14.179564Z","caller":"traceutil/trace.go:171","msg":"trace[748919795] transaction","detail":"{read_only:false; response_revision:25805; number_of_response:1; }","duration":"291.650279ms","start":"2026-05-12T17:24:13.887894Z","end":"2026-05-12T17:24:14.179544Z","steps":["trace[748919795] 'process raft request' (duration: 188.571274ms)","trace[748919795] 'compare' (duration: 101.869522ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:14.17953Z","caller":"traceutil/trace.go:171","msg":"trace[521201120] transaction","detail":"{read_only:false; response_revision:25806; number_of_response:1; }","duration":"262.3557ms","start":"2026-05-12T17:24:13.917135Z","end":"2026-05-12T17:24:14.179491Z","steps":["trace[521201120] 'process raft request' (duration: 261.926319ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:14.179225Z","caller":"traceutil/trace.go:171","msg":"trace[200972648] linearizableReadLoop","detail":"{readStateIndex:26661; appliedIndex:26659; }","duration":"268.488588ms","start":"2026-05-12T17:24:13.910689Z","end":"2026-05-12T17:24:14.179177Z","steps":["trace[200972648] 'read index received' (duration: 14.357221ms)","trace[200972648] 'applied index is now lower than readState.Index' (duration: 254.130177ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:24:14.179579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.655995ms","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-05-12T17:24:14.179664Z","caller":"traceutil/trace.go:171","msg":"trace[1388904588] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25806; }","duration":"346.784439ms","start":"2026-05-12T17:24:13.832864Z","end":"2026-05-12T17:24:14.179648Z","steps":["trace[1388904588] 'agreement among raft nodes before linearized reading' (duration: 346.630254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:14.179716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:13.832856Z","time spent":"346.848301ms","remote":"127.0.0.1:59972","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":"warn","ts":"2026-05-12T17:24:14.179892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.523336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-12T17:24:14.179996Z","caller":"traceutil/trace.go:171","msg":"trace[1309137203] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25806; }","duration":"381.68317ms","start":"2026-05-12T17:24:13.798279Z","end":"2026-05-12T17:24:14.179962Z","steps":["trace[1309137203] 'agreement among raft nodes before linearized reading' (duration: 381.3447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:14.180064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:13.798253Z","time spent":"381.797813ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-12T17:24:14.181558Z","caller":"traceutil/trace.go:171","msg":"trace[2077211992] transaction","detail":"{read_only:false; response_revision:25807; number_of_response:1; }","duration":"255.387591ms","start":"2026-05-12T17:24:13.926107Z","end":"2026-05-12T17:24:14.181495Z","steps":["trace[2077211992] 'process raft request' (duration: 255.089563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:14.483748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.90831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-12T17:24:14.483837Z","caller":"traceutil/trace.go:171","msg":"trace[956780856] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25808; }","duration":"150.057414ms","start":"2026-05-12T17:24:14.33376Z","end":"2026-05-12T17:24:14.483817Z","steps":["trace[956780856] 'range keys from in-memory index tree' (duration: 149.602792ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:14.603117Z","caller":"traceutil/trace.go:171","msg":"trace[2079585596] transaction","detail":"{read_only:false; response_revision:25809; number_of_response:1; }","duration":"135.885479ms","start":"2026-05-12T17:24:14.467204Z","end":"2026-05-12T17:24:14.603089Z","steps":["trace[2079585596] 'process raft request' (duration: 135.718064ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:14.643806Z","caller":"traceutil/trace.go:171","msg":"trace[587774094] transaction","detail":"{read_only:false; response_revision:25810; number_of_response:1; }","duration":"155.439081ms","start":"2026-05-12T17:24:14.488333Z","end":"2026-05-12T17:24:14.643772Z","steps":["trace[587774094] 'process raft request' (duration: 155.245096ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:15.08961Z","caller":"traceutil/trace.go:171","msg":"trace[376974397] transaction","detail":"{read_only:false; response_revision:25811; number_of_response:1; }","duration":"155.984955ms","start":"2026-05-12T17:24:14.933596Z","end":"2026-05-12T17:24:15.089581Z","steps":["trace[376974397] 'process raft request' (duration: 155.765009ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:15.388082Z","caller":"traceutil/trace.go:171","msg":"trace[616264146] transaction","detail":"{read_only:false; response_revision:25812; number_of_response:1; }","duration":"127.203053ms","start":"2026-05-12T17:24:15.260845Z","end":"2026-05-12T17:24:15.388048Z","steps":["trace[616264146] 'process raft request' (duration: 126.867354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:15.629604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.290735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:24:15.629707Z","caller":"traceutil/trace.go:171","msg":"trace[407265733] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:25814; }","duration":"170.44202ms","start":"2026-05-12T17:24:15.459239Z","end":"2026-05-12T17:24:15.629681Z","steps":["trace[407265733] 'count revisions from in-memory index tree' (duration: 170.180843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:15.629979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.392511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:24:15.630053Z","caller":"traceutil/trace.go:171","msg":"trace[123113245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25814; }","duration":"169.467833ms","start":"2026-05-12T17:24:15.460542Z","end":"2026-05-12T17:24:15.630009Z","steps":["trace[123113245] 'range keys from in-memory index tree' (duration: 169.317198ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:15.770773Z","caller":"traceutil/trace.go:171","msg":"trace[1399164885] transaction","detail":"{read_only:false; response_revision:25815; number_of_response:1; }","duration":"123.45658ms","start":"2026-05-12T17:24:15.647283Z","end":"2026-05-12T17:24:15.770739Z","steps":["trace[1399164885] 'process raft request' (duration: 123.259765ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:16.140281Z","caller":"traceutil/trace.go:171","msg":"trace[1671124925] transaction","detail":"{read_only:false; response_revision:25817; number_of_response:1; }","duration":"222.060114ms","start":"2026-05-12T17:24:15.918188Z","end":"2026-05-12T17:24:16.140248Z","steps":["trace[1671124925] 'process raft request' (duration: 196.310643ms)","trace[1671124925] 'compare' (duration: 25.549676ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:16.140637Z","caller":"traceutil/trace.go:171","msg":"trace[931949980] linearizableReadLoop","detail":"{readStateIndex:26676; appliedIndex:26672; }","duration":"166.325158ms","start":"2026-05-12T17:24:15.974291Z","end":"2026-05-12T17:24:16.140616Z","steps":["trace[931949980] 'read index received' (duration: 140.236647ms)","trace[931949980] 'applied index is now lower than readState.Index' (duration: 26.087811ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:16.140702Z","caller":"traceutil/trace.go:171","msg":"trace[729757117] transaction","detail":"{read_only:false; response_revision:25820; number_of_response:1; }","duration":"211.632441ms","start":"2026-05-12T17:24:15.929059Z","end":"2026-05-12T17:24:16.140692Z","steps":["trace[729757117] 'process raft request' (duration: 211.510497ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:16.140699Z","caller":"traceutil/trace.go:171","msg":"trace[691480535] transaction","detail":"{read_only:false; response_revision:25819; number_of_response:1; }","duration":"216.571116ms","start":"2026-05-12T17:24:15.924091Z","end":"2026-05-12T17:24:16.140662Z","steps":["trace[691480535] 'process raft request' (duration: 216.342629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:16.140818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.145558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:24:16.140857Z","caller":"traceutil/trace.go:171","msg":"trace[1832803510] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:25820; }","duration":"157.239551ms","start":"2026-05-12T17:24:15.983606Z","end":"2026-05-12T17:24:16.140846Z","steps":["trace[1832803510] 'agreement among raft nodes before linearized reading' (duration: 157.149288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:16.140865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.524873ms","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-05-12T17:24:16.140972Z","caller":"traceutil/trace.go:171","msg":"trace[1486807748] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25820; }","duration":"166.718529ms","start":"2026-05-12T17:24:15.974229Z","end":"2026-05-12T17:24:16.140947Z","steps":["trace[1486807748] 'agreement among raft nodes before linearized reading' (duration: 166.477722ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:16.141572Z","caller":"traceutil/trace.go:171","msg":"trace[256279865] transaction","detail":"{read_only:false; response_revision:25818; number_of_response:1; }","duration":"220.705327ms","start":"2026-05-12T17:24:15.9199Z","end":"2026-05-12T17:24:16.140605Z","steps":["trace[256279865] 'process raft request' (duration: 220.303786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:16.389864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.905205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:24:16.390006Z","caller":"traceutil/trace.go:171","msg":"trace[1604768222] transaction","detail":"{read_only:false; response_revision:25821; number_of_response:1; }","duration":"204.419204ms","start":"2026-05-12T17:24:16.185562Z","end":"2026-05-12T17:24:16.389981Z","steps":["trace[1604768222] 'process raft request' (duration: 88.249072ms)","trace[1604768222] 'compare' (duration: 114.406054ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:16.555076Z","caller":"traceutil/trace.go:171","msg":"trace[730120538] linearizableReadLoop","detail":"{readStateIndex:26678; appliedIndex:26676; }","duration":"345.435203ms","start":"2026-05-12T17:24:16.209614Z","end":"2026-05-12T17:24:16.555049Z","steps":["trace[730120538] 'read index received' (duration: 64.331831ms)","trace[730120538] 'applied index is now lower than readState.Index' (duration: 281.101942ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:16.555227Z","caller":"traceutil/trace.go:171","msg":"trace[343463015] transaction","detail":"{read_only:false; response_revision:25822; number_of_response:1; }","duration":"366.945788ms","start":"2026-05-12T17:24:16.188268Z","end":"2026-05-12T17:24:16.555213Z","steps":["trace[343463015] 'process raft request' (duration: 351.750223ms)","trace[343463015] 'compare' (duration: 14.902876ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:24:16.555359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:16.188245Z","time spent":"367.0119ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:24:16.555642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.003908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-12T17:24:16.555757Z","caller":"traceutil/trace.go:171","msg":"trace[1019721460] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25822; }","duration":"346.163453ms","start":"2026-05-12T17:24:16.20956Z","end":"2026-05-12T17:24:16.555723Z","steps":["trace[1019721460] 'agreement among raft nodes before linearized reading' (duration: 345.883825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:16.555817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:16.209542Z","time spent":"346.260266ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-12T17:24:16.767126Z","caller":"traceutil/trace.go:171","msg":"trace[867879424] linearizableReadLoop","detail":"{readStateIndex:26680; appliedIndex:26679; }","duration":"152.320495ms","start":"2026-05-12T17:24:16.614768Z","end":"2026-05-12T17:24:16.767089Z","steps":["trace[867879424] 'read index received' (duration: 112.602944ms)","trace[867879424] 'applied index is now lower than readState.Index' (duration: 39.715821ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:24:16.76759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.796959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:24:16.767589Z","caller":"traceutil/trace.go:171","msg":"trace[1028013778] transaction","detail":"{read_only:false; response_revision:25824; number_of_response:1; }","duration":"157.024955ms","start":"2026-05-12T17:24:16.610295Z","end":"2026-05-12T17:24:16.76732Z","steps":["trace[1028013778] 'process raft request' (duration: 117.199531ms)","trace[1028013778] 'compare' (duration: 39.396912ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:16.767735Z","caller":"traceutil/trace.go:171","msg":"trace[24550247] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25824; }","duration":"152.971533ms","start":"2026-05-12T17:24:16.61471Z","end":"2026-05-12T17:24:16.767681Z","steps":["trace[24550247] 'agreement among raft nodes before linearized reading' (duration: 152.790898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:16.768341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.853171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-12T17:24:16.768438Z","caller":"traceutil/trace.go:171","msg":"trace[1924839594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25824; }","duration":"120.991154ms","start":"2026-05-12T17:24:16.647433Z","end":"2026-05-12T17:24:16.768424Z","steps":["trace[1924839594] 'agreement among raft nodes before linearized reading' (duration: 120.760348ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:16.977101Z","caller":"traceutil/trace.go:171","msg":"trace[693543301] transaction","detail":"{read_only:false; response_revision:25825; number_of_response:1; }","duration":"204.307331ms","start":"2026-05-12T17:24:16.77274Z","end":"2026-05-12T17:24:16.977047Z","steps":["trace[693543301] 'process raft request' (duration: 191.096341ms)","trace[693543301] 'compare' (duration: 12.913182ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:17.659767Z","caller":"traceutil/trace.go:171","msg":"trace[1236599789] transaction","detail":"{read_only:false; response_revision:25827; number_of_response:1; }","duration":"245.438001ms","start":"2026-05-12T17:24:17.414243Z","end":"2026-05-12T17:24:17.659681Z","steps":["trace[1236599789] 'process raft request' (duration: 221.720704ms)","trace[1236599789] 'compare' (duration: 23.10938ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:18.026504Z","caller":"traceutil/trace.go:171","msg":"trace[1400427952] transaction","detail":"{read_only:false; response_revision:25828; number_of_response:1; }","duration":"249.958104ms","start":"2026-05-12T17:24:17.776515Z","end":"2026-05-12T17:24:18.026473Z","steps":["trace[1400427952] 'process raft request' (duration: 249.739178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:18.382443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.922356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:24:18.382926Z","caller":"traceutil/trace.go:171","msg":"trace[1703549651] transaction","detail":"{read_only:false; response_revision:25833; number_of_response:1; }","duration":"233.892487ms","start":"2026-05-12T17:24:18.149007Z","end":"2026-05-12T17:24:18.382899Z","steps":["trace[1703549651] 'process raft request' (duration: 233.842226ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:18.38332Z","caller":"traceutil/trace.go:171","msg":"trace[1947161465] transaction","detail":"{read_only:false; response_revision:25829; number_of_response:1; }","duration":"436.104231ms","start":"2026-05-12T17:24:17.947202Z","end":"2026-05-12T17:24:18.383306Z","steps":["trace[1947161465] 'process raft request' (duration: 204.169987ms)","trace[1947161465] 'compare' (duration: 230.636719ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:24:18.383392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:17.947169Z","time spent":"436.185793ms","remote":"127.0.0.1:40634","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-05-12T17:24:18.38355Z","caller":"traceutil/trace.go:171","msg":"trace[1615737476] linearizableReadLoop","detail":"{readStateIndex:26685; appliedIndex:26684; }","duration":"270.273816ms","start":"2026-05-12T17:24:18.113265Z","end":"2026-05-12T17:24:18.383539Z","steps":["trace[1615737476] 'read index received' (duration: 38.153478ms)","trace[1615737476] 'applied index is now lower than readState.Index' (duration: 232.118908ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:18.383658Z","caller":"traceutil/trace.go:171","msg":"trace[457823855] transaction","detail":"{read_only:false; response_revision:25830; number_of_response:1; }","duration":"236.869437ms","start":"2026-05-12T17:24:18.146781Z","end":"2026-05-12T17:24:18.38365Z","steps":["trace[457823855] 'process raft request' (duration: 235.835839ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:18.383798Z","caller":"traceutil/trace.go:171","msg":"trace[342482087] transaction","detail":"{read_only:false; response_revision:25831; number_of_response:1; }","duration":"236.870907ms","start":"2026-05-12T17:24:18.146918Z","end":"2026-05-12T17:24:18.383789Z","steps":["trace[342482087] 'process raft request' (duration: 235.834789ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:18.383926Z","caller":"traceutil/trace.go:171","msg":"trace[1886828666] transaction","detail":"{read_only:false; response_revision:25832; number_of_response:1; }","duration":"236.902617ms","start":"2026-05-12T17:24:18.147014Z","end":"2026-05-12T17:24:18.383917Z","steps":["trace[1886828666] 'process raft request' (duration: 235.784427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:18.384253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.005966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-05-12T17:24:18.384294Z","caller":"traceutil/trace.go:171","msg":"trace[690134408] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:25833; }","duration":"271.093598ms","start":"2026-05-12T17:24:18.113189Z","end":"2026-05-12T17:24:18.384283Z","steps":["trace[690134408] 'agreement among raft nodes before linearized reading' (duration: 270.951514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:18.384485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.321933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:24:18.384526Z","caller":"traceutil/trace.go:171","msg":"trace[695129797] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25833; }","duration":"203.396176ms","start":"2026-05-12T17:24:18.181117Z","end":"2026-05-12T17:24:18.384513Z","steps":["trace[695129797] 'agreement among raft nodes before linearized reading' (duration: 203.333813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:18.847308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.793108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:24:18.848621Z","caller":"traceutil/trace.go:171","msg":"trace[1003475504] transaction","detail":"{read_only:false; response_revision:25834; number_of_response:1; }","duration":"453.524745ms","start":"2026-05-12T17:24:18.39505Z","end":"2026-05-12T17:24:18.848575Z","steps":["trace[1003475504] 'process raft request' (duration: 151.34614ms)","trace[1003475504] 'compare' (duration: 300.647704ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:24:18.848844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:18.395022Z","time spent":"453.69966ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:24:18.848981Z","caller":"traceutil/trace.go:171","msg":"trace[350039650] linearizableReadLoop","detail":"{readStateIndex:26690; appliedIndex:26689; }","duration":"439.865163ms","start":"2026-05-12T17:24:18.409065Z","end":"2026-05-12T17:24:18.848931Z","steps":["trace[350039650] 'read index received' (duration: 137.40301ms)","trace[350039650] 'applied index is now lower than readState.Index' (duration: 302.442282ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:24:19.00025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.814859ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-05-12T17:24:19.000626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.554002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-12T17:24:19.000693Z","caller":"traceutil/trace.go:171","msg":"trace[1716796002] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25834; }","duration":"591.646854ms","start":"2026-05-12T17:24:18.409028Z","end":"2026-05-12T17:24:19.000675Z","steps":["trace[1716796002] 'agreement among raft nodes before linearized reading' (duration: 439.980406ms)","trace[1716796002] 'range keys from in-memory index tree' (duration: 151.457533ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:19.000654Z","caller":"traceutil/trace.go:171","msg":"trace[589939185] linearizableReadLoop","detail":"{readStateIndex:26692; appliedIndex:26690; }","duration":"151.521854ms","start":"2026-05-12T17:24:18.849094Z","end":"2026-05-12T17:24:19.000615Z","steps":["trace[589939185] 'read index received' (duration: 2.851968ms)","trace[589939185] 'applied index is now lower than readState.Index' (duration: 148.667326ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:19.000654Z","caller":"traceutil/trace.go:171","msg":"trace[1966943025] transaction","detail":"{read_only:false; response_revision:25835; number_of_response:1; }","duration":"226.400551ms","start":"2026-05-12T17:24:18.774216Z","end":"2026-05-12T17:24:19.000616Z","steps":["trace[1966943025] 'process raft request' (duration: 226.137174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:19.000725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:18.409017Z","time spent":"591.701136ms","remote":"127.0.0.1:40560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-12T17:24:19.080649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.943983ms","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-05-12T17:24:19.080733Z","caller":"traceutil/trace.go:171","msg":"trace[1112868303] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25835; }","duration":"394.045806ms","start":"2026-05-12T17:24:18.686662Z","end":"2026-05-12T17:24:19.080708Z","steps":["trace[1112868303] 'agreement among raft nodes before linearized reading' (duration: 314.058428ms)","trace[1112868303] 'count revisions from in-memory index tree' (duration: 79.874775ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:24:19.080782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:18.686654Z","time spent":"394.114538ms","remote":"127.0.0.1:55620","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-05-12T17:24:19.080834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.749111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-12T17:24:19.080856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.676092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-12T17:24:19.080969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.808181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T17:24:19.080988Z","caller":"traceutil/trace.go:171","msg":"trace[46314390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25835; }","duration":"506.868856ms","start":"2026-05-12T17:24:18.574088Z","end":"2026-05-12T17:24:19.080957Z","steps":["trace[46314390] 'agreement among raft nodes before linearized reading' (duration: 426.666743ms)","trace[46314390] 'range keys from in-memory index tree' (duration: 79.933076ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:19.081013Z","caller":"traceutil/trace.go:171","msg":"trace[990887691] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:25835; }","duration":"511.892783ms","start":"2026-05-12T17:24:18.569106Z","end":"2026-05-12T17:24:19.080999Z","steps":["trace[990887691] 'agreement among raft nodes before linearized reading' (duration: 431.611298ms)","trace[990887691] 'count revisions from in-memory index tree' (duration: 80.203583ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:24:19.08107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:18.574028Z","time spent":"507.00482ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T17:24:19.081081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:18.569081Z","time spent":"511.988226ms","remote":"127.0.0.1:42468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":31,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true "} {"level":"warn","ts":"2026-05-12T17:24:19.081143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.774132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-12T17:24:19.080913Z","caller":"traceutil/trace.go:171","msg":"trace[1904611164] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:25835; }","duration":"442.855144ms","start":"2026-05-12T17:24:18.638029Z","end":"2026-05-12T17:24:19.080884Z","steps":["trace[1904611164] 'agreement among raft nodes before linearized reading' (duration: 362.709882ms)","trace[1904611164] 'count revisions from in-memory index tree' (duration: 80.07206ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:24:19.080619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.210709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:24:19.081268Z","caller":"traceutil/trace.go:171","msg":"trace[1591242388] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:25835; }","duration":"553.944198ms","start":"2026-05-12T17:24:18.527306Z","end":"2026-05-12T17:24:19.08125Z","steps":["trace[1591242388] 'agreement among raft nodes before linearized reading' (duration: 473.431186ms)","trace[1591242388] 'count revisions from in-memory index tree' (duration: 79.781842ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:24:19.081321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:18.638005Z","time spent":"443.249384ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":126,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-05-12T17:24:19.081359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:18.527283Z","time spent":"554.059232ms","remote":"127.0.0.1:39174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true "} {"level":"info","ts":"2026-05-12T17:24:19.081211Z","caller":"traceutil/trace.go:171","msg":"trace[989573845] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25835; }","duration":"467.857675ms","start":"2026-05-12T17:24:18.613331Z","end":"2026-05-12T17:24:19.081189Z","steps":["trace[989573845] 'agreement among raft nodes before linearized reading' (duration: 387.320752ms)","trace[989573845] 'range keys from in-memory index tree' (duration: 80.331067ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:24:19.081605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:18.613319Z","time spent":"468.265355ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-12T17:24:19.082146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.687464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:24:19.082397Z","caller":"traceutil/trace.go:171","msg":"trace[1028804827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25835; }","duration":"621.399934ms","start":"2026-05-12T17:24:18.460934Z","end":"2026-05-12T17:24:19.082334Z","steps":["trace[1028804827] 'agreement among raft nodes before linearized reading' (duration: 539.836703ms)","trace[1028804827] 'range keys from in-memory index tree' (duration: 80.851791ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:24:19.082499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:18.460878Z","time spent":"621.605009ms","remote":"127.0.0.1:40410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-12T17:24:19.285336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.290802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-12T17:24:19.285423Z","caller":"traceutil/trace.go:171","msg":"trace[1617884234] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25838; }","duration":"103.431675ms","start":"2026-05-12T17:24:19.181968Z","end":"2026-05-12T17:24:19.2854Z","steps":["trace[1617884234] 'range keys from in-memory index tree' (duration: 103.132998ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:26.604364Z","caller":"traceutil/trace.go:171","msg":"trace[1804912513] transaction","detail":"{read_only:false; response_revision:25888; number_of_response:1; }","duration":"191.138873ms","start":"2026-05-12T17:24:26.413192Z","end":"2026-05-12T17:24:26.60433Z","steps":["trace[1804912513] 'process raft request' (duration: 190.920018ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:26.932514Z","caller":"traceutil/trace.go:171","msg":"trace[700798663] transaction","detail":"{read_only:false; response_revision:25890; number_of_response:1; }","duration":"490.029338ms","start":"2026-05-12T17:24:26.442459Z","end":"2026-05-12T17:24:26.932488Z","steps":["trace[700798663] 'process raft request' (duration: 489.940726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:26.932692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:26.442435Z","time spent":"490.171082ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:24:26.932841Z","caller":"traceutil/trace.go:171","msg":"trace[877829313] transaction","detail":"{read_only:false; response_revision:25889; number_of_response:1; }","duration":"519.041968ms","start":"2026-05-12T17:24:26.413788Z","end":"2026-05-12T17:24:26.93283Z","steps":["trace[877829313] 'process raft request' (duration: 518.421011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:26.932913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:26.413759Z","time spent":"519.11196ms","remote":"127.0.0.1:40634","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-05-12T17:24:26.93308Z","caller":"traceutil/trace.go:171","msg":"trace[207215938] transaction","detail":"{read_only:false; response_revision:25891; number_of_response:1; }","duration":"484.9775ms","start":"2026-05-12T17:24:26.448091Z","end":"2026-05-12T17:24:26.933068Z","steps":["trace[207215938] 'process raft request' (duration: 484.355683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:26.933142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:26.448081Z","time spent":"485.030492ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:24:26.93354Z","caller":"traceutil/trace.go:171","msg":"trace[321784540] linearizableReadLoop","detail":"{readStateIndex:26750; appliedIndex:26745; }","duration":"445.27414ms","start":"2026-05-12T17:24:26.488252Z","end":"2026-05-12T17:24:26.933526Z","steps":["trace[321784540] 'read index received' (duration: 115.785063ms)","trace[321784540] 'applied index is now lower than readState.Index' (duration: 329.487407ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:26.933616Z","caller":"traceutil/trace.go:171","msg":"trace[82382371] transaction","detail":"{read_only:false; response_revision:25892; number_of_response:1; }","duration":"482.646516ms","start":"2026-05-12T17:24:26.450959Z","end":"2026-05-12T17:24:26.933605Z","steps":["trace[82382371] 'process raft request' (duration: 482.04958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:26.933673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:26.450931Z","time spent":"482.709398ms","remote":"127.0.0.1:40634","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-05-12T17:24:26.933879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.604159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:24:26.934033Z","caller":"traceutil/trace.go:171","msg":"trace[698331374] 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:25892; }","duration":"445.820914ms","start":"2026-05-12T17:24:26.488187Z","end":"2026-05-12T17:24:26.934008Z","steps":["trace[698331374] 'agreement among raft nodes before linearized reading' (duration: 445.607339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:26.934121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:26.48816Z","time spent":"445.945368ms","remote":"127.0.0.1:53660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-12T17:24:27.458922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.331289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:24:27.459159Z","caller":"traceutil/trace.go:171","msg":"trace[42908262] transaction","detail":"{read_only:false; response_revision:25893; number_of_response:1; }","duration":"577.614332ms","start":"2026-05-12T17:24:26.881505Z","end":"2026-05-12T17:24:27.459119Z","steps":["trace[42908262] 'process raft request' (duration: 306.950504ms)","trace[42908262] 'compare' (duration: 269.851066ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:27.459254Z","caller":"traceutil/trace.go:171","msg":"trace[1323133816] transaction","detail":"{read_only:false; response_revision:25894; number_of_response:1; }","duration":"429.704667ms","start":"2026-05-12T17:24:27.029526Z","end":"2026-05-12T17:24:27.45923Z","steps":["trace[1323133816] 'process raft request' (duration: 429.578144ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:27.459291Z","caller":"traceutil/trace.go:171","msg":"trace[1788916591] linearizableReadLoop","detail":"{readStateIndex:26751; appliedIndex:26750; }","duration":"525.666728ms","start":"2026-05-12T17:24:26.933573Z","end":"2026-05-12T17:24:27.459239Z","steps":["trace[1788916591] 'read index received' (duration: 254.837356ms)","trace[1788916591] 'applied index is now lower than readState.Index' (duration: 270.816372ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:24:27.459349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:27.02951Z","time spent":"429.805189ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:24:27.459354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.531665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-12T17:24:27.459429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:26.881488Z","time spent":"577.824307ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:24:27.45944Z","caller":"traceutil/trace.go:171","msg":"trace[987618482] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:25894; }","duration":"532.70605ms","start":"2026-05-12T17:24:26.926717Z","end":"2026-05-12T17:24:27.459423Z","steps":["trace[987618482] 'agreement among raft nodes before linearized reading' (duration: 532.527655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:27.459491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:26.92669Z","time spent":"532.788452ms","remote":"127.0.0.1:40748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-05-12T17:24:27.459451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.969294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-12T17:24:27.459524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.497637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-05-12T17:24:27.459559Z","caller":"traceutil/trace.go:171","msg":"trace[861727993] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25894; }","duration":"678.20126ms","start":"2026-05-12T17:24:26.781336Z","end":"2026-05-12T17:24:27.459537Z","steps":["trace[861727993] 'agreement among raft nodes before linearized reading' (duration: 678.020395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:27.459615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:26.781308Z","time spent":"678.298483ms","remote":"127.0.0.1:53402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":29,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"warn","ts":"2026-05-12T17:24:27.459618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.258144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:24:27.459626Z","caller":"traceutil/trace.go:171","msg":"trace[506890522] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25894; }","duration":"603.63736ms","start":"2026-05-12T17:24:26.85596Z","end":"2026-05-12T17:24:27.459597Z","steps":["trace[506890522] 'agreement among raft nodes before linearized reading' (duration: 603.321561ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:27.459653Z","caller":"traceutil/trace.go:171","msg":"trace[1866493505] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25894; }","duration":"256.310616ms","start":"2026-05-12T17:24:27.203332Z","end":"2026-05-12T17:24:27.459642Z","steps":["trace[1866493505] 'agreement among raft nodes before linearized reading' (duration: 256.263095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:27.459679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:26.855948Z","time spent":"603.721572ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":585,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-05-12T17:24:27.459683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.982947ms","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":"warn","ts":"2026-05-12T17:24:27.459567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.422858ms","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-05-12T17:24:27.459764Z","caller":"traceutil/trace.go:171","msg":"trace[1815052455] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25894; }","duration":"245.132072ms","start":"2026-05-12T17:24:27.214614Z","end":"2026-05-12T17:24:27.459746Z","steps":["trace[1815052455] 'agreement among raft nodes before linearized reading' (duration: 244.914056ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:27.459782Z","caller":"traceutil/trace.go:171","msg":"trace[196086614] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25894; }","duration":"444.703225ms","start":"2026-05-12T17:24:27.01506Z","end":"2026-05-12T17:24:27.459763Z","steps":["trace[196086614] 'agreement among raft nodes before linearized reading' (duration: 444.403357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:27.459835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:27.015041Z","time spent":"444.775417ms","remote":"127.0.0.1:59960","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":"warn","ts":"2026-05-12T17:24:27.4599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.079994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-12T17:24:27.459932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.625547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-12T17:24:27.459948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.576857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-12T17:24:27.459978Z","caller":"traceutil/trace.go:171","msg":"trace[875829936] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25894; }","duration":"331.694858ms","start":"2026-05-12T17:24:27.128272Z","end":"2026-05-12T17:24:27.459967Z","steps":["trace[875829936] 'agreement among raft nodes before linearized reading' (duration: 331.545294ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:27.459989Z","caller":"traceutil/trace.go:171","msg":"trace[975526988] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:25894; }","duration":"280.193367ms","start":"2026-05-12T17:24:27.179773Z","end":"2026-05-12T17:24:27.459966Z","steps":["trace[975526988] 'agreement among raft nodes before linearized reading' (duration: 280.004662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:27.460006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:27.12826Z","time spent":"331.74162ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-12T17:24:27.460012Z","caller":"traceutil/trace.go:171","msg":"trace[1661648655] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25894; }","duration":"244.69758ms","start":"2026-05-12T17:24:27.215299Z","end":"2026-05-12T17:24:27.459997Z","steps":["trace[1661648655] 'agreement among raft nodes before linearized reading' (duration: 244.496824ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:28.003195Z","caller":"traceutil/trace.go:171","msg":"trace[1058396787] linearizableReadLoop","detail":"{readStateIndex:26756; appliedIndex:26752; }","duration":"537.022397ms","start":"2026-05-12T17:24:27.466141Z","end":"2026-05-12T17:24:28.003163Z","steps":["trace[1058396787] 'read index received' (duration: 4.432181ms)","trace[1058396787] 'applied index is now lower than readState.Index' (duration: 532.588636ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:28.003672Z","caller":"traceutil/trace.go:171","msg":"trace[575350913] transaction","detail":"{read_only:false; response_revision:25896; number_of_response:1; }","duration":"538.136728ms","start":"2026-05-12T17:24:27.465522Z","end":"2026-05-12T17:24:28.003659Z","steps":["trace[575350913] 'process raft request' (duration: 537.308365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:28.003809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:27.465506Z","time spent":"538.197879ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:24:28.003997Z","caller":"traceutil/trace.go:171","msg":"trace[501595622] transaction","detail":"{read_only:false; response_revision:25897; number_of_response:1; }","duration":"538.045025ms","start":"2026-05-12T17:24:27.465942Z","end":"2026-05-12T17:24:28.003987Z","steps":["trace[501595622] 'process raft request' (duration: 537.126821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:28.004051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:27.465924Z","time spent":"538.094676ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:24:28.00417Z","caller":"traceutil/trace.go:171","msg":"trace[1952847188] transaction","detail":"{read_only:false; response_revision:25898; number_of_response:1; }","duration":"538.099816ms","start":"2026-05-12T17:24:27.46606Z","end":"2026-05-12T17:24:28.00416Z","steps":["trace[1952847188] 'process raft request' (duration: 537.060198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:28.004223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:27.466055Z","time spent":"538.134708ms","remote":"127.0.0.1:40634","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-05-12T17:24:28.004395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.24718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:24:28.004431Z","caller":"traceutil/trace.go:171","msg":"trace[829500258] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25898; }","duration":"538.292352ms","start":"2026-05-12T17:24:27.466126Z","end":"2026-05-12T17:24:28.004418Z","steps":["trace[829500258] 'agreement among raft nodes before linearized reading' (duration: 538.22248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:28.004469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:27.466118Z","time spent":"538.336763ms","remote":"127.0.0.1:40564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-05-12T17:24:28.004786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.236494ms","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-05-12T17:24:28.004817Z","caller":"traceutil/trace.go:171","msg":"trace[720114950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25898; }","duration":"465.268904ms","start":"2026-05-12T17:24:27.539538Z","end":"2026-05-12T17:24:28.004807Z","steps":["trace[720114950] 'agreement among raft nodes before linearized reading' (duration: 465.11776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:28.004841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:27.539469Z","time spent":"465.364636ms","remote":"127.0.0.1:40634","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-05-12T17:24:28.005211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.963083ms","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-05-12T17:24:28.005247Z","caller":"traceutil/trace.go:171","msg":"trace[1368430983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25898; }","duration":"297.049905ms","start":"2026-05-12T17:24:27.708182Z","end":"2026-05-12T17:24:28.005232Z","steps":["trace[1368430983] 'agreement among raft nodes before linearized reading' (duration: 296.957062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:28.451741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.624214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:24:28.451865Z","caller":"traceutil/trace.go:171","msg":"trace[881704447] linearizableReadLoop","detail":"{readStateIndex:26759; appliedIndex:26758; }","duration":"356.316288ms","start":"2026-05-12T17:24:28.09553Z","end":"2026-05-12T17:24:28.451846Z","steps":["trace[881704447] 'read index received' (duration: 174.559641ms)","trace[881704447] 'applied index is now lower than readState.Index' (duration: 181.755777ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:28.451949Z","caller":"traceutil/trace.go:171","msg":"trace[2042360991] transaction","detail":"{read_only:false; response_revision:25901; number_of_response:1; }","duration":"386.709736ms","start":"2026-05-12T17:24:28.06523Z","end":"2026-05-12T17:24:28.451939Z","steps":["trace[2042360991] 'process raft request' (duration: 204.799074ms)","trace[2042360991] 'compare' (duration: 181.241864ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:24:28.452003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:28.065199Z","time spent":"386.776928ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:24:28.4523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.37168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-05-12T17:24:28.452415Z","caller":"traceutil/trace.go:171","msg":"trace[958700815] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:25901; }","duration":"309.502464ms","start":"2026-05-12T17:24:28.142883Z","end":"2026-05-12T17:24:28.452385Z","steps":["trace[958700815] 'agreement among raft nodes before linearized reading' (duration: 309.185465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:28.452471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:28.142783Z","time spent":"309.677059ms","remote":"127.0.0.1:40532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":1589,"request content":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" "} {"level":"warn","ts":"2026-05-12T17:24:28.452465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.912104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:24:28.452557Z","caller":"traceutil/trace.go:171","msg":"trace[887205776] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:25901; }","duration":"357.076069ms","start":"2026-05-12T17:24:28.095462Z","end":"2026-05-12T17:24:28.452538Z","steps":["trace[887205776] 'agreement among raft nodes before linearized reading' (duration: 356.879533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:28.452599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:28.095446Z","time spent":"357.144041ms","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-12T17:24:29.101272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.519774ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-12T17:24:29.101792Z","caller":"traceutil/trace.go:171","msg":"trace[1574471170] linearizableReadLoop","detail":"{readStateIndex:26761; appliedIndex:26759; }","duration":"293.478768ms","start":"2026-05-12T17:24:28.808278Z","end":"2026-05-12T17:24:29.101757Z","steps":["trace[1574471170] 'read index received' (duration: 75.562µs)","trace[1574471170] 'applied index is now lower than readState.Index' (duration: 293.401436ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:29.101818Z","caller":"traceutil/trace.go:171","msg":"trace[234030793] transaction","detail":"{read_only:false; response_revision:25902; number_of_response:1; }","duration":"491.92453ms","start":"2026-05-12T17:24:28.609848Z","end":"2026-05-12T17:24:29.101772Z","steps":["trace[234030793] 'process raft request' (duration: 491.579901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:29.101955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.657144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:24:29.102005Z","caller":"traceutil/trace.go:171","msg":"trace[18940275] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25902; }","duration":"293.769687ms","start":"2026-05-12T17:24:28.808217Z","end":"2026-05-12T17:24:29.101986Z","steps":["trace[18940275] 'agreement among raft nodes before linearized reading' (duration: 293.656644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:29.10201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:28.609813Z","time spent":"492.094654ms","remote":"127.0.0.1:40634","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-05-12T17:24:29.153158Z","caller":"traceutil/trace.go:171","msg":"trace[1040197880] transaction","detail":"{read_only:false; response_revision:25903; number_of_response:1; }","duration":"214.636762ms","start":"2026-05-12T17:24:28.938492Z","end":"2026-05-12T17:24:29.153129Z","steps":["trace[1040197880] 'process raft request' (duration: 214.434316ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:29.154557Z","caller":"traceutil/trace.go:171","msg":"trace[730769764] transaction","detail":"{read_only:false; response_revision:25905; number_of_response:1; }","duration":"215.147146ms","start":"2026-05-12T17:24:28.939385Z","end":"2026-05-12T17:24:29.154532Z","steps":["trace[730769764] 'process raft request' (duration: 214.703984ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:29.154652Z","caller":"traceutil/trace.go:171","msg":"trace[1116770062] transaction","detail":"{read_only:false; response_revision:25906; number_of_response:1; }","duration":"215.049123ms","start":"2026-05-12T17:24:28.939571Z","end":"2026-05-12T17:24:29.15462Z","steps":["trace[1116770062] 'process raft request' (duration: 214.585731ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:29.155184Z","caller":"traceutil/trace.go:171","msg":"trace[136511659] transaction","detail":"{read_only:false; response_revision:25904; number_of_response:1; }","duration":"216.239906ms","start":"2026-05-12T17:24:28.93891Z","end":"2026-05-12T17:24:29.155149Z","steps":["trace[136511659] 'process raft request' (duration: 214.991092ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:30.643005Z","caller":"traceutil/trace.go:171","msg":"trace[52323199] linearizableReadLoop","detail":"{readStateIndex:26833; appliedIndex:26832; }","duration":"182.5893ms","start":"2026-05-12T17:24:30.460382Z","end":"2026-05-12T17:24:30.642971Z","steps":["trace[52323199] 'read index received' (duration: 104.765203ms)","trace[52323199] 'applied index is now lower than readState.Index' (duration: 77.822587ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:30.643034Z","caller":"traceutil/trace.go:171","msg":"trace[1638678213] transaction","detail":"{read_only:false; response_revision:25974; number_of_response:1; }","duration":"185.207811ms","start":"2026-05-12T17:24:30.457774Z","end":"2026-05-12T17:24:30.642982Z","steps":["trace[1638678213] 'process raft request' (duration: 107.503017ms)","trace[1638678213] 'compare' (duration: 77.425897ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:24:30.643106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.712823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:24:30.643152Z","caller":"traceutil/trace.go:171","msg":"trace[1965649128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25974; }","duration":"182.821306ms","start":"2026-05-12T17:24:30.460318Z","end":"2026-05-12T17:24:30.643139Z","steps":["trace[1965649128] 'agreement among raft nodes before linearized reading' (duration: 182.724133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:37.44021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.528748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:24:37.44039Z","caller":"traceutil/trace.go:171","msg":"trace[1783666115] transaction","detail":"{read_only:false; response_revision:26045; number_of_response:1; }","duration":"259.253397ms","start":"2026-05-12T17:24:37.181106Z","end":"2026-05-12T17:24:37.440359Z","steps":["trace[1783666115] 'process raft request' (duration: 130.459012ms)","trace[1783666115] 'compare' (duration: 128.334673ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:37.467257Z","caller":"traceutil/trace.go:171","msg":"trace[819926754] transaction","detail":"{read_only:false; response_revision:26046; number_of_response:1; }","duration":"284.071762ms","start":"2026-05-12T17:24:37.183153Z","end":"2026-05-12T17:24:37.467225Z","steps":["trace[819926754] 'process raft request' (duration: 283.877547ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:37.46756Z","caller":"traceutil/trace.go:171","msg":"trace[645040516] transaction","detail":"{read_only:false; response_revision:26047; number_of_response:1; }","duration":"280.409302ms","start":"2026-05-12T17:24:37.187126Z","end":"2026-05-12T17:24:37.467536Z","steps":["trace[645040516] 'process raft request' (duration: 280.042513ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:37.467887Z","caller":"traceutil/trace.go:171","msg":"trace[1438496952] transaction","detail":"{read_only:false; response_revision:26048; number_of_response:1; }","duration":"280.396922ms","start":"2026-05-12T17:24:37.187441Z","end":"2026-05-12T17:24:37.467838Z","steps":["trace[1438496952] 'process raft request' (duration: 279.988051ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:37.468061Z","caller":"traceutil/trace.go:171","msg":"trace[905177979] linearizableReadLoop","detail":"{readStateIndex:26908; appliedIndex:26905; }","duration":"151.241897ms","start":"2026-05-12T17:24:37.316578Z","end":"2026-05-12T17:24:37.46782Z","steps":["trace[905177979] 'read index received' (duration: 150.452365ms)","trace[905177979] 'applied index is now lower than readState.Index' (duration: 788.652µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:24:37.468123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.526694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-12T17:24:37.468219Z","caller":"traceutil/trace.go:171","msg":"trace[815113742] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:26048; }","duration":"151.690399ms","start":"2026-05-12T17:24:37.316508Z","end":"2026-05-12T17:24:37.468198Z","steps":["trace[815113742] 'agreement among raft nodes before linearized reading' (duration: 151.513344ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:37.606186Z","caller":"traceutil/trace.go:171","msg":"trace[1604294007] transaction","detail":"{read_only:false; response_revision:26049; number_of_response:1; }","duration":"108.330359ms","start":"2026-05-12T17:24:37.497831Z","end":"2026-05-12T17:24:37.606161Z","steps":["trace[1604294007] 'process raft request' (duration: 107.379304ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:37.692964Z","caller":"traceutil/trace.go:171","msg":"trace[1770109885] linearizableReadLoop","detail":"{readStateIndex:26910; appliedIndex:26908; }","duration":"168.063584ms","start":"2026-05-12T17:24:37.524871Z","end":"2026-05-12T17:24:37.692935Z","steps":["trace[1770109885] 'read index received' (duration: 80.329006ms)","trace[1770109885] 'applied index is now lower than readState.Index' (duration: 87.733218ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:37.693109Z","caller":"traceutil/trace.go:171","msg":"trace[1334083312] transaction","detail":"{read_only:false; response_revision:26050; number_of_response:1; }","duration":"190.494856ms","start":"2026-05-12T17:24:37.502599Z","end":"2026-05-12T17:24:37.693093Z","steps":["trace[1334083312] 'process raft request' (duration: 190.177727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:37.693475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.571419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-12T17:24:37.693609Z","caller":"traceutil/trace.go:171","msg":"trace[1113392184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26050; }","duration":"168.780364ms","start":"2026-05-12T17:24:37.524801Z","end":"2026-05-12T17:24:37.693582Z","steps":["trace[1113392184] 'agreement among raft nodes before linearized reading' (duration: 168.375743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:37.696819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.691515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:24:37.696882Z","caller":"traceutil/trace.go:171","msg":"trace[303558115] 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:26051; }","duration":"127.809008ms","start":"2026-05-12T17:24:37.569055Z","end":"2026-05-12T17:24:37.696864Z","steps":["trace[303558115] 'agreement among raft nodes before linearized reading' (duration: 127.635883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:58.139471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.878405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-05-12T17:24:58.139592Z","caller":"traceutil/trace.go:171","msg":"trace[765296257] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:26217; }","duration":"116.07209ms","start":"2026-05-12T17:24:58.023493Z","end":"2026-05-12T17:24:58.139565Z","steps":["trace[765296257] 'range keys from in-memory index tree' (duration: 115.644878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:58.139583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.479586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:24:58.139707Z","caller":"traceutil/trace.go:171","msg":"trace[2090831102] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:26217; }","duration":"179.692661ms","start":"2026-05-12T17:24:57.959984Z","end":"2026-05-12T17:24:58.139677Z","steps":["trace[2090831102] 'count revisions from in-memory index tree' (duration: 179.326881ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:58.295242Z","caller":"traceutil/trace.go:171","msg":"trace[737829964] linearizableReadLoop","detail":"{readStateIndex:27082; appliedIndex:27081; }","duration":"148.379489ms","start":"2026-05-12T17:24:58.14683Z","end":"2026-05-12T17:24:58.295209Z","steps":["trace[737829964] 'read index received' (duration: 148.317287ms)","trace[737829964] 'applied index is now lower than readState.Index' (duration: 60.532µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:58.29538Z","caller":"traceutil/trace.go:171","msg":"trace[935214004] transaction","detail":"{read_only:false; response_revision:26218; number_of_response:1; }","duration":"150.855387ms","start":"2026-05-12T17:24:58.144497Z","end":"2026-05-12T17:24:58.295352Z","steps":["trace[935214004] 'process raft request' (duration: 150.512968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:58.295458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.603855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:24:58.295504Z","caller":"traceutil/trace.go:171","msg":"trace[1367224817] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26218; }","duration":"148.683207ms","start":"2026-05-12T17:24:58.146803Z","end":"2026-05-12T17:24:58.295486Z","steps":["trace[1367224817] 'agreement among raft nodes before linearized reading' (duration: 148.585175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:58.295676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.350707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-12T17:24:58.295773Z","caller":"traceutil/trace.go:171","msg":"trace[1643686584] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26218; }","duration":"123.504001ms","start":"2026-05-12T17:24:58.172243Z","end":"2026-05-12T17:24:58.295747Z","steps":["trace[1643686584] 'agreement among raft nodes before linearized reading' (duration: 123.242944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:58.295896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.443443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-12T17:24:58.295903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.838354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-12T17:24:58.295936Z","caller":"traceutil/trace.go:171","msg":"trace[1621622040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26218; }","duration":"111.518985ms","start":"2026-05-12T17:24:58.184405Z","end":"2026-05-12T17:24:58.295924Z","steps":["trace[1621622040] 'agreement among raft nodes before linearized reading' (duration: 111.386381ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:58.295945Z","caller":"traceutil/trace.go:171","msg":"trace[2141727570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26218; }","duration":"111.905505ms","start":"2026-05-12T17:24:58.184028Z","end":"2026-05-12T17:24:58.295933Z","steps":["trace[2141727570] 'agreement among raft nodes before linearized reading' (duration: 111.73542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:58.296092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.791599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-12T17:24:58.29614Z","caller":"traceutil/trace.go:171","msg":"trace[485143613] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26218; }","duration":"116.882542ms","start":"2026-05-12T17:24:58.179243Z","end":"2026-05-12T17:24:58.296125Z","steps":["trace[485143613] 'agreement among raft nodes before linearized reading' (duration: 116.770608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:58.583248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.601102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:24:58.583387Z","caller":"traceutil/trace.go:171","msg":"trace[1282105603] transaction","detail":"{read_only:false; response_revision:26219; number_of_response:1; }","duration":"282.026807ms","start":"2026-05-12T17:24:58.301336Z","end":"2026-05-12T17:24:58.583363Z","steps":["trace[1282105603] 'process raft request' (duration: 125.186718ms)","trace[1282105603] 'compare' (duration: 156.256673ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:58.584673Z","caller":"traceutil/trace.go:171","msg":"trace[918623616] transaction","detail":"{read_only:false; response_revision:26220; number_of_response:1; }","duration":"283.245861ms","start":"2026-05-12T17:24:58.301393Z","end":"2026-05-12T17:24:58.584639Z","steps":["trace[918623616] 'process raft request' (duration: 282.748396ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:58.585029Z","caller":"traceutil/trace.go:171","msg":"trace[999097229] transaction","detail":"{read_only:false; response_revision:26221; number_of_response:1; }","duration":"283.488236ms","start":"2026-05-12T17:24:58.301523Z","end":"2026-05-12T17:24:58.585011Z","steps":["trace[999097229] 'process raft request' (duration: 283.028733ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:58.585009Z","caller":"traceutil/trace.go:171","msg":"trace[701568337] linearizableReadLoop","detail":"{readStateIndex:27086; appliedIndex:27082; }","duration":"283.024244ms","start":"2026-05-12T17:24:58.301945Z","end":"2026-05-12T17:24:58.584969Z","steps":["trace[701568337] 'read index received' (duration: 124.707314ms)","trace[701568337] 'applied index is now lower than readState.Index' (duration: 158.31548ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:24:58.585232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.271811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-12T17:24:58.585252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.645023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:24:58.585228Z","caller":"traceutil/trace.go:171","msg":"trace[2078665292] transaction","detail":"{read_only:false; response_revision:26222; number_of_response:1; }","duration":"283.506966ms","start":"2026-05-12T17:24:58.301674Z","end":"2026-05-12T17:24:58.585181Z","steps":["trace[2078665292] 'process raft request' (duration: 283.170967ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:58.585331Z","caller":"traceutil/trace.go:171","msg":"trace[101111630] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26222; }","duration":"282.772817ms","start":"2026-05-12T17:24:58.30254Z","end":"2026-05-12T17:24:58.585313Z","steps":["trace[101111630] 'agreement among raft nodes before linearized reading' (duration: 282.619803ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:58.585343Z","caller":"traceutil/trace.go:171","msg":"trace[10500044] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:26222; }","duration":"283.390834ms","start":"2026-05-12T17:24:58.301878Z","end":"2026-05-12T17:24:58.585268Z","steps":["trace[10500044] 'agreement among raft nodes before linearized reading' (duration: 283.26395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:59.00931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.128776ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-12T17:24:59.009731Z","caller":"traceutil/trace.go:171","msg":"trace[475447530] linearizableReadLoop","detail":"{readStateIndex:27088; appliedIndex:27086; }","duration":"181.881321ms","start":"2026-05-12T17:24:58.827812Z","end":"2026-05-12T17:24:59.009693Z","steps":["trace[475447530] 'read index received' (duration: 29.886604ms)","trace[475447530] 'applied index is now lower than readState.Index' (duration: 151.991327ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:59.009763Z","caller":"traceutil/trace.go:171","msg":"trace[1578225260] transaction","detail":"{read_only:false; response_revision:26223; number_of_response:1; }","duration":"261.385635ms","start":"2026-05-12T17:24:58.748345Z","end":"2026-05-12T17:24:59.009731Z","steps":["trace[1578225260] 'process raft request' (duration: 261.080597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:59.011231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.398342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:24:59.01128Z","caller":"traceutil/trace.go:171","msg":"trace[634325674] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:26223; }","duration":"183.508866ms","start":"2026-05-12T17:24:58.827752Z","end":"2026-05-12T17:24:59.011261Z","steps":["trace[634325674] 'agreement among raft nodes before linearized reading' (duration: 182.040366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:59.666907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.364607ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:24:59.667045Z","caller":"traceutil/trace.go:171","msg":"trace[1568767478] transaction","detail":"{read_only:false; response_revision:26226; number_of_response:1; }","duration":"139.131647ms","start":"2026-05-12T17:24:59.527892Z","end":"2026-05-12T17:24:59.667024Z","steps":["trace[1568767478] 'compare' (duration: 133.685109ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:59.903631Z","caller":"traceutil/trace.go:171","msg":"trace[1109322610] transaction","detail":"{read_only:false; response_revision:26227; number_of_response:1; }","duration":"342.000259ms","start":"2026-05-12T17:24:59.561584Z","end":"2026-05-12T17:24:59.903584Z","steps":["trace[1109322610] 'process raft request' (duration: 247.196919ms)","trace[1109322610] 'compare' (duration: 94.455651ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:24:59.903718Z","caller":"traceutil/trace.go:171","msg":"trace[1284449915] transaction","detail":"{read_only:false; response_revision:26228; number_of_response:1; }","duration":"331.076982ms","start":"2026-05-12T17:24:59.572612Z","end":"2026-05-12T17:24:59.903689Z","steps":["trace[1284449915] 'process raft request' (duration: 330.798514ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:59.903711Z","caller":"traceutil/trace.go:171","msg":"trace[1025114819] transaction","detail":"{read_only:false; response_revision:26231; number_of_response:1; }","duration":"129.979487ms","start":"2026-05-12T17:24:59.773694Z","end":"2026-05-12T17:24:59.903674Z","steps":["trace[1025114819] 'process raft request' (duration: 129.879535ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:24:59.903728Z","caller":"traceutil/trace.go:171","msg":"trace[2135631681] transaction","detail":"{read_only:false; response_revision:26229; number_of_response:1; }","duration":"330.203628ms","start":"2026-05-12T17:24:59.573479Z","end":"2026-05-12T17:24:59.903682Z","steps":["trace[2135631681] 'process raft request' (duration: 329.978822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:59.903838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:59.561557Z","time spent":"342.172844ms","remote":"127.0.0.1:40634","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-05-12T17:24:59.903885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:59.572582Z","time spent":"331.207716ms","remote":"127.0.0.1:40634","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-05-12T17:24:59.903716Z","caller":"traceutil/trace.go:171","msg":"trace[778134897] transaction","detail":"{read_only:false; response_revision:26230; number_of_response:1; }","duration":"195.032308ms","start":"2026-05-12T17:24:59.708639Z","end":"2026-05-12T17:24:59.903671Z","steps":["trace[778134897] 'process raft request' (duration: 194.868844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:24:59.90392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:24:59.573464Z","time spent":"330.361722ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:25:00.097422Z","caller":"traceutil/trace.go:171","msg":"trace[168324378] transaction","detail":"{read_only:false; response_revision:26232; number_of_response:1; }","duration":"188.44655ms","start":"2026-05-12T17:24:59.908911Z","end":"2026-05-12T17:25:00.097358Z","steps":["trace[168324378] 'process raft request' (duration: 149.390847ms)","trace[168324378] 'compare' (duration: 38.768174ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:25:00.379661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.55638ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:25:00.379863Z","caller":"traceutil/trace.go:171","msg":"trace[938517683] transaction","detail":"{read_only:false; response_revision:26233; number_of_response:1; }","duration":"269.602398ms","start":"2026-05-12T17:25:00.110223Z","end":"2026-05-12T17:25:00.379825Z","steps":["trace[938517683] 'process raft request' (duration: 126.800191ms)","trace[938517683] 'compare' (duration: 142.228162ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:25:00.379928Z","caller":"traceutil/trace.go:171","msg":"trace[1566023072] transaction","detail":"{read_only:false; response_revision:26234; number_of_response:1; }","duration":"164.115266ms","start":"2026-05-12T17:25:00.215792Z","end":"2026-05-12T17:25:00.379907Z","steps":["trace[1566023072] 'process raft request' (duration: 163.999113ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:00.380393Z","caller":"traceutil/trace.go:171","msg":"trace[1877772014] linearizableReadLoop","detail":"{readStateIndex:27098; appliedIndex:27097; }","duration":"227.325639ms","start":"2026-05-12T17:25:00.153053Z","end":"2026-05-12T17:25:00.380379Z","steps":["trace[1877772014] 'read index received' (duration: 83.930995ms)","trace[1877772014] 'applied index is now lower than readState.Index' (duration: 143.393254ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:25:00.380575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.516424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-12T17:25:00.380611Z","caller":"traceutil/trace.go:171","msg":"trace[1790204096] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26234; }","duration":"227.564185ms","start":"2026-05-12T17:25:00.153038Z","end":"2026-05-12T17:25:00.380602Z","steps":["trace[1790204096] 'agreement among raft nodes before linearized reading' (duration: 227.39531ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:00.550655Z","caller":"traceutil/trace.go:171","msg":"trace[2043153095] transaction","detail":"{read_only:false; response_revision:26236; number_of_response:1; }","duration":"162.820832ms","start":"2026-05-12T17:25:00.387809Z","end":"2026-05-12T17:25:00.550629Z","steps":["trace[2043153095] 'process raft request' (duration: 162.395831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:00.551144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.947583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:25:00.551187Z","caller":"traceutil/trace.go:171","msg":"trace[2126889760] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:26236; }","duration":"164.035446ms","start":"2026-05-12T17:25:00.387138Z","end":"2026-05-12T17:25:00.551173Z","steps":["trace[2126889760] 'agreement among raft nodes before linearized reading' (duration: 163.949913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:00.551436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.339623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-12T17:25:00.551467Z","caller":"traceutil/trace.go:171","msg":"trace[1584008011] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26236; }","duration":"143.402924ms","start":"2026-05-12T17:25:00.408054Z","end":"2026-05-12T17:25:00.551457Z","steps":["trace[1584008011] 'agreement among raft nodes before linearized reading' (duration: 143.281581ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:00.667868Z","caller":"traceutil/trace.go:171","msg":"trace[2078748139] transaction","detail":"{read_only:false; response_revision:26237; number_of_response:1; }","duration":"113.615012ms","start":"2026-05-12T17:25:00.554208Z","end":"2026-05-12T17:25:00.667823Z","steps":["trace[2078748139] 'process raft request' (duration: 98.570123ms)","trace[2078748139] 'compare' (duration: 14.605427ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:25:00.889841Z","caller":"traceutil/trace.go:171","msg":"trace[1535320348] transaction","detail":"{read_only:false; response_revision:26238; number_of_response:1; }","duration":"217.112819ms","start":"2026-05-12T17:25:00.672651Z","end":"2026-05-12T17:25:00.889764Z","steps":["trace[1535320348] 'process raft request' (duration: 170.256885ms)","trace[1535320348] 'compare' (duration: 46.576207ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:25:01.032725Z","caller":"traceutil/trace.go:171","msg":"trace[2124173820] linearizableReadLoop","detail":"{readStateIndex:27106; appliedIndex:27102; }","duration":"357.973173ms","start":"2026-05-12T17:25:00.674733Z","end":"2026-05-12T17:25:01.032706Z","steps":["trace[2124173820] 'read index received' (duration: 168.25942ms)","trace[2124173820] 'applied index is now lower than readState.Index' (duration: 189.712833ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:25:01.032811Z","caller":"traceutil/trace.go:171","msg":"trace[755189948] transaction","detail":"{read_only:false; response_revision:26242; number_of_response:1; }","duration":"357.596203ms","start":"2026-05-12T17:25:00.675206Z","end":"2026-05-12T17:25:01.032802Z","steps":["trace[755189948] 'process raft request' (duration: 357.417649ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:01.032816Z","caller":"traceutil/trace.go:171","msg":"trace[402815796] transaction","detail":"{read_only:false; response_revision:26241; number_of_response:1; }","duration":"358.549019ms","start":"2026-05-12T17:25:00.674228Z","end":"2026-05-12T17:25:01.032777Z","steps":["trace[402815796] 'process raft request' (duration: 358.369984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:01.032918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:25:00.675195Z","time spent":"357.632135ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:25:01.032908Z","caller":"traceutil/trace.go:171","msg":"trace[1048790309] transaction","detail":"{read_only:false; response_revision:26243; number_of_response:1; }","duration":"357.246114ms","start":"2026-05-12T17:25:00.675626Z","end":"2026-05-12T17:25:01.032872Z","steps":["trace[1048790309] 'process raft request' (duration: 357.021947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:01.033002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:25:00.674215Z","time spent":"358.673674ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:25:01.033087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:25:00.675616Z","time spent":"357.371398ms","remote":"127.0.0.1:40634","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-05-12T17:25:01.033018Z","caller":"traceutil/trace.go:171","msg":"trace[1719632333] transaction","detail":"{read_only:false; response_revision:26244; number_of_response:1; }","duration":"356.470814ms","start":"2026-05-12T17:25:00.676539Z","end":"2026-05-12T17:25:01.03301Z","steps":["trace[1719632333] 'process raft request' (duration: 356.132624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:01.033121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.388355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-12T17:25:01.03315Z","caller":"traceutil/trace.go:171","msg":"trace[1316605842] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26244; }","duration":"358.440627ms","start":"2026-05-12T17:25:00.674702Z","end":"2026-05-12T17:25:01.033142Z","steps":["trace[1316605842] 'agreement among raft nodes before linearized reading' (duration: 358.327214ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:01.033138Z","caller":"traceutil/trace.go:171","msg":"trace[1869096376] transaction","detail":"{read_only:false; response_revision:26240; number_of_response:1; }","duration":"358.981922ms","start":"2026-05-12T17:25:00.674138Z","end":"2026-05-12T17:25:01.03312Z","steps":["trace[1869096376] 'process raft request' (duration: 358.403775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:01.033167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:25:00.674689Z","time spent":"358.473387ms","remote":"127.0.0.1:40560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-12T17:25:01.033116Z","caller":"traceutil/trace.go:171","msg":"trace[1507973967] transaction","detail":"{read_only:false; response_revision:26239; number_of_response:1; }","duration":"359.340332ms","start":"2026-05-12T17:25:00.673726Z","end":"2026-05-12T17:25:01.033066Z","steps":["trace[1507973967] 'process raft request' (duration: 358.670373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:01.033204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:25:00.674123Z","time spent":"359.046674ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:25:01.033334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:25:00.673691Z","time spent":"359.529557ms","remote":"127.0.0.1:40480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":720,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T17:25:01.033177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:25:00.676524Z","time spent":"356.618558ms","remote":"127.0.0.1:40616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5599,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T17:25:01.044781Z","caller":"traceutil/trace.go:171","msg":"trace[770937218] transaction","detail":"{read_only:false; response_revision:26245; number_of_response:1; }","duration":"150.333253ms","start":"2026-05-12T17:25:00.894421Z","end":"2026-05-12T17:25:01.044754Z","steps":["trace[770937218] 'process raft request' (duration: 150.056575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:01.04492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.960496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/heat-engine-cleaner-29643445-7vst2\" ","response":"range_response_count:1 size:5678"} {"level":"info","ts":"2026-05-12T17:25:01.044971Z","caller":"traceutil/trace.go:171","msg":"trace[1296465884] range","detail":"{range_begin:/registry/pods/openstack/heat-engine-cleaner-29643445-7vst2; range_end:; response_count:1; response_revision:26246; }","duration":"134.07141ms","start":"2026-05-12T17:25:00.910884Z","end":"2026-05-12T17:25:01.044955Z","steps":["trace[1296465884] 'agreement among raft nodes before linearized reading' (duration: 133.867964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:01.045163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.146036ms","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":"info","ts":"2026-05-12T17:25:01.045196Z","caller":"traceutil/trace.go:171","msg":"trace[920368785] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:26246; }","duration":"226.201407ms","start":"2026-05-12T17:25:00.818982Z","end":"2026-05-12T17:25:01.045184Z","steps":["trace[920368785] 'agreement among raft nodes before linearized reading' (duration: 226.120215ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:01.313457Z","caller":"traceutil/trace.go:171","msg":"trace[1325067224] linearizableReadLoop","detail":"{readStateIndex:27112; appliedIndex:27111; }","duration":"257.043537ms","start":"2026-05-12T17:25:01.056392Z","end":"2026-05-12T17:25:01.313435Z","steps":["trace[1325067224] 'read index received' (duration: 256.902533ms)","trace[1325067224] 'applied index is now lower than readState.Index' (duration: 139.894µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:25:01.313579Z","caller":"traceutil/trace.go:171","msg":"trace[1313683467] transaction","detail":"{read_only:false; response_revision:26247; number_of_response:1; }","duration":"259.320549ms","start":"2026-05-12T17:25:01.054217Z","end":"2026-05-12T17:25:01.313537Z","steps":["trace[1313683467] 'process raft request' (duration: 259.023651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:01.313712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.303433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-05-12T17:25:01.313743Z","caller":"traceutil/trace.go:171","msg":"trace[1307746243] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26247; }","duration":"257.400537ms","start":"2026-05-12T17:25:01.056328Z","end":"2026-05-12T17:25:01.313729Z","steps":["trace[1307746243] 'agreement among raft nodes before linearized reading' (duration: 257.215622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:01.334689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.901214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/heat-engine-cleaner\" ","response":"range_response_count:1 size:541"} {"level":"info","ts":"2026-05-12T17:25:01.334769Z","caller":"traceutil/trace.go:171","msg":"trace[1214122941] range","detail":"{range_begin:/registry/serviceaccounts/openstack/heat-engine-cleaner; range_end:; response_count:1; response_revision:26248; }","duration":"224.055839ms","start":"2026-05-12T17:25:01.110693Z","end":"2026-05-12T17:25:01.334749Z","steps":["trace[1214122941] 'agreement among raft nodes before linearized reading' (duration: 223.832933ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:02.033357Z","caller":"traceutil/trace.go:171","msg":"trace[2081097456] transaction","detail":"{read_only:false; response_revision:26250; number_of_response:1; }","duration":"122.732281ms","start":"2026-05-12T17:25:01.910579Z","end":"2026-05-12T17:25:02.033311Z","steps":["trace[2081097456] 'process raft request' (duration: 122.458103ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:02.169855Z","caller":"traceutil/trace.go:171","msg":"trace[1566623385] transaction","detail":"{read_only:false; response_revision:26251; number_of_response:1; }","duration":"259.207825ms","start":"2026-05-12T17:25:01.910614Z","end":"2026-05-12T17:25:02.169822Z","steps":["trace[1566623385] 'process raft request' (duration: 228.831458ms)","trace[1566623385] 'compare' (duration: 30.103229ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:25:02.17023Z","caller":"traceutil/trace.go:171","msg":"trace[697449657] transaction","detail":"{read_only:false; response_revision:26252; number_of_response:1; }","duration":"259.535765ms","start":"2026-05-12T17:25:01.910662Z","end":"2026-05-12T17:25:02.170198Z","steps":["trace[697449657] 'process raft request' (duration: 259.105492ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:02.170338Z","caller":"traceutil/trace.go:171","msg":"trace[1500590153] linearizableReadLoop","detail":"{readStateIndex:27120; appliedIndex:27114; }","duration":"192.622223ms","start":"2026-05-12T17:25:01.977697Z","end":"2026-05-12T17:25:02.170319Z","steps":["trace[1500590153] 'read index received' (duration: 55.411638ms)","trace[1500590153] 'applied index is now lower than readState.Index' (duration: 137.209185ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:25:02.170424Z","caller":"traceutil/trace.go:171","msg":"trace[1440019651] transaction","detail":"{read_only:false; response_revision:26254; number_of_response:1; }","duration":"259.557466ms","start":"2026-05-12T17:25:01.910856Z","end":"2026-05-12T17:25:02.170413Z","steps":["trace[1440019651] 'process raft request' (duration: 259.33495ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:02.170556Z","caller":"traceutil/trace.go:171","msg":"trace[1824988798] transaction","detail":"{read_only:false; response_revision:26253; number_of_response:1; }","duration":"259.793342ms","start":"2026-05-12T17:25:01.910752Z","end":"2026-05-12T17:25:02.170545Z","steps":["trace[1824988798] 'process raft request' (duration: 259.322148ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:02.170646Z","caller":"traceutil/trace.go:171","msg":"trace[1061679500] transaction","detail":"{read_only:false; response_revision:26255; number_of_response:1; }","duration":"216.249697ms","start":"2026-05-12T17:25:01.954382Z","end":"2026-05-12T17:25:02.170631Z","steps":["trace[1061679500] 'process raft request' (duration: 215.865267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:02.170988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.251941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7533"} {"level":"info","ts":"2026-05-12T17:25:02.171081Z","caller":"traceutil/trace.go:171","msg":"trace[1758802238] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:26255; }","duration":"193.400274ms","start":"2026-05-12T17:25:01.977659Z","end":"2026-05-12T17:25:02.171059Z","steps":["trace[1758802238] 'agreement among raft nodes before linearized reading' (duration: 193.116806ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:02.366134Z","caller":"traceutil/trace.go:171","msg":"trace[1386625535] transaction","detail":"{read_only:false; response_revision:26257; number_of_response:1; }","duration":"183.70879ms","start":"2026-05-12T17:25:02.182405Z","end":"2026-05-12T17:25:02.366114Z","steps":["trace[1386625535] 'process raft request' (duration: 183.451303ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:03.241932Z","caller":"traceutil/trace.go:171","msg":"trace[368255862] transaction","detail":"{read_only:false; response_revision:26264; number_of_response:1; }","duration":"153.601451ms","start":"2026-05-12T17:25:03.088302Z","end":"2026-05-12T17:25:03.241904Z","steps":["trace[368255862] 'process raft request' (duration: 77.021237ms)","trace[368255862] 'compare' (duration: 76.174503ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:25:03.242378Z","caller":"traceutil/trace.go:171","msg":"trace[2134042187] transaction","detail":"{read_only:false; response_revision:26265; number_of_response:1; }","duration":"150.693452ms","start":"2026-05-12T17:25:03.091463Z","end":"2026-05-12T17:25:03.242157Z","steps":["trace[2134042187] 'process raft request' (duration: 150.335092ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:03.242477Z","caller":"traceutil/trace.go:171","msg":"trace[1544651864] transaction","detail":"{read_only:false; response_revision:26266; number_of_response:1; }","duration":"122.328929ms","start":"2026-05-12T17:25:03.120122Z","end":"2026-05-12T17:25:03.242451Z","steps":["trace[1544651864] 'process raft request' (duration: 121.974229ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:03.242476Z","caller":"traceutil/trace.go:171","msg":"trace[2117284880] transaction","detail":"{read_only:false; response_revision:26267; number_of_response:1; }","duration":"121.093736ms","start":"2026-05-12T17:25:03.121354Z","end":"2026-05-12T17:25:03.242448Z","steps":["trace[2117284880] 'process raft request' (duration: 121.048265ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:03.242502Z","caller":"traceutil/trace.go:171","msg":"trace[2115020412] linearizableReadLoop","detail":"{readStateIndex:27130; appliedIndex:27128; }","duration":"125.676561ms","start":"2026-05-12T17:25:03.116787Z","end":"2026-05-12T17:25:03.242463Z","steps":["trace[2115020412] 'read index received' (duration: 48.553343ms)","trace[2115020412] 'applied index is now lower than readState.Index' (duration: 77.120188ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:25:03.242624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.615175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-endpoints\" ","response":"range_response_count:1 size:21561"} {"level":"info","ts":"2026-05-12T17:25:03.24266Z","caller":"traceutil/trace.go:171","msg":"trace[1793253254] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-endpoints; range_end:; response_count:1; response_revision:26267; }","duration":"148.673897ms","start":"2026-05-12T17:25:03.093976Z","end":"2026-05-12T17:25:03.24265Z","steps":["trace[1793253254] 'agreement among raft nodes before linearized reading' (duration: 148.512303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:03.242671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.700866ms","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-05-12T17:25:03.2427Z","caller":"traceutil/trace.go:171","msg":"trace[1184055896] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26267; }","duration":"156.792537ms","start":"2026-05-12T17:25:03.085897Z","end":"2026-05-12T17:25:03.24269Z","steps":["trace[1184055896] 'agreement among raft nodes before linearized reading' (duration: 156.669764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:03.242711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.18012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-db-sync\" ","response":"range_response_count:1 size:5129"} {"level":"info","ts":"2026-05-12T17:25:03.242764Z","caller":"traceutil/trace.go:171","msg":"trace[1422775749] range","detail":"{range_begin:/registry/jobs/openstack/heat-db-sync; range_end:; response_count:1; response_revision:26267; }","duration":"149.269523ms","start":"2026-05-12T17:25:03.09348Z","end":"2026-05-12T17:25:03.24275Z","steps":["trace[1422775749] 'agreement among raft nodes before linearized reading' (duration: 149.076008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:03.242766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.232055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-ks-user\" ","response":"range_response_count:1 size:11169"} {"level":"warn","ts":"2026-05-12T17:25:03.242769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.403789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/heat-domain-ks-user\" ","response":"range_response_count:1 size:7564"} {"level":"info","ts":"2026-05-12T17:25:03.2428Z","caller":"traceutil/trace.go:171","msg":"trace[1287557451] range","detail":"{range_begin:/registry/jobs/openstack/heat-ks-user; range_end:; response_count:1; response_revision:26267; }","duration":"148.294837ms","start":"2026-05-12T17:25:03.094494Z","end":"2026-05-12T17:25:03.242789Z","steps":["trace[1287557451] 'agreement among raft nodes before linearized reading' (duration: 148.135883ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:03.242808Z","caller":"traceutil/trace.go:171","msg":"trace[1413069077] range","detail":"{range_begin:/registry/jobs/openstack/heat-domain-ks-user; range_end:; response_count:1; response_revision:26267; }","duration":"148.464611ms","start":"2026-05-12T17:25:03.094332Z","end":"2026-05-12T17:25:03.242796Z","steps":["trace[1413069077] 'agreement among raft nodes before linearized reading' (duration: 148.336118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:03.242818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.947784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/percona-xtradb-haproxy\" ","response":"range_response_count:1 size:1149"} {"level":"info","ts":"2026-05-12T17:25:03.242905Z","caller":"traceutil/trace.go:171","msg":"trace[1561724259] range","detail":"{range_begin:/registry/services/endpoints/openstack/percona-xtradb-haproxy; range_end:; response_count:1; response_revision:26267; }","duration":"149.067568ms","start":"2026-05-12T17:25:03.093814Z","end":"2026-05-12T17:25:03.242881Z","steps":["trace[1561724259] 'agreement among raft nodes before linearized reading' (duration: 148.80033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:03.242925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.302379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:676"} {"level":"warn","ts":"2026-05-12T17:25:03.242943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.603749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:895"} {"level":"info","ts":"2026-05-12T17:25:03.242947Z","caller":"traceutil/trace.go:171","msg":"trace[831032204] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:26267; }","duration":"147.34321ms","start":"2026-05-12T17:25:03.095597Z","end":"2026-05-12T17:25:03.242941Z","steps":["trace[831032204] 'agreement among raft nodes before linearized reading' (duration: 147.274548ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:03.242969Z","caller":"traceutil/trace.go:171","msg":"trace[173210139] range","detail":"{range_begin:/registry/services/endpoints/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:26267; }","duration":"150.65016ms","start":"2026-05-12T17:25:03.092311Z","end":"2026-05-12T17:25:03.242961Z","steps":["trace[173210139] 'agreement among raft nodes before linearized reading' (duration: 150.566528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:05.579098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.601554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:25:05.57922Z","caller":"traceutil/trace.go:171","msg":"trace[2025819971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26284; }","duration":"105.7996ms","start":"2026-05-12T17:25:05.473392Z","end":"2026-05-12T17:25:05.579192Z","steps":["trace[2025819971] 'range keys from in-memory index tree' (duration: 105.477031ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:09.871218Z","caller":"traceutil/trace.go:171","msg":"trace[1838415521] transaction","detail":"{read_only:false; response_revision:26320; number_of_response:1; }","duration":"155.330287ms","start":"2026-05-12T17:25:09.715834Z","end":"2026-05-12T17:25:09.871165Z","steps":["trace[1838415521] 'process raft request' (duration: 154.743891ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:10.299218Z","caller":"traceutil/trace.go:171","msg":"trace[749299123] transaction","detail":"{read_only:false; response_revision:26321; number_of_response:1; }","duration":"235.09872ms","start":"2026-05-12T17:25:10.064037Z","end":"2026-05-12T17:25:10.299135Z","steps":["trace[749299123] 'process raft request' (duration: 234.838723ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:10.519466Z","caller":"traceutil/trace.go:171","msg":"trace[176191563] transaction","detail":"{read_only:false; response_revision:26322; number_of_response:1; }","duration":"274.119221ms","start":"2026-05-12T17:25:10.245314Z","end":"2026-05-12T17:25:10.519433Z","steps":["trace[176191563] 'process raft request' (duration: 224.297775ms)","trace[176191563] 'compare' (duration: 49.58101ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:25:10.519864Z","caller":"traceutil/trace.go:171","msg":"trace[388255340] transaction","detail":"{read_only:false; response_revision:26323; number_of_response:1; }","duration":"272.950869ms","start":"2026-05-12T17:25:10.246886Z","end":"2026-05-12T17:25:10.519837Z","steps":["trace[388255340] 'process raft request' (duration: 272.491127ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:10.520115Z","caller":"traceutil/trace.go:171","msg":"trace[1078178352] linearizableReadLoop","detail":"{readStateIndex:27192; appliedIndex:27187; }","duration":"251.072793ms","start":"2026-05-12T17:25:10.269022Z","end":"2026-05-12T17:25:10.520095Z","steps":["trace[1078178352] 'read index received' (duration: 29.918303ms)","trace[1078178352] 'applied index is now lower than readState.Index' (duration: 221.15272ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:25:10.520119Z","caller":"traceutil/trace.go:171","msg":"trace[1971037955] transaction","detail":"{read_only:false; response_revision:26324; number_of_response:1; }","duration":"273.064192ms","start":"2026-05-12T17:25:10.247029Z","end":"2026-05-12T17:25:10.520093Z","steps":["trace[1971037955] 'process raft request' (duration: 272.590369ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:10.52024Z","caller":"traceutil/trace.go:171","msg":"trace[1503975596] transaction","detail":"{read_only:false; response_revision:26325; number_of_response:1; }","duration":"272.056745ms","start":"2026-05-12T17:25:10.248143Z","end":"2026-05-12T17:25:10.5202Z","steps":["trace[1503975596] 'process raft request' (duration: 271.87649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:10.520355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.3158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-12T17:25:10.5204Z","caller":"traceutil/trace.go:171","msg":"trace[811770626] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26325; }","duration":"251.418054ms","start":"2026-05-12T17:25:10.268968Z","end":"2026-05-12T17:25:10.520386Z","steps":["trace[811770626] 'agreement among raft nodes before linearized reading' (duration: 251.25839ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:10.758237Z","caller":"traceutil/trace.go:171","msg":"trace[1707150350] linearizableReadLoop","detail":"{readStateIndex:27193; appliedIndex:27192; }","duration":"100.709692ms","start":"2026-05-12T17:25:10.657473Z","end":"2026-05-12T17:25:10.758182Z","steps":["trace[1707150350] 'read index received' (duration: 3.364892ms)","trace[1707150350] 'applied index is now lower than readState.Index' (duration: 97.34323ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:25:10.758448Z","caller":"traceutil/trace.go:171","msg":"trace[1022176632] transaction","detail":"{read_only:false; response_revision:26326; number_of_response:1; }","duration":"232.391115ms","start":"2026-05-12T17:25:10.526007Z","end":"2026-05-12T17:25:10.758398Z","steps":["trace[1022176632] 'process raft request' (duration: 134.905192ms)","trace[1022176632] 'compare' (duration: 97.075092ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:25:10.758676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.173464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-12T17:25:10.758726Z","caller":"traceutil/trace.go:171","msg":"trace[707136904] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:26326; }","duration":"101.318668ms","start":"2026-05-12T17:25:10.657394Z","end":"2026-05-12T17:25:10.758713Z","steps":["trace[707136904] 'agreement among raft nodes before linearized reading' (duration: 100.957278ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:11.280185Z","caller":"traceutil/trace.go:171","msg":"trace[1047512490] linearizableReadLoop","detail":"{readStateIndex:27194; appliedIndex:27193; }","duration":"114.378632ms","start":"2026-05-12T17:25:11.165781Z","end":"2026-05-12T17:25:11.28016Z","steps":["trace[1047512490] 'read index received' (duration: 114.136926ms)","trace[1047512490] 'applied index is now lower than readState.Index' (duration: 240.766µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:25:11.280286Z","caller":"traceutil/trace.go:171","msg":"trace[1816621514] transaction","detail":"{read_only:false; response_revision:26327; number_of_response:1; }","duration":"137.114932ms","start":"2026-05-12T17:25:11.143139Z","end":"2026-05-12T17:25:11.280254Z","steps":["trace[1816621514] 'process raft request' (duration: 136.840794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:11.280547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.740423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-12T17:25:11.280634Z","caller":"traceutil/trace.go:171","msg":"trace[2108875039] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26327; }","duration":"114.891227ms","start":"2026-05-12T17:25:11.165715Z","end":"2026-05-12T17:25:11.280606Z","steps":["trace[2108875039] 'agreement among raft nodes before linearized reading' (duration: 114.61063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:11.46227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.513877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:25:11.462425Z","caller":"traceutil/trace.go:171","msg":"trace[1723037742] transaction","detail":"{read_only:false; response_revision:26328; number_of_response:1; }","duration":"177.651467ms","start":"2026-05-12T17:25:11.284744Z","end":"2026-05-12T17:25:11.462395Z","steps":["trace[1723037742] 'process raft request' (duration: 48.856271ms)","trace[1723037742] 'compare' (duration: 128.27115ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:25:11.484849Z","caller":"traceutil/trace.go:171","msg":"trace[1895785432] transaction","detail":"{read_only:false; response_revision:26329; number_of_response:1; }","duration":"199.8469ms","start":"2026-05-12T17:25:11.284971Z","end":"2026-05-12T17:25:11.484818Z","steps":["trace[1895785432] 'process raft request' (duration: 199.575452ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:11.484965Z","caller":"traceutil/trace.go:171","msg":"trace[15784448] transaction","detail":"{read_only:false; response_revision:26331; number_of_response:1; }","duration":"198.301798ms","start":"2026-05-12T17:25:11.286605Z","end":"2026-05-12T17:25:11.484907Z","steps":["trace[15784448] 'process raft request' (duration: 198.145853ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:11.484977Z","caller":"traceutil/trace.go:171","msg":"trace[1475920196] transaction","detail":"{read_only:false; response_revision:26330; number_of_response:1; }","duration":"198.465312ms","start":"2026-05-12T17:25:11.286466Z","end":"2026-05-12T17:25:11.484931Z","steps":["trace[1475920196] 'process raft request' (duration: 198.236615ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:11.485051Z","caller":"traceutil/trace.go:171","msg":"trace[1991452257] transaction","detail":"{read_only:false; response_revision:26332; number_of_response:1; }","duration":"197.364413ms","start":"2026-05-12T17:25:11.287633Z","end":"2026-05-12T17:25:11.484997Z","steps":["trace[1991452257] 'process raft request' (duration: 197.155507ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:11.554963Z","caller":"traceutil/trace.go:171","msg":"trace[1377213188] transaction","detail":"{read_only:false; response_revision:26333; number_of_response:1; }","duration":"125.505887ms","start":"2026-05-12T17:25:11.429399Z","end":"2026-05-12T17:25:11.554905Z","steps":["trace[1377213188] 'process raft request' (duration: 125.333141ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:13.456045Z","caller":"traceutil/trace.go:171","msg":"trace[1754498236] linearizableReadLoop","detail":"{readStateIndex:27216; appliedIndex:27215; }","duration":"108.227095ms","start":"2026-05-12T17:25:13.347754Z","end":"2026-05-12T17:25:13.455981Z","steps":["trace[1754498236] 'read index received' (duration: 70.18728ms)","trace[1754498236] 'applied index is now lower than readState.Index' (duration: 38.037865ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:25:13.45606Z","caller":"traceutil/trace.go:171","msg":"trace[886732638] transaction","detail":"{read_only:false; response_revision:26347; number_of_response:1; }","duration":"118.65836ms","start":"2026-05-12T17:25:13.337355Z","end":"2026-05-12T17:25:13.456014Z","steps":["trace[886732638] 'process raft request' (duration: 80.667526ms)","trace[886732638] 'compare' (duration: 37.776728ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:25:13.456273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.503033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-05-12T17:25:13.456359Z","caller":"traceutil/trace.go:171","msg":"trace[25498817] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:26347; }","duration":"108.620206ms","start":"2026-05-12T17:25:13.347719Z","end":"2026-05-12T17:25:13.456339Z","steps":["trace[25498817] 'agreement among raft nodes before linearized reading' (duration: 108.367739ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:13.602822Z","caller":"traceutil/trace.go:171","msg":"trace[317171325] linearizableReadLoop","detail":"{readStateIndex:27217; appliedIndex:27216; }","duration":"114.272191ms","start":"2026-05-12T17:25:13.488523Z","end":"2026-05-12T17:25:13.602795Z","steps":["trace[317171325] 'read index received' (duration: 97.934726ms)","trace[317171325] 'applied index is now lower than readState.Index' (duration: 16.336115ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:25:13.602931Z","caller":"traceutil/trace.go:171","msg":"trace[450928671] transaction","detail":"{read_only:false; response_revision:26348; number_of_response:1; }","duration":"129.817244ms","start":"2026-05-12T17:25:13.473072Z","end":"2026-05-12T17:25:13.602889Z","steps":["trace[450928671] 'process raft request' (duration: 113.326635ms)","trace[450928671] 'compare' (duration: 16.235712ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:25:13.603051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.505837ms","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-05-12T17:25:13.603101Z","caller":"traceutil/trace.go:171","msg":"trace[2128458469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26348; }","duration":"114.592899ms","start":"2026-05-12T17:25:13.488493Z","end":"2026-05-12T17:25:13.603086Z","steps":["trace[2128458469] 'agreement among raft nodes before linearized reading' (duration: 114.421615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:13.608332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.580065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-12T17:25:13.608291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.547715ms","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-05-12T17:25:13.608381Z","caller":"traceutil/trace.go:171","msg":"trace[50075740] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26349; }","duration":"119.639267ms","start":"2026-05-12T17:25:13.488731Z","end":"2026-05-12T17:25:13.60837Z","steps":["trace[50075740] 'agreement among raft nodes before linearized reading' (duration: 119.524003ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:13.608405Z","caller":"traceutil/trace.go:171","msg":"trace[771063892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26349; }","duration":"119.724179ms","start":"2026-05-12T17:25:13.488659Z","end":"2026-05-12T17:25:13.608383Z","steps":["trace[771063892] 'agreement among raft nodes before linearized reading' (duration: 119.471961ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:34.552004Z","caller":"traceutil/trace.go:171","msg":"trace[865147411] transaction","detail":"{read_only:false; response_revision:26524; number_of_response:1; }","duration":"151.02129ms","start":"2026-05-12T17:25:34.400936Z","end":"2026-05-12T17:25:34.551957Z","steps":["trace[865147411] 'process raft request' (duration: 150.781414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:34.834969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.011484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-12T17:25:34.83511Z","caller":"traceutil/trace.go:171","msg":"trace[1283367002] linearizableReadLoop","detail":"{readStateIndex:27398; appliedIndex:27397; }","duration":"283.179368ms","start":"2026-05-12T17:25:34.551907Z","end":"2026-05-12T17:25:34.835086Z","steps":["trace[1283367002] 'read index received' (duration: 136.957188ms)","trace[1283367002] 'applied index is now lower than readState.Index' (duration: 146.22001ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:25:34.835214Z","caller":"traceutil/trace.go:171","msg":"trace[889595545] transaction","detail":"{read_only:false; response_revision:26525; number_of_response:1; }","duration":"358.583971ms","start":"2026-05-12T17:25:34.476614Z","end":"2026-05-12T17:25:34.835198Z","steps":["trace[889595545] 'process raft request' (duration: 212.195846ms)","trace[889595545] 'compare' (duration: 145.683086ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:25:34.83529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:25:34.476585Z","time spent":"358.665853ms","remote":"127.0.0.1:40634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T17:25:34.835539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.568019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:25:34.835928Z","caller":"traceutil/trace.go:171","msg":"trace[206781497] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:26525; }","duration":"319.744893ms","start":"2026-05-12T17:25:34.515885Z","end":"2026-05-12T17:25:34.83563Z","steps":["trace[206781497] 'agreement among raft nodes before linearized reading' (duration: 319.453756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:34.835998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:25:34.515869Z","time spent":"320.111893ms","remote":"127.0.0.1:39172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true "} {"level":"info","ts":"2026-05-12T17:25:34.868038Z","caller":"traceutil/trace.go:171","msg":"trace[1280783961] transaction","detail":"{read_only:false; response_revision:26526; number_of_response:1; }","duration":"248.033442ms","start":"2026-05-12T17:25:34.61997Z","end":"2026-05-12T17:25:34.868004Z","steps":["trace[1280783961] 'process raft request' (duration: 247.833076ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:34.868337Z","caller":"traceutil/trace.go:171","msg":"trace[1094600747] transaction","detail":"{read_only:false; response_revision:26527; number_of_response:1; }","duration":"245.523153ms","start":"2026-05-12T17:25:34.622773Z","end":"2026-05-12T17:25:34.868296Z","steps":["trace[1094600747] 'process raft request' (duration: 245.177824ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:34.868707Z","caller":"traceutil/trace.go:171","msg":"trace[1036343852] transaction","detail":"{read_only:false; response_revision:26528; number_of_response:1; }","duration":"242.08965ms","start":"2026-05-12T17:25:34.626599Z","end":"2026-05-12T17:25:34.868689Z","steps":["trace[1036343852] 'process raft request' (duration: 241.69424ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:34.868953Z","caller":"traceutil/trace.go:171","msg":"trace[1683638514] transaction","detail":"{read_only:false; response_revision:26529; number_of_response:1; }","duration":"242.183552ms","start":"2026-05-12T17:25:34.626722Z","end":"2026-05-12T17:25:34.868906Z","steps":["trace[1683638514] 'process raft request' (duration: 241.925115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:34.869016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.081346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-12T17:25:34.868936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.825456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:25:34.869182Z","caller":"traceutil/trace.go:171","msg":"trace[992606408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26529; }","duration":"316.062323ms","start":"2026-05-12T17:25:34.553065Z","end":"2026-05-12T17:25:34.869127Z","steps":["trace[992606408] 'agreement among raft nodes before linearized reading' (duration: 315.747825ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:34.869184Z","caller":"traceutil/trace.go:171","msg":"trace[233925185] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:26529; }","duration":"110.329152ms","start":"2026-05-12T17:25:34.758838Z","end":"2026-05-12T17:25:34.869167Z","steps":["trace[233925185] 'agreement among raft nodes before linearized reading' (duration: 110.088906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T17:25:34.869229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T17:25:34.553054Z","time spent":"316.165346ms","remote":"127.0.0.1:40424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-12T17:25:35.121225Z","caller":"traceutil/trace.go:171","msg":"trace[320388795] transaction","detail":"{read_only:false; response_revision:26530; number_of_response:1; }","duration":"233.249858ms","start":"2026-05-12T17:25:34.887941Z","end":"2026-05-12T17:25:35.121191Z","steps":["trace[320388795] 'process raft request' (duration: 198.541804ms)","trace[320388795] 'compare' (duration: 34.570631ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T17:25:35.568389Z","caller":"traceutil/trace.go:171","msg":"trace[1016629965] linearizableReadLoop","detail":"{readStateIndex:27404; appliedIndex:27403; }","duration":"106.014926ms","start":"2026-05-12T17:25:35.462334Z","end":"2026-05-12T17:25:35.568349Z","steps":["trace[1016629965] 'read index received' (duration: 105.719997ms)","trace[1016629965] 'applied index is now lower than readState.Index' (duration: 293.629µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:25:35.568558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.208741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-12T17:25:35.568544Z","caller":"traceutil/trace.go:171","msg":"trace[1849091702] transaction","detail":"{read_only:false; response_revision:26531; number_of_response:1; }","duration":"121.506748ms","start":"2026-05-12T17:25:35.446977Z","end":"2026-05-12T17:25:35.568484Z","steps":["trace[1849091702] 'process raft request' (duration: 121.158657ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:25:35.568615Z","caller":"traceutil/trace.go:171","msg":"trace[39803139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26531; }","duration":"106.308593ms","start":"2026-05-12T17:25:35.462274Z","end":"2026-05-12T17:25:35.568582Z","steps":["trace[39803139] 'agreement among raft nodes before linearized reading' (duration: 106.223091ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:26:15.851287Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006cef.wal"} {"level":"info","ts":"2026-05-12T17:28:30.279121Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25350} {"level":"info","ts":"2026-05-12T17:28:30.433944Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25350,"took":"149.13491ms","hash":2686958777,"current-db-size-bytes":58130432,"current-db-size":"58 MB","current-db-size-in-use-bytes":37482496,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-05-12T17:28:30.434025Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2686958777,"revision":25350,"compact-revision":22126} {"level":"info","ts":"2026-05-12T17:28:42.844782Z","caller":"traceutil/trace.go:171","msg":"trace[2112874204] transaction","detail":"{read_only:false; response_revision:28409; number_of_response:1; }","duration":"100.232588ms","start":"2026-05-12T17:28:42.744511Z","end":"2026-05-12T17:28:42.844744Z","steps":["trace[2112874204] 'process raft request' (duration: 100.014403ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:28:42.845345Z","caller":"traceutil/trace.go:171","msg":"trace[1156108455] transaction","detail":"{read_only:false; response_revision:28410; number_of_response:1; }","duration":"100.228608ms","start":"2026-05-12T17:28:42.745085Z","end":"2026-05-12T17:28:42.845314Z","steps":["trace[1156108455] 'process raft request' (duration: 100.022652ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:28:42.845572Z","caller":"traceutil/trace.go:171","msg":"trace[1737607824] transaction","detail":"{read_only:false; response_revision:28411; number_of_response:1; }","duration":"100.28197ms","start":"2026-05-12T17:28:42.745261Z","end":"2026-05-12T17:28:42.845543Z","steps":["trace[1737607824] 'process raft request' (duration: 99.971442ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:28:42.845597Z","caller":"traceutil/trace.go:171","msg":"trace[1970060372] linearizableReadLoop","detail":"{readStateIndex:29329; appliedIndex:29327; }","duration":"100.323311ms","start":"2026-05-12T17:28:42.745217Z","end":"2026-05-12T17:28:42.845541Z","steps":["trace[1970060372] 'read index received' (duration: 99.357915ms)","trace[1970060372] 'applied index is now lower than readState.Index' (duration: 962.806µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T17:28:42.84582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.572407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-12T17:28:42.845882Z","caller":"traceutil/trace.go:171","msg":"trace[249911574] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:28411; }","duration":"100.672301ms","start":"2026-05-12T17:28:42.745192Z","end":"2026-05-12T17:28:42.845865Z","steps":["trace[249911574] 'agreement among raft nodes before linearized reading' (duration: 100.506666ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T17:29:54.587262Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"355195d5aae85944","local-member-applied-index":30004,"local-member-snapshot-index":20003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-12T17:29:54.594048Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-05-12T17:29:54.594202Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-05-12T17:33:30.286816Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28294} {"level":"info","ts":"2026-05-12T17:33:30.438455Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28294,"took":"145.894304ms","hash":1776683188,"current-db-size-bytes":58130432,"current-db-size":"58 MB","current-db-size-in-use-bytes":32178176,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-12T17:33:30.438519Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1776683188,"revision":28294,"compact-revision":25350} {"level":"info","ts":"2026-05-12T17:38:30.29382Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30672} {"level":"info","ts":"2026-05-12T17:38:30.440562Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30672,"took":"140.641821ms","hash":3372783891,"current-db-size-bytes":58130432,"current-db-size":"58 MB","current-db-size-in-use-bytes":30134272,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-12T17:38:30.440651Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3372783891,"revision":30672,"compact-revision":28294} {"level":"info","ts":"2026-05-12T17:43:30.30003Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32932} {"level":"info","ts":"2026-05-12T17:43:30.443177Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32932,"took":"136.349613ms","hash":595674952,"current-db-size-bytes":58130432,"current-db-size":"58 MB","current-db-size-in-use-bytes":30248960,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-12T17:43:30.443264Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":595674952,"revision":32932,"compact-revision":30672} {"level":"info","ts":"2026-05-12T17:48:30.307887Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35219} {"level":"info","ts":"2026-05-12T17:48:30.450544Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35219,"took":"137.364939ms","hash":762097879,"current-db-size-bytes":58130432,"current-db-size":"58 MB","current-db-size-in-use-bytes":31092736,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-12T17:48:30.450625Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":762097879,"revision":35219,"compact-revision":32932} {"level":"info","ts":"2026-05-12T17:51:21.659998Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"355195d5aae85944","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-12T17:51:21.665436Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-05-12T17:51:21.665596Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-05-12T17:53:30.314173Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37452} {"level":"info","ts":"2026-05-12T17:53:30.432858Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37452,"took":"114.145647ms","hash":1854364911,"current-db-size-bytes":58974208,"current-db-size":"59 MB","current-db-size-in-use-bytes":31608832,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-12T17:53:30.43293Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1854364911,"revision":37452,"compact-revision":35219}