G
GoldCityComputers
Hi,
We are migrating a customer off SBS 2011 over to O365 and Server 2016 Essentials. The essentials server has not yet been connected, but we are trying to install Azure Sync (DirSync) to sync the local domain with O365 as we have done many times before for other clients with the same configuration.
This particular SBS server can not install the Sync Service and here is the error log:
[10:23:01.295] [ 1] [INFO ]
[10:23:01.311] [ 1] [INFO ] ================================================================================
[10:23:01.311] [ 1] [INFO ] Application starting
[10:23:01.311] [ 1] [INFO ] ================================================================================
[10:23:01.311] [ 1] [INFO ] Start Time (Local): Thu, 28 Feb 2019 10:23:01 GMT
[10:23:01.311] [ 1] [INFO ] Start Time (UTC): Thu, 28 Feb 2019 00:23:01 GMT
[10:23:01.311] [ 1] [INFO ] Application Version: 1.2.70.0
[10:23:01.311] [ 1] [INFO ] Application Build Date: 2018-12-17 07:19:47Z
[10:23:03.713] [ 1] [INFO ] Telemetry session identifier: {76b73b66-08cf-4363-be53-579dd563799e}
[10:23:03.713] [ 1] [INFO ] Telemetry device identifier: MrDPgTedYrF8EXvmvab4YZse5swLIISGay/dq5T8DTU=
[10:23:03.713] [ 1] [INFO ] Application Build Identifier: AD-IAM-HybridSync master (590693a40)
[10:23:03.854] [ 1] [INFO ] machine.config path: C:\Windows\Microsoft.NET\Framework64\v4.0.30319\Config\machine.config.
[10:23:03.854] [ 1] [INFO ] Default Proxy [ProxyAddress]: <Unspecified>
[10:23:03.854] [ 1] [INFO ] Default Proxy [UseSystemDefault]: Unspecified
[10:23:03.854] [ 1] [INFO ] Default Proxy [BypassOnLocal]: Unspecified
[10:23:03.854] [ 1] [INFO ] Default Proxy [Enabled]: True
[10:23:03.854] [ 1] [INFO ] Default Proxy [AutoDetect]: Unspecified
[10:23:03.901] [ 1] [VERB ] Scheduler wizard mutex wait timeout: 00:00:05
[10:23:03.901] [ 1] [INFO ] AADConnect changes ALLOWED: Successfully acquired the configuration change mutex.
[10:23:03.994] [ 1] [INFO ] RootPageViewModel.GetInitialPages: Beginning detection for creating initial pages.
[10:23:04.041] [ 1] [INFO ] Loading the persisted settings .
[10:23:04.088] [ 1] [INFO ] Checking if machine version is 6.1.7601 or higher
[10:23:04.150] [ 1] [INFO ] The current operating system version is 6.1.7601, the requirement is 6.1.7601.
[10:23:04.150] [ 1] [INFO ] Password Hash Sync supported: 'True'
[10:23:04.213] [ 1] [INFO ] DetectInstalledComponents stage: The installed OS SKU is 9
[10:23:04.228] [ 1] [INFO ] DetectInstalledComponents stage: Checking install context.
[10:23:04.244] [ 1] [INFO ] Performing direct lookup of upgrade codes for: Microsoft Visual C++ 2013 Redistributable Package
[10:23:04.244] [ 1] [VERB ] Getting list of installed packages by upgrade code
[10:23:04.259] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {20400cf0-de7c-327e-9ae4-f0f38d9085f8}: verified product code {a749d8e6-b613-3be3-8f5f-045c84eba29b}.
[10:23:04.259] [ 1] [VERB ] Package=Microsoft Visual C++ 2013 x64 Minimum Runtime - 12.0.21005, Version=12.0.21005, ProductCode=a749d8e6-b613-3be3-8f5f-045c84eba29b, UpgradeCode=20400cf0-de7c-327e-9ae4-f0f38d9085f8
[10:23:04.259] [ 1] [INFO ] Determining installation action for Microsoft Visual C++ 2013 Redistributable Package (20400cf0-de7c-327e-9ae4-f0f38d9085f8)
[10:23:04.259] [ 1] [INFO ] Product Microsoft Visual C++ 2013 Redistributable Package (version 12.0.21005) is installed.
[10:23:04.259] [ 1] [INFO ] Performing direct lookup of upgrade codes for: Microsoft Directory Sync Tool
[10:23:04.259] [ 1] [VERB ] Getting list of installed packages by upgrade code
[10:23:04.259] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {bef7e7d9-2ac2-44b9-abfc-3335222b92a7}: no registered products found.
[10:23:04.259] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {dc9e604e-37b0-4efc-b429-21721cf49d0d}: no registered products found.
[10:23:04.259] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {545334d7-13cd-4bab-8da1-2775fa8cf7c2}: no registered products found.
[10:23:04.275] [ 1] [INFO ] Determining installation action for Microsoft Directory Sync Tool UpgradeCodes {bef7e7d9-2ac2-44b9-abfc-3335222b92a7}, {dc9e604e-37b0-4efc-b429-21721cf49d0d}
[10:23:04.275] [ 1] [INFO ] DirectorySyncComponent: Product Microsoft Directory Sync Tool is not installed.
[10:23:04.275] [ 1] [INFO ] Performing direct lookup of upgrade codes for: Azure AD Sync Engine
[10:23:04.275] [ 1] [VERB ] Getting list of installed packages by upgrade code
[10:23:04.275] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {545334d7-13cd-4bab-8da1-2775fa8cf7c2}: no registered products found.
[10:23:04.275] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {dc9e604e-37b0-4efc-b429-21721cf49d0d}: no registered products found.
[10:23:04.275] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {bef7e7d9-2ac2-44b9-abfc-3335222b92a7}: no registered products found.
[10:23:04.291] [ 1] [INFO ] Determining installation action for Azure AD Sync Engine (545334d7-13cd-4bab-8da1-2775fa8cf7c2)
[10:23:04.759] [ 1] [INFO ] Product Azure AD Sync Engine is not installed.
[10:23:04.759] [ 1] [INFO ] Performing direct lookup of upgrade codes for: Azure AD Connect Synchronization Agent
[10:23:04.759] [ 1] [VERB ] Getting list of installed packages by upgrade code
[10:23:04.759] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {3cd653e3-5195-4ff2-9d6c-db3dacc82c25}: no registered products found.
[10:23:04.759] [ 1] [INFO ] Determining installation action for Azure AD Connect Synchronization Agent (3cd653e3-5195-4ff2-9d6c-db3dacc82c25)
[10:23:04.759] [ 1] [INFO ] Product Azure AD Connect Synchronization Agent is not installed.
[10:23:04.759] [ 1] [INFO ] Performing direct lookup of upgrade codes for: Azure AD Connect Health agent for sync
[10:23:04.759] [ 1] [VERB ] Getting list of installed packages by upgrade code
[10:23:04.759] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {114fb294-8aa6-43db-9e5c-4ede5e32886f}: no registered products found.
[10:23:04.759] [ 1] [INFO ] Determining installation action for Azure AD Connect Health agent for sync (114fb294-8aa6-43db-9e5c-4ede5e32886f)
[10:23:04.759] [ 1] [INFO ] Product Azure AD Connect Health agent for sync is not installed.
[10:23:04.759] [ 1] [INFO ] Performing direct lookup of upgrade codes for: Microsoft Azure AD Connect Authentication Agent
[10:23:04.759] [ 1] [VERB ] Getting list of installed packages by upgrade code
[10:23:04.759] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {0c06f9df-c56b-42c4-a41b-f5f64d01a35c}: no registered products found.
[10:23:04.759] [ 1] [INFO ] Determining installation action for Microsoft Azure AD Connect Authentication Agent (0c06f9df-c56b-42c4-a41b-f5f64d01a35c)
[10:23:04.759] [ 1] [INFO ] Product Microsoft Azure AD Connect Authentication Agent is not installed.
[10:23:04.759] [ 1] [INFO ] Performing direct lookup of upgrade codes for: Microsoft SQL Server 2012 Command Line Utilities
[10:23:04.759] [ 1] [VERB ] Getting list of installed packages by upgrade code
[10:23:04.759] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {52446750-c08e-49ef-8c2e-1e0662791e7b}: verified product code {89ca7913-f891-4546-8f55-355338677fe6}.
[10:23:04.759] [ 1] [VERB ] Package=Microsoft SQL Server 2012 Command Line Utilities , Version=11.4.7001.0, ProductCode=89ca7913-f891-4546-8f55-355338677fe6, UpgradeCode=52446750-c08e-49ef-8c2e-1e0662791e7b
[10:23:04.759] [ 1] [INFO ] Determining installation action for Microsoft SQL Server 2012 Command Line Utilities (52446750-c08e-49ef-8c2e-1e0662791e7b)
[10:23:04.759] [ 1] [INFO ] Product Microsoft SQL Server 2012 Command Line Utilities (version 11.4.7001.0) is installed.
[10:23:04.759] [ 1] [INFO ] Performing direct lookup of upgrade codes for: Microsoft SQL Server 2012 Express LocalDB
[10:23:04.759] [ 1] [VERB ] Getting list of installed packages by upgrade code
[10:23:04.759] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {c3593f78-0f11-4d8d-8d82-55460308e261}: verified product code {72b030ed-b1e3-45e5-ba33-a1f5625f2b93}.
[10:23:04.759] [ 1] [VERB ] Package=Microsoft SQL Server 2012 Express LocalDB , Version=11.4.7469.6, ProductCode=72b030ed-b1e3-45e5-ba33-a1f5625f2b93, UpgradeCode=c3593f78-0f11-4d8d-8d82-55460308e261
[10:23:04.759] [ 1] [INFO ] Determining installation action for Microsoft SQL Server 2012 Express LocalDB (c3593f78-0f11-4d8d-8d82-55460308e261)
[10:23:04.759] [ 1] [INFO ] Product Microsoft SQL Server 2012 Express LocalDB (version 11.4.7469.6) is installed.
[10:23:04.759] [ 1] [INFO ] Performing direct lookup of upgrade codes for: Microsoft SQL Server 2012 Native Client
[10:23:04.759] [ 1] [VERB ] Getting list of installed packages by upgrade code
[10:23:04.759] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {1d2d1fa0-e158-4798-98c6-a296f55414f9}: verified product code {b9274744-8bae-4874-8e59-2610919cd419}.
[10:23:04.759] [ 1] [VERB ] Package=Microsoft SQL Server 2012 Native Client , Version=11.4.7001.0, ProductCode=b9274744-8bae-4874-8e59-2610919cd419, UpgradeCode=1d2d1fa0-e158-4798-98c6-a296f55414f9
[10:23:04.759] [ 1] [INFO ] Determining installation action for Microsoft SQL Server 2012 Native Client (1d2d1fa0-e158-4798-98c6-a296f55414f9)
[10:23:04.759] [ 1] [INFO ] Product Microsoft SQL Server 2012 Native Client (version 11.4.7001.0) is installed.
[10:23:04.759] [ 1] [INFO ] Performing direct lookup of upgrade codes for: Microsoft Azure AD Connect Authentication Agent
[10:23:04.759] [ 1] [VERB ] Getting list of installed packages by upgrade code
[10:23:04.759] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {fb3feca7-5190-43e7-8d4b-5eec88ed9455}: no registered products found.
[10:23:04.759] [ 1] [INFO ] Determining installation action for Microsoft Azure AD Connect Authentication Agent (fb3feca7-5190-43e7-8d4b-5eec88ed9455)
[10:23:04.759] [ 1] [INFO ] Product Microsoft Azure AD Connect Authentication Agent is not installed.
[10:23:04.759] [ 1] [INFO ] Determining installation action for Microsoft Azure AD Connection Tool.
[10:23:04.930] [ 1] [WARN ] Failed to read DisplayName registry key: An error occurred while executing the 'Get-ItemProperty' command. Cannot find path 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\Windows\CurrentVersion\Uninstall\MicrosoftAzureADConnectionTool' because it does not exist.
[10:23:04.930] [ 1] [INFO ] Product Microsoft Azure AD Connection Tool is not installed.
[10:23:04.930] [ 1] [INFO ] Performing direct lookup of upgrade codes for: Azure Active Directory Connect
[10:23:04.930] [ 1] [VERB ] Getting list of installed packages by upgrade code
[10:23:04.930] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {d61eb959-f2d1-4170-be64-4dc367f451ea}: verified product code {b9170312-edaf-4e0c-9241-2407915b93ec}.
[10:23:04.930] [ 1] [VERB ] Package=Microsoft Azure AD Connect, Version=1.2.70.0, ProductCode=b9170312-edaf-4e0c-9241-2407915b93ec, UpgradeCode=d61eb959-f2d1-4170-be64-4dc367f451ea
[10:23:04.930] [ 1] [INFO ] Determining installation action for Azure Active Directory Connect (d61eb959-f2d1-4170-be64-4dc367f451ea)
[10:23:04.930] [ 1] [INFO ] Product Azure Active Directory Connect (version 1.2.70.0) is installed.
[10:23:05.227] [ 1] [INFO ] ServiceControllerProvider: GetServiceStartMode(seclogon) is 'Manual'.
[10:23:05.227] [ 1] [INFO ] ServiceControllerProvider: verifying EventLog is in state (Running)
[10:23:05.227] [ 1] [INFO ] ServiceControllerProvider: current service status: Running
[10:23:05.227] [ 1] [INFO ] Checking for DirSync conditions.
[10:23:05.227] [ 1] [INFO ] DirSync not detected. Checking for AADSync/AADConnect upgrade conditions.
[10:23:05.242] [ 1] [INFO ] Initial configuration is incomplete.
[10:23:05.242] [ 1] [INFO ] Resume Wizard from previous Azure service connectivity failure.
[10:23:05.258] [ 1] [INFO ] SyncDataProvider:LoadSettings - loading context with persisted global settings.
[10:23:25.616] [ 1] [ERROR] Configuration policy could not be retrieved (GetGlobalConfigurationParameters). Details: System.Management.Automation.CommandNotFoundException: The term 'Get-ADSyncGlobalSettingsParameter' is not recognized as the name of a cmdlet, function, script file, or operable program. Check the spelling of the name, or if a path was included, verify that the path is correct and try again.
at System.Management.Automation.Runspaces.PipelineBase.Invoke(IEnumerable input)
at System.Management.Automation.PowerShell.Worker.ConstructPipelineAndDoWork(Runspace rs, Boolean performSyncInvoke)
at System.Management.Automation.PowerShell.Worker.CreateRunspaceIfNeededAndDoWork(Runspace rsToUse, Boolean isSync)
at System.Management.Automation.PowerShell.CoreInvokeHelper[TInput,TOutput](PSDataCollection`1 input, PSDataCollection`1 output, PSInvocationSettings settings)
at System.Management.Automation.PowerShell.CoreInvoke[TInput,TOutput](PSDataCollection`1 input, PSDataCollection`1 output, PSInvocationSettings settings)
at System.Management.Automation.PowerShell.Invoke(IEnumerable input, PSInvocationSettings settings)
at Microsoft.Online.Deployment.PowerShell.LocalPowerShell.Invoke()
at Microsoft.Online.Deployment.PowerShell.PowerShellAdapter.TypeDependencies.InvokePowerShell(IPowerShell powerShell)
at Microsoft.Online.Deployment.PowerShell.PowerShellAdapter.InvokePowerShellCommand(String commandName, InitialSessionState initialSessionState, IDictionary`2 commandParameters, Boolean isScript)
at Microsoft.Azure.ActiveDirectory.Synchronization.PowerShellConfigAdapter.GlobalSettingsConfigAdapter.GetGlobalConfigurationParameters()
at Microsoft.Online.Deployment.Types.Providers.SyncDataProvider.LoadSettings(IAadSyncContext aadSyncContext)
[10:23:25.834] [ 1] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Start background task Microsoft.Online.Deployment.OneADWizard.UI.WizardPages.ExpressSettingsPageViewModel.GatherEnvironmentData in Page:"Express Settings"
[10:23:25.834] [ 1] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Started Background Task Id:1
[10:23:25.850] [ 8] [INFO ] Checking if machine version is 6.1.7601 or higher
[10:23:25.850] [ 8] [INFO ] The current operating system version is 6.1.7601, the requirement is 6.1.7601.
[10:23:25.850] [ 8] [INFO ] Password Hash Sync supported: 'True'
[10:23:26.255] [ 1] [INFO ] Express Settings install is supported: domain-joined + OS version allowed.
[10:23:31.224] [ 1] [INFO ] Express Settings: Updating page flow for EXPRESS mode install.
[10:23:31.227] [ 1] [INFO ] Called SetWizardMode(ExpressInstall, True)
[10:23:31.231] [ 1] [WARN ] MicrosoftOnlinePersistedStateProvider.Save: zero state elements provided, saving an empty persisted state file
[10:23:31.233] [ 1] [INFO ] MicrosoftOnlinePersistedStateProvider.UpdateFileProtection: updating file protection from the persisted state file: C:\ProgramData\AADConnect\PersistedState.xml, isAddProtection: False
[10:23:31.242] [ 1] [INFO ] MicrosoftOnlinePersistedStateProvider.UpdateFileProtection: updating file protection from the persisted state file: C:\ProgramData\AADConnect\PersistedState.xml, isAddProtection: True
[10:23:31.274] [ 1] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Start background task Microsoft.Online.Deployment.OneADWizard.UI.WizardPages.ExpressSettingsPageViewModel.StartPrerequisiteInstallation in Page:"Express Settings"
[10:23:31.275] [ 1] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Started Background Task Id:817
[10:23:31.334] [ 8] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Start background task Microsoft.Online.Deployment.OneADWizard.UI.WizardPages.InstallSyncEnginePageViewModel.StartNewInstallation in Page:"Install required components"
[10:23:31.335] [ 8] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Started Background Task Id:847
[10:23:31.382] [ 7] [INFO ] SyncEngineSetupViewModel: Validating sync engine settings.
[10:23:31.389] [ 7] [INFO ] Enter ValidateSqlVersion.
[10:23:31.389] [ 7] [INFO ] Exit ValidateSqlVersion (localdb).
[10:23:31.393] [ 7] [INFO ] Enter ValidateSqlAoaAsyncInstance.
[10:23:31.393] [ 7] [INFO ] Exit ValidateSqlAoaAsyncInstance (localdb).
[10:23:31.395] [ 7] [INFO ] The ADSync database does not exist and will be created. serverAdmin=True.
[10:23:31.395] [ 7] [INFO ] Attaching to the ADSync database: SQLServerName=DoesNotExist SQLInstanceName= ServiceAccountName=, state=, Collation=, /UseExistingDatabase=False.
[10:23:31.395] [ 7] [INFO ] Starting Sync Engine installation
[10:23:31.397] [ 7] [INFO ] Starting Prerequisite installation
[10:23:31.399] [ 7] [VERB ] WorkflowEngine created
[10:23:31.402] [ 7] [INFO ] Performing direct lookup of upgrade codes for: Microsoft Visual C++ 2013 Redistributable Package
[10:23:31.402] [ 7] [VERB ] Getting list of installed packages by upgrade code
[10:23:31.403] [ 7] [INFO ] GetInstalledPackagesByUpgradeCode {20400cf0-de7c-327e-9ae4-f0f38d9085f8}: verified product code {a749d8e6-b613-3be3-8f5f-045c84eba29b}.
[10:23:31.404] [ 7] [VERB ] Package=Microsoft Visual C++ 2013 x64 Minimum Runtime - 12.0.21005, Version=12.0.21005, ProductCode=a749d8e6-b613-3be3-8f5f-045c84eba29b, UpgradeCode=20400cf0-de7c-327e-9ae4-f0f38d9085f8
[10:23:31.404] [ 7] [INFO ] Determining installation action for Microsoft Visual C++ 2013 Redistributable Package (20400cf0-de7c-327e-9ae4-f0f38d9085f8)
[10:23:31.404] [ 7] [INFO ] Product Microsoft Visual C++ 2013 Redistributable Package (version 12.0.21005) is installed.
[10:23:31.411] [ 1] [INFO ] Page transition from "Express Settings" [ExpressSettingsPageViewModel] to "Connect to Azure AD" [AzureTenantPageViewModel]
[10:23:31.447] [ 1] [INFO ] Property Password failed validation with error A valid domain must be selected.
[10:23:48.717] [ 1] [INFO ] Property Password failed validation with error A valid domain must be selected.
[10:23:56.653] [ 6] [INFO ] AzureTenantPage: Beginning Windows Azure tenant credential validation for user - administrator@fdcgympie.onmicrosoft.com
[10:23:56.684] [ 6] [INFO ] AzureConfigurationFromPrincipalName: Successfully resolved UPN (administrator@fdcgympie.onmicrosoft.com) to the Worldwide Azure instance.
Resolution Method [Registry Configuration]: Worldwide.
[10:23:56.700] [ 6] [INFO ] ResolveAzureInstance [Worldwide]: authority=HTTPS://LOGIN.WINDOWS.NET/FDCGYMPIE.ONMICROSOFT.COM,
Resolution Method [Registry Configuration]: Worldwide.
[10:23:56.715] [ 6] [INFO ] Authenticate-ADAL [Acquiring token]: STS endpoint (HTTPS://LOGIN.WINDOWS.NET/FDCGYMPIE.ONMICROSOFT.COM), resource (https://graph.windows.net), userName (administrator@fdcgympie.onmicrosoft.com).
[10:23:56.731] [ 6] [INFO ] ADAL: 2019-02-28T00:23:56.7312399Z: 00000000-0000-0000-0000-000000000000 - LoggerBase.cs: Clearing Cache :- 0 items to be removed
[10:23:56.731] [ 6] [INFO ] ADAL: 2019-02-28T00:23:56.7312399Z: 00000000-0000-0000-0000-000000000000 - LoggerBase.cs: Successfully Cleared Cache
[10:23:56.746] [ 6] [INFO ] ADAL: 2019-02-28T00:23:56.7468400Z: 77538200-e1b3-419a-b161-62771e4923b3 - LoggerBase.cs: ADAL PCL.Desktop with assembly version '3.19.6.14301', file version '3.19.50523.1839' and informational version '1ae77ee16c2204403e53d7e652ddc8f4d315cfb1' is running...
[10:23:56.762] [ 6] [INFO ] ADAL: 2019-02-28T00:23:56.7624400Z: 77538200-e1b3-419a-b161-62771e4923b3 - LoggerBase.cs: === Token Acquisition started:
CacheType: null
Authentication Target: User
, Authority Host: login.windows.net
[10:23:57.308] [ 17] [INFO ] ADAL: 2019-02-28T00:23:57.3084409Z: 77538200-e1b3-419a-b161-62771e4923b3 - LoggerBase.cs: No matching token was found in the cache
[10:23:57.308] [ 17] [INFO ] ADAL: 2019-02-28T00:23:57.3084409Z: 77538200-e1b3-419a-b161-62771e4923b3 - LoggerBase.cs: No matching token was found in the cache
[10:23:57.308] [ 17] [INFO ] ADAL: 2019-02-28T00:23:57.3084409Z: 77538200-e1b3-419a-b161-62771e4923b3 - LoggerBase.cs: No matching token was found in the cache
[10:23:57.308] [ 17] [INFO ] ADAL: 2019-02-28T00:23:57.3084409Z: 77538200-e1b3-419a-b161-62771e4923b3 - LoggerBase.cs: No matching token was found in the cache
[10:23:57.308] [ 17] [INFO ] ADAL: 2019-02-28T00:23:57.3084409Z: 77538200-e1b3-419a-b161-62771e4923b3 - LoggerBase.cs: No matching token was found in the cache
[10:23:57.308] [ 17] [INFO ] ADAL: 2019-02-28T00:23:57.3084409Z: 77538200-e1b3-419a-b161-62771e4923b3 - LoggerBase.cs: No matching token was found in the cache
[10:23:57.324] [ 17] [INFO ] ADAL: 2019-02-28T00:23:57.3240410Z: 77538200-e1b3-419a-b161-62771e4923b3 - LoggerBase.cs: Sending request to userrealm endpoint.
[10:23:58.431] [ 17] [INFO ] ADAL: 2019-02-28T00:23:58.4316429Z: 77538200-e1b3-419a-b161-62771e4923b3 - LoggerBase.cs: === Token Acquisition finished successfully. An access token was returned: Expiration Time: 28/02/2019 1:23:58 AM +00:00
[10:23:58.431] [ 6] [INFO ] Authenticate-ADAL: successfully acquired an access token. TenantId=1412e715-9c27-46bb-825a-7db972c70296, ExpiresUTC=28/02/2019 1:23:58 AM +00:00, UserInfo=administrator@fdcgympie.onmicrosoft.com, IdentityProvider=https://sts.windows.net/1412e715-9c27-46bb-825a-7db972c70296/.
[10:23:58.431] [ 6] [INFO ] AzureTenantPage: attempting to connect to Azure via AAD PowerShell.
[10:23:58.431] [ 6] [INFO ] DiscoverServiceEndpoint [AzurePowerShell]: ServiceEndpoint=ProvisioningWebService Service, AdalAuthority=HTTPS://LOGIN.WINDOWS.NET/FDCGYMPIE.ONMICROSOFT.COM, AdalResource=https://graph.windows.net.
[10:23:58.431] [ 6] [INFO ] AcquireServiceToken [AzurePowerShell]: acquiring service token.
[10:23:58.431] [ 6] [INFO ] Authenticate-ADAL [Acquiring token]: STS endpoint (HTTPS://LOGIN.WINDOWS.NET/FDCGYMPIE.ONMICROSOFT.COM), resource (https://graph.windows.net), userName (administrator@fdcgympie.onmicrosoft.com).
[10:23:58.431] [ 6] [INFO ] ADAL: 2019-02-28T00:23:58.4316429Z: 9f094cd5-4b42-4f3b-a455-d7190345c872 - LoggerBase.cs: ADAL PCL.Desktop with assembly version '3.19.6.14301', file version '3.19.50523.1839' and informational version '1ae77ee16c2204403e53d7e652ddc8f4d315cfb1' is running...
[10:23:58.431] [ 6] [INFO ] ADAL: 2019-02-28T00:23:58.4316429Z: 9f094cd5-4b42-4f3b-a455-d7190345c872 - LoggerBase.cs: === Token Acquisition started:
CacheType: null
Authentication Target: User
, Authority Host: login.windows.net
[10:23:58.431] [ 6] [INFO ] ADAL: 2019-02-28T00:23:58.4316429Z: 9f094cd5-4b42-4f3b-a455-d7190345c872 - LoggerBase.cs: An item matching the requested resource was found in the cache
[10:23:58.431] [ 6] [INFO ] ADAL: 2019-02-28T00:23:58.4316429Z: 9f094cd5-4b42-4f3b-a455-d7190345c872 - LoggerBase.cs: 59.9989599983333 minutes left until token in cache expires
[10:23:58.431] [ 6] [INFO ] ADAL: 2019-02-28T00:23:58.4316429Z: 9f094cd5-4b42-4f3b-a455-d7190345c872 - LoggerBase.cs: A matching item (access token or refresh token or both) was found in the cache
[10:23:58.431] [ 6] [INFO ] ADAL: 2019-02-28T00:23:58.4316429Z: 9f094cd5-4b42-4f3b-a455-d7190345c872 - LoggerBase.cs: === Token Acquisition finished successfully. An access token was returned: Expiration Time: 28/02/2019 1:23:58 AM +00:00
[10:23:58.431] [ 6] [INFO ] Authenticate-ADAL: successfully acquired an access token. TenantId=1412e715-9c27-46bb-825a-7db972c70296, ExpiresUTC=28/02/2019 1:23:58 AM +00:00, UserInfo=administrator@fdcgympie.onmicrosoft.com, IdentityProvider=https://sts.windows.net/1412e715-9c27-46bb-825a-7db972c70296/.
[10:23:58.447] [ 6] [INFO ] PowerShellHelper.ConnectMsolService: Connecting using an AccessToken. AzureEnvironment=0.
[10:23:58.962] [ 6] [WARN ] Failed to import module MSOnline for PowerShell Command Connect-MsolService
[10:23:59.991] [ 6] [INFO ] AzureTenantPage: successfully connected to Azure via AAD PowerShell.
[10:24:01.442] [ 6] [WARN ] Failed to import module MSOnline for PowerShell Command Get-MsolCompanyInformation
[10:24:02.097] [ 6] [INFO ] AzureTenantPage: Successfully retrieved company information for tenant 1412e715-9c27-46bb-825a-7db972c70296. Initial domain (fdcgympie.onmicrosoft.com).
[10:24:02.113] [ 6] [INFO ] AzureTenantPage: DirectorySynchronizationEnabled=False
[10:24:02.113] [ 6] [INFO ] AzureTenantPage: DirectorySynchronizationStatus=Disabled
[10:24:02.113] [ 6] [INFO ] PowershellHelper: lastDirectorySyncTime=null
[10:24:02.784] [ 6] [INFO ] AzureTenantPageViewModel.GetSynchronizedUserCount: number of synchronized users (max 500) - 1
[10:24:03.330] [ 6] [INFO ] AzureTenantPageViewModel.GetSynchronizedUserCount: number of synchronized users (max 500) - 1
[10:24:03.330] [ 6] [WARN ] Failed to import module MSOnline for PowerShell Command Get-MsolDomain
[10:24:03.907] [ 6] [INFO ] AzureTenantPage: Successfully retrieved 2 domains from the tenant.
[10:24:03.907] [ 6] [INFO ] AzureTenantPage: Calling to get the last dir sync time for the current user
[10:24:04.515] [ 6] [INFO ] DiscoverServiceEndpoint [AdminWebService]: ServiceEndpoint=Service, AdalAuthority=HTTPS://LOGIN.WINDOWS.NET/FDCGYMPIE.ONMICROSOFT.COM, AdalResource=https://graph.windows.net.
[10:24:04.531] [ 6] [INFO ] DiscoverServiceEndpoint [AdminWebService]: ServiceEndpoint=Service, AdalAuthority=HTTPS://LOGIN.WINDOWS.NET/FDCGYMPIE.ONMICROSOFT.COM, AdalResource=https://graph.windows.net.
[10:24:04.531] [ 6] [INFO ] AcquireServiceToken [AdminWebService]: acquiring service token.
[10:24:04.531] [ 6] [INFO ] Authenticate-ADAL [Acquiring token]: STS endpoint (HTTPS://LOGIN.WINDOWS.NET/FDCGYMPIE.ONMICROSOFT.COM), resource (https://graph.windows.net), userName (administrator@fdcgympie.onmicrosoft.com).
[10:24:04.531] [ 6] [INFO ] ADAL: 2019-02-28T00:24:04.5312536Z: daf99cba-f712-4b8c-a182-07a8f9fd6a80 - LoggerBase.cs: ADAL PCL.Desktop with assembly version '3.19.6.14301', file version '3.19.50523.1839' and informational version '1ae77ee16c2204403e53d7e652ddc8f4d315cfb1' is running...
[10:24:04.531] [ 6] [INFO ] ADAL: 2019-02-28T00:24:04.5312536Z: daf99cba-f712-4b8c-a182-07a8f9fd6a80 - LoggerBase.cs: === Token Acquisition started:
CacheType: null
Authentication Target: User
, Authority Host: login.windows.net
[10:24:04.531] [ 6] [INFO ] ADAL: 2019-02-28T00:24:04.5312536Z: daf99cba-f712-4b8c-a182-07a8f9fd6a80 - LoggerBase.cs: An item matching the requested resource was found in the cache
[10:24:04.531] [ 6] [INFO ] ADAL: 2019-02-28T00:24:04.5312536Z: daf99cba-f712-4b8c-a182-07a8f9fd6a80 - LoggerBase.cs: 59.89729982 minutes left until token in cache expires
[10:24:04.531] [ 6] [INFO ] ADAL: 2019-02-28T00:24:04.5312536Z: daf99cba-f712-4b8c-a182-07a8f9fd6a80 - LoggerBase.cs: A matching item (access token or refresh token or both) was found in the cache
[10:24:04.531] [ 6] [INFO ] ADAL: 2019-02-28T00:24:04.5312536Z: daf99cba-f712-4b8c-a182-07a8f9fd6a80 - LoggerBase.cs: === Token Acquisition finished successfully. An access token was returned: Expiration Time: 28/02/2019 1:23:58 AM +00:00
[10:24:04.531] [ 6] [INFO ] Authenticate-ADAL: successfully acquired an access token. TenantId=1412e715-9c27-46bb-825a-7db972c70296, ExpiresUTC=28/02/2019 1:23:58 AM +00:00, UserInfo=administrator@fdcgympie.onmicrosoft.com, IdentityProvider=https://sts.windows.net/1412e715-9c27-46bb-825a-7db972c70296/.
[10:24:05.295] [ 6] [INFO ] GetCompanyConfiguration: tenantId=(1412e715-9c27-46bb-825a-7db972c70296), IsDirSyncing=False, IsPasswordSyncing=False, DomainName=, DirSyncFeatures=0, AllowedFeatures=None.
[10:24:05.295] [ 6] [INFO ] AzureTenantPage: AdminWebService returned the company information for tenant 1412e715-9c27-46bb-825a-7db972c70296.
[10:24:05.311] [ 6] [INFO ] MicrosoftOnlinePersistedStateProvider.Save: saving the persisted state file
[10:24:05.311] [ 6] [INFO ] MicrosoftOnlinePersistedStateProvider.UpdateFileProtection: updating file protection from the persisted state file: C:\ProgramData\AADConnect\PersistedState.xml, isAddProtection: False
[10:24:05.311] [ 6] [INFO ] MicrosoftOnlinePersistedStateProvider.UpdateFileProtection: updating file protection from the persisted state file: C:\ProgramData\AADConnect\PersistedState.xml, isAddProtection: True
[10:24:05.311] [ 6] [INFO ] AzureTenantPage: Windows Azure tenant credentials validation succeeded.
[10:24:05.326] [ 1] [INFO ] Page transition from "Connect to Azure AD" [AzureTenantPageViewModel] to "Connect to AD DS" [ConfigOnPremiseCredentialsPageViewModel]
[10:24:05.342] [ 1] [INFO ] Property Username failed validation with error Enterprise Administrator credentials are required
[10:24:08.675] [ 1] [INFO ] Property Username failed validation with error The username format is incorrect. Specify the username in the format of DOMAIN\username.
[10:24:12.177] [ 1] [INFO ] Property Password failed validation with error A password is required - unless using a Virtual or Managed Service Account .
[10:24:23.130] [ 18] [INFO ] ConfigOnPremiseCredentialsPage: Validating credentials for user - FDCC\administrator
[10:24:23.145] [ 18] [INFO ] ConfigOnPremiseCredentialsPage: LogonUser succeeded for user FDCC\administrator
[10:24:23.145] [ 18] [INFO ] ActiveDirectoryProvider.GetRootDomainName: getting user root domain name
[10:24:23.195] [ 18] [INFO ] ActiveDirectoryProvider.GetRootDomainName: user root domain - FDCC.local
[10:24:23.199] [ 18] [INFO ] ActiveDirectoryProvider.IsUserGroupMember: checking if FDCC\administrator has AccountEnterpriseAdminsSid privileges in FDCC.local
[10:24:23.611] [ 18] [INFO ] ActiveDirectoryProvider.IsUserGroupMember: domain sid - S-1-5-21-1243049921-2400037562-2501132947, group sid - S-1-5-21-1243049921-2400037562-2501132947-519
[10:24:23.615] [ 18] [INFO ] ActiveDirectoryProvider.GetGroupMembershipSidsForUser: retrieving group membership SIDs from AD
[10:24:23.625] [ 18] [INFO ] ActiveDirectoryProvider.IsUserGroupMember: found membership - user is a member of the group
[10:24:23.643] [ 18] [INFO ] ValidateCredentials UseExpressSettings: The domain name 'FDCC.local' was successfully matched.
[10:24:23.652] [ 18] [INFO ] ConfigOnPremiseCredentialsPage: Validating forest
[10:24:23.662] [ 18] [INFO ] Validating forest with FQDN FDCC.local
[10:24:23.703] [ 18] [INFO ] Examining domain FDCC.local
0% complete)
[10:24:23.709] [ 18] [INFO ] ValidateForest: using FDCC-SERVER.FDCC.local to validate domain FDCC.local
[10:24:23.713] [ 18] [INFO ] Successfully examined domain FDCC.local GUID:2c51e74e-4472-489a-ad6a-bf54899d4af7 DNC=FDCC,DC=local
[10:24:23.737] [ 18] [INFO ] ConfigOnPremiseCredentialsPageViewModel: Credentials will be used to administer the AD MA account (New Install).
[10:24:23.787] [ 18] [VERB ] MsolDomainExtensions.ConnectMsolService: Connecting to MSOL service.
[10:24:23.787] [ 18] [INFO ] DiscoverServiceEndpoint [AzurePowerShell]: ServiceEndpoint=ProvisioningWebService Service, AdalAuthority=HTTPS://LOGIN.WINDOWS.NET/FDCGYMPIE.ONMICROSOFT.COM, AdalResource=https://graph.windows.net.
[10:24:23.787] [ 18] [INFO ] AcquireServiceToken [AzurePowerShell]: acquiring service token.
[10:24:23.787] [ 18] [INFO ] Authenticate-ADAL [Acquiring token]: STS endpoint (HTTPS://LOGIN.WINDOWS.NET/FDCGYMPIE.ONMICROSOFT.COM), resource (https://graph.windows.net), userName (administrator@fdcgympie.onmicrosoft.com).
[10:24:23.787] [ 18] [INFO ] ADAL: 2019-02-28T00:24:23.7875743Z: ab026b8a-e055-4661-9b59-c70f0419d520 - LoggerBase.cs: ADAL PCL.Desktop with assembly version '3.19.6.14301', file version '3.19.50523.1839' and informational version '1ae77ee16c2204403e53d7e652ddc8f4d315cfb1' is running...
[10:24:23.788] [ 18] [INFO ] ADAL: 2019-02-28T00:24:23.7885743Z: ab026b8a-e055-4661-9b59-c70f0419d520 - LoggerBase.cs: === Token Acquisition started:
CacheType: null
Authentication Target: User
, Authority Host: login.windows.net
[10:24:23.788] [ 18] [INFO ] ADAL: 2019-02-28T00:24:23.7885743Z: ab026b8a-e055-4661-9b59-c70f0419d520 - LoggerBase.cs: An item matching the requested resource was found in the cache
[10:24:23.788] [ 18] [INFO ] ADAL: 2019-02-28T00:24:23.7885743Z: ab026b8a-e055-4661-9b59-c70f0419d520 - LoggerBase.cs: 59.576344475 minutes left until token in cache expires
[10:24:23.788] [ 18] [INFO ] ADAL: 2019-02-28T00:24:23.7885743Z: ab026b8a-e055-4661-9b59-c70f0419d520 - LoggerBase.cs: A matching item (access token or refresh token or both) was found in the cache
[10:24:23.788] [ 18] [INFO ] ADAL: 2019-02-28T00:24:23.7885743Z: ab026b8a-e055-4661-9b59-c70f0419d520 - LoggerBase.cs: === Token Acquisition finished successfully. An access token was returned: Expiration Time: 28/02/2019 1:23:58 AM +00:00
[10:24:23.788] [ 18] [INFO ] Authenticate-ADAL: successfully acquired an access token. TenantId=1412e715-9c27-46bb-825a-7db972c70296, ExpiresUTC=28/02/2019 1:23:58 AM +00:00, UserInfo=administrator@fdcgympie.onmicrosoft.com, IdentityProvider=https://sts.windows.net/1412e715-9c27-46bb-825a-7db972c70296/.
[10:24:23.788] [ 18] [INFO ] PowerShellHelper.ConnectMsolService: Connecting using an AccessToken. AzureEnvironment=0.
[10:24:24.156] [ 18] [WARN ] Failed to import module MSOnline for PowerShell Command Connect-MsolService
[10:24:24.698] [ 18] [WARN ] Failed to import module MSOnline for PowerShell Command Get-MsolDomain
[10:24:25.231] [ 18] [INFO ] Page transition from "Connect to AD DS" [ConfigOnPremiseCredentialsPageViewModel] to "Azure AD sign-in" [UserSignInConfigPageViewModel]
[10:24:25.237] [ 18] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Start background task Microsoft.Online.Deployment.OneADWizard.UI.WizardPages.UserSignInConfigPageViewModel.ValidateScenario in Page:"Azure AD sign-in configuration"
[10:24:25.237] [ 18] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Started Background Task Id:5192
[10:24:25.360] [ 7] [VERB ] MsolDomainExtensions.ConnectMsolService: Connecting to MSOL service.
[10:24:25.360] [ 7] [INFO ] DiscoverServiceEndpoint [AzurePowerShell]: ServiceEndpoint=ProvisioningWebService Service, AdalAuthority=HTTPS://LOGIN.WINDOWS.NET/FDCGYMPIE.ONMICROSOFT.COM, AdalResource=https://graph.windows.net.
[10:24:25.360] [ 7] [INFO ] AcquireServiceToken [AzurePowerShell]: acquiring service token.
[10:24:25.360] [ 7] [INFO ] Authenticate-ADAL [Acquiring token]: STS endpoint (HTTPS://LOGIN.WINDOWS.NET/FDCGYMPIE.ONMICROSOFT.COM), resource (https://graph.windows.net), userName (administrator@fdcgympie.onmicrosoft.com).
[10:24:25.361] [ 7] [INFO ] ADAL: 2019-02-28T00:24:25.3616643Z: 28c7320d-1f08-4396-81eb-2d4aeb72d0ee - LoggerBase.cs: ADAL PCL.Desktop with assembly version '3.19.6.14301', file version '3.19.50523.1839' and informational version '1ae77ee16c2204403e53d7e652ddc8f4d315cfb1' is running...
[10:24:25.361] [ 7] [INFO ] ADAL: 2019-02-28T00:24:25.3616643Z: 28c7320d-1f08-4396-81eb-2d4aeb72d0ee - LoggerBase.cs: === Token Acquisition started:
CacheType: null
Authentication Target: User
, Authority Host: login.windows.net
[10:24:25.361] [ 7] [INFO ] ADAL: 2019-02-28T00:24:25.3616643Z: 28c7320d-1f08-4396-81eb-2d4aeb72d0ee - LoggerBase.cs: An item matching the requested resource was found in the cache
[10:24:25.361] [ 7] [INFO ] ADAL: 2019-02-28T00:24:25.3616643Z: 28c7320d-1f08-4396-81eb-2d4aeb72d0ee - LoggerBase.cs: 59.5501263083333 minutes left until token in cache expires
[10:24:25.361] [ 7] [INFO ] ADAL: 2019-02-28T00:24:25.3616643Z: 28c7320d-1f08-4396-81eb-2d4aeb72d0ee - LoggerBase.cs: A matching item (access token or refresh token or both) was found in the cache
[10:24:25.362] [ 7] [INFO ] ADAL: 2019-02-28T00:24:25.3626644Z: 28c7320d-1f08-4396-81eb-2d4aeb72d0ee - LoggerBase.cs: === Token Acquisition finished successfully. An access token was returned: Expiration Time: 28/02/2019 1:23:58 AM +00:00
[10:24:25.362] [ 7] [INFO ] Authenticate-ADAL: successfully acquired an access token. TenantId=1412e715-9c27-46bb-825a-7db972c70296, ExpiresUTC=28/02/2019 1:23:58 AM +00:00, UserInfo=administrator@fdcgympie.onmicrosoft.com, IdentityProvider=https://sts.windows.net/1412e715-9c27-46bb-825a-7db972c70296/.
[10:24:25.362] [ 7] [INFO ] PowerShellHelper.ConnectMsolService: Connecting using an AccessToken. AzureEnvironment=0.
[10:24:25.707] [ 7] [WARN ] Failed to import module MSOnline for PowerShell Command Connect-MsolService
[10:24:26.249] [ 7] [WARN ] Failed to import module MSOnline for PowerShell Command Get-MsolDomain
[10:24:26.802] [ 1] [INFO ] UPN Suffix List
[10:24:26.802] [ 1] [INFO ] --------------------------------------------------------------------
[10:24:26.802] [ 1] [INFO ] UPN Suffix [Azure Status]
[10:24:26.802] [ 1] [INFO ] --------------------------------------------------------------------
[10:24:26.806] [ 1] [INFO ] fdcc.local [Not Added]
[10:24:26.807] [ 1] [INFO ] fdcgympie.org.au [Verified]
[10:24:26.807] [ 1] [INFO ] --------------------------------------------------------------------
[10:24:26.807] [ 7] [INFO ] UserSignInConfigPageViewModel: AD Domains: notAddedDomains 1, notVerifiedDomains 0, verifiedDomains 1
[10:24:26.807] [ 7] [INFO ] UserSignInConfigPageViewModel: Azure Domains: aadUnverifiedDomains 0, aadVerifiedDomains 1
[10:24:26.807] [ 7] [INFO ] UserSignInConfigPageViewModel: The currently selected sign-in method is PasswordHashSync
[10:24:26.810] [ 1] [WARN ] UserSignInConfigPageViewModel: Some users will not be able to sign-in to Azure AD with on-premises credentials as their UPN suffixes in AD do not have a corresponding Azure verified domain in tenant (fdcgympie.onmicrosoft.com).
[10:24:31.372] [ 1] [INFO ] Page transition from "Azure AD sign-in" [UserSignInConfigPageViewModel] to "Configure" [PerformConfigurationPageViewModel]
[10:24:31.380] [ 1] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Start background task Microsoft.Online.Deployment.OneADWizard.UI.WizardPages.PerformConfigurationPageViewModel.BackgroundInitialize in Page:"Ready to configure"
[10:24:31.381] [ 1] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Started Background Task Id:6210
[10:24:32.389] [ 12] [VERB ] PerformConfigurationPageViewModel:ExecuteAutoUpgradeCheck: context.WizardMode ExpressInstall.
[10:24:32.413] [ 12] [WARN ] DetermineAutoUpgradeState: AutoUpgrade entering ENABLED mode for express installation.
[10:24:32.413] [ 12] [VERB ] PerformConfigurationPageViewModel:ExecuteAutoUpgradeCheck: autoUpgradeState set to Enabled.
[10:24:32.418] [ 12] [INFO ] SetAutoUpgradeViaAdhealthRegistrykey: Updated SOFTWARE\Microsoft\ADHealthAgent\Sync\UpdateCheckEnabled registry value to 1
[10:24:32.420] [ 12] [INFO ] Restarting Monitoring Agent service.
[10:24:32.423] [ 12] [INFO ] ServiceControllerProvider: InvalidOperationException on serviceController.Status property means the service AzureADConnectHealthSyncMonitor was not found
[10:24:32.423] [ 12] [WARN ] Monitoring Agent service is not installed, so the service cannot be restarted.
[10:24:35.690] [ 1] [INFO ] MicrosoftOnlinePersistedStateProvider.Save: saving the persisted state file
[10:24:35.690] [ 1] [INFO ] MicrosoftOnlinePersistedStateProvider.UpdateFileProtection: updating file protection from the persisted state file: C:\ProgramData\AADConnect\PersistedState.xml, isAddProtection: False
[10:24:35.692] [ 1] [INFO ] MicrosoftOnlinePersistedStateProvider.UpdateFileProtection: updating file protection from the persisted state file: C:\ProgramData\AADConnect\PersistedState.xml, isAddProtection: True
[10:24:35.699] [ 1] [INFO ] PersistAzureAffinity: Azure affinity was previously persisted as Worldwide (0).
[10:24:35.699] [ 1] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Start background task Microsoft.Online.Deployment.OneADWizard.UI.WizardPages.PerformConfigurationPageViewModel.ExecuteADSyncConfiguration in Page:"Configuring"
[10:24:35.699] [ 1] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Started Background Task Id:6921
[10:24:35.702] [ 8] [INFO ] PerformConfigurationPageViewModel.ExecuteADSyncConfiguration: Preparing to configure sync engine (WizardMode=ExpressInstall).
[10:24:35.704] [ 8] [INFO ] PerformConfigurationPageViewModel.ExecuteSyncEngineInstallCore: Preparing to install sync engine (WizardMode=ExpressInstall).
[10:24:35.707] [ 8] [INFO ] Starting Sync Engine installation
[10:24:38.693] [ 8] [INFO ] ServiceControllerProvider: InvalidOperationException on serviceController.Status property means the service ADSync was not found
[10:24:38.699] [ 8] [INFO ] ServiceControllerProvider:CreateService - serviceName:ADSync, username:FDCC\AAD_dc5a812f7347, assemblyPath:C:\Program Files\Microsoft Azure Active Directory Connect\ADSyncBootstrap.exe
[10:24:39.026] [ 8] [INFO ] ServiceControllerProvider: Processing StartService request for: ADSync
[10:24:39.027] [ 8] [VERB ] ServiceControllerProvider: Initial service status: Stopped
[10:24:39.027] [ 8] [VERB ] ServiceControllerProvider: Starting service and waiting for completion.
[10:24:39.548] [ 8] [INFO ] ServiceControllerProvider: StartService status: Running
[10:24:54.495] [ 8] [INFO ] ServiceControllerProvider: processing StopService request for: ADSync
[10:24:54.496] [ 8] [VERB ] ServiceControllerProvider: Initial service status: Running
[10:24:54.496] [ 8] [VERB ] ServiceControllerProvider: stopping service and waiting for completion.
[10:24:54.748] [ 8] [INFO ] ServiceControllerProvider: StopService status: Stopped
[10:24:54.748] [ 8] [INFO ] ServiceControllerProvider: Processing StartService request for: ADSync
[10:24:54.750] [ 8] [VERB ] ServiceControllerProvider: Initial service status: Stopped
[10:24:54.750] [ 8] [VERB ] ServiceControllerProvider: Starting service and waiting for completion.
[10:24:55.416] [ 8] [INFO ] ServiceControllerProvider: StartService status: Running
[10:24:55.714] [ 8] [INFO ] ServiceControllerProvider: processing StopService request for: ADSync
[10:24:55.714] [ 8] [VERB ] ServiceControllerProvider: Initial service status: Running
[10:24:55.714] [ 8] [VERB ] ServiceControllerProvider: stopping service and waiting for completion.
[10:24:55.966] [ 8] [INFO ] ServiceControllerProvider: StopService status: Stopped
[10:24:55.969] [ 8] [INFO ] ServiceControllerProvidereleteService - serviceName:ADSync
[10:25:05.995] [ 8] [INFO ] ServiceControllerProvider: InvalidOperationException on serviceController.Status property means the service ADSync was not found
[10:25:05.995] [ 8] [INFO ] ServiceControllerProvidereleteService successful - serviceName:ADSync
[10:25:06.002] [ 8] [INFO ] BuildMsiArguments: Setting Sync Engine MSI parameters for clean installation
[10:25:17.440] [ 8] [ERROR] PerformConfigurationPageViewModel: Caught exception while installing synchronization service.
Exception Data (Raw): System.Exception: Unable to install the Synchronization Service. Please see the event log for additional details. ---> Microsoft.Online.Deployment.Client.Framework.ProcessExecutionFailedException: Error installing msi package 'Synchronization Service.msi'. Full log is available at 'C:\ProgramData\AADConnect\Synchronization Service_Install-20190228-102506.log'.
Extracted error message:
ActionStart(Name=ProcessMachineDcomPermission,,)
MSI (s) (38:74) [10:25:15:059]: Executing op: CustomActionSchedule(Action=ProcessMachineDcomPermission,ActionType=1025,Source=BinaryData,Target=ProcessMachineDcomPermission,CustomActionData=ADMINS=ADSyncAdmins OPERATORS=ADSyncOperators BROWSE=ADSyncBrowse PASSWORDSET=ADSyncPasswordSet)
MSI (s) (38:58) [10:25:15:062]: Invoking remote custom action. DLL: C:\Windows\Installer\MSIFDA8.tmp, Entrypoint: ProcessMachineDcomPermission
CustomAction ProcessMachineDcomPermission returned actual error code 1603 (note this may not be 100% accurate if translation happened inside sandbox)
MSI (s) (38:74) [10:25:15:238]: User policy value 'DisableRollback' is 0
MSI (s) (38:74) [10:25:15:238]: Machine policy value 'DisableRollback' is 0
Action ended 10:25:15: InstallExecute.
---> Microsoft.Online.Deployment.Client.Framework.ProcessExecutionFailedException: Exception: Execution failed with errorCode: 1603.
Details:
at Microsoft.Azure.ActiveDirectory.Synchronization.Framework.ProcessAdapter.StartProcessCore(String fileName, String& processOutput, String arguments, String workingDirectory, NetworkCredential credential, Boolean loadUserProfile, Boolean hideWindow, Boolean waitForExit, Boolean traceArguments, Int32 exitCodeToIgnore)
at Microsoft.Azure.ActiveDirectory.Synchronization.Framework.MsiExecAdapter.InstallMsiPackage(String msiPackageDirectory, String msiPackageFileName, String packageOptions, String installationPath, NetworkCredential credential, String installLogFileName, Boolean extractOnly, Boolean quiet, Boolean suppressReboot)
--- End of inner exception stack trace ---
at Microsoft.Azure.ActiveDirectory.Synchronization.Framework.MsiExecAdapter.InstallMsiPackage(String msiPackageDirectory, String msiPackageFileName, String packageOptions, String installationPath, NetworkCredential credential, String installLogFileName, Boolean extractOnly, Boolean quiet, Boolean suppressReboot)
at Microsoft.Azure.ActiveDirectory.Synchronization.Setup.SynchronizationServiceSetupTask.InstallSynchronizationService(String pathToMsiFiles, String msiFileName, String installationPath, String sqlServerName, String sqlInstanceName, Boolean useInstallPathForDBFiles, IDictionary`2 syncServiceGroups, SyncServiceAccount syncServiceAccount, String logFilePath)
at Microsoft.Azure.ActiveDirectory.Synchronization.Setup.SynchronizationServiceSetupTask.InstallCore(String logFilePath, String logFileSuffix)
at Microsoft.Azure.ActiveDirectory.Synchronization.Framework.ActionExecutor.ExecuteWithSetupResultsStatus(SetupAction action, String description, String logFileName, String logFileSuffix)
at Microsoft.Azure.ActiveDirectory.Synchronization.Setup.SetupBase.Install()
--- End of inner exception stack trace ---
at Microsoft.Azure.ActiveDirectory.Synchronization.Setup.SetupBase.ThrowSetupTaskFailureException(String exceptionFormatString, String taskName, Exception innerException)
at Microsoft.Azure.ActiveDirectory.Synchronization.Setup.SetupBase.Install()
at Microsoft.Online.Deployment.OneADWizard.Runtime.Stages.InstallSyncEngineStage.ExecuteInstallCore(ISyncEngineInstallContext syncEngineInstallContext, ProgressChangedEventHandler progressChangesEventHandler)
at Microsoft.Online.Deployment.OneADWizard.Runtime.Stages.InstallSyncEngineStage.ExecuteInstall(ISyncEngineInstallContext syncEngineInstallContext, ProgressChangedEventHandler progressChangesEventHandler)
at Microsoft.Online.Deployment.OneADWizard.UI.WizardPages.PerformConfigurationPageViewModel.ExecuteSyncEngineInstallCore(AADConnectResult& result)
[10:25:23.536] [ 1] [INFO ] Opened log file at path C:\ProgramData\AADConnect\trace-20190228-102301.log
Continue reading...
We are migrating a customer off SBS 2011 over to O365 and Server 2016 Essentials. The essentials server has not yet been connected, but we are trying to install Azure Sync (DirSync) to sync the local domain with O365 as we have done many times before for other clients with the same configuration.
This particular SBS server can not install the Sync Service and here is the error log:
[10:23:01.295] [ 1] [INFO ]
[10:23:01.311] [ 1] [INFO ] ================================================================================
[10:23:01.311] [ 1] [INFO ] Application starting
[10:23:01.311] [ 1] [INFO ] ================================================================================
[10:23:01.311] [ 1] [INFO ] Start Time (Local): Thu, 28 Feb 2019 10:23:01 GMT
[10:23:01.311] [ 1] [INFO ] Start Time (UTC): Thu, 28 Feb 2019 00:23:01 GMT
[10:23:01.311] [ 1] [INFO ] Application Version: 1.2.70.0
[10:23:01.311] [ 1] [INFO ] Application Build Date: 2018-12-17 07:19:47Z
[10:23:03.713] [ 1] [INFO ] Telemetry session identifier: {76b73b66-08cf-4363-be53-579dd563799e}
[10:23:03.713] [ 1] [INFO ] Telemetry device identifier: MrDPgTedYrF8EXvmvab4YZse5swLIISGay/dq5T8DTU=
[10:23:03.713] [ 1] [INFO ] Application Build Identifier: AD-IAM-HybridSync master (590693a40)
[10:23:03.854] [ 1] [INFO ] machine.config path: C:\Windows\Microsoft.NET\Framework64\v4.0.30319\Config\machine.config.
[10:23:03.854] [ 1] [INFO ] Default Proxy [ProxyAddress]: <Unspecified>
[10:23:03.854] [ 1] [INFO ] Default Proxy [UseSystemDefault]: Unspecified
[10:23:03.854] [ 1] [INFO ] Default Proxy [BypassOnLocal]: Unspecified
[10:23:03.854] [ 1] [INFO ] Default Proxy [Enabled]: True
[10:23:03.854] [ 1] [INFO ] Default Proxy [AutoDetect]: Unspecified
[10:23:03.901] [ 1] [VERB ] Scheduler wizard mutex wait timeout: 00:00:05
[10:23:03.901] [ 1] [INFO ] AADConnect changes ALLOWED: Successfully acquired the configuration change mutex.
[10:23:03.994] [ 1] [INFO ] RootPageViewModel.GetInitialPages: Beginning detection for creating initial pages.
[10:23:04.041] [ 1] [INFO ] Loading the persisted settings .
[10:23:04.088] [ 1] [INFO ] Checking if machine version is 6.1.7601 or higher
[10:23:04.150] [ 1] [INFO ] The current operating system version is 6.1.7601, the requirement is 6.1.7601.
[10:23:04.150] [ 1] [INFO ] Password Hash Sync supported: 'True'
[10:23:04.213] [ 1] [INFO ] DetectInstalledComponents stage: The installed OS SKU is 9
[10:23:04.228] [ 1] [INFO ] DetectInstalledComponents stage: Checking install context.
[10:23:04.244] [ 1] [INFO ] Performing direct lookup of upgrade codes for: Microsoft Visual C++ 2013 Redistributable Package
[10:23:04.244] [ 1] [VERB ] Getting list of installed packages by upgrade code
[10:23:04.259] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {20400cf0-de7c-327e-9ae4-f0f38d9085f8}: verified product code {a749d8e6-b613-3be3-8f5f-045c84eba29b}.
[10:23:04.259] [ 1] [VERB ] Package=Microsoft Visual C++ 2013 x64 Minimum Runtime - 12.0.21005, Version=12.0.21005, ProductCode=a749d8e6-b613-3be3-8f5f-045c84eba29b, UpgradeCode=20400cf0-de7c-327e-9ae4-f0f38d9085f8
[10:23:04.259] [ 1] [INFO ] Determining installation action for Microsoft Visual C++ 2013 Redistributable Package (20400cf0-de7c-327e-9ae4-f0f38d9085f8)
[10:23:04.259] [ 1] [INFO ] Product Microsoft Visual C++ 2013 Redistributable Package (version 12.0.21005) is installed.
[10:23:04.259] [ 1] [INFO ] Performing direct lookup of upgrade codes for: Microsoft Directory Sync Tool
[10:23:04.259] [ 1] [VERB ] Getting list of installed packages by upgrade code
[10:23:04.259] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {bef7e7d9-2ac2-44b9-abfc-3335222b92a7}: no registered products found.
[10:23:04.259] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {dc9e604e-37b0-4efc-b429-21721cf49d0d}: no registered products found.
[10:23:04.259] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {545334d7-13cd-4bab-8da1-2775fa8cf7c2}: no registered products found.
[10:23:04.275] [ 1] [INFO ] Determining installation action for Microsoft Directory Sync Tool UpgradeCodes {bef7e7d9-2ac2-44b9-abfc-3335222b92a7}, {dc9e604e-37b0-4efc-b429-21721cf49d0d}
[10:23:04.275] [ 1] [INFO ] DirectorySyncComponent: Product Microsoft Directory Sync Tool is not installed.
[10:23:04.275] [ 1] [INFO ] Performing direct lookup of upgrade codes for: Azure AD Sync Engine
[10:23:04.275] [ 1] [VERB ] Getting list of installed packages by upgrade code
[10:23:04.275] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {545334d7-13cd-4bab-8da1-2775fa8cf7c2}: no registered products found.
[10:23:04.275] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {dc9e604e-37b0-4efc-b429-21721cf49d0d}: no registered products found.
[10:23:04.275] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {bef7e7d9-2ac2-44b9-abfc-3335222b92a7}: no registered products found.
[10:23:04.291] [ 1] [INFO ] Determining installation action for Azure AD Sync Engine (545334d7-13cd-4bab-8da1-2775fa8cf7c2)
[10:23:04.759] [ 1] [INFO ] Product Azure AD Sync Engine is not installed.
[10:23:04.759] [ 1] [INFO ] Performing direct lookup of upgrade codes for: Azure AD Connect Synchronization Agent
[10:23:04.759] [ 1] [VERB ] Getting list of installed packages by upgrade code
[10:23:04.759] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {3cd653e3-5195-4ff2-9d6c-db3dacc82c25}: no registered products found.
[10:23:04.759] [ 1] [INFO ] Determining installation action for Azure AD Connect Synchronization Agent (3cd653e3-5195-4ff2-9d6c-db3dacc82c25)
[10:23:04.759] [ 1] [INFO ] Product Azure AD Connect Synchronization Agent is not installed.
[10:23:04.759] [ 1] [INFO ] Performing direct lookup of upgrade codes for: Azure AD Connect Health agent for sync
[10:23:04.759] [ 1] [VERB ] Getting list of installed packages by upgrade code
[10:23:04.759] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {114fb294-8aa6-43db-9e5c-4ede5e32886f}: no registered products found.
[10:23:04.759] [ 1] [INFO ] Determining installation action for Azure AD Connect Health agent for sync (114fb294-8aa6-43db-9e5c-4ede5e32886f)
[10:23:04.759] [ 1] [INFO ] Product Azure AD Connect Health agent for sync is not installed.
[10:23:04.759] [ 1] [INFO ] Performing direct lookup of upgrade codes for: Microsoft Azure AD Connect Authentication Agent
[10:23:04.759] [ 1] [VERB ] Getting list of installed packages by upgrade code
[10:23:04.759] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {0c06f9df-c56b-42c4-a41b-f5f64d01a35c}: no registered products found.
[10:23:04.759] [ 1] [INFO ] Determining installation action for Microsoft Azure AD Connect Authentication Agent (0c06f9df-c56b-42c4-a41b-f5f64d01a35c)
[10:23:04.759] [ 1] [INFO ] Product Microsoft Azure AD Connect Authentication Agent is not installed.
[10:23:04.759] [ 1] [INFO ] Performing direct lookup of upgrade codes for: Microsoft SQL Server 2012 Command Line Utilities
[10:23:04.759] [ 1] [VERB ] Getting list of installed packages by upgrade code
[10:23:04.759] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {52446750-c08e-49ef-8c2e-1e0662791e7b}: verified product code {89ca7913-f891-4546-8f55-355338677fe6}.
[10:23:04.759] [ 1] [VERB ] Package=Microsoft SQL Server 2012 Command Line Utilities , Version=11.4.7001.0, ProductCode=89ca7913-f891-4546-8f55-355338677fe6, UpgradeCode=52446750-c08e-49ef-8c2e-1e0662791e7b
[10:23:04.759] [ 1] [INFO ] Determining installation action for Microsoft SQL Server 2012 Command Line Utilities (52446750-c08e-49ef-8c2e-1e0662791e7b)
[10:23:04.759] [ 1] [INFO ] Product Microsoft SQL Server 2012 Command Line Utilities (version 11.4.7001.0) is installed.
[10:23:04.759] [ 1] [INFO ] Performing direct lookup of upgrade codes for: Microsoft SQL Server 2012 Express LocalDB
[10:23:04.759] [ 1] [VERB ] Getting list of installed packages by upgrade code
[10:23:04.759] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {c3593f78-0f11-4d8d-8d82-55460308e261}: verified product code {72b030ed-b1e3-45e5-ba33-a1f5625f2b93}.
[10:23:04.759] [ 1] [VERB ] Package=Microsoft SQL Server 2012 Express LocalDB , Version=11.4.7469.6, ProductCode=72b030ed-b1e3-45e5-ba33-a1f5625f2b93, UpgradeCode=c3593f78-0f11-4d8d-8d82-55460308e261
[10:23:04.759] [ 1] [INFO ] Determining installation action for Microsoft SQL Server 2012 Express LocalDB (c3593f78-0f11-4d8d-8d82-55460308e261)
[10:23:04.759] [ 1] [INFO ] Product Microsoft SQL Server 2012 Express LocalDB (version 11.4.7469.6) is installed.
[10:23:04.759] [ 1] [INFO ] Performing direct lookup of upgrade codes for: Microsoft SQL Server 2012 Native Client
[10:23:04.759] [ 1] [VERB ] Getting list of installed packages by upgrade code
[10:23:04.759] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {1d2d1fa0-e158-4798-98c6-a296f55414f9}: verified product code {b9274744-8bae-4874-8e59-2610919cd419}.
[10:23:04.759] [ 1] [VERB ] Package=Microsoft SQL Server 2012 Native Client , Version=11.4.7001.0, ProductCode=b9274744-8bae-4874-8e59-2610919cd419, UpgradeCode=1d2d1fa0-e158-4798-98c6-a296f55414f9
[10:23:04.759] [ 1] [INFO ] Determining installation action for Microsoft SQL Server 2012 Native Client (1d2d1fa0-e158-4798-98c6-a296f55414f9)
[10:23:04.759] [ 1] [INFO ] Product Microsoft SQL Server 2012 Native Client (version 11.4.7001.0) is installed.
[10:23:04.759] [ 1] [INFO ] Performing direct lookup of upgrade codes for: Microsoft Azure AD Connect Authentication Agent
[10:23:04.759] [ 1] [VERB ] Getting list of installed packages by upgrade code
[10:23:04.759] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {fb3feca7-5190-43e7-8d4b-5eec88ed9455}: no registered products found.
[10:23:04.759] [ 1] [INFO ] Determining installation action for Microsoft Azure AD Connect Authentication Agent (fb3feca7-5190-43e7-8d4b-5eec88ed9455)
[10:23:04.759] [ 1] [INFO ] Product Microsoft Azure AD Connect Authentication Agent is not installed.
[10:23:04.759] [ 1] [INFO ] Determining installation action for Microsoft Azure AD Connection Tool.
[10:23:04.930] [ 1] [WARN ] Failed to read DisplayName registry key: An error occurred while executing the 'Get-ItemProperty' command. Cannot find path 'HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\Windows\CurrentVersion\Uninstall\MicrosoftAzureADConnectionTool' because it does not exist.
[10:23:04.930] [ 1] [INFO ] Product Microsoft Azure AD Connection Tool is not installed.
[10:23:04.930] [ 1] [INFO ] Performing direct lookup of upgrade codes for: Azure Active Directory Connect
[10:23:04.930] [ 1] [VERB ] Getting list of installed packages by upgrade code
[10:23:04.930] [ 1] [INFO ] GetInstalledPackagesByUpgradeCode {d61eb959-f2d1-4170-be64-4dc367f451ea}: verified product code {b9170312-edaf-4e0c-9241-2407915b93ec}.
[10:23:04.930] [ 1] [VERB ] Package=Microsoft Azure AD Connect, Version=1.2.70.0, ProductCode=b9170312-edaf-4e0c-9241-2407915b93ec, UpgradeCode=d61eb959-f2d1-4170-be64-4dc367f451ea
[10:23:04.930] [ 1] [INFO ] Determining installation action for Azure Active Directory Connect (d61eb959-f2d1-4170-be64-4dc367f451ea)
[10:23:04.930] [ 1] [INFO ] Product Azure Active Directory Connect (version 1.2.70.0) is installed.
[10:23:05.227] [ 1] [INFO ] ServiceControllerProvider: GetServiceStartMode(seclogon) is 'Manual'.
[10:23:05.227] [ 1] [INFO ] ServiceControllerProvider: verifying EventLog is in state (Running)
[10:23:05.227] [ 1] [INFO ] ServiceControllerProvider: current service status: Running
[10:23:05.227] [ 1] [INFO ] Checking for DirSync conditions.
[10:23:05.227] [ 1] [INFO ] DirSync not detected. Checking for AADSync/AADConnect upgrade conditions.
[10:23:05.242] [ 1] [INFO ] Initial configuration is incomplete.
[10:23:05.242] [ 1] [INFO ] Resume Wizard from previous Azure service connectivity failure.
[10:23:05.258] [ 1] [INFO ] SyncDataProvider:LoadSettings - loading context with persisted global settings.
[10:23:25.616] [ 1] [ERROR] Configuration policy could not be retrieved (GetGlobalConfigurationParameters). Details: System.Management.Automation.CommandNotFoundException: The term 'Get-ADSyncGlobalSettingsParameter' is not recognized as the name of a cmdlet, function, script file, or operable program. Check the spelling of the name, or if a path was included, verify that the path is correct and try again.
at System.Management.Automation.Runspaces.PipelineBase.Invoke(IEnumerable input)
at System.Management.Automation.PowerShell.Worker.ConstructPipelineAndDoWork(Runspace rs, Boolean performSyncInvoke)
at System.Management.Automation.PowerShell.Worker.CreateRunspaceIfNeededAndDoWork(Runspace rsToUse, Boolean isSync)
at System.Management.Automation.PowerShell.CoreInvokeHelper[TInput,TOutput](PSDataCollection`1 input, PSDataCollection`1 output, PSInvocationSettings settings)
at System.Management.Automation.PowerShell.CoreInvoke[TInput,TOutput](PSDataCollection`1 input, PSDataCollection`1 output, PSInvocationSettings settings)
at System.Management.Automation.PowerShell.Invoke(IEnumerable input, PSInvocationSettings settings)
at Microsoft.Online.Deployment.PowerShell.LocalPowerShell.Invoke()
at Microsoft.Online.Deployment.PowerShell.PowerShellAdapter.TypeDependencies.InvokePowerShell(IPowerShell powerShell)
at Microsoft.Online.Deployment.PowerShell.PowerShellAdapter.InvokePowerShellCommand(String commandName, InitialSessionState initialSessionState, IDictionary`2 commandParameters, Boolean isScript)
at Microsoft.Azure.ActiveDirectory.Synchronization.PowerShellConfigAdapter.GlobalSettingsConfigAdapter.GetGlobalConfigurationParameters()
at Microsoft.Online.Deployment.Types.Providers.SyncDataProvider.LoadSettings(IAadSyncContext aadSyncContext)
[10:23:25.834] [ 1] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Start background task Microsoft.Online.Deployment.OneADWizard.UI.WizardPages.ExpressSettingsPageViewModel.GatherEnvironmentData in Page:"Express Settings"
[10:23:25.834] [ 1] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Started Background Task Id:1
[10:23:25.850] [ 8] [INFO ] Checking if machine version is 6.1.7601 or higher
[10:23:25.850] [ 8] [INFO ] The current operating system version is 6.1.7601, the requirement is 6.1.7601.
[10:23:25.850] [ 8] [INFO ] Password Hash Sync supported: 'True'
[10:23:26.255] [ 1] [INFO ] Express Settings install is supported: domain-joined + OS version allowed.
[10:23:31.224] [ 1] [INFO ] Express Settings: Updating page flow for EXPRESS mode install.
[10:23:31.227] [ 1] [INFO ] Called SetWizardMode(ExpressInstall, True)
[10:23:31.231] [ 1] [WARN ] MicrosoftOnlinePersistedStateProvider.Save: zero state elements provided, saving an empty persisted state file
[10:23:31.233] [ 1] [INFO ] MicrosoftOnlinePersistedStateProvider.UpdateFileProtection: updating file protection from the persisted state file: C:\ProgramData\AADConnect\PersistedState.xml, isAddProtection: False
[10:23:31.242] [ 1] [INFO ] MicrosoftOnlinePersistedStateProvider.UpdateFileProtection: updating file protection from the persisted state file: C:\ProgramData\AADConnect\PersistedState.xml, isAddProtection: True
[10:23:31.274] [ 1] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Start background task Microsoft.Online.Deployment.OneADWizard.UI.WizardPages.ExpressSettingsPageViewModel.StartPrerequisiteInstallation in Page:"Express Settings"
[10:23:31.275] [ 1] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Started Background Task Id:817
[10:23:31.334] [ 8] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Start background task Microsoft.Online.Deployment.OneADWizard.UI.WizardPages.InstallSyncEnginePageViewModel.StartNewInstallation in Page:"Install required components"
[10:23:31.335] [ 8] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Started Background Task Id:847
[10:23:31.382] [ 7] [INFO ] SyncEngineSetupViewModel: Validating sync engine settings.
[10:23:31.389] [ 7] [INFO ] Enter ValidateSqlVersion.
[10:23:31.389] [ 7] [INFO ] Exit ValidateSqlVersion (localdb).
[10:23:31.393] [ 7] [INFO ] Enter ValidateSqlAoaAsyncInstance.
[10:23:31.393] [ 7] [INFO ] Exit ValidateSqlAoaAsyncInstance (localdb).
[10:23:31.395] [ 7] [INFO ] The ADSync database does not exist and will be created. serverAdmin=True.
[10:23:31.395] [ 7] [INFO ] Attaching to the ADSync database: SQLServerName=DoesNotExist SQLInstanceName= ServiceAccountName=, state=, Collation=, /UseExistingDatabase=False.
[10:23:31.395] [ 7] [INFO ] Starting Sync Engine installation
[10:23:31.397] [ 7] [INFO ] Starting Prerequisite installation
[10:23:31.399] [ 7] [VERB ] WorkflowEngine created
[10:23:31.402] [ 7] [INFO ] Performing direct lookup of upgrade codes for: Microsoft Visual C++ 2013 Redistributable Package
[10:23:31.402] [ 7] [VERB ] Getting list of installed packages by upgrade code
[10:23:31.403] [ 7] [INFO ] GetInstalledPackagesByUpgradeCode {20400cf0-de7c-327e-9ae4-f0f38d9085f8}: verified product code {a749d8e6-b613-3be3-8f5f-045c84eba29b}.
[10:23:31.404] [ 7] [VERB ] Package=Microsoft Visual C++ 2013 x64 Minimum Runtime - 12.0.21005, Version=12.0.21005, ProductCode=a749d8e6-b613-3be3-8f5f-045c84eba29b, UpgradeCode=20400cf0-de7c-327e-9ae4-f0f38d9085f8
[10:23:31.404] [ 7] [INFO ] Determining installation action for Microsoft Visual C++ 2013 Redistributable Package (20400cf0-de7c-327e-9ae4-f0f38d9085f8)
[10:23:31.404] [ 7] [INFO ] Product Microsoft Visual C++ 2013 Redistributable Package (version 12.0.21005) is installed.
[10:23:31.411] [ 1] [INFO ] Page transition from "Express Settings" [ExpressSettingsPageViewModel] to "Connect to Azure AD" [AzureTenantPageViewModel]
[10:23:31.447] [ 1] [INFO ] Property Password failed validation with error A valid domain must be selected.
[10:23:48.717] [ 1] [INFO ] Property Password failed validation with error A valid domain must be selected.
[10:23:56.653] [ 6] [INFO ] AzureTenantPage: Beginning Windows Azure tenant credential validation for user - administrator@fdcgympie.onmicrosoft.com
[10:23:56.684] [ 6] [INFO ] AzureConfigurationFromPrincipalName: Successfully resolved UPN (administrator@fdcgympie.onmicrosoft.com) to the Worldwide Azure instance.
Resolution Method [Registry Configuration]: Worldwide.
[10:23:56.700] [ 6] [INFO ] ResolveAzureInstance [Worldwide]: authority=HTTPS://LOGIN.WINDOWS.NET/FDCGYMPIE.ONMICROSOFT.COM,
Resolution Method [Registry Configuration]: Worldwide.
[10:23:56.715] [ 6] [INFO ] Authenticate-ADAL [Acquiring token]: STS endpoint (HTTPS://LOGIN.WINDOWS.NET/FDCGYMPIE.ONMICROSOFT.COM), resource (https://graph.windows.net), userName (administrator@fdcgympie.onmicrosoft.com).
[10:23:56.731] [ 6] [INFO ] ADAL: 2019-02-28T00:23:56.7312399Z: 00000000-0000-0000-0000-000000000000 - LoggerBase.cs: Clearing Cache :- 0 items to be removed
[10:23:56.731] [ 6] [INFO ] ADAL: 2019-02-28T00:23:56.7312399Z: 00000000-0000-0000-0000-000000000000 - LoggerBase.cs: Successfully Cleared Cache
[10:23:56.746] [ 6] [INFO ] ADAL: 2019-02-28T00:23:56.7468400Z: 77538200-e1b3-419a-b161-62771e4923b3 - LoggerBase.cs: ADAL PCL.Desktop with assembly version '3.19.6.14301', file version '3.19.50523.1839' and informational version '1ae77ee16c2204403e53d7e652ddc8f4d315cfb1' is running...
[10:23:56.762] [ 6] [INFO ] ADAL: 2019-02-28T00:23:56.7624400Z: 77538200-e1b3-419a-b161-62771e4923b3 - LoggerBase.cs: === Token Acquisition started:
CacheType: null
Authentication Target: User
, Authority Host: login.windows.net
[10:23:57.308] [ 17] [INFO ] ADAL: 2019-02-28T00:23:57.3084409Z: 77538200-e1b3-419a-b161-62771e4923b3 - LoggerBase.cs: No matching token was found in the cache
[10:23:57.308] [ 17] [INFO ] ADAL: 2019-02-28T00:23:57.3084409Z: 77538200-e1b3-419a-b161-62771e4923b3 - LoggerBase.cs: No matching token was found in the cache
[10:23:57.308] [ 17] [INFO ] ADAL: 2019-02-28T00:23:57.3084409Z: 77538200-e1b3-419a-b161-62771e4923b3 - LoggerBase.cs: No matching token was found in the cache
[10:23:57.308] [ 17] [INFO ] ADAL: 2019-02-28T00:23:57.3084409Z: 77538200-e1b3-419a-b161-62771e4923b3 - LoggerBase.cs: No matching token was found in the cache
[10:23:57.308] [ 17] [INFO ] ADAL: 2019-02-28T00:23:57.3084409Z: 77538200-e1b3-419a-b161-62771e4923b3 - LoggerBase.cs: No matching token was found in the cache
[10:23:57.308] [ 17] [INFO ] ADAL: 2019-02-28T00:23:57.3084409Z: 77538200-e1b3-419a-b161-62771e4923b3 - LoggerBase.cs: No matching token was found in the cache
[10:23:57.324] [ 17] [INFO ] ADAL: 2019-02-28T00:23:57.3240410Z: 77538200-e1b3-419a-b161-62771e4923b3 - LoggerBase.cs: Sending request to userrealm endpoint.
[10:23:58.431] [ 17] [INFO ] ADAL: 2019-02-28T00:23:58.4316429Z: 77538200-e1b3-419a-b161-62771e4923b3 - LoggerBase.cs: === Token Acquisition finished successfully. An access token was returned: Expiration Time: 28/02/2019 1:23:58 AM +00:00
[10:23:58.431] [ 6] [INFO ] Authenticate-ADAL: successfully acquired an access token. TenantId=1412e715-9c27-46bb-825a-7db972c70296, ExpiresUTC=28/02/2019 1:23:58 AM +00:00, UserInfo=administrator@fdcgympie.onmicrosoft.com, IdentityProvider=https://sts.windows.net/1412e715-9c27-46bb-825a-7db972c70296/.
[10:23:58.431] [ 6] [INFO ] AzureTenantPage: attempting to connect to Azure via AAD PowerShell.
[10:23:58.431] [ 6] [INFO ] DiscoverServiceEndpoint [AzurePowerShell]: ServiceEndpoint=ProvisioningWebService Service, AdalAuthority=HTTPS://LOGIN.WINDOWS.NET/FDCGYMPIE.ONMICROSOFT.COM, AdalResource=https://graph.windows.net.
[10:23:58.431] [ 6] [INFO ] AcquireServiceToken [AzurePowerShell]: acquiring service token.
[10:23:58.431] [ 6] [INFO ] Authenticate-ADAL [Acquiring token]: STS endpoint (HTTPS://LOGIN.WINDOWS.NET/FDCGYMPIE.ONMICROSOFT.COM), resource (https://graph.windows.net), userName (administrator@fdcgympie.onmicrosoft.com).
[10:23:58.431] [ 6] [INFO ] ADAL: 2019-02-28T00:23:58.4316429Z: 9f094cd5-4b42-4f3b-a455-d7190345c872 - LoggerBase.cs: ADAL PCL.Desktop with assembly version '3.19.6.14301', file version '3.19.50523.1839' and informational version '1ae77ee16c2204403e53d7e652ddc8f4d315cfb1' is running...
[10:23:58.431] [ 6] [INFO ] ADAL: 2019-02-28T00:23:58.4316429Z: 9f094cd5-4b42-4f3b-a455-d7190345c872 - LoggerBase.cs: === Token Acquisition started:
CacheType: null
Authentication Target: User
, Authority Host: login.windows.net
[10:23:58.431] [ 6] [INFO ] ADAL: 2019-02-28T00:23:58.4316429Z: 9f094cd5-4b42-4f3b-a455-d7190345c872 - LoggerBase.cs: An item matching the requested resource was found in the cache
[10:23:58.431] [ 6] [INFO ] ADAL: 2019-02-28T00:23:58.4316429Z: 9f094cd5-4b42-4f3b-a455-d7190345c872 - LoggerBase.cs: 59.9989599983333 minutes left until token in cache expires
[10:23:58.431] [ 6] [INFO ] ADAL: 2019-02-28T00:23:58.4316429Z: 9f094cd5-4b42-4f3b-a455-d7190345c872 - LoggerBase.cs: A matching item (access token or refresh token or both) was found in the cache
[10:23:58.431] [ 6] [INFO ] ADAL: 2019-02-28T00:23:58.4316429Z: 9f094cd5-4b42-4f3b-a455-d7190345c872 - LoggerBase.cs: === Token Acquisition finished successfully. An access token was returned: Expiration Time: 28/02/2019 1:23:58 AM +00:00
[10:23:58.431] [ 6] [INFO ] Authenticate-ADAL: successfully acquired an access token. TenantId=1412e715-9c27-46bb-825a-7db972c70296, ExpiresUTC=28/02/2019 1:23:58 AM +00:00, UserInfo=administrator@fdcgympie.onmicrosoft.com, IdentityProvider=https://sts.windows.net/1412e715-9c27-46bb-825a-7db972c70296/.
[10:23:58.447] [ 6] [INFO ] PowerShellHelper.ConnectMsolService: Connecting using an AccessToken. AzureEnvironment=0.
[10:23:58.962] [ 6] [WARN ] Failed to import module MSOnline for PowerShell Command Connect-MsolService
[10:23:59.991] [ 6] [INFO ] AzureTenantPage: successfully connected to Azure via AAD PowerShell.
[10:24:01.442] [ 6] [WARN ] Failed to import module MSOnline for PowerShell Command Get-MsolCompanyInformation
[10:24:02.097] [ 6] [INFO ] AzureTenantPage: Successfully retrieved company information for tenant 1412e715-9c27-46bb-825a-7db972c70296. Initial domain (fdcgympie.onmicrosoft.com).
[10:24:02.113] [ 6] [INFO ] AzureTenantPage: DirectorySynchronizationEnabled=False
[10:24:02.113] [ 6] [INFO ] AzureTenantPage: DirectorySynchronizationStatus=Disabled
[10:24:02.113] [ 6] [INFO ] PowershellHelper: lastDirectorySyncTime=null
[10:24:02.784] [ 6] [INFO ] AzureTenantPageViewModel.GetSynchronizedUserCount: number of synchronized users (max 500) - 1
[10:24:03.330] [ 6] [INFO ] AzureTenantPageViewModel.GetSynchronizedUserCount: number of synchronized users (max 500) - 1
[10:24:03.330] [ 6] [WARN ] Failed to import module MSOnline for PowerShell Command Get-MsolDomain
[10:24:03.907] [ 6] [INFO ] AzureTenantPage: Successfully retrieved 2 domains from the tenant.
[10:24:03.907] [ 6] [INFO ] AzureTenantPage: Calling to get the last dir sync time for the current user
[10:24:04.515] [ 6] [INFO ] DiscoverServiceEndpoint [AdminWebService]: ServiceEndpoint=Service, AdalAuthority=HTTPS://LOGIN.WINDOWS.NET/FDCGYMPIE.ONMICROSOFT.COM, AdalResource=https://graph.windows.net.
[10:24:04.531] [ 6] [INFO ] DiscoverServiceEndpoint [AdminWebService]: ServiceEndpoint=Service, AdalAuthority=HTTPS://LOGIN.WINDOWS.NET/FDCGYMPIE.ONMICROSOFT.COM, AdalResource=https://graph.windows.net.
[10:24:04.531] [ 6] [INFO ] AcquireServiceToken [AdminWebService]: acquiring service token.
[10:24:04.531] [ 6] [INFO ] Authenticate-ADAL [Acquiring token]: STS endpoint (HTTPS://LOGIN.WINDOWS.NET/FDCGYMPIE.ONMICROSOFT.COM), resource (https://graph.windows.net), userName (administrator@fdcgympie.onmicrosoft.com).
[10:24:04.531] [ 6] [INFO ] ADAL: 2019-02-28T00:24:04.5312536Z: daf99cba-f712-4b8c-a182-07a8f9fd6a80 - LoggerBase.cs: ADAL PCL.Desktop with assembly version '3.19.6.14301', file version '3.19.50523.1839' and informational version '1ae77ee16c2204403e53d7e652ddc8f4d315cfb1' is running...
[10:24:04.531] [ 6] [INFO ] ADAL: 2019-02-28T00:24:04.5312536Z: daf99cba-f712-4b8c-a182-07a8f9fd6a80 - LoggerBase.cs: === Token Acquisition started:
CacheType: null
Authentication Target: User
, Authority Host: login.windows.net
[10:24:04.531] [ 6] [INFO ] ADAL: 2019-02-28T00:24:04.5312536Z: daf99cba-f712-4b8c-a182-07a8f9fd6a80 - LoggerBase.cs: An item matching the requested resource was found in the cache
[10:24:04.531] [ 6] [INFO ] ADAL: 2019-02-28T00:24:04.5312536Z: daf99cba-f712-4b8c-a182-07a8f9fd6a80 - LoggerBase.cs: 59.89729982 minutes left until token in cache expires
[10:24:04.531] [ 6] [INFO ] ADAL: 2019-02-28T00:24:04.5312536Z: daf99cba-f712-4b8c-a182-07a8f9fd6a80 - LoggerBase.cs: A matching item (access token or refresh token or both) was found in the cache
[10:24:04.531] [ 6] [INFO ] ADAL: 2019-02-28T00:24:04.5312536Z: daf99cba-f712-4b8c-a182-07a8f9fd6a80 - LoggerBase.cs: === Token Acquisition finished successfully. An access token was returned: Expiration Time: 28/02/2019 1:23:58 AM +00:00
[10:24:04.531] [ 6] [INFO ] Authenticate-ADAL: successfully acquired an access token. TenantId=1412e715-9c27-46bb-825a-7db972c70296, ExpiresUTC=28/02/2019 1:23:58 AM +00:00, UserInfo=administrator@fdcgympie.onmicrosoft.com, IdentityProvider=https://sts.windows.net/1412e715-9c27-46bb-825a-7db972c70296/.
[10:24:05.295] [ 6] [INFO ] GetCompanyConfiguration: tenantId=(1412e715-9c27-46bb-825a-7db972c70296), IsDirSyncing=False, IsPasswordSyncing=False, DomainName=, DirSyncFeatures=0, AllowedFeatures=None.
[10:24:05.295] [ 6] [INFO ] AzureTenantPage: AdminWebService returned the company information for tenant 1412e715-9c27-46bb-825a-7db972c70296.
[10:24:05.311] [ 6] [INFO ] MicrosoftOnlinePersistedStateProvider.Save: saving the persisted state file
[10:24:05.311] [ 6] [INFO ] MicrosoftOnlinePersistedStateProvider.UpdateFileProtection: updating file protection from the persisted state file: C:\ProgramData\AADConnect\PersistedState.xml, isAddProtection: False
[10:24:05.311] [ 6] [INFO ] MicrosoftOnlinePersistedStateProvider.UpdateFileProtection: updating file protection from the persisted state file: C:\ProgramData\AADConnect\PersistedState.xml, isAddProtection: True
[10:24:05.311] [ 6] [INFO ] AzureTenantPage: Windows Azure tenant credentials validation succeeded.
[10:24:05.326] [ 1] [INFO ] Page transition from "Connect to Azure AD" [AzureTenantPageViewModel] to "Connect to AD DS" [ConfigOnPremiseCredentialsPageViewModel]
[10:24:05.342] [ 1] [INFO ] Property Username failed validation with error Enterprise Administrator credentials are required
[10:24:08.675] [ 1] [INFO ] Property Username failed validation with error The username format is incorrect. Specify the username in the format of DOMAIN\username.
[10:24:12.177] [ 1] [INFO ] Property Password failed validation with error A password is required - unless using a Virtual or Managed Service Account .
[10:24:23.130] [ 18] [INFO ] ConfigOnPremiseCredentialsPage: Validating credentials for user - FDCC\administrator
[10:24:23.145] [ 18] [INFO ] ConfigOnPremiseCredentialsPage: LogonUser succeeded for user FDCC\administrator
[10:24:23.145] [ 18] [INFO ] ActiveDirectoryProvider.GetRootDomainName: getting user root domain name
[10:24:23.195] [ 18] [INFO ] ActiveDirectoryProvider.GetRootDomainName: user root domain - FDCC.local
[10:24:23.199] [ 18] [INFO ] ActiveDirectoryProvider.IsUserGroupMember: checking if FDCC\administrator has AccountEnterpriseAdminsSid privileges in FDCC.local
[10:24:23.611] [ 18] [INFO ] ActiveDirectoryProvider.IsUserGroupMember: domain sid - S-1-5-21-1243049921-2400037562-2501132947, group sid - S-1-5-21-1243049921-2400037562-2501132947-519
[10:24:23.615] [ 18] [INFO ] ActiveDirectoryProvider.GetGroupMembershipSidsForUser: retrieving group membership SIDs from AD
[10:24:23.625] [ 18] [INFO ] ActiveDirectoryProvider.IsUserGroupMember: found membership - user is a member of the group
[10:24:23.643] [ 18] [INFO ] ValidateCredentials UseExpressSettings: The domain name 'FDCC.local' was successfully matched.
[10:24:23.652] [ 18] [INFO ] ConfigOnPremiseCredentialsPage: Validating forest
[10:24:23.662] [ 18] [INFO ] Validating forest with FQDN FDCC.local
[10:24:23.703] [ 18] [INFO ] Examining domain FDCC.local
0% complete)[10:24:23.709] [ 18] [INFO ] ValidateForest: using FDCC-SERVER.FDCC.local to validate domain FDCC.local
[10:24:23.713] [ 18] [INFO ] Successfully examined domain FDCC.local GUID:2c51e74e-4472-489a-ad6a-bf54899d4af7 DN
C=FDCC,DC=local[10:24:23.737] [ 18] [INFO ] ConfigOnPremiseCredentialsPageViewModel: Credentials will be used to administer the AD MA account (New Install).
[10:24:23.787] [ 18] [VERB ] MsolDomainExtensions.ConnectMsolService: Connecting to MSOL service.
[10:24:23.787] [ 18] [INFO ] DiscoverServiceEndpoint [AzurePowerShell]: ServiceEndpoint=ProvisioningWebService Service, AdalAuthority=HTTPS://LOGIN.WINDOWS.NET/FDCGYMPIE.ONMICROSOFT.COM, AdalResource=https://graph.windows.net.
[10:24:23.787] [ 18] [INFO ] AcquireServiceToken [AzurePowerShell]: acquiring service token.
[10:24:23.787] [ 18] [INFO ] Authenticate-ADAL [Acquiring token]: STS endpoint (HTTPS://LOGIN.WINDOWS.NET/FDCGYMPIE.ONMICROSOFT.COM), resource (https://graph.windows.net), userName (administrator@fdcgympie.onmicrosoft.com).
[10:24:23.787] [ 18] [INFO ] ADAL: 2019-02-28T00:24:23.7875743Z: ab026b8a-e055-4661-9b59-c70f0419d520 - LoggerBase.cs: ADAL PCL.Desktop with assembly version '3.19.6.14301', file version '3.19.50523.1839' and informational version '1ae77ee16c2204403e53d7e652ddc8f4d315cfb1' is running...
[10:24:23.788] [ 18] [INFO ] ADAL: 2019-02-28T00:24:23.7885743Z: ab026b8a-e055-4661-9b59-c70f0419d520 - LoggerBase.cs: === Token Acquisition started:
CacheType: null
Authentication Target: User
, Authority Host: login.windows.net
[10:24:23.788] [ 18] [INFO ] ADAL: 2019-02-28T00:24:23.7885743Z: ab026b8a-e055-4661-9b59-c70f0419d520 - LoggerBase.cs: An item matching the requested resource was found in the cache
[10:24:23.788] [ 18] [INFO ] ADAL: 2019-02-28T00:24:23.7885743Z: ab026b8a-e055-4661-9b59-c70f0419d520 - LoggerBase.cs: 59.576344475 minutes left until token in cache expires
[10:24:23.788] [ 18] [INFO ] ADAL: 2019-02-28T00:24:23.7885743Z: ab026b8a-e055-4661-9b59-c70f0419d520 - LoggerBase.cs: A matching item (access token or refresh token or both) was found in the cache
[10:24:23.788] [ 18] [INFO ] ADAL: 2019-02-28T00:24:23.7885743Z: ab026b8a-e055-4661-9b59-c70f0419d520 - LoggerBase.cs: === Token Acquisition finished successfully. An access token was returned: Expiration Time: 28/02/2019 1:23:58 AM +00:00
[10:24:23.788] [ 18] [INFO ] Authenticate-ADAL: successfully acquired an access token. TenantId=1412e715-9c27-46bb-825a-7db972c70296, ExpiresUTC=28/02/2019 1:23:58 AM +00:00, UserInfo=administrator@fdcgympie.onmicrosoft.com, IdentityProvider=https://sts.windows.net/1412e715-9c27-46bb-825a-7db972c70296/.
[10:24:23.788] [ 18] [INFO ] PowerShellHelper.ConnectMsolService: Connecting using an AccessToken. AzureEnvironment=0.
[10:24:24.156] [ 18] [WARN ] Failed to import module MSOnline for PowerShell Command Connect-MsolService
[10:24:24.698] [ 18] [WARN ] Failed to import module MSOnline for PowerShell Command Get-MsolDomain
[10:24:25.231] [ 18] [INFO ] Page transition from "Connect to AD DS" [ConfigOnPremiseCredentialsPageViewModel] to "Azure AD sign-in" [UserSignInConfigPageViewModel]
[10:24:25.237] [ 18] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Start background task Microsoft.Online.Deployment.OneADWizard.UI.WizardPages.UserSignInConfigPageViewModel.ValidateScenario in Page:"Azure AD sign-in configuration"
[10:24:25.237] [ 18] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Started Background Task Id:5192
[10:24:25.360] [ 7] [VERB ] MsolDomainExtensions.ConnectMsolService: Connecting to MSOL service.
[10:24:25.360] [ 7] [INFO ] DiscoverServiceEndpoint [AzurePowerShell]: ServiceEndpoint=ProvisioningWebService Service, AdalAuthority=HTTPS://LOGIN.WINDOWS.NET/FDCGYMPIE.ONMICROSOFT.COM, AdalResource=https://graph.windows.net.
[10:24:25.360] [ 7] [INFO ] AcquireServiceToken [AzurePowerShell]: acquiring service token.
[10:24:25.360] [ 7] [INFO ] Authenticate-ADAL [Acquiring token]: STS endpoint (HTTPS://LOGIN.WINDOWS.NET/FDCGYMPIE.ONMICROSOFT.COM), resource (https://graph.windows.net), userName (administrator@fdcgympie.onmicrosoft.com).
[10:24:25.361] [ 7] [INFO ] ADAL: 2019-02-28T00:24:25.3616643Z: 28c7320d-1f08-4396-81eb-2d4aeb72d0ee - LoggerBase.cs: ADAL PCL.Desktop with assembly version '3.19.6.14301', file version '3.19.50523.1839' and informational version '1ae77ee16c2204403e53d7e652ddc8f4d315cfb1' is running...
[10:24:25.361] [ 7] [INFO ] ADAL: 2019-02-28T00:24:25.3616643Z: 28c7320d-1f08-4396-81eb-2d4aeb72d0ee - LoggerBase.cs: === Token Acquisition started:
CacheType: null
Authentication Target: User
, Authority Host: login.windows.net
[10:24:25.361] [ 7] [INFO ] ADAL: 2019-02-28T00:24:25.3616643Z: 28c7320d-1f08-4396-81eb-2d4aeb72d0ee - LoggerBase.cs: An item matching the requested resource was found in the cache
[10:24:25.361] [ 7] [INFO ] ADAL: 2019-02-28T00:24:25.3616643Z: 28c7320d-1f08-4396-81eb-2d4aeb72d0ee - LoggerBase.cs: 59.5501263083333 minutes left until token in cache expires
[10:24:25.361] [ 7] [INFO ] ADAL: 2019-02-28T00:24:25.3616643Z: 28c7320d-1f08-4396-81eb-2d4aeb72d0ee - LoggerBase.cs: A matching item (access token or refresh token or both) was found in the cache
[10:24:25.362] [ 7] [INFO ] ADAL: 2019-02-28T00:24:25.3626644Z: 28c7320d-1f08-4396-81eb-2d4aeb72d0ee - LoggerBase.cs: === Token Acquisition finished successfully. An access token was returned: Expiration Time: 28/02/2019 1:23:58 AM +00:00
[10:24:25.362] [ 7] [INFO ] Authenticate-ADAL: successfully acquired an access token. TenantId=1412e715-9c27-46bb-825a-7db972c70296, ExpiresUTC=28/02/2019 1:23:58 AM +00:00, UserInfo=administrator@fdcgympie.onmicrosoft.com, IdentityProvider=https://sts.windows.net/1412e715-9c27-46bb-825a-7db972c70296/.
[10:24:25.362] [ 7] [INFO ] PowerShellHelper.ConnectMsolService: Connecting using an AccessToken. AzureEnvironment=0.
[10:24:25.707] [ 7] [WARN ] Failed to import module MSOnline for PowerShell Command Connect-MsolService
[10:24:26.249] [ 7] [WARN ] Failed to import module MSOnline for PowerShell Command Get-MsolDomain
[10:24:26.802] [ 1] [INFO ] UPN Suffix List
[10:24:26.802] [ 1] [INFO ] --------------------------------------------------------------------
[10:24:26.802] [ 1] [INFO ] UPN Suffix [Azure Status]
[10:24:26.802] [ 1] [INFO ] --------------------------------------------------------------------
[10:24:26.806] [ 1] [INFO ] fdcc.local [Not Added]
[10:24:26.807] [ 1] [INFO ] fdcgympie.org.au [Verified]
[10:24:26.807] [ 1] [INFO ] --------------------------------------------------------------------
[10:24:26.807] [ 7] [INFO ] UserSignInConfigPageViewModel: AD Domains: notAddedDomains 1, notVerifiedDomains 0, verifiedDomains 1
[10:24:26.807] [ 7] [INFO ] UserSignInConfigPageViewModel: Azure Domains: aadUnverifiedDomains 0, aadVerifiedDomains 1
[10:24:26.807] [ 7] [INFO ] UserSignInConfigPageViewModel: The currently selected sign-in method is PasswordHashSync
[10:24:26.810] [ 1] [WARN ] UserSignInConfigPageViewModel: Some users will not be able to sign-in to Azure AD with on-premises credentials as their UPN suffixes in AD do not have a corresponding Azure verified domain in tenant (fdcgympie.onmicrosoft.com).
[10:24:31.372] [ 1] [INFO ] Page transition from "Azure AD sign-in" [UserSignInConfigPageViewModel] to "Configure" [PerformConfigurationPageViewModel]
[10:24:31.380] [ 1] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Start background task Microsoft.Online.Deployment.OneADWizard.UI.WizardPages.PerformConfigurationPageViewModel.BackgroundInitialize in Page:"Ready to configure"
[10:24:31.381] [ 1] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Started Background Task Id:6210
[10:24:32.389] [ 12] [VERB ] PerformConfigurationPageViewModel:ExecuteAutoUpgradeCheck: context.WizardMode ExpressInstall.
[10:24:32.413] [ 12] [WARN ] DetermineAutoUpgradeState: AutoUpgrade entering ENABLED mode for express installation.
[10:24:32.413] [ 12] [VERB ] PerformConfigurationPageViewModel:ExecuteAutoUpgradeCheck: autoUpgradeState set to Enabled.
[10:24:32.418] [ 12] [INFO ] SetAutoUpgradeViaAdhealthRegistrykey: Updated SOFTWARE\Microsoft\ADHealthAgent\Sync\UpdateCheckEnabled registry value to 1
[10:24:32.420] [ 12] [INFO ] Restarting Monitoring Agent service.
[10:24:32.423] [ 12] [INFO ] ServiceControllerProvider: InvalidOperationException on serviceController.Status property means the service AzureADConnectHealthSyncMonitor was not found
[10:24:32.423] [ 12] [WARN ] Monitoring Agent service is not installed, so the service cannot be restarted.
[10:24:35.690] [ 1] [INFO ] MicrosoftOnlinePersistedStateProvider.Save: saving the persisted state file
[10:24:35.690] [ 1] [INFO ] MicrosoftOnlinePersistedStateProvider.UpdateFileProtection: updating file protection from the persisted state file: C:\ProgramData\AADConnect\PersistedState.xml, isAddProtection: False
[10:24:35.692] [ 1] [INFO ] MicrosoftOnlinePersistedStateProvider.UpdateFileProtection: updating file protection from the persisted state file: C:\ProgramData\AADConnect\PersistedState.xml, isAddProtection: True
[10:24:35.699] [ 1] [INFO ] PersistAzureAffinity: Azure affinity was previously persisted as Worldwide (0).
[10:24:35.699] [ 1] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Start background task Microsoft.Online.Deployment.OneADWizard.UI.WizardPages.PerformConfigurationPageViewModel.ExecuteADSyncConfiguration in Page:"Configuring"
[10:24:35.699] [ 1] [INFO ] ProgressWizardPageViewModel:StartProgressOperation Started Background Task Id:6921
[10:24:35.702] [ 8] [INFO ] PerformConfigurationPageViewModel.ExecuteADSyncConfiguration: Preparing to configure sync engine (WizardMode=ExpressInstall).
[10:24:35.704] [ 8] [INFO ] PerformConfigurationPageViewModel.ExecuteSyncEngineInstallCore: Preparing to install sync engine (WizardMode=ExpressInstall).
[10:24:35.707] [ 8] [INFO ] Starting Sync Engine installation
[10:24:38.693] [ 8] [INFO ] ServiceControllerProvider: InvalidOperationException on serviceController.Status property means the service ADSync was not found
[10:24:38.699] [ 8] [INFO ] ServiceControllerProvider:CreateService - serviceName:ADSync, username:FDCC\AAD_dc5a812f7347, assemblyPath:C:\Program Files\Microsoft Azure Active Directory Connect\ADSyncBootstrap.exe
[10:24:39.026] [ 8] [INFO ] ServiceControllerProvider: Processing StartService request for: ADSync
[10:24:39.027] [ 8] [VERB ] ServiceControllerProvider: Initial service status: Stopped
[10:24:39.027] [ 8] [VERB ] ServiceControllerProvider: Starting service and waiting for completion.
[10:24:39.548] [ 8] [INFO ] ServiceControllerProvider: StartService status: Running
[10:24:54.495] [ 8] [INFO ] ServiceControllerProvider: processing StopService request for: ADSync
[10:24:54.496] [ 8] [VERB ] ServiceControllerProvider: Initial service status: Running
[10:24:54.496] [ 8] [VERB ] ServiceControllerProvider: stopping service and waiting for completion.
[10:24:54.748] [ 8] [INFO ] ServiceControllerProvider: StopService status: Stopped
[10:24:54.748] [ 8] [INFO ] ServiceControllerProvider: Processing StartService request for: ADSync
[10:24:54.750] [ 8] [VERB ] ServiceControllerProvider: Initial service status: Stopped
[10:24:54.750] [ 8] [VERB ] ServiceControllerProvider: Starting service and waiting for completion.
[10:24:55.416] [ 8] [INFO ] ServiceControllerProvider: StartService status: Running
[10:24:55.714] [ 8] [INFO ] ServiceControllerProvider: processing StopService request for: ADSync
[10:24:55.714] [ 8] [VERB ] ServiceControllerProvider: Initial service status: Running
[10:24:55.714] [ 8] [VERB ] ServiceControllerProvider: stopping service and waiting for completion.
[10:24:55.966] [ 8] [INFO ] ServiceControllerProvider: StopService status: Stopped
[10:24:55.969] [ 8] [INFO ] ServiceControllerProvider
eleteService - serviceName:ADSync[10:25:05.995] [ 8] [INFO ] ServiceControllerProvider: InvalidOperationException on serviceController.Status property means the service ADSync was not found
[10:25:05.995] [ 8] [INFO ] ServiceControllerProvider
eleteService successful - serviceName:ADSync[10:25:06.002] [ 8] [INFO ] BuildMsiArguments: Setting Sync Engine MSI parameters for clean installation
[10:25:17.440] [ 8] [ERROR] PerformConfigurationPageViewModel: Caught exception while installing synchronization service.
Exception Data (Raw): System.Exception: Unable to install the Synchronization Service. Please see the event log for additional details. ---> Microsoft.Online.Deployment.Client.Framework.ProcessExecutionFailedException: Error installing msi package 'Synchronization Service.msi'. Full log is available at 'C:\ProgramData\AADConnect\Synchronization Service_Install-20190228-102506.log'.
Extracted error message:
ActionStart(Name=ProcessMachineDcomPermission,,)
MSI (s) (38:74) [10:25:15:059]: Executing op: CustomActionSchedule(Action=ProcessMachineDcomPermission,ActionType=1025,Source=BinaryData,Target=ProcessMachineDcomPermission,CustomActionData=ADMINS=ADSyncAdmins OPERATORS=ADSyncOperators BROWSE=ADSyncBrowse PASSWORDSET=ADSyncPasswordSet)
MSI (s) (38:58) [10:25:15:062]: Invoking remote custom action. DLL: C:\Windows\Installer\MSIFDA8.tmp, Entrypoint: ProcessMachineDcomPermission
CustomAction ProcessMachineDcomPermission returned actual error code 1603 (note this may not be 100% accurate if translation happened inside sandbox)
MSI (s) (38:74) [10:25:15:238]: User policy value 'DisableRollback' is 0
MSI (s) (38:74) [10:25:15:238]: Machine policy value 'DisableRollback' is 0
Action ended 10:25:15: InstallExecute.
---> Microsoft.Online.Deployment.Client.Framework.ProcessExecutionFailedException: Exception: Execution failed with errorCode: 1603.
Details:
at Microsoft.Azure.ActiveDirectory.Synchronization.Framework.ProcessAdapter.StartProcessCore(String fileName, String& processOutput, String arguments, String workingDirectory, NetworkCredential credential, Boolean loadUserProfile, Boolean hideWindow, Boolean waitForExit, Boolean traceArguments, Int32 exitCodeToIgnore)
at Microsoft.Azure.ActiveDirectory.Synchronization.Framework.MsiExecAdapter.InstallMsiPackage(String msiPackageDirectory, String msiPackageFileName, String packageOptions, String installationPath, NetworkCredential credential, String installLogFileName, Boolean extractOnly, Boolean quiet, Boolean suppressReboot)
--- End of inner exception stack trace ---
at Microsoft.Azure.ActiveDirectory.Synchronization.Framework.MsiExecAdapter.InstallMsiPackage(String msiPackageDirectory, String msiPackageFileName, String packageOptions, String installationPath, NetworkCredential credential, String installLogFileName, Boolean extractOnly, Boolean quiet, Boolean suppressReboot)
at Microsoft.Azure.ActiveDirectory.Synchronization.Setup.SynchronizationServiceSetupTask.InstallSynchronizationService(String pathToMsiFiles, String msiFileName, String installationPath, String sqlServerName, String sqlInstanceName, Boolean useInstallPathForDBFiles, IDictionary`2 syncServiceGroups, SyncServiceAccount syncServiceAccount, String logFilePath)
at Microsoft.Azure.ActiveDirectory.Synchronization.Setup.SynchronizationServiceSetupTask.InstallCore(String logFilePath, String logFileSuffix)
at Microsoft.Azure.ActiveDirectory.Synchronization.Framework.ActionExecutor.ExecuteWithSetupResultsStatus(SetupAction action, String description, String logFileName, String logFileSuffix)
at Microsoft.Azure.ActiveDirectory.Synchronization.Setup.SetupBase.Install()
--- End of inner exception stack trace ---
at Microsoft.Azure.ActiveDirectory.Synchronization.Setup.SetupBase.ThrowSetupTaskFailureException(String exceptionFormatString, String taskName, Exception innerException)
at Microsoft.Azure.ActiveDirectory.Synchronization.Setup.SetupBase.Install()
at Microsoft.Online.Deployment.OneADWizard.Runtime.Stages.InstallSyncEngineStage.ExecuteInstallCore(ISyncEngineInstallContext syncEngineInstallContext, ProgressChangedEventHandler progressChangesEventHandler)
at Microsoft.Online.Deployment.OneADWizard.Runtime.Stages.InstallSyncEngineStage.ExecuteInstall(ISyncEngineInstallContext syncEngineInstallContext, ProgressChangedEventHandler progressChangesEventHandler)
at Microsoft.Online.Deployment.OneADWizard.UI.WizardPages.PerformConfigurationPageViewModel.ExecuteSyncEngineInstallCore(AADConnectResult& result)
[10:25:23.536] [ 1] [INFO ] Opened log file at path C:\ProgramData\AADConnect\trace-20190228-102301.log
Continue reading...