aic79xx dumping card state

aic79xx dumping card state

[Michael Stone]

How to debug? Kernel is 2.6.18.1, I think the other details are in the 
dmesg output:

ACPI: PCI Interrupt 0000:01:06.0[A] -> GSI 16 (level, low) -> IRQ 21
scsi2 : Adaptec AIC79XX PCI-X SCSI HBA DRIVER, Rev 3.0
        <Adaptec 39320A Ultra320 SCSI adapter>
        aic7902: Ultra320 Wide Channel A, SCSI Id=7, PCI-X 67-100Mhz, 512 SCBs

  Vendor: SC U320/  Model: SATA16R           Rev: R0.0
  Type:   Direct-Access                      ANSI SCSI revision: 03
 target2:0:0: asynchronous
scsi2:A:0:0: Tagged Queuing enabled.  Depth 32
 target2:0:0: Beginning Domain Validation
 target2:0:0: wide asynchronous
 target2:0:0: FAST-160 WIDE SCSI 320.0 MB/s DT IU RTI WRFLOW PCOMP (6.25 ns, offset 127)
 target2:0:0: Ending Domain Validation
SCSI device sdb: 4095967232 1024-byte hdwr sectors (4194270 MB)
sdb: Write Protect is off
sdb: Mode Sense: cd 00 00 08
SCSI device sdb: drive cache: write back
SCSI device sdb: 4095967232 1024-byte hdwr sectors (4194270 MB)
sdb: Write Protect is off
sdb: Mode Sense: cd 00 00 08
SCSI device sdb: drive cache: write back
 sdb: unknown partition table
sd 2:0:0:0: Attached scsi disk sdb


At this point I try to dd from /dev/sdb, don't get very far before:

sd 2:0:0:0: Attempting to queue an ABORT message:CDB: 0x28 0x0 0x0 0x0 0x28 0x70
 0x0 0x1 0x0 0x0
scsi2: At time of recovery, card was not paused
>>>>>>>>>>>>>>>>>> Dump Card State Begins <<<<<<<<<<<<<<<<<
scsi2: Dumping Card State at program address 0x3b Mode 0x22
Card was paused
INTSTAT[0x0] SELOID[0x0] SELID[0x0] HS_MAILBOX[0x0] 
INTCTL[0x80]:(SWTMINTMASK) SEQINTSTAT[0x0] SAVED_MODE[0x11] 
DFFSTAT[0x33]:(CURRFIFO_NONE|FIFO0FREE|FIFO1FREE) 
SCSISIGI[0x24]:(P_DATAOUT_DT|BSYI) SCSIPHASE[0x0] 
SCSIBUS[0x0] LASTPHASE[0x1]:(P_DATAOUT|P_BUSFREE) 
SCSISEQ0[0x40]:(ENSELO) SCSISEQ1[0x12]:(ENAUTOATNP|ENRSELI) 
SEQCTL0[0x0] SEQINTCTL[0x0] SEQ_FLAGS[0x0] 
SEQ_FLAGS2[0x4]:(SELECTOUT_QFROZEN) 
QFREEZE_COUNT[0x0] KERNEL_QFREEZE_COUNT[0x0] MK_MESSAGE_SCB[0xff00] 
MK_MESSAGE_SCSIID[0xff] SSTAT0[0x10]:(SELINGO) 
SSTAT1[0x0] SSTAT2[0x0] SSTAT3[0x0] PERRDIAG[0x0] 
SIMODE1[0xac]:(ENSCSIPERR|ENBUSFREE|ENSCSIRST|ENSELTIMO) 
LQISTAT0[0x0] LQISTAT1[0x0] LQISTAT2[0x80]:(PACKETIZED) 
LQOSTAT0[0x0] LQOSTAT1[0x0] LQOSTAT2[0x40] 

SCB Count = 4 CMDS_PENDING = 1 LASTSCB 0xffff CURRSCB 0x3 NEXTSCB 0xff00
qinstart = 90 qinfifonext = 90
QINFIFO:
WAITING_TID_QUEUES:
       0 ( 0x3 )
Pending list:
  3 FIFO_USE[0x0] SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x7] 
Total 1
Kernel Free SCB list: 2 1 0 
Sequencer Complete DMA-inprog list: 
Sequencer Complete list: 
Sequencer DMA-Up and Complete list: 
Sequencer On QFreeze and Complete list: 


scsi2: FIFO0 Free, LONGJMP == 0x8271, SCB 0x3
SEQIMODE[0x3f]:(ENCFG4TCMD|ENCFG4ICMD|ENCFG4TSTAT|ENCFG4ISTAT|ENCFG4DATA|ENSAVEP
TRS) 
SEQINTSRC[0x0] DFCNTRL[0x0] DFSTATUS[0x89]:(FIFOEMP|HDONE|PRELOAD_AVAIL) 
SG_CACHE_SHADOW[0x2]:(LAST_SEG) SG_STATE[0x0] DFFSXFRCTL[0x0] 
SOFFCNT[0x0] MDFFSTAT[0x5]:(FIFOFREE|DLZERO) SHADDR = 0x00, SHCNT = 0x0 
HADDR = 0x00, HCNT = 0x0 CCSGCTL[0x10]:(SG_CACHE_AVAIL) 

scsi2: FIFO1 Free, LONGJMP == 0x8063, SCB 0x3
SEQIMODE[0x3f]:(ENCFG4TCMD|ENCFG4ICMD|ENCFG4TSTAT|ENCFG4ISTAT|ENCFG4DATA|ENSAVEP
TRS) 
SEQINTSRC[0x0] DFCNTRL[0x0] DFSTATUS[0x89]:(FIFOEMP|HDONE|PRELOAD_AVAIL) 
SG_CACHE_SHADOW[0x2]:(LAST_SEG) SG_STATE[0x0] DFFSXFRCTL[0x0] 
SOFFCNT[0x0] MDFFSTAT[0x5]:(FIFOFREE|DLZERO) SHADDR = 0x00, SHCNT = 0x0 
HADDR = 0x00, HCNT = 0x0 CCSGCTL[0x10]:(SG_CACHE_AVAIL) 
LQIN: 0x8 0x0 0x0 0x3 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 
0x0 0x0 0x
0 0x0 
scsi2: LQISTATE = 0x1, LQOSTATE = 0x1a, OPTIONMODE = 0x52
scsi2: OS_SPACE_CNT = 0x20 MAXCMDCNT = 0x0
scsi2: SAVED_SCSIID = 0x0 SAVED_LUN = 0x0

SIMODE0[0xc]:(ENOVERRUN|ENIOERR) 
CCSCBCTL[0x4]:(CCSCBDIR) 
scsi2: REG0 == 0x3, SINDEX = 0x102, DINDEX = 0x102
scsi2: SCBPTR == 0xff03, SCB_NEXT == 0xff00, SCB_NEXT2 == 0x0
CDB 3 1 0 0 0 0
STACK: 0x24 0x0 0x0 0x0 0x0 0x0 0x0 0x0
<<<<<<<<<<<<<<<<< Dump Card State Ends >>>>>>>>>>>>>>>>>>
scsi2:0:0:0: Cmd aborted from QINFIFO
sd 2:0:0:0: Attempting to queue an ABORT message:CDB: 0x0 0x0 0x0 0x0 
0x0 0x0
scsi2: At time of recovery, card was not paused
>>>>>>>>>>>>>>>>>> Dump Card State Begins <<<<<<<<<<<<<<<<<
scsi2: Dumping Card State at program address 0x3b Mode 0x22
Card was paused
INTSTAT[0x0] SELOID[0x0] SELID[0x0] HS_MAILBOX[0x0] 
INTCTL[0x80]:(SWTMINTMASK) SEQINTSTAT[0x0] SAVED_MODE[0x11] 
DFFSTAT[0x33]:(CURRFIFO_NONE|FIFO0FREE|FIFO1FREE) 
SCSISIGI[0x24]:(P_DATAOUT_DT|BSYI) SCSIPHASE[0x0] 
SCSIBUS[0x0] LASTPHASE[0x1]:(P_DATAOUT|P_BUSFREE) 
SCSISEQ0[0x40]:(ENSELO) SCSISEQ1[0x12]:(ENAUTOATNP|ENRSELI) 
SEQCTL0[0x0] SEQINTCTL[0x0] SEQ_FLAGS[0x0] 
SEQ_FLAGS2[0x4]:(SELECTOUT_QFROZEN) 
QFREEZE_COUNT[0x0] KERNEL_QFREEZE_COUNT[0x0] MK_MESSAGE_SCB[0xff00] 
MK_MESSAGE_SCSIID[0xff] SSTAT0[0x10]:(SELINGO) 
SSTAT1[0x0] SSTAT2[0x0] SSTAT3[0x0] PERRDIAG[0x0] 
SIMODE1[0xac]:(ENSCSIPERR|ENBUSFREE|ENSCSIRST|ENSELTIMO) 
LQISTAT0[0x0] LQISTAT1[0x0] LQISTAT2[0x80]:(PACKETIZED) 
LQOSTAT0[0x0] LQOSTAT1[0x0] LQOSTAT2[0x40] 

SCB Count = 4 CMDS_PENDING = 0 LASTSCB 0xffff CURRSCB 0x3 NEXTSCB 0xff00
qinstart = 90 qinfifonext = 91
QINFIFO: 0x3
WAITING_TID_QUEUES:
Pending list:
  3 FIFO_USE[0x0] SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x7] 
Total 1
Kernel Free SCB list: 2 1 0 
Sequencer Complete DMA-inprog list: 
Sequencer Complete list: 
Sequencer DMA-Up and Complete list: 
Sequencer On QFreeze and Complete list: 


scsi2: FIFO0 Free, LONGJMP == 0x8271, SCB 0x3
SEQIMODE[0x3f]:(ENCFG4TCMD|ENCFG4ICMD|ENCFG4TSTAT|ENCFG4ISTAT|ENCFG4DATA|ENSAVEP
TRS) 
SEQINTSRC[0x0] DFCNTRL[0x0] DFSTATUS[0x89]:(FIFOEMP|HDONE|PRELOAD_AVAIL) 
SG_CACHE_SHADOW[0x2]:(LAST_SEG) SG_STATE[0x0] DFFSXFRCTL[0x0] 
SOFFCNT[0x0] MDFFSTAT[0x5]:(FIFOFREE|DLZERO) SHADDR = 0x00, SHCNT = 0x0 
HADDR = 0x00, HCNT = 0x0 CCSGCTL[0x10]:(SG_CACHE_AVAIL) 

scsi2: FIFO1 Free, LONGJMP == 0x8063, SCB 0x3
SEQIMODE[0x3f]:(ENCFG4TCMD|ENCFG4ICMD|ENCFG4TSTAT|ENCFG4ISTAT|ENCFG4DATA|ENSAVEP
TRS) 
SEQINTSRC[0x0] DFCNTRL[0x0] DFSTATUS[0x89]:(FIFOEMP|HDONE|PRELOAD_AVAIL) 
SG_CACHE_SHADOW[0x2]:(LAST_SEG) SG_STATE[0x0] DFFSXFRCTL[0x0] 
SOFFCNT[0x0] MDFFSTAT[0x5]:(FIFOFREE|DLZERO) SHADDR = 0x00, SHCNT = 0x0 
HADDR = 0x00, HCNT = 0x0 CCSGCTL[0x10]:(SG_CACHE_AVAIL) 
LQIN: 0x8 0x0 0x0 0x3 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 
0x0 0x0 0x
0 0x0 
scsi2: LQISTATE = 0x1, LQOSTATE = 0x1a, OPTIONMODE = 0x52
scsi2: OS_SPACE_CNT = 0x20 MAXCMDCNT = 0x0
scsi2: SAVED_SCSIID = 0x0 SAVED_LUN = 0x0

SIMODE0[0xc]:(ENOVERRUN|ENIOERR) 
CCSCBCTL[0x4]:(CCSCBDIR) 
scsi2: REG0 == 0x3, SINDEX = 0x102, DINDEX = 0x102
scsi2: SCBPTR == 0xff03, SCB_NEXT == 0xff00, SCB_NEXT2 == 0x0
CDB 3 1 0 0 0 0
STACK: 0x24 0x0 0x0 0x0 0x0 0x0 0x0 0x0
<<<<<<<<<<<<<<<<< Dump Card State Ends >>>>>>>>>>>>>>>>>>
scsi2:0:0:0: Cmd aborted from QINFIFO
sd 2:0:0:0: Attempting to queue a TARGET RESET message:CDB: 0x28 0x0 0x0 
0x0 0x2
8 0x70 0x0 0x1 0x0 0x0
scsi2: Device reset code sleeping
scsi2: Device reset timer expired (active 1)
scsi2: Device reset returning 0x2003
Recovery SCB completes
sd 2:0:0:0: scsi: Device offlined - not ready after error recovery
sd 2:0:0:0: SCSI error: return code = 0x00020000
end_request: I/O error, dev sdb, sector 20704
Buffer I/O error on device sdb, logical block 2588
Buffer I/O error on device sdb, logical block 2589
Buffer I/O error on device sdb, logical block 2590
Buffer I/O error on device sdb, logical block 2591
Buffer I/O error on device sdb, logical block 2592
Buffer I/O error on device sdb, logical block 2593
Buffer I/O error on device sdb, logical block 2594
sd 2:0:0:0: rejecting I/O to offline device
Buffer I/O error on device sdb, logical block 2588
Buffer I/O error on device sdb, logical block 2595
Buffer I/O error on device sdb, logical block 2596

 

Re: aic79xx dumping card state

[Jaco van der Schyff]

On Thu, Nov 09, 2006 at 07:45:09PM -0500, Michael Stone wrote:
> >>>>>>>>>>>>>>>>>>Dump Card State Begins <<<<<<<<<<<<<<<<<
> scsi2: Dumping Card State at program address 0x3b Mode 0x22
> Card was paused
> INTSTAT[0x0] SELOID[0x0] SELID[0x0] HS_MAILBOX[0x0] INTCTL[0x80]:(SWTMINTMASK) SEQINTSTAT[0x0] SAVED_MODE[0x11] DFFSTAT[0x33]:(CURRFIFO_NONE|FIFO0FREE|FIFO1FREE) 
> SCSISIGI[0x24]:(P_DATAOUT_DT|BSYI) SCSIPHASE[0x0] SCSIBUS[0x0] LASTPHASE[0x1]:(P_DATAOUT|P_BUSFREE) SCSISEQ0[0x40]:(ENSELO) SCSISEQ1[0x12]:(ENAUTOATNP|ENRSELI) SEQCTL0[0x0] SEQINTCTL[0x0] 
> SEQ_FLAGS[0x0] SEQ_FLAGS2[0x4]:(SELECTOUT_QFROZEN) QFREEZE_COUNT[0x0] KERNEL_QFREEZE_COUNT[0x0] MK_MESSAGE_SCB[0xff00] MK_MESSAGE_SCSIID[0xff] SSTAT0[0x10]:(SELINGO) SSTAT1[0x0] 
> SSTAT2[0x0] SSTAT3[0x0] PERRDIAG[0x0] SIMODE1[0xac]:(ENSCSIPERR|ENBUSFREE|ENSCSIRST|ENSELTIMO) LQISTAT0[0x0] LQISTAT1[0x0] LQISTAT2[0x80]:(PACKETIZED) LQOSTAT0[0x0] LQOSTAT1[0x0] 
> LQOSTAT2[0x40] 
> SCB Count = 4 CMDS_PENDING = 1 LASTSCB 0xffff CURRSCB 0x3 NEXTSCB 0xff00
> qinstart = 90 qinfifonext = 90
> QINFIFO:
> WAITING_TID_QUEUES:
>       0 ( 0x3 )
> Pending list:
>  3 FIFO_USE[0x0] SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x7] Total 1

I had very similar problems on the aic79xx cards.  What you could first
check if QAS is enabled/disabled on both devices, and step the card down
to 160MB/s.  I haven't successfully ran any aic79xx Ultra320 card at
Ultra320...

Clock it down and it should be fine...


- Jaco van der Schyff

Re: aic79xx dumping card state

[Michael Stone]

On Fri, Nov 10, 2006 at 05:01:26PM +0200, Jaco van der Schyff wrote:
>I had very similar problems on the aic79xx cards.  What you could first
>check if QAS is enabled/disabled on both devices, 

How do you tell that? Is it something readable from the kernel, or do I 
have to poke around on the devices?

>and step the card down
>to 160MB/s.  I haven't successfully ran any aic79xx Ultra320 card at
>Ultra320...
>
>Clock it down and it should be fine...

Yikes, that's not a solution. :) I found the aic79xx=slowcrc option and 
enabled that, and I've been running pretty solid at 320 (~300MBps 
transfer rates). I'll pound on it over the weekend and see if it stays 
solid.

Mike Stone

Re: aic79xx dumping card state

[Jaco van der Schyff]

> On Fri, Nov 10, 2006 at 05:01:26PM +0200, Jaco van der Schyff wrote:
>>I had very similar problems on the aic79xx cards.  What you could first
>>check if QAS is enabled/disabled on both devices,
>
> How do you tell that? Is it something readable from the kernel, or do I
> have to poke around on the devices?

Yes on the devices, you will be able to see the settings of the card in
the scsi select util at bootup.  Check the manuals of the devices you
connect to the scsi bus for their settings.

>>and step the card down
>>to 160MB/s.  I haven't successfully ran any aic79xx Ultra320 card at
>>Ultra320...
>>
>>Clock it down and it should be fine...
>
> Yikes, that's not a solution. :) I found the aic79xx=slowcrc option and
> enabled that, and I've been running pretty solid at 320 (~300MBps
> transfer rates). I'll pound on it over the weekend and see if it stays
> solid.

Cool! slowcrc didn't work for me... :(

- Jaco van der Schyff