{"level":"warn","ts":"2026-09-08T02:07:02.146902Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-08T02:07:02.147085Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.191: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://162.253.55.191:2380","--initial-cluster=instance=https://162.253.55.191:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.191:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.191: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-09-08T02:07:02.147186Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-08T02:07:02.147205Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.191:2380"]} {"level":"info","ts":"2026-09-08T02:07:02.147232Z","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-09-08T02:07:02.148038Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.191:2379"]} {"level":"info","ts":"2026-09-08T02:07:02.148132Z","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://162.253.55.191:2380"],"listen-peer-urls":["https://162.253.55.191:2380"],"advertise-client-urls":["https://162.253.55.191:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.191:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.191: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-09-08T02:07:02.152019Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.612649ms"} {"level":"info","ts":"2026-09-08T02:07:02.160461Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"adb6690514c92321","cluster-id":"ee04900e3a861beb"} {"level":"info","ts":"2026-09-08T02:07:02.160554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 switched to configuration voters=()"} {"level":"info","ts":"2026-09-08T02:07:02.160607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 became follower at term 0"} {"level":"info","ts":"2026-09-08T02:07:02.160633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft adb6690514c92321 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-08T02:07:02.160648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 became follower at term 1"} {"level":"info","ts":"2026-09-08T02:07:02.160704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 switched to configuration voters=(12517307684867351329)"} {"level":"warn","ts":"2026-09-08T02:07:02.166Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-08T02:07:02.16788Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-08T02:07:02.171228Z","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-09-08T02:07:02.174889Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"adb6690514c92321","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-08T02:07:02.175183Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"adb6690514c92321","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-08T02:07:02.175217Z","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-09-08T02:07:02.175322Z","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-09-08T02:07:02.175334Z","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-09-08T02:07:02.176803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 switched to configuration voters=(12517307684867351329)"} {"level":"info","ts":"2026-09-08T02:07:02.177091Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ee04900e3a861beb","local-member-id":"adb6690514c92321","added-peer-id":"adb6690514c92321","added-peer-peer-urls":["https://162.253.55.191:2380"]} {"level":"info","ts":"2026-09-08T02:07:02.179743Z","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-09-08T02:07:02.179846Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.191:2380"} {"level":"info","ts":"2026-09-08T02:07:02.179913Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.191:2380"} {"level":"info","ts":"2026-09-08T02:07:02.180145Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"adb6690514c92321","initial-advertise-peer-urls":["https://162.253.55.191:2380"],"listen-peer-urls":["https://162.253.55.191:2380"],"advertise-client-urls":["https://162.253.55.191:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.191:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-08T02:07:02.180225Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-08T02:07:02.560929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 is starting a new election at term 1"} {"level":"info","ts":"2026-09-08T02:07:02.561044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-08T02:07:02.561069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 received MsgPreVoteResp from adb6690514c92321 at term 1"} {"level":"info","ts":"2026-09-08T02:07:02.561085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 became candidate at term 2"} {"level":"info","ts":"2026-09-08T02:07:02.561095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 received MsgVoteResp from adb6690514c92321 at term 2"} {"level":"info","ts":"2026-09-08T02:07:02.56111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 became leader at term 2"} {"level":"info","ts":"2026-09-08T02:07:02.561122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: adb6690514c92321 elected leader adb6690514c92321 at term 2"} {"level":"info","ts":"2026-09-08T02:07:02.563256Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T02:07:02.565403Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T02:07:02.565402Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"adb6690514c92321","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.191:2379]}","request-path":"/0/members/adb6690514c92321/attributes","cluster-id":"ee04900e3a861beb","publish-timeout":"7s"} {"level":"info","ts":"2026-09-08T02:07:02.565551Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T02:07:02.565714Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-08T02:07:02.565803Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-08T02:07:02.567054Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ee04900e3a861beb","local-member-id":"adb6690514c92321","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T02:07:02.567189Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T02:07:02.567244Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T02:07:02.568646Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.191:2379"} {"level":"info","ts":"2026-09-08T02:07:02.569837Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-08T02:07:47.787791Z","caller":"traceutil/trace.go:171","msg":"trace[1923743726] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"102.750424ms","start":"2026-09-08T02:07:47.684961Z","end":"2026-09-08T02:07:47.787711Z","steps":["trace[1923743726] 'process raft request' (duration: 98.890577ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:07:48.080466Z","caller":"traceutil/trace.go:171","msg":"trace[542683943] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"192.485353ms","start":"2026-09-08T02:07:47.887945Z","end":"2026-09-08T02:07:48.08043Z","steps":["trace[542683943] 'read index received' (duration: 170.225788ms)","trace[542683943] 'applied index is now lower than readState.Index' (duration: 22.258375ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:07:48.080589Z","caller":"traceutil/trace.go:171","msg":"trace[373160691] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"272.768136ms","start":"2026-09-08T02:07:47.807788Z","end":"2026-09-08T02:07:48.080557Z","steps":["trace[373160691] 'process raft request' (duration: 250.4883ms)","trace[373160691] 'compare' (duration: 22.019818ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:07:48.080673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.70908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T02:07:48.08113Z","caller":"traceutil/trace.go:171","msg":"trace[917320849] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:584; }","duration":"192.924907ms","start":"2026-09-08T02:07:47.887867Z","end":"2026-09-08T02:07:48.080792Z","steps":["trace[917320849] 'agreement among raft nodes before linearized reading' (duration: 192.70539ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:07:48.751702Z","caller":"traceutil/trace.go:171","msg":"trace[925275094] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"220.857342ms","start":"2026-09-08T02:07:48.530814Z","end":"2026-09-08T02:07:48.751671Z","steps":["trace[925275094] 'read index received' (duration: 219.076249ms)","trace[925275094] 'applied index is now lower than readState.Index' (duration: 1.780173ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:07:48.751821Z","caller":"traceutil/trace.go:171","msg":"trace[1536064031] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"225.882415ms","start":"2026-09-08T02:07:48.525898Z","end":"2026-09-08T02:07:48.751781Z","steps":["trace[1536064031] 'process raft request' (duration: 224.691489ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:07:48.751942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.086889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T02:07:48.751992Z","caller":"traceutil/trace.go:171","msg":"trace[1080336254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"221.193222ms","start":"2026-09-08T02:07:48.530782Z","end":"2026-09-08T02:07:48.751976Z","steps":["trace[1080336254] 'agreement among raft nodes before linearized reading' (duration: 221.065609ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:07:48.752268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.551157ms","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-09-08T02:07:48.752389Z","caller":"traceutil/trace.go:171","msg":"trace[1156385074] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"131.709082ms","start":"2026-09-08T02:07:48.620647Z","end":"2026-09-08T02:07:48.752356Z","steps":["trace[1156385074] 'agreement among raft nodes before linearized reading' (duration: 131.35582ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:07:48.752267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.854516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-08T02:07:48.752595Z","caller":"traceutil/trace.go:171","msg":"trace[1110990472] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"168.240728ms","start":"2026-09-08T02:07:48.584335Z","end":"2026-09-08T02:07:48.752576Z","steps":["trace[1110990472] 'agreement among raft nodes before linearized reading' (duration: 167.66627ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:08:34.676028Z","caller":"traceutil/trace.go:171","msg":"trace[2104299214] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"154.077542ms","start":"2026-09-08T02:08:34.521924Z","end":"2026-09-08T02:08:34.676001Z","steps":["trace[2104299214] 'process raft request' (duration: 153.891766ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:09:24.297595Z","caller":"traceutil/trace.go:171","msg":"trace[866452187] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"251.216353ms","start":"2026-09-08T02:09:24.046332Z","end":"2026-09-08T02:09:24.297549Z","steps":["trace[866452187] 'process raft request' (duration: 170.732147ms)","trace[866452187] 'compare' (duration: 80.019271ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:09:24.520408Z","caller":"traceutil/trace.go:171","msg":"trace[791746283] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1921; }","duration":"166.071115ms","start":"2026-09-08T02:09:24.354268Z","end":"2026-09-08T02:09:24.520339Z","steps":["trace[791746283] 'read index received' (duration: 34.567806ms)","trace[791746283] 'applied index is now lower than readState.Index' (duration: 131.501259ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:09:24.520591Z","caller":"traceutil/trace.go:171","msg":"trace[1196495428] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"212.750558ms","start":"2026-09-08T02:09:24.30781Z","end":"2026-09-08T02:09:24.520561Z","steps":["trace[1196495428] 'process raft request' (duration: 169.094367ms)","trace[1196495428] 'compare' (duration: 43.110704ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:09:24.521159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.86978ms","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-09-08T02:09:24.521228Z","caller":"traceutil/trace.go:171","msg":"trace[86818852] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1848; }","duration":"166.973613ms","start":"2026-09-08T02:09:24.354237Z","end":"2026-09-08T02:09:24.52121Z","steps":["trace[86818852] 'agreement among raft nodes before linearized reading' (duration: 166.361154ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:09:24.856503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.24036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aad4e6e7-b3de-45ac-8f8a-cd5dd2665794\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-09-08T02:09:24.856615Z","caller":"traceutil/trace.go:171","msg":"trace[1905461282] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aad4e6e7-b3de-45ac-8f8a-cd5dd2665794; range_end:; response_count:1; response_revision:1848; }","duration":"130.440797ms","start":"2026-09-08T02:09:24.72615Z","end":"2026-09-08T02:09:24.856591Z","steps":["trace[1905461282] 'range keys from in-memory index tree' (duration: 130.074256ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:09:38.071147Z","caller":"traceutil/trace.go:171","msg":"trace[1126565316] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"344.449922ms","start":"2026-09-08T02:09:37.726665Z","end":"2026-09-08T02:09:38.071115Z","steps":["trace[1126565316] 'process raft request' (duration: 344.240786ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:09:38.071727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:09:37.726628Z","time spent":"344.599467ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T02:09:38.181695Z","caller":"traceutil/trace.go:171","msg":"trace[320957843] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"207.801727ms","start":"2026-09-08T02:09:37.973852Z","end":"2026-09-08T02:09:38.181653Z","steps":["trace[320957843] 'process raft request' (duration: 207.611382ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:10:14.445371Z","caller":"traceutil/trace.go:171","msg":"trace[612450440] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"136.828818ms","start":"2026-09-08T02:10:14.308509Z","end":"2026-09-08T02:10:14.445337Z","steps":["trace[612450440] 'process raft request' (duration: 136.652643ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:10:14.85773Z","caller":"traceutil/trace.go:171","msg":"trace[448635446] transaction","detail":"{read_only:false; response_revision:2173; number_of_response:1; }","duration":"116.399303ms","start":"2026-09-08T02:10:14.741313Z","end":"2026-09-08T02:10:14.857713Z","steps":["trace[448635446] 'process raft request' (duration: 116.2936ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:10:20.784288Z","caller":"traceutil/trace.go:171","msg":"trace[857602476] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"316.872499ms","start":"2026-09-08T02:10:20.467381Z","end":"2026-09-08T02:10:20.784253Z","steps":["trace[857602476] 'process raft request' (duration: 316.679404ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:10:20.784486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:10:20.467345Z","time spent":"317.037024ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T02:10:20.785556Z","caller":"traceutil/trace.go:171","msg":"trace[1694704122] transaction","detail":"{read_only:false; response_revision:2203; number_of_response:1; }","duration":"253.356465ms","start":"2026-09-08T02:10:20.532165Z","end":"2026-09-08T02:10:20.785522Z","steps":["trace[1694704122] 'process raft request' (duration: 253.18186ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:10:21.032908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.395233ms","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-09-08T02:10:21.033009Z","caller":"traceutil/trace.go:171","msg":"trace[1813289610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2203; }","duration":"147.575178ms","start":"2026-09-08T02:10:20.885411Z","end":"2026-09-08T02:10:21.032986Z","steps":["trace[1813289610] 'range keys from in-memory index tree' (duration: 147.01104ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:10:21.033174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.357617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T02:10:21.033305Z","caller":"traceutil/trace.go:171","msg":"trace[1024214888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2203; }","duration":"124.585534ms","start":"2026-09-08T02:10:20.908695Z","end":"2026-09-08T02:10:21.03328Z","steps":["trace[1024214888] 'range keys from in-memory index tree' (duration: 124.256254ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:10:21.195713Z","caller":"traceutil/trace.go:171","msg":"trace[2136866692] linearizableReadLoop","detail":"{readStateIndex:2291; appliedIndex:2290; }","duration":"138.634174ms","start":"2026-09-08T02:10:21.057048Z","end":"2026-09-08T02:10:21.195683Z","steps":["trace[2136866692] 'read index received' (duration: 138.372006ms)","trace[2136866692] 'applied index is now lower than readState.Index' (duration: 260.918µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:10:21.195792Z","caller":"traceutil/trace.go:171","msg":"trace[1446402343] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"156.267124ms","start":"2026-09-08T02:10:21.039484Z","end":"2026-09-08T02:10:21.195751Z","steps":["trace[1446402343] 'process raft request' (duration: 156.032387ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:10:21.196012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.899622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T02:10:21.196324Z","caller":"traceutil/trace.go:171","msg":"trace[1938632655] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2204; }","duration":"139.277953ms","start":"2026-09-08T02:10:21.057015Z","end":"2026-09-08T02:10:21.196293Z","steps":["trace[1938632655] 'agreement among raft nodes before linearized reading' (duration: 138.785939ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:11:41.366322Z","caller":"traceutil/trace.go:171","msg":"trace[1254170671] transaction","detail":"{read_only:false; response_revision:2832; number_of_response:1; }","duration":"176.119312ms","start":"2026-09-08T02:11:41.190176Z","end":"2026-09-08T02:11:41.366296Z","steps":["trace[1254170671] 'process raft request' (duration: 175.923415ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:11:41.398084Z","caller":"traceutil/trace.go:171","msg":"trace[970416364] transaction","detail":"{read_only:false; response_revision:2833; number_of_response:1; }","duration":"123.128435ms","start":"2026-09-08T02:11:41.274925Z","end":"2026-09-08T02:11:41.398054Z","steps":["trace[970416364] 'process raft request' (duration: 122.904118ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:11:41.747054Z","caller":"traceutil/trace.go:171","msg":"trace[1223233304] transaction","detail":"{read_only:false; response_revision:2834; number_of_response:1; }","duration":"112.234755ms","start":"2026-09-08T02:11:41.634776Z","end":"2026-09-08T02:11:41.747011Z","steps":["trace[1223233304] 'process raft request' (duration: 111.909015ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:13:34.043217Z","caller":"traceutil/trace.go:171","msg":"trace[728257056] transaction","detail":"{read_only:false; response_revision:3428; number_of_response:1; }","duration":"125.093017ms","start":"2026-09-08T02:13:33.918089Z","end":"2026-09-08T02:13:34.043183Z","steps":["trace[728257056] 'process raft request' (duration: 123.948912ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:14:47.815908Z","caller":"traceutil/trace.go:171","msg":"trace[1201009960] transaction","detail":"{read_only:false; response_revision:3950; number_of_response:1; }","duration":"129.774473ms","start":"2026-09-08T02:14:47.686105Z","end":"2026-09-08T02:14:47.81588Z","steps":["trace[1201009960] 'process raft request' (duration: 127.101991ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:14:47.816033Z","caller":"traceutil/trace.go:171","msg":"trace[1954395064] linearizableReadLoop","detail":"{readStateIndex:4107; appliedIndex:4106; }","duration":"128.586427ms","start":"2026-09-08T02:14:47.687429Z","end":"2026-09-08T02:14:47.816015Z","steps":["trace[1954395064] 'read index received' (duration: 46.810532ms)","trace[1954395064] 'applied index is now lower than readState.Index' (duration: 81.774795ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:14:47.816114Z","caller":"traceutil/trace.go:171","msg":"trace[611664824] transaction","detail":"{read_only:false; response_revision:3951; number_of_response:1; }","duration":"127.944037ms","start":"2026-09-08T02:14:47.688159Z","end":"2026-09-08T02:14:47.816103Z","steps":["trace[611664824] 'process raft request' (duration: 127.651129ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:14:47.816376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.919196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/passwords.secretgen.k14s.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T02:14:47.816429Z","caller":"traceutil/trace.go:171","msg":"trace[1294444691] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/passwords.secretgen.k14s.io; range_end:; response_count:0; response_revision:3951; }","duration":"129.01192ms","start":"2026-09-08T02:14:47.687403Z","end":"2026-09-08T02:14:47.816415Z","steps":["trace[1294444691] 'agreement among raft nodes before linearized reading' (duration: 128.883646ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:14:50.395361Z","caller":"traceutil/trace.go:171","msg":"trace[1855911775] transaction","detail":"{read_only:false; response_revision:3997; number_of_response:1; }","duration":"152.38528ms","start":"2026-09-08T02:14:50.242936Z","end":"2026-09-08T02:14:50.395321Z","steps":["trace[1855911775] 'process raft request' (duration: 121.857142ms)","trace[1855911775] 'compare' (duration: 30.321812ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:14:50.395521Z","caller":"traceutil/trace.go:171","msg":"trace[354341385] linearizableReadLoop","detail":"{readStateIndex:4154; appliedIndex:4153; }","duration":"111.040413ms","start":"2026-09-08T02:14:50.284437Z","end":"2026-09-08T02:14:50.395477Z","steps":["trace[354341385] 'read index received' (duration: 80.260738ms)","trace[354341385] 'applied index is now lower than readState.Index' (duration: 30.775925ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:14:50.395715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.2545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T02:14:50.395759Z","caller":"traceutil/trace.go:171","msg":"trace[177988349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3998; }","duration":"111.359492ms","start":"2026-09-08T02:14:50.284383Z","end":"2026-09-08T02:14:50.395743Z","steps":["trace[177988349] 'agreement among raft nodes before linearized reading' (duration: 111.217928ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:15:19.624624Z","caller":"traceutil/trace.go:171","msg":"trace[398323464] linearizableReadLoop","detail":"{readStateIndex:4341; appliedIndex:4340; }","duration":"122.427892ms","start":"2026-09-08T02:15:19.502155Z","end":"2026-09-08T02:15:19.624583Z","steps":["trace[398323464] 'read index received' (duration: 122.057451ms)","trace[398323464] 'applied index is now lower than readState.Index' (duration: 367.991µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:15:19.624693Z","caller":"traceutil/trace.go:171","msg":"trace[1716217481] transaction","detail":"{read_only:false; response_revision:4178; number_of_response:1; }","duration":"207.329014ms","start":"2026-09-08T02:15:19.417335Z","end":"2026-09-08T02:15:19.624664Z","steps":["trace[1716217481] 'process raft request' (duration: 207.041215ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:15:19.625073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.890567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T02:15:19.625171Z","caller":"traceutil/trace.go:171","msg":"trace[1949698749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4178; }","duration":"123.038791ms","start":"2026-09-08T02:15:19.502107Z","end":"2026-09-08T02:15:19.625146Z","steps":["trace[1949698749] 'agreement among raft nodes before linearized reading' (duration: 122.679671ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:15:19.769068Z","caller":"traceutil/trace.go:171","msg":"trace[2041859386] linearizableReadLoop","detail":"{readStateIndex:4342; appliedIndex:4341; }","duration":"111.707716ms","start":"2026-09-08T02:15:19.657322Z","end":"2026-09-08T02:15:19.76903Z","steps":["trace[2041859386] 'read index received' (duration: 108.838079ms)","trace[2041859386] 'applied index is now lower than readState.Index' (duration: 2.868037ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:15:19.769089Z","caller":"traceutil/trace.go:171","msg":"trace[544428163] transaction","detail":"{read_only:false; response_revision:4179; number_of_response:1; }","duration":"139.828742ms","start":"2026-09-08T02:15:19.629215Z","end":"2026-09-08T02:15:19.769043Z","steps":["trace[544428163] 'process raft request' (duration: 136.928914ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:15:19.769453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.081178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-08T02:15:19.769595Z","caller":"traceutil/trace.go:171","msg":"trace[1701926407] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4180; }","duration":"112.267314ms","start":"2026-09-08T02:15:19.657305Z","end":"2026-09-08T02:15:19.769572Z","steps":["trace[1701926407] 'agreement among raft nodes before linearized reading' (duration: 111.845401ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:17:00.091291Z","caller":"traceutil/trace.go:171","msg":"trace[2021901992] transaction","detail":"{read_only:false; response_revision:5410; number_of_response:1; }","duration":"134.999254ms","start":"2026-09-08T02:16:59.95626Z","end":"2026-09-08T02:17:00.09126Z","steps":["trace[2021901992] 'process raft request' (duration: 134.785798ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:17:00.315419Z","caller":"traceutil/trace.go:171","msg":"trace[1195769480] linearizableReadLoop","detail":"{readStateIndex:5621; appliedIndex:5620; }","duration":"136.81636ms","start":"2026-09-08T02:17:00.178568Z","end":"2026-09-08T02:17:00.315384Z","steps":["trace[1195769480] 'read index received' (duration: 70.516664ms)","trace[1195769480] 'applied index is now lower than readState.Index' (duration: 66.298506ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:17:00.315487Z","caller":"traceutil/trace.go:171","msg":"trace[976171727] transaction","detail":"{read_only:false; response_revision:5411; number_of_response:1; }","duration":"165.473591ms","start":"2026-09-08T02:17:00.149973Z","end":"2026-09-08T02:17:00.315447Z","steps":["trace[976171727] 'process raft request' (duration: 99.096594ms)","trace[976171727] 'compare' (duration: 66.143461ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:17:00.315736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.135989ms","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-09-08T02:17:00.31583Z","caller":"traceutil/trace.go:171","msg":"trace[790942492] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5411; }","duration":"137.319735ms","start":"2026-09-08T02:17:00.178498Z","end":"2026-09-08T02:17:00.315818Z","steps":["trace[790942492] 'agreement among raft nodes before linearized reading' (duration: 137.056887ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:17:02.614943Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2951} {"level":"info","ts":"2026-09-08T02:17:02.707775Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2951,"took":"90.639496ms","hash":2382292092,"current-db-size-bytes":55300096,"current-db-size":"55 MB","current-db-size-in-use-bytes":30081024,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-08T02:17:02.707864Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2382292092,"revision":2951,"compact-revision":-1} {"level":"warn","ts":"2026-09-08T02:17:16.497549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.105583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T02:17:16.497642Z","caller":"traceutil/trace.go:171","msg":"trace[419282582] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:5708; }","duration":"173.259807ms","start":"2026-09-08T02:17:16.324362Z","end":"2026-09-08T02:17:16.497622Z","steps":["trace[419282582] 'count revisions from in-memory index tree' (duration: 173.02078ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:17:16.522667Z","caller":"traceutil/trace.go:171","msg":"trace[1824302606] linearizableReadLoop","detail":"{readStateIndex:5930; appliedIndex:5929; }","duration":"111.596728ms","start":"2026-09-08T02:17:16.411042Z","end":"2026-09-08T02:17:16.522639Z","steps":["trace[1824302606] 'read index received' (duration: 111.358871ms)","trace[1824302606] 'applied index is now lower than readState.Index' (duration: 236.737µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:17:16.522808Z","caller":"traceutil/trace.go:171","msg":"trace[1850582427] transaction","detail":"{read_only:false; response_revision:5709; number_of_response:1; }","duration":"148.389379ms","start":"2026-09-08T02:17:16.374387Z","end":"2026-09-08T02:17:16.522776Z","steps":["trace[1850582427] 'process raft request' (duration: 148.068839ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:17:16.523374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.31135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-08T02:17:16.523489Z","caller":"traceutil/trace.go:171","msg":"trace[152472549] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5709; }","duration":"112.407524ms","start":"2026-09-08T02:17:16.411037Z","end":"2026-09-08T02:17:16.523445Z","steps":["trace[152472549] 'agreement among raft nodes before linearized reading' (duration: 112.189348ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:17:26.160976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.249913ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-09-08T02:17:26.161576Z","caller":"traceutil/trace.go:171","msg":"trace[1752933985] transaction","detail":"{read_only:false; response_revision:5903; number_of_response:1; }","duration":"168.978427ms","start":"2026-09-08T02:17:25.992566Z","end":"2026-09-08T02:17:26.161545Z","steps":["trace[1752933985] 'process raft request' (duration: 168.657957ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:17:26.345438Z","caller":"traceutil/trace.go:171","msg":"trace[1814733407] linearizableReadLoop","detail":"{readStateIndex:6131; appliedIndex:6130; }","duration":"100.119309ms","start":"2026-09-08T02:17:26.245288Z","end":"2026-09-08T02:17:26.345407Z","steps":["trace[1814733407] 'read index received' (duration: 99.799959ms)","trace[1814733407] 'applied index is now lower than readState.Index' (duration: 318.36µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:17:26.345621Z","caller":"traceutil/trace.go:171","msg":"trace[39703321] transaction","detail":"{read_only:false; response_revision:5905; number_of_response:1; }","duration":"175.67614ms","start":"2026-09-08T02:17:26.169888Z","end":"2026-09-08T02:17:26.345564Z","steps":["trace[39703321] 'process raft request' (duration: 175.3265ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:17:26.345918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.574344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-09-08T02:17:26.346038Z","caller":"traceutil/trace.go:171","msg":"trace[160592409] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:5905; }","duration":"100.78621ms","start":"2026-09-08T02:17:26.24523Z","end":"2026-09-08T02:17:26.346016Z","steps":["trace[160592409] 'agreement among raft nodes before linearized reading' (duration: 100.333486ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:17:26.520901Z","caller":"traceutil/trace.go:171","msg":"trace[2064084922] transaction","detail":"{read_only:false; response_revision:5906; number_of_response:1; }","duration":"157.104834ms","start":"2026-09-08T02:17:26.363769Z","end":"2026-09-08T02:17:26.520874Z","steps":["trace[2064084922] 'process raft request' (duration: 156.151306ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:17:38.41048Z","caller":"traceutil/trace.go:171","msg":"trace[1231238588] transaction","detail":"{read_only:false; response_revision:6024; number_of_response:1; }","duration":"109.472134ms","start":"2026-09-08T02:17:38.30096Z","end":"2026-09-08T02:17:38.410432Z","steps":["trace[1231238588] 'process raft request' (duration: 109.167315ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:17:38.410494Z","caller":"traceutil/trace.go:171","msg":"trace[598724104] transaction","detail":"{read_only:false; response_revision:6025; number_of_response:1; }","duration":"109.369311ms","start":"2026-09-08T02:17:38.301094Z","end":"2026-09-08T02:17:38.410463Z","steps":["trace[598724104] 'process raft request' (duration: 109.234067ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:17:38.411798Z","caller":"traceutil/trace.go:171","msg":"trace[413904134] transaction","detail":"{read_only:false; response_revision:6026; number_of_response:1; }","duration":"110.025252ms","start":"2026-09-08T02:17:38.301731Z","end":"2026-09-08T02:17:38.411757Z","steps":["trace[413904134] 'process raft request' (duration: 108.66011ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:22:02.62159Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5453} {"level":"info","ts":"2026-09-08T02:22:02.709099Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5453,"took":"85.2027ms","hash":1689979774,"current-db-size-bytes":55300096,"current-db-size":"55 MB","current-db-size-in-use-bytes":28672000,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-08T02:22:02.709191Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1689979774,"revision":5453,"compact-revision":2951} {"level":"info","ts":"2026-09-08T02:24:04.45348Z","caller":"traceutil/trace.go:171","msg":"trace[1640280393] linearizableReadLoop","detail":"{readStateIndex:9184; appliedIndex:9182; }","duration":"165.693469ms","start":"2026-09-08T02:24:04.287754Z","end":"2026-09-08T02:24:04.453448Z","steps":["trace[1640280393] 'read index received' (duration: 4.689014ms)","trace[1640280393] 'applied index is now lower than readState.Index' (duration: 161.003145ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:24:04.453788Z","caller":"traceutil/trace.go:171","msg":"trace[1224462455] transaction","detail":"{read_only:false; response_revision:8862; number_of_response:1; }","duration":"239.040868ms","start":"2026-09-08T02:24:04.214588Z","end":"2026-09-08T02:24:04.453629Z","steps":["trace[1224462455] 'process raft request' (duration: 223.0043ms)","trace[1224462455] 'compare' (duration: 15.518552ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:24:04.453965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.187003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T02:24:04.454107Z","caller":"traceutil/trace.go:171","msg":"trace[207155491] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8862; }","duration":"166.390709ms","start":"2026-09-08T02:24:04.287683Z","end":"2026-09-08T02:24:04.454074Z","steps":["trace[207155491] 'agreement among raft nodes before linearized reading' (duration: 166.201954ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:24:04.454566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.821298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T02:24:04.454636Z","caller":"traceutil/trace.go:171","msg":"trace[2113925597] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:8862; }","duration":"157.983452ms","start":"2026-09-08T02:24:04.296634Z","end":"2026-09-08T02:24:04.454618Z","steps":["trace[2113925597] 'agreement among raft nodes before linearized reading' (duration: 157.131817ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:24:15.17711Z","caller":"traceutil/trace.go:171","msg":"trace[62489713] linearizableReadLoop","detail":"{readStateIndex:9242; appliedIndex:9240; }","duration":"155.106194ms","start":"2026-09-08T02:24:15.021971Z","end":"2026-09-08T02:24:15.177077Z","steps":["trace[62489713] 'read index received' (duration: 39.269033ms)","trace[62489713] 'applied index is now lower than readState.Index' (duration: 115.835611ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:24:15.177143Z","caller":"traceutil/trace.go:171","msg":"trace[1879916618] transaction","detail":"{read_only:false; response_revision:8918; number_of_response:1; }","duration":"169.10651ms","start":"2026-09-08T02:24:15.007976Z","end":"2026-09-08T02:24:15.177083Z","steps":["trace[1879916618] 'process raft request' (duration: 168.786801ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:24:15.17723Z","caller":"traceutil/trace.go:171","msg":"trace[1063342748] transaction","detail":"{read_only:false; response_revision:8920; number_of_response:1; }","duration":"147.36138ms","start":"2026-09-08T02:24:15.029857Z","end":"2026-09-08T02:24:15.177218Z","steps":["trace[1063342748] 'process raft request' (duration: 147.163143ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:24:15.177447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.454646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-08T02:24:15.177153Z","caller":"traceutil/trace.go:171","msg":"trace[1207038822] transaction","detail":"{read_only:false; response_revision:8919; number_of_response:1; }","duration":"151.596949ms","start":"2026-09-08T02:24:15.025526Z","end":"2026-09-08T02:24:15.177123Z","steps":["trace[1207038822] 'process raft request' (duration: 151.441164ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:24:15.177513Z","caller":"traceutil/trace.go:171","msg":"trace[1331048702] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8920; }","duration":"155.591409ms","start":"2026-09-08T02:24:15.021904Z","end":"2026-09-08T02:24:15.177496Z","steps":["trace[1331048702] 'agreement among raft nodes before linearized reading' (duration: 155.360783ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:24:15.17745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.8343ms","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-09-08T02:24:15.177784Z","caller":"traceutil/trace.go:171","msg":"trace[1223987512] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8920; }","duration":"139.223792ms","start":"2026-09-08T02:24:15.038538Z","end":"2026-09-08T02:24:15.177762Z","steps":["trace[1223987512] 'agreement among raft nodes before linearized reading' (duration: 138.732188ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:24:16.556863Z","caller":"traceutil/trace.go:171","msg":"trace[1369246084] transaction","detail":"{read_only:false; response_revision:8925; number_of_response:1; }","duration":"192.981727ms","start":"2026-09-08T02:24:16.363853Z","end":"2026-09-08T02:24:16.556835Z","steps":["trace[1369246084] 'process raft request' (duration: 192.681188ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:24:25.120546Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002455.wal"} {"level":"info","ts":"2026-09-08T02:24:42.312362Z","caller":"traceutil/trace.go:171","msg":"trace[1997153448] linearizableReadLoop","detail":"{readStateIndex:9450; appliedIndex:9449; }","duration":"117.0872ms","start":"2026-09-08T02:24:42.195238Z","end":"2026-09-08T02:24:42.312325Z","steps":["trace[1997153448] 'read index received' (duration: 116.710388ms)","trace[1997153448] 'applied index is now lower than readState.Index' (duration: 374.832µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:24:42.312389Z","caller":"traceutil/trace.go:171","msg":"trace[1254829359] transaction","detail":"{read_only:false; response_revision:9117; number_of_response:1; }","duration":"118.472513ms","start":"2026-09-08T02:24:42.193874Z","end":"2026-09-08T02:24:42.312346Z","steps":["trace[1254829359] 'process raft request' (duration: 118.122502ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:24:42.312499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.239615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/rook-ceph/csi-rbdplugin-provisioner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T02:24:42.312601Z","caller":"traceutil/trace.go:171","msg":"trace[303069466] range","detail":"{range_begin:/registry/deployments/rook-ceph/csi-rbdplugin-provisioner; range_end:; response_count:0; response_revision:9117; }","duration":"117.372809ms","start":"2026-09-08T02:24:42.195202Z","end":"2026-09-08T02:24:42.312575Z","steps":["trace[303069466] 'agreement among raft nodes before linearized reading' (duration: 117.197953ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:24:46.501635Z","caller":"traceutil/trace.go:171","msg":"trace[1063965437] transaction","detail":"{read_only:false; response_revision:9139; number_of_response:1; }","duration":"502.128157ms","start":"2026-09-08T02:24:45.999473Z","end":"2026-09-08T02:24:46.501601Z","steps":["trace[1063965437] 'process raft request' (duration: 434.15575ms)","trace[1063965437] 'compare' (duration: 67.274905ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:24:46.501863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:24:45.999433Z","time spent":"502.314462ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T02:24:46.501845Z","caller":"traceutil/trace.go:171","msg":"trace[2031754422] transaction","detail":"{read_only:false; response_revision:9140; number_of_response:1; }","duration":"265.372588ms","start":"2026-09-08T02:24:46.236426Z","end":"2026-09-08T02:24:46.501799Z","steps":["trace[2031754422] 'process raft request' (duration: 265.108001ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:24:49.309547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.100145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T02:24:49.309689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.352651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T02:24:49.309775Z","caller":"traceutil/trace.go:171","msg":"trace[501406829] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:9153; }","duration":"227.499695ms","start":"2026-09-08T02:24:49.082256Z","end":"2026-09-08T02:24:49.309756Z","steps":["trace[501406829] 'count revisions from in-memory index tree' (duration: 227.244498ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:24:49.309959Z","caller":"traceutil/trace.go:171","msg":"trace[735931930] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:9153; }","duration":"231.297302ms","start":"2026-09-08T02:24:49.078341Z","end":"2026-09-08T02:24:49.309639Z","steps":["trace[735931930] 'count revisions from in-memory index tree' (duration: 230.972151ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:24:49.340348Z","caller":"traceutil/trace.go:171","msg":"trace[1497779379] transaction","detail":"{read_only:false; response_revision:9154; number_of_response:1; }","duration":"133.833288ms","start":"2026-09-08T02:24:49.206491Z","end":"2026-09-08T02:24:49.340324Z","steps":["trace[1497779379] 'process raft request' (duration: 133.658233ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:24:57.693589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.509678ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T02:24:57.693755Z","caller":"traceutil/trace.go:171","msg":"trace[1014385620] linearizableReadLoop","detail":"{readStateIndex:9536; appliedIndex:9534; }","duration":"297.868915ms","start":"2026-09-08T02:24:57.395831Z","end":"2026-09-08T02:24:57.6937Z","steps":["trace[1014385620] 'read index received' (duration: 23.05204ms)","trace[1014385620] 'applied index is now lower than readState.Index' (duration: 274.815435ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:24:57.694147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.296409ms","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-09-08T02:24:57.694244Z","caller":"traceutil/trace.go:171","msg":"trace[1829429421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9200; }","duration":"298.443214ms","start":"2026-09-08T02:24:57.395778Z","end":"2026-09-08T02:24:57.694222Z","steps":["trace[1829429421] 'agreement among raft nodes before linearized reading' (duration: 298.166315ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:24:57.694246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.242916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-08T02:24:57.694299Z","caller":"traceutil/trace.go:171","msg":"trace[1556919695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9200; }","duration":"296.332849ms","start":"2026-09-08T02:24:57.39795Z","end":"2026-09-08T02:24:57.694283Z","steps":["trace[1556919695] 'agreement among raft nodes before linearized reading' (duration: 296.134823ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:24:57.694406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.77828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-09-08T02:24:57.694429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.56039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-08T02:24:57.694369Z","caller":"traceutil/trace.go:171","msg":"trace[1878725639] transaction","detail":"{read_only:false; response_revision:9200; number_of_response:1; }","duration":"316.251114ms","start":"2026-09-08T02:24:57.378033Z","end":"2026-09-08T02:24:57.694284Z","steps":["trace[1878725639] 'process raft request' (duration: 133.91316ms)","trace[1878725639] 'compare' (duration: 181.355404ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:24:57.694461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.272186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T02:24:57.694497Z","caller":"traceutil/trace.go:171","msg":"trace[2030716470] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9200; }","duration":"290.882464ms","start":"2026-09-08T02:24:57.403589Z","end":"2026-09-08T02:24:57.694472Z","steps":["trace[2030716470] 'agreement among raft nodes before linearized reading' (duration: 290.714249ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:24:57.694517Z","caller":"traceutil/trace.go:171","msg":"trace[1977143449] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9200; }","duration":"280.685483ms","start":"2026-09-08T02:24:57.413814Z","end":"2026-09-08T02:24:57.6945Z","steps":["trace[1977143449] 'agreement among raft nodes before linearized reading' (duration: 280.480287ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:24:57.694553Z","caller":"traceutil/trace.go:171","msg":"trace[1517387382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9200; }","duration":"192.461251ms","start":"2026-09-08T02:24:57.502067Z","end":"2026-09-08T02:24:57.694528Z","steps":["trace[1517387382] 'agreement among raft nodes before linearized reading' (duration: 192.234355ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:24:57.694644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:24:57.378001Z","time spent":"316.523112ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T02:24:57.862768Z","caller":"traceutil/trace.go:171","msg":"trace[1569422267] transaction","detail":"{read_only:false; response_revision:9203; number_of_response:1; }","duration":"162.026616ms","start":"2026-09-08T02:24:57.700703Z","end":"2026-09-08T02:24:57.86273Z","steps":["trace[1569422267] 'process raft request' (duration: 161.717377ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:24:57.8628Z","caller":"traceutil/trace.go:171","msg":"trace[1503332050] transaction","detail":"{read_only:false; response_revision:9204; number_of_response:1; }","duration":"161.106468ms","start":"2026-09-08T02:24:57.701669Z","end":"2026-09-08T02:24:57.862776Z","steps":["trace[1503332050] 'process raft request' (duration: 160.937863ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:24:57.862789Z","caller":"traceutil/trace.go:171","msg":"trace[185772647] transaction","detail":"{read_only:false; response_revision:9202; number_of_response:1; }","duration":"162.061737ms","start":"2026-09-08T02:24:57.700651Z","end":"2026-09-08T02:24:57.862713Z","steps":["trace[185772647] 'process raft request' (duration: 101.503386ms)","trace[185772647] 'compare' (duration: 60.093578ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:24:57.862767Z","caller":"traceutil/trace.go:171","msg":"trace[297011210] transaction","detail":"{read_only:false; response_revision:9205; number_of_response:1; }","duration":"149.946798ms","start":"2026-09-08T02:24:57.712802Z","end":"2026-09-08T02:24:57.862749Z","steps":["trace[297011210] 'process raft request' (duration: 149.856286ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:25:00.120578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.316519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T02:25:00.120685Z","caller":"traceutil/trace.go:171","msg":"trace[1421259222] linearizableReadLoop","detail":"{readStateIndex:9561; appliedIndex:9560; }","duration":"220.702169ms","start":"2026-09-08T02:24:59.899967Z","end":"2026-09-08T02:25:00.120669Z","steps":["trace[1421259222] 'read index received' (duration: 46.124802ms)","trace[1421259222] 'applied index is now lower than readState.Index' (duration: 174.576547ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:25:00.120764Z","caller":"traceutil/trace.go:171","msg":"trace[420020805] transaction","detail":"{read_only:false; response_revision:9225; number_of_response:1; }","duration":"220.933206ms","start":"2026-09-08T02:24:59.899767Z","end":"2026-09-08T02:25:00.120701Z","steps":["trace[420020805] 'process raft request' (duration: 46.39838ms)","trace[420020805] 'compare' (duration: 174.087272ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:25:00.120862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.893856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-p7l72\" ","response":"range_response_count:1 size:2803"} {"level":"info","ts":"2026-09-08T02:25:00.120887Z","caller":"traceutil/trace.go:171","msg":"trace[181230800] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-p7l72; range_end:; response_count:1; response_revision:9225; }","duration":"220.938057ms","start":"2026-09-08T02:24:59.899937Z","end":"2026-09-08T02:25:00.120875Z","steps":["trace[181230800] 'agreement among raft nodes before linearized reading' (duration: 220.801873ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:25:45.869642Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"adb6690514c92321","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-08T02:25:45.876956Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-08T02:25:45.877222Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-08T02:26:31.422148Z","caller":"traceutil/trace.go:171","msg":"trace[40921562] transaction","detail":"{read_only:false; response_revision:10084; number_of_response:1; }","duration":"103.033793ms","start":"2026-09-08T02:26:31.31906Z","end":"2026-09-08T02:26:31.422094Z","steps":["trace[40921562] 'process raft request' (duration: 102.677763ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:26:31.812569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.437425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T02:26:31.812715Z","caller":"traceutil/trace.go:171","msg":"trace[1691582602] linearizableReadLoop","detail":"{readStateIndex:10448; appliedIndex:10447; }","duration":"361.49757ms","start":"2026-09-08T02:26:31.451191Z","end":"2026-09-08T02:26:31.812688Z","steps":["trace[1691582602] 'read index received' (duration: 234.847289ms)","trace[1691582602] 'applied index is now lower than readState.Index' (duration: 126.647531ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:26:31.812816Z","caller":"traceutil/trace.go:171","msg":"trace[1007062503] transaction","detail":"{read_only:false; response_revision:10085; number_of_response:1; }","duration":"385.232401ms","start":"2026-09-08T02:26:31.427557Z","end":"2026-09-08T02:26:31.81279Z","steps":["trace[1007062503] 'process raft request' (duration: 258.457686ms)","trace[1007062503] 'compare' (duration: 126.293921ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:26:31.812943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.553451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T02:26:31.812936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:26:31.427523Z","time spent":"385.363875ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T02:26:31.812993Z","caller":"traceutil/trace.go:171","msg":"trace[900631772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10085; }","duration":"309.652964ms","start":"2026-09-08T02:26:31.503326Z","end":"2026-09-08T02:26:31.812979Z","steps":["trace[900631772] 'agreement among raft nodes before linearized reading' (duration: 309.53002ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:26:31.813041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:26:31.503296Z","time spent":"309.735496ms","remote":"127.0.0.1:41294","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-09-08T02:26:31.813051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.83963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-08T02:26:31.813166Z","caller":"traceutil/trace.go:171","msg":"trace[466892868] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:10085; }","duration":"362.043976ms","start":"2026-09-08T02:26:31.451092Z","end":"2026-09-08T02:26:31.813136Z","steps":["trace[466892868] 'agreement among raft nodes before linearized reading' (duration: 361.694236ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:26:31.813218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:26:31.451063Z","time spent":"362.14582ms","remote":"127.0.0.1:41472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-08T02:27:02.62965Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7942} {"level":"info","ts":"2026-09-08T02:27:02.736486Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7942,"took":"103.733454ms","hash":1132023872,"current-db-size-bytes":55300096,"current-db-size":"55 MB","current-db-size-in-use-bytes":24748032,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-08T02:27:02.736577Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1132023872,"revision":7942,"compact-revision":5453} {"level":"info","ts":"2026-09-08T02:30:14.442346Z","caller":"traceutil/trace.go:171","msg":"trace[9644759] transaction","detail":"{read_only:false; response_revision:11451; number_of_response:1; }","duration":"126.312012ms","start":"2026-09-08T02:30:14.315996Z","end":"2026-09-08T02:30:14.442308Z","steps":["trace[9644759] 'process raft request' (duration: 126.105316ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:30:15.156507Z","caller":"traceutil/trace.go:171","msg":"trace[1230318282] transaction","detail":"{read_only:false; response_revision:11457; number_of_response:1; }","duration":"123.951379ms","start":"2026-09-08T02:30:15.032501Z","end":"2026-09-08T02:30:15.156452Z","steps":["trace[1230318282] 'process raft request' (duration: 90.952776ms)","trace[1230318282] 'compare' (duration: 32.721445ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:30:41.4307Z","caller":"traceutil/trace.go:171","msg":"trace[764179164] transaction","detail":"{read_only:false; response_revision:11692; number_of_response:1; }","duration":"109.675006ms","start":"2026-09-08T02:30:41.320989Z","end":"2026-09-08T02:30:41.430664Z","steps":["trace[764179164] 'process raft request' (duration: 67.508893ms)","trace[764179164] 'compare' (duration: 41.960007ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:32:02.636989Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10303} {"level":"info","ts":"2026-09-08T02:32:02.726276Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10303,"took":"86.365648ms","hash":2499131470,"current-db-size-bytes":55300096,"current-db-size":"55 MB","current-db-size-in-use-bytes":25530368,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-08T02:32:02.726378Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2499131470,"revision":10303,"compact-revision":7942} {"level":"info","ts":"2026-09-08T02:32:10.422209Z","caller":"traceutil/trace.go:171","msg":"trace[1357348124] linearizableReadLoop","detail":"{readStateIndex:12955; appliedIndex:12954; }","duration":"109.4832ms","start":"2026-09-08T02:32:10.312699Z","end":"2026-09-08T02:32:10.422182Z","steps":["trace[1357348124] 'read index received' (duration: 109.277234ms)","trace[1357348124] 'applied index is now lower than readState.Index' (duration: 204.866µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:32:10.422332Z","caller":"traceutil/trace.go:171","msg":"trace[1433509491] transaction","detail":"{read_only:false; response_revision:12513; number_of_response:1; }","duration":"113.860143ms","start":"2026-09-08T02:32:10.30846Z","end":"2026-09-08T02:32:10.42232Z","steps":["trace[1433509491] 'process raft request' (duration: 113.520183ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:32:10.422567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.83753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T02:32:10.422619Z","caller":"traceutil/trace.go:171","msg":"trace[95991153] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:12513; }","duration":"109.946324ms","start":"2026-09-08T02:32:10.312657Z","end":"2026-09-08T02:32:10.422604Z","steps":["trace[95991153] 'agreement among raft nodes before linearized reading' (duration: 109.827ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:37:02.64868Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12474} {"level":"info","ts":"2026-09-08T02:37:02.731869Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12474,"took":"80.335255ms","hash":3000835344,"current-db-size-bytes":55300096,"current-db-size":"55 MB","current-db-size-in-use-bytes":27766784,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-08T02:37:02.731957Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3000835344,"revision":12474,"compact-revision":10303} {"level":"info","ts":"2026-09-08T02:37:59.157437Z","caller":"traceutil/trace.go:171","msg":"trace[1942680797] transaction","detail":"{read_only:false; response_revision:14854; number_of_response:1; }","duration":"169.3938ms","start":"2026-09-08T02:37:58.98802Z","end":"2026-09-08T02:37:59.157414Z","steps":["trace[1942680797] 'process raft request' (duration: 169.221425ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:37:59.204333Z","caller":"traceutil/trace.go:171","msg":"trace[1370772225] linearizableReadLoop","detail":"{readStateIndex:15378; appliedIndex:15376; }","duration":"139.97135ms","start":"2026-09-08T02:37:59.064339Z","end":"2026-09-08T02:37:59.204311Z","steps":["trace[1370772225] 'read index received' (duration: 92.935815ms)","trace[1370772225] 'applied index is now lower than readState.Index' (duration: 47.034255ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:37:59.204551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.204897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/placement-ks-endpoints\" ","response":"range_response_count:1 size:12752"} {"level":"info","ts":"2026-09-08T02:37:59.204576Z","caller":"traceutil/trace.go:171","msg":"trace[2031387151] range","detail":"{range_begin:/registry/jobs/openstack/placement-ks-endpoints; range_end:; response_count:1; response_revision:14855; }","duration":"140.282779ms","start":"2026-09-08T02:37:59.064286Z","end":"2026-09-08T02:37:59.204569Z","steps":["trace[2031387151] 'agreement among raft nodes before linearized reading' (duration: 140.134525ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:37:59.205668Z","caller":"traceutil/trace.go:171","msg":"trace[1735299807] transaction","detail":"{read_only:false; response_revision:14855; number_of_response:1; }","duration":"207.269331ms","start":"2026-09-08T02:37:58.997502Z","end":"2026-09-08T02:37:59.204771Z","steps":["trace[1735299807] 'process raft request' (duration: 171.078592ms)","trace[1735299807] 'compare' (duration: 35.557319ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:38:00.070703Z","caller":"traceutil/trace.go:171","msg":"trace[138609524] transaction","detail":"{read_only:false; response_revision:14858; number_of_response:1; }","duration":"115.697888ms","start":"2026-09-08T02:37:59.954975Z","end":"2026-09-08T02:38:00.070673Z","steps":["trace[138609524] 'process raft request' (duration: 75.99499ms)","trace[138609524] 'compare' (duration: 39.492222ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:38:00.573388Z","caller":"traceutil/trace.go:171","msg":"trace[716953169] transaction","detail":"{read_only:false; response_revision:14861; number_of_response:1; }","duration":"197.244662ms","start":"2026-09-08T02:38:00.376108Z","end":"2026-09-08T02:38:00.573353Z","steps":["trace[716953169] 'process raft request' (duration: 146.017588ms)","trace[716953169] 'compare' (duration: 51.08403ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:38:00.609139Z","caller":"traceutil/trace.go:171","msg":"trace[1442046541] transaction","detail":"{read_only:false; response_revision:14862; number_of_response:1; }","duration":"232.953316ms","start":"2026-09-08T02:38:00.37616Z","end":"2026-09-08T02:38:00.609113Z","steps":["trace[1442046541] 'process raft request' (duration: 232.77935ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:00.609304Z","caller":"traceutil/trace.go:171","msg":"trace[1557738771] transaction","detail":"{read_only:false; response_revision:14863; number_of_response:1; }","duration":"232.320787ms","start":"2026-09-08T02:38:00.376962Z","end":"2026-09-08T02:38:00.609283Z","steps":["trace[1557738771] 'process raft request' (duration: 232.10409ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:00.672034Z","caller":"traceutil/trace.go:171","msg":"trace[1561599951] transaction","detail":"{read_only:false; response_revision:14864; number_of_response:1; }","duration":"140.434416ms","start":"2026-09-08T02:38:00.531309Z","end":"2026-09-08T02:38:00.671743Z","steps":["trace[1561599951] 'process raft request' (duration: 140.279831ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:00.84223Z","caller":"traceutil/trace.go:171","msg":"trace[1274588252] linearizableReadLoop","detail":"{readStateIndex:15388; appliedIndex:15386; }","duration":"181.245346ms","start":"2026-09-08T02:38:00.660961Z","end":"2026-09-08T02:38:00.842206Z","steps":["trace[1274588252] 'read index received' (duration: 10.475933ms)","trace[1274588252] 'applied index is now lower than readState.Index' (duration: 170.768553ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:38:00.842328Z","caller":"traceutil/trace.go:171","msg":"trace[368263174] transaction","detail":"{read_only:false; response_revision:14865; number_of_response:1; }","duration":"204.463745ms","start":"2026-09-08T02:38:00.63781Z","end":"2026-09-08T02:38:00.842274Z","steps":["trace[368263174] 'process raft request' (duration: 204.249709ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:00.84238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.39457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T02:38:00.842466Z","caller":"traceutil/trace.go:171","msg":"trace[1486456251] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:14865; }","duration":"181.516906ms","start":"2026-09-08T02:38:00.660915Z","end":"2026-09-08T02:38:00.842432Z","steps":["trace[1486456251] 'agreement among raft nodes before linearized reading' (duration: 181.411002ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:00.842523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.687009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T02:38:00.842591Z","caller":"traceutil/trace.go:171","msg":"trace[35146764] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:14865; }","duration":"105.810134ms","start":"2026-09-08T02:38:00.73676Z","end":"2026-09-08T02:38:00.84257Z","steps":["trace[35146764] 'agreement among raft nodes before linearized reading' (duration: 105.618557ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:02.206109Z","caller":"traceutil/trace.go:171","msg":"trace[849036863] transaction","detail":"{read_only:false; response_revision:14868; number_of_response:1; }","duration":"128.841426ms","start":"2026-09-08T02:38:02.077245Z","end":"2026-09-08T02:38:02.206086Z","steps":["trace[849036863] 'process raft request' (duration: 67.87664ms)","trace[849036863] 'compare' (duration: 60.852402ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:38:02.846145Z","caller":"traceutil/trace.go:171","msg":"trace[1971586970] transaction","detail":"{read_only:false; response_revision:14872; number_of_response:1; }","duration":"229.306494ms","start":"2026-09-08T02:38:02.616811Z","end":"2026-09-08T02:38:02.846117Z","steps":["trace[1971586970] 'process raft request' (duration: 228.643943ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:02.846322Z","caller":"traceutil/trace.go:171","msg":"trace[171593840] transaction","detail":"{read_only:false; response_revision:14873; number_of_response:1; }","duration":"229.058196ms","start":"2026-09-08T02:38:02.617254Z","end":"2026-09-08T02:38:02.846312Z","steps":["trace[171593840] 'process raft request' (duration: 228.348503ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:02.84664Z","caller":"traceutil/trace.go:171","msg":"trace[953429481] transaction","detail":"{read_only:false; response_revision:14874; number_of_response:1; }","duration":"170.020219ms","start":"2026-09-08T02:38:02.676599Z","end":"2026-09-08T02:38:02.846619Z","steps":["trace[953429481] 'process raft request' (duration: 169.047339ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:03.54976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.785887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T02:38:03.549834Z","caller":"traceutil/trace.go:171","msg":"trace[1276865106] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:14874; }","duration":"430.914721ms","start":"2026-09-08T02:38:03.118902Z","end":"2026-09-08T02:38:03.549817Z","steps":["trace[1276865106] 'count revisions from in-memory index tree' (duration: 430.661752ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:03.549866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:03.118883Z","time spent":"430.975012ms","remote":"127.0.0.1:41758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":33,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-09-08T02:38:03.550165Z","caller":"traceutil/trace.go:171","msg":"trace[761478420] linearizableReadLoop","detail":"{readStateIndex:15399; appliedIndex:15398; }","duration":"331.270698ms","start":"2026-09-08T02:38:03.218872Z","end":"2026-09-08T02:38:03.550143Z","steps":["trace[761478420] 'read index received' (duration: 330.578807ms)","trace[761478420] 'applied index is now lower than readState.Index' (duration: 690.711µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:38:03.550219Z","caller":"traceutil/trace.go:171","msg":"trace[2048026001] transaction","detail":"{read_only:false; response_revision:14875; number_of_response:1; }","duration":"372.07951ms","start":"2026-09-08T02:38:03.178113Z","end":"2026-09-08T02:38:03.550192Z","steps":["trace[2048026001] 'process raft request' (duration: 371.237424ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:03.550354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:03.178092Z","time spent":"372.189414ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T02:38:03.550337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.108163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"warn","ts":"2026-09-08T02:38:03.550366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.489835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/placement-ks-endpoints\" ","response":"range_response_count:1 size:12752"} {"level":"info","ts":"2026-09-08T02:38:03.55047Z","caller":"traceutil/trace.go:171","msg":"trace[1069862712] range","detail":"{range_begin:/registry/jobs/openstack/placement-ks-endpoints; range_end:; response_count:1; response_revision:14875; }","duration":"331.608019ms","start":"2026-09-08T02:38:03.218817Z","end":"2026-09-08T02:38:03.550425Z","steps":["trace[1069862712] 'agreement among raft nodes before linearized reading' (duration: 331.405802ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:03.550522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:03.218794Z","time spent":"331.715222ms","remote":"127.0.0.1:41530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":12776,"request content":"key:\"/registry/jobs/openstack/placement-ks-endpoints\" "} {"level":"info","ts":"2026-09-08T02:38:03.550994Z","caller":"traceutil/trace.go:171","msg":"trace[70841092] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:14874; }","duration":"618.217766ms","start":"2026-09-08T02:38:02.932177Z","end":"2026-09-08T02:38:03.550395Z","steps":["trace[70841092] 'range keys from in-memory index tree' (duration: 617.499083ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:03.551082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:02.932166Z","time spent":"618.900467ms","remote":"127.0.0.1:54298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5675,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-08T02:38:03.588518Z","caller":"traceutil/trace.go:171","msg":"trace[615677066] transaction","detail":"{read_only:false; response_revision:14876; number_of_response:1; }","duration":"364.028042ms","start":"2026-09-08T02:38:03.224452Z","end":"2026-09-08T02:38:03.58848Z","steps":["trace[615677066] 'process raft request' (duration: 363.840856ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:03.588692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:03.224428Z","time spent":"364.171006ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T02:38:04.727567Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2531480931887129607,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-08T02:38:04.787732Z","caller":"traceutil/trace.go:171","msg":"trace[462961058] transaction","detail":"{read_only:false; response_revision:14877; number_of_response:1; }","duration":"789.048169ms","start":"2026-09-08T02:38:03.998626Z","end":"2026-09-08T02:38:04.787674Z","steps":["trace[462961058] 'process raft request' (duration: 788.869083ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:04.787887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:03.998609Z","time spent":"789.188504ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T02:38:04.885715Z","caller":"traceutil/trace.go:171","msg":"trace[293346956] transaction","detail":"{read_only:false; response_revision:14879; number_of_response:1; }","duration":"616.149611ms","start":"2026-09-08T02:38:04.269539Z","end":"2026-09-08T02:38:04.885688Z","steps":["trace[293346956] 'process raft request' (duration: 616.062349ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:04.88574Z","caller":"traceutil/trace.go:171","msg":"trace[860387983] transaction","detail":"{read_only:false; response_revision:14878; number_of_response:1; }","duration":"675.458015ms","start":"2026-09-08T02:38:04.210256Z","end":"2026-09-08T02:38:04.885714Z","steps":["trace[860387983] 'process raft request' (duration: 605.430709ms)","trace[860387983] 'compare' (duration: 69.713397ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:38:04.885896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:04.269517Z","time spent":"616.286736ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T02:38:04.886028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:04.210234Z","time spent":"675.678132ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T02:38:04.886292Z","caller":"traceutil/trace.go:171","msg":"trace[1443738656] linearizableReadLoop","detail":"{readStateIndex:15405; appliedIndex:15400; }","duration":"659.369388ms","start":"2026-09-08T02:38:04.226909Z","end":"2026-09-08T02:38:04.886278Z","steps":["trace[1443738656] 'read index received' (duration: 560.512229ms)","trace[1443738656] 'applied index is now lower than readState.Index' (duration: 98.856299ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:38:04.886309Z","caller":"traceutil/trace.go:171","msg":"trace[598359610] transaction","detail":"{read_only:false; response_revision:14880; number_of_response:1; }","duration":"474.212481ms","start":"2026-09-08T02:38:04.412047Z","end":"2026-09-08T02:38:04.88626Z","steps":["trace[598359610] 'process raft request' (duration: 473.596931ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:04.88633Z","caller":"traceutil/trace.go:171","msg":"trace[1026489414] transaction","detail":"{read_only:false; response_revision:14881; number_of_response:1; }","duration":"306.73858ms","start":"2026-09-08T02:38:04.57956Z","end":"2026-09-08T02:38:04.886299Z","steps":["trace[1026489414] 'process raft request' (duration: 306.610976ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:04.886504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.584154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-08T02:38:04.886546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:04.57954Z","time spent":"306.881824ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T02:38:04.88657Z","caller":"traceutil/trace.go:171","msg":"trace[186317599] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:14881; }","duration":"659.691168ms","start":"2026-09-08T02:38:04.226862Z","end":"2026-09-08T02:38:04.886553Z","steps":["trace[186317599] 'agreement among raft nodes before linearized reading' (duration: 659.485592ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:04.886583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:04.412023Z","time spent":"474.376156ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T02:38:04.886606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:04.226839Z","time spent":"659.75696ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-09-08T02:38:04.889269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.392765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-09-08T02:38:04.889308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.303117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-09-08T02:38:04.889326Z","caller":"traceutil/trace.go:171","msg":"trace[1159214069] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14882; }","duration":"590.485248ms","start":"2026-09-08T02:38:04.298826Z","end":"2026-09-08T02:38:04.889311Z","steps":["trace[1159214069] 'agreement among raft nodes before linearized reading' (duration: 590.293012ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:04.889361Z","caller":"traceutil/trace.go:171","msg":"trace[1478593416] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:14882; }","duration":"157.40618ms","start":"2026-09-08T02:38:04.731936Z","end":"2026-09-08T02:38:04.889343Z","steps":["trace[1478593416] 'agreement among raft nodes before linearized reading' (duration: 157.241595ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:04.889365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:04.298806Z","time spent":"590.550719ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-08T02:38:04.889634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.811369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-08T02:38:04.889675Z","caller":"traceutil/trace.go:171","msg":"trace[49060945] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14882; }","duration":"185.885051ms","start":"2026-09-08T02:38:04.703779Z","end":"2026-09-08T02:38:04.889664Z","steps":["trace[49060945] 'agreement among raft nodes before linearized reading' (duration: 185.777498ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:04.889929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.090555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T02:38:04.889962Z","caller":"traceutil/trace.go:171","msg":"trace[1505825522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14882; }","duration":"387.187147ms","start":"2026-09-08T02:38:04.502766Z","end":"2026-09-08T02:38:04.889954Z","steps":["trace[1505825522] 'agreement among raft nodes before linearized reading' (duration: 387.116345ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:04.889981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:04.502746Z","time spent":"387.229979ms","remote":"127.0.0.1:41280","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-09-08T02:38:04.890144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.392887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rqlw6\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-08T02:38:04.890168Z","caller":"traceutil/trace.go:171","msg":"trace[276249720] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rqlw6; range_end:; response_count:1; response_revision:14882; }","duration":"561.456249ms","start":"2026-09-08T02:38:04.328706Z","end":"2026-09-08T02:38:04.890162Z","steps":["trace[276249720] 'agreement among raft nodes before linearized reading' (duration: 561.383627ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:04.890184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:04.328683Z","time spent":"561.49721ms","remote":"127.0.0.1:41472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-rqlw6\" "} {"level":"info","ts":"2026-09-08T02:38:07.013471Z","caller":"traceutil/trace.go:171","msg":"trace[1863882038] transaction","detail":"{read_only:false; response_revision:14891; number_of_response:1; }","duration":"119.936661ms","start":"2026-09-08T02:38:06.893507Z","end":"2026-09-08T02:38:07.013444Z","steps":["trace[1863882038] 'process raft request' (duration: 119.552639ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:07.013691Z","caller":"traceutil/trace.go:171","msg":"trace[1648838041] transaction","detail":"{read_only:false; response_revision:14892; number_of_response:1; }","duration":"117.447203ms","start":"2026-09-08T02:38:06.896233Z","end":"2026-09-08T02:38:07.01368Z","steps":["trace[1648838041] 'process raft request' (duration: 116.905467ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:09.672663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.137834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T02:38:09.672751Z","caller":"traceutil/trace.go:171","msg":"trace[1553769786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14905; }","duration":"171.272198ms","start":"2026-09-08T02:38:09.501459Z","end":"2026-09-08T02:38:09.672731Z","steps":["trace[1553769786] 'range keys from in-memory index tree' (duration: 171.060141ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:09.672776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.016278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/placement-ks-endpoints\" ","response":"range_response_count:1 size:12752"} {"level":"info","ts":"2026-09-08T02:38:09.673195Z","caller":"traceutil/trace.go:171","msg":"trace[1900484759] range","detail":"{range_begin:/registry/jobs/openstack/placement-ks-endpoints; range_end:; response_count:1; response_revision:14905; }","duration":"104.44536ms","start":"2026-09-08T02:38:09.56868Z","end":"2026-09-08T02:38:09.673125Z","steps":["trace[1900484759] 'range keys from in-memory index tree' (duration: 103.803891ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:10.401521Z","caller":"traceutil/trace.go:171","msg":"trace[1571166727] linearizableReadLoop","detail":"{readStateIndex:15431; appliedIndex:15430; }","duration":"434.314816ms","start":"2026-09-08T02:38:09.96718Z","end":"2026-09-08T02:38:10.401494Z","steps":["trace[1571166727] 'read index received' (duration: 434.050818ms)","trace[1571166727] 'applied index is now lower than readState.Index' (duration: 263.098µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:38:10.401591Z","caller":"traceutil/trace.go:171","msg":"trace[665586735] transaction","detail":"{read_only:false; response_revision:14906; number_of_response:1; }","duration":"720.082227ms","start":"2026-09-08T02:38:09.681473Z","end":"2026-09-08T02:38:10.401555Z","steps":["trace[665586735] 'process raft request' (duration: 719.799718ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:10.401717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.520243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T02:38:10.401767Z","caller":"traceutil/trace.go:171","msg":"trace[649343866] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:14906; }","duration":"434.631557ms","start":"2026-09-08T02:38:09.967122Z","end":"2026-09-08T02:38:10.401753Z","steps":["trace[649343866] 'agreement among raft nodes before linearized reading' (duration: 434.478482ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:10.401801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:09.967097Z","time spent":"434.694408ms","remote":"127.0.0.1:40678","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/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-09-08T02:38:10.401837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:09.681447Z","time spent":"720.275543ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T02:38:11.330162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.668075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-09-08T02:38:11.3302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.397399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-08T02:38:11.3302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.214329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T02:38:11.33024Z","caller":"traceutil/trace.go:171","msg":"trace[395754014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14906; }","duration":"367.85012ms","start":"2026-09-08T02:38:10.962372Z","end":"2026-09-08T02:38:11.330222Z","steps":["trace[395754014] 'range keys from in-memory index tree' (duration: 367.49383ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:11.330271Z","caller":"traceutil/trace.go:171","msg":"trace[115456154] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:14906; }","duration":"295.625125ms","start":"2026-09-08T02:38:11.034628Z","end":"2026-09-08T02:38:11.330254Z","steps":["trace[115456154] 'count revisions from in-memory index tree' (duration: 295.246184ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:11.330275Z","caller":"traceutil/trace.go:171","msg":"trace[874609122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14906; }","duration":"826.338223ms","start":"2026-09-08T02:38:10.503921Z","end":"2026-09-08T02:38:11.330259Z","steps":["trace[874609122] 'range keys from in-memory index tree' (duration: 825.819807ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:11.330278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:10.962353Z","time spent":"367.918422ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-08T02:38:11.330305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:10.5039Z","time spent":"826.397005ms","remote":"127.0.0.1:41280","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-09-08T02:38:11.425317Z","caller":"traceutil/trace.go:171","msg":"trace[1214534681] transaction","detail":"{read_only:false; response_revision:14907; number_of_response:1; }","duration":"383.060041ms","start":"2026-09-08T02:38:11.04223Z","end":"2026-09-08T02:38:11.42529Z","steps":["trace[1214534681] 'process raft request' (duration: 382.840604ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:11.425587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:11.042203Z","time spent":"383.204425ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T02:38:12.150947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.084423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T02:38:12.151126Z","caller":"traceutil/trace.go:171","msg":"trace[2143259153] transaction","detail":"{read_only:false; response_revision:14908; number_of_response:1; }","duration":"1.034309436s","start":"2026-09-08T02:38:11.116791Z","end":"2026-09-08T02:38:12.1511Z","steps":["trace[2143259153] 'process raft request' (duration: 615.993726ms)","trace[2143259153] 'compare' (duration: 417.97229ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:38:12.151174Z","caller":"traceutil/trace.go:171","msg":"trace[256948884] transaction","detail":"{read_only:false; response_revision:14909; number_of_response:1; }","duration":"1.034270635s","start":"2026-09-08T02:38:11.116872Z","end":"2026-09-08T02:38:12.151143Z","steps":["trace[256948884] 'process raft request' (duration: 1.034153131s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:12.151215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:11.11676Z","time spent":"1.03441344s","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T02:38:12.151341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:11.116832Z","time spent":"1.03441928s","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T02:38:12.151451Z","caller":"traceutil/trace.go:171","msg":"trace[924256687] transaction","detail":"{read_only:false; response_revision:14910; number_of_response:1; }","duration":"816.763316ms","start":"2026-09-08T02:38:11.334631Z","end":"2026-09-08T02:38:12.151394Z","steps":["trace[924256687] 'process raft request' (duration: 816.428946ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:12.151603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:11.33461Z","time spent":"816.910312ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T02:38:12.15162Z","caller":"traceutil/trace.go:171","msg":"trace[1837265097] linearizableReadLoop","detail":"{readStateIndex:15434; appliedIndex:15431; }","duration":"1.0234037s","start":"2026-09-08T02:38:11.128178Z","end":"2026-09-08T02:38:12.151582Z","steps":["trace[1837265097] 'read index received' (duration: 296.948927ms)","trace[1837265097] 'applied index is now lower than readState.Index' (duration: 726.446733ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:38:12.685344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.225667ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-09-08T02:38:12.685573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.557357318s","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-09-08T02:38:12.685705Z","caller":"traceutil/trace.go:171","msg":"trace[1544770348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14910; }","duration":"1.557506752s","start":"2026-09-08T02:38:11.12816Z","end":"2026-09-08T02:38:12.685667Z","steps":["trace[1544770348] 'agreement among raft nodes before linearized reading' (duration: 1.023565924s)","trace[1544770348] 'range keys from in-memory index tree' (duration: 533.584317ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:38:12.685772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:11.128096Z","time spent":"1.557661217s","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-08T02:38:12.686162Z","caller":"traceutil/trace.go:171","msg":"trace[1611388385] linearizableReadLoop","detail":"{readStateIndex:15437; appliedIndex:15435; }","duration":"534.460564ms","start":"2026-09-08T02:38:12.15169Z","end":"2026-09-08T02:38:12.686151Z","steps":["trace[1611388385] 'read index received' (duration: 113.549823ms)","trace[1611388385] 'applied index is now lower than readState.Index' (duration: 420.909181ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:38:12.686225Z","caller":"traceutil/trace.go:171","msg":"trace[107903236] transaction","detail":"{read_only:false; response_revision:14911; number_of_response:1; }","duration":"820.454031ms","start":"2026-09-08T02:38:11.865745Z","end":"2026-09-08T02:38:12.686199Z","steps":["trace[107903236] 'process raft request' (duration: 819.806892ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:12.68639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:11.865716Z","time spent":"820.586606ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T02:38:13.168488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.031324259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-rqlw6\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-09-08T02:38:13.168625Z","caller":"traceutil/trace.go:171","msg":"trace[796206398] range","detail":"{range_begin:/registry/pods/openstack/keepalived-rqlw6; range_end:; response_count:1; response_revision:14911; }","duration":"2.031495395s","start":"2026-09-08T02:38:11.137081Z","end":"2026-09-08T02:38:13.168576Z","steps":["trace[796206398] 'agreement among raft nodes before linearized reading' (duration: 1.549106523s)","trace[796206398] 'range keys from in-memory index tree' (duration: 482.092333ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:38:13.168672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091785544s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T02:38:13.168769Z","caller":"traceutil/trace.go:171","msg":"trace[2051975381] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:14911; }","duration":"1.091952559s","start":"2026-09-08T02:38:12.07679Z","end":"2026-09-08T02:38:13.168742Z","steps":["trace[2051975381] 'agreement among raft nodes before linearized reading' (duration: 609.416712ms)","trace[2051975381] 'count revisions from in-memory index tree' (duration: 482.395462ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:38:13.168806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:12.076768Z","time spent":"1.092029461s","remote":"127.0.0.1:41516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-09-08T02:38:13.168695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:11.137058Z","time spent":"2.031614068s","remote":"127.0.0.1:41472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-rqlw6\" "} {"level":"warn","ts":"2026-09-08T02:38:13.168996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.951956834s","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-09-08T02:38:13.169039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.489615171s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/placement-ks-endpoints\" ","response":"range_response_count:1 size:12752"} {"level":"warn","ts":"2026-09-08T02:38:13.169228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.952201661s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-08T02:38:13.169118Z","caller":"traceutil/trace.go:171","msg":"trace[1104555976] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14911; }","duration":"1.952137889s","start":"2026-09-08T02:38:11.216949Z","end":"2026-09-08T02:38:13.169086Z","steps":["trace[1104555976] 'agreement among raft nodes before linearized reading' (duration: 1.469254092s)","trace[1104555976] 'range keys from in-memory index tree' (duration: 482.572008ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:38:13.169228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.836923696s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T02:38:13.169224Z","caller":"traceutil/trace.go:171","msg":"trace[746616391] range","detail":"{range_begin:/registry/jobs/openstack/placement-ks-endpoints; range_end:; response_count:1; response_revision:14911; }","duration":"1.489849898s","start":"2026-09-08T02:38:11.679323Z","end":"2026-09-08T02:38:13.169173Z","steps":["trace[746616391] 'agreement among raft nodes before linearized reading' (duration: 1.006896259s)","trace[746616391] 'range keys from in-memory index tree' (duration: 482.581628ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:38:13.169303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:11.21693Z","time spent":"1.952356036s","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-08T02:38:13.169326Z","caller":"traceutil/trace.go:171","msg":"trace[1455559713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14911; }","duration":"1.837036548s","start":"2026-09-08T02:38:11.332258Z","end":"2026-09-08T02:38:13.169294Z","steps":["trace[1455559713] 'agreement among raft nodes before linearized reading' (duration: 1.353903674s)","trace[1455559713] 'range keys from in-memory index tree' (duration: 483.00029ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:38:13.169328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:11.679297Z","time spent":"1.490018744s","remote":"127.0.0.1:41530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":12776,"request content":"key:\"/registry/jobs/openstack/placement-ks-endpoints\" "} {"level":"warn","ts":"2026-09-08T02:38:13.169383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:11.332243Z","time spent":"1.837130892s","remote":"127.0.0.1:41294","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-09-08T02:38:13.169395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.952199502s","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-09-08T02:38:13.169271Z","caller":"traceutil/trace.go:171","msg":"trace[1303274280] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14911; }","duration":"1.952251572s","start":"2026-09-08T02:38:11.217009Z","end":"2026-09-08T02:38:13.16926Z","steps":["trace[1303274280] 'agreement among raft nodes before linearized reading' (duration: 1.469155659s)","trace[1303274280] 'range keys from in-memory index tree' (duration: 482.99641ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:38:13.169515Z","caller":"traceutil/trace.go:171","msg":"trace[1828902018] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14911; }","duration":"1.952375557s","start":"2026-09-08T02:38:11.217105Z","end":"2026-09-08T02:38:13.16948Z","steps":["trace[1828902018] 'agreement among raft nodes before linearized reading' (duration: 1.469083507s)","trace[1828902018] 'range keys from in-memory index tree' (duration: 483.009931ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:38:13.169556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:11.217083Z","time spent":"1.95246448s","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-08T02:38:13.169581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:11.217003Z","time spent":"1.952547282s","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-08T02:38:13.339132Z","caller":"traceutil/trace.go:171","msg":"trace[443404634] transaction","detail":"{read_only:false; response_revision:14912; number_of_response:1; }","duration":"945.475637ms","start":"2026-09-08T02:38:12.393627Z","end":"2026-09-08T02:38:13.339103Z","steps":["trace[443404634] 'process raft request' (duration: 945.284291ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:13.339305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:12.393601Z","time spent":"945.613842ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T02:38:15.179259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999728882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-09-08T02:38:15.17934Z","caller":"traceutil/trace.go:171","msg":"trace[568249634] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999862666s","start":"2026-09-08T02:38:13.179458Z","end":"2026-09-08T02:38:15.179321Z","steps":["trace[568249634] 'agreement among raft nodes before linearized reading' (duration: 1.999759653s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:15.179379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:13.179442Z","time spent":"1.999931528s","remote":"127.0.0.1:41280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/09/08 02:38:15 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-09-08T02:38:15.628149Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.289196388s","expected-duration":"1s"} {"level":"info","ts":"2026-09-08T02:38:15.628769Z","caller":"traceutil/trace.go:171","msg":"trace[600682216] linearizableReadLoop","detail":"{readStateIndex:15440; appliedIndex:15437; }","duration":"2.704615799s","start":"2026-09-08T02:38:12.924122Z","end":"2026-09-08T02:38:15.628737Z","steps":["trace[600682216] 'read index received' (duration: 414.874095ms)","trace[600682216] 'applied index is now lower than readState.Index' (duration: 2.289740744s)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:38:15.628889Z","caller":"traceutil/trace.go:171","msg":"trace[2028015767] transaction","detail":"{read_only:false; response_revision:14916; number_of_response:1; }","duration":"2.452598832s","start":"2026-09-08T02:38:13.17628Z","end":"2026-09-08T02:38:15.628878Z","steps":["trace[2028015767] 'process raft request' (duration: 2.452357865s)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:15.628938Z","caller":"traceutil/trace.go:171","msg":"trace[434331878] transaction","detail":"{read_only:false; response_revision:14915; number_of_response:1; }","duration":"2.453298793s","start":"2026-09-08T02:38:13.175583Z","end":"2026-09-08T02:38:15.628881Z","steps":["trace[434331878] 'process raft request' (duration: 2.453021385s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:15.629002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:13.176251Z","time spent":"2.452663934s","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T02:38:15.629068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:13.175558Z","time spent":"2.453460238s","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T02:38:15.629218Z","caller":"traceutil/trace.go:171","msg":"trace[376479882] transaction","detail":"{read_only:false; response_revision:14913; number_of_response:1; }","duration":"3.222220019s","start":"2026-09-08T02:38:12.40693Z","end":"2026-09-08T02:38:15.62915Z","steps":["trace[376479882] 'process raft request' (duration: 3.221427055s)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:15.629247Z","caller":"traceutil/trace.go:171","msg":"trace[1415159482] transaction","detail":"{read_only:false; response_revision:14914; number_of_response:1; }","duration":"2.933583441s","start":"2026-09-08T02:38:12.695651Z","end":"2026-09-08T02:38:15.629234Z","steps":["trace[1415159482] 'process raft request' (duration: 2.93291002s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:15.62937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.705244327s","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-09-08T02:38:15.629436Z","caller":"traceutil/trace.go:171","msg":"trace[467170792] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:14918; }","duration":"2.705339662s","start":"2026-09-08T02:38:12.924067Z","end":"2026-09-08T02:38:15.629407Z","steps":["trace[467170792] 'agreement among raft nodes before linearized reading' (duration: 2.705244358s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:15.629466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:12.924042Z","time spent":"2.705415903s","remote":"127.0.0.1:41602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-09-08T02:38:15.629342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:12.695622Z","time spent":"2.933685114s","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T02:38:15.629781Z","caller":"traceutil/trace.go:171","msg":"trace[1872032313] transaction","detail":"{read_only:false; response_revision:14918; number_of_response:1; }","duration":"2.314638374s","start":"2026-09-08T02:38:13.315085Z","end":"2026-09-08T02:38:15.629723Z","steps":["trace[1872032313] 'process raft request' (duration: 2.313612332s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:15.62993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:12.406878Z","time spent":"3.222433346s","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T02:38:15.629976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:13.315057Z","time spent":"2.314799029s","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T02:38:15.630189Z","caller":"traceutil/trace.go:171","msg":"trace[1838942056] transaction","detail":"{read_only:false; response_revision:14917; number_of_response:1; }","duration":"2.452491719s","start":"2026-09-08T02:38:13.177686Z","end":"2026-09-08T02:38:15.630177Z","steps":["trace[1838942056] 'process raft request' (duration: 2.450984492s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:15.63026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:13.177674Z","time spent":"2.45255111s","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T02:38:16.129927Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2531480931887129704,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-08T02:38:16.355387Z","caller":"traceutil/trace.go:171","msg":"trace[375007145] transaction","detail":"{read_only:false; response_revision:14920; number_of_response:1; }","duration":"2.509590264s","start":"2026-09-08T02:38:13.845764Z","end":"2026-09-08T02:38:16.355354Z","steps":["trace[375007145] 'process raft request' (duration: 2.509480711s)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:16.355408Z","caller":"traceutil/trace.go:171","msg":"trace[1253094405] transaction","detail":"{read_only:false; response_revision:14919; number_of_response:1; }","duration":"2.924165479s","start":"2026-09-08T02:38:13.431196Z","end":"2026-09-08T02:38:16.355362Z","steps":["trace[1253094405] 'process raft request' (duration: 2.92384906s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:16.355588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:13.845747Z","time spent":"2.509752248s","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T02:38:16.355613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:13.43117Z","time spent":"2.924336764s","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T02:38:16.355651Z","caller":"traceutil/trace.go:171","msg":"trace[1373675692] transaction","detail":"{read_only:false; response_revision:14921; number_of_response:1; }","duration":"2.198029205s","start":"2026-09-08T02:38:14.157595Z","end":"2026-09-08T02:38:16.355624Z","steps":["trace[1373675692] 'process raft request' (duration: 2.197702625s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:16.35579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:14.157579Z","time spent":"2.198130909s","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T02:38:16.355846Z","caller":"traceutil/trace.go:171","msg":"trace[1110943307] transaction","detail":"{read_only:false; response_revision:14922; number_of_response:1; }","duration":"2.1968701s","start":"2026-09-08T02:38:14.158937Z","end":"2026-09-08T02:38:16.355808Z","steps":["trace[1110943307] 'process raft request' (duration: 2.196633523s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:16.35603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.185119826s","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:7"} {"level":"info","ts":"2026-09-08T02:38:16.356126Z","caller":"traceutil/trace.go:171","msg":"trace[921224336] transaction","detail":"{read_only:false; response_revision:14923; number_of_response:1; }","duration":"1.302240515s","start":"2026-09-08T02:38:15.053873Z","end":"2026-09-08T02:38:16.356113Z","steps":["trace[921224336] 'process raft request' (duration: 1.301878263s)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:16.356134Z","caller":"traceutil/trace.go:171","msg":"trace[979827070] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:14923; }","duration":"2.185269521s","start":"2026-09-08T02:38:14.170842Z","end":"2026-09-08T02:38:16.356111Z","steps":["trace[979827070] 'agreement among raft nodes before linearized reading' (duration: 2.185054063s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:16.356145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.707884379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-09-08T02:38:16.35614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.031963035s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T02:38:16.356187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:15.053849Z","time spent":"1.302308886s","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T02:38:16.356188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.199493451s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-09-08T02:38:16.356199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:14.170831Z","time spent":"2.185359644s","remote":"127.0.0.1:36844","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":"info","ts":"2026-09-08T02:38:16.356206Z","caller":"traceutil/trace.go:171","msg":"trace[1156966297] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14923; }","duration":"2.707983761s","start":"2026-09-08T02:38:13.648206Z","end":"2026-09-08T02:38:16.35619Z","steps":["trace[1156966297] 'agreement among raft nodes before linearized reading' (duration: 2.707782954s)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:16.356227Z","caller":"traceutil/trace.go:171","msg":"trace[1072165644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14923; }","duration":"2.199576853s","start":"2026-09-08T02:38:14.156643Z","end":"2026-09-08T02:38:16.356219Z","steps":["trace[1072165644] 'agreement among raft nodes before linearized reading' (duration: 2.19945688s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:16.356248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:14.15662Z","time spent":"2.199623735s","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-08T02:38:16.356235Z","caller":"traceutil/trace.go:171","msg":"trace[627111522] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:14923; }","duration":"3.032086029s","start":"2026-09-08T02:38:13.324123Z","end":"2026-09-08T02:38:16.356209Z","steps":["trace[627111522] 'agreement among raft nodes before linearized reading' (duration: 3.031939314s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:16.356254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:13.648186Z","time spent":"2.708056024s","remote":"127.0.0.1:41472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-08T02:38:16.356061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:14.158906Z","time spent":"2.197032855s","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T02:38:16.356291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:13.324114Z","time spent":"3.032167561s","remote":"127.0.0.1:37548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true "} {"level":"warn","ts":"2026-09-08T02:38:16.356299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.074195505s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T02:38:16.356076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.564793783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-08T02:38:16.35632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178708902s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/placement-ks-endpoints\" ","response":"range_response_count:1 size:12752"} {"level":"warn","ts":"2026-09-08T02:38:16.356324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.510373649s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T02:38:16.356401Z","caller":"traceutil/trace.go:171","msg":"trace[974245287] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:14923; }","duration":"2.565106632s","start":"2026-09-08T02:38:13.791238Z","end":"2026-09-08T02:38:16.356344Z","steps":["trace[974245287] 'agreement among raft nodes before linearized reading' (duration: 2.564660508s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:16.356496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:13.791215Z","time spent":"2.565258516s","remote":"127.0.0.1:41488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":156,"response size":32,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-09-08T02:38:16.356355Z","caller":"traceutil/trace.go:171","msg":"trace[58851230] range","detail":"{range_begin:/registry/jobs/openstack/placement-ks-endpoints; range_end:; response_count:1; response_revision:14923; }","duration":"1.178791425s","start":"2026-09-08T02:38:15.177554Z","end":"2026-09-08T02:38:16.356346Z","steps":["trace[58851230] 'agreement among raft nodes before linearized reading' (duration: 1.178598409s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:16.356582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:15.177527Z","time spent":"1.179046553s","remote":"127.0.0.1:41530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":12776,"request content":"key:\"/registry/jobs/openstack/placement-ks-endpoints\" "} {"level":"info","ts":"2026-09-08T02:38:16.356403Z","caller":"traceutil/trace.go:171","msg":"trace[845407650] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:14923; }","duration":"2.510469791s","start":"2026-09-08T02:38:13.845912Z","end":"2026-09-08T02:38:16.356381Z","steps":["trace[845407650] 'agreement among raft nodes before linearized reading' (duration: 2.510238534s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:16.356677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:13.845905Z","time spent":"2.510749499s","remote":"127.0.0.1:41664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":112,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-09-08T02:38:16.355885Z","caller":"traceutil/trace.go:171","msg":"trace[20480370] linearizableReadLoop","detail":"{readStateIndex:15449; appliedIndex:15444; }","duration":"727.024289ms","start":"2026-09-08T02:38:15.628839Z","end":"2026-09-08T02:38:16.355863Z","steps":["trace[20480370] 'read index received' (duration: 726.169524ms)","trace[20480370] 'applied index is now lower than readState.Index' (duration: 853.425µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:38:16.356343Z","caller":"traceutil/trace.go:171","msg":"trace[1796124316] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:14923; }","duration":"2.074302629s","start":"2026-09-08T02:38:14.282027Z","end":"2026-09-08T02:38:16.356329Z","steps":["trace[1796124316] 'agreement among raft nodes before linearized reading' (duration: 2.074233196s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:16.356817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.180497879s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:62 size:582289"} {"level":"warn","ts":"2026-09-08T02:38:16.356865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:14.281999Z","time spent":"2.074791094s","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true "} {"level":"warn","ts":"2026-09-08T02:38:16.356936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.235933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-08T02:38:16.356123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176438313s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T02:38:16.356988Z","caller":"traceutil/trace.go:171","msg":"trace[254284729] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:14923; }","duration":"541.339416ms","start":"2026-09-08T02:38:15.815637Z","end":"2026-09-08T02:38:16.356976Z","steps":["trace[254284729] 'agreement among raft nodes before linearized reading' (duration: 541.196072ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:16.356906Z","caller":"traceutil/trace.go:171","msg":"trace[910932985] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:62; response_revision:14923; }","duration":"3.180633434s","start":"2026-09-08T02:38:13.176261Z","end":"2026-09-08T02:38:16.356894Z","steps":["trace[910932985] 'agreement among raft nodes before linearized reading' (duration: 3.179665263s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:16.356999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.473652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.191\" ","response":"range_response_count:1 size:138"} {"level":"warn","ts":"2026-09-08T02:38:16.357051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:13.176219Z","time spent":"3.180813999s","remote":"127.0.0.1:41472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":62,"response size":582313,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-09-08T02:38:16.357076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:15.815611Z","time spent":"541.412888ms","remote":"127.0.0.1:41480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":64,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-09-08T02:38:16.357041Z","caller":"traceutil/trace.go:171","msg":"trace[1986014876] range","detail":"{range_begin:/registry/masterleases/162.253.55.191; range_end:; response_count:1; response_revision:14923; }","duration":"338.555494ms","start":"2026-09-08T02:38:16.018478Z","end":"2026-09-08T02:38:16.357033Z","steps":["trace[1986014876] 'agreement among raft nodes before linearized reading' (duration: 338.389149ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:16.357129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:16.018456Z","time spent":"338.666988ms","remote":"127.0.0.1:41334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/162.253.55.191\" "} {"level":"warn","ts":"2026-09-08T02:38:16.359035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.625706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-08T02:38:16.359066Z","caller":"traceutil/trace.go:171","msg":"trace[784443679] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:14923; }","duration":"124.707258ms","start":"2026-09-08T02:38:16.23435Z","end":"2026-09-08T02:38:16.359058Z","steps":["trace[784443679] 'agreement among raft nodes before linearized reading' (duration: 122.52878ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:16.359193Z","caller":"traceutil/trace.go:171","msg":"trace[1039726116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14923; }","duration":"1.177389551s","start":"2026-09-08T02:38:15.179593Z","end":"2026-09-08T02:38:16.356982Z","steps":["trace[1039726116] 'agreement among raft nodes before linearized reading' (duration: 1.176423591s)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:16.359247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:15.179572Z","time spent":"1.179663742s","remote":"127.0.0.1:41294","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-09-08T02:38:16.791855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.137868ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-09-08T02:38:16.792115Z","caller":"traceutil/trace.go:171","msg":"trace[1304988490] transaction","detail":"{read_only:false; response_revision:14925; number_of_response:1; }","duration":"427.950778ms","start":"2026-09-08T02:38:16.364149Z","end":"2026-09-08T02:38:16.7921Z","steps":["trace[1304988490] 'process raft request' (duration: 427.906927ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:16.792184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:16.364135Z","time spent":"428.00482ms","remote":"127.0.0.1:41378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-08T02:38:16.792239Z","caller":"traceutil/trace.go:171","msg":"trace[1987103812] linearizableReadLoop","detail":"{readStateIndex:15450; appliedIndex:15449; }","duration":"431.396165ms","start":"2026-09-08T02:38:16.360813Z","end":"2026-09-08T02:38:16.792209Z","steps":["trace[1987103812] 'read index received' (duration: 115.876035ms)","trace[1987103812] 'applied index is now lower than readState.Index' (duration: 315.516619ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:38:16.791963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:16.359741Z","time spent":"432.21483ms","remote":"127.0.0.1:41334","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-08T02:38:16.7925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.675743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-08T02:38:16.792577Z","caller":"traceutil/trace.go:171","msg":"trace[1913736315] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14925; }","duration":"431.784566ms","start":"2026-09-08T02:38:16.360779Z","end":"2026-09-08T02:38:16.792564Z","steps":["trace[1913736315] 'agreement among raft nodes before linearized reading' (duration: 431.542299ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:16.792614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.307726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T02:38:16.792636Z","caller":"traceutil/trace.go:171","msg":"trace[758071434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14925; }","duration":"425.374307ms","start":"2026-09-08T02:38:16.367256Z","end":"2026-09-08T02:38:16.79263Z","steps":["trace[758071434] 'agreement among raft nodes before linearized reading' (duration: 425.335946ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:16.792651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:16.367236Z","time spent":"425.41146ms","remote":"127.0.0.1:41280","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-09-08T02:38:16.79269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:16.360762Z","time spent":"431.860858ms","remote":"127.0.0.1:41472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-08T02:38:16.792498Z","caller":"traceutil/trace.go:171","msg":"trace[1181298505] transaction","detail":"{read_only:false; response_revision:14924; number_of_response:1; }","duration":"430.57291ms","start":"2026-09-08T02:38:16.36159Z","end":"2026-09-08T02:38:16.792163Z","steps":["trace[1181298505] 'process raft request' (duration: 430.353464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:16.793071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.335457ms","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:5"} {"level":"info","ts":"2026-09-08T02:38:16.79315Z","caller":"traceutil/trace.go:171","msg":"trace[1032947383] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:14925; }","duration":"146.480101ms","start":"2026-09-08T02:38:16.646648Z","end":"2026-09-08T02:38:16.793128Z","steps":["trace[1032947383] 'agreement among raft nodes before linearized reading' (duration: 146.058528ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:16.793079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:16.361574Z","time spent":"431.291051ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T02:38:16.792781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.738775ms","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:5"} {"level":"info","ts":"2026-09-08T02:38:16.793766Z","caller":"traceutil/trace.go:171","msg":"trace[1876977989] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:14925; }","duration":"208.758598ms","start":"2026-09-08T02:38:16.584985Z","end":"2026-09-08T02:38:16.793743Z","steps":["trace[1876977989] 'agreement among raft nodes before linearized reading' (duration: 207.763597ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:17.074339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.104858ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T02:38:17.074492Z","caller":"traceutil/trace.go:171","msg":"trace[883871774] linearizableReadLoop","detail":"{readStateIndex:15453; appliedIndex:15452; }","duration":"278.6309ms","start":"2026-09-08T02:38:16.795838Z","end":"2026-09-08T02:38:17.074469Z","steps":["trace[883871774] 'read index received' (duration: 123.270574ms)","trace[883871774] 'applied index is now lower than readState.Index' (duration: 155.358536ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:38:17.074568Z","caller":"traceutil/trace.go:171","msg":"trace[1550871918] transaction","detail":"{read_only:false; response_revision:14926; number_of_response:1; }","duration":"280.429546ms","start":"2026-09-08T02:38:16.79408Z","end":"2026-09-08T02:38:17.07451Z","steps":["trace[1550871918] 'process raft request' (duration: 125.06021ms)","trace[1550871918] 'compare' (duration: 154.967843ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:38:17.074734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.888368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-aad4e6e7-b3de-45ac-8f8a-cd5dd2665794\" ","response":"range_response_count:1 size:1303"} {"level":"warn","ts":"2026-09-08T02:38:17.074723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.043099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T02:38:17.074833Z","caller":"traceutil/trace.go:171","msg":"trace[502547897] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14926; }","duration":"211.208274ms","start":"2026-09-08T02:38:16.8636Z","end":"2026-09-08T02:38:17.074808Z","steps":["trace[502547897] 'agreement among raft nodes before linearized reading' (duration: 211.008908ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:17.074974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.598919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-09-08T02:38:17.075028Z","caller":"traceutil/trace.go:171","msg":"trace[932225448] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:14926; }","duration":"171.655721ms","start":"2026-09-08T02:38:16.90336Z","end":"2026-09-08T02:38:17.075016Z","steps":["trace[932225448] 'agreement among raft nodes before linearized reading' (duration: 171.461885ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:17.074775Z","caller":"traceutil/trace.go:171","msg":"trace[976148223] range","detail":"{range_begin:/registry/persistentvolumes/pvc-aad4e6e7-b3de-45ac-8f8a-cd5dd2665794; range_end:; response_count:1; response_revision:14926; }","duration":"278.94928ms","start":"2026-09-08T02:38:16.795813Z","end":"2026-09-08T02:38:17.074762Z","steps":["trace[976148223] 'agreement among raft nodes before linearized reading' (duration: 278.776695ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:17.438091Z","caller":"traceutil/trace.go:171","msg":"trace[1248895625] transaction","detail":"{read_only:false; response_revision:14927; number_of_response:1; }","duration":"323.26612ms","start":"2026-09-08T02:38:17.114798Z","end":"2026-09-08T02:38:17.438065Z","steps":["trace[1248895625] 'process raft request' (duration: 323.065624ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:38:17.438265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:38:17.114775Z","time spent":"323.392524ms","remote":"127.0.0.1:41472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15796,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T02:38:17.815742Z","caller":"traceutil/trace.go:171","msg":"trace[323736524] transaction","detail":"{read_only:false; response_revision:14929; number_of_response:1; }","duration":"176.16975ms","start":"2026-09-08T02:38:17.639531Z","end":"2026-09-08T02:38:17.815701Z","steps":["trace[323736524] 'process raft request' (duration: 157.634687ms)","trace[323736524] 'compare' (duration: 18.132221ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:38:17.815904Z","caller":"traceutil/trace.go:171","msg":"trace[2017469111] transaction","detail":"{read_only:false; response_revision:14932; number_of_response:1; }","duration":"175.8742ms","start":"2026-09-08T02:38:17.639989Z","end":"2026-09-08T02:38:17.815863Z","steps":["trace[2017469111] 'process raft request' (duration: 175.813039ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:17.816213Z","caller":"traceutil/trace.go:171","msg":"trace[165519171] transaction","detail":"{read_only:false; response_revision:14930; number_of_response:1; }","duration":"176.557492ms","start":"2026-09-08T02:38:17.63962Z","end":"2026-09-08T02:38:17.816178Z","steps":["trace[165519171] 'process raft request' (duration: 175.978724ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:17.816203Z","caller":"traceutil/trace.go:171","msg":"trace[1786789006] transaction","detail":"{read_only:false; response_revision:14931; number_of_response:1; }","duration":"176.511831ms","start":"2026-09-08T02:38:17.639666Z","end":"2026-09-08T02:38:17.816178Z","steps":["trace[1786789006] 'process raft request' (duration: 176.090048ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:38:18.228684Z","caller":"traceutil/trace.go:171","msg":"trace[689106575] transaction","detail":"{read_only:false; response_revision:14933; number_of_response:1; }","duration":"101.609903ms","start":"2026-09-08T02:38:18.127055Z","end":"2026-09-08T02:38:18.228665Z","steps":["trace[689106575] 'process raft request' (duration: 101.48201ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:39:11.240946Z","caller":"traceutil/trace.go:171","msg":"trace[6678207] linearizableReadLoop","detail":"{readStateIndex:15809; appliedIndex:15808; }","duration":"140.221739ms","start":"2026-09-08T02:39:11.100695Z","end":"2026-09-08T02:39:11.240917Z","steps":["trace[6678207] 'read index received' (duration: 140.011253ms)","trace[6678207] 'applied index is now lower than readState.Index' (duration: 209.436µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:39:11.241068Z","caller":"traceutil/trace.go:171","msg":"trace[1142406622] transaction","detail":"{read_only:false; response_revision:15267; number_of_response:1; }","duration":"177.895246ms","start":"2026-09-08T02:39:11.063109Z","end":"2026-09-08T02:39:11.241004Z","steps":["trace[1142406622] 'process raft request' (duration: 177.600597ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:39:11.241167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.438025ms","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:5"} {"level":"info","ts":"2026-09-08T02:39:11.24129Z","caller":"traceutil/trace.go:171","msg":"trace[1602088081] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:15267; }","duration":"140.625811ms","start":"2026-09-08T02:39:11.100634Z","end":"2026-09-08T02:39:11.24126Z","steps":["trace[1602088081] 'agreement among raft nodes before linearized reading' (duration: 140.405755ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:39:19.970208Z","caller":"traceutil/trace.go:171","msg":"trace[158875035] transaction","detail":"{read_only:false; response_revision:15332; number_of_response:1; }","duration":"101.85329ms","start":"2026-09-08T02:39:19.868331Z","end":"2026-09-08T02:39:19.970185Z","steps":["trace[158875035] 'process raft request' (duration: 101.775848ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:39:19.970209Z","caller":"traceutil/trace.go:171","msg":"trace[568639901] transaction","detail":"{read_only:false; response_revision:15331; number_of_response:1; }","duration":"101.877281ms","start":"2026-09-08T02:39:19.868296Z","end":"2026-09-08T02:39:19.970173Z","steps":["trace[568639901] 'process raft request' (duration: 92.035621ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:39:22.063693Z","caller":"traceutil/trace.go:171","msg":"trace[1258175739] transaction","detail":"{read_only:false; response_revision:15441; number_of_response:1; }","duration":"126.745048ms","start":"2026-09-08T02:39:21.936882Z","end":"2026-09-08T02:39:22.063627Z","steps":["trace[1258175739] 'process raft request' (duration: 126.330446ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:39:22.51377Z","caller":"traceutil/trace.go:171","msg":"trace[466097603] transaction","detail":"{read_only:false; response_revision:15443; number_of_response:1; }","duration":"438.612233ms","start":"2026-09-08T02:39:22.075116Z","end":"2026-09-08T02:39:22.513728Z","steps":["trace[466097603] 'process raft request' (duration: 438.075167ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:39:22.513401Z","caller":"traceutil/trace.go:171","msg":"trace[963009112] linearizableReadLoop","detail":"{readStateIndex:15989; appliedIndex:15988; }","duration":"322.761867ms","start":"2026-09-08T02:39:22.19061Z","end":"2026-09-08T02:39:22.513372Z","steps":["trace[963009112] 'read index received' (duration: 322.490998ms)","trace[963009112] 'applied index is now lower than readState.Index' (duration: 269.719µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:39:22.514118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:39:22.075082Z","time spent":"438.896361ms","remote":"127.0.0.1:41472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3712,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T02:39:22.514323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.457671ms","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-09-08T02:39:22.514379Z","caller":"traceutil/trace.go:171","msg":"trace[1415137192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15443; }","duration":"286.580024ms","start":"2026-09-08T02:39:22.227784Z","end":"2026-09-08T02:39:22.514364Z","steps":["trace[1415137192] 'agreement among raft nodes before linearized reading' (duration: 286.332417ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:39:22.514405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.353734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-4hqm2.18d33819e21642ad\" ","response":"range_response_count:1 size:783"} {"level":"warn","ts":"2026-09-08T02:39:22.514428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.1071ms","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-09-08T02:39:22.514511Z","caller":"traceutil/trace.go:171","msg":"trace[1648827251] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-4hqm2.18d33819e21642ad; range_end:; response_count:1; response_revision:15443; }","duration":"167.51463ms","start":"2026-09-08T02:39:22.346973Z","end":"2026-09-08T02:39:22.514488Z","steps":["trace[1648827251] 'agreement among raft nodes before linearized reading' (duration: 167.257152ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:39:22.514525Z","caller":"traceutil/trace.go:171","msg":"trace[1882457879] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15443; }","duration":"286.211993ms","start":"2026-09-08T02:39:22.228288Z","end":"2026-09-08T02:39:22.5145Z","steps":["trace[1882457879] 'agreement among raft nodes before linearized reading' (duration: 286.053508ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:39:22.514789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.952106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-08T02:39:22.514825Z","caller":"traceutil/trace.go:171","msg":"trace[1161857696] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15443; }","duration":"287.031078ms","start":"2026-09-08T02:39:22.227784Z","end":"2026-09-08T02:39:22.514815Z","steps":["trace[1161857696] 'agreement among raft nodes before linearized reading' (duration: 286.933385ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:39:22.516572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.612232ms","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-09-08T02:39:22.516652Z","caller":"traceutil/trace.go:171","msg":"trace[354243082] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15443; }","duration":"326.039456ms","start":"2026-09-08T02:39:22.190557Z","end":"2026-09-08T02:39:22.516596Z","steps":["trace[354243082] 'agreement among raft nodes before linearized reading' (duration: 323.464498ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:39:22.516695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:39:22.190535Z","time spent":"326.1369ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-08T02:40:53.895086Z","caller":"traceutil/trace.go:171","msg":"trace[1637545123] transaction","detail":"{read_only:false; response_revision:16486; number_of_response:1; }","duration":"148.060478ms","start":"2026-09-08T02:40:53.747001Z","end":"2026-09-08T02:40:53.895061Z","steps":["trace[1637545123] 'process raft request' (duration: 147.927384ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:42:02.656362Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14506} {"level":"info","ts":"2026-09-08T02:42:02.740257Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14506,"took":"80.385152ms","hash":4091327350,"current-db-size-bytes":55300096,"current-db-size":"55 MB","current-db-size-in-use-bytes":30691328,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-08T02:42:02.740571Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4091327350,"revision":14506,"compact-revision":12474} {"level":"info","ts":"2026-09-08T02:44:28.365097Z","caller":"traceutil/trace.go:171","msg":"trace[1690216396] transaction","detail":"{read_only:false; response_revision:18124; number_of_response:1; }","duration":"114.863295ms","start":"2026-09-08T02:44:28.250198Z","end":"2026-09-08T02:44:28.365061Z","steps":["trace[1690216396] 'process raft request' (duration: 114.641959ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:44:54.943509Z","caller":"traceutil/trace.go:171","msg":"trace[160418387] transaction","detail":"{read_only:false; response_revision:18257; number_of_response:1; }","duration":"187.416699ms","start":"2026-09-08T02:44:54.756066Z","end":"2026-09-08T02:44:54.943483Z","steps":["trace[160418387] 'process raft request' (duration: 187.130251ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:45:32.768953Z","caller":"traceutil/trace.go:171","msg":"trace[2027041730] linearizableReadLoop","detail":"{readStateIndex:19148; appliedIndex:19147; }","duration":"117.494261ms","start":"2026-09-08T02:45:32.651424Z","end":"2026-09-08T02:45:32.768918Z","steps":["trace[2027041730] 'read index received' (duration: 117.359997ms)","trace[2027041730] 'applied index is now lower than readState.Index' (duration: 132.984µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:45:32.769023Z","caller":"traceutil/trace.go:171","msg":"trace[1557887720] transaction","detail":"{read_only:false; response_revision:18502; number_of_response:1; }","duration":"118.715419ms","start":"2026-09-08T02:45:32.650261Z","end":"2026-09-08T02:45:32.768977Z","steps":["trace[1557887720] 'process raft request' (duration: 118.4236ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:45:32.769265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.819211ms","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-09-08T02:45:32.769364Z","caller":"traceutil/trace.go:171","msg":"trace[257135693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18502; }","duration":"117.972036ms","start":"2026-09-08T02:45:32.651374Z","end":"2026-09-08T02:45:32.769346Z","steps":["trace[257135693] 'agreement among raft nodes before linearized reading' (duration: 117.684327ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:45:33.879293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.862792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:93 size:914907"} {"level":"info","ts":"2026-09-08T02:45:33.879371Z","caller":"traceutil/trace.go:171","msg":"trace[1205393764] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:93; response_revision:18508; }","duration":"187.983995ms","start":"2026-09-08T02:45:33.691372Z","end":"2026-09-08T02:45:33.879356Z","steps":["trace[1205393764] 'range keys from in-memory index tree' (duration: 187.225461ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:46:42.073484Z","caller":"traceutil/trace.go:171","msg":"trace[1858740879] transaction","detail":"{read_only:false; response_revision:18951; number_of_response:1; }","duration":"190.310816ms","start":"2026-09-08T02:46:41.883135Z","end":"2026-09-08T02:46:42.073446Z","steps":["trace[1858740879] 'process raft request' (duration: 102.585835ms)","trace[1858740879] 'compare' (duration: 87.445732ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:46:46.746696Z","caller":"traceutil/trace.go:171","msg":"trace[347386897] transaction","detail":"{read_only:false; response_revision:19003; number_of_response:1; }","duration":"243.76713ms","start":"2026-09-08T02:46:46.502871Z","end":"2026-09-08T02:46:46.746638Z","steps":["trace[347386897] 'process raft request' (duration: 214.93371ms)","trace[347386897] 'compare' (duration: 28.319235ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:46:46.746692Z","caller":"traceutil/trace.go:171","msg":"trace[1561801934] linearizableReadLoop","detail":"{readStateIndex:19666; appliedIndex:19665; }","duration":"242.050138ms","start":"2026-09-08T02:46:46.504539Z","end":"2026-09-08T02:46:46.746589Z","steps":["trace[1561801934] 'read index received' (duration: 213.30014ms)","trace[1561801934] 'applied index is now lower than readState.Index' (duration: 28.747618ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:46:46.747134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.538743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:709"} {"level":"info","ts":"2026-09-08T02:46:46.747201Z","caller":"traceutil/trace.go:171","msg":"trace[1856171342] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:19003; }","duration":"242.674578ms","start":"2026-09-08T02:46:46.504505Z","end":"2026-09-08T02:46:46.747179Z","steps":["trace[1856171342] 'agreement among raft nodes before linearized reading' (duration: 242.257494ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:46:46.787355Z","caller":"traceutil/trace.go:171","msg":"trace[1549192910] transaction","detail":"{read_only:false; response_revision:19004; number_of_response:1; }","duration":"282.423441ms","start":"2026-09-08T02:46:46.504905Z","end":"2026-09-08T02:46:46.787328Z","steps":["trace[1549192910] 'process raft request' (duration: 281.750431ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:46:46.788056Z","caller":"traceutil/trace.go:171","msg":"trace[1806678120] transaction","detail":"{read_only:false; response_revision:19005; number_of_response:1; }","duration":"268.694582ms","start":"2026-09-08T02:46:46.519337Z","end":"2026-09-08T02:46:46.788032Z","steps":["trace[1806678120] 'process raft request' (duration: 267.528546ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:46:46.78833Z","caller":"traceutil/trace.go:171","msg":"trace[1660464179] transaction","detail":"{read_only:false; response_revision:19006; number_of_response:1; }","duration":"268.742944ms","start":"2026-09-08T02:46:46.519577Z","end":"2026-09-08T02:46:46.78832Z","steps":["trace[1660464179] 'process raft request' (duration: 267.427763ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:46:46.940777Z","caller":"traceutil/trace.go:171","msg":"trace[53482274] linearizableReadLoop","detail":"{readStateIndex:19670; appliedIndex:19669; }","duration":"137.418119ms","start":"2026-09-08T02:46:46.803335Z","end":"2026-09-08T02:46:46.940753Z","steps":["trace[53482274] 'read index received' (duration: 137.180992ms)","trace[53482274] 'applied index is now lower than readState.Index' (duration: 236.247µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:46:46.940846Z","caller":"traceutil/trace.go:171","msg":"trace[594290063] transaction","detail":"{read_only:false; response_revision:19007; number_of_response:1; }","duration":"141.193376ms","start":"2026-09-08T02:46:46.799605Z","end":"2026-09-08T02:46:46.940798Z","steps":["trace[594290063] 'process raft request' (duration: 140.944428ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:46:46.941107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.75309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/monitoring/\" range_end:\"/registry/resourcequotas/monitoring0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T02:46:46.941186Z","caller":"traceutil/trace.go:171","msg":"trace[43762171] range","detail":"{range_begin:/registry/resourcequotas/monitoring/; range_end:/registry/resourcequotas/monitoring0; response_count:0; response_revision:19007; }","duration":"137.879674ms","start":"2026-09-08T02:46:46.803281Z","end":"2026-09-08T02:46:46.941161Z","steps":["trace[43762171] 'agreement among raft nodes before linearized reading' (duration: 137.642136ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:46:46.941354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.480861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/neutron-server\" ","response":"range_response_count:1 size:11400"} {"level":"info","ts":"2026-09-08T02:46:46.94148Z","caller":"traceutil/trace.go:171","msg":"trace[1012519482] range","detail":"{range_begin:/registry/deployments/openstack/neutron-server; range_end:; response_count:1; response_revision:19007; }","duration":"136.622185ms","start":"2026-09-08T02:46:46.804821Z","end":"2026-09-08T02:46:46.941443Z","steps":["trace[1012519482] 'agreement among raft nodes before linearized reading' (duration: 136.261004ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:46:46.942422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.876022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1818"} {"level":"info","ts":"2026-09-08T02:46:46.94254Z","caller":"traceutil/trace.go:171","msg":"trace[1082442978] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:19007; }","duration":"116.036656ms","start":"2026-09-08T02:46:46.82648Z","end":"2026-09-08T02:46:46.942517Z","steps":["trace[1082442978] 'agreement among raft nodes before linearized reading' (duration: 114.621163ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:46:46.944013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.549997ms","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-09-08T02:46:46.94408Z","caller":"traceutil/trace.go:171","msg":"trace[1696301568] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19007; }","duration":"108.65323ms","start":"2026-09-08T02:46:46.83541Z","end":"2026-09-08T02:46:46.944063Z","steps":["trace[1696301568] 'agreement among raft nodes before linearized reading' (duration: 108.150485ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:46:47.1606Z","caller":"traceutil/trace.go:171","msg":"trace[1744520773] linearizableReadLoop","detail":"{readStateIndex:19673; appliedIndex:19671; }","duration":"180.881258ms","start":"2026-09-08T02:46:46.979692Z","end":"2026-09-08T02:46:47.160574Z","steps":["trace[1744520773] 'read index received' (duration: 180.749844ms)","trace[1744520773] 'applied index is now lower than readState.Index' (duration: 130.324µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:46:47.160652Z","caller":"traceutil/trace.go:171","msg":"trace[1174412118] transaction","detail":"{read_only:false; response_revision:19009; number_of_response:1; }","duration":"204.556332ms","start":"2026-09-08T02:46:46.956053Z","end":"2026-09-08T02:46:47.16061Z","steps":["trace[1174412118] 'process raft request' (duration: 204.292444ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:46:47.160944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.915888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-08T02:46:47.160992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.258413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:111963"} {"level":"info","ts":"2026-09-08T02:46:47.161029Z","caller":"traceutil/trace.go:171","msg":"trace[1703866940] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:19009; }","duration":"127.075654ms","start":"2026-09-08T02:46:47.033936Z","end":"2026-09-08T02:46:47.161011Z","steps":["trace[1703866940] 'agreement among raft nodes before linearized reading' (duration: 126.917299ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:46:47.161036Z","caller":"traceutil/trace.go:171","msg":"trace[250278251] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:19009; }","duration":"204.309174ms","start":"2026-09-08T02:46:46.956715Z","end":"2026-09-08T02:46:47.161024Z","steps":["trace[250278251] 'agreement among raft nodes before linearized reading' (duration: 204.005955ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:46:47.160712Z","caller":"traceutil/trace.go:171","msg":"trace[1025094872] transaction","detail":"{read_only:false; number_of_response:1; response_revision:19009; }","duration":"197.713323ms","start":"2026-09-08T02:46:46.962927Z","end":"2026-09-08T02:46:47.16064Z","steps":["trace[1025094872] 'process raft request' (duration: 197.606199ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:46:47.160977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.623637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3959"} {"level":"info","ts":"2026-09-08T02:46:47.161193Z","caller":"traceutil/trace.go:171","msg":"trace[2122606603] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:19009; }","duration":"175.886715ms","start":"2026-09-08T02:46:46.985289Z","end":"2026-09-08T02:46:47.161176Z","steps":["trace[2122606603] 'agreement among raft nodes before linearized reading' (duration: 175.574365ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:46:47.160978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.897546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"info","ts":"2026-09-08T02:46:47.161248Z","caller":"traceutil/trace.go:171","msg":"trace[520287277] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:19009; }","duration":"177.190275ms","start":"2026-09-08T02:46:46.98404Z","end":"2026-09-08T02:46:47.16123Z","steps":["trace[520287277] 'agreement among raft nodes before linearized reading' (duration: 176.763282ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:46:47.572722Z","caller":"traceutil/trace.go:171","msg":"trace[987932661] transaction","detail":"{read_only:false; number_of_response:1; response_revision:19010; }","duration":"404.610476ms","start":"2026-09-08T02:46:47.168052Z","end":"2026-09-08T02:46:47.572663Z","steps":["trace[987932661] 'process raft request' (duration: 338.64875ms)","trace[987932661] 'compare' (duration: 64.949776ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:46:47.572957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:46:47.16803Z","time spent":"404.773441ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58,"response count":0,"response size":44,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-09-08T02:46:47.651992Z","caller":"traceutil/trace.go:171","msg":"trace[1037258078] linearizableReadLoop","detail":"{readStateIndex:19675; appliedIndex:19673; }","duration":"483.673713ms","start":"2026-09-08T02:46:47.168298Z","end":"2026-09-08T02:46:47.651972Z","steps":["trace[1037258078] 'read index received' (duration: 338.433494ms)","trace[1037258078] 'applied index is now lower than readState.Index' (duration: 145.239519ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:46:47.652359Z","caller":"traceutil/trace.go:171","msg":"trace[812513802] transaction","detail":"{read_only:false; response_revision:19011; number_of_response:1; }","duration":"484.079375ms","start":"2026-09-08T02:46:47.168269Z","end":"2026-09-08T02:46:47.652348Z","steps":["trace[812513802] 'process raft request' (duration: 483.465057ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:46:47.652534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:46:47.168242Z","time spent":"484.142567ms","remote":"127.0.0.1:41378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":42,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-08T02:46:47.65286Z","caller":"traceutil/trace.go:171","msg":"trace[1052908324] transaction","detail":"{read_only:false; response_revision:19013; number_of_response:1; }","duration":"240.407038ms","start":"2026-09-08T02:46:47.412426Z","end":"2026-09-08T02:46:47.652833Z","steps":["trace[1052908324] 'process raft request' (duration: 239.5ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:46:47.653083Z","caller":"traceutil/trace.go:171","msg":"trace[1582674589] transaction","detail":"{read_only:false; response_revision:19012; number_of_response:1; }","duration":"478.997359ms","start":"2026-09-08T02:46:47.174076Z","end":"2026-09-08T02:46:47.653073Z","steps":["trace[1582674589] 'process raft request' (duration: 477.795092ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:46:47.653167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:46:47.174059Z","time spent":"479.045741ms","remote":"127.0.0.1:36244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2668,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T02:46:47.653407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.089346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3685"} {"level":"info","ts":"2026-09-08T02:46:47.653439Z","caller":"traceutil/trace.go:171","msg":"trace[366764814] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:19013; }","duration":"485.167869ms","start":"2026-09-08T02:46:47.168262Z","end":"2026-09-08T02:46:47.65343Z","steps":["trace[366764814] 'agreement among raft nodes before linearized reading' (duration: 484.636723ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:46:47.653458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:46:47.168247Z","time spent":"485.206869ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3709,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"info","ts":"2026-09-08T02:46:47.795187Z","caller":"traceutil/trace.go:171","msg":"trace[623192344] linearizableReadLoop","detail":"{readStateIndex:19679; appliedIndex:19677; }","duration":"143.118494ms","start":"2026-09-08T02:46:47.652048Z","end":"2026-09-08T02:46:47.795166Z","steps":["trace[623192344] 'read index received' (duration: 142.866857ms)","trace[623192344] 'applied index is now lower than readState.Index' (duration: 250.527µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:46:47.795278Z","caller":"traceutil/trace.go:171","msg":"trace[1867858163] transaction","detail":"{read_only:false; number_of_response:1; response_revision:19013; }","duration":"211.429341ms","start":"2026-09-08T02:46:47.583842Z","end":"2026-09-08T02:46:47.795271Z","steps":["trace[1867858163] 'process raft request' (duration: 211.092931ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:46:47.795532Z","caller":"traceutil/trace.go:171","msg":"trace[753028949] transaction","detail":"{read_only:false; response_revision:19014; number_of_response:1; }","duration":"193.942207ms","start":"2026-09-08T02:46:47.601583Z","end":"2026-09-08T02:46:47.795525Z","steps":["trace[753028949] 'process raft request' (duration: 193.497064ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:46:47.795701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.383995ms","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-09-08T02:46:47.795731Z","caller":"traceutil/trace.go:171","msg":"trace[1945515114] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:19014; }","duration":"148.445377ms","start":"2026-09-08T02:46:47.647278Z","end":"2026-09-08T02:46:47.795723Z","steps":["trace[1945515114] 'agreement among raft nodes before linearized reading' (duration: 148.343123ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:46:47.795958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.370864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1344"} {"level":"warn","ts":"2026-09-08T02:46:47.795954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.774084ms","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-09-08T02:46:47.795991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.6605ms","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-09-08T02:46:47.796015Z","caller":"traceutil/trace.go:171","msg":"trace[1305482797] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:19014; }","duration":"627.460627ms","start":"2026-09-08T02:46:47.168539Z","end":"2026-09-08T02:46:47.796Z","steps":["trace[1305482797] 'agreement among raft nodes before linearized reading' (duration: 627.266331ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:46:47.796044Z","caller":"traceutil/trace.go:171","msg":"trace[581752036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19014; }","duration":"604.746083ms","start":"2026-09-08T02:46:47.191284Z","end":"2026-09-08T02:46:47.79603Z","steps":["trace[581752036] 'agreement among raft nodes before linearized reading' (duration: 604.571808ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:46:47.796054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:46:47.168526Z","time spent":"627.519419ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":1,"response size":1368,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" "} {"level":"info","ts":"2026-09-08T02:46:47.796044Z","caller":"traceutil/trace.go:171","msg":"trace[1537570536] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19014; }","duration":"603.904748ms","start":"2026-09-08T02:46:47.192116Z","end":"2026-09-08T02:46:47.796021Z","steps":["trace[1537570536] 'agreement among raft nodes before linearized reading' (duration: 603.617049ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:46:47.796097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:46:47.191267Z","time spent":"604.821245ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-08T02:46:47.796086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.048997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-08T02:46:47.79612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.965497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1343"} {"level":"warn","ts":"2026-09-08T02:46:47.796129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.969938ms","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-09-08T02:46:47.796097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.99031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4727"} {"level":"info","ts":"2026-09-08T02:46:47.796159Z","caller":"traceutil/trace.go:171","msg":"trace[85528007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19014; }","duration":"276.16213ms","start":"2026-09-08T02:46:47.51998Z","end":"2026-09-08T02:46:47.796142Z","steps":["trace[85528007] 'agreement among raft nodes before linearized reading' (duration: 276.041927ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:46:47.796171Z","caller":"traceutil/trace.go:171","msg":"trace[1402492823] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:19014; }","duration":"139.0582ms","start":"2026-09-08T02:46:47.657095Z","end":"2026-09-08T02:46:47.796153Z","steps":["trace[1402492823] 'agreement among raft nodes before linearized reading' (duration: 138.861764ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:46:47.796166Z","caller":"traceutil/trace.go:171","msg":"trace[571941319] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19014; }","duration":"602.05045ms","start":"2026-09-08T02:46:47.194107Z","end":"2026-09-08T02:46:47.796157Z","steps":["trace[571941319] 'agreement among raft nodes before linearized reading' (duration: 601.961547ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:46:47.796235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:46:47.192106Z","time spent":"604.059132ms","remote":"127.0.0.1:41566","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":"warn","ts":"2026-09-08T02:46:47.796301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:46:47.194087Z","time spent":"602.200065ms","remote":"127.0.0.1:41566","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":"info","ts":"2026-09-08T02:46:47.796179Z","caller":"traceutil/trace.go:171","msg":"trace[1020614260] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:19014; }","duration":"621.070612ms","start":"2026-09-08T02:46:47.175097Z","end":"2026-09-08T02:46:47.796167Z","steps":["trace[1020614260] 'agreement among raft nodes before linearized reading' (duration: 620.937528ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:46:47.796351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:46:47.175051Z","time spent":"621.292688ms","remote":"127.0.0.1:48194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":4751,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" "} {"level":"warn","ts":"2026-09-08T02:46:47.797854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.819969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:940297"} {"level":"info","ts":"2026-09-08T02:46:47.798124Z","caller":"traceutil/trace.go:171","msg":"trace[1228654620] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:19014; }","duration":"629.149298ms","start":"2026-09-08T02:46:47.168952Z","end":"2026-09-08T02:46:47.798101Z","steps":["trace[1228654620] 'agreement among raft nodes before linearized reading' (duration: 626.747545ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:46:47.799161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:46:47.168939Z","time spent":"630.200411ms","remote":"127.0.0.1:41472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":95,"response size":940321,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-09-08T02:47:02.664084Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16905} {"level":"info","ts":"2026-09-08T02:47:02.754396Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16905,"took":"87.804663ms","hash":1074073996,"current-db-size-bytes":55300096,"current-db-size":"55 MB","current-db-size-in-use-bytes":31485952,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-08T02:47:02.754471Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1074073996,"revision":16905,"compact-revision":14506} {"level":"info","ts":"2026-09-08T02:47:10.124575Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"adb6690514c92321","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-08T02:47:10.134769Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-08T02:47:10.135052Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-08T02:52:02.67187Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19205} {"level":"info","ts":"2026-09-08T02:52:02.7741Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19205,"took":"98.910929ms","hash":4158972245,"current-db-size-bytes":55300096,"current-db-size":"55 MB","current-db-size-in-use-bytes":34148352,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-08T02:52:02.774171Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4158972245,"revision":19205,"compact-revision":16905} {"level":"info","ts":"2026-09-08T02:53:11.413692Z","caller":"traceutil/trace.go:171","msg":"trace[1588295884] linearizableReadLoop","detail":"{readStateIndex:22732; appliedIndex:22731; }","duration":"245.290647ms","start":"2026-09-08T02:53:11.168374Z","end":"2026-09-08T02:53:11.413664Z","steps":["trace[1588295884] 'read index received' (duration: 245.0766ms)","trace[1588295884] 'applied index is now lower than readState.Index' (duration: 212.897µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:53:11.413899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.498712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-08T02:53:11.413945Z","caller":"traceutil/trace.go:171","msg":"trace[696803725] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:21971; }","duration":"245.606736ms","start":"2026-09-08T02:53:11.168325Z","end":"2026-09-08T02:53:11.413931Z","steps":["trace[696803725] 'agreement among raft nodes before linearized reading' (duration: 245.461031ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:53:11.414098Z","caller":"traceutil/trace.go:171","msg":"trace[1356980791] transaction","detail":"{read_only:false; response_revision:21971; number_of_response:1; }","duration":"261.179711ms","start":"2026-09-08T02:53:11.15287Z","end":"2026-09-08T02:53:11.41405Z","steps":["trace[1356980791] 'process raft request' (duration: 260.613734ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:53:27.772405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.380562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-08T02:53:27.772591Z","caller":"traceutil/trace.go:171","msg":"trace[1223910740] transaction","detail":"{read_only:false; response_revision:22055; number_of_response:1; }","duration":"176.210659ms","start":"2026-09-08T02:53:27.596342Z","end":"2026-09-08T02:53:27.772553Z","steps":["trace[1223910740] 'process raft request' (duration: 42.550728ms)","trace[1223910740] 'compare' (duration: 133.049122ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:53:28.558637Z","caller":"traceutil/trace.go:171","msg":"trace[325406346] linearizableReadLoop","detail":"{readStateIndex:22821; appliedIndex:22820; }","duration":"438.118392ms","start":"2026-09-08T02:53:28.120486Z","end":"2026-09-08T02:53:28.558605Z","steps":["trace[325406346] 'read index received' (duration: 437.898105ms)","trace[325406346] 'applied index is now lower than readState.Index' (duration: 219.167µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:53:28.558693Z","caller":"traceutil/trace.go:171","msg":"trace[728400692] transaction","detail":"{read_only:false; response_revision:22056; number_of_response:1; }","duration":"443.432014ms","start":"2026-09-08T02:53:28.115231Z","end":"2026-09-08T02:53:28.558663Z","steps":["trace[728400692] 'process raft request' (duration: 443.163126ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:53:28.558873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:53:28.115206Z","time spent":"443.546256ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T02:53:28.558963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.457172ms","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-09-08T02:53:28.559036Z","caller":"traceutil/trace.go:171","msg":"trace[1632843603] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22056; }","duration":"438.556434ms","start":"2026-09-08T02:53:28.120461Z","end":"2026-09-08T02:53:28.559018Z","steps":["trace[1632843603] 'agreement among raft nodes before linearized reading' (duration: 438.277656ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:53:28.559066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:53:28.120451Z","time spent":"438.610098ms","remote":"127.0.0.1:41566","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":"info","ts":"2026-09-08T02:53:28.754127Z","caller":"traceutil/trace.go:171","msg":"trace[1226397687] transaction","detail":"{read_only:false; response_revision:22058; number_of_response:1; }","duration":"481.918799ms","start":"2026-09-08T02:53:28.272183Z","end":"2026-09-08T02:53:28.754102Z","steps":["trace[1226397687] 'process raft request' (duration: 481.703982ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:53:28.754881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:53:28.272158Z","time spent":"482.62581ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T02:53:28.75407Z","caller":"traceutil/trace.go:171","msg":"trace[1675640825] transaction","detail":"{read_only:false; response_revision:22057; number_of_response:1; }","duration":"619.543149ms","start":"2026-09-08T02:53:28.134485Z","end":"2026-09-08T02:53:28.754028Z","steps":["trace[1675640825] 'process raft request' (duration: 618.753455ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:53:28.755031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:53:28.134473Z","time spent":"620.484978ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T02:53:28.754269Z","caller":"traceutil/trace.go:171","msg":"trace[1723789091] linearizableReadLoop","detail":"{readStateIndex:22824; appliedIndex:22821; }","duration":"195.470866ms","start":"2026-09-08T02:53:28.558724Z","end":"2026-09-08T02:53:28.754195Z","steps":["trace[1723789091] 'read index received' (duration: 194.598679ms)","trace[1723789091] 'applied index is now lower than readState.Index' (duration: 868.287µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:53:28.754291Z","caller":"traceutil/trace.go:171","msg":"trace[60335720] transaction","detail":"{read_only:false; response_revision:22060; number_of_response:1; }","duration":"190.243697ms","start":"2026-09-08T02:53:28.564016Z","end":"2026-09-08T02:53:28.75426Z","steps":["trace[60335720] 'process raft request' (duration: 190.040861ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:53:28.754421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.463445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T02:53:28.755293Z","caller":"traceutil/trace.go:171","msg":"trace[35972559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22060; }","duration":"253.387583ms","start":"2026-09-08T02:53:28.501888Z","end":"2026-09-08T02:53:28.755275Z","steps":["trace[35972559] 'agreement among raft nodes before linearized reading' (duration: 252.459035ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:53:28.754512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.955501ms","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-09-08T02:53:28.755441Z","caller":"traceutil/trace.go:171","msg":"trace[1790025314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22060; }","duration":"464.928651ms","start":"2026-09-08T02:53:28.29049Z","end":"2026-09-08T02:53:28.755419Z","steps":["trace[1790025314] 'agreement among raft nodes before linearized reading' (duration: 463.857798ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:53:28.754503Z","caller":"traceutil/trace.go:171","msg":"trace[2096618227] transaction","detail":"{read_only:false; response_revision:22059; number_of_response:1; }","duration":"338.685978ms","start":"2026-09-08T02:53:28.415791Z","end":"2026-09-08T02:53:28.754477Z","steps":["trace[2096618227] 'process raft request' (duration: 338.208193ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:53:28.755498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:53:28.290471Z","time spent":"465.010613ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-08T02:53:28.755522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:53:28.415762Z","time spent":"339.728169ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T02:53:28.754517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.356254ms","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-09-08T02:53:28.755584Z","caller":"traceutil/trace.go:171","msg":"trace[109364917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22060; }","duration":"603.460008ms","start":"2026-09-08T02:53:28.152108Z","end":"2026-09-08T02:53:28.755568Z","steps":["trace[109364917] 'agreement among raft nodes before linearized reading' (duration: 602.257051ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:53:28.754517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.850783ms","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-09-08T02:53:28.75564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:53:28.152088Z","time spent":"603.530971ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-08T02:53:28.755666Z","caller":"traceutil/trace.go:171","msg":"trace[1961992664] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22060; }","duration":"612.017849ms","start":"2026-09-08T02:53:28.143618Z","end":"2026-09-08T02:53:28.755636Z","steps":["trace[1961992664] 'agreement among raft nodes before linearized reading' (duration: 610.696939ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:53:28.755748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:53:28.143611Z","time spent":"612.117052ms","remote":"127.0.0.1:41566","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-09-08T02:57:02.678831Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21577} {"level":"info","ts":"2026-09-08T02:57:02.803523Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21577,"took":"120.465714ms","hash":869048741,"current-db-size-bytes":55300096,"current-db-size":"55 MB","current-db-size-in-use-bytes":30846976,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-08T02:57:02.803599Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":869048741,"revision":21577,"compact-revision":19205} {"level":"info","ts":"2026-09-08T02:57:36.251516Z","caller":"traceutil/trace.go:171","msg":"trace[145449799] linearizableReadLoop","detail":"{readStateIndex:25410; appliedIndex:25409; }","duration":"139.480074ms","start":"2026-09-08T02:57:36.112015Z","end":"2026-09-08T02:57:36.251495Z","steps":["trace[145449799] 'read index received' (duration: 139.268958ms)","trace[145449799] 'applied index is now lower than readState.Index' (duration: 210.096µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:57:36.251555Z","caller":"traceutil/trace.go:171","msg":"trace[216344519] transaction","detail":"{read_only:false; response_revision:24569; number_of_response:1; }","duration":"150.19204ms","start":"2026-09-08T02:57:36.10133Z","end":"2026-09-08T02:57:36.251522Z","steps":["trace[216344519] 'process raft request' (duration: 150.005094ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:57:36.251789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.756522ms","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-09-08T02:57:36.251823Z","caller":"traceutil/trace.go:171","msg":"trace[251136814] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24569; }","duration":"139.831625ms","start":"2026-09-08T02:57:36.111982Z","end":"2026-09-08T02:57:36.251814Z","steps":["trace[251136814] 'agreement among raft nodes before linearized reading' (duration: 139.651839ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:57:36.252088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.196403ms","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-09-08T02:57:36.252135Z","caller":"traceutil/trace.go:171","msg":"trace[789023781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24569; }","duration":"135.299326ms","start":"2026-09-08T02:57:36.116824Z","end":"2026-09-08T02:57:36.252123Z","steps":["trace[789023781] 'agreement among raft nodes before linearized reading' (duration: 135.121611ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:57:36.663034Z","caller":"traceutil/trace.go:171","msg":"trace[743658240] transaction","detail":"{read_only:false; response_revision:24573; number_of_response:1; }","duration":"403.866528ms","start":"2026-09-08T02:57:36.259105Z","end":"2026-09-08T02:57:36.662971Z","steps":["trace[743658240] 'process raft request' (duration: 363.752934ms)","trace[743658240] 'compare' (duration: 38.699241ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:57:36.663107Z","caller":"traceutil/trace.go:171","msg":"trace[1186086057] linearizableReadLoop","detail":"{readStateIndex:25415; appliedIndex:25413; }","duration":"249.320594ms","start":"2026-09-08T02:57:36.41376Z","end":"2026-09-08T02:57:36.663081Z","steps":["trace[1186086057] 'read index received' (duration: 209.114797ms)","trace[1186086057] 'applied index is now lower than readState.Index' (duration: 40.204777ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T02:57:36.663251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:57:36.259072Z","time spent":"404.065304ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T02:57:36.663446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.316276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-08T02:57:36.663482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.720206ms","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-09-08T02:57:36.663525Z","caller":"traceutil/trace.go:171","msg":"trace[1918701406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24574; }","duration":"154.448731ms","start":"2026-09-08T02:57:36.509062Z","end":"2026-09-08T02:57:36.663511Z","steps":["trace[1918701406] 'agreement among raft nodes before linearized reading' (duration: 154.324407ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:57:36.663446Z","caller":"traceutil/trace.go:171","msg":"trace[775961610] transaction","detail":"{read_only:false; response_revision:24574; number_of_response:1; }","duration":"379.991279ms","start":"2026-09-08T02:57:36.283381Z","end":"2026-09-08T02:57:36.663372Z","steps":["trace[775961610] 'process raft request' (duration: 379.608357ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:57:36.66375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T02:57:36.283371Z","time spent":"380.227806ms","remote":"127.0.0.1:41566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T02:57:36.663867Z","caller":"traceutil/trace.go:171","msg":"trace[1615490718] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24574; }","duration":"249.807858ms","start":"2026-09-08T02:57:36.413708Z","end":"2026-09-08T02:57:36.663516Z","steps":["trace[1615490718] 'agreement among raft nodes before linearized reading' (duration: 249.625023ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:58:58.030309Z","caller":"traceutil/trace.go:171","msg":"trace[1121280600] linearizableReadLoop","detail":"{readStateIndex:26312; appliedIndex:26311; }","duration":"105.629783ms","start":"2026-09-08T02:58:57.924633Z","end":"2026-09-08T02:58:58.030262Z","steps":["trace[1121280600] 'read index received' (duration: 105.460358ms)","trace[1121280600] 'applied index is now lower than readState.Index' (duration: 168.155µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T02:58:58.030499Z","caller":"traceutil/trace.go:171","msg":"trace[413834890] transaction","detail":"{read_only:false; response_revision:25449; number_of_response:1; }","duration":"121.737196ms","start":"2026-09-08T02:58:57.908749Z","end":"2026-09-08T02:58:58.030486Z","steps":["trace[413834890] 'process raft request' (duration: 121.340824ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:58:58.030752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.975595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-08T02:58:58.030806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.47957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T02:58:58.030847Z","caller":"traceutil/trace.go:171","msg":"trace[554434295] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:25449; }","duration":"105.534241ms","start":"2026-09-08T02:58:57.9253Z","end":"2026-09-08T02:58:58.030835Z","steps":["trace[554434295] 'agreement among raft nodes before linearized reading' (duration: 105.450889ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:58:58.030798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.345495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T02:58:58.030854Z","caller":"traceutil/trace.go:171","msg":"trace[1576487975] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:25449; }","duration":"106.116898ms","start":"2026-09-08T02:58:57.924712Z","end":"2026-09-08T02:58:58.030829Z","steps":["trace[1576487975] 'agreement among raft nodes before linearized reading' (duration: 105.921683ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T02:58:58.030921Z","caller":"traceutil/trace.go:171","msg":"trace[211428596] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:25449; }","duration":"105.543461ms","start":"2026-09-08T02:58:57.92535Z","end":"2026-09-08T02:58:58.030894Z","steps":["trace[211428596] 'agreement among raft nodes before linearized reading' (duration: 105.323095ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T02:58:58.030706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.041827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T02:58:58.031289Z","caller":"traceutil/trace.go:171","msg":"trace[2046611150] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:25449; }","duration":"106.598814ms","start":"2026-09-08T02:58:57.924625Z","end":"2026-09-08T02:58:58.031224Z","steps":["trace[2046611150] 'agreement among raft nodes before linearized reading' (duration: 105.984345ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T03:01:23.656043Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006b8a.wal"} {"level":"info","ts":"2026-09-08T03:02:02.68603Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23773} {"level":"info","ts":"2026-09-08T03:02:02.807842Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23773,"took":"118.366393ms","hash":498863239,"current-db-size-bytes":55730176,"current-db-size":"56 MB","current-db-size-in-use-bytes":42356736,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-08T03:02:02.808336Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":498863239,"revision":23773,"compact-revision":21577} {"level":"info","ts":"2026-09-08T03:06:43.964355Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"adb6690514c92321","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-08T03:06:43.97008Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-08T03:06:43.970212Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-08T03:07:02.693055Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26921} {"level":"info","ts":"2026-09-08T03:07:02.848759Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26921,"took":"150.425231ms","hash":2713268279,"current-db-size-bytes":55730176,"current-db-size":"56 MB","current-db-size-in-use-bytes":30187520,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-08T03:07:02.848833Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2713268279,"revision":26921,"compact-revision":23773}