Parent Log: http://ci.aztec-labs.com/778f0cf50fd384fb Command: d8033ca60b1dba76 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/9ddf7d35886489afed34aca3cb819b32c86194b4 Env: REF_NAME=gh-readonly-queue/next/pr-15215-99e234fe4473771b32124e0402885610a6a3f98a CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Tue Jun 24 09:23:36 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15215_amd64_x4-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 09:23:39 [09:23:39.590] INFO: world_state Created world state synchroniser with block history of infinity 09:23:39 [09:23:39.775] INFO: world_state Started world state synchronizer from block 1 09:23:40 [09:23:40.195] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":7.389349999999922,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:40 [09:23:40.200] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.9618780000000697,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:40 [09:23:40.205] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":4.07180100000005,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:40 [09:23:40.424] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.7044599999999264,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:40 [09:23:40.426] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.3537999999998647,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:40 [09:23:40.427] INFO: world_state Stopped world state synchronizer 09:23:40 [09:23:40.428] INFO: world_state Stopped world state synchronizer 09:23:40 [09:23:40.431] INFO: world_state Created world state synchroniser with block history of infinity 09:23:40 [09:23:40.572] INFO: world_state Started world state synchronizer from block 1 09:23:40 [09:23:40.876] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.7957430000005843,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:40 [09:23:40.879] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.4364729999997508,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:40 [09:23:40.881] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.4549230000002353,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:40 [09:23:40.883] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.5890370000006442,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:40 [09:23:40.885] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.492855000000418,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:40 [09:23:40.887] INFO: world_state Stopped world state synchronizer 09:23:40 [09:23:40.889] INFO: world_state Created world state synchroniser with block history of infinity 09:23:40 [09:23:40.889] INFO: world_state Started world state synchronizer from block 1 09:23:40 [09:23:40.890] INFO: world_state Stopped world state synchronizer 09:23:40 [09:23:40.891] INFO: world_state Created world state synchroniser with block history of infinity 09:23:40 [09:23:40.891] INFO: world_state Started world state synchronizer from block 1 09:23:40 [09:23:40.891] INFO: world_state Stopped world state synchronizer 09:23:40 [09:23:40.908] INFO: world_state Stopped world state synchronizer 09:23:40 [09:23:40.910] INFO: world_state Created world state synchroniser with block history of infinity 09:23:41 [09:23:41.055] INFO: world_state Started world state synchronizer from block 1 09:23:41 [09:23:41.361] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.3731209999996281,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:41 [09:23:41.365] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.3765910000001895,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:41 [09:23:41.367] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.1498849999998129,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:41 [09:23:41.368] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.203975999999784,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:41 [09:23:41.370] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.06068200000027,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:41 [09:23:41.547] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":14.182482000000164,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:41 [09:23:41.549] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.1891850000001796,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:41 [09:23:41.550] INFO: world_state Stopped world state synchronizer 09:23:41 [09:23:41.551] INFO: world_state Created world state synchroniser with block history of infinity 09:23:41 [09:23:41.757] INFO: world_state Started world state synchronizer from block 1 09:23:42 [09:23:42.051] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.5374659999997675,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:42 [09:23:42.053] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.5859769999997297,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:42 [09:23:42.055] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.008759999999711,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:42 [09:23:42.057] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1667649999999412,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:42 [09:23:42.058] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2254869999997027,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:42 [09:23:42.352] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.4019810000008874,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:42 [09:23:42.354] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.2685180000007676,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:42 [09:23:42.356] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.2349169999997684,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:42 [09:23:42.357] INFO: world_state Stopped world state synchronizer 09:23:42 [09:23:42.358] INFO: world_state Created world state synchroniser with block history of infinity 09:23:42 [09:23:42.520] INFO: world_state Started world state synchronizer from block 1 09:23:42 [09:23:42.813] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.4611629999999423,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:42 [09:23:42.815] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.2372059999997873,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:42 [09:23:42.817] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.1946649999999863,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:42 [09:23:42.818] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2876989999995203,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:42 [09:23:42.820] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.319919000000482,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:42 [09:23:42.823] INFO: world_state Stopped world state synchronizer 09:23:42 [09:23:42.825] INFO: world_state Created world state synchroniser with block history of infinity 09:23:42 [09:23:42.996] INFO: world_state Started world state synchronizer from block 1 09:23:43 [09:23:43.288] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":19.44717800000035,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:43 [09:23:43.290] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.3043989999996484,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:43 [09:23:43.292] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.8965459999999439,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:43 [09:23:43.294] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1890050000001793,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:43 [09:23:43.296] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.113652999999431,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:23:43 [09:23:43.298] INFO: world_state Stopped world state synchronizer 09:23:43 [09:23:43.299] INFO: world_state Created world state synchroniser with block history of infinity 09:23:43 [09:23:43.302] INFO: world_state Stopped world state synchronizer 09:23:43 [09:23:43.304] INFO: world_state Created world state synchroniser with block history of infinity 09:23:43 [09:23:43.471] INFO: world_state Started world state synchronizer from block 1 09:23:43 [09:23:43.807] INFO: world_state Stopped world state synchronizer 09:23:43 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.536 s) 09:23:43 ServerWorldStateSynchronizer 09:23:43 ✓ updates sync progress (845 ms) 09:23:43 ✓ handles multiple calls to start (457 ms) 09:23:43 ✓ immediately syncs if no new blocks (2 ms) 09:23:43 ✓ cannot be started if already stopped (19 ms) 09:23:43 ✓ can immediately sync to latest (641 ms) 09:23:43 ✓ can immediately sync to a minimum block number (806 ms) 09:23:43 ✓ sync returns immediately if block was already synced (466 ms) 09:23:43 ✓ throws if you try to sync to an unavailable block (474 ms) 09:23:43 ✓ throws if you try to immediate sync when not running (4 ms) 09:23:43 ✓ throws if handling blocks fails (505 ms) 09:23:43 09:23:43 Test Suites: 1 passed, 1 total 09:23:43 Tests: 10 passed, 10 total 09:23:43 Snapshots: 0 total 09:23:43 Time: 6.607 s 09:23:43 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 09:23:43 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?