Installer should handle reboot requests from virtual box msi installation ------------------------------------------------------------------------- Key: JBDS-3655 URL: https://issues.jboss.org/browse/JBDS-3655 Project: Developer Studio (JBoss Developer Studio) Issue Type: Bug Components: installer Affects Versions: 9.1.0.CR1 Reporter: Denis Golovin Assignee: Denis Golovin Labels: havoc Fix For: 10.0.0.Alpha1 {code}=== Logging started: 04/03/2016 18:46:48 === Action start 18:46:48: INSTALL. Action start 18:46:48: AppSearch. Action ended 18:46:48: AppSearch. Return value 1. Action start 18:46:48: LaunchConditions. Action ended 18:46:48: LaunchConditions. Return value 1. Action start 18:46:48: FindRelatedProducts. Action ended 18:46:48: FindRelatedProducts. Return value 0. Action start 18:46:48: ValidateProductID. Action ended 18:46:48: ValidateProductID. Return value 1. Action start 18:46:48: CostInitialize. Action ended 18:46:50: CostInitialize. Return value 1. Action start 18:46:50: FileCost. Action ended 18:46:50: FileCost. Return value 1. Action start 18:46:50: CostFinalize. Action ended 18:46:50: CostFinalize. Return value 1. Action start 18:46:50: InstallValidate. Action ended 18:46:50: InstallValidate. Return value 1. Action start 18:46:50: InstallInitialize. Action ended 18:46:50: InstallInitialize. Return value 1. Action start 18:46:50: ProcessComponents. Action ended 18:46:50: ProcessComponents. Return value 1. Action start 18:46:50: UnpublishFeatures. Action ended 18:46:50: UnpublishFeatures. Return value 1. Action start 18:46:50: UnregisterTypeLibraries. Action ended 18:46:50: UnregisterTypeLibraries. Return value 1. Action start 18:46:50: RemoveRegistryValues. Action ended 18:46:50: RemoveRegistryValues. Return value 1. Action start 18:46:50: RemoveShortcuts. Action ended 18:46:50: RemoveShortcuts. Return value 1. Action start 18:46:50: RemoveEnvironmentStrings. Action ended 18:46:50: RemoveEnvironmentStrings. Return value 1. Action start 18:46:50: RemoveFiles. Action ended 18:46:50: RemoveFiles. Return value 0. Action start 18:46:50: RemoveFolders. Action ended 18:46:50: RemoveFolders. Return value 1. Action start 18:46:50: CreateFolders. Action ended 18:46:50: CreateFolders. Return value 1. Action start 18:46:50: ca_UninstallTAPInstances. Action ended 18:46:50: ca_UninstallTAPInstances. Return value 1. Action start 18:46:50: InstallFiles. Action ended 18:46:50: InstallFiles. Return value 1. Action start 18:46:50: MsiProcessDrivers. DIFXAPP: ENTER: ProcessDriverPackages() DIFXAPP: INFO: 'Component' is 'cp_USBFilterDriver' DIFXAPP: INFO: Component state 0x3 -> 0x3 DIFXAPP: INFO: 'ComponentId' is {B7D782D2-96DF-4775-A0E1-A76CF7B04B65} DIFXAPP: INFO: 'Flags' is 23 DIFXAPP: INFO: component path is C:\DeveloperPlatform\virtualbox\drivers\USB\filter\ DIFXAPP: INFO: user SID of user performing the install is 'S-1-5-21-4257854280-2759556151-3260634400-1001'. DIFXAPP: INFO: creating HKEY_USERS\S-1-5-21-4257854280-2759556151-3260634400-1001\Software\Microsoft\Windows\CurrentVersion\DIFxApp\Components\{B7D782D2-96DF-4775-A0E1-A76CF7B04B65} (User's SID: 'S-1-5-21-4257854280-2759556151-3260634400-1001') ... Action start 18:46:50: MsiRollbackInstall. Action ended 18:46:50: MsiRollbackInstall. Return value 1. Action start 18:46:50: MsiInstallDrivers. Action ended 18:46:50: MsiInstallDrivers. Return value 1. DIFXAPP: INFO: 'Component' is 'cp_USBDeviceDriver' DIFXAPP: INFO: Component state 0x3 -> 0x3 DIFXAPP: INFO: 'ComponentId' is {010FE46A-E358-43E2-8BDC-38BC8BEC82E0} DIFXAPP: INFO: 'Flags' is 23 DIFXAPP: INFO: component path is C:\DeveloperPlatform\virtualbox\drivers\USB\device\ DIFXAPP: INFO: user SID of user performing the install is 'S-1-5-21-4257854280-2759556151-3260634400-1001'. DIFXAPP: INFO: creating HKEY_USERS\S-1-5-21-4257854280-2759556151-3260634400-1001\Software\Microsoft\Windows\CurrentVersion\DIFxApp\Components\{010FE46A-E358-43E2-8BDC-38BC8BEC82E0} (User's SID: 'S-1-5-21-4257854280-2759556151-3260634400-1001') ... Action start 18:46:50: MsiRollbackInstall. Action ended 18:46:50: MsiRollbackInstall. Return value 1. Action start 18:46:50: MsiInstallDrivers. Action ended 18:46:50: MsiInstallDrivers. Return value 1. DIFXAPP: INFO: 'Component' is 'cp_VBoxDrv' DIFXAPP: INFO: Component state 0x3 -> 0x3 DIFXAPP: INFO: 'ComponentId' is {D3E2F2BB-569F-46A2-836C-BDF30FF1EDF8} DIFXAPP: INFO: 'Flags' is 23 DIFXAPP: INFO: component path is C:\DeveloperPlatform\virtualbox\drivers\vboxdrv\ DIFXAPP: INFO: user SID of user performing the install is 'S-1-5-21-4257854280-2759556151-3260634400-1001'. DIFXAPP: INFO: creating HKEY_USERS\S-1-5-21-4257854280-2759556151-3260634400-1001\Software\Microsoft\Windows\CurrentVersion\DIFxApp\Components\{D3E2F2BB-569F-46A2-836C-BDF30FF1EDF8} (User's SID: 'S-1-5-21-4257854280-2759556151-3260634400-1001') ... Action start 18:46:50: MsiRollbackInstall. Action ended 18:46:50: MsiRollbackInstall. Return value 1. Action start 18:46:50: MsiInstallDrivers. Action ended 18:46:50: MsiInstallDrivers. Return value 1. DIFXAPP: RETURN: ProcessDriverPackages() 0 (0x0) Action ended 18:46:50: MsiProcessDrivers. Return value 1. Action start 18:46:50: CreateShortcuts. Action ended 18:46:50: CreateShortcuts. Return value 1. Action start 18:46:50: WriteRegistryValues. Action ended 18:46:50: WriteRegistryValues. Return value 1. Action start 18:46:50: WriteEnvironmentStrings. Action ended 18:46:50: WriteEnvironmentStrings. Return value 1. Action start 18:46:50: RegisterTypeLibraries. Action ended 18:46:50: RegisterTypeLibraries. Return value 1. Action start 18:46:50: RegisterUser. Action ended 18:46:50: RegisterUser. Return value 0. Action start 18:46:50: RegisterProduct. Action ended 18:46:50: RegisterProduct. Return value 1. Action start 18:46:50: PublishFeatures. Action ended 18:46:50: PublishFeatures. Return value 1. Action start 18:46:50: PublishProduct. Action ended 18:46:51: PublishProduct. Return value 1. Action start 18:46:51: ca_InstallBrandingArgs. Action ended 18:46:51: ca_InstallBrandingArgs. Return value 1. Action start 18:46:51: ca_InstallBranding. Action ended 18:46:51: ca_InstallBranding. Return value 1. Action start 18:46:51: InstallFinalize. VBox HostInterfaces: Enumerating interfaces ... VBox HostInterfaces: No interfaces found. VBox HostInterfaces: Removing interfaces done. DIFXAPP: ENTER: InstallDriverPackages() DIFXAPP: INFO: 'CustomActionData' property 'DIFxApp Version' is '2.1'. DIFXAPP: INFO: 'CustomActionData' property 'UI Level' is '3'. DIFXAPP: INFO: 'CustomActionData' property 'componentId' is '{B7D782D2-96DF-4775-A0E1-A76CF7B04B65}'. DIFXAPP: INFO: 'CustomActionData' property 'componentPath' is 'C:\DeveloperPlatform\virtualbox\drivers\USB\filter\'. DIFXAPP: INFO: 'CustomActionData' property 'flags' is 0x17. DIFXAPP: INFO: 'CustomActionData' property 'installState' is '3'. DIFXAPP: INFO: 'CustomActionData' property 'ProductName' is 'Oracle VM VirtualBox 5.0.8'. DIFXAPP: INFO: 'CustomActionData' property 'ManufacturerName' is 'Oracle Corporation'. DIFXAPP: INFO: user SID of user performing the install is 'S-1-5-21-4257854280-2759556151-3260634400-1001'. DIFXAPP: INFO: opening HKEY_USERS\S-1-5-21-4257854280-2759556151-3260634400-1001\Software\Microsoft\Windows\CurrentVersion\DIFxApp\Components\{B7D782D2-96DF-4775-A0E1-A76CF7B04B65} (User's SID: 'S-1-5-21-4257854280-2759556151-3260634400-1001') ... DIFXAPP: INFO: ENTER: DriverPackageInstallW DIFXAPP: INFO: VBoxUSBMon.inf: checking signature with catalog 'C:\DeveloperPlatform\virtualbox\drivers\USB\filter\VBoxUSBMon.cat' ... DIFXAPP: INFO: Driver package 'VBoxUSBMon.inf' is Authenticode signed. DIFXAPP: INFO: Copied 'VBoxUSBMon.inf' to driver store... DIFXAPP: INFO: Copied 'VBoxUSBMon.cat' to driver store... DIFXAPP: INFO: Commiting queue... DIFXAPP: INFO: Copied file: 'C:\DeveloperPlatform\virtualbox\drivers\USB\filter\VBoxUSBMon.sys' -> 'C:\Windows\system32\DRVSTORE\VBoxUSBMon_E58AA38F21DEABCF7012351B00050623AA397B44\VBoxUSBMon.sys'. DIFXAPP: INFO: Installing INF file "C:\Windows\system32\DRVSTORE\VBoxUSBMon_E58AA38F21DEABCF7012351B00050623AA397B44\VBoxUSBMon.inf" of Type 1. DIFXAPP: INFO: Installing legacy driver 'C:\Windows\system32\DRVSTORE\VBoxUSBMon_E58AA38F21DEABCF7012351B00050623AA397B44\VBoxUSBMon.inf' DIFXAPP: INFO: 'C:\Windows\system32\DRVSTORE\VBoxUSBMon_E58AA38F21DEABCF7012351B00050623AA397B44\VBoxUSBMon.inf' requires a reboot. DIFXAPP: ERROR: Unable to start service 'VBoxUSBMon' because of error 0x422 DIFXAPP: SUCCESS:Installation completed with code 0x0. DIFXAPP: INFO: RETURN: DriverPackageInstallW (0x0) DIFXAPP: INFO: ENTER: DriverPackageGetPathW DIFXAPP: SUCCESS:Found driver store entry. DIFXAPP: INFO: RETURN: DriverPackageGetPathW (0x7A) DIFXAPP: INFO: ENTER: DriverPackageGetPathW DIFXAPP: SUCCESS:Found driver store entry. DIFXAPP: INFO: RETURN: DriverPackageGetPathW (0x0) DIFXAPP: INFO: driver store entry for 'C:\DeveloperPlatform\virtualbox\drivers\USB\filter\VBoxUSBMon.inf' is 'C:\Windows\system32\DRVSTORE\VBoxUSBMon_E58AA38F21DEABCF7012351B00050623AA397B44\VBoxUSBMon.inf'. DIFXAPP: INFO: The component Id '{B7D782D2-96DF-4775-A0E1-A76CF7B04B65}' is now set to point to driver store: 'C:\Windows\system32\DRVSTORE\VBoxUSBMon_E58AA38F21DEABCF7012351B00050623AA397B44\VBoxUSBMon.inf' DIFXAPP: INFO: A reboot is needed to install the component '{B7D782D2-96DF-4775-A0E1-A76CF7B04B65}'. DIFXAPP: RETURN: InstallDriverPackages() 0 (0x0) DIFXAPP: ENTER: InstallDriverPackages() DIFXAPP: INFO: 'CustomActionData' property 'DIFxApp Version' is '2.1'. DIFXAPP: INFO: 'CustomActionData' property 'UI Level' is '3'. DIFXAPP: INFO: 'CustomActionData' property 'componentId' is '{010FE46A-E358-43E2-8BDC-38BC8BEC82E0}'. DIFXAPP: INFO: 'CustomActionData' property 'componentPath' is 'C:\DeveloperPlatform\virtualbox\drivers\USB\device\'. DIFXAPP: INFO: 'CustomActionData' property 'flags' is 0x17. DIFXAPP: INFO: 'CustomActionData' property 'installState' is '3'. DIFXAPP: INFO: 'CustomActionData' property 'ProductName' is 'Oracle VM VirtualBox 5.0.8'. DIFXAPP: INFO: 'CustomActionData' property 'ManufacturerName' is 'Oracle Corporation'. DIFXAPP: INFO: user SID of user performing the install is 'S-1-5-21-4257854280-2759556151-3260634400-1001'. DIFXAPP: INFO: opening HKEY_USERS\S-1-5-21-4257854280-2759556151-3260634400-1001\Software\Microsoft\Windows\CurrentVersion\DIFxApp\Components\{010FE46A-E358-43E2-8BDC-38BC8BEC82E0} (User's SID: 'S-1-5-21-4257854280-2759556151-3260634400-1001') ... DIFXAPP: INFO: ENTER: DriverPackageInstallW DIFXAPP: INFO: Installing INF file 'C:\DeveloperPlatform\virtualbox\drivers\USB\device\VBoxUSB.inf' (Plug and Play). DIFXAPP: INFO: Attempting to repair driver with INF 'C:\Windows\System32\DriverStore\FileRepository\vboxusb.inf_amd64_5102030e9b878df9\VBoxUSB.inf'. DIFXAPP: INFO: 'C:\Windows\System32\DriverStore\FileRepository\vboxusb.inf_amd64_5102030e9b878df9\VBoxUSB.inf' matches OEM Inf 'oem11.inf'. DIFXAPP: SUCCESS:Install completed DIFXAPP: INFO: RETURN: DriverPackageInstallW (0x0) DIFXAPP: INFO: ENTER: DriverPackageGetPathW DIFXAPP: INFO: RETURN: DriverPackageGetPathW (0x7A) DIFXAPP: INFO: ENTER: DriverPackageGetPathW DIFXAPP: INFO: RETURN: DriverPackageGetPathW (0x0) DIFXAPP: INFO: driver store entry for 'C:\DeveloperPlatform\virtualbox\drivers\USB\device\VBoxUSB.inf' is 'C:\Windows\System32\DriverStore\FileRepository\vboxusb.inf_amd64_5102030e9b878df9\vboxusb.inf'. DIFXAPP: INFO: The component Id '{010FE46A-E358-43E2-8BDC-38BC8BEC82E0}' is now set to point to driver store: 'C:\Windows\System32\DriverStore\FileRepository\vboxusb.inf_amd64_5102030e9b878df9\vboxusb.inf' DIFXAPP: INFO: A reboot is not needed to install the component '{010FE46A-E358-43E2-8BDC-38BC8BEC82E0}'. DIFXAPP: RETURN: InstallDriverPackages() 0 (0x0) DIFXAPP: ENTER: InstallDriverPackages() DIFXAPP: INFO: 'CustomActionData' property 'DIFxApp Version' is '2.1'. DIFXAPP: INFO: 'CustomActionData' property 'UI Level' is '3'. DIFXAPP: INFO: 'CustomActionData' property 'componentId' is '{D3E2F2BB-569F-46A2-836C-BDF30FF1EDF8}'. DIFXAPP: INFO: 'CustomActionData' property 'componentPath' is 'C:\DeveloperPlatform\virtualbox\drivers\vboxdrv\'. DIFXAPP: INFO: 'CustomActionData' property 'flags' is 0x17. DIFXAPP: INFO: 'CustomActionData' property 'installState' is '3'. DIFXAPP: INFO: 'CustomActionData' property 'ProductName' is 'Oracle VM VirtualBox 5.0.8'. DIFXAPP: INFO: 'CustomActionData' property 'ManufacturerName' is 'Oracle Corporation'. DIFXAPP: INFO: user SID of user performing the install is 'S-1-5-21-4257854280-2759556151-3260634400-1001'. DIFXAPP: INFO: opening HKEY_USERS\S-1-5-21-4257854280-2759556151-3260634400-1001\Software\Microsoft\Windows\CurrentVersion\DIFxApp\Components\{D3E2F2BB-569F-46A2-836C-BDF30FF1EDF8} (User's SID: 'S-1-5-21-4257854280-2759556151-3260634400-1001') ... DIFXAPP: INFO: ENTER: DriverPackageInstallW DIFXAPP: INFO: VBoxDrv.inf: checking signature with catalog 'C:\DeveloperPlatform\virtualbox\drivers\vboxdrv\VBoxDrv.cat' ... DIFXAPP: INFO: Driver package 'VBoxDrv.inf' is Authenticode signed. DIFXAPP: INFO: Copied 'VBoxDrv.inf' to driver store... DIFXAPP: INFO: Copied 'VBoxDrv.cat' to driver store... DIFXAPP: INFO: Commiting queue... DIFXAPP: INFO: Copied file: 'C:\DeveloperPlatform\virtualbox\drivers\vboxdrv\VBoxDrv.sys' -> 'C:\Windows\system32\DRVSTORE\VBoxDrv_768DB834341E67355F249CB0FF27F93B6D225DD0\VBoxDrv.sys'. DIFXAPP: INFO: Installing INF file "C:\Windows\system32\DRVSTORE\VBoxDrv_768DB834341E67355F249CB0FF27F93B6D225DD0\VBoxDrv.inf" of Type 1. DIFXAPP: INFO: Installing legacy driver 'C:\Windows\system32\DRVSTORE\VBoxDrv_768DB834341E67355F249CB0FF27F93B6D225DD0\VBoxDrv.inf' DIFXAPP: INFO: 'C:\Windows\system32\DRVSTORE\VBoxDrv_768DB834341E67355F249CB0FF27F93B6D225DD0\VBoxDrv.inf' requires a reboot. DIFXAPP: ERROR: Unable to start service 'VBoxDrv' because of error 0x422 DIFXAPP: SUCCESS:Installation completed with code 0x0. DIFXAPP: INFO: RETURN: DriverPackageInstallW (0x0) DIFXAPP: INFO: ENTER: DriverPackageGetPathW DIFXAPP: SUCCESS:Found driver store entry. DIFXAPP: INFO: RETURN: DriverPackageGetPathW (0x7A) DIFXAPP: INFO: ENTER: DriverPackageGetPathW DIFXAPP: SUCCESS:Found driver store entry. DIFXAPP: INFO: RETURN: DriverPackageGetPathW (0x0) DIFXAPP: INFO: driver store entry for 'C:\DeveloperPlatform\virtualbox\drivers\vboxdrv\VBoxDrv.inf' is 'C:\Windows\system32\DRVSTORE\VBoxDrv_768DB834341E67355F249CB0FF27F93B6D225DD0\VBoxDrv.inf'. DIFXAPP: INFO: The component Id '{D3E2F2BB-569F-46A2-836C-BDF30FF1EDF8}' is now set to point to driver store: 'C:\Windows\system32\DRVSTORE\VBoxDrv_768DB834341E67355F249CB0FF27F93B6D225DD0\VBoxDrv.inf' DIFXAPP: INFO: A reboot is needed to install the component '{D3E2F2BB-569F-46A2-836C-BDF30FF1EDF8}'. DIFXAPP: RETURN: InstallDriverPackages() 0 (0x0) InstallBranding: Handling branding file ... CopyDir: DestDir=C:\DeveloperPlatform\virtualbox\, SourceDir=.custom CopyDir: Copy operation returned status 0x2 InstallBranding: Handling done. (rc=31 (ignored)) Action ended 18:46:59: InstallFinalize. Return value 1. Action start 18:46:59: MsiCleanupOnSuccess. DIFXAPP: ENTER: CleanupOnSuccess() DIFXAPP: INFO: 'Component' is 'cp_USBFilterDriver' DIFXAPP: INFO: 'ComponentId' is {B7D782D2-96DF-4775-A0E1-A76CF7B04B65} DIFXAPP: INFO: user SID of user performing the install is 'S-1-5-21-4257854280-2759556151-3260634400-1001'. DIFXAPP: INFO: opening HKEY_USERS\S-1-5-21-4257854280-2759556151-3260634400-1001\Software\Microsoft\Windows\CurrentVersion\DIFxApp\Components\{B7D782D2-96DF-4775-A0E1-A76CF7B04B65} (User's SID: 'S-1-5-21-4257854280-2759556151-3260634400-1001') ... DIFXAPP: INFO: 'Component' is 'cp_USBDeviceDriver' DIFXAPP: INFO: 'ComponentId' is {010FE46A-E358-43E2-8BDC-38BC8BEC82E0} DIFXAPP: INFO: user SID of user performing the install is 'S-1-5-21-4257854280-2759556151-3260634400-1001'. DIFXAPP: INFO: opening HKEY_USERS\S-1-5-21-4257854280-2759556151-3260634400-1001\Software\Microsoft\Windows\CurrentVersion\DIFxApp\Components\{010FE46A-E358-43E2-8BDC-38BC8BEC82E0} (User's SID: 'S-1-5-21-4257854280-2759556151-3260634400-1001') ... DIFXAPP: INFO: 'Component' is 'cp_VBoxDrv' DIFXAPP: INFO: 'ComponentId' is {D3E2F2BB-569F-46A2-836C-BDF30FF1EDF8} DIFXAPP: INFO: user SID of user performing the install is 'S-1-5-21-4257854280-2759556151-3260634400-1001'. DIFXAPP: INFO: opening HKEY_USERS\S-1-5-21-4257854280-2759556151-3260634400-1001\Software\Microsoft\Windows\CurrentVersion\DIFxApp\Components\{D3E2F2BB-569F-46A2-836C-BDF30FF1EDF8} (User's SID: 'S-1-5-21-4257854280-2759556151-3260634400-1001') ... Action start 18:46:59: ScheduleReboot. Action ended 18:46:59: ScheduleReboot. Return value 1. DIFXAPP: RETURN: CleanupOnSuccess() 0 (0x0) Action ended 18:46:59: MsiCleanupOnSuccess. Return value 1. Action ended 18:46:59: INSTALL. Return value 1. MSI (s) (50:48) [18:46:59:122]: Product: Oracle VM VirtualBox 5.0.8 -- Configuration completed successfully. MSI (s) (50:48) [18:46:59:125]: Windows Installer reconfigured the product. Product Name: Oracle VM VirtualBox 5.0.8. Product Version: 5.0.8. Product Language: 1033. Manufacturer: Oracle Corporation. Reconfiguration success or error status: 0. MSI (s) (50:48) [18:46:59:126]: Windows Installer requires a system restart. Product Name: Oracle VM VirtualBox 5.0.8. Product Version: 5.0.8. Product Language: 1033. Manufacturer: Oracle Corporation. Type of System Restart: 2. Reason for Restart: 2. MSI (s) (50:48) [18:46:59:126]: Product: Oracle VM VirtualBox 5.0.8. Restart required. The product installation or update required a restart for all changes to take effect. The restart was deferred to a later time. === Logging stopped: 04/03/2016 18:46:59 === {code}