Share via

System state backups using MARS agent fail on VSS copy: The volume C:\Program Files\Microsoft Azure Recovery Services Agent\Scratch\SSBV\ is not found.

Nathan Hasey 45 Reputation points
2026-03-26T15:24:56.97+00:00

My backups for system state have been failing for the past week or so.

The actual backup finishes successfully but the VSS copy it performs fails with the error below.
Image

Log from CBEngineCurr.errlog

1DBC	0FAC	03/26	10:27:15.576	69	WindowsServerBackupProxy.cs(1225)		9338A017-4298-4074-B360-CC292782D99F		NORMAL	GetJobStatus::JobState: CopyingFiles | Params: {JobId  = 3/26/2026 4:08 AM}{Enumerated Files = 0}{Completed Percentage = 99}{OpMessage = 99% backup completed for System Writer ...}
1DBC	0FAC	03/26	10:27:15.576	71	dscontext.cpp(184)	[00000273F2947AA0]	9338A017-4298-4074-B360-CC292782D99F		ACTIVITY	Last completed state for Ds Id (52778112601008) is 33
1DBC	0FAC	03/26	10:27:18.607	69	WindowsServerBackupProxy.cs(1225)		9338A017-4298-4074-B360-CC292782D99F		NORMAL	GetJobStatus::JobState: CopyingFiles | Params: {JobId  = 3/26/2026 4:08 AM}{Enumerated Files = 0}{Completed Percentage = 99}{OpMessage = 99% backup completed for System Writer ...}
1DBC	0FAC	03/26	10:27:18.607	71	dscontext.cpp(184)	[00000273F2947AA0]	9338A017-4298-4074-B360-CC292782D99F		ACTIVITY	Last completed state for Ds Id (52778112601008) is 33
1DBC	0FAC	03/26	10:27:21.617	69	WindowsServerBackupProxy.cs(1225)		9338A017-4298-4074-B360-CC292782D99F		NORMAL	GetJobStatus::JobState: CopyingFiles | Params: {JobId  = 3/26/2026 4:08 AM}{Enumerated Files = 0}{Completed Percentage = 100}{OpMessage = 100% backup completed for Registry Writer ...}
1DBC	0FAC	03/26	10:27:21.617	71	dscontext.cpp(184)	[00000273F2947AA0]	9338A017-4298-4074-B360-CC292782D99F		ACTIVITY	Last completed state for Ds Id (52778112601008) is 33
1DBC	0FAC	03/26	10:27:24.633	69	WindowsServerBackupProxy.cs(1225)		9338A017-4298-4074-B360-CC292782D99F		NORMAL	GetJobStatus::JobState: CopyingFiles | Params: {JobId  = 3/26/2026 4:08 AM}{Enumerated Files = 0}{Completed Percentage = 100}{OpMessage = 100% backup completed for WMI Writer ...}
1DBC	0FAC	03/26	10:27:24.633	71	dscontext.cpp(184)	[00000273F2947AA0]	9338A017-4298-4074-B360-CC292782D99F		ACTIVITY	Last completed state for Ds Id (52778112601008) is 33
1DBC	0FAC	03/26	10:27:27.665	69	WindowsServerBackupProxy.cs(1225)		9338A017-4298-4074-B360-CC292782D99F		NORMAL	GetJobStatus::JobState: Running | Params: {JobId  = 3/26/2026 4:08 AM}{Enumerated Files = 0}{Completed Percentage = 0}{OpMessage = The system state backup has completed.}
1DBC	0FAC	03/26	10:27:27.665	71	dscontext.cpp(184)	[00000273F2947AA0]	9338A017-4298-4074-B360-CC292782D99F		ACTIVITY	Last completed state for Ds Id (52778112601008) is 30
1DBC	0FAC	03/26	10:27:30.697	69	WindowsServerBackupProxy.cs(1225)		9338A017-4298-4074-B360-CC292782D99F		NORMAL	GetJobStatus::JobState: ShadowCopy | Params: {JobId  = 3/26/2026 4:08 AM}{Enumerated Files = 0}{Completed Percentage = 0}{OpMessage = Creating a shadow copy of the backup storage location(s)...}
1DBC	0FAC	03/26	10:27:30.697	71	dscontext.cpp(184)	[00000273F2947AA0]	9338A017-4298-4074-B360-CC292782D99F		ACTIVITY	Last completed state for Ds Id (52778112601008) is 31
1DBC	0FAC	03/26	10:27:33.728	69	WindowsServerBackupProxy.cs(1225)		9338A017-4298-4074-B360-CC292782D99F		NORMAL	GetJobStatus::JobState: ShadowCopy | Params: {JobId  = 3/26/2026 4:08 AM}{Enumerated Files = 0}{Completed Percentage = 0}{OpMessage = Creating a shadow copy of the backup storage location(s)...}
1DBC	0FAC	03/26	10:27:33.728	71	dscontext.cpp(184)	[00000273F2947AA0]	9338A017-4298-4074-B360-CC292782D99F		ACTIVITY	Last completed state for Ds Id (52778112601008) is 31
1DBC	0FAC	03/26	10:27:36.759	69	WindowsServerBackupProxy.cs(1206)		9338A017-4298-4074-B360-CC292782D99F		NORMAL	GetJobStatus::JobState:  NotRunning | Params: {JobId  = }
1DBC	0FAC	03/26	10:27:36.759	69	WindowsServerBackupProxy.cs(1225)		9338A017-4298-4074-B360-CC292782D99F		NORMAL	GetJobStatus::JobState: NotRunning | Params: {JobId  = }{Enumerated Files = 0}{Completed Percentage = 0}{OpMessage = }
1DBC	0FAC	03/26	10:27:36.759	69	WindowsServerBackupProxy.cs(740)		9338A017-4298-4074-B360-CC292782D99F		NORMAL	Current Job jobId did not match:  | Params: {Expected Job Id = 3/26/2026 4:08 AM}{Found Job Id = }
1DBC	0FAC	03/26	10:27:37.775	69	WindowsServerBackupProxy.cs(1200)		9338A017-4298-4074-B360-CC292782D99F		NORMAL	GetJobStatus::JobState: Completed | Params: {JobId  = 3/26/2026 4:08 AM}
1DBC	0FAC	03/26	10:27:37.775	69	WindowsServerBackupProxy.cs(1225)		9338A017-4298-4074-B360-CC292782D99F		NORMAL	GetJobStatus::JobState: OperationComplete | Params: {JobId  = 3/26/2026 4:08 AM}{Enumerated Files = 0}{Completed Percentage = 0}{OpMessage = }
1DBC	0FAC	03/26	10:27:37.775	69	WindowsServerBackupProxy.cs(1034)		9338A017-4298-4074-B360-CC292782D99F		WARNING	WSB Job run duration reported: 8340 
1DBC	0FAC	03/26	10:27:37.775	69	WindowsServerBackupProxy.cs(1480)		9338A017-4298-4074-B360-CC292782D99F		WARNING	TraceWSBJobStatus::JobId:               3/26/2026 4:08 AM 
1DBC	0FAC	03/26	10:27:37.775	69	WindowsServerBackupProxy.cs(1481)		9338A017-4298-4074-B360-CC292782D99F		WARNING	TraceWSBJobStatus::JobState:            OperationComplete 
1DBC	0FAC	03/26	10:27:37.775	69	WindowsServerBackupProxy.cs(1482)		9338A017-4298-4074-B360-CC292782D99F		WARNING	TraceWSBJobStatus::EnumeratedFiles:     0 
1DBC	0FAC	03/26	10:27:37.775	69	WindowsServerBackupProxy.cs(1483)		9338A017-4298-4074-B360-CC292782D99F		WARNING	TraceWSBJobStatus::CompletedPercentage: 0 
1DBC	0FAC	03/26	10:27:37.775	69	WindowsServerBackupProxy.cs(1484)		9338A017-4298-4074-B360-CC292782D99F		WARNING	TraceWSBJobStatus::VersionId:           03/26/2026-08:08 
1DBC	0FAC	03/26	10:27:37.775	69	WindowsServerBackupProxy.cs(1485)		9338A017-4298-4074-B360-CC292782D99F		WARNING	TraceWSBJobStatus::JobType:             Backup 
1DBC	0FAC	03/26	10:27:37.775	69	WindowsServerBackupProxy.cs(1486)		9338A017-4298-4074-B360-CC292782D99F		WARNING	TraceWSBJobStatus::JobItems:            Microsoft.Windows.ServerBackup.Commands.WBItem[] 
1DBC	0FAC	03/26	10:27:37.775	69	WindowsServerBackupProxy.cs(1487)		9338A017-4298-4074-B360-CC292782D99F		WARNING	TraceWSBJobStatus::HResult:             0 
1DBC	0FAC	03/26	10:27:37.775	69	WindowsServerBackupProxy.cs(1488)		9338A017-4298-4074-B360-CC292782D99F		WARNING	TraceWSBJobStatus::DetailedHResult:     0 
1DBC	0FAC	03/26	10:27:37.775	69	WindowsServerBackupProxy.cs(1489)		9338A017-4298-4074-B360-CC292782D99F		WARNING	TraceWSBJobStatus::ErrorDescription:     
1DBC	0FAC	03/26	10:27:37.775	69	WindowsServerBackupProxy.cs(1490)		9338A017-4298-4074-B360-CC292782D99F		WARNING	TraceWSBJobStatus::StartTime:           3/26/2026 4:08 AM 
1DBC	0FAC	03/26	10:27:37.775	69	WindowsServerBackupProxy.cs(1491)		9338A017-4298-4074-B360-CC292782D99F		WARNING	TraceWSBJobStatus::EndTime:             3/26/2026 6:27 AM 
1DBC	0FAC	03/26	10:27:37.775	69	WindowsServerBackupProxy.cs(1492)		9338A017-4298-4074-B360-CC292782D99F		WARNING	TraceWSBJobStatus::SuccessLogPath:      C:\windows\Logs\WindowsServerBackup\Backup-26-03-2026_08-08-55.log 
1DBC	0FAC	03/26	10:27:37.775	69	WindowsServerBackupProxy.cs(1493)		9338A017-4298-4074-B360-CC292782D99F		WARNING	TraceWSBJobStatus::FailureLogPath:      C:\windows\Logs\WindowsServerBackup\Backup_Error-26-03-2026_08-08-55.log 
1DBC	0FAC	03/26	10:27:37.775	69	WindowsServerBackupProxy.cs(974)		9338A017-4298-4074-B360-CC292782D99F		WARNING	WSBErrorTrace : Reading error trace from file: C:\windows\Logs\WindowsServerBackup\Backup_Error-26-03-2026_08-08-55.log 
1DBC	0FAC	03/26	10:27:39.222	71	dscontext.cpp(184)	[00000273F2947AA0]	9338A017-4298-4074-B360-CC292782D99F		ACTIVITY	Last completed state for Ds Id (52778112601008) is 34
1DBC	0FAC	03/26	10:27:41.245	71	SystemStateAsyncWorker.cpp(333)	[00000273F1179EC0]	9338A017-4298-4074-B360-CC292782D99F		NORMAL	==>CAsyncSystemStateWorker::ValidateSystemStateBackup
1DBC	0FAC	03/26	10:27:41.245	69	WindowsServerBackupProxy.cs(801)		9338A017-4298-4074-B360-CC292782D99F		NORMAL	ValidateSystemStateBackup called  | Params: {TargetVolumePath  = C:\Program Files\Microsoft Azure Recovery Services Agent\Scratch\SSBV\}{ExpectedStartTime  = 3/26/2026 8:06:42 AM}
1DBC	0FAC	03/26	10:27:41.245	69	WSBHelper.cs(1417)		9338A017-4298-4074-B360-CC292782D99F		NORMAL	IsValidSSB files in path : C:\Program Files\Microsoft Azure Recovery Services Agent\Scratch\SSBV\ 
1DBC	0FAC	03/26	10:27:41.464	69	WSBHelper.cs(66)		9338A017-4298-4074-B360-CC292782D99F		WARNING	Error occurred during operation:CheckSSBInBackupSetScript; errors The volume C:\Program Files\Microsoft Azure Recovery Services Agent\Scratch\SSBV\ is not found. 
1DBC	0FAC	03/26	10:27:41.464	69	WSBHelper.cs(1448)		9338A017-4298-4074-B360-CC292782D99F		WARNING	IsValidSSB failed. Trying to reassign volume to path. path: C:\Program Files\Microsoft Azure Recovery Services Agent\Scratch\SSBV\ curTry: 1 exception: System.ArgumentException: The volume C:\Program Files\Microsoft Azure Recovery Services Agent\Scratch\SSBV\ is not found.
1DBC	0FAC	03/26	10:27:41.464	69	WSBHelper.cs(1448)		9338A017-4298-4074-B360-CC292782D99F		WARNING	   at Microsoft.Internal.CloudBackup.Client.Proxy.WindowsServerBackup.WSBHelper.PowerShellInvokeHelper(PowerShell psInstance, String operation, Boolean ignoreFailure)
1DBC	0FAC	03/26	10:27:41.464	69	WSBHelper.cs(1448)		9338A017-4298-4074-B360-CC292782D99F		WARNING	   at Microsoft.Internal.CloudBackup.Client.Proxy.WindowsServerBackup.WSBHelper.IsValidSSB(String sBackupFilesPath) 
1DBC	0FAC	03/26	10:27:41.557	69	WSBHelper.cs(713)		9338A017-4298-4074-B360-CC292782D99F		NORMAL	GetSystemStateVolume Volume Guid Found : \\?\Volume{6422bcc2-c38a-4a4b-a13e-8b13ecda1c58}\ 
1DBC	0FAC	03/26	10:27:41.557	69	WSBHelper.cs(615)		9338A017-4298-4074-B360-CC292782D99F		NORMAL	AssignVolumeToPath Volume Name : \\?\Volume{6422bcc2-c38a-4a4b-a13e-8b13ecda1c58}\ 
1DBC	0FAC	03/26	10:27:41.557	69	WSBHelper.cs(616)		9338A017-4298-4074-B360-CC292782D99F		NORMAL	AssignVolumeToPath Volume path : C:\Program Files\Microsoft Azure Recovery Services Agent\Scratch\SSBV 

The SSBV volume exists during the backup but some is removed before the vss copy operation can finish.

User's image

I have tried a few different things such as deleting the scratch dir and running the agent updater.

The backup job under Microsoft Azure Backup then fails.
User's image

There is one event log error related to VSS, this fires twice with each backup, once at the start of the system state and once right before the VSS copy.
User's image

Azure Backup
Azure Backup

An Azure backup service that provides built-in management at scale.


Your answer

Answers can be marked as 'Accepted' by the question author and 'Recommended' by moderators, which helps users know the answer solved the author's problem.