Parent Log: http://ci.aztec-labs.com/3b5b76807dd91ce4 Command: 31ac9f84524855a2 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/505a3c071181dbc5242dc8b655a9b2d69988c8db Env: REF_NAME=gh-readonly-queue/staging/pr-15211-e8f6c3b7e3e0e72a33e118ddf8e67d11e51464a8 CURRENT_VERSION=0.87.6 CI_FULL=0 Date: Mon Jun 23 12:46:52 UTC 2025 System: ARCH=arm64 CPUS=64 MEM=247Gi HOSTNAME=pr-15211_arm64_a1-fast Resources: CPU_LIST=0-63 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_staging 12:46:54 [12:46:54.500] INFO: world_state Created world state synchroniser with block history of infinity 12:46:54 [12:46:54.598] INFO: world_state Started world state synchronizer from block 1 12:46:54 [12:46:54.900] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":4.2938530000001265,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:54 [12:46:54.904] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.1742639999997664,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:54 [12:46:54.906] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.4831469999999172,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:55 [12:46:55.047] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.6000970000000052,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:55 [12:46:55.049] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.500036999999793,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:55 [12:46:55.051] INFO: world_state Stopped world state synchronizer 12:46:55 [12:46:55.052] INFO: world_state Stopped world state synchronizer 12:46:55 [12:46:55.055] INFO: world_state Created world state synchroniser with block history of infinity 12:46:55 [12:46:55.172] INFO: world_state Started world state synchronizer from block 1 12:46:55 [12:46:55.390] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.0329530000003615,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:55 [12:46:55.392] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0101669999999103,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:55 [12:46:55.394] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9833010000002105,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:55 [12:46:55.395] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3171110000002955,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:55 [12:46:55.397] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1888739999999416,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:55 [12:46:55.398] INFO: world_state Stopped world state synchronizer 12:46:55 [12:46:55.400] INFO: world_state Created world state synchroniser with block history of infinity 12:46:55 [12:46:55.401] INFO: world_state Started world state synchronizer from block 1 12:46:55 [12:46:55.401] INFO: world_state Stopped world state synchronizer 12:46:55 [12:46:55.402] INFO: world_state Created world state synchroniser with block history of infinity 12:46:55 [12:46:55.403] INFO: world_state Started world state synchronizer from block 1 12:46:55 [12:46:55.403] INFO: world_state Stopped world state synchronizer 12:46:55 [12:46:55.417] INFO: world_state Stopped world state synchronizer 12:46:55 [12:46:55.418] INFO: world_state Created world state synchroniser with block history of infinity 12:46:55 [12:46:55.541] INFO: world_state Started world state synchronizer from block 1 12:46:55 [12:46:55.760] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.9830389999997351,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:55 [12:46:55.761] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9382530000002589,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:55 [12:46:55.763] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9227310000001125,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:55 [12:46:55.764] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.9138519999996788,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:55 [12:46:55.765] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9019539999999324,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:55 [12:46:55.904] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.1690249999996922,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:55 [12:46:55.905] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.9129600000001119,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:55 [12:46:55.906] INFO: world_state Stopped world state synchronizer 12:46:55 [12:46:55.907] INFO: world_state Created world state synchroniser with block history of infinity 12:46:56 [12:46:56.023] INFO: world_state Started world state synchronizer from block 1 12:46:56 [12:46:56.242] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.9640810000000783,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:56 [12:46:56.243] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.8943559999997888,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:56 [12:46:56.244] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8563450000001467,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:56 [12:46:56.246] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.8444689999996626,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:56 [12:46:56.247] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8462589999999182,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:56 [12:46:56.454] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":0.945966999999655,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:56 [12:46:56.456] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.899808000000121,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:56 [12:46:56.457] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":0.9435259999991104,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:56 [12:46:56.458] INFO: world_state Stopped world state synchronizer 12:46:56 [12:46:56.460] INFO: world_state Created world state synchroniser with block history of infinity 12:46:56 [12:46:56.576] INFO: world_state Started world state synchronizer from block 1 12:46:56 [12:46:56.794] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.9604159999998956,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:56 [12:46:56.796] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.8904149999998481,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:56 [12:46:56.797] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8819720000001325,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:56 [12:46:56.798] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.9442550000003394,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:56 [12:46:56.800] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9578129999999874,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:56 [12:46:56.801] INFO: world_state Stopped world state synchronizer 12:46:56 [12:46:56.803] INFO: world_state Created world state synchroniser with block history of infinity 12:46:56 [12:46:56.926] INFO: world_state Started world state synchronizer from block 1 12:46:57 [12:46:57.138] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.9230889999998908,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:57 [12:46:57.147] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.933828999999605,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:57 [12:46:57.148] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8708070000002408,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:57 [12:46:57.150] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.9354880000000776,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:57 [12:46:57.151] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8591690000002927,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 12:46:57 [12:46:57.152] INFO: world_state Stopped world state synchronizer 12:46:57 [12:46:57.154] INFO: world_state Created world state synchroniser with block history of infinity 12:46:57 [12:46:57.156] INFO: world_state Stopped world state synchronizer 12:46:57 [12:46:57.158] INFO: world_state Created world state synchroniser with block history of infinity 12:46:57 [12:46:57.273] INFO: world_state Started world state synchronizer from block 1 12:46:57 [12:46:57.494] INFO: world_state Stopped world state synchronizer 12:46:57 PASS src/synchronizer/server_world_state_synchronizer.test.ts 12:46:57 ServerWorldStateSynchronizer 12:46:57 ✓ updates sync progress (557 ms) 12:46:57 ✓ handles multiple calls to start (344 ms) 12:46:57 ✓ immediately syncs if no new blocks (3 ms) 12:46:57 ✓ cannot be started if already stopped (15 ms) 12:46:57 ✓ can immediately sync to latest (489 ms) 12:46:57 ✓ can immediately sync to a minimum block number (551 ms) 12:46:57 ✓ sync returns immediately if block was already synced (343 ms) 12:46:57 ✓ throws if you try to sync to an unavailable block (351 ms) 12:46:57 ✓ throws if you try to immediate sync when not running (4 ms) 12:46:57 ✓ throws if handling blocks fails (338 ms) 12:46:57 12:46:57 Test Suites: 1 passed, 1 total 12:46:57 Tests: 10 passed, 10 total 12:46:57 Snapshots: 0 total 12:46:57 Time: 4.775 s 12:46:57 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 12:46:57 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?