---- twins::basic_twins_test::basic_start_test stdout ----
2026-04-17T10:49:54.208073Z [twins::basic_twins_test::basic_start_test] INFO consensus/src/persistent_liveness_storage.rs:290 The last committed block id as recorded in storage: LedgerInfo: [commit_info: BlockInfo: [epoch: 0, round: 0, id: 00000000, executed_state_id: 41434355, version: 0, timestamp (us): 0, next_epoch_state: EpochState [epoch: 1, validator: ValidatorSet: [95716e5fba657e6087644993a94f4758024e36ce2e474c560489cac069436e2: 1, 507dd91c02d0f976035c0051c5204aa9f46bd6bff9ddbc53e0304dbd4ed101e6: 1, 59c17a820b6643d116625d127b78d6ad908b93cc3aa97a66522f803a151462d9: 1, 9c21a89d84f0d0fcb8e02f5cfa53604f4576c4c5f38f2fbbd9b7b1a612cfeac9: 1, ]]]] [Consensus data hash: 00000000]
2026-04-17T10:49:54.208246Z [twins::basic_twins_test::basic_start_test] INFO consensus/src/persistent_liveness_storage.rs:193 Commit block is [id: 43bf836b, author: (NIL), epoch: 1, round: 00, parent_id: 00000000, timestamp: 0], window block is [id: 43bf836b, author: (NIL), epoch: 1, round: 00, parent_id: 00000000, timestamp: 0]
2026-04-17T10:49:54.210486Z [twin-0-18] INFO mempool/src/shared_mempool/coordinator.rs:72 {"event":"start","name":"coordinator_runtime"}
2026-04-17T10:49:54.210484Z [twin-0-30] DEBUG mempool/src/shared_mempool/coordinator.rs:447 {"event":"start","name":"g_c_runtime"}
2026-04-17T10:49:54.210553Z [twin-0-18] INFO mempool/src/shared_mempool/coordinator.rs:278 {"event":"received","name":"reconfig_update"}
2026-04-17T10:49:54.210653Z [twin-0-30] INFO mempool/src/shared_mempool/tasks.rs:687 {"event":"process","name":"reconfig_update"}
2026-04-17T10:49:54.210660Z [twins::basic_twins_test::basic_start_test] INFO consensus/safety-rules/src/persistent_safety_storage.rs:182 {"event":"update","name":"waypoint","waypoint":"0:801edd69f7e92f7cf4ddc230521f1b4629aaaeeea9c14b12e58844390c6ee77a"}
2026-04-17T10:49:54.210721Z [twins::basic_twins_test::basic_start_test] INFO consensus/safety-rules/src/persistent_safety_storage.rs:182 {"event":"update","name":"waypoint","waypoint":"0:801edd69f7e92f7cf4ddc230521f1b4629aaaeeea9c14b12e58844390c6ee77a"}
2026-04-17T10:49:54.210996Z [twins::basic_twins_test::basic_start_test] INFO consensus/src/persistent_liveness_storage.rs:290 The last committed block id as recorded in storage: LedgerInfo: [commit_info: BlockInfo: [epoch: 0, round: 0, id: 00000000, executed_state_id: 41434355, version: 0, timestamp (us): 0, next_epoch_state: EpochState [epoch: 1, validator: ValidatorSet: [95716e5fba657e6087644993a94f4758024e36ce2e474c560489cac069436e2: 1, 507dd91c02d0f976035c0051c5204aa9f46bd6bff9ddbc53e0304dbd4ed101e6: 1, 59c17a820b6643d116625d127b78d6ad908b93cc3aa97a66522f803a151462d9: 1, 9c21a89d84f0d0fcb8e02f5cfa53604f4576c4c5f38f2fbbd9b7b1a612cfeac9: 1, ]]]] [Consensus data hash: 00000000]
2026-04-17T10:49:54.211156Z [twins::basic_twins_test::basic_start_test] INFO consensus/src/persistent_liveness_storage.rs:193 Commit block is [id: 43bf836b, author: (NIL), epoch: 1, round: 00, parent_id: 00000000, timestamp: 0], window block is [id: 43bf836b, author: (NIL), epoch: 1, round: 00, parent_id: 00000000, timestamp: 0]
2026-04-17T10:49:54.211188Z [twin-0-14] WARN consensus/src/epoch_manager.rs:1171 Failed to read on-chain execution config [on-chain cfg] config not in payload
2026-04-17T10:49:54.211197Z [twin-0-14] WARN consensus/src/epoch_manager.rs:1175 Failed to read on-chain randomness config [on-chain cfg] config not in payload
2026-04-17T10:49:54.211210Z [twin-0-14] INFO consensus/src/epoch_manager.rs:1186 Checking randomness config override. {"epoch":1,"local":0,"onchain":0}
2026-04-17T10:49:54.211398Z [twin-0-14] INFO consensus/src/epoch_manager.rs:1242 [Randomness] start_new_epoch: epoch=1, rand_config=None, fast_rand_config=None
2026-04-17T10:49:54.211518Z [twin-0-14] INFO consensus/src/epoch_manager.rs:742 Building DirectMempool
2026-04-17T10:49:54.211528Z [twin-0-14] DEBUG consensus/src/epoch_manager.rs:1320 effective_vtxn_config=V0
2026-04-17T10:49:54.211616Z [twin-0-14] INFO consensus/src/persistent_liveness_storage.rs:290 The last committed block id as recorded in storage: LedgerInfo: [commit_info: BlockInfo: [epoch: 0, round: 0, id: 00000000, executed_state_id: 41434355, version: 0, timestamp (us): 0, next_epoch_state: EpochState [epoch: 1, validator: ValidatorSet: [95716e5fba657e6087644993a94f4758024e36ce2e474c560489cac069436e2: 1, 507dd91c02d0f976035c0051c5204aa9f46bd6bff9ddbc53e0304dbd4ed101e6: 1, 59c17a820b6643d116625d127b78d6ad908b93cc3aa97a66522f803a151462d9: 1, 9c21a89d84f0d0fcb8e02f5cfa53604f4576c4c5f38f2fbbd9b7b1a612cfeac9: 1, ]]]] [Consensus data hash: 00000000]
2026-04-17T10:49:54.211673Z [twin-0-18] DEBUG mempool/src/shared_mempool/coordinator.rs:452 {"event":"live","name":"g_c_runtime"}
2026-04-17T10:49:54.211816Z [twin-0-14] INFO consensus/src/persistent_liveness_storage.rs:268 Consensus root block is [id: 43bf836b, author: (NIL), epoch: 1, round: 00, parent_id: 00000000, timestamp: 0]
2026-04-17T10:49:54.211862Z [twin-0-14] INFO consensus/src/epoch_manager.rs:802 Starting new epoch {"epoch":1,"root_block":"[id: 43bf836b, author: (NIL), epoch: 1, round: 00, parent_id: 00000000, timestamp: 0]","validators":"ValidatorSet: [95716e5fba657e6087644993a94f4758024e36ce2e474c560489cac069436e2: 1, 507dd91c02d0f976035c0051c5204aa9f46bd6bff9ddbc53e0304dbd4ed101e6: 1, 59c17a820b6643d116625d127b78d6ad908b93cc3aa97a66522f803a151462d9: 1, 9c21a89d84f0d0fcb8e02f5cfa53604f4576c4c5f38f2fbbd9b7b1a612cfeac9: 1, ]"}
2026-04-17T10:49:54.211877Z [twin-0-14] INFO consensus/src/epoch_manager.rs:809 Update SafetyRules {"epoch":1}
2026-04-17T10:49:54.212494Z [twin-0-14] INFO consensus/src/epoch_manager.rs:831 Create RoundState {"epoch":1}
2026-04-17T10:49:54.212507Z [twin-0-14] INFO consensus/src/epoch_manager.rs:835 Create ProposerElection {"epoch":1}
2026-04-17T10:49:54.212549Z [twin-0-14] INFO consensus/src/epoch_manager.rs:879 Create BlockStore {"epoch":1}
2026-04-17T10:49:54.213327Z [twin-0-14] INFO consensus/src/epoch_manager.rs:906 Create ProposalGenerator {"epoch":1}
2026-04-17T10:49:54.213398Z [twin-0-14] DEBUG consensus/src/round_manager.rs:303 vtxn_config=V0
2026-04-17T10:49:54.213442Z [twin-0-14] DEBUG consensus/src/liveness/round_state.rs:375 "928.53µs" passed since the previous deadline. {"round":1}
2026-04-17T10:49:54.213449Z [twin-0-14] DEBUG consensus/src/liveness/round_state.rs:381 Set round deadline to 2000s from now {"round":1}
2026-04-17T10:49:54.213474Z [twin-0-14] INFO consensus/src/liveness/round_state.rs:286 Starting new round: NewRoundEvent: [round: 1, reason: QCReady, timeout: 2000s] {"round":1}
2026-04-17T10:49:54.213488Z [twin-0-14] INFO consensus/src/round_manager.rs:396 {"epoch":1,"event":"NewRound","reason":"QCReady","round":1}
2026-04-17T10:49:54.213572Z [twin-0-18] INFO consensus/src/round_manager.rs:532 Preparing new proposal {"conflicting_num_votes":0,"conflicting_voting_power":"0","epoch":1,"max_num_votes":0,"max_voting_power":"0","round":1,"timeout_num_votes":0,"timeout_voting_power":"0","total_voting_power":"4"}
2026-04-17T10:49:54.213622Z [twin-0-19] INFO consensus/src/epoch_manager.rs:572 Block retrieval task starts {"epoch":1}
2026-04-17T10:49:54.213641Z [twin-0-0] INFO consensus/src/round_manager.rs:1733 RoundManager started {"epoch":1}
2026-04-17T10:49:54.213661Z [twin-0-18] INFO consensus/src/block_storage/block_store.rs:735 Pipeline pending latency on proposal creation {"commit_round":0,"latency_to_commit_cert_ms":0,"latency_to_committed_ms":0,"latency_to_oldest_not_committed":0,"latency_to_ordered_ms":0,"oldest_not_committed_round":0,"oldest_not_committed_spent_in_pipeline":0,"ordered_round":0,"pending_rounds":0}
2026-04-17T10:49:54.213683Z [twin-0-18] INFO consensus/src/block_storage/block_store.rs:784 Couldn't find execution time for 43bf836b
2026-04-17T10:49:54.213736Z [twin-0-18] WARN consensus/src/liveness/proposal_generator.rs:762 Proposal generation backpressure details {"block_gas_limit_override":0,"is_chain_health_backoff":false,"is_execution_backpressure":false,"is_pipeline_backpressure":false,"max_block_size":{"count":5000,"bytes":3145728},"max_block_txns_after_filtering":1800,"max_txns_from_block_to_execute":1800,"pipeline_pending_latency":0,"proposal_delay_ms":0,"round":1}
2026-04-17T10:49:54.213772Z [twin-0-18] DEBUG consensus/src/payload_client/mixed.rs:92 num_validator_txns=0
2026-04-17T10:49:54.215218Z [twin-1-30] DEBUG mempool/src/shared_mempool/coordinator.rs:447 {"event":"start","name":"g_c_runtime"}
2026-04-17T10:49:54.215221Z [twin-1-31] INFO mempool/src/shared_mempool/coordinator.rs:72 {"event":"start","name":"coordinator_runtime"}
2026-04-17T10:49:54.215274Z [twin-1-31] INFO mempool/src/shared_mempool/coordinator.rs:278 {"event":"received","name":"reconfig_update"}
2026-04-17T10:49:54.215306Z [twin-1-30] INFO mempool/src/shared_mempool/tasks.rs:687 {"event":"process","name":"reconfig_update"}
2026-04-17T10:49:54.215373Z [twins::basic_twins_test::basic_start_test] INFO consensus/safety-rules/src/persistent_safety_storage.rs:182 {"event":"update","name":"waypoint","waypoint":"0:801edd69f7e92f7cf4ddc230521f1b4629aaaeeea9c14b12e58844390c6ee77a"}
2026-04-17T10:49:54.215427Z [twins::basic_twins_test::basic_start_test] INFO consensus/safety-rules/src/persistent_safety_storage.rs:182 {"event":"update","name":"waypoint","waypoint":"0:801edd69f7e92f7cf4ddc230521f1b4629aaaeeea9c14b12e58844390c6ee77a"}
2026-04-17T10:49:54.215767Z [twins::basic_twins_test::basic_start_test] INFO consensus/src/persistent_liveness_storage.rs:290 The last committed block id as recorded in storage: LedgerInfo: [commit_info: BlockInfo: [epoch: 0, round: 0, id: 00000000, executed_state_id: 41434355, version: 0, timestamp (us): 0, next_epoch_state: EpochState [epoch: 1, validator: ValidatorSet: [95716e5fba657e6087644993a94f4758024e36ce2e474c560489cac069436e2: 1, 507dd91c02d0f976035c0051c5204aa9f46bd6bff9ddbc53e0304dbd4ed101e6: 1, 59c17a820b6643d116625d127b78d6ad908b93cc3aa97a66522f803a151462d9: 1, 9c21a89d84f0d0fcb8e02f5cfa53604f4576c4c5f38f2fbbd9b7b1a612cfeac9: 1, ]]]] [Consensus data hash: 00000000]
2026-04-17T10:49:54.215875Z [twin-1-31] WARN consensus/src/epoch_manager.rs:1171 Failed to read on-chain execution config [on-chain cfg] config not in payload
2026-04-17T10:49:54.215879Z [twin-1-31] WARN consensus/src/epoch_manager.rs:1175 Failed to read on-chain randomness config [on-chain cfg] config not in payload
2026-04-17T10:49:54.215888Z [twin-1-31] INFO consensus/src/epoch_manager.rs:1186 Checking randomness config override. {"epoch":1,"local":0,"onchain":0}
2026-04-17T10:49:54.216000Z [twins::basic_twins_test::basic_start_test] INFO consensus/src/persistent_liveness_storage.rs:193 Commit block is [id: 43bf836b, author: (NIL), epoch: 1, round: 00, parent_id: 00000000, timestamp: 0], window block is [id: 43bf836b, author: (NIL), epoch: 1, round: 00, parent_id: 00000000, timestamp: 0]
2026-04-17T10:49:54.216032Z [twin-1-31] INFO consensus/src/epoch_manager.rs:1242 [Randomness] start_new_epoch: epoch=1, rand_config=None, fast_rand_config=None
2026-04-17T10:49:54.216074Z [twin-1-31] INFO consensus/src/epoch_manager.rs:742 Building DirectMempool
2026-04-17T10:49:54.216084Z [twin-1-31] DEBUG consensus/src/epoch_manager.rs:1320 effective_vtxn_config=V0
2026-04-17T10:49:54.216146Z [twin-1-31] INFO consensus/src/persistent_liveness_storage.rs:290 The last committed block id as recorded in storage: LedgerInfo: [commit_info: BlockInfo: [epoch: 0, round: 0, id: 00000000, executed_state_id: 41434355, version: 0, timestamp (us): 0, next_epoch_state: EpochState [epoch: 1, validator: ValidatorSet: [95716e5fba657e6087644993a94f4758024e36ce2e474c560489cac069436e2: 1, 507dd91c02d0f976035c0051c5204aa9f46bd6bff9ddbc53e0304dbd4ed101e6: 1, 59c17a820b6643d116625d127b78d6ad908b93cc3aa97a66522f803a151462d9: 1, 9c21a89d84f0d0fcb8e02f5cfa53604f4576c4c5f38f2fbbd9b7b1a612cfeac9: 1, ]]]] [Consensus data hash: 00000000]
2026-04-17T10:49:54.216292Z [twin-1-31] INFO consensus/src/persistent_liveness_storage.rs:268 Consensus root block is [id: 43bf836b, author: (NIL), epoch: 1, round: 00, parent_id: 00000000, timestamp: 0]
2026-04-17T10:49:54.216322Z [twin-1-31] INFO consensus/src/epoch_manager.rs:802 Starting new epoch {"epoch":1,"root_block":"[id: 43bf836b, author: (NIL), epoch: 1, round: 00, parent_id: 00000000, timestamp: 0]","validators":"ValidatorSet: [95716e5fba657e6087644993a94f4758024e36ce2e474c560489cac069436e2: 1, 507dd91c02d0f976035c0051c5204aa9f46bd6bff9ddbc53e0304dbd4ed101e6: 1, 59c17a820b6643d116625d127b78d6ad908b93cc3aa97a66522f803a151462d9: 1, 9c21a89d84f0d0fcb8e02f5cfa53604f4576c4c5f38f2fbbd9b7b1a612cfeac9: 1, ]"}
2026-04-17T10:49:54.216338Z [twin-1-31] INFO consensus/src/epoch_manager.rs:809 Update SafetyRules {"epoch":1}
2026-04-17T10:49:54.216742Z [twin-1-31] INFO consensus/src/epoch_manager.rs:831 Create RoundState {"epoch":1}
2026-04-17T10:49:54.216754Z [twin-1-31] INFO consensus/src/epoch_manager.rs:835 Create ProposerElection {"epoch":1}
2026-04-17T10:49:54.216793Z [twin-1-31] INFO consensus/src/epoch_manager.rs:879 Create BlockStore {"epoch":1}
2026-04-17T10:49:54.217164Z [twin-1-31] INFO consensus/src/epoch_manager.rs:906 Create ProposalGenerator {"epoch":1}
2026-04-17T10:49:54.217192Z [twin-1-31] DEBUG consensus/src/round_manager.rs:303 vtxn_config=V0
2026-04-17T10:49:54.217234Z [twin-1-31] DEBUG consensus/src/liveness/round_state.rs:375 "469.37µs" passed since the previous deadline. {"round":1}
2026-04-17T10:49:54.217240Z [twin-1-31] DEBUG consensus/src/liveness/round_state.rs:381 Set round deadline to 2000s from now {"round":1}
2026-04-17T10:49:54.217262Z [twin-1-31] INFO consensus/src/liveness/round_state.rs:286 Starting new round: NewRoundEvent: [round: 1, reason: QCReady, timeout: 2000s] {"round":1}
2026-04-17T10:49:54.217274Z [twin-1-31] INFO consensus/src/round_manager.rs:396 {"epoch":1,"event":"NewRound","reason":"QCReady","round":1}
2026-04-17T10:49:54.217338Z [twin-1-29] INFO consensus/src/epoch_manager.rs:572 Block retrieval task starts {"epoch":1}
2026-04-17T10:49:54.217400Z [twin-1-0] INFO consensus/src/round_manager.rs:1733 RoundManager started {"epoch":1}
2026-04-17T10:49:54.218346Z [twin-2-30] DEBUG mempool/src/shared_mempool/coordinator.rs:447 {"event":"start","name":"g_c_runtime"}
2026-04-17T10:49:54.218346Z [twin-2-26] INFO mempool/src/shared_mempool/coordinator.rs:72 {"event":"start","name":"coordinator_runtime"}
2026-04-17T10:49:54.218400Z [twin-2-26] INFO mempool/src/shared_mempool/coordinator.rs:278 {"event":"received","name":"reconfig_update"}
2026-04-17T10:49:54.218428Z [twins::basic_twins_test::basic_start_test] INFO consensus/safety-rules/src/persistent_safety_storage.rs:182 {"event":"update","name":"waypoint","waypoint":"0:801edd69f7e92f7cf4ddc230521f1b4629aaaeeea9c14b12e58844390c6ee77a"}
2026-04-17T10:49:54.218441Z [twin-2-30] INFO mempool/src/shared_mempool/tasks.rs:687 {"event":"process","name":"reconfig_update"}
2026-04-17T10:49:54.218472Z [twins::basic_twins_test::basic_start_test] INFO consensus/safety-rules/src/persistent_safety_storage.rs:182 {"event":"update","name":"waypoint","waypoint":"0:801edd69f7e92f7cf4ddc230521f1b4629aaaeeea9c14b12e58844390c6ee77a"}
2026-04-17T10:49:54.218703Z [twins::basic_twins_test::basic_start_test] INFO consensus/src/persistent_liveness_storage.rs:290 The last committed block id as recorded in storage: LedgerInfo: [commit_info: BlockInfo: [epoch: 0, round: 0, id: 00000000, executed_state_id: 41434355, version: 0, timestamp (us): 0, next_epoch_state: EpochState [epoch: 1, validator: ValidatorSet: [95716e5fba657e6087644993a94f4758024e36ce2e474c560489cac069436e2: 1, 507dd91c02d0f976035c0051c5204aa9f46bd6bff9ddbc53e0304dbd4ed101e6: 1, 59c17a820b6643d116625d127b78d6ad908b93cc3aa97a66522f803a151462d9: 1, 9c21a89d84f0d0fcb8e02f5cfa53604f4576c4c5f38f2fbbd9b7b1a612cfeac9: 1, ]]]] [Consensus data hash: 00000000]
2026-04-17T10:49:54.218858Z [twins::basic_twins_test::basic_start_test] INFO consensus/src/persistent_liveness_storage.rs:193 Commit block is [id: 43bf836b, author: (NIL), epoch: 1, round: 00, parent_id: 00000000, timestamp: 0], window block is [id: 43bf836b, author: (NIL), epoch: 1, round: 00, parent_id: 00000000, timestamp: 0]
2026-04-17T10:49:54.218952Z [twin-2-28] WARN consensus/src/epoch_manager.rs:1171 Failed to read on-chain execution config [on-chain cfg] config not in payload
2026-04-17T10:49:54.218957Z [twin-2-28] WARN consensus/src/epoch_manager.rs:1175 Failed to read on-chain randomness config [on-chain cfg] config not in payload
2026-04-17T10:49:54.218970Z [twin-2-28] INFO consensus/src/epoch_manager.rs:1186 Checking randomness config override. {"epoch":1,"local":0,"onchain":0}
2026-04-17T10:49:54.219180Z [twin-2-28] INFO consensus/src/epoch_manager.rs:1242 [Randomness] start_new_epoch: epoch=1, rand_config=None, fast_rand_config=None
2026-04-17T10:49:54.219221Z [twin-2-28] INFO consensus/src/epoch_manager.rs:742 Building DirectMempool
2026-04-17T10:49:54.219234Z [twin-2-28] DEBUG consensus/src/epoch_manager.rs:1320 effective_vtxn_config=V0
2026-04-17T10:49:54.219365Z [twin-2-28] INFO consensus/src/persistent_liveness_storage.rs:290 The last committed block id as recorded in storage: LedgerInfo: [commit_info: BlockInfo: [epoch: 0, round: 0, id: 00000000, executed_state_id: 41434355, version: 0, timestamp (us): 0, next_epoch_state: EpochState [epoch: 1, validator: ValidatorSet: [95716e5fba657e6087644993a94f4758024e36ce2e474c560489cac069436e2: 1, 507dd91c02d0f976035c0051c5204aa9f46bd6bff9ddbc53e0304dbd4ed101e6: 1, 59c17a820b6643d116625d127b78d6ad908b93cc3aa97a66522f803a151462d9: 1, 9c21a89d84f0d0fcb8e02f5cfa53604f4576c4c5f38f2fbbd9b7b1a612cfeac9: 1, ]]]] [Consensus data hash: 00000000]
2026-04-17T10:49:54.219586Z [twin-2-28] INFO consensus/src/persistent_liveness_storage.rs:268 Consensus root block is [id: 43bf836b, author: (NIL), epoch: 1, round: 00, parent_id: 00000000, timestamp: 0]
2026-04-17T10:49:54.219622Z [twin-2-28] INFO consensus/src/epoch_manager.rs:802 Starting new epoch {"epoch":1,"root_block":"[id: 43bf836b, author: (NIL), epoch: 1, round: 00, parent_id: 00000000, timestamp: 0]","validators":"ValidatorSet: [95716e5fba657e6087644993a94f4758024e36ce2e474c560489cac069436e2: 1, 507dd91c02d0f976035c0051c5204aa9f46bd6bff9ddbc53e0304dbd4ed101e6: 1, 59c17a820b6643d116625d127b78d6ad908b93cc3aa97a66522f803a151462d9: 1, 9c21a89d84f0d0fcb8e02f5cfa53604f4576c4c5f38f2fbbd9b7b1a612cfeac9: 1, ]"}
2026-04-17T10:49:54.219636Z [twin-2-28] INFO consensus/src/epoch_manager.rs:809 Update SafetyRules {"epoch":1}
2026-04-17T10:49:54.220187Z [twin-2-28] INFO consensus/src/epoch_manager.rs:831 Create RoundState {"epoch":1}
2026-04-17T10:49:54.220198Z [twin-2-28] INFO consensus/src/epoch_manager.rs:835 Create ProposerElection {"epoch":1}
2026-04-17T10:49:54.220249Z [twin-2-28] INFO consensus/src/epoch_manager.rs:879 Create BlockStore {"epoch":1}
2026-04-17T10:49:54.220722Z [twin-2-28] INFO consensus/src/epoch_manager.rs:906 Create ProposalGenerator {"epoch":1}
2026-04-17T10:49:54.220756Z [twin-2-28] DEBUG consensus/src/round_manager.rs:303 vtxn_config=V0
2026-04-17T10:49:54.220790Z [twin-2-28] DEBUG consensus/src/liveness/round_state.rs:375 "586.209µs" passed since the previous deadline. {"round":1}
2026-04-17T10:49:54.220795Z [twin-2-28] DEBUG consensus/src/liveness/round_state.rs:381 Set round deadline to 2000s from now {"round":1}
2026-04-17T10:49:54.220821Z [twin-2-28] INFO consensus/src/liveness/round_state.rs:286 Starting new round: NewRoundEvent: [round: 1, reason: QCReady, timeout: 2000s] {"round":1}
2026-04-17T10:49:54.220833Z [twin-2-28] INFO consensus/src/round_manager.rs:396 {"epoch":1,"event":"NewRound","reason":"QCReady","round":1}
2026-04-17T10:49:54.220914Z [twin-2-1] INFO consensus/src/epoch_manager.rs:572 Block retrieval task starts {"epoch":1}
2026-04-17T10:49:54.220947Z [twin-2-26] INFO consensus/src/round_manager.rs:1733 RoundManager started {"epoch":1}
2026-04-17T10:49:54.221189Z [twin-3-22] INFO mempool/src/shared_mempool/coordinator.rs:72 {"event":"start","name":"coordinator_runtime"}
2026-04-17T10:49:54.221240Z [twin-3-22] INFO mempool/src/shared_mempool/coordinator.rs:278 {"event":"received","name":"reconfig_update"}
2026-04-17T10:49:54.221265Z [twin-3-26] DEBUG mempool/src/shared_mempool/coordinator.rs:447 {"event":"start","name":"g_c_runtime"}
2026-04-17T10:49:54.221303Z [twin-3-26] INFO mempool/src/shared_mempool/tasks.rs:687 {"event":"process","name":"reconfig_update"}
2026-04-17T10:49:54.221313Z [twins::basic_twins_test::basic_start_test] INFO consensus/safety-rules/src/persistent_safety_storage.rs:182 {"event":"update","name":"waypoint","waypoint":"0:801edd69f7e92f7cf4ddc230521f1b4629aaaeeea9c14b12e58844390c6ee77a"}
2026-04-17T10:49:54.221364Z [twins::basic_twins_test::basic_start_test] INFO consensus/safety-rules/src/persistent_safety_storage.rs:182 {"event":"update","name":"waypoint","waypoint":"0:801edd69f7e92f7cf4ddc230521f1b4629aaaeeea9c14b12e58844390c6ee77a"}
2026-04-17T10:49:54.221779Z [twin-3-25] WARN consensus/src/epoch_manager.rs:1171 Failed to read on-chain execution config [on-chain cfg] config not in payload
2026-04-17T10:49:54.221784Z [twin-3-25] WARN consensus/src/epoch_manager.rs:1175 Failed to read on-chain randomness config [on-chain cfg] config not in payload
2026-04-17T10:49:54.221795Z [twin-3-25] INFO consensus/src/epoch_manager.rs:1186 Checking randomness config override. {"epoch":1,"local":0,"onchain":0}
2026-04-17T10:49:54.221962Z [twin-3-25] INFO consensus/src/epoch_manager.rs:1242 [Randomness] start_new_epoch: epoch=1, rand_config=None, fast_rand_config=None
2026-04-17T10:49:54.222008Z [twin-3-25] INFO consensus/src/epoch_manager.rs:742 Building DirectMempool
2026-04-17T10:49:54.222021Z [twin-3-25] DEBUG consensus/src/epoch_manager.rs:1320 effective_vtxn_config=V0
2026-04-17T10:49:54.222084Z [twin-3-25] INFO consensus/src/persistent_liveness_storage.rs:290 The last committed block id as recorded in storage: LedgerInfo: [commit_info: BlockInfo: [epoch: 0, round: 0, id: 00000000, executed_state_id: 41434355, version: 0, timestamp (us): 0, next_epoch_state: EpochState [epoch: 1, validator: ValidatorSet: [95716e5fba657e6087644993a94f4758024e36ce2e474c560489cac069436e2: 1, 507dd91c02d0f976035c0051c5204aa9f46bd6bff9ddbc53e0304dbd4ed101e6: 1, 59c17a820b6643d116625d127b78d6ad908b93cc3aa97a66522f803a151462d9: 1, 9c21a89d84f0d0fcb8e02f5cfa53604f4576c4c5f38f2fbbd9b7b1a612cfeac9: 1, ]]]] [Consensus data hash: 00000000]
2026-04-17T10:49:54.222300Z [twin-3-25] INFO consensus/src/persistent_liveness_storage.rs:268 Consensus root block is [id: 43bf836b, author: (NIL), epoch: 1, round: 00, parent_id: 00000000, timestamp: 0]
2026-04-17T10:49:54.222337Z [twin-3-25] INFO consensus/src/epoch_manager.rs:802 Starting new epoch {"epoch":1,"root_block":"[id: 43bf836b, author: (NIL), epoch: 1, round: 00, parent_id: 00000000, timestamp: 0]","validators":"ValidatorSet: [95716e5fba657e6087644993a94f4758024e36ce2e474c560489cac069436e2: 1, 507dd91c02d0f976035c0051c5204aa9f46bd6bff9ddbc53e0304dbd4ed101e6: 1, 59c17a820b6643d116625d127b78d6ad908b93cc3aa97a66522f803a151462d9: 1, 9c21a89d84f0d0fcb8e02f5cfa53604f4576c4c5f38f2fbbd9b7b1a612cfeac9: 1, ]"}
2026-04-17T10:49:54.222352Z [twin-3-25] INFO consensus/src/epoch_manager.rs:809 Update SafetyRules {"epoch":1}
2026-04-17T10:49:54.222838Z [twin-3-25] INFO consensus/src/epoch_manager.rs:831 Create RoundState {"epoch":1}
2026-04-17T10:49:54.222848Z [twin-3-25] INFO consensus/src/epoch_manager.rs:835 Create ProposerElection {"epoch":1}
2026-04-17T10:49:54.222888Z [twin-3-25] INFO consensus/src/epoch_manager.rs:879 Create BlockStore {"epoch":1}
2026-04-17T10:49:54.223303Z [twin-3-25] INFO consensus/src/epoch_manager.rs:906 Create ProposalGenerator {"epoch":1}
2026-04-17T10:49:54.223335Z [twin-3-25] DEBUG consensus/src/round_manager.rs:303 vtxn_config=V0
2026-04-17T10:49:54.223371Z [twin-3-25] DEBUG consensus/src/liveness/round_state.rs:375 "519.594µs" passed since the previous deadline. {"round":1}
2026-04-17T10:49:54.223377Z [twin-3-25] DEBUG consensus/src/liveness/round_state.rs:381 Set round deadline to 2000s from now {"round":1}
2026-04-17T10:49:54.223403Z [twin-3-25] INFO consensus/src/liveness/round_state.rs:286 Starting new round: NewRoundEvent: [round: 1, reason: QCReady, timeout: 2000s] {"round":1}
2026-04-17T10:49:54.223424Z [twin-3-25] INFO consensus/src/round_manager.rs:396 {"epoch":1,"event":"NewRound","reason":"QCReady","round":1}
2026-04-17T10:49:54.223680Z [twin-3-29] INFO consensus/src/epoch_manager.rs:572 Block retrieval task starts {"epoch":1}
2026-04-17T10:49:54.223702Z [twin-3-1] INFO consensus/src/round_manager.rs:1733 RoundManager started {"epoch":1}
2026-04-17T10:49:54.615504Z [twin-0-14] WARN consensus/src/round_manager.rs:427 Error generating and sending proposal: Fail to retrieve payload
2026-04-17T10:50:02.484990Z [twin-0-30] ERROR mempool/src/shared_mempool/tasks.rs:704 Failed to read on-chain consensus config, keeping value broadcast_within_validator_network=true: [on-chain cfg] config not in payload
2026-04-17T10:50:02.486913Z [twin-2-30] ERROR mempool/src/shared_mempool/tasks.rs:704 Failed to read on-chain consensus config, keeping value broadcast_within_validator_network=true: [on-chain cfg] config not in payload
2026-04-17T10:50:02.488708Z [twin-1-30] ERROR mempool/src/shared_mempool/tasks.rs:704 Failed to read on-chain consensus config, keeping value broadcast_within_validator_network=true: [on-chain cfg] config not in payload
2026-04-17T10:50:02.532002Z [twin-3-26] ERROR mempool/src/shared_mempool/tasks.rs:704 Failed to read on-chain consensus config, keeping value broadcast_within_validator_network=true: [on-chain cfg] config not in payload
2026-04-17T10:50:02.901133Z [twin-0-0] ERROR consensus/src/quorum_store/direct_mempool_quorum_store.rs:111 GetBatch failed
2026-04-17T10:50:02.920298Z [twin-0-0] ERROR consensus/src/quorum_store/direct_mempool_quorum_store.rs:126 Callback failed
2026-04-17T10:50:54.212609Z [twin-0-30] DEBUG mempool/src/shared_mempool/coordinator.rs:452 {"event":"live","name":"g_c_runtime"}
This is the whole logs:
Originally posted by @musitdev in #312 (comment)