Parent Log: http://ci.aztec-labs.com/b6a274e7e4414258 Command: 3987b0c732d12451 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/d3bba2d69dbc070d51bcd50607354193573876ba Env: REF_NAME=gh-readonly-queue/next/pr-14877-314d7622c6bacc8333ae49fe9583967cab78349b CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Fri Jun 13 12:55:58 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14877_amd64_x4-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 12:56:01 [12:56:01.173] INFO: world_state Created world state synchroniser with block history of infinity 12:56:01 [12:56:01.302] INFO: world_state Started world state synchronizer from block 1 12:56:01 [12:56:01.691] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":3.4989550000000236,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:01 [12:56:01.694] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.6674459999999272,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:01 [12:56:01.696] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.3779489999997168,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:01 [12:56:01.849] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.9312119999999595,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:01 [12:56:01.852] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.667645999999877,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:01 [12:56:01.854] INFO: world_state Stopped world state synchronizer 12:56:01 [12:56:01.856] INFO: world_state Stopped world state synchronizer 12:56:01 [12:56:01.858] INFO: world_state Created world state synchroniser with block history of infinity 12:56:01 [12:56:01.996] INFO: world_state Started world state synchronizer from block 1 12:56:02 [12:56:02.243] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.6637959999998202,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:02 [12:56:02.246] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.7210170000003018,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:02 [12:56:02.248] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.51152299999967,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:02 [12:56:02.251] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.7457680000002256,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:02 [12:56:02.252] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1127040000001216,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:02 [12:56:02.254] INFO: world_state Stopped world state synchronizer 12:56:02 [12:56:02.255] INFO: world_state Created world state synchroniser with block history of infinity 12:56:02 [12:56:02.256] INFO: world_state Started world state synchronizer from block 1 12:56:02 [12:56:02.257] INFO: world_state Stopped world state synchronizer 12:56:02 [12:56:02.258] INFO: world_state Created world state synchroniser with block history of infinity 12:56:02 [12:56:02.258] INFO: world_state Started world state synchronizer from block 1 12:56:02 [12:56:02.258] INFO: world_state Stopped world state synchronizer 12:56:02 [12:56:02.281] INFO: world_state Stopped world state synchronizer 12:56:02 [12:56:02.283] INFO: world_state Created world state synchroniser with block history of infinity 12:56:02 [12:56:02.439] INFO: world_state Started world state synchronizer from block 1 12:56:02 [12:56:02.682] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.8822389999995721,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:02 [12:56:02.683] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.6498539999993227,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:02 [12:56:02.684] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.6821350000000166,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:02 [12:56:02.685] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.6427140000005238,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:02 [12:56:02.686] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.7437959999997474,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:02 [12:56:02.851] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.2548470000001544,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:02 [12:56:02.853] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.1304440000003524,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:02 [12:56:02.855] INFO: world_state Stopped world state synchronizer 12:56:02 [12:56:02.857] INFO: world_state Created world state synchroniser with block history of infinity 12:56:03 [12:56:03.033] INFO: world_state Started world state synchronizer from block 1 12:56:03 [12:56:03.325] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.2893480000002455,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:03 [12:56:03.328] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.123873999999887,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:03 [12:56:03.330] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.10912400000052,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:03 [12:56:03.332] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.0510329999997339,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:03 [12:56:03.334] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0897429999995438,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:03 [12:56:03.648] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.539933000000019,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:03 [12:56:03.670] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":20.254484999999477,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:03 [12:56:03.672] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.3822789999994711,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:03 [12:56:03.676] INFO: world_state Stopped world state synchronizer 12:56:03 [12:56:03.679] INFO: world_state Created world state synchroniser with block history of infinity 12:56:03 [12:56:03.844] INFO: world_state Started world state synchronizer from block 1 12:56:04 [12:56:04.165] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.2442360000004555,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:04 [12:56:04.167] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.1395540000003166,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:04 [12:56:04.168] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.1077130000003308,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:04 [12:56:04.170] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.129334000000199,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:04 [12:56:04.172] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1724750000003041,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:04 [12:56:04.174] INFO: world_state Stopped world state synchronizer 12:56:04 [12:56:04.175] INFO: world_state Created world state synchroniser with block history of infinity 12:56:04 [12:56:04.356] INFO: world_state Started world state synchronizer from block 1 12:56:04 [12:56:04.617] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.8325979999999618,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:04 [12:56:04.618] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.8821189999998751,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:04 [12:56:04.620] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.868888999999399,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:04 [12:56:04.621] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.8128779999997278,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:04 [12:56:04.622] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0115820000000895,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:56:04 [12:56:04.624] INFO: world_state Stopped world state synchronizer 12:56:04 [12:56:04.626] INFO: world_state Created world state synchroniser with block history of infinity 12:56:04 [12:56:04.629] INFO: world_state Stopped world state synchronizer 12:56:04 [12:56:04.630] INFO: world_state Created world state synchroniser with block history of infinity 12:56:04 [12:56:04.772] INFO: world_state Started world state synchronizer from block 1 12:56:05 [12:56:05.046] INFO: world_state Stopped world state synchronizer 12:56:05 PASS src/synchronizer/server_world_state_synchronizer.test.ts (5.846 s) 12:56:05 ServerWorldStateSynchronizer 12:56:05 ✓ updates sync progress (687 ms) 12:56:05 ✓ handles multiple calls to start (396 ms) 12:56:05 ✓ immediately syncs if no new blocks (3 ms) 12:56:05 ✓ cannot be started if already stopped (24 ms) 12:56:05 ✓ can immediately sync to latest (573 ms) 12:56:05 ✓ can immediately sync to a minimum block number (820 ms) 12:56:05 ✓ sync returns immediately if block was already synced (496 ms) 12:56:05 ✓ throws if you try to sync to an unavailable block (450 ms) 12:56:05 ✓ throws if you try to immediate sync when not running (4 ms) 12:56:05 ✓ throws if handling blocks fails (416 ms) 12:56:05 12:56:05 Test Suites: 1 passed, 1 total 12:56:05 Tests: 10 passed, 10 total 12:56:05 Snapshots: 0 total 12:56:05 Time: 5.937 s 12:56:05 Ran all test suites matching /world-state\/src\/synchronizer\/server_world_state_synchronizer.test.ts/i. 12:56:05 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?