ip_adress: [6152.8732] <4> ov_log::OVInit: INF - Starting log file: C:\Program Files\VERITAS\NetBackup\logs\BPBKAR\082415.LOG ip_adress: [6152.8732] <4> ov_log::OVInit: GENERAL Log Level: 0 ip_adress: [6152.8732] <4> ov_log::OVInit: TCP Log Level: 0 ip_adress: [6152.8732] <4> ov_log::OVInit: INF - the log mutex: 444 BPBKAR NetBackup Backup/Archive 7.1GA [Mar 17 2012] Copyright © 1993 - 2012 Symantec Corporation, All Rights Reserved. All Rights Reserved. ip_adress: [6152.8732] <2> WinMain: DAT - _pgmptr = 'C:\Program Files\VERITAS\NetBackup\bin\bpbkar32.exe' ip_adress: [6152.8732] <2> WinMain: DAT - lpCmdLine = '-r 3888000 -ru root -dt 0 -to 0 -clnt client -class policy_CSxxxx_Dedupe -sched Full -st FULL -bpstart_to 300 -bpend_to 300 -read_to 1800 -ost_shmcd -blks_per_buffer 512 -use_otm -fso -b client_1440408988 -kl 12 -WOFB_enabled -WOFB_fim 0 -WOFB_usage 0 -WOFB_error 0 -ct 13 -use_ofb ' ip_adress: [6152.8732] <2> date_debug: DAT - timezone: GMT Standard Time, offset=0, dst: GMT Daylight Time 10:36:55.926: [6152.8732] <2> date_debug: DAT - current time: 1440409015, 24/08/2015 10:36:55 10:36:55.926: [6152.8732] <2> date_debug: DAT - 01/01/94 UCT: 757382400, 01/01/1994 01:00:00 10:36:55.926: [6152.8732] <2> date_debug: DAT - 07/01/94 UCT: 773020800, 01/07/1994 01:00:00 10:36:55.926: [6152.8732] <2> WinMain: DAT - standard input handle = 412 10:36:55.926: [6152.8732] <2> WinMain: DAT - standard output handle = 256 10:36:55.926: [6152.8732] <2> WinMain: DAT - standard error handle = 376 10:36:56.051: [6152.8732] <4> tar_backup::V_SetupJobData: INF - dwJobData: ffffffff 10:36:56.051: [6152.8732] <4> tar_backup::V_SetupJobData: INF - dwJob: ffffffff ip_adress: [6152.8732] <4> dos_backup::V_PreProcessing: INF - backup privileges enabled, previous = 0 ip_adress: [6152.8732] <4> dos_backup::V_PreProcessing: INF - restore privileges enabled, previous = 0 ip_adress: [6152.8732] <4> dos_backup::V_PreProcessing: INF - security privileges enabled, previous = 0 ip_adress: [6152.8732] <4> dos_backup::V_PreProcessing: INF - tcb privileges enabled, previous = 0 ip_adress: [6152.8732] <4> dos_backup::V_PreProcessing: INF - create token privileges enabled, previous = 0 ip_adress: [6152.8732] <4> dos_backup::V_PreProcessing: INF - user name: root ip_adress: [6152.8732] <4> dos_backup::V_PreProcessing: INF - no access token: scheduled backup ip_adress: [6152.8732] <2> ov_log::V_GlobalLog: WRN - ubsDetermineExchangeVersion(): RegOpenKeyEx() failed for HKLM\SOFTWARE\MICROSOFT\ExchangeServer\v14\Setup - 0x2. 10:36:56.161: [6152.8732] <2> ov_log::V_GlobalLog: INF - BEDS_Init() Enter InitFlags:0x0 ip_adress: [6152.8732] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: C: ip_adress: [6152.8732] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: D: ip_adress: [6152.8732] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: G: ip_adress: [6152.8732] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: I: ip_adress: [6152.8732] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: J: ip_adress: [6152.8732] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: L: ip_adress: [6152.8732] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: P: ip_adress: [6152.8732] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: W: ip_adress: [6152.8732] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Terminal Services ip_adress: [6152.8732] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Windows Network ip_adress: [6152.8732] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Shadow?Copy?Components ip_adress: [6152.8732] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: System?State ip_adress: [6152.8732] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Active Directory Application Mode 10:36:57.270: [6152.8732] <2> tar_base::V_vTarMsgW: INF - BACKUP START 6152 ip_adress: [6152.8732] <2> tar_backup::V_SetupProcessContinue: TAR - CONTINUE BACKUP received ip_adress: [6152.8732] <2> tar_base::V_vTarMsgW: INF - CONTINUE BACKUP message received ip_adress: [6152.8732] <2> tar_backup_tfi::setupFileDirectives: TAR - backup filename = ALL_LOCAL_DRIVES ip_adress: [6152.9392] <4> tar_base::keepaliveThread: INF - keepalive thread is active with an interval of 60 seconds ip_address: [6152.8732] <2> tar_backup_vxbsa::add: INF - called with '/' ip_address: [6152.8732] <4> dos_backup::V_VerifyFileSystem: INF - Added: C:\ ip_address: [6152.8732] <4> dos_backup::V_VerifyFileSystem: INF - Added: D:\ ip_address: [6152.8732] <4> dos_backup::V_VerifyFileSystem: INF - Added: G:\ ip_address: [6152.8732] <4> dos_backup::V_VerifyFileSystem: INF - Added: I:\ ip_address: [6152.8732] <4> dos_backup::V_VerifyFileSystem: INF - Added: J:\ ip_address: [6152.8732] <4> dos_backup::V_VerifyFileSystem: INF - Added: L:\ ip_address: [6152.8732] <4> dos_backup::V_VerifyFileSystem: INF - Added: P:\ ip_address: [6152.8732] <4> dos_backup::V_VerifyFileSystem: INF - Added: W:\ ip_address: [6152.8732] <4> dos_backup::V_VerifyFileSystem: INF - Added: Shadow Copy Components:\ ip_address: [6152.8732] <4> dos_backup::V_VerifyFileSystem: INF - Added: System State:\ ip_address: [6152.8732] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'C:\' --> 10020002 ip_address: [6152.8732] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'D:\' --> 10020002 ip_address: [6152.8732] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'G:\' --> 10020002 ip_address: [6152.8732] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'I:\' --> 10020002 ip_address: [6152.8732] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'J:\' --> 10020002 ip_address: [6152.8732] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'L:\' --> 10020002 ip_address: [6152.8732] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'P:\' --> 10020002 ip_address: [6152.8732] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'W:\' --> 10020002 10:37:16.301: [6152.8732] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'Shadow Copy Components:\' --> 10020027 10:37:22.786: [6152.8732] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'System State:\' --> 1002002d 10:37:22.786: [6152.8732] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for '_BACKUP_SPECIAL_OBJECTS AFTER System State:' --> 1002002d 10:37:29.708: [6152.8732] <4> VssSnapshot::V_VssSetMetadata(): INF - Unable to locate DFSR metadata 10:37:30.504: [6152.8732] <4> dos_backup::V_InitializeShadowCopy: INF - ============================================ 10:37:30.504: [6152.8732] <4> dos_backup::V_InitializeShadowCopy: INF - ShadowCopy PrePost: Begin 10:37:47.848: [6152.8732] <4> dos_backup::tfs_findfirst: INF - unable to find first file: 'Shadow Copy Components:\System Service\Background Intelligent Transfer Service\' : [6152.8732] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - Drive 'C:' added to VSS Volume Set : [6152.8732] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - Drive 'G:' added to VSS Volume Set : [6152.8732] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - VSS Volume Set snapped : [6152.8732] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - About to commence post-snap unbounded association checks 10:37:56.239: [6152.8732] <4> dos_backup::V_InitializeShadowCopy: INF - ShadowCopy PrePost: End : [6152.8732] <4> dos_backup::V_InitializeShadowCopy: INF - ============================================ : [6152.8732] <4> VssSnapshot::V_VssSetMetadata(): INF - Unable to locate DFSR metadata : [6152.8732] <4> dos_backup::tfs_scanstart: INF - Backup Special Objects request AFTER System State: 10:38:37.536: [6152.8732] <4> dos_backup::tfs_getinfo: INF - current object (System State:) is a parent of previous (object (System State:\Registry\Registry) ip_adress: [6152.8732] <4> dos_backup::tfs_findfirst: INF - unable to find first file: 'System State:\' ip_adress: [6152.8732] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - Drive 'C:' added to VSS Volume Set ip_adress: [6152.8732] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - Drive 'W:' added to VSS Volume Set ip_adress: [6152.8732] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - Drive 'c:' added to VSS Volume Set 10:38:50.348: [6152.8732] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - VSS Volume Set snapped 10:38:50.348: [6152.8732] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - About to commence post-snap unbounded association checks 10:38:51.411: [6152.8732] <4> backup_create: INF - NetBackup Temp Directory: 'C:\Program Files\VERITAS\\NetBackup\Temp' 10:38:51.411: [6152.8732] <16> tar_backup::SetExcludeFileList: **** DBG **** - alternate client fileter file is null 10:38:51.411: [6152.8732] <4> tar_backup_tfi::UpdateExcludeListWithVHD: INF - UpdateExludeListWithVHD begin 10:38:57.395: [6152.8732] <2> tar_base::V_vTarMsgW: INF - Enabling volume snapshots for (C:), please wait... 10:38:57.395: [6152.8732] <4> V_DetermineMountInfo: INF - Checking Volume \\?\Volume{009d8489-2338-11e2-96ad-806e6f6e6963}\ for '' 10:38:57.395: [6152.8732] <4> V_DetermineMountInfo: INF - Adding NTFS Volume C:\ ==> \\?\Volume{009d8489-2338-11e2-96ad-806e6f6e6963}\ 10:38:57.395: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - ====================================================================== 10:38:57.395: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Attempting to create snapshots for 'C:' 10:38:57.395: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - CREATE request: C:\Program Files\VERITAS\NetBackup\bin\bpfis create -owner NBU -fso -WOFB -fim VSP -id client_1440408988 C:\ 10:39:04.598: [6152.8732] <4> V_Snapshot::V_Snapshot_AddMappings: INF - V_Snapshot_AddMappings input parameters: source path = "C:\" snap path = "\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy1237\" mount path = "(null)" 10:39:04.598: [6152.8732] <4> V_Snapshot::V_GetSourceVolume: INF - source = "C:\" snapshot = "\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy1237\" 10:39:04.598: [6152.8732] <4> V_Snapshot::V_Snapshot_AddMappings: INF - Volume Snapshot Mapping: \\?\Volume{009d8489-2338-11e2-96ad-806e6f6e6963}\ --> GLOBALROOT\Device\HarddiskVolumeShadowCopy1237 10:39:04.598: [6152.8732] <4> V_Snapshot::V_Snapshot_ParseBpfisOutput: INF - Snapshot creation, FIS_ID: client_1440408988 10:39:04.598: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Snapshot creation was successful 10:39:04.598: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Snapshot provider: VSP 10:39:04.598: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - ====================================================================== 10:39:04.598: [6152.8732] <2> tar_base::V_vTarMsgW: INF - Volume snapshots enabled 10:39:04.598: [6152.8732] <2> tar_base::V_vTarMsgW: INF - Excluded: C: 10:39:04.598: [6152.8732] <2> tar_base::V_vTarMsgW: INF - Enabling volume snapshots for (D:), please wait... 10:39:04.598: [6152.8732] <4> V_DetermineMountInfo: INF - Checking Volume \\?\Volume{009d848a-2338-11e2-96ad-806e6f6e6963}\ for '' 10:39:04.598: [6152.8732] <4> V_DetermineMountInfo: INF - Adding NTFS Volume D:\ ==> \\?\Volume{009d848a-2338-11e2-96ad-806e6f6e6963}\ 10:39:04.598: [6152.8732] <4> V_Snapshot::V_Snapshot_Destroy: INF - Attempting to destroy volume snapshots: bpfis delete -id client_1440408988 10:39:30.598: [6152.8732] <4> V_Snapshot::V_Snapshot_Destroy: INF - Snapshot Destroy EXIT STATUS 0: the requested operation was successfully completed 10:39:30.598: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - ====================================================================== 10:39:30.598: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Attempting to create snapshots for 'D:' 10:39:30.598: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - CREATE request: C:\Program Files\VERITAS\NetBackup\bin\bpfis create -owner NBU -fso -WOFB -fim VSP -id client_1440408988 D:\ ip_adress.661: [6152.8732] <4> V_Snapshot::V_Snapshot_AddMappings: INF - V_Snapshot_AddMappings input parameters: source path = "D:\" snap path = "\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy1238\" mount path = "(null)" ip_adress.661: [6152.8732] <4> V_Snapshot::V_GetSourceVolume: INF - source = "D:\" snapshot = "\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy1238\" ip_adress.661: [6152.8732] <4> V_Snapshot::V_Snapshot_AddMappings: INF - Volume Snapshot Mapping: \\?\Volume{009d848a-2338-11e2-96ad-806e6f6e6963}\ --> GLOBALROOT\Device\HarddiskVolumeShadowCopy1238 ip_adress.661: [6152.8732] <4> V_Snapshot::V_Snapshot_ParseBpfisOutput: INF - Snapshot creation, FIS_ID: client_1440408988 ip_adress.661: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Snapshot creation was successful ip_adress.661: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Snapshot provider: VSP ip_adress.661: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - ====================================================================== ip_adress.661: [6152.8732] <2> tar_base::V_vTarMsgW: INF - Volume snapshots enabled ip_adress.661: [6152.8732] <2> tar_base::V_vTarMsgW: INF - Excluded: D: ip_adress.661: [6152.8732] <2> tar_base::V_vTarMsgW: INF - Enabling volume snapshots for (G:), please wait... ip_adress.661: [6152.8732] <4> V_DetermineMountInfo: INF - Checking Volume \\?\Volume{009d848b-2338-11e2-96ad-806e6f6e6963}\ for '' ip_adress.661: [6152.8732] <4> V_DetermineMountInfo: INF - Adding NTFS Volume G:\ ==> \\?\Volume{009d848b-2338-11e2-96ad-806e6f6e6963}\ ip_adress.661: [6152.8732] <4> V_Snapshot::V_Snapshot_Destroy: INF - Attempting to destroy volume snapshots: bpfis delete -id client_1440408988 10:40:00.661: [6152.8732] <4> V_Snapshot::V_Snapshot_Destroy: INF - Snapshot Destroy EXIT STATUS 0: the requested operation was successfully completed 10:40:00.661: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - ====================================================================== 10:40:00.661: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Attempting to create snapshots for 'G:' 10:40:00.661: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - CREATE request: C:\Program Files\VERITAS\NetBackup\bin\bpfis create -owner NBU -fso -WOFB -fim VSP -id client_1440408988 G:\ 10:40:04.661: [6152.8732] <4> V_Snapshot::V_Snapshot_AddMappings: INF - V_Snapshot_AddMappings input parameters: source path = "G:\" snap path = "\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy1239\" mount path = "(null)" 10:40:04.661: [6152.8732] <4> V_Snapshot::V_GetSourceVolume: INF - source = "G:\" snapshot = "\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy1239\" 10:40:04.661: [6152.8732] <4> V_Snapshot::V_Snapshot_AddMappings: INF - Volume Snapshot Mapping: \\?\Volume{009d848b-2338-11e2-96ad-806e6f6e6963}\ --> GLOBALROOT\Device\HarddiskVolumeShadowCopy1239 10:40:04.661: [6152.8732] <4> V_Snapshot::V_Snapshot_ParseBpfisOutput: INF - Snapshot creation, FIS_ID: client_1440408988 10:40:04.661: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Snapshot creation was successful 10:40:04.661: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Snapshot provider: VSP 10:40:04.661: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - ====================================================================== 10:40:04.661: [6152.8732] <2> tar_base::V_vTarMsgW: INF - Volume snapshots enabled 10:40:04.661: [6152.8732] <2> tar_base::V_vTarMsgW: INF - Excluded: G: 10:40:04.661: [6152.8732] <2> tar_base::V_vTarMsgW: INF - Enabling volume snapshots for (I:), please wait... 10:40:04.661: [6152.8732] <4> V_DetermineMountInfo: INF - Checking Volume \\?\Volume{009d848e-2338-11e2-96ad-806e6f6e6963}\ for '' 10:40:04.661: [6152.8732] <4> V_DetermineMountInfo: INF - Adding NTFS Volume I:\ ==> \\?\Volume{009d848e-2338-11e2-96ad-806e6f6e6963}\ 10:40:04.661: [6152.8732] <4> V_Snapshot::V_Snapshot_Destroy: INF - Attempting to destroy volume snapshots: bpfis delete -id client_1440408988 10:40:31.676: [6152.8732] <4> V_Snapshot::V_Snapshot_Destroy: INF - Snapshot Destroy EXIT STATUS 0: the requested operation was successfully completed 10:40:31.676: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - ====================================================================== 10:40:31.676: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Attempting to create snapshots for 'I:' 10:40:31.676: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - CREATE request: C:\Program Files\VERITAS\NetBackup\bin\bpfis create -owner NBU -fso -WOFB -fim VSP -id client_1440408988 I:\ 10:40:35.864: [6152.8732] <4> V_Snapshot::V_Snapshot_AddMappings: INF - V_Snapshot_AddMappings input parameters: source path = "I:\" snap path = "\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy1240\" mount path = "(null)" 10:40:35.864: [6152.8732] <4> V_Snapshot::V_GetSourceVolume: INF - source = "I:\" snapshot = "\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy1240\" 10:40:35.864: [6152.8732] <4> V_Snapshot::V_Snapshot_AddMappings: INF - Volume Snapshot Mapping: \\?\Volume{009d848e-2338-11e2-96ad-806e6f6e6963}\ --> GLOBALROOT\Device\HarddiskVolumeShadowCopy1240 10:40:35.864: [6152.8732] <4> V_Snapshot::V_Snapshot_ParseBpfisOutput: INF - Snapshot creation, FIS_ID: client_1440408988 10:40:35.864: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Snapshot creation was successful 10:40:35.864: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Snapshot provider: VSP 10:40:35.864: [6152.8732] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - ====================================================================== 10:40:35.864: [6152.8732] <2> tar_base::V_vTarMsgW: INF - Volume snapshots enabled 10:40:37.223: [6152.8732] <2> ov_log::V_GlobalLogEx: INF - file_access (constructor): 0 non-NTFS volumes 10:42:37.473: [6152.8732] <4> tar_backup::backup_done_state: INF - number of file directives not found: 0 10:42:37.473: [6152.8732] <4> tar_backup::backup_done_state: INF - number of file directives found: 7 10:42:37.473: [6152.9392] <4> tar_base::keepaliveThread: INF - keepalive thread terminating (reason: WAIT_OBJECT_0) 10:42:37.473: [6152.8732] <4> tar_base::stopKeepaliveThread: INF - keepalive thread has exited. (reason: WAIT_OBJECT_0) 10:42:37.473: [6152.8732] <4> send_msg_to_monitor: INF - in send_msg_to_monitor()... 10:42:37.473: [6152.8732] <2> tar_base::V_vTarMsgW: INF - EXIT STATUS 40: network connection broken 10:42:37.473: [6152.8732] <4> tar_backup::backup_done_state: INF - Not waiting for server status 10:42:37.473: [6152.8732] <4> dos_backup::tfs_reset: INF - Snapshot deletion start 10:42:37.473: [6152.8732] <4> V_Snapshot::V_Snapshot_Destroy: INF - Attempting to destroy volume snapshots: bpfis delete -id client_1440408988 10:43:03.473: [6152.8732] <4> V_Snapshot::V_Snapshot_Destroy: INF - Snapshot Destroy EXIT STATUS 0: the requested operation was successfully completed 10:43:03.567: [6152.8732] <4> OVStopCmd: INF - EXIT - status = 0 10:43:03.567: [6152.8732] <2> tar_base::V_Close: closing... 10:43:03.567: [6152.8732] <4> dos_backup::tfs_reset: INF - Snapshot deletion start 10:43:04.348: [6152.8732] <2> ov_log::V_GlobalLog: INF - BEDS_Term(): enter - InitFlags:0x00000001 10:43:04.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:05.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:06.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:07.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:08.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:09.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:10.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:11.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:12.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:13.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:14.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:15.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:16.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:17.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:18.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:19.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:20.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:21.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:22.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:23.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:24.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:25.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:26.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:27.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:28.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:29.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:30.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:31.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:32.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:33.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:34.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:35.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:36.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:37.411: [6152.8732] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 10:43:37.411: [6152.8732] <4> OVShutdown: INF - Finished process 10:43:37.411: [6152.8732] <4> WinMain: INF - Exiting C:\Program Files\VERITAS\NetBackup\bin\bpbkar32.exe 10:43:39.411: [6152.8732] <4> ov_log::OVClose: INF - Closing log file: C:\Program Files\VERITAS\NetBackup\logs\BPBKAR\082415.LOG 10:53:50.442: [7472.9876] <4> ov_log::OVInit: INF - Starting log file: C:\Program Files\VERITAS\NetBackup\logs\BPBKAR\082415.LOG 10:53:50.442: [7472.9876] <4> ov_log::OVInit: GENERAL Log Level: 0 10:53:50.442: [7472.9876] <4> ov_log::OVInit: TCP Log Level: 0 10:53:50.442: [7472.9876] <4> ov_log::OVInit: INF - the log mutex: 444 BPBKAR NetBackup Backup/Archive 7.1GA [Mar 17 2012] Copyright © 1993 - 2012 Symantec Corporation, All Rights Reserved. All Rights Reserved. 10:53:50.442: [7472.9876] <2> WinMain: DAT - _pgmptr = 'C:\Program Files\VERITAS\NetBackup\bin\bpbkar32.exe' 10:53:50.442: [7472.9876] <2> WinMain: DAT - lpCmdLine = '-r 3888000 -ru root -dt 0 -to 0 -clnt client -class policy_CSxxxx_Dedupe -sched Full -st FULL -bpstart_to 300 -bpend_to 300 -read_to 1800 -ost_shmcd -blks_per_buffer 512 -use_otm -fso -b client_1440410011 -kl 12 -WOFB_enabled -WOFB_fim 0 -WOFB_usage 0 -WOFB_error 0 -ct 13 -use_ofb ' 10:53:50.442: [7472.9876] <2> date_debug: DAT - timezone: GMT Standard Time, offset=0, dst: GMT Daylight Time 10:53:50.442: [7472.9876] <2> date_debug: DAT - current time: 1440410030, 24/08/2015 10:53:50 10:53:50.442: [7472.9876] <2> date_debug: DAT - 01/01/94 UCT: 757382400, 01/01/1994 01:00:00 10:53:50.442: [7472.9876] <2> date_debug: DAT - 07/01/94 UCT: 773020800, 01/07/1994 01:00:00 10:53:50.442: [7472.9876] <2> WinMain: DAT - standard input handle = 412 10:53:50.442: [7472.9876] <2> WinMain: DAT - standard output handle = 256 10:53:50.442: [7472.9876] <2> WinMain: DAT - standard error handle = 376 10:53:50.458: [7472.9876] <4> tar_backup::V_SetupJobData: INF - dwJobData: ffffffff 10:53:50.458: [7472.9876] <4> tar_backup::V_SetupJobData: INF - dwJob: ffffffff 10:53:50.504: [7472.9876] <4> dos_backup::V_PreProcessing: INF - backup privileges enabled, previous = 0 10:53:50.504: [7472.9876] <4> dos_backup::V_PreProcessing: INF - restore privileges enabled, previous = 0 10:53:50.504: [7472.9876] <4> dos_backup::V_PreProcessing: INF - security privileges enabled, previous = 0 10:53:50.504: [7472.9876] <4> dos_backup::V_PreProcessing: INF - tcb privileges enabled, previous = 0 10:53:50.504: [7472.9876] <4> dos_backup::V_PreProcessing: INF - create token privileges enabled, previous = 0 10:53:50.504: [7472.9876] <4> dos_backup::V_PreProcessing: INF - user name: root 10:53:50.504: [7472.9876] <4> dos_backup::V_PreProcessing: INF - no access token: scheduled backup 10:53:50.504: [7472.9876] <2> ov_log::V_GlobalLog: WRN - ubsDetermineExchangeVersion(): RegOpenKeyEx() failed for HKLM\SOFTWARE\MICROSOFT\ExchangeServer\v14\Setup - 0x2. 10:53:50.520: [7472.9876] <2> ov_log::V_GlobalLog: INF - BEDS_Init() Enter InitFlags:0x0 10:53:50.583: [7472.9876] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: C: 10:53:50.583: [7472.9876] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: D: 10:53:50.583: [7472.9876] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: G: 10:53:50.583: [7472.9876] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: I: 10:53:50.583: [7472.9876] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: J: 10:53:50.583: [7472.9876] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: L: 10:53:50.583: [7472.9876] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: P: 10:53:50.583: [7472.9876] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: W: 10:53:50.583: [7472.9876] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Terminal Services 10:53:50.583: [7472.9876] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Windows Network 10:53:50.583: [7472.9876] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Shadow?Copy?Components 10:53:50.583: [7472.9876] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: System?State 10:53:50.583: [7472.9876] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Active Directory Application Mode 10:53:50.583: [7472.9876] <2> tar_base::V_vTarMsgW: INF - BACKUP START 7472 10:53:50.817: [7472.9876] <2> tar_backup::V_SetupProcessContinue: TAR - CONTINUE BACKUP received 10:53:50.817: [7472.9876] <2> tar_base::V_vTarMsgW: INF - CONTINUE BACKUP message received 10:53:51.036: [7472.9876] <2> tar_backup_tfi::setupFileDirectives: TAR - backup filename = ALL_LOCAL_DRIVES 10:53:51.036: [7472.9644] <4> tar_base::keepaliveThread: INF - keepalive thread is active with an interval of 60 seconds ip_adress: [7472.9876] <2> tar_backup_vxbsa::add: INF - called with '/' ip_adress: [7472.9876] <4> dos_backup::V_VerifyFileSystem: INF - Added: C:\ ip_adress: [7472.9876] <4> dos_backup::V_VerifyFileSystem: INF - Added: D:\ ip_adress: [7472.9876] <4> dos_backup::V_VerifyFileSystem: INF - Added: G:\ ip_adress: [7472.9876] <4> dos_backup::V_VerifyFileSystem: INF - Added: I:\ ip_adress: [7472.9876] <4> dos_backup::V_VerifyFileSystem: INF - Added: J:\ ip_adress: [7472.9876] <4> dos_backup::V_VerifyFileSystem: INF - Added: L:\ ip_adress: [7472.9876] <4> dos_backup::V_VerifyFileSystem: INF - Added: P:\ ip_adress: [7472.9876] <4> dos_backup::V_VerifyFileSystem: INF - Added: W:\ ip_adress: [7472.9876] <4> dos_backup::V_VerifyFileSystem: INF - Added: Shadow Copy Components:\ ip_adress: [7472.9876] <4> dos_backup::V_VerifyFileSystem: INF - Added: System State:\ ip_adress: [7472.9876] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'C:\' --> 10020002 ip_adress: [7472.9876] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'D:\' --> 10020002 ip_adress: [7472.9876] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'G:\' --> 10020002 ip_adress: [7472.9876] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'I:\' --> 10020002 ip_adress: [7472.9876] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'J:\' --> 10020002 ip_adress: [7472.9876] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'L:\' --> 10020002 ip_adress: [7472.9876] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'P:\' --> 10020002 ip_adress: [7472.9876] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'W:\' --> 10020002 10:53:59.145: [7472.9876] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'Shadow Copy Components:\' --> 10020027 10:54:05.629: [7472.9876] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'System State:\' --> 1002002d 10:54:05.629: [7472.9876] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for '_BACKUP_SPECIAL_OBJECTS AFTER System State:' --> 1002002d 10:54:12.020: [7472.9876] <4> VssSnapshot::V_VssSetMetadata(): INF - Unable to locate DFSR metadata 10:54:12.661: [7472.9876] <4> dos_backup::V_InitializeShadowCopy: INF - ============================================ 10:54:12.661: [7472.9876] <4> dos_backup::V_InitializeShadowCopy: INF - ShadowCopy PrePost: Begin 10:54:30.551: [7472.9876] <4> dos_backup::tfs_findfirst: INF - unable to find first file: 'Shadow Copy Components:\System Service\Background Intelligent Transfer Service\' 10:54:30.551: [7472.9876] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - Drive 'C:' added to VSS Volume Set 10:54:30.567: [7472.9876] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - Drive 'G:' added to VSS Volume Set 10:54:34.958: [7472.9876] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - VSS Volume Set snapped 10:54:34.958: [7472.9876] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - About to commence post-snap unbounded association checks 10:54:34.958: [7472.9876] <4> dos_backup::V_InitializeShadowCopy: INF - ShadowCopy PrePost: End 10:54:34.958: [7472.9876] <4> dos_backup::V_InitializeShadowCopy: INF - ============================================ 10:54:34.958: [7472.9876] <4> VssSnapshot::V_VssSetMetadata(): INF - Unable to locate DFSR metadata 10:55:03.958: [7472.9876] <4> dos_backup::tfs_scanstart: INF - Backup Special Objects request AFTER System State: 10:55:15.458: [7472.9876] <4> dos_backup::tfs_getinfo: INF - current object (System State:) is a parent of previous (object (System State:\Internet Information Services\IISCONFIG) 10:55:21.286: [7472.9876] <4> dos_backup::tfs_findfirst: INF - unable to find first file: 'System State:\' 10:55:21.301: [7472.9876] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - Drive 'C:' added to VSS Volume Set 10:55:21.301: [7472.9876] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - Drive 'W:' added to VSS Volume Set 10:55:21.301: [7472.9876] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - Drive 'c:' added to VSS Volume Set 10:55:25.661: [7472.9876] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - VSS Volume Set snapped 10:55:25.661: [7472.9876] <4> VssSnapshot::V_VssVolumeSnapshot(): INF - About to commence post-snap unbounded association checks 10:55:26.661: [7472.9876] <4> backup_create: INF - NetBackup Temp Directory: 'C:\Program Files\VERITAS\\NetBackup\Temp' 10:55:26.661: [7472.9876] <16> tar_backup::SetExcludeFileList: **** DBG **** - alternate client fileter file is null 10:55:26.661: [7472.9876] <4> tar_backup_tfi::UpdateExcludeListWithVHD: INF - UpdateExludeListWithVHD begin ip_adress: [7472.9876] <2> tar_base::V_vTarMsgW: INF - Enabling volume snapshots for (C:), please wait... ip_adress: [7472.9876] <4> V_DetermineMountInfo: INF - Checking Volume \\?\Volume{009d8489-2338-11e2-96ad-806e6f6e6963}\ for '' ip_adress: [7472.9876] <4> V_DetermineMountInfo: INF - Adding NTFS Volume C:\ ==> \\?\Volume{009d8489-2338-11e2-96ad-806e6f6e6963}\ ip_adress: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - ====================================================================== ip_adress: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Attempting to create snapshots for 'C:' ip_adress: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - CREATE request: C:\Program Files\VERITAS\NetBackup\bin\bpfis create -owner NBU -fso -WOFB -fim VSP -id client_1440410011 C:\ 10:55:36.098: [7472.9876] <4> V_Snapshot::V_Snapshot_AddMappings: INF - V_Snapshot_AddMappings input parameters: source path = "C:\" snap path = "\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy1245\" mount path = "(null)" 10:55:36.098: [7472.9876] <4> V_Snapshot::V_GetSourceVolume: INF - source = "C:\" snapshot = "\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy1245\" 10:55:36.098: [7472.9876] <4> V_Snapshot::V_Snapshot_AddMappings: INF - Volume Snapshot Mapping: \\?\Volume{009d8489-2338-11e2-96ad-806e6f6e6963}\ --> GLOBALROOT\Device\HarddiskVolumeShadowCopy1245 10:55:36.098: [7472.9876] <4> V_Snapshot::V_Snapshot_ParseBpfisOutput: INF - Snapshot creation, FIS_ID: client_1440410011 10:55:36.098: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Snapshot creation was successful 10:55:36.098: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Snapshot provider: VSP 10:55:36.098: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - ====================================================================== 10:55:36.098: [7472.9876] <2> tar_base::V_vTarMsgW: INF - Volume snapshots enabled 10:55:36.098: [7472.9876] <2> tar_base::V_vTarMsgW: INF - Excluded: C: 10:55:36.098: [7472.9876] <2> tar_base::V_vTarMsgW: INF - Enabling volume snapshots for (D:), please wait... 10:55:36.098: [7472.9876] <4> V_DetermineMountInfo: INF - Checking Volume \\?\Volume{009d848a-2338-11e2-96ad-806e6f6e6963}\ for '' 10:55:36.098: [7472.9876] <4> V_DetermineMountInfo: INF - Adding NTFS Volume D:\ ==> \\?\Volume{009d848a-2338-11e2-96ad-806e6f6e6963}\ 10:55:36.098: [7472.9876] <4> V_Snapshot::V_Snapshot_Destroy: INF - Attempting to destroy volume snapshots: bpfis delete -id client_1440410011 10:56:02.098: [7472.9876] <4> V_Snapshot::V_Snapshot_Destroy: INF - Snapshot Destroy EXIT STATUS 0: the requested operation was successfully completed 10:56:02.098: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - ====================================================================== 10:56:02.098: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Attempting to create snapshots for 'D:' 10:56:02.098: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - CREATE request: C:\Program Files\VERITAS\NetBackup\bin\bpfis create -owner NBU -fso -WOFB -fim VSP -id client_1440410011 D:\ 10:56:06.098: [7472.9876] <4> V_Snapshot::V_Snapshot_AddMappings: INF - V_Snapshot_AddMappings input parameters: source path = "D:\" snap path = "\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy1246\" mount path = "(null)" 10:56:06.098: [7472.9876] <4> V_Snapshot::V_GetSourceVolume: INF - source = "D:\" snapshot = "\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy1246\" 10:56:06.098: [7472.9876] <4> V_Snapshot::V_Snapshot_AddMappings: INF - Volume Snapshot Mapping: \\?\Volume{009d848a-2338-11e2-96ad-806e6f6e6963}\ --> GLOBALROOT\Device\HarddiskVolumeShadowCopy1246 10:56:06.098: [7472.9876] <4> V_Snapshot::V_Snapshot_ParseBpfisOutput: INF - Snapshot creation, FIS_ID: client_1440410011 10:56:06.098: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Snapshot creation was successful 10:56:06.098: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Snapshot provider: VSP 10:56:06.098: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - ====================================================================== 10:56:06.098: [7472.9876] <2> tar_base::V_vTarMsgW: INF - Volume snapshots enabled 10:56:06.098: [7472.9876] <2> tar_base::V_vTarMsgW: INF - Excluded: D: 10:56:06.098: [7472.9876] <2> tar_base::V_vTarMsgW: INF - Enabling volume snapshots for (G:), please wait... 10:56:06.098: [7472.9876] <4> V_DetermineMountInfo: INF - Checking Volume \\?\Volume{009d848b-2338-11e2-96ad-806e6f6e6963}\ for '' 10:56:06.098: [7472.9876] <4> V_DetermineMountInfo: INF - Adding NTFS Volume G:\ ==> \\?\Volume{009d848b-2338-11e2-96ad-806e6f6e6963}\ 10:56:06.098: [7472.9876] <4> V_Snapshot::V_Snapshot_Destroy: INF - Attempting to destroy volume snapshots: bpfis delete -id client_1440410011 10:56:32.161: [7472.9876] <4> V_Snapshot::V_Snapshot_Destroy: INF - Snapshot Destroy EXIT STATUS 0: the requested operation was successfully completed 10:56:32.161: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - ====================================================================== 10:56:32.161: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Attempting to create snapshots for 'G:' 10:56:32.161: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - CREATE request: C:\Program Files\VERITAS\NetBackup\bin\bpfis create -owner NBU -fso -WOFB -fim VSP -id client_1440410011 G:\ 10:56:36.161: [7472.9876] <4> V_Snapshot::V_Snapshot_AddMappings: INF - V_Snapshot_AddMappings input parameters: source path = "G:\" snap path = "\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy1247\" mount path = "(null)" 10:56:36.161: [7472.9876] <4> V_Snapshot::V_GetSourceVolume: INF - source = "G:\" snapshot = "\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy1247\" 10:56:36.161: [7472.9876] <4> V_Snapshot::V_Snapshot_AddMappings: INF - Volume Snapshot Mapping: \\?\Volume{009d848b-2338-11e2-96ad-806e6f6e6963}\ --> GLOBALROOT\Device\HarddiskVolumeShadowCopy1247 10:56:36.161: [7472.9876] <4> V_Snapshot::V_Snapshot_ParseBpfisOutput: INF - Snapshot creation, FIS_ID: client_1440410011 10:56:36.161: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Snapshot creation was successful 10:56:36.161: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Snapshot provider: VSP 10:56:36.161: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - ====================================================================== 10:56:36.161: [7472.9876] <2> tar_base::V_vTarMsgW: INF - Volume snapshots enabled 10:56:36.161: [7472.9876] <2> tar_base::V_vTarMsgW: INF - Excluded: G: 10:56:36.161: [7472.9876] <2> tar_base::V_vTarMsgW: INF - Enabling volume snapshots for (I:), please wait... 10:56:36.161: [7472.9876] <4> V_DetermineMountInfo: INF - Checking Volume \\?\Volume{009d848e-2338-11e2-96ad-806e6f6e6963}\ for '' 10:56:36.161: [7472.9876] <4> V_DetermineMountInfo: INF - Adding NTFS Volume I:\ ==> \\?\Volume{009d848e-2338-11e2-96ad-806e6f6e6963}\ 10:56:36.161: [7472.9876] <4> V_Snapshot::V_Snapshot_Destroy: INF - Attempting to destroy volume snapshots: bpfis delete -id client_1440410011 10:57:02.161: [7472.9876] <4> V_Snapshot::V_Snapshot_Destroy: INF - Snapshot Destroy EXIT STATUS 0: the requested operation was successfully completed 10:57:02.161: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - ====================================================================== 10:57:02.161: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Attempting to create snapshots for 'I:' 10:57:02.161: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - CREATE request: C:\Program Files\VERITAS\NetBackup\bin\bpfis create -owner NBU -fso -WOFB -fim VSP -id client_1440410011 I:\ ip_adress: [7472.9876] <4> V_Snapshot::V_Snapshot_AddMappings: INF - V_Snapshot_AddMappings input parameters: source path = "I:\" snap path = "\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy1248\" mount path = "(null)" ip_adress: [7472.9876] <4> V_Snapshot::V_GetSourceVolume: INF - source = "I:\" snapshot = "\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy1248\" ip_adress: [7472.9876] <4> V_Snapshot::V_Snapshot_AddMappings: INF - Volume Snapshot Mapping: \\?\Volume{009d848e-2338-11e2-96ad-806e6f6e6963}\ --> GLOBALROOT\Device\HarddiskVolumeShadowCopy1248 ip_adress: [7472.9876] <4> V_Snapshot::V_Snapshot_ParseBpfisOutput: INF - Snapshot creation, FIS_ID: client_1440410011 ip_adress: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Snapshot creation was successful ip_adress: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - Snapshot provider: VSP ip_adress: [7472.9876] <4> V_Snapshot::V_Snapshot_CreateSnapshot: INF - ====================================================================== ip_adress: [7472.9876] <2> tar_base::V_vTarMsgW: INF - Volume snapshots enabled 10:57:07.942: [7472.9876] <2> ov_log::V_GlobalLogEx: INF - file_access (constructor): 0 non-NTFS volumes 10:59:45.895: [7472.9876] <4> tar_backup::backup_done_state: INF - number of file directives not found: 0 10:59:45.895: [7472.9876] <4> tar_backup::backup_done_state: INF - number of file directives found: 7 10:59:45.895: [7472.9644] <4> tar_base::keepaliveThread: INF - keepalive thread terminating (reason: WAIT_OBJECT_0) 10:59:45.895: [7472.9876] <4> tar_base::stopKeepaliveThread: INF - keepalive thread has exited. (reason: WAIT_OBJECT_0) 10:59:45.895: [7472.9876] <4> send_msg_to_monitor: INF - in send_msg_to_monitor()... 10:59:45.895: [7472.9876] <2> tar_base::V_vTarMsgW: INF - EXIT STATUS 40: network connection broken 10:59:45.895: [7472.9876] <4> tar_backup::backup_done_state: INF - Not waiting for server status 10:59:45.895: [7472.9876] <4> dos_backup::tfs_reset: INF - Snapshot deletion start 10:59:45.895: [7472.9876] <4> V_Snapshot::V_Snapshot_Destroy: INF - Attempting to destroy volume snapshots: bpfis delete -id client_1440410011 11:00:19.895: [7472.9876] <4> V_Snapshot::V_Snapshot_Destroy: INF - Snapshot Destroy EXIT STATUS 0: the requested operation was successfully completed 11:00:20.004: [7472.9876] <4> OVStopCmd: INF - EXIT - status = 0 11:00:20.004: [7472.9876] <2> tar_base::V_Close: closing... 11:00:20.004: [7472.9876] <4> dos_backup::tfs_reset: INF - Snapshot deletion start 11:00:20.848: [7472.9876] <2> ov_log::V_GlobalLog: INF - BEDS_Term(): enter - InitFlags:0x00000001 11:00:20.848: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:21.848: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:22.848: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:23.848: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:24.848: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:25.848: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:26.879: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:27.879: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:28.879: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:29.879: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:30.879: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:31.879: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:32.879: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:33.879: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:34.879: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:35.879: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:36.895: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:37.895: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:38.895: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:39.895: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:40.895: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:41.895: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:42.895: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:43.895: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:44.895: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:45.895: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:46.895: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:47.895: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:48.895: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:49.895: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:50.895: [7472.9876] <16> dtcp_read: TCP - failure: recv socket (412) (TCP 10053: Software caused connection abort) 11:00:50.895: [7472.9876] <4> OVShutdown: INF - Finished process 11:00:50.895: [7472.9876] <4> WinMain: INF - Exiting C:\Program Files\VERITAS\NetBackup\bin\bpbkar32.exe 11:00:52.895: [7472.9876] <4> ov_log::OVClose: INF - Closing log file: C:\Program Files\VERITAS\NetBackup\logs\BPBKAR\082415.LOG