Parent Log: http://ci.aztec-labs.com/62cb07227cb8778b Command: 3cad4e1fd0756a5c yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/e9a2301883801edbe36ea8bba05dcca7e714f30a Env: REF_NAME=gh-readonly-queue/master/pr-15131-464e1a9d9b40b5188586643b44bc52bffccbc3e6 CURRENT_VERSION=0.87.8 CI_FULL=1 Date: Wed Jun 18 12:52:25 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15131_amd64_x1-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_master 12:52:29 [12:52:29.271] INFO: world_state Created world state synchroniser with block history of infinity 12:52:29 [12:52:29.413] INFO: world_state Started world state synchronizer from block 1 12:52:29 [12:52:29.612] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":6.6619409999998425,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:29 [12:52:29.627] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":12.506281999999828,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:29 [12:52:29.630] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.4274689999997463,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:29 [12:52:29.802] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.723446999999851,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:29 [12:52:29.805] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.6401809999997568,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:29 [12:52:29.807] INFO: world_state Stopped world state synchronizer 12:52:29 [12:52:29.809] INFO: world_state Stopped world state synchronizer 12:52:29 [12:52:29.811] INFO: world_state Created world state synchroniser with block history of infinity 12:52:29 [12:52:29.969] INFO: world_state Started world state synchronizer from block 1 12:52:30 [12:52:30.221] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1706060000005891,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:30 [12:52:30.223] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9084670000002006,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:30 [12:52:30.224] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8324320000001535,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:30 [12:52:30.225] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.0497180000002118,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:30 [12:52:30.226] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8702250000005733,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:30 [12:52:30.228] INFO: world_state Stopped world state synchronizer 12:52:30 [12:52:30.229] INFO: world_state Created world state synchroniser with block history of infinity 12:52:30 [12:52:30.229] INFO: world_state Started world state synchronizer from block 1 12:52:30 [12:52:30.230] INFO: world_state Stopped world state synchronizer 12:52:30 [12:52:30.231] INFO: world_state Created world state synchroniser with block history of infinity 12:52:30 [12:52:30.231] INFO: world_state Started world state synchronizer from block 1 12:52:30 [12:52:30.231] INFO: world_state Stopped world state synchronizer 12:52:30 [12:52:30.253] INFO: world_state Stopped world state synchronizer 12:52:30 [12:52:30.255] INFO: world_state Created world state synchroniser with block history of infinity 12:52:30 [12:52:30.419] INFO: world_state Started world state synchronizer from block 1 12:52:30 [12:52:30.706] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.0377670000007129,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:30 [12:52:30.708] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.1725360000000364,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:30 [12:52:30.710] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.343098000000282,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:30 [12:52:30.713] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.012978999999177,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:30 [12:52:30.715] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0042350000003353,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:30 [12:52:30.888] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.5566149999995105,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:30 [12:52:30.890] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.4789189999992232,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:30 [12:52:30.892] INFO: world_state Stopped world state synchronizer 12:52:30 [12:52:30.893] INFO: world_state Created world state synchroniser with block history of infinity 12:52:31 [12:52:31.051] INFO: world_state Started world state synchronizer from block 1 12:52:31 [12:52:31.278] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.9836129999994228,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:31 [12:52:31.279] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.8303809999997611,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:31 [12:52:31.280] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.7848469999998997,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:31 [12:52:31.281] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.9165579999998954,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:31 [12:52:31.283] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8519319999995787,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:31 [12:52:31.537] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.5724049999998897,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:31 [12:52:31.539] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.4231250000002547,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:31 [12:52:31.542] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.4076740000000427,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:31 [12:52:31.543] INFO: world_state Stopped world state synchronizer 12:52:31 [12:52:31.545] INFO: world_state Created world state synchroniser with block history of infinity 12:52:31 [12:52:31.698] INFO: world_state Started world state synchronizer from block 1 12:52:31 [12:52:31.961] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1808970000001864,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:31 [12:52:31.964] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.3547699999999168,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:31 [12:52:31.965] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9266890000008061,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:31 [12:52:31.966] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.8368609999997716,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:31 [12:52:31.968] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8300009999993563,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:31 [12:52:31.969] INFO: world_state Stopped world state synchronizer 12:52:31 [12:52:31.971] INFO: world_state Created world state synchroniser with block history of infinity 12:52:32 [12:52:32.111] INFO: world_state Started world state synchronizer from block 1 12:52:32 [12:52:32.421] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1608759999999165,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:32 [12:52:32.423] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.345288999999866,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:32 [12:52:32.425] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.418174999999792,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:32 [12:52:32.427] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.8420119999991584,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:32 [12:52:32.428] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.860634000000573,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:52:32 [12:52:32.429] INFO: world_state Stopped world state synchronizer 12:52:32 [12:52:32.430] INFO: world_state Created world state synchroniser with block history of infinity 12:52:32 [12:52:32.434] INFO: world_state Stopped world state synchronizer 12:52:32 [12:52:32.436] INFO: world_state Created world state synchroniser with block history of infinity 12:52:32 [12:52:32.604] INFO: world_state Started world state synchronizer from block 1 12:52:32 [12:52:32.888] INFO: world_state Stopped world state synchronizer 12:52:32 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.029 s) 12:52:32 ServerWorldStateSynchronizer 12:52:32 ✓ updates sync progress (543 ms) 12:52:32 ✓ handles multiple calls to start (417 ms) 12:52:32 ✓ immediately syncs if no new blocks (2 ms) 12:52:32 ✓ cannot be started if already stopped (24 ms) 12:52:32 ✓ can immediately sync to latest (638 ms) 12:52:32 ✓ can immediately sync to a minimum block number (651 ms) 12:52:32 ✓ sync returns immediately if block was already synced (426 ms) 12:52:32 ✓ throws if you try to sync to an unavailable block (459 ms) 12:52:32 ✓ throws if you try to immediate sync when not running (5 ms) 12:52:32 ✓ throws if handling blocks fails (454 ms) 12:52:32 12:52:32 Test Suites: 1 passed, 1 total 12:52:32 Tests: 10 passed, 10 total 12:52:32 Snapshots: 0 total 12:52:32 Time: 6.1 s 12:52:32 Ran all test suites matching /world-state\/src\/synchronizer\/server_world_state_synchronizer.test.ts/i. 12:52:32 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?