Parent Log: http://ci.aztec-labs.com/4526418a08521f72 Command: 9d60524367dfa79a yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/056e5230c330d6ad19900135ecf2a4787181496a Env: REF_NAME=gh-readonly-queue/next/pr-12986-a9f91dd1e5946aecfeb192ce869eb3e8eca2742e CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Tue Jun 17 13:14:16 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-12986_amd64_x2-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 13:14:20 [13:14:20.307] INFO: world_state Created world state synchroniser with block history of infinity 13:14:20 [13:14:20.449] INFO: world_state Started world state synchronizer from block 1 13:14:20 [13:14:20.877] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":7.999182000000474,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:20 [13:14:20.888] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":5.572793000000274,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:20 [13:14:20.891] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.3818780000001425,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:21 [13:14:21.077] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":3.8048969999999827,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:21 [13:14:21.084] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":6.1620640000001,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:21 [13:14:21.100] INFO: world_state Stopped world state synchronizer 13:14:21 [13:14:21.102] INFO: world_state Stopped world state synchronizer 13:14:21 [13:14:21.106] INFO: world_state Created world state synchroniser with block history of infinity 13:14:21 [13:14:21.253] INFO: world_state Started world state synchronizer from block 1 13:14:21 [13:14:21.556] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.0844520000000557,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:21 [13:14:21.558] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.1524129999997967,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:21 [13:14:21.560] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2591959999999744,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:21 [13:14:21.562] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.204603999999563,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:21 [13:14:21.565] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.6194729999997435,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:21 [13:14:21.567] INFO: world_state Stopped world state synchronizer 13:14:21 [13:14:21.569] INFO: world_state Created world state synchroniser with block history of infinity 13:14:21 [13:14:21.569] INFO: world_state Started world state synchronizer from block 1 13:14:21 [13:14:21.570] INFO: world_state Stopped world state synchronizer 13:14:21 [13:14:21.571] INFO: world_state Created world state synchroniser with block history of infinity 13:14:21 [13:14:21.572] INFO: world_state Started world state synchronizer from block 1 13:14:21 [13:14:21.572] INFO: world_state Stopped world state synchronizer 13:14:21 [13:14:21.596] INFO: world_state Stopped world state synchronizer 13:14:21 [13:14:21.598] INFO: world_state Created world state synchroniser with block history of infinity 13:14:21 [13:14:21.756] INFO: world_state Started world state synchronizer from block 1 13:14:22 [13:14:22.025] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.0969930000001114,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:22 [13:14:22.027] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0500010000005204,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:22 [13:14:22.028] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9714290000001711,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:22 [13:14:22.030] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1320429999996122,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:22 [13:14:22.031] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1453629999996338,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:22 [13:14:22.191] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":0.9434090000004289,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:22 [13:14:22.193] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.9951899999996385,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:22 [13:14:22.194] INFO: world_state Stopped world state synchronizer 13:14:22 [13:14:22.195] INFO: world_state Created world state synchroniser with block history of infinity 13:14:22 [13:14:22.354] INFO: world_state Started world state synchronizer from block 1 13:14:22 [13:14:22.699] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.4270089999999982,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:22 [13:14:22.701] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9175880000002508,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:22 [13:14:22.702] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.849316999999246,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:22 [13:14:22.704] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2543949999999313,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:22 [13:14:22.706] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.948460000000523,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:22 [13:14:22.981] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.4777699999995093,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:22 [13:14:22.983] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.4106080000001384,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:22 [13:14:22.985] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.1904540000005,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:22 [13:14:22.987] INFO: world_state Stopped world state synchronizer 13:14:22 [13:14:22.988] INFO: world_state Created world state synchroniser with block history of infinity 13:14:23 [13:14:23.130] INFO: world_state Started world state synchronizer from block 1 13:14:23 [13:14:23.470] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.7833759999994072,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:23 [13:14:23.472] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.2776249999997162,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:23 [13:14:23.474] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2470649999995658,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:23 [13:14:23.476] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3102269999999407,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:23 [13:14:23.477] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.295086000000083,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:23 [13:14:23.481] INFO: world_state Stopped world state synchronizer 13:14:23 [13:14:23.484] INFO: world_state Created world state synchroniser with block history of infinity 13:14:23 [13:14:23.634] INFO: world_state Started world state synchronizer from block 1 13:14:23 [13:14:23.913] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.3137070000002495,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:23 [13:14:23.915] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.2478459999992992,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:23 [13:14:23.917] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.1846040000000357,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:23 [13:14:23.918] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1076130000001285,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:23 [13:14:23.920] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0963520000004792,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:14:23 [13:14:23.921] INFO: world_state Stopped world state synchronizer 13:14:23 [13:14:23.923] INFO: world_state Created world state synchroniser with block history of infinity 13:14:23 [13:14:23.925] INFO: world_state Stopped world state synchronizer 13:14:23 [13:14:23.926] INFO: world_state Created world state synchroniser with block history of infinity 13:14:24 [13:14:24.093] INFO: world_state Started world state synchronizer from block 1 13:14:24 [13:14:24.431] INFO: world_state Stopped world state synchronizer 13:14:24 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.615 s) 13:14:24 ServerWorldStateSynchronizer 13:14:24 ✓ updates sync progress (801 ms) 13:14:24 ✓ handles multiple calls to start (462 ms) 13:14:24 ✓ immediately syncs if no new blocks (2 ms) 13:14:24 ✓ cannot be started if already stopped (26 ms) 13:14:24 ✓ can immediately sync to latest (597 ms) 13:14:24 ✓ can immediately sync to a minimum block number (792 ms) 13:14:24 ✓ sync returns immediately if block was already synced (495 ms) 13:14:24 ✓ throws if you try to sync to an unavailable block (439 ms) 13:14:24 ✓ throws if you try to immediate sync when not running (3 ms) 13:14:24 ✓ throws if handling blocks fails (506 ms) 13:14:24 13:14:24 Test Suites: 1 passed, 1 total 13:14:24 Tests: 10 passed, 10 total 13:14:24 Snapshots: 0 total 13:14:24 Time: 6.729 s 13:14:24 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 13:14:24 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?