mhVTL: Centos 6.3/VirtualBox VM/cannot load a cartridge.

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

mhVTL: Centos 6.3/VirtualBox VM/cannot load a cartridge.

gizurieta
Hello,

After my mhVTL installation and GUI  setup, tried to load a tape:
[root@centos6301 ~]# lsscsi -g
[0:0:0:0]    cd/dvd  VBOX     CD-ROM           1.0   /dev/sr0   /dev/sg0
[1:0:0:0]    cd/dvd  VBOX     CD-ROM           1.0   /dev/sr1   /dev/sg1
[2:0:0:0]    disk    ATA      VBOX HARDDISK    1.0   /dev/sda   /dev/sg2
[8:3:0:0]    mediumx HP       MSL6000 Series   2.00  /dev/sch0  /dev/sg7
[8:3:0:1]    tape    HP       Ultrium 3-SCSI   N11G  /dev/st0   /dev/sg3
[8:3:0:2]    tape    HP       Ultrium 3-SCSI   N11G  /dev/st1   /dev/sg4
[8:3:0:3]    tape    HP       Ultrium 3-SCSI   N11G  /dev/st2   /dev/sg5
[8:3:0:4]    tape    HP       Ultrium 3-SCSI   N11G  /dev/st3   /dev/sg6

[root@centos6301 ~]# mtx -f /dev/sg7 load 1 0
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=Hardware Error
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 = 04
mtx: Request Sense: Additional Sense Qualifier = 03
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

I increased my verbose level to 3 and then restarted mhvtl:
# service mhvtl shutdown
# service mhvtl start

Here my /var/log/messages

=~=~=~=~=~=~=~=~=~=~=~= PuTTY log 2013.06.27 17:46:33 =~=~=~=~=~=~=~=~=~=~=~=
cat /var/log/messages
Jun 27 17:38:21 centos6301 kernel: scsi8 : mhvtl: version 0.18.11 [20110801-0], opts=0x0
Jun 27 17:38:21 centos6301 vtltape[5815]: readline(): VERSION: 5#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline(): #015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline(): Library: 90 CHANNEL: 3 TARGET: 00 LUN: 00#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Product identification: MSL6000 Series#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Product revision level: 2.00#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Unit serial number: 80000090#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  NAA: 90:11:22:33:ab:3:00:00#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline(): #015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline(): Drive: 91 CHANNEL: 3 TARGET: 00 LUN: 01#015
Jun 27 17:38:21 centos6301 vtltape[5815]: init_lu(): Looking for 91, Found drive 91
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Library ID: 90 Slot: 01#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Product identification: Ultrium 3-SCSI#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Product revision level: N11G#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Unit serial number: 80000091#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  NAA: 90:11:22:33:ab:3:00:01#015
Jun 27 17:38:21 centos6301 vtltape[5815]: init_lu(): Setting NAA: to 90:11:22:33:ab:03:00:01
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Compression: factor 1 enabled 1#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline(): #015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline(): Drive: 92 CHANNEL: 3 TARGET: 00 LUN: 2#015
Jun 27 17:38:21 centos6301 vtltape[5815]: init_lu(): Looking for 91, Found drive 92
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Library ID: 90 Slot: 2#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Product identification: Ultrium 3-SCSI#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Product revision level: N11G#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Unit serial number: 80000092#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  NAA: 90:11:22:33:ab:3:00:02#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Compression: factor 1 enabled 1#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline(): #015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline(): Drive: 93 CHANNEL: 3 TARGET: 00 LUN: 3#015
Jun 27 17:38:21 centos6301 vtltape[5815]: init_lu(): Looking for 91, Found drive 93
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Library ID: 90 Slot: 3#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Product identification: Ultrium 3-SCSI#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Product revision level: N11G#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Unit serial number: 80000093#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  NAA: 90:11:22:33:ab:3:00:03#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Compression: factor 1 enabled 1#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline(): #015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline(): Drive: 94 CHANNEL: 3 TARGET: 00 LUN: 4#015
Jun 27 17:38:21 centos6301 vtltape[5815]: init_lu(): Looking for 91, Found drive 94
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Library ID: 90 Slot: 4#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Product identification: Ultrium 3-SCSI#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Product revision level: N11G#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Unit serial number: 80000094#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  NAA: 90:11:22:33:ab:3:00:04#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline():  Compression: factor 1 enabled 1#015
Jun 27 17:38:21 centos6301 vtltape[5815]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtltape[5815]: init_default_ssc(): +++ Trace +++
Jun 27 17:38:21 centos6301 vtltape[5815]: personality_module_register(): default emulation
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x01, size 12
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_pcode(): Looking for: pcode 0x01, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_pcode(): Page/subpage code 0x01/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_mode_page(): pcodePointer: 0x12230c0 for mode page 0x01
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x02, size 12
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_pcode(): Looking for: pcode 0x02, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_pcode(): Page/subpage code 0x02/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_mode_page(): pcodePointer: 0x1223140 for mode page 0x02
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x0a, size 12
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_pcode(): Looking for: pcode 0x0a, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_pcode(): Page/subpage code 0x0a/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_mode_page(): pcodePointer: 0x12231c0 for mode page 0x0a
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x0f, size 16
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_pcode(): Looking for: pcode 0x0f, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_pcode(): Page/subpage code 0x0f/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_mode_page(): pcodePointer: 0x1223240 for mode page 0x0f
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x10, size 16
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_pcode(): Looking for: pcode 0x10, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_pcode(): Page/subpage code 0x10/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_mode_page(): pcodePointer: 0x12232c0 for mode page 0x10
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x11, size 16
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_pcode(): Looking for: pcode 0x11, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_pcode(): Page/subpage code 0x11/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_mode_page(): pcodePointer: 0x1223340 for mode page 0x11
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x1a, size 38
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_pcode(): Looking for: pcode 0x1a, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_pcode(): Page/subpage code 0x1a/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_mode_page(): pcodePointer: 0x12233c0 for mode page 0x1a
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x1c, size 12
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_pcode(): Looking for: pcode 0x1c, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_pcode(): Page/subpage code 0x1c/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_mode_page(): pcodePointer: 0x1223460 for mode page 0x1c
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x1d, size 32
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_pcode(): Looking for: pcode 0x1d, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_pcode(): Page/subpage code 0x1d/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_mode_page(): pcodePointer: 0x12234e0 for mode page 0x1d
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_log_page(): 0x6245e0 : Allocate log page 0x02, size 96
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_log_pg(): Looking for: log page 0x02
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_log_pg(): Log page 0x02 not found
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_log_page(): log page pointer: 0x1223570 for log page 0x02
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_log_page(): 0x6245e0 : Allocate log page 0x03, size 96
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_log_pg(): Looking for: log page 0x03
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_log_pg(): Log page 0x03 not found
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_log_page(): log page pointer: 0x1223610 for log page 0x03
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_log_page(): 0x6245e0 : Allocate log page 0x0c, size 128
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_log_pg(): Looking for: log page 0x0c
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_log_pg(): Log page 0x0c not found
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_log_page(): log page pointer: 0x12236b0 for log page 0x0c
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_log_page(): 0x6245e0 : Allocate log page 0x0d, size 10
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_log_pg(): Looking for: log page 0x0d
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_log_pg(): Log page 0x0d not found
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_log_page(): log page pointer: 0x1223770 for log page 0x0d
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_log_page(): 0x6245e0 : Allocate log page 0x2e, size 324
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_log_pg(): Looking for: log page 0x2e
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_log_pg(): Log page 0x2e not found
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_log_page(): log page pointer: 0x12237c0 for log page 0x2e
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_log_page(): 0x6245e0 : Allocate log page 0x30, size 96
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_log_pg(): Looking for: log page 0x30
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_log_pg(): Log page 0x30 not found
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_log_page(): log page pointer: 0x1223940 for log page 0x30
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_log_page(): 0x6245e0 : Allocate log page 0x31, size 36
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_log_pg(): Looking for: log page 0x31
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_log_pg(): Log page 0x31 not found
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_log_page(): log page pointer: 0x12239e0 for log page 0x31
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_log_page(): 0x6245e0 : Allocate log page 0x32, size 80
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_log_pg(): Looking for: log page 0x32
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_log_pg(): Log page 0x32 not found
Jun 27 17:38:21 centos6301 vtltape[5815]: alloc_log_page(): log page pointer: 0x1225c10 for log page 0x32
Jun 27 17:38:21 centos6301 vtltape[5815]: set_default_comp(): +++ Trace +++
Jun 27 17:38:21 centos6301 vtltape[5815]: set_compression_mode_pg(): *** Trace ***
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_pcode(): Looking for: pcode 0x0f, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_pcode(): Found "Data Compression" -> pcode 0x0f, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5815]: set_compression_mode_pg(): l: 0x6245d0, m: 0x1223200, m->pcodePointer: 0x1223240
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_pcode(): Looking for: pcode 0x10, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5815]: lookup_pcode(): Found "Device Configuration" -> pcode 0x10, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5815]: set_compression_mode_pg(): l: 0x6245d0, m: 0x1223280, m->pcodePointer: 0x12232c0
Jun 27 17:38:21 centos6301 vtltape[5815]: chrdev_create(): Major number: 248, minor number: 91
Jun 27 17:38:21 centos6301 vtltape[5815]: chrdev_create(): mknod(/dev/mhvtl91, 20660, major: 248 minor: 91
Jun 27 17:38:21 centos6301 vtltape[5815]: add_lu(): Child PID 5816 starting logical unit [3:0:1]
Jun 27 17:38:21 centos6301 vtltape[5815]: chrdev_chown(): chown(/dev/mhvtl91, 499, 498)
Jun 27 17:38:21 centos6301 vtltape[5815]: main(): Running as vtl, uid: 499
Jun 27 17:38:21 centos6301 vtltape[5815]: main(): Successfully started daemon: PID 5817
Jun 27 17:38:21 centos6301 vtltape[5817]: main(): Started vtltape: version 1.3.1.el6.elrepo, verbose log lvl: 3, lu [3:0:1]
Jun 27 17:38:21 centos6301 vtltape[5817]: main(): Size of buffer is 2097152
Jun 27 17:38:21 centos6301 vtltape[5817]: oom_adjust(): Can't open oom-killer's pardon /proc/5817/oom_adj, Permission denied
Jun 27 17:38:21 centos6301 vtltape[5818]: readline(): VERSION: 5#015
Jun 27 17:38:21 centos6301 vtltape[5817]: mhvtl_shared_mem(): shm count is: 0
Jun 27 17:38:21 centos6301 vtltape[5817]: mhvtl_shared_mem(): shm count now: 1
Jun 27 17:38:21 centos6301 vtltape[5817]: main(): Cleaning up after add_lu child pid: 5816
Jun 27 17:38:21 centos6301 vtltape[5818]: readline(): #015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline(): Library: 90 CHANNEL: 3 TARGET: 00 LUN: 00#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Product identification: MSL6000 Series#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Product revision level: 2.00#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Unit serial number: 80000090#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  NAA: 90:11:22:33:ab:3:00:00#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline(): #015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline(): Drive: 91 CHANNEL: 3 TARGET: 00 LUN: 01#015
Jun 27 17:38:21 centos6301 vtltape[5818]: init_lu(): Looking for 92, Found drive 91
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Library ID: 90 Slot: 01#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Product identification: Ultrium 3-SCSI#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Product revision level: N11G#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Unit serial number: 80000091#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  NAA: 90:11:22:33:ab:3:00:01#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Compression: factor 1 enabled 1#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline(): #015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline(): Drive: 92 CHANNEL: 3 TARGET: 00 LUN: 2#015
Jun 27 17:38:21 centos6301 vtltape[5818]: init_lu(): Looking for 92, Found drive 92
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Library ID: 90 Slot: 2#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Product identification: Ultrium 3-SCSI#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Product revision level: N11G#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Unit serial number: 80000092#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  NAA: 90:11:22:33:ab:3:00:02#015
Jun 27 17:38:21 centos6301 vtltape[5818]: init_lu(): Setting NAA: to 90:11:22:33:ab:03:00:02
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Compression: factor 1 enabled 1#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline(): #015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline(): Drive: 93 CHANNEL: 3 TARGET: 00 LUN: 3#015
Jun 27 17:38:21 centos6301 vtltape[5818]: init_lu(): Looking for 92, Found drive 93
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Library ID: 90 Slot: 3#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Product identification: Ultrium 3-SCSI#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Product revision level: N11G#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Unit serial number: 80000093#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  NAA: 90:11:22:33:ab:3:00:03#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Compression: factor 1 enabled 1#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline(): #015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline(): Drive: 94 CHANNEL: 3 TARGET: 00 LUN: 4#015
Jun 27 17:38:21 centos6301 vtltape[5818]: init_lu(): Looking for 92, Found drive 94
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Library ID: 90 Slot: 4#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Product identification: Ultrium 3-SCSI#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Product revision level: N11G#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Unit serial number: 80000094#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  NAA: 90:11:22:33:ab:3:00:04#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline():  Compression: factor 1 enabled 1#015
Jun 27 17:38:21 centos6301 vtltape[5818]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtltape[5818]: init_default_ssc(): +++ Trace +++
Jun 27 17:38:21 centos6301 vtltape[5818]: personality_module_register(): default emulation
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x01, size 12
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_pcode(): Looking for: pcode 0x01, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_pcode(): Page/subpage code 0x01/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_mode_page(): pcodePointer: 0xb170c0 for mode page 0x01
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x02, size 12
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_pcode(): Looking for: pcode 0x02, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_pcode(): Page/subpage code 0x02/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_mode_page(): pcodePointer: 0xb17140 for mode page 0x02
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x0a, size 12
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_pcode(): Looking for: pcode 0x0a, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_pcode(): Page/subpage code 0x0a/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_mode_page(): pcodePointer: 0xb171c0 for mode page 0x0a
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x0f, size 16
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_pcode(): Looking for: pcode 0x0f, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_pcode(): Page/subpage code 0x0f/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_mode_page(): pcodePointer: 0xb17240 for mode page 0x0f
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x10, size 16
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_pcode(): Looking for: pcode 0x10, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_pcode(): Page/subpage code 0x10/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_mode_page(): pcodePointer: 0xb172c0 for mode page 0x10
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x11, size 16
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_pcode(): Looking for: pcode 0x11, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_pcode(): Page/subpage code 0x11/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_mode_page(): pcodePointer: 0xb17340 for mode page 0x11
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x1a, size 38
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_pcode(): Looking for: pcode 0x1a, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_pcode(): Page/subpage code 0x1a/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_mode_page(): pcodePointer: 0xb173c0 for mode page 0x1a
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x1c, size 12
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_pcode(): Looking for: pcode 0x1c, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_pcode(): Page/subpage code 0x1c/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_mode_page(): pcodePointer: 0xb17460 for mode page 0x1c
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x1d, size 32
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_pcode(): Looking for: pcode 0x1d, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_pcode(): Page/subpage code 0x1d/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_mode_page(): pcodePointer: 0xb174e0 for mode page 0x1d
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_log_page(): 0x6245e0 : Allocate log page 0x02, size 96
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_log_pg(): Looking for: log page 0x02
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_log_pg(): Log page 0x02 not found
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_log_page(): log page pointer: 0xb17570 for log page 0x02
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_log_page(): 0x6245e0 : Allocate log page 0x03, size 96
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_log_pg(): Looking for: log page 0x03
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_log_pg(): Log page 0x03 not found
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_log_page(): log page pointer: 0xb17610 for log page 0x03
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_log_page(): 0x6245e0 : Allocate log page 0x0c, size 128
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_log_pg(): Looking for: log page 0x0c
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_log_pg(): Log page 0x0c not found
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_log_page(): log page pointer: 0xb176b0 for log page 0x0c
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_log_page(): 0x6245e0 : Allocate log page 0x0d, size 10
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_log_pg(): Looking for: log page 0x0d
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_log_pg(): Log page 0x0d not found
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_log_page(): log page pointer: 0xb17770 for log page 0x0d
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_log_page(): 0x6245e0 : Allocate log page 0x2e, size 324
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_log_pg(): Looking for: log page 0x2e
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_log_pg(): Log page 0x2e not found
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_log_page(): log page pointer: 0xb177c0 for log page 0x2e
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_log_page(): 0x6245e0 : Allocate log page 0x30, size 96
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_log_pg(): Looking for: log page 0x30
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_log_pg(): Log page 0x30 not found
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_log_page(): log page pointer: 0xb17940 for log page 0x30
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_log_page(): 0x6245e0 : Allocate log page 0x31, size 36
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_log_pg(): Looking for: log page 0x31
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_log_pg(): Log page 0x31 not found
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_log_page(): log page pointer: 0xb179e0 for log page 0x31
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_log_page(): 0x6245e0 : Allocate log page 0x32, size 80
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_log_pg(): Looking for: log page 0x32
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_log_pg(): Log page 0x32 not found
Jun 27 17:38:21 centos6301 vtltape[5818]: alloc_log_page(): log page pointer: 0xb19c10 for log page 0x32
Jun 27 17:38:21 centos6301 vtltape[5818]: set_default_comp(): +++ Trace +++
Jun 27 17:38:21 centos6301 vtltape[5818]: set_compression_mode_pg(): *** Trace ***
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_pcode(): Looking for: pcode 0x0f, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_pcode(): Found "Data Compression" -> pcode 0x0f, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5818]: set_compression_mode_pg(): l: 0x6245d0, m: 0xb17200, m->pcodePointer: 0xb17240
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_pcode(): Looking for: pcode 0x10, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5818]: lookup_pcode(): Found "Device Configuration" -> pcode 0x10, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5818]: set_compression_mode_pg(): l: 0x6245d0, m: 0xb17280, m->pcodePointer: 0xb172c0
Jun 27 17:38:21 centos6301 vtltape[5818]: chrdev_create(): Major number: 248, minor number: 92
Jun 27 17:38:21 centos6301 vtltape[5818]: chrdev_create(): mknod(/dev/mhvtl92, 20660, major: 248 minor: 92
Jun 27 17:38:21 centos6301 vtltape[5818]: add_lu(): Child PID 5819 starting logical unit [3:0:2]
Jun 27 17:38:21 centos6301 vtltape[5818]: chrdev_chown(): chown(/dev/mhvtl92, 499, 498)
Jun 27 17:38:21 centos6301 vtltape[5818]: main(): Running as vtl, uid: 499
Jun 27 17:38:21 centos6301 vtltape[5818]: main(): Successfully started daemon: PID 5820
Jun 27 17:38:21 centos6301 vtltape[5820]: main(): Started vtltape: version 1.3.1.el6.elrepo, verbose log lvl: 3, lu [3:0:2]
Jun 27 17:38:21 centos6301 vtltape[5820]: main(): Size of buffer is 2097152
Jun 27 17:38:21 centos6301 vtltape[5820]: oom_adjust(): Can't open oom-killer's pardon /proc/5820/oom_adj, Permission denied
Jun 27 17:38:21 centos6301 vtltape[5820]: mhvtl_shared_mem(): shm count is: 1
Jun 27 17:38:21 centos6301 vtltape[5820]: mhvtl_shared_mem(): shm count now: 2
Jun 27 17:38:21 centos6301 vtltape[5820]: main(): Cleaning up after add_lu child pid: 5819
Jun 27 17:38:21 centos6301 vtltape[5821]: readline(): VERSION: 5#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline(): #015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline(): Library: 90 CHANNEL: 3 TARGET: 00 LUN: 00#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Product identification: MSL6000 Series#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Product revision level: 2.00#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Unit serial number: 80000090#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  NAA: 90:11:22:33:ab:3:00:00#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline(): #015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline(): Drive: 91 CHANNEL: 3 TARGET: 00 LUN: 01#015
Jun 27 17:38:21 centos6301 vtltape[5821]: init_lu(): Looking for 93, Found drive 91
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Library ID: 90 Slot: 01#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Product identification: Ultrium 3-SCSI#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Product revision level: N11G#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Unit serial number: 80000091#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  NAA: 90:11:22:33:ab:3:00:01#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Compression: factor 1 enabled 1#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline(): #015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline(): Drive: 92 CHANNEL: 3 TARGET: 00 LUN: 2#015
Jun 27 17:38:21 centos6301 vtltape[5821]: init_lu(): Looking for 93, Found drive 92
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Library ID: 90 Slot: 2#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Product identification: Ultrium 3-SCSI#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Product revision level: N11G#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Unit serial number: 80000092#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  NAA: 90:11:22:33:ab:3:00:02#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Compression: factor 1 enabled 1#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline(): #015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline(): Drive: 93 CHANNEL: 3 TARGET: 00 LUN: 3#015
Jun 27 17:38:21 centos6301 vtltape[5821]: init_lu(): Looking for 93, Found drive 93
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Library ID: 90 Slot: 3#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Product identification: Ultrium 3-SCSI#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Product revision level: N11G#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Unit serial number: 80000093#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  NAA: 90:11:22:33:ab:3:00:03#015
Jun 27 17:38:21 centos6301 vtltape[5821]: init_lu(): Setting NAA: to 90:11:22:33:ab:03:00:03
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Compression: factor 1 enabled 1#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline(): #015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline(): Drive: 94 CHANNEL: 3 TARGET: 00 LUN: 4#015
Jun 27 17:38:21 centos6301 vtltape[5821]: init_lu(): Looking for 93, Found drive 94
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Library ID: 90 Slot: 4#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Product identification: Ultrium 3-SCSI#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Product revision level: N11G#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Unit serial number: 80000094#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  NAA: 90:11:22:33:ab:3:00:04#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline():  Compression: factor 1 enabled 1#015
Jun 27 17:38:21 centos6301 vtltape[5821]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtltape[5821]: init_default_ssc(): +++ Trace +++
Jun 27 17:38:21 centos6301 vtltape[5821]: personality_module_register(): default emulation
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x01, size 12
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_pcode(): Looking for: pcode 0x01, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_pcode(): Page/subpage code 0x01/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_mode_page(): pcodePointer: 0x1f6d0c0 for mode page 0x01
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x02, size 12
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_pcode(): Looking for: pcode 0x02, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_pcode(): Page/subpage code 0x02/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_mode_page(): pcodePointer: 0x1f6d140 for mode page 0x02
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x0a, size 12
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_pcode(): Looking for: pcode 0x0a, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_pcode(): Page/subpage code 0x0a/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_mode_page(): pcodePointer: 0x1f6d1c0 for mode page 0x0a
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x0f, size 16
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_pcode(): Looking for: pcode 0x0f, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_pcode(): Page/subpage code 0x0f/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_mode_page(): pcodePointer: 0x1f6d240 for mode page 0x0f
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x10, size 16
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_pcode(): Looking for: pcode 0x10, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_pcode(): Page/subpage code 0x10/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_mode_page(): pcodePointer: 0x1f6d2c0 for mode page 0x10
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x11, size 16
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_pcode(): Looking for: pcode 0x11, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_pcode(): Page/subpage code 0x11/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_mode_page(): pcodePointer: 0x1f6d340 for mode page 0x11
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x1a, size 38
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_pcode(): Looking for: pcode 0x1a, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_pcode(): Page/subpage code 0x1a/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_mode_page(): pcodePointer: 0x1f6d3c0 for mode page 0x1a
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x1c, size 12
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_pcode(): Looking for: pcode 0x1c, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_pcode(): Page/subpage code 0x1c/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_mode_page(): pcodePointer: 0x1f6d460 for mode page 0x1c
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x1d, size 32
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_pcode(): Looking for: pcode 0x1d, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_pcode(): Page/subpage code 0x1d/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_mode_page(): pcodePointer: 0x1f6d4e0 for mode page 0x1d
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_log_page(): 0x6245e0 : Allocate log page 0x02, size 96
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_log_pg(): Looking for: log page 0x02
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_log_pg(): Log page 0x02 not found
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_log_page(): log page pointer: 0x1f6d570 for log page 0x02
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_log_page(): 0x6245e0 : Allocate log page 0x03, size 96
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_log_pg(): Looking for: log page 0x03
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_log_pg(): Log page 0x03 not found
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_log_page(): log page pointer: 0x1f6d610 for log page 0x03
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_log_page(): 0x6245e0 : Allocate log page 0x0c, size 128
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_log_pg(): Looking for: log page 0x0c
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_log_pg(): Log page 0x0c not found
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_log_page(): log page pointer: 0x1f6d6b0 for log page 0x0c
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_log_page(): 0x6245e0 : Allocate log page 0x0d, size 10
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_log_pg(): Looking for: log page 0x0d
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_log_pg(): Log page 0x0d not found
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_log_page(): log page pointer: 0x1f6d770 for log page 0x0d
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_log_page(): 0x6245e0 : Allocate log page 0x2e, size 324
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_log_pg(): Looking for: log page 0x2e
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_log_pg(): Log page 0x2e not found
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_log_page(): log page pointer: 0x1f6d7c0 for log page 0x2e
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_log_page(): 0x6245e0 : Allocate log page 0x30, size 96
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_log_pg(): Looking for: log page 0x30
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_log_pg(): Log page 0x30 not found
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_log_page(): log page pointer: 0x1f6d940 for log page 0x30
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_log_page(): 0x6245e0 : Allocate log page 0x31, size 36
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_log_pg(): Looking for: log page 0x31
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_log_pg(): Log page 0x31 not found
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_log_page(): log page pointer: 0x1f6d9e0 for log page 0x31
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_log_page(): 0x6245e0 : Allocate log page 0x32, size 80
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_log_pg(): Looking for: log page 0x32
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_log_pg(): Log page 0x32 not found
Jun 27 17:38:21 centos6301 vtltape[5821]: alloc_log_page(): log page pointer: 0x1f6fc10 for log page 0x32
Jun 27 17:38:21 centos6301 vtltape[5821]: set_default_comp(): +++ Trace +++
Jun 27 17:38:21 centos6301 vtltape[5821]: set_compression_mode_pg(): *** Trace ***
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_pcode(): Looking for: pcode 0x0f, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_pcode(): Found "Data Compression" -> pcode 0x0f, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5821]: set_compression_mode_pg(): l: 0x6245d0, m: 0x1f6d200, m->pcodePointer: 0x1f6d240
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_pcode(): Looking for: pcode 0x10, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5821]: lookup_pcode(): Found "Device Configuration" -> pcode 0x10, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5821]: set_compression_mode_pg(): l: 0x6245d0, m: 0x1f6d280, m->pcodePointer: 0x1f6d2c0
Jun 27 17:38:21 centos6301 vtltape[5821]: chrdev_create(): Major number: 248, minor number: 93
Jun 27 17:38:21 centos6301 vtltape[5821]: chrdev_create(): mknod(/dev/mhvtl93, 20660, major: 248 minor: 93
Jun 27 17:38:21 centos6301 vtltape[5821]: add_lu(): Child PID 5822 starting logical unit [3:0:3]
Jun 27 17:38:21 centos6301 vtltape[5821]: chrdev_chown(): chown(/dev/mhvtl93, 499, 498)
Jun 27 17:38:21 centos6301 vtltape[5821]: main(): Running as vtl, uid: 499
Jun 27 17:38:21 centos6301 vtltape[5821]: main(): Successfully started daemon: PID 5823
Jun 27 17:38:21 centos6301 vtltape[5824]: readline(): VERSION: 5#015
Jun 27 17:38:21 centos6301 vtltape[5823]: main(): Started vtltape: version 1.3.1.el6.elrepo, verbose log lvl: 3, lu [3:0:3]
Jun 27 17:38:21 centos6301 vtltape[5823]: main(): Size of buffer is 2097152
Jun 27 17:38:21 centos6301 vtltape[5823]: oom_adjust(): Can't open oom-killer's pardon /proc/5823/oom_adj, Permission denied
Jun 27 17:38:21 centos6301 vtltape[5823]: mhvtl_shared_mem(): shm count is: 2
Jun 27 17:38:21 centos6301 vtltape[5823]: mhvtl_shared_mem(): shm count now: 3
Jun 27 17:38:21 centos6301 vtltape[5823]: main(): Cleaning up after add_lu child pid: 5822
Jun 27 17:38:21 centos6301 vtltape[5824]: readline(): #015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline(): Library: 90 CHANNEL: 3 TARGET: 00 LUN: 00#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Product identification: MSL6000 Series#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Product revision level: 2.00#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Unit serial number: 80000090#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  NAA: 90:11:22:33:ab:3:00:00#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline(): #015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline(): Drive: 91 CHANNEL: 3 TARGET: 00 LUN: 01#015
Jun 27 17:38:21 centos6301 vtltape[5824]: init_lu(): Looking for 94, Found drive 91
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Library ID: 90 Slot: 01#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Product identification: Ultrium 3-SCSI#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Product revision level: N11G#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Unit serial number: 80000091#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  NAA: 90:11:22:33:ab:3:00:01#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Compression: factor 1 enabled 1#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline(): #015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline(): Drive: 92 CHANNEL: 3 TARGET: 00 LUN: 2#015
Jun 27 17:38:21 centos6301 vtltape[5824]: init_lu(): Looking for 94, Found drive 92
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Library ID: 90 Slot: 2#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Product identification: Ultrium 3-SCSI#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Product revision level: N11G#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Unit serial number: 80000092#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  NAA: 90:11:22:33:ab:3:00:02#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Compression: factor 1 enabled 1#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline(): #015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline(): Drive: 93 CHANNEL: 3 TARGET: 00 LUN: 3#015
Jun 27 17:38:21 centos6301 vtltape[5824]: init_lu(): Looking for 94, Found drive 93
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Library ID: 90 Slot: 3#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Product identification: Ultrium 3-SCSI#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Product revision level: N11G#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Unit serial number: 80000093#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  NAA: 90:11:22:33:ab:3:00:03#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Compression: factor 1 enabled 1#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline(): #015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline(): Drive: 94 CHANNEL: 3 TARGET: 00 LUN: 4#015
Jun 27 17:38:21 centos6301 vtltape[5824]: init_lu(): Looking for 94, Found drive 94
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Library ID: 90 Slot: 4#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Product identification: Ultrium 3-SCSI#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Product revision level: N11G#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Unit serial number: 80000094#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  NAA: 90:11:22:33:ab:3:00:04#015
Jun 27 17:38:21 centos6301 vtltape[5824]: init_lu(): Setting NAA: to 90:11:22:33:ab:03:00:04
Jun 27 17:38:21 centos6301 vtltape[5824]: readline():  Compression: factor 1 enabled 1#015
Jun 27 17:38:21 centos6301 vtltape[5824]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtltape[5824]: init_default_ssc(): +++ Trace +++
Jun 27 17:38:21 centos6301 vtltape[5824]: personality_module_register(): default emulation
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x01, size 12
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_pcode(): Looking for: pcode 0x01, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_pcode(): Page/subpage code 0x01/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_mode_page(): pcodePointer: 0x198c0c0 for mode page 0x01
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x02, size 12
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_pcode(): Looking for: pcode 0x02, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_pcode(): Page/subpage code 0x02/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_mode_page(): pcodePointer: 0x198c140 for mode page 0x02
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x0a, size 12
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_pcode(): Looking for: pcode 0x0a, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_pcode(): Page/subpage code 0x0a/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_mode_page(): pcodePointer: 0x198c1c0 for mode page 0x0a
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x0f, size 16
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_pcode(): Looking for: pcode 0x0f, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_pcode(): Page/subpage code 0x0f/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_mode_page(): pcodePointer: 0x198c240 for mode page 0x0f
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x10, size 16
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_pcode(): Looking for: pcode 0x10, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_pcode(): Page/subpage code 0x10/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_mode_page(): pcodePointer: 0x198c2c0 for mode page 0x10
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x11, size 16
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_pcode(): Looking for: pcode 0x11, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_pcode(): Page/subpage code 0x11/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_mode_page(): pcodePointer: 0x198c340 for mode page 0x11
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x1a, size 38
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_pcode(): Looking for: pcode 0x1a, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_pcode(): Page/subpage code 0x1a/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_mode_page(): pcodePointer: 0x198c3c0 for mode page 0x1a
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x1c, size 12
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_pcode(): Looking for: pcode 0x1c, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_pcode(): Page/subpage code 0x1c/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_mode_page(): pcodePointer: 0x198c460 for mode page 0x1c
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_mode_page(): 0x6245d0 : Allocate mode page 0x1d, size 32
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_pcode(): Looking for: pcode 0x1d, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_pcode(): Page/subpage code 0x1d/0x00 not found
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_mode_page(): pcodePointer: 0x198c4e0 for mode page 0x1d
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_log_page(): 0x6245e0 : Allocate log page 0x02, size 96
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_log_pg(): Looking for: log page 0x02
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_log_pg(): Log page 0x02 not found
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_log_page(): log page pointer: 0x198c570 for log page 0x02
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_log_page(): 0x6245e0 : Allocate log page 0x03, size 96
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_log_pg(): Looking for: log page 0x03
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_log_pg(): Log page 0x03 not found
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_log_page(): log page pointer: 0x198c610 for log page 0x03
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_log_page(): 0x6245e0 : Allocate log page 0x0c, size 128
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_log_pg(): Looking for: log page 0x0c
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_log_pg(): Log page 0x0c not found
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_log_page(): log page pointer: 0x198c6b0 for log page 0x0c
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_log_page(): 0x6245e0 : Allocate log page 0x0d, size 10
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_log_pg(): Looking for: log page 0x0d
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_log_pg(): Log page 0x0d not found
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_log_page(): log page pointer: 0x198c770 for log page 0x0d
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_log_page(): 0x6245e0 : Allocate log page 0x2e, size 324
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_log_pg(): Looking for: log page 0x2e
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_log_pg(): Log page 0x2e not found
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_log_page(): log page pointer: 0x198c7c0 for log page 0x2e
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_log_page(): 0x6245e0 : Allocate log page 0x30, size 96
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_log_pg(): Looking for: log page 0x30
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_log_pg(): Log page 0x30 not found
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_log_page(): log page pointer: 0x198c940 for log page 0x30
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_log_page(): 0x6245e0 : Allocate log page 0x31, size 36
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_log_pg(): Looking for: log page 0x31
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_log_pg(): Log page 0x31 not found
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_log_page(): log page pointer: 0x198c9e0 for log page 0x31
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_log_page(): 0x6245e0 : Allocate log page 0x32, size 80
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_log_pg(): Looking for: log page 0x32
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_log_pg(): Log page 0x32 not found
Jun 27 17:38:21 centos6301 vtltape[5824]: alloc_log_page(): log page pointer: 0x198ec10 for log page 0x32
Jun 27 17:38:21 centos6301 vtltape[5824]: set_default_comp(): +++ Trace +++
Jun 27 17:38:21 centos6301 vtltape[5824]: set_compression_mode_pg(): *** Trace ***
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_pcode(): Looking for: pcode 0x0f, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_pcode(): Found "Data Compression" -> pcode 0x0f, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5824]: set_compression_mode_pg(): l: 0x6245d0, m: 0x198c200, m->pcodePointer: 0x198c240
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_pcode(): Looking for: pcode 0x10, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5824]: lookup_pcode(): Found "Device Configuration" -> pcode 0x10, subpcode 0x00
Jun 27 17:38:21 centos6301 vtltape[5824]: set_compression_mode_pg(): l: 0x6245d0, m: 0x198c280, m->pcodePointer: 0x198c2c0
Jun 27 17:38:21 centos6301 vtltape[5824]: chrdev_create(): Major number: 248, minor number: 94
Jun 27 17:38:21 centos6301 vtltape[5824]: chrdev_create(): mknod(/dev/mhvtl94, 20660, major: 248 minor: 94
Jun 27 17:38:21 centos6301 vtltape[5824]: add_lu(): Child PID 5825 starting logical unit [3:0:4]
Jun 27 17:38:21 centos6301 vtltape[5824]: chrdev_chown(): chown(/dev/mhvtl94, 499, 498)
Jun 27 17:38:21 centos6301 vtltape[5824]: main(): Running as vtl, uid: 499
Jun 27 17:38:21 centos6301 vtltape[5824]: main(): Successfully started daemon: PID 5826
Jun 27 17:38:21 centos6301 vtltape[5826]: main(): Started vtltape: version 1.3.1.el6.elrepo, verbose log lvl: 3, lu [3:0:4]
Jun 27 17:38:21 centos6301 vtltape[5826]: main(): Size of buffer is 2097152
Jun 27 17:38:21 centos6301 vtltape[5826]: oom_adjust(): Can't open oom-killer's pardon /proc/5826/oom_adj, Permission denied
Jun 27 17:38:21 centos6301 vtltape[5826]: mhvtl_shared_mem(): shm count is: 3
Jun 27 17:38:21 centos6301 vtltape[5826]: mhvtl_shared_mem(): shm count now: 4
Jun 27 17:38:21 centos6301 vtltape[5826]: main(): Cleaning up after add_lu child pid: 5825
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): VERSION: 5#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): #015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Library: 90 CHANNEL: 3 TARGET: 00 LUN: 00#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_lu(): Found Library 90, looking for 90
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Product identification: MSL6000 Series#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Product revision level: 2.00#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Unit serial number: 80000090#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  NAA: 90:11:22:33:ab:3:00:00#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_lu(): Setting NAA: to 90:11:22:33:ab:03:00:00
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): #015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Drive: 91 CHANNEL: 3 TARGET: 00 LUN: 01#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Library ID: 90 Slot: 01#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Product identification: Ultrium 3-SCSI#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Product revision level: N11G#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Unit serial number: 80000091#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  NAA: 90:11:22:33:ab:3:00:01#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Compression: factor 1 enabled 1#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): #015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Drive: 92 CHANNEL: 3 TARGET: 00 LUN: 2#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Library ID: 90 Slot: 2#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Product identification: Ultrium 3-SCSI#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Product revision level: N11G#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Unit serial number: 80000092#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  NAA: 90:11:22:33:ab:3:00:02#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Compression: factor 1 enabled 1#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): #015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Drive: 93 CHANNEL: 3 TARGET: 00 LUN: 3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Library ID: 90 Slot: 3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Product identification: Ultrium 3-SCSI#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Product revision level: N11G#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Unit serial number: 80000093#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  NAA: 90:11:22:33:ab:3:00:03#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Compression: factor 1 enabled 1#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): #015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Drive: 94 CHANNEL: 3 TARGET: 00 LUN: 4#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Library ID: 90 Slot: 4#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Vendor identification: HP#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Product identification: Ultrium 3-SCSI#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Product revision level: N11G#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Unit serial number: 80000094#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  NAA: 90:11:22:33:ab:3:00:04#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline():  Compression: factor 1 enabled 1#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Drive1:#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Drive2:#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Drive3:#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Drive4:#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): #015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): MAP 1:#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): MAP 2:#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): MAP 3:#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): MAP 4:#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): #015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Picker 1:#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): #015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 1: HPS001L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS001L3 in slot 1
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS001L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 2: HPS002L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS002L3 in slot 2
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS002L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 3: HPS003L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS003L3 in slot 3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS003L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 4: HPS004L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS004L3 in slot 4
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS004L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 5: HPS005L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS005L3 in slot 5
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS005L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 6: HPS006L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS006L3 in slot 6
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS006L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 7: HPS007L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS007L3 in slot 7
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS007L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 8: HPS008L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS008L3 in slot 8
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS008L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 9: HPS009L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS009L3 in slot 9
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS009L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 10: HPS010L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS010L3 in slot 10
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS010L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 11: HPS011L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS011L3 in slot 11
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS011L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 12: HPS012L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS012L3 in slot 12
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS012L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 13: HPS013L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS013L3 in slot 13
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS013L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 14: HPS014L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS014L3 in slot 14
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS014L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 15: HPS015L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS015L3 in slot 15
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS015L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 16: HPS016L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS016L3 in slot 16
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS016L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 17: HPS017L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS017L3 in slot 17
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS017L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 18: HPS018L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS018L3 in slot 18
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS018L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 19: HPS019L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS019L3 in slot 19
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS019L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 20: HPS020L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS020L3 in slot 20
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS020L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 21: HPS021L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS021L3 in slot 21
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS021L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 22: HPS022L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS022L3 in slot 22
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS022L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 23: HPS023L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS023L3 in slot 23
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS023L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 24: HPS024L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS024L3 in slot 24
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS024L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 25: HPS025L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS025L3 in slot 25
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS025L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 26: HPS026L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS026L3 in slot 26
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS026L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 27: HPS027L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS027L3 in slot 27
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS027L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 28: HPS028L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS028L3 in slot 28
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS028L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 29: HPS029L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS029L3 in slot 29
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS029L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 30: HPS030L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS030L3 in slot 30
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS030L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 31: HPS031L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS031L3 in slot 31
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS031L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 32: HPS032L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS032L3 in slot 32
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS032L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 33: HPS033L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS033L3 in slot 33
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS033L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 34: HPS034L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS034L3 in slot 34
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS034L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 35: HPS035L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS035L3 in slot 35
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS035L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 36: HPS036L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS036L3 in slot 36
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS036L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 37: HPS037L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS037L3 in slot 37
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS037L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 38: HPS038L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS038L3 in slot 38
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS038L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 39: HPS039L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS039L3 in slot 39
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS039L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 40: HPS040L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS040L3 in slot 40
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS040L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 41: HPS041L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS041L3 in slot 41
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS041L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 42: HPS042L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS042L3 in slot 42
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS042L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 43: HPS043L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS043L3 in slot 43
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS043L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 44: HPS044L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS044L3 in slot 44
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS044L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 45: HPS045L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS045L3 in slot 45
Jun 27 17:38:21 centos6301 vtllibrary[5828]: cart_type(): Data cart found: HPS045L3
Jun 27 17:38:21 centos6301 vtllibrary[5828]: readline(): Slot 46: HPS046L3#015
Jun 27 17:38:21 centos6301 vtllibrary[5828]: init_slot_info(): Barcode HPS046L3 in slot 46
Jun 27 17:38:21 centos6301 rsyslogd-2177: imuxsock begins to drop messages from pid 5828 due to rate-limiting
Jun 27 17:38:21 centos6301 vtllibrary[5830]: main(): Started vtllibrary: version 1.3.1.el6.elrepo, verbose log lvl: 3, lu [3:0:0]
Jun 27 17:38:21 centos6301 vtllibrary[5830]: oom_adjust(): Can't open oom-killer's pardon /proc/5830/oom_adj, Permission denied
Jun 27 17:38:21 centos6301 vtllibrary[5830]: mhvtl_shared_mem(): shm count is: 4
Jun 27 17:38:21 centos6301 vtllibrary[5830]: mhvtl_shared_mem(): shm count now: 5
Jun 27 17:38:21 centos6301 vtllibrary[5830]: main(): Cleaning up after add_lu child pid: 5829
Jun 27 17:38:21 centos6301 vtltape[5817]: processMessageQ(): Sender id: 90, msg : Register
Jun 27 17:38:21 centos6301 vtltape[5817]: processMessageQ(): Notice from Library controller : Register
Jun 27 17:38:21 centos6301 vtltape[5817]: CDB (1) 12 20 00 00 24 00
Jun 27 17:38:21 centos6301 vtltape[5817]: spc_inquiry(): INQUIRY ** (1)
Jun 27 17:38:21 centos6301 vtltape[5817]: completeSCSICommand(): OP s/n: (1), sz: 66, sam_status: 0
Jun 27 17:38:21 centos6301 kernel: scsi 8:3:0:1: Sequential-Access HP       Ultrium 3-SCSI   N11G PQ: 0 ANSI: 5
Jun 27 17:38:21 centos6301 kernel: st 8:3:0:1: Attached scsi tape st0
Jun 27 17:38:21 centos6301 kernel: st 8:3:0:1: st0: try direct i/o: yes (alignment 4 B)
Jun 27 17:38:21 centos6301 kernel: st 8:3:0:1: Attached scsi generic sg3 type 1
Jun 27 17:38:21 centos6301 vtltape[5817]: CDB (2) 12 20 00 00 42 00
Jun 27 17:38:21 centos6301 vtltape[5817]: spc_inquiry(): INQUIRY ** (2)
Jun 27 17:38:21 centos6301 vtltape[5817]: completeSCSICommand(): OP s/n: (2), sz: 66, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5816]: add_lu(): Wrote add 91 3 0 1#012 (13 bytes)
Jun 27 17:38:21 centos6301 vtltape[5816]: add_lu(): Child anounces 'lu [3:0:1] created'.
Jun 27 17:38:21 centos6301 vtltape[5817]: CDB (4) 12 00 00 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5817]: spc_inquiry(): INQUIRY ** (4)
Jun 27 17:38:21 centos6301 vtltape[5817]: completeSCSICommand(): OP s/n: (4), sz: 66, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5817]: CDB (5) 12 00 00 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5817]: spc_inquiry(): INQUIRY ** (5)
Jun 27 17:38:21 centos6301 vtltape[5817]: completeSCSICommand(): OP s/n: (5), sz: 66, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5820]: processMessageQ(): Sender id: 90, msg : Register
Jun 27 17:38:21 centos6301 vtltape[5820]: processMessageQ(): Notice from Library controller : Register
Jun 27 17:38:21 centos6301 vtltape[5820]: CDB (3) 12 00 00 00 24 00
Jun 27 17:38:21 centos6301 vtltape[5820]: spc_inquiry(): INQUIRY ** (3)
Jun 27 17:38:21 centos6301 vtltape[5820]: completeSCSICommand(): OP s/n: (3), sz: 66, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5820]: CDB (6) 12 00 00 00 42 00
Jun 27 17:38:21 centos6301 vtltape[5820]: spc_inquiry(): INQUIRY ** (6)
Jun 27 17:38:21 centos6301 vtltape[5820]: completeSCSICommand(): OP s/n: (6), sz: 66, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5819]: add_lu(): Wrote add 92 3 0 2#012 (13 bytes)
Jun 27 17:38:21 centos6301 vtltape[5819]: add_lu(): Child anounces 'lu [3:0:2] created'.
Jun 27 17:38:21 centos6301 kernel: scsi 8:3:0:2: Sequential-Access HP       Ultrium 3-SCSI   N11G PQ: 0 ANSI: 5
Jun 27 17:38:21 centos6301 kernel: st 8:3:0:2: Attached scsi tape st1
Jun 27 17:38:21 centos6301 kernel: st 8:3:0:2: st1: try direct i/o: yes (alignment 4 B)
Jun 27 17:38:21 centos6301 kernel: st 8:3:0:2: Attached scsi generic sg4 type 1
Jun 27 17:38:21 centos6301 vtltape[5817]: CDB (7) 12 01 00 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5817]: spc_inquiry(): INQUIRY ** (7)
Jun 27 17:38:21 centos6301 vtltape[5817]: spc_inquiry(): Page code 0x00
Jun 27 17:38:21 centos6301 vtltape[5817]: completeSCSICommand(): OP s/n: (7), sz: 12, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5817]: CDB (8) 12 01 00 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5817]: spc_inquiry(): INQUIRY ** (8)
Jun 27 17:38:21 centos6301 vtltape[5817]: spc_inquiry(): Page code 0x00
Jun 27 17:38:21 centos6301 vtltape[5817]: completeSCSICommand(): OP s/n: (8), sz: 12, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5817]: CDB (10) 12 01 80 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5817]: spc_inquiry(): INQUIRY ** (10)
Jun 27 17:38:21 centos6301 vtltape[5817]: spc_inquiry(): Page code 0x80
Jun 27 17:38:21 centos6301 vtltape[5817]: spc_inquiry(): Found page 0x80
Jun 27 17:38:21 centos6301 vtltape[5817]: completeSCSICommand(): OP s/n: (10), sz: 14, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5817]: CDB (11) 12 01 83 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5817]: spc_inquiry(): INQUIRY ** (11)
Jun 27 17:38:21 centos6301 vtltape[5817]: spc_inquiry(): Page code 0x83
Jun 27 17:38:21 centos6301 vtltape[5817]: spc_inquiry(): Found page 0x83
Jun 27 17:38:21 centos6301 vtltape[5817]: completeSCSICommand(): OP s/n: (11), sz: 54, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5817]: CDB (12) 12 01 80 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5817]: spc_inquiry(): INQUIRY ** (12)
Jun 27 17:38:21 centos6301 vtltape[5817]: spc_inquiry(): Page code 0x80
Jun 27 17:38:21 centos6301 vtltape[5817]: spc_inquiry(): Found page 0x80
Jun 27 17:38:21 centos6301 vtltape[5817]: completeSCSICommand(): OP s/n: (12), sz: 14, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5823]: processMessageQ(): Sender id: 90, msg : Register
Jun 27 17:38:21 centos6301 vtltape[5823]: processMessageQ(): Notice from Library controller : Register
Jun 27 17:38:21 centos6301 vtltape[5823]: CDB (9) 12 00 00 00 24 00
Jun 27 17:38:21 centos6301 vtltape[5823]: spc_inquiry(): INQUIRY ** (9)
Jun 27 17:38:21 centos6301 vtltape[5823]: completeSCSICommand(): OP s/n: (9), sz: 66, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5817]: CDB (14) 12 01 83 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5817]: spc_inquiry(): INQUIRY ** (14)
Jun 27 17:38:21 centos6301 vtltape[5817]: spc_inquiry(): Page code 0x83
Jun 27 17:38:21 centos6301 vtltape[5817]: spc_inquiry(): Found page 0x83
Jun 27 17:38:21 centos6301 vtltape[5817]: completeSCSICommand(): OP s/n: (14), sz: 54, sam_status: 0
Jun 27 17:38:21 centos6301 kernel: scsi 8:3:0:3: Sequential-Access HP       Ultrium 3-SCSI   N11G PQ: 0 ANSI: 5
Jun 27 17:38:21 centos6301 kernel: st 8:3:0:3: Attached scsi tape st2
Jun 27 17:38:21 centos6301 kernel: st 8:3:0:3: st2: try direct i/o: yes (alignment 4 B)
Jun 27 17:38:21 centos6301 kernel: st 8:3:0:3: Attached scsi generic sg5 type 1
Jun 27 17:38:21 centos6301 vtltape[5820]: CDB (13) 12 00 00 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5820]: spc_inquiry(): INQUIRY ** (13)
Jun 27 17:38:21 centos6301 vtltape[5820]: completeSCSICommand(): OP s/n: (13), sz: 66, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5820]: CDB (15) 12 00 00 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5820]: spc_inquiry(): INQUIRY ** (15)
Jun 27 17:38:21 centos6301 vtltape[5820]: completeSCSICommand(): OP s/n: (15), sz: 66, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5823]: CDB (16) 12 00 00 00 42 00
Jun 27 17:38:21 centos6301 vtltape[5823]: spc_inquiry(): INQUIRY ** (16)
Jun 27 17:38:21 centos6301 vtltape[5823]: completeSCSICommand(): OP s/n: (16), sz: 66, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5822]: add_lu(): Wrote add 93 3 0 3#012 (13 bytes)
Jun 27 17:38:21 centos6301 vtltape[5822]: add_lu(): Child anounces 'lu [3:0:3] created'.
Jun 27 17:38:21 centos6301 vtltape[5820]: CDB (18) 12 01 00 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5820]: spc_inquiry(): INQUIRY ** (18)
Jun 27 17:38:21 centos6301 vtltape[5820]: spc_inquiry(): Page code 0x00
Jun 27 17:38:21 centos6301 vtltape[5820]: completeSCSICommand(): OP s/n: (18), sz: 12, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5820]: CDB (19) 12 01 00 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5820]: spc_inquiry(): INQUIRY ** (19)
Jun 27 17:38:21 centos6301 vtltape[5820]: spc_inquiry(): Page code 0x00
Jun 27 17:38:21 centos6301 vtltape[5820]: completeSCSICommand(): OP s/n: (19), sz: 12, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5820]: CDB (21) 12 01 80 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5820]: spc_inquiry(): INQUIRY ** (21)
Jun 27 17:38:21 centos6301 vtltape[5820]: spc_inquiry(): Page code 0x80
Jun 27 17:38:21 centos6301 vtltape[5820]: spc_inquiry(): Found page 0x80
Jun 27 17:38:21 centos6301 vtltape[5820]: completeSCSICommand(): OP s/n: (21), sz: 14, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5820]: CDB (22) 12 01 80 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5820]: spc_inquiry(): INQUIRY ** (22)
Jun 27 17:38:21 centos6301 vtltape[5820]: spc_inquiry(): Page code 0x80
Jun 27 17:38:21 centos6301 vtltape[5820]: spc_inquiry(): Found page 0x80
Jun 27 17:38:21 centos6301 vtltape[5820]: completeSCSICommand(): OP s/n: (22), sz: 14, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5820]: CDB (23) 12 01 83 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5820]: spc_inquiry(): INQUIRY ** (23)
Jun 27 17:38:21 centos6301 vtltape[5820]: spc_inquiry(): Page code 0x83
Jun 27 17:38:21 centos6301 vtltape[5820]: spc_inquiry(): Found page 0x83
Jun 27 17:38:21 centos6301 vtltape[5820]: completeSCSICommand(): OP s/n: (23), sz: 54, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5820]: CDB (24) 12 01 83 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5820]: spc_inquiry(): INQUIRY ** (24)
Jun 27 17:38:21 centos6301 vtltape[5820]: spc_inquiry(): Page code 0x83
Jun 27 17:38:21 centos6301 vtltape[5820]: spc_inquiry(): Found page 0x83
Jun 27 17:38:21 centos6301 vtltape[5820]: completeSCSICommand(): OP s/n: (24), sz: 54, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5826]: processMessageQ(): Sender id: 90, msg : Register
Jun 27 17:38:21 centos6301 vtltape[5826]: processMessageQ(): Notice from Library controller : Register
Jun 27 17:38:21 centos6301 vtltape[5826]: CDB (17) 12 00 00 00 24 00
Jun 27 17:38:21 centos6301 vtltape[5826]: spc_inquiry(): INQUIRY ** (17)
Jun 27 17:38:21 centos6301 vtltape[5826]: completeSCSICommand(): OP s/n: (17), sz: 66, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5823]: CDB (20) 12 00 00 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5823]: spc_inquiry(): INQUIRY ** (20)
Jun 27 17:38:21 centos6301 vtltape[5823]: completeSCSICommand(): OP s/n: (20), sz: 66, sam_status: 0
Jun 27 17:38:21 centos6301 kernel: scsi 8:3:0:4: Sequential-Access HP       Ultrium 3-SCSI   N11G PQ: 0 ANSI: 5
Jun 27 17:38:21 centos6301 kernel: st 8:3:0:4: Attached scsi tape st3
Jun 27 17:38:21 centos6301 kernel: st 8:3:0:4: st3: try direct i/o: yes (alignment 4 B)
Jun 27 17:38:21 centos6301 kernel: st 8:3:0:4: Attached scsi generic sg6 type 1
Jun 27 17:38:21 centos6301 vtltape[5826]: CDB (25) 12 00 00 00 42 00
Jun 27 17:38:21 centos6301 vtltape[5826]: spc_inquiry(): INQUIRY ** (25)
Jun 27 17:38:21 centos6301 vtltape[5826]: completeSCSICommand(): OP s/n: (25), sz: 66, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5825]: add_lu(): Wrote add 94 3 0 4#012 (13 bytes)
Jun 27 17:38:21 centos6301 vtltape[5825]: add_lu(): Child anounces 'lu [3:0:4] created'.
Jun 27 17:38:21 centos6301 vtltape[5823]: CDB (27) 12 00 00 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5823]: spc_inquiry(): INQUIRY ** (27)
Jun 27 17:38:21 centos6301 vtltape[5823]: completeSCSICommand(): OP s/n: (27), sz: 66, sam_status: 0
Jun 27 17:38:21 centos6301 vtllibrary[5830]: CDB (26) 12 00 00 00 24 00
Jun 27 17:38:21 centos6301 vtllibrary[5830]: spc_inquiry(): INQUIRY ** (26)
Jun 27 17:38:21 centos6301 vtllibrary[5830]: completeSCSICommand(): OP s/n: (26), sz: 66, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5823]: CDB (31) 12 01 00 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5823]: spc_inquiry(): INQUIRY ** (31)
Jun 27 17:38:21 centos6301 vtltape[5823]: spc_inquiry(): Page code 0x00
Jun 27 17:38:21 centos6301 vtltape[5823]: completeSCSICommand(): OP s/n: (31), sz: 12, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5826]: CDB (28) 12 00 00 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5826]: spc_inquiry(): INQUIRY ** (28)
Jun 27 17:38:21 centos6301 vtltape[5826]: completeSCSICommand(): OP s/n: (28), sz: 66, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5826]: CDB (30) 12 00 00 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5826]: spc_inquiry(): INQUIRY ** (30)
Jun 27 17:38:21 centos6301 vtltape[5826]: completeSCSICommand(): OP s/n: (30), sz: 66, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5823]: CDB (32) 12 01 80 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5823]: spc_inquiry(): INQUIRY ** (32)
Jun 27 17:38:21 centos6301 vtltape[5823]: spc_inquiry(): Page code 0x80
Jun 27 17:38:21 centos6301 vtltape[5823]: spc_inquiry(): Found page 0x80
Jun 27 17:38:21 centos6301 vtltape[5823]: completeSCSICommand(): OP s/n: (32), sz: 14, sam_status: 0
Jun 27 17:38:21 centos6301 vtllibrary[5830]: CDB (29) 12 00 00 00 42 00
Jun 27 17:38:21 centos6301 vtllibrary[5830]: spc_inquiry(): INQUIRY ** (29)
Jun 27 17:38:21 centos6301 vtllibrary[5830]: completeSCSICommand(): OP s/n: (29), sz: 66, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5823]: CDB (33) 12 01 83 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5823]: spc_inquiry(): INQUIRY ** (33)
Jun 27 17:38:21 centos6301 vtltape[5823]: spc_inquiry(): Page code 0x83
Jun 27 17:38:21 centos6301 vtltape[5823]: spc_inquiry(): Found page 0x83
Jun 27 17:38:21 centos6301 vtltape[5823]: completeSCSICommand(): OP s/n: (33), sz: 54, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5823]: CDB (35) 12 01 00 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5823]: spc_inquiry(): INQUIRY ** (35)
Jun 27 17:38:21 centos6301 vtltape[5823]: spc_inquiry(): Page code 0x00
Jun 27 17:38:21 centos6301 vtltape[5823]: completeSCSICommand(): OP s/n: (35), sz: 12, sam_status: 0
Jun 27 17:38:21 centos6301 kernel: scsi 8:3:0:0: Medium Changer    HP       MSL6000 Series   2.00 PQ: 0 ANSI: 5
Jun 27 17:38:21 centos6301 vtltape[5826]: CDB (34) 12 01 00 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5826]: spc_inquiry(): INQUIRY ** (34)
Jun 27 17:38:21 centos6301 vtltape[5826]: spc_inquiry(): Page code 0x00
Jun 27 17:38:21 centos6301 vtltape[5826]: completeSCSICommand(): OP s/n: (34), sz: 12, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5826]: CDB (36) 12 01 00 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5826]: spc_inquiry(): INQUIRY ** (36)
Jun 27 17:38:21 centos6301 vtltape[5826]: spc_inquiry(): Page code 0x00
Jun 27 17:38:21 centos6301 vtltape[5826]: completeSCSICommand(): OP s/n: (36), sz: 12, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5823]: CDB (38) 12 01 80 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5823]: spc_inquiry(): INQUIRY ** (38)
Jun 27 17:38:21 centos6301 vtltape[5823]: spc_inquiry(): Page code 0x80
Jun 27 17:38:21 centos6301 vtltape[5823]: spc_inquiry(): Found page 0x80
Jun 27 17:38:21 centos6301 vtltape[5823]: completeSCSICommand(): OP s/n: (38), sz: 14, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5826]: CDB (39) 12 01 80 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5826]: spc_inquiry(): INQUIRY ** (39)
Jun 27 17:38:21 centos6301 vtltape[5826]: spc_inquiry(): Page code 0x80
Jun 27 17:38:21 centos6301 vtltape[5826]: spc_inquiry(): Found page 0x80
Jun 27 17:38:21 centos6301 vtltape[5826]: completeSCSICommand(): OP s/n: (39), sz: 14, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5826]: CDB (40) 12 01 80 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5826]: spc_inquiry(): INQUIRY ** (40)
Jun 27 17:38:21 centos6301 vtltape[5826]: spc_inquiry(): Page code 0x80
Jun 27 17:38:21 centos6301 vtltape[5826]: spc_inquiry(): Found page 0x80
Jun 27 17:38:21 centos6301 vtltape[5826]: completeSCSICommand(): OP s/n: (40), sz: 14, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5823]: CDB (41) 12 01 83 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5823]: spc_inquiry(): INQUIRY ** (41)
Jun 27 17:38:21 centos6301 vtltape[5823]: spc_inquiry(): Page code 0x83
Jun 27 17:38:21 centos6301 vtltape[5823]: spc_inquiry(): Found page 0x83
Jun 27 17:38:21 centos6301 vtltape[5823]: completeSCSICommand(): OP s/n: (41), sz: 54, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5826]: CDB (42) 12 01 83 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5826]: spc_inquiry(): INQUIRY ** (42)
Jun 27 17:38:21 centos6301 vtltape[5826]: spc_inquiry(): Page code 0x83
Jun 27 17:38:21 centos6301 vtltape[5826]: spc_inquiry(): Found page 0x83
Jun 27 17:38:21 centos6301 vtltape[5826]: completeSCSICommand(): OP s/n: (42), sz: 54, sam_status: 0
Jun 27 17:38:21 centos6301 vtltape[5826]: CDB (43) 12 01 83 00 fe 00
Jun 27 17:38:21 centos6301 vtltape[5826]: spc_inquiry(): INQUIRY ** (43)
Jun 27 17:38:21 centos6301 vtltape[5826]: spc_inquiry(): Page code 0x83
Jun 27 17:38:21 centos6301 vtltape[5826]: spc_inquiry(): Found page 0x83
Jun 27 17:38:21 centos6301 vtltape[5826]: completeSCSICommand(): OP s/n: (43), sz: 54, sam_status: 0
Jun 27 17:38:21 centos6301 vtllibrary[5830]: CDB (37) 1a 00 1d 00 ff 00
Jun 27 17:38:21 centos6301 vtllibrary[5830]: mkSenseBuf(): SENSE [Key/ASC/ASCQ] [06 29 00]
Jun 27 17:38:21 centos6301 vtllibrary[5830]: completeSCSICommand(): OP s/n: (37), sz: 0, sam_status: 2
Jun 27 17:38:21 centos6301 vtllibrary[5830]: completeSCSICommand(): [Key/ASC/ASCQ] [06 29 00]
Jun 27 17:38:21 centos6301 vtllibrary[5830]: CDB (44) 1a 00 1d 00 ff 00
Jun 27 17:38:21 centos6301 vtllibrary[5830]: spc_mode_sense(): MODE SENSE (44) **
Jun 27 17:38:21 centos6301 vtllibrary[5830]: spc_mode_sense():  Mode Sense 6 byte version
Jun 27 17:38:21 centos6301 vtllibrary[5830]: spc_mode_sense():  Page Control     : Current values(0x00)
Jun 27 17:38:21 centos6301 vtllibrary[5830]: spc_mode_sense():  Page/Subpage Code: 0x1d/0x00
Jun 27 17:38:21 centos6301 vtllibrary[5830]: spc_mode_sense():  Report Block Descriptor
Jun 27 17:38:21 centos6301 vtllibrary[5830]: spc_mode_sense():  Allocation len   : 255
Jun 27 17:38:21 centos6301 vtllibrary[5830]: lookup_pcode(): Looking for: pcode 0x1d, subpcode 0x00
Jun 27 17:38:21 centos6301 vtllibrary[5830]: lookup_pcode(): Found "Element Address" -> pcode 0x1d, subpcode 0x00
Jun 27 17:38:21 centos6301 vtllibrary[5830]: completeSCSICommand(): OP s/n: (44), sz: 32, sam_status: 0
Jun 27 17:38:21 centos6301 kernel: ch0: type #1 (mt): 0x100+1 [medium transport]
Jun 27 17:38:21 centos6301 kernel: ch0: type #2 (st): 0x400+50 [storage]
Jun 27 17:38:21 centos6301 kernel: ch0: type #3 (ie): 0x200+4 [import/export]
Jun 27 17:38:21 centos6301 kernel: ch0: type #4 (dt): 0x1+4 [data transfer]
Jun 27 17:38:21 centos6301 vtllibrary[5830]: CDB (45) b8 04 00 01 00 01 00 00 00 ff 00 00
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status(): READ ELEMENT STATUS (45) **
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status():  Element type(4) => Data Transfer Elements
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status():   Starting Element Address: 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status():   Number of Elements      : 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status():   Allocation length       : 255
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status():   Device ID: No, voltag: No
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_page(): Element type: Data Transfer (drive) Element, min: 1 num: 4
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_page(): max_count: 1, max_bytes: 255
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_page(): avail: 4, count: 1, space: 14 cur_count: 0 need_bytes: 72 (0x0048)
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_page_hdr(): Element Status Page Header: 04 00 00 10 00 00 00 10
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1, DVCID: 0, VOLTAG: 0
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 16 bytes
Jun 27 17:38:21 centos6301 vtllibrary[5830]: num_available_elements(): Determing 1 element of type Drive starting at 1, returning 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status(): cur_count: 1, all_bytes: 0x0048
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr(): Building READ ELEMENT STATUS Header struct
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():  Starting slot: 1, number of configured slots: 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():  Element Status Data HEADER: 00 01 00 01 00 00 00 48
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():  Decoded:
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():   First element Address    : 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():   Number elements reported : 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():   Total byte count         : 72 (0x0048)
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status(): Returning 32 bytes
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status(): Element Status Data
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   First element reported       : 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Number of elements available : 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Byte count of report         : 72
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status(): Element Status Page
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Element Type code            : 4
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Primary Vol Tag              : No
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Alt Vol Tag                  : No
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Element descriptor length    : 16
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Byte count of descriptor data: 16
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status(): Element Descriptor(s) : Num of Elements 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():  Debug.... i = 0, len = 16
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Element Address             : 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Status                      : 0x08
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Medium type                 : 0
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():  Identification Descriptor
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Code Set                     : 0x00
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Identifier type              : 0x00
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Identifier length            : 0
Jun 27 17:38:21 centos6301 vtllibrary[5830]: completeSCSICommand(): OP s/n: (45), sz: 32, sam_status: 0
Jun 27 17:38:21 centos6301 kernel: ch0: dt 0x1: ID/LUN unknown
Jun 27 17:38:21 centos6301 vtllibrary[5830]: CDB (46) b8 04 00 02 00 01 00 00 00 ff 00 00
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status(): READ ELEMENT STATUS (46) **
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status():  Element type(4) => Data Transfer Elements
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status():   Starting Element Address: 2
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status():   Number of Elements      : 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status():   Allocation length       : 255
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status():   Device ID: No, voltag: No
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_page(): Element type: Data Transfer (drive) Element, min: 1 num: 4
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_page(): max_count: 1, max_bytes: 255
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_page(): avail: 3, count: 1, space: 14 cur_count: 0 need_bytes: 56 (0x0038)
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_page_hdr(): Element Status Page Header: 04 00 00 10 00 00 00 10
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 2
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 2, DVCID: 0, VOLTAG: 0
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 16 bytes
Jun 27 17:38:21 centos6301 vtllibrary[5830]: num_available_elements(): Determing 1 element of type Drive starting at 2, returning 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status(): cur_count: 1, all_bytes: 0x0038
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr(): Building READ ELEMENT STATUS Header struct
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():  Starting slot: 2, number of configured slots: 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():  Element Status Data HEADER: 00 02 00 01 00 00 00 38
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():  Decoded:
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():   First element Address    : 2
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():   Number elements reported : 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():   Total byte count         : 56 (0x0038)
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status(): Returning 32 bytes
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status(): Element Status Data
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   First element reported       : 2
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Number of elements available : 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Byte count of report         : 56
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status(): Element Status Page
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Element Type code            : 4
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Primary Vol Tag              : No
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Alt Vol Tag                  : No
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Element descriptor length    : 16
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Byte count of descriptor data: 16
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status(): Element Descriptor(s) : Num of Elements 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():  Debug.... i = 0, len = 16
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Element Address             : 2
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Status                      : 0x08
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Medium type                 : 0
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():  Identification Descriptor
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Code Set                     : 0x00
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Identifier type              : 0x00
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Identifier length            : 0
Jun 27 17:38:21 centos6301 vtllibrary[5830]: completeSCSICommand(): OP s/n: (46), sz: 32, sam_status: 0
Jun 27 17:38:21 centos6301 kernel: ch0: dt 0x2: ID/LUN unknown
Jun 27 17:38:21 centos6301 vtllibrary[5830]: CDB (47) b8 04 00 03 00 01 00 00 00 ff 00 00
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status(): READ ELEMENT STATUS (47) **
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status():  Element type(4) => Data Transfer Elements
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status():   Starting Element Address: 3
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status():   Number of Elements      : 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status():   Allocation length       : 255
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status():   Device ID: No, voltag: No
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_page(): Element type: Data Transfer (drive) Element, min: 1 num: 4
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_page(): max_count: 1, max_bytes: 255
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_page(): avail: 2, count: 1, space: 14 cur_count: 0 need_bytes: 40 (0x0028)
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_page_hdr(): Element Status Page Header: 04 00 00 10 00 00 00 10
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 3
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 3, DVCID: 0, VOLTAG: 0
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 16 bytes
Jun 27 17:38:21 centos6301 vtllibrary[5830]: num_available_elements(): Determing 1 element of type Drive starting at 3, returning 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status(): cur_count: 1, all_bytes: 0x0028
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr(): Building READ ELEMENT STATUS Header struct
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():  Starting slot: 3, number of configured slots: 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():  Element Status Data HEADER: 00 03 00 01 00 00 00 28
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():  Decoded:
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():   First element Address    : 3
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():   Number elements reported : 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():   Total byte count         : 40 (0x0028)
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status(): Returning 32 bytes
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status(): Element Status Data
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   First element reported       : 3
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Number of elements available : 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Byte count of report         : 40
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status(): Element Status Page
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Element Type code            : 4
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Primary Vol Tag              : No
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Alt Vol Tag                  : No
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Element descriptor length    : 16
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Byte count of descriptor data: 16
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status(): Element Descriptor(s) : Num of Elements 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():  Debug.... i = 0, len = 16
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Element Address             : 3
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Status                      : 0x08
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Medium type                 : 0
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():  Identification Descriptor
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Code Set                     : 0x00
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Identifier type              : 0x00
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Identifier length            : 0
Jun 27 17:38:21 centos6301 vtllibrary[5830]: completeSCSICommand(): OP s/n: (47), sz: 32, sam_status: 0
Jun 27 17:38:21 centos6301 kernel: ch0: dt 0x3: ID/LUN unknown
Jun 27 17:38:21 centos6301 vtllibrary[5830]: CDB (48) b8 04 00 04 00 01 00 00 00 ff 00 00
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status(): READ ELEMENT STATUS (48) **
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status():  Element type(4) => Data Transfer Elements
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status():   Starting Element Address: 4
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status():   Number of Elements      : 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status():   Allocation length       : 255
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status():   Device ID: No, voltag: No
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_page(): Element type: Data Transfer (drive) Element, min: 1 num: 4
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_page(): max_count: 1, max_bytes: 255
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_page(): avail: 1, count: 1, space: 14 cur_count: 0 need_bytes: 24 (0x0018)
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_page_hdr(): Element Status Page Header: 04 00 00 10 00 00 00 10
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 4
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 4, DVCID: 0, VOLTAG: 0
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 16 bytes
Jun 27 17:38:21 centos6301 vtllibrary[5830]: num_available_elements(): Determing 1 element of type Drive starting at 4, returning 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status(): cur_count: 1, all_bytes: 0x0018
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr(): Building READ ELEMENT STATUS Header struct
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():  Starting slot: 4, number of configured slots: 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():  Element Status Data HEADER: 00 04 00 01 00 00 00 18
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():  Decoded:
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():   First element Address    : 4
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():   Number elements reported : 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():   Total byte count         : 24 (0x0018)
Jun 27 17:38:21 centos6301 vtllibrary[5830]: smc_read_element_status(): Returning 32 bytes
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status(): Element Status Data
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   First element reported       : 4
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Number of elements available : 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Byte count of report         : 24
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status(): Element Status Page
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Element Type code            : 4
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Primary Vol Tag              : No
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Alt Vol Tag                  : No
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Element descriptor length    : 16
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status():   Byte count of descriptor data: 16
Jun 27 17:38:21 centos6301 vtllibrary[5830]: decode_element_status(): Element Descriptor(s) : Num of Elements 1
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():  Debug.... i = 0, len = 16
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Element Address             : 4
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Status                      : 0x08
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Medium type                 : 0
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():  Identification Descriptor
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Code Set                     : 0x00
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Identifier type              : 0x00
Jun 27 17:38:21 centos6301 vtllibrary[5830]: dump_element_desc():   Identifier length            : 0
Jun 27 17:38:21 centos6301 rsyslogd-2177: imuxsock begins to drop messages from pid 5830 due to rate-limiting
Jun 27 17:38:21 centos6301 kernel: ch0: dt 0x4: ID/LUN unknown
Jun 27 17:38:21 centos6301 kernel: ch0: INITIALIZE ELEMENT STATUS, may take some time ...
Jun 27 17:38:22 centos6301 kernel: ch0: ... finished
Jun 27 17:38:22 centos6301 kernel: ch 8:3:0:0: Attached scsi changer ch0
Jun 27 17:38:22 centos6301 kernel: ch 8:3:0:0: Attached scsi generic sg7 type 8
Jun 27 17:38:22 centos6301 vtllibrary[5829]: add_lu(): Wrote add 90 3 0 0#012 (13 bytes)
Jun 27 17:38:22 centos6301 vtllibrary[5829]: add_lu(): Child anounces 'lu [3:0:0] created'.
Jun 27 17:43:12 centos6301 vtltape[5817]: CDB (54) 00 00 00 00 00 00
Jun 27 17:43:12 centos6301 vtltape[5817]: mkSenseBuf(): SENSE [Key/ASC/ASCQ] [06 29 00]
Jun 27 17:43:12 centos6301 vtltape[5817]: completeSCSICommand(): OP s/n: (54), sz: 0, sam_status: 2
Jun 27 17:43:12 centos6301 vtltape[5817]: completeSCSICommand(): [Key/ASC/ASCQ] [06 29 00]
Jun 27 17:43:12 centos6301 vtltape[5817]: CDB (55) 00 00 00 00 00 00
Jun 27 17:43:12 centos6301 vtltape[5817]: mkSenseBuf(): SENSE [Key/ASC/ASCQ] [02 3a 00]
Jun 27 17:43:12 centos6301 vtltape[5817]: ssc_tur(): Test Unit Ready (55) ** : No, No tape loaded
Jun 27 17:43:12 centos6301 vtltape[5817]: completeSCSICommand(): OP s/n: (55), sz: 0, sam_status: 2
Jun 27 17:43:12 centos6301 vtltape[5817]: completeSCSICommand(): [Key/ASC/ASCQ] [02 3a 00]
Jun 27 17:43:13 centos6301 vtltape[5817]: CDB (56) 00 00 00 00 00 00
Jun 27 17:43:13 centos6301 vtltape[5817]: mkSenseBuf(): SENSE [Key/ASC/ASCQ] [02 3a 00]
Jun 27 17:43:13 centos6301 vtltape[5817]: ssc_tur(): Test Unit Ready (56) ** : No, No tape loaded
Jun 27 17:43:13 centos6301 vtltape[5817]: completeSCSICommand(): OP s/n: (56), sz: 0, sam_status: 2
Jun 27 17:43:13 centos6301 vtltape[5817]: completeSCSICommand(): [Key/ASC/ASCQ] [02 3a 00]
Jun 27 17:43:50 centos6301 rsyslogd-2177: imuxsock lost 21 messages from pid 5830 due to rate-limiting
Jun 27 17:43:50 centos6301 vtllibrary[5830]: CDB (57) 12 00 00 00 38 00
Jun 27 17:43:50 centos6301 vtllibrary[5830]: spc_inquiry(): INQUIRY ** (57)
Jun 27 17:43:50 centos6301 vtllibrary[5830]: completeSCSICommand(): OP s/n: (57), sz: 66, sam_status: 0
Jun 27 17:43:50 centos6301 vtllibrary[5830]: CDB (58) 1a 08 1d 00 88 00
Jun 27 17:43:50 centos6301 vtllibrary[5830]: spc_mode_sense(): MODE SENSE (58) **
Jun 27 17:43:50 centos6301 vtllibrary[5830]: spc_mode_sense():  Mode Sense 6 byte version
Jun 27 17:43:50 centos6301 vtllibrary[5830]: spc_mode_sense():  Page Control     : Current values(0x00)
Jun 27 17:43:50 centos6301 vtllibrary[5830]: spc_mode_sense():  Page/Subpage Code: 0x1d/0x00
Jun 27 17:43:50 centos6301 vtllibrary[5830]: spc_mode_sense():  Disable Block Descriptor
Jun 27 17:43:50 centos6301 vtllibrary[5830]: spc_mode_sense():  Allocation len   : 136
Jun 27 17:43:50 centos6301 vtllibrary[5830]: lookup_pcode(): Looking for: pcode 0x1d, subpcode 0x00
Jun 27 17:43:50 centos6301 vtllibrary[5830]: lookup_pcode(): Found "Element Address" -> pcode 0x1d, subpcode 0x00
Jun 27 17:43:50 centos6301 vtllibrary[5830]: completeSCSICommand(): OP s/n: (58), sz: 24, sam_status: 0
Jun 27 17:43:50 centos6301 vtllibrary[5830]: CDB (59) b8 12 04 00 00 32 00 00 13 84 00 00
Jun 27 17:43:50 centos6301 vtllibrary[5830]: smc_read_element_status(): READ ELEMENT STATUS (59) **
Jun 27 17:43:50 centos6301 vtllibrary[5830]: smc_read_element_status():  Element type(2) => Storage Elements
Jun 27 17:43:50 centos6301 vtllibrary[5830]: smc_read_element_status():   Starting Element Address: 1024
Jun 27 17:43:50 centos6301 vtllibrary[5830]: smc_read_element_status():   Number of Elements      : 50
Jun 27 17:43:50 centos6301 vtllibrary[5830]: smc_read_element_status():   Allocation length       : 4996
Jun 27 17:43:50 centos6301 vtllibrary[5830]: smc_read_element_status():   Device ID: No, voltag: Yes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Element type: Storage Element, min: 1024 num: 50
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): max_count: 50, max_bytes: 4996
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): avail: 50, count: 50, space: 95 cur_count: 0 need_bytes: 2608 (0x0a30)
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_status_page_hdr(): Element Status Page Header: 02 80 00 34 00 00 0a 28
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1024
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1024, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1025
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1025, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1026
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1026, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1027
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1027, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1028
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1028, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1029
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1029, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1030
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1030, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1031
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1031, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1032
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1032, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1033
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1033, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1034
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1034, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1035
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1035, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1036
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1036, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1037
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1037, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1038
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1038, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1039
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1039, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1040
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1040, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1041
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1041, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1042
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1042, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1043
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1043, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1044
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1044, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1045
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1045, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1046
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1046, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1047
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1047, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1048
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1048, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1049
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1049, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1050
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1050, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1051
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1051, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1052
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1052, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1053
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1053, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1054
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1054, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1055
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1055, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1056
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1056, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1057
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1057, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1058
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1058, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1059
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1059, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1060
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1060, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1061
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1061, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1062
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1062, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1063
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1063, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1064
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1064, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1065
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1065, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1066
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1066, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1067
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1067, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1068
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1068, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1069
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1069, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1070
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1070, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1071
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1071, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1072
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1072, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1073
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1073, DVCID: 0, VOLTAG: 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: num_available_elements(): Determing 50 elements of type Storage starting at 1024, returning 50
Jun 27 17:43:50 centos6301 vtllibrary[5830]: smc_read_element_status(): cur_count: 50, all_bytes: 0x0a30
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_status_data_hdr(): Building READ ELEMENT STATUS Header struct
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():  Starting slot: 1024, number of configured slots: 50
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():  Element Status Data HEADER: 04 00 00 32 00 00 0a 30
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():  Decoded:
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():   First element Address    : 1024
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():   Number elements reported : 50
Jun 27 17:43:50 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():   Total byte count         : 2608 (0x0a30)
Jun 27 17:43:50 centos6301 vtllibrary[5830]: smc_read_element_status(): Returning 2616 bytes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: decode_element_status(): Element Status Data
Jun 27 17:43:50 centos6301 vtllibrary[5830]: decode_element_status():   First element reported       : 1024
Jun 27 17:43:50 centos6301 vtllibrary[5830]: decode_element_status():   Number of elements available : 50
Jun 27 17:43:50 centos6301 vtllibrary[5830]: decode_element_status():   Byte count of report         : 2608
Jun 27 17:43:50 centos6301 vtllibrary[5830]: decode_element_status(): Element Status Page
Jun 27 17:43:50 centos6301 vtllibrary[5830]: decode_element_status():   Element Type code            : 2
Jun 27 17:43:50 centos6301 vtllibrary[5830]: decode_element_status():   Primary Vol Tag              : Yes
Jun 27 17:43:50 centos6301 vtllibrary[5830]: decode_element_status():   Alt Vol Tag                  : No
Jun 27 17:43:50 centos6301 vtllibrary[5830]: decode_element_status():   Element descriptor length    : 52
Jun 27 17:43:50 centos6301 vtllibrary[5830]: decode_element_status():   Byte count of descriptor data: 2600
Jun 27 17:43:50 centos6301 vtllibrary[5830]: decode_element_status(): Element Descriptor(s) : Num of Elements 50
Jun 27 17:43:50 centos6301 vtllibrary[5830]: dump_element_desc():  Debug.... i = 0, len = 52
Jun 27 17:43:50 centos6301 vtllibrary[5830]: dump_element_desc():   Element Address             : 1024
Jun 27 17:43:50 centos6301 vtllibrary[5830]: dump_element_desc():   Status                      : 0x09
Jun 27 17:43:50 centos6301 vtllibrary[5830]: dump_element_desc():   Medium type                 : 1
Jun 27 17:43:50 centos6301 vtllibrary[5830]: dump_element_desc():  Voltag info...
Jun 27 17:43:50 centos6301 rsyslogd-2177: imuxsock begins to drop messages from pid 5830 due to rate-limiting
Jun 27 17:43:59 centos6301 rsyslogd-2177: imuxsock lost 653 messages from pid 5830 due to rate-limiting
Jun 27 17:43:59 centos6301 vtllibrary[5830]: CDB (63) 12 00 00 00 38 00
Jun 27 17:43:59 centos6301 vtllibrary[5830]: spc_inquiry(): INQUIRY ** (63)
Jun 27 17:43:59 centos6301 vtllibrary[5830]: completeSCSICommand(): OP s/n: (63), sz: 66, sam_status: 0
Jun 27 17:43:59 centos6301 vtllibrary[5830]: CDB (64) 1a 08 1d 00 88 00
Jun 27 17:43:59 centos6301 vtllibrary[5830]: spc_mode_sense(): MODE SENSE (64) **
Jun 27 17:43:59 centos6301 vtllibrary[5830]: spc_mode_sense():  Mode Sense 6 byte version
Jun 27 17:43:59 centos6301 vtllibrary[5830]: spc_mode_sense():  Page Control     : Current values(0x00)
Jun 27 17:43:59 centos6301 vtllibrary[5830]: spc_mode_sense():  Page/Subpage Code: 0x1d/0x00
Jun 27 17:43:59 centos6301 vtllibrary[5830]: spc_mode_sense():  Disable Block Descriptor
Jun 27 17:43:59 centos6301 vtllibrary[5830]: spc_mode_sense():  Allocation len   : 136
Jun 27 17:43:59 centos6301 vtllibrary[5830]: lookup_pcode(): Looking for: pcode 0x1d, subpcode 0x00
Jun 27 17:43:59 centos6301 vtllibrary[5830]: lookup_pcode(): Found "Element Address" -> pcode 0x1d, subpcode 0x00
Jun 27 17:43:59 centos6301 vtllibrary[5830]: completeSCSICommand(): OP s/n: (64), sz: 24, sam_status: 0
Jun 27 17:43:59 centos6301 vtllibrary[5830]: CDB (65) b8 12 04 00 00 32 00 00 13 84 00 00
Jun 27 17:43:59 centos6301 vtllibrary[5830]: smc_read_element_status(): READ ELEMENT STATUS (65) **
Jun 27 17:43:59 centos6301 vtllibrary[5830]: smc_read_element_status():  Element type(2) => Storage Elements
Jun 27 17:43:59 centos6301 vtllibrary[5830]: smc_read_element_status():   Starting Element Address: 1024
Jun 27 17:43:59 centos6301 vtllibrary[5830]: smc_read_element_status():   Number of Elements      : 50
Jun 27 17:43:59 centos6301 vtllibrary[5830]: smc_read_element_status():   Allocation length       : 4996
Jun 27 17:43:59 centos6301 vtllibrary[5830]: smc_read_element_status():   Device ID: No, voltag: Yes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Element type: Storage Element, min: 1024 num: 50
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): max_count: 50, max_bytes: 4996
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): avail: 50, count: 50, space: 95 cur_count: 0 need_bytes: 2608 (0x0a30)
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_status_page_hdr(): Element Status Page Header: 02 80 00 34 00 00 0a 28
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1024
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1024, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1025
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1025, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1026
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1026, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1027
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1027, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1028
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1028, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1029
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1029, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1030
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1030, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1031
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1031, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1032
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1032, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1033
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1033, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1034
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1034, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1035
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1035, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1036
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1036, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1037
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1037, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1038
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1038, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1039
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1039, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1040
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1040, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1041
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1041, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1042
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1042, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1043
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1043, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1044
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1044, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1045
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1045, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1046
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1046, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1047
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1047, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1048
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1048, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1049
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1049, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1050
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1050, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1051
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1051, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1052
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1052, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1053
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1053, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1054
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1054, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1055
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1055, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1056
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1056, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1057
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1057, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1058
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1058, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1059
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1059, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1060
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1060, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1061
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1061, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1062
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1062, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1063
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1063, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1064
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1064, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1065
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1065, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1066
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1066, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1067
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1067, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1068
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1068, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1069
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1069, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1070
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1070, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1071
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1071, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1072
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1072, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_page(): Slot: 1073
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Slot location: 1073, DVCID: 0, VOLTAG: 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_descriptor(): Returning 52 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: num_available_elements(): Determing 50 elements of type Storage starting at 1024, returning 50
Jun 27 17:43:59 centos6301 vtllibrary[5830]: smc_read_element_status(): cur_count: 50, all_bytes: 0x0a30
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_status_data_hdr(): Building READ ELEMENT STATUS Header struct
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():  Starting slot: 1024, number of configured slots: 50
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():  Element Status Data HEADER: 04 00 00 32 00 00 0a 30
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():  Decoded:
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():   First element Address    : 1024
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():   Number elements reported : 50
Jun 27 17:43:59 centos6301 vtllibrary[5830]: fill_element_status_data_hdr():   Total byte count         : 2608 (0x0a30)
Jun 27 17:43:59 centos6301 vtllibrary[5830]: smc_read_element_status(): Returning 2616 bytes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: decode_element_status(): Element Status Data
Jun 27 17:43:59 centos6301 vtllibrary[5830]: decode_element_status():   First element reported       : 1024
Jun 27 17:43:59 centos6301 vtllibrary[5830]: decode_element_status():   Number of elements available : 50
Jun 27 17:43:59 centos6301 vtllibrary[5830]: decode_element_status():   Byte count of report         : 2608
Jun 27 17:43:59 centos6301 vtllibrary[5830]: decode_element_status(): Element Status Page
Jun 27 17:43:59 centos6301 vtllibrary[5830]: decode_element_status():   Element Type code            : 2
Jun 27 17:43:59 centos6301 vtllibrary[5830]: decode_element_status():   Primary Vol Tag              : Yes
Jun 27 17:43:59 centos6301 vtllibrary[5830]: decode_element_status():   Alt Vol Tag                  : No
Jun 27 17:43:59 centos6301 vtllibrary[5830]: decode_element_status():   Element descriptor length    : 52
Jun 27 17:43:59 centos6301 vtllibrary[5830]: decode_element_status():   Byte count of descriptor data: 2600
Jun 27 17:43:59 centos6301 vtllibrary[5830]: decode_element_status(): Element Descriptor(s) : Num of Elements 50
Jun 27 17:43:59 centos6301 vtllibrary[5830]: dump_element_desc():  Debug.... i = 0, len = 52
Jun 27 17:43:59 centos6301 vtllibrary[5830]: dump_element_desc():   Element Address             : 1024
Jun 27 17:43:59 centos6301 vtllibrary[5830]: dump_element_desc():   Status                      : 0x09
Jun 27 17:43:59 centos6301 vtllibrary[5830]: dump_element_desc():   Medium type                 : 1
Jun 27 17:43:59 centos6301 vtllibrary[5830]: dump_element_desc():  Voltag info...
Jun 27 17:43:59 centos6301 rsyslogd-2177: imuxsock begins to drop messages from pid 5830 due to rate-limiting
Jun 27 17:43:59 centos6301 vtltape[5817]: processMessageQ(): Sender id: 90, msg : lload HPS001L3
Jun 27 17:43:59 centos6301 vtltape[5817]: load_tape(): Opening file/media /opt/mhvtl/HPS001L3
Jun 27 17:43:59 centos6301 vtltape[5817]: ERROR: load_tape(): open of pcl HPS001L3 file /opt/mhvtl/HPS001L3/data failed, No such file or directory
Jun 27 17:43:59 centos6301 vtltape[5817]: loadTape(): Media load failed.. Unsupported format
]0;root@centos6301:/opt/mhvtl[root@centos6301 mhvtl]#


I noticed that my /opt/mhvtl has the following:
[root@centos6301 mhvtl]# ls -l
total 492
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 ?
drwxr-sr-x 2 vtl vtl 4096 jun 20 16:51 90
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 CLN048L3
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 CLN050L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 CLN101L4
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 CLN102L5
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 CLN303TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 E01001L4
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 E01002L4
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 E01003L4
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 E01004L4
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 E01005L4
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 E01006L4
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 E01007L4
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 E01008L4
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 E01009L4
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 E01010L4
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 E01011L4
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 E01012L4
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 E01013L4
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 E01014L4
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 E01015L4
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 E01016L4
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 E01017L4
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 E01018L4
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 E01019L4
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 E01020L4
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 F01030L5
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 F01031L5
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 F01032L5
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 F01033L5
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 F01034L5
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 F01035L5
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 F01036L5
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 F01037L5
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 F01038L5
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 F01039L5
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03001TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03002TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03003TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03004TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03005TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03006TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03007TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03008TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03009TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03010TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03011TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03012TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03013TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03014TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03015TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03016TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03017TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03018TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03019TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03020TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03021TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03022TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03023TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03024TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03025TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03026TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03027TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03028TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03029TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03030TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03031TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03032TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03033TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03034TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03035TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03036TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03037TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03038TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 G03039TA
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS001L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS002L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS003L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS004L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS005L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS006L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS007L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS008L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS009L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS010L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS011L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS012L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS013L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS014L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS015L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS016L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS017L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS018L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS019L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS020L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS021L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS022L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS023L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS024L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS025L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS026L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS027L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS028L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS029L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS030L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS031L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS032L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS033L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS034L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS035L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS036L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS037L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS038L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS039L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS040L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS041L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS042L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS043L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS044L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS045L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS046L3?
drwxrws--- 2 vtl vtl 4096 jun 20 16:51 HPS047L3?


Then I moved out the Cartrige HPS002L2 to Slot 1.

Then tried to load:
[root@centos6301 ~]# mtx -f /dev/sg7 load 1 0
Loading media from Storage Element 1 into drive 0...Source Element Address 1024 is Empty


[root@centos6301 ~]# mtx -f /dev/sg7 load 1 0
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=Hardware Error
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 = 04
mtx: Request Sense: Additional Sense Qualifier = 03
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


Library Operation - Move Volume :
      Storage Element 48:Full :VolumeTag=HPS001L3

Library Operation - Move Volume :
      Storage Element 1:Full :VolumeTag=HPS002L3

Then I tried to mount Tape:
Library Robot Device: /dev/sg7
Select Element - Slot: 1:Full :Volume Tag=HPS002L3
Select Element - Drive: Data Transfer Element 0:Empty

Library Operation - Load Volume :
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=Hardware Error
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 = 04
mtx: Request Sense: Additional Sense Qualifier = 03
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

My final status is:  (notice that my cartridges are Full)

[root@centos6301 ~]# mtx -f /dev/sg7 status
  Storage Changer /dev/sg7:4 Drives, 54 Slots ( 4 Import/Export )
Data Transfer Element 0:Empty
Data Transfer Element 1:Empty
Data Transfer Element 2:Empty
Data Transfer Element 3:Empty
      Storage Element 1:Full :VolumeTag=HPS002L3
      Storage Element 2:Empty
      Storage Element 3:Full :VolumeTag=HPS003L3
      Storage Element 4:Full :VolumeTag=HPS004L3
      Storage Element 5:Full :VolumeTag=HPS005L3
      Storage Element 6:Full :VolumeTag=HPS006L3
      Storage Element 7:Full :VolumeTag=HPS007L3
      Storage Element 8:Full :VolumeTag=HPS008L3
      Storage Element 9:Full :VolumeTag=HPS009L3
      Storage Element 10:Full :VolumeTag=HPS010L3
      Storage Element 11:Full :VolumeTag=HPS011L3
      Storage Element 12:Full :VolumeTag=HPS012L3
      Storage Element 13:Full :VolumeTag=HPS013L3
      Storage Element 14:Full :VolumeTag=HPS014L3
      Storage Element 15:Full :VolumeTag=HPS015L3
      Storage Element 16:Full :VolumeTag=HPS016L3
      Storage Element 17:Full :VolumeTag=HPS017L3
      Storage Element 18:Full :VolumeTag=HPS018L3
      Storage Element 19:Full :VolumeTag=HPS019L3
      Storage Element 20:Full :VolumeTag=HPS020L3
      Storage Element 21:Full :VolumeTag=HPS021L3
      Storage Element 22:Full :VolumeTag=HPS022L3
      Storage Element 23:Full :VolumeTag=HPS023L3
      Storage Element 24:Full :VolumeTag=HPS024L3
      Storage Element 25:Full :VolumeTag=HPS025L3
      Storage Element 26:Full :VolumeTag=HPS026L3
      Storage Element 27:Full :VolumeTag=HPS027L3
      Storage Element 28:Full :VolumeTag=HPS028L3
      Storage Element 29:Full :VolumeTag=HPS029L3
      Storage Element 30:Full :VolumeTag=HPS030L3
      Storage Element 31:Full :VolumeTag=HPS031L3
      Storage Element 32:Full :VolumeTag=HPS032L3
      Storage Element 33:Full :VolumeTag=HPS033L3
      Storage Element 34:Full :VolumeTag=HPS034L3
      Storage Element 35:Full :VolumeTag=HPS035L3
      Storage Element 36:Full :VolumeTag=HPS036L3
      Storage Element 37:Full :VolumeTag=HPS037L3
      Storage Element 38:Full :VolumeTag=HPS038L3
      Storage Element 39:Full :VolumeTag=HPS039L3
      Storage Element 40:Full :VolumeTag=HPS040L3
      Storage Element 41:Full :VolumeTag=HPS041L3
      Storage Element 42:Full :VolumeTag=HPS042L3
      Storage Element 43:Full :VolumeTag=HPS043L3
      Storage Element 44:Full :VolumeTag=HPS044L3
      Storage Element 45:Full :VolumeTag=HPS045L3
      Storage Element 46:Full :VolumeTag=HPS046L3
      Storage Element 47:Full :VolumeTag=HPS047L3
      Storage Element 48:Full :VolumeTag=HPS001L3
      Storage Element 49:Empty
      Storage Element 50:Full :VolumeTag=CLN050L3
      Storage Element 51 IMPORT/EXPORT:Empty
      Storage Element 52 IMPORT/EXPORT:Empty
      Storage Element 53 IMPORT/EXPORT:Empty
      Storage Element 54 IMPORT/EXPORT:Empty

Please advice!

G.I.
Reply | Threaded
Open this post in threaded view
|

Re: mhVTL: Centos 6.3/VirtualBox VM/cannot load a cartridge.

Mark Harvey
Administrator
: main(): Started vtltape: version 1.3.1.el6.elrepo

This version didn't understand HP Ultrium correctly

Go to home page and download / install v1.4-6 rpm
No need to change kmod package

Sent from my iPhone

On 28/06/2013, at 9:53, "gizurieta [via mhVTL - A Linux Virtual Tape Library]" <[hidden email]> wrote:

> 17]: main(): Started vtltape: version 1.3.1.el6.elrepo
Regards from Australia
Mark Harvey
Reply | Threaded
Open this post in threaded view
|

Re: mhVTL: Centos 6.3/VirtualBox VM/cannot load a cartridge.

gizurieta
Hello Mr. Harvey,

Thanks a lot!  I have installed the new version v1.4-7 rpm.

Then I got the same results.  But this time I go forward and saw again that under /opt/mhvtl/90 directory saw my cartridges names ending with the ? sign:

[root@centos6301 90]# ll
total 192
drwxrws--- 2 vtl vtl 4096 jun 28 16:28 CLN050L3?
drwxrws--- 2 vtl vtl 4096 jun 28 16:28 HPS001L3?
drwxrws--- 2 vtl vtl 4096 jun 28 16:28 HPS002L3?
drwxrws--- 2 vtl vtl 4096 jun 28 16:28 HPS003L3?
drwxrws--- 2 vtl vtl 4096 jun 28 16:28 HPS004L3?
.
.
.

drwxrws--- 2 vtl vtl 4096 jun 28 16:28 HPS047L3?

Then checked out the /var/adm/messages and noticed that are listed my lines for example:
Jun 28 15:21:25 centos6301 vtltape[3368]: readline(): VERSION: 5#015
Jun 28 15:21:25 centos6301 vtltape[3368]: readline(): #015
Jun 28 15:21:25 centos6301 vtltape[3368]: readline(): Library: 90 CHANNEL: 3 TARGET: 00 LUN: 00#015
Jun 28 15:21:25 centos6301 vtltape[3368]: readline():  Vendor identification: HP#015
Jun 28 15:21:25 centos6301 vtltape[3368]: readline():  Product identification: MSL6000 Series#015
Jun 28 15:21:25 centos6301 vtltape[3368]: readline():  Product revision level: 2.00#015
Jun 28 15:21:25 centos6301 vtltape[3368]: readline():  Unit serial number: 80000090#015
Jun 28 15:21:25 centos6301 vtltape[3368]: readline():  NAA: 90:11:22:33:ab:3:00:00#015
Jun 28 15:21:25 centos6301 vtltape[3368]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 28 15:21:25 centos6301 vtltape[3368]: readline(): #015


ending with the #015 character.

And then checked out my file /etc/mhvtl/device.conf and /etc/mhvtl/library_contents.90 and all the lines are ending with ^M character.
After execute the dos2unix command on these files, and then restarted the mhvtl service all is fine after execute:
# mtx -f /dev/sg7 load 1 0

The /var/log/messages reports:
Jun 28 23:46:20 centos6301 rsyslogd-2177: imuxsock begins to drop messages from pid 2795 due to rate-limiting
Jun 28 23:46:20 centos6301 vtltape[2764]: processMessageQ(): Sender id: 90, msg : lload HPS001L3
Jun 28 23:46:20 centos6301 vtltape[2764]: load_tape(): Opening media: HPS001L3
Jun 28 23:46:20 centos6301 vtltape[2764]: read_header(): Reading header 0 at offset 0, type: END OF DATA, size: 0
Jun 28 23:46:20 centos6301 vtltape[2764]: hp_media_load(): +++ Trace +++ load
Jun 28 23:46:20 centos6301 vtltape[2764]: lookup_media_type(): looking for media type 0x05
Jun 28 23:46:20 centos6301 vtltape[2764]: loadTape(): Media type 'LTO3' loaded with S/No. : HPS001L3_1372458504
Jun 28 23:46:20 centos6301 vtltape[2764]: clear_ult_WORM(): +++ Trace mode pages at 0x524f40 +++
Jun 28 23:46:20 centos6301 vtltape[2764]: lookup_pcode(): Looking for: Page/subpage (1d/00)
Jun 28 23:46:20 centos6301 vtltape[2764]: lookup_pcode(): Found "Medium Configuration" -> Page/subpage (1d/00)
Jun 28 23:46:20 centos6301 vtltape[2764]: clear_WORM(): l: 0x524f40, m: 0x163b7a0, m->pcodePointer: 0x163b7e0
Jun 28 23:46:20 centos6301 vtltape[2764]: mkSenseBuf(): SENSE [Key/ASC/ASCQ] [06 28 00]
Jun 28 23:46:20 centos6301 vtltape[2764]: loadTape(): Tape capacity: 524288000 + Early Warning 2097152
Jun 28 23:46:20 centos6301 vtltape[2764]: updateMAM(): updateMAM(load)
Jun 28 23:46:20 centos6301 vtltape[2764]: check_media_can_load(): Looking for media_type: 0x05
Jun 28 23:46:20 centos6301 vtltape[2764]: check_media_can_load(): testing against m_detail->media_type (0x01)
Jun 28 23:46:20 centos6301 vtltape[2764]: check_media_can_load(): testing against m_detail->media_type (0x02)
Jun 28 23:46:20 centos6301 vtltape[2764]: check_media_can_load(): testing against m_detail->media_type (0x03)
Jun 28 23:46:20 centos6301 vtltape[2764]: check_media_can_load(): testing against m_detail->media_type (0x04)
Jun 28 23:46:20 centos6301 vtltape[2764]: check_media_can_load(): testing against m_detail->media_type (0x05)

[root@centos6301 90]# mtx -f /dev/sg7 load 1 0
Loading media from Storage Element 1 into drive 0...done
[root@centos6301 90]# mtx -f /dev/sg7 load 1 0
Drive 0 Full (Storage Element 1 loaded)

So, I think from now I can continue to setup my lab with Dataprotector 8.x.

Thanks a lot for your help and for this great piece of software!

Kind Regards,

Gustavo Izurieta 


Reply | Threaded
Open this post in threaded view
|

Re: mhVTL: Centos 6.3/VirtualBox VM/cannot load a cartridge.

Mark Harvey
Administrator
Cool bug report.

 Thanks for the report

Sent from my iPhone

On 29/06/2013, at 16:03, "gizurieta [via mhVTL - A Linux Virtual Tape Library]" <[hidden email]> wrote:

Hello Mr. Harvey,

Thanks a lot!  I have installed the new version v1.4-7 rpm.

Then I got the same results.  But this time I go forward and saw again that under /opt/mhvtl/90 directory saw my cartridges names ending with the ? sign:

[root@centos6301 90]# ll
total 192
drwxrws--- 2 vtl vtl 4096 jun 28 16:28 CLN050L3?
drwxrws--- 2 vtl vtl 4096 jun 28 16:28 HPS001L3?
drwxrws--- 2 vtl vtl 4096 jun 28 16:28 HPS002L3?
drwxrws--- 2 vtl vtl 4096 jun 28 16:28 HPS003L3?
drwxrws--- 2 vtl vtl 4096 jun 28 16:28 HPS004L3?
.
.
.

drwxrws--- 2 vtl vtl 4096 jun 28 16:28 HPS047L3?

Then checked out the /var/adm/messages and noticed that are listed my lines for example:
Jun 28 15:21:25 centos6301 vtltape[3368]: readline(): VERSION: 5#015
Jun 28 15:21:25 centos6301 vtltape[3368]: readline(): #015
Jun 28 15:21:25 centos6301 vtltape[3368]: readline(): Library: 90 CHANNEL: 3 TARGET: 00 LUN: 00#015
Jun 28 15:21:25 centos6301 vtltape[3368]: readline():  Vendor identification: HP#015
Jun 28 15:21:25 centos6301 vtltape[3368]: readline():  Product identification: MSL6000 Series#015
Jun 28 15:21:25 centos6301 vtltape[3368]: readline():  Product revision level: 2.00#015
Jun 28 15:21:25 centos6301 vtltape[3368]: readline():  Unit serial number: 80000090#015
Jun 28 15:21:25 centos6301 vtltape[3368]: readline():  NAA: 90:11:22:33:ab:3:00:00#015
Jun 28 15:21:25 centos6301 vtltape[3368]: readline(): # fifo: /var/tmp/mhvtl#015
Jun 28 15:21:25 centos6301 vtltape[3368]: readline(): #015


ending with the #015 character.

And then checked out my file /etc/mhvtl/device.conf and /etc/mhvtl/library_contents.90 and all the lines are ending with ^M character.
After execute the dos2unix command on these files, and then restarted the mhvtl service all is fine after execute:
# mtx -f /dev/sg7 load 1 0

The /var/log/messages reports:
Jun 28 23:46:20 centos6301 rsyslogd-2177: imuxsock begins to drop messages from pid 2795 due to rate-limiting
Jun 28 23:46:20 centos6301 vtltape[2764]: processMessageQ(): Sender id: 90, msg : lload HPS001L3
Jun 28 23:46:20 centos6301 vtltape[2764]: load_tape(): Opening media: HPS001L3
Jun 28 23:46:20 centos6301 vtltape[2764]: read_header(): Reading header 0 at offset 0, type: END OF DATA, size: 0
Jun 28 23:46:20 centos6301 vtltape[2764]: hp_media_load(): +++ Trace +++ load
Jun 28 23:46:20 centos6301 vtltape[2764]: lookup_media_type(): looking for media type 0x05
Jun 28 23:46:20 centos6301 vtltape[2764]: loadTape(): Media type 'LTO3' loaded with S/No. : HPS001L3_1372458504
Jun 28 23:46:20 centos6301 vtltape[2764]: clear_ult_WORM(): +++ Trace mode pages at 0x524f40 +++
Jun 28 23:46:20 centos6301 vtltape[2764]: lookup_pcode(): Looking for: Page/subpage (1d/00)
Jun 28 23:46:20 centos6301 vtltape[2764]: lookup_pcode(): Found "Medium Configuration" -> Page/subpage (1d/00)
Jun 28 23:46:20 centos6301 vtltape[2764]: clear_WORM(): l: 0x524f40, m: 0x163b7a0, m->pcodePointer: 0x163b7e0
Jun 28 23:46:20 centos6301 vtltape[2764]: mkSenseBuf(): SENSE [Key/ASC/ASCQ] [06 28 00]
Jun 28 23:46:20 centos6301 vtltape[2764]: loadTape(): Tape capacity: 524288000 + Early Warning 2097152
Jun 28 23:46:20 centos6301 vtltape[2764]: updateMAM(): updateMAM(load)
Jun 28 23:46:20 centos6301 vtltape[2764]: check_media_can_load(): Looking for media_type: 0x05
Jun 28 23:46:20 centos6301 vtltape[2764]: check_media_can_load(): testing against m_detail->media_type (0x01)
Jun 28 23:46:20 centos6301 vtltape[2764]: check_media_can_load(): testing against m_detail->media_type (0x02)
Jun 28 23:46:20 centos6301 vtltape[2764]: check_media_can_load(): testing against m_detail->media_type (0x03)
Jun 28 23:46:20 centos6301 vtltape[2764]: check_media_can_load(): testing against m_detail->media_type (0x04)
Jun 28 23:46:20 centos6301 vtltape[2764]: check_media_can_load(): testing against m_detail->media_type (0x05)

[root@centos6301 90]# mtx -f /dev/sg7 load 1 0
Loading media from Storage Element 1 into drive 0...done
[root@centos6301 90]# mtx -f /dev/sg7 load 1 0
Drive 0 Full (Storage Element 1 loaded)

So, I think from now I can continue to setup my lab with Dataprotector 8.x.

Thanks a lot for your help and for this great piece of software!

Kind Regards,

Gustavo Izurieta 





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