ahc0: Dumping Card State in Message-in phase, at SEQADDR 0x1ae

José Viana (Sapo) jose-viana at sapo.pt
Thu Dec 18 08:40:34 PST 2003 

Hi !

I have a second SCSI disk with an Adaptec card that failed today and I 
can't mount this now.
Both disks were running for months in FreeBSD 4_8 without problems and I 
didn't touch the system for days. So I don't know what's happening.

Any opinions ? Thanks in advance.


root at wf1:~# mount /www1
mount: /dev/da1s1e: Device not configured


Logs(there are varous as this one):

Dec 18 09:21:18 wf1 /kernel: ahc0:A:15: no active SCB for reconnecting 
target - issuing BUS DEVICE RESET
Dec 18 09:21:18 wf1 /kernel: SAVED_SCSIID == 0xf7, SAVED_LUN == 0x0, 
ARG_1 == 0x50 ACCUM = 0xe7
Dec 18 09:21:18 wf1 /kernel: SEQ_FLAGS == 0xc0, SCBPTR == 0x1e, BTT == 
0xff, SINDEX == 0x31
Dec 18 09:21:18 wf1 /kernel: SCSIID == 0x0, SCB_SCSIID == 0xe7, SCB_LUN 
== 0x0, SCB_TAG == 0xff, SCB_CONTROL == 0x0
Dec 18 09:21:18 wf1 /kernel: SCSIBUSL == 0x50, SCSISIGI == 0xe6
Dec 18 09:21:18 wf1 /kernel: SXFRCTL0 == 0x88
Dec 18 09:21:18 wf1 /kernel: SEQCTL == 0x10
Dec 18 09:21:18 wf1 /kernel: >>>>>>>>>>>>>>>>>> Dump Card State Begins 
<<<<<<<<<<<<<<<<<
Dec 18 09:21:18 wf1 /kernel: ahc0: Dumping Card State in Message-in 
phase, at SEQADDR 0x1ae
Dec 18 09:21:18 wf1 /kernel: Card was paused
Dec 18 09:21:18 wf1 /kernel: ACCUM = 0xe7, SINDEX = 0x31, DINDEX = 0xe4, 
ARG_2 = 0x14
Dec 18 09:21:18 wf1 /kernel: HCNT = 0x0 SCBPTR = 0x1e
Dec 18 09:21:18 wf1 /kernel: SCSIPHASE[0x8]:(MSG_IN_PHASE) 
SCSISIGI[0xe6]:(REQI|BSYI|MSGI|IOI|CDI)
Dec 18 09:21:18 wf1 /kernel: ERROR[0x0] SCSIBUSL[0x50] 
LASTPHASE[0xe0]:(MSGI|IOI|CDI)
Dec 18 09:21:18 wf1 /kernel: SCSISEQ[0x12]:(ENAUTOATNP|ENRSELI) 
SBLKCTL[0x6]:(SELWIDE|ENAB20)
Dec 18 09:21:18 wf1 /kernel: SCSIRATE[0x0] SEQCTL[0x10]:(FASTMODE) 
SEQ_FLAGS[0xc0]:(NO_CDB_SENT|NOT_IDENTIFIED)
Dec 18 09:21:18 wf1 /kernel: SSTAT0[0x2]:(SPIORDY) SSTAT1[0x1]:(REQINIT) 
SSTAT2[0x0]
Dec 18 09:21:18 wf1 /kernel: SSTAT3[0x0] SIMODE0[0x8]:(ENSWRAP) 
SIMODE1[0xac]:(ENSCSIPERR|ENBUSFREE|ENSCSIRST|ENSELTIMO)
Dec 18 09:21:18 wf1 /kernel: SXFRCTL0[0x88]:(SPIOEN|DFON) DFCNTRL[0x0] 
DFSTATUS[0x89]:(FIFOEMP|HDONE|PRELOAD_AVAIL)
Dec 18 09:21:18 wf1 /kernel: STACK: 0x147 0x0 0x16b 0x180
Dec 18 09:21:18 wf1 /kernel: SCB count = 150
Dec 18 09:21:18 wf1 /kernel: Kernel NEXTQSCB = 97
Dec 18 09:21:18 wf1 /kernel: Card NEXTQSCB = 97
Dec 18 09:21:18 wf1 /kernel: QINFIFO entries:
Dec 18 09:21:18 wf1 /kernel: Waiting Queue entries:
Dec 18 09:21:18 wf1 /kernel: Disconnected Queue entries: 17:110 1:82
Dec 18 09:21:18 wf1 /kernel: QOUTFIFO entries:
Dec 18 09:21:18 wf1 /kernel: Sequencer Free SCB List: 30 12 29 26 19 24 
27 0 2 28 23 31 3 8 5 16 25 22 10 14 20 18 13 6 21 15 4 7 11 9
Dec 18 09:21:18 wf1 /kernel: Sequencer SCB Info:
Dec 18 09:21:18 wf1 /kernel: 0 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 1 
SCB_CONTROL[0x66]:(DISCONNECTED|TAG_ENB|DISCENB) 
SCB_SCSIID[0xf7]:(TWIN_CHNLB|TWIN_TID)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0x52]
Dec 18 09:21:18 wf1 /kernel: 2 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 3 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 4 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 5 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 6 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 7 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 8 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 9 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 10 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 11 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 12 
SCB_CONTROL[0xe2]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 13 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 14 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 15 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 16 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 17 
SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0x6e]
Dec 18 09:21:18 wf1 /kernel: 18 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 19 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 20 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 21 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 22 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 23 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 24 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 25 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 26 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 27 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 28 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 29 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 30 SCB_CONTROL[0x0] 
SCB_SCSIID[0xe7]:(TWIN_CHNLB) SCB_LUN[0x0]
Dec 18 09:21:18 wf1 /kernel: SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: 31 
SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0] SCB_TAG[0xff]
Dec 18 09:21:18 wf1 /kernel: Pending list:
Dec 18 09:21:18 wf1 /kernel: 110 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) 
SCB_SCSIID[0xe7]:(TWIN_CHNLB)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0]
Dec 18 09:21:18 wf1 /kernel: 82 SCB_CONTROL[0x62]:(TAG_ENB|DISCENB) 
SCB_SCSIID[0xf7]:(TWIN_CHNLB|TWIN_TID)
Dec 18 09:21:18 wf1 /kernel: SCB_LUN[0x0]
Dec 18 09:21:18 wf1 /kernel: Kernel Free SCB list: 16 50 117 123 74 29 9 
81 102 57 20 28 80 77 18 126 125 119 129 92 103 89 69 51 62 127 52 124 
38 1 8 44 61 35 118 55 42 59 13 93 128 109 19 88 115 54 113 15 84 101 98 
114
4 99 65 22 27 47 56 45 21 7 40 111 90 73 37 116 43 141 0 67 31 34 66 23 
11 26 100 86 94 108 104 41 83 48 112 79 39 53 36 49 78 91 75 105 32 58 
107 87 3 72 71 12 60 17 10 64 6 96 85 95 33 68 25 70 76 63 30 14 46 2 
106 24 5
  122 142 143 144 145 146 147 148 149 130 131 132 133 134 135 136 137 
138 139 120 121 140
Dec 18 09:21:18 wf1 /kernel:
Dec 18 09:21:18 wf1 /kernel: <<<<<<<<<<<<<<<< Dump Card State Ends 
 >>>>>>>>>>>>>>>>>>
Dec 18 09:21:18 wf1 /kernel: ahc0: Bus Device Reset on A:15. 1 SCBs aborted
Dec 18 09:21:33 wf1 /kernel: (da1:ahc0:0:15:0): WRITE(10). CDB: 2a 0 2 
6d 20 ff 0 0 20 0
Dec 18 09:21:33 wf1 /kernel: (da1:ahc0:0:15:0): ILLEGAL REQUEST asc:24,0
Dec 18 09:21:33 wf1 /kernel: (da1:ahc0:0:15:0): Invalid field in CDB

More information about the freebsd-stable mailing list