Parent Log: http://ci.aztec-labs.com/3930f3da8e0771bb Command: d5ace37751aeb3b2 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/64d1ec347b1cea9fa1b04ff6fc2984f8ee073b2b Env: REF_NAME=gh-readonly-queue/next/pr-15185-107a625e4a5b124b87c02fa9ff2ae12b5778baa9 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Fri Jun 20 14:50:40 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15185_amd64_x1-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 14:50:44 [14:50:44.647] INFO: world_state Created world state synchroniser with block history of infinity 14:50:44 [14:50:44.791] INFO: world_state Started world state synchronizer from block 1 14:50:45 [14:50:45.199] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":8.351794999999584,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:45 [14:50:45.206] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":4.153397000000041,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:45 [14:50:45.212] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":3.7787570000000414,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:45 [14:50:45.420] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.2058969999998226,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:45 [14:50:45.423] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.6367220000001907,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:45 [14:50:45.425] INFO: world_state Stopped world state synchronizer 14:50:45 [14:50:45.427] INFO: world_state Stopped world state synchronizer 14:50:45 [14:50:45.430] INFO: world_state Created world state synchroniser with block history of infinity 14:50:45 [14:50:45.613] INFO: world_state Started world state synchronizer from block 1 14:50:45 [14:50:45.951] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":2.0533430000004955,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:45 [14:50:45.954] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.7701149999993504,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:45 [14:50:45.956] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.8076670000000377,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:45 [14:50:45.959] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.084893999999622,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:45 [14:50:45.962] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.9678610000000845,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:45 [14:50:45.964] INFO: world_state Stopped world state synchronizer 14:50:45 [14:50:45.967] INFO: world_state Created world state synchroniser with block history of infinity 14:50:45 [14:50:45.968] INFO: world_state Started world state synchronizer from block 1 14:50:45 [14:50:45.969] INFO: world_state Stopped world state synchronizer 14:50:45 [14:50:45.971] INFO: world_state Created world state synchroniser with block history of infinity 14:50:45 [14:50:45.972] INFO: world_state Started world state synchronizer from block 1 14:50:45 [14:50:45.972] INFO: world_state Stopped world state synchronizer 14:50:45 [14:50:45.996] INFO: world_state Stopped world state synchronizer 14:50:45 [14:50:45.999] INFO: world_state Created world state synchroniser with block history of infinity 14:50:46 [14:50:46.219] INFO: world_state Started world state synchronizer from block 1 14:50:46 [14:50:46.579] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.6265519999997196,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:46 [14:50:46.581] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.4321060000002035,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:46 [14:50:46.583] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.00262500000008,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:46 [14:50:46.593] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":9.9220949999999,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:46 [14:50:46.595] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.4478269999999611,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:46 [14:50:46.753] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.457777999999962,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:46 [14:50:46.755] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.3660149999996065,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:46 [14:50:46.757] INFO: world_state Stopped world state synchronizer 14:50:46 [14:50:46.759] INFO: world_state Created world state synchroniser with block history of infinity 14:50:46 [14:50:46.915] INFO: world_state Started world state synchronizer from block 1 14:50:47 [14:50:47.288] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1507799999999406,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:47 [14:50:47.289] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0515470000000278,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:47 [14:50:47.291] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0116459999999279,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:47 [14:50:47.292] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.8914430000004359,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:47 [14:50:47.293] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8848929999994652,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:47 [14:50:47.567] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.8623179999995045,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:47 [14:50:47.570] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.5500899999997273,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:47 [14:50:47.571] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.0846780000001672,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:47 [14:50:47.575] INFO: world_state Stopped world state synchronizer 14:50:47 [14:50:47.577] INFO: world_state Created world state synchroniser with block history of infinity 14:50:47 [14:50:47.769] INFO: world_state Started world state synchronizer from block 1 14:50:48 [14:50:48.124] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1021380000001955,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:48 [14:50:48.126] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0321970000004512,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:48 [14:50:48.127] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0612970000001951,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:48 [14:50:48.129] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.337703999999576,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:48 [14:50:48.131] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0280159999992975,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:48 [14:50:48.133] INFO: world_state Stopped world state synchronizer 14:50:48 [14:50:48.134] INFO: world_state Created world state synchroniser with block history of infinity 14:50:48 [14:50:48.294] INFO: world_state Started world state synchronizer from block 1 14:50:48 [14:50:48.636] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.0735179999992397,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:48 [14:50:48.638] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9804749999993874,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:48 [14:50:48.639] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9713250000004336,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:48 [14:50:48.641] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.0124359999999797,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:48 [14:50:48.642] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9386739999999918,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 14:50:48 [14:50:48.644] INFO: world_state Stopped world state synchronizer 14:50:48 [14:50:48.646] INFO: world_state Created world state synchroniser with block history of infinity 14:50:48 [14:50:48.649] INFO: world_state Stopped world state synchronizer 14:50:48 [14:50:48.651] INFO: world_state Created world state synchroniser with block history of infinity 14:50:48 [14:50:48.826] INFO: world_state Started world state synchronizer from block 1 14:50:49 [14:50:49.115] INFO: world_state Stopped world state synchronizer 14:50:49 PASS src/synchronizer/server_world_state_synchronizer.test.ts (7.096 s) 14:50:49 ServerWorldStateSynchronizer 14:50:49 ✓ updates sync progress (786 ms) 14:50:49 ✓ handles multiple calls to start (536 ms) 14:50:49 ✓ immediately syncs if no new blocks (4 ms) 14:50:49 ✓ cannot be started if already stopped (27 ms) 14:50:49 ✓ can immediately sync to latest (760 ms) 14:50:49 ✓ can immediately sync to a minimum block number (817 ms) 14:50:49 ✓ sync returns immediately if block was already synced (557 ms) 14:50:49 ✓ throws if you try to sync to an unavailable block (512 ms) 14:50:49 ✓ throws if you try to immediate sync when not running (4 ms) 14:50:49 ✓ throws if handling blocks fails (467 ms) 14:50:49 14:50:49 Test Suites: 1 passed, 1 total 14:50:49 Tests: 10 passed, 10 total 14:50:49 Snapshots: 0 total 14:50:49 Time: 7.191 s 14:50:49 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 14:50:49 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?