Skip to content
CI

CI #5409

Sign in to view logs

CI

CI #5409

GitHub Actions / Test Results (CI) failed Sep 4, 2024 in 0s

1 fail, 107 pass in 32m 27s

108 tests   - 445   107 ✅  - 445   32m 27s ⏱️ - 1h 45m 35s
 15 suites  -  43     0 💤 ±  0 
  1 files    -   1     1 ❌ ±  0 

Results for commit 9b6b704. ± Comparison against earlier commit b3f1507.

Annotations

Check warning on line 0 in consensus_tests

See this annotation in the file changed.

@github-actions github-actions / Test Results (CI)

consensus::epoch_change (consensus_tests) failed 

artifacts/test-results/junit.xml [took 2s]
Raw output 
thread 'consensus::epoch_change' panicked at dan_layer/consensus_tests/src/support/harness.rs:456:21:
assertion `left == right` failed: Validator TestAddress(1) is at height [NodeHeight(5), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1] but validator TestAddress(2) is at height [NodeHeight(4), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647]
  left: BlockId(FixedHash([121, 190, 198, 223, 229, 136, 204, 131, 107, 122, 10, 236, 142, 230, 17, 2, 83, 62, 154, 85, 210, 47, 236, 218, 161, 47, 248, 206, 233, 202, 154, 209]))
 right: BlockId(FixedHash([157, 89, 112, 199, 235, 57, 200, 140, 47, 102, 43, 94, 3, 237, 137, 183, 88, 47, 115, 3, 217, 206, 226, 252, 255, 211, 52, 123, 172, 166, 166, 71]))
note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace

running 1 test
2024-09-04T08:45:46.272468390Z [consensus_tests::support::validator::builder] INFO Spawning validator with address TestAddress(1) and public key cec1426a33965eb2a7d82b281964ad39f06d6fba7d8e57f8da4fcfefd946d855
2024-09-04T08:45:46.276759560Z [consensus_tests::support::validator::builder] INFO Spawning validator with address TestAddress(2) and public key 9e504f9b10c40230ec4e1570dcf295d5da01aa0daeced57316b160c80bc57e0c
2024-09-04T08:45:46.282110424Z [tari::dan::consensus::sm::worker] INFO ⚙️ TRANSITION: Idle --- Registered for epoch Epoch(1) ---> CheckSync
2024-09-04T08:45:46.282126924Z [tari::dan::consensus::sm::worker] INFO ⚙️ TRANSITION: CheckSync --- Ready ---> Running
2024-09-04T08:45:46.282255596Z [worker] DEBUG Creating zero block
2024-09-04T08:45:46.282267856Z [tari::dan::consensus::sm::worker] INFO ⚙️ TRANSITION: Idle --- Registered for epoch Epoch(1) ---> CheckSync
2024-09-04T08:45:46.282276806Z [tari::dan::consensus::sm::worker] INFO ⚙️ TRANSITION: CheckSync --- Ready ---> Running
2024-09-04T08:45:46.282370967Z [worker] DEBUG Creating zero block
2024-09-04T08:45:46.283221989Z [worker] INFO ✨Creating genesis block [NodeHeight(0), Epoch(1), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), ac05e09beadc7962d072b60ba04556b62e6596a29550578da575411b49162eae]
2024-09-04T08:45:46.283444752Z [worker] INFO ✨Creating genesis block [NodeHeight(0), Epoch(1), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), ac05e09beadc7962d072b60ba04556b62e6596a29550578da575411b49162eae]
2024-09-04T08:45:46.283630145Z [worker] INFO \U0001f680 Pacemaker starting for epoch Epoch(1), height: NodeHeight(0), high_qc: (block_id: 0000000000000000000000000000000000000000000000000000000000000000, height: NodeHeight(0), qc_id: 9e34ed49e414afcdcb0a3a41a13df4ac1b7fb1fa055b358c30c5d15f6a7cc5dc)
2024-09-04T08:45:46.283648355Z [current_view] INFO \U0001f9ff PACEMAKER: View updated to Epoch(1)/NodeHeight(0)
2024-09-04T08:45:46.283716186Z [on_catch_up_sync] INFO ⏰ Catch up required from block (block_id: 0000000000000000000000000000000000000000000000000000000000000000, height: NodeHeight(0), qc_id: 9e34ed49e414afcdcb0a3a41a13df4ac1b7fb1fa055b358c30c5d15f6a7cc5dc) from TestAddress(1) (current view: Epoch(1)/NodeHeight(0))
2024-09-04T08:45:46.283730436Z [current_view] INFO \U0001f9ff PACEMAKER RESET: View updated to Epoch(1)/NodeHeight(0)
2024-09-04T08:45:46.283739956Z [worker] DEBUG \U0001f525 Current height #1
2024-09-04T08:45:46.283743036Z [worker] INFO \U0001f680 Pacemaker starting for epoch Epoch(1), height: NodeHeight(0), high_qc: (block_id: 0000000000000000000000000000000000000000000000000000000000000000, height: NodeHeight(0), qc_id: 9e34ed49e414afcdcb0a3a41a13df4ac1b7fb1fa055b358c30c5d15f6a7cc5dc)
2024-09-04T08:45:46.283755707Z [current_view] INFO \U0001f9ff PACEMAKER: View updated to Epoch(1)/NodeHeight(0)
2024-09-04T08:45:46.283759097Z [pacemaker] INFO \U0001f680 Starting pacemaker at leaf height Epoch(1)/NodeHeight(0) and high QC: NodeHeight(0)
2024-09-04T08:45:46.283762977Z [pacemaker] INFO Reset! Current height: Epoch(1)/NodeHeight(0), Delta: 20.00s
2024-09-04T08:45:46.283771767Z [pacemaker] INFO Reset! Current height: Epoch(1)/NodeHeight(0), Delta: 20.00s
2024-09-04T08:45:46.283799667Z [on_catch_up_sync] INFO ⏰ Catch up required from block (block_id: 0000000000000000000000000000000000000000000000000000000000000000, height: NodeHeight(0), qc_id: 9e34ed49e414afcdcb0a3a41a13df4ac1b7fb1fa055b358c30c5d15f6a7cc5dc) from TestAddress(2) (current view: Epoch(1)/NodeHeight(0))
2024-09-04T08:45:46.283812317Z [current_view] INFO \U0001f9ff PACEMAKER RESET: View updated to Epoch(1)/NodeHeight(0)
2024-09-04T08:45:46.283818677Z [worker] DEBUG \U0001f525 Current height #1
2024-09-04T08:45:46.283833518Z [pacemaker] INFO \U0001f680 Starting pacemaker at leaf height Epoch(1)/NodeHeight(0) and high QC: NodeHeight(0)
2024-09-04T08:45:46.283836728Z [pacemaker] INFO Reset! Current height: Epoch(1)/NodeHeight(0), Delta: 20.00s
2024-09-04T08:45:46.283841888Z [pacemaker] INFO Reset! Current height: Epoch(1)/NodeHeight(0), Delta: 20.00s
2024-09-04T08:45:46.284111581Z [worker] DEBUG [on_beat] No transactions to propose. Waiting for a timeout.
2024-09-04T08:45:46.284121452Z [worker] DEBUG \U0001f525 Current height #1
2024-09-04T08:45:46.284138322Z [worker] DEBUG [on_beat] No transactions to propose. Waiting for a timeout.
2024-09-04T08:45:46.284144412Z [worker] DEBUG \U0001f525 Current height #1
2024-09-04T08:45:46.569679587Z [consensus_tests::support::harness] INFO Validator TestAddress(1): consensus is running
2024-09-04T08:45:46.569713037Z [consensus_tests::support::harness] INFO Validator TestAddress(2): consensus is running
2024-09-04T08:45:46.597735296Z [consensus_tests::support::network] INFO \U0001f680 Network started
2024-09-04T08:45:46.597773856Z [consensus_tests::support::network] DEBUG ✉️ Message SyncRequest((block_id: 0000000000000000000000000000000000000000000000000000000000000000, height: NodeHeight(0), qc_id: 9e34ed49e414afcdcb0a3a41a13df4ac1b7fb1fa055b358c30c5d15f6a7cc5dc)) from TestAddress(2) to TestAddress(1)
2024-09-04T08:45:46.597821967Z [consensus_tests::support::network] DEBUG ✉️ Message SyncRequest((block_id: 0000000000000000000000000000000000000000000000000000000000000000, height: NodeHeight(0), qc_id: 9e34ed49e414afcdcb0a3a41a13df4ac1b7fb1fa055b358c30c5d15f6a7cc5dc)) from TestAddress(1) to TestAddress(2)
2024-09-04T08:45:46.597891808Z [worker] DEBUG \U0001f525 Current height #1
2024-09-04T08:45:46.598245153Z [on_sync_request] INFO \U0001f310 Received catch up request from TestAddress(1) from block (block_id: 0000000000000000000000000000000000000000000000000000000000000000, height: NodeHeight(0), qc_id: 9e34ed49e414afcdcb0a3a41a13df4ac1b7fb1fa055b358c30c5d15f6a7cc5dc) to [NodeHeight(0), Epoch(1), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), ac05e09beadc7962d072b60ba04556b62e6596a29550578da575411b49162eae]
2024-09-04T08:45:46.598272533Z [tari::dan::storage::state_store_sqlite::reader] DEBUG get_block_ids_between: start: 0000000000000000000000000000000000000000000000000000000000000000, end: ac05e09beadc7962d072b60ba04556b62e6596a29550578da575411b49162eae
2024-09-04T08:45:46.598692169Z [on_sync_request] INFO \U0001f310 Sending 0 block(s) (NodeHeight(0) to NodeHeight(0)) to TestAddress(1)
2024-09-04T08:45:46.598794501Z [consensus_tests::support::network] INFO \U0001f41e New transaction a5efafadc42dbc750227d7960e28e4e2fb20d46832bce9d8745abfe327daf75b for vn TestAddress(2)
2024-09-04T08:45:46.598807341Z [consensus_tests::support::network] INFO \U0001f41e New transaction a5efafadc42dbc750227d7960e28e4e2fb20d46832bce9d8745abfe327daf75b for vn TestAddress(1)
2024-09-04T08:45:46.599227427Z [worker] DEBUG \U0001f525 new transaction ready for consensus: a5efafadc42dbc750227d7960e28e4e2fb20d46832bce9d8745abfe327daf75b (0 pending)
2024-09-04T08:45:46.599340398Z [worker] DEBUG \U0001f525 Current height #1
2024-09-04T08:45:46.599397549Z [worker] INFO \U0001f525 [on_beat] TestAddress(2) Is leader: true, leaf_block: LeafBlock(NodeHeight(0) ac05e09beadc7962d072b60ba04556b62e6596a29550578da575411b49162eae Epoch(1)), local_committee: 2
2024-09-04T08:45:46.600229150Z [on_local_propose] DEBUG \U0001f33f Found 0 burnt utxos for next block
2024-09-04T08:45:46.600339722Z [tari::dan::storage::state_store_sqlite::reader] DEBUG get_block_ids_between: start: ac05e09beadc7962d072b60ba04556b62e6596a29550578da575411b49162eae, end: ac05e09beadc7962d072b60ba04556b62e6596a29550578da575411b49162eae
2024-09-04T08:45:46.600444613Z [tari::dan::storage::state_store_sqlite::reader] DEBUG get_transaction_atom_state_updates_between_blocks: from_block_id=ac05e09beadc7962d072b60ba04556b62e6596a29550578da575411b49162eae, to_block_id=ac05e09beadc7962d072b60ba04556b62e6596a29550578da575411b49162eae, len(applicable_block_ids)=1
2024-09-04T08:45:46.600736087Z [tari::dan::storage::state_store_sqlite::reader] DEBUG transaction_pool_get_many_ready: locked.block_id=ac05e09beadc7962d072b60ba04556b62e6596a29550578da575411b49162eae, leaf.block_id=ac05e09beadc7962d072b60ba04556b62e6596a29550578da575411b49162eae, len(ready_txs)=1, updates=0
2024-09-04T08:45:46.600830969Z [on_local_propose] INFO \U0001f468‍\U0001f527 PROPOSE: PREPARE transaction a5efafadc42dbc750227d7960e28e4e2fb20d46832bce9d8745abfe327daf75b
2024-09-04T08:45:46.629763870Z [worker] DEBUG \U0001f525 Current height #1
2024-09-04T08:45:46.630328278Z [worker] DEBUG \U0001f525 new transaction ready for consensus: a5efafadc42dbc750227d7960e28e4e2fb20d46832bce9d8745abfe327daf75b (0 pending)
2024-09-04T08:45:46.630486360Z [worker] DEBUG \U0001f525 Current height #1
2024-09-04T08:45:46.630558841Z [worker] INFO \U0001f525 [on_beat] TestAddress(1) Is leader: false, leaf_block: LeafBlock(NodeHeight(0) ac05e09beadc7962d072b60ba04556b62e6596a29550578da575411b49162eae Epoch(1)), local_committee: 2
2024-09-04T08:45:46.630573111Z [worker] DEBUG \U0001f525 Current height #1
2024-09-04T08:45:46.630830574Z [on_sync_request] INFO \U0001f310 Received catch up request from TestAddress(2) from block (block_id: 0000000000000000000000000000000000000000000000000000000000000000, height: NodeHeight(0), qc_id: 9e34ed49e414afcdcb0a3a41a13df4ac1b7fb1fa055b358c30c5d15f6a7cc5dc) to [NodeHeight(0), Epoch(1), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), ac05e09beadc7962d072b60ba04556b62e6596a29550578da575411b49162eae]
2024-09-04T08:45:46.630855975Z [tari::dan::storage::state_store_sqlite::reader] DEBUG get_block_ids_between: start: 0000000000000000000000000000000000000000000000000000000000000000, end: ac05e09beadc7962d072b60ba04556b62e6596a29550578da575411b49162eae
2024-09-04T08:45:46.631298281Z [on_sync_request] INFO \U0001f310 Sending 0 block(s) (NodeHeight(0) to NodeHeight(0)) to TestAddress(2)
2024-09-04T08:45:46.665922191Z [block_transaction_executor] INFO Resolved LOCAL substate: component_627815356033cb1da519a762aca73f47fe9adfbaebcc93d8a9938ad7956096c9:0
2024-09-04T08:45:46.666214285Z [block_transaction_executor] INFO Resolved LOCAL substate: component_1d97cc7aba8c981a3c0182545a6e21dfca81fd204279ca3ba752e0a7fc24beea:0
2024-09-04T08:45:46.666381117Z [block_transaction_executor] INFO Resolved LOCAL substate: component_c0164519d90bec552264bcd4a7cf7e551d3b3bb5362ab1fc45f00e509cff3328:0
2024-09-04T08:45:46.666666921Z [block_transaction_executor] INFO Resolved LOCAL substate: component_afbe8467740c2027f117fba4e4e95ebd75ea9e6fb6ebb51f070e5488beafc1d2:0
2024-09-04T08:45:46.666833863Z [block_transaction_executor] INFO Resolved LOCAL substate: component_abf0c6ae1c62657fa8d79fcbfa14d0057b380880a6b3ed2f77d8c26a5d0e5b13:0
2024-09-04T08:45:46.666860914Z [block_transaction_executor] INFO \U0001f468‍\U0001f527 PREPARE: Local-Only Executed transaction a5efafadc42dbc750227d7960e28e4e2fb20d46832bce9d8745abfe327daf75b with Commit decision
2024-09-04T08:45:46.666872184Z [tari::dan::hotstuff::substate_store::pending_store] INFO \U0001f512️ Requested substate lock: component_627815356033cb1da519a762aca73f47fe9adfbaebcc93d8a9938ad7956096c9:0 (Write)
2024-09-04T08:45:46.667124137Z [tari::dan::hotstuff::substate_store::pending_store] INFO \U0001f512️ Requested substate lock: component_1d97cc7aba8c981a3c0182545a6e21dfca81fd204279ca3ba752e0a7fc24beea:0 (Write)
2024-09-04T08:45:46.667296330Z [tari::dan::hotstuff::substate_store::pending_store] INFO \U0001f512️ Requested substate lock: component_c0164519d90bec552264bcd4a7cf7e551d3b3bb5362ab1fc45f00e509cff3328:0 (Write)
2024-09-04T08:45:46.667460682Z [tari::dan::hotstuff::substate_store::pending_store] INFO \U0001f512️ Requested substate lock: component_afbe8467740c2027f117fba4e4e95ebd75ea9e6fb6ebb51f070e5488beafc1d2:0 (Write)
2024-09-04T08:45:46.667617024Z [tari::dan::hotstuff::substate_store::pending_store] INFO \U0001f512️ Requested substate lock: component_abf0c6ae1c62657fa8d79fcbfa14d0057b380880a6b3ed2f77d8c26a5d0e5b13:0 (Write)
2024-09-04T08:45:46.667774906Z [tari::dan::hotstuff::substate_store::pending_store] INFO \U0001f512️ Requested substate lock: component_4099b72d470528cd910bfabe560cc67d69a5a34483e014b235a40ebdce5585d6:0 (Output)
2024-09-04T08:45:46.667830177Z [tari::dan::hotstuff::substate_store::pending_store] INFO \U0001f512️ Requested substate lock: txreceipt_a5efafadc42dbc750227d7960e28e4e2fb20d46832bce9d8745abfe327daf75b:0 (Output)
2024-09-04T08:45:46.667895408Z [on_local_propose] INFO \U0001f3e0️ Transaction a5efafadc42dbc750227d7960e28e4e2fb20d46832bce9d8745abfe327daf75b is local only, proposing LocalOnly
2024-09-04T08:45:46.667929628Z [on_local_propose] DEBUG command(s) for next block: [LocalOnly(a5efafadc42dbc750227d7960e28e4e2fb20d46832bce9d8745abfe327daf75b, Commit)]
2024-09-04T08:45:46.668066000Z [tari::dan::consensus::sharded_state_tree] DEBUG v1 contains 1 tree change(s) for shard Shard(16)
2024-09-04T08:45:46.668088481Z [tari_state_tree::tree] DEBUG Inserting node: v1:
2024-09-04T08:45:46.668099511Z [tari::dan::consensus::sharded_state_tree] DEBUG v1 contains 1 tree change(s) for shard Shard(41)
2024-09-04T08:45:46.668109011Z [tari_state_tree::tree] DEBUG Inserting node: v1:
2024-09-04T08:45:46.668510596Z [on_local_propose] DEBUG Saving 1 executed transaction(s) for block 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af
2024-09-04T08:45:46.668782130Z [on_local_propose] INFO \U0001f33f [TestAddress(2)] PROPOSING new local block [NodeHeight(1), Epoch(1), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af] to 2 validators. justify: 0000000000000000000000000000000000000000000000000000000000000000 (NodeHeight(0)), parent: ac05e09beadc7962d072b60ba04556b62e6596a29550578da575411b49162eae
2024-09-04T08:45:46.668809391Z [on_local_propose] INFO \U0001f33f Broadcasting local proposal [NodeHeight(1), Epoch(1), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af] to 2 local committees
2024-09-04T08:45:46.668832301Z [worker] DEBUG \U0001f525 Current height #1
2024-09-04T08:45:46.668863741Z [consensus_tests::support::network] DEBUG ✉️ Broadcast Proposal(Epoch=Epoch(1),Height=NodeHeight(1)) from TestAddress(2) to TestAddress(1), TestAddress(2)
2024-09-04T08:45:46.668906252Z [on_message_validate] INFO \U0001f4dc new unvalidated PROPOSAL message [NodeHeight(1), Epoch(1), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af] from 9e504f9b10c40230ec4e1570dcf295d5da01aa0daeced57316b160c80bc57e0c (current height = NodeHeight(1))
2024-09-04T08:45:46.669340898Z [on_message_validate] DEBUG ✅ Block [NodeHeight(1), Epoch(1), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af] has no missing transactions
2024-09-04T08:45:46.669365278Z [on_receive_local_proposal] DEBUG \U0001f525 LOCAL PROPOSAL: block [NodeHeight(1), Epoch(1), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af] from 9e504f9b10c40230ec4e1570dcf295d5da01aa0daeced57316b160c80bc57e0c
2024-09-04T08:45:46.673880171Z [on_message_validate] INFO \U0001f4dc new unvalidated PROPOSAL message [NodeHeight(1), Epoch(1), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af] from 9e504f9b10c40230ec4e1570dcf295d5da01aa0daeced57316b160c80bc57e0c (current height = NodeHeight(1))
2024-09-04T08:45:46.674347647Z [on_message_validate] DEBUG ✅ Block [NodeHeight(1), Epoch(1), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af] has no missing transactions
2024-09-04T08:45:46.674375638Z [on_receive_local_proposal] DEBUG \U0001f525 LOCAL PROPOSAL: block [NodeHeight(1), Epoch(1), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af] from 9e504f9b10c40230ec4e1570dcf295d5da01aa0daeced57316b160c80bc57e0c
2024-09-04T08:45:46.674871214Z [on_receive_local_proposal] INFO ✅ Block ValidBlock([NodeHeight(1), Epoch(1), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af]) is valid and persisted. HighQc((block_id: 0000000000000000000000000000000000000000000000000000000000000000, height: NodeHeight(0), qc_id: 9e34ed49e414afcdcb0a3a41a13df4ac1b7fb1fa055b358c30c5d15f6a7cc5dc))
2024-09-04T08:45:46.701781717Z [on_ready_to_vote_on_local_block] DEBUG \U0001f525 LOCAL PROPOSAL READY: ValidBlock([NodeHeight(1), Epoch(1), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af])
2024-09-04T08:45:46.702182083Z [tari::dan::storage::state_store_sqlite::reader] DEBUG get_block_ids_between: start: ac05e09beadc7962d072b60ba04556b62e6596a29550578da575411b49162eae, end: 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af
2024-09-04T08:45:46.702341115Z [tari::dan::storage::state_store_sqlite::reader] DEBUG get_transaction_atom_state_updates_between_blocks: from_block_id=ac05e09beadc7962d072b60ba04556b62e6596a29550578da575411b49162eae, to_block_id=8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af, len(applicable_block_ids)=2
2024-09-04T08:45:46.702632519Z [tari::dan::storage::state_store_sqlite::reader] DEBUG transaction_pool_get: from_block_id=ac05e09beadc7962d072b60ba04556b62e6596a29550578da575411b49162eae, to_block_id=8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af, transaction_id=a5efafadc42dbc750227d7960e28e4e2fb20d46832bce9d8745abfe327daf75b, updates=0 [[]]
2024-09-04T08:45:46.703215277Z [block_transaction_executor] INFO Resolved LOCAL substate: component_627815356033cb1da519a762aca73f47fe9adfbaebcc93d8a9938ad7956096c9:0
2024-09-04T08:45:46.703384000Z [block_transaction_executor] INFO Resolved LOCAL substate: component_1d97cc7aba8c981a3c0182545a6e21dfca81fd204279ca3ba752e0a7fc24beea:0
2024-09-04T08:45:46.703551612Z [block_transaction_executor] INFO Resolved LOCAL substate: component_c0164519d90bec552264bcd4a7cf7e551d3b3bb5362ab1fc45f00e509cff3328:0
2024-09-04T08:45:46.703718894Z [block_transaction_executor] INFO Resolved LOCAL substate: component_afbe8467740c2027f117fba4e4e95ebd75ea9e6fb6ebb51f070e5488beafc1d2:0
2024-09-04T08:45:46.703875906Z [block_transaction_executor] INFO Resolved LOCAL substate: component_abf0c6ae1c62657fa8d79fcbfa14d0057b380880a6b3ed2f77d8c26a5d0e5b13:0
2024-09-04T08:45:46.703903997Z [block_transaction_executor] INFO \U0001f468‍\U0001f527 PREPARE: Local-Only Executed transaction a5efafadc42dbc750227d7960e28e4e2fb20d46832bce9d8745abfe327daf75b with Commit decision
2024-09-04T08:45:46.703915067Z [tari::dan::hotstuff::substate_store::pending_store] INFO \U0001f512️ Requested substate lock: component_627815356033cb1da519a762aca73f47fe9adfbaebcc93d8a9938ad7956096c9:0 (Write)
2024-09-04T08:45:46.704161690Z [tari::dan::hotstuff::substate_store::pending_store] INFO \U0001f512️ Requested substate lock: component_1d97cc7aba8c981a3c0182545a6e21dfca81fd204279ca3ba752e0a7fc24beea:0 (Write)
2024-09-04T08:45:46.704322783Z [tari::dan::hotstuff::substate_store::pending_store] INFO \U0001f512️ Requested substate lock: component_c0164519d90bec552264bcd4a7cf7e551d3b3bb5362ab1fc45f00e509cff3328:0 (Write)
2024-09-04T08:45:46.704483125Z [tari::dan::hotstuff::substate_store::pending_store] INFO \U0001f512️ Requested substate lock: component_afbe8467740c2027f117fba4e4e95ebd75ea9e6fb6ebb51f070e5488beafc1d2:0 (Write)
2024-09-04T08:45:46.704650067Z [tari::dan::hotstuff::substate_store::pending_store] INFO \U0001f512️ Requested substate lock: component_abf0c6ae1c62657fa8d79fcbfa14d0057b380880a6b3ed2f77d8c26a5d0e5b13:0 (Write)
2024-09-04T08:45:46.704830880Z [tari::dan::hotstuff::substate_store::pending_store] INFO \U0001f512️ Requested substate lock: component_4099b72d470528cd910bfabe560cc67d69a5a34483e014b235a40ebdce5585d6:0 (Output)
2024-09-04T08:45:46.704886950Z [tari::dan::hotstuff::substate_store::pending_store] INFO \U0001f512️ Requested substate lock: txreceipt_a5efafadc42dbc750227d7960e28e4e2fb20d46832bce9d8745abfe327daf75b:0 (Output)
2024-09-04T08:45:46.704938811Z [on_ready_to_vote_on_local_block] INFO \U0001f468‍\U0001f527 LocalOnly: Prepare for transaction a5efafadc42dbc750227d7960e28e4e2fb20d46832bce9d8745abfe327daf75b (Commit) in block [NodeHeight(1), Epoch(1), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af]
2024-09-04T08:45:46.704969902Z [tari::dan::storage::transaction_pool] INFO \U0001f4dd Setting next update for transaction a5efafadc42dbc750227d7960e28e4e2fb20d46832bce9d8745abfe327daf75b to New->LocalOnly,is_ready=true->false,Commit
2024-09-04T08:45:46.705124324Z [tari::dan::consensus::sharded_state_tree] DEBUG v1 contains 1 tree change(s) for shard Shard(16)
2024-09-04T08:45:46.705148824Z [tari_state_tree::tree] DEBUG Inserting node: v1:
2024-09-04T08:45:46.705160694Z [tari::dan::consensus::sharded_state_tree] DEBUG v1 contains 1 tree change(s) for shard Shard(41)
2024-09-04T08:45:46.705170174Z [tari_state_tree::tree] DEBUG Inserting node: v1:
2024-09-04T08:45:46.705337307Z [tari::dan::storage::quorum_certificate] INFO \U0001f525 UPDATE_HIGH_QC (Qc(block: 0000000000000000000000000000000000000000000000000000000000000000 NodeHeight(0), qc_id: 9e34ed49e414afcdcb0a3a41a13df4ac1b7fb1fa055b358c30c5d15f6a7cc5dc, epoch: Epoch(1), 0 signatures), previous high QC: 0000000000000000000000000000000000000000000000000000000000000000 NodeHeight(0))
2024-09-04T08:45:46.725965403Z [on_receive_local_proposal] INFO ✅ Block ValidBlock([NodeHeight(1), Epoch(1), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af]) is valid and persisted. HighQc((block_id: 0000000000000000000000000000000000000000000000000000000000000000, height: NodeHeight(0), qc_id: 9e34ed49e414afcdcb0a3a41a13df4ac1b7fb1fa055b358c30c5d15f6a7cc5dc))
2024-09-04T08:45:46.741775221Z [on_ready_to_vote_on_local_block] DEBUG \U0001f525 LOCAL PROPOSAL READY: ValidBlock([NodeHeight(1), Epoch(1), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af])
2024-09-04T08:45:46.742084546Z [tari::dan::storage::state_store_sqlite::reader] DEBUG get_block_ids_between: start: ac05e09beadc7962d072b60ba04556b62e6596a29550578da575411b49162eae, end: 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af
2024-09-04T08:45:46.742234578Z [tari::dan::storage::state_store_sqlite::reader] DEBUG get_transaction_atom_state_updates_between_blocks: from_block_id=ac05e09beadc7962d072b60ba04556b62e6596a29550578da575411b49162eae, to_block_id=8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af, len(applicable_block_ids)=2
2024-09-04T08:45:46.742510102Z [tari::dan::storage::state_store_sqlite::reader] DEBUG transaction_pool_get: from_block_id=ac05e09beadc7962d072b60ba04556b62e6596a29550578da575411b49162eae, to_block_id=8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af, transaction_id=a5efafadc42dbc750227d7960e28e4e2fb20d46832bce9d8745abfe327daf75b, updates=0 [[]]
2024-09-04T08:45:46.742950188Z [block_transaction_executor] INFO Resolved LOCAL substate: component_627815356033cb1da519a762aca73f47fe9adfbaebcc93d8a9938ad7956096c9:0
2024-09-04T08:45:46.743113070Z [block_transaction_executor] INFO Resolved LOCAL substate: component_1d97cc7aba8c981a3c0182545a6e21dfca81fd204279ca3ba752e0a7fc24beea:0
2024-09-04T08:45:46.743261932Z [block_transaction_executor] INFO Resolved LOCAL substate: component_c0164519d90bec552264bcd4a7cf7e551d3b3bb5362ab1fc45f00e509cff3328:0
2024-09-04T08:45:46.743411624Z [block_transaction_executor] INFO Resolved LOCAL substate: component_afbe8467740c2027f117fba4e4e95ebd75ea9e6fb6ebb51f070e5488beafc1d2:0
2024-09-04T08:45:46.743563396Z [block_transaction_executor] INFO Resolved LOCAL substate: component_abf0c6ae1c62657fa8d79fcbfa14d0057b380880a6b3ed2f77d8c26a5d0e5b13:0
2024-09-04T08:45:46.743588897Z [block_transaction_executor] INFO \U0001f468‍\U0001f527 PREPARE: Local-Only Executed transaction a5efafadc42dbc750227d7960e28e4e2fb20d46832bce9d8745abfe327daf75b with Commit decision
2024-09-04T08:45:46.743598857Z [tari::dan::hotstuff::substate_store::pending_store] INFO \U0001f512️ Requested substate lock: component_627815356033cb1da519a762aca73f47fe9adfbaebcc93d8a9938ad7956096c9:0 (Write)
2024-09-04T08:45:46.743761109Z [tari::dan::hotstuff::substate_store::pending_store] INFO \U0001f512️ Requested substate lock: component_1d97cc7aba8c981a3c0182545a6e21dfca81fd204279ca3ba752e0a7fc24beea:0 (Write)
2024-09-04T08:45:46.743919951Z [tari::dan::hotstuff::substate_store::pending_store] INFO \U0001f512️ Requested substate lock: component_c0164519d90bec552264bcd4a7cf7e551d3b3bb5362ab1fc45f00e509cff3328:0 (Write)
2024-09-04T08:45:46.744084633Z [tari::dan::hotstuff::substate_store::pending_store] INFO \U0001f512️ Requested substate lock: component_afbe8467740c2027f117fba4e4e95ebd75ea9e6fb6ebb51f070e5488beafc1d2:0 (Write)
2024-09-04T08:45:46.744256016Z [tari::dan::hotstuff::substate_store::pending_store] INFO \U0001f512️ Requested substate lock: component_abf0c6ae1c62657fa8d79fcbfa14d0057b380880a6b3ed2f77d8c26a5d0e5b13:0 (Write)
2024-09-04T08:45:46.744421618Z [tari::dan::hotstuff::substate_store::pending_store] INFO \U0001f512️ Requested substate lock: component_4099b72d470528cd910bfabe560cc67d69a5a34483e014b235a40ebdce5585d6:0 (Output)
2024-09-04T08:45:46.744475879Z [tari::dan::hotstuff::substate_store::pending_store] INFO \U0001f512️ Requested substate lock: txreceipt_a5efafadc42dbc750227d7960e28e4e2fb20d46832bce9d8745abfe327daf75b:0 (Output)
2024-09-04T08:45:46.744525110Z [on_ready_to_vote_on_local_block] INFO \U0001f468‍\U0001f527 LocalOnly: Prepare for transaction a5efafadc42dbc750227d7960e28e4e2fb20d46832bce9d8745abfe327daf75b (Commit) in block [NodeHeight(1), Epoch(1), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af]
2024-09-04T08:45:46.744554530Z [tari::dan::storage::transaction_pool] INFO \U0001f4dd Setting next update for transaction a5efafadc42dbc750227d7960e28e4e2fb20d46832bce9d8745abfe327daf75b to New->LocalOnly,is_ready=true->false,Commit
2024-09-04T08:45:46.744696202Z [tari::dan::consensus::sharded_state_tree] DEBUG v1 contains 1 tree change(s) for shard Shard(16)
2024-09-04T08:45:46.744719382Z [tari_state_tree::tree] DEBUG Inserting node: v1:
2024-09-04T08:45:46.744730882Z [tari::dan::consensus::sharded_state_tree] DEBUG v1 contains 1 tree change(s) for shard Shard(41)
2024-09-04T08:45:46.744740312Z [tari_state_tree::tree] DEBUG Inserting node: v1:
2024-09-04T08:45:46.744907045Z [tari::dan::storage::quorum_certificate] INFO \U0001f525 UPDATE_HIGH_QC (Qc(block: 0000000000000000000000000000000000000000000000000000000000000000 NodeHeight(0), qc_id: 9e34ed49e414afcdcb0a3a41a13df4ac1b7fb1fa055b358c30c5d15f6a7cc5dc, epoch: Epoch(1), 0 signatures), previous high QC: 0000000000000000000000000000000000000000000000000000000000000000 NodeHeight(0))
2024-09-04T08:45:46.745592834Z [tari::dan::consensus::block_change_set] INFO \U0001f4dd Transaction execution for a5efafadc42dbc750227d7960e28e4e2fb20d46832bce9d8745abfe327daf75b already exists in block 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af
2024-09-04T08:45:46.754321696Z [tari::dan::consensus::block_change_set] INFO \U0001f4dd Transaction execution for a5efafadc42dbc750227d7960e28e4e2fb20d46832bce9d8745abfe327daf75b saved in block 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af
2024-09-04T08:45:46.769717349Z [on_receive_local_proposal] DEBUG \U0001f525 LOCAL PROPOSAL ValidBlock([NodeHeight(1), Epoch(1), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af]) DECIDED Accept
2024-09-04T08:45:46.769987743Z [on_receive_local_proposal] INFO \U0001f525 VOTE Accept for block [NodeHeight(1), Epoch(1), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af] proposed by 9e504f9b10c40230ec4e1570dcf295d5da01aa0daeced57316b160c80bc57e0c to next leader TestAddress(1)
2024-09-04T08:45:46.770174525Z [current_view] INFO \U0001f9ff PACEMAKER: View updated to Epoch(1)/NodeHeight(1)
2024-09-04T08:45:46.770195685Z [worker] DEBUG \U0001f525 Current height #2
2024-09-04T08:45:46.770215536Z [pacemaker] INFO Reset! Current height: Epoch(1)/NodeHeight(1), Delta: 20.00s
2024-09-04T08:45:46.770233246Z [consensus_tests::support::network] DEBUG ✉️ Message Vote(NodeHeight(1), 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af, Accept) from TestAddress(1) to TestAddress(1)
2024-09-04T08:45:46.770280367Z [on_receive_vote] DEBUG \U0001f4ec Validating vote message from TestAddress(1): VoteMessage: Epoch(1), block_id: 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af, NodeHeight(1), decision: Accept, voter: cec1426a33965eb2a7d82b281964ad39f06d6fba7d8e57f8da4fcfefd946d855
2024-09-04T08:45:46.770551330Z [on_receive_vote] INFO \U0001f525 Received vote for block Epoch(1) NodeHeight(1) 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af from TestAddress(1) (1 of 2)
2024-09-04T08:45:46.770565831Z [worker] DEBUG \U0001f525 Current height #2
2024-09-04T08:45:46.778111965Z [on_receive_local_proposal] DEBUG \U0001f525 LOCAL PROPOSAL ValidBlock([NodeHeight(1), Epoch(1), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af]) DECIDED Accept
2024-09-04T08:45:46.778328488Z [on_receive_local_proposal] INFO \U0001f525 VOTE Accept for block [NodeHeight(1), Epoch(1), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af] proposed by 9e504f9b10c40230ec4e1570dcf295d5da01aa0daeced57316b160c80bc57e0c to next leader TestAddress(1)
2024-09-04T08:45:46.778496690Z [current_view] INFO \U0001f9ff PACEMAKER: View updated to Epoch(1)/NodeHeight(1)
2024-09-04T08:45:46.778511300Z [worker] DEBUG \U0001f525 Current height #2
2024-09-04T08:45:46.778531891Z [pacemaker] INFO Reset! Current height: Epoch(1)/NodeHeight(1), Delta: 20.00s
2024-09-04T08:45:46.778549781Z [consensus_tests::support::network] DEBUG ✉️ Message Vote(NodeHeight(1), 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af, Accept) from TestAddress(2) to TestAddress(1)
2024-09-04T08:45:46.778591482Z [on_receive_vote] DEBUG \U0001f4ec Validating vote message from TestAddress(2): VoteMessage: Epoch(1), block_id: 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af, NodeHeight(1), decision: Accept, voter: 9e504f9b10c40230ec4e1570dcf295d5da01aa0daeced57316b160c80bc57e0c
2024-09-04T08:45:46.778750294Z [on_receive_vote] INFO \U0001f525 Received vote for block Epoch(1) NodeHeight(1) 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af from TestAddress(2) (2 of 2)
2024-09-04T08:45:46.779129349Z [on_receive_vote] INFO \U0001f525 New QC Qc(block: 8937aa688a36e4a405e336c2f3919b41941bbe4c09907ace637ecd02a818d2af NodeHeight(1), qc_id: 8885716632b4d87c57ff5b910aca42405b6d0fbbdba13828f9c8a071253cc014, epoch: Epoch(1), 2 signatures)
2024-09-04T08:45:46.77916…45332Z [tari::dan::storage::consensus_models::block] DEBUG ✅ Node NodeHeight(4) 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647 forms a 3-chain b'' = b2cdc3efc147fd7ccd1234b1be30ffc279f21bf420d7e5a459a3ec9b5e421513, b' = 18345bd630e00908ea23895e2d2d129c8ffce560dcf4b8d27d38c8cadda7552a, b = fb6517d5ce8101c8ce481e72bd779a94ecd79d1f901f1b8b600d8dc0c85b1277
2024-09-04T08:45:48.349557879Z [on_ready_to_vote_on_local_block] INFO \U0001f333 Committing block [NodeHeight(1), Epoch(2), ShardGroup[Shard(0), Shard(63)], 7 cmd(s), fb6517d5ce8101c8ce481e72bd779a94ecd79d1f901f1b8b600d8dc0c85b1277] with 14 substate change(s)
2024-09-04T08:45:48.365697912Z [on_receive_local_proposal] DEBUG Broadcast new locked block: [NodeHeight(2), Epoch(2), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 18345bd630e00908ea23895e2d2d129c8ffce560dcf4b8d27d38c8cadda7552a]
2024-09-04T08:45:48.365761473Z [on_receive_local_proposal] DEBUG \U0001f525 LOCAL PROPOSAL ValidBlock([NodeHeight(4), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647]) DECIDED Accept
2024-09-04T08:45:48.365978706Z [on_receive_local_proposal] INFO \U0001f525 VOTE Accept for block [NodeHeight(4), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647] proposed by cec1426a33965eb2a7d82b281964ad39f06d6fba7d8e57f8da4fcfefd946d855 to next leader TestAddress(2)
2024-09-04T08:45:48.366154518Z [current_view] INFO \U0001f9ff PACEMAKER: View updated to Epoch(2)/NodeHeight(4)
2024-09-04T08:45:48.366166509Z [worker] DEBUG \U0001f525 Current height #5
2024-09-04T08:45:48.366189899Z [pacemaker] INFO Reset! Current height: Epoch(2)/NodeHeight(4), Delta: 14.00s
2024-09-04T08:45:48.366210489Z [consensus_tests::support::network] DEBUG ✉️ Message Vote(NodeHeight(4), 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647, Accept) from TestAddress(2) to TestAddress(2)
2024-09-04T08:45:48.366252370Z [on_receive_vote] DEBUG \U0001f4ec Validating vote message from TestAddress(2): VoteMessage: Epoch(2), block_id: 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647, NodeHeight(4), decision: Accept, voter: 9e504f9b10c40230ec4e1570dcf295d5da01aa0daeced57316b160c80bc57e0c
2024-09-04T08:45:48.366413852Z [on_receive_vote] INFO \U0001f525 Received vote for block Epoch(2) NodeHeight(4) 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647 from TestAddress(2) (1 of 2)
2024-09-04T08:45:48.366426932Z [worker] DEBUG \U0001f525 Current height #5
2024-09-04T08:45:48.382272152Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(0)) Inserting node: v1:
2024-09-04T08:45:48.382429324Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(3)) Inserting node: v1:
2024-09-04T08:45:48.382494195Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(4)) Inserting node: v1:
2024-09-04T08:45:48.382553666Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(6)) Inserting node: v1:
2024-09-04T08:45:48.382610657Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(9)) Inserting node: v1:
2024-09-04T08:45:48.382661928Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(16)) Recording stale tree node: v1:
2024-09-04T08:45:48.382742779Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(16)) Inserting node: v2:1
2024-09-04T08:45:48.382772429Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(16)) Inserting node: v2:
2024-09-04T08:45:48.382802830Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(16)) Inserting node: v2:7
2024-09-04T08:45:48.382825820Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(16)) Inserting node: v2:0
2024-09-04T08:45:48.382888411Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(26)) Inserting node: v1:
2024-09-04T08:45:48.382943861Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(29)) Inserting node: v1:
2024-09-04T08:45:48.382996192Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(30)) Inserting node: v1:
2024-09-04T08:45:48.383047913Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(37)) Inserting node: v1:
2024-09-04T08:45:48.383099374Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(45)) Inserting node: v1:
2024-09-04T08:45:48.383157734Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(47)) Inserting node: v1:
2024-09-04T08:45:48.383206145Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(55)) Inserting node: v1:
2024-09-04T08:45:48.385093261Z [tari::dan::storage] DEBUG Deleted 0 foreign substate pledges
2024-09-04T08:45:48.385231033Z [tari::dan::storage::state_store_sqlite::reader] DEBUG get_block_ids_between: start: 0000000000000000000000000000000000000000000000000000000000000000, end: fb6517d5ce8101c8ce481e72bd779a94ecd79d1f901f1b8b600d8dc0c85b1277
2024-09-04T08:45:48.425820365Z [tari::dan::storage::state_store_sqlite::reader] DEBUG get_block_ids_between: start: 0000000000000000000000000000000000000000000000000000000000000000, end: fb6517d5ce8101c8ce481e72bd779a94ecd79d1f901f1b8b600d8dc0c85b1277
2024-09-04T08:45:48.426451984Z [tari::dan::storage::state_store_sqlite::reader] DEBUG get_block_ids_between: start: 0000000000000000000000000000000000000000000000000000000000000000, end: fb6517d5ce8101c8ce481e72bd779a94ecd79d1f901f1b8b600d8dc0c85b1277
2024-09-04T08:45:48.426812749Z [tari::dan::storage::state_store_sqlite::reader] DEBUG get_block_ids_between: start: 0000000000000000000000000000000000000000000000000000000000000000, end: fb6517d5ce8101c8ce481e72bd779a94ecd79d1f901f1b8b600d8dc0c85b1277
2024-09-04T08:45:48.427182964Z [tari::dan::storage::state_store_sqlite::reader] DEBUG get_block_ids_between: start: 0000000000000000000000000000000000000000000000000000000000000000, end: fb6517d5ce8101c8ce481e72bd779a94ecd79d1f901f1b8b600d8dc0c85b1277
2024-09-04T08:45:48.427547319Z [tari::dan::storage::state_store_sqlite::reader] DEBUG get_block_ids_between: start: 0000000000000000000000000000000000000000000000000000000000000000, end: fb6517d5ce8101c8ce481e72bd779a94ecd79d1f901f1b8b600d8dc0c85b1277
2024-09-04T08:45:48.427894314Z [tari::dan::storage::state_store_sqlite::reader] DEBUG get_block_ids_between: start: 0000000000000000000000000000000000000000000000000000000000000000, end: fb6517d5ce8101c8ce481e72bd779a94ecd79d1f901f1b8b600d8dc0c85b1277
2024-09-04T08:45:48.428577813Z [on_ready_to_vote_on_local_block] DEBUG ✅ 7 transactions finalized
2024-09-04T08:45:48.428585464Z [on_ready_to_vote_on_local_block] INFO \U0001fa99 Validator fee for block 9e504f9b10c40230ec4e1570dcf295d5da01aa0daeced57316b160c80bc57e0c (7, Total Fees Paid = 7)
2024-09-04T08:45:48.428595254Z [on_ready_to_vote_on_local_block] DEBUG ✅ COMMIT block [NodeHeight(1), Epoch(2), ShardGroup[Shard(0), Shard(63)], 7 cmd(s), fb6517d5ce8101c8ce481e72bd779a94ecd79d1f901f1b8b600d8dc0c85b1277], last executed height = NodeHeight(0)
2024-09-04T08:45:48.428898858Z [consensus_tests::support::harness] INFO TestAddress(1) has 1 transactions in pool
2024-09-04T08:45:48.428970999Z [on_receive_local_proposal] DEBUG Broadcast new locked block: [NodeHeight(2), Epoch(2), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 18345bd630e00908ea23895e2d2d129c8ffce560dcf4b8d27d38c8cadda7552a]
2024-09-04T08:45:48.429010289Z [on_receive_local_proposal] DEBUG \U0001f525 LOCAL PROPOSAL ValidBlock([NodeHeight(4), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647]) DECIDED Accept
2024-09-04T08:45:48.429208442Z [on_receive_local_proposal] INFO \U0001f525 VOTE Accept for block [NodeHeight(4), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647] proposed by cec1426a33965eb2a7d82b281964ad39f06d6fba7d8e57f8da4fcfefd946d855 to next leader TestAddress(2)
2024-09-04T08:45:48.429323164Z [current_view] INFO \U0001f9ff PACEMAKER: View updated to Epoch(2)/NodeHeight(4)
2024-09-04T08:45:48.429335234Z [worker] DEBUG \U0001f525 Current height #5
2024-09-04T08:45:48.429355324Z [pacemaker] INFO Reset! Current height: Epoch(2)/NodeHeight(4), Delta: 14.00s
2024-09-04T08:45:48.429371314Z [consensus_tests::support::network] DEBUG ✉️ Message Vote(NodeHeight(4), 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647, Accept) from TestAddress(1) to TestAddress(2)
2024-09-04T08:45:48.429430795Z [on_receive_vote] DEBUG \U0001f4ec Validating vote message from TestAddress(1): VoteMessage: Epoch(2), block_id: 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647, NodeHeight(4), decision: Accept, voter: cec1426a33965eb2a7d82b281964ad39f06d6fba7d8e57f8da4fcfefd946d855
2024-09-04T08:45:48.429595808Z [on_receive_vote] INFO \U0001f525 Received vote for block Epoch(2) NodeHeight(4) 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647 from TestAddress(1) (2 of 2)
2024-09-04T08:45:48.429971243Z [on_receive_vote] INFO \U0001f525 New QC Qc(block: 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647 NodeHeight(4), qc_id: 3183abfca3e2910786df15de08de009661c5cddfbc2d7eff5e88c0a392f94f9e, epoch: Epoch(2), 2 signatures)
2024-09-04T08:45:48.430011893Z [tari::dan::storage::quorum_certificate] INFO \U0001f525 UPDATE_HIGH_QC (Qc(block: 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647 NodeHeight(4), qc_id: 3183abfca3e2910786df15de08de009661c5cddfbc2d7eff5e88c0a392f94f9e, epoch: Epoch(2), 2 signatures), previous high QC: 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647 NodeHeight(4))
2024-09-04T08:45:48.430171255Z [worker] DEBUG \U0001f525 Current height #5
2024-09-04T08:45:48.430236756Z [worker] INFO \U0001f525 [on_beat] TestAddress(2) Is leader: true, leaf_block: LeafBlock(NodeHeight(4) 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647 Epoch(2)), local_committee: 2
2024-09-04T08:45:48.431032877Z [on_local_propose] DEBUG \U0001f33f Found 0 burnt utxos for next block
2024-09-04T08:45:48.431073118Z [tari::dan::storage::state_store_sqlite::reader] DEBUG get_block_ids_between: start: 18345bd630e00908ea23895e2d2d129c8ffce560dcf4b8d27d38c8cadda7552a, end: 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647
2024-09-04T08:45:48.431168929Z [tari::dan::storage::state_store_sqlite::reader] DEBUG get_transaction_atom_state_updates_between_blocks: from_block_id=18345bd630e00908ea23895e2d2d129c8ffce560dcf4b8d27d38c8cadda7552a, to_block_id=9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647, len(applicable_block_ids)=3
2024-09-04T08:45:48.431401963Z [tari::dan::storage::state_store_sqlite::reader] DEBUG transaction_pool_get_many_ready: locked.block_id=18345bd630e00908ea23895e2d2d129c8ffce560dcf4b8d27d38c8cadda7552a, leaf.block_id=9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647, len(ready_txs)=1, updates=0
2024-09-04T08:45:48.431452713Z [on_local_propose] DEBUG command(s) for next block: []
2024-09-04T08:45:48.432146033Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING INSERT: node v2:9
2024-09-04T08:45:48.432153633Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING INSERT: node v2:6
2024-09-04T08:45:48.432156323Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING INSERT: node v2:
2024-09-04T08:45:48.432162483Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING DELETE: node v1:
2024-09-04T08:45:48.432609649Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING INSERT: node v2:8
2024-09-04T08:45:48.432615259Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING INSERT: node v2:d
2024-09-04T08:45:48.432617459Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING INSERT: node v2:
2024-09-04T08:45:48.432619549Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING DELETE: node v1:
2024-09-04T08:45:48.433213348Z [on_local_propose] INFO \U0001f33f [TestAddress(2)] PROPOSING new local block [NodeHeight(5), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1] to 2 validators. justify: 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647 (NodeHeight(4)), parent: 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647
2024-09-04T08:45:48.433239528Z [on_local_propose] INFO \U0001f33f Broadcasting local proposal [NodeHeight(5), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1] to 2 local committees
2024-09-04T08:45:48.433256288Z [worker] DEBUG \U0001f525 Current height #5
2024-09-04T08:45:48.433276578Z [pacemaker] INFO Reset! Current height: Epoch(2)/NodeHeight(4), Delta: 14.00s
2024-09-04T08:45:48.433295479Z [consensus_tests::support::network] DEBUG ✉️ Broadcast Proposal(Epoch=Epoch(2),Height=NodeHeight(5)) from TestAddress(2) to TestAddress(1), TestAddress(2)
2024-09-04T08:45:48.433328099Z [on_message_validate] INFO \U0001f4dc new unvalidated PROPOSAL message [NodeHeight(5), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1] from 9e504f9b10c40230ec4e1570dcf295d5da01aa0daeced57316b160c80bc57e0c (current height = NodeHeight(5))
2024-09-04T08:45:48.441802037Z [on_message_validate] INFO \U0001f4dc new unvalidated PROPOSAL message [NodeHeight(5), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1] from 9e504f9b10c40230ec4e1570dcf295d5da01aa0daeced57316b160c80bc57e0c (current height = NodeHeight(5))
2024-09-04T08:45:48.442506447Z [on_message_validate] DEBUG ✅ Block [NodeHeight(5), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1] is empty (no missing transactions)
2024-09-04T08:45:48.442535177Z [on_receive_local_proposal] DEBUG \U0001f525 LOCAL PROPOSAL: block [NodeHeight(5), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1] from 9e504f9b10c40230ec4e1570dcf295d5da01aa0daeced57316b160c80bc57e0c
2024-09-04T08:45:48.443342729Z [on_receive_local_proposal] INFO ✅ Block ValidBlock([NodeHeight(5), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1]) is valid and persisted. HighQc((block_id: 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647, height: NodeHeight(4), qc_id: 3183abfca3e2910786df15de08de009661c5cddfbc2d7eff5e88c0a392f94f9e))
2024-09-04T08:45:48.443645493Z [on_ready_to_vote_on_local_block] DEBUG \U0001f525 LOCAL PROPOSAL READY: ValidBlock([NodeHeight(5), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1])
2024-09-04T08:45:48.443838735Z [on_ready_to_vote_on_local_block] INFO ✅ New leaf block [NodeHeight(4), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647] is justified. Updating evidence for transactions
2024-09-04T08:45:48.444733858Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING INSERT: node v2:9
2024-09-04T08:45:48.444741878Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING INSERT: node v2:6
2024-09-04T08:45:48.444744228Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING INSERT: node v2:
2024-09-04T08:45:48.444746868Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING DELETE: node v1:
2024-09-04T08:45:48.445203974Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING INSERT: node v2:8
2024-09-04T08:45:48.445209844Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING INSERT: node v2:d
2024-09-04T08:45:48.445212064Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING INSERT: node v2:
2024-09-04T08:45:48.445214194Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING DELETE: node v1:
2024-09-04T08:45:48.445578800Z [tari::dan::storage::quorum_certificate] INFO \U0001f525 UPDATE_HIGH_QC (Qc(block: 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647 NodeHeight(4), qc_id: 3183abfca3e2910786df15de08de009661c5cddfbc2d7eff5e88c0a392f94f9e, epoch: Epoch(2), 2 signatures), previous high QC: 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647 NodeHeight(4))
2024-09-04T08:45:48.446255939Z [on_ready_to_vote_on_local_block] INFO \U0001f512️ LOCKED BLOCK: [NodeHeight(3), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), b2cdc3efc147fd7ccd1234b1be30ffc279f21bf420d7e5a459a3ec9b5e421513]
2024-09-04T08:45:48.446286849Z [tari::dan::storage] DEBUG transaction_pool_confirm_all_transitions: new_locked_block=LockedBlock(NodeHeight(3), b2cdc3efc147fd7ccd1234b1be30ffc279f21bf420d7e5a459a3ec9b5e421513), 0 updates
2024-09-04T08:45:48.446366170Z [tari::dan::storage::consensus_models::block] DEBUG ✅ Node NodeHeight(5) 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1 forms a 3-chain b'' = 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647, b' = b2cdc3efc147fd7ccd1234b1be30ffc279f21bf420d7e5a459a3ec9b5e421513, b = 18345bd630e00908ea23895e2d2d129c8ffce560dcf4b8d27d38c8cadda7552a
2024-09-04T08:45:48.446862817Z [on_ready_to_vote_on_local_block] INFO \U0001f333 Committing block [NodeHeight(2), Epoch(2), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 18345bd630e00908ea23895e2d2d129c8ffce560dcf4b8d27d38c8cadda7552a] with 2 substate change(s)
2024-09-04T08:45:48.447060340Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(26)) Recording stale tree node: v1:
2024-09-04T08:45:48.447136951Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(26)) Inserting node: v2:9
2024-09-04T08:45:48.447176252Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(26)) Inserting node: v2:6
2024-09-04T08:45:48.447203042Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(26)) Inserting node: v2:
2024-09-04T08:45:48.447287423Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(54)) Recording stale tree node: v1:
2024-09-04T08:45:48.447345004Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(54)) Inserting node: v2:8
2024-09-04T08:45:48.447371614Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(54)) Inserting node: v2:d
2024-09-04T08:45:48.447394635Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(54)) Inserting node: v2:
2024-09-04T08:45:48.447910662Z [tari::dan::storage] DEBUG Deleted 0 foreign substate pledges
2024-09-04T08:45:48.447949592Z [tari::dan::storage::state_store_sqlite::reader] DEBUG get_block_ids_between: start: 0000000000000000000000000000000000000000000000000000000000000000, end: 18345bd630e00908ea23895e2d2d129c8ffce560dcf4b8d27d38c8cadda7552a
2024-09-04T08:45:48.448390458Z [on_ready_to_vote_on_local_block] DEBUG ✅ 1 transactions finalized
2024-09-04T08:45:48.448397078Z [on_ready_to_vote_on_local_block] INFO \U0001fa99 Validator fee for block cec1426a33965eb2a7d82b281964ad39f06d6fba7d8e57f8da4fcfefd946d855 (1, Total Fees Paid = 1)
2024-09-04T08:45:48.448405359Z [on_ready_to_vote_on_local_block] DEBUG ✅ COMMIT block [NodeHeight(2), Epoch(2), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 18345bd630e00908ea23895e2d2d129c8ffce560dcf4b8d27d38c8cadda7552a], last executed height = NodeHeight(1)
2024-09-04T08:45:48.513757164Z [on_receive_local_proposal] DEBUG Broadcast new locked block: [NodeHeight(3), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), b2cdc3efc147fd7ccd1234b1be30ffc279f21bf420d7e5a459a3ec9b5e421513]
2024-09-04T08:45:48.513819165Z [on_receive_local_proposal] DEBUG \U0001f525 LOCAL PROPOSAL ValidBlock([NodeHeight(5), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1]) DECIDED Accept
2024-09-04T08:45:48.514035418Z [on_receive_local_proposal] INFO \U0001f525 VOTE Accept for block [NodeHeight(5), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1] proposed by 9e504f9b10c40230ec4e1570dcf295d5da01aa0daeced57316b160c80bc57e0c to next leader TestAddress(1)
2024-09-04T08:45:48.514225251Z [current_view] INFO \U0001f9ff PACEMAKER: View updated to Epoch(2)/NodeHeight(5)
2024-09-04T08:45:48.514239591Z [worker] DEBUG \U0001f525 Current height #6
2024-09-04T08:45:48.514263571Z [pacemaker] INFO Reset! Current height: Epoch(2)/NodeHeight(5), Delta: 14.00s
2024-09-04T08:45:48.514283611Z [consensus_tests::support::network] DEBUG ✉️ Message Vote(NodeHeight(5), 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1, Accept) from TestAddress(1) to TestAddress(1)
2024-09-04T08:45:48.514328112Z [on_receive_vote] DEBUG \U0001f4ec Validating vote message from TestAddress(1): VoteMessage: Epoch(2), block_id: 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1, NodeHeight(5), decision: Accept, voter: cec1426a33965eb2a7d82b281964ad39f06d6fba7d8e57f8da4fcfefd946d855
2024-09-04T08:45:48.514490774Z [on_receive_vote] INFO \U0001f525 Received vote for block Epoch(2) NodeHeight(5) 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1 from TestAddress(1) (1 of 2)
2024-09-04T08:45:48.514503665Z [worker] DEBUG \U0001f525 Current height #6
2024-09-04T08:45:48.533957434Z [on_message_validate] DEBUG ✅ Block [NodeHeight(5), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1] is empty (no missing transactions)
2024-09-04T08:45:48.534018894Z [on_receive_local_proposal] DEBUG \U0001f525 LOCAL PROPOSAL: block [NodeHeight(5), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1] from 9e504f9b10c40230ec4e1570dcf295d5da01aa0daeced57316b160c80bc57e0c
2024-09-04T08:45:48.534817425Z [on_receive_local_proposal] INFO ✅ Block ValidBlock([NodeHeight(5), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1]) is valid and persisted. HighQc((block_id: 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647, height: NodeHeight(4), qc_id: 3183abfca3e2910786df15de08de009661c5cddfbc2d7eff5e88c0a392f94f9e))
2024-09-04T08:45:48.534895927Z [on_ready_to_vote_on_local_block] DEBUG \U0001f525 LOCAL PROPOSAL READY: ValidBlock([NodeHeight(5), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1])
2024-09-04T08:45:48.535087419Z [on_ready_to_vote_on_local_block] INFO ✅ New leaf block [NodeHeight(4), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647] is justified. Updating evidence for transactions
2024-09-04T08:45:48.535957501Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING INSERT: node v2:9
2024-09-04T08:45:48.535965631Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING INSERT: node v2:6
2024-09-04T08:45:48.535968301Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING INSERT: node v2:
2024-09-04T08:45:48.535970881Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING DELETE: node v1:
2024-09-04T08:45:48.536385247Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING INSERT: node v2:8
2024-09-04T08:45:48.536397167Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING INSERT: node v2:d
2024-09-04T08:45:48.536399907Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING INSERT: node v2:
2024-09-04T08:45:48.536402267Z [tari::dan::consensus::sharded_state_tree] DEBUG PENDING DELETE: node v1:
2024-09-04T08:45:48.536763372Z [tari::dan::storage::quorum_certificate] INFO \U0001f525 UPDATE_HIGH_QC (Qc(block: 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647 NodeHeight(4), qc_id: 3183abfca3e2910786df15de08de009661c5cddfbc2d7eff5e88c0a392f94f9e, epoch: Epoch(2), 2 signatures), previous high QC: 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647 NodeHeight(4))
2024-09-04T08:45:48.537374671Z [on_ready_to_vote_on_local_block] INFO \U0001f512️ LOCKED BLOCK: [NodeHeight(3), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), b2cdc3efc147fd7ccd1234b1be30ffc279f21bf420d7e5a459a3ec9b5e421513]
2024-09-04T08:45:48.537401411Z [tari::dan::storage] DEBUG transaction_pool_confirm_all_transitions: new_locked_block=LockedBlock(NodeHeight(3), b2cdc3efc147fd7ccd1234b1be30ffc279f21bf420d7e5a459a3ec9b5e421513), 0 updates
2024-09-04T08:45:48.537477942Z [tari::dan::storage::consensus_models::block] DEBUG ✅ Node NodeHeight(5) 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1 forms a 3-chain b'' = 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647, b' = b2cdc3efc147fd7ccd1234b1be30ffc279f21bf420d7e5a459a3ec9b5e421513, b = 18345bd630e00908ea23895e2d2d129c8ffce560dcf4b8d27d38c8cadda7552a
2024-09-04T08:45:48.537999870Z [on_ready_to_vote_on_local_block] INFO \U0001f333 Committing block [NodeHeight(2), Epoch(2), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 18345bd630e00908ea23895e2d2d129c8ffce560dcf4b8d27d38c8cadda7552a] with 2 substate change(s)
2024-09-04T08:45:48.538187922Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(26)) Recording stale tree node: v1:
2024-09-04T08:45:48.538263603Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(26)) Inserting node: v2:9
2024-09-04T08:45:48.538310284Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(26)) Inserting node: v2:6
2024-09-04T08:45:48.538340194Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(26)) Inserting node: v2:
2024-09-04T08:45:48.538431316Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(54)) Recording stale tree node: v1:
2024-09-04T08:45:48.538500607Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(54)) Inserting node: v2:8
2024-09-04T08:45:48.538534147Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(54)) Inserting node: v2:d
2024-09-04T08:45:48.538560697Z [tari_consensus::hotstuff::substate_store::sharded_state_tree] DEBUG (shard=Shard(54)) Inserting node: v2:
2024-09-04T08:45:48.539096425Z [tari::dan::storage] DEBUG Deleted 0 foreign substate pledges
2024-09-04T08:45:48.539138856Z [tari::dan::storage::state_store_sqlite::reader] DEBUG get_block_ids_between: start: 0000000000000000000000000000000000000000000000000000000000000000, end: 18345bd630e00908ea23895e2d2d129c8ffce560dcf4b8d27d38c8cadda7552a
2024-09-04T08:45:48.539585702Z [on_ready_to_vote_on_local_block] DEBUG ✅ 1 transactions finalized
2024-09-04T08:45:48.539596332Z [on_ready_to_vote_on_local_block] INFO \U0001fa99 Validator fee for block cec1426a33965eb2a7d82b281964ad39f06d6fba7d8e57f8da4fcfefd946d855 (1, Total Fees Paid = 1)
2024-09-04T08:45:48.539607512Z [on_ready_to_vote_on_local_block] DEBUG ✅ COMMIT block [NodeHeight(2), Epoch(2), ShardGroup[Shard(0), Shard(63)], 1 cmd(s), 18345bd630e00908ea23895e2d2d129c8ffce560dcf4b8d27d38c8cadda7552a], last executed height = NodeHeight(1)
2024-09-04T08:45:48.539740114Z [on_receive_local_proposal] DEBUG Broadcast new locked block: [NodeHeight(3), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), b2cdc3efc147fd7ccd1234b1be30ffc279f21bf420d7e5a459a3ec9b5e421513]
2024-09-04T08:45:48.539776295Z [on_receive_local_proposal] DEBUG \U0001f525 LOCAL PROPOSAL ValidBlock([NodeHeight(5), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1]) DECIDED Accept
2024-09-04T08:45:48.539968977Z [on_receive_local_proposal] INFO \U0001f525 VOTE Accept for block [NodeHeight(5), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1] proposed by 9e504f9b10c40230ec4e1570dcf295d5da01aa0daeced57316b160c80bc57e0c to next leader TestAddress(1)
2024-09-04T08:45:48.540081869Z [current_view] INFO \U0001f9ff PACEMAKER: View updated to Epoch(2)/NodeHeight(5)
2024-09-04T08:45:48.540093259Z [worker] DEBUG \U0001f525 Current height #6
2024-09-04T08:45:48.540114029Z [pacemaker] INFO Reset! Current height: Epoch(2)/NodeHeight(5), Delta: 14.00s
2024-09-04T08:45:48.540129619Z [consensus_tests::support::network] DEBUG ✉️ Message Vote(NodeHeight(5), 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1, Accept) from TestAddress(2) to TestAddress(1)
2024-09-04T08:45:48.540167630Z [on_receive_vote] DEBUG \U0001f4ec Validating vote message from TestAddress(2): VoteMessage: Epoch(2), block_id: 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1, NodeHeight(5), decision: Accept, voter: 9e504f9b10c40230ec4e1570dcf295d5da01aa0daeced57316b160c80bc57e0c
2024-09-04T08:45:48.540328392Z [on_receive_vote] INFO \U0001f525 Received vote for block Epoch(2) NodeHeight(5) 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1 from TestAddress(2) (2 of 2)
2024-09-04T08:45:48.540647757Z [on_receive_vote] INFO \U0001f525 New QC Qc(block: 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1 NodeHeight(5), qc_id: 188c144bd7ba9acc996ca32aa59f87f7b2b4134bfa6bbc1ac0a9b5ed583b52c2, epoch: Epoch(2), 2 signatures)
2024-09-04T08:45:48.540688127Z [tari::dan::storage::quorum_certificate] INFO \U0001f525 UPDATE_HIGH_QC (Qc(block: 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1 NodeHeight(5), qc_id: 188c144bd7ba9acc996ca32aa59f87f7b2b4134bfa6bbc1ac0a9b5ed583b52c2, epoch: Epoch(2), 2 signatures), previous high QC: 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1 NodeHeight(5))
2024-09-04T08:45:48.540843570Z [worker] DEBUG \U0001f525 Current height #6
2024-09-04T08:45:48.540932771Z [worker] DEBUG [on_beat] No transactions to propose. Waiting for a timeout.
2024-09-04T08:45:48.540940641Z [worker] DEBUG \U0001f525 Current height #6
2024-09-04T08:45:48.540952121Z [pacemaker] INFO Reset! Current height: Epoch(2)/NodeHeight(5), Delta: 14.00s
2024-09-04T08:45:48.542060037Z [consensus_tests::support::network] INFO \U0001f6d1 Mempool task stopped
2024-09-04T08:45:48.542080507Z [consensus_tests::support::network] INFO \U0001f6d1 Network stopped
2024-09-04T08:45:48.542136708Z [inbound_messages] INFO Inbound messaging has terminated. Current view: Epoch(2)/NodeHeight(6)
2024-09-04T08:45:48.542146798Z [inbound_messages] INFO Inbound messaging has terminated. Current view: Epoch(2)/NodeHeight(6)
test consensus::epoch_change ... FAILED

failures:

failures:
    consensus::epoch_change

test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 23 filtered out; finished in 2.29s
thread 'consensus::epoch_change' panicked at dan_layer/consensus_tests/src/support/harness.rs:456:21:
assertion `left == right` failed: Validator TestAddress(1) is at height [NodeHeight(5), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 79bec6dfe588cc836b7a0aec8ee61102533e9a55d22fecdaa12ff8cee9ca9ad1] but validator TestAddress(2) is at height [NodeHeight(4), Epoch(2), ShardGroup[Shard(0), Shard(63)], 0 cmd(s), 9d5970c7eb39c88c2f662b5e03ed89b7582f7303d9cee2fcffd3347baca6a647]
  left: BlockId(FixedHash([121, 190, 198, 223, 229, 136, 204, 131, 107, 122, 10, 236, 142, 230, 17, 2, 83, 62, 154, 85, 210, 47, 236, 218, 161, 47, 248, 206, 233, 202, 154, 209]))
 right: BlockId(FixedHash([157, 89, 112, 199, 235, 57, 200, 140, 47, 102, 43, 94, 3, 237, 137, 183, 88, 47, 115, 3, 217, 206, 226, 252, 255, 211, 52, 123, 172, 166, 166, 71]))
note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace

Check notice on line 0 in .github

See this annotation in the file changed.

@github-actions github-actions / Test Results (CI)

108 tests found 

There are 108 tests, see "Raw output" for the full list of tests.
Raw output 
consensus_tests ‑ consensus::epoch_change
consensus_tests ‑ consensus::foreign_shard_group_decides_to_abort
consensus_tests ‑ consensus::leader_failure_node_goes_down
consensus_tests ‑ consensus::multi_shard_propose_blocks_with_new_transactions_until_all_committed
consensus_tests ‑ consensus::multi_shard_single_transaction
consensus_tests ‑ consensus::multi_validator_propose_blocks_with_new_transactions_until_all_committed
consensus_tests ‑ consensus::multishard_inputs_from_previous_outputs
consensus_tests ‑ consensus::multishard_local_inputs_and_outputs_foreign_outputs
consensus_tests ‑ consensus::multishard_local_inputs_foreign_outputs
consensus_tests ‑ consensus::multishard_output_conflict_abort
consensus_tests ‑ consensus::node_requests_missing_transaction_from_local_leader
consensus_tests ‑ consensus::propose_blocks_with_new_transactions_until_all_committed
consensus_tests ‑ consensus::propose_blocks_with_queued_up_transactions_until_all_committed
consensus_tests ‑ consensus::single_shard_input_conflict
consensus_tests ‑ consensus::single_shard_inputs_from_previous_outputs
consensus_tests ‑ consensus::single_shard_unversioned_inputs
consensus_tests ‑ consensus::single_transaction
consensus_tests ‑ consensus::single_transaction_abort
consensus_tests ‑ substate_store::it_allows_down_then_up
consensus_tests ‑ substate_store::it_allows_requesting_the_same_lock_within_one_transaction
consensus_tests ‑ substate_store::it_allows_substate_up_for_v0
consensus_tests ‑ substate_store::it_disallows_more_than_one_write_lock_non_local_only
consensus_tests ‑ substate_store::it_fails_if_previous_version_is_not_down
libp2p-messaging ‑ behaviour::tests::cycle_once_works
tari_base_node_client ‑ types::export_bindings_baselayervalidatornode
tari_bor ‑ json_encoding::tests::decode_encode
tari_bor ‑ tag::tests::decoding
tari_bor ‑ tag::tests::encoding
tari_dan_app_utilities ‑ json_encoding::tests::it_encodes_confidential_vaults
tari_dan_common_types ‑ committee::export_bindings_committee
tari_dan_common_types ‑ committee::export_bindings_committeeinfo
tari_dan_common_types ‑ committee::export_bindings_committeeshardinfo
tari_dan_common_types ‑ committee::export_bindings_networkcommitteeinfo
tari_dan_common_types ‑ committee::tests::select_n_starting_from::it_does_not_panic_empty_committee
tari_dan_common_types ‑ committee::tests::select_n_starting_from::it_selects_members_wrapping_around
tari_dan_common_types ‑ committee::tests::select_n_starting_from::it_wraps_around_once
tari_dan_common_types ‑ committee::tests::select_n_starting_from::it_wraps_the_start_index_around
tari_dan_common_types ‑ epoch::export_bindings_epoch
tari_dan_common_types ‑ lock_intent::export_bindings_substatelocktype
tari_dan_common_types ‑ node_height::export_bindings_nodeheight
tari_dan_common_types ‑ num_preshards::export_bindings_numpreshards
tari_dan_common_types ‑ peer_address::export_bindings_peeraddress
tari_dan_common_types ‑ peer_address::tests::check_conversions
tari_dan_common_types ‑ peer_address::tests::zero
tari_dan_common_types ‑ shard::export_bindings_shard
tari_dan_common_types ‑ shard::test::committee_is_properly_computed
tari_dan_common_types ‑ shard_group::export_bindings_shardgroup
tari_dan_common_types ‑ shard_group::tests::encode_decode
tari_dan_common_types ‑ shard_group::tests::to_substate_address_range
tari_dan_common_types ‑ substate_address::export_bindings_substateaddress
tari_dan_common_types ‑ substate_address::tests::max_committees
tari_dan_common_types ‑ substate_address::tests::shard_group_to_substate_address_range::it_works
tari_dan_common_types ‑ substate_address::tests::substate_addresses_to_from_u256_endianness_matches
tari_dan_common_types ‑ substate_address::tests::to_committee_shard_and_shard_range_match
tari_dan_common_types ‑ substate_address::tests::to_shard
tari_dan_common_types ‑ substate_address::tests::to_shard_group::it_returns_the_correct_shard_group
tari_dan_common_types ‑ substate_address::tests::to_shard_group::it_returns_the_correct_shard_group_for_odd_num_committees
tari_dan_common_types ‑ substate_address::tests::to_shard_group::it_returns_the_correct_shard_group_generic
tari_dan_common_types ‑ substate_type::export_bindings_substatetype
tari_dan_common_types ‑ uint::tests::u256_one
tari_dan_common_types ‑ versioned_substate_id::export_bindings_substaterequirement
tari_dan_common_types ‑ versioned_substate_id::export_bindings_versionedsubstateid
tari_dan_common_types ‑ versioned_substate_id::tests::it_hashes_identically_to_a_substate_id
tari_dan_engine ‑ runtime::workspace::tests::tuples
tari_dan_engine ‑ state_store::memory::tests::read_write
tari_dan_engine ‑ state_store::memory::tests::read_write_rollback_commit
tari_dan_engine ‑ wasm::version::tests::it_accepts_compatible_versions
tari_dan_engine ‑ wasm::version::tests::it_rejects_incompatible_versions
tari_dan_engine::access_rules ‑ component_access_rules::it_allows_owner_to_update_component_access_rules
tari_dan_engine::access_rules ‑ component_access_rules::it_prevents_access_rule_modification_if_owner_is_none
tari_dan_engine::access_rules ‑ component_access_rules::it_restricts_component_methods
tari_dan_engine::access_rules ‑ resource_access_rules::it_allows_access_for_proofs_by_amount
tari_dan_engine::access_rules ‑ resource_access_rules::it_allows_resource_access_with_badge_then_recall
tari_dan_engine::access_rules ‑ resource_access_rules::it_allows_resource_actions_if_auth_hook_passes
tari_dan_engine::access_rules ‑ resource_access_rules::it_creates_a_proof_from_bucket
tari_dan_engine::access_rules ‑ resource_access_rules::it_denies_actions_on_resource
tari_dan_engine::access_rules ‑ resource_access_rules::it_denies_recall_for_owner
tari_dan_engine::access_rules ‑ resource_access_rules::it_denies_resource_actions_if_auth_hook_fails
tari_dan_engine::access_rules ‑ resource_access_rules::it_disallows_hook_that_attempts_mutable_call_to_another_component_in_the_transaction
tari_dan_engine::access_rules ‑ resource_access_rules::it_disallows_hook_that_attempts_mutable_call_to_caller
tari_dan_engine::access_rules ‑ resource_access_rules::it_disallows_hook_that_writes_to_caller_component
tari_dan_engine::access_rules ‑ resource_access_rules::it_fails_if_auth_hook_is_invalid
tari_dan_engine::access_rules ‑ resource_access_rules::it_locks_resources_used_in_proofs
tari_dan_engine::access_rules ‑ resource_access_rules::it_permits_cross_template_calls_using_proofs
tari_dan_engine::access_rules ‑ resource_access_rules::it_restricts_resource_actions_to_component
tari_dan_engine::account ‑ attempt_to_overwrite_account
tari_dan_engine::account ‑ basic_faucet_transfer
tari_dan_engine::account ‑ gasless
tari_dan_engine::account ‑ withdraw_from_account_prevented
tari_dan_engine::account_nfts ‑ basic_nft_mint
tari_dan_engine::account_nfts ‑ mint_multiple_times
tari_dan_engine::address_allocation ‑ it_fails_if_allocation_is_not_used
tari_dan_engine::address_allocation ‑ it_uses_allocation_address
tari_dan_engine::airdrop ‑ airdrop
tari_dan_engine::asserts ‑ it_fails_with_invalid_amount
tari_dan_engine::asserts ‑ it_fails_with_invalid_bucket
tari_dan_engine::asserts ‑ it_fails_with_invalid_resource
tari_dan_engine::asserts ‑ it_fails_with_invalid_workspace_key
tari_dan_engine::asserts ‑ successful_assert
tari_dan_engine::composability ‑ it_allows_function_to_function_calls
tari_dan_engine::composability ‑ it_allows_function_to_method_calls
tari_dan_engine::composability ‑ it_allows_method_to_function_calls
tari_dan_engine::composability ‑ it_allows_method_to_method_calls
tari_dan_engine::composability ‑ it_allows_multiple_recursion_levels
tari_dan_engine::composability ‑ it_does_not_propagate_permissions
tari_dan_engine::composability ‑ it_fails_on_invalid_calls
tari_dan_engine::composability ‑ it_fails_when_surpassing_recursion_limit
tari_dan_engine::confidential ‑ attempt_to_reveal_with_unbalanced_proof
View more details on GitHub Actions