[Incident]: Some users report they are unable to access the case portal. Please see this community notice for more information.

cancel
Showing results for 
Show  only  | Search instead for 
Did you mean: 
sarfuis
Level 3

Installer hanging after Installation. Chain MSIs.

Hi,
I am facing an issue where in the Installation is hanging at the end. The finish dialog never comes up. I can see that all the files have got installed all the CAs have run properly. Details.

I am using InstallShield Professional 2009.
I am using Basic MSI setup.exe.
It is a chain Installer with 3 msis.
Its a Windows 2003 server.
All the three chained msis are installed properly.
IT IS NOT RESISTANTLY REPRODUCED. If i Uninstall and reinstall the package again, Installation will just go fine.

Please let me know any pointers so that i can look at them.

Here is the last log entry reported by the windows installer..
I have removed few lines of log because of the limit of posting.

Here inLine Bud Service is the last chain package to be installed. After it get installed, Installer will return to the parent package. I see in the failure log,
Installer is not proceeding further after last chain package gets installed.

MSI (s) (34:FC) [16:45:39:354]: Note: 1: 1707
MSI (s) (34:FC) [16:45:39:354]: Product: myproduct inLine Bud Service -- Installation operation completed successfully.

MSI (s) (34:FC) [16:45:39:354]: Windows Installer installed the product. Product Name: myproduct inLine Bud Service. Product Version: 1.0.0.1452. Product Language: 1033. Installation success or error status: 0.

MSI (s) (34:FC) [16:45:39:354]: MainEngineThread is returning 0
=== Logging stopped: 5/2/2011 16:45:39 ===
MSI (c) (B8:DC) [16:45:39:464]: Decrementing counter to disable shutdown. If counter >= 0, shutdown will be denied. Counter after decrement: -1
MSI (c) (B8:DC) [16:45:39:464]: MainEngineThread is returning 0
=== Verbose logging stopped: 5/2/2011 16:45:39 ===

MSI (s) (34:40) [16:45:39:495]: User policy value 'DisableRollback' is 0
MSI (s) (34:40) [16:45:39:495]: Machine policy value 'DisableRollback' is 0
MSI (s) (34:40) [16:45:39:495]: Incrementing counter to disable shutdown. Counter after increment: 0
MSI (s) (34:68) [16:45:39:511]: Invoking remote custom action. DLL: C:\WINDOWS\Installer\MSI16DD.tmp, Entrypoint: ISChainPackagesCleanup
MSI (s) (34:40) [16:45:39:682]: Decrementing counter to disable shutdown. If counter >= 0, shutdown will be denied. Counter after decrement: -1
MSI (s) (34:40) [16:45:39:682]: Restoring environment variables
MSI (s) (34:40) [16:45:39:682]: No System Restore sequence number for this installation.


-----------------------------------------------
But the complete installation with the Setup Finish dialog will have the following log
------------------------------------------------


MSI (s) (74:54) [22:22:17:265]: Note: 1: 1707
MSI (s) (74:54) [22:22:17:265]: Product: myproduct inLine Bud Service -- Installation operation completed successfully.

MSI (s) (74:54) [22:22:17:265]: Windows Installer installed the product. Product Name: myproduct inLine Bud Service. Product Version: 1.0.0.1443. Product Language: 1033. Installation success or error status: 0.

MSI (s) (74:54) [22:22:17:265]: MainEngineThread is returning 0
=== Logging stopped: 4/26/2011 22:22:17 ===
MSI (c) (28:CC) [22:22:17:374]: Decrementing counter to disable shutdown. If counter >= 0, shutdown will be denied. Counter after decrement: -1
MSI (c) (28:CC) [22:22:17:374]: MainEngineThread is returning 0
=== Verbose logging stopped: 4/26/2011 22:22:17 ===

MSI (s) (74:20) [22:22:17:390]: User policy value 'DisableRollback' is 0
MSI (s) (74:20) [22:22:17:390]: Machine policy value 'DisableRollback' is 0
MSI (s) (74:20) [22:22:17:390]: Incrementing counter to disable shutdown. Counter after increment: 0
MSI (s) (74:34) [22:22:17:406]: Invoking remote custom action. DLL: C:\WINDOWS\Installer\MSI97F.tmp, Entrypoint: ISChainPackagesCleanup
MSI (s) (74:20) [22:22:17:562]: Decrementing counter to disable shutdown. If counter >= 0, shutdown will be denied. Counter after decrement: -1
MSI (s) (74:20) [22:22:17:562]: Restoring environment variables
MSI (s) (74:20) [22:22:17:562]: No System Restore sequence number for this installation.
=== Verbose logging started: 4/26/2011 22:22:17 Build type: SHIP UNICODE 4.05.6001.00 Calling process: C:\WINDOWS\Installer\MSI89B.tmp ===
MSI (c) (28:50) [22:22:17:562]: Cloaking enabled.
MSI (c) (28:50) [22:22:17:562]: Attempting to enable all disabled privileges before calling Install on Server
=== Verbose logging stopped: 4/26/2011 22:22:17 ===

MSI (c) (10:EC) [22:22:17:577]: Back from server. Return value: 0
MSI (c) (10:EC) [22:22:17:577]: Decrementing counter to disable shutdown. If counter >= 0, shutdown will be denied. Counter after decrement: -1
MSI (c) (10:EC) [22:22:17:577]: PROPERTY CHANGE: Deleting SECONDSEQUENCE property. Its current value is '1'.
Action ended 22:22:17: ExecuteAction. Return value 1.
MSI (c) (10:EC) [22:22:17:577]: Doing action: DLLWrapCleanup
Action 22:22:17: DLLWrapCleanup.
Action start 22:22:17: DLLWrapCleanup.
MSI (c) (10:EC) [22:22:17:577]: Creating MSIHANDLE (710) of type 790542 for thread 6124
(10!FC) [22:22:17:749]: Creating MSIHANDLE (748) of type 790540 for thread 2556
M
MSI (c) (10!FC) [22:22:17:765]: Closing MSIHANDLE (771) of type 790541 for thread 2556
MSI (c) (10!FC) [22:22:17:765]: Closing MSIHANDLE (772) of type 790540 for thread 2556
MSI (c) (10:B0) [22:22:17:765]: Closing MSIHANDLE (710) of type 790542 for thread 6124
Action ended 22:22:17: DLLWrapCleanup. Return value 1.
MSI (c) (10:EC) [22:22:17:765]: Doing action: ISSetupFilesCleanup
Action 22:22:17: ISSetupFilesCleanup.
Action start 22:22:17: ISSetupFilesCleanup.
MSI (c) (10:EC) [22:22:17:765]: Creating MSIHANDLE (774) of type 790542 for thread 6124
MSI (c) (10:D4) [22:22:17:765]: Invoking remote custom action. DLL: C:\DOCUME~1\1\LOCALS~1\Temp\MSI982.tmp, Entrypoint: SFCleanupEx
MSI (c) (10!E0) [22:22:17:859]: Creating MSIHANDLE (775) of type 790531 for thread 6112
1: Deleting Support File: C:\DOCUME~1\1\LOCALS~1\Temp\{3C230D85-CB6A-47DA-A647-41CDE9F8E235}\AgentUISetupHelper.dll
MSI (c) (10!E0) [22:22:17:859]: Closing MSIHANDLE (775) of type 790531 for thread 6112
MSI (c) (10!E0) [22:22:17:859]: Creating MSIHANDLE (776) of type 790531 for thread 6112
1: Deleting Support File: C:\DOCUME~1\1\LOCALS~1\Temp\{3C230D85-CB6A-47DA-A647-41CDE9F8E235}\importpfx.exe
MSI (c) (10!E0) [22:22:17:859]: Closing MSIHANDLE (776) of type 790531 for thread 6112
MSI (c) (10!E0) [22:22:17:859]: Creating MSIHANDLE (777) of type 790531 for thread 6112
1: Deleting Support File: C:\DOCUME~1\1\LOCALS~1\Temp\{3C230D85-CB6A-47DA-A647-41CDE9F8E235}\libeay32.dll
MSI (c) (10!E0) [22:22:17:859]: Closing MSIHANDLE (777) of type 790531 for thread 6112
MSI (c) (10!E0) [22:22:17:859]: Creating MSIHANDLE (778) of type 790531 for thread 6112
1: Deleting Support File: C:\DOCUME~1\1\LOCALS~1\Temp\{3C230D85-CB6A-47DA-A647-41CDE9F8E235}\SDConfig.inf
MSI (c) (10!E0) [22:22:17:859]: Closing MSIHANDLE (778) of type 790531 for thread 6112
MSI (c) (10!E0) [22:22:17:859]: Creating MSIHANDLE (779) of type 790531 for thread 6112
1: Deleting Support File: C:\DOCUME~1\1\LOCALS~1\Temp\{3C230D85-CB6A-47DA-A647-41CDE9F8E235}\SDIBMConfig.dll
MSI (c) (10!E0) [22:22:17:859]: Closing MSIHANDLE (779) of type 790531 for thread 6112
MSI (c) (10!E0) [22:22:17:859]: Creating MSIHANDLE (780) of type 790531 for thread 6112
1: Deleting Support File: C:\DOCUME~1\1\LOCALS~1\Temp\{3C230D85-CB6A-47DA-A647-41CDE9F8E235}\SDKyoConfig.dll
MSI (c) (10!E0) [22:22:17:859]: Closing MSIHANDLE (780) of type 790531 for thread 6112
MSI (c) (10!E0) [22:22:17:859]: Creating MSIHANDLE (781) of type 790531 for thread 6112
1: Deleting Support File: C:\DOCUME~1\1\LOCALS~1\Temp\{3C230D85-CB6A-47DA-A647-41CDE9F8E235}\ssleay32.dll
MSI (c) (10!E0) [22:22:17:859]: Closing MSIHANDLE (781) of type 790531 for thread 6112
MSI (c) (10:D4) [22:22:17:952]: Closing MSIHANDLE (774) of type 790542 for thread 6124
Action ended 22:22:17: ISSetupFilesCleanup. Return value 1.
MSI (c) (10:EC) [22:22:17:952]: Doing action: SetupCompleteSuccess
Action 22:22:17: SetupCompleteSuccess.
Action start 22:22:17: SetupCompleteSuccess.
Action 22:22:17: SetupCompleteSuccess. Dialog created
MSI (c) (10:D4) [22:36:14:511]: Doing action: ISSetupFilesCleanup
Action 22:36:14: ISSetupFilesCleanup.
Action start 22:36:14: ISSetupFilesCleanup.
MSI (c) (10:D4) [22:36:14:511]: Creating MSIHANDLE (782) of type 790542 for thread 4052
MSI (c) (10:B0) [22:36:14:511]: Invoking remote custom action. DLL: C:\DOCUME~1\1\LOCALS~1\Temp\MSI983.tmp, Entrypoint: SFCleanupEx
MSI (c) (10:B0) [22:36:14:558]: Closing MSIHANDLE (782) of type 790542 for thread 4052
Action ended 22:36:14: ISSetupFilesCleanup. Return value 1.
Action ended 22:36:14: SetupCompleteSuccess. Return value 2.
Action ended 22:36:14: INSTALL. Return value 1.
MSI (c) (10:EC) [22:36:14:573]: Destroying RemoteAPI object.
MSI (c) (10:94) [22:36:14:589]: Custom Action Manager thread ending.
Property(C): DiskPrompt = [1]
Property(C): UpgradeCode = {39058B1C-DB70-4B21-9D96-6BCE3FF762DC}
Property(C): ProductCode = {3C230D85-CB6A-47DA-A647-41CDE9F8E235}
Property(C): PROCESS_EXISTS = NO
Property(C): EXISTING_EXTENSION_RCU = 0
Property(C): OPROCESS_EXISTS = NO
Property(C): EXISTING_EXTENSION_VSC = 0
Property(C): EXISTING_EXTENSION_NVPF = 0
Property(C): CHECK_PROCESS_OK = 0
Property(C): PORTSVALID = 1
Property(C): PLUGIN_TYPE = 0
Property(C): NON_HV_OS = 1
Property(C): NO_SPACE_AVAILABLE = 0
Property(C): CHECK_EXE_OK = 0
Property(C): PrimaryVolumeSpaceRemaining = 0
Property(C): PrimaryVolumeSpaceRequired = 0
Property(C): PrimaryVolumeSpaceAvailable = 0
Property(C): OutOfNoRbDiskSpace = 0
Property(C): OutOfDiskSpace = 0
Property(C): SourcedirProduct = {3C230D85-CB6A-47DA-A647-41CDE9F8E235}
Property(C): SETUPEXECOUNT = NO
Property(C): CostingComplete = 1
Property(C): ROOTDRIVE = C:\
Property(C): SDCONFIG_LIB_PATH = C:\DOCUME~1\1\LOCALS~1\Temp\SDConfig.dll
Py(C): CommonAppDataFolder = C:\Documents and Settings\All Users\Application Data\
Property(C): TemplateFolder = C:\Documents and Settings\All Users\Templates\
Property(C): HS_DYMC_CERT = null
Property(C): HSFOLDERREMOVE = 1
Property(C): ISCHECKFORPRODUCTUPDATES = 1
Property(C): RCU1 = 0
Property(C): RCU11 = 0
Property(C): SD_AgentUI = 0
Property(C): VSC1 = 0
Property(C): NewProperty50 = 0
Property(C): NewProperty51 = 0
Property(C): ApplicationUsers = AllUsers
Property(C): NewProperty10 = 0
Property(C): NewProperty11 = 0
Property(C): NewProperty25 = 0
Property(C): NewProperty195 = 0
Property(C): NewProperty20 = 0
Property(C): NewProperty26 = 0
Property(C): NewProperty27 = 0
Property(C): NewProperty37 = 0
Property(C): AgentUI_SERVER_SSL_PORT = 8488
Property(C): AgentUI_SERVER_COM_PORT = 8488
Property(C): HS_MGM_PORT = 8799
Property(C): HS_ADMIN_PORT = 8699
Property(C): HS_FS_PORT = 8897
Property(C): TCPIP_PORT = 808
Property(C): HTTP_PORT = 4094
Property(C): HTTPS_PORT = 4095
Pro): SMHVINSTALLED = 0
Property(C): SMVI_PLUGIN_PORT = 8044
Property(C): SVCACCOUNTVALID = 1
Property(C): SecureCustomProperties = ISFOUNDNEWERPRODUCTVERSION;USERNAME;COMPANYNAME;ISX_SERIALNUM;SUPPORTDIR;VSC1;RCU1;RCU11;SD_AgentUI;HS_MGM_PORT;HS_ADMIN_PORT;HS_FS_PORT;PLUGIN_PORT;TCPIP_PORT;HTTP_PORT;HTTPS_PORT;SVCUSERNAME;SVCCONFIRMUSERname;AgentUI_SKIP_VERIFY;AgentUI_SERVER_COM_PORT;AgentUI_SERVER_USERNAME;AgentUI_SERVER_SSL_PORT;AgentUI_SERVER_name;HSFOLDERREMOVE;AgentUI_SERVER_INFO;EXISTING_VSPHERE_EXTENSIONS_TEXT;VMWARE_PLUGIN_IP;VCENTER_IP;VCENTER_USERNAME;VCENTER_name;VCENTER_PORT;VC_SKIP_VERIFY;RemoveTempISFiles;AgentUI_SRV_LOGON_PASS;SMHVSRV_name;SMHVSRV_CONFIRMUSERname; SVCUSERname; SVCCONFIRMUSERname;AgentUI_SERVER_name;VCENTER_name;name;PSPOG_KEYSTORE_PASS;PSPOG_KEY_PASS;HS_DYMC_CERT
Property(C): SetupType = Typical
Property(C): TRANSPORT_PRT_SELECTION = 1
Property(C): VALID_AgentUI_SRV_DETECTED = 0
Property(C): VCACCOUNTVALID = 1
Property(C): VENDOR = Kyo
Property(C): VMWAREUPGRADE = 1
Property(C): VSC = 0
Property(C): ISReleaseFlags = X86
Property(C): SETUPEXENAME = ocInLine-setup-mainN_110425_1615-x86.exe
Property(C): MsiLogFileLocation = C:\DOCUME~1\1\LOCALS~1\Temp\MSI25c07.LOG
Property(C): VMPLUGINVALID = 1
Property(C): SendToFolder = C:\Documents and Settings\1\SendTo\
Property(C): RecentFolder = C:\Documents and Settings\1\Recent\
Property(C): PrintHoodFolder = C:\Documents and Settings\1\PrintHood\
Property(C): PersonalFolder = C:\Documents and Settings\1\My Documents\
Property(C): NetHoodFolder = C:\Documents and Settings\1\NetHood\
Property(C): FavoritesFolder = C:\Documents and Settings\1\Favorites\
Property(C): AppDataFolder = C:\Documents and Settings\1\Application Data\
Property(C): TempFolder = C:\DOCUME~1\1\LOCALS~1\Temp\
Property(C): RemoteAdminTS = 1
Property(C): SVCUSERNAME = VIM\1
Property(C): SVCUSERname = **********
Property(C): System16Folder = C:\WINDOWS\system\
Property(C): SystemFolder = C:\WINDOWS\system32\
Property(C): WindowsVolume = C:\
Property(C): MsiNTSuiteEnterprise = 1
Property(C): MsiNTProductType = 3
Property(C): _BrowseProperty = INSTALLDIR
Property(C): ServicePackLevelMinor = 0
Property(C): ServicePackLevel = 2
Property(C): COMPANYNAME = Kyo
Property(C): USERNAME = mvikram
Property(C): AgentUI_SKIP_VERIFY = 1
Property(C): AgentUI_SERVER_INFO = AgentUI_IP
Property(C): AgentUI_SERVER_USERNAME = AgentUI_SERVER_USERNAME
Property(C): WindowsBuild = 3790
Property(C): PLUGIN_PORT = 8044
Property(C): VersionMsi = 4.05
Property(C): VersionDatabase = 200
Property(C): CLIENTPROCESSID = 5904
Property(C): CLIENTUILEVEL = 0
Property(C): CURRENTDIRECTORY = C:\Documents and Settings\1\Desktop
Property(C): SETUPEXEDIR = C:\Documents and Settings\1\Desktop
Property(C): PackagecodeChanging = 1
Property(C): ProductState = -1
Property(C): PackageCode = {BBC4CBB2-5FC7-4683-B3C6-7CDFA4C96954}


=== Logging stopped: 4/26/2011 22:36:14 ===
MSI (c) (10:EC) [22:36:14:620]: Note: 1: 1707
MSI (c) (10:EC) [22:36:14:620]: Product: myproduct inLine Bud Service -- Installation operation completed successfully.

MSI (c) (10:EC) [22:36:14:620]: Windows Installer installed the product. Product Name: myproduct inLine Bud Service. Product Version: 1.0. Product Language: 1033. Installation success or error status: 0.

MSI (c) (10:EC) [22:36:14:636]: Grabbed execution mutex.
MSI (c) (10:EC) [22:36:14:636]: Cleaning up uninstalled install packages, if any exist
MSI (c) (10:EC) [22:36:14:636]: MainEngineThread is returning 0
=== Verbose logging stopped: 4/26/2011 22:36:14 ===
Labels (1)
0 Kudos
1 Reply
Matthias1967
Level 5

Hello sarfuis,

I am getting the same issue with IS2010 on several OSs.

BTW, if I just reboot the machine (or kill msiexec.exe), everything is there!

I am heavily searching for solutions to this issue, but have not yet found any answers.

What is more, the chainer does not write anything to the log file, so I do not get any clues from there either.

I think this is a bug (in the ISChainer or in Windows Installer?) that should be fixed soon...

Best regards

Matthias
0 Kudos