| | | 1 | | // Licensed to the .NET Foundation under one or more agreements. |
| | | 2 | | // The .NET Foundation licenses this file to you under the MIT license. |
| | | 3 | | |
| | | 4 | | using System; |
| | | 5 | | using System.IO.Pipelines; |
| | | 6 | | using Microsoft.Extensions.Logging; |
| | | 7 | | |
| | | 8 | | namespace CoreWCF.Channels.Framing |
| | | 9 | | { |
| | | 10 | | internal static class FramingLoggingExtensions |
| | | 11 | | { |
| | | 12 | | // Convention is for paired events (eg Start/Stop) to have sequential numbers with the start even being even and |
| | | 13 | | // Paired events use event id's in the 1000-1999 range. Solo events start at 2000 |
| | | 14 | | #region PairedEvents |
| | 2 | 15 | | private static Action<ILogger, string, Exception> s_serverModeDecoderStartState = LoggerMessage.Define<string>( |
| | 2 | 16 | | LogLevel.Trace, |
| | 2 | 17 | | new EventId(1000, nameof(ServerModeDecoder) + "StartState"), |
| | 2 | 18 | | nameof(ServerModeDecoder) + ":Start state is {decoderState}"); |
| | | 19 | | |
| | 2 | 20 | | private static Action<ILogger, string, int, Exception> s_serverModeDecoderEndState = LoggerMessage.Define<string |
| | 2 | 21 | | LogLevel.Trace, |
| | 2 | 22 | | new EventId(1001, nameof(ServerModeDecoder) + "EndState"), |
| | 2 | 23 | | nameof(ServerModeDecoder) + ":End state is {decoderState} after reading {bytesRead} bytes"); |
| | | 24 | | |
| | 2 | 25 | | private static Action<ILogger, string, Exception> s_serverSessionDecoderStartState = LoggerMessage.Define<string |
| | 2 | 26 | | LogLevel.Trace, |
| | 2 | 27 | | new EventId(1002, nameof(ServerSessionDecoder) + "StartState"), |
| | 2 | 28 | | nameof(ServerSessionDecoder) + ":Start state is {decoderState}"); |
| | | 29 | | |
| | 2 | 30 | | private static Action<ILogger, string, int, Exception> s_serverSessionDecoderEndState = LoggerMessage.Define<str |
| | 2 | 31 | | LogLevel.Trace, |
| | 2 | 32 | | new EventId(1003, nameof(ServerSessionDecoder) + "EndState"), |
| | 2 | 33 | | nameof(ServerSessionDecoder) + ":End state is {decoderState} after reading {bytesRead} bytes"); |
| | | 34 | | |
| | 2 | 35 | | private static Action<ILogger, string, Exception> s_singletonMessageDecoderStartState = LoggerMessage.Define<str |
| | 2 | 36 | | LogLevel.Trace, |
| | 2 | 37 | | new EventId(1004, nameof(SingletonMessageDecoder) + "StartState"), |
| | 2 | 38 | | nameof(SingletonMessageDecoder) + ":Start state is {decoderState}"); |
| | | 39 | | |
| | 2 | 40 | | private static Action<ILogger, string, int, Exception> s_singletonMessageDecoderEndState = LoggerMessage.Define< |
| | 2 | 41 | | LogLevel.Trace, |
| | 2 | 42 | | new EventId(1005, nameof(SingletonMessageDecoder) + "EndState"), |
| | 2 | 43 | | nameof(SingletonMessageDecoder) + ":End state is {decoderState} after reading {bytesRead} bytes"); |
| | | 44 | | |
| | 2 | 45 | | private static Action<ILogger, string, Exception> s_serverSingletonDecoderStartState = LoggerMessage.Define<stri |
| | 2 | 46 | | LogLevel.Trace, |
| | 2 | 47 | | new EventId(1006, nameof(ServerSingletonDecoder) + "StartState"), |
| | 2 | 48 | | nameof(ServerSingletonDecoder) + ":Start state is {decoderState}"); |
| | | 49 | | |
| | 2 | 50 | | private static Action<ILogger, string, int, Exception> s_serverSingletonDecoderEndState = LoggerMessage.Define<s |
| | 2 | 51 | | LogLevel.Trace, |
| | 2 | 52 | | new EventId(1007, nameof(ServerSingletonDecoder) + "EndState"), |
| | 2 | 53 | | nameof(ServerSingletonDecoder) + ":End state is {decoderState} after reading {bytesRead} bytes"); |
| | | 54 | | |
| | 2 | 55 | | private static Action<ILogger, string, Exception> s_serverSingletonSizedDecoderStartState = LoggerMessage.Define |
| | 2 | 56 | | LogLevel.Trace, |
| | 2 | 57 | | new EventId(1008, nameof(ServerSingletonSizedDecoder) + "StartState"), |
| | 2 | 58 | | nameof(ServerSingletonSizedDecoder) + ":Start state is {decoderState}"); |
| | | 59 | | |
| | 2 | 60 | | private static Action<ILogger, string, int, Exception> s_serverSingletonSizedDecoderEndState = LoggerMessage.Def |
| | 2 | 61 | | LogLevel.Trace, |
| | 2 | 62 | | new EventId(1009, nameof(ServerSingletonSizedDecoder) + "EndState"), |
| | 2 | 63 | | nameof(ServerSingletonSizedDecoder) + ":End state is {decoderState} after reading {bytesRead} bytes"); |
| | | 64 | | |
| | 2 | 65 | | private static Action<ILogger, string, Exception> s_stringDecoderStartState = LoggerMessage.Define<string>( |
| | 2 | 66 | | LogLevel.Trace, |
| | 2 | 67 | | new EventId(1010, nameof(StringDecoder) + "StartState"), |
| | 2 | 68 | | nameof(StringDecoder) + ":Start state is {decoderState}"); |
| | | 69 | | |
| | 2 | 70 | | private static Action<ILogger, string, int, Exception> s_stringDecoderEndState = LoggerMessage.Define<string, in |
| | 2 | 71 | | LogLevel.Trace, |
| | 2 | 72 | | new EventId(1011, nameof(StringDecoder) + "EndState"), |
| | 2 | 73 | | nameof(StringDecoder) + ":End state is {decoderState} after reading {bytesRead} bytes"); |
| | | 74 | | |
| | 2 | 75 | | private static Action<ILogger, string, Exception> s_startStreamUpgradeAccept = LoggerMessage.Define<string>( |
| | 2 | 76 | | LogLevel.Trace, |
| | 2 | 77 | | new EventId(1012, nameof(StreamUpgradeAcceptor) + "StartUpgrade"), |
| | 2 | 78 | | "{upgradeAcceptorType} start stream upgrade"); |
| | | 79 | | |
| | 2 | 80 | | private static Action<ILogger, string, Exception> s_completeStreamUpgradeAccept = LoggerMessage.Define<string>( |
| | 2 | 81 | | LogLevel.Trace, |
| | 2 | 82 | | new EventId(1013, nameof(StreamUpgradeAcceptor) + "CompleteUpgrade"), |
| | 2 | 83 | | "{upgradeAcceptorType} stream upgrade completed"); |
| | | 84 | | |
| | 2 | 85 | | private static Action<ILogger, Exception> s_startPendingReadOnIdleSocket = LoggerMessage.Define( |
| | 2 | 86 | | LogLevel.Trace, |
| | 2 | 87 | | new EventId(1014, "StartPendingReadIdleSocket"), |
| | 2 | 88 | | "Starting pending read on idle socket"); |
| | | 89 | | |
| | 2 | 90 | | private static Action<ILogger, bool, bool, long, Exception> s_endPendingReadOnIdleSocket = LoggerMessage.Define< |
| | 2 | 91 | | LogLevel.Trace, |
| | 2 | 92 | | new EventId(1014, "EndPendingReadIdleSocket"), |
| | 2 | 93 | | "Pending read on idle socket completed, IsCompleted: {isCompleted}, IsCanceled: {isCanceled}, bytes received |
| | | 94 | | |
| | | 95 | | #endregion // PairedEvents |
| | | 96 | | |
| | 2 | 97 | | private static Action<ILogger, int, int, int, Exception> s_decodingInt = LoggerMessage.Define<int, int, int>( |
| | 2 | 98 | | LogLevel.Trace, |
| | 2 | 99 | | new EventId(1050, nameof(IntDecoder) + "DecodingValue"), |
| | 2 | 100 | | nameof(IntDecoder) + ":Decoding Int with next {next} at index {index}, with total so far {value}"); |
| | | 101 | | |
| | 2 | 102 | | private static Action<ILogger, string, Exception> s_stringDecoded = LoggerMessage.Define<string>( |
| | 2 | 103 | | LogLevel.Trace, |
| | 2 | 104 | | new EventId(1051, nameof(StringDecoder) + "DecodedString"), |
| | 2 | 105 | | nameof(StringDecoder) + ":String decoded: \"{decodedString}\""); |
| | | 106 | | |
| | 2 | 107 | | private static Action<ILogger, Exception> s_unwrappingRawStream = LoggerMessage.Define( |
| | 2 | 108 | | LogLevel.Trace, |
| | 2 | 109 | | new EventId(1052, "UnwrappingRawStream"), |
| | 2 | 110 | | "Unwrapping raw stream"); |
| | | 111 | | |
| | 2 | 112 | | private static Action<ILogger, Exception> s_connectionPoolFull = LoggerMessage.Define( |
| | 2 | 113 | | LogLevel.Trace, |
| | 2 | 114 | | new EventId(1053, "RawSocketClose"), |
| | 2 | 115 | | "Connection pool full, closing raw socket"); |
| | | 116 | | |
| | 2 | 117 | | private static Action<ILogger, Exception> s_failureInConnectionReuse = LoggerMessage.Define( |
| | 2 | 118 | | LogLevel.Trace, |
| | 2 | 119 | | new EventId(1054, "ConnectionReuseFailure"), |
| | 2 | 120 | | "Failed to reuse connection"); |
| | | 121 | | |
| | 2 | 122 | | private static Action<ILogger, Exception> s_idleConnectionClosed = LoggerMessage.Define( |
| | 2 | 123 | | LogLevel.Trace, |
| | 2 | 124 | | new EventId(1055, "IdleConnectionClosed"), |
| | 2 | 125 | | "Idle connection closed when waiting for reuse"); |
| | | 126 | | |
| | 2 | 127 | | private static Action<ILogger, Exception> s_receivedNullMessage = LoggerMessage.Define( |
| | 2 | 128 | | LogLevel.Debug, |
| | 2 | 129 | | new EventId(1056, "ReceivedNullMessage"), |
| | 2 | 130 | | "Null message received by transport"); |
| | | 131 | | |
| | 2 | 132 | | private static Action<ILogger, string, string, string, Exception> s_receivedMessage = LoggerMessage.Define<strin |
| | 2 | 133 | | LogLevel.Trace, |
| | 2 | 134 | | new EventId(1057, "ReceivedMessage"), |
| | 2 | 135 | | "Received message with Id \"{id}\" with action \"{action}\" to \"{toAddress}\""); |
| | | 136 | | |
| | 2 | 137 | | private static Action<ILogger, string, string, string, string, Exception> s_sendMessage = LoggerMessage.Define<s |
| | 2 | 138 | | LogLevel.Trace, |
| | 2 | 139 | | new EventId(1058, "SendMessage"), |
| | 2 | 140 | | "Sending message with RelatesTo Id \"{relatesToId}\" Id \"{id}\" with action \"{action}\" to \"{toAddress}\" |
| | | 141 | | |
| | 2 | 142 | | private static Action<ILogger, string, int, string, Exception> s_connectionLogging = LoggerMessage.Define<string |
| | 2 | 143 | | LogLevel.Trace, |
| | 2 | 144 | | new EventId(1059, "ConnectionLogging"), |
| | 2 | 145 | | "{method}[{byteCount}]{data}"); |
| | | 146 | | |
| | | 147 | | public static void LogStartState(this ILogger logger, ServerModeDecoder serverModeDecoder) |
| | | 148 | | { |
| | 575 | 149 | | s_serverModeDecoderStartState(logger, serverModeDecoder.CurrentState.ToString(), null); |
| | 575 | 150 | | } |
| | | 151 | | |
| | | 152 | | public static void LogEndState(this ILogger logger, ServerModeDecoder serverModeDecoder, int bytesRead) |
| | | 153 | | { |
| | 575 | 154 | | s_serverModeDecoderEndState(logger, serverModeDecoder.CurrentState.ToString(), bytesRead, null); |
| | 575 | 155 | | } |
| | | 156 | | |
| | | 157 | | public static void LogStartState(this ILogger logger, ServerSessionDecoder serverSessionDecoder) |
| | | 158 | | { |
| | 1123 | 159 | | s_serverSessionDecoderStartState(logger, serverSessionDecoder.CurrentState.ToString(), null); |
| | 1123 | 160 | | } |
| | | 161 | | |
| | | 162 | | public static void LogEndState(this ILogger logger, ServerSessionDecoder serverSessionDecoder, int bytesRead) |
| | | 163 | | { |
| | 1123 | 164 | | s_serverSessionDecoderEndState(logger, serverSessionDecoder.CurrentState.ToString(), bytesRead, null); |
| | 1123 | 165 | | } |
| | | 166 | | |
| | | 167 | | public static void LogStartState(this ILogger logger, SingletonMessageDecoder singletonMessageDecoder) |
| | | 168 | | { |
| | 3875 | 169 | | s_singletonMessageDecoderStartState(logger, singletonMessageDecoder.CurrentState.ToString(), null); |
| | 3875 | 170 | | } |
| | | 171 | | |
| | | 172 | | public static void LogEndState(this ILogger logger, SingletonMessageDecoder singletonMessageDecoder, int bytesRe |
| | | 173 | | { |
| | 3875 | 174 | | s_singletonMessageDecoderEndState(logger, singletonMessageDecoder.CurrentState.ToString(), bytesRead, null); |
| | 3875 | 175 | | } |
| | | 176 | | |
| | | 177 | | public static void LogStartState(this ILogger logger, ServerSingletonDecoder serverSingletonDecoder) |
| | | 178 | | { |
| | 516 | 179 | | s_serverSingletonDecoderStartState(logger, serverSingletonDecoder.CurrentState.ToString(), null); |
| | 516 | 180 | | } |
| | | 181 | | |
| | | 182 | | public static void LogEndState(this ILogger logger, ServerSingletonDecoder serverSingletonDecoder, int bytesRead |
| | | 183 | | { |
| | 516 | 184 | | s_serverSingletonDecoderEndState(logger, serverSingletonDecoder.CurrentState.ToString(), bytesRead, null); |
| | 516 | 185 | | } |
| | | 186 | | |
| | | 187 | | public static void LogStartState(this ILogger logger, ServerSingletonSizedDecoder serverSingletonSizedDecoder) |
| | | 188 | | { |
| | 0 | 189 | | s_serverSingletonSizedDecoderStartState(logger, serverSingletonSizedDecoder.CurrentState.ToString(), null); |
| | 0 | 190 | | } |
| | | 191 | | |
| | | 192 | | public static void LogEndState(this ILogger logger, ServerSingletonSizedDecoder serverSingletonSizedDecoder, int |
| | | 193 | | { |
| | 0 | 194 | | s_serverSingletonSizedDecoderEndState(logger, serverSingletonSizedDecoder.CurrentState.ToString(), bytesRead |
| | 0 | 195 | | } |
| | | 196 | | |
| | | 197 | | public static void DecodingInt(this ILogger logger, int next, int index, int value) |
| | | 198 | | { |
| | 1102 | 199 | | s_decodingInt(logger, next, index, value, null); |
| | 1102 | 200 | | } |
| | | 201 | | |
| | | 202 | | public static void LogStartState(this ILogger logger, StringDecoder stringDecoder) |
| | | 203 | | { |
| | 278 | 204 | | s_stringDecoderStartState(logger, stringDecoder.CurrentState.ToString(), null); |
| | 278 | 205 | | } |
| | | 206 | | |
| | | 207 | | public static void LogEndState(this ILogger logger, StringDecoder stringDecoder, int bytesRead) |
| | | 208 | | { |
| | 278 | 209 | | s_stringDecoderEndState(logger, stringDecoder.CurrentState.ToString(), bytesRead, null); |
| | 278 | 210 | | } |
| | | 211 | | |
| | | 212 | | public static void StringDecoded(this ILogger logger, string decodedString) |
| | | 213 | | { |
| | 139 | 214 | | s_stringDecoded(logger, decodedString, null); |
| | 139 | 215 | | } |
| | | 216 | | |
| | | 217 | | public static void StartStreamUpgradeAccept(this ILogger logger, StreamUpgradeAcceptor upgradeAcceptor) |
| | | 218 | | { |
| | 7 | 219 | | s_startStreamUpgradeAccept(logger, upgradeAcceptor.GetType().Name, null); |
| | 7 | 220 | | } |
| | | 221 | | |
| | | 222 | | public static void CompleteStreamUpgradeAccept(this ILogger logger, StreamUpgradeAcceptor upgradeAcceptor) |
| | | 223 | | { |
| | 7 | 224 | | s_completeStreamUpgradeAccept(logger, upgradeAcceptor.GetType().Name, null); |
| | 7 | 225 | | } |
| | | 226 | | |
| | | 227 | | public static void UnwrappingRawStream(this ILogger logger) |
| | | 228 | | { |
| | 7 | 229 | | s_unwrappingRawStream(logger, null); |
| | 7 | 230 | | } |
| | | 231 | | |
| | | 232 | | public static void ConnectionPoolFull(this ILogger logger) |
| | | 233 | | { |
| | 0 | 234 | | s_connectionPoolFull(logger, null); |
| | 0 | 235 | | } |
| | | 236 | | |
| | | 237 | | public static void StartPendingReadOnIdleSocket(this ILogger logger) |
| | | 238 | | { |
| | 105 | 239 | | s_startPendingReadOnIdleSocket(logger, null); |
| | 105 | 240 | | } |
| | | 241 | | |
| | | 242 | | public static void EndPendingReadOnIdleSocket(this ILogger logger, ReadResult readResult) |
| | | 243 | | { |
| | 37 | 244 | | s_endPendingReadOnIdleSocket(logger, readResult.IsCompleted, readResult.IsCanceled, readResult.Buffer.Length |
| | 37 | 245 | | } |
| | | 246 | | |
| | | 247 | | public static void FailureInConnectionReuse(this ILogger logger, Exception e) |
| | | 248 | | { |
| | 1 | 249 | | s_failureInConnectionReuse(logger, e); |
| | 1 | 250 | | } |
| | | 251 | | |
| | | 252 | | public static void IdleConnectionClosed(this ILogger logger) |
| | | 253 | | { |
| | 68 | 254 | | s_idleConnectionClosed(logger, null); |
| | 68 | 255 | | } |
| | | 256 | | |
| | | 257 | | public static void ReceivedMessage(this ILogger logger, Message message) |
| | | 258 | | { |
| | 133 | 259 | | if (message == null) |
| | 57 | 260 | | s_receivedNullMessage(logger, null); |
| | | 261 | | else |
| | 76 | 262 | | s_receivedMessage(logger, message.Headers.MessageId?.ToString(), message.Headers.Action?.ToString(), mes |
| | 76 | 263 | | } |
| | | 264 | | |
| | | 265 | | public static void SendMessage(this ILogger logger, Message message) |
| | | 266 | | { |
| | 76 | 267 | | s_sendMessage(logger, message.Headers.RelatesTo?.ToString(), message.Headers.MessageId?.ToString(), message. |
| | 76 | 268 | | } |
| | | 269 | | |
| | | 270 | | public static void LogBytes(this ILogger logger, string method, int count, string data) |
| | | 271 | | { |
| | 0 | 272 | | s_connectionLogging(logger, method, count, data, null); |
| | 0 | 273 | | } |
| | | 274 | | } |
| | | 275 | | } |