Parent Log: http://ci.aztec-labs.com/3def59c4542ba0a9 Command: 2a84f19e72ab8189 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/69efc9daf98e823f486416900de52fe8a44f25d9 Env: REF_NAME=gh-readonly-queue/next/pr-15187-6e2f55a89973b55c8c3c210b7f3a692b4d3d6473 CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Mon Jun 23 16:08:02 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15187_amd64_x1-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 16:08:05 [16:08:05.903] INFO: world_state Created world state synchroniser with block history of infinity 16:08:06 [16:08:06.055] INFO: world_state Started world state synchronizer from block 1 16:08:06 [16:08:06.465] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":5.259204000000409,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:06 [16:08:06.468] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.5599790000001121,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:06 [16:08:06.478] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.6439220000002024,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:06 [16:08:06.663] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":13.573465000000397,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:06 [16:08:06.666] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":2.1919150000003356,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:06 [16:08:06.669] INFO: world_state Stopped world state synchronizer 16:08:06 [16:08:06.670] INFO: world_state Stopped world state synchronizer 16:08:06 [16:08:06.672] INFO: world_state Created world state synchroniser with block history of infinity 16:08:06 [16:08:06.841] INFO: world_state Started world state synchronizer from block 1 16:08:07 [16:08:07.117] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.2825220000004265,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:07 [16:08:07.121] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.0859229999996387,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:07 [16:08:07.123] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.4684870000000956,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:07 [16:08:07.126] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.7636350000002494,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:07 [16:08:07.128] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.6173309999994672,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:07 [16:08:07.130] INFO: world_state Stopped world state synchronizer 16:08:07 [16:08:07.131] INFO: world_state Created world state synchroniser with block history of infinity 16:08:07 [16:08:07.132] INFO: world_state Started world state synchronizer from block 1 16:08:07 [16:08:07.133] INFO: world_state Stopped world state synchronizer 16:08:07 [16:08:07.134] INFO: world_state Created world state synchroniser with block history of infinity 16:08:07 [16:08:07.135] INFO: world_state Started world state synchronizer from block 1 16:08:07 [16:08:07.135] INFO: world_state Stopped world state synchronizer 16:08:07 [16:08:07.156] INFO: world_state Stopped world state synchronizer 16:08:07 [16:08:07.158] INFO: world_state Created world state synchroniser with block history of infinity 16:08:07 [16:08:07.347] INFO: world_state Started world state synchronizer from block 1 16:08:07 [16:08:07.634] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1773800000000847,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:07 [16:08:07.636] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.3536340000000564,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:07 [16:08:07.638] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.331253999999717,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:07 [16:08:07.640] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.367023999999219,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:07 [16:08:07.642] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.318623000000116,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:07 [16:08:07.808] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.5056489999997211,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:07 [16:08:07.810] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.3956459999999424,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:07 [16:08:07.812] INFO: world_state Stopped world state synchronizer 16:08:07 [16:08:07.814] INFO: world_state Created world state synchroniser with block history of infinity 16:08:07 [16:08:07.990] INFO: world_state Started world state synchronizer from block 1 16:08:08 [16:08:08.326] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.9582499999996799,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:08 [16:08:08.329] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.2020809999994526,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:08 [16:08:08.331] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.03086600000006,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:08 [16:08:08.333] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3129430000008142,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:08 [16:08:08.334] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8560809999999037,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:08 [16:08:08.599] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.4688070000001971,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:08 [16:08:08.604] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":3.9755809999996927,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:08 [16:08:08.606] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.426115999999638,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:08 [16:08:08.624] INFO: world_state Stopped world state synchronizer 16:08:08 [16:08:08.627] INFO: world_state Created world state synchroniser with block history of infinity 16:08:08 [16:08:08.769] INFO: world_state Started world state synchronizer from block 1 16:08:09 [16:08:09.035] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.3661640000000261,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:09 [16:08:09.037] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.2208609999997861,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:09 [16:08:09.039] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.1882699999996476,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:09 [16:08:09.041] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3079730000008567,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:09 [16:08:09.042] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1648800000002666,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:09 [16:08:09.044] INFO: world_state Stopped world state synchronizer 16:08:09 [16:08:09.046] INFO: world_state Created world state synchroniser with block history of infinity 16:08:09 [16:08:09.223] INFO: world_state Started world state synchronizer from block 1 16:08:09 [16:08:09.571] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":2.2823490000000675,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:09 [16:08:09.574] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.9366090000003169,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:09 [16:08:09.577] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.492298000000119,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:09 [16:08:09.579] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.4180560000004334,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:09 [16:08:09.581] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.3325839999997697,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 16:08:09 [16:08:09.585] INFO: world_state Stopped world state synchronizer 16:08:09 [16:08:09.588] INFO: world_state Created world state synchroniser with block history of infinity 16:08:09 [16:08:09.593] INFO: world_state Stopped world state synchronizer 16:08:09 [16:08:09.595] INFO: world_state Created world state synchroniser with block history of infinity 16:08:09 [16:08:09.773] INFO: world_state Started world state synchronizer from block 1 16:08:10 [16:08:10.071] INFO: world_state Stopped world state synchronizer 16:08:10 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.531 s) 16:08:10 ServerWorldStateSynchronizer 16:08:10 ✓ updates sync progress (773 ms) 16:08:10 ✓ handles multiple calls to start (458 ms) 16:08:10 ✓ immediately syncs if no new blocks (3 ms) 16:08:10 ✓ cannot be started if already stopped (23 ms) 16:08:10 ✓ can immediately sync to latest (656 ms) 16:08:10 ✓ can immediately sync to a minimum block number (811 ms) 16:08:10 ✓ sync returns immediately if block was already synced (419 ms) 16:08:10 ✓ throws if you try to sync to an unavailable block (541 ms) 16:08:10 ✓ throws if you try to immediate sync when not running (7 ms) 16:08:10 ✓ throws if handling blocks fails (477 ms) 16:08:10 16:08:10 Test Suites: 1 passed, 1 total 16:08:10 Tests: 10 passed, 10 total 16:08:10 Snapshots: 0 total 16:08:10 Time: 6.668 s 16:08:10 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 16:08:10 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?