vstest: .NET Core 2.1 RC1 test runner hangs after tests complete but before results are reported
Description
In both using the Test Explorer in Visual Studio 2017 15.7.1 and on the command-line using dotnet test with .NET Core 2.1.300 RC1, I’m experiencing an issue where the vstest process appears to be hanging indefinitely between the tests all being run and the test results being reported to the console.
Adding the -- RunConfiguration.TestSessionTimeout=N argument breaks the test runner out of the indefinite hang, but causes the test run to fail due to the test run being aborted.
I can’t yet provide a definite and simple repro solution as this is a part of a larger closed-source internal Line-of-Business application, but it seems to only be affecting tests that are using Selenium to run UI tests. Simple unit test runs are unaffected, and integration/functional tests that self-host the application but perform straight-forward HTTP calls to the self-hosted application are also unaffected by the hang.
With “Just My Code” disabled in Visual Studio with the debugger attached, the copied stack trace appears to be:
System.Private.CoreLib.dll!System.Threading.ManualResetEventSlim.Wait(int millisecondsTimeout, System.Threading.CancellationToken cancellationToken) Line 635 C#
System.Private.CoreLib.dll!System.Threading.Tasks.Task.SpinThenBlockingWait(int millisecondsTimeout, System.Threading.CancellationToken cancellationToken) Line 2978 C#
System.Private.CoreLib.dll!System.Threading.Tasks.Task.InternalWaitCore(int millisecondsTimeout, System.Threading.CancellationToken cancellationToken) Line 2917 C#
System.Private.CoreLib.dll!System.Threading.Tasks.Task.Wait(int millisecondsTimeout, System.Threading.CancellationToken cancellationToken) Line 2818 C#
System.Private.CoreLib.dll!System.Threading.Tasks.Task.WaitAnyCore(System.Threading.Tasks.Task[] tasks, int millisecondsTimeout, System.Threading.CancellationToken cancellationToken) Line 5061 C#
testhost.dll!Microsoft.VisualStudio.TestPlatform.TestHost.DefaultEngineInvoker.Invoke(System.Collections.Generic.IDictionary<string, string> argsDictionary) Unknown
> testhost.dll!Microsoft.VisualStudio.TestPlatform.TestHost.Program.Main(string[] args) Unknown
Looking at the code for DefaultEngineInvoker in master, could this have been introduced accidentally by #1538?
Steps to reproduce
The hang can be reproduced by running the relevant tests in Visual Studio using the Test Explorer (running or debugging) or by using dotnet test on the command line. Below is an example template used in the build scripts we have to run the tests (including extra arguments to work around dotnet/cli#9229):
dotnet test $Project --output $OutputPath --filter $TestFilter --verbosity=normal /nodeReuse:false -- RunConfiguration.TestSessionTimeout=600000
Expected behavior
Test runner should exit once all tests have been executed.
Actual behavior
Test runner hangs indefinitely, or aborts the test run if RunConfiguration.TestSessionTimeout is specified once the timeout is reached.
Diagnostic logs
vstest-hang.log:
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:20.513, 166632758214, vstest.console.dll, Using .Net Framework version:.NETCoreApp,Version=v2.1
TpTrace Verbose: 0 : 12716, 1, 2018/05/13, 18:57:20.811, 166635188406, vstest.console.dll, TestPluginCache: Discovering the extensions using extension path.
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:20.830, 166635378262, vstest.console.dll, AssemblyResolver: Microsoft.TestPlatform.Extensions.BlameDataCollector: Resolving assembly.
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:20.842, 166635499676, vstest.console.dll, AssemblyResolver: Microsoft.TestPlatform.Extensions.BlameDataCollector: Resolved assembly.
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:20.854, 166635620568, vstest.console.dll, AssemblyResolver: Microsoft.TestPlatform.Extensions.EventLogCollector: Resolving assembly.
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:20.859, 166635671774, vstest.console.dll, AssemblyResolver: Microsoft.TestPlatform.Extensions.EventLogCollector: Resolved assembly.
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:20.865, 166635733517, vstest.console.dll, AssemblyResolver: Microsoft.TestPlatform.TestHostRuntimeProvider: Resolving assembly.
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:20.871, 166635786364, vstest.console.dll, AssemblyResolver: Microsoft.TestPlatform.TestHostRuntimeProvider: Resolved assembly.
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:20.906, 166636135711, vstest.console.dll, AssemblyResolver: Microsoft.VisualStudio.TestPlatform.Extensions.Trx.TestLogger: Resolving assembly.
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:20.910, 166636178369, vstest.console.dll, AssemblyResolver: Microsoft.VisualStudio.TestPlatform.Extensions.Trx.TestLogger: Resolved assembly.
TpTrace Verbose: 0 : 12716, 1, 2018/05/13, 18:57:20.924, 166636321522, vstest.console.dll, TestPluginCache: Discovered the extensions using extension path ''.
TpTrace Verbose: 0 : 12716, 1, 2018/05/13, 18:57:20.926, 166636336673, vstest.console.dll, TestPluginCache: Discoverers are ''.
TpTrace Verbose: 0 : 12716, 1, 2018/05/13, 18:57:20.926, 166636339800, vstest.console.dll, TestPluginCache: Executors are ''.
TpTrace Verbose: 0 : 12716, 1, 2018/05/13, 18:57:20.926, 166636344645, vstest.console.dll, TestPluginCache: Setting providers are ''.
TpTrace Verbose: 0 : 12716, 1, 2018/05/13, 18:57:20.927, 166636348188, vstest.console.dll, TestPluginCache: Loggers are ''.
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:21.029, 166637372991, vstest.console.dll, RunTestsArgumentProcessor:Execute: Test run is starting.
TpTrace Verbose: 0 : 12716, 1, 2018/05/13, 18:57:21.029, 166637375185, vstest.console.dll, RunTestsArgumentProcessor:Execute: Queuing Test run.
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:21.032, 166637398669, vstest.console.dll, TestRequestManager.RunTests: run tests started.
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:21.218, 166639262851, vstest.console.dll, AssemblyMetadataProvider.GetFrameWork: Determined framework:'.NETCoreApp,Version=v2.1' for source: 'C:\Coding\MyApplication\artifacts\MyApplication.Tests.dll'
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:21.219, 166639271382, vstest.console.dll, Determined framework for all sources: .NETCoreApp,Version=v2.1
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:21.223, 166639305700, vstest.console.dll, AssemblyMetadataProvider.GetArchitecture: Determined architecture:AnyCPU info for assembly: C:\Coding\MyApplication\artifacts\MyApplication.Tests.dll
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:21.225, 166639333647, vstest.console.dll, Determined platform for all sources: X86
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:21.231, 166639392016, vstest.console.dll, Compatible sources list :
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:21.231, 166639394492, vstest.console.dll, C:\Coding\MyApplication\artifacts\MyApplication.Tests.dll
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:21.282, 166639902019, vstest.console.dll, InferRunSettingsHelper.IsTestSettingsEnabled: Unable to navigate to RunSettings/MSTest. Current node: RunSettings
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:21.412, 166641204336, vstest.console.dll, TestPluginManager.CreateTestExtension: Attempting to load test extension: Microsoft.VisualStudio.TestPlatform.CrossPlatEngine.Hosting.DefaultTestHostManager
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:21.417, 166641251258, vstest.console.dll, TestPluginManager.CreateTestExtension: Attempting to load test extension: Microsoft.VisualStudio.TestPlatform.CrossPlatEngine.Hosting.DotnetTestHostManager
TpTrace Verbose: 0 : 12716, 1, 2018/05/13, 18:57:21.429, 166641371675, vstest.console.dll, TestEngine: Initializing Parallel Execution as MaxCpuCount is set to: 1
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:21.432, 166641398892, vstest.console.dll, InferRunSettingsHelper.IsTestSettingsEnabled: Unable to navigate to RunSettings/MSTest. Current node: RunSettings
TpTrace Verbose: 0 : 12716, 1, 2018/05/13, 18:57:21.545, 166642529888, vstest.console.dll, TestRequestSender is acting as server
TpTrace Verbose: 0 : 12716, 1, 2018/05/13, 18:57:21.610, 166643178124, vstest.console.dll, TestRunRequest.ExecuteAsync: Creating test run request.
TpTrace Verbose: 0 : 12716, 1, 2018/05/13, 18:57:21.615, 166643226928, vstest.console.dll, TestRunRequest.ExecuteAsync: Starting.
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:21.619, 166643272569, vstest.console.dll, TestRunRequest.ExecuteAsync: Starting run with settings:TestRunCriteria:
KeepAlive=False,FrequencyOfRunStatsChangeEvent=10,RunStatsChangeEventTimeout=00:00:01.5000000,TestCaseFilter=processing,TestExecutorLauncher=
Settingsxml=<RunSettings>
<RunConfiguration>
<ResultsDirectory>C:\Coding\MyApplication\tests\MyApplication.Tests\TestResults</ResultsDirectory>
<TargetPlatform>X86</TargetPlatform>
<TargetFrameworkVersion>.NETCoreApp,Version=v2.1</TargetFrameworkVersion>
<TestSessionTimeout>600000</TestSessionTimeout>
<DesignMode>False</DesignMode>
<CollectSourceInformation>False</CollectSourceInformation>
</RunConfiguration>
<LoggerRunSettings>
<Loggers>
<Logger friendlyName="Console" assemblyQualifiedName="Microsoft.VisualStudio.TestPlatform.CommandLine.Internal.ConsoleLogger, vstest.console, Version=15.0.0.0, Culture=neutral, PublicKeyToken=b03f5f7f11d50a3a" codeBase="C:\Program Files\dotnet\sdk\2.1.300-rc1-008673\vstest.console.dll" enabled="True">
<Configuration>
<Verbosity>normal</Verbosity>
</Configuration>
</Logger>
</Loggers>
</LoggerRunSettings>
</RunSettings>
TpTrace Verbose: 0 : 12716, 1, 2018/05/13, 18:57:21.619, 166643275244, vstest.console.dll, TestRunRequest.ExecuteAsync: Wait for the first run request is over.
TpTrace Verbose: 0 : 12716, 1, 2018/05/13, 18:57:21.620, 166643279295, vstest.console.dll, TestRunRequest.ExecuteAsync: TestSessionTimeout is 600000 milliseconds.
TpTrace Verbose: 0 : 12716, 1, 2018/05/13, 18:57:21.629, 166643366006, vstest.console.dll, ParallelProxyExecutionManager: Start execution. Total sources: 1
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:21.638, 166643464439, vstest.console.dll, ProxyParallelExecutionManager: Triggering test run for next source: C:\Coding\MyApplication\artifacts\MyApplication.Tests.dll
TpTrace Verbose: 0 : 12716, 4, 2018/05/13, 18:57:21.639, 166643474712, vstest.console.dll, ParallelProxyExecutionManager: Execution started. Started clients: 1
TpTrace Verbose: 0 : 12716, 1, 2018/05/13, 18:57:21.641, 166643491180, vstest.console.dll, ProxyParallelExecutionManager: No sources available for execution.
TpTrace Information: 0 : 12716, 1, 2018/05/13, 18:57:21.641, 166643493918, vstest.console.dll, TestRunRequest.ExecuteAsync: Started.
TpTrace Verbose: 0 : 12716, 1, 2018/05/13, 18:57:21.643, 166643511096, vstest.console.dll, TestRunRequest.WaitForCompletion: Waiting with timeout -1.
TpTrace Verbose: 0 : 12716, 4, 2018/05/13, 18:57:21.644, 166643526630, vstest.console.dll, ProxyExecutionManager: Test host is always Lazy initialize.
TpTrace Verbose: 0 : 12716, 4, 2018/05/13, 18:57:21.651, 166643592136, vstest.console.dll, TestRequestSender.InitializeCommunication: initialize communication.
TpTrace Information: 0 : 12716, 4, 2018/05/13, 18:57:21.716, 166644243873, vstest.console.dll, SocketServer: Listening on end point : 127.0.0.1:61147
TpTrace Verbose: 0 : 12716, 4, 2018/05/13, 18:57:21.769, 166644772091, vstest.console.dll, DotnetTestHostmanager: Full path of dotnet.exe is C:\Program Files\dotnet\dotnet.exe
TpTrace Verbose: 0 : 12716, 4, 2018/05/13, 18:57:21.770, 166644781861, vstest.console.dll, DotnetTestHostmanager: Adding --runtimeconfig "C:\Coding\MyApplication\artifacts\MyApplication.Tests.runtimeconfig.json" in args
TpTrace Verbose: 0 : 12716, 4, 2018/05/13, 18:57:21.770, 166644784231, vstest.console.dll, DotnetTestHostmanager: Adding --depsfile "C:\Coding\MyApplication\artifacts\MyApplication.Tests.deps.json" in args
TpTrace Information: 0 : 12716, 4, 2018/05/13, 18:57:21.771, 166644786414, vstest.console.dll, AssemblyResolver: Microsoft.Extensions.DependencyModel: Resolving assembly.
TpTrace Information: 0 : 12716, 4, 2018/05/13, 18:57:21.773, 166644809203, vstest.console.dll, AssemblyResolver: Microsoft.Extensions.DependencyModel: Resolved assembly.
TpTrace Verbose: 0 : 12716, 4, 2018/05/13, 18:57:21.793, 166645007987, vstest.console.dll, DotnetTestHostmanager: Reading file C:\Coding\MyApplication\artifacts\MyApplication.Tests.deps.json to get path of testhost.dll
TpTrace Verbose: 0 : 12716, 4, 2018/05/13, 18:57:21.907, 166646147090, vstest.console.dll, DotnetTestHostmanager: Relative path of testhost.dll with respect to package folder is microsoft.testplatform.testhost/15.7.0\lib/netstandard1.5/testhost.dll
TpTrace Verbose: 0 : 12716, 4, 2018/05/13, 18:57:21.925, 166646326541, vstest.console.dll, DotnetTestHostmanager: Looking for path microsoft.testplatform.testhost/15.7.0\lib/netstandard1.5/testhost.dll in folder C:\Users\Martin.Costello\.dotnet\store\|arch|\|tfm|
TpTrace Verbose: 0 : 12716, 4, 2018/05/13, 18:57:21.925, 166646331031, vstest.console.dll, DotnetTestHostmanager: Looking for path microsoft.testplatform.testhost/15.7.0\lib/netstandard1.5/testhost.dll in folder C:\Users\Martin.Costello\.nuget\packages
TpTrace Verbose: 0 : 12716, 4, 2018/05/13, 18:57:21.926, 166646337460, vstest.console.dll, DotnetTestHostmanager: Full path of testhost.dll is C:\Users\Martin.Costello\.nuget\packages\microsoft.testplatform.testhost/15.7.0\lib/netstandard1.5/testhost.dll
TpTrace Verbose: 0 : 12716, 5, 2018/05/13, 18:57:21.938, 166646463926, vstest.console.dll, DotnetTestHostManager: Starting process 'C:\Program Files\dotnet\dotnet.exe' with command line 'exec --runtimeconfig "C:\Coding\MyApplication\artifacts\MyApplication.Tests.runtimeconfig.json" --depsfile "C:\Coding\MyApplication\artifacts\MyApplication.Tests.deps.json" "C:\Users\Martin.Costello\.nuget\packages\microsoft.testplatform.testhost/15.7.0\lib/netstandard1.5/testhost.dll" --port 61147 --endpoint 127.0.0.1:061147 --role client --parentprocessid 12716 --diag "C:\Coding\_\vstest-hang.host.18-05-13_18-57-21_74897_4.log" --telemetryoptedin false'
TpTrace Verbose: 0 : 12716, 5, 2018/05/13, 18:57:22.015, 166647231618, vstest.console.dll, Test Runtime launched
TpTrace Verbose: 0 : 12716, 4, 2018/05/13, 18:57:22.028, 166647356453, vstest.console.dll, TestRequestSender.WaitForRequestHandlerConnection: waiting for connection with timeout: 60000
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:57:24.100, 166668078489, vstest.console.dll, Client connected, and starting MessageLoopAsync
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:57:25.243, 166679506623, vstest.console.dll, TestRequestSender.CheckVersionWithTestHost: onMessageReceived received message: (ProtocolVersion) -> 2
TpTrace Verbose: 0 : 12716, 4, 2018/05/13, 18:57:25.275, 166679829760, vstest.console.dll, TestRequestSender.InitializeExecution: Sending initializing execution with message: {"Version":2,"MessageType":"TestExecution.Initialize","Payload":["C:\\Coding\\MyApplication\\artifacts\\xunit.runner.visualstudio.dotnetcore.testadapter.dll"]}
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:57:25.285, 166679933890, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestSession.Message","Payload":{"MessageLevel":0,"Message":"Logging TestHost Diagnostics in file: C:\\Coding\\_\\vstest-hang.host.18-05-13_18-57-21_74897_4.log"}}
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:57:25.307, 166680146705, vstest.console.dll, TestRunRequest:SendTestRunMessage: Starting.
TpTrace Information: 0 : 12716, 8, 2018/05/13, 18:57:25.309, 166680167701, vstest.console.dll, TestRunRequest:SendTestRunMessage: Completed.
TpTrace Verbose: 0 : 12716, 4, 2018/05/13, 18:57:25.379, 166680867552, vstest.console.dll, TestRequestSender.StartTestRun: Sending test run with message: {"Version":2,"MessageType":"TestExecution.StartWithSources","Payload":{"AdapterSourceMap":{"_none_":["C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll"]},"RunSettings":"<RunSettings>\r\n <RunConfiguration>\r\n <ResultsDirectory>C:\\Coding\\MyApplication\\tests\\MyApplication.Tests\\TestResults</ResultsDirectory>\r\n <TargetPlatform>X86</TargetPlatform>\r\n <TargetFrameworkVersion>.NETCoreApp,Version=v2.1</TargetFrameworkVersion>\r\n <TestSessionTimeout>600000</TestSessionTimeout>\r\n <DesignMode>False</DesignMode>\r\n <CollectSourceInformation>False</CollectSourceInformation>\r\n </RunConfiguration>\r\n <LoggerRunSettings>\r\n <Loggers>\r\n <Logger friendlyName=\"Console\" assemblyQualifiedName=\"Microsoft.VisualStudio.TestPlatform.CommandLine.Internal.ConsoleLogger, vstest.console, Version=15.0.0.0, Culture=neutral, PublicKeyToken=b03f5f7f11d50a3a\" codeBase=\"C:\\Program Files\\dotnet\\sdk\\2.1.300-rc1-008673\\vstest.console.dll\" enabled=\"True\">\r\n <Configuration>\r\n <Verbosity>normal</Verbosity>\r\n </Configuration>\r\n </Logger>\r\n </Loggers>\r\n </LoggerRunSettings>\r\n</RunSettings>","TestExecutionContext":{"FrequencyOfRunStatsChangeEvent":10,"RunStatsChangeEventTimeout":"00:00:01.5000000","InIsolation":false,"KeepAlive":false,"AreTestCaseLevelEventsRequired":false,"IsDebug":false,"TestCaseFilter":"processing","FilterOptions":null},"Package":null}}
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:57:30.664, 166733717074, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestSession.Message","Payload":{"MessageLevel":0,"Message":"[xUnit.net 00:00:04.7563850] Discovering: MyApplication.Tests"}}
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:57:30.664, 166733721126, vstest.console.dll, TestRunRequest:SendTestRunMessage: Starting.
TpTrace Information: 0 : 12716, 8, 2018/05/13, 18:57:30.664, 166733722760, vstest.console.dll, TestRunRequest:SendTestRunMessage: Completed.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:57:31.542, 166742500774, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestSession.Message","Payload":{"MessageLevel":0,"Message":"[xUnit.net 00:00:05.6375087] Discovered: MyApplication.Tests"}}
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:57:31.543, 166742505665, vstest.console.dll, TestRunRequest:SendTestRunMessage: Starting.
TpTrace Information: 0 : 12716, 8, 2018/05/13, 18:57:31.543, 166742508265, vstest.console.dll, TestRunRequest:SendTestRunMessage: Completed.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:57:31.562, 166742696588, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestSession.Message","Payload":{"MessageLevel":0,"Message":"[xUnit.net 00:00:05.6570174] Starting: MyApplication.Tests"}}
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:57:31.562, 166742700409, vstest.console.dll, TestRunRequest:SendTestRunMessage: Starting.
TpTrace Information: 0 : 12716, 8, 2018/05/13, 18:57:31.562, 166742701933, vstest.console.dll, TestRunRequest:SendTestRunMessage: Completed.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:57:31.850, 166745580576, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[],"TestRunStatistics":{"ExecutedTests":0,"Stats":{}},"ActiveTests":[{"Id":"14e4f259-eab8-cc3b-2bb1-58a637458024","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]}]}}
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:57:31.936, 166746443235, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Starting.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:57:31.937, 166746450721, vstest.console.dll, InProgress is Redacted_Test_Name
TpTrace Information: 0 : 12716, 8, 2018/05/13, 18:57:31.939, 166746466786, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Completed.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:57:32.696, 166754036560, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[{"TestCase":{"Id":"14e4f259-eab8-cc3b-2bb1-58a637458024","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.7220000","StartTime":"2018-05-13T18:57:32.564645+01:00","EndTime":"2018-05-13T18:57:32.570256+01:00","Properties":[]},{"TestCase":{"Id":"bb48d237-8e77-c99b-8062-080417f99f3d","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0040000","StartTime":"2018-05-13T18:57:32.5890058+01:00","EndTime":"2018-05-13T18:57:32.5890289+01:00","Properties":[]},{"TestCase":{"Id":"b5ed1dff-2450-9ed5-657f-7a7884b99b69","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0030000","StartTime":"2018-05-13T18:57:32.5921822+01:00","EndTime":"2018-05-13T18:57:32.5921901+01:00","Properties":[]},{"TestCase":{"Id":"e3a01a6c-ebf3-efeb-21a2-0ae931d3e9fa","FullyQualifiedName":".Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0020000","StartTime":"2018-05-13T18:57:32.6012294+01:00","EndTime":"2018-05-13T18:57:32.60124+01:00","Properties":[]},{"TestCase":{"Id":"f42d378c-6472-ab43-2756-9e2e5e27156e","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0010000","StartTime":"2018-05-13T18:57:32.6017873+01:00","EndTime":"2018-05-13T18:57:32.6017948+01:00","Properties":[]},{"TestCase":{"Id":"a595f857-fe1b-8d90-527f-3fad3e1f4dcd","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0010000","StartTime":"2018-05-13T18:57:32.6025621+01:00","EndTime":"2018-05-13T18:57:32.6025682+01:00","Properties":[]},{"TestCase":{"Id":"f2296043-0415-2d22-2a66-b6900688bed3","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0560000","StartTime":"2018-05-13T18:57:32.6342185+01:00","EndTime":"2018-05-13T18:57:32.634231+01:00","Properties":[]},{"TestCase":{"Id":"15d99d60-ad92-976e-ca7d-3c6ce26c382c","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0030000","StartTime":"2018-05-13T18:57:32.6369397+01:00","EndTime":"2018-05-13T18:57:32.636951+01:00","Properties":[]},{"TestCase":{"Id":"84f94082-883d-65af-06bd-f242170c8da6","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0290000","StartTime":"2018-05-13T18:57:32.666384+01:00","EndTime":"2018-05-13T18:57:32.6663964+01:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":9,"Stats":{"Passed":9}},"ActiveTests":[{"Id":"ff8be433-4fac-3a74-d0ed-9c3c2b739dff","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]}]}}
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:57:32.736, 166754442924, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Starting.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:57:32.736, 166754445052, vstest.console.dll, InProgress is Redacted_Test_Name
TpTrace Information: 0 : 12716, 8, 2018/05/13, 18:57:32.738, 166754461021, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Completed.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:57:34.208, 166769156417, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[{"TestCase":{"Id":"ff8be433-4fac-3a74-d0ed-9c3c2b739dff","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0080000","StartTime":"2018-05-13T18:57:32.6966136+01:00","EndTime":"2018-05-13T18:57:32.6966228+01:00","Properties":[]},{"TestCase":{"Id":"5df23517-73eb-9afb-9766-bad7c3722b0e","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0020000","StartTime":"2018-05-13T18:57:32.6975445+01:00","EndTime":"2018-05-13T18:57:32.6975503+01:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":11,"Stats":{"Passed":11}},"ActiveTests":[]}}
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:57:34.209, 166769170819, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Starting.
TpTrace Information: 0 : 12716, 8, 2018/05/13, 18:57:34.210, 166769176841, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Completed.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:57:35.765, 166784727429, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[],"TestRunStatistics":{"ExecutedTests":11,"Stats":{"Passed":11}},"ActiveTests":[{"Id":"518ec267-5766-4ed4-70ee-2b5ea0aec60b","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]}]}}
TpTrace Information: 0 : 12716, 8, 2018/05/13, 18:57:36.139, 166788468176, vstest.console.dll, AssemblyResolver: System.Private.DataContractSerialization.resources: Resolving assembly.
TpTrace Information: 0 : 12716, 8, 2018/05/13, 18:57:36.139, 166788471785, vstest.console.dll, AssemblyResolver: System.Private.DataContractSerialization.resources: Failed to load assembly.
TpTrace Information: 0 : 12716, 8, 2018/05/13, 18:57:36.140, 166788480945, vstest.console.dll, AssemblyResolver: System.Private.DataContractSerialization.resources: Resolving assembly.
TpTrace Information: 0 : 12716, 8, 2018/05/13, 18:57:36.140, 166788482355, vstest.console.dll, AssemblyResolver: System.Private.DataContractSerialization.resources: Resolved from cache.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:57:36.216, 166789240641, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Starting.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:57:36.216, 166789243100, vstest.console.dll, InProgress is Redacted_Test_Name
TpTrace Information: 0 : 12716, 8, 2018/05/13, 18:57:36.216, 166789244817, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Completed.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:58:16.565, 167192728716, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[{"TestCase":{"Id":"518ec267-5766-4ed4-70ee-2b5ea0aec60b","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:42.1710000","StartTime":"2018-05-13T18:58:16.5630699+01:00","EndTime":"2018-05-13T18:58:16.5630833+01:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":12,"Stats":{"Passed":12}},"ActiveTests":[{"Id":"645ad45f-e508-016f-fee4-272a5dbfe3fe","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]}]}}
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:58:16.568, 167192760669, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Starting.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:58:16.569, 167192771842, vstest.console.dll, InProgress is Redacted_Test_Name
TpTrace Information: 0 : 12716, 8, 2018/05/13, 18:58:16.570, 167192778451, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Completed.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:58:47.546, 167502543178, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[{"TestCase":{"Id":"645ad45f-e508-016f-fee4-272a5dbfe3fe","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:30.9790000","StartTime":"2018-05-13T18:58:47.5398207+01:00","EndTime":"2018-05-13T18:58:47.5398354+01:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":13,"Stats":{"Passed":13}},"ActiveTests":[{"Id":"2115c715-f67f-c9d6-4dda-61545d6b7f11","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]}]}}
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:58:47.556, 167502639372, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Starting.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:58:47.556, 167502642524, vstest.console.dll, InProgress is Redacted_Test_Name
TpTrace Information: 0 : 12716, 8, 2018/05/13, 18:58:47.556, 167502644476, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Completed.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:58:49.063, 167517710454, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[{"TestCase":{"Id":"2115c715-f67f-c9d6-4dda-61545d6b7f11","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.1810000","StartTime":"2018-05-13T18:58:47.7344297+01:00","EndTime":"2018-05-13T18:58:47.7344428+01:00","Properties":[]},{"TestCase":{"Id":"24ffff36-79f2-94e6-e085-50f112f1f510","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0390000","StartTime":"2018-05-13T18:58:47.7781443+01:00","EndTime":"2018-05-13T18:58:47.7781545+01:00","Properties":[]},{"TestCase":{"Id":"ef038343-28ee-8b09-d27c-868e8e816b25","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0270000","StartTime":"2018-05-13T18:58:47.805601+01:00","EndTime":"2018-05-13T18:58:47.80561+01:00","Properties":[]},{"TestCase":{"Id":"532a7ac5-ba14-98f2-2ee0-052c8b27523f","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0380000","StartTime":"2018-05-13T18:58:47.8439777+01:00","EndTime":"2018-05-13T18:58:47.8439899+01:00","Properties":[]},{"TestCase":{"Id":"adaf9ed0-1d6c-9ce5-feae-0065dea9c8f1","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0300000","StartTime":"2018-05-13T18:58:47.8742497+01:00","EndTime":"2018-05-13T18:58:47.8742616+01:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":18,"Stats":{"Passed":18}},"ActiveTests":[{"Id":"ba599226-35ec-d3ed-dfee-4c2ed8dc3845","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]}]}}
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:58:49.069, 167517767168, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Starting.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:58:49.069, 167517770065, vstest.console.dll, InProgress is Redacted_Test_Name
TpTrace Information: 0 : 12716, 8, 2018/05/13, 18:58:49.069, 167517771719, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Completed.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:59:14.289, 167769972947, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[{"TestCase":{"Id":"ba599226-35ec-d3ed-dfee-4c2ed8dc3845","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:26.3920000","StartTime":"2018-05-13T18:59:14.2840844+01:00","EndTime":"2018-05-13T18:59:14.2840979+01:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":19,"Stats":{"Passed":19}},"ActiveTests":[{"Id":"fffc9348-a492-55a0-f1c6-56c9f3b83aea","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]}]}}
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:59:14.292, 167769999321, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Starting.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:59:14.292, 167770002139, vstest.console.dll, InProgress is Redacted_Test_Name
TpTrace Information: 0 : 12716, 8, 2018/05/13, 18:59:14.292, 167770004141, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Completed.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:59:14.294, 167770024059, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestSession.Message","Payload":{"MessageLevel":1,"Message":"[xUnit.net 00:01:48.3899210] Redacted_Test_Name [SKIP]"}}
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:59:14.295, 167770028111, vstest.console.dll, TestRunRequest:SendTestRunMessage: Starting.
TpTrace Information: 0 : 12716, 8, 2018/05/13, 18:59:14.295, 167770030231, vstest.console.dll, TestRunRequest:SendTestRunMessage: Completed.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:59:14.296, 167770042200, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestSession.Message","Payload":{"MessageLevel":0,"Message":"[xUnit.net 00:01:48.3918223] Redacted."}}
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:59:14.296, 167770046173, vstest.console.dll, TestRunRequest:SendTestRunMessage: Starting.
TpTrace Information: 0 : 12716, 8, 2018/05/13, 18:59:14.297, 167770048136, vstest.console.dll, TestRunRequest:SendTestRunMessage: Completed.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:59:15.805, 167785130266, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[{"TestCase":{"Id":"fffc9348-a492-55a0-f1c6-56c9f3b83aea","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]},"Attachments":[],"Outcome":3,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[{"Category":"StdOutMsgs","Text":"This check is only enforced for non-Development environments."}],"ComputerName":"MyComputerName","Duration":"00:00:00.0010000","StartTime":"2018-05-13T18:59:14.2981615+01:00","EndTime":"2018-05-13T18:59:14.2981748+01:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":20,"Stats":{"Passed":19,"Skipped":1}},"ActiveTests":[]}}
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 18:59:15.810, 167785185925, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Starting.
TpTrace Information: 0 : 12716, 8, 2018/05/13, 18:59:15.811, 167785196503, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Completed.
TpTrace Verbose: 0 : 12716, 10, 2018/05/13, 19:07:21.610, 172643360291, vstest.console.dll, TestRunRequest.OnTestSessionTimeout: calling cancelation as test run exceeded testSessionTimeout 600000 milliseconds
TpTrace Information: 0 : 12716, 10, 2018/05/13, 19:07:21.611, 172643372026, vstest.console.dll, AssemblyResolver: Microsoft.VisualStudio.TestPlatform.Client.resources: Resolving assembly.
TpTrace Information: 0 : 12716, 10, 2018/05/13, 19:07:21.612, 172643379541, vstest.console.dll, AssemblyResolver: Microsoft.VisualStudio.TestPlatform.Client.resources: Failed to load assembly.
TpTrace Information: 0 : 12716, 10, 2018/05/13, 19:07:21.613, 172643391224, vstest.console.dll, AssemblyResolver: Microsoft.VisualStudio.TestPlatform.Client.resources: Resolving assembly.
TpTrace Information: 0 : 12716, 10, 2018/05/13, 19:07:21.613, 172643393578, vstest.console.dll, AssemblyResolver: Microsoft.VisualStudio.TestPlatform.Client.resources: Resolved from cache.
TpTrace Verbose: 0 : 12716, 10, 2018/05/13, 19:07:21.620, 172643458521, vstest.console.dll, TestRunRequest:SendTestRunMessage: Starting.
TpTrace Information: 0 : 12716, 10, 2018/05/13, 19:07:21.620, 172643461131, vstest.console.dll, TestRunRequest:SendTestRunMessage: Completed.
TpTrace Verbose: 0 : 12716, 10, 2018/05/13, 19:07:21.621, 172643469744, vstest.console.dll, TestRunRequest.Abort: Aborting.
TpTrace Verbose: 0 : 12716, 11, 2018/05/13, 19:07:21.623, 172643490609, vstest.console.dll, TestRequestSender.SendTestRunAbort: Sending test run abort.
TpTrace Information: 0 : 12716, 10, 2018/05/13, 19:07:21.624, 172643498504, vstest.console.dll, TestRunRequest.Abort: Aborted.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 19:07:21.645, 172643713995, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestExecution.Completed","Payload":{"TestRunCompleteArgs":{"TestRunStatistics":{"ExecutedTests":20,"Stats":{"Passed":19,"Skipped":1}},"IsCanceled":false,"IsAborted":true,"Error":null,"AttachmentSets":[],"ElapsedTimeInRunningTests":"00:00:00","Metrics":{}},"LastRunTests":{"NewTestResults":[],"TestRunStatistics":{"ExecutedTests":20,"Stats":{"Passed":19,"Skipped":1}},"ActiveTests":[]},"RunAttachments":[],"ExecutorUris":[]}}
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 19:07:21.647, 172643728945, vstest.console.dll, TestRequestSender.EndSession: Sending end session.
TpTrace Warning: 0 : 12716, 8, 2018/05/13, 19:07:21.748, 172644737556, vstest.console.dll, ProxyOperationManager: Timed out waiting for test host to exit. Will terminate process.
TpTrace Information: 0 : 12716, 8, 2018/05/13, 19:07:22.064, 172647895186, vstest.console.dll, TestRequestSender.OnClientProcessExit: Test host process exited. Standard error:
TpTrace Information: 0 : 12716, 8, 2018/05/13, 19:07:22.064, 172647902207, vstest.console.dll, SocketServer: Stop: Cancellation requested. Stopping message loop.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 19:07:22.131, 172648569834, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Starting.
TpTrace Information: 0 : 12716, 8, 2018/05/13, 19:07:22.131, 172648572277, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Completed.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 19:07:22.139, 172648654118, vstest.console.dll, ParallelProxyExecutionManager: HandlePartialRunComplete: Total completed clients = 1, Run complete = True, Run canceled: False.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 19:07:22.223, 172649492914, vstest.console.dll, TestRunRequest:TestRunComplete: Starting. IsAborted:True IsCanceled:False.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 19:07:22.224, 172649501615, vstest.console.dll, TestLoggerManager.HandleTestRunComplete: Ignoring as the object is disposed.
TpTrace Verbose: 0 : 12716, 1, 2018/05/13, 19:07:22.228, 172649537868, vstest.console.dll, TestRunRequest.Dispose: Starting.
TpTrace Information: 0 : 12716, 8, 2018/05/13, 19:07:22.229, 172649547518, vstest.console.dll, TestRunRequest:TestRunComplete: Completed.
TpTrace Information: 0 : 12716, 1, 2018/05/13, 19:07:22.230, 172649554360, vstest.console.dll, TestRunRequest.Dispose: Completed.
TpTrace Information: 0 : 12716, 1, 2018/05/13, 19:07:22.230, 172649556668, vstest.console.dll, TestRequestManager.RunTests: run tests completed.
TpTrace Verbose: 0 : 12716, 8, 2018/05/13, 19:07:22.230, 172649561492, vstest.console.dll, TestRequestSender.SetOperationComplete: Setting operation complete.
TpTrace Information: 0 : 12716, 1, 2018/05/13, 19:07:22.231, 172649564581, vstest.console.dll, RunTestsArgumentProcessor:Execute: Test run is completed.
TpTrace Verbose: 0 : 12716, 1, 2018/05/13, 19:07:22.231, 172649568350, vstest.console.dll, Executor.Execute: Exiting with exit code of 1
vstest-hang.host.18-05-13_18-57-21_74897_4.log:
TpTrace Verbose: 0 : 19776, 1, 2018/05/13, 18:57:23.772, 166665513852, testhost.dll, TestRequestHanlder is acting as client
TpTrace Information: 0 : 19776, 1, 2018/05/13, 18:57:23.935, 166666428724, testhost.dll, DefaultEngineInvoker: Monitoring parent process with id: '12716'
TpTrace Information: 0 : 19776, 1, 2018/05/13, 18:57:23.953, 166666609383, testhost.dll, DefaultEngineInvoker: Initialize communication on endpoint address: '127.0.0.1:061147'
TpTrace Information: 0 : 19776, 1, 2018/05/13, 18:57:24.004, 166667123119, testhost.dll, SocketClient.Start: connecting to server endpoint: 127.0.0.1:061147
TpTrace Information: 0 : 19776, 1, 2018/05/13, 18:57:24.063, 166667710713, testhost.dll, DefaultEngineInvoker: Start Request Processing.
TpTrace Information: 0 : 19776, 8, 2018/05/13, 18:57:24.109, 166668174857, testhost.dll, SocketClient.OnServerConnected: connected to server endpoint: 127.0.0.1:061147
TpTrace Verbose: 0 : 19776, 8, 2018/05/13, 18:57:24.122, 166668302136, testhost.dll, Connected to server, and starting MessageLoopAsync
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:24.132, 166668404704, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:24.495, 166672033660, testhost.dll, TcpClientExtensions.MessageLoopAsync: NotifyDataAvailable remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Information: 0 : 19776, 9, 2018/05/13, 18:57:25.107, 166678149003, testhost.dll, TestRequestHandler.ProcessRequests: received message: (ProtocolVersion) -> 2
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:25.283, 166679912239, testhost.dll, TestRequestHandler.SendData: sending data from testhost: {"Version":2,"MessageType":"TestSession.Message","Payload":{"MessageLevel":0,"Message":"Logging TestHost Diagnostics in file: C:\\Coding\\_\\vstest-hang.host.18-05-13_18-57-21_74897_4.log"}}
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:25.284, 166679916120, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:25.284, 166679918434, testhost.dll, TcpClientExtensions.MessageLoopAsync: NotifyDataAvailable remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Information: 0 : 19776, 9, 2018/05/13, 18:57:25.329, 166680372973, testhost.dll, TestRequestHandler.ProcessRequests: received message: (TestExecution.Initialize) -> [
"C:\\Coding\\MyApplication\\artifacts\\xunit.runner.visualstudio.dotnetcore.testadapter.dll"
]
TpTrace Information: 0 : 19776, 9, 2018/05/13, 18:57:25.330, 166680376145, testhost.dll, Execution Session Initialize.
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:25.365, 166680733426, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.370, 166680783339, testhost.dll, TestExecutorService: Loading the extensions
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:25.379, 166680871634, testhost.dll, TcpClientExtensions.MessageLoopAsync: NotifyDataAvailable remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Information: 0 : 19776, 9, 2018/05/13, 18:57:25.387, 166680954414, testhost.dll, TestRequestHandler.ProcessRequests: received message: (TestExecution.StartWithSources) -> {
"AdapterSourceMap": {
"_none_": [
"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll"
]
},
"RunSettings": "<RunSettings>\r\n <RunConfiguration>\r\n <ResultsDirectory>C:\\Coding\\MyApplication\\tests\\MyApplication.Tests\\TestResults</ResultsDirectory>\r\n <TargetPlatform>X86</TargetPlatform>\r\n <TargetFrameworkVersion>.NETCoreApp,Version=v2.1</TargetFrameworkVersion>\r\n <TestSessionTimeout>600000</TestSessionTimeout>\r\n <DesignMode>False</DesignMode>\r\n <CollectSourceInformation>False</CollectSourceInformation>\r\n </RunConfiguration>\r\n <LoggerRunSettings>\r\n <Loggers>\r\n <Logger friendlyName=\"Console\" assemblyQualifiedName=\"Microsoft.VisualStudio.TestPlatform.CommandLine.Internal.ConsoleLogger, vstest.console, Version=15.0.0.0, Culture=neutral, PublicKeyToken=b03f5f7f11d50a3a\" codeBase=\"C:\\Program Files\\dotnet\\sdk\\2.1.300-rc1-008673\\vstest.console.dll\" enabled=\"True\">\r\n <Configuration>\r\n <Verbosity>normal</Verbosity>\r\n </Configuration>\r\n </Logger>\r\n </Loggers>\r\n </LoggerRunSettings>\r\n</RunSettings>",
"TestExecutionContext": {
"FrequencyOfRunStatsChangeEvent": 10,
"RunStatsChangeEventTimeout": "00:00:01.5000000",
"InIsolation": false,
"KeepAlive": false,
"AreTestCaseLevelEventsRequired": false,
"IsDebug": false,
"TestCaseFilter": "processing",
"FilterOptions": null
},
"Package": null
}
TpTrace Information: 0 : 19776, 9, 2018/05/13, 18:57:25.388, 166680958905, testhost.dll, Execution started.
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.417, 166681247938, testhost.dll, TestPluginCache: Discovering the extensions using extension path.
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:25.431, 166681393150, testhost.dll, AssemblyResolver: xunit.runner.visualstudio.dotnetcore.testadapter: Resolving assembly.
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:25.440, 166681480226, testhost.dll, AssemblyResolver: xunit.runner.visualstudio.dotnetcore.testadapter: Resolved assembly.
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:25.446, 166681535999, testhost.dll, AssemblyResolver: xunit.runner.utility.netcoreapp10: Resolving assembly.
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:25.449, 166681572717, testhost.dll, AssemblyResolver: xunit.runner.utility.netcoreapp10: Resolved assembly.
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.480, 166681881254, testhost.dll, TestPluginCache: Discovered the extensions using extension path ''.
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:25.481, 166681886117, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.481, 166681894215, testhost.dll, TestPluginCache: Discoverers are ''.
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.482, 166681904114, testhost.dll, TestPluginCache: Executors are 'executor://xunit/VsTestRunner2/netcoreapp'.
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.483, 166681906544, testhost.dll, TestPluginCache: Setting providers are ''.
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.483, 166681908563, testhost.dll, TestPluginCache: Loggers are ''.
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:25.511, 166682185563, testhost.dll, TestPluginManager.CreateTestExtension: Attempting to load test extension: Xunit.Runner.VisualStudio.VsTestRunner
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.511, 166682192363, testhost.dll, TestExecutorExtensionManager: Loading executor Xunit.Runner.VisualStudio.VsTestRunner
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.511, 166682194937, testhost.dll, TestExecutorService: Loaded the executors
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.514, 166682216459, testhost.dll, TestPluginCache: Discovering the extensions using extension path.
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.514, 166682223980, testhost.dll, TestPluginCache: Discovered the extensions using extension path ''.
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.515, 166682225868, testhost.dll, TestPluginCache: Discoverers are ''.
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.515, 166682227307, testhost.dll, TestPluginCache: Executors are 'executor://xunit/VsTestRunner2/netcoreapp'.
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.515, 166682228891, testhost.dll, TestPluginCache: Setting providers are ''.
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.515, 166682229926, testhost.dll, TestPluginCache: Loggers are ''.
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.517, 166682250004, testhost.dll, TestExecutorService: Loaded the settings providers
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:25.517, 166682253551, testhost.dll, TestExecutorService: Loaded the extensions
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:25.645, 166683532408, testhost.dll, AssemblyResolver: Microsoft.VisualStudio.TestPlatform.Common.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:25.646, 166683537771, testhost.dll, AssemblyResolver: Microsoft.VisualStudio.TestPlatform.Common.resources: Failed to load assembly.
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:25.651, 166683590594, testhost.dll, AssemblyResolver: Microsoft.VisualStudio.TestPlatform.Common.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:25.652, 166683597896, testhost.dll, AssemblyResolver: Microsoft.VisualStudio.TestPlatform.Common.resources: Resolved from cache.
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:25.702, 166684104121, testhost.dll, TestDiscoveryManager: Discovering tests from sources C:\Coding\MyApplication\artifacts\MyApplication.Tests.dll
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.706, 166684141458, testhost.dll, TestPluginCache: Discovering the extensions using extension path.
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.709, 166684168875, testhost.dll, TestPluginCache: Discovered the extensions using extension path ''.
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.710, 166684184757, testhost.dll, TestPluginCache: Discoverers are 'Xunit.Runner.VisualStudio.VsTestRunner, xunit.runner.visualstudio.dotnetcore.testadapter, Version=2.3.1.3858, Culture=neutral, PublicKeyToken=8d05b1bb7a6fdb6c'.
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.711, 166684187836, testhost.dll, TestPluginCache: Executors are 'executor://xunit/VsTestRunner2/netcoreapp'.
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.711, 166684190461, testhost.dll, TestPluginCache: Setting providers are ''.
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.712, 166684198149, testhost.dll, TestPluginCache: Loggers are ''.
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:25.898, 166686063349, testhost.dll, BaseRunTests.RunTestInternalWithExecutors: Running tests for executor://xunit/VsTestRunner2/netcoreapp
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:26.481, 166691891683, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:27.481, 166701891578, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:28.482, 166711901574, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:29.483, 166721906850, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:30.020, 166727278428, testhost.dll, AssemblyResolver: MyApplication.Benchmarks: Resolving assembly.
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:30.025, 166727330327, testhost.dll, AssemblyResolver: MyApplication.Benchmarks: Resolved assembly.
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:30.025, 166727333885, testhost.dll, AssemblyResolver: BenchmarkDotNet.Core: Resolving assembly.
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:30.026, 166727337668, testhost.dll, AssemblyResolver: BenchmarkDotNet.Core: Failed to load assembly.
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:30.031, 166727393312, testhost.dll, AssemblyResolver: MyApplication.EndToEndTests: Resolving assembly.
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:30.036, 166727439118, testhost.dll, AssemblyResolver: MyApplication.EndToEndTests: Resolved assembly.
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:30.041, 166727488799, testhost.dll, AssemblyResolver: MyApplication.Views: Resolving assembly.
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:30.292, 166729998631, testhost.dll, AssemblyResolver: MyApplication.Views: Resolved assembly.
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:30.299, 166730067440, testhost.dll, AssemblyResolver: xunit.runner.reporters.netcoreapp10: Resolving assembly.
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:30.302, 166730096791, testhost.dll, AssemblyResolver: xunit.runner.reporters.netcoreapp10: Resolved assembly.
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:30.306, 166730137272, testhost.dll, AssemblyResolver: xunit.runner.utility.netcoreapp10: Resolving assembly.
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:30.306, 166730139671, testhost.dll, AssemblyResolver: xunit.runner.utility.netcoreapp10: Resolved from cache.
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:30.482, 166731902109, testhost.dll, AssemblyResolver: BenchmarkDotNet.Core: Resolving assembly.
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:30.482, 166731904636, testhost.dll, AssemblyResolver: BenchmarkDotNet.Core: Resolved from cache.
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:30.483, 166731906727, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:30.663, 166733710796, testhost.dll, [xUnit.net 00:00:04.7563850] Discovering: MyApplication.Tests
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:30.663, 166733713635, testhost.dll, TestRequestHandler.SendData: sending data from testhost: {"Version":2,"MessageType":"TestSession.Message","Payload":{"MessageLevel":0,"Message":"[xUnit.net 00:00:04.7563850] Discovering: MyApplication.Tests"}}
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:31.483, 166741906867, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:31.541, 166742492085, testhost.dll, [xUnit.net 00:00:05.6375087] Discovered: MyApplication.Tests
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:31.541, 166742495198, testhost.dll, TestRequestHandler.SendData: sending data from testhost: {"Version":2,"MessageType":"TestSession.Message","Payload":{"MessageLevel":0,"Message":"[xUnit.net 00:00:05.6375087] Discovered: MyApplication.Tests"}}
TpTrace Information: 0 : 19776, 3, 2018/05/13, 18:57:31.561, 166742686988, testhost.dll, [xUnit.net 00:00:05.6570174] Starting: MyApplication.Tests
TpTrace Verbose: 0 : 19776, 3, 2018/05/13, 18:57:31.561, 166742692131, testhost.dll, TestRequestHandler.SendData: sending data from testhost: {"Version":2,"MessageType":"TestSession.Message","Payload":{"MessageLevel":0,"Message":"[xUnit.net 00:00:05.6570174] Starting: MyApplication.Tests"}}
TpTrace Information: 0 : 19776, 14, 2018/05/13, 18:57:31.774, 166744817720, testhost.dll, Sending test run statistics
TpTrace Verbose: 0 : 19776, 14, 2018/05/13, 18:57:31.849, 166745574913, testhost.dll, TestRequestHandler.SendData: sending data from testhost: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[],"TestRunStatistics":{"ExecutedTests":0,"Stats":{}},"ActiveTests":[{"Id":"14e4f259-eab8-cc3b-2bb1-58a637458024","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]}]}}
TpTrace Verbose: 0 : 19776, 14, 2018/05/13, 18:57:31.851, 166745586423, testhost.dll, TestRunCache: OnNewTestResult: Notified the onCacheHit callback.
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:32.483, 166751906869, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Information: 0 : 19776, 16, 2018/05/13, 18:57:32.489, 166751965936, testhost.dll, AssemblyResolver: MyApplication.Domain.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 16, 2018/05/13, 18:57:32.489, 166751972239, testhost.dll, AssemblyResolver: MyApplication.Domain.resources: Failed to load assembly.
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:57:32.579, 166752868235, testhost.dll, InProgressTests is null
TpTrace Information: 0 : 19776, 14, 2018/05/13, 18:57:32.581, 166752895246, testhost.dll, AssemblyResolver: Microsoft.VisualStudio.TestPlatform.ObjectModel.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 14, 2018/05/13, 18:57:32.582, 166752901176, testhost.dll, AssemblyResolver: Microsoft.VisualStudio.TestPlatform.ObjectModel.resources: Failed to load assembly.
TpTrace Information: 0 : 19776, 14, 2018/05/13, 18:57:32.583, 166752909012, testhost.dll, AssemblyResolver: Microsoft.VisualStudio.TestPlatform.ObjectModel.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 14, 2018/05/13, 18:57:32.583, 166752911652, testhost.dll, AssemblyResolver: Microsoft.VisualStudio.TestPlatform.ObjectModel.resources: Resolved from cache.
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:57:32.584, 166752925495, testhost.dll, TestRunCache: No test found corresponding to testResult 'Redacted_Test_Name Passed' in inProgress list.
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:57:32.588, 166752959552, testhost.dll, InProgressTests is null
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:57:32.591, 166752990622, testhost.dll, InProgressTests is null
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:57:32.600, 166753081121, testhost.dll, InProgressTests is null
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:57:32.601, 166753089317, testhost.dll, InProgressTests is null
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:57:32.601, 166753094388, testhost.dll, InProgressTests is null
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:57:32.602, 166753102143, testhost.dll, InProgressTests is null
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:57:32.634, 166753418933, testhost.dll, InProgressTests is null
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:57:32.637, 166753446113, testhost.dll, InProgressTests is null
TpTrace Information: 0 : 19776, 16, 2018/05/13, 18:57:32.638, 166753464870, testhost.dll, AssemblyResolver: MyApplication.Domain.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 16, 2018/05/13, 18:57:32.639, 166753467883, testhost.dll, AssemblyResolver: MyApplication.Domain.resources: Resolved from cache.
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:57:32.666, 166753740576, testhost.dll, InProgressTests is null
TpTrace Information: 0 : 19776, 14, 2018/05/13, 18:57:32.667, 166753748218, testhost.dll, Sending test run statistics
TpTrace Verbose: 0 : 19776, 14, 2018/05/13, 18:57:32.694, 166754024181, testhost.dll, TestRequestHandler.SendData: sending data from testhost: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[{"TestCase":{"Id":"14e4f259-eab8-cc3b-2bb1-58a637458024","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Map_Maps_Model_Correctly_For_Attempts(attempt: 0, expectedAttempts: 1, expectedIsError: False)","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.7220000","StartTime":"2018-05-13T18:57:32.564645+01:00","EndTime":"2018-05-13T18:57:32.570256+01:00","Properties":[]},{"TestCase":{"Id":"bb48d237-8e77-c99b-8062-080417f99f3d","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Map_Maps_Model_Correctly_For_Attempts(attempt: -1, expectedAttempts: 1, expectedIsError: False)","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0040000","StartTime":"2018-05-13T18:57:32.5890058+01:00","EndTime":"2018-05-13T18:57:32.5890289+01:00","Properties":[]},{"TestCase":{"Id":"b5ed1dff-2450-9ed5-657f-7a7884b99b69","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Map_Maps_Model_Correctly_For_Attempts(attempt: 1, expectedAttempts: 1, expectedIsError: False)","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0030000","StartTime":"2018-05-13T18:57:32.5921822+01:00","EndTime":"2018-05-13T18:57:32.5921901+01:00","Properties":[]},{"TestCase":{"Id":"e3a01a6c-ebf3-efeb-21a2-0ae931d3e9fa","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Map_Maps_Model_Correctly_For_Attempts(attempt: 4, expectedAttempts: 3, expectedIsError: True)","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0020000","StartTime":"2018-05-13T18:57:32.6012294+01:00","EndTime":"2018-05-13T18:57:32.60124+01:00","Properties":[]},{"TestCase":{"Id":"f42d378c-6472-ab43-2756-9e2e5e27156e","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Map_Maps_Model_Correctly_For_Attempts(attempt: 3, expectedAttempts: 3, expectedIsError: True)","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0010000","StartTime":"2018-05-13T18:57:32.6017873+01:00","EndTime":"2018-05-13T18:57:32.6017948+01:00","Properties":[]},{"TestCase":{"Id":"a595f857-fe1b-8d90-527f-3fad3e1f4dcd","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Map_Maps_Model_Correctly_For_Attempts(attempt: 2, expectedAttempts: 2, expectedIsError: False)","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0010000","StartTime":"2018-05-13T18:57:32.6025621+01:00","EndTime":"2018-05-13T18:57:32.6025682+01:00","Properties":[]},{"TestCase":{"Id":"f2296043-0415-2d22-2a66-b6900688bed3","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0560000","StartTime":"2018-05-13T18:57:32.6342185+01:00","EndTime":"2018-05-13T18:57:32.634231+01:00","Properties":[]},{"TestCase":{"Id":"15d99d60-ad92-976e-ca7d-3c6ce26c382c","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0030000","StartTime":"2018-05-13T18:57:32.6369397+01:00","EndTime":"2018-05-13T18:57:32.636951+01:00","Properties":[]},{"TestCase":{"Id":"84f94082-883d-65af-06bd-f242170c8da6","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0290000","StartTime":"2018-05-13T18:57:32.666384+01:00","EndTime":"2018-05-13T18:57:32.6663964+01:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":9,"Stats":{"Passed":9}},"ActiveTests":[{"Id":"ff8be433-4fac-3a74-d0ed-9c3c2b739dff","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]}]}}
TpTrace Verbose: 0 : 19776, 14, 2018/05/13, 18:57:32.696, 166754037375, testhost.dll, TestRunCache: OnNewTestResult: Notified the onCacheHit callback.
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:57:32.696, 166754042067, testhost.dll, InProgressTests is null
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:57:32.696, 166754044388, testhost.dll, TestRunCache: No test found corresponding to testResult 'Redacted_Test_Name Passed' in inProgress list.
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:57:32.697, 166754046995, testhost.dll, InProgressTests is null
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:57:32.697, 166754051983, testhost.dll, InProgressTests is null
TpTrace Information: 0 : 19776, 15, 2018/05/13, 18:57:33.183, 166758908369, testhost.dll, AssemblyResolver: Microsoft.AspNetCore.DataProtection.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 15, 2018/05/13, 18:57:33.183, 166758912057, testhost.dll, AssemblyResolver: Microsoft.AspNetCore.DataProtection.resources: Failed to load assembly.
TpTrace Information: 0 : 19776, 15, 2018/05/13, 18:57:33.184, 166758916834, testhost.dll, AssemblyResolver: Microsoft.AspNetCore.DataProtection.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 15, 2018/05/13, 18:57:33.184, 166758918194, testhost.dll, AssemblyResolver: Microsoft.AspNetCore.DataProtection.resources: Resolved from cache.
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:33.483, 166761913118, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Information: 0 : 19776, 15, 2018/05/13, 18:57:33.695, 166764034721, testhost.dll, AssemblyResolver: Microsoft.AspNetCore.Mvc.Abstractions.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 15, 2018/05/13, 18:57:33.696, 166764040300, testhost.dll, AssemblyResolver: Microsoft.AspNetCore.Mvc.Abstractions.resources: Failed to load assembly.
TpTrace Information: 0 : 19776, 15, 2018/05/13, 18:57:33.697, 166764047198, testhost.dll, AssemblyResolver: Microsoft.AspNetCore.Mvc.Abstractions.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 15, 2018/05/13, 18:57:33.697, 166764048973, testhost.dll, AssemblyResolver: Microsoft.AspNetCore.Mvc.Abstractions.resources: Resolved from cache.
TpTrace Information: 0 : 19776, 15, 2018/05/13, 18:57:33.856, 166765645015, testhost.dll, AssemblyResolver: Microsoft.AspNetCore.Mvc.Razor.Extensions.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 15, 2018/05/13, 18:57:33.858, 166765658994, testhost.dll, AssemblyResolver: Microsoft.AspNetCore.Mvc.Razor.Extensions.resources: Failed to load assembly.
TpTrace Information: 0 : 19776, 15, 2018/05/13, 18:57:33.859, 166765667653, testhost.dll, AssemblyResolver: Microsoft.AspNetCore.Mvc.Razor.Extensions.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 15, 2018/05/13, 18:57:33.859, 166765670113, testhost.dll, AssemblyResolver: Microsoft.AspNetCore.Mvc.Razor.Extensions.resources: Resolved from cache.
TpTrace Information: 0 : 19776, 15, 2018/05/13, 18:57:33.880, 166765876898, testhost.dll, AssemblyResolver: Microsoft.AspNetCore.Razor.Language.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 15, 2018/05/13, 18:57:33.880, 166765881360, testhost.dll, AssemblyResolver: Microsoft.AspNetCore.Razor.Language.resources: Failed to load assembly.
TpTrace Information: 0 : 19776, 15, 2018/05/13, 18:57:33.881, 166765885919, testhost.dll, AssemblyResolver: Microsoft.AspNetCore.Razor.Language.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 15, 2018/05/13, 18:57:33.881, 166765887457, testhost.dll, AssemblyResolver: Microsoft.AspNetCore.Razor.Language.resources: Resolved from cache.
TpTrace Information: 0 : 19776, 8, 2018/05/13, 18:57:34.205, 166769135235, testhost.dll, Sending test run statistics
TpTrace Verbose: 0 : 19776, 8, 2018/05/13, 18:57:34.207, 166769147406, testhost.dll, TestRequestHandler.SendData: sending data from testhost: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[{"TestCase":{"Id":"ff8be433-4fac-3a74-d0ed-9c3c2b739dff","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0080000","StartTime":"2018-05-13T18:57:32.6966136+01:00","EndTime":"2018-05-13T18:57:32.6966228+01:00","Properties":[]},{"TestCase":{"Id":"5df23517-73eb-9afb-9766-bad7c3722b0e","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0020000","StartTime":"2018-05-13T18:57:32.6975445+01:00","EndTime":"2018-05-13T18:57:32.6975503+01:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":11,"Stats":{"Passed":11}},"ActiveTests":[]}}
TpTrace Verbose: 0 : 19776, 8, 2018/05/13, 18:57:34.207, 166769155398, testhost.dll, TestRunCache: OnNewTestResult: Notified the onCacheHit callback.
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:34.484, 166771922851, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:35.484, 166781923509, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Information: 0 : 19776, 8, 2018/05/13, 18:57:35.708, 166784156613, testhost.dll, Sending test run statistics
TpTrace Verbose: 0 : 19776, 8, 2018/05/13, 18:57:35.764, 166784722167, testhost.dll, TestRequestHandler.SendData: sending data from testhost: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[],"TestRunStatistics":{"ExecutedTests":11,"Stats":{"Passed":11}},"ActiveTests":[{"Id":"518ec267-5766-4ed4-70ee-2b5ea0aec60b","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]}]}}
TpTrace Verbose: 0 : 19776, 8, 2018/05/13, 18:57:35.765, 166784726774, testhost.dll, TestRunCache: OnNewTestResult: Notified the onCacheHit callback.
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:36.485, 166791933203, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:37.485, 166801932994, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:38.486, 166811943061, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:39.487, 166821952912, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:40.487, 166831954543, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:41.487, 166841954581, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:42.487, 166851954737, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:43.488, 166861964567, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:44.489, 166871966010, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:45.489, 166881974244, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:46.490, 166891983029, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:47.490, 166901983784, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:48.491, 166911986557, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:49.491, 166921991438, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:50.491, 166931992283, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:51.492, 166941995803, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Information: 0 : 19776, 20, 2018/05/13, 18:57:51.616, 166943244043, testhost.dll, AssemblyResolver: MyApplication.Domain.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 20, 2018/05/13, 18:57:51.617, 166943246805, testhost.dll, AssemblyResolver: MyApplication.Domain.resources: Resolved from cache.
TpTrace Information: 0 : 19776, 20, 2018/05/13, 18:57:52.088, 166947960138, testhost.dll, AssemblyResolver: Microsoft.CSharp.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 20, 2018/05/13, 18:57:52.088, 166947963583, testhost.dll, AssemblyResolver: Microsoft.CSharp.resources: Failed to load assembly.
TpTrace Information: 0 : 19776, 20, 2018/05/13, 18:57:52.089, 166947968073, testhost.dll, AssemblyResolver: Microsoft.CSharp.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 20, 2018/05/13, 18:57:52.089, 166947969322, testhost.dll, AssemblyResolver: Microsoft.CSharp.resources: Resolved from cache.
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:52.493, 166952006311, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:53.493, 166962006641, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:54.493, 166972011505, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:55.493, 166982011441, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:56.494, 166992022947, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:57.495, 167002032320, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:58.496, 167012042948, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:57:59.497, 167022048685, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:00.497, 167032049289, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:01.497, 167042055151, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:02.498, 167052064284, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:03.499, 167062074068, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:04.499, 167072074026, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Information: 0 : 19776, 8, 2018/05/13, 18:58:05.101, 167078089004, testhost.dll, AssemblyResolver: System.ComponentModel.TypeConverter.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 8, 2018/05/13, 18:58:05.102, 167078104975, testhost.dll, AssemblyResolver: System.ComponentModel.TypeConverter.resources: Failed to load assembly.
TpTrace Information: 0 : 19776, 8, 2018/05/13, 18:58:05.104, 167078124679, testhost.dll, AssemblyResolver: System.ComponentModel.TypeConverter.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 8, 2018/05/13, 18:58:05.105, 167078129371, testhost.dll, AssemblyResolver: System.ComponentModel.TypeConverter.resources: Resolved from cache.
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:05.500, 167082084062, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.426, 167091337735, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.426, 167091342909, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Failed to load assembly.
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.443, 167091507295, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.443, 167091510125, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolved from cache.
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.451, 167091593006, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.452, 167091595587, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolved from cache.
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.461, 167091694175, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.462, 167091697257, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolved from cache.
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.485, 167091930978, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.485, 167091933630, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolved from cache.
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.486, 167091943865, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.487, 167091945586, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolved from cache.
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.487, 167091954420, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.488, 167091956376, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolved from cache.
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.489, 167091967208, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.489, 167091969434, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolved from cache.
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.494, 167092025271, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.495, 167092027548, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolved from cache.
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:06.500, 167092085226, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.507, 167092153711, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.508, 167092157902, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolved from cache.
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.541, 167092494369, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.542, 167092497777, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolved from cache.
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.554, 167092623794, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 6, 2018/05/13, 18:58:06.555, 167092627601, testhost.dll, AssemblyResolver: MyApplication.Tests.resources: Resolved from cache.
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:07.501, 167102095076, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:08.502, 167112105462, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Information: 0 : 19776, 8, 2018/05/13, 18:58:09.042, 167117502345, testhost.dll, AssemblyResolver: Microsoft.AspNetCore.Diagnostics.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 8, 2018/05/13, 18:58:09.043, 167117509410, testhost.dll, AssemblyResolver: Microsoft.AspNetCore.Diagnostics.resources: Failed to load assembly.
TpTrace Information: 0 : 19776, 8, 2018/05/13, 18:58:09.044, 167117516909, testhost.dll, AssemblyResolver: Microsoft.AspNetCore.Diagnostics.resources: Resolving assembly.
TpTrace Information: 0 : 19776, 8, 2018/05/13, 18:58:09.044, 167117519051, testhost.dll, AssemblyResolver: Microsoft.AspNetCore.Diagnostics.resources: Resolved from cache.
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:09.503, 167122113948, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:10.503, 167132114181, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:11.504, 167142115870, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:12.503, 167152115331, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:13.504, 167162124223, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:14.504, 167172124804, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:15.505, 167182134086, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:16.505, 167192134924, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:58:16.563, 167192706523, testhost.dll, InProgressTests is null
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:58:16.563, 167192709983, testhost.dll, TestRunCache: No test found corresponding to testResult 'Redacted_Test_Name Passed' in inProgress list.
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:58:16.563, 167192712748, testhost.dll, InProgressTests is null
TpTrace Information: 0 : 19776, 14, 2018/05/13, 18:58:16.563, 167192715060, testhost.dll, Sending test run statistics
TpTrace Verbose: 0 : 19776, 14, 2018/05/13, 18:58:16.564, 167192722618, testhost.dll, TestRequestHandler.SendData: sending data from testhost: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[{"TestCase":{"Id":"518ec267-5766-4ed4-70ee-2b5ea0aec60b","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:42.1710000","StartTime":"2018-05-13T18:58:16.5630699+01:00","EndTime":"2018-05-13T18:58:16.5630833+01:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":12,"Stats":{"Passed":12}},"ActiveTests":[{"Id":"645ad45f-e508-016f-fee4-272a5dbfe3fe","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]}]}}
TpTrace Verbose: 0 : 19776, 14, 2018/05/13, 18:58:16.565, 167192727144, testhost.dll, TestRunCache: OnNewTestResult: Notified the onCacheHit callback.
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:17.506, 167202136016, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:18.507, 167212145809, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:19.507, 167222154980, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:20.507, 167232155270, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:21.508, 167242164854, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:22.508, 167252165232, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:23.510, 167262175811, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:24.510, 167272185203, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:25.512, 167282195555, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:26.512, 167292204698, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:27.512, 167302205165, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:28.513, 167312214723, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:29.514, 167322216005, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:30.514, 167332225413, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:31.515, 167342226416, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:32.515, 167352226222, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:33.515, 167362225963, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:34.515, 167372227463, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:35.516, 167382237435, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:36.517, 167392246187, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:37.517, 167402251501, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:38.518, 167412258904, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:39.519, 167422268015, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:40.519, 167432272081, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:41.521, 167442285411, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:42.521, 167452291874, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:43.522, 167462302002, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:44.523, 167472312016, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:45.523, 167482314353, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:46.523, 167492315412, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:47.523, 167502314719, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:58:47.540, 167502477460, testhost.dll, InProgressTests is null
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:58:47.540, 167502480541, testhost.dll, TestRunCache: No test found corresponding to testResult 'Redacted_Test_Name Passed' in inProgress list.
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:58:47.541, 167502485933, testhost.dll, InProgressTests is null
TpTrace Information: 0 : 19776, 14, 2018/05/13, 18:58:47.541, 167502489873, testhost.dll, Sending test run statistics
TpTrace Verbose: 0 : 19776, 14, 2018/05/13, 18:58:47.546, 167502536989, testhost.dll, TestRequestHandler.SendData: sending data from testhost: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[{"TestCase":{"Id":"645ad45f-e508-016f-fee4-272a5dbfe3fe","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:30.9790000","StartTime":"2018-05-13T18:58:47.5398207+01:00","EndTime":"2018-05-13T18:58:47.5398354+01:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":13,"Stats":{"Passed":13}},"ActiveTests":[{"Id":"2115c715-f67f-c9d6-4dda-61545d6b7f11","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]}]}}
TpTrace Verbose: 0 : 19776, 14, 2018/05/13, 18:58:47.546, 167502542250, testhost.dll, TestRunCache: OnNewTestResult: Notified the onCacheHit callback.
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:58:47.734, 167504420109, testhost.dll, InProgressTests is null
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:58:47.734, 167504424841, testhost.dll, TestRunCache: No test found corresponding to testResult 'Redacted_Test_Name Passed' in inProgress list.
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:58:47.736, 167504436458, testhost.dll, InProgressTests is null
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:58:47.778, 167504858060, testhost.dll, InProgressTests is null
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:58:47.805, 167505133069, testhost.dll, InProgressTests is null
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:58:47.844, 167505516387, testhost.dll, InProgressTests is null
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:58:47.874, 167505819161, testhost.dll, InProgressTests is null
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:48.524, 167512322508, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Information: 0 : 19776, 8, 2018/05/13, 18:58:49.059, 167517673320, testhost.dll, Sending test run statistics
TpTrace Verbose: 0 : 19776, 8, 2018/05/13, 18:58:49.062, 167517697601, testhost.dll, TestRequestHandler.SendData: sending data from testhost: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[{"TestCase":{"Id":"2115c715-f67f-c9d6-4dda-61545d6b7f11","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.1810000","StartTime":"2018-05-13T18:58:47.7344297+01:00","EndTime":"2018-05-13T18:58:47.7344428+01:00","Properties":[]},{"TestCase":{"Id":"24ffff36-79f2-94e6-e085-50f112f1f510","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0390000","StartTime":"2018-05-13T18:58:47.7781443+01:00","EndTime":"2018-05-13T18:58:47.7781545+01:00","Properties":[]},{"TestCase":{"Id":"ef038343-28ee-8b09-d27c-868e8e816b25","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0270000","StartTime":"2018-05-13T18:58:47.805601+01:00","EndTime":"2018-05-13T18:58:47.80561+01:00","Properties":[]},{"TestCase":{"Id":"532a7ac5-ba14-98f2-2ee0-052c8b27523f","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0380000","StartTime":"2018-05-13T18:58:47.8439777+01:00","EndTime":"2018-05-13T18:58:47.8439899+01:00","Properties":[]},{"TestCase":{"Id":"adaf9ed0-1d6c-9ce5-feae-0065dea9c8f1","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:00.0300000","StartTime":"2018-05-13T18:58:47.8742497+01:00","EndTime":"2018-05-13T18:58:47.8742616+01:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":18,"Stats":{"Passed":18}},"ActiveTests":[{"Id":"ba599226-35ec-d3ed-dfee-4c2ed8dc3845","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]}]}}
TpTrace Verbose: 0 : 19776, 8, 2018/05/13, 18:58:49.063, 167517707608, testhost.dll, TestRunCache: OnNewTestResult: Notified the onCacheHit callback.
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:49.524, 167522322924, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:50.526, 167532342098, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:51.526, 167542342150, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:52.527, 167552352164, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:53.527, 167562352226, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:54.528, 167572362609, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:55.528, 167582363207, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:56.529, 167592371949, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:57.529, 167602372847, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:58.529, 167612374892, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:58:59.531, 167622394175, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:00.533, 167632414143, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:01.534, 167642416615, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:02.536, 167652435493, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:03.537, 167662454504, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:04.537, 167672455242, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:05.539, 167682465737, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:06.540, 167692484834, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:07.541, 167702493723, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:08.541, 167712494571, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:09.542, 167722505338, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:10.543, 167732514776, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:11.544, 167742524635, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:12.544, 167752525249, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:13.546, 167762543897, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:59:14.284, 167769919169, testhost.dll, InProgressTests is null
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:59:14.284, 167769922665, testhost.dll, TestRunCache: No test found corresponding to testResult 'Redacted_Test_Name Passed' in inProgress list.
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:59:14.285, 167769931073, testhost.dll, InProgressTests is null
TpTrace Information: 0 : 19776, 14, 2018/05/13, 18:59:14.286, 167769941926, testhost.dll, Sending test run statistics
TpTrace Verbose: 0 : 19776, 14, 2018/05/13, 18:59:14.288, 167769962932, testhost.dll, TestRequestHandler.SendData: sending data from testhost: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[{"TestCase":{"Id":"ba599226-35ec-d3ed-dfee-4c2ed8dc3845","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[],"ComputerName":"MyComputerName","Duration":"00:00:26.3920000","StartTime":"2018-05-13T18:59:14.2840844+01:00","EndTime":"2018-05-13T18:59:14.2840979+01:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":19,"Stats":{"Passed":19}},"ActiveTests":[{"Id":"fffc9348-a492-55a0-f1c6-56c9f3b83aea","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]}]}}
TpTrace Verbose: 0 : 19776, 14, 2018/05/13, 18:59:14.289, 167769973046, testhost.dll, TestRunCache: OnNewTestResult: Notified the onCacheHit callback.
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:59:14.293, 167770016184, testhost.dll, [xUnit.net 00:01:48.3899210] Redacted_Test_Name [SKIP]
TpTrace Verbose: 0 : 19776, 14, 2018/05/13, 18:59:14.294, 167770019634, testhost.dll, TestRequestHandler.SendData: sending data from testhost: {"Version":2,"MessageType":"TestSession.Message","Payload":{"MessageLevel":1,"Message":"[xUnit.net 00:01:48.3899210] Redacted_Test_Name [SKIP]"}}
TpTrace Information: 0 : 19776, 14, 2018/05/13, 18:59:14.295, 167770034855, testhost.dll, [xUnit.net 00:01:48.3918223] This check is only enforced for non-Development environments.
TpTrace Verbose: 0 : 19776, 14, 2018/05/13, 18:59:14.296, 167770038197, testhost.dll, TestRequestHandler.SendData: sending data from testhost: {"Version":2,"MessageType":"TestSession.Message","Payload":{"MessageLevel":0,"Message":"[xUnit.net 00:01:48.3918223] This check is only enforced for non-Development environments."}}
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:59:14.299, 167770073258, testhost.dll, InProgressTests is null
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:59:14.301, 167770089822, testhost.dll, TestRunCache: No test found corresponding to testResult 'Redacted_Test_Name Skipped
Test Messages:
StdOutMsgs:
This check is only enforced for non-Development environments.' in inProgress list.
TpTrace Warning: 0 : 19776, 14, 2018/05/13, 18:59:14.301, 167770094593, testhost.dll, InProgressTests is null
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:14.546, 167772545781, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:15.548, 167782563544, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Information: 0 : 19776, 28, 2018/05/13, 18:59:15.798, 167785064522, testhost.dll, Sending test run statistics
TpTrace Verbose: 0 : 19776, 28, 2018/05/13, 18:59:15.803, 167785117680, testhost.dll, TestRequestHandler.SendData: sending data from testhost: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[{"TestCase":{"Id":"fffc9348-a492-55a0-f1c6-56c9f3b83aea","FullyQualifiedName":"Redacted_Test_Name","DisplayName":"Redacted_Test_Name","ExecutorUri":"executor://xunit/VsTestRunner2/netcoreapp","Source":"C:\\Coding\\MyApplication\\artifacts\\MyApplication.Tests.dll","CodeFilePath":null,"LineNumber":-1,"Properties":[{"Key":{"Id":"TestObject.Traits","Label":"Traits","Category":"","Description":"","Attributes":5,"ValueType":"System.Collections.Generic.KeyValuePair`2[[System.String],[System.String]][]"},"Value":[{"Key":"Category","Value":"Integration"},{"Key":"Category","Value":"Processing"}]}]},"Attachments":[],"Outcome":3,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Redacted_Test_Name","Messages":[{"Category":"StdOutMsgs","Text":"This check is only enforced for non-Development environments."}],"ComputerName":"MyComputerName","Duration":"00:00:00.0010000","StartTime":"2018-05-13T18:59:14.2981615+01:00","EndTime":"2018-05-13T18:59:14.2981748+01:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":20,"Stats":{"Passed":19,"Skipped":1}},"ActiveTests":[]}}
TpTrace Verbose: 0 : 19776, 28, 2018/05/13, 18:59:15.805, 167785128619, testhost.dll, TestRunCache: OnNewTestResult: Notified the onCacheHit callback.
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:16.548, 167792563692, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:17.548, 167802564342, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:18.549, 167812573487, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:19.549, 167822577631, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:20.550, 167832584871, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:21.551, 167842592768, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:22.552, 167852602369, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:23.552, 167862602537, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:24.552, 167872602282, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:25.552, 167882612130, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:26.553, 167892614465, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:27.554, 167902632710, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:28.555, 167912639036, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:29.556, 167922647981, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:30.556, 167932654465, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:31.557, 167942664028, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:32.557, 167952665038, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:33.558, 167962672221, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:34.559, 167972677393, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:35.559, 167982686232, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:36.560, 167992688662, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:37.561, 168002698176, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:38.561, 168012699679, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:39.562, 168022710830, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:40.563, 168032726456, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:41.563, 168042726793, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:42.564, 168052737429, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:43.565, 168062749512, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:44.566, 168072760359, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:45.567, 168082769577, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:46.569, 168092789817, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:47.571, 168102808200, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:48.571, 168112810655, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:49.572, 168122818286, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:50.573, 168132827967, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:51.573, 168142828056, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:52.574, 168152838149, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:53.574, 168162838421, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:54.574, 168172838509, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:55.575, 168182847848, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:56.575, 168192847945, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:57.575, 168202848781, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:58.576, 168212858418, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 18:59:59.577, 168222868130, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:00.577, 168232868465, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:01.577, 168242869221, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:02.578, 168252878873, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:03.580, 168262898483, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:04.580, 168272908378, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:05.581, 168282918750, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:06.583, 168292929630, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:07.583, 168302935458, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:08.583, 168312938417, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:09.584, 168322947472, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:10.585, 168332957477, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:11.586, 168342967839, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:12.587, 168352976937, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:13.587, 168362978026, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:14.587, 168372977712, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:15.588, 168382987040, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:16.589, 168392998175, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:17.590, 168403007065, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:18.590, 168413007223, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:19.591, 168423017663, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:20.592, 168433026795, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:21.593, 168443036668, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:22.593, 168453037043, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:23.593, 168463038126, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:24.594, 168473048762, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:25.594, 168483048136, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:26.594, 168493052807, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:27.595, 168503059888, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:28.595, 168513066855, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:29.595, 168523068876, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:30.596, 168533074174, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:31.597, 168543083115, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:32.597, 168553084830, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:33.597, 168563088322, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:34.598, 168573092233, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:35.598, 168583092547, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:36.598, 168593093467, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:37.599, 168603102579, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:38.599, 168613103112, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:39.599, 168623112422, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:40.599, 168633113249, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:41.600, 168643122698, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:42.601, 168653133515, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:43.602, 168663142495, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:44.602, 168673143575, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:45.603, 168683152568, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:46.604, 168693159133, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:47.605, 168703168401, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:48.606, 168713177580, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:49.606, 168723177502, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:50.606, 168733177712, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:51.606, 168743187599, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:52.607, 168753197413, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:53.607, 168763197562, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:54.607, 168773198132, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:55.607, 168783197629, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:56.608, 168793201505, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:57.608, 168803203582, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:58.608, 168813209234, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:00:59.609, 168823214365, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:00.610, 168833222851, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:01.610, 168843223250, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:02.610, 168853228143, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:03.611, 168863233135, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:04.611, 168873240203, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:05.611, 168883240849, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:06.613, 168893262628, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:07.615, 168903283240, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:08.617, 168913298062, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:09.618, 168923310536, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:10.618, 168933310356, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:11.619, 168943320539, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:12.620, 168953331707, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:13.622, 168963350157, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:14.623, 168973361228, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:15.624, 168983370337, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:16.625, 168993380601, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:17.626, 169003389488, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:18.626, 169013391534, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:19.626, 169023396954, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:20.627, 169033406350, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:21.627, 169043409252, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:22.628, 169053414317, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:23.628, 169063418813, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:24.629, 169073421416, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:25.629, 169083428521, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:26.630, 169093433065, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:27.631, 169103446873, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:28.631, 169113451313, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:29.632, 169123458279, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:30.632, 169133458578, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:31.633, 169143468429, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:32.634, 169153478526, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:33.635, 169163487875, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:34.635, 169173488821, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:35.635, 169183489792, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:36.637, 169193509069, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:37.639, 169203527010, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:38.644, 169213581608, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:39.645, 169223590152, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:40.645, 169233591264, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:41.645, 169243597874, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:42.646, 169253608441, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:43.647, 169263614499, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:44.648, 169273621080, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:45.648, 169283626136, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:46.648, 169293627624, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:47.649, 169303633687, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:48.649, 169313638819, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:49.649, 169323637820, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:50.650, 169333644120, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:51.650, 169343646440, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:52.651, 169353654103, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:53.651, 169363661251, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:54.653, 169373679288, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:55.653, 169383679513, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:56.653, 169393680104, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:57.653, 169403684780, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:58.654, 169413691185, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:01:59.654, 169423692051, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:00.654, 169433692213, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:01.655, 169443696502, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:02.655, 169453699058, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:03.655, 169463706766, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:04.655, 169473707601, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:05.656, 169483717093, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:06.657, 169493723208, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:07.658, 169503732463, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:08.659, 169513741967, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:09.659, 169523742267, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:10.659, 169533742390, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:11.659, 169543747967, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:12.660, 169553756790, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:13.660, 169563760621, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:14.661, 169573764356, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:15.661, 169583766569, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:16.662, 169593775667, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:17.663, 169603784095, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:18.663, 169613790076, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:19.663, 169623792323, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:20.664, 169633796746, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:21.664, 169643803553, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:22.665, 169653805541, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:23.665, 169663808385, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:24.665, 169673814184, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:25.666, 169683819846, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:26.667, 169693829921, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:27.667, 169703829797, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:28.668, 169713840374, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:29.668, 169723840314, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:30.669, 169733850398, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:31.671, 169743870487, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:32.672, 169753880182, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:33.674, 169763900300, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:34.674, 169773900596, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:35.674, 169783905854, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:36.674, 169793911050, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:37.674, 169803910421, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:38.675, 169813912785, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:39.676, 169823925863, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:40.676, 169833923003, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:41.676, 169843932755, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:42.676, 169853932939, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:43.678, 169863944655, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:44.679, 169873962122, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:45.680, 169883972258, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:46.681, 169893977222, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:47.683, 169903997298, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:48.683, 169913997190, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:49.683, 169923997274, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:50.683, 169934006436, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:51.684, 169944016722, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:52.684, 169954016342, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:53.684, 169964017708, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:54.685, 169974021805, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:55.686, 169984031826, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:56.686, 169994032537, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:57.686, 170004036313, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:58.687, 170014045213, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:02:59.687, 170024047844, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:00.688, 170034057536, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:01.688, 170044058350, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:02.689, 170054068362, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:03.689, 170064068078, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:04.689, 170074069180, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:05.689, 170084068979, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:06.689, 170094069909, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:07.689, 170104072567, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:08.690, 170114077125, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:09.691, 170124084983, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:10.691, 170134087448, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:11.691, 170144092597, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:12.692, 170154100766, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:13.693, 170164107032, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:14.693, 170174114074, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:15.694, 170184117240, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:16.694, 170194119459, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:17.694, 170204123729, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:18.695, 170214135559, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:19.696, 170224140462, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:20.696, 170234146241, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:21.697, 170244152484, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:22.698, 170254162223, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:23.699, 170264171813, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:24.700, 170274181685, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:25.700, 170284182783, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:26.700, 170294183821, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:27.701, 170304193581, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:28.701, 170314200723, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:29.702, 170324208038, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:30.702, 170334211434, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:31.702, 170344212723, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:32.703, 170354217504, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:33.703, 170364217505, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:34.704, 170374227289, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:35.704, 170384233343, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:36.704, 170394234090, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:37.705, 170404244181, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:38.705, 170414244158, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:39.705, 170424244893, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:40.706, 170434248698, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:41.706, 170444248199, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:42.706, 170454248242, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:43.706, 170464248164, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:44.706, 170474258078, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:45.707, 170484259510, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:46.706, 170494259279, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:47.708, 170504269938, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:48.709, 170514288940, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:49.709, 170524289855, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:50.710, 170534300660, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:51.712, 170544319666, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:52.721, 170554404856, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:53.721, 170564409628, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:54.722, 170574419053, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:55.722, 170584419554, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:56.722, 170594419655, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:57.723, 170604429314, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:58.724, 170614439101, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:03:59.724, 170624438931, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:00.724, 170634442164, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:01.724, 170644444346, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:02.725, 170654449416, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:03.725, 170664455008, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:04.726, 170674462136, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:05.727, 170684467705, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:06.726, 170694467334, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:07.727, 170704472629, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:08.728, 170714478615, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:09.728, 170724485359, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:10.728, 170734488837, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:11.729, 170744490882, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:12.729, 170754492598, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:13.729, 170764494786, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:14.730, 170774502775, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:15.730, 170784509811, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:16.731, 170794520803, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:17.732, 170804529547, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:18.733, 170814539882, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:19.734, 170824549596, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:20.735, 170834555508, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:21.737, 170844579096, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:22.737, 170854579824, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:23.738, 170864589720, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:24.738, 170874591623, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:25.739, 170884601359, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:26.740, 170894609760, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:27.741, 170904619737, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:28.741, 170914621363, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:29.741, 170924621761, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:30.742, 170934626813, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:31.742, 170944626432, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:32.742, 170954632015, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:33.742, 170964632076, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:34.742, 170974635225, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:35.743, 170984637863, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:36.744, 170994656146, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:37.744, 171004656133, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:38.745, 171014661556, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:39.746, 171024670411, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:40.746, 171034670507, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:41.747, 171044680296, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:42.747, 171054682791, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:43.747, 171064685951, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:44.747, 171074686735, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:45.748, 171084693299, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:46.749, 171094699766, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:47.750, 171104711645, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:48.750, 171114716116, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:49.751, 171124723999, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:50.752, 171134732402, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:51.752, 171144739130, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:52.753, 171154748061, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:53.753, 171164748598, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:54.754, 171174757915, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:55.755, 171184767962, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:56.756, 171194775314, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:57.756, 171204780020, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:58.757, 171214787188, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:04:59.758, 171224793555, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:00.757, 171234793305, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:01.758, 171244798988, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:02.759, 171254808542, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:03.760, 171264822759, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:04.761, 171274833040, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:05.762, 171284835666, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:06.762, 171294843202, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:07.763, 171304853250, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:08.765, 171314871800, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:09.765, 171324871972, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:10.765, 171334875444, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:11.766, 171344877605, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:12.766, 171354881422, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:13.768, 171364902624, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:14.769, 171374912854, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:15.770, 171384922107, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:16.771, 171394930916, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:17.773, 171404950481, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:18.774, 171414959852, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:19.774, 171424959828, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:20.775, 171434970075, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:21.776, 171444980125, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:22.776, 171454989278, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:23.777, 171464990298, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:24.778, 171475006922, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:25.779, 171485011834, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:26.779, 171495014796, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:27.779, 171505020055, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:28.780, 171515028396, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:29.780, 171525029970, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:30.781, 171535036461, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:31.781, 171545040804, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:32.781, 171555041731, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:33.781, 171565041477, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:34.782, 171575046907, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:35.783, 171585055173, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:36.783, 171595056589, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:37.784, 171605066266, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:38.784, 171615066632, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:39.785, 171625076025, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:40.786, 171635086347, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:41.786, 171645092646, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:42.787, 171655096522, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:43.787, 171665096732, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:44.787, 171675097227, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:45.787, 171685100914, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:46.788, 171695109282, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:47.788, 171705114735, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:48.789, 171715121322, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:49.790, 171725131999, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:50.792, 171735157117, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:51.793, 171745163390, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:52.793, 171755165396, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:53.794, 171765171125, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:54.794, 171775174784, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:55.794, 171785177390, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:56.796, 171795196949, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:57.797, 171805208058, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:58.798, 171815212558, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:05:59.799, 171825225886, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:00.799, 171835230285, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:01.800, 171845232206, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:02.800, 171855237053, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:03.800, 171865236802, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:04.800, 171875236945, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:05.800, 171885238810, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:06.802, 171895258636, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:07.804, 171905276594, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:08.804, 171915278125, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:09.805, 171925286950, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:10.805, 171935292337, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:11.806, 171945297217, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:12.806, 171955300351, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:13.807, 171965306521, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:14.807, 171975310580, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:15.807, 171985311828, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:16.808, 171995317049, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:17.808, 172005317957, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:18.809, 172015328344, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:19.809, 172025335625, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:20.810, 172035341362, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:21.811, 172045351108, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:22.811, 172055351047, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:23.811, 172065353224, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:24.812, 172075358936, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:25.813, 172085368473, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:26.813, 172095369020, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:27.813, 172105378378, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:28.814, 172115388374, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:29.815, 172125398639, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:30.816, 172135407841, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:31.816, 172145408608, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:32.817, 172155419047, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:33.819, 172165440032, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:34.820, 172175450555, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:35.822, 172185468825, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:36.823, 172195478675, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:37.824, 172205488331, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:38.825, 172215497647, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:39.825, 172225498815, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:40.825, 172235499108, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:41.825, 172245499764, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:42.826, 172255507122, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:43.827, 172265517592, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:44.827, 172275522150, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:45.827, 172285522818, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:46.827, 172295523888, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:47.828, 172305531618, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:48.828, 172315532507, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:49.829, 172325535797, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:50.829, 172335540383, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:51.829, 172345544711, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:52.829, 172355545335, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:53.830, 172365548349, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:54.831, 172375558710, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:55.832, 172385568603, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:56.833, 172395582529, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:57.833, 172405586432, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:58.834, 172415592666, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:06:59.835, 172425598485, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:00.835, 172435602943, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:01.835, 172445608047, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:02.836, 172455609279, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:03.836, 172465609604, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:04.838, 172475629669, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:05.839, 172485643897, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:06.840, 172495652385, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:07.840, 172505652598, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:08.841, 172515660999, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:09.841, 172525665833, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:10.841, 172535670996, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:11.842, 172545676373, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:12.843, 172555683020, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:13.843, 172565684452, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:14.843, 172575685489, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:15.844, 172585693190, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:16.844, 172595702301, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:17.844, 172605702078, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:18.845, 172615708265, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:19.845, 172625709132, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:20.845, 172635713207, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:21.624, 172643498174, testhost.dll, TcpClientExtensions.MessageLoopAsync: NotifyDataAvailable remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Information: 0 : 19776, 9, 2018/05/13, 19:07:21.625, 172643506077, testhost.dll, TestRequestHandler.ProcessRequests: received message: (TestExecution.Abort) -> null
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:21.626, 172643516851, testhost.dll, BaseRunTests.Abort: Calling RaiseTestRunComplete
TpTrace Information: 0 : 19776, 9, 2018/05/13, 19:07:21.629, 172643550407, testhost.dll, Sending test run complete
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:21.645, 172643709647, testhost.dll, TestRequestHandler.SendData: sending data from testhost: {"Version":2,"MessageType":"TestExecution.Completed","Payload":{"TestRunCompleteArgs":{"TestRunStatistics":{"ExecutedTests":20,"Stats":{"Passed":19,"Skipped":1}},"IsCanceled":false,"IsAborted":true,"Error":null,"AttachmentSets":[],"ElapsedTimeInRunningTests":"00:00:00","Metrics":{}},"LastRunTests":{"NewTestResults":[],"TestRunStatistics":{"ExecutedTests":20,"Stats":{"Passed":19,"Skipped":1}},"ActiveTests":[]},"RunAttachments":[],"ExecutorUris":[]}}
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:21.645, 172643713018, testhost.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:21.647, 172643732656, testhost.dll, TcpClientExtensions.MessageLoopAsync: NotifyDataAvailable remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
TpTrace Information: 0 : 19776, 9, 2018/05/13, 19:07:21.648, 172643736750, testhost.dll, TestRequestHandler.ProcessRequests: received message: (TestSession.Terminate) -> null
TpTrace Information: 0 : 19776, 9, 2018/05/13, 19:07:21.648, 172643738603, testhost.dll, Session End message received from server. Closing the connection.
TpTrace Information: 0 : 19776, 9, 2018/05/13, 19:07:21.649, 172643749504, testhost.dll, SocketClient.Stop: Stop communication from server endpoint: 127.0.0.1:061147
TpTrace Information: 0 : 19776, 9, 2018/05/13, 19:07:21.650, 172643754343, testhost.dll, SocketClient: Stop: Cancellation requested. Stopping message loop.
TpTrace Information: 0 : 19776, 1, 2018/05/13, 19:07:21.649, 172643749820, testhost.dll, SocketClient.Stop: Stop communication from server endpoint: 127.0.0.1:061147
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:21.650, 172643762243, testhost.dll, LengthPrefixCommunicationChannel.Dispose: Dispose reader and writer.
TpTrace Information: 0 : 19776, 9, 2018/05/13, 19:07:21.651, 172643766310, testhost.dll, Closing the connection !
TpTrace Information: 0 : 19776, 1, 2018/05/13, 19:07:21.651, 172643764220, testhost.dll, SocketClient: Stop: Cancellation requested. Stopping message loop.
TpTrace Verbose: 0 : 19776, 1, 2018/05/13, 19:07:21.651, 172643773064, testhost.dll, LengthPrefixCommunicationChannel.Dispose: Dispose reader and writer.
TpTrace Information: 0 : 19776, 9, 2018/05/13, 19:07:21.652, 172643775512, testhost.dll, SocketClient.PrivateStop: Stop communication from server endpoint: 127.0.0.1:061147, error:
TpTrace Information: 0 : 19776, 1, 2018/05/13, 19:07:21.652, 172643777977, testhost.dll, Testhost process exiting.
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:21.653, 172643785393, testhost.dll, LengthPrefixCommunicationChannel.Dispose: Dispose reader and writer.
TpTrace Verbose: 0 : 19776, 9, 2018/05/13, 19:07:21.653, 172643788211, testhost.dll, TcpClientExtensions.MessageLoopAsync: exiting MessageLoopAsync remoteEndPoint: 127.0.0.1:61147 localEndPoint: 127.0.0.1:61148
Environment
Issue is occurring both on my laptop running Windows 10 Version 1803 (OS Build 17134.1) as well as on a TeamCity hosted build agent running Windows Server 2016.
Output from dotnet --info on my laptop:
.NET Core SDK (reflecting any global.json):
Version: 2.1.300-rc1-008673
Commit: f5e3ddbe73
Runtime Environment:
OS Name: Windows
OS Version: 10.0.17134
OS Platform: Windows
RID: win10-x64
Base Path: C:\Program Files\dotnet\sdk\2.1.300-rc1-008673\
Host (useful for support):
Version: 2.1.0-rc1
Commit: eb9bc92051
.NET Core SDKs installed:
1.0.0 [C:\Program Files\dotnet\sdk]
1.0.1 [C:\Program Files\dotnet\sdk]
1.0.2 [C:\Program Files\dotnet\sdk]
1.0.3 [C:\Program Files\dotnet\sdk]
1.0.4 [C:\Program Files\dotnet\sdk]
1.1.0 [C:\Program Files\dotnet\sdk]
2.0.0 [C:\Program Files\dotnet\sdk]
2.0.2 [C:\Program Files\dotnet\sdk]
2.0.3 [C:\Program Files\dotnet\sdk]
2.1.2 [C:\Program Files\dotnet\sdk]
2.1.3 [C:\Program Files\dotnet\sdk]
2.1.4 [C:\Program Files\dotnet\sdk]
2.1.100 [C:\Program Files\dotnet\sdk]
2.1.101 [C:\Program Files\dotnet\sdk]
2.1.102 [C:\Program Files\dotnet\sdk]
2.1.103 [C:\Program Files\dotnet\sdk]
2.1.104 [C:\Program Files\dotnet\sdk]
2.1.200 [C:\Program Files\dotnet\sdk]
2.1.300-preview1-008174 [C:\Program Files\dotnet\sdk]
2.1.300-preview2-008530 [C:\Program Files\dotnet\sdk]
2.1.300-rc1-008673 [C:\Program Files\dotnet\sdk]
.NET Core runtimes installed:
Microsoft.AspNetCore.All 2.1.0-preview1-final [C:\Program Files\dotnet\shared\Microsoft.AspNetCore.All]
Microsoft.AspNetCore.All 2.1.0-preview2-final [C:\Program Files\dotnet\shared\Microsoft.AspNetCore.All]
Microsoft.AspNetCore.All 2.1.0-rc1-final [C:\Program Files\dotnet\shared\Microsoft.AspNetCore.All]
Microsoft.AspNetCore.App 2.1.0-preview1-final [C:\Program Files\dotnet\shared\Microsoft.AspNetCore.App]
Microsoft.AspNetCore.App 2.1.0-preview2-final [C:\Program Files\dotnet\shared\Microsoft.AspNetCore.App]
Microsoft.AspNetCore.App 2.1.0-rc1-final [C:\Program Files\dotnet\shared\Microsoft.AspNetCore.App]
Microsoft.NETCore.App 1.0.4 [C:\Program Files\dotnet\shared\Microsoft.NETCore.App]
Microsoft.NETCore.App 1.0.5 [C:\Program Files\dotnet\shared\Microsoft.NETCore.App]
Microsoft.NETCore.App 1.1.1 [C:\Program Files\dotnet\shared\Microsoft.NETCore.App]
Microsoft.NETCore.App 1.1.2 [C:\Program Files\dotnet\shared\Microsoft.NETCore.App]
Microsoft.NETCore.App 2.0.0 [C:\Program Files\dotnet\shared\Microsoft.NETCore.App]
Microsoft.NETCore.App 2.0.3 [C:\Program Files\dotnet\shared\Microsoft.NETCore.App]
Microsoft.NETCore.App 2.0.4 [C:\Program Files\dotnet\shared\Microsoft.NETCore.App]
Microsoft.NETCore.App 2.0.5 [C:\Program Files\dotnet\shared\Microsoft.NETCore.App]
Microsoft.NETCore.App 2.0.6 [C:\Program Files\dotnet\shared\Microsoft.NETCore.App]
Microsoft.NETCore.App 2.0.7 [C:\Program Files\dotnet\shared\Microsoft.NETCore.App]
Microsoft.NETCore.App 2.1.0-preview1-26216-03 [C:\Program Files\dotnet\shared\Microsoft.NETCore.App]
Microsoft.NETCore.App 2.1.0-preview2-26406-04 [C:\Program Files\dotnet\shared\Microsoft.NETCore.App]
Microsoft.NETCore.App 2.1.0-rc1 [C:\Program Files\dotnet\shared\Microsoft.NETCore.App]
To install additional .NET Core runtimes or SDKs:
https://aka.ms/dotnet-download
About this issue
- Original URL
- State: closed
- Created 6 years ago
- Comments: 19 (13 by maintainers)
I’m experiencing the same problem with my project based on .NET Core 2.1. One of my test projects runs normally (it contains unit tests for class library) while another one (which contains unit tests for ASP.NET Core application) is stably hangs over while test running (reproduced on Windows 7, Windows 10, Ubuntu 17.10)
I don’t have any node services in my solution, but symptoms are the same.
This is happening for me on MacOS, dotnet 2.1.4. With 2.1.300 it’s fine. These are xunit tests FWIW.