Fix information logs getting logged as debug in VSCode (#78522)

Updates LSP logging to log using debug more appropriately, and fixes LSP
server logging to not downgrade information logs to debug.

Author: dibarbet

src/LanguageServer/Microsoft.CodeAnalysis.LanguageServer/LanguageServer/Handler/Restore/RestoreHandler.cs

@@ -106,7 +106,7 @@ private static ImmutableArray<string> GetRestorePaths(RestoreParams request, Sol
             .Distinct()
             .ToImmutableArray();
 
-        context.TraceInformation($"Found {projects.Length} restorable projects from {solution.Projects.Count()} projects in solution");
+        context.TraceDebug($"Found {projects.Length} restorable projects from {solution.Projects.Count()} projects in solution");
         return projects;
     }
 }

src/LanguageServer/Microsoft.CodeAnalysis.LanguageServer/Logging/LspServiceLogger.cs

@@ -35,7 +35,7 @@ public LspServiceLogger(ILogger hostLogger)
     /// <summary>
     /// TODO - Switch this to call LogInformation once appropriate callers have been changed to LogDebug.
     /// </summary>
-    public override void LogInformation(string message, params object[] @params) => _hostLogger.LogDebug(message, @params);
+    public override void LogInformation(string message, params object[] @params) => _hostLogger.LogInformation(message, @params);
 
     public override void LogWarning(string message, params object[] @params) => _hostLogger.LogWarning(message, @params);
 }

src/LanguageServer/Microsoft.CommonLanguageServerProtocol.Framework/AbstractLanguageServer.cs

@@ -181,7 +181,7 @@ protected IRequestExecutionQueue<TRequestContext> GetRequestExecutionQueue()
 
     public virtual bool TryGetLanguageForRequest(string methodName, object? serializedRequest, [NotNullWhen(true)] out string? language)
     {
-        Logger.LogInformation($"Using default language handler for {methodName}");
+        Logger.LogDebug($"Using default language handler for {methodName}");
         language = LanguageServerConstants.DefaultLanguageName;
         return true;
     }

src/LanguageServer/Microsoft.CommonLanguageServerProtocol.Framework/QueueItem.cs

@@ -223,7 +223,7 @@ public async Task StartRequestAsync<TRequest, TResponse>(TRequest request, TRequ
         {
             // Record logs + metrics on cancellation.
             _requestTelemetryScope?.RecordCancellation();
-            _logger.LogInformation($"Request was cancelled.");
+            _logger.LogDebug($"Request was cancelled.");
 
             _completionSource.TrySetCanceled(ex.CancellationToken);
         }

src/LanguageServer/Protocol/Extensions/ProtocolConversions.cs

@@ -492,7 +492,7 @@ public static LSP.Range TextSpanToRange(TextSpan textSpan, SourceText text)
 
         if (uri == null)
         {
-            context?.TraceInformation($"Could not convert '{mappedSpan.FilePath}' to uri");
+            context?.TraceWarning($"Could not convert '{mappedSpan.FilePath}' to uri");
             return null;
         }
 

src/LanguageServer/Protocol/Handler/CodeActions/CodeActionResolveHelper.cs

@@ -30,7 +30,7 @@ internal sealed class CodeActionResolveHelper
             data,
             operations,
             context.GetRequiredClientCapabilities().Workspace?.WorkspaceEdit?.ResourceOperations ?? [],
-            context.TraceInformation,
+            context.TraceDebug,
             cancellationToken);
     }
 

src/LanguageServer/Protocol/Handler/Completion/CompletionResolveHandler.cs

@@ -51,7 +51,7 @@ public CompletionResolveHandler(IGlobalOptionService globalOptions)
         if (!completionListCache.TryGetCompletionListCacheEntry(completionItem, out var cacheEntry))
         {
             // Don't have a cache associated with this completion item, cannot resolve.
-            context.TraceInformation("No cache entry found for the provided completion item at resolve time.");
+            context.TraceWarning("No cache entry found for the provided completion item at resolve time.");
             return Task.FromResult(completionItem);
         }
 

src/LanguageServer/Protocol/Handler/Diagnostics/AbstractDocumentPullDiagnosticHandler.cs

@@ -37,7 +37,7 @@ protected override ValueTask<ImmutableArray<IDiagnosticSource>> GetOrderedDiagno
         var textDocument = context.TextDocument;
         if (textDocument is null)
         {
-            context.TraceInformation("Ignoring diagnostics request because no text document was provided");
+            context.TraceDebug("Ignoring diagnostics request because no text document was provided");
             return new([]);
         }
 

src/LanguageServer/Protocol/Handler/Diagnostics/AbstractPullDiagnosticHandler.cs

@@ -107,7 +107,7 @@ protected virtual Task WaitForChangesAsync(string? category, RequestContext cont
         // noise.  It is not exposed to the user.
         if (!this.GlobalOptions.GetOption(SolutionCrawlerRegistrationService.EnableSolutionCrawler))
         {
-            context.TraceInformation($"{this.GetType()}. Skipping due to {nameof(SolutionCrawlerRegistrationService.EnableSolutionCrawler)}={false}");
+            context.TraceDebug($"{this.GetType()}. Skipping due to {nameof(SolutionCrawlerRegistrationService.EnableSolutionCrawler)}={false}");
         }
         else
         {
@@ -116,15 +116,15 @@ protected virtual Task WaitForChangesAsync(string? category, RequestContext cont
             var clientCapabilities = context.GetRequiredClientCapabilities();
             var category = GetRequestDiagnosticCategory(diagnosticsParams);
             var handlerName = $"{this.GetType().Name}(category: {category})";
-            context.TraceInformation($"{handlerName} started getting diagnostics");
+            context.TraceDebug($"{handlerName} started getting diagnostics");
 
             var versionedCache = _categoryToVersionedCache.GetOrAdd(
                 handlerName, static (handlerName, globalOptions) => new(globalOptions, handlerName), GlobalOptions);
 
             // Get the set of results the request said were previously reported.  We can use this to determine both
             // what to skip, and what files we have to tell the client have been removed.
             var previousResults = GetPreviousResults(diagnosticsParams) ?? [];
-            context.TraceInformation($"previousResults.Length={previousResults.Length}");
+            context.TraceDebug($"previousResults.Length={previousResults.Length}");
 
             // Create a mapping from documents to the previous results the client says it has for them.  That way as we
             // process documents we know if we should tell the client it should stay the same, or we can tell it what
@@ -142,7 +142,7 @@ protected virtual Task WaitForChangesAsync(string? category, RequestContext cont
             var orderedSources = await GetOrderedDiagnosticSourcesAsync(
                 diagnosticsParams, category, context, cancellationToken).ConfigureAwait(false);
 
-            context.TraceInformation($"Processing {orderedSources.Length} documents");
+            context.TraceDebug($"Processing {orderedSources.Length} documents");
 
             // Keep track of what diagnostic sources we see this time around.  For any we do not see this time
             // around, we'll notify the client that the diagnostics for it have been removed.
@@ -169,7 +169,7 @@ protected virtual Task WaitForChangesAsync(string? category, RequestContext cont
                 }
                 else
                 {
-                    context.TraceInformation($"Diagnostics were unchanged for {diagnosticSource.ToDisplayString()}");
+                    context.TraceDebug($"Diagnostics were unchanged for {diagnosticSource.ToDisplayString()}");
 
                     // Nothing changed between the last request and this one.  Report a (null-diagnostics,
                     // same-result-id) response to the client as that means they should just preserve the current
@@ -212,7 +212,7 @@ protected virtual Task WaitForChangesAsync(string? category, RequestContext cont
 
             // If we had a progress object, then we will have been reporting to that.  Otherwise, take what we've been
             // collecting and return that.
-            context.TraceInformation($"{this.GetType()} finished getting diagnostics");
+            context.TraceDebug($"{this.GetType()} finished getting diagnostics");
         }
 
         return CreateReturn(progress);
@@ -297,7 +297,7 @@ private void HandleRemovedDocuments(RequestContext context, HashSet<PreviousPull
     {
         foreach (var removedResult in removedPreviousResults)
         {
-            context.TraceInformation($"Clearing diagnostics for removed document: {removedResult.TextDocument.DocumentUri}");
+            context.TraceDebug($"Clearing diagnostics for removed document: {removedResult.TextDocument.DocumentUri}");
 
             // Client is asking server about a document that no longer exists (i.e. was removed/deleted from
             // the workspace). Report a (null-diagnostics, null-result-id) response to the client as that

src/LanguageServer/Protocol/Handler/Diagnostics/AbstractWorkspacePullDiagnosticsHandler.cs

@@ -114,7 +114,7 @@ protected override async Task WaitForChangesAsync(string? category, RequestConte
         }
 
         // We've hit a change, so we close the current request to allow the client to open a new one.
-        context.TraceInformation($"Closing workspace/diagnostics request for {category}");
+        context.TraceDebug($"Closing workspace/diagnostics request for {category}");
         return;
 
         bool HasChanged()