Veritas-bu

[Veritas-bu] Backup speeds to STK 9940A drives

2002-10-22 11:26:52
Subject: [Veritas-bu] Backup speeds to STK 9940A drives
From: andy.skates AT btopenworld DOT com (andy.skates AT btopenworld DOT com)
Date: Tue, 22 Oct 2002 16:26:52 +0100 (BST)
------=_Part_11540_5425168.1035300412765
Content-Type: text/plain; charset=iso-8859-1
Content-Transfer-Encoding: 7bit

Solaris 8 Media Server
NBU 4.5 MP1
E220R (dual processor, 3Gb RAM)
Emulex LP8000 HBA

NUMBER_DATA_BUFFERS = 32
SIZE_DATA_BUFFERS = 262144

Only getting about 15009Kb/sec to FC tape.

Is there anything that I can change to try and
improve this?
------=_Part_11540_5425168.1035300412765
Content-Type: text/plain; name=log.102202.64.256
Content-Transfer-Encoding: 7bit
Content-Disposition: form-data; filename=log.102202.64.256

12:26:55.741 [5536] <2> bptm: INITIATING (VERBOSE = 0): -w -c lnoxp011nbu -den 
6 -rt 8 -rn 0 -stunit lnoxp011nbu -cl Test_lnoxp011nbu -bt 1035286174 -b 
lnoxp011nbu_1035286174 -st 0 -cj 4 -p Sybase -hostname lnoxp011nbu -ru root 
-rclnt lnoxp011nbu -rclnthostname lnoxp011nbu -rl 1 -rp 1209600 -sl FULL -ct 0 
-maxfrag 2000 -mediasvr lnoxp011nbu -jobid 386 -masterversion 450000 -shm 
12:26:55.742 [5536] <2> validate_parameters: maximum fragment size (in Kbytes) 
is 2000000
12:26:55.742 [5536] <2> io_init: using 262144 data buffer size
12:26:55.742 [5536] <2> io_init: CINDEX 0, sched Kbytes for monitoring = 30000
12:26:55.742 [5536] <2> io_set_recvbuf: setting receive network buffer to 32032 
bytes
12:26:55.743 [5536] <2> io_init: using 64 data buffers
12:26:55.743 [5536] <2> io_init: child delay = 20, parent delay = 30 
(milliseconds)
12:26:55.743 [5536] <2> io_init: shm_size = 16778756, buffer address = 
0xfdc00000, buf control = 0xfec00000, ready ptr = 0xfec00600
12:26:55.771 [5536] <2> setup_bpbkar_info: 
/usr/openv/netbackup/db/config/shm/lnoxp011nbu_1035286174 file successfully 
created, mode = 0600, user root
12:26:55.783 [5536] <2> logconnections: BPDBM CONNECT FROM 128.180.101.48.33364 
TO 128.180.101.49.13721
12:26:56.001 [5536] <2> logconnections: BPDBM CONNECT FROM 128.180.101.48.33365 
TO 128.180.101.49.13721
12:26:56.210 [5536] <2> add_to_vmhost_list: added lnoxp010nbu to vmhost list
12:26:56.381 [5536] <2> select_media: selected media id IS0011 for backup[0], 
lnoxp011nbu(rl = 1) <----------
12:26:56.382 [5536] <2> mount_open_media: Waiting for mount of media id IS0011 
(copy 1) on server lnoxp011nbu.
12:27:30.027 [5536] <2> io_open: SCSI RESERVE
12:27:30.041 [5536] <2> io_open: file 
/usr/openv/netbackup/db/media/tpreq/IS0011 successfully opened
12:27:30.041 [5536] <2> write_backup: media id IS0011 mounted on drive index 0, 
drivepath /dev/rmt/0cbn, drivename 9940-1, copy 1
12:27:30.042 [5536] <2> io_read_media_header: drive index 0, reading media 
header, buflen = 32768, buff = 0x2282c0, copy 1
12:27:30.042 [5536] <2> io_ioctl: command (5)MTREW 1 from (bptm.c.6110) on 
drive index 0
12:27:30.051 [5536] <2> io_ioctl: command (1)MTFSF 1 from (bptm.c.6301) on 
drive index 0
12:27:30.052 [5536] <2> io_position_for_write: position media id IS0011, copy 
1, current number images = 16
12:27:30.052 [5536] <2> io_position_for_write: locating to absolute block 
number 207925, copy 1
12:28:34.703 [5536] <2> io_position_for_write: locate block is done
12:28:35.559 [5536] <2> io_position_for_write: processing empty header, filenum 
= 17, bid = (empty_file), copy 1
12:28:35.560 [5536] <2> io_position_for_write: empty header found on IS0011, 
OK, copy 1
12:28:35.560 [5536] <2> io_close: closing 
/usr/openv/netbackup/db/media/tpreq/IS0011, from bptm.c.16684
12:28:35.561 [5536] <2> io_open: SCSI RESERVE
12:28:35.567 [5536] <2> io_open: file 
/usr/openv/netbackup/db/media/tpreq/IS0011 successfully opened
12:28:35.567 [5536] <2> io_ioctl: command (2)MTBSF 1 from (bptm.c.16704) on 
drive index 0
12:28:37.724 [5536] <2> io_ioctl: command (0)MTWEOF 1 from (bptm.c.16728) on 
drive index 0
12:28:42.508 [5536] <2> logconnections: BPDBM CONNECT FROM 128.180.101.48.33367 
TO 128.180.101.49.13721
12:28:42.730 [5536] <4> write_backup: begin writing backup id 
lnoxp011nbu_1035286174, copy 1, fragment 1, to media id IS0011 on drive index 0
12:28:42.730 [5536] <2> signal_parent: sending SIGUSR1 to bpbrm (pid = 5533)
12:28:42.731 [5536] <2> write_data: twin_index: 0 active: 1 dont_process: 0 
wrote_backup_hdr: 0 finished_buff: 0 saved_cindex: 0
12:28:42.734 [5536] <2> write_data: absolute block position prior to writing 
backup header(s) is 207925, copy 1
12:28:42.734 [5536] <2> io_write_back_header: drive index 0, 
lnoxp011nbu_1035286174, file num = 17, mpx_headers = 0, copy 1
12:28:42.736 [5536] <2> write_data: completed writing backup header, start 
writing data when first buffer is available, copy 1
12:28:42.736 [5536] <2> write_data: first write, twin_index: 0 cindex: 0 
dont_process: 1 wrote_backup_hdr: 1 finished_buff: 0
12:28:42.736 [5536] <2> write_data: received first buffer (262144 bytes), begin 
writing data
12:29:50.221 [5536] <2> write_data: writing block shorter than BUFF_SIZE, 
196608 bytes
12:29:50.227 [5536] <2> write_data: writing short block, 196608 bytes, 
remainder 0
12:29:50.227 [5536] <2> write_data: waited for full buffer 0 times, delayed 0 
times
12:29:50.228 [5536] <2> write_backup: write_data() returned, exit_status = 0, 
CINDEX = 0, TWIN_INDEX = 0, backup_status = 0
12:29:50.228 [5536] <2> io_terminate_tape: writing empty backup header, drive 
index 0, copy 1
12:29:50.228 [5536] <2> io_ioctl: command (0)MTWEOF 1 from (bptm.c.6531) on 
drive index 0
12:29:51.758 [5536] <2> io_write_back_header: drive index 0, empty_file, file 
num = 18, mpx_headers = 0, copy 1
12:29:51.759 [5536] <2> io_close: closing 
/usr/openv/netbackup/db/media/tpreq/IS0011, from bptm.c.6619
12:29:52.644 [5536] <2> io_terminate_tape: absolute block position prior to 
writing empty header is 211885, copy 1
12:29:52.644 [5536] <2> io_terminate_tape: block position check: actual 211885, 
expected 211885
12:29:52.671 [5536] <2> io_open: SCSI RESERVE
12:29:52.677 [5536] <2> io_open: file 
/usr/openv/netbackup/db/media/tpreq/IS0011 successfully opened
12:29:52.677 [5536] <2> io_ioctl: command (2)MTBSF 2 from (bptm.c.6727) on 
drive index 0
12:29:52.679 [5536] <2> io_ioctl: command (1)MTFSF 1 from (bptm.c.6729) on 
drive index 0
12:29:52.681 [5536] <2> io_close: closing 
/usr/openv/netbackup/db/media/tpreq/IS0011, from bptm.c.6732
12:29:52.683 [5536] <2> logconnections: BPDBM CONNECT FROM 128.180.101.48.33401 
TO 128.180.101.49.13721
12:29:52.910 [5536] <2> logconnections: BPDBM CONNECT FROM 128.180.101.48.33402 
TO 128.180.101.49.13721
12:29:53.120 [5536] <4> write_backup: successfully wrote backup id 
lnoxp011nbu_1035286174, copy 1, fragment 1, 1013184 Kbytes at 15011.913 
Kbytes/sec
12:29:53.120 [5536] <2> notify: executing - 
/usr/openv/netbackup/bin/backup_notify bptm lnoxp011nbu_1035286174
12:29:53.276 [5536] <2> bptm: EXITING with status 0 <----------
12:29:56.780 [5576] <2> bptm: INITIATING (VERBOSE = 0): -count -cmd -rt 8 -rn 0 
-stunit lnoxp011nbu -den 6 -mt 2 -masterversion 450000 
12:29:56.782 [5576] <2> bptm: EXITING with status 0 <----------
12:29:57.840 [5579] <2> bptm: INITIATING (VERBOSE = 0): -U 
12:29:57.841 [5579] <2> db_byid: search for media id IS0011
12:29:57.841 [5579] <2> db_byid: IS0011 found at offset 1
12:29:57.841 [5579] <2> tpunmount_all: tpunmount'ing 
/usr/openv/netbackup/db/media/tpreq/IS0011
12:29:57.843 [5579] <2> TpUnmountWrapper: SCSI RELEASE
12:29:57.939 [5579] <2> bptm: EXITING with status 0 <----------
12:29:59.227 [5585] <2> bptm: INITIATING (VERBOSE = 0): -U 
12:29:59.228 [5585] <2> bptm: EXITING with status 0 <----------
12:30:26.782 [5589] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired 
12:30:26.783 [5589] <2> bptm: EXITING with status 0 <----------
12:34:25.451 [5600] <2> bptm: INITIATING (VERBOSE = 0): -count -cmd -rt 8 -rn 0 
-stunit lnoxp011nbu -den 6 -mt 2 -masterversion 450000 
12:34:25.453 [5600] <2> bptm: EXITING with status 0 <----------
12:34:44.566 [5605] <2> bptm: INITIATING (VERBOSE = 0): -delete_expired 
12:34:44.566 [5605] <2> bptm: EXITING with status 0 <----------
12:35:58.681 [5678] <2> bptm: INITIATING (VERBOSE = 0): -count -cmd -rt 8 -rn 0 
-stunit lnoxp011nbu -den 6 -mt 2 -masterversion 450000 
12:35:58.694 [5678] <2> logconnections: BPDBM CONNECT FROM 128.180.101.48.33404 
TO 128.180.101.49.13721
12:35:58.910 [5678] <16> bptm: cannot count up drives, device manager daemon 
(ltid) may not be running
12:35:58.910 [5678] <2> bptm: EXITING with status 80 <----------
12:36:41.542 [5830] <2> bptm: INITIATING (VERBOSE = 0): -count -cmd -rt 8 -rn 0 
-stunit lnoxp011nbu -den 6 -mt 2 -masterversion 450000 
12:36:41.544 [5830] <2> bptm: EXITING with status 0 <----------
12:40:24.323 [5886] <2> bptm: INITIATING (VERBOSE = 0): -U 
12:40:24.324 [5886] <2> bptm: EXITING with status 0 <----------

------=_Part_11540_5425168.1035300412765--


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