03:48:02.034: [13136.10224] <4> ov_log::OVInit: INF - Starting log file: C:\Program Files\VERITAS\NetBackup\logs\BPBKAR\101614.LOG


03:48:02.034: [13136.10224] <4> ov_log::OVInit: GENERAL Log Level: 0

03:48:02.034: [13136.10224] <4> ov_log::OVInit: TCP Log Level: 0

03:48:02.034: [13136.10224] <4> ov_log::OVInit: INF - the log mutex: 560
BPBKAR  NetBackup Backup/Archive  7.1GA  [Dec  7 2011]
Copyright © 1993 - 2011 Symantec Corporation, All Rights Reserved.
All Rights Reserved.


03:48:02.034: [13136.10224] <2> WinMain: DAT - _pgmptr = 'C:\Program Files\VERITAS\NetBackup\bin\bpbkar32.exe'

03:48:02.034: [13136.10224] <2> WinMain: DAT - lpCmdLine = '-r 604800 -ru root -dt 0 -to 0 -bpstart_time 1413427975 -clnt SERVERNAMEHERE -class POLICYNAMEHERE -sched Diff-Daily -st INCR -bpstart_to 300 -bpend_to 300 -read_to 900 -stream_count 9 -stream_number 1 -jobgrpid 278246 -ckpt_time 3600 -blks_per_buffer 512 -use_otm -fso -b SERVERNAMEHERE_1413427674 -kl 7 -ct 13 -use_ofb '

03:48:02.034: [13136.10224] <2> date_debug: DAT - timezone: GMT Standard Time, offset=0, dst: GMT Daylight Time

03:48:02.034: [13136.10224] <2> date_debug: DAT - current time: 1413427682, 16/10/2014 03:48:02

03:48:02.034: [13136.10224] <2> date_debug: DAT - 01/01/94 UCT:  757382400, 01/01/1994 01:00:00

03:48:02.035: [13136.10224] <2> date_debug: DAT - 07/01/94 UCT:  773020800, 01/07/1994 01:00:00

03:48:02.035: [13136.10224] <2> WinMain: DAT - standard input handle = 584

03:48:02.035: [13136.10224] <2> WinMain: DAT - standard output handle = 284

03:48:02.035: [13136.10224] <2> WinMain: DAT - standard error handle = 540

03:48:02.038: [13136.10224] <8> tar_process_backup_args: WRN - invalid option: -bpstart_time 1413427975

03:48:02.038: [13136.10224] <4> tar_backup::V_SetupJobData: INF - dwJobData: ffffffff

03:48:02.038: [13136.10224] <4> tar_backup::V_SetupJobData: INF -     dwJob: ffffffff

03:48:02.066: [13136.10224] <4> dos_backup::V_PreProcessing: INF - backup privileges enabled, previous = 0

03:48:02.066: [13136.10224] <4> dos_backup::V_PreProcessing: INF - restore privileges enabled, previous = 0

03:48:02.066: [13136.10224] <4> dos_backup::V_PreProcessing: INF - security privileges enabled, previous = 0

03:48:02.067: [13136.10224] <4> dos_backup::V_PreProcessing: INF - tcb privileges enabled, previous = 0

03:48:02.067: [13136.10224] <4> dos_backup::V_PreProcessing: INF - create token privileges enabled, previous = 0

03:48:02.067: [13136.10224] <4> dos_backup::V_PreProcessing: INF - user name: root

03:48:02.067: [13136.10224] <4> dos_backup::V_PreProcessing: INF - no access token: scheduled backup

03:48:02.067: [13136.10224] <2> ov_log::V_GlobalLog: WRN - ubsDetermineExchangeVersion(): RegOpenKeyEx() failed for HKLM\SOFTWARE\MICROSOFT\ExchangeServer\v14\Setup - 0x2.

03:48:02.067: [13136.10224] <2> ov_log::V_GlobalLog: INF - BEDS_Init() Enter InitFlags:0x0

03:48:03.554: [13136.10224] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: C:

03:48:03.554: [13136.10224] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: D:

03:48:03.554: [13136.10224] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Terminal Services

03:48:03.554: [13136.10224] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Windows Network

03:48:03.554: [13136.10224] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Shadow?Copy?Components

03:48:03.554: [13136.10224] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: System?State

03:48:03.554: [13136.10224] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Active Directory Application Mode

03:48:04.868: [13136.10224] <2> tar_base::V_vTarMsgW: INF - BACKUP START 13136

03:48:05.072: [13136.10224] <2> tar_backup::V_SetupProcessContinue: TAR - CONTINUE BACKUP received

03:48:05.072: [13136.10224] <2> tar_base::V_vTarMsgW: INF - CONTINUE BACKUP message received

03:48:05.072: [13136.10224] <4> tar_backup_cpr::start: INF - checkpoint thread started

03:48:05.272: [13136.10224] <2> tar_backup_tfi::setupFileDirectives: TAR - backup filename = SET SNAP_ID=SERVERNAMEHERE_1413427656

03:48:05.272: [13136.10224] <2> tar_backup_tfi::setupFileDirectives: TAR - backup filename = Shadow Copy Components:\

03:48:05.272: [13136.11428] <4> tar_base::keepaliveThread: INF - keepalive thread is active with an interval of 60 seconds

03:48:05.365: [13136.10224] <2> tar_backup_vxbsa::add: INF - called with 'SET SNAP_ID=SERVERNAMEHERE_1413427656'

03:48:05.365: [13136.10224] <2> tar_backup_vxbsa::add: INF - called with 'Shadow Copy Components:\'

03:48:05.365: [13136.10224] <4> dos_backup::V_VerifyFileSystem: INF - Added: System State:\

03:48:10.698: [13136.10224] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'Shadow Copy Components:\' --> 10020027

03:48:17.051: [13136.10224] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'System State:\' --> 1002002d

03:48:17.051: [13136.10224] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for '_BACKUP_SPECIAL_OBJECTS AFTER System State:' --> 1002002d

03:48:21.511: [13136.10224] <4> VssSnapshot::V_VssSetMetadata(): INF - Unable to locate DFSR metadata

03:48:22.964: [13136.10224] <4> dos_backup::V_InitializeShadowCopy: INF - ============================================

03:48:22.964: [13136.10224] <4> dos_backup::V_InitializeShadowCopy: INF - ShadowCopy PrePost: Begin

03:48:32.708: [13136.10224] <4> dos_backup::tfs_findfirst: INF - unable to find first file: 'Shadow Copy Components:\System Service\Background Intelligent Transfer Service\'

03:48:32.711: [13136.10224] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - Drive 'C:' added to VSS Volume Set

03:48:36.472: [13136.10224] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - VSS Volume Set snapped

03:48:36.472: [13136.10224] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - About to commence post-snap unbounded association checks

03:48:36.472: [13136.10224] <4> dos_backup::V_InitializeShadowCopy: INF - ShadowCopy PrePost: End

03:48:36.472: [13136.10224] <4> dos_backup::V_InitializeShadowCopy: INF - ============================================

03:48:36.472: [13136.10224] <4> VssSnapshot::V_VssSetMetadata(): INF - Unable to locate DFSR metadata

03:48:50.531: [13136.10224] <16> dos_backup::V_InitializeSystemState: ERR - FS_AddToSnapshotSet() Failed! (System State:\System Files\System Files) 0xE0000352:The specified volume does not exist. Recreate the volume, and then try the job again.

03:48:50.531: [13136.10224] <2> tar_base::V_vTarMsgW: ERR - Volume resolution error for (System State:\System Files\System Files)

03:48:50.531: [13136.10224] <2> tar_base::V_vTarMsgW: INF - EXIT STATUS 156: snapshot error encountered

03:48:50.531: [13136.10224] <4> backup_create: INF - NetBackup Temp Directory: 'C:\Program Files\VERITAS\\NetBackup\Temp'

03:48:50.531: [13136.10224] <16> tar_backup::SetExcludeFileList: **** DBG **** - alternate client fileter file is null

03:48:50.535: [13136.10224] <4> tar_backup_tfi::UpdateExcludeListWithVHD: INF - UpdateExludeListWithVHD begin

03:48:50.549: [13136.10224] <4> OVStopCmd: INF - EXIT - status = 0

03:48:50.549: [13136.10224] <2> tar_base::V_Close: closing...

03:48:51.691: [13136.10224] <2> ov_log::V_GlobalLog: INF - BEDS_Term(): enter - InitFlags:0x00000001

03:48:51.694: [13136.10224] <16> dtcp_read: TCP - failure: recv socket (584) (TCP 10053: Software caused connection abort)

03:48:52.694: [13136.10224] <16> dtcp_read: TCP - failure: recv socket (584) (TCP 10053: Software caused connection abort)

03:48:53.694: [13136.10224] <16> dtcp_read: TCP - failure: recv socket (584) (TCP 10053: Software caused connection abort)

03:48:54.694: [13136.10224] <16> dtcp_read: TCP - failure: recv socket (584) (TCP 10053: Software caused connection abort)

03:48:55.694: [13136.10224] <16> dtcp_read: TCP - failure: recv socket (584) (TCP 10053: Software caused connection abort)

03:48:56.694: [13136.10224] <16> dtcp_read: TCP - failure: recv socket (584) (TCP 10053: Software caused connection abort)

03:48:57.694: [13136.10224] <16> dtcp_read: TCP - failure: recv socket (584) (TCP 10053: Software caused connection abort)

03:48:58.694: [13136.10224] <16> dtcp_read: TCP - failure: recv socket (584) (TCP 10053: Software caused connection abort)

03:48:59.694: [13136.10224] <16> dtcp_read: TCP - failure: recv socket (584) (TCP 10053: Software caused connection abort)

03:49:00.694: [13136.10224] <16> dtcp_read: TCP - failure: recv socket (584) (TCP 10053: Software caused connection abort)

03:49:01.695: [13136.10224] <16> dtcp_read: TCP - failure: recv socket (584) (TCP 10053: Software caused connection abort)

03:49:01.695: [13136.10224] <4> OVShutdown: INF - Finished process

03:49:01.695: [13136.10224] <4> WinMain: INF - Exiting C:\Program Files\VERITAS\NetBackup\bin\bpbkar32.exe

03:49:03.695: [13136.10224] <4> ov_log::OVClose: INF - Closing log file: C:\Program Files\VERITAS\NetBackup\logs\BPBKAR\101614.LOG