Veritas-bu

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

2002-10-22 17:19:33
Subject: [Veritas-bu] Backup speeds to STK 9940A drives
From: joe AT joe DOT net (Johnny Oestergaard)
Date: Tue, 22 Oct 2002 23:19:33 +0200
The STK9940A drive has 10MB/s over the head, so depending on your data 15 
MB/s could be good.
I have seen people getting 35MB/s, but that was in a lab test.

We use Win2K master/media servers and we get a max of 12-13 MB/s

/johnny



At 16:26 22-10-2002 +0100, andy.skates AT btopenworld DOT com wrote:
>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?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 <----------


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