Dev 3.0.129 doesn't install, keeps returning to 128

[doron 7]

Started MBC today, got the "New version available - Update Now?" for 129. Click "Yes" and confirm.

WMC closes, the usual "downloading" dialog happens, and then - much more quickly than the typical update cycle - WMC/MBC comes up again, splashes the screen and after a bit tells me "New version available" again for 129. When I check I see that 128 is still the installed version.

 

Rinse, repeat, same thing over and over again. Doesn't want to move to 129.

Log (below) seems to think that everything is okay.

 

What gives? What do I do?

 

Thanks!

MediaBrowser.Classic.Installer.exe Information: 0 : Installing Media Browser Classic
MediaBrowser.Classic.Installer.exe Information: 0 : Attempting to retrieve latest version of MBClassic
MediaBrowser.Classic.Installer.exe Information: 0 : Found 1 versions.  Will choose latest one of Dev class
MediaBrowser.Classic.Installer.exe Information: 0 : Version is 3.0.129.0
MediaBrowser.Classic.Installer.exe Information: 0 : Downloading Media Browser Classic version 3.0.129.0
MediaBrowser.Classic.Installer.exe Information: 0 : Successfully downloaded version 3.0.129.0
MediaBrowser.Classic.Installer.exe Information: 0 : Archive is MSI installer C:\Users\Doron\AppData\Local\Temp\MediaBrowser\MediaBrowserClassic.msi
MediaBrowser.Classic.Installer.exe Information: 0 : Calling msi install
MediaBrowser.Classic.Installer.exe Information: 0 : Deleting Pismo install files
MediaBrowser.Classic.Installer.exe Information: 0 : Attempting to start program C:\Windows\ehome\ehshell.exe /nostartupanimation /entrypoint:{CE32C570-4BEC-4aeb-AD1D-CF47B91DE0B2}\{FC9ABCCC-36CB-47ac-8BAB-03E8EF5F6F22}
MediaBrowser.Classic.Installer.exe Information: 0 : Installation complete

[ebr 14944]

What does the MSIInstall log in your logs folder look like?

[doron 7]

I presume you're talking about MBClassic-Msi.log, living in ProgramData\MediaBrowser-Classic\Logs .

If so, pasted below. If not, please direct me to the file you're referring to.

=== Logging started: 12-Feb-14  23:47:18 ===
Action start 23:47:18: INSTALL.
Action start 23:47:18: FindRelatedProducts.
Action ended 23:47:18: FindRelatedProducts. Return value 0.
Action start 23:47:18: AppSearch.
Action ended 23:47:18: AppSearch. Return value 1.
Action start 23:47:18: LaunchConditions.
Action ended 23:47:18: LaunchConditions. Return value 1.
Action start 23:47:18: WixQueryOsWellKnownSID.
Action ended 23:47:18: WixQueryOsWellKnownSID. Return value 1.
Action start 23:47:18: ValidateProductID.
Action ended 23:47:18: ValidateProductID. Return value 1.
Action start 23:47:18: CostInitialize.
Action ended 23:47:18: CostInitialize. Return value 1.
Action start 23:47:18: FileCost.
Action ended 23:47:18: FileCost. Return value 1.
Action start 23:47:18: CostFinalize.
Action ended 23:47:18: CostFinalize. Return value 1.
Action start 23:47:18: CA_RegisterMceApp_Unregister_Install_Cmd7.
Action ended 23:47:18: CA_RegisterMceApp_Unregister_Install_Cmd7. Return value 1.
Action start 23:47:18: CA_RegisterMceApp_Register_Cmd7.
Action ended 23:47:18: CA_RegisterMceApp_Register_Cmd7. Return value 1.
Action start 23:47:18: CA_RegisterMceApp_Rollback_Cmd7.
Action ended 23:47:18: CA_RegisterMceApp_Rollback_Cmd7. Return value 1.
Action start 23:47:18: InstallValidate.
Action ended 23:47:18: InstallValidate. Return value 1.
Action start 23:47:18: RemoveExistingProducts.
Action ended 23:47:18: RemoveExistingProducts. Return value 0.
Action start 23:47:18: SaveInstallLocation.
Action ended 23:47:18: SaveInstallLocation. Return value 1.
Action start 23:47:18: InstallInitialize.
Action ended 23:47:18: InstallInitialize. Return value 1.
Action start 23:47:18: ProcessComponents.
Action ended 23:47:18: ProcessComponents. Return value 1.
Action start 23:47:18: MsiUnpublishAssemblies.
Action ended 23:47:18: MsiUnpublishAssemblies. Return value 1.
Action start 23:47:18: UnpublishFeatures.
Action ended 23:47:18: UnpublishFeatures. Return value 1.
Action start 23:47:18: SchedSecureObjectsRollback.
Action start 23:47:18: ExecSecureObjectsRollback.
Action ended 23:47:18: ExecSecureObjectsRollback. Return value 1.
Action start 23:47:18: ExecSecureObjectsRollback.
Action ended 23:47:18: ExecSecureObjectsRollback. Return value 1.
Action ended 23:47:18: SchedSecureObjectsRollback. Return value 1.
Action start 23:47:18: RemoveRegistryValues.
Action ended 23:47:18: RemoveRegistryValues. Return value 1.
Action start 23:47:18: RemoveShortcuts.
Action ended 23:47:18: RemoveShortcuts. Return value 1.
Action start 23:47:18: RemoveFiles.
Action ended 23:47:18: RemoveFiles. Return value 1.
Action start 23:47:18: WixSchedInternetShortcuts.
Action ended 23:47:18: WixSchedInternetShortcuts. Return value 1.
Action start 23:47:18: RemoveFolders.
Action ended 23:47:18: RemoveFolders. Return value 1.
Action start 23:47:18: CreateFolders.
Action ended 23:47:18: CreateFolders. Return value 1.
Action start 23:47:18: InstallFiles.
Action ended 23:47:18: InstallFiles. Return value 1.
Action start 23:47:18: CA_RegisterMceApp_Rollback.
Action ended 23:47:18: CA_RegisterMceApp_Rollback. Return value 1.
Action start 23:47:18: CA_RegisterMceApp_Unregister_Install.
Action ended 23:47:18: CA_RegisterMceApp_Unregister_Install. Return value 1.
Action start 23:47:18: CA_RegisterMceApp_Register.
Action ended 23:47:18: CA_RegisterMceApp_Register. Return value 1.
Action start 23:47:18: CreateShortcuts.
Action ended 23:47:18: CreateShortcuts. Return value 1.
Action start 23:47:18: WixRollbackInternetShortcuts.
Action ended 23:47:18: WixRollbackInternetShortcuts. Return value 1.
Action start 23:47:18: WixCreateInternetShortcuts.
Action ended 23:47:18: WixCreateInternetShortcuts. Return value 1.
Action start 23:47:18: WriteRegistryValues.
Action ended 23:47:18: WriteRegistryValues. Return value 1.
Action start 23:47:18: InstallServices.
Action ended 23:47:18: InstallServices. Return value 1.
Action start 23:47:18: SchedSecureObjects.
Action ended 23:47:18: SchedSecureObjects. Return value 1.
Action start 23:47:18: RegisterUser.
Action ended 23:47:18: RegisterUser. Return value 0.
Action start 23:47:18: RegisterProduct.
Action ended 23:47:18: RegisterProduct. Return value 1.
Action start 23:47:18: MsiPublishAssemblies.
Action ended 23:47:18: MsiPublishAssemblies. Return value 1.
Action start 23:47:18: PublishFeatures.
Action ended 23:47:18: PublishFeatures. Return value 1.
Action start 23:47:18: PublishProduct.
Action ended 23:47:18: PublishProduct. Return value 1.
Action start 23:47:18: InstallFinalize.
CAQuietExec:  
Success
CAQuietExec:  
Success
Action ended 23:47:19: InstallFinalize. Return value 1.
Action start 23:47:19: CA_Migrate.
Action ended 23:47:20: CA_Migrate. Return value 1.
Action ended 23:47:20: INSTALL. Return value 1.
MSI (s) (DC:D8) [23:47:20:477]: Product: Media Browser Classic -- Configuration completed successfully.

MSI (s) (DC:D8) [23:47:20:478]: Windows Installer reconfigured the product. Product Name: Media Browser Classic. Product Version: 3.0.128.0. Product Language: 1033. Manufacturer: Media Browser Team. Reconfiguration success or error status: 0.

=== Logging stopped: 12-Feb-14  23:47:20 ===

[ebr 14944]

Yep but that is from version 128.  My best guess is you are having a caching issue at either your ISP or the CDN that propagates our packages.  Give it some time and try again.

[moviefan 184]

I have had issues with both of the last two updates for it updating automatically.

 

For some reason it has been bringing up the UAC prompt and forcing me to go get my mouse remote to click ok.

 

For the 127 upgrade I didn't wait long enough or something and had to uninstall/reinstall MBC completely.

 

Have you changed how these upgrades work now due to the new plugin functionality being included?

[doron 7]

Yep but that is from version 128.  My best guess is you are having a caching issue at either your ISP or the CDN that propagates our packages.  Give it some time and try again.

 

Thanks. Seems like a good guess as the download speed seems to go exceptionally high, much more so than in previous upgrades.

Waited some hours and it's still broken... Is there a URL I can use to short-circuit this mess - like bring in the main beef (I'll bring it via another ISP)?

 

 

Perhaps, for future versions, you might want to put in a little something that'll prevent stuff like that (e.g. slightly different file names or whatnot).

[ebr 14944]

Each release has a different source file name. 

 

Nothing has changed as far as UAC is concerned - we have always required administrator authorization to install.  This is a requirement of any WMC plug-in.

[doron 7]

A day later and the issue is still there.

I see your point re different file names. in that case, there is some problem with this build. This never happened before and I've been installing practically every dev build.

Anything else I can do to debug this?

[ebr 14944]

A lot of people have installed it so it must be something more specific.

[moviefan 184]

Each release has a different source file name. 

 

Nothing has changed as far as UAC is concerned - we have always required administrator authorization to install.  This is a requirement of any WMC plug-in.

 

I am certain that there have been many upgrades which did not require me to click the UAC acceptance dialog.  Not sure how or why but this just started happening for me again recently with the previous two.

[ebr 14944]

Perhaps your UAC level setting has changed on the machine for some reason.

[moviefan 184]

Would make sense but there is no way... the PC just runs MediaBrowser.  Doesnt do anything else.  And I am the only one who ever makes changes.  Anyway I will just watch for the next one and see if it happens again.

[doron 7]

Just an extra data point - in my case, UAC dialog appears on every upgrade.

 

(btw, I stll can't upgrade to 129. Same: The download completes, actual installed runs for a split second and that's that. WMC restarts and MBC asks again to upgrade).

[ebr 14944]

doron - can you repost your latest msi install log?

[doron 7]

doron - can you repost your latest msi install log?

 

Here you go.

=== Logging started: 14-Feb-14  18:14:57 ===
Action start 18:14:57: INSTALL.
Action start 18:14:57: FindRelatedProducts.
Action ended 18:14:57: FindRelatedProducts. Return value 0.
Action start 18:14:57: AppSearch.
Action ended 18:14:57: AppSearch. Return value 1.
Action start 18:14:57: LaunchConditions.
Action ended 18:14:57: LaunchConditions. Return value 1.
Action start 18:14:57: WixQueryOsWellKnownSID.
Action ended 18:14:58: WixQueryOsWellKnownSID. Return value 1.
Action start 18:14:58: ValidateProductID.
Action ended 18:14:58: ValidateProductID. Return value 1.
Action start 18:14:58: CostInitialize.
Action ended 18:15:07: CostInitialize. Return value 1.
Action start 18:15:07: FileCost.
Action ended 18:15:07: FileCost. Return value 1.
Action start 18:15:07: CostFinalize.
Action ended 18:15:08: CostFinalize. Return value 1.
Action start 18:15:08: CA_RegisterMceApp_Unregister_Install_Cmd7.
Action ended 18:15:08: CA_RegisterMceApp_Unregister_Install_Cmd7. Return value 1.
Action start 18:15:08: CA_RegisterMceApp_Register_Cmd7.
Action ended 18:15:08: CA_RegisterMceApp_Register_Cmd7. Return value 1.
Action start 18:15:08: CA_RegisterMceApp_Rollback_Cmd7.
Action ended 18:15:08: CA_RegisterMceApp_Rollback_Cmd7. Return value 1.
Action start 18:15:08: InstallValidate.
Action ended 18:15:08: InstallValidate. Return value 1.
Action start 18:15:08: RemoveExistingProducts.
Action ended 18:15:08: RemoveExistingProducts. Return value 0.
Action start 18:15:08: SaveInstallLocation.
Action ended 18:15:08: SaveInstallLocation. Return value 1.
Action start 18:15:08: InstallInitialize.
Action ended 18:15:08: InstallInitialize. Return value 1.
Action start 18:15:08: ProcessComponents.
Action ended 18:15:08: ProcessComponents. Return value 1.
Action start 18:15:08: MsiUnpublishAssemblies.
Action ended 18:15:08: MsiUnpublishAssemblies. Return value 1.
Action start 18:15:08: UnpublishFeatures.
Action ended 18:15:08: UnpublishFeatures. Return value 1.
Action start 18:15:08: SchedSecureObjectsRollback.
Action start 18:15:08: ExecSecureObjectsRollback.
Action ended 18:15:08: ExecSecureObjectsRollback. Return value 1.
Action start 18:15:08: ExecSecureObjectsRollback.
Action ended 18:15:08: ExecSecureObjectsRollback. Return value 1.
Action ended 18:15:08: SchedSecureObjectsRollback. Return value 1.
Action start 18:15:08: RemoveRegistryValues.
Action ended 18:15:08: RemoveRegistryValues. Return value 1.
Action start 18:15:08: RemoveShortcuts.
Action ended 18:15:08: RemoveShortcuts. Return value 1.
Action start 18:15:08: RemoveFiles.
Action ended 18:15:08: RemoveFiles. Return value 1.
Action start 18:15:08: WixSchedInternetShortcuts.
Action ended 18:15:08: WixSchedInternetShortcuts. Return value 1.
Action start 18:15:08: RemoveFolders.
Action ended 18:15:08: RemoveFolders. Return value 1.
Action start 18:15:08: CreateFolders.
Action ended 18:15:08: CreateFolders. Return value 1.
Action start 18:15:08: InstallFiles.
Action ended 18:15:08: InstallFiles. Return value 1.
Action start 18:15:08: CA_RegisterMceApp_Rollback.
Action ended 18:15:08: CA_RegisterMceApp_Rollback. Return value 1.
Action start 18:15:08: CA_RegisterMceApp_Unregister_Install.
Action ended 18:15:08: CA_RegisterMceApp_Unregister_Install. Return value 1.
Action start 18:15:08: CA_RegisterMceApp_Register.
Action ended 18:15:08: CA_RegisterMceApp_Register. Return value 1.
Action start 18:15:08: CreateShortcuts.
Action ended 18:15:08: CreateShortcuts. Return value 1.
Action start 18:15:08: WixRollbackInternetShortcuts.
Action ended 18:15:08: WixRollbackInternetShortcuts. Return value 1.
Action start 18:15:08: WixCreateInternetShortcuts.
Action ended 18:15:08: WixCreateInternetShortcuts. Return value 1.
Action start 18:15:08: WriteRegistryValues.
Action ended 18:15:08: WriteRegistryValues. Return value 1.
Action start 18:15:08: InstallServices.
Action ended 18:15:08: InstallServices. Return value 1.
Action start 18:15:08: SchedSecureObjects.
Action ended 18:15:08: SchedSecureObjects. Return value 1.
Action start 18:15:08: RegisterUser.
Action ended 18:15:08: RegisterUser. Return value 0.
Action start 18:15:08: RegisterProduct.
Action ended 18:15:08: RegisterProduct. Return value 1.
Action start 18:15:08: MsiPublishAssemblies.
Action ended 18:15:08: MsiPublishAssemblies. Return value 1.
Action start 18:15:08: PublishFeatures.
Action ended 18:15:08: PublishFeatures. Return value 1.
Action start 18:15:08: PublishProduct.
Action ended 18:15:08: PublishProduct. Return value 1.
Action start 18:15:08: InstallFinalize.
CAQuietExec:  
Success
CAQuietExec:  
Success
Action ended 18:15:09: InstallFinalize. Return value 1.
Action start 18:15:09: CA_Migrate.
Action ended 18:15:17: CA_Migrate. Return value 1.
Action ended 18:15:17: INSTALL. Return value 1.
MSI (s) (60:94) [18:15:17:703]: Product: Media Browser Classic -- Configuration completed successfully.

MSI (s) (60:94) [18:15:17:754]: Windows Installer reconfigured the product. Product Name: Media Browser Classic. Product Version: 3.0.128.0. Product Language: 1033. Manufacturer: Media Browser Team. Reconfiguration success or error status: 0.

=== Logging stopped: 14-Feb-14  18:15:17 ===

[ebr 14944]

Yep, you are seeing the issue we've seen from one or two others.  Your ISP or something else in your network delivery chain is caching very aggressively such that it doesn't realize this is a new file.  It must be using the target name for its cache instead of the source or something like that.  The other person seeing this issue was able to take their machine (it was a laptop) to a different network connection and it updated properly right away.

 

Why don't you try completely re-booting your router just in case that is what is trying to be too smart.