Parent Log: http://ci.aztec-labs.com/34bf0c3e7d631ad0 Command: f803628267cda2b6 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/21a568ac0c9ef351b1923375be41d4620add2b60 Env: REF_NAME=gh-readonly-queue/next/pr-15042-b80949775e5c27908a6055ad24592f7d98bdc45a CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Sun Jun 15 12:35:37 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15042_amd64_x1-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 12:35:40 [12:35:40.716] INFO: world_state Created world state synchroniser with block history of infinity 12:35:40 [12:35:40.868] INFO: world_state Started world state synchronizer from block 1 12:35:41 [12:35:41.286] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":4.8261240000001635,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:41 [12:35:41.289] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.638162000000193,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:41 [12:35:41.291] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.5320799999999508,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:41 [12:35:41.559] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":32.669802000000345,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:41 [12:35:41.564] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":3.2100219999992987,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:41 [12:35:41.566] INFO: world_state Stopped world state synchronizer 12:35:41 [12:35:41.568] INFO: world_state Stopped world state synchronizer 12:35:41 [12:35:41.572] INFO: world_state Created world state synchroniser with block history of infinity 12:35:41 [12:35:41.754] INFO: world_state Started world state synchronizer from block 1 12:35:42 [12:35:42.068] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.5619800000004034,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:42 [12:35:42.070] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.5425900000000183,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:42 [12:35:42.072] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2196530000001076,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:42 [12:35:42.075] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.7551039999998466,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:42 [12:35:42.077] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.5490400000007867,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:42 [12:35:42.079] INFO: world_state Stopped world state synchronizer 12:35:42 [12:35:42.081] INFO: world_state Created world state synchroniser with block history of infinity 12:35:42 [12:35:42.082] INFO: world_state Started world state synchronizer from block 1 12:35:42 [12:35:42.083] INFO: world_state Stopped world state synchronizer 12:35:42 [12:35:42.084] INFO: world_state Created world state synchroniser with block history of infinity 12:35:42 [12:35:42.085] INFO: world_state Started world state synchronizer from block 1 12:35:42 [12:35:42.085] INFO: world_state Stopped world state synchronizer 12:35:42 [12:35:42.107] INFO: world_state Stopped world state synchronizer 12:35:42 [12:35:42.108] INFO: world_state Created world state synchroniser with block history of infinity 12:35:42 [12:35:42.318] INFO: world_state Started world state synchronizer from block 1 12:35:42 [12:35:42.664] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.208292999999685,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:42 [12:35:42.665] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0209089999998469,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:42 [12:35:42.668] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.8216449999999895,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:42 [12:35:42.670] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2357229999997799,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:42 [12:35:42.672] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0049999999991996,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:42 [12:35:42.860] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":2.201601999999184,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:42 [12:35:42.862] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.2902149999999892,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:42 [12:35:42.877] INFO: world_state Stopped world state synchronizer 12:35:42 [12:35:42.887] INFO: world_state Created world state synchroniser with block history of infinity 12:35:43 [12:35:43.123] INFO: world_state Started world state synchronizer from block 1 12:35:43 [12:35:43.433] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.4977380000000267,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:43 [12:35:43.435] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0487100000000282,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:43 [12:35:43.436] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8801770000000033,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:43 [12:35:43.438] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.0616709999994782,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:43 [12:35:43.439] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0337500000005093,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:43 [12:35:43.720] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.0372999999999593,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:43 [12:35:43.722] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.8816459999998187,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:43 [12:35:43.725] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.7251930000002176,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:43 [12:35:43.729] INFO: world_state Stopped world state synchronizer 12:35:43 [12:35:43.731] INFO: world_state Created world state synchroniser with block history of infinity 12:35:43 [12:35:43.920] INFO: world_state Started world state synchronizer from block 1 12:35:44 [12:35:44.191] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.924127999999655,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:44 [12:35:44.193] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.6400719999992361,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:44 [12:35:44.194] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.5905619999994087,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:44 [12:35:44.196] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2683450000004086,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:44 [12:35:44.198] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.5882709999996223,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:44 [12:35:44.201] INFO: world_state Stopped world state synchronizer 12:35:44 [12:35:44.202] INFO: world_state Created world state synchroniser with block history of infinity 12:35:44 [12:35:44.363] INFO: world_state Started world state synchronizer from block 1 12:35:44 [12:35:44.667] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.133201999999983,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:44 [12:35:44.669] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.899836999999934,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:44 [12:35:44.670] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9146270000001095,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:44 [12:35:44.672] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.9697489999998652,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:44 [12:35:44.673] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.7127739999996265,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:44 [12:35:44.675] INFO: world_state Stopped world state synchronizer 12:35:44 [12:35:44.676] INFO: world_state Created world state synchroniser with block history of infinity 12:35:44 [12:35:44.679] INFO: world_state Stopped world state synchronizer 12:35:44 [12:35:44.680] INFO: world_state Created world state synchroniser with block history of infinity 12:35:44 [12:35:44.841] INFO: world_state Started world state synchronizer from block 1 12:35:45 [12:35:45.102] INFO: world_state Stopped world state synchronizer 12:35:45 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.687 s) 12:35:45 ServerWorldStateSynchronizer 12:35:45 ✓ updates sync progress (858 ms) 12:35:45 ✓ handles multiple calls to start (509 ms) 12:35:45 ✓ immediately syncs if no new blocks (3 ms) 12:35:45 ✓ cannot be started if already stopped (24 ms) 12:35:45 ✓ can immediately sync to latest (773 ms) 12:35:45 ✓ can immediately sync to a minimum block number (845 ms) 12:35:45 ✓ sync returns immediately if block was already synced (471 ms) 12:35:45 ✓ throws if you try to sync to an unavailable block (474 ms) 12:35:45 ✓ throws if you try to immediate sync when not running (3 ms) 12:35:45 ✓ throws if handling blocks fails (423 ms) 12:35:45 12:35:45 Test Suites: 1 passed, 1 total 12:35:45 Tests: 10 passed, 10 total 12:35:45 Snapshots: 0 total 12:35:45 Time: 6.774 s 12:35:45 Ran all test suites matching /world-state\/src\/synchronizer\/server_world_state_synchronizer.test.ts/i. 12:35:45 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?