Parent Log: http://ci.aztec-labs.com/c3e440b46afd0ff2 Command: b5d029682575c433 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/fa4d5ef48c8de52059b5658bbd42e4514386f7db Env: REF_NAME=gh-readonly-queue/next/pr-15073-5ca43853c40a2b8bfbc2148d5fdd46c6f82a74eb CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Mon Jun 16 15:34:53 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15073_amd64_x4-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 15:34:57 [15:34:57.770] INFO: world_state Created world state synchroniser with block history of infinity 15:34:57 [15:34:57.924] INFO: world_state Started world state synchronizer from block 1 15:34:58 [15:34:58.306] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":5.524229999999989,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:34:58 [15:34:58.310] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.9705519999997705,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:34:58 [15:34:58.313] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.6172540000006848,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:34:58 [15:34:58.523] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.0557589999998527,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:34:58 [15:34:58.526] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.6113439999999173,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:34:58 [15:34:58.528] INFO: world_state Stopped world state synchronizer 15:34:58 [15:34:58.529] INFO: world_state Stopped world state synchronizer 15:34:58 [15:34:58.532] INFO: world_state Created world state synchroniser with block history of infinity 15:34:58 [15:34:58.731] INFO: world_state Started world state synchronizer from block 1 15:34:59 [15:34:59.063] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.73790100000042,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:34:59 [15:34:59.066] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.547168999999485,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:34:59 [15:34:59.068] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.5168080000003101,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:34:59 [15:34:59.070] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.7665719999995417,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:34:59 [15:34:59.072] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.7618119999997361,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:34:59 [15:34:59.074] INFO: world_state Stopped world state synchronizer 15:34:59 [15:34:59.075] INFO: world_state Created world state synchroniser with block history of infinity 15:34:59 [15:34:59.076] INFO: world_state Started world state synchronizer from block 1 15:34:59 [15:34:59.077] INFO: world_state Stopped world state synchronizer 15:34:59 [15:34:59.078] INFO: world_state Created world state synchroniser with block history of infinity 15:34:59 [15:34:59.078] INFO: world_state Started world state synchronizer from block 1 15:34:59 [15:34:59.079] INFO: world_state Stopped world state synchronizer 15:34:59 [15:34:59.107] INFO: world_state Stopped world state synchronizer 15:34:59 [15:34:59.109] INFO: world_state Created world state synchroniser with block history of infinity 15:34:59 [15:34:59.274] INFO: world_state Started world state synchronizer from block 1 15:34:59 [15:34:59.617] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.0356409999994867,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:34:59 [15:34:59.618] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.7316730000002281,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:34:59 [15:34:59.619] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.7027109999999084,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:34:59 [15:34:59.620] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.7368230000001859,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:34:59 [15:34:59.621] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.6839289999998073,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:34:59 [15:34:59.812] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.2030800000002273,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:34:59 [15:34:59.814] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.2526720000005298,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:34:59 [15:34:59.815] INFO: world_state Stopped world state synchronizer 15:34:59 [15:34:59.816] INFO: world_state Created world state synchroniser with block history of infinity 15:35:00 [15:35:00.046] INFO: world_state Started world state synchronizer from block 1 15:35:00 [15:35:00.398] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.3928500000001804,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:35:00 [15:35:00.404] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.2417619999996532,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:35:00 [15:35:00.406] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8687600000002931,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:35:00 [15:35:00.408] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.7480530000002545,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:35:00 [15:35:00.409] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1192049999999654,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:35:00 [15:35:00.705] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.4640650000001187,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:35:00 [15:35:00.707] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.9545749999997497,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:35:00 [15:35:00.720] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":12.167895000000499,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:35:00 [15:35:00.723] INFO: world_state Stopped world state synchronizer 15:35:00 [15:35:00.726] INFO: world_state Created world state synchroniser with block history of infinity 15:35:00 [15:35:00.880] INFO: world_state Started world state synchronizer from block 1 15:35:01 [15:35:01.198] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.4671849999995175,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:35:01 [15:35:01.201] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.1694479999996474,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:35:01 [15:35:01.203] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.4525849999999991,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:35:01 [15:35:01.205] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3235670000003665,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:35:01 [15:35:01.207] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2030400000003283,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:35:01 [15:35:01.210] INFO: world_state Stopped world state synchronizer 15:35:01 [15:35:01.211] INFO: world_state Created world state synchroniser with block history of infinity 15:35:01 [15:35:01.372] INFO: world_state Started world state synchronizer from block 1 15:35:01 [15:35:01.675] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.2680639999998675,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:35:01 [15:35:01.678] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.1705679999995482,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:35:01 [15:35:01.680] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.5323090000001685,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:35:01 [15:35:01.683] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.3503570000002583,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:35:01 [15:35:01.687] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.4871359999997367,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:35:01 [15:35:01.692] INFO: world_state Stopped world state synchronizer 15:35:01 [15:35:01.695] INFO: world_state Created world state synchroniser with block history of infinity 15:35:01 [15:35:01.701] INFO: world_state Stopped world state synchronizer 15:35:01 [15:35:01.704] INFO: world_state Created world state synchroniser with block history of infinity 15:35:01 [15:35:01.900] INFO: world_state Started world state synchronizer from block 1 15:35:02 [15:35:02.251] INFO: world_state Stopped world state synchronizer 15:35:02 PASS src/synchronizer/server_world_state_synchronizer.test.ts (7.01 s) 15:35:02 ServerWorldStateSynchronizer 15:35:02 ✓ updates sync progress (764 ms) 15:35:02 ✓ handles multiple calls to start (543 ms) 15:35:02 ✓ immediately syncs if no new blocks (3 ms) 15:35:02 ✓ cannot be started if already stopped (31 ms) 15:35:02 ✓ can immediately sync to latest (706 ms) 15:35:02 ✓ can immediately sync to a minimum block number (908 ms) 15:35:02 ✓ sync returns immediately if block was already synced (485 ms) 15:35:02 ✓ throws if you try to sync to an unavailable block (482 ms) 15:35:02 ✓ throws if you try to immediate sync when not running (8 ms) 15:35:02 ✓ throws if handling blocks fails (549 ms) 15:35:02 15:35:02 Test Suites: 1 passed, 1 total 15:35:02 Tests: 10 passed, 10 total 15:35:02 Snapshots: 0 total 15:35:02 Time: 7.128 s 15:35:02 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 15:35:02 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?