ahc

Mon Nov 17 01:06:30 PST 2008

Dear FreeBSD users,

I'm running FreeBSD 7.0 with Bacula as a backup box. This box has an
Adaptec 2940 SCSI card and a Sony SDX700-C tape drive :

ahc0: <Adaptec 2940 Ultra SCSI adapter> port 0xec00-0xecff mem
0xdffff000-0xdfffffff irq 18 at device 7.0 on pci0
aic7880: Ultra Wide Channel A, SCSI Id=7, 16/253 SCBs

sa0 at ahc0 bus 0 target 5 lun 0
sa0: <SONY SDX-700C 0103> Removable Sequential Access SCSI-2 device 
sa0: 20.000MB/s transfers (10.000MHz, offset 8, 16bit)

It works more or less in the sense that sometimes Bacula fails to write
final EOF to tape. I'm sure that the tapes are OK because I reached the
tape rotation cycle and sometimes it fails with a tape that didn't fail
in the previous rotation cycle.
When I look at the kernel outputs, I have the following :

ahc0: Recovery Initiated
>>>>>>>>>>>>>>>>>> Dump Card State Begins <<<<<<<<<<<<<<<<<
ahc0: Dumping Card State while idle, at SEQADDR 0x7
Card was paused
ACCUM = 0x83, SINDEX = 0x57, DINDEX = 0x26, ARG_2 = 0x3e
HCNT = 0xe8 SCBPTR = 0x0
SCSISIGI[0x0] ERROR[0x0] SCSIBUSL[0x0] LASTPHASE[0x1]:(P_BUSFREE) 
SCSISEQ[0x12]:(ENAUTOATNP|ENRSELI) SBLKCTL[0x2]:(SELWIDE) 
SCSIRATE[0x0] SEQCTL[0x10]:(FASTMODE) SEQ_FLAGS[0xc0]:(NO_CDB_SENT|
NOT_IDENTIFIED) 
SSTAT0[0x0] SSTAT1[0xa]:(PHASECHG|BUSFREE) SSTAT2[0x0] 
SSTAT3[0x0] SIMODE0[0x0] SIMODE1[0xa4]:(ENSCSIPERR|ENSCSIRST|ENSELTIMO) 
SXFRCTL0[0x80]:(DFON) DFCNTRL[0x0] DFSTATUS[0x2]:(FIFOFULL) 
STACK: 0x0 0x16a 0x19a 0x3
SCB count = 254
Kernel NEXTQSCB = 238
Card NEXTQSCB = 238
QINFIFO entries: 
Waiting Queue entries: 
Disconnected Queue entries: 0:248 
QOUTFIFO entries: 
Sequencer Free SCB List: 1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 
Sequencer SCB Info: 
  0 SCB_CONTROL[0x44]:(DISCONNECTED|DISCENB) SCB_SCSIID[0x57] 
SCB_LUN[0x0] SCB_TAG[0xf8] 
  1 SCB_CONTROL[0x0] SCB_SCSIID[0xff]:(TWIN_CHNLB|OID|TWIN_TID) 
SCB_LUN[0xff]:(SCB_XFERLEN_ODD|LID) SCB_TAG[0xff] 
  2 SCB_CONTROL[0x0] SCB_SCSIID[0xff]:(TWIN_CHNLB|OID|TWIN_TID) 
SCB_LUN[0xff]:(SCB_XFERLEN_ODD|LID) SCB_TAG[0xff] 
  3 SCB_CONTROL[0x0] SCB_SCSIID[0xff]:(TWIN_CHNLB|OID|TWIN_TID) 
SCB_LUN[0xff]:(SCB_XFERLEN_ODD|LID) SCB_TAG[0xff] 
  4 SCB_CONTROL[0x0] SCB_SCSIID[0xff]:(TWIN_CHNLB|OID|TWIN_TID) 
SCB_LUN[0xff]:(SCB_XFERLEN_ODD|LID) SCB_TAG[0xff] 
  5 SCB_CONTROL[0x0] SCB_SCSIID[0xff]:(TWIN_CHNLB|OID|TWIN_TID) 
SCB_LUN[0xff]:(SCB_XFERLEN_ODD|LID) SCB_TAG[0xff] 
  6 SCB_CONTROL[0x0] SCB_SCSIID[0xff]:(TWIN_CHNLB|OID|TWIN_TID) 
SCB_LUN[0xff]:(SCB_XFERLEN_ODD|LID) SCB_TAG[0xff] 
  7 SCB_CONTROL[0x0] SCB_SCSIID[0xff]:(TWIN_CHNLB|OID|TWIN_TID) 
SCB_LUN[0xff]:(SCB_XFERLEN_ODD|LID) SCB_TAG[0xff] 
  8 SCB_CONTROL[0x0] SCB_SCSIID[0xff]:(TWIN_CHNLB|OID|TWIN_TID) 
SCB_LUN[0xff]:(SCB_XFERLEN_ODD|LID) SCB_TAG[0xff] 
  9 SCB_CONTROL[0x0] SCB_SCSIID[0xff]:(TWIN_CHNLB|OID|TWIN_TID) 
SCB_LUN[0xff]:(SCB_XFERLEN_ODD|LID) SCB_TAG[0xff] 
 10 SCB_CONTROL[0x0] SCB_SCSIID[0xff]:(TWIN_CHNLB|OID|TWIN_TID) 
SCB_LUN[0xff]:(SCB_XFERLEN_ODD|LID) SCB_TAG[0xff] 
 11 SCB_CONTROL[0x0] SCB_SCSIID[0xff]:(TWIN_CHNLB|OID|TWIN_TID) 
SCB_LUN[0xff]:(SCB_XFERLEN_ODD|LID) SCB_TAG[0xff] 
 12 SCB_CONTROL[0x0] SCB_SCSIID[0xff]:(TWIN_CHNLB|OID|TWIN_TID) 
SCB_LUN[0xff]:(SCB_XFERLEN_ODD|LID) SCB_TAG[0xff] 
 13 SCB_CONTROL[0x0] SCB_SCSIID[0xff]:(TWIN_CHNLB|OID|TWIN_TID) 
SCB_LUN[0xff]:(SCB_XFERLEN_ODD|LID) SCB_TAG[0xff] 
 14 SCB_CONTROL[0x0] SCB_SCSIID[0xff]:(TWIN_CHNLB|OID|TWIN_TID) 
SCB_LUN[0xff]:(SCB_XFERLEN_ODD|LID) SCB_TAG[0xff] 
 15 SCB_CONTROL[0x0] SCB_SCSIID[0xff]:(TWIN_CHNLB|OID|TWIN_TID) 
SCB_LUN[0xff]:(SCB_XFERLEN_ODD|LID) SCB_TAG[0xff] 
Pending list: 
248 SCB_CONTROL[0x40]:(DISCENB) SCB_SCSIID[0x57] SCB_LUN[0x0] 
Kernel Free SCB list: 239 240 241 242 243 244 245 246 247 249 250 251
252 253 237 236 235 234 233 232 231 230 229 228 227 226 225 224 223 222
221 220 219 218 217 216 215 214 213 212 211 210 209 208 207 206 205 204
203 202 201 200 199 198 197 196 195 194 193 192 191 190 189 188 187 186
185 184 183 182 181 180 179 178 177 176 175 174 173 172 171 170 169 168
167 166 165 164 163 162 161 160 159 158 157 156 155 154 153 152 151 150
149 148 147 146 145 144 143 142 141 140 139 138 137 136 135 134 133 132
131 130 129 128 127 126 125 124 123 122 121 120 119 118 117 116 115 114
113 112 111 110 109 108 107 106 105 104 103 102 101 100 99 98 97 96 95
94 93 92 91 90 89 88 87 86 85 84 83 82 81 80 79 78 77 76 75 74 73 72 71
70 69 68 67 66 65 64 63 62 61 60 59 58 57 56 55 54 53 52 51 50 49 48 47
46 45 44 43 42 41 40 39 38 37 36 35 34 33 32 31 30 29 28 27 26 25 24 23
22 21 20 19 18 17 16 15 14 13 12 11 10 9 8 7 6 5 4 3 2 1 0 
Untagged Q(5): 248 

<<<<<<<<<<<<<<<<< Dump Card State Ends >>>>>>>>>>>>>>>>>>
(sa0:ahc0:0:5:0): SCB 0xf8 - timed out
sg[0] - Addr 0x375e028 : Length 8152
sg[1] - Addr 0x3700000 : Length 4096
sg[2] - Addr 0x2cb0000 : Length 20480
sg[3] - Addr 0x3701000 : Length 20480
sg[4] - Addr 0x375c000 : Length 4096
sg[5] - Addr 0x4026000 : Length 4096
sg[6] - Addr 0x4700000 : Length 3112
(sa0:ahc0:0:5:0): Queuing a BDR SCB
ahc0: Timedout SCBs already complete. Interrupts may not be functioning.
(sa0:ahc0:0:5:0): Bus Device Reset Message Sent
(sa0:ahc0:0:5:0): no longer in timeout, status = 24b
ahc0: Bus Device Reset on A:5. 1 SCBs aborted
(sa0:ahc0:0:5:0): MODE SENSE(06). CDB: 1a 0 f 0 1c 0 
(sa0:ahc0:0:5:0): NO SENSE ILI (length mismatch): -56320 asc:0,0
(sa0:ahc0:0:5:0): No additional sense information
(sa0:ahc0:0:5:0): MODE SENSE(06). CDB: 1a 0 f 0 1c 0 
(sa0:ahc0:0:5:0): NO SENSE ILI (length mismatch): -56320 asc:0,0
(sa0:ahc0:0:5:0): No additional sense information
%

Do you have an idea what could be wrong ? Should I fill a bug report ?

Thanks, (and sorry for my english)

Julien

ps: as I'm not subscribed on this list, could you include my email
address if you reply ?

-- 
Julien Cigar
Belgian Biodiversity Platform
http://www.biodiversity.be
Université Libre de Bruxelles (ULB)
Campus de la Plaine CP 257
Bâtiment NO, Bureau 4 N4 115C (Niveau 4)
Boulevard du Triomphe, entrée ULB 2
B-1050 Bruxelles
Mail: jcigar at ulb.ac.be
@biobel: http://biobel.biodiversity.be/person/show/471
Tel : 02 650 57 52