{"level":"warn","ts":"2026-08-24T20:39:26.905058Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T20:39:26.905138Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.182: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.182:2380","--initial-cluster=instance=https://199.204.45.182:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.182:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.182: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-08-24T20:39:26.905194Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T20:39:26.905208Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.182:2380"]} {"level":"info","ts":"2026-08-24T20:39:26.905229Z","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-08-24T20:39:26.905849Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.182:2379"]} {"level":"info","ts":"2026-08-24T20:39:26.905919Z","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.182:2380"],"listen-peer-urls":["https://199.204.45.182:2380"],"advertise-client-urls":["https://199.204.45.182:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.182:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.182: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-08-24T20:39:26.910042Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.925171ms"} {"level":"info","ts":"2026-08-24T20:39:26.919208Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e44ae1545340c14b","cluster-id":"7284b69afd367451"} {"level":"info","ts":"2026-08-24T20:39:26.919624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b switched to configuration voters=()"} {"level":"info","ts":"2026-08-24T20:39:26.919737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became follower at term 0"} {"level":"info","ts":"2026-08-24T20:39:26.919817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e44ae1545340c14b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-24T20:39:26.919932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became follower at term 1"} {"level":"info","ts":"2026-08-24T20:39:26.919984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b switched to configuration voters=(16450208341214413131)"} {"level":"warn","ts":"2026-08-24T20:39:26.936236Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-24T20:39:26.937735Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-24T20:39:26.939432Z","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-08-24T20:39:26.944101Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e44ae1545340c14b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-24T20:39:26.944412Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e44ae1545340c14b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-24T20:39:26.944613Z","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-08-24T20:39:26.94474Z","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-08-24T20:39:26.944777Z","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-08-24T20:39:26.945497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b switched to configuration voters=(16450208341214413131)"} {"level":"info","ts":"2026-08-24T20:39:26.945684Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7284b69afd367451","local-member-id":"e44ae1545340c14b","added-peer-id":"e44ae1545340c14b","added-peer-peer-urls":["https://199.204.45.182:2380"]} {"level":"info","ts":"2026-08-24T20:39:26.947391Z","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-08-24T20:39:26.947684Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.182:2380"} {"level":"info","ts":"2026-08-24T20:39:26.947684Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e44ae1545340c14b","initial-advertise-peer-urls":["https://199.204.45.182:2380"],"listen-peer-urls":["https://199.204.45.182:2380"],"advertise-client-urls":["https://199.204.45.182:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.182:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-24T20:39:26.947719Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.182:2380"} {"level":"info","ts":"2026-08-24T20:39:26.947758Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-24T20:39:27.423289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b is starting a new election at term 1"} {"level":"info","ts":"2026-08-24T20:39:27.423339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became pre-candidate at term 1"} {"level":"info","ts":"2026-08-24T20:39:27.423366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b received MsgPreVoteResp from e44ae1545340c14b at term 1"} {"level":"info","ts":"2026-08-24T20:39:27.423378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became candidate at term 2"} {"level":"info","ts":"2026-08-24T20:39:27.423384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b received MsgVoteResp from e44ae1545340c14b at term 2"} {"level":"info","ts":"2026-08-24T20:39:27.423392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e44ae1545340c14b became leader at term 2"} {"level":"info","ts":"2026-08-24T20:39:27.423402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e44ae1545340c14b elected leader e44ae1545340c14b at term 2"} {"level":"info","ts":"2026-08-24T20:39:27.431431Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e44ae1545340c14b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.182:2379]}","request-path":"/0/members/e44ae1545340c14b/attributes","cluster-id":"7284b69afd367451","publish-timeout":"7s"} {"level":"info","ts":"2026-08-24T20:39:27.431463Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T20:39:27.43168Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T20:39:27.431517Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T20:39:27.436179Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7284b69afd367451","local-member-id":"e44ae1545340c14b","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T20:39:27.436476Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T20:39:27.436402Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-24T20:39:27.436563Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T20:39:27.436587Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-24T20:39:27.440147Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.182:2379"} {"level":"info","ts":"2026-08-24T20:39:27.440199Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-24T20:39:46.26241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.126588ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-24T20:40:10.063688Z","caller":"traceutil/trace.go:171","msg":"trace[86940529] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"291.617761ms","start":"2026-08-24T20:40:09.772044Z","end":"2026-08-24T20:40:10.063662Z","steps":["trace[86940529] 'process raft request' (duration: 291.422268ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:40:10.070857Z","caller":"traceutil/trace.go:171","msg":"trace[148626725] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"253.252707ms","start":"2026-08-24T20:40:09.81758Z","end":"2026-08-24T20:40:10.070832Z","steps":["trace[148626725] 'process raft request' (duration: 253.129815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:40:10.5469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.922155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hkvq4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-24T20:40:10.547Z","caller":"traceutil/trace.go:171","msg":"trace[481836832] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hkvq4; range_end:; response_count:1; response_revision:637; }","duration":"223.129269ms","start":"2026-08-24T20:40:10.323846Z","end":"2026-08-24T20:40:10.546975Z","steps":["trace[481836832] 'range keys from in-memory index tree' (duration: 222.746833ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:40:26.098563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.568201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T20:40:26.098715Z","caller":"traceutil/trace.go:171","msg":"trace[1852680647] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"294.946596ms","start":"2026-08-24T20:40:25.803737Z","end":"2026-08-24T20:40:26.098683Z","steps":["trace[1852680647] 'process raft request' (duration: 109.330878ms)","trace[1852680647] 'compare' (duration: 184.386197ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:40:26.098782Z","caller":"traceutil/trace.go:171","msg":"trace[452212008] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:832; }","duration":"217.913262ms","start":"2026-08-24T20:40:25.880851Z","end":"2026-08-24T20:40:26.098764Z","steps":["trace[452212008] 'read index received' (duration: 32.162832ms)","trace[452212008] 'applied index is now lower than readState.Index' (duration: 185.74904ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:40:26.112088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.02704ms","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-08-24T20:40:26.112251Z","caller":"traceutil/trace.go:171","msg":"trace[51499861] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:803; }","duration":"231.433996ms","start":"2026-08-24T20:40:25.880793Z","end":"2026-08-24T20:40:26.112227Z","steps":["trace[51499861] 'agreement among raft nodes before linearized reading' (duration: 218.028864ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:41:19.877331Z","caller":"traceutil/trace.go:171","msg":"trace[154926300] linearizableReadLoop","detail":"{readStateIndex:1512; appliedIndex:1511; }","duration":"129.909781ms","start":"2026-08-24T20:41:19.747396Z","end":"2026-08-24T20:41:19.877306Z","steps":["trace[154926300] 'read index received' (duration: 116.884865ms)","trace[154926300] 'applied index is now lower than readState.Index' (duration: 13.023686ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:41:19.877471Z","caller":"traceutil/trace.go:171","msg":"trace[499129072] transaction","detail":"{read_only:false; response_revision:1466; number_of_response:1; }","duration":"137.741443ms","start":"2026-08-24T20:41:19.739687Z","end":"2026-08-24T20:41:19.877429Z","steps":["trace[499129072] 'process raft request' (duration: 124.580624ms)","trace[499129072] 'compare' (duration: 12.920234ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:41:19.877549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.128795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:41:19.87764Z","caller":"traceutil/trace.go:171","msg":"trace[766564755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1466; }","duration":"130.254167ms","start":"2026-08-24T20:41:19.747367Z","end":"2026-08-24T20:41:19.877622Z","steps":["trace[766564755] 'agreement among raft nodes before linearized reading' (duration: 130.089464ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:43.201212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.360918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-24T20:41:43.20132Z","caller":"traceutil/trace.go:171","msg":"trace[2119869181] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1873; }","duration":"185.52084ms","start":"2026-08-24T20:41:43.01578Z","end":"2026-08-24T20:41:43.201301Z","steps":["trace[2119869181] 'range keys from in-memory index tree' (duration: 185.115983ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:41:43.20132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.611778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e6f51d8e-55ad-4596-b379-5a31de4fe5df\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-24T20:41:43.201422Z","caller":"traceutil/trace.go:171","msg":"trace[1159960848] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e6f51d8e-55ad-4596-b379-5a31de4fe5df; range_end:; response_count:1; response_revision:1873; }","duration":"253.770672ms","start":"2026-08-24T20:41:42.947628Z","end":"2026-08-24T20:41:43.201398Z","steps":["trace[1159960848] 'range keys from in-memory index tree' (duration: 253.337313ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:42:26.946126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.693064ms","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-08-24T20:42:26.946277Z","caller":"traceutil/trace.go:171","msg":"trace[512087900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2157; }","duration":"111.879548ms","start":"2026-08-24T20:42:26.834352Z","end":"2026-08-24T20:42:26.946232Z","steps":["trace[512087900] 'range keys from in-memory index tree' (duration: 111.46751ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:43:24.892947Z","caller":"traceutil/trace.go:171","msg":"trace[1514821687] linearizableReadLoop","detail":"{readStateIndex:2747; appliedIndex:2746; }","duration":"142.813809ms","start":"2026-08-24T20:43:24.7501Z","end":"2026-08-24T20:43:24.892914Z","steps":["trace[1514821687] 'read index received' (duration: 142.641776ms)","trace[1514821687] 'applied index is now lower than readState.Index' (duration: 171.013µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:43:24.893067Z","caller":"traceutil/trace.go:171","msg":"trace[362144361] transaction","detail":"{read_only:false; response_revision:2644; number_of_response:1; }","duration":"146.606297ms","start":"2026-08-24T20:43:24.74641Z","end":"2026-08-24T20:43:24.893016Z","steps":["trace[362144361] 'process raft request' (duration: 146.329012ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:24.893117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.009793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:43:24.893188Z","caller":"traceutil/trace.go:171","msg":"trace[1923026543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2644; }","duration":"143.139886ms","start":"2026-08-24T20:43:24.750031Z","end":"2026-08-24T20:43:24.893171Z","steps":["trace[1923026543] 'agreement among raft nodes before linearized reading' (duration: 143.000104ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:43:58.797811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.504027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T20:43:58.798063Z","caller":"traceutil/trace.go:171","msg":"trace[2075413522] transaction","detail":"{read_only:false; response_revision:2846; number_of_response:1; }","duration":"120.0366ms","start":"2026-08-24T20:43:58.677995Z","end":"2026-08-24T20:43:58.798031Z","steps":["trace[2075413522] 'compare' (duration: 114.385215ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:43:58.798156Z","caller":"traceutil/trace.go:171","msg":"trace[1846000042] transaction","detail":"{read_only:false; response_revision:2847; number_of_response:1; }","duration":"119.985939ms","start":"2026-08-24T20:43:58.678108Z","end":"2026-08-24T20:43:58.798094Z","steps":["trace[1846000042] 'process raft request' (duration: 119.831276ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:43:58.798315Z","caller":"traceutil/trace.go:171","msg":"trace[2108051343] transaction","detail":"{read_only:false; response_revision:2848; number_of_response:1; }","duration":"120.125862ms","start":"2026-08-24T20:43:58.67814Z","end":"2026-08-24T20:43:58.798266Z","steps":["trace[2108051343] 'process raft request' (duration: 119.853027ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:43:59.159763Z","caller":"traceutil/trace.go:171","msg":"trace[1085991408] transaction","detail":"{read_only:false; response_revision:2849; number_of_response:1; }","duration":"148.807806ms","start":"2026-08-24T20:43:59.010935Z","end":"2026-08-24T20:43:59.159743Z","steps":["trace[1085991408] 'process raft request' (duration: 148.662784ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:47:44.851546Z","caller":"traceutil/trace.go:171","msg":"trace[1665772763] linearizableReadLoop","detail":"{readStateIndex:4977; appliedIndex:4976; }","duration":"104.029787ms","start":"2026-08-24T20:47:44.747487Z","end":"2026-08-24T20:47:44.851517Z","steps":["trace[1665772763] 'read index received' (duration: 25.675136ms)","trace[1665772763] 'applied index is now lower than readState.Index' (duration: 78.353371ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:47:44.851694Z","caller":"traceutil/trace.go:171","msg":"trace[584340656] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4792; }","duration":"136.232446ms","start":"2026-08-24T20:47:44.715449Z","end":"2026-08-24T20:47:44.851681Z","steps":["trace[584340656] 'process raft request' (duration: 57.767573ms)","trace[584340656] 'compare' (duration: 78.185677ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:47:44.852039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.534066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:47:44.852115Z","caller":"traceutil/trace.go:171","msg":"trace[968921726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4792; }","duration":"104.661499ms","start":"2026-08-24T20:47:44.747434Z","end":"2026-08-24T20:47:44.852096Z","steps":["trace[968921726] 'agreement among raft nodes before linearized reading' (duration: 104.543286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:47:51.343492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.028846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T20:47:51.343701Z","caller":"traceutil/trace.go:171","msg":"trace[439877528] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"152.92084ms","start":"2026-08-24T20:47:51.190748Z","end":"2026-08-24T20:47:51.343668Z","steps":["trace[439877528] 'process raft request' (duration: 29.61158ms)","trace[439877528] 'compare' (duration: 122.72727ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:48:04.78232Z","caller":"traceutil/trace.go:171","msg":"trace[1661252001] transaction","detail":"{read_only:false; response_revision:5278; number_of_response:1; }","duration":"194.029128ms","start":"2026-08-24T20:48:04.588266Z","end":"2026-08-24T20:48:04.782295Z","steps":["trace[1661252001] 'process raft request' (duration: 193.886285ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:48:12.141825Z","caller":"traceutil/trace.go:171","msg":"trace[1442148976] transaction","detail":"{read_only:false; response_revision:5418; number_of_response:1; }","duration":"102.728472ms","start":"2026-08-24T20:48:12.03907Z","end":"2026-08-24T20:48:12.141799Z","steps":["trace[1442148976] 'process raft request' (duration: 69.677287ms)","trace[1442148976] 'compare' (duration: 32.881762ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:48:12.380867Z","caller":"traceutil/trace.go:171","msg":"trace[1909276358] transaction","detail":"{read_only:false; response_revision:5425; number_of_response:1; }","duration":"171.070974ms","start":"2026-08-24T20:48:12.20977Z","end":"2026-08-24T20:48:12.380841Z","steps":["trace[1909276358] 'process raft request' (duration: 170.918701ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:48:12.381352Z","caller":"traceutil/trace.go:171","msg":"trace[176708644] linearizableReadLoop","detail":"{readStateIndex:5631; appliedIndex:5628; }","duration":"148.088549ms","start":"2026-08-24T20:48:12.233231Z","end":"2026-08-24T20:48:12.38132Z","steps":["trace[176708644] 'read index received' (duration: 147.530828ms)","trace[176708644] 'applied index is now lower than readState.Index' (duration: 556.311µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:48:12.381641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.90281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-24T20:48:12.38162Z","caller":"traceutil/trace.go:171","msg":"trace[1365115754] transaction","detail":"{read_only:false; response_revision:5427; number_of_response:1; }","duration":"159.938353ms","start":"2026-08-24T20:48:12.22157Z","end":"2026-08-24T20:48:12.381509Z","steps":["trace[1365115754] 'process raft request' (duration: 159.618818ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:48:12.381695Z","caller":"traceutil/trace.go:171","msg":"trace[1767056158] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5427; }","duration":"172.012722ms","start":"2026-08-24T20:48:12.209667Z","end":"2026-08-24T20:48:12.38168Z","steps":["trace[1767056158] 'agreement among raft nodes before linearized reading' (duration: 171.782487ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:48:12.381716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.18098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-08-24T20:48:12.381786Z","caller":"traceutil/trace.go:171","msg":"trace[1403981402] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5427; }","duration":"149.273501ms","start":"2026-08-24T20:48:12.232494Z","end":"2026-08-24T20:48:12.381768Z","steps":["trace[1403981402] 'agreement among raft nodes before linearized reading' (duration: 149.074058ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:48:12.381971Z","caller":"traceutil/trace.go:171","msg":"trace[458403845] transaction","detail":"{read_only:false; response_revision:5426; number_of_response:1; }","duration":"170.081395ms","start":"2026-08-24T20:48:12.211848Z","end":"2026-08-24T20:48:12.381929Z","steps":["trace[458403845] 'process raft request' (duration: 168.958554ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:48:12.714903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.992028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-24T20:48:12.715125Z","caller":"traceutil/trace.go:171","msg":"trace[724816308] linearizableReadLoop","detail":"{readStateIndex:5633; appliedIndex:5631; }","duration":"331.375573ms","start":"2026-08-24T20:48:12.383732Z","end":"2026-08-24T20:48:12.715107Z","steps":["trace[724816308] 'read index received' (duration: 102.047248ms)","trace[724816308] 'applied index is now lower than readState.Index' (duration: 229.327325ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:48:12.715149Z","caller":"traceutil/trace.go:171","msg":"trace[1643488491] transaction","detail":"{read_only:false; response_revision:5428; number_of_response:1; }","duration":"403.883034ms","start":"2026-08-24T20:48:12.311217Z","end":"2026-08-24T20:48:12.7151Z","steps":["trace[1643488491] 'process raft request' (duration: 174.631462ms)","trace[1643488491] 'compare' (duration: 228.811214ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:48:12.715314Z","caller":"traceutil/trace.go:171","msg":"trace[678077408] transaction","detail":"{read_only:false; response_revision:5429; number_of_response:1; }","duration":"336.622673ms","start":"2026-08-24T20:48:12.37865Z","end":"2026-08-24T20:48:12.715273Z","steps":["trace[678077408] 'process raft request' (duration: 336.368188ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:48:12.715401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.18453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-e6f51d8e-55ad-4596-b379-5a31de4fe5df\" ","response":"range_response_count:1 size:1303"} {"level":"warn","ts":"2026-08-24T20:48:12.715357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:48:12.311197Z","time spent":"404.075827ms","remote":"127.0.0.1:49818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2585,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T20:48:12.715446Z","caller":"traceutil/trace.go:171","msg":"trace[1286538239] range","detail":"{range_begin:/registry/persistentvolumes/pvc-e6f51d8e-55ad-4596-b379-5a31de4fe5df; range_end:; response_count:1; response_revision:5429; }","duration":"331.260381ms","start":"2026-08-24T20:48:12.384173Z","end":"2026-08-24T20:48:12.715434Z","steps":["trace[1286538239] 'agreement among raft nodes before linearized reading' (duration: 331.043727ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:48:12.715442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.702976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-24T20:48:12.715485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:48:12.384163Z","time spent":"331.313203ms","remote":"127.0.0.1:49872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1326,"request content":"key:\"/registry/persistentvolumes/pvc-e6f51d8e-55ad-4596-b379-5a31de4fe5df\" "} {"level":"info","ts":"2026-08-24T20:48:12.715491Z","caller":"traceutil/trace.go:171","msg":"trace[340529361] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5429; }","duration":"134.804319ms","start":"2026-08-24T20:48:12.580673Z","end":"2026-08-24T20:48:12.715477Z","steps":["trace[340529361] 'agreement among raft nodes before linearized reading' (duration: 134.608445ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:48:12.715487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:48:12.378614Z","time spent":"336.787106ms","remote":"127.0.0.1:38116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T20:48:12.715512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.742511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18287"} {"level":"info","ts":"2026-08-24T20:48:12.715559Z","caller":"traceutil/trace.go:171","msg":"trace[1482870024] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5429; }","duration":"331.883723ms","start":"2026-08-24T20:48:12.383662Z","end":"2026-08-24T20:48:12.715546Z","steps":["trace[1482870024] 'agreement among raft nodes before linearized reading' (duration: 331.524516ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:48:12.715631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:48:12.383641Z","time spent":"331.980226ms","remote":"127.0.0.1:49902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18310,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-24T20:49:27.468233Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3007} {"level":"info","ts":"2026-08-24T20:49:27.549555Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3007,"took":"78.903845ms","hash":2364375155,"current-db-size-bytes":58433536,"current-db-size":"58 MB","current-db-size-in-use-bytes":31817728,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-24T20:49:27.549646Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2364375155,"revision":3007,"compact-revision":-1} {"level":"info","ts":"2026-08-24T20:54:18.956091Z","caller":"traceutil/trace.go:171","msg":"trace[1319066213] linearizableReadLoop","detail":"{readStateIndex:8569; appliedIndex:8568; }","duration":"101.582973ms","start":"2026-08-24T20:54:18.854487Z","end":"2026-08-24T20:54:18.95607Z","steps":["trace[1319066213] 'read index received' (duration: 101.43404ms)","trace[1319066213] 'applied index is now lower than readState.Index' (duration: 148.113µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:54:18.95615Z","caller":"traceutil/trace.go:171","msg":"trace[599760484] transaction","detail":"{read_only:false; response_revision:8273; number_of_response:1; }","duration":"102.205185ms","start":"2026-08-24T20:54:18.853918Z","end":"2026-08-24T20:54:18.956124Z","steps":["trace[599760484] 'process raft request' (duration: 102.014831ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:54:18.956197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.706874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T20:54:18.956223Z","caller":"traceutil/trace.go:171","msg":"trace[1584067758] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:8273; }","duration":"101.800307ms","start":"2026-08-24T20:54:18.854414Z","end":"2026-08-24T20:54:18.956214Z","steps":["trace[1584067758] 'agreement among raft nodes before linearized reading' (duration: 101.738536ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:54:27.474928Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6129} {"level":"info","ts":"2026-08-24T20:54:27.567136Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6129,"took":"90.160407ms","hash":1302119955,"current-db-size-bytes":58433536,"current-db-size":"58 MB","current-db-size-in-use-bytes":23293952,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-24T20:54:27.567216Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1302119955,"revision":6129,"compact-revision":3007} {"level":"info","ts":"2026-08-24T20:54:30.953074Z","caller":"traceutil/trace.go:171","msg":"trace[2003915044] linearizableReadLoop","detail":"{readStateIndex:8636; appliedIndex:8635; }","duration":"312.695379ms","start":"2026-08-24T20:54:30.640352Z","end":"2026-08-24T20:54:30.953047Z","steps":["trace[2003915044] 'read index received' (duration: 15.239237ms)","trace[2003915044] 'applied index is now lower than readState.Index' (duration: 297.455202ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:54:30.953202Z","caller":"traceutil/trace.go:171","msg":"trace[1736509039] transaction","detail":"{read_only:false; response_revision:8336; number_of_response:1; }","duration":"319.181491ms","start":"2026-08-24T20:54:30.634009Z","end":"2026-08-24T20:54:30.953191Z","steps":["trace[1736509039] 'process raft request' (duration: 257.513489ms)","trace[1736509039] 'compare' (duration: 61.408896ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T20:54:30.953319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:54:30.633996Z","time spent":"319.237642ms","remote":"127.0.0.1:38116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T20:54:30.953496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.641748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-08-24T20:54:30.953595Z","caller":"traceutil/trace.go:171","msg":"trace[1795905251] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:8336; }","duration":"295.768709ms","start":"2026-08-24T20:54:30.657802Z","end":"2026-08-24T20:54:30.953571Z","steps":["trace[1795905251] 'agreement among raft nodes before linearized reading' (duration: 295.504505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:54:30.953645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.285871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-24T20:54:30.953665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.703751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T20:54:30.953772Z","caller":"traceutil/trace.go:171","msg":"trace[1167740485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8336; }","duration":"208.846273ms","start":"2026-08-24T20:54:30.744906Z","end":"2026-08-24T20:54:30.953753Z","steps":["trace[1167740485] 'agreement among raft nodes before linearized reading' (duration: 208.67743ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:54:30.953689Z","caller":"traceutil/trace.go:171","msg":"trace[977410692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8336; }","duration":"313.343931ms","start":"2026-08-24T20:54:30.640333Z","end":"2026-08-24T20:54:30.953677Z","steps":["trace[977410692] 'agreement among raft nodes before linearized reading' (duration: 313.208099ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:54:30.954336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T20:54:30.640326Z","time spent":"313.997473ms","remote":"127.0.0.1:38116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-24T20:54:31.695728Z","caller":"traceutil/trace.go:171","msg":"trace[1830769247] transaction","detail":"{read_only:false; response_revision:8340; number_of_response:1; }","duration":"211.767127ms","start":"2026-08-24T20:54:31.483917Z","end":"2026-08-24T20:54:31.695685Z","steps":["trace[1830769247] 'process raft request' (duration: 208.67898ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:54:33.208712Z","caller":"traceutil/trace.go:171","msg":"trace[1422304693] transaction","detail":"{read_only:false; response_revision:8350; number_of_response:1; }","duration":"164.57635ms","start":"2026-08-24T20:54:33.044103Z","end":"2026-08-24T20:54:33.20868Z","steps":["trace[1422304693] 'process raft request' (duration: 131.984746ms)","trace[1422304693] 'compare' (duration: 32.45683ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:54:59.491829Z","caller":"traceutil/trace.go:171","msg":"trace[2039322775] transaction","detail":"{read_only:false; response_revision:8542; number_of_response:1; }","duration":"191.656968ms","start":"2026-08-24T20:54:59.300146Z","end":"2026-08-24T20:54:59.491803Z","steps":["trace[2039322775] 'process raft request' (duration: 191.508725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:54:59.687428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.178635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lxq7f\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-24T20:54:59.687523Z","caller":"traceutil/trace.go:171","msg":"trace[629685352] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lxq7f; range_end:; response_count:1; response_revision:8542; }","duration":"102.336257ms","start":"2026-08-24T20:54:59.585162Z","end":"2026-08-24T20:54:59.687498Z","steps":["trace[629685352] 'range keys from in-memory index tree' (duration: 101.904009ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:55:05.749593Z","caller":"traceutil/trace.go:171","msg":"trace[1919294390] transaction","detail":"{read_only:false; response_revision:8576; number_of_response:1; }","duration":"152.125765ms","start":"2026-08-24T20:55:05.597433Z","end":"2026-08-24T20:55:05.749559Z","steps":["trace[1919294390] 'process raft request' (duration: 151.86682ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:55:06.329418Z","caller":"traceutil/trace.go:171","msg":"trace[377462719] transaction","detail":"{read_only:false; response_revision:8579; number_of_response:1; }","duration":"197.615861ms","start":"2026-08-24T20:55:06.131778Z","end":"2026-08-24T20:55:06.329394Z","steps":["trace[377462719] 'process raft request' (duration: 197.428728ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:55:12.60509Z","caller":"traceutil/trace.go:171","msg":"trace[1333269] transaction","detail":"{read_only:false; response_revision:8653; number_of_response:1; }","duration":"146.169152ms","start":"2026-08-24T20:55:12.458883Z","end":"2026-08-24T20:55:12.605053Z","steps":["trace[1333269] 'process raft request' (duration: 129.543519ms)","trace[1333269] 'compare' (duration: 16.4712ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:55:12.611687Z","caller":"traceutil/trace.go:171","msg":"trace[414394042] transaction","detail":"{read_only:false; response_revision:8654; number_of_response:1; }","duration":"151.762968ms","start":"2026-08-24T20:55:12.459866Z","end":"2026-08-24T20:55:12.611629Z","steps":["trace[414394042] 'process raft request' (duration: 151.431052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:55:12.612051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.635125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-7m7r7\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-08-24T20:55:12.611725Z","caller":"traceutil/trace.go:171","msg":"trace[1511457961] linearizableReadLoop","detail":"{readStateIndex:8968; appliedIndex:8966; }","duration":"151.294689ms","start":"2026-08-24T20:55:12.460399Z","end":"2026-08-24T20:55:12.611694Z","steps":["trace[1511457961] 'read index received' (duration: 127.885748ms)","trace[1511457961] 'applied index is now lower than readState.Index' (duration: 23.406551ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:55:12.612112Z","caller":"traceutil/trace.go:171","msg":"trace[1952114836] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-7m7r7; range_end:; response_count:1; response_revision:8655; }","duration":"151.711356ms","start":"2026-08-24T20:55:12.460383Z","end":"2026-08-24T20:55:12.612095Z","steps":["trace[1952114836] 'agreement among raft nodes before linearized reading' (duration: 151.407291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T20:55:12.612298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.713781ms","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-08-24T20:55:12.612385Z","caller":"traceutil/trace.go:171","msg":"trace[1403304320] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8655; }","duration":"130.879714ms","start":"2026-08-24T20:55:12.481484Z","end":"2026-08-24T20:55:12.612364Z","steps":["trace[1403304320] 'agreement among raft nodes before linearized reading' (duration: 130.679291ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:55:13.465702Z","caller":"traceutil/trace.go:171","msg":"trace[1481893594] transaction","detail":"{read_only:false; response_revision:8660; number_of_response:1; }","duration":"200.148019ms","start":"2026-08-24T20:55:13.265458Z","end":"2026-08-24T20:55:13.465606Z","steps":["trace[1481893594] 'process raft request' (duration: 118.992251ms)","trace[1481893594] 'compare' (duration: 80.961054ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T20:55:39.965788Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000243d.wal"} {"level":"warn","ts":"2026-08-24T20:56:30.469236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.242425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lxq7f\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-08-24T20:56:30.469335Z","caller":"traceutil/trace.go:171","msg":"trace[836655584] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lxq7f; range_end:; response_count:1; response_revision:9409; }","duration":"169.369097ms","start":"2026-08-24T20:56:30.299946Z","end":"2026-08-24T20:56:30.469315Z","steps":["trace[836655584] 'range keys from in-memory index tree' (duration: 169.040551ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T20:57:04.732893Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e44ae1545340c14b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-24T20:57:04.73867Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-24T20:57:04.738788Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-24T20:59:27.48132Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8317} {"level":"info","ts":"2026-08-24T20:59:27.559615Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8317,"took":"76.453624ms","hash":606866010,"current-db-size-bytes":58433536,"current-db-size":"58 MB","current-db-size-in-use-bytes":26308608,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-24T20:59:27.559686Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":606866010,"revision":8317,"compact-revision":6129} {"level":"info","ts":"2026-08-24T21:00:12.294328Z","caller":"traceutil/trace.go:171","msg":"trace[537228646] transaction","detail":"{read_only:false; response_revision:11244; number_of_response:1; }","duration":"112.964688ms","start":"2026-08-24T21:00:12.18132Z","end":"2026-08-24T21:00:12.294284Z","steps":["trace[537228646] 'process raft request' (duration: 76.640308ms)","trace[537228646] 'compare' (duration: 36.076906ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T21:04:27.487611Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10810} {"level":"info","ts":"2026-08-24T21:04:27.572316Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10810,"took":"81.523531ms","hash":2742753009,"current-db-size-bytes":58433536,"current-db-size":"58 MB","current-db-size-in-use-bytes":27570176,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-24T21:04:27.572376Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2742753009,"revision":10810,"compact-revision":8317} {"level":"warn","ts":"2026-08-24T21:06:17.422744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.732288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1857"} {"level":"info","ts":"2026-08-24T21:06:17.422834Z","caller":"traceutil/trace.go:171","msg":"trace[1609297471] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:13750; }","duration":"165.871391ms","start":"2026-08-24T21:06:17.256945Z","end":"2026-08-24T21:06:17.422816Z","steps":["trace[1609297471] 'range keys from in-memory index tree' (duration: 165.571584ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:06:17.42296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.14805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-24T21:06:17.422994Z","caller":"traceutil/trace.go:171","msg":"trace[1603627083] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:13750; }","duration":"121.206432ms","start":"2026-08-24T21:06:17.301778Z","end":"2026-08-24T21:06:17.422984Z","steps":["trace[1603627083] 'range keys from in-memory index tree' (duration: 121.053969ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:06:34.703546Z","caller":"traceutil/trace.go:171","msg":"trace[40655525] linearizableReadLoop","detail":"{readStateIndex:14345; appliedIndex:14344; }","duration":"187.505827ms","start":"2026-08-24T21:06:34.516008Z","end":"2026-08-24T21:06:34.703514Z","steps":["trace[40655525] 'read index received' (duration: 159.764103ms)","trace[40655525] 'applied index is now lower than readState.Index' (duration: 27.740134ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T21:06:34.703787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.980993ms","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:7"} {"level":"warn","ts":"2026-08-24T21:06:34.703735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.704421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T21:06:34.703847Z","caller":"traceutil/trace.go:171","msg":"trace[1978767480] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:13864; }","duration":"154.168546ms","start":"2026-08-24T21:06:34.549665Z","end":"2026-08-24T21:06:34.703834Z","steps":["trace[1978767480] 'agreement among raft nodes before linearized reading' (duration: 154.030993ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:06:34.703884Z","caller":"traceutil/trace.go:171","msg":"trace[864002722] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:13864; }","duration":"187.868374ms","start":"2026-08-24T21:06:34.515987Z","end":"2026-08-24T21:06:34.703855Z","steps":["trace[864002722] 'agreement among raft nodes before linearized reading' (duration: 187.63091ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:06:34.703743Z","caller":"traceutil/trace.go:171","msg":"trace[271141597] transaction","detail":"{read_only:false; response_revision:13864; number_of_response:1; }","duration":"267.528136ms","start":"2026-08-24T21:06:34.436014Z","end":"2026-08-24T21:06:34.703542Z","steps":["trace[271141597] 'process raft request' (duration: 239.68588ms)","trace[271141597] 'compare' (duration: 27.53003ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T21:06:35.06203Z","caller":"traceutil/trace.go:171","msg":"trace[285129046] linearizableReadLoop","detail":"{readStateIndex:14346; appliedIndex:14345; }","duration":"213.783302ms","start":"2026-08-24T21:06:34.84822Z","end":"2026-08-24T21:06:35.062003Z","steps":["trace[285129046] 'read index received' (duration: 213.67498ms)","trace[285129046] 'applied index is now lower than readState.Index' (duration: 106.952µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T21:06:35.062085Z","caller":"traceutil/trace.go:171","msg":"trace[171281378] transaction","detail":"{read_only:false; response_revision:13865; number_of_response:1; }","duration":"274.187505ms","start":"2026-08-24T21:06:34.787853Z","end":"2026-08-24T21:06:35.062041Z","steps":["trace[171281378] 'process raft request' (duration: 273.984541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:06:35.062325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.092518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-24T21:06:35.06241Z","caller":"traceutil/trace.go:171","msg":"trace[612794698] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13865; }","duration":"214.237851ms","start":"2026-08-24T21:06:34.84815Z","end":"2026-08-24T21:06:35.062388Z","steps":["trace[612794698] 'agreement among raft nodes before linearized reading' (duration: 213.973506ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:06:35.256815Z","caller":"traceutil/trace.go:171","msg":"trace[2139732268] linearizableReadLoop","detail":"{readStateIndex:14347; appliedIndex:14346; }","duration":"194.700895ms","start":"2026-08-24T21:06:35.06209Z","end":"2026-08-24T21:06:35.256791Z","steps":["trace[2139732268] 'read index received' (duration: 190.83689ms)","trace[2139732268] 'applied index is now lower than readState.Index' (duration: 3.862775ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T21:06:35.257075Z","caller":"traceutil/trace.go:171","msg":"trace[1664354949] transaction","detail":"{read_only:false; response_revision:13866; number_of_response:1; }","duration":"270.639696ms","start":"2026-08-24T21:06:34.986377Z","end":"2026-08-24T21:06:35.257016Z","steps":["trace[1664354949] 'process raft request' (duration: 266.611558ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:06:35.257099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.634822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T21:06:35.257206Z","caller":"traceutil/trace.go:171","msg":"trace[433745074] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:13866; }","duration":"341.759334ms","start":"2026-08-24T21:06:34.915419Z","end":"2026-08-24T21:06:35.257179Z","steps":["trace[433745074] 'agreement among raft nodes before linearized reading' (duration: 341.56955ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:06:35.257263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T21:06:34.915386Z","time spent":"341.864856ms","remote":"127.0.0.1:34854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-24T21:06:35.25788Z","caller":"traceutil/trace.go:171","msg":"trace[896641954] transaction","detail":"{read_only:false; response_revision:13867; number_of_response:1; }","duration":"190.500594ms","start":"2026-08-24T21:06:35.067353Z","end":"2026-08-24T21:06:35.257854Z","steps":["trace[896641954] 'process raft request' (duration: 190.309221ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:07:42.744037Z","caller":"traceutil/trace.go:171","msg":"trace[384109777] transaction","detail":"{read_only:false; response_revision:14763; number_of_response:1; }","duration":"107.352775ms","start":"2026-08-24T21:07:42.636651Z","end":"2026-08-24T21:07:42.744003Z","steps":["trace[384109777] 'process raft request' (duration: 72.587956ms)","trace[384109777] 'compare' (duration: 34.521874ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T21:07:42.744245Z","caller":"traceutil/trace.go:171","msg":"trace[958249267] transaction","detail":"{read_only:false; response_revision:14764; number_of_response:1; }","duration":"107.290654ms","start":"2026-08-24T21:07:42.636902Z","end":"2026-08-24T21:07:42.744193Z","steps":["trace[958249267] 'process raft request' (duration: 107.039538ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:08:16.698129Z","caller":"traceutil/trace.go:171","msg":"trace[1899970439] linearizableReadLoop","detail":"{readStateIndex:15456; appliedIndex:15455; }","duration":"126.135386ms","start":"2026-08-24T21:08:16.571964Z","end":"2026-08-24T21:08:16.698099Z","steps":["trace[1899970439] 'read index received' (duration: 125.76766ms)","trace[1899970439] 'applied index is now lower than readState.Index' (duration: 366.786µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T21:08:16.698227Z","caller":"traceutil/trace.go:171","msg":"trace[164648085] transaction","detail":"{read_only:false; response_revision:14939; number_of_response:1; }","duration":"132.09192ms","start":"2026-08-24T21:08:16.566106Z","end":"2026-08-24T21:08:16.698198Z","steps":["trace[164648085] 'process raft request' (duration: 131.776344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:08:16.698401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.421902ms","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-08-24T21:08:16.698445Z","caller":"traceutil/trace.go:171","msg":"trace[835422756] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14939; }","duration":"126.529054ms","start":"2026-08-24T21:08:16.571906Z","end":"2026-08-24T21:08:16.698435Z","steps":["trace[835422756] 'agreement among raft nodes before linearized reading' (duration: 126.34795ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:09:27.494104Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12911} {"level":"info","ts":"2026-08-24T21:09:27.576527Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12911,"took":"77.42234ms","hash":3060063289,"current-db-size-bytes":58433536,"current-db-size":"58 MB","current-db-size-in-use-bytes":31318016,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-24T21:09:27.576593Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3060063289,"revision":12911,"compact-revision":10810} {"level":"info","ts":"2026-08-24T21:13:36.440239Z","caller":"traceutil/trace.go:171","msg":"trace[283121156] transaction","detail":"{read_only:false; response_revision:17386; number_of_response:1; }","duration":"107.183816ms","start":"2026-08-24T21:13:36.333006Z","end":"2026-08-24T21:13:36.440189Z","steps":["trace[283121156] 'process raft request' (duration: 58.958551ms)","trace[283121156] 'compare' (duration: 47.928839ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T21:13:36.841747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.544142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T21:13:36.842262Z","caller":"traceutil/trace.go:171","msg":"trace[1968265184] linearizableReadLoop","detail":"{readStateIndex:17987; appliedIndex:17985; }","duration":"142.729657ms","start":"2026-08-24T21:13:36.6993Z","end":"2026-08-24T21:13:36.84203Z","steps":["trace[1968265184] 'read index received' (duration: 9.840769ms)","trace[1968265184] 'applied index is now lower than readState.Index' (duration: 132.888258ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T21:13:36.842501Z","caller":"traceutil/trace.go:171","msg":"trace[1113095400] transaction","detail":"{read_only:false; response_revision:17389; number_of_response:1; }","duration":"144.810858ms","start":"2026-08-24T21:13:36.697412Z","end":"2026-08-24T21:13:36.842223Z","steps":["trace[1113095400] 'process raft request' (duration: 11.721496ms)","trace[1113095400] 'compare' (duration: 132.425208ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T21:13:36.842633Z","caller":"traceutil/trace.go:171","msg":"trace[338419509] transaction","detail":"{read_only:false; response_revision:17390; number_of_response:1; }","duration":"144.291777ms","start":"2026-08-24T21:13:36.69794Z","end":"2026-08-24T21:13:36.842232Z","steps":["trace[338419509] 'process raft request' (duration: 144.048192ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:13:36.843011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.710085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:953117"} {"level":"info","ts":"2026-08-24T21:13:36.843054Z","caller":"traceutil/trace.go:171","msg":"trace[1035045881] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:17390; }","duration":"143.822958ms","start":"2026-08-24T21:13:36.699223Z","end":"2026-08-24T21:13:36.843045Z","steps":["trace[1035045881] 'agreement among raft nodes before linearized reading' (duration: 143.083634ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:13:37.237793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.66509ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T21:13:37.237988Z","caller":"traceutil/trace.go:171","msg":"trace[1857882744] linearizableReadLoop","detail":"{readStateIndex:17988; appliedIndex:17987; }","duration":"395.677708ms","start":"2026-08-24T21:13:36.842287Z","end":"2026-08-24T21:13:37.237965Z","steps":["trace[1857882744] 'read index received' (duration: 123.685662ms)","trace[1857882744] 'applied index is now lower than readState.Index' (duration: 271.989736ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T21:13:37.238002Z","caller":"traceutil/trace.go:171","msg":"trace[173447174] transaction","detail":"{read_only:false; response_revision:17391; number_of_response:1; }","duration":"457.589985ms","start":"2026-08-24T21:13:36.780387Z","end":"2026-08-24T21:13:37.237977Z","steps":["trace[173447174] 'process raft request' (duration: 185.63786ms)","trace[173447174] 'compare' (duration: 271.390195ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T21:13:37.238089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.125361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-24T21:13:37.238121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T21:13:36.780362Z","time spent":"457.698767ms","remote":"127.0.0.1:38116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T21:13:37.238141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.82855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T21:13:37.23818Z","caller":"traceutil/trace.go:171","msg":"trace[1318485086] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:17391; }","duration":"440.255164ms","start":"2026-08-24T21:13:36.797899Z","end":"2026-08-24T21:13:37.238154Z","steps":["trace[1318485086] 'agreement among raft nodes before linearized reading' (duration: 440.06771ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:13:37.238213Z","caller":"traceutil/trace.go:171","msg":"trace[1550047085] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:17391; }","duration":"506.935032ms","start":"2026-08-24T21:13:36.731257Z","end":"2026-08-24T21:13:37.238192Z","steps":["trace[1550047085] 'agreement among raft nodes before linearized reading' (duration: 506.765329ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:13:37.238238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T21:13:36.797887Z","time spent":"440.334376ms","remote":"127.0.0.1:35030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-24T21:13:37.238237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.370258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-08-24T21:13:37.238436Z","caller":"traceutil/trace.go:171","msg":"trace[1876645092] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:17391; }","duration":"122.609802ms","start":"2026-08-24T21:13:37.115814Z","end":"2026-08-24T21:13:37.238423Z","steps":["trace[1876645092] 'agreement among raft nodes before linearized reading' (duration: 122.272374ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:13:37.238251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T21:13:36.731194Z","time spent":"507.049525ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-08-24T21:13:37.383572Z","caller":"traceutil/trace.go:171","msg":"trace[1491599377] transaction","detail":"{read_only:false; response_revision:17392; number_of_response:1; }","duration":"140.145087ms","start":"2026-08-24T21:13:37.243406Z","end":"2026-08-24T21:13:37.383551Z","steps":["trace[1491599377] 'process raft request' (duration: 133.128253ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:14:27.501174Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15423} {"level":"info","ts":"2026-08-24T21:14:27.596399Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15423,"took":"91.674157ms","hash":3845148626,"current-db-size-bytes":58433536,"current-db-size":"58 MB","current-db-size-in-use-bytes":34025472,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-24T21:14:27.596453Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3845148626,"revision":15423,"compact-revision":12911} {"level":"info","ts":"2026-08-24T21:17:27.172844Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e44ae1545340c14b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-24T21:17:27.177181Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-24T21:17:27.177275Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-08-24T21:18:46.256184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.12757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-08-24T21:18:46.256272Z","caller":"traceutil/trace.go:171","msg":"trace[992898075] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19873; }","duration":"130.257742ms","start":"2026-08-24T21:18:46.125995Z","end":"2026-08-24T21:18:46.256253Z","steps":["trace[992898075] 'range keys from in-memory index tree' (duration: 129.973466ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:19:07.722441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.00423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T21:19:07.722556Z","caller":"traceutil/trace.go:171","msg":"trace[2047772366] transaction","detail":"{read_only:false; response_revision:19984; number_of_response:1; }","duration":"201.663164ms","start":"2026-08-24T21:19:07.520873Z","end":"2026-08-24T21:19:07.722536Z","steps":["trace[2047772366] 'process raft request' (duration: 63.47832ms)","trace[2047772366] 'compare' (duration: 137.734595ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T21:19:08.329944Z","caller":"traceutil/trace.go:171","msg":"trace[1869053328] transaction","detail":"{read_only:false; response_revision:19985; number_of_response:1; }","duration":"130.282182ms","start":"2026-08-24T21:19:08.199615Z","end":"2026-08-24T21:19:08.329898Z","steps":["trace[1869053328] 'process raft request' (duration: 130.061328ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:19:08.580557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.886305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T21:19:08.580696Z","caller":"traceutil/trace.go:171","msg":"trace[1044263118] transaction","detail":"{read_only:false; response_revision:19986; number_of_response:1; }","duration":"193.493626ms","start":"2026-08-24T21:19:08.38718Z","end":"2026-08-24T21:19:08.580674Z","steps":["trace[1044263118] 'process raft request' (duration: 51.385277ms)","trace[1044263118] 'compare' (duration: 141.702442ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T21:19:08.584801Z","caller":"traceutil/trace.go:171","msg":"trace[1241244399] transaction","detail":"{read_only:false; response_revision:19987; number_of_response:1; }","duration":"117.570048ms","start":"2026-08-24T21:19:08.467214Z","end":"2026-08-24T21:19:08.584784Z","steps":["trace[1241244399] 'process raft request' (duration: 117.481136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:19:08.833057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.33707ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-24T21:19:08.833342Z","caller":"traceutil/trace.go:171","msg":"trace[1274244253] transaction","detail":"{read_only:false; response_revision:19989; number_of_response:1; }","duration":"229.723583ms","start":"2026-08-24T21:19:08.603592Z","end":"2026-08-24T21:19:08.833315Z","steps":["trace[1274244253] 'process raft request' (duration: 229.59729ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:19:08.833386Z","caller":"traceutil/trace.go:171","msg":"trace[1535985846] transaction","detail":"{read_only:false; response_revision:19988; number_of_response:1; }","duration":"230.05484ms","start":"2026-08-24T21:19:08.60327Z","end":"2026-08-24T21:19:08.833325Z","steps":["trace[1535985846] 'process raft request' (duration: 108.337611ms)","trace[1535985846] 'compare' (duration: 120.938813ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T21:19:27.507691Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18004} {"level":"info","ts":"2026-08-24T21:19:27.617262Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18004,"took":"107.148888ms","hash":3703068762,"current-db-size-bytes":58433536,"current-db-size":"58 MB","current-db-size-in-use-bytes":29859840,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-24T21:19:27.617345Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3703068762,"revision":18004,"compact-revision":15423} {"level":"info","ts":"2026-08-24T21:21:51.568937Z","caller":"traceutil/trace.go:171","msg":"trace[1113245470] transaction","detail":"{read_only:false; response_revision:21302; number_of_response:1; }","duration":"100.750643ms","start":"2026-08-24T21:21:51.468153Z","end":"2026-08-24T21:21:51.568904Z","steps":["trace[1113245470] 'process raft request' (duration: 100.571869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T21:22:49.136183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.622097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-24T21:22:49.136261Z","caller":"traceutil/trace.go:171","msg":"trace[568804034] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:22356; }","duration":"179.933483ms","start":"2026-08-24T21:22:48.956309Z","end":"2026-08-24T21:22:49.136242Z","steps":["trace[568804034] 'count revisions from in-memory index tree' (duration: 179.507675ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T21:24:27.514931Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20084} {"level":"info","ts":"2026-08-24T21:24:27.62635Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20084,"took":"107.743796ms","hash":1753208686,"current-db-size-bytes":58433536,"current-db-size":"58 MB","current-db-size-in-use-bytes":43220992,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-24T21:24:27.626441Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1753208686,"revision":20084,"compact-revision":18004} {"level":"info","ts":"2026-08-24T21:29:27.520943Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23367} {"level":"info","ts":"2026-08-24T21:29:27.646663Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23367,"took":"122.702265ms","hash":3593902920,"current-db-size-bytes":58433536,"current-db-size":"58 MB","current-db-size-in-use-bytes":31055872,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-24T21:29:27.646773Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3593902920,"revision":23367,"compact-revision":20084}