massa
massa copied to clipboard
Published
20 hours ago •
massalabs
massalabs
Problems on the current main branch
Messages
Messages: message.txt
Signature verification error on bootstrap
[2m2022-10-20T15:34:13.271761Z[0m [32m INFO[0m [2mmassa_bootstrap::client[0m[2m:[0m Start bootstrapping from 54.36.174.177:31245
[2m2022-10-20T15:35:48.706413Z[0m [33m WARN[0m [2mmassa_bootstrap::client[0m[2m:[0m Error while bootstrapping: io error: bad message
[2m2022-10-20T15:35:48.706499Z[0m [32m INFO[0m [2mmassa_bootstrap::client[0m[2m:[0m Bootstrap from server 54.36.174.177:31245 failed. Your node will try to bootstrap from another server in 60s.
[2m2022-10-20T15:36:48.707776Z[0m [32m INFO[0m [2mmassa_bootstrap::client[0m[2m:[0m Start bootstrapping from 51.75.60.228:31245
[2m2022-10-20T15:36:49.052342Z[0m [33m WARN[0m [2mmassa_bootstrap::client[0m[2m:[0m Error while bootstrapping: `massa_signature` error signature error : Signature verification failed: signature error: Verification equation was not satisfied
[2m2022-10-20T15:36:49.052402Z[0m [32m INFO[0m [2mmassa_bootstrap::client[0m[2m:[0m Bootstrap from server 51.75.60.228:31245 failed. Your node will try to bootstrap from another server in 60s.
[2m2022-10-20T15:37:49.058086Z[0m [32m INFO[0m [2mmassa_bootstrap::client[0m[2m:[0m Start bootstrapping from 198.27.74.5:31245
[2m2022-10-20T15:38:00.330648Z[0m [32m INFO[0m [2mmassa_bootstrap::client[0m[2m:[0m State bootstrap complete
[2m2022-10-20T15:38:25.859845Z[0m [32m INFO[0m [2mmassa_bootstrap::client[0m[2m:[0m Successful bootstrap
Cache mismatch at start
Note: the "cache empty" ones are expected but not the mismatch one
[2m2022-10-20T15:38:28.009925Z[0m [33m WARN[0m [2mmassa_execution_worker::execution[0m[2m:[0m speculative execution cache mismatch (final slot=(period: 54703, thread: 11)/block=Some(2MTQKXFRXZzKMY8pKeS5faR81cE6pmyY1rt73ktHnjFScNuAqW), front speculative slot=(period: 54703, thread: 11)/block=None). Resetting the cache.
[2m2022-10-20T15:38:28.017294Z[0m [32m INFO[0m [2mmassa_execution_worker::execution[0m[2m:[0m speculative execution cache empty, executing final slot=(period: 54703, thread: 12)/block=Some(s6jWbsceePfftyHHPcd4tqkGJNds8wUJLUbtNg7ywzXnzjQfx)
Wrong PoS state after bootstrap ?
PoS is desync 6 cycles after bootstrap
[2m2022-10-20T15:59:04.001910Z[0m [32m INFO[0m [2mmassa_consensus_worker::consensus_worker[0m[2m:[0m Started cycle 428
[2m2022-10-20T16:33:12.003115Z[0m [32m INFO[0m [2mmassa_consensus_worker::consensus_worker[0m[2m:[0m Started cycle 429
[2m2022-10-20T17:07:20.002029Z[0m [32m INFO[0m [2mmassa_consensus_worker::consensus_worker[0m[2m:[0m Started cycle 430
[2m2022-10-20T17:41:28.001220Z[0m [32m INFO[0m [2mmassa_consensus_worker::consensus_worker[0m[2m:[0m Started cycle 431
[2m2022-10-20T18:15:36.002044Z[0m [32m INFO[0m [2mmassa_consensus_worker::consensus_worker[0m[2m:[0m Started cycle 432
[2m2022-10-20T18:16:47.507788Z[0m [32m INFO[0m [2mmassa_factory_worker::block_factory[0m[2m:[0m block cRtKSQ8wspiVKjtX7mC4HAqNLddzH8aw4WyFGw4aUa8TcHaFK created at slot (period: 55300, thread: 15) by address A1ieGZG9TovywWwdquEkjfL381Tff5W9h88ipXV4pjd4NBUTnqJ
[2m2022-10-20T18:34:10.508332Z[0m [32m INFO[0m [2mmassa_factory_worker::block_factory[0m[2m:[0m block 2QqCwmqTvhTWE957Y9MwByLAoo8ig93sakx5pXyLcixX3uJZCC created at slot (period: 55365, thread: 21) by address A1ieGZG9TovywWwdquEkjfL381Tff5W9h88ipXV4pjd4NBUTnqJ
[2m2022-10-20T18:49:44.001127Z[0m [32m INFO[0m [2mmassa_consensus_worker::consensus_worker[0m[2m:[0m Started cycle 433
[2m2022-10-20T18:49:45.060608Z[0m [32m INFO[0m [2mmassa_graph::block_graph[0m[2m:[0m consensus.block_graph.maybe_note_attack_attempt DiscardReason::Invalid:endorser draw mismatch for header in slot: (period: 55424, thread: 0)
Note: there are MANY
[2m2022-10-20T18:49:52.208670Z[0m [32m INFO[0m [2mmassa_graph::block_graph[0m[2m:[0m consensus.block_graph.maybe_note_attack_attempt DiscardReason::Invalid:Bad creator turn for the slot:(period: 55424, thread: 14)
after the uploaded part of the log, and from there the node dies
I have launched a node on main I had no problems. My logs :
2022-10-21T07:25:53.639623Z INFO massa_node: Node version : TEST.15.1
2022-10-21T07:25:53.651823Z INFO massa_bootstrap::client: Start bootstrapping from 198.27.74.5:31245
2022-10-21T07:25:57.660060Z INFO massa_bootstrap::client: State bootstrap complete
2022-10-21T07:26:12.734874Z INFO massa_bootstrap::client: Successful bootstrap
2022-10-21T07:26:12.736124Z INFO massa_network_worker: The node_id of this node is: P1ex7EghGtDtzUaWtGwuk5LnwBeST7BL97iermrpUUyhLBQjcQg
2022-10-21T07:26:12.859366Z INFO massa_protocol_worker::protocol_network: Connected to node P12RXutHgj6iPv6nETVbVhWG7czABCzLxdNnLrbqLhiwtARyKRwF
2022-10-21T07:26:12.880096Z INFO massa_protocol_worker::protocol_network: Connected to node P123QdNzbnn8HFXu2FDR198jsQX6nTZ2hzzqAKuQLwTaCcoKVSj8
2022-10-21T07:26:12.886043Z INFO massa_protocol_worker::protocol_network: Connected to node P1gFb4tiSQ6M7b4PoP1o27xjnJdzi45k6Xziw1zHhnv4NuihBRM
2022-10-21T07:26:12.886308Z INFO massa_protocol_worker::protocol_network: Connected to node P12E8TxpK9x6MBp8DByjQMgCDKAApKDfEuVK2jc7cwRB4ATbVsjs
2022-10-21T07:26:12.890951Z INFO massa_protocol_worker::protocol_network: Connected to node P123JUyujn2dd3DNVgGHZBCyuAMtERZCMTcJXhxTp5savwerYfuH
2022-10-21T07:26:12.970655Z INFO massa_consensus_worker::consensus_worker: Started node at time 2022-10-21T07:26:12Z, cycle 455, period 58260, thread 26
2022-10-21T07:26:13.011776Z INFO massa_execution_worker::execution: speculative execution cache empty, executing final slot=(period: 58257, thread: 20)/block=Some(gAhT3MhEW13VMYD4vrCU4F57B1Tqp3H2CeutDU6TSCeQREaVF)
2022-10-21T07:26:13.016606Z INFO massa_execution_worker::execution: speculative execution cache empty, executing final slot=(period: 58257, thread: 21)/block=Some(peEPnTE6JF5FqqTxBDmCUzS4oXmDAhShj5LpT9KJbU4Sgex2D)
2022-10-21T07:26:13.021638Z INFO massa_execution_worker::execution: speculative execution cache empty, executing final slot=(period: 58257, thread: 22)/block=Some(2g5MAC76UQ4oJ7WyiWSqFQz4DgBA6Z5wcaaF2nyKaGo6t3ygFP)
2022-10-21T07:26:13.026568Z INFO massa_execution_worker::execution: speculative execution cache empty, executing final slot=(period: 58257, thread: 23)/block=Some(2bdPoz4K9rmrnzFGcMhEiLFjdBsg2sCbjzGpNtJjbvjH8nvGAg)
2022-10-21T07:26:13.142610Z INFO massa_protocol_worker::protocol_network: Connected to node P12jPg6F4MzEXDrfrnezBtwUJaJNXobyaPGKmEMXmzoxppomsnKK
2022-10-21T07:26:13.155969Z INFO massa_protocol_worker::protocol_network: Connected to node P1Uj4LhW4uUu1KcoxLnLvf1xA7uDEGd5mciCyzJSRBBecw6r5xU
2022-10-21T07:26:17.976960Z INFO massa_protocol_worker::protocol_network: Connected to node P1mENPanVqW9xVf9kN1eNwBCi1gmoedXgAHQxG8YBi9MAS3H8Xk
2022-10-21T07:26:19.035095Z INFO massa_protocol_worker::protocol_network: Connected to node P122Hq2QT8KYiVcj7EX1pTCWBQ5WMdcVCUmaYkjKihfnHyaZZtdi
2022-10-21T07:26:19.203153Z INFO massa_protocol_worker::protocol_network: Connected to node P1268xUMrySnWMFkR3bZcDcuQJ7iydTvi3j2Xcz664NUHHLfbheH
I was able to replicate only the speculative mismatch bug:
2022-10-20T20:54:45.115728Z INFO massa_consensus_worker::consensus_worker: Started node at time 2022-10-20T20:54:45Z, cycle 436, period 55892, thread 27
2022-10-20T20:54:45.119505Z INFO massa_protocol_worker::protocol_network: Connected to node P12GsQEqLBKana5Acr47Q4oxeECJmBR9hhnujAc62tgMbzb2ekeL
2022-10-20T20:54:45.175511Z INFO massa_execution_worker::execution: speculative execution cache empty, executing final slot=(period: 55889, thread: 4)/block=Some(8h59RrA1b77rLQu1VYqZYjTVX5CM1of5WwiDpGFz4fBuV7dni)
2022-10-20T20:54:45.508406Z INFO massa_protocol_worker::protocol_network: Connected to node P12LThTs4ZtUWnwB7mBy7gSDZRvNe1HZapHUwxqwfAFG2t1Vo1YH
2022-10-20T20:54:48.919790Z WARN massa_execution_worker::execution: speculative execution cache mismatch (final slot=(period: 55889, thread: 27)/block=Some(TqH5AwtFAvCR5x2bkSijQZopFyrQRLwW9yGHT5YhnZmJ7G9R9), front speculative slot=(period: 55889, thread: 27)/block=None). Resetting the cache.
2022-10-20T20:54:48.925669Z INFO massa_execution_worker::execution: speculative execution cache empty, executing final slot=(period: 55889, thread: 28)/block=Some(t54dRn4hPnFtmrFBnGNdBchhkY2rrsebL2or3yHkSBnvhrm6o)
2022-10-20T20:54:48.932238Z INFO massa_execution_worker::execution: speculative execution cache empty, executing final slot=(period: 55889, thread: 29)/block=Some(2cx3QLENS8Ft4gPFM6K125QvSXc2DCR7h5GHh1CGNHmQ5Y9zg)
I was able to replicate only the speculative mismatch bug:
I relaunched and had this one also :
2022-10-21T08:19:31.329367Z INFO massa_node: Node version : TEST.15.1
2022-10-21T08:19:31.341719Z INFO massa_bootstrap::client: Start bootstrapping from 149.202.86.103:31245
2022-10-21T08:19:35.587371Z INFO massa_bootstrap::client: State bootstrap complete
2022-10-21T08:19:53.808644Z INFO massa_bootstrap::client: Successful bootstrap
2022-10-21T08:19:53.809770Z INFO massa_network_worker: The node_id of this node is: P1ex7EghGtDtzUaWtGwuk5LnwBeST7BL97iermrpUUyhLBQjcQg
2022-10-21T08:19:54.096244Z INFO massa_consensus_worker::consensus_worker: Started node at time 2022-10-21T08:19:54Z, cycle 456, period 58462, thread 5
2022-10-21T08:19:54.142963Z INFO massa_execution_worker::execution: speculative execution cache empty, executing final slot=(period: 58458, thread: 28)/block=Some(wXCaYcYgT3HdeHEZk8YgNjEUU2hkZSRk2BmpbJwZzk8BzBRHc)
2022-10-21T08:19:54.154291Z INFO massa_protocol_worker::protocol_network: Connected to node P1bYFPK7xqwFSzgKGSTMdPUgKDpy8degzwWrSnB6aQgVoaQUpLB
2022-10-21T08:19:54.203771Z INFO massa_protocol_worker::protocol_network: Connected to node P125yvWXXGEZu5NskpMa5M4k4p3aKQH7S2FBTThg6MSSgdcRSeDk
2022-10-21T08:19:54.512512Z INFO massa_protocol_worker::protocol_network: Connected to node P1U2YWJtgn5LN6ox9WvmAhG4P4KNM4LbevcVkx1tDc92GuhKX9M
2022-10-21T08:19:54.623866Z INFO massa_protocol_worker::protocol_network: Connected to node P12Lwym5nBmawviAsuyy1hHavxFdC8oKcJyNpH2E4yW1uT9EgDg8
2022-10-21T08:19:54.996857Z INFO massa_protocol_worker::protocol_network: Connected to node P1SB9tLWXtGnJjKkajmtWmR7N6K5F9Ko8N8ypGKHc3rEmg4BkSk
2022-10-21T08:19:58.146503Z INFO massa_protocol_worker::protocol_network: Connected to node P12X85HihQuSQ4yLhzn9ahovuSu2HaBLMLFJPUMhDz5xqMCrAdcP
2022-10-21T08:19:58.190823Z INFO massa_protocol_worker::protocol_network: Connected to node P12UdDt8NcCt9nmT2n5CXbX8LkhA5FFs9X2Vdsd3Dea6mxZuU6sZ
2022-10-21T08:19:58.677923Z INFO massa_protocol_worker::protocol_network: Connected to node P13hMxLF5Uwd6KMfTuXhd6iGzkuzfdu9r5BaiBZBSF5G95abNqu
2022-10-21T08:19:58.764825Z INFO massa_protocol_worker::protocol_network: Connected to node P14zH4AVFQcWMd973YF9kjrRhXEo4LAiqjUyo4tZUXWspLfNV5q
2022-10-21T08:19:59.867263Z WARN massa_execution_worker::execution: speculative execution cache mismatch (final slot=(period: 58458, thread: 30)/block=Some(26XeAXGcLw74P4eQcj9JccV43ZztBUzDrfMYAGvwibT5ih45rQ), front speculative slot=(period: 58458, thread: 30)/block=None). Resetting the cache.
2022-10-21T08:19:59.872338Z INFO massa_execution_worker::execution: speculative execution cache empty, executing final slot=(period: 58458, thread: 31)/block=Some(zDTN1fRZySenHS2jaQmveMnkwzU7uApsZBFBSGaZWWcTdXmhP)
2022-10-21T08:19:59.877186Z INFO massa_execution_worker::execution: speculative execution cache empty, executing final slot=(period: 58459, thread: 0)/block=Some(K5cqcbnS2pBHgtbeAVrKaGctcKJivtgHsqRB9WBz3yjvMsvTJ)
2022-10-21T08:19:59.882152Z INFO massa_execution_worker::execution: speculative execution cache empty, executing final slot=(period: 58459, thread: 1)/block=Some(igD6cpZNtNVywx9ziWevgP2g6mYX5JXS2bzpCXpwDFTEBTvez)
2022-10-21T08:19:59.887109Z INFO massa_execution_worker::execution: speculative execution cache empty, executing final slot=(period: 58459, thread: 2)/block=Some(w4Wt5RHD4jkKcFD5xc76AsEXFCbmLYfMN8tSmWdNH6TPdhnEa)
2022-10-21T08:19:59.892129Z INFO massa_execution_worker::execution: speculative execution cache empty, executing final slot=(period: 58459, thread: 3)/block=Some(9YMA3GAVjNLgXNo38r5Eiy6Eusrm3tizX2vEdVdTury8ixBaa)
2022-10-21T08:19:59.897143Z INFO massa_execution_worker::execution: speculative execution cache empty, executing final slot=(period: 58459, thread: 4)/block=Some(VVZEBo77ZemWm2abEecjAPGCvdadio9GDet4DpcJGLmAepK7B)
2022-10-21T08:19:59.902238Z INFO massa_execution_worker::execution: speculative execution cache empty, executing final slot=(period: 58459, thread: 5)/block=Some(Lv8T8BTHE3f1nbPok6HfQLPE7pJTaqy8C4TtMR5mStrGZaLke)
2022-10-21T08:19:59.907686Z INFO massa_execution_worker::execution: speculative execution cache empty, executing final slot=(period: 58459, thread: 6)/block=Some(6n6YDd8CcCMxm8hyNF9oZ3oF9kB1iJZ2TL3BhUkzx4jNsvbbx)