FSLogix always recreates VHDX, user registry not being exported to container during logoff

Sebastian Hoffmann 6 Reputation points
2021-03-13T10:51:05.847+00:00

We use FSLogix together with folder redirection on 3 RDSH. Everytime a user connects the existing VHDX is renamed to "CORRUPTED.... .vhdx" and a new one is created.
We believe this is also the cause why all users have to enter the Office 365 login data every day again.

From the FSLOGix profile log I can see that the users registry isn't exported and the next time the user logs in I can see in the log that the VHDX is recreated, because auf the missing users registry hive. But I don't understand why it can't be exported during logoff.

This is the log during the logoff 

[10:46:45.601][tid:00000ab0.000007c8][INFO]           ===== Begin Session:  Unload profile: xxx
[10:46:45.601][tid:00000ab0.000007c8][INFO]             User: xxx. SID: S-1-5-21-3689327215-3964591171-841422420-3605.
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\Enabled.  Data: 1
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Include group SID: S-1-5-21-3611441089-499911054-2832136425-1000
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Exclude group SID: S-1-5-21-3611441089-499911054-2832136425-1001
[10:46:45.601][tid:00000ab0.000007c8][INFO]             User is a member of the include group
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\IgnoreNonWVD.  Using default: 0
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\Enabled.  Data: 1
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\RefCount = '1'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration wrote (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\RefCount = '0'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Profile refcount decremented to: 0
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\AccessNetworkAsComputerObject = '0'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (REG_SZ): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\ActivityId = '8cb2ae2a-fbf0-4ad0-a934-785ccb4b2249'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\AMP = '1'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (REG_SZ): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\AttachVHDSDDL = 'D:AI(A;;GA;;;SY)(A;;GA;;;BA)(A;;GA;;;BU)(A;;GA;;;WD)(A;;GA;;;RC)(A;;GA;;;AC)S:(ML;;NW;;;LW)'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (REG_SZ): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\CorruptDiskFilename = 'CORRUPT_99a07128-edfc-4065-9222-3ccd3a7e79fb_Profile_xxx.VHDX'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\CurrentVolumeType = '2'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\DeleteLocalProfileWhenVHDShouldApply = '1'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\DirectAttachOnCreateVHD = '0'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\DisableRegistryLocalRedirect = '1'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\ErrorCode = '0'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (REG_MULTI_SZ): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\ExcludeFolderRedirections
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\FirstLogon = '1'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\FrxshellErrorCode = '0'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\FrxshellStatus = '200'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\FrxshellReason = '0'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (REG_MULTI_SZ): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\IncludeFolderRedirections
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\IsDynamic = '1'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\LastProfileLoadTimeMS = '36469'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (REG_SZ): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\LocalProfilePath = 'C:\Users\local_xxx1'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\LockedRetryCount = '1'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\LockedRetryInterval = '5'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\LogonStage = '3'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\NoProfileContainingFolder = '0'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\OutlookCachedModeEnabled = '0'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\ProfileFormatVersion = '2'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (REG_SZ): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\ProfilePath = 'C:\Users\xxx'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\ProfileType = '0'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\Reason = '0'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\RedirectType = '2'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\RefCount = '0'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\RoamSearch = '0'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\SearchServiceWasEnabled = '0'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\SearchServiceWasRunning = '0'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\SizeInMBs = '30000'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\Status = '0'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (REG_SZ): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\VHDOpenedFilePath = '\\srv-dc2\UPDProfiles\S-1-5-21-3689327215-3964591171-841422420-3605_xxx\Profile_xxx.VHDX'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (REG_SZ): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\VHDRODiffDiskFilePath = 'C:\Windows\TEMP\S-1-5-21-3689327215-3964591171-841422420-3605_RO.VHDX'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (REG_SZ): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\VHDRWDiffDiskFilePath = '\\srv-dc2\UPDProfiles\S-1-5-21-3689327215-3964591171-841422420-3605_xxx\RW.VHDX'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (REG_SZ): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\VHDRootFilePath = '\\srv-dc2\UPDProfiles\S-1-5-21-3689327215-3964591171-841422420-3605_xxx\Profile_xxx.VHDX'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (REG_SZ): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\VhdStorageType = 'LocalVhdStorage'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\VHDXSectorSize = '0'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (REG_SZ): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\Volume = '\\?\Volume{5f5a99c1-daf3-4ee1-97bc-06601fdfc4b0}\'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\WindowsSessionID = '3'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Session configuration read (DWORD): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\WindowsTempProfile = '0'
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\RemoveOrphanedOSTFilesOnLogoff.  Using default: 0
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\OutlookFolderPath.  Using default: %userprofile%\AppData\Local\Microsoft\Outlook
[10:46:45.601][tid:00000ab0.000007c8][INFO]             vhd(x) \\srv-dc2\UPDProfiles\S-1-5-21-3689327215-3964591171-841422420-3605_xxx\Profile_xxx.VHDX has 29916.79 MB left (99.73 % free)
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\RegisterAppNotifications.  Using default: 1
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Prep3
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Prep3: Key not found
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Accessing network as user object
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Adjusting symlinks
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Removing Profile Redirections
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Removed redirection from C:\Users\xxx\AppData\Roaming\Microsoft\Protect
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Removed redirection from C:\Users\xxx\AppData\Roaming\Microsoft\Credentials
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Removed redirection from C:\Users\xxx\AppData\Local\Microsoft\Credentials
[10:46:45.601][tid:00000ab0.000007c8][INFO]             Mirroring exclude folder: AppData\Roaming\Microsoft\Protect\
[10:46:45.647][tid:00000ab0.000007c8][INFO]             Mirroring exclude folder: AppData\Roaming\Microsoft\Credentials\
[10:46:45.663][tid:00000ab0.000007c8][INFO]             Mirroring exclude folder: AppData\Local\Microsoft\Credentials\
[10:46:45.694][tid:00000ab0.000007c8][INFO]             Removal of folder redirection complete
[10:46:45.710][tid:00000ab0.000007c8][ERROR:00000001]   Export registry failure (Unzulässige Funktion.)
[10:46:45.710][tid:00000ab0.000007c8][ERROR:00000001]   Error exporting profile registry information (Unzulässige Funktion.)
[10:46:45.710][tid:00000ab0.000007c8][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\GroupPolicyState.  Using default: 0
[10:46:45.710][tid:00000ab0.000007c8][INFO]             Removed redirection from C:\Users\xxx
[10:46:45.710][tid:00000ab0.000007c8][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\ControlTileService.  Using default: 0
[10:46:45.757][tid:00000ab0.000007c8][INFO]             Detached vhd(x)
[10:46:45.757][tid:00000ab0.000007c8][INFO]             VHD(x) Detach request returning after 47 milliseconds
[10:46:45.757][tid:00000ab0.000007c8][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\KeepLocalDir.  Data: 0
[10:46:45.757][tid:00000ab0.000007c8][INFO]             Directory removal queued: C:\Users\local_xxx1
[10:46:45.757][tid:00000ab0.000007c8][INFO]             Device cleanup queued
[10:46:45.757][tid:00000ab0.00001fe8][INFO]           ===== Begin Session:   Remove Local Directory
[10:46:45.757][tid:00000ab0.000007c8][INFO]              Configuration setting not found: SOFTWARE\FSLogix\Profiles\DeleteProfileOnLogoff.  Using default: 1
[10:46:45.757][tid:00000ab0.000007c8][INFO]              Configuration setting not found: SOFTWARE\FSLogix\Profiles\DeleteProfileTimeout.  Using default: 30000
[10:46:45.757][tid:00000ab0.00000c60][INFO]              Deleting profile on logoff
[10:46:45.757][tid:00000ab0.00000c60][ERROR:00000002]    Error removing profile key for sid S-1-5-21-3689327215-3964591171-841422420-3605 (Das System kann die angegebene Datei nicht finden.)
[10:46:45.757][tid:00000ab0.00001fe8][INFO]              Successfully removed C:\Users\local_xxx1
[10:46:45.757][tid:00000ab0.00001fe8][INFO]           ===== End Session:   Remove Local Directory
[10:46:45.757][tid:00000ab0.000007c8][INFO]             UnloadProfile successful.  User: xxx. SID: S-1-5-21-3689327215-3964591171-841422420-3605.
[10:46:45.757][tid:00000ab0.000007c8][INFO]             unloadProfile time: 156 milliseconds
[10:46:45.757][tid:00000ab0.000007c8][INFO]           ===== End Session:  Unload profile: xxx
[10:46:45.757][tid:00000ab0.000007c8][INFO]           ===== End Session: Logoff
[10:46:45.772][tid:00000ab0.00000ab4][INFO]           Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\ReAttachRetryCount.  Data: 1
[10:46:45.772][tid:00000ab0.00000ab4][INFO]           Configuration setting not found: SOFTWARE\FSLogix\Profiles\ReAttachIntervalSeconds.  Using default: 10
[10:46:45.772][tid:00000ab0.00000ab4][INFO]           ===== Begin Session: Volume re-attach
[10:46:45.772][tid:00000ab0.00000ab4][INFO]           ===== End Session: Volume re-attach
[10:46:45.866][tid:00000ab0.00002650][INFO]           ===== Begin Session: Remove Unused Device Information
[10:46:46.038][tid:00000ab0.00002650][INFO]            Devices removed: 8. Errors: 0
[10:46:46.038][tid:00000ab0.00002650][INFO]           ===== End Session: Remove Unused Device Information

and this is the part where a new profile disk is being created 

[10:44:42.090][tid:00000ab0.000007c8][INFO]             Attached vhd(x) successfully
[10:44:42.090][tid:00000ab0.000007c8][INFO]             VHD(x) attach request returning after 78 milliseconds
[10:44:42.090][tid:00000ab0.00000ab4][INFO]             Volume attach event
[10:44:43.184][tid:00000ab0.000007c8][INFO]             Detached vhd(x)
[10:44:43.184][tid:00000ab0.000007c8][INFO]             VHD(x) Detach request returning after 1063 milliseconds
[10:44:43.184][tid:00000ab0.000007c8][ERROR:00000002]   Creating new user profile disk (user's registry hive was missing) (Das System kann die angegebene Datei nicht finden.)
[10:44:43.184][tid:00000ab0.000007c8][INFO]             Renaming corrupt user profile disk. From: \\srv-dc2\UPDProfiles\S-1-5-21-3689327215-3964591171-841422420-3605_xxx\Profile_maik.schwenker.VHDX To: \\srv-dc2\UPDProfiles\S-1-5-21-3689327215-3964591171-841422420-3605_maik.schwenker\CORRUPT_99a07128-edfc-4065-9222-3ccd3a7e79fb_Profile_maik.schwenker.VHDX
[10:44:43.184][tid:00000ab0.000007c8][INFO]             Session configuration wrote (REG_SZ): SOFTWARE\FSLogix\Profiles\Sessions\S-1-5-21-3689327215-3964591171-841422420-3605\CorruptDiskFilename = 'CORRUPT_99a07128-edfc-4065-9222-3ccd3a7e79fb_Profile_xxx.VHDX'
[10:44:43.184][tid:00000ab0.000007c8][INFO]             Rename was successful
[10:44:43.184][tid:00000ab0.000007c8][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\SIDDirNamePattern.  Using default: %sid%_%username%
[10:44:43.184][tid:00000ab0.000007c8][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\VHDNamePattern.  Using default: Profile_%username%
[10:44:43.184][tid:00000ab0.000007c8][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\DisableRegistryLocalRedirect.  Using default: 1
[10:44:43.184][tid:00000ab0.000007c8][INFO]             Profile VHD not found.  Creating new VHD.
[10:44:43.184][tid:00000ab0.000007c8][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\DirectAttachOnCreateVHD.  Using default: 0
[10:44:43.184][tid:00000ab0.000007c8][INFO]             Virtual HD type: vhd
[10:44:43.184][tid:00000ab0.000007c8][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\VHDXSectorSize.  Using default: 0
[10:44:43.184][tid:00000ab0.000007c8][INFO]             Virtual HD sector size: 0
[10:44:43.184][tid:00000ab0.000007c8][INFO]             Configuration setting not found: SOFTWARE\FSLogix\Profiles\SizeInMBs.  Using default: 30000
[10:44:43.184][tid:00000ab0.000007c8][INFO]             Virtual HD size (MBs): 30000
[10:44:43.184][tid:00000ab0.000007c8][INFO]             Configuration Read (DWORD): SOFTWARE\FSLogix\Profiles\IsDynamic.  Data: 1
[10:44:43.184][tid:00000ab0.000007c8][INFO]             Virtual HD is: Dynamic
[10:44:43.184][tid:00000ab0.000007c8][INFO]           ===== Begin Session:   Create Formatted Disk
FSLogix
FSLogix
A set of solutions that enhance, enable, and simplify non-persistent Windows computing environments and may also be used to create more portable computing sessions when using physical devices.
463 questions

1 answer

Sort by: Most helpful
Most helpful Newest Oldest
  1. Sebastian Hoffmann 6 Reputation points
    2021-03-19T11:41:45.863+00:00

    Hi,

    thank you for your answer. We are currently experimenting with a new set of GPOs. We believe that there are some policies that don't fit together and are working against each other....