Parent Log: http://ci.aztec-labs.com/a259c1275bd5145f Command: be792e9a8a7f847e yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/a8c553f203c98e5ca274e763c8e51daaaaf760d6 Env: REF_NAME=gh-readonly-queue/next/pr-15017-30660269b33bab8cca354c41659533acf4d48e07 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Fri Jun 13 09:38:25 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15017_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:38:28 [09:38:28.177] INFO: world_state Created world state synchroniser with block history of infinity 09:38:28 [09:38:28.293] INFO: world_state Started world state synchronizer from block 1 09:38:28 [09:38:28.655] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":4.8130160000000615,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:28 [09:38:28.663] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.020093999999972,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:28 [09:38:28.668] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.494152999999642,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:28 [09:38:28.824] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.9028020000000652,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:28 [09:38:28.841] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":15.150355999999647,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:28 [09:38:28.843] INFO: world_state Stopped world state synchronizer 09:38:28 [09:38:28.844] INFO: world_state Stopped world state synchronizer 09:38:28 [09:38:28.847] INFO: world_state Created world state synchroniser with block history of infinity 09:38:29 [09:38:29.009] INFO: world_state Started world state synchronizer from block 1 09:38:29 [09:38:29.282] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.050253000000339,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:29 [09:38:29.284] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9401910000005955,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:29 [09:38:29.286] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.8755209999999352,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:29 [09:38:29.289] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.826930999999604,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:29 [09:38:29.292] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.6924269999999524,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:29 [09:38:29.294] INFO: world_state Stopped world state synchronizer 09:38:29 [09:38:29.297] INFO: world_state Created world state synchroniser with block history of infinity 09:38:29 [09:38:29.298] INFO: world_state Started world state synchronizer from block 1 09:38:29 [09:38:29.299] INFO: world_state Stopped world state synchronizer 09:38:29 [09:38:29.300] INFO: world_state Created world state synchroniser with block history of infinity 09:38:29 [09:38:29.301] INFO: world_state Started world state synchronizer from block 1 09:38:29 [09:38:29.301] INFO: world_state Stopped world state synchronizer 09:38:29 [09:38:29.326] INFO: world_state Stopped world state synchronizer 09:38:29 [09:38:29.328] INFO: world_state Created world state synchroniser with block history of infinity 09:38:29 [09:38:29.514] INFO: world_state Started world state synchronizer from block 1 09:38:29 [09:38:29.824] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.4718519999996715,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:29 [09:38:29.827] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.6944180000000415,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:29 [09:38:29.829] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.4128920000002836,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:29 [09:38:29.831] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2009459999999308,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:29 [09:38:29.833] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1209149999995134,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:30 [09:38:30.013] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.4485130000002755,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:30 [09:38:30.016] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.1302949999999328,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:30 [09:38:30.017] INFO: world_state Stopped world state synchronizer 09:38:30 [09:38:30.018] INFO: world_state Created world state synchroniser with block history of infinity 09:38:30 [09:38:30.169] INFO: world_state Started world state synchronizer from block 1 09:38:30 [09:38:30.437] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.7776880000001256,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:30 [09:38:30.438] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.5889530000004015,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:30 [09:38:30.439] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.5720329999994647,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:30 [09:38:30.440] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.6114729999999327,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:30 [09:38:30.440] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.6119639999997162,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:30 [09:38:30.716] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":0.9201199999997698,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:30 [09:38:30.718] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.1329159999995682,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:30 [09:38:30.719] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":0.6344939999999042,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:30 [09:38:30.721] INFO: world_state Stopped world state synchronizer 09:38:30 [09:38:30.723] INFO: world_state Created world state synchroniser with block history of infinity 09:38:30 [09:38:30.881] INFO: world_state Started world state synchronizer from block 1 09:38:31 [09:38:31.161] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.189556000000266,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:31 [09:38:31.163] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.576955999999882,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:31 [09:38:31.165] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0452830000003814,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:31 [09:38:31.170] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":3.4430069999998523,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:31 [09:38:31.172] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9740020000008371,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:31 [09:38:31.176] INFO: world_state Stopped world state synchronizer 09:38:31 [09:38:31.177] INFO: world_state Created world state synchroniser with block history of infinity 09:38:31 [09:38:31.287] INFO: world_state Started world state synchronizer from block 1 09:38:31 [09:38:31.587] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1339950000001409,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:31 [09:38:31.588] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.8593389999996361,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:31 [09:38:31.590] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9165700000003199,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:31 [09:38:31.591] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.798066999999719,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:31 [09:38:31.592] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8153080000001864,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:38:31 [09:38:31.594] INFO: world_state Stopped world state synchronizer 09:38:31 [09:38:31.596] INFO: world_state Created world state synchroniser with block history of infinity 09:38:31 [09:38:31.600] INFO: world_state Stopped world state synchronizer 09:38:31 [09:38:31.601] INFO: world_state Created world state synchroniser with block history of infinity 09:38:31 [09:38:31.776] INFO: world_state Started world state synchronizer from block 1 09:38:32 [09:38:32.097] INFO: world_state Stopped world state synchronizer 09:38:32 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.086 s) 09:38:32 ServerWorldStateSynchronizer 09:38:32 ✓ updates sync progress (673 ms) 09:38:32 ✓ handles multiple calls to start (449 ms) 09:38:32 ✓ immediately syncs if no new blocks (3 ms) 09:38:32 ✓ cannot be started if already stopped (26 ms) 09:38:32 ✓ can immediately sync to latest (690 ms) 09:38:32 ✓ can immediately sync to a minimum block number (703 ms) 09:38:32 ✓ sync returns immediately if block was already synced (455 ms) 09:38:32 ✓ throws if you try to sync to an unavailable block (418 ms) 09:38:32 ✓ throws if you try to immediate sync when not running (5 ms) 09:38:32 ✓ throws if handling blocks fails (498 ms) 09:38:32 09:38:32 Test Suites: 1 passed, 1 total 09:38:32 Tests: 10 passed, 10 total 09:38:32 Snapshots: 0 total 09:38:32 Time: 6.189 s 09:38:32 Ran all test suites matching /world-state\/src\/synchronizer\/server_world_state_synchronizer.test.ts/i. 09:38:32 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?