Parent Log: http://ci.aztec-labs.com/7ec536495953bb64 Command: 70462c95d683706b yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/fa159c5d52a45358479f951a547fed37f141a6ed Env: REF_NAME=gh-readonly-queue/next/pr-15054-184d5f7d956dd7251670a2dd6728f313cfdf3caf CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Fri Jun 13 22:57:00 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15054_amd64_x1-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 22:57:04 [22:57:04.143] INFO: world_state Created world state synchroniser with block history of infinity 22:57:04 [22:57:04.268] INFO: world_state Started world state synchronizer from block 1 22:57:04 [22:57:04.702] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":8.219079999999849,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:04 [22:57:04.706] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.9818449999997938,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:04 [22:57:04.709] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.2071420000002036,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:04 [22:57:04.888] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.647816000000148,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:04 [22:57:04.890] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9688080000000809,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:04 [22:57:04.892] INFO: world_state Stopped world state synchronizer 22:57:04 [22:57:04.893] INFO: world_state Stopped world state synchronizer 22:57:04 [22:57:04.896] INFO: world_state Created world state synchroniser with block history of infinity 22:57:05 [22:57:05.037] INFO: world_state Started world state synchronizer from block 1 22:57:05 [22:57:05.279] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.233885000000555,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:05 [22:57:05.281] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9607869999999821,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:05 [22:57:05.282] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9057459999994535,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:05 [22:57:05.284] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1571219999996174,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:05 [22:57:05.285] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0724499999996624,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:05 [22:57:05.286] INFO: world_state Stopped world state synchronizer 22:57:05 [22:57:05.288] INFO: world_state Created world state synchroniser with block history of infinity 22:57:05 [22:57:05.288] INFO: world_state Started world state synchronizer from block 1 22:57:05 [22:57:05.289] INFO: world_state Stopped world state synchronizer 22:57:05 [22:57:05.289] INFO: world_state Created world state synchroniser with block history of infinity 22:57:05 [22:57:05.290] INFO: world_state Started world state synchronizer from block 1 22:57:05 [22:57:05.290] INFO: world_state Stopped world state synchronizer 22:57:05 [22:57:05.306] INFO: world_state Stopped world state synchronizer 22:57:05 [22:57:05.307] INFO: world_state Created world state synchroniser with block history of infinity 22:57:05 [22:57:05.470] INFO: world_state Started world state synchronizer from block 1 22:57:05 [22:57:05.789] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.2530049999995754,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:05 [22:57:05.792] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.4143199999998615,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:05 [22:57:05.793] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2962960000004387,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:05 [22:57:05.795] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3223779999998442,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:05 [22:57:05.797] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2259039999999004,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:05 [22:57:05.951] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.368649000000005,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:05 [22:57:05.953] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.219383999999991,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:05 [22:57:05.955] INFO: world_state Stopped world state synchronizer 22:57:05 [22:57:05.965] INFO: world_state Created world state synchroniser with block history of infinity 22:57:06 [22:57:06.112] INFO: world_state Started world state synchronizer from block 1 22:57:06 [22:57:06.537] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.6275150000001304,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:06 [22:57:06.541] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.9145639999997002,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:06 [22:57:06.544] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.693066999999246,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:06 [22:57:06.546] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.8290320000005522,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:06 [22:57:06.549] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.7443890000004103,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:06 [22:57:06.913] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.1222109999998793,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:06 [22:57:06.914] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.9139459999996689,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:06 [22:57:06.916] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.3814689999999246,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:06 [22:57:06.919] INFO: world_state Stopped world state synchronizer 22:57:06 [22:57:06.923] INFO: world_state Created world state synchroniser with block history of infinity 22:57:07 [22:57:07.100] INFO: world_state Started world state synchronizer from block 1 22:57:07 [22:57:07.450] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.0368600000001607,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:07 [22:57:07.452] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.321907000000465,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:07 [22:57:07.454] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8848849999994854,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:07 [22:57:07.456] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2540549999994255,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:07 [22:57:07.458] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.001857999999629,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:07 [22:57:07.462] INFO: world_state Stopped world state synchronizer 22:57:07 [22:57:07.464] INFO: world_state Created world state synchroniser with block history of infinity 22:57:07 [22:57:07.661] INFO: world_state Started world state synchronizer from block 1 22:57:07 [22:57:07.933] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.5295230000001538,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:07 [22:57:07.935] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.3098270000000412,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:07 [22:57:07.944] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":8.383994999999231,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:07 [22:57:07.947] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.345777999999882,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:07 [22:57:07.949] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1586420000003272,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:57:07 [22:57:07.951] INFO: world_state Stopped world state synchronizer 22:57:07 [22:57:07.952] INFO: world_state Created world state synchroniser with block history of infinity 22:57:07 [22:57:07.956] INFO: world_state Stopped world state synchronizer 22:57:07 [22:57:07.957] INFO: world_state Created world state synchroniser with block history of infinity 22:57:08 [22:57:08.123] INFO: world_state Started world state synchronizer from block 1 22:57:08 [22:57:08.431] INFO: world_state Stopped world state synchronizer 22:57:08 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.523 s) 22:57:08 ServerWorldStateSynchronizer 22:57:08 ✓ updates sync progress (755 ms) 22:57:08 ✓ handles multiple calls to start (392 ms) 22:57:08 ✓ immediately syncs if no new blocks (2 ms) 22:57:08 ✓ cannot be started if already stopped (17 ms) 22:57:08 ✓ can immediately sync to latest (648 ms) 22:57:08 ✓ can immediately sync to a minimum block number (963 ms) 22:57:08 ✓ sync returns immediately if block was already synced (542 ms) 22:57:08 ✓ throws if you try to sync to an unavailable block (488 ms) 22:57:08 ✓ throws if you try to immediate sync when not running (4 ms) 22:57:08 ✓ throws if handling blocks fails (475 ms) 22:57:08 22:57:08 Test Suites: 1 passed, 1 total 22:57:08 Tests: 10 passed, 10 total 22:57:08 Snapshots: 0 total 22:57:08 Time: 6.606 s 22:57:08 Ran all test suites matching /world-state\/src\/synchronizer\/server_world_state_synchronizer.test.ts/i. 22:57:08 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?