Veritas-bu
[Top] [All Lists]
<prev [Date] next>
[Advanced]
 <prev [Thread] next>

[Veritas-bu] Can someone please help me with this bpduplicate problem?

2001-08-15 12:20:01
Subject: [Veritas-bu] Can someone please help me with this bpduplicate problem?
From: c.williams AT capitalone DOT com (Williams, Chris (Lan))
Date: Wed, 15 Aug 2001 12:20:01 -0400 
Here is a big section of my log, sorry its so much info.  My problem is the
network errors contained in this (put_long: (11) network write() error: An
existing connection was forcibly closed by the remote host.)  Could someone
please give me some insight into this?  I am getting the following errors
while running duplication:

09:38:43 [864.948] <2> io_open: file
D:\VERITAS\NetBackup\db\media\tpreq/WES079 successfully opened
09:38:43 [864.948] <2> write_backup: media id WES079 mounted on drive index
2, drivepath \\.\Tape0, drivename Drive3
09:38:43 [864.948] <2> io_read_media_header: drive index 2, reading media
header, buflen = 32768, buff = 0x12789f0
09:38:43 [864.948] <2> io_ioctl: command (5)MTREW 1 from (../bptm.c.4951) on
drive index 2
09:38:43 [864.948] <2> io_write_media_header: drive index 2, writing media
header
09:38:43 [864.948] <2> io_ioctl: command (5)MTREW 1 from (../bptm.c.5784) on
drive index 2
09:38:43 [864.948] <2> io_ioctl: command (0)MTWEOF 1 from (../bptm.c.5812)
on drive index 2
09:38:44 [864.948] <2> getsockconnected: host=hwdw2kbkp01 service=bpcd
address=10.60.9.91 protocol=tcp reserved port=13782
09:38:44 [864.948] <2> bind_on_port_addr: bound to port 779
09:38:44 [864.948] <2> getsockconnected: Connect to hwdw2kbkp01 on port 779
09:38:44 [864.948] <2> bpcr_connect: no authentication required
09:38:44 [864.948] <2> bind_on_port_addr: bound to port 657
09:38:44 [864.948] <2> getsockconnected: host=hwdw2kbkp01 service=bpdbm
address=10.60.9.91 protocol=tcp non-reserved port=13721
09:38:44 [864.948] <2> bind_on_port_addr: bound to port 1426
09:38:44 [864.948] <2> check_authentication: no authentication required
09:38:44 [864.948] <4> write_backup: begin writing backup id
EASNOV02_0997841287, copy 2, fragment 1, to media id WES079 on drive index 2
09:38:44 [864.948] <2> process_brm_msg: no pending message from bpbrm
09:38:44 [864.948] <2> write_backup: waiting 5 seconds for start of another
backup
09:38:49 [864.948] <2> send_brm_msg: MEDIA READY
09:38:49 [864.948] <2> write_data: writing file number 1, setting block
position to 2
09:38:49 [864.948] <2> io_write_back_header: drive index 2,
EASNOV02_0997841287, file num = 1, mpx_headers = 1
09:38:49 [864.948] <2> write_data: completed writing backup header, start
writing data when first buffer is available
09:38:59 [1836.2764] <2> io_open: file
D:\VERITAS\NetBackup\db\media\tpreq/HWD003 successfully opened
09:38:59 [1836.2764] <2> io_read_media_header: drive index 1, reading media
header, buflen = 32768, buff = 0x1278438
09:38:59 [1836.2764] <2> io_ioctl: command (5)MTREW 1 from (../bptm.c.4951)
on drive index 1
09:38:59 [1836.2764] <2> io_ioctl: command (1)MTFSF 1 from (../bptm.c.5123)
on drive index 1
09:38:59 [1836.2764] <2> read_backup_mount_media: media id HWD003 mounted on
drive index 1
09:38:59 [1836.2764] <2> getsockconnected: host=hwdw2kbkp01 service=bpcd
address=10.60.9.91 protocol=tcp reserved port=13782
09:38:59 [1836.2764] <2> bind_on_port_addr: bound to port 909
09:38:59 [1836.2764] <2> getsockconnected: Connect to hwdw2kbkp01 on port
909
09:38:59 [1836.2764] <2> bpcr_connect: no authentication required
09:38:59 [1836.2764] <2> bind_on_port_addr: bound to port 736
09:38:59 [1836.2764] <2> io_position_for_read: positioning HWD003 to file
number 13
09:38:59 [1836.2764] <2> io_position_for_read: locating to absolute block
number 317243
09:39:03 [1836.2764] <2> io_position_for_read: locate block is done
09:39:03 [1836.2764] <2> io_read_back_header: drive index 1, reading backup
header
09:39:03 [1836.2764] <2> io_read_back_header: drive index 1, reading backup
header
09:39:03 [1836.2764] <2> io_position_for_read: successfully positioned
HWD003 to file number 13, mpx_header = 1
09:39:03 [1836.2764] <2> getsockconnected: host=hwdw2kbkp01 service=bpcd
address=10.60.9.91 protocol=tcp reserved port=13782
09:39:03 [1836.2764] <2> bind_on_port_addr: bound to port 791
09:39:03 [1836.2764] <2> getsockconnected: Connect to hwdw2kbkp01 on port
791
09:39:03 [1836.2764] <2> bpcr_connect: no authentication required
09:39:03 [1836.2764] <2> bind_on_port_addr: bound to port 787
09:39:03 [1836.2764] <2> getsockconnected: host=hwdw2kbkp01 service=bpdbm
address=10.60.9.91 protocol=tcp non-reserved port=13721
09:39:03 [1836.2764] <2> bind_on_port_addr: bound to port 1432
09:39:03 [1836.2764] <2> check_authentication: no authentication required
09:39:04 [1836.2764] <4> mpx_issue_begin_msg: begin reading backup id
EASNOV02_0997841287 (duplicate), copy 1, fragment 1 from media id HWD003 on
drive index 1
09:39:04 [1836.2764] <2> send_brm_msg: CURRENT POSITION HWD003 13
09:39:04 [1836.2764] <2> process_brm_msg: no pending message from bpbrm
09:39:05 [1836.2764] <2> read_brm_msg: CONTINUE RESTORE
09:39:05 [1836.2764] <2> send_brm_msg: MEDIA READY
09:39:05 [1836.2764] <2> mpx_read_data: begin reading data from media id
HWD003, file num 13
09:39:05 [1836.2764] <2> get_tape_position_for_read: absolute block position
prior to reading is 317245
09:39:05 [864.948] <2> write_data: received first buffer (65536 bytes),
begin writing data
09:39:12 [1836.2764] <2> mpx_read_data: read short block, bytes = 10752,
remainder = 512
09:39:12 [1836.2764] <2> mpx_read_data: ReadFile returned FALSE, A tape
access reached a filemark. (1101);bytes = 0
09:39:12 [1836.2764] <2> mpx_read_data: ReadFile detected EOM or EOF, bytes
= 0
09:39:12 [1836.2764] <2> mpx_read_data: waited for empty buffer 217 times,
delayed 224 times
09:39:12 [1836.2764] <2> send_brm_msg: MEDIA NOT READY
09:39:12 [1836.2764] <2> io_close: closing
D:\VERITAS\NetBackup\db\media\tpreq/HWD003, from ../mpxrestore.c.2304
09:39:12 [1836.2764] <2> mpx_read_backup: waiting for children to terminate
so exit status can be sent to bpbrm
09:39:12 [1308.1792] <2> terminate_restore: [1836] waited for full buffer 0
times, delayed 0 times
09:39:17 [2616.848] <2> fill_buffer: [864] socket is closed, waited for
empty buffer 0 times, delayed 0 times, read 29010944 bytes
09:39:17 [864.948] <2> write_data: writing block shorter than BUFF_SIZE,
10752 bytes
09:39:17 [864.948] <2> write_data: writing short block, 10752 bytes,
remainder 512
09:39:17 [864.948] <2> wait_for_sigcld: waiting for child 2616 to exit,
timeout is 300
09:39:17 [864.948] <2> write_backup: write_data() returned, exit_status = 0,
CINDEX = 0, backup_status = 0
09:39:17 [864.948] <2> write_backup: tp = 572268046, stp = 572255859, et =
12187, mpx_total_kbytes = 28554
09:39:17 [864.948] <2> io_terminate_tape: writing empty backup header, drive
index 2
09:39:17 [864.948] <2> io_ioctl: command (0)MTWEOF 1 from (../bptm.c.5274)
on drive index 2
09:39:17 [864.948] <2> io_terminate_tape: absolute block position prior to
writing empty header is 451
09:39:17 [864.948] <2> io_write_back_header: drive index 2, empty_file, file
num = 2, mpx_headers = 0
09:39:17 [864.948] <2> io_close: closing
D:\VERITAS\NetBackup\db\media\tpreq/WES079, from ../bptm.c.5344
09:39:17 [864.948] <2> io_open: file
D:\VERITAS\NetBackup\db\media\tpreq/WES079 successfully opened
09:39:17 [864.948] <2> io_terminate_tape: locate block positioned tape to
451 in io_terminate_tape
09:39:17 [864.948] <2> io_close: closing
D:\VERITAS\NetBackup\db\media\tpreq/WES079, from ../bptm.c.5443
09:39:17 [864.948] <2> getsockconnected: host=hwdw2kbkp01 service=bpdbm
address=10.60.9.91 protocol=tcp non-reserved port=13721
09:39:17 [864.948] <2> bind_on_port_addr: bound to port 1436
09:39:17 [864.948] <2> check_authentication: no authentication required
09:39:17 [864.948] <16> put_long: (11) network write() error: An existing
connection was forcibly closed by the remote host. ; socket = 400
09:39:17 [864.948] <2> getsockconnected: host=hwdw2kbkp01 service=bpdbm
address=10.60.9.91 protocol=tcp non-reserved port=13721
09:39:17 [864.948] <2> bind_on_port_addr: bound to port 1437
09:39:17 [864.948] <2> check_authentication: no authentication required
09:39:18 [864.948] <16> send_brm_msg: unable to write bpbrm message, network
write error (h_errno = 10054)
09:39:18 [864.948] <16> put_long: (11) network write() error: An existing
connection was forcibly closed by the remote host. ; socket = 400
09:39:18 [864.948] <2> getsockconnected: host=hwdw2kbkp01 service=bpdbm
address=10.60.9.91 protocol=tcp non-reserved port=13721
09:39:18 [864.948] <2> bind_on_port_addr: bound to port 1438
09:39:18 [864.948] <2> check_authentication: no authentication required
09:39:18 [1836.2764] <4> notify: executing -
D:\VERITAS\NetBackup\bin\restore_notify.cmd bptm EASNOV02_0997841287
duplication
09:39:18 [864.948] <16> send_brm_msg: unable to write bpbrm message, network
write error (h_errno = 10054)
09:39:18 [864.948] <4> notify: executing -
D:\VERITAS\NetBackup\bin\backup_notify.cmd bptm EASNOV02_0997841287
09:39:18 [1836.2764] <2> getsockconnected: host=hwdw2kbkp01 service=bpdbm
address=10.60.9.91 protocol=tcp non-reserved port=13721
09:39:18 [1836.2764] <2> bind_on_port_addr: bound to port 1439
09:39:18 [1836.2764] <2> check_authentication: no authentication required
09:39:18 [864.948] <2> getsockconnected: host=hwdw2kbkp01 service=bpdbm
address=10.60.9.91 protocol=tcp non-reserved port=13721
09:39:18 [864.948] <2> bind_on_port_addr: bound to port 1440
09:39:18 [864.948] <2> check_authentication: no authentication required
09:39:18 [1836.2764] <4> mpx_check_children: successfully duplicated backup
id EASNOV02_0997841287, copy 1, 28554 kbytes
09:39:18 [1836.2764] <16> put_long: (11) network write() error: An existing
connection was forcibly closed by the remote host. ; socket = 400
09:39:18 [1836.2764] <2> getsockconnected: host=hwdw2kbkp01 service=bpdbm
address=10.60.9.91 protocol=tcp non-reserved port=13721
09:39:18 [1836.2764] <2> bind_on_port_addr: bound to port 1441
09:39:18 [1836.2764] <2> check_authentication: no authentication required
09:39:18 [864.948] <4> write_backup: successfully wrote backup id
EASNOV02_0997841287, copy 2, 28554 Kbytes
09:39:18 [864.948] <2> bct_clear_active: removing group 0 handle 456
09:39:18 [864.948] <2> bct_clear_active: Set BCTable entry 0 to inactive,
ACTIVE_GC count decremented to 0
09:39:18 [864.948] <2> write_backup: waited for full buffer 225 times,
delayed 874 times
09:39:18 [864.948] <2> getsockconnected: host=hwdw2kbkp01 service=bpdbm
address=10.60.9.91 protocol=tcp non-reserved port=13721
09:39:18 [864.948] <2> bind_on_port_addr: bound to port 1442
09:39:18 [864.948] <2> check_authentication: no authentication required
09:39:18 [1836.2764] <16> send_brm_msg: unable to write bpbrm message,
network write error (h_errno = 10054)
09:39:18 [1836.2764] <2> bct_clear_active: Set BCTable entry 0 to inactive,
ACTIVE_GC count decremented to 0
09:39:18 [1836.2764] <2> mpx_waiting_term: waiting for TERMINATE or another
START RESTORE
09:39:18 [1836.2764] <16> get_long: (1) cannot read (byte 1) from network:
An existing connection was forcibly closed by the remote host. 
09:39:18 [1836.2764] <2> getsockconnected: host=hwdw2kbkp01 service=bpdbm
address=10.60.9.91 protocol=tcp non-reserved port=13721
09:39:18 [1836.2764] <2> bind_on_port_addr: bound to port 1443
09:39:18 [1836.2764] <2> check_authentication: no authentication required
09:39:18 [864.948] <4> write_backup: successfully wrote 1 of 1 multiplexed
duplications, total Kbytes 28554 at 2342.988 Kbytes/sec
09:39:18 [864.948] <2> bptm: waiting for TERMINATE from bpbrm/bpsched
09:39:18 [864.948] <16> get_long: (1) cannot read (byte 1) from network: An
existing connection was forcibly closed by the remote host. 
09:39:18 [864.948] <2> getsockconnected: host=hwdw2kbkp01 service=bpdbm
address=10.60.9.91 protocol=tcp non-reserved port=13721
09:39:18 [864.948] <2> bind_on_port_addr: bound to port 1444
09:39:18 [864.948] <2> check_authentication: no authentication required
09:39:18 [1836.2764] <16> read_brm_msg: unable to read bpbrm message,
network read error (h_errno = 10054)
09:39:18 [1836.2764] <2> getsockconnected: host=hwdw2kbkp01 service=bpdbm
address=10.60.9.91 protocol=tcp non-reserved port=13721
09:39:18 [1836.2764] <2> bind_on_port_addr: bound to port 1445
09:39:18 [1836.2764] <2> check_authentication: no authentication required
09:39:18 [864.948] <16> read_brm_msg: unable to read bpbrm message, network
read error (h_errno = 10054)
09:39:18 [864.948] <2> bptm: socket closed while waiting for TERMINATE from
bpbrm/bpsched
09:39:18 [864.948] <2> bptm: EXITING with status 0 <----------
 
**************************************************************************
The Information transmitted herewith is sensitive information intended only
for use to the individual or entity to which it is addressed. If the reader
of this message is not the intended recipient, you are hereby notified that
any review, retransmission, dissemination, distribution, copying or other
use of, or taking of any action in reliance upon, this information is
strictly prohibited. If you have received this communication in error,
please contact the sender and delete the material from your computer.
[More with this subject...]
<Prev in Thread] Current Thread [Next in Thread>
  • [Veritas-bu] Can someone please help me with this bpduplicate problem?, Williams, Chris (Lan) <=
Previous by Date:  [Veritas-bu] RE: Find media id of duplicate, Michael.Gorishek AT ebancllc DOT com
Next by Date:  [Veritas-bu] Patch Level for Netbackup 3.2, Larry Kingery
Previous by Thread:  [Veritas-bu] RE: Find media id of duplicate, Michael.Gorishek AT ebancllc DOT com
Next by Thread:  [Veritas-bu] trying to get a byte count from all backups, Wesley Brown
Indexes:  [Date] [Thread] [Top] [All Lists]