View Issue Details

IDProjectCategoryView StatusLast Update
0001296bareos-core[All Projects] directorpublic2021-01-02 03:47
ReporterprogseregaAssigned To 
PrioritynormalSeveritymajorReproducibilityalways
Status newResolutionopen 
PlatformLinuxOSDebianOS Version10
Product Version20.0.0 
Fixed in Version 
Summary0001296: can not mount tape volume
Description1. I setup HPE MSL 3040 tape library.
2. btape success all test
3. but jobs freeze with status:

02-янв 12:10 bareos-dir JobId 29: shell command: run BeforeJob "/usr/lib/bareos/scripts/make_catalog_backup.pl MyCatalog"
02-янв 12:10 bareos-dir JobId 29: Start Backup JobId 29, Job=BackupCatalog.2021-01-02_12.10.54_20
02-янв 12:10 bareos-dir JobId 29: Connected Storage daemon at 10.75.232.210:9103, encryption: TLS_CHACHA20_POLY1305_SHA256 TLSv1.3
02-янв 12:10 bareos-dir JobId 29: Using Device "Msl-Drive-1" to write.
02-янв 12:10 bareos-dir JobId 29: Connected Client: bareos-fd at localhost:9102, encryption: TLS_CHACHA20_POLY1305_SHA256 TLSv1.3
02-янв 12:10 bareos-dir JobId 29: Handshake: Immediate TLS
02-янв 12:10 bareos-dir.rs.int-fd JobId 29: Connected Storage daemon at 10.75.232.210:9103, encryption: TLS_CHACHA20_POLY1305_SHA256 TLSv1.3
02-янв 12:10 bareos-dir.rs.int-fd JobId 29: Extended attribute support is enabled
02-янв 12:10 bareos-dir.rs.int-fd JobId 29: ACL support is enabled
02-янв 12:10 MslStorage JobId 29: 3301 Issuing autochanger "loaded? drive 0" command.
02-янв 12:10 MslStorage JobId 29: 3302 Autochanger "loaded? drive 0", result: nothing loaded.
02-янв 12:10 MslStorage JobId 29: 3301 Issuing autochanger "loaded? drive 0" command.
02-янв 12:10 MslStorage JobId 29: 3302 Autochanger "loaded? drive 0", result: nothing loaded.
02-янв 12:10 MslStorage JobId 29: 3301 Issuing autochanger "loaded? drive 1" command.
02-янв 12:10 MslStorage JobId 29: 3302 Autochanger "loaded? drive 1", result: nothing loaded.
02-янв 12:10 MslStorage JobId 29: 3304 Issuing autochanger "load slot 2, drive 0" command.
02-янв 12:11 MslStorage JobId 29: 3305 Autochanger "load slot 2, drive 0", status is OK.
02-янв 12:11 MslStorage JobId 29: Please mount append Volume "AJM284L8" or label a new one for:
    Job: BackupCatalog.2021-01-02_12.10.54_20
    Storage: "Msl-Drive-1" (/dev/tape/by-id/scsi-351402ec01503feb7)
    Pool: mslPool
    Media type: LTO-8
*

bareos-sd debugs logs:
02-янв-2021 12:11:32.833471 MslStorage (100): stored/autochanger.cc:264-29 load slot 2, drive 0, status is OK.
02-янв-2021 12:11:32.833500 MslStorage (100): stored/autochanger.cc:290-29 load slot 2 status=0
02-янв-2021 12:11:32.833515 MslStorage (200): stored/autochanger.cc:441-29 Unlocking changer msl3040ch1
02-янв-2021 12:11:32.833524 MslStorage (100): stored/autochanger.cc:297-29 After changer, status=0
02-янв-2021 12:11:32.833531 MslStorage (150): stored/mount.cc:205-29 autoLoadDev returns 1
02-янв-2021 12:11:32.833537 MslStorage (150): stored/mount.cc:240-29 want vol=AJM284L8 devvol= dev="Msl-Drive-1" (/dev/tape/by-id/scsi-351402ec01503feb7)
02-янв-2021 12:11:32.833545 MslStorage (100): stored/dev.cc:619-29 open dev: type=2 dev_name="Msl-Drive-1" (/dev/tape/by-id/scsi-351402ec01503feb7) vol=AJM284L8 mode=OPEN_READ_WRITE
02-янв-2021 12:11:32.833552 MslStorage (100): stored/dev.cc:638-29 call OpenDevice mode=OPEN_READ_WRITE
02-янв-2021 12:11:32.833560 MslStorage (190): stored/dev.cc:1071-29 Enter mount
02-янв-2021 12:11:32.833568 MslStorage (100): backends/generic_tape_device.cc:58-29 Open dev: device is tape
02-янв-2021 12:11:32.833576 MslStorage (100): backends/generic_tape_device.cc:67-29 Try open "Msl-Drive-1" (/dev/tape/by-id/scsi-351402ec01503feb7) mode=OPEN_READ_WRITE
02-янв-2021 12:11:32.835884 MslStorage (100): backends/generic_tape_device.cc:93-29 Rewind after open
02-янв-2021 12:11:32.856825 MslStorage (100): backends/generic_tape_device.cc:997-29 In SetOsDeviceParameters
02-янв-2021 12:11:32.856885 MslStorage (100): backends/generic_tape_device.cc:146-29 open dev: tape 6 opened
02-янв-2021 12:11:32.856909 MslStorage (100): stored/dev.cc:646-29 preserve=14065654506 fd=6
02-янв-2021 12:11:32.856947 MslStorage (100): stored/dev.cc:563-29 setting minblocksize to 64512, maxblocksize to label_block_size=64512, on device "Msl-Drive-1" (/dev/tape/by-id/scsi-351402ec01503feb7)
02-янв-2021 12:11:32.856972 MslStorage (100): stored/label.cc:93-29 Enter ReadVolumeLabel res=1 device="Msl-Drive-1" (/dev/tape/by-id/scsi-351402ec01503feb7) vol=AJM284L8 dev_Vol=*NULL* max_blocksize=64512
02-янв-2021 12:11:32.862304 MslStorage (130): stored/label.cc:154-29 Big if statement in ReadVolumeLabel
02-янв-2021 12:11:34.042983 MslStorage (130): stored/label.cc:168-29 Could not UnSerialize Volume label: ERR=stored/label.cc:745 Expecting Volume Label, got FI=0 Stream=0 len=64412

02-янв-2021 12:11:34.043039 MslStorage (100): stored/label.cc:187-29 No volume label - bailing out
02-янв-2021 12:11:34.054159 MslStorage (150): stored/label.cc:303-29 return 3
02-янв-2021 12:11:34.054212 MslStorage (150): stored/mount.cc:462-29 Want dirVol=AJM284L8 dirStat=Append
02-янв-2021 12:11:34.054235 MslStorage (200): stored/mount.cc:567-29 VOL_NO_MEDIA or default.
02-янв-2021 12:11:34.054252 MslStorage (50): stored/mount.cc:290-29 SetUnload
02-янв-2021 12:11:34.054270 MslStorage (150): stored/mount.cc:292-29 goto mount_next_vol
02-янв-2021 12:11:34.054293 MslStorage (150): stored/mount.cc:98-29 mount_next_vol retry=1
02-янв-2021 12:11:34.054320 MslStorage (100): stored/mount.cc:674-29 No swap_dev set
02-янв-2021 12:11:34.054420 MslStorage (50): stored/askdir.cc:181-29 >dird CatReq Job=BackupCatalog.2021-01-02_12.10.54_20 GetVolInfo VolName=AJM284L8 write=1
02-янв-2021 12:11:34.055289 MslStorage (50): stored/askdir.cc:108-29 <dird 1000 OK VolName=AJM284L8 VolJobs=2 VolFiles=2 VolBlocks=4 VolBytes=322560 VolMounts=1 VolErrors=0 VolWrites=5 MaxVolBytes=32985348833280 VolCapacityBytes=0 VolStatus=Append Slot=2 MaxVolJobs=0 MaxVolFiles=0 InChanger=1 VolReadTime=0 VolWriteTime=62117 EndFile=1 EndBlock=1 LabelType=0 MediaId=2 EncryptionKey= MinBlocksize=0 MaxBlocksize=0
02-янв-2021 12:11:34.055312 MslStorage (50): stored/askdir.cc:143-29 DoGetVolumeInfo return true slot=2 Volume=AJM284L8, VolminBlocksize=0 VolMaxBlocksize=0
02-янв-2021 12:11:34.055319 MslStorage (50): stored/askdir.cc:146-29 setting dcr->VolMinBlocksize(0) to vol.VolMinBlocksize(0)
02-янв-2021 12:11:34.055325 MslStorage (50): stored/askdir.cc:149-29 setting dcr->VolMaxBlocksize(0) to vol.VolMaxBlocksize(0)
02-янв-2021 12:11:34.055332 MslStorage (150): stored/mount.cc:135-29 After find_next_append. Vol=AJM284L8 Slot=2
02-янв-2021 12:11:34.055338 MslStorage (100): stored/autochanger.cc:154-29 autoload: slot=2 InChgr=1 Vol=AJM284L8
02-янв-2021 12:11:34.055345 MslStorage (100): stored/autochanger.cc:297-29 After changer, status=0
02-янв-2021 12:11:34.055352 MslStorage (150): stored/mount.cc:205-29 autoLoadDev returns 1
02-янв-2021 12:11:34.055358 MslStorage (150): stored/mount.cc:240-29 want vol=AJM284L8 devvol= dev="Msl-Drive-1" (/dev/tape/by-id/scsi-351402ec01503feb7)
02-янв-2021 12:11:34.055365 MslStorage (100): stored/dev.cc:563-29 setting minblocksize to 64512, maxblocksize to label_block_size=64512, on device "Msl-Drive-1" (/dev/tape/by-id/scsi-351402ec01503feb7)
02-янв-2021 12:11:34.055372 MslStorage (100): stored/label.cc:93-29 Enter ReadVolumeLabel res=1 device="Msl-Drive-1" (/dev/tape/by-id/scsi-351402ec01503feb7) vol=AJM284L8 dev_Vol=*NULL* max_blocksize=64512
02-янв-2021 12:11:34.058864 MslStorage (130): stored/label.cc:154-29 Big if statement in ReadVolumeLabel
02-янв-2021 12:11:34.064178 MslStorage (130): stored/label.cc:168-29 Could not UnSerialize Volume label: ERR=stored/label.cc:745 Expecting Volume Label, got FI=0 Stream=0 len=64412

02-янв-2021 12:11:34.064218 MslStorage (100): stored/label.cc:187-29 No volume label - bailing out
02-янв-2021 12:11:34.076219 MslStorage (150): stored/label.cc:303-29 return 3
02-янв-2021 12:11:34.076262 MslStorage (150): stored/mount.cc:462-29 Want dirVol=AJM284L8 dirStat=Append
02-янв-2021 12:11:34.076281 MslStorage (200): stored/mount.cc:567-29 VOL_NO_MEDIA or default.
02-янв-2021 12:11:34.076298 MslStorage (50): stored/mount.cc:290-29 SetUnload
02-янв-2021 12:11:34.076314 MslStorage (150): stored/mount.cc:292-29 goto mount_next_vol
02-янв-2021 12:11:34.076331 MslStorage (150): stored/mount.cc:98-29 mount_next_vol retry=2
02-янв-2021 12:11:34.076348 MslStorage (100): stored/mount.cc:674-29 No swap_dev set
02-янв-2021 12:11:34.076468 MslStorage (50): stored/askdir.cc:181-29 >dird CatReq Job=BackupCatalog.2021-01-02_12.10.54_20 GetVolInfo VolName=AJM284L8 write=1
02-янв-2021 12:11:34.077064 MslStorage (50): stored/askdir.cc:108-29 <dird 1000 OK VolName=AJM284L8 VolJobs=2 VolFiles=2 VolBlocks=4 VolBytes=322560 VolMounts=1 VolErrors=0 VolWrites=5 MaxVolBytes=32985348833280 VolCapacityBytes=0 VolStatus=Append Slot=2 MaxVolJobs=0 MaxVolFiles=0 InChanger=1 VolReadTime=0 VolWriteTime=62117 EndFile=1 EndBlock=1 LabelType=0 MediaId=2 EncryptionKey= MinBlocksize=0 MaxBlocksize=0
02-янв-2021 12:11:34.077185 MslStorage (50): stored/askdir.cc:143-29 DoGetVolumeInfo return true slot=2 Volume=AJM284L8, VolminBlocksize=0 VolMaxBlocksize=0
02-янв-2021 12:11:34.077295 MslStorage (50): stored/askdir.cc:146-29 setting dcr->VolMinBlocksize(0) to vol.VolMinBlocksize(0)
02-янв-2021 12:11:34.077380 MslStorage (50): stored/askdir.cc:149-29 setting dcr->VolMaxBlocksize(0) to vol.VolMaxBlocksize(0)
02-янв-2021 12:11:34.077457 MslStorage (150): stored/mount.cc:135-29 After find_next_append. Vol=AJM284L8 Slot=2
02-янв-2021 12:11:34.077533 MslStorage (100): stored/autochanger.cc:154-29 autoload: slot=2 InChgr=1 Vol=AJM284L8
02-янв-2021 12:11:34.077609 MslStorage (100): stored/autochanger.cc:297-29 After changer, status=0
02-янв-2021 12:11:34.077684 MslStorage (150): stored/mount.cc:205-29 autoLoadDev returns 1
02-янв-2021 12:11:34.077760 MslStorage (150): stored/mount.cc:240-29 want vol=AJM284L8 devvol= dev="Msl-Drive-1" (/dev/tape/by-id/scsi-351402ec01503feb7)
02-янв-2021 12:11:34.077837 MslStorage (100): stored/dev.cc:563-29 setting minblocksize to 64512, maxblocksize to label_block_size=64512, on device "Msl-Drive-1" (/dev/tape/by-id/scsi-351402ec01503feb7)
02-янв-2021 12:11:34.077914 MslStorage (100): stored/label.cc:93-29 Enter ReadVolumeLabel res=1 device="Msl-Drive-1" (/dev/tape/by-id/scsi-351402ec01503feb7) vol=AJM284L8 dev_Vol=*NULL* max_blocksize=64512
02-янв-2021 12:11:34.080915 MslStorage (130): stored/label.cc:154-29 Big if statement in ReadVolumeLabel
02-янв-2021 12:11:34.086963 MslStorage (130): stored/label.cc:168-29 Could not UnSerialize Volume label: ERR=stored/label.cc:745 Expecting Volume Label, got FI=0 Stream=0 len=64412
02-янв-2021 12:11:34.087289 MslStorage (100): stored/label.cc:187-29 No volume label - bailing out
02-янв-2021 12:11:34.102340 MslStorage (150): stored/label.cc:303-29 return 3
02-янв-2021 12:11:34.102521 MslStorage (150): stored/mount.cc:462-29 Want dirVol=AJM284L8 dirStat=Append
02-янв-2021 12:11:34.102641 MslStorage (200): stored/mount.cc:567-29 VOL_NO_MEDIA or default.
02-янв-2021 12:11:34.102775 MslStorage (50): stored/mount.cc:290-29 SetUnload
02-янв-2021 12:11:34.102877 MslStorage (150): stored/mount.cc:292-29 goto mount_next_vol
02-янв-2021 12:11:34.102983 MslStorage (150): stored/mount.cc:98-29 mount_next_vol retry=3
02-янв-2021 12:11:34.103076 MslStorage (100): stored/mount.cc:674-29 No swap_dev set
02-янв-2021 12:11:34.103177 MslStorage (50): stored/askdir.cc:181-29 >dird CatReq Job=BackupCatalog.2021-01-02_12.10.54_20 GetVolInfo VolName=AJM284L8 write=1
02-янв-2021 12:11:34.103639 MslStorage (50): stored/askdir.cc:108-29 <dird 1000 OK VolName=AJM284L8 VolJobs=2 VolFiles=2 VolBlocks=4 VolBytes=322560 VolMounts=1 VolErrors=0 VolWrites=5 MaxVolBytes=32985348833280 VolCapacityBytes=0 VolStatus=Append Slot=2 MaxVolJobs=0 MaxVolFiles=0 InChanger=1 VolReadTime=0 VolWriteTime=62117 EndFile=1 EndBlock=1 LabelType=0 MediaId=2 EncryptionKey= MinBlocksize=0 MaxBlocksize=0
02-янв-2021 12:11:34.103695 MslStorage (50): stored/askdir.cc:143-29 DoGetVolumeInfo return true slot=2 Volume=AJM284L8, VolminBlocksize=0 VolMaxBlocksize=0
02-янв-2021 12:11:34.103708 MslStorage (50): stored/askdir.cc:146-29 setting dcr->VolMinBlocksize(0) to vol.VolMinBlocksize(0)
02-янв-2021 12:11:34.103738 MslStorage (50): stored/askdir.cc:149-29 setting dcr->VolMaxBlocksize(0) to vol.VolMaxBlocksize(0)
02-янв-2021 12:11:34.103767 MslStorage (150): stored/mount.cc:135-29 After find_next_append. Vol=AJM284L8 Slot=2
02-янв-2021 12:11:34.103781 MslStorage (100): stored/autochanger.cc:154-29 autoload: slot=2 InChgr=1 Vol=AJM284L8
02-янв-2021 12:11:34.103811 MslStorage (100): stored/autochanger.cc:297-29 After changer, status=0
02-янв-2021 12:11:34.103824 MslStorage (150): stored/mount.cc:205-29 autoLoadDev returns 1
02-янв-2021 12:11:34.103853 MslStorage (150): stored/mount.cc:240-29 want vol=AJM284L8 devvol= dev="Msl-Drive-1" (/dev/tape/by-id/scsi-351402ec01503feb7)
02-янв-2021 12:11:34.103883 MslStorage (100): stored/dev.cc:563-29 setting minblocksize to 64512, maxblocksize to label_block_size=64512, on device "Msl-Drive-1" (/dev/tape/by-id/scsi-351402ec01503feb7)
02-янв-2021 12:11:34.103897 MslStorage (100): stored/label.cc:93-29 Enter ReadVolumeLabel res=1 device="Msl-Drive-1" (/dev/tape/by-id/scsi-351402ec01503feb7) vol=AJM284L8 dev_Vol=*NULL* max_blocksize=64512
02-янв-2021 12:11:34.106989 MslStorage (130): stored/label.cc:154-29 Big if statement in ReadVolumeLabel
02-янв-2021 12:11:34.113402 MslStorage (130): stored/label.cc:168-29 Could not UnSerialize Volume label: ERR=stored/label.cc:745 Expecting Volume Label, got FI=0 Stream=0 len=64412

Volume AJM284L8 was success inserted in reader device by autochanger, but bareos dir not mount it automaticaly...

Volumes I success was added by label barcode:

*list volumes
Automatically selected Catalog: MyCatalog
Using Catalog "MyCatalog"
Pool: Scratch
No results to list.
Pool: mslManualPool
No results to list.
Pool: mslPool
+---------+------------+-----------+---------+----------+----------+--------------+---------+------+-----------+-----------+---------------------+-------------------+
| mediaid | volumename | volstatus | enabled | volbytes | volfiles | volretention | recycle | slot | inchanger | mediatype | lastwritten | storage |
+---------+------------+-----------+---------+----------+----------+--------------+---------+------+-----------+-----------+---------------------+-------------------+
| 1 | CLNU00L1 | Cleaning | 1 | 0 | 0 | 157,680,000 | 1 | 7 | 1 | | | MslStorageRobot01 |
| 2 | AJM284L8 | Append | 1 | 322,560 | 2 | 157,680,000 | 1 | 2 | 1 | LTO-8 | 2020-12-31 10:05:49 | MslStorageRobot01 |
| 3 | AJM281L8 | Append | 1 | 64,512 | 0 | 157,680,000 | 1 | 3 | 1 | LTO-8 | | MslStorageRobot01 |
| 4 | AJM282L8 | Append | 1 | 64,512 | 0 | 157,680,000 | 1 | 4 | 1 | LTO-8 | | MslStorageRobot01 |
| 5 | AJM280L8 | Append | 1 | 64,512 | 0 | 157,680,000 | 1 | 6 | 1 | LTO-8 | | MslStorageRobot01 |
| 6 | AJM287L8 | Append | 1 | 64,512 | 0 | 157,680,000 | 1 | 5 | 1 | LTO-8 | | MslStorageRobot01 |
| 7 | AJM283L8 | Append | 1 | 64,512 | 0 | 157,680,000 | 1 | 1 | 1 | LTO-8 | | MslStorageRobot01 |
+---------+------------+-----------+---------+----------+----------+--------------+---------+------+-----------+-----------+---------------------+-------------------+
Pool: Incremental
No results to list.
Pool: Full
No results to list.
Pool: Differential
No results to list.
You have messages.
*
Tagsstorage, volume
bareos-master: impact
bareos-master: action
bareos-19.2: impact
bareos-19.2: action
bareos-18.2: impact
bareos-18.2: action
bareos-17.2: impact
bareos-17.2: action
bareos-16.2: impact
bareos-16.2: action
bareos-15.2: impact
bareos-15.2: action
bareos-14.2: impact
bareos-14.2: action
bareos-13.2: impact
bareos-13.2: action
bareos-12.4: impact
bareos-12.4: action

Activities

progserega

progserega

2021-01-02 03:36

reporter  

dir-job-def.conf (348 bytes)
dir-job.conf (828 bytes)
dir-pool.conf (646 bytes)
dir-storage.conf (833 bytes)
sd-storage.conf (454 bytes)
sd-devices.conf (1,797 bytes)
progserega

progserega

2021-01-02 03:47

reporter   ~0004075

May be my configuration is wrong?

Issue History

Date Modified Username Field Change
2021-01-02 03:36 progserega New Issue
2021-01-02 03:36 progserega Tag Attached: storage
2021-01-02 03:36 progserega Tag Attached: volume
2021-01-02 03:36 progserega File Added: dir-job-def.conf
2021-01-02 03:36 progserega File Added: dir-job.conf
2021-01-02 03:36 progserega File Added: dir-pool.conf
2021-01-02 03:36 progserega File Added: dir-storage.conf
2021-01-02 03:36 progserega File Added: sd-storage.conf
2021-01-02 03:36 progserega File Added: sd-devices.conf
2021-01-02 03:47 progserega Note Added: 0004075