Parent Log: http://ci.aztec-labs.com/2dd73c7f6b4dcf15 Command: c118fea9c063e772 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/12d931e5ea9343eec403068931d3121e1d10e37e Env: REF_NAME=gh-readonly-queue/next/pr-15042-254faae7d330e160b14c6eaaa099dc92d4a9cac7 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Mon Jun 16 12:35:43 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:46 [12:35:46.608] INFO: world_state Created world state synchroniser with block history of infinity 12:35:46 [12:35:46.767] INFO: world_state Started world state synchronizer from block 1 12:35:47 [12:35:47.224] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":6.534231000000091,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:47 [12:35:47.229] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":3.0669960000000174,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:47 [12:35:47.233] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.2898800000002666,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:47 [12:35:47.438] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.9252210000004197,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:47 [12:35:47.441] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.6541159999997035,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:47 [12:35:47.448] INFO: world_state Stopped world state synchronizer 12:35:47 [12:35:47.451] INFO: world_state Stopped world state synchronizer 12:35:47 [12:35:47.456] INFO: world_state Created world state synchroniser with block history of infinity 12:35:47 [12:35:47.636] INFO: world_state Started world state synchronizer from block 1 12:35:47 [12:35:47.934] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.2455769999996846,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:47 [12:35:47.937] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.4525219999995898,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:47 [12:35:47.939] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.6398950000002515,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:47 [12:35:47.942] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.935841999999866,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:47 [12:35:47.948] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":2.075855000000047,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:47 [12:35:47.951] INFO: world_state Stopped world state synchronizer 12:35:47 [12:35:47.954] INFO: world_state Created world state synchroniser with block history of infinity 12:35:47 [12:35:47.955] INFO: world_state Started world state synchronizer from block 1 12:35:47 [12:35:47.956] INFO: world_state Stopped world state synchronizer 12:35:47 [12:35:47.962] INFO: world_state Created world state synchroniser with block history of infinity 12:35:47 [12:35:47.963] INFO: world_state Started world state synchronizer from block 1 12:35:47 [12:35:47.963] INFO: world_state Stopped world state synchronizer 12:35:47 [12:35:47.991] INFO: world_state Stopped world state synchronizer 12:35:47 [12:35:47.993] INFO: world_state Created world state synchroniser with block history of infinity 12:35:48 [12:35:48.180] INFO: world_state Started world state synchronizer from block 1 12:35:48 [12:35:48.505] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.2403869999998278,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:48 [12:35:48.506] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9945010000001275,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:48 [12:35:48.508] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.018910999999207,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:48 [12:35:48.509] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.7741169999999329,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:48 [12:35:48.510] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.7073559999998906,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:48 [12:35:48.686] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":0.906858999999713,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:48 [12:35:48.687] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.798597000000882,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:48 [12:35:48.689] INFO: world_state Stopped world state synchronizer 12:35:48 [12:35:48.691] INFO: world_state Created world state synchroniser with block history of infinity 12:35:48 [12:35:48.870] INFO: world_state Started world state synchronizer from block 1 12:35:49 [12:35:49.168] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.4344620000001669,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:49 [12:35:49.171] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.213596999999936,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:49 [12:35:49.172] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0916240000005928,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:49 [12:35:49.174] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.12266499999987,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:49 [12:35:49.175] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0098019999995813,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:49 [12:35:49.474] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":31.164483999999902,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:49 [12:35:49.477] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.4552410000005693,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:49 [12:35:49.479] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.4931129999995392,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:49 [12:35:49.481] INFO: world_state Stopped world state synchronizer 12:35:49 [12:35:49.483] INFO: world_state Created world state synchroniser with block history of infinity 12:35:49 [12:35:49.654] INFO: world_state Started world state synchronizer from block 1 12:35:49 [12:35:49.985] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.8639689999999973,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:49 [12:35:49.986] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.7351459999999861,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:49 [12:35:49.987] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.6865149999994173,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:49 [12:35:49.988] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.7282059999997728,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:49 [12:35:49.989] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.6399639999999636,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:49 [12:35:49.991] INFO: world_state Stopped world state synchronizer 12:35:49 [12:35:49.992] INFO: world_state Created world state synchroniser with block history of infinity 12:35:50 [12:35:50.174] INFO: world_state Started world state synchronizer from block 1 12:35:50 [12:35:50.493] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.7319569999999658,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:50 [12:35:50.496] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.1115040000004228,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:50 [12:35:50.498] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2445569999999861,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:50 [12:35:50.501] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.0401240000001053,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:50 [12:35:50.504] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.5346329999993031,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:35:50 [12:35:50.510] INFO: world_state Stopped world state synchronizer 12:35:50 [12:35:50.514] INFO: world_state Created world state synchroniser with block history of infinity 12:35:50 [12:35:50.518] INFO: world_state Stopped world state synchronizer 12:35:50 [12:35:50.519] INFO: world_state Created world state synchroniser with block history of infinity 12:35:50 [12:35:50.744] INFO: world_state Started world state synchronizer from block 1 12:35:51 [12:35:51.111] INFO: world_state Stopped world state synchronizer 12:35:51 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.846 s) 12:35:51 ServerWorldStateSynchronizer 12:35:51 ✓ updates sync progress (849 ms) 12:35:51 ✓ handles multiple calls to start (498 ms) 12:35:51 ✓ immediately syncs if no new blocks (5 ms) 12:35:51 ✓ cannot be started if already stopped (35 ms) 12:35:51 ✓ can immediately sync to latest (697 ms) 12:35:51 ✓ can immediately sync to a minimum block number (791 ms) 12:35:51 ✓ sync returns immediately if block was already synced (509 ms) 12:35:51 ✓ throws if you try to sync to an unavailable block (519 ms) 12:35:51 ✓ throws if you try to immediate sync when not running (5 ms) 12:35:51 ✓ throws if handling blocks fails (594 ms) 12:35:51 12:35:51 Test Suites: 1 passed, 1 total 12:35:51 Tests: 10 passed, 10 total 12:35:51 Snapshots: 0 total 12:35:51 Time: 6.932 s 12:35:51 Ran all test suites matching /world-state\/src\/synchronizer\/server_world_state_synchronizer.test.ts/i. 12:35:51 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?