TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.684, 4056025053273205, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":7,"MessageType":"TestSession.Message","Payload":{"MessageLevel":0,"Message":"[xUnit.net 00:00:06.96]       System.TimeoutException : The operation has timed out."}}
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.685, 4056025053393567, vstest.console.dll, TestRunRequest:SendTestRunMessage: Starting.
TpTrace Verbose: 0 : 91, 4, 2023/01/26, 15:35:14.685, 4056025053545604, vstest.console.dll, MulticastDelegateUtilities.SafeInvoke: InternalTestLoggerEvents.SendTestRunMessage: Invoking callback 1/2 for Spekt.TestLogger.Core.TestRunBuilder., took 0 ms.
TpTrace Verbose: 0 : 91, 4, 2023/01/26, 15:35:14.685, 4056025053647144, vstest.console.dll, MulticastDelegateUtilities.SafeInvoke: InternalTestLoggerEvents.SendTestRunMessage: Invoking callback 2/2 for Microsoft.VisualStudio.TestPlatform.CommandLine.Internal.ConsoleLogger., took 0 ms.
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.686, 4056025054588907, vstest.console.dll, MulticastDelegateUtilities.SafeInvoke: TestRun.LogMessages: Invoking callback 1/1 for Microsoft.VisualStudio.TestPlatform.CommandLine.TestRunResultAggregator., took 0 ms.
TpTrace Information: 0 : 91, 9, 2023/01/26, 15:35:14.686, 4056025054664306, vstest.console.dll, TestRunRequest:SendTestRunMessage: Completed.
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.686, 4056025054736442, vstest.console.dll, MulticastDelegateUtilities.SafeInvoke: LengthPrefixCommunicationChannel: MessageReceived: Invoking callback 1/1 for Microsoft.VisualStudio.TestPlatform.CommunicationUtilities.TestRequestSender+<>c__DisplayClass31_0., took 1 ms.
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.686, 4056025054806864, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:48222 localEndPoint: 127.0.0.1:35463
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.690, 4056025059011141, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: NotifyDataAvailable remoteEndPoint: 127.0.0.1:48222 localEndPoint: 127.0.0.1:35463
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.690, 4056025059150184, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":7,"MessageType":"TestSession.Message","Payload":{"MessageLevel":0,"Message":"[xUnit.net 00:00:06.97]       Stack Trace:"}}
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.690, 4056025059282647, vstest.console.dll, TestRunRequest:SendTestRunMessage: Starting.
TpTrace Verbose: 0 : 91, 4, 2023/01/26, 15:35:14.691, 4056025059432888, vstest.console.dll, MulticastDelegateUtilities.SafeInvoke: InternalTestLoggerEvents.SendTestRunMessage: Invoking callback 1/2 for Spekt.TestLogger.Core.TestRunBuilder., took 0 ms.
TpTrace Verbose: 0 : 91, 4, 2023/01/26, 15:35:14.691, 4056025059544967, vstest.console.dll, MulticastDelegateUtilities.SafeInvoke: InternalTestLoggerEvents.SendTestRunMessage: Invoking callback 2/2 for Microsoft.VisualStudio.TestPlatform.CommandLine.Internal.ConsoleLogger., took 0 ms.
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.691, 4056025059656853, vstest.console.dll, MulticastDelegateUtilities.SafeInvoke: TestRun.LogMessages: Invoking callback 1/1 for Microsoft.VisualStudio.TestPlatform.CommandLine.TestRunResultAggregator., took 0 ms.
TpTrace Information: 0 : 91, 9, 2023/01/26, 15:35:14.691, 4056025059727505, vstest.console.dll, TestRunRequest:SendTestRunMessage: Completed.
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.691, 4056025059798169, vstest.console.dll, MulticastDelegateUtilities.SafeInvoke: LengthPrefixCommunicationChannel: MessageReceived: Invoking callback 1/1 for Microsoft.VisualStudio.TestPlatform.CommunicationUtilities.TestRequestSender+<>c__DisplayClass31_0., took 0 ms.
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.691, 4056025059868552, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:48222 localEndPoint: 127.0.0.1:35463
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.692, 4056025060755058, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: NotifyDataAvailable remoteEndPoint: 127.0.0.1:48222 localEndPoint: 127.0.0.1:35463
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.692, 4056025060889157, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":7,"MessageType":"TestSession.Message","Payload":{"MessageLevel":0,"Message":"[xUnit.net 00:00:06.97]         /run/TestSuite/Timeout.cs(42,0): at TestSuite.TaskWithTimeout.Run(Int32 timeout, Action action, String exceptionMessage)"}}
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.692, 4056025060998542, vstest.console.dll, TestRunRequest:SendTestRunMessage: Starting.
TpTrace Verbose: 0 : 91, 4, 2023/01/26, 15:35:14.692, 4056025061144650, vstest.console.dll, MulticastDelegateUtilities.SafeInvoke: InternalTestLoggerEvents.SendTestRunMessage: Invoking callback 1/2 for Spekt.TestLogger.Core.TestRunBuilder., took 0 ms.
TpTrace Verbose: 0 : 91, 4, 2023/01/26, 15:35:14.692, 4056025061229618, vstest.console.dll, MulticastDelegateUtilities.SafeInvoke: InternalTestLoggerEvents.SendTestRunMessage: Invoking callback 2/2 for Microsoft.VisualStudio.TestPlatform.CommandLine.Internal.ConsoleLogger., took 0 ms.
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.692, 4056025061325787, vstest.console.dll, MulticastDelegateUtilities.SafeInvoke: TestRun.LogMessages: Invoking callback 1/1 for Microsoft.VisualStudio.TestPlatform.CommandLine.TestRunResultAggregator., took 0 ms.
TpTrace Information: 0 : 91, 9, 2023/01/26, 15:35:14.693, 4056025061395400, vstest.console.dll, TestRunRequest:SendTestRunMessage: Completed.
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.693, 4056025061466942, vstest.console.dll, MulticastDelegateUtilities.SafeInvoke: LengthPrefixCommunicationChannel: MessageReceived: Invoking callback 1/1 for Microsoft.VisualStudio.TestPlatform.CommunicationUtilities.TestRequestSender+<>c__DisplayClass31_0., took 0 ms.
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.693, 4056025061546846, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:48222 localEndPoint: 127.0.0.1:35463
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.696, 4056025065165271, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: NotifyDataAvailable remoteEndPoint: 127.0.0.1:48222 localEndPoint: 127.0.0.1:35463
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.696, 4056025065315170, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":7,"MessageType":"TestSession.Message","Payload":{"MessageLevel":0,"Message":"[xUnit.net 00:00:06.97]         /run/TestSuite/TestGameHard.cs(25,0): at TestSuite.Hard.TestGameHard.Hard_Game_Adventure_Several_Check(String input)"}}
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.697, 4056025065429488, vstest.console.dll, TestRunRequest:SendTestRunMessage: Starting.
TpTrace Verbose: 0 : 91, 4, 2023/01/26, 15:35:14.697, 4056025065573442, vstest.console.dll, MulticastDelegateUtilities.SafeInvoke: InternalTestLoggerEvents.SendTestRunMessage: Invoking callback 1/2 for Spekt.TestLogger.Core.TestRunBuilder., took 0 ms.
TpTrace Verbose: 0 : 91, 4, 2023/01/26, 15:35:14.697, 4056025065659172, vstest.console.dll, MulticastDelegateUtilities.SafeInvoke: InternalTestLoggerEvents.SendTestRunMessage: Invoking callback 2/2 for Microsoft.VisualStudio.TestPlatform.CommandLine.Internal.ConsoleLogger., took 0 ms.
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.697, 4056025065759431, vstest.console.dll, MulticastDelegateUtilities.SafeInvoke: TestRun.LogMessages: Invoking callback 1/1 for Microsoft.VisualStudio.TestPlatform.CommandLine.TestRunResultAggregator., took 0 ms.
TpTrace Information: 0 : 91, 9, 2023/01/26, 15:35:14.697, 4056025065829746, vstest.console.dll, TestRunRequest:SendTestRunMessage: Completed.
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.697, 4056025065900966, vstest.console.dll, MulticastDelegateUtilities.SafeInvoke: LengthPrefixCommunicationChannel: MessageReceived: Invoking callback 1/1 for Microsoft.VisualStudio.TestPlatform.CommunicationUtilities.TestRequestSender+<>c__DisplayClass31_0., took 0 ms.
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.697, 4056025065970679, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:48222 localEndPoint: 127.0.0.1:35463
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.697, 4056025066046637, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: NotifyDataAvailable remoteEndPoint: 127.0.0.1:48222 localEndPoint: 127.0.0.1:35463
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.697, 4056025066167518, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":7,"MessageType":"TestSession.Message","Payload":{"MessageLevel":0,"Message":"[xUnit.net 00:00:06.97]            at System.Threading.Tasks.Task.<>c.<ThrowAsync>b__128_0(Object state)"}}
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.697, 4056025066315057, vstest.console.dll, TestRunRequest:SendTestRunMessage: Starting.
TpTrace Verbose: 0 : 91, 4, 2023/01/26, 15:35:14.698, 4056025066455122, vstest.console.dll, MulticastDelegateUtilities.SafeInvoke: InternalTestLoggerEvents.SendTestRunMessage: Invoking callback 1/2 for Spekt.TestLogger.Core.TestRunBuilder., took 0 ms.
TpTrace Verbose: 0 : 91, 4, 2023/01/26, 15:35:14.698, 4056025066560248, vstest.console.dll, MulticastDelegateUtilities.SafeInvoke: InternalTestLoggerEvents.SendTestRunMessage: Invoking callback 2/2 for Microsoft.VisualStudio.TestPlatform.CommandLine.Internal.ConsoleLogger., took 0 ms.
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.698, 4056025066658256, vstest.console.dll, MulticastDelegateUtilities.SafeInvoke: TestRun.LogMessages: Invoking callback 1/1 for Microsoft.VisualStudio.TestPlatform.CommandLine.TestRunResultAggregator., took 0 ms.
TpTrace Information: 0 : 91, 9, 2023/01/26, 15:35:14.698, 4056025066739855, vstest.console.dll, TestRunRequest:SendTestRunMessage: Completed.
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.698, 4056025066829739, vstest.console.dll, MulticastDelegateUtilities.SafeInvoke: LengthPrefixCommunicationChannel: MessageReceived: Invoking callback 1/1 for Microsoft.VisualStudio.TestPlatform.CommunicationUtilities.TestRequestSender+<>c__DisplayClass31_0., took 0 ms.
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.698, 4056025066903909, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:48222 localEndPoint: 127.0.0.1:35463
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.850, 4056025219242202, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: NotifyDataAvailable remoteEndPoint: 127.0.0.1:48222 localEndPoint: 127.0.0.1:35463
TpTrace Error: 0 : 91, 9, 2023/01/26, 15:35:14.852, 4056025221142763, vstest.console.dll, Socket: Message loop: failed to receive message due to socket error System.IO.EndOfStreamException: Unable to read beyond the end of the stream.
   at System.IO.BinaryReader.Read7BitEncodedInt()
   at System.IO.BinaryReader.ReadString()
   at Microsoft.VisualStudio.TestPlatform.CommunicationUtilities.LengthPrefixCommunicationChannel.NotifyDataAvailable()
   at Microsoft.VisualStudio.TestPlatform.CommunicationUtilities.TcpClientExtensions.MessageLoopAsync(TcpClient client, ICommunicationChannel channel, Action`1 errorHandler, CancellationToken cancellationToken), remoteEndPoint: 127.0.0.1:48222 localEndPoint: 127.0.0.1:35463
TpTrace Information: 0 : 91, 9, 2023/01/26, 15:35:14.852, 4056025221347880, vstest.console.dll, SocketServer.PrivateStop: Stopping server endPoint: 127.0.0.1:35463 error: System.IO.EndOfStreamException: Unable to read beyond the end of the stream.
   at System.IO.BinaryReader.Read7BitEncodedInt()
   at System.IO.BinaryReader.ReadString()
   at Microsoft.VisualStudio.TestPlatform.CommunicationUtilities.LengthPrefixCommunicationChannel.NotifyDataAvailable()
   at Microsoft.VisualStudio.TestPlatform.CommunicationUtilities.TcpClientExtensions.MessageLoopAsync(TcpClient client, ICommunicationChannel channel, Action`1 errorHandler, CancellationToken cancellationToken)
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.859, 4056025227852109, vstest.console.dll, LengthPrefixCommunicationChannel.Dispose: Dispose reader and writer.
TpTrace Information: 0 : 91, 9, 2023/01/26, 15:35:14.859, 4056025228105625, vstest.console.dll, SocketServer.Stop: Raise disconnected event endPoint: 127.0.0.1:35463 error: System.IO.EndOfStreamException: Unable to read beyond the end of the stream.
   at System.IO.BinaryReader.Read7BitEncodedInt()
   at System.IO.BinaryReader.ReadString()
   at Microsoft.VisualStudio.TestPlatform.CommunicationUtilities.LengthPrefixCommunicationChannel.NotifyDataAvailable()
   at Microsoft.VisualStudio.TestPlatform.CommunicationUtilities.TcpClientExtensions.MessageLoopAsync(TcpClient client, ICommunicationChannel channel, Action`1 errorHandler, CancellationToken cancellationToken)
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.859, 4056025228309149, vstest.console.dll, TestRequestSender: OnTestRunAbort: Set operation complete.
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.860, 4056025228398935, vstest.console.dll, TestRequestSender.SetOperationComplete: Setting operation complete.
TpTrace Information: 0 : 91, 9, 2023/01/26, 15:35:14.860, 4056025228493111, vstest.console.dll, SocketServer.Stop: Stop server endPoint: 127.0.0.1:35463
TpTrace Verbose: 0 : 91, 9, 2023/01/26, 15:35:14.860, 4056025228649942, vstest.console.dll, TestRequestSender.GetAbortErrorMessage: Exception: System.IO.EndOfStreamException: Unable to read beyond the end of the stream.
   at System.IO.BinaryReader.Read7BitEncodedInt()
   at System.IO.BinaryReader.ReadString()
   at Microsoft.VisualStudio.TestPlatform.CommunicationUtilities.LengthPrefixCommunicationChannel.NotifyDataAvailable()
   at Microsoft.VisualStudio.TestPlatform.CommunicationUtilities.TcpClientExtensions.MessageLoopAsync(TcpClient client, ICommunicationChannel channel, Action`1 errorHandler, CancellationToken cancellationToken)