ARM update process? Hitting 1612 when trying to update

[Michael3704760794a0]

Hi All,

Is their documentation on the ARM workflow end to end?

I am trying to debug a 1612 error to find out whats going on. I've turned ARM (Below) and MSI verbose logging on(No details). This is a install from Windows Store but trying to use the Adobe Updater to update the application.

So far I can tell:
1. File download to the users temp folder  C:\Username\App Data\Local\Adobe\ARM\<FOLDER>\(MSP + Manifest file).

2. Files are moved to C:\ProgramData\Adobe\Arm\<Build>. Can see the MSP folder but not the manifest file.

3. Adobe in the tray exclaims the update failed, throwing a 1612 update error - The installation source for this product is not avaialble. Verify that the source exists and that you can access it.

It's likely a system policy from WDAC blocking this, i am peeling off the layers to find out where, its just a slow process. Can any further logging of the installation attempt be enabled, the Windows MSI logging isn't providing any output. 

Verbose logs are as below:

[2024-04-29 15:04:58:0125] Adobe ARM 1.824.460.1067 logging started.
[2024-04-29 15:04:58:0125] Command Line:
[2024-04-29 15:04:58:0125] Notify...
[2024-04-29 15:04:58:0125] Could not find ARM server to notify
[2024-04-29 15:04:58:0125] GetLastError(): 2 - The system cannot find the file specified.
[2024-04-29 15:04:58:0125] CreateSessionFromReportData... new instance
[2024-04-29 15:04:58:0125] No Report file
[2024-04-29 15:04:58:0125] SafeDeleteReportFile...
[2024-04-29 15:04:58:0125] InitUIStrings...
[2024-04-29 15:04:58:0135] UpdateARM...
[2024-04-29 15:04:58:0135] IsTimeElapsed...
[2024-04-29 15:04:58:0135] Last check for updates is not registered
[2024-04-29 15:04:58:0135] IsErrorExpired...
[2024-04-29 15:04:58:0135] Last error is not registered
[2024-04-29 15:04:58:0135] IsAnyProductInFullAuto...
[2024-04-29 15:04:58:0135] EULA accepted from 2
[2024-04-29 15:04:58:0135] found full auto for: {AC76BA86-1033-FF00-7760-BC15014EA700} in Mode
[2024-04-29 15:04:58:0135] InitListener...
[2024-04-29 15:04:58:0135] Init...
[2024-04-29 15:04:58:0135] Notify...
[2024-04-29 15:04:58:0135] Could not find ARM server to notify
[2024-04-29 15:04:58:0135] GetLastError(): 2 - The system cannot find the file specified.
[2024-04-29 15:04:58:0135] StartServer...
[2024-04-29 15:04:58:0135] IsVistaOrLater returns 1
[2024-04-29 15:04:58:0135] ARM Server started
[2024-04-29 15:04:58:0135] Action added: Listener
[2024-04-29 15:04:58:0135] SetupArmUpdate...
[2024-04-29 15:04:58:0135] InitDownloadForArmUpdate...
[2024-04-29 15:04:58:0135] GetOverwriteURL...
[2024-04-29 15:04:58:0135] No overwrite file
[2024-04-29 15:04:58:0149] DownloadArmManifest...
[2024-04-29 15:04:58:0152] DownloadFileAndWait()
[2024-04-29 15:05:02:0382] Creating BITS job LocalFileName:C:\Users\TestUser\AppData\Local\Adobe\ARM\S\ArmManifest3.msi
[2024-04-29 15:05:02:0382] Creating BITS job RemoteName:https://armmf.adobe.com/arm-manifests/win/ArmManifest3.msi
[2024-04-29 15:05:02:0382] downLoadFileSet(): File Count: 1
[2024-04-29 15:05:20:0134] StoreCRD...
[2024-04-29 15:05:20:0134] No CRD to store
[2024-04-29 15:05:20:0136] BITs download success: C:\Users\TestUser\AppData\Local\Adobe\ARM\S\ArmManifest3.msi
[2024-04-29 15:05:20:0277] valid after BITs download: C:\Users\TestUser\AppData\Local\Adobe\ARM\S\ArmManifest3.msi
[2024-04-29 15:05:20:0277] ReportSessionData...
[2024-04-29 15:05:20:0277] ProcessArmUpdateManifest...
[2024-04-29 15:05:20:0322] InitArmUpdate...
[2024-04-29 15:05:20:0322] EvaluateCondition...: DisableArmUpdate
[2024-04-29 15:05:20:0322] ProcessOSCondition...
[2024-04-29 15:05:20:0322] CheckSystemRequirements os,sp = 100 0 osString = 100W6, physicalMemory (16004) = 16384
[2024-04-29 15:05:20:0322] CheckSystemRequirements failed. Required MaxOS: 60
[2024-04-29 15:05:20:0322] ARM update record found
[2024-04-29 15:05:20:0322] IsNewArmAvailable...
[2024-04-29 15:05:20:0322] GetFileVersion(): C:\Program Files (x86)\Common Files\Adobe\ARM\1.0\AdobeARM.exe
[2024-04-29 15:05:20:0322] File version: 1.824.460.1067
[2024-04-29 15:05:20:0322] IsNewArmAvailable: ARM version is up to date, returning 0.
[2024-04-29 15:05:20:0322] Newer version ARM update is not available
[2024-04-29 15:05:20:0324] RegisterResult...
[2024-04-29 15:05:20:0324] LogReportDataIntoAnalytics... requested by update ARM
[2024-04-29 15:05:20:0324] did not pass throttle check: 7476
[2024-04-29 15:05:20:0324] done with Report file
[2024-04-29 15:05:20:0324] SafeDeleteReportFile...
[2024-04-29 15:05:20:0324] IsAnyProductInFullAuto...
[2024-04-29 15:05:20:0324] EULA accepted from 2
[2024-04-29 15:05:20:0324] found full auto for: {AC76BA86-1033-FF00-7760-BC15014EA700} in Mode
[2024-04-29 15:05:20:0324] ************************************
[2024-04-29 15:05:20:0324] InitSessionWithProduct...
[2024-04-29 15:05:20:0324] FindCurrentPDFOwner...
[2024-04-29 15:05:20:0340] Failed to detrmine .pdf owner progID: QueryCurrentDefault
[2024-04-29 15:05:20:0340] ProductCode: {AC76BA86-1033-FF00-7760-BC15014EA700}
[2024-04-29 15:05:20:0340] ProductName: Adobe Acrobat (64-bit)
[2024-04-29 15:05:20:0340] ProductVersion: 23.008.20533
[2024-04-29 15:05:20:0340] ProductUACPatching: enabled
[2024-04-29 15:05:20:0340] Product UI Language: ENU
[2024-04-29 15:05:20:0340] ProductLanguage installed: 0
[2024-04-29 15:05:20:0340] ProductRegistry: SOFTWARE\Adobe\Adobe Acrobat\DC
[2024-04-29 15:05:20:0340] ProductInstallDir: C:\Program Files\Adobe\Acrobat DC\
[2024-04-29 15:05:20:0340] Manifest URL: https://armmf.adobe.com/arm-manifests/win/AcrobatDCx64Manifest3.msi
[2024-04-29 15:05:20:0340] InitUIStrings...
[2024-04-29 15:05:20:0340] re-initializing strings with langauge: ENU
[2024-04-29 15:05:20:0371] Run_SpeedLaunchers...
[2024-04-29 15:05:20:0371] Token time: 133588398800535448
[2024-04-29 15:05:20:0371] Registered time : 133588398800535448
[2024-04-29 15:05:20:0371] Started auto mode for: Acrobat
[2024-04-29 15:05:20:0371] CanRun...
[2024-04-29 15:05:20:0371] IsPatchingDisabled...
[2024-04-29 15:05:20:0371] Std User, will update
[2024-04-29 15:05:20:0371] StartProduct...
[2024-04-29 15:05:20:0371] IsEULA_Accepted...
[2024-04-29 15:05:20:0371] EULA accepted from 2
[2024-04-29 15:05:20:0371] GetPreferences...
[2024-04-29 15:05:20:0371] Using registered preference AUTO_ALL
[2024-04-29 15:05:20:0371] IsTimeElapsed...
[2024-04-29 15:05:20:0371] Last check for updates is not registered
[2024-04-29 15:05:20:0371] IsErrorExpired...
[2024-04-29 15:05:20:0371] Last error is not registered
[2024-04-29 15:05:20:0371] InitDownloadManager...
[2024-04-29 15:05:20:0371] GetOverwriteURL...
[2024-04-29 15:05:20:0371] No overwrite file
[2024-04-29 15:05:20:0387] Product can start
[2024-04-29 15:05:20:0387] Network is available, waited 0 ms
[2024-04-29 15:05:20:0387] Action added: Download Manifest
[2024-04-29 15:05:20:0387] Downloading Manifest:
[2024-04-29 15:05:20:0387] URL: https://armmf.adobe.com/arm-manifests/win/AcrobatDCx64Manifest3.msi
[2024-04-29 15:05:20:0387] file: C:\Users\TestUser\AppData\Local\Adobe\ARM\Acrobat_23.008.20533\AcrobatDCx64Manifest3.msi
[2024-04-29 15:05:20:0387] DownloadFileAndWait()
[2024-04-29 15:05:20:0402] Creating BITS job LocalFileName:C:\Users\TestUser\AppData\Local\Adobe\ARM\Acrobat_23.008.20533\AcrobatDCx64Manifest3.msi
[2024-04-29 15:05:20:0402] Creating BITS job RemoteName:https://armmf.adobe.com/arm-manifests/win/AcrobatDCx64Manifest3.msi
[2024-04-29 15:05:20:0402] downLoadFileSet(): File Count: 1
[2024-04-29 15:05:26:0145] StoreCRD...
[2024-04-29 15:05:26:0145] No CRD to store
[2024-04-29 15:05:26:0147] BITs download success: C:\Users\TestUser\AppData\Local\Adobe\ARM\Acrobat_23.008.20533\AcrobatDCx64Manifest3.msi
[2024-04-29 15:05:26:0178] valid after BITs download: C:\Users\TestUser\AppData\Local\Adobe\ARM\Acrobat_23.008.20533\AcrobatDCx64Manifest3.msi
[2024-04-29 15:05:26:0178] TransferProductManifest...
[2024-04-29 15:05:26:0178] Transferred: C:\ProgramData\Adobe\ARM\Acrobat_23.008.20533\AcrobatDCx64Manifest3.msi
[2024-04-29 15:05:26:0178] Action deleted: Download Manifest
[2024-04-29 15:05:26:0178] ReportSessionData...
[2024-04-29 15:05:26:0178] InitSession...
[2024-04-29 15:05:26:0178] InitUpdateData...
[2024-04-29 15:05:26:0219] Init from: C:\ProgramData\Adobe\ARM\Acrobat_23.008.20533\AcrobatDCx64Manifest3.msi
[2024-04-29 15:05:26:0219] InitExtraManifestProperties...
[2024-04-29 15:05:26:0219] InitUpdateFromProductUpdatesTable...
[2024-04-29 15:05:26:0219] MDOV1615
[2024-04-29 15:05:26:0219] InitUpdateFromProductUpdatesExTable...
[2024-04-29 15:05:26:0219] InitUpdateDataFromRecord...
[2024-04-29 15:05:26:0219] InitUpgrade...
[2024-04-29 15:05:26:0219] Upgrade data not available: Upgrade ID
[2024-04-29 15:05:26:0219] File list in Manifest: AcroRdrDCx64Upd2400220687_MUI.msp
[2024-04-29 15:05:26:0219] InitUpdateFiles...
[2024-04-29 15:05:26:0219] SetUpdateSelection..
[2024-04-29 15:05:26:0219] entered evaluation for: ID_AcroRdrDCx64Upd2400220687_MUI.msp
[2024-04-29 15:05:26:0219] ID_AcroRdrDCx64Upd2400220687_MUI.msp evaluated to Install
[2024-04-29 15:05:26:0219] New file object: AcroRdrDCx64Upd2400220687_MUI.msp
[2024-04-29 15:05:26:0219] AddFileObject...
[2024-04-29 15:05:26:0219] InitAlternativePayload...
[2024-04-29 15:05:26:0219] MVF259
[2024-04-29 15:05:26:0219] get update values for Update ID: ID_AcroRdrDCx64Upd2400220687_MUI.msp
[2024-04-29 15:05:26:0219] InitFilesInUse...
[2024-04-29 15:05:26:0219] InitManifestProperties...
[2024-04-29 15:05:26:0219] property value: 60
[2024-04-29 15:05:26:0219] property value: http://www.adobe.com/go/update_error_win
[2024-04-29 15:05:26:0219] property value: http://www.adobe.com/products/reader.html
[2024-04-29 15:05:26:0224] property value: 604800000
[2024-04-29 15:05:26:0224] property value: 604800000
[2024-04-29 15:05:26:0224] property value: 1
[2024-04-29 15:05:26:0224] property value: Distiller;AdobeCollabSynchronizerNotification;AcrobatTrayIcon
[2024-04-29 15:05:26:0224] property value: SvcCond1;SvcCond2
[2024-04-29 15:05:26:0224] EvaluateCondition...: SvcCond1
[2024-04-29 15:05:26:0224] ProcessFileCondition...
[2024-04-29 15:05:26:0224] File: C:\Program Files (x86)\Common Files\Adobe\ARM\1.0\AdobeARM.exe
[2024-04-29 15:05:26:0228] File version: 1.824.460.1067
[2024-04-29 15:05:26:0228] Condition met
[2024-04-29 15:05:26:0228] EvaluateCondition...: SvcCond2
[2024-04-29 15:05:26:0228] ProcessFileCondition...
[2024-04-29 15:05:26:0228] File: C:\Program Files (x86)\Common Files\Adobe\ARM\1.0\AdobeARMHelper.exe
[2024-04-29 15:05:26:0234] File version: 1.824.460.1067
[2024-04-29 15:05:26:0234] Condition met
[2024-04-29 15:05:26:0234] property value: 2
[2024-04-29 15:05:26:0234] Service allowed in full auto mode only
[2024-04-29 15:05:26:0234] property value: 2;16;17;18;19;52;53;54;63;68;81;82;83;84;85;86;87;88;89;90;91;92;93;97
[2024-04-29 15:05:26:0234] property value: http://ardownload.adobe.com
[2024-04-29 15:05:26:0234] IsNotUpdatableAdminInstall...
[2024-04-29 15:05:26:0245] GetOverwriteURL...
[2024-04-29 15:05:26:0246] No overwrite file
[2024-04-29 15:05:26:0246] UAC is enabled
[2024-04-29 15:05:26:0246] DownloadFiles...
[2024-04-29 15:05:26:0246] DownloadPayloadFiles...
[2024-04-29 15:05:26:0246] DownloadFilesWithBITs...
[2024-04-29 15:05:26:0247] FindJob
[2024-04-29 15:05:26:0247] GetJobs
[2024-04-29 15:05:26:0256] Creating BITS job LocalFileName:C:\Users\TestUser\AppData\Local\Adobe\ARM\Acrobat_23.008.20533\AcroRdrDCx64Upd2400220687_MUI.msp
[2024-04-29 15:05:26:0256] Creating BITS job RemoteName:https://ardownload3.adobe.com/pub/adobe/acrobat/win/AcrobatDC/2400220687/AcroRdrDCx64Upd2400220687_M...
[2024-04-29 15:05:26:0256] downLoadFileSet(): File Count: 1
[2024-04-29 15:06:05:0576] StoreCRD...
[2024-04-29 15:06:05:0576] No CRD to store
[2024-04-29 15:06:05:0576] CancelJobs
[2024-04-29 15:06:05:0576] GetJobs
[2024-04-29 15:06:05:0592] no registered jobs
[2024-04-29 15:06:05:0592] RegisterOnDemandValue: iDownloadPayloadTime
[2024-04-29 15:06:05:0592] PingOnDemand: DownloadPayloadTime02
[2024-04-29 15:06:05:0592] TransferPayloads...
[2024-04-29 15:06:05:0733] Transferred: C:\ProgramData\Adobe\ARM\Acrobat_23.008.20533\AcroRdrDCx64Upd2400220687_MUI.msp
[2024-04-29 15:06:05:0795] InitInstalls...
[2024-04-29 15:06:05:0795] SetupArmUpdate...
[2024-04-29 15:06:05:0795] skipping, already executed
[2024-04-29 15:06:05:0795] WaitForMsiMutex...
[2024-04-29 15:06:05:0795] WaitFilesInUse...
[2024-04-29 15:06:06:0220] WaitDynamicFilesInUseEx...
[2024-04-29 15:06:06:0235] value is not registered: tTimeWaitedFilesInUse_Acrobat
[2024-04-29 15:06:06:0235] MustElevate...
[2024-04-29 15:06:06:0235] required to elevate
[2024-04-29 15:06:06:0235] RelaunchARM... service only
[2024-04-29 15:06:06:0251] Service reported success, time elapsed: 0
[2024-04-29 15:06:06:0313] Service notify success
[2024-04-29 15:06:06:0313] Command line for restart: /ArmElevate /MODE:3 /UpdateSelection:0 /PRODUCT:Acrobat /VERSION:23.0 /LANG:ENU
[2024-04-29 15:06:06:0313] GetFinalCommandLineForSM...
[2024-04-29 15:06:06:0313] found service version: 1.824.460.1067
[2024-04-29 15:06:06:0313] final cmd line for service: /ArmElevate /MODE:3 /UpdateSelection:0 /PRODUCT:Acrobat /VERSION:23.0 /LANG:ENU
[2024-04-29 15:06:06:0313] Notify service to create ShM
[2024-04-29 15:06:06:0329] Service reported success, time elapsed: 0
[2024-04-29 15:06:06:0392] Service notify success
[2024-04-29 15:06:06:0392] SM command line: /ArmElevate /MODE:3 /UpdateSelection:0 /PRODUCT:Acrobat /VERSION:23.0 /LANG:ENU
[2024-04-29 15:06:06:0392] SM ARM update folder:
[2024-04-29 15:06:06:0392] SM manifest path:
[2024-04-29 15:06:06:0392] SM session id: 1
[2024-04-29 15:06:06:0392] WriteSharedMemory(): succeeded. mapping obj name: Global\{E8F34725-3471-4506-B28B-47145817B1AE}_-1071605443thsnYaViMRAeBoda
[2024-04-29 15:06:06:0924] Service reported success, time elapsed: 0
[2024-04-29 15:06:06:0986] Service notify success
[2024-04-29 15:06:06:0986] CanExit confirmed
[2024-04-29 15:06:06:0986] LogReportDataIntoAnalytics... requested by exit instance
[2024-04-29 15:06:06:0986] skipping, will restart or system context
[2024-04-29 15:06:06:0986] Cleanup...
[2024-04-29 15:06:06:0986] Notify...
[2024-04-29 15:06:06:0986] WriteFile succeeded in Notify
[2024-04-29 15:06:07:0002] Requested to stop listener
[2024-04-29 15:06:07:0002] Confirming request to stop listener
[2024-04-29 15:06:07:0002] Action deleted: Listener
[2024-04-29 15:06:07:0189] ARM returns ERROR_SUCCESS
[2024-04-29 15:06:07:0189] Adobe ARM 1.824.460.1067 logging finished.

[2024-04-29 15:06:07:0549] Adobe ARM 1.824.460.1067 logging started.
[2024-04-29 15:06:07:0549] Command Line: /CloseApplications /FOLDER:"Distiller;AdobeCollabSynchronizerNotification;AcrobatTrayIcon"
[2024-04-29 15:06:07:0549] ARM returns ERROR_SUCCESS
[2024-04-29 15:06:07:0549] Adobe ARM 1.824.460.1067 logging finished.

[2024-04-29 15:06:11:0101] Adobe ARM 1.824.460.1067 logging started.
[2024-04-29 15:06:11:0101] Command Line: /ProcessResult /PRODUCT:Acrobat /LANG:ENU
[2024-04-29 15:06:13:0129] Notify...
[2024-04-29 15:06:13:0129] Could not find ARM server to notify
[2024-04-29 15:06:13:0129] GetLastError(): 2 - The system cannot find the file specified.
[2024-04-29 15:06:13:0129] InitUIStrings...
[2024-04-29 15:06:13:0145] ************************************
[2024-04-29 15:06:13:0145] InitSessionWithProduct...
[2024-04-29 15:06:13:0145] ProductCode: {AC76BA86-1033-FF00-7760-BC15014EA700}
[2024-04-29 15:06:13:0145] ProductName: Adobe Acrobat (64-bit)
[2024-04-29 15:06:13:0145] ProductVersion: 23.008.20533
[2024-04-29 15:06:13:0145] ProductUACPatching: enabled
[2024-04-29 15:06:13:0145] Product UI Language: ENU
[2024-04-29 15:06:13:0145] ProductLanguage installed: 0
[2024-04-29 15:06:13:0145] ProductRegistry: SOFTWARE\Adobe\Adobe Acrobat\DC
[2024-04-29 15:06:13:0145] ProductInstallDir: C:\Program Files\Adobe\Acrobat DC\
[2024-04-29 15:06:13:0145] Manifest URL: https://armmf.adobe.com/arm-manifests/win/AcrobatDCx64Manifest3.msi
[2024-04-29 15:06:13:0145] InitUIStrings...
[2024-04-29 15:06:13:0145] re-initializing strings with langauge: ENU
[2024-04-29 15:06:13:0145] InitListener...
[2024-04-29 15:06:13:0145] Notify...
[2024-04-29 15:06:13:0145] Could not find ARM server to notify
[2024-04-29 15:06:13:0145] GetLastError(): 2 - The system cannot find the file specified.
[2024-04-29 15:06:13:0145] Init...
[2024-04-29 15:06:13:0145] Notify...
[2024-04-29 15:06:13:0145] Could not find ARM server to notify
[2024-04-29 15:06:13:0145] GetLastError(): 2 - The system cannot find the file specified.
[2024-04-29 15:06:13:0145] StartServer...
[2024-04-29 15:06:13:0145] IsVistaOrLater returns 1
[2024-04-29 15:06:13:0159] ARM Server started
[2024-04-29 15:06:13:0159] Action added: Listener
[2024-04-29 15:06:13:0159] ProcessResult...
[2024-04-29 15:06:13:0163] The installation source for this product is not available. Verify that the source exists and that you can access it.

Error: 1612
[2024-04-29 15:06:13:0163] Error Code: 5
[2024-04-29 15:06:13:0164] ** Setting Error Condition: 150000
[2024-04-29 15:06:13:0164] Entering kInstallFailure in Error object
[2024-04-29 15:06:13:0164] RegisterError...
[2024-04-29 15:06:13:0164] ShowArmDialog...
[2024-04-29 15:06:13:0271] Action added: Dialog
[2024-04-29 15:06:13:0699] SetupReminder, UI minimized
[2024-04-29 15:06:13:0715] SetBalooonInstallFinished...
[2024-04-29 15:09:22:0079] SetupReminder, UI maximized
[2024-04-29 15:10:40:0077] User exited
[2024-04-29 15:10:40:0116] Action deleted: Dialog
[2024-04-29 15:10:40:0128] LogReportDataIntoAnalytics... requested by exit instance
[2024-04-29 15:10:40:0129] did not pass throttle check: 7476
[2024-04-29 15:10:40:0129] done with Report file
[2024-04-29 15:10:40:0129] SafeDeleteReportFile...
[2024-04-29 15:10:40:0129] Notify...
[2024-04-29 15:10:40:0129] WriteFile succeeded in Notify
[2024-04-29 15:10:40:0129] Requested to stop listener
[2024-04-29 15:10:40:0129] Confirming request to stop listener
[2024-04-29 15:10:40:0129] Action deleted: Listener
[2024-04-29 15:10:40:0131] ARM returns ERROR_SUCCESS
[2024-04-29 15:10:40:0131] Adobe ARM 1.824.460.1067 logging finished.

[LeoUpdaterX]

When MSI based product is deployed, original source is registered by MSI in a very specific way.

This source information (also known as “source resiliency”) is an important part of the product maintenance (repairs, updates, including patch installation, etc..)

More on this here- Source Resiliency - Win32 apps | Microsoft Learn

Verbose MSI log file should have full path to where MSI expects Source to be available.

The bottom line – original installer package (Source) must be accessible for the life of a product on the system.