search

microsoft / AL-Go

The plug-and-play DevOps solution for Business Central app development on GitHub
MIT License
278 stars 114 forks source link

Downloading BcContainerHelper fails #804

Closed AskeHolst closed 5 months ago

AskeHolst commented 10 months ago

We are seeing errors across our repos today.

It is intermittent, errors in different places, the common thing is that all are related to downloading BcContainerHelper

Example 1

Run microsoft/AL-Go-Actions/WorkflowInitialize@v4.0
  with:
    shell: pwsh
    eventId: DO0101
  env:
    workflowDepth: [2](https://github.com/prymeGlobal/prymeFunding/actions/runs/6775400303/job/18414601336#step:3:2)
    ALGoOrgSettings: {
    "enableCodeCop": true,
    "enableUICop": true,
    "UpdateGitHubGoSystemFilesSchedule": "1 1 * * SAT",
    "githubRunner": "self-hosted",
    "runs-on": "Ubuntu-Latest" 
  }
    ALGoRepoSettings: 
Run $errorActionPreference = "Stop"; $ProgressPreference = "SilentlyContinue"; Set-StrictMode -Version 2.0
          _           _____          __              _____ _ _   _    _       _        _____             
    /\   | |         / ____|        / _|            / ____(_) | | |  | |     | |      |  __ \            
   /  \  | |  ______| |  __  ___   | |_ ___  _ __  | |  __ _| |_| |__| |_   _| |__    | |  | | _____   __
  / /\ \ | | |______| | |_ |/ _ \  |  _/ _ \| '__| | | |_ | | __|  __  | | | | '_ \   | |  | |/ _ \ \ / /
 / ____ \| |____    | |__| | (_) | | || (_) | |    | |__| | | |_| |  | | |_| | |_) |  | |__| |  __/\ V / 
/_/    \_\______|    \_____|\___/  |_| \___/|_|     \_____|_|\__|_|  |_|\__,_|_.__/   |_____/ \___| \_(_)
Checking AL-Go Org Settings variable (ALGoOrgSettings)
BaseFolder: /home/runner/work/prymeFunding/prymeFunding
Checking AL-Go Repo settings file in .github/AL-Go-Settings.json (type = Repo)
Checking AL-Go Workflow settings file in .github/Test Current.settings.json (type = Workflow)
Checking AL-Go Workflow settings file in .github/Test Next Major.settings.json (type = Workflow)
Checking AL-Go Workflow settings file in .github/Test Next Minor.settings.json (type = Workflow)
Checking AL-Go Project settings file in FAPYME/.AL-Go/settings.json (type = Project)
Warning: Property 'currentSchedule' should not exist in FAPYME/.AL-Go/settings.json
Checking AL-Go Project settings file in FYME/.AL-Go/settings.json (type = Project)
Warning: Property 'currentSchedule' should not exist in FYME/.AL-Go/settings.json
Downloading BcContainerHelper latest version from CDN
Error: Unexpected error when running action. Error Message: Exception calling "DownloadFile" with "2" argument(s): "The SSL connection could not be established, see inner exception.", StackTrace: at GetBcContainerHelperPath, /home/runner/work/_actions/microsoft/AL-Go-Actions/v4.0/AL-Go-Helper.ps1: line [3](https://github.com/prymeGlobal/prymeFunding/actions/runs/6775400303/job/18414601336#step:3:3)3[4](https://github.com/prymeGlobal/prymeFunding/actions/runs/6775400303/job/18414601336#step:3:4) <- at DownloadAndImportBcContainerHelper, /home/runner/work/_actions/microsoft/AL-Go-Actions/v4.0/AL-Go-Helper.ps1: line 414 <- at <ScriptBlock>, /home/runner/work/_actions/microsoft/AL-Go-Actions/v4.0/WorkflowInitialize/WorkflowInitialize.ps1: line 30 <- at <ScriptBlock>, /home/runner/work/_temp/3c1dd[5](https://github.com/prymeGlobal/prymeFunding/actions/runs/6775400303/job/18414601336#step:3:5)[6](https://github.com/prymeGlobal/prymeFunding/actions/runs/6775400303/job/18414601336#step:3:6)b-a301-4fcc-be04-8da[7](https://github.com/prymeGlobal/prymeFunding/actions/runs/6775400303/job/18414601336#step:3:7)a90[8](https://github.com/prymeGlobal/prymeFunding/actions/runs/6775400303/job/18414601336#step:3:8)[52](https://github.com/prymeGlobal/prymeFunding/actions/runs/6775400303/job/18414601336#step:3:54)35.ps1: line 4 <- at <ScriptBlock>, <No file>: line 1
Error: Process completed with exit code 1.

Example 2

Run microsoft/AL-Go-Actions/DetermineArtifactUrl@v4.0
Run $errorActionPreference = "Stop"; $ProgressPreference = "SilentlyContinue"; Set-StrictMode -Version 2.0
Downloading BcContainerHelper latest version from CDN
Error: Unexpected error when running action. Error Message: Exception calling "DownloadFile" with "2" argument(s): "The SSL connection could not be established, see inner exception.", StackTrace: at GetBcContainerHelperPath, C:\1\_work\_actions\microsoft\AL-Go-Actions\v4.0\AL-Go-Helper.ps1: line 334 <- at DownloadAndImportBcContainerHelper, C:\1\_work\_actions\microsoft\AL-Go-Actions\v4.0\AL-Go-Helper.ps1: line 414 <- at <ScriptBlock>, C:\1\_work\_actions\microsoft\AL-Go-Actions\v4.0\DetermineArtifactUrl\DetermineArtifactUrl.ps1: line 13 <- at <ScriptBlock>, C:\1\_work\_temp\4cc2745a-e81c-46c3-b6c5-8e347273d273.ps1: line 4 <- at <ScriptBlock>, <No file>: line 1
Error: Process completed with exit code 1.
freddydk commented 10 months ago

OK - strange, as it is grabbing ContainerHelper from either a CDN or a storage account (I would assume the certificate would be in place by Azure) - haven't heard this from others.

Are you using self-hosted runners? Could it be related to some of these? Could some of those be missing a restart?

I also haven't seen this on any of my nightly runs (it's morning now)

AskeHolst commented 10 months ago

It was both GitHub and self-hosted runners. But it seems to be back to normal, I did re-runs in 4 repos and they are now all successful.

ninjaniels commented 10 months ago

same

freddydk commented 10 months ago

@ninjaniels - yours also failed, but worked after re-runs?

ninjaniels commented 10 months ago

Hi,

We are currently using DevOps and ALOps service, but are looking into AL-Go and GitHub. We got similar error Unexpected error when running action. Error Message: Exception calling "DownloadFile" with "2" argument(s): "The underlying connection was closed: An unexpected error occurred on a send.". The pipeline worked after re-run.

On a side note, we have also seen a similar error randomly appear on our build pipelines in DevOps when we run AL-Validation in BcContainerHelper using ALOps.

Our DevOps pipelines are self-hosted, but GitHub are hosted.

2023-11-07T10:58:59.0211163Z Requested labels: windows-latest
2023-11-07T10:58:59.0212073Z Job defined at: Armada-Dynamics/Armada-Dynamics/.github/workflows/CICD.yaml@refs/heads/main
2023-11-07T10:58:59.0212252Z Waiting for a runner to pick up this job...
2023-11-07T10:58:59.8133744Z Job is waiting for a hosted runner to come online.
2023-11-07T10:59:08.1269334Z Job is about to start running on the hosted runner: GitHub Actions 1 (hosted)
2023-11-07T10:59:13.4969636Z Current runner version: '2.311.0'
2023-11-07T10:59:13.5006548Z ##[group]Operating System
2023-11-07T10:59:13.5007596Z Microsoft Windows Server 2022
2023-11-07T10:59:13.5008227Z 10.0.20348
2023-11-07T10:59:13.5008633Z Datacenter
2023-11-07T10:59:13.5009247Z ##[endgroup]
2023-11-07T10:59:13.5009757Z ##[group]Runner Image
2023-11-07T10:59:13.5010330Z Image: windows-2022
2023-11-07T10:59:13.5010854Z Version: 20231029.1.0
2023-11-07T10:59:13.5012148Z Included Software: https://github.com/actions/runner-images/blob/win22/20231029.1/images/win/Windows2022-Readme.md
2023-11-07T10:59:13.5013999Z Image Release: https://github.com/actions/runner-images/releases/tag/win22%2F20231029.1
2023-11-07T10:59:13.5015098Z ##[endgroup]
2023-11-07T10:59:13.5015635Z ##[group]Runner Image Provisioner
2023-11-07T10:59:13.5016285Z 2.0.312.1
2023-11-07T10:59:13.5016732Z ##[endgroup]
2023-11-07T10:59:13.5018565Z ##[group]GITHUB_TOKEN Permissions
2023-11-07T10:59:13.5021004Z Actions: read
2023-11-07T10:59:13.5021865Z Contents: read
2023-11-07T10:59:13.5022513Z Metadata: read
2023-11-07T10:59:13.5023040Z ##[endgroup]
2023-11-07T10:59:13.5027706Z Secret source: Actions
2023-11-07T10:59:13.5028585Z Prepare workflow directory
2023-11-07T10:59:13.6555442Z Prepare all required actions
2023-11-07T10:59:13.6782454Z Getting action download info
2023-11-07T10:59:14.1467870Z Download action repository 'actions/checkout@v3' (SHA:f43a0e5ff2bd294095638e18286ca9a3d1956744)
2023-11-07T10:59:14.7588300Z Download action repository 'microsoft/AL-Go-Actions@v4.0' (SHA:b1b387bea634df694cc7c53b2397d375b5499eed)
2023-11-07T10:59:15.6006236Z Complete job name: Initialization
2023-11-07T10:59:15.8652211Z ##[group]Run actions/checkout@v3
2023-11-07T10:59:15.8653153Z with:
2023-11-07T10:59:15.8653571Z   lfs: true
2023-11-07T10:59:15.8654107Z   repository: Armada-Dynamics/Armada-Dynamics
2023-11-07T10:59:15.8655150Z   token: ***
2023-11-07T10:59:15.8655603Z   ssh-strict: true
2023-11-07T10:59:15.8656064Z   persist-credentials: true
2023-11-07T10:59:15.8656653Z   clean: true
2023-11-07T10:59:15.8657138Z   sparse-checkout-cone-mode: true
2023-11-07T10:59:15.8657678Z   fetch-depth: 1
2023-11-07T10:59:15.8658116Z   fetch-tags: false
2023-11-07T10:59:15.8658701Z   submodules: false
2023-11-07T10:59:15.8659170Z   set-safe-directory: true
2023-11-07T10:59:15.8659872Z env:
2023-11-07T10:59:15.8660435Z   workflowDepth: 1
2023-11-07T10:59:15.8660973Z   ALGoOrgSettings: 
2023-11-07T10:59:15.8661515Z   ALGoRepoSettings: 
2023-11-07T10:59:15.8662048Z ##[endgroup]
2023-11-07T10:59:17.7242325Z Syncing repository: Armada-Dynamics/Armada-Dynamics
2023-11-07T10:59:17.7245032Z ##[group]Getting Git version info
2023-11-07T10:59:17.7246133Z Working directory is 'D:\a\Armada-Dynamics\Armada-Dynamics'
2023-11-07T10:59:17.9558840Z [command]"C:\Program Files\Git\bin\git.exe" version
2023-11-07T10:59:18.0881416Z git version 2.42.0.windows.2
2023-11-07T10:59:18.2579051Z [command]"C:\Program Files\Git\bin\git.exe" lfs version
2023-11-07T10:59:22.8633713Z git-lfs/3.4.0 (GitHub; windows amd64; go 1.20.6; git d06d6e9e)
2023-11-07T10:59:22.8717686Z ##[endgroup]
2023-11-07T10:59:22.8839768Z Temporarily overriding HOME='D:\a\_temp\f0085475-8bec-4c9b-a663-715b6a64a3fe' before making global git config changes
2023-11-07T10:59:22.8841428Z Adding repository directory to the temporary git global config as a safe directory
2023-11-07T10:59:22.8870033Z [command]"C:\Program Files\Git\bin\git.exe" config --global --add safe.directory D:\a\Armada-Dynamics\Armada-Dynamics
2023-11-07T10:59:22.9640981Z Deleting the contents of 'D:\a\Armada-Dynamics\Armada-Dynamics'
2023-11-07T10:59:22.9657201Z ##[group]Initializing the repository
2023-11-07T10:59:22.9675583Z [command]"C:\Program Files\Git\bin\git.exe" init D:\a\Armada-Dynamics\Armada-Dynamics
2023-11-07T10:59:23.0755632Z Initialized empty Git repository in D:/a/Armada-Dynamics/Armada-Dynamics/.git/
2023-11-07T10:59:23.0806751Z [command]"C:\Program Files\Git\bin\git.exe" remote add origin https://github.com/Armada-Dynamics/Armada-Dynamics
2023-11-07T10:59:23.1506677Z ##[endgroup]
2023-11-07T10:59:23.1507522Z ##[group]Disabling automatic garbage collection
2023-11-07T10:59:23.1519244Z [command]"C:\Program Files\Git\bin\git.exe" config --local gc.auto 0
2023-11-07T10:59:23.1929728Z ##[endgroup]
2023-11-07T10:59:23.1930510Z ##[group]Setting up auth
2023-11-07T10:59:23.1947464Z [command]"C:\Program Files\Git\bin\git.exe" config --local --name-only --get-regexp core\.sshCommand
2023-11-07T10:59:23.2378568Z [command]"C:\Program Files\Git\bin\git.exe" submodule foreach --recursive "sh -c \"git config --local --name-only --get-regexp 'core\.sshCommand' && git config --local --unset-all 'core.sshCommand' || :\""
2023-11-07T10:59:24.9028771Z [command]"C:\Program Files\Git\bin\git.exe" config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader
2023-11-07T10:59:24.9394441Z [command]"C:\Program Files\Git\bin\git.exe" submodule foreach --recursive "sh -c \"git config --local --name-only --get-regexp 'http\.https\:\/\/github\.com\/\.extraheader' && git config --local --unset-all 'http.https://github.com/.extraheader' || :\""
2023-11-07T10:59:25.5132427Z [command]"C:\Program Files\Git\bin\git.exe" config --local http.https://github.com/.extraheader "AUTHORIZATION: basic ***"
2023-11-07T10:59:25.5558955Z ##[endgroup]
2023-11-07T10:59:25.5570166Z [command]"C:\Program Files\Git\bin\git.exe" lfs install --local
2023-11-07T10:59:26.4607484Z Updated Git hooks.
2023-11-07T10:59:26.4608255Z Git LFS initialized.
2023-11-07T10:59:26.4677921Z ##[group]Fetching the repository
2023-11-07T10:59:26.4701610Z [command]"C:\Program Files\Git\bin\git.exe" -c protocol.version=2 fetch --no-tags --prune --progress --no-recurse-submodules --depth=1 origin +1e67760267eb602b8a4cfc4618271b80201c2b66:refs/remotes/origin/main
2023-11-07T10:59:29.1717123Z remote: Enumerating objects: 34, done.        
2023-11-07T10:59:29.1717782Z remote: Counting objects:   2% (1/34)        
2023-11-07T10:59:29.1718329Z remote: Counting objects:   5% (2/34)        
2023-11-07T10:59:29.1719062Z remote: Counting objects:   8% (3/34)        
2023-11-07T10:59:29.1719632Z remote: Counting objects:  11% (4/34)        
2023-11-07T10:59:29.1720176Z remote: Counting objects:  14% (5/34)        
2023-11-07T10:59:29.1721191Z remote: Counting objects:  17% (6/34)        
2023-11-07T10:59:29.1721765Z remote: Counting objects:  20% (7/34)        
2023-11-07T10:59:29.1722305Z remote: Counting objects:  23% (8/34)        
2023-11-07T10:59:29.1722861Z remote: Counting objects:  26% (9/34)        
2023-11-07T10:59:29.1723431Z remote: Counting objects:  29% (10/34)        
2023-11-07T10:59:29.1724026Z remote: Counting objects:  32% (11/34)        
2023-11-07T10:59:29.1724609Z remote: Counting objects:  35% (12/34)        
2023-11-07T10:59:29.1725296Z remote: Counting objects:  38% (13/34)        
2023-11-07T10:59:29.1725826Z remote: Counting objects:  41% (14/34)        
2023-11-07T10:59:29.1726375Z remote: Counting objects:  44% (15/34)        
2023-11-07T10:59:29.1726933Z remote: Counting objects:  47% (16/34)        
2023-11-07T10:59:29.1727466Z remote: Counting objects:  50% (17/34)        
2023-11-07T10:59:29.1728010Z remote: Counting objects:  52% (18/34)        
2023-11-07T10:59:29.1728557Z remote: Counting objects:  55% (19/34)        
2023-11-07T10:59:29.1729111Z remote: Counting objects:  58% (20/34)        
2023-11-07T10:59:29.1729760Z remote: Counting objects:  61% (21/34)        
2023-11-07T10:59:29.1730327Z remote: Counting objects:  64% (22/34)        
2023-11-07T10:59:29.1730894Z remote: Counting objects:  67% (23/34)        
2023-11-07T10:59:29.1731496Z remote: Counting objects:  70% (24/34)        
2023-11-07T10:59:29.1732073Z remote: Counting objects:  73% (25/34)        
2023-11-07T10:59:29.1732640Z remote: Counting objects:  76% (26/34)        
2023-11-07T10:59:29.1733188Z remote: Counting objects:  79% (27/34)        
2023-11-07T10:59:29.1733749Z remote: Counting objects:  82% (28/34)        
2023-11-07T10:59:29.1735757Z remote: Counting objects:  85% (29/34)        
2023-11-07T10:59:29.1736288Z remote: Counting objects:  88% (30/34)        
2023-11-07T10:59:29.1736837Z remote: Counting objects:  91% (31/34)        
2023-11-07T10:59:29.1737383Z remote: Counting objects:  94% (32/34)        
2023-11-07T10:59:29.1737947Z remote: Counting objects:  97% (33/34)        
2023-11-07T10:59:29.1738482Z remote: Counting objects: 100% (34/34)        
2023-11-07T10:59:29.1739069Z remote: Counting objects: 100% (34/34), done.        
2023-11-07T10:59:29.1739681Z remote: Compressing objects:   3% (1/33)        
2023-11-07T10:59:29.1740396Z remote: Compressing objects:   6% (2/33)        
2023-11-07T10:59:29.1741000Z remote: Compressing objects:   9% (3/33)        
2023-11-07T10:59:29.1741598Z remote: Compressing objects:  12% (4/33)        
2023-11-07T10:59:29.1742179Z remote: Compressing objects:  15% (5/33)        
2023-11-07T10:59:29.1742776Z remote: Compressing objects:  18% (6/33)        
2023-11-07T10:59:29.1743492Z remote: Compressing objects:  21% (7/33)        
2023-11-07T10:59:29.1744057Z remote: Compressing objects:  24% (8/33)        
2023-11-07T10:59:29.1744637Z remote: Compressing objects:  27% (9/33)        
2023-11-07T10:59:29.1745220Z remote: Compressing objects:  30% (10/33)        
2023-11-07T10:59:29.1745809Z remote: Compressing objects:  33% (11/33)        
2023-11-07T10:59:29.1951313Z remote: Compressing objects:  36% (12/33)        
2023-11-07T10:59:29.1952701Z remote: Compressing objects:  39% (13/33)        
2023-11-07T10:59:29.1953653Z remote: Compressing objects:  42% (14/33)        
2023-11-07T10:59:29.1956384Z remote: Compressing objects:  45% (15/33)        
2023-11-07T10:59:29.1957781Z remote: Compressing objects:  48% (16/33)        
2023-11-07T10:59:29.1959656Z remote: Compressing objects:  51% (17/33)        
2023-11-07T10:59:29.1960504Z remote: Compressing objects:  54% (18/33)        
2023-11-07T10:59:29.1961278Z remote: Compressing objects:  57% (19/33)        
2023-11-07T10:59:29.1962049Z remote: Compressing objects:  60% (20/33)        
2023-11-07T10:59:29.1962816Z remote: Compressing objects:  63% (21/33)        
2023-11-07T10:59:29.1963582Z remote: Compressing objects:  66% (22/33)        
2023-11-07T10:59:29.1964320Z remote: Compressing objects:  69% (23/33)        
2023-11-07T10:59:29.1965075Z remote: Compressing objects:  72% (24/33)        
2023-11-07T10:59:29.1965831Z remote: Compressing objects:  75% (25/33)        
2023-11-07T10:59:29.1966493Z remote: Compressing objects:  78% (26/33)        
2023-11-07T10:59:29.1967084Z remote: Compressing objects:  81% (27/33)        
2023-11-07T10:59:29.1967742Z remote: Compressing objects:  84% (28/33)        
2023-11-07T10:59:29.1968515Z remote: Compressing objects:  87% (29/33)        
2023-11-07T10:59:29.1969250Z remote: Compressing objects:  90% (30/33)        
2023-11-07T10:59:29.1970605Z remote: Compressing objects:  93% (31/33)        
2023-11-07T10:59:29.1971392Z remote: Compressing objects:  96% (32/33)        
2023-11-07T10:59:29.1972174Z remote: Compressing objects: 100% (33/33)        
2023-11-07T10:59:29.1972962Z remote: Compressing objects: 100% (33/33), done.        
2023-11-07T10:59:29.3111448Z remote: Total 34 (delta 10), reused 11 (delta 0), pack-reused 0        
2023-11-07T10:59:29.4646281Z From https://github.com/Armada-Dynamics/Armada-Dynamics
2023-11-07T10:59:29.4648105Z  * [new ref]         1e67760267eb602b8a4cfc4618271b80201c2b66 -> origin/main
2023-11-07T10:59:29.5259534Z ##[endgroup]
2023-11-07T10:59:29.5260460Z ##[group]Determining the checkout info
2023-11-07T10:59:29.5261951Z ##[endgroup]
2023-11-07T10:59:29.5262685Z ##[group]Fetching LFS objects
2023-11-07T10:59:29.5275593Z [command]"C:\Program Files\Git\bin\git.exe" lfs fetch origin refs/remotes/origin/main
2023-11-07T10:59:29.9666245Z fetch: Fetching reference refs/remotes/origin/main
2023-11-07T10:59:30.1831415Z ##[endgroup]
2023-11-07T10:59:30.1835181Z ##[group]Checking out the ref
2023-11-07T10:59:30.1843785Z [command]"C:\Program Files\Git\bin\git.exe" checkout --progress --force -B main refs/remotes/origin/main
2023-11-07T10:59:30.2550966Z branch 'main' set up to track 'origin/main'.
2023-11-07T10:59:30.2552543Z Switched to a new branch 'main'
2023-11-07T10:59:30.6360038Z ##[endgroup]
2023-11-07T10:59:30.6817015Z [command]"C:\Program Files\Git\bin\git.exe" log -1 --format='%H'
2023-11-07T10:59:30.7164631Z '1e67760267eb602b8a4cfc4618271b80201c2b66'
2023-11-07T10:59:30.7778435Z ##[group]Run microsoft/AL-Go-Actions/WorkflowInitialize@v4.0
2023-11-07T10:59:30.7779131Z with:
2023-11-07T10:59:30.7779431Z   shell: powershell
2023-11-07T10:59:30.7779781Z   eventId: DO0091
2023-11-07T10:59:30.7780077Z env:
2023-11-07T10:59:30.7780357Z   workflowDepth: 1
2023-11-07T10:59:30.7780709Z   ALGoOrgSettings: 
2023-11-07T10:59:30.7781164Z   ALGoRepoSettings: 
2023-11-07T10:59:30.7781505Z ##[endgroup]
2023-11-07T10:59:30.8303388Z ##[group]Run $errorActionPreference = "Stop"; $ProgressPreference = "SilentlyContinue"; Set-StrictMode -Version 2.0
2023-11-07T10:59:30.8305067Z $errorActionPreference = "Stop"; $ProgressPreference = "SilentlyContinue"; Set-StrictMode -Version 2.0
2023-11-07T10:59:30.8306060Z try {
2023-11-07T10:59:30.8306995Z   D:\a\_actions\microsoft\AL-Go-Actions\v4.0\WorkflowInitialize/WorkflowInitialize.ps1 -eventId $ENV:_eventId
2023-11-07T10:59:30.8307950Z }
2023-11-07T10:59:30.8308291Z catch {
2023-11-07T10:59:30.8309611Z   Write-Host "::ERROR::Unexpected error when running action. Error Message: $($_.Exception.Message.Replace("`r",'').Replace("`n",' ')), StackTrace: $($_.ScriptStackTrace.Replace("`r",'').Replace("`n",' <- '))";
2023-11-07T10:59:30.8311133Z   exit 1
2023-11-07T10:59:30.8311450Z }
2023-11-07T10:59:30.8394053Z shell: C:\Windows\System32\WindowsPowerShell\v1.0\powershell.EXE -command ". '{0}'"
2023-11-07T10:59:30.8394807Z env:
2023-11-07T10:59:30.8395077Z   workflowDepth: 1
2023-11-07T10:59:30.8395412Z   ALGoOrgSettings: 
2023-11-07T10:59:30.8395768Z   ALGoRepoSettings: 
2023-11-07T10:59:30.8396096Z   _eventId: DO0091
2023-11-07T10:59:30.8396409Z ##[endgroup]
2023-11-07T10:59:31.4971658Z           _           _____          __              _____ _ _   _    _       _               _  _        ___  
2023-11-07T10:59:31.5030946Z     /\   | |         / ____|        / _|            / ____(_) | | |  | |     | |             | || |      / _ \ 
2023-11-07T10:59:31.5081539Z    /  \  | |  ______| |  __  ___   | |_ ___  _ __  | |  __ _| |_| |__| |_   _| |__    __   __| || |_    | | | |
2023-11-07T10:59:31.5135540Z   / /\ \ | | |______| | |_ |/ _ \  |  _/ _ \| '__| | | |_ | | __|  __  | | | | '_ \   \ \ / /|__   _|   | | | |
2023-11-07T10:59:31.5184570Z  / ____ \| |____    | |__| | (_) | | || (_) | |    | |__| | | |_| |  | | |_| | |_) |   \ V /    | |   _ | |_| |
2023-11-07T10:59:31.5235041Z /_/    \_\______|    \_____|\___/  |_| \___/|_|     \_____|_|\__|_|  |_|\__,_|_.__/     \_(_)   |_|  (_) \___/ 
2023-11-07T10:59:31.5586074Z BaseFolder: D:\a\Armada-Dynamics\Armada-Dynamics
2023-11-07T10:59:31.5916780Z Checking AL-Go Project settings file in .AL-Go\settings.json (type = Project)
2023-11-07T10:59:32.5144100Z Checking AL-Go Repo settings file in .github\AL-Go-Settings.json (type = Repo)
2023-11-07T10:59:32.5208921Z Checking AL-Go Workflow settings file in .github\Test Current.settings.json (type = Workflow)
2023-11-07T10:59:32.6318727Z Checking AL-Go Workflow settings file in .github\Test Next Major.settings.json (type = Workflow)
2023-11-07T10:59:32.6366228Z Checking AL-Go Workflow settings file in .github\Test Next Minor.settings.json (type = Workflow)
2023-11-07T10:59:33.1770806Z Downloading BcContainerHelper latest version from CDN
2023-11-07T10:59:33.9442418Z ##[error]Unexpected error when running action. Error Message: Exception calling "DownloadFile" with "2" argument(s): "The underlying connection was closed: An unexpected error occurred on a send.", StackTrace: at GetBcContainerHelperPath, D:\a\_actions\microsoft\AL-Go-Actions\v4.0\AL-Go-Helper.ps1: line 334 <- at DownloadAndImportBcContainerHelper, D:\a\_actions\microsoft\AL-Go-Actions\v4.0\AL-Go-Helper.ps1: line 414 <- at <ScriptBlock>, D:\a\_actions\microsoft\AL-Go-Actions\v4.0\WorkflowInitialize\WorkflowInitialize.ps1: line 30 <- at <ScriptBlock>, D:\a\_temp\bd168941-e7ef-41c8-8298-e47e91531f46.ps1: line 4 <- at <ScriptBlock>, <No file>: line 1
2023-11-07T10:59:33.9848266Z ##[error]Process completed with exit code 1.
2023-11-07T10:59:34.0816701Z Post job cleanup.
2023-11-07T10:59:34.7880912Z [command]"C:\Program Files\Git\bin\git.exe" version
2023-11-07T10:59:34.8618667Z git version 2.42.0.windows.2
2023-11-07T10:59:34.9301232Z Temporarily overriding HOME='D:\a\_temp\eae228eb-c957-45dd-a62f-a662d18a756f' before making global git config changes
2023-11-07T10:59:34.9326884Z Adding repository directory to the temporary git global config as a safe directory
2023-11-07T10:59:34.9367088Z [command]"C:\Program Files\Git\bin\git.exe" config --global --add safe.directory D:\a\Armada-Dynamics\Armada-Dynamics
2023-11-07T10:59:35.0232594Z [command]"C:\Program Files\Git\bin\git.exe" config --local --name-only --get-regexp core\.sshCommand
2023-11-07T10:59:35.0875988Z [command]"C:\Program Files\Git\bin\git.exe" submodule foreach --recursive "sh -c \"git config --local --name-only --get-regexp 'core\.sshCommand' && git config --local --unset-all 'core.sshCommand' || :\""
2023-11-07T10:59:35.8987655Z [command]"C:\Program Files\Git\bin\git.exe" config --local --name-only --get-regexp http\.https\:\/\/github\.com\/\.extraheader
2023-11-07T10:59:35.9431795Z http.https://github.com/.extraheader
2023-11-07T10:59:35.9483650Z [command]"C:\Program Files\Git\bin\git.exe" config --local --unset-all http.https://github.com/.extraheader
2023-11-07T10:59:35.9900590Z [command]"C:\Program Files\Git\bin\git.exe" submodule foreach --recursive "sh -c \"git config --local --name-only --get-regexp 'http\.https\:\/\/github\.com\/\.extraheader' && git config --local --unset-all 'http.https://github.com/.extraheader' || :\""
2023-11-07T10:59:36.5908533Z Evaluate and set job outputs
2023-11-07T10:59:36.5927472Z Cleaning up orphan processes

Similar error in DevOps

2023-11-01T01:14:56.9237254Z ##[section]Starting: ALOps App Validation
2023-11-01T01:14:56.9900521Z ==============================================================================
2023-11-01T01:14:56.9900901Z Task         : ALOps App Validation
2023-11-01T01:14:56.9901123Z Description  : Validate App from Business Central AppSource
2023-11-01T01:14:56.9901355Z Version      : 1.459.5009
2023-11-01T01:14:56.9901513Z Author       : Hodor
2023-11-01T01:14:56.9901680Z Help         : Validate App from Business Central AppSource.
2023-11-01T01:14:56.9901918Z ==============================================================================
2023-11-01T01:14:58.3839076Z *** Validate configuration
2023-11-01T01:14:58.4962908Z *** Task Inputs:
2023-11-01T01:14:58.5027423Z 
2023-11-01T01:14:58.5181391Z name                value                                                                                              
2023-11-01T01:14:58.5186799Z ----                -----                                                                                              
2023-11-01T01:14:58.5251144Z license_path        C:\docker\agents\elektra\_work\132\s\https:\armadadiag223.blob.core.windows.net\licenses\eqmLice...
2023-11-01T01:14:58.5254857Z countries           US                                                                                                 
2023-11-01T01:14:58.5261313Z affixes             EQM                                                                                                
2023-11-01T01:14:58.5265848Z artifact_path       C:\docker\agents\elektra\_work\132\a                                                               
2023-11-01T01:14:58.5267299Z artifact_filter     *APP.app                                                                                           
2023-11-01T01:14:58.5270709Z installapps_path    C:\docker\agents\elektra\_work\132\a\install                                                       
2023-11-01T01:14:58.5274879Z installapps_filter  *APP.app                                                                                           
2023-11-01T01:14:58.5279397Z previousapps_path   C:\docker\agents\elektra\_work\132\a\previous                                                      
2023-11-01T01:14:58.5282987Z previousapps_filter *APP.app                                                                                           
2023-11-01T01:14:58.5287160Z memory              8G                                                                                                 
2023-11-01T01:14:58.5290760Z validateversion                                                                                                        
2023-11-01T01:14:58.5293777Z validatecurrent     True                                                                                               
2023-11-01T01:14:58.5309010Z validatenextminor   False                                                                                              
2023-11-01T01:14:58.5311921Z validatenextmajor   False                                                                                              
2023-11-01T01:14:58.5315258Z sastoken                                                                                                               
2023-11-01T01:14:58.5318572Z skipverification    False                                                                                              
2023-11-01T01:14:58.5321904Z skipappsourcecop    False                                                                                              
2023-11-01T01:14:58.5325467Z includewarnings     False                                                                                              
2023-11-01T01:14:58.5327914Z failonerror         True                                                                                               
2023-11-01T01:14:58.5331000Z containername       bcserver                                                                                           
2023-11-01T01:14:58.5333097Z 
2023-11-01T01:14:58.5404019Z 
2023-11-01T01:14:58.5416042Z 
2023-11-01T01:14:58.5651314Z *** For documentation, please visit   : https://www.alops.be/documentation
2023-11-01T01:14:58.5655886Z 
2023-11-01T01:14:59.0446908Z *** ALOps License:
2023-11-01T01:14:59.0480425Z   * Licensed To: Armada Dynamics AS (Organization License)
2023-11-01T01:14:59.0487531Z 
2023-11-01T01:14:59.0935974Z *** Importing required PS-Functions
2023-11-01T01:14:59.1169788Z *** Install/Update BCContainerHelper [https://github.com/microsoft/navcontainerhelper]
2023-11-01T01:14:59.1252286Z *** Check BcContainerHelper
2023-11-01T01:14:59.2777518Z *** Load BcContainerHelper
2023-11-01T01:14:59.5466069Z BcContainerHelper version 6.0.0
2023-11-01T01:15:00.4857996Z BC.HelperFunctions emits usage statistics telemetry to Microsoft
2023-11-01T01:15:08.6599037Z 
2023-11-01T01:15:08.6600650Z ModuleType Version    Name                                ExportedCommands                                             
2023-11-01T01:15:08.6601948Z ---------- -------    ----                                ----------------                                             
2023-11-01T01:15:08.6603042Z Script     6.0.0      BcContainerHelper                   {Add-FontsToBcContainer, Add-GitToAlProjectFolder, AddTele...
2023-11-01T01:15:08.6603373Z 
2023-11-01T01:15:08.6603549Z 
2023-11-01T01:15:08.6612011Z 
2023-11-01T01:15:08.6618882Z 
2023-11-01T01:15:08.6627607Z *** Setup Flf File: https://armadadiag223.blob.core.windows.net/licenses/eqmLicense23.bclicense?sp=r&st=2023-10-09T07:32:09Z&se=2023-12-31T22:59:59Z&spr=https&sv=2022-11-02&sr=b&sig=gmecG%2Fn0cWeN4fEX%2B3beCzNKPMOhwnq8yfmzkJe%2BelE%3D
2023-11-01T01:15:08.6704637Z *** Create TempFile
2023-11-01T01:15:08.7080405Z *** Download file
2023-11-01T01:15:09.3336340Z *** Flf File: C:\Windows\TEMP\tmp70AA.tmp.flf
2023-11-01T01:15:09.3345587Z *** Flf Content:
2023-11-01T01:15:09.3451785Z Microsoft Software License Information
2023-11-01T01:15:09.3454190Z Copyright (C) 2007-2022 Microsoft Corporation. All rights reserved
2023-11-01T01:15:09.3455352Z ****************************************************************************
2023-11-01T01:15:09.3455931Z VOICE Account Number    : 5164993
2023-11-01T01:15:09.3456412Z Licensed to             : Armada Dynamics AS
2023-11-01T01:15:09.3456824Z                           Sandakerveien 52
2023-11-01T01:15:09.3457279Z                           OSLO
2023-11-01T01:15:09.3457649Z                           OSLO
2023-11-01T01:15:09.3457995Z                           OSLO
2023-11-01T01:15:09.3458356Z                           03
2023-11-01T01:15:09.3458724Z                           0477
2023-11-01T01:15:09.3459052Z                           Norway
2023-11-01T01:15:09.3467112Z 
2023-11-01T01:15:09.3467754Z Product Line            : Microsoft Dynamics 365 Business Central on premises
2023-11-01T01:15:09.3468114Z Product Edition         : Unspecified
2023-11-01T01:15:09.3468320Z Product Version            : 23
2023-11-01T01:15:09.3468477Z Country                 : Norway
2023-11-01T01:15:09.3468696Z Language                : English (US)
2023-11-01T01:15:09.3469376Z Created Date            : 10/5/2023 6:02:06 AM (-07:00)
2023-11-01T01:15:09.3469574Z Expires                 : 1/3/2024
2023-11-01T01:15:09.3469773Z Configuration           : Armada DEV BC 23
2023-11-01T01:15:09.3469969Z First registration Date : 
2023-11-01T01:15:09.3470186Z ****************************************************************************
2023-11-01T01:15:09.3471297Z Name                                                                  Amount
2023-11-01T01:15:09.3471670Z ----------------------------------------------------------------- ----------
2023-11-01T01:15:09.3478794Z All Application Module                                                     1
2023-11-01T01:15:09.3479482Z Country Code: W1                                                           1
2023-11-01T01:15:09.3479894Z Demo/Dev Named User                                                      150
2023-11-01T01:15:09.3483612Z Dyn365 Business Central Device                                           150
2023-11-01T01:15:09.3484154Z Dyn365 Business Central Team Members                                     300
2023-11-01T01:15:09.3484653Z Partner Demo/Dev Module                                                    1
2023-11-01T01:15:09.3485055Z Product Specialist                                                         1
2023-11-01T01:15:09.3485498Z Solution Developer                                                         1
2023-11-01T01:15:09.3485699Z 
2023-11-01T01:15:09.3486196Z ****************************************************************************
2023-11-01T01:15:09.3486478Z 
2023-11-01T01:15:09.3486599Z 
2023-11-01T01:15:09.3666369Z ##[command]Run-AlValidation
2023-11-01T01:15:09.3689098Z 
2023-11-01T01:15:09.3689800Z Name                           Value                                                                                   
2023-11-01T01:15:09.3690664Z ----                           -----                                                                                   
2023-11-01T01:15:09.3691217Z affixes                        EQM                                                                                     
2023-11-01T01:15:09.3691634Z includeWarnings                False                                                                                   
2023-11-01T01:15:09.3692110Z validateNextMajor              False                                                                                   
2023-11-01T01:15:09.3692714Z apps                           {C:\docker\agents\elektra\_work\132\a\Armada Dynamics AS_EQM 365 Rental_23.0.202344.2...
2023-11-01T01:15:09.3693648Z containerName                  bcserver                                                                                
2023-11-01T01:15:09.3694325Z memoryLimit                    8G                                                                                      
2023-11-01T01:15:09.3695044Z countries                      US                                                                                      
2023-11-01T01:15:09.3696105Z previousApps                   C:\docker\agents\elektra\_work\132\a\previous\Dynamics 365\Armada Dynamics AS_EQM 365...
2023-11-01T01:15:09.3697108Z failOnError                    True                                                                                    
2023-11-01T01:15:09.3697902Z skipappsourcecop               False                                                                                   
2023-11-01T01:15:09.3698723Z skipverification               False                                                                                   
2023-11-01T01:15:09.3699215Z validateCurrent                True                                                                                    
2023-11-01T01:15:09.3699638Z validateNextMinor              False                                                                                   
2023-11-01T01:15:09.3699878Z 
2023-11-01T01:15:09.3699999Z 
2023-11-01T01:15:09.3700112Z 
2023-11-01T01:15:12.7122485Z   _____                               _                
2023-11-01T01:15:12.7123612Z  |  __ \                             | |               
2023-11-01T01:15:12.7124361Z  | |__) |_ _ _ __ __ _ _ __ ___   ___| |_ ___ _ __ ___ 
2023-11-01T01:15:12.7125680Z  |  ___/ _` | '__/ _` | '_ ` _ \ / _ \ __/ _ \ '__/ __|
2023-11-01T01:15:12.7126095Z  | |  | (_| | | | (_| | | | | | |  __/ |_  __/ |  \__ \
2023-11-01T01:15:12.7128826Z  |_|   \__,_|_|  \__,_|_| |_| |_|\___|\__\___|_|  |___/
2023-11-01T01:15:12.7129160Z 
2023-11-01T01:15:12.7178053Z Container name                  bcserver
2023-11-01T01:15:12.7465330Z Credential                      admin/Zixi2580
2023-11-01T01:15:12.7662609Z MemoryLimit                     8G
2023-11-01T01:15:12.7681109Z validateVersion                 
2023-11-01T01:15:12.7705678Z validateCurrent                 True
2023-11-01T01:15:12.7731651Z validateNextMinor               False
2023-11-01T01:15:12.7754644Z validateNextMajor               False
2023-11-01T01:15:12.7792424Z SasToken                        Not Specified
2023-11-01T01:15:12.7842663Z countries                       us
2023-11-01T01:15:12.7866589Z validateCountries               us
2023-11-01T01:15:12.7889687Z affixes                         EQM
2023-11-01T01:15:12.7924216Z supportedCountries              
2023-11-01T01:15:12.7942270Z ObsoleteTagMinAllowedMajorMinor 
2023-11-01T01:15:12.7959952Z vsixFile                        
2023-11-01T01:15:12.7971148Z Install Apps
2023-11-01T01:15:12.7983253Z - None
2023-11-01T01:15:12.7993947Z Previous Apps
2023-11-01T01:15:12.8021692Z - C:\docker\agents\elektra\_work\132\a\previous\Dynamics 365\Armada Dynamics AS_EQM 365 Rental_22.5.202340.28195_APP.app
2023-11-01T01:15:12.8033113Z Apps
2023-11-01T01:15:12.8059577Z - C:\docker\agents\elektra\_work\132\a\Armada Dynamics AS_EQM 365 Rental_23.0.202344.29284_APP.app
2023-11-01T01:15:12.8071733Z - C:\docker\agents\elektra\_work\132\a\previous\Dynamics 365\Armada Dynamics AS_EQM 365 Rental_22.5.202340.28195_APP.app
2023-11-01T01:15:12.8169550Z 
2023-11-01T01:15:12.8171261Z   _____       _ _ _                                          _        _                            
2023-11-01T01:15:12.8171969Z  |  __ \     | | (_)                                        (_)      (_)                           
2023-11-01T01:15:12.8172810Z  | |__) |   _| | |_ _ __   __ _    __ _  ___ _ __   ___ _ __ _  ___   _ _ __ ___   __ _  __ _  ___ 
2023-11-01T01:15:12.8173933Z  |  ___/ | | | | | | '_ \ / _` |  / _` |/ _ \ '_ \ / _ \ '__| |/ __| | | '_ ` _ \ / _` |/ _` |/ _ \
2023-11-01T01:15:12.8174459Z  | |   | |_| | | | | | | | (_| | | (_| |  __/ | | |  __/ |  | | (__  | | | | | | | (_| | (_| |  __/
2023-11-01T01:15:12.8174983Z  |_|    \__,_|_|_|_|_| |_|\__, |  \__, |\___|_| |_|\___|_|  |_|\___| |_|_| |_| |_|\__,_|\__, |\___|
2023-11-01T01:15:12.8175443Z                            __/ |   __/ |                                                 __/ |     
2023-11-01T01:15:12.8175860Z                           |___/   |___/                                                 |___/      
2023-11-01T01:15:12.8176031Z 
2023-11-01T01:15:12.8186485Z Pulling mcr.microsoft.com/businesscentral:10.0.20348.2031-filesonly
2023-11-01T01:15:13.2296684Z 
2023-11-01T01:15:13.2299430Z Pulling generic image took 0 seconds
2023-11-01T01:15:13.2734000Z   _____       _                      _                         _   _  __           _       
2023-11-01T01:15:13.2734820Z  |  __ \     | |                    (_)                       | | (_)/ _|         | |      
2023-11-01T01:15:13.2735303Z  | |  | | ___| |_ ___ _ __ _ __ ___  _ _ __   ___    __ _ _ __| |_ _| |_ __ _  ___| |_ ___ 
2023-11-01T01:15:13.2736825Z  | |  | |/ _ \ __/ _ \ '__| '_ ` _ \| | '_ \ / _ \  / _` | '__| __| |  _/ _` |/ __| __/ __|
2023-11-01T01:15:13.2737348Z  | |__| |  __/ |_  __/ |  | | | | | | | | | |  __/ | (_| | |  | |_| | || (_| | (__| |_\__ \
2023-11-01T01:15:13.2737837Z  |_____/ \___|\__\___|_|  |_| |_| |_|_|_| |_|\___|  \__,_|_|   \__|_|_| \__,_|\___|\__|___/
2023-11-01T01:15:13.2738361Z                                                                                            
2023-11-01T01:15:38.3071940Z Found https://bcartifacts.azureedge.net/sandbox/23.0.12034.13572/us
2023-11-01T01:15:38.3866287Z Using https://bcartifacts.azureedge.net/sandbox/23.0.12034.13572/us
2023-11-01T01:15:38.4000370Z 
2023-11-01T01:15:38.4000978Z    _____                _   _                               _        _                 
2023-11-01T01:15:38.4002210Z   / ____|              | | (_)                             | |      (_)                
2023-11-01T01:15:38.4016806Z  | |     _ __ ___  __ _| |_ _ _ __   __ _    ___ ___  _ __ | |_ __ _ _ _ __   ___ _ __ 
2023-11-01T01:15:38.4018263Z  | |    | '__/ _ \/ _` | __| | '_ \ / _` |  / __/ _ \| '_ \| __/ _` | | '_ \ / _ \ '__|
2023-11-01T01:15:38.4018905Z  | |____| | |  __/ (_| | |_| | | | | (_| | | (__ (_) | | | | |_ (_| | | | | |  __/ |   
2023-11-01T01:15:38.4020994Z   \_____|_|  \___|\__,_|\__|_|_| |_|\__, |  \___\___/|_| |_|\__\__,_|_|_| |_|\___|_|   
2023-11-01T01:15:38.4021449Z                                      __/ |                                             
2023-11-01T01:15:38.4021870Z                                     |___/                                              
2023-11-01T01:15:38.4022064Z 
2023-11-01T01:15:38.4081220Z Creating container for country us
2023-11-01T01:15:38.8686848Z BcContainerHelper is version 6.0.0
2023-11-01T01:15:38.8709794Z BcContainerHelper is running as administrator
2023-11-01T01:15:40.8573318Z HyperV is Disabled
2023-11-01T01:15:40.8621750Z UsePsSession is True
2023-11-01T01:15:40.8660092Z Host is Microsoft Windows Server 2022 Standard - 10.0.20348.2031
2023-11-01T01:15:41.0210090Z Docker Client Version is master-dockerproject-2022-03-26
2023-11-01T01:15:41.0241970Z Docker Server Version is master-dockerproject-2022-03-26
2023-11-01T01:15:41.4722447Z Removing Desktop shortcuts
2023-11-01T01:15:42.6656266Z Fetching all docker images
2023-11-01T01:15:42.8296059Z Fetching all docker volumes
2023-11-01T01:15:43.1132897Z Using image mcr.microsoft.com/businesscentral:10.0.20348.2031-filesonly
2023-11-01T01:15:43.7873923Z Creating Container bcserver
2023-11-01T01:15:43.7896781Z Style: sandbox
2023-11-01T01:15:43.7918581Z Multitenant: Yes
2023-11-01T01:15:43.7941400Z Version: 23.0.12034.13572
2023-11-01T01:15:43.7963835Z Platform: 23.0.13543.0
2023-11-01T01:15:43.7993441Z Generic Tag: 1.0.2.14
2023-11-01T01:15:43.8094585Z Container OS Version: 10.0.20348.2031 (ltsc2022)
2023-11-01T01:15:43.8113202Z Host OS Version: 10.0.20348.2031 (ltsc2022)
2023-11-01T01:15:43.8225674Z Using process isolation
2023-11-01T01:15:43.8276591Z Using locale en-US
2023-11-01T01:15:43.8329729Z Disabling the standard eventlog dump to container log every 2 seconds (use -dumpEventLog to enable)
2023-11-01T01:15:43.8799082Z Downloading C:\ProgramData\BcContainerHelper\Extensions\bcserver\Microsoft.VisualStudio.Services.VSIXPackage.vsix
2023-11-01T01:15:43.8926442Z Downloading using WebClient
2023-11-01T01:15:48.7182243Z Additional Parameters:
2023-11-01T01:15:48.7215862Z --env customNavSettings=EnableTaskScheduler=True
2023-11-01T01:15:48.7235908Z Files in C:\ProgramData\BcContainerHelper\Extensions\bcserver\my:
2023-11-01T01:15:48.7377087Z - AdditionalOutput.ps1
2023-11-01T01:15:48.7403675Z - MainLoop.ps1
2023-11-01T01:15:48.7423958Z - SetupVariables.ps1
2023-11-01T01:15:48.7443635Z - updatehosts.ps1
2023-11-01T01:15:48.7461744Z Creating container bcserver from image mcr.microsoft.com/businesscentral:10.0.20348.2031-filesonly
2023-11-01T01:15:49.3612094Z 0efb63f5e004aaeb00caec56cf959722364476486ed58a9274cab698f3a8ea08
2023-11-01T01:15:53.2820564Z Waiting for container bcserver to be ready
2023-11-01T01:15:56.7179570Z Using artifactUrl https://bcartifacts.azureedge.net/sandbox/23.0.12034.13572/us
2023-11-01T01:15:56.7180235Z Using installer from C:\Run\210-new
2023-11-01T01:15:56.7180439Z Installing Business Central
2023-11-01T01:15:56.7180710Z Installing from artifacts
2023-11-01T01:15:56.7196860Z Copying Service Tier Files
2023-11-01T01:15:59.1154529Z c:\dl\sandbox\23.0.12034.13572\platform\ServiceTier\Program Files
2023-11-01T01:15:59.1155611Z c:\dl\sandbox\23.0.12034.13572\platform\ServiceTier\System64Folder
2023-11-01T01:15:59.1156136Z Copying PowerShell Scripts
2023-11-01T01:15:59.1156740Z c:\dl\sandbox\23.0.12034.13572\platform\WindowsPowerShellScripts\Cloud\NAVAdministration
2023-11-01T01:15:59.1157977Z c:\dl\sandbox\23.0.12034.13572\platform\WindowsPowerShellScripts\WebSearch
2023-11-01T01:15:59.1158393Z Copying Web Client Files
2023-11-01T01:16:02.5526857Z c:\dl\sandbox\23.0.12034.13572\platform\WebClient\Microsoft Dynamics NAV
2023-11-01T01:16:02.5528996Z Copying ModernDev Files
2023-11-01T01:16:02.5529661Z c:\dl\sandbox\23.0.12034.13572\platform
2023-11-01T01:16:03.6894518Z c:\dl\sandbox\23.0.12034.13572\platform\ModernDev\program files\Microsoft Dynamics NAV
2023-11-01T01:16:03.6896785Z Copying additional files
2023-11-01T01:16:03.6896949Z Copying ConfigurationPackages
2023-11-01T01:16:03.6897114Z C:\dl\sandbox\23.0.12034.13572\us\ConfigurationPackages
2023-11-01T01:16:03.6915938Z Copying Test Assemblies
2023-11-01T01:16:03.6916286Z C:\dl\sandbox\23.0.12034.13572\platform\Test Assemblies
2023-11-01T01:16:03.6916807Z Copying Extensions
2023-11-01T01:16:03.6917130Z C:\dl\sandbox\23.0.12034.13572\us\Extensions
2023-11-01T01:16:03.6917356Z Copying Applications
2023-11-01T01:16:04.8410729Z C:\dl\sandbox\23.0.12034.13572\platform\Applications
2023-11-01T01:16:04.8411473Z Copying Applications.US
2023-11-01T01:16:04.8411913Z C:\dl\sandbox\23.0.12034.13572\us\Applications.US
2023-11-01T01:16:04.8412542Z Copying dependencies
2023-11-01T01:16:14.2081221Z Copying ReportBuilder
2023-11-01T01:16:14.2082143Z Importing PowerShell Modules
2023-11-01T01:16:14.2083013Z Skipping restore of Cronus database
2023-11-01T01:16:14.2083808Z Modifying Business Central Service Tier Config File for Docker
2023-11-01T01:16:15.3406621Z Installing SIP crypto provider: 'C:\Windows\System32\NavSip.dll'
2023-11-01T01:16:15.3407082Z Installation took 18 seconds
2023-11-01T01:16:15.3407438Z Installation complete
2023-11-01T01:16:15.3423541Z Ready for connections!
2023-11-01T01:16:17.4429850Z Reading CustomSettings.config from bcserver
2023-11-01T01:16:17.8474670Z Cleanup old dotnet core assemblies
2023-11-01T01:16:18.9092471Z 
2023-11-01T01:16:18.9096146Z Creating container took 41 seconds
2023-11-01T01:16:18.9114688Z   _____                   _                                    _____                           _____            
2023-11-01T01:16:18.9115261Z  |  __ \                 (_)                 /\               / ____|                         / ____|           
2023-11-01T01:16:18.9115833Z  | |__) |   _ _ __  _ __  _ _ __   __ _     /  \   _ __  _ __| (___   ___  _   _ _ __ ___ ___| |     ___  _ __  
2023-11-01T01:16:18.9117034Z  |  _  / | | | '_ \| '_ \| | '_ \ / _` |   / /\ \ | '_ \| '_ \\___ \ / _ \| | | | '__/ __/ _ \ |    / _ \| '_ \ 
2023-11-01T01:16:18.9117576Z  | | \ \ |_| | | | | | | | | | | | (_| |  / ____ \| |_) | |_) |___) | (_) | |_| | | | (__  __/ |____ (_) | |_) |
2023-11-01T01:16:18.9118186Z  |_|  \_\__,_|_| |_|_| |_|_|_| |_|\__, | /_/    \_\ .__/| .__/_____/ \___/ \__,_|_|  \___\___|\_____\___/| .__/ 
2023-11-01T01:16:18.9118731Z                                    __/ |          | |   | |                                              | |    
2023-11-01T01:16:18.9119120Z                                   |___/           |_|   |_|                                              |_|    
2023-11-01T01:16:20.5420278Z Copying previous apps to packages folder
2023-11-01T01:16:21.1081857Z Extracting C:\ProgramData\BcContainerHelper\ed0b984d-d274-42cc-b1e5-5481a6f55587\Armada Dynamics AS_EQM 365 Rental_22.5.202340.28195_APP.app
2023-11-01T01:16:28.4604785Z Armada Dynamics AS_EQM 365 Rental = 22.5.202340.28195
2023-11-01T01:16:46.1403682Z Latest Supported Runtime Version: 12.0
2023-11-01T01:16:46.3828219Z Downloading vcredist_x64 (version 140)
2023-11-01T01:24:47.7075061Z Exception calling "DownloadFile" with "2" argument(s): "An exception occurred during a WebClient request."
2023-11-01T01:24:47.7083286Z 
2023-11-01T01:24:47.7107062Z Exception Script Stack Trace:
2023-11-01T01:24:47.7151607Z at <ScriptBlock>, <No file>: line 4
2023-11-01T01:24:47.7164691Z 
2023-11-01T01:24:47.7182649Z PowerShell Call Stack:
2023-11-01T01:24:47.7205223Z at Invoke-ScriptInBcContainer, C:\Program Files\WindowsPowerShell\Modules\BcContainerHelper\6.0.0\ContainerHandling\Invoke-ScriptInNavContainer.ps1: line 71
2023-11-01T01:24:47.7216706Z at <ScriptBlock>, C:\Program Files\WindowsPowerShell\Modules\BcContainerHelper\6.0.0\AppHandling\Run-AlCops.ps1: line 153
2023-11-01T01:24:47.7228629Z at Run-AlCops, C:\Program Files\WindowsPowerShell\Modules\BcContainerHelper\6.0.0\AppHandling\Run-AlCops.ps1: line 144
2023-11-01T01:24:47.7240517Z at <ScriptBlock>, C:\Program Files\WindowsPowerShell\Modules\BcContainerHelper\6.0.0\AppHandling\Run-AlValidation.ps1: line 508
2023-11-01T01:24:47.7252091Z at <ScriptBlock>, C:\Program Files\WindowsPowerShell\Modules\BcContainerHelper\6.0.0\AppHandling\Run-AlValidation.ps1: line 487
2023-11-01T01:24:47.7263604Z at <ScriptBlock>, C:\Program Files\WindowsPowerShell\Modules\BcContainerHelper\6.0.0\AppHandling\Run-AlValidation.ps1: line 431
2023-11-01T01:24:47.7275615Z at <ScriptBlock>, C:\Program Files\WindowsPowerShell\Modules\BcContainerHelper\6.0.0\AppHandling\Run-AlValidation.ps1: line 395
2023-11-01T01:24:47.7286258Z at Run-AlValidation, C:\Program Files\WindowsPowerShell\Modules\BcContainerHelper\6.0.0\AppHandling\Run-AlValidation.ps1: line 393
2023-11-01T01:24:47.7297389Z at <ScriptBlock>, C:\docker\agents\elektra\_work\_tasks\ALOpsAppValidation_ade79b0a-fd75-46d4-b16c-ba9d8d808092\1.459.5009\7966e147-bfba-4644-9ce6-86237280dce4.ps1: line 229
2023-11-01T01:24:47.7308407Z at <ScriptBlock>, C:\docker\agents\elektra\_work\_tasks\ALOpsAppValidation_ade79b0a-fd75-46d4-b16c-ba9d8d808092\1.459.5009\932c562a-ae27-4480-b8b4-5d051a0c14c1.ps1: line 1
2023-11-01T01:24:47.7318533Z at <ScriptBlock>, <No file>: line 1
2023-11-01T01:24:47.7329829Z at <ScriptBlock>, <No file>: line 22
2023-11-01T01:24:47.7340844Z at <ScriptBlock>, <No file>: line 18
2023-11-01T01:24:47.7351861Z at <ScriptBlock>, <No file>: line 1
2023-11-01T01:24:48.2552610Z 
2023-11-01T01:24:48.2556891Z Container Free Physical Memory: 19.9Gb
2023-11-01T01:24:48.2577059Z 
2023-11-01T01:24:48.2581493Z Services in container bcserver:
2023-11-01T01:24:48.2894001Z - No services found
2023-11-01T01:24:48.2906254Z 
2023-11-01T01:24:49.3270561Z Run-AlCops Telemetry Correlation Id: 7106da8c-e7c5-487b-9638-25a15c2a2b74
2023-11-01T01:24:49.3407458Z Unexpected error while validating app. Error is: Exception calling "DownloadFile" with "2" argument(s): "An exception occurred during a WebClient request."
2023-11-01T01:24:49.3449703Z at Invoke-ScriptInBcContainer, C:\Program Files\WindowsPowerShell\Modules\BcContainerHelper\6.0.0\ContainerHandling\Invoke-ScriptInNavContainer.ps1: line 112
2023-11-01T01:24:49.3451316Z at <ScriptBlock>, C:\Program Files\WindowsPowerShell\Modules\BcContainerHelper\6.0.0\AppHandling\Run-AlCops.ps1: line 153
2023-11-01T01:24:49.3452726Z at Run-AlCops, C:\Program Files\WindowsPowerShell\Modules\BcContainerHelper\6.0.0\AppHandling\Run-AlCops.ps1: line 144
2023-11-01T01:24:49.3454357Z at <ScriptBlock>, C:\Program Files\WindowsPowerShell\Modules\BcContainerHelper\6.0.0\AppHandling\Run-AlValidation.ps1: line 508
2023-11-01T01:24:49.3465385Z at <ScriptBlock>, C:\Program Files\WindowsPowerShell\Modules\BcContainerHelper\6.0.0\AppHandling\Run-AlValidation.ps1: line 487
2023-11-01T01:24:49.3466819Z at <ScriptBlock>, C:\Program Files\WindowsPowerShell\Modules\BcContainerHelper\6.0.0\AppHandling\Run-AlValidation.ps1: line 431
2023-11-01T01:24:49.3468059Z at <ScriptBlock>, C:\Program Files\WindowsPowerShell\Modules\BcContainerHelper\6.0.0\AppHandling\Run-AlValidation.ps1: line 395
2023-11-01T01:24:49.3469304Z at Run-AlValidation, C:\Program Files\WindowsPowerShell\Modules\BcContainerHelper\6.0.0\AppHandling\Run-AlValidation.ps1: line 393
2023-11-01T01:24:49.3470804Z at <ScriptBlock>, C:\docker\agents\elektra\_work\_tasks\ALOpsAppValidation_ade79b0a-fd75-46d4-b16c-ba9d8d808092\1.459.5009\7966e147-bfba-4644-9ce6-86237280dce4.ps1: line 229
2023-11-01T01:24:49.3472302Z at <ScriptBlock>, C:\docker\agents\elektra\_work\_tasks\ALOpsAppValidation_ade79b0a-fd75-46d4-b16c-ba9d8d808092\1.459.5009\932c562a-ae27-4480-b8b4-5d051a0c14c1.ps1: line 1
2023-11-01T01:24:49.3474631Z at <ScriptBlock>, <No file>: line 1
2023-11-01T01:24:49.3475138Z at <ScriptBlock>, <No file>: line 22
2023-11-01T01:24:49.3475612Z at <ScriptBlock>, <No file>: line 18
2023-11-01T01:24:49.3476031Z at <ScriptBlock>, <No file>: line 1
2023-11-01T01:24:49.3540134Z 
2023-11-01T01:24:49.3541320Z   _____                           _                _____            _        _                 
2023-11-01T01:24:49.3542073Z  |  __ \                         (_)              / ____|          | |      (_)                
2023-11-01T01:24:49.3543972Z  | |__) |___ _ __ ___   _____   ___ _ __   __ _  | |     ___  _ __ | |_ __ _ _ _ __   ___ _ __ 
2023-11-01T01:24:49.3544998Z  |  _  // _ \ '_ ` _ \ / _ \ \ / / | '_ \ / _` | | |    / _ \| '_ \| __/ _` | | '_ \ / _ \ '__|
2023-11-01T01:24:49.3545506Z  | | \ \  __/ | | | | | (_) \ V /| | | | | (_| | | |____ (_) | | | | |_ (_| | | | | |  __/ |   
2023-11-01T01:24:49.3546059Z  |_|  \_\___|_| |_| |_|\___/ \_/ |_|_| |_|\__, |  \_____\___/|_| |_|\__\__,_|_|_| |_|\___|_|   
2023-11-01T01:24:49.3546502Z                                            __/ |                                               
2023-11-01T01:24:49.3546943Z                                           |___/                                                
2023-11-01T01:24:49.3547105Z 
2023-11-01T01:24:49.9277426Z Removing Session bcserver
2023-11-01T01:24:50.1060675Z Removing container bcserver
2023-11-01T01:24:52.0115637Z Removing entries from hosts
2023-11-01T01:24:52.0769266Z Removing bcserver from container hosts file
2023-11-01T01:24:52.1792496Z Removing bcserver-* from container hosts file
2023-11-01T01:24:52.1866947Z Removing Desktop shortcuts
2023-11-01T01:24:52.2079502Z Removing C:\ProgramData\BcContainerHelper\Extensions\bcserver
2023-11-01T01:24:52.2560928Z 
2023-11-01T01:24:52.2563833Z Removing container took 3 seconds
2023-11-01T01:24:52.2590536Z 
2023-11-01T01:24:52.2591836Z AL Validation finished in 579 seconds
2023-11-01T01:24:52.2601618Z  __      __   _ _     _       _   _               _____                _ _       
2023-11-01T01:24:52.2603093Z  \ \    / /  | (_)   | |     | | (_)             |  __ \              | | |      
2023-11-01T01:24:52.2603978Z   \ \  / /_ _| |_  __| | __ _| |_ _  ___  _ __   | |__) |___ ___ _   _| | |_ ___ 
2023-11-01T01:24:52.2605942Z    \ \/ / _` | | |/ _` |/ _` | __| |/ _ \| '_ \  |  _  // _ \ __| | | | | __/ __|
2023-11-01T01:24:52.2606730Z     \  / (_| | | | (_| | (_| | |_| | (_) | | | | | | \ \  __\__ \ |_| | | |_\__ \
2023-11-01T01:24:52.2607556Z      \/ \__,_|_|_|\__,_|\__,_|\__|_|\___/|_| |_| |_|  \_\___|___/\__,_|_|\__|___/
2023-11-01T01:24:52.2607988Z 
2023-11-01T01:24:52.2617410Z Unexpected error while validating app. Error is: Exception calling "DownloadFile" with "2" argument(s): "An exception occurred during a WebClient request."
2023-11-01T01:24:52.2625489Z   _____                          ___      __   _ _     _       _   _               ______    _ _                
2023-11-01T01:24:52.2626444Z  |  __ \                   /\   | \ \    / /  | (_)   | |     | | (_)             |  ____|  (_) |               
2023-11-01T01:24:52.2627334Z  | |__) |   _ _ __ ______ /  \  | |\ \  / /_ _| |_  __| | __ _| |_ _  ___  _ __   | |__ __ _ _| |_   _ _ __ ___ 
2023-11-01T01:24:52.2628760Z  |  _  / | | | '_ \______/ /\ \ | | \ \/ / _` | | |/ _` |/ _` | __| |/ _ \| '_ \  |  __/ _` | | | | | | '__/ _ \
2023-11-01T01:24:52.2629509Z  | | \ \ |_| | | | |    / ____ \| |  \  / (_| | | | (_| | (_| | |_| | (_) | | | | | | | (_| | | | |_| | | |  __/
2023-11-01T01:24:52.2630394Z  |_|  \_\__,_|_| |_|   /_/    \_\_|   \/ \__,_|_|_|\__,_|\__,_|\__|_|\___/|_| |_| |_|  \__,_|_|_|\__,_|_|  \___|
2023-11-01T01:24:52.2630846Z 
2023-11-01T01:24:52.7177121Z ##[error]Unexpected error while validating app. Error is: Exception calling "DownloadFile" with "2" argument(s): "An exception occurred during a WebClient request."
2023-11-01T01:24:52.7416327Z *** Cleanup VSTS Environment: True
2023-11-01T01:24:55.0616335Z ##[section]Finishing: ALOps App Validation
AskeHolst commented 10 months ago

I had a run today hanging on downloads, I killed it after 4 hours. Here BCH did not fail to download, but other artifacts. I would have expected it to time out, 4 hours to download a file is a long time 🙂

4h 22m 1s
Wed, 08 Nov 2023 16:01:11 GMT
Run microsoft/AL-Go-Actions/RunPipeline@v4.0
Wed, 08 Nov 2023 16:01:11 GMT
Run $errorActionPreference = "Stop"; $ProgressPreference = "SilentlyContinue"; Set-StrictMode -Version 2.0
Wed, 08 Nov 2023 16:01:11 GMT
Import from C:\ProgramData\BcContainerHelper\6.0.0\BcContainerHelper\BcContainerHelper.ps1
Wed, 08 Nov 2023 16:01:11 GMT
BcContainerHelper version 6.0.0
Wed, 08 Nov 2023 16:01:12 GMT
BC.HelperFunctions emits usage statistics telemetry to Microsoft
Wed, 08 Nov 2023 16:01:12 GMT
Running on PowerShell 7
Wed, 08 Nov 2023 16:01:15 GMT
use settings and secrets
Wed, 08 Nov 2023 16:01:15 GMT
Analyzing repository
Wed, 08 Nov 2023 16:01:15 GMT
  Checking type
Wed, 08 Nov 2023 16:01:15 GMT
  Checking appFolders, testFolders and bcptTestFolders
Wed, 08 Nov 2023 16:01:15 GMT
  Application Dependency 22.0.0.0
Wed, 08 Nov 2023 16:01:15 GMT
  Checking artifact setting for project
Wed, 08 Nov 2023 16:01:15 GMT
  Downloading artifacts from https://bcartifacts.azureedge.net/sandbox/23.0.12034.13[75](https://github.com/prymeGlobal/prymeTime/actions/runs/6800753914/job/18490170591#step:8:77)2/gb
Wed, 08 Nov 2023 16:01:15 GMT
  Downloading artifact /sandbox/23.0.12034.13752/gb
Wed, 08 Nov 2023 16:01:15 GMT
  Downloading C:\Windows\SystemTemp\448062fc-84b3-4dff-9b27-8eecb61bd017.zip
Wed, 08 Nov 2023 16:01:15 GMT
  Downloading using HttpClient
Wed, 08 Nov 2023 16:06:02 GMT
  Could not download from CDN..., retrying from blob storage in 5 seconds...
Wed, 08 Nov 2023 16:06:07 GMT
  Downloading using HttpClient
Wed, 08 Nov 2023 16:06:18 GMT
  Unpacking artifact to tmp folder using 7zip
Wed, 08 Nov 2023 16:06:22 GMT
  Downloading platform artifact /sandbox/23.0.12034.13752/platform
Wed, 08 Nov 2023 16:06:22 GMT
  Downloading C:\Windows\SystemTemp\3ed2acc4-8c57-4c89-9348-923b[79](https://github.com/prymeGlobal/prymeTime/actions/runs/6800753914/job/18490170591#step:8:81)1eb600.zip
Wed, 08 Nov 2023 16:06:22 GMT
  Downloading using HttpClient
Wed, 08 Nov 2023 16:06:22 GMT
  Could not download from CDN..., retrying from blob storage in 3 seconds...
Wed, 08 Nov 2023 16:06:25 GMT
  Downloading using HttpClient
Wed, 08 Nov 2023 16:06:39 GMT
  Unpacking platform artifact to tmp folder using 7zip
Wed, 08 Nov 2023 16:06:53 GMT
  Downloading Prerequisite Components
Wed, 08 Nov 2023 16:06:53 GMT
  Downloading c:\bcartifacts.cache\sandbox\23.0.12034.13752\platform\Prerequisite Components\IIS URL Rewrite Module\rewrite_2.0_rtw_x64.msi
Wed, 08 Nov 2023 16:06:53 GMT
  Downloading using HttpClient
Wed, 08 Nov 2023 16:06:53 GMT
  Could not download from CDN..., retrying from blob storage in 2 seconds...
Wed, 08 Nov 2023 16:06:55 GMT
  Downloading using HttpClient
Wed, 08 Nov 2023 16:06:55 GMT
  Downloading c:\bcartifacts.cache\sandbox\23.0.12034.13752\platform\Prerequisite Components\DotNetCore\DotNetCore.1.0.4_1.1.1-WindowsHosting.exe
Wed, 08 Nov 2023 16:06:55 GMT
  Downloading using HttpClient
Wed, 08 Nov 2023 20:23:12 GMT
  Error: The operation was canceled.
freddydk commented 10 months ago

Looks like the problem is in the CDN, which is one of the things you normally should just trust. I did however build in some resilience to retry using the blob storage URL - but since the CDN download didn't fail, just hang - it never retries. Will check timeouts etc. what they are set to, In the meantime, you could create two organizational settings called

"DoNotUseCdnForArtifacts": true
"BcContainerHelperVersion": "preview"

Then all your pipelines should stop using CDN for artifacts. The BcContainerHelper version is just because preview is grabbed directly from a storage url, whereas latest is fetched from CDN.

freddydk commented 5 months ago

In the latest AL-Go for GitHub we no longer use CDN for downloading BcContainerHelper - and we have switched to a different CDN for artifacts