search

microsoft / azure-pipelines-agent

Azure Pipelines Agent 🚀
MIT License
1.73k stars 869 forks source link

TFVC Build directory override not taking hold on hosted agent in all accounts. #3632

Closed jessehouwing closed 2 years ago

jessehouwing commented 3 years ago

Agent Version and Platform

Version of your agent? 2.195

OS of the machine running the agent? Windows

Azure DevOps Type and Version

dev.azure.com hosted pool

What's not working?

@anatolybolshakov I'm seeing agent 2.195 rolling out with a number of fixes for TFVC on the hosted agent (including a couple of my own 🚀🎉), but I'm not (yet) seeing the hosted agents changing the workspace folder from ws_1_AgentId to ws_AgentId_AgentId. Is that a separate setting rolling out to a hosted pool near me soon? I'm seeing that on my own account (jessehouwing-dev), but not on my client's account yet with the same pool settings on a new TFVC build definition.

I see a setting to turn off the behaviour, but none to force it on...

https://github.com/microsoft/azure-pipelines-agent/blob/f384b2b194b333c99dcd51a7d7ec73115a703675/src/Agent.Sdk/Knob/AgentKnobs.cs#L269-L274

Attached screenshots from their account and mine showing the behaviour isn't yet enabled on theirs. Both accounts are in West-Europe...

Working on my account: image

Not working on Client's account: image

I can share more account details and logs from the client privately.

EzzhevNikita commented 3 years ago

Hey @jessehouwing, as I see the fix that you've mentioned wasn't included in the release 2.195.0 of an agent, it was merged after we have started rollout of the new version. This fix should be available with the next agent release. You can track changes that are included in particular agent release in release notes here.

jessehouwing commented 3 years ago

Can you explain why my jessehouwing-dev and jessehouwing orgs are seeing the new behavior? Is there a way for my client's org to get the same magic feature toggle? Otherwise they're probably looking at another 4 weeks of randomly failing builds.

EzzhevNikita commented 3 years ago

@jessehouwing Hmm, that sounds a little bit strange. Am I right that we are talking about the fix that was introduced by this PR? If so, I am totally sure that it doesn't exist in 2.195.0 Agent release, you can ensure in it by yourself both in release notes and in commit history for the agent release 2.195.0. The only idea, why you can see this behavior in your organizations is that there are agents of not release version that contains described fix, maybe you have tested those changes and didn't remove the test agent. Please share an agent version that has the new behavior in your organizations.

jessehouwing commented 3 years ago

My organisation is jessehouwing and jessehouwing-dev, but since these are MVP accounts it's possible they are allocated in deployment ring 0 or 1 and are getting newer bits.

I've tested the same TFVC based pipeline in these 2 accounts and they both show ws_AgentId_AgentId instead of ws_1_AgentId. This is when using the windows-2019 hosted pool, not running my own agent in these cases.

I tried to find a way to overrule the agent.builddirectory from the pipeline itself, but I haven't found a way to accomplish that yet. My last attempt was to add a pre-job pwsh task and use the task lib to overwrite the variable, but that's blocked. I may try to export the environment variable, see if that works, but I'm afraid it will be blocked too.

It would be nice if we could set some if the agent config elements as a capability on a hosted pool. But I'm not aware if any way to do that (yet).

jessehouwing commented 3 years ago

And yes, I'm looking at that PR, as far as I can tell that's the code that will set a different workspace name and build folder for TFVC workflows.

jessehouwing commented 3 years ago

Log from agent init:

Starting: Initialize job
Agent name: 'Hosted Agent'
Agent machine name: 'WIN-GN0TI3Q7IGJ'
Current agent version: '2.195.0'
Operating System
Virtual Environment
Virtual Environment Provisioner
Current image version: '20211122.1'
Agent running as: 'VssAdministrator'
##[debug]Triggering repository: vsts-tfvc-tasks-test. repository type: Tfvc
Prepare build directory.
##[debug]Creating build directory: 'D:\a\19'
##[debug]Delete existing artifacts directory: 'D:\a\19\a'
##[debug]Creating artifacts directory: 'D:\a\19\a'
##[debug]Delete existing test results directory: 'D:\a\19\TestResults'
##[debug]Creating test results directory: 'D:\a\19\TestResults'
##[debug]Creating binaries directory: 'D:\a\19\b'
##[debug]Creating source directory: 'D:\a\19\s'
Set build variables.
Download all required tasks.
Checking job knob settings.
   Knob: AgentToolsDirectory = C:\hostedtoolcache\windows Source: ${AGENT_TOOLSDIRECTORY} 
   Knob: AgentPerflog = C:\agents\perflog Source: ${VSTS_AGENT_PERFLOG} 
Finished checking job knob settings.
##[debug]Log plugin 'TestResultLogPlugin' is disabled.
##[debug]Log plugin 'TestFilePublisherPlugin' is disabled.
Start tracking orphan processes.
Finishing: Initialize job

Then in Checkout it's clearly creating ws_19_19 instead of ws_1_19 as well as maps to d:\a\19 instead of d:\a\1.

tf vc workspace /new /location:local /permission:Public ws_19_19 /collection:https://dev.azure.com/jessehouwing-dev/ /loginType:OAuth /login:.,*** /noprompt
tf vc workfold /map /workspace:ws_19_19 $/vsts-tfvc-tasks-exclusive-test/BuildProcessTemplates D:\a\19\s\BuildProcessTemplates /collection:https://dev.azure.com/jessehouwing-dev/ /loginType:OAuth /login:.,*** /noprompt

This is the behavior we need for the client as well.

jessehouwing commented 3 years ago

And from the client's environment:

Starting: Initialize job
Agent name: 'Hosted Agent'
Agent machine name: 'fv-az204-338'
Current agent version: '2.195.0'
Operating System
Virtual Environment
Virtual Environment Provisioner
Current image version: '20211122.1'
Agent running as: 'VssAdministrator'
Prepare build directory.
##[debug]Creating build directory: 'D:\a\1'
##[debug]Delete existing artifacts directory: 'D:\a\1\a'
##[debug]Creating artifacts directory: 'D:\a\1\a'
##[debug]Delete existing test results directory: 'D:\a\1\TestResults'
##[debug]Creating test results directory: 'D:\a\1\TestResults'
##[debug]Creating binaries directory: 'D:\a\1\b'
##[debug]Creating source directory: 'D:\a\1\s'
Download all required tasks.
Downloading task: tf-vc-fixparallel-log (2.2.51)
Downloading task: tf-vc-fixparallel (2.2.51)
Downloading task: CmdLine (2.182.0)
Checking job knob settings.
   Knob: AgentToolsDirectory = C:\hostedtoolcache\windows Source: ${AGENT_TOOLSDIRECTORY} 
   Knob: AgentPerflog = c:\vsts\perflog Source: ${VSTS_AGENT_PERFLOG} 
Finished checking job knob settings.
Start tracking orphan processes.
Finishing: Initialize job

And in checkout:

tf vc workspace /new /location:local /permission:Public ws_1_8 /collection:https://dev.azure.com/xxxxx/ /loginType:OAuth /login:.,*** /noprompt
tf vc workfold /map /workspace:ws_1_8 $/xxxxx D:\a\1\s /collection:https://dev.azure.com/xxxxx/ /loginType:OAuth /login:.,*** /noprompt
anatolybolshakov commented 3 years ago

Hi @jessehouwing recent fix for TFVC is not rolled out yet - the behavior you observe could be related to the condition for assignment of agent id as build directory name (one tfvc repository in a pipeline) or spontaneous nature of original issue.

jessehouwing commented 3 years ago

The client's builds have only 1 TFVC repo in their build definitions. Which is always the case for TFVC builds as far as I can tell.

In that case, what bug is causing that line not to work for our builds?! The Pipeline clearly hars only a single repo and it's type is TFVC:

  "repository": {
    "properties": {
      "cleanOptions": "0",
      "tfvcMapping": "{\"mappings\":[{\"serverPath\":\"$/xxxx",\"mappingType\":\"map\",\"localPath\":\"\\\\\"}]}",
      "labelSources": "0",
      "labelSourcesFormat": "$(build.buildNumber)"
    },
    "id": "$/",
    "type": "TfsVersionControl",
    "name": "xxxx",
    "url": "https://dev.azure.com/xxxxS/",
    "defaultBranch": "$/xxxx",
    "rootFolder": "$/xxxx",
    "clean": "false",
    "checkoutSubmodules": false
  },

And the agent is of type Hosted:

Starting: Fix parallel execution on hosted agent 1/2.
==============================================================================
Task         : TFVC - fix parallel execution on hosted agent 1/2
Description  : Fix parallel execution on hosted agent. 1/2
Version      : 2.2.51
Author       : Jesse Houwing
Help         : https://jessehouwing.net/azure-pipelines-fixing-massive-parallel-builds-with-tfvc/
==============================================================================
##[debug]VstsTaskSdk 0.11.0 commit 7ff27a3e0bdd6f7b06690ae5f5b63cb84d0f23f4
System.JobId:275f1d19-1bd8-5591-b06b-07d489ea915a
System.JobAttempt:1
Agent.AgentId:8
System.ServerType:Hosted
Agent.MachineName:fv-az223-788
Build.Repository.Tfvc.Workspace:ws_1_8
Finishing: Fix parallel execution on hosted agent 1/2.

It's too predictably true for my account to be of spontaneous nature. I've tried creating new builds and all of them fail this important check and allocate ws_1_AgentId on the client's environment.

Is it possible this happens on environments that were imported, my accounts are all created through the portal, their account is a recent import. Could that somehow infuence the repository count?

jessehouwing commented 3 years ago

It looks like the pending PR does influence this behavior, the value of shouldOverrideBuildDirectory was previously discarded in a merge situation:

https://github.com/microsoft/azure-pipelines-agent/blob/9deaae2bb250f3cfb91f149d4a6d763f770b907e/src/Agent.Worker/Build/BuildDirectoryManager.cs#L67

frankvaneykelen-work commented 3 years ago

The client's builds have only 1 TFVC repo in their build definitions. Which is always the case for TFVC builds as far as I can tell.

Isn't this an example of having 2 repo's in one build definition? image

(btw: I work for the client @jessehouwing makes references to in this issue)

EzzhevNikita commented 2 years ago

Let me close this item since the changes mentioned in this ticket have already been deployed. Feel free to ping in case of any questions.

jessehouwing commented 2 years ago

Still failing with 2.196.2 this is not fixed.

jessehouwing commented 2 years ago

IMG-20220118-WA0002~2

anatolybolshakov commented 2 years ago

@EzzhevNikita could you please check?

EzzhevNikita commented 2 years ago

@jessehouwing Could you please share an example of yml, where you have seen an issue?

frankvaneykelen-work commented 2 years ago

The actual build @jessehouwing shared a screenshot of is JSON. but this is the YAML export. Please note the parallel: 20 which is what actually causes some of the runs to fail.

resources:
  repositories:
  - repository: self
    type: git
    ref: $/[redacted]/
jobs:
- job: Job_1
  displayName: Agent job 1
  strategy:
    parallel: 20
  pool:
    vmImage: windows-2019
  steps:
  - checkout: self
  - task: CmdLine@2
    displayName: Command Line Script
...

This is the JSON of the task, showing it does a trivial command line echo:

        "steps": [
          {
            "environment": {
            },
            "enabled": true,
            "continueOnError": false,
            "alwaysRun": false,
            "displayName": "Command Line Script",
            "timeoutInMinutes": 0,
            "retryCountOnTaskFailure": 0,
            "condition": "succeeded()",
            "task": {
              "id": "d9bafed4-0b18-4f58-968d-86655b4d2ce9",
              "versionSpec": "2.*",
              "definitionType": "task"
            },
            "inputs": {
              "script": "echo Write your commands here\n\necho Hello world\n",
              "workingDirectory": "",
              "failOnStderr": "false"
            }
          }
        ],
EzzhevNikita commented 2 years ago

@frankvaneykelen-work Could you please also attach a log of the failed pipeline?

frankvaneykelen-work commented 2 years ago

Agent job 1 8

2022-01-18T10:20:32.6542413Z ##[debug]Evaluating condition for step: 'Checkout REDACTED@ to s'
2022-01-18T10:20:32.6589599Z ##[debug]Evaluating: SucceededNode()
2022-01-18T10:20:32.6595444Z ##[debug]Evaluating SucceededNode:
2022-01-18T10:20:32.6638138Z ##[debug]=> True
2022-01-18T10:20:32.6647307Z ##[debug]Result: True
2022-01-18T10:20:32.6697825Z ##[section]Starting: Checkout REDACTED@ to s
2022-01-18T10:20:33.5926476Z ==============================================================================
2022-01-18T10:20:33.5927150Z Task         : Get sources
2022-01-18T10:20:33.5929746Z Description  : Get sources from a repository. Supports Git, TfsVC, and SVN repositories.
2022-01-18T10:20:33.5930014Z Version      : 1.0.0
2022-01-18T10:20:33.5930299Z Author       : Microsoft
2022-01-18T10:20:33.5930744Z Help         : [More Information](https://go.microsoft.com/fwlink/?LinkId=798199)
2022-01-18T10:20:33.5931027Z ==============================================================================
2022-01-18T10:20:34.6038918Z ##[debug]Processed: ##vso[plugininternal.updaterepositorypath alias=__designer_repo;]D:\a\1\s
2022-01-18T10:20:34.6043910Z ##[debug]Repository requires to be placed at 'D:\a\1\s', current location is 'D:\a\1\s'
2022-01-18T10:20:34.6046108Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\.NETFramework', value name 'InstallRoot': 'C:\Windows\Microsoft.NET\Framework64\'
2022-01-18T10:20:34.6046897Z ##[debug]Key name 'SOFTWARE\Microsoft\NET Framework Setup\NDP' contains sub keys:
2022-01-18T10:20:34.6047457Z ##[debug] 'CDF'
2022-01-18T10:20:34.6047921Z ##[debug] 'v2.0.50727'
2022-01-18T10:20:34.6048408Z ##[debug] 'v3.0'
2022-01-18T10:20:34.6048869Z ##[debug] 'v3.5'
2022-01-18T10:20:34.6049394Z ##[debug] 'v4'
2022-01-18T10:20:34.6049852Z ##[debug] 'v4.0'
2022-01-18T10:20:34.6050492Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v2.0.50727', value name 'Version': '2.0.50727.4927'
2022-01-18T10:20:34.6051274Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v2.0.50727', value name 'Install': '1'
2022-01-18T10:20:34.6051969Z ##[debug]Testing directory: 'C:\Windows\Microsoft.NET\Framework64\v2.0.50727'
2022-01-18T10:20:34.6052655Z ##[debug]Found version: 2.0.50727
2022-01-18T10:20:34.6053316Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v3.0', value name 'Version': '3.0.30729.4926'
2022-01-18T10:20:34.6054092Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v3.0', value name 'Install': '1'
2022-01-18T10:20:34.6054774Z ##[debug]Testing directory: 'C:\Windows\Microsoft.NET\Framework64\v3.0'
2022-01-18T10:20:34.6055320Z ##[debug]Found version: 3.0
2022-01-18T10:20:34.6055962Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v3.5', value name 'Version': '3.5.30729.4926'
2022-01-18T10:20:34.6056720Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v3.5', value name 'Install': '1'
2022-01-18T10:20:34.6057399Z ##[debug]Testing directory: 'C:\Windows\Microsoft.NET\Framework64\v3.5'
2022-01-18T10:20:34.6057957Z ##[debug]Found version: 3.5
2022-01-18T10:20:34.6058535Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4', value name 'Version' is null.
2022-01-18T10:20:34.6059416Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4', value name '' is null.
2022-01-18T10:20:34.6060082Z ##[debug]Key name 'SOFTWARE\Microsoft\NET Framework Setup\NDP\v4' contains sub keys:
2022-01-18T10:20:34.6060652Z ##[debug] 'Client'
2022-01-18T10:20:34.6061125Z ##[debug] 'Full'
2022-01-18T10:20:34.6061760Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Client', value name 'Version': '4.8.03761'
2022-01-18T10:20:34.6062606Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Client', value name 'Install': '1'
2022-01-18T10:20:34.6063432Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Client', value name 'InstallPath': 'C:\Windows\Microsoft.NET\Framework64\v4.0.30319\'
2022-01-18T10:20:34.6065989Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Client', value name 'Release': '528049'
2022-01-18T10:20:34.6066629Z ##[debug]Type is System.Int32
2022-01-18T10:20:34.6067135Z ##[debug]Interpreted version: 4.7.0
2022-01-18T10:20:34.6067778Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Full', value name 'Version': '4.8.03761'
2022-01-18T10:20:34.6068997Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Full', value name 'Install': '1'
2022-01-18T10:20:34.6069823Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Full', value name 'InstallPath': 'C:\Windows\Microsoft.NET\Framework64\v4.0.30319\'
2022-01-18T10:20:34.6070638Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Full', value name 'Release': '528049'
2022-01-18T10:20:34.6071279Z ##[debug]Type is System.Int32
2022-01-18T10:20:34.6071795Z ##[debug]Interpreted version: 4.7.0
2022-01-18T10:20:34.6072561Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4.0', value name 'Version' is null.
2022-01-18T10:20:34.6073274Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4.0', value name '': 'deprecated'
2022-01-18T10:20:34.6073898Z ##[debug]Found 5 versions:
2022-01-18T10:20:34.6074389Z ##[debug] 2.0.50727
2022-01-18T10:20:34.6074865Z ##[debug] 3.0
2022-01-18T10:20:34.6075305Z ##[debug] 3.5
2022-01-18T10:20:34.6075757Z ##[debug] 4.7.0
2022-01-18T10:20:34.6076220Z ##[debug] 4.7.0
2022-01-18T10:20:34.6076745Z ##[debug]Testing for min NET Framework version: '4.6'
2022-01-18T10:20:34.6077223Z Prepending Path environment variable with directory containing 'tf.exe'.
2022-01-18T10:20:34.6082757Z ##[debug]Processed: ##vso[task.prependpath]C:\agents\2.196.2\externals\tf
2022-01-18T10:20:34.6087980Z ##[debug]PATH: 'C:\agents\2.196.2\externals\tf;C:\Program Files\MongoDB\Server\5.0\bin;C:\aliyun-cli;C:\vcpkg;C:\cf-cli;C:\Program Files (x86)\NSIS\;C:\tools\zstd;C:\Program Files\Mercurial\;C:\hostedtoolcache\windows\stack\2.7.3\x64;C:\cabal\bin;C:\\ghcup\bin;C:\tools\ghc-9.2.1\bin;C:\Program Files\dotnet;C:\mysql\bin;C:\Program Files\R\R-4.1.2\bin\x64;C:\SeleniumWebDrivers\GeckoDriver;C:\Program Files (x86)\sbt\bin;C:\Program Files (x86)\GitHub CLI;C:\Program Files\Git\bin;C:\Program Files (x86)\pipx_bin;C:\hostedtoolcache\windows\go\1.15.15\x64\bin;C:\hostedtoolcache\windows\Python\3.7.9\x64\Scripts;C:\hostedtoolcache\windows\Python\3.7.9\x64;C:\hostedtoolcache\windows\Ruby\2.5.9\x64\bin;C:\tools\kotlinc\bin;C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\8.0.312-7\x64\bin;C:\npm\prefix;C:\Program Files (x86)\Microsoft SDKs\Azure\CLI2\wbin;C:\ProgramData\kind;C:\Program Files\Eclipse Foundation\jdk-8.0.302.8-hotspot\bin;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\Windows\System32\OpenSSH\;C:\ProgramData\Chocolatey\bin;C:\Program Files\Docker;C:\Program Files\PowerShell\7\;C:\Program Files\Microsoft\Web Platform Installer\;C:\Program Files\dotnet\;C:\Program Files\Microsoft SQL Server\130\Tools\Binn\;C:\Program Files\Microsoft SQL Server\Client SDK\ODBC\170\Tools\Binn\;C:\Program Files (x86)\Windows Kits\10\Windows Performance Toolkit\;C:\Program Files (x86)\Microsoft SQL Server\110\DTS\Binn\;C:\Program Files (x86)\Microsoft SQL Server\120\DTS\Binn\;C:\Program Files (x86)\Microsoft SQL Server\130\DTS\Binn\;C:\Program Files (x86)\Microsoft SQL Server\140\DTS\Binn\;C:\Program Files (x86)\Microsoft SQL Server\150\DTS\Binn\;C:\Program Files\nodejs\;C:\Program Files\OpenSSL\bin;C:\Strawberry\c\bin;C:\Strawberry\perl\site\bin;C:\Strawberry\perl\bin;C:\ProgramData\chocolatey\lib\pulumi\tools\Pulumi\bin;C:\Program Files\TortoiseSVN\bin;C:\Program Files\CMake\bin;C:\ProgramData\chocolatey\lib\maven\apache-maven-3.8.4\bin;C:\Program Files\Microsoft Service Fabric\bin\Fabric\Fabric.Code;C:\Program Files\Microsoft SDKs\Service Fabric\Tools\ServiceFabricLocalClusterManager;C:\Program Files\Git\cmd;C:\Program Files\Git\mingw64\bin;C:\Program Files\Git\usr\bin;c:\tools\php;C:\Program Files (x86)\sbt\bin;C:\SeleniumWebDrivers\ChromeDriver\;C:\SeleniumWebDrivers\EdgeDriver\;C:\Program Files\Amazon\AWSCLIV2\;C:\Program Files\Amazon\SessionManagerPlugin\bin\;C:\Program Files\Amazon\AWSSAMCLI\bin\;C:\Program Files (x86)\Google\Cloud SDK\google-cloud-sdk\bin;C:\Program Files (x86)\Microsoft BizTalk Server\;C:\Program Files\LLVM\bin;C:\Users\VssAdministrator\.dotnet\tools;C:\Users\VssAdministrator\.cargo\bin;C:\Users\VssAdministrator\AppData\Local\Microsoft\WindowsApps'
2022-01-18T10:20:34.6093340Z Setting environment variable TFVC_BUILDAGENT_POLICYPATH
2022-01-18T10:20:34.6100137Z ##[debug]Processed: ##vso[task.setvariable variable=TFVC_BUILDAGENT_POLICYPATH;issecret=False;]C:\agents\2.196.2\externals\tf\Microsoft.TeamFoundation.VersionControl.Controls.dll
2022-01-18T10:20:34.6100845Z Querying workspace information.
2022-01-18T10:20:34.6106281Z ##[debug]tf vc workspaces /format:xml /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:34.6155753Z ##[debug]Starting process:
2022-01-18T10:20:34.6156688Z ##[debug]  File name: 'tf'
2022-01-18T10:20:34.6159080Z ##[debug]  Arguments: 'vc workspaces /format:xml /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:34.6159911Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:34.6160601Z ##[debug]  Require exit code zero: 'True'
2022-01-18T10:20:34.6163217Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:34.6164004Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:34.6164689Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:34.6165361Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:34.6166027Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:34.6166874Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:34.6258324Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:34.6364222Z ##[debug]Process started with process id 1276, waiting for process exit.
2022-01-18T10:20:41.8571877Z ##[debug]<Workspaces>
2022-01-18T10:20:41.8620981Z ##[debug]  <Workspace computer="fv-az46-570" islocal="true" name="ws_1_73" ownerdisp="Project Collection Build Service (REDACTED)" ownerid="ab21bc76-eb45-4c55-bc13-70ab5137d5ad:Build:03bfd66a-1ced-4a3f-b479-186dc2c894f0" ownertype="Microsoft.TeamFoundation.ServiceIdentity" owner="c75ebe8d-c40f-466b-8698-55694170789e" owneruniq="c75ebe8d-c40f-466b-8698-55694170789e">
2022-01-18T10:20:41.8624687Z ##[debug]    <Comment />
2022-01-18T10:20:41.8627215Z ##[debug]    <Folders>
2022-01-18T10:20:41.8629709Z ##[debug]      <WorkingFolder local="D:\a\1\s\ALM\Deployment" item="$/ALM/Deployment" />
2022-01-18T10:20:41.8632233Z ##[debug]      <WorkingFolder local="D:\a\1\s\Dev06\Base1\Binaries" item="$/REDACTED/Dev06/Base1/Binaries" />
2022-01-18T10:20:41.8634497Z ##[debug]      <WorkingFolder local="D:\a\1\s\Dev06\Base2" item="$/REDACTED/Dev06/Base2" />
2022-01-18T10:20:41.8636689Z ##[debug]      <WorkingFolder local="D:\a\1\s\Dev06\IdentityServer\Nextens.IdentityServer.MultiFactor.Domain.Shared" item="$/REDACTED/Dev06/IdentityServer/Nextens.IdentityServer.MultiFactor.Domain.Shared" />
2022-01-18T10:20:41.8638905Z ##[debug]      <WorkingFolder local="D:\a\1\s\Dev06\IdentityServer\Nextens.IdentityServer.MultiFactor.Port.Http" item="$/REDACTED/Dev06/IdentityServer/Nextens.IdentityServer.MultiFactor.Port.Http" />
2022-01-18T10:20:41.8641137Z ##[debug]      <WorkingFolder local="D:\a\1\s\Dev06\IdentityServer\Nextens.IdentityServer.MultiFactor.Port" item="$/REDACTED/Dev06/IdentityServer/Nextens.IdentityServer.MultiFactor.Port" />
2022-01-18T10:20:41.8643072Z ##[debug]      <WorkingFolder local="D:\a\1\s\Dev06\Libraries" item="$/REDACTED/Dev06/Libraries" />
2022-01-18T10:20:41.8645425Z ##[debug]      <WorkingFolder local="D:\a\1\s\Tools\PowerShell" item="$/REDACTED/Tools/PowerShell" />
2022-01-18T10:20:41.8647165Z ##[debug]    </Folders>
2022-01-18T10:20:41.8648895Z ##[debug]    <LastAccessDate>2022-01-18T09:55:57.813+00:00</LastAccessDate>
2022-01-18T10:20:41.8650959Z ##[debug]    <OwnerAliases>
2022-01-18T10:20:41.8652311Z ##[debug]      <string>Build\03bfd66a-1ced-4a3f-b479-186dc2c894f0</string>
2022-01-18T10:20:41.8653625Z ##[debug]      <string>03bfd66a-1ced-4a3f-b479-186dc2c894f0</string>
2022-01-18T10:20:41.8654970Z ##[debug]      <string>Project Collection Build Service (REDACTED)</string>
2022-01-18T10:20:41.8656468Z ##[debug]    </OwnerAliases>
2022-01-18T10:20:41.8657704Z ##[debug]  </Workspace>
2022-01-18T10:20:41.8658872Z ##[debug]</Workspaces>
2022-01-18T10:20:42.0012756Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:42.0046782Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:42.0083546Z ##[debug]Exited process 1276 with exit code 0
2022-01-18T10:20:42.0084948Z ##[debug]Finished process 1276 with exit code 0, and elapsed time 00:00:07.3915333.
2022-01-18T10:20:42.1313679Z ##[debug]Processed: ##vso[task.setvariable variable=build.repository.tfvc.workspace;issecret=False;]ws_1_325
2022-01-18T10:20:42.1445578Z ##[debug]Sources directory does not exist or is empty.
2022-01-18T10:20:42.1526603Z ##[command]tf vc workspace /delete ws_1_73%3Bc75ebe8d-c40f-466b-8698-55694170789e /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:42.1527537Z ##[debug]Starting process:
2022-01-18T10:20:42.1528140Z ##[debug]  File name: 'tf'
2022-01-18T10:20:42.1530287Z ##[debug]  Arguments: 'vc workspace /delete ws_1_73;c75ebe8d-c40f-466b-8698-55694170789e /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:42.1531092Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:42.1531727Z ##[debug]  Require exit code zero: 'True'
2022-01-18T10:20:42.1532401Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:42.1533065Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:42.1533708Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:42.1534324Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:42.1534961Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:42.1535599Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:42.1569668Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:42.1571796Z ##[debug]Process started with process id 1196, waiting for process exit.
2022-01-18T10:20:47.5680376Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:47.5745198Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:47.5774391Z ##[debug]Exited process 1196 with exit code 0
2022-01-18T10:20:47.5804026Z ##[debug]Finished process 1196 with exit code 0, and elapsed time 00:00:05.4242783.
2022-01-18T10:20:47.5850327Z ##[debug]tf vc workspaces ws_1_325 /computer:* /format:xml /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:47.5853435Z ##[debug]Starting process:
2022-01-18T10:20:47.5854969Z ##[debug]  File name: 'tf'
2022-01-18T10:20:47.5857807Z ##[debug]  Arguments: 'vc workspaces ws_1_325 /computer:* /format:xml /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:47.5858778Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:47.5859505Z ##[debug]  Require exit code zero: 'True'
2022-01-18T10:20:47.5860540Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:47.5861319Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:47.5862075Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:47.5862795Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:47.5863535Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:47.5864265Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:47.5865883Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:47.5866990Z ##[debug]Process started with process id 6280, waiting for process exit.
2022-01-18T10:20:49.6435808Z ##[debug]<Workspaces />
2022-01-18T10:20:49.6562357Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:49.6565641Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:49.6610722Z ##[debug]Exited process 6280 with exit code 0
2022-01-18T10:20:49.6611739Z ##[debug]Finished process 6280 with exit code 0, and elapsed time 00:00:02.0818419.
2022-01-18T10:20:49.6617000Z ##[debug]Deleting: 'D:\a\1\s'.
2022-01-18T10:20:49.6769684Z ##[debug]useServerWorkspace is set to : 'False'
2022-01-18T10:20:49.6774292Z ##[command]tf vc workspace /new /location:local /permission:Public ws_1_325 /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:49.6775176Z ##[debug]Starting process:
2022-01-18T10:20:49.6775814Z ##[debug]  File name: 'tf'
2022-01-18T10:20:49.6777902Z ##[debug]  Arguments: 'vc workspace /new /location:local /permission:Public ws_1_325 /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:49.6778959Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:49.6779985Z ##[debug]  Require exit code zero: 'False'
2022-01-18T10:20:49.6780708Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:49.6781354Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:49.6781996Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:49.6784383Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:49.6785058Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:49.6785605Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:49.6811444Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:49.6812821Z ##[debug]Process started with process id 5640, waiting for process exit.
2022-01-18T10:20:51.5645167Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:51.5648834Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:51.5742961Z ##[debug]Exited process 5640 with exit code 0
2022-01-18T10:20:51.5777700Z ##[debug]Finished process 5640 with exit code 0, and elapsed time 00:00:01.8931171.
2022-01-18T10:20:51.5786596Z ##[command]tf vc workfold /unmap /workspace:ws_1_325 $/ /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:51.5792284Z ##[debug]Starting process:
2022-01-18T10:20:51.5796558Z ##[debug]  File name: 'tf'
2022-01-18T10:20:51.5799175Z ##[debug]  Arguments: 'vc workfold /unmap /workspace:ws_1_325 $/ /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:51.5800123Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:51.5803583Z ##[debug]  Require exit code zero: 'False'
2022-01-18T10:20:51.5804485Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:51.5805410Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:51.5806206Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:51.5806931Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:51.5807657Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:51.5813139Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:51.5814172Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:51.5818773Z ##[debug]Process started with process id 6568, waiting for process exit.
2022-01-18T10:20:52.7241636Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:52.7248665Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:52.7307461Z ##[debug]Exited process 6568 with exit code 0
2022-01-18T10:20:52.7308822Z ##[debug]Finished process 6568 with exit code 0, and elapsed time 00:00:01.1571164.
2022-01-18T10:20:52.7318910Z ##[command]tf vc workfold /map /workspace:ws_1_325 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:52.7324785Z ##[debug]Starting process:
2022-01-18T10:20:52.7326878Z ##[debug]  File name: 'tf'
2022-01-18T10:20:52.7329384Z ##[debug]  Arguments: 'vc workfold /map /workspace:ws_1_325 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:52.7330245Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:52.7332842Z ##[debug]  Require exit code zero: 'False'
2022-01-18T10:20:52.7336249Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:52.7346932Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:52.7348402Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:52.7349267Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:52.7349972Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:52.7355653Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:52.7361869Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:52.7369176Z ##[debug]Process started with process id 5244, waiting for process exit.
2022-01-18T10:20:53.5654571Z The working folder D:\a\1\s is already in use by the workspace ws_1_322;Project Collection Build Service (REDACTED) on computer fv-az46-570.
2022-01-18T10:20:53.5739778Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:53.5743059Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:53.5804134Z ##[debug]Exited process 5244 with exit code 100
2022-01-18T10:20:53.5810749Z ##[debug]Finished process 5244 with exit code 100, and elapsed time 00:00:00.8483830.
2022-01-18T10:20:53.5811641Z Sleeping for 200 ms
2022-01-18T10:20:53.7882719Z Retrying. Attempt 2/3
2022-01-18T10:20:53.7887868Z ##[command]tf vc workfold /map /workspace:ws_1_325 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:53.7888942Z ##[debug]Starting process:
2022-01-18T10:20:53.7889570Z ##[debug]  File name: 'tf'
2022-01-18T10:20:53.7891709Z ##[debug]  Arguments: 'vc workfold /map /workspace:ws_1_325 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:53.7892504Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:53.7893116Z ##[debug]  Require exit code zero: 'False'
2022-01-18T10:20:53.7893789Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:53.7894473Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:53.7895100Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:53.7895691Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:53.7896304Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:53.7896909Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:53.7913197Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:53.7914447Z ##[debug]Process started with process id 2884, waiting for process exit.
2022-01-18T10:20:54.7249803Z The working folder D:\a\1\s is already in use by the workspace ws_1_322;Project Collection Build Service (REDACTED) on computer fv-az46-570.
2022-01-18T10:20:54.7326025Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:54.7329297Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:54.7372885Z ##[debug]Exited process 2884 with exit code 100
2022-01-18T10:20:54.7374058Z ##[debug]Finished process 2884 with exit code 100, and elapsed time 00:00:00.9482291.
2022-01-18T10:20:54.7374632Z Sleeping for 1000 ms
2022-01-18T10:20:56.0820144Z Retrying. Attempt 3/3
2022-01-18T10:20:56.0822959Z ##[command]tf vc workfold /map /workspace:ws_1_325 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:56.0823729Z ##[debug]Starting process:
2022-01-18T10:20:56.0824210Z ##[debug]  File name: 'tf'
2022-01-18T10:20:56.0826953Z ##[debug]  Arguments: 'vc workfold /map /workspace:ws_1_325 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:56.0827630Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:56.0828232Z ##[debug]  Require exit code zero: 'True'
2022-01-18T10:20:56.0828791Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:56.0829333Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:56.0829843Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:56.0830464Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:56.0830969Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:56.0831473Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:56.0831955Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:56.0832742Z ##[debug]Process started with process id 5292, waiting for process exit.
2022-01-18T10:20:56.6287829Z The working folder D:\a\1\s is already in use by the workspace ws_1_322;Project Collection Build Service (REDACTED) on computer fv-az46-570.
2022-01-18T10:20:56.6371445Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:56.6383300Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:56.6434465Z ##[debug]Exited process 5292 with exit code 100
2022-01-18T10:20:56.6435343Z ##[debug]Finished process 5292 with exit code 100, and elapsed time 00:00:00.9044958.
2022-01-18T10:20:56.6483886Z ##[error]Exit code 100 returned from process: file name 'tf', arguments 'vc workfold /map /workspace:ws_1_325 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'.
2022-01-18T10:20:56.6495374Z ##[debug]Processed: ##vso[task.logissue type=error;]Exit code 100 returned from process: file name 'tf', arguments 'vc workfold /map /workspace:ws_1_325 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'.
2022-01-18T10:20:56.6504464Z ##[debug]Processed: ##vso[task.complete result=Failed;]
2022-01-18T10:20:56.6638045Z ##[debug]   at Microsoft.VisualStudio.Services.Agent.Util.ProcessInvoker.ExecuteAsync(String workingDirectory, String fileName, String arguments, IDictionary`2 environment, Boolean requireExitCodeZero, Encoding outputEncoding, Boolean killProcessOnCancel, InputQueue`1 redirectStandardIn, Boolean inheritConsoleHandler, Boolean keepStandardInOpen, Boolean highPriorityProcess, CancellationToken cancellationToken)
   at Agent.Plugins.Repository.TfsVCCliManager.RunCommandAsync(FormatFlags formatFlags, Boolean quiet, Boolean failOnNonZeroExitCode, String[] args)
   at Agent.Plugins.Repository.TfsVCCliManager.RunCommandAsync(FormatFlags formatFlags, Boolean quiet, Int32 retriesOnFailure, String[] args)
   at Agent.Plugins.Repository.TFCliManager.WorkfoldMapAsync(String serverPath, String localPath)
   at Agent.Plugins.Repository.TfsVCSourceProvider.GetSourceAsync(AgentTaskPluginExecutionContext executionContext, RepositoryResource repository, CancellationToken cancellationToken)
   at Agent.Plugins.Repository.CheckoutTask.RunAsync(AgentTaskPluginExecutionContext executionContext, CancellationToken token)
   at Agent.PluginHost.Program.Main(String[] args)
2022-01-18T10:20:56.6767184Z ##[section]Finishing: Checkout REDACTED@ to s
frankvaneykelen-work commented 2 years ago

Agent job 1 15

2022-01-18T10:20:31.1170552Z ##[section]Starting: Agent job 1 15
2022-01-18T10:20:31.1221151Z ##[debug]Cleaning agent temp folder: D:\a\_temp
2022-01-18T10:20:31.1260511Z ##[debug]Skipping overwrite %TEMP% environment variable
2022-01-18T10:20:31.3040397Z ##[section]Starting: Initialize job
2022-01-18T10:20:31.3041838Z Agent name: 'Azure Pipelines 6'
2022-01-18T10:20:31.3042219Z Agent machine name: 'fv-az105-591'
2022-01-18T10:20:31.3042418Z Current agent version: '2.196.2'
2022-01-18T10:20:31.3083904Z ##[group]Operating System
2022-01-18T10:20:31.3084145Z Microsoft Windows Server 2019
2022-01-18T10:20:31.3084303Z 10.0.17763
2022-01-18T10:20:31.3084416Z Datacenter
2022-01-18T10:20:31.3084535Z ##[endgroup]
2022-01-18T10:20:31.3084677Z ##[group]Virtual Environment
2022-01-18T10:20:31.3084856Z Environment: windows-2019
2022-01-18T10:20:31.3085005Z Version: 20220110.1
2022-01-18T10:20:31.3085257Z Included Software: https://github.com/actions/virtual-environments/blob/win19/20220110.1/images/win/Windows2019-Readme.md
2022-01-18T10:20:31.3085829Z Image Release: https://github.com/actions/virtual-environments/releases/tag/win19%2F20220110.1
2022-01-18T10:20:31.3086044Z ##[endgroup]
2022-01-18T10:20:31.3086228Z ##[group]Virtual Environment Provisioner
2022-01-18T10:20:31.3086395Z 1.0.0.0-main-20211214-1
2022-01-18T10:20:31.3086542Z ##[endgroup]
2022-01-18T10:20:31.3087353Z Current image version: '20220110.1'
2022-01-18T10:20:31.3096070Z Agent running as: 'VssAdministrator'
2022-01-18T10:20:31.3130997Z ##[debug]Triggering repository: REDACTED. repository type: Tfvc
2022-01-18T10:20:31.3134739Z Prepare build directory.
2022-01-18T10:20:31.3333721Z ##[debug]Creating build directory: 'D:\a\1'
2022-01-18T10:20:31.3336203Z ##[debug]Delete existing artifacts directory: 'D:\a\1\a'
2022-01-18T10:20:31.3338427Z ##[debug]Creating artifacts directory: 'D:\a\1\a'
2022-01-18T10:20:31.3340398Z ##[debug]Delete existing test results directory: 'D:\a\1\TestResults'
2022-01-18T10:20:31.3341056Z ##[debug]Creating test results directory: 'D:\a\1\TestResults'
2022-01-18T10:20:31.3346851Z ##[debug]Creating binaries directory: 'D:\a\1\b'
2022-01-18T10:20:31.3348628Z ##[debug]Creating source directory: 'D:\a\1\s'
2022-01-18T10:20:31.3399069Z Set build variables.
2022-01-18T10:20:31.3432392Z Download all required tasks.
2022-01-18T10:20:31.3544981Z Downloading task: CmdLine (2.182.0)
2022-01-18T10:20:31.8774384Z ##[debug]Task 'CmdLine' has been downloaded into 'D:\a\_tasks\CmdLine_d9bafed4-0b18-4f58-968d-86655b4d2ce9\2.182.0'.
2022-01-18T10:20:32.6952991Z ##[debug]Task 'Command Line Script' has following condition: 'succeeded()'.
2022-01-18T10:20:32.6996413Z ##[debug]Parsing expression: <succeeded()>
2022-01-18T10:20:32.7034491Z ##[debug]succeeded
2022-01-18T10:20:32.7038581Z ##[debug](
2022-01-18T10:20:32.7041062Z ##[debug])
2022-01-18T10:20:32.7055866Z Checking job knob settings.
2022-01-18T10:20:32.7065607Z    Knob: AgentToolsDirectory = C:\hostedtoolcache\windows Source: ${AGENT_TOOLSDIRECTORY} 
2022-01-18T10:20:32.7066844Z    Knob: AgentPerflog = c:\vsts\perflog Source: ${VSTS_AGENT_PERFLOG} 
2022-01-18T10:20:32.7068566Z Finished checking job knob settings.
2022-01-18T10:20:32.7593646Z ##[debug]Log plugin 'TestResultLogPlugin' is disabled.
2022-01-18T10:20:32.7594114Z ##[debug]Log plugin 'TestFilePublisherPlugin' is disabled.
2022-01-18T10:20:32.7594532Z Start tracking orphan processes.
2022-01-18T10:20:32.7675895Z ##[section]Finishing: Initialize job
2022-01-18T10:20:32.7927927Z ##[debug]Evaluating condition for step: 'Checkout REDACTED@ to s'
2022-01-18T10:20:32.7982018Z ##[debug]Evaluating: SucceededNode()
2022-01-18T10:20:32.7988581Z ##[debug]Evaluating SucceededNode:
2022-01-18T10:20:32.8037031Z ##[debug]=> True
2022-01-18T10:20:32.8047307Z ##[debug]Result: True
2022-01-18T10:20:32.8095776Z ##[section]Starting: Checkout REDACTED@ to s
2022-01-18T10:20:32.8885565Z ==============================================================================
2022-01-18T10:20:32.8886023Z Task         : Get sources
2022-01-18T10:20:32.8888553Z Description  : Get sources from a repository. Supports Git, TfsVC, and SVN repositories.
2022-01-18T10:20:32.8888820Z Version      : 1.0.0
2022-01-18T10:20:32.8889112Z Author       : Microsoft
2022-01-18T10:20:32.8889768Z Help         : [More Information](https://go.microsoft.com/fwlink/?LinkId=798199)
2022-01-18T10:20:32.8890058Z ==============================================================================
2022-01-18T10:20:33.6973573Z ##[debug]Processed: ##vso[plugininternal.updaterepositorypath alias=__designer_repo;]D:\a\1\s
2022-01-18T10:20:33.7003776Z ##[debug]Repository requires to be placed at 'D:\a\1\s', current location is 'D:\a\1\s'
2022-01-18T10:20:33.7107310Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\.NETFramework', value name 'InstallRoot': 'C:\Windows\Microsoft.NET\Framework64\'
2022-01-18T10:20:33.7108823Z ##[debug]Key name 'SOFTWARE\Microsoft\NET Framework Setup\NDP' contains sub keys:
2022-01-18T10:20:33.7109405Z ##[debug] 'CDF'
2022-01-18T10:20:33.7109896Z ##[debug] 'v2.0.50727'
2022-01-18T10:20:33.7110421Z ##[debug] 'v3.0'
2022-01-18T10:20:33.7110889Z ##[debug] 'v3.5'
2022-01-18T10:20:33.7116892Z ##[debug] 'v4'
2022-01-18T10:20:33.7117397Z ##[debug] 'v4.0'
2022-01-18T10:20:33.7118569Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v2.0.50727', value name 'Version': '2.0.50727.4927'
2022-01-18T10:20:33.7119496Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v2.0.50727', value name 'Install': '1'
2022-01-18T10:20:33.7120292Z ##[debug]Testing directory: 'C:\Windows\Microsoft.NET\Framework64\v2.0.50727'
2022-01-18T10:20:33.7120987Z ##[debug]Found version: 2.0.50727
2022-01-18T10:20:33.7121738Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v3.0', value name 'Version': '3.0.30729.4926'
2022-01-18T10:20:33.7122609Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v3.0', value name 'Install': '1'
2022-01-18T10:20:33.7123394Z ##[debug]Testing directory: 'C:\Windows\Microsoft.NET\Framework64\v3.0'
2022-01-18T10:20:33.7124061Z ##[debug]Found version: 3.0
2022-01-18T10:20:33.7124810Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v3.5', value name 'Version': '3.5.30729.4926'
2022-01-18T10:20:33.7126395Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v3.5', value name 'Install': '1'
2022-01-18T10:20:33.7127477Z ##[debug]Testing directory: 'C:\Windows\Microsoft.NET\Framework64\v3.5'
2022-01-18T10:20:33.7128331Z ##[debug]Found version: 3.5
2022-01-18T10:20:33.7129043Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4', value name 'Version' is null.
2022-01-18T10:20:33.7129832Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4', value name '' is null.
2022-01-18T10:20:33.7130604Z ##[debug]Key name 'SOFTWARE\Microsoft\NET Framework Setup\NDP\v4' contains sub keys:
2022-01-18T10:20:33.7131272Z ##[debug] 'Client'
2022-01-18T10:20:33.7131851Z ##[debug] 'Full'
2022-01-18T10:20:33.7132565Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Client', value name 'Version': '4.8.03761'
2022-01-18T10:20:33.7133524Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Client', value name 'Install': '1'
2022-01-18T10:20:33.7136767Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Client', value name 'InstallPath': 'C:\Windows\Microsoft.NET\Framework64\v4.0.30319\'
2022-01-18T10:20:33.7149335Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Client', value name 'Release': '528049'
2022-01-18T10:20:33.7150063Z ##[debug]Type is System.Int32
2022-01-18T10:20:33.7150592Z ##[debug]Interpreted version: 4.7.0
2022-01-18T10:20:33.7151252Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Full', value name 'Version': '4.8.03761'
2022-01-18T10:20:33.7152891Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Full', value name 'Install': '1'
2022-01-18T10:20:33.7153737Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Full', value name 'InstallPath': 'C:\Windows\Microsoft.NET\Framework64\v4.0.30319\'
2022-01-18T10:20:33.7154590Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Full', value name 'Release': '528049'
2022-01-18T10:20:33.7155230Z ##[debug]Type is System.Int32
2022-01-18T10:20:33.7155892Z ##[debug]Interpreted version: 4.7.0
2022-01-18T10:20:33.7156502Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4.0', value name 'Version' is null.
2022-01-18T10:20:33.7157230Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4.0', value name '': 'deprecated'
2022-01-18T10:20:33.7157863Z ##[debug]Found 5 versions:
2022-01-18T10:20:33.7158361Z ##[debug] 2.0.50727
2022-01-18T10:20:33.7158857Z ##[debug] 3.0
2022-01-18T10:20:33.7159307Z ##[debug] 3.5
2022-01-18T10:20:33.7159781Z ##[debug] 4.7.0
2022-01-18T10:20:33.7160247Z ##[debug] 4.7.0
2022-01-18T10:20:33.7160780Z ##[debug]Testing for min NET Framework version: '4.6'
2022-01-18T10:20:33.7161300Z Prepending Path environment variable with directory containing 'tf.exe'.
2022-01-18T10:20:33.7167626Z ##[debug]Processed: ##vso[task.prependpath]C:\agents\2.196.2\externals\tf
2022-01-18T10:20:33.7173566Z ##[debug]PATH: 'C:\agents\2.196.2\externals\tf;C:\Program Files\MongoDB\Server\5.0\bin;C:\aliyun-cli;C:\vcpkg;C:\cf-cli;C:\Program Files (x86)\NSIS\;C:\tools\zstd;C:\Program Files\Mercurial\;C:\hostedtoolcache\windows\stack\2.7.3\x64;C:\cabal\bin;C:\\ghcup\bin;C:\tools\ghc-9.2.1\bin;C:\Program Files\dotnet;C:\mysql\bin;C:\Program Files\R\R-4.1.2\bin\x64;C:\SeleniumWebDrivers\GeckoDriver;C:\Program Files (x86)\sbt\bin;C:\Program Files (x86)\GitHub CLI;C:\Program Files\Git\bin;C:\Program Files (x86)\pipx_bin;C:\hostedtoolcache\windows\go\1.15.15\x64\bin;C:\hostedtoolcache\windows\Python\3.7.9\x64\Scripts;C:\hostedtoolcache\windows\Python\3.7.9\x64;C:\hostedtoolcache\windows\Ruby\2.5.9\x64\bin;C:\tools\kotlinc\bin;C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\8.0.312-7\x64\bin;C:\npm\prefix;C:\Program Files (x86)\Microsoft SDKs\Azure\CLI2\wbin;C:\ProgramData\kind;C:\Program Files\Eclipse Foundation\jdk-8.0.302.8-hotspot\bin;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\Windows\System32\OpenSSH\;C:\ProgramData\Chocolatey\bin;C:\Program Files\Docker;C:\Program Files\PowerShell\7\;C:\Program Files\Microsoft\Web Platform Installer\;C:\Program Files\dotnet\;C:\Program Files\Microsoft SQL Server\130\Tools\Binn\;C:\Program Files\Microsoft SQL Server\Client SDK\ODBC\170\Tools\Binn\;C:\Program Files (x86)\Windows Kits\10\Windows Performance Toolkit\;C:\Program Files (x86)\Microsoft SQL Server\110\DTS\Binn\;C:\Program Files (x86)\Microsoft SQL Server\120\DTS\Binn\;C:\Program Files (x86)\Microsoft SQL Server\130\DTS\Binn\;C:\Program Files (x86)\Microsoft SQL Server\140\DTS\Binn\;C:\Program Files (x86)\Microsoft SQL Server\150\DTS\Binn\;C:\Program Files\nodejs\;C:\Program Files\OpenSSL\bin;C:\Strawberry\c\bin;C:\Strawberry\perl\site\bin;C:\Strawberry\perl\bin;C:\ProgramData\chocolatey\lib\pulumi\tools\Pulumi\bin;C:\Program Files\TortoiseSVN\bin;C:\Program Files\CMake\bin;C:\ProgramData\chocolatey\lib\maven\apache-maven-3.8.4\bin;C:\Program Files\Microsoft Service Fabric\bin\Fabric\Fabric.Code;C:\Program Files\Microsoft SDKs\Service Fabric\Tools\ServiceFabricLocalClusterManager;C:\Program Files\Git\cmd;C:\Program Files\Git\mingw64\bin;C:\Program Files\Git\usr\bin;c:\tools\php;C:\Program Files (x86)\sbt\bin;C:\SeleniumWebDrivers\ChromeDriver\;C:\SeleniumWebDrivers\EdgeDriver\;C:\Program Files\Amazon\AWSCLIV2\;C:\Program Files\Amazon\SessionManagerPlugin\bin\;C:\Program Files\Amazon\AWSSAMCLI\bin\;C:\Program Files (x86)\Google\Cloud SDK\google-cloud-sdk\bin;C:\Program Files (x86)\Microsoft BizTalk Server\;C:\Program Files\LLVM\bin;C:\Users\VssAdministrator\.dotnet\tools;C:\Users\VssAdministrator\.cargo\bin;C:\Users\VssAdministrator\AppData\Local\Microsoft\WindowsApps'
2022-01-18T10:20:33.7178321Z Setting environment variable TFVC_BUILDAGENT_POLICYPATH
2022-01-18T10:20:33.7186191Z ##[debug]Processed: ##vso[task.setvariable variable=TFVC_BUILDAGENT_POLICYPATH;issecret=False;]C:\agents\2.196.2\externals\tf\Microsoft.TeamFoundation.VersionControl.Controls.dll
2022-01-18T10:20:33.7186866Z Querying workspace information.
2022-01-18T10:20:33.7189305Z ##[debug]tf vc workspaces /format:xml /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:33.7246422Z ##[debug]Starting process:
2022-01-18T10:20:33.7247309Z ##[debug]  File name: 'tf'
2022-01-18T10:20:33.7250736Z ##[debug]  Arguments: 'vc workspaces /format:xml /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:33.7251586Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:33.7252258Z ##[debug]  Require exit code zero: 'True'
2022-01-18T10:20:33.7254244Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:33.7255010Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:33.7255786Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:33.7256421Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:33.7257095Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:33.7259174Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:33.7365153Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:33.7475483Z ##[debug]Process started with process id 5588, waiting for process exit.
2022-01-18T10:20:45.9691556Z ##[debug]<Workspaces />
2022-01-18T10:20:46.1032907Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:46.1088588Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:46.1112602Z ##[debug]Exited process 5588 with exit code 0
2022-01-18T10:20:46.1132051Z ##[debug]Finished process 5588 with exit code 0, and elapsed time 00:00:12.3847492.
2022-01-18T10:20:46.1828337Z ##[debug]Processed: ##vso[task.setvariable variable=build.repository.tfvc.workspace;issecret=False;]ws_1_76
2022-01-18T10:20:46.2001175Z ##[debug]Sources directory does not exist or is empty.
2022-01-18T10:20:46.2041996Z ##[debug]tf vc workspaces ws_1_76 /computer:* /format:xml /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:46.2043363Z ##[debug]Starting process:
2022-01-18T10:20:46.2043996Z ##[debug]  File name: 'tf'
2022-01-18T10:20:46.2046139Z ##[debug]  Arguments: 'vc workspaces ws_1_76 /computer:* /format:xml /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:46.2046943Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:46.2047577Z ##[debug]  Require exit code zero: 'True'
2022-01-18T10:20:46.2048273Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:46.2048985Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:46.2049622Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:46.2050242Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:46.2050868Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:46.2051493Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:46.2066602Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:46.2067691Z ##[debug]Process started with process id 2988, waiting for process exit.
2022-01-18T10:20:48.7665623Z ##[debug]<Workspaces>
2022-01-18T10:20:48.7733971Z ##[debug]  <Workspace computer="fv-az30-43" islocal="true" name="ws_1_76" ownerdisp="Project Collection Build Service (REDACTED)" ownerid="ab21bc76-eb45-4c55-bc13-70ab5137d5ad:Build:03bfd66a-1ced-4a3f-b479-186dc2c894f0" ownertype="Microsoft.TeamFoundation.ServiceIdentity" owner="c75ebe8d-c40f-466b-8698-55694170789e" owneruniq="c75ebe8d-c40f-466b-8698-55694170789e">
2022-01-18T10:20:48.7744887Z ##[debug]    <Comment />
2022-01-18T10:20:48.7751682Z ##[debug]    <Folders>
2022-01-18T10:20:48.7767534Z ##[debug]      <WorkingFolder local="D:\a\1\s" item="$/REDACTED" />
2022-01-18T10:20:48.7791525Z ##[debug]    </Folders>
2022-01-18T10:20:48.7833251Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:48.7879068Z ##[debug]    <LastAccessDate>2022-01-18T09:55:20.89+00:00</LastAccessDate>
2022-01-18T10:20:48.7920448Z ##[debug]    <OwnerAliases>
2022-01-18T10:20:48.7922714Z ##[debug]      <string>Build\03bfd66a-1ced-4a3f-b479-186dc2c894f0</string>
2022-01-18T10:20:48.7923897Z ##[debug]      <string>03bfd66a-1ced-4a3f-b479-186dc2c894f0</string>
2022-01-18T10:20:48.7925161Z ##[debug]      <string>Project Collection Build Service (REDACTED)</string>
2022-01-18T10:20:48.7926275Z ##[debug]Exited process 2988 with exit code 0
2022-01-18T10:20:48.7944702Z ##[debug]    </OwnerAliases>
2022-01-18T10:20:48.7945758Z ##[debug]  </Workspace>
2022-01-18T10:20:48.7946871Z ##[debug]</Workspaces>
2022-01-18T10:20:48.7947576Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:48.7948375Z ##[debug]Finished process 2988 with exit code 0, and elapsed time 00:00:02.5887046.
2022-01-18T10:20:48.8023259Z ##[command]tf vc workspace /delete ws_1_76%3Bc75ebe8d-c40f-466b-8698-55694170789e /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:48.8024571Z ##[debug]Starting process:
2022-01-18T10:20:48.8025211Z ##[debug]  File name: 'tf'
2022-01-18T10:20:48.8027296Z ##[debug]  Arguments: 'vc workspace /delete ws_1_76;c75ebe8d-c40f-466b-8698-55694170789e /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:48.8028095Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:48.8028713Z ##[debug]  Require exit code zero: 'True'
2022-01-18T10:20:48.8029370Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:48.8030025Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:48.8030653Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:48.8031261Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:48.8031880Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:48.8032484Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:48.8063664Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:48.8065208Z ##[debug]Process started with process id 5824, waiting for process exit.
2022-01-18T10:20:49.6887942Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:49.6904402Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:49.6946753Z ##[debug]Exited process 5824 with exit code 0
2022-01-18T10:20:49.6949639Z ##[debug]Finished process 5824 with exit code 0, and elapsed time 00:00:00.8910131.
2022-01-18T10:20:49.6952455Z ##[debug]Deleting: 'D:\a\1\s'.
2022-01-18T10:20:49.7116698Z ##[debug]useServerWorkspace is set to : 'False'
2022-01-18T10:20:49.7120869Z ##[command]tf vc workspace /new /location:local /permission:Public ws_1_76 /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:49.7121737Z ##[debug]Starting process:
2022-01-18T10:20:49.7122338Z ##[debug]  File name: 'tf'
2022-01-18T10:20:49.7124770Z ##[debug]  Arguments: 'vc workspace /new /location:local /permission:Public ws_1_76 /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:49.7125596Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:49.7126202Z ##[debug]  Require exit code zero: 'False'
2022-01-18T10:20:49.7126865Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:49.7127521Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:49.7128156Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:49.7128772Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:49.7129386Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:49.7130934Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:49.7156493Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:49.7158008Z ##[debug]Process started with process id 1352, waiting for process exit.
2022-01-18T10:20:51.9072419Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:51.9090917Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:51.9177022Z ##[debug]Exited process 1352 with exit code 0
2022-01-18T10:20:51.9221410Z ##[debug]Finished process 1352 with exit code 0, and elapsed time 00:00:02.2010936.
2022-01-18T10:20:51.9285551Z ##[command]tf vc workfold /unmap /workspace:ws_1_76 $/ /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:51.9350142Z ##[debug]Starting process:
2022-01-18T10:20:51.9353935Z ##[debug]  File name: 'tf'
2022-01-18T10:20:51.9497291Z ##[debug]  Arguments: 'vc workfold /unmap /workspace:ws_1_76 $/ /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:51.9501721Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:51.9502972Z ##[debug]  Require exit code zero: 'False'
2022-01-18T10:20:51.9503844Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:51.9504574Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:51.9505277Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:51.9505983Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:51.9506681Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:51.9507353Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:51.9508265Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:51.9508955Z ##[debug]Process started with process id 4404, waiting for process exit.
2022-01-18T10:20:53.2305282Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:53.2336728Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:53.2384636Z ##[debug]Exited process 4404 with exit code 0
2022-01-18T10:20:53.2422682Z ##[debug]Finished process 4404 with exit code 0, and elapsed time 00:00:01.3221799.
2022-01-18T10:20:53.2425689Z ##[command]tf vc workfold /map /workspace:ws_1_76 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:53.2448412Z ##[debug]Starting process:
2022-01-18T10:20:53.2449966Z ##[debug]  File name: 'tf'
2022-01-18T10:20:53.2453039Z ##[debug]  Arguments: 'vc workfold /map /workspace:ws_1_76 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:53.2458805Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:53.2460144Z ##[debug]  Require exit code zero: 'False'
2022-01-18T10:20:53.2461037Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:53.2461832Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:53.2462818Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:53.2463543Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:53.2464287Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:53.2465031Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:53.2465879Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:53.2467692Z ##[debug]Process started with process id 4636, waiting for process exit.
2022-01-18T10:20:54.4516595Z The working folder D:\a\1\s is already in use by the workspace ws_1_75;Project Collection Build Service (REDACTED) on computer fv-az105-591.
2022-01-18T10:20:54.4617133Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:54.4637784Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:54.4684096Z ##[debug]Exited process 4636 with exit code 100
2022-01-18T10:20:54.4685124Z ##[debug]Finished process 4636 with exit code 100, and elapsed time 00:00:01.2278831.
2022-01-18T10:20:54.4685825Z Sleeping for 200 ms
2022-01-18T10:20:54.6681417Z Retrying. Attempt 2/3
2022-01-18T10:20:54.6685224Z ##[command]tf vc workfold /map /workspace:ws_1_76 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:54.6686320Z ##[debug]Starting process:
2022-01-18T10:20:54.6686992Z ##[debug]  File name: 'tf'
2022-01-18T10:20:54.6689116Z ##[debug]  Arguments: 'vc workfold /map /workspace:ws_1_76 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:54.6690010Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:54.6690688Z ##[debug]  Require exit code zero: 'False'
2022-01-18T10:20:54.6691467Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:54.6692198Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:54.6692882Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:54.6693568Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:54.6694281Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:54.6694968Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:54.6709653Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:54.6710615Z ##[debug]Process started with process id 792, waiting for process exit.
2022-01-18T10:20:55.5491845Z The working folder D:\a\1\s is already in use by the workspace ws_1_75;Project Collection Build Service (REDACTED) on computer fv-az105-591.
2022-01-18T10:20:55.5608585Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:55.5613344Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:55.5652281Z ##[debug]Exited process 792 with exit code 100
2022-01-18T10:20:55.5654348Z ##[debug]Finished process 792 with exit code 100, and elapsed time 00:00:00.8964253.
2022-01-18T10:20:55.5655114Z Sleeping for 1000 ms
2022-01-18T10:20:56.5739774Z Retrying. Attempt 3/3
2022-01-18T10:20:56.5771302Z ##[command]tf vc workfold /map /workspace:ws_1_76 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:56.5848096Z ##[debug]Starting process:
2022-01-18T10:20:56.5849257Z ##[debug]  File name: 'tf'
2022-01-18T10:20:56.5861961Z ##[debug]  Arguments: 'vc workfold /map /workspace:ws_1_76 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:56.5863513Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:56.5864494Z ##[debug]  Require exit code zero: 'True'
2022-01-18T10:20:56.5865297Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:56.5866034Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:56.5866746Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:56.5867481Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:56.5869583Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:56.5870466Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:56.5885896Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:56.5887359Z ##[debug]Process started with process id 5564, waiting for process exit.
2022-01-18T10:20:57.6029100Z The working folder D:\a\1\s is already in use by the workspace ws_1_75;Project Collection Build Service (REDACTED) on computer fv-az105-591.
2022-01-18T10:20:57.6115591Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:57.6178118Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:57.6189631Z ##[debug]Exited process 5564 with exit code 100
2022-01-18T10:20:57.6191035Z ##[debug]Finished process 5564 with exit code 100, and elapsed time 00:00:01.0432491.
2022-01-18T10:20:57.6236935Z ##[error]Exit code 100 returned from process: file name 'tf', arguments 'vc workfold /map /workspace:ws_1_76 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'.
2022-01-18T10:20:57.6249336Z ##[debug]Processed: ##vso[task.logissue type=error;]Exit code 100 returned from process: file name 'tf', arguments 'vc workfold /map /workspace:ws_1_76 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'.
2022-01-18T10:20:57.6259937Z ##[debug]Processed: ##vso[task.complete result=Failed;]
2022-01-18T10:20:57.6438215Z ##[debug]   at Microsoft.VisualStudio.Services.Agent.Util.ProcessInvoker.ExecuteAsync(String workingDirectory, String fileName, String arguments, IDictionary`2 environment, Boolean requireExitCodeZero, Encoding outputEncoding, Boolean killProcessOnCancel, InputQueue`1 redirectStandardIn, Boolean inheritConsoleHandler, Boolean keepStandardInOpen, Boolean highPriorityProcess, CancellationToken cancellationToken)
   at Agent.Plugins.Repository.TfsVCCliManager.RunCommandAsync(FormatFlags formatFlags, Boolean quiet, Boolean failOnNonZeroExitCode, String[] args)
   at Agent.Plugins.Repository.TfsVCCliManager.RunCommandAsync(FormatFlags formatFlags, Boolean quiet, Int32 retriesOnFailure, String[] args)
   at Agent.Plugins.Repository.TFCliManager.WorkfoldMapAsync(String serverPath, String localPath)
   at Agent.Plugins.Repository.TfsVCSourceProvider.GetSourceAsync(AgentTaskPluginExecutionContext executionContext, RepositoryResource repository, CancellationToken cancellationToken)
   at Agent.Plugins.Repository.CheckoutTask.RunAsync(AgentTaskPluginExecutionContext executionContext, CancellationToken token)
   at Agent.PluginHost.Program.Main(String[] args)
2022-01-18T10:20:57.6571479Z ##[section]Finishing: Checkout REDACTED@ to s
2022-01-18T10:20:57.6600662Z ##[debug]Evaluating condition for step: 'Command Line Script'
2022-01-18T10:20:57.6602541Z ##[debug]Evaluating: succeeded()
2022-01-18T10:20:57.6602972Z ##[debug]Evaluating succeeded:
2022-01-18T10:20:57.6604091Z ##[debug]=> False
2022-01-18T10:20:57.6604576Z ##[debug]Result: False
2022-01-18T10:20:57.6812517Z ##[debug]Evaluating condition for step: 'Checkout REDACTED@ to s'
2022-01-18T10:20:57.6814996Z ##[debug]Evaluating: AlwaysNode()
2022-01-18T10:20:57.6815255Z ##[debug]Evaluating AlwaysNode:
2022-01-18T10:20:57.6817028Z ##[debug]=> True
2022-01-18T10:20:57.6817729Z ##[debug]Result: True
2022-01-18T10:20:57.6818282Z ##[section]Starting: Checkout REDACTED@ to s
2022-01-18T10:20:57.6968265Z ==============================================================================
2022-01-18T10:20:57.6968507Z Task         : Get sources
2022-01-18T10:20:57.6968765Z Description  : Get sources from a repository. Supports Git, TfsVC, and SVN repositories.
2022-01-18T10:20:57.6968969Z Version      : 1.0.0
2022-01-18T10:20:57.6969124Z Author       : Microsoft
2022-01-18T10:20:57.6969369Z Help         : [More Information](https://go.microsoft.com/fwlink/?LinkId=798199)
2022-01-18T10:20:57.6969643Z ==============================================================================
2022-01-18T10:20:58.0981423Z ##[section]Finishing: Checkout REDACTED@ to s
2022-01-18T10:20:58.1669302Z ##[section]Starting: Finalize Job
2022-01-18T10:20:58.1704607Z Cleaning up task key
2022-01-18T10:20:58.1706628Z Start cleaning up orphan processes.
2022-01-18T10:20:58.1727080Z ##[section]Finishing: Finalize Job
2022-01-18T10:20:58.1779807Z ##[debug]Starting diagnostic file upload.
2022-01-18T10:20:58.1780109Z ##[debug]Setting up diagnostic log folders.
2022-01-18T10:20:58.1783739Z ##[debug]Creating diagnostic log files folder.
2022-01-18T10:20:58.1784737Z ##[debug]Creating diagnostic log environment file.
2022-01-18T10:20:58.5883477Z ##[debug]Creating capabilities file.
2022-01-18T10:20:58.5925653Z ##[debug]Copying 1 worker diag logs.
2022-01-18T10:20:58.5974446Z ##[debug]Copying 1 agent diag logs.
2022-01-18T10:20:58.5979129Z ##[debug]Zipping diagnostic files.
2022-01-18T10:20:58.6028167Z ##[debug]Uploading diagnostic metadata file.
2022-01-18T10:20:58.6106741Z ##[debug]Diagnostic file upload complete.
2022-01-18T10:20:58.6154865Z ##[section]Finishing: Agent job 1 15
frankvaneykelen-work commented 2 years ago

Agent job 1 19

2022-01-18T10:20:31.3060839Z ##[section]Starting: Agent job 1 19
2022-01-18T10:20:31.3115338Z ##[debug]Cleaning agent temp folder: D:\a\_temp
2022-01-18T10:20:31.3153755Z ##[debug]Skipping overwrite %TEMP% environment variable
2022-01-18T10:20:31.5037608Z ##[section]Starting: Initialize job
2022-01-18T10:20:31.5039064Z Agent name: 'Azure Pipelines 12'
2022-01-18T10:20:31.5039477Z Agent machine name: 'fv-az182-789'
2022-01-18T10:20:31.5039694Z Current agent version: '2.196.2'
2022-01-18T10:20:31.5083152Z ##[group]Operating System
2022-01-18T10:20:31.5083391Z Microsoft Windows Server 2019
2022-01-18T10:20:31.5083549Z 10.0.17763
2022-01-18T10:20:31.5083679Z Datacenter
2022-01-18T10:20:31.5083830Z ##[endgroup]
2022-01-18T10:20:31.5083985Z ##[group]Virtual Environment
2022-01-18T10:20:31.5084169Z Environment: windows-2019
2022-01-18T10:20:31.5084340Z Version: 20220110.1
2022-01-18T10:20:31.5084650Z Included Software: https://github.com/actions/virtual-environments/blob/win19/20220110.1/images/win/Windows2019-Readme.md
2022-01-18T10:20:31.5085330Z Image Release: https://github.com/actions/virtual-environments/releases/tag/win19%2F20220110.1
2022-01-18T10:20:31.5085594Z ##[endgroup]
2022-01-18T10:20:31.5085788Z ##[group]Virtual Environment Provisioner
2022-01-18T10:20:31.5085976Z 1.0.0.0-main-20211214-1
2022-01-18T10:20:31.5086122Z ##[endgroup]
2022-01-18T10:20:31.5087010Z Current image version: '20220110.1'
2022-01-18T10:20:31.5094138Z Agent running as: 'VssAdministrator'
2022-01-18T10:20:31.5132943Z ##[debug]Triggering repository: REDACTED. repository type: Tfvc
2022-01-18T10:20:31.5136724Z Prepare build directory.
2022-01-18T10:20:31.5390911Z ##[debug]Creating build directory: 'D:\a\1'
2022-01-18T10:20:31.5393216Z ##[debug]Delete existing artifacts directory: 'D:\a\1\a'
2022-01-18T10:20:31.5395921Z ##[debug]Creating artifacts directory: 'D:\a\1\a'
2022-01-18T10:20:31.5398043Z ##[debug]Delete existing test results directory: 'D:\a\1\TestResults'
2022-01-18T10:20:31.5398778Z ##[debug]Creating test results directory: 'D:\a\1\TestResults'
2022-01-18T10:20:31.5400850Z ##[debug]Creating binaries directory: 'D:\a\1\b'
2022-01-18T10:20:31.5407588Z ##[debug]Creating source directory: 'D:\a\1\s'
2022-01-18T10:20:31.5460555Z Set build variables.
2022-01-18T10:20:31.5495610Z Download all required tasks.
2022-01-18T10:20:31.5619353Z Downloading task: CmdLine (2.182.0)
2022-01-18T10:20:32.1590708Z ##[debug]Task 'CmdLine' has been downloaded into 'D:\a\_tasks\CmdLine_d9bafed4-0b18-4f58-968d-86655b4d2ce9\2.182.0'.
2022-01-18T10:20:32.2738180Z ##[debug]Task 'Command Line Script' has following condition: 'succeeded()'.
2022-01-18T10:20:32.2782877Z ##[debug]Parsing expression: <succeeded()>
2022-01-18T10:20:32.2828815Z ##[debug]succeeded
2022-01-18T10:20:32.2833052Z ##[debug](
2022-01-18T10:20:32.2835777Z ##[debug])
2022-01-18T10:20:32.2852856Z Checking job knob settings.
2022-01-18T10:20:32.2862808Z    Knob: AgentToolsDirectory = C:\hostedtoolcache\windows Source: ${AGENT_TOOLSDIRECTORY} 
2022-01-18T10:20:32.2864151Z    Knob: AgentPerflog = c:\vsts\perflog Source: ${VSTS_AGENT_PERFLOG} 
2022-01-18T10:20:32.2866061Z Finished checking job knob settings.
2022-01-18T10:20:32.3174836Z ##[debug]Log plugin 'TestResultLogPlugin' is disabled.
2022-01-18T10:20:32.3175328Z ##[debug]Log plugin 'TestFilePublisherPlugin' is disabled.
2022-01-18T10:20:32.3175785Z Start tracking orphan processes.
2022-01-18T10:20:32.3253545Z ##[section]Finishing: Initialize job
2022-01-18T10:20:32.3406258Z ##[debug]Evaluating condition for step: 'Checkout REDACTED@ to s'
2022-01-18T10:20:32.3486723Z ##[debug]Evaluating: SucceededNode()
2022-01-18T10:20:32.3494002Z ##[debug]Evaluating SucceededNode:
2022-01-18T10:20:32.3542824Z ##[debug]=> True
2022-01-18T10:20:32.3553315Z ##[debug]Result: True
2022-01-18T10:20:32.3605436Z ##[section]Starting: Checkout REDACTED@ to s
2022-01-18T10:20:32.4537314Z ==============================================================================
2022-01-18T10:20:32.4537838Z Task         : Get sources
2022-01-18T10:20:32.4540356Z Description  : Get sources from a repository. Supports Git, TfsVC, and SVN repositories.
2022-01-18T10:20:32.4540625Z Version      : 1.0.0
2022-01-18T10:20:32.4541013Z Author       : Microsoft
2022-01-18T10:20:32.4541709Z Help         : [More Information](https://go.microsoft.com/fwlink/?LinkId=798199)
2022-01-18T10:20:32.4542041Z ==============================================================================
2022-01-18T10:20:33.7886471Z ##[debug]Processed: ##vso[plugininternal.updaterepositorypath alias=__designer_repo;]D:\a\1\s
2022-01-18T10:20:33.7980878Z ##[debug]Repository requires to be placed at 'D:\a\1\s', current location is 'D:\a\1\s'
2022-01-18T10:20:33.7982201Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\.NETFramework', value name 'InstallRoot': 'C:\Windows\Microsoft.NET\Framework64\'
2022-01-18T10:20:33.7983208Z ##[debug]Key name 'SOFTWARE\Microsoft\NET Framework Setup\NDP' contains sub keys:
2022-01-18T10:20:33.7984407Z ##[debug] 'CDF'
2022-01-18T10:20:33.7985141Z ##[debug] 'v2.0.50727'
2022-01-18T10:20:33.7985827Z ##[debug] 'v3.0'
2022-01-18T10:20:33.7986509Z ##[debug] 'v3.5'
2022-01-18T10:20:33.7988378Z ##[debug] 'v4'
2022-01-18T10:20:33.7989171Z ##[debug] 'v4.0'
2022-01-18T10:20:33.7990055Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v2.0.50727', value name 'Version': '2.0.50727.4927'
2022-01-18T10:20:33.7991107Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v2.0.50727', value name 'Install': '1'
2022-01-18T10:20:33.7992027Z ##[debug]Testing directory: 'C:\Windows\Microsoft.NET\Framework64\v2.0.50727'
2022-01-18T10:20:33.7992795Z ##[debug]Found version: 2.0.50727
2022-01-18T10:20:33.7993660Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v3.0', value name 'Version': '3.0.30729.4926'
2022-01-18T10:20:33.7994812Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v3.0', value name 'Install': '1'
2022-01-18T10:20:33.7995692Z ##[debug]Testing directory: 'C:\Windows\Microsoft.NET\Framework64\v3.0'
2022-01-18T10:20:33.7996454Z ##[debug]Found version: 3.0
2022-01-18T10:20:33.7997313Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v3.5', value name 'Version': '3.5.30729.4926'
2022-01-18T10:20:33.7998441Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v3.5', value name 'Install': '1'
2022-01-18T10:20:33.7999351Z ##[debug]Testing directory: 'C:\Windows\Microsoft.NET\Framework64\v3.5'
2022-01-18T10:20:33.8000095Z ##[debug]Found version: 3.5
2022-01-18T10:20:33.8000886Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4', value name 'Version' is null.
2022-01-18T10:20:33.8001756Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4', value name '' is null.
2022-01-18T10:20:33.8002615Z ##[debug]Key name 'SOFTWARE\Microsoft\NET Framework Setup\NDP\v4' contains sub keys:
2022-01-18T10:20:33.8003384Z ##[debug] 'Client'
2022-01-18T10:20:33.8004110Z ##[debug] 'Full'
2022-01-18T10:20:33.8004996Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Client', value name 'Version': '4.8.03761'
2022-01-18T10:20:33.8006006Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Client', value name 'Install': '1'
2022-01-18T10:20:33.8008988Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Client', value name 'InstallPath': 'C:\Windows\Microsoft.NET\Framework64\v4.0.30319\'
2022-01-18T10:20:33.8010348Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Client', value name 'Release': '528049'
2022-01-18T10:20:33.8011204Z ##[debug]Type is System.Int32
2022-01-18T10:20:33.8011900Z ##[debug]Interpreted version: 4.7.0
2022-01-18T10:20:33.8012748Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Full', value name 'Version': '4.8.03761'
2022-01-18T10:20:33.8014460Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Full', value name 'Install': '1'
2022-01-18T10:20:33.8015473Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Full', value name 'InstallPath': 'C:\Windows\Microsoft.NET\Framework64\v4.0.30319\'
2022-01-18T10:20:33.8016497Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4\Full', value name 'Release': '528049'
2022-01-18T10:20:33.8017243Z ##[debug]Type is System.Int32
2022-01-18T10:20:33.8017835Z ##[debug]Interpreted version: 4.7.0
2022-01-18T10:20:33.8021415Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4.0', value name 'Version' is null.
2022-01-18T10:20:33.8022424Z ##[debug]Key name 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\NET Framework Setup\NDP\v4.0', value name '': 'deprecated'
2022-01-18T10:20:33.8023252Z ##[debug]Found 5 versions:
2022-01-18T10:20:33.8024047Z ##[debug] 2.0.50727
2022-01-18T10:20:33.8024727Z ##[debug] 3.0
2022-01-18T10:20:33.8025360Z ##[debug] 3.5
2022-01-18T10:20:33.8025988Z ##[debug] 4.7.0
2022-01-18T10:20:33.8026602Z ##[debug] 4.7.0
2022-01-18T10:20:33.8027305Z ##[debug]Testing for min NET Framework version: '4.6'
2022-01-18T10:20:33.8027908Z Prepending Path environment variable with directory containing 'tf.exe'.
2022-01-18T10:20:33.8034983Z ##[debug]Processed: ##vso[task.prependpath]C:\agents\2.196.2\externals\tf
2022-01-18T10:20:33.8041718Z ##[debug]PATH: 'C:\agents\2.196.2\externals\tf;C:\Program Files\MongoDB\Server\5.0\bin;C:\aliyun-cli;C:\vcpkg;C:\cf-cli;C:\Program Files (x86)\NSIS\;C:\tools\zstd;C:\Program Files\Mercurial\;C:\hostedtoolcache\windows\stack\2.7.3\x64;C:\cabal\bin;C:\\ghcup\bin;C:\tools\ghc-9.2.1\bin;C:\Program Files\dotnet;C:\mysql\bin;C:\Program Files\R\R-4.1.2\bin\x64;C:\SeleniumWebDrivers\GeckoDriver;C:\Program Files (x86)\sbt\bin;C:\Program Files (x86)\GitHub CLI;C:\Program Files\Git\bin;C:\Program Files (x86)\pipx_bin;C:\hostedtoolcache\windows\go\1.15.15\x64\bin;C:\hostedtoolcache\windows\Python\3.7.9\x64\Scripts;C:\hostedtoolcache\windows\Python\3.7.9\x64;C:\hostedtoolcache\windows\Ruby\2.5.9\x64\bin;C:\tools\kotlinc\bin;C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\8.0.312-7\x64\bin;C:\npm\prefix;C:\Program Files (x86)\Microsoft SDKs\Azure\CLI2\wbin;C:\ProgramData\kind;C:\Program Files\Eclipse Foundation\jdk-8.0.302.8-hotspot\bin;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\Windows\System32\OpenSSH\;C:\ProgramData\Chocolatey\bin;C:\Program Files\Docker;C:\Program Files\PowerShell\7\;C:\Program Files\Microsoft\Web Platform Installer\;C:\Program Files\dotnet\;C:\Program Files\Microsoft SQL Server\130\Tools\Binn\;C:\Program Files\Microsoft SQL Server\Client SDK\ODBC\170\Tools\Binn\;C:\Program Files (x86)\Windows Kits\10\Windows Performance Toolkit\;C:\Program Files (x86)\Microsoft SQL Server\110\DTS\Binn\;C:\Program Files (x86)\Microsoft SQL Server\120\DTS\Binn\;C:\Program Files (x86)\Microsoft SQL Server\130\DTS\Binn\;C:\Program Files (x86)\Microsoft SQL Server\140\DTS\Binn\;C:\Program Files (x86)\Microsoft SQL Server\150\DTS\Binn\;C:\Program Files\nodejs\;C:\Program Files\OpenSSL\bin;C:\Strawberry\c\bin;C:\Strawberry\perl\site\bin;C:\Strawberry\perl\bin;C:\ProgramData\chocolatey\lib\pulumi\tools\Pulumi\bin;C:\Program Files\TortoiseSVN\bin;C:\Program Files\CMake\bin;C:\ProgramData\chocolatey\lib\maven\apache-maven-3.8.4\bin;C:\Program Files\Microsoft Service Fabric\bin\Fabric\Fabric.Code;C:\Program Files\Microsoft SDKs\Service Fabric\Tools\ServiceFabricLocalClusterManager;C:\Program Files\Git\cmd;C:\Program Files\Git\mingw64\bin;C:\Program Files\Git\usr\bin;c:\tools\php;C:\Program Files (x86)\sbt\bin;C:\SeleniumWebDrivers\ChromeDriver\;C:\SeleniumWebDrivers\EdgeDriver\;C:\Program Files\Amazon\AWSCLIV2\;C:\Program Files\Amazon\SessionManagerPlugin\bin\;C:\Program Files\Amazon\AWSSAMCLI\bin\;C:\Program Files (x86)\Google\Cloud SDK\google-cloud-sdk\bin;C:\Program Files (x86)\Microsoft BizTalk Server\;C:\Program Files\LLVM\bin;C:\Users\VssAdministrator\.dotnet\tools;C:\Users\VssAdministrator\.cargo\bin;C:\Users\VssAdministrator\AppData\Local\Microsoft\WindowsApps'
2022-01-18T10:20:33.8058200Z Setting environment variable TFVC_BUILDAGENT_POLICYPATH
2022-01-18T10:20:33.8069313Z ##[debug]Processed: ##vso[task.setvariable variable=TFVC_BUILDAGENT_POLICYPATH;issecret=False;]C:\agents\2.196.2\externals\tf\Microsoft.TeamFoundation.VersionControl.Controls.dll
2022-01-18T10:20:33.8070391Z Querying workspace information.
2022-01-18T10:20:33.8073090Z ##[debug]tf vc workspaces /format:xml /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:33.8141778Z ##[debug]Starting process:
2022-01-18T10:20:34.7773274Z ##[debug]  File name: 'tf'
2022-01-18T10:20:34.7777912Z ##[debug]  Arguments: 'vc workspaces /format:xml /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:34.7778994Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:34.7779881Z ##[debug]  Require exit code zero: 'True'
2022-01-18T10:20:34.7780900Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:34.7781862Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:34.7782708Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:34.7784901Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:34.7785570Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:34.7786208Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:34.7786824Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:34.7787471Z ##[debug]Process started with process id 2332, waiting for process exit.
2022-01-18T10:20:45.8095162Z ##[debug]<Workspaces />
2022-01-18T10:20:46.0009350Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:46.0062026Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:46.0122515Z ##[debug]Exited process 2332 with exit code 0
2022-01-18T10:20:46.0125963Z ##[debug]Finished process 2332 with exit code 0, and elapsed time 00:00:12.1956581.
2022-01-18T10:20:46.1162314Z ##[debug]Processed: ##vso[task.setvariable variable=build.repository.tfvc.workspace;issecret=False;]ws_1_202
2022-01-18T10:20:46.1339976Z ##[debug]Sources directory does not exist or is empty.
2022-01-18T10:20:46.1384033Z ##[debug]tf vc workspaces ws_1_202 /computer:* /format:xml /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:46.1401653Z ##[debug]Starting process:
2022-01-18T10:20:46.1442331Z ##[debug]  File name: 'tf'
2022-01-18T10:20:46.1448311Z ##[debug]  Arguments: 'vc workspaces ws_1_202 /computer:* /format:xml /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:46.1513108Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:46.1542030Z ##[debug]  Require exit code zero: 'True'
2022-01-18T10:20:46.1575051Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:46.1579535Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:46.1581510Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:46.1700829Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:46.1710888Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:46.1713724Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:46.1716294Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:46.1717377Z ##[debug]Process started with process id 2180, waiting for process exit.
2022-01-18T10:20:47.9010031Z ##[debug]<Workspaces>
2022-01-18T10:20:47.9058286Z ##[debug]  <Workspace computer="WIN-104DNQMGKG6" islocal="true" name="ws_1_202" ownerdisp="Project Collection Build Service (REDACTED)" ownerid="ab21bc76-eb45-4c55-bc13-70ab5137d5ad:Build:03bfd66a-1ced-4a3f-b479-186dc2c894f0" ownertype="Microsoft.TeamFoundation.ServiceIdentity" owner="c75ebe8d-c40f-466b-8698-55694170789e" owneruniq="c75ebe8d-c40f-466b-8698-55694170789e">
2022-01-18T10:20:47.9072266Z ##[debug]    <Comment />
2022-01-18T10:20:47.9078171Z ##[debug]    <Folders>
2022-01-18T10:20:47.9081019Z ##[debug]      <WorkingFolder local="D:\a\1\s" item="$/REDACTED" />
2022-01-18T10:20:47.9115982Z ##[debug]    </Folders>
2022-01-18T10:20:47.9162914Z ##[debug]    <LastAccessDate>2022-01-18T09:55:34.493+00:00</LastAccessDate>
2022-01-18T10:20:47.9176865Z ##[debug]    <OwnerAliases>
2022-01-18T10:20:47.9211360Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:47.9233132Z ##[debug]      <string>Build\03bfd66a-1ced-4a3f-b479-186dc2c894f0</string>
2022-01-18T10:20:47.9236296Z ##[debug]      <string>03bfd66a-1ced-4a3f-b479-186dc2c894f0</string>
2022-01-18T10:20:47.9260870Z ##[debug]      <string>Project Collection Build Service (REDACTED)</string>
2022-01-18T10:20:47.9262932Z ##[debug]    </OwnerAliases>
2022-01-18T10:20:47.9387966Z ##[debug]  </Workspace>
2022-01-18T10:20:47.9519434Z ##[debug]Exited process 2180 with exit code 0
2022-01-18T10:20:47.9520804Z ##[debug]</Workspaces>
2022-01-18T10:20:47.9521854Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:47.9522959Z ##[debug]Finished process 2180 with exit code 0, and elapsed time 00:00:01.7882641.
2022-01-18T10:20:47.9526653Z ##[command]tf vc workspace /delete ws_1_202%3Bc75ebe8d-c40f-466b-8698-55694170789e /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:47.9527678Z ##[debug]Starting process:
2022-01-18T10:20:47.9528547Z ##[debug]  File name: 'tf'
2022-01-18T10:20:47.9531481Z ##[debug]  Arguments: 'vc workspace /delete ws_1_202;c75ebe8d-c40f-466b-8698-55694170789e /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:47.9532623Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:47.9533531Z ##[debug]  Require exit code zero: 'True'
2022-01-18T10:20:47.9534523Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:47.9535953Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:47.9537277Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:47.9538212Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:47.9539153Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:47.9540075Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:47.9541384Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:47.9542347Z ##[debug]Process started with process id 3996, waiting for process exit.
2022-01-18T10:20:48.8605330Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:48.8610099Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:48.8737426Z ##[debug]Exited process 3996 with exit code 0
2022-01-18T10:20:48.8744366Z ##[debug]Finished process 3996 with exit code 0, and elapsed time 00:00:00.9297006.
2022-01-18T10:20:48.8746383Z ##[debug]Deleting: 'D:\a\1\s'.
2022-01-18T10:20:48.8854493Z ##[debug]useServerWorkspace is set to : 'False'
2022-01-18T10:20:48.8860236Z ##[command]tf vc workspace /new /location:local /permission:Public ws_1_202 /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:48.8861338Z ##[debug]Starting process:
2022-01-18T10:20:48.8862080Z ##[debug]  File name: 'tf'
2022-01-18T10:20:48.8865536Z ##[debug]  Arguments: 'vc workspace /new /location:local /permission:Public ws_1_202 /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:48.8870964Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:48.8872090Z ##[debug]  Require exit code zero: 'False'
2022-01-18T10:20:48.8873044Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:48.8873926Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:48.8874847Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:48.8875653Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:48.8877345Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:48.8892527Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:48.8906289Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:48.8914381Z ##[debug]Process started with process id 512, waiting for process exit.
2022-01-18T10:20:51.1639434Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:51.1646298Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:51.1728225Z ##[debug]Exited process 512 with exit code 0
2022-01-18T10:20:51.1729352Z ##[debug]Finished process 512 with exit code 0, and elapsed time 00:00:02.2866187.
2022-01-18T10:20:51.1749422Z ##[command]tf vc workfold /unmap /workspace:ws_1_202 $/ /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:51.1752313Z ##[debug]Starting process:
2022-01-18T10:20:51.1753231Z ##[debug]  File name: 'tf'
2022-01-18T10:20:51.1760407Z ##[debug]  Arguments: 'vc workfold /unmap /workspace:ws_1_202 $/ /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:51.1761710Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:51.1764813Z ##[debug]  Require exit code zero: 'False'
2022-01-18T10:20:51.1769258Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:51.1781121Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:51.1782750Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:51.1783975Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:51.1784886Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:51.1785775Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:51.1792303Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:51.1793276Z ##[debug]Process started with process id 604, waiting for process exit.
2022-01-18T10:20:52.3153403Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:52.3157253Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:52.3223785Z ##[debug]Exited process 604 with exit code 0
2022-01-18T10:20:52.3225927Z ##[debug]Finished process 604 with exit code 0, and elapsed time 00:00:01.1468540.
2022-01-18T10:20:52.3249912Z ##[command]tf vc workfold /map /workspace:ws_1_202 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:52.3251847Z ##[debug]Starting process:
2022-01-18T10:20:52.3253213Z ##[debug]  File name: 'tf'
2022-01-18T10:20:52.3258531Z ##[debug]  Arguments: 'vc workfold /map /workspace:ws_1_202 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:52.3260634Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:52.3262174Z ##[debug]  Require exit code zero: 'False'
2022-01-18T10:20:52.3263684Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:52.3276263Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:52.3277995Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:52.3289905Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:52.3290986Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:52.3292372Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:52.3293700Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:52.3295501Z ##[debug]Process started with process id 6880, waiting for process exit.
2022-01-18T10:20:53.2816576Z HTTP code 503: Service Unavailable
2022-01-18T10:20:53.2911326Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:53.2937274Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:53.2996068Z ##[debug]Exited process 6880 with exit code 100
2022-01-18T10:20:53.2997165Z ##[debug]Finished process 6880 with exit code 100, and elapsed time 00:00:00.9747290.
2022-01-18T10:20:53.2997985Z Sleeping for 200 ms
2022-01-18T10:20:53.5151052Z Retrying. Attempt 2/3
2022-01-18T10:20:53.5185839Z ##[command]tf vc workfold /map /workspace:ws_1_202 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:53.5224220Z ##[debug]Starting process:
2022-01-18T10:20:53.5241307Z ##[debug]  File name: 'tf'
2022-01-18T10:20:53.5289252Z ##[debug]  Arguments: 'vc workfold /map /workspace:ws_1_202 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:53.5359092Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:53.5362387Z ##[debug]  Require exit code zero: 'False'
2022-01-18T10:20:53.5408176Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:53.5411596Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:53.5412523Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:53.5413404Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:53.5414285Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:53.5415193Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:53.5416284Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:53.5417189Z ##[debug]Process started with process id 4784, waiting for process exit.
2022-01-18T10:20:55.2776132Z The working folder D:\a\1\s is already in use by the workspace ws_1_204;Project Collection Build Service (REDACTED) on computer fv-az182-789.
2022-01-18T10:20:55.2777704Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:55.2778858Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:55.2779969Z ##[debug]Exited process 4784 with exit code 100
2022-01-18T10:20:55.2781269Z ##[debug]Finished process 4784 with exit code 100, and elapsed time 00:00:01.1025594.
2022-01-18T10:20:55.2782169Z Sleeping for 1000 ms
2022-01-18T10:20:55.6330918Z Retrying. Attempt 3/3
2022-01-18T10:20:55.6377591Z ##[command]tf vc workfold /map /workspace:ws_1_202 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt
2022-01-18T10:20:55.6378801Z ##[debug]Starting process:
2022-01-18T10:20:55.6379761Z ##[debug]  File name: 'tf'
2022-01-18T10:20:55.6382964Z ##[debug]  Arguments: 'vc workfold /map /workspace:ws_1_202 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'
2022-01-18T10:20:55.6384153Z ##[debug]  Working directory: 'D:\a\1\s'
2022-01-18T10:20:55.6385023Z ##[debug]  Require exit code zero: 'True'
2022-01-18T10:20:55.6385955Z ##[debug]  Encoding web name: windows-1252 ; code page: '1252'
2022-01-18T10:20:55.6386870Z ##[debug]  Force kill process on cancellation: 'False'
2022-01-18T10:20:55.6387746Z ##[debug]  Redirected STDIN: 'False'
2022-01-18T10:20:55.6388604Z ##[debug]  Persist current code page: 'False'
2022-01-18T10:20:55.6389482Z ##[debug]  Keep redirected STDIN open: 'False'
2022-01-18T10:20:55.6391081Z ##[debug]  High priority process: 'False'
2022-01-18T10:20:55.6392258Z ##[debug]OOM score adjustment is Linux-only.
2022-01-18T10:20:55.6393172Z ##[debug]Process started with process id 6140, waiting for process exit.
2022-01-18T10:20:56.6094744Z The working folder D:\a\1\s is already in use by the workspace ws_1_204;Project Collection Build Service (REDACTED) on computer fv-az182-789.
2022-01-18T10:20:56.6188461Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:56.6219462Z ##[debug]STDOUT/STDERR stream read finished.
2022-01-18T10:20:56.6324703Z ##[debug]Exited process 6140 with exit code 100
2022-01-18T10:20:56.6326956Z ##[debug]Finished process 6140 with exit code 100, and elapsed time 00:00:00.9975777.
2022-01-18T10:20:56.6452092Z ##[error]Exit code 100 returned from process: file name 'tf', arguments 'vc workfold /map /workspace:ws_1_202 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'.
2022-01-18T10:20:56.6473707Z ##[debug]Processed: ##vso[task.logissue type=error;]Exit code 100 returned from process: file name 'tf', arguments 'vc workfold /map /workspace:ws_1_202 $/REDACTED D:\a\1\s /collection:https://dev.azure.com/REDACTED/ /loginType:OAuth /login:.,*** /noprompt'.
2022-01-18T10:20:56.6484509Z ##[debug]Processed: ##vso[task.complete result=Failed;]
2022-01-18T10:20:56.6553118Z ##[debug]   at Microsoft.VisualStudio.Services.Agent.Util.ProcessInvoker.ExecuteAsync(String workingDirectory, String fileName, String arguments, IDictionary`2 environment, Boolean requireExitCodeZero, Encoding outputEncoding, Boolean killProcessOnCancel, InputQueue`1 redirectStandardIn, Boolean inheritConsoleHandler, Boolean keepStandardInOpen, Boolean highPriorityProcess, CancellationToken cancellationToken)
   at Agent.Plugins.Repository.TfsVCCliManager.RunCommandAsync(FormatFlags formatFlags, Boolean quiet, Boolean failOnNonZeroExitCode, String[] args)
   at Agent.Plugins.Repository.TfsVCCliManager.RunCommandAsync(FormatFlags formatFlags, Boolean quiet, Int32 retriesOnFailure, String[] args)
   at Agent.Plugins.Repository.TFCliManager.WorkfoldMapAsync(String serverPath, String localPath)
   at Agent.Plugins.Repository.TfsVCSourceProvider.GetSourceAsync(AgentTaskPluginExecutionContext executionContext, RepositoryResource repository, CancellationToken cancellationToken)
   at Agent.Plugins.Repository.CheckoutTask.RunAsync(AgentTaskPluginExecutionContext executionContext, CancellationToken token)
   at Agent.PluginHost.Program.Main(String[] args)
2022-01-18T10:20:56.6709532Z ##[section]Finishing: Checkout REDACTED@ to s
2022-01-18T10:20:56.6757656Z ##[debug]Evaluating condition for step: 'Command Line Script'
2022-01-18T10:20:56.6759700Z ##[debug]Evaluating: succeeded()
2022-01-18T10:20:56.6760250Z ##[debug]Evaluating succeeded:
2022-01-18T10:20:56.6761403Z ##[debug]=> False
2022-01-18T10:20:56.6762010Z ##[debug]Result: False
2022-01-18T10:20:56.6788639Z ##[debug]Evaluating condition for step: 'Checkout REDACTED@ to s'
2022-01-18T10:20:56.6790362Z ##[debug]Evaluating: AlwaysNode()
2022-01-18T10:20:56.6790728Z ##[debug]Evaluating AlwaysNode:
2022-01-18T10:20:56.6791899Z ##[debug]=> True
2022-01-18T10:20:56.6792412Z ##[debug]Result: True
2022-01-18T10:20:56.6792955Z ##[section]Starting: Checkout REDACTED@ to s
2022-01-18T10:20:56.6957838Z ==============================================================================
2022-01-18T10:20:56.6958152Z Task         : Get sources
2022-01-18T10:20:56.6958457Z Description  : Get sources from a repository. Supports Git, TfsVC, and SVN repositories.
2022-01-18T10:20:56.6958736Z Version      : 1.0.0
2022-01-18T10:20:56.6958929Z Author       : Microsoft
2022-01-18T10:20:56.6959258Z Help         : [More Information](https://go.microsoft.com/fwlink/?LinkId=798199)
2022-01-18T10:20:56.6959612Z ==============================================================================
2022-01-18T10:20:57.1022383Z ##[section]Finishing: Checkout REDACTED@ to s
2022-01-18T10:20:57.1096566Z ##[section]Starting: Finalize Job
2022-01-18T10:20:57.1134305Z Cleaning up task key
2022-01-18T10:20:57.1135962Z Start cleaning up orphan processes.
2022-01-18T10:20:57.1159630Z ##[section]Finishing: Finalize Job
2022-01-18T10:20:57.1213999Z ##[debug]Starting diagnostic file upload.
2022-01-18T10:20:57.1214292Z ##[debug]Setting up diagnostic log folders.
2022-01-18T10:20:57.1217529Z ##[debug]Creating diagnostic log files folder.
2022-01-18T10:20:57.1218616Z ##[debug]Creating diagnostic log environment file.
2022-01-18T10:20:57.5355801Z ##[debug]Creating capabilities file.
2022-01-18T10:20:57.5398494Z ##[debug]Copying 1 worker diag logs.
2022-01-18T10:20:57.5436873Z ##[debug]Copying 1 agent diag logs.
2022-01-18T10:20:57.5440739Z ##[debug]Zipping diagnostic files.
2022-01-18T10:20:57.5483475Z ##[debug]Uploading diagnostic metadata file.
2022-01-18T10:20:57.5566101Z ##[debug]Diagnostic file upload complete.
2022-01-18T10:20:57.5623108Z ##[section]Finishing: Agent job 1 19
frankvaneykelen-work commented 2 years ago

@EzzhevNikita please let me know if you need the debug logs of a succeeded run too

jessehouwing commented 2 years ago

The trick of that build is to make sure it spends some time performing the checkout. I think I checked in a folder with a couple of MB of random PDFs from my download folder.

The build itself doesn't matter. As long as the organisation is able to spin up 20 parallel jobs on the hosted pool the reproduction time is near instant.

And from the logs it's clear that all runs still create workspace ws_1_agentid instead of ws_agentid_agentid. And they check out to /a/1 instead of /a/agentid.

jessehouwing commented 2 years ago

This is the minimal repro pipeline to reproduce the issue

Screenshot_20220119-120003.png Screenshot_20220119-120007.png Screenshot_20220119-120014.png

And make sure this value is a significantly high number to increase the chances this will reproduce:

Screenshot_20220119-120112.png

For the client this is at 25.

And as you can see in the screenshots at the original report, for some reason the bug doesn't surface across all my accounts. Using the same json import seeing different results. I've been unable to explain these differences.

jessehouwing commented 2 years ago

Ship! Ship! It! 🛳️

Is this still able to ship in this cycle, say 2.198.3?

EzzhevNikita commented 2 years ago

@jessehouwing The fix for this issue will be included in the next agent release 2.199. We are planning to start it this week.

jessehouwing commented 2 years ago

What's the hold-up with 2.200? I really don't see why this issue is taking so long to get deployed. Even after multiple high-level support issues. It could have easily been merged into 2.198.x, it seems 2.199 was skipped altogether and now 2.200 has had 3 weeks to roll out since 198 but hasn't.

EzzhevNikita commented 2 years ago

@jessehouwing Deployment of agent version 2.200.0 completed, could you please check if the issue was resolved?

jessehouwing commented 2 years ago

Pinged the client.

frankvaneykelen-work commented 2 years ago

Our agents are now running on 2.200.2, and our Multi-Agent (20x) Parallelism Test build, that used to consistently fail on "The working folder D:\a\1\s is already in use" errors in some of the Checkout tasks, now completed successfully twice in a row, so I am quite confident that the issue has indeed been fixed! 🎉