Parent Log: http://ci.aztec-labs.com/d99f6ae688b69380 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/421792466f3790b8c43cf90684c77ff576d80ae5 Env: REF_NAME=palla/damn-stream-ids CURRENT_VERSION=0.87.6 CI_FULL=0 Date: Fri Jun 13 22:07:14 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=palla_damn-stream-ids_amd64 Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 22:07:17 [22:07:17.336] INFO: world_state Created world state synchroniser with block history of infinity 22:07:17 [22:07:17.438] INFO: world_state Started world state synchronizer from block 1 22:07:17 [22:07:17.770] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":7.904219999999896,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:17 [22:07:17.773] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.109683000000132,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:17 [22:07:17.776] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.971962000000076,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:17 [22:07:17.945] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.046777999999904,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:17 [22:07:17.947] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.3215119999999843,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:17 [22:07:17.948] INFO: world_state Stopped world state synchronizer 22:07:17 [22:07:17.949] INFO: world_state Stopped world state synchronizer 22:07:17 [22:07:17.951] INFO: world_state Created world state synchroniser with block history of infinity 22:07:18 [22:07:18.070] INFO: world_state Started world state synchronizer from block 1 22:07:18 [22:07:18.292] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1098750000001019,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:18 [22:07:18.294] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0201090000000477,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:18 [22:07:18.296] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.1725809999998091,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:18 [22:07:18.297] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1709799999998722,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:18 [22:07:18.299] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9912359999998444,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:18 [22:07:18.300] INFO: world_state Stopped world state synchronizer 22:07:18 [22:07:18.301] INFO: world_state Created world state synchroniser with block history of infinity 22:07:18 [22:07:18.302] INFO: world_state Started world state synchronizer from block 1 22:07:18 [22:07:18.302] INFO: world_state Stopped world state synchronizer 22:07:18 [22:07:18.303] INFO: world_state Created world state synchroniser with block history of infinity 22:07:18 [22:07:18.304] INFO: world_state Started world state synchronizer from block 1 22:07:18 [22:07:18.304] INFO: world_state Stopped world state synchronizer 22:07:18 [22:07:18.318] INFO: world_state Stopped world state synchronizer 22:07:18 [22:07:18.320] INFO: world_state Created world state synchroniser with block history of infinity 22:07:18 [22:07:18.451] INFO: world_state Started world state synchronizer from block 1 22:07:18 [22:07:18.676] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.563481000000138,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:18 [22:07:18.677] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.8720269999998891,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:18 [22:07:18.679] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.866106999999829,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:18 [22:07:18.680] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.9701049999998759,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:18 [22:07:18.682] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.0929439999999886,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:18 [22:07:18.825] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.2548170000000027,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:18 [22:07:18.827] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.1451790000000983,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:18 [22:07:18.828] INFO: world_state Stopped world state synchronizer 22:07:18 [22:07:18.837] INFO: world_state Created world state synchroniser with block history of infinity 22:07:18 [22:07:18.970] INFO: world_state Started world state synchronizer from block 1 22:07:19 [22:07:19.191] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.9852659999996831,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:19 [22:07:19.193] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9206610000001092,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:19 [22:07:19.194] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8030310000003738,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:19 [22:07:19.195] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.7697989999996935,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:19 [22:07:19.196] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.7813300000007075,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:19 [22:07:19.392] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":0.9983870000005481,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:19 [22:07:19.394] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.2032829999998285,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:19 [22:07:19.395] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.0537109999995664,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:19 [22:07:19.397] INFO: world_state Stopped world state synchronizer 22:07:19 [22:07:19.398] INFO: world_state Created world state synchroniser with block history of infinity 22:07:19 [22:07:19.516] INFO: world_state Started world state synchronizer from block 1 22:07:19 [22:07:19.788] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.6091839999999138,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:19 [22:07:19.791] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.1510589999998047,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:19 [22:07:19.792] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.794252000000597,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:19 [22:07:19.793] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.7990019999997457,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:19 [22:07:19.794] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.7373070000003281,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:19 [22:07:19.797] INFO: world_state Stopped world state synchronizer 22:07:19 [22:07:19.800] INFO: world_state Created world state synchroniser with block history of infinity 22:07:19 [22:07:19.923] INFO: world_state Started world state synchronizer from block 1 22:07:20 [22:07:20.156] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.9389920000003258,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:20 [22:07:20.170] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":13.372730000000047,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:20 [22:07:20.171] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9998569999997926,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:20 [22:07:20.173] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.062960999999632,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:20 [22:07:20.174] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8197129999998651,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 22:07:20 [22:07:20.176] INFO: world_state Stopped world state synchronizer 22:07:20 [22:07:20.178] INFO: world_state Created world state synchroniser with block history of infinity 22:07:20 [22:07:20.180] INFO: world_state Stopped world state synchronizer 22:07:20 [22:07:20.181] INFO: world_state Created world state synchroniser with block history of infinity 22:07:20 [22:07:20.305] INFO: world_state Started world state synchronizer from block 1 22:07:20 [22:07:20.579] INFO: world_state Stopped world state synchronizer 22:07:20 PASS src/synchronizer/server_world_state_synchronizer.test.ts (5.045 s) 22:07:20 ServerWorldStateSynchronizer 22:07:20 ✓ updates sync progress (617 ms) 22:07:20 ✓ handles multiple calls to start (349 ms) 22:07:20 ✓ immediately syncs if no new blocks (2 ms) 22:07:20 ✓ cannot be started if already stopped (16 ms) 22:07:20 ✓ can immediately sync to latest (509 ms) 22:07:20 ✓ can immediately sync to a minimum block number (568 ms) 22:07:20 ✓ sync returns immediately if block was already synced (400 ms) 22:07:20 ✓ throws if you try to sync to an unavailable block (377 ms) 22:07:20 ✓ throws if you try to immediate sync when not running (4 ms) 22:07:20 ✓ throws if handling blocks fails (398 ms) 22:07:20 22:07:20 Test Suites: 1 passed, 1 total 22:07:20 Tests: 10 passed, 10 total 22:07:20 Snapshots: 0 total 22:07:20 Time: 5.119 s 22:07:20 Ran all test suites matching /world-state\/src\/synchronizer\/server_world_state_synchronizer.test.ts/i. 22:07:20 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?