------=_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--
|