Parent Log: http://ci.aztec-labs.com/99010f58f098e93c Command: d4f09ddb861c41ed yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/d64582316e960acb89baf16d63e5ac1e59a00898 Env: REF_NAME=gh-readonly-queue/staging/pr-15274-9ba478709ce5c48099f79f9531da8292811cb18a CURRENT_VERSION=1.0.0 CI_FULL=1 Date: Wed Jun 25 10:36:25 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15274_amd64_x4-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_staging 10:36:29 [10:36:29.409] INFO: world_state Created world state synchroniser with block history of infinity 10:36:29 [10:36:29.553] INFO: world_state Started world state synchronizer from block 1 10:36:29 [10:36:29.957] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":5.584026999999878,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:29 [10:36:29.961] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.6197449999995115,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:29 [10:36:29.963] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.583813000000191,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:30 [10:36:30.132] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.2086260000005495,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:30 [10:36:30.135] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.9489999999996144,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:30 [10:36:30.154] INFO: world_state Stopped world state synchronizer 10:36:30 [10:36:30.159] INFO: world_state Stopped world state synchronizer 10:36:30 [10:36:30.163] INFO: world_state Created world state synchroniser with block history of infinity 10:36:30 [10:36:30.326] INFO: world_state Started world state synchronizer from block 1 10:36:30 [10:36:30.670] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.0270410000002812,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:30 [10:36:30.671] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.849528000000646,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:30 [10:36:30.672] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8424080000004324,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:30 [10:36:30.674] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.0799329999999827,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:30 [10:36:30.675] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9860200000002806,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:30 [10:36:30.676] INFO: world_state Stopped world state synchronizer 10:36:30 [10:36:30.678] INFO: world_state Created world state synchroniser with block history of infinity 10:36:30 [10:36:30.678] INFO: world_state Started world state synchronizer from block 1 10:36:30 [10:36:30.679] INFO: world_state Stopped world state synchronizer 10:36:30 [10:36:30.680] INFO: world_state Created world state synchroniser with block history of infinity 10:36:30 [10:36:30.680] INFO: world_state Started world state synchronizer from block 1 10:36:30 [10:36:30.680] INFO: world_state Stopped world state synchronizer 10:36:30 [10:36:30.694] INFO: world_state Stopped world state synchronizer 10:36:30 [10:36:30.696] INFO: world_state Created world state synchroniser with block history of infinity 10:36:30 [10:36:30.888] INFO: world_state Started world state synchronizer from block 1 10:36:31 [10:36:31.246] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.5737929999995686,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:31 [10:36:31.249] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.413289000000077,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:31 [10:36:31.252] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2648260000005394,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:31 [10:36:31.254] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2205060000005687,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:31 [10:36:31.258] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":2.6586559999996098,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:31 [10:36:31.435] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.4735410000002958,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:31 [10:36:31.438] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.3135279999996783,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:31 [10:36:31.440] INFO: world_state Stopped world state synchronizer 10:36:31 [10:36:31.442] INFO: world_state Created world state synchroniser with block history of infinity 10:36:31 [10:36:31.642] INFO: world_state Started world state synchronizer from block 1 10:36:31 [10:36:31.990] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.9270599999999831,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:31 [10:36:31.993] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.142652999999882,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:31 [10:36:31.994] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.148084000000381,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:31 [10:36:31.996] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.188734999999724,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:31 [10:36:31.998] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2117560000006051,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:32 [10:36:32.293] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.5980440000003,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:32 [10:36:32.298] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":3.3814999999995052,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:32 [10:36:32.300] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.385269000000335,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:32 [10:36:32.303] INFO: world_state Stopped world state synchronizer 10:36:32 [10:36:32.306] INFO: world_state Created world state synchroniser with block history of infinity 10:36:32 [10:36:32.461] INFO: world_state Started world state synchronizer from block 1 10:36:32 [10:36:32.790] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.2676970000002257,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:32 [10:36:32.792] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0609519999998156,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:32 [10:36:32.794] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.091472999999496,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:32 [10:36:32.795] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1480140000003303,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:32 [10:36:32.797] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0316819999998188,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:32 [10:36:32.799] INFO: world_state Stopped world state synchronizer 10:36:32 [10:36:32.801] INFO: world_state Created world state synchroniser with block history of infinity 10:36:33 [10:36:33.005] INFO: world_state Started world state synchronizer from block 1 10:36:33 [10:36:33.316] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.7859770000004573,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:33 [10:36:33.318] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.8098869999994349,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:33 [10:36:33.319] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.785305999999764,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:33 [10:36:33.320] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.8114869999999428,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:33 [10:36:33.322] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1068130000003293,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:36:33 [10:36:33.325] INFO: world_state Stopped world state synchronizer 10:36:33 [10:36:33.327] INFO: world_state Created world state synchroniser with block history of infinity 10:36:33 [10:36:33.335] INFO: world_state Stopped world state synchronizer 10:36:33 [10:36:33.337] INFO: world_state Created world state synchroniser with block history of infinity 10:36:33 [10:36:33.504] INFO: world_state Started world state synchronizer from block 1 10:36:33 [10:36:33.802] INFO: world_state Stopped world state synchronizer 10:36:33 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.861 s) 10:36:33 ServerWorldStateSynchronizer 10:36:33 ✓ updates sync progress (755 ms) 10:36:33 ✓ handles multiple calls to start (515 ms) 10:36:33 ✓ immediately syncs if no new blocks (2 ms) 10:36:33 ✓ cannot be started if already stopped (16 ms) 10:36:33 ✓ can immediately sync to latest (745 ms) 10:36:33 ✓ can immediately sync to a minimum block number (861 ms) 10:36:33 ✓ sync returns immediately if block was already synced (495 ms) 10:36:33 ✓ throws if you try to sync to an unavailable block (525 ms) 10:36:33 ✓ throws if you try to immediate sync when not running (9 ms) 10:36:33 ✓ throws if handling blocks fails (467 ms) 10:36:33 10:36:33 Test Suites: 1 passed, 1 total 10:36:33 Tests: 10 passed, 10 total 10:36:33 Snapshots: 0 total 10:36:33 Time: 6.953 s 10:36:33 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 10:36:33 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?