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

Logon perf issue: Fslogix profile load takes 25-30 seconds


John Morrissey1709152766

Question

Hi all,

New environment running VAD 1912 LTSR CU1 - Windows 10 1909 VDI

 

Issue seeing "waiting for fslogix app services" on logon taking 25-30 seconds - as new to environment what would be a normal benchmark? am using office container enabled as well. Have put in italics and bold below around the point most of the processing occurs but there is little interaction with the logs - anyone know what is going on around that point?

 

07:31:49.595][tid:000012f0.0000193c][INFO]           ===== Begin Session:  LoadProfile: <user>
[07:31:49.595][tid:000012f0.0000193c][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\Enabled.  Data: 1
[07:31:49.595][tid:000012f0.0000193c][INFO]             User: <user>. SID: S-1-5-21-137981764-1709787988-231145771-144716.
[07:31:49.595][tid:000012f0.0000193c][INFO]             Include group SID: S-1-5-21-740431820-361567022-3868551771-1150
[07:31:49.595][tid:000012f0.0000193c][INFO]             Exclude group SID: S-1-5-21-740431820-361567022-3868551771-1151
[07:31:49.595][tid:000012f0.0000193c][INFO]             User is a member of the include group
[07:31:49.595][tid:000012f0.0000193c][INFO]             Status set to 200: Setup in progress
[07:31:49.595][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\RedirectType.  Using default: 2
[07:31:49.595][tid:000012f0.0000193c][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\ConcurrentUserSessions.  Data: 1
[07:31:49.595][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\VolumeWaitTimeMS.  Using default: 20000
[07:31:49.595][tid:000012f0.0000193c][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\DeleteLocalProfileWhenVHDShouldApply.  Data: 1
[07:31:49.595][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\FSLogixShellPath.  Using default: 
[07:31:49.595][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\AccessNetworkAsComputerObject.  Using default: 0
[07:31:49.595][tid:000012f0.0000193c][INFO]             Accessing network as user object
[07:31:49.595][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\AttachVHDSDDL.  Using default: 
[07:31:49.610][tid:000012f0.0000193c][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\ProfileType.  Data: 3
[07:31:49.610][tid:000012f0.0000193c][INFO]             Profile type: If first then Read/Write
[07:31:49.610][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\CCDLocations.  Using default: 
[07:31:49.610][tid:000012f0.0000193c][INFO]             Configuration Read (REG_SZ): SOFTWARE\FSLogix\Profiles\VHDLocations.
[07:31:49.610][tid:000012f0.0000193c][INFO]             VHDLocations found - configured to use Local Disk
[07:31:49.610][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\DiffDiskParentFolderPath.  Using default: C:\windows\TEMP\
[07:31:49.610][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\NoProfileContainingFolder.  Using default: 0
[07:31:49.610][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\FlipFlopProfileDirectoryName.  Using default: 0
[07:31:49.610][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\SIDDirNameMatch.  Using default: %sid%_%username%
[07:31:49.610][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\VHDNameMatch.  Using default: Profile*
[07:31:49.610][tid:000012f0.0000193c][INFO]             Configuration Read (REG_SZ): SOFTWARE\FSLogix\Profiles\VolumeType.  Data: VHDX
[07:31:49.657][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\DisableRegistryLocalRedirect.  Using default: 1
[07:31:49.657][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\LockedRetryCount.  Using default: 12
[07:31:49.657][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\LockedRetryInterval.  Using default: 5
[07:31:49.657][tid:000012f0.0000193c][INFO]             Profile VHD Path: \\dubsfsrv001p\ctx_testprof\<user>\S-1-5-21-137981764-1709787988-231145771-144716_<user>\Profile_<user>.VHDX
[07:31:49.657][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\SIDDirNamePattern.  Using default: %sid%_%username%
[07:31:49.657][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\VHDNamePattern.  Using default: Profile_%username%
[07:31:49.673][tid:000012f0.0000193c][INFO]             RW does not exist. Taking RW role
[07:31:53.095][tid:000012f0.0000193c][INFO]             Created RW diff: \\dubsfsrv001p\ctx_testprof\<user>\S-1-5-21-137981764-1709787988-231145771-144716_<user>\RW.VHDX
[07:31:53.095][tid:000012f0.0000193c][INFO]             User Profile Path: C:\Users\<user>.FSL0
[07:31:53.235][tid:000012f0.0000193c][INFO]             Attached vhd(x) successfully
[07:31:53.235][tid:000012f0.0000193c][INFO]             VHD attach request returning after 109 milliseconds
[07:31:53.235][tid:000012f0.0000193c][INFO]             VHD attached
[07:31:53.235][tid:000012f0.000012f4][INFO]             Volume attach event
[07:31:53.407][tid:000012f0.0000193c][INFO]             Volume name: \\?\Volume{fe5f740e-5d85-47bc-b814-d67960fdc2c2}\
[07:31:53.423][tid:000012f0.0000193c][INFO]             Profile format version 2
[07:31:53.423][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\ProfileDirSDDL.  Using default: 
[07:31:53.423][tid:000012f0.0000193c][INFO]             Create user directory success
[07:31:53.423][tid:000012f0.0000193c][INFO]             Security looks good
[07:31:53.423][tid:000012f0.0000193c][INFO]             Added redirection C:\Users\<user>.FSL0 -> \\?\Volume{fe5f740e-5d85-47bc-b814-d67960fdc2c2}\Profile
[07:31:53.423][tid:000012f0.0000193c][INFO]             Create mount point success
[07:31:54.345][tid:000012f0.0000193c][INFO]             Reg import process creation completed in 204 milliseconds
[07:31:54.673][tid:000012f0.0000193c][INFO]             Reg import execution completed in 328 milliseconds
[07:31:54.688][tid:000012f0.0000193c][INFO]             Import Profile Registry success
[07:31:54.688][tid:000012f0.0000193c][INFO]             Checking to see if cleanup necessary
[07:31:54.688][tid:000012f0.0000193c][INFO]             Removing junction: C:\Users\<user>.FSL0.  Adding junction C:\Users\<user>.
[07:31:54.688][tid:000012f0.0000193c][INFO]             Removed redirection from C:\Users\<user>.FSL0
[07:31:54.688][tid:000012f0.0000193c][INFO]             Added redirection C:\Users\<user> -> \\?\Volume{fe5f740e-5d85-47bc-b814-d67960fdc2c2}\Profile
[07:31:54.688][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\KeepLocalDir.  Using default: 0
[07:31:54.688][tid:000012f0.0000193c][INFO]             Local temp directory: C:\Users\local_<user>
[07:31:54.688][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\RedirXMLSourceFolder.  Using default: 
[07:31:54.688][tid:000012f0.0000193c][INFO]             Reading profile folder redirections
[07:31:54.688][tid:000012f0.0000193c][INFO]             Adding redirections for Credential Manager
[07:31:54.688][tid:000012f0.0000193c][INFO]             Creating base folders for profile folder redirections
[07:31:54.688][tid:000012f0.0000193c][INFO]             Creating base folder 'AppData\Roaming\Microsoft\Protect\'
[07:31:56.423][tid:000012f0.0000193c][INFO]             Mirror success: C:\Users\<user>\AppData\Roaming\Microsoft\Protect -> C:\Users\local_<user>\AppData\Roaming\Microsoft\Protect
[07:31:56.423][tid:000012f0.0000193c][INFO]             Creating base folder 'AppData\Roaming\Microsoft\Credentials\'
[07:31:56.673][tid:000012f0.0000193c][INFO]             Mirror success: C:\Users\<user>\AppData\Roaming\Microsoft\Credentials -> C:\Users\local_<user>\AppData\Roaming\Microsoft\Credentials
[07:31:56.673][tid:000012f0.0000193c][INFO]             Creating base folder 'AppData\Local\Microsoft\Credentials\'
[07:31:57.548][tid:000012f0.0000193c][INFO]             Mirror success: C:\Users\<user>\AppData\Local\Microsoft\Credentials -> C:\Users\local_<user>\AppData\Local\Microsoft\Credentials
[07:31:57.548][tid:000012f0.0000193c][INFO]             Adding exclude rule for folder 'AppData\Roaming\Microsoft\Protect\'
[07:31:57.548][tid:000012f0.0000193c][INFO]             Added redirection C:\Users\<user>\AppData\Roaming\Microsoft\Protect -> C:\Users\local_<user>\AppData\Roaming\Microsoft\Protect
[07:31:57.548][tid:000012f0.0000193c][INFO]             Adding exclude rule for folder 'AppData\Roaming\Microsoft\Credentials\'
[07:31:57.548][tid:000012f0.0000193c][INFO]             Added redirection C:\Users\<user>\AppData\Roaming\Microsoft\Credentials -> C:\Users\local_<user>\AppData\Roaming\Microsoft\Credentials
[07:31:57.548][tid:000012f0.0000193c][INFO]             Adding exclude rule for folder 'AppData\Local\Microsoft\Credentials\'
[07:31:57.548][tid:000012f0.0000193c][INFO]             Added redirection C:\Users\<user>\AppData\Local\Microsoft\Credentials -> C:\Users\local_<user>\AppData\Local\Microsoft\Credentials
[07:31:57.548][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\GroupPolicyState.  Using default: 1
[07:31:58.454][tid:000012f0.0000193c][INFO]             Imported group policy sid key
[07:31:58.907][tid:000012f0.0000193c][INFO]             Imported group policy state key
[07:31:58.907][tid:000012f0.0000193c][INFO]             Folder redirections created
[07:31:58.907][tid:000012f0.0000193c][INFO]             Status set to 0: Success
[07:31:58.907][tid:000012f0.0000193c][INFO]             Profile refcount incremented to: 1
[07:31:58.907][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\CleanOutNotifications.  Using default: 1
[07:31:58.907][tid:000012f0.0000193c][INFO]             Prep1
[07:31:58.938][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\RegisterAppNotifications.  Using default: 1
[07:31:58.938][tid:000012f0.0000193c][INFO]             Prep2
[07:32:16.454][tid:000012f0.0000193c][INFO]             OneDriveTemp setup
[07:32:16.454][tid:000012f0.0000193c][INFO]             C:\OneDriveTemp\S-1-5-21-137981764-1709787988-231145771-144716 --> \\?\Volume{fe5f740e-5d85-47bc-b814-d67960fdc2c2}\ODTemp
[07:32:16.454][tid:000012f0.0000193c][INFO]             OneDriveTemp setup successful

[07:32:16.454][tid:000012f0.0000193c][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\PreventLoginWithFailure.  Using default: 0
[07:32:16.470][tid:000012f0.0000193c][INFO]             LoadProfile successful.  User: <user>. SID: S-1-5-21-137981764-1709787988-231145771-144716.
[07:32:16.470][tid:000012f0.0000193c][INFO]             loadProfile time: 26875 milliseconds
[07:32:16.470][tid:000012f0.0000193c][INFO]           ===== End Session:  LoadProfile: <user>
[07:32:16.470][tid:000012f0.0000193c][INFO]           ===== End Session: Logon
 

Link to comment

2 answers to this question

Recommended Posts

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...