Parent Log: http://ci.aztec-labs.com/859c0f8441ea940a Command: 9c87e3b98c85c9a9 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/7763eef5eb7f045b9184a0a4591b394296eb3aa8 Env: REF_NAME=gh-readonly-queue/next/pr-14770-5ca43853c40a2b8bfbc2148d5fdd46c6f82a74eb CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Mon Jun 16 15:07:27 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14770_amd64_x4-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 15:07:31 [15:07:31.641] INFO: world_state Created world state synchroniser with block history of infinity 15:07:31 [15:07:31.794] INFO: world_state Started world state synchronizer from block 1 15:07:32 [15:07:32.186] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":5.226892000000589,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:32 [15:07:32.190] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.583499999999731,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:32 [15:07:32.192] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.8394399999997404,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:32 [15:07:32.357] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.468470000000707,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:32 [15:07:32.359] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1274309999998877,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:32 [15:07:32.360] INFO: world_state Stopped world state synchronizer 15:07:32 [15:07:32.361] INFO: world_state Stopped world state synchronizer 15:07:32 [15:07:32.364] INFO: world_state Created world state synchroniser with block history of infinity 15:07:32 [15:07:32.515] INFO: world_state Started world state synchronizer from block 1 15:07:32 [15:07:32.731] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.0906199999999444,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:32 [15:07:32.733] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9452549999996336,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:32 [15:07:32.734] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9946369999997842,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:32 [15:07:32.736] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1473809999997684,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:32 [15:07:32.737] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0650080000004891,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:32 [15:07:32.738] INFO: world_state Stopped world state synchronizer 15:07:32 [15:07:32.740] INFO: world_state Created world state synchroniser with block history of infinity 15:07:32 [15:07:32.740] INFO: world_state Started world state synchronizer from block 1 15:07:32 [15:07:32.740] INFO: world_state Stopped world state synchronizer 15:07:32 [15:07:32.741] INFO: world_state Created world state synchroniser with block history of infinity 15:07:32 [15:07:32.742] INFO: world_state Started world state synchronizer from block 1 15:07:32 [15:07:32.742] INFO: world_state Stopped world state synchronizer 15:07:32 [15:07:32.755] INFO: world_state Stopped world state synchronizer 15:07:32 [15:07:32.757] INFO: world_state Created world state synchroniser with block history of infinity 15:07:32 [15:07:32.904] INFO: world_state Started world state synchronizer from block 1 15:07:33 [15:07:33.177] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.3738479999992705,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:33 [15:07:33.178] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.1639319999994768,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:33 [15:07:33.180] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.1092600000001767,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:33 [15:07:33.190] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.4806009999992966,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:33 [15:07:33.191] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2637839999997595,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:33 [15:07:33.409] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.291465000000244,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:33 [15:07:33.411] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.1077299999997194,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:33 [15:07:33.412] INFO: world_state Stopped world state synchronizer 15:07:33 [15:07:33.414] INFO: world_state Created world state synchroniser with block history of infinity 15:07:33 [15:07:33.623] INFO: world_state Started world state synchronizer from block 1 15:07:33 [15:07:33.925] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.7939620000006471,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:33 [15:07:33.927] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.8495830000001661,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:33 [15:07:33.928] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8489230000004682,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:33 [15:07:33.929] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.8133719999996174,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:33 [15:07:33.930] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.6898690000007264,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:34 [15:07:34.154] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":0.884755000000041,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:34 [15:07:34.155] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.9107850000000326,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:34 [15:07:34.157] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":0.8190819999999803,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:34 [15:07:34.158] INFO: world_state Stopped world state synchronizer 15:07:34 [15:07:34.159] INFO: world_state Created world state synchroniser with block history of infinity 15:07:34 [15:07:34.284] INFO: world_state Started world state synchronizer from block 1 15:07:34 [15:07:34.547] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.8110619999997652,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:34 [15:07:34.548] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.8866140000000087,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:34 [15:07:34.549] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8354629999994359,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:34 [15:07:34.550] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.8310529999998835,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:34 [15:07:34.551] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.7753109999994194,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:34 [15:07:34.553] INFO: world_state Stopped world state synchronizer 15:07:34 [15:07:34.554] INFO: world_state Created world state synchroniser with block history of infinity 15:07:34 [15:07:34.721] INFO: world_state Started world state synchronizer from block 1 15:07:35 [15:07:35.029] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.5522220000002562,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:35 [15:07:35.031] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0386280000002444,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:35 [15:07:35.032] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0500379999994038,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:35 [15:07:35.034] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.0107969999999113,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:35 [15:07:35.035] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0157380000000558,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:07:35 [15:07:35.038] INFO: world_state Stopped world state synchronizer 15:07:35 [15:07:35.041] INFO: world_state Created world state synchroniser with block history of infinity 15:07:35 [15:07:35.044] INFO: world_state Stopped world state synchronizer 15:07:35 [15:07:35.046] INFO: world_state Created world state synchroniser with block history of infinity 15:07:35 [15:07:35.226] INFO: world_state Started world state synchronizer from block 1 15:07:35 [15:07:35.571] INFO: world_state Stopped world state synchronizer 15:07:35 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.436 s) 15:07:35 ServerWorldStateSynchronizer 15:07:35 ✓ updates sync progress (726 ms) 15:07:35 ✓ handles multiple calls to start (375 ms) 15:07:35 ✓ immediately syncs if no new blocks (2 ms) 15:07:35 ✓ cannot be started if already stopped (15 ms) 15:07:35 ✓ can immediately sync to latest (657 ms) 15:07:35 ✓ can immediately sync to a minimum block number (745 ms) 15:07:35 ✓ sync returns immediately if block was already synced (395 ms) 15:07:35 ✓ throws if you try to sync to an unavailable block (484 ms) 15:07:35 ✓ throws if you try to immediate sync when not running (5 ms) 15:07:35 ✓ throws if handling blocks fails (526 ms) 15:07:35 15:07:35 Test Suites: 1 passed, 1 total 15:07:35 Tests: 10 passed, 10 total 15:07:35 Snapshots: 0 total 15:07:35 Time: 6.558 s 15:07:35 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 15:07:35 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?