Parent Log: http://ci.aztec-labs.com/9b7174caf3ef97da Command: 816a8a0e28c14627 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/7d223783d91db15002a09abc1b52d1455eb3e3da Env: REF_NAME=gh-readonly-queue/next/pr-14161-97080f178c0a3e244c64b3c17bf255bed63fc3cf CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Mon Jun 16 10:41:03 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14161_amd64_x3-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 10:41:06 [10:41:06.807] INFO: world_state Created world state synchroniser with block history of infinity 10:41:06 [10:41:06.958] INFO: world_state Started world state synchronizer from block 1 10:41:07 [10:41:07.434] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":7.991679000000204,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:07 [10:41:07.440] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":3.2533109999999397,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:07 [10:41:07.444] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.8614540000003217,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:07 [10:41:07.627] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.414216000000124,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:07 [10:41:07.629] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.998337999999876,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:07 [10:41:07.630] INFO: world_state Stopped world state synchronizer 10:41:07 [10:41:07.631] INFO: world_state Stopped world state synchronizer 10:41:07 [10:41:07.634] INFO: world_state Created world state synchroniser with block history of infinity 10:41:07 [10:41:07.820] INFO: world_state Started world state synchronizer from block 1 10:41:08 [10:41:08.112] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.195332999999664,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:08 [10:41:08.114] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0357700000004115,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:08 [10:41:08.115] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9069170000002487,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:08 [10:41:08.117] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.116971000000376,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:08 [10:41:08.118] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9866989999991347,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:08 [10:41:08.119] INFO: world_state Stopped world state synchronizer 10:41:08 [10:41:08.121] INFO: world_state Created world state synchroniser with block history of infinity 10:41:08 [10:41:08.121] INFO: world_state Started world state synchronizer from block 1 10:41:08 [10:41:08.122] INFO: world_state Stopped world state synchronizer 10:41:08 [10:41:08.122] INFO: world_state Created world state synchroniser with block history of infinity 10:41:08 [10:41:08.123] INFO: world_state Started world state synchronizer from block 1 10:41:08 [10:41:08.123] INFO: world_state Stopped world state synchronizer 10:41:08 [10:41:08.143] INFO: world_state Stopped world state synchronizer 10:41:08 [10:41:08.145] INFO: world_state Created world state synchroniser with block history of infinity 10:41:08 [10:41:08.346] INFO: world_state Started world state synchronizer from block 1 10:41:08 [10:41:08.672] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":2.0632189999996626,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:08 [10:41:08.674] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.5776800000003277,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:08 [10:41:08.676] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.611439999999675,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:08 [10:41:08.679] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.4435770000000048,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:08 [10:41:08.681] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.4553169999999227,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:08 [10:41:08.902] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":17.643871000000217,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:08 [10:41:08.905] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.502758999999969,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:08 [10:41:08.907] INFO: world_state Stopped world state synchronizer 10:41:08 [10:41:08.909] INFO: world_state Created world state synchroniser with block history of infinity 10:41:09 [10:41:09.121] INFO: world_state Started world state synchronizer from block 1 10:41:09 [10:41:09.508] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.599380999999994,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:09 [10:41:09.510] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.1396519999998418,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:09 [10:41:09.512] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2779239999999845,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:09 [10:41:09.513] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1732120000006034,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:09 [10:41:09.515] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.3166650000002846,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:09 [10:41:09.817] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":0.9780780000000959,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:09 [10:41:09.819] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.1468510000004244,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:09 [10:41:09.820] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.2134130000004006,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:09 [10:41:09.823] INFO: world_state Stopped world state synchronizer 10:41:09 [10:41:09.825] INFO: world_state Created world state synchroniser with block history of infinity 10:41:09 [10:41:09.998] INFO: world_state Started world state synchronizer from block 1 10:41:10 [10:41:10.306] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.0694299999995565,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:10 [10:41:10.307] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.966747999999825,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:10 [10:41:10.309] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8676760000007562,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:10 [10:41:10.311] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.4531370000004245,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:10 [10:41:10.313] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2079530000000887,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:10 [10:41:10.315] INFO: world_state Stopped world state synchronizer 10:41:10 [10:41:10.316] INFO: world_state Created world state synchroniser with block history of infinity 10:41:10 [10:41:10.515] INFO: world_state Started world state synchronizer from block 1 10:41:10 [10:41:10.811] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.4324799999994866,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:10 [10:41:10.813] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.3576190000003407,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:10 [10:41:10.815] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.415860000000066,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:10 [10:41:10.817] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3500089999997726,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:10 [10:41:10.819] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.231206000000384,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:41:10 [10:41:10.821] INFO: world_state Stopped world state synchronizer 10:41:10 [10:41:10.823] INFO: world_state Created world state synchroniser with block history of infinity 10:41:10 [10:41:10.826] INFO: world_state Stopped world state synchronizer 10:41:10 [10:41:10.827] INFO: world_state Created world state synchroniser with block history of infinity 10:41:11 [10:41:11.012] INFO: world_state Started world state synchronizer from block 1 10:41:11 [10:41:11.317] INFO: world_state Stopped world state synchronizer 10:41:11 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.729 s) 10:41:11 ServerWorldStateSynchronizer 10:41:11 ✓ updates sync progress (831 ms) 10:41:11 ✓ handles multiple calls to start (486 ms) 10:41:11 ✓ immediately syncs if no new blocks (2 ms) 10:41:11 ✓ cannot be started if already stopped (22 ms) 10:41:11 ✓ can immediately sync to latest (763 ms) 10:41:11 ✓ can immediately sync to a minimum block number (915 ms) 10:41:11 ✓ sync returns immediately if block was already synced (492 ms) 10:41:11 ✓ throws if you try to sync to an unavailable block (505 ms) 10:41:11 ✓ throws if you try to immediate sync when not running (4 ms) 10:41:11 ✓ throws if handling blocks fails (490 ms) 10:41:11 10:41:11 Test Suites: 1 passed, 1 total 10:41:11 Tests: 10 passed, 10 total 10:41:11 Snapshots: 0 total 10:41:11 Time: 6.821 s 10:41:11 Ran all test suites matching /world-state\/src\/synchronizer\/server_world_state_synchronizer.test.ts/i. 10:41:11 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?