2021-10-21T11:18:45.0930458Z ##[section]Starting: Build VM 2021-10-21T11:18:45.1125359Z ============================================================================== 2021-10-21T11:18:45.1125654Z Task : PowerShell 2021-10-21T11:18:45.1125901Z Description : Run a PowerShell script on Linux, macOS, or Windows 2021-10-21T11:18:45.1126122Z Version : 2.190.0 2021-10-21T11:18:45.1126317Z Author : Microsoft Corporation 2021-10-21T11:18:45.1126610Z Help : https://docs.microsoft.com/azure/devops/pipelines/tasks/utility/powershell 2021-10-21T11:18:45.1126950Z ============================================================================== 2021-10-21T11:18:46.1655055Z Generating script. 2021-10-21T11:18:46.1723622Z Formatted command: . 'D:\a\1\s\YAML Packer Pipeline\scripts\build-image.ps1' -TemplatePath virtual-environments/images/win/windows2019.json -ClientId 85a1aee5-9cd9-4610-aa51-7b186e581f74 -ClientSecret *** -GitHubFeedToken *** -ResourcesNamePrefix 12132 -Location "East US 2" -ResourceGroup rg_kovaselfhostedmsagents -StorageAccount mspackerimage -SubscriptionId 0e387b95-3ed8-4362-a2f9-8a6deede44d5 -TenantId 8ed8e78c-ee9d-4ce6-b60e-7787131de9a6 2021-10-21T11:18:46.2250428Z ========================== Starting Command Output =========================== 2021-10-21T11:18:46.2537538Z ##[command]"C:\Windows\System32\WindowsPowerShell\v1.0\powershell.exe" -NoLogo -NoProfile -NonInteractive -ExecutionPolicy Unrestricted -Command ". 'D:\a\_temp\d271738d-4f56-490f-8010-3ab3fd214dc8.ps1'" 2021-10-21T11:18:46.5615898Z 2021-10-21T11:18:46.5643574Z Id Name PSJobTypeName State HasMoreData Location Command 2021-10-21T11:18:46.5661212Z -- ---- ------------- ----- ----------- -------- ------- 2021-10-21T11:18:46.5687204Z 1 e36312d7-438... NotStarted False ... 2021-10-21T11:18:47.4790280Z Syntax-only check passed. Everything looks okay. 2021-10-21T11:18:47.7637184Z Show Packer Version 2021-10-21T11:18:48.3662079Z 1.7.2 2021-10-21T11:18:48.6322570Z Build windows2019 VM 2021-10-21T11:18:56.1843874Z vhd: output will be in this color. 2021-10-21T11:18:56.1854380Z 2021-10-21T11:18:56.1864435Z ==> vhd: Running builder ... 2021-10-21T11:18:56.1878742Z ==> vhd: Getting tokens using client secret 2021-10-21T11:18:56.3558340Z ==> vhd: Getting tokens using client secret 2021-10-21T11:18:56.4338134Z vhd: Creating Azure Resource Manager (ARM) client ... 2021-10-21T11:18:56.6150614Z ==> vhd: Warning: You are using Azure Packer Builder to create VHDs which is being deprecated, consider using Managed Images. Learn more https://www.packer.io/docs/builders/azure/arm#azure-arm-builder-specific-options 2021-10-21T11:18:56.6160435Z ==> vhd: WARNING: Zone resiliency may not be supported in East US 2, checkout the docs at https://docs.microsoft.com/en-us/azure/availability-zones/ 2021-10-21T11:18:56.7487293Z ==> vhd: Creating resource group ... 2021-10-21T11:18:57.3959664Z ==> vhd: Validating deployment template ... 2021-10-21T11:18:58.5162602Z ==> vhd: Deploying deployment template ... 2021-10-21T11:20:00.0245871Z ==> vhd: 2021-10-21T11:20:00.0254707Z ==> vhd: Getting the certificate's URL ... 2021-10-21T11:20:00.5389730Z ==> vhd: Setting the certificate's URL ... 2021-10-21T11:20:00.5399483Z ==> vhd: Validating deployment template ... 2021-10-21T11:20:01.2854612Z ==> vhd: Deploying deployment template ... 2021-10-21T11:22:02.6161580Z ==> vhd: 2021-10-21T11:22:02.6171880Z ==> vhd: Getting the VM's IP address ... 2021-10-21T11:22:02.7388796Z ==> vhd: Waiting for WinRM to become available... 2021-10-21T11:22:13.3408539Z vhd: WinRM connected. 2021-10-21T11:22:13.3418511Z ==> vhd: Connected to WinRM! 2021-10-21T11:22:13.3458161Z ==> vhd: Provisioning with Powershell... 2021-10-21T11:22:13.3474460Z ==> vhd: Provisioning with powershell script: C:\Users\VSSADM~1\AppData\Local\Temp\powershell-provisioner195763071 2021-10-21T11:22:17.5197217Z vhd: 2021-10-21T11:22:17.5209848Z vhd: 2021-10-21T11:22:17.5225982Z vhd: Directory: C:\ 2021-10-21T11:22:17.5236413Z vhd: 2021-10-21T11:22:17.5244947Z vhd: 2021-10-21T11:22:17.5253122Z vhd: Mode LastWriteTime Length Name 2021-10-21T11:22:17.5271085Z vhd: ---- ------------- ------ ---- 2021-10-21T11:22:17.5304127Z vhd: d----- 10/21/2021 11:22 AM image 2021-10-21T11:22:19.8846439Z ==> vhd: Uploading D:\a\1\s\virtual-environments\images\win/scripts/ImageHelpers => C:\Program Files\WindowsPowerShell\Modules\ 2021-10-21T11:22:33.5168683Z ==> vhd: Uploading D:\a\1\s\virtual-environments\images\win/scripts/SoftwareReport => C:\image 2021-10-21T11:22:51.1390240Z ==> vhd: Uploading D:\a\1\s\virtual-environments\images\win/post-generation => C:/ 2021-10-21T11:23:01.1150485Z ==> vhd: Uploading D:\a\1\s\virtual-environments\images\win/scripts/Tests => C:\image 2021-10-21T11:23:51.4829594Z ==> vhd: Uploading D:\a\1\s\virtual-environments\images\win/toolsets/toolset-2019.json => C:\image\toolset.json 2021-10-21T11:23:53.4631199Z ==> vhd: Provisioning with windows-shell... 2021-10-21T11:23:53.4642005Z ==> vhd: Provisioning with shell script: C:\Users\VSSADM~1\AppData\Local\Temp\windows-shell-provisioner475848459 2021-10-21T11:23:55.9134685Z vhd: 2021-10-21T11:23:55.9147247Z vhd: C:\Users\packer>net user installer /add /passwordchg:no /passwordreq:yes /active:yes /Y 2021-10-21T11:23:55.9156880Z vhd: The command completed successfully. 2021-10-21T11:23:55.9167965Z vhd: 2021-10-21T11:23:55.9177506Z vhd: 2021-10-21T11:23:55.9188983Z vhd: C:\Users\packer>net localgroup Administrators installer /add 2021-10-21T11:23:55.9229200Z vhd: The command completed successfully. 2021-10-21T11:23:55.9229854Z vhd: 2021-10-21T11:23:55.9230175Z vhd: 2021-10-21T11:23:55.9230673Z vhd: C:\Users\packer>winrm set winrm/config/service/auth @{Basic="true"} 2021-10-21T11:23:55.9238599Z vhd: Auth 2021-10-21T11:23:55.9247554Z vhd: Basic = true 2021-10-21T11:23:55.9256149Z vhd: Kerberos = true 2021-10-21T11:23:55.9273032Z vhd: Negotiate = true 2021-10-21T11:23:55.9277090Z vhd: Certificate = false 2021-10-21T11:23:55.9287313Z vhd: CredSSP = false 2021-10-21T11:23:55.9306557Z vhd: CbtHardeningLevel = Relaxed 2021-10-21T11:23:55.9318967Z vhd: 2021-10-21T11:23:55.9325730Z ==> vhd: Provisioning with Powershell... 2021-10-21T11:23:55.9334582Z ==> vhd: Provisioning with powershell script: C:\Users\VSSADM~1\AppData\Local\Temp\powershell-provisioner149425167 2021-10-21T11:24:02.4799174Z ==> vhd: Provisioning with Powershell... 2021-10-21T11:24:02.4813728Z ==> vhd: Provisioning with powershell script: C:\Users\VSSADM~1\AppData\Local\Temp\powershell-provisioner200702943 2021-10-21T11:24:11.7457851Z vhd: The operation completed successfully. 2021-10-21T11:24:14.1478442Z ==> vhd: Provisioning with Powershell... 2021-10-21T11:24:14.1487923Z ==> vhd: Provisioning with powershell script: D:\a\1\s\virtual-environments\images\win/scripts/Installers/Configure-Antivirus.ps1 2021-10-21T11:24:18.8921034Z vhd: Set antivirus parameters 2021-10-21T11:24:19.4993271Z vhd: Disable Antivirus 2021-10-21T11:24:19.5002400Z ==> vhd: Provisioning with powershell script: D:\a\1\s\virtual-environments\images\win/scripts/Installers/Install-PowerShellModules.ps1 2021-10-21T11:24:23.7542293Z vhd: Setup PowerShellGet 2021-10-21T11:24:26.8505552Z vhd: 2021-10-21T11:24:26.8535410Z vhd: Name Version Source Summary 2021-10-21T11:24:26.8550527Z vhd: ---- ------- ------ ------- 2021-10-21T11:24:26.8567585Z vhd: nuget 2.8.5.208 https://onege... NuGet provider for the OneGet meta-package manager 2021-10-21T11:24:42.7531272Z vhd: Installing Pester module 2021-10-21T11:25:06.3768181Z vhd: Pester v5.3.1 2021-10-21T11:25:06.3780703Z vhd: 2021-10-21T11:25:06.3788544Z vhd: Starting discovery in 1 files. 2021-10-21T11:25:07.7985313Z vhd: Discovery found 8 tests in 882ms. 2021-10-21T11:25:07.8009575Z vhd: Filter 'FullName' set to ('PowerShellModules'). 2021-10-21T11:25:07.8018991Z vhd: Filters selected 1 tests to run. 2021-10-21T11:25:07.8030207Z vhd: Running tests. 2021-10-21T11:25:07.8065652Z vhd: 2021-10-21T11:25:07.8067828Z vhd: Running tests from 'C:\image\Tests\PowerShellModules.Tests.ps1' 2021-10-21T11:25:09.1273082Z vhd: Describing PowerShellModules 2021-10-21T11:25:10.0017477Z vhd: [+] Pester is installed 755ms (562ms|193ms) 2021-10-21T11:25:11.0063448Z vhd: Tests completed in 3.92s 2021-10-21T11:25:11.0074396Z vhd: Tests Passed: 1, Failed: 0, Skipped: 0 NotRun: 7 2021-10-21T11:25:11.0085440Z ==> vhd: Provisioning with powershell script: D:\a\1\s\virtual-environments\images\win/scripts/Installers/Install-Choco.ps1 2021-10-21T11:25:14.9754827Z vhd: Set TLS1.2 2021-10-21T11:25:14.9765870Z vhd: Install chocolatey 2021-10-21T11:25:14.9776976Z vhd: Forcing web requests to allow TLS v1.2 (Required for requests to Chocolatey.org) 2021-10-21T11:25:14.9785964Z vhd: Getting latest version of the Chocolatey package for download. 2021-10-21T11:25:14.9800826Z vhd: Not using proxy. 2021-10-21T11:25:14.9812743Z vhd: Getting Chocolatey from https://community.chocolatey.org/api/v2/package/chocolatey/0.11.2. 2021-10-21T11:25:14.9823714Z vhd: Downloading https://community.chocolatey.org/api/v2/package/chocolatey/0.11.2 to C:\Users\packer\AppData\Local\Temp\chocolatey\chocoInstall\chocolatey.zip 2021-10-21T11:25:14.9859144Z vhd: Not using proxy. 2021-10-21T11:25:14.9869077Z vhd: Extracting C:\Users\packer\AppData\Local\Temp\chocolatey\chocoInstall\chocolatey.zip to C:\Users\packer\AppData\Local\Temp\chocolatey\chocoInstall 2021-10-21T11:25:17.7752049Z vhd: Installing Chocolatey on the local machine 2021-10-21T11:25:17.7761405Z vhd: Creating ChocolateyInstall as an environment variable (targeting 'Machine') 2021-10-21T11:25:17.7770013Z vhd: Setting ChocolateyInstall to 'C:\ProgramData\chocolatey' 2021-10-21T11:25:17.7779262Z vhd: WARNING: It's very likely you will need to close and reopen your shell 2021-10-21T11:25:17.7792204Z vhd: before you can use choco. 2021-10-21T11:25:17.7804595Z vhd: Restricting write permissions to Administrators 2021-10-21T11:25:17.7820591Z vhd: We are setting up the Chocolatey package repository. 2021-10-21T11:25:17.7830988Z vhd: The packages themselves go to 'C:\ProgramData\chocolatey\lib' 2021-10-21T11:25:17.7843595Z vhd: (i.e. C:\ProgramData\chocolatey\lib\yourPackageName). 2021-10-21T11:25:17.7852840Z vhd: A shim file for the command line goes to 'C:\ProgramData\chocolatey\bin' 2021-10-21T11:25:17.7863317Z vhd: and points to an executable in 'C:\ProgramData\chocolatey\lib\yourPackageName'. 2021-10-21T11:25:17.7873039Z vhd: 2021-10-21T11:25:17.7882168Z vhd: Creating Chocolatey folders if they do not already exist. 2021-10-21T11:25:17.7891369Z vhd: 2021-10-21T11:25:17.7905200Z vhd: WARNING: You can safely ignore errors related to missing log files when 2021-10-21T11:25:17.8278854Z vhd: upgrading from a version of Chocolatey less than 0.9.9. 2021-10-21T11:25:17.8286421Z vhd: 'Batch file could not be found' is also safe to ignore. 2021-10-21T11:25:17.8297143Z vhd: 'The system cannot find the file specified' - also safe. 2021-10-21T11:25:17.8303861Z vhd: chocolatey.nupkg file not installed in lib. 2021-10-21T11:25:17.8311587Z vhd: Attempting to locate it from bootstrapper. 2021-10-21T11:25:17.8317467Z vhd: WARNING: Not setting tab completion: Profile file does not exist at 2021-10-21T11:25:17.8323255Z vhd: 'C:\Users\packer\Documents\WindowsPowerShell\Microsoft.PowerShell_profile.ps1'. 2021-10-21T11:25:18.6673466Z vhd: Chocolatey (choco.exe) is now ready. 2021-10-21T11:25:18.6687415Z vhd: You can call choco from anywhere, command line or powershell by typing choco. 2021-10-21T11:25:18.6710050Z vhd: Run choco /? for a list of functions. 2021-10-21T11:25:18.6750293Z vhd: You may need to shut down and restart powershell and/or consoles 2021-10-21T11:25:18.6766408Z vhd: first prior to using choco. 2021-10-21T11:25:18.6783742Z vhd: Ensuring Chocolatey commands are on the path 2021-10-21T11:25:18.6793154Z vhd: Ensuring chocolatey.nupkg is in the lib folder 2021-10-21T11:25:18.6802898Z vhd: Chocolatey v0.11.2 2021-10-21T11:25:18.6811334Z vhd: Enabled allowGlobalConfirmation 2021-10-21T11:25:18.6847645Z vhd: C:\tools 2021-10-21T11:25:18.6852046Z ==> vhd: Provisioning with powershell script: D:\a\1\s\virtual-environments\images\win/scripts/Installers/Initialize-VM.ps1 2021-10-21T11:25:22.1314056Z vhd: Disable Server Manager on Logon 2021-10-21T11:25:23.3849538Z vhd: 2021-10-21T11:25:23.3858277Z vhd: TaskPath TaskName State 2021-10-21T11:25:23.3870817Z vhd: -------- -------- ----- 2021-10-21T11:25:23.3887596Z vhd: \Microsoft\Windows\Server Manager\ ServerManager Disabled 2021-10-21T11:25:23.3894680Z vhd: Disable 'Allow your PC to be discoverable by other PCs' popup 2021-10-21T11:25:23.3908378Z vhd: 2021-10-21T11:25:23.3918226Z vhd: Property : {} 2021-10-21T11:25:23.3928253Z vhd: PSPath : Microsoft.PowerShell.Core\Registry::HKEY_LOCAL_MACHINE\System\CurrentControlSet\Control\Network\NewNetw 2021-10-21T11:25:23.3950497Z vhd: orkWindowOff 2021-10-21T11:25:23.3963667Z vhd: PSParentPath : Microsoft.PowerShell.Core\Registry::HKEY_LOCAL_MACHINE\System\CurrentControlSet\Control\Network 2021-10-21T11:25:23.3975582Z vhd: PSChildName : NewNetworkWindowOff 2021-10-21T11:25:23.3983821Z vhd: PSDrive : HKLM 2021-10-21T11:25:23.3997546Z vhd: PSProvider : Microsoft.PowerShell.Core\Registry 2021-10-21T11:25:23.4007826Z vhd: PSIsContainer : True 2021-10-21T11:25:23.4018435Z vhd: SubKeyCount : 0 2021-10-21T11:25:23.4025675Z vhd: View : Default 2021-10-21T11:25:23.4036257Z vhd: Handle : Microsoft.Win32.SafeHandles.SafeRegistryHandle 2021-10-21T11:25:23.4049222Z vhd: ValueCount : 0 2021-10-21T11:25:23.4061084Z vhd: Name : HKEY_LOCAL_MACHINE\System\CurrentControlSet\Control\Network\NewNetworkWindowOff 2021-10-21T11:25:23.4071170Z vhd: 2021-10-21T11:25:23.4081735Z vhd: Disable Windows Update 2021-10-21T11:25:23.4093726Z vhd: Disabled Windows Update 2021-10-21T11:25:23.4102522Z vhd: Disable UAC 2021-10-21T11:25:23.4113395Z vhd: User Access Control (UAC) has been disabled. 2021-10-21T11:25:23.4122237Z vhd: Disable IE Welcome Screen 2021-10-21T11:25:23.4138342Z vhd: 2021-10-21T11:25:23.4148370Z vhd: Property : {(default)} 2021-10-21T11:25:23.4160010Z vhd: PSPath : Microsoft.PowerShell.Core\Registry::HKEY_LOCAL_MACHINE\Software\Policies\Microsoft\Internet 2021-10-21T11:25:23.4173663Z vhd: Explorer\Main 2021-10-21T11:25:23.4200142Z vhd: PSParentPath : Microsoft.PowerShell.Core\Registry::HKEY_LOCAL_MACHINE\Software\Policies\Microsoft\Internet Explorer 2021-10-21T11:25:23.4211838Z vhd: PSChildName : Main 2021-10-21T11:25:23.4248760Z vhd: PSDrive : HKLM 2021-10-21T11:25:23.4275567Z vhd: PSProvider : Microsoft.PowerShell.Core\Registry 2021-10-21T11:25:23.4287566Z vhd: PSIsContainer : True 2021-10-21T11:25:23.4294691Z vhd: SubKeyCount : 0 2021-10-21T11:25:23.4307014Z vhd: View : Default 2021-10-21T11:25:23.4318231Z vhd: Handle : Microsoft.Win32.SafeHandles.SafeRegistryHandle 2021-10-21T11:25:23.4326648Z vhd: ValueCount : 1 2021-10-21T11:25:23.4338982Z vhd: Name : HKEY_LOCAL_MACHINE\Software\Policies\Microsoft\Internet Explorer\Main 2021-10-21T11:25:23.4346622Z vhd: 2021-10-21T11:25:23.4354067Z vhd: Disabled IE Welcome screen 2021-10-21T11:25:23.4361084Z vhd: Disable IE ESC 2021-10-21T11:25:23.4368688Z vhd: IE Enhanced Security Configuration (ESC) has been disabled. 2021-10-21T11:25:23.4375672Z vhd: Setting local execution policy 2021-10-21T11:25:23.4382619Z vhd: 2021-10-21T11:25:23.4388247Z vhd: Scope : MachinePolicy 2021-10-21T11:25:23.4395034Z vhd: ExecutionPolicy : Undefined 2021-10-21T11:25:23.4399349Z vhd: 2021-10-21T11:25:23.4405453Z vhd: 2021-10-21T11:25:23.4410228Z vhd: Scope : UserPolicy 2021-10-21T11:25:23.4416364Z vhd: ExecutionPolicy : Undefined 2021-10-21T11:25:23.4422380Z vhd: 2021-10-21T11:25:23.4427931Z vhd: 2021-10-21T11:25:23.4432746Z vhd: Scope : Process 2021-10-21T11:25:23.4438540Z vhd: ExecutionPolicy : Unrestricted 2021-10-21T11:25:23.4444567Z vhd: 2021-10-21T11:25:23.4449350Z vhd: 2021-10-21T11:25:23.4455971Z vhd: Scope : CurrentUser 2021-10-21T11:25:23.4462136Z vhd: ExecutionPolicy : Undefined 2021-10-21T11:25:23.4472872Z vhd: 2021-10-21T11:25:23.4480109Z vhd: 2021-10-21T11:25:23.4486862Z vhd: Scope : LocalMachine 2021-10-21T11:25:23.4492638Z vhd: ExecutionPolicy : Unrestricted 2021-10-21T11:25:23.4499552Z vhd: 2021-10-21T11:25:23.4507887Z vhd: Enable long path behavior 2021-10-21T11:25:30.2052801Z vhd: 2021-10-21T11:25:30.2063262Z vhd: DriveLetter : 2021-10-21T11:25:30.2076101Z vhd: SizeRemaining : 488061440 2021-10-21T11:25:30.2085889Z vhd: Size : 524287488 2021-10-21T11:25:30.2103827Z vhd: 2021-10-21T11:25:30.2113790Z vhd: 2021-10-21T11:25:30.2122005Z vhd: DriveLetter : C 2021-10-21T11:25:30.2130131Z vhd: SizeRemaining : 259413409792 2021-10-21T11:25:30.2137642Z vhd: Size : 274351517696 2021-10-21T11:25:30.2149293Z vhd: 2021-10-21T11:25:30.2180356Z vhd: 2021-10-21T11:25:30.2189389Z vhd: DriveLetter : D 2021-10-21T11:25:30.2208546Z vhd: SizeRemaining : 0 2021-10-21T11:25:30.2211831Z vhd: Size : 0 2021-10-21T11:25:30.2219511Z vhd: 2021-10-21T11:25:30.2243974Z ==> vhd: Provisioning with powershell script: D:\a\1\s\virtual-environments\images\win/scripts/Installers/Update-ImageData.ps1 2021-10-21T11:25:34.2384704Z vhd: 2021-10-21T11:25:34.2443592Z vhd: SUCCESS: Specified value was saved. 2021-10-21T11:25:34.2456487Z vhd: 2021-10-21T11:25:34.2465610Z vhd: SUCCESS: Specified value was saved. 2021-10-21T11:25:34.2476387Z vhd: 2021-10-21T11:25:34.2484183Z vhd: SUCCESS: Specified value was saved. 2021-10-21T11:25:34.2494557Z ==> vhd: Provisioning with powershell script: D:\a\1\s\virtual-environments\images\win/scripts/Installers/Update-DotnetTLS.ps1 2021-10-21T11:25:38.7330863Z ==> vhd: Restarting Machine 2021-10-21T11:25:38.9168413Z ==> vhd: Waiting for machine to restart... 2021-10-21T11:25:39.1143307Z ==> vhd: A system shutdown is in progress.(1115) 2021-10-21T11:26:16.2643839Z vhd: pkrvmxls5h6pcik restarted. 2021-10-21T11:26:16.2654156Z ==> vhd: Machine successfully restarted, moving on 2021-10-21T11:26:16.2665445Z ==> vhd: Provisioning with Powershell... 2021-10-21T11:26:16.2677018Z ==> vhd: Provisioning with powershell script: D:\a\1\s\virtual-environments\images\win/scripts/Installers/Install-CommonUtils.ps1 2021-10-21T11:26:21.5255203Z vhd: Running [#1]: choco install 7zip.install -y 2021-10-21T11:26:21.5265427Z vhd: Chocolatey v0.11.2 2021-10-21T11:26:21.5277908Z vhd: Installing the following packages: 2021-10-21T11:26:21.5315482Z vhd: 7zip.install 2021-10-21T11:26:21.5326341Z vhd: By installing, you accept licenses for the packages. 2021-10-21T11:26:23.6640421Z vhd: 2021-10-21T11:26:23.6648572Z vhd: chocolatey-core.extension v1.3.5.1 [Approved] 2021-10-21T11:26:23.6656076Z vhd: chocolatey-core.extension package files install completed. Performing other installation steps. 2021-10-21T11:26:25.5869296Z vhd: Installed/updated chocolatey-core extensions. 2021-10-21T11:26:25.5881325Z vhd: The install of chocolatey-core.extension was successful. 2021-10-21T11:26:25.5890567Z vhd: Software installed to 'C:\ProgramData\chocolatey\extensions\chocolatey-core' 2021-10-21T11:26:25.5900373Z vhd: 2021-10-21T11:26:25.5909525Z vhd: 7zip.install v19.0 [Approved] 2021-10-21T11:26:25.5915821Z vhd: 7zip.install package files install completed. Performing other installation steps. 2021-10-21T11:26:26.2472851Z vhd: Installing 64 bit version 2021-10-21T11:26:26.2481508Z vhd: Installing 7zip.install... 2021-10-21T11:26:27.7663490Z vhd: 7zip.install has been installed. 2021-10-21T11:26:27.7732160Z vhd: 7zip installed to 'C:\Program Files\7-Zip' 2021-10-21T11:26:28.4851553Z vhd: Added C:\ProgramData\chocolatey\bin\7z.exe shim pointed to 'c:\program files\7-zip\7z.exe'. 2021-10-21T11:26:28.4861612Z vhd: 7zip.install may be able to be automatically uninstalled. 2021-10-21T11:26:28.4869947Z vhd: The install of 7zip.install was successful. 2021-10-21T11:26:28.4879381Z vhd: Software installed to 'C:\Program Files\7-Zip\' 2021-10-21T11:26:28.4889207Z vhd: 2021-10-21T11:26:28.4897323Z vhd: Chocolatey installed 2/2 packages. 2021-10-21T11:26:28.4902018Z vhd: See the log for details (C:\ProgramData\chocolatey\logs\chocolatey.log). 2021-10-21T11:26:29.0510185Z vhd: Package installed: 7zip.install|19.0 2021-10-21T11:26:29.0524475Z ==> vhd: Provisioning with powershell script: D:\a\1\s\virtual-environments\images\win/scripts/Installers/Install-JavaTools.ps1 2021-10-21T11:26:33.6956149Z vhd: Downloading package from: https://github.com/adoptium/temurin8-binaries/releases/download/jdk8u302-b08.1/OpenJDK8U-jdk_x64_windows_hotspot_8u302b08.zip to path C:\Users\packer\AppData\Local\Temp\OpenJDK8U-jdk_x64_windows_hotspot_8u302b08.zip . 2021-10-21T11:26:34.9613299Z vhd: Package downloaded successfully in 1.2 seconds 2021-10-21T11:26:34.9634455Z vhd: 2021-10-21T11:26:34.9649507Z vhd: 2021-10-21T11:26:34.9657541Z vhd: Directory: C:\Users\packer\AppData\Local\Temp 2021-10-21T11:26:34.9668707Z vhd: 2021-10-21T11:26:34.9678067Z vhd: 2021-10-21T11:26:34.9686632Z vhd: Mode LastWriteTime Length Name 2021-10-21T11:26:34.9694911Z vhd: ---- ------------- ------ ---- 2021-10-21T11:26:34.9703154Z vhd: d----- 10/21/2021 11:26 AM chocolatey 2021-10-21T11:26:34.9711743Z vhd: d----- 10/21/2021 11:24 AM Microsoft.PackageManagement 2021-10-21T11:26:34.9718139Z vhd: -a---- 10/21/2021 11:26 AM 8 123.txt 2021-10-21T11:26:34.9726313Z vhd: -a---- 10/21/2021 11:26 AM 104297671 OpenJDK8U-jdk_x64_windows_hotspot_8u302b08.zip 2021-10-21T11:26:34.9733130Z vhd: 2021-10-21T11:26:34.9742749Z vhd: 2021-10-21T11:26:34.9748690Z vhd: 2021-10-21T11:26:34.9755812Z vhd: Creating Temurin-Hotspot toolcache folder 2021-10-21T11:26:34.9762139Z vhd: Creating Java '8.0.302-8' folder in 'C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\8.0.302-8' 2021-10-21T11:26:34.9768796Z vhd: Expand archive 'C:\Users\packer\AppData\Local\Temp\OpenJDK8U-jdk_x64_windows_hotspot_8u302b08.zip' to 'C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\8.0.302-8' directory 2021-10-21T11:26:37.0521618Z vhd: Rename 'C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\8.0.302-8' to 'C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\8.0.302-8\x64' path 2021-10-21T11:26:37.0531515Z vhd: 2021-10-21T11:26:37.0542046Z vhd: 2021-10-21T11:26:37.0549670Z vhd: Directory: C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\8.0.302-8 2021-10-21T11:26:37.0557523Z vhd: 2021-10-21T11:26:37.0565638Z vhd: 2021-10-21T11:26:37.0574319Z vhd: Mode LastWriteTime Length Name 2021-10-21T11:26:37.0586662Z vhd: ---- ------------- ------ ---- 2021-10-21T11:26:37.0597085Z vhd: d----- 7/20/2021 9:43 PM jdk8u302-b08 2021-10-21T11:26:37.0607152Z vhd: 2021-10-21T11:26:37.0616031Z vhd: 2021-10-21T11:26:37.0623335Z vhd: 2021-10-21T11:26:37.0631215Z vhd: VERBOSE: Performing the operation "Rename Directory" on target "Item: 2021-10-21T11:26:37.0651547Z vhd: C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\8.0.302-8\jdk8u302-b08 Destination: 2021-10-21T11:26:37.0675387Z vhd: C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\8.0.302-8\x64". 2021-10-21T11:26:37.0683392Z vhd: Set 'JAVA_HOME_8_X64' environmental variable as C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\8.0.302-8\x64 2021-10-21T11:26:37.0692479Z vhd: 2021-10-21T11:26:37.0701330Z vhd: SUCCESS: Specified value was saved. 2021-10-21T11:26:37.0710730Z vhd: Add C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\8.0.302-8\x64\bin to PATH 2021-10-21T11:26:37.0749622Z vhd: C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\8.0.302-8\x64\bin;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\Windows\System32\OpenSSH\;C:\ProgramData\Chocolatey\bin 2021-10-21T11:26:37.0756653Z vhd: Set JAVA_HOME environmental variable as C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\8.0.302-8\x64 2021-10-21T11:26:37.0763050Z vhd: 2021-10-21T11:26:37.0769448Z vhd: SUCCESS: Specified value was saved. 2021-10-21T11:26:37.0809956Z vhd: Downloading package from: https://github.com/adoptium/temurin11-binaries/releases/download/jdk-11.0.12%2B7/OpenJDK11U-jdk_x64_windows_hotspot_11.0.12_7.zip to path C:\Users\packer\AppData\Local\Temp\OpenJDK11U-jdk_x64_windows_hotspot_11.0.12_7.zip . 2021-10-21T11:26:39.1072713Z vhd: Package downloaded successfully in 2.05 seconds 2021-10-21T11:26:39.1082592Z vhd: 2021-10-21T11:26:39.1090143Z vhd: 2021-10-21T11:26:39.1099458Z vhd: Directory: C:\Users\packer\AppData\Local\Temp 2021-10-21T11:26:39.1109841Z vhd: 2021-10-21T11:26:39.1121806Z vhd: 2021-10-21T11:26:39.1138408Z vhd: Mode LastWriteTime Length Name 2021-10-21T11:26:39.1163739Z vhd: ---- ------------- ------ ---- 2021-10-21T11:26:39.1172450Z vhd: d----- 10/21/2021 11:26 AM chocolatey 2021-10-21T11:26:39.1208874Z vhd: d----- 10/21/2021 11:24 AM Microsoft.PackageManagement 2021-10-21T11:26:39.1216611Z vhd: -a---- 10/21/2021 11:26 AM 8 123.txt 2021-10-21T11:26:39.1225968Z vhd: -a---- 10/21/2021 11:26 AM 196595966 OpenJDK11U-jdk_x64_windows_hotspot_11.0.12_7.zip 2021-10-21T11:26:39.1232715Z vhd: -a---- 10/21/2021 11:26 AM 104297671 OpenJDK8U-jdk_x64_windows_hotspot_8u302b08.zip 2021-10-21T11:26:39.1244660Z vhd: 2021-10-21T11:26:39.1284042Z vhd: 2021-10-21T11:26:39.1293336Z vhd: 2021-10-21T11:26:39.1303094Z vhd: Creating Java '11.0.12-7' folder in 'C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\11.0.12-7' 2021-10-21T11:26:39.1314743Z vhd: Expand archive 'C:\Users\packer\AppData\Local\Temp\OpenJDK11U-jdk_x64_windows_hotspot_11.0.12_7.zip' to 'C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\11.0.12-7' directory 2021-10-21T11:26:41.8634184Z vhd: Rename 'C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\11.0.12-7' to 'C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\11.0.12-7\x64' path 2021-10-21T11:26:41.8667437Z vhd: 2021-10-21T11:26:41.8675256Z vhd: 2021-10-21T11:26:41.8685289Z vhd: Directory: C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\11.0.12-7 2021-10-21T11:26:41.8702583Z vhd: 2021-10-21T11:26:41.8709376Z vhd: 2021-10-21T11:26:41.8718387Z vhd: Mode LastWriteTime Length Name 2021-10-21T11:26:41.8725830Z vhd: ---- ------------- ------ ---- 2021-10-21T11:26:41.8734027Z vhd: d----- 7/21/2021 8:38 AM jdk-11.0.12+7 2021-10-21T11:26:41.8743615Z vhd: 2021-10-21T11:26:41.8752149Z vhd: 2021-10-21T11:26:41.8760198Z vhd: 2021-10-21T11:26:41.8772587Z vhd: VERBOSE: Performing the operation "Rename Directory" on target "Item: 2021-10-21T11:26:41.8782439Z vhd: C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\11.0.12-7\jdk-11.0.12+7 Destination: 2021-10-21T11:26:41.8789806Z vhd: C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\11.0.12-7\x64". 2021-10-21T11:26:41.8795721Z vhd: Set 'JAVA_HOME_11_X64' environmental variable as C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\11.0.12-7\x64 2021-10-21T11:26:41.8802520Z vhd: 2021-10-21T11:26:41.8809773Z vhd: SUCCESS: Specified value was saved. 2021-10-21T11:26:41.8816784Z vhd: Downloading package from: https://github.com/adoptium/temurin17-binaries/releases/download/jdk-17%2B35/OpenJDK17-jdk_x64_windows_hotspot_17_35.zip to path C:\Users\packer\AppData\Local\Temp\OpenJDK17-jdk_x64_windows_hotspot_17_35.zip . 2021-10-21T11:26:46.9352575Z vhd: Package downloaded successfully in 4.94 seconds 2021-10-21T11:26:46.9361431Z vhd: 2021-10-21T11:26:46.9370452Z vhd: 2021-10-21T11:26:46.9380128Z vhd: Directory: C:\Users\packer\AppData\Local\Temp 2021-10-21T11:26:46.9392621Z vhd: 2021-10-21T11:26:46.9401945Z vhd: 2021-10-21T11:26:46.9436063Z vhd: Mode LastWriteTime Length Name 2021-10-21T11:26:46.9444577Z vhd: ---- ------------- ------ ---- 2021-10-21T11:26:46.9454716Z vhd: d----- 10/21/2021 11:26 AM chocolatey 2021-10-21T11:26:46.9462130Z vhd: d----- 10/21/2021 11:24 AM Microsoft.PackageManagement 2021-10-21T11:26:46.9469188Z vhd: -a---- 10/21/2021 11:26 AM 8 123.txt 2021-10-21T11:26:46.9477737Z vhd: -a---- 10/21/2021 11:26 AM 196595966 OpenJDK11U-jdk_x64_windows_hotspot_11.0.12_7.zip 2021-10-21T11:26:46.9484727Z vhd: -a---- 10/21/2021 11:26 AM 188690370 OpenJDK17-jdk_x64_windows_hotspot_17_35.zip 2021-10-21T11:26:46.9494423Z vhd: -a---- 10/21/2021 11:26 AM 104297671 OpenJDK8U-jdk_x64_windows_hotspot_8u302b08.zip 2021-10-21T11:26:46.9501072Z vhd: 2021-10-21T11:26:46.9509829Z vhd: 2021-10-21T11:26:46.9522703Z vhd: 2021-10-21T11:26:46.9530164Z vhd: Creating Java '17.0.0-35' folder in 'C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\17.0.0-35' 2021-10-21T11:26:46.9542157Z vhd: Expand archive 'C:\Users\packer\AppData\Local\Temp\OpenJDK17-jdk_x64_windows_hotspot_17_35.zip' to 'C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\17.0.0-35' directory 2021-10-21T11:26:50.4006673Z vhd: Rename 'C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\17.0.0-35' to 'C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\17.0.0-35\x64' path 2021-10-21T11:26:50.4010135Z vhd: 2021-10-21T11:26:50.4019262Z vhd: 2021-10-21T11:26:50.4027976Z vhd: Directory: C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\17.0.0-35 2021-10-21T11:26:50.4036793Z vhd: 2021-10-21T11:26:50.4043742Z vhd: 2021-10-21T11:26:50.4050602Z vhd: Mode LastWriteTime Length Name 2021-10-21T11:26:50.4058433Z vhd: ---- ------------- ------ ---- 2021-10-21T11:26:50.4067750Z vhd: d----- 9/14/2021 10:42 PM jdk-17+35 2021-10-21T11:26:50.4078668Z vhd: 2021-10-21T11:26:50.4086364Z vhd: 2021-10-21T11:26:50.4095737Z vhd: 2021-10-21T11:26:50.4107689Z vhd: VERBOSE: Performing the operation "Rename Directory" on target "Item: 2021-10-21T11:26:50.4118146Z vhd: C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\17.0.0-35\jdk-17+35 Destination: 2021-10-21T11:26:50.4127785Z vhd: C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\17.0.0-35\x64". 2021-10-21T11:26:50.4138159Z vhd: Set 'JAVA_HOME_17_X64' environmental variable as C:\hostedtoolcache\windows\Java_Temurin-Hotspot_jdk\17.0.0-35\x64 2021-10-21T11:26:50.4153070Z vhd: 2021-10-21T11:26:50.4162851Z vhd: SUCCESS: Specified value was saved. 2021-10-21T11:26:50.4173165Z vhd: Downloading package from: https://github.com/adoptium/temurin8-binaries/releases/download/jdk8u302-b08.1/OpenJDK8U-jdk_x64_windows_hotspot_8u302b08.zip to path C:\Users\packer\AppData\Local\Temp\OpenJDK8U-jdk_x64_windows_hotspot_8u302b08.zip . 2021-10-21T11:26:50.4180194Z vhd: Package downloaded successfully in 0.36 seconds 2021-10-21T11:26:50.4189517Z vhd: 2021-10-21T11:26:50.4200805Z vhd: 2021-10-21T11:26:50.4211359Z vhd: Directory: C:\Users\packer\AppData\Local\Temp 2021-10-21T11:26:50.4221289Z vhd: 2021-10-21T11:26:50.4229907Z vhd: 2021-10-21T11:26:50.4238790Z vhd: Mode LastWriteTime Length Name 2021-10-21T11:26:50.4247395Z vhd: ---- ------------- ------ ---- 2021-10-21T11:26:50.4259971Z vhd: d----- 10/21/2021 11:26 AM chocolatey 2021-10-21T11:26:50.4275655Z vhd: d----- 10/21/2021 11:24 AM Microsoft.PackageManagement 2021-10-21T11:26:50.4288636Z vhd: -a---- 10/21/2021 11:26 AM 8 123.txt 2021-10-21T11:26:50.4295936Z vhd: -a---- 10/21/2021 11:26 AM 196595966 OpenJDK11U-jdk_x64_windows_hotspot_11.0.12_7.zip 2021-10-21T11:26:50.4315961Z vhd: -a---- 10/21/2021 11:26 AM 188690370 OpenJDK17-jdk_x64_windows_hotspot_17_35.zip 2021-10-21T11:26:50.4317421Z vhd: -a---- 10/21/2021 11:26 AM 104297671 OpenJDK8U-jdk_x64_windows_hotspot_8u302b08.zip 2021-10-21T11:26:50.4325138Z vhd: 2021-10-21T11:26:50.4331929Z vhd: 2021-10-21T11:26:50.4370899Z vhd: 2021-10-21T11:26:50.4371546Z vhd: Creating Adopt toolcache folder 2021-10-21T11:26:50.4377029Z vhd: Creating Java '8.0.302-8' folder in 'C:\hostedtoolcache\windows\Java_Adopt_jdk\8.0.302-8' 2021-10-21T11:26:50.4381705Z vhd: Expand archive 'C:\Users\packer\AppData\Local\Temp\OpenJDK8U-jdk_x64_windows_hotspot_8u302b08.zip' to 'C:\hostedtoolcache\windows\Java_Adopt_jdk\8.0.302-8' directory 2021-10-21T11:26:59.9095395Z vhd: Rename 'C:\hostedtoolcache\windows\Java_Adopt_jdk\8.0.302-8' to 'C:\hostedtoolcache\windows\Java_Adopt_jdk\8.0.302-8\x64' path 2021-10-21T11:26:59.9103096Z vhd: 2021-10-21T11:26:59.9115461Z vhd: 2021-10-21T11:26:59.9124754Z vhd: Directory: C:\hostedtoolcache\windows\Java_Adopt_jdk\8.0.302-8 2021-10-21T11:26:59.9134772Z vhd: 2021-10-21T11:26:59.9145513Z vhd: 2021-10-21T11:26:59.9154867Z vhd: Mode LastWriteTime Length Name 2021-10-21T11:26:59.9161826Z vhd: ---- ------------- ------ ---- 2021-10-21T11:26:59.9171301Z vhd: d----- 7/20/2021 9:43 PM jdk8u302-b08 2021-10-21T11:26:59.9184479Z vhd: 2021-10-21T11:26:59.9194552Z vhd: 2021-10-21T11:26:59.9201233Z vhd: 2021-10-21T11:26:59.9218185Z vhd: VERBOSE: Performing the operation "Rename Directory" on target "Item: 2021-10-21T11:26:59.9229274Z vhd: C:\hostedtoolcache\windows\Java_Adopt_jdk\8.0.302-8\jdk8u302-b08 Destination: 2021-10-21T11:26:59.9246224Z vhd: C:\hostedtoolcache\windows\Java_Adopt_jdk\8.0.302-8\x64". 2021-10-21T11:26:59.9270555Z vhd: Downloading package from: https://github.com/adoptium/temurin11-binaries/releases/download/jdk-11.0.12%2B7/OpenJDK11U-jdk_x64_windows_hotspot_11.0.12_7.zip to path C:\Users\packer\AppData\Local\Temp\OpenJDK11U-jdk_x64_windows_hotspot_11.0.12_7.zip . 2021-10-21T11:27:01.4154507Z vhd: Package downloaded successfully in 1.48 seconds 2021-10-21T11:27:01.4162541Z vhd: 2021-10-21T11:27:01.4172881Z vhd: 2021-10-21T11:27:01.4193399Z vhd: Directory: C:\Users\packer\AppData\Local\Temp 2021-10-21T11:27:01.4202113Z vhd: 2021-10-21T11:27:01.4215038Z vhd: 2021-10-21T11:27:01.4223762Z vhd: Mode LastWriteTime Length Name 2021-10-21T11:27:01.4234038Z vhd: ---- ------------- ------ ---- 2021-10-21T11:27:01.4243184Z vhd: d----- 10/21/2021 11:26 AM chocolatey 2021-10-21T11:27:01.4252141Z vhd: d----- 10/21/2021 11:24 AM Microsoft.PackageManagement 2021-10-21T11:27:01.4263415Z vhd: -a---- 10/21/2021 11:27 AM 8 123.txt 2021-10-21T11:27:01.4271598Z vhd: -a---- 10/21/2021 11:27 AM 196595966 OpenJDK11U-jdk_x64_windows_hotspot_11.0.12_7.zip 2021-10-21T11:27:01.4280289Z vhd: -a---- 10/21/2021 11:26 AM 188690370 OpenJDK17-jdk_x64_windows_hotspot_17_35.zip 2021-10-21T11:27:01.4291713Z vhd: -a---- 10/21/2021 11:26 AM 104297671 OpenJDK8U-jdk_x64_windows_hotspot_8u302b08.zip 2021-10-21T11:27:01.4299329Z vhd: 2021-10-21T11:27:01.4307221Z vhd: 2021-10-21T11:27:01.4313557Z vhd: 2021-10-21T11:27:01.4318932Z vhd: Creating Java '11.0.12-7' folder in 'C:\hostedtoolcache\windows\Java_Adopt_jdk\11.0.12-7' 2021-10-21T11:27:01.4325026Z vhd: Expand archive 'C:\Users\packer\AppData\Local\Temp\OpenJDK11U-jdk_x64_windows_hotspot_11.0.12_7.zip' to 'C:\hostedtoolcache\windows\Java_Adopt_jdk\11.0.12-7' directory 2021-10-21T11:27:07.3832563Z vhd: Rename 'C:\hostedtoolcache\windows\Java_Adopt_jdk\11.0.12-7' to 'C:\hostedtoolcache\windows\Java_Adopt_jdk\11.0.12-7\x64' path 2021-10-21T11:27:07.3839822Z vhd: 2021-10-21T11:27:07.3848597Z vhd: 2021-10-21T11:27:07.3858431Z vhd: Directory: C:\hostedtoolcache\windows\Java_Adopt_jdk\11.0.12-7 2021-10-21T11:27:07.3868730Z vhd: 2021-10-21T11:27:07.3876594Z vhd: 2021-10-21T11:27:07.3883577Z vhd: Mode LastWriteTime Length Name 2021-10-21T11:27:07.3894935Z vhd: ---- ------------- ------ ---- 2021-10-21T11:27:07.3904506Z vhd: d----- 7/21/2021 8:38 AM jdk-11.0.12+7 2021-10-21T11:27:07.3912686Z vhd: 2021-10-21T11:27:07.3920485Z vhd: 2021-10-21T11:27:07.3933599Z vhd: 2021-10-21T11:27:07.3939646Z vhd: VERBOSE: Performing the operation "Rename Directory" on target "Item: 2021-10-21T11:27:07.3977219Z vhd: C:\hostedtoolcache\windows\Java_Adopt_jdk\11.0.12-7\jdk-11.0.12+7 Destination: 2021-10-21T11:27:07.3991034Z vhd: C:\hostedtoolcache\windows\Java_Adopt_jdk\11.0.12-7\x64". 2021-10-21T11:27:07.4026884Z vhd: Downloading package from: https://github.com/AdoptOpenJDK/openjdk13-binaries/releases/download/jdk-13.0.2%2B8/OpenJDK13U-jdk_x64_windows_hotspot_13.0.2_8.zip to path C:\Users\packer\AppData\Local\Temp\OpenJDK13U-jdk_x64_windows_hotspot_13.0.2_8.zip . 2021-10-21T11:27:10.8196088Z vhd: Package downloaded successfully in 3.38 seconds 2021-10-21T11:27:10.8207721Z vhd: 2021-10-21T11:27:10.8216278Z vhd: 2021-10-21T11:27:10.8224985Z vhd: Directory: C:\Users\packer\AppData\Local\Temp 2021-10-21T11:27:10.8233193Z vhd: 2021-10-21T11:27:10.8239402Z vhd: 2021-10-21T11:27:10.8257778Z vhd: Mode LastWriteTime Length Name 2021-10-21T11:27:10.8266276Z vhd: ---- ------------- ------ ---- 2021-10-21T11:27:10.8274813Z vhd: d----- 10/21/2021 11:26 AM chocolatey 2021-10-21T11:27:10.8286848Z vhd: d----- 10/21/2021 11:24 AM Microsoft.PackageManagement 2021-10-21T11:27:10.8294415Z vhd: -a---- 10/21/2021 11:27 AM 8 123.txt 2021-10-21T11:27:10.8305673Z vhd: -a---- 10/21/2021 11:27 AM 196595966 OpenJDK11U-jdk_x64_windows_hotspot_11.0.12_7.zip 2021-10-21T11:27:10.8313599Z vhd: -a---- 10/21/2021 11:27 AM 209645142 OpenJDK13U-jdk_x64_windows_hotspot_13.0.2_8.zip 2021-10-21T11:27:10.8319867Z vhd: -a---- 10/21/2021 11:26 AM 188690370 OpenJDK17-jdk_x64_windows_hotspot_17_35.zip 2021-10-21T11:27:10.8327491Z vhd: -a---- 10/21/2021 11:26 AM 104297671 OpenJDK8U-jdk_x64_windows_hotspot_8u302b08.zip 2021-10-21T11:27:10.8337586Z vhd: 2021-10-21T11:27:10.8344637Z vhd: 2021-10-21T11:27:10.8351801Z vhd: 2021-10-21T11:27:10.8358056Z vhd: Creating Java '13.0.2-8.1' folder in 'C:\hostedtoolcache\windows\Java_Adopt_jdk\13.0.2-8.1' 2021-10-21T11:27:10.8365509Z vhd: Expand archive 'C:\Users\packer\AppData\Local\Temp\OpenJDK13U-jdk_x64_windows_hotspot_13.0.2_8.zip' to 'C:\hostedtoolcache\windows\Java_Adopt_jdk\13.0.2-8.1' directory 2021-10-21T11:27:17.6801788Z vhd: Rename 'C:\hostedtoolcache\windows\Java_Adopt_jdk\13.0.2-8.1' to 'C:\hostedtoolcache\windows\Java_Adopt_jdk\13.0.2-8.1\x64' path 2021-10-21T11:27:17.6810134Z vhd: 2021-10-21T11:27:17.6816592Z vhd: 2021-10-21T11:27:17.6823916Z vhd: Directory: C:\hostedtoolcache\windows\Java_Adopt_jdk\13.0.2-8.1 2021-10-21T11:27:17.6831315Z vhd: 2021-10-21T11:27:17.6840536Z vhd: 2021-10-21T11:27:17.6848912Z vhd: Mode LastWriteTime Length Name 2021-10-21T11:27:17.6862419Z vhd: ---- ------------- ------ ---- 2021-10-21T11:27:17.6874633Z vhd: d----- 1/17/2020 4:47 PM jdk-13.0.2+8 2021-10-21T11:27:17.6919037Z vhd: 2021-10-21T11:27:17.6928423Z vhd: 2021-10-21T11:27:17.6935852Z vhd: 2021-10-21T11:27:17.6944587Z vhd: VERBOSE: Performing the operation "Rename Directory" on target "Item: 2021-10-21T11:27:17.6951930Z vhd: C:\hostedtoolcache\windows\Java_Adopt_jdk\13.0.2-8.1\jdk-13.0.2+8 Destination: 2021-10-21T11:27:17.6959617Z vhd: C:\hostedtoolcache\windows\Java_Adopt_jdk\13.0.2-8.1\x64". 2021-10-21T11:27:18.6849605Z vhd: The script is finished. Exit with 1 2021-10-21T11:27:18.6858397Z ==> vhd: Provisioning step had errors: Running the cleanup provisioner, if present... 2021-10-21T11:27:18.6890743Z ==> vhd: 2021-10-21T11:27:18.6898559Z ==> vhd: Deleting individual resources ... 2021-10-21T11:27:18.9671013Z ==> vhd: Adding to deletion queue -> Microsoft.Compute/virtualMachines : 'pkrvmxls5h6pcik' 2021-10-21T11:27:18.9679585Z ==> vhd: Adding to deletion queue -> Microsoft.Network/networkInterfaces : 'pkrnixls5h6pcik' 2021-10-21T11:27:18.9711135Z ==> vhd: Adding to deletion queue -> Microsoft.Network/publicIPAddresses : 'pkripxls5h6pcik' 2021-10-21T11:27:18.9712457Z ==> vhd: Adding to deletion queue -> Microsoft.Network/virtualNetworks : 'pkrvnxls5h6pcik' 2021-10-21T11:27:18.9720096Z ==> vhd: Attempting deletion -> Microsoft.Network/networkInterfaces : 'pkrnixls5h6pcik' 2021-10-21T11:27:18.9728693Z ==> vhd: Waiting for deletion of all resources... 2021-10-21T11:27:18.9737070Z ==> vhd: Attempting deletion -> Microsoft.Compute/virtualMachines : 'pkrvmxls5h6pcik' 2021-10-21T11:27:18.9748977Z ==> vhd: Attempting deletion -> Microsoft.Network/virtualNetworks : 'pkrvnxls5h6pcik' 2021-10-21T11:27:18.9756301Z ==> vhd: Attempting deletion -> Microsoft.Network/publicIPAddresses : 'pkripxls5h6pcik' 2021-10-21T11:27:19.2498425Z ==> vhd: Error deleting resource. Will retry. 2021-10-21T11:27:19.2506760Z ==> vhd: Name: pkrnixls5h6pcik 2021-10-21T11:27:19.2514355Z ==> vhd: Error: network.InterfacesClient#Delete: Failure sending request: StatusCode=400 -- Original Error: Code="NicInUse" Message="Network Interface /subscriptions/0e387b95-3ed8-4362-a2f9-8a6deede44d5/resourceGroups/12132_windows2019/providers/Microsoft.Network/networkInterfaces/pkrnixls5h6pcik is used by existing resource /subscriptions/0e387b95-3ed8-4362-a2f9-8a6deede44d5/resourceGroups/12132_windows2019/providers/Microsoft.Compute/virtualMachines/pkrvmxls5h6pcik. In order to delete the network interface, it must be dissociated from the resource. To learn more, see aka.ms/deletenic." Details=[] 2021-10-21T11:27:19.2519370Z ==> vhd: 2021-10-21T11:27:19.2728531Z ==> vhd: Error deleting resource. Will retry. 2021-10-21T11:27:19.2735205Z ==> vhd: Name: pkrvnxls5h6pcik 2021-10-21T11:27:19.2745682Z ==> vhd: Error: network.VirtualNetworksClient#Delete: Failure sending request: StatusCode=400 -- Original Error: Code="InUseSubnetCannotBeDeleted" Message="Subnet pkrsnxls5h6pcik is in use by /subscriptions/0e387b95-3ed8-4362-a2f9-8a6deede44d5/resourceGroups/12132_windows2019/providers/Microsoft.Network/networkInterfaces/pkrnixls5h6pcik/ipConfigurations/ipconfig and cannot be deleted. In order to delete the subnet, delete all the resources within the subnet. See aka.ms/deletesubnet." Details=[] 2021-10-21T11:27:19.2751058Z ==> vhd: 2021-10-21T11:27:19.2942462Z ==> vhd: Error deleting resource. Will retry. 2021-10-21T11:27:19.2949165Z ==> vhd: Name: pkripxls5h6pcik 2021-10-21T11:27:19.2956483Z ==> vhd: Error: network.PublicIPAddressesClient#Delete: Failure sending request: StatusCode=400 -- Original Error: Code="PublicIPAddressCannotBeDeleted" Message="Public IP address /subscriptions/0e387b95-3ed8-4362-a2f9-8a6deede44d5/resourceGroups/12132_windows2019/providers/Microsoft.Network/publicIPAddresses/pkripxls5h6pcik can not be deleted since it is still allocated to resource /subscriptions/0e387b95-3ed8-4362-a2f9-8a6deede44d5/resourceGroups/12132_windows2019/providers/Microsoft.Network/networkInterfaces/pkrnixls5h6pcik/ipConfigurations/ipconfig. In order to delete the public IP, disassociate/detach the Public IP address from the resource. To learn how to do this, see aka.ms/deletepublicip." Details=[] 2021-10-21T11:27:19.2962770Z ==> vhd: 2021-10-21T11:27:29.2505239Z ==> vhd: Attempting deletion -> Microsoft.Network/networkInterfaces : 'pkrnixls5h6pcik' 2021-10-21T11:27:29.2744014Z ==> vhd: Attempting deletion -> Microsoft.Network/virtualNetworks : 'pkrvnxls5h6pcik' 2021-10-21T11:27:29.2990582Z ==> vhd: Attempting deletion -> Microsoft.Network/publicIPAddresses : 'pkripxls5h6pcik' 2021-10-21T11:27:29.4183844Z ==> vhd: Error deleting resource. Will retry. 2021-10-21T11:27:29.4217382Z ==> vhd: Name: pkrnixls5h6pcik 2021-10-21T11:27:29.4219362Z ==> vhd: Error: network.InterfacesClient#Delete: Failure sending request: StatusCode=400 -- Original Error: Code="NicInUse" Message="Network Interface /subscriptions/0e387b95-3ed8-4362-a2f9-8a6deede44d5/resourceGroups/12132_windows2019/providers/Microsoft.Network/networkInterfaces/pkrnixls5h6pcik is used by existing resource /subscriptions/0e387b95-3ed8-4362-a2f9-8a6deede44d5/resourceGroups/12132_windows2019/providers/Microsoft.Compute/virtualMachines/pkrvmxls5h6pcik. In order to delete the network interface, it must be dissociated from the resource. To learn more, see aka.ms/deletenic." Details=[] 2021-10-21T11:27:29.4221967Z ==> vhd: 2021-10-21T11:27:29.4828122Z ==> vhd: Error deleting resource. Will retry. 2021-10-21T11:27:29.4840731Z ==> vhd: Name: pkrvnxls5h6pcik 2021-10-21T11:27:29.4850782Z ==> vhd: Error: network.VirtualNetworksClient#Delete: Failure sending request: StatusCode=400 -- Original Error: Code="InUseSubnetCannotBeDeleted" Message="Subnet pkrsnxls5h6pcik is in use by /subscriptions/0e387b95-3ed8-4362-a2f9-8a6deede44d5/resourceGroups/12132_windows2019/providers/Microsoft.Network/networkInterfaces/pkrnixls5h6pcik/ipConfigurations/ipconfig and cannot be deleted. In order to delete the subnet, delete all the resources within the subnet. See aka.ms/deletesubnet." Details=[] 2021-10-21T11:27:29.4856585Z ==> vhd: 2021-10-21T11:27:29.5169094Z ==> vhd: Error deleting resource. Will retry. 2021-10-21T11:27:29.5175071Z ==> vhd: Name: pkripxls5h6pcik 2021-10-21T11:27:29.5184492Z ==> vhd: Error: network.PublicIPAddressesClient#Delete: Failure sending request: StatusCode=400 -- Original Error: Code="PublicIPAddressCannotBeDeleted" Message="Public IP address /subscriptions/0e387b95-3ed8-4362-a2f9-8a6deede44d5/resourceGroups/12132_windows2019/providers/Microsoft.Network/publicIPAddresses/pkripxls5h6pcik can not be deleted since it is still allocated to resource /subscriptions/0e387b95-3ed8-4362-a2f9-8a6deede44d5/resourceGroups/12132_windows2019/providers/Microsoft.Network/networkInterfaces/pkrnixls5h6pcik/ipConfigurations/ipconfig. In order to delete the public IP, disassociate/detach the Public IP address from the resource. To learn how to do this, see aka.ms/deletepublicip." Details=[] 2021-10-21T11:27:29.5188971Z ==> vhd: 2021-10-21T11:27:49.4222106Z ==> vhd: Attempting deletion -> Microsoft.Network/networkInterfaces : 'pkrnixls5h6pcik' 2021-10-21T11:27:49.4840483Z ==> vhd: Attempting deletion -> Microsoft.Network/virtualNetworks : 'pkrvnxls5h6pcik' 2021-10-21T11:27:49.5183577Z ==> vhd: Attempting deletion -> Microsoft.Network/publicIPAddresses : 'pkripxls5h6pcik' 2021-10-21T11:27:49.7067353Z ==> vhd: Error deleting resource. Will retry. 2021-10-21T11:27:49.7099825Z ==> vhd: Name: pkrvnxls5h6pcik 2021-10-21T11:27:49.7106705Z ==> vhd: Error: network.VirtualNetworksClient#Delete: Failure sending request: StatusCode=400 -- Original Error: Code="InUseSubnetCannotBeDeleted" Message="Subnet pkrsnxls5h6pcik is in use by /subscriptions/0e387b95-3ed8-4362-a2f9-8a6deede44d5/resourceGroups/12132_windows2019/providers/Microsoft.Network/networkInterfaces/pkrnixls5h6pcik/ipConfigurations/ipconfig and cannot be deleted. In order to delete the subnet, delete all the resources within the subnet. See aka.ms/deletesubnet." Details=[] 2021-10-21T11:27:49.7119750Z ==> vhd: 2021-10-21T11:27:49.7199183Z ==> vhd: Error deleting resource. Will retry. 2021-10-21T11:27:49.7207800Z ==> vhd: Name: pkripxls5h6pcik 2021-10-21T11:27:49.7217443Z ==> vhd: Error: network.PublicIPAddressesClient#Delete: Failure sending request: StatusCode=400 -- Original Error: Code="PublicIPAddressCannotBeDeleted" Message="Public IP address /subscriptions/0e387b95-3ed8-4362-a2f9-8a6deede44d5/resourceGroups/12132_windows2019/providers/Microsoft.Network/publicIPAddresses/pkripxls5h6pcik can not be deleted since it is still allocated to resource /subscriptions/0e387b95-3ed8-4362-a2f9-8a6deede44d5/resourceGroups/12132_windows2019/providers/Microsoft.Network/networkInterfaces/pkrnixls5h6pcik/ipConfigurations/ipconfig. In order to delete the public IP, disassociate/detach the Public IP address from the resource. To learn how to do this, see aka.ms/deletepublicip." Details=[] 2021-10-21T11:27:49.7231650Z ==> vhd: 2021-10-21T11:27:49.7692987Z ==> vhd: Error deleting resource. Will retry. 2021-10-21T11:27:49.7702214Z ==> vhd: Name: pkrnixls5h6pcik 2021-10-21T11:27:49.7721476Z ==> vhd: Error: network.InterfacesClient#Delete: Failure sending request: StatusCode=400 -- Original Error: Code="NicInUse" Message="Network Interface /subscriptions/0e387b95-3ed8-4362-a2f9-8a6deede44d5/resourceGroups/12132_windows2019/providers/Microsoft.Network/networkInterfaces/pkrnixls5h6pcik is used by existing resource /subscriptions/0e387b95-3ed8-4362-a2f9-8a6deede44d5/resourceGroups/12132_windows2019/providers/Microsoft.Compute/virtualMachines/pkrvmxls5h6pcik. In order to delete the network interface, it must be dissociated from the resource. To learn more, see aka.ms/deletenic." Details=[] 2021-10-21T11:27:49.7723178Z ==> vhd: 2021-10-21T11:28:29.7100388Z ==> vhd: Attempting deletion -> Microsoft.Network/virtualNetworks : 'pkrvnxls5h6pcik' 2021-10-21T11:28:29.7238028Z ==> vhd: Attempting deletion -> Microsoft.Network/publicIPAddresses : 'pkripxls5h6pcik' 2021-10-21T11:28:29.7717202Z ==> vhd: Attempting deletion -> Microsoft.Network/networkInterfaces : 'pkrnixls5h6pcik' 2021-10-21T11:28:29.9276337Z ==> vhd: Error deleting resource. Will retry. 2021-10-21T11:28:29.9284249Z ==> vhd: Name: pkrvnxls5h6pcik 2021-10-21T11:28:29.9294979Z ==> vhd: Error: network.VirtualNetworksClient#Delete: Failure sending request: StatusCode=400 -- Original Error: Code="InUseSubnetCannotBeDeleted" Message="Subnet pkrsnxls5h6pcik is in use by /subscriptions/0e387b95-3ed8-4362-a2f9-8a6deede44d5/resourceGroups/12132_windows2019/providers/Microsoft.Network/networkInterfaces/pkrnixls5h6pcik/ipConfigurations/ipconfig and cannot be deleted. In order to delete the subnet, delete all the resources within the subnet. See aka.ms/deletesubnet." Details=[] 2021-10-21T11:28:29.9302437Z ==> vhd: 2021-10-21T11:29:49.9299058Z ==> vhd: Attempting deletion -> Microsoft.Network/virtualNetworks : 'pkrvnxls5h6pcik' 2021-10-21T11:30:00.2786432Z ==> vhd: Deleting -> image : 'https://mspackerimage.blob.core.windows.net/images/pkrosxls5h6pcik.vhd' 2021-10-21T11:30:00.6143034Z ==> vhd: Removing the created Deployment object: 'pkrdpxls5h6pcik' 2021-10-21T11:30:16.5185769Z ==> vhd: 2021-10-21T11:30:16.5196031Z ==> vhd: Deleting individual resources ... 2021-10-21T11:30:16.7764410Z ==> vhd: Adding to deletion queue -> Microsoft.KeyVault/vaults/secrets : 'pkrkvxls5h6pcik/packerKeyVaultSecret' 2021-10-21T11:30:16.7776964Z ==> vhd: Adding to deletion queue -> Microsoft.KeyVault/vaults : 'pkrkvxls5h6pcik' 2021-10-21T11:30:16.7788765Z ==> vhd: Attempting deletion -> Microsoft.KeyVault/vaults/secrets : 'pkrkvxls5h6pcik/packerKeyVaultSecret' 2021-10-21T11:30:16.7798868Z ==> vhd: Attempting deletion -> Microsoft.KeyVault/vaults : 'pkrkvxls5h6pcik' 2021-10-21T11:30:16.7807879Z ==> vhd: Waiting for deletion of all resources... 2021-10-21T11:30:18.6854919Z ==> vhd: Failed to find temporary OS disk on VM. Please delete manually. 2021-10-21T11:30:18.6865577Z ==> vhd: 2021-10-21T11:30:18.6874221Z ==> vhd: VM Name: pkrvmxls5h6pcik 2021-10-21T11:30:18.6881806Z ==> vhd: Error: %!s() 2021-10-21T11:30:18.6889443Z ==> vhd: Removing the created Deployment object: 'kvpkrdpxls5h6pcik' 2021-10-21T11:30:34.4334277Z ==> vhd: 2021-10-21T11:30:34.4346804Z ==> vhd: Cleanup requested, deleting resource group ... 2021-10-21T11:30:50.3516361Z ==> vhd: Resource group has been deleted. 2021-10-21T11:30:50.3548117Z Build 'vhd' errored after 11 minutes 54 seconds: Script exited with non-zero exit status: 1.Allowed exit codes are: [0] 2021-10-21T11:30:50.3554153Z 2021-10-21T11:30:50.3563077Z ==> Wait completed after 11 minutes 54 seconds 2021-10-21T11:30:50.3571653Z 2021-10-21T11:30:50.3580323Z ==> Some builds didn't complete successfully and had errors: 2021-10-21T11:30:50.3587829Z --> vhd: Script exited with non-zero exit status: 1.Allowed exit codes are: [0] 2021-10-21T11:30:50.3593660Z 2021-10-21T11:30:50.3601150Z ==> Builds finished but no artifacts were created. 2021-10-21T11:30:50.7438786Z 2021-10-21T11:30:50.7439704Z 2021-10-21T11:30:50.9752717Z ##[error]PowerShell exited with code '1'. 2021-10-21T11:30:51.0305024Z ##[section]Finishing: Build VM