Parent Log: http://ci.aztec-labs.com/fadb814e2da9969e Command: 22a91bf956a4cb93 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/47bd93499e6606131b6ececa3536632cf5e84409 Env: REF_NAME=gh-readonly-queue/next/pr-15080-215be54ba7d05741f58f591612a9e5454104b24d CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Wed Jun 18 09:37:09 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15080_amd64_x2-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 09:37:13 [09:37:13.653] INFO: world_state Created world state synchroniser with block history of infinity 09:37:13 [09:37:13.804] INFO: world_state Started world state synchronizer from block 1 09:37:14 [09:37:14.285] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":7.717227999999523,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:14 [09:37:14.288] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.6770249999999578,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:14 [09:37:14.290] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.4766840000002048,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:14 [09:37:14.463] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.9182359999995242,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:14 [09:37:14.483] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.7469380000002275,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:14 [09:37:14.485] INFO: world_state Stopped world state synchronizer 09:37:14 [09:37:14.486] INFO: world_state Stopped world state synchronizer 09:37:14 [09:37:14.488] INFO: world_state Created world state synchroniser with block history of infinity 09:37:14 [09:37:14.623] INFO: world_state Started world state synchronizer from block 1 09:37:14 [09:37:14.891] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.1868590000003678,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:14 [09:37:14.893] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.104284999999436,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:14 [09:37:14.895] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0900650000003225,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:14 [09:37:14.897] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.235341999999946,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:14 [09:37:14.899] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.545347999999649,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:14 [09:37:14.900] INFO: world_state Stopped world state synchronizer 09:37:14 [09:37:14.902] INFO: world_state Created world state synchroniser with block history of infinity 09:37:14 [09:37:14.903] INFO: world_state Started world state synchronizer from block 1 09:37:14 [09:37:14.904] INFO: world_state Stopped world state synchronizer 09:37:14 [09:37:14.905] INFO: world_state Created world state synchroniser with block history of infinity 09:37:14 [09:37:14.906] INFO: world_state Started world state synchronizer from block 1 09:37:14 [09:37:14.906] INFO: world_state Stopped world state synchronizer 09:37:14 [09:37:14.927] INFO: world_state Stopped world state synchronizer 09:37:14 [09:37:14.929] INFO: world_state Created world state synchroniser with block history of infinity 09:37:15 [09:37:15.089] INFO: world_state Started world state synchronizer from block 1 09:37:15 [09:37:15.399] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.4027909999995245,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:15 [09:37:15.401] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.8488429999997607,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:15 [09:37:15.402] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.7910399999991569,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:15 [09:37:15.403] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.7896289999998771,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:15 [09:37:15.404] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.7764790000001085,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:15 [09:37:15.597] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.02657199999976,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:15 [09:37:15.598] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.9119259999997666,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:15 [09:37:15.599] INFO: world_state Stopped world state synchronizer 09:37:15 [09:37:15.601] INFO: world_state Created world state synchroniser with block history of infinity 09:37:15 [09:37:15.760] INFO: world_state Started world state synchronizer from block 1 09:37:16 [09:37:16.036] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.3658390000000509,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:16 [09:37:16.037] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9294369999997798,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:16 [09:37:16.039] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0699439999998503,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:16 [09:37:16.040] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.1441570000006323,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:16 [09:37:16.042] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.062554000000091,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:16 [09:37:16.271] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.0939650000000256,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:16 [09:37:16.273] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.0021910000004937,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:16 [09:37:16.274] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":0.7951100000000224,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:16 [09:37:16.281] INFO: world_state Stopped world state synchronizer 09:37:16 [09:37:16.283] INFO: world_state Created world state synchroniser with block history of infinity 09:37:16 [09:37:16.409] INFO: world_state Started world state synchronizer from block 1 09:37:16 [09:37:16.669] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.142138000000159,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:16 [09:37:16.671] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0221709999996165,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:16 [09:37:16.672] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0767739999992045,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:16 [09:37:16.673] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.7672789999996894,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:16 [09:37:16.674] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.747747000000345,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:16 [09:37:16.676] INFO: world_state Stopped world state synchronizer 09:37:16 [09:37:16.677] INFO: world_state Created world state synchroniser with block history of infinity 09:37:16 [09:37:16.823] INFO: world_state Started world state synchronizer from block 1 09:37:17 [09:37:17.063] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.4440119999999297,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:17 [09:37:17.065] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0871250000000146,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:17 [09:37:17.066] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.1818789999997534,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:17 [09:37:17.068] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.0333019999998214,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:17 [09:37:17.069] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.8678340000005846,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:37:17 [09:37:17.071] INFO: world_state Stopped world state synchronizer 09:37:17 [09:37:17.073] INFO: world_state Created world state synchroniser with block history of infinity 09:37:17 [09:37:17.075] INFO: world_state Stopped world state synchronizer 09:37:17 [09:37:17.076] INFO: world_state Created world state synchroniser with block history of infinity 09:37:17 [09:37:17.236] INFO: world_state Started world state synchronizer from block 1 09:37:17 [09:37:17.528] INFO: world_state Stopped world state synchronizer 09:37:17 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.653 s) 09:37:17 ServerWorldStateSynchronizer 09:37:17 ✓ updates sync progress (841 ms) 09:37:17 ✓ handles multiple calls to start (413 ms) 09:37:17 ✓ immediately syncs if no new blocks (3 ms) 09:37:17 ✓ cannot be started if already stopped (23 ms) 09:37:17 ✓ can immediately sync to latest (672 ms) 09:37:17 ✓ can immediately sync to a minimum block number (682 ms) 09:37:17 ✓ sync returns immediately if block was already synced (394 ms) 09:37:17 ✓ throws if you try to sync to an unavailable block (395 ms) 09:37:17 ✓ throws if you try to immediate sync when not running (3 ms) 09:37:17 ✓ throws if handling blocks fails (453 ms) 09:37:17 09:37:17 Test Suites: 1 passed, 1 total 09:37:17 Tests: 10 passed, 10 total 09:37:17 Snapshots: 0 total 09:37:17 Time: 6.746 s 09:37:17 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 09:37:17 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?