Veritas-bu

[Veritas-bu] Backup Hangs with VSP enabled NBU 5.0 MP3 Any Ideas?

2005-06-16 11:13:05
Subject: [Veritas-bu] Backup Hangs with VSP enabled NBU 5.0 MP3 Any Ideas?
From: BarberL AT mont.disa DOT mil (BarberL AT mont.disa DOT mil)
Date: Thu, 16 Jun 2005 10:13:05 -0500
This message is in MIME format. Since your mail reader does not understand
this format, some or all of this message may not be legible.

------_=_NextPart_001_01C57285.E5288242
Content-Type: text/plain

When I run a backup for a client (win2003) and VSP is enabled, the job runs
fine for a while and then just hangs. No data being sent/written. If I
disable VSP, it will run fine. I got the nic and switch locked 100/full.
What I see in the BPBKAR log is this:
 
BPBKAR NetBackup Backup/Archive 5.0GA [Nov 6 2003]
 
Copyright 1993 - 2003 VERITAS Software Corporation
 
All Rights Reserved.
 
1:59:40.727 PM: [1172.2544] <2> WinMain: DAT - _pgmptr = 'C:\Program
Files\VERITAS\NetBackup\bin\bpbkar32.exe'
 
1:59:40.727 PM: [1172.2544] <2> WinMain: DAT - lpCmdLine = '-r 1073748208
-ru root -dt 3043 -to 600 -clnt xxx.xxx.xxx.xxx -class UJACDP09sys -sched
Incr -st INCR -bpstart_to 600 -bpend_to 600 -read_to 300 -use_otm -fso -b
xxx.xxx.xxx.xxx_1118771940 -kl 10 -ct 13 -use_ofb '
 
1:59:40.727 PM: [1172.2544] <2> date_debug: DAT - timezone: Eastern Standard
Time, offset=18000, dst: Eastern Daylight Time
 
1:59:40.727 PM: [1172.2544] <2> date_debug: DAT - current time: 1118771980,
6/14/2005 1:59:40 PM
 
1:59:40.727 PM: [1172.2544] <2> date_debug: DAT - 01/01/94 UCT: 757382400,
12/31/1993 8:00:00 PM
 
1:59:40.727 PM: [1172.2544] <2> date_debug: DAT - 07/01/94 UCT: 773020800,
6/30/1994 8:00:00 PM
 
1:59:40.727 PM: [1172.2544] <2> WinMain: DAT - standard input handle = 292
 
1:59:40.727 PM: [1172.2544] <2> WinMain: DAT - standard output handle = 1892
 
1:59:40.727 PM: [1172.2544] <2> WinMain: DAT - standard error handle = 1904
 
1:59:41.805 PM: [1172.2544] <4> tar_backup::backup_create: INF - dwJobData:
ffffffff
 
1:59:41.805 PM: [1172.2544] <4> tar_backup::backup_create: INF - dwJob:
ffffffff
 
1:59:41.805 PM: [1172.2544] <4> dos_backup::V_PreProcessing: INF - backup
privileges enabled, previous = 0
 
1:59:41.805 PM: [1172.2544] <4> dos_backup::V_PreProcessing: INF - restore
privileges enabled, previous = 0
 
1:59:41.805 PM: [1172.2544] <4> dos_backup::V_PreProcessing: INF - security
privileges enabled, previous = 0
 
1:59:41.805 PM: [1172.2544] <4> dos_backup::V_PreProcessing: INF - tcb
privileges enabled, previous = 0
 
1:59:41.805 PM: [1172.2544] <4> dos_backup::V_PreProcessing: INF - create
token privileges enabled, previous = 0
 
1:59:41.805 PM: [1172.2544] <4> dos_backup::V_PreProcessing: INF - user
name: root
 
1:59:41.805 PM: [1172.2544] <4> dos_backup::V_PreProcessing: INF - no access
token: scheduled backup
 
1:59:41.805 PM: [1172.2544] <2> tar_backup::backup_create: TAR - Incremental
backup using the archive bit
 
1:59:41.883 PM: [1172.2544] <2> tar_backup::backup_create: TAR - CONTINUE
BACKUP received
 
1:59:41.883 PM: [1172.2544] <2> tar_backup::backup_create: TAR - backup
filename = ALL_LOCAL_DRIVES
 
1:59:41.914 PM: [1172.2544] <4> dos_backup::V_VerifyFileList: INF - Added:
C:\
 
1:59:41.914 PM: [1172.2544] <4> dos_backup::V_VerifyFileList: INF - Added:
D:\
 
1:59:41.914 PM: [1172.2544] <4> dos_backup::V_VerifyFileList: INF - Added:
System_State:\
 
1:59:41.914 PM: [1172.2544] <2> ov_log::V_GlobalLog: INF - BEDS_Init() Enter
InitFlags:0x0
 
1:59:41.930 PM: [1172.2544] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE
Device Name: C:
 
1:59:41.930 PM: [1172.2544] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE
Device Name: D:
 
1:59:41.930 PM: [1172.2544] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE
Device Name: Microsoft Terminal Services
 
1:59:41.930 PM: [1172.2544] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE
Device Name: Microsoft Windows Network
 
1:59:41.930 PM: [1172.2544] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE
Device Name: Web Client Network
 
1:59:41.930 PM: [1172.2544] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE
Device Name: UJACDP09
 
1:59:41.930 PM: [1172.2544] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE
Device Name: Shadow?Copy?Components
 
1:59:42.383 PM: [1172.2544] <2> ov_log::V_GlobalLog: INF - BEDS_Term() Enter
InitFlags:0x1
 
1:59:42.398 PM: [1172.2544] <4> backup_create: INF - NetBackup Temp
Directory: 'C:\Program Files\VERITAS\\NetBackup\Temp'
 
1:59:42.398 PM: [1172.2544] <2> ov_log::V_GlobalLog: INF - BEDS_Init() Enter
InitFlags:0x0
 
1:59:42.414 PM: [1172.2544] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE
Device Name: C:
 
1:59:42.414 PM: [1172.2544] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE
Device Name: D:
 
1:59:42.414 PM: [1172.2544] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE
Device Name: Microsoft Terminal Services
 
1:59:42.414 PM: [1172.2544] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE
Device Name: Microsoft Windows Network
 
1:59:42.414 PM: [1172.2544] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE
Device Name: Web Client Network
 
1:59:42.414 PM: [1172.2544] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE
Device Name: ujacdp09
 
1:59:42.414 PM: [1172.2544] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE
Device Name: Shadow?Copy?Components
 
1:59:42.414 PM: [1172.2544] <4> dos_backup::tfs_scanstart: INF - backup
type: INCR
 
1:59:42.414 PM: [1172.2544] <4> dos_backup::tfs_include: INF - folder
(Boot_Files) has been created recently (since 6/14/2005 12:08:58 PM). It
will be backed up in full.
 
1:59:44.789 PM: [1020.1892] <16> dtcp_write: TCP - failure: send socket
(1904) (TCP 10053: Software caused connection abort)
 
1:59:44.789 PM: [1020.1892] <16> dtcp_write: TCP - failure: attempted to
send 1 bytes
 
 
This error reapeats until I kill the job.
 
 

Thank you,
 
Layne Barber

------_=_NextPart_001_01C57285.E5288242
Content-Type: text/html

<!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 4.0 Transitional//EN">
<HTML><HEAD>
<META HTTP-EQUIV="Content-Type" CONTENT="text/html; charset=US-ASCII">
<TITLE>Message</TITLE>

<META content="MSHTML 6.00.2900.2668" name=GENERATOR></HEAD>
<BODY>
<DIV><FONT face=Arial size=2>When I run a backup for a client (win2003) and VSP 
is enabled, the job runs fine for a while and then just hangs. No data being 
sent/written. If I disable VSP, it will run fine. I got the nic and switch 
locked 100/full. What I see in the BP<SPAN class=447170515-16062005>B</SPAN>KAR 
log is this:</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>BPBKAR NetBackup Backup/Archive 5.0GA [Nov 6 
2003]</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>Copyright 1993 - 2003 VERITAS Software 
Corporation</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>All Rights Reserved.</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:40.727 PM: [1172.2544] &lt;2&gt; WinMain: DAT 
- _pgmptr = 'C:\Program Files\VERITAS\NetBackup\bin\bpbkar32.exe'</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:40.727 PM: [1172.2544] &lt;2&gt; WinMain: DAT 
- lpCmdLine = '-r 1073748208 -ru root -dt 3043 -to 600 -clnt xxx.xxx.xxx.xxx 
-class UJACDP09sys -sched Incr -st INCR -bpstart_to 600 -bpend_to 600 -read_to 
300 -use_otm -fso -b xxx.xxx.xxx.xxx_1118771940 -kl 10 -ct 13 -use_ofb 
'</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:40.727 PM: [1172.2544] &lt;2&gt; date_debug: 
DAT - timezone: Eastern Standard Time, offset=18000, dst: Eastern Daylight 
Time</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:40.727 PM: [1172.2544] &lt;2&gt; date_debug: 
DAT - current time: 1118771980, 6/14/2005 1:59:40 PM</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:40.727 PM: [1172.2544] &lt;2&gt; date_debug: 
DAT - 01/01/94 UCT: 757382400, 12/31/1993 8:00:00 PM</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:40.727 PM: [1172.2544] &lt;2&gt; date_debug: 
DAT - 07/01/94 UCT: 773020800, 6/30/1994 8:00:00 PM</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:40.727 PM: [1172.2544] &lt;2&gt; WinMain: DAT 
- standard input handle = 292</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:40.727 PM: [1172.2544] &lt;2&gt; WinMain: DAT 
- standard output handle = 1892</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:40.727 PM: [1172.2544] &lt;2&gt; WinMain: DAT 
- standard error handle = 1904</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.805 PM: [1172.2544] &lt;4&gt; 
tar_backup::backup_create: INF - dwJobData: ffffffff</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.805 PM: [1172.2544] &lt;4&gt; 
tar_backup::backup_create: INF - dwJob: ffffffff</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.805 PM: [1172.2544] &lt;4&gt; 
dos_backup::V_PreProcessing: INF - backup privileges enabled, previous = 
0</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.805 PM: [1172.2544] &lt;4&gt; 
dos_backup::V_PreProcessing: INF - restore privileges enabled, previous = 
0</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.805 PM: [1172.2544] &lt;4&gt; 
dos_backup::V_PreProcessing: INF - security privileges enabled, previous = 
0</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.805 PM: [1172.2544] &lt;4&gt; 
dos_backup::V_PreProcessing: INF - tcb privileges enabled, previous = 
0</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.805 PM: [1172.2544] &lt;4&gt; 
dos_backup::V_PreProcessing: INF - create token privileges enabled, previous = 
0</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.805 PM: [1172.2544] &lt;4&gt; 
dos_backup::V_PreProcessing: INF - user name: root</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.805 PM: [1172.2544] &lt;4&gt; 
dos_backup::V_PreProcessing: INF - no access token: scheduled 
backup</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.805 PM: [1172.2544] &lt;2&gt; 
tar_backup::backup_create: TAR - Incremental backup using the archive 
bit</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.883 PM: [1172.2544] &lt;2&gt; 
tar_backup::backup_create: TAR - CONTINUE BACKUP received</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.883 PM: [1172.2544] &lt;2&gt; 
tar_backup::backup_create: TAR - backup filename = ALL_LOCAL_DRIVES</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.914 PM: [1172.2544] &lt;4&gt; 
dos_backup::V_VerifyFileList: INF - Added: C:\</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.914 PM: [1172.2544] &lt;4&gt; 
dos_backup::V_VerifyFileList: INF - Added: D:\</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.914 PM: [1172.2544] &lt;4&gt; 
dos_backup::V_VerifyFileList: INF - Added: System_State:\</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.914 PM: [1172.2544] &lt;2&gt; 
ov_log::V_GlobalLog: INF - BEDS_Init() Enter InitFlags:0x0</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.930 PM: [1172.2544] &lt;2&gt; 
ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: C:</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.930 PM: [1172.2544] &lt;2&gt; 
ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: D:</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.930 PM: [1172.2544] &lt;2&gt; 
ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Terminal 
Services</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.930 PM: [1172.2544] &lt;2&gt; 
ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Windows 
Network</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.930 PM: [1172.2544] &lt;2&gt; 
ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Web Client 
Network</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.930 PM: [1172.2544] &lt;2&gt; 
ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: UJACDP09</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:41.930 PM: [1172.2544] &lt;2&gt; 
ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: 
Shadow?Copy?Components</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:42.383 PM: [1172.2544] &lt;2&gt; 
ov_log::V_GlobalLog: INF - BEDS_Term() Enter InitFlags:0x1</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:42.398 PM: [1172.2544] &lt;4&gt; 
backup_create: INF - NetBackup Temp Directory: 'C:\Program 
Files\VERITAS\\NetBackup\Temp'</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:42.398 PM: [1172.2544] &lt;2&gt; 
ov_log::V_GlobalLog: INF - BEDS_Init() Enter InitFlags:0x0</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:42.414 PM: [1172.2544] &lt;2&gt; 
ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: C:</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:42.414 PM: [1172.2544] &lt;2&gt; 
ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: D:</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:42.414 PM: [1172.2544] &lt;2&gt; 
ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Terminal 
Services</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:42.414 PM: [1172.2544] &lt;2&gt; 
ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Windows 
Network</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:42.414 PM: [1172.2544] &lt;2&gt; 
ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Web Client 
Network</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:42.414 PM: [1172.2544] &lt;2&gt; 
ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: ujacdp09</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:42.414 PM: [1172.2544] &lt;2&gt; 
ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: 
Shadow?Copy?Components</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:42.414 PM: [1172.2544] &lt;4&gt; 
dos_backup::tfs_scanstart: INF - backup type: INCR</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:42.414 PM: [1172.2544] &lt;4&gt; 
dos_backup::tfs_include: INF - folder (Boot_Files) has been created recently 
(since 6/14/2005 12:08:58 PM). It will be backed up in full.</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:44.789 PM: [1020.1892] &lt;16&gt; dtcp_write: 
TCP - failure: send socket (1904) (TCP 10053: Software caused connection 
abort)</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2>1:59:44.789 PM: [1020.1892] &lt;16&gt; dtcp_write: 
TCP - failure: attempted to send 1 bytes</FONT></DIV>
<DIV>&nbsp;</DIV>
<DIV><FONT face=Arial size=2></FONT>&nbsp;</DIV>
<DIV><SPAN class=447170515-16062005><FONT face=Arial size=2>This error reapeats 
until I kill the job.</FONT></SPAN></DIV>
<DIV><FONT face=Arial size=2></FONT>&nbsp;</DIV>
<DIV>&nbsp;</DIV><FONT face=Arial size=2>
<DIV><BR>Thank you,</DIV>
<DIV>&nbsp;</DIV>
<DIV>Layne Barber</FONT></DIV></BODY></HTML>

------_=_NextPart_001_01C57285.E5288242--

<Prev in Thread] Current Thread [Next in Thread>