From owner-freebsd-scsi@freebsd.org Fri Jul 3 06:52:13 2015 Return-Path: Delivered-To: freebsd-scsi@mailman.ysv.freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:1900:2254:206a::19:1]) by mailman.ysv.freebsd.org (Postfix) with ESMTP id 5C570992AFA for ; Fri, 3 Jul 2015 06:52:13 +0000 (UTC) (envelope-from marcus@odin.blazingdot.com) Received: from odin.blazingdot.com (odin.blazingdot.com [204.109.60.170]) by mx1.freebsd.org (Postfix) with ESMTP id 45C54146A for ; Fri, 3 Jul 2015 06:52:12 +0000 (UTC) (envelope-from marcus@odin.blazingdot.com) Received: by odin.blazingdot.com (Postfix, from userid 1001) id 3BDE61320ED; Thu, 2 Jul 2015 23:42:47 -0700 (PDT) Date: Thu, 2 Jul 2015 23:42:47 -0700 From: Marcus Reid To: freebsd-scsi@freebsd.org Subject: ahd and LTO3 drive, not working right Message-ID: <20150703064247.GA19462@blazingdot.com> MIME-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline Content-Transfer-Encoding: quoted-printable X-Coffee-Level: nearly-fatal User-Agent: Mutt/1.5.23 (2014-03-12) X-BeenThere: freebsd-scsi@freebsd.org X-Mailman-Version: 2.1.20 Precedence: list List-Id: SCSI subsystem List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Fri, 03 Jul 2015 06:52:13 -0000 Hi, I've got a: ahd0@pci0:4:1:0: class=3D0x010400 card=3D0x00449005 chip=3D0x809= 79005 rev=3D0x10 hdr=3D0x00 vendor =3D 'Adaptec' device =3D 'ASC-29320ALP U320 w/HostRAID' class =3D mass storage subclass =3D RAID with an HP LTO3 drive on it. Controlling the drive and writing work great, but when I attempt to read the tape I get a few blocks and then: Jul 2 23:23:31 sol kernel: (sa0:ahd0:0:3:0): 10240-byte tape record bi= gger than supplied buffer Jul 2 23:24:15 sol kernel: ahd0: SCSI offset overrun detected. Resett= ing bus. Jul 2 23:24:15 sol kernel: ahd0: Invalid Sequencer interrupt occurred. Jul 2 23:24:15 sol kernel: >>>>>>>>>>>>>>>>>> Dump Card State Begins <= <<<<<<<<<<<<<<<< Jul 2 23:24:15 sol kernel: ahd0: Dumping Card State at program address= 0x231 Mode 0x0 Jul 2 23:24:15 sol kernel: Card was paused Jul 2 23:24:15 sol kernel: INTSTAT[0x0] SELOID[0x3] SELID[0x30] HS_MAI= LBOX[0x0]=20 Jul 2 23:24:15 sol kernel: INTCTL[0x80]:(SWTMINTMASK) SEQINTSTAT[0x0] = SAVED_MODE[0x11]=20 Jul 2 23:24:15 sol kernel: DFFSTAT[0x33]:(CURRFIFO_NONE|FIFO0FREE|FIFO= 1FREE)=20 Jul 2 23:24:15 sol kernel: SCSISIGI[0x0]:(P_DATAOUT) SCSIPHASE[0x0] SC= SIBUS[0x0]=20 Jul 2 23:24:15 sol kernel: LASTPHASE[0x1]:(P_DATAOUT|P_BUSFREE) SCSISE= Q0[0x0]=20 Jul 2 23:24:15 sol kernel: SCSISEQ1[0x12]:(ENAUTOATNP|ENRSELI) SEQCTL0= [0x0] SEQINTCTL[0x6]:(INTMASK1|INTMASK2)=20 Jul 2 23:24:15 sol kernel: SEQ_FLAGS[0x0] SEQ_FLAGS2[0x0] QFREEZE_COUN= T[0x7c]=20 Jul 2 23:24:15 sol kernel: KERNEL_QFREEZE_COUNT[0x7c] MK_MESSAGE_SCB[0= xff00]=20 Jul 2 23:24:15 sol kernel: MK_MESSAGE_SCSIID[0xff] SSTAT0[0x0] SSTAT1[= 0x0] SSTAT2[0x0]=20 Jul 2 23:24:15 sol kernel: SSTAT3[0x0] PERRDIAG[0x0] SIMODE1[0x84]:(EN= SCSIPERR|ENSELTIMO)=20 Jul 2 23:24:15 sol kernel: LQISTAT0[0x0] LQISTAT1[0x0] LQISTAT2[0x0] L= QOSTAT0[0x0]=20 Jul 2 23:24:15 sol kernel: LQOSTAT1[0x0] LQOSTAT2[0xe1]:(LQOSTOP0|LQOP= KT)=20 Jul 2 23:24:15 sol kernel:=20 Jul 2 23:24:15 sol kernel: SCB Count =3D 512 CMDS_PENDING =3D 0 LASTSC= B 0xffff CURRSCB 0x1f4 NEXTSCB 0xff40 Jul 2 23:24:15 sol kernel: qinstart =3D 172 qinfifonext =3D 172 Jul 2 23:24:15 sol kernel: QINFIFO: Jul 2 23:24:15 sol kernel: WAITING_TID_QUEUES: Jul 2 23:24:15 sol kernel: Pending list: Jul 2 23:24:15 sol kernel: Total 0 Jul 2 23:24:15 sol kernel: Kernel Free SCB lists:=20 Jul 2 23:24:15 sol kernel: Any Device: 500 497 498 499 501 502 503 504= 505 506 507 508 509 510 511 496 495 494 493 492 491 490 489 488 487 486 48= 5 484 483 482 481 480 479 478 477 476 475 474 473 472 471 470 469 468 467 4= 66 465 464 463 462 461 460 459 458 457 456 455 454 453 452 451 450 449 448 = 447 446 445 444 443 442 441 440 439 438 437 436 435 434 433 432 431 430 429= 428 427 426 425 424 423 422 421 420 419 418 417 416 415 414 413 412 411 41= 0 409 408 407 406 405 404 403 402 401 400 399 398 397 396 395 394 393 392 3= 91 390 389 388 387 386 385 384 383 382 381 380 379 378 377 376 375 374 373 = 372 371 370 369 368 367 366 365 364 363 362 361 360 359 358 357 356 355 354= 353 352 351 350 349 348 347 346 345 344 343 342 341 340 339 338 337 336 33= 5 334 333 332 331 330 329 328 327 326 325 324 323 322 321 320 319 318 317 3= 16 315 314 313 312 311 310 309 308 307 306 305 304 303 302 301 300 299 298 = 297 296 295 294 293 292 291 290 289 288 287 286 285 284 283 282 281 280 279= 278 277 276 275 274 273 272 271 270 269 268 267 266 265 264 263 262 261=20 Jul 2 23:24:15 sol kernel: 59 258 257 256 255 254 253 252 251 250 249 = 248 247 246 245 244 243 242 241 240 239 238 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 21= 1 210 209 208 207 206 205 204 203 202 201 200 199 198 197 196 195 194 193 1= 92 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 13= 6 135 134 133 132 131 130 129 128 127 126 125 124 123 122 121 120 119 118 1= 17 116 115 114 113 112 111 110 109 108 107 106 105 104 103 102 101 100 99 9= 8 97 96 95 94 93 92 91 90 89 88 87 86 85 84 83 82 81 80 79 78 77 76 75 74 7= 3 72 71 70 69 68 67 66 65 64 63 62 61 60 59 58 57 56 55 54 53 52 51 50 49 4= 8 47 46 45 44 43 42 41 40 39 38 37 36 35 34 33 32 31 30 29 28 27 26 25 24 2= 3 22 21 20 19 18 17 16 15 14 13 12 11 10 9 8 7 6 5 4 3 2 1 0=20 Jul 2 23:24:15 sol kernel: Sequencer Complete DMA-inprog list:=20 Jul 2 23:24:15 sol kernel: Sequencer Complete list:=20 Jul 2 23:24:15 sol kernel: Sequencer DMA-Up and Complete list:=20 Jul 2 23:24:15 sol kernel: Sequencer On QFreeze and Complete list:=20 Jul 2 23:24:15 sol kernel:=20 Jul 2 23:24:15 sol kernel:=20 Jul 2 23:24:15 sol kernel: ahd0: FIFO0 Free, LONGJMP =3D=3D 0x8054, SC= B 0x1f4 Jul 2 23:24:15 sol kernel: SEQIMODE[0x3f]:(ENCFG4TCMD|ENCFG4ICMD|ENCFG= 4TSTAT|ENCFG4ISTAT|ENCFG4DATA|ENSAVEPTRS)=20 Jul 2 23:24:15 sol kernel: SEQINTSRC[0x0] DFCNTRL[0x0] DFSTATUS[0x89]:= (FIFOEMP|HDONE|PRELOAD_AVAIL)=20 Jul 2 23:24:15 sol kernel: SG_CACHE_SHADOW[0x2]:(LAST_SEG) SG_STATE[0x= 0] DFFSXFRCTL[0x0]=20 Jul 2 23:24:15 sol kernel: SOFFCNT[0x0] MDFFSTAT[0x5]:(FIFOFREE|DLZERO= ) SHADDR =3D 0x00, SHCNT =3D 0x0=20 Jul 2 23:24:15 sol kernel: HADDR =3D 0x00, HCNT =3D 0x0 CCSGCTL[0x10]:= (SG_CACHE_AVAIL)=20 Jul 2 23:24:15 sol kernel:=20 Jul 2 23:24:15 sol kernel: ahd0: FIFO1 Free, LONGJMP =3D=3D 0x807c, SC= B 0x1f4 Jul 2 23:24:15 sol kernel: SEQIMODE[0x3f]:(ENCFG4TCMD|ENCFG4ICMD|ENCFG= 4TSTAT|ENCFG4ISTAT|ENCFG4DATA|ENSAVEPTRS)=20 Jul 2 23:24:15 sol kernel: SEQINTSRC[0x0] DFCNTRL[0x0] DFSTATUS[0x89]:= (FIFOEMP|HDONE|PRELOAD_AVAIL)=20 Jul 2 23:24:15 sol kernel: SG_CACHE_SHADOW[0x2]:(LAST_SEG) SG_STATE[0x= 0] DFFSXFRCTL[0x0]=20 Jul 2 23:24:15 sol kernel: SOFFCNT[0x0] MDFFSTAT[0x5]:(FIFOFREE|DLZERO= ) SHADDR =3D 0x00, SHCNT =3D 0x0=20 Jul 2 23:24:15 sol kernel: HADDR =3D 0x00, HCNT =3D 0x0 CCSGCTL[0x10]:= (SG_CACHE_AVAIL)=20 Jul 2 23:24:15 sol kernel: LQIN: 0x4 0x0 0x1 0xf4 0x0 0x0 0x0 0x0 0x0 = 0x0 0x0 0x0 0x0 0x0 0x28 0x0 0x0 0x0 0x2 0x0=20 Jul 2 23:24:15 sol kernel: ahd0: LQISTATE =3D 0x0, LQOSTATE =3D 0x0, O= PTIONMODE =3D 0x52 Jul 2 23:24:15 sol kernel: ahd0: OS_SPACE_CNT =3D 0x20 MAXCMDCNT =3D 0= x1 Jul 2 23:24:15 sol kernel: ahd0: SAVED_SCSIID =3D 0x0 SAVED_LUN =3D 0x0 Jul 2 23:24:15 sol kernel:=20 Jul 2 23:24:15 sol kernel: SIMODE0[0xc]:(ENOVERRUN|ENIOERR)=20 Jul 2 23:24:15 sol kernel: CCSCBCTL[0x4]:(CCSCBDIR)=20 Jul 2 23:24:15 sol kernel: ahd0: REG0 =3D=3D 0xa360, SINDEX =3D 0x108,= DINDEX =3D 0x108 Jul 2 23:24:15 sol kernel: ahd0: SCBPTR =3D=3D 0x1f4, SCB_NEXT =3D=3D = 0xff40, SCB_NEXT2 =3D=3D 0xffb8 Jul 2 23:24:15 sol kernel: CDB 8 0 1 80 8 fe Jul 2 23:24:15 sol kernel: STACK: 0x22c 0x1 0x0 0x0 0x0 0x0 0x0 0x0 Jul 2 23:24:15 sol kernel: <<<<<<<<<<<<<<<<< Dump Card State Ends >>>>= >>>>>>>>>>>>>> Jul 2 23:24:20 sol kernel: (sa0:ahd0:0:3:0): PREVENT ALLOW MEDIUM REMO= VAL. CDB: 1e 00 00 00 00 00=20 Jul 2 23:24:20 sol kernel: (sa0:ahd0:0:3:0): CAM status: SCSI Status E= rror Jul 2 23:24:20 sol kernel: (sa0:ahd0:0:3:0): SCSI status: Check Condit= ion Jul 2 23:24:20 sol kernel: (sa0:ahd0:0:3:0): SCSI sense: UNIT ATTENTIO= N asc:29,6 (Transceiver mode changed to LVD) Jul 2 23:24:20 sol kernel: (sa0:ahd0:0:3:0): Retrying command (per sen= se data) Jul 2 23:24:20 sol kernel: (sa0:ahd0:0:3:0): tape is now frozen- use a= n OFFLINE, REWIND or MTEOM command to clear this state. I tried it in a different OS and things seem to work (it's slow over there but it is able to read the tape). I've tried a number of different block sizes. Any ideas what to try next? Thanks, Marcus