Parent Log: http://ci.aztec-labs.com/505880563421c087 Command: 816a8a0e28c14627 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/7d223783d91db15002a09abc1b52d1455eb3e3da Env: REF_NAME=gh-readonly-queue/next/pr-14161-97080f178c0a3e244c64b3c17bf255bed63fc3cf CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Mon Jun 16 10:41:52 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14161_amd64_x4-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 10:41:55 [10:41:55.341] INFO: world_state Created world state synchroniser with block history of infinity 10:41:55 [10:41:55.483] INFO: world_state Started world state synchronizer from block 1 10:41:55 [10:41:55.910] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":6.115362000000005,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:55 [10:41:55.914] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.013912999999775,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:55 [10:41:55.917] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.936641000000236,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:56 [10:41:56.132] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.7438140000003841,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:56 [10:41:56.134] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2568689999998242,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:56 [10:41:56.135] INFO: world_state Stopped world state synchronizer 10:41:56 [10:41:56.136] INFO: world_state Stopped world state synchronizer 10:41:56 [10:41:56.139] INFO: world_state Created world state synchroniser with block history of infinity 10:41:56 [10:41:56.297] INFO: world_state Started world state synchronizer from block 1 10:41:56 [10:41:56.605] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.3537919999998849,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:56 [10:41:56.608] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.4784870000003139,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:56 [10:41:56.609] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0935039999994842,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:56 [10:41:56.611] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.417754999999488,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:56 [10:41:56.613] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1518049999995128,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:56 [10:41:56.615] INFO: world_state Stopped world state synchronizer 10:41:56 [10:41:56.617] INFO: world_state Created world state synchroniser with block history of infinity 10:41:56 [10:41:56.617] INFO: world_state Started world state synchronizer from block 1 10:41:56 [10:41:56.619] INFO: world_state Stopped world state synchronizer 10:41:56 [10:41:56.620] INFO: world_state Created world state synchroniser with block history of infinity 10:41:56 [10:41:56.621] INFO: world_state Started world state synchronizer from block 1 10:41:56 [10:41:56.622] INFO: world_state Stopped world state synchronizer 10:41:56 [10:41:56.640] INFO: world_state Stopped world state synchronizer 10:41:56 [10:41:56.643] INFO: world_state Created world state synchroniser with block history of infinity 10:41:56 [10:41:56.838] INFO: world_state Started world state synchronizer from block 1 10:41:57 [10:41:57.151] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.4648660000002565,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:57 [10:41:57.153] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.1970469999996567,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:57 [10:41:57.154] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0902439999999842,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:57 [10:41:57.156] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.0619329999999536,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:57 [10:41:57.157] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8899079999991955,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:57 [10:41:57.317] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.0612630000005083,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:57 [10:41:57.318] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.930199000000357,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:57 [10:41:57.319] INFO: world_state Stopped world state synchronizer 10:41:57 [10:41:57.321] INFO: world_state Created world state synchroniser with block history of infinity 10:41:57 [10:41:57.475] INFO: world_state Started world state synchronizer from block 1 10:41:57 [10:41:57.778] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.6048399999999674,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:57 [10:41:57.781] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.4870970000001762,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:57 [10:41:57.782] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.4365749999997206,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:57 [10:41:57.784] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2503790000000663,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:57 [10:41:57.786] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0873639999999796,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:58 [10:41:58.071] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":0.9849610000001121,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:58 [10:41:58.073] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.8357660000001488,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:58 [10:41:58.074] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":0.7804339999993317,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:58 [10:41:58.075] INFO: world_state Stopped world state synchronizer 10:41:58 [10:41:58.076] INFO: world_state Created world state synchroniser with block history of infinity 10:41:58 [10:41:58.221] INFO: world_state Started world state synchronizer from block 1 10:41:58 [10:41:58.518] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.5561479999996664,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:58 [10:41:58.521] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.1036159999994197,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:58 [10:41:58.523] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.370372999999745,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:58 [10:41:58.525] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3799630000003162,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:58 [10:41:58.527] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.3682230000003983,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:58 [10:41:58.529] INFO: world_state Stopped world state synchronizer 10:41:58 [10:41:58.530] INFO: world_state Created world state synchroniser with block history of infinity 10:41:58 [10:41:58.732] INFO: world_state Started world state synchronizer from block 1 10:41:59 [10:41:59.067] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.5410480000000462,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:59 [10:41:59.069] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.4117550000000847,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:59 [10:41:59.071] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.4680549999993673,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:59 [10:41:59.073] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3959730000005948,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:59 [10:41:59.075] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.3203819999998814,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:59 [10:41:59.087] INFO: world_state Stopped world state synchronizer 10:41:59 [10:41:59.089] INFO: world_state Created world state synchroniser with block history of infinity 10:41:59 [10:41:59.091] INFO: world_state Stopped world state synchronizer 10:41:59 [10:41:59.093] INFO: world_state Created world state synchroniser with block history of infinity 10:41:59 [10:41:59.243] INFO: world_state Started world state synchronizer from block 1 10:41:59 [10:41:59.605] INFO: world_state Stopped world state synchronizer 10:41:59 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.307 s) 10:41:59 ServerWorldStateSynchronizer 10:41:59 ✓ updates sync progress (801 ms) 10:41:59 ✓ handles multiple calls to start (477 ms) 10:41:59 ✓ immediately syncs if no new blocks (4 ms) 10:41:59 ✓ cannot be started if already stopped (22 ms) 10:41:59 ✓ can immediately sync to latest (679 ms) 10:41:59 ✓ can immediately sync to a minimum block number (755 ms) 10:41:59 ✓ sync returns immediately if block was already synced (454 ms) 10:41:59 ✓ throws if you try to sync to an unavailable block (559 ms) 10:41:59 ✓ throws if you try to immediate sync when not running (4 ms) 10:41:59 ✓ throws if handling blocks fails (514 ms) 10:41:59 10:41:59 Test Suites: 1 passed, 1 total 10:41:59 Tests: 10 passed, 10 total 10:41:59 Snapshots: 0 total 10:41:59 Time: 6.398 s 10:41:59 Ran all test suites matching /world-state\/src\/synchronizer\/server_world_state_synchronizer.test.ts/i. 10:41:59 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?