Exchange Differential backup fails with error -- system error occurred(130)
Environment
Exchange2010
OS = 2008R2
Cluster Nodes = Two
Yesterday and day before yesterday I was able to backed up Differential backup partially successfull because a Public Folder were not able to backed up But other Databases were backed up successfully in the same policy/job. Now the same policy is being failed with error 130. See the below Detail Status of Activity Monitor for both backup jobs which are Partial successfull and error .
In both times (backup job "Partial successful" and with "error") the Mailbox Database name ""Mailbox Database 0960687183"" was dismounted.
Partial Differential Backup
12/23/2011 6:17:16 AM - Info nbjm(pid=3276) starting backup job (jobid=124222) for client PRIMARYMBX, policy Exchange2010, schedule Exchange2010
12/23/2011 6:17:16 AM - Info nbjm(pid=3276) requesting STANDARD_RESOURCE resources from RB for backup job (jobid=124222, request id:{A05DAD10-83D1-4D02-ADD5-C111E39CEB79})
12/23/2011 6:17:16 AM - requesting resource Veritas-NBU-hcart-robot-tld-0
12/23/2011 6:17:16 AM - requesting resource Veritas-NBU.NBU_CLIENT.MAXJOBS.PRIMARYMBX
12/23/2011 6:17:16 AM - requesting resource Veritas-NBU.NBU_POLICY.MAXJOBS.Exchange2010
12/23/2011 6:17:16 AM - granted resource Veritas-NBU.NBU_CLIENT.MAXJOBS.PRIMARYMBX
12/23/2011 6:17:16 AM - granted resource Veritas-NBU.NBU_POLICY.MAXJOBS.Exchange2010
12/23/2011 6:17:16 AM - granted resource 2045L4
12/23/2011 6:17:16 AM - granted resource IBM.ULT3580-TD5.001
12/23/2011 6:17:16 AM - granted resource Veritas-NBU-hcart-robot-tld-0
12/23/2011 6:17:18 AM - estimated 2611999 Kbytes needed
12/23/2011 6:17:18 AM - begin Parent Job
12/23/2011 6:17:18 AM - begin Unknown Type, Step By Condition
Status 0
12/23/2011 6:17:18 AM - end Unknown Type, Step By Condition; elapsed time: 00:00:00
12/23/2011 6:17:18 AM - begin Unknown Type, Read File List
Status 0
12/23/2011 6:17:18 AM - end Unknown Type, Read File List; elapsed time: 00:00:00
12/23/2011 6:17:18 AM - begin Unknown Type, Create Snapshot
12/23/2011 6:17:18 AM - started process bpbrm (1860)
12/23/2011 6:17:18 AM - started
12/23/2011 6:17:19 AM - Info bpbrm(pid=1860) PRIMARYMBX is the host to backup data from
12/23/2011 6:17:23 AM - Info bpbrm(pid=1860) reading file list from client
12/23/2011 6:17:23 AM - Info bpbrm(pid=1860) start bpfis on client
12/23/2011 6:17:23 AM - begin Create Snapshot
12/23/2011 6:17:24 AM - Info bpfis(pid=1084) Backup started
12/23/2011 6:17:35 AM - Warning bpbrm(pid=1860) from client PRIMARYMBX: WRN - snapshot preparation successful, with the following condition: Exchange database <Mailbox Database 0960687183> is not mounted and cannot be backed up.
12/23/2011 6:17:51 AM - Info bpfis(pid=1084) done. status: 1
12/23/2011 6:17:51 AM - end Create Snapshot; elapsed time: 00:00:28
12/23/2011 6:17:54 AM - end writing
Status 1
12/23/2011 6:17:54 AM - end Unknown Type, Create Snapshot; elapsed time: 00:00:36
12/23/2011 6:17:54 AM - begin Unknown Type, Policy Execution Manager Preprocessed
Status 0
12/23/2011 6:17:59 AM - Info bpfis(pid=0) done. status: 1: the requested operation was partially successful
12/23/2011 6:19:54 AM - end Unknown Type, Policy Execution Manager Preprocessed; elapsed time: 00:02:00
12/23/2011 6:19:54 AM - begin Unknown Type, Delete Snapshot
12/23/2011 6:19:54 AM - started process bpbrm (5136)
12/23/2011 6:21:58 AM - end writing
Status 0
12/23/2011 6:21:58 AM - end Unknown Type, Delete Snapshot; elapsed time: 00:02:04
12/23/2011 6:21:58 AM - begin Unknown Type, Delete Snapshot On Exit
Status 0
12/23/2011 6:21:58 AM - end Unknown Type, Delete Snapshot On Exit; elapsed time: 00:00:00
Status 1
12/23/2011 6:21:58 AM - end Parent Job; elapsed time: 00:04:40
the requested operation was partially successful(1)
The job was successfully completed, but some files may have been
busy or unaccessible. See the problems report or the client's logs for more details.
Error on Differential Backup
12/26/2011 5:43:52 AM - granted resource Veritas-NBU.NBU_CLIENT.MAXJOBS.PRIMARYMBX
12/26/2011 5:43:52 AM - granted resource Veritas-NBU.NBU_POLICY.MAXJOBS.Exchange2010
12/26/2011 5:43:52 AM - granted resource 0006L5
12/26/2011 5:43:52 AM - granted resource IBM.ULT3580-TD5.000
12/26/2011 5:43:52 AM - granted resource Veritas-NBU-hcart-robot-tld-0
12/26/2011 5:43:52 AM - estimated 19827 Kbytes needed
12/26/2011 5:43:52 AM - begin Parent Job
12/26/2011 5:43:52 AM - begin Unknown Type, Step By Condition
Status 0
12/26/2011 5:43:52 AM - end Unknown Type, Step By Condition; elapsed time: 00:00:00
12/26/2011 5:43:52 AM - begin Unknown Type, Read File List
Status 0
12/26/2011 5:43:52 AM - end Unknown Type, Read File List; elapsed time: 00:00:00
12/26/2011 5:43:52 AM - begin Unknown Type, Create Snapshot
12/26/2011 5:43:52 AM - started process bpbrm (6592)
12/26/2011 5:43:52 AM - started
12/26/2011 5:43:53 AM - Info bpbrm(pid=6592) PRIMARYMBX is the host to backup data from
12/26/2011 5:43:56 AM - Info bpbrm(pid=6592) reading file list from client
12/26/2011 5:43:56 AM - Info bpbrm(pid=6592) start bpfis on client
12/26/2011 5:43:56 AM - begin Create Snapshot
12/26/2011 5:43:57 AM - Info bpfis(pid=6064) Backup started
12/26/2011 5:44:12 AM - Warning bpbrm(pid=6592) from client PRIMARYMBX: WRN - snapshot preparation successful, with the following condition: Exchange database <Mailbox Database 0960687183> is not mounted and cannot be backed up.
12/26/2011 5:44:23 AM - Critical bpbrm(pid=6592) from client PRIMARYMBX: FTL - snapshot creation failed - SNAPSHOT_NOTIFICATION::PostSnapshot failed., status 130
12/26/2011 5:44:23 AM - Info bpbrm(pid=6592) DB_BACKUP_STATUS is 156
12/26/2011 5:44:23 AM - Info bpbrm(pid=6592) DB_BACKUP_STATUS is 156
12/26/2011 5:44:24 AM - Info bpbrm(pid=6592) DB_BACKUP_STATUS is 156
12/26/2011 5:44:24 AM - Info bpbrm(pid=6592) DB_BACKUP_STATUS is 156
12/26/2011 5:44:24 AM - Info bpbrm(pid=6592) DB_BACKUP_STATUS is 156
12/26/2011 5:44:24 AM - Critical bpbrm(pid=6592) from client PRIMARYMBX: FTL - snapshot creation failed, status 130
12/26/2011 5:44:24 AM - Warning bpbrm(pid=6592) from client PRIMARYMBX: WRN - NEW_STREAM0 is not frozen
12/26/2011 5:44:24 AM - Warning bpbrm(pid=6592) from client PRIMARYMBX: WRN - Microsoft Information Store:\DB1 is not frozen
12/26/2011 5:44:24 AM - Warning bpbrm(pid=6592) from client PRIMARYMBX: WRN - Microsoft Information Store:\DB2 is not frozen
12/26/2011 5:44:24 AM - Warning bpbrm(pid=6592) from client PRIMARYMBX: WRN - Microsoft Information Store:\DB3_New is not frozen
12/26/2011 5:44:24 AM - Warning bpbrm(pid=6592) from client PRIMARYMBX: WRN - Microsoft Information Store:\Mailbox Database 0960687183 is not frozen
12/26/2011 5:44:24 AM - Warning bpbrm(pid=6592) from client PRIMARYMBX: WRN - Microsoft Information Store:\Public Folder Database 0652434192 is not frozen
12/26/2011 5:44:24 AM - Info bpfis(pid=6064) done. status: 130
12/26/2011 5:44:24 AM - end Create Snapshot; elapsed time: 00:00:28
12/26/2011 5:44:26 AM - end writing
Status 130
12/26/2011 5:44:26 AM - end Unknown Type, Create Snapshot; elapsed time: 00:00:34
12/26/2011 5:44:26 AM - begin Unknown Type, Stop On Error
Status 0
12/26/2011 5:44:26 AM - end Unknown Type, Stop On Error; elapsed time: 00:00:00
12/26/2011 5:44:26 AM - begin Unknown Type, Delete Snapshot On Exit
12/26/2011 5:44:26 AM - begin Unknown Type, Delete Snapshot On Exit
12/26/2011 5:44:26 AM - started process bpbrm (4856)
12/26/2011 5:44:31 AM - Info bpfis(pid=0) done. status: 130: system error occurred
12/26/2011 5:44:35 AM - end writing
Status 0
12/26/2011 5:44:35 AM - end Unknown Type, Delete Snapshot On Exit; elapsed time: 00:00:09
Status 130
12/26/2011 5:44:35 AM - end Unknown Type, Delete Snapshot On Exit; elapsed time: 00:00:09
system error occurred(130) 12/26/2011 5:43:52 AM - granted resource Veritas-NBU.NBU_CLIENT.MAXJOBS.PRIMARYMBX
12/26/2011 5:43:52 AM - granted resource Veritas-NBU.NBU_POLICY.MAXJOBS.Exchange2010
12/26/2011 5:43:52 AM - granted resource 0006L5
12/26/2011 5:43:52 AM - granted resource IBM.ULT3580-TD5.000
12/26/2011 5:43:52 AM - granted resource Veritas-NBU-hcart-robot-tld-0
12/26/2011 5:43:52 AM - estimated 19827 Kbytes needed
12/26/2011 5:43:52 AM - begin Parent Job
12/26/2011 5:43:52 AM - begin Unknown Type, Step By Condition
Status 0
12/26/2011 5:43:52 AM - end Unknown Type, Step By Condition; elapsed time: 00:00:00
12/26/2011 5:43:52 AM - begin Unknown Type, Read File List
Status 0
12/26/2011 5:43:52 AM - end Unknown Type, Read File List; elapsed time: 00:00:00
12/26/2011 5:43:52 AM - begin Unknown Type, Create Snapshot
12/26/2011 5:43:52 AM - started process bpbrm (6592)
12/26/2011 5:43:52 AM - started
12/26/2011 5:43:53 AM - Info bpbrm(pid=6592) PRIMARYMBX is the host to backup data from
12/26/2011 5:43:56 AM - Info bpbrm(pid=6592) reading file list from client
12/26/2011 5:43:56 AM - Info bpbrm(pid=6592) start bpfis on client
12/26/2011 5:43:56 AM - begin Create Snapshot
12/26/2011 5:43:57 AM - Info bpfis(pid=6064) Backup started
12/26/2011 5:44:12 AM - Warning bpbrm(pid=6592) from client PRIMARYMBX: WRN - snapshot preparation successful, with the following condition: Exchange database <Mailbox Database 0960687183> is not mounted and cannot be backed up.
12/26/2011 5:44:23 AM - Critical bpbrm(pid=6592) from client PRIMARYMBX: FTL - snapshot creation failed - SNAPSHOT_NOTIFICATION::PostSnapshot failed., status 130
12/26/2011 5:44:23 AM - Info bpbrm(pid=6592) DB_BACKUP_STATUS is 156
12/26/2011 5:44:23 AM - Info bpbrm(pid=6592) DB_BACKUP_STATUS is 156
12/26/2011 5:44:24 AM - Info bpbrm(pid=6592) DB_BACKUP_STATUS is 156
12/26/2011 5:44:24 AM - Info bpbrm(pid=6592) DB_BACKUP_STATUS is 156
12/26/2011 5:44:24 AM - Info bpbrm(pid=6592) DB_BACKUP_STATUS is 156
12/26/2011 5:44:24 AM - Critical bpbrm(pid=6592) from client PRIMARYMBX: FTL - snapshot creation failed, status 130
12/26/2011 5:44:24 AM - Warning bpbrm(pid=6592) from client PRIMARYMBX: WRN - NEW_STREAM0 is not frozen
12/26/2011 5:44:24 AM - Warning bpbrm(pid=6592) from client PRIMARYMBX: WRN - Microsoft Information Store:\DB1 is not frozen
12/26/2011 5:44:24 AM - Warning bpbrm(pid=6592) from client PRIMARYMBX: WRN - Microsoft Information Store:\DB2 is not frozen
12/26/2011 5:44:24 AM - Warning bpbrm(pid=6592) from client PRIMARYMBX: WRN - Microsoft Information Store:\DB3_New is not frozen
12/26/2011 5:44:24 AM - Warning bpbrm(pid=6592) from client PRIMARYMBX: WRN - Microsoft Information Store:\Mailbox Database 0960687183 is not frozen
12/26/2011 5:44:24 AM - Warning bpbrm(pid=6592) from client PRIMARYMBX: WRN - Microsoft Information Store:\Public Folder Database 0652434192 is not frozen
12/26/2011 5:44:24 AM - Info bpfis(pid=6064) done. status: 130
12/26/2011 5:44:24 AM - end Create Snapshot; elapsed time: 00:00:28
12/26/2011 5:44:26 AM - end writing
Status 130
12/26/2011 5:44:26 AM - end Unknown Type, Create Snapshot; elapsed time: 00:00:34
12/26/2011 5:44:26 AM - begin Unknown Type, Stop On Error
Status 0
12/26/2011 5:44:26 AM - end Unknown Type, Stop On Error; elapsed time: 00:00:00
12/26/2011 5:44:26 AM - begin Unknown Type, Delete Snapshot On Exit
12/26/2011 5:44:26 AM - begin Unknown Type, Delete Snapshot On Exit
12/26/2011 5:44:26 AM - started process bpbrm (4856)
12/26/2011 5:44:31 AM - Info bpfis(pid=0) done. status: 130: system error occurred
12/26/2011 5:44:35 AM - end writing
Status 0
12/26/2011 5:44:35 AM - end Unknown Type, Delete Snapshot On Exit; elapsed time: 00:00:09
Status 130
12/26/2011 5:44:35 AM - end Unknown Type, Delete Snapshot On Exit; elapsed time: 00:00:09
system error occurred(130)