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