Parent Log: http://ci.aztec-labs.com/8e8dd02b40b54e5e Command: 97e7ef9a556fd7e6 yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/cf4d4501e7edfb97cb6406ee7f30893939a522f9 Env: REF_NAME=gh-readonly-queue/master/pr-14912-015cb0cadef6a41094c3649f32a735392e1b9693 CURRENT_VERSION=0.87.9 CI_FULL=1 Date: Wed Jun 18 15:36:00 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-14912_amd64_x2-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_master 15:36:04 [15:36:04.496] INFO: world_state Created world state synchroniser with block history of infinity 15:36:04 [15:36:04.632] INFO: world_state Started world state synchronizer from block 1 15:36:04 [15:36:04.876] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":5.429133999999976,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:04 [15:36:04.880] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.953302000000349,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:04 [15:36:04.883] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.5658260000000155,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:05 [15:36:05.055] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.8075920000001133,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:05 [15:36:05.059] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":2.1278309999997873,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:05 [15:36:05.063] INFO: world_state Stopped world state synchronizer 15:36:05 [15:36:05.065] INFO: world_state Stopped world state synchronizer 15:36:05 [15:36:05.067] INFO: world_state Created world state synchroniser with block history of infinity 15:36:05 [15:36:05.217] INFO: world_state Started world state synchronizer from block 1 15:36:05 [15:36:05.449] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":8.39876699999968,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:05 [15:36:05.455] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":3.093189999999595,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:05 [15:36:05.458] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.3153430000002118,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:05 [15:36:05.462] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":2.554247999999461,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:05 [15:36:05.472] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":9.332484000000477,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:05 [15:36:05.473] INFO: world_state Stopped world state synchronizer 15:36:05 [15:36:05.475] INFO: world_state Created world state synchroniser with block history of infinity 15:36:05 [15:36:05.475] INFO: world_state Started world state synchronizer from block 1 15:36:05 [15:36:05.476] INFO: world_state Stopped world state synchronizer 15:36:05 [15:36:05.477] INFO: world_state Created world state synchroniser with block history of infinity 15:36:05 [15:36:05.478] INFO: world_state Started world state synchronizer from block 1 15:36:05 [15:36:05.478] INFO: world_state Stopped world state synchronizer 15:36:05 [15:36:05.492] INFO: world_state Stopped world state synchronizer 15:36:05 [15:36:05.494] INFO: world_state Created world state synchroniser with block history of infinity 15:36:05 [15:36:05.633] INFO: world_state Started world state synchronizer from block 1 15:36:05 [15:36:05.921] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.893355000000156,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:05 [15:36:05.922] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.7619869999998627,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:05 [15:36:05.923] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.7604469999996581,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:05 [15:36:05.924] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.6611309999998412,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:05 [15:36:05.926] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1790030000001934,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:06 [15:36:06.107] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.2485870000000432,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:06 [15:36:06.108] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":0.8996560000005047,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:06 [15:36:06.110] INFO: world_state Stopped world state synchronizer 15:36:06 [15:36:06.112] INFO: world_state Created world state synchroniser with block history of infinity 15:36:06 [15:36:06.274] INFO: world_state Started world state synchronizer from block 1 15:36:06 [15:36:06.597] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.9600289999998495,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:06 [15:36:06.599] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.8131999999995969,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:06 [15:36:06.600] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.8428819999999178,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:06 [15:36:06.602] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.0261929999996937,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:06 [15:36:06.603] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.9228570000004765,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:06 [15:36:06.910] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.6618530000005194,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:06 [15:36:06.915] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":4.062300000000505,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:06 [15:36:06.917] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":1.3507229999995616,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:06 [15:36:06.919] INFO: world_state Stopped world state synchronizer 15:36:06 [15:36:06.921] INFO: world_state Created world state synchroniser with block history of infinity 15:36:07 [15:36:07.120] INFO: world_state Started world state synchronizer from block 1 15:36:07 [15:36:07.436] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":2.428109000000404,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:07 [15:36:07.438] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.5080219999999827,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:07 [15:36:07.440] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.3585430000002816,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:07 [15:36:07.450] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.847634000000653,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:07 [15:36:07.454] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.4692210000002888,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:07 [15:36:07.462] INFO: world_state Stopped world state synchronizer 15:36:07 [15:36:07.464] INFO: world_state Created world state synchroniser with block history of infinity 15:36:07 [15:36:07.635] INFO: world_state Started world state synchronizer from block 1 15:36:07 [15:36:07.915] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.8998759999994945,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:07 [15:36:07.917] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.9381580000008398,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:07 [15:36:07.918] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.7322850000000471,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:07 [15:36:07.919] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":0.6459699999995792,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:07 [15:36:07.920] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":0.6479099999996834,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:36:07 [15:36:07.921] INFO: world_state Stopped world state synchronizer 15:36:07 [15:36:07.922] INFO: world_state Created world state synchroniser with block history of infinity 15:36:07 [15:36:07.924] INFO: world_state Stopped world state synchronizer 15:36:07 [15:36:07.925] INFO: world_state Created world state synchroniser with block history of infinity 15:36:08 [15:36:08.072] INFO: world_state Started world state synchronizer from block 1 15:36:08 [15:36:08.436] INFO: world_state Stopped world state synchronizer 15:36:08 PASS src/synchronizer/server_world_state_synchronizer.test.ts (6.634 s) 15:36:08 ServerWorldStateSynchronizer 15:36:08 ✓ updates sync progress (574 ms) 15:36:08 ✓ handles multiple calls to start (406 ms) 15:36:08 ✓ immediately syncs if no new blocks (2 ms) 15:36:08 ✓ cannot be started if already stopped (15 ms) 15:36:08 ✓ can immediately sync to latest (617 ms) 15:36:08 ✓ can immediately sync to a minimum block number (809 ms) 15:36:08 ✓ sync returns immediately if block was already synced (542 ms) 15:36:08 ✓ throws if you try to sync to an unavailable block (458 ms) 15:36:08 ✓ throws if you try to immediate sync when not running (3 ms) 15:36:08 ✓ throws if handling blocks fails (513 ms) 15:36:08 15:36:08 Test Suites: 1 passed, 1 total 15:36:08 Tests: 10 passed, 10 total 15:36:08 Snapshots: 0 total 15:36:08 Time: 6.735 s 15:36:08 Ran all test suites matching /world-state\/src\/synchronizer\/server_world_state_synchronizer.test.ts/i. 15:36:08 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?