| | | 1 | | using System.Diagnostics; |
| | | 2 | | using Elsa.Diagnostics.StructuredLogs.Contracts; |
| | | 3 | | using Elsa.Diagnostics.StructuredLogs.Models; |
| | | 4 | | using Elsa.Diagnostics.StructuredLogs.Persistence.Relational.Contracts; |
| | | 5 | | using Elsa.Diagnostics.StructuredLogs.Persistence.Relational.Options; |
| | | 6 | | using Elsa.Diagnostics.StructuredLogs.Persistence.Relational.Stores; |
| | | 7 | | using Microsoft.Extensions.Hosting; |
| | | 8 | | using Microsoft.Extensions.Options; |
| | | 9 | | |
| | | 10 | | namespace Elsa.Diagnostics.StructuredLogs.Persistence.Relational.Services; |
| | | 11 | | |
| | 1 | 12 | | public class StructuredLogWriteBuffer( |
| | 1 | 13 | | RelationalStructuredLogStore store, |
| | 1 | 14 | | IStructuredLogSourceRegistry sourceRegistry, |
| | 1 | 15 | | IOptions<RelationalStructuredLogOptions> options) : IStructuredLogStore, IStructuredLogWriteBuffer, IHostedService, |
| | | 16 | | { |
| | 1 | 17 | | private readonly object _lifecycleLock = new(); |
| | 1 | 18 | | private readonly Queue<StructuredLogEvent> _queue = new(); |
| | 1 | 19 | | private readonly SemaphoreSlim _signal = new(0); |
| | 1 | 20 | | private CancellationTokenSource _stopTokenSource = new(); |
| | | 21 | | private Task? _backgroundTask; |
| | | 22 | | private long _droppedWriteCount; |
| | | 23 | | private int _activeStartCount; |
| | | 24 | | private int _disposed; |
| | | 25 | | |
| | 0 | 26 | | public long DroppedWriteCount => Interlocked.Read(ref _droppedWriteCount); |
| | | 27 | | |
| | | 28 | | public ValueTask WriteAsync(StructuredLogEvent logEvent, CancellationToken cancellationToken = default) |
| | | 29 | | { |
| | 0 | 30 | | var shouldSignal = false; |
| | | 31 | | |
| | 0 | 32 | | lock (_queue) |
| | | 33 | | { |
| | 0 | 34 | | if (_queue.Count >= Math.Max(1, options.Value.WriteQueue.Capacity)) |
| | | 35 | | { |
| | 0 | 36 | | Interlocked.Increment(ref _droppedWriteCount); |
| | 0 | 37 | | return ValueTask.CompletedTask; |
| | | 38 | | } |
| | | 39 | | |
| | 0 | 40 | | shouldSignal = _queue.Count == 0; |
| | 0 | 41 | | _queue.Enqueue(logEvent); |
| | 0 | 42 | | } |
| | | 43 | | |
| | 0 | 44 | | sourceRegistry.MarkSeen(logEvent.SourceId, logEvent.ReceivedAt); |
| | | 45 | | |
| | 0 | 46 | | if (shouldSignal) |
| | 0 | 47 | | _signal.Release(); |
| | | 48 | | |
| | 0 | 49 | | return ValueTask.CompletedTask; |
| | 0 | 50 | | } |
| | | 51 | | |
| | | 52 | | public async ValueTask WriteManyAsync(IReadOnlyCollection<StructuredLogEvent> logEvents, CancellationToken cancellat |
| | | 53 | | { |
| | 0 | 54 | | foreach (var logEvent in logEvents) |
| | 0 | 55 | | await WriteAsync(logEvent, cancellationToken); |
| | 0 | 56 | | } |
| | | 57 | | |
| | | 58 | | public ValueTask<RecentStructuredLogsResult> QueryAsync(StructuredLogFilter filter, CancellationToken cancellationTo |
| | | 59 | | { |
| | 0 | 60 | | return store.QueryAsync(filter, cancellationToken); |
| | | 61 | | } |
| | | 62 | | |
| | | 63 | | public ValueTask<IReadOnlyCollection<StructuredLogSource>> ListSourcesAsync(CancellationToken cancellationToken = de |
| | | 64 | | { |
| | 0 | 65 | | return store.ListSourcesAsync(cancellationToken); |
| | | 66 | | } |
| | | 67 | | |
| | | 68 | | public Task StartAsync(CancellationToken cancellationToken) |
| | | 69 | | { |
| | 0 | 70 | | lock (_lifecycleLock) |
| | | 71 | | { |
| | 0 | 72 | | _activeStartCount++; |
| | | 73 | | |
| | 0 | 74 | | if (_backgroundTask is { IsCompleted: false }) |
| | 0 | 75 | | return Task.CompletedTask; |
| | | 76 | | |
| | 0 | 77 | | if (_stopTokenSource.IsCancellationRequested) |
| | | 78 | | { |
| | 0 | 79 | | _stopTokenSource.Dispose(); |
| | 0 | 80 | | _stopTokenSource = new(); |
| | | 81 | | } |
| | | 82 | | |
| | 0 | 83 | | var stopToken = _stopTokenSource.Token; |
| | 0 | 84 | | _backgroundTask = Task.Run(() => ProcessQueueAsync(stopToken), CancellationToken.None); |
| | 0 | 85 | | } |
| | | 86 | | |
| | 0 | 87 | | return Task.CompletedTask; |
| | 0 | 88 | | } |
| | | 89 | | |
| | | 90 | | public async Task StopAsync(CancellationToken cancellationToken) |
| | | 91 | | { |
| | | 92 | | Task? backgroundTask; |
| | | 93 | | CancellationTokenSource stopTokenSource; |
| | | 94 | | |
| | 0 | 95 | | lock (_lifecycleLock) |
| | | 96 | | { |
| | 0 | 97 | | if (_activeStartCount == 0) |
| | 0 | 98 | | return; |
| | | 99 | | |
| | 0 | 100 | | _activeStartCount--; |
| | | 101 | | |
| | 0 | 102 | | if (_activeStartCount > 0) |
| | 0 | 103 | | return; |
| | | 104 | | |
| | 0 | 105 | | backgroundTask = _backgroundTask; |
| | 0 | 106 | | stopTokenSource = _stopTokenSource; |
| | 0 | 107 | | } |
| | | 108 | | |
| | 0 | 109 | | await stopTokenSource.CancelAsync(); |
| | | 110 | | |
| | 0 | 111 | | if (backgroundTask != null) |
| | | 112 | | { |
| | | 113 | | try |
| | | 114 | | { |
| | 0 | 115 | | await backgroundTask.WaitAsync(cancellationToken); |
| | 0 | 116 | | } |
| | 0 | 117 | | catch (OperationCanceledException) when (cancellationToken.IsCancellationRequested || stopTokenSource.IsCanc |
| | | 118 | | { |
| | | 119 | | // Expected during shutdown; remaining queued writes are flushed below. |
| | 0 | 120 | | } |
| | | 121 | | } |
| | | 122 | | |
| | 0 | 123 | | using var timeoutTokenSource = CancellationTokenSource.CreateLinkedTokenSource(cancellationToken); |
| | 0 | 124 | | timeoutTokenSource.CancelAfter(options.Value.WriteQueue.ShutdownFlushTimeout); |
| | | 125 | | try |
| | | 126 | | { |
| | 0 | 127 | | await FlushAsync(timeoutTokenSource.Token); |
| | 0 | 128 | | } |
| | 0 | 129 | | catch (OperationCanceledException) when (timeoutTokenSource.IsCancellationRequested) |
| | | 130 | | { |
| | 0 | 131 | | CountPendingWritesAsDropped(); |
| | 0 | 132 | | } |
| | 0 | 133 | | } |
| | | 134 | | |
| | | 135 | | public async ValueTask FlushAsync(CancellationToken cancellationToken = default) |
| | | 136 | | { |
| | 0 | 137 | | while (true) |
| | | 138 | | { |
| | 1 | 139 | | var batch = DequeueBatch(); |
| | 1 | 140 | | if (batch.Count == 0) |
| | 1 | 141 | | return; |
| | | 142 | | |
| | | 143 | | try |
| | | 144 | | { |
| | 0 | 145 | | await store.WriteManyAsync(batch, cancellationToken); |
| | 0 | 146 | | } |
| | 0 | 147 | | catch |
| | | 148 | | { |
| | 0 | 149 | | Interlocked.Add(ref _droppedWriteCount, batch.Count); |
| | 0 | 150 | | throw; |
| | | 151 | | } |
| | 0 | 152 | | } |
| | 1 | 153 | | } |
| | | 154 | | |
| | | 155 | | public async ValueTask DisposeAsync() |
| | | 156 | | { |
| | 2 | 157 | | if (Interlocked.Exchange(ref _disposed, 1) == 1) |
| | 1 | 158 | | return; |
| | | 159 | | |
| | | 160 | | Task? backgroundTask; |
| | | 161 | | CancellationTokenSource stopTokenSource; |
| | | 162 | | |
| | 1 | 163 | | lock (_lifecycleLock) |
| | | 164 | | { |
| | 1 | 165 | | backgroundTask = _backgroundTask; |
| | 1 | 166 | | stopTokenSource = _stopTokenSource; |
| | 1 | 167 | | } |
| | | 168 | | |
| | 1 | 169 | | await stopTokenSource.CancelAsync(); |
| | 1 | 170 | | using var timeoutTokenSource = new CancellationTokenSource(options.Value.WriteQueue.ShutdownFlushTimeout); |
| | | 171 | | |
| | 1 | 172 | | if (backgroundTask != null) |
| | | 173 | | { |
| | | 174 | | try |
| | | 175 | | { |
| | 0 | 176 | | await backgroundTask.WaitAsync(timeoutTokenSource.Token); |
| | 0 | 177 | | } |
| | 0 | 178 | | catch (OperationCanceledException) when (timeoutTokenSource.IsCancellationRequested || stopTokenSource.IsCan |
| | | 179 | | { |
| | 0 | 180 | | CountPendingWritesAsDropped(); |
| | 0 | 181 | | } |
| | | 182 | | } |
| | | 183 | | |
| | | 184 | | try |
| | | 185 | | { |
| | 1 | 186 | | await FlushAsync(timeoutTokenSource.Token); |
| | 1 | 187 | | } |
| | 0 | 188 | | catch (OperationCanceledException) when (timeoutTokenSource.IsCancellationRequested) |
| | | 189 | | { |
| | 0 | 190 | | CountPendingWritesAsDropped(); |
| | 0 | 191 | | } |
| | | 192 | | |
| | 1 | 193 | | _signal.Dispose(); |
| | 1 | 194 | | stopTokenSource.Dispose(); |
| | 2 | 195 | | } |
| | | 196 | | |
| | | 197 | | private async Task ProcessQueueAsync(CancellationToken cancellationToken) |
| | | 198 | | { |
| | 0 | 199 | | while (!cancellationToken.IsCancellationRequested) |
| | | 200 | | { |
| | | 201 | | try |
| | | 202 | | { |
| | 0 | 203 | | await _signal.WaitAsync(options.Value.WriteQueue.FlushInterval, cancellationToken); |
| | | 204 | | |
| | 0 | 205 | | if (cancellationToken.IsCancellationRequested) |
| | 0 | 206 | | return; |
| | | 207 | | |
| | 0 | 208 | | await FlushAsync(); |
| | 0 | 209 | | } |
| | 0 | 210 | | catch (OperationCanceledException) when (cancellationToken.IsCancellationRequested) |
| | | 211 | | { |
| | 0 | 212 | | return; |
| | | 213 | | } |
| | 0 | 214 | | catch (ObjectDisposedException) when (cancellationToken.IsCancellationRequested) |
| | | 215 | | { |
| | 0 | 216 | | return; |
| | | 217 | | } |
| | 0 | 218 | | catch (Exception e) |
| | | 219 | | { |
| | 0 | 220 | | Trace.TraceError("Failed to flush structured log writes: {0}", e); |
| | | 221 | | |
| | 0 | 222 | | if (cancellationToken.IsCancellationRequested) |
| | 0 | 223 | | return; |
| | 0 | 224 | | } |
| | | 225 | | } |
| | 0 | 226 | | } |
| | | 227 | | |
| | | 228 | | private IReadOnlyCollection<StructuredLogEvent> DequeueBatch() |
| | | 229 | | { |
| | 1 | 230 | | var batchSize = Math.Max(1, options.Value.WriteQueue.BatchSize); |
| | 1 | 231 | | var batch = new List<StructuredLogEvent>(batchSize); |
| | | 232 | | |
| | 1 | 233 | | lock (_queue) |
| | | 234 | | { |
| | 1 | 235 | | while (_queue.Count > 0 && batch.Count < batchSize) |
| | 0 | 236 | | batch.Add(_queue.Dequeue()); |
| | 1 | 237 | | } |
| | | 238 | | |
| | 1 | 239 | | return batch; |
| | | 240 | | } |
| | | 241 | | |
| | | 242 | | private void CountPendingWritesAsDropped() |
| | | 243 | | { |
| | 0 | 244 | | lock (_queue) |
| | | 245 | | { |
| | 0 | 246 | | if (_queue.Count == 0) |
| | 0 | 247 | | return; |
| | | 248 | | |
| | 0 | 249 | | Interlocked.Add(ref _droppedWriteCount, _queue.Count); |
| | 0 | 250 | | _queue.Clear(); |
| | 0 | 251 | | } |
| | 0 | 252 | | } |
| | | 253 | | } |