Parent Log: http://ci.aztec-labs.com/38d1899c9cb6c2c4 Command: 95395504b6c81849 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/517501b8b3c82d562e946c782c8fe75426951f62 Env: REF_NAME=gh-readonly-queue/next/pr-15016-a8c553f203c98e5ca274e763c8e51daaaaf760d6 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Fri Jun 13 09:53:48 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15016_amd64_x3-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 09:53:51 [09:53:51.900] INFO: world_state Created world state synchroniser with block history of infinity 09:53:52 [09:53:52.042] INFO: world_state Started world state synchronizer from block 1 09:53:52 [09:53:52.439] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":4.918542999999772,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:52 [09:53:52.442] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.9104980000001888,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:52 [09:53:52.445] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.781434999999874,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:52 [09:53:52.639] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.8131760000001123,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:52 [09:53:52.642] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.4825470000000678,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:52 [09:53:52.644] INFO: world_state Stopped world state synchronizer 09:53:52 [09:53:52.645] INFO: world_state Stopped world state synchronizer 09:53:52 [09:53:52.647] INFO: world_state Created world state synchroniser with block history of infinity 09:53:52 [09:53:52.819] INFO: world_state Started world state synchronizer from block 1 09:53:53 [09:53:53.126] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1257580000001326,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:53 [09:53:53.127] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.8996020000004137,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:53 [09:53:53.129] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0138050000005023,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:53 [09:53:53.131] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.309693000000152,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:53 [09:53:53.133] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.4522960000003877,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:53 [09:53:53.134] INFO: world_state Stopped world state synchronizer 09:53:53 [09:53:53.136] INFO: world_state Created world state synchroniser with block history of infinity 09:53:53 [09:53:53.138] INFO: world_state Started world state synchronizer from block 1 09:53:53 [09:53:53.139] INFO: world_state Stopped world state synchronizer 09:53:53 [09:53:53.141] INFO: world_state Created world state synchroniser with block history of infinity 09:53:53 [09:53:53.142] INFO: world_state Started world state synchronizer from block 1 09:53:53 [09:53:53.142] INFO: world_state Stopped world state synchronizer 09:53:53 [09:53:53.163] INFO: world_state Stopped world state synchronizer 09:53:53 [09:53:53.165] INFO: world_state Created world state synchroniser with block history of infinity 09:53:53 [09:53:53.348] INFO: world_state Started world state synchronizer from block 1 09:53:53 [09:53:53.677] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.513278000000355,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:53 [09:53:53.679] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.2784019999999146,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:53 [09:53:53.681] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2011300000003757,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:53 [09:53:53.683] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1307589999996708,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:53 [09:53:53.684] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.197400000000016,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:53 [09:53:53.888] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.6321109999998953,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:53 [09:53:53.891] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.4961369999991803,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:53 [09:53:53.893] INFO: world_state Stopped world state synchronizer 09:53:53 [09:53:53.895] INFO: world_state Created world state synchroniser with block history of infinity 09:53:54 [09:53:54.071] INFO: world_state Started world state synchronizer from block 1 09:53:54 [09:53:54.396] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.4243860000005952,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:54 [09:53:54.398] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.2924519999996846,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:54 [09:53:54.400] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.210399999999936,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:54 [09:53:54.402] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.900061999999707,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:54 [09:53:54.404] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0715369999998074,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:54 [09:53:54.663] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.170180000000073,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:54 [09:53:54.664] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.9925549999998111,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:54 [09:53:54.678] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.4237859999993816,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:54 [09:53:54.679] INFO: world_state Stopped world state synchronizer 09:53:54 [09:53:54.681] INFO: world_state Created world state synchroniser with block history of infinity 09:53:54 [09:53:54.853] INFO: world_state Started world state synchronizer from block 1 09:53:55 [09:53:55.122] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.0272249999998166,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:55 [09:53:55.123] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.764948000000004,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:55 [09:53:55.124] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.7630600000002232,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:55 [09:53:55.125] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.8336410000001706,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:55 [09:53:55.126] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.7299080000002505,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:55 [09:53:55.128] INFO: world_state Stopped world state synchronizer 09:53:55 [09:53:55.129] INFO: world_state Created world state synchroniser with block history of infinity 09:53:55 [09:53:55.303] INFO: world_state Started world state synchronizer from block 1 09:53:55 [09:53:55.581] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.554298999999446,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:55 [09:53:55.584] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.561649000000216,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:55 [09:53:55.586] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.168069000000287,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:55 [09:53:55.588] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1349890000001324,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:55 [09:53:55.590] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0581460000003062,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:53:55 [09:53:55.592] INFO: world_state Stopped world state synchronizer 09:53:55 [09:53:55.593] INFO: world_state Created world state synchroniser with block history of infinity 09:53:55 [09:53:55.596] INFO: world_state Stopped world state synchronizer 09:53:55 [09:53:55.597] INFO: world_state Created world state synchroniser with block history of infinity 09:53:55 [09:53:55.782] INFO: world_state Started world state synchronizer from block 1 09:53:56 [09:53:56.066] INFO: world_state Stopped world state synchronizer 09:53:56 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.318 s) 09:53:56 ServerWorldStateSynchronizer 09:53:56 ✓ updates sync progress (750 ms) 09:53:56 ✓ handles multiple calls to start (487 ms) 09:53:56 ✓ immediately syncs if no new blocks (5 ms) 09:53:56 ✓ cannot be started if already stopped (23 ms) 09:53:56 ✓ can immediately sync to latest (729 ms) 09:53:56 ✓ can immediately sync to a minimum block number (786 ms) 09:53:56 ✓ sync returns immediately if block was already synced (449 ms) 09:53:56 ✓ throws if you try to sync to an unavailable block (463 ms) 09:53:56 ✓ throws if you try to immediate sync when not running (4 ms) 09:53:56 ✓ throws if handling blocks fails (471 ms) 09:53:56 09:53:56 Test Suites: 1 passed, 1 total 09:53:56 Tests: 10 passed, 10 total 09:53:56 Snapshots: 0 total 09:53:56 Time: 6.397 s 09:53:56 Ran all test suites matching /world-state\/src\/synchronizer\/server_world_state_synchronizer.test.ts/i. 09:53:56 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?