Parent Log: http://ci.aztec-labs.com/c9d290fe0f81769d Command: 8eab3f4af2f43a8d yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/5d81047d880386c72ab2e6701b0c86ae607c3618 Env: REF_NAME=gh-readonly-queue/next/pr-15164-308037ad9b1489540aedbb57a9dac3d71282010e CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Fri Jun 20 13:09:49 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15164_amd64_x1-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 13:09:53 [13:09:53.279] INFO: world_state Created world state synchroniser with block history of infinity 13:09:53 [13:09:53.407] INFO: world_state Started world state synchronizer from block 1 13:09:53 [13:09:53.835] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":5.441605999999865,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:53 [13:09:53.854] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":3.1570760000004157,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:53 [13:09:53.857] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.505783000000065,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:54 [13:09:54.013] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.6356550000000425,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:54 [13:09:54.015] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.4207500000002256,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:54 [13:09:54.017] INFO: world_state Stopped world state synchronizer 13:09:54 [13:09:54.019] INFO: world_state Stopped world state synchronizer 13:09:54 [13:09:54.022] INFO: world_state Created world state synchroniser with block history of infinity 13:09:54 [13:09:54.171] INFO: world_state Started world state synchronizer from block 1 13:09:54 [13:09:54.457] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.7666269999999713,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:54 [13:09:54.460] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.704225999999835,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:54 [13:09:54.462] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.7176159999999072,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:54 [13:09:54.465] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.8919099999993705,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:54 [13:09:54.467] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.8951099999994767,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:54 [13:09:54.469] INFO: world_state Stopped world state synchronizer 13:09:54 [13:09:54.471] INFO: world_state Created world state synchroniser with block history of infinity 13:09:54 [13:09:54.472] INFO: world_state Started world state synchronizer from block 1 13:09:54 [13:09:54.473] INFO: world_state Stopped world state synchronizer 13:09:54 [13:09:54.474] INFO: world_state Created world state synchroniser with block history of infinity 13:09:54 [13:09:54.474] INFO: world_state Started world state synchronizer from block 1 13:09:54 [13:09:54.475] INFO: world_state Stopped world state synchronizer 13:09:54 [13:09:54.493] INFO: world_state Stopped world state synchronizer 13:09:54 [13:09:54.494] INFO: world_state Created world state synchroniser with block history of infinity 13:09:54 [13:09:54.645] INFO: world_state Started world state synchronizer from block 1 13:09:54 [13:09:54.946] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.3200980000001437,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:54 [13:09:54.947] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.984480000000076,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:54 [13:09:54.949] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.1192739999996775,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:54 [13:09:54.951] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.6442649999999048,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:54 [13:09:54.953] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9020390000005136,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:55 [13:09:55.143] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.07281200000034,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:55 [13:09:55.145] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.9320699999998396,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:55 [13:09:55.146] INFO: world_state Stopped world state synchronizer 13:09:55 [13:09:55.147] INFO: world_state Created world state synchroniser with block history of infinity 13:09:55 [13:09:55.307] INFO: world_state Started world state synchronizer from block 1 13:09:55 [13:09:55.611] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.8631089999998949,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:55 [13:09:55.613] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.987051000000065,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:55 [13:09:55.614] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9428599999992002,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:55 [13:09:55.616] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.9326000000000931,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:55 [13:09:55.617] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9283699999996315,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:55 [13:09:55.919] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.3983989999996993,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:55 [13:09:55.921] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.2987079999993512,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:55 [13:09:55.923] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.2522670000007565,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:55 [13:09:55.925] INFO: world_state Stopped world state synchronizer 13:09:55 [13:09:55.927] INFO: world_state Created world state synchroniser with block history of infinity 13:09:56 [13:09:56.101] INFO: world_state Started world state synchronizer from block 1 13:09:56 [13:09:56.383] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.2512769999993907,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:56 [13:09:56.385] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.146534999999858,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:56 [13:09:56.387] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0808829999996306,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:56 [13:09:56.388] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.0877030000001469,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:56 [13:09:56.390] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.075713000000178,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:56 [13:09:56.392] INFO: world_state Stopped world state synchronizer 13:09:56 [13:09:56.393] INFO: world_state Created world state synchroniser with block history of infinity 13:09:56 [13:09:56.549] INFO: world_state Started world state synchronizer from block 1 13:09:56 [13:09:56.844] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.8384470000000874,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:56 [13:09:56.845] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.6632739999995465,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:56 [13:09:56.846] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.6818440000006376,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:56 [13:09:56.847] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.6279340000000957,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:56 [13:09:56.848] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.6836139999995794,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 13:09:56 [13:09:56.849] INFO: world_state Stopped world state synchronizer 13:09:56 [13:09:56.850] INFO: world_state Created world state synchroniser with block history of infinity 13:09:56 [13:09:56.852] INFO: world_state Stopped world state synchronizer 13:09:56 [13:09:56.853] INFO: world_state Created world state synchroniser with block history of infinity 13:09:56 [13:09:56.989] INFO: world_state Started world state synchronizer from block 1 13:09:57 [13:09:57.270] INFO: world_state Stopped world state synchronizer 13:09:57 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.37 s) 13:09:57 ServerWorldStateSynchronizer 13:09:57 ✓ updates sync progress (745 ms) 13:09:57 ✓ handles multiple calls to start (449 ms) 13:09:57 ✓ immediately syncs if no new blocks (3 ms) 13:09:57 ✓ cannot be started if already stopped (20 ms) 13:09:57 ✓ can immediately sync to latest (653 ms) 13:09:57 ✓ can immediately sync to a minimum block number (778 ms) 13:09:57 ✓ sync returns immediately if block was already synced (466 ms) 13:09:57 ✓ throws if you try to sync to an unavailable block (457 ms) 13:09:57 ✓ throws if you try to immediate sync when not running (3 ms) 13:09:57 ✓ throws if handling blocks fails (418 ms) 13:09:57 13:09:57 Test Suites: 1 passed, 1 total 13:09:57 Tests: 10 passed, 10 total 13:09:57 Snapshots: 0 total 13:09:57 Time: 6.461 s 13:09:57 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 13:09:57 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?