btape fill i/o error on omnios

25 views
Skip to first unread message

Jürgen Bereuter

unread,
Sep 8, 2024, 3:43:29 PM9/8/24
to bareos-users
i cannot finish a btape fill test on omnios with an lto-5 drive.
i tried different block sizes, and added 
two eof = yes
bsf at eom = yes

in the config file. But i get an i/o error.

06-Sep. 00:33 btape JobId 0: 3302 Autochanger "loaded? drive 0", result: nothing loaded.
btape (100): stored/autochanger.cc:214-0 Doing changer load slot 2 "tapedrive-0" (/dev/rmt/2mbn)
06-Sep. 00:33 btape JobId 0: 3304 Issuing autochanger "load slot 2, drive 0" command.
btape (100): stored/dev.cc:886-0 close_dev "tapedrive-0" (/dev/rmt/2mbn)
btape (100): stored/dev.cc:889-0 device "tapedrive-0" (/dev/rmt/2mbn) already closed vol=
06-Sep. 00:34 btape JobId 0: 3305 Autochanger "load slot 2, drive 0", status is OK.
btape (100): stored/autochanger.cc:231-0 load slot 2, drive 0, status is OK.
btape (100): stored/autochanger.cc:256-0 load slot 2 status=0
btape (100): stored/autochanger.cc:263-0 After changer, status=0
btape (100): stored/dev.cc:505-0 open dev: type=17294584 archive_device_string="tapedrive-0" (/dev/rmt/2mbn) vol=TestVolume2 mode=OPEN_READ_ONLY
btape (100): stored/dev.cc:523-0 call OpenDevice mode=OPEN_READ_ONLY
btape (100): backends/generic_tape_device.cc:60-0 Open dev: device is tape
btape (100): backends/generic_tape_device.cc:69-0 Try open "tapedrive-0" (/dev/rmt/2mbn) mode=OPEN_READ_ONLY
btape (100): backends/generic_tape_device.cc:89-0 Rewind after open
btape (100): backends/generic_tape_device.cc:134-0 open dev: tape 3 opened
btape (100): stored/dev.cc:529-0 preserve=37767616272 fd=3
btape (100): stored/dev.cc:448-0 setting minblocksize to 64512, maxblocksize to label_block_size=64512, on device "tapedrive-0" (/dev/rmt/2mbn)
btape (100): stored/block.cc:137-0 created new block of blocksize 64512 (dev->max_block_size)
btape (100): stored/dev.cc:462-0 created new block of buf_len: 64512 on device "tapedrive-0" (/dev/rmt/2mbn)
btape (100): stored/dev.cc:492-0 Close fd for mode change.
btape (100): stored/dev.cc:505-0 open dev: type=17294584 archive_device_string="tapedrive-0" (/dev/rmt/2mbn) vol=TestVolume2 mode=OPEN_READ_WRITE
btape (100): stored/dev.cc:523-0 call OpenDevice mode=OPEN_READ_WRITE
btape (100): backends/generic_tape_device.cc:60-0 Open dev: device is tape
btape (100): backends/generic_tape_device.cc:69-0 Try open "tapedrive-0" (/dev/rmt/2mbn) mode=OPEN_READ_WRITE
btape (100): backends/generic_tape_device.cc:89-0 Rewind after open
btape (100): backends/generic_tape_device.cc:134-0 open dev: tape 3 opened
btape (100): stored/dev.cc:529-0 preserve=37767616212 fd=3
btape (100): stored/dev.cc:874-0 Clear volhdr vol=

Volume Label:
Id                : Bareos 2.0 immortal
VerNo             : 20
VolName           : TestVolume2
PrevVolName       :
VolFile           : 0
LabelType         : PRE_LABEL
LabelSize         : 0
PoolName          : Default
MediaType         : LTO
PoolType          : Backup
HostName          : omnios2
Date label written: 06-Sep.-2024 00:34

Volume Label:
Id                : Bareos 2.0 immortal
VerNo             : 20
VolName           : TestVolume2
PrevVolName       :
VolFile           : 1
LabelType         : PRE_LABEL
LabelSize         : 0
PoolName          : Default
MediaType         : LTO
PoolType          : Backup
HostName          : omnios2
Date label written: 06-Sep.-2024 00:34
btape (100): stored/label.cc:431-0 Call reserve_volume
btape (100): stored/dev.cc:371-0 Device "tapedrive-0" (/dev/rmt/2mbn) has dev->device->max_block_size of 1048576 and dev->max_block_size of 64512, dcr->VolMaxBlocksize is 0
btape (100): stored/dev.cc:380-0 setting dev->max_block_size to dev->device_resource->max_block_size=1048576 on device "tapedrive-0" (/dev/rmt/2mbn) because dcr->VolMaxBlocksize is 0
btape (100): stored/dev.cc:421-0 set minblocksize to 0, maxblocksize to 1048576 on device "tapedrive-0" (/dev/rmt/2mbn)
btape (100): stored/dev.cc:429-0 created new block of buf_len: 1048576 on device "tapedrive-0" (/dev/rmt/2mbn)
btape (100): stored/block.cc:137-0 created new block of blocksize 1048576 (dev->max_block_size)
btape (100): stored/dev.cc:433-0 created new block of buf_len: 1048576 on device "tapedrive-0" (/dev/rmt/2mbn), freeing block
Wrote Volume label for volume "TestVolume2".
btape (20): stored/btape.cc:2936-0 Enter DirFindNextAppendableVolume. stop=1
btape (100): stored/askdir.cc:670-0 Fake DirGetVolumeInfo
btape (100): stored/autochanger.cc:139-0 autoload: slot=2 InChgr=1 Vol=TestVolume2
btape (100): stored/autochanger.cc:263-0 After changer, status=0
btape (100): stored/dev.cc:448-0 setting minblocksize to 64512, maxblocksize to label_block_size=64512, on device "tapedrive-0" (/dev/rmt/2mbn)
btape (100): stored/block.cc:137-0 created new block of blocksize 64512 (dev->max_block_size)
btape (100): stored/dev.cc:462-0 created new block of buf_len: 64512 on device "tapedrive-0" (/dev/rmt/2mbn)
btape (100): stored/label.cc:87-0 Enter ReadVolumeLabel res=0 device="tapedrive-0" (/dev/rmt/2mbn) vol=TestVolume2 dev_Vol=TestVolume2 max_blocksize=64512
btape (100): stored/label.cc:244-0 Call reserve_volume=TestVolume2
btape (100): stored/dev.cc:371-0 Device "tapedrive-0" (/dev/rmt/2mbn) has dev->device->max_block_size of 1048576 and dev->max_block_size of 64512, dcr->VolMaxBlocksize is 0
btape (100): stored/dev.cc:380-0 setting dev->max_block_size to dev->device_resource->max_block_size=1048576 on device "tapedrive-0" (/dev/rmt/2mbn) because dcr->VolMaxBlocksize is 0
btape (100): stored/dev.cc:421-0 set minblocksize to 0, maxblocksize to 1048576 on device "tapedrive-0" (/dev/rmt/2mbn)
btape (100): stored/dev.cc:429-0 created new block of buf_len: 1048576 on device "tapedrive-0" (/dev/rmt/2mbn)
btape (100): stored/block.cc:137-0 created new block of blocksize 1048576 (dev->max_block_size)
btape (100): stored/dev.cc:433-0 created new block of buf_len: 1048576 on device "tapedrive-0" (/dev/rmt/2mbn), freeing block
btape (100): stored/askdir.cc:670-0 Fake DirGetVolumeInfo
btape (100): stored/askdir.cc:670-0 Fake DirGetVolumeInfo
btape (100): stored/dev.cc:371-0 Device "tapedrive-0" (/dev/rmt/2mbn) has dev->device->max_block_size of 1048576 and dev->max_block_size of 1048576, dcr->VolMaxBlocksize is 0
btape (100): stored/dev.cc:380-0 setting dev->max_block_size to dev->device_resource->max_block_size=1048576 on device "tapedrive-0" (/dev/rmt/2mbn) because dcr->VolMaxBlocksize is 0
btape (100): stored/dev.cc:421-0 set minblocksize to 0, maxblocksize to 1048576 on device "tapedrive-0" (/dev/rmt/2mbn)
btape (100): stored/dev.cc:448-0 setting minblocksize to 64512, maxblocksize to label_block_size=64512, on device "tapedrive-0" (/dev/rmt/2mbn)
btape (100): stored/block.cc:137-0 created new block of blocksize 64512 (dev->max_block_size)
btape (100): stored/dev.cc:462-0 created new block of buf_len: 64512 on device "tapedrive-0" (/dev/rmt/2mbn)
06-Sep. 00:34 btape JobId 0: Wrote label to prelabeled Volume "TestVolume2" on device "tapedrive-0" (/dev/rmt/2mbn)
btape (100): stored/dev.cc:371-0 Device "tapedrive-0" (/dev/rmt/2mbn) has dev->device->max_block_size of 1048576 and dev->max_block_size of 64512, dcr->VolMaxBlocksize is 0
btape (100): stored/dev.cc:380-0 setting dev->max_block_size to dev->device_resource->max_block_size=1048576 on device "tapedrive-0" (/dev/rmt/2mbn) because dcr->VolMaxBlocksize is 0
btape (100): stored/dev.cc:421-0 set minblocksize to 0, maxblocksize to 1048576 on device "tapedrive-0" (/dev/rmt/2mbn)
btape (100): stored/dev.cc:429-0 created new block of buf_len: 1048576 on device "tapedrive-0" (/dev/rmt/2mbn)
btape (100): stored/block.cc:137-0 created new block of blocksize 1048576 (dev->max_block_size)
btape (100): stored/dev.cc:433-0 created new block of buf_len: 1048576 on device "tapedrive-0" (/dev/rmt/2mbn), freeing block
btape (40): stored/device.cc:130-0 MustUnload=0 dev="tapedrive-0" (/dev/rmt/2mbn)
06-Sep. 00:34 btape JobId 0: New volume "TestVolume2" mounted on device "tapedrive-0" (/dev/rmt/2mbn) at 06-Sep.-2024 00:34.
btape (100): stored/block.cc:578-0 return WriteBlockToDev no data to write
btape (100): stored/device.cc:158-0 Notify vol change. Volume=TestVolume2
btape: stored/btape.cc:2250-0 Wrote 1000 blocks on second tape. Done.
Done writing 0 records ...
btape (100): stored/btape.cc:2274-0 Write_end_session_label()
Wrote End of Session label.
btape: stored/btape.cc:2317-0 Wrote state file last_block_num1=6006 last_block_num2=1001
btape: stored/btape.cc:2338-0

00:34:24 Done filling tapes at 0:1003. Now beginning re-read of first tape ...
btape: stored/btape.cc:2420-0 Enter do_unfill
btape (100): stored/autochanger.cc:139-0 autoload: slot=1 InChgr=1 Vol=TestVolume1
06-Sep. 00:34 btape JobId 0: 3307 Issuing autochanger "unload slot 2, drive 0" command.
btape (100): stored/dev.cc:886-0 close_dev "tapedrive-0" (/dev/rmt/2mbn)
btape (100): stored/dev.cc:979-0 Enter unmount
btape (100): stored/dev.cc:874-0 Clear volhdr vol=TestVolume2
btape (100): stored/autochanger.cc:462-0 Run program=/usr/lib/bareos/scripts/mtx-changer /dev/scsi/changer/c0t46554A4954535520455445524E5553204C542053322020204C54444536353435313936335F4C4C30d0 unload 2 /dev/rmt/2mbn 0
btape (100): stored/autochanger.cc:214-0 Doing changer load slot 1 "tapedrive-0" (/dev/rmt/2mbn)
06-Sep. 00:35 btape JobId 0: 3304 Issuing autochanger "load slot 1, drive 0" command.
btape (100): stored/dev.cc:886-0 close_dev "tapedrive-0" (/dev/rmt/2mbn)
btape (100): stored/dev.cc:889-0 device "tapedrive-0" (/dev/rmt/2mbn) already closed vol=
06-Sep. 00:36 btape JobId 0: 3305 Autochanger "load slot 1, drive 0", status is OK.
btape (100): stored/autochanger.cc:231-0 load slot 1, drive 0, status is OK.
btape (100): stored/autochanger.cc:256-0 load slot 1 status=0
btape (100): stored/autochanger.cc:263-0 After changer, status=0
btape (100): stored/dev.cc:886-0 close_dev "tapedrive-0" (/dev/rmt/2mbn)
btape (100): stored/dev.cc:889-0 device "tapedrive-0" (/dev/rmt/2mbn) already closed vol=
btape (100): stored/acquire.cc:106-0 dcr=1079fd0 dev=107e400
btape (100): stored/acquire.cc:107-0 MediaType dcr= dev=LTO
btape (100): stored/acquire.cc:137-0 Want Vol=TestVolume1 Slot=1
btape (100): stored/acquire.cc:149-0 MediaType dcr= dev=LTO
btape (100): stored/acquire.cc:220-0 MediaType dcr= dev=LTO
btape (100): stored/acquire.cc:238-0 DirGetVolumeInfo vol=TestVolume1
btape (100): stored/askdir.cc:670-0 Fake DirGetVolumeInfo
btape (100): stored/mount.cc:621-0 No swap_dev set
btape (100): stored/mount.cc:575-0 Must load "tapedrive-0" (/dev/rmt/2mbn)
btape (100): stored/autochanger.cc:139-0 autoload: slot=1 InChgr=1 Vol=TestVolume1
btape (100): stored/autochanger.cc:263-0 After changer, status=0
btape (100): stored/acquire.cc:272-0 stored: open vol=TestVolume1
btape (100): stored/dev.cc:505-0 open dev: type=17294584 archive_device_string="tapedrive-0" (/dev/rmt/2mbn) vol=TestVolume1 mode=OPEN_READ_ONLY
btape (100): stored/dev.cc:523-0 call OpenDevice mode=OPEN_READ_ONLY
btape (100): backends/generic_tape_device.cc:60-0 Open dev: device is tape
btape (100): backends/generic_tape_device.cc:69-0 Try open "tapedrive-0" (/dev/rmt/2mbn) mode=OPEN_READ_ONLY
btape (100): backends/generic_tape_device.cc:89-0 Rewind after open
btape (100): backends/generic_tape_device.cc:134-0 open dev: tape 3 opened
btape (100): stored/dev.cc:529-0 preserve=37767616332 fd=3
btape (100): stored/acquire.cc:281-0 opened dev "tapedrive-0" (/dev/rmt/2mbn) OK
btape (100): stored/acquire.cc:291-0 calling read-vol-label
btape (100): stored/dev.cc:448-0 setting minblocksize to 64512, maxblocksize to label_block_size=64512, on device "tapedrive-0" (/dev/rmt/2mbn)
btape (100): stored/block.cc:137-0 created new block of blocksize 64512 (dev->max_block_size)
btape (100): stored/dev.cc:462-0 created new block of buf_len: 64512 on device "tapedrive-0" (/dev/rmt/2mbn)
btape (100): stored/label.cc:87-0 Enter ReadVolumeLabel res=0 device="tapedrive-0" (/dev/rmt/2mbn) vol=TestVolume1 dev_Vol=*NULL* max_blocksize=64512
btape (100): stored/label.cc:244-0 Call reserve_volume=TestVolume1
btape (100): stored/dev.cc:371-0 Device "tapedrive-0" (/dev/rmt/2mbn) has dev->device->max_block_size of 1048576 and dev->max_block_size of 64512, dcr->VolMaxBlocksize is 0
btape (100): stored/dev.cc:380-0 setting dev->max_block_size to dev->device_resource->max_block_size=1048576 on device "tapedrive-0" (/dev/rmt/2mbn) because dcr->VolMaxBlocksize is 0
btape (100): stored/dev.cc:421-0 set minblocksize to 0, maxblocksize to 1048576 on device "tapedrive-0" (/dev/rmt/2mbn)
btape (100): stored/dev.cc:429-0 created new block of buf_len: 1048576 on device "tapedrive-0" (/dev/rmt/2mbn)
btape (100): stored/block.cc:137-0 created new block of blocksize 1048576 (dev->max_block_size)
btape (100): stored/dev.cc:433-0 created new block of buf_len: 1048576 on device "tapedrive-0" (/dev/rmt/2mbn), freeing block
btape (100): stored/acquire.cc:297-0 Got correct volume.
06-Sep. 00:36 btape JobId 0: Ready to read from volume "TestVolume1" on device "tapedrive-0" (/dev/rmt/2mbn).
btape (100): stored/acquire.cc:393-0 dcr=1079fd0 dev=107e400
btape (100): stored/acquire.cc:394-0 MediaType dcr= dev=LTO
Rewinding.
Reading the first 10'000 records from 0:0.
10'000 records read now at 1:313
Reposition from 1:313 to 152:6006
btape (100): backends/generic_tape_device.cc:1265-0 Reposition from 1:313 to 152:6006
btape (100): backends/generic_tape_device.cc:1273-0 fsf 151
btape (100): backends/generic_tape_device.cc:403-0 fsf
btape (100): backends/generic_tape_device.cc:1278-0 wanted_file=152 at_file=152
btape (100): backends/generic_tape_device.cc:1292-0 fsr 6006
btape (100): backends/generic_tape_device.cc:620-0 fsr 6006
btape (100): backends/generic_tape_device.cc:633-0 FSF fail: ERR=I/O error
btape (100): backends/generic_tape_device.cc:591-0 DevGetOsPos
btape (100): backends/generic_tape_device.cc:635-0 Adjust from 152:0 to 152:6006
Reposition error. ERR=backends/generic_tape_device.cc:646 ioctl MTFSR 6006 error on "tapedrive-0" (/dev/rmt/2mbn). ERR=I/O error.

btape: stored/btape.cc:2347-0 do_unfill failed.
********


Reply all
Reply to author
Forward
0 new messages