Parent Log: http://ci.aztec-labs.com/ec5a363ace1c3c4c Command: a5511b51e55ea9c0 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/6ee95286f8bcc5bb6dca43222459ac5bf43a8a75 Env: REF_NAME=gh-readonly-queue/next/pr-15156-9cbd161a1dfa928167c1f969200b95df55ed4c6b CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Fri Jun 20 09:44:11 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15156_amd64_x2-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 09:44:14 [09:44:14.701] INFO: world_state Created world state synchroniser with block history of infinity 09:44:14 [09:44:14.823] INFO: world_state Started world state synchronizer from block 1 09:44:15 [09:44:15.265] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":6.846074000000044,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:15 [09:44:15.269] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.161085999999159,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:15 [09:44:15.272] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.092345000000023,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:15 [09:44:15.434] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2378710000002684,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:15 [09:44:15.436] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9387649999998757,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:15 [09:44:15.437] INFO: world_state Stopped world state synchronizer 09:44:15 [09:44:15.438] INFO: world_state Stopped world state synchronizer 09:44:15 [09:44:15.441] INFO: world_state Created world state synchroniser with block history of infinity 09:44:15 [09:44:15.652] INFO: world_state Started world state synchronizer from block 1 09:44:15 [09:44:15.933] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1286989999998696,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:15 [09:44:15.934] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9329459999999017,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:15 [09:44:15.936] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8607339999998658,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:15 [09:44:15.937] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.094767999999931,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:15 [09:44:15.939] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.5122550000005504,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:15 [09:44:15.941] INFO: world_state Stopped world state synchronizer 09:44:15 [09:44:15.942] INFO: world_state Created world state synchroniser with block history of infinity 09:44:15 [09:44:15.942] INFO: world_state Started world state synchronizer from block 1 09:44:15 [09:44:15.943] INFO: world_state Stopped world state synchronizer 09:44:15 [09:44:15.944] INFO: world_state Created world state synchroniser with block history of infinity 09:44:15 [09:44:15.945] INFO: world_state Started world state synchronizer from block 1 09:44:15 [09:44:15.945] INFO: world_state Stopped world state synchronizer 09:44:15 [09:44:15.957] INFO: world_state Stopped world state synchronizer 09:44:15 [09:44:15.958] INFO: world_state Created world state synchroniser with block history of infinity 09:44:16 [09:44:16.090] INFO: world_state Started world state synchronizer from block 1 09:44:16 [09:44:16.439] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":2.2546170000005077,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:16 [09:44:16.442] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.8888120000001436,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:16 [09:44:16.445] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.5028449999999793,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:16 [09:44:16.447] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.5177260000000388,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:16 [09:44:16.449] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.4582839999993666,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:16 [09:44:16.692] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":2.531051999999363,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:16 [09:44:16.694] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.5137549999999464,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:16 [09:44:16.695] INFO: world_state Stopped world state synchronizer 09:44:16 [09:44:16.697] INFO: world_state Created world state synchroniser with block history of infinity 09:44:16 [09:44:16.848] INFO: world_state Started world state synchronizer from block 1 09:44:17 [09:44:17.228] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.5718270000006669,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:17 [09:44:17.230] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.4841139999998632,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:17 [09:44:17.232] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.467413999999735,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:17 [09:44:17.234] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.54267499999969,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:17 [09:44:17.236] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0499179999997068,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:17 [09:44:17.567] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":0.9556659999998374,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:17 [09:44:17.568] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.0254679999998189,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:17 [09:44:17.570] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.1571199999998498,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:17 [09:44:17.571] INFO: world_state Stopped world state synchronizer 09:44:17 [09:44:17.573] INFO: world_state Created world state synchroniser with block history of infinity 09:44:17 [09:44:17.735] INFO: world_state Started world state synchronizer from block 1 09:44:18 [09:44:18.095] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.7120890000005602,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:18 [09:44:18.096] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9913269999997283,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:18 [09:44:18.098] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0310070000004998,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:18 [09:44:18.099] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1282179999998334,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:18 [09:44:18.101] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.353581999999733,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:18 [09:44:18.105] INFO: world_state Stopped world state synchronizer 09:44:18 [09:44:18.108] INFO: world_state Created world state synchroniser with block history of infinity 09:44:18 [09:44:18.282] INFO: world_state Started world state synchronizer from block 1 09:44:18 [09:44:18.539] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.9812359999996261,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:18 [09:44:18.541] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9532460000000356,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:18 [09:44:18.543] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.061837999999625,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:18 [09:44:18.545] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.955855999999585,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:18 [09:44:18.546] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.977856000000429,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:18 [09:44:18.549] INFO: world_state Stopped world state synchronizer 09:44:18 [09:44:18.551] INFO: world_state Created world state synchroniser with block history of infinity 09:44:18 [09:44:18.554] INFO: world_state Stopped world state synchronizer 09:44:18 [09:44:18.556] INFO: world_state Created world state synchroniser with block history of infinity 09:44:18 [09:44:18.731] INFO: world_state Started world state synchronizer from block 1 09:44:19 [09:44:19.076] INFO: world_state Stopped world state synchronizer 09:44:19 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.945 s) 09:44:19 ServerWorldStateSynchronizer 09:44:19 ✓ updates sync progress (743 ms) 09:44:19 ✓ handles multiple calls to start (501 ms) 09:44:19 ✓ immediately syncs if no new blocks (2 ms) 09:44:19 ✓ cannot be started if already stopped (13 ms) 09:44:19 ✓ can immediately sync to latest (738 ms) 09:44:19 ✓ can immediately sync to a minimum block number (876 ms) 09:44:19 ✓ sync returns immediately if block was already synced (533 ms) 09:44:19 ✓ throws if you try to sync to an unavailable block (442 ms) 09:44:19 ✓ throws if you try to immediate sync when not running (5 ms) 09:44:19 ✓ throws if handling blocks fails (522 ms) 09:44:19 09:44:19 Test Suites: 1 passed, 1 total 09:44:19 Tests: 10 passed, 10 total 09:44:19 Snapshots: 0 total 09:44:19 Time: 7.035 s 09:44:19 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 09:44:19 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?