Parent Log: http://ci.aztec-labs.com/453396286815f782 Command: b895a7fa8f839b60 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/b80949775e5c27908a6055ad24592f7d98bdc45a Env: REF_NAME=gh-readonly-queue/next/pr-14816-fa159c5d52a45358479f951a547fed37f141a6ed CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Sat Jun 14 10:44:39 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14816_amd64_x1-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 10:44:42 [10:44:42.977] INFO: world_state Created world state synchroniser with block history of infinity 10:44:43 [10:44:43.139] INFO: world_state Started world state synchronizer from block 1 10:44:43 [10:44:43.538] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":5.059363999999732,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:43 [10:44:43.542] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.128204000000096,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:43 [10:44:43.544] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.45670999999993,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:43 [10:44:43.740] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.521252000000004,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:43 [10:44:43.744] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":2.177654999999959,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:43 [10:44:43.746] INFO: world_state Stopped world state synchronizer 10:44:43 [10:44:43.748] INFO: world_state Stopped world state synchronizer 10:44:43 [10:44:43.751] INFO: world_state Created world state synchroniser with block history of infinity 10:44:43 [10:44:43.914] INFO: world_state Started world state synchronizer from block 1 10:44:44 [10:44:44.216] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.2530650000007881,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:44 [10:44:44.217] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0036509999999907,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:44 [10:44:44.219] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9108690000002753,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:44 [10:44:44.221] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3223570000000109,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:44 [10:44:44.223] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2674459999998362,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:44 [10:44:44.225] INFO: world_state Stopped world state synchronizer 10:44:44 [10:44:44.226] INFO: world_state Created world state synchroniser with block history of infinity 10:44:44 [10:44:44.227] INFO: world_state Started world state synchronizer from block 1 10:44:44 [10:44:44.228] INFO: world_state Stopped world state synchronizer 10:44:44 [10:44:44.229] INFO: world_state Created world state synchroniser with block history of infinity 10:44:44 [10:44:44.230] INFO: world_state Started world state synchronizer from block 1 10:44:44 [10:44:44.230] INFO: world_state Stopped world state synchronizer 10:44:44 [10:44:44.250] INFO: world_state Stopped world state synchronizer 10:44:44 [10:44:44.252] INFO: world_state Created world state synchroniser with block history of infinity 10:44:44 [10:44:44.438] INFO: world_state Started world state synchronizer from block 1 10:44:44 [10:44:44.759] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.4807199999995646,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:44 [10:44:44.761] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.3153369999999995,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:44 [10:44:44.763] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2940870000002178,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:44 [10:44:44.765] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2947970000004716,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:44 [10:44:44.767] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2197649999998248,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:45 [10:44:45.022] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.5886820000005173,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:45 [10:44:45.025] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.200755000000754,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:45 [10:44:45.026] INFO: world_state Stopped world state synchronizer 10:44:45 [10:44:45.028] INFO: world_state Created world state synchroniser with block history of infinity 10:44:45 [10:44:45.203] INFO: world_state Started world state synchronizer from block 1 10:44:45 [10:44:45.590] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":2.3748590000004697,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:45 [10:44:45.595] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.5605419999992591,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:45 [10:44:45.597] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.309277000000293,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:45 [10:44:45.599] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3448679999992237,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:45 [10:44:45.601] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.3571279999996477,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:45 [10:44:45.894] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.7583560000002763,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:45 [10:44:45.896] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.6378840000006676,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:45 [10:44:45.901] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":2.057443000000603,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:45 [10:44:45.905] INFO: world_state Stopped world state synchronizer 10:44:45 [10:44:45.909] INFO: world_state Created world state synchroniser with block history of infinity 10:44:46 [10:44:46.098] INFO: world_state Started world state synchronizer from block 1 10:44:46 [10:44:46.365] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.3565380000000005,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:46 [10:44:46.367] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.1381129999999757,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:46 [10:44:46.369] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.9837600000000748,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:46 [10:44:46.370] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.7860360000004221,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:46 [10:44:46.371] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1307029999998122,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:46 [10:44:46.374] INFO: world_state Stopped world state synchronizer 10:44:46 [10:44:46.375] INFO: world_state Created world state synchroniser with block history of infinity 10:44:46 [10:44:46.536] INFO: world_state Started world state synchronizer from block 1 10:44:46 [10:44:46.816] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.4571100000002843,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:46 [10:44:46.818] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.251075000000128,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:46 [10:44:46.821] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.7393759999995382,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:46 [10:44:46.824] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.4531899999992675,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:46 [10:44:46.826] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.373367999999573,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 10:44:46 [10:44:46.828] INFO: world_state Stopped world state synchronizer 10:44:46 [10:44:46.830] INFO: world_state Created world state synchroniser with block history of infinity 10:44:46 [10:44:46.834] INFO: world_state Stopped world state synchronizer 10:44:46 [10:44:46.836] INFO: world_state Created world state synchroniser with block history of infinity 10:44:46 [10:44:46.971] INFO: world_state Started world state synchronizer from block 1 10:44:47 [10:44:47.261] INFO: world_state Stopped world state synchronizer 10:44:47 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.536 s) 10:44:47 ServerWorldStateSynchronizer 10:44:47 ✓ updates sync progress (779 ms) 10:44:47 ✓ handles multiple calls to start (475 ms) 10:44:47 ✓ immediately syncs if no new blocks (3 ms) 10:44:47 ✓ cannot be started if already stopped (22 ms) 10:44:47 ✓ can immediately sync to latest (776 ms) 10:44:47 ✓ can immediately sync to a minimum block number (879 ms) 10:44:47 ✓ sync returns immediately if block was already synced (466 ms) 10:44:47 ✓ throws if you try to sync to an unavailable block (454 ms) 10:44:47 ✓ throws if you try to immediate sync when not running (5 ms) 10:44:47 ✓ throws if handling blocks fails (427 ms) 10:44:47 10:44:47 Test Suites: 1 passed, 1 total 10:44:47 Tests: 10 passed, 10 total 10:44:47 Snapshots: 0 total 10:44:47 Time: 6.62 s 10:44:47 Ran all test suites matching /world-state\/src\/synchronizer\/server_world_state_synchronizer.test.ts/i. 10:44:47 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?