I have raised a bug for this and attached logs and source: https://sourceforge.net/tracker/?func=detail&aid=2951181&group_id=105970 &atid=642714
Neil -----Original Message----- From: Neil Sleightholm [mailto:n...@x2systems.com] Sent: 10 February 2010 22:07 To: General discussion for Windows Installer XML toolset. Subject: Re: [WiX-users] Installer hanging while doing FileCost, not frequently though. I think I have finally caught this and got a log. I ran a very simple install that uses WixUI_Advanced, I created a batch file to install and uninstall this again and again. After about 20 iterations the install hung displaying the dialog "Please wait while the installer finishes determining your disk space requirements". You can click on the "Return" button and it goes back to the license dialog. If you click "Install" it displays the "Please wait..." dialog again and the only option is to cancel the install. Looking at the log (shown below) I am not sure the subject title of this email reflects what is actually happening but it does match the link the was originally in this question (http://n2.nabble.com/Installer-hanging-while-doing-FileCost-not-frequen tly-though-td2116872.html#a4519081) and is something I have seen quite a lot. I have this in a snapshot of a VM if anyone can think of any questions to ask to help diagnose this. Regards Neil Neil Sleightholm X2 Systems Limited n...@x2systems.com <mailto:n...@x2systems.com> === Verbose logging started: 10/02/2010 21:49:45 Build type: SHIP UNICODE 4.05.6001.00 Calling process: C:\WINDOWS\system32\msiexec.exe === MSI (c) (F8:D0) [21:49:45:343]: Resetting cached policy values MSI (c) (F8:D0) [21:49:45:343]: Machine policy value 'Debug' is 0 MSI (c) (F8:D0) [21:49:45:343]: ******* RunEngine: ******* Product: Setup.msi ******* Action: ******* CommandLine: ********** MSI (c) (F8:D0) [21:49:45:343]: Machine policy value 'DisableUserInstalls' is 0 MSI (c) (F8:D0) [21:49:45:343]: SOFTWARE RESTRICTION POLICY: Verifying package --> 'C:\Documents and Settings\Administrator\Desktop\Setup.msi' against software restriction policy MSI (c) (F8:D0) [21:49:45:343]: Note: 1: 2262 2: DigitalSignature 3: -2147287038 MSI (c) (F8:D0) [21:49:45:343]: SOFTWARE RESTRICTION POLICY: C:\Documents and Settings\Administrator\Desktop\Setup.msi is not digitally signed MSI (c) (F8:D0) [21:49:45:343]: SOFTWARE RESTRICTION POLICY: C:\Documents and Settings\Administrator\Desktop\Setup.msi is permitted to run at the 'unrestricted' authorization level. MSI (c) (F8:D0) [21:49:45:358]: Cloaking enabled. MSI (c) (F8:D0) [21:49:45:358]: Attempting to enable all disabled privileges before calling Install on Server MSI (c) (F8:D0) [21:49:45:358]: End dialog not enabled MSI (c) (F8:D0) [21:49:45:358]: Original package ==> C:\Documents and Settings\Administrator\Desktop\Setup.msi MSI (c) (F8:D0) [21:49:45:358]: Package we're running from ==> C:\DOCUME~1\ADMINI~1\LOCALS~1\Temp\20ce31.msi MSI (c) (F8:D0) [21:49:45:358]: APPCOMPAT: looking for appcompat database entry with ProductCode '{6DB85887-FFBD-41C7-9E86-B3844572E6CB}'. MSI (c) (F8:D0) [21:49:45:358]: APPCOMPAT: no matching ProductCode found in database. MSI (c) (F8:D0) [21:49:45:358]: MSCOREE not loaded loading copy from system32 MSI (c) (F8:D0) [21:49:45:374]: Machine policy value 'TransformsSecure' is 0 MSI (c) (F8:D0) [21:49:45:374]: User policy value 'TransformsAtSource' is 0 MSI (c) (F8:D0) [21:49:45:374]: Note: 1: 2205 2: 3: MsiFileHash MSI (c) (F8:D0) [21:49:45:374]: Machine policy value 'DisablePatch' is 0 MSI (c) (F8:D0) [21:49:45:374]: Machine policy value 'AllowLockdownPatch' is 0 MSI (c) (F8:D0) [21:49:45:374]: Machine policy value 'DisableLUAPatching' is 0 MSI (c) (F8:D0) [21:49:45:374]: Machine policy value 'DisableFlyWeightPatching' is 0 MSI (c) (F8:D0) [21:49:45:374]: APPCOMPAT: looking for appcompat database entry with ProductCode '{6DB85887-FFBD-41C7-9E86-B3844572E6CB}'. MSI (c) (F8:D0) [21:49:45:374]: APPCOMPAT: no matching ProductCode found in database. MSI (c) (F8:D0) [21:49:45:374]: Transforms are not secure. MSI (c) (F8:D0) [21:49:45:374]: PROPERTY CHANGE: Adding MsiLogFileLocation property. Its value is 'C:\Documents and Settings\Administrator\Desktop\Setup.log'. MSI (c) (F8:D0) [21:49:45:374]: Command Line: CURRENTDIRECTORY=C:\Documents and Settings\Administrator\Desktop CLIENTUILEVEL=0 CLIENTPROCESSID=3320 MSI (c) (F8:D0) [21:49:45:374]: PROPERTY CHANGE: Adding PackageCode property. Its value is '{FA568AEC-31C1-482A-BC9E-38333FCAC44A}'. MSI (c) (F8:D0) [21:49:45:374]: Product Code passed to Engine.Initialize: '' MSI (c) (F8:D0) [21:49:45:374]: Product Code from property table before transforms: '{6DB85887-FFBD-41C7-9E86-B3844572E6CB}' MSI (c) (F8:D0) [21:49:45:374]: Product Code from property table after transforms: '{6DB85887-FFBD-41C7-9E86-B3844572E6CB}' MSI (c) (F8:D0) [21:49:45:374]: Product not registered: beginning first-time install MSI (c) (F8:D0) [21:49:45:374]: PROPERTY CHANGE: Adding ProductState property. Its value is '-1'. MSI (c) (F8:D0) [21:49:45:374]: Entering CMsiConfigurationManager::SetLastUsedSource. MSI (c) (F8:D0) [21:49:45:374]: User policy value 'SearchOrder' is 'nmu' MSI (c) (F8:D0) [21:49:45:374]: Adding new sources is allowed. MSI (c) (F8:D0) [21:49:45:374]: PROPERTY CHANGE: Adding PackagecodeChanging property. Its value is '1'. MSI (c) (F8:D0) [21:49:45:374]: Package name extracted from package path: 'Setup.msi' MSI (c) (F8:D0) [21:49:45:374]: Package to be registered: 'Setup.msi' MSI (c) (F8:D0) [21:49:45:374]: Note: 1: 2262 2: AdminProperties 3: -2147287038 MSI (c) (F8:D0) [21:49:45:374]: Machine policy value 'DisableMsi' is 0 MSI (c) (F8:D0) [21:49:45:374]: Machine policy value 'AlwaysInstallElevated' is 0 MSI (c) (F8:D0) [21:49:45:374]: User policy value 'AlwaysInstallElevated' is 0 MSI (c) (F8:D0) [21:49:45:374]: Product installation will be elevated because user is admin and product is being installed per-machine. MSI (c) (F8:D0) [21:49:45:374]: Running product '{6DB85887-FFBD-41C7-9E86-B3844572E6CB}' with elevated privileges: Product is assigned. MSI (c) (F8:D0) [21:49:45:374]: PROPERTY CHANGE: Adding CURRENTDIRECTORY property. Its value is 'C:\Documents and Settings\Administrator\Desktop'. MSI (c) (F8:D0) [21:49:45:374]: PROPERTY CHANGE: Adding CLIENTUILEVEL property. Its value is '0'. MSI (c) (F8:D0) [21:49:45:374]: PROPERTY CHANGE: Adding CLIENTPROCESSID property. Its value is '3320'. MSI (c) (F8:D0) [21:49:45:374]: PROPERTY CHANGE: Adding MsiSystemRebootPending property. Its value is '1'. MSI (c) (F8:D0) [21:49:45:374]: TRANSFORMS property is now: MSI (c) (F8:D0) [21:49:45:374]: PROPERTY CHANGE: Adding VersionDatabase property. Its value is '200'. MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\Documents and Settings\Administrator\Application Data MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\Documents and Settings\Administrator\Favorites MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\Documents and Settings\Administrator\NetHood MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\Documents and Settings\Administrator\My Documents MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\Documents and Settings\Administrator\PrintHood MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\Documents and Settings\Administrator\Recent MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\Documents and Settings\Administrator\SendTo MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\Documents and Settings\Administrator\Templates MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\Documents and Settings\All Users\Application Data MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\Documents and Settings\Administrator\Local Settings\Application Data MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\Documents and Settings\Administrator\My Documents\My Pictures MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\Documents and Settings\All Users\Start Menu\Programs\Administrative Tools MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\Documents and Settings\All Users\Start Menu\Programs\Startup MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\Documents and Settings\All Users\Start Menu\Programs MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\Documents and Settings\All Users\Start Menu MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\Documents and Settings\All Users\Desktop MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\Documents and Settings\Administrator\Start Menu\Programs\Administrative Tools MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\Documents and Settings\Administrator\Start Menu\Programs\Startup MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\Documents and Settings\Administrator\Start Menu\Programs MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\Documents and Settings\Administrator\Start Menu MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\Documents and Settings\Administrator\Desktop MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\Documents and Settings\All Users\Templates MSI (c) (F8:D0) [21:49:45:374]: SHELL32::SHGetFolderPath returned: C:\WINDOWS\Fonts MSI (c) (F8:D0) [21:49:45:374]: Note: 1: 2898 2: MS Sans Serif 3: MS Sans Serif 4: 0 5: 16 MSI (c) (F8:D0) [21:49:45:374]: PROPERTY CHANGE: Adding Privileged property. Its value is '1'. MSI (c) (F8:D0) [21:49:45:374]: Note: 1: 1402 2: HKEY_CURRENT_USER\Software\Microsoft\MS Setup (ACME)\User Info 3: 2 MSI (c) (F8:D0) [21:49:45:374]: PROPERTY CHANGE: Adding USERNAME property. Its value is 'Neil Sleightholm'. MSI (c) (F8:D0) [21:49:45:374]: Note: 1: 1402 2: HKEY_CURRENT_USER\Software\Microsoft\MS Setup (ACME)\User Info 3: 2 MSI (c) (F8:D0) [21:49:45:374]: PROPERTY CHANGE: Adding COMPANYNAME property. Its value is 'X2 Systems Limited'. MSI (c) (F8:D0) [21:49:45:374]: PROPERTY CHANGE: Adding DATABASE property. Its value is 'C:\DOCUME~1\ADMINI~1\LOCALS~1\Temp\20ce31.msi'. MSI (c) (F8:D0) [21:49:45:374]: PROPERTY CHANGE: Adding OriginalDatabase property. Its value is 'C:\Documents and Settings\Administrator\Desktop\Setup.msi'. MSI (c) (F8:D0) [21:49:45:374]: Machine policy value 'MsiDisableEmbeddedUI' is 0 MSI (c) (F8:D0) [21:49:45:374]: PROPERTY CHANGE: Adding SourceDir property. Its value is 'C:\Documents and Settings\Administrator\Desktop\'. MSI (c) (F8:D0) [21:49:45:374]: PROPERTY CHANGE: Adding SOURCEDIR property. Its value is 'C:\Documents and Settings\Administrator\Desktop\'. MSI (c) (F8:74) [21:49:45:374]: PROPERTY CHANGE: Adding VersionHandler property. Its value is '4.05'. === Logging started: 10/02/2010 21:49:45 === MSI (c) (F8:D0) [21:49:45:390]: Note: 1: 2205 2: 3: PatchPackage MSI (c) (F8:D0) [21:49:45:390]: Machine policy value 'DisableRollback' is 0 MSI (c) (F8:D0) [21:49:45:390]: User policy value 'DisableRollback' is 0 MSI (c) (F8:D0) [21:49:45:390]: PROPERTY CHANGE: Adding UILevel property. Its value is '5'. MSI (c) (F8:D0) [21:49:45:390]: PROPERTY CHANGE: Adding ACTION property. Its value is 'INSTALL'. MSI (c) (F8:D0) [21:49:45:390]: Doing action: INSTALL Action 21:49:45: INSTALL. Action start 21:49:45: INSTALL. MSI (c) (F8:D0) [21:49:45:390]: UI Sequence table 'InstallUISequence' is present and populated. MSI (c) (F8:D0) [21:49:45:390]: Running UISequence MSI (c) (F8:D0) [21:49:45:390]: PROPERTY CHANGE: Adding EXECUTEACTION property. Its value is 'INSTALL'. MSI (c) (F8:D0) [21:49:45:390]: Doing action: PrepareDlg Action 21:49:45: PrepareDlg. Action start 21:49:45: PrepareDlg. Info 2898. For WixUI_Font_Normal textstyle, the system created a 'Tahoma' font, in 1 character set, of 13 pixels height. Info 2898. For WixUI_Font_Bigger textstyle, the system created a 'Tahoma' font, in 1 character set, of 19 pixels height. Action 21:49:45: PrepareDlg. Dialog created Action ended 21:49:45: PrepareDlg. Return value 1. MSI (c) (F8:D0) [21:49:45:405]: Doing action: AppSearch Action 21:49:45: AppSearch. Searching for installed applications Action start 21:49:45: AppSearch. MSI (c) (F8:D0) [21:49:45:405]: Note: 1: 2205 2: 3: AppSearch MSI (c) (F8:D0) [21:49:45:405]: Note: 1: 2228 2: 3: AppSearch 4: SELECT `Property`, `Signature_` FROM `AppSearch` Action ended 21:49:45: AppSearch. Return value 0. MSI (c) (F8:D0) [21:49:45:405]: Doing action: FindRelatedProducts Action 21:49:45: FindRelatedProducts. Searching for related applications Action start 21:49:45: FindRelatedProducts. Action ended 21:49:45: FindRelatedProducts. Return value 1. MSI (c) (F8:D0) [21:49:45:405]: Doing action: LaunchConditions Action 21:49:45: LaunchConditions. Evaluating launch conditions Action start 21:49:45: LaunchConditions. Action ended 21:49:45: LaunchConditions. Return value 1. MSI (c) (F8:D0) [21:49:45:405]: Doing action: ValidateProductID Action 21:49:45: ValidateProductID. Action start 21:49:45: ValidateProductID. Action ended 21:49:45: ValidateProductID. Return value 1. MSI (c) (F8:D0) [21:49:45:405]: Doing action: CostInitialize Action 21:49:45: CostInitialize. Computing space requirements Action start 21:49:45: CostInitialize. MSI (c) (F8:D0) [21:49:45:405]: Machine policy value 'MaxPatchCacheSize' is 10 MSI (c) (F8:D0) [21:49:45:405]: PROPERTY CHANGE: Adding ROOTDRIVE property. Its value is 'C:\'. MSI (c) (F8:D0) [21:49:45:405]: PROPERTY CHANGE: Adding CostingComplete property. Its value is '0'. Action ended 21:49:45: CostInitialize. Return value 1. MSI (c) (F8:D0) [21:49:45:405]: Doing action: FileCost Action 21:49:45: FileCost. Computing space requirements Action start 21:49:45: FileCost. MSI (c) (F8:D0) [21:49:45:405]: Note: 1: 2205 2: 3: MsiAssembly MSI (c) (F8:D0) [21:49:45:405]: Note: 1: 2205 2: 3: Registry MSI (c) (F8:D0) [21:49:45:405]: Note: 1: 2205 2: 3: Registry MSI (c) (F8:D0) [21:49:45:405]: Note: 1: 2205 2: 3: Class MSI (c) (F8:D0) [21:49:45:405]: Note: 1: 2205 2: 3: Extension MSI (c) (F8:D0) [21:49:45:405]: Note: 1: 2205 2: 3: TypeLib Action ended 21:49:45: FileCost. Return value 1. MSI (c) (F8:D0) [21:49:45:405]: Doing action: WixSetDefaultPerUserFolder Action 21:49:45: WixSetDefaultPerUserFolder. Action start 21:49:45: WixSetDefaultPerUserFolder. MSI (c) (F8:D0) [21:49:45:421]: PROPERTY CHANGE: Adding WixPerUserFolder property. Its value is 'C:\Documents and Settings\Administrator\Local Settings\Application Data\Apps\My Application'. Action ended 21:49:45: WixSetDefaultPerUserFolder. Return value 1. MSI (c) (F8:D0) [21:49:45:421]: Doing action: WixSetDefaultPerMachineFolder Action 21:49:45: WixSetDefaultPerMachineFolder. Action start 21:49:45: WixSetDefaultPerMachineFolder. MSI (c) (F8:D0) [21:49:45:421]: PROPERTY CHANGE: Adding WixPerMachineFolder property. Its value is 'C:\Program Files\My Application'. Action ended 21:49:45: WixSetDefaultPerMachineFolder. Return value 1. MSI (c) (F8:D0) [21:49:45:421]: Skipping action: WixSetPerUserFolder (condition is false) MSI (c) (F8:D0) [21:49:45:421]: Doing action: WixSetPerMachineFolder Action 21:49:45: WixSetPerMachineFolder. Action start 21:49:45: WixSetPerMachineFolder. MSI (c) (F8:D0) [21:49:45:421]: PROPERTY CHANGE: Adding APPLICATIONFOLDER property. Its value is 'C:\Program Files\My Application'. Action ended 21:49:45: WixSetPerMachineFolder. Return value 1. MSI (c) (F8:D0) [21:49:45:421]: Doing action: CostFinalize Action 21:49:45: CostFinalize. Computing space requirements Action start 21:49:45: CostFinalize. MSI (c) (F8:D0) [21:49:45:421]: PROPERTY CHANGE: Adding OutOfDiskSpace property. Its value is '0'. MSI (c) (F8:D0) [21:49:45:421]: PROPERTY CHANGE: Adding OutOfNoRbDiskSpace property. Its value is '0'. MSI (c) (F8:D0) [21:49:45:421]: PROPERTY CHANGE: Adding PrimaryVolumeSpaceAvailable property. Its value is '0'. MSI (c) (F8:D0) [21:49:45:421]: PROPERTY CHANGE: Adding PrimaryVolumeSpaceRequired property. Its value is '0'. MSI (c) (F8:D0) [21:49:45:421]: PROPERTY CHANGE: Adding PrimaryVolumeSpaceRemaining property. Its value is '0'. MSI (c) (F8:D0) [21:49:45:421]: Note: 1: 2205 2: 3: Patch MSI (c) (F8:D0) [21:49:45:421]: Note: 1: 2205 2: 3: Condition MSI (c) (F8:D0) [21:49:45:421]: PROPERTY CHANGE: Adding TARGETDIR property. Its value is 'C:\'. MSI (c) (F8:D0) [21:49:45:421]: PROPERTY CHANGE: Modifying APPLICATIONFOLDER property. Its current value is 'C:\Program Files\My Application'. Its new value: 'C:\Program Files\My Application\'. MSI (c) (F8:D0) [21:49:45:421]: Target path resolution complete. Dumping Directory table... MSI (c) (F8:D0) [21:49:45:421]: Note: target paths subject to change (via custom actions or browsing) MSI (c) (F8:D0) [21:49:45:421]: Dir (target): Key: TARGETDIR , Object: C:\ MSI (c) (F8:D0) [21:49:45:421]: Dir (target): Key: ProgramFilesFolder , Object: C:\Program Files\ MSI (c) (F8:D0) [21:49:45:421]: Dir (target): Key: APPLICATIONFOLDER , Object: C:\Program Files\My Application\ MSI (c) (F8:D0) [21:49:45:421]: PROPERTY CHANGE: Adding INSTALLLEVEL property. Its value is '1'. MSI (c) (F8:D0) [21:49:45:421]: Note: 1: 2205 2: 3: MsiAssembly MSI (c) (F8:D0) [21:49:45:421]: 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 ended 21:49:45: CostFinalize. Return value 1. MSI (c) (F8:D0) [21:49:45:421]: Doing action: AdvancedWelcomeEulaDlg Action 21:49:45: AdvancedWelcomeEulaDlg. Action start 21:49:45: AdvancedWelcomeEulaDlg. ------------------------------------------------------------------------ ------ SOLARIS 10 is the OS for Data Centers - provides features such as DTrace, Predictive Self Healing and Award Winning ZFS. Get Solaris 10 NOW http://p.sf.net/sfu/solaris-dev2dev _______________________________________________ WiX-users mailing list WiX-users@lists.sourceforge.net https://lists.sourceforge.net/lists/listinfo/wix-users ------------------------------------------------------------------------------ SOLARIS 10 is the OS for Data Centers - provides features such as DTrace, Predictive Self Healing and Award Winning ZFS. Get Solaris 10 NOW http://p.sf.net/sfu/solaris-dev2dev _______________________________________________ WiX-users mailing list WiX-users@lists.sourceforge.net https://lists.sourceforge.net/lists/listinfo/wix-users
