José Viana (Sapo)
2003-Dec-18 08:41 UTC
ahc0: Dumping Card State in Message-in phase, at SEQADDR 0x1ae
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@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