{"level":"warn","ts":"2026-07-08T21:37:42.912447Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T21:37:42.912542Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.30:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.30:2380","--initial-cluster=instance=https://199.19.213.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.30:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-08T21:37:42.912638Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T21:37:42.91265Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.30:2380"]} {"level":"info","ts":"2026-07-08T21:37:42.91267Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-08T21:37:42.913622Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.30:2379"]} {"level":"info","ts":"2026-07-08T21:37:42.913712Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.30:2380"],"listen-peer-urls":["https://199.19.213.30:2380"],"advertise-client-urls":["https://199.19.213.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.30:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-08T21:37:42.920722Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.784375ms"} {"level":"info","ts":"2026-07-08T21:37:42.933605Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e2b5783e11defe7e","cluster-id":"5210a9231a779bf0"} {"level":"info","ts":"2026-07-08T21:37:42.933734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e switched to configuration voters=()"} {"level":"info","ts":"2026-07-08T21:37:42.933767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became follower at term 0"} {"level":"info","ts":"2026-07-08T21:37:42.933789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e2b5783e11defe7e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-08T21:37:42.933804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became follower at term 1"} {"level":"info","ts":"2026-07-08T21:37:42.933872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e switched to configuration voters=(16336095431339474558)"} {"level":"warn","ts":"2026-07-08T21:37:42.941038Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-08T21:37:42.943163Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-08T21:37:42.94508Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-08T21:37:42.948889Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e2b5783e11defe7e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-08T21:37:42.949079Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-08T21:37:42.949194Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-08T21:37:42.949206Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-08T21:37:42.94949Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e2b5783e11defe7e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-08T21:37:42.950455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e switched to configuration voters=(16336095431339474558)"} {"level":"info","ts":"2026-07-08T21:37:42.951674Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5210a9231a779bf0","local-member-id":"e2b5783e11defe7e","added-peer-id":"e2b5783e11defe7e","added-peer-peer-urls":["https://199.19.213.30:2380"]} {"level":"info","ts":"2026-07-08T21:37:42.953923Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-08T21:37:42.954088Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.30:2380"} {"level":"info","ts":"2026-07-08T21:37:42.954132Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.30:2380"} {"level":"info","ts":"2026-07-08T21:37:42.954382Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-08T21:37:42.954327Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e2b5783e11defe7e","initial-advertise-peer-urls":["https://199.19.213.30:2380"],"listen-peer-urls":["https://199.19.213.30:2380"],"advertise-client-urls":["https://199.19.213.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-08T21:37:43.134778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e is starting a new election at term 1"} {"level":"info","ts":"2026-07-08T21:37:43.135017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became pre-candidate at term 1"} {"level":"info","ts":"2026-07-08T21:37:43.135081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e received MsgPreVoteResp from e2b5783e11defe7e at term 1"} {"level":"info","ts":"2026-07-08T21:37:43.135136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became candidate at term 2"} {"level":"info","ts":"2026-07-08T21:37:43.135183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e received MsgVoteResp from e2b5783e11defe7e at term 2"} {"level":"info","ts":"2026-07-08T21:37:43.135261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became leader at term 2"} {"level":"info","ts":"2026-07-08T21:37:43.135316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e2b5783e11defe7e elected leader e2b5783e11defe7e at term 2"} {"level":"info","ts":"2026-07-08T21:37:43.136233Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T21:37:43.137427Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T21:37:43.137433Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e2b5783e11defe7e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.30:2379]}","request-path":"/0/members/e2b5783e11defe7e/attributes","cluster-id":"5210a9231a779bf0","publish-timeout":"7s"} {"level":"info","ts":"2026-07-08T21:37:43.137724Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T21:37:43.138898Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5210a9231a779bf0","local-member-id":"e2b5783e11defe7e","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T21:37:43.13909Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T21:37:43.139146Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T21:37:43.139894Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.30:2379"} {"level":"info","ts":"2026-07-08T21:37:43.140892Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-08T21:37:43.147274Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-08T21:37:43.147316Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-08T21:38:18.293289Z","caller":"traceutil/trace.go:171","msg":"trace[1588012874] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"130.66829ms","start":"2026-07-08T21:38:18.162595Z","end":"2026-07-08T21:38:18.293263Z","steps":["trace[1588012874] 'process raft request' (duration: 130.519988ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:38:18.298162Z","caller":"traceutil/trace.go:171","msg":"trace[980149704] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"119.651087ms","start":"2026-07-08T21:38:18.178468Z","end":"2026-07-08T21:38:18.298119Z","steps":["trace[980149704] 'process raft request' (duration: 119.429193ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:38:18.29824Z","caller":"traceutil/trace.go:171","msg":"trace[412102655] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"129.366366ms","start":"2026-07-08T21:38:18.168832Z","end":"2026-07-08T21:38:18.298199Z","steps":["trace[412102655] 'process raft request' (duration: 128.951548ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:38:18.298335Z","caller":"traceutil/trace.go:171","msg":"trace[1383391083] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"130.070109ms","start":"2026-07-08T21:38:18.168189Z","end":"2026-07-08T21:38:18.298259Z","steps":["trace[1383391083] 'process raft request' (duration: 129.488228ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:38:18.298389Z","caller":"traceutil/trace.go:171","msg":"trace[1140396044] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"118.459344ms","start":"2026-07-08T21:38:18.179886Z","end":"2026-07-08T21:38:18.298346Z","steps":["trace[1140396044] 'process raft request' (duration: 118.093138ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:38:18.298466Z","caller":"traceutil/trace.go:171","msg":"trace[1343372044] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"119.020615ms","start":"2026-07-08T21:38:18.179389Z","end":"2026-07-08T21:38:18.298409Z","steps":["trace[1343372044] 'process raft request' (duration: 118.534517ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:38:18.298517Z","caller":"traceutil/trace.go:171","msg":"trace[866062409] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"118.586177ms","start":"2026-07-08T21:38:18.179878Z","end":"2026-07-08T21:38:18.298465Z","steps":["trace[866062409] 'process raft request' (duration: 118.070038ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:38:18.298299Z","caller":"traceutil/trace.go:171","msg":"trace[772805687] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"120.790488ms","start":"2026-07-08T21:38:18.17748Z","end":"2026-07-08T21:38:18.298271Z","steps":["trace[772805687] 'process raft request' (duration: 120.3309ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:38:24.169145Z","caller":"traceutil/trace.go:171","msg":"trace[527001206] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"126.527203ms","start":"2026-07-08T21:38:24.042589Z","end":"2026-07-08T21:38:24.169117Z","steps":["trace[527001206] 'process raft request' (duration: 126.330079ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:38:24.278228Z","caller":"traceutil/trace.go:171","msg":"trace[2014766700] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"167.443509ms","start":"2026-07-08T21:38:24.110757Z","end":"2026-07-08T21:38:24.278201Z","steps":["trace[2014766700] 'process raft request' (duration: 165.737658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:38:24.660735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.767818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T21:38:24.660979Z","caller":"traceutil/trace.go:171","msg":"trace[697835507] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"316.074553ms","start":"2026-07-08T21:38:24.344865Z","end":"2026-07-08T21:38:24.66094Z","steps":["trace[697835507] 'process raft request' (duration: 138.540042ms)","trace[697835507] 'compare' (duration: 176.555374ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:38:24.661144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:38:24.34483Z","time spent":"316.232236ms","remote":"127.0.0.1:55860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T21:39:07.666021Z","caller":"traceutil/trace.go:171","msg":"trace[45529551] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"226.071356ms","start":"2026-07-08T21:39:07.439916Z","end":"2026-07-08T21:39:07.665988Z","steps":["trace[45529551] 'process raft request' (duration: 225.883222ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:54.770691Z","caller":"traceutil/trace.go:171","msg":"trace[421339555] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"237.515228ms","start":"2026-07-08T21:39:54.533147Z","end":"2026-07-08T21:39:54.770662Z","steps":["trace[421339555] 'process raft request' (duration: 237.350535ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:54.921194Z","caller":"traceutil/trace.go:171","msg":"trace[1259179749] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1933; }","duration":"354.7683ms","start":"2026-07-08T21:39:54.566403Z","end":"2026-07-08T21:39:54.921171Z","steps":["trace[1259179749] 'read index received' (duration: 204.119301ms)","trace[1259179749] 'applied index is now lower than readState.Index' (duration: 150.647209ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:39:54.921297Z","caller":"traceutil/trace.go:171","msg":"trace[382900394] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"383.754603ms","start":"2026-07-08T21:39:54.537504Z","end":"2026-07-08T21:39:54.921259Z","steps":["trace[382900394] 'process raft request' (duration: 365.308107ms)","trace[382900394] 'compare' (duration: 18.232192ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:39:54.921383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.952284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:39:54.921464Z","caller":"traceutil/trace.go:171","msg":"trace[421934077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1866; }","duration":"355.098197ms","start":"2026-07-08T21:39:54.566347Z","end":"2026-07-08T21:39:54.921445Z","steps":["trace[421934077] 'agreement among raft nodes before linearized reading' (duration: 354.946494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:54.921556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:54.566321Z","time spent":"355.222538ms","remote":"127.0.0.1:55388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T21:39:54.921741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:54.537481Z","time spent":"383.903727ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:39:54.940853Z","caller":"traceutil/trace.go:171","msg":"trace[947509286] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"164.614151ms","start":"2026-07-08T21:39:54.77622Z","end":"2026-07-08T21:39:54.940834Z","steps":["trace[947509286] 'process raft request' (duration: 164.422537ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:54.941158Z","caller":"traceutil/trace.go:171","msg":"trace[1881461219] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"164.549848ms","start":"2026-07-08T21:39:54.776592Z","end":"2026-07-08T21:39:54.941141Z","steps":["trace[1881461219] 'process raft request' (duration: 164.183062ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:54.941348Z","caller":"traceutil/trace.go:171","msg":"trace[1303178306] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"163.985939ms","start":"2026-07-08T21:39:54.777336Z","end":"2026-07-08T21:39:54.941322Z","steps":["trace[1303178306] 'process raft request' (duration: 163.727344ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:54.941926Z","caller":"traceutil/trace.go:171","msg":"trace[404314292] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"163.755244ms","start":"2026-07-08T21:39:54.778139Z","end":"2026-07-08T21:39:54.941894Z","steps":["trace[404314292] 'process raft request' (duration: 163.487938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:54.942112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.570357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6ed31dd1-59df-4371-a9ee-149cc4482c0c\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-08T21:39:54.942186Z","caller":"traceutil/trace.go:171","msg":"trace[388478531] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6ed31dd1-59df-4371-a9ee-149cc4482c0c; range_end:; response_count:1; response_revision:1870; }","duration":"166.696019ms","start":"2026-07-08T21:39:54.775474Z","end":"2026-07-08T21:39:54.94217Z","steps":["trace[388478531] 'agreement among raft nodes before linearized reading' (duration: 166.504065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:54.942258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.645873ms","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-07-08T21:39:54.942332Z","caller":"traceutil/trace.go:171","msg":"trace[282545431] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1870; }","duration":"301.761845ms","start":"2026-07-08T21:39:54.64055Z","end":"2026-07-08T21:39:54.942312Z","steps":["trace[282545431] 'agreement among raft nodes before linearized reading' (duration: 301.557702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:54.94238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:54.640522Z","time spent":"301.844796ms","remote":"127.0.0.1:47492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-08T21:40:07.892913Z","caller":"traceutil/trace.go:171","msg":"trace[601428031] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"120.012093ms","start":"2026-07-08T21:40:07.772869Z","end":"2026-07-08T21:40:07.892881Z","steps":["trace[601428031] 'process raft request' (duration: 119.809848ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:44.553466Z","caller":"traceutil/trace.go:171","msg":"trace[598356950] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"160.514244ms","start":"2026-07-08T21:40:44.392924Z","end":"2026-07-08T21:40:44.553439Z","steps":["trace[598356950] 'process raft request' (duration: 108.28891ms)","trace[598356950] 'compare' (duration: 52.117262ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:40:44.586859Z","caller":"traceutil/trace.go:171","msg":"trace[1297817584] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"104.441398ms","start":"2026-07-08T21:40:44.482386Z","end":"2026-07-08T21:40:44.586828Z","steps":["trace[1297817584] 'process raft request' (duration: 104.189893ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:50.721945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.226425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:40:50.722088Z","caller":"traceutil/trace.go:171","msg":"trace[600532160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2220; }","duration":"155.419707ms","start":"2026-07-08T21:40:50.566626Z","end":"2026-07-08T21:40:50.722045Z","steps":["trace[600532160] 'range keys from in-memory index tree' (duration: 155.118742ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:40:50.72224Z","caller":"traceutil/trace.go:171","msg":"trace[146977634] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"147.148832ms","start":"2026-07-08T21:40:50.575047Z","end":"2026-07-08T21:40:50.722196Z","steps":["trace[146977634] 'process raft request' (duration: 58.117694ms)","trace[146977634] 'compare' (duration: 88.626521ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:40:50.800504Z","caller":"traceutil/trace.go:171","msg":"trace[1730042455] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"191.918106ms","start":"2026-07-08T21:40:50.60855Z","end":"2026-07-08T21:40:50.800468Z","steps":["trace[1730042455] 'process raft request' (duration: 191.680341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:40:50.999238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.134565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T21:40:50.999375Z","caller":"traceutil/trace.go:171","msg":"trace[1905496891] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"178.124035ms","start":"2026-07-08T21:40:50.821228Z","end":"2026-07-08T21:40:50.999352Z","steps":["trace[1905496891] 'process raft request' (duration: 33.757726ms)","trace[1905496891] 'compare' (duration: 143.900731ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:41:33.391377Z","caller":"traceutil/trace.go:171","msg":"trace[212890372] transaction","detail":"{read_only:false; response_revision:2627; number_of_response:1; }","duration":"179.753296ms","start":"2026-07-08T21:41:33.211602Z","end":"2026-07-08T21:41:33.391356Z","steps":["trace[212890372] 'process raft request' (duration: 179.628423ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:41:33.392214Z","caller":"traceutil/trace.go:171","msg":"trace[765835307] transaction","detail":"{read_only:false; response_revision:2628; number_of_response:1; }","duration":"121.412698ms","start":"2026-07-08T21:41:33.270773Z","end":"2026-07-08T21:41:33.392186Z","steps":["trace[765835307] 'process raft request' (duration: 121.269775ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:45:55.680819Z","caller":"traceutil/trace.go:171","msg":"trace[477142536] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"103.725549ms","start":"2026-07-08T21:45:55.576879Z","end":"2026-07-08T21:45:55.680604Z","steps":["trace[477142536] 'process raft request' (duration: 103.112687ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:45:59.657997Z","caller":"traceutil/trace.go:171","msg":"trace[648694601] linearizableReadLoop","detail":"{readStateIndex:5165; appliedIndex:5164; }","duration":"136.176931ms","start":"2026-07-08T21:45:59.521797Z","end":"2026-07-08T21:45:59.657974Z","steps":["trace[648694601] 'read index received' (duration: 136.015068ms)","trace[648694601] 'applied index is now lower than readState.Index' (duration: 161.063µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:45:59.658124Z","caller":"traceutil/trace.go:171","msg":"trace[492890728] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"165.745772ms","start":"2026-07-08T21:45:59.492367Z","end":"2026-07-08T21:45:59.658113Z","steps":["trace[492890728] 'process raft request' (duration: 165.455206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:45:59.658427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.61541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:45:59.658487Z","caller":"traceutil/trace.go:171","msg":"trace[2108918349] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:4980; }","duration":"136.713742ms","start":"2026-07-08T21:45:59.521758Z","end":"2026-07-08T21:45:59.658472Z","steps":["trace[2108918349] 'agreement among raft nodes before linearized reading' (duration: 136.624191ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:46:01.681062Z","caller":"traceutil/trace.go:171","msg":"trace[1397509270] linearizableReadLoop","detail":"{readStateIndex:5205; appliedIndex:5204; }","duration":"115.144391ms","start":"2026-07-08T21:46:01.56589Z","end":"2026-07-08T21:46:01.681034Z","steps":["trace[1397509270] 'read index received' (duration: 76.162257ms)","trace[1397509270] 'applied index is now lower than readState.Index' (duration: 38.980354ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:46:01.681239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.341774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:46:01.681274Z","caller":"traceutil/trace.go:171","msg":"trace[1290786272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5018; }","duration":"115.424847ms","start":"2026-07-08T21:46:01.565838Z","end":"2026-07-08T21:46:01.681262Z","steps":["trace[1290786272] 'agreement among raft nodes before linearized reading' (duration: 115.337684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:46:13.98452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.791419ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T21:46:13.984617Z","caller":"traceutil/trace.go:171","msg":"trace[1174911765] transaction","detail":"{read_only:false; response_revision:5256; number_of_response:1; }","duration":"235.00542ms","start":"2026-07-08T21:46:13.749598Z","end":"2026-07-08T21:46:13.984603Z","steps":["trace[1174911765] 'process raft request' (duration: 30.066029ms)","trace[1174911765] 'compare' (duration: 204.626495ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:46:14.046176Z","caller":"traceutil/trace.go:171","msg":"trace[444015401] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"296.405961ms","start":"2026-07-08T21:46:13.749748Z","end":"2026-07-08T21:46:14.046154Z","steps":["trace[444015401] 'process raft request' (duration: 296.235978ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:46:14.046294Z","caller":"traceutil/trace.go:171","msg":"trace[1279028676] linearizableReadLoop","detail":"{readStateIndex:5452; appliedIndex:5450; }","duration":"202.74045ms","start":"2026-07-08T21:46:13.843535Z","end":"2026-07-08T21:46:14.046275Z","steps":["trace[1279028676] 'read index received' (duration: 202.513746ms)","trace[1279028676] 'applied index is now lower than readState.Index' (duration: 225.664µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:46:14.046333Z","caller":"traceutil/trace.go:171","msg":"trace[1394075434] transaction","detail":"{read_only:false; response_revision:5258; number_of_response:1; }","duration":"295.893101ms","start":"2026-07-08T21:46:13.750429Z","end":"2026-07-08T21:46:14.046322Z","steps":["trace[1394075434] 'process raft request' (duration: 295.672097ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:46:14.046589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.034325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-08T21:46:14.046678Z","caller":"traceutil/trace.go:171","msg":"trace[1985153147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5258; }","duration":"203.170958ms","start":"2026-07-08T21:46:13.843489Z","end":"2026-07-08T21:46:14.04666Z","steps":["trace[1985153147] 'agreement among raft nodes before linearized reading' (duration: 202.867372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:46:14.056736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.973316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-08T21:46:14.056838Z","caller":"traceutil/trace.go:171","msg":"trace[425317976] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5260; }","duration":"187.119128ms","start":"2026-07-08T21:46:13.869703Z","end":"2026-07-08T21:46:14.056822Z","steps":["trace[425317976] 'agreement among raft nodes before linearized reading' (duration: 186.900095ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:47:43.340553Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3029} {"level":"info","ts":"2026-07-08T21:47:43.4286Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3029,"took":"86.475674ms","hash":3641271209,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":32235520,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-08T21:47:43.428674Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3641271209,"revision":3029,"compact-revision":-1} {"level":"info","ts":"2026-07-08T21:52:27.925128Z","caller":"traceutil/trace.go:171","msg":"trace[1564719767] transaction","detail":"{read_only:false; response_revision:8246; number_of_response:1; }","duration":"320.032393ms","start":"2026-07-08T21:52:27.605049Z","end":"2026-07-08T21:52:27.925081Z","steps":["trace[1564719767] 'process raft request' (duration: 319.658715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:52:27.925416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:52:27.605012Z","time spent":"320.255007ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:52:40.115998Z","caller":"traceutil/trace.go:171","msg":"trace[1653520242] transaction","detail":"{read_only:false; response_revision:8311; number_of_response:1; }","duration":"104.830985ms","start":"2026-07-08T21:52:40.011111Z","end":"2026-07-08T21:52:40.115942Z","steps":["trace[1653520242] 'process raft request' (duration: 61.908291ms)","trace[1653520242] 'compare' (duration: 42.778581ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:52:40.978708Z","caller":"traceutil/trace.go:171","msg":"trace[84571619] transaction","detail":"{read_only:false; response_revision:8315; number_of_response:1; }","duration":"148.081415ms","start":"2026-07-08T21:52:40.830586Z","end":"2026-07-08T21:52:40.978667Z","steps":["trace[84571619] 'process raft request' (duration: 136.855377ms)","trace[84571619] 'compare' (duration: 10.927412ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:52:43.347309Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6161} {"level":"info","ts":"2026-07-08T21:52:43.445693Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6161,"took":"96.174797ms","hash":485012214,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":23433216,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-07-08T21:52:43.44577Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":485012214,"revision":6161,"compact-revision":3029} {"level":"info","ts":"2026-07-08T21:53:08.584822Z","caller":"traceutil/trace.go:171","msg":"trace[1234296844] transaction","detail":"{read_only:false; response_revision:8520; number_of_response:1; }","duration":"311.851715ms","start":"2026-07-08T21:53:08.272942Z","end":"2026-07-08T21:53:08.584793Z","steps":["trace[1234296844] 'process raft request' (duration: 246.441914ms)","trace[1234296844] 'compare' (duration: 65.104704ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:53:08.585003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:53:08.272918Z","time spent":"311.994387ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:53:08.72927Z","caller":"traceutil/trace.go:171","msg":"trace[367188005] linearizableReadLoop","detail":"{readStateIndex:8816; appliedIndex:8815; }","duration":"142.336284ms","start":"2026-07-08T21:53:08.586907Z","end":"2026-07-08T21:53:08.729243Z","steps":["trace[367188005] 'read index received' (duration: 126.538906ms)","trace[367188005] 'applied index is now lower than readState.Index' (duration: 15.796078ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:53:08.72942Z","caller":"traceutil/trace.go:171","msg":"trace[812376657] transaction","detail":"{read_only:false; response_revision:8521; number_of_response:1; }","duration":"143.887034ms","start":"2026-07-08T21:53:08.585518Z","end":"2026-07-08T21:53:08.729405Z","steps":["trace[812376657] 'process raft request' (duration: 128.075497ms)","trace[812376657] 'compare' (duration: 15.514621ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:53:08.729851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.912344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:53:08.729952Z","caller":"traceutil/trace.go:171","msg":"trace[1029297133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8521; }","duration":"143.086928ms","start":"2026-07-08T21:53:08.586847Z","end":"2026-07-08T21:53:08.729934Z","steps":["trace[1029297133] 'agreement among raft nodes before linearized reading' (duration: 142.937605ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:53:20.784109Z","caller":"traceutil/trace.go:171","msg":"trace[1818707915] transaction","detail":"{read_only:false; response_revision:8625; number_of_response:1; }","duration":"103.527539ms","start":"2026-07-08T21:53:20.680546Z","end":"2026-07-08T21:53:20.784074Z","steps":["trace[1818707915] 'process raft request' (duration: 103.333276ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:53:21.282362Z","caller":"traceutil/trace.go:171","msg":"trace[563794178] transaction","detail":"{read_only:false; response_revision:8628; number_of_response:1; }","duration":"134.537402ms","start":"2026-07-08T21:53:21.147787Z","end":"2026-07-08T21:53:21.282324Z","steps":["trace[563794178] 'process raft request' (duration: 101.093183ms)","trace[563794178] 'compare' (duration: 33.270386ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:53:21.324917Z","caller":"traceutil/trace.go:171","msg":"trace[2001115884] linearizableReadLoop","detail":"{readStateIndex:8928; appliedIndex:8926; }","duration":"127.820172ms","start":"2026-07-08T21:53:21.197061Z","end":"2026-07-08T21:53:21.324882Z","steps":["trace[2001115884] 'read index received' (duration: 51.97652ms)","trace[2001115884] 'applied index is now lower than readState.Index' (duration: 75.841642ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:53:21.325111Z","caller":"traceutil/trace.go:171","msg":"trace[1362875450] transaction","detail":"{read_only:false; response_revision:8629; number_of_response:1; }","duration":"169.187674ms","start":"2026-07-08T21:53:21.155879Z","end":"2026-07-08T21:53:21.325067Z","steps":["trace[1362875450] 'process raft request' (duration: 168.833718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:53:21.325282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.204059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-08T21:53:21.325392Z","caller":"traceutil/trace.go:171","msg":"trace[675423759] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8629; }","duration":"128.384263ms","start":"2026-07-08T21:53:21.196983Z","end":"2026-07-08T21:53:21.325367Z","steps":["trace[675423759] 'agreement among raft nodes before linearized reading' (duration: 128.031226ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:53:21.461126Z","caller":"traceutil/trace.go:171","msg":"trace[1485854630] transaction","detail":"{read_only:false; response_revision:8630; number_of_response:1; }","duration":"129.935333ms","start":"2026-07-08T21:53:21.33115Z","end":"2026-07-08T21:53:21.461086Z","steps":["trace[1485854630] 'process raft request' (duration: 67.353838ms)","trace[1485854630] 'compare' (duration: 62.416682ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:53:22.250432Z","caller":"traceutil/trace.go:171","msg":"trace[1038098400] transaction","detail":"{read_only:false; response_revision:8634; number_of_response:1; }","duration":"160.925175ms","start":"2026-07-08T21:53:22.089482Z","end":"2026-07-08T21:53:22.250407Z","steps":["trace[1038098400] 'process raft request' (duration: 160.746051ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:53:22.256677Z","caller":"traceutil/trace.go:171","msg":"trace[1761937434] linearizableReadLoop","detail":"{readStateIndex:8935; appliedIndex:8933; }","duration":"111.176928ms","start":"2026-07-08T21:53:22.145463Z","end":"2026-07-08T21:53:22.25664Z","steps":["trace[1761937434] 'read index received' (duration: 104.769654ms)","trace[1761937434] 'applied index is now lower than readState.Index' (duration: 6.405624ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:53:22.256769Z","caller":"traceutil/trace.go:171","msg":"trace[1379650634] transaction","detail":"{read_only:false; response_revision:8635; number_of_response:1; }","duration":"112.994603ms","start":"2026-07-08T21:53:22.143732Z","end":"2026-07-08T21:53:22.256727Z","steps":["trace[1379650634] 'process raft request' (duration: 112.717888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:53:22.257028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.538305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T21:53:22.257104Z","caller":"traceutil/trace.go:171","msg":"trace[147073530] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:8635; }","duration":"111.682849ms","start":"2026-07-08T21:53:22.1454Z","end":"2026-07-08T21:53:22.257083Z","steps":["trace[147073530] 'agreement among raft nodes before linearized reading' (duration: 111.436043ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:53:22.378362Z","caller":"traceutil/trace.go:171","msg":"trace[1868042094] transaction","detail":"{read_only:false; response_revision:8637; number_of_response:1; }","duration":"114.081774ms","start":"2026-07-08T21:53:22.264247Z","end":"2026-07-08T21:53:22.378329Z","steps":["trace[1868042094] 'process raft request' (duration: 98.889329ms)","trace[1868042094] 'compare' (duration: 14.776887ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:53:38.678526Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023a9.wal"} {"level":"info","ts":"2026-07-08T21:54:25.958622Z","caller":"traceutil/trace.go:171","msg":"trace[2143575811] transaction","detail":"{read_only:false; response_revision:9311; number_of_response:1; }","duration":"105.740891ms","start":"2026-07-08T21:54:25.852854Z","end":"2026-07-08T21:54:25.958595Z","steps":["trace[2143575811] 'process raft request' (duration: 105.523287ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:54:25.959782Z","caller":"traceutil/trace.go:171","msg":"trace[2107864404] transaction","detail":"{read_only:false; response_revision:9312; number_of_response:1; }","duration":"100.031502ms","start":"2026-07-08T21:54:25.859726Z","end":"2026-07-08T21:54:25.959757Z","steps":["trace[2107864404] 'process raft request' (duration: 99.876279ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:54:38.755601Z","caller":"traceutil/trace.go:171","msg":"trace[1711071215] linearizableReadLoop","detail":"{readStateIndex:9703; appliedIndex:9702; }","duration":"116.177622ms","start":"2026-07-08T21:54:38.639375Z","end":"2026-07-08T21:54:38.755552Z","steps":["trace[1711071215] 'read index received' (duration: 116.126741ms)","trace[1711071215] 'applied index is now lower than readState.Index' (duration: 49.271µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:54:38.755682Z","caller":"traceutil/trace.go:171","msg":"trace[1171613383] transaction","detail":"{read_only:false; response_revision:9383; number_of_response:1; }","duration":"214.686725ms","start":"2026-07-08T21:54:38.540968Z","end":"2026-07-08T21:54:38.755655Z","steps":["trace[1171613383] 'process raft request' (duration: 214.42389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:54:38.755854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.441397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-08T21:54:38.75592Z","caller":"traceutil/trace.go:171","msg":"trace[65455231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9383; }","duration":"116.61465ms","start":"2026-07-08T21:54:38.639293Z","end":"2026-07-08T21:54:38.755908Z","steps":["trace[65455231] 'agreement among raft nodes before linearized reading' (duration: 116.400156ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:55:17.402133Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e2b5783e11defe7e","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-08T21:55:17.407623Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-08T21:55:17.407749Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-08T21:57:43.355185Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8334} {"level":"info","ts":"2026-07-08T21:57:43.437753Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8334,"took":"80.383852ms","hash":74838090,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":25538560,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-08T21:57:43.437839Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":74838090,"revision":8334,"compact-revision":6161} {"level":"info","ts":"2026-07-08T21:58:21.15123Z","caller":"traceutil/trace.go:171","msg":"trace[1769294227] transaction","detail":"{read_only:false; response_revision:11227; number_of_response:1; }","duration":"145.834327ms","start":"2026-07-08T21:58:21.005348Z","end":"2026-07-08T21:58:21.151182Z","steps":["trace[1769294227] 'process raft request' (duration: 145.545932ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:02:43.36091Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10831} {"level":"info","ts":"2026-07-08T22:02:43.444598Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10831,"took":"81.171223ms","hash":486332233,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":27951104,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-08T22:02:43.444726Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":486332233,"revision":10831,"compact-revision":8334} {"level":"warn","ts":"2026-07-08T22:05:03.257246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.537425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T22:05:03.257361Z","caller":"traceutil/trace.go:171","msg":"trace[1262965658] linearizableReadLoop","detail":"{readStateIndex:14496; appliedIndex:14495; }","duration":"145.564184ms","start":"2026-07-08T22:05:03.11178Z","end":"2026-07-08T22:05:03.257345Z","steps":["trace[1262965658] 'read index received' (duration: 41.5µs)","trace[1262965658] 'applied index is now lower than readState.Index' (duration: 145.521494ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:05:03.257444Z","caller":"traceutil/trace.go:171","msg":"trace[364958030] transaction","detail":"{read_only:false; response_revision:14025; number_of_response:1; }","duration":"145.685387ms","start":"2026-07-08T22:05:03.111747Z","end":"2026-07-08T22:05:03.257432Z","steps":["trace[364958030] 'process raft request' (duration: 23.898287ms)","trace[364958030] 'compare' (duration: 121.432823ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T22:05:03.25759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.796068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-07-08T22:05:03.257651Z","caller":"traceutil/trace.go:171","msg":"trace[242286562] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:14025; }","duration":"145.885961ms","start":"2026-07-08T22:05:03.111752Z","end":"2026-07-08T22:05:03.257638Z","steps":["trace[242286562] 'agreement among raft nodes before linearized reading' (duration: 145.698697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:05:03.257634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.804379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-07-08T22:05:03.257781Z","caller":"traceutil/trace.go:171","msg":"trace[1335018548] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:14025; }","duration":"145.991542ms","start":"2026-07-08T22:05:03.111782Z","end":"2026-07-08T22:05:03.257774Z","steps":["trace[1335018548] 'agreement among raft nodes before linearized reading' (duration: 145.708077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:05:03.25786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.013893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-07-08T22:05:03.25791Z","caller":"traceutil/trace.go:171","msg":"trace[378839551] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:14025; }","duration":"146.070483ms","start":"2026-07-08T22:05:03.111827Z","end":"2026-07-08T22:05:03.257897Z","steps":["trace[378839551] 'agreement among raft nodes before linearized reading' (duration: 145.932721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:05:03.257966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.141427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/ovn-controller\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-07-08T22:05:03.258012Z","caller":"traceutil/trace.go:171","msg":"trace[1854906176] range","detail":"{range_begin:/registry/serviceaccounts/openstack/ovn-controller; range_end:; response_count:1; response_revision:14025; }","duration":"146.193697ms","start":"2026-07-08T22:05:03.111805Z","end":"2026-07-08T22:05:03.257999Z","steps":["trace[1854906176] 'agreement among raft nodes before linearized reading' (duration: 146.126766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:05:03.257916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.085715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/ovn-northd\" ","response":"range_response_count:1 size:519"} {"level":"info","ts":"2026-07-08T22:05:03.258076Z","caller":"traceutil/trace.go:171","msg":"trace[680014266] range","detail":"{range_begin:/registry/serviceaccounts/openstack/ovn-northd; range_end:; response_count:1; response_revision:14025; }","duration":"146.256019ms","start":"2026-07-08T22:05:03.11181Z","end":"2026-07-08T22:05:03.258066Z","steps":["trace[680014266] 'agreement among raft nodes before linearized reading' (duration: 146.060874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:05:03.258419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.359953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:90 size:990341"} {"level":"info","ts":"2026-07-08T22:05:03.258443Z","caller":"traceutil/trace.go:171","msg":"trace[1083083901] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:90; response_revision:14025; }","duration":"143.393583ms","start":"2026-07-08T22:05:03.115044Z","end":"2026-07-08T22:05:03.258438Z","steps":["trace[1083083901] 'agreement among raft nodes before linearized reading' (duration: 142.900254ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:05:03.574138Z","caller":"traceutil/trace.go:171","msg":"trace[1745080904] linearizableReadLoop","detail":"{readStateIndex:14498; appliedIndex:14497; }","duration":"243.281523ms","start":"2026-07-08T22:05:03.330836Z","end":"2026-07-08T22:05:03.574118Z","steps":["trace[1745080904] 'read index received' (duration: 243.197751ms)","trace[1745080904] 'applied index is now lower than readState.Index' (duration: 83.082µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:05:03.574285Z","caller":"traceutil/trace.go:171","msg":"trace[1745848942] transaction","detail":"{read_only:false; response_revision:14027; number_of_response:1; }","duration":"247.115257ms","start":"2026-07-08T22:05:03.327162Z","end":"2026-07-08T22:05:03.574277Z","steps":["trace[1745848942] 'process raft request' (duration: 246.816501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:05:03.574527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.671331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-07-08T22:05:03.574604Z","caller":"traceutil/trace.go:171","msg":"trace[741152338] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:14027; }","duration":"243.767812ms","start":"2026-07-08T22:05:03.33082Z","end":"2026-07-08T22:05:03.574587Z","steps":["trace[741152338] 'agreement among raft nodes before linearized reading' (duration: 243.565949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:05:03.585666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.776904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:111932"} {"level":"info","ts":"2026-07-08T22:05:03.585722Z","caller":"traceutil/trace.go:171","msg":"trace[1678442100] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:14029; }","duration":"254.872715ms","start":"2026-07-08T22:05:03.330838Z","end":"2026-07-08T22:05:03.585711Z","steps":["trace[1678442100] 'agreement among raft nodes before linearized reading' (duration: 254.523508ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:05:03.586095Z","caller":"traceutil/trace.go:171","msg":"trace[247520620] transaction","detail":"{read_only:false; response_revision:14028; number_of_response:1; }","duration":"138.164233ms","start":"2026-07-08T22:05:03.447879Z","end":"2026-07-08T22:05:03.586043Z","steps":["trace[247520620] 'process raft request' (duration: 136.600244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:06:10.67073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.661565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T22:06:10.67098Z","caller":"traceutil/trace.go:171","msg":"trace[2123926686] transaction","detail":"{read_only:false; response_revision:14978; number_of_response:1; }","duration":"133.794298ms","start":"2026-07-08T22:06:10.537147Z","end":"2026-07-08T22:06:10.670941Z","steps":["trace[2123926686] 'process raft request' (duration: 32.809287ms)","trace[2123926686] 'compare' (duration: 100.456322ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:06:10.67112Z","caller":"traceutil/trace.go:171","msg":"trace[1421080113] transaction","detail":"{read_only:false; response_revision:14979; number_of_response:1; }","duration":"102.237556ms","start":"2026-07-08T22:06:10.568839Z","end":"2026-07-08T22:06:10.671076Z","steps":["trace[1421080113] 'process raft request' (duration: 102.037192ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:06:44.601127Z","caller":"traceutil/trace.go:171","msg":"trace[1890197972] transaction","detail":"{read_only:false; response_revision:15154; number_of_response:1; }","duration":"111.824649ms","start":"2026-07-08T22:06:44.489276Z","end":"2026-07-08T22:06:44.601101Z","steps":["trace[1890197972] 'process raft request' (duration: 111.664885ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:06:44.772814Z","caller":"traceutil/trace.go:171","msg":"trace[1954530818] linearizableReadLoop","detail":"{readStateIndex:15664; appliedIndex:15662; }","duration":"208.505988ms","start":"2026-07-08T22:06:44.564284Z","end":"2026-07-08T22:06:44.77279Z","steps":["trace[1954530818] 'read index received' (duration: 36.712693ms)","trace[1954530818] 'applied index is now lower than readState.Index' (duration: 171.792195ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:06:44.77292Z","caller":"traceutil/trace.go:171","msg":"trace[1701771769] transaction","detail":"{read_only:false; response_revision:15155; number_of_response:1; }","duration":"283.044184ms","start":"2026-07-08T22:06:44.489828Z","end":"2026-07-08T22:06:44.772872Z","steps":["trace[1701771769] 'process raft request' (duration: 210.749871ms)","trace[1701771769] 'compare' (duration: 72.098369ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T22:06:44.772969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.679412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T22:06:44.773008Z","caller":"traceutil/trace.go:171","msg":"trace[88185089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15155; }","duration":"208.772453ms","start":"2026-07-08T22:06:44.564226Z","end":"2026-07-08T22:06:44.772999Z","steps":["trace[88185089] 'agreement among raft nodes before linearized reading' (duration: 208.695782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:06:44.772994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.895194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T22:06:44.773031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.284913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T22:06:44.773086Z","caller":"traceutil/trace.go:171","msg":"trace[2063639025] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15155; }","duration":"185.371155ms","start":"2026-07-08T22:06:44.587697Z","end":"2026-07-08T22:06:44.773068Z","steps":["trace[2063639025] 'agreement among raft nodes before linearized reading' (duration: 185.286844ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:06:44.773176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.615759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-07-08T22:06:44.773179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.677721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"warn","ts":"2026-07-08T22:06:44.773172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.552589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:680"} {"level":"info","ts":"2026-07-08T22:06:44.773221Z","caller":"traceutil/trace.go:171","msg":"trace[1053300521] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15155; }","duration":"186.700232ms","start":"2026-07-08T22:06:44.586508Z","end":"2026-07-08T22:06:44.773208Z","steps":["trace[1053300521] 'agreement among raft nodes before linearized reading' (duration: 186.497887ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:06:44.773234Z","caller":"traceutil/trace.go:171","msg":"trace[2005249065] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15155; }","duration":"185.762274ms","start":"2026-07-08T22:06:44.587456Z","end":"2026-07-08T22:06:44.773219Z","steps":["trace[2005249065] 'agreement among raft nodes before linearized reading' (duration: 185.60774ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:06:44.773253Z","caller":"traceutil/trace.go:171","msg":"trace[473004647] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:15155; }","duration":"186.674611ms","start":"2026-07-08T22:06:44.586557Z","end":"2026-07-08T22:06:44.773231Z","steps":["trace[473004647] 'agreement among raft nodes before linearized reading' (duration: 186.423006ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:06:44.773054Z","caller":"traceutil/trace.go:171","msg":"trace[961393264] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:15155; }","duration":"187.998666ms","start":"2026-07-08T22:06:44.585039Z","end":"2026-07-08T22:06:44.773038Z","steps":["trace[961393264] 'agreement among raft nodes before linearized reading' (duration: 187.903174ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:06:44.979369Z","caller":"traceutil/trace.go:171","msg":"trace[1015139812] transaction","detail":"{read_only:false; response_revision:15157; number_of_response:1; }","duration":"190.318061ms","start":"2026-07-08T22:06:44.789023Z","end":"2026-07-08T22:06:44.979341Z","steps":["trace[1015139812] 'process raft request' (duration: 124.95925ms)","trace[1015139812] 'compare' (duration: 65.228789ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:06:45.003183Z","caller":"traceutil/trace.go:171","msg":"trace[1147559830] transaction","detail":"{read_only:false; response_revision:15158; number_of_response:1; }","duration":"211.489206ms","start":"2026-07-08T22:06:44.791662Z","end":"2026-07-08T22:06:45.003151Z","steps":["trace[1147559830] 'process raft request' (duration: 211.104737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:06:45.238273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.50617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-hcpkm\" ","response":"range_response_count:1 size:22121"} {"level":"info","ts":"2026-07-08T22:06:45.238356Z","caller":"traceutil/trace.go:171","msg":"trace[742793782] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-hcpkm; range_end:; response_count:1; response_revision:15158; }","duration":"116.627541ms","start":"2026-07-08T22:06:45.12171Z","end":"2026-07-08T22:06:45.238337Z","steps":["trace[742793782] 'range keys from in-memory index tree' (duration: 116.079241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:06:45.239189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.601137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:80 size:776900"} {"level":"info","ts":"2026-07-08T22:06:45.239295Z","caller":"traceutil/trace.go:171","msg":"trace[379565111] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:80; response_revision:15158; }","duration":"153.753641ms","start":"2026-07-08T22:06:45.085518Z","end":"2026-07-08T22:06:45.239271Z","steps":["trace[379565111] 'range keys from in-memory index tree' (duration: 152.328083ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:07:43.367634Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13011} {"level":"info","ts":"2026-07-08T22:07:43.449611Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13011,"took":"79.039037ms","hash":696333623,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":31301632,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-08T22:07:43.44969Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":696333623,"revision":13011,"compact-revision":10831} {"level":"warn","ts":"2026-07-08T22:09:42.671665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.304943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:09:42.671775Z","caller":"traceutil/trace.go:171","msg":"trace[1860435624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16569; }","duration":"105.468306ms","start":"2026-07-08T22:09:42.566285Z","end":"2026-07-08T22:09:42.671754Z","steps":["trace[1860435624] 'range keys from in-memory index tree' (duration: 105.18899ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:12:20.339247Z","caller":"traceutil/trace.go:171","msg":"trace[1856708109] transaction","detail":"{read_only:false; response_revision:17696; number_of_response:1; }","duration":"106.021926ms","start":"2026-07-08T22:12:20.233184Z","end":"2026-07-08T22:12:20.339206Z","steps":["trace[1856708109] 'process raft request' (duration: 105.796252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:12:26.687166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.715191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-07-08T22:12:26.687254Z","caller":"traceutil/trace.go:171","msg":"trace[918773061] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:17759; }","duration":"373.824944ms","start":"2026-07-08T22:12:26.313405Z","end":"2026-07-08T22:12:26.68723Z","steps":["trace[918773061] 'range keys from in-memory index tree' (duration: 373.562889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:12:26.687303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:12:26.313397Z","time spent":"373.890395ms","remote":"127.0.0.1:47492","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-07-08T22:12:26.707202Z","caller":"traceutil/trace.go:171","msg":"trace[679999028] linearizableReadLoop","detail":"{readStateIndex:18352; appliedIndex:18351; }","duration":"312.689956ms","start":"2026-07-08T22:12:26.39447Z","end":"2026-07-08T22:12:26.70716Z","steps":["trace[679999028] 'read index received' (duration: 312.340879ms)","trace[679999028] 'applied index is now lower than readState.Index' (duration: 347.597µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:12:26.707704Z","caller":"traceutil/trace.go:171","msg":"trace[631459195] transaction","detail":"{read_only:false; response_revision:17760; number_of_response:1; }","duration":"343.614176ms","start":"2026-07-08T22:12:26.364048Z","end":"2026-07-08T22:12:26.707662Z","steps":["trace[631459195] 'process raft request' (duration: 342.855082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:12:26.707888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:12:26.364007Z","time spent":"343.758209ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T22:12:26.708213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.757727ms","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-07-08T22:12:26.708261Z","caller":"traceutil/trace.go:171","msg":"trace[73912059] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17760; }","duration":"313.807918ms","start":"2026-07-08T22:12:26.394445Z","end":"2026-07-08T22:12:26.708253Z","steps":["trace[73912059] 'agreement among raft nodes before linearized reading' (duration: 313.603053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:12:26.708286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:12:26.394356Z","time spent":"313.92428ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T22:12:26.724201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.005492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.30\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-08T22:12:26.724256Z","caller":"traceutil/trace.go:171","msg":"trace[546937446] range","detail":"{range_begin:/registry/masterleases/199.19.213.30; range_end:; response_count:1; response_revision:17761; }","duration":"313.085513ms","start":"2026-07-08T22:12:26.411158Z","end":"2026-07-08T22:12:26.724244Z","steps":["trace[546937446] 'agreement among raft nodes before linearized reading' (duration: 312.965161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:12:26.724285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:12:26.411141Z","time spent":"313.137635ms","remote":"127.0.0.1:55408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/199.19.213.30\" "} {"level":"warn","ts":"2026-07-08T22:12:26.7246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.836146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-08T22:12:26.724629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.910376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:12:26.724681Z","caller":"traceutil/trace.go:171","msg":"trace[2012183271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17761; }","duration":"158.988738ms","start":"2026-07-08T22:12:26.565678Z","end":"2026-07-08T22:12:26.724667Z","steps":["trace[2012183271] 'agreement among raft nodes before linearized reading' (duration: 158.888856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:12:26.724825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.220257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-08T22:12:26.724778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.845184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:12:26.72487Z","caller":"traceutil/trace.go:171","msg":"trace[1038684981] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17761; }","duration":"210.269529ms","start":"2026-07-08T22:12:26.514586Z","end":"2026-07-08T22:12:26.724855Z","steps":["trace[1038684981] 'agreement among raft nodes before linearized reading' (duration: 210.180517ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:12:26.724908Z","caller":"traceutil/trace.go:171","msg":"trace[701708565] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:17761; }","duration":"273.000857ms","start":"2026-07-08T22:12:26.451873Z","end":"2026-07-08T22:12:26.724873Z","steps":["trace[701708565] 'agreement among raft nodes before linearized reading' (duration: 272.763443ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:12:26.725649Z","caller":"traceutil/trace.go:171","msg":"trace[1933137097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17761; }","duration":"213.898788ms","start":"2026-07-08T22:12:26.510723Z","end":"2026-07-08T22:12:26.724622Z","steps":["trace[1933137097] 'agreement among raft nodes before linearized reading' (duration: 213.818477ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:12:43.374739Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15522} {"level":"info","ts":"2026-07-08T22:12:43.474016Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15522,"took":"96.008945ms","hash":3794301237,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":32374784,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-08T22:12:43.474138Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3794301237,"revision":15522,"compact-revision":13011} {"level":"info","ts":"2026-07-08T22:15:46.42874Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e2b5783e11defe7e","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-08T22:15:46.434483Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-08T22:15:46.434575Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-08T22:17:43.38259Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17946} {"level":"info","ts":"2026-07-08T22:17:43.502685Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17946,"took":"114.891699ms","hash":2631243860,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":30285824,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-08T22:17:43.50274Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2631243860,"revision":17946,"compact-revision":15522} {"level":"info","ts":"2026-07-08T22:18:05.686357Z","caller":"traceutil/trace.go:171","msg":"trace[1432860477] transaction","detail":"{read_only:false; response_revision:20330; number_of_response:1; }","duration":"147.691154ms","start":"2026-07-08T22:18:05.538628Z","end":"2026-07-08T22:18:05.686319Z","steps":["trace[1432860477] 'process raft request' (duration: 147.4508ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:18:05.704903Z","caller":"traceutil/trace.go:171","msg":"trace[1819829511] linearizableReadLoop","detail":"{readStateIndex:21012; appliedIndex:21010; }","duration":"138.683492ms","start":"2026-07-08T22:18:05.56619Z","end":"2026-07-08T22:18:05.704873Z","steps":["trace[1819829511] 'read index received' (duration: 119.943716ms)","trace[1819829511] 'applied index is now lower than readState.Index' (duration: 18.737426ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T22:18:05.705083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.856315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:18:05.705062Z","caller":"traceutil/trace.go:171","msg":"trace[339123433] transaction","detail":"{read_only:false; response_revision:20331; number_of_response:1; }","duration":"144.810719ms","start":"2026-07-08T22:18:05.560207Z","end":"2026-07-08T22:18:05.705018Z","steps":["trace[339123433] 'process raft request' (duration: 144.476133ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:18:05.705131Z","caller":"traceutil/trace.go:171","msg":"trace[1149264250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20331; }","duration":"138.987388ms","start":"2026-07-08T22:18:05.566131Z","end":"2026-07-08T22:18:05.705118Z","steps":["trace[1149264250] 'agreement among raft nodes before linearized reading' (duration: 138.875186ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:18:06.206062Z","caller":"traceutil/trace.go:171","msg":"trace[590039798] transaction","detail":"{read_only:false; response_revision:20332; number_of_response:1; }","duration":"119.725552ms","start":"2026-07-08T22:18:06.086306Z","end":"2026-07-08T22:18:06.206032Z","steps":["trace[590039798] 'process raft request' (duration: 119.528878ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:18:06.272686Z","caller":"traceutil/trace.go:171","msg":"trace[429257419] transaction","detail":"{read_only:false; response_revision:20334; number_of_response:1; }","duration":"106.256073ms","start":"2026-07-08T22:18:06.166405Z","end":"2026-07-08T22:18:06.272661Z","steps":["trace[429257419] 'process raft request' (duration: 106.179132ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:18:06.272828Z","caller":"traceutil/trace.go:171","msg":"trace[951912778] transaction","detail":"{read_only:false; response_revision:20333; number_of_response:1; }","duration":"177.225607ms","start":"2026-07-08T22:18:06.095551Z","end":"2026-07-08T22:18:06.272777Z","steps":["trace[951912778] 'process raft request' (duration: 176.91117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:18:06.552122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.443549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T22:18:06.552319Z","caller":"traceutil/trace.go:171","msg":"trace[434853246] transaction","detail":"{read_only:false; response_revision:20335; number_of_response:1; }","duration":"321.861082ms","start":"2026-07-08T22:18:06.230421Z","end":"2026-07-08T22:18:06.552282Z","steps":["trace[434853246] 'process raft request' (duration: 89.085927ms)","trace[434853246] 'compare' (duration: 231.95448ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:18:06.552445Z","caller":"traceutil/trace.go:171","msg":"trace[1463667997] linearizableReadLoop","detail":"{readStateIndex:21016; appliedIndex:21015; }","duration":"118.397725ms","start":"2026-07-08T22:18:06.433853Z","end":"2026-07-08T22:18:06.552251Z","steps":["trace[1463667997] 'read index received' (duration: 56.781µs)","trace[1463667997] 'applied index is now lower than readState.Index' (duration: 118.338744ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T22:18:06.552502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:18:06.230401Z","time spent":"322.003265ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T22:18:06.552684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.802472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.30\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-07-08T22:18:06.55278Z","caller":"traceutil/trace.go:171","msg":"trace[865832841] range","detail":"{range_begin:/registry/masterleases/199.19.213.30; range_end:; response_count:1; response_revision:20335; }","duration":"118.908465ms","start":"2026-07-08T22:18:06.433846Z","end":"2026-07-08T22:18:06.552755Z","steps":["trace[865832841] 'agreement among raft nodes before linearized reading' (duration: 118.622359ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:18:06.75635Z","caller":"traceutil/trace.go:171","msg":"trace[956337924] linearizableReadLoop","detail":"{readStateIndex:21017; appliedIndex:21016; }","duration":"189.99279ms","start":"2026-07-08T22:18:06.566319Z","end":"2026-07-08T22:18:06.756312Z","steps":["trace[956337924] 'read index received' (duration: 122.236519ms)","trace[956337924] 'applied index is now lower than readState.Index' (duration: 67.751861ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:18:06.756379Z","caller":"traceutil/trace.go:171","msg":"trace[1175938845] transaction","detail":"{read_only:false; response_revision:20336; number_of_response:1; }","duration":"182.504577ms","start":"2026-07-08T22:18:06.573853Z","end":"2026-07-08T22:18:06.756357Z","steps":["trace[1175938845] 'process raft request' (duration: 182.367574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:18:06.756528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.193184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:18:06.756569Z","caller":"traceutil/trace.go:171","msg":"trace[188857233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20336; }","duration":"190.364937ms","start":"2026-07-08T22:18:06.566195Z","end":"2026-07-08T22:18:06.75656Z","steps":["trace[188857233] 'agreement among raft nodes before linearized reading' (duration: 190.269395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:18:06.756687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.504672ms","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-07-08T22:18:06.756736Z","caller":"traceutil/trace.go:171","msg":"trace[144290761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20336; }","duration":"187.662215ms","start":"2026-07-08T22:18:06.569062Z","end":"2026-07-08T22:18:06.756724Z","steps":["trace[144290761] 'agreement among raft nodes before linearized reading' (duration: 187.437481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:18:06.756773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.885752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-08T22:18:06.756776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.206929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T22:18:06.756801Z","caller":"traceutil/trace.go:171","msg":"trace[1553449379] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20336; }","duration":"185.936073ms","start":"2026-07-08T22:18:06.570858Z","end":"2026-07-08T22:18:06.756794Z","steps":["trace[1553449379] 'agreement among raft nodes before linearized reading' (duration: 185.851731ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:18:06.756806Z","caller":"traceutil/trace.go:171","msg":"trace[1971407581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20336; }","duration":"184.27025ms","start":"2026-07-08T22:18:06.572528Z","end":"2026-07-08T22:18:06.756798Z","steps":["trace[1971407581] 'agreement among raft nodes before linearized reading' (duration: 184.161328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:18:06.756889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.530631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-08T22:18:06.756989Z","caller":"traceutil/trace.go:171","msg":"trace[840022319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20336; }","duration":"146.668114ms","start":"2026-07-08T22:18:06.610294Z","end":"2026-07-08T22:18:06.756962Z","steps":["trace[840022319] 'agreement among raft nodes before linearized reading' (duration: 146.342568ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:18:06.947042Z","caller":"traceutil/trace.go:171","msg":"trace[598975014] transaction","detail":"{read_only:false; response_revision:20337; number_of_response:1; }","duration":"189.397978ms","start":"2026-07-08T22:18:06.757614Z","end":"2026-07-08T22:18:06.947012Z","steps":["trace[598975014] 'process raft request' (duration: 135.656825ms)","trace[598975014] 'compare' (duration: 53.608971ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:18:06.94821Z","caller":"traceutil/trace.go:171","msg":"trace[491176804] transaction","detail":"{read_only:false; response_revision:20338; number_of_response:1; }","duration":"187.418941ms","start":"2026-07-08T22:18:06.760774Z","end":"2026-07-08T22:18:06.948193Z","steps":["trace[491176804] 'process raft request' (duration: 187.221187ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:18:06.948558Z","caller":"traceutil/trace.go:171","msg":"trace[160426076] transaction","detail":"{read_only:false; response_revision:20339; number_of_response:1; }","duration":"187.35607ms","start":"2026-07-08T22:18:06.761172Z","end":"2026-07-08T22:18:06.948528Z","steps":["trace[160426076] 'process raft request' (duration: 186.979383ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:18:06.948677Z","caller":"traceutil/trace.go:171","msg":"trace[1377823903] transaction","detail":"{read_only:false; response_revision:20341; number_of_response:1; }","duration":"186.458023ms","start":"2026-07-08T22:18:06.762184Z","end":"2026-07-08T22:18:06.948642Z","steps":["trace[1377823903] 'process raft request' (duration: 186.389122ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:18:06.948688Z","caller":"traceutil/trace.go:171","msg":"trace[1660812569] transaction","detail":"{read_only:false; response_revision:20340; number_of_response:1; }","duration":"187.438531ms","start":"2026-07-08T22:18:06.761224Z","end":"2026-07-08T22:18:06.948662Z","steps":["trace[1660812569] 'process raft request' (duration: 187.202987ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:22:43.392171Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20213} {"level":"info","ts":"2026-07-08T22:22:43.504709Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20213,"took":"109.465501ms","hash":3897073145,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":42504192,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-07-08T22:22:43.504794Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3897073145,"revision":20213,"compact-revision":17946} {"level":"warn","ts":"2026-07-08T22:25:26.975384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.830884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T22:25:26.975712Z","caller":"traceutil/trace.go:171","msg":"trace[556833344] transaction","detail":"{read_only:false; response_revision:24884; number_of_response:1; }","duration":"346.325935ms","start":"2026-07-08T22:25:26.629373Z","end":"2026-07-08T22:25:26.975699Z","steps":["trace[556833344] 'process raft request' (duration: 86.057603ms)","trace[556833344] 'compare' (duration: 259.401166ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T22:25:26.975747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:26.629344Z","time spent":"346.386237ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T22:25:27.18106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.53494ms","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-07-08T22:25:27.181173Z","caller":"traceutil/trace.go:171","msg":"trace[327438183] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:24884; }","duration":"101.695443ms","start":"2026-07-08T22:25:27.079453Z","end":"2026-07-08T22:25:27.181148Z","steps":["trace[327438183] 'count revisions from in-memory index tree' (duration: 101.468317ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:27.535212Z","caller":"traceutil/trace.go:171","msg":"trace[1187946673] linearizableReadLoop","detail":"{readStateIndex:25694; appliedIndex:25693; }","duration":"102.761463ms","start":"2026-07-08T22:25:27.43242Z","end":"2026-07-08T22:25:27.535182Z","steps":["trace[1187946673] 'read index received' (duration: 102.519908ms)","trace[1187946673] 'applied index is now lower than readState.Index' (duration: 240.175µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:27.535352Z","caller":"traceutil/trace.go:171","msg":"trace[2056946310] transaction","detail":"{read_only:false; response_revision:24885; number_of_response:1; }","duration":"167.285306ms","start":"2026-07-08T22:25:27.368054Z","end":"2026-07-08T22:25:27.53534Z","steps":["trace[2056946310] 'process raft request' (duration: 166.922289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:27.535651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.219821ms","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-07-08T22:25:27.535685Z","caller":"traceutil/trace.go:171","msg":"trace[1699182532] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24885; }","duration":"103.268982ms","start":"2026-07-08T22:25:27.432403Z","end":"2026-07-08T22:25:27.535672Z","steps":["trace[1699182532] 'agreement among raft nodes before linearized reading' (duration: 103.075668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:27.535928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.598841ms","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-07-08T22:25:27.536034Z","caller":"traceutil/trace.go:171","msg":"trace[619032424] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24885; }","duration":"100.765555ms","start":"2026-07-08T22:25:27.435245Z","end":"2026-07-08T22:25:27.53601Z","steps":["trace[619032424] 'agreement among raft nodes before linearized reading' (duration: 100.53074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:27.536163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.818506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T22:25:27.536188Z","caller":"traceutil/trace.go:171","msg":"trace[1275751867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24885; }","duration":"100.860657ms","start":"2026-07-08T22:25:27.435322Z","end":"2026-07-08T22:25:27.536183Z","steps":["trace[1275751867] 'agreement among raft nodes before linearized reading' (duration: 100.801606ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:27.741743Z","caller":"traceutil/trace.go:171","msg":"trace[593807759] transaction","detail":"{read_only:false; response_revision:24886; number_of_response:1; }","duration":"201.246264ms","start":"2026-07-08T22:25:27.54047Z","end":"2026-07-08T22:25:27.741716Z","steps":["trace[593807759] 'process raft request' (duration: 200.805246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:28.036726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.032452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T22:25:28.037014Z","caller":"traceutil/trace.go:171","msg":"trace[204261601] linearizableReadLoop","detail":"{readStateIndex:25698; appliedIndex:25694; }","duration":"472.210921ms","start":"2026-07-08T22:25:27.564784Z","end":"2026-07-08T22:25:28.036995Z","steps":["trace[204261601] 'read index received' (duration: 176.429571ms)","trace[204261601] 'applied index is now lower than readState.Index' (duration: 295.7802ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T22:25:28.037306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.514797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:25:28.037292Z","caller":"traceutil/trace.go:171","msg":"trace[300961471] transaction","detail":"{read_only:false; response_revision:24889; number_of_response:1; }","duration":"494.526368ms","start":"2026-07-08T22:25:27.542716Z","end":"2026-07-08T22:25:28.037242Z","steps":["trace[300961471] 'process raft request' (duration: 494.233472ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:28.037354Z","caller":"traceutil/trace.go:171","msg":"trace[1194778094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24889; }","duration":"472.560378ms","start":"2026-07-08T22:25:27.564779Z","end":"2026-07-08T22:25:28.037339Z","steps":["trace[1194778094] 'agreement among raft nodes before linearized reading' (duration: 472.484987ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:28.037395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:27.564727Z","time spent":"472.66002ms","remote":"127.0.0.1:55388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T22:25:28.037492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:27.542707Z","time spent":"494.678641ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T22:25:28.037768Z","caller":"traceutil/trace.go:171","msg":"trace[1314241723] transaction","detail":"{read_only:false; response_revision:24887; number_of_response:1; }","duration":"496.347573ms","start":"2026-07-08T22:25:27.541405Z","end":"2026-07-08T22:25:28.037752Z","steps":["trace[1314241723] 'process raft request' (duration: 307.151508ms)","trace[1314241723] 'compare' (duration: 187.831979ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T22:25:28.037878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:27.541396Z","time spent":"496.409464ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T22:25:28.037867Z","caller":"traceutil/trace.go:171","msg":"trace[1676701532] transaction","detail":"{read_only:false; response_revision:24888; number_of_response:1; }","duration":"495.944114ms","start":"2026-07-08T22:25:27.541878Z","end":"2026-07-08T22:25:28.037822Z","steps":["trace[1676701532] 'process raft request' (duration: 494.961975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:28.038103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:27.54184Z","time spent":"496.137068ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T22:25:28.041333Z","caller":"traceutil/trace.go:171","msg":"trace[749028920] transaction","detail":"{read_only:false; response_revision:24890; number_of_response:1; }","duration":"145.04251ms","start":"2026-07-08T22:25:27.896275Z","end":"2026-07-08T22:25:28.041317Z","steps":["trace[749028920] 'process raft request' (duration: 144.876877ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:28.272858Z","caller":"traceutil/trace.go:171","msg":"trace[1395319802] transaction","detail":"{read_only:false; response_revision:24891; number_of_response:1; }","duration":"370.995747ms","start":"2026-07-08T22:25:27.901825Z","end":"2026-07-08T22:25:28.27282Z","steps":["trace[1395319802] 'process raft request' (duration: 360.594998ms)","trace[1395319802] 'compare' (duration: 10.178395ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T22:25:28.273068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:27.901796Z","time spent":"371.15649ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T22:25:28.273115Z","caller":"traceutil/trace.go:171","msg":"trace[131545917] linearizableReadLoop","detail":"{readStateIndex:25701; appliedIndex:25698; }","duration":"236.019309ms","start":"2026-07-08T22:25:28.037059Z","end":"2026-07-08T22:25:28.273078Z","steps":["trace[131545917] 'read index received' (duration: 4.224091ms)","trace[131545917] 'applied index is now lower than readState.Index' (duration: 231.792428ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:28.273125Z","caller":"traceutil/trace.go:171","msg":"trace[788691713] transaction","detail":"{read_only:false; response_revision:24892; number_of_response:1; }","duration":"253.950811ms","start":"2026-07-08T22:25:28.019136Z","end":"2026-07-08T22:25:28.273087Z","steps":["trace[788691713] 'process raft request' (duration: 253.637426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:28.273284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.063955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-08T22:25:28.273363Z","caller":"traceutil/trace.go:171","msg":"trace[720598231] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24892; }","duration":"240.210348ms","start":"2026-07-08T22:25:28.033134Z","end":"2026-07-08T22:25:28.273345Z","steps":["trace[720598231] 'agreement among raft nodes before linearized reading' (duration: 239.956013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:28.273386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.417407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-07-08T22:25:28.273425Z","caller":"traceutil/trace.go:171","msg":"trace[1754883108] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:24892; }","duration":"358.512548ms","start":"2026-07-08T22:25:27.914899Z","end":"2026-07-08T22:25:28.273412Z","steps":["trace[1754883108] 'agreement among raft nodes before linearized reading' (duration: 358.330015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:28.273408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.499386ms","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-07-08T22:25:28.273455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:27.914878Z","time spent":"358.57166ms","remote":"127.0.0.1:55508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":1589,"request content":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" "} {"level":"info","ts":"2026-07-08T22:25:28.273476Z","caller":"traceutil/trace.go:171","msg":"trace[712109531] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24892; }","duration":"238.659098ms","start":"2026-07-08T22:25:28.0348Z","end":"2026-07-08T22:25:28.273459Z","steps":["trace[712109531] 'agreement among raft nodes before linearized reading' (duration: 238.434474ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:28.273522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.66572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-08T22:25:28.273534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.337182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-c65997cf5-wvll5\" ","response":"range_response_count:1 size:8646"} {"level":"info","ts":"2026-07-08T22:25:28.27355Z","caller":"traceutil/trace.go:171","msg":"trace[222370952] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:24892; }","duration":"671.778673ms","start":"2026-07-08T22:25:27.601763Z","end":"2026-07-08T22:25:28.273542Z","steps":["trace[222370952] 'agreement among raft nodes before linearized reading' (duration: 671.725522ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:28.273573Z","caller":"traceutil/trace.go:171","msg":"trace[1676322236] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-c65997cf5-wvll5; range_end:; response_count:1; response_revision:24892; }","duration":"473.445084ms","start":"2026-07-08T22:25:27.800119Z","end":"2026-07-08T22:25:28.273564Z","steps":["trace[1676322236] 'agreement among raft nodes before linearized reading' (duration: 473.290721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:28.273578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:27.60174Z","time spent":"671.830244ms","remote":"127.0.0.1:50664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "} {"level":"warn","ts":"2026-07-08T22:25:28.273603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:27.800089Z","time spent":"473.509136ms","remote":"127.0.0.1:55538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":8669,"request content":"key:\"/registry/pods/openstack/magnum-api-c65997cf5-wvll5\" "} {"level":"info","ts":"2026-07-08T22:25:28.277542Z","caller":"traceutil/trace.go:171","msg":"trace[901611723] transaction","detail":"{read_only:false; response_revision:24893; number_of_response:1; }","duration":"106.329701ms","start":"2026-07-08T22:25:28.171201Z","end":"2026-07-08T22:25:28.277531Z","steps":["trace[901611723] 'process raft request' (duration: 106.217889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:28.511699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.424577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T22:25:28.511822Z","caller":"traceutil/trace.go:171","msg":"trace[1759595183] linearizableReadLoop","detail":"{readStateIndex:25703; appliedIndex:25702; }","duration":"238.604058ms","start":"2026-07-08T22:25:28.273197Z","end":"2026-07-08T22:25:28.511801Z","steps":["trace[1759595183] 'read index received' (duration: 4.667729ms)","trace[1759595183] 'applied index is now lower than readState.Index' (duration: 233.934809ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:28.512136Z","caller":"traceutil/trace.go:171","msg":"trace[260292724] transaction","detail":"{read_only:false; response_revision:24894; number_of_response:1; }","duration":"246.869327ms","start":"2026-07-08T22:25:28.265247Z","end":"2026-07-08T22:25:28.512116Z","steps":["trace[260292724] 'process raft request' (duration: 143.854319ms)","trace[260292724] 'compare' (duration: 102.279513ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T22:25:28.512632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.575767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:25:28.512698Z","caller":"traceutil/trace.go:171","msg":"trace[1665537496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24894; }","duration":"473.701919ms","start":"2026-07-08T22:25:28.03898Z","end":"2026-07-08T22:25:28.512682Z","steps":["trace[1665537496] 'agreement among raft nodes before linearized reading' (duration: 473.567067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:28.512732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:28.038963Z","time spent":"473.76312ms","remote":"127.0.0.1:55380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T22:25:28.812711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.116208ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T22:25:28.813161Z","caller":"traceutil/trace.go:171","msg":"trace[1595505472] linearizableReadLoop","detail":"{readStateIndex:25706; appliedIndex:25703; }","duration":"301.276785ms","start":"2026-07-08T22:25:28.511855Z","end":"2026-07-08T22:25:28.813132Z","steps":["trace[1595505472] 'read index received' (duration: 151.639357ms)","trace[1595505472] 'applied index is now lower than readState.Index' (duration: 149.629638ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:28.81368Z","caller":"traceutil/trace.go:171","msg":"trace[1529098837] transaction","detail":"{read_only:false; response_revision:24895; number_of_response:1; }","duration":"535.818967ms","start":"2026-07-08T22:25:28.277818Z","end":"2026-07-08T22:25:28.813637Z","steps":["trace[1529098837] 'process raft request' (duration: 385.649858ms)","trace[1529098837] 'compare' (duration: 148.956625ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:28.81369Z","caller":"traceutil/trace.go:171","msg":"trace[1634053502] transaction","detail":"{read_only:false; response_revision:24898; number_of_response:1; }","duration":"237.922975ms","start":"2026-07-08T22:25:28.57571Z","end":"2026-07-08T22:25:28.813633Z","steps":["trace[1634053502] 'process raft request' (duration: 237.339374ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:28.813712Z","caller":"traceutil/trace.go:171","msg":"trace[337156418] transaction","detail":"{read_only:false; response_revision:24896; number_of_response:1; }","duration":"527.570959ms","start":"2026-07-08T22:25:28.286082Z","end":"2026-07-08T22:25:28.813653Z","steps":["trace[337156418] 'process raft request' (duration: 526.751713ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:28.813825Z","caller":"traceutil/trace.go:171","msg":"trace[1204997105] transaction","detail":"{read_only:false; response_revision:24897; number_of_response:1; }","duration":"346.827846ms","start":"2026-07-08T22:25:28.466964Z","end":"2026-07-08T22:25:28.813792Z","steps":["trace[1204997105] 'process raft request' (duration: 345.998381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:28.813853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:28.27779Z","time spent":"535.959739ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T22:25:28.813934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:28.286056Z","time spent":"527.753872ms","remote":"127.0.0.1:55538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8444,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T22:25:28.813929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.070429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11795"} {"level":"warn","ts":"2026-07-08T22:25:28.813988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:28.46693Z","time spent":"346.971278ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T22:25:28.81401Z","caller":"traceutil/trace.go:171","msg":"trace[2123246681] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24898; }","duration":"448.229692ms","start":"2026-07-08T22:25:28.36576Z","end":"2026-07-08T22:25:28.813989Z","steps":["trace[2123246681] 'agreement among raft nodes before linearized reading' (duration: 447.933047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:28.814058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:28.365736Z","time spent":"448.312484ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11818,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-07-08T22:25:28.814044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.391629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-fb6654b0-2813-4cac-a3c0-1cd3568d681c\" ","response":"range_response_count:1 size:1284"} {"level":"warn","ts":"2026-07-08T22:25:28.814097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.128752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-08T22:25:28.814165Z","caller":"traceutil/trace.go:171","msg":"trace[958359657] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24898; }","duration":"536.219543ms","start":"2026-07-08T22:25:28.277931Z","end":"2026-07-08T22:25:28.81415Z","steps":["trace[958359657] 'agreement among raft nodes before linearized reading' (duration: 535.944708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:28.814206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:28.277915Z","time spent":"536.282355ms","remote":"127.0.0.1:55538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-08T22:25:28.814187Z","caller":"traceutil/trace.go:171","msg":"trace[906401915] range","detail":"{range_begin:/registry/persistentvolumes/pvc-fb6654b0-2813-4cac-a3c0-1cd3568d681c; range_end:; response_count:1; response_revision:24898; }","duration":"533.563113ms","start":"2026-07-08T22:25:28.28058Z","end":"2026-07-08T22:25:28.814143Z","steps":["trace[906401915] 'agreement among raft nodes before linearized reading' (duration: 533.197616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:28.814291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:28.28057Z","time spent":"533.706116ms","remote":"127.0.0.1:55494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1307,"request content":"key:\"/registry/persistentvolumes/pvc-fb6654b0-2813-4cac-a3c0-1cd3568d681c\" "} {"level":"warn","ts":"2026-07-08T22:25:28.814837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.418707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:25:28.81489Z","caller":"traceutil/trace.go:171","msg":"trace[2008852621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24898; }","duration":"248.523618ms","start":"2026-07-08T22:25:28.566356Z","end":"2026-07-08T22:25:28.814879Z","steps":["trace[2008852621] 'agreement among raft nodes before linearized reading' (duration: 248.445567ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:34.194974Z","caller":"traceutil/trace.go:171","msg":"trace[1381558114] linearizableReadLoop","detail":"{readStateIndex:25752; appliedIndex:25751; }","duration":"123.816075ms","start":"2026-07-08T22:25:34.071135Z","end":"2026-07-08T22:25:34.194951Z","steps":["trace[1381558114] 'read index received' (duration: 123.612281ms)","trace[1381558114] 'applied index is now lower than readState.Index' (duration: 202.744µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:34.195282Z","caller":"traceutil/trace.go:171","msg":"trace[349353564] transaction","detail":"{read_only:false; response_revision:24942; number_of_response:1; }","duration":"129.613167ms","start":"2026-07-08T22:25:34.065654Z","end":"2026-07-08T22:25:34.195267Z","steps":["trace[349353564] 'process raft request' (duration: 129.155047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:34.195609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.424657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-08T22:25:34.195648Z","caller":"traceutil/trace.go:171","msg":"trace[661756321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24942; }","duration":"124.54223ms","start":"2026-07-08T22:25:34.071095Z","end":"2026-07-08T22:25:34.195637Z","steps":["trace[661756321] 'agreement among raft nodes before linearized reading' (duration: 124.365676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:34.195856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.62533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-08T22:25:34.195898Z","caller":"traceutil/trace.go:171","msg":"trace[496956189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24942; }","duration":"124.676031ms","start":"2026-07-08T22:25:34.071213Z","end":"2026-07-08T22:25:34.195889Z","steps":["trace[496956189] 'agreement among raft nodes before linearized reading' (duration: 124.579479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:34.1962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.728569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-07-08T22:25:34.196235Z","caller":"traceutil/trace.go:171","msg":"trace[1580282909] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24942; }","duration":"117.78148ms","start":"2026-07-08T22:25:34.07844Z","end":"2026-07-08T22:25:34.196222Z","steps":["trace[1580282909] 'agreement among raft nodes before linearized reading' (duration: 117.681428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:34.196453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.172611ms","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-07-08T22:25:34.196482Z","caller":"traceutil/trace.go:171","msg":"trace[1515642640] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24942; }","duration":"125.215233ms","start":"2026-07-08T22:25:34.071258Z","end":"2026-07-08T22:25:34.196473Z","steps":["trace[1515642640] 'agreement among raft nodes before linearized reading' (duration: 125.136211ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:34.478254Z","caller":"traceutil/trace.go:171","msg":"trace[1037259861] transaction","detail":"{read_only:false; response_revision:24943; number_of_response:1; }","duration":"277.821848ms","start":"2026-07-08T22:25:34.200399Z","end":"2026-07-08T22:25:34.47822Z","steps":["trace[1037259861] 'process raft request' (duration: 277.587103ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:34.521829Z","caller":"traceutil/trace.go:171","msg":"trace[1609424126] transaction","detail":"{read_only:false; response_revision:24948; number_of_response:1; }","duration":"215.426645ms","start":"2026-07-08T22:25:34.306376Z","end":"2026-07-08T22:25:34.521803Z","steps":["trace[1609424126] 'process raft request' (duration: 215.375784ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:34.522153Z","caller":"traceutil/trace.go:171","msg":"trace[1158369595] transaction","detail":"{read_only:false; response_revision:24944; number_of_response:1; }","duration":"319.955482ms","start":"2026-07-08T22:25:34.202189Z","end":"2026-07-08T22:25:34.522144Z","steps":["trace[1158369595] 'process raft request' (duration: 318.470423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:34.522257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:34.202179Z","time spent":"319.997792ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T22:25:34.522358Z","caller":"traceutil/trace.go:171","msg":"trace[42083851] transaction","detail":"{read_only:false; response_revision:24945; number_of_response:1; }","duration":"320.101464ms","start":"2026-07-08T22:25:34.20225Z","end":"2026-07-08T22:25:34.522352Z","steps":["trace[42083851] 'process raft request' (duration: 319.33794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:34.522399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:34.202209Z","time spent":"320.169256ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T22:25:34.522499Z","caller":"traceutil/trace.go:171","msg":"trace[1245580716] transaction","detail":"{read_only:false; response_revision:24946; number_of_response:1; }","duration":"227.426684ms","start":"2026-07-08T22:25:34.295053Z","end":"2026-07-08T22:25:34.522479Z","steps":["trace[1245580716] 'process raft request' (duration: 226.621469ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:34.52259Z","caller":"traceutil/trace.go:171","msg":"trace[1834472770] transaction","detail":"{read_only:false; response_revision:24947; number_of_response:1; }","duration":"216.75188ms","start":"2026-07-08T22:25:34.305832Z","end":"2026-07-08T22:25:34.522584Z","steps":["trace[1834472770] 'process raft request' (duration: 215.877964ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:34.655588Z","caller":"traceutil/trace.go:171","msg":"trace[1237077518] transaction","detail":"{read_only:false; response_revision:24949; number_of_response:1; }","duration":"115.560678ms","start":"2026-07-08T22:25:34.539978Z","end":"2026-07-08T22:25:34.655539Z","steps":["trace[1237077518] 'process raft request' (duration: 115.382454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:34.960738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.024556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T22:25:34.961033Z","caller":"traceutil/trace.go:171","msg":"trace[482360932] linearizableReadLoop","detail":"{readStateIndex:25760; appliedIndex:25759; }","duration":"118.999593ms","start":"2026-07-08T22:25:34.842004Z","end":"2026-07-08T22:25:34.961003Z","steps":["trace[482360932] 'read index received' (duration: 115.612408ms)","trace[482360932] 'applied index is now lower than readState.Index' (duration: 3.385675ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:34.961142Z","caller":"traceutil/trace.go:171","msg":"trace[1758564621] transaction","detail":"{read_only:false; response_revision:24950; number_of_response:1; }","duration":"124.462547ms","start":"2026-07-08T22:25:34.836622Z","end":"2026-07-08T22:25:34.961084Z","steps":["trace[1758564621] 'process raft request' (duration: 121.055602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:34.961263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.261568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-08T22:25:34.960962Z","caller":"traceutil/trace.go:171","msg":"trace[1153096777] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:24949; }","duration":"193.18906ms","start":"2026-07-08T22:25:34.767622Z","end":"2026-07-08T22:25:34.960811Z","steps":["trace[1153096777] 'count revisions from in-memory index tree' (duration: 192.904374ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:34.961342Z","caller":"traceutil/trace.go:171","msg":"trace[1727808194] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24950; }","duration":"119.397161ms","start":"2026-07-08T22:25:34.841928Z","end":"2026-07-08T22:25:34.961325Z","steps":["trace[1727808194] 'agreement among raft nodes before linearized reading' (duration: 119.165837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:35.223386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.020552ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T22:25:35.223604Z","caller":"traceutil/trace.go:171","msg":"trace[964041532] transaction","detail":"{read_only:false; response_revision:24952; number_of_response:1; }","duration":"219.707998ms","start":"2026-07-08T22:25:35.003877Z","end":"2026-07-08T22:25:35.223585Z","steps":["trace[964041532] 'process raft request' (duration: 219.623056ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:35.223819Z","caller":"traceutil/trace.go:171","msg":"trace[816629665] transaction","detail":"{read_only:false; response_revision:24951; number_of_response:1; }","duration":"256.851286ms","start":"2026-07-08T22:25:34.966952Z","end":"2026-07-08T22:25:35.223803Z","steps":["trace[816629665] 'process raft request' (duration: 124.309674ms)","trace[816629665] 'compare' (duration: 131.89233ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:36.141138Z","caller":"traceutil/trace.go:171","msg":"trace[1127703298] linearizableReadLoop","detail":"{readStateIndex:25764; appliedIndex:25763; }","duration":"208.244207ms","start":"2026-07-08T22:25:35.93286Z","end":"2026-07-08T22:25:36.141104Z","steps":["trace[1127703298] 'read index received' (duration: 208.028373ms)","trace[1127703298] 'applied index is now lower than readState.Index' (duration: 214.354µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:36.141285Z","caller":"traceutil/trace.go:171","msg":"trace[602914500] transaction","detail":"{read_only:false; response_revision:24954; number_of_response:1; }","duration":"252.744889ms","start":"2026-07-08T22:25:35.888491Z","end":"2026-07-08T22:25:36.141236Z","steps":["trace[602914500] 'process raft request' (duration: 252.413862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:36.141479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.20342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-08T22:25:36.141481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.359871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T22:25:36.141536Z","caller":"traceutil/trace.go:171","msg":"trace[1692631512] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:24954; }","duration":"149.314433ms","start":"2026-07-08T22:25:35.992202Z","end":"2026-07-08T22:25:36.141516Z","steps":["trace[1692631512] 'agreement among raft nodes before linearized reading' (duration: 149.190091ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:36.141581Z","caller":"traceutil/trace.go:171","msg":"trace[2101812221] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:24954; }","duration":"208.552364ms","start":"2026-07-08T22:25:35.933006Z","end":"2026-07-08T22:25:36.141559Z","steps":["trace[2101812221] 'agreement among raft nodes before linearized reading' (duration: 208.33326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:36.141869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.010442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T22:25:36.141913Z","caller":"traceutil/trace.go:171","msg":"trace[1852627610] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:24954; }","duration":"209.093184ms","start":"2026-07-08T22:25:35.93281Z","end":"2026-07-08T22:25:36.141903Z","steps":["trace[1852627610] 'agreement among raft nodes before linearized reading' (duration: 208.436892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:36.387303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.524913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T22:25:36.387476Z","caller":"traceutil/trace.go:171","msg":"trace[659901906] transaction","detail":"{read_only:false; response_revision:24955; number_of_response:1; }","duration":"240.035644ms","start":"2026-07-08T22:25:36.147413Z","end":"2026-07-08T22:25:36.387449Z","steps":["trace[659901906] 'process raft request' (duration: 131.233117ms)","trace[659901906] 'compare' (duration: 108.051944ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:36.414619Z","caller":"traceutil/trace.go:171","msg":"trace[260385589] transaction","detail":"{read_only:false; response_revision:24956; number_of_response:1; }","duration":"211.101913ms","start":"2026-07-08T22:25:36.2035Z","end":"2026-07-08T22:25:36.414602Z","steps":["trace[260385589] 'process raft request' (duration: 210.698585ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:36.414524Z","caller":"traceutil/trace.go:171","msg":"trace[684969917] linearizableReadLoop","detail":"{readStateIndex:25766; appliedIndex:25764; }","duration":"206.23713ms","start":"2026-07-08T22:25:36.208257Z","end":"2026-07-08T22:25:36.414494Z","steps":["trace[684969917] 'read index received' (duration: 70.481766ms)","trace[684969917] 'applied index is now lower than readState.Index' (duration: 135.754124ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T22:25:36.414869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.588137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:25:36.41496Z","caller":"traceutil/trace.go:171","msg":"trace[1631574939] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:24956; }","duration":"206.681249ms","start":"2026-07-08T22:25:36.208251Z","end":"2026-07-08T22:25:36.414932Z","steps":["trace[1631574939] 'agreement among raft nodes before linearized reading' (duration: 206.531506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:36.415018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.90268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:25:36.415068Z","caller":"traceutil/trace.go:171","msg":"trace[1866832964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24956; }","duration":"155.003911ms","start":"2026-07-08T22:25:36.260052Z","end":"2026-07-08T22:25:36.415056Z","steps":["trace[1866832964] 'agreement among raft nodes before linearized reading' (duration: 154.909209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:36.415319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.46922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:25:36.415392Z","caller":"traceutil/trace.go:171","msg":"trace[982701458] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:24956; }","duration":"166.971659ms","start":"2026-07-08T22:25:36.248404Z","end":"2026-07-08T22:25:36.415376Z","steps":["trace[982701458] 'agreement among raft nodes before linearized reading' (duration: 166.4945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:36.723724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.658295ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-07-08T22:25:36.723851Z","caller":"traceutil/trace.go:171","msg":"trace[467289091] linearizableReadLoop","detail":"{readStateIndex:25767; appliedIndex:25766; }","duration":"232.564323ms","start":"2026-07-08T22:25:36.491265Z","end":"2026-07-08T22:25:36.723829Z","steps":["trace[467289091] 'read index received' (duration: 88.804886ms)","trace[467289091] 'applied index is now lower than readState.Index' (duration: 143.757606ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T22:25:36.724285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.026701ms","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:8"} {"level":"info","ts":"2026-07-08T22:25:36.72438Z","caller":"traceutil/trace.go:171","msg":"trace[631343665] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:24956; }","duration":"233.142053ms","start":"2026-07-08T22:25:36.491218Z","end":"2026-07-08T22:25:36.72436Z","steps":["trace[631343665] 'agreement among raft nodes before linearized reading' (duration: 232.891629ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:36.743902Z","caller":"traceutil/trace.go:171","msg":"trace[325486012] transaction","detail":"{read_only:false; response_revision:24957; number_of_response:1; }","duration":"215.948495ms","start":"2026-07-08T22:25:36.527932Z","end":"2026-07-08T22:25:36.743881Z","steps":["trace[325486012] 'process raft request' (duration: 215.616099ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:36.744118Z","caller":"traceutil/trace.go:171","msg":"trace[1629958684] transaction","detail":"{read_only:false; response_revision:24959; number_of_response:1; }","duration":"208.651826ms","start":"2026-07-08T22:25:36.535448Z","end":"2026-07-08T22:25:36.7441Z","steps":["trace[1629958684] 'process raft request' (duration: 208.386901ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:36.743971Z","caller":"traceutil/trace.go:171","msg":"trace[393077736] transaction","detail":"{read_only:false; response_revision:24958; number_of_response:1; }","duration":"214.454777ms","start":"2026-07-08T22:25:36.529463Z","end":"2026-07-08T22:25:36.743918Z","steps":["trace[393077736] 'process raft request' (duration: 214.314424ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:36.978429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.452356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T22:25:36.978843Z","caller":"traceutil/trace.go:171","msg":"trace[1028650286] transaction","detail":"{read_only:false; response_revision:24960; number_of_response:1; }","duration":"317.325182ms","start":"2026-07-08T22:25:36.661472Z","end":"2026-07-08T22:25:36.978797Z","steps":["trace[1028650286] 'process raft request' (duration: 168.358626ms)","trace[1028650286] 'compare' (duration: 148.203471ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:36.978864Z","caller":"traceutil/trace.go:171","msg":"trace[1133050675] transaction","detail":"{read_only:false; response_revision:24961; number_of_response:1; }","duration":"253.410842ms","start":"2026-07-08T22:25:36.725406Z","end":"2026-07-08T22:25:36.978817Z","steps":["trace[1133050675] 'process raft request' (duration: 253.156027ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:36.97892Z","caller":"traceutil/trace.go:171","msg":"trace[2045150751] linearizableReadLoop","detail":"{readStateIndex:25771; appliedIndex:25767; }","duration":"254.94879ms","start":"2026-07-08T22:25:36.723948Z","end":"2026-07-08T22:25:36.978896Z","steps":["trace[2045150751] 'read index received' (duration: 19.665705ms)","trace[2045150751] 'applied index is now lower than readState.Index' (duration: 235.281205ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T22:25:36.979024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:36.661434Z","time spent":"317.487266ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T22:25:36.978866Z","caller":"traceutil/trace.go:171","msg":"trace[1569546301] transaction","detail":"{read_only:false; response_revision:24962; number_of_response:1; }","duration":"216.930764ms","start":"2026-07-08T22:25:36.761912Z","end":"2026-07-08T22:25:36.978843Z","steps":["trace[1569546301] 'process raft request' (duration: 216.865543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:36.979142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.706629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-08T22:25:36.979181Z","caller":"traceutil/trace.go:171","msg":"trace[182392463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24962; }","duration":"485.78368ms","start":"2026-07-08T22:25:36.493386Z","end":"2026-07-08T22:25:36.97917Z","steps":["trace[182392463] 'agreement among raft nodes before linearized reading' (duration: 485.614957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:36.97922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:36.493372Z","time spent":"485.839082ms","remote":"127.0.0.1:55604","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-07-08T22:25:36.979243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.928908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:25:36.979272Z","caller":"traceutil/trace.go:171","msg":"trace[230842769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24962; }","duration":"412.97219ms","start":"2026-07-08T22:25:36.566288Z","end":"2026-07-08T22:25:36.97926Z","steps":["trace[230842769] 'agreement among raft nodes before linearized reading' (duration: 412.915029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:36.979298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:36.566278Z","time spent":"413.01554ms","remote":"127.0.0.1:55380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-08T22:25:36.979387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.892913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-08T22:25:36.9794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.495863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:25:36.979453Z","caller":"traceutil/trace.go:171","msg":"trace[1183375584] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:24962; }","duration":"286.612415ms","start":"2026-07-08T22:25:36.692824Z","end":"2026-07-08T22:25:36.979436Z","steps":["trace[1183375584] 'agreement among raft nodes before linearized reading' (duration: 286.483923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:36.979642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.912872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-08T22:25:36.979675Z","caller":"traceutil/trace.go:171","msg":"trace[1152546144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24962; }","duration":"453.967852ms","start":"2026-07-08T22:25:36.525696Z","end":"2026-07-08T22:25:36.979664Z","steps":["trace[1152546144] 'agreement among raft nodes before linearized reading' (duration: 453.813049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:36.979697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:36.525677Z","time spent":"454.013464ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-08T22:25:36.979719Z","caller":"traceutil/trace.go:171","msg":"trace[2085331290] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24962; }","duration":"451.940134ms","start":"2026-07-08T22:25:36.52747Z","end":"2026-07-08T22:25:36.97941Z","steps":["trace[2085331290] 'agreement among raft nodes before linearized reading' (duration: 451.847952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:36.979786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:36.52746Z","time spent":"452.313371ms","remote":"127.0.0.1:55604","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-07-08T22:25:37.319484Z","caller":"traceutil/trace.go:171","msg":"trace[524730939] linearizableReadLoop","detail":"{readStateIndex:25776; appliedIndex:25775; }","duration":"333.49866ms","start":"2026-07-08T22:25:36.985951Z","end":"2026-07-08T22:25:37.31945Z","steps":["trace[524730939] 'read index received' (duration: 26.230081ms)","trace[524730939] 'applied index is now lower than readState.Index' (duration: 307.266789ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:37.319705Z","caller":"traceutil/trace.go:171","msg":"trace[1953468243] transaction","detail":"{read_only:false; response_revision:24966; number_of_response:1; }","duration":"333.632753ms","start":"2026-07-08T22:25:36.986052Z","end":"2026-07-08T22:25:37.319685Z","steps":["trace[1953468243] 'process raft request' (duration: 333.339767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:37.319888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:36.986043Z","time spent":"333.707825ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T22:25:37.31996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.967269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-08T22:25:37.320063Z","caller":"traceutil/trace.go:171","msg":"trace[401784729] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:24966; }","duration":"334.086301ms","start":"2026-07-08T22:25:36.985949Z","end":"2026-07-08T22:25:37.320036Z","steps":["trace[401784729] 'agreement among raft nodes before linearized reading' (duration: 333.792086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:37.320112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:36.985924Z","time spent":"334.176464ms","remote":"127.0.0.1:55618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-07-08T22:25:37.320399Z","caller":"traceutil/trace.go:171","msg":"trace[1413385788] transaction","detail":"{read_only:false; response_revision:24965; number_of_response:1; }","duration":"333.953859ms","start":"2026-07-08T22:25:36.985538Z","end":"2026-07-08T22:25:37.319492Z","steps":["trace[1413385788] 'process raft request' (duration: 333.46204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:37.32069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:36.985529Z","time spent":"335.000219ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T22:25:37.348718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.260941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:25:37.348818Z","caller":"traceutil/trace.go:171","msg":"trace[320339408] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:24967; }","duration":"283.436374ms","start":"2026-07-08T22:25:37.065367Z","end":"2026-07-08T22:25:37.348804Z","steps":["trace[320339408] 'agreement among raft nodes before linearized reading' (duration: 283.278081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:37.348818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.772827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-08T22:25:37.348901Z","caller":"traceutil/trace.go:171","msg":"trace[1614838997] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24967; }","duration":"120.90989ms","start":"2026-07-08T22:25:37.227966Z","end":"2026-07-08T22:25:37.348876Z","steps":["trace[1614838997] 'agreement among raft nodes before linearized reading' (duration: 120.689106ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:37.348906Z","caller":"traceutil/trace.go:171","msg":"trace[964649008] transaction","detail":"{read_only:false; response_revision:24967; number_of_response:1; }","duration":"119.976513ms","start":"2026-07-08T22:25:37.228901Z","end":"2026-07-08T22:25:37.348877Z","steps":["trace[964649008] 'process raft request' (duration: 119.164967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:37.348936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.256903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:25:37.349035Z","caller":"traceutil/trace.go:171","msg":"trace[483990640] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:24967; }","duration":"337.404835ms","start":"2026-07-08T22:25:37.011605Z","end":"2026-07-08T22:25:37.34901Z","steps":["trace[483990640] 'agreement among raft nodes before linearized reading' (duration: 337.207291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:37.34909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:37.011549Z","time spent":"337.529207ms","remote":"127.0.0.1:32962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-07-08T22:25:37.761141Z","caller":"traceutil/trace.go:171","msg":"trace[452300385] linearizableReadLoop","detail":"{readStateIndex:25780; appliedIndex:25779; }","duration":"163.933051ms","start":"2026-07-08T22:25:37.597166Z","end":"2026-07-08T22:25:37.761099Z","steps":["trace[452300385] 'read index received' (duration: 163.423502ms)","trace[452300385] 'applied index is now lower than readState.Index' (duration: 507.899µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:37.761274Z","caller":"traceutil/trace.go:171","msg":"trace[946180364] transaction","detail":"{read_only:false; response_revision:24969; number_of_response:1; }","duration":"168.477509ms","start":"2026-07-08T22:25:37.592749Z","end":"2026-07-08T22:25:37.761227Z","steps":["trace[946180364] 'process raft request' (duration: 168.072731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:37.761379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.151697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:25:37.761485Z","caller":"traceutil/trace.go:171","msg":"trace[883104832] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:24969; }","duration":"164.364721ms","start":"2026-07-08T22:25:37.597097Z","end":"2026-07-08T22:25:37.761462Z","steps":["trace[883104832] 'agreement among raft nodes before linearized reading' (duration: 164.150557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:38.55159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.237663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:25:38.551718Z","caller":"traceutil/trace.go:171","msg":"trace[1615485839] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:24970; }","duration":"202.454606ms","start":"2026-07-08T22:25:38.349236Z","end":"2026-07-08T22:25:38.551691Z","steps":["trace[1615485839] 'count revisions from in-memory index tree' (duration: 202.141841ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:38.80204Z","caller":"traceutil/trace.go:171","msg":"trace[2058645503] linearizableReadLoop","detail":"{readStateIndex:25782; appliedIndex:25781; }","duration":"230.253129ms","start":"2026-07-08T22:25:38.571762Z","end":"2026-07-08T22:25:38.802016Z","steps":["trace[2058645503] 'read index received' (duration: 230.038465ms)","trace[2058645503] 'applied index is now lower than readState.Index' (duration: 213.714µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:38.802127Z","caller":"traceutil/trace.go:171","msg":"trace[1169142157] transaction","detail":"{read_only:false; response_revision:24971; number_of_response:1; }","duration":"380.324436ms","start":"2026-07-08T22:25:38.421774Z","end":"2026-07-08T22:25:38.802099Z","steps":["trace[1169142157] 'process raft request' (duration: 380.0324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:38.802255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.465983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:25:38.802326Z","caller":"traceutil/trace.go:171","msg":"trace[331355336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24971; }","duration":"230.598075ms","start":"2026-07-08T22:25:38.571714Z","end":"2026-07-08T22:25:38.802312Z","steps":["trace[331355336] 'agreement among raft nodes before linearized reading' (duration: 230.412842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:38.802325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:38.421703Z","time spent":"380.488559ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T22:25:38.883518Z","caller":"traceutil/trace.go:171","msg":"trace[1198841368] transaction","detail":"{read_only:false; response_revision:24973; number_of_response:1; }","duration":"133.052071ms","start":"2026-07-08T22:25:38.750444Z","end":"2026-07-08T22:25:38.883496Z","steps":["trace[1198841368] 'process raft request' (duration: 132.960619ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:38.883517Z","caller":"traceutil/trace.go:171","msg":"trace[1178366692] transaction","detail":"{read_only:false; response_revision:24972; number_of_response:1; }","duration":"133.054322ms","start":"2026-07-08T22:25:38.750417Z","end":"2026-07-08T22:25:38.883471Z","steps":["trace[1178366692] 'process raft request' (duration: 119.224078ms)","trace[1178366692] 'compare' (duration: 13.64587ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:38.883777Z","caller":"traceutil/trace.go:171","msg":"trace[1598663158] transaction","detail":"{read_only:false; response_revision:24974; number_of_response:1; }","duration":"133.174814ms","start":"2026-07-08T22:25:38.750566Z","end":"2026-07-08T22:25:38.883741Z","steps":["trace[1598663158] 'process raft request' (duration: 132.871298ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:39.232874Z","caller":"traceutil/trace.go:171","msg":"trace[1439410513] transaction","detail":"{read_only:false; response_revision:24976; number_of_response:1; }","duration":"247.796634ms","start":"2026-07-08T22:25:38.985053Z","end":"2026-07-08T22:25:39.23285Z","steps":["trace[1439410513] 'process raft request' (duration: 247.605259ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:39.234175Z","caller":"traceutil/trace.go:171","msg":"trace[1688987999] linearizableReadLoop","detail":"{readStateIndex:25788; appliedIndex:25786; }","duration":"217.337373ms","start":"2026-07-08T22:25:39.016804Z","end":"2026-07-08T22:25:39.234141Z","steps":["trace[1688987999] 'read index received' (duration: 215.903425ms)","trace[1688987999] 'applied index is now lower than readState.Index' (duration: 1.431848ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:39.234236Z","caller":"traceutil/trace.go:171","msg":"trace[410939837] transaction","detail":"{read_only:false; response_revision:24977; number_of_response:1; }","duration":"247.100821ms","start":"2026-07-08T22:25:38.987091Z","end":"2026-07-08T22:25:39.234191Z","steps":["trace[410939837] 'process raft request' (duration: 246.865176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:39.234452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.638899ms","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-07-08T22:25:39.234514Z","caller":"traceutil/trace.go:171","msg":"trace[2037127464] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24977; }","duration":"217.763301ms","start":"2026-07-08T22:25:39.016737Z","end":"2026-07-08T22:25:39.2345Z","steps":["trace[2037127464] 'agreement among raft nodes before linearized reading' (duration: 217.549597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:39.545313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.140749ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T22:25:39.545611Z","caller":"traceutil/trace.go:171","msg":"trace[1378127454] linearizableReadLoop","detail":"{readStateIndex:25791; appliedIndex:25789; }","duration":"209.516423ms","start":"2026-07-08T22:25:39.336083Z","end":"2026-07-08T22:25:39.545599Z","steps":["trace[1378127454] 'read index received' (duration: 70.040388ms)","trace[1378127454] 'applied index is now lower than readState.Index' (duration: 139.475255ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:39.545597Z","caller":"traceutil/trace.go:171","msg":"trace[1639754200] transaction","detail":"{read_only:false; response_revision:24979; number_of_response:1; }","duration":"216.73471ms","start":"2026-07-08T22:25:39.328826Z","end":"2026-07-08T22:25:39.545561Z","steps":["trace[1639754200] 'process raft request' (duration: 77.259056ms)","trace[1639754200] 'compare' (duration: 138.933754ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T22:25:39.54592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.956546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-08T22:25:39.545947Z","caller":"traceutil/trace.go:171","msg":"trace[179607241] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24981; }","duration":"160.015826ms","start":"2026-07-08T22:25:39.385924Z","end":"2026-07-08T22:25:39.54594Z","steps":["trace[179607241] 'agreement among raft nodes before linearized reading' (duration: 159.910594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:39.546055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.8973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-07-08T22:25:39.546123Z","caller":"traceutil/trace.go:171","msg":"trace[834954254] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24981; }","duration":"210.083623ms","start":"2026-07-08T22:25:39.336021Z","end":"2026-07-08T22:25:39.546104Z","steps":["trace[834954254] 'agreement among raft nodes before linearized reading' (duration: 209.816708ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:39.546123Z","caller":"traceutil/trace.go:171","msg":"trace[1901903520] transaction","detail":"{read_only:false; response_revision:24981; number_of_response:1; }","duration":"191.406206ms","start":"2026-07-08T22:25:39.354689Z","end":"2026-07-08T22:25:39.546095Z","steps":["trace[1901903520] 'process raft request' (duration: 190.879046ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:39.546123Z","caller":"traceutil/trace.go:171","msg":"trace[1483575690] transaction","detail":"{read_only:false; response_revision:24980; number_of_response:1; }","duration":"214.841864ms","start":"2026-07-08T22:25:39.331249Z","end":"2026-07-08T22:25:39.546091Z","steps":["trace[1483575690] 'process raft request' (duration: 214.157651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:39.852075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.811295ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T22:25:39.852281Z","caller":"traceutil/trace.go:171","msg":"trace[829465422] linearizableReadLoop","detail":"{readStateIndex:25793; appliedIndex:25792; }","duration":"188.197105ms","start":"2026-07-08T22:25:39.663989Z","end":"2026-07-08T22:25:39.852186Z","steps":["trace[829465422] 'read index received' (duration: 75.218106ms)","trace[829465422] 'applied index is now lower than readState.Index' (duration: 112.977559ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:39.852523Z","caller":"traceutil/trace.go:171","msg":"trace[1666667423] transaction","detail":"{read_only:false; response_revision:24982; number_of_response:1; }","duration":"301.321066ms","start":"2026-07-08T22:25:39.551147Z","end":"2026-07-08T22:25:39.852468Z","steps":["trace[1666667423] 'process raft request' (duration: 187.989361ms)","trace[1666667423] 'compare' (duration: 112.653653ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T22:25:39.852598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.570822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:25:39.852688Z","caller":"traceutil/trace.go:171","msg":"trace[1927543390] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:24982; }","duration":"188.723985ms","start":"2026-07-08T22:25:39.663944Z","end":"2026-07-08T22:25:39.852668Z","steps":["trace[1927543390] 'agreement among raft nodes before linearized reading' (duration: 188.525381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:39.852742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:39.551116Z","time spent":"301.48962ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T22:25:41.034238Z","caller":"traceutil/trace.go:171","msg":"trace[1260832648] transaction","detail":"{read_only:false; response_revision:24986; number_of_response:1; }","duration":"143.94816ms","start":"2026-07-08T22:25:40.890266Z","end":"2026-07-08T22:25:41.034214Z","steps":["trace[1260832648] 'process raft request' (duration: 143.756856ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:41.034571Z","caller":"traceutil/trace.go:171","msg":"trace[1197405784] transaction","detail":"{read_only:false; response_revision:24987; number_of_response:1; }","duration":"144.156773ms","start":"2026-07-08T22:25:40.890366Z","end":"2026-07-08T22:25:41.034523Z","steps":["trace[1197405784] 'process raft request' (duration: 143.810317ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:41.03465Z","caller":"traceutil/trace.go:171","msg":"trace[1394293031] transaction","detail":"{read_only:false; response_revision:24988; number_of_response:1; }","duration":"143.566982ms","start":"2026-07-08T22:25:40.891048Z","end":"2026-07-08T22:25:41.034615Z","steps":["trace[1394293031] 'process raft request' (duration: 143.392089ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:43.089242Z","caller":"traceutil/trace.go:171","msg":"trace[1923990526] transaction","detail":"{read_only:false; response_revision:24999; number_of_response:1; }","duration":"178.067872ms","start":"2026-07-08T22:25:42.911143Z","end":"2026-07-08T22:25:43.08921Z","steps":["trace[1923990526] 'process raft request' (duration: 177.850158ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:43.396525Z","caller":"traceutil/trace.go:171","msg":"trace[1141292714] transaction","detail":"{read_only:false; response_revision:25002; number_of_response:1; }","duration":"355.410938ms","start":"2026-07-08T22:25:43.041079Z","end":"2026-07-08T22:25:43.39649Z","steps":["trace[1141292714] 'process raft request' (duration: 355.355997ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:43.396637Z","caller":"traceutil/trace.go:171","msg":"trace[1042037131] transaction","detail":"{read_only:false; response_revision:25000; number_of_response:1; }","duration":"355.915639ms","start":"2026-07-08T22:25:43.04065Z","end":"2026-07-08T22:25:43.396566Z","steps":["trace[1042037131] 'process raft request' (duration: 304.789433ms)","trace[1042037131] 'compare' (duration: 50.752949ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T22:25:43.396734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:43.041042Z","time spent":"355.592542ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T22:25:43.396813Z","caller":"traceutil/trace.go:171","msg":"trace[342077882] transaction","detail":"{read_only:false; response_revision:25001; number_of_response:1; }","duration":"355.982461ms","start":"2026-07-08T22:25:43.040795Z","end":"2026-07-08T22:25:43.396777Z","steps":["trace[342077882] 'process raft request' (duration: 355.590843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:43.396921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:43.04062Z","time spent":"356.133014ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T22:25:43.39703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:43.040759Z","time spent":"356.129344ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T22:25:43.397698Z","caller":"traceutil/trace.go:171","msg":"trace[161369261] linearizableReadLoop","detail":"{readStateIndex:25816; appliedIndex:25811; }","duration":"119.780217ms","start":"2026-07-08T22:25:43.27789Z","end":"2026-07-08T22:25:43.39767Z","steps":["trace[161369261] 'read index received' (duration: 67.56701ms)","trace[161369261] 'applied index is now lower than readState.Index' (duration: 52.211747ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:43.398305Z","caller":"traceutil/trace.go:171","msg":"trace[455426796] transaction","detail":"{read_only:false; response_revision:25004; number_of_response:1; }","duration":"151.30375ms","start":"2026-07-08T22:25:43.246973Z","end":"2026-07-08T22:25:43.398277Z","steps":["trace[455426796] 'process raft request' (duration: 150.643847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:43.398786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.860499ms","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-07-08T22:25:43.3989Z","caller":"traceutil/trace.go:171","msg":"trace[157704781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25004; }","duration":"121.028921ms","start":"2026-07-08T22:25:43.277845Z","end":"2026-07-08T22:25:43.398874Z","steps":["trace[157704781] 'agreement among raft nodes before linearized reading' (duration: 119.93062ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:43.39775Z","caller":"traceutil/trace.go:171","msg":"trace[1172912096] transaction","detail":"{read_only:false; response_revision:25003; number_of_response:1; }","duration":"150.749749ms","start":"2026-07-08T22:25:43.246975Z","end":"2026-07-08T22:25:43.397724Z","steps":["trace[1172912096] 'process raft request' (duration: 150.475634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:51.784486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.914557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-85a20686-a256-4260-b97a-fb5e8594b54f\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-08T22:25:51.784589Z","caller":"traceutil/trace.go:171","msg":"trace[1629254424] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-85a20686-a256-4260-b97a-fb5e8594b54f; range_end:; response_count:1; response_revision:25129; }","duration":"146.035199ms","start":"2026-07-08T22:25:51.638532Z","end":"2026-07-08T22:25:51.784567Z","steps":["trace[1629254424] 'range keys from in-memory index tree' (duration: 145.712873ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:52.092387Z","caller":"traceutil/trace.go:171","msg":"trace[1088778491] linearizableReadLoop","detail":"{readStateIndex:25944; appliedIndex:25943; }","duration":"236.964187ms","start":"2026-07-08T22:25:51.855382Z","end":"2026-07-08T22:25:52.092347Z","steps":["trace[1088778491] 'read index received' (duration: 236.755833ms)","trace[1088778491] 'applied index is now lower than readState.Index' (duration: 206.994µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:52.092552Z","caller":"traceutil/trace.go:171","msg":"trace[827375175] transaction","detail":"{read_only:false; response_revision:25130; number_of_response:1; }","duration":"289.078102ms","start":"2026-07-08T22:25:51.803418Z","end":"2026-07-08T22:25:52.092496Z","steps":["trace[827375175] 'process raft request' (duration: 288.679494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:52.092738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.331193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-85a20686-a256-4260-b97a-fb5e8594b54f\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-07-08T22:25:52.092808Z","caller":"traceutil/trace.go:171","msg":"trace[1312585354] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-85a20686-a256-4260-b97a-fb5e8594b54f; range_end:; response_count:1; response_revision:25130; }","duration":"237.470596ms","start":"2026-07-08T22:25:51.855317Z","end":"2026-07-08T22:25:52.092788Z","steps":["trace[1312585354] 'agreement among raft nodes before linearized reading' (duration: 237.223982ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:52.15236Z","caller":"traceutil/trace.go:171","msg":"trace[660574190] transaction","detail":"{read_only:false; response_revision:25131; number_of_response:1; }","duration":"235.913177ms","start":"2026-07-08T22:25:51.916418Z","end":"2026-07-08T22:25:52.152331Z","steps":["trace[660574190] 'process raft request' (duration: 235.622432ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:52.152588Z","caller":"traceutil/trace.go:171","msg":"trace[1751098103] transaction","detail":"{read_only:false; response_revision:25132; number_of_response:1; }","duration":"160.067617ms","start":"2026-07-08T22:25:51.992493Z","end":"2026-07-08T22:25:52.152561Z","steps":["trace[1751098103] 'process raft request' (duration: 159.776292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:52.292415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.890053ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-08T22:25:52.292539Z","caller":"traceutil/trace.go:171","msg":"trace[584198666] linearizableReadLoop","detail":"{readStateIndex:25947; appliedIndex:25944; }","duration":"200.025731ms","start":"2026-07-08T22:25:52.092493Z","end":"2026-07-08T22:25:52.292519Z","steps":["trace[584198666] 'read index received' (duration: 59.476966ms)","trace[584198666] 'applied index is now lower than readState.Index' (duration: 140.546625ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T22:25:52.293036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.0898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-08T22:25:52.29317Z","caller":"traceutil/trace.go:171","msg":"trace[1420355069] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25132; }","duration":"369.198693ms","start":"2026-07-08T22:25:51.923913Z","end":"2026-07-08T22:25:52.293111Z","steps":["trace[1420355069] 'agreement among raft nodes before linearized reading' (duration: 368.911777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:52.293227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:51.923901Z","time spent":"369.313716ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-08T22:25:52.337932Z","caller":"traceutil/trace.go:171","msg":"trace[870027045] transaction","detail":"{read_only:false; response_revision:25133; number_of_response:1; }","duration":"238.162659ms","start":"2026-07-08T22:25:52.099737Z","end":"2026-07-08T22:25:52.3379Z","steps":["trace[870027045] 'process raft request' (duration: 237.932135ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:52.499228Z","caller":"traceutil/trace.go:171","msg":"trace[1520944312] transaction","detail":"{read_only:false; response_revision:25134; number_of_response:1; }","duration":"209.520193ms","start":"2026-07-08T22:25:52.28965Z","end":"2026-07-08T22:25:52.49917Z","steps":["trace[1520944312] 'process raft request' (duration: 119.685707ms)","trace[1520944312] 'compare' (duration: 89.241964ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:52.499244Z","caller":"traceutil/trace.go:171","msg":"trace[595488743] transaction","detail":"{read_only:false; response_revision:25135; number_of_response:1; }","duration":"200.879708ms","start":"2026-07-08T22:25:52.298326Z","end":"2026-07-08T22:25:52.499205Z","steps":["trace[595488743] 'process raft request' (duration: 200.534371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:52.499264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.707475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T22:25:52.499337Z","caller":"traceutil/trace.go:171","msg":"trace[343201360] linearizableReadLoop","detail":"{readStateIndex:25949; appliedIndex:25947; }","duration":"206.586677ms","start":"2026-07-08T22:25:52.292584Z","end":"2026-07-08T22:25:52.499171Z","steps":["trace[343201360] 'read index received' (duration: 45.161423ms)","trace[343201360] 'applied index is now lower than readState.Index' (duration: 161.424034ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:52.499365Z","caller":"traceutil/trace.go:171","msg":"trace[1162455237] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25135; }","duration":"389.869389ms","start":"2026-07-08T22:25:52.109477Z","end":"2026-07-08T22:25:52.499347Z","steps":["trace[1162455237] 'agreement among raft nodes before linearized reading' (duration: 389.700425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:52.499412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:25:52.109453Z","time spent":"389.944639ms","remote":"127.0.0.1:47488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-08T22:25:52.49951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.429671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-08T22:25:52.499643Z","caller":"traceutil/trace.go:171","msg":"trace[1719245975] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25135; }","duration":"254.618534ms","start":"2026-07-08T22:25:52.244996Z","end":"2026-07-08T22:25:52.499615Z","steps":["trace[1719245975] 'agreement among raft nodes before linearized reading' (duration: 254.275838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:25:52.778029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.305673ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T22:25:52.77817Z","caller":"traceutil/trace.go:171","msg":"trace[553298204] linearizableReadLoop","detail":"{readStateIndex:25951; appliedIndex:25950; }","duration":"212.097752ms","start":"2026-07-08T22:25:52.56605Z","end":"2026-07-08T22:25:52.778148Z","steps":["trace[553298204] 'read index received' (duration: 62.541345ms)","trace[553298204] 'applied index is now lower than readState.Index' (duration: 149.554187ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T22:25:52.778262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.216034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:25:52.778297Z","caller":"traceutil/trace.go:171","msg":"trace[2140555295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25136; }","duration":"212.286896ms","start":"2026-07-08T22:25:52.565999Z","end":"2026-07-08T22:25:52.778286Z","steps":["trace[2140555295] 'agreement among raft nodes before linearized reading' (duration: 212.208774ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:52.778443Z","caller":"traceutil/trace.go:171","msg":"trace[205753422] transaction","detail":"{read_only:false; response_revision:25136; number_of_response:1; }","duration":"273.894723ms","start":"2026-07-08T22:25:52.504508Z","end":"2026-07-08T22:25:52.778403Z","steps":["trace[205753422] 'process raft request' (duration: 124.063471ms)","trace[205753422] 'compare' (duration: 149.068648ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:25:53.254993Z","caller":"traceutil/trace.go:171","msg":"trace[2049621345] transaction","detail":"{read_only:false; response_revision:25137; number_of_response:1; }","duration":"128.640467ms","start":"2026-07-08T22:25:53.126321Z","end":"2026-07-08T22:25:53.254961Z","steps":["trace[2049621345] 'process raft request' (duration: 128.469044ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:58.843443Z","caller":"traceutil/trace.go:171","msg":"trace[1461976318] transaction","detail":"{read_only:false; response_revision:25193; number_of_response:1; }","duration":"134.611891ms","start":"2026-07-08T22:25:58.708805Z","end":"2026-07-08T22:25:58.843417Z","steps":["trace[1461976318] 'process raft request' (duration: 133.966958ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:25:59.131106Z","caller":"traceutil/trace.go:171","msg":"trace[1745706546] transaction","detail":"{read_only:false; response_revision:25195; number_of_response:1; }","duration":"210.741545ms","start":"2026-07-08T22:25:58.920335Z","end":"2026-07-08T22:25:59.131077Z","steps":["trace[1745706546] 'process raft request' (duration: 210.532131ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:01.05031Z","caller":"traceutil/trace.go:171","msg":"trace[907061810] linearizableReadLoop","detail":"{readStateIndex:26034; appliedIndex:26033; }","duration":"124.265684ms","start":"2026-07-08T22:26:00.926024Z","end":"2026-07-08T22:26:01.050289Z","steps":["trace[907061810] 'read index received' (duration: 124.092831ms)","trace[907061810] 'applied index is now lower than readState.Index' (duration: 171.723µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:26:01.050415Z","caller":"traceutil/trace.go:171","msg":"trace[1715293737] transaction","detail":"{read_only:false; response_revision:25217; number_of_response:1; }","duration":"143.154445ms","start":"2026-07-08T22:26:00.907231Z","end":"2026-07-08T22:26:01.050385Z","steps":["trace[1715293737] 'process raft request' (duration: 142.90866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:01.050524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.477158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-07-08T22:26:01.050555Z","caller":"traceutil/trace.go:171","msg":"trace[1299902213] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:25217; }","duration":"124.56941ms","start":"2026-07-08T22:26:00.925974Z","end":"2026-07-08T22:26:01.050543Z","steps":["trace[1299902213] 'agreement among raft nodes before linearized reading' (duration: 124.402217ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:23.397365Z","caller":"traceutil/trace.go:171","msg":"trace[771974233] transaction","detail":"{read_only:false; response_revision:25398; number_of_response:1; }","duration":"203.006188ms","start":"2026-07-08T22:26:23.194323Z","end":"2026-07-08T22:26:23.397329Z","steps":["trace[771974233] 'process raft request' (duration: 202.764274ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:23.573183Z","caller":"traceutil/trace.go:171","msg":"trace[1482012559] transaction","detail":"{read_only:false; response_revision:25399; number_of_response:1; }","duration":"124.07308ms","start":"2026-07-08T22:26:23.449078Z","end":"2026-07-08T22:26:23.573151Z","steps":["trace[1482012559] 'process raft request' (duration: 123.788724ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:23.790936Z","caller":"traceutil/trace.go:171","msg":"trace[803306593] transaction","detail":"{read_only:false; response_revision:25400; number_of_response:1; }","duration":"237.62969ms","start":"2026-07-08T22:26:23.553277Z","end":"2026-07-08T22:26:23.790907Z","steps":["trace[803306593] 'process raft request' (duration: 237.382785ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:23.791262Z","caller":"traceutil/trace.go:171","msg":"trace[419021393] transaction","detail":"{read_only:false; response_revision:25402; number_of_response:1; }","duration":"234.493169ms","start":"2026-07-08T22:26:23.556757Z","end":"2026-07-08T22:26:23.79125Z","steps":["trace[419021393] 'process raft request' (duration: 234.313776ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:23.791305Z","caller":"traceutil/trace.go:171","msg":"trace[2066677288] transaction","detail":"{read_only:false; response_revision:25401; number_of_response:1; }","duration":"237.525777ms","start":"2026-07-08T22:26:23.55373Z","end":"2026-07-08T22:26:23.791255Z","steps":["trace[2066677288] 'process raft request' (duration: 237.12538ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:23.791389Z","caller":"traceutil/trace.go:171","msg":"trace[662544701] transaction","detail":"{read_only:false; response_revision:25404; number_of_response:1; }","duration":"229.7993ms","start":"2026-07-08T22:26:23.561547Z","end":"2026-07-08T22:26:23.791346Z","steps":["trace[662544701] 'process raft request' (duration: 229.660547ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:23.791282Z","caller":"traceutil/trace.go:171","msg":"trace[1758604997] linearizableReadLoop","detail":"{readStateIndex:26226; appliedIndex:26221; }","duration":"218.165377ms","start":"2026-07-08T22:26:23.573096Z","end":"2026-07-08T22:26:23.791261Z","steps":["trace[1758604997] 'read index received' (duration: 217.626567ms)","trace[1758604997] 'applied index is now lower than readState.Index' (duration: 537.49µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:26:23.791387Z","caller":"traceutil/trace.go:171","msg":"trace[969810459] transaction","detail":"{read_only:false; response_revision:25403; number_of_response:1; }","duration":"234.446928ms","start":"2026-07-08T22:26:23.556891Z","end":"2026-07-08T22:26:23.791338Z","steps":["trace[969810459] 'process raft request' (duration: 234.259994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:23.791853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.116768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:277945"} {"level":"info","ts":"2026-07-08T22:26:23.791932Z","caller":"traceutil/trace.go:171","msg":"trace[1610045088] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:25404; }","duration":"249.220571ms","start":"2026-07-08T22:26:23.542696Z","end":"2026-07-08T22:26:23.791916Z","steps":["trace[1610045088] 'agreement among raft nodes before linearized reading' (duration: 248.631139ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:23.900209Z","caller":"traceutil/trace.go:171","msg":"trace[1704708900] linearizableReadLoop","detail":"{readStateIndex:26227; appliedIndex:26226; }","duration":"108.584436ms","start":"2026-07-08T22:26:23.791594Z","end":"2026-07-08T22:26:23.900179Z","steps":["trace[1704708900] 'read index received' (duration: 65.055404ms)","trace[1704708900] 'applied index is now lower than readState.Index' (duration: 43.527251ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:26:23.900461Z","caller":"traceutil/trace.go:171","msg":"trace[1788943146] transaction","detail":"{read_only:false; response_revision:25405; number_of_response:1; }","duration":"194.404095ms","start":"2026-07-08T22:26:23.706046Z","end":"2026-07-08T22:26:23.900451Z","steps":["trace[1788943146] 'process raft request' (duration: 150.70997ms)","trace[1788943146] 'compare' (duration: 43.174165ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T22:26:23.900586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.037351ms","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-07-08T22:26:23.900665Z","caller":"traceutil/trace.go:171","msg":"trace[1672643592] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25405; }","duration":"323.168364ms","start":"2026-07-08T22:26:23.577477Z","end":"2026-07-08T22:26:23.900645Z","steps":["trace[1672643592] 'agreement among raft nodes before linearized reading' (duration: 322.96828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:23.900713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:26:23.577452Z","time spent":"323.251606ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T22:26:23.900854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.710041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-08T22:26:23.900877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.798523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T22:26:23.90095Z","caller":"traceutil/trace.go:171","msg":"trace[113310723] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25405; }","duration":"148.889044ms","start":"2026-07-08T22:26:23.752036Z","end":"2026-07-08T22:26:23.900926Z","steps":["trace[113310723] 'agreement among raft nodes before linearized reading' (duration: 148.689801ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:23.900994Z","caller":"traceutil/trace.go:171","msg":"trace[1192668517] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25405; }","duration":"293.965387ms","start":"2026-07-08T22:26:23.606977Z","end":"2026-07-08T22:26:23.900942Z","steps":["trace[1192668517] 'agreement among raft nodes before linearized reading' (duration: 293.753913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:24.165913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.058699ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-08T22:26:24.16608Z","caller":"traceutil/trace.go:171","msg":"trace[508495659] transaction","detail":"{read_only:false; response_revision:25407; number_of_response:1; }","duration":"259.807224ms","start":"2026-07-08T22:26:23.906256Z","end":"2026-07-08T22:26:24.166064Z","steps":["trace[508495659] 'process raft request' (duration: 101.50458ms)","trace[508495659] 'compare' (duration: 157.930626ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:26:24.166292Z","caller":"traceutil/trace.go:171","msg":"trace[694645363] linearizableReadLoop","detail":"{readStateIndex:26230; appliedIndex:26227; }","duration":"257.734513ms","start":"2026-07-08T22:26:23.908531Z","end":"2026-07-08T22:26:24.166265Z","steps":["trace[694645363] 'read index received' (duration: 25.743981ms)","trace[694645363] 'applied index is now lower than readState.Index' (duration: 231.989482ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:26:24.166453Z","caller":"traceutil/trace.go:171","msg":"trace[192709246] transaction","detail":"{read_only:false; response_revision:25408; number_of_response:1; }","duration":"260.085329ms","start":"2026-07-08T22:26:23.906359Z","end":"2026-07-08T22:26:24.166444Z","steps":["trace[192709246] 'process raft request' (duration: 259.659951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:24.166651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.09755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:26:24.166688Z","caller":"traceutil/trace.go:171","msg":"trace[1343435033] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25408; }","duration":"258.170932ms","start":"2026-07-08T22:26:23.908508Z","end":"2026-07-08T22:26:24.166679Z","steps":["trace[1343435033] 'agreement among raft nodes before linearized reading' (duration: 258.091471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:24.166896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.58055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-07-08T22:26:24.166923Z","caller":"traceutil/trace.go:171","msg":"trace[1773722132] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25408; }","duration":"224.644811ms","start":"2026-07-08T22:26:23.94227Z","end":"2026-07-08T22:26:24.166915Z","steps":["trace[1773722132] 'agreement among raft nodes before linearized reading' (duration: 224.533439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:24.167004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.080917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T22:26:24.167109Z","caller":"traceutil/trace.go:171","msg":"trace[482761739] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:25408; }","duration":"204.235831ms","start":"2026-07-08T22:26:23.962854Z","end":"2026-07-08T22:26:24.16709Z","steps":["trace[482761739] 'agreement among raft nodes before linearized reading' (duration: 204.038437ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:24.40142Z","caller":"traceutil/trace.go:171","msg":"trace[73060172] linearizableReadLoop","detail":"{readStateIndex:26231; appliedIndex:26230; }","duration":"231.779948ms","start":"2026-07-08T22:26:24.169608Z","end":"2026-07-08T22:26:24.401388Z","steps":["trace[73060172] 'read index received' (duration: 181.456797ms)","trace[73060172] 'applied index is now lower than readState.Index' (duration: 50.321351ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:26:24.401582Z","caller":"traceutil/trace.go:171","msg":"trace[509679021] transaction","detail":"{read_only:false; response_revision:25409; number_of_response:1; }","duration":"234.228315ms","start":"2026-07-08T22:26:24.167335Z","end":"2026-07-08T22:26:24.401564Z","steps":["trace[509679021] 'process raft request' (duration: 183.123368ms)","trace[509679021] 'compare' (duration: 50.697879ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:26:24.401682Z","caller":"traceutil/trace.go:171","msg":"trace[1221314605] transaction","detail":"{read_only:false; response_revision:25410; number_of_response:1; }","duration":"106.977043ms","start":"2026-07-08T22:26:24.294665Z","end":"2026-07-08T22:26:24.401642Z","steps":["trace[1221314605] 'process raft request' (duration: 106.670517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:24.402303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.679835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-6ed31dd1-59df-4371-a9ee-149cc4482c0c\" ","response":"range_response_count:1 size:1304"} {"level":"warn","ts":"2026-07-08T22:26:24.402096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.557164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:26:24.402357Z","caller":"traceutil/trace.go:171","msg":"trace[768562405] range","detail":"{range_begin:/registry/persistentvolumes/pvc-6ed31dd1-59df-4371-a9ee-149cc4482c0c; range_end:; response_count:1; response_revision:25410; }","duration":"232.782597ms","start":"2026-07-08T22:26:24.16956Z","end":"2026-07-08T22:26:24.402342Z","steps":["trace[768562405] 'agreement among raft nodes before linearized reading' (duration: 232.545303ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:24.402384Z","caller":"traceutil/trace.go:171","msg":"trace[448059343] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25410; }","duration":"230.857489ms","start":"2026-07-08T22:26:24.171502Z","end":"2026-07-08T22:26:24.402359Z","steps":["trace[448059343] 'agreement among raft nodes before linearized reading' (duration: 230.294879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:25.812898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.370069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:26:25.812966Z","caller":"traceutil/trace.go:171","msg":"trace[767850039] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25413; }","duration":"110.45233ms","start":"2026-07-08T22:26:25.702499Z","end":"2026-07-08T22:26:25.812951Z","steps":["trace[767850039] 'range keys from in-memory index tree' (duration: 110.284977ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:25.968063Z","caller":"traceutil/trace.go:171","msg":"trace[1301829658] transaction","detail":"{read_only:false; response_revision:25414; number_of_response:1; }","duration":"170.373495ms","start":"2026-07-08T22:26:25.797666Z","end":"2026-07-08T22:26:25.96804Z","steps":["trace[1301829658] 'process raft request' (duration: 170.183241ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:26.043492Z","caller":"traceutil/trace.go:171","msg":"trace[990747333] transaction","detail":"{read_only:false; response_revision:25415; number_of_response:1; }","duration":"245.4831ms","start":"2026-07-08T22:26:25.797975Z","end":"2026-07-08T22:26:26.043458Z","steps":["trace[990747333] 'process raft request' (duration: 245.243444ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:26.043509Z","caller":"traceutil/trace.go:171","msg":"trace[1810009521] transaction","detail":"{read_only:false; response_revision:25416; number_of_response:1; }","duration":"245.01088ms","start":"2026-07-08T22:26:25.798472Z","end":"2026-07-08T22:26:26.043482Z","steps":["trace[1810009521] 'process raft request' (duration: 244.908578ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:26.043807Z","caller":"traceutil/trace.go:171","msg":"trace[1334840941] transaction","detail":"{read_only:false; response_revision:25417; number_of_response:1; }","duration":"245.219714ms","start":"2026-07-08T22:26:25.798575Z","end":"2026-07-08T22:26:26.043795Z","steps":["trace[1334840941] 'process raft request' (duration: 244.857167ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:26.043941Z","caller":"traceutil/trace.go:171","msg":"trace[389910099] transaction","detail":"{read_only:false; response_revision:25418; number_of_response:1; }","duration":"244.4218ms","start":"2026-07-08T22:26:25.799507Z","end":"2026-07-08T22:26:26.043929Z","steps":["trace[389910099] 'process raft request' (duration: 244.224796ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:26.043938Z","caller":"traceutil/trace.go:171","msg":"trace[177788942] linearizableReadLoop","detail":"{readStateIndex:26240; appliedIndex:26235; }","duration":"228.024556ms","start":"2026-07-08T22:26:25.815892Z","end":"2026-07-08T22:26:26.043916Z","steps":["trace[177788942] 'read index received' (duration: 152.014645ms)","trace[177788942] 'applied index is now lower than readState.Index' (duration: 76.009111ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:26:26.043973Z","caller":"traceutil/trace.go:171","msg":"trace[431016016] transaction","detail":"{read_only:false; response_revision:25419; number_of_response:1; }","duration":"136.255133ms","start":"2026-07-08T22:26:25.907696Z","end":"2026-07-08T22:26:26.043951Z","steps":["trace[431016016] 'process raft request' (duration: 136.166591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:26.04411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.189899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:26:26.044155Z","caller":"traceutil/trace.go:171","msg":"trace[880061038] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25419; }","duration":"228.295811ms","start":"2026-07-08T22:26:25.815847Z","end":"2026-07-08T22:26:26.044142Z","steps":["trace[880061038] 'agreement among raft nodes before linearized reading' (duration: 228.189159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:26.044177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.153888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-08T22:26:26.044207Z","caller":"traceutil/trace.go:171","msg":"trace[1502953755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25419; }","duration":"106.216489ms","start":"2026-07-08T22:26:25.93798Z","end":"2026-07-08T22:26:26.044197Z","steps":["trace[1502953755] 'agreement among raft nodes before linearized reading' (duration: 106.083187ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:26.544225Z","caller":"traceutil/trace.go:171","msg":"trace[1351709460] transaction","detail":"{read_only:false; response_revision:25423; number_of_response:1; }","duration":"134.53735ms","start":"2026-07-08T22:26:26.409633Z","end":"2026-07-08T22:26:26.54417Z","steps":["trace[1351709460] 'process raft request' (duration: 134.211734ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:26.693895Z","caller":"traceutil/trace.go:171","msg":"trace[1910024349] linearizableReadLoop","detail":"{readStateIndex:26246; appliedIndex:26245; }","duration":"127.519526ms","start":"2026-07-08T22:26:26.566318Z","end":"2026-07-08T22:26:26.693838Z","steps":["trace[1910024349] 'read index received' (duration: 108.261378ms)","trace[1910024349] 'applied index is now lower than readState.Index' (duration: 19.256028ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T22:26:26.694055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.712229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:26:26.694138Z","caller":"traceutil/trace.go:171","msg":"trace[1350656990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25423; }","duration":"127.825431ms","start":"2026-07-08T22:26:26.566294Z","end":"2026-07-08T22:26:26.69412Z","steps":["trace[1350656990] 'agreement among raft nodes before linearized reading' (duration: 127.678568ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:26.839024Z","caller":"traceutil/trace.go:171","msg":"trace[423290484] linearizableReadLoop","detail":"{readStateIndex:26247; appliedIndex:26246; }","duration":"139.301531ms","start":"2026-07-08T22:26:26.699683Z","end":"2026-07-08T22:26:26.838984Z","steps":["trace[423290484] 'read index received' (duration: 108.087584ms)","trace[423290484] 'applied index is now lower than readState.Index' (duration: 31.212257ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:26:26.839344Z","caller":"traceutil/trace.go:171","msg":"trace[963186433] transaction","detail":"{read_only:false; response_revision:25424; number_of_response:1; }","duration":"141.985743ms","start":"2026-07-08T22:26:26.697017Z","end":"2026-07-08T22:26:26.839003Z","steps":["trace[963186433] 'process raft request' (duration: 110.837548ms)","trace[963186433] 'compare' (duration: 30.8902ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T22:26:26.839359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.643337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-08T22:26:26.839613Z","caller":"traceutil/trace.go:171","msg":"trace[230616393] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25424; }","duration":"139.945603ms","start":"2026-07-08T22:26:26.699639Z","end":"2026-07-08T22:26:26.839585Z","steps":["trace[230616393] 'agreement among raft nodes before linearized reading' (duration: 139.463254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:27.180834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.049944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:521"} {"level":"info","ts":"2026-07-08T22:26:27.180912Z","caller":"traceutil/trace.go:171","msg":"trace[512947912] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:25425; }","duration":"175.138136ms","start":"2026-07-08T22:26:27.005754Z","end":"2026-07-08T22:26:27.180892Z","steps":["trace[512947912] 'range keys from in-memory index tree' (duration: 174.807179ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:27.450337Z","caller":"traceutil/trace.go:171","msg":"trace[2128692981] transaction","detail":"{read_only:false; response_revision:25427; number_of_response:1; }","duration":"191.382916ms","start":"2026-07-08T22:26:27.258921Z","end":"2026-07-08T22:26:27.450304Z","steps":["trace[2128692981] 'process raft request' (duration: 124.984758ms)","trace[2128692981] 'compare' (duration: 66.233735ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:26:27.743551Z","caller":"traceutil/trace.go:171","msg":"trace[2059651868] transaction","detail":"{read_only:false; response_revision:25428; number_of_response:1; }","duration":"125.356335ms","start":"2026-07-08T22:26:27.618172Z","end":"2026-07-08T22:26:27.743528Z","steps":["trace[2059651868] 'process raft request' (duration: 125.162991ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:27.743515Z","caller":"traceutil/trace.go:171","msg":"trace[1660952282] linearizableReadLoop","detail":"{readStateIndex:26251; appliedIndex:26250; }","duration":"112.06854ms","start":"2026-07-08T22:26:27.631422Z","end":"2026-07-08T22:26:27.743491Z","steps":["trace[1660952282] 'read index received' (duration: 111.897907ms)","trace[1660952282] 'applied index is now lower than readState.Index' (duration: 169.443µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T22:26:27.743687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.248424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:26:27.743772Z","caller":"traceutil/trace.go:171","msg":"trace[1108291430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25428; }","duration":"112.390957ms","start":"2026-07-08T22:26:27.631361Z","end":"2026-07-08T22:26:27.743752Z","steps":["trace[1108291430] 'agreement among raft nodes before linearized reading' (duration: 112.235714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:37.766688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.943443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-08T22:26:37.766762Z","caller":"traceutil/trace.go:171","msg":"trace[695122694] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:25507; }","duration":"163.059305ms","start":"2026-07-08T22:26:37.603686Z","end":"2026-07-08T22:26:37.766746Z","steps":["trace[695122694] 'count revisions from in-memory index tree' (duration: 162.865342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:37.767231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.221586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-08T22:26:37.767287Z","caller":"traceutil/trace.go:171","msg":"trace[817977011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25507; }","duration":"199.321518ms","start":"2026-07-08T22:26:37.567952Z","end":"2026-07-08T22:26:37.767273Z","steps":["trace[817977011] 'range keys from in-memory index tree' (duration: 199.156225ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:38.269844Z","caller":"traceutil/trace.go:171","msg":"trace[745308448] transaction","detail":"{read_only:false; response_revision:25509; number_of_response:1; }","duration":"494.106369ms","start":"2026-07-08T22:26:37.775714Z","end":"2026-07-08T22:26:38.26982Z","steps":["trace[745308448] 'process raft request' (duration: 493.914416ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:38.270016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:26:37.775699Z","time spent":"494.217011ms","remote":"127.0.0.1:55538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T22:26:38.429417Z","caller":"traceutil/trace.go:171","msg":"trace[1057885277] transaction","detail":"{read_only:false; response_revision:25510; number_of_response:1; }","duration":"414.472477ms","start":"2026-07-08T22:26:38.014912Z","end":"2026-07-08T22:26:38.429384Z","steps":["trace[1057885277] 'process raft request' (duration: 414.026479ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:38.429501Z","caller":"traceutil/trace.go:171","msg":"trace[309084032] transaction","detail":"{read_only:false; response_revision:25511; number_of_response:1; }","duration":"281.688081ms","start":"2026-07-08T22:26:38.147786Z","end":"2026-07-08T22:26:38.429474Z","steps":["trace[309084032] 'process raft request' (duration: 281.370125ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:38.429523Z","caller":"traceutil/trace.go:171","msg":"trace[1549338217] transaction","detail":"{read_only:false; response_revision:25513; number_of_response:1; }","duration":"276.136105ms","start":"2026-07-08T22:26:38.15333Z","end":"2026-07-08T22:26:38.429467Z","steps":["trace[1549338217] 'process raft request' (duration: 275.917011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:38.429629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:26:38.014886Z","time spent":"414.641311ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T22:26:38.429645Z","caller":"traceutil/trace.go:171","msg":"trace[1906521530] transaction","detail":"{read_only:false; response_revision:25512; number_of_response:1; }","duration":"279.112381ms","start":"2026-07-08T22:26:38.150482Z","end":"2026-07-08T22:26:38.429594Z","steps":["trace[1906521530] 'process raft request' (duration: 278.724814ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:38.430092Z","caller":"traceutil/trace.go:171","msg":"trace[1772907429] transaction","detail":"{read_only:false; response_revision:25514; number_of_response:1; }","duration":"275.795769ms","start":"2026-07-08T22:26:38.15411Z","end":"2026-07-08T22:26:38.429906Z","steps":["trace[1772907429] 'process raft request' (duration: 275.30612ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:38.430385Z","caller":"traceutil/trace.go:171","msg":"trace[1995462580] linearizableReadLoop","detail":"{readStateIndex:26340; appliedIndex:26333; }","duration":"272.303893ms","start":"2026-07-08T22:26:38.15804Z","end":"2026-07-08T22:26:38.430344Z","steps":["trace[1995462580] 'read index received' (duration: 111.637494ms)","trace[1995462580] 'applied index is now lower than readState.Index' (duration: 160.664959ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:26:38.430472Z","caller":"traceutil/trace.go:171","msg":"trace[1439510626] transaction","detail":"{read_only:false; response_revision:25515; number_of_response:1; }","duration":"276.020144ms","start":"2026-07-08T22:26:38.154434Z","end":"2026-07-08T22:26:38.430454Z","steps":["trace[1439510626] 'process raft request' (duration: 275.461523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:38.43139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.369512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-08T22:26:38.431435Z","caller":"traceutil/trace.go:171","msg":"trace[1926298857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25515; }","duration":"273.474124ms","start":"2026-07-08T22:26:38.15795Z","end":"2026-07-08T22:26:38.431424Z","steps":["trace[1926298857] 'agreement among raft nodes before linearized reading' (duration: 272.562897ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:38.648615Z","caller":"traceutil/trace.go:171","msg":"trace[885485508] linearizableReadLoop","detail":"{readStateIndex:26342; appliedIndex:26340; }","duration":"218.070025ms","start":"2026-07-08T22:26:38.430504Z","end":"2026-07-08T22:26:38.648575Z","steps":["trace[885485508] 'read index received' (duration: 203.150721ms)","trace[885485508] 'applied index is now lower than readState.Index' (duration: 14.918114ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:26:38.648733Z","caller":"traceutil/trace.go:171","msg":"trace[816005014] transaction","detail":"{read_only:false; response_revision:25517; number_of_response:1; }","duration":"371.67056ms","start":"2026-07-08T22:26:38.277028Z","end":"2026-07-08T22:26:38.648698Z","steps":["trace[816005014] 'process raft request' (duration: 371.464826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:38.648805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.266344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-07-08T22:26:38.648861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.842754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:208"} {"level":"info","ts":"2026-07-08T22:26:38.648896Z","caller":"traceutil/trace.go:171","msg":"trace[1614967230] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:25517; }","duration":"372.936485ms","start":"2026-07-08T22:26:38.275948Z","end":"2026-07-08T22:26:38.648885Z","steps":["trace[1614967230] 'agreement among raft nodes before linearized reading' (duration: 372.751911ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:38.64889Z","caller":"traceutil/trace.go:171","msg":"trace[1830953934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25517; }","duration":"315.413937ms","start":"2026-07-08T22:26:38.333456Z","end":"2026-07-08T22:26:38.64887Z","steps":["trace[1830953934] 'agreement among raft nodes before linearized reading' (duration: 315.127761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:38.6489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.786828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-08T22:26:38.648928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:26:38.27593Z","time spent":"372.991026ms","remote":"127.0.0.1:55554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-07-08T22:26:38.648947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:26:38.333432Z","time spent":"315.505568ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-08T22:26:38.648957Z","caller":"traceutil/trace.go:171","msg":"trace[437564795] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:25517; }","duration":"232.89133ms","start":"2026-07-08T22:26:38.416048Z","end":"2026-07-08T22:26:38.64894Z","steps":["trace[437564795] 'agreement among raft nodes before linearized reading' (duration: 232.779758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:38.648944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:26:38.277021Z","time spent":"371.822573ms","remote":"127.0.0.1:55618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1206,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T22:26:38.648783Z","caller":"traceutil/trace.go:171","msg":"trace[1179593173] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"372.507988ms","start":"2026-07-08T22:26:38.276252Z","end":"2026-07-08T22:26:38.64876Z","steps":["trace[1179593173] 'process raft request' (duration: 357.382419ms)","trace[1179593173] 'compare' (duration: 14.636839ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T22:26:38.649221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.318322ms","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-07-08T22:26:38.649256Z","caller":"traceutil/trace.go:171","msg":"trace[811179810] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25517; }","duration":"317.389384ms","start":"2026-07-08T22:26:38.331859Z","end":"2026-07-08T22:26:38.649248Z","steps":["trace[811179810] 'agreement among raft nodes before linearized reading' (duration: 317.296402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:38.649283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:26:38.331832Z","time spent":"317.445654ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-08T22:26:38.649363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:26:38.276225Z","time spent":"373.027487ms","remote":"127.0.0.1:55518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":676,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T22:26:38.649583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.516085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-registry-c45778976-pm89m\" ","response":"range_response_count:1 size:3539"} {"level":"info","ts":"2026-07-08T22:26:38.649683Z","caller":"traceutil/trace.go:171","msg":"trace[2059623340] range","detail":"{range_begin:/registry/pods/openstack/magnum-registry-c45778976-pm89m; range_end:; response_count:1; response_revision:25517; }","duration":"363.697588ms","start":"2026-07-08T22:26:38.285961Z","end":"2026-07-08T22:26:38.649659Z","steps":["trace[2059623340] 'agreement among raft nodes before linearized reading' (duration: 363.23169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:38.649747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:26:38.285938Z","time spent":"363.79748ms","remote":"127.0.0.1:55538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":3562,"request content":"key:\"/registry/pods/openstack/magnum-registry-c45778976-pm89m\" "} {"level":"info","ts":"2026-07-08T22:26:38.665088Z","caller":"traceutil/trace.go:171","msg":"trace[1185203404] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"228.360172ms","start":"2026-07-08T22:26:38.436693Z","end":"2026-07-08T22:26:38.665053Z","steps":["trace[1185203404] 'process raft request' (duration: 228.126308ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:48.706047Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006736.wal"} {"level":"info","ts":"2026-07-08T22:26:49.56944Z","caller":"traceutil/trace.go:171","msg":"trace[1539904915] transaction","detail":"{read_only:false; response_revision:25599; number_of_response:1; }","duration":"129.749418ms","start":"2026-07-08T22:26:49.43965Z","end":"2026-07-08T22:26:49.569399Z","steps":["trace[1539904915] 'process raft request' (duration: 129.447183ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T22:26:49.945522Z","caller":"traceutil/trace.go:171","msg":"trace[2103689964] transaction","detail":"{read_only:false; response_revision:25600; number_of_response:1; }","duration":"325.665622ms","start":"2026-07-08T22:26:49.619812Z","end":"2026-07-08T22:26:49.945478Z","steps":["trace[2103689964] 'process raft request' (duration: 325.451428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T22:26:49.945758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T22:26:49.619789Z","time spent":"325.865306ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T22:26:50.085324Z","caller":"traceutil/trace.go:171","msg":"trace[750360761] transaction","detail":"{read_only:false; response_revision:25601; number_of_response:1; }","duration":"182.235042ms","start":"2026-07-08T22:26:49.903068Z","end":"2026-07-08T22:26:50.085303Z","steps":["trace[750360761] 'process raft request' (duration: 109.488462ms)","trace[750360761] 'compare' (duration: 72.363292ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T22:27:43.397818Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23219} {"level":"info","ts":"2026-07-08T22:27:43.537465Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23219,"took":"134.851776ms","hash":1060813859,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":35504128,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-08T22:27:43.537532Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1060813859,"revision":23219,"compact-revision":20213} {"level":"info","ts":"2026-07-08T22:32:43.403978Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26167} {"level":"info","ts":"2026-07-08T22:32:43.55839Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26167,"took":"150.15494ms","hash":2719893439,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":34615296,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-08T22:32:43.558459Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2719893439,"revision":26167,"compact-revision":23219} {"level":"info","ts":"2026-07-08T22:33:13.286194Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e2b5783e11defe7e","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-08T22:33:13.292566Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-08T22:33:13.292777Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-08T22:37:43.409138Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28861} {"level":"info","ts":"2026-07-08T22:37:43.546437Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28861,"took":"131.743958ms","hash":4236099594,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":31207424,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-08T22:37:43.546509Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4236099594,"revision":28861,"compact-revision":26167} {"level":"info","ts":"2026-07-08T22:42:43.41695Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31087} {"level":"info","ts":"2026-07-08T22:42:43.560422Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31087,"took":"137.296344ms","hash":423389863,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":31260672,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-08T22:42:43.560524Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":423389863,"revision":31087,"compact-revision":28861} {"level":"info","ts":"2026-07-08T22:47:43.426393Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33350} {"level":"info","ts":"2026-07-08T22:47:43.587698Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33350,"took":"153.599426ms","hash":922955939,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":31764480,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-08T22:47:43.587804Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":922955939,"revision":33350,"compact-revision":31087} {"level":"info","ts":"2026-07-08T22:52:43.433944Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35641} {"level":"info","ts":"2026-07-08T22:52:43.59682Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35641,"took":"143.41164ms","hash":283678531,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":31629312,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-08T22:52:43.596898Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":283678531,"revision":35641,"compact-revision":33350} {"level":"info","ts":"2026-07-08T22:54:44.714589Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e2b5783e11defe7e","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-08T22:54:44.720818Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-08T22:54:44.721264Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-07-08T22:57:43.440337Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37872} {"level":"info","ts":"2026-07-08T22:57:43.561087Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37872,"took":"115.965355ms","hash":2101522192,"current-db-size-bytes":59297792,"current-db-size":"59 MB","current-db-size-in-use-bytes":32018432,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-08T22:57:43.561143Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2101522192,"revision":37872,"compact-revision":35641}