Parent Log: http://ci.aztec-labs.com/5dcd1b40cb4c7fca Command: 7a41fc0e1576329f yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/142e6cd5fbf7371f649078f9d6d08828585c664d Env: REF_NAME=gh-readonly-queue/next/pr-14610-016def8adbfe93f34b41a41f439b146b5318c895 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Tue Jun 24 12:58:12 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14610_amd64_x2-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 12:58:16 [12:58:16.292] INFO: world_state Created world state synchroniser with block history of infinity 12:58:16 [12:58:16.445] INFO: world_state Started world state synchronizer from block 1 12:58:16 [12:58:16.873] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":28.127457999999933,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:16 [12:58:16.878] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":3.2122009999998227,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:16 [12:58:16.882] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.985074000000168,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:17 [12:58:17.071] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.302748000000065,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:17 [12:58:17.073] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2234609999995882,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:17 [12:58:17.074] INFO: world_state Stopped world state synchronizer 12:58:17 [12:58:17.076] INFO: world_state Stopped world state synchronizer 12:58:17 [12:58:17.078] INFO: world_state Created world state synchroniser with block history of infinity 12:58:17 [12:58:17.250] INFO: world_state Started world state synchronizer from block 1 12:58:17 [12:58:17.542] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.8734699999995428,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:17 [12:58:17.545] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.5401359999996203,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:17 [12:58:17.547] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.4924920000003112,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:17 [12:58:17.550] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.8666699999994307,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:17 [12:58:17.553] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.6447429999998349,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:17 [12:58:17.555] INFO: world_state Stopped world state synchronizer 12:58:17 [12:58:17.557] INFO: world_state Created world state synchroniser with block history of infinity 12:58:17 [12:58:17.557] INFO: world_state Started world state synchronizer from block 1 12:58:17 [12:58:17.558] INFO: world_state Stopped world state synchronizer 12:58:17 [12:58:17.559] INFO: world_state Created world state synchroniser with block history of infinity 12:58:17 [12:58:17.560] INFO: world_state Started world state synchronizer from block 1 12:58:17 [12:58:17.561] INFO: world_state Stopped world state synchronizer 12:58:17 [12:58:17.577] INFO: world_state Stopped world state synchronizer 12:58:17 [12:58:17.579] INFO: world_state Created world state synchroniser with block history of infinity 12:58:17 [12:58:17.755] INFO: world_state Started world state synchronizer from block 1 12:58:18 [12:58:18.051] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.5224340000004304,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:18 [12:58:18.053] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.4688209999994797,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:18 [12:58:18.055] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.367921999999453,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:18 [12:58:18.056] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3359800000007453,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:18 [12:58:18.058] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.301397000000179,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:18 [12:58:18.265] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.1203340000001845,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:18 [12:58:18.267] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.9472909999994954,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:18 [12:58:18.268] INFO: world_state Stopped world state synchronizer 12:58:18 [12:58:18.269] INFO: world_state Created world state synchroniser with block history of infinity 12:58:18 [12:58:18.480] INFO: world_state Started world state synchronizer from block 1 12:58:18 [12:58:18.830] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.6318929999997636,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:18 [12:58:18.832] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.545405999999275,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:18 [12:58:18.834] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.4931109999997716,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:18 [12:58:18.836] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3407310000002326,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:18 [12:58:18.837] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.3523510000004535,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:19 [12:58:19.132] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.552216000000044,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:19 [12:58:19.134] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.4132159999999203,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:19 [12:58:19.136] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.337228999999752,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:19 [12:58:19.138] INFO: world_state Stopped world state synchronizer 12:58:19 [12:58:19.140] INFO: world_state Created world state synchroniser with block history of infinity 12:58:19 [12:58:19.297] INFO: world_state Started world state synchronizer from block 1 12:58:19 [12:58:19.613] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.596599999999853,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:19 [12:58:19.615] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.4326570000002903,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:19 [12:58:19.617] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.384624000000258,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:19 [12:58:19.619] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3963549999998577,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:19 [12:58:19.620] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.349631999999474,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:19 [12:58:19.622] INFO: world_state Stopped world state synchronizer 12:58:19 [12:58:19.624] INFO: world_state Created world state synchroniser with block history of infinity 12:58:19 [12:58:19.781] INFO: world_state Started world state synchronizer from block 1 12:58:20 [12:58:20.067] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.431587000000036,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:20 [12:58:20.069] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.2347929999996268,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:20 [12:58:20.070] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8963569999996253,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:20 [12:58:20.071] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.049409000000196,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:20 [12:58:20.073] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.3805730000003678,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:58:20 [12:58:20.075] INFO: world_state Stopped world state synchronizer 12:58:20 [12:58:20.076] INFO: world_state Created world state synchroniser with block history of infinity 12:58:20 [12:58:20.078] INFO: world_state Stopped world state synchronizer 12:58:20 [12:58:20.079] INFO: world_state Created world state synchroniser with block history of infinity 12:58:20 [12:58:20.223] INFO: world_state Started world state synchronizer from block 1 12:58:20 [12:58:20.508] INFO: world_state Stopped world state synchronizer 12:58:20 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.557 s) 12:58:20 ServerWorldStateSynchronizer 12:58:20 ✓ updates sync progress (789 ms) 12:58:20 ✓ handles multiple calls to start (478 ms) 12:58:20 ✓ immediately syncs if no new blocks (3 ms) 12:58:20 ✓ cannot be started if already stopped (19 ms) 12:58:20 ✓ can immediately sync to latest (690 ms) 12:58:20 ✓ can immediately sync to a minimum block number (870 ms) 12:58:20 ✓ sync returns immediately if block was already synced (483 ms) 12:58:20 ✓ throws if you try to sync to an unavailable block (452 ms) 12:58:20 ✓ throws if you try to immediate sync when not running (3 ms) 12:58:20 ✓ throws if handling blocks fails (430 ms) 12:58:20 12:58:20 Test Suites: 1 passed, 1 total 12:58:20 Tests: 10 passed, 10 total 12:58:20 Snapshots: 0 total 12:58:20 Time: 6.649 s 12:58:20 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 12:58:20 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?