Parent Log: http://ci.aztec-labs.com/704f35e55ac1c274 Command: b54d5ade67e7ffc9 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/347f29fb98cc855ead57129b4d1a444d9fb16820 Env: REF_NAME=gh-readonly-queue/next/pr-15059-16fc84676c7308fa5f7ca4a54b67319803d0f5b7 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Mon Jun 16 16:01:46 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15059_amd64_x2-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 16:01:50 [16:01:50.316] INFO: world_state Created world state synchroniser with block history of infinity 16:01:50 [16:01:50.482] INFO: world_state Started world state synchronizer from block 1 16:01:50 [16:01:50.926] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":7.6267109999998866,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:50 [16:01:50.930] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.733632999999827,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:50 [16:01:50.933] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.340006999999787,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:51 [16:01:51.171] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.5885010000001785,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:51 [16:01:51.174] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":2.11166300000059,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:51 [16:01:51.176] INFO: world_state Stopped world state synchronizer 16:01:51 [16:01:51.178] INFO: world_state Stopped world state synchronizer 16:01:51 [16:01:51.181] INFO: world_state Created world state synchroniser with block history of infinity 16:01:51 [16:01:51.330] INFO: world_state Started world state synchronizer from block 1 16:01:51 [16:01:51.578] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1851189999997587,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:51 [16:01:51.580] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0674269999999524,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:51 [16:01:51.581] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9900850000003629,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:51 [16:01:51.583] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1123680000000604,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:51 [16:01:51.584] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0089259999995193,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:51 [16:01:51.585] INFO: world_state Stopped world state synchronizer 16:01:51 [16:01:51.587] INFO: world_state Created world state synchroniser with block history of infinity 16:01:51 [16:01:51.588] INFO: world_state Started world state synchronizer from block 1 16:01:51 [16:01:51.588] INFO: world_state Stopped world state synchronizer 16:01:51 [16:01:51.589] INFO: world_state Created world state synchroniser with block history of infinity 16:01:51 [16:01:51.590] INFO: world_state Started world state synchronizer from block 1 16:01:51 [16:01:51.590] INFO: world_state Stopped world state synchronizer 16:01:51 [16:01:51.603] INFO: world_state Stopped world state synchronizer 16:01:51 [16:01:51.604] INFO: world_state Created world state synchroniser with block history of infinity 16:01:51 [16:01:51.730] INFO: world_state Started world state synchronizer from block 1 16:01:51 [16:01:51.984] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":2.2769659999994474,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:51 [16:01:51.987] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.8870299999998679,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:51 [16:01:51.990] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.5649450000000797,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:51 [16:01:51.991] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.943304999999782,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:51 [16:01:51.993] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1499479999993127,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:52 [16:01:52.163] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.1596589999999196,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:52 [16:01:52.165] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.3275210000001607,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:52 [16:01:52.167] INFO: world_state Stopped world state synchronizer 16:01:52 [16:01:52.169] INFO: world_state Created world state synchroniser with block history of infinity 16:01:52 [16:01:52.331] INFO: world_state Started world state synchronizer from block 1 16:01:52 [16:01:52.630] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":4.645502999999735,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:52 [16:01:52.633] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.2479899999998452,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:52 [16:01:52.634] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.1773089999996955,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:52 [16:01:52.636] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.8847040000000561,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:52 [16:01:52.637] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8002120000001014,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:52 [16:01:52.955] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.55462499999976,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:52 [16:01:52.957] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.3190809999996418,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:52 [16:01:52.959] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":0.9108839999998963,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:52 [16:01:52.961] INFO: world_state Stopped world state synchronizer 16:01:52 [16:01:52.963] INFO: world_state Created world state synchroniser with block history of infinity 16:01:53 [16:01:53.132] INFO: world_state Started world state synchronizer from block 1 16:01:53 [16:01:53.491] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":2.1800240000002304,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:53 [16:01:53.493] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.3956520000001547,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:53 [16:01:53.495] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2725600000003396,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:53 [16:01:53.497] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3477210000000923,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:53 [16:01:53.499] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2971309999993537,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:53 [16:01:53.502] INFO: world_state Stopped world state synchronizer 16:01:53 [16:01:53.504] INFO: world_state Created world state synchroniser with block history of infinity 16:01:53 [16:01:53.705] INFO: world_state Started world state synchronizer from block 1 16:01:54 [16:01:54.019] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.9274740000000747,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:54 [16:01:54.021] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.2202399999996487,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:54 [16:01:54.023] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2537200000006123,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:54 [16:01:54.025] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.0573960000001534,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:54 [16:01:54.026] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9413349999995262,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:01:54 [16:01:54.028] INFO: world_state Stopped world state synchronizer 16:01:54 [16:01:54.029] INFO: world_state Created world state synchroniser with block history of infinity 16:01:54 [16:01:54.032] INFO: world_state Stopped world state synchronizer 16:01:54 [16:01:54.034] INFO: world_state Created world state synchroniser with block history of infinity 16:01:54 [16:01:54.235] INFO: world_state Started world state synchronizer from block 1 16:01:54 [16:01:54.551] INFO: world_state Stopped world state synchronizer 16:01:54 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.729 s) 16:01:54 ServerWorldStateSynchronizer 16:01:54 ✓ updates sync progress (869 ms) 16:01:54 ✓ handles multiple calls to start (406 ms) 16:01:54 ✓ immediately syncs if no new blocks (2 ms) 16:01:54 ✓ cannot be started if already stopped (14 ms) 16:01:54 ✓ can immediately sync to latest (563 ms) 16:01:54 ✓ can immediately sync to a minimum block number (793 ms) 16:01:54 ✓ sync returns immediately if block was already synced (541 ms) 16:01:54 ✓ throws if you try to sync to an unavailable block (525 ms) 16:01:54 ✓ throws if you try to immediate sync when not running (4 ms) 16:01:54 ✓ throws if handling blocks fails (518 ms) 16:01:54 16:01:54 Test Suites: 1 passed, 1 total 16:01:54 Tests: 10 passed, 10 total 16:01:54 Snapshots: 0 total 16:01:54 Time: 6.848 s 16:01:54 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 16:01:54 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?