Backup's Failing with status code 13

[john10]

Hello Experts,

We are using NBU 7.0.1 on Win-2008 R2 standard server (Master/Media same server), while trying to take the backup for 1 client backup is failing with 13 error code. if i run a small backup it was completing with out any error, could any one help me on this.

Failed Job Details:-

4/2/2014 3:17:29 PM - requesting resource ch15w0001-hcart2-robot-tld-0
4/2/2014 3:17:29 PM - requesting resource ch15w0001.NBU_CLIENT.MAXJOBS.ch15w0002
4/2/2014 3:17:29 PM - requesting resource ch15w0001.NBU_POLICY.MAXJOBS.Test
4/2/2014 3:17:29 PM - granted resource ch15w0001.NBU_CLIENT.MAXJOBS.ch15w0002
4/2/2014 3:17:29 PM - granted resource ch15w0001.NBU_POLICY.MAXJOBS.Test
4/2/2014 3:17:29 PM - granted resource 0234L4
4/2/2014 3:17:29 PM - granted resource IBM.ULT3580-HH5.001
4/2/2014 3:17:29 PM - granted resource ch15w0001-hcart2-robot-tld-0
4/2/2014 3:17:29 PM - estimated 300 Kbytes needed
4/2/2014 3:17:30 PM - started process bpbrm (8580)
4/2/2014 3:18:04 PM - mounting 0234L4
4/2/2014 3:18:07 PM - connecting
4/2/2014 3:18:12 PM - connected; connect time: 00:00:05
4/2/2014 3:18:31 PM - mounted; mount time: 00:00:27
4/2/2014 3:18:31 PM - positioning 0234L4 to file 21
4/2/2014 3:19:16 PM - positioned 0234L4; position time: 00:00:45
4/2/2014 3:19:16 PM - begin writing
4/2/2014 3:52:06 PM - Error bpbrm(pid=2420) socket read failed, Access is denied.  (5)     
4/2/2014 3:53:03 PM - end writing; write time: 00:33:47
file read failed(13)
4/2/2014 3:53:13 PM - Error bpbrm(pid=8580) cannot send mail to root on client ch15w0002

[RamNagalla]

4/2/2014 3:52:06 PM - Error bpbrm(pid=2420) socket read failed, Access is denied.  (5)  

Check NetBackup Client service logon account. It sometimes reset to LocalSystem during an upgrade, which might result in insufficient permissions to backup all files/folders. Ensure logon account is a domain user with sufficient permissions.

Please enable bpbkar and bpfis log folders on Client for further troubleshooting.

From Marianne comment in below post

https://www-secure.symantec.com/connect/forums/13-file-read-failed-0#comment-6150401  

[john10]

Hello Nagalla,

I changed the NetBackup Client service to domain admin account(EID is included in Admin group on client server) still the backup failed with 13 status code with out writing any data, but in job deatails it showed as writing for 48 Minutes and failed.

Below are the logs,

Bpbkr Log:-

4:53:47.006 PM: [12336.7896] <4> ov_log::OVInit: INF - Starting log file: C:\Program Files\Veritas\NetBackup\logs\BPBKAR\040214.LOG

4:53:47.006 PM: [12336.7896] <4> ov_log::OVInit: GENERAL Log Level: 0
4:53:47.006 PM: [12336.7896] <4> ov_log::OVInit: TCP Log Level: 0
4:53:47.006 PM: [12336.7896] <4> ov_log::OVInit: INF - the log mutex: 452
BPBKAR  NetBackup Backup/Archive  7.0GA  [Jul 21 2010]
Copyright © 1993 - 2010 Symantec Corporation, All Rights Reserved.
All Rights Reserved.

4:53:47.007 PM: [12336.7896] <2> WinMain: DAT - _pgmptr = 'C:\Program Files\Veritas\NetBackup\bin\bpbkar32.exe'
4:53:47.007 PM: [12336.7896] <2> WinMain: DAT - lpCmdLine = '-r 8035200 -ru root -dt 7670 -to 0 -clnt ch15w0002 -class Test -sched Differential-Inc -st INCR -bpstart_to 300 -bpend_to 300 -read_to 7200 -ckpt_time 900 -blks_per_buffer 127 -use_otm -use_ofb -b ch15w0002_1396423049 -kl 365 -fso -WOFB_enabled -WOFB_fim 1 -WOFB_usage 0 -WOFB_error 0 -ct 13 '
4:53:47.009 PM: [12336.7896] <2> date_debug: DAT - timezone: China Standard Time, offset=-28800, dst: NONE
4:53:47.010 PM: [12336.7896] <2> date_debug: DAT - current time: 1396428827, 4/2/2014 4:53:47 PM
4:53:47.010 PM: [12336.7896] <2> date_debug: DAT - 01/01/94 UCT:  757382400, 1/1/1994 8:00:00 AM
4:53:47.010 PM: [12336.7896] <2> date_debug: DAT - 07/01/94 UCT:  773020800, 7/1/1994 8:00:00 AM
4:53:47.010 PM: [12336.7896] <2> WinMain: DAT - standard input handle = 612
4:53:47.010 PM: [12336.7896] <2> WinMain: DAT - standard output handle = 564
4:53:47.010 PM: [12336.7896] <2> WinMain: DAT - standard error handle = 584
4:53:47.017 PM: [12336.7896] <4> tar_backup::V_SetupJobData: INF - dwJobData: ffffffff
4:53:47.017 PM: [12336.7896] <4> tar_backup::V_SetupJobData: INF -     dwJob: ffffffff
4:53:47.063 PM: [12336.7896] <4> dos_backup::V_PreProcessing: INF - backup privileges enabled, previous = 0
4:53:47.063 PM: [12336.7896] <4> dos_backup::V_PreProcessing: INF - restore privileges enabled, previous = 0
4:53:47.064 PM: [12336.7896] <4> dos_backup::V_PreProcessing: INF - security privileges enabled, previous = 0
4:53:47.064 PM: [12336.7896] <4> dos_backup::V_PreProcessing: INF - tcb privileges enabled, previous = 0
4:53:47.065 PM: [12336.7896] <4> dos_backup::V_PreProcessing: INF - create token privileges enabled, previous = 0
4:53:47.065 PM: [12336.7896] <4> dos_backup::V_PreProcessing: INF - user name: root
4:53:47.065 PM: [12336.7896] <4> dos_backup::V_PreProcessing: INF - no access token: scheduled backup
4:53:47.067 PM: [12336.7896] <2> ov_log::V_GlobalLog: WRN - ubsDetermineExchangeVersion(): RegOpenKeyEx() failed for HKLM\SOFTWARE\MICROSOFT\ExchangeServer\v14\Setup - 0x2.
4:53:47.068 PM: [12336.7896] <2> ov_log::V_GlobalLog: INF - BEDS_Init() Enter InitFlags:0x0
4:53:47.210 PM: [12336.7896] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: C:
4:53:47.210 PM: [12336.7896] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: D:
4:53:47.210 PM: [12336.7896] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: F:
4:53:47.210 PM: [12336.7896] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: G:
4:53:47.210 PM: [12336.7896] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Terminal Services
4:53:47.210 PM: [12336.7896] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Windows Network
4:53:47.210 PM: [12336.7896] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: CH15W0002
4:53:47.210 PM: [12336.7896] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Shadow?Copy?Components
4:53:47.210 PM: [12336.7896] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: System?State
4:53:47.210 PM: [12336.7896] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Active Directory Application Mode
4:53:47.219 PM: [12336.7896] <2> tar_backupt_tfi::create: TAR - Incremental backup using the archive bit
4:53:47.422 PM: [12336.7896] <2> tar_backup::V_SetupProcessContinue: TAR - CONTINUE BACKUP received
4:53:47.423 PM: [12336.7896] <4> tar_backup_cpr::start: INF - checkpoint thread started
4:53:47.620 PM: [12336.7896] <2> tar_backup_tfi::setupFileDirectives: TAR - backup filename = ALL_LOCAL_DRIVES
4:53:47.620 PM: [12336.10200] <4> tar_base::keepaliveThread: INF - keepalive thread is active with an interval of 60 seconds
4:53:47.670 PM: [12336.7896] <4> V_Snapshot::V_Snapshot_Query: INF - Attempting to query volume snapshots: bpfis query -id ch15w0002_1396423049
4:53:48.673 PM: [12336.7896] <4> V_Snapshot::V_Snapshot_ParseBpfisOutput: INF - Snapshot creation, FIS_ID: ch15w0002_1396423049
4:53:48.673 PM: [12336.7896] <2> tar_backup_vxbsa::add: INF - called with '/'
4:53:48.673 PM: [12336.7896] <4> dos_backup::V_VerifyFileSystem: INF - Added: C:\
4:53:48.673 PM: [12336.7896] <4> dos_backup::V_VerifyFileSystem: INF - Added: D:\
4:53:48.673 PM: [12336.7896] <4> dos_backup::V_VerifyFileSystem: INF - Added: F:\
4:53:48.673 PM: [12336.7896] <4> dos_backup::V_VerifyFileSystem: INF - Added: G:\
4:53:48.673 PM: [12336.7896] <4> dos_backup::V_VerifyFileSystem: INF - Added: Shadow Copy Components:\
4:53:48.674 PM: [12336.7896] <4> dos_backup::V_VerifyFileSystem: INF - Added: System State:\
4:53:48.674 PM: [12336.7896] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'C:\' --> 10020002
4:53:48.674 PM: [12336.7896] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'D:\' --> 10020002
4:53:48.674 PM: [12336.7896] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'F:\' --> 10020002
4:53:48.674 PM: [12336.7896] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'G:\' --> 10020002
4:53:56.227 PM: [12336.7896] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'Shadow Copy Components:\' --> 10020027
4:53:59.386 PM: [12336.7896] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'System State:\' --> 1002002d
4:53:59.386 PM: [12336.7896] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for '_BACKUP_SPECIAL_OBJECTS AFTER System State:' --> 1002002d
4:54:02.334 PM: [12336.7896] <4> VssSnapshot::V_VssSetMetadata(): INF - Unable to locate DFSR metadata
4:54:04.640 PM: [12336.7896] <4> dos_backup::V_InitializeShadowCopy: INF - ============================================
4:54:04.640 PM: [12336.7896] <4> dos_backup::V_InitializeShadowCopy: INF - ShadowCopy PrePost: Begin
4:54:07.828 PM: [12336.7896] <4> dos_backup::tfs_findfirst: INF - unable to find first file: 'Shadow Copy Components:\System Service\Background Intelligent Transfer Service\'
4:54:08.102 PM: [12336.7896] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - Drive 'C:' added to VSS Volume Set
4:54:08.596 PM: [12336.7896] <16> VssSnapshot::V_VssVolumeSnapshot(): INF - VSS Volume Set prepare failed
4:54:08.596 PM: [12336.7896] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - About to commence post-snap unbounded association checks
4:54:08.596 PM: [12336.7896] <4> dos_backup::V_InitializeShadowCopy: INF - ShadowCopy PrePost: End
4:54:08.596 PM: [12336.7896] <4> dos_backup::V_InitializeShadowCopy: INF - ============================================
4:54:08.596 PM: [12336.7896] <4> VssSnapshot::V_VssSetMetadata(): INF - Unable to locate DFSR metadata

5:17:33.233 PM: [3380.12952] <4> ov_log::OVInit: INF - Starting log file: C:\Program Files\Veritas\NetBackup\logs\BPBKAR\040214.LOG

5:17:33.233 PM: [3380.12952] <4> ov_log::OVInit: GENERAL Log Level: 0
5:17:33.233 PM: [3380.12952] <4> ov_log::OVInit: TCP Log Level: 0
5:17:33.233 PM: [3380.12952] <4> ov_log::OVInit: INF - the log mutex: 452
BPBKAR  NetBackup Backup/Archive  7.0GA  [Jul 21 2010]
Copyright © 1993 - 2010 Symantec Corporation, All Rights Reserved.
All Rights Reserved.

5:17:33.234 PM: [3380.12952] <2> WinMain: DAT - _pgmptr = 'C:\Program Files\Veritas\NetBackup\bin\bpbkar32.exe'
5:17:33.234 PM: [3380.12952] <2> WinMain: DAT - lpCmdLine = '-r 8035200 -ru root -dt 9095 -to 0 -clnt ch15w0002 -class Test -sched Differential-Inc -st INCR -bpstart_to 300 -bpend_to 300 -read_to 7200 -ckpt_time 900 -blks_per_buffer 127 -use_otm -use_ofb -b ch15w0002_1396430208 -kl 365 -fso -WOFB_enabled -WOFB_fim 1 -WOFB_usage 0 -WOFB_error 0 -ct 13 '
5:17:33.238 PM: [3380.12952] <2> date_debug: DAT - timezone: China Standard Time, offset=-28800, dst: NONE
5:17:33.238 PM: [3380.12952] <2> date_debug: DAT - current time: 1396430253, 4/2/2014 5:17:33 PM
5:17:33.238 PM: [3380.12952] <2> date_debug: DAT - 01/01/94 UCT:  757382400, 1/1/1994 8:00:00 AM
5:17:33.238 PM: [3380.12952] <2> date_debug: DAT - 07/01/94 UCT:  773020800, 7/1/1994 8:00:00 AM
5:17:33.238 PM: [3380.12952] <2> WinMain: DAT - standard input handle = 628
5:17:33.238 PM: [3380.12952] <2> WinMain: DAT - standard output handle = 572
5:17:33.239 PM: [3380.12952] <2> WinMain: DAT - standard error handle = 576
5:17:33.248 PM: [3380.12952] <4> tar_backup::V_SetupJobData: INF - dwJobData: ffffffff
5:17:33.249 PM: [3380.12952] <4> tar_backup::V_SetupJobData: INF -     dwJob: ffffffff
5:17:33.317 PM: [3380.12952] <4> dos_backup::V_PreProcessing: INF - backup privileges enabled, previous = 0
5:17:33.318 PM: [3380.12952] <4> dos_backup::V_PreProcessing: INF - restore privileges enabled, previous = 0
5:17:33.319 PM: [3380.12952] <4> dos_backup::V_PreProcessing: INF - security privileges enabled, previous = 0
5:17:33.320 PM: [3380.12952] <4> dos_backup::V_PreProcessing: INF - tcb privileges enabled, previous = 0
5:17:33.321 PM: [3380.12952] <4> dos_backup::V_PreProcessing: INF - create token privileges enabled, previous = 0
5:17:33.321 PM: [3380.12952] <4> dos_backup::V_PreProcessing: INF - user name: root
5:17:33.321 PM: [3380.12952] <4> dos_backup::V_PreProcessing: INF - no access token: scheduled backup
5:17:33.323 PM: [3380.12952] <2> ov_log::V_GlobalLog: WRN - ubsDetermineExchangeVersion(): RegOpenKeyEx() failed for HKLM\SOFTWARE\MICROSOFT\ExchangeServer\v14\Setup - 0x2.
5:17:33.324 PM: [3380.12952] <2> ov_log::V_GlobalLog: INF - BEDS_Init() Enter InitFlags:0x0
5:17:33.414 PM: [3380.12952] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: C:
5:17:33.414 PM: [3380.12952] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: D:
5:17:33.414 PM: [3380.12952] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: F:
5:17:33.414 PM: [3380.12952] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: G:
5:17:33.414 PM: [3380.12952] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Terminal Services
5:17:33.414 PM: [3380.12952] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Windows Network
5:17:33.414 PM: [3380.12952] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: CH15W0002
5:17:33.414 PM: [3380.12952] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Shadow?Copy?Components
5:17:33.414 PM: [3380.12952] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: System?State
5:17:33.414 PM: [3380.12952] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Active Directory Application Mode
5:17:33.428 PM: [3380.12952] <2> tar_backupt_tfi::create: TAR - Incremental backup using the archive bit
5:17:33.630 PM: [3380.12952] <2> tar_backup::V_SetupProcessContinue: TAR - CONTINUE BACKUP received
5:17:33.630 PM: [3380.12952] <4> tar_backup_cpr::start: INF - checkpoint thread started
5:17:33.824 PM: [3380.12952] <2> tar_backup_tfi::setupFileDirectives: TAR - backup filename = ALL_LOCAL_DRIVES
5:17:33.825 PM: [3380.8444] <4> tar_base::keepaliveThread: INF - keepalive thread is active with an interval of 60 seconds
5:17:33.902 PM: [3380.12952] <4> V_Snapshot::V_Snapshot_Query: INF - Attempting to query volume snapshots: bpfis query -id ch15w0002_1396430208
5:17:34.907 PM: [3380.12952] <4> V_Snapshot::V_Snapshot_ParseBpfisOutput: INF - Snapshot creation, FIS_ID: ch15w0002_1396430208
5:17:34.907 PM: [3380.12952] <2> tar_backup_vxbsa::add: INF - called with '/'
5:17:34.907 PM: [3380.12952] <4> dos_backup::V_VerifyFileSystem: INF - Added: C:\
5:17:34.907 PM: [3380.12952] <4> dos_backup::V_VerifyFileSystem: INF - Added: D:\
5:17:34.907 PM: [3380.12952] <4> dos_backup::V_VerifyFileSystem: INF - Added: F:\
5:17:34.907 PM: [3380.12952] <4> dos_backup::V_VerifyFileSystem: INF - Added: G:\
5:17:34.907 PM: [3380.12952] <4> dos_backup::V_VerifyFileSystem: INF - Added: Shadow Copy Components:\
5:17:34.908 PM: [3380.12952] <4> dos_backup::V_VerifyFileSystem: INF - Added: System State:\
5:17:34.908 PM: [3380.12952] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'C:\' --> 10020002
5:17:34.908 PM: [3380.12952] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'D:\' --> 10020002
5:17:34.908 PM: [3380.12952] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'F:\' --> 10020002
5:17:34.908 PM: [3380.12952] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'G:\' --> 10020002
5:17:39.818 PM: [3380.12952] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'Shadow Copy Components:\' --> 10020027
5:17:42.807 PM: [3380.12952] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'System State:\' --> 1002002d
5:17:42.807 PM: [3380.12952] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for '_BACKUP_SPECIAL_OBJECTS AFTER System State:' --> 1002002d
5:17:46.211 PM: [3380.12952] <4> VssSnapshot::V_VssSetMetadata(): INF - Unable to locate DFSR metadata
5:21:19.517 PM: [3380.12952] <4> dos_backup::V_InitializeShadowCopy: INF - ============================================
5:21:19.517 PM: [3380.12952] <4> dos_backup::V_InitializeShadowCopy: INF - ShadowCopy PrePost: Begin
5:21:22.911 PM: [3380.12952] <4> dos_backup::tfs_findfirst: INF - unable to find first file: 'Shadow Copy Components:\System Service\Background Intelligent Transfer Service\'
5:21:22.922 PM: [3380.12952] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - Drive 'C:' added to VSS Volume Set
5:21:23.396 PM: [3380.12952] <16> VssSnapshot::V_VssVolumeSnapshot(): INF - VSS Volume Set prepare failed
5:21:23.396 PM: [3380.12952] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - About to commence post-snap unbounded association checks
5:21:23.396 PM: [3380.12952] <4> dos_backup::V_InitializeShadowCopy: INF - ShadowCopy PrePost: End
5:21:23.396 PM: [3380.12952] <4> dos_backup::V_InitializeShadowCopy: INF - ============================================
5:21:23.396 PM: [3380.12952] <4> VssSnapshot::V_VssSetMetadata(): INF - Unable to locate DFSR metadata
5:54:54.616 PM: [3380.12952] <2> ov_log::V_GlobalLog: ERR - BEDS_AttachToDLE():FS_AttachToDLE() DeviceName:'System?State' BackupReason:0x400 Failed! (0xE000FEC9:A failure occurred accessing the Writer metadata.
)
5:54:54.616 PM: [3380.12952] <2> tar_base::V_vTarMsgW: ERR - Unable to backup System State or Shadow Copy. Please check the state of VSS and associated Writers.5:54:54.616 PM: [3380.12952] <4> dos_backup::tfs_scanstart: INF - Backup Special Objects request AFTER System State:
5:54:54.619 PM: [3380.12952] <2> ov_log::V_GlobalLog: ERR - BEDS_AttachToDLE():FS_AttachToDLE() DeviceName:'System?State' BackupReason:0x400 Failed! (0xE000FEC9:A failure occurred accessing the Writer metadata.
)
5:54:54.620 PM: [3380.12952] <2> tar_base::V_vTarMsgW: ERR - Unable to backup System State or Shadow Copy. Please check the state of VSS and associated Writers.5:54:54.620 PM: [3380.12952] <8> backup_create: WRN - Unable to create temporary directory, C:\Program Files\Veritas\\NetBackup\Temp.
5:54:56.879 PM: [3380.12952] <4> tar_backup_tfi::UpdateExcludeListWithVHD: INF - UpdateExludeListWithVHD begin
5:55:00.354 PM: [3380.12952] <16> dtcp_write: TCP - failure: send socket (576) (TCP 10054: Connection reset by peer)
5:55:00.355 PM: [3380.12952] <16> dtcp_write: TCP - failure: attempted to send 21 bytes
5:55:00.418 PM: [3380.12952] <2> tar_base::V_vTarMsgW: INF - Enabling volume snapshots for (C:), please wait...
5:55:00.418 PM: [3380.12952] <16> dtcp_write: TCP - failure: send socket (576) (TCP 10054: Connection reset by peer)
5:55:00.418 PM: [3380.12952] <16> dtcp_write: TCP - failure: attempted to send 57 bytes
5:55:00.418 PM: [3380.12952] <4> V_DetermineMountInfo: INF - Checking Volume \\?\Volume{6f83ff02-0672-11e1-b64e-806e6f6e6963}\ for ''
5:55:00.418 PM: [3380.12952] <4> V_DetermineMountInfo: INF -   Adding NTFS Volume C:\ ==> \\?\Volume{6f83ff02-0672-11e1-b64e-806e6f6e6963}\
5:55:00.418 PM: [3380.12952] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - ======================================================================
5:55:00.418 PM: [3380.12952] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Attempting to create snapshots for 'C:'
5:55:00.418 PM: [3380.12952] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - CREATE request: C:\Program Files\Veritas\NetBackup\bin\bpfis create -owner NBU -fso -WOFB -fim VSS -id ch15w0002_1396430208 C:\
5:55:33.939 PM: [3380.8444] <16> dtcp_write: TCP - failure: send socket (576) (TCP 10054: Connection reset by peer)
5:55:33.939 PM: [3380.8444] <16> dtcp_write: TCP - failure: attempted to send 1 bytes
5:55:33.939 PM: [3380.8444] <16> tar_base::keepaliveThread: INF - keepalive thread abnormal exit :14

Bpfis Log:-

16:53:47.783 [6900.7500] <2> logparams: bpfis query -id ch15w0002_1396423049
16:53:47.790 [6900.7500] <4> bpfis: INF - BACKUP START 6900
16:53:47.790 [6900.7500] <32> bpfis: FTL - cannot open C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.ch15w0002_1396423049.0
16:53:47.790 [6900.7500] <4> bpfis: INF - EXIT STATUS 12: file open failed
17:17:33.989 [10088.10896] <2> logparams: bpfis query -id ch15w0002_1396430208
17:17:33.995 [10088.10896] <4> bpfis: INF - BACKUP START 10088
17:17:33.996 [10088.10896] <32> bpfis: FTL - cannot open C:\Program Files\Veritas\NetBackup\online_util\fi_cntl\bpfis.fim.ch15w0002_1396430208.0
17:17:33.996 [10088.10896] <4> bpfis: INF - EXIT STATUS 12: file open failed
17:55:00.502 [12764.5952] <2> logparams: C:\Program Files\Veritas\NetBackup\bin\bpfis create -owner NBU -fso -WOFB -fim VSS -id ch15w0002_1396430208 C:\
17:55:00.504 [12764.5952] <2> bpfis main: received FIM as [3] VSS
17:55:00.510 [12764.5952] <4> bpfis: INF - BACKUP START 12764
17:55:02.780 [12764.5952] <8> bpfis: WRN - VfMS error 10; see following messages:
17:55:02.780 [12764.5952] <8> bpfis: WRN - Non-fatal method error was reported
17:55:02.780 [12764.5952] <8> bpfis: WRN - vfm_configure_fi_one: method: FlashSnap, type: FIM, function: FlashSnap_init
17:55:02.780 [12764.5952] <8> bpfis: WRN - VfMS method error 3; see following message:
17:55:02.780 [12764.5952] <8> bpfis: WRN - FlashSnap_init: Veritas Volume Manager not installed.
17:55:02.783 [12764.5952] <8> bpfis: WRN - VfMS error 10; see following messages:
17:55:02.783 [12764.5952] <8> bpfis: WRN - Non-fatal method error was reported
17:55:02.783 [12764.5952] <8> bpfis: WRN - vfm_configure_fi_one: method: vxvm, type: FIM, function: vxvm_init
17:55:02.783 [12764.5952] <8> bpfis: WRN - VfMS method error 3; see following message:
17:55:02.783 [12764.5952] <8> bpfis: WRN - vxvm_init: Veritas Volume Manager not installed.
17:55:02.801 [12764.5952] <4> bpfis: INF - FIS_ID=ch15w0002_1396430208
17:55:02.809 [12764.5952] <4> bpfis: INF - Preparing freeze of C:\ using snapshot method VSS.
17:55:02.810 [12764.5952] <4> bpfis: INF - Created mount point C:\Program Files\Veritas\NetBackup\temp\_vrts_frzn_img_C__12764
17:55:02.810 [12764.5952] <2> read_vfm_conf: options=0x2
17:55:02.811 [12764.5952] <2> read_vfm_conf: Reading file: C:\Program Files\Veritas\NetBackup\vfm.conf
17:55:02.811 [12764.5952] <2> read_vfm_conf: Found FIM: auto
17:55:02.811 [12764.5952] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_auto.dll
17:55:02.811 [12764.5952] <2> read_vfm_conf: Found FIM: Hyper-V
17:55:02.811 [12764.5952] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found keyword nameuse
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found data type %dd
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found value specification client_name_type_list
17:55:02.811 [12764.5952] <2> parse_value_specification: Value specification='client_name_type_list'
17:55:02.811 [12764.5952] <2> parse_value_specification: Found default client_name_type_list
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found prompt Client Name Selection
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found keyword Virtual_machine_backup
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found data type %dd
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found value specification hyperv_backup_type_list
17:55:02.811 [12764.5952] <2> parse_value_specification: Value specification='hyperv_backup_type_list'
17:55:02.811 [12764.5952] <2> parse_value_specification: Found default hyperv_backup_type_list
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found prompt Virtual Machine Backup
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found keyword prov_type
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found data type %dd
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found value specification vss_provider_type_list
17:55:02.811 [12764.5952] <2> parse_value_specification: Value specification='vss_provider_type_list'
17:55:02.811 [12764.5952] <2> parse_value_specification: Found default vss_provider_type_list
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found prompt Provider Type
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found keyword snap_attr
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found data type %dd
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found value specification snapshot_attribute_list
17:55:02.811 [12764.5952] <2> parse_value_specification: Value specification='snapshot_attribute_list'
17:55:02.811 [12764.5952] <2> parse_value_specification: Found default snapshot_attribute_list
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found prompt Snapshot Attribute
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found keyword allow_offline_backup
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found data type %b
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found value specification 1
17:55:02.811 [12764.5952] <2> parse_value_specification: Value specification='1'
17:55:02.811 [12764.5952] <2> parse_value_specification: Found default 1
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found prompt Allow Offline Backup of VM
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found keyword csv_timeout
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found data type %d
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found value specification 0-:0
17:55:02.811 [12764.5952] <2> parse_value_specification: Value specification='0-:0'
17:55:02.811 [12764.5952] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
17:55:02.811 [12764.5952] <2> parse_value_specification: Found default 0
17:55:02.811 [12764.5952] <2> parse_ssm_parms: Found prompt CSV Timeout in Minutes
17:55:02.811 [12764.5952] <2> map_integer_value: virtual_machine_supported=Hyper-V, converted value=2, status=1
17:55:02.812 [12764.5952] <2> read_vfm_conf: Found FIM: VSS
17:55:02.812 [12764.5952] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libvfms.dll
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found keyword prov_type
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found data type %d
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found value specification 0-3:0
17:55:02.812 [12764.5952] <2> parse_value_specification: Value specification='0-3:0'
17:55:02.812 [12764.5952] <2> parse_value_specification: Found default 0
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found prompt Provider Type (0-auto 1-system 2-software 3-hardware)
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found keyword snap_attr
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found data type %d
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found value specification 0-2:0
17:55:02.812 [12764.5952] <2> parse_value_specification: Value specification='0-2:0'
17:55:02.812 [12764.5952] <2> parse_value_specification: Found default 0
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found prompt Snapshot Attribute(0-unspecified 1-differential 2-plex)
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found keyword max_snapshots
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found data type %d
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found value specification 1-:1
17:55:02.812 [12764.5952] <2> parse_value_specification: Value specification='1-:1'
17:55:02.812 [12764.5952] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
17:55:02.812 [12764.5952] <2> parse_value_specification: Found default 1
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)
17:55:02.812 [12764.5952] <2> read_vfm_conf: Found FIM: FlashSnap
17:55:02.812 [12764.5952] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_FlashSnap.dll
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found keyword keep_fi
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found data type %b
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found value specification 0
17:55:02.812 [12764.5952] <2> parse_value_specification: Value specification='0'
17:55:02.812 [12764.5952] <2> parse_value_specification: Found default 0
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found prompt Keep snapshot after backup (not Instant Recovery)
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found keyword async_resync
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found data type %b
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found value specification 0
17:55:02.812 [12764.5952] <2> parse_value_specification: Value specification='0'
17:55:02.812 [12764.5952] <2> parse_value_specification: Found default 0
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery)
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found keyword max_snapshots
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found data type %d
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found value specification 1-:1
17:55:02.812 [12764.5952] <2> parse_value_specification: Value specification='1-:1'
17:55:02.812 [12764.5952] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
17:55:02.812 [12764.5952] <2> parse_value_specification: Found default 1
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found keyword dg_split_retry_times
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found data type %d
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found value specification 1
17:55:02.812 [12764.5952] <2> parse_value_specification: Value specification='1'
17:55:02.812 [12764.5952] <2> parse_value_specification: Found default 1
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found prompt Number of times to retry disk group split
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found keyword dg_split_retry_wait_period
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found data type %d
17:55:02.812 [12764.5952] <2> parse_ssm_parms: Found value specification 60-:60
17:55:02.812 [12764.5952] <2> parse_value_specification: Value specification='60-:60'
17:55:02.812 [12764.5952] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
17:55:02.813 [12764.5952] <2> parse_value_specification: Found default 60
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found prompt Delay in seconds between disk group split retries
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found keyword num_sync_vols
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found data type %d
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found value specification 1-32:1
17:55:02.813 [12764.5952] <2> parse_value_specification: Value specification='1-32:1'
17:55:02.813 [12764.5952] <2> parse_value_specification: Found default 1
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found keyword iosize
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found data type %d
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found value specification 1-128:1
17:55:02.813 [12764.5952] <2> parse_value_specification: Value specification='1-128:1'
17:55:02.813 [12764.5952] <2> parse_value_specification: Found default 1
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only)
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found keyword slow
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found data type %d
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found value specification 0-:0
17:55:02.813 [12764.5952] <2> parse_value_specification: Value specification='0-:0'
17:55:02.813 [12764.5952] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
17:55:02.813 [12764.5952] <2> parse_value_specification: Found default 0
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only)
17:55:02.813 [12764.5952] <2> read_vfm_conf: Found FIM: vxvm
17:55:02.813 [12764.5952] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vxvm.dll
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found keyword async_resync
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found data type %b
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found value specification 0
17:55:02.813 [12764.5952] <2> parse_value_specification: Value specification='0'
17:55:02.813 [12764.5952] <2> parse_value_specification: Found default 0
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found prompt Resynchronize mirror in background (not Instant Recovery)
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found keyword max_snapshots
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found data type %d
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found value specification 1-:1
17:55:02.813 [12764.5952] <2> parse_value_specification: Value specification='1-:1'
17:55:02.813 [12764.5952] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
17:55:02.813 [12764.5952] <2> parse_value_specification: Found default 1
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found keyword sync_wait
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found data type %b
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found value specification 1
17:55:02.813 [12764.5952] <2> parse_value_specification: Value specification='1'
17:55:02.813 [12764.5952] <2> parse_value_specification: Found default 1
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found prompt Wait for mirror sync completion (Data Mover backups only)
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found keyword num_sync_vols
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found data type %d
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found value specification 1-32:1
17:55:02.813 [12764.5952] <2> parse_value_specification: Value specification='1-32:1'
17:55:02.813 [12764.5952] <2> parse_value_specification: Found default 1
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found prompt Maximum number of volumes to resynchronize concurrently
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found keyword iosize
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found data type %d
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found value specification 1-128:1
17:55:02.813 [12764.5952] <2> parse_value_specification: Value specification='1-128:1'
17:55:02.813 [12764.5952] <2> parse_value_specification: Found default 1
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found prompt Sync region size in MB (UNIX only)
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found keyword slow
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found data type %d
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found value specification 0-:0
17:55:02.813 [12764.5952] <2> parse_value_specification: Value specification='0-:0'
17:55:02.813 [12764.5952] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
17:55:02.813 [12764.5952] <2> parse_value_specification: Found default 0
17:55:02.813 [12764.5952] <2> parse_ssm_parms: Found prompt Sync I/O delay in milliseconds (UNIX only)
17:55:02.814 [12764.5952] <2> read_vfm_conf: Found FIM: VMware
17:55:02.814 [12764.5952] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_VMware.dll
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found keyword nameuse
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found data type %dd
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found value specification client_name_type_list
17:55:02.814 [12764.5952] <2> parse_value_specification: Value specification='client_name_type_list'
17:55:02.814 [12764.5952] <2> parse_value_specification: Found default client_name_type_list
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found prompt Client name selection
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found keyword Virtual_machine_backup
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found data type %dd
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found value specification vmware_backup_type_list
17:55:02.814 [12764.5952] <2> parse_value_specification: Value specification='vmware_backup_type_list'
17:55:02.814 [12764.5952] <2> parse_value_specification: Found default vmware_backup_type_list
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found prompt Virtual machine backup
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found keyword trantype
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found data type %dd
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found value specification VMware_transfer_list
17:55:02.814 [12764.5952] <2> parse_value_specification: Value specification='VMware_transfer_list'
17:55:02.814 [12764.5952] <2> parse_value_specification: Found default VMware_transfer_list
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found prompt Transfer type
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found keyword disable_quiesce
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found data type %dd
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found value specification enabled_disable_quiesce_list
17:55:02.814 [12764.5952] <2> parse_value_specification: Value specification='enabled_disable_quiesce_list'
17:55:02.814 [12764.5952] <2> parse_value_specification: Found default enabled_disable_quiesce_list
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found prompt Virtual machine quiesce
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found keyword file_system_optimization
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found data type %dd
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found value specification enabled_disabled_type_list
17:55:02.814 [12764.5952] <2> parse_value_specification: Value specification='enabled_disabled_type_list'
17:55:02.814 [12764.5952] <2> parse_value_specification: Found default enabled_disabled_type_list
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found prompt Exclude unused and deleted blocks
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found keyword snapact
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found data type %dd
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found value specification snapshot_handling_list
17:55:02.814 [12764.5952] <2> parse_value_specification: Value specification='snapshot_handling_list'
17:55:02.814 [12764.5952] <2> parse_value_specification: Found default snapshot_handling_list
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found prompt Existing snapshot handling
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found keyword vmmono
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found data type %dd
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found value specification disabled_enabled_type_list
17:55:02.814 [12764.5952] <2> parse_value_specification: Value specification='disabled_enabled_type_list'
17:55:02.814 [12764.5952] <2> parse_value_specification: Found default disabled_enabled_type_list
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found prompt Monolithic export (VCB only)
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found keyword mntpt
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found data type %s
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found prompt Snapshot mount point (VCB only)
17:55:02.814 [12764.5952] <2> map_integer_value: virtual_machine_supported=VMware, converted value=1, status=1
17:55:02.814 [12764.5952] <2> read_vfm_conf: Found FIM: NAS_Snapshot
17:55:02.814 [12764.5952] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_NAS_Snapshot.dll
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found keyword max_snapshots
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found data type %d
17:55:02.814 [12764.5952] <2> parse_ssm_parms: Found value specification 1-:1
17:55:02.815 [12764.5952] <2> parse_value_specification: Value specification='1-:1'
17:55:02.815 [12764.5952] <2> parse_value_specification: Null maximum value; using INT_MAX (2147483647)
17:55:02.815 [12764.5952] <2> parse_value_specification: Found default 1
17:55:02.815 [12764.5952] <2> parse_ssm_parms: Found prompt Maximum Snapshots (Instant Recovery only)
17:55:02.815 [12764.5952] <2> read_vfm_conf: Found FIM: VSS_Writer
17:55:02.815 [12764.5952] <2> read_vfm_conf: Found library: C:\Program Files\Veritas\NetBackup\bin\libfi_vss_writer.dll
 

[john10]

Hello Nagalla,

Have you find any issue in the Logs? could you please suggest me how to fix the issue.

[revarooo]

what does the command: vssadmin list writers show on the client ?

[john10]

Hello Revaroo,

Please find the below out put for "vssadmin list writers ,

C:\Windows\system32>vssadmin list writers
vssadmin 1.1 - Volume Shadow Copy Service administrative command-line tool
(C) Copyright 2001-2005 Microsoft Corp.

Writer name: 'System Writer'
   Writer Id: {e8132975-6f93-4464-a53e-1050253ae220}
   Writer Instance Id: {bc7f28af-2c8d-41f8-967a-902a938acb13}
   State: [1] Stable
   Last error: No error

Writer name: 'SqlServerWriter'
   Writer Id: {a65faa63-5ea8-4ebc-9dbd-a0c4db26912a}
   Writer Instance Id: {d6501453-d0ec-46d8-aa42-092063e6c47d}
   State: [1] Stable
   Last error: No error

Writer name: 'ASR Writer'
   Writer Id: {be000cbe-11fe-4426-9c58-531aa6355fc4}
   Writer Instance Id: {451d2c7b-ea82-45b8-934b-ac0147443405}
   State: [1] Stable
   Last error: No error

Writer name: 'Shadow Copy Optimization Writer'
   Writer Id: {4dc3bdd4-ab48-4d07-adb0-3bee2926fd7f}
   Writer Instance Id: {f601bf8a-eade-4ace-97b2-7641b7ccd353}
   State: [1] Stable
   Last error: No error

Writer name: 'IIS Config Writer'
   Writer Id: {2a40fd15-dfca-4aa8-a654-1f8c654603f6}
   Writer Instance Id: {1460b51c-bf5d-4c18-9da9-6f022a968d61}
   State: [1] Stable
   Last error: No error

Writer name: 'Registry Writer'
   Writer Id: {afbab4a2-367d-4d15-a586-71dbb18f8485}
   Writer Instance Id: {82109522-3d4b-428c-8279-f992c22ac157}
   State: [1] Stable
   Last error: No error

Writer name: 'COM+ REGDB Writer'
   Writer Id: {542da469-d3e1-473c-9f4f-7847f01fc64f}
   Writer Instance Id: {4f7b6070-cbe3-46dd-b44f-973e6ebbf1df}
   State: [1] Stable
   Last error: No error

Writer name: 'BITS Writer'
   Writer Id: {4969d978-be47-48b0-b100-f328f07ac1e0}
   Writer Instance Id: {3a886d09-3394-40d3-a76c-fcd7faf6cba8}
   State: [1] Stable
   Last error: No error

Writer name: 'WMI Writer'
   Writer Id: {a6ad56c2-b509-4e6c-bb19-49d8f43532f0}
   Writer Instance Id: {afe016ed-86d7-49c0-9bad-02d5815e7984}
   State: [5] Waiting for completion
   Last error: No error

Writer name: 'IIS Metabase Writer'
   Writer Id: {59b1f0cf-90ef-465f-9609-6ca8b2938366}
   Writer Instance Id: {e8de41a3-6aea-49a2-b80a-b6a458b633ed}
   State: [1] Stable
   Last error: No error

C:\Windows\system32>

[RamNagalla · Accepted solution]

5:21:23.396 PM: [3380.12952] <4> VssSnapshot::V_VssSetMetadata(): INF - Unable to locate DFSR metadata
5:54:54.616 PM: [3380.12952] <2> ov_log::V_GlobalLog: ERR - BEDS_AttachToDLE():FS_AttachToDLE() DeviceName:'System?State' BackupReason:0x400 Failed! (0xE000FEC9:A failure occurred accessing the Writer metadata.
)
5:54:54.616 PM: [3380.12952] <2> tar_base::V_vTarMsgW: ERR - Unable to backup System State or Shadow Copy. Please check the state of VSS and associated Writers.5:54:54.616 PM: [3380.12952] <4> dos_backup::tfs_scanstart: INF - Backup Special Objects request AFTER System State:
5:54:54.619 PM: [3380.12952] <2> ov_log::V_GlobalLog: ERR - BEDS_AttachToDLE():FS_AttachToDLE() DeviceName:'System?State' BackupReason:0x400 Failed! (0xE000FEC9:A failure occurred accessing the Writer metadata.
)
5:54:54.620 PM: [3380.12952] <2> tar_base::V_vTarMsgW: ERR - Unable to backup System State or Shadow Copy. Please check the state of VSS and associated Writers.5:54:54.620 PM: [3380.12952] <8> backup_create: WRN - Unable to create temporary directory, C:\Program Files\Veritas\\NetBackup\Temp.

have a look into the Below tech note

http://www.symantec.com/business/support/index?page=content&id=TECH161657