Parent Log: http://ci.aztec-labs.com/6a5301d8067cde2f Command: 3713b4397d55fa0c yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/314d7622c6bacc8333ae49fe9583967cab78349b Env: REF_NAME=gh-readonly-queue/next/pr-15000-c51485c507bcf668c6e55129eb2c1794af4a0732 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Fri Jun 13 11:34:52 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15000_amd64_x2-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 11:34:56 [11:34:56.110] INFO: world_state Created world state synchroniser with block history of infinity 11:34:56 [11:34:56.245] INFO: world_state Started world state synchronizer from block 1 11:34:56 [11:34:56.715] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":6.093656999999894,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:56 [11:34:56.723] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":5.149509000000307,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:56 [11:34:56.726] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.9845470000000205,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:56 [11:34:56.939] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":9.222295999999915,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:56 [11:34:56.941] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.013010000000122,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:56 [11:34:56.942] INFO: world_state Stopped world state synchronizer 11:34:56 [11:34:56.943] INFO: world_state Stopped world state synchronizer 11:34:56 [11:34:56.946] INFO: world_state Created world state synchroniser with block history of infinity 11:34:57 [11:34:57.088] INFO: world_state Started world state synchronizer from block 1 11:34:57 [11:34:57.424] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":2.0012479999995776,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:57 [11:34:57.427] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.854193999999552,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:57 [11:34:57.429] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.6851190000006682,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:57 [11:34:57.432] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.026699000000008,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:57 [11:34:57.434] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.4853030000003855,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:57 [11:34:57.436] INFO: world_state Stopped world state synchronizer 11:34:57 [11:34:57.438] INFO: world_state Created world state synchroniser with block history of infinity 11:34:57 [11:34:57.439] INFO: world_state Started world state synchronizer from block 1 11:34:57 [11:34:57.440] INFO: world_state Stopped world state synchronizer 11:34:57 [11:34:57.441] INFO: world_state Created world state synchroniser with block history of infinity 11:34:57 [11:34:57.441] INFO: world_state Started world state synchronizer from block 1 11:34:57 [11:34:57.442] INFO: world_state Stopped world state synchronizer 11:34:57 [11:34:57.461] INFO: world_state Stopped world state synchronizer 11:34:57 [11:34:57.462] INFO: world_state Created world state synchroniser with block history of infinity 11:34:57 [11:34:57.628] INFO: world_state Started world state synchronizer from block 1 11:34:57 [11:34:57.847] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.8628449999996519,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:57 [11:34:57.849] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.9088649999994232,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:57 [11:34:57.851] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0798809999996593,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:57 [11:34:57.852] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.9941389999994499,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:57 [11:34:57.854] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0681510000003982,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:58 [11:34:58.031] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.5077339999998003,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:58 [11:34:58.033] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.186333999999988,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:58 [11:34:58.034] INFO: world_state Stopped world state synchronizer 11:34:58 [11:34:58.036] INFO: world_state Created world state synchroniser with block history of infinity 11:34:58 [11:34:58.220] INFO: world_state Started world state synchronizer from block 1 11:34:58 [11:34:58.510] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.177494000000479,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:58 [11:34:58.511] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0366409999996904,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:58 [11:34:58.513] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8672950000000128,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:58 [11:34:58.514] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.9475280000006023,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:58 [11:34:58.515] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.823153999999704,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:58 [11:34:58.851] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.0823600000003353,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:58 [11:34:58.853] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.8179019999997763,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:58 [11:34:58.854] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":0.7493800000002011,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:58 [11:34:58.874] INFO: world_state Stopped world state synchronizer 11:34:58 [11:34:58.878] INFO: world_state Created world state synchroniser with block history of infinity 11:34:59 [11:34:59.039] INFO: world_state Started world state synchronizer from block 1 11:34:59 [11:34:59.278] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.410029000000577,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:59 [11:34:59.279] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0837899999996807,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:59 [11:34:59.281] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0456789999998364,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:59 [11:34:59.283] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.117091000000073,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:59 [11:34:59.284] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1445109999995111,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:59 [11:34:59.286] INFO: world_state Stopped world state synchronizer 11:34:59 [11:34:59.288] INFO: world_state Created world state synchroniser with block history of infinity 11:34:59 [11:34:59.440] INFO: world_state Started world state synchronizer from block 1 11:34:59 [11:34:59.735] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.004598000000442,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:59 [11:34:59.736] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.12170100000003,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:59 [11:34:59.738] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.104989999999816,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:59 [11:34:59.740] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.0284089999995558,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:59 [11:34:59.741] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0108680000003005,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 11:34:59 [11:34:59.743] INFO: world_state Stopped world state synchronizer 11:34:59 [11:34:59.745] INFO: world_state Created world state synchroniser with block history of infinity 11:34:59 [11:34:59.749] INFO: world_state Stopped world state synchronizer 11:34:59 [11:34:59.751] INFO: world_state Created world state synchroniser with block history of infinity 11:34:59 [11:34:59.915] INFO: world_state Started world state synchronizer from block 1 11:35:00 [11:35:00.165] INFO: world_state Stopped world state synchronizer 11:35:00 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.256 s) 11:35:00 ServerWorldStateSynchronizer 11:35:00 ✓ updates sync progress (838 ms) 11:35:00 ✓ handles multiple calls to start (491 ms) 11:35:00 ✓ immediately syncs if no new blocks (3 ms) 11:35:00 ✓ cannot be started if already stopped (21 ms) 11:35:00 ✓ can immediately sync to latest (574 ms) 11:35:00 ✓ can immediately sync to a minimum block number (840 ms) 11:35:00 ✓ sync returns immediately if block was already synced (410 ms) 11:35:00 ✓ throws if you try to sync to an unavailable block (457 ms) 11:35:00 ✓ throws if you try to immediate sync when not running (6 ms) 11:35:00 ✓ throws if handling blocks fails (416 ms) 11:35:00 11:35:00 Test Suites: 1 passed, 1 total 11:35:00 Tests: 10 passed, 10 total 11:35:00 Snapshots: 0 total 11:35:00 Time: 6.352 s 11:35:00 Ran all test suites matching /world-state\/src\/synchronizer\/server_world_state_synchronizer.test.ts/i. 11:35:00 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?