Help: using mhvtl on ubuntu server with CA Arcserve

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

Help: using mhvtl on ubuntu server with CA Arcserve

sbcedservice
I'm trying CA Arcserve 16 and mhvtl 1.2.3.

I succed on installation of mhvtl on ubuntu server 11.10 x64 and I can use it with Arcserve (I successefully tryed nearly all functions I need).

The only problem is that I have the crash of the Arcserve tape service when I try to use more than 1 tape drive at the same time. The service crashes with "unknown error" and I can't find any more information on it.

I tryed first to define 1 library with 2 devices, but when I tryed to use the service crashed.

Then I defined 2 libraries whith 1 device, and all seems to work well. But if I try to use the 2 libraries at the same time, I have also the crash.

Here is my atcual config:

device.conf


VERSION: 5

# VPD page format:
# <page #> <Length> <x> <x+1>... <x+n>
# NAA format is an 8 hex byte value seperated by ':'
# Note: NAA is part of inquiry VPD 0x83
#
# Each 'record' is separated by one (or more) blank lines.
# Each 'record' starts at column 1
# Serial num max len is 10.
# Compression: factor X enabled 0|1
#     Where X is zlib compression factor        1 = Fastest compression
#                                               9 = Best compression
#     enabled 0 == off, 1 == on
#
# fifo: /var/tmp/mhvtl
# If enabled, data must be read from fifo, otherwise daemon will block
# trying to write.
# e.g. cat /var/tmp/mhvtl (in another terminal)

Library: 50 CHANNEL: 4 TARGET: 03 LUN: 00
 Vendor identification: IBM
 Product identification: ULT3582-TL
 Product revision level: 205B
 Unit serial number: 10000050
 NAA: 50:11:22:33:ab:4:03:00

Drive: 51 CHANNEL: 4 TARGET: 03 LUN: 01
 Library ID: 50 Slot: 01
 Vendor identification: IBM
 Product identification: ULT3580-TD5
 Product revision level: A3K6
 Unit serial number: 10000051
 NAA: 50:11:22:33:ab:4:03:01
 Compression: factor 1 enabled 0
 Compression type: lzo


Library: 70 CHANNEL: 5 TARGET: 05 LUN: 00
 Vendor identification: IBM
 Product identification: ULT3582-TL
 Product revision level: 205B
 Unit serial number: 10000070
 NAA: 70:11:22:33:ab:5:05:00

Drive: 71 CHANNEL: 5 TARGET: 05 LUN: 01
 Library ID: 70 Slot: 01
 Vendor identification: IBM
 Product identification: ULT3580-TD5
 Product revision level: A3K6
 Unit serial number: 10000071
 NAA: 70:11:22:33:ab:5:05:01
 Compression: factor 1 enabled 0
 Compression type: lzo


mhvtl.conf

# Home directory for config file(s)
MHVTL_CONFIG_PATH=/etc/mhvtl

# Default media capacity (500 M)
CAPACITY=87040

# Set default verbosity [0|1|2|3]
VERBOSE=1

# Set kernel module debuging [0|1]
VTL_DEBUG=0


targets.conf

default-driver iscsi

<target iqn.2001-04.com.example:sbvtl2:mhvtl:stgt:2>
        backing-store /dev/sg13
        backing-store /dev/sg11
</target>

<target iqn.2001-04.com.example:sbvtl2:mhvtl:stgt:1>
        backing-store /dev/sg12
        backing-store /dev/sg10
</target>



You can also view some more informations from the attached web-gui screenshots

Any suggestion is welcome
Reply | Threaded
Open this post in threaded view
|

Re: Help: using mhvtl on ubuntu server with CA Arcserve

Mark Harvey
Administrator
Can I ask for some clarification on your problem description..

You state that the 'arcserve tape service' crashes..
Can you reproduce the error and supply the output of
"ps -ef | grep vtl"
and
"ps -ef | grep tgt"

I want to check if anything on the targets are also crashing..

Can you also please modify /etc/mhvtl/mhvtl.conf and change VERBOSE = 3
Re-start mhvtl & tgt (might be easier to just reboot :) )
Re-produce the 'arcserve tape service crash' and supply the following information

syslog (typically /var/log/messages) - you may need to verify location by looking at /etc/syslog.conf
"ps -ef | grep vtl"
"ps -ef | grep tgt"

Time of when you ran the test and experienced the crash - This makes my life easier than reading the syslog sequentially hoping to find something..
Regards from Australia
Mark Harvey
Reply | Threaded
Open this post in threaded view
|

Re: Help: using mhvtl on ubuntu server with CA Arcserve

brianrts
Good afternoon. I work for CA on the ARCserve backup product and I also can reproduce this. I'm looking at it from our side to see whats going on from the tape engine logs.

It took me 2 tries/crashs of tape engine before I was able to inventory the library and format a tape, but once it worked it stayed working.

Let me know what I can do to assist you.
Reply | Threaded
Open this post in threaded view
|

Re: Help: using mhvtl on ubuntu server with CA Arcserve

brianrts
Created a 2 drive spectra python with 2 SDLT600's.  

VTL thinks tapes are in slots 1-6

Slot 1: QUA001S3
Slot 2: QUA002S3
Slot 3: QUA003S3
Slot 4: QUA004S3
Slot 5: QUA005S3

We inventory Slot 1 and return drive dismounted.  /var/log/messages dump is below


Jun 12 15:15:21 mhvtl vtllibrary[16710]: CDB (18691) b8 12 04 00 00 01 00 00 00 6e 00 00
Jun 12 15:15:21 mhvtl vtllibrary[16710]: smc_read_element_status(): READ ELEMENT STATUS (18691) **
Jun 12 15:15:21 mhvtl vtllibrary[16710]: CDB (18692) b8 12 04 00 00 01 00 00 00 6e 00 00
Jun 12 15:15:21 mhvtl vtllibrary[16710]: smc_read_element_status(): READ ELEMENT STATUS (18692) **
Jun 12 15:15:21 mhvtl vtllibrary[16710]: CDB (18693) 00 20 00 00 00 00
Jun 12 15:15:21 mhvtl vtllibrary[16710]: spc_tur(): ** Test Unit Ready : Returning =>  (18693) Online **
Jun 12 15:15:21 mhvtl vtllibrary[16710]: CDB (18694) b8 12 04 00 00 01 00 00 00 6e 00 00
Jun 12 15:15:21 mhvtl vtllibrary[16710]: smc_read_element_status(): READ ELEMENT STATUS (18694) **
Jun 12 15:15:21 mhvtl vtllibrary[16710]: CDB (18695) 00 20 00 00 00 00
Jun 12 15:15:21 mhvtl vtllibrary[16710]: spc_tur(): ** Test Unit Ready : Returning =>  (18695) Online **
Jun 12 15:15:21 mhvtl vtllibrary[16710]: CDB (18696) b8 12 04 00 00 01 00 00 00 6e 00 00
Jun 12 15:15:21 mhvtl vtllibrary[16710]: smc_read_element_status(): READ ELEMENT STATUS (18696) **
Jun 12 15:15:23 mhvtl vtltape[16703]: CDB (18697) 00 40 00 00 00 00
Jun 12 15:15:23 mhvtl vtltape[16703]: mkSenseBuf(): SENSE [Key/ASC/ASCQ] [02 3a 00]
Jun 12 15:15:23 mhvtl vtltape[16703]: ssc_tur(): Test Unit Ready (18697) ** : No, No tape loaded
Jun 12 15:15:23 mhvtl vtllibrary[16710]: CDB (18698) a5 20 01 00 04 00 00 01 00 00 00 00
Jun 12 15:15:23 mhvtl vtllibrary[16710]: smc_move_medium(): MOVE MEDIUM (18698) **
Jun 12 15:15:23 mhvtl vtllibrary[16710]: smc_move_medium(): Moving from slot 1024 to slot 1 using transport 256, Invert media: no
Jun 12 15:15:23 mhvtl vtllibrary[16710]: move_slot2drive(): About to send cmd: 'lload QUA001S3' to drive 1
Jun 12 15:15:23 mhvtl vtltape[16703]: processMessageQ(): Sender id: 30, msg : lload QUA001S3
Jun 12 15:15:23 mhvtl vtltape[16703]: loadTape(): Media type 'SDLT 320' loaded with S/No. : QUA001S3_1339526379
Jun 12 15:15:23 mhvtl vtltape[16703]: mkSenseBuf(): SENSE [Key/ASC/ASCQ] [06 28 00]
Jun 12 15:15:23 mhvtl vtltape[16703]: loadTape(): Previous unload was not clean
Jun 12 15:15:23 mhvtl vtltape[16703]: ERROR: loadTape(): Tape QUA001S3 failed to load with type 'SDLT 320' in drive type 'default emulation'
Jun 12 15:15:23 mhvtl vtllibrary[16710]: ERROR: move_slot2drive(): Load of QUA001S3 into drive 1 failed
Jun 12 15:15:23 mhvtl vtllibrary[16710]: mkSenseBuf(): SENSE [Key/ASC/ASCQ] [04 04 03]

When i try to inventory the entire library in one shot, tape engine crashes and restarts. Still looking into that.


Reply | Threaded
Open this post in threaded view
|

Re: Help: using mhvtl on ubuntu server with CA Arcserve

brianrts
Mark,  I have an older version of HMVTL (without GUI) that is working fine. I had gotten it a year or two ago from a rar that you had uploaded.  How can I check the version # of that build vs the the brand new install I did today thats crashing?
Reply | Threaded
Open this post in threaded view
|

Re: Help: using mhvtl on ubuntu server with CA Arcserve

Mark Harvey
Administrator
Arrhhh... Something I should document better..

I've yet to find any SDLT "SCSI Programmer Guide" documentation which describes what values quantum drives return.

All SDLT SCSI programmer's guides (I've found) list the SCSI OP codes supported, but not the values Quantum return. Until I can get my hands on a real drive and dump the raw data, I'm unable to emulate SDLT drives.

Can you try either STK 9x40, STK T10000, IBM LTO (1,2,3,4 or 5). - HP LTO should work, but I've managed to break emulation at the moment..

FWIW: I am treating this as a bug report.. The 'default' emulation should allow the mount of any media. Instead it's rejecting the SDLT media as incompatible.

Cheers
Regards from Australia
Mark Harvey
Reply | Threaded
Open this post in threaded view
|

Re: Help: using mhvtl on ubuntu server with CA Arcserve

brianrts
Mark, so I was able to reproduce the initial problem where the OP had issues with multiple drives/operations caused crashes. I've asked some dev's to take a look from our side and see if we can find anything. I was NOT able to reproduce the issue where the OP was writing to 2 VTL's attached to the same BU server however I was only using one drive per library.
Reply | Threaded
Open this post in threaded view
|

Re: Help: using mhvtl on ubuntu server with CA Arcserve

Mark Harvey
Administrator
Any chance of getting some details on the actual problem ?

I tryed first to define 1 library with 2 devices, but when I tryed to use the service crashed.

Then I defined 2 libraries whith 1 device, and all seems to work well. But if I try to use the 2 libraries at the same time, I have also the crash.
What actually 'crashes' ?
Do we get a core file ?
If it does can somebody run a backtrace on the core file ?

(Example below assumes it's vtltape that's cored)
gdb /usr/bin/vtltape /path/to/core/file
gdb> bt
gdb> quit

Is the syslog (typically /var/log/messages) available ?
If not, can the VERBOSE = 3 be set in /etc/mhvtl/mhvtl.conf, mhvtl restarted and the crash reproduced.
Please note the actual time of the crash and provide the time + the syslog for further analysis.
Note: If the complete syslog is too large, can I get the syslog between the time the mhvtl was started to just past the time the actual crash occurred would be perfect.
Regards from Australia
Mark Harvey
Reply | Threaded
Open this post in threaded view
|

Re: Help: using mhvtl on ubuntu server with CA Arcserve

sbcedservice
Sorry to all for late response, but I was out of office.

I have to reconfigure all, I hope tomorrow to have something more on this problem.

Thanks.
Reply | Threaded
Open this post in threaded view
|

Re: Help: using mhvtl on ubuntu server with CA Arcserve

brianrts
Evening all,

this looks like an OS issue as I've moved to Ubuntu 10.x running 2.6.38-8 and the following modules
 MHVTL is up-to-date      1.2.3-b12c3fe
 MHVTL-GUI is up-to-date  1.2.5-29e9173 
 TGT is up-to-date        1.0.28-c63ad5a

and I am not having any problems with ARCserve r16 Sp1. I have one library with 4 LTO drives. I am backing up 2 differnt servers to each tape drive without a problem. I can also do a full inventory of the library using all tape drives and there was no crash.



On Thu, Jun 14, 2012 at 12:52 PM, sbcedservice [via MHVTL - Linux Virtual Tape Library - Community Forums] <[hidden email]> wrote:
Sorry to all for late response, but I was out of office.

I have to reconfigure all, I hope tomorrow to have something more on this problem.

Thanks.



To unsubscribe from Help: using mhvtl on ubuntu server with CA Arcserve, click here.
NAML

Reply | Threaded
Open this post in threaded view
|

Re: Help: using mhvtl on ubuntu server with CA Arcserve

sbcedservice
In reply to this post by sbcedservice
Hi Mark,
thanks for great work.

As logs are big, you can find syslog from the mhvtl server and the tapelog from Arcserve at:

ftp://mhvtl:mhvtl123@88.42.173.19

The crash was 16.49.22 (Arcserve windows server time).

Hope this can help.

2 more questions:

When I restart mhvtl (or only tgt) I had to reconfigure tgt because it has "no target"; also if I can find targets.conf in /etc/tgt.
Every time I reconfigure the configuration is appended in the target.conf file

<target iqn.2001-04.com.example:sbvtl2:mhvtl:stgt:2>
        backing-store /dev/sg13
        backing-store /dev/sg11
</target>

<target iqn.2001-04.com.example:sbvtl2:mhvtl:stgt:1>
        backing-store /dev/sg12
        backing-store /dev/sg10
</target>

default-driver iscsi

<target iqn.2001-04.com.example:sbvtl2:mhvtl:stgt:2>
        backing-store /dev/sg13
        backing-store /dev/sg11
</target>

<target iqn.2001-04.com.example:sbvtl2:mhvtl:stgt:1>
        backing-store /dev/sg12
        backing-store /dev/sg10
</target>

default-driver iscsi

<target iqn.2001-04.com.example:sbvtl2:mhvtl:stgt:2>
        backing-store /dev/sg13
        backing-store /dev/sg11
</target>

<target iqn.2001-04.com.example:sbvtl2:mhvtl:stgt:1>
        backing-store /dev/sg12
        backing-store /dev/sg10
</target>

default-driver iscsi

<target iqn.2001-04.com.example:sbvtl2:mhvtl:stgt:2>
        backing-store /dev/sg15
        backing-store /dev/sg11
</target>

<target iqn.2001-04.com.example:sbvtl2:mhvtl:stgt:1>
        backing-store /dev/sg14
        backing-store /dev/sg10
</target>

<target iqn.2001-04.com.example:sbvtl2:mhvtl:stgt:3>
        backing-store /dev/sg16
        backing-store /dev/sg12
        backing-store /dev/sg13
</target>

default-driver iscsi

<target iqn.2001-04.com.example:sbvtl2:mhvtl:stgt:2>
        backing-store /dev/sg13
        backing-store /dev/sg11
</target>

<target iqn.2001-04.com.example:sbvtl2:mhvtl:stgt:1>
        backing-store /dev/sg12
        backing-store /dev/sg10
</target>

default-driver iscsi

<target iqn.2001-04.com.example:sbvtl2:mhvtl:stgt:2>
        backing-store /dev/sg13
        backing-store /dev/sg11
</target>

<target iqn.2001-04.com.example:sbvtl2:mhvtl:stgt:1>
        backing-store /dev/sg12
        backing-store /dev/sg10
</target>



I was notified of 1.3.0-git-0fca656 mhvtl upgrade .....

next week I will repeat the test with the new release if you think it's ok
Reply | Threaded
Open this post in threaded view
|

Re: Help: using mhvtl on ubuntu server with CA Arcserve

Mark Harvey
Administrator
Nice log format from Arcserve...

This area looks to be what's leading up to the crash..

I've got a feeling that I'm doing the wrong thing here..
i.e.
- Arcserve is asking for an inventory of 1 element at location 0x400.
- mhvtl is reporting back that the size require to return all valid data is 1048 bytes, but Arcserve has only allocated 220 bytes.

Need to look at this and figure out why it thinks it would take 1048 bytes to return all data for one element... it should only require about 100bytes per element..
  ===========

[06/15 16:49:17 12d4  3                   ] ChangerAllocateMemory()
[06/15 16:49:17 12d4  2                   ]      LDN:12 ChgReadElementStatusStorage: Starting Element[400] # of Elements[1]
[06/15 16:49:17 12d4  2                   ] =>ABSL:1031 [READ ELEMENT STAT] b8 12 04 00 00 01 00 00 00 6e tt[20]
[06/15 16:49:17 12d4  2                   ] ---------------Read Element Status HEX DUMP----------------
[06/15 16:49:17 12d4  2                   ] Hexdump Starts --------------------
[06/15 16:49:17 12d4  2                   ] 04 00 00 01 00 00 04 18 02 80 00 34 00 00 00 34   ...........4...4
[06/15 16:49:17 12d4  2                   ] 04 00 09 00 ff ff 00 00 00 01 00 00 55 42 4d 30   ............UBM0
[06/15 16:49:17 12d4  2                   ] 30 31 4c 35 20 20 20 20 20 20 20 20 20 20 20 20   01L5            
[06/15 16:49:17 12d4  2                   ] 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20                  
[06/15 16:49:17 12d4  2                   ] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00   ................
[06/15 16:49:17 12d4  2                   ] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00   ................
[06/15 16:49:17 12d4  2                   ] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 20 20   ..............  
[06/15 16:49:17 12d4  2                   ] 20 20 20 20 00 00 00 00 04 02 09 00 ff ff 00 00       ............
[06/15 16:49:17 12d4  2                   ]
[06/15 16:49:17 12d4  1                   ]      LDN:12 Byte count reported [1048] Buffersize[220] - possible data overflow!
[06/15 16:49:17 12d4  2                   ]      LDN:12 ChgReadElementStatusStorage: Reporting [1] Elements...
Regards from Australia
Mark Harvey
Reply | Threaded
Open this post in threaded view
|

Re: Help: using mhvtl on ubuntu server with CA Arcserve

sbcedservice
Hi Mark,

any news?

Reply | Threaded
Open this post in threaded view
|

Re: Help: using mhvtl on ubuntu server with CA Arcserve

Mark Harvey
Administrator
Sorry, been a little unwell this last week..

It will most likely be another few days before I get back to this as I'm sure there is a backlog waiting for me at work..

Sent from my iPad

On Jun 25, 2012, at 19:54, "sbcedservice [via MHVTL - Linux Virtual Tape Library - CommunityForums]" <[hidden email]> wrote:

Hi Mark,

any news?




To start a new topic under MHVTL - Linux Virtual Tape Library - Community Forums, email [hidden email]
To unsubscribe from MHVTL - Linux Virtual Tape Library - Community Forums, click here.
NAML
Regards from Australia
Mark Harvey
Reply | Threaded
Open this post in threaded view
|

Re: Help: using mhvtl on ubuntu server with CA Arcserve

sbcedservice
Hallo Mark,

now I'm using MHVTL v1.4.1-883f5d4, and all is woking ok, but the inventory function still have the problem, with the Arcserve tape service crashing.

Here is the log of last test, where the only thing I can see is the "possible data overflow" warning; but the crash seems to happen some more operations later (at the end of log the service stop work):

[08/28 15:15:40 0e54  3 DM               4]      LDN:12 ...in ChangerInventorySlots
[08/28 15:15:40 0e54  2 DM               4]      LDN:12 Inventory slots.
[08/28 15:15:40 0e54  3 DM               4] ChangerAllocateMemory()
[08/28 15:15:40 0e54  3 DM               4] ChangerAllocateMemory()
[08/28 15:15:40 0e54  3 DM               4] ChangerAllocateMemory()
[08/28 15:15:40 0e54  2 DM               4]      LDN:12 Inventory Slot[2]
[08/28 15:15:40 0e54  2 DM               4]      LDN:12 Enter LockUnLockAllSlotGroups for Slot[2] to Slot[2]
[08/28 15:15:40 0e54  3 DM               4] GetGCBHandleFromGroupName(): GroupName [UNIX]
[08/28 15:15:40 0e54  3 DM               4] GetGCBHandleFromGroupName(): GroupName [UNIX]
[08/28 15:15:40 0e54  2 DM               4]   GRP: Locking Group [UNIX], JobType 8
[08/28 15:15:40 0e54  3 DM               4] ChangerAllocateMemory()
[08/28 15:15:40 0e54  3 DM               4]      LDN:12 ...in _ChgGetChangerDrive
[08/28 15:15:40 0e54  3 DM               4]      LDN:12 ...in ChgGetWithTapeChangerDrive
[08/28 15:15:40 0e54  2 DM               4]      LDN:12 ChgGetWithTapeChangerDrive: Group Information not available
[08/28 15:15:40 0e54  3 DM               4]      LDN:12 ...in ChgGetEmptyChangerDrive: Searching for any empty Library drive
[08/28 15:15:40 0e54  2 DM               4]      LDN:12 offset[0] Drive[13] is enabled but not empty and is associated with gorup: [UNIX]
[08/28 15:15:40 0e54  3 DM               4]      LDN:12 ...in ChgGetAnyChangerDrive
[08/28 15:15:40 0e54  3 DM               4] Attempting to Lock device [13] timeout=[0]
[08/28 15:15:40 0e54  3 DM               4] Successfully Locked device [13]
[08/28 15:15:40 0e54  2 DM               4]      LDN:12 Total number of free drives 0
[08/28 15:15:40 0e54  2 DM               4]      LDN:12 Found any Library drive offset[0] LDN:13 TInDrive group [UNIX]
[08/28 15:15:40 0e54  2 DM               4]      LDN:12 ChgGetChangerDrive: found Any drive!
[08/28 15:15:40 0e54  3 DM               4] ...in ChangerTestUnitReady
[08/28 15:15:40 0e54  2 DM               4] =>ABSL:1011 [TEST UNIT READY ] 00 20 00 00 00 00 00 00 00 80 tt[5]
[08/28 15:15:40 0e54  3 DM               4] Attempting to UnLock device [13]
[08/28 15:15:40 0e54  3 DM               4] Successfully UnLocked device [13]
[08/28 15:15:40 0e54  3 DM               4]      LDN:12 ...in ChgReturnTapesInSlotRangeToHomeSlot
[08/28 15:15:40 0e54  2 DM               4]      LDN:12 ...in ChgReturnTapeToHomeSlot
[08/28 15:15:40 0e54  3 DM               4]      LDN:12 ...in ChgGetOriginalHomeSlot: for LDN[13]
[08/28 15:15:40 0e54  2 DM               4]      LDN:12 ChgOriginalHomeSlot(): offset in TID:0 LDN:13 homeSlot[0] Invert:0
[08/28 15:15:40 0e54  2 DM               4]      LDN:12 Returning tape from Drive[13] to Slot :0.
[08/28 15:15:40 0e54  2 DM               4] Drive[13] Eject the tape from drive.
[08/28 15:15:40 0e54  3 DM               4]      LDN:12 ...in ChgAskLibraryIfDriveIsFull
[08/28 15:15:40 0e54  3 DM               4] ChangerAllocateMemory()
[08/28 15:15:40 0e54  2 DM               4] =>ABSL:1011 [READ ELEMENT STAT] b8 24 00 01 00 01 00 00 00 80 tt[20]
[08/28 15:15:40 0e54  2 DM               4] ---------------Read Element Status HEX DUMP----------------
[08/28 15:15:40 0e54  2 DM               4] Hexdump Starts --------------------
[08/28 15:15:40 0e54  2 DM               4] 00 01 00 01 00 00 00 18 04 00 00 10 00 00 00 10   ................
[08/28 15:15:40 0e54  2 DM               4] 00 01 09 00 ff ff 00 00 00 81 04 00 00 00 00 00   ................
[08/28 15:15:40 0e54  2 DM               4] 30 31 4c 35 20 20 20 20 20 20 20 20 20 20 20 20   01L5            
[08/28 15:15:40 0e54  2 DM               4] 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20                  
[08/28 15:15:40 0e54  2 DM               4] 00 00 00 00 04 01 09 00 ff ff 00 00 00 01 00 00   ................
[08/28 15:15:40 0e54  2 DM               4] 49 42 4d 30 30 32 4c 35 20 20 20 20 20 20 20 20   IBM002L5        
[08/28 15:15:40 0e54  2 DM               4] 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20                  
[08/28 15:15:40 0e54  2 DM               4] 20 20 20 20 00 00 00 00 04 02 09 00 ff ff 00 00       ............
[08/28 15:15:40 0e54  2 DM               4]
[08/28 15:15:40 0e54  2 DM               4] -------------- Read Element Status HEX DUMP ----------------
[08/28 15:15:40 0e54  2 DM               4] Hexdump Starts --------------------
[08/28 15:15:40 0e54  2 DM               4] 00 01 00 01 00 00 00 18 04 00 00 10 00 00 00 10   ................
[08/28 15:15:40 0e54  2 DM               4] 00 01 09 00 ff ff 00 00 00 81 04 00 00 00 00 00   ................
[08/28 15:15:40 0e54  2 DM               4] 30 31 4c 35 20 20 20 20 20 20 20 20 20 20 20 20   01L5            
[08/28 15:15:40 0e54  2 DM               4] 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20                  
[08/28 15:15:40 0e54  2 DM               4] 00 00 00 00 04 01 09 00 ff ff 00 00 00 01 00 00   ................
[08/28 15:15:40 0e54  2 DM               4] 49 42 4d 30 30 32 4c 35 20 20 20 20 20 20 20 20   IBM002L5        
[08/28 15:15:40 0e54  2 DM               4] 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20                  
[08/28 15:15:40 0e54  2 DM               4] 20 20 20 20 00 00 00 00 04 02 09 00 ff ff 00 00       ............
[08/28 15:15:40 0e54  2 DM               4]
[08/28 15:15:40 0e54  2 DM               4]      LDN:12 Element[0x0001] Source Element[0x0400] Valid?[0x81]
[08/28 15:15:40 0e54  2 DM               4]       Drive[0x0001] is FULL!
[08/28 15:15:40 0e54  2 DM               4]      LDN:12 Slot address of media in Drive[0x0001] is [0x0400]
[08/28 15:15:40 0e54  3 DM               4] ChangerFreeMemory()
[08/28 15:15:40 0e54  3 DM               4]      LDN:12 ...in ChgDriveElementStatus
[08/28 15:15:40 0e54  3 DM               4] ChangerAllocateMemory()
[08/28 15:15:40 0e54  2 DM               4] =>ABSL:1011 [READ ELEMENT STAT] b8 24 00 01 00 01 00 00 00 80 tt[20]
[08/28 15:15:40 0e54  2 DM               4] ---------------Read Element Status HEX DUMP----------------
[08/28 15:15:40 0e54  2 DM               4] Hexdump Starts --------------------
[08/28 15:15:40 0e54  2 DM               4] 00 01 00 01 00 00 00 18 04 00 00 10 00 00 00 10   ................
[08/28 15:15:40 0e54  2 DM               4] 00 01 09 00 ff ff 00 00 00 81 04 00 00 00 00 00   ................
[08/28 15:15:40 0e54  2 DM               4] 30 31 4c 35 20 20 20 20 20 20 20 20 20 20 20 20   01L5            
[08/28 15:15:40 0e54  2 DM               4] 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20                  
[08/28 15:15:40 0e54  2 DM               4] 00 00 00 00 04 01 09 00 ff ff 00 00 00 01 00 00   ................
[08/28 15:15:40 0e54  2 DM               4] 49 42 4d 30 30 32 4c 35 20 20 20 20 20 20 20 20   IBM002L5        
[08/28 15:15:40 0e54  2 DM               4] 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20                  
[08/28 15:15:40 0e54  2 DM               4] 20 20 20 20 00 00 00 00 04 02 09 00 ff ff 00 00       ............
[08/28 15:15:40 0e54  2 DM               4]
[08/28 15:15:40 0e54  2 DM               4] -------------- Read Element Status HEX DUMP ----------------
[08/28 15:15:40 0e54  2 DM               4] Hexdump Starts --------------------
[08/28 15:15:40 0e54  2 DM               4] 00 01 00 01 00 00 00 18 04 00 00 10 00 00 00 10   ................
[08/28 15:15:40 0e54  2 DM               4] 00 01 09 00 ff ff 00 00 00 81 04 00 00 00 00 00   ................
[08/28 15:15:40 0e54  2 DM               4] 30 31 4c 35 20 20 20 20 20 20 20 20 20 20 20 20   01L5            
[08/28 15:15:40 0e54  2 DM               4] 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20                  
[08/28 15:15:40 0e54  2 DM               4] 00 00 00 00 04 01 09 00 ff ff 00 00 00 01 00 00   ................
[08/28 15:15:40 0e54  2 DM               4] 49 42 4d 30 30 32 4c 35 20 20 20 20 20 20 20 20   IBM002L5        
[08/28 15:15:40 0e54  2 DM               4] 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20                  
[08/28 15:15:40 0e54  2 DM               4] 20 20 20 20 00 00 00 00 04 02 09 00 ff ff 00 00       ............
[08/28 15:15:40 0e54  2 DM               4]
[08/28 15:15:40 0e54  2 DM               4]      LDN:12 Element[0x0001] Source Element[0x0400] Valid?[0x81]
[08/28 15:15:40 0e54  3 DM               4] ChangerFreeMemory()
[08/28 15:15:40 0e54  3 DM               4]      LDN:12 ChgMoveMedium: From address:[1] to address:[400] Flip:[0] MovingOut?[1].
[08/28 15:15:40 0e54  2 DM               4]      LDN:12 Moving Tape from Library Drive[13] to Slot[0]
[08/28 15:15:40 0e54  2 DM               4] =>ABSL:1012 [UNLOAD TAPE     ] 1b 00 00 00 00 00 00 01 44 00 tt[30]
[08/28 15:15:40 108c  2                   ] =>ABSL:0060 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 00 00 tt[5]
[08/28 15:15:40 0aa4  2                   ] =>ABSL:0090 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 00 00 tt[5]
[08/28 15:15:40 12b0  2                   ] =>ABSL:2000 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 00 00 tt[5]
[08/28 15:15:40 0ee8  2                   ] =>ABSL:00140 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 00 00 tt[5]
[08/28 15:15:40 0184  2                   ] =>ABSL:00150 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 00 00 tt[5]
[08/28 15:15:40 0ec4  2                   ] =>ABSL:0040 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 00 00 tt[5]
[08/28 15:15:40 0dc8  2                   ] =>ABSL:0050 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 00 00 tt[5]
[08/28 15:15:40 0f00  2                   ] =>ABSL:00110 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 00 00 tt[5]
[08/28 15:15:40 13e0  2                   ] =>ABSL:00130 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 00 00 tt[5]
[08/28 15:15:40 0924  2                   ] =>ABSL:0030 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 00 00 tt[5]
[08/28 15:15:40 0750  2                   ] =>ABSL:0010 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 00 00 tt[5]
[08/28 15:15:40 10e0  2                   ] =>ABSL:0070 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 00 00 tt[5]
[08/28 15:15:40 1318  2                   ] =>ABSL:0080 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 00 00 tt[5]
[08/28 15:15:40 0920  2                   ] =>ABSL:00120 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 00 00 tt[5]
[08/28 15:15:40 10b4  2                   ] =>ABSL:0020 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 00 00 tt[5]
[08/28 15:15:40 0df0  2                   ] =>ABSL:00100 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 00 00 tt[5]
[08/28 15:15:40 0e54  3 DM               4]      LDN:12 Start _ChgMoveMedium: Source[1] Dest[400]
[08/28 15:15:40 0e54  2 DM               4]      LDN:12 MOVE->: TapeName[8/28/12 10:07 AM] RID[8F0F] Seq[1] BarCode[IBM001L5] Slot[0]
[08/28 15:15:40 0e54  2 DM               4]      LDN:12 MOVE->: From Drive[13] ABSL[1012] S/N[10000031  ] Element[0x0001]
[08/28 15:15:40 0e54  2 DM               4]      LDN:12 MOVE->: To Slot[0] Element[0x0400]
[08/28 15:15:40 0e54  3 DM               4] LDN: [12] Locking Library in _ChgMoveMedium; Flag: [0x13]
[08/28 15:15:40 0e54  2 DM               4] =>ABSL:1011 [MOVE MEDIUM     ] a5 20 01 00 00 01 04 00 00 00 tt[20]
[08/28 15:15:40 0e54  3 DM               4]      LDN:12 ...in ChgCheckElementType
[08/28 15:15:40 0e54  3 DM               4]      LDN:12 ...in ChgCheckElementType
[08/28 15:15:40 0e54  2 DM               4] Serial number update on drive[13] ABSL[1012], newSN is NULL, previousSN[IBM001L5]: Tape is moved out from the drive.
[08/28 15:15:40 0e54  3 DM               4]      LDN:12 ClearSem in _ChgMoveMedium; Flag: [0x11]
[08/28 15:15:40 0e54  3 DM               4]      LDN:12 ...in ChgRecordTapeInDrive
[08/28 15:15:40 0e54  3 DM               4]      LDN:12 RecordTapeInDrive(OUT): Offset:0 DEA:1 SEA:1024 Invert:0 Group:[UNIX]
[08/28 15:15:40 0e54  3 DM               4] Attempting to Lock device [13] timeout=[0]
[08/28 15:15:40 0e54  3 DM               4] Successfully Locked device [13]
[08/28 15:15:40 0e54  3 DM               4]      LDN:12 ...in ChgRezeroUnit
[08/28 15:15:40 0e54  3 DM               4] ChgTestUnitReady: Entering Critical Section!
[08/28 15:15:40 0e54  2 DM               4] =>ABSL:1011 [TEST UNIT READY ] 00 20 00 00 00 00 04 00 00 00 tt[5]
[08/28 15:15:40 0e54  3 DM               4]                  Test Unit Ready Leaving Critical Section!
[08/28 15:15:40 0e54  3 DM               4]                  Test Unit Ready Left Critical Section!
[08/28 15:15:40 0e54  3 DM               4]      LDN:12 ...in ChgInitializeElementStatus
[08/28 15:15:40 0e54  2 DM               4]      LDN:12 Initialize Element Status
[08/28 15:15:40 0e54  2 DM               4] =>ABSL:1011 [INIT ELEMENT STAT] 07 20 00 00 00 00 00 00 00 00 tt[300]
[08/28 15:15:40 0c48  3 DM               4] RPCSetServerContext(): Context is already set to Server [SBBK00] hJob[042EA054]
[08/28 15:15:40 10ac  3 DM               4] GetXCB 9255: ulTime[943493750]
[08/28 15:15:40 0c48  3 DM               4] GetXCB 9255: ulTime[943493781]
[08/28 15:15:40 10ac  3 DM               4] GetXCB 9255: ulTime[943493796]
[08/28 15:15:40 0c48  3 DM               4] GetXCB 9255: ulTime[943493796]
[08/28 15:15:40 0c48  3 DM               4] GetXCB 9255: ulTime[943493796]
[08/28 15:15:40 10ac  3 DM               4] DEVICE_QUERY_EXTENDED_CAPS ulCapFlags0[458753], ulCapFlags1[128]
[08/28 15:15:40 10ac  3 DM               4] DEVICE_QUERY_EXTENDED_CAPS SERVER NAME[SBBK00]
[08/28 15:15:41 0e54  3 DM               4] ChangerAllocateMemory()
[08/28 15:15:41 0e54  3 DM               4] ChangerAllocateMemory()
[08/28 15:15:41 0e54  2 DM               4]      LDN:12 ChangerUpdateSlotOneThread created ThreadID[12D0]
[08/28 15:15:41 0e54  2 DM               4] >>WaitForAllThreads<<
[08/28 15:15:41 12d0  3                   ]      LDN:12 ...in ChangerUpdateSlotOneThread
[08/28 15:15:41 12d0  2                   ]      LDN:12 Start updating Slot information for Slot[2], No BC
[08/28 15:15:41 12d0  3                   ]      LDN:12 ...in ChgNoBCUpdateSlotInfo
[08/28 15:15:41 12d0  2                   ] =>ABSL:1011 [TEST UNIT READY ] 00 20 00 00 00 00 00 00 00 00 tt[5]
[08/28 15:15:41 12d0  3                   ] ChangerAllocateMemory()
[08/28 15:15:41 12d0  2                   ]      LDN:12 ChgReadElementStatusStorage: Starting Element[402] # of Elements[1]
[08/28 15:15:41 12d0  2                   ] =>ABSL:1011 [READ ELEMENT STAT] b8 12 04 02 00 01 00 00 00 6e tt[20]
[08/28 15:15:41 12d0  2                   ] ---------------Read Element Status HEX DUMP----------------
[08/28 15:15:41 12d0  2                   ] Hexdump Starts --------------------
[08/28 15:15:41 12d0  2                   ] 04 02 00 01 00 00 03 b0 02 80 00 34 00 00 00 34   ...........4...4
[08/28 15:15:41 12d0  2                   ] 04 02 09 00 ff ff 00 00 00 01 00 00 49 42 4d 30   ............IBM0
[08/28 15:15:41 12d0  2                   ] 30 33 4c 35 20 20 20 20 20 20 20 20 20 20 20 20   03L5            
[08/28 15:15:41 12d0  2                   ] 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20                  
[08/28 15:15:41 12d0  2                   ] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00   ................
[08/28 15:15:41 12d0  2                   ] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00   ................
[08/28 15:15:41 12d0  2                   ] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 20 20   ..............  
[08/28 15:15:41 12d0  2                   ] 20 20 20 20 00 00 00 00 04 02 09 00 ff ff 00 00       ............
[08/28 15:15:41 12d0  2                   ]
[08/28 15:15:41 12d0  1                   ]      LDN:12 Byte count reported [944] Buffersize[220] - possible data overflow!
[08/28 15:15:41 12d0  2                   ]      LDN:12 ChgReadElementStatusStorage: Reporting [1] Elements...
[08/28 15:15:41 12d0  3                   ]      LDN:12 ...in ChgCheckIfTapeInAnyChangerDrive
[08/28 15:15:41 12d0  3                   ]  ChgCheckIfTapeInAnyChangerDrive: locked device[12] after 0 retry  
[08/28 15:15:41 12d0  2                   ]      LDN:12 Update Tape in Slot[2]
[08/28 15:15:41 12d0  2                   ]      LDN:12 B/C[IBM003L5] in Slot[2]
[08/28 15:15:41 12d0  2                   ]      LDN:13 ...in ChgInsertTapeIntoTapeDrive: Moving from Slot[2] to Drive[13]
[08/28 15:15:41 12d0  3                   ]      LDN:12 ChgMoveMedium: From address:[402] to address:[1] Flip:[0] MovingOut?[1].
[08/28 15:15:41 12d0  2                   ]      LDN:12 Moving Tape from Slot[2] to Library Drive[13]
[08/28 15:15:41 12d0  2                   ]      LDN:12 Issue a LOAD for LTO or DLT Drive[13]
[08/28 15:15:41 12d0  2                   ] =>ABSL:1012 [LOAD TAPE       ] 1b 00 00 00 01 00 00 01 44 00 tt[30]
[08/28 15:15:43 12d0  2                   ] =>ABSL:1012 [TEST UNIT READY ] 00 40 00 00 00 00 00 01 44 00 tt[5]
[08/28 15:15:43 12d0  3                   ] =>ABSL:1012 <TEST UNIT READY> - Returning status[2]
[08/28 15:15:43 12d0  3                   ] Sense Data allready returned.
[08/28 15:15:43 12d0  2                   ] =>ABSL:1012 [REQUEST SENSE   ] 03 00 00 00 40 00 00 01 44 00
[08/28 15:15:43 12d0  2                   ]             <TEST UNIT READY     >, Sense Data as Follows:
[08/28 15:15:43 12d0  2                   ]          SENSE ABSL:1012      f0 00 02 00 00 00 00 58 00 00 00 00 3a
[08/28 15:15:43 12d0  2                   ]       EX SENSE ABSL:1012      00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[08/28 15:15:43 12d0  2                   ]          SENSE ABSL:1012      Not Ready [02]
[08/28 15:15:43 12d0  2                   ]       EX SENSE ABSL:1012      Medium Not Present [3a, 00]
[08/28 15:15:43 12d0  3                   ]      LDN:12 ...in ChgCheckIfTapeInAnyChangerDrive
[08/28 15:15:43 12d0  3                   ]  ChgCheckIfTapeInAnyChangerDrive: locked device[12] after 0 retry  
[08/28 15:15:43 12d0  3                   ]      LDN:12 Moving tape from Element [402] to Element [1]
[08/28 15:15:43 12d0  3                   ]      LDN:12 Start _ChgMoveMedium: Source[402] Dest[1]
[08/28 15:15:43 12d0  2                   ]      LDN:12 MOVE->: TapeName[] RID[0] Seq[0] BarCode[IBM003L5] Slot[2]
[08/28 15:15:43 12d0  2                   ]      LDN:12 MOVE->: From Slot[2] Element[0x0402]
[08/28 15:15:43 12d0  2                   ]      LDN:12 MOVE->: To Drive[13] ABSL[1012] S/N[10000031  ] Element[0x0001]
[08/28 15:15:43 12d0  3                   ] LDN: [12] Locking Library in _ChgMoveMedium; Flag: [0x13]
[08/28 15:15:43 12d0  2                   ] =>ABSL:1011 [MOVE MEDIUM     ] a5 20 01 00 04 02 00 01 00 00 tt[20]
[08/28 15:15:43 12d0  3                   ]      LDN:12 ...in ChgCheckElementType
[08/28 15:15:43 12d0  2                   ]      LDN:12 Indicates that the tape needs to be read in
[08/28 15:15:43 12d0  2                   ] Serial number update on drive[13] ABSL[1012], newSN[IBM003L5], previousSN[]: Tape is from slot[2].
[08/28 15:15:43 12d0  3                   ]      LDN:12 ...in ChgCheckElementType
[08/28 15:15:43 12d0  3                   ]      LDN:12 ClearSem in _ChgMoveMedium; Flag: [0x11]
[08/28 15:15:43 12d0  3                   ]      LDN:12 ...in ChgRecordTapeInDrive
[08/28 15:15:43 12d0  3                   ]      LDN:12 RecordTapeInDrive(IN): Offset:0 DEA:1 SEA:1026 Invert:0 Group:[UNIX]
[08/28 15:15:45 12d0  2                   ] =>ABSL:1012 [TEST UNIT READY ] 00 40 00 00 00 00 00 01 44 00 tt[5]
[08/28 15:15:45 12d0  2                   ]
[08/28 15:15:45 12d0  2                   ]  LDN:12 InsertTapeInDrive: ABSL:1012 Slot[2] BarCode[IBM003L5] sflags[822]**
[08/28 15:15:45 12d0  3                   ] ChgGetSlotFlags(): Slot[02], SlotFlag[0x00000822], ChgLDN[0]
[08/28 15:15:45 12d0  2                   ] =>ABSL:1012 [TEST UNIT READY ] 00 40 00 00 00 00 00 01 44 00 tt[5]
[08/28 15:15:45 12d0  3                   ] LDN:1369814424 Calling LoadTapeDrive
[08/28 15:15:45 12d0  2                   ] =>ABSL:1012 [LOAD TAPE       ] 1b 00 00 00 01 00 00 01 44 00 tt[30]
[08/28 15:15:45 12d0  2                   ] =>ABSL:1012 [MODE SENSE      ] 1a 00 00 00 0c 00 00 01 44 00 tt[5]
[08/28 15:15:45 12d0  2                   ]    MS Page[00] ABSL:1012      0b 58 10 08 58 00 00 00 00 01 00 00
[08/28 15:15:45 12d0  2                   ]                ABSL:1012      00 00 00 00 00 00 00 00 00 00 00 00
[08/28 15:15:45 12d0  2                   ]         DRV:13 Tape is NOT Write Protected
[08/28 15:15:45 12d0  3                   ] Calling SetTapeDriveModes
[08/28 15:15:45 12d0  3                   ] Calling SetDensityCode
[08/28 15:15:45 12d0  3                   ] Calling SetErrorRecoveryAndReporting
[08/28 15:15:45 12d0  3                   ] Calling SetGapThreshold
[08/28 15:15:45 12d0  3                   ] Calling SetDriveCompressionMode
[08/28 15:15:45 12d0  2                   ] =>ABSL:1012 [MODE SENSE      ] 1a 00 0f 00 1c 00 00 01 44 00 tt[5]
[08/28 15:15:45 12d0  2                   ]    MS Page[0f] ABSL:1012      1b 58 10 08 58 00 00 00 00 01 00 00
[08/28 15:15:45 12d0  2                   ]                ABSL:1012      0f 0e 40 80 ff ff ff ff ff ff ff ff
[08/28 15:15:45 12d0  2                   ] LDN:13 Compression Not Enabled
[08/28 15:15:45 12d0  2                   ]         DRV:13 Compression Not Enabled
[08/28 15:15:45 12d0  3                   ] Calling SetDrivePaddingMode
[08/28 15:15:45 12d0  2                   ] =>ABSL:1012 [ENCRYPT CHECK   ] a2 20 00 10 00 00 00 00 00 40 tt[5]
[08/28 15:15:45 12d0  2                   ]  SpIn Page[10] ABSL:1012      00 10 00 28 01 00 00 00 00 00 00 00
[08/28 15:15:45 12d0  2                   ]                ABSL:1012      00 00 00 00 00 00 00 00 01 00 00 14
[08/28 15:15:45 12d0  2                   ] =>ABSL:1012 [TEST UNIT READY ] 00 40 00 00 00 00 00 00 00 40 tt[5]
[08/28 15:15:45 12d0  2                   ] =>ABSL:1012 [INQUIRY         ] 12 41 b0 00 40 00 00 00 00 40 tt[1]
[08/28 15:15:45 12d0  2                   ] ---------------INQUIRY HEX DUMP----------------
[08/28 15:15:45 12d0  2                   ] Hexdump Starts --------------------
[08/28 15:15:45 12d0  2                   ] 01 b0 00 04 01 00 00 00 00 00 00 00 00 00 00 00   ................
[08/28 15:15:45 12d0  2                   ] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00   ................
[08/28 15:15:45 12d0  2                   ] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00   ................
[08/28 15:15:45 12d0  2                   ] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00   ................
[08/28 15:15:45 12d0  2                   ]
[08/28 15:15:45 12d0  2                   ] DEVICE13 [IBM     ULT3580-TD5     A3K6] - WORM drive
[08/28 15:15:45 12d0  2                   ] [ULT3580-TD5     A3K6] WORM drive
[08/28 15:15:45 12d0  2                   ] =>ABSL:1012 [MODE SENSE      ] 1a 00 00 00 40 00 00 00 00 40 tt[5]
[08/28 15:15:45 12d0  2                   ]    MS Page[00] ABSL:1012      0b 58 10 08 58 00 00 00 00 01 00 00
[08/28 15:15:45 12d0  2                   ]                ABSL:1012      00 00 00 00 00 00 00 00 01 00 00 14
[08/28 15:15:45 12d0  2                   ] NOT WORM []loaded
[08/28 15:15:45 12d0  2                   ] NOT WORM capable tape []loaded, Density[0x00]
[08/28 15:15:45 12d0  2                   ] =>ABSL:1012 [MODE SENSE      ] 1a 00 11 00 40 00 00 00 00 40 tt[5]
[08/28 15:15:45 12d0  2                   ]    MS Page[11] ABSL:1012      1b 58 10 08 58 00 00 00 00 01 00 00
[08/28 15:15:45 12d0  2                   ]                ABSL:1012      11 0e 00 00 00 00 00 00 00 00 00 00
[08/28 15:15:45 12d0  2                   ] =>ABSL:1012 [TEST UNIT READY ] 00 40 00 00 00 00 00 00 00 40 tt[5]
[08/28 15:15:45 12d0  3                   ] DB: Updating tape Usage info in database.
[08/28 15:15:45 12d0  2                   ] DB: Updating tapeusage in database: START
[08/28 15:15:45 12d0  3                   ] DB: BABTapeDB Semaphore [4f0] Locking...
[08/28 15:15:45 12d0  3                   ] DB: BABTapeDB Semaphore [4f0] LOCKED!
[08/28 15:15:45 12d0  3                   ] DB: IMPERSONATE
[08/28 15:15:45 12d0  3                   ] DB: BABTapeDB Semaphore [4f0] Releasing
[08/28 15:15:45 12d0  2                   ] DB: Finished Updating tapeusage in database: END
[08/28 15:15:45 12d0  3                   ] DB: REVERT
[08/28 15:15:45 12d0  2                   ] =>ABSL:1012 [REWIND          ] 01 00 00 00 00 00 00 00 00 40 tt[30]
[08/28 15:15:45 12d0  2                   ] =>ABSL:1012 [READ POSITION   ] 34 00 00 00 00 00 00 00 00 00 tt[6]
[08/28 15:15:45 12d0  2                   ]         DRV:13 Current block #: [00000000] [0]
[08/28 15:15:45 12d0  2                   ] =>ABSL:1012 [MODE SENSE      ] 1a 00 00 00 0c 00 00 00 00 00 tt[5]
[08/28 15:15:45 12d0  2                   ]    MS Page[00] ABSL:1012      0b 58 10 08 58 00 00 00 00 01 00 00
[08/28 15:15:45 12d0  2                   ]                ABSL:1012      00 00 00 00 00 00 00 00 00 00 00 00
[08/28 15:15:45 12d0  2                   ] =>ABSL:1012 [READ            ] 08 01 00 00 01 00 00 00 00 00 tt[20]
[08/28 15:15:45 12d0  3                   ] =>ABSL:1012 <READ> - Returning status[2]
[08/28 15:15:45 12d0  3                   ] Sense Data allready returned.
[08/28 15:15:45 12d0  2                   ] =>ABSL:1012 [REQUEST SENSE   ] 03 00 00 00 40 00 00 00 00 00
[08/28 15:15:45 12d0  2                   ]             <READ                >, Sense Data as Follows:
[08/28 15:15:45 12d0  2                   ]          SENSE ABSL:1012      f0 00 08 00 00 00 01 58 00 00 00 00 00
[08/28 15:15:45 12d0  2                   ]       EX SENSE ABSL:1012      05 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[08/28 15:15:45 12d0  2                   ]          SENSE ABSL:1012      Blank Check [08]
[08/28 15:15:45 12d0  2                   ]       EX SENSE ABSL:1012      End-Of-Data Detected [00, 05]
[08/28 15:15:45 12d0  2                   ]         DRV:13 Read TH RID: [2000], VTL Expiration cleared.
[08/28 15:15:45 12d0  2                   ]         DRV:13 Found Totally Blank Tape, Blank Check
[08/28 15:15:45 12d0  2                   ] =>ABSL:1012 [MODE SENSE      ] 1a 00 11 00 40 00 00 00 00 00 tt[5]
[08/28 15:15:45 12d0  2                   ]    MS Page[11] ABSL:1012      1b 58 10 08 58 00 00 00 00 01 00 00
[08/28 15:15:45 12d0  2                   ]                ABSL:1012      11 0e 00 00 00 00 00 00 00 00 00 00
[08/28 15:15:45 12d0  2                   ] Barcode[IBM003L5] does not match with the internal serial number[] on the tape Name[] RandomID[0000] SequenceNo[0]
[08/28 15:15:45 12d0  2                   ] =>ABSL:1012 [REWIND          ] 01 00 00 00 00 00 00 00 00 00 tt[30]
[08/28 15:15:45 12d0  2                   ] =>ABSL:1012 [READ POSITION   ] 34 00 00 00 00 00 00 00 00 00 tt[6]
[08/28 15:15:45 12d0  2                   ]         DRV:13 Current block #: [00000000] [0]
[08/28 15:15:45 12d0  2                   ] DB: DBRegisterTape is being called. DBState[2]
[08/28 15:15:45 12d0  2                   ] DB: CAlling DBRegisterTapeInternal() [2]
[08/28 15:15:45 12d0  2                   ] DB: dbRegisterape found BarCode.
[08/28 15:15:45 12d0  2                   ] DB: Serial# (Blank tape) 'IBM003L5' saved to registry.
[08/28 15:15:45 12d0  3                   ]      LDN:12 TSI_CF_READ_TAPE is set, and PrepTapeDrive returned:[0x0]
[08/28 15:15:45 12d0  3                   ] ChgGetSlotFlags(): Slot[02], SlotFlag[0x00000822], ChgLDN[0]
[08/28 15:15:45 12d0  3                   ] Slot[2] NO WORM Flag
[08/28 15:15:45 12d0  3                   ]      LDN:12(BLANK w/BarCode), Slot[2], BC from Library [IBM003L5], TH [IBM003L5]
[08/28 15:15:45 12d0  3                   ] LDN[12] ChgEnterSlotInfo(): Update Slot[2] with SlotFlag[0x10000911]

Reply | Threaded
Open this post in threaded view
|

Re: Help: using mhvtl on ubuntu server with CA Arcserve

Mark Harvey
Administrator
Thanks for the reminder that I've still got this on my todo list.

I should really create a todo list rather than relying on my memory :)

I did look at this and thought everything looked ok but still had a couple of cosmetic things that need to be fixed.

I'm currently overseas for work (Indian food has not agreed with me this time around) so it will be a couple of weeks before I can submit any time looking into the problem.

The op code in question does return the correct number of bytes (80 odd bytes) and not the 1048 bytes reported in the logs.

However, arcserv may infact believe the library and attempt to xfer 1048 bytes, resulting buffer overflow within arcserv and from then on, anything is possible. After all, if you can't believe the library to the number of bytes returned, who can you believe.

Is arcserv running on windows ?
if so, is it possible to run tcpdump to capture network traffic betweeen vtl & arcserv server.
This will (possibly) indicate if  arcserv is beleiving the early data instead of the actual returned data size.


Regards from Australia
Mark Harvey
Reply | Threaded
Open this post in threaded view
|

Re: Help: using mhvtl on ubuntu server with CA Arcserve

Mark Harvey
Administrator
I've finally overcome my reluctance to revisit the READ ELEMENT STATUS code..

Not sure what I was thinking while writing that code! Couldn't understand what the code was trying to do at all.

It's still not right.. But getting better.

[08/28 15:15:41 12d0  2                   ] =>ABSL:1011 [READ ELEMENT STAT] b8 12 04 02 00 01 00 00 00 6e tt[20]
[08/28 15:15:41 12d0  2                   ] ---------------Read Element Status HEX DUMP----------------
[08/28 15:15:41 12d0  2                   ] Hexdump Starts --------------------
[08/28 15:15:41 12d0  2                   ] 04 02 00 01 00 00 03 b0 02 80 00 34 00 00 00 34   ...........4...4
[08/28 15:15:41 12d0  2                   ] 04 02 09 00 ff ff 00 00 00 01 00 00 49 42 4d 30   ............IBM0
[08/28 15:15:41 12d0  2                   ] 30 33 4c 35 20 20 20 20 20 20 20 20 20 20 20 20   03L5            
[08/28 15:15:41 12d0  2                   ] 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20                  
[08/28 15:15:41 12d0  2                   ] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00   ................
[08/28 15:15:41 12d0  2                   ] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00   ................
[08/28 15:15:41 12d0  2                   ] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 20 20   ..............  
[08/28 15:15:41 12d0  2                   ] 20 20 20 20 00 00 00 00 04 02 09 00 ff ff 00 00       ............
[08/28 15:15:41 12d0  2                   ]
[08/28 15:15:41 12d0  1                   ]      LDN:12 Byte count reported [944] Buffersize[220] - possible data overflow!
Any chance of applying the following patch to see if it resolves this above error. (It should !)

Potentially fixes the Arcserve problem crashing too (if this bug was the one tripping it up).

fix_read_element_status.diff
Regards from Australia
Mark Harvey
Reply | Threaded
Open this post in threaded view
|

Re: Help: using mhvtl on ubuntu server with CA Arcserve

sbcedservice
Sorry for late answer, but I was far from my office till today.

I'll try the patch as soon as possible.

Regards

Reply | Threaded
Open this post in threaded view
|

Re: Help: using mhvtl on ubuntu server with CA Arcserve

Mark Harvey
Administrator
this patch is old....

Several bugs identified. Hopefully all resolved now. Please use github as latest & greatest :)

Thanks

Sent from my iPad

On Mar 18, 2013, at 20:04, "sbcedservice [via MHVTL - Linux Virtual Tape Library - Community Forums]" <[hidden email]> wrote:

Sorry for late answer, but I was far from my office till today.

I'll try the patch as soon as possible.

Regards




To start a new topic under MHVTL - Linux Virtual Tape Library - Community Forums, email [hidden email]
To unsubscribe from MHVTL - Linux Virtual Tape Library - Community Forums, click here.
NAML
Regards from Australia
Mark Harvey