Parent Log: http://ci.aztec-labs.com/94875c55e610a324 Command: 751df696adf04d3f yarn-project/scripts/run_test.sh world-state/src/synchronizer/server_world_state_synchronizer.test.ts Commit: https://github.com/AztecProtocol/aztec-packages/commit/106f897363bb5aee27ddd091b5be9641b011c5bb Env: REF_NAME=gh-readonly-queue/next/pr-15004-2b9c409698cf0f475a7a9f5884117c8ad2a4f79a CURRENT_VERSION=0.87.6 CI_FULL=1 Date: Thu Jun 12 15:52:43 UTC 2025 System: ARCH=amd64 CPUS=128 MEM=493Gi HOSTNAME=pr-15004_amd64_x2-full Resources: CPU_LIST=0-127 CPUS=2 MEM=8g TIMEOUT=600s History: http://ci.aztec-labs.com/list/history_c070c71f57e89be8_next 15:52:46 [15:52:46.449] INFO: world_state Created world state synchroniser with block history of infinity 15:52:46 [15:52:46.559] INFO: world_state Started world state synchronizer from block 1 15:52:46 [15:52:46.972] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":13.100282999999763,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:46 [15:52:46.977] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":2.645336000000043,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:46 [15:52:46.980] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":2.393211000000065,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:47 [15:52:47.176] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":19.57425699999976,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:47 [15:52:47.180] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":2.736228999999639,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:47 [15:52:47.183] INFO: world_state Stopped world state synchronizer 15:52:47 [15:52:47.185] INFO: world_state Stopped world state synchronizer 15:52:47 [15:52:47.189] INFO: world_state Created world state synchroniser with block history of infinity 15:52:47 [15:52:47.345] INFO: world_state Started world state synchronizer from block 1 15:52:47 [15:52:47.592] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.4911079999997128,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:47 [15:52:47.594] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.4280359999997927,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:47 [15:52:47.596] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.297892999999931,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:47 [15:52:47.598] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.3683040000000801,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:47 [15:52:47.600] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2331519999997909,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:47 [15:52:47.601] INFO: world_state Stopped world state synchronizer 15:52:47 [15:52:47.603] INFO: world_state Created world state synchroniser with block history of infinity 15:52:47 [15:52:47.603] INFO: world_state Started world state synchronizer from block 1 15:52:47 [15:52:47.604] INFO: world_state Stopped world state synchronizer 15:52:47 [15:52:47.605] INFO: world_state Created world state synchroniser with block history of infinity 15:52:47 [15:52:47.605] INFO: world_state Started world state synchronizer from block 1 15:52:47 [15:52:47.606] INFO: world_state Stopped world state synchronizer 15:52:47 [15:52:47.621] INFO: world_state Stopped world state synchronizer 15:52:47 [15:52:47.622] INFO: world_state Created world state synchroniser with block history of infinity 15:52:47 [15:52:47.771] INFO: world_state Started world state synchronizer from block 1 15:52:48 [15:52:48.090] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.9926049999994575,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:48 [15:52:48.091] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.8778330000004644,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:48 [15:52:48.093] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.9455889999999272,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:48 [15:52:48.095] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.2489719999994122,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:48 [15:52:48.098] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.6439220000002024,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:48 [15:52:48.245] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.4103449999993245,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:48 [15:52:48.247] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.2322210000002087,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:48 [15:52:48.249] INFO: world_state Stopped world state synchronizer 15:52:48 [15:52:48.251] INFO: world_state Created world state synchroniser with block history of infinity 15:52:48 [15:52:48.415] INFO: world_state Started world state synchronizer from block 1 15:52:48 [15:52:48.678] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":0.991095000000314,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:48 [15:52:48.679] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":0.7904900000003181,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:48 [15:52:48.680] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":0.762018999999782,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:48 [15:52:48.682] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.9080579999999827,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:48 [15:52:48.685] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.5004289999997127,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:48 [15:52:48.927] INFO: world_state World state updated with L2 block 6 {"eventName":"l2-block-handled","duration":1.5132290000001376,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":6,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:48 [15:52:48.930] INFO: world_state World state updated with L2 block 7 {"eventName":"l2-block-handled","duration":1.3183429999999134,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":7,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:48 [15:52:48.939] INFO: world_state World state updated with L2 block 8 {"eventName":"l2-block-handled","duration":8.768982999999935,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":8,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:48 [15:52:48.940] INFO: world_state Stopped world state synchronizer 15:52:48 [15:52:48.942] INFO: world_state Created world state synchroniser with block history of infinity 15:52:49 [15:52:49.094] INFO: world_state Started world state synchronizer from block 1 15:52:49 [15:52:49.369] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.3923549999999523,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:49 [15:52:49.371] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.2072500000003856,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:49 [15:52:49.373] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.1788399999995818,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:49 [15:52:49.375] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.195600000000013,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:49 [15:52:49.376] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.2157500000002983,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:49 [15:52:49.378] INFO: world_state Stopped world state synchronizer 15:52:49 [15:52:49.380] INFO: world_state Created world state synchroniser with block history of infinity 15:52:49 [15:52:49.531] INFO: world_state Started world state synchronizer from block 1 15:52:49 [15:52:49.809] INFO: world_state World state updated with L2 block 1 {"eventName":"l2-block-handled","duration":1.254030999999486,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":1,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:49 [15:52:49.811] INFO: world_state World state updated with L2 block 2 {"eventName":"l2-block-handled","duration":1.358024000000114,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":2,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:49 [15:52:49.813] INFO: world_state World state updated with L2 block 3 {"eventName":"l2-block-handled","duration":1.2891030000000683,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":3,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:49 [15:52:49.815] INFO: world_state World state updated with L2 block 4 {"eventName":"l2-block-handled","duration":1.0847780000003695,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":4,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:49 [15:52:49.816] INFO: world_state World state updated with L2 block 5 {"eventName":"l2-block-handled","duration":1.1395590000001903,"unfinalisedBlockNumber":0,"finalisedBlockNumber":0,"oldestHistoricBlock":0,"txCount":4,"blockNumber":5,"blockTimestamp":1796,"privateLogCount":128,"publicLogCount":12,"contractClassLogCount":4,"contractClassLogSize":6040} 15:52:49 [15:52:49.818] INFO: world_state Stopped world state synchronizer 15:52:49 [15:52:49.820] INFO: world_state Created world state synchroniser with block history of infinity 15:52:49 [15:52:49.823] INFO: world_state Stopped world state synchronizer 15:52:49 [15:52:49.824] INFO: world_state Created world state synchroniser with block history of infinity 15:52:50 [15:52:50.013] INFO: world_state Started world state synchronizer from block 1 15:52:50 [15:52:50.303] INFO: world_state Stopped world state synchronizer 15:52:50 PASS src/synchronizer/server_world_state_synchronizer.test.ts (5.87 s) 15:52:50 ServerWorldStateSynchronizer 15:52:50 ✓ updates sync progress (744 ms) 15:52:50 ✓ handles multiple calls to start (414 ms) 15:52:50 ✓ immediately syncs if no new blocks (2 ms) 15:52:50 ✓ cannot be started if already stopped (17 ms) 15:52:50 ✓ can immediately sync to latest (627 ms) 15:52:50 ✓ can immediately sync to a minimum block number (691 ms) 15:52:50 ✓ sync returns immediately if block was already synced (438 ms) 15:52:50 ✓ throws if you try to sync to an unavailable block (440 ms) 15:52:50 ✓ throws if you try to immediate sync when not running (4 ms) 15:52:50 ✓ throws if handling blocks fails (480 ms) 15:52:50 15:52:50 Test Suites: 1 passed, 1 total 15:52:50 Tests: 10 passed, 10 total 15:52:50 Snapshots: 0 total 15:52:50 Time: 5.961 s 15:52:50 Ran all test suites matching /world-state\/src\/synchronizer\/server_world_state_synchronizer.test.ts/i. 15:52:50 Force exiting Jest: Have you considered using `--detectOpenHandles` to detect async operations that kept running after all tests finished?