Add comprehensive logging for post scan tasks

1 files changed, 31 insertions, 3 deletions

diff --git a/Emby.Server.Implementations/Library/LibraryManager.cs b/Emby.Server.Implementations/Library/LibraryManager.cs
index 3db8265f6e..19b2ad2ba4 100644
--- a/Emby.Server.Implementations/Library/LibraryManager.cs
+++ b/Emby.Server.Implementations/Library/LibraryManager.cs
@@ -3,6 +3,7 @@
 
 using System;
 using System.Collections.Generic;
+using System.Diagnostics;
 using System.Globalization;
 using System.IO;
 using System.Linq;
@@ -1489,6 +1490,10 @@ namespace Emby.Server.Implementations.Library
             var numComplete = 0;
             var numTasks = tasks.Count;
 
+            _logger.LogInformation("Running {TaskCount} post-scan task(s)", numTasks);
+
+            var phaseStart = Stopwatch.GetTimestamp();
+
             foreach (var task in tasks)
             {
                 // Prevent access to modified closure
@@ -1506,20 +1511,39 @@ namespace Emby.Server.Implementations.Library
                     progress.Report(innerPercent);
                 });
 
-                _logger.LogDebug("Running post-scan task {0}", task.GetType().Name);
+                var taskName = task.GetType().Name;
+                var taskStart = Stopwatch.GetTimestamp();
+
+                _logger.LogInformation(
+                    "Running post-scan task {TaskNumber}/{TaskCount}: {TaskName}",
+                    currentNumComplete + 1,
+                    numTasks,
+                    taskName);
 
                 try
                 {
                     await task.Run(innerProgress, cancellationToken).ConfigureAwait(false);
+
+                    _logger.LogInformation(
+                        "Post-scan task {TaskName} completed in {Elapsed}",
+                        taskName,
+                        Stopwatch.GetElapsedTime(taskStart));
                 }
                 catch (OperationCanceledException)
                 {
-                    _logger.LogInformation("Post-scan task cancelled: {0}", task.GetType().Name);
+                    _logger.LogInformation(
+                        "Post-scan task cancelled after {Elapsed}: {TaskName}",
+                        Stopwatch.GetElapsedTime(taskStart),
+                        taskName);
                     throw;
                 }
                 catch (Exception ex)
                 {
-                    _logger.LogError(ex, "Error running post-scan task");
+                    _logger.LogError(
+                        ex,
+                        "Error running post-scan task {TaskName} after {Elapsed}",
+                        taskName,
+                        Stopwatch.GetElapsedTime(taskStart));
                 }
 
                 numComplete++;
@@ -1528,6 +1552,10 @@ namespace Emby.Server.Implementations.Library
                 progress.Report(percent * 100);
             }
 
+            _logger.LogInformation(
+                "All post-scan tasks completed in {Elapsed}",
+                Stopwatch.GetElapsedTime(phaseStart));
+
             _persistenceService.UpdateInheritedValues();
 
             progress.Report(100);