Parent Log: http://ci.aztec-labs.com/c03ea752b0af6173 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:22:03 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14891_amd64_x1-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 19:22:06 [19:22:06.144] INFO: world_state Created world state synchroniser with block history of infinity 19:22:06 [19:22:06.290] INFO: world_state Started world state synchronizer from block 1 19:22:06 [19:22:06.662] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":3.5190300000003845,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:06 [19:22:06.665] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.4874119999999493,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:06 [19:22:06.667] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2428350000000137,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:06 [19:22:06.804] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.230674999999792,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:06 [19:22:06.806] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9596769999998287,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:06 [19:22:06.807] INFO: world_state Stopped world state synchronizer 19:22:06 [19:22:06.808] INFO: world_state Stopped world state synchronizer 19:22:06 [19:22:06.810] INFO: world_state Created world state synchroniser with block history of infinity 19:22:06 [19:22:06.924] INFO: world_state Started world state synchronizer from block 1 19:22:07 [19:22:07.248] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.7756900000003952,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:07 [19:22:07.251] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.8306320000001506,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:07 [19:22:07.254] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.7983409999997093,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:07 [19:22:07.257] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.0015669999993406,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:07 [19:22:07.259] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.6207670000003418,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:07 [19:22:07.261] INFO: world_state Stopped world state synchronizer 19:22:07 [19:22:07.263] INFO: world_state Created world state synchroniser with block history of infinity 19:22:07 [19:22:07.264] INFO: world_state Started world state synchronizer from block 1 19:22:07 [19:22:07.265] INFO: world_state Stopped world state synchronizer 19:22:07 [19:22:07.266] INFO: world_state Created world state synchroniser with block history of infinity 19:22:07 [19:22:07.267] INFO: world_state Started world state synchronizer from block 1 19:22:07 [19:22:07.267] INFO: world_state Stopped world state synchronizer 19:22:07 [19:22:07.283] INFO: world_state Stopped world state synchronizer 19:22:07 [19:22:07.285] INFO: world_state Created world state synchroniser with block history of infinity 19:22:07 [19:22:07.448] INFO: world_state Started world state synchronizer from block 1 19:22:07 [19:22:07.708] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.389049000000341,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:07 [19:22:07.710] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.1241819999995641,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:07 [19:22:07.711] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8347039999998742,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:07 [19:22:07.713] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.092290000000503,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:07 [19:22:07.714] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9618469999995796,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:07 [19:22:07.878] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.3208270000004632,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:07 [19:22:07.880] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.0462699999998222,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:07 [19:22:07.881] INFO: world_state Stopped world state synchronizer 19:22:07 [19:22:07.883] INFO: world_state Created world state synchroniser with block history of infinity 19:22:08 [19:22:08.024] INFO: world_state Started world state synchronizer from block 1 19:22:08 [19:22:08.287] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1683839999996053,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:08 [19:22:08.289] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.016139000000294,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:08 [19:22:08.291] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9910780000000159,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:08 [19:22:08.292] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.9911890000003041,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:08 [19:22:08.294] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9966480000002775,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:08 [19:22:08.563] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":0.8807560000004742,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:08 [19:22:08.565] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.8936559999992824,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:08 [19:22:08.566] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":0.7507620000005772,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:08 [19:22:08.567] INFO: world_state Stopped world state synchronizer 19:22:08 [19:22:08.568] INFO: world_state Created world state synchroniser with block history of infinity 19:22:08 [19:22:08.689] INFO: world_state Started world state synchronizer from block 1 19:22:08 [19:22:08.964] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.2325449999998455,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:08 [19:22:08.966] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.063430000000153,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:08 [19:22:08.967] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0646999999999025,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:08 [19:22:08.969] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.0921109999999317,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:08 [19:22:08.970] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9930379999996148,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:08 [19:22:08.973] INFO: world_state Stopped world state synchronizer 19:22:08 [19:22:08.974] INFO: world_state Created world state synchroniser with block history of infinity 19:22:09 [19:22:09.170] INFO: world_state Started world state synchronizer from block 1 19:22:09 [19:22:09.454] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1034309999995457,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:09 [19:22:09.456] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.935577000000194,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:09 [19:22:09.458] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.635946000000331,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:09 [19:22:09.459] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.6730189999998402,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:09 [19:22:09.460] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.88508499999989,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 19:22:09 [19:22:09.462] INFO: world_state Stopped world state synchronizer 19:22:09 [19:22:09.474] INFO: world_state Created world state synchroniser with block history of infinity 19:22:09 [19:22:09.477] INFO: world_state Stopped world state synchronizer 19:22:09 [19:22:09.478] INFO: world_state Created world state synchroniser with block history of infinity 19:22:09 [19:22:09.605] INFO: world_state Started world state synchronizer from block 1 19:22:09 [19:22:09.905] INFO: world_state Stopped world state synchronizer 19:22:09 PASS src/synchronizer/server_world_state_synchronizer.test.ts (5.892 s) 19:22:09 ServerWorldStateSynchronizer 19:22:09 ✓ updates sync progress (669 ms) 19:22:09 ✓ handles multiple calls to start (451 ms) 19:22:09 ✓ immediately syncs if no new blocks (3 ms) 19:22:09 ✓ cannot be started if already stopped (18 ms) 19:22:09 ✓ can immediately sync to latest (598 ms) 19:22:09 ✓ can immediately sync to a minimum block number (685 ms) 19:22:09 ✓ sync returns immediately if block was already synced (405 ms) 19:22:09 ✓ throws if you try to sync to an unavailable block (489 ms) 19:22:09 ✓ throws if you try to immediate sync when not running (15 ms) 19:22:09 ✓ throws if handling blocks fails (428 ms) 19:22:09 19:22:09 Test Suites: 1 passed, 1 total 19:22:09 Tests: 10 passed, 10 total 19:22:09 Snapshots: 0 total 19:22:09 Time: 5.971 s 19:22:09 Ran all test suites matching /world-state\/src\/synchronizer\/server_world_state_synchronizer.test.ts/i. 19:22:09 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?