{"level":"warn","ts":"2026-05-26T21:09:36.492492Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-26T21:09:36.492647Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-26T21:09:36.492739Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-26T21:09:36.492756Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-05-26T21:09:36.492787Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-26T21:09:36.493726Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-05-26T21:09:36.493864Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-26T21:09:36.5003Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.476121ms"} {"level":"info","ts":"2026-05-26T21:09:36.507329Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-05-26T21:09:36.507411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-05-26T21:09:36.507431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-05-26T21:09:36.50745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-26T21:09:36.50747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-05-26T21:09:36.507515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-05-26T21:09:36.511752Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-26T21:09:36.513033Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-26T21:09:36.514172Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-26T21:09:36.516469Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-26T21:09:36.516585Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-26T21:09:36.516743Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-26T21:09:36.51682Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-26T21:09:36.516832Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-26T21:09:36.517742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-05-26T21:09:36.518454Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-05-26T21:09:36.521175Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-26T21:09:36.521283Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-05-26T21:09:36.52134Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-05-26T21:09:36.521479Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-26T21:09:36.52153Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-26T21:09:36.607961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-05-26T21:09:36.608016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-05-26T21:09:36.608038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-05-26T21:09:36.608054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-05-26T21:09:36.608062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-05-26T21:09:36.608073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-05-26T21:09:36.608083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-05-26T21:09:36.609007Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T21:09:36.609873Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T21:09:36.609876Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-05-26T21:09:36.609902Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T21:09:36.610212Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-26T21:09:36.610257Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-26T21:09:36.610641Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T21:09:36.610769Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T21:09:36.610804Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T21:09:36.612475Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-05-26T21:09:36.612622Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-26T21:09:59.263727Z","caller":"traceutil/trace.go:171","msg":"trace[1203665982] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"111.368716ms","start":"2026-05-26T21:09:59.152333Z","end":"2026-05-26T21:09:59.263702Z","steps":["trace[1203665982] 'process raft request' (duration: 111.237483ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:10:21.976423Z","caller":"traceutil/trace.go:171","msg":"trace[1585030513] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"195.501576ms","start":"2026-05-26T21:10:21.780898Z","end":"2026-05-26T21:10:21.9764Z","steps":["trace[1585030513] 'process raft request' (duration: 194.033916ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:10:22.216569Z","caller":"traceutil/trace.go:171","msg":"trace[1429568488] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"183.351442ms","start":"2026-05-26T21:10:22.033194Z","end":"2026-05-26T21:10:22.216546Z","steps":["trace[1429568488] 'process raft request' (duration: 183.212798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:10:22.725521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.242913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:10:22.72571Z","caller":"traceutil/trace.go:171","msg":"trace[30423734] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"483.376182ms","start":"2026-05-26T21:10:22.242304Z","end":"2026-05-26T21:10:22.72568Z","steps":["trace[30423734] 'process raft request' (duration: 301.364878ms)","trace[30423734] 'compare' (duration: 180.921784ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:10:22.725855Z","caller":"traceutil/trace.go:171","msg":"trace[442780802] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"235.743137ms","start":"2026-05-26T21:10:22.490073Z","end":"2026-05-26T21:10:22.725816Z","steps":["trace[442780802] 'read index received' (duration: 53.466156ms)","trace[442780802] 'applied index is now lower than readState.Index' (duration: 182.273831ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:10:22.725888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:10:22.24226Z","time spent":"483.569978ms","remote":"127.0.0.1:52710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:10:22.726023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.902891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:10:22.726097Z","caller":"traceutil/trace.go:171","msg":"trace[1278387672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"236.063375ms","start":"2026-05-26T21:10:22.490016Z","end":"2026-05-26T21:10:22.726079Z","steps":["trace[1278387672] 'agreement among raft nodes before linearized reading' (duration: 235.895761ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:11:05.613788Z","caller":"traceutil/trace.go:171","msg":"trace[1296620864] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"191.836575ms","start":"2026-05-26T21:11:05.421915Z","end":"2026-05-26T21:11:05.613752Z","steps":["trace[1296620864] 'process raft request' (duration: 191.532207ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:11:05.613765Z","caller":"traceutil/trace.go:171","msg":"trace[262464526] linearizableReadLoop","detail":"{readStateIndex:1235; appliedIndex:1234; }","duration":"175.675139ms","start":"2026-05-26T21:11:05.438016Z","end":"2026-05-26T21:11:05.613691Z","steps":["trace[262464526] 'read index received' (duration: 175.418302ms)","trace[262464526] 'applied index is now lower than readState.Index' (duration: 255.217µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:11:05.614036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.610547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:11:05.614069Z","caller":"traceutil/trace.go:171","msg":"trace[1927140360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1198; }","duration":"125.671799ms","start":"2026-05-26T21:11:05.488389Z","end":"2026-05-26T21:11:05.61406Z","steps":["trace[1927140360] 'agreement among raft nodes before linearized reading' (duration: 125.583046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:11:05.614173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.53831ms","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-05-26T21:11:05.614225Z","caller":"traceutil/trace.go:171","msg":"trace[1002476460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1198; }","duration":"131.679375ms","start":"2026-05-26T21:11:05.482533Z","end":"2026-05-26T21:11:05.614212Z","steps":["trace[1002476460] 'agreement among raft nodes before linearized reading' (duration: 131.442478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:11:05.614227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.201344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-26T21:11:05.614348Z","caller":"traceutil/trace.go:171","msg":"trace[1980370120] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1198; }","duration":"176.379999ms","start":"2026-05-26T21:11:05.437942Z","end":"2026-05-26T21:11:05.614321Z","steps":["trace[1980370120] 'agreement among raft nodes before linearized reading' (duration: 175.928996ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:11:27.792801Z","caller":"traceutil/trace.go:171","msg":"trace[1358906016] transaction","detail":"{read_only:false; response_revision:1455; number_of_response:1; }","duration":"106.750486ms","start":"2026-05-26T21:11:27.68593Z","end":"2026-05-26T21:11:27.792681Z","steps":["trace[1358906016] 'process raft request' (duration: 106.576561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:11:27.962599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.986262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:11:27.962961Z","caller":"traceutil/trace.go:171","msg":"trace[643159391] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"165.022377ms","start":"2026-05-26T21:11:27.797696Z","end":"2026-05-26T21:11:27.962718Z","steps":["trace[643159391] 'process raft request' (duration: 46.804848ms)","trace[643159391] 'compare' (duration: 117.828648ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:11:52.816738Z","caller":"traceutil/trace.go:171","msg":"trace[79368259] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"272.201774ms","start":"2026-05-26T21:11:52.544507Z","end":"2026-05-26T21:11:52.816709Z","steps":["trace[79368259] 'process raft request' (duration: 271.952128ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:12:09.4826Z","caller":"traceutil/trace.go:171","msg":"trace[2030929834] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"117.948781ms","start":"2026-05-26T21:12:09.364618Z","end":"2026-05-26T21:12:09.482567Z","steps":["trace[2030929834] 'process raft request' (duration: 95.391555ms)","trace[2030929834] 'compare' (duration: 22.388592ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:12:13.890758Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555606078566468377,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-26T21:12:13.91645Z","caller":"traceutil/trace.go:171","msg":"trace[1319248892] linearizableReadLoop","detail":"{readStateIndex:2112; appliedIndex:2111; }","duration":"526.322704ms","start":"2026-05-26T21:12:13.390106Z","end":"2026-05-26T21:12:13.916429Z","steps":["trace[1319248892] 'read index received' (duration: 526.125959ms)","trace[1319248892] 'applied index is now lower than readState.Index' (duration: 196.005µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:12:13.916543Z","caller":"traceutil/trace.go:171","msg":"trace[1625374993] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"559.756528ms","start":"2026-05-26T21:12:13.356761Z","end":"2026-05-26T21:12:13.916518Z","steps":["trace[1625374993] 'process raft request' (duration: 559.528371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:12:13.916649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.864278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:12:13.91672Z","caller":"traceutil/trace.go:171","msg":"trace[729867667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2033; }","duration":"427.010942ms","start":"2026-05-26T21:12:13.489693Z","end":"2026-05-26T21:12:13.916704Z","steps":["trace[729867667] 'agreement among raft nodes before linearized reading' (duration: 426.871678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:12:13.91674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:12:13.356744Z","time spent":"559.926202ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:12:13.916779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:12:13.48967Z","time spent":"427.096574ms","remote":"127.0.0.1:52542","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-05-26T21:12:13.916762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.631792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-26T21:12:13.916821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.74736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T21:12:13.916863Z","caller":"traceutil/trace.go:171","msg":"trace[263557194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2033; }","duration":"526.742235ms","start":"2026-05-26T21:12:13.390096Z","end":"2026-05-26T21:12:13.916838Z","steps":["trace[263557194] 'agreement among raft nodes before linearized reading' (duration: 526.457528ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:12:13.916899Z","caller":"traceutil/trace.go:171","msg":"trace[1659849977] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2033; }","duration":"279.880114ms","start":"2026-05-26T21:12:13.637Z","end":"2026-05-26T21:12:13.91688Z","steps":["trace[1659849977] 'agreement among raft nodes before linearized reading' (duration: 279.73497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:12:13.916928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:12:13.39007Z","time spent":"526.846419ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-26T21:12:13.917175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.792917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-26T21:12:13.91774Z","caller":"traceutil/trace.go:171","msg":"trace[275885713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2033; }","duration":"419.370723ms","start":"2026-05-26T21:12:13.498348Z","end":"2026-05-26T21:12:13.917719Z","steps":["trace[275885713] 'agreement among raft nodes before linearized reading' (duration: 418.719656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:12:13.917908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:12:13.498334Z","time spent":"419.470926ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-26T21:12:46.381005Z","caller":"traceutil/trace.go:171","msg":"trace[1353054916] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"114.918067ms","start":"2026-05-26T21:12:46.266026Z","end":"2026-05-26T21:12:46.380944Z","steps":["trace[1353054916] 'process raft request' (duration: 114.784324ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:12:46.574121Z","caller":"traceutil/trace.go:171","msg":"trace[420886279] transaction","detail":"{read_only:false; response_revision:2219; number_of_response:1; }","duration":"157.253521ms","start":"2026-05-26T21:12:46.416832Z","end":"2026-05-26T21:12:46.574086Z","steps":["trace[420886279] 'process raft request' (duration: 75.625738ms)","trace[420886279] 'compare' (duration: 81.315754ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:12:46.71495Z","caller":"traceutil/trace.go:171","msg":"trace[1320558771] linearizableReadLoop","detail":"{readStateIndex:2307; appliedIndex:2306; }","duration":"139.125502ms","start":"2026-05-26T21:12:46.575799Z","end":"2026-05-26T21:12:46.714925Z","steps":["trace[1320558771] 'read index received' (duration: 123.220545ms)","trace[1320558771] 'applied index is now lower than readState.Index' (duration: 15.903857ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:12:46.715047Z","caller":"traceutil/trace.go:171","msg":"trace[830857312] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"167.709797ms","start":"2026-05-26T21:12:46.547297Z","end":"2026-05-26T21:12:46.715007Z","steps":["trace[830857312] 'process raft request' (duration: 151.688827ms)","trace[830857312] 'compare' (duration: 15.797254ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:12:46.715187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.357928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:12:46.715276Z","caller":"traceutil/trace.go:171","msg":"trace[2059395159] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2220; }","duration":"139.488412ms","start":"2026-05-26T21:12:46.57577Z","end":"2026-05-26T21:12:46.715258Z","steps":["trace[2059395159] 'agreement among raft nodes before linearized reading' (duration: 139.261346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:12:47.300876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.442671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:12:47.301095Z","caller":"traceutil/trace.go:171","msg":"trace[717173527] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"208.19278ms","start":"2026-05-26T21:12:47.092879Z","end":"2026-05-26T21:12:47.301072Z","steps":["trace[717173527] 'process raft request' (duration: 72.470281ms)","trace[717173527] 'compare' (duration: 135.210175ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:12:52.793742Z","caller":"traceutil/trace.go:171","msg":"trace[174901348] transaction","detail":"{read_only:false; response_revision:2248; number_of_response:1; }","duration":"190.01844ms","start":"2026-05-26T21:12:52.603695Z","end":"2026-05-26T21:12:52.793714Z","steps":["trace[174901348] 'process raft request' (duration: 189.835115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:12:53.147264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.328158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:12:53.147358Z","caller":"traceutil/trace.go:171","msg":"trace[1137028848] transaction","detail":"{read_only:false; response_revision:2249; number_of_response:1; }","duration":"412.38703ms","start":"2026-05-26T21:12:52.734957Z","end":"2026-05-26T21:12:53.147344Z","steps":["trace[1137028848] 'process raft request' (duration: 165.911168ms)","trace[1137028848] 'compare' (duration: 245.992009ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:12:53.147399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:12:52.73493Z","time spent":"412.447892ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:12:53.32559Z","caller":"traceutil/trace.go:171","msg":"trace[552110184] transaction","detail":"{read_only:false; response_revision:2250; number_of_response:1; }","duration":"246.551624ms","start":"2026-05-26T21:12:53.078962Z","end":"2026-05-26T21:12:53.325514Z","steps":["trace[552110184] 'process raft request' (duration: 168.099509ms)","trace[552110184] 'compare' (duration: 77.984842ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:12:53.325615Z","caller":"traceutil/trace.go:171","msg":"trace[760267096] transaction","detail":"{read_only:false; response_revision:2251; number_of_response:1; }","duration":"217.533966ms","start":"2026-05-26T21:12:53.108025Z","end":"2026-05-26T21:12:53.325559Z","steps":["trace[760267096] 'process raft request' (duration: 217.31741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:14:34.819468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.425508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:14:34.819625Z","caller":"traceutil/trace.go:171","msg":"trace[762132193] transaction","detail":"{read_only:false; response_revision:2988; number_of_response:1; }","duration":"198.165687ms","start":"2026-05-26T21:14:34.621432Z","end":"2026-05-26T21:14:34.819598Z","steps":["trace[762132193] 'process raft request' (duration: 62.505142ms)","trace[762132193] 'compare' (duration: 135.212143ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:14:35.154341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.343967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-26T21:14:35.154459Z","caller":"traceutil/trace.go:171","msg":"trace[1964651863] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2988; }","duration":"228.510761ms","start":"2026-05-26T21:14:34.92592Z","end":"2026-05-26T21:14:35.154431Z","steps":["trace[1964651863] 'range keys from in-memory index tree' (duration: 228.154791ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:14:35.303908Z","caller":"traceutil/trace.go:171","msg":"trace[839653412] transaction","detail":"{read_only:false; response_revision:2989; number_of_response:1; }","duration":"144.465547ms","start":"2026-05-26T21:14:35.159418Z","end":"2026-05-26T21:14:35.303884Z","steps":["trace[839653412] 'process raft request' (duration: 144.311143ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:17:11.621364Z","caller":"traceutil/trace.go:171","msg":"trace[495739351] transaction","detail":"{read_only:false; response_revision:4012; number_of_response:1; }","duration":"176.393177ms","start":"2026-05-26T21:17:11.444936Z","end":"2026-05-26T21:17:11.621329Z","steps":["trace[495739351] 'process raft request' (duration: 175.028409ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:18:32.044042Z","caller":"traceutil/trace.go:171","msg":"trace[1755336436] linearizableReadLoop","detail":"{readStateIndex:5250; appliedIndex:5249; }","duration":"125.053591ms","start":"2026-05-26T21:18:31.918955Z","end":"2026-05-26T21:18:32.044009Z","steps":["trace[1755336436] 'read index received' (duration: 108.559753ms)","trace[1755336436] 'applied index is now lower than readState.Index' (duration: 16.492508ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:18:32.044222Z","caller":"traceutil/trace.go:171","msg":"trace[160425543] transaction","detail":"{read_only:false; response_revision:5055; number_of_response:1; }","duration":"126.321476ms","start":"2026-05-26T21:18:31.917839Z","end":"2026-05-26T21:18:32.044161Z","steps":["trace[160425543] 'process raft request' (duration: 109.659584ms)","trace[160425543] 'compare' (duration: 16.354284ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:18:32.044338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.839914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-05-26T21:18:32.044396Z","caller":"traceutil/trace.go:171","msg":"trace[1949329130] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5055; }","duration":"120.932726ms","start":"2026-05-26T21:18:31.923445Z","end":"2026-05-26T21:18:32.044378Z","steps":["trace[1949329130] 'agreement among raft nodes before linearized reading' (duration: 120.742981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:18:32.044422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.432401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:75521"} {"level":"info","ts":"2026-05-26T21:18:32.044508Z","caller":"traceutil/trace.go:171","msg":"trace[1058922779] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:5055; }","duration":"125.542904ms","start":"2026-05-26T21:18:31.918946Z","end":"2026-05-26T21:18:32.044489Z","steps":["trace[1058922779] 'agreement among raft nodes before linearized reading' (duration: 125.217785ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:18:32.840085Z","caller":"traceutil/trace.go:171","msg":"trace[2141383383] transaction","detail":"{read_only:false; response_revision:5064; number_of_response:1; }","duration":"149.906491ms","start":"2026-05-26T21:18:32.690154Z","end":"2026-05-26T21:18:32.84006Z","steps":["trace[2141383383] 'process raft request' (duration: 148.627015ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:18:49.665119Z","caller":"traceutil/trace.go:171","msg":"trace[502305261] transaction","detail":"{read_only:false; response_revision:5453; number_of_response:1; }","duration":"186.856466ms","start":"2026-05-26T21:18:49.478233Z","end":"2026-05-26T21:18:49.66509Z","steps":["trace[502305261] 'process raft request' (duration: 186.66075ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:18:49.980451Z","caller":"traceutil/trace.go:171","msg":"trace[1877363920] transaction","detail":"{read_only:false; response_revision:5454; number_of_response:1; }","duration":"495.848731ms","start":"2026-05-26T21:18:49.48458Z","end":"2026-05-26T21:18:49.980429Z","steps":["trace[1877363920] 'process raft request' (duration: 489.652739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:18:49.980597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:18:49.484551Z","time spent":"495.968085ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:18:49.980682Z","caller":"traceutil/trace.go:171","msg":"trace[533576960] linearizableReadLoop","detail":"{readStateIndex:5659; appliedIndex:5657; }","duration":"488.785876ms","start":"2026-05-26T21:18:49.491867Z","end":"2026-05-26T21:18:49.980653Z","steps":["trace[533576960] 'read index received' (duration: 173.072783ms)","trace[533576960] 'applied index is now lower than readState.Index' (duration: 315.710903ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:18:49.980795Z","caller":"traceutil/trace.go:171","msg":"trace[1853106205] transaction","detail":"{read_only:false; response_revision:5455; number_of_response:1; }","duration":"468.666857ms","start":"2026-05-26T21:18:49.512106Z","end":"2026-05-26T21:18:49.980773Z","steps":["trace[1853106205] 'process raft request' (duration: 468.272966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:18:49.980924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:18:49.512095Z","time spent":"468.745049ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:18:49.981011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.134225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:18:49.981071Z","caller":"traceutil/trace.go:171","msg":"trace[243586928] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5455; }","duration":"489.227938ms","start":"2026-05-26T21:18:49.491826Z","end":"2026-05-26T21:18:49.981054Z","steps":["trace[243586928] 'agreement among raft nodes before linearized reading' (duration: 488.94563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:18:49.981141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:18:49.491813Z","time spent":"489.292329ms","remote":"127.0.0.1:52542","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-05-26T21:18:49.981239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.220272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-zs72b\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-05-26T21:18:49.981344Z","caller":"traceutil/trace.go:171","msg":"trace[1290051337] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-zs72b; range_end:; response_count:1; response_revision:5455; }","duration":"122.383717ms","start":"2026-05-26T21:18:49.85893Z","end":"2026-05-26T21:18:49.981313Z","steps":["trace[1290051337] 'agreement among raft nodes before linearized reading' (duration: 121.884353ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:18:56.911094Z","caller":"traceutil/trace.go:171","msg":"trace[372666725] linearizableReadLoop","detail":"{readStateIndex:5835; appliedIndex:5834; }","duration":"178.95569ms","start":"2026-05-26T21:18:56.732102Z","end":"2026-05-26T21:18:56.911057Z","steps":["trace[372666725] 'read index received' (duration: 136.339254ms)","trace[372666725] 'applied index is now lower than readState.Index' (duration: 42.615236ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:18:56.911163Z","caller":"traceutil/trace.go:171","msg":"trace[1406835812] transaction","detail":"{read_only:false; response_revision:5625; number_of_response:1; }","duration":"190.134921ms","start":"2026-05-26T21:18:56.720939Z","end":"2026-05-26T21:18:56.911073Z","steps":["trace[1406835812] 'process raft request' (duration: 147.434223ms)","trace[1406835812] 'compare' (duration: 42.504043ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:18:56.911324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.199826ms","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-05-26T21:18:56.911376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.150325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-26T21:18:56.911409Z","caller":"traceutil/trace.go:171","msg":"trace[2007837700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5625; }","duration":"179.32889ms","start":"2026-05-26T21:18:56.732063Z","end":"2026-05-26T21:18:56.911392Z","steps":["trace[2007837700] 'agreement among raft nodes before linearized reading' (duration: 179.084613ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:18:56.911419Z","caller":"traceutil/trace.go:171","msg":"trace[1307083252] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5625; }","duration":"179.245317ms","start":"2026-05-26T21:18:56.732159Z","end":"2026-05-26T21:18:56.911404Z","steps":["trace[1307083252] 'agreement among raft nodes before linearized reading' (duration: 179.074033ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:18:57.047862Z","caller":"traceutil/trace.go:171","msg":"trace[2143725255] transaction","detail":"{read_only:false; response_revision:5626; number_of_response:1; }","duration":"133.076712ms","start":"2026-05-26T21:18:56.91475Z","end":"2026-05-26T21:18:57.047827Z","steps":["trace[2143725255] 'process raft request' (duration: 132.872757ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:18:57.089589Z","caller":"traceutil/trace.go:171","msg":"trace[1641827388] transaction","detail":"{read_only:false; response_revision:5627; number_of_response:1; }","duration":"174.266699ms","start":"2026-05-26T21:18:56.915298Z","end":"2026-05-26T21:18:57.089564Z","steps":["trace[1641827388] 'process raft request' (duration: 173.722894ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:18:57.269996Z","caller":"traceutil/trace.go:171","msg":"trace[2004837276] transaction","detail":"{read_only:false; response_revision:5629; number_of_response:1; }","duration":"172.597033ms","start":"2026-05-26T21:18:57.097368Z","end":"2026-05-26T21:18:57.269965Z","steps":["trace[2004837276] 'process raft request' (duration: 119.269409ms)","trace[2004837276] 'compare' (duration: 53.097547ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:19:37.010122Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3000} {"level":"info","ts":"2026-05-26T21:19:37.085306Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3000,"took":"74.062481ms","hash":2239010124,"current-db-size-bytes":56848384,"current-db-size":"57 MB","current-db-size-in-use-bytes":33128448,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-26T21:19:37.085369Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2239010124,"revision":3000,"compact-revision":-1} {"level":"info","ts":"2026-05-26T21:24:37.017276Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6021} {"level":"info","ts":"2026-05-26T21:24:37.108366Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6021,"took":"89.156622ms","hash":4151870059,"current-db-size-bytes":56848384,"current-db-size":"57 MB","current-db-size-in-use-bytes":21430272,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-05-26T21:24:37.108447Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4151870059,"revision":6021,"compact-revision":3000} {"level":"info","ts":"2026-05-26T21:25:08.256651Z","caller":"traceutil/trace.go:171","msg":"trace[820075840] linearizableReadLoop","detail":"{readStateIndex:8766; appliedIndex:8765; }","duration":"163.045155ms","start":"2026-05-26T21:25:08.093577Z","end":"2026-05-26T21:25:08.256622Z","steps":["trace[820075840] 'read index received' (duration: 162.87442ms)","trace[820075840] 'applied index is now lower than readState.Index' (duration: 169.185µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:25:08.25672Z","caller":"traceutil/trace.go:171","msg":"trace[1131372194] transaction","detail":"{read_only:false; response_revision:8463; number_of_response:1; }","duration":"248.160074ms","start":"2026-05-26T21:25:08.008522Z","end":"2026-05-26T21:25:08.256682Z","steps":["trace[1131372194] 'process raft request' (duration: 247.913507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:25:08.256897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.301053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-26T21:25:08.256939Z","caller":"traceutil/trace.go:171","msg":"trace[1557036946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8463; }","duration":"163.389896ms","start":"2026-05-26T21:25:08.093536Z","end":"2026-05-26T21:25:08.256926Z","steps":["trace[1557036946] 'agreement among raft nodes before linearized reading' (duration: 163.223091ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:25:08.436625Z","caller":"traceutil/trace.go:171","msg":"trace[900174974] transaction","detail":"{read_only:false; response_revision:8464; number_of_response:1; }","duration":"210.724163ms","start":"2026-05-26T21:25:08.225863Z","end":"2026-05-26T21:25:08.436587Z","steps":["trace[900174974] 'process raft request' (duration: 125.545622ms)","trace[900174974] 'compare' (duration: 84.855352ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:25:08.436775Z","caller":"traceutil/trace.go:171","msg":"trace[24524989] transaction","detail":"{read_only:false; response_revision:8465; number_of_response:1; }","duration":"175.68515ms","start":"2026-05-26T21:25:08.261071Z","end":"2026-05-26T21:25:08.436756Z","steps":["trace[24524989] 'process raft request' (duration: 175.407892ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:25:20.7709Z","caller":"traceutil/trace.go:171","msg":"trace[533061380] linearizableReadLoop","detail":"{readStateIndex:8834; appliedIndex:8832; }","duration":"278.906268ms","start":"2026-05-26T21:25:20.491958Z","end":"2026-05-26T21:25:20.770864Z","steps":["trace[533061380] 'read index received' (duration: 30.703722ms)","trace[533061380] 'applied index is now lower than readState.Index' (duration: 248.200886ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:25:20.771117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.140994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-26T21:25:20.771132Z","caller":"traceutil/trace.go:171","msg":"trace[731167737] transaction","detail":"{read_only:false; response_revision:8528; number_of_response:1; }","duration":"296.215754ms","start":"2026-05-26T21:25:20.474897Z","end":"2026-05-26T21:25:20.771113Z","steps":["trace[731167737] 'process raft request' (duration: 295.822383ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:25:20.771153Z","caller":"traceutil/trace.go:171","msg":"trace[940111104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8528; }","duration":"279.235027ms","start":"2026-05-26T21:25:20.491909Z","end":"2026-05-26T21:25:20.771144Z","steps":["trace[940111104] 'agreement among raft nodes before linearized reading' (duration: 279.076162ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:25:20.900105Z","caller":"traceutil/trace.go:171","msg":"trace[929686764] transaction","detail":"{read_only:false; response_revision:8529; number_of_response:1; }","duration":"230.340474ms","start":"2026-05-26T21:25:20.669743Z","end":"2026-05-26T21:25:20.900084Z","steps":["trace[929686764] 'process raft request' (duration: 204.060367ms)","trace[929686764] 'compare' (duration: 26.183894ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:25:20.961443Z","caller":"traceutil/trace.go:171","msg":"trace[1036402861] transaction","detail":"{read_only:false; response_revision:8530; number_of_response:1; }","duration":"186.134664ms","start":"2026-05-26T21:25:20.775285Z","end":"2026-05-26T21:25:20.96142Z","steps":["trace[1036402861] 'process raft request' (duration: 186.01093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:25:49.17828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.575141ms","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:7"} {"level":"info","ts":"2026-05-26T21:25:49.178353Z","caller":"traceutil/trace.go:171","msg":"trace[441686596] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:8734; }","duration":"107.682955ms","start":"2026-05-26T21:25:49.070654Z","end":"2026-05-26T21:25:49.178337Z","steps":["trace[441686596] 'count revisions from in-memory index tree' (duration: 107.519ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:25:49.368631Z","caller":"traceutil/trace.go:171","msg":"trace[530405290] linearizableReadLoop","detail":"{readStateIndex:9048; appliedIndex:9047; }","duration":"201.958184ms","start":"2026-05-26T21:25:49.166647Z","end":"2026-05-26T21:25:49.368606Z","steps":["trace[530405290] 'read index received' (duration: 201.741338ms)","trace[530405290] 'applied index is now lower than readState.Index' (duration: 215.266µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:25:49.368677Z","caller":"traceutil/trace.go:171","msg":"trace[489976939] transaction","detail":"{read_only:false; response_revision:8735; number_of_response:1; }","duration":"203.498597ms","start":"2026-05-26T21:25:49.165151Z","end":"2026-05-26T21:25:49.368649Z","steps":["trace[489976939] 'process raft request' (duration: 203.277641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:25:49.369404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.348835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-26T21:25:49.369486Z","caller":"traceutil/trace.go:171","msg":"trace[1266181890] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8735; }","duration":"202.87509ms","start":"2026-05-26T21:25:49.166588Z","end":"2026-05-26T21:25:49.369463Z","steps":["trace[1266181890] 'agreement among raft nodes before linearized reading' (duration: 202.14385ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:25:49.370593Z","caller":"traceutil/trace.go:171","msg":"trace[590127660] transaction","detail":"{read_only:false; response_revision:8736; number_of_response:1; }","duration":"186.101411ms","start":"2026-05-26T21:25:49.18445Z","end":"2026-05-26T21:25:49.370552Z","steps":["trace[590127660] 'process raft request' (duration: 185.531675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:25:49.370614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.397204ms","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-05-26T21:25:49.37086Z","caller":"traceutil/trace.go:171","msg":"trace[807205663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8736; }","duration":"203.62232ms","start":"2026-05-26T21:25:49.167173Z","end":"2026-05-26T21:25:49.370795Z","steps":["trace[807205663] 'agreement among raft nodes before linearized reading' (duration: 203.192348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:26:02.315097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.870158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-05-26T21:26:02.315201Z","caller":"traceutil/trace.go:171","msg":"trace[87747658] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8850; }","duration":"104.041362ms","start":"2026-05-26T21:26:02.211137Z","end":"2026-05-26T21:26:02.315178Z","steps":["trace[87747658] 'range keys from in-memory index tree' (duration: 103.732064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:26:03.322671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.452096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"info","ts":"2026-05-26T21:26:03.322775Z","caller":"traceutil/trace.go:171","msg":"trace[924765905] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8854; }","duration":"217.58837ms","start":"2026-05-26T21:26:03.10515Z","end":"2026-05-26T21:26:03.322739Z","steps":["trace[924765905] 'range keys from in-memory index tree' (duration: 217.22971ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:26:17.459896Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000245c.wal"} {"level":"info","ts":"2026-05-26T21:27:26.007079Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"35b2766da46a76bb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-26T21:27:26.012472Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-26T21:27:26.012545Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-26T21:29:37.02272Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8122} {"level":"info","ts":"2026-05-26T21:29:37.08401Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8122,"took":"59.092538ms","hash":1923110534,"current-db-size-bytes":56848384,"current-db-size":"57 MB","current-db-size-in-use-bytes":29233152,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-26T21:29:37.084127Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1923110534,"revision":8122,"compact-revision":6021} {"level":"info","ts":"2026-05-26T21:29:40.791862Z","caller":"traceutil/trace.go:171","msg":"trace[1786632262] transaction","detail":"{read_only:false; response_revision:10665; number_of_response:1; }","duration":"134.926254ms","start":"2026-05-26T21:29:40.656912Z","end":"2026-05-26T21:29:40.791838Z","steps":["trace[1786632262] 'process raft request' (duration: 87.060599ms)","trace[1786632262] 'compare' (duration: 47.756772ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:31:04.307495Z","caller":"traceutil/trace.go:171","msg":"trace[806202820] linearizableReadLoop","detail":"{readStateIndex:11849; appliedIndex:11848; }","duration":"258.221329ms","start":"2026-05-26T21:31:04.04924Z","end":"2026-05-26T21:31:04.307462Z","steps":["trace[806202820] 'read index received' (duration: 257.942352ms)","trace[806202820] 'applied index is now lower than readState.Index' (duration: 277.337µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:31:04.307814Z","caller":"traceutil/trace.go:171","msg":"trace[869480045] transaction","detail":"{read_only:false; response_revision:11453; number_of_response:1; }","duration":"286.504357ms","start":"2026-05-26T21:31:04.021262Z","end":"2026-05-26T21:31:04.307767Z","steps":["trace[869480045] 'process raft request' (duration: 285.992433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:31:04.307846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.508328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-26T21:31:04.308224Z","caller":"traceutil/trace.go:171","msg":"trace[209698804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11453; }","duration":"259.007752ms","start":"2026-05-26T21:31:04.049188Z","end":"2026-05-26T21:31:04.308196Z","steps":["trace[209698804] 'agreement among raft nodes before linearized reading' (duration: 258.402175ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:34:37.030977Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10639} {"level":"info","ts":"2026-05-26T21:34:37.113263Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10639,"took":"79.533596ms","hash":2983094751,"current-db-size-bytes":56848384,"current-db-size":"57 MB","current-db-size-in-use-bytes":27209728,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-26T21:34:37.113341Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2983094751,"revision":10639,"compact-revision":8122} {"level":"warn","ts":"2026-05-26T21:37:14.185746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.93264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T21:37:14.185858Z","caller":"traceutil/trace.go:171","msg":"trace[452837323] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:13941; }","duration":"117.098935ms","start":"2026-05-26T21:37:14.068737Z","end":"2026-05-26T21:37:14.185836Z","steps":["trace[452837323] 'count revisions from in-memory index tree' (duration: 116.853898ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:37:17.015631Z","caller":"traceutil/trace.go:171","msg":"trace[357612874] linearizableReadLoop","detail":"{readStateIndex:14438; appliedIndex:14437; }","duration":"116.493448ms","start":"2026-05-26T21:37:16.899111Z","end":"2026-05-26T21:37:17.015605Z","steps":["trace[357612874] 'read index received' (duration: 116.212141ms)","trace[357612874] 'applied index is now lower than readState.Index' (duration: 280.397µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:37:17.016027Z","caller":"traceutil/trace.go:171","msg":"trace[271405962] transaction","detail":"{read_only:false; response_revision:13956; number_of_response:1; }","duration":"118.632968ms","start":"2026-05-26T21:37:16.897379Z","end":"2026-05-26T21:37:17.016012Z","steps":["trace[271405962] 'process raft request' (duration: 118.057002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:37:17.016889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.755883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:62 size:582716"} {"level":"info","ts":"2026-05-26T21:37:17.016934Z","caller":"traceutil/trace.go:171","msg":"trace[1601879789] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:62; response_revision:13956; }","duration":"117.837616ms","start":"2026-05-26T21:37:16.899086Z","end":"2026-05-26T21:37:17.016923Z","steps":["trace[1601879789] 'agreement among raft nodes before linearized reading' (duration: 117.234569ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:37:51.991586Z","caller":"traceutil/trace.go:171","msg":"trace[198433874] transaction","detail":"{read_only:false; response_revision:14158; number_of_response:1; }","duration":"219.978104ms","start":"2026-05-26T21:37:51.771582Z","end":"2026-05-26T21:37:51.99156Z","steps":["trace[198433874] 'process raft request' (duration: 219.813769ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:38:01.459468Z","caller":"traceutil/trace.go:171","msg":"trace[127999788] transaction","detail":"{read_only:false; response_revision:14328; number_of_response:1; }","duration":"148.648483ms","start":"2026-05-26T21:38:01.310796Z","end":"2026-05-26T21:38:01.459445Z","steps":["trace[127999788] 'process raft request' (duration: 148.509659ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:38:01.460579Z","caller":"traceutil/trace.go:171","msg":"trace[1313819229] transaction","detail":"{read_only:false; response_revision:14329; number_of_response:1; }","duration":"137.936415ms","start":"2026-05-26T21:38:01.322608Z","end":"2026-05-26T21:38:01.460544Z","steps":["trace[1313819229] 'process raft request' (duration: 137.625306ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:39:36.066665Z","caller":"traceutil/trace.go:171","msg":"trace[959104990] transaction","detail":"{read_only:false; response_revision:15388; number_of_response:1; }","duration":"104.235718ms","start":"2026-05-26T21:39:35.962379Z","end":"2026-05-26T21:39:36.066615Z","steps":["trace[959104990] 'process raft request' (duration: 83.922381ms)","trace[959104990] 'compare' (duration: 20.104211ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:39:37.039748Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12812} {"level":"info","ts":"2026-05-26T21:39:37.113606Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12812,"took":"70.99369ms","hash":2435146723,"current-db-size-bytes":56848384,"current-db-size":"57 MB","current-db-size-in-use-bytes":30683136,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-26T21:39:37.113671Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2435146723,"revision":12812,"compact-revision":10639} {"level":"info","ts":"2026-05-26T21:39:41.233474Z","caller":"traceutil/trace.go:171","msg":"trace[405102355] transaction","detail":"{read_only:false; response_revision:15417; number_of_response:1; }","duration":"179.305822ms","start":"2026-05-26T21:39:41.054142Z","end":"2026-05-26T21:39:41.233448Z","steps":["trace[405102355] 'process raft request' (duration: 179.112987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:39:41.234342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.533361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T21:39:41.234422Z","caller":"traceutil/trace.go:171","msg":"trace[484768563] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:15417; }","duration":"178.664434ms","start":"2026-05-26T21:39:41.055735Z","end":"2026-05-26T21:39:41.234399Z","steps":["trace[484768563] 'agreement among raft nodes before linearized reading' (duration: 178.449458ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:39:41.234032Z","caller":"traceutil/trace.go:171","msg":"trace[1536516138] linearizableReadLoop","detail":"{readStateIndex:15945; appliedIndex:15945; }","duration":"178.199432ms","start":"2026-05-26T21:39:41.05576Z","end":"2026-05-26T21:39:41.23396Z","steps":["trace[1536516138] 'read index received' (duration: 178.188901ms)","trace[1536516138] 'applied index is now lower than readState.Index' (duration: 8.831µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:39:41.24271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.350271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T21:39:41.242712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.877156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:39:41.242738Z","caller":"traceutil/trace.go:171","msg":"trace[1468235272] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15417; }","duration":"104.423273ms","start":"2026-05-26T21:39:41.138306Z","end":"2026-05-26T21:39:41.24273Z","steps":["trace[1468235272] 'agreement among raft nodes before linearized reading' (duration: 104.363692ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:39:41.242743Z","caller":"traceutil/trace.go:171","msg":"trace[1684398670] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15417; }","duration":"104.941577ms","start":"2026-05-26T21:39:41.137794Z","end":"2026-05-26T21:39:41.242735Z","steps":["trace[1684398670] 'agreement among raft nodes before linearized reading' (duration: 104.874316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:39:41.242825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.805274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:39:41.242843Z","caller":"traceutil/trace.go:171","msg":"trace[1586707261] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15417; }","duration":"104.839285ms","start":"2026-05-26T21:39:41.137998Z","end":"2026-05-26T21:39:41.242838Z","steps":["trace[1586707261] 'agreement among raft nodes before linearized reading' (duration: 104.810304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:39:41.243022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.523779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-05-26T21:39:41.24309Z","caller":"traceutil/trace.go:171","msg":"trace[42623058] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15417; }","duration":"103.627431ms","start":"2026-05-26T21:39:41.139448Z","end":"2026-05-26T21:39:41.243075Z","steps":["trace[42623058] 'agreement among raft nodes before linearized reading' (duration: 103.416015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:39:42.373218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.627792ms","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-05-26T21:39:42.373316Z","caller":"traceutil/trace.go:171","msg":"trace[1497549872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15420; }","duration":"283.758986ms","start":"2026-05-26T21:39:42.089534Z","end":"2026-05-26T21:39:42.373293Z","steps":["trace[1497549872] 'range keys from in-memory index tree' (duration: 283.491298ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:39:42.539062Z","caller":"traceutil/trace.go:171","msg":"trace[1830422809] transaction","detail":"{read_only:false; response_revision:15422; number_of_response:1; }","duration":"103.113036ms","start":"2026-05-26T21:39:42.435924Z","end":"2026-05-26T21:39:42.539037Z","steps":["trace[1830422809] 'process raft request' (duration: 102.927991ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:44:37.045807Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15394} {"level":"info","ts":"2026-05-26T21:44:37.144533Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15394,"took":"95.716391ms","hash":2055839515,"current-db-size-bytes":56848384,"current-db-size":"57 MB","current-db-size-in-use-bytes":31944704,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-26T21:44:37.144612Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2055839515,"revision":15394,"compact-revision":12812} {"level":"info","ts":"2026-05-26T21:45:23.10731Z","caller":"traceutil/trace.go:171","msg":"trace[588933545] transaction","detail":"{read_only:false; response_revision:17934; number_of_response:1; }","duration":"102.973663ms","start":"2026-05-26T21:45:23.004305Z","end":"2026-05-26T21:45:23.107279Z","steps":["trace[588933545] 'process raft request' (duration: 102.817408ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:45:23.170088Z","caller":"traceutil/trace.go:171","msg":"trace[1019860336] transaction","detail":"{read_only:false; response_revision:17935; number_of_response:1; }","duration":"161.624489ms","start":"2026-05-26T21:45:23.008416Z","end":"2026-05-26T21:45:23.170041Z","steps":["trace[1019860336] 'process raft request' (duration: 161.356601ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:45:23.549979Z","caller":"traceutil/trace.go:171","msg":"trace[1525497847] transaction","detail":"{read_only:false; response_revision:17936; number_of_response:1; }","duration":"105.73426ms","start":"2026-05-26T21:45:23.44421Z","end":"2026-05-26T21:45:23.549945Z","steps":["trace[1525497847] 'process raft request' (duration: 105.555445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:45:23.849466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.113954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-26T21:45:23.849561Z","caller":"traceutil/trace.go:171","msg":"trace[523836938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17936; }","duration":"214.253598ms","start":"2026-05-26T21:45:23.635287Z","end":"2026-05-26T21:45:23.84954Z","steps":["trace[523836938] 'range keys from in-memory index tree' (duration: 213.917128ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:45:24.114442Z","caller":"traceutil/trace.go:171","msg":"trace[971651108] transaction","detail":"{read_only:false; response_revision:17937; number_of_response:1; }","duration":"438.078914ms","start":"2026-05-26T21:45:23.67634Z","end":"2026-05-26T21:45:24.114419Z","steps":["trace[971651108] 'process raft request' (duration: 437.802856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:45:24.114733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.186938ms","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":"warn","ts":"2026-05-26T21:45:24.114758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:45:23.676315Z","time spent":"438.212997ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:45:24.11481Z","caller":"traceutil/trace.go:171","msg":"trace[1674911757] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17937; }","duration":"404.29852ms","start":"2026-05-26T21:45:23.710489Z","end":"2026-05-26T21:45:24.114787Z","steps":["trace[1674911757] 'agreement among raft nodes before linearized reading' (duration: 404.079094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:45:24.11487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:45:23.710475Z","time spent":"404.381493ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-26T21:45:24.114367Z","caller":"traceutil/trace.go:171","msg":"trace[315807053] linearizableReadLoop","detail":"{readStateIndex:18546; appliedIndex:18545; }","duration":"403.801897ms","start":"2026-05-26T21:45:23.710541Z","end":"2026-05-26T21:45:24.114343Z","steps":["trace[315807053] 'read index received' (duration: 403.636202ms)","trace[315807053] 'applied index is now lower than readState.Index' (duration: 164.825µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:45:24.123047Z","caller":"traceutil/trace.go:171","msg":"trace[1058373945] transaction","detail":"{read_only:false; response_revision:17938; number_of_response:1; }","duration":"341.852188ms","start":"2026-05-26T21:45:23.781174Z","end":"2026-05-26T21:45:24.123026Z","steps":["trace[1058373945] 'process raft request' (duration: 341.713664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:45:24.123165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:45:23.781145Z","time spent":"341.958131ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:45:24.123308Z","caller":"traceutil/trace.go:171","msg":"trace[283629741] transaction","detail":"{read_only:false; response_revision:17939; number_of_response:1; }","duration":"340.546771ms","start":"2026-05-26T21:45:23.782747Z","end":"2026-05-26T21:45:24.123294Z","steps":["trace[283629741] 'process raft request' (duration: 340.242543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:45:24.123342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.317873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-6phrb\" ","response":"range_response_count:1 size:9789"} {"level":"warn","ts":"2026-05-26T21:45:24.12339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:45:23.782723Z","time spent":"340.625024ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:45:24.123408Z","caller":"traceutil/trace.go:171","msg":"trace[171990953] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-6phrb; range_end:; response_count:1; response_revision:17940; }","duration":"384.427986ms","start":"2026-05-26T21:45:23.738965Z","end":"2026-05-26T21:45:24.123393Z","steps":["trace[171990953] 'agreement among raft nodes before linearized reading' (duration: 384.238221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:45:24.123445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:45:23.738942Z","time spent":"384.495468ms","remote":"127.0.0.1:52710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9812,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-6phrb\" "} {"level":"info","ts":"2026-05-26T21:45:24.123608Z","caller":"traceutil/trace.go:171","msg":"trace[1244767218] transaction","detail":"{read_only:false; response_revision:17940; number_of_response:1; }","duration":"269.17971ms","start":"2026-05-26T21:45:23.854142Z","end":"2026-05-26T21:45:24.123322Z","steps":["trace[1244767218] 'process raft request' (duration: 268.981715ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:49:32.961456Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"35b2766da46a76bb","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-26T21:49:32.965722Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-26T21:49:32.965828Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-26T21:49:37.052845Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17609} {"level":"info","ts":"2026-05-26T21:49:37.144542Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17609,"took":"88.387635ms","hash":779216412,"current-db-size-bytes":56848384,"current-db-size":"57 MB","current-db-size-in-use-bytes":28188672,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-26T21:49:37.14461Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":779216412,"revision":17609,"compact-revision":15394}