Jump to content
Welcome to our new Citrix community!
  • 1

User layer POC setup very slow logon around 3.5 minutes


GPS Windows

Question

Wondering if someone can help here. I'm really excited about using app layering with full user layers but have come across a very slow logon issue. We are using SSDs for storage (Standard windows SMB share), we have 10GB network and high end VMWare clusters. VMs have been give 8vCPUs and i've followed Carl Stalhood's guides to get me to this point. The infrastructure as described above should be plenty to at least accommodate a sub 1 minute logon. I am not getting any indicator of why it's taking so long to logon. The user layer log gives me no detail from programdata\unidesk. 

 

I can see the delay in the log however. The second I begin the launch process, the user layer disk is created in the share as expected at around 64MB and then takes a while to get to around 3GB. On second logon, the logon process is just as slow, so it's not a new profile thing. Citrix support have not been very helpful to be honest and i'm close to abandoning the solution, which is a shame as I don't think it's the problem, but could be wrong. Please do let me know if you need any more information. OS is Win10 20H2.
 

2021-11-18 17:51:57,429 INFO 6 LayeringWindowsService: *** Citrix Layering Service has started as a service. build 21.10.0.5 ***
2021-11-18 17:51:57,475 INFO 6 Program: *** Citrix Layering Service has started as a service. build 21.10.0.5 ***
2021-11-18 17:51:58,054 INFO 6 ScheduledEventsInstaller: registering Scheduled Events
2021-11-18 17:51:58,491 INFO 6 HandlerInstaller: Installing Command/Query Handlers
2021-11-18 17:51:59,288 INFO 6 MachineIdentityService: Reading Elastic Layer Mode...
2021-11-18 17:51:59,335 INFO 6 MachineIdentityService: Elastic Layering Mode = Desktop
2021-11-18 17:51:59,350 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:51:59,350 INFO 6 MachineIdentitySelector: Selected 'VdiDynamicLayeringService' for 'IDynamicLayeringService' from 2 options
2021-11-18 17:51:59,350 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:51:59,350 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,194 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,194 INFO 6 MachineIdentitySelector: Selected 'VdiDynamicLayeringService' for 'IDynamicLayeringService' from 2 options
2021-11-18 17:52:00,194 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,194 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,194 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,194 INFO 6 MachineIdentitySelector: Selected 'VdiDynamicLayeringService' for 'IDynamicLayeringService' from 2 options
2021-11-18 17:52:00,194 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,194 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,194 INFO 6 MachineIdentitySelector: Selected 'VdiDynamicLayeringService' for 'IDynamicLayeringService' from 2 options
2021-11-18 17:52:00,194 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,194 INFO 6 MachineIdentitySelector: Selected 'VdiDynamicLayeringService' for 'IDynamicLayeringService' from 2 options
2021-11-18 17:52:00,194 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,210 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,210 INFO 6 MachineIdentitySelector: Selected 'VdiDynamicLayeringService' for 'IDynamicLayeringService' from 2 options
2021-11-18 17:52:00,210 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,210 INFO 6 MachineIdentitySelector: Selected 'VdiDynamicLayeringService' for 'IDynamicLayeringService' from 2 options
2021-11-18 17:52:00,210 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,210 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,210 INFO 6 MachineIdentitySelector: Selected 'VdiDynamicLayeringService' for 'IDynamicLayeringService' from 2 options
2021-11-18 17:52:00,210 INFO 6 MachineIdentitySelector: Selected 'VdiDynamicLayeringService' for 'IDynamicLayeringService' from 2 options
2021-11-18 17:52:00,210 INFO 6 MachineIdentitySelector: Selected 'VdiDynamicLayeringService' for 'IDynamicLayeringService' from 2 options
2021-11-18 17:52:00,257 INFO 6 MachineIdentitySelector: Selected 'VdiDynamicLayeringService' for 'IDynamicLayeringService' from 2 options
2021-11-18 17:52:00,288 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,444 INFO 6 MachineIdentitySelector: Selected 'UserLayerInitializationService' for 'IUserLayerInitializationService' from 2 options
2021-11-18 17:52:00,491 INFO 6 UserLayerService: Repopulated userLayerIds with 0 entries
2021-11-18 17:52:00,522 INFO 6 MachineIdentitySelector: Selected 'Windows10UserLayerPostMountService' for 'IUserLayerPostMountService' from 3 options
2021-11-18 17:52:00,522 INFO 6 MachineIdentitySelector: Selected 'UserLayerMetadataService' for 'IUserLayerMetadataService' from 2 options
2021-11-18 17:52:00,616 INFO 6 MachineIdentitySelector: Selected 'MoveFilesDatabaseService' for 'IMoveFilesDatabaseService' from 1 options
2021-11-18 17:52:00,616 INFO 6 MachineIdentitySelector: Selected 'UserLayerMetadataService' for 'IUserLayerMetadataService' from 2 options
2021-11-18 17:52:00,616 INFO 6 MachineIdentitySelector: Selected 'UserLayerRepairService' for 'IUserLayerRepairService' from 2 options
2021-11-18 17:52:00,616 INFO 6 UserLayerRepairConfigFileService: UserLayerRepairConfigFileService starting
2021-11-18 17:52:00,616 INFO 6 MachineIdentitySelector: Selected 'UserLayerUpdateService' for 'IUserLayerUpdateService' from 2 options
2021-11-18 17:52:00,616 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,616 INFO 6 MachineIdentitySelector: Selected 'UserLayerMetadataService' for 'IUserLayerMetadataService' from 2 options
2021-11-18 17:52:00,647 INFO 6 MachineIdentitySelector: Selected 'UserLayerProfileCompatService' for 'IUserLayerProfileCompatService' from 2 options
2021-11-18 17:52:00,647 INFO 6 MachineIdentitySelector: Selected 'UserLayerProfileCompatService' for 'IUserLayerProfileCompatService' from 2 options
2021-11-18 17:52:00,647 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,647 INFO 6 VdiDynamicLayeringService: Created VdiDynamicLayeringService
2021-11-18 17:52:00,663 INFO 6 MachineIdentitySelector: Selected 'IUserRestrictionService' for 'IUserRestrictionService' from 2 options
2021-11-18 17:52:00,663 INFO 6 MachineIdentitySelector: Selected 'IUserRestrictionService' for 'IUserRestrictionService' from 2 options
2021-11-18 17:52:00,663 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,663 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,679 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,679 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,694 INFO 6 MachineIdentitySelector: Selected 'IUserRestrictionService' for 'IUserRestrictionService' from 2 options
2021-11-18 17:52:00,710 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,710 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,710 INFO 6 MachineIdentitySelector: Selected 'UserLayerProfileCompatService' for 'IUserLayerProfileCompatService' from 2 options
2021-11-18 17:52:00,710 INFO 6 MachineIdentitySelector: Selected 'IUserRestrictionService' for 'IUserRestrictionService' from 2 options
2021-11-18 17:52:00,741 INFO 6 MachineIdentitySelector: Selected 'VdiDynamicLayeringService' for 'IDynamicLayeringService' from 2 options
2021-11-18 17:52:00,741 INFO 6 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:52:00,741 INFO 6 ServiceLifecycleManager: Creating start up task...
2021-11-18 17:52:00,757 INFO 6 ServiceLifecycleManager: Start up task is running...
2021-11-18 17:52:00,757 INFO 6 ServiceLifecycleManager: Attempting to request the required registry and volume privileges
2021-11-18 17:52:00,772 INFO 6 DynamicLayeringService: Cleared the configured volumes
2021-11-18 17:52:00,772 INFO 6 ServiceLifecycleManager: Loading ServiceLifeCycleManager .NET assemblies
2021-11-18 17:52:01,507 INFO 6 ServiceLifecycleManager: Loaded 34 .NET assemblies
2021-11-18 17:52:01,507 INFO 6 SessionEventRegistrar: Registering for logon/logoff events.
2021-11-18 17:52:01,522 INFO 6 SessionEventRegistrar: Registered for logon/logoff events successfully.
2021-11-18 17:52:01,569 INFO 6 LayerConfigService: Starting volume checker...
2021-11-18 17:52:01,585 INFO 6 ActiveDirectoryInfoService: Starting AD Machine group polling
2021-11-18 17:52:01,663 WARN 6 ActiveDirectoryInfoService: Error encountered while refreshing machine groups from AD: System.DirectoryServices.AccountManagement.PrincipalServerDownException: The server could not be contacted. ---> System.DirectoryServices.Protocols.LdapException: The LDAP server is unavailable.
   at System.DirectoryServices.Protocols.LdapConnection.Connect()
   at System.DirectoryServices.Protocols.LdapConnection.SendRequestHelper(DirectoryRequest request, Int32& messageID)
   at System.DirectoryServices.Protocols.LdapConnection.SendRequest(DirectoryRequest request, TimeSpan requestTimeout)
   at System.DirectoryServices.AccountManagement.PrincipalContext.ReadServerConfig(String serverName, ServerProperties& properties)
   --- End of inner exception stack trace ---
   at System.DirectoryServices.AccountManagement.PrincipalContext.ReadServerConfig(String serverName, ServerProperties& properties)
   at System.DirectoryServices.AccountManagement.PrincipalContext.DoServerVerifyAndPropRetrieval()
   at System.DirectoryServices.AccountManagement.PrincipalContext..ctor(ContextType contextType, String name, String container, ContextOptions options, String userName, String password)
   at System.DirectoryServices.AccountManagement.PrincipalContext..ctor(ContextType contextType)
   at Unidesk.Guest.LayeringService.Services.AdPrincipalContext.<>c.<.ctor>b__3_0()
   at System.Lazy`1.CreateValue()
   at System.Lazy`1.LazyInitValue()
   at Unidesk.Guest.LayeringService.Services.AdFindBy.FindComputerByNameInDomain(IAdPrincipalContext ctx, String name)
   at Unidesk.Guest.LayeringService.Services.ActiveDirectoryQueryService.GetSecurityGroupSidsForComputer(String computerName)
   at Unidesk.Guest.LayeringService.Services.ActiveDirectoryInfoService.OnRefreshLocalMachineGroups(Object sender, RefreshLocalMachineGroupsEvent eventObj)
2021-11-18 17:52:01,694 WARN 6 ActiveDirectoryInfoService: Unable to refresh machine groups from Active Directory.
2021-11-18 17:52:01,772 INFO 6 DynamicLayeringService: SCCMServiceAutoStartDisabled=False
2021-11-18 17:52:01,772 INFO 6 DynamicLayeringService: DoesServiceExist(CcmExec)=True
2021-11-18 17:52:01,772 INFO 6 DynamicLayeringService: GetStartType(CcmExec)=Automatic
2021-11-18 17:52:01,788 INFO 6 DynamicLayeringService: IsServiceRunning=False
2021-11-18 17:52:01,788 INFO 6 DynamicLayeringService: Attempting to start CcmExec service.
2021-11-18 17:52:01,788 INFO 12 WindowsServiceControl: Starting 'CcmExec' service, not waiting for status
2021-11-18 17:52:02,038 INFO 12 DynamicLayeringService: Started CcmExec service.
2021-11-18 17:52:32,585 INFO 8 ActiveDirectoryInfoService: Machine Group SID(s): domain.local\Domain Computers
2021-11-18 17:52:32,585 INFO 8 ActiveDirectoryInfoService: Access to Active Directory restored. Machine groups refreshed.
2021-11-18 17:52:32,585 INFO 8 ActiveDirectoryInfoService: Access to Active Directory restored. Machine groups refreshed.
2021-11-18 17:55:07,459 INFO 13 RequestService: Starting request 1 on behalf of domain.local\johnsmith
2021-11-18 17:55:07,490 INFO 13 SessionEventHandler: Received logon event
2021-11-18 17:55:07,490 INFO 13 SessionEventHandler: logging in user domain.local\johnsmith (S-1-5-21-73586283-2111687655-839522115-312299)
2021-11-18 17:55:07,506 INFO 13 LogonInfoService: User domain.local\johnsmith is not a local user
2021-11-18 17:55:07,506 INFO 13 LogonInfoService: User domain.local\johnsmith (S-1-5-21-73586283-2111687655-839522115-312299) is an elastic layer user. Elastic layers are enabled.
2021-11-18 17:55:07,506 INFO 13 DynamicLayeringService: Logging in user domain.local\johnsmith (S-1-5-21-73586283-2111687655-839522115-312299)
2021-11-18 17:55:07,506 INFO 13 VdiDynamicLayeringService: Attach user disk for sid 'S-1-5-21-73586283-2111687655-839522115-312299'
2021-11-18 17:55:07,521 INFO 13 UserLayerAttachDetachService: Open user disk for VDI
2021-11-18 17:55:07,521 INFO 13 UserLayerService: Finding or creating user layer for user domain.local\johnsmith
2021-11-18 17:55:07,521 INFO 13 WindowsId: Impersonating domain.local\johnsmith
2021-11-18 17:55:07,912 INFO 13 ShareDataSource: Splitting default share Default Location(3735552)
2021-11-18 17:55:07,943 INFO 13 WindowsId: Finished impersonating domain.local\johnsmith
2021-11-18 17:55:07,959 INFO 13 WindowsId: Impersonating domain.local\johnsmith
2021-11-18 17:55:07,959 INFO 13 PathGeneratorService: Using Path from Share.json = '\\ploninengs04\Test_Share' from ULayer settings
2021-11-18 17:55:07,959 INFO 13 Impersonator: Entering Self impersonation context
2021-11-18 17:55:07,959 INFO 13 WindowsId: Impersonating NT AUTHORITY\SYSTEM
2021-11-18 17:55:07,959 INFO 13 MachineIdentityService: ImageInfo path: C:\Program Files\Unidesk\Etc\ImageInfo.json
2021-11-18 17:55:07,974 INFO 13 MachineIdentityService: ImageInfo path: {"OsLayerId":1769473,"OsLayerGuid":"a7e5de37-c60b-4062-bf12-47b6594f3fd8","OsLayerName":"Windows 10"}
2021-11-18 17:55:07,974 INFO 13 MachineIdentityService: ImageInfo: 1769473, Windows 10
2021-11-18 17:55:07,974 INFO 13 WindowsId: Finished impersonating NT AUTHORITY\SYSTEM
2021-11-18 17:55:07,974 INFO 13 UserLayerService: Searching for user layer for 'domain.local\johnsmith' using pattern '\\ploninengs04\Test_Share\Users\domain.local_johnsmith\1B0001_*\johnsmith.vhd'
2021-11-18 17:55:08,006 INFO 13 QuotaService: Has Quota: False: User Free: 24299692032  User Total: 64300773376  Disk Free: 24299692032
2021-11-18 17:55:08,021 INFO 13 LayerSettingsService: Using DefaultUserLayerSizeInGb = '10' from ULayer settings.
2021-11-18 17:55:08,021 INFO 13 WindowsId: Finished impersonating domain.local\johnsmith
2021-11-18 17:55:08,021 INFO 13 UserLayerService: Returning user layer path \\ploninengs04\Test_Share\Users\domain.local_johnsmith\1B0001_Windows 10\johnsmith.vhd
2021-11-18 17:55:08,021 INFO 13 UserLayerAttachDetachService: Opening user disk '\\ploninengs04\Test_Share\Users\domain.local_johnsmith\1B0001_Windows 10\johnsmith.vhd' for 'domain.local\johnsmith'
2021-11-18 17:55:08,021 INFO 13 UserLayerAttachDetachService: Open with lock timout '120' seconds, '5' retries if locked
2021-11-18 17:55:08,021 INFO 13 WindowsId: Impersonating domain.local\johnsmith
2021-11-18 17:55:08,115 INFO 13 UserLayerAttachDetachService: ExtendedDiagRetentionCount=5
2021-11-18 17:55:08,131 INFO 13 WindowsId: Finished impersonating domain.local\johnsmith
2021-11-18 17:55:08,146 INFO 13 UserLayerExpandService: User layer current size 10737418240, configured size 10737418240, not expanding
2021-11-18 17:55:08,146 INFO 13 VirtualDiskService: Attaching virtual disk '\\ploninengs04\Test_Share\Users\domain.local_johnsmith\1B0001_Windows 10\johnsmith.vhd'
2021-11-18 17:55:08,599 INFO 13 WindowsId: Impersonating domain.local\johnsmith
2021-11-18 17:55:08,615 INFO 13 WindowsId: Finished impersonating domain.local\johnsmith
2021-11-18 17:55:08,615 INFO 13 PathGeneratorService: Using Path from Share.json = '\\ploninengs04\Test_Share' from ULayer settings
2021-11-18 17:55:08,615 INFO 13 UserLayerRepairService: Repair User Layer: user 'domain.local\johnsmith', search pattern '\\ploninengs04\Test_Share\Users\domain.local_johnsmith\1B0001_*\UserLayerRepair*.json'
2021-11-18 17:55:08,615 INFO 13 WindowsId: Impersonating domain.local\johnsmith
2021-11-18 17:55:08,615 INFO 13 WindowsId: Finished impersonating domain.local\johnsmith
2021-11-18 17:55:08,615 INFO 13 UserLayerRepairService: Repair User Layer: no layer revisions configured for repair
2021-11-18 17:55:08,646 INFO 13 MachineIdentityService: currentVersionKey BuildLabEx '19041.1.amd64fre.vb_release.191206-1406', UBR '1165', CurrentBuild '19042'
2021-11-18 17:55:08,646 INFO 13 MachineIdentityService: Updating BuildLabEx major version '19041' to CurrentBuild '19042'
2021-11-18 17:55:08,646 INFO 13 MachineIdentityService: Updating BuildLabEx minor version '1' to UBR '1165'
2021-11-18 17:55:08,646 INFO 13 MachineIdentityService: Original BuildLabEx '19041.1.amd64fre.vb_release.191206-1406', updated '19042.1165.amd64fre.vb_release.191206-1406'
2021-11-18 17:55:08,646 INFO 13 Windows10UserLayerPostMountService: Mounting Existing ulayer \\?\Volume{14e78411-0000-0000-007e-000000000000}\ at C:\Program Files\Unidesk\Etc\uep_mount\, format version 1
2021-11-18 17:55:09,709 INFO 13 Windows10UserLayerPostMountService: Scrub dir C:\ProgramData\Microsoft\Search\Data\Applications\Windows
2021-11-18 17:55:09,709 INFO 13 Windows10UserLayerPostMountService: Adding ResetCache value to Ntuser_Temp\SOFTWARE\Microsoft\Windows\CurrentVersion\ImmersiveShell\StateStore
2021-11-18 17:55:09,724 INFO 13 Windows10UserLayerPostMountService: Windows version is 19042.1165.amd64fre.vb_release.191206-1406
2021-11-18 17:55:09,724 INFO 13 Windows10UserLayerPostMountService: User layer format 1 setup for user domain.local\johnsmith has completed.
2021-11-18 17:55:09,959 INFO 13 WindowsSearchIndexService: Preparing for user layer path \\?\Volume{14e78411-0000-0000-007e-000000000000}\
2021-11-18 17:55:09,959 INFO 13 UserServicesService: Processing user layer services
2021-11-18 17:55:09,959 INFO 13 UserServicesService: Mounting user layer '\\?\Volume{14e78411-0000-0000-007e-000000000000}\' at 'c:\Program Files\Unidesk\Etc\uep_mount\'
2021-11-18 17:55:09,959 INFO 13 UserServicesService: Removing mount point 'c:\Program Files\Unidesk\Etc\uep_mount\'
2021-11-18 17:55:10,178 INFO 13 UserServicesService: Discovered 1 user layer services.
2021-11-18 17:55:10,193 INFO 13 UserServicesService: DismountDisk: 'c:\Program Files\Unidesk\Etc\uep_mount\'
2021-11-18 17:55:10,193 INFO 13 UserServicesService: Removing mount point 'c:\Program Files\Unidesk\Etc\uep_mount\'
2021-11-18 17:55:10,256 INFO 13 UserLayerProfileCompatService: Setting user-profile disposition to 'existing'
2021-11-18 17:55:10,256 INFO 13 UniRsdConfigurationService: Configuring unirsd
2021-11-18 17:55:10,303 INFO 13 UnifltrConfigurationService: Configuring unifltr and unikcdrv: user disk UserLayerVolume
2021-11-18 17:55:10,303 INFO 13 UnifltrConfigurationService: Configure user disk 0: UserLayerVolume
2021-11-18 17:55:10,349 INFO 13 CompositingManager: Beginning compositing app layers: user domain.local\johnsmith (S-1-5-21-73586283-2111687655-839522115-312299)
2021-11-18 17:55:10,349 INFO 13 WindowsId: Impersonating domain.local\johnsmith
2021-11-18 17:55:10,396 INFO 13 ElasticLayerDataSource: Compositing phase settings: Default=PostLogon, Max=OnDemand
2021-11-18 17:55:10,428 INFO 13 ElasticLayerOnDemandFilter: Filtering all OnDemand layers
2021-11-18 17:55:10,443 INFO 13 WindowsId: Finished impersonating domain.local\johnsmith
2021-11-18 17:55:10,474 INFO 13 TaskScheduleService: Task tree key, found 'HKLM\Software\Microsoft\Windows NT\CurrentVersion\Schedule\TaskCache\Tree'
2021-11-18 17:55:10,506 INFO 13 WindowsServiceControl: Starting 'unirsd' service, waiting for status
2021-11-18 17:55:11,756 INFO 13 UserLayerProfileCompatService: Setting 'ALProfileCatalog' for user profile 'SOFTWARE\Microsoft\Windows NT\CurrentVersion\ProfileList\S-1-5-21-73586283-2111687655-839522115-312299'
2021-11-18 17:55:11,771 INFO 13 VdiDynamicLayeringService: Current value of Windows Search 'SetupCompletedSuccessfully' flag: 1
2021-11-18 17:55:11,787 INFO 13 CompositingManager: Completing compositing phase PreLogon for user domain.local\johnsmith (S-1-5-21-73586283-2111687655-839522115-312299)
2021-11-18 17:55:11,787 INFO 13 CompositingManager: Waiting for 0 layers to be ready ()
2021-11-18 17:55:11,787 INFO 13 CompositingManager: Configuring layers
2021-11-18 17:55:11,803 INFO 13 CompositingManager: Layering Phase PreLogon Complete
---------== Layering Results ==-----------
User: domain.local\johnsmith (S-1-5-21-73586283-2111687655-839522115-312299), Machine: LONVA00002, ULayer: 21.10.0.5
Assigned Layers:
------------------------------------------
2021-11-18 17:55:11,803 INFO 13 CompositingManager: User domain.local\johnsmith logged in successfully.  No elastic layers were attached.
2021-11-18 17:55:11,834 INFO 13 WindowsServiceControl: Starting 'uniservice' service, waiting for status
2021-11-18 17:55:12,396 INFO 13 WindowsServiceControl: Starting 'StateRepository' service, not waiting for status
2021-11-18 17:55:12,506 INFO 13 WindowsServiceControl: Starting 'WpnService' service, not waiting for status
2021-11-18 17:55:12,584 INFO 13 WindowsServiceControl: Service 'Bits' already running
2021-11-18 17:55:12,584 INFO 13 WindowsServiceControl: Starting 'AppReadiness' service, not waiting for status
2021-11-18 17:55:12,646 INFO 13 WindowsServiceControl: Starting 'EntAppSvc' service, not waiting for status
2021-11-18 17:55:12,709 INFO 13 WindowsServiceControl: Starting 'msiServer' service, not waiting for status
2021-11-18 17:55:12,990 INFO 13 WindowsServiceControl: Service 'wuauserv' already running
2021-11-18 17:55:12,990 INFO 13 WindowsServiceControl: Starting 'DoSvc' service, not waiting for status
2021-11-18 17:55:13,053 INFO 13 WindowsServiceControl: Starting 'StorSvc' service, not waiting for status
2021-11-18 17:55:13,131 INFO 13 WindowsServiceControl: Starting 'CamSvc' service, not waiting for status
2021-11-18 17:55:13,209 INFO 13 WindowsServiceControl: Starting 'AppxSvc' service, not waiting for status
2021-11-18 17:55:15,068 INFO 13 LogonInfoService: User domain.local\johnsmith is not a local user
2021-11-18 17:55:15,068 INFO 13 SessionEventHandler: Finished processing logon event
2021-11-18 17:55:15,068 INFO 13 RequestService: Ending request 1 started on behalf of domain.local\johnsmith
2021-11-18 17:56:05,037 INFO 14 RequestService: Starting request 2 on behalf of domain.local\johnsmith
2021-11-18 17:56:05,053 INFO 14 HandlerHelper: Processing Query LogonStatusQuery->LogonStatusResult
2021-11-18 17:56:05,068 INFO 14 MachineIdentitySelector: Selected 'WindowsSearchIndexService' for 'IWindowsSearchIndexService' from 1 options
2021-11-18 17:56:05,068 INFO 14 LogonInfoService: User domain.local\johnsmith is not a local user
2021-11-18 17:56:05,068 INFO 14 LogonInfoService: User domain.local\johnsmith (S-1-5-21-73586283-2111687655-839522115-312299) is an elastic layer user. Elastic layers are enabled.
2021-11-18 17:56:05,068 INFO 14 CompositingManager: Completing compositing phase PostLogon for user domain.local\johnsmith (S-1-5-21-73586283-2111687655-839522115-312299)
2021-11-18 17:56:05,068 INFO 14 CompositingManager: Waiting for 0 layers to be ready ()
2021-11-18 17:56:05,068 INFO 14 CompositingManager: Configuring layers
2021-11-18 17:56:05,068 INFO 14 CompositingManager: Layering Phase PostLogon Complete
---------== Layering Results ==-----------
User: domain.local\johnsmith (S-1-5-21-73586283-2111687655-839522115-312299), Machine: LONVA00002, ULayer: 21.10.0.5
Assigned Layers:
------------------------------------------
2021-11-18 17:56:05,068 INFO 14 CompositingManager: User domain.local\johnsmith logged in successfully.  No elastic layers were attached.
2021-11-18 17:56:05,068 INFO 14 CompositingManager: Calling FinishBuildingSearchEntryList()
2021-11-18 17:56:05,084 INFO 14 WindowsSearchIndexService: Enabling and starting the Windows Search service
2021-11-18 17:56:05,084 INFO 14 WindowsSearchIndexService: Windows Search Index service work completed
2021-11-18 17:56:05,084 INFO 14 CompositingManager: Calling FinishBuildingFontEntryList()
2021-11-18 17:56:05,084 INFO 14 CompositingManager: Calling RefreshSchedule()
2021-11-18 17:56:05,084 INFO 14 TaskScheduleService: Processing user layer tasks
2021-11-18 17:56:05,084 INFO 14 TaskScheduleService: Task tree key, found 'HKLM\RSD_UepMount\Registry\Machine\Software\Microsoft\Windows NT\CurrentVersion\Schedule\TaskCache\Tree'
2021-11-18 17:56:05,099 INFO 14 TaskScheduleService: Adding task 'SensorFramework-LogonTask-{100ee514-48c8-f419-6760-6fb8cb2767cd}' found on 'user layer'
2021-11-18 17:56:05,099 INFO 14 TaskScheduleService: Adding task '{A7719E0F-10DB-4640-AD8C-490CC6AD5202}' found on 'user layer'
2021-11-18 17:56:05,115 INFO 14 TaskScheduleService: Adding task 'PushLaunch' found on 'user layer'
2021-11-18 17:56:05,115 INFO 14 TaskScheduleService: Adding task 'PushRenewal' found on 'user layer'
2021-11-18 17:56:05,115 INFO 14 TaskScheduleService: Adding task 'OS Edition Upgrade event listener created by enrollment client' found on 'user layer'
2021-11-18 17:56:05,115 INFO 14 TaskScheduleService: Adding task 'Passport for Work alert created by enrollment client' found on 'user layer'
2021-11-18 17:56:05,115 INFO 14 TaskScheduleService: Adding task 'Provisioning initiated session' found on 'user layer'
2021-11-18 17:56:05,115 INFO 14 TaskScheduleService: Adding task 'PushLaunch' found on 'user layer'
2021-11-18 17:56:05,115 INFO 14 TaskScheduleService: Adding task 'PushRenewal' found on 'user layer'
2021-11-18 17:56:05,115 INFO 14 TaskScheduleService: Adding task 'Schedule #1 created by enrollment client' found on 'user layer'
2021-11-18 17:56:05,115 INFO 14 TaskScheduleService: Adding task 'Schedule #2 created by enrollment client' found on 'user layer'
2021-11-18 17:56:05,115 INFO 14 TaskScheduleService: Adding task 'Schedule #3 created by enrollment client' found on 'user layer'
2021-11-18 17:56:05,115 INFO 14 TaskScheduleService: Adding task 'Schedule created by enrollment client for renewal of certificate warning' found on 'user layer'
2021-11-18 17:56:05,115 INFO 14 TaskScheduleService: Adding task 'Schedule to run OMADMClient by client' found on 'user layer'
2021-11-18 17:56:05,131 INFO 14 TaskScheduleService: Adding task 'Schedule to run OMADMClient by server' found on 'user layer'
2021-11-18 17:56:05,131 INFO 14 TaskScheduleService: Adding task 'Win10 S Mode event listener created by enrollment client' found on 'user layer'
2021-11-18 17:56:05,131 INFO 14 TaskScheduleService: Adding task 'Intune Management Extension Health Evaluation' found on 'user layer'
2021-11-18 17:56:05,131 INFO 14 UserServicesService: User services startup work completed
2021-11-18 17:56:05,131 INFO 14 HandlerHelper: Finished Query LogonStatusQuery->LogonStatusResult
2021-11-18 17:56:05,131 INFO 14 RequestService: Ending request 2 started on behalf of domain.local\johnsmith
2021-11-18 17:56:05,146 INFO 3 UserServicesService: Trying to create and start the service 'IntuneManagementExtension'.
2021-11-18 17:56:05,162 INFO 3 UserServicesService: Successfully created the service 'IntuneManagementExtension'.
2021-11-18 17:56:05,162 INFO 3 WindowsServiceControl: Starting 'IntuneManagementExtension' service, waiting for status
2021-11-18 17:56:05,771 ERROR 4 TaskScheduleService: Unexpected error while handling a windows task:'Microsoft\Windows\EnterpriseMgmt\A3AF258B-7381-4F5D-BED2-96CD4257E499\PushRenewal' file:'C:\Windows\System32\Tasks\Microsoft\Windows\EnterpriseMgmt\A3AF258B-7381-4F5D-BED2-96CD4257E499\PushRenewal'.
System.IO.FileNotFoundException: The system cannot find the file specified. (Exception from HRESULT: 0x80070002)
   at Unidesk.Guest.LayeringService.Services.TaskScheduleServiceSupport.ITaskService.GetFolder(String Path)
   at Castle.Proxies.Invocations.ITaskService_GetFolder.InvokeMethodOnTarget()
   at Castle.DynamicProxy.AbstractInvocation.Proceed()
   at Unidesk.Guest.Core.Facilities.ComObject.ScopedComObjectInterceptor.Intercept(IInvocation invocation)
   at Castle.DynamicProxy.AbstractInvocation.Proceed()
   at Castle.Proxies.ITaskServiceProxy.GetFolder(String Path)
   at Unidesk.Guest.LayeringService.Services.TaskScheduleService.UpdateTaskFromFile(ITaskService taskService, String taskXmlFile, String taskFullName)
2021-11-18 17:56:05,787 ERROR 4 TaskScheduleService: Unexpected error while handling a windows task:'Microsoft\Windows\EnterpriseMgmt\A3AF258B-7381-4F5D-BED2-96CD4257E499\PushLaunch' file:'C:\Windows\System32\Tasks\Microsoft\Windows\EnterpriseMgmt\A3AF258B-7381-4F5D-BED2-96CD4257E499\PushLaunch'.
System.IO.FileNotFoundException: The system cannot find the file specified. (Exception from HRESULT: 0x80070002)
   at Unidesk.Guest.LayeringService.Services.TaskScheduleServiceSupport.ITaskService.GetFolder(String Path)
   at Castle.Proxies.Invocations.ITaskService_GetFolder.InvokeMethodOnTarget()
   at Castle.DynamicProxy.AbstractInvocation.Proceed()
   at Unidesk.Guest.Core.Facilities.ComObject.ScopedComObjectInterceptor.Intercept(IInvocation invocation)
   at Castle.DynamicProxy.AbstractInvocation.Proceed()
   at Castle.Proxies.ITaskServiceProxy.GetFolder(String Path)
   at Unidesk.Guest.LayeringService.Services.TaskScheduleService.UpdateTaskFromFile(ITaskService taskService, String taskXmlFile, String taskFullName)
2021-11-18 17:56:08,693 INFO 3 UserServicesService: Successfully started the service 'IntuneManagementExtension'.
 

Link to comment

0 answers to this question

Recommended Posts

There have been no answers to this question yet

Create an account or sign in to comment

You need to be a member in order to leave a comment

Create an account

Sign up for a new account in our community. It's easy!

Register a new account

Sign in

Already have an account? Sign in here.

Sign In Now
×
×
  • Create New...