No subject


Mon Sep 15 09:42:21 CDT 2008


minutes.  The machine says the read-rate was 180 Kbytes/sec but some quick 
math says 234496 KB in 371 minutes averages merely 6.4 KB/sec. 
By the way, all the fragments for this image were arranged sequentially on 
the same tape according to the logged positioning info so I don't think 
it's a positioning/mechanical delay.
Most of the big time delays were flanked by this pair of notes: 
02:58:20 [25598] <2> get_tape_position_for_read: absolute block position 
prior to reading is 82012 
09:07:54 [25598] <2> read_data: stopping mpx read because 234496 Kbytes of 
234496 were read 
I've been searching the support site and found technote 243197 about 
NET_BUFFER_SZ and adjusted that yesterday to match my SIZE_DATA_BUFFERS 
setting.  I haven't seen a significant change from this but, frankly, it's 
still early - the first bptm child since this change just started about 2 
hours ago.
So - basically - does anybody know what's going on with this? 
-Mark 
Here's just one fragment from bptm log... 
02:58:20 [25598] <2> read_backup: current media id is 000351, next media 
id is 0 
00351 
02:58:20 [25598] <2> io_position_for_read: positioning 000351 to file 
number 13 
02:58:20 [25598] <2> io_read_back_header: drive index 3, reading backup 
header 
02:58:20 [25598] <2> io_read_back_header: drive index 3, reading backup 
header 
02:58:20 [25598] <2> io_read_back_header: drive index 3, reading backup 
header 
02:58:20 [25598] <2> io_read_back_header: drive index 3, reading backup 
header 
02:58:20 [25598] <2> io_position_for_read: successfully positioned 000351 
to fil 
e number 13, mpx_header = 1 
02:58:20 [25598] <2> getsockconnected: host=backup00 service=bpdbm 
address=192.1 
68.1.30 protocol=tcp non-reserved port=13721 
02:58:20 [25598] <4> read_backup: begin reading backup id 
tools00.lodo_101727692 
3 (duplicate), copy 1, fragment 11 from media id 000351 on drive index 3 
02:58:20 [25598] <2> read_data: last_frag = 0, opt_bytes_left = 0, 
tape_Kbytes_l 
eft = 234496, opt_remainder = 0 
02:58:20 [25598] <2> get_tape_position_for_read: absolute block position 
prior t 
o reading is 82012 
09:07:54 [25598] <2> read_data: stopping mpx read because 234496 Kbytes of 
23449 
6 were read 
09:07:54 [25598] <2> io_ioctl: command (1)MTFSF 1 from (bptm.c.9438) on 
drive in 
dex 3 
09:09:14 [25598] <2> read_data: waited for empty buffer 0 times, delayed 0 
times 
09:09:14 [25598] <2> getsockconnected: host=backup00 service=bpdbm 
address=192.1 
68.1.30 protocol=tcp non-reserved port=13721 
09:09:14 [25598] <4> read_backup: successfully read (duplicate) backup id 
tools0 
0.lodo.in.exactis.com_1017276923, copy 1, fragment 11, 234496 Kbytes at 
180.137 
Kbytes/sec 






More information about the Veritas-bu mailing list