Parent Log: http://ci.aztec-labs.com/903550316e4b829b Command: ef85c2b8680d9e9c yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/9ba478709ce5c48099f79f9531da8292811cb18a Env: REF_NAME=gh-readonly-queue/staging/pr-15247-3863bc02d671057d0cffd4ced17ca317788ff190 CURRENT_VERSION=1.0.0 CI_FULL=1 Date: Tue Jun 24 15:00:05 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15247_amd64_x2-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_staging 15:00:08 [15:00:08.996] INFO: world_state Created world state synchroniser with block history of infinity 15:00:09 [15:00:09.118] INFO: world_state Started world state synchronizer from block 1 15:00:09 [15:00:09.459] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":5.0785689999997885,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:09 [15:00:09.462] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.5942570000001979,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:09 [15:00:09.465] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.2491360000003624,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:09 [15:00:09.626] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.8327830000002905,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:09 [15:00:09.629] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":2.3897400000000744,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:09 [15:00:09.631] INFO: world_state Stopped world state synchronizer 15:00:09 [15:00:09.633] INFO: world_state Stopped world state synchronizer 15:00:09 [15:00:09.636] INFO: world_state Created world state synchroniser with block history of infinity 15:00:09 [15:00:09.815] INFO: world_state Started world state synchronizer from block 1 15:00:10 [15:00:10.117] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.8341239999999743,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:10 [15:00:10.120] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.5693860000001223,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:10 [15:00:10.121] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2626179999997476,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:10 [15:00:10.123] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.4033110000000306,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:10 [15:00:10.125] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.223015999999916,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:10 [15:00:10.126] INFO: world_state Stopped world state synchronizer 15:00:10 [15:00:10.128] INFO: world_state Created world state synchroniser with block history of infinity 15:00:10 [15:00:10.129] INFO: world_state Started world state synchronizer from block 1 15:00:10 [15:00:10.129] INFO: world_state Stopped world state synchronizer 15:00:10 [15:00:10.130] INFO: world_state Created world state synchroniser with block history of infinity 15:00:10 [15:00:10.131] INFO: world_state Started world state synchronizer from block 1 15:00:10 [15:00:10.131] INFO: world_state Stopped world state synchronizer 15:00:10 [15:00:10.149] INFO: world_state Stopped world state synchronizer 15:00:10 [15:00:10.150] INFO: world_state Created world state synchroniser with block history of infinity 15:00:10 [15:00:10.329] INFO: world_state Started world state synchronizer from block 1 15:00:10 [15:00:10.582] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.4336419999999634,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:10 [15:00:10.584] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.1997849999997925,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:10 [15:00:10.585] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.1741350000002058,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:10 [15:00:10.587] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.0237299999998868,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:10 [15:00:10.588] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8862460000000283,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:10 [15:00:10.767] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.0416110000005574,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:10 [15:00:10.769] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.9731889999993655,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:10 [15:00:10.770] INFO: world_state Stopped world state synchronizer 15:00:10 [15:00:10.771] INFO: world_state Created world state synchroniser with block history of infinity 15:00:10 [15:00:10.906] INFO: world_state Started world state synchronizer from block 1 15:00:11 [15:00:11.167] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1722239999999147,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:11 [15:00:11.169] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0620410000001357,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:11 [15:00:11.170] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.017809999999372,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:11 [15:00:11.172] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1313529999997627,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:11 [15:00:11.173] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.160334000000148,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:11 [15:00:11.435] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.5602650000000722,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:11 [15:00:11.436] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.2070059999996374,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:11 [15:00:11.438] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.2192860000004657,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:11 [15:00:11.440] INFO: world_state Stopped world state synchronizer 15:00:11 [15:00:11.441] INFO: world_state Created world state synchroniser with block history of infinity 15:00:11 [15:00:11.581] INFO: world_state Started world state synchronizer from block 1 15:00:11 [15:00:11.877] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.5194649999993999,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:11 [15:00:11.879] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.4250509999992573,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:11 [15:00:11.881] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.386410000000069,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:11 [15:00:11.883] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.402310999999827,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:11 [15:00:11.885] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.417250999999851,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:11 [15:00:11.887] INFO: world_state Stopped world state synchronizer 15:00:11 [15:00:11.889] INFO: world_state Created world state synchroniser with block history of infinity 15:00:12 [15:00:12.078] INFO: world_state Started world state synchronizer from block 1 15:00:12 [15:00:12.334] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.4369619999997667,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:12 [15:00:12.336] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.1615740000006554,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:12 [15:00:12.338] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2389570000004824,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:12 [15:00:12.340] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1114919999999984,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:12 [15:00:12.341] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1277030000001105,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:00:12 [15:00:12.343] INFO: world_state Stopped world state synchronizer 15:00:12 [15:00:12.345] INFO: world_state Created world state synchroniser with block history of infinity 15:00:12 [15:00:12.348] INFO: world_state Stopped world state synchronizer 15:00:12 [15:00:12.349] INFO: world_state Created world state synchroniser with block history of infinity 15:00:12 [15:00:12.521] INFO: world_state Started world state synchronizer from block 1 15:00:12 [15:00:12.778] INFO: world_state Stopped world state synchronizer 15:00:12 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.254 s) 15:00:12 ServerWorldStateSynchronizer 15:00:12 ✓ updates sync progress (643 ms) 15:00:12 ✓ handles multiple calls to start (492 ms) 15:00:12 ✓ immediately syncs if no new blocks (3 ms) 15:00:12 ✓ cannot be started if already stopped (19 ms) 15:00:12 ✓ can immediately sync to latest (620 ms) 15:00:12 ✓ can immediately sync to a minimum block number (669 ms) 15:00:12 ✓ sync returns immediately if block was already synced (448 ms) 15:00:12 ✓ throws if you try to sync to an unavailable block (455 ms) 15:00:12 ✓ throws if you try to immediate sync when not running (4 ms) 15:00:12 ✓ throws if handling blocks fails (430 ms) 15:00:12 15:00:12 Test Suites: 1 passed, 1 total 15:00:12 Tests: 10 passed, 10 total 15:00:12 Snapshots: 0 total 15:00:12 Time: 6.332 s 15:00:12 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 15:00:12 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?