From owner-freebsd-scsi@FreeBSD.ORG Mon Nov 17 09:06:30 2008 Return-Path: Delivered-To: freebsd-scsi@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:4f8:fff6::34]) by hub.freebsd.org (Postfix) with ESMTP id 97EB51065670 for ; Mon, 17 Nov 2008 09:06:30 +0000 (UTC) (envelope-from jcigar@ulb.ac.be) Received: from mxin.vub.ac.be (mxin.vub.ac.be [134.184.129.110]) by mx1.freebsd.org (Postfix) with ESMTP id 1999B8FC12 for ; Mon, 17 Nov 2008 09:06:29 +0000 (UTC) (envelope-from jcigar@ulb.ac.be) X-IronPort-Anti-Spam-Filtered: true X-IronPort-Anti-Spam-Result: Ai0BAHmyIEmkD30E/2dsb2JhbAAIlFmvH4lbgnk Received: from bebif01.ulb.ac.be (HELO [10.0.0.194]) ([164.15.125.4]) by smtp.vub.ac.be with ESMTP; 17 Nov 2008 09:36:53 +0100 From: Julien Cigar To: freebsd-scsi@freebsd.org Content-Type: text/plain; charset=UTF-8 Date: Mon, 17 Nov 2008 09:37:08 +0100 Message-Id: <1226911028.2746.14.camel@frodon.be-bif.ulb.ac.be> Mime-Version: 1.0 X-Mailer: Evolution 2.22.3.1 Content-Transfer-Encoding: 8bit Subject: ahc X-BeenThere: freebsd-scsi@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: SCSI subsystem List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Mon, 17 Nov 2008 09:06:30 -0000 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: 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: 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@ulb.ac.be @biobel: http://biobel.biodiversity.be/person/show/471 Tel : 02 650 57 52