Parent Log: http://ci.aztec-labs.com/8ca66f532d1b481c Command: c6dc62f84205193f yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/3cbe7945c84145b8646cf5cf8fb730b915c0dc93 Env: REF_NAME=gh-readonly-queue/next/pr-15068-254faae7d330e160b14c6eaaa099dc92d4a9cac7 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Mon Jun 16 13:30:16 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15068_amd64_x1-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 13:30:19 [13:30:19.878] INFO: world_state Created world state synchroniser with block history of infinity 13:30:20 [13:30:20.005] INFO: world_state Started world state synchronizer from block 1 13:30:20 [13:30:20.344] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":6.281721000000289,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:20 [13:30:20.347] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.7237620000000788,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:20 [13:30:20.349] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.7541819999996733,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:20 [13:30:20.503] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.78355399999964,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:20 [13:30:20.506] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":2.021759000000202,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:20 [13:30:20.507] INFO: world_state Stopped world state synchronizer 13:30:20 [13:30:20.509] INFO: world_state Stopped world state synchronizer 13:30:20 [13:30:20.511] INFO: world_state Created world state synchroniser with block history of infinity 13:30:20 [13:30:20.636] INFO: world_state Started world state synchronizer from block 1 13:30:20 [13:30:20.961] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.5588479999996707,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:20 [13:30:20.964] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.640689000000748,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:20 [13:30:20.966] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.490646000000197,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:20 [13:30:20.969] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.9420769999996992,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:20 [13:30:20.971] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.698750999999902,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:20 [13:30:20.973] INFO: world_state Stopped world state synchronizer 13:30:20 [13:30:20.975] INFO: world_state Created world state synchroniser with block history of infinity 13:30:20 [13:30:20.976] INFO: world_state Started world state synchronizer from block 1 13:30:20 [13:30:20.977] INFO: world_state Stopped world state synchronizer 13:30:20 [13:30:20.978] INFO: world_state Created world state synchroniser with block history of infinity 13:30:20 [13:30:20.979] INFO: world_state Started world state synchronizer from block 1 13:30:20 [13:30:20.979] INFO: world_state Stopped world state synchronizer 13:30:20 [13:30:20.998] INFO: world_state Stopped world state synchronizer 13:30:21 [13:30:21.000] INFO: world_state Created world state synchroniser with block history of infinity 13:30:21 [13:30:21.171] INFO: world_state Started world state synchronizer from block 1 13:30:21 [13:30:21.414] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.3348630000000412,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:21 [13:30:21.415] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0680360000005749,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:21 [13:30:21.417] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8735309999992751,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:21 [13:30:21.418] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.802759000000151,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:21 [13:30:21.419] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8009000000001834,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:21 [13:30:21.572] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.4023339999994278,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:21 [13:30:21.574] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.1577379999998811,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:21 [13:30:21.582] INFO: world_state Stopped world state synchronizer 13:30:21 [13:30:21.584] INFO: world_state Created world state synchroniser with block history of infinity 13:30:21 [13:30:21.752] INFO: world_state Started world state synchronizer from block 1 13:30:22 [13:30:22.051] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.6512400000001435,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:22 [13:30:22.054] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.4132239999999001,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:22 [13:30:22.055] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.3040919999994003,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:22 [13:30:22.057] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.9293319999997038,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:22 [13:30:22.059] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.3515720000004876,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:22 [13:30:22.425] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":3.8809940000001006,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:22 [13:30:22.429] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.43654400000014,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:22 [13:30:22.431] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.3008209999998144,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:22 [13:30:22.435] INFO: world_state Stopped world state synchronizer 13:30:22 [13:30:22.439] INFO: world_state Created world state synchroniser with block history of infinity 13:30:22 [13:30:22.619] INFO: world_state Started world state synchronizer from block 1 13:30:22 [13:30:22.873] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.924511999999595,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:22 [13:30:22.875] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.7853890000005777,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:22 [13:30:22.876] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.7639280000003055,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:22 [13:30:22.877] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.8423400000001493,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:22 [13:30:22.879] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.774688999999853,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:22 [13:30:22.880] INFO: world_state Stopped world state synchronizer 13:30:22 [13:30:22.881] INFO: world_state Created world state synchroniser with block history of infinity 13:30:23 [13:30:23.033] INFO: world_state Started world state synchronizer from block 1 13:30:23 [13:30:23.291] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.947823000000426,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:23 [13:30:23.292] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.8055389999999534,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:23 [13:30:23.304] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":11.697772000000441,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:23 [13:30:23.306] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1901889999999185,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:23 [13:30:23.308] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.082806000000346,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:30:23 [13:30:23.310] INFO: world_state Stopped world state synchronizer 13:30:23 [13:30:23.311] INFO: world_state Created world state synchroniser with block history of infinity 13:30:23 [13:30:23.314] INFO: world_state Stopped world state synchronizer 13:30:23 [13:30:23.315] INFO: world_state Created world state synchroniser with block history of infinity 13:30:23 [13:30:23.455] INFO: world_state Started world state synchronizer from block 1 13:30:23 [13:30:23.756] INFO: world_state Stopped world state synchronizer 13:30:23 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.273 s) 13:30:23 ServerWorldStateSynchronizer 13:30:23 ✓ updates sync progress (634 ms) 13:30:23 ✓ handles multiple calls to start (463 ms) 13:30:23 ✓ immediately syncs if no new blocks (3 ms) 13:30:23 ✓ cannot be started if already stopped (21 ms) 13:30:23 ✓ can immediately sync to latest (584 ms) 13:30:23 ✓ can immediately sync to a minimum block number (852 ms) 13:30:23 ✓ sync returns immediately if block was already synced (444 ms) 13:30:23 ✓ throws if you try to sync to an unavailable block (429 ms) 13:30:23 ✓ throws if you try to immediate sync when not running (4 ms) 13:30:23 ✓ throws if handling blocks fails (442 ms) 13:30:23 13:30:23 Test Suites: 1 passed, 1 total 13:30:23 Tests: 10 passed, 10 total 13:30:23 Snapshots: 0 total 13:30:23 Time: 6.401 s 13:30:23 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 13:30:23 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?