Parent Log: http://ci.aztec-labs.com/4bd36cecb2b5c7bf Command: 3987b0c732d12451 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/d3bba2d69dbc070d51bcd50607354193573876ba Env: REF_NAME=gh-readonly-queue/next/pr-14877-314d7622c6bacc8333ae49fe9583967cab78349b CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Fri Jun 13 12:56:15 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14877_amd64_x3-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 12:56:19 [12:56:19.244] INFO: world_state Created world state synchroniser with block history of infinity 12:56:19 [12:56:19.380] INFO: world_state Started world state synchronizer from block 1 12:56:19 [12:56:19.771] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":6.518259000000398,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:19 [12:56:19.776] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.825814000000264,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:19 [12:56:19.779] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.1371249999997417,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:19 [12:56:19.928] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.7666860000003908,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:19 [12:56:19.938] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":9.175968000000012,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:19 [12:56:19.939] INFO: world_state Stopped world state synchronizer 12:56:19 [12:56:19.941] INFO: world_state Stopped world state synchronizer 12:56:19 [12:56:19.943] INFO: world_state Created world state synchroniser with block history of infinity 12:56:20 [12:56:20.062] INFO: world_state Started world state synchronizer from block 1 12:56:20 [12:56:20.339] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.554399999999987,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:20 [12:56:20.341] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.42570699999942,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:20 [12:56:20.343] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2739730000002965,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:20 [12:56:20.345] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.471238999999514,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:20 [12:56:20.347] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.5120889999998326,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:20 [12:56:20.349] INFO: world_state Stopped world state synchronizer 12:56:20 [12:56:20.351] INFO: world_state Created world state synchroniser with block history of infinity 12:56:20 [12:56:20.351] INFO: world_state Started world state synchronizer from block 1 12:56:20 [12:56:20.352] INFO: world_state Stopped world state synchronizer 12:56:20 [12:56:20.353] INFO: world_state Created world state synchroniser with block history of infinity 12:56:20 [12:56:20.354] INFO: world_state Started world state synchronizer from block 1 12:56:20 [12:56:20.354] INFO: world_state Stopped world state synchronizer 12:56:20 [12:56:20.372] INFO: world_state Stopped world state synchronizer 12:56:20 [12:56:20.374] INFO: world_state Created world state synchroniser with block history of infinity 12:56:20 [12:56:20.516] INFO: world_state Started world state synchronizer from block 1 12:56:20 [12:56:20.747] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.2930429999996704,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:20 [12:56:20.749] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.2541620000001785,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:20 [12:56:20.751] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9307640000006359,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:20 [12:56:20.752] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.0629079999998794,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:20 [12:56:20.753] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9174039999998058,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:20 [12:56:20.905] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.397196000000804,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:20 [12:56:20.907] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.1879810000000361,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:20 [12:56:20.908] INFO: world_state Stopped world state synchronizer 12:56:20 [12:56:20.910] INFO: world_state Created world state synchroniser with block history of infinity 12:56:21 [12:56:21.067] INFO: world_state Started world state synchronizer from block 1 12:56:21 [12:56:21.343] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.5009689999997136,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:21 [12:56:21.345] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.3675750000002154,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:21 [12:56:21.347] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.3564049999995405,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:21 [12:56:21.349] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2911239999993995,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:21 [12:56:21.351] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.271853000000192,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:21 [12:56:21.608] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":0.9788660000003802,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:21 [12:56:21.610] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.3646249999992506,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:21 [12:56:21.612] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.3302140000005238,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:21 [12:56:21.625] INFO: world_state Stopped world state synchronizer 12:56:21 [12:56:21.627] INFO: world_state Created world state synchroniser with block history of infinity 12:56:21 [12:56:21.755] INFO: world_state Started world state synchronizer from block 1 12:56:22 [12:56:22.029] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.3762260000003153,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:22 [12:56:22.032] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.5647609999996348,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:22 [12:56:22.034] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.1567599999998492,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:22 [12:56:22.036] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.008085999999821,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:22 [12:56:22.037] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.7997310000000653,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:22 [12:56:22.038] INFO: world_state Stopped world state synchronizer 12:56:22 [12:56:22.040] INFO: world_state Created world state synchroniser with block history of infinity 12:56:22 [12:56:22.180] INFO: world_state Started world state synchronizer from block 1 12:56:22 [12:56:22.413] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.0785679999999047,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:22 [12:56:22.414] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.8580119999996896,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:22 [12:56:22.415] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8406720000002679,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:22 [12:56:22.417] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1952409999994416,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:22 [12:56:22.419] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9839560000000347,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:22 [12:56:22.420] INFO: world_state Stopped world state synchronizer 12:56:22 [12:56:22.422] INFO: world_state Created world state synchroniser with block history of infinity 12:56:22 [12:56:22.424] INFO: world_state Stopped world state synchronizer 12:56:22 [12:56:22.425] INFO: world_state Created world state synchroniser with block history of infinity 12:56:22 [12:56:22.587] INFO: world_state Started world state synchronizer from block 1 12:56:22 [12:56:22.884] INFO: world_state Stopped world state synchronizer 12:56:22 PASS src/synchronizer/server_world_state_synchronizer.test.ts (5.907 s) 12:56:22 ServerWorldStateSynchronizer 12:56:22 ✓ updates sync progress (703 ms) 12:56:22 ✓ handles multiple calls to start (407 ms) 12:56:22 ✓ immediately syncs if no new blocks (3 ms) 12:56:22 ✓ cannot be started if already stopped (20 ms) 12:56:22 ✓ can immediately sync to latest (535 ms) 12:56:22 ✓ can immediately sync to a minimum block number (717 ms) 12:56:22 ✓ sync returns immediately if block was already synced (413 ms) 12:56:22 ✓ throws if you try to sync to an unavailable block (381 ms) 12:56:22 ✓ throws if you try to immediate sync when not running (3 ms) 12:56:22 ✓ throws if handling blocks fails (460 ms) 12:56:22 12:56:22 Test Suites: 1 passed, 1 total 12:56:22 Tests: 10 passed, 10 total 12:56:22 Snapshots: 0 total 12:56:22 Time: 5.981 s 12:56:22 Ran all test suites matching /world-state\/src\/synchronizer\/server_world_state_synchronizer.test.ts/i. 12:56:22 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?