Parent Log: http://ci.aztec-labs.com/84dea640742bbf96 Command: 448a16f463e450de yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/80d800435dd8dfa325e38593d83b405488449dba Env: REF_NAME=gh-readonly-queue/next/pr-14444-28bf32bfc2566751e8baa0a9125a9db20d474bee CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Fri Jun 20 15:59:16 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14444_amd64_x3-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 15:59:20 [15:59:20.143] INFO: world_state Created world state synchroniser with block history of infinity 15:59:20 [15:59:20.268] INFO: world_state Started world state synchronizer from block 1 15:59:20 [15:59:20.704] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":9.392734000000019,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:20 [15:59:20.708] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.557307000000037,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:20 [15:59:20.712] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.3155400000000554,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:20 [15:59:20.914] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":13.055119000000104,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:20 [15:59:20.916] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.421425999999883,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:20 [15:59:20.917] INFO: world_state Stopped world state synchronizer 15:59:20 [15:59:20.919] INFO: world_state Stopped world state synchronizer 15:59:20 [15:59:20.921] INFO: world_state Created world state synchroniser with block history of infinity 15:59:21 [15:59:21.073] INFO: world_state Started world state synchronizer from block 1 15:59:21 [15:59:21.339] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.8649580000001151,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:21 [15:59:21.343] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.8086429999993925,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:21 [15:59:21.345] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.432068000000072,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:21 [15:59:21.348] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.75182599999971,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:21 [15:59:21.350] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.6238119999998162,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:21 [15:59:21.351] INFO: world_state Stopped world state synchronizer 15:59:21 [15:59:21.358] INFO: world_state Created world state synchroniser with block history of infinity 15:59:21 [15:59:21.359] INFO: world_state Started world state synchronizer from block 1 15:59:21 [15:59:21.360] INFO: world_state Stopped world state synchronizer 15:59:21 [15:59:21.362] INFO: world_state Created world state synchroniser with block history of infinity 15:59:21 [15:59:21.362] INFO: world_state Started world state synchronizer from block 1 15:59:21 [15:59:21.363] INFO: world_state Stopped world state synchronizer 15:59:21 [15:59:21.388] INFO: world_state Stopped world state synchronizer 15:59:21 [15:59:21.390] INFO: world_state Created world state synchroniser with block history of infinity 15:59:21 [15:59:21.595] INFO: world_state Started world state synchronizer from block 1 15:59:21 [15:59:21.892] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.5739510000003065,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:21 [15:59:21.894] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.3994769999999335,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:21 [15:59:21.896] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.378486000000521,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:21 [15:59:21.898] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2789530000000013,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:21 [15:59:21.900] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.3278239999999641,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:22 [15:59:22.083] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":0.9929760000004535,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:22 [15:59:22.085] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.8690829999995913,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:22 [15:59:22.086] INFO: world_state Stopped world state synchronizer 15:59:22 [15:59:22.087] INFO: world_state Created world state synchroniser with block history of infinity 15:59:22 [15:59:22.247] INFO: world_state Started world state synchronizer from block 1 15:59:22 [15:59:22.485] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.0344359999999142,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:22 [15:59:22.487] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.3526350000001912,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:22 [15:59:22.489] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.401557000000139,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:22 [15:59:22.491] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3490750000000844,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:22 [15:59:22.492] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2806829999999536,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:22 [15:59:22.822] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.56641000000036,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:22 [15:59:22.825] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.8644279999998616,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:22 [15:59:22.836] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":10.200064999999995,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:22 [15:59:22.838] INFO: world_state Stopped world state synchronizer 15:59:22 [15:59:22.840] INFO: world_state Created world state synchroniser with block history of infinity 15:59:22 [15:59:22.972] INFO: world_state Started world state synchronizer from block 1 15:59:23 [15:59:23.264] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.6300919999994221,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:23 [15:59:23.266] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.4760590000005323,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:23 [15:59:23.268] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.452448000000004,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:23 [15:59:23.270] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.4910890000001018,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:23 [15:59:23.275] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.5915610000001834,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:23 [15:59:23.279] INFO: world_state Stopped world state synchronizer 15:59:23 [15:59:23.280] INFO: world_state Created world state synchroniser with block history of infinity 15:59:23 [15:59:23.432] INFO: world_state Started world state synchronizer from block 1 15:59:23 [15:59:23.770] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.4385170000005019,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:23 [15:59:23.772] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.2651929999992717,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:23 [15:59:23.775] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.127445999999509,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:23 [15:59:23.777] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3172850000000835,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:23 [15:59:23.779] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.448236999999608,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:59:23 [15:59:23.781] INFO: world_state Stopped world state synchronizer 15:59:23 [15:59:23.782] INFO: world_state Created world state synchroniser with block history of infinity 15:59:23 [15:59:23.786] INFO: world_state Stopped world state synchronizer 15:59:23 [15:59:23.787] INFO: world_state Created world state synchroniser with block history of infinity 15:59:23 [15:59:23.958] INFO: world_state Started world state synchronizer from block 1 15:59:24 [15:59:24.210] INFO: world_state Stopped world state synchronizer 15:59:24 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.373 s) 15:59:24 ServerWorldStateSynchronizer 15:59:24 ✓ updates sync progress (780 ms) 15:59:24 ✓ handles multiple calls to start (432 ms) 15:59:24 ✓ immediately syncs if no new blocks (9 ms) 15:59:24 ✓ cannot be started if already stopped (27 ms) 15:59:24 ✓ can immediately sync to latest (697 ms) 15:59:24 ✓ can immediately sync to a minimum block number (751 ms) 15:59:24 ✓ sync returns immediately if block was already synced (439 ms) 15:59:24 ✓ throws if you try to sync to an unavailable block (501 ms) 15:59:24 ✓ throws if you try to immediate sync when not running (4 ms) 15:59:24 ✓ throws if handling blocks fails (425 ms) 15:59:24 15:59:24 Test Suites: 1 passed, 1 total 15:59:24 Tests: 10 passed, 10 total 15:59:24 Snapshots: 0 total 15:59:24 Time: 6.478 s 15:59:24 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 15:59:24 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?