Parent Log: http://ci.aztec-labs.com/050e54d95cb8be2c Command: f44e1b4f382283e1 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/7d1abcb337009939ee47da8445b5fee80f39fb58 Env: REF_NAME=gh-readonly-queue/next/pr-15156-df137f9440a970af2eb6e2ee94c48bf3bf47e207 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Fri Jun 20 10:05:10 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15156_amd64_x1-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 10:05:14 [10:05:14.189] INFO: world_state Created world state synchroniser with block history of infinity 10:05:14 [10:05:14.332] INFO: world_state Started world state synchronizer from block 1 10:05:14 [10:05:14.763] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":7.090500999999676,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:14 [10:05:14.767] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.611220000000685,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:14 [10:05:14.770] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.1260009999996328,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:14 [10:05:14.986] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.7387799999996787,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:14 [10:05:14.988] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.3793910000003962,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:14 [10:05:14.990] INFO: world_state Stopped world state synchronizer 10:05:14 [10:05:14.991] INFO: world_state Stopped world state synchronizer 10:05:14 [10:05:14.993] INFO: world_state Created world state synchroniser with block history of infinity 10:05:15 [10:05:15.160] INFO: world_state Started world state synchronizer from block 1 10:05:15 [10:05:15.547] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1015790000001289,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:15 [10:05:15.549] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9606679999997141,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:15 [10:05:15.550] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0048210000004474,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:15 [10:05:15.552] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.0239520000004632,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:15 [10:05:15.553] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.940005999999812,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:15 [10:05:15.554] INFO: world_state Stopped world state synchronizer 10:05:15 [10:05:15.555] INFO: world_state Created world state synchroniser with block history of infinity 10:05:15 [10:05:15.556] INFO: world_state Started world state synchronizer from block 1 10:05:15 [10:05:15.556] INFO: world_state Stopped world state synchronizer 10:05:15 [10:05:15.557] INFO: world_state Created world state synchroniser with block history of infinity 10:05:15 [10:05:15.557] INFO: world_state Started world state synchronizer from block 1 10:05:15 [10:05:15.558] INFO: world_state Stopped world state synchronizer 10:05:15 [10:05:15.573] INFO: world_state Stopped world state synchronizer 10:05:15 [10:05:15.574] INFO: world_state Created world state synchroniser with block history of infinity 10:05:15 [10:05:15.765] INFO: world_state Started world state synchronizer from block 1 10:05:16 [10:05:16.110] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.7619019999992815,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:16 [10:05:16.112] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.4438270000000557,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:16 [10:05:16.114] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.3250470000002679,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:16 [10:05:16.116] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3196659999994154,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:16 [10:05:16.119] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.3322270000007848,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:16 [10:05:16.305] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.4745090000005803,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:16 [10:05:16.308] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.5320739999997386,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:16 [10:05:16.310] INFO: world_state Stopped world state synchronizer 10:05:16 [10:05:16.332] INFO: world_state Created world state synchroniser with block history of infinity 10:05:16 [10:05:16.493] INFO: world_state Started world state synchronizer from block 1 10:05:16 [10:05:16.783] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.139672000000246,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:16 [10:05:16.785] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0297230000005584,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:16 [10:05:16.787] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.3086350000003222,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:16 [10:05:16.789] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.9406859999999142,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:16 [10:05:16.791] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.3503389999996216,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:17 [10:05:17.166] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.0984290000005785,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:17 [10:05:17.168] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.9978499999997439,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:17 [10:05:17.170] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.461608000000524,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:17 [10:05:17.172] INFO: world_state Stopped world state synchronizer 10:05:17 [10:05:17.175] INFO: world_state Created world state synchroniser with block history of infinity 10:05:17 [10:05:17.369] INFO: world_state Started world state synchronizer from block 1 10:05:17 [10:05:17.726] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.437065000000075,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:17 [10:05:17.727] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9100330000001122,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:17 [10:05:17.729] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.7937339999998585,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:17 [10:05:17.730] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.8863010000004579,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:17 [10:05:17.731] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8423779999993712,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:17 [10:05:17.733] INFO: world_state Stopped world state synchronizer 10:05:17 [10:05:17.735] INFO: world_state Created world state synchroniser with block history of infinity 10:05:17 [10:05:17.956] INFO: world_state Started world state synchronizer from block 1 10:05:18 [10:05:18.251] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1007180000005974,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:18 [10:05:18.253] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9349250000004758,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:18 [10:05:18.254] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9240550000004077,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:18 [10:05:18.273] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":17.995719000000463,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:18 [10:05:18.275] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2396600000001854,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:05:18 [10:05:18.276] INFO: world_state Stopped world state synchronizer 10:05:18 [10:05:18.278] INFO: world_state Created world state synchroniser with block history of infinity 10:05:18 [10:05:18.280] INFO: world_state Stopped world state synchronizer 10:05:18 [10:05:18.282] INFO: world_state Created world state synchroniser with block history of infinity 10:05:18 [10:05:18.439] INFO: world_state Started world state synchronizer from block 1 10:05:18 [10:05:18.801] INFO: world_state Stopped world state synchronizer 10:05:18 PASS src/synchronizer/server_world_state_synchronizer.test.ts (7.177 s) 10:05:18 ServerWorldStateSynchronizer 10:05:18 ✓ updates sync progress (808 ms) 10:05:18 ✓ handles multiple calls to start (561 ms) 10:05:18 ✓ immediately syncs if no new blocks (2 ms) 10:05:18 ✓ cannot be started if already stopped (16 ms) 10:05:18 ✓ can immediately sync to latest (737 ms) 10:05:18 ✓ can immediately sync to a minimum block number (862 ms) 10:05:18 ✓ sync returns immediately if block was already synced (560 ms) 10:05:18 ✓ throws if you try to sync to an unavailable block (543 ms) 10:05:18 ✓ throws if you try to immediate sync when not running (3 ms) 10:05:18 ✓ throws if handling blocks fails (520 ms) 10:05:18 10:05:18 Test Suites: 1 passed, 1 total 10:05:18 Tests: 10 passed, 10 total 10:05:18 Snapshots: 0 total 10:05:18 Time: 7.269 s 10:05:18 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 10:05:18 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?