Parent Log: http://ci.aztec-labs.com/1526d186161d462d Command: 581a044a34e123b9 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/98fbe17330964452777de26d56f0a3ed5633aca1 Env: REF_NAME=gh-readonly-queue/next/pr-14921-fa23733be8278348dff0959c8094bcfd89eb44d5 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Thu Jun 12 10:20:10 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14921_amd64_x1-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 10:20:13 [10:20:13.269] INFO: world_state Created world state synchroniser with block history of infinity 10:20:13 [10:20:13.400] INFO: world_state Started world state synchronizer from block 1 10:20:13 [10:20:13.807] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":7.373025000000325,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:13 [10:20:13.816] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.7037920000002487,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:13 [10:20:13.819] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.305290000000241,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:13 [10:20:13.976] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.784049999999752,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:13 [10:20:13.979] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":2.027274000000034,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:13 [10:20:13.981] INFO: world_state Stopped world state synchronizer 10:20:13 [10:20:13.982] INFO: world_state Stopped world state synchronizer 10:20:13 [10:20:13.985] INFO: world_state Created world state synchroniser with block history of infinity 10:20:14 [10:20:14.164] INFO: world_state Started world state synchronizer from block 1 10:20:14 [10:20:14.491] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.5342659999996613,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:14 [10:20:14.494] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.4883630000003905,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:14 [10:20:14.496] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2644919999993363,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:14 [10:20:14.498] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.6000100000001112,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:14 [10:20:14.500] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.4123890000000756,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:14 [10:20:14.502] INFO: world_state Stopped world state synchronizer 10:20:14 [10:20:14.503] INFO: world_state Created world state synchroniser with block history of infinity 10:20:14 [10:20:14.504] INFO: world_state Started world state synchronizer from block 1 10:20:14 [10:20:14.505] INFO: world_state Stopped world state synchronizer 10:20:14 [10:20:14.506] INFO: world_state Created world state synchroniser with block history of infinity 10:20:14 [10:20:14.507] INFO: world_state Started world state synchronizer from block 1 10:20:14 [10:20:14.507] INFO: world_state Stopped world state synchronizer 10:20:14 [10:20:14.529] INFO: world_state Stopped world state synchronizer 10:20:14 [10:20:14.531] INFO: world_state Created world state synchroniser with block history of infinity 10:20:14 [10:20:14.688] INFO: world_state Started world state synchronizer from block 1 10:20:14 [10:20:14.978] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.5941689999999653,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:14 [10:20:14.980] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.3179739999995945,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:14 [10:20:14.982] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.3346050000000105,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:14 [10:20:14.984] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2966929999993226,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:14 [10:20:14.986] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2813919999998689,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:15 [10:20:15.172] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.384447000000364,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:15 [10:20:15.174] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.1881069999999454,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:15 [10:20:15.176] INFO: world_state Stopped world state synchronizer 10:20:15 [10:20:15.177] INFO: world_state Created world state synchroniser with block history of infinity 10:20:15 [10:20:15.313] INFO: world_state Started world state synchronizer from block 1 10:20:15 [10:20:15.568] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.3074240000005375,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:15 [10:20:15.570] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0885410000000775,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:15 [10:20:15.571] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0430990000004385,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:15 [10:20:15.573] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.8329969999995228,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:15 [10:20:15.574] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1607050000002346,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:15 [10:20:15.797] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.259780000000319,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:15 [10:20:15.798] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.108502000000044,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:15 [10:20:15.800] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":0.8196760000000722,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:15 [10:20:15.801] INFO: world_state Stopped world state synchronizer 10:20:15 [10:20:15.802] INFO: world_state Created world state synchroniser with block history of infinity 10:20:15 [10:20:15.920] INFO: world_state Started world state synchronizer from block 1 10:20:16 [10:20:16.177] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.993876000000455,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:16 [10:20:16.178] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.8507180000005974,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:16 [10:20:16.179] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.7882250000002387,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:16 [10:20:16.180] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.8081860000002052,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:16 [10:20:16.182] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9906860000000961,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:16 [10:20:16.184] INFO: world_state Stopped world state synchronizer 10:20:16 [10:20:16.185] INFO: world_state Created world state synchroniser with block history of infinity 10:20:16 [10:20:16.308] INFO: world_state Started world state synchronizer from block 1 10:20:16 [10:20:16.548] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.0461190000005445,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:16 [10:20:16.549] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9124420000007376,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:16 [10:20:16.551] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8781699999999546,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:16 [10:20:16.552] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.9728150000000824,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:16 [10:20:16.553] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.968485000000328,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:20:16 [10:20:16.555] INFO: world_state Stopped world state synchronizer 10:20:16 [10:20:16.556] INFO: world_state Created world state synchroniser with block history of infinity 10:20:16 [10:20:16.559] INFO: world_state Stopped world state synchronizer 10:20:16 [10:20:16.560] INFO: world_state Created world state synchroniser with block history of infinity 10:20:16 [10:20:16.701] INFO: world_state Started world state synchronizer from block 1 10:20:16 [10:20:16.981] INFO: world_state Stopped world state synchronizer 10:20:16 PASS src/synchronizer/server_world_state_synchronizer.test.ts (5.824 s) 10:20:16 ServerWorldStateSynchronizer 10:20:16 ✓ updates sync progress (717 ms) 10:20:16 ✓ handles multiple calls to start (518 ms) 10:20:16 ✓ immediately syncs if no new blocks (3 ms) 10:20:16 ✓ cannot be started if already stopped (24 ms) 10:20:16 ✓ can immediately sync to latest (646 ms) 10:20:16 ✓ can immediately sync to a minimum block number (624 ms) 10:20:16 ✓ sync returns immediately if block was already synced (383 ms) 10:20:16 ✓ throws if you try to sync to an unavailable block (371 ms) 10:20:16 ✓ throws if you try to immediate sync when not running (4 ms) 10:20:16 ✓ throws if handling blocks fails (423 ms) 10:20:16 10:20:16 Test Suites: 1 passed, 1 total 10:20:16 Tests: 10 passed, 10 total 10:20:16 Snapshots: 0 total 10:20:16 Time: 5.909 s 10:20:16 Ran all test suites matching /world-state\/src\/synchronizer\/server_world_state_synchronizer.test.ts/i. 10:20:16 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?