Parent Log: http://ci.aztec-labs.com/fa73fdbe320cad40 Command: 61f8e44af0fc4001 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/e56baa7f24bac54baf9e2f22f6f33ae6fa8b8c0f Env: REF_NAME=gh-readonly-queue/next/pr-14891-76ca48a2187e3506bb464eae574e49476c2876ca CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Fri Jun 13 19:23:32 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14891_amd64_x3-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 19:23:35 [19:23:35.555] INFO: world_state Created world state synchroniser with block history of infinity 19:23:35 [19:23:35.651] INFO: world_state Started world state synchronizer from block 1 19:23:35 [19:23:35.998] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":5.596654999999828,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:36 [19:23:36.003] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":3.2324640000001637,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:36 [19:23:36.006] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.9582310000000689,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:36 [19:23:36.190] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.0159319999997933,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:36 [19:23:36.193] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.7736360000003515,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:36 [19:23:36.195] INFO: world_state Stopped world state synchronizer 19:23:36 [19:23:36.196] INFO: world_state Stopped world state synchronizer 19:23:36 [19:23:36.199] INFO: world_state Created world state synchroniser with block history of infinity 19:23:36 [19:23:36.325] INFO: world_state Started world state synchronizer from block 1 19:23:36 [19:23:36.570] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.9205940000001647,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:36 [19:23:36.571] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.7916710000004059,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:36 [19:23:36.572] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.7392589999999473,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:36 [19:23:36.574] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.9288139999998748,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:36 [19:23:36.575] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8708120000001145,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:36 [19:23:36.576] INFO: world_state Stopped world state synchronizer 19:23:36 [19:23:36.577] INFO: world_state Created world state synchroniser with block history of infinity 19:23:36 [19:23:36.578] INFO: world_state Started world state synchronizer from block 1 19:23:36 [19:23:36.578] INFO: world_state Stopped world state synchronizer 19:23:36 [19:23:36.579] INFO: world_state Created world state synchroniser with block history of infinity 19:23:36 [19:23:36.579] INFO: world_state Started world state synchronizer from block 1 19:23:36 [19:23:36.580] INFO: world_state Stopped world state synchronizer 19:23:36 [19:23:36.593] INFO: world_state Stopped world state synchronizer 19:23:36 [19:23:36.594] INFO: world_state Created world state synchroniser with block history of infinity 19:23:36 [19:23:36.724] INFO: world_state Started world state synchronizer from block 1 19:23:36 [19:23:36.947] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.8908030000002327,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:36 [19:23:36.949] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.887072999999873,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:36 [19:23:36.950] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.7755800000004456,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:36 [19:23:36.951] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.6687170000004699,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:36 [19:23:36.952] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8920130000005884,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:37 [19:23:37.087] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":0.8908029999993232,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:37 [19:23:37.089] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.1379989999995814,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:37 [19:23:37.090] INFO: world_state Stopped world state synchronizer 19:23:37 [19:23:37.092] INFO: world_state Created world state synchroniser with block history of infinity 19:23:37 [19:23:37.212] INFO: world_state Started world state synchronizer from block 1 19:23:37 [19:23:37.453] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.0516769999994722,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:37 [19:23:37.454] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0351670000000013,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:37 [19:23:37.456] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8972130000001926,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:37 [19:23:37.457] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.0433669999993072,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:37 [19:23:37.459] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9893749999991996,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:37 [19:23:37.678] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":0.8332620000001043,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:37 [19:23:37.680] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.7224390000001222,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:37 [19:23:37.681] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":0.6626880000003439,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:37 [19:23:37.682] INFO: world_state Stopped world state synchronizer 19:23:37 [19:23:37.683] INFO: world_state Created world state synchroniser with block history of infinity 19:23:37 [19:23:37.844] INFO: world_state Started world state synchronizer from block 1 19:23:38 [19:23:38.118] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":2.0870130000002973,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:38 [19:23:38.122] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.4046560000006139,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:38 [19:23:38.124] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.1698299999998198,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:38 [19:23:38.125] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2444820000000618,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:38 [19:23:38.127] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.105688000000555,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:38 [19:23:38.132] INFO: world_state Stopped world state synchronizer 19:23:38 [19:23:38.135] INFO: world_state Created world state synchroniser with block history of infinity 19:23:38 [19:23:38.405] INFO: world_state Started world state synchronizer from block 1 19:23:38 [19:23:38.681] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.84718200000043,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:38 [19:23:38.695] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":13.111547999999857,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:38 [19:23:38.698] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.5165489999999409,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:38 [19:23:38.700] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2173220000004221,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:38 [19:23:38.702] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.056866999999329,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:23:38 [19:23:38.704] INFO: world_state Stopped world state synchronizer 19:23:38 [19:23:38.706] INFO: world_state Created world state synchroniser with block history of infinity 19:23:38 [19:23:38.710] INFO: world_state Stopped world state synchronizer 19:23:38 [19:23:38.711] INFO: world_state Created world state synchroniser with block history of infinity 19:23:38 [19:23:38.878] INFO: world_state Started world state synchronizer from block 1 19:23:39 [19:23:39.161] INFO: world_state Stopped world state synchronizer 19:23:39 PASS src/synchronizer/server_world_state_synchronizer.test.ts (5.431 s) 19:23:39 ServerWorldStateSynchronizer 19:23:39 ✓ updates sync progress (645 ms) 19:23:39 ✓ handles multiple calls to start (378 ms) 19:23:39 ✓ immediately syncs if no new blocks (2 ms) 19:23:39 ✓ cannot be started if already stopped (15 ms) 19:23:39 ✓ can immediately sync to latest (497 ms) 19:23:39 ✓ can immediately sync to a minimum block number (591 ms) 19:23:39 ✓ sync returns immediately if block was already synced (450 ms) 19:23:39 ✓ throws if you try to sync to an unavailable block (571 ms) 19:23:39 ✓ throws if you try to immediate sync when not running (5 ms) 19:23:39 ✓ throws if handling blocks fails (451 ms) 19:23:39 19:23:39 Test Suites: 1 passed, 1 total 19:23:39 Tests: 10 passed, 10 total 19:23:39 Snapshots: 0 total 19:23:39 Time: 5.513 s 19:23:39 Ran all test suites matching /world-state\/src\/synchronizer\/server_world_state_synchronizer.test.ts/i. 19:23:39 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?