mhVTL on OpenSuSE 11.4 - mtx load fails

classic Classic list List threaded Threaded
4 messages Options
Reply | Threaded
Open this post in threaded view
|

mhVTL on OpenSuSE 11.4 - mtx load fails

danci1973
I have successfully used mhVTL to emulate a single drive for testing Bacula settings / strategy.

Now I need to do the same for a 8-slot, 1-drive tape changer (HP ThinStor AutoLdr / HP Ultrium 1-SCSI).

This is my 'device.conf':

Library: 1 CHANNEL: 00 TARGET: 00 LUN: 00
 Vendor identification: HP
 Product identification: ThinStor AutoLdr
 Unit serial number: 000000
 NAA: 10:22:33:44:ab:00:00:00
 Home directory: /var/lib/mhvtl/1
 Backoff: 400
 fifo: /var/tmp/mhvtl

Drive: 2 CHANNEL: 00 TARGET: 01 LUN: 00
 Vendor identification: HP
 Product identification: Ultrium 1-SCSI
 Unit serial number: 000001
 NAA: 10:22:33:44:ab:00:01:00
 Compression: factor 1 enabled 1
 Compression type: lzo
 Backoff: 400
 fifo: /var/tmp/mhvtl

And this is my 'library_contents.1' (stripped of comments):

Drive 1:

Picker 1:

MAP 1:

Slot 1: E01002L1
Slot 2: E01003L1
Slot 3: E01004L1
Slot 4: E01005L1
Slot 5: E01006L1
Slot 6: E01007L1
Slot 7: E01008L1
Slot 8: E01008L1

On the first glance, everything seems to work:

# lsscsi
[15:0:0:0]   mediumx HP       ThinStor AutoLdr 0104  /dev/sch0
[15:0:1:0]   tape    HP       Ultrium 1-SCSI   0104  /dev/st0

# mtx status
  Storage Changer /dev/changer:1 Drives, 9 Slots ( 1 Import/Export )
Data Transfer Element 0:Empty
      Storage Element 1:Full :VolumeTag=E01002L1                            
      Storage Element 2:Full :VolumeTag=E01003L1                            
      Storage Element 3:Full :VolumeTag=E01004L1                            
      Storage Element 4:Full :VolumeTag=E01005L1                            
      Storage Element 5:Full :VolumeTag=E01006L1                            
      Storage Element 6:Full :VolumeTag=E01007L1                            
      Storage Element 7:Full :VolumeTag=E01008L1                            
      Storage Element 8:Full :VolumeTag=E01008L1                            
      Storage Element 9 IMPORT/EXPORT:Empty

But when I try to actually load a tape into the drive, it doesn't:

# mtx load 1
Loading media from Storage Element 1 into drive 0...mtx: Request Sense: Long Report=yes
mtx: Request Sense: Valid Residual=yes
mtx: Request Sense: Error Code=70 (Current)
mtx: Request Sense: Sense Key=Illegal Request
mtx: Request Sense: FileMark=no
mtx: Request Sense: EOM=no
mtx: Request Sense: ILI=no
mtx: Request Sense: Residual = 00 00 00 00
mtx: Request Sense: Additional Sense Code = 24
mtx: Request Sense: Additional Sense Qualifier = 00
mtx: Request Sense: BPV=no
mtx: Request Sense: Error in CDB=no
mtx: Request Sense: SKSV=no
MOVE MEDIUM from Element Address 1024 to 1 Failed

Since I've set verbosity to 3 in '/etc/mhvtl/mhvtl.conf', I get quite a lot in /var/log/messages - but unfortunately I don't quite understand it:

Sep  4 12:46:33 barbie vtllibrary[6976]: CDB (304) (delay 238005): 12 00 00 00 38 00
Sep  4 12:46:33 barbie vtllibrary[6976]: spc_inquiry(): INQUIRY ** (304)
Sep  4 12:46:33 barbie vtllibrary[6976]: completeSCSICommand(): OP s/n: (304), sz: 64, sam_status: 0
Sep  4 12:46:33 barbie vtllibrary[6976]: CDB (305) (delay 405): 1a 08 1d 00 88 00
Sep  4 12:46:33 barbie vtllibrary[6976]: spc_mode_sense(): MODE SENSE 6 (305) **
Sep  4 12:46:33 barbie vtllibrary[6976]: spc_mode_sense():  Page Control     : Current values(0x00)
Sep  4 12:46:33 barbie vtllibrary[6976]: spc_mode_sense():  Page/Subpage Code: 0x1d/0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: spc_mode_sense():  Disable Block Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: spc_mode_sense():  Allocation len   : 136
Sep  4 12:46:33 barbie vtllibrary[6976]: lookup_pcode(): Looking for: Page/subpage (1d/00)
Sep  4 12:46:33 barbie vtllibrary[6976]: lookup_pcode(): Found "Element Address" -> Page/subpage (1d/00)
Sep  4 12:46:33 barbie vtllibrary[6976]: completeSCSICommand(): OP s/n: (305), sz: 24, sam_status: 0
Sep  4 12:46:33 barbie vtllibrary[6976]: CDB (306) (delay 805): b8 12 04 00 00 08 00 00 03 c4 00 00
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status(): READ ELEMENT STATUS (306) **
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():  Element type(2) => Storage
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Starting Element Address: 1024
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Number of Elements      : 8
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Allocation length       : 964 (0x03c4)
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Device ID: No, voltag: Yes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Query 8 elements starting from addr: 1024 of type: (2) Storage
Sep  4 12:46:33 barbie vtllibrary[6976]: num_available_elements(): Determing 8 elements of type Storage starting at 1024, returning 8
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Available count: 8, type: 2
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_page_hdr(): Element Status Page Header: 02 80 00 34 00 00 01 a0
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Slot location: 1024, DVCID: 0, VOLTAG: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Returning 52 (0x34) bytes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Count: 1, max_count: 8, slot: 1024
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Slot location: 1025, DVCID: 0, VOLTAG: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Returning 52 (0x34) bytes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Count: 2, max_count: 8, slot: 1025
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Slot location: 1026, DVCID: 0, VOLTAG: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Returning 52 (0x34) bytes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Count: 3, max_count: 8, slot: 1026
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Slot location: 1027, DVCID: 0, VOLTAG: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Returning 52 (0x34) bytes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Count: 4, max_count: 8, slot: 1027
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Slot location: 1028, DVCID: 0, VOLTAG: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Returning 52 (0x34) bytes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Count: 5, max_count: 8, slot: 1028
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Slot location: 1029, DVCID: 0, VOLTAG: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Returning 52 (0x34) bytes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Count: 6, max_count: 8, slot: 1029
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Slot location: 1030, DVCID: 0, VOLTAG: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Returning 52 (0x34) bytes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Count: 7, max_count: 8, slot: 1030
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Slot location: 1031, DVCID: 0, VOLTAG: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Returning 52 (0x34) bytes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Count: 8, max_count: 8, slot: 1031
Sep  4 12:46:33 barbie vtllibrary[6976]: num_available_elements(): Determing 8 elements of type Storage starting at 1024, returning 8
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr(): Building READ ELEMENT STATUS Header struct
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Starting slot: 1024, number of configured slots: 8
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Element Status Data HEADER: 04 00 00 08 00 00 01 a8
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Decoded:
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   First element Address    : 1024 (0x400)
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   Number elements reported : 8 (0x08)
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   Total byte count         : 424 (0x01a8)
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Status Data
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   First element reported       : 1024
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Number of elements available : 8
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Byte count of report         : 424
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Status Page
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Element Type code            : 2 (Storage)
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Primary Vol Tag              : Yes
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Alt Vol Tag                  : No
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Element descriptor length    : 52
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Byte count of descriptor data: 416
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Descriptor(s) : Num of Elements 8
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Debug.... i = 0, len = 52
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Element Address             : 1024
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Status                      : 0x09
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Medium type                 : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Voltag info...
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Identification Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Code Set                     : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier type              : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier length            : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Debug.... i = 52, len = 52
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Element Address             : 1025
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Status                      : 0x09
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Medium type                 : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Voltag info...
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Identification Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Code Set                     : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier type              : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier length            : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Debug.... i = 104, len = 52
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Element Address             : 1026
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Status                      : 0x09
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Medium type                 : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Voltag info...
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Identification Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Code Set                     : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier type              : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier length            : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Debug.... i = 156, len = 52
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Element Address             : 1027
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Status                      : 0x09
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Medium type                 : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Voltag info...
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Identification Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Code Set                     : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier type              : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier length            : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Debug.... i = 208, len = 52
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Element Address             : 1028
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Status                      : 0x09
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Medium type                 : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Voltag info...
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Identification Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Code Set                     : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier type              : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier length            : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Debug.... i = 260, len = 52
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Element Address             : 1029
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Status                      : 0x09
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Medium type                 : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Voltag info...
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Identification Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Code Set                     : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier type              : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier length            : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Debug.... i = 312, len = 52
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Element Address             : 1030
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Status                      : 0x09
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Medium type                 : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Voltag info...
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Identification Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Code Set                     : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier type              : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier length            : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Debug.... i = 364, len = 52
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Element Address             : 1031
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Status                      : 0x09
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Medium type                 : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Voltag info...
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Identification Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Code Set                     : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier type              : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier length            : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status(): Element count: 8, Elem byte count: 424 (0x01a8), alloc_len: 964, returning 432
Sep  4 12:46:33 barbie vtllibrary[6976]: completeSCSICommand(): OP s/n: (306), sz: 432, sam_status: 0
Sep  4 12:46:33 barbie vtllibrary[6976]: CDB (307) (delay 405): b8 13 03 00 00 01 00 00 03 c4 00 00
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status(): READ ELEMENT STATUS (307) **
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():  Element type(3) => MAP
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Starting Element Address: 768
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Number of Elements      : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Allocation length       : 964 (0x03c4)
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Device ID: No, voltag: Yes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Query 1 element starting from addr: 768 of type: (3) MAP
Sep  4 12:46:33 barbie vtllibrary[6976]: num_available_elements(): Determing 1 element of type MAP starting at 768, returning 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Available count: 1, type: 3
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_page_hdr(): Element Status Page Header: 03 80 00 34 00 00 00 34
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Slot location: 768, DVCID: 0, VOLTAG: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Returning 52 (0x34) bytes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Count: 1, max_count: 1, slot: 768
Sep  4 12:46:33 barbie vtllibrary[6976]: num_available_elements(): Determing 1 element of type MAP starting at 768, returning 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr(): Building READ ELEMENT STATUS Header struct
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Starting slot: 768, number of configured slots: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Element Status Data HEADER: 03 00 00 01 00 00 00 3c
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Decoded:
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   First element Address    : 768 (0x300)
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   Number elements reported : 1 (0x01)
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   Total byte count         : 60 (0x003c)
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Status Data
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   First element reported       : 768
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Number of elements available : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Byte count of report         : 60
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Status Page
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Element Type code            : 3 (MAP)
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Primary Vol Tag              : Yes
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Alt Vol Tag                  : No
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Element descriptor length    : 52
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Byte count of descriptor data: 52
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Descriptor(s) : Num of Elements 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Debug.... i = 0, len = 52
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Element Address             : 768
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Status                      : 0x3a
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Medium type                 : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Voltag info...
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Identification Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Code Set                     : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier type              : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier length            : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status(): Element count: 1, Elem byte count: 60 (0x003c), alloc_len: 964, returning 68
Sep  4 12:46:33 barbie vtllibrary[6976]: completeSCSICommand(): OP s/n: (307), sz: 68, sam_status: 0
Sep  4 12:46:33 barbie vtllibrary[6976]: CDB (308) (delay 405): b8 14 00 01 00 01 00 00 03 c4 00 00
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status(): READ ELEMENT STATUS (308) **
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():  Element type(4) => Drive
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Starting Element Address: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Number of Elements      : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Allocation length       : 964 (0x03c4)
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Device ID: No, voltag: Yes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Query 1 element starting from addr: 1 of type: (4) Drive
Sep  4 12:46:33 barbie vtllibrary[6976]: num_available_elements(): Determing 1 element of type Drive starting at 1, returning 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Available count: 1, type: 4
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_page_hdr(): Element Status Page Header: 04 80 00 34 00 00 00 34
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Slot location: 1, DVCID: 0, VOLTAG: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Returning 52 (0x34) bytes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Count: 1, max_count: 1, slot: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: num_available_elements(): Determing 1 element of type Drive starting at 1, returning 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr(): Building READ ELEMENT STATUS Header struct
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Starting slot: 1, number of configured slots: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Element Status Data HEADER: 00 01 00 01 00 00 00 3c
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Decoded:
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   First element Address    : 1 (0x01)
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   Number elements reported : 1 (0x01)
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   Total byte count         : 60 (0x003c)
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Status Data
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   First element reported       : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Number of elements available : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Byte count of report         : 60
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Status Page
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Element Type code            : 4 (Drive)
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Primary Vol Tag              : Yes
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Alt Vol Tag                  : No
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Element descriptor length    : 52
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Byte count of descriptor data: 52
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Descriptor(s) : Num of Elements 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Debug.... i = 0, len = 52
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Element Address             : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Status                      : 0x08
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Medium type                 : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Voltag info...
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Identification Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Code Set                     : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier type              : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier length            : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status(): Element count: 1, Elem byte count: 60 (0x003c), alloc_len: 964, returning 68
Sep  4 12:46:33 barbie vtllibrary[6976]: completeSCSICommand(): OP s/n: (308), sz: 68, sam_status: 0
Sep  4 12:46:33 barbie vtllibrary[6976]: CDB (309) (delay 405): b8 11 02 c0 00 01 00 00 03 c4 00 00
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status(): READ ELEMENT STATUS (309) **
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():  Element type(1) => Picker
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Starting Element Address: 704
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Number of Elements      : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Allocation length       : 964 (0x03c4)
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Device ID: No, voltag: Yes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Query 1 element starting from addr: 704 of type: (1) Picker
Sep  4 12:46:33 barbie vtllibrary[6976]: num_available_elements(): Determing 1 element of type Picker starting at 704, returning 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Available count: 1, type: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_page_hdr(): Element Status Page Header: 01 80 00 34 00 00 00 34
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Slot location: 704, DVCID: 0, VOLTAG: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Returning 52 (0x34) bytes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Count: 1, max_count: 1, slot: 704
Sep  4 12:46:33 barbie vtllibrary[6976]: num_available_elements(): Determing 1 element of type Picker starting at 704, returning 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr(): Building READ ELEMENT STATUS Header struct
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Starting slot: 704, number of configured slots: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Element Status Data HEADER: 02 c0 00 01 00 00 00 3c
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Decoded:
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   First element Address    : 704 (0x2c0)
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   Number elements reported : 1 (0x01)
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   Total byte count         : 60 (0x003c)
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Status Data
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   First element reported       : 704
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Number of elements available : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Byte count of report         : 60
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Status Page
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Element Type code            : 1 (Picker)
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Primary Vol Tag              : Yes
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Alt Vol Tag                  : No
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Element descriptor length    : 52
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Byte count of descriptor data: 52
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Descriptor(s) : Num of Elements 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Debug.... i = 0, len = 52
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Element Address             : 704
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Status                      : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Medium type                 : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Voltag info...
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Identification Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Code Set                     : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier type              : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier length            : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status(): Element count: 1, Elem byte count: 60 (0x003c), alloc_len: 964, returning 68
Sep  4 12:46:33 barbie vtllibrary[6976]: completeSCSICommand(): OP s/n: (309), sz: 68, sam_status: 0
Sep  4 12:46:33 barbie vtllibrary[6976]: CDB (310) (delay 405): a5 00 02 c0 04 00 00 01 00 00 00 00
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_move_medium(): MOVE MEDIUM (310) **
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_move_medium(): Moving from slot 1024 to slot 1 using transport 704, Invert media: no
Sep  4 12:46:33 barbie vtllibrary[6976]: valid_slot(): Storage slot 1024
Sep  4 12:46:33 barbie vtllibrary[6976]: valid_slot(): Drive slot 1
Sep  4 12:46:33 barbie vtllibrary[6976]: ERROR: valid_slot(): No drive in slot: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: ERROR: smc_move_medium(): Invalid dest slot: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: mkSenseBuf(): SENSE [Key/ASC/ASCQ] [05 24 00]
Sep  4 12:46:33 barbie vtllibrary[6976]: completeSCSICommand(): s/n: (310), sz: 0, sam_status: 2 [05 24 00]

Initially I was using 1.4.7, but have upgraded to 1.4.9 with same result.

So - is it something in my configuration?


 Regards, Danilo
Reply | Threaded
Open this post in threaded view
|

Re: mhVTL on OpenSuSE 11.4 - mtx load fails

Mark Harvey
Administrator
With this config, you managed to create a library and stand alone drive.

Update the "Drive: 2" entry to include the line
' Library ID: 1 Slot: 1'
(Include leading space)

Which will tie the drive into the Library & location..

Cheers
Sent from my iPhone

On 04/09/2013, at 20:51, "danci1973 [via mhVTL - A Linux Virtual Tape Library]" <[hidden email]> wrote:

I have successfully used mhVTL to emulate a single drive for testing Bacula settings / strategy.

Now I need to do the same for a 8-slot, 1-drive tape changer (HP ThinStor AutoLdr / HP Ultrium 1-SCSI).

This is my 'device.conf':

Library: 1 CHANNEL: 00 TARGET: 00 LUN: 00
 Vendor identification: HP
 Product identification: ThinStor AutoLdr
 Unit serial number: 000000
 NAA: 10:22:33:44:ab:00:00:00
 Home directory: /var/lib/mhvtl/1
 Backoff: 400
 fifo: /var/tmp/mhvtl

Drive: 2 CHANNEL: 00 TARGET: 01 LUN: 00
 Vendor identification: HP
 Product identification: Ultrium 1-SCSI
 Unit serial number: 000001
 NAA: 10:22:33:44:ab:00:01:00
 Compression: factor 1 enabled 1
 Compression type: lzo
 Backoff: 400
 fifo: /var/tmp/mhvtl

And this is my 'library_contents.1' (stripped of comments):

Drive 1:

Picker 1:

MAP 1:

Slot 1: E01002L1
Slot 2: E01003L1
Slot 3: E01004L1
Slot 4: E01005L1
Slot 5: E01006L1
Slot 6: E01007L1
Slot 7: E01008L1
Slot 8: E01008L1

On the first glance, everything seems to work:

# lsscsi
[15:0:0:0]   mediumx HP       ThinStor AutoLdr 0104  /dev/sch0
[15:0:1:0]   tape    HP       Ultrium 1-SCSI   0104  /dev/st0

# mtx status
  Storage Changer /dev/changer:1 Drives, 9 Slots ( 1 Import/Export )
Data Transfer Element 0:Empty
      Storage Element 1:Full :VolumeTag=E01002L1                            
      Storage Element 2:Full :VolumeTag=E01003L1                            
      Storage Element 3:Full :VolumeTag=E01004L1                            
      Storage Element 4:Full :VolumeTag=E01005L1                            
      Storage Element 5:Full :VolumeTag=E01006L1                            
      Storage Element 6:Full :VolumeTag=E01007L1                            
      Storage Element 7:Full :VolumeTag=E01008L1                            
      Storage Element 8:Full :VolumeTag=E01008L1                            
      Storage Element 9 IMPORT/EXPORT:Empty

But when I try to actually load a tape into the drive, it doesn't:

# mtx load 1
Loading media from Storage Element 1 into drive 0...mtx: Request Sense: Long Report=yes
mtx: Request Sense: Valid Residual=yes
mtx: Request Sense: Error Code=70 (Current)
mtx: Request Sense: Sense Key=Illegal Request
mtx: Request Sense: FileMark=no
mtx: Request Sense: EOM=no
mtx: Request Sense: ILI=no
mtx: Request Sense: Residual = 00 00 00 00
mtx: Request Sense: Additional Sense Code = 24
mtx: Request Sense: Additional Sense Qualifier = 00
mtx: Request Sense: BPV=no
mtx: Request Sense: Error in CDB=no
mtx: Request Sense: SKSV=no
MOVE MEDIUM from Element Address 1024 to 1 Failed

Since I've set verbosity to 3 in '/etc/mhvtl/mhvtl.conf', I get quite a lot in /var/log/messages - but unfortunately I don't quite understand it:

Sep  4 12:46:33 barbie vtllibrary[6976]: CDB (304) (delay 238005): 12 00 00 00 38 00
Sep  4 12:46:33 barbie vtllibrary[6976]: spc_inquiry(): INQUIRY ** (304)
Sep  4 12:46:33 barbie vtllibrary[6976]: completeSCSICommand(): OP s/n: (304), sz: 64, sam_status: 0
Sep  4 12:46:33 barbie vtllibrary[6976]: CDB (305) (delay 405): 1a 08 1d 00 88 00
Sep  4 12:46:33 barbie vtllibrary[6976]: spc_mode_sense(): MODE SENSE 6 (305) **
Sep  4 12:46:33 barbie vtllibrary[6976]: spc_mode_sense():  Page Control     : Current values(0x00)
Sep  4 12:46:33 barbie vtllibrary[6976]: spc_mode_sense():  Page/Subpage Code: 0x1d/0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: spc_mode_sense():  Disable Block Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: spc_mode_sense():  Allocation len   : 136
Sep  4 12:46:33 barbie vtllibrary[6976]: lookup_pcode(): Looking for: Page/subpage (1d/00)
Sep  4 12:46:33 barbie vtllibrary[6976]: lookup_pcode(): Found "Element Address" -> Page/subpage (1d/00)
Sep  4 12:46:33 barbie vtllibrary[6976]: completeSCSICommand(): OP s/n: (305), sz: 24, sam_status: 0
Sep  4 12:46:33 barbie vtllibrary[6976]: CDB (306) (delay 805): b8 12 04 00 00 08 00 00 03 c4 00 00
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status(): READ ELEMENT STATUS (306) **
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():  Element type(2) => Storage
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Starting Element Address: 1024
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Number of Elements      : 8
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Allocation length       : 964 (0x03c4)
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Device ID: No, voltag: Yes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Query 8 elements starting from addr: 1024 of type: (2) Storage
Sep  4 12:46:33 barbie vtllibrary[6976]: num_available_elements(): Determing 8 elements of type Storage starting at 1024, returning 8
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Available count: 8, type: 2
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_page_hdr(): Element Status Page Header: 02 80 00 34 00 00 01 a0
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Slot location: 1024, DVCID: 0, VOLTAG: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Returning 52 (0x34) bytes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Count: 1, max_count: 8, slot: 1024
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Slot location: 1025, DVCID: 0, VOLTAG: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Returning 52 (0x34) bytes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Count: 2, max_count: 8, slot: 1025
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Slot location: 1026, DVCID: 0, VOLTAG: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Returning 52 (0x34) bytes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Count: 3, max_count: 8, slot: 1026
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Slot location: 1027, DVCID: 0, VOLTAG: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Returning 52 (0x34) bytes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Count: 4, max_count: 8, slot: 1027
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Slot location: 1028, DVCID: 0, VOLTAG: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Returning 52 (0x34) bytes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Count: 5, max_count: 8, slot: 1028
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Slot location: 1029, DVCID: 0, VOLTAG: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Returning 52 (0x34) bytes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Count: 6, max_count: 8, slot: 1029
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Slot location: 1030, DVCID: 0, VOLTAG: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Returning 52 (0x34) bytes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Count: 7, max_count: 8, slot: 1030
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Slot location: 1031, DVCID: 0, VOLTAG: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Returning 52 (0x34) bytes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Count: 8, max_count: 8, slot: 1031
Sep  4 12:46:33 barbie vtllibrary[6976]: num_available_elements(): Determing 8 elements of type Storage starting at 1024, returning 8
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr(): Building READ ELEMENT STATUS Header struct
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Starting slot: 1024, number of configured slots: 8
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Element Status Data HEADER: 04 00 00 08 00 00 01 a8
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Decoded:
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   First element Address    : 1024 (0x400)
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   Number elements reported : 8 (0x08)
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   Total byte count         : 424 (0x01a8)
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Status Data
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   First element reported       : 1024
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Number of elements available : 8
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Byte count of report         : 424
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Status Page
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Element Type code            : 2 (Storage)
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Primary Vol Tag              : Yes
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Alt Vol Tag                  : No
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Element descriptor length    : 52
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Byte count of descriptor data: 416
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Descriptor(s) : Num of Elements 8
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Debug.... i = 0, len = 52
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Element Address             : 1024
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Status                      : 0x09
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Medium type                 : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Voltag info...
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Identification Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Code Set                     : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier type              : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier length            : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Debug.... i = 52, len = 52
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Element Address             : 1025
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Status                      : 0x09
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Medium type                 : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Voltag info...
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Identification Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Code Set                     : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier type              : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier length            : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Debug.... i = 104, len = 52
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Element Address             : 1026
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Status                      : 0x09
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Medium type                 : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Voltag info...
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Identification Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Code Set                     : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier type              : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier length            : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Debug.... i = 156, len = 52
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Element Address             : 1027
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Status                      : 0x09
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Medium type                 : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Voltag info...
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Identification Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Code Set                     : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier type              : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier length            : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Debug.... i = 208, len = 52
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Element Address             : 1028
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Status                      : 0x09
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Medium type                 : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Voltag info...
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Identification Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Code Set                     : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier type              : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier length            : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Debug.... i = 260, len = 52
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Element Address             : 1029
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Status                      : 0x09
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Medium type                 : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Voltag info...
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Identification Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Code Set                     : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier type              : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier length            : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Debug.... i = 312, len = 52
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Element Address             : 1030
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Status                      : 0x09
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Medium type                 : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Voltag info...
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Identification Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Code Set                     : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier type              : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier length            : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Debug.... i = 364, len = 52
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Element Address             : 1031
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Status                      : 0x09
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Medium type                 : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Voltag info...
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Identification Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Code Set                     : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier type              : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier length            : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status(): Element count: 8, Elem byte count: 424 (0x01a8), alloc_len: 964, returning 432
Sep  4 12:46:33 barbie vtllibrary[6976]: completeSCSICommand(): OP s/n: (306), sz: 432, sam_status: 0
Sep  4 12:46:33 barbie vtllibrary[6976]: CDB (307) (delay 405): b8 13 03 00 00 01 00 00 03 c4 00 00
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status(): READ ELEMENT STATUS (307) **
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():  Element type(3) => MAP
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Starting Element Address: 768
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Number of Elements      : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Allocation length       : 964 (0x03c4)
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Device ID: No, voltag: Yes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Query 1 element starting from addr: 768 of type: (3) MAP
Sep  4 12:46:33 barbie vtllibrary[6976]: num_available_elements(): Determing 1 element of type MAP starting at 768, returning 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Available count: 1, type: 3
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_page_hdr(): Element Status Page Header: 03 80 00 34 00 00 00 34
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Slot location: 768, DVCID: 0, VOLTAG: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Returning 52 (0x34) bytes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Count: 1, max_count: 1, slot: 768
Sep  4 12:46:33 barbie vtllibrary[6976]: num_available_elements(): Determing 1 element of type MAP starting at 768, returning 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr(): Building READ ELEMENT STATUS Header struct
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Starting slot: 768, number of configured slots: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Element Status Data HEADER: 03 00 00 01 00 00 00 3c
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Decoded:
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   First element Address    : 768 (0x300)
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   Number elements reported : 1 (0x01)
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   Total byte count         : 60 (0x003c)
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Status Data
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   First element reported       : 768
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Number of elements available : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Byte count of report         : 60
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Status Page
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Element Type code            : 3 (MAP)
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Primary Vol Tag              : Yes
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Alt Vol Tag                  : No
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Element descriptor length    : 52
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Byte count of descriptor data: 52
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Descriptor(s) : Num of Elements 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Debug.... i = 0, len = 52
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Element Address             : 768
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Status                      : 0x3a
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Medium type                 : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Voltag info...
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Identification Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Code Set                     : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier type              : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier length            : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status(): Element count: 1, Elem byte count: 60 (0x003c), alloc_len: 964, returning 68
Sep  4 12:46:33 barbie vtllibrary[6976]: completeSCSICommand(): OP s/n: (307), sz: 68, sam_status: 0
Sep  4 12:46:33 barbie vtllibrary[6976]: CDB (308) (delay 405): b8 14 00 01 00 01 00 00 03 c4 00 00
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status(): READ ELEMENT STATUS (308) **
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():  Element type(4) => Drive
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Starting Element Address: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Number of Elements      : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Allocation length       : 964 (0x03c4)
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Device ID: No, voltag: Yes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Query 1 element starting from addr: 1 of type: (4) Drive
Sep  4 12:46:33 barbie vtllibrary[6976]: num_available_elements(): Determing 1 element of type Drive starting at 1, returning 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Available count: 1, type: 4
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_page_hdr(): Element Status Page Header: 04 80 00 34 00 00 00 34
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Slot location: 1, DVCID: 0, VOLTAG: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Returning 52 (0x34) bytes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Count: 1, max_count: 1, slot: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: num_available_elements(): Determing 1 element of type Drive starting at 1, returning 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr(): Building READ ELEMENT STATUS Header struct
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Starting slot: 1, number of configured slots: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Element Status Data HEADER: 00 01 00 01 00 00 00 3c
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Decoded:
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   First element Address    : 1 (0x01)
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   Number elements reported : 1 (0x01)
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   Total byte count         : 60 (0x003c)
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Status Data
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   First element reported       : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Number of elements available : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Byte count of report         : 60
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Status Page
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Element Type code            : 4 (Drive)
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Primary Vol Tag              : Yes
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Alt Vol Tag                  : No
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Element descriptor length    : 52
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Byte count of descriptor data: 52
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Descriptor(s) : Num of Elements 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Debug.... i = 0, len = 52
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Element Address             : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Status                      : 0x08
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Medium type                 : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Voltag info...
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Identification Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Code Set                     : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier type              : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier length            : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status(): Element count: 1, Elem byte count: 60 (0x003c), alloc_len: 964, returning 68
Sep  4 12:46:33 barbie vtllibrary[6976]: completeSCSICommand(): OP s/n: (308), sz: 68, sam_status: 0
Sep  4 12:46:33 barbie vtllibrary[6976]: CDB (309) (delay 405): b8 11 02 c0 00 01 00 00 03 c4 00 00
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status(): READ ELEMENT STATUS (309) **
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():  Element type(1) => Picker
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Starting Element Address: 704
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Number of Elements      : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Allocation length       : 964 (0x03c4)
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status():   Device ID: No, voltag: Yes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Query 1 element starting from addr: 704 of type: (1) Picker
Sep  4 12:46:33 barbie vtllibrary[6976]: num_available_elements(): Determing 1 element of type Picker starting at 704, returning 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Available count: 1, type: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_page_hdr(): Element Status Page Header: 01 80 00 34 00 00 00 34
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Slot location: 704, DVCID: 0, VOLTAG: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_descriptor(): Returning 52 (0x34) bytes
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_page(): Count: 1, max_count: 1, slot: 704
Sep  4 12:46:33 barbie vtllibrary[6976]: num_available_elements(): Determing 1 element of type Picker starting at 704, returning 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr(): Building READ ELEMENT STATUS Header struct
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Starting slot: 704, number of configured slots: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Element Status Data HEADER: 02 c0 00 01 00 00 00 3c
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():  Decoded:
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   First element Address    : 704 (0x2c0)
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   Number elements reported : 1 (0x01)
Sep  4 12:46:33 barbie vtllibrary[6976]: fill_element_status_data_hdr():   Total byte count         : 60 (0x003c)
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Status Data
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   First element reported       : 704
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Number of elements available : 1
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Byte count of report         : 60
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Status Page
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Element Type code            : 1 (Picker)
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Primary Vol Tag              : Yes
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Alt Vol Tag                  : No
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Element descriptor length    : 52
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status():   Byte count of descriptor data: 52
Sep  4 12:46:33 barbie vtllibrary[6976]: decode_element_status(): Element Descriptor(s) : Num of Elements 1
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Debug.... i = 0, len = 52
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Element Address             : 704
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Status                      : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Medium type                 : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Voltag info...
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():  Identification Descriptor
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Code Set                     : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier type              : 0x00
Sep  4 12:46:33 barbie vtllibrary[6976]: dump_element_desc():   Identifier length            : 0
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_read_element_status(): Element count: 1, Elem byte count: 60 (0x003c), alloc_len: 964, returning 68
Sep  4 12:46:33 barbie vtllibrary[6976]: completeSCSICommand(): OP s/n: (309), sz: 68, sam_status: 0
Sep  4 12:46:33 barbie vtllibrary[6976]: CDB (310) (delay 405): a5 00 02 c0 04 00 00 01 00 00 00 00
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_move_medium(): MOVE MEDIUM (310) **
Sep  4 12:46:33 barbie vtllibrary[6976]: smc_move_medium(): Moving from slot 1024 to slot 1 using transport 704, Invert media: no
Sep  4 12:46:33 barbie vtllibrary[6976]: valid_slot(): Storage slot 1024
Sep  4 12:46:33 barbie vtllibrary[6976]: valid_slot(): Drive slot 1
Sep  4 12:46:33 barbie vtllibrary[6976]: ERROR: valid_slot(): No drive in slot: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: ERROR: smc_move_medium(): Invalid dest slot: 1
Sep  4 12:46:33 barbie vtllibrary[6976]: mkSenseBuf(): SENSE [Key/ASC/ASCQ] [05 24 00]
Sep  4 12:46:33 barbie vtllibrary[6976]: completeSCSICommand(): s/n: (310), sz: 0, sam_status: 2 [05 24 00]

Initially I was using 1.4.7, but have upgraded to 1.4.9 with same result.

So - is it something in my configuration?


 Regards, Danilo



If you reply to this email, your message will be added to the discussion below:
http://mhvtl-a-linux-virtual-tape-library.966029.n3.nabble.com/mhVTL-on-OpenSuSE-11-4-mtx-load-fails-tp4025537.html
To start a new topic under mhVTL - A Linux Virtual Tape Library, email [hidden email]
To unsubscribe from mhVTL - A Linux Virtual Tape Library, click here.
NAML
Regards from Australia
Mark Harvey
Reply | Threaded
Open this post in threaded view
|

Re: mhVTL on OpenSuSE 11.4 - mtx load fails

danci1973
Thank you very much!

It's so obvious now, how could I've missed that...
Reply | Threaded
Open this post in threaded view
|

Re: mhVTL on OpenSuSE 11.4 - mtx load fails

Mark Harvey
Administrator
Manually editing the config files is way, way too error prone..

The nice thing about nia's GUI addition is that it takes the manual drudgery out of  config files :)
Regards from Australia
Mark Harvey