add more info logging

Report about the inputs to main and bootstrap.

Report more details about pre-build steps in
build-sequence.

Report reading requirements and build-order files.

Author: dhellmann

src/fromager/__main__.py

@@ -179,6 +179,14 @@ def main(
     if error_log_file:
         logger.info("logging errors to %s", error_log_file)
 
+    logger.info(f"primary settings file: {settings_file}")
+    logger.info(f"per-package settings dir: {settings_dir}")
+    logger.info(f"variant: {variant}")
+    logger.info(f"patches dir: {patches_dir}")
+    logger.info(f"maximum concurrent jobs: {jobs}")
+    logger.info(f"constraints file: {constraints_file}")
+    logger.info(f"wheel server url: {wheel_server_url}")
+    logger.info(f"network isolation: {network_isolation}")
     overrides.log_overrides()
 
     if network_isolation and not SUPPORTS_NETWORK_ISOLATION:

src/fromager/commands/bootstrap.py

@@ -89,8 +89,10 @@ def bootstrap(
     logger.info("bootstrapping %r variant of %s", wkctx.variant, to_build)
 
     if prev_graph_file:
+        logger.info("reading previous bootstrap data from %s", prev_graph_file)
         prev_graph = dependency_graph.DependencyGraph.from_file(prev_graph_file)
     else:
+        logger.info("no previous bootstrap data")
         prev_graph = None
 
     pre_built = wkctx.settings.list_pre_built()
@@ -103,6 +105,7 @@ def bootstrap(
     # this is to ensure that if we are using an older bootstrap to resolve packages
     # we are able to upgrade a package anywhere in the dependency tree if it is mentioned
     # in the toplevel without having to fall back to history
+    logger.info("resolving top-level dependencies before building")
     for req in to_build:
         pbi = wkctx.package_build_info(req)
         if pbi.pre_built:
@@ -112,6 +115,7 @@ def bootstrap(
             source_url, version = sources.resolve_source(
                 wkctx, req, resolver.PYPI_SERVER_URL
             )
+        logger.info("%s resolves to %s", req, version)
         wkctx.dependency_graph.add_dependency(
             parent_name=None,
             parent_version=None,

src/fromager/commands/build.py

@@ -93,6 +93,7 @@ def build_sequence(
             wkctx.wheel_server_url,
         )
 
+    logger.info("reading build order from %s", build_order_file)
     with open(build_order_file, "r") as f:
         for entry in progress.progress(json.load(f)):
             dist_name = entry["dist"]

src/fromager/commands/download_sequence.py

@@ -56,6 +56,7 @@ def download_sequence(
     else:
         wheel_servers = [sdist_server_url]
 
+    logger.info("reading build order from %s", build_order_file)
     with open(build_order_file, "r") as f:
         build_order = json.load(f)
 

src/fromager/packagesettings.py

@@ -365,7 +365,7 @@ def from_file(cls, filename: pathlib.Path) -> "PackageSettings":
         The package name is taken from the stem of the file name.
         """
         filename = filename.absolute()
-        logger.debug("Loading package config from %s", filename)
+        logger.debug("loading package config from %s", filename)
         raw_yaml = filename.read_text(encoding="utf-8")
         return cls.from_string(filename.stem, raw_yaml, source=filename)
 
@@ -412,7 +412,7 @@ def _resolve_template(
         return string.Template(template).substitute(template_env)
     except KeyError:
         logger.warning(
-            f"{pkg}: Couldn't resolve url or name for {template} using the template: {template_env}"
+            f"{pkg}: couldn't resolve url or name for {template} using the template: {template_env}"
         )
         raise
 

src/fromager/requirements_file.py

@@ -21,6 +21,7 @@ class RequirementType(StrEnum):
 def parse_requirements_file(
     req_file: pathlib.Path,
 ) -> typing.Iterable[str]:
+    logger.debug("reading requirements file %s", req_file)
     lines = []
     with open(req_file, "r") as f:
         for line in f: