Parent Log: http://ci.aztec-labs.com/54c502658eb930c3 Command: 19ef62d9450eeb9e yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/eb7990c89ac679ce7fdfc2e985670cbf85c04be2 Env: REF_NAME=gh-readonly-queue/next/pr-14918-b70544b6d50e3ecb6598361f1990bd51745c7b55 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Tue Jun 17 09:03:20 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14918_amd64_x4-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 09:03:24 [09:03:24.329] INFO: world_state Created world state synchroniser with block history of infinity 09:03:24 [09:03:24.512] INFO: world_state Started world state synchronizer from block 1 09:03:24 [09:03:24.963] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":7.112657999999101,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:24 [09:03:24.968] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.8099240000001373,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:24 [09:03:24.971] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.016859000000295,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:25 [09:03:25.188] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":6.687941000000137,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:25 [09:03:25.193] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":2.094560999999885,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:25 [09:03:25.195] INFO: world_state Stopped world state synchronizer 09:03:25 [09:03:25.197] INFO: world_state Stopped world state synchronizer 09:03:25 [09:03:25.202] INFO: world_state Created world state synchroniser with block history of infinity 09:03:25 [09:03:25.378] INFO: world_state Started world state synchronizer from block 1 09:03:25 [09:03:25.739] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.4810889999998835,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:25 [09:03:25.742] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.2035829999995258,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:25 [09:03:25.744] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.168003000000681,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:25 [09:03:25.747] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.437576999999692,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:25 [09:03:25.749] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.7798149999998714,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:25 [09:03:25.751] INFO: world_state Stopped world state synchronizer 09:03:25 [09:03:25.753] INFO: world_state Created world state synchroniser with block history of infinity 09:03:25 [09:03:25.754] INFO: world_state Started world state synchronizer from block 1 09:03:25 [09:03:25.754] INFO: world_state Stopped world state synchronizer 09:03:25 [09:03:25.755] INFO: world_state Created world state synchroniser with block history of infinity 09:03:25 [09:03:25.756] INFO: world_state Started world state synchronizer from block 1 09:03:25 [09:03:25.756] INFO: world_state Stopped world state synchronizer 09:03:25 [09:03:25.780] INFO: world_state Stopped world state synchronizer 09:03:25 [09:03:25.781] INFO: world_state Created world state synchroniser with block history of infinity 09:03:25 [09:03:25.959] INFO: world_state Started world state synchronizer from block 1 09:03:26 [09:03:26.316] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.256543999999849,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:26 [09:03:26.318] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.1651529999999184,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:26 [09:03:26.320] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.5220300000000861,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:26 [09:03:26.322] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3681269999997312,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:26 [09:03:26.324] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.341827000000194,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:26 [09:03:26.578] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":0.9170279999998456,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:26 [09:03:26.579] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.7281640000001062,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:26 [09:03:26.580] INFO: world_state Stopped world state synchronizer 09:03:26 [09:03:26.581] INFO: world_state Created world state synchroniser with block history of infinity 09:03:26 [09:03:26.825] INFO: world_state Started world state synchronizer from block 1 09:03:27 [09:03:27.270] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.5810110000002169,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:27 [09:03:27.272] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.4695080000001326,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:27 [09:03:27.274] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2816750000001775,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:27 [09:03:27.275] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1974639999998544,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:27 [09:03:27.277] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2029030000003331,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:27 [09:03:27.544] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.8216059999995196,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:27 [09:03:27.547] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.3925570000001244,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:27 [09:03:27.571] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":24.22935099999995,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:27 [09:03:27.574] INFO: world_state Stopped world state synchronizer 09:03:27 [09:03:27.576] INFO: world_state Created world state synchroniser with block history of infinity 09:03:27 [09:03:27.739] INFO: world_state Started world state synchronizer from block 1 09:03:28 [09:03:28.084] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.3233060000002297,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:28 [09:03:28.086] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.149703000000045,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:28 [09:03:28.089] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.2288330000001224,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:28 [09:03:28.091] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.272274999999354,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:28 [09:03:28.094] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1336520000004384,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:28 [09:03:28.102] INFO: world_state Stopped world state synchronizer 09:03:28 [09:03:28.104] INFO: world_state Created world state synchroniser with block history of infinity 09:03:28 [09:03:28.272] INFO: world_state Started world state synchronizer from block 1 09:03:28 [09:03:28.581] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.0009200000004057,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:28 [09:03:28.583] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.8748770000001969,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:28 [09:03:28.584] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.777644999999211,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:28 [09:03:28.585] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.9012969999994311,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:28 [09:03:28.587] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9940489999999045,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:03:28 [09:03:28.588] INFO: world_state Stopped world state synchronizer 09:03:28 [09:03:28.590] INFO: world_state Created world state synchroniser with block history of infinity 09:03:28 [09:03:28.593] INFO: world_state Stopped world state synchronizer 09:03:28 [09:03:28.594] INFO: world_state Created world state synchroniser with block history of infinity 09:03:28 [09:03:28.781] INFO: world_state Started world state synchronizer from block 1 09:03:29 [09:03:29.201] INFO: world_state Stopped world state synchronizer 09:03:29 PASS src/synchronizer/server_world_state_synchronizer.test.ts (7.432 s) 09:03:29 ServerWorldStateSynchronizer 09:03:29 ✓ updates sync progress (873 ms) 09:03:29 ✓ handles multiple calls to start (551 ms) 09:03:29 ✓ immediately syncs if no new blocks (3 ms) 09:03:29 ✓ cannot be started if already stopped (25 ms) 09:03:29 ✓ can immediately sync to latest (800 ms) 09:03:29 ✓ can immediately sync to a minimum block number (994 ms) 09:03:29 ✓ sync returns immediately if block was already synced (527 ms) 09:03:29 ✓ throws if you try to sync to an unavailable block (485 ms) 09:03:29 ✓ throws if you try to immediate sync when not running (4 ms) 09:03:29 ✓ throws if handling blocks fails (609 ms) 09:03:29 09:03:29 Test Suites: 1 passed, 1 total 09:03:29 Tests: 10 passed, 10 total 09:03:29 Snapshots: 0 total 09:03:29 Time: 7.559 s 09:03:29 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 09:03:29 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?