Parent Log: http://ci.aztec-labs.com/a0f0f050e593860b Command: 4dcaaab13058c9f1 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/1542a83b8e029762e119474de1725d84a1aee3d2 Env: REF_NAME=gh-readonly-queue/next/pr-15095-eb7990c89ac679ce7fdfc2e985670cbf85c04be2 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Tue Jun 17 10:15:56 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15095_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:16:00 [10:16:00.810] INFO: world_state Created world state synchroniser with block history of infinity 10:16:00 [10:16:00.962] INFO: world_state Started world state synchronizer from block 1 10:16:01 [10:16:01.410] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":4.960331000000224,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:01 [10:16:01.414] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.2989219999999477,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:01 [10:16:01.417] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.20708999999988,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:01 [10:16:01.610] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.217779999999948,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:01 [10:16:01.613] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":2.0174550000001545,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:01 [10:16:01.619] INFO: world_state Stopped world state synchronizer 10:16:01 [10:16:01.621] INFO: world_state Stopped world state synchronizer 10:16:01 [10:16:01.623] INFO: world_state Created world state synchroniser with block history of infinity 10:16:01 [10:16:01.789] INFO: world_state Started world state synchronizer from block 1 10:16:02 [10:16:02.127] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.5234540000001289,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:02 [10:16:02.130] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.443822000000182,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:02 [10:16:02.132] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.3631909999994605,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:02 [10:16:02.134] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.643286999999873,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:02 [10:16:02.136] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.359121000000414,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:02 [10:16:02.138] INFO: world_state Stopped world state synchronizer 10:16:02 [10:16:02.140] INFO: world_state Created world state synchroniser with block history of infinity 10:16:02 [10:16:02.140] INFO: world_state Started world state synchronizer from block 1 10:16:02 [10:16:02.141] INFO: world_state Stopped world state synchronizer 10:16:02 [10:16:02.142] INFO: world_state Created world state synchroniser with block history of infinity 10:16:02 [10:16:02.143] INFO: world_state Started world state synchronizer from block 1 10:16:02 [10:16:02.143] INFO: world_state Stopped world state synchronizer 10:16:02 [10:16:02.163] INFO: world_state Stopped world state synchronizer 10:16:02 [10:16:02.165] INFO: world_state Created world state synchroniser with block history of infinity 10:16:02 [10:16:02.361] INFO: world_state Started world state synchronizer from block 1 10:16:02 [10:16:02.661] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.260258000000249,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:02 [10:16:02.663] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0219520000000557,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:02 [10:16:02.664] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9847520000002987,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:02 [10:16:02.665] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.9568720000006579,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:02 [10:16:02.667] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9758420000007391,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:02 [10:16:02.824] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.4661219999998139,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:02 [10:16:02.826] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.3056589999996504,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:02 [10:16:02.828] INFO: world_state Stopped world state synchronizer 10:16:02 [10:16:02.830] INFO: world_state Created world state synchroniser with block history of infinity 10:16:03 [10:16:03.003] INFO: world_state Started world state synchronizer from block 1 10:16:03 [10:16:03.334] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":3.532959000000119,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:03 [10:16:03.336] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.312989000000016,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:03 [10:16:03.338] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.231167999999343,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:03 [10:16:03.340] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2332580000002054,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:03 [10:16:03.341] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1793259999994916,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:03 [10:16:03.665] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.1141139999999723,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:03 [10:16:03.666] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.010153000000173,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:03 [10:16:03.670] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":0.8549389999998311,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:03 [10:16:03.679] INFO: world_state Stopped world state synchronizer 10:16:03 [10:16:03.681] INFO: world_state Created world state synchroniser with block history of infinity 10:16:03 [10:16:03.827] INFO: world_state Started world state synchronizer from block 1 10:16:04 [10:16:04.100] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1876659999998083,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:04 [10:16:04.102] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.866769000000204,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:04 [10:16:04.103] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9599310000003243,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:04 [10:16:04.104] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.8985199999997349,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:04 [10:16:04.106] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1122949999999037,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:04 [10:16:04.109] INFO: world_state Stopped world state synchronizer 10:16:04 [10:16:04.111] INFO: world_state Created world state synchroniser with block history of infinity 10:16:04 [10:16:04.310] INFO: world_state Started world state synchronizer from block 1 10:16:04 [10:16:04.611] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.3997410000001764,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:04 [10:16:04.613] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.177875999999742,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:04 [10:16:04.614] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.1535559999992984,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:04 [10:16:04.616] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.201096000000689,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:04 [10:16:04.618] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0743139999995037,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:16:04 [10:16:04.620] INFO: world_state Stopped world state synchronizer 10:16:04 [10:16:04.621] INFO: world_state Created world state synchroniser with block history of infinity 10:16:04 [10:16:04.624] INFO: world_state Stopped world state synchronizer 10:16:04 [10:16:04.626] INFO: world_state Created world state synchroniser with block history of infinity 10:16:04 [10:16:04.826] INFO: world_state Started world state synchronizer from block 1 10:16:05 [10:16:05.185] INFO: world_state Stopped world state synchronizer 10:16:05 PASS src/synchronizer/server_world_state_synchronizer.test.ts (7.059 s) 10:16:05 ServerWorldStateSynchronizer 10:16:05 ✓ updates sync progress (817 ms) 10:16:05 ✓ handles multiple calls to start (515 ms) 10:16:05 ✓ immediately syncs if no new blocks (2 ms) 10:16:05 ✓ cannot be started if already stopped (21 ms) 10:16:05 ✓ can immediately sync to latest (664 ms) 10:16:05 ✓ can immediately sync to a minimum block number (851 ms) 10:16:05 ✓ sync returns immediately if block was already synced (428 ms) 10:16:05 ✓ throws if you try to sync to an unavailable block (510 ms) 10:16:05 ✓ throws if you try to immediate sync when not running (5 ms) 10:16:05 ✓ throws if handling blocks fails (560 ms) 10:16:05 10:16:05 Test Suites: 1 passed, 1 total 10:16:05 Tests: 10 passed, 10 total 10:16:05 Snapshots: 0 total 10:16:05 Time: 7.186 s 10:16:05 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 10:16:05 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?