mining: unexpected results for BMM block inclusion

[torkelrogstad]

This is logs for both Thunder and Enforcer. Doing a BMM attempt from Thunder yielded this final log line: mine: BMM not accepted as new tip: 000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1. However, when looking at the logs from the enforcer, it seems like this block actually was accepted?

A mainchain block with this hash can also be found in the explorer: https://mempool.drivechain.live/block/0000002d6a3f40d6788c1456320378486239efcc64f04d50e81b2a8aa89334f1

enforcer-1|2025-01-26T15:30:02.166561Z  INFO bip300301_enforcer_lib::wallet::cusf_block_producer: src/wallet/cusf_block_producer.rs:30: CUSF block producer: connecting block 000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1
enforcer-1|2025-01-26T16:30:02.166000+0100 DEBUG bip300301_enforcer_lib::validator::cusf_enforcer: src/validator/cusf_enforcer.rs:178: Storing header block_hash=000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1
enforcer-1|2025-01-26T16:30:02.166000+0100 DEBUG bip300301_enforcer_lib::validator::cusf_enforcer: src/validator/cusf_enforcer.rs:190: Connecting block block_hash=000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1
enforcer-1|2025-01-26T16:30:02.166000+0100 TRACE connect_block: bip300301_enforcer_lib::validator::task: src/validator/task/mod.rs:661: Failed to parse coinbase script: Failure(Error { input: [20, 156, 1, 139, 165, 251, 76, 10, 227, 209, 123, 93, 211, 28, 29, 166, 213, 117, 82, 131, 163], code: Fail }) block_hash=000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1
enforcer-1|2025-01-26T16:30:02.166000+0100 TRACE connect_block: bip300301_enforcer_lib::validator::task: src/validator/task/mod.rs:661: Failed to parse coinbase script: Error(Error { input: [170, 33, 169, 237, 226, 246, 28, 63, 113, 209, 222, 253, 63, 169, 153, 223, 163, 105, 83, 117, 92, 105, 6, 137, 121, 153, 98, 180, 139, 235, 216, 54, 151, 78, 140, 249], code: Tag }) block_hash=000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1
enforcer-1|2025-01-26T16:30:02.166000+0100 TRACE connect_block: bip300301_enforcer_lib::validator::task: src/validator/task/mod.rs:714: Handled coinbase tx, handling other txs... block_hash=000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1
enforcer-1|2025-01-26T16:30:02.166000+0100 TRACE connect_block: bip300301_enforcer_lib::validator::task: src/validator/task/mod.rs:735: Handled block txs block_hash=000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1
enforcer-1|2025-01-26T16:30:02.166000+0100 TRACE connect_block: bip300301_enforcer_lib::validator::task: src/validator/task/mod.rs:741: Storing block info block_hash=000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1
enforcer-1|2025-01-26T16:30:02.166000+0100 TRACE connect_block: bip300301_enforcer_lib::validator::task: src/validator/task/mod.rs:746: Stored block info block_hash=000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1
enforcer-1|2025-01-26T16:30:02.166000+0100 DEBUG connect_block: bip300301_enforcer_lib::validator::task: src/validator/task/mod.rs:761: updated current chain tip: 18429 block_hash=000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1
enforcer-1|2025-01-26T15:30:02.166807Z  INFO bip300301_enforcer_lib::validator::cusf_enforcer: src/validator/cusf_enforcer.rs:244: Accepted block block_hash=000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1
enforcer-1|2025-01-26T15:30:02.169556Z  INFO request: tower_http::trace::on_response: /usr/local/cargo/registry/src/index.crates.io-6f17d22bba15001f/tower-http-0.6.2/src/trace/on_response.rs:114: finished processing request latency=0 ms method=POST uri=http://enforcer:50051/cusf.mainchain.v1.ValidatorService/GetBlockHeaderInfo version=HTTP/2.0
enforcer-1|2025-01-26T15:30:02.173001Z  INFO request: tower_http::trace::on_response: /usr/local/cargo/registry/src/index.crates.io-6f17d22bba15001f/tower-http-0.6.2/src/trace/on_response.rs:114: finished processing request latency=0 ms method=POST uri=http://enforcer:50051/cusf.mainchain.v1.ValidatorService/GetBmmHStarCommitment version=HTTP/2.0
enforcer-1|2025-01-26T15:30:02.177009Z  INFO request: tower_http::trace::on_response: /usr/local/cargo/registry/src/index.crates.io-6f17d22bba15001f/tower-http-0.6.2/src/trace/on_response.rs:114: finished processing request latency=0 ms method=POST uri=http://enforcer:50051/cusf.mainchain.v1.ValidatorService/GetChainTip version=HTTP/2.0
thunder-1 |2025-01-26T16:30:02.168000+0100 DEBUG thunder::miner:88: confirm BMM: verified block_hash=f9ecb1500ebe466b74ee3606d29fb9c042e59f75f2a487a7342744a54d69ce21
thunder-1 |2025-01-26T16:30:02.169000+0100 DEBUG thunder_app::app:364: mine: confirmed BMM, submitting block f9ecb1500ebe466b74ee3606d29fb9c042e59f75f2a487a7342744a54d69ce21
thunder-1 |2025-01-26T16:30:02.169000+0100 DEBUG thunder::node::mainchain_task:115: requesting ancestor headers block_hash=000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1
thunder-1 |2025-01-26T16:30:02.169000+0100 TRACE thunder::node::mainchain_task:150: storing ancestor headers block_hash=000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1
thunder-1 |2025-01-26T16:30:02.172000+0100 TRACE thunder::node::mainchain_task:157: stored ancestor headers block_hash=000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1
thunder-1 |2025-01-26T16:30:02.172000+0100 DEBUG thunder::node::mainchain_task:196: requesting ancestor bmm commitments main_hash=000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1
thunder-1 |2025-01-26T16:30:02.172000+0100 TRACE thunder::node::mainchain_task:198: requesting ancestor bmm commitment: 000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1 main_hash=000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1
thunder-1 |2025-01-26T16:30:02.173000+0100 TRACE thunder::node::mainchain_task:208: storing ancestor bmm commitment: 000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1 main_hash=000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1
thunder-1 |2025-01-26T16:30:02.174000+0100 TRACE thunder::node::mainchain_task:220: stored ancestor bmm commitment: 000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1 main_hash=000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1
thunder-1 |2025-01-26T16:30:02.174000+0100 TRACE thunder::node:517: Storing header: f9ecb1500ebe466b74ee3606d29fb9c042e59f75f2a487a7342744a54d69ce21
thunder-1 |2025-01-26T16:30:02.174000+0100 TRACE thunder::archive:747: Verified BMM @ 000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1: verified parent block_hash=f9ecb1500ebe466b74ee3606d29fb9c042e59f75f2a487a7342744a54d69ce21
thunder-1 |2025-01-26T16:30:02.175000+0100 TRACE thunder::node:523: Stored header: f9ecb1500ebe466b74ee3606d29fb9c042e59f75f2a487a7342744a54d69ce21
thunder-1 |2025-01-26T16:30:02.176000+0100 DEBUG thunder::node:571: submitting new tip via net task new_tip=Tip { block_hash: f9ecb1500ebe466b74ee3606d29fb9c042e59f75f2a487a7342744a54d69ce21, main_block_hash: 000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1 }
thunder-1 |2025-01-26T16:30:02.176000+0100 DEBUG thunder::node::net_task:987: sending new tip ready confirm new_tip=Tip { block_hash: f9ecb1500ebe466b74ee3606d29fb9c042e59f75f2a487a7342744a54d69ce21, main_block_hash: 000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1 }
thunder-1 |2025-01-26T16:30:02.176000+0100 TRACE thunder::node::net_task:705: received new mailbox item mailbox_item=NewTipReady(Tip { block_hash: f9ecb1500ebe466b74ee3606d29fb9c042e59f75f2a487a7342744a54d69ce21, main_block_hash: 000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1 }, None, Some(Sender { complete: false }))
thunder-1 |2025-01-26T15:30:02.176671Z  INFO thunder::node:578: new tip not accepted new_tip=Tip { block_hash: f9ecb1500ebe466b74ee3606d29fb9c042e59f75f2a487a7342744a54d69ce21, main_block_hash: 000000d593c3a103d4f1d76bb6560baeb5b3aa061edf0826d194a96c036ec5f1 }