Parent Log: http://ci.aztec-labs.com/7e515574fa561c6b Command: 2ca1986c0c6d5c2c yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/9222c7777effe26aa04b5367a455d25be0e7f62e Env: REF_NAME=gh-readonly-queue/next/pr-15055-a9bb2f683797c8e631b892c5f539a2867bddaabf CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Wed Jun 18 16:04:21 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15055_amd64_x3-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 16:04:25 [16:04:25.328] INFO: world_state Created world state synchroniser with block history of infinity 16:04:25 [16:04:25.482] INFO: world_state Started world state synchronizer from block 1 16:04:25 [16:04:25.923] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":7.040531999999985,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:25 [16:04:25.926] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.8258240000004662,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:25 [16:04:25.929] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.9707479999997304,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:26 [16:04:26.094] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.3361970000005385,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:26 [16:04:26.098] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.9147259999999733,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:26 [16:04:26.100] INFO: world_state Stopped world state synchronizer 16:04:26 [16:04:26.113] INFO: world_state Stopped world state synchronizer 16:04:26 [16:04:26.116] INFO: world_state Created world state synchroniser with block history of infinity 16:04:26 [16:04:26.257] INFO: world_state Started world state synchronizer from block 1 16:04:26 [16:04:26.562] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.6082790000000386,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:26 [16:04:26.563] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.981314000000566,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:26 [16:04:26.565] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.3038619999997536,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:26 [16:04:26.568] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.7215219999998226,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:26 [16:04:26.569] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.4069440000002942,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:26 [16:04:26.571] INFO: world_state Stopped world state synchronizer 16:04:26 [16:04:26.573] INFO: world_state Created world state synchroniser with block history of infinity 16:04:26 [16:04:26.573] INFO: world_state Started world state synchronizer from block 1 16:04:26 [16:04:26.574] INFO: world_state Stopped world state synchronizer 16:04:26 [16:04:26.576] INFO: world_state Created world state synchroniser with block history of infinity 16:04:26 [16:04:26.576] INFO: world_state Started world state synchronizer from block 1 16:04:26 [16:04:26.576] INFO: world_state Stopped world state synchronizer 16:04:26 [16:04:26.597] INFO: world_state Stopped world state synchronizer 16:04:26 [16:04:26.599] INFO: world_state Created world state synchroniser with block history of infinity 16:04:26 [16:04:26.796] INFO: world_state Started world state synchronizer from block 1 16:04:27 [16:04:27.071] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":2.0637699999997494,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:27 [16:04:27.073] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.362873000000036,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:27 [16:04:27.075] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2855420000005324,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:27 [16:04:27.076] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2191000000002532,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:27 [16:04:27.078] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2022800000004281,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:27 [16:04:27.256] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.4831460000004881,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:27 [16:04:27.258] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.3553929999998218,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:27 [16:04:27.260] INFO: world_state Stopped world state synchronizer 16:04:27 [16:04:27.261] INFO: world_state Created world state synchroniser with block history of infinity 16:04:27 [16:04:27.448] INFO: world_state Started world state synchronizer from block 1 16:04:27 [16:04:27.819] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1058160000002317,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:27 [16:04:27.821] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.301621999999952,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:27 [16:04:27.823] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.1864990000003672,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:27 [16:04:27.824] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.8414899999997942,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:27 [16:04:27.826] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8659410000000207,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:28 [16:04:28.086] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.6888209999997343,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:28 [16:04:28.088] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.5698480000000927,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:28 [16:04:28.090] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.287822000000233,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:28 [16:04:28.092] INFO: world_state Stopped world state synchronizer 16:04:28 [16:04:28.093] INFO: world_state Created world state synchroniser with block history of infinity 16:04:28 [16:04:28.278] INFO: world_state Started world state synchronizer from block 1 16:04:28 [16:04:28.611] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1200870000002396,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:28 [16:04:28.612] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0460259999999835,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:28 [16:04:28.614] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2116690000002563,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:28 [16:04:28.616] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.4198749999995925,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:28 [16:04:28.618] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.4158139999999548,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:28 [16:04:28.620] INFO: world_state Stopped world state synchronizer 16:04:28 [16:04:28.622] INFO: world_state Created world state synchroniser with block history of infinity 16:04:28 [16:04:28.792] INFO: world_state Started world state synchronizer from block 1 16:04:29 [16:04:29.042] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.7464020000006713,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:29 [16:04:29.044] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.3201119999994262,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:29 [16:04:29.046] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.3319730000002892,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:29 [16:04:29.047] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.046556000000237,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:29 [16:04:29.049] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1725980000001073,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:04:29 [16:04:29.051] INFO: world_state Stopped world state synchronizer 16:04:29 [16:04:29.053] INFO: world_state Created world state synchroniser with block history of infinity 16:04:29 [16:04:29.057] INFO: world_state Stopped world state synchronizer 16:04:29 [16:04:29.058] INFO: world_state Created world state synchroniser with block history of infinity 16:04:29 [16:04:29.215] INFO: world_state Started world state synchronizer from block 1 16:04:29 [16:04:29.546] INFO: world_state Stopped world state synchronizer 16:04:29 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.959 s) 16:04:29 ServerWorldStateSynchronizer 16:04:29 ✓ updates sync progress (793 ms) 16:04:29 ✓ handles multiple calls to start (456 ms) 16:04:29 ✓ immediately syncs if no new blocks (3 ms) 16:04:29 ✓ cannot be started if already stopped (22 ms) 16:04:29 ✓ can immediately sync to latest (662 ms) 16:04:29 ✓ can immediately sync to a minimum block number (831 ms) 16:04:29 ✓ sync returns immediately if block was already synced (528 ms) 16:04:29 ✓ throws if you try to sync to an unavailable block (431 ms) 16:04:29 ✓ throws if you try to immediate sync when not running (5 ms) 16:04:29 ✓ throws if handling blocks fails (490 ms) 16:04:29 16:04:29 Test Suites: 1 passed, 1 total 16:04:29 Tests: 10 passed, 10 total 16:04:29 Snapshots: 0 total 16:04:29 Time: 7.058 s 16:04:29 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 16:04:29 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?