Log association elapsed time

Signed-off-by: Victor Chang <[email protected]>

Author: mocsharp

src/InformaticsGateway/Logging/Log.100.200.ScpService.cs

@@ -86,8 +86,8 @@ public static partial class Log
         [LoggerMessage(EventId = 208, Level = LogLevel.Warning, Message = "Aborted {source} with reason {reason}.")]
         public static partial void CStoreAbort(this ILogger logger, DicomAbortSource source, DicomAbortReason reason);
 
-        [LoggerMessage(EventId = 209, Level = LogLevel.Information, Message = "Association release request received.")]
-        public static partial void CStoreAssociationReleaseRequest(this ILogger logger);
+        [LoggerMessage(EventId = 209, Level = LogLevel.Information, Message = "Association release request received. Connection Time: {elapsedTime}.")]
+        public static partial void CStoreAssociationReleaseRequest(this ILogger logger, TimeSpan elapsedTime);
 
         [LoggerMessage(EventId = 210, Level = LogLevel.Information, Message = "Association received from {host}:{port}.")]
         public static partial void CStoreAssociationReceived(this ILogger logger, string host, int port);

src/InformaticsGateway/Services/Scp/ScpServiceInternal.cs

@@ -41,6 +41,7 @@ internal class ScpServiceInternal :
         private IApplicationEntityManager _associationDataProvider;
         private IDisposable _loggerScope;
         private Guid _associationId;
+        private DateTimeOffset? _associationReceived;
 
         public ScpServiceInternal(INetworkStream stream, Encoding fallbackEncoding, FellowOakDicom.Log.ILogger log, DicomServiceDependencies dicomServiceDependencies)
                 : base(stream, fallbackEncoding, log, dicomServiceDependencies)
@@ -106,13 +107,20 @@ public void OnReceiveAbort(DicomAbortSource source, DicomAbortReason reason)
         /// <returns></returns>
         public Task OnReceiveAssociationReleaseRequestAsync()
         {
-            _logger?.CStoreAssociationReleaseRequest();
+            var associationElapsed = TimeSpan.Zero;
+            if (_associationReceived.HasValue)
+            {
+                associationElapsed = DateTimeOffset.UtcNow.Subtract(_associationReceived.Value);
+            }
+
+            _logger?.CStoreAssociationReleaseRequest(associationElapsed);
             return SendAssociationReleaseResponseAsync();
         }
 
         public Task OnReceiveAssociationRequestAsync(DicomAssociation association)
         {
             Interlocked.Increment(ref ScpService.ActiveConnections);
+            _associationReceived = DateTimeOffset.UtcNow;
             _associationDataProvider = UserState as IApplicationEntityManager;
 
             if (_associationDataProvider is null)