none
FSLogix Eventually stops loading VHDs or Importing the Registry RRS feed

  • Question

  • We have run into an issue where a server in our multi Win 2016 RDS environment will stop loading VHDs with an "Error set to 121" in our FSLogix logs and give the user a temp profile. Or a user will login and not get a temp profile (the login simply hangs) and FSLogix reports that their Registry could not be imported "ImportProfileRegistry failure". The server never recovers and becomes unusable as an RDS server, since it cannot load Profiles.

    This has happened to every server in the environment at some point and we have rebuilt them all from scratch. It is very strange because it will not affect every server simultaneously. The VHD themselves are hosted on 2016 file server.

    Email support from FSLogix is frustratingly slow and unresponsive, and we have taken to basically weekly BMR's on at least one server. Has anyone run into any issue like this before with VHDs/RegImport? 

    Friday, November 22, 2019 3:42 PM

All replies

  • We have run into an issue where a server in our multi Win 2016 RDS environment will stop loading VHDs with an "Error set to 121" in our FSLogix logs and give the user a temp profile. Or a user will login and not get a temp profile (the login simply hangs) and FSLogix reports that their Registry could not be imported "ImportProfileRegistry failure". The server never recovers and becomes unusable as an RDS server, since it cannot load Profiles.

    This has happened to every server in the environment at some point and we have rebuilt them all from scratch. It is very strange because it will not affect every server simultaneously. The VHD themselves are hosted on 2016 file server.

    Email support from FSLogix is frustratingly slow and unresponsive, and we have taken to basically weekly BMR's on at least one server. Has anyone run into any issue like this before with VHDs/RegImport? 

    I would like to see some logs, or a manually initiated crash dump from a server showing this behavior.  A crash dump is likely the fastest way to diagnose, but some logs for context would help as well.  It sounds like a lock of some sort on the registry, and we would need to see why.  The 121 is a timeout acquiring a lock.  Does a reboot of the server clean it up?  Are there any other errors in the log before the load completes?  Are you configured to use Profiles, Office Containers or both?


    • Edited by Brian Mann1 Tuesday, November 26, 2019 1:16 AM
    Tuesday, November 26, 2019 1:16 AM
  • Hello Brian,

    I have been off for a bit, but I have captured an example error from a server whose FSLogix stopped functioning today. I have the entire log, but I dot not think I can attach files. In this case it looks like the user encounter the error, but was able to try again a login. Server reboot does not prevent the error and I will be taking this server out of the pool and creating a new one as described in the OP. Several users received these same errors and have identical entries in the logs.

    Please keep in mind that other RDS servers (there are 7 running concurrently) were not affected, but the issue eventually hits all of them at roughly the loss of one server a week.

    [11:42:15.784][tid:00000ff8.000036d4][INFO]           ===== Begin Session: Profile Directory Changed: S-1-5-21-3026608784-1296969905-2008666479-2128
    [11:42:15.785][tid:00000ff8.000036d4][INFO]            New Profile Directory: C:\Users\UserB
    [11:42:15.785][tid:00000ff8.000036d4][INFO]            Error set to 121
    [11:42:15.786][tid:00000ff8.000036d4][INFO]           ===== End Session: Profile Directory Changed: S-1-5-21-3026608784-1296969905-2008666479-2128
    [11:42:20.350][tid:00000ff8.00009d80][INFO]           ===== Begin Session: Logoff
    [11:42:20.350][tid:00000ff8.00009d80][INFO]            User: S-1-5-21-3026608784-1296969905-2008666479-2128 (UserB)
    [11:42:20.350][tid:00000ff8.00009d80][INFO]            Acquiring Lock for user logoff
    [11:42:20.350][tid:00000ff8.00009d80][INFO]            Configuration setting not found: SOFTWARE\FSLogix\Profiles\LogonSyncMutexTimeout.  Using default: 60000
    [11:42:20.350][tid:00000ff8.00009d80][INFO]            Mutex acquired
    [11:42:20.350][tid:00000ff8.00009d80][INFO]           ===== Begin Session:  Unload profile: UserB
    [11:42:20.350][tid:00000ff8.00009d80][INFO]             User: UserB. SID: S-1-5-21-3026608784-1296969905-2008666479-2128.
    [11:42:20.350][tid:00000ff8.00009d80][INFO]             Session configuration read (REG_SZ): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3026608784-1296969905-2008666479-2128\ActivityId = '381e8134-ae58-0004-66cf-293858aed501'
    [11:42:20.351][tid:00000ff8.00009d80][INFO]             Error set to 121
    [11:42:20.351][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\ConcurrentUserSessions.  Using default: 1
    [11:42:20.351][tid:00000ff8.00009d80][INFO]             Profile refcount decremented to: -1
    [11:42:20.351][tid:00000ff8.00009d80][INFO]             No teardown required
    [11:42:20.352][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\ShutdownOnUserLogoff.  Using default: 0
    [11:42:20.352][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\RebootOnUserLogoff.  Using default: 0
    [11:42:20.352][tid:00000ff8.00009d80][INFO]             UnloadProfile successful.  User: UserB. SID: S-1-5-21-3026608784-1296969905-2008666479-2128.
    [11:42:20.352][tid:00000ff8.00009d80][INFO]             unloadProfile time: 0 milliseconds
    [11:42:20.352][tid:00000ff8.00009d80][INFO]           ===== End Session:  Unload profile: UserB
    [11:42:20.352][tid:00000ff8.00009d80][INFO]           ===== End Session: Logoff
    [11:48:33.621][tid:00000ff8.00007b14][INFO]           ===== Begin Session: Logon
    [11:48:33.621][tid:00000ff8.00007b14][INFO]            User: S-1-5-21-3026608784-1296969905-2008666479-2128 (UserB)
    [11:48:33.621][tid:00000ff8.00007b14][INFO]            Acquiring mutex for user logon
    [11:48:33.621][tid:00000ff8.00007b14][INFO]            Configuration setting not found: SOFTWARE\FSLogix\Profiles\LogonSyncMutexTimeout.  Using default: 60000
    [11:48:33.621][tid:00000ff8.00007b14][INFO]            Mutex acquired
    [11:48:33.621][tid:00000ff8.00007b14][INFO]           ===== Begin Session:  LoadProfile: UserB
    [11:48:33.621][tid:00000ff8.00007b14][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\Enabled.  Data: 1
    [11:48:33.621][tid:00000ff8.00007b14][INFO]             User: UserB. SID: S-1-5-21-3026608784-1296969905-2008666479-2128.
    [11:48:33.622][tid:00000ff8.00007b14][INFO]             Include group SID: S-1-5-21-232078018-1588528326-334240843-1002
    [11:48:33.622][tid:00000ff8.00007b14][INFO]             Exclude group SID: S-1-5-21-232078018-1588528326-334240843-1003
    [11:48:33.622][tid:00000ff8.00007b14][INFO]             User is a member of the include group
    [11:48:33.622][tid:00000ff8.00007b14][INFO]             Status set to 200: Setup in progress
    [11:48:33.622][tid:00000ff8.00007b14][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\RedirectType.  Using default: 2
    [11:48:33.622][tid:00000ff8.00007b14][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\ConcurrentUserSessions.  Using default: 1
    [11:48:33.624][tid:00000ff8.00007b14][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\VolumeWaitTimeMS.  Using default: 20000
    [11:48:33.625][tid:00000ff8.00007b14][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\DeleteLocalProfileWhenVHDShouldApply.  Data: 1
    [11:48:33.625][tid:00000ff8.00007b14][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\FSLogixShellPath.  Using default: 
    [11:48:33.625][tid:00000ff8.00007b14][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\AccessNetworkAsComputerObject.  Using default: 0
    [11:48:33.625][tid:00000ff8.00007b14][INFO]             Accessing network as user object
    [11:48:33.625][tid:00000ff8.00007b14][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\AttachVHDSDDL.  Using default: 
    [11:48:33.918][tid:00000ff8.00007b14][INFO]             Successfully removed Profile for S-1-5-21-3026608784-1296969905-2008666479-2128
    [11:48:33.918][tid:00000ff8.00007b14][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\ProfileType.  Data: 0
    [11:48:33.918][tid:00000ff8.00007b14][INFO]             Profile type: Normal
    [11:48:33.918][tid:00000ff8.00007b14][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\CCDLocations.  Using default: 
    [11:48:33.918][tid:00000ff8.00007b14][INFO]             Configuration Read (REG_SZ): SOFTWARE\FSLogix\Profiles\VHDLocations.
    [11:48:33.919][tid:00000ff8.00007b14][INFO]             VHDLocations found - configured to use Local Disk
    [11:48:33.919][tid:00000ff8.00007b14][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\DiffDiskParentFolderPath.  Using default: C:\Windows\TEMP\
    [11:48:33.920][tid:00000ff8.00007b14][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\NoProfileContainingFolder.  Using default: 0
    [11:48:33.920][tid:00000ff8.00007b14][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\FlipFlopProfileDirectoryName.  Data: 1
    [11:48:33.921][tid:00000ff8.00007b14][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\VHDNameMatch.  Using default: Profile*
    [11:48:33.921][tid:00000ff8.00007b14][INFO]             Configuration Read (REG_SZ): SOFTWARE\FSLogix\Profiles\VolumeType.  Data: VHDX
    [11:48:33.961][tid:00000ff8.00007b14][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\LockedRetryCount.  Data: 10
    [11:48:33.961][tid:00000ff8.00007b14][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\LockedRetryInterval.  Data: 6
    [11:48:34.108][tid:00000ff8.00000ffc][INFO]             Volume attach event
    [11:48:35.913][tid:00000ff8.00007b14][INFO]             Detached vhd(x)
    [11:48:35.914][tid:00000ff8.00007b14][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\DisableRegistryLocalRedirect.  Using default: 1
    [11:48:35.914][tid:00000ff8.00007b14][INFO]             Profile VHD Path: \\Server-FILE-01\FSLogixProfiles$\UserB_S-1-5-21-3026608784-1296969905-2008666479-2128\Profile_UserB.VHDX
    [11:48:35.915][tid:00000ff8.00007b14][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\VHDNamePattern.  Using default: Profile_%username%
    [11:48:35.915][tid:00000ff8.00007b14][INFO]             User Profile Path: C:\Users\UserB.FSL0
    [11:48:35.950][tid:00000ff8.00000ffc][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\ReAttachRetryCount.  Data: 12
    [11:48:35.950][tid:00000ff8.00000ffc][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\ReAttachIntervalSeconds.  Data: 5
    [11:48:35.950][tid:00000ff8.00000ffc][INFO]           ===== Begin Session:   Volume re-attach
    [11:48:35.951][tid:00000ff8.00000ffc][INFO]           ===== End Session:   Volume re-attach
    [11:48:36.025][tid:00000ff8.00007b14][INFO]             VHD attached
    [11:48:36.028][tid:00000ff8.00000ffc][INFO]             Volume attach event
    [11:48:36.371][tid:00000ff8.00007b14][INFO]             Volume name: \\?\Volume{28db866b-5515-4ef2-90dd-bde9edc8abee}\
    [11:48:36.410][tid:00000ff8.00007b14][INFO]             Profile format version 2
    [11:48:36.410][tid:00000ff8.00007b14][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\ProfileDirSDDL.  Using default: 
    [11:48:36.411][tid:00000ff8.00007b14][INFO]             Create user directory success
    [11:48:36.411][tid:00000ff8.00007b14][INFO]             Security looks good
    [11:48:36.411][tid:00000ff8.00007b14][INFO]             Added redirection C:\Users\UserB.FSL0 -> \\?\Volume{28db866b-5515-4ef2-90dd-bde9edc8abee}\Profile
    [11:48:36.411][tid:00000ff8.00007b14][INFO]             Create mount point success
    [11:48:36.507][tid:00000ff8.00007b14][INFO]             Import Profile Registry success
    [11:48:36.507][tid:00000ff8.00007b14][INFO]             Checking to see if cleanup necessary
    [11:48:36.507][tid:00000ff8.00007b14][INFO]             Removing junction: C:\Users\UserB.FSL0.  Adding junction C:\Users\UserB.
    [11:48:36.508][tid:00000ff8.00007b14][INFO]             Removed redirection from C:\Users\UserB.FSL0
    [11:48:36.508][tid:00000ff8.00007b14][INFO]             Added redirection C:\Users\UserB -> \\?\Volume{28db866b-5515-4ef2-90dd-bde9edc8abee}\Profile
    [11:48:36.509][tid:00000ff8.00007b14][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\KeepLocalDir.  Using default: 0
    [11:48:36.511][tid:00000ff8.00007b14][INFO]             Local temp directory: C:\Users\local_UserB
    [11:48:36.511][tid:00000ff8.00007b14][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\RedirXMLSourceFolder.  Using default: 
    [11:48:36.511][tid:00000ff8.00007b14][INFO]             Reading profile folder redirections
    [11:48:36.511][tid:00000ff8.00007b14][INFO]             Adding redirections for Credential Manager
    [11:48:36.511][tid:00000ff8.00007b14][INFO]             Creating base folders for profile folder redirections
    [11:48:36.511][tid:00000ff8.00007b14][INFO]             Creating base folder 'AppData\Roaming\Microsoft\Credentials\'
    [11:48:36.633][tid:00000ff8.00007b14][INFO]             Mirror success: C:\Users\UserB\AppData\Roaming\Microsoft\Credentials -> C:\Users\local_UserB\AppData\Roaming\Microsoft\Credentials
    [11:48:36.633][tid:00000ff8.00007b14][INFO]             Creating base folder 'AppData\Local\Microsoft\Credentials\'
    [11:48:36.725][tid:00000ff8.00007b14][INFO]             Mirror success: C:\Users\UserB\AppData\Local\Microsoft\Credentials -> C:\Users\local_UserB\AppData\Local\Microsoft\Credentials
    [11:48:36.725][tid:00000ff8.00007b14][INFO]             Adding exclude rule for folder 'AppData\Roaming\Microsoft\Credentials\'
    [11:48:36.725][tid:00000ff8.00007b14][INFO]             Added redirection C:\Users\UserB\AppData\Roaming\Microsoft\Credentials -> C:\Users\local_UserB\AppData\Roaming\Microsoft\Credentials
    [11:48:36.725][tid:00000ff8.00007b14][INFO]             Adding exclude rule for folder 'AppData\Local\Microsoft\Credentials\'
    [11:48:36.725][tid:00000ff8.00007b14][INFO]             Added redirection C:\Users\UserB\AppData\Local\Microsoft\Credentials -> C:\Users\local_UserB\AppData\Local\Microsoft\Credentials
    [11:48:36.725][tid:00000ff8.00007b14][INFO]             Folder redirections created
    [11:48:36.725][tid:00000ff8.00007b14][INFO]             Status set to 0: Success
    [11:48:36.727][tid:00000ff8.00007b14][INFO]             Profile refcount incremented to: 1
    [11:48:36.727][tid:00000ff8.00007b14][INFO]             OneDriveTemp setup
    [11:48:36.727][tid:00000ff8.00007b14][INFO]             C:\OneDriveTemp\S-1-5-21-3026608784-1296969905-2008666479-2128 --> \\?\Volume{28db866b-5515-4ef2-90dd-bde9edc8abee}\ODTemp
    [11:48:36.727][tid:00000ff8.00007b14][INFO]             OneDriveTemp setup successful
    [11:48:36.727][tid:00000ff8.00007b14][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\PreventLoginWithFailure.  Using default: 0
    [11:48:36.729][tid:00000ff8.00007b14][INFO]             vhd(x) \\Server-FILE-01\FSLogixProfiles$\UserB_S-1-5-21-3026608784-1296969905-2008666479-2128\Profile_UserB.VHDX has 193667.88 MB left (97.60 % free)
    [11:48:36.729][tid:00000ff8.00007b14][INFO]             LoadProfile successful.  User: UserB. SID: S-1-5-21-3026608784-1296969905-2008666479-2128.
    [11:48:36.729][tid:00000ff8.00007b14][INFO]             loadProfile time: 3109 milliseconds
    [11:48:36.729][tid:00000ff8.00007b14][INFO]           ===== End Session:  LoadProfile: UserB

    Monday, December 9, 2019 6:41 PM
  • And a second excerpt with Semaphore mentioned:

    [11:37:14.882][tid:00000ff8.00009d80][INFO]           ===== Begin Session: Logon
    [11:37:14.883][tid:00000ff8.00009d80][INFO]            User: S-1-5-21-3026608784-1296969905-2008666479-2128 (UserB)
    [11:37:14.883][tid:00000ff8.00009d80][INFO]            Acquiring mutex for user logon
    [11:37:14.883][tid:00000ff8.00009d80][INFO]            Configuration setting not found: SOFTWARE\FSLogix\Profiles\LogonSyncMutexTimeout.  Using default: 60000
    [11:37:14.883][tid:00000ff8.00009d80][INFO]            Mutex acquired
    [11:37:14.883][tid:00000ff8.00009d80][INFO]           ===== Begin Session:  LoadProfile: UserB
    [11:37:14.883][tid:00000ff8.00009d80][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\Enabled.  Data: 1
    [11:37:14.883][tid:00000ff8.00009d80][INFO]             User: UserB. SID: S-1-5-21-3026608784-1296969905-2008666479-2128.
    [11:37:14.884][tid:00000ff8.00009d80][INFO]             Include group SID: S-1-5-21-232078018-1588528326-334240843-1002
    [11:37:14.884][tid:00000ff8.00009d80][INFO]             Exclude group SID: S-1-5-21-232078018-1588528326-334240843-1003
    [11:37:14.884][tid:00000ff8.00009d80][INFO]             User is a member of the include group
    [11:37:14.884][tid:00000ff8.00009d80][INFO]             Status set to 200: Setup in progress
    [11:37:14.884][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\RedirectType.  Using default: 2
    [11:37:14.885][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\ConcurrentUserSessions.  Using default: 1
    [11:37:14.888][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\VolumeWaitTimeMS.  Using default: 20000
    [11:37:14.888][tid:00000ff8.00009d80][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\DeleteLocalProfileWhenVHDShouldApply.  Data: 1
    [11:37:14.888][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\FSLogixShellPath.  Using default: 
    [11:37:14.888][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\AccessNetworkAsComputerObject.  Using default: 0
    [11:37:14.888][tid:00000ff8.00009d80][INFO]             Accessing network as user object
    [11:37:14.888][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\AttachVHDSDDL.  Using default: 
    [11:37:14.889][tid:00000ff8.00009d80][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\ProfileType.  Data: 0
    [11:37:14.889][tid:00000ff8.00009d80][INFO]             Profile type: Normal
    [11:37:14.889][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\CCDLocations.  Using default: 
    [11:37:14.889][tid:00000ff8.00009d80][INFO]             Configuration Read (REG_SZ): SOFTWARE\FSLogix\Profiles\VHDLocations.
    [11:37:14.890][tid:00000ff8.00009d80][INFO]             VHDLocations found - configured to use Local Disk
    [11:37:14.890][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\DiffDiskParentFolderPath.  Using default: C:\Windows\TEMP\
    [11:37:14.890][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\NoProfileContainingFolder.  Using default: 0
    [11:37:14.890][tid:00000ff8.00009d80][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\FlipFlopProfileDirectoryName.  Data: 1
    [11:37:14.891][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\VHDNameMatch.  Using default: Profile*
    [11:37:14.891][tid:00000ff8.00009d80][INFO]             Configuration Read (REG_SZ): SOFTWARE\FSLogix\Profiles\VolumeType.  Data: VHDX
    [11:37:14.899][tid:00000ff8.00009d80][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\LockedRetryCount.  Data: 10
    [11:37:14.899][tid:00000ff8.00009d80][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\LockedRetryInterval.  Data: 6
    [11:37:15.211][tid:00000ff8.00000ffc][INFO]             Volume attach event
    [11:37:15.727][tid:00000ff8.00009d80][INFO]             Detached vhd(x)
    [11:37:15.728][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\DisableRegistryLocalRedirect.  Using default: 1
    [11:37:15.728][tid:00000ff8.00009d80][INFO]             Profile VHD Path: \\Server-FILE-01\FSLogixProfiles$\UserB_S-1-5-21-3026608784-1296969905-2008666479-2128\Profile_UserB.VHDX
    [11:37:15.729][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\VHDNamePattern.  Using default: Profile_%username%
    [11:37:15.729][tid:00000ff8.00009d80][INFO]             User Profile Path: C:\Users\UserB.FSL0
    [11:37:15.777][tid:00000ff8.00000ffc][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\ReAttachRetryCount.  Data: 12
    [11:37:15.777][tid:00000ff8.00000ffc][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\ReAttachIntervalSeconds.  Data: 5
    [11:37:15.777][tid:00000ff8.00000ffc][INFO]           ===== Begin Session:   Volume re-attach
    [11:37:15.781][tid:00000ff8.00000ffc][INFO]           ===== End Session:   Volume re-attach
    [11:42:15.758][tid:00000ff8.00009d80][INFO]             Status set to 12: Cannot attach to virtual disk
    [11:42:15.758][tid:00000ff8.00009d80][INFO]             Error set to 121
    [11:42:15.758][tid:00000ff8.00009d80][ERROR:00000079]   AttachVirtualDisk error (The semaphore timeout period has expired.)
    [11:42:15.759][tid:00000ff8.00009d80][INFO]             Error. Cleaning up.
    [11:42:15.760][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\PreventLoginWithFailure.  Using default: 0
    [11:42:15.766][tid:00000ff8.00009d80][ERROR:0000001f]   LoadProfile failed.  User: UserB. SID: S-1-5-21-3026608784-1296969905-2008666479-2128. (A device attached to the system is not functioning.)
    [11:42:15.766][tid:00000ff8.00009d80][INFO]             loadProfile time: 300875 milliseconds
    [11:42:15.766][tid:00000ff8.00009d80][INFO]           ===== End Session:  LoadProfile: UserB
    [11:42:15.767][tid:00000ff8.00009d80][INFO]           ===== End Session: Logon

    Monday, December 9, 2019 6:45 PM
  • And a second excerpt with Semaphore mentioned:

    [11:37:14.882][tid:00000ff8.00009d80][INFO]           ===== Begin Session: Logon
    [11:37:14.883][tid:00000ff8.00009d80][INFO]            User: S-1-5-21-3026608784-1296969905-2008666479-2128 (UserB)
    [11:37:14.883][tid:00000ff8.00009d80][INFO]            Acquiring mutex for user logon
    [11:37:14.883][tid:00000ff8.00009d80][INFO]            Configuration setting not found: SOFTWARE\FSLogix\Profiles\LogonSyncMutexTimeout.  Using default: 60000
    [11:37:14.883][tid:00000ff8.00009d80][INFO]            Mutex acquired
    [11:37:14.883][tid:00000ff8.00009d80][INFO]           ===== Begin Session:  LoadProfile: UserB
    [11:37:14.883][tid:00000ff8.00009d80][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\Enabled.  Data: 1
    [11:37:14.883][tid:00000ff8.00009d80][INFO]             User: UserB. SID: S-1-5-21-3026608784-1296969905-2008666479-2128.
    [11:37:14.884][tid:00000ff8.00009d80][INFO]             Include group SID: S-1-5-21-232078018-1588528326-334240843-1002
    [11:37:14.884][tid:00000ff8.00009d80][INFO]             Exclude group SID: S-1-5-21-232078018-1588528326-334240843-1003
    [11:37:14.884][tid:00000ff8.00009d80][INFO]             User is a member of the include group
    [11:37:14.884][tid:00000ff8.00009d80][INFO]             Status set to 200: Setup in progress
    [11:37:14.884][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\RedirectType.  Using default: 2
    [11:37:14.885][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\ConcurrentUserSessions.  Using default: 1
    [11:37:14.888][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\VolumeWaitTimeMS.  Using default: 20000
    [11:37:14.888][tid:00000ff8.00009d80][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\DeleteLocalProfileWhenVHDShouldApply.  Data: 1
    [11:37:14.888][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\FSLogixShellPath.  Using default: 
    [11:37:14.888][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\AccessNetworkAsComputerObject.  Using default: 0
    [11:37:14.888][tid:00000ff8.00009d80][INFO]             Accessing network as user object
    [11:37:14.888][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\AttachVHDSDDL.  Using default: 
    [11:37:14.889][tid:00000ff8.00009d80][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\ProfileType.  Data: 0
    [11:37:14.889][tid:00000ff8.00009d80][INFO]             Profile type: Normal
    [11:37:14.889][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\CCDLocations.  Using default: 
    [11:37:14.889][tid:00000ff8.00009d80][INFO]             Configuration Read (REG_SZ): SOFTWARE\FSLogix\Profiles\VHDLocations.
    [11:37:14.890][tid:00000ff8.00009d80][INFO]             VHDLocations found - configured to use Local Disk
    [11:37:14.890][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\DiffDiskParentFolderPath.  Using default: C:\Windows\TEMP\
    [11:37:14.890][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\NoProfileContainingFolder.  Using default: 0
    [11:37:14.890][tid:00000ff8.00009d80][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\FlipFlopProfileDirectoryName.  Data: 1
    [11:37:14.891][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\VHDNameMatch.  Using default: Profile*
    [11:37:14.891][tid:00000ff8.00009d80][INFO]             Configuration Read (REG_SZ): SOFTWARE\FSLogix\Profiles\VolumeType.  Data: VHDX
    [11:37:14.899][tid:00000ff8.00009d80][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\LockedRetryCount.  Data: 10
    [11:37:14.899][tid:00000ff8.00009d80][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\LockedRetryInterval.  Data: 6
    [11:37:15.211][tid:00000ff8.00000ffc][INFO]             Volume attach event
    [11:37:15.727][tid:00000ff8.00009d80][INFO]             Detached vhd(x)
    [11:37:15.728][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\DisableRegistryLocalRedirect.  Using default: 1
    [11:37:15.728][tid:00000ff8.00009d80][INFO]             Profile VHD Path: \\Server-FILE-01\FSLogixProfiles$\UserB_S-1-5-21-3026608784-1296969905-2008666479-2128\Profile_UserB.VHDX
    [11:37:15.729][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\VHDNamePattern.  Using default: Profile_%username%
    [11:37:15.729][tid:00000ff8.00009d80][INFO]             User Profile Path: C:\Users\UserB.FSL0
    [11:37:15.777][tid:00000ff8.00000ffc][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\ReAttachRetryCount.  Data: 12
    [11:37:15.777][tid:00000ff8.00000ffc][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\ReAttachIntervalSeconds.  Data: 5
    [11:37:15.777][tid:00000ff8.00000ffc][INFO]           ===== Begin Session:   Volume re-attach
    [11:37:15.781][tid:00000ff8.00000ffc][INFO]           ===== End Session:   Volume re-attach
    [11:42:15.758][tid:00000ff8.00009d80][INFO]             Status set to 12: Cannot attach to virtual disk
    [11:42:15.758][tid:00000ff8.00009d80][INFO]             Error set to 121
    [11:42:15.758][tid:00000ff8.00009d80][ERROR:00000079]   AttachVirtualDisk error (The semaphore timeout period has expired.)
    [11:42:15.759][tid:00000ff8.00009d80][INFO]             Error. Cleaning up.
    [11:42:15.760][tid:00000ff8.00009d80][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\PreventLoginWithFailure.  Using default: 0
    [11:42:15.766][tid:00000ff8.00009d80][ERROR:0000001f]   LoadProfile failed.  User: UserB. SID: S-1-5-21-3026608784-1296969905-2008666479-2128. (A device attached to the system is not functioning.)
    [11:42:15.766][tid:00000ff8.00009d80][INFO]             loadProfile time: 300875 milliseconds
    [11:42:15.766][tid:00000ff8.00009d80][INFO]           ===== End Session:  LoadProfile: UserB
    [11:42:15.767][tid:00000ff8.00009d80][INFO]           ===== End Session: Logon

    This is a failure during the call to Attach the virtual disk.  The most likely causes would be network issues, or firewalls blocking us.  Is it possible that your firewall or AV has reacted to our network traffic in a way that is blocking us at some point?
    Friday, December 20, 2019 11:48 PM
  • We have the same issues on our RDS 2016 Farm with a Windows Server 2016 as FSLogix Profile location.

    We use FSLogix Agent 2.9.7237.48865

    Sometime a user logs in the RDS Farm and have a blackscreen during the login for about 3 min.
    Then the user gets the desktop

    We getting the Error in the FSLogix Log;
    Error (A device attached to the system is not function)
    AttachVirtualDisk (The semaphore timeout period has expired.)

    We see no locks on the VHDX Files for these users

    Have you ever fixed this?


    • Edited by Viper-12 Friday, January 31, 2020 12:54 PM
    Friday, January 31, 2020 12:36 PM
  • (we have the same problem with the  last fslogix version (public review
    Wednesday, April 22, 2020 10:46 AM
  • I dont know if this helps You but i had an issue with 2019 rds servers that new Users wont get correctly the Profile attached. I disabled the delta disk (rw.vhdx) ,disabled  mulit mountof the Profile (so only one rds can Host the Session) and set a logoff of the User after idle time. So the User is sticked to one rds which in my case handles VMware Horizon for me. Maybe this is Something similar. 
    Friday, April 24, 2020 9:06 PM