| | | 1 | | using Microsoft.Extensions.Logging; |
| | | 2 | | using NexMediator.Abstractions.Context; |
| | | 3 | | using NexMediator.Abstractions.Interfaces; |
| | | 4 | | using System.Diagnostics; |
| | | 5 | | |
| | | 6 | | namespace NexMediator.Pipeline.Behaviors; |
| | | 7 | | |
| | | 8 | | /// <summary> |
| | | 9 | | /// Pipeline behavior that logs requests and responses, |
| | | 10 | | /// optionally including a correlation ID if available. |
| | | 11 | | /// </summary> |
| | | 12 | | /// <typeparam name="TRequest">The request type.</typeparam> |
| | | 13 | | /// <typeparam name="TResponse">The response type.</typeparam> |
| | | 14 | | public class LoggingBehavior<TRequest, TResponse> : INexPipelineBehavior<TRequest, TResponse> |
| | | 15 | | where TRequest : INexRequest<TResponse> |
| | | 16 | | { |
| | | 17 | | private readonly ILogger<LoggingBehavior<TRequest, TResponse>> _logger; |
| | | 18 | | private readonly ICorrelationContext? _correlation; |
| | | 19 | | |
| | | 20 | | /// <summary> |
| | | 21 | | /// Creates an instance of <see cref="LoggingBehavior{TRequest,TResponse}"/> |
| | | 22 | | /// without correlation support. |
| | | 23 | | /// </summary> |
| | | 24 | | /// <param name="logger">The logger instance from DI.</param> |
| | 6 | 25 | | public LoggingBehavior(ILogger<LoggingBehavior<TRequest, TResponse>> logger) |
| | 6 | 26 | | { |
| | 6 | 27 | | _logger = logger ?? throw new ArgumentNullException(nameof(logger)); |
| | 5 | 28 | | _correlation = null; |
| | 5 | 29 | | } |
| | | 30 | | |
| | | 31 | | /// <summary> |
| | | 32 | | /// Creates an instance of <see cref="LoggingBehavior{TRequest,TResponse}"/> |
| | | 33 | | /// with correlation support. |
| | | 34 | | /// </summary> |
| | | 35 | | /// <param name="logger">The logger instance from DI.</param> |
| | | 36 | | /// <param name="correlation">The correlation context providing an ID.</param> |
| | | 37 | | public LoggingBehavior( |
| | 6 | 38 | | ILogger<LoggingBehavior<TRequest, TResponse>> logger, ICorrelationContext correlation) : this(logger) |
| | 5 | 39 | | { |
| | 5 | 40 | | _correlation = correlation ?? throw new ArgumentNullException(nameof(correlation)); |
| | 5 | 41 | | } |
| | | 42 | | |
| | | 43 | | /// <inheritdoc/> |
| | | 44 | | public async Task<TResponse> Handle( |
| | | 45 | | TRequest request, |
| | | 46 | | RequestHandlerDelegate<TResponse> next, |
| | | 47 | | CancellationToken cancellationToken) |
| | 4 | 48 | | { |
| | 4 | 49 | | var requestName = typeof(TRequest).Name; |
| | 4 | 50 | | var correlationId = _correlation?.CorrelationId; |
| | 4 | 51 | | var stopwatch = Stopwatch.StartNew(); |
| | | 52 | | |
| | | 53 | | // Log start of request |
| | 4 | 54 | | if (!string.IsNullOrEmpty(correlationId)) |
| | 4 | 55 | | { |
| | 4 | 56 | | _logger.LogInformation( |
| | 4 | 57 | | "Handling {RequestType} started at {StartTime} (CorrelationId: {CorrelationId})", |
| | 4 | 58 | | requestName, |
| | 4 | 59 | | DateTime.UtcNow, |
| | 4 | 60 | | correlationId); |
| | 4 | 61 | | } |
| | | 62 | | else |
| | 0 | 63 | | { |
| | 0 | 64 | | _logger.LogInformation( |
| | 0 | 65 | | "Handling {RequestType} started at {StartTime}", |
| | 0 | 66 | | requestName, |
| | 0 | 67 | | DateTime.UtcNow); |
| | 0 | 68 | | } |
| | | 69 | | |
| | | 70 | | try |
| | 4 | 71 | | { |
| | 4 | 72 | | var response = await next(); |
| | 3 | 73 | | stopwatch.Stop(); |
| | | 74 | | |
| | | 75 | | // Log successful completion |
| | 3 | 76 | | if (!string.IsNullOrEmpty(correlationId)) |
| | 3 | 77 | | { |
| | 3 | 78 | | _logger.LogInformation( |
| | 3 | 79 | | "Handled {RequestType} completed in {ElapsedMilliseconds}ms (CorrelationId: {CorrelationId}) with re |
| | 3 | 80 | | requestName, |
| | 3 | 81 | | stopwatch.ElapsedMilliseconds, |
| | 3 | 82 | | correlationId, |
| | 3 | 83 | | response); |
| | 3 | 84 | | } |
| | | 85 | | else |
| | 0 | 86 | | { |
| | 0 | 87 | | _logger.LogInformation( |
| | 0 | 88 | | "Handled {RequestType} completed in {ElapsedMilliseconds}ms with response {@Response}", |
| | 0 | 89 | | requestName, |
| | 0 | 90 | | stopwatch.ElapsedMilliseconds, |
| | 0 | 91 | | response); |
| | 0 | 92 | | } |
| | | 93 | | |
| | 3 | 94 | | return response; |
| | | 95 | | } |
| | 1 | 96 | | catch (Exception ex) |
| | 1 | 97 | | { |
| | 1 | 98 | | stopwatch.Stop(); |
| | | 99 | | |
| | | 100 | | // Log error |
| | 1 | 101 | | if (!string.IsNullOrEmpty(correlationId)) |
| | 1 | 102 | | { |
| | 1 | 103 | | _logger.LogError( |
| | 1 | 104 | | ex, |
| | 1 | 105 | | "Error handling {RequestType} after {ElapsedMilliseconds}ms (CorrelationId: {CorrelationId}) {@Reque |
| | 1 | 106 | | requestName, |
| | 1 | 107 | | stopwatch.ElapsedMilliseconds, |
| | 1 | 108 | | correlationId, |
| | 1 | 109 | | request); |
| | 1 | 110 | | } |
| | | 111 | | else |
| | 0 | 112 | | { |
| | 0 | 113 | | _logger.LogError( |
| | 0 | 114 | | ex, |
| | 0 | 115 | | "Error handling {RequestType} after {ElapsedMilliseconds}ms {@Request}", |
| | 0 | 116 | | requestName, |
| | 0 | 117 | | stopwatch.ElapsedMilliseconds, |
| | 0 | 118 | | request); |
| | 0 | 119 | | } |
| | | 120 | | |
| | 1 | 121 | | throw; |
| | | 122 | | } |
| | 3 | 123 | | } |
| | | 124 | | } |