VSTest and TRX upload failure + long timeout

Hi,

Question, Bug, or Feature? : Bug

Enter Task Name: VSTest@2 (version 2.170.1)

Environment

Server Azure Pipelines Build : https://dev.azure.com/c4game/appGame/_build/results?buildId=4319&view=logs&j=12f1170f-54f2-53f3-20dd-22fc7dff55f9
Agent Private agent 2.186.1 Windows Server 2016 (v1607 14393.4350) Hosted in our Azure subscription

Issue Description

Step definition

- task: VSTest@2
  displayName: 'Test Assemblies'
  inputs:
    testAssemblyVer2: |
     **\$(buildConfiguration)\*test*.dll
     !**\obj\**
    codeCoverageEnabled: true
    platform: '$(buildPlatform)'
    configuration: '$(buildConfiguration)'

Interesting message

2021-10-28T08:16:19.9117131Z Publishing Attachments: 2
2021-10-28T08:16:19.9240502Z ##[debug]Entering CreateTestRunAttachmentsAsync
2021-10-28T08:16:20.0023053Z ##[debug]Leaving CreateTestRunAttachmentsAsync
2021-10-28T08:16:20.0041144Z ##[debug]Uploading test run attachements individually
2021-10-28T08:16:20.0108647Z ##[debug]Entering UploadTestRunLogAsync
2021-10-28T08:16:20.0593401Z ##[debug]Entering UploadTestRunLogAsync
2021-10-28T08:16:20.3599919Z ##[debug]Blob name: CodeCoverage/XXXXX$_XXXXX_2021-10-28.10_15_18.coverage .
2021-10-28T08:16:20.3602219Z ##[debug]Blob name: GeneralAttachment/XXXXX$_XXXXX_2021-10-28_10_15_25.trx .
2021-10-28T08:27:11.5319908Z ##[warning]Failed to upload file XXXXX$_XXXXX_2021-10-28_10_15_25.trx to Blob Transfer exception with errorcode Unknown, exception message Microsoft.Azure.Storage.DataMovement.TransferException: The transfer failed. ---> Microsoft.Azure.Storage.StorageException: An error occurred while sending the request. ---> System.Net.Http.HttpRequestException: An error occurred while sending the request. ---> System.Net.WebException: The underlying connection was closed: An unexpected error occurred on a send. ---> System.IO.IOException: Unable to read data from the transport connection: An existing connection was forcibly closed by the remote host. ---> System.Net.Sockets.SocketException: An existing connection was forcibly closed by the remote host
2021-10-28T08:27:11.5324990Z ##[debug]Processed: ##vso[task.logissue type=warning;]Failed to upload file XXXXX$_XXXXX_2021-10-28_10_15_25.trx to Blob Transfer exception with errorcode Unknown, exception message Microsoft.Azure.Storage.DataMovement.TransferException: The transfer failed. ---> Microsoft.Azure.Storage.StorageException: An error occurred while sending the request. ---> System.Net.Http.HttpRequestException: An error occurred while sending the request. ---> System.Net.WebException: The underlying connection was closed: An unexpected error occurred on a send. ---> System.IO.IOException: Unable to read data from the transport connection: An existing connection was forcibly closed by the remote host. ---> System.Net.Sockets.SocketException: An existing connection was forcibly closed by the remote host
[...]
2021-10-28T08:27:11.5359904Z ##[debug]Failed to Upload on log store with error code TransferFailed
2021-10-28T08:27:11.5378403Z Failed to upload run logs to LogStore, Trying to upload to FileService
2021-10-28T08:27:11.8721649Z Failed to publish Telemetry Exception: Object reference not set to an instance of an object.
2021-10-28T08:27:35.4433036Z ##[warning]Failed to upload file XXXXX$_XXXXX_2021-10-28.10_15_18.coverage to Blob Transfer exception with errorcode Unknown, exception message Microsoft.Azure.Storage.DataMovement.TransferException: The transfer failed. ---> Microsoft.Azure.Storage.StorageException: An error occurred while sending the request. ---> System.Net.Http.HttpRequestException: An error occurred while sending the request. ---> System.Net.WebException: The underlying connection was closed: An unexpected error occurred on a send. ---> System.IO.IOException: Unable to read data from the transport connection: An existing connection was forcibly closed by the remote host. ---> System.Net.Sockets.SocketException: An existing connection was forcibly closed by the remote host
2021-10-28T08:27:35.4439108Z ##[debug]Processed: ##vso[task.logissue type=warning;]Failed to upload file XXXXX$_XXXXX_2021-10-28.10_15_18.coverage to Blob Transfer exception with errorcode Unknown, exception message Microsoft.Azure.Storage.DataMovement.TransferException: The transfer failed. ---> Microsoft.Azure.Storage.StorageException: An error occurred while sending the request. ---> System.Net.Http.HttpRequestException: An error occurred while sending the request. ---> System.Net.WebException: The underlying connection was closed: An unexpected error occurred on a send. ---> System.IO.IOException: Unable to read data from the transport connection: An existing connection was forcibly closed by the remote host. ---> System.Net.Sockets.SocketException: An existing connection was forcibly closed by the remote host

Nothing happens between [08:16:20 => 08:27:11]

From the agent VM, if I test the following DNS list : https://docs.microsoft.com/en-us/azure/devops/organizations/security/allow-list-ip-url?view=azure-devops&tabs=IP-V4

=> It seems to be OK. vsassets.io is OK, only microsoftonline.com failed

Results

ComputerName     : XXXX.dev.azure.com
RemoteAddress    : 13.107.43.20
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : XXXX.gallerycdn.vsassets.io
RemoteAddress    : 68.232.34.200
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : XXXX.vstmrblob.vsassets.io
RemoteAddress    : 13.107.9.175
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : aadcdn.msauth.net
RemoteAddress    : 13.107.246.42
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : aadcdn.msftauth.net
RemoteAddress    : 152.199.23.37
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : aex.dev.azure.com
RemoteAddress    : 13.107.43.20
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : aexprodea1.vsaex.visualstudio.com
RemoteAddress    : 13.107.43.18
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : amcdn.msftauth.net
RemoteAddress    : 13.107.213.42
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : amp.azure.net
RemoteAddress    : 152.199.19.160
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : app.vssps.dev.azure.com
RemoteAddress    : 13.107.43.20
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : app.vssps.visualstudio.com
RemoteAddress    : 13.107.43.18
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : vssps.visualstudio.com
RemoteAddress    : 13.107.43.18
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : azure.microsoft.com
RemoteAddress    : 13.107.42.16
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : azurecomcdn.azureedge.net
RemoteAddress    : 152.199.19.161
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : cdn.vsassets.io
RemoteAddress    : 152.199.19.160
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : dev.azure.com
RemoteAddress    : 13.107.42.20
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : go.microsoft.com
RemoteAddress    : 104.69.107.145
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : graph.microsoft.com
RemoteAddress    : 20.190.177.152
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : live.com
RemoteAddress    : 204.79.197.212
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : login.live.com
RemoteAddress    : 20.190.160.71
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : login.microsoftonline.com
RemoteAddress    : 20.190.160.71
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : management.azure.com
RemoteAddress    : 40.79.131.240
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : management.core.windows.net
RemoteAddress    : 23.102.135.246
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : microsoft.com
RemoteAddress    : 40.76.4.15
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName   : microsoftonline.com
RemoteAddress  : 
InterfaceAlias : 
SourceAddress  : 
PingSucceeded  : False

ComputerName     : static2.sharepointonline.com
RemoteAddress    : 104.69.98.128
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : visualstudio.com
RemoteAddress    : 13.107.42.18
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : vsrm.dev.azure.com
RemoteAddress    : 13.107.43.20
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : vstsagentpackage.azureedge.net
RemoteAddress    : 152.199.19.161
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : windows.net
RemoteAddress    : 13.77.161.179
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : login.microsoftonline.com
RemoteAddress    : 20.190.160.129
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : app.vssps.visualstudio.com
RemoteAddress    : 13.107.43.18
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : XXXXX.visualstudio.com
RemoteAddress    : 13.107.42.18
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : XXXXX.vsrm.visualstudio.com
RemoteAddress    : 13.107.43.18
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : XXXXX.vstmr.visualstudio.com
RemoteAddress    : 13.107.43.18
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : XXXXX.pkgs.visualstudio.com
RemoteAddress    : 13.107.43.18
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

ComputerName     : XXXXX.vssps.visualstudio.com
RemoteAddress    : 13.107.42.18
RemotePort       : 443
InterfaceAlias   : Ethernet
SourceAddress    : 10.176.136.29
TcpTestSucceeded : True

I've also tried to deactivate the upload with the publishRunAttachments: false argument but it does not work anymore.

Is it possible to override the timeout value?
Is it possible to fully deactivate the upload of .trx and .coverage files?
Is it possible that the M$ DNS list is out-of-date with missing?

Thank you.

Task logs

2021-10-28T08:15:12.0921756Z ##[debug]Evaluating condition for step: 'Test Assemblies'
2021-10-28T08:15:12.0924993Z ##[debug]Evaluating: SucceededNode()
2021-10-28T08:15:12.0925932Z ##[debug]Evaluating SucceededNode:
2021-10-28T08:15:12.0927406Z ##[debug]=> True
2021-10-28T08:15:12.0928748Z ##[debug]Result: True
2021-10-28T08:15:12.0930007Z ##[section]Starting: Test Assemblies
2021-10-28T08:15:12.1200493Z ==============================================================================
2021-10-28T08:15:12.1200958Z Task         : Visual Studio Test
2021-10-28T08:15:12.1201879Z Description  : Run unit and functional tests (Selenium, Appium, Coded UI test, etc.) using the Visual Studio Test (VsTest) runner. Test frameworks that have a Visual Studio test adapter such as MsTest, xUnit, NUnit, Chutzpah (for JavaScript tests using QUnit, Mocha and Jasmine), etc. can be run. Tests can be distributed on multiple agents using this task (version 2).
2021-10-28T08:15:12.1202807Z Version      : 2.170.1
2021-10-28T08:15:12.1203159Z Author       : Microsoft Corporation
2021-10-28T08:15:12.1203673Z Help         : https://docs.microsoft.com/azure/devops/pipelines/tasks/test/vstest
2021-10-28T08:15:12.1204171Z ==============================================================================
2021-10-28T08:15:12.1294618Z ##[debug]Using node path: C:\azagents\BuildAgents\BuildAgent_1\externals\node\bin\node.exe
2021-10-28T08:15:12.4069088Z ##[debug]agent.TempDirectory=C:\azagents\BuildAgents\BuildAgent_1\_work\_temp
2021-10-28T08:15:12.4097263Z ##[debug]loading inputs and endpoints
2021-10-28T08:15:12.4103271Z ##[debug]loading ENDPOINT_AUTH_PARAMETER_SYSTEMVSSCONNECTION_ACCESSTOKEN
2021-10-28T08:15:12.4154368Z ##[debug]loading ENDPOINT_AUTH_SCHEME_SYSTEMVSSCONNECTION
2021-10-28T08:15:12.4159741Z ##[debug]loading ENDPOINT_AUTH_SYSTEMVSSCONNECTION
2021-10-28T08:15:12.4162955Z ##[debug]loading INPUT_BATCHINGBASEDONAGENTSOPTION
2021-10-28T08:15:12.4166106Z ##[debug]loading INPUT_BATCHINGBASEDONEXECUTIONTIMEOPTION
2021-10-28T08:15:12.4168899Z ##[debug]loading INPUT_CODECOVERAGEENABLED
2021-10-28T08:15:12.4171692Z ##[debug]loading INPUT_COLLECTDUMPON
2021-10-28T08:15:12.4174504Z ##[debug]loading INPUT_CONFIGURATION
2021-10-28T08:15:12.4177602Z ##[debug]loading INPUT_CUSTOMBATCHSIZEVALUE
2021-10-28T08:15:12.4180439Z ##[debug]loading INPUT_CUSTOMRUNTIMEPERBATCHVALUE
2021-10-28T08:15:12.4183197Z ##[debug]loading INPUT_DIAGNOSTICSENABLED
2021-10-28T08:15:12.4185925Z ##[debug]loading INPUT_DISTRIBUTIONBATCHTYPE
2021-10-28T08:15:12.4188714Z ##[debug]loading INPUT_DONTDISTRIBUTE
2021-10-28T08:15:12.4191506Z ##[debug]loading INPUT_FAILONMINTESTSNOTRUN
2021-10-28T08:15:12.4194294Z ##[debug]loading INPUT_MINIMUMEXPECTEDTESTS
2021-10-28T08:15:12.4197215Z ##[debug]loading INPUT_PLATFORM
2021-10-28T08:15:12.4200381Z ##[debug]loading INPUT_PUBLISHRUNATTACHMENTS
2021-10-28T08:15:12.4203441Z ##[debug]loading INPUT_RERUNFAILEDTESTCASESMAXLIMIT
2021-10-28T08:15:12.4216676Z ##[debug]loading INPUT_RERUNFAILEDTESTS
2021-10-28T08:15:12.4217798Z ##[debug]loading INPUT_RERUNFAILEDTHRESHOLD
2021-10-28T08:15:12.4218681Z ##[debug]loading INPUT_RERUNMAXATTEMPTS
2021-10-28T08:15:12.4225989Z ##[debug]loading INPUT_RERUNTYPE
2021-10-28T08:15:12.4229801Z ##[debug]loading INPUT_RESULTSFOLDER
2021-10-28T08:15:12.4233285Z ##[debug]loading INPUT_RUNALLTESTSAFTERXBUILDS
2021-10-28T08:15:12.4236623Z ##[debug]loading INPUT_RUNINPARALLEL
2021-10-28T08:15:12.4240127Z ##[debug]loading INPUT_RUNONLYIMPACTEDTESTS
2021-10-28T08:15:12.4243723Z ##[debug]loading INPUT_RUNSETTINGSFILE
2021-10-28T08:15:12.4248929Z ##[debug]loading INPUT_RUNTESTSINISOLATION
2021-10-28T08:15:12.4266001Z ##[debug]loading INPUT_SEARCHFOLDER
2021-10-28T08:15:12.4267296Z ##[debug]loading INPUT_TCMTESTRUN
2021-10-28T08:15:12.4270108Z ##[debug]loading INPUT_TESTASSEMBLYVER2
2021-10-28T08:15:12.4274288Z ##[debug]loading INPUT_TESTSELECTOR
2021-10-28T08:15:12.4278507Z ##[debug]loading INPUT_UITESTS
2021-10-28T08:15:12.4287604Z ##[debug]loading INPUT_VSTESTLOCATIONMETHOD
2021-10-28T08:15:12.4291045Z ##[debug]loading INPUT_VSTESTVERSION
2021-10-28T08:15:12.4304046Z ##[debug]loading SECRET_SONARQUBE_ENDPOINT
2021-10-28T08:15:12.4307933Z ##[debug]loading SECRET_SYSTEM_ACCESSTOKEN
2021-10-28T08:15:12.4318155Z ##[debug]loaded 37
2021-10-28T08:15:12.4334583Z ##[debug]Agent.ProxyUrl=undefined
2021-10-28T08:15:12.4336476Z ##[debug]Agent.CAInfo=undefined
2021-10-28T08:15:12.4337312Z ##[debug]Agent.ClientCert=undefined
2021-10-28T08:15:12.4338082Z ##[debug]Agent.SkipCertValidation=undefined
2021-10-28T08:15:12.5935940Z ##[debug]System.DefaultWorkingDirectory=C:\azagents\BuildAgents\BuildAgent_1\_work\3\s
2021-10-28T08:15:12.8858988Z ##[debug]check path : C:\azagents\BuildAgents\BuildAgent_1\_work\_tasks\VSTest_ef087383-ee5e-42c7-9a53-ab56c98420f9\2.170.1\task.json
2021-10-28T08:15:12.8860210Z ##[debug]adding resource file: C:\azagents\BuildAgents\BuildAgent_1\_work\_tasks\VSTest_ef087383-ee5e-42c7-9a53-ab56c98420f9\2.170.1\task.json
2021-10-28T08:15:12.8860957Z ##[debug]system.culture=en-US
2021-10-28T08:15:12.8952124Z ##[debug]Agent.Version=2.186.1
2021-10-28T08:15:12.8952806Z ##[debug]Release.ReleaseUri=undefined
2021-10-28T08:15:12.8953351Z ##[debug]Release.ReleaseId=undefined
2021-10-28T08:15:12.8953924Z ##[debug]Build.BuildUri=vstfs:///Build/Build/4292
2021-10-28T08:15:12.8954348Z ##[debug]Build.Buildid=4292
2021-10-28T08:15:12.8955004Z ##[debug]telemetry area: TestExecution feature: TestExecutionTask data: {"builduri":"vstfs:///Build/Build/4292","buildid":"4292","state":"started"}
2021-10-28T08:15:12.9224233Z ##[debug]Processed: ##vso[telemetry.publish area=TestExecution;feature=TestExecutionTask;]{"builduri":"vstfs:///Build/Build/4292","buildid":"4292","state":"started"}
2021-10-28T08:15:12.9226140Z ##[debug]System.TeamFoundationCollectionUri=https://dev.azure.com/c4game/
2021-10-28T08:15:12.9226781Z SystemVssConnection exists true
2021-10-28T08:15:12.9227282Z ##[debug]SystemVssConnection exists true
2021-10-28T08:15:13.1803067Z ##[debug] Final feature flag state: false
2021-10-28T08:15:13.1806386Z ##[debug]Changing active code page to UTF-8
2021-10-28T08:15:13.1810494Z ##[debug]which 'C:\Windows\system32\chcp.com'
2021-10-28T08:15:13.1817906Z ##[debug]found: 'C:\Windows\system32\chcp.com'
2021-10-28T08:15:13.1818529Z ##[debug]which 'C:\Windows\system32\chcp.com'
2021-10-28T08:15:13.1819920Z ##[debug]found: 'C:\Windows\system32\chcp.com'
2021-10-28T08:15:13.1822234Z ##[debug]C:\Windows\system32\chcp.com arg: ["65001"]
2021-10-28T08:15:13.1827371Z ##[debug]exec tool: C:\Windows\system32\chcp.com
2021-10-28T08:15:13.1827922Z ##[debug]arguments:
2021-10-28T08:15:13.1830855Z ##[debug]   65001
2021-10-28T08:15:13.2063395Z ##[debug]testSelector=testAssemblies
2021-10-28T08:15:13.2063969Z ##[debug]System.ParallelExecutionType=undefined
2021-10-28T08:15:13.2067691Z ##[debug]distributionBatchType=basedOnTestCases
2021-10-28T08:15:13.2071548Z ##[debug]batchingBasedOnAgentsOption=autoBatchSize
2021-10-28T08:15:13.2074384Z ##[debug]testSelector=testAssemblies
2021-10-28T08:15:13.2074857Z ##[debug]Value of Test Selector :testAssemblies
2021-10-28T08:15:13.2075476Z ##[debug]System.ParallelExecutionType=undefined
2021-10-28T08:15:13.2075929Z ##[debug]Value of ParallelExecutionType :undefined
2021-10-28T08:15:13.2076585Z ##[debug]System.TeamFoundationCollectionUri=https://dev.azure.com/c4game/
2021-10-28T08:15:13.2082421Z SystemVssConnection exists true
2021-10-28T08:15:13.2083042Z ##[debug]SystemVssConnection exists true
2021-10-28T08:15:13.3325307Z ##[debug] Final feature flag state: true
2021-10-28T08:15:13.3326833Z ##[debug]Diagnostics feature flag is set to: true
2021-10-28T08:15:13.3327961Z ##[debug]Agent.Version=2.186.1
2021-10-28T08:15:13.3328564Z ##[debug]Release.ReleaseUri=undefined
2021-10-28T08:15:13.3329141Z ##[debug]Release.ReleaseId=undefined
2021-10-28T08:15:13.3329682Z ##[debug]Build.BuildUri=vstfs:///Build/Build/4292
2021-10-28T08:15:13.3330205Z ##[debug]Build.Buildid=4292
2021-10-28T08:15:13.3330918Z ##[debug]telemetry area: TestExecution feature: TestExecutionTask data: {"builduri":"vstfs:///Build/Build/4292","buildid":"4292","runmode":"nondistributed"}
2021-10-28T08:15:13.3728291Z ##[debug]Processed: ##vso[telemetry.publish area=TestExecution;feature=TestExecutionTask;]{"builduri":"vstfs:///Build/Build/4292","buildid":"4292","runmode":"nondistributed"}
2021-10-28T08:15:13.3729419Z Running tests using vstest.console.exe runner.
2021-10-28T08:15:13.3729905Z ======================================================
2021-10-28T08:15:13.3730939Z ##[debug]testSelector=testAssemblies
2021-10-28T08:15:13.3731508Z Test selector : Test assemblies
2021-10-28T08:15:13.3732024Z ##[debug]testFiltercriteria=null
2021-10-28T08:15:13.3732509Z Test filter criteria : null
2021-10-28T08:15:13.3733115Z ##[debug]searchFolder=C:\azagents\BuildAgents\BuildAgent_1\_work\3\s
2021-10-28T08:15:13.3733856Z Search folder : C:\azagents\BuildAgents\BuildAgent_1\_work\3\s
2021-10-28T08:15:13.3734524Z ##[debug]Release.DefinitionId=undefined
2021-10-28T08:15:13.3735216Z ##[debug]System.DefinitionId=21
2021-10-28T08:15:13.3735888Z ##[debug]Release.DefinitionId=undefined
2021-10-28T08:15:13.3736461Z ##[debug]Build.Buildid=4292
2021-10-28T08:15:13.3737035Z ##[debug]Build.Buildid=4292
2021-10-28T08:15:13.3737671Z ##[debug]Build.BuildUri=vstfs:///Build/Build/4292
2021-10-28T08:15:13.3738347Z ##[debug]Release.ReleaseId=undefined
2021-10-28T08:15:13.3738973Z ##[debug]Release.ReleaseUri=undefined
2021-10-28T08:15:13.3739538Z ##[debug]Release.EnvironmentUri=undefined
2021-10-28T08:15:13.3740098Z ##[debug]System.DefaultWorkingDirectory=C:\azagents\BuildAgents\BuildAgent_1\_work\3\s
2021-10-28T08:15:13.3742004Z ##[debug]System.PhaseName=Job
2021-10-28T08:15:13.3742397Z ##[debug]System.PhaseAttempt=1
2021-10-28T08:15:13.3742797Z ##[debug]System.PhaseAttempt=1
2021-10-28T08:15:13.3743200Z ##[debug]System.StageName=__default
2021-10-28T08:15:13.3743593Z ##[debug]System.StageAttempt=1
2021-10-28T08:15:13.3743976Z ##[debug]System.StageAttempt=1
2021-10-28T08:15:13.3744369Z ##[debug]System.JobName=__default
2021-10-28T08:15:13.3744768Z ##[debug]System.JobAttempt=1
2021-10-28T08:15:13.3745148Z ##[debug]System.JobAttempt=1
2021-10-28T08:15:13.3745618Z ##[debug]configuration=Release
2021-10-28T08:15:13.3746051Z ##[debug]platform=Any CPU
2021-10-28T08:15:13.3746426Z ##[debug]testRunTitle=null
2021-10-28T08:15:13.3746879Z ##[debug]Agent.TempDirectory=C:\azagents\BuildAgents\BuildAgent_1\_work\_temp
2021-10-28T08:15:13.3747433Z ##[debug]resultsFolder=C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\TestResults
2021-10-28T08:15:13.3747993Z ##[debug]TestResultsFolder: C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\TestResults
2021-10-28T08:15:13.3748537Z ##[debug]Agent.TempDirectory=C:\azagents\BuildAgents\BuildAgent_1\_work\_temp
2021-10-28T08:15:13.3749164Z ##[debug]telemetry area: TestExecution feature: ResultsDirectory data: {"TestResultsFolderUi":"$(Agent.TempDirectory)/TestResults"}
2021-10-28T08:15:13.4180677Z ##[debug]Processed: ##vso[telemetry.publish area=TestExecution;feature=ResultsDirectory;]{"TestResultsFolderUi":"$(Agent.TempDirectory)/TestResults"}
2021-10-28T08:15:13.4182129Z ##[debug]System.PullRequest.TargetBranch=undefined
2021-10-28T08:15:13.4182814Z ##[debug]vstest.ignoretestfailures=undefined
2021-10-28T08:15:13.4183444Z ##[debug]BUILD_DEFINITIONNAME=CI - DataCentric
2021-10-28T08:15:13.4184074Z ##[debug]BUILD_BUILDNUMBER=20211028.3
2021-10-28T08:15:13.4184524Z ##[debug]failOnMinTestsNotRun=False
2021-10-28T08:15:13.4185053Z Action when minimum tests threshold not met : donothing
2021-10-28T08:15:13.4185545Z Minimum tests expected to be run: 0
2021-10-28T08:15:13.4185931Z ##[debug]vstestLocationMethod=version
2021-10-28T08:15:13.4186324Z ##[debug]vsTestVersion=latest
2021-10-28T08:15:13.4186689Z VisualStudio version selected for test execution : latest
2021-10-28T08:15:13.4187065Z ##[debug]vsTestVersion=latest
2021-10-28T08:15:13.4187468Z ##[debug]Searching for latest Visual Studio.
2021-10-28T08:15:13.4188017Z ##[debug]which 'C:\azagents\BuildAgents\BuildAgent_1\_work\_tasks\VSTest_ef087383-ee5e-42c7-9a53-ab56c98420f9\2.170.1\vswhere.exe'
2021-10-28T08:15:13.4189468Z ##[debug]found: 'C:\azagents\BuildAgents\BuildAgent_1\_work\_tasks\VSTest_ef087383-ee5e-42c7-9a53-ab56c98420f9\2.170.1\vswhere.exe'
2021-10-28T08:15:13.4190503Z ##[debug]which 'C:\azagents\BuildAgents\BuildAgent_1\_work\_tasks\VSTest_ef087383-ee5e-42c7-9a53-ab56c98420f9\2.170.1\vswhere.exe'
2021-10-28T08:15:13.4191463Z ##[debug]found: 'C:\azagents\BuildAgents\BuildAgent_1\_work\_tasks\VSTest_ef087383-ee5e-42c7-9a53-ab56c98420f9\2.170.1\vswhere.exe'
2021-10-28T08:15:13.4192073Z Attempting to find vstest.console from a visual studio installation with version [16.0,17.0).
2021-10-28T08:15:13.4192885Z ##[debug]C:\azagents\BuildAgents\BuildAgent_1\_work\_tasks\VSTest_ef087383-ee5e-42c7-9a53-ab56c98420f9\2.170.1\vswhere.exe arg: -version [16.0,17.0) -latest -products * -requires Microsoft.VisualStudio.PackageGroup.TestTools.Core -property installationPath
2021-10-28T08:15:13.4193768Z ##[debug]exec tool: C:\azagents\BuildAgents\BuildAgent_1\_work\_tasks\VSTest_ef087383-ee5e-42c7-9a53-ab56c98420f9\2.170.1\vswhere.exe
2021-10-28T08:15:13.4194297Z ##[debug]arguments:
2021-10-28T08:15:13.4194673Z ##[debug]   -version
2021-10-28T08:15:13.4195102Z ##[debug]   [16.0,17.0)
2021-10-28T08:15:13.4195534Z ##[debug]   -latest
2021-10-28T08:15:13.4195891Z ##[debug]   -products
2021-10-28T08:15:13.4196240Z ##[debug]   *
2021-10-28T08:15:13.4196589Z ##[debug]   -requires
2021-10-28T08:15:13.4197002Z ##[debug]   Microsoft.VisualStudio.PackageGroup.TestTools.Core
2021-10-28T08:15:13.4197983Z ##[debug]   -property
2021-10-28T08:15:13.4198358Z ##[debug]   installationPath
2021-10-28T08:15:13.4199009Z ##[debug]Visual Studio 15.0 or higher installed path: C:\Program Files (x86)\Microsoft Visual Studio\2019\Enterprise
2021-10-28T08:15:13.4199573Z ##[debug]System.Debug=true
2021-10-28T08:15:13.4199959Z ##[debug]agent.proxyurl=undefined
2021-10-28T08:15:13.4200359Z ##[debug]agent.proxyusername=undefined
2021-10-28T08:15:13.4200765Z ##[debug]agent.proxypassword=undefined
2021-10-28T08:15:13.4201179Z ##[debug]agent.proxybypasslist=undefined
2021-10-28T08:15:13.4201696Z ##[debug]runSettingsFile=C:\azagents\BuildAgents\BuildAgent_1\_work\3\s
2021-10-28T08:15:13.4202371Z ##[debug]Absolute path for pathSegments: C:\azagents\BuildAgents\BuildAgent_1\_work\3\s = C:\azagents\BuildAgents\BuildAgent_1\_work\3\s
2021-10-28T08:15:13.4203007Z ##[debug]build.sourcesDirectory=C:\azagents\BuildAgents\BuildAgent_1\_work\3\s
2021-10-28T08:15:13.4203657Z ##[debug]Absolute path for pathSegments: C:\azagents\BuildAgents\BuildAgent_1\_work\3\s = C:\azagents\BuildAgents\BuildAgent_1\_work\3\s
2021-10-28T08:15:13.4204253Z ##[debug]runSettingsFilepath supplied :false
2021-10-28T08:15:13.4204672Z ##[debug]Agent.Version=2.186.1
2021-10-28T08:15:13.4205130Z ##[debug]Agent.TempDirectory=C:\azagents\BuildAgents\BuildAgent_1\_work\_temp
2021-10-28T08:15:13.4205598Z ##[debug]overrideTestrunParameters=null
2021-10-28T08:15:13.4206014Z ##[debug]OverrideTestrunParameters set to null
2021-10-28T08:15:13.4206418Z ##[debug]runInParallel=False
2021-10-28T08:15:13.4206736Z Run in parallel : false
2021-10-28T08:15:13.4207159Z ##[debug]runTestsInIsolation=False
2021-10-28T08:15:13.4207476Z Run in isolation : false
2021-10-28T08:15:13.4207809Z ##[debug]pathtoCustomTestAdapters=null
2021-10-28T08:15:13.4208234Z Path to custom adapters : null
2021-10-28T08:15:13.4208731Z ##[debug]ProceedAfterAbortedTestCase=undefined
2021-10-28T08:15:13.4209242Z ##[debug]ProceedAfterAbortedTestCase is set to : false
2021-10-28T08:15:13.4209669Z ##[debug]uiTests=false
2021-10-28T08:15:13.4210047Z ##[debug]otherConsoleOptions=null
2021-10-28T08:15:13.4210424Z Other console options : null
2021-10-28T08:15:13.4210924Z ##[debug]codeCoverageEnabled=true
2021-10-28T08:15:13.4211392Z Code coverage enabled : true
2021-10-28T08:15:13.4211832Z ##[debug]diagnosticsEnabled=false
2021-10-28T08:15:13.4212351Z ##[debug]collectDumpOn=onAbortOnly
2021-10-28T08:15:13.4212802Z Diagnostics enabled : false
2021-10-28T08:15:13.4213735Z ##[debug]vstest.customConsoleWrapperAssemblyLocation=undefined
2021-10-28T08:15:13.4214194Z ##[debug]runOnlyImpactedTests=False
2021-10-28T08:15:13.4214588Z ##[debug]rerunFailedTests=False
2021-10-28T08:15:13.4214979Z ##[debug]System.TeamProject=appGame
2021-10-28T08:15:13.4215465Z ##[debug]System.TeamFoundationCollectionUri=https://dev.azure.com/c4game/
2021-10-28T08:15:13.4215838Z SystemVssConnection exists true
2021-10-28T08:15:13.4216175Z ##[debug]SystemVssConnection exists true
2021-10-28T08:15:13.4216595Z ##[debug]Agent.MachineName=XXXXX
2021-10-28T08:15:13.4217014Z ##[debug]Agent.Name=XXXXX
2021-10-28T08:15:13.4217388Z ##[debug]Agent.Id=47
2021-10-28T08:15:13.4217762Z ##[debug]DTA_INSTANCE_ID=undefined
2021-10-28T08:15:13.4218150Z ##[debug]set DTA_INSTANCE_ID=1
2021-10-28T08:15:13.4219332Z ##[debug]Processed: ##vso[task.setvariable variable=DTA_INSTANCE_ID;issecret=false;]1
2021-10-28T08:15:13.4219805Z ##[debug]dontDistribute=False
2021-10-28T08:15:13.4220216Z ##[debug]Release.ReleaseId=undefined
2021-10-28T08:15:13.4220613Z ##[debug]System.JobPositionInPhase=1
2021-10-28T08:15:13.4221029Z ##[debug]System.ParallelExecutionType=undefined
2021-10-28T08:15:13.4221478Z ##[debug]System.PhaseId=3a3a2a60-14c7-570b-14a4-fa42ad92f52a
2021-10-28T08:15:13.4221911Z ##[debug]Hydra.EnableApiFlow=undefined
2021-10-28T08:15:13.4222381Z ##[debug]Build.SourcesDirectory=C:\azagents\BuildAgents\BuildAgent_1\_work\3\s
2021-10-28T08:15:13.4222837Z ##[debug]System.ServerType=Hosted
2021-10-28T08:15:13.4223540Z ##[debug]uiTests=false
2021-10-28T08:15:13.4223950Z ##[debug]Enabling Hydra flow since serverType is hosted.
2021-10-28T08:15:13.4224450Z ##[debug]testAssemblyVer2=**\Release\*test*.dll
!**\obj\**
2021-10-28T08:15:13.4224813Z Run the tests locally using vstest.console.exe
2021-10-28T08:15:13.4225116Z ========================================================
2021-10-28T08:15:13.4225622Z ##[debug]which 'C:\Program Files (x86)\Microsoft Visual Studio\2019\Enterprise\Common7\IDE\Extensions\TestPlatform\vstest.console.exe'
2021-10-28T08:15:13.4226305Z ##[debug]found: 'C:\Program Files (x86)\Microsoft Visual Studio\2019\Enterprise\Common7\IDE\Extensions\TestPlatform\vstest.console.exe'
2021-10-28T08:15:13.4226979Z ##[debug]which 'C:\Program Files (x86)\Microsoft Visual Studio\2019\Enterprise\Common7\IDE\Extensions\TestPlatform\vstest.console.exe'
2021-10-28T08:15:13.4227780Z ##[debug]found: 'C:\Program Files (x86)\Microsoft Visual Studio\2019\Enterprise\Common7\IDE\Extensions\TestPlatform\vstest.console.exe'
2021-10-28T08:15:13.4228314Z Source filter: **\Release\*test*.dll,!**\obj\**
2021-10-28T08:15:13.4228745Z ##[debug]defaultRoot: 'C:\azagents\BuildAgents\BuildAgent_1\_work\3\s'
2021-10-28T08:15:13.4229241Z ##[debug]findOptions.allowBrokenSymbolicLinks: 'false'
2021-10-28T08:15:13.4229726Z ##[debug]findOptions.followSpecifiedSymbolicLink: 'true'
2021-10-28T08:15:13.4230209Z ##[debug]findOptions.followSymbolicLinks: 'true'
2021-10-28T08:15:13.4230651Z ##[debug]matchOptions.debug: 'false'
2021-10-28T08:15:13.4231073Z ##[debug]matchOptions.nobrace: 'true'
2021-10-28T08:15:13.4231504Z ##[debug]matchOptions.noglobstar: 'false'
2021-10-28T08:15:13.4231924Z ##[debug]matchOptions.dot: 'true'
2021-10-28T08:15:13.4232343Z ##[debug]matchOptions.noext: 'false'
2021-10-28T08:15:13.4232777Z ##[debug]matchOptions.nocase: 'true'
2021-10-28T08:15:13.4233198Z ##[debug]matchOptions.nonull: 'false'
2021-10-28T08:15:13.4233628Z ##[debug]matchOptions.matchBase: 'false'
2021-10-28T08:15:13.4234062Z ##[debug]matchOptions.nocomment: 'false'
2021-10-28T08:15:13.4234494Z ##[debug]matchOptions.nonegate: 'false'
2021-10-28T08:15:13.4234925Z ##[debug]matchOptions.flipNegate: 'false'
2021-10-28T08:15:13.4235352Z ##[debug]pattern: '**\Release\*test*.dll'
2021-10-28T08:15:13.4237188Z ##[debug]findPath: 'C:\azagents\BuildAgents\BuildAgent_1\_work\3\s'
2021-10-28T08:15:13.4237697Z ##[debug]statOnly: 'false'
2021-10-28T08:15:13.4240378Z ##[debug]findPath: 'C:\azagents\BuildAgents\BuildAgent_1\_work\3\s'
2021-10-28T08:15:13.4241995Z ##[debug]findOptions.allowBrokenSymbolicLinks: 'false'
2021-10-28T08:15:13.4242483Z ##[debug]findOptions.followSpecifiedSymbolicLink: 'true'
2021-10-28T08:15:13.4242960Z ##[debug]findOptions.followSymbolicLinks: 'true'
2021-10-28T08:15:13.4245588Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s (directory)
2021-10-28T08:15:13.4257398Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.doc (directory)
2021-10-28T08:15:13.4265944Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.doc\images (directory)
2021-10-28T08:15:13.4273323Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.doc\images\game-feed.png (file)
2021-10-28T08:15:13.4274222Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git (directory)
2021-10-28T08:15:13.4281689Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\config (file)
2021-10-28T08:15:13.4282494Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\description (file)
2021-10-28T08:15:13.4283689Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\FETCH_HEAD (file)
2021-10-28T08:15:13.4284369Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\HEAD (file)
2021-10-28T08:15:13.4285145Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\hooks (directory)
2021-10-28T08:15:13.4293390Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\hooks\applypatch-msg.sample (file)
2021-10-28T08:15:13.4294274Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\hooks\commit-msg.sample (file)
2021-10-28T08:15:13.4295444Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\hooks\fsmonitor-watchman.sample (file)
2021-10-28T08:15:13.4296163Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\hooks\post-update.sample (file)
2021-10-28T08:15:13.4297322Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\hooks\pre-applypatch.sample (file)
2021-10-28T08:15:13.4298119Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\hooks\pre-commit.sample (file)
2021-10-28T08:15:13.4298919Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\hooks\pre-merge-commit.sample (file)
2021-10-28T08:15:13.4300042Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\hooks\pre-push.sample (file)
2021-10-28T08:15:13.4300790Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\hooks\pre-rebase.sample (file)
2021-10-28T08:15:13.4301975Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\hooks\pre-receive.sample (file)
2021-10-28T08:15:13.4302702Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\hooks\prepare-commit-msg.sample (file)
2021-10-28T08:15:13.4303787Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\hooks\push-to-checkout.sample (file)
2021-10-28T08:15:13.4304557Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\hooks\update.sample (file)
2021-10-28T08:15:13.4306939Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\index (file)
2021-10-28T08:15:13.4307813Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\info (directory)
2021-10-28T08:15:13.4317825Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\info\exclude (file)
2021-10-28T08:15:13.4318553Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\logs (directory)
2021-10-28T08:15:13.4325202Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\logs\HEAD (file)
2021-10-28T08:15:13.4325944Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\logs\refs (directory)
2021-10-28T08:15:13.4334196Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\logs\refs\remotes (directory)
2021-10-28T08:15:13.4347579Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\logs\refs\remotes\origin (directory)
2021-10-28T08:15:13.4364554Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\logs\refs\remotes\origin\f36ed670ed4ec5a4c376ef71406eed270f48c3bd (file)
2021-10-28T08:15:13.4366121Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\logs\refs\remotes\origin\master (file)
2021-10-28T08:15:13.4366742Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\objects (directory)
2021-10-28T08:15:13.4373491Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\objects\info (directory)
2021-10-28T08:15:13.4380099Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\objects\pack (directory)
2021-10-28T08:15:13.4388083Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\objects\pack\pack-40457e657f4484bf7ab0603fd5c9e4cf103bdcc4.idx (file)
2021-10-28T08:15:13.4388975Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\objects\pack\pack-40457e657f4484bf7ab0603fd5c9e4cf103bdcc4.pack (file)
2021-10-28T08:15:13.4389673Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\refs (directory)
2021-10-28T08:15:13.4396487Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\refs\heads (directory)
2021-10-28T08:15:13.4404758Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\refs\remotes (directory)
2021-10-28T08:15:13.4412267Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\refs\remotes\origin (directory)
2021-10-28T08:15:13.4420272Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\refs\remotes\origin\f36ed670ed4ec5a4c376ef71406eed270f48c3bd (file)
2021-10-28T08:15:13.4421057Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\refs\remotes\origin\master (file)
2021-10-28T08:15:13.4422364Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.git\refs\tags (directory)
2021-10-28T08:15:13.4429487Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\.gitignore (file)
2021-10-28T08:15:13.4431893Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\pipeline (directory)
2021-10-28T08:15:13.4438216Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\pipeline\azure (directory)
2021-10-28T08:15:13.4445618Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\pipeline\azure\azure-pipelines.yml (file)
2021-10-28T08:15:13.4446375Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\pipeline\azure\tasks (directory)
2021-10-28T08:15:13.4453114Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\pipeline\azure\tasks\deploy-readfrombroker-service-task.json (file)
2021-10-28T08:15:13.4454027Z ##[debug]  C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\pipeline\azure\tasks\deploy-sendtobroker-service-task.json (file)
[...fetching...]
2021-10-28T08:15:13.8002213Z ##[debug]1449 results
2021-10-28T08:15:13.8002597Z ##[debug]found 1449 paths
2021-10-28T08:15:13.8002983Z ##[debug]applying include pattern
2021-10-28T08:15:13.8003476Z ##[debug]adjustedPattern: 'C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\**\Release\*test*.dll'
2021-10-28T08:15:13.8171444Z ##[debug]9 matches
2021-10-28T08:15:13.8172006Z ##[debug]pattern: '!**\obj\**'
2021-10-28T08:15:13.8172471Z ##[debug]trimmed leading '!'. pattern: '**\obj\**'
2021-10-28T08:15:13.8173020Z ##[debug]after ensurePatternRooted, pattern: 'C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\**\obj\**'
2021-10-28T08:15:13.8173525Z ##[debug]applying exclude pattern
2021-10-28T08:15:13.8176729Z ##[debug]3 matches
2021-10-28T08:15:13.8178012Z ##[debug]6 final results
2021-10-28T08:15:13.8179230Z ##[debug]Time taken for applying the minimatch pattern to filter out the sources 391 ms
2021-10-28T08:15:13.8179769Z ##[debug]6 files matched the given minimatch filter
2021-10-28T08:15:13.8180435Z ##[debug]telemetry area: TestExecution feature: MinimatchFilterPerformance data: {"MiniMatchLines":2,"ExecutionFlow":"NonDistributed","TimeToSearchDLLsInMilliSeconds":391}
2021-10-28T08:15:13.8595957Z ##[debug]Processed: ##vso[telemetry.publish area=TestExecution;feature=MinimatchFilterPerformance;]{"MiniMatchLines":2,"ExecutionFlow":"NonDistributed","TimeToSearchDLLsInMilliSeconds":391}
2021-10-28T08:15:13.8597820Z ##[debug]Files matching count :6
2021-10-28T08:15:13.8598684Z ##[debug]Agent.Version=2.186.1
2021-10-28T08:15:13.8599397Z ##[debug]Agent.TempDirectory=C:\azagents\BuildAgents\BuildAgent_1\_work\_temp
2021-10-28T08:15:13.8600201Z ##[debug]Test Sources file :C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\testSources_2d348900-37c7-11ec-ba87-9d57af20f4cb.src
2021-10-28T08:15:13.8600918Z ##[debug]which 'C:\azagents\BuildAgents\BuildAgent_1\_work\_tasks\VSTest_ef087383-ee5e-42c7-9a53-ab56c98420f9\2.170.1\Modules\DTAExecutionHost.exe'
2021-10-28T08:15:13.8601762Z ##[debug]found: 'C:\azagents\BuildAgents\BuildAgent_1\_work\_tasks\VSTest_ef087383-ee5e-42c7-9a53-ab56c98420f9\2.170.1\Modules\DTAExecutionHost.exe'
2021-10-28T08:15:13.8602478Z ##[debug]which 'C:\azagents\BuildAgents\BuildAgent_1\_work\_tasks\VSTest_ef087383-ee5e-42c7-9a53-ab56c98420f9\2.170.1\Modules\DTAExecutionHost.exe'
2021-10-28T08:15:13.8604415Z ##[debug]found: 'C:\azagents\BuildAgents\BuildAgent_1\_work\_tasks\VSTest_ef087383-ee5e-42c7-9a53-ab56c98420f9\2.170.1\Modules\DTAExecutionHost.exe'
2021-10-28T08:15:13.8605241Z ##[debug]Agent.Version=2.186.1
2021-10-28T08:15:13.8605977Z ##[debug]Agent.TempDirectory=C:\azagents\BuildAgents\BuildAgent_1\_work\_temp
2021-10-28T08:15:13.8606712Z ##[debug]Removing node TestCaseFilter as its value is null.
2021-10-28T08:15:13.8607184Z ##[debug]Removing node ReleaseDefinitionId as its value is null.
2021-10-28T08:15:13.8607660Z ##[debug]Removing node ReleaseId as its value is null.
2021-10-28T08:15:13.8608117Z ##[debug]Removing node ReleaseUri as its value is undefined.
2021-10-28T08:15:13.8608592Z ##[debug]Removing node ReleaseEnvironmentUri as its value is undefined.
2021-10-28T08:15:13.8609092Z ##[debug]Removing node PullRequestTargetBranchName as its value is undefined.
2021-10-28T08:15:13.8609596Z ##[debug]Removing node TestSourceSettings as its value is [object Object].
2021-10-28T08:15:13.8610092Z ##[debug]Removing node ProxyUrl as its value is undefined.
2021-10-28T08:15:13.8610558Z ##[debug]Removing node ProxyUsername as its value is undefined.
2021-10-28T08:15:13.8611024Z ##[debug]Removing node ProxyPassword as its value is undefined.
2021-10-28T08:15:13.8611527Z ##[debug]Removing node ProxyBypassHosts as its value is undefined.
2021-10-28T08:15:13.8612002Z ##[debug]Removing node ProxySettings as its value is [object Object].
2021-10-28T08:15:13.8612485Z ##[debug]Removing node OverridenParameters as its value is null.
2021-10-28T08:15:13.8612959Z ##[debug]Removing node PathToCustomTestAdapters as its value is null.
2021-10-28T08:15:13.8613449Z ##[debug]Removing node AdditionalConsoleParameters as its value is null.
2021-10-28T08:15:13.8613976Z ##[debug]Removing node PathToCustomVsTestConsoleWrapperAssembly as its value is undefined.
2021-10-28T08:15:13.8614822Z ##[debug]C:\azagents\BuildAgents\BuildAgent_1\_work\_tasks\VSTest_ef087383-ee5e-42c7-9a53-ab56c98420f9\2.170.1\Modules\DTAExecutionHost.exe arg: ["--inputFile","C:\\azagents\\BuildAgents\\BuildAgent_1\\_work\\_temp\\input_2d348901-37c7-11ec-ba87-9d57af20f4cb.json"]
2021-10-28T08:15:13.8615513Z SystemVssConnection exists true
2021-10-28T08:15:13.8615856Z ##[debug]SystemVssConnection exists true
2021-10-28T08:15:13.8616428Z ##[debug]exec tool: C:\azagents\BuildAgents\BuildAgent_1\_work\_tasks\VSTest_ef087383-ee5e-42c7-9a53-ab56c98420f9\2.170.1\Modules\DTAExecutionHost.exe
2021-10-28T08:15:13.8617416Z ##[debug]arguments:
2021-10-28T08:15:13.8617791Z ##[debug]   --inputFile
2021-10-28T08:15:13.8618292Z ##[debug]   C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\input_2d348901-37c7-11ec-ba87-9d57af20f4cb.json
2021-10-28T08:15:13.8619049Z [command]C:\azagents\BuildAgents\BuildAgent_1\_work\_tasks\VSTest_ef087383-ee5e-42c7-9a53-ab56c98420f9\2.170.1\Modules\DTAExecutionHost.exe --inputFile C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\input_2d348901-37c7-11ec-ba87-9d57af20f4cb.json
2021-10-28T08:15:13.9560997Z ======================================================
2021-10-28T08:15:13.9566170Z ##[debug]Agent.Version=2.186.1
2021-10-28T08:15:13.9567573Z ##[debug]Release.ReleaseUri=undefined
2021-10-28T08:15:13.9572058Z ##[debug]Release.ReleaseId=undefined
2021-10-28T08:15:13.9573388Z ##[debug]Build.BuildUri=vstfs:///Build/Build/4292
2021-10-28T08:15:13.9574493Z ##[debug]Build.Buildid=4292
2021-10-28T08:15:13.9576422Z ##[debug]telemetry area: TestExecution feature: TestExecutionTask data: {"builduri":"vstfs:///Build/Build/4292","buildid":"4292","state":"completed"}
2021-10-28T08:15:13.9991376Z ##[debug]Processed: ##vso[telemetry.publish area=TestExecution;feature=TestExecutionTask;]{"builduri":"vstfs:///Build/Build/4292","buildid":"4292","state":"completed"}
2021-10-28T08:15:14.0302245Z ##########################################################################
2021-10-28T08:15:14.0306695Z DtaExecutionHost version 18.170.30112.1.
2021-10-28T08:15:14.2911932Z ##[debug]Set DTA.AgentName to XXXXX-XXXXX-47.
2021-10-28T08:15:14.2922839Z ##[debug]Found file version info for vstest.console.exe present in C:\Program Files (x86)\Microsoft Visual Studio\2019\Enterprise\Common7\IDE\Extensions\TestPlatform to be 16.11.0-release-20210730-02
2021-10-28T08:15:14.2959264Z ##[debug]Forcing TPv2 as the vstest.console.exe pointed to is the TPv2 one.
2021-10-28T08:15:14.2968711Z ##[debug]Set DTA.ForcePlatformV2 to True.
2021-10-28T08:15:14.2969559Z ##[debug]Set DTA.TestPlatformVersion to 16.11.0-release-20210730-02.
2021-10-28T08:15:14.2970414Z ##[debug]Set TE.SourceFilter to ThisWontBeUsed.
2021-10-28T08:15:14.2971237Z ##[debug]Set DTA.TeamFoundationCollectionUri to https://dev.azure.com/c4game/.
2021-10-28T08:15:14.2981620Z ##[debug]Set DTA.EnvironmentUri to vstest://env/appGame/_apis/build/21/4292/3a3a2a60-14c7-570b-14a4-fa42ad92f52a/1.
2021-10-28T08:15:14.2984497Z ##[debug]Set DTA.ProjectName to appGame.
2021-10-28T08:15:14.2985332Z ##[debug]Set TE.TestRunTitle to TestRun_CI - DataCentric_20211028.3.
2021-10-28T08:15:14.2986143Z ##[debug]Set TE.TestSelection to testassemblies.
2021-10-28T08:15:14.2986991Z ##[debug]Set DTA.LocalTestDropPath to C:\azagents\BuildAgents\BuildAgent_1\_work\3\s.
2021-10-28T08:15:14.2994608Z ##[debug]Values is null for TE.TCMTestRun.
2021-10-28T08:15:14.2995143Z ##[debug]Values is null for TE.TestPlan.
2021-10-28T08:15:14.2995802Z ##[debug]Values is null for TE.TestPlanConfigId.
2021-10-28T08:15:14.2996289Z ##[debug]Value is empty for key TE.TestSuites.
2021-10-28T08:15:14.2996738Z ##[debug]Value is empty for key TE.TestCaseFilter.
2021-10-28T08:15:14.2997175Z ##[debug]Set DTA.BuildId to 4292.
2021-10-28T08:15:14.2997617Z ##[debug]Set DTA.BuildUri to vstfs:///Build/Build/4292.
2021-10-28T08:15:14.2998052Z ##[debug]Set DTA.ReleaseId to 0.
2021-10-28T08:15:14.2998466Z ##[debug]Value is empty for key DTA.ReleaseUri.
2021-10-28T08:15:14.2998883Z ##[debug]Set TE.BuildConfig to Release.
2021-10-28T08:15:14.2999310Z ##[debug]Set TE.BuildPlatform to Any CPU.
2021-10-28T08:15:14.2999738Z ##[debug]Values is null for TE.RerunFailedTests.
2021-10-28T08:15:14.3000188Z ##[debug]Values is null for DTA.RerunFailedTestCasesMaxLimit.
2021-10-28T08:15:14.3005145Z ##[debug]Values is null for DTA.RerunFailedThreshold.
2021-10-28T08:15:14.3005723Z ##[debug]Values is null for TE.RerunMaxAttempts.
2021-10-28T08:15:14.3006319Z ##[debug]Values is null for TE.MaxAgentPhaseSlicing.
2021-10-28T08:15:14.3006944Z ##[debug]Values is null for TE.SliceTime.
2021-10-28T08:15:14.3008211Z ##[debug]Values is null for TE.NumberOfTestCasesPerSlice.
2021-10-28T08:15:14.3008821Z ##[debug]Set DTA.TestResultDirectory to C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\TestResults.
2021-10-28T08:15:14.3009458Z ##[debug]Value is empty for key DTA.CustomTestAdapters.
2021-10-28T08:15:14.3009952Z ##[debug]Set TE.IgnoreTestFailures to False.
2021-10-28T08:15:14.3010571Z ##[debug]Set DTA.ProceedAfterAbortedTestCase to False.
2021-10-28T08:15:14.3011111Z ##[debug]Value is empty for key TE.RunSettings.
2021-10-28T08:15:14.3011604Z ##[debug]Values is null for DTA.TIAEnabled.
2021-10-28T08:15:14.3012188Z ##[debug]Set DTA.TiaContext to CI.
2021-10-28T08:15:14.3012610Z ##[debug]Set DTA.EnableDiagLogs to True.
2021-10-28T08:15:14.3013024Z ##[debug]Set DTA.EnableConsoleLogs to True.
2021-10-28T08:15:14.3013460Z ##[debug]Set DTA.PublishTestResultsInTiaMode to False.
2021-10-28T08:15:14.3013882Z ##[debug]Set DTA.Owner to vstest.
2021-10-28T08:15:14.3014602Z ##[debug]Set DTA.TestSelector to C:\azagents\BuildAgents\BuildAgent_1\_work\_tasks\VSTest_ef087383-ee5e-42c7-9a53-ab56c98420f9\2.170.1\Modules\DTAExecutionHost.exe\..\..\TestSelector\TestSelector.exe.
2021-10-28T08:15:14.3015340Z ##[debug]Value is empty for key DTA.ReleaseEnvironmentUri.
2021-10-28T08:15:14.3015854Z ##[debug]Set TE.TestDropLocation to C:\azagents\BuildAgents\BuildAgent_1\_work\3\s.
2021-10-28T08:15:14.3016381Z ##[debug]Set DTA.Runname to TestRun_CI - DataCentric_20211028.3.
2021-10-28T08:15:14.3016991Z ##[debug]Set DTA.MiniMatchTestSourcesFile to C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\testSources_2d348900-37c7-11ec-ba87-9d57af20f4cb.src.
2021-10-28T08:15:14.3018017Z ##[debug]Values is null for TE.RerunFailedTestCasesMaxLimit.
2021-10-28T08:15:14.3018475Z ##[debug]Values is null for TE.RerunFailedThreshold.
2021-10-28T08:15:14.3019043Z ##[debug]Set DTA.VstestConsole to C:\Program Files (x86)\Microsoft Visual Studio\2019\Enterprise\Common7\IDE\Extensions\TestPlatform.
2021-10-28T08:15:14.3019744Z ##[debug]Set DTA.TestWindow.Path to C:\Program Files (x86)\Microsoft Visual Studio\2019\Enterprise\Common7\IDE\Extensions\TestPlatform.
2021-10-28T08:15:14.3027653Z ##[debug]Going via local test run host as DistributionSettings node is not present.
2021-10-28T08:15:14.3058966Z Starting TestExecution Model...
2021-10-28T08:15:16.2081756Z ##[debug]Object of TestLogStore created.
2021-10-28T08:15:16.2083048Z Result Attachments will be stored in LogStore
2021-10-28T08:15:16.2083854Z ##[debug]Object of TestResultsPublisher created.
2021-10-28T08:15:16.2919914Z ##[debug]Object of TestLogStore created.
2021-10-28T08:15:16.2920952Z Run Attachments will be stored in LogStore
2021-10-28T08:15:16.2921819Z ##[debug]Object of TestRunPublisher created.
2021-10-28T08:15:16.3002620Z ##[debug]Object of TrxResultParser created.
2021-10-28T08:15:16.3003560Z ##[debug]Object of TestLogStore created.
2021-10-28T08:15:16.3004178Z Result Attachments will be stored in LogStore
2021-10-28T08:15:16.3004885Z ##[debug]Object of TestResultsPublisher created.
2021-10-28T08:15:16.3679501Z ##[debug]Object of TestLogStore created.
2021-10-28T08:15:16.3680432Z Result Attachments will be stored in LogStore
2021-10-28T08:15:16.3681166Z ##[debug]Object of TestResultsPublisher created.
2021-10-28T08:15:16.3681928Z ##[debug]Object of TestLogStore created.
2021-10-28T08:15:16.3682528Z Run Attachments will be stored in LogStore
2021-10-28T08:15:16.3683151Z ##[debug]Object of TestRunPublisher created.
2021-10-28T08:15:16.3697773Z ##[debug]Object of CodeCoverageInvoker created.
2021-10-28T08:15:16.3720073Z ##[debug]Starting the ExecutionStateModel
2021-10-28T08:15:16.3770522Z ##[debug]Current phase: MS.VS.TestService.VstestConsoleAdapter.SetupPhase
2021-10-28T08:15:16.3782930Z ##[debug]SetupPhase.Run: Running necessary setup steps.
2021-10-28T08:15:16.3822902Z ##[debug]Creating a dummy run settings file.
2021-10-28T08:15:16.3884805Z ##[debug]Code coverage was enabled. Attempting to add code coverage node to settings file.
2021-10-28T08:15:16.3888069Z ##[debug]Adding code coverage settings details to runsettings file.
2021-10-28T08:15:16.3903628Z ##[debug]Updating runsettings file from RunSettings node
2021-10-28T08:15:16.3904637Z ##[debug]Successfully added code coverage settings details to runsettings file.
2021-10-28T08:15:16.3916385Z ##[debug]Code coverage node present.
2021-10-28T08:15:16.3917342Z ##[debug]Updating the BatchSize in RunSettings to 1000
2021-10-28T08:15:16.3925879Z ##[debug]Run configuration not found in the runsettings, so adding one with BatchSize
2021-10-28T08:15:16.3938789Z ##[debug]Updating ResultDirectory by editing given runsettings if needed.
2021-10-28T08:15:16.3964410Z ##[debug]UpdateIfRequiredAndParse called for TestResultsDirectoryRunsettingsParser.
2021-10-28T08:15:16.3965250Z ##[debug]ResultsDirectory node not found in run configuration. So adding ResultsDirectory node.
2021-10-28T08:15:16.3966090Z ##[debug]CommonSettingsManager : TestResultsDirectory intialized to C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\TestResults
2021-10-28T08:15:16.3966705Z Updated Run Settings:
2021-10-28T08:15:16.3977475Z <RunSettings>
2021-10-28T08:15:16.3978036Z   <DataCollectionRunSettings>
2021-10-28T08:15:16.3978496Z     <DataCollectors>
2021-10-28T08:15:16.3979564Z       <DataCollector friendlyName="Code Coverage" uri="datacollector://Microsoft/CodeCoverage/2.0" assemblyQualifiedName="Microsoft.VisualStudio.Coverage.DynamicCoverageDataCollector, Microsoft.VisualStudio.TraceCollector, Version=11.0.0.0, Culture=neutral, PublicKeyToken=b03f5f7f11d50a3a">
2021-10-28T08:15:16.3981439Z         <Configuration>
2021-10-28T08:15:16.3981917Z           <CodeCoverage>
2021-10-28T08:15:16.3982355Z             <ModulePaths>
2021-10-28T08:15:16.3982825Z               <Include>
2021-10-28T08:15:16.3983304Z                 <ModulePath>.*\.dll$</ModulePath>
2021-10-28T08:15:16.3983817Z                 <ModulePath>.*\.exe$</ModulePath>
2021-10-28T08:15:16.3984305Z               </Include>
2021-10-28T08:15:16.3984774Z               <Exclude>
2021-10-28T08:15:16.3985273Z                 <ModulePath>.*CPPUnitTestFramework.*</ModulePath>
2021-10-28T08:15:16.3985811Z               </Exclude>
2021-10-28T08:15:16.3986249Z             </ModulePaths>
2021-10-28T08:15:16.3986679Z             <Functions>
2021-10-28T08:15:16.3987120Z               <Exclude>
2021-10-28T08:15:16.3987643Z                 <Function>^Fabrikam\.UnitTest\..*</Function>
2021-10-28T08:15:16.3988207Z                 <Function>^std::.*</Function>
2021-10-28T08:15:16.3988772Z                 <Function>^ATL::.*</Function>
2021-10-28T08:15:16.3989346Z                 <Function>.*::__GetTestMethodInfo.*</Function>
2021-10-28T08:15:16.3990042Z                 <Function>^Microsoft::VisualStudio::CppCodeCoverageFramework::.*</Function>
2021-10-28T08:15:16.3990845Z                 <Function>^Microsoft::VisualStudio::CppUnitTestFramework::.*</Function>
2021-10-28T08:15:16.3991495Z               </Exclude>
2021-10-28T08:15:16.3991923Z             </Functions>
2021-10-28T08:15:16.3992300Z             <Attributes>
2021-10-28T08:15:16.3993500Z               <Exclude>
2021-10-28T08:15:16.3994053Z                 <Attribute>^System\.Diagnostics\.DebuggerHiddenAttribute$</Attribute>
2021-10-28T08:15:16.3994930Z                 <Attribute>^System\.Diagnostics\.DebuggerNonUserCodeAttribute$</Attribute>
2021-10-28T08:15:16.3995809Z                 <Attribute>^System\.Runtime\.CompilerServices\.CompilerGeneratedAttribute$</Attribute>
2021-10-28T08:15:16.3997173Z                 <Attribute>^System\.CodeDom\.Compiler\.GeneratedCodeAttribute$</Attribute>
2021-10-28T08:15:16.3998000Z                 <Attribute>^System\.Diagnostics\.CodeAnalysis\.ExcludeFromCodeCoverageAttribute$</Attribute>
2021-10-28T08:15:16.3998666Z               </Exclude>
2021-10-28T08:15:16.3999160Z             </Attributes>
2021-10-28T08:15:16.3999618Z             <Sources>
2021-10-28T08:15:16.4000076Z               <Exclude>
2021-10-28T08:15:16.4000533Z                 <Source>.*\\atlmfc\\.*</Source>
2021-10-28T08:15:16.4001801Z                 <Source>.*\\vctools\\.*</Source>
2021-10-28T08:15:16.4002336Z                 <Source>.*\\public\\sdk\\.*</Source>
2021-10-28T08:15:16.4002841Z                 <Source>.*\\microsoft sdks\\.*</Source>
2021-10-28T08:15:16.4003419Z                 <Source>.*\\vc\\include\\.*</Source>
2021-10-28T08:15:16.4003964Z               </Exclude>
2021-10-28T08:15:16.4004424Z             </Sources>
2021-10-28T08:15:16.4004870Z             <CompanyNames>
2021-10-28T08:15:16.4005353Z               <Exclude>
2021-10-28T08:15:16.4005885Z                 <CompanyName>.*microsoft.*</CompanyName>
2021-10-28T08:15:16.4006399Z               </Exclude>
2021-10-28T08:15:16.4006867Z             </CompanyNames>
2021-10-28T08:15:16.4007356Z             <PublicKeyTokens>
2021-10-28T08:15:16.4007815Z               <Exclude>
2021-10-28T08:15:16.4008355Z                 <PublicKeyToken>^B77A5C561934E089$</PublicKeyToken>
2021-10-28T08:15:16.4009002Z                 <PublicKeyToken>^B03F5F7F11D50A3A$</PublicKeyToken>
2021-10-28T08:15:16.4009637Z                 <PublicKeyToken>^31BF3856AD364E35$</PublicKeyToken>
2021-10-28T08:15:16.4010285Z                 <PublicKeyToken>^89845DCD8080CC91$</PublicKeyToken>
2021-10-28T08:15:16.4010923Z                 <PublicKeyToken>^71E9BCE111E9429C$</PublicKeyToken>
2021-10-28T08:15:16.4011535Z                 <PublicKeyToken>^8F50407C4E9E73B6$</PublicKeyToken>
2021-10-28T08:15:16.4012363Z                 <PublicKeyToken>^E361AF139669C375$</PublicKeyToken>
2021-10-28T08:15:16.4014251Z               </Exclude>
2021-10-28T08:15:16.4014816Z             </PublicKeyTokens>
2021-10-28T08:15:16.4015759Z             <UseVerifiableInstrumentation>True</UseVerifiableInstrumentation>
2021-10-28T08:15:16.4016498Z             <AllowLowIntegrityProcesses>True</AllowLowIntegrityProcesses>
2021-10-28T08:15:16.4017569Z             <CollectFromChildProcesses>True</CollectFromChildProcesses>
2021-10-28T08:15:16.4018185Z             <CollectAspDotNet>False</CollectAspDotNet>
2021-10-28T08:15:16.4018753Z           </CodeCoverage>
2021-10-28T08:15:16.4019223Z         </Configuration>
2021-10-28T08:15:16.4019668Z       </DataCollector>
2021-10-28T08:15:16.4020142Z     </DataCollectors>
2021-10-28T08:15:16.4020627Z   </DataCollectionRunSettings>
2021-10-28T08:15:16.4021083Z   <RunConfiguration>
2021-10-28T08:15:16.4021560Z     <BatchSize>1000</BatchSize>
2021-10-28T08:15:16.4022235Z     <ResultsDirectory>C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\TestResults</ResultsDirectory>
2021-10-28T08:15:16.4022867Z   </RunConfiguration>
2021-10-28T08:15:16.4023315Z </RunSettings>
2021-10-28T08:15:16.4024143Z ##[debug]PERF: SettingsUpdationPhase.UpdateSettingsAsRequired: took 23.1708 ms
2021-10-28T08:15:16.4025100Z ##[debug]SetupPhase.Run : Update settings completed.
2021-10-28T08:15:16.4068304Z ##[debug]Current Phase: MS.VS.TestService.VstestConsoleAdapter.SetupPhase Phase Result: MS.VS.TestService.VstestConsoleAdapter.SetupPhaseResults
2021-10-28T08:15:16.4069739Z ##[debug]PERF: Running the phase MS.VS.TestService.VstestConsoleAdapter.SetupPhase: took 29.5619 ms
2021-10-28T08:15:16.4070850Z ##[debug]Current phase: MS.VS.TestService.VstestConsoleAdapter.ExecuteVsTestPhase
2021-10-28T08:15:16.4083588Z **************** Starting test execution *********************
2021-10-28T08:15:16.4145346Z ##[debug]Generating list of args to pass to vstest.console.exe.
2021-10-28T08:15:16.4192625Z [command]C:\Program Files (x86)\Microsoft Visual Studio\2019\Enterprise\Common7\IDE\Extensions\TestPlatform\vstest.console.exe "@C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\myoq02p5fxt.tmp"
2021-10-28T08:15:16.5478402Z Microsoft (R) Test Execution Command Line Tool Version 16.11.0
2021-10-28T08:15:16.5479055Z Copyright (c) Microsoft Corporation.  All rights reserved.
2021-10-28T08:15:16.5524763Z vstest.console.exe "C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\src\UnitTests\XXXXX.UnitTests\bin\Release\XXXXX.UnitTests.dll"
2021-10-28T08:15:16.5526460Z "C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\src\UnitTests\XXXXX.UnitTests\bin\Release\xunit.runner.visualstudio.testadapter.dll"
2021-10-28T08:15:16.5527238Z "C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\src\UnitTests\XXXXX.UnitTests\bin\Release\XXXXX.UnitTests.dll"
2021-10-28T08:15:16.5527961Z "C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\src\UnitTests\XXXXX.UnitTests\bin\Release\xunit.runner.visualstudio.testadapter.dll"
2021-10-28T08:15:16.5529035Z "C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\src\UnitTests\XXXXX.UnitTests\bin\Release\XXXXX.UnitTests.dll"
2021-10-28T08:15:16.5530130Z "C:\azagents\BuildAgents\BuildAgent_1\_work\3\s\src\UnitTests\XXXXX.UnitTests\bin\Release\xunit.runner.visualstudio.testadapter.dll"
2021-10-28T08:15:16.5530993Z /Settings:"C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\3pkooubwx2y.tmp.runsettings"
2021-10-28T08:15:16.5531582Z /EnableCodeCoverage
2021-10-28T08:15:16.5531977Z /Logger:"trx"
2021-10-28T08:15:16.5532443Z /TestAdapterPath:"C:\azagents\BuildAgents\BuildAgent_1\_work\3\s"
2021-10-28T08:15:16.5532944Z /Diag:"C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\Attempt--1_pxvgyi.diag"
2021-10-28T08:15:17.2497091Z Starting test execution, please wait...
2021-10-28T08:15:17.2506297Z Logging Vstest Diagnostics in file: C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\Attempt--1_pxvgyi.diag
2021-10-28T08:15:18.9284601Z 1.9498
2021-10-28T08:15:18.9547569Z A total of 6 test files matched the specified pattern.
2021-10-28T08:15:18.9568972Z Data collection : Logging DataCollector Diagnostics in file: C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\Attempt--1_pxvgyi.datacollector.21-10-28_10-15-17_79521_1.diag
2021-10-28T08:15:19.8572567Z Logging TestHost Diagnostics in file: C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\Attempt--1_pxvgyi.host.21-10-28_10-15-18_95257_1.diag
2021-10-28T08:15:21.7630239Z [xUnit.net 00:00:00.00] xUnit.net VSTest Adapter v2.4.3+1b45f5407b (32-bit Desktop .NET 4.0.30319.42000)
[...running tests...]
2021-10-28T08:16:17.1913081Z Results File: C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\TestResults\XXXXX$_XXXXX_2021-10-28_10_15_25.trx
2021-10-28T08:16:17.1968161Z Attachments:
2021-10-28T08:16:17.1974101Z   C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\TestResults\9eb0df25-c5e1-41eb-83e6-2cb910b43527\XXXXX$_XXXXX_2021-10-28.10_15_18.coverage
2021-10-28T08:16:17.2045254Z Test Run Successful.
2021-10-28T08:16:17.2045782Z Total tests: 55
2021-10-28T08:16:17.2046162Z      Passed: 55
2021-10-28T08:16:17.2051256Z  Total time: 58.1804 Seconds
2021-10-28T08:16:17.2258021Z ##[debug]Exited vstest.console.exe with code 0.
2021-10-28T08:16:17.2259137Z ##[debug]PERF: ExecuteVsTestPhase.InvokeVSTest: took 60816.7442 ms
2021-10-28T08:16:17.2259802Z Vstest.console.exe exited with code 0.
2021-10-28T08:16:17.2260282Z **************** Completed test execution *********************
2021-10-28T08:16:17.2261253Z ##[debug]Current Phase: MS.VS.TestService.VstestConsoleAdapter.ExecuteVsTestPhase Phase Result: MS.VS.TestService.VstestConsoleAdapter.ExecutionVsTestPhaseResults
2021-10-28T08:16:17.2262560Z ##[debug]PERF WARNING: Running the phase MS.VS.TestService.VstestConsoleAdapter.ExecuteVsTestPhase: took 60818.5707 ms
2021-10-28T08:16:17.2263680Z ##[debug]Current phase: MS.VS.TestService.VstestConsoleAdapter.PublishTestResultPhase
2021-10-28T08:16:17.2299779Z ##[debug]Starting Publish Test Results: TIA: False
2021-10-28T08:16:17.2317444Z ##[debug]Starting PublishTestResult from Result Files
2021-10-28T08:16:17.2352902Z Test results files: C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\TestResults\XXXXX$_XXXXX_2021-10-28_10_15_25.trx
2021-10-28T08:16:17.2451907Z ##[debug]Entering ParseTestResultFiles
2021-10-28T08:16:17.2455171Z ##[debug]runContext.ReleaseURI is null
2021-10-28T08:16:17.2455955Z ##[debug]runContext.ReleaseEnvironmentUri is null
2021-10-28T08:16:17.2491700Z ##[debug]Reading test results from file 'C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\TestResults\XXXXX$_XXXXX_2021-10-28_10_15_25.trx'.
2021-10-28T08:16:17.2527059Z ##[debug]Setting run start and finish times.
2021-10-28T08:16:17.2575634Z ##[debug]Attachment location: C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\TestResults\XXXXX$_XXXXX_2021-10-28_10_15_25\In.
2021-10-28T08:16:17.2583407Z ##[debug]Adding run level attachment: C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\TestResults\XXXXX$_XXXXX_2021-10-28_10_15_25\In\XXXXX\XXXXX$_XXXXX_2021-10-28.10_15_18.coverage.
2021-10-28T08:16:17.2880051Z ##[debug]Total test results: 29.
2021-10-28T08:16:17.2880956Z ##[debug]Leaving ParseTestResultFiles
2021-10-28T08:16:17.3206736Z ##[debug]Entering CreateTestRunAsync
2021-10-28T08:16:17.3207506Z ##[debug]Leaving CreateTestRunAsync
2021-10-28T08:16:18.0720586Z Created test run: 5962
2021-10-28T08:16:18.0722178Z ##[debug]Processed: ##vso[task.setvariable variable=vstest.testrunid]5962
2021-10-28T08:16:18.0722659Z Publishing test results: 29
2021-10-28T08:16:18.0723052Z ##[debug]Entering AddTestResultsAsync
2021-10-28T08:16:18.1457345Z Publishing test results to test run '5962'.
2021-10-28T08:16:18.1492435Z TestResults To Publish 29, Test run id:5962
2021-10-28T08:16:18.1571223Z Test results publishing 29, remaining: 0. Test run id: 5962
2021-10-28T08:16:19.9110713Z ##[debug]Leaving AddTestResultsAsync
2021-10-28T08:16:19.9111323Z Published test results: 29
2021-10-28T08:16:19.9117131Z Publishing Attachments: 2
2021-10-28T08:16:19.9240502Z ##[debug]Entering CreateTestRunAttachmentsAsync
2021-10-28T08:16:20.0023053Z ##[debug]Leaving CreateTestRunAttachmentsAsync
2021-10-28T08:16:20.0041144Z ##[debug]Uploading test run attachements individually
2021-10-28T08:16:20.0108647Z ##[debug]Entering UploadTestRunLogAsync
2021-10-28T08:16:20.0593401Z ##[debug]Entering UploadTestRunLogAsync
2021-10-28T08:16:20.3599919Z ##[debug]Blob name: CodeCoverage/XXXXX$_XXXXX_2021-10-28.10_15_18.coverage .
2021-10-28T08:16:20.3602219Z ##[debug]Blob name: GeneralAttachment/XXXXX$_XXXXX_2021-10-28_10_15_25.trx .
2021-10-28T08:27:11.5319908Z ##[warning]Failed to upload file XXXXX$_XXXXX_2021-10-28_10_15_25.trx to Blob Transfer exception with errorcode Unknown, exception message Microsoft.Azure.Storage.DataMovement.TransferException: The transfer failed. ---> Microsoft.Azure.Storage.StorageException: An error occurred while sending the request. ---> System.Net.Http.HttpRequestException: An error occurred while sending the request. ---> System.Net.WebException: The underlying connection was closed: An unexpected error occurred on a send. ---> System.IO.IOException: Unable to read data from the transport connection: An existing connection was forcibly closed by the remote host. ---> System.Net.Sockets.SocketException: An existing connection was forcibly closed by the remote host
2021-10-28T08:27:11.5324990Z ##[debug]Processed: ##vso[task.logissue type=warning;]Failed to upload file XXXXX$_XXXXX_2021-10-28_10_15_25.trx to Blob Transfer exception with errorcode Unknown, exception message Microsoft.Azure.Storage.DataMovement.TransferException: The transfer failed. ---> Microsoft.Azure.Storage.StorageException: An error occurred while sending the request. ---> System.Net.Http.HttpRequestException: An error occurred while sending the request. ---> System.Net.WebException: The underlying connection was closed: An unexpected error occurred on a send. ---> System.IO.IOException: Unable to read data from the transport connection: An existing connection was forcibly closed by the remote host. ---> System.Net.Sockets.SocketException: An existing connection was forcibly closed by the remote host
2021-10-28T08:27:11.5328010Z    at System.Net.Sockets.Socket.EndReceive(IAsyncResult asyncResult)
2021-10-28T08:27:11.5328722Z    at System.Net.Sockets.NetworkStream.EndRead(IAsyncResult asyncResult)
2021-10-28T08:27:11.5329268Z    --- End of inner exception stack trace ---
2021-10-28T08:27:11.5330759Z    at System.Net.TlsStream.EndWrite(IAsyncResult asyncResult)
2021-10-28T08:27:11.5331299Z    at System.Net.ConnectStream.WriteHeadersCallback(IAsyncResult ar)
2021-10-28T08:27:11.5331823Z    --- End of inner exception stack trace ---
2021-10-28T08:27:11.5332343Z    at System.Net.HttpWebRequest.EndGetResponse(IAsyncResult asyncResult)
2021-10-28T08:27:11.5332875Z    at System.Net.Http.HttpClientHandler.GetResponseCallback(IAsyncResult ar)
2021-10-28T08:27:11.5333361Z    --- End of inner exception stack trace ---
2021-10-28T08:27:11.5334004Z    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
2021-10-28T08:27:11.5334605Z    at Microsoft.Azure.Storage.Core.Executor.Executor.<ExecuteAsync>d__1`1.MoveNext()
2021-10-28T08:27:11.5335148Z    --- End of inner exception stack trace ---
2021-10-28T08:27:11.5335715Z    at Microsoft.Azure.Storage.DataMovement.TransferControllers.BlockBlobWriter.<HandleFetchAttributesResultAsync>d__19.MoveNext()
2021-10-28T08:27:11.5336389Z --- End of stack trace from previous location where exception was thrown ---
2021-10-28T08:27:11.5336996Z    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
2021-10-28T08:27:11.5337595Z    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
2021-10-28T08:27:11.5338300Z    at Microsoft.Azure.Storage.DataMovement.TransferControllers.BlockBlobWriter.<FetchAttributesAsync>d__18.MoveNext()
2021-10-28T08:27:11.5338928Z --- End of stack trace from previous location where exception was thrown ---
2021-10-28T08:27:11.5340777Z    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
2021-10-28T08:27:11.5341395Z    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
2021-10-28T08:27:11.5342078Z    at Microsoft.Azure.Storage.DataMovement.TransferControllers.BlockBlobWriter.<DoWorkInternalAsync>d__17.MoveNext()
2021-10-28T08:27:11.5342714Z --- End of stack trace from previous location where exception was thrown ---
2021-10-28T08:27:11.5343306Z    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
2021-10-28T08:27:11.5343913Z    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
2021-10-28T08:27:11.5344580Z    at Microsoft.Azure.Storage.DataMovement.TransferControllers.SyncTransferController.<DoWorkInternalAsync>d__14.MoveNext()
2021-10-28T08:27:11.5345230Z --- End of stack trace from previous location where exception was thrown ---
2021-10-28T08:27:11.5345843Z    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
2021-10-28T08:27:11.5346491Z    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
2021-10-28T08:27:11.5347202Z    at Microsoft.Azure.Storage.DataMovement.TransferControllers.TransferControllerBase.<DoWorkAsync>d__34.MoveNext()
2021-10-28T08:27:11.5347834Z --- End of stack trace from previous location where exception was thrown ---
2021-10-28T08:27:11.5348368Z    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
2021-10-28T08:27:11.5348984Z    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
2021-10-28T08:27:11.5349644Z    at Microsoft.Azure.Storage.DataMovement.TransferScheduler.<ExecuteJobInternalAsync>d__28.MoveNext()
2021-10-28T08:27:11.5350159Z    --- End of inner exception stack trace ---
2021-10-28T08:27:11.5350702Z    at Microsoft.TeamFoundation.TestClient.PublishTestResults.TestLogStore.<_executeWithRetry>d__33.MoveNext()
2021-10-28T08:27:11.5351290Z --- End of stack trace from previous location where exception was thrown ---
2021-10-28T08:27:11.5351841Z    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
2021-10-28T08:27:11.5352431Z    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
2021-10-28T08:27:11.5353094Z    at Microsoft.TeamFoundation.TestClient.PublishTestResults.TestLogStore.<UploadInternalAsync>d__19.MoveNext()
2021-10-28T08:27:11.5359904Z ##[debug]Failed to Upload on log store with error code TransferFailed
2021-10-28T08:27:11.5378403Z Failed to upload run logs to LogStore, Trying to upload to FileService
2021-10-28T08:27:11.8721649Z Failed to publish Telemetry Exception: Object reference not set to an instance of an object.
2021-10-28T08:27:35.4433036Z ##[warning]Failed to upload file XXXXX$_XXXXX_2021-10-28.10_15_18.coverage to Blob Transfer exception with errorcode Unknown, exception message Microsoft.Azure.Storage.DataMovement.TransferException: The transfer failed. ---> Microsoft.Azure.Storage.StorageException: An error occurred while sending the request. ---> System.Net.Http.HttpRequestException: An error occurred while sending the request. ---> System.Net.WebException: The underlying connection was closed: An unexpected error occurred on a send. ---> System.IO.IOException: Unable to read data from the transport connection: An existing connection was forcibly closed by the remote host. ---> System.Net.Sockets.SocketException: An existing connection was forcibly closed by the remote host
2021-10-28T08:27:35.4439108Z ##[debug]Processed: ##vso[task.logissue type=warning;]Failed to upload file XXXXX$_XXXXX_2021-10-28.10_15_18.coverage to Blob Transfer exception with errorcode Unknown, exception message Microsoft.Azure.Storage.DataMovement.TransferException: The transfer failed. ---> Microsoft.Azure.Storage.StorageException: An error occurred while sending the request. ---> System.Net.Http.HttpRequestException: An error occurred while sending the request. ---> System.Net.WebException: The underlying connection was closed: An unexpected error occurred on a send. ---> System.IO.IOException: Unable to read data from the transport connection: An existing connection was forcibly closed by the remote host. ---> System.Net.Sockets.SocketException: An existing connection was forcibly closed by the remote host
2021-10-28T08:27:35.4443337Z    at System.Net.Sockets.Socket.EndReceive(IAsyncResult asyncResult)
2021-10-28T08:27:35.4444304Z    at System.Net.Sockets.NetworkStream.EndRead(IAsyncResult asyncResult)
2021-10-28T08:27:35.4445003Z    --- End of inner exception stack trace ---
2021-10-28T08:27:35.4445608Z    at System.Net.TlsStream.EndWrite(IAsyncResult asyncResult)
2021-10-28T08:27:35.4446234Z    at System.Net.ConnectStream.WriteHeadersCallback(IAsyncResult ar)
2021-10-28T08:27:35.4446958Z    --- End of inner exception stack trace ---
2021-10-28T08:27:35.4447663Z    at System.Net.HttpWebRequest.EndGetResponse(IAsyncResult asyncResult)
2021-10-28T08:27:35.4448409Z    at System.Net.Http.HttpClientHandler.GetResponseCallback(IAsyncResult ar)
2021-10-28T08:27:35.4449095Z    --- End of inner exception stack trace ---
2021-10-28T08:27:35.4449698Z    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
2021-10-28T08:27:35.4450429Z    at Microsoft.Azure.Storage.Core.Executor.Executor.<ExecuteAsync>d__1`1.MoveNext()
2021-10-28T08:27:35.4451151Z    --- End of inner exception stack trace ---
2021-10-28T08:27:35.4451965Z    at Microsoft.Azure.Storage.DataMovement.TransferControllers.BlockBlobWriter.<HandleFetchAttributesResultAsync>d__19.MoveNext()
2021-10-28T08:27:35.4452801Z --- End of stack trace from previous location where exception was thrown ---
2021-10-28T08:27:35.4453588Z    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
2021-10-28T08:27:35.4454550Z    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
2021-10-28T08:27:35.4455587Z    at Microsoft.Azure.Storage.DataMovement.TransferControllers.BlockBlobWriter.<FetchAttributesAsync>d__18.MoveNext()
2021-10-28T08:27:35.4456399Z --- End of stack trace from previous location where exception was thrown ---
2021-10-28T08:27:35.4457147Z    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
2021-10-28T08:27:35.4457893Z    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
2021-10-28T08:27:35.4459925Z    at Microsoft.Azure.Storage.DataMovement.TransferControllers.BlockBlobWriter.<DoWorkInternalAsync>d__17.MoveNext()
2021-10-28T08:27:35.4460805Z --- End of stack trace from previous location where exception was thrown ---
2021-10-28T08:27:35.4461529Z    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
2021-10-28T08:27:35.4462310Z    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
2021-10-28T08:27:35.4463213Z    at Microsoft.Azure.Storage.DataMovement.TransferControllers.SyncTransferController.<DoWorkInternalAsync>d__14.MoveNext()
2021-10-28T08:27:35.4464170Z --- End of stack trace from previous location where exception was thrown ---
2021-10-28T08:27:35.4464786Z    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
2021-10-28T08:27:35.4465468Z    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
2021-10-28T08:27:35.4466244Z    at Microsoft.Azure.Storage.DataMovement.TransferControllers.TransferControllerBase.<DoWorkAsync>d__34.MoveNext()
2021-10-28T08:27:35.4466957Z --- End of stack trace from previous location where exception was thrown ---
2021-10-28T08:27:35.4467598Z    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
2021-10-28T08:27:35.4468286Z    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
2021-10-28T08:27:35.4469040Z    at Microsoft.Azure.Storage.DataMovement.TransferScheduler.<ExecuteJobInternalAsync>d__28.MoveNext()
2021-10-28T08:27:35.4470282Z    --- End of inner exception stack trace ---
2021-10-28T08:27:35.4470951Z    at Microsoft.TeamFoundation.TestClient.PublishTestResults.TestLogStore.<_executeWithRetry>d__33.MoveNext()
2021-10-28T08:27:35.4471675Z --- End of stack trace from previous location where exception was thrown ---
2021-10-28T08:27:35.4472293Z    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
2021-10-28T08:27:35.4472978Z    at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
2021-10-28T08:27:35.4473760Z    at Microsoft.TeamFoundation.TestClient.PublishTestResults.TestLogStore.<UploadInternalAsync>d__19.MoveNext()
2021-10-28T08:27:35.4474797Z ##[debug]Failed to Upload on log store with error code TransferFailed
2021-10-28T08:27:35.4475540Z Failed to upload run logs to LogStore, Trying to upload to FileService
2021-10-28T08:27:36.1073868Z Failed to publish Telemetry Exception: Object reference not set to an instance of an object.
2021-10-28T08:27:36.7575756Z ##[debug]Completed PublishTestResult
2021-10-28T08:27:36.7576553Z ##[debug]PERF: PublishTestResultPhase.Publish: took 679521.0391 ms
2021-10-28T08:27:36.7577087Z ##[debug]Completed Publish Test Results.
2021-10-28T08:27:36.7577708Z ##[debug]Current Phase: MS.VS.TestService.VstestConsoleAdapter.PublishTestResultPhase Phase Result: MS.VS.TestService.VstestConsoleAdapter.PublishTestResultPhaseResults
2021-10-28T08:27:36.7578698Z ##[debug]PERF WARNING: Running the phase MS.VS.TestService.VstestConsoleAdapter.PublishTestResultPhase: took 679525.2132 ms
2021-10-28T08:27:36.7579520Z ##[debug]Current phase: MS.VS.TestService.VstestConsoleAdapter.RerunValidationPhase
2021-10-28T08:27:36.7628914Z ##[debug]Started RerunValidationPhase for 5962
2021-10-28T08:27:36.7629574Z ##[debug]Rerunning of failed tests is not enabled
2021-10-28T08:27:36.7630217Z ##[debug]Current Phase: MS.VS.TestService.VstestConsoleAdapter.RerunValidationPhase Phase Result: MS.VS.TestService.VstestConsoleAdapter.RerunValidationPhaseResults
2021-10-28T08:27:36.7630973Z ##[debug]PERF: Running the phase MS.VS.TestService.VstestConsoleAdapter.RerunValidationPhase: took 5.3448 ms
2021-10-28T08:27:36.7631588Z ##[debug]Current phase: MS.VS.TestService.VstestConsoleAdapter.QueueCodeCoverageMergePhase
2021-10-28T08:27:36.7642768Z ##[debug]Started QueueuCodeCoverageMergePhase 4292
2021-10-28T08:27:36.7643301Z ##[debug]Invoking Merge CodeCoverage for Build ID 4292
2021-10-28T08:27:37.7756820Z ##[debug]Completed QueueuCodeCoverageMergePhase 4292
2021-10-28T08:27:37.7758210Z ##[debug]Current Phase: MS.VS.TestService.VstestConsoleAdapter.QueueCodeCoverageMergePhase Phase Result: MS.VS.TestService.VstestConsoleAdapter.QueueCodeCoverageMergePhaseResults
2021-10-28T08:27:37.7759753Z ##[debug]PERF WARNING: Running the phase MS.VS.TestService.VstestConsoleAdapter.QueueCodeCoverageMergePhase: took 1012.6392 ms
2021-10-28T08:27:37.7760981Z ##[debug]Current phase: MS.VS.TestService.VstestConsoleAdapter.TestRunCompletionPhase
2021-10-28T08:27:37.7791231Z ##[debug]Started TestRunCompletionPhase 5962
2021-10-28T08:27:37.7791747Z ##[debug]Test run needs to be marked as complete.
2021-10-28T08:27:37.7825159Z ##[debug]Entering UpdateTestRunAsync
2021-10-28T08:27:37.7825935Z ##[debug]Leaving UpdateTestRunAsync
2021-10-28T08:27:37.9780345Z ##[debug]PERF: TestRunCompletionPhase:CompleteTestRun: took 198.6247 ms
2021-10-28T08:27:38.1213800Z ##[debug]Completed TestRunCompletionPhase 5962
2021-10-28T08:27:38.1215309Z ##[debug]Current Phase: MS.VS.TestService.VstestConsoleAdapter.TestRunCompletionPhase Phase Result: MS.VS.TestService.VstestConsoleAdapter.TestRunCompletionPhaseResults
2021-10-28T08:27:38.1217087Z ##[debug]PERF: Running the phase MS.VS.TestService.VstestConsoleAdapter.TestRunCompletionPhase: took 345.6165 ms
2021-10-28T08:27:38.1218242Z ##[debug]Current phase: MS.VS.TestService.VstestConsoleAdapter.CleanupPhase
2021-10-28T08:27:38.1229008Z ##[debug]Started CleanupPhase 5962
2021-10-28T08:27:38.1241219Z ##[debug]Looking for diag files with pattern Attempt*pxvgyi*.diag
2021-10-28T08:27:38.1271423Z ##[debug]Processed: ##vso[task.uploadfile]C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\Attempt--1_pxvgyi.datacollector.21-10-28_10-15-17_79521_1.diag
2021-10-28T08:27:38.1275106Z ##[debug]Processed: ##vso[task.uploadfile]C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\Attempt--1_pxvgyi.diag
2021-10-28T08:27:38.1277990Z ##[debug]Processed: ##vso[task.uploadfile]C:\azagents\BuildAgents\BuildAgent_1\_work\_temp\Attempt--1_pxvgyi.host.21-10-28_10-15-18_95257_1.diag
2021-10-28T08:27:38.1754908Z ##[debug]Completed CleanupPhase 5962
2021-10-28T08:27:38.1755813Z ##[debug]Current Phase: MS.VS.TestService.VstestConsoleAdapter.CleanupPhase Phase Result: MS.VS.TestService.VstestConsoleAdapter.CleanupPhaseResults
2021-10-28T08:27:38.1756882Z ##[debug]PERF: Running the phase MS.VS.TestService.VstestConsoleAdapter.CleanupPhase: took 54.2679 ms
2021-10-28T08:27:38.1757497Z ##[debug]Completed the ExecutionStateModel
2021-10-28T08:27:38.1757918Z Completed TestExecution Model...
2021-10-28T08:27:38.1956838Z ##[debug]Exit code 0 received from tool 'C:\azagents\BuildAgents\BuildAgent_1\_work\_tasks\VSTest_ef087383-ee5e-42c7-9a53-ab56c98420f9\2.170.1\Modules\DTAExecutionHost.exe'
2021-10-28T08:27:38.1966579Z ##[debug]STDIO streams have closed for tool 'C:\azagents\BuildAgents\BuildAgent_1\_work\_tasks\VSTest_ef087383-ee5e-42c7-9a53-ab56c98420f9\2.170.1\Modules\DTAExecutionHost.exe'
2021-10-28T08:27:38.1983491Z ##[debug]DtaExecutionHost finished
2021-10-28T08:27:38.1984137Z ##[debug]Modules/DTAExecutionHost.exe exited with code 0
2021-10-28T08:27:38.1984799Z ##[debug]task result: Succeeded
2021-10-28T08:27:38.1991491Z ##[debug]Processed: ##vso[task.complete result=Succeeded;done=true;]Task succeeded
2021-10-28T08:27:38.2076569Z ##[section]Finishing: Test Assemblies

Thank you for reading :)

microsoft / azure-pipelines-tasks

VSTest and TRX upload failure + long timeout #15456

Environment

Issue Description

Task logs