Venus: [bug]Can't Get FIL from faucet

[AndyChen1984]

Describe the bug
Can't Get FIL from faucet. The command "go-filecoin message wait" can't return.

To Reproduce
rm -rf ~/.filecoin
go-filecoin init --devnet-user --genesisfile=https://genesis.user.kittyhawk.wtf/genesis.car
go-filecoin daemon &

export WALLET_ADDR=go-filecoin address ls # fetch your wallet address into a handy variable
MESSAGE_CID=curl -X POST -F "target=${WALLET_ADDR}" "http://user.kittyhawk.wtf:9797/tap" | cut -d" " -f4
go-filecoin message wait ${MESSAGE_CID}

Expected behavior
Get FIL successfully

Screenshots

root@localhost:~# export WALLET_ADDR=go-filecoin address ls # fetch your wallet address into a handy variable
The faucet will provide you with a message CID. The message should be processed in about 30 seconds. You can run the following command to wait for confirmation:

go-filecoin message wait ${MESSAGE_CID}
To verify that the FIL has landed in your wallet, check your wallet balance:

go-filecoin wallet balance ${WALLET_ADDR}
� Congrats, you're now connected to Filecoin AND can begin mining or storing data on the Filecoin network! Next, choose your adventure:

Mining Filecoin
Storing Data
See something missing? Have tips to share? File an issue, and we'll follow up as soon as possible. (If you have write permission in this repo, feel free to edit directly.)

Pages 12
Find a Page…
Home
Block Explorer
Commands
Dashboards
Devnets
Getting Started
How Filecoin Works
How to: connect two nodes directly
Mining Filecoin
Running a network locally
Storing on Filecoin
Troubleshooting & FAQ
Clone this wiki locally
root@localhost:~# MESSAGE_CID=curl -X POST -F "target=${WALLET_ADDR}" "http://user.kittyhawk.wtf:9797/tap" | cut -d" " -f4
% Total % Received % Xferd Average Speed Time Time Time Current
Dload Upload Total Spent Left Speed
0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 023:03:06.849 INFO chain.sync: chain synced default_syncer.go:135
23:03:06.849 INFO node: error handling blocks: { zDPWYqFD4gwLTSE9mMbcTubVrej62Q5yL1abFPyEaaUJmbiiF5Kg } node.go:508
23:03:07.033 INFO chain.sync: syncing the chain, currently at block height 79013 default_syncer.go:147
100 257 100 75 100 182 163 397 --:--:-- --:--:-- --:--:-- 559
root@localhost:~# The faucet will provide you with a message CID. The message should be processed in about 30 seconds. You can run the following command to wait for confirmation:
-bash: The: command not found
root@localhost:~#
root@localhost:~# go-filecoin message wait ${MESSAGE_CID}
waiting for: zDPWYqFCzGgm3WgBdfU6uQ1FvhTmYVWkY6gJ7T3N63YiWTg5chhr

root@localhost:~# go-filecoin wallet balance t15v53ifm7xgwvzac2c57ksjz2p6pcg7oasccxvza

0

root@localhost:~# go-filecoin chain head
zDPWYqFCu9VdMN9pUSSttbjJsxd2sTJGZjXv8AGZvquxnsMRFh9e
root@localhost:~# go-filecoin show block zDPWYqFCu9VdMN9pUSSttbjJsxd2sTJGZjXv8AGZvquxnsMRFh9e
Block Details
Miner: empty
Weight: 0.000
Height: 0
Nonce: 0
root@localhost:~# go-filecoin swarm peers
/ip4/111.19.129.161/tcp/6000/ipfs/QmXPqN35hF6r6Ami5k7FazbkFcrfBte7LwYZE4GXrZHzXn
/ip4/111.19.129.164/tcp/6000/ipfs/Qmb4RdUbdrVd67Qixde6GzTk25JXH1M2T7B9s9DD12dt5b
/ip4/111.19.129.165/tcp/6000/ipfs/QmNpKoKmrCVboDZz92PivjtUW5w13wU6YvcUVukZLELBwF
/ip4/111.19.129.166/tcp/6000/ipfs/QmXhteqjRgU1yQPVdVi1XggikCnMxBz4VbaKbHofKt1D3g
/ip4/111.19.129.167/tcp/6000/ipfs/QmdhZKmSqsLPHh8c7Y83KDJruZEpZRJPLXWjP43ZyfnRL1
/ip4/113.116.54.71/tcp/6000/ipfs/QmaKAZjNeJTbDWJVPXiCqHCPeuAWEQYjKkyhgFNDQrUnXN
/ip4/115.238.154.85/tcp/6000/ipfs/QmWHnXYxJ5LbHEGtPi7XswTT7hETgQuByBhoG6oJav2Yrq
/ip4/115.238.154.86/tcp/6000/ipfs/QmRxnJWcNT5bQhfKfz1xr3sPgPcpWhkq8ddatC3iPx4hqj
/ip4/119.147.149.138/tcp/6000/ipfs/QmXHGid7PwjoTDk44xHPWbgh8mthD3NdnNfG8xEAv1y8q4
/ip4/119.18.195.146/tcp/6000/ipfs/QmNpk4rmF8i3NmWDGuiapuV9iCEJ8rtcrUuaMREGkygR32
/ip4/119.18.195.147/tcp/6000/ipfs/QmY7KaXRv7uPQ4tTXQWkMk4ycBeX23oGkLdvdLG97rtyx6
/ip4/124.95.132.82/tcp/6000/ipfs/QmXa2QJkKjQQqGamFBHXR46oREqu2e4wUej8UCJa9oTZet
/ip4/124.95.132.83/tcp/6000/ipfs/QmWk3rr75PrQ7ToQ1kFhtNtZXJfMQyfnmuFbW5cmNwuXNC
/ip4/124.95.132.85/tcp/6000/ipfs/QmNNNB159v7JkzfLHBws4TqfrfdaF18gwm8UM5CCNx55b3
/ip4/124.95.132.89/tcp/6000/ipfs/QmYi4JfXiE324EkBTFiSbocz2RJY2RgV5itYmFc3wp8Vwt
/ip4/125.64.108.34/tcp/6000/ipfs/QmeZ3qiGqoNYouKiicG4ogmcruRMpwnFzqeZVSCp7iHe43
/ip4/125.64.108.35/tcp/6000/ipfs/QmcAm3q6izTcCUSUMzCPfXPn3M6W6A4HgBrdVEKZBDhsef
/ip4/125.64.108.36/tcp/6000/ipfs/QmURc8N74kiJFA7Z1XVycBYFo6N8zT5eHTNoA4TqFtn7Rw
/ip4/125.64.108.37/tcp/6000/ipfs/Qmf58wuemWsS4wjyR5nEJoLJBT3gmEZQaCQAc8d8KJ8mnZ
/ip4/13.58.127.200/tcp/6000/ipfs/QmfH8pEY3xPAQCi3ubNqXiW6QxK1oUjC1wNbEsYwKn9Sa8
/ip4/14.18.236.174/tcp/6000/ipfs/QmavuEcQtKLte7XHKc7Jgf1CXgonT99Fk937WJDceHPDtu
/ip4/149.28.211.198/tcp/6000/ipfs/QmYJ4B9Y3DDgnydv3DNU8S4NC9FvCU3yaqpL5kQUdjVvCz
/ip4/180.97.68.93/tcp/6000/ipfs/QmPnf91Pp7U8hKYUtm7ihN37sJpFidZhxmAGFFDf8yDmdB
/ip4/180.97.68.95/tcp/6000/ipfs/QmTDvh5kC48Fw4oHCcj38e2NbuLChgyj5iHFnLMH5rV9ZL
/ip4/207.246.90.39/tcp/6000/ipfs/QmPhDsmn6hX9ueRytsytX55wdNhnUpN2PfqKJWBygHXZb4
/ip4/218.17.34.43/tcp/6000/ipfs/QmfHMse6CCYvChbKGbAN6eWRpVe6Tk4dMF1YCLcHavAYVd
/ip4/218.18.152.24/tcp/6000/ipfs/QmWzhvaTAtCZtmQAwqG7m3vbBdZdxn7mJehtm7iUuGit6Z
/ip4/218.60.3.231/tcp/6000/ipfs/QmUjw2u7rorUgG1VXQukgZUnSD99ma8kY7ECZaVLVNHwtR
/ip4/218.60.3.233/tcp/6000/ipfs/QmUDGMXKcxL6RV7GALE8uZ8nGZEtp1wPfMgizzJytN3CGW
/ip4/221.225.83.83/tcp/6000/ipfs/QmZ5s8JN3rKbm8zci99maQNR9ooq4DJi5L6hFFY8zJRtrj
/ip4/222.188.110.115/tcp/6000/ipfs/QmYF39g6cHKGirYweM3La3TzvpaxKktdJBEUpHKemkFVFz
/ip4/223.111.184.238/tcp/6000/ipfs/QmUSX4PATBHATUEsjkPR6SoeAeRAypKuBBNUYSywKWvQ4k
/ip4/223.111.184.239/tcp/6000/ipfs/Qma4sGAziJuQRRATsrEg5JmUQHN8B9Qt8gH2T6546dP4a2
/ip4/3.90.230.176/tcp/9001/ipfs/QmXq6XEYeEmUzBFuuKbVEGgxEpVD4xbSkG2Rhek6zkFMp4
/ip4/3.90.230.176/tcp/9002/ipfs/QmXhxqTKzBKHA5FcMuiKZv8YaMPwpbKGXHRVZcFB2DX9XY
/ip4/3.90.230.176/tcp/9003/ipfs/QmZGDLdQLUTi7uYTNavKwCd7SBc5KMfxzWxAyvqRQvwuiV
/ip4/3.90.230.176/tcp/9004/ipfs/QmZRnwmCjyNHgeNDiyT8mXRtGhP6uSzgHtrozc42crmVbg
/ip4/43.254.11.196/tcp/6000/ipfs/QmRapTCCg5WQYFqpt4m7KEjb6HnzNCaSjp79hgSeRBCG9e
/ip4/43.254.11.200/tcp/6000/ipfs/QmV8gDDFRhTJyhv96vxHf911by1QEp3fZUgVYhPk3wEiuF
/ip4/43.254.11.201/tcp/6000/ipfs/QmaXMJkqkgJgXjytEKokc1r9jpWbdgaM5t6UGPGjUcFHaz
/ip4/43.254.11.202/tcp/6000/ipfs/QmUnUZB2j67weAco53i63kcVggv1WV8WtNg3msDf9Rdvjo
/ip4/43.254.11.204/tcp/6000/ipfs/QmckpbbuT8cWoauKEA3haWK3KRhEfenTCHTomJbQ6EFc1J
/ip4/58.215.43.86/tcp/6000/ipfs/QmRJDMyDyQTPb2KF1FhFkk6pvtbPgrCEjs5JjjeL4Lcb7j
/ip4/58.215.43.87/tcp/6000/ipfs/QmPE8hA6UkctLzUFpAPLnWuMYM1VzPx5kt8BQDUDQv2fWv
/ip4/58.215.43.89/tcp/6000/ipfs/QmcTPyLkibdq2X9MEigYRrT57fUmPd8NdpBsfMd7kQtRk5
/ip4/58.215.43.90/tcp/6000/ipfs/Qme6bUKE8ZK1H7jLYJSamYYf7usRECJCuFmMRnQn3SfhBj
/ip4/58.215.43.92/tcp/6000/ipfs/QmbSu5PHHw1mHmueD8mSjCP7Jwnk6QEva2GsUnfmxUMxgL
root@localhost:~# go-filecoin ping QmbSu5PHHw1mHmueD8mSjCP7Jwnk6QEva2GsUnfmxUMxgL
Pong received: seq=0 time=42.98 ms
Pong received: seq=1 time=60.06 ms
Pong received: seq=2 time=45.45 ms
Pong received: seq=3 time=55.01 ms
Pong received: seq=4 time=49.82 ms
Pong received: seq=5 time=51.21 ms
Pong received: seq=6 time=49.51 ms
Pong received: seq=7 time=49.97 ms

go-filecoin daemon report log:
13:52:02.739 INFO chain.sync: chain synced default_syncer.go:135
13:52:02.748 INFO node: error handling blocks: { zDPWYqFCsRxMjCS7PrVz57qjNBKc1aZB7z6CDbxRWLqSp2cUQRrK } node.go:508
13:52:03.202 INFO chain.sync: syncing the chain, currently at block height 79085 default_syncer.go:147
13:52:26.399 INFO chain.sync: syncing the chain, currently at block height 75564 default_syncer.go:147
13:52:43.548 INFO autonat: Discovered AutoNAT peer QmXq6XEYeEmUzBFuuKbVEGgxEpVD4xbSkG2Rhek6zkFMp4 notify.go:33
13:52:51.037 INFO chain.sync: syncing the chain, currently at block height 72305 default_syncer.go:147
13:52:52.731 INFO autonat: Discovered AutoNAT peer QmZGDLdQLUTi7uYTNavKwCd7SBc5KMfxzWxAyvqRQvwuiV notify.go:33
13:53:11.876 WARNI /fil/hello: genesis cid: zDPWYqFCutuHwRZhGXzji9L4eHeoFxxNCxCruGjWje36aAvbK2XV does not match: zDPWYqFCu9VdMN9pUSSttbjJsxd2sTJGZjXv8AGZvquxnsMRFh9e, disconnecting from peer: hello.go:93
13:53:12.984 WARNI /fil/hello: genesis cid: zDPWYqFCutuHwRZhGXzji9L4eHeoFxxNCxCruGjWje36aAvbK2XV does not match: zDPWYqFCu9VdMN9pUSSttbjJsxd2sTJGZjXv8AGZvquxnsMRFh9e, disconnecting from peer: hello.go:93
13:53:13.016 WARNI /fil/hello: failed to send hello handshake to peer : session shutdown hello.go:167
13:53:13.912 WARNI /fil/hello: genesis cid: zDPWYqFCutuHwRZhGXzji9L4eHeoFxxNCxCruGjWje36aAvbK2XV does not match: zDPWYqFCu9VdMN9pUSSttbjJsxd2sTJGZjXv8AGZvquxnsMRFh9e, disconnecting from peer: hello.go:93
13:53:42.619 INFO chain.sync: syncing the chain, currently at block height 69998 default_syncer.go:147
13:53:43.726 INFO autonat: Discovered AutoNAT peer QmZGDLdQLUTi7uYTNavKwCd7SBc5KMfxzWxAyvqRQvwuiV notify.go:33
13:53:55.808 INFO chain.sync: syncing the chain, currently at block height 67845 default_syncer.go:147
13:54:05.393 WARNI /fil/hello: failed to send hello handshake to peer : protocol not supported hello.go:167
13:54:05.769 INFO chain.sync: syncing the chain, currently at block height 65475 default_syncer.go:147
13:54:13.229 INFO chain.sync: syncing the chain, currently at block height 62596 default_syncer.go:147
13:54:20.057 INFO chain.sync: syncing the chain, currently at block height 59539 default_syncer.go:147
13:54:27.644 INFO chain.sync: syncing the chain, currently at block height 56728 default_syncer.go:147

13:54:33.820 INFO chain.sync: syncing the chain, currently at block height 53793 default_syncer.go:147

Version information

• Go:
• Rust:
• Cargo:
• Commit:

_go env_

<!-- go env -->

_cc_

<!-- $(go env | grep -E "^CC" |  sed -e 's/.*\=//' | sed -e 's/^"//' -e 's/"$//') --version -->

• Version:

Additional context

[ZenGround0]

@AndyChen1984 First off thank you very much for filing the full bug report! Filling out all of the fields was very helpful for helping diagnose your problem.

My best explanation of your issue is this: you simply need to wait longer for your chain to download and sync. Only after your node has synced the chain will it be able to see your funds. An unfortunate consequence of go-filecoin's prototype status is that chain syncing takes too long (it can take hours with a chain this long). See this issue for some relevant discussion and common points of user confusion.

If you still see this problem after your chain has synced (you can tell this by comparing the output of go-filecoin chain head to the head reported in the dashboard), please reopen this issue.

[AndyChen1984]

But I wait for two days,my node still have not synced one block successfully.

go-filecoin chain ls
zDPWYqFCu9VdMN9pUSSttbjJsxd2sTJGZjXv8AGZvquxnsMRFh9e

root@localhost:~# go-filecoin show block zDPWYqFCu9VdMN9pUSSttbjJsxd2sTJGZjXv8AGZvquxnsMRFh9e
Block Details
Miner: empty
Weight: 0.000
Height: 0
Nonce: 0

[dannyhchan]

Same issue as AndyChen1984. Any workarounds?

[anorth]

Since this issue was originally posted, we have released a new user devnet. Please ensure you are running the 0.2.2 code, and post your ./go-filecoin inspect output here.

Is your node connected to peers? Do log messages indicate that it's receiving messages and blocks?

[maybeuright]

Same issue as AndyChen1984.
"go-filecoin message wait $MESSAGE_CID" run almost 7 hours and still running.
And in the daemon window, below output are in a loop：
May 22 07:17:06.685 INFO verify_seal: start, target: FFI, root: filecoin-proofs, place: filecoin-proofs/src/api/mod.rs:47 filecoin_proofs::api
May 22 07:17:06.686 INFO trying parameters memory cache for: ZIGZAG[268435456]-verifying-key, target: params, root: filecoin-proofs, place: filecoin-proofs/src/api/internal.rs:77 filecoin_proofs::api::internal
May 22 07:17:06.686 INFO found params in memory cache for ZIGZAG[268435456]-verifying-key, target: params, root: filecoin-proofs, place: filecoin-proofs/src/api/internal.rs:82 filecoin_proofs::api::internal
May 22 07:17:06.686 INFO got verifying key (268435456) while verifying seal, target: params, root: filecoin-proofs, place: filecoin-proofs/src/api/internal.rs:710 filecoin_proofs::api::internal
May 22 07:17:06.707 INFO verify_seal: finish, target: FFI, root: filecoin-proofs, place: filecoin-proofs/src/api/mod.rs:88 filecoin_proofs::apie output like below:

[anorth]

Your node is validating the chain. This involves validating all the seal proofs: verify_seal. This is very slow: more than 7 hours is not unexpected at this point. You can follow progress by inspecting your chain head: ./go-filecoin show block $(./go-filecoin chain head).

At present, my node is at a block height approaching 32000.

[dannyhchan]

@anorth My current chain head is below, how long would it take to synchronize?
Block Details
Weight: 44590.002
Height: 4142
Nonce: 0

Also, does that mean my wallet will be 0 till the node syncs?

[AndyChen1984]

My chain is stopped at this block for tow days. @anorth

go-filecoin version
commit: fcf56acdc98a2ea4fc4d0b62878a9302f51af869

root@localhost:~/.filecoin# go-filecoin show block zDPWYqFCyENwJ2nYKkQjMH7PjJn74sHJvraMLvjAVXQituBavxky
Block Details
Miner: t2ez3a3mmljpfak3nw5hs272teefio6m5fi3yujxq
Weight: 17482.918
Height: 1342
Nonce: 0

scree :
7:19:37.594 ERROR node: processMessage(): validation error adding message to pool: message nonce (13208) is too much greater than actor nonce (0) node.go:1040
07:19:37.600 INFO node: Received new block from network cid: zDPWYqFCvRwv1kFncRPYV7rBAZQKTppk3cU1eXYXwgE94GQWZujN height: 228145 block.go:56
07:19:37.941 WARNI /fil/hello: failed to send hello handshake to peer QmQwV9Pr6jqsBXXmzuE2XvZQuhzBJCEMWTGYRyirifXSiJ: stream reset hello.go:179
07:19:38.001 INFO chain.sync: fetching the chain, 500 blocks fetched default_syncer.go:153
07:19:38.176 INFO chain.sync: fetching the chain, 1000 blocks fetched default_syncer.go:153
07:19:38.313 INFO chain.sync: fetching the chain, 1500 blocks fetched default_syncer.go:153
07:19:38.449 INFO chain.sync: fetching the chain, 2000 blocks fetched default_syncer.go:153
07:19:38.587 INFO chain.sync: fetching the chain, 2500 blocks fetched default_syncer.go:153
07:19:38.730 INFO chain.sync: fetching the chain, 3000 blocks fetched default_syncer.go:153
07:19:38.887 INFO chain.sync: fetching the chain, 3500 blocks fetched default_syncer.go:153
07:19:39.040 INFO chain.sync: fetching the chain, 4000 blocks fetched default_syncer.go:153
07:19:39.200 INFO chain.sync: fetching the chain, 4500 blocks fetched default_syncer.go:153
07:19:39.341 INFO chain.sync: fetching the chain, 5000 blocks fetched default_syncer.go:153
07:19:39.511 INFO chain.sync: fetching the chain, 5500 blocks fetched default_syncer.go:153
07:19:39.700 INFO chain.sync: fetching the chain, 6000 blocks fetched default_syncer.go:153
07:19:39.867 INFO chain.sync: fetching the chain, 6500 blocks fetched default_syncer.go:153
07:19:40.024 INFO chain.sync: fetching the chain, 7000 blocks fetched default_syncer.go:153
07:19:40.275 ERROR node: processMessage(): validation error adding message to pool: message nonce (17413) is too much greater than actor nonce (0) node.go:1040
07:19:40.338 INFO chain.sync: fetching the chain, 7500 blocks fetched default_syncer.go:153
07:19:40.422 ERROR node: processMessage(): validation error adding message to pool: message nonce (17414) is too much greater than actor nonce (0) node.go:1040
07:19:40.509 INFO chain.sync: fetching the chain, 8000 blocks fetched default_syncer.go:153
07:19:40.657 INFO chain.sync: chain fetch from network complete { zDPWYqFCvRwv1kFncRPYV7rBAZQKTppk3cU1eXYXwgE94GQWZujN } default_syncer.go:136
07:19:40.657 ERROR node: processBlock(): processing block from network: input chain contains a cached bad tipset node.go:1040
07:19:41.368 ERROR node: processMessage(): validation error adding message to pool: message nonce (17415) is too much greater than actor nonce (0) node.go:1040
07:19:41.889 ERROR node: processMessage(): validation error adding message to pool: message nonce (13209) is too much greater than actor nonce (0) node.go:1040
07:19:42.421 INFO chain.sync: fetching the chain, 500 blocks fetched default_syncer.go:153
07:19:42.553 INFO chain.sync: fetching the chain, 1000 blocks fetched default_syncer.go:153
07:19:42.692 INFO chain.sync: fetching the chain, 1500 blocks fetched default_syncer.go:153
07:19:42.825 INFO chain.sync: fetching the chain, 2000 blocks fetched default_syncer.go:153
07:19:42.953 INFO chain.sync: fetching the chain, 2500 blocks fetched default_syncer.go:153
07:19:43.128 INFO chain.sync: fetching the chain, 3000 blocks fetched default_syncer.go:153
07:19:43.275 INFO chain.sync: fetching the chain, 3500 blocks fetched default_syncer.go:153
07:19:43.410 INFO chain.sync: fetching the chain, 4000 blocks fetched default_syncer.go:153
07:19:43.553 INFO chain.sync: fetching the chain, 4500 blocks fetched default_syncer.go:153
07:19:43.714 INFO chain.sync: fetching the chain, 5000 blocks fetched default_syncer.go:153
07:19:43.867 INFO chain.sync: fetching the chain, 5500 blocks fetched default_syncer.go:153
07:19:43.895 INFO chain.sync: chain fetch from network complete { zDPWYqFD8EwAYAQCrb8ZBhS7trmL88Gpw7hfiCPbPJG8pBvMTnZJ } default_syncer.go:136
07:19:43.896 INFO node: error handling blocks: { zDPWYqFD8EwAYAQCrb8ZBhS7trmL88Gpw7hfiCPbPJG8pBvMTnZJ } node.go:512
07:19:44.024 INFO chain.sync: fetching the chain, 500 blocks fetched default_syncer.go:153
07:19:44.157 INFO chain.sync: fetching the chain, 1000 blocks fetched default_syncer.go:153
07:19:44.292 INFO chain.sync: fetching the chain, 1500 blocks fetched default_syncer.go:153
07:19:44.427 INFO chain.sync: fetching the chain, 2000 blocks fetched default_syncer.go:153
07:19:44.572 INFO chain.sync: fetching the chain, 2500 blocks fetched default_syncer.go:153
07:19:44.708 INFO chain.sync: fetching the chain, 3000 blocks fetched default_syncer.go:153
07:19:44.840 INFO chain.sync: fetching the chain, 3500 blocks fetched default_syncer.go:153
07:19:45.027 INFO chain.sync: fetching the chain, 4000 blocks fetched default_syncer.go:153
07:19:45.179 INFO chain.sync: fetching the chain, 4500 blocks fetched default_syncer.go:153
07:19:45.399 ERROR node: processMessage(): validation error adding message to pool: message nonce (12285) is too much greater than actor nonce (0) node.go:1040
07:19:45.442 INFO chain.sync: fetching the chain, 5000 blocks fetched default_syncer.go:153
07:19:45.592 INFO chain.sync: fetching the chain, 5500 blocks fetched default_syncer.go:153
07:19:45.756 INFO chain.sync: fetching the chain, 6000 blocks fetched default_syncer.go:153
07:19:45.914 INFO chain.sync: fetching the chain, 6500 blocks fetched default_syncer.go:153
07:19:45.968 INFO chain.sync: chain fetch from network complete { zDPWYqFD3F7MCnevEGcbfMR8CHgVvSQrgF1rU2jiNQy9AFvPpzQU } default_syncer.go:136
07:19:45.968 INFO node: error handling blocks: { zDPWYqFD3F7MCnevEGcbfMR8CHgVvSQrgF1rU2jiNQy9AFvPpzQU } node.go:512
07:19:46.118 INFO autonat: Discovered AutoNAT peer QmXhxqTKzBKHA5FcMuiKZv8YaMPwpbKGXHRVZcFB2DX9XY notify.go:33
07:19:46.591 INFO chain.sync: fetching the chain, 500 blocks fetched default_syncer.go:153
07:19:46.768 INFO chain.sync: fetching the chain, 1000 blocks fetched default_syncer.go:153
07:19:46.905 INFO chain.sync: fetching the chain, 1500 blocks fetched default_syncer.go:153
07:19:47.038 INFO chain.sync: fetching the chain, 2000 blocks fetched default_syncer.go:153
07:19:47.175 INFO chain.sync: fetching the chain, 2500 blocks fetched default_syncer.go:153
07:19:47.317 INFO chain.sync: fetching the chain, 3000 blocks fetched default_syncer.go:153
07:19:47.451 WARNI /fil/hello: failed to send hello handshake to peer QmQwV9Pr6jqsBXXmzuE2XvZQuhzBJCEMWTGYRyirifXSiJ: stream reset hello.go:179
07:19:47.462 INFO chain.sync: fetching the chain, 3500 blocks fetched default_syncer.go:153
07:19:47.611 INFO chain.sync: fetching the chain, 4000 blocks fetched default_syncer.go:153
07:19:47.764 INFO chain.sync: fetching the chain, 4500 blocks fetched default_syncer.go:153
07:19:47.900 INFO chain.sync: fetching the chain, 5000 blocks fetched default_syncer.go:153
07:19:48.063 INFO chain.sync: fetching the chain, 5500 blocks fetched default_syncer.go:153
07:19:48.246 INFO chain.sync: fetching the chain, 6000 blocks fetched default_syncer.go:153
07:19:48.400 INFO chain.sync: fetching the chain, 6500 blocks fetched default_syncer.go:153
07:19:48.556 INFO chain.sync: fetching the chain, 7000 blocks fetched default_syncer.go:153
07:19:48.860 INFO chain.sync: fetching the chain, 7500 blocks fetched default_syncer.go:153
07:19:49.027 INFO chain.sync: fetching the chain, 8000 blocks fetched default_syncer.go:153
07:19:49.174 INFO chain.sync: chain fetch from network complete { zDPWYqFCsyzmatmXY2Rj7H5xQ7GMk5r46qsbN8fuDcPBXehsWPsN } default_syncer.go:136
07:19:49.174 INFO node: error handling blocks: { zDPWYqFCsyzmatmXY2Rj7H5xQ7GMk5r46qsbN8fuDcPBXehsWPsN } node.go:512
07:19:51.288 INFO node: Received new block from network cid: zDPWYqFCvdMEdNqb1xECaxoQnZbqdW5J4gteFqLdLKZb68HgkWy6 height: 228147 block.go:56
07:19:51.552 INFO chain.sync: fetching the chain, 500 blocks fetched default_syncer.go:153
07:19:51.729 INFO chain.sync: fetching the chain, 1000 blocks fetched default_syncer.go:153
07:19:51.868 INFO chain.sync: fetching the chain, 1500 blocks fetched default_syncer.go:153
07:19:52.003 INFO chain.sync: fetching the chain, 2000 blocks fetched default_syncer.go:153
07:19:52.140 INFO chain.sync: fetching the chain, 2500 blocks fetched default_syncer.go:153
07:19:52.292 INFO chain.sync: fetching the chain, 3000 blocks fetched default_syncer.go:153
07:19:52.439 INFO chain.sync: fetching the chain, 3500 blocks fetched default_syncer.go:153
07:19:52.591 INFO chain.sync: fetching the chain, 4000 blocks fetched default_syncer.go:153
07:19:52.751 INFO chain.sync: fetching the chain, 4500 blocks fetched default_syncer.go:153
07:19:52.888 INFO chain.sync: fetching the chain, 5000 blocks fetched default_syncer.go:153
07:19:53.051 INFO chain.sync: fetching the chain, 5500 blocks fetched default_syncer.go:153
07:19:53.234 INFO chain.sync: fetching the chain, 6000 blocks fetched default_syncer.go:153
07:19:53.390 INFO chain.sync: fetching the chain, 6500 blocks fetched default_syncer.go:153
07:19:53.661 INFO chain.sync: fetching the chain, 7000 blocks fetched default_syncer.go:153
07:19:53.829 INFO chain.sync: fetching the chain, 7500 blocks fetched default_syncer.go:153
07:19:53.996 INFO chain.sync: fetching the chain, 8000 blocks fetched default_syncer.go:153
07:19:54.141 INFO chain.sync: chain fetch from network complete { zDPWYqFCvdMEdNqb1xECaxoQnZbqdW5J4gteFqLdLKZb68HgkWy6 } default_syncer.go:136
07:19:54.141 ERROR node: processBlock(): processing block from network: input chain contains a cached bad tipset node.go:1040
07:19:54.616 ERROR node: processMessage(): validation error adding message to pool: message nonce (29077) is too much greater than actor nonce (1348) node.go:1040
07:19:55.898 ERROR node: processMessage(): validation error adding message to pool: message nonce (29078) is too much greater than actor nonce (1348) node.go:1040
07:19:57.257 ERROR node: processMessage(): validation error adding message to pool: message nonce (29079) is too much greater than actor nonce (1348) node.go:1040
07:19:57.275 ERROR node: processMessage(): validation error adding message to pool: message nonce (17416) is too much greater than actor nonce (0) node.go:1040
07:19:57.563 ERROR node: processMessage(): validation error adding message to pool: message nonce (9044) is too much greater than actor nonce (387) node.go:1040
07:19:59.264 ERROR node: processMessage(): validation error adding message to pool: message nonce (29080) is too much greater than actor nonce (1348) node.go:1040
07:19:59.848 ERROR node: processMessage(): validation error adding message to pool: message nonce (15916) is too much greater than actor nonce (0) node.go:1040
07:19:59.860 ERROR node: processMessage(): validation error adding message to pool: message nonce (15917) is too much greater than actor nonce (0) node.go:1040
07:20:00.697 ERROR node: processMessage(): validation error adding message to pool: message nonce (9045) is too much greater than actor nonce (387) node.go:1040
07:20:02.307 ERROR node: processMessage(): validation error adding message to pool: message nonce (14929) is too much greater than actor nonce (0) node.go:1040
07:20:07.910 INFO node: Received new block from network cid: zDPWYqFD3crDakLW3LKhrXba4Dr8qUrZgVJk2RaPrMtjHWc7Yw53 height: 228151 block.go:56
07:20:08.169 INFO chain.sync: fetching the chain, 500 blocks fetched default_syncer.go:153
07:20:08.347 INFO chain.sync: fetching the chain, 1000 blocks fetched default_syncer.go:153
07:20:08.483 INFO chain.sync: fetching the chain, 1500 blocks fetched default_syncer.go:153
07:20:08.619 INFO chain.sync: fetching the chain, 2000 blocks fetched default_syncer.go:153
07:20:08.756 INFO chain.sync: fetching the chain, 2500 blocks fetched default_syncer.go:153
07:20:08.901 INFO chain.sync: fetching the chain, 3000 blocks fetched default_syncer.go:153
07:20:09.049 INFO chain.sync: fetching the chain, 3500 blocks fetched default_syncer.go:153
07:20:09.197 INFO chain.sync: fetching the chain, 4000 blocks fetched default_syncer.go:153
07:20:09.352 INFO chain.sync: fetching the chain, 4500 blocks fetched default_syncer.go:153
07:20:09.500 INFO chain.sync: fetching the chain, 5000 blocks fetched default_syncer.go:153
07:20:09.580 ERROR node: processMessage(): validation error adding message to pool: message nonce (9046) is too much greater than actor nonce (387) node.go:1040
07:20:09.801 INFO chain.sync: fetching the chain, 5500 blocks fetched default_syncer.go:153
07:20:10.016 INFO chain.sync: fetching the chain, 6000 blocks fetched default_syncer.go:153
07:20:10.173 INFO chain.sync: fetching the chain, 6500 blocks fetched default_syncer.go:153
07:20:10.321 INFO chain.sync: fetching the chain, 7000 blocks fetched default_syncer.go:153
07:20:10.488 INFO chain.sync: fetching the chain, 7500 blocks fetched default_syncer.go:153
07:20:10.655 INFO chain.sync: fetching the chain, 8000 blocks fetched default_syncer.go:153
07:20:10.803 INFO chain.sync: chain fetch from network complete { zDPWYqFD3crDakLW3LKhrXba4Dr8qUrZgVJk2RaPrMtjHWc7Yw53 } default_syncer.go:136
07:20:10.803 ERROR node: processBlock(): processing block from network: input chain contains a cached bad tipset node.go:1040
07:20:11.968 ERROR node: processMessage(): validation error adding message to pool: message nonce (12283) is too much greater than actor nonce (0) node.go:1040
07:20:13.319 ERROR node: processMessage(): validation error adding message to pool: message nonce (12284) is too much greater than actor nonce (0) node.go:1040
07:20:13.817 ERROR node: processMessage(): validation error adding message to pool: message nonce (14926) is too much greater than actor nonce (0) node.go:1040
07:20:15.141 ERROR node: processMessage(): validation error adding message to pool: message nonce (14927) is too much greater than actor nonce (0) node.go:1040
07:20:16.972 ERROR node: processMessage(): validation error adding message to pool: message nonce (9047) is too much greater than actor nonce (387) node.go:1040
07:20:18.017 ERROR node: processMessage(): validation error adding message to pool: message nonce (13207) is too much greater than actor nonce (0) node.go:1040
07:20:20.374 ERROR node: processMessage(): validation error adding message to pool: message nonce (14928) is too much greater than actor nonce (0) node.go:1040
07:20:24.973 ERROR node: processMessage(): validation error adding message to pool: message nonce (12861) is too much greater than actor nonce (0) node.go:1040
07:20:25.512 ERROR node: processMessage(): validation error adding message to pool: message nonce (12862) is too much greater than actor nonce (0) node.go:1040
07:20:25.811 INFO node: Received new block from network cid: zDPWYqFD7KEXaUjjQCWZhP6Q57FwZK3duCpHbKT78EybKTJjXkRL height: 228156 block.go:56
07:20:26.063 INFO chain.sync: fetching the chain, 500 blocks fetched default_syncer.go:153
07:20:26.132 ERROR node: processMessage(): validation error adding message to pool: message nonce (93961) is too much greater than actor nonce (512) node.go:1040
07:20:26.145 ERROR node: processMessage(): validation error adding message to pool: message nonce (93962) is too much greater than actor nonce (512) node.go:1040
07:20:26.246 INFO chain.sync: fetching the chain, 1000 blocks fetched default_syncer.go:153
07:20:26.387 INFO chain.sync: fetching the chain, 1500 blocks fetched default_syncer.go:153
07:20:26.522 INFO chain.sync: fetching the chain, 2000 blocks fetched default_syncer.go:153
07:20:26.664 INFO chain.sync: fetching the chain, 2500 blocks fetched default_syncer.go:153
07:20:26.812 INFO chain.sync: fetching the chain, 3000 blocks fetched default_syncer.go:153
07:20:26.962 INFO chain.sync: fetching the chain, 3500 blocks fetched default_syncer.go:153
07:20:27.112 INFO chain.sync: fetching the chain, 4000 blocks fetched default_syncer.go:153
07:20:27.391 INFO chain.sync: fetching the chain, 4500 blocks fetched default_syncer.go:153
07:20:27.530 INFO chain.sync: fetching the chain, 5000 blocks fetched default_syncer.go:153
07:20:27.614 ERROR node: processMessage(): validation error adding message to pool: message nonce (12863) is too much greater than actor nonce (0) node.go:1040
07:20:27.696 INFO chain.sync: fetching the chain, 5500 blocks fetched default_syncer.go:153
07:20:27.891 INFO chain.sync: fetching the chain, 6000 blocks fetched default_syncer.go:153
07:20:28.047 INFO chain.sync: fetching the chain, 6500 blocks fetched default_syncer.go:153
07:20:28.194 INFO chain.sync: fetching the chain, 7000 blocks fetched default_syncer.go:153
07:20:28.365 INFO chain.sync: fetching the chain, 7500 blocks fetched default_syncer.go:153
07:20:28.532 INFO chain.sync: fetching the chain, 8000 blocks fetched default_syncer.go:153
07:20:28.691 INFO chain.sync: chain fetch from network complete { zDPWYqFD7KEXaUjjQCWZhP6Q57FwZK3duCpHbKT78EybKTJjXkRL } default_syncer.go:136
07:20:28.691 ERROR node: processBlock(): processing block from network: input chain contains a cached bad tipset node.go:1040
07:20:30.260 ERROR node: processMessage(): validation error adding message to pool: message nonce (15918) is too much greater than actor nonce (0) node.go:1040
07:20:30.273 ERROR node: processMessage(): validation error adding message to pool: message nonce (15919) is too much greater than actor nonce (0) node.go:1040
07:20:33.588 INFO chain.sync: fetching the chain, 500 blocks fetched default_syncer.go:153
07:20:33.719 INFO chain.sync: fetching the chain, 1000 blocks fetched default_syncer.go:153
07:20:33.858 INFO chain.sync: fetching the chain, 1500 blocks fetched default_syncer.go:153
07:20:33.986 INFO chain.sync: fetching the chain, 2000 blocks fetched default_syncer.go:153
07:20:34.117 INFO chain.sync: fetching the chain, 2500 blocks fetched default_syncer.go:153
07:20:34.293 INFO chain.sync: fetching the chain, 3000 blocks fetched default_syncer.go:153
07:20:34.568 INFO chain.sync: fetching the chain, 3500 blocks fetched default_syncer.go:153
07:20:34.712 INFO chain.sync: fetching the chain, 4000 blocks fetched default_syncer.go:153
07:20:34.721 INFO node: Received new block from network cid: zDPWYqFD5ctEgXTn4SR8BVaJ3asuBaifJf6WYFeoxmqeHdMijgxH height: 228146 block.go:56
07:20:34.856 INFO chain.sync: fetching the chain, 4500 blocks fetched default_syncer.go:153
07:20:35.019 INFO chain.sync: fetching the chain, 5000 blocks fetched default_syncer.go:153
07:20:35.172 INFO chain.sync: fetching the chain, 5500 blocks fetched default_syncer.go:153
07:20:35.200 INFO chain.sync: chain fetch from network complete { zDPWYqFCyqddUqn5bLYyiSHadvqPQ6C5kzsDZz8juAy7sqf9XUZX } default_syncer.go:136
07:20:35.201 INFO node: error handling blocks: { zDPWYqFCyqddUqn5bLYyiSHadvqPQ6C5kzsDZz8juAy7sqf9XUZX } node.go:512
07:20:35.438 INFO chain.sync: fetching the chain, 500 blocks fetched default_syncer.go:153
07:20:35.620 INFO chain.sync: fetching the chain, 1000 blocks fetched default_syncer.go:153
07:20:35.761 INFO chain.sync: fetching the chain, 1500 blocks fetched default_syncer.go:153
07:20:35.901 INFO chain.sync: fetching the chain, 2000 blocks fetched default_syncer.go:153
07:20:36.040 INFO chain.sync: fetching the chain, 2500 blocks fetched default_syncer.go:153
07:20:36.185 INFO chain.sync: fetching the chain, 3000 blocks fetched default_syncer.go:153
07:20:36.333 INFO chain.sync: fetching the chain, 3500 blocks fetched default_syncer.go:153
07:20:36.483 INFO chain.sync: fetching the chain, 4000 blocks fetched default_syncer.go:153
07:20:36.639 INFO chain.sync: fetching the chain, 4500 blocks fetched default_syncer.go:153
07:20:36.776 INFO chain.sync: fetching the chain, 5000 blocks fetched default_syncer.go:153

[dannyhchan]

It's been more than a day, my height is still increasing but at a very slow rate.
Below are my current state:
Block Details
Weight: 209798.383
Height: 16622
Nonce: 0

Checking here: http://user.kittyhawk.wtf:8000/, the height is at 244756 at the highest block. Is this normal that the height is so slow to increase?

[RobQuistNL]

@dannyhchan yes, it is. It took me well over 80 hours to get up to sync. Hold tight, it will be there eventually :) also improvements will be coming soon.. However the POC and functionality have a higher priority than performance right now.

[dannyhchan]

@RobQuistNL Did you get your FIL before it synced? Or do I have to wait till everything is synced before getting the first FIL in the wallet?

[RobQuistNL]

@dannyhchan
Initially I had this bug where my chain was synched and I didn't get any FIL (the reason this issue was opened), but it will only appear after your chain is synced - because the messages putting the FIL in your wallet will be stacked on the end - you will have to wait for it to sync. If its not there after you're in sync, you can be quite sure something went wrong.

[anorth]

We've since released another new network, 0.3.2, with somewhat faster (but still slow) chain sync. I'll close this, but please open another if symptoms persist.