Hi again,
Here is the btape output from my second ultrium-1 drive (Drive-2). The
drive is part of an autochanger, but I am talking to the drives
directly for these btape tests.
This drive doesn't hang, like Drive-1. However, it still fails on the
"btape fill simple" command after writing ~2GB. The last block does not
appear to be properly written to tape. Subsequently, the read of this
last block fails.
Since I am having similar problems with two drives with different tapes,
I suspect there is a problem in my configuration (opposed to
hardware/media problems).
Some questions:
i) Can you please post your bacula-sd.conf & bacula-dir.conf settings
for LTO drives (especially HP Ultrium-1 drives) plus additional st
options ?
ii) Should I set the MaxVolume Size ?
iii) Suggestions for HBA settings (eg command queuing, drive buffers,
etc) ?
Thanks,
My bacula-sd.conf section:
Device {
Name = Drive-2 #
Drive Index = 1
Media Type = LTO-1
Archive Device = /dev/nst1
AutomaticMount = yes; # when device opened, read it
AlwaysOpen = no;
RemovableMedia = yes;
RandomAccess = no;
AutoChanger = yes
LabelMedia = yes;
# Enable the Alert command only if you have the mtx package loaded
Alert Command = "sh -c 'tapeinfo -f %c |grep TapeAlert|cat'"
# SpoolDirectory=/.tape_spool
# Maximum Spool Size = 20G
# Maximum Open Wait = 30 seconds
}
[EMAIL PROTECTED] bin]# ./btape -v -d 100 /dev/nst1
Tape block granularity is 1024 bytes.
btape: stored_conf.c:613 Inserting director res: localhost-mon
btape: stored_conf.c:613 Inserting device res: Drive-1
btape: stored_conf.c:613 Inserting device res: Drive-2
btape: butil.c:266 Using device: "/dev/nst1" for writing.
btape: dev.c:242 init_dev: tape=2 dev_name=/dev/nst1
btape: dev.c:277 open dev: tape=2 dev_name="Drive-2" (/dev/nst1) vol=
mode=OPEN_READ_ONLY
btape: dev.c:335 open dev: device is tape
btape: autochanger.c:246 Locking changer Autochanger
07-Jan 10:09 btape: 3301 Issuing autochanger "loaded drive 1" command.
btape: bpipe.c:291 Run program returning 268435457
btape: autochanger.c:217 run_prog: /opt/bacula/bacula/bin/mtx-changer
/dev/sg0 loaded 0 /dev/nst1 1 stat=268435457 result=cannot open SCSI
device '/dev/sg0' - No such file or directory
07-Jan 10:09 btape: 3991 Bad autochanger "loaded drive 1" command:
ERR=Child exited with code 1.
btape: autochanger.c:255 Unlocking changer Autochanger
btape: dev.c:358 Try open "Drive-2" (/dev/nst1) mode=OPEN_READ_ONLY
nonblocking=2048
btape: dev.c:397 openmode=3 OPEN_READ_ONLY
btape: dev.c:410 open dev: tape 3 opened
btape: dev.c:269 Close fd for mode change.
btape: dev.c:277 open dev: tape=2 dev_name="Drive-2" (/dev/nst1) vol=
mode=OPEN_READ_WRITE
btape: dev.c:335 open dev: device is tape
btape: autochanger.c:246 Locking changer Autochanger
07-Jan 10:09 btape: 3301 Issuing autochanger "loaded drive 1" command.
btape: bpipe.c:291 Run program returning 268435457
btape: autochanger.c:217 run_prog: /opt/bacula/bacula/bin/mtx-changer
/dev/sg0 loaded 0 /dev/nst1 1 stat=268435457 result=cannot open SCSI
device '/dev/sg0' - No such file or directory
07-Jan 10:09 btape: 3991 Bad autochanger "loaded drive 1" command:
ERR=Child exited with code 1.
btape: autochanger.c:255 Unlocking changer Autochanger
btape: dev.c:358 Try open "Drive-2" (/dev/nst1) mode=OPEN_READ_WRITE
nonblocking=0
btape: dev.c:397 openmode=2 OPEN_READ_WRITE
btape: dev.c:410 open dev: tape 3 opened
btape: btape.c:338 open device "Drive-2" (/dev/nst1): OK
*rewind
btape: dev.c:655 rewind_dev fd=3 "Drive-2" (/dev/nst1)
btape: btape.c:438 Rewound "Drive-2" (/dev/nst1)
*status
Bacula status: file=0 block=0
Device status: BOT ONLINE IM_REP_EN file=0 block=0
btape: btape.c:1759 Device status: 645. ERR=
*cap
Configured device capabilities:
EOF BSR BSF FSR FSF FASTFSF !BSFATEOM EOM REM !RACCESS AUTOMOUNT LABEL
!ANONVOLS !ALWAYSOPEN MTIOCGET
Device status:
OPENED TAPE !LABEL MALLOC APPEND !READ !EOT !WEOT !EOF !NEXTVOL !SHORT
Device parameters:
Device name: /dev/nst1
File=0 block=0
Min block=0 Max block=0
Status:
Bacula status: file=0 block=0
Device status: BOT ONLINE IM_REP_EN file=0 block=0
btape: btape.c:1759 Device status: 645. ERR=
*fill
This command simulates Bacula writing to a tape.
It requires either one or two blank tapes, which it
will label and write.
If you have an autochanger configured, it will use
the tapes that are in slots 1 and 2, otherwise, you will
be prompted to insert the tapes when necessary.
It will print a status approximately
every 322 MB, and write an EOF every 3.2 GB. If you have
selected the simple test option, after writing the first tape
it will rewind it and re-read the last block written.
If you have selected the multiple tape test, when the first tape
fills, it will ask for a second, and after writing a few more
blocks, it will stop. Then it will begin re-reading the
two tapes.
This may take a long time -- hours! ...
Do you want to run the simplified test (s) with one tape
or the complete multiple tape (m) test: (s/m) s
Simple test (single tape) selected.
btape: btape.c:1818 Begin append device="Drive-2" (/dev/nst1)
btape: btape.c:1819 MaxVolSize=0
btape: dev.c:655 rewind_dev fd=3 "Drive-2" (/dev/nst1)
btape: dev.c:1521 weof_dev
btape: dev.c:655 rewind_dev fd=3 "Drive-2" (/dev/nst1)
btape: label.c:299 write_volume_label()
btape: label.c:305 Label type=0
btape: dev.c:655 rewind_dev fd=3 "Drive-2" (/dev/nst1)
btape: label.c:538 Start create_volume_label()
Volume Label:
Id : Bacula 1.0 immortal
VerNo : 11
VolName : TestVolume1
PrevVolName :
VolFile : 0
LabelType : PRE_LABEL
LabelSize : 0
PoolName : Default
MediaType : LTO-1
PoolType : Backup
HostName : shuswap.astro.yale.edu
Date label written: 07-Jan-2006 10:51
btape: label.c:527 Created Vol label rec: FI=PRE_LABEL len=176
btape: label.c:341 Wrote label of 176 bytes to "Drive-2" (/dev/nst1)
btape: label.c:344 Call write_block_to_dev()
btape: label.c:349 Wrote block to device
btape: dev.c:1521 weof_dev
Volume Label:
Id : Bacula 1.0 immortal
VerNo : 11
VolName : TestVolume1
PrevVolName :
VolFile : 1
LabelType : PRE_LABEL
LabelSize : 0
PoolName : Default
MediaType : LTO-1
PoolType : Backup
HostName : shuswap.astro.yale.edu
Date label written: 07-Jan-2006 10:51
Wrote Volume label for volume "TestVolume1".
btape: btape.c:1840 just before acquire_device
btape: btape.c:2649 Enter dir_get_volume_info
btape: btape.c:1847 Just after acquire_device_for_append
btape: label.c:636 session_label record=8c53ea8
btape: label.c:692 Write sesson_label record JobId=0 FI=SOS_LABEL
SessId=1 Strm=0 len=144 remainder=0
btape: label.c:696 Leave write_session_label Block=0 File=1
Wrote Start of Session label.
Begin writing Bacula records to tape ...
Wrote blk_block=5000, dev_blk_num=4999 VolBytes=322,495,472 rate=1528.4
KB/s
Wrote blk_block=10000, dev_blk_num=9999 VolBytes=645,055,424 rate=1532.2
KB/s
Wrote blk_block=15000, dev_blk_num=14999 VolBytes=967,615,368
rate=1531.0 KB/s
Flush block, write EOF
btape: block.c:420 return write_block_to_dev no data to write
btape: dev.c:1521 weof_dev
Wrote blk_block=20000, dev_blk_num=5000 VolBytes=1,290,175,312
rate=1530.5 KB/s
Wrote blk_block=25000, dev_blk_num=10000 VolBytes=1,612,735,264
rate=1531.6 KB/s
Wrote blk_block=30000, dev_blk_num=15000 VolBytes=1,935,295,208
rate=1531.1 KB/s
Flush block, write EOF
btape: block.c:420 return write_block_to_dev no data to write
btape: dev.c:1521 weof_dev
Wrote blk_block=35000, dev_blk_num=5000 VolBytes=2,257,855,160
rate=1527.6 KB/s
07-Jan 11:19 btape: btape Error: block.c:538 Write error at 3:9049 on
device "Drive-2" (/dev/nst1). ERR=Input/output error.
btape: block.c:550 === Write error. fd=3 size=64512 rtn=-1 dev_blk=9049
blk_blk=39049 errno=5: ERR=Input/output error
btape: dev.c:1521 weof_dev
07-Jan 11:19 btape: btape Error: Error writing final EOF to tape. This
Volume may not be readable.
dev.c:1553 ioctl MTWEOF error on "Drive-2" (/dev/nst1). ERR=Input/output
error.
btape: block.c:688 WEOF error.
btape: block.c:705 dir_update_volume_info terminate writing -- ERROR
btape: block.c:728 Leave terminate_writing_volume -- ERROR
btape: btape.c:2295 Last block at: 3:9048 this_dev_block_num=9049
btape: btape.c:2309 Block not written: FileIndex=76807 blk_block=39049
Size=64512
btape: block.c:81 Dump block Last block written 8c53f18: size=64512
BlkNum=39048
Hdrcksum=9c56653f cksum=9c56653f
btape: block.c:94 Rec: VId=1 VT=1136648713 FI=76803 Strm=contDATA
len=7924 p=8c7d0d8
btape: block.c:94 Rec: VId=1 VT=1136648713 FI=76804 Strm=DATA
len=32768 p=8c7efd8
btape: block.c:94 Rec: VId=1 VT=1136648713 FI=76805 Strm=DATA
len=32768 p=8c86fe4
btape: block.c:81 Dump block Block not written 8c43580: size=64512
BlkNum=39049
Hdrcksum=c8486c88 cksum=c8486c88
btape: block.c:94 Rec: VId=1 VT=1136648713 FI=76805 Strm=contDATA
len=9008 p=8c43608
btape: block.c:94 Rec: VId=1 VT=1136648713 FI=76806 Strm=DATA
len=32768 p=8c45944
btape: block.c:94 Rec: VId=1 VT=1136648713 FI=76807 Strm=DATA
len=32768 p=8c4d950
btape: btape.c:2329 End of tape 3:9049. VolumeCapacity=2,519,064,200.
Write rate = 1500.3 KB/s
Done writing ...
Wrote state file last_block_num1=9048 last_block_num2=0
Done filling tape at 3:9049. Now beginning re-read of tape ...
btape: btape.c:2077 Enter do_unfill
btape: dev.c:655 rewind_dev fd=3 "Drive-2" (/dev/nst1)
Reading the first 10000 records.
07-Jan 11:21 btape: Got EOF at file 1 on device "Drive-2" (/dev/nst1),
Volume "TestVolume1"
ERROR! device at 1:1 count=1
ERROR! device at 1:1 count=1
Reposition from 1:1 to 3:9048
btape: dev.c:1478 reposition_dev from 1:1 to 3:9048
btape: dev.c:1486 fsf 2
btape: dev.c:1176 fsf
btape: dev.c:1491 wanted_file=3 at_file=3
btape: dev.c:1503 fsr 9048
btape: dev.c:1373 fsr 9048
Reading block 9048.
07-Jan 11:23 btape: btape Error: block.c:940 Read error at file:blk
3:9048 on device "Drive-2" (/dev/nst1). ERR=Input/output error.
Error reading block: ERR=block.c:940 Read error at file:blk 3:9048 on
device "Drive-2" (/dev/nst1). ERR=Input/output error.
*
--
Chris Hunter
Systems Programmer
Department of Astronomy, Yale University
[EMAIL PROTECTED]
-------------------------------------------------------
This SF.net email is sponsored by: Splunk Inc. Do you grep through log files
for problems? Stop! Download the new AJAX search engine that makes
searching your log files as easy as surfing the web. DOWNLOAD SPLUNK!
http://ads.osdn.com/?ad_id=7637&alloc_id=16865&op=click
_______________________________________________
Bacula-users mailing list
Bacula-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/bacula-users
