Parent Log: http://ci.aztec-labs.com/2945bd5165943bd8 Command: 2f76ae4bba915073 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/87c4b689268afecc86ab007c3587e45110daa43d Env: REF_NAME=gh-readonly-queue/next/pr-15220-282da3bd25d49b92970e6fc85f110092e8566299 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Tue Jun 24 16:35:10 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15220_amd64_x2-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 16:35:14 [16:35:14.088] INFO: world_state Created world state synchroniser with block history of infinity 16:35:14 [16:35:14.228] INFO: world_state Started world state synchronizer from block 1 16:35:14 [16:35:14.612] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":3.7107419999997546,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:14 [16:35:14.616] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.2929180000000997,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:14 [16:35:14.633] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.7160030000000006,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:14 [16:35:14.808] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3474630000000616,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:14 [16:35:14.810] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0371660000000702,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:14 [16:35:14.811] INFO: world_state Stopped world state synchronizer 16:35:14 [16:35:14.812] INFO: world_state Stopped world state synchronizer 16:35:14 [16:35:14.815] INFO: world_state Created world state synchroniser with block history of infinity 16:35:15 [16:35:15.017] INFO: world_state Started world state synchronizer from block 1 16:35:15 [16:35:15.343] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1834399999997913,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:15 [16:35:15.345] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.5883199999998396,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:15 [16:35:15.347] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0942180000001827,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:15 [16:35:15.349] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.2761970000001384,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:15 [16:35:15.352] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.4840269999995144,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:15 [16:35:15.353] INFO: world_state Stopped world state synchronizer 16:35:15 [16:35:15.355] INFO: world_state Created world state synchroniser with block history of infinity 16:35:15 [16:35:15.355] INFO: world_state Started world state synchronizer from block 1 16:35:15 [16:35:15.356] INFO: world_state Stopped world state synchronizer 16:35:15 [16:35:15.357] INFO: world_state Created world state synchroniser with block history of infinity 16:35:15 [16:35:15.357] INFO: world_state Started world state synchronizer from block 1 16:35:15 [16:35:15.358] INFO: world_state Stopped world state synchronizer 16:35:15 [16:35:15.382] INFO: world_state Stopped world state synchronizer 16:35:15 [16:35:15.383] INFO: world_state Created world state synchroniser with block history of infinity 16:35:15 [16:35:15.553] INFO: world_state Started world state synchronizer from block 1 16:35:15 [16:35:15.825] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.9537639999998646,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:15 [16:35:15.827] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.960214000000633,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:15 [16:35:15.828] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9469140000001062,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:15 [16:35:15.830] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.931282999999894,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:15 [16:35:15.831] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9856149999995978,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:16 [16:35:16.032] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":0.9760440000000017,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:16 [16:35:16.033] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.6982280000001992,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:16 [16:35:16.034] INFO: world_state Stopped world state synchronizer 16:35:16 [16:35:16.035] INFO: world_state Created world state synchroniser with block history of infinity 16:35:16 [16:35:16.218] INFO: world_state Started world state synchronizer from block 1 16:35:16 [16:35:16.528] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.073685999999725,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:16 [16:35:16.529] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.036776999999347,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:16 [16:35:16.531] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9547629999997298,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:16 [16:35:16.532] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.6920769999996992,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:16 [16:35:16.533] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.7054280000002109,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:16 [16:35:16.746] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":0.8620410000003176,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:16 [16:35:16.748] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.9237229999998817,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:16 [16:35:16.750] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.2541120000005321,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:16 [16:35:16.751] INFO: world_state Stopped world state synchronizer 16:35:16 [16:35:16.753] INFO: world_state Created world state synchroniser with block history of infinity 16:35:16 [16:35:16.891] INFO: world_state Started world state synchronizer from block 1 16:35:17 [16:35:17.143] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.8529310000003534,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:17 [16:35:17.144] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.7778589999998076,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:17 [16:35:17.145] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8047690000003058,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:17 [16:35:17.146] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.7069480000000112,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:17 [16:35:17.147] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.6210760000003575,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:17 [16:35:17.149] INFO: world_state Stopped world state synchronizer 16:35:17 [16:35:17.150] INFO: world_state Created world state synchroniser with block history of infinity 16:35:17 [16:35:17.280] INFO: world_state Started world state synchronizer from block 1 16:35:17 [16:35:17.547] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.2590110000001005,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:17 [16:35:17.549] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0560059999997975,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:17 [16:35:17.551] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0245249999998123,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:17 [16:35:17.552] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.9261930000002394,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:17 [16:35:17.553] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9199730000000272,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:35:17 [16:35:17.556] INFO: world_state Stopped world state synchronizer 16:35:17 [16:35:17.558] INFO: world_state Created world state synchroniser with block history of infinity 16:35:17 [16:35:17.561] INFO: world_state Stopped world state synchronizer 16:35:17 [16:35:17.562] INFO: world_state Created world state synchroniser with block history of infinity 16:35:17 [16:35:17.787] INFO: world_state Started world state synchronizer from block 1 16:35:18 [16:35:18.138] INFO: world_state Stopped world state synchronizer 16:35:18 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.376 s) 16:35:18 ServerWorldStateSynchronizer 16:35:18 ✓ updates sync progress (730 ms) 16:35:18 ✓ handles multiple calls to start (539 ms) 16:35:18 ✓ immediately syncs if no new blocks (2 ms) 16:35:18 ✓ cannot be started if already stopped (25 ms) 16:35:18 ✓ can immediately sync to latest (651 ms) 16:35:18 ✓ can immediately sync to a minimum block number (717 ms) 16:35:18 ✓ sync returns immediately if block was already synced (397 ms) 16:35:18 ✓ throws if you try to sync to an unavailable block (406 ms) 16:35:18 ✓ throws if you try to immediate sync when not running (4 ms) 16:35:18 ✓ throws if handling blocks fails (576 ms) 16:35:18 16:35:18 Test Suites: 1 passed, 1 total 16:35:18 Tests: 10 passed, 10 total 16:35:18 Snapshots: 0 total 16:35:18 Time: 6.459 s 16:35:18 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 16:35:18 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?