Parent Log: http://ci.aztec-labs.com/2130b691f6e90cdc Command: bcc565b00bd8bb22 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/3c671e0822075208f705135771771f5a47e9652e Env: REF_NAME=gh-readonly-queue/next/pr-15109-9222c7777effe26aa04b5367a455d25be0e7f62e CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Wed Jun 18 16:06:01 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15109_amd64_x3-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 16:06:05 [16:06:05.431] INFO: world_state Created world state synchroniser with block history of infinity 16:06:05 [16:06:05.598] INFO: world_state Started world state synchronizer from block 1 16:06:06 [16:06:06.130] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":5.642760000000635,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:06 [16:06:06.134] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.1212580000001253,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:06 [16:06:06.137] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.312412000000222,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:06 [16:06:06.373] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.5638549999994211,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:06 [16:06:06.376] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.5066839999999502,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:06 [16:06:06.377] INFO: world_state Stopped world state synchronizer 16:06:06 [16:06:06.379] INFO: world_state Stopped world state synchronizer 16:06:06 [16:06:06.382] INFO: world_state Created world state synchroniser with block history of infinity 16:06:06 [16:06:06.556] INFO: world_state Started world state synchronizer from block 1 16:06:06 [16:06:06.872] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.459523999999874,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:06 [16:06:06.876] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.5491080000001602,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:06 [16:06:06.878] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.3586109999996552,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:06 [16:06:06.881] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.7454199999992852,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:06 [16:06:06.883] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.3815009999998438,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:06 [16:06:06.885] INFO: world_state Stopped world state synchronizer 16:06:06 [16:06:06.887] INFO: world_state Created world state synchroniser with block history of infinity 16:06:06 [16:06:06.888] INFO: world_state Started world state synchronizer from block 1 16:06:06 [16:06:06.889] INFO: world_state Stopped world state synchronizer 16:06:06 [16:06:06.890] INFO: world_state Created world state synchroniser with block history of infinity 16:06:06 [16:06:06.892] INFO: world_state Started world state synchronizer from block 1 16:06:06 [16:06:06.893] INFO: world_state Stopped world state synchronizer 16:06:06 [16:06:06.914] INFO: world_state Stopped world state synchronizer 16:06:06 [16:06:06.915] INFO: world_state Created world state synchroniser with block history of infinity 16:06:07 [16:06:07.117] INFO: world_state Started world state synchronizer from block 1 16:06:07 [16:06:07.498] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.9695019999999204,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:07 [16:06:07.499] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0109440000005634,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:07 [16:06:07.501] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.7873380000000907,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:07 [16:06:07.502] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.717066000000159,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:07 [16:06:07.503] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.7093459999996412,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:07 [16:06:07.747] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":5.064675999999963,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:07 [16:06:07.753] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.2720190000000002,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:07 [16:06:07.755] INFO: world_state Stopped world state synchronizer 16:06:07 [16:06:07.757] INFO: world_state Created world state synchroniser with block history of infinity 16:06:07 [16:06:07.941] INFO: world_state Started world state synchronizer from block 1 16:06:08 [16:06:08.282] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.8026190000000497,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:08 [16:06:08.283] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.6064139999998588,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:08 [16:06:08.284] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.7879680000005465,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:08 [16:06:08.285] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.7400569999999789,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:08 [16:06:08.286] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8157380000002377,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:08 [16:06:08.614] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.3043809999999212,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:08 [16:06:08.616] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.2312080000001515,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:08 [16:06:08.618] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.1334059999999226,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:08 [16:06:08.620] INFO: world_state Stopped world state synchronizer 16:06:08 [16:06:08.622] INFO: world_state Created world state synchroniser with block history of infinity 16:06:08 [16:06:08.834] INFO: world_state Started world state synchronizer from block 1 16:06:09 [16:06:09.199] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1922770000001037,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:09 [16:06:09.200] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0000229999996009,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:09 [16:06:09.202] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9286019999999553,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:09 [16:06:09.203] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.0556550000001153,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:09 [16:06:09.205] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1658260000003793,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:09 [16:06:09.207] INFO: world_state Stopped world state synchronizer 16:06:09 [16:06:09.209] INFO: world_state Created world state synchroniser with block history of infinity 16:06:09 [16:06:09.397] INFO: world_state Started world state synchronizer from block 1 16:06:09 [16:06:09.740] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.9329820000002655,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:09 [16:06:09.741] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.7725669999999809,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:09 [16:06:09.742] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.7905179999997927,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:09 [16:06:09.744] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.8361389999990934,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:09 [16:06:09.745] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.7888479999983247,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:06:09 [16:06:09.747] INFO: world_state Stopped world state synchronizer 16:06:09 [16:06:09.748] INFO: world_state Created world state synchroniser with block history of infinity 16:06:09 [16:06:09.751] INFO: world_state Stopped world state synchronizer 16:06:09 [16:06:09.753] INFO: world_state Created world state synchroniser with block history of infinity 16:06:09 [16:06:09.930] INFO: world_state Started world state synchronizer from block 1 16:06:10 [16:06:10.195] INFO: world_state Stopped world state synchronizer 16:06:10 PASS src/synchronizer/server_world_state_synchronizer.test.ts (7.474 s) 16:06:10 ServerWorldStateSynchronizer 16:06:10 ✓ updates sync progress (955 ms) 16:06:10 ✓ handles multiple calls to start (503 ms) 16:06:10 ✓ immediately syncs if no new blocks (3 ms) 16:06:10 ✓ cannot be started if already stopped (26 ms) 16:06:10 ✓ can immediately sync to latest (840 ms) 16:06:10 ✓ can immediately sync to a minimum block number (864 ms) 16:06:10 ✓ sync returns immediately if block was already synced (587 ms) 16:06:10 ✓ throws if you try to sync to an unavailable block (539 ms) 16:06:10 ✓ throws if you try to immediate sync when not running (4 ms) 16:06:10 ✓ throws if handling blocks fails (444 ms) 16:06:10 16:06:10 Test Suites: 1 passed, 1 total 16:06:10 Tests: 10 passed, 10 total 16:06:10 Snapshots: 0 total 16:06:10 Time: 7.567 s 16:06:10 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 16:06:10 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?