lin_tape: IBMtape modesense10_page failed: -22

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

lin_tape: IBMtape modesense10_page failed: -22

nia
Administrator
This post was updated on .
I am running IBM lin_tape driver with mhvtl-0.18-11 and keep seeing these messages in the sys log:

lin_tape: IBMtape0-----25111 tape_modesense10_page failed: -22
lin_tape: IBMtape0-----25111 tape_modesense10_page failed: -22
lin_tape: IBMtape1-----25112 tape_modesense10_page failed: -22
lin_tape: IBMtape1-----25112 tape_modesense10_page failed: -22
lin_tape: IBMtape2-----25113 tape_modesense10_page failed: -22
lin_tape: IBMtape2-----25113 tape_modesense10_page failed: -22


It does not look like it is causing any issues that I can see in using mhvtl with my application (TSM) but cannot figure out why they keep generating every time TSM tries to use the drives
Reply | Threaded
Open this post in threaded view
|

Re: lin_tape: IBMtape modesense10_page failed: -22

Mark Harvey
Administrator
Do you have any verbose logging enabled on the (mhvtl) tape devices ?
If so, can you please forward the syslog during a period when the 'mode sense 10' failed occurs.

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

Re: lin_tape: IBMtape modesense10_page failed: -22

nia
Administrator
Mark,

This was at the end while TSM was taking tape inventory.


Oct  8 08:35:48 mhvtl vtltape[23587]: CDB (346) 12 01 83 00 ff 00
Oct  8 08:35:48 mhvtl vtltape[23587]: processCommand: INQUIRY (346) **
Oct  8 08:35:48 mhvtl vtltape[23587]: spc_inquiry: Page code 0x83
Oct  8 08:35:48 mhvtl vtltape[23587]: spc_inquiry: Found page 0x83
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: OP s/n: (346), sz: 54, sam_status: 0
Oct  8 08:35:48 mhvtl vtltape[23587]: CDB (347) 00 00 00 00 00 00
Oct  8 08:35:48 mhvtl vtltape[23587]: mkSenseBuf: Sense buf: 0x805faf8
Oct  8 08:35:48 mhvtl vtltape[23587]: mkSenseBuf: SENSE [Key/ASC/ASCQ] [06 29 00]
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: OP s/n: (347), sz: 0, sam_status: 2
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: [Key/ASC/ASCQ] [06 29 00]
Oct  8 08:35:48 mhvtl vtltape[23587]: CDB (348) 00 00 00 00 00 00
Oct  8 08:35:48 mhvtl vtltape[23587]: processCommand: Test Unit Ready (348) : No
Oct  8 08:35:48 mhvtl vtltape[23587]: mkSenseBuf: Sense buf: 0x805faf8
Oct  8 08:35:48 mhvtl vtltape[23587]: mkSenseBuf: SENSE [Key/ASC/ASCQ] [02 3a 00]
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: OP s/n: (348), sz: 0, sam_status: 2
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: [Key/ASC/ASCQ] [02 3a 00]
Oct  8 08:35:48 mhvtl vtltape[23587]: CDB (349) 16 00 00 00 00 00
Oct  8 08:35:48 mhvtl vtltape[23587]: processCommand: Reserve (349) **
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: OP s/n: (349), sz: 0, sam_status: 0
Oct  8 08:35:48 mhvtl vtltape[23587]: CDB (350) 1a 00 10 00 0c 00
Oct  8 08:35:48 mhvtl vtltape[23587]: processCommand: MODE SENSE (350) **
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Mode Sense 6 byte version
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Page Control  : Current configuration(0x0)
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Page Code     : 0x10
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Disable Block Descriptor => No
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Allocation len: 12
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense: pcode: 0x10
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: Entered: pcode 0x10
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: (0x10): match pcode 16
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: OP s/n: (350), sz: 28, sam_status: 0
Oct  8 08:35:48 mhvtl vtltape[23587]: CDB (351) 15 10 00 00 0c 00
Oct  8 08:35:48 mhvtl vtltape[23587]: processCommand: MODE SELECT (351) **
Oct  8 08:35:48 mhvtl vtltape[23587]: retrieve_CDB_data: retrieving 12 bytes from kernel
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: OP s/n: (351), sz: 0, sam_status: 0
Oct  8 08:35:48 mhvtl vtltape[23587]: CDB (352) 1a 08 0f 00 14 00
Oct  8 08:35:48 mhvtl vtltape[23587]: processCommand: MODE SENSE (352) **
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Mode Sense 6 byte version
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Page Control  : Current configuration(0x0)
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Page Code     : 0xf
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Disable Block Descriptor => Yes
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Allocation len: 20
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense: pcode: 0x0f
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: Entered: pcode 0xf
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: (0xf): match pcode 15
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: OP s/n: (352), sz: 20, sam_status: 0
Oct  8 08:35:48 mhvtl vtltape[23587]: CDB (353) 15 10 00 00 14 00
Oct  8 08:35:48 mhvtl vtltape[23587]: processCommand: MODE SELECT (353) **
Oct  8 08:35:48 mhvtl vtltape[23587]: retrieve_CDB_data: retrieving 20 bytes from kernel
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: Entered: pcode 0xf
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: (0xf): match pcode 15
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: Entered: pcode 0x10
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: (0x10): match pcode 16
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: OP s/n: (353), sz: 0, sam_status: 0
Oct  8 08:35:48 mhvtl vtltape[23587]: CDB (354) 1a 08 10 00 14 00
Oct  8 08:35:48 mhvtl vtltape[23587]: processCommand: MODE SENSE (354) **
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Mode Sense 6 byte version
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Page Control  : Current configuration(0x0)
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Page Code     : 0x10
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Disable Block Descriptor => Yes
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Allocation len: 20
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense: pcode: 0x10
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: Entered: pcode 0x10
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: (0x10): match pcode 16
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: OP s/n: (354), sz: 20, sam_status: 0
Oct  8 08:35:48 mhvtl vtltape[23587]: CDB (355) 15 10 00 00 14 00
Oct  8 08:35:48 mhvtl vtltape[23587]: processCommand: MODE SELECT (355) **
Oct  8 08:35:48 mhvtl vtltape[23587]: retrieve_CDB_data: retrieving 20 bytes from kernel
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: Entered: pcode 0xf
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: (0xf): match pcode 15
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: Entered: pcode 0x10
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: (0x10): match pcode 16
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: OP s/n: (355), sz: 0, sam_status: 0
Oct  8 08:35:48 mhvtl vtltape[23587]: CDB (356) 1a 08 01 00 10 00
Oct  8 08:35:48 mhvtl vtltape[23587]: processCommand: MODE SENSE (356) **
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Mode Sense 6 byte version
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Page Control  : Current configuration(0x0)
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Page Code     : 0x1
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Disable Block Descriptor => Yes
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Allocation len: 16
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense: pcode: 0x01
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: Entered: pcode 0x1
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: (0x1): match pcode 1
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: OP s/n: (356), sz: 16, sam_status: 0
Oct  8 08:35:48 mhvtl vtltape[23587]: CDB (357) 1a 00 10 00 ff 00
Oct  8 08:35:48 mhvtl vtltape[23587]: processCommand: MODE SENSE (357) **
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Mode Sense 6 byte version
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Page Control  : Current configuration(0x0)
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Page Code     : 0x10
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Disable Block Descriptor => No
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Allocation len: 255
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense: pcode: 0x10
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: Entered: pcode 0x10
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: (0x10): match pcode 16
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: OP s/n: (357), sz: 28, sam_status: 0
Oct  8 08:35:48 mhvtl vtltape[23587]: CDB (358) 05 00 00 00 00 00
Oct  8 08:35:48 mhvtl vtltape[23587]: processCommand: Read block limits (358) **
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: OP s/n: (358), sz: 6, sam_status: 0
Oct  8 08:35:48 mhvtl vtltape[23587]: CDB (359) 5a 08 24 00 00 00 00 28 08 00
Oct  8 08:35:48 mhvtl vtltape[23587]: processCommand: MODE SENSE (359) **
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Mode Sense 10 byte version
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Page Control  : Current configuration(0x0)
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Page Code     : 0x24
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Disable Block Descriptor => Yes
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Allocation len: 10248
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense: pcode: 0x24
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: Entered: pcode 0x24
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: Page code 0x24 not found
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense: Unknown mode page : 36
Oct  8 08:35:48 mhvtl vtltape[23587]: mkSenseBuf: Sense buf: 0x805faf8
Oct  8 08:35:48 mhvtl vtltape[23587]: mkSenseBuf: SENSE [Key/ASC/ASCQ] [05 24 00]
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: OP s/n: (359), sz: 0, sam_status: 2
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: [Key/ASC/ASCQ] [05 24 00]
Oct  8 08:35:48 mhvtl kernel: lin_tape: IBMtape13----25111 tape_modesense10_page failed: -22
Oct  8 08:35:48 mhvtl vtltape[23587]: CDB (360) 12 00 00 00 ff 00
Oct  8 08:35:48 mhvtl vtltape[23587]: processCommand: INQUIRY (360) **
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: OP s/n: (360), sz: 66, sam_status: 0
Oct  8 08:35:48 mhvtl vtltape[23587]: CDB (361) 01 00 00 00 00 00
Oct  8 08:35:48 mhvtl vtltape[23587]: processCommand: Rewinding (361) **
Oct  8 08:35:48 mhvtl vtltape[23587]: mkSenseBuf: Sense buf: 0x805faf8
Oct  8 08:35:48 mhvtl vtltape[23587]: mkSenseBuf: SENSE [Key/ASC/ASCQ] [02 3a 00]
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: OP s/n: (361), sz: 0, sam_status: 2


nia
Reply | Threaded
Open this post in threaded view
|

Re: lin_tape: IBMtape modesense10_page failed: -22

nia
Administrator
In reply to this post by Mark Harvey
Oct  8 08:35:48 mhvtl vtltape[23587]: processCommand: MODE SENSE (359) **
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Mode Sense 10 byte version
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Page Control  : Current configuration(0x0)
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Page Code     : 0x24
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Disable Block Descriptor => Yes
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Allocation len: 10248
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense: pcode: 0x24
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: Entered: pcode 0x24
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: Page code 0x24 not found
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense: Unknown mode page : 36
Oct  8 08:35:48 mhvtl vtltape[23587]: mkSenseBuf: Sense buf: 0x805faf8
Oct  8 08:35:48 mhvtl vtltape[23587]: mkSenseBuf: SENSE [Key/ASC/ASCQ] [05 24 00]
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: OP s/n: (359), sz: 0, sam_status: 2
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: [Key/ASC/ASCQ] [05 24 00]
Oct  8 08:35:48 mhvtl kernel: lin_tape: IBMtape13----25111 tape_modesense10_page failed: -22
Reply | Threaded
Open this post in threaded view
|

Re: lin_tape: IBMtape modesense10_page failed: -22

Mark Harvey
Administrator
Yep, I need to visit IBM Doco and identify what mode page 36 (0x24) is and implement it in some code..

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

Re: lin_tape: IBMtape modesense10_page failed: -22

Mark Harvey
Administrator
In reply to this post by nia
Mode Sense page 0x24

Here is what the IBM (10th edition) says about this page.

"Mode Page 24h: Vendor-Specific Control Mode Page
The Vendor-Specific Control Mode Page provides control over undocumented test
options. Modifying these values could have adverse effects on the drives operation.
As such, this page should never be used in a Mode Select operation. A Mode Sense
of this page may be used to determine if the drive supports encryption."

i.e. On one hand, the documentation says "don't use this page", on the other is says it may be used to determine if drive supports encryption"

I'll implement it what little information the documentation provides and lets see where it goes from there.

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

Re: lin_tape: IBMtape modesense10_page failed: -22

Mark Harvey
Administrator
In reply to this post by nia
Hello Nia,

Any chance of testing this build ?

I'm interested in reports of any other unsupported MODE (or LOG) SENSE pages used by any backup applications.

Cheers
Mark

On 10/10/10 04:51, nia [via MHVTL - Community Forums] wrote:
Oct  8 08:35:48 mhvtl vtltape[23587]: processCommand: MODE SENSE (359) **
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Mode Sense 10 byte version
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Page Control  : Current configuration(0x0)
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Page Code     : 0x24
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Disable Block Descriptor => Yes
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense:  Allocation len: 10248
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense: pcode: 0x24
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: Entered: pcode 0x24
Oct  8 08:35:48 mhvtl vtltape[23587]: find_pcode: Page code 0x24 not found
Oct  8 08:35:48 mhvtl vtltape[23587]: resp_mode_sense: Unknown mode page : 36
Oct  8 08:35:48 mhvtl vtltape[23587]: mkSenseBuf: Sense buf: 0x805faf8
Oct  8 08:35:48 mhvtl vtltape[23587]: mkSenseBuf: SENSE [Key/ASC/ASCQ] [05 24 00]
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: OP s/n: (359), sz: 0, sam_status: 2
Oct  8 08:35:48 mhvtl vtltape[23587]: completeSCSICommand: [Key/ASC/ASCQ] [05 24 00]
Oct  8 08:35:48 mhvtl kernel: lin_tape: IBMtape13----25111 tape_modesense10_page failed: -22





mhvtl-2010-10-12-0.18.11-git-12-zz.tgz (236K) Download Attachment
Regards from Australia
Mark Harvey
nia
Reply | Threaded
Open this post in threaded view
|

Re: lin_tape: IBMtape modesense10_page failed: -22

nia
Administrator
Mark,

I have tested the new code provided on 10/12/2010 and looks like it did take care of that modesense10_page failed: -22 error .. I have not seen it pop yet.

I will continue to test this to make sure also.


Thanks so much ..

nia
nia
Reply | Threaded
Open this post in threaded view
|

Re: lin_tape: IBMtape modesense10_page failed: -22

nia
Administrator
I forgot to mention: during the compilation of code 0.18-11-10-12-2010 , these warnings showed:

vtllibrary.c: In function 'empty_map':
vtllibrary.c:559: warning: 'a' may be used uninitialized in this function
vtllibrary.c: In function 'load_map':
vtllibrary.c:515: warning: 'slt' may be used uninitialized in this function
vtllibrary.c: In function 'update_drive_details':
vtllibrary.c:720: warning: 'dp' may be used uninitialized in this function