Windows Server 2012: Cannot install SMTP-Server, system always requires restart
I'm not able to install the "SMTP-Server" feature on a Windows 2012 server. The installation process always fails with a "restart required" message. After the restart and (re)starting the SMTP-Server feature, the system requires a new restart.
I tried to install the feature using the installation wizard and PowerShell.
When trying to install the feature using PowerShell the error looks as follows:
PS > Install-WindowsFeature SMTP-Server
Install-WindowsFeature : Fehler bei der Anforderung zum Hinzufügen oder Entfernen von Features auf dem angegebenen
Server.
Der Vorgang kann nicht abgeschlossen werden, da der angegebene Server neu gestartet werden muss.
In Zeile:1 Zeichen:1
+ Install-WindowsFeature SMTP-Server
+ ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
+ CategoryInfo : DeviceError: (@{Vhd=; Credent...Name=localhost}:PSObject) [Install-WindowsFeature], Exce
ption
+ FullyQualifiedErrorId : DISMAPI_Error__Failed_Reboot_Required,Microsoft.Windows.ServerManager.Commands.AddWindow
sFeatureCommand
Success Restart Needed Exit Code Feature Result
------- -------------- --------- --------------
False No Failed {}
Using the Get-PendingReboot script from here https://gallery.technet.microsoft.com/scriptcenter/Get-PendingReboot-Query-bdb79542 shows the following pending restart status after trying to install the feature:
CBServicing : True
WindowsUpdate : False
CCMClientSDK : False
PendFileRename : False
PendFileRenVal :
RebootPending : True
After a reboot and before starting the SMTP-Server installation, there is no pending restart, i.e. the script shows (RebootPending : False).
After an unsuccessful installation process I even cannot list the Windows features:
PS > Get-WindowsFeature
Get-WindowsFeature : Fehler bei der Anforderung zum Auflisten der auf dem angegebenen Server verfügbaren Features.
Der Vorgang kann nicht abgeschlossen werden, da der angegebene Server neu gestartet werden muss.
In Zeile:1 Zeichen:1
+ Get-WindowsFeature
+ ~~~~~~~~~~~~~~~~~~
+ CategoryInfo : DeviceError: (@{Vhd=; Credent...Name=localhost}:PSObject) [Get-WindowsFeature], Deployme
ntProviderException
+ FullyQualifiedErrorId : GetEnumerationState_Failed_Reboot_Required,Microsoft.Windows.ServerManager.Commands.GetW
indowsFeatureCommand
dism.exe /online /get-features reports the following:
Tool zur Imageverwaltung für die Bereitstellung
Version: 6.2.9200.16384
Abbildversion: 6.2.9200.16384
Featureauflistung für das Paket : Microsoft-Windows-ServerCore-Package~31bf3856a
d364e35~amd64~~6.2.9200.16384
Featurename : NetFx3ServerFeatures
Status : Aktiviert
Fehler: 3017
Der angeforderte Vorgang war nicht erfolgreich. Es ist ein Systemneustart erford
erlich, um die durchgeführten Änderungen rückgängig zu machen.
Die DISM-Protokolldatei befindet sich unter "C:\Windows\Logs\DISM\dism.log".
The following lines are logged in C:\Windows\Logs\DISM\dism.log:
2015-06-28 21:35:58, Info DISM DISM Provider Store: PID=4852 TID=5124 Provider has previously been initialized. Returning the existing instance. - CDISMProviderStore::Internal_GetProvider
2015-06-28 21:35:58, Info DISM DISM Package Manager: PID=4852 TID=5124 Processing the top level command token(get-features). - CPackageManagerCLIHandler::Private_ValidateCmdLine
2015-06-28 21:35:58, Info DISM DISM Package Manager: PID=4852 TID=5124 Attempting to route to appropriate command handler. - CPackageManagerCLIHandler::ExecuteCmdLine
2015-06-28 21:35:58, Info DISM DISM Package Manager: PID=4852 TID=5124 Routing the command... - CPackageManagerCLIHandler::ExecuteCmdLine
2015-06-28 21:35:59, Info DISM DISM Package Manager: PID=4852 TID=5124 Feature NetFx3ServerFeatures with CBS state 7(CbsInstallStateInstalled) being mapped to dism state 7(DISM_INSTALL_STATE_INSTALLED) - CDISMPackageFeature::LogInstallStateMapping
2015-06-28 21:35:59, Error DISM DISM Package Manager: PID=4852 TID=5124 Failed to get the State of the Feature - CDISMPackageFeature::Internal_GetCbsState(hr:0x80070bc9)
2015-06-28 21:35:59, Error DISM DISM Package Manager: PID=4852 TID=5124 Failed to get the CBS State of the Feature - CDISMPackageFeature::get_State(hr:0x80070bc9)
2015-06-28 21:35:59, Error DISM DISM Package Manager: PID=4852 TID=5124 Failed to get the feature state. - CCBSFormatter::PrintCbsFeatures_ListFormat(hr:0x80070bc9)
2015-06-28 21:35:59, Error DISM DISM Package Manager: PID=4852 TID=5124 Failed while processing command get-features. - CPackageManagerCLIHandler::ExecuteCmdLine(hr:0x80070bc9)
2015-06-28 21:35:59, Info DISM DISM Package Manager: PID=4852 TID=5124 Further logs for online package and feature related operations can be found at %WINDIR%\logs\CBS\cbs.log - CPackageManagerCLIHandler::ExecuteCmdLine
2015-06-28 21:35:59, Error DISM DISM.EXE: DISM Package Manager processed the command line but failed. HRESULT=80070BC9
The following lines are logged in C:\Windows\logs\CBS\cbs.log:
2015-06-28 21:35:58, Info CBS TI: --- Initializing Trusted Installer ---
2015-06-28 21:35:58, Info CBS TI: Last boot time: 2015-06-28 15:46:57.314
2015-06-28 21:35:58, Info CBS TrustedInstaller terminated with pending operations.
2015-06-28 21:35:58, Info CBS Starting TrustedInstaller initialization.
2015-06-28 21:35:58, Info CBS Ending TrustedInstaller initialization.
2015-06-28 21:35:58, Info CBS Starting the TrustedInstaller main loop.
2015-06-28 21:35:58, Info CBS TrustedInstaller service starts successfully.
2015-06-28 21:35:58, Info CBS Registering for Winlogon CreateSession notifications
2015-06-28 21:35:58, Info CBS Registering for pre-shutdown notification to complete the pended operation
2015-06-28 21:35:58, Info CBS Startup processing thread terminated normally
2015-06-28 21:35:58, Info CBS Starting TiWorker initialization.
2015-06-28 21:35:58, Info CBS Ending TiWorker initialization.
2015-06-28 21:35:58, Info CBS Starting the TiWorker main loop.
2015-06-28 21:35:58, Info CBS TiWorker starts successfully.
2015-06-28 21:35:58, Info CBS Universal Time is: 2015-06-28 19:35:58.771
2015-06-28 21:35:58, Info CBS Loaded Servicing Stack v6.2.9200.17280 with Core: C:\Windows\winsxs\amd64_microsoft-windows-servicingstack_31bf3856ad364e35_6.2.9200.17280_none_6224eed751126779\cbscore.dll
2015-06-28 21:35:58, Info CSI 00000001@2015/6/28:19:35:58.771 WcpInitialize (wcp.dll version 0.0.0.6) called (stack @0x7f950ce63c1 @0x7f9570198ba @0x7f956fd4dd4 @0x7f684d3d1df @0x7f684d3d95c @0x7f96b8e1d35)
2015-06-28 21:35:58, Info CBS Could not load SrClient DLL from path: SrClient.dll. Continuing without system restore points.
2015-06-28 21:35:58, Info CSI 00000002@2015/6/28:19:35:58.771 WcpInitialize (wcp.dll version 0.0.0.6) called (stack @0x7f950ce63c1 @0x7f957076248 @0x7f957037719 @0x7f956fd4f47 @0x7f684d3d1df @0x7f684d3d95c)
2015-06-28 21:35:58, Info CBS SQM: Initializing online with Windows opt-in: False
2015-06-28 21:35:58, Info CBS SQM: Cleaning up report files older than 10 days.
2015-06-28 21:35:58, Info CBS SQM: Requesting upload of all unsent reports.
2015-06-28 21:35:58, Info CBS SQM: Failed to start upload with file pattern: C:\Windows\servicing\sqm\*_std.sqm, flags: 0x2 [HRESULT = 0x80004005 - E_FAIL]
2015-06-28 21:35:58, Info CBS SQM: Failed to start standard sample upload. [HRESULT = 0x80004005 - E_FAIL]
2015-06-28 21:35:58, Info CBS SQM: Queued 0 file(s) for upload with pattern: C:\Windows\servicing\sqm\*_all.sqm, flags: 0x6
2015-06-28 21:35:58, Info CBS SQM: Warning: Failed to upload all unsent reports. [HRESULT = 0x80004005 - E_FAIL]
2015-06-28 21:35:58, Info CBS Session: 30454233_2827951091 initialized by client DISM Package Manager Provider.
2015-06-28 21:35:58, Info CBS Enumerating Foundation package: Microsoft-Windows-ServerCore-Package~31bf3856ad364e35~amd64~~6.2.9200.16384, this could be slow
2015-06-28 21:35:59, Info CSI 00000003 CSI Store 1037981022800 (0x000000f1ac7d7a50) initialized
2015-06-28 21:35:59, Info CSI 00000004 Transaction merge required, do-not-merge flag passed.
2015-06-28 21:35:59, Info CSI 00000005@2015/6/28:19:35:59.604 CSI Transaction @0xf1ac867fb0 initialized for deployment engine {d16d444c-56d8-11d5-882d-0080c847b195} with flags 00000002 and client id [26]"TI5.30454233_2827951091:1/"
2015-06-28 21:35:59, Error CSI 00000006 (F) Current pending rollback transaction content precludes other operations; reboot to complete rollback.[gle=0x80004005]
2015-06-28 21:35:59, Info CBS Failed to get transaction analysis because of pending operations. Disposition from Analyze: 0x1 [HRESULT = 0x80070bc9 - ERROR_FAIL_REBOOT_REQUIRED]
2015-06-28 21:35:59, Info CBS Failed to get inventory. [HRESULT = 0x80070bc9 - ERROR_FAIL_REBOOT_REQUIRED]
2015-06-28 21:35:59, Info CSI 00000007@2015/6/28:19:35:59.916 CSI Transaction @0xf1ac867fb0 destroyed
2015-06-28 21:35:59, Info CBS Failed to get CSI Inventory [HRESULT = 0x80070bc9 - ERROR_FAIL_REBOOT_REQUIRED]
2015-06-28 21:35:59, Info CBS Failed to get component state. [HRESULT = 0x80070bc9 - ERROR_FAIL_REBOOT_REQUIRED]
2015-06-28 21:35:59, Info CBS Failed to get current state of the deployment [HRESULT = 0x80070bc9 - ERROR_FAIL_REBOOT_REQUIRED]
2015-06-28 21:35:59, Info CBS Failed to get Transaction State for package: Microsoft-Windows-NetFx3-Server-OC-Package~31bf3856ad364e35~amd64~~6.2.9200.16384, update: NetFx3 [HRESULT = 0x80070bc9 - ERROR_FAIL_REBOOT_REQUIRED]
2015-06-28 21:35:59, Error CBS Failed to get store state [HRESULT = 0x80070bc9 - ERROR_FAIL_REBOOT_REQUIRED]
2015-06-28 21:37:59, Info CBS Trusted Installer successfully registered to be restarted for pre-shutdown
2015-06-28 21:37:59, Info CBS Trusted Installer is shutting down because: SHUTDOWN_REASON_AUTOSTOP
2015-06-28 21:37:59, Info CBS Stopping Winlogon CreateSession notifications
2015-06-28 21:37:59, Info CBS Unloading SysNotify DLL
2015-06-28 21:37:59, Info CBS TiWorker signaled for shutdown, going to exit.
2015-06-28 21:37:59, Info CBS Ending the TiWorker main loop.
2015-06-28 21:37:59, Info CBS Starting TiWorker finalization.
2015-06-28 21:37:59, Info CBS Ending the TrustedInstaller main loop.
2015-06-28 21:37:59, Info CBS Starting TrustedInstaller finalization.
2015-06-28 21:37:59, Info CBS Ending TrustedInstaller finalization.
2015-06-28 21:38:00, Info CBS Ending TiWorker finalization.
I've no idea what causes the CBServicing to require a restart when installing the SMTP-Server feature nor how to fix the problem so that I'm able to install the SMTP-Server feature. Any help would be greatly appreciated :).
I found the problem: The cause was not the SMTP-Server feature itself but the IIS-ODBCLogging feature that was required by the SMTP-Server. The installation of this IIS related feature failed due to a permission problem: It seemed that the installer had problems to update the IIS configuration which is stored on a network share (for sharing it between different servers in the web-farm).
In C:\Windows\iis.log I found:
[06/23/2015 13:28:58] [ ***** IIS 8.0 Component Based Setup ***** ]
[06/23/2015 13:28:58] "C:\Windows\System32\inetsrv\iissetup.exe" /install Metabase
[06/23/2015 13:28:58] < WARNING! > Shared configuration detected. Install/Uninstall of optional component may fail.
[06/23/2015 13:28:58] Successfully updated IIS_IUSRS rights.
[06/23/2015 13:28:58] Successfully populated IIS_IUSRS.
[06/23/2015 13:28:58] Successfully set IISADMIN install state.
[06/23/2015 13:28:58] Successfully removed old metabase files.
[06/23/2015 13:28:59] Successfully started IISADMIN.
[06/23/2015 13:28:59] < !!FAIL!! > MB::SetData result=0x80070005
[06/23/2015 13:28:59] < !!FAIL!! > METABASE_UTIL::InstallIisAdminMetabaseEntries result=0x80070005
[06/23/2015 13:29:05] Successfully stopped IISADMIN.
[06/23/2015 13:29:05] < !!FAIL!! > MB::SetData result=0x80070005
[06/23/2015 13:29:05] < WARNING! > METABASE_UTIL::InstallLegacySnapInMetabaseEntries result=0x80070005
[06/23/2015 13:29:05] Successfully started IISADMIN.
In the end disabling the IIS shared config, installing the feature and enabling the shared config again did the trick.