Image Backups Failing - "Backing up System Reserved (SYSVOL) partition failed."

Hello,

I’m currently running Urbackup Server 2.3.8 on a bare-metal Server 2016 Standard system, with Stablebit’s DrivePool software creating a virtual disk to store backups/media.

I have five Windows 10 Pro clients running 2.3.4 that can backup files fine (full and incremental), but all clients are failing to create image backups.

From the web UI, it provides the error “Backing up System Reserved (SYSVOL) partition failed. Image backup failed”.

Detailed logs from the server show:

2019-04-01 19:29:40: Channel message: START IMAGE FULL
2019-04-01 19:29:40: msg=START IMAGE FULL
2019-04-01 19:29:40: Starting unscheduled full image backup of volume “C:”…
2019-04-01 19:29:40: Backing up SYSVOL…
2019-04-01 19:29:40: Starting unscheduled full image backup of volume “SYSVOL”…
2019-04-01 19:30:19: ERROR: Syncing file system failed. Image backup is not completely on disk. The request is not supported. (code: 50)
2019-04-01 19:30:19: Transferred 419.698 MB - Average speed: 89.2373 MBit/s
2019-04-01 19:30:19: Script does not exist urbackup\post_full_imagebackup.bat
2019-04-01 19:30:19: Updating statistics…
2019-04-01 19:30:19: Updating image stats…
2019-04-01 19:30:19: Updating file statistics…
2019-04-01 19:30:19: Done updating statistics.
2019-04-01 19:30:20: ERROR: Backing up System Reserved (SYSVOL) partition failed. Image backup failed
2019-04-01 19:30:20: Time taken for backing up client Darlene-Laptop: 40s
2019-04-01 19:30:20: ERROR: Backup failed
2019-04-01 19:30:20: msg=WAKEUP
2019-04-01 19:30:20: WARNING: Exponential backoff: Waiting at least 5h 20m before next image backup

And from the client:

2019-04-01 19:29:40: ClientService cmd: START IMAGE FULL#pw=YxsxJCiVtthndcC3Z21S3in4x9nO8U
2019-04-01 19:29:40: rc=0 hasError=true state=0
2019-04-01 19:29:40: ClientService cmd: #IqjgFEnwj7G0TowHeJY0e#MBR driveletter=SYSVOL&disk_path=SYSVOL&image_full=1&running_jobs=1&token=h3Mwg038iaX2WZ2xSxvI
2019-04-01 19:29:40: rc=0 hasError=true state=0
2019-04-01 19:29:40: ClientService cmd: #IqjgFEnwj7G0TowHeJY0e#2PING RUNNING pc_done=0&status_id=30&speed_bpms=0&total_bytes=-1&done_bytes=0&paused_fb=1#token=h3Mwg038iaX2WZ2xSxvI
2019-04-01 19:29:40: ClientService cmd: #IqjgFEnwj7G0TowHeJY0e#FULL IMAGE letter=SYSVOL&token=h3Mwg038iaX2WZ2xSxvI&checksum=1&bitmap=1&status_id=30&running_jobs=1&zero_skipped=1
2019-04-01 19:29:40: Filesystem type is ntfs (\?\Volume{9721ae85-0000-0000-0000-100000000000})
2019-04-01 19:29:40: TotalNumberOfClusters=140543 numberOfClusters=140543 n_clusters=17568 StartingLcn=0 BitmapSize=140543 r_bytes=17584
2019-04-01 19:29:40: FSINFO: blocksize=4096 size=575667712 has_error=false used_space=439169024
2019-04-01 19:29:41: ClientService cmd: STATUS#pw=YxsxJCiVtthndcC3Z21S3in4x9nO8U
2019-04-01 19:29:50: ClientService cmd: #IqjgFEnwj7G0TowHeJY0e#2PING RUNNING pc_done=29&status_id=30&speed_bpms=0&total_bytes=439169024&done_bytes=128102400&paused_fb=1#token=h3Mwg038iaX2WZ2xSxvI
2019-04-01 19:29:51: ClientService cmd: STATUS#pw=YxsxJCiVtthndcC3Z21S3in4x9nO8U
2019-04-01 19:30:00: ClientService cmd: #IqjgFEnwj7G0TowHeJY0e#2PING RUNNING pc_done=55&status_id=30&speed_bpms=0&total_bytes=439169024&done_bytes=242679808&paused_fb=1#token=h3Mwg038iaX2WZ2xSxvI
2019-04-01 19:30:01: ClientService cmd: STATUS#pw=YxsxJCiVtthndcC3Z21S3in4x9nO8U
2019-04-01 19:30:10: ClientService cmd: #IqjgFEnwj7G0TowHeJY0e#2PING RUNNING pc_done=84&status_id=30&speed_bpms=11920.5&total_bytes=439169024&done_bytes=367296512&paused_fb=1#token=h3Mwg038iaX2WZ2xSxvI
2019-04-01 19:30:11: ClientService cmd: STATUS#pw=YxsxJCiVtthndcC3Z21S3in4x9nO8U
2019-04-01 19:30:16: rc=0 hasError=true state=4
2019-04-01 19:30:16: Sending full image done
2019-04-01 19:30:20: rc=0 hasError=true state=0
2019-04-01 19:30:20: ClientService cmd: #IqjgFEnwj7G0TowHeJY0e#2LOGDATA 1554161420 0-1554161380-Starting unscheduled full image backup of volume “C:”…
2-1554161420-Backing up System Reserved (SYSVOL) partition failed. Image backup failed
0-1554161420-Time taken for backing up client Darlene-Laptop: 40s
2-1554161420-Backup failed

Following some Googling, I’ve tried disabling 8dot3names on both the clients and the server, with no change in behaviour.

Here is a screenshot of Disk Management for this particular client.

For another client, I tried mapping the System Reserved to drive letter V:, and got a different message.

Client (and it’s Disk Management):

2019-04-01 19:40:24: ClientService cmd: START IMAGE FULL#pw=sNyW2LkQ44CYT1KilB76WH0ComerQr
2019-04-01 19:40:24: rc=0 hasError=true state=0
2019-04-01 19:40:24: ClientService cmd: #IPH3OyhN9A5Q7DRecpMBg#MBR driveletter=SYSVOL&disk_path=SYSVOL&image_full=1&running_jobs=1&token=h3Mwg038iaX2WZ2xSxvI
2019-04-01 19:40:24: ClientService cmd: #IPH3OyhN9A5Q7DRecpMBg#2PING RUNNING pc_done=0&status_id=34&speed_bpms=0&total_bytes=-1&done_bytes=0&paused_fb=1#token=h3Mwg038iaX2WZ2xSxvI
2019-04-01 19:40:24: rc=0 hasError=true state=0
2019-04-01 19:40:24: ClientService cmd: #IPH3OyhN9A5Q7DRecpMBg#FULL IMAGE letter=SYSVOL&token=h3Mwg038iaX2WZ2xSxvI&checksum=1&bitmap=1&status_id=34&running_jobs=1&zero_skipped=1
2019-04-01 19:40:24: Script “C:\Program Files\UrBackup\preimagebackup.bat” does not exist
2019-04-01 19:40:24: Creating shadowcopy of “V:”…
2019-04-01 19:40:25: ClientService cmd: STATUS#pw=sNyW2LkQ44CYT1KilB76WH0ComerQr
2019-04-01 19:40:25: Number of Writers: 10
2019-04-01 19:40:25: Writer Task Scheduler Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:25: Writer VSS Metadata Store Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:25: Writer Performance Counters Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:25: Writer System Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:25: Writer ASR Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:25: Writer Shadow Copy Optimization Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:25: Writer Registry Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:25: Writer COM+ REGDB Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:25: Writer MSSearch Service Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:25: Writer WMI Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:25: Preparing change block tracking reset for volume v: failed: Incorrect function. (code: 1)
2019-04-01 19:40:25: Number of Writers: 10
2019-04-01 19:40:25: Writer Task Scheduler Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:25: Writer VSS Metadata Store Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:25: Writer Performance Counters Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:25: Writer System Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:25: Writer ASR Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:25: Writer Shadow Copy Optimization Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:25: Writer Registry Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:25: Writer COM+ REGDB Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:25: Writer MSSearch Service Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:25: Writer WMI Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:27: Number of Writers: 10
2019-04-01 19:40:27: Writer Task Scheduler Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:27: Writer VSS Metadata Store Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:27: Writer Performance Counters Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:27: Writer System Writer has failure state VSS_WS_WAITING_FOR_BACKUP_COMPLETE with error S_OK.
2019-04-01 19:40:27: Writer ASR Writer has failure state VSS_WS_WAITING_FOR_BACKUP_COMPLETE with error S_OK.
2019-04-01 19:40:27: Writer Shadow Copy Optimization Writer has failure state VSS_WS_WAITING_FOR_BACKUP_COMPLETE with error S_OK.
2019-04-01 19:40:27: Writer Registry Writer has failure state VSS_WS_WAITING_FOR_BACKUP_COMPLETE with error S_OK.
2019-04-01 19:40:27: Writer COM+ REGDB Writer has failure state VSS_WS_WAITING_FOR_BACKUP_COMPLETE with error S_OK.
2019-04-01 19:40:27: Writer MSSearch Service Writer has failure state VSS_WS_WAITING_FOR_BACKUP_COMPLETE with error S_OK.
2019-04-01 19:40:27: Writer WMI Writer has failure state VSS_WS_WAITING_FOR_BACKUP_COMPLETE with error S_OK.
2019-04-01 19:40:27: Shadowcopy path: \?\GLOBALROOT\Device\HarddiskVolumeShadowCopy14
2019-04-01 19:40:27: done.
2019-04-01 19:40:27: Disabling CBT on volume “V:”
2019-04-01 19:40:27: Filesystem type is ntfs (\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy14)
2019-04-01 19:40:27: TotalNumberOfClusters=127999 numberOfClusters=127999 n_clusters=16000 StartingLcn=0 BitmapSize=127999 r_bytes=16016
2019-04-01 19:40:27: Trying to exclude contents of file \?\GLOBALROOT\Device\HarddiskVolumeShadowCopy14\System Volume Information{3808876b-c176-4e48-b7ae-04046e6cc752} from backup…
2019-04-01 19:40:27: FSINFO: blocksize=4096 size=524287488 has_error=false used_space=332668928
2019-04-01 19:40:34: ClientService cmd: #IPH3OyhN9A5Q7DRecpMBg#2PING RUNNING pc_done=29&status_id=34&speed_bpms=0&total_bytes=332668928&done_bytes=96604160&paused_fb=1#token=h3Mwg038iaX2WZ2xSxvI
2019-04-01 19:40:35: ClientService cmd: STATUS#pw=sNyW2LkQ44CYT1KilB76WH0ComerQr
2019-04-01 19:40:44: ClientService cmd: #IPH3OyhN9A5Q7DRecpMBg#2PING RUNNING pc_done=66&status_id=34&speed_bpms=0&total_bytes=332668928&done_bytes=220094464&paused_fb=1#token=h3Mwg038iaX2WZ2xSxvI
2019-04-01 19:40:45: ClientService cmd: STATUS#pw=sNyW2LkQ44CYT1KilB76WH0ComerQr
2019-04-01 19:40:53: Sending full image done
2019-04-01 19:40:53: Deleting shadowcopy for path “\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy14” -2
2019-04-01 19:40:53: rc=0 hasError=true state=4
2019-04-01 19:40:54: ClientService cmd: #IPH3OyhN9A5Q7DRecpMBg#2PING RUNNING pc_done=100&status_id=34&speed_bpms=0&total_bytes=332668928&done_bytes=331030528&paused_fb=1#token=h3Mwg038iaX2WZ2xSxvI
2019-04-01 19:40:55: Number of Writers: 10
2019-04-01 19:40:55: Writer Task Scheduler Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:55: Writer VSS Metadata Store Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:55: Writer Performance Counters Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:55: Writer System Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:55: Writer ASR Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:55: Writer MSSearch Service Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:55: Writer Shadow Copy Optimization Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:55: Writer WMI Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:55: Writer Registry Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:55: Writer COM+ REGDB Writer has failure state VSS_WS_STABLE with error S_OK.
2019-04-01 19:40:55: Deleting Shadowcopy for dir “V:”
2019-04-01 19:40:55: ClientService cmd: STATUS#pw=sNyW2LkQ44CYT1KilB76WH0ComerQr
2019-04-01 19:40:58: ClientService cmd: #IPH3OyhN9A5Q7DRecpMBg#2LOGDATA 1554162057 0-1554162023-Starting unscheduled full image backup of volume “C:”…
2-1554162057-Backing up System Reserved (SYSVOL) partition failed. Image backup failed
0-1554162057-Time taken for backing up client Mark-PC: 33s
2-1554162057-Backup failed

Server:

2019-04-01 19:40:23: msg=START IMAGE FULL
2019-04-01 19:40:23: Starting unscheduled full image backup of volume “C:”…
2019-04-01 19:40:23: Backing up SYSVOL…
2019-04-01 19:40:23: Starting unscheduled full image backup of volume “SYSVOL”…
2019-04-01 19:40:30: Sending file “urbackup\www”
2019-04-01 19:40:30: Sending file: urbackup\www/index.htm
2019-04-01 19:40:30: Sending file: urbackup\www/index.htm done
2019-04-01 19:40:30: Sending file “urbackup\www/images/urbackup.png”
2019-04-01 19:40:30: Sending file: urbackup\www/images/urbackup.png
2019-04-01 19:40:30: Sending file: urbackup\www/images/urbackup.png done
2019-04-01 19:40:30: Sending file “urbackup\www/favicon.ico”
2019-04-01 19:40:30: Sending file: urbackup\www/favicon.ico
2019-04-01 19:40:30: Sending file: urbackup\www/favicon.ico done
2019-04-01 19:40:31: Sending file “urbackup\www/favicon.ico”
2019-04-01 19:40:31: Sending file: urbackup\www/favicon.ico
2019-04-01 19:40:31: Sending file: urbackup\www/favicon.ico done
2019-04-01 19:40:31: Sending file “urbackup\www/fonts/glyphicons-halflings-regular.woff2”
2019-04-01 19:40:31: Sending file: urbackup\www/fonts/glyphicons-halflings-regular.woff2
2019-04-01 19:40:31: Sending file: urbackup\www/fonts/glyphicons-halflings-regular.woff2 done
2019-04-01 19:40:56: ERROR: Syncing file system failed. Image backup is not completely on disk. The request is not supported. (code: 50)
2019-04-01 19:40:56: Transferred 317.923 MB - Average speed: 81.0863 MBit/s
2019-04-01 19:40:56: Script does not exist urbackup\post_full_imagebackup.bat
2019-04-01 19:40:56: Updating statistics…
2019-04-01 19:40:56: Updating image stats…
2019-04-01 19:40:56: Updating file statistics…
2019-04-01 19:40:56: Done updating statistics.
2019-04-01 19:40:57: ERROR: Backing up System Reserved (SYSVOL) partition failed. Image backup failed
2019-04-01 19:40:57: Time taken for backing up client Mark-PC: 33s
2019-04-01 19:40:57: ERROR: Backup failed
2019-04-01 19:40:57: Updating statistics…
2019-04-01 19:40:57: Updating image stats…
2019-04-01 19:40:57: Updating file statistics…
2019-04-01 19:40:57: Done updating statistics.
2019-04-01 19:40:57: msg=WAKEUP
2019-04-01 19:40:58: msg=WAKEUP
2019-04-01 19:40:58: WARNING: Exponential backoff: Waiting at least 10h 40m before next image backup
2019-04-01 19:41:34: Starting scheduled incremental image backup of volume “C:”…
2019-04-01 19:41:34: Backing up SYSVOL…
2019-04-01 19:41:34: Starting scheduled full image backup of volume “SYSVOL”…
2019-04-01 19:42:07: ERROR: Syncing file system failed. Image backup is not completely on disk. The request is not supported. (code: 50)
2019-04-01 19:42:07: Transferred 340.613 MB - Average speed: 87.3276 MBit/s
2019-04-01 19:42:07: Script does not exist urbackup\post_full_imagebackup.bat
2019-04-01 19:42:07: Updating statistics…
2019-04-01 19:42:07: Updating image stats…
2019-04-01 19:42:07: Updating file statistics…
2019-04-01 19:42:07: Done updating statistics.
2019-04-01 19:42:07: ERROR: Backing up System Reserved (SYSVOL) partition failed. Image backup failed
2019-04-01 19:42:07: Time taken for backing up client Server1: 33s
2019-04-01 19:42:07: ERROR: Backup failed
2019-04-01 19:42:08: msg=WAKEUP
2019-04-01 19:42:08: msg=WAKEUP
2019-04-01 19:42:08: WARNING: Exponential backoff: Waiting at least 5h 20m before next image backup

Any help would be greatly appreciated!

Looks like that Stablebit DrivePool thing does not support FlushFileBuffers().

Thanks for the quick response. Seems I’ll have to see about moving the backup location to some other storage solution.

Hallo,
ich benutze die gleiche Konfiguration wie Du. Bei mir schlägt die Abbildsicherung immer fehl. Würde mich auch einmal interessieren ob es schon eine Lösung gibt.