Re: Adaptec 39320A "Card was not paused"

Scott Long wrote:

> Cameron Murdoch wrote:
>
>> I am having a problem with an adaptec 39320A SCSI card accessing an 
>> external raid box, (JetStor III 416S).
>>
>> Host computer is a Dell PowerEdge2650 with a single processor.
>>
>> 6-CURRENT from Saturday has the following relevant dmesg:
>>
>> **snip**
>
>
> You snipped off the output that was important.  Send a complete log, 
> please.
>
>
> Scott

Apologies, here is a more complete error message:

Jan 16 21:20:21  kernel: ahd0: Recovery Initiated - Card was not paused
Jan 16 21:20:21  kernel: >>>>>>>>>>>>>>>>>> Dump Card State Begins 
<<<<<<<<<<<<<<<<<
Jan 16 21:20:21  kernel: ahd0: Dumping Card State at program address 
0x81 Mode 0x22
Jan 16 21:20:21  kernel: INTSTAT[0x0] SELOID[0x0] SELID[0x0] 
HS_MAILBOX[0x0]
Jan 16 21:20:21  kernel: INTCTL[0x80]:(SWTMINTMASK) SEQINTSTAT[0x0] 
SAVED_MODE[0x11]
Jan 16 21:20:21  kernel: DFFSTAT[0x33]:(CURRFIFO_NONE|FIFO0FREE|FIFO1FREE)
Jan 16 21:20:21  kernel: SCSISIGI[0x25]:(P_DATAOUT_DT|ACKI|BSYI) 
SCSIPHASE[0x0]
Jan 16 21:20:21  kernel: SCSIBUS[0x0] LASTPHASE[0x1]:(P_DATAOUT|P_BUSFREE)
Jan 16 21:20:21  kernel: SCSISEQ0[0x40]:(ENSELO) 
SCSISEQ1[0x12]:(ENAUTOATNP|ENRSELI)
Jan 16 21:20:21  kernel: SEQCTL0[0x0] SEQINTCTL[0x0] SEQ_FLAGS[0x0] 
SEQ_FLAGS2[0x0]
Jan 16 21:20:21  kernel: QFREEZE_COUNT[0x0] KERNEL_QFREEZE_COUNT[0x0] 
MK_MESSAGE_SCB[0xff00]
Jan 16 21:20:21  kernel: MK_MESSAGE_SCSIID[0xff] SSTAT0[0x10]:(SELINGO) 
SSTAT1[0x8]:(BUSFREE)
Jan 16 21:20:21  kernel: SSTAT2[0x0] SSTAT3[0x0] 
PERRDIAG[0xc0]:(HIPERR|HIZERO)
Jan 16 21:20:21  kernel: SIMODE1[0xa4]:(ENSCSIPERR|ENSCSIRST|ENSELTIMO) 
LQISTAT0[0x0]
Jan 16 21:20:21  kernel: LQISTAT1[0x0] LQISTAT2[0x80]:(PACKETIZED) 
LQOSTAT0[0x0]
Jan 16 21:20:21  kernel: LQOSTAT1[0x0] LQOSTAT2[0x40]
Jan 16 21:20:21  kernel:
Jan 16 21:20:21  kernel: SCB Count = 16 CMDS_PENDING = 1 LASTSCB 0xffff 
CURRSCB 0xf NEXTSCB 0xff00
Jan 16 21:20:21  kernel: qinstart = 342 qinfifonext = 342
Jan 16 21:20:21  kernel: QINFIFO:
Jan 16 21:20:21  kernel: WAITING_TID_QUEUES:
Jan 16 21:20:21  kernel: 0 ( 0xf )
Jan 16 21:20:21  kernel: Pending list:
Jan 16 21:20:21  kernel: 15 FIFO_USE[0x0] 
SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x7]
Jan 16 21:20:21  kernel: Total 1
Jan 16 21:20:21  kernel: Kernel Free SCB list: 1 2 3 4 5 6 7 8 9 10 11 
12 13 14 0
Jan 16 21:20:21  kernel: Sequencer Complete DMA-inprog list:
Jan 16 21:20:21  kernel: Sequencer Complete list:
Jan 16 21:20:21  kernel: Sequencer DMA-Up and Complete list:
Jan 16 21:20:21  kernel: Sequencer On QFreeze and Complete list:
Jan 16 21:20:21  kernel:
Jan 16 21:20:21  kernel:
Jan 16 21:20:21  kernel: ahd0: FIFO0 Free, LONGJMP == 0x8276, SCB 0xf
Jan 16 21:20:21  kernel: 
SEQIMODE[0x3f]:(ENCFG4TCMD|ENCFG4ICMD|ENCFG4TSTAT|ENCFG4ISTAT|ENCFG4DATA|ENSAVEPTRS)
Jan 16 21:20:21  kernel: SEQINTSRC[0x0] DFCNTRL[0x4]:(DIRECTION) 
DFSTATUS[0x89]:(FIFOEMP|HDONE|PRELOAD_AVAIL)
Jan 16 21:20:21  kernel: SG_CACHE_SHADOW[0x2]:(LAST_SEG) SG_STATE[0x0] 
DFFSXFRCTL[0x0]
Jan 16 21:20:21  kernel: SOFFCNT[0x0] MDFFSTAT[0x5]:(FIFOFREE|DLZERO) 
SHADDR = 0x00, SHCNT = 0x0
Jan 16 21:20:21  kernel: HADDR = 0x00, HCNT = 0x0 
CCSGCTL[0x10]:(SG_CACHE_AVAIL)
Jan 16 21:20:21  kernel:
Jan 16 21:20:21  kernel: ahd0: FIFO1 Free, LONGJMP == 0x8063, SCB 0xf
Jan 16 21:20:21  kernel: 
SEQIMODE[0x3f]:(ENCFG4TCMD|ENCFG4ICMD|ENCFG4TSTAT|ENCFG4ISTAT|ENCFG4DATA|ENSAVEPTRS)
Jan 16 21:20:21  kernel: SEQINTSRC[0x0] DFCNTRL[0x0] 
DFSTATUS[0x89]:(FIFOEMP|HDONE|PRELOAD_AVAIL)
Jan 16 21:20:21  kernel: SG_CACHE_SHADOW[0x2]:(LAST_SEG) SG_STATE[0x0] 
DFFSXFRCTL[0x0]
Jan 16 21:20:21  kernel: SOFFCNT[0x0] MDFFSTAT[0x5]:(FIFOFREE|DLZERO) 
SHADDR = 0x00, SHCNT = 0x0
Jan 16 21:20:21  kernel: HADDR = 0x00, HCNT = 0x0 
CCSGCTL[0x10]:(SG_CACHE_AVAIL)
Jan 16 21:20:21  kernel: LQIN: 0x8 0x0 0x0 0xf 0x0 0x0 0x0 0x0 0x0 0x0 
0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0
Jan 16 21:20:21  kernel: ahd0: LQISTATE = 0x1, LQOSTATE = 0x1a, 
OPTIONMODE = 0x42
Jan 16 21:20:21  kernel: ahd0: OS_SPACE_CNT = 0x20 MAXCMDCNT = 0x0
Jan 16 21:20:21  kernel: ahd0: SAVED_SCSIID = 0x0 SAVED_LUN = 0x0
Jan 16 21:20:21  kernel:
Jan 16 21:20:21  kernel: SIMODE0[0xc]:(ENOVERRUN|ENIOERR)
Jan 16 21:20:21  kernel: CCSCBCTL[0x4]:(CCSCBDIR)
Jan 16 21:20:21  kernel: ahd0: REG0 == 0xf, SINDEX = 0x102, DINDEX = 0x102
Jan 16 21:20:21  kernel: ahd0: SCBPTR == 0xff0f, SCB_NEXT == 0xff00, 
SCB_NEXT2 == 0x0
Jan 16 21:20:21  kernel: CDB f 1 0 0 0 0
Jan 16 21:20:21  kernel: STACK: 0x24 0x0 0x0 0x0 0x0 0x0 0x0 0x0
Jan 16 21:20:21  kernel: <<<<<<<<<<<<<<<<< Dump Card State Ends 
 >>>>>>>>>>>>>>>>>>
Jan 16 21:20:21  kernel: (da0:ahd0:0:0:0): SCB 0xf - timed out
Jan 16 21:20:21  kernel: (da0:ahd0:0:0:0): BDR message in message buffer
Jan 16 21:20:23  kernel: ahd0: Recovery Initiated - Card was not paused
Jan 16 21:20:23  kernel: >>>>>>>>>>>>>>>>>> Dump Card State Begins 
<<<<<<<<<<<<<<<<<
Jan 16 21:20:23  kernel: ahd0: Dumping Card State at program address 
0x23 Mode 0x11
Jan 16 21:20:23  kernel: INTSTAT[0x0] SELOID[0x0] SELID[0x0] HS_MAILBOX[0x0]
Jan 16 21:20:23  kernel: INTCTL[0x80]:(SWTMINTMASK) SEQINTSTAT[0x0] 
SAVED_MODE[0x11]
Jan 16 21:20:23  kernel: DFFSTAT[0x33]:(CURRFIFO_NONE|FIFO0FREE|FIFO1FREE)
Jan 16 21:20:23  kernel: SCSISIGI[0x35]:(P_DATAOUT_DT|ACKI|BSYI|ATNI) 
SCSIPHASE[0x0]
Jan 16 21:20:23  kernel: SCSIBUS[0x0] LASTPHASE[0x1]:(P_DATAOUT|P_BUSFREE)
Jan 16 21:20:23  kernel: SCSISEQ0[0x40]:(ENSELO) 
SCSISEQ1[0x12]:(ENAUTOATNP|ENRSELI)
Jan 16 21:20:23  kernel: SEQCTL0[0x0] SEQINTCTL[0x0] SEQ_FLAGS[0x0] 
SEQ_FLAGS2[0x4]:(SELECTOUT_QFROZEN)
Jan 16 21:20:23  kernel: QFREEZE_COUNT[0x0] KERNEL_QFREEZE_COUNT[0x0] 
MK_MESSAGE_SCB[0xff00]
Jan 16 21:20:23  kernel: MK_MESSAGE_SCSIID[0xff] SSTAT0[0x10]:(SELINGO) 
SSTAT1[0x0]
Jan 16 21:20:23  kernel: SSTAT2[0x0] SSTAT3[0x0] 
PERRDIAG[0xc0]:(HIPERR|HIZERO)
Jan 16 21:20:23  kernel: SIMODE1[0xa4]:(ENSCSIPERR|ENSCSIRST|ENSELTIMO) 
LQISTAT0[0x0]
Jan 16 21:20:23  kernel: LQISTAT1[0x0] LQISTAT2[0x80]:(PACKETIZED) 
LQOSTAT0[0x0]
Jan 16 21:20:23  kernel: LQOSTAT1[0x0] LQOSTAT2[0x40]
Jan 16 21:20:23  kernel:
Jan 16 21:20:23  kernel: SCB Count = 16 CMDS_PENDING = 1 LASTSCB 0xffff 
CURRSCB 0xf NEXTSCB 0xff00
Jan 16 21:20:23  kernel: qinstart = 342 qinfifonext = 342
Jan 16 21:20:23  kernel: QINFIFO:
Jan 16 21:20:23  kernel: WAITING_TID_QUEUES:
Jan 16 21:20:23  kernel: 0 ( 0xf )
Jan 16 21:20:23  kernel: Pending list:
Jan 16 21:20:23  kernel: 15 FIFO_USE[0x0] 
SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x7]
Jan 16 21:20:23  kernel: Total 1
Jan 16 21:20:23  kernel: Kernel Free SCB list: 1 2 3 4 5 6 7 8 9 10 11 
12 13 14 0
Jan 16 21:20:23  kernel: Sequencer Complete DMA-inprog list:
Jan 16 21:20:23  kernel: Sequencer Complete list:
Jan 16 21:20:23  kernel: Sequencer DMA-Up and Complete list:
Jan 16 21:20:23  kernel: Sequencer On QFreeze and Complete list:
Jan 16 21:20:23  kernel:
Jan 16 21:20:23  kernel:
Jan 16 21:20:23  kernel: ahd0: FIFO0 Free, LONGJMP == 0x8276, SCB 0xf
Jan 16 21:20:23  kernel: 
SEQIMODE[0x3f]:(ENCFG4TCMD|ENCFG4ICMD|ENCFG4TSTAT|ENCFG4ISTAT|ENCFG4DATA|ENSAVEPTRS)
Jan 16 21:20:23  kernel: SEQINTSRC[0x0] DFCNTRL[0x4]:(DIRECTION) 
DFSTATUS[0x89]:(FIFOEMP|HDONE|PRELOAD_AVAIL)
Jan 16 21:20:23  kernel: SG_CACHE_SHADOW[0x2]:(LAST_SEG) SG_STATE[0x0] 
DFFSXFRCTL[0x0]
Jan 16 21:20:23  kernel: SOFFCNT[0x0] MDFFSTAT[0x5]:(FIFOFREE|DLZERO) 
SHADDR = 0x00, SHCNT = 0x0
Jan 16 21:20:23  kernel: HADDR = 0x00, HCNT = 0x0 
CCSGCTL[0x10]:(SG_CACHE_AVAIL)
Jan 16 21:20:23  kernel:
Jan 16 21:20:23  kernel: ahd0: FIFO1 Free, LONGJMP == 0x8063, SCB 0xf
Jan 16 21:20:23  kernel: 
SEQIMODE[0x3f]:(ENCFG4TCMD|ENCFG4ICMD|ENCFG4TSTAT|ENCFG4ISTAT|ENCFG4DATA|ENSAVEPTRS)
Jan 16 21:20:23  kernel: SEQINTSRC[0x0] DFCNTRL[0x0] 
DFSTATUS[0x89]:(FIFOEMP|HDONE|PRELOAD_AVAIL)
Jan 16 21:20:23  kernel: SG_CACHE_SHADOW[0x2]:(LAST_SEG) SG_STATE[0x0] 
DFFSXFRCTL[0x0]
Jan 16 21:20:23  kernel: SOFFCNT[0x0] MDFFSTAT[0x5]:(FIFOFREE|DLZERO) 
SHADDR = 0x00, SHCNT = 0x0
Jan 16 21:20:23  kernel: HADDR = 0x00, HCNT = 0x0 
CCSGCTL[0x10]:(SG_CACHE_AVAIL)
Jan 16 21:20:23  kernel: LQIN: 0x8 0x0 0x0 0xf 0x0 0x0 0x0 0x0 0x0 0x0 
0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0
Jan 16 21:20:23  kernel: ahd0: LQISTATE = 0x1, LQOSTATE = 0x1a, 
OPTIONMODE = 0x42
Jan 16 21:20:23  kernel: ahd0: OS_SPACE_CNT = 0x20 MAXCMDCNT = 0x0
Jan 16 21:20:23  kernel: ahd0: SAVED_SCSIID = 0x0 SAVED_LUN = 0x0
Jan 16 21:20:23  kernel:
Jan 16 21:20:23  kernel: SIMODE0[0xc]:(ENOVERRUN|ENIOERR)
Jan 16 21:20:23  kernel: CCSCBCTL[0x4]:(CCSCBDIR)
Jan 16 21:20:23  kernel: ahd0: REG0 == 0xf, SINDEX = 0x102, DINDEX = 0x102
Jan 16 21:20:23  kernel: ahd0: SCBPTR == 0xf, SCB_NEXT == 0xff00, 
SCB_NEXT2 == 0xff96
Jan 16 21:20:23  kernel: CDB 2a 0 3 35 22 df
Jan 16 21:20:23  kernel: STACK: 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0
Jan 16 21:20:23  kernel: <<<<<<<<<<<<<<<<< Dump Card State Ends 
 >>>>>>>>>>>>>>>>>>
Jan 16 21:20:23  kernel: (da0:ahd0:0:0:0): SCB 0xf - timed out
Jan 16 21:20:23  kernel: (da0:ahd0:0:0:0): no longer in timeout, status 
= 24b
Jan 16 21:20:23  kernel: ahd0: Issued Channel A Bus Reset. 1 SCBs aborted

Cameron