search

jberezanski / ChocolateyPackages

Chocolatey packages maintained by me
MIT License
83 stars 51 forks source link

VS 2019 Build Tools silent failure installing VCTools workload #108

Closed robinbanbury closed 1 year ago

robinbanbury commented 3 years ago

I'm trying to install the VCTools workload (Desktop development with C++) using the visualstudio2019buildtools / visualstudio2019-workload-vctools Chocolatey packages, but getting silent failures. My aim is to use Packer for the build, to have a completely hands off / automated VM image build process.

The issue seems to be with the Windows 10 SDK component. On Windows 2012, this component seems to install ok, but on Windows 2019 it fails silently.

I've tried the following commands to install the components/workloads I need:

choco install visualstudio2019buildtools --package-parameters "--add Microsoft.VisualStudio.Component.VC.Tools.x86.x64 --add Microsoft.VisualStudio.Component.Windows10SDK.19041"
choco install visualstudio2019buildtools --package-parameters "--add Microsoft.VisualStudio.Workload.VCTools --includeRecommended"
choco install visualstudio2019buildtools --package-parameters "--allWorkloads --includeRecommended"
choco install visualstudio2019buildtools && choco install visualstudio2019-workload-vctools

In each case, the Windows 10 SDK is not installed, but the failure is silent.

Debug

Packer logs from a failed install (Windows 2019):

2021/06/10 12:10:00 ui: ^[[0;32m    googlecompute: Installing visualstudio2019buildtools...^[[0m
2021/06/10 12:10:00 ui: ^[[0;32m    googlecompute:^[[0m
2021/06/10 12:14:33 packer-builder-googlecompute plugin: [INFO] command 'powershell -executionpolicy bypass "& { if (Test-Path variable:global:ProgressPreference){set-variable -name variable:global:ProgressPreference -value 'SilentlyContinue'};. c:/Windows/Temp/packer-ps-env-vars-60c1f23f-b203-e4ed-6257-1e2a88e4bb84.ps1; &'c:/Windows/Temp/script-60c1f23f-11c5-b445-a0c5-30f30ed5cd8e.ps1'; exit $LastExitCode }"' exited with code: 0
2021/06/10 12:14:33 packer-builder-googlecompute plugin: [INFO] RPC endpoint: Communicator ended with: 0
2021/06/10 12:14:33 [INFO] 15196 bytes written for 'stdout'
2021/06/10 12:14:33 [INFO] 0 bytes written for 'stderr'
2021/06/10 12:14:33 [INFO] RPC client: Communicator ended with: 0
2021/06/10 12:14:33 [INFO] RPC endpoint: Communicator ended with: 0
2021/06/10 12:14:33 packer-provisioner-powershell plugin: [INFO] 15196 bytes written for 'stdout'
2021/06/10 12:14:33 packer-provisioner-powershell plugin: [INFO] 0 bytes written for 'stderr'
2021/06/10 12:14:33 packer-provisioner-powershell plugin: [INFO] RPC client: Communicator ended with: 0

...

// some time later, during subsequent installation script steps
[WARN ] - [Pending] Removing incomplete install for 'visualstudio2019buildtools

The script returns exits code 0, but the VS Build Tools did not install all required components.

Indeed, when I spin up an instance of the resulting VM image, I can see several messages like the following in Event Viewer, after startup:

2021-06-10 14:06:30 Beginning a Windows Installer transaction: C:\ProgramData\Package Cache\{FF7D4409-CF59-34AE-BDC7-8A6146A9BA36}v10.1.19041.685\Installers\Windows SDK for Windows Store Managed Apps Libs-x86_en-us.msi. Client Process Id: 6992.
2021-06-10 14:06:30 Product: Windows SDK for Windows Store Managed Apps Libs -- Installation completed successfully.

So it looks like the installer is still trying to continue the installation, or fix itself somehow?

Compare that with a successful installation log, on Windows 2012:

2021/06/10 13:49:16 ui: ^[[0;32m    googlecompute: Installing visualstudio2019buildtools...^[[0m
2021/06/10 13:49:16 ui: ^[[0;32m    googlecompute:^[[0m
2021/06/10 13:51:47 packer-builder-googlecompute plugin: [INFO] command 'powershell -executionpolicy bypass "& { if (Test-Path variable:global:ProgressPreference){set-variable -name variable:global:ProgressPreference -value 'SilentlyContinue'};. c:/Windows/Temp/packer-ps-env-vars-60c20970-54f5-65ed-f94f-39b6dd2c7d22.ps1; &'c:/Windows/Temp/script-60c20970-a6d3-66a9-3773-07edb3eb850a.ps1'; exit $LastExitCode }"' exited with code: 5
2021/06/10 13:51:47 packer-builder-googlecompute plugin: [INFO] RPC endpoint: Communicator ended with: 5
2021/06/10 13:51:47 [INFO] 27483 bytes written for 'stdout'
2021/06/10 13:51:47 [INFO] 0 bytes written for 'stderr'
2021/06/10 13:51:47 [INFO] RPC client: Communicator ended with: 5
2021/06/10 13:51:47 [INFO] RPC endpoint: Communicator ended with: 5
2021/06/10 13:51:47 ui: ^[[0;32m    googlecompute: Preparing: C:\Users\packer_user\AppData\Local\Temp\chocolatey\6496e8039e91919d341e7cb615\vs_bootstrapper_d15\HelpFile\1028\help.html...^[[0m
2021/06/10 13:51:47 packer-provisioner-powershell plugin: [INFO] 0 bytes written for 'stderr'
2021/06/10 13:51:47 ui: ^[[0;32m    googlecompute:^[[0m
2021/06/10 13:51:47 ui: ^[[0;32m    googlecompute: Preparing: C:\Users\packer_user\AppData\Local\Temp\chocolatey\6496e8039e91919d341e7cb615\vs_bootstrapper_d15\HelpFile\2052\help.html...^[[0m
...
2021/06/10 13:51:47 ui: ^[[0;32m    googlecompute: Preparing: C:\Users\packer_user\AppData\Local\Temp\chocolatey\6496e8039e91919d341e7cb615\vs_bootstrapper_d15\detection.json...^[[0m
2021/06/10 13:51:47 ui: ^[[0;32m    googlecompute:^[[0m
2021/06/10 13:51:47 ui: ^[[0;32m    googlecompute: Preparing: C:\Users\packer_user\AppData\Local\Temp\chocolatey\6496e8039e91919d341e7cb615\vs_bootstrapper_d15\vs_setup_bootstrapper.json...^[[0m
2021/06/10 13:51:47 ui: ^[[0;32m    googlecompute:^[[0m
2021/06/10 13:51:47 ui: ^[[0;32m    googlecompute:^[[0m
2021/06/10 13:51:47 ui: ^[[0;32m    googlecompute: visualstudio2019buildtools has been installed.^[[0m
2021/06/10 13:51:47 ui: ^[[0;32m    googlecompute:   visualstudio2019buildtools may be able to be automatically uninstalled.^[[0m
2021/06/10 13:51:47 ui: ^[[0;32m    googlecompute:  The install of visualstudio2019buildtools was successful.^[[0m
2021/06/10 13:51:47 ui: ^[[0;32m    googlecompute:   Software installed to '"C:\Program Files (x86)\Microsoft Visual Studio\Installer"'^[[0m
...
2021/06/10 13:51:47 ui: ^[[0;32m    googlecompute: Installed:^[[0m
2021/06/10 13:51:47 ui: ^[[0;32m    googlecompute:  - kb2919355 v1.0.20160915^[[0m
2021/06/10 13:51:47 ui: ^[[0;32m    googlecompute:  - dotnetfx v4.8.0.20190930^[[0m
2021/06/10 13:51:47 ui: ^[[0;32m    googlecompute:  - chocolatey-visualstudio.extension v1.9.0^[[0m
2021/06/10 13:51:47 ui: ^[[0;32m    googlecompute:  - visualstudio2019buildtools v16.9.5.0^[[0m
2021/06/10 13:51:47 ui: ^[[0;32m    googlecompute:  - visualstudio-installer v2.0.1^[[0m
2021/06/10 13:51:47 ui: ^[[0;32m    googlecompute:  - chocolatey-dotnetfx.extension v1.0.1^[[0m
2021/06/10 13:51:47 ui: ^[[0;32m    googlecompute:  - chocolatey-windowsupdate.extension v1.0.4^[[0m

Compared to the unsuccessful installation, we get confirmation that visualstudio2019buildtools has been installed.

The logs from the unsuccessful installation seem to stop suddenly, without recording a success/failure. I wonder if there is a timeout, or if the process is being moved into the background / killed?

Looking at the chocolatey logs:

Unsuccessful install

2021-06-10 11:36:37,457 6076 [DEBUG] - Command line: "C:\ProgramData\chocolatey\choco.exe" install visualstudio2019buildtools --version=16.9.5.0 --package-parameters "--add Microsoft.VisualStudio.Component.VC.Tools.x86.x64 --add Microsoft.VisualStudio.Component.Windows10SDK.19041"^[[0m
...
2021-06-10 11:37:06,014 6076 [DEBUG] - Running 'Start-VSChocolateyProcessAsAdmin' with exeToRun:'C:\Users\packer_user\AppData\Local\Temp\chocolatey\visualstudio2019buildtools\16.9.5.0\vs_BuildTools.exe', statements:'--quiet --add Microsoft.VisualStudio.Component.VC.Tools.x86.x64 --add Microsoft.VisualStudio.Component.Windows10SDK.19041 --norestart --wait', minimized:False, noSleep:False, validExitCodes:'0 3010 -2147185721 -2147205120 -2147172352'^[[0m
plugin: [INFO] RPC client: Communicator ended with: 0
2021-06-10 11:37:06,014 6076 [DEBUG] - Elevating Permissions and running ["C:\Users\packer_user\AppData\Local\Temp\chocolatey\visualstudio2019buildtools\16.9.5.0\vs_BuildTools.exe" --quiet --add Microsoft.VisualStudio.Component.VC.Tools.x86.x64 --add Microsoft.VisualStudio.Component.Windows10SDK.19041 --norestart --wait]. This may take a while, depending on the statements.^[[0m
2021-06-10 11:37:06,045 6076 [DEBUG] - Setting RunAs for elevation^[[0m

^^ that last line is the end of the log.

Successful install

2021-06-10 12:48:36,053 3292 [DEBUG] - Command line: "C:\ProgramData\chocolatey\choco.exe" install visualstudio2019buildtools --version=16.9.5.0 --package-parameters "--add Microsoft.VisualStudio.Component.VC.Tools.x86.x64 --add Microsoft.VisualStudio.Component.Windows10SDK.19041"^[[0m
...
2021-06-10 12:49:15,646 3292 [DEBUG] - Running 'Start-VSChocolateyProcessAsAdmin' with exeToRun:'C:\Users\packer_user\AppData\Local\Temp\chocolatey\visualstudio2019buildtools\16.9.5.0\vs_BuildTools.exe', statements:'--quiet --add Microsoft.VisualStudio.Component.VC.Tools.x86.x64 --add Microsoft.VisualStudio.Component.Windows10SDK.19041 --norestart --wait', minimized:False, noSleep:False, validExitCodes:'0 3010 -2147185721 -2147205120 -2147172352'^[[0m
2021-06-10 12:49:15,646 3292 [DEBUG] - Elevating Permissions and running ["C:\Users\packer_user\AppData\Local\Temp\chocolatey\visualstudio2019buildtools\16.9.5.0\vs_BuildTools.exe" --quiet --add Microsoft.VisualStudio.Component.VC.Tools.x86.x64 --add Microsoft.VisualStudio.Component.Windows10SDK.19041 --norestart --wait]. This may take a while, depending on the statements.^[[0m
2021-06-10 12:49:15,662 3292 [DEBUG] - Setting RunAs for elevation^[[0m
2021-06-10 12:51:46,334 3292 [INFO ] - Preparing: C:\Users\packer_user\AppData\Local\Temp\chocolatey\6496e8039e91919d341e7cb615\vs_bootstrapper_d15\HelpFile\1028\help.html...^[[0m
2021-06-10 12:51:46,334 3292 [INFO ] -^[[0m
2021-06-10 12:51:46,334 3292 [INFO ] - Preparing: C:\Users\packer_user\AppData\Local\Temp\chocolatey\6496e8039e91919d341e7cb615\vs_bootstrapper_d15\HelpFile\2052\help.html...^[[0m
...
2021-06-10 12:51:46,396 3292 [INFO ] - Preparing: C:\Users\packer_user\AppData\Local\Temp\chocolatey\6496e8039e91919d341e7cb615\vs_bootstrapper_d15\vs_setup_bootstrapper.json...^[[0m
2021-06-10 12:51:46,396 3292 [INFO ] -^[[0m
2021-06-10 12:51:46,396 3292 [INFO ] -^[[0m
2021-06-10 12:51:46,412 3292 [DEBUG] - Command ["C:\Users\packer_user\AppData\Local\Temp\chocolatey\visualstudio2019buildtools\16.9.5.0\vs_BuildTools.exe" --quiet --add Microsoft.VisualStudio.Component.VC.Tools.x86.x64 --add Microsoft.VisualStudio.Component.Windows10SDK.19041 --norestart --wait] exited with '0'.^[[0m
2021-06-10 12:51:46,412 3292 [DEBUG] - Finishing 'Start-VSChocolateyProcessAsAdmin'^[[0m
2021-06-10 12:51:46,412 3292 [DEBUG] - Exit code returned from Start-VSChocolateyProcessAsAdmin: '0'^[[0m
2021-06-10 12:51:46,412 3292 [DEBUG] - [20210610 12:51:46.412] Looking for still running VS installer processes^[[0m
2021-06-10 12:51:46,412 3292 [DEBUG] - Did not find any running VS installer processes.^[[0m
2021-06-10 12:51:46,412 3292 [DEBUG] - [20210610 12:51:46.412] Looking for vs_installer.windows.exe processes spawned by the uninstaller^[[0m
2021-06-10 12:51:46,428 3292 [DEBUG] - Did not find any running vs_installer.windows.exe processes.^[[0m
2021-06-10 12:51:46,428 3292 [DEBUG] - Setting Env:ChocolateyExitCode to '0'^[[0m
2021-06-10 12:51:46,428 3292 [INFO ] - visualstudio2019buildtools has been installed.^[[0m
2021-06-10 12:51:46,428 3292 [INFO ] - VERBOSE: No ISO to dismount^[[0m
2021-06-10 12:51:46,443 3292 [DEBUG] - ----------------------------------------------------------------------^[[0m
2021-06-10 12:51:46,443 3292 [DEBUG] - Built-in PowerShell host called with ['[System.Threading.Thread]::CurrentThread.CurrentCulture = '';[System.Threading.Thread]::CurrentThread.CurrentUICulture = ''; & import-module -name 'C:\ProgramData\chocolatey\helpers\chocolateyInstaller.psm1'; & 'C:\ProgramData\chocolatey\helpers\chocolateyScriptRunner.ps1' -packageScript 'C:\ProgramData\chocolatey\lib\visualstudio2019buildtools\tools\ChocolateyInstall.ps1' -installArguments '' -packageParameters '--add Microsoft.VisualStudio.Component.VC.Tools.x86.x64 --add Microsoft.VisualStudio.Component.Windows10SDK.19041''] exited with '0'.^[[0m

I can post detailed steps to reproduce, if required.

jberezanski commented 3 years ago

Thank you for the very detailed description.

Please be aware that there have been issues with the Windows 10 SDK component recently, caused by errors on Microsoft side (https://github.com/jberezanski/ChocolateyPackages/issues/107). The symptoms were different than in your case (the component simply failed to install with a clear error message), but still, you can try again now that the issue has been fixed.

Assuming your problem does not go away, I'd like to ask you to provide the (numerous) log files generated by the Visual Studio Installer: $Env:TEMP\chocolatey\dd_*.log. They usually are enough to identify any problems with the VS install itself.

The fact that the Chocolatey log just stops is curious indeed and yes, it may indicate that choco.exe is being killed by something. Now, I'm not familiar with Packer, but perhaps by correlating the VS installer logs with the Packer log it might be possible to determine whether the VS installer continues running in the background.

choco install visualstudio2019buildtools && choco install visualstudio2019-workload-vctools

I noticed you do not pass -y to choco.exe, which is normally required on a default install. I assume you are enabling the allowGlobalConfirmation feature - is that correct? What other Chocolatey settings do you change from the default values?

I wonder if there is a timeout

Yes, there is:

     --timeout, --execution-timeout=VALUE
     CommandExecutionTimeout (in seconds) - The time to allow a command to
       finish before timing out. Overrides the default execution timeout in the
       configuration of 2700 seconds. '0' for infinite starting in 0.10.4.

I believe, however, that choco.exe would write a clear message if the timeout was reached (but I'm not 100% sure about it). You can try with some large value, such as --execution-timeout 108000 (it's what I use on slow virtual machines when testing the VS packages).

robinbanbury commented 3 years ago

Thanks for your excellent suggestions. I'll try using the high timeout value today, and collect the log files you requested.

Sadly, the Windows 10 SDK fix released by Microsoft doesn't appear to have fixed the problem for me.

I assume you are enabling the allowGlobalConfirmation feature - is that correct?

Yes, using choco feature enable -n=allowGlobalConfirmation

robinbanbury commented 3 years ago

Using a long timeout didn't help either...

I've collected the content of the dd_*.log files. There's a lot of output (140+ files, ~50k lines), which I can send if required. I've analysed the log files and added my thoughts below.

The last line of logging from Packer regarding the Visual Studio Build Tools installation is the following:

2021/06/15 08:47:10 ui: ^[[0;32m    googlecompute: Download of vs_BuildTools.exe (1.4 MB) completed.^[[0m
2021/06/15 08:47:13 ui: ^[[0;32m    googlecompute: Hashes match.^[[0m
2021/06/15 08:47:13 ui: ^[[0;32m    googlecompute: Installing visualstudio2019buildtools...^[[0m
2021/06/15 08:47:13 ui: ^[[0;32m    googlecompute:^[[0m
2021/06/15 08:51:50 packer-builder-googlecompute plugin: [INFO] command 'powershell -executionpolicy bypass "& { if (Test-Path variable:global:ProgressPreference){set-variable -name variable:global:ProgressPreference -value 'SilentlyContinue'};. c:/Windows/Temp/packer-ps-env-vars-60c86862-8b03-6abb-1fcd-4ddb9cbacce3.ps1; &'c:/Windows/Temp/script-60c86862-be9b-e6af-3d50-42c429c5013c.ps1'; exit $LastExitCode }"' exited with code: 0
2021/06/15 08:51:50 packer-builder-googlecompute plugin: [INFO] RPC endpoint: Communicator ended with: 0

The last logs I can see in any of the dd_*.log files is at 08:48:40, in the following files:

dd_installer_elevated_20210615084732.log.FullName:

Content of file dd_installer_elevated_20210615084732.log.FullName^[[0m
[0e98:0001][2021-06-15T08:47:32] Setting the locale to en-US.^[[0m
[0e98:0001][2021-06-15T08:47:32] Saving the current locale (en-US) to user.json.^[[0m
[0e98:0001][2021-06-15T08:47:32] Setting the telemetry services^[[0m
[0e98:0003][2021-06-15T08:47:32] Creating a new telemetry service based on the previously serialized session.^[[0m
[0e98:0001][2021-06-15T08:47:32] Visual Studio Installer Version: 2.10.2174^[[0m
[0e98:0001][2021-06-15T08:47:32] Raw Command line: "C:\Program Files (x86)\Microsoft Visual Studio\Installer\setup.exe" elevate --activityId 46df08b0-03f2-4684-8f25-88175f760256 --locale en-US --pid 1640 --pipeName 522c11a6998d4d95926e7f2e4003cd42 --pipeSecret f404fc004065483597922146e1a4d057 --serializedSession "{\"IsOptedIn\":true,\"HostName\":\"Default\",\"AppInsightsInstrumentationKey\":\"f144292e-e3b2-4011-ac90-20e5c03fbce5\",\"AsimovInstrumentationKey\":\"AIF-312cbd79-9dbb-4c48-a7da-3cc2a931cb70\",\"AppId\":1000,\"UserId\":\"4c97eeb1-35ed-4a64-b65b-70dcaed57ae3\",\"Id\":\"1e7ce905-03fc-439e-8470-3402261edd6c\",\"ProcessStartTime\":637593436453999712}"^[[0m
[0e98:0001][2021-06-15T08:47:33] Parsed command line options: elevate --activityId 46df08b0-03f2-4684-8f25-88175f760256 --locale en-US --pid 1640 --pipeName 522c11a6998d4d95926e7f2e4003cd42 --pipeSecret f404fc004065483597922146e1a4d057 --serializedSession "{\"IsOptedIn\":true,\"HostName\":\"Default\",\"AppInsightsInstrumentationKey\":\"f144292e-e3b2-4011-ac90-20e5c03fbce5\",\"AsimovInstrumentationKey\":\"AIF-312cbd79-9dbb-4c48-a7da-3cc2a931cb70\",\"AppId\":1000,\"UserId\":\"4c97eeb1-35ed-4a64-b65b-70dcaed57ae3\",\"Id\":\"1e7ce905-03fc-439e-8470-3402261edd6c\",\"ProcessStartTime\":637593436453999712}"^[[0m

...

[0e98:0006][2021-06-15T08:48:40] BEGIN: Caching package 'Microsoft.VisualStudio.Component.VC.Tools.x86.x64,version=16.10.31205.252'^[[0m
[0e98:0006][2021-06-15T08:48:40] END: Caching package 'Microsoft.VisualStudio.Component.VC.Tools.x86.x64,version=16.10.31205.252'^[[0m
[0e98:0006][2021-06-15T08:48:40] BEGIN: Installing "Win10SDK_10.0.19041,version=10.0.19041.1"^[[0m
[0e98:0006][2021-06-15T08:48:40] Started: Installing Win10SDK_10.0.19041^[[0m
[0e98:0006][2021-06-15T08:48:40] Package Log: C:\Users\packer_user\AppData\Local\Temp\chocolatey\dd_setup_20210615084733_128_Win10SDK_10.0.19041.log^[[0m
[0e98:0006][2021-06-15T08:48:40] Executing: "C:\ProgramData\Microsoft\VisualStudio\Packages\Win10SDK_10.0.19041,version=10.0.19041.1\winsdkinstaller.exe" SetupExe=winsdksetup.exe LogFile="C:\Users\packer_user\AppData\Local\Temp\chocolatey\dd_setup_20210615084733_128_Win10SDK_10.0.19041.log" SetupLogFolder=windowssdk CeipSetting="on" ProgramFilesOrSharedDriveSdkPath="C:\Program Files (x86)\Windows Kits\10" SetupParameters="/features OptionId.AvrfExternal OptionId.UWPManaged OptionId.WindowsSoftwareLogoToolkit OptionId.SigningTools OptionId.UWPLocalized OptionId.UWPCPP OptionId.DesktopCPPx64 OptionId.DesktopCPPx86 OptionID.DesktopCPPARM OptionID.DesktopCPPARM64 OptionId.MSIInstallTools /quiet /norestart"^[[0m

dd_setup_20210615084733.log.FullName:

[0e98:000d][2021-06-15T08:47:33] Setup Engine v2.10.2174, Microsoft Windows NT 10.0.17763.0^[[0m
[0e98:000d][2021-06-15T08:47:33] Command line: "C:\Program Files (x86)\Microsoft Visual Studio\Installer\setup.exe" elevate --activityId 46df08b0-03f2-4684-8f25-88175f760256 --locale en-US --pid 1640 --pipeName 522c11a6998d4d95926e7f2e4003cd42 --pipeSecret f404fc004065483597922146e1a4d057 --serializedSession "{\"IsOptedIn\":true,\"HostName\":\"Default\",\"AppInsightsInstrumentationKey\":\"f144292e-e3b2-4011-ac90-20e5c03fbce5\",\"AsimovInstrumentationKey\":\"AIF-312cbd79-9dbb-4c48-a7da-3cc2a931cb70\",\"AppId\":1000,\"UserId\":\"4c97eeb1-35ed-4a64-b65b-70dcaed57ae3\",\"Id\":\"1e7ce905-03fc-439e-8470-3402261edd6c\",\"ProcessStartTime\":637593436453999712}"^[[0m

...

[0e98:0006][2021-06-15T08:48:40] BEGIN: Caching package 'Microsoft.VisualStudio.Component.VC.Tools.x86.x64,version=16.10.31205.252'^[[0m
[0e98:0006][2021-06-15T08:48:40] END: Caching package 'Microsoft.VisualStudio.Component.VC.Tools.x86.x64,version=16.10.31205.252'^[[0m
[0e98:0006][2021-06-15T08:48:40] BEGIN: Installing "Win10SDK_10.0.19041,version=10.0.19041.1"^[[0m
[0e98:0006][2021-06-15T08:48:40] Started: Installing Win10SDK_10.0.19041^[[0m
[0e98:0006][2021-06-15T08:48:40] Package Log: C:\Users\packer_user\AppData\Local\Temp\chocolatey\dd_setup_20210615084733_128_Win10SDK_10.0.19041.log^[[0m
[0e98:0006][2021-06-15T08:48:40] Executing: "C:\ProgramData\Microsoft\VisualStudio\Packages\Win10SDK_10.0.19041,version=10.0.19041.1\winsdkinstaller.exe" SetupExe=winsdksetup.exe LogFile="C:\Users\packer_user\AppData\Local\Temp\chocolatey\dd_setup_20210615084733_128_Win10SDK_10.0.19041.log" SetupLogFolder=windowssdk CeipSetting="on" ProgramFilesOrSharedDriveSdkPath="C:\Program Files (x86)\Windows Kits\10" SetupParameters="/features OptionId.AvrfExternal OptionId.UWPManaged OptionId.WindowsSoftwareLogoToolkit OptionId.SigningTools OptionId.UWPLocalized OptionId.UWPCPP OptionId.DesktopCPPx64 OptionId.DesktopCPPx86 OptionID.DesktopCPPARM OptionID.DesktopCPPARM64 OptionId.MSIInstallTools /quiet /norestart"^[[0m

dd_setup_20210615084733_125_Microsoft.VC.14.29.16.10.CodeAnalysis.Extensions.X86.base.log.FullName:

[0e98:0006][2021-06-15T08:48:40] Opening the package for processing^[[0m
[0e98:0006][2021-06-15T08:48:40] Using package payload to get the embedded manifest^[[0m
[0e98:0006][2021-06-15T08:48:40] Getting list of files within the package^[[0m
[0e98:0006][2021-06-15T08:48:40] Processing file /Contents/VC/Tools/MSVC/14.29.30037/bin/HostX86/x86/cfgpersist.dll^[[0m
[0e98:0006][2021-06-15T08:48:40] Started: Copying file /Contents/VC/Tools/MSVC/14.29.30037/bin/HostX86/x86/cfgpersist.dll to C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Tools\MSVC\14.29.30037\bin\HostX86\x86\cfgpersist.dll^[[0m
[0e98:0006][2021-06-15T08:48:40] Completed: Copying file /Contents/VC/Tools/MSVC/14.29.30037/bin/HostX86/x86/cfgpersist.dll to C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Tools\MSVC\14.29.30037\bin\HostX86\x86\cfgpersist.dll^[[0m
[0e98:0006][2021-06-15T08:48:40] Timestamp updated for file 'C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Tools\MSVC\14.29.30037\bin\HostX86\x86\cfgpersist.dll'.^[[0m
[0e98:0006][2021-06-15T08:48:40] Processing file /Contents/VC/Tools/MSVC/14.29.30037/bin/HostX86/x86/ConcurrencyCheck.dll^[[0m
[0e98:0006][2021-06-15T08:48:40] Started: Copying file /Contents/VC/Tools/MSVC/14.29.30037/bin/HostX86/x86/ConcurrencyCheck.dll to C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Tools\MSVC\14.29.30037\bin\HostX86\x86\ConcurrencyCheck.dll^[[0m
[0e98:0006][2021-06-15T08:48:40] Completed: Copying file /Contents/VC/Tools/MSVC/14.29.30037/bin/HostX86/x86/ConcurrencyCheck.dll to C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Tools\MSVC\14.29.30037\bin\HostX86\x86\ConcurrencyCheck.dll^[[0m
[0e98:0006][2021-06-15T08:48:40] Timestamp updated for file 'C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Tools\MSVC\14.29.30037\bin\HostX86\x86\ConcurrencyCheck.dll'.^[[0m
[0e98:0006][2021-06-15T08:48:40] Processing file /Contents/VC/Tools/MSVC/14.29.30037/bin/HostX86/x86/CppCoreCheck.dll^[[0m
[0e98:0006][2021-06-15T08:48:40] Started: Copying file /Contents/VC/Tools/MSVC/14.29.30037/bin/HostX86/x86/CppCoreCheck.dll to C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Tools\MSVC\14.29.30037\bin\HostX86\x86\CppCoreCheck.dll^[[0m
[0e98:0006][2021-06-15T08:48:40] Completed: Copying file /Contents/VC/Tools/MSVC/14.29.30037/bin/HostX86/x86/CppCoreCheck.dll to C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Tools\MSVC\14.29.30037\bin\HostX86\x86\CppCoreCheck.dll^[[0m
[0e98:0006][2021-06-15T08:48:40] Timestamp updated for file 'C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Tools\MSVC\14.29.30037\bin\HostX86\x86\CppCoreCheck.dll'.^[[0m
[0e98:0006][2021-06-15T08:48:40] Processing file /Contents/VC/Tools/MSVC/14.29.30037/bin/HostX86/x86/EnumIndex.dll^[[0m
[0e98:0006][2021-06-15T08:48:40] Started: Copying file /Contents/VC/Tools/MSVC/14.29.30037/bin/HostX86/x86/EnumIndex.dll to C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Tools\MSVC\14.29.30037\bin\HostX86\x86\EnumIndex.dll^[[0m
[0e98:0006][2021-06-15T08:48:40] Completed: Copying file /Contents/VC/Tools/MSVC/14.29.30037/bin/HostX86/x86/EnumIndex.dll to C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Tools\MSVC\14.29.30037\bin\HostX86\x86\EnumIndex.dll^[[0m
[0e98:0006][2021-06-15T08:48:40] Timestamp updated for file 'C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Tools\MSVC\14.29.30037\bin\HostX86\x86\EnumIndex.dll'.^[[0m
[0e98:0006][2021-06-15T08:48:40] Processing file /Contents/VC/Tools/MSVC/14.29.30037/bin/HostX86/x86/EspXEngine.dll^[[0m
[0e98:0006][2021-06-15T08:48:40] Started: Copying file /Contents/VC/Tools/MSVC/14.29.30037/bin/HostX86/x86/EspXEngine.dll to C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Tools\MSVC\14.29.30037\bin\HostX86\x86\EspXEngine.dll^[[0m
[0e98:0006][2021-06-15T08:48:40] Completed: Copying file /Contents/VC/Tools/MSVC/14.29.30037/bin/HostX86/x86/EspXEngine.dll to C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Tools\MSVC\14.29.30037\bin\HostX86\x86\EspXEngine.dll^[[0m
[0e98:0006][2021-06-15T08:48:40] Timestamp updated for file 'C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Tools\MSVC\14.29.30037\bin\HostX86\x86\EspXEngine.dll'.^[[0m
[0e98:0006][2021-06-15T08:48:40] Processing file /Contents/VC/Tools/MSVC/14.29.30037/bin/HostX86/x86/HResultCheck.dll^[[0m
[0e98:0006][2021-06-15T08:48:40] Started: Copying file /Contents/VC/Tools/MSVC/14.29.30037/bin/HostX86/x86/HResultCheck.dll to C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Tools\MSVC\14.29.30037\bin\HostX86\x86\HResultCheck.dll^[[0m
[0e98:0006][2021-06-15T08:48:40] Completed: Copying file /Contents/VC/Tools/MSVC/14.29.30037/bin/HostX86/x86/HResultCheck.dll to C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Tools\MSVC\14.29.30037\bin\HostX86\x86\HResultCheck.dll^[[0m
[0e98:0006][2021-06-15T08:48:40] Timestamp updated for file 'C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Tools\MSVC\14.29.30037\bin\HostX86\x86\HResultCheck.dll'.^[[0m
[0e98:0006][2021-06-15T08:48:40] Processing file /Contents/VC/Tools/MSVC/14.29.30037/bin/HostX86/x86/VariantClear.dll^[[0m
[0e98:0006][2021-06-15T08:48:40] Started: Copying file /Contents/VC/Tools/MSVC/14.29.30037/bin/HostX86/x86/VariantClear.dll to C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Tools\MSVC\14.29.30037\bin\HostX86\x86\VariantClear.dll^[[0m
[0e98:0006][2021-06-15T08:48:40] Completed: Copying file /Contents/VC/Tools/MSVC/14.29.30037/bin/HostX86/x86/VariantClear.dll to C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Tools\MSVC\14.29.30037\bin\HostX86\x86\VariantClear.dll^[[0m
[0e98:0006][2021-06-15T08:48:40] Timestamp updated for file 'C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Tools\MSVC\14.29.30037\bin\HostX86\x86\VariantClear.dll'.^[[0m
[0e98:0006][2021-06-15T08:48:40] Package executed successfully. Return code: 0^[[0m

dd_setup_20210615084733_126_Microsoft.VisualCpp.CodeAnalysis.Extensions.X86.log.FullName:

[0e98:0006][2021-06-15T08:48:40] Opening the package for processing^[[0m
[0e98:0006][2021-06-15T08:48:40] Using package payload to get the embedded manifest^[[0m
[0e98:0006][2021-06-15T08:48:40] Getting list of files within the package^[[0m
[0e98:0006][2021-06-15T08:48:40] Package executed successfully. Return code: 0^[[0m

dd_setup_20210615084733_127_Microsoft.VisualCpp.CodeAnalysis.Extensions.log.FullName:

[0e98:0006][2021-06-15T08:48:40] Opening the package for processing^[[0m
[0e98:0006][2021-06-15T08:48:40] Using package payload to get the embedded manifest^[[0m
[0e98:0006][2021-06-15T08:48:40] Getting list of files within the package^[[0m
[0e98:0006][2021-06-15T08:48:40] Processing file /Contents/Msbuild/Microsoft/VC/v160/Microsoft.CodeAnalysis.Extensions.props^[[0m
[0e98:0006][2021-06-15T08:48:40] Started: Copying file /Contents/Msbuild/Microsoft/VC/v160/Microsoft.CodeAnalysis.Extensions.props to C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\Msbuild\Microsoft\VC\v160\Microsoft.CodeAnalysis.Extensions.props^[[0m
[0e98:0006][2021-06-15T08:48:40] Completed: Copying file /Contents/Msbuild/Microsoft/VC/v160/Microsoft.CodeAnalysis.Extensions.props to C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\Msbuild\Microsoft\VC\v160\Microsoft.CodeAnalysis.Extensions.props^[[0m
[0e98:0006][2021-06-15T08:48:40] Timestamp updated for file 'C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\Msbuild\Microsoft\VC\v160\Microsoft.CodeAnalysis.Extensions.props'.^[[0m
[0e98:0006][2021-06-15T08:48:40] Processing file /Contents/Msbuild/Microsoft/VC/v160/Microsoft.CodeAnalysis.Extensions.targets^[[0m
[0e98:0006][2021-06-15T08:48:40] Started: Copying file /Contents/Msbuild/Microsoft/VC/v160/Microsoft.CodeAnalysis.Extensions.targets to C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\Msbuild\Microsoft\VC\v160\Microsoft.CodeAnalysis.Extensions.targets^[[0m
[0e98:0006][2021-06-15T08:48:40] Completed: Copying file /Contents/Msbuild/Microsoft/VC/v160/Microsoft.CodeAnalysis.Extensions.targets to C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\Msbuild\Microsoft\VC\v160\Microsoft.CodeAnalysis.Extensions.targets^[[0m
[0e98:0006][2021-06-15T08:48:40] Timestamp updated for file 'C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\Msbuild\Microsoft\VC\v160\Microsoft.CodeAnalysis.Extensions.targets'.^[[0m
[0e98:0006][2021-06-15T08:48:40] Processing file /Contents/VC/Auxiliary/VS/include/CppCoreCheck/warnings.h^[[0m
[0e98:0006][2021-06-15T08:48:40] Started: Copying file /Contents/VC/Auxiliary/VS/include/CppCoreCheck/warnings.h to C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Auxiliary\VS\include\CppCoreCheck\warnings.h^[[0m
[0e98:0006][2021-06-15T08:48:40] Completed: Copying file /Contents/VC/Auxiliary/VS/include/CppCoreCheck/warnings.h to C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Auxiliary\VS\include\CppCoreCheck\warnings.h^[[0m
[0e98:0006][2021-06-15T08:48:40] Timestamp updated for file 'C:\Program Files (x86)\Microsoft Visual Studio\2019\BuildTools\VC\Auxiliary\VS\include\CppCoreCheck\warnings.h'.^[[0m
[0e98:0006][2021-06-15T08:48:40] Package executed successfully. Return code: 0^[[0m

dd_setup_20210615084733_128_Win10SDK_10.0.19041.log.FullName:

[6/15/2021 08:48:40] Launching 'C:\ProgramData\Microsoft\VisualStudio\Packages\Win10SDK_10.0.19041,version=10.0.19041.1\winsdksetup.exe' with arguments '/features OptionId.AvrfExternal OptionId.UWPManaged OptionId.WindowsSoftwareLogoToolkit OptionId.SigningTools OptionId.UWPLocalized OptionId.UWPCPP OptionId.DesktopCPPx64 OptionId.DesktopCPPx86 OptionID.DesktopCPPARM OptionID.DesktopCPPARM64 OptionId.MSIInstallTools /quiet /norestart /Ceip on /InstallPath "C:\Program Files (x86)\Windows Kits\10" /Log "C:\Users\packer_user\AppData\Local\Temp\chocolatey\windowssdk\dd_setup_20210615084733_128_Win10SDK_10.0.19041.winsdksetup_1.log"'.^[[0m
[6/15/2021 08:48:40] Waiting for child process to terminate...^[[0m

So again it seems the problem is with the Windows 10 SDK installation, and no logging for several minutes before the silent failure. The other components seem to install ok - the log files look like they return an exit code of 0, a few are are 'waiting for setup process to complete'.

Is that helpful?

jberezanski commented 1 year ago

I think whatever the problem was with the Windows 10 SDK installer, it has since been resolved. The workload installs successfully, at least on machines I manage.