Parent Log: http://ci.aztec-labs.com/b19cd8e5b38225d7 Command: f1d5df06d37e0c0f yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/5c77169d7f01a9e15bc19b05fa231fd96111d7dd Env: REF_NAME=gh-readonly-queue/next/pr-15042-d5c48d855d83e19712c6b72281801c6fce176ec1 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Wed Jun 18 15:02:13 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15042_amd64_x3-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 15:02:17 [15:02:17.511] INFO: world_state Created world state synchroniser with block history of infinity 15:02:17 [15:02:17.662] INFO: world_state Started world state synchronizer from block 1 15:02:18 [15:02:18.106] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":7.0925450000004275,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:18 [15:02:18.110] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.979812000000493,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:18 [15:02:18.114] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.659983000000466,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:18 [15:02:18.332] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.0645869999998467,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:18 [15:02:18.335] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.7308560000001307,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:18 [15:02:18.337] INFO: world_state Stopped world state synchronizer 15:02:18 [15:02:18.339] INFO: world_state Stopped world state synchronizer 15:02:18 [15:02:18.342] INFO: world_state Created world state synchroniser with block history of infinity 15:02:18 [15:02:18.562] INFO: world_state Started world state synchronizer from block 1 15:02:18 [15:02:18.922] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.9241780000002109,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:18 [15:02:18.925] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.6368810000003577,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:18 [15:02:18.927] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.4647789999999077,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:18 [15:02:18.929] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.6110790000002453,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:18 [15:02:18.931] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.6298199999991994,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:18 [15:02:18.933] INFO: world_state Stopped world state synchronizer 15:02:18 [15:02:18.935] INFO: world_state Created world state synchroniser with block history of infinity 15:02:18 [15:02:18.936] INFO: world_state Started world state synchronizer from block 1 15:02:18 [15:02:18.936] INFO: world_state Stopped world state synchronizer 15:02:18 [15:02:18.938] INFO: world_state Created world state synchroniser with block history of infinity 15:02:18 [15:02:18.938] INFO: world_state Started world state synchronizer from block 1 15:02:18 [15:02:18.938] INFO: world_state Stopped world state synchronizer 15:02:18 [15:02:18.966] INFO: world_state Stopped world state synchronizer 15:02:18 [15:02:18.972] INFO: world_state Created world state synchroniser with block history of infinity 15:02:19 [15:02:19.148] INFO: world_state Started world state synchronizer from block 1 15:02:19 [15:02:19.433] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.146310000000085,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:19 [15:02:19.435] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9596389999996973,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:19 [15:02:19.437] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2515370000000985,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:19 [15:02:19.450] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":12.984596000000238,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:19 [15:02:19.453] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.420256999999765,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:19 [15:02:19.648] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.3903149999996458,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:19 [15:02:19.650] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.369593999999779,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:19 [15:02:19.652] INFO: world_state Stopped world state synchronizer 15:02:19 [15:02:19.654] INFO: world_state Created world state synchroniser with block history of infinity 15:02:19 [15:02:19.819] INFO: world_state Started world state synchronizer from block 1 15:02:20 [15:02:20.126] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.7152649999998175,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:20 [15:02:20.129] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.56432599999971,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:20 [15:02:20.131] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.5439150000001973,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:20 [15:02:20.133] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.5537950000007186,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:20 [15:02:20.135] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.528733000000102,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:20 [15:02:20.425] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.0329340000007505,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:20 [15:02:20.427] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.0692259999996168,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:20 [15:02:20.429] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.4193470000000161,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:20 [15:02:20.430] INFO: world_state Stopped world state synchronizer 15:02:20 [15:02:20.431] INFO: world_state Created world state synchroniser with block history of infinity 15:02:20 [15:02:20.602] INFO: world_state Started world state synchronizer from block 1 15:02:20 [15:02:20.940] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.857984000000215,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:20 [15:02:20.942] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.4696200000007593,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:20 [15:02:20.944] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.3678540000000794,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:20 [15:02:20.947] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.8992959999995946,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:20 [15:02:20.948] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.4625000000005457,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:20 [15:02:20.951] INFO: world_state Stopped world state synchronizer 15:02:20 [15:02:20.952] INFO: world_state Created world state synchroniser with block history of infinity 15:02:21 [15:02:21.144] INFO: world_state Started world state synchronizer from block 1 15:02:21 [15:02:21.531] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1502900000004956,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:21 [15:02:21.534] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0930269999998927,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:21 [15:02:21.536] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.4838809999992009,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:21 [15:02:21.538] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2773180000003777,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:21 [15:02:21.539] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.3064799999992829,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:02:21 [15:02:21.542] INFO: world_state Stopped world state synchronizer 15:02:21 [15:02:21.543] INFO: world_state Created world state synchroniser with block history of infinity 15:02:21 [15:02:21.546] INFO: world_state Stopped world state synchronizer 15:02:21 [15:02:21.548] INFO: world_state Created world state synchroniser with block history of infinity 15:02:21 [15:02:21.781] INFO: world_state Started world state synchronizer from block 1 15:02:22 [15:02:22.125] INFO: world_state Stopped world state synchronizer 15:02:22 PASS src/synchronizer/server_world_state_synchronizer.test.ts (7.072 s) 15:02:22 ServerWorldStateSynchronizer 15:02:22 ✓ updates sync progress (833 ms) 15:02:22 ✓ handles multiple calls to start (592 ms) 15:02:22 ✓ immediately syncs if no new blocks (3 ms) 15:02:22 ✓ cannot be started if already stopped (32 ms) 15:02:22 ✓ can immediately sync to latest (681 ms) 15:02:22 ✓ can immediately sync to a minimum block number (777 ms) 15:02:22 ✓ sync returns immediately if block was already synced (520 ms) 15:02:22 ✓ throws if you try to sync to an unavailable block (590 ms) 15:02:22 ✓ throws if you try to immediate sync when not running (5 ms) 15:02:22 ✓ throws if handling blocks fails (579 ms) 15:02:22 15:02:22 Test Suites: 1 passed, 1 total 15:02:22 Tests: 10 passed, 10 total 15:02:22 Snapshots: 0 total 15:02:22 Time: 7.17 s 15:02:22 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 15:02:22 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?