Parent Log: http://ci.aztec-labs.com/b9bb376bdf3237ce Command: 0f4f65dca0a2798e yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/67bf12030291ce3da2cf39ff5fbfa799eae98dc5 Env: REF_NAME=gh-readonly-queue/next/pr-14963-42440bd429a3b283b3a344db0be4652d818bd261 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Mon Jun 16 20:02:24 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14963_amd64_x1-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 20:02:27 [20:02:27.924] INFO: world_state Created world state synchroniser with block history of infinity 20:02:28 [20:02:28.057] INFO: world_state Started world state synchronizer from block 1 20:02:28 [20:02:28.527] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":6.931119999999282,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:28 [20:02:28.531] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.8340220000000045,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:28 [20:02:28.535] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.6132959999995364,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:28 [20:02:28.737] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3325589999994918,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:28 [20:02:28.739] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9845320000003994,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:28 [20:02:28.740] INFO: world_state Stopped world state synchronizer 20:02:28 [20:02:28.741] INFO: world_state Stopped world state synchronizer 20:02:28 [20:02:28.744] INFO: world_state Created world state synchroniser with block history of infinity 20:02:28 [20:02:28.887] INFO: world_state Started world state synchronizer from block 1 20:02:29 [20:02:29.231] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.260377999999946,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:29 [20:02:29.233] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.1119939999998678,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:29 [20:02:29.234] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9835109999994529,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:29 [20:02:29.236] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1623060000001715,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:29 [20:02:29.238] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.371578999999656,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:29 [20:02:29.239] INFO: world_state Stopped world state synchronizer 20:02:29 [20:02:29.241] INFO: world_state Created world state synchroniser with block history of infinity 20:02:29 [20:02:29.242] INFO: world_state Started world state synchronizer from block 1 20:02:29 [20:02:29.242] INFO: world_state Stopped world state synchronizer 20:02:29 [20:02:29.243] INFO: world_state Created world state synchroniser with block history of infinity 20:02:29 [20:02:29.244] INFO: world_state Started world state synchronizer from block 1 20:02:29 [20:02:29.244] INFO: world_state Stopped world state synchronizer 20:02:29 [20:02:29.268] INFO: world_state Stopped world state synchronizer 20:02:29 [20:02:29.271] INFO: world_state Created world state synchroniser with block history of infinity 20:02:29 [20:02:29.467] INFO: world_state Started world state synchronizer from block 1 20:02:29 [20:02:29.871] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":2.0997349999997823,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:29 [20:02:29.874] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.5705639999996492,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:29 [20:02:29.875] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9401799999996001,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:29 [20:02:29.877] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.0814330000002883,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:29 [20:02:29.878] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.883469000000332,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:30 [20:02:30.080] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.56708400000025,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:30 [20:02:30.082] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.2082860000000437,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:30 [20:02:30.083] INFO: world_state Stopped world state synchronizer 20:02:30 [20:02:30.084] INFO: world_state Created world state synchroniser with block history of infinity 20:02:30 [20:02:30.337] INFO: world_state Started world state synchronizer from block 1 20:02:30 [20:02:30.679] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":2.4637030000003506,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:30 [20:02:30.681] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.3125079999999798,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:30 [20:02:30.684] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.7294980000006035,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:30 [20:02:30.686] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.5577239999993253,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:30 [20:02:30.688] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.5112330000001748,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:31 [20:02:31.021] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.7302880000006553,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:31 [20:02:31.024] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.8426699999999983,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:31 [20:02:31.047] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":22.416755000000194,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:31 [20:02:31.052] INFO: world_state Stopped world state synchronizer 20:02:31 [20:02:31.058] INFO: world_state Created world state synchroniser with block history of infinity 20:02:31 [20:02:31.266] INFO: world_state Started world state synchronizer from block 1 20:02:31 [20:02:31.539] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.7286680000006527,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:31 [20:02:31.541] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.3534190000000308,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:31 [20:02:31.543] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.395419999999831,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:31 [20:02:31.545] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.5202229999995325,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:31 [20:02:31.547] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2883879999999408,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:31 [20:02:31.550] INFO: world_state Stopped world state synchronizer 20:02:31 [20:02:31.552] INFO: world_state Created world state synchroniser with block history of infinity 20:02:31 [20:02:31.738] INFO: world_state Started world state synchronizer from block 1 20:02:32 [20:02:32.032] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.4839019999999437,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:32 [20:02:32.034] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.373779999999897,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:32 [20:02:32.036] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.3265179999998509,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:32 [20:02:32.038] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2800979999992705,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:32 [20:02:32.040] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2923780000000988,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 20:02:32 [20:02:32.042] INFO: world_state Stopped world state synchronizer 20:02:32 [20:02:32.044] INFO: world_state Created world state synchroniser with block history of infinity 20:02:32 [20:02:32.082] INFO: world_state Stopped world state synchronizer 20:02:32 [20:02:32.131] INFO: world_state Created world state synchroniser with block history of infinity 20:02:32 [20:02:32.356] INFO: world_state Started world state synchronizer from block 1 20:02:32 [20:02:32.659] INFO: world_state Stopped world state synchronizer 20:02:32 PASS src/synchronizer/server_world_state_synchronizer.test.ts (7.225 s) 20:02:32 ServerWorldStateSynchronizer 20:02:32 ✓ updates sync progress (822 ms) 20:02:32 ✓ handles multiple calls to start (496 ms) 20:02:32 ✓ immediately syncs if no new blocks (3 ms) 20:02:32 ✓ cannot be started if already stopped (26 ms) 20:02:32 ✓ can immediately sync to latest (814 ms) 20:02:32 ✓ can immediately sync to a minimum block number (970 ms) 20:02:32 ✓ sync returns immediately if block was already synced (494 ms) 20:02:32 ✓ throws if you try to sync to an unavailable block (492 ms) 20:02:32 ✓ throws if you try to immediate sync when not running (42 ms) 20:02:32 ✓ throws if handling blocks fails (571 ms) 20:02:32 20:02:32 Test Suites: 1 passed, 1 total 20:02:32 Tests: 10 passed, 10 total 20:02:32 Snapshots: 0 total 20:02:32 Time: 7.369 s 20:02:32 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 20:02:32 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?