ACI ·

Parent Log: http://ci.aztec-labs.com/92c2dda0ace2278e Command: a5511b51e55ea9c0 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/6ee95286f8bcc5bb6dca43222459ac5bf43a8a75 Env: REF_NAME=gh-readonly-queue/next/pr-15156-9cbd161a1dfa928167c1f969200b95df55ed4c6b CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Fri Jun 20 09:44:13 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15156_amd64_x4-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 09:44:16 [09:44:16.868] INFO: world_state Created world state synchroniser with block history of infinity 09:44:17 [09:44:17.022] INFO: world_state Started world state synchronizer from block 1 09:44:17 [09:44:17.445] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":7.0589630000004036,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:17 [09:44:17.450] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.8921620000000985,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:17 [09:44:17.453] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.676546000000144,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:17 [09:44:17.672] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.8796739999997953,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:17 [09:44:17.675] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.4665319999999156,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:17 [09:44:17.677] INFO: world_state Stopped world state synchronizer 09:44:17 [09:44:17.678] INFO: world_state Stopped world state synchronizer 09:44:17 [09:44:17.682] INFO: world_state Created world state synchroniser with block history of infinity 09:44:17 [09:44:17.855] INFO: world_state Started world state synchronizer from block 1 09:44:18 [09:44:18.138] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.2024219999993875,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:18 [09:44:18.140] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.1532880000004297,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:18 [09:44:18.141] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.156699000000117,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:18 [09:44:18.143] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3438230000001568,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:18 [09:44:18.145] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2815989999999147,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:18 [09:44:18.146] INFO: world_state Stopped world state synchronizer 09:44:18 [09:44:18.148] INFO: world_state Created world state synchroniser with block history of infinity 09:44:18 [09:44:18.148] INFO: world_state Started world state synchronizer from block 1 09:44:18 [09:44:18.149] INFO: world_state Stopped world state synchronizer 09:44:18 [09:44:18.150] INFO: world_state Created world state synchroniser with block history of infinity 09:44:18 [09:44:18.150] INFO: world_state Started world state synchronizer from block 1 09:44:18 [09:44:18.150] INFO: world_state Stopped world state synchronizer 09:44:18 [09:44:18.166] INFO: world_state Stopped world state synchronizer 09:44:18 [09:44:18.168] INFO: world_state Created world state synchroniser with block history of infinity 09:44:18 [09:44:18.330] INFO: world_state Started world state synchronizer from block 1 09:44:18 [09:44:18.651] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":2.191848000000391,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:18 [09:44:18.654] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.7171820000003208,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:18 [09:44:18.656] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.6990499999992608,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:18 [09:44:18.670] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":12.563465000000178,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:18 [09:44:18.672] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.520676999999523,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:18 [09:44:18.887] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":2.1014210000003004,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:18 [09:44:18.890] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.7213819999997213,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:18 [09:44:18.893] INFO: world_state Stopped world state synchronizer 09:44:18 [09:44:18.896] INFO: world_state Created world state synchroniser with block history of infinity 09:44:19 [09:44:19.064] INFO: world_state Started world state synchronizer from block 1 09:44:19 [09:44:19.370] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.6642579999997906,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:19 [09:44:19.372] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.280468999999357,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:19 [09:44:19.374] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2093030000005456,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:19 [09:44:19.376] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.4467610000001514,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:19 [09:44:19.378] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2101429999993343,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:19 [09:44:19.646] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.0314389999994091,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:19 [09:44:19.647] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.9677639999999883,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:19 [09:44:19.650] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.4748229999995601,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:19 [09:44:19.651] INFO: world_state Stopped world state synchronizer 09:44:19 [09:44:19.652] INFO: world_state Created world state synchroniser with block history of infinity 09:44:19 [09:44:19.819] INFO: world_state Started world state synchronizer from block 1 09:44:20 [09:44:20.087] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.0206090000001495,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:20 [09:44:20.089] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.0784130000001824,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:20 [09:44:20.090] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0300590000006196,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:20 [09:44:20.092] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.9666339999994307,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:20 [09:44:20.093] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1737599999996746,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:20 [09:44:20.095] INFO: world_state Stopped world state synchronizer 09:44:20 [09:44:20.097] INFO: world_state Created world state synchroniser with block history of infinity 09:44:20 [09:44:20.307] INFO: world_state Started world state synchronizer from block 1 09:44:20 [09:44:20.607] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.404077999999572,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:20 [09:44:20.609] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9758549999996831,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:20 [09:44:20.610] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.0205379999997604,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:20 [09:44:20.612] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.0036870000003546,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:20 [09:44:20.613] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1390169999995123,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 09:44:20 [09:44:20.615] INFO: world_state Stopped world state synchronizer 09:44:20 [09:44:20.616] INFO: world_state Created world state synchroniser with block history of infinity 09:44:20 [09:44:20.619] INFO: world_state Stopped world state synchronizer 09:44:20 [09:44:20.621] INFO: world_state Created world state synchroniser with block history of infinity 09:44:20 [09:44:20.770] INFO: world_state Started world state synchronizer from block 1 09:44:21 [09:44:21.038] INFO: world_state Stopped world state synchronizer 09:44:21 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.389 s) 09:44:21 ServerWorldStateSynchronizer 09:44:21 ✓ updates sync progress (817 ms) 09:44:21 ✓ handles multiple calls to start (465 ms) 09:44:21 ✓ immediately syncs if no new blocks (2 ms) 09:44:21 ✓ cannot be started if already stopped (18 ms) 09:44:21 ✓ can immediately sync to latest (726 ms) 09:44:21 ✓ can immediately sync to a minimum block number (756 ms) 09:44:21 ✓ sync returns immediately if block was already synced (444 ms) 09:44:21 ✓ throws if you try to sync to an unavailable block (519 ms) 09:44:21 ✓ throws if you try to immediate sync when not running (4 ms) 09:44:21 ✓ throws if handling blocks fails (419 ms) 09:44:21 09:44:21 Test Suites: 1 passed, 1 total 09:44:21 Tests: 10 passed, 10 total 09:44:21 Snapshots: 0 total 09:44:21 Time: 6.48 s 09:44:21 Ran all test suites matching world-state/src/synchronizer/server_world_state_synchronizer.test.ts. 09:44:21 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?