I've been successfully using mhVTL with TSM on a platform which is all Linux. I have just tried to install TSM on windows 2003 and connect to the mhVTL library via iSCSI (using tgt framework on Linux). On Windows I am using the IBMtape drivers with IBM some defined library/drives in mhVTL.
Everything goes OK until the very last stage - the point where I try and label some LTO3 media and check the tapes into the TSM library. At this point TSM seems to think that the media is 3592 and not LTO3 and complains it cannot find an appropriate drive to load the media into (ANR8847E No 3592-type drives are currently available in library IBM_VTL). Any ideas? Here is some config/diagnosis: [root@mhvtl mhvtl]# cat device.conf VERSION: 5 Library: 10 CHANNEL: 00 TARGET: 00 LUN: 00 Vendor identification: IBM Product identification: 03584L22 Product revision level: 4.02 Unit serial number: 70000050 NAA: 30:22:33:44:ab:00:08:00 Home directory: /opt/mhvtl Backoff: 400 # fifo: /var/tmp/mhvtl Drive: 11 CHANNEL: 00 TARGET: 01 LUN: 00 Library ID: 10 Slot: 01 Vendor identification: IBM Product identification: ULT3580-TD3 Unit serial number: 1068047945 NAA: 10:22:33:44:ab:00:01:00 Compression: factor 1 enabled 1 Compression type: lzo Backoff: 400 # fifo: /var/tmp/mhvtl Drive: 12 CHANNEL: 00 TARGET: 02 LUN: 00 Library ID: 10 Slot: 02 Vendor identification: IBM Product identification: ULT3580-TD3 Product revision level: 252D Unit serial number: 70000051 NAA: 50:11:22:33:ab:1:00:01 Compression: factor 1 enabled 1 Compression type: lzo Backoff: 400 Drive: 13 CHANNEL: 00 TARGET: 03 LUN: 00 Library ID: 10 Slot: 03 Vendor identification: IBM Product identification: ULT3580-TD3 Product revision level: 252D Unit serial number: 70000052 NAA: 50:11:22:33:ab:1:00:2 Compression: factor 1 enabled 1 Compression type: lzo Backoff: 400 Drive: 14 CHANNEL: 00 TARGET: 04 LUN: 00 Library ID: 10 Slot: 04 Vendor identification: IBM Product identification: ULT3580-TD3 Product revision level: 252D Unit serial number: 70000053 NAA: 50:11:22:33:ab:1:00:3 Compression: factor 1 enabled 1 Compression type: lzo Backoff: 400 [root@mhvtl mhvtl]# cat mhvtl.conf # Home directory for config file(s) MHVTL_CONFIG_PATH=/etc/mhvtl # Default media capacity (500 M) CAPACITY=500 # Set default verbosity [0|1|2|3] VERBOSE=1 # Set kernel module debuging [0|1] VTL_DEBUG=1 [root@mhvtl mhvtl]# cat library_contents.10 Drive 1: Drive 2: Drive 3: Drive 4: Picker 1: MAP 1: MAP 2: MAP 3: MAP 4: # Slot 1 - ?, no gaps # Slot N: [barcode] # [barcode] # a barcode is comprised of three fields: [Leading] [identifier] [Trailing] # Leading "CLN" -- cleaning tape # Leading "W" -- WORM tape # Leading "NOBAR" -- will appear to have no barcode # If the barcode is at least 8 character long, then the last two characters are Trailing # Trailing "S3" - SDLT600 # Trailing "X4" - AIT-4 # Trailing "L1" - LTO 1, "L2" - LTO 2, "L3" - LTO 3, "L4" - LTO 4, "L5" - LTO 5 # Trailing "LT" - LTO 3 WORM, "LU" - LTO 4 WORM, "LV" - LTO 5 WORM # Trailing "L6" - LTO 6, "LW" - LTO 6 WORM # Trailing "TA" - T10000+ # Trailing "TZ" - 9840A, "TY" - 9840B, "TX" - 9840C, "TW" - 9840D # Trailing "TV" - 9940A, "TU" - 9940B # Trailing "JA" - 3592+ # Trailing "JB" - 3592E05+ # Trailing "JW" - WORM 3592+ # Trailing "JX" - WORM 3592E05+ # Trailing "D7" - DLT7000 media (DLT IV) # Slot 1: E01001L3 Slot 2: E01002L3 Slot 3: E01003L3 Slot 4: E01004L3 Slot 5: E01005L3 Slot 6: E01006L3 Slot 7: E01007L3 Slot 8: E01008L3 Slot 9: E01009L3 Slot 10: E01010L3 Slot 11: E01011L3 Slot 12: E01012L3 Slot 13: E01013L3 Slot 14: E01014L3 Slot 15: E01015L3 Slot 16: E01016L3 Slot 17: E01017L3 Slot 18: E01018L3 Slot 19: E01019L3 Slot 20: E01020L3 Slot 21: Slot 22: Slot 23: Slot 24: Here are the commands within TSM I used to set up the library and drives: tsm: VMTSM55WIN>define library IBM_VTL libtype=scsi ANR8400I Library IBM_VTL defined. tsm: VMTSM55WIN>define path VMTSM55WIN IBM_VTL srctype=server desttype=library device=lb0.5.0.3 ANR1720I A path from VMTSM55WIN to IBM_VTL has been defined. tsm: VMTSM55WIN>q libr f=d Library Name: IBM_VTL Library Type: SCSI ACS Id: Private Category: Scratch Category: WORM Scratch Category: External Manager: RSM Media Type: Shared: No LanFree: ObeyMountRetention: Primary Library Manager: WWN: Serial Number: 70000050 AutoLabel: No Reset Drives: No Last Update by (administrator): ADMIN Last Update Date/Time: 08/25/2013 23:34:15 tsm: VMTSM55WIN>define drive IBM_VTL DRIVE_1 ANR8404I Drive DRIVE_1 defined in library IBM_VTL. tsm: VMTSM55WIN>define drive IBM_VTL DRIVE_2 ANR8404I Drive DRIVE_2 defined in library IBM_VTL. tsm: VMTSM55WIN>define drive IBM_VTL DRIVE_3 ANR8404I Drive DRIVE_3 defined in library IBM_VTL. tsm: VMTSM55WIN>define drive IBM_VTL DRIVE_4 ANR8404I Drive DRIVE_4 defined in library IBM_VTL. tsm: VMTSM55WIN>define path VMTSM55WIN DRIVE_1 srctype=server desttype=drive library=IBM_VTL device=mt0.1.0.3 ANR1720I A path from VMTSM55WIN to IBM_VTL DRIVE_1 has been defined. tsm: VMTSM55WIN>define path VMTSM55WIN DRIVE_2 srctype=server desttype=drive library=IBM_VTL device=mt0.2.0.3 ANR1720I A path from VMTSM55WIN to IBM_VTL DRIVE_2 has been defined. tsm: VMTSM55WIN>define path VMTSM55WIN DRIVE_3 srctype=server desttype=drive library=IBM_VTL device=mt0.3.0.3 ANR1720I A path from VMTSM55WIN to IBM_VTL DRIVE_3 has been defined. tsm: VMTSM55WIN>define path VMTSM55WIN DRIVE_4 srctype=server desttype=drive library=IBM_VTL device=mt0.4.0.3 ANR1720I A path from VMTSM55WIN to IBM_VTL DRIVE_4 has been defined. tsm: VMTSM55WIN>q drive f=d Library Name: IBM_VTL Drive Name: DRIVE_1 Device Type: LTO On-Line: Yes Read Formats: ULTRIUM3C,ULTRIUM3,ULTRIUM2C,ULTRIUM2,ULTRIUMC,ULTRIUM Write Formats: ULTRIUM3C,ULTRIUM3,ULTRIUM2C,ULTRIUM2 Element: 1 Drive State: UNKNOWN Volume Name: Allocated to: WWN: 50223344AB000100 Serial Number: 1068047945 Last Update by (administrator): ADMIN Last Update Date/Time: 08/25/2013 23:35:58 Cleaning Frequency (Gigabytes/ASNEEDED/NONE): NONE Library Name: IBM_VTL Drive Name: DRIVE_2 Device Type: LTO On-Line: Yes Read Formats: ULTRIUM3C,ULTRIUM3,ULTRIUM2C,ULTRIUM2,ULTRIUMC,ULTRIUM Write Formats: ULTRIUM3C,ULTRIUM3,ULTRIUM2C,ULTRIUM2 Element: 2 Drive State: UNKNOWN Volume Name: Allocated to: WWN: 50112233AB010001 Serial Number: 70000051 Last Update by (administrator): ADMIN Last Update Date/Time: 08/25/2013 23:36:08 Cleaning Frequency (Gigabytes/ASNEEDED/NONE): NONE Library Name: IBM_VTL Drive Name: DRIVE_3 Device Type: LTO On-Line: Yes Read Formats: ULTRIUM3C,ULTRIUM3,ULTRIUM2C,ULTRIUM2,ULTRIUMC,ULTRIUM Write Formats: ULTRIUM3C,ULTRIUM3,ULTRIUM2C,ULTRIUM2 Element: 3 Drive State: UNKNOWN Volume Name: Allocated to: WWN: 50112233AB010002 Serial Number: 70000052 Last Update by (administrator): ADMIN Last Update Date/Time: 08/25/2013 23:36:16 Cleaning Frequency (Gigabytes/ASNEEDED/NONE): NONE Library Name: IBM_VTL Drive Name: DRIVE_4 Device Type: LTO more... (<ENTER> to continue, 'C' to cancel) On-Line: Yes Read Formats: ULTRIUM3C,ULTRIUM3,ULTRIUM2C,ULTRIUM2,ULTRIUMC,ULTRIUM Write Formats: ULTRIUM3C,ULTRIUM3,ULTRIUM2C,ULTRIUM2 Element: 4 Drive State: UNKNOWN Volume Name: Allocated to: WWN: 50112233AB010003 Serial Number: 70000053 Last Update by (administrator): ADMIN Last Update Date/Time: 08/25/2013 23:36:25 Cleaning Frequency (Gigabytes/ASNEEDED/NONE): NONE tsm: VMTSM55WIN>define devclass VTL_LTO library=IBM_VTL devtype=LTO estcapacity=500M ANR2203I Device class VTL_LTO defined. tsm: VMTSM55WIN>define stgpool VTL_PRIMARY VTL_LTO maxscratch=10 ANR2200I Storage pool VTL_PRIMARY defined (device class VTL_LTO). tsm: VMTSM55WIN>label libvol IBM_VTL search=yes labelsource=barcode checkin=scratch ANS8003I Process number 3 started. tsm: VMTSM55WIN>q libvol ANR2034E QUERY LIBVOLUME: No match found using this criteria. ANS8001I Return code 11. tsm: VMTSM55WIN>q act Date/Time Message -------------------- ---------------------------------------------------------- 08/25/2013 23:40:20 ANR2017I Administrator ADMIN issued command: LABEL LIBVOLUME IBM_VTL search=yes labelsource=barcode checkin=scratch (SESSION: 3) 08/25/2013 23:40:20 ANR0984I Process 3 for LABEL LIBVOLUME started in the BACKGROUND at 23:40:20. (SESSION: 3, PROCESS: 3) 08/25/2013 23:40:20 ANR8799I LABEL LIBVOLUME: Operation for library IBM_VTL started as process 3. (SESSION: 3, PROCESS: 3) 08/25/2013 23:40:20 ANR8439I SCSI library IBM_VTL is ready for operations. (SESSION: 3, PROCESS: 3) 08/25/2013 23:40:21 ANR8847E No 3592-type drives are currently available in library IBM_VTL. (SESSION: 3, PROCESS: 3) 08/25/2013 23:40:21 ANR8801I LABEL LIBVOLUME process 3 for library IBM_VTL completed; 0 volume(s) labeled, 0 volume(s) checked-in. (SESSION: 3, PROCESS: 3) 08/25/2013 23:40:21 ANR0985I Process 3 for LABEL LIBVOLUME running in the BACKGROUND completed with completion state SUCCESS at 23:40:21. (SESSION: 3, PROCESS: 3) And here is the debug output from mhVTL when I issued the TSM command to label and check-in the tape media: Aug 26 00:48:09 mhvtl vtllibrary[1585]: processMessageQ(): verbose: enabled at level 3 Aug 26 00:48:17 mhvtl kernel: mhvtl: CDB (2774) 16 bytes Aug 26 00:48:17 mhvtl kernel: 12 00 00 00 38 00 00 00 00 00 00 00 00 00 00 00 Aug 26 00:48:17 mhvtl vtllibrary[1585]: CDB (2774) (delay 91605): 12 00 00 00 38 00 Aug 26 00:48:17 mhvtl vtllibrary[1585]: spc_inquiry(): INQUIRY ** (2774) Aug 26 00:48:17 mhvtl vtllibrary[1585]: completeSCSICommand(): OP s/n: (2774), sz: 64, sam_status: 0 Aug 26 00:48:17 mhvtl vtllibrary[1585]: CDB (2775) (delay 805): 12 01 80 00 04 00 Aug 26 00:48:17 mhvtl vtllibrary[1585]: spc_inquiry(): INQUIRY ** (2775) Aug 26 00:48:17 mhvtl vtllibrary[1585]: spc_inquiry(): Page code 0x80 Aug 26 00:48:17 mhvtl vtllibrary[1585]: spc_inquiry(): Found page 0x80 Aug 26 00:48:17 mhvtl vtllibrary[1585]: completeSCSICommand(): OP s/n: (2775), sz: 14, sam_status: 0 Aug 26 00:48:17 mhvtl kernel: mhvtl: CDB (2775) 16 bytes Aug 26 00:48:17 mhvtl kernel: 12 01 80 00 04 00 00 00 00 00 00 00 00 00 00 00 Aug 26 00:48:17 mhvtl vtllibrary[1585]: CDB (2776) (delay 805): 12 01 80 00 0e 00 Aug 26 00:48:17 mhvtl vtllibrary[1585]: spc_inquiry(): INQUIRY ** (2776) Aug 26 00:48:17 mhvtl vtllibrary[1585]: spc_inquiry(): Page code 0x80 Aug 26 00:48:17 mhvtl vtllibrary[1585]: spc_inquiry(): Found page 0x80 Aug 26 00:48:17 mhvtl vtllibrary[1585]: completeSCSICommand(): OP s/n: (2776), sz: 14, sam_status: 0 Aug 26 00:48:17 mhvtl kernel: mhvtl: CDB (2776) 16 bytes Aug 26 00:48:17 mhvtl kernel: 12 01 80 00 0e 00 00 00 00 00 00 00 00 00 00 00 Aug 26 00:48:17 mhvtl vtllibrary[1585]: CDB (2777) (delay 805): 12 01 83 00 04 00 Aug 26 00:48:17 mhvtl vtllibrary[1585]: spc_inquiry(): INQUIRY ** (2777) Aug 26 00:48:17 mhvtl vtllibrary[1585]: spc_inquiry(): Page code 0x83 Aug 26 00:48:17 mhvtl vtllibrary[1585]: spc_inquiry(): Found page 0x83 Aug 26 00:48:17 mhvtl vtllibrary[1585]: completeSCSICommand(): OP s/n: (2777), sz: 54, sam_status: 0 Aug 26 00:48:17 mhvtl kernel: mhvtl: CDB (2777) 16 bytes Aug 26 00:48:17 mhvtl kernel: 12 01 83 00 04 00 00 00 00 00 00 00 00 00 00 00 Aug 26 00:48:17 mhvtl kernel: mhvtl: CDB (2778) 16 bytes Aug 26 00:48:17 mhvtl kernel: 12 01 83 00 36 00 00 00 00 00 00 00 00 00 00 00 Aug 26 00:48:17 mhvtl vtllibrary[1585]: CDB (2778) (delay 805): 12 01 83 00 36 00 Aug 26 00:48:17 mhvtl vtllibrary[1585]: spc_inquiry(): INQUIRY ** (2778) Aug 26 00:48:17 mhvtl vtllibrary[1585]: spc_inquiry(): Page code 0x83 Aug 26 00:48:17 mhvtl vtllibrary[1585]: spc_inquiry(): Found page 0x83 Aug 26 00:48:17 mhvtl vtllibrary[1585]: completeSCSICommand(): OP s/n: (2778), sz: 54, sam_status: 0 Aug 26 00:48:17 mhvtl kernel: mhvtl: CDB (2779) 16 bytes Aug 26 00:48:17 mhvtl kernel: 07 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Aug 26 00:48:17 mhvtl vtllibrary[1585]: CDB (2779) (delay 805): 07 00 00 00 00 00 Aug 26 00:48:17 mhvtl vtllibrary[1585]: smc_initialize_element_status(): INITIALIZE ELEMENT (2779) ** Aug 26 00:48:18 mhvtl vtllibrary[1585]: completeSCSICommand(): OP s/n: (2779), sz: 0, sam_status: 0 Aug 26 00:48:18 mhvtl kernel: mhvtl: CDB (2780) 16 bytes Aug 26 00:48:18 mhvtl kernel: 12 00 00 00 38 00 00 00 00 00 00 00 00 00 00 00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: CDB (2780) (delay 1205): 12 00 00 00 38 00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: spc_inquiry(): INQUIRY ** (2780) Aug 26 00:48:18 mhvtl vtllibrary[1585]: completeSCSICommand(): OP s/n: (2780), sz: 64, sam_status: 0 Aug 26 00:48:18 mhvtl kernel: mhvtl: CDB (2781) 16 bytes Aug 26 00:48:18 mhvtl kernel: 12 01 80 00 04 00 00 00 00 00 00 00 00 00 00 00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: CDB (2781) (delay 805): 12 01 80 00 04 00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: spc_inquiry(): INQUIRY ** (2781) Aug 26 00:48:18 mhvtl vtllibrary[1585]: spc_inquiry(): Page code 0x80 Aug 26 00:48:18 mhvtl vtllibrary[1585]: spc_inquiry(): Found page 0x80 Aug 26 00:48:18 mhvtl vtllibrary[1585]: completeSCSICommand(): OP s/n: (2781), sz: 14, sam_status: 0 Aug 26 00:48:18 mhvtl kernel: mhvtl: CDB (2782) 16 bytes Aug 26 00:48:18 mhvtl kernel: 12 01 80 00 0e 00 00 00 00 00 00 00 00 00 00 00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: CDB (2782) (delay 805): 12 01 80 00 0e 00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: spc_inquiry(): INQUIRY ** (2782) Aug 26 00:48:18 mhvtl vtllibrary[1585]: spc_inquiry(): Page code 0x80 Aug 26 00:48:18 mhvtl vtllibrary[1585]: spc_inquiry(): Found page 0x80 Aug 26 00:48:18 mhvtl vtllibrary[1585]: completeSCSICommand(): OP s/n: (2782), sz: 14, sam_status: 0 Aug 26 00:48:18 mhvtl kernel: mhvtl: CDB (2783) 16 bytes Aug 26 00:48:18 mhvtl kernel: 12 01 83 00 04 00 00 00 00 00 00 00 00 00 00 00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: CDB (2783) (delay 805): 12 01 83 00 04 00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: spc_inquiry(): INQUIRY ** (2783) Aug 26 00:48:18 mhvtl vtllibrary[1585]: spc_inquiry(): Page code 0x83 Aug 26 00:48:18 mhvtl vtllibrary[1585]: spc_inquiry(): Found page 0x83 Aug 26 00:48:18 mhvtl vtllibrary[1585]: completeSCSICommand(): OP s/n: (2783), sz: 54, sam_status: 0 Aug 26 00:48:18 mhvtl kernel: mhvtl: CDB (2784) 16 bytes Aug 26 00:48:18 mhvtl kernel: 12 01 83 00 36 00 00 00 00 00 00 00 00 00 00 00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: CDB (2784) (delay 805): 12 01 83 00 36 00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: spc_inquiry(): INQUIRY ** (2784) Aug 26 00:48:18 mhvtl vtllibrary[1585]: spc_inquiry(): Page code 0x83 Aug 26 00:48:18 mhvtl vtllibrary[1585]: spc_inquiry(): Found page 0x83 Aug 26 00:48:18 mhvtl vtllibrary[1585]: completeSCSICommand(): OP s/n: (2784), sz: 54, sam_status: 0 Aug 26 00:48:18 mhvtl kernel: mhvtl: CDB (2785) 16 bytes Aug 26 00:48:18 mhvtl kernel: b8 12 04 00 00 18 00 00 04 f0 00 00 00 00 00 00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: CDB (2785) (delay 805): b8 12 04 00 00 18 00 00 04 f0 00 00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: smc_read_element_status(): READ ELEMENT STATUS (2785) ** Aug 26 00:48:18 mhvtl vtllibrary[1585]: smc_read_element_status(): Element type(2) => Storage Aug 26 00:48:18 mhvtl vtllibrary[1585]: smc_read_element_status(): Starting Element Address: 1024 Aug 26 00:48:18 mhvtl vtllibrary[1585]: smc_read_element_status(): Number of Elements : 24 Aug 26 00:48:18 mhvtl vtllibrary[1585]: smc_read_element_status(): Allocation length : 1264 (0x04f0) Aug 26 00:48:18 mhvtl vtllibrary[1585]: smc_read_element_status(): Device ID: No, voltag: Yes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Query 24 elements starting from addr: 1024 of type: (2) Storage Aug 26 00:48:18 mhvtl vtllibrary[1585]: num_available_elements(): Determing 24 elements of type Storage starting at 1024, returning 24 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Available count: 24, type: 2 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_status_page_hdr(): Element Status Page Header: 02 80 00 34 00 00 04 e0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1024, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 1, max_count: 24, slot: 1024 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1025, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 2, max_count: 24, slot: 1025 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1026, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 3, max_count: 24, slot: 1026 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1027, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 4, max_count: 24, slot: 1027 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1028, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 5, max_count: 24, slot: 1028 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1029, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 6, max_count: 24, slot: 1029 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1030, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 7, max_count: 24, slot: 1030 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1031, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 8, max_count: 24, slot: 1031 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1032, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 9, max_count: 24, slot: 1032 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1033, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 10, max_count: 24, slot: 1033 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1034, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 11, max_count: 24, slot: 1034 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1035, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 12, max_count: 24, slot: 1035 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1036, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 13, max_count: 24, slot: 1036 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1037, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 14, max_count: 24, slot: 1037 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1038, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 15, max_count: 24, slot: 1038 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1039, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 16, max_count: 24, slot: 1039 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1040, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 17, max_count: 24, slot: 1040 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1041, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 18, max_count: 24, slot: 1041 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1042, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 19, max_count: 24, slot: 1042 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1043, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 20, max_count: 24, slot: 1043 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1044, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 21, max_count: 24, slot: 1044 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1045, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 22, max_count: 24, slot: 1045 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1046, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 23, max_count: 24, slot: 1046 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Slot location: 1047, DVCID: 0, VOLTAG: 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_descriptor(): Returning 52 bytes Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_page(): Count: 24, max_count: 24, slot: 1047 Aug 26 00:48:18 mhvtl vtllibrary[1585]: num_available_elements(): Determing 24 elements of type Storage starting at 1024, returning 24 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_status_data_hdr(): Building READ ELEMENT STATUS Header struct Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_status_data_hdr(): Starting slot: 1024, number of configured slots: 24 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_status_data_hdr(): Element Status Data HEADER: 04 00 00 18 00 00 04 f0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_status_data_hdr(): Decoded: Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_status_data_hdr(): First element Address : 1024 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_status_data_hdr(): Number elements reported : 24 Aug 26 00:48:18 mhvtl vtllibrary[1585]: fill_element_status_data_hdr(): Total byte count : 1264 (0x04f0) Aug 26 00:48:18 mhvtl vtllibrary[1585]: decode_element_status(): Element Status Data Aug 26 00:48:18 mhvtl vtllibrary[1585]: decode_element_status(): First element reported : 1024 Aug 26 00:48:18 mhvtl vtllibrary[1585]: decode_element_status(): Number of elements available : 24 Aug 26 00:48:18 mhvtl vtllibrary[1585]: decode_element_status(): Byte count of report : 1264 Aug 26 00:48:18 mhvtl vtllibrary[1585]: decode_element_status(): Element Status Page Aug 26 00:48:18 mhvtl vtllibrary[1585]: decode_element_status(): Element Type code : 2 (Storage) Aug 26 00:48:18 mhvtl vtllibrary[1585]: decode_element_status(): Primary Vol Tag : Yes Aug 26 00:48:18 mhvtl vtllibrary[1585]: decode_element_status(): Alt Vol Tag : No Aug 26 00:48:18 mhvtl vtllibrary[1585]: decode_element_status(): Element descriptor length : 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: decode_element_status(): Byte count of descriptor data: 1248 Aug 26 00:48:18 mhvtl vtllibrary[1585]: decode_element_status(): Element Descriptor(s) : Num of Elements 24 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 0, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1024 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x09 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 52, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1025 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x09 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 104, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1026 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x09 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 156, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1027 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x09 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 208, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1028 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x09 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 260, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1029 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x09 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 312, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1030 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x09 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 364, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1031 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x09 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 416, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1032 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x09 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 468, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1033 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x09 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 520, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1034 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x09 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 572, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1035 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x09 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 624, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1036 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x09 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 676, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1037 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x09 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 728, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1038 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x09 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 780, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1039 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x09 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 832, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1040 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x09 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 884, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1041 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x09 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 936, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1042 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x09 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 988, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1043 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x09 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 1 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 1040, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1044 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x08 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 1092, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1045 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x08 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 1144, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1046 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x08 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Debug.... i = 1196, len = 52 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Element Address : 1047 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Status : 0x08 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Medium type : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Voltag info... Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identification Descriptor Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Code Set : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier type : 0x00 Aug 26 00:48:18 mhvtl vtllibrary[1585]: dump_element_desc(): Identifier length : 0 Aug 26 00:48:18 mhvtl vtllibrary[1585]: smc_read_element_status(): Element count: 24, Elem byte count: 0x04f0, alloc_len: 1264 Aug 26 00:48:18 mhvtl vtllibrary[1585]: completeSCSICommand(): OP s/n: (2785), sz: 1264, sam_status: 0 Aug 26 00:48:23 mhvtl kernel: mhvtl: CDB (2786) 16 bytes Aug 26 00:48:23 mhvtl kernel: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Aug 26 00:48:23 mhvtl vtltape[1569]: CDB (2786) (delay 154405): 00 00 00 00 00 00 Aug 26 00:48:23 mhvtl vtltape[1569]: mkSenseBuf(): SENSE [Key/ASC/ASCQ] [02 3a 00] Aug 26 00:48:23 mhvtl vtltape[1569]: ssc_tur(): Test Unit Ready (2786) ** : No, No tape loaded Aug 26 00:48:25 mhvtl kernel: mhvtl: CDB (2787) 16 bytes Aug 26 00:48:25 mhvtl kernel: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Aug 26 00:48:25 mhvtl vtltape[1575]: CDB (2787) (delay 154805): 00 00 00 00 00 00 Aug 26 00:48:25 mhvtl vtltape[1575]: mkSenseBuf(): SENSE [Key/ASC/ASCQ] [02 3a 00] Aug 26 00:48:25 mhvtl vtltape[1575]: ssc_tur(): Test Unit Ready (2787) ** : No, No tape loaded Aug 26 00:48:27 mhvtl kernel: mhvtl: CDB (2788) 16 bytes Aug 26 00:48:27 mhvtl kernel: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Aug 26 00:48:27 mhvtl vtltape[1578]: CDB (2788) (delay 154805): 00 00 00 00 00 00 Aug 26 00:48:27 mhvtl vtltape[1578]: mkSenseBuf(): SENSE [Key/ASC/ASCQ] [02 3a 00] Aug 26 00:48:27 mhvtl vtltape[1578]: ssc_tur(): Test Unit Ready (2788) ** : No, No tape loaded Aug 26 00:48:29 mhvtl kernel: mhvtl: CDB (2789) 16 bytes Aug 26 00:48:29 mhvtl kernel: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 Aug 26 00:48:29 mhvtl vtltape[1581]: CDB (2789) (delay 154805): 00 00 00 00 00 00 Aug 26 00:48:29 mhvtl vtltape[1581]: mkSenseBuf(): SENSE [Key/ASC/ASCQ] [02 3a 00] Aug 26 00:48:29 mhvtl vtltape[1581]: ssc_tur(): Test Unit Ready (2789) ** : No, No tape loaded |
mayby you are missing the Product revision level on device at Drive: 11
|
Thanks for pointing out the omission but that hasn't resolved anything.
I can't claim to understand the mhvtl debug log but I'm wondering if what is being passed to TSM during the tape label process is correct. I assume TSM is enquiring from the VTL what media (via the barcode reader) is in the slots and, based upon the media types being returned, attempts to find a compatible drive to load and label them. Now TSM recognises it has LTO-3 drives as it has correctly picked up the formats the drive supports when they were defined to TSM. I suspect however that when it is getting info about the actual tapes it somehow thinks they are 3592 tapes, for which it doesn't have an appropriate drive. Is mhVTL somehow providing incorrect information to TSM or is TSM misunderstanding the information it is being given about the media? |
Administrator
|
At this point in development, there is no 'personality module' for the Libraries.
While you are able to adjust the SCSI Inquiry string, nothing is tweaked within the code to attempt to emulate the library type. If your backup software expects certain behaviour on different SCSI inquiry strings, you may find the backup software will be 'confused'. i.e. NetBackup: For mhVTL libraries to work with NetBackup, check the device_mappings.txt file and confirm the Inquiry strings match up with entries which are marked 'LIBRARY_SERIALIZATION=2' (not a 100% guarantee - but is a good starting point) e.g. [CHANGER:STK L700] <cut> LIBRARY_SERIALIZATION=2 <cut> Based on postings on this forum, TSM seems to work best if the library vendor is the same as the drive vendor. i.e. - For IBM drives - Use IBM library inquiry strings. - For STK (Now Oracle) drives, use STK library In an ideal world, the software should read the MODE SENSE which returns the library dynamics (slots, drives, co-ordinates etc) and dynamically handle the information. Unfortunately, library behaviour is often hard coded - or at least some pre-set idea about the configuration is. When the backup software sees 'brand X', it will often expect 'Y' behaviour. mhVTL only presents 'Z' behaviour at this point in time. So the trick is finding SCSI inquiry string that matches 'type Z behaviour' to your software.. Until I can find time to create library personality modules that is...
Regards from Australia
Mark Harvey |
Administrator
|
In reply to this post by Tiger22
Try using the following : update library IBM_VTL AutoLabel=yes RelabelScratch=yes CHECKIn LIBVolume IBM_VTL CHECKLabel=barcode SEARCH=Yes status=scratch devt=lto Regards, -nia |
In reply to this post by Mark Harvey
I did know that but somehow forgot about it in the midst of this new configuration. Oops. I have successfully modified the inquiry string now to emulate a TS3200 library (which these days uses LTO exclusively) and everything worked fine. It is strange that the Linux and Windows versions of the IBM library/tape drivers behave differently as I didn't have any problems using the 03584L22 inquiry string on Linux with LTO drives. Thanks for your help. |
In reply to this post by Tiger22
if you use 03584L22 library,tsm drives (windows) will search element number from 257,but the element of mhvtl driver begin at 1,so tsm say no 3592 driver .if use ts3200 library it search element number from 1 ,so tsm work ok.
hope this infomation help you. ps. i want to know how to edit mhvtl element number. |
Administrator
|
In 'smc.h' are the following entries
* * FIXME: The following should be dynamic (read from config file) * **** Danger Will Robinson!! ****: * START_DRIVE HAS TO start at slot 1 * The Order of Drives with lowest start, followed by Picker, followed * by MAP, finally Storage slots is IMPORTANT. - You have been warned. * Some of the logic in this source depends on it. */ #define START_DRIVE 0x0001 #define START_PICKER 0x02c0 #define START_MAP 0x0300 #define START_STORAGE 0x0400 Perhaps the 'START_DRIVE HAS TO start at slot 1' is a little misleading. The order is important - so the drives should come first, followed by the PICKER, followed by the MAP and finally the Storage Slots. Some of the original logic has been replaced with location independent code.. BUT I've never attempted to try swapping order of slot types.. Feel free to test and report back (I'd recommend creating a new post for any such reports). Patches are (as always) welcome - but so is testing and bug / feature requests.. Time constraints will dictate how soon such reported items are dealt with :)
Regards from Australia
Mark Harvey |
Administrator
|
If anybody does attempt to change the slot order - check vtllibrary.c
/* Linked list data needs to be built in slot order */ static void init_slot_info(struct lu_phy_attr *lu) { __init_slot_info(lu, DATA_TRANSFER); __init_slot_info(lu, MEDIUM_TRANSPORT); __init_slot_info(lu, MAP_ELEMENT); __init_slot_info(lu, STORAGE_ELEMENT); } The 'initialization' order will need to be swapped in the 'init_slot_info()' routine so the list of slots is initialised "lowest to highest".
Regards from Australia
Mark Harvey |
Administrator
|
In reply to this post by zy
This information is very helpful and explains why TSM has problems with some configurations - but no error messages from mhVTL side of things. Where/how did you determine that the first "search element number from 257" ? I reviewed the previous logs and information and can see no place where this information was collected / recorded. i.e. If I can reproduce/test, it saves a lot of time asking for other people to perform the tests on my behalf.
Regards from Australia
Mark Harvey |
In reply to this post by Tiger22
Sorry , i make a mistake.
when we use tsm lable wizards ,it can`t find driver`s element,and,it will let you select one library type (or manual input element),but you select `ibm ultrium 3584` the element will change to 257. the really reason of ERROR no 3593-type is 03584l22 library olny support the drive that like 3592-e05(or e06 e07 eu6 j1a,that i find it from IBM econfig). 03584l32 support drive ultrium 3 4 5 6(lto) so,if use 03584l22 library,you should use supported drive modle.(03592e05 or 03592j1) i had tested 03584l32 with lto5 drive,it works ok.(manual input element with 1) but tested 03584l22 with 3592 drive not yet ok,i think it`s tsm config problem. my config: DEFINE Library 358422lib libtype=scsi serial=70000050 AutoLabel=overwrite resetdrives=yes shared=yes wwn=70112233ab060000 DEFINE Devc 358422class library=358422lib devt=3592 format=drive mountret=4 DEFINE PATH TSMa 358422lib SRCType=SERVER DESTType=LIBRary Device=changer0 ONLine=y DEFINE drive 358422lib 358422drv1 serial=70000051 element=autod wwn=70112233ab060001 online=yes DEFINE PATH TSMA 358422drv1 SRCType=SERVER DESTType=drive LIBRary=358422lib Device=\\.\Tape0 ONLine=yes LABEL LIBVOLUME 358422lib SEARCH=yes labelsource=barcode CHECKIN=SCRATCH OVERWRITE=yes WAITTIME=0 the error: anr8302e i/o error on drive 358422drv1 ........ sorry,my english is very poor.hope this infomation is helpful. |
In reply to this post by Tiger22
Hello, guys !
I'm trying to use mhVTL on RHEL6 x86.64 + TSM 5.5.6 I tried to made as usual: 1) create library, 2) create path to library, 3) create drive? 4) create path to drive. The fist and second steps - are OK, informations obtained from library, I can see its status and so on. The third step - OK, created, but when I tried to create path to library's drives I faced with problem: DEFINE PATH oradb1 3573DRV1 SRCTYPE=SERVER DESTTYPE=DRIVE LIBRARY=3573LIB DEVICE=/dev/st0 ANR8420E DEFINE PATH: An I/O error occurred while accessing drive 3573DRV1. ANS8001I Return code 15. trace messages: Nov 12 21:46:46 oradb vtltape[1311]: CDB (24809) (delay 768005): 00 00 00 00 00 00 Nov 12 21:46:46 oradb vtltape[1311]: return_sense(): [Key/ASC/ASCQ] [02 3a 00] Nov 12 21:46:46 oradb vtltape[1311]: ssc_tur(): Test Unit Ready (24809) ** : No, No tape loaded Nov 12 21:46:46 oradb vtltape[1311]: completeSCSICommand(): s/n: (24809), sz: 0, sam_status: 2 [02 3a 00] another variant: DEFINE PATH oradb1 3573DRV1 SRCTYPE=SERVER DESTTYPE=DRIVE LIBRARY=3573LIB DEVICE=/dev/sg3 ANR8420E DEFINE PATH: An I/O error occurred while accessing drive 3573DRV1. ANS8001I Return code 15. trace message: Nov 12 21:47:15 oradb vtltape[1311]: CDB (24810) (delay 151205): 12 00 00 00 38 00 Nov 12 21:47:15 oradb vtltape[1311]: spc_inquiry(): INQUIRY ** (24810) Nov 12 21:47:15 oradb vtltape[1311]: completeSCSICommand(): OP s/n: (24810), sz: 64, sam_status: 0 configuration: lsscsi -g [0:0:0:0] disk VBOX HARDDISK 1.0 /dev/sda /dev/sg0 [0:0:1:0] disk VBOX HARDDISK 1.0 /dev/sdb /dev/sg1 [0:0:2:0] cd/dvd VBOX CD-ROM 1.0 /dev/sr0 /dev/sg2 [1:3:0:0] mediumx IBM 3573-TL 4.02 /dev/sch0 /dev/sg5 [1:3:0:1] tape IBM ULT3580-TD4 252D /dev/st0 /dev/sg3 [1:3:0:2] tape IBM ULT3580-TD4 252D /dev/st1 /dev/sg4 devices.conf: Library: 90 CHANNEL: 3 TARGET: 00 LUN: 00 Vendor identification: IBM Product identification: 3573-TL Product revision level: 4.02 Unit serial number: 70000090 NAA: 90:11:22:33:ab:3:00:00 Home directory: /opt/mhvtl/90 Backoff: 400 Drive: 91 CHANNEL: 3 TARGET: 00 LUN: 01 Library ID: 90 Slot: 01 Vendor identification: IBM Product identification: ULT3580-TD4 Product revision level: 252D Unit serial number: 70000091 NAA: 90:11:22:33:ab:3:00:01 Compression: factor 1 enabled 1 Compression type: lzo Backoff: 400 Drive: 92 CHANNEL: 3 TARGET: 00 LUN: 2 Library ID: 90 Slot: 2 Vendor identification: IBM Product identification: ULT3580-TD4 Product revision level: 252D Unit serial number: 70000092 NAA: 90:11:22:33:ab:3:00:2 Compression: factor 1 enabled 1 Compression type: lzo Backoff: 400 library_contents.90: Drive1: Drive2: Picker 1: MAP 1: MAP 2: MAP 3: MAP 4: MAP 5: Slot 1: M00001L4 Slot 2: M00002L4 Slot 3: M00003L4 Slot 4: M00004L4 Slot 5: M00005L4 Slot 6: M00006L4 Slot 7: M00007L4 Slot 8: M00008L4 Slot 9: M00009L4 Slot 10: M00000L4 Slot 11: Slot 12: Slot 13: Slot 14: Slot 15: Why I have the bad result unlike people who use Win2K for TSM server instance ? I'm trying to do step by step except device parameter device ( /dev/ instead ld(mt) ? |
Hi,guys! give me your config command for define drive You try this command: DEFINE drive 3573LIB 3573DRV1 serial=70000091 element=autod wwn=90112233ab030001 online=yes good luck!
|
Hello,
Thank you for response! Problem was solved by using scenario of user named danlthom. Here are the link to article : http://mhvtl-a-linux-virtual-tape-library.966029.n3.nabble.com/Details-on-testing-TSM-with-mhvtl-emulated-TS3500-and-LTO5-LTO4-td4025929.html Need to use IBM lin_tape driver for LTO drives with IBM library. |
Free forum by Nabble | Edit this page |