vstest.console.dll hanging for hours until our CI agent kills it

[javiercn]

On one of our latest test runs on our CI, the build hung on the test run until team city killed it.
From what I can see in the logs seems like some kind of issue within vstest.console.dll

Steps to reproduce

This happened as part of
https://github.com/aspnet/AspNetCore-Internal/issues/1217

Expected behavior

Tests runs and build completes.

Actual behavior

Build hangs while running tests.

Diagnostic logs

See below for details or above for the full log.

Environment

Microsoft.NET.Test.Sdk 15.6.1 (Not sure about this)
Win-7

Below is a brief extract of the output log at the time this starts to happen:

TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.359, 6051414685667, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55443 localEndPoint: 127.0.0.1:55440
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.401, 6051415110767, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: NotifyDataAvailable remoteEndPoint: 127.0.0.1:55443 localEndPoint: 127.0.0.1:55440
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.402, 6051415113265, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[{"TestCase":{"Id":"ca3bd764-c0b7-b689-814e-390c5e7066c8","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.RedirectToSibling_RedirectsToIndexPage_WithoutIndexSegment","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.RedirectToSibling_RedirectsToIndexPage_WithoutIndexSegment","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.RedirectToSibling_RedirectsToIndexPage_WithoutIndexSegment","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0040000","StartTime":"2018-10-23T10:09:19.3587915+00:00","EndTime":"2018-10-23T10:09:19.3587915+00:00","Properties":[]},{"TestCase":{"Id":"e729fb07-d977-59f8-47ff-7db1fab2057c","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.PageProperty_WithSupportsGetTrue_OnPageWithHandler_FuzzyMatchesHeadRequest","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.PageProperty_WithSupportsGetTrue_OnPageWithHandler_FuzzyMatchesHeadRequest","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.PageProperty_WithSupportsGetTrue_OnPageWithHandler_FuzzyMatchesHeadRequest","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0120000","StartTime":"2018-10-23T10:09:19.3607225+00:00","EndTime":"2018-10-23T10:09:19.3607225+00:00","Properties":[]},{"TestCase":{"Id":"15bac0a1-d238-4f5e-7029-900381839e75","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.Page_Handler_Async","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.Page_Handler_Async","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.Page_Handler_Async","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0080000","StartTime":"2018-10-23T10:09:19.363619+00:00","EndTime":"2018-10-23T10:09:19.363619+00:00","Properties":[]},{"TestCase":{"Id":"5717e967-9472-0b62-d6c1-8f743cd31214","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.PageFiltersAppliedToPageModel_AreExecuted","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.PageFiltersAppliedToPageModel_AreExecuted","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.PageFiltersAppliedToPageModel_AreExecuted","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0310000","StartTime":"2018-10-23T10:09:19.375205+00:00","EndTime":"2018-10-23T10:09:19.375205+00:00","Properties":[]},{"TestCase":{"Id":"5f7deec9-ed46-5906-280e-d4ec147320e7","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.AuthFiltersAppliedToPageModel_AreExecuted","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.AuthFiltersAppliedToPageModel_AreExecuted","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.AuthFiltersAppliedToPageModel_AreExecuted","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0160000","StartTime":"2018-10-23T10:09:19.379067+00:00","EndTime":"2018-10-23T10:09:19.379067+00:00","Properties":[]},{"TestCase":{"Id":"bd289d0c-e6ee-90c7-7656-61c92ff4d942","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.PolymorphicPropertiesOnPageModelsAreValidated","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.PolymorphicPropertiesOnPageModelsAreValidated","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.PolymorphicPropertiesOnPageModelsAreValidated","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0210000","StartTime":"2018-10-23T10:09:19.382929+00:00","EndTime":"2018-10-23T10:09:19.382929+00:00","Properties":[]},{"TestCase":{"Id":"d32a0879-d35a-7ec4-1710-c62a778fbe3e","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.RedirectToSibling_RedirectsToParentDirectory","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.RedirectToSibling_RedirectsToParentDirectory","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.RedirectToSibling_RedirectsToParentDirectory","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0050000","StartTime":"2018-10-23T10:09:19.3896875+00:00","EndTime":"2018-10-23T10:09:19.3896875+00:00","Properties":[]},{"TestCase":{"Id":"a9345877-8788-aaa5-0287-783a62d559e7","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.PageHandlerCanReturnBadRequest","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.PageHandlerCanReturnBadRequest","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.PageHandlerCanReturnBadRequest","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0130000","StartTime":"2018-10-23T10:09:19.3935495+00:00","EndTime":"2018-10-23T10:09:19.3935495+00:00","Properties":[]},{"TestCase":{"Id":"2b30ff0d-ffd0-ae40-df01-b23d81ecef80","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.RedirectFromPageModelWorks","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.RedirectFromPageModelWorks","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.RedirectFromPageModelWorks","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0330000","StartTime":"2018-10-23T10:09:19.3974115+00:00","EndTime":"2018-10-23T10:09:19.3974115+00:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":1437,"Stats":{"Passed":1430,"Skipped":7}},"ActiveTests":[{"Id":"84bc83da-5917-4e02-8916-580dd3869a21","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.PageModel_Handler_Handler","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.PageModel_Handler_Handler","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]}]}}
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.403, 6051415127120, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Starting.
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.403, 6051415127660, vstest.console.dll, InProgress is Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.PageModel_Handler_Handler
TpTrace Information: 0 : 11324, 6, 2018/10/23, 03:09:19.403, 6051415128026, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Completed.
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.403, 6051415128289, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55443 localEndPoint: 127.0.0.1:55440
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.440, 6051415511080, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: NotifyDataAvailable remoteEndPoint: 127.0.0.1:55443 localEndPoint: 127.0.0.1:55440
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.441, 6051415513805, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[{"TestCase":{"Id":"84bc83da-5917-4e02-8916-580dd3869a21","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.PageModel_Handler_Handler","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.PageModel_Handler_Handler","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.PageModel_Handler_Handler","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0060000","StartTime":"2018-10-23T10:09:19.4032045+00:00","EndTime":"2018-10-23T10:09:19.4032045+00:00","Properties":[]},{"TestCase":{"Id":"644d2261-2353-0875-316c-67321b567a1d","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.TempData_SetTempDataInPage_CanReadValue","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.TempData_SetTempDataInPage_CanReadValue","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorPagesTest.TempData_SetTempDataInPage_CanReadValue","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0380000","StartTime":"2018-10-23T10:09:19.4167215+00:00","EndTime":"2018-10-23T10:09:19.4167215+00:00","Properties":[]},{"TestCase":{"Id":"721ec331-8e9e-7c37-4f64-d4df9a2cd6a2","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorBuildTest.Rzc_LocalViewWithDifferentContent_IsUsed","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorBuildTest.Rzc_LocalViewWithDifferentContent_IsUsed","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorBuildTest.Rzc_LocalViewWithDifferentContent_IsUsed","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:10.9990000","StartTime":"2018-10-23T10:09:19.4186525+00:00","EndTime":"2018-10-23T10:09:19.4186525+00:00","Properties":[]},{"TestCase":{"Id":"f0881b26-1fb5-7458-c314-cba68f58b99d","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_ExecutesPageAndLayout","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_ExecutesPageAndLayout(actionName: \"ViewWithFullPath\", expected: \"<layout>\\r\\nViewWithFullPath-content\\r\\n</layout>\")","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_ExecutesPageAndLayout(actionName: \"ViewWithFullPath\", expected: \"<layout>\\r\\nViewWithFullPath-content\\r\\n</layout>\")","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:10.0060000","StartTime":"2018-10-23T10:09:19.425411+00:00","EndTime":"2018-10-23T10:09:19.425411+00:00","Properties":[]},{"TestCase":{"Id":"79054e2a-278c-e16d-5b59-52b611160c3b","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.TagHelpersFromServicesTest.TagHelpersWithConstructorInjectionAreCreatedAndActivated","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.TagHelpersFromServicesTest.TagHelpersWithConstructorInjectionAreCreatedAndActivated","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.TagHelpersFromServicesTest.TagHelpersWithConstructorInjectionAreCreatedAndActivated","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:18.7860000","StartTime":"2018-10-23T10:09:19.429273+00:00","EndTime":"2018-10-23T10:09:19.429273+00:00","Properties":[]},{"TestCase":{"Id":"15214349-dc47-bdeb-7662-b801370e2a5f","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_ExecutesPageAndLayout","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_ExecutesPageAndLayout(actionName: \"ViewWithoutLayout\", expected: \"ViewWithoutLayout-Content\")","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_ExecutesPageAndLayout(actionName: \"ViewWithoutLayout\", expected: \"ViewWithoutLayout-Content\")","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0080000","StartTime":"2018-10-23T10:09:19.4321695+00:00","EndTime":"2018-10-23T10:09:19.4321695+00:00","Properties":[]},{"TestCase":{"Id":"4140673c-4d04-af61-4b70-681bdc1fddd2","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_ExecutesPageAndLayout","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_ExecutesPageAndLayout(actionName: \"ViewWithLayout\", expected: \"<layout>\\r\\nViewWithLayout-Content\\r\\n</layout>\")","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_ExecutesPageAndLayout(actionName: \"ViewWithLayout\", expected: \"<layout>\\r\\nViewWithLayout-Content\\r\\n</layout>\")","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0080000","StartTime":"2018-10-23T10:09:19.4360315+00:00","EndTime":"2018-10-23T10:09:19.4360315+00:00","Properties":[]},{"TestCase":{"Id":"d14ef6a9-4f4f-8445-b91d-6fc7d8d9e5e7","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ErrorPageTests.RuntimeErrorAreListedByErrorPageMiddleware","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ErrorPageTests.RuntimeErrorAreListedByErrorPageMiddleware","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ErrorPageTests.RuntimeErrorAreListedByErrorPageMiddleware","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.5760000","StartTime":"2018-10-23T10:09:19.438928+00:00","EndTime":"2018-10-23T10:09:19.438928+00:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":1445,"Stats":{"Passed":1438,"Skipped":7}},"ActiveTests":[{"Id":"ccd070c7-10d3-dd8c-a105-f9c19ff9c406","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorBuildTest.Rzc_LocalPageWithDifferentContent_IsUsed","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorBuildTest.Rzc_LocalPageWithDifferentContent_IsUsed","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},{"Id":"56e3567a-4ab1-1e2d-5bbb-9dc4bd83b939","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_ExecutesPageAndLayout","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_ExecutesPageAndLayout(actionName: \"ViewWithDataFromController\", expected: \"<h1>hello from controller</h1>\")","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]}]}}
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.442, 6051415526610, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Starting.
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.442, 6051415527080, vstest.console.dll, InProgress is Microsoft.AspNetCore.Mvc.FunctionalTests.RazorBuildTest.Rzc_LocalPageWithDifferentContent_IsUsed
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.442, 6051415527350, vstest.console.dll, InProgress is Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_ExecutesPageAndLayout(actionName: "ViewWithDataFromController", expected: "<h1>hello from controller</h1>")
TpTrace Information: 0 : 11324, 6, 2018/10/23, 03:09:19.442, 6051415527688, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Completed.
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.442, 6051415527950, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55443 localEndPoint: 127.0.0.1:55440
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.477, 6051415885241, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: NotifyDataAvailable remoteEndPoint: 127.0.0.1:55443 localEndPoint: 127.0.0.1:55440
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.477, 6051415888587, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[{"TestCase":{"Id":"56e3567a-4ab1-1e2d-5bbb-9dc4bd83b939","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_ExecutesPageAndLayout","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_ExecutesPageAndLayout(actionName: \"ViewWithDataFromController\", expected: \"<h1>hello from controller</h1>\")","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_ExecutesPageAndLayout(actionName: \"ViewWithDataFromController\", expected: \"<h1>hello from controller</h1>\")","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0150000","StartTime":"2018-10-23T10:09:19.448583+00:00","EndTime":"2018-10-23T10:09:19.448583+00:00","Properties":[]},{"TestCase":{"Id":"78a47c13-a8e6-5b21-5d88-9fce9b4e0098","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_SetsViewPathAndExecutingPagePath","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_SetsViewPathAndExecutingPagePath","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_SetsViewPathAndExecutingPagePath","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0470000","StartTime":"2018-10-23T10:09:19.4514795+00:00","EndTime":"2018-10-23T10:09:19.4514795+00:00","Properties":[]},{"TestCase":{"Id":"43b993a2-3906-1b1a-2381-a8fa59770040","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersPartialViews","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersPartialViews(actionName: \"PartialWithDataFromController\", expected: \"<h1>hello from controller</h1>\")","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersPartialViews(actionName: \"PartialWithDataFromController\", expected: \"<h1>hello from controller</h1>\")","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0270000","StartTime":"2018-10-23T10:09:19.454376+00:00","EndTime":"2018-10-23T10:09:19.454376+00:00","Properties":[]},{"TestCase":{"Id":"1bc1bced-e7a8-e6cd-2d69-f4ef57f44089","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersPartialViews","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersPartialViews(actionName: \"PartialWithModel\", expected: \"my name is judge\\r\\n<partial>98052\\r\\n</partial>\")","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersPartialViews(actionName: \"PartialWithModel\", expected: \"my name is judge\\r\\n<partial>98052\\r\\n</partial>\")","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0130000","StartTime":"2018-10-23T10:09:19.458238+00:00","EndTime":"2018-10-23T10:09:19.458238+00:00","Properties":[]},{"TestCase":{"Id":"c8105144-2409-d1bd-c33c-93e131ecf28c","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersPartialViews","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersPartialViews(actionName: \"ViewWithNestedLayout\", expected: \"<layout>\\r\\n<nested-layout>\\r\\n/PartialViewEngine/\"...)","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersPartialViews(actionName: \"ViewWithNestedLayout\", expected: \"<layout>\\r\\n<nested-layout>\\r\\n/PartialViewEngine/\"...)","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0050000","StartTime":"2018-10-23T10:09:19.460169+00:00","EndTime":"2018-10-23T10:09:19.460169+00:00","Properties":[]},{"TestCase":{"Id":"ad09798a-be61-57db-dbd4-d4ac53791ee0","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersPartialViews","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersPartialViews(actionName: \"ViewWithFullPath\", expected: \"<layout>\\r\\nViewWithFullPath-content\\r\\n</layout>\")","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersPartialViews(actionName: \"ViewWithFullPath\", expected: \"<layout>\\r\\nViewWithFullPath-content\\r\\n</layout>\")","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0010000","StartTime":"2018-10-23T10:09:19.4621+00:00","EndTime":"2018-10-23T10:09:19.4621+00:00","Properties":[]},{"TestCase":{"Id":"10617474-aa72-df29-4a1d-89e33ce9db5d","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersPartialViews","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersPartialViews(actionName: \"ViewWithoutLayout\", expected: \"ViewWithoutLayout-Content\")","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersPartialViews(actionName: \"ViewWithoutLayout\", expected: \"ViewWithoutLayout-Content\")","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0250000","StartTime":"2018-10-23T10:09:19.471755+00:00","EndTime":"2018-10-23T10:09:19.471755+00:00","Properties":[]},{"TestCase":{"Id":"deee2e2b-3585-0f34-b681-dd294feb4351","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersPartialViews","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersPartialViews(actionName: \"PartialViewWithNamePassedIn\", expected: \"<layout>\\r\\nViewWithLayout-Content\\r\\n</layout>\")","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersPartialViews(actionName: \"PartialViewWithNamePassedIn\", expected: \"<layout>\\r\\nViewWithLayout-Content\\r\\n</layout>\")","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0050000","StartTime":"2018-10-23T10:09:19.473686+00:00","EndTime":"2018-10-23T10:09:19.473686+00:00","Properties":[]},{"TestCase":{"Id":"ccd070c7-10d3-dd8c-a105-f9c19ff9c406","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorBuildTest.Rzc_LocalPageWithDifferentContent_IsUsed","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorBuildTest.Rzc_LocalPageWithDifferentContent_IsUsed","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorBuildTest.Rzc_LocalPageWithDifferentContent_IsUsed","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.2500000","StartTime":"2018-10-23T10:09:19.4765825+00:00","EndTime":"2018-10-23T10:09:19.4765825+00:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":1454,"Stats":{"Passed":1447,"Skipped":7}},"ActiveTests":[{"Id":"eb05b3e9-8e22-4c3a-c63d-e9b2010bebdf","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewEngine_ResolvesPathsWithSlashesThatDoNotHaveExtensions","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewEngine_ResolvesPathsWithSlashesThatDoNotHaveExtensions","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]}]}}
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.481, 6051415913461, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Starting.
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.481, 6051415914214, vstest.console.dll, InProgress is Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewEngine_ResolvesPathsWithSlashesThatDoNotHaveExtensions
TpTrace Information: 0 : 11324, 6, 2018/10/23, 03:09:19.481, 6051415914870, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Completed.
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.481, 6051415915386, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55443 localEndPoint: 127.0.0.1:55440
TpTrace Verbose: 0 : 11324, 11, 2018/10/23, 03:09:19.491, 6051416013364, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: NotifyDataAvailable remoteEndPoint: 127.0.0.1:55441 localEndPoint: 127.0.0.1:55438
TpTrace Verbose: 0 : 11324, 11, 2018/10/23, 03:09:19.491, 6051416014897, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[{"TestCase":{"Id":"a2d071f0-d20b-f20a-2feb-333c7ad9c085","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForOverriddenNonActionMethod","DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForOverriddenNonActionMethod","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Mvc.Api.Analyzers.Test\\bin\\Release\\net461\\Mvc.Api.Analyzers.Test.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForOverriddenNonActionMethod","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.1860000","StartTime":"2018-10-23T10:09:18.7090104+00:00","EndTime":"2018-10-23T10:09:18.7090104+00:00","Properties":[]},{"TestCase":{"Id":"409cafb3-e155-5648-50fd-b5c74b8dcc39","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.ActualApiResponseMetadataFactoryTest.InspectReturnExpression_ReturnsStatusCodeFromConstructorAssignment","DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.ActualApiResponseMetadataFactoryTest.InspectReturnExpression_ReturnsStatusCodeFromConstructorAssignment","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Mvc.Api.Analyzers.Test\\bin\\Release\\net461\\Mvc.Api.Analyzers.Test.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.ActualApiResponseMetadataFactoryTest.InspectReturnExpression_ReturnsStatusCodeFromConstructorAssignment","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.1810000","StartTime":"2018-10-23T10:09:18.8152154+00:00","EndTime":"2018-10-23T10:09:18.8152154+00:00","Properties":[]},{"TestCase":{"Id":"2b55dc84-b977-e32d-d40b-f6b545127a20","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsController_ReturnsTrueForTypeWithoutSuffix_WithControllerAttribute","DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsController_ReturnsTrueForTypeWithoutSuffix_WithControllerAttribute","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Mvc.Api.Analyzers.Test\\bin\\Release\\net461\\Mvc.Api.Analyzers.Test.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsController_ReturnsTrueForTypeWithoutSuffix_WithControllerAttribute","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.1530000","StartTime":"2018-10-23T10:09:18.8605939+00:00","EndTime":"2018-10-23T10:09:18.8605939+00:00","Properties":[]},{"TestCase":{"Id":"b9c3355a-df13-d857-da14-6b5ea002a9e8","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForGenericMethod","DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForGenericMethod","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Mvc.Api.Analyzers.Test\\bin\\Release\\net461\\Mvc.Api.Analyzers.Test.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForGenericMethod","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.1270000","StartTime":"2018-10-23T10:09:18.9861089+00:00","EndTime":"2018-10-23T10:09:18.9861089+00:00","Properties":[]},{"TestCase":{"Id":"f6a5c865-54dd-4fc4-77a4-f833242a4f6e","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForNonActionMethod","DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForNonActionMethod","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Mvc.Api.Analyzers.Test\\bin\\Release\\net461\\Mvc.Api.Analyzers.Test.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForNonActionMethod","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.1150000","StartTime":"2018-10-23T10:09:19.1067962+00:00","EndTime":"2018-10-23T10:09:19.1067962+00:00","Properties":[]},{"TestCase":{"Id":"294ad433-b922-2c1a-13bd-823ef5fb0129","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForExplicitDisposableDispose","DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForExplicitDisposableDispose","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Mvc.Api.Analyzers.Test\\bin\\Release\\net461\\Mvc.Api.Analyzers.Test.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForExplicitDisposableDispose","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0880000","StartTime":"2018-10-23T10:09:19.1946567+00:00","EndTime":"2018-10-23T10:09:19.1946567+00:00","Properties":[]},{"TestCase":{"Id":"f3fd8d4d-dde9-1767-20d6-18ce34c37555","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForInternalMethod","DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForInternalMethod","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Mvc.Api.Analyzers.Test\\bin\\Release\\net461\\Mvc.Api.Analyzers.Test.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForInternalMethod","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0880000","StartTime":"2018-10-23T10:09:19.282517+00:00","EndTime":"2018-10-23T10:09:19.282517+00:00","Properties":[]},{"TestCase":{"Id":"710b53ab-ce07-e9ca-5c65-02f370365c2f","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsTrueForOverriddenMethod","DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsTrueForOverriddenMethod","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Mvc.Api.Analyzers.Test\\bin\\Release\\net461\\Mvc.Api.Analyzers.Test.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsTrueForOverriddenMethod","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0970000","StartTime":"2018-10-23T10:09:19.379067+00:00","EndTime":"2018-10-23T10:09:19.379067+00:00","Properties":[]},{"TestCase":{"Id":"0f84670e-3532-cf67-7887-219466311ddf","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForStaticConstructor","DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForStaticConstructor","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Mvc.Api.Analyzers.Test\\bin\\Release\\net461\\Mvc.Api.Analyzers.Test.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForStaticConstructor","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.1030000","StartTime":"2018-10-23T10:09:19.48141+00:00","EndTime":"2018-10-23T10:09:19.48141+00:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":180,"Stats":{"Passed":180}},"ActiveTests":[{"Id":"60320c7e-e58e-94b9-4fb1-be9f623336d8","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForStaticMethod","DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForStaticMethod","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Mvc.Api.Analyzers.Test\\bin\\Release\\net461\\Mvc.Api.Analyzers.Test.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]}]}}
TpTrace Verbose: 0 : 11324, 11, 2018/10/23, 03:09:19.492, 6051416027407, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Starting.
TpTrace Verbose: 0 : 11324, 11, 2018/10/23, 03:09:19.492, 6051416027900, vstest.console.dll, InProgress is Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForStaticMethod
TpTrace Information: 0 : 11324, 11, 2018/10/23, 03:09:19.492, 6051416028269, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Completed.
TpTrace Verbose: 0 : 11324, 11, 2018/10/23, 03:09:19.492, 6051416028537, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55441 localEndPoint: 127.0.0.1:55438
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.680, 6051417935382, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: NotifyDataAvailable remoteEndPoint: 127.0.0.1:55443 localEndPoint: 127.0.0.1:55440
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.680, 6051417938395, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[{"TestCase":{"Id":"8b225210-8288-3bb7-f9c3-846effc43a02","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorFileUpdateTests.RazorPages_AreUpdatedWhenViewImportsChange","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorFileUpdateTests.RazorPages_AreUpdatedWhenViewImportsChange","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorFileUpdateTests.RazorPages_AreUpdatedWhenViewImportsChange","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:18.0980000","StartTime":"2018-10-23T10:09:19.48141+00:00","EndTime":"2018-10-23T10:09:19.48141+00:00","Properties":[]},{"TestCase":{"Id":"968f73fd-163c-0821-8495-090ebc152aa7","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.CacheTagHelper_ExpiresContent_BasedOnExpiresParameter","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.CacheTagHelper_ExpiresContent_BasedOnExpiresParameter","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.CacheTagHelper_ExpiresContent_BasedOnExpiresParameter","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:02.0150000","StartTime":"2018-10-23T10:09:19.537409+00:00","EndTime":"2018-10-23T10:09:19.537409+00:00","Properties":[]},{"TestCase":{"Id":"421d2c11-3adb-9184-21b3-298acf72fe93","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.PartialTagHelper_AllowsUsingFallback","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.PartialTagHelper_AllowsUsingFallback","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.PartialTagHelper_AllowsUsingFallback","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0380000","StartTime":"2018-10-23T10:09:19.5750635+00:00","EndTime":"2018-10-23T10:09:19.5750635+00:00","Properties":[]},{"TestCase":{"Id":"2efe932a-2c87-4a55-7f37-31fd92ac338d","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.CacheTagHelper_VariesByRoute","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.CacheTagHelper_VariesByRoute","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.CacheTagHelper_VariesByRoute","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0340000","StartTime":"2018-10-23T10:09:19.608856+00:00","EndTime":"2018-10-23T10:09:19.608856+00:00","Properties":[]},{"TestCase":{"Id":"f26e0a0f-072a-b55e-e7c0-c42baf364709","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.PartialTagHelper_AllowsPassingModelValue","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.PartialTagHelper_AllowsPassingModelValue","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.PartialTagHelper_AllowsPassingModelValue","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0040000","StartTime":"2018-10-23T10:09:19.6136835+00:00","EndTime":"2018-10-23T10:09:19.6136835+00:00","Properties":[]},{"TestCase":{"Id":"39a96783-dbec-2b85-46c3-3e2542412dfe","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.ValidationTagHelpers_GeneratesExpectedSpansAndDivs","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.ValidationTagHelpers_GeneratesExpectedSpansAndDivs","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.ValidationTagHelpers_GeneratesExpectedSpansAndDivs","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0100000","StartTime":"2018-10-23T10:09:19.632028+00:00","EndTime":"2018-10-23T10:09:19.632028+00:00","Properties":[]},{"TestCase":{"Id":"20c81833-6762-7d3f-6e7b-b78294223550","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.PartialTagHelper_AllowsUsingOptional","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.PartialTagHelper_AllowsUsingOptional","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.PartialTagHelper_AllowsUsingOptional","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0200000","StartTime":"2018-10-23T10:09:19.6523035+00:00","EndTime":"2018-10-23T10:09:19.6523035+00:00","Properties":[]},{"TestCase":{"Id":"eb05b3e9-8e22-4c3a-c63d-e9b2010bebdf","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewEngine_ResolvesPathsWithSlashesThatDoNotHaveExtensions","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewEngine_ResolvesPathsWithSlashesThatDoNotHaveExtensions","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewEngine_ResolvesPathsWithSlashesThatDoNotHaveExtensions","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.5830000","StartTime":"2018-10-23T10:09:19.6793375+00:00","EndTime":"2018-10-23T10:09:19.6793375+00:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":1462,"Stats":{"Passed":1455,"Skipped":7}},"ActiveTests":[{"Id":"fbadb3c1-6dae-3571-343c-cdd1374f3bca","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorFileUpdateTests.RazorPages_AreUpdatedOnChange","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorFileUpdateTests.RazorPages_AreUpdatedOnChange","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},{"Id":"73c5c623-769b-717c-8720-4e9ecc197a24","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.CacheTagHelper_CanCachePortionsOfViewsPartialViewsAndViewComponents","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.CacheTagHelper_CanCachePortionsOfViewsPartialViewsAndViewComponents","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]}]}}
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.682, 6051417961397, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Starting.
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.683, 6051417962214, vstest.console.dll, InProgress is Microsoft.AspNetCore.Mvc.FunctionalTests.RazorFileUpdateTests.RazorPages_AreUpdatedOnChange
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.683, 6051417962814, vstest.console.dll, InProgress is Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.CacheTagHelper_CanCachePortionsOfViewsPartialViewsAndViewComponents
TpTrace Information: 0 : 11324, 6, 2018/10/23, 03:09:19.683, 6051417964147, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Completed.
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:19.683, 6051417970329, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55443 localEndPoint: 127.0.0.1:55440
TpTrace Verbose: 0 : 11324, 11, 2018/10/23, 03:09:19.905, 6051420207896, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: NotifyDataAvailable remoteEndPoint: 127.0.0.1:55441 localEndPoint: 127.0.0.1:55438
TpTrace Verbose: 0 : 11324, 11, 2018/10/23, 03:09:19.905, 6051420209377, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestSession.Message","Payload":{"MessageLevel":0,"Message":"[xUnit.net 00:00:27.13]   Finished:    Mvc.Api.Analyzers.Test"}}
TpTrace Verbose: 0 : 11324, 11, 2018/10/23, 03:09:19.905, 6051420210705, vstest.console.dll, TestRunRequest:SendTestRunMessage: Starting.
TpTrace Information: 0 : 11324, 11, 2018/10/23, 03:09:19.906, 6051420211678, vstest.console.dll, TestRunRequest:SendTestRunMessage: Completed.
TpTrace Verbose: 0 : 11324, 11, 2018/10/23, 03:09:19.906, 6051420212162, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55441 localEndPoint: 127.0.0.1:55438
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:19.965, 6051420811919, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.055, 6051421737203, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: NotifyDataAvailable remoteEndPoint: 127.0.0.1:55443 localEndPoint: 127.0.0.1:55440
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.055, 6051421740112, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[{"TestCase":{"Id":"51b44bdf-3203-b581-1fff-5b3079f83d4f","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_ExecutesPartialPagesWithCorrectContext","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_ExecutesPartialPagesWithCorrectContext","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_ExecutesPartialPagesWithCorrectContext","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0190000","StartTime":"2018-10-23T10:09:19.6986475+00:00","EndTime":"2018-10-23T10:09:19.6986475+00:00","Properties":[]},{"TestCase":{"Id":"73c5c623-769b-717c-8720-4e9ecc197a24","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.CacheTagHelper_CanCachePortionsOfViewsPartialViewsAndViewComponents","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.CacheTagHelper_CanCachePortionsOfViewsPartialViewsAndViewComponents","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.CacheTagHelper_CanCachePortionsOfViewsPartialViewsAndViewComponents","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0820000","StartTime":"2018-10-23T10:09:19.734371+00:00","EndTime":"2018-10-23T10:09:19.734371+00:00","Properties":[]},{"TestCase":{"Id":"d9955c7f-0bca-7338-eb23-d2226246bd76","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.CacheTagHelper_BubblesExpirationOfNestedTagHelpers","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.CacheTagHelper_BubblesExpirationOfNestedTagHelpers","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.CacheTagHelper_BubblesExpirationOfNestedTagHelpers","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0780000","StartTime":"2018-10-23T10:09:19.8106455+00:00","EndTime":"2018-10-23T10:09:19.8106455+00:00","Properties":[]},{"TestCase":{"Id":"fbadb3c1-6dae-3571-343c-cdd1374f3bca","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorFileUpdateTests.RazorPages_AreUpdatedOnChange","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorFileUpdateTests.RazorPages_AreUpdatedOnChange","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorFileUpdateTests.RazorPages_AreUpdatedOnChange","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.5460000","StartTime":"2018-10-23T10:09:19.827059+00:00","EndTime":"2018-10-23T10:09:19.827059+00:00","Properties":[]},{"TestCase":{"Id":"afe65ed6-547e-8661-6dd8-66dac67bdef0","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.CacheTagHelper_DoesNotCacheIfDisabled","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.CacheTagHelper_DoesNotCacheIfDisabled","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.CacheTagHelper_DoesNotCacheIfDisabled","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0420000","StartTime":"2018-10-23T10:09:19.8531275+00:00","EndTime":"2018-10-23T10:09:19.8531275+00:00","Properties":[]},{"TestCase":{"Id":"5599b684-018a-7de0-d790-e9f08173653c","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.CacheTagHelper_VariesByUserId","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.CacheTagHelper_VariesByUserId","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.HtmlGenerationTest.CacheTagHelper_VariesByUserId","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0190000","StartTime":"2018-10-23T10:09:19.871472+00:00","EndTime":"2018-10-23T10:09:19.871472+00:00","Properties":[]},{"TestCase":{"Id":"35d651e4-089f-d59a-008e-5a5f7140bb77","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersViewsFromEmbeddedFileProvider_WhenLookedupByPath","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersViewsFromEmbeddedFileProvider_WhenLookedupByPath","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersViewsFromEmbeddedFileProvider_WhenLookedupByPath","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.3150000","StartTime":"2018-10-23T10:09:20.010504+00:00","EndTime":"2018-10-23T10:09:20.010504+00:00","Properties":[]},{"TestCase":{"Id":"b40906f9-c5b1-7c85-461e-b31a7b71796f","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewComponentsExecuteLayout","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewComponentsExecuteLayout","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewComponentsExecuteLayout","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0440000","StartTime":"2018-10-23T10:09:20.0539515+00:00","EndTime":"2018-10-23T10:09:20.0539515+00:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":1470,"Stats":{"Passed":1463,"Skipped":7}},"ActiveTests":[{"Id":"07e20a4a-b34b-4233-58b4-7118e325dc2c","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorFileUpdateTests.RazorViews_AreUpdatedOnChange","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorFileUpdateTests.RazorViews_AreUpdatedOnChange","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},{"Id":"9478b332-abb5-5f82-1b61-42cf809a6b4a","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewEngine_DiscoversViewsFromPagesSharedDirectory","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewEngine_DiscoversViewsFromPagesSharedDirectory","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]}]}}
TpTrace Verbose: 0 : 11324, 11, 2018/10/23, 03:09:20.057, 6051421748923, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: NotifyDataAvailable remoteEndPoint: 127.0.0.1:55441 localEndPoint: 127.0.0.1:55438
TpTrace Verbose: 0 : 11324, 11, 2018/10/23, 03:09:20.057, 6051421750168, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestExecution.Completed","Payload":{"TestRunCompleteArgs":{"TestRunStatistics":{"ExecutedTests":184,"Stats":{"Passed":184}},"IsCanceled":false,"IsAborted":false,"Error":null,"AttachmentSets":[],"ElapsedTimeInRunningTests":"00:00:27.2989004","Metrics":{}},"LastRunTests":{"NewTestResults":[{"TestCase":{"Id":"60320c7e-e58e-94b9-4fb1-be9f623336d8","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForStaticMethod","DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForStaticMethod","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Mvc.Api.Analyzers.Test\\bin\\Release\\net461\\Mvc.Api.Analyzers.Test.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsAction_ReturnsFalseForStaticMethod","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.1020000","StartTime":"2018-10-23T10:09:19.581822+00:00","EndTime":"2018-10-23T10:09:19.581822+00:00","Properties":[]},{"TestCase":{"Id":"f5c8bbb8-87e4-b83d-df8f-e79216b262f7","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsController_ReturnsFalseForGenericType","DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsController_ReturnsFalseForGenericType","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Mvc.Api.Analyzers.Test\\bin\\Release\\net461\\Mvc.Api.Analyzers.Test.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsController_ReturnsFalseForGenericType","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0910000","StartTime":"2018-10-23T10:09:19.682234+00:00","EndTime":"2018-10-23T10:09:19.682234+00:00","Properties":[]},{"TestCase":{"Id":"36991077-cbf9-a82d-2f31-ef9ef49bf05d","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsController_ReturnsFalseForAbstractTypes","DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsController_ReturnsFalseForAbstractTypes","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Mvc.Api.Analyzers.Test\\bin\\Release\\net461\\Mvc.Api.Analyzers.Test.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsController_ReturnsFalseForAbstractTypes","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.1050000","StartTime":"2018-10-23T10:09:19.7758875+00:00","EndTime":"2018-10-23T10:09:19.7758875+00:00","Properties":[]},{"TestCase":{"Id":"8b174e9c-ca8d-72a3-92ab-71aa950fd30a","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsController_ReturnsTrueForTypeWithSuffix_ThatIsNotDerivedFromController","DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsController_ReturnsTrueForTypeWithSuffix_ThatIsNotDerivedFromController","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Mvc.Api.Analyzers.Test\\bin\\Release\\net461\\Mvc.Api.Analyzers.Test.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.Api.Analyzers.MvcFactsTest.IsController_ReturnsTrueForTypeWithSuffix_ThatIsNotDerivedFromController","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.1190000","StartTime":"2018-10-23T10:09:19.892713+00:00","EndTime":"2018-10-23T10:09:19.892713+00:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":184,"Stats":{"Passed":184}},"ActiveTests":[]},"RunAttachments":[],"ExecutorUris":["executor://xunit/VsTestRunner2/net"]}}
TpTrace Verbose: 0 : 11324, 11, 2018/10/23, 03:09:20.057, 6051421752686, vstest.console.dll, TestRequestSender.EndSession: Sending end session.
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.059, 6051421766374, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Starting.
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.059, 6051421767058, vstest.console.dll, InProgress is Microsoft.AspNetCore.Mvc.FunctionalTests.RazorFileUpdateTests.RazorViews_AreUpdatedOnChange
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.059, 6051421767480, vstest.console.dll, InProgress is Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewEngine_DiscoversViewsFromPagesSharedDirectory
TpTrace Information: 0 : 11324, 6, 2018/10/23, 03:09:20.059, 6051421768070, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Completed.
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.059, 6051421768511, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55443 localEndPoint: 127.0.0.1:55440
TpTrace Warning: 0 : 11324, 25, 2018/10/23, 03:09:20.064, 6051421820466, vstest.console.dll, TestHostManagerCallbacks.ErrorReceivedCallback Test host standard error line: 
TpTrace Information: 0 : 11324, 15, 2018/10/23, 03:09:20.077, 6051421947454, vstest.console.dll, TestHostManagerCallbacks.ExitCallBack: Testhost processId: 14332 exited with exitcode: 0 error: ''
TpTrace Information: 0 : 11324, 15, 2018/10/23, 03:09:20.077, 6051421948425, vstest.console.dll, TestRequestSender.OnClientProcessExit: Test host process exited. Standard error: 
TpTrace Information: 0 : 11324, 15, 2018/10/23, 03:09:20.077, 6051421948850, vstest.console.dll, SocketServer.Stop: Stop server endPoint: 127.0.0.1:55438
TpTrace Information: 0 : 11324, 15, 2018/10/23, 03:09:20.077, 6051421949079, vstest.console.dll, SocketServer.Stop: Cancellation requested. Stopping message loop.
TpTrace Warning: 0 : 11324, 11, 2018/10/23, 03:09:20.077, 6051421949483, vstest.console.dll, ProxyOperationManager: Timed out waiting for test host to exit. Will terminate process.
TpTrace Verbose: 0 : 11324, 11, 2018/10/23, 03:09:20.079, 6051421967616, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Starting.
TpTrace Information: 0 : 11324, 11, 2018/10/23, 03:09:20.079, 6051421968226, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Completed.
TpTrace Verbose: 0 : 11324, 11, 2018/10/23, 03:09:20.079, 6051421968693, vstest.console.dll, ParallelProxyExecutionManager: HandlePartialRunComplete: Total completed clients = 16, Run complete = False, Run canceled: False.
TpTrace Verbose: 0 : 11324, 11, 2018/10/23, 03:09:20.079, 6051421969067, vstest.console.dll, ParallelProxyExecutionManager: HandlePartialRunComplete: Replace execution manager. Shared: True, Aborted: False.
TpTrace Warning: 0 : 11324, 11, 2018/10/23, 03:09:20.081, 6051421993855, vstest.console.dll, Invalid settings 'RunConfiguration'. Unexpected XmlElement: 'NoAutoReporters'.
TpTrace Warning: 0 : 11324, 11, 2018/10/23, 03:09:20.081, 6051421995282, vstest.console.dll, Invalid settings 'RunConfiguration'. Unexpected XmlElement: 'NoAutoReporters'.
TpTrace Verbose: 0 : 11324, 11, 2018/10/23, 03:09:20.081, 6051421995901, vstest.console.dll, TestRequestSender is acting as server
TpTrace Verbose: 0 : 11324, 11, 2018/10/23, 03:09:20.081, 6051421996374, vstest.console.dll, ProxyParallelExecutionManager: No sources available for execution.
TpTrace Verbose: 0 : 11324, 11, 2018/10/23, 03:09:20.081, 6051421996661, vstest.console.dll, TestRequestSender.SetOperationComplete: Setting operation complete.
TpTrace Information: 0 : 11324, 11, 2018/10/23, 03:09:20.081, 6051421996998, vstest.console.dll, SocketServer.Stop: Stop server endPoint: 127.0.0.1:55438
TpTrace Information: 0 : 11324, 11, 2018/10/23, 03:09:20.081, 6051421997244, vstest.console.dll, SocketServer.Stop: Cancellation requested. Stopping message loop.
TpTrace Information: 0 : 11324, 11, 2018/10/23, 03:09:20.081, 6051421997488, vstest.console.dll, SocketServer.PrivateStop: Stopp server endPoint: 127.0.0.1:55438 error: 
TpTrace Verbose: 0 : 11324, 11, 2018/10/23, 03:09:20.081, 6051421998923, vstest.console.dll, LengthPrefixCommunicationChannel.Dispose: Dispose reader and writer.
TpTrace Information: 0 : 11324, 11, 2018/10/23, 03:09:20.081, 6051421999317, vstest.console.dll, SocketServer.Stop: Raise disconnected event endPoint: 127.0.0.1:55438 error: 
TpTrace Verbose: 0 : 11324, 11, 2018/10/23, 03:09:20.081, 6051421999739, vstest.console.dll, TestRequestSender: OnTestRunAbort: Operation is already complete. Skip error message.
TpTrace Verbose: 0 : 11324, 11, 2018/10/23, 03:09:20.081, 6051422000023, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: exiting MessageLoopAsync remoteEndPoint: 127.0.0.1:55441 localEndPoint: 127.0.0.1:55438
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.232, 6051423515917, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: NotifyDataAvailable remoteEndPoint: 127.0.0.1:55443 localEndPoint: 127.0.0.1:55440
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.232, 6051423518101, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[{"TestCase":{"Id":"9478b332-abb5-5f82-1b61-42cf809a6b4a","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewEngine_DiscoversViewsFromPagesSharedDirectory","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewEngine_DiscoversViewsFromPagesSharedDirectory","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewEngine_DiscoversViewsFromPagesSharedDirectory","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0130000","StartTime":"2018-10-23T10:09:20.066503+00:00","EndTime":"2018-10-23T10:09:20.066503+00:00","Properties":[]},{"TestCase":{"Id":"52d7dd99-44a6-530b-392f-053222e7f7ee","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewEngine_NormalizesPathsReturnedByViewLocationExpanders","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewEngine_NormalizesPathsReturnedByViewLocationExpanders","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewEngine_NormalizesPathsReturnedByViewLocationExpanders","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0210000","StartTime":"2018-10-23T10:09:20.087744+00:00","EndTime":"2018-10-23T10:09:20.087744+00:00","Properties":[]},{"TestCase":{"Id":"7a2ed9fb-b4a4-499f-d557-2653306458d6","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_DoesNotThrow_PartialViewWithEnumerableModel","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_DoesNotThrow_PartialViewWithEnumerableModel","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_DoesNotThrow_PartialViewWithEnumerableModel","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0390000","StartTime":"2018-10-23T10:09:20.1273295+00:00","EndTime":"2018-10-23T10:09:20.1273295+00:00","Properties":[]},{"TestCase":{"Id":"56e024ab-8f1f-0eda-8387-aaf80bd7ed9d","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_UsesExpandersForLayouts","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_UsesExpandersForLayouts(value: \"fr\", expected: \"<fr-language-layout>View With Layout\\r\\n</fr-langu\"...)","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_UsesExpandersForLayouts(value: \"fr\", expected: \"<fr-language-layout>View With Layout\\r\\n</fr-langu\"...)","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0150000","StartTime":"2018-10-23T10:09:20.1427775+00:00","EndTime":"2018-10-23T10:09:20.1427775+00:00","Properties":[]},{"TestCase":{"Id":"0612ef3d-7331-7828-c533-6afc4157459d","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_UsesExpandersForLayouts","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_UsesExpandersForLayouts(value: \"en-GB\", expected: \"<language-layout>View With Layout\\r\\n</language-la\"...)","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_UsesExpandersForLayouts(value: \"en-GB\", expected: \"<language-layout>View With Layout\\r\\n</language-la\"...)","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0050000","StartTime":"2018-10-23T10:09:20.147605+00:00","EndTime":"2018-10-23T10:09:20.147605+00:00","Properties":[]},{"TestCase":{"Id":"b9d8e1b8-bb5e-d466-48a3-beac39134807","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_UsesExpandersForLayouts","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_UsesExpandersForLayouts(value: \"!-invalid-!\", expected: \"<language-layout>View With Layout\\r\\n</language-la\"...)","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_UsesExpandersForLayouts(value: \"!-invalid-!\", expected: \"<language-layout>View With Layout\\r\\n</language-la\"...)","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0010000","StartTime":"2018-10-23T10:09:20.166915+00:00","EndTime":"2018-10-23T10:09:20.166915+00:00","Properties":[]},{"TestCase":{"Id":"cf5390a7-2c9a-2cb8-091b-8acfbec578a4","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.PartialsRenderedViaPartialAsync_CanRenderLayouts","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.PartialsRenderedViaPartialAsync_CanRenderLayouts","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.PartialsRenderedViaPartialAsync_CanRenderLayouts","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0400000","StartTime":"2018-10-23T10:09:20.1891236+00:00","EndTime":"2018-10-23T10:09:20.1891236+00:00","Properties":[]},{"TestCase":{"Id":"c2996731-c136-ab94-55ec-0ce158444d59","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_PassesViewContextBetweenViewAndLayout","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_PassesViewContextBetweenViewAndLayout","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorView_PassesViewContextBetweenViewAndLayout","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0150000","StartTime":"2018-10-23T10:09:20.2036076+00:00","EndTime":"2018-10-23T10:09:20.2036076+00:00","Properties":[]},{"TestCase":{"Id":"fa8793fd-227e-bcf7-c71a-5350e4402131","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewComponentsDoNotExecuteViewStarts","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewComponentsDoNotExecuteViewStarts","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewComponentsDoNotExecuteViewStarts","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0260000","StartTime":"2018-10-23T10:09:20.2296788+00:00","EndTime":"2018-10-23T10:09:20.2296788+00:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":1479,"Stats":{"Passed":1472,"Skipped":7}},"ActiveTests":[{"Id":"4b6fcbe5-6715-4f8c-22c8-07053ce69564","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewStartsCanUseDirectivesInjectedFromParentGlobals","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewStartsCanUseDirectivesInjectedFromParentGlobals","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]}]}}
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.234, 6051423531908, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Starting.
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.234, 6051423532400, vstest.console.dll, InProgress is Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewStartsCanUseDirectivesInjectedFromParentGlobals
TpTrace Information: 0 : 11324, 6, 2018/10/23, 03:09:20.234, 6051423532778, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Completed.
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.234, 6051423533071, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55443 localEndPoint: 127.0.0.1:55440
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.340, 6051424569637, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: NotifyDataAvailable remoteEndPoint: 127.0.0.1:55443 localEndPoint: 127.0.0.1:55440
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.341, 6051424572341, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestExecution.StatsChange","Payload":{"NewTestResults":[{"TestCase":{"Id":"4b6fcbe5-6715-4f8c-22c8-07053ce69564","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewStartsCanUseDirectivesInjectedFromParentGlobals","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewStartsCanUseDirectivesInjectedFromParentGlobals","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewStartsCanUseDirectivesInjectedFromParentGlobals","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0140000","StartTime":"2018-10-23T10:09:20.2441628+00:00","EndTime":"2018-10-23T10:09:20.2441628+00:00","Properties":[]},{"TestCase":{"Id":"c7eb484e-f079-e3cf-d6c5-1535eb66116b","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewLocationExpanders_GetIsMainPageFromContext","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewLocationExpanders_GetIsMainPageFromContext(action: \"Partial\", expected: \"<shared-views>/Shared-Views/ExpanderViews/_Expande\"...)","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewLocationExpanders_GetIsMainPageFromContext(action: \"Partial\", expected: \"<shared-views>/Shared-Views/ExpanderViews/_Expande\"...)","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0070000","StartTime":"2018-10-23T10:09:20.2499564+00:00","EndTime":"2018-10-23T10:09:20.2499564+00:00","Properties":[]},{"TestCase":{"Id":"e9d9da07-c709-3b2d-6f01-23b834b0b518","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewLocationExpanders_GetIsMainPageFromContext","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewLocationExpanders_GetIsMainPageFromContext(action: \"Index\", expected: \"<expander-view><shared-views>/Shared-Views/Expande\"...)","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.ViewLocationExpanders_GetIsMainPageFromContext(action: \"Index\", expected: \"<expander-view><shared-views>/Shared-Views/Expande\"...)","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0050000","StartTime":"2018-10-23T10:09:20.2538188+00:00","EndTime":"2018-10-23T10:09:20.2538188+00:00","Properties":[]},{"TestCase":{"Id":"fdeb0f2f-6f9b-d1a1-89ab-d6c01ae07ddd","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RelativePathsWorkAsExpected","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RelativePathsWorkAsExpected","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RelativePathsWorkAsExpected","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0370000","StartTime":"2018-10-23T10:09:20.2953396+00:00","EndTime":"2018-10-23T10:09:20.2953396+00:00","Properties":[]},{"TestCase":{"Id":"991052da-f876-17c4-2a30-8e3002e4d378","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.LayoutValueIsPassedBetweenNestedViewStarts","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.LayoutValueIsPassedBetweenNestedViewStarts","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.LayoutValueIsPassedBetweenNestedViewStarts","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0280000","StartTime":"2018-10-23T10:09:20.3272042+00:00","EndTime":"2018-10-23T10:09:20.3272042+00:00","Properties":[]},{"TestCase":{"Id":"57539497-9516-6c99-de34-14d5255e83e4","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersViewsFromEmbeddedFileProvider_WhenLookedupByName","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersViewsFromEmbeddedFileProvider_WhenLookedupByName","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.RazorViewEngine_RendersViewsFromEmbeddedFileProvider_WhenLookedupByName","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0030000","StartTime":"2018-10-23T10:09:20.3310666+00:00","EndTime":"2018-10-23T10:09:20.3310666+00:00","Properties":[]},{"TestCase":{"Id":"07e20a4a-b34b-4233-58b4-7118e325dc2c","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorFileUpdateTests.RazorViews_AreUpdatedOnChange","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorFileUpdateTests.RazorViews_AreUpdatedOnChange","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorFileUpdateTests.RazorViews_AreUpdatedOnChange","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.5040000","StartTime":"2018-10-23T10:09:20.334929+00:00","EndTime":"2018-10-23T10:09:20.334929+00:00","Properties":[]},{"TestCase":{"Id":"dc6b144e-18c5-6afc-9067-0267ccb2de07","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.PartialsRenderedViaRenderPartialAsync_CanRenderLayouts","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.PartialsRenderedViaRenderPartialAsync_CanRenderLayouts","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.PartialsRenderedViaRenderPartialAsync_CanRenderLayouts","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0080000","StartTime":"2018-10-23T10:09:20.3387914+00:00","EndTime":"2018-10-23T10:09:20.3387914+00:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":1487,"Stats":{"Passed":1480,"Skipped":7}},"ActiveTests":[{"Id":"5fb3773b-2ed5-a1cf-9a76-bbb91080bdca","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorFileUpdateTests.RazorViews_AreUpdatedWhenViewImportsChange","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorFileUpdateTests.RazorViews_AreUpdatedWhenViewImportsChange","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},{"Id":"0c586db5-bad9-57ba-c1c2-d649e1d4a708","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.PartialDoNotExecuteViewStarts","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.PartialDoNotExecuteViewStarts","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]}]}}
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.342, 6051424585068, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Starting.
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.342, 6051424585667, vstest.console.dll, InProgress is Microsoft.AspNetCore.Mvc.FunctionalTests.RazorFileUpdateTests.RazorViews_AreUpdatedWhenViewImportsChange
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.342, 6051424585998, vstest.console.dll, InProgress is Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.PartialDoNotExecuteViewStarts
TpTrace Information: 0 : 11324, 6, 2018/10/23, 03:09:20.342, 6051424586406, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Completed.
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.342, 6051424586668, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55443 localEndPoint: 127.0.0.1:55440
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.684, 6051428002978, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: NotifyDataAvailable remoteEndPoint: 127.0.0.1:55443 localEndPoint: 127.0.0.1:55440
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.684, 6051428004268, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestSession.Message","Payload":{"MessageLevel":0,"Message":"[xUnit.net 00:00:27.65]   Finished:    Microsoft.AspNetCore.Mvc.FunctionalTests"}}
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.684, 6051428005373, vstest.console.dll, TestRunRequest:SendTestRunMessage: Starting.
TpTrace Information: 0 : 11324, 6, 2018/10/23, 03:09:20.684, 6051428005944, vstest.console.dll, TestRunRequest:SendTestRunMessage: Completed.
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.684, 6051428006251, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55443 localEndPoint: 127.0.0.1:55440
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.878, 6051429969569, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: NotifyDataAvailable remoteEndPoint: 127.0.0.1:55443 localEndPoint: 127.0.0.1:55440
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.878, 6051429971013, vstest.console.dll, TestRequestSender.OnExecutionMessageReceived: Received message: {"Version":2,"MessageType":"TestExecution.Completed","Payload":{"TestRunCompleteArgs":{"TestRunStatistics":{"ExecutedTests":1489,"Stats":{"Passed":1482,"Skipped":7}},"IsCanceled":false,"IsAborted":false,"Error":null,"AttachmentSets":[],"ElapsedTimeInRunningTests":"00:00:27.8532728","Metrics":{}},"LastRunTests":{"NewTestResults":[{"TestCase":{"Id":"0c586db5-bad9-57ba-c1c2-d649e1d4a708","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.PartialDoNotExecuteViewStarts","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.PartialDoNotExecuteViewStarts","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.ViewEngineTests.PartialDoNotExecuteViewStarts","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.0030000","StartTime":"2018-10-23T10:09:20.3416882+00:00","EndTime":"2018-10-23T10:09:20.3416882+00:00","Properties":[]},{"TestCase":{"Id":"5fb3773b-2ed5-a1cf-9a76-bbb91080bdca","FullyQualifiedName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorFileUpdateTests.RazorViews_AreUpdatedWhenViewImportsChange","DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorFileUpdateTests.RazorViews_AreUpdatedWhenViewImportsChange","ExecutorUri":"executor://xunit/VsTestRunner2/net","Source":"C:\\BuildAgent\\work\\e37dd45d8cd1eaf4\\modules\\Mvc\\test\\Microsoft.AspNetCore.Mvc.FunctionalTests\\bin\\Release\\net461\\Microsoft.AspNetCore.Mvc.FunctionalTests.dll","CodeFilePath":null,"LineNumber":0,"Properties":[]},"Attachments":[],"Outcome":1,"ErrorMessage":null,"ErrorStackTrace":null,"DisplayName":"Microsoft.AspNetCore.Mvc.FunctionalTests.RazorFileUpdateTests.RazorViews_AreUpdatedWhenViewImportsChange","Messages":[],"ComputerName":"ASPNETCI-A-112","Duration":"00:00:00.3480000","StartTime":"2018-10-23T10:09:20.6786824+00:00","EndTime":"2018-10-23T10:09:20.6786824+00:00","Properties":[]}],"TestRunStatistics":{"ExecutedTests":1489,"Stats":{"Passed":1482,"Skipped":7}},"ActiveTests":[]},"RunAttachments":[],"ExecutorUris":["executor://xunit/VsTestRunner2/net"]}}
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.879, 6051429973165, vstest.console.dll, TestRequestSender.EndSession: Sending end session.
TpTrace Warning: 0 : 11324, 27, 2018/10/23, 03:09:20.887, 6051430053345, vstest.console.dll, TestHostManagerCallbacks.ErrorReceivedCallback Test host standard error line: 
TpTrace Information: 0 : 11324, 15, 2018/10/23, 03:09:20.912, 6051430305624, vstest.console.dll, TestHostManagerCallbacks.ExitCallBack: Testhost processId: 16992 exited with exitcode: 0 error: ''
TpTrace Information: 0 : 11324, 15, 2018/10/23, 03:09:20.912, 6051430306874, vstest.console.dll, TestRequestSender.OnClientProcessExit: Test host process exited. Standard error: 
TpTrace Information: 0 : 11324, 15, 2018/10/23, 03:09:20.912, 6051430307263, vstest.console.dll, SocketServer.Stop: Stop server endPoint: 127.0.0.1:55440
TpTrace Information: 0 : 11324, 15, 2018/10/23, 03:09:20.912, 6051430307488, vstest.console.dll, SocketServer.Stop: Cancellation requested. Stopping message loop.
TpTrace Warning: 0 : 11324, 6, 2018/10/23, 03:09:20.912, 6051430307866, vstest.console.dll, ProxyOperationManager: Timed out waiting for test host to exit. Will terminate process.
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.913, 6051430318585, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Starting.
TpTrace Information: 0 : 11324, 6, 2018/10/23, 03:09:20.913, 6051430319156, vstest.console.dll, TestRunRequest:SendTestRunStatsChange: Completed.
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.913, 6051430319590, vstest.console.dll, ParallelProxyExecutionManager: HandlePartialRunComplete: Total completed clients = 17, Run complete = False, Run canceled: False.
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.913, 6051430319887, vstest.console.dll, ParallelProxyExecutionManager: HandlePartialRunComplete: Replace execution manager. Shared: True, Aborted: False.
TpTrace Warning: 0 : 11324, 6, 2018/10/23, 03:09:20.913, 6051430321449, vstest.console.dll, Invalid settings 'RunConfiguration'. Unexpected XmlElement: 'NoAutoReporters'.
TpTrace Warning: 0 : 11324, 6, 2018/10/23, 03:09:20.914, 6051430322604, vstest.console.dll, Invalid settings 'RunConfiguration'. Unexpected XmlElement: 'NoAutoReporters'.
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.914, 6051430323375, vstest.console.dll, TestRequestSender is acting as server
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.914, 6051430323816, vstest.console.dll, ProxyParallelExecutionManager: No sources available for execution.
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.914, 6051430324070, vstest.console.dll, TestRequestSender.SetOperationComplete: Setting operation complete.
TpTrace Information: 0 : 11324, 6, 2018/10/23, 03:09:20.914, 6051430324310, vstest.console.dll, SocketServer.Stop: Stop server endPoint: 127.0.0.1:55440
TpTrace Information: 0 : 11324, 6, 2018/10/23, 03:09:20.914, 6051430324516, vstest.console.dll, SocketServer.Stop: Cancellation requested. Stopping message loop.
TpTrace Information: 0 : 11324, 6, 2018/10/23, 03:09:20.914, 6051430324757, vstest.console.dll, SocketServer.PrivateStop: Stopp server endPoint: 127.0.0.1:55440 error: 
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.914, 6051430327093, vstest.console.dll, LengthPrefixCommunicationChannel.Dispose: Dispose reader and writer.
TpTrace Information: 0 : 11324, 6, 2018/10/23, 03:09:20.914, 6051430327685, vstest.console.dll, SocketServer.Stop: Raise disconnected event endPoint: 127.0.0.1:55440 error: 
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.914, 6051430328089, vstest.console.dll, TestRequestSender: OnTestRunAbort: Operation is already complete. Skip error message.
TpTrace Verbose: 0 : 11324, 6, 2018/10/23, 03:09:20.914, 6051430328384, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: exiting MessageLoopAsync remoteEndPoint: 127.0.0.1:55443 localEndPoint: 127.0.0.1:55440
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:20.953, 6051430721664, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:21.942, 6051440671676, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:22.931, 6051450572128, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:23.920, 6051460492047, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:24.908, 6051470341699, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:25.897, 6051480151569, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:26.886, 6051490012052, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:27.875, 6051499871584, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:28.864, 6051509842226, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:29.852, 6051519731740, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:30.841, 6051529621966, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:31.830, 6051539395296, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:32.827, 6051549356186, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:33.816, 6051559365557, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:34.805, 6051569314625, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:35.794, 6051579245200, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:36.782, 6051589112547, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:37.771, 6051598802779, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:38.760, 6051608772233, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:39.750, 6051618712618, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:40.739, 6051628553590, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:41.728, 6051638342726, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:42.717, 6051648293665, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:43.706, 6051658232787, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:44.695, 6051668143874, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:45.683, 6051678141365, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:46.672, 6051688028148, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:47.661, 6051698009083, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:48.650, 6051707988132, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:49.639, 6051717808556, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:50.628, 6051727459159, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:51.617, 6051737449336, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:52.606, 6051747439360, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:53.595, 6051757420177, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:54.583, 6051767338598, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:55.572, 6051777190042, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411
TpTrace Verbose: 0 : 11324, 26, 2018/10/23, 03:09:56.561, 6051787119365, vstest.console.dll, TcpClientExtensions.MessageLoopAsync: Polling on remoteEndPoint: 127.0.0.1:55417 localEndPoint: 127.0.0.1:55411

[mayankbansal018]

@javiercn can you please attach full logs for both vstest.console, & testhost.dll.
Do these runs pass for you during local developement? Or is it just CI issue?

[javiercn]

Tests pass normally and on the CI regularly. I would have to check the specific configuration. (Also not sure if we have those logs you’re asking for)

We run our tests with dotnet test on both local and CI

[mayankbansal018]

@javiercn the trace logs that you have shared above, there must be two files generated, one for vstest.console.dll(that you partially shared), & other for testhost.dll, can you share both those files with us? The files are generated when you run dotnet with --diag:<path_to_some_file>

As you said that the tests used to pass, is this intermittent issue, or are you getting it consistent?

[javiercn]

@mayankbansal018 This is an intermittent/point in time issue. I haven't seen it happen more than this time, but I don't believe we have changed anything recently. @ryanbrandenburg have you seen this issue happen other times? Can you help in providing the logs they are asking for?

[ryanbrandenburg]

We've had hangs with the same behavior here and here. Unfortunately we didn't use to produce/store these logs, but the issue happens frequently enough that we've had at least one instance since we started capturing them. The logs requested above are essentially every log listed in my link that ends in .diag. I'll leave making them available to @mayankbansal018 to @javiercn (our CI is private, though hypothetically there should be nothing sensitive in these logs).

[javiercn]

@mayankbansal018 I've uploaded the logs here.
vstestlogs.zip

[Eilon]

@mayankbansal018 - this is happening a lot to ASP.NET Core tests. Do you have an ETA when we can have someone take a look at this? (It might not even be an issue in vstest - but perhaps vstest can offer more diagnostic information about why it fails.)

[mayankbansal018]

@javiercn , I went through the logs, & it seems that for some unknown reason the testhost which is executing tests for assembly "Microsoft.AspNetCore.Mvc.Core.Test.dll" full CLR(net461) is stuck, can you please try running test for this one single test assembly multiple times, & check if you can repro it locally ? If possible please try to capture dumps for the testhost process at that time.

[javiercn]

@mayankbansal018 This looks like a recurring issue across different components of the product (not just this test assembly).

I'm not familiar with what to do to capture the logs and the dumps as you are suggesting, I think it would be better if you try to reproduce it on your box, all our tests and code are public and you just need to clone the repo (for example MVC) and call build.cmd to setup everything and run all the tests.
The functional tests are located in test/Microsoft.AspNetCore.Mvc.FunctionalTests

I'll try to see if we can do something on our end to capture the dumps on the CI when this happens, but keep in mind that I'm not an expert in this area at all

[mayankbansal018]

@javiercn, I tried to repro this locally, but so far was unsuccessful, even after running it 10 times consecutively. Will let it run 100 times over the night hoping for a possible repro. Will get back to you if I do manage to find one.

[javiercn]

I tried to repro it on our end running it 1000 times over the weekend but I wasn’t able to either. I’ll try to setup something on our CI to capture dumps when we believe it hangs.

[Eilon]

@mayankbansal018 - are you aware of any changes from the last ~1 month that could affect this? Changes to how threads are managed? Perf optimizations that could have unintended consequences? Across our hundreds of test runs with millions of tests being run, we're seeing failures quite often. It's just hard to repro in any single case.

[mayankbansal018]

@javiercn , thanks please let me know if you do capture anything.
@Eilon , no we have not taken any major changes in test platform, after from enabling Code Coverage support(windows only) for dotnet core & your tests are not running under coverage, which means it should not have any impact.

I'll take another look at logs tomorrow, to see if I missed something. Just to summarize, the tests from all test modules have ran, but for one particular module, the test platform has not issued Close request, because, we have not received "MessageType":"TestExecution.Completed" from the testhost, which is executing tests for that module.

Another thing that we could look at it is to try and enabled logs for xUnit, since it's the xUnit adapter which is running the tests, & check if adapter is infact saying that it has run all the tests

[mayankbansal018]

@javiercn , @Eilon , can you please enable logs for xUnit adapter, share them with us. The only thing that is missing right now from logs is whether the xUnit adapter has run all the test, & has send a message back to vstests platform regarding test run completion or not.

[Eilon]

@javiercn - can you try to do this?

[javiercn]

@eilon it’s on my queue

[javiercn]

I'm adding procdump to the build to try and capture dumps when the build hangs

[javiercn]

I've setup procdump on our Windows Server 2012 Test build and I'll see if I'm validating that we can successfully capture dumps. Once I've validated that I'll increment the sleep time on the script to try and capture dumps only when the build is hanging.

[javiercn]

We have procdump setup on our windows test agents now to capture memory dumps for all the dotnet processes if the build runs for more than 3h.

@mayankbansal018 Will that be enough or do we need to capture dumps for anything other than dotnet.
We are capturing the dumps with procdump.exe -accepteula -ma are there any other parameters that we should pass? (not an expert on capturing dumps here).

[javiercn]

@mayankbansal018 I was actually able to capture some dumps. What would be the best way to share them?

[Eilon]

Use your MS SharePoint.

[Eilon]

I.e. OneDrive for Business.

[javiercn]

@mayankbansal018 Here is a link to the memory dumps. Let me know if you can't access it https://microsoft-my.sharepoint.com/:u:/p/jacalvar/EYsle6UXX7BFhVIj9Y0BYl8BZTRE0TFAr82CR3R4hsgqIQ?e=8cVpRW

Once our agent kills the build I'll confirm that is stuck in the same place, but it seemed like it.

[mayankbansal018]

@javiercn , thanks I can access them, will take a look

[mayankbansal018]

@javiercn I looked at the logs, & it seems we have not received execution complete message from xUnit adapter. The stacks show that the adapter is waiting at line , & then it would have send the final message.

@bradwilson , @onovotny, Can you please help us diagnose this, logs can be found here . You can use either file "testhost.x86.DMP", or "testhost.x86.exe_181108_144619.dmp" to diagnose. Let me know if you need any help to set it up.

[NickCraver]

For what it's worth, we have seen this in unawaited calls in tests. I'd look for async shenanigans here in the tests themselves.

[javiercn]

@Eilon Should we go on the hunt for unawaited calls in tests as @NickCraver suggests? Could we distribute this among people in different areas so that is feasible?

@davidfowl this might interest you as is async related. Could we potentially write an analyzer for our tests if this ends up being the case?

[Eilon]

@davidfowl - do you know if there's already such an analyzer?

@javiercn - I'm hoping that we can do some basic analysis of at least one of the seemingly-known failing test projects and maybe just spot it. Or if we can find an analyzer that does it, use that.

[javiercn]

@Eilon I'm a bit scared that trying to find this by eye is like trying to find a needle in a haystack. Given that the test making the build hang might not be related at all with the test that shows up on the log.

[Eilon]

But we know which test assembly, right? Is any failing assembly a "small" one? I.e. fewer than say 100 tests?

[javiercn]

@Eilon I searched on MVC real quick https://github.com/aspnet/Mvc/search?q=%22async+void%22&unscoped_q=%22async+void%22

There are a couple of async void tests, and I realized that there are cases where we won't be able to avoid this as we need to test support for async void within our own frameworks.

[Eilon]

Talked to @javiercn in person, he will search & replace obviously bad tests.

[bradwilson]

Long-running test detection should help you find the hung test(s). https://xunit.github.io/docs/configuring-with-json

[javiercn]

@bradwilson Is there a way to pass those settings through dotnet test instead of through the json file? If we have to use the json file we will have to put the same file on each test project and it will be terribly painful

[bradwilson]

Unfortunately, no.

Your easiest solution is probably a single xunit.runner.json that gets auto-included into all test projects with a Directory.Build.props file, like we use: https://github.com/xunit/xunit/blob/c54cc52ffb275c81afed022521870193bbca6c39/test/Directory.Build.props#L21-L25

[javiercn]

@bradwilson Thanks, I think that might do the trick. @natemcmaster are you ok with the approach suggested by Brad? Could we put this in Korebuild (in addition to not adding them to the projects that already have one?)

[NickCraver]

Careful here - the unfriendly aspect of the long-running build detection output is it's tied to diagnostic message output which is much more verbose than normal (I really wish these weren't connected). We'd leave it turned on, if it weren't for that.

[natemcmaster]

@javiercn I'm hesitant to turn on diagnostic logging and long-running test detection by default across all KoreBuild projects. As Nick mentions, this can balloon output, and we already have problems with test logs producing gigabytes of output. Let's start by applying this option selectively to the tests projects under investigation.

[mayankbansal018]

Hi all, Since this looks unrelated to vstest, I'm closing this issue for now. Please comment here if needs to be re-opened.