Parent Log: http://ci.aztec-labs.com/f97bdeeb954e1abb Command: 17e0d785507e6983 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/5867520ed258adecfee692b3694d693b2c7964ca Env: REF_NAME=gh-readonly-queue/next/pr-14770-9b801754ba98479ee645488c29d2fda1eecbfd85 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Mon Jun 16 21:52:06 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14770_amd64_x4-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 21:52:10 [21:52:10.543] INFO: world_state Created world state synchroniser with block history of infinity 21:52:10 [21:52:10.652] INFO: world_state Started world state synchronizer from block 1 21:52:11 [21:52:11.056] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":5.208655000000363,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:11 [21:52:11.060] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":3.2652879999996003,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:11 [21:52:11.064] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.091961000000083,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:11 [21:52:11.303] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.7213760000004186,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:11 [21:52:11.307] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":2.15327200000047,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:11 [21:52:11.310] INFO: world_state Stopped world state synchronizer 21:52:11 [21:52:11.313] INFO: world_state Stopped world state synchronizer 21:52:11 [21:52:11.319] INFO: world_state Created world state synchroniser with block history of infinity 21:52:11 [21:52:11.523] INFO: world_state Started world state synchronizer from block 1 21:52:11 [21:52:11.842] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.9732029999995575,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:11 [21:52:11.843] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.871171000000686,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:11 [21:52:11.844] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.7739689999998518,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:11 [21:52:11.846] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.0325949999996737,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:11 [21:52:11.847] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9675230000002557,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:11 [21:52:11.848] INFO: world_state Stopped world state synchronizer 21:52:11 [21:52:11.850] INFO: world_state Created world state synchroniser with block history of infinity 21:52:11 [21:52:11.850] INFO: world_state Started world state synchronizer from block 1 21:52:11 [21:52:11.851] INFO: world_state Stopped world state synchronizer 21:52:11 [21:52:11.851] INFO: world_state Created world state synchroniser with block history of infinity 21:52:11 [21:52:11.852] INFO: world_state Started world state synchronizer from block 1 21:52:11 [21:52:11.852] INFO: world_state Stopped world state synchronizer 21:52:11 [21:52:11.869] INFO: world_state Stopped world state synchronizer 21:52:11 [21:52:11.871] INFO: world_state Created world state synchroniser with block history of infinity 21:52:12 [21:52:12.066] INFO: world_state Started world state synchronizer from block 1 21:52:12 [21:52:12.389] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.3382019999999102,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:12 [21:52:12.391] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.1633080000001428,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:12 [21:52:12.393] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0601259999994,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:12 [21:52:12.394] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.8784619999996721,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:12 [21:52:12.396] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1862579999997251,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:12 [21:52:12.599] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.7531520000002274,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:12 [21:52:12.602] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.4671049999997194,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:12 [21:52:12.603] INFO: world_state Stopped world state synchronizer 21:52:12 [21:52:12.605] INFO: world_state Created world state synchroniser with block history of infinity 21:52:12 [21:52:12.792] INFO: world_state Started world state synchronizer from block 1 21:52:13 [21:52:13.090] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.2003089999998338,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:13 [21:52:13.092] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9222520000002987,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:13 [21:52:13.093] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8236499999993612,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:13 [21:52:13.095] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.8260599999994156,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:13 [21:52:13.098] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.817323999999644,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:13 [21:52:13.397] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.438054999999622,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:13 [21:52:13.409] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":5.0095009999995455,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:13 [21:52:13.412] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.6705510000001595,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:13 [21:52:13.414] INFO: world_state Stopped world state synchronizer 21:52:13 [21:52:13.416] INFO: world_state Created world state synchroniser with block history of infinity 21:52:13 [21:52:13.590] INFO: world_state Started world state synchronizer from block 1 21:52:13 [21:52:13.933] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.242989999999736,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:13 [21:52:13.935] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.149557000000641,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:13 [21:52:13.937] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.134567000000061,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:13 [21:52:13.938] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.187778999999864,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:13 [21:52:13.940] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.011884999999893,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:13 [21:52:13.943] INFO: world_state Stopped world state synchronizer 21:52:13 [21:52:13.945] INFO: world_state Created world state synchroniser with block history of infinity 21:52:14 [21:52:14.115] INFO: world_state Started world state synchronizer from block 1 21:52:14 [21:52:14.373] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.8731110000007902,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:14 [21:52:14.374] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.725018000000091,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:14 [21:52:14.375] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.7179770000002463,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:14 [21:52:14.376] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.7583889999996245,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:14 [21:52:14.377] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8197399999999107,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 21:52:14 [21:52:14.378] INFO: world_state Stopped world state synchronizer 21:52:14 [21:52:14.380] INFO: world_state Created world state synchroniser with block history of infinity 21:52:14 [21:52:14.383] INFO: world_state Stopped world state synchronizer 21:52:14 [21:52:14.392] INFO: world_state Created world state synchroniser with block history of infinity 21:52:14 [21:52:14.542] INFO: world_state Started world state synchronizer from block 1 21:52:14 [21:52:14.869] INFO: world_state Stopped world state synchronizer 21:52:14 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.766 s) 21:52:14 ServerWorldStateSynchronizer 21:52:14 ✓ updates sync progress (775 ms) 21:52:14 ✓ handles multiple calls to start (532 ms) 21:52:14 ✓ immediately syncs if no new blocks (2 ms) 21:52:14 ✓ cannot be started if already stopped (18 ms) 21:52:14 ✓ can immediately sync to latest (734 ms) 21:52:14 ✓ can immediately sync to a minimum block number (809 ms) 21:52:14 ✓ sync returns immediately if block was already synced (529 ms) 21:52:14 ✓ throws if you try to sync to an unavailable block (435 ms) 21:52:14 ✓ throws if you try to immediate sync when not running (4 ms) 21:52:14 ✓ throws if handling blocks fails (487 ms) 21:52:14 21:52:14 Test Suites: 1 passed, 1 total 21:52:14 Tests: 10 passed, 10 total 21:52:14 Snapshots: 0 total 21:52:14 Time: 6.898 s 21:52:14 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 21:52:14 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?