Stuck at "Allocated cache and file handles"

[netquery]

Geth starts, but gets stuck after printing into log this line:

# tail -100f bsc.log
t=2022-02-24T17:17:34+0000 lvl=info msg="Set global gas cap" cap=25,000,000
t=2022-02-24T17:17:34+0000 lvl=info msg="Allocated trie memory caches" clean="614.00 MiB" dirty="1024.00 MiB"
t=2022-02-24T17:17:34+0000 lvl=info msg="Allocated cache and file handles" database=/bsc-data/geth/chaindata cache="1.60 GiB" handles=32767

geth version:

Geth
Version: 1.1.8
Git Commit: 859186f299fa94fada3e92608db1392be1a45599
Architecture: amd64
Go Version: go1.17.1
Operating System: linux
GOPATH=
GOROOT=go

snapshot: https://tf-dex-prod-public-snapshot-site1.s3-accelerate.amazonaws.com/geth-20220223-prune-ancient.tar.lz4?AWSAccessKeyId=AKIAYINE6SBQPUZDDRRO&Signature=k6yJlcR7pda3E5CaArHjAUMxd38%3D&Expires=1648270913

[pythonberg1997]

Hello @netquery. It's interesting that you got stuck and had only 3 line log. Maybe you can try to switch the log level to debug and get more information.

[lbadger]

I just did a prune. Started the node up again. I'm now having the same issue.

[lbadger]

I changed my log level to debug.

t=2022-03-24T05:02:01+0000 lvl=dbug msg="FS scan times" list="69.751µs" set=882ns diff="1.573µs"
t=2022-03-24T05:02:01+0000 lvl=dbug msg="Sanitizing Go's GC trigger" percent=20
t=2022-03-24T05:02:01+0000 lvl=info msg="Set global gas cap"         cap=25,000,000
t=2022-03-24T05:02:01+0000 lvl=info msg="Allocated trie memory caches" clean="1.17 GiB" dirty="1.95 GiB"
t=2022-03-24T05:02:02+0000 lvl=dbug msg="FS scan times" list="26.429µs" set="1.092µs" diff="2.775µs"
t=2022-03-24T05:02:04+0000 lvl=dbug msg="FS scan times" list="32.341µs" set=691ns diff="1.152µs"
t=2022-03-24T05:02:04+0000 lvl=dbug msg="Sanitizing Go's GC trigger" percent=20
t=2022-03-24T05:02:04+0000 lvl=info msg="Set global gas cap"         cap=25,000,000
t=2022-03-24T05:02:06+0000 lvl=dbug msg="FS scan times" list="39.434µs" set=862ns diff="1.723µs"
t=2022-03-24T05:02:07+0000 lvl=dbug msg="FS scan times" list="121.036µs" set="1.162µs" diff="1.853µs"
t=2022-03-24T05:02:07+0000 lvl=dbug msg="Sanitizing Go's GC trigger" percent=20
t=2022-03-24T05:02:07+0000 lvl=info msg="Set global gas cap"         cap=25,000,000
t=2022-03-24T05:02:07+0000 lvl=info msg="Allocated trie memory caches" clean="1.17 GiB" dirty="1.95 GiB"
t=2022-03-24T05:02:07+0000 lvl=info msg="Allocated cache and file handles" database=/home/bsc/node/geth/chaindata cache="3.12 GiB" handles=32768
t=2022-03-24T05:02:08+0000 lvl=dbug msg="FS scan times" list=1.324349ms set=891ns diff="1.433µs"
t=2022-03-24T05:02:08+0000 lvl=dbug msg="FS scan times" list="45.997µs" set=340ns diff=601ns
t=2022-03-24T05:02:08+0000 lvl=dbug msg="Sanitizing Go's GC trigger" percent=20
t=2022-03-24T05:02:08+0000 lvl=info msg="Set global gas cap"         cap=25,000,000
t=2022-03-24T05:02:08+0000 lvl=info msg="Allocated trie memory caches" clean="1.17 GiB" dirty="1.95 GiB"
t=2022-03-24T05:02:08+0000 lvl=info msg="Allocated cache and file handles" database=/home/bsc/node/geth/chaindata cache="3.12 GiB" handles=32768
t=2022-03-24T05:02:09+0000 lvl=dbug msg="FS scan times" list="25.648µs" set="79.088µs" diff="3.788µs"
t=2022-03-24T05:02:11+0000 lvl=dbug msg="FS scan times" list="34.585µs" set="1.012µs" diff="2.375µs"
t=2022-03-24T05:02:11+0000 lvl=dbug msg="Sanitizing Go's GC trigger" percent=20
t=2022-03-24T05:02:11+0000 lvl=info msg="Set global gas cap"         cap=25,000,000
t=2022-03-24T05:02:11+0000 lvl=info msg="Allocated trie memory caches" clean="1.17 GiB" dirty="1.95 GiB"
t=2022-03-24T05:02:11+0000 lvl=info msg="Allocated cache and file handles" database=/home/bsc/node/geth/chaindata cache="3.12 GiB" handles=32768
t=2022-03-24T05:02:12+0000 lvl=dbug msg="FS scan times" list="27.812µs" set=932ns diff="2.615µs"
t=2022-03-24T05:02:12+0000 lvl=dbug msg="Sanitizing Go's GC trigger" percent=20
t=2022-03-24T05:02:12+0000 lvl=info msg="Set global gas cap"         cap=25,000,000
t=2022-03-24T05:02:12+0000 lvl=info msg="Allocated trie memory caches" clean="1.17 GiB" dirty="1.95 GiB"
t=2022-03-24T05:02:12+0000 lvl=info msg="Allocated cache and file handles" database=/home/bsc/node/geth/chaindata cache="3.12 GiB" handles=32768
t=2022-03-24T05:02:13+0000 lvl=dbug msg="FS scan times" list="19.787µs" set=601ns diff="1.303µs"
t=2022-03-24T05:02:13+0000 lvl=dbug msg="FS scan times" list="8.867µs"  set=501ns diff=711ns
t=2022-03-24T05:02:14+0000 lvl=dbug msg="FS scan times" list="22.812µs" set=812ns diff="1.693µs"
t=2022-03-24T05:02:14+0000 lvl=dbug msg="Sanitizing Go's GC trigger" percent=20
t=2022-03-24T05:02:14+0000 lvl=info msg="Set global gas cap"         cap=25,000,000
t=2022-03-24T05:02:14+0000 lvl=info msg="Allocated trie memory caches" clean="1.17 GiB" dirty="1.95 GiB"

[yutianwu]

you need to wait a rather long time, there are snapshot-related works ongoing.

[nyllewin]

@lbadger any update? running in to the same problem.

solved. pruned with root, needed to chown directory after.

[ofarukcaki]

@lbadger any update? running in to the same problem.

solved. pruned with root, needed to chown directory after.

How did you do that exacly?