Parent Log: http://ci.aztec-labs.com/bb5a5dfbdc7368c1 Command: a5511b51e55ea9c0 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/0dc6f60f84deaa57a8570f9942eb75317f5aeea5 Env: REF_NAME=gh-readonly-queue/next/pr-15162-6ee95286f8bcc5bb6dca43222459ac5bf43a8a75 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Fri Jun 20 09:43:26 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15162_amd64_x1-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 09:43:30 [09:43:30.100] INFO: world_state Created world state synchroniser with block history of infinity 09:43:30 [09:43:30.259] INFO: world_state Started world state synchronizer from block 1 09:43:30 [09:43:30.668] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":7.268015999999989,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:30 [09:43:30.673] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.8110039999996843,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:30 [09:43:30.676] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.450365000000147,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:30 [09:43:30.913] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.9788580000004004,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:30 [09:43:30.929] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":14.908490999999685,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:30 [09:43:30.931] INFO: world_state Stopped world state synchronizer 09:43:30 [09:43:30.933] INFO: world_state Stopped world state synchronizer 09:43:30 [09:43:30.938] INFO: world_state Created world state synchroniser with block history of infinity 09:43:31 [09:43:31.124] INFO: world_state Started world state synchronizer from block 1 09:43:31 [09:43:31.469] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.7775400000000445,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:31 [09:43:31.472] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.6421970000001238,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:31 [09:43:31.474] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.5383059999994657,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:31 [09:43:31.477] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.6300899999996545,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:31 [09:43:31.480] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.8237920000001395,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:31 [09:43:31.482] INFO: world_state Stopped world state synchronizer 09:43:31 [09:43:31.484] INFO: world_state Created world state synchroniser with block history of infinity 09:43:31 [09:43:31.485] INFO: world_state Started world state synchronizer from block 1 09:43:31 [09:43:31.487] INFO: world_state Stopped world state synchronizer 09:43:31 [09:43:31.491] INFO: world_state Created world state synchroniser with block history of infinity 09:43:31 [09:43:31.494] INFO: world_state Started world state synchronizer from block 1 09:43:31 [09:43:31.494] INFO: world_state Stopped world state synchronizer 09:43:31 [09:43:31.524] INFO: world_state Stopped world state synchronizer 09:43:31 [09:43:31.527] INFO: world_state Created world state synchroniser with block history of infinity 09:43:31 [09:43:31.720] INFO: world_state Started world state synchronizer from block 1 09:43:32 [09:43:32.048] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":11.51631400000042,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:32 [09:43:32.050] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.1068749999994907,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:32 [09:43:32.053] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0847249999997075,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:32 [09:43:32.054] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.8126989999991565,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:32 [09:43:32.056] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8757489999998143,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:32 [09:43:32.236] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.0220339999996213,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:32 [09:43:32.239] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.7553300000008676,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:32 [09:43:32.241] INFO: world_state Stopped world state synchronizer 09:43:32 [09:43:32.243] INFO: world_state Created world state synchroniser with block history of infinity 09:43:32 [09:43:32.463] INFO: world_state Started world state synchronizer from block 1 09:43:32 [09:43:32.801] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.5453660000002856,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:32 [09:43:32.803] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.3485510000000431,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:32 [09:43:32.805] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.3264110000000073,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:32 [09:43:32.807] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3062989999998535,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:32 [09:43:32.809] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.3865820000000895,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:33 [09:43:33.080] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.6364370000001145,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:33 [09:43:33.083] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.415472000000591,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:33 [09:43:33.085] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.6946090000001277,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:33 [09:43:33.087] INFO: world_state Stopped world state synchronizer 09:43:33 [09:43:33.088] INFO: world_state Created world state synchroniser with block history of infinity 09:43:33 [09:43:33.276] INFO: world_state Started world state synchronizer from block 1 09:43:33 [09:43:33.570] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.328230000000076,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:33 [09:43:33.572] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.179497000000083,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:33 [09:43:33.574] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0705740000003061,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:33 [09:43:33.575] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1207860000004075,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:33 [09:43:33.577] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0184330000001864,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:33 [09:43:33.579] INFO: world_state Stopped world state synchronizer 09:43:33 [09:43:33.580] INFO: world_state Created world state synchroniser with block history of infinity 09:43:33 [09:43:33.755] INFO: world_state Started world state synchronizer from block 1 09:43:34 [09:43:34.054] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.3771919999999227,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:34 [09:43:34.056] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.213318000000072,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:34 [09:43:34.058] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.1485959999999977,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:34 [09:43:34.060] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1094950000006065,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:34 [09:43:34.061] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.108556000000135,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:34 [09:43:34.063] INFO: world_state Stopped world state synchronizer 09:43:34 [09:43:34.065] INFO: world_state Created world state synchroniser with block history of infinity 09:43:34 [09:43:34.067] INFO: world_state Stopped world state synchronizer 09:43:34 [09:43:34.068] INFO: world_state Created world state synchroniser with block history of infinity 09:43:34 [09:43:34.248] INFO: world_state Started world state synchronizer from block 1 09:43:34 [09:43:34.579] INFO: world_state Stopped world state synchronizer 09:43:34 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.728 s) 09:43:34 ServerWorldStateSynchronizer 09:43:34 ✓ updates sync progress (839 ms) 09:43:34 ✓ handles multiple calls to start (547 ms) 09:43:34 ✓ immediately syncs if no new blocks (4 ms) 09:43:34 ✓ cannot be started if already stopped (34 ms) 09:43:34 ✓ can immediately sync to latest (716 ms) 09:43:34 ✓ can immediately sync to a minimum block number (845 ms) 09:43:34 ✓ sync returns immediately if block was already synced (491 ms) 09:43:34 ✓ throws if you try to sync to an unavailable block (484 ms) 09:43:34 ✓ throws if you try to immediate sync when not running (3 ms) 09:43:34 ✓ throws if handling blocks fails (512 ms) 09:43:34 09:43:34 Test Suites: 1 passed, 1 total 09:43:34 Tests: 10 passed, 10 total 09:43:34 Snapshots: 0 total 09:43:34 Time: 6.813 s 09:43:34 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 09:43:34 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?