Parent Log: http://ci.aztec-labs.com/ac73f67c4ebf5c73 Command: a5511b51e55ea9c0 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/0dc6f60f84deaa57a8570f9942eb75317f5aeea5 Env: REF_NAME=gh-readonly-queue/next/pr-15162-6ee95286f8bcc5bb6dca43222459ac5bf43a8a75 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Fri Jun 20 09:45:25 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15162_amd64_x3-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 09:45:28 [09:45:28.985] INFO: world_state Created world state synchroniser with block history of infinity 09:45:29 [09:45:29.114] INFO: world_state Started world state synchronizer from block 1 09:45:29 [09:45:29.519] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":12.250435999999809,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:29 [09:45:29.527] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":6.021117000000231,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:29 [09:45:29.534] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.9129009999996924,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:29 [09:45:29.718] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.31374199999982,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:29 [09:45:29.722] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":2.0741689999999835,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:29 [09:45:29.742] INFO: world_state Stopped world state synchronizer 09:45:29 [09:45:29.746] INFO: world_state Stopped world state synchronizer 09:45:29 [09:45:29.750] INFO: world_state Created world state synchroniser with block history of infinity 09:45:29 [09:45:29.920] INFO: world_state Started world state synchronizer from block 1 09:45:30 [09:45:30.295] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.7841740000003483,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:30 [09:45:30.297] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.4864780000007158,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:30 [09:45:30.300] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.3286900000002788,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:30 [09:45:30.302] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3446509999994305,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:30 [09:45:30.303] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1481400000002395,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:30 [09:45:30.305] INFO: world_state Stopped world state synchronizer 09:45:30 [09:45:30.307] INFO: world_state Created world state synchroniser with block history of infinity 09:45:30 [09:45:30.307] INFO: world_state Started world state synchronizer from block 1 09:45:30 [09:45:30.308] INFO: world_state Stopped world state synchronizer 09:45:30 [09:45:30.309] INFO: world_state Created world state synchroniser with block history of infinity 09:45:30 [09:45:30.309] INFO: world_state Started world state synchronizer from block 1 09:45:30 [09:45:30.310] INFO: world_state Stopped world state synchronizer 09:45:30 [09:45:30.329] INFO: world_state Stopped world state synchronizer 09:45:30 [09:45:30.331] INFO: world_state Created world state synchroniser with block history of infinity 09:45:30 [09:45:30.471] INFO: world_state Started world state synchronizer from block 1 09:45:30 [09:45:30.842] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":23.490349000000606,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:30 [09:45:30.844] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.1535409999996773,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:30 [09:45:30.846] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.1085979999998017,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:30 [09:45:30.847] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.10436799999934,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:30 [09:45:30.848] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.091128000000026,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:31 [09:45:31.010] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.2692070000002786,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:31 [09:45:31.013] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.5800630000003366,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:31 [09:45:31.018] INFO: world_state Stopped world state synchronizer 09:45:31 [09:45:31.021] INFO: world_state Created world state synchroniser with block history of infinity 09:45:31 [09:45:31.188] INFO: world_state Started world state synchronizer from block 1 09:45:31 [09:45:31.500] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.978211999999985,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:31 [09:45:31.501] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.7178579999999783,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:31 [09:45:31.502] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.6728549999997995,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:31 [09:45:31.503] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.6854570000004969,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:31 [09:45:31.505] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0051640000001498,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:31 [09:45:31.821] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.2094340000003285,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:31 [09:45:31.823] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.1518809999997757,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:31 [09:45:31.824] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":0.76987999999983,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:31 [09:45:31.825] INFO: world_state Stopped world state synchronizer 09:45:31 [09:45:31.827] INFO: world_state Created world state synchroniser with block history of infinity 09:45:31 [09:45:31.995] INFO: world_state Started world state synchronizer from block 1 09:45:32 [09:45:32.277] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.5565710000000763,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:32 [09:45:32.279] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.3155689999994138,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:32 [09:45:32.281] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.3212299999995594,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:32 [09:45:32.283] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.4179250000006505,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:32 [09:45:32.285] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1677720000006957,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:32 [09:45:32.288] INFO: world_state Stopped world state synchronizer 09:45:32 [09:45:32.290] INFO: world_state Created world state synchroniser with block history of infinity 09:45:32 [09:45:32.475] INFO: world_state Started world state synchronizer from block 1 09:45:32 [09:45:32.837] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.3200699999997596,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:32 [09:45:32.839] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.1359099999999671,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:32 [09:45:32.840] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2275939999999537,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:32 [09:45:32.842] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1788520000000062,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:32 [09:45:32.844] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.13383900000008,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:45:32 [09:45:32.846] INFO: world_state Stopped world state synchronizer 09:45:32 [09:45:32.847] INFO: world_state Created world state synchroniser with block history of infinity 09:45:32 [09:45:32.850] INFO: world_state Stopped world state synchronizer 09:45:32 [09:45:32.852] INFO: world_state Created world state synchroniser with block history of infinity 09:45:33 [09:45:33.009] INFO: world_state Started world state synchronizer from block 1 09:45:33 [09:45:33.289] INFO: world_state Stopped world state synchronizer 09:45:33 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.591 s) 09:45:33 ServerWorldStateSynchronizer 09:45:33 ✓ updates sync progress (766 ms) 09:45:33 ✓ handles multiple calls to start (557 ms) 09:45:33 ✓ immediately syncs if no new blocks (2 ms) 09:45:33 ✓ cannot be started if already stopped (22 ms) 09:45:33 ✓ can immediately sync to latest (689 ms) 09:45:33 ✓ can immediately sync to a minimum block number (806 ms) 09:45:33 ✓ sync returns immediately if block was already synced (463 ms) 09:45:33 ✓ throws if you try to sync to an unavailable block (557 ms) 09:45:33 ✓ throws if you try to immediate sync when not running (4 ms) 09:45:33 ✓ throws if handling blocks fails (439 ms) 09:45:33 09:45:33 Test Suites: 1 passed, 1 total 09:45:33 Tests: 10 passed, 10 total 09:45:33 Snapshots: 0 total 09:45:33 Time: 6.691 s 09:45:33 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 09:45:33 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?