Make -ldebug less chatty when using remote caching (#11667)

These workunits were flooding `-ldebug`. If a user still wants them at `-ldebug` level to debug remoting, they can use `--log-levels-by-target`. This PR is about setting a sensible default for every day uses.

[ci skip-build-wheels]

src/python/pants/engine/process.py

@@ -505,7 +505,7 @@ async def get_bash() -> BashBinary:
     return await Get(BashBinary, BashBinaryRequest(rationale="execute bash scripts"))
 
 
-@rule(desc="Find binary path", level=LogLevel.DEBUG)
+@rule
 async def find_binary(request: BinaryPathRequest) -> BinaryPaths:
     # If we are not already locating bash, recurse to locate bash to use it as an absolute path in
     # our shebang. This avoids mixing locations that we would search for bash into the search paths

src/python/pants/option/global_options.py

@@ -189,6 +189,9 @@ def from_options(
                 remote_cache_read = False
                 remote_cache_write = False
             else:
+                logger.debug(
+                    "`--remote-auth-plugin` succeeded. Remote caching/execution will be attempted."
+                )
                 remote_execution_headers = auth_plugin_result.execution_headers
                 remote_store_headers = auth_plugin_result.store_headers
                 if (

src/rust/engine/process_execution/src/cache.rs

@@ -131,7 +131,7 @@ impl crate::CommandRunner for CommandRunner {
       context2.workunit_store,
       "local_cache_read".to_owned(),
       WorkunitMetadata {
-        level: Level::Debug,
+        level: Level::Trace,
         ..WorkunitMetadata::default()
       },
       cache_read_future,

src/rust/engine/process_execution/src/remote.rs

@@ -313,7 +313,7 @@ impl CommandRunner {
           parent_id,
           &workunit_store,
           WorkunitMetadata {
-            level: Level::Debug,
+            level: Level::Trace,
             ..WorkunitMetadata::default()
           },
         ),
@@ -338,7 +338,7 @@ impl CommandRunner {
           parent_id,
           &workunit_store,
           WorkunitMetadata {
-            level: Level::Debug,
+            level: Level::Trace,
             ..WorkunitMetadata::default()
           },
         ),
@@ -363,7 +363,7 @@ impl CommandRunner {
           parent_id,
           &workunit_store,
           WorkunitMetadata {
-            level: Level::Debug,
+            level: Level::Trace,
             ..WorkunitMetadata::default()
           },
         ),
@@ -388,7 +388,7 @@ impl CommandRunner {
           parent_id,
           &workunit_store,
           WorkunitMetadata {
-            level: Level::Debug,
+            level: Level::Trace,
             ..WorkunitMetadata::default()
           },
         ),
@@ -771,7 +771,8 @@ impl crate::CommandRunner for CommandRunner {
       context.workunit_store.clone(),
       "ensure_action_stored_locally".to_owned(),
       WorkunitMetadata {
-        level: Level::Debug,
+        level: Level::Trace,
+        desc: Some(format!("ensure action stored locally for {:?}", action)),
         ..WorkunitMetadata::default()
       },
       ensure_action_stored_locally(&self.store, &command, &action),
@@ -785,7 +786,8 @@ impl crate::CommandRunner for CommandRunner {
       context.workunit_store.clone(),
       "check_action_cache".to_owned(),
       WorkunitMetadata {
-        level: Level::Debug,
+        level: Level::Trace,
+        desc: Some(format!("check action cache for {:?}", action_digest)),
         ..WorkunitMetadata::default()
       },
       check_action_cache(
@@ -813,7 +815,8 @@ impl crate::CommandRunner for CommandRunner {
       context.workunit_store.clone(),
       "ensure_action_uploaded".to_owned(),
       WorkunitMetadata {
-        level: Level::Debug,
+        level: Level::Trace,
+        desc: Some(format!("ensure action uploaded for {:?}", action_digest)),
         ..WorkunitMetadata::default()
       },
       ensure_action_uploaded(&store, command_digest, action_digest, request.input_files),
@@ -831,7 +834,7 @@ impl crate::CommandRunner for CommandRunner {
       context.workunit_store.clone(),
       "run_execute_request".to_owned(),
       WorkunitMetadata {
-        level: Level::Debug,
+        level: Level::Trace,
         ..WorkunitMetadata::default()
       },
       timeout_fut,

src/rust/engine/process_execution/src/remote_cache.rs

@@ -342,7 +342,8 @@ impl CommandRunner {
       context.workunit_store.clone(),
       "ensure_action_uploaded".to_owned(),
       WorkunitMetadata {
-        level: Level::Debug,
+        level: Level::Trace,
+        desc: Some(format!("ensure action uploaded for {:?}", action_digest)),
         ..WorkunitMetadata::default()
       },
       crate::remote::ensure_action_uploaded(
@@ -408,7 +409,8 @@ impl crate::CommandRunner for CommandRunner {
       context.workunit_store.clone(),
       "ensure_action_stored_locally".to_owned(),
       WorkunitMetadata {
-        level: Level::Debug,
+        level: Level::Trace,
+        desc: Some(format!("ensure action stored locally for {:?}", action)),
         ..WorkunitMetadata::default()
       },
       crate::remote::ensure_action_stored_locally(&self.store, &command, &action),
@@ -425,7 +427,8 @@ impl crate::CommandRunner for CommandRunner {
           context.workunit_store.clone(),
           "check_action_cache".to_owned(),
           WorkunitMetadata {
-            level: Level::Debug,
+            level: Level::Trace,
+            desc: Some(format!("check action cache for {:?}", action_digest)),
             ..WorkunitMetadata::default()
           },
           crate::remote::check_action_cache(
@@ -527,7 +530,7 @@ impl crate::CommandRunner for CommandRunner {
         context.workunit_store,
         "remote_cache_write".to_owned(),
         WorkunitMetadata {
-          level: Level::Debug,
+          level: Level::Trace,
           ..WorkunitMetadata::default()
         },
         cache_write_future,