filecoin-project / venus

Filecoin Full Node Implementation in Go
https://venus.filecoin.io
Other
2.06k stars 463 forks source link

[bug]error in handler github.com/filecoin-project/go-filecoin/node.(*Node). #3297

Closed adextop closed 5 years ago

adextop commented 5 years ago

After I finished init node, I started go-filecoin daemon, It always errors happened. I am not sure init was ok or not, and why it's always errors there? pls tell me the resolve solution. thanks.

details like this:

# go-filecoin init  --devnet-user --genesisfile=https://genesis.user.kittyhawk.wtf/genesis.car  
initializing filecoin node at /root/.filecoin
21:15:22.621  INFO     badger: All 0 tables opened in 0s
 logger.go:46
21:15:22.644  INFO     badger: All 0 tables opened in 0s
 logger.go:46
21:15:22.650  INFO     badger: All 0 tables opened in 0s
 logger.go:46
21:15:22.657  INFO     badger: All 0 tables opened in 0s

#go-filecoin daemon 
21:15:53.377  INFO     badger: All 1 tables opened in 1ms
 logger.go:46
21:15:53.378  INFO     badger: Replaying file id: 0 at offset: 3912
 logger.go:46
21:15:53.379  INFO     badger: Replay took: 21.186µs
 logger.go:46
21:15:53.383  INFO     badger: All 1 tables opened in 1ms
 logger.go:46
21:15:53.384  INFO     badger: Replaying file id: 0 at offset: 176
 logger.go:46
21:15:53.384  INFO     badger: Replay took: 17.67µs
 logger.go:46
21:15:53.388  INFO     badger: All 1 tables opened in 1ms
 logger.go:46
21:15:53.388  INFO     badger: Replaying file id: 0 at offset: 323
 logger.go:46
21:15:53.389  INFO     badger: Replay took: 13.898µs
 logger.go:46
21:15:53.391  INFO     badger: All 0 tables opened in 0s
 logger.go:46
21:15:53.392  INFO     badger: Replaying file id: 0 at offset: 0
 logger.go:46
21:15:53.392  INFO     badger: Replay took: 11.947µs
 logger.go:46
My peer ID is QmQsh7CP9ftiEZKEdgh4dDfsxkoTBgTKe6FGNfABnaC2UA
Swarm listening on: /ip4/127.0.0.1/tcp/6000
Swarm listening on: /ip4/154.221.26.35/tcp/6000
21:15:53.394  INFO chain.stor: start loading chain at tipset: { bafy2bzacecevx2g2fs2lcxknmein52cm2gqocfg7rc2u2dotsyo5r6osgdyy4 }, height: 0 store.go:135
21:15:53.395  INFO chain.stor: finished loading 0 tipsets from { bafy2bzacecevx2g2fs2lcxknmein52cm2gqocfg7rc2u2dotsyo5r6osgdyy4 } store.go:179
21:16:04.758  INFO peer-track: Tracking {peer=QmXhxqTKzBKHA5FcMuiKZv8YaMPwpbKGXHRVZcFB2DX9XY height=716 head={ bafy2bzacebnqrogjjd76ajregikwcqrukfdhn54azhfijalpe6gzymd2b3vyg }}, new=true, count=1 peer_tracker.go:40
21:16:04.758  INFO net.graphs: fetching initial tipset { bafy2bzacebnqrogjjd76ajregikwcqrukfdhn54azhfijalpe6gzymd2b3vyg } from peer QmXhxqTKzBKHA5FcMuiKZv8YaMPwpbKGXHRVZcFB2DX9XY graphsync_fetcher.go:117
21:16:05.225  INFO net.graphs: fetching chain from height 716, block bafy2bzacebnqrogjjd76ajregikwcqrukfdhn54azhfijalpe6gzymd2b3vyg, peer QmXhxqTKzBKHA5FcMuiKZv8YaMPwpbKGXHRVZcFB2DX9XY, 1 levels graphsync_fetcher.go:158
21:16:05.454  INFO peer-track: Tracking {peer=QmUDAnhTNwEGscVfEgLfA4Tt8BFMWMcGeUHzjWWqWQ3nAX height=726 head={ bafy2bzacecixeqfca4ceot5u32f4hxpqmkugyzjbb3qhlypq5jpsdr4ooa7cw }}, new=true, count=2 peer_tracker.go:40
21:16:05.554  INFO peer-track: Tracking {peer=QmUNcaGMskX9yC5EohyLRonwrPSp1xeCHrok1gwqGB9yix height=726 head={ bafy2bzacecixeqfca4ceot5u32f4hxpqmkugyzjbb3qhlypq5jpsdr4ooa7cw }}, new=true, count=3 peer_tracker.go:40
21:16:06.678  INFO peer-track: Tracking {peer=Qmd6xrWYHsxivfakYRy6MszTpuAiEoFbgE1LWw4EvwBpp4 height=726 head={ bafy2bzacecixeqfca4ceot5u32f4hxpqmkugyzjbb3qhlypq5jpsdr4ooa7cw }}, new=true, count=4 peer_tracker.go:40
21:16:06.741  INFO peer-track: Tracking {peer=QmZRnwmCjyNHgeNDiyT8mXRtGhP6uSzgHtrozc42crmVbg height=720 head={ bafy2bzacedt2z3gklcbgjhsi4cheib6nftaandjr3v2ie3sb3ppk3calwdxki }}, new=true, count=5 peer_tracker.go:40
21:16:06.810  INFO net.graphs: fetching chain from height 714, block bafy2bzaceac3vz54jkcfe2th6fng7dlyggbktc2o5iutbycdcqui4dywsof6y, peer QmXhxqTKzBKHA5FcMuiKZv8YaMPwpbKGXHRVZcFB2DX9XY, 4 levels graphsync_fetcher.go:158
21:16:06.937  INFO       node: error in handler github.com/filecoin-project/go-filecoin/node.(*Node).processMessage-fm for topic /fil/msgs/devnet-4: validation error adding message to pool: message nonce (1086) is too much greater than actor nonce (0) node.go:1103
21:16:07.494  INFO net.graphs: fetching chain from height 699, block bafy2bzaceaklpua4clviryc5wrqffjiey273lrta6qjx3hslp4jtz4rh6dgcu, peer QmXhxqTKzBKHA5FcMuiKZv8YaMPwpbKGXHRVZcFB2DX9XY, 16 levels graphsync_fetcher.go:158
21:16:07.669  INFO       node: error in handler github.com/filecoin-project/go-filecoin/node.(*Node).processMessage-fm for topic /fil/msgs/devnet-4: validation error adding message to pool: message nonce (2012) is too much greater than actor nonce (3) node.go:1103
21:16:07.948  INFO peer-track: Tracking {peer=QmZGDLdQLUTi7uYTNavKwCd7SBc5KMfxzWxAyvqRQvwuiV height=713 head={ bafy2bzacedufh67h4wrbugzgkrgolgduxxsx24ui4rihdiph7fovolbclpxlg }}, new=true, count=6 peer_tracker.go:40
21:16:09.312  INFO peer-track: Tracking {peer=QmNekXn8mB4F3NunpZFXJY76Faydd8pPqfdP9wGkQeaQnx height=723 head={ bafy2bzacecqhrdeg5xiqf6ote2u4sdgtpjzqakhzgrrhqh2q5dow4zbnt7xp2 }}, new=true, count=7 peer_tracker.go:40
21:16:09.318  INFO    autonat: Discovered AutoNAT peer QmXhxqTKzBKHA5FcMuiKZv8YaMPwpbKGXHRVZcFB2DX9XY notify.go:34
21:16:09.389  INFO peer-track: Tracking {peer=QmWpsJKLYD4JQd7fQVjHX2gFz8MFYoyLVDxSVTVggozUAF height=720 head={ bafy2bzacedtkdedtn2zlbgf2jgtuidfmfpshlqkfew3wffwv6l5lypjajoula bafy2bzacedt2z3gklcbgjhsi4cheib6nftaandjr3v2ie3sb3ppk3calwdxki }}, new=true, count=8 peer_tracker.go:40
21:16:09.960 WARNI net/identi: error reading identify message:  short buffer id.go:190
21:16:10.178  INFO peer-track: Tracking {peer=QmNjzNu5hfgxrcdh9AY3mGerEctErBhe3CP7ubnMwvyN9j height=716 head={ bafy2bzaceajikxhp6cdt6lioofu2vdnqfjgue6rkl4k4tafamvuzqhklvcuuo bafy2bzacebnqrogjjd76ajregikwcqrukfdhn54azhfijalpe6gzymd2b3vyg }}, new=true, count=9 peer_tracker.go:40
21:16:11.153  INFO    autonat: Discovered AutoNAT peer QmZGDLdQLUTi7uYTNavKwCd7SBc5KMfxzWxAyvqRQvwuiV notify.go:34
21:16:11.237  INFO    autonat: Discovered AutoNAT peer Qmd6xrWYHsxivfakYRy6MszTpuAiEoFbgE1LWw4EvwBpp4 notify.go:34
21:16:11.255  INFO       node: error in handler github.com/filecoin-project/go-filecoin/node.(*Node).processMessage-fm for topic /fil/msgs/devnet-4: validation error adding message to pool: balance insufficient to cover transfer+gas node.go:1103
21:16:11.286  INFO    autonat: Discovered AutoNAT peer QmZRnwmCjyNHgeNDiyT8mXRtGhP6uSzgHtrozc42crmVbg notify.go:34
21:16:12.050  INFO net.graphs: fetching chain from height 647, block bafy2bzaceblr4h5xlmubdegtrq4d7algea5bcbicztx7jttcwsjyz2sxter3g, peer QmXhxqTKzBKHA5FcMuiKZv8YaMPwpbKGXHRVZcFB2DX9XY, 64 levels graphsync_fetcher.go:158
21:16:12.506  INFO    autonat: Discovered AutoNAT peer QmZGDLdQLUTi7uYTNavKwCd7SBc5KMfxzWxAyvqRQvwuiV notify.go:34
21:16:15.119  INFO       node: error in handler github.com/filecoin-project/go-filecoin/node.(*Node).processMessage-fm for topic /fil/msgs/devnet-4: validation error adding message to pool: balance insufficient to cover transfer+gas node.go:1103
21:16:16.010  INFO       node: error in handler github.com/filecoin-project/go-filecoin/node.(*Node).processMessage-fm for topic /fil/msgs/devnet-4: validation error adding message to pool: message nonce (1087) is too much greater than actor nonce (0) node.go:1103
21:16:18.290  INFO       node: error in handler github.com/filecoin-project/go-filecoin/node.(*Node).processMessage-fm for topic /fil/msgs/devnet-4: validation error adding message to pool: balance insufficient to cover transfer+gas node.go:1103
21:16:20.878  INFO peer-track: Tracking {peer=QmXq6XEYeEmUzBFuuKbVEGgxEpVD4xbSkG2Rhek6zkFMp4 height=718 head={ bafy2bzacebo3kovakkotdgddxbp4tf5jvzt5czo7qc4upfhlrtc2hmkqbuc56 }}, new=true, count=10 peer_tracker.go:40
21:16:21.514  INFO    autonat: Discovered AutoNAT peer QmXq6XEYeEmUzBFuuKbVEGgxEpVD4xbSkG2Rhek6zkFMp4 notify.go:34
21:16:22.365  INFO peer-track: Tracking {peer=QmUm9NA94vSMLqQQ3djWgTZhTsoRkxvNqSHrCg499GX2eP height=726 head={ bafy2bzacecixeqfca4ceot5u32f4hxpqmkugyzjbb3qhlypq5jpsdr4ooa7cw }}, new=true, count=11 peer_tracker.go:40
21:16:22.672  INFO net.graphs: fetching chain from height 513, block bafy2bzaced6pwpas6ya7bxphexn4tt7j46r7t66wcbwfmixc3lfkygxfbuchy, peer QmXhxqTKzBKHA5FcMuiKZv8YaMPwpbKGXHRVZcFB2DX9XY, 64 levels graphsync_fetcher.go:158
21:16:23.099  INFO    autonat: Discovered AutoNAT peer QmXq6XEYeEmUzBFuuKbVEGgxEpVD4xbSkG2Rhek6zkFMp4 notify.go:34
21:16:24.013  INFO    autonat: Discovered AutoNAT peer QmXq6XEYeEmUzBFuuKbVEGgxEpVD4xbSkG2Rhek6zkFMp4 notify.go:34
21:16:25.409  INFO    autonat: Discovered AutoNAT peer QmXq6XEYeEmUzBFuuKbVEGgxEpVD4xbSkG2Rhek6zkFMp4 notify.go:34
21:16:26.036  INFO       node: error in handler github.com/filecoin-project/go-filecoin/node.(*Node).processMessage-fm for topic /fil/msgs/devnet-4: validation error adding message to pool: message nonce (262) is too much greater than actor nonce (0) node.go:1103
21:16:26.436  INFO       node: error in handler github.com/filecoin-project/go-filecoin/node.(*Node).processMessage-fm for topic /fil/msgs/devnet-4: validation error adding message to pool: message nonce (992) is too much greater than actor nonce (0) node.go:1103
21:16:26.707  INFO       node: error in handler github.com/filecoin-project/go-filecoin/node.(*Node).processMessage-fm for topic /fil/msgs/devnet-4: validation error adding message to pool: balance insufficient to cover transfer+gas node.go:1103
21:16:28.283  INFO net.graphs: fetching chain from height 384, block bafy2bzacecaxbqn5zni5jjwmfee7s6mk6qm4rwwtl4w5sg2vkbenjxojsp2iq, peer QmXhxqTKzBKHA5FcMuiKZv8YaMPwpbKGXHRVZcFB2DX9XY, 64 levels graphsync_fetcher.go:158
21:16:30.560  INFO       node: error in handler github.com/filecoin-project/go-filecoin/node.(*Node).processMessage-fm for topic /fil/msgs/devnet-4: validation error adding message to pool: message nonce (2013) is too much greater than actor nonce (3) node.go:1103
21:16:32.183  INFO       node: error in handler github.com/filecoin-project/go-filecoin/node.(*Node).processMessage-fm for topic /fil/msgs/devnet-4: validation error adding message to pool: balance insufficient to cover transf
travisperson commented 5 years ago

I don't think there is actually a bug anywhere here so I'm going to close this. Please reopen if I missed something.