12:55:37.655 [5196.5992] <4> ov_log::OVInit: INF - Starting log file: C:\Program Files\Veritas\NetBackup\logs\BPBKAR\110717.log 12:55:37.655 [5196.5992] <4> ov_log::OVInit: GENERAL Log Level (Effective): 0 12:55:37.655 [5196.5992] <4> ov_log::OVInit: TCP Log Level (Effective): 0 12:55:37.655 [5196.5992] <8> OVLog: BPBKAR NetBackup Backup/Archive 7.6GA [May 22 2015] 12:55:37.655 [5196.5992] <8> OVLog: Copyright © 1993 - 2015 Symantec Corporation, All Rights Reserved. All Rights Reserved. 12:55:37.655 [5196.5992] <2> WinMain: BPBKAR_VERBOSE Debug log level: Actual=0, Effective=0 12:55:37.655 [5196.5992] <2> WinMain: DAT - _pgmptr = 'C:\Program Files\Veritas\NetBackup\bin\bpbkar32.exe' 12:55:37.655 [5196.5992] <2> WinMain: DAT - lpCmdLine = '-r 2678400 -ru root -dt 0 -to 0 -bpstart_time 0 -clnt abcsqlabc001 -class TEst-DNY4_Win_Servers_1a -sched Weekly_Full -st FULL -bpstart_to 300 -bpend_to 300 -read_to 30000 -ckpt_time 900 -blks_per_buffer 127 -stream_count 1 -stream_number 1 -jobgrpid 74195 -use_otm -use_ofb -b abcsqlabc001_1510057053 -kl 28 -fso -ct 13 ' 12:55:37.655 [5196.5992] <2> date_debug: DAT - timezone: GMT Standard Time, offset=0, dst: GMT Daylight Time 12:55:37.655 [5196.5992] <2> date_debug: DAT - current time: 1510059337, 11/7/2017 12:55:37 PM 12:55:37.655 [5196.5992] <2> date_debug: DAT - 01/01/94 UCT: 757382400, 1/1/1994 12:00:00 AM 12:55:37.655 [5196.5992] <2> date_debug: DAT - 07/01/94 UCT: 773020800, 7/1/1994 1:00:00 AM 12:55:37.655 [5196.5992] <2> WinMain: DAT - standard input handle = 548 12:55:37.655 [5196.5992] <2> WinMain: DAT - standard input handle is NOT resilient socket. 12:55:37.655 [5196.5992] <2> WinMain: DAT - standard output handle = 556 12:55:37.655 [5196.5992] <2> WinMain: DAT - standard output handle is NOT resilient socket. 12:55:37.655 [5196.5992] <2> WinMain: DAT - standard error handle = 544 12:55:37.655 [5196.5992] <2> WinMain: DAT - standard error handle is NOT resilient socket. 12:55:37.671 [5196.5992] <4> tar_backup::V_SetupJobData: INF - dwJobData: ffffffff 12:55:37.671 [5196.5992] <4> tar_backup::V_SetupJobData: INF - dwJob: ffffffff 12:55:37.671 [5196.5992] <4> dos_backup::V_PreProcessing: INF - backup privileges enabled, previous = 0 12:55:37.671 [5196.5992] <4> dos_backup::V_PreProcessing: INF - restore privileges enabled, previous = 0 12:55:37.671 [5196.5992] <4> dos_backup::V_PreProcessing: INF - security privileges enabled, previous = 0 12:55:37.671 [5196.5992] <4> dos_backup::V_PreProcessing: INF - tcb privileges enabled, previous = 0 12:55:37.671 [5196.5992] <4> dos_backup::V_PreProcessing: INF - create token privileges enabled, previous = 0 12:55:37.671 [5196.5992] <4> dos_backup::V_PreProcessing: INF - user name: root 12:55:37.671 [5196.5992] <4> dos_backup::V_PreProcessing: INF - no access token: scheduled backup 12:55:37.671 [5196.5992] <2> ov_log::V_GlobalLog: WRN - ubsVerifyMIS():Microsoft Exchange Information Store is not installed. 12:55:37.671 [5196.5992] <2> ov_log::V_GlobalLog: INF - BEDS_Init() Enter InitFlags:0x0 12:55:37.967 [5196.5992] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: C: 12:55:37.967 [5196.5992] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: D: 12:55:37.967 [5196.5992] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: E: 12:55:37.967 [5196.5992] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: F: 12:55:37.967 [5196.5992] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: EFI>System>Partition 12:55:37.967 [5196.5992] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Terminal Services 12:55:37.967 [5196.5992] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Windows Network 12:55:37.967 [5196.5992] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: abcsqlabc001\Microsoft SQL Server Instances 12:55:37.967 [5196.5992] <2> ov_log::V_GlobalLog: INF - DumpChildDleInfo() Child DLE Device Name: abcsqlabc001\MSSQLSERVER 12:55:37.967 [5196.5992] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: System?State 12:55:37.967 [5196.5992] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Active Directory Application Mode 12:55:37.999 [5196.5992] <2> tar_base::V_vTarMsgW: INF - BACKUP START 5196 12:55:37.999 [5196.5992] <2> tar_base::V_vTarMsgW: JBD - change time comparison: 12:55:37.999 [5196.5992] <2> tar_base::V_vTarMsgW: JBD - archive bit processing: 12:55:38.045 [5196.5992] <2> tar_backup::V_SetupProcessContinue: TAR - CONTINUE BACKUP received 12:55:38.045 [5196.5992] <2> tar_base::V_vTarMsgW: INF - CONTINUE BACKUP message received 12:55:38.045 [5196.5992] <4> tar_backup_cpr::start: INF - checkpoint thread started 12:55:38.264 [5196.5992] <2> tar_backup_tfi::setupFileDirectives: TAR - backup filename = SET SNAP_ID=abcsqlabc001_1510057044 12:55:38.264 [5196.5992] <2> tar_backup_tfi::setupFileDirectives: TAR - backup filename = Shadow Copy Components:\ 12:55:38.264 [5196.1160] <4> tar_base::keepaliveThread: INF - keepalive thread is active with an interval of 60 seconds 12:55:38.326 [5196.5992] <2> tar_backup_vxbsa::add: INF - called with 'SET SNAP_ID=abcsqlabc001_1510057044' 12:55:38.326 [5196.5992] <2> tar_backup_vxbsa::add: INF - called with 'Shadow Copy Components:\' 12:55:38.326 [5196.5992] <2> _expand_root: INF - checking for root expansion: 12:55:38.326 [5196.5992] <2> _fix_registry: INF - checking for registry expansion: 12:55:38.326 [5196.5992] <2> _fix_system_state: INF - Added: System State:\ 12:55:39.527 [5196.5992] <4> dos_backup::V_VerifyFileList: INF - Hidden Device, Ignoring filelist entry for 'Shadow Copy Components:\' 12:55:39.527 [5196.5992] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'Shadow Copy Components:\' --> 10020027 12:55:40.557 [5196.5992] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'System State:\' --> 1002002d 12:55:40.557 [5196.5992] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for '_BACKUP_SPECIAL_OBJECTS AFTER System State:' --> 1002002d 12:55:40.557 [5196.5992] <8> dos_backup::_change_journal_check: WRN - change journal not supported for non-local file systems, file directive:, not allowing change journal usage 12:55:40.557 [5196.5992] <8> dos_backup::_change_journal_check: WRN - change journal not supported for non-local file systems, file directive:<_BACKUP_SPECIAL_OBJECTS AFTER System State:>, not allowing change journal usage 12:55:40.557 [5196.5992] <4> dos_backup::V_Initialize: INF - did not create change journal collection, not needed 12:55:48.872 [5196.5992] <4> VssSnapshot::V_VssSetMetadata(): INF - Unable to locate DFSR metadata 12:55:49.621 [5196.5992] <4> dos_backup::tfs_scanstart: INF - starting scan for file directive: 12:55:49.621 [5196.5992] <4> dos_backup::tfs_scanstart: INF - starting scan using name: 12:55:49.621 [5196.5992] <4> dos_backup::tfs_scanstart: INF - in 'pre-backup' mode 12:56:02.569 [5196.5992] <4> dos_backup::tfs_findfirst: INF - unable to find first file: 'System State:\Background Intelligent Transfer Service\' 12:56:02.569 [5196.5992] <4> dos_backup::tfs_scannext: INF - no more path list entries 12:56:02.569 [5196.5992] <4> dos_backup::tfs_scanstart: INF - Backup Special Objects request AFTER System State: 12:56:02.569 [5196.5992] <4> dos_backup::tfs_scanstart: INF - starting scan for file directive:<_BACKUP_SPECIAL_OBJECTS AFTER System State:> 12:56:02.569 [5196.5992] <4> dos_backup::tfs_scanstart: INF - starting scan using name: 12:56:02.569 [5196.5992] <4> dos_backup::tfs_scanstart: INF - in 'pre-backup' mode 12:56:02.569 [5196.5992] <4> dos_backup::tfs_getinfo: INF - current object (System State:) is a parent of previous (object (System State:\Windows Management Instrumentation\WMI) 12:56:02.569 [5196.5992] <4> dos_backup::tfs_findfirst: INF - unable to find first file: 'System State:\' 12:56:02.569 [5196.5992] <4> dos_backup::tfs_scannext: INF - no more path list entries 12:56:02.694 [5196.5992] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - Drive 'C:' added to VSS Volume Set 12:56:02.694 [5196.5992] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - Drive 'c:' added to VSS Volume Set 12:56:07.436 [5196.5992] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - VSS Volume Set snapped 12:56:07.436 [5196.5992] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - About to commence post-snap unbounded association checks 12:56:07.436 [5196.5992] <4> backup_create: INF - NetBackup Temp Directory: 'C:\Program Files\Veritas\\NetBackup\Temp' 12:56:07.452 [5196.5992] <4> tar_backup_tfi::UpdateExcludeListWithVHD: INF - UpdateExludeListWithVHD begin 12:56:07.702 [5196.5992] <4> tar_backup_tfi::backup_arg_state: INF - remember start error count: 0 12:56:07.702 [5196.5992] <4> tar_backup_tfi::backup_arg_state: INF - remember start fatal count: 0 12:56:07.702 [5196.5992] <4> dos_backup::tfs_scanstart: INF - starting scan for file directive: 12:56:07.702 [5196.5992] <4> dos_backup::tfs_scanstart: INF - starting scan using name: 12:56:07.702 [5196.5992] <4> dos_backup::tfs_scanstart: INF - in 'backup' mode 13:21:33.707 [5196.5992] <16> tar_tfi::processException: An Exception of type [SocketWriteException] has occured at: Module: @(#) $Source: src/ncf/tfi/lib/TransporterRemote.cpp,v $ $Revision: 1.55 $ , Function: TransporterRemote::write[2](), Line: 338 Module: @(#) $Source: src/ncf/tfi/lib/Packer.cpp,v $ $Revision: 1.93 $ , Function: Packer::getBuffer(), Line: 665 Module: tar_tfi::getBuffer, Function: D:\NB\7.6.1.2\src\cl\clientpc\util\tar_tfi.cpp, Line: 311 Local Address: [::]:0 Remote Address: [::]:0 OS Error: 10060 (A connection attempt failed because the connected party did not properly respond after a period of time, or established connection failed because connected host has failed to respond. ) Expected bytes: 131072 13:21:33.707 [5196.5992] <2> tar_base::V_vTarMsgW: FTL - socket write failed 13:21:33.707 [5196.5992] <4> tar_backup_tfi::backup_done_state: INF - number of file directives not found: 0 13:21:33.707 [5196.5992] <4> tar_backup_tfi::backup_done_state: INF - number of file directives found: 3 13:21:33.707 [5196.1160] <4> tar_base::keepaliveThread: INF - keepalive thread terminating (reason: WAIT_OBJECT_0) 13:21:33.707 [5196.5992] <4> tar_base::stopKeepaliveThread: INF - keepalive thread has exited. (reason: WAIT_OBJECT_0) 13:21:33.707 [5196.5992] <2> tar_base::V_vTarMsgW: INF - EXIT STATUS 24: socket write failed 13:21:33.707 [5196.5992] <4> tar_backup_tfi::backup_done_state: INF - Not waiting for server status 13:21:34.721 [5196.5992] <4> dos_backup::ClearDFSRArchiveBits: INF - no DFSR file list was created, No bits cleared. 13:21:34.799 [5196.5992] <4> dos_backup::V_ShadowCopyBackupEnd: INF - foreign snapshot released. 13:21:34.799 [5196.5992] <4> OVStopCmd: INF - EXIT - status = 0 13:21:34.799 [5196.5992] <2> tar_base::V_Close: closing... 13:21:34.799 [5196.5992] <4> dos_backup::tfs_reset: INF - Snapshot deletion start 13:21:34.799 [5196.5992] <4> dos_backup::V_ShadowCopyBackupEnd: INF - foreign snapshot released. 13:21:34.846 [5196.5992] <4> dos_backup::V_ShadowCopyBackupEnd: INF - foreign snapshot released. 13:21:36.656 [5196.5992] <2> ov_log::V_GlobalLog: INF - BEDS_Term(): enter - InitFlags:0x00000101 13:21:36.656 [5196.5992] <2> ov_log::V_GlobalLog: INF - BEDS_Term(): ubs specifics: 0x001d0000 13:21:36.921 [5196.5992] <16> dtcp_read: TCP - failure: recv socket (548) (TCP 10053: Software caused connection abort) 13:21:36.921 [5196.5992] <4> OVShutdown: INF - Finished process 13:21:36.921 [5196.5992] <4> WinMain: INF - Exiting C:\Program Files\Veritas\NetBackup\bin\bpbkar32.exe