Parent Log: http://ci.aztec-labs.com/8251982aad1ef834 Command: a5511b51e55ea9c0 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/9cbd161a1dfa928167c1f969200b95df55ed4c6b Env: REF_NAME=gh-readonly-queue/next/pr-15157-6e3848bbd6845559b55825f0bc64df0673272969 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Fri Jun 20 09:43:38 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15157_amd64_x2-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 09:43:41 [09:43:41.694] INFO: world_state Created world state synchroniser with block history of infinity 09:43:41 [09:43:41.824] INFO: world_state Started world state synchronizer from block 1 09:43:42 [09:43:42.259] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":6.856483999999909,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:42 [09:43:42.264] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.816584000000148,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:42 [09:43:42.267] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.3661809999998695,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:42 [09:43:42.478] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":20.819821999999476,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:42 [09:43:42.482] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":2.663118999999824,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:42 [09:43:42.484] INFO: world_state Stopped world state synchronizer 09:43:42 [09:43:42.486] INFO: world_state Stopped world state synchronizer 09:43:42 [09:43:42.489] INFO: world_state Created world state synchroniser with block history of infinity 09:43:42 [09:43:42.685] INFO: world_state Started world state synchronizer from block 1 09:43:43 [09:43:43.019] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.5754270000006727,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:43 [09:43:43.022] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.6290389999994659,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:43 [09:43:43.024] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.485504000000219,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:43 [09:43:43.027] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.714292000000569,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:43 [09:43:43.030] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.980109000000084,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:43 [09:43:43.032] INFO: world_state Stopped world state synchronizer 09:43:43 [09:43:43.034] INFO: world_state Created world state synchroniser with block history of infinity 09:43:43 [09:43:43.035] INFO: world_state Started world state synchronizer from block 1 09:43:43 [09:43:43.035] INFO: world_state Stopped world state synchronizer 09:43:43 [09:43:43.037] INFO: world_state Created world state synchroniser with block history of infinity 09:43:43 [09:43:43.037] INFO: world_state Started world state synchronizer from block 1 09:43:43 [09:43:43.038] INFO: world_state Stopped world state synchronizer 09:43:43 [09:43:43.066] INFO: world_state Stopped world state synchronizer 09:43:43 [09:43:43.068] INFO: world_state Created world state synchroniser with block history of infinity 09:43:43 [09:43:43.270] INFO: world_state Started world state synchronizer from block 1 09:43:43 [09:43:43.563] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.2821190000004208,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:43 [09:43:43.564] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9628189999993992,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:43 [09:43:43.566] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.95977799999946,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:43 [09:43:43.567] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.9265179999993052,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:43 [09:43:43.568] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8599360000007437,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:43 [09:43:43.777] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.9665180000001783,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:43 [09:43:43.780] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.5692369999997027,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:43 [09:43:43.782] INFO: world_state Stopped world state synchronizer 09:43:43 [09:43:43.785] INFO: world_state Created world state synchroniser with block history of infinity 09:43:43 [09:43:43.979] INFO: world_state Started world state synchronizer from block 1 09:43:44 [09:43:44.246] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.4658140000001367,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:44 [09:43:44.248] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0021600000000035,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:44 [09:43:44.250] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2946680000004562,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:44 [09:43:44.252] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2476980000001276,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:44 [09:43:44.253] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2853290000002744,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:44 [09:43:44.510] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.0908820000004198,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:44 [09:43:44.511] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.0015000000003056,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:44 [09:43:44.514] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.346969999999601,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:44 [09:43:44.526] INFO: world_state Stopped world state synchronizer 09:43:44 [09:43:44.528] INFO: world_state Created world state synchroniser with block history of infinity 09:43:44 [09:43:44.676] INFO: world_state Started world state synchronizer from block 1 09:43:44 [09:43:44.953] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.906637000000046,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:44 [09:43:44.955] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.478334000000359,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:44 [09:43:44.957] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.6811399999996866,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:44 [09:43:44.960] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.5194350000001577,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:44 [09:43:44.961] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.3594110000003639,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:44 [09:43:44.964] INFO: world_state Stopped world state synchronizer 09:43:44 [09:43:44.965] INFO: world_state Created world state synchroniser with block history of infinity 09:43:45 [09:43:45.126] INFO: world_state Started world state synchronizer from block 1 09:43:45 [09:43:45.433] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.469213999999738,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:45 [09:43:45.436] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.712351000000126,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:45 [09:43:45.439] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.672049999999217,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:45 [09:43:45.441] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2642079999995985,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:45 [09:43:45.442] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1833449999994627,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:43:45 [09:43:45.444] INFO: world_state Stopped world state synchronizer 09:43:45 [09:43:45.451] INFO: world_state Created world state synchroniser with block history of infinity 09:43:45 [09:43:45.455] INFO: world_state Stopped world state synchronizer 09:43:45 [09:43:45.457] INFO: world_state Created world state synchroniser with block history of infinity 09:43:45 [09:43:45.650] INFO: world_state Started world state synchronizer from block 1 09:43:45 [09:43:45.936] INFO: world_state Stopped world state synchronizer 09:43:45 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.584 s) 09:43:45 ServerWorldStateSynchronizer 09:43:45 ✓ updates sync progress (797 ms) 09:43:45 ✓ handles multiple calls to start (544 ms) 09:43:45 ✓ immediately syncs if no new blocks (3 ms) 09:43:45 ✓ cannot be started if already stopped (31 ms) 09:43:45 ✓ can immediately sync to latest (716 ms) 09:43:45 ✓ can immediately sync to a minimum block number (743 ms) 09:43:45 ✓ sync returns immediately if block was already synced (437 ms) 09:43:45 ✓ throws if you try to sync to an unavailable block (484 ms) 09:43:45 ✓ throws if you try to immediate sync when not running (6 ms) 09:43:45 ✓ throws if handling blocks fails (480 ms) 09:43:45 09:43:45 Test Suites: 1 passed, 1 total 09:43:45 Tests: 10 passed, 10 total 09:43:45 Snapshots: 0 total 09:43:45 Time: 6.669 s 09:43:45 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 09:43:45 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?