search

slonopotamus / stevedore

🚢 Docker distribution for Windows Containers that Just Works
Apache License 2.0
296 stars 12 forks source link

Error 0x800706ba: failed to create user: SYSTEM when trying to install via Salt service running as SYSTEM account #10

Closed slonopotamus closed 2 years ago

slonopotamus commented 3 years ago

Stevedore Sersion

0.0.2

Windows version

2019 LTSC

Log output

=== Verbose logging started: 10/8/2021  19:40:45  Build type: SHIP UNICODE 5.00.10011.00  Calling process: C:\Windows\System32\msiexec.exe ===
MSI (c) (F8:18) [19:40:45:208]: Resetting cached policy values
MSI (c) (F8:18) [19:40:45:208]: Machine policy value 'Debug' is 0
MSI (c) (F8:18) [19:40:45:208]: ******* RunEngine:
           ******* Product: C:\Windows\TEMP\chocolatey\stevedore\0.0.2\stevedore-0.0.2-x86_64.msi
           ******* Action: 
           ******* CommandLine: **********
MSI (c) (F8:18) [19:40:45:208]: Client-side and UI is none or basic: Running entire install on the server.
MSI (c) (F8:18) [19:40:45:208]: Grabbed execution mutex.
MSI (c) (F8:18) [19:40:45:317]: Cloaking enabled.
MSI (c) (F8:18) [19:40:45:317]: Attempting to enable all disabled privileges before calling Install on Server
MSI (c) (F8:18) [19:40:45:317]: Incrementing counter to disable shutdown. Counter after increment: 0
MSI (s) (64:80) [19:40:45:348]: Running installation inside multi-package transaction C:\Windows\TEMP\chocolatey\stevedore\0.0.2\stevedore-0.0.2-x86_64.msi
MSI (s) (64:80) [19:40:45:348]: Grabbed execution mutex.
MSI (s) (64:3C) [19:40:45:348]: Resetting cached policy values
MSI (s) (64:3C) [19:40:45:348]: Machine policy value 'Debug' is 0
MSI (s) (64:3C) [19:40:45:348]: ******* RunEngine:
           ******* Product: C:\Windows\TEMP\chocolatey\stevedore\0.0.2\stevedore-0.0.2-x86_64.msi
           ******* Action: 
           ******* CommandLine: **********
MSI (s) (64:3C) [19:40:45:380]: Machine policy value 'DisableUserInstalls' is 0
MSI (s) (64:3C) [19:40:45:427]: Note: 1: 2203 2: C:\Windows\Installer\inprogressinstallinfo.ipi 3: -2147287038 
MSI (s) (64:3C) [19:40:45:427]: SRSetRestorePoint skipped for this transaction.
MSI (s) (64:3C) [19:40:45:427]: Note: 1: 1402 2: HKEY_CURRENT_USER\Software\Microsoft\Windows\CurrentVersion\Policies\Explorer 3: 2 
MSI (s) (64:3C) [19:40:45:442]: File will have security applied from OpCode.
MSI (s) (64:3C) [19:40:45:692]: SOFTWARE RESTRICTION POLICY: Verifying package --> 'C:\Windows\TEMP\chocolatey\stevedore\0.0.2\stevedore-0.0.2-x86_64.msi' against software restriction policy
MSI (s) (64:3C) [19:40:45:692]: Note: 1: 2262 2: DigitalSignature 3: -2147287038 
MSI (s) (64:3C) [19:40:45:692]: SOFTWARE RESTRICTION POLICY: C:\Windows\TEMP\chocolatey\stevedore\0.0.2\stevedore-0.0.2-x86_64.msi is not digitally signed
MSI (s) (64:3C) [19:40:45:724]: SOFTWARE RESTRICTION POLICY: C:\Windows\TEMP\chocolatey\stevedore\0.0.2\stevedore-0.0.2-x86_64.msi is permitted to run because the user token authorizes execution (system or service token).
MSI (s) (64:3C) [19:40:45:724]: MSCOREE not loaded loading copy from system32
MSI (s) (64:3C) [19:40:46:052]: End dialog not enabled
MSI (s) (64:3C) [19:40:46:052]: Original package ==> C:\Windows\TEMP\chocolatey\stevedore\0.0.2\stevedore-0.0.2-x86_64.msi
MSI (s) (64:3C) [19:40:46:052]: Package we're running from ==> C:\Windows\Installer\1068ce.msi
MSI (s) (64:3C) [19:40:46:099]: APPCOMPAT: Compatibility mode property overrides found.
MSI (s) (64:3C) [19:40:46:114]: APPCOMPAT: looking for appcompat database entry with ProductCode '{25FDBBF2-09C4-4CD6-93B1-29FDFC6193C8}'.
MSI (s) (64:3C) [19:40:46:145]: APPCOMPAT: no matching ProductCode found in database.
MSI (s) (64:3C) [19:40:47:270]: Machine policy value 'TransformsSecure' is 1
MSI (s) (64:3C) [19:40:47:349]: Machine policy value 'DisablePatch' is 0
MSI (s) (64:3C) [19:40:47:349]: Machine policy value 'AllowLockdownPatch' is 0
MSI (s) (64:3C) [19:40:47:349]: Machine policy value 'DisableLUAPatching' is 0
MSI (s) (64:3C) [19:40:47:349]: Machine policy value 'DisableFlyWeightPatching' is 0
MSI (s) (64:3C) [19:40:47:364]: APPCOMPAT: looking for appcompat database entry with ProductCode '{25FDBBF2-09C4-4CD6-93B1-29FDFC6193C8}'.
MSI (s) (64:3C) [19:40:47:364]: APPCOMPAT: no matching ProductCode found in database.
MSI (s) (64:3C) [19:40:47:364]: Transforms are not secure.
MSI (s) (64:3C) [19:40:47:364]: PROPERTY CHANGE: Adding MsiLogFileLocation property. Its value is 'C:\Windows\TEMP\chocolatey\stevedore.0.0.2.MsiInstall.log'.
MSI (s) (64:3C) [19:40:47:364]: Command Line: REBOOT=ReallySuppress CURRENTDIRECTORY=C:\Windows\system32\config\systemprofile CLIENTUILEVEL=3 CLIENTPROCESSID=3064 
MSI (s) (64:3C) [19:40:47:364]: PROPERTY CHANGE: Adding PackageCode property. Its value is '{8DF632A0-1D65-4ADC-B9CB-C5AF513893A7}'.
MSI (s) (64:3C) [19:40:47:364]: Product Code passed to Engine.Initialize:           ''
MSI (s) (64:3C) [19:40:47:364]: Product Code from property table before transforms: '{25FDBBF2-09C4-4CD6-93B1-29FDFC6193C8}'
MSI (s) (64:3C) [19:40:47:364]: Product Code from property table after transforms:  '{25FDBBF2-09C4-4CD6-93B1-29FDFC6193C8}'
MSI (s) (64:3C) [19:40:47:364]: Product not registered: beginning first-time install
MSI (s) (64:3C) [19:40:47:364]: Product {25FDBBF2-09C4-4CD6-93B1-29FDFC6193C8} is not managed.
MSI (s) (64:3C) [19:40:47:364]: MSI_LUA: Credential prompt not required, user is an admin
MSI (s) (64:3C) [19:40:47:380]: PROPERTY CHANGE: Adding ProductState property. Its value is '-1'.
MSI (s) (64:3C) [19:40:47:380]: Entering CMsiConfigurationManager::SetLastUsedSource.
MSI (s) (64:3C) [19:40:47:380]: User policy value 'SearchOrder' is 'nmu'
MSI (s) (64:3C) [19:40:47:380]: Adding new sources is allowed.
MSI (s) (64:3C) [19:40:47:380]: PROPERTY CHANGE: Adding PackagecodeChanging property. Its value is '1'.
MSI (s) (64:3C) [19:40:47:380]: Package name extracted from package path: 'stevedore-0.0.2-x86_64.msi'
MSI (s) (64:3C) [19:40:47:380]: Package to be registered: 'stevedore-0.0.2-x86_64.msi'
MSI (s) (64:3C) [19:40:47:380]: Note: 1: 2262 2: AdminProperties 3: -2147287038 
MSI (s) (64:3C) [19:40:47:380]: Machine policy value 'DisableMsi' is 1
MSI (s) (64:3C) [19:40:47:380]: Machine policy value 'AlwaysInstallElevated' is 0
MSI (s) (64:3C) [19:40:47:380]: User policy value 'AlwaysInstallElevated' is 0
MSI (s) (64:3C) [19:40:47:380]: Product installation will be elevated because user is admin and product is being installed per-machine.
MSI (s) (64:3C) [19:40:47:380]: Running product '{25FDBBF2-09C4-4CD6-93B1-29FDFC6193C8}' with elevated privileges: Product is assigned.
MSI (s) (64:3C) [19:40:47:380]: PROPERTY CHANGE: Adding REBOOT property. Its value is 'ReallySuppress'.
MSI (s) (64:3C) [19:40:47:380]: PROPERTY CHANGE: Adding CURRENTDIRECTORY property. Its value is 'C:\Windows\system32\config\systemprofile'.
MSI (s) (64:3C) [19:40:47:380]: PROPERTY CHANGE: Adding CLIENTUILEVEL property. Its value is '3'.
MSI (s) (64:3C) [19:40:47:380]: PROPERTY CHANGE: Adding CLIENTPROCESSID property. Its value is '3064'.
MSI (s) (64:3C) [19:40:47:380]: Machine policy value 'DisableAutomaticApplicationShutdown' is 0
MSI (s) (64:3C) [19:40:47:427]: PROPERTY CHANGE: Adding MsiRestartManagerSessionKey property. Its value is '28b13990d236e94ca717f9dab47c9aa0'.
MSI (s) (64:3C) [19:40:47:427]: RESTART MANAGER: Session opened.
MSI (s) (64:3C) [19:40:47:427]: TRANSFORMS property is now: 
MSI (s) (64:3C) [19:40:47:427]: PROPERTY CHANGE: Adding VersionDatabase property. Its value is '450'.
MSI (s) (64:3C) [19:40:47:442]: SHELL32::SHGetFolderPath returned: C:\Windows\system32\config\systemprofile\AppData\Roaming
MSI (s) (64:3C) [19:40:47:442]: SHELL32::SHGetFolderPath returned: C:\Windows\system32\config\systemprofile\Favorites
MSI (s) (64:3C) [19:40:47:442]: SHELL32::SHGetFolderPath returned: C:\Windows\system32\config\systemprofile\AppData\Roaming\Microsoft\Windows\Network Shortcuts
MSI (s) (64:3C) [19:40:47:458]: SHELL32::SHGetFolderPath returned: C:\Windows\system32\config\systemprofile\Documents
MSI (s) (64:3C) [19:40:47:458]: SHELL32::SHGetFolderPath returned: C:\Windows\system32\config\systemprofile\AppData\Roaming\Microsoft\Windows\Printer Shortcuts
MSI (s) (64:3C) [19:40:47:458]: SHELL32::SHGetFolderPath returned: C:\Windows\system32\config\systemprofile\AppData\Roaming\Microsoft\Windows\Recent
MSI (s) (64:3C) [19:40:47:458]: SHELL32::SHGetFolderPath returned: C:\Windows\system32\config\systemprofile\AppData\Roaming\Microsoft\Windows\SendTo
MSI (s) (64:3C) [19:40:47:474]: SHELL32::SHGetFolderPath returned: C:\Windows\system32\config\systemprofile\AppData\Roaming\Microsoft\Windows\Templates
MSI (s) (64:3C) [19:40:47:474]: SHELL32::SHGetFolderPath returned: C:\ProgramData
MSI (s) (64:3C) [19:40:47:474]: SHELL32::SHGetFolderPath returned: C:\Windows\system32\config\systemprofile\AppData\Local
MSI (s) (64:3C) [19:40:47:474]: SHELL32::SHGetFolderPath returned: C:\Windows\system32\config\systemprofile\Pictures
MSI (s) (64:3C) [19:40:47:474]: SHELL32::SHGetFolderPath returned: C:\ProgramData\Microsoft\Windows\Start Menu\Programs\Administrative Tools
MSI (s) (64:3C) [19:40:47:474]: SHELL32::SHGetFolderPath returned: C:\ProgramData\Microsoft\Windows\Start Menu\Programs\Startup
MSI (s) (64:3C) [19:40:47:474]: SHELL32::SHGetFolderPath returned: C:\ProgramData\Microsoft\Windows\Start Menu\Programs
MSI (s) (64:3C) [19:40:47:474]: SHELL32::SHGetFolderPath returned: C:\ProgramData\Microsoft\Windows\Start Menu
MSI (s) (64:3C) [19:40:47:474]: SHELL32::SHGetFolderPath returned: C:\Users\Public\Desktop
MSI (s) (64:3C) [19:40:47:489]: SHELL32::SHGetFolderPath returned: C:\Windows\system32\config\systemprofile\AppData\Roaming\Microsoft\Windows\Start Menu\Programs\Administrative Tools
MSI (s) (64:3C) [19:40:47:489]: SHELL32::SHGetFolderPath returned: C:\Windows\system32\config\systemprofile\AppData\Roaming\Microsoft\Windows\Start Menu\Programs\Startup
MSI (s) (64:3C) [19:40:47:489]: SHELL32::SHGetFolderPath returned: C:\Windows\system32\config\systemprofile\AppData\Roaming\Microsoft\Windows\Start Menu\Programs
MSI (s) (64:3C) [19:40:47:489]: SHELL32::SHGetFolderPath returned: C:\Windows\system32\config\systemprofile\AppData\Roaming\Microsoft\Windows\Start Menu
MSI (s) (64:3C) [19:40:47:489]: SHELL32::SHGetFolderPath returned: C:\Windows\system32\config\systemprofile\Desktop
MSI (s) (64:3C) [19:40:47:520]: SHELL32::SHGetFolderPath returned: C:\ProgramData\Microsoft\Windows\Templates
MSI (s) (64:3C) [19:40:47:520]: SHELL32::SHGetFolderPath returned: C:\Windows\Fonts
MSI (s) (64:3C) [19:40:47:552]: Note: 1: 2898 2: MS Sans Serif 3: MS Sans Serif 4: 0 5: 16 
MSI (s) (64:3C) [19:40:47:567]: MSI_LUA: Setting MsiRunningElevated property to 1 because the install is already running elevated.
MSI (s) (64:3C) [19:40:47:567]: PROPERTY CHANGE: Adding MsiRunningElevated property. Its value is '1'.
MSI (s) (64:3C) [19:40:47:567]: PROPERTY CHANGE: Adding Privileged property. Its value is '1'.
MSI (s) (64:3C) [19:40:47:567]: Note: 1: 1402 2: HKEY_CURRENT_USER\Software\Microsoft\MS Setup (ACME)\User Info 3: 2 
MSI (s) (64:3C) [19:40:47:567]: Note: 1: 1402 2: HKEY_CURRENT_USER\Software\Microsoft\MS Setup (ACME)\User Info 3: 2 
MSI (s) (64:3C) [19:40:47:567]: PROPERTY CHANGE: Adding DATABASE property. Its value is 'C:\Windows\Installer\1068ce.msi'.
MSI (s) (64:3C) [19:40:47:567]: PROPERTY CHANGE: Adding OriginalDatabase property. Its value is 'C:\Windows\TEMP\chocolatey\stevedore\0.0.2\stevedore-0.0.2-x86_64.msi'.
MSI (s) (64:3C) [19:40:47:567]: Machine policy value 'MsiDisableEmbeddedUI' is 0
MSI (s) (64:3C) [19:40:47:567]: EEUI - Disabling MsiEmbeddedUI for service because it's not a quiet/basic install
MSI (s) (64:3C) [19:40:47:567]: Note: 1: 2205 2:  3: PatchPackage 
MSI (s) (64:3C) [19:40:47:567]: Machine policy value 'DisableRollback' is 0
MSI (s) (64:3C) [19:40:47:567]: User policy value 'DisableRollback' is 0
MSI (s) (64:3C) [19:40:47:567]: PROPERTY CHANGE: Adding UILevel property. Its value is '2'.
=== Logging started: 10/8/2021  19:40:47 ===
MSI (s) (64:3C) [19:40:47:567]: Note: 1: 2203 2: C:\Windows\Installer\inprogressinstallinfo.ipi 3: -2147287038 
MSI (s) (64:3C) [19:40:47:567]: APPCOMPAT: [DetectVersionLaunchCondition] Launch condition already passes.
MSI (s) (64:3C) [19:40:47:583]: PROPERTY CHANGE: Adding ACTION property. Its value is 'INSTALL'.
MSI (s) (64:3C) [19:40:47:583]: Doing action: INSTALL
MSI (s) (64:3C) [19:40:47:583]: Note: 1: 2205 2:  3: ActionText 
Action start 19:40:47: INSTALL.
MSI (s) (64:3C) [19:40:47:599]: Running ExecuteSequence
MSI (s) (64:3C) [19:40:47:599]: Doing action: FindRelatedProducts
MSI (s) (64:3C) [19:40:47:599]: Note: 1: 2205 2:  3: ActionText 
Action start 19:40:47: FindRelatedProducts.
MSI (s) (64:3C) [19:40:47:630]: Doing action: LaunchConditions
MSI (s) (64:3C) [19:40:47:630]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:47: FindRelatedProducts. Return value 1.
Action start 19:40:47: LaunchConditions.
MSI (s) (64:3C) [19:40:47:630]: Doing action: ValidateProductID
MSI (s) (64:3C) [19:40:47:630]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:47: LaunchConditions. Return value 1.
Action start 19:40:47: ValidateProductID.
MSI (s) (64:3C) [19:40:47:645]: Doing action: CostInitialize
MSI (s) (64:3C) [19:40:47:645]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:47: ValidateProductID. Return value 1.
MSI (s) (64:3C) [19:40:47:645]: Machine policy value 'MaxPatchCacheSize' is 10
MSI (s) (64:3C) [19:40:47:645]: PROPERTY CHANGE: Adding ROOTDRIVE property. Its value is 'C:\'.
MSI (s) (64:3C) [19:40:47:645]: PROPERTY CHANGE: Adding CostingComplete property. Its value is '0'.
MSI (s) (64:3C) [19:40:47:645]: Note: 1: 2205 2:  3: Patch 
MSI (s) (64:3C) [19:40:47:645]: Note: 1: 2205 2:  3: PatchPackage 
MSI (s) (64:3C) [19:40:47:645]: Note: 1: 2205 2:  3: MsiPatchHeaders 
MSI (s) (64:3C) [19:40:47:645]: Note: 1: 2205 2:  3: __MsiPatchFileList 
MSI (s) (64:3C) [19:40:47:645]: Note: 1: 2205 2:  3: PatchPackage 
MSI (s) (64:3C) [19:40:47:645]: Note: 1: 2228 2:  3: PatchPackage 4: SELECT `DiskId`, `PatchId`, `LastSequence` FROM `Media`, `PatchPackage` WHERE `Media`.`DiskId`=`PatchPackage`.`Media_` ORDER BY `DiskId`  
MSI (s) (64:3C) [19:40:47:645]: Note: 1: 2205 2:  3: Patch 
Action start 19:40:47: CostInitialize.
MSI (s) (64:3C) [19:40:47:661]: Doing action: FileCost
MSI (s) (64:3C) [19:40:47:661]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:47: CostInitialize. Return value 1.
MSI (s) (64:3C) [19:40:47:661]: Note: 1: 2205 2:  3: MsiAssembly 
MSI (s) (64:3C) [19:40:47:661]: Note: 1: 2205 2:  3: Registry 
MSI (s) (64:3C) [19:40:47:661]: Note: 1: 2205 2:  3: Registry 
MSI (s) (64:3C) [19:40:47:661]: Note: 1: 2205 2:  3: Class 
MSI (s) (64:3C) [19:40:47:661]: Note: 1: 2205 2:  3: Extension 
MSI (s) (64:3C) [19:40:47:661]: Note: 1: 2205 2:  3: TypeLib 
Action start 19:40:47: FileCost.
MSI (s) (64:3C) [19:40:47:661]: Doing action: CostFinalize
MSI (s) (64:3C) [19:40:47:661]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:47: FileCost. Return value 1.
MSI (s) (64:3C) [19:40:47:661]: PROPERTY CHANGE: Adding OutOfDiskSpace property. Its value is '0'.
MSI (s) (64:3C) [19:40:47:661]: PROPERTY CHANGE: Adding OutOfNoRbDiskSpace property. Its value is '0'.
MSI (s) (64:3C) [19:40:47:661]: PROPERTY CHANGE: Adding PrimaryVolumeSpaceAvailable property. Its value is '0'.
MSI (s) (64:3C) [19:40:47:661]: PROPERTY CHANGE: Adding PrimaryVolumeSpaceRequired property. Its value is '0'.
MSI (s) (64:3C) [19:40:47:661]: PROPERTY CHANGE: Adding PrimaryVolumeSpaceRemaining property. Its value is '0'.
MSI (s) (64:3C) [19:40:47:661]: Note: 1: 2205 2:  3: Patch 
MSI (s) (64:3C) [19:40:47:661]: Note: 1: 2205 2:  3: Condition 
MSI (s) (64:3C) [19:40:47:661]: PROPERTY CHANGE: Adding TARGETDIR property. Its value is 'C:\'.
MSI (s) (64:3C) [19:40:47:661]: PROPERTY CHANGE: Adding Docker property. Its value is 'C:\Program Files\Docker\'.
MSI (s) (64:3C) [19:40:47:661]: PROPERTY CHANGE: Adding cli_plugins property. Its value is 'C:\Program Files\Docker\cli-plugins\'.
MSI (s) (64:3C) [19:40:47:661]: PROPERTY CHANGE: Adding APPLICATIONFOLDER property. Its value is 'C:\Program Files\Stevedore\'.
MSI (s) (64:3C) [19:40:47:661]: PROPERTY CHANGE: Adding bin property. Its value is 'C:\Program Files\Stevedore\bin\'.
MSI (s) (64:3C) [19:40:47:661]: Target path resolution complete. Dumping Directory table...
MSI (s) (64:3C) [19:40:47:661]: Note: target paths subject to change (via custom actions or browsing)
MSI (s) (64:3C) [19:40:47:661]: Dir (target): Key: TARGETDIR    , Object: C:\
MSI (s) (64:3C) [19:40:47:661]: Dir (target): Key: ProgramFiles64Folder , Object: C:\Program Files\
MSI (s) (64:3C) [19:40:47:661]: Dir (target): Key: Docker   , Object: C:\Program Files\Docker\
MSI (s) (64:3C) [19:40:47:661]: Dir (target): Key: cli_plugins  , Object: C:\Program Files\Docker\cli-plugins\
MSI (s) (64:3C) [19:40:47:661]: Dir (target): Key: APPLICATIONFOLDER    , Object: C:\Program Files\Stevedore\
MSI (s) (64:3C) [19:40:47:661]: Dir (target): Key: bin  , Object: C:\Program Files\Stevedore\bin\
MSI (s) (64:3C) [19:40:47:661]: PROPERTY CHANGE: Adding INSTALLLEVEL property. Its value is '1'.
MSI (s) (64:3C) [19:40:47:661]: Note: 1: 2205 2:  3: MsiAssembly 
MSI (s) (64:3C) [19:40:47:661]: Note: 1: 2228 2:  3: MsiAssembly 4:  SELECT `MsiAssembly`.`Attributes`, `MsiAssembly`.`File_Application`, `MsiAssembly`.`File_Manifest`,  `Component`.`KeyPath` FROM `MsiAssembly`, `Component` WHERE  `MsiAssembly`.`Component_` = `Component`.`Component` AND `MsiAssembly`.`Component_` = ? 
Action start 19:40:47: CostFinalize.
MSI (s) (64:3C) [19:40:47:661]: Doing action: SetARPINSTALLLOCATION
MSI (s) (64:3C) [19:40:47:661]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:47: CostFinalize. Return value 1.
MSI (s) (64:3C) [19:40:47:661]: PROPERTY CHANGE: Adding ARPINSTALLLOCATION property. Its value is 'C:\Program Files\Stevedore\'.
Action start 19:40:47: SetARPINSTALLLOCATION.
MSI (s) (64:3C) [19:40:47:661]: Doing action: MigrateFeatureStates
MSI (s) (64:3C) [19:40:47:661]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:47: SetARPINSTALLLOCATION. Return value 1.
Action start 19:40:47: MigrateFeatureStates.
MSI (s) (64:3C) [19:40:47:677]: Doing action: InstallValidate
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:47: MigrateFeatureStates. Return value 0.
MSI (s) (64:3C) [19:40:47:677]: PROPERTY CHANGE: Deleting MsiRestartManagerSessionKey property. Its current value is '28b13990d236e94ca717f9dab47c9aa0'.
MSI (s) (64:3C) [19:40:47:677]: Feature: Binaries; Installed: Absent;   Request: Local;   Action: Local
MSI (s) (64:3C) [19:40:47:677]: Component: License; Installed: Absent;   Request: Local;   Action: Local
MSI (s) (64:3C) [19:40:47:677]: Component: dockerd_Comp; Installed: Absent;   Request: Local;   Action: Local
MSI (s) (64:3C) [19:40:47:677]: Component: bin_Comp; Installed: Absent;   Request: Local;   Action: Local
MSI (s) (64:3C) [19:40:47:677]: Component: cli_plugins_Comp; Installed: Absent;   Request: Local;   Action: Local
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: Registry 
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: BindImage 
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: ProgId 
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: PublishComponent 
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: SelfReg 
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: Extension 
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: Font 
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: Shortcut 
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: Class 
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: Icon 
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: TypeLib 
Action start 19:40:47: InstallValidate.
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: _RemoveFilePath 
MSI (s) (64:3C) [19:40:47:677]: PROPERTY CHANGE: Modifying CostingComplete property. Its current value is '0'. Its new value: '1'.
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: Registry 
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: BindImage 
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: ProgId 
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: PublishComponent 
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: SelfReg 
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: Extension 
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: Font 
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: Shortcut 
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: Class 
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: Icon 
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: TypeLib 
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2727 2:  
MSI (s) (64:3C) [19:40:47:677]: Note: 1: 2205 2:  3: FilesInUse 
MSI (s) (64:3C) [19:40:47:692]: Note: 1: 2727 2:  
MSI (s) (64:3C) [19:40:47:692]: Doing action: InstallInitialize
MSI (s) (64:3C) [19:40:47:692]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:47: InstallValidate. Return value 1.
MSI (s) (64:3C) [19:40:47:692]: Machine policy value 'AlwaysInstallElevated' is 0
MSI (s) (64:3C) [19:40:47:692]: User policy value 'AlwaysInstallElevated' is 0
MSI (s) (64:3C) [19:40:47:692]: BeginTransaction: Locking Server
MSI (s) (64:3C) [19:40:47:708]: Note: 1: 2203 2: C:\Windows\Installer\inprogressinstallinfo.ipi 3: -2147287038 
MSI (s) (64:3C) [19:40:47:708]: SRSetRestorePoint skipped for this transaction.
MSI (s) (64:3C) [19:40:47:708]: Note: 1: 2203 2: C:\Windows\Installer\inprogressinstallinfo.ipi 3: -2147287038 
MSI (s) (64:3C) [19:40:47:708]: Server not locked: locking for product {25FDBBF2-09C4-4CD6-93B1-29FDFC6193C8}
Action start 19:40:47: InstallInitialize.
MSI (s) (64:3C) [19:40:47:989]: Doing action: RemoveExistingProducts
MSI (s) (64:3C) [19:40:47:989]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:47: InstallInitialize. Return value 1.
Action start 19:40:47: RemoveExistingProducts.
MSI (s) (64:3C) [19:40:48:005]: Doing action: ProcessComponents
MSI (s) (64:3C) [19:40:48:005]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:48: RemoveExistingProducts. Return value 1.
MSI (s) (64:3C) [19:40:48:005]: Note: 1: 2205 2:  3: MsiPatchCertificate 
MSI (s) (64:3C) [19:40:48:005]: LUA patching is disabled: missing MsiPatchCertificate table
MSI (s) (64:3C) [19:40:48:005]: Resolving source.
MSI (s) (64:3C) [19:40:48:005]: Resolving source to launched-from source.
MSI (s) (64:3C) [19:40:48:005]: Setting launched-from source as last-used.
MSI (s) (64:3C) [19:40:48:005]: PROPERTY CHANGE: Adding SourceDir property. Its value is 'C:\Windows\TEMP\chocolatey\stevedore\0.0.2\'.
MSI (s) (64:3C) [19:40:48:005]: PROPERTY CHANGE: Adding SOURCEDIR property. Its value is 'C:\Windows\TEMP\chocolatey\stevedore\0.0.2\'.
MSI (s) (64:3C) [19:40:48:005]: PROPERTY CHANGE: Adding SourcedirProduct property. Its value is '{25FDBBF2-09C4-4CD6-93B1-29FDFC6193C8}'.
MSI (s) (64:3C) [19:40:48:005]: SOURCEDIR ==> C:\Windows\TEMP\chocolatey\stevedore\0.0.2\
MSI (s) (64:3C) [19:40:48:005]: SOURCEDIR product ==> {25FDBBF2-09C4-4CD6-93B1-29FDFC6193C8}
MSI (s) (64:3C) [19:40:48:020]: Determining source type
MSI (s) (64:3C) [19:40:48:020]: Source type from package 'stevedore-0.0.2-x86_64.msi': 2
MSI (s) (64:3C) [19:40:48:020]: SECREPAIR: Hash Database: C:\Windows\Installer\SourceHash{25FDBBF2-09C4-4CD6-93B1-29FDFC6193C8}
MSI (s) (64:3C) [19:40:48:036]: Note: 1: 2262 2: SourceHash 3: -2147287038 
MSI (s) (64:3C) [19:40:48:208]: SECREPAIR: New Hash Database creation complete.
Action start 19:40:48: ProcessComponents.
MSI (s) (64:3C) [19:40:49:239]: Source path resolution complete. Dumping Directory table...
MSI (s) (64:3C) [19:40:49:239]: Dir (source): Key: TARGETDIR    , Object: C:\Windows\TEMP\chocolatey\stevedore\0.0.2\   , LongSubPath:  , ShortSubPath: 
MSI (s) (64:3C) [19:40:49:239]: Dir (source): Key: ProgramFiles64Folder , Object: C:\Windows\TEMP\chocolatey\stevedore\0.0.2\   , LongSubPath: PFiles\  , ShortSubPath: 
MSI (s) (64:3C) [19:40:49:239]: Dir (source): Key: Docker   , Object: C:\Windows\TEMP\chocolatey\stevedore\0.0.2\   , LongSubPath: PFiles\Docker\   , ShortSubPath: 
MSI (s) (64:3C) [19:40:49:239]: Dir (source): Key: cli_plugins  , Object: C:\Windows\TEMP\chocolatey\stevedore\0.0.2\   , LongSubPath: PFiles\Docker\cli-plugins\   , ShortSubPath: PFiles\Docker\2ixflye6\
MSI (s) (64:3C) [19:40:49:239]: Dir (source): Key: APPLICATIONFOLDER    , Object: C:\Windows\TEMP\chocolatey\stevedore\0.0.2\   , LongSubPath: PFiles\Stevedore\    , ShortSubPath: PFiles\6p49cjgt\
MSI (s) (64:3C) [19:40:49:239]: Dir (source): Key: bin  , Object: C:\Windows\TEMP\chocolatey\stevedore\0.0.2\   , LongSubPath: PFiles\Stevedore\bin\    , ShortSubPath: PFiles\6p49cjgt\bin\
MSI (s) (64:3C) [19:40:49:239]: Note: 1: 2205 2:  3: ActionText 
MSI (s) (64:3C) [19:40:49:239]: Note: 1: 2205 2:  3: ActionText 
MSI (s) (64:3C) [19:40:49:255]: Note: 1: 2205 2:  3: ActionText 
MSI (s) (64:3C) [19:40:49:255]: Doing action: UnpublishFeatures
MSI (s) (64:3C) [19:40:49:255]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:49: ProcessComponents. Return value 1.
Action start 19:40:49: UnpublishFeatures.
MSI (s) (64:3C) [19:40:49:255]: Doing action: StopServices
MSI (s) (64:3C) [19:40:49:255]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:49: UnpublishFeatures. Return value 1.
Action start 19:40:49: StopServices.
MSI (s) (64:3C) [19:40:49:255]: Doing action: DeleteServices
MSI (s) (64:3C) [19:40:49:255]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:49: StopServices. Return value 1.
Action start 19:40:49: DeleteServices.
MSI (s) (64:3C) [19:40:49:270]: Doing action: RemoveEnvironmentStrings
MSI (s) (64:3C) [19:40:49:270]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:49: DeleteServices. Return value 1.
Action start 19:40:49: RemoveEnvironmentStrings.
MSI (s) (64:3C) [19:40:49:286]: Doing action: RemoveFiles
MSI (s) (64:3C) [19:40:49:286]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:49: RemoveEnvironmentStrings. Return value 1.
MSI (s) (64:3C) [19:40:49:286]: Note: 1: 2205 2:  3: RemoveFile 
MSI (s) (64:3C) [19:40:49:286]: Note: 1: 2205 2:  3: RemoveFile 
Action start 19:40:49: RemoveFiles.
MSI (s) (64:3C) [19:40:49:286]: Doing action: SetCreateDockerGroup
MSI (s) (64:3C) [19:40:49:286]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:49: RemoveFiles. Return value 0.
MSI (s) (64:3C) [19:40:49:302]: PROPERTY CHANGE: Adding CreateDockerGroup property. Its value is '"C:\Windows\sysnative\WindowsPowerShell\v1.0\powershell.exe" -Command "(Get-LocalGroup -Name docker-users) -or (New-LocalGroup -Name docker-users -Description 'Users of Docker Desktop')"'.
Action start 19:40:49: SetCreateDockerGroup.
MSI (s) (64:3C) [19:40:49:302]: Doing action: CreateDockerGroup
MSI (s) (64:3C) [19:40:49:302]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:49: SetCreateDockerGroup. Return value 1.
Action start 19:40:49: CreateDockerGroup.
MSI (s) (64:3C) [19:40:49:302]: Doing action: SetInstallContainersFeature
MSI (s) (64:3C) [19:40:49:302]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:49: CreateDockerGroup. Return value 1.
MSI (s) (64:3C) [19:40:49:302]: PROPERTY CHANGE: Adding InstallContainersFeature property. Its value is '"C:\Windows\sysnative\WindowsPowerShell\v1.0\powershell.exe" -Command "Enable-WindowsOptionalFeature -Online -FeatureName Containers -All -NoRestart"'.
Action start 19:40:49: SetInstallContainersFeature.
MSI (s) (64:3C) [19:40:49:302]: Doing action: InstallContainersFeature
MSI (s) (64:3C) [19:40:49:302]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:49: SetInstallContainersFeature. Return value 1.
Action start 19:40:49: InstallContainersFeature.
MSI (s) (64:3C) [19:40:49:317]: Doing action: ConfigureUsers
MSI (s) (64:3C) [19:40:49:317]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:49: InstallContainersFeature. Return value 1.
MSI (s) (64:B4) [19:40:49:317]: Invoking remote custom action. DLL: C:\Windows\Installer\MSI7801.tmp, Entrypoint: ConfigureUsers
MSI (s) (64:1C) [19:40:49:317]: Generating random cookie.
MSI (s) (64:1C) [19:40:49:411]: Created Custom Action Server with PID 720 (0x2D0).
MSI (s) (64:FC) [19:40:50:427]: Running as a service.
MSI (s) (64:FC) [19:40:50:552]: Hello, I'm your 32bit Elevated Non-remapped custom action server.
MSI (s) (64!8C) [19:40:55:583]: PROPERTY CHANGE: Adding CreateUserRollback property. Its value is '**********'.
Action start 19:40:49: ConfigureUsers.
MSI (s) (64!8C) [19:40:55:583]: Doing action: CreateUserRollback
MSI (s) (64!8C) [19:40:55:583]: Note: 1: 2205 2:  3: ActionText 
Action start 19:40:55: CreateUserRollback.
MSI (s) (64!8C) [19:40:55:583]: PROPERTY CHANGE: Adding CreateUser property. Its value is '**********'.
Action ended 19:40:55: CreateUserRollback. Return value 1.
MSI (s) (64!8C) [19:40:55:583]: Doing action: CreateUser
MSI (s) (64!8C) [19:40:55:583]: Note: 1: 2205 2:  3: ActionText 
Action start 19:40:55: CreateUser.
Action ended 19:40:55: CreateUser. Return value 1.
MSI (s) (64:3C) [19:40:55:630]: Doing action: InstallFiles
MSI (s) (64:3C) [19:40:55:630]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:55: ConfigureUsers. Return value 1.
Action start 19:40:55: InstallFiles.
MSI (s) (64:3C) [19:40:55:661]: Note: 1: 2205 2:  3: Patch 
MSI (s) (64:3C) [19:40:55:661]: Note: 1: 2228 2:  3: Patch 4: SELECT `Patch`.`File_`, `Patch`.`Header`, `Patch`.`Attributes`, `Patch`.`Sequence`, `Patch`.`StreamRef_` FROM `Patch` WHERE `Patch`.`File_` = ? AND `Patch`.`#_MsiActive`=? ORDER BY `Patch`.`Sequence` 
MSI (s) (64:3C) [19:40:55:661]: Note: 1: 2205 2:  3: MsiSFCBypass 
MSI (s) (64:3C) [19:40:55:661]: Note: 1: 2228 2:  3: MsiSFCBypass 4: SELECT `File_` FROM `MsiSFCBypass` WHERE `File_` = ? 
MSI (s) (64:3C) [19:40:55:661]: Note: 1: 2205 2:  3: MsiPatchHeaders 
MSI (s) (64:3C) [19:40:55:661]: Note: 1: 2228 2:  3: MsiPatchHeaders 4: SELECT `Header` FROM `MsiPatchHeaders` WHERE `StreamRef` = ? 
MSI (s) (64:3C) [19:40:55:661]: Note: 1: 2205 2:  3: PatchPackage 
MSI (s) (64:3C) [19:40:55:677]: Note: 1: 2205 2:  3: MsiPatchHeaders 
MSI (s) (64:3C) [19:40:55:677]: Note: 1: 2205 2:  3: PatchPackage 
MSI (s) (64:3C) [19:40:55:692]: Doing action: WriteEnvironmentStrings
MSI (s) (64:3C) [19:40:55:692]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:55: InstallFiles. Return value 1.
Action start 19:40:55: WriteEnvironmentStrings.
MSI (s) (64:3C) [19:40:55:692]: Doing action: InstallServices
MSI (s) (64:3C) [19:40:55:692]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:55: WriteEnvironmentStrings. Return value 1.
Action start 19:40:55: InstallServices.
MSI (s) (64:3C) [19:40:55:692]: Doing action: StartServices
MSI (s) (64:3C) [19:40:55:692]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:55: InstallServices. Return value 1.
Action start 19:40:55: StartServices.
MSI (s) (64:3C) [19:40:55:692]: Doing action: RegisterUser
MSI (s) (64:3C) [19:40:55:692]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:55: StartServices. Return value 1.
Action start 19:40:55: RegisterUser.
MSI (s) (64:3C) [19:40:55:708]: Doing action: RegisterProduct
MSI (s) (64:3C) [19:40:55:708]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:55: RegisterUser. Return value 1.
Action start 19:40:55: RegisterProduct.
MSI (s) (64:3C) [19:40:55:708]: PROPERTY CHANGE: Adding ProductToBeRegistered property. Its value is '1'.
MSI (s) (64:3C) [19:40:55:708]: Doing action: PublishFeatures
MSI (s) (64:3C) [19:40:55:708]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:55: RegisterProduct. Return value 1.
Action start 19:40:55: PublishFeatures.
MSI (s) (64:3C) [19:40:55:724]: Doing action: PublishProduct
MSI (s) (64:3C) [19:40:55:724]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:55: PublishFeatures. Return value 1.
MSI (s) (64:3C) [19:40:55:724]: Note: 1: 2205 2:  3: Icon 
MSI (s) (64:3C) [19:40:55:724]: Note: 1: 2228 2:  3: Icon 4: SELECT `Name`, `Data` FROM `Icon` 
Action start 19:40:55: PublishProduct.
MSI (s) (64:3C) [19:40:55:739]: Doing action: InstallFinalize
MSI (s) (64:3C) [19:40:55:739]: Note: 1: 2205 2:  3: ActionText 
Action ended 19:40:55: PublishProduct. Return value 1.
MSI (s) (64:3C) [19:40:55:755]: Running Script: C:\Windows\Installer\MSI77B2.tmp
MSI (s) (64:3C) [19:40:55:755]: PROPERTY CHANGE: Adding UpdateStarted property. Its value is '1'.
MSI (s) (64:3C) [19:40:55:802]: Note: 1: 2265 2:  3: -2147287035 
MSI (s) (64:3C) [19:40:55:802]: Machine policy value 'DisableRollback' is 0
MSI (s) (64:3C) [19:40:55:817]: Note: 1: 2318 2:  
MSI (s) (64:3C) [19:40:55:833]: Note: 1: 2318 2:  
MSI (s) (64:3C) [19:40:55:833]: Note: 1: 1402 2: HKEY_LOCAL_MACHINE\Software\Microsoft\Windows\CurrentVersion\Installer\Rollback\Scripts 3: 2 
MSI (s) (64:3C) [19:40:55:864]: Executing op: Header(Signature=1397708873,Version=500,Timestamp=1397267737,LangId=1033,Platform=589824,ScriptType=1,ScriptMajorVersion=21,ScriptMinorVersion=4,ScriptAttributes=1)
Action start 19:40:55: InstallFinalize.
MSI (s) (64:3C) [19:40:55:880]: Executing op: ProductInfo(ProductKey={25FDBBF2-09C4-4CD6-93B1-29FDFC6193C8},ProductName=Stevedore,PackageName=stevedore-0.0.2-x86_64.msi,Language=1033,Version=2,Assignment=1,ObsoleteArg=0,,,PackageCode={8DF632A0-1D65-4ADC-B9CB-C5AF513893A7},,,InstanceType=0,LUASetting=0,RemoteURTInstalls=0,ProductDeploymentFlags=3)
MSI (s) (64:3C) [19:40:55:880]: Executing op: DialogInfo(Type=0,Argument=1033)
MSI (s) (64:3C) [19:40:55:895]: Executing op: DialogInfo(Type=1,Argument=Stevedore)
MSI (s) (64:3C) [19:40:55:895]: Executing op: RollbackInfo(,RollbackAction=Rollback,RollbackDescription=Rolling back action:,RollbackTemplate=[1],CleanupAction=RollbackCleanup,CleanupDescription=Removing backup files,CleanupTemplate=File: [1])
MSI (s) (64:3C) [19:40:55:895]: Executing op: SetBaseline(Baseline=0,)
MSI (s) (64:3C) [19:40:55:895]: Executing op: SetBaseline(Baseline=1,)
MSI (s) (64:3C) [19:40:55:895]: Executing op: ActionStart(Name=ProcessComponents,Description=Updating component registration,)
MSI (s) (64:3C) [19:40:55:895]: Executing op: ProgressTotal(Total=4,Type=1,ByteEquivalent=24000)
MSI (s) (64:3C) [19:40:55:895]: Executing op: ComponentRegister(ComponentId={9DC0FD11-AA21-57A0-AAAF-DC34FF2DC0F6},KeyPath=C:\Program Files\Stevedore\License.rtf,State=3,,Disk=1,SharedDllRefCount=0,BinaryType=1)
MSI (s) (64:3C) [19:40:55:911]: Executing op: ComponentRegister(ComponentId={7CF1EA33-D17E-52C5-8092-0B901F2428E0},KeyPath=C:\Program Files\Stevedore\dockerd.exe,State=3,,Disk=1,SharedDllRefCount=0,BinaryType=1)
MSI (s) (64:3C) [19:40:55:911]: Executing op: ComponentRegister(ComponentId={E68F9AC8-53CC-378F-8C95-589BAB7D0B21},KeyPath=C:\Program Files\Stevedore\bin\docker.exe,State=3,,Disk=1,SharedDllRefCount=0,BinaryType=1)
MSI (s) (64:3C) [19:40:55:911]: Executing op: ComponentRegister(ComponentId={A0307534-D554-3265-AC67-19D45951FD96},KeyPath=C:\Program Files\Docker\cli-plugins\docker-app.exe,State=3,,Disk=1,SharedDllRefCount=0,BinaryType=1)
MSI (s) (64:3C) [19:40:55:911]: Executing op: ActionStart(Name=StopServices,Description=Stopping services,Template=Service: [1])
MSI (s) (64:3C) [19:40:55:911]: Executing op: ProgressTotal(Total=1,Type=1,ByteEquivalent=1300000)
MSI (s) (64:3C) [19:40:55:911]: Executing op: ServiceControl(,Name=stevedored,Action=2,Wait=1,)
MSI (s) (64:3C) [19:40:55:911]: Executing op: ActionStart(Name=CreateDockerGroup,,)
MSI (s) (64:3C) [19:40:55:911]: Executing op: CustomActionSchedule(Action=CreateDockerGroup,ActionType=3073,Source=BinaryData,Target=WixQuietExec,CustomActionData="C:\Windows\sysnative\WindowsPowerShell\v1.0\powershell.exe" -Command "(Get-LocalGroup -Name docker-users) -or (New-LocalGroup -Name docker-users -Description 'Users of Docker Desktop')")
MSI (s) (64:74) [19:40:55:927]: Invoking remote custom action. DLL: C:\Windows\Installer\MSI91C4.tmp, Entrypoint: WixQuietExec
MSI (s) (64:3C) [19:41:00:583]: Executing op: ActionStart(Name=InstallContainersFeature,,)
WixQuietExec:  True
MSI (s) (64:3C) [19:41:00:583]: Executing op: CustomActionSchedule(Action=InstallContainersFeature,ActionType=3073,Source=BinaryData,Target=WixQuietExec,CustomActionData="C:\Windows\sysnative\WindowsPowerShell\v1.0\powershell.exe" -Command "Enable-WindowsOptionalFeature -Online -FeatureName Containers -All -NoRestart")
MSI (s) (64:A0) [19:41:00:583]: Invoking remote custom action. DLL: C:\Windows\Installer\MSIA405.tmp, Entrypoint: WixQuietExec
WixQuietExec:  
WixQuietExec:  
WixQuietExec:  Path          : 
WixQuietExec:  Online        : True
WixQuietExec:  RestartNeeded : False
WixQuietExec:  
WixQuietExec:  
MSI (s) (64:3C) [19:41:10:567]: Executing op: ActionStart(Name=CreateUserRollback,,)
WixQuietExec:  
MSI (s) (64:3C) [19:41:10:567]: Executing op: CustomActionSchedule(Action=CreateUserRollback,ActionType=11521,Source=BinaryData,Target=**********,CustomActionData=**********)
MSI (s) (64:3C) [19:41:10:567]: Executing op: ActionStart(Name=CreateUser,,)
MSI (s) (64:3C) [19:41:10:567]: Executing op: CustomActionSchedule(Action=CreateUser,ActionType=11265,Source=BinaryData,Target=**********,CustomActionData=**********)
MSI (s) (64:F4) [19:41:10:567]: Invoking remote custom action. DLL: C:\Windows\Installer\MSICB06.tmp, Entrypoint: CreateUser
CreateUser:  Error 0x800706ba: failed to create user: SYSTEM
MSI (s) (64!7C) [19:41:12:911]: Product: Stevedore -- Error 26401. Failed to create user.  (-2147023174   SYSTEM      )

Error 26401. Failed to create user.  (-2147023174   SYSTEM      )
CustomAction CreateUser returned actual error code 1603 (note this may not be 100% accurate if translation happened inside sandbox)
MSI (s) (64:3C) [19:41:13:005]: Note: 1: 2265 2:  3: -2147287035 
MSI (s) (64:3C) [19:41:13:005]: User policy value 'DisableRollback' is 0
MSI (s) (64:3C) [19:41:13:005]: Machine policy value 'DisableRollback' is 0
Action ended 19:41:12: InstallFinalize. Return value 3.
MSI (s) (64:3C) [19:41:13:036]: Note: 1: 2318 2:  
MSI (s) (64:3C) [19:41:13:052]: Executing op: Header(Signature=1397708873,Version=500,Timestamp=1397267740,LangId=1033,Platform=589824,ScriptType=2,ScriptMajorVersion=21,ScriptMinorVersion=4,ScriptAttributes=1)
MSI (s) (64:3C) [19:41:13:052]: Executing op: DialogInfo(Type=0,Argument=1033)
MSI (s) (64:3C) [19:41:13:052]: Executing op: DialogInfo(Type=1,Argument=Stevedore)
MSI (s) (64:3C) [19:41:13:067]: Executing op: RollbackInfo(,RollbackAction=Rollback,RollbackDescription=Rolling back action:,RollbackTemplate=[1],CleanupAction=RollbackCleanup,CleanupDescription=Removing backup files,CleanupTemplate=File: [1])
MSI (s) (64:3C) [19:41:13:083]: Executing op: ActionStart(Name=CreateUser,,)
MSI (s) (64:3C) [19:41:13:083]: Executing op: ProductInfo(ProductKey={25FDBBF2-09C4-4CD6-93B1-29FDFC6193C8},ProductName=Stevedore,PackageName=stevedore-0.0.2-x86_64.msi,Language=1033,Version=2,Assignment=1,ObsoleteArg=0,,,PackageCode={8DF632A0-1D65-4ADC-B9CB-C5AF513893A7},,,InstanceType=0,LUASetting=0,RemoteURTInstalls=0,ProductDeploymentFlags=3)
MSI (s) (64:3C) [19:41:13:083]: Executing op: ActionStart(Name=CreateUserRollback,,)
MSI (s) (64:3C) [19:41:13:099]: Executing op: CustomActionRollback(Action=CreateUserRollback,ActionType=11521,Source=BinaryData,Target=**********,CustomActionData=**********)
MSI (s) (64:54) [19:41:13:099]: Invoking remote custom action. DLL: C:\Windows\Installer\MSID4EB.tmp, Entrypoint: RemoveUser
RemoveUser:  Error 0x800706ba: failed to delete user account: SYSTEM
CustomAction CreateUserRollback returned actual error code 1603 but will be translated to success due to continue marking
MSI (s) (64:3C) [19:41:15:474]: Executing op: ActionStart(Name=InstallContainersFeature,,)
MSI (s) (64:3C) [19:41:15:474]: Executing op: ActionStart(Name=CreateDockerGroup,,)
MSI (s) (64:3C) [19:41:15:474]: Executing op: ActionStart(Name=StopServices,Description=Stopping services,Template=Service: [1])
MSI (s) (64:3C) [19:41:15:474]: Executing op: ActionStart(Name=ProcessComponents,Description=Updating component registration,)
MSI (s) (64:3C) [19:41:15:474]: Executing op: ComponentUnregister(ComponentId={A0307534-D554-3265-AC67-19D45951FD96},ProductKey={25FDBBF2-09C4-4CD6-93B1-29FDFC6193C8},BinaryType=1,)
MSI (s) (64:3C) [19:41:15:489]: Executing op: ComponentUnregister(ComponentId={E68F9AC8-53CC-378F-8C95-589BAB7D0B21},ProductKey={25FDBBF2-09C4-4CD6-93B1-29FDFC6193C8},BinaryType=1,)
MSI (s) (64:3C) [19:41:15:505]: Executing op: ComponentUnregister(ComponentId={7CF1EA33-D17E-52C5-8092-0B901F2428E0},ProductKey={25FDBBF2-09C4-4CD6-93B1-29FDFC6193C8},BinaryType=1,)
MSI (s) (64:3C) [19:41:15:505]: Executing op: ComponentUnregister(ComponentId={9DC0FD11-AA21-57A0-AAAF-DC34FF2DC0F6},ProductKey={25FDBBF2-09C4-4CD6-93B1-29FDFC6193C8},BinaryType=1,)
MSI (s) (64:3C) [19:41:15:505]: Executing op: End(Checksum=0,ProgressTotalHDWord=0,ProgressTotalLDWord=0)
MSI (s) (64:3C) [19:41:15:505]: Error in rollback skipped.  Return: 5
MSI (s) (64:3C) [19:41:15:614]: Note: 1: 2318 2:  
MSI (s) (64:3C) [19:41:15:614]: Note: 1: 2318 2:  
MSI (s) (64:3C) [19:41:15:614]: No System Restore sequence number for this installation.
MSI (s) (64:3C) [19:41:15:614]: Unlocking Server
MSI (s) (64:3C) [19:41:15:661]: PROPERTY CHANGE: Deleting UpdateStarted property. Its current value is '1'.
Action ended 19:41:15: INSTALL. Return value 3.
Property(S): DiskPrompt = Stevedore Installation
Property(S): UpgradeCode = {9A68EC9C-B494-4CDD-B081-F074FE91BF0B}
Property(S): APPLICATIONFOLDER = C:\Program Files\Stevedore\
Property(S): bin = C:\Program Files\Stevedore\bin\
Property(S): cli_plugins = C:\Program Files\Docker\cli-plugins\
Property(S): WixUIRMOption = UseRM
Property(S): ALLUSERS = 1
Property(S): CreateDockerGroup = "C:\Windows\sysnative\WindowsPowerShell\v1.0\powershell.exe" -Command "(Get-LocalGroup -Name docker-users) -or (New-LocalGroup -Name docker-users -Description 'Users of Docker Desktop')"
Property(S): InstallContainersFeature = "C:\Windows\sysnative\WindowsPowerShell\v1.0\powershell.exe" -Command "Enable-WindowsOptionalFeature -Online -FeatureName Containers -All -NoRestart"
Property(S): ARPINSTALLLOCATION = C:\Program Files\Stevedore\
Property(S): CreateUser = **********
Property(S): CreateUserRollback = **********
Property(S): ProgramFiles64Folder = C:\Program Files\
Property(S): Docker = C:\Program Files\Docker\
Property(S): TARGETDIR = C:\
Property(S): SourceDir = C:\Windows\TEMP\chocolatey\stevedore\0.0.2\
Property(S): VersionNT = 603
Property(S): POWERSHELLEXE = C:\Windows\sysnative\WindowsPowerShell\v1.0\powershell.exe
Property(S): ARPHELPLINK = https://github.com/slonopotamus/stevedore
Property(S): Manufacturer = Marat Radchenko
Property(S): ProductCode = {25FDBBF2-09C4-4CD6-93B1-29FDFC6193C8}
Property(S): ProductLanguage = 1033
Property(S): ProductName = Stevedore
Property(S): ProductVersion = 0.0.2
Property(S): DefaultUIFont = WixUI_Font_Normal
Property(S): WixUI_Mode = FeatureTree
Property(S): ErrorDialog = ErrorDlg
Property(S): SecureCustomProperties = WIX_DOWNGRADE_DETECTED;WIX_UPGRADE_DETECTED
Property(S): MsiHiddenProperties = CreateUser;CreateUserRollback;RemoveUser
Property(S): MsiLogFileLocation = C:\Windows\TEMP\chocolatey\stevedore.0.0.2.MsiInstall.log
Property(S): PackageCode = {8DF632A0-1D65-4ADC-B9CB-C5AF513893A7}
Property(S): ProductState = -1
Property(S): PackagecodeChanging = 1
Property(S): REBOOT = ReallySuppress
Property(S): CURRENTDIRECTORY = C:\Windows\system32\config\systemprofile
Property(S): CLIENTUILEVEL = 3
Property(S): CLIENTPROCESSID = 3064
Property(S): VersionDatabase = 450
Property(S): VersionMsi = 5.00
Property(S): VersionNT64 = 603
Property(S): WindowsBuild = 9600
Property(S): ServicePackLevel = 0
Property(S): ServicePackLevelMinor = 0
Property(S): MsiNTProductType = 3
Property(S): WindowsFolder = C:\Windows\
Property(S): WindowsVolume = C:\
Property(S): System64Folder = C:\Windows\system32\
Property(S): SystemFolder = C:\Windows\SysWOW64\
Property(S): RemoteAdminTS = 1
Property(S): TempFolder = C:\Windows\TEMP\
Property(S): ProgramFilesFolder = C:\Program Files (x86)\
Property(S): CommonFilesFolder = C:\Program Files (x86)\Common Files\
Property(S): CommonFiles64Folder = C:\Program Files\Common Files\
Property(S): AppDataFolder = C:\Windows\system32\config\systemprofile\AppData\Roaming\
Property(S): FavoritesFolder = C:\Windows\system32\config\systemprofile\Favorites\
Property(S): NetHoodFolder = C:\Windows\system32\config\systemprofile\AppData\Roaming\Microsoft\Windows\Network Shortcuts\
Property(S): PersonalFolder = C:\Windows\system32\config\systemprofile\Documents\
Property(S): PrintHoodFolder = C:\Windows\system32\config\systemprofile\AppData\Roaming\Microsoft\Windows\Printer Shortcuts\
Property(S): RecentFolder = C:\Windows\system32\config\systemprofile\AppData\Roaming\Microsoft\Windows\Recent\
Property(S): SendToFolder = C:\Windows\system32\config\systemprofile\AppData\Roaming\Microsoft\Windows\SendTo\
Property(S): TemplateFolder = C:\ProgramData\Microsoft\Windows\Templates\
Property(S): CommonAppDataFolder = C:\ProgramData\
Property(S): LocalAppDataFolder = C:\Windows\system32\config\systemprofile\AppData\Local\
Property(S): MyPicturesFolder = C:\Windows\system32\config\systemprofile\Pictures\
Property(S): AdminToolsFolder = C:\ProgramData\Microsoft\Windows\Start Menu\Programs\Administrative Tools\
Property(S): StartupFolder = C:\ProgramData\Microsoft\Windows\Start Menu\Programs\Startup\
Property(S): ProgramMenuFolder = C:\ProgramData\Microsoft\Windows\Start Menu\Programs\
Property(S): StartMenuFolder = C:\ProgramData\Microsoft\Windows\Start Menu\
Property(S): DesktopFolder = C:\Users\Public\Desktop\
Property(S): FontsFolder = C:\Windows\Fonts\
Property(S): GPTSupport = 1
Property(S): OLEAdvtSupport = 1
Property(S): ShellAdvtSupport = 1
Property(S): MsiAMD64 = 6
Property(S): Msix64 = 6
Property(S): Intel = 6
Property(S): PhysicalMemory = 2048
Property(S): VirtualMemory = 293
Property(S): AdminUser = 1
Property(S): MsiTrueAdminUser = 1
Property(S): LogonUser = SYSTEM
Property(S): UserSID = S-1-5-18
Property(S): UserLanguageID = 1033
Property(S): ComputerName = SPRW-BA-WIN-3
Property(S): SystemLanguageID = 1033
Property(S): ScreenX = 1024
Property(S): ScreenY = 768
Property(S): CaptionHeight = 35
Property(S): BorderTop = 1
Property(S): BorderSide = 1
Property(S): TextHeight = 16
Property(S): TextInternalLeading = 3
Property(S): ColorBits = 32
Property(S): TTCSupport = 1
Property(S): Time = 19:41:15
Property(S): Date = 10/8/2021
Property(S): MsiNetAssemblySupport = 4.7.3190.0
Property(S): MsiWin32AssemblySupport = 6.3.17763.1
Property(S): RedirectedDllSupport = 2
Property(S): MsiRunningElevated = 1
Property(S): Privileged = 1
Property(S): DATABASE = C:\Windows\Installer\1068ce.msi
Property(S): OriginalDatabase = C:\Windows\TEMP\chocolatey\stevedore\0.0.2\stevedore-0.0.2-x86_64.msi
Property(S): UILevel = 2
Property(S): ACTION = INSTALL
Property(S): ROOTDRIVE = C:\
Property(S): CostingComplete = 1
Property(S): OutOfDiskSpace = 0
Property(S): OutOfNoRbDiskSpace = 0
Property(S): PrimaryVolumeSpaceAvailable = 0
Property(S): PrimaryVolumeSpaceRequired = 0
Property(S): PrimaryVolumeSpaceRemaining = 0
Property(S): INSTALLLEVEL = 1
Property(S): SOURCEDIR = C:\Windows\TEMP\chocolatey\stevedore\0.0.2\
Property(S): SourcedirProduct = {25FDBBF2-09C4-4CD6-93B1-29FDFC6193C8}
Property(S): ProductToBeRegistered = 1
MSI (s) (64:3C) [19:41:15:771]: Note: 1: 1708 
MSI (s) (64:3C) [19:41:15:771]: Product: Stevedore -- Installation failed.

MSI (s) (64:3C) [19:41:15:786]: Windows Installer installed the product. Product Name: Stevedore. Product Version: 0.0.2. Product Language: 1033. Manufacturer: Marat Radchenko. Installation success or error status: 1603.

MSI (s) (64:3C) [19:41:15:802]: Deferring clean up of packages/files, if any exist
MSI (s) (64:3C) [19:41:15:802]: MainEngineThread is returning 1603
MSI (s) (64:80) [19:41:15:818]: RESTART MANAGER: Session closed.
MSI (s) (64:80) [19:41:15:818]: No System Restore sequence number for this installation.
=== Logging stopped: 10/8/2021  19:41:15 ===
MSI (s) (64:80) [19:41:15:833]: User policy value 'DisableRollback' is 0
MSI (s) (64:80) [19:41:15:833]: Machine policy value 'DisableRollback' is 0
MSI (s) (64:80) [19:41:15:833]: Incrementing counter to disable shutdown. Counter after increment: 0
MSI (s) (64:80) [19:41:15:833]: Note: 1: 1402 2: HKEY_LOCAL_MACHINE\Software\Microsoft\Windows\CurrentVersion\Installer\Rollback\Scripts 3: 2 
MSI (s) (64:80) [19:41:15:833]: Note: 1: 1402 2: HKEY_LOCAL_MACHINE\Software\Microsoft\Windows\CurrentVersion\Installer\Rollback\Scripts 3: 2 
MSI (s) (64:80) [19:41:15:833]: Decrementing counter to disable shutdown. If counter >= 0, shutdown will be denied.  Counter after decrement: -1
MSI (s) (64:80) [19:41:15:865]: Destroying RemoteAPI object.
MSI (s) (64:1C) [19:41:15:865]: Custom Action Manager thread ending.
MSI (c) (F8:18) [19:41:15:865]: Decrementing counter to disable shutdown. If counter >= 0, shutdown will be denied.  Counter after decrement: -1
MSI (c) (F8:18) [19:41:15:865]: MainEngineThread is returning 1603
=== Verbose logging stopped: 10/8/2021  19:41:15 ===

Additional context

Stevedore fails to install via Salt.

slonopotamus commented 2 years ago

This was very likely fixed by #13. Feel free to comment/reopen in case it wasn't.

slonopotamus commented 2 years ago

Reopening, bug is still present. See #21

slonopotamus commented 2 years ago

How to become SYSTEM: https://github.com/slonopotamus/stevedore/issues/21#issuecomment-1036236679

TBBle commented 2 years ago

13 fixed the first hurdle, seen above:

CreateUser: Error 0x800706ba: failed to create user: SYSTEM

and #21's logs show the next hurdle:

CreateUser: Error 0x80005000: Failed to add user Workgroup/SYSTEM to group 'WinNT://Localhost/docker-users'. CreateUser: Error 0x80005000: failed to add user: SYSTEM to group docker-users

I wonder if it's actually even possible to add SYSTEM to a local group? https://superuser.com/a/1216142 suggests it's something special (not a normal user, nor a normal group) so maybe it needs to be specifically excepted from the 'add to docker-users' step. (Maybe there's a whole class of things that can be recognised this way? Other discussion on that superuser link and related links suggest that when pushed, it self-identifies as a group, rather than a user, except when it's the primary SID attached to a session, as is the case for psexec -s and similar low-level system privileges.)

It's possible this is something the WIX code should actually recognise as impossible and deal with, e.g. document and skip.

I feel that in general, system accounts, e.g. System (aka LocalSystem), LocalService, NetworkService, etc. should not be being added to docker-users when they are running the installer. I'm not sure how that would be usefully distinguished off-hand, maybe there's an API to check this, or we could just check if the primary SID falls into the 'predefined SIDs' list.

Maybe in the end we just need an MSI option to skip the 'add users' step, that system administrators deploying via tools like Puppet or Salt can use, since they would presumably want to manage group membership through those tools anyway, e.g. adding "Interactive Users" to the docker-users group so that whoever happens to be logged in to that machine can use the local Docker instance.

Interestingly, the logs for #21 also show what might be a hideous bug in WIX:

RemoveUser: Error 0x800706ba: failed to delete user account: SYSTEM

i.e. rolling back the failed 'add user to group' (which has the 'create=false' flag so knows that it did not create this user) appears to have attempted to delete the user.

This would be 100% disastrous if it happened to a user account that could be deleted. It's trying to roll back an action it did not take, which seems pretty faulty to me.

Eww. Similar issue (no-op due to existing user rather than due to create=false) reported in 2005 and closed by timeout, never actually resolved.