Date: Sat, 1 Mar 2003 10:49:34 +0100 From: Francesco Casadei <fcasadei@inwind.it> To: Joan Picanyol i Puig <lists-freebsd-stable@biaix.org> Cc: freebsd-stable@freebsd.org Subject: Re: (da0:ahc0:0:0:0): Unexpected busfree in Data-in phase and other weirdness Message-ID: <20030301094934.GA547@goku.kasby> In-Reply-To: <20030228235031.GE5033@grummit.biaix.org> References: <20030228235031.GE5033@grummit.biaix.org>
next in thread | previous in thread | raw e-mail | index | archive | help
[-- Attachment #1 --] On Sat, Mar 01, 2003 at 12:50:31AM +0100, Joan Picanyol i Puig wrote: > [reposted from -scsi@, maybe that's not the right place] > > Hi, > > On an Adaptec 2940 I have an IBM DNES-309170W and a SEAGATE ST318438LW, > soft-raided with vinum. Lately it seems that the Seagate disc has become > 'unstable', and I don't know how to diagnose any further. I've checked > the cabling and I've tried the SeaTools floppy disk from Seagate but it > hangs on my system :( > > Please have a look at the excerpt of kernel logs at > http://biaix.org/pk/debug/. messages.1.kernel shows what happened (look > for Feb 4) while recording a cd with my SCSI cd-writer. The system > appeared to hang for anything between 3 and 20 minutes while I was > getting those. messages.0.kernel shows what happened today (Feb 27) for > no apparent reason. Problems persisted across reboots, even though some > of them were not logged (could not fsck). > > For further reference, please look at this thread from two months ago: > http://www.FreeBSD.org/cgi/getmsg.cgi?fetch=2329637+0+/usr/local/www/db/text/2002/freebsd-questions/20021222.freebsd-questions > > I'm really stumped so I'd appreciate any help in the lines of: > > 1.- What's causing these problems? > 2.- How can I solve them? > > tks > -- > pica > > To Unsubscribe: send mail to majordomo@FreeBSD.org > with "unsubscribe freebsd-stable" in the body of the message > > end of the original message I'm having this problem too. When this happens the SCSI bus is reset. Here's the error message (not wrapped): Jan 24 12:10:27 goku /kernel: (da1:ahc0:0:1:0): SCB 0x8 - timed out Jan 24 12:10:27 goku /kernel: ahc0: Dumping Card State while idle, at SEQADDR 0x8 Jan 24 12:10:27 goku /kernel: ACCUM = 0xc0, SINDEX = 0x11, DINDEX = 0x8c, ARG_2 = 0x0 Jan 24 12:10:27 goku /kernel: HCNT = 0x0 SCBPTR = 0x0 Jan 24 12:10:27 goku /kernel: SCSISEQ = 0x12, SBLKCTL = 0x2 Jan 24 12:10:27 goku /kernel: DFCNTRL = 0x0, DFSTATUS = 0x29 Jan 24 12:10:27 goku /kernel: LASTPHASE = 0x1, SCSISIGI = 0x0, SXFRCTL0 = 0x80 Jan 24 12:10:27 goku /kernel: SSTAT0 = 0x5, SSTAT1 = 0xa Jan 24 12:10:27 goku /kernel: STACK == 0x3, 0xec, 0x147, 0x0 Jan 24 12:10:27 goku /kernel: SCB count = 80 Jan 24 12:10:27 goku /kernel: Kernel NEXTQSCB = 30 Jan 24 12:10:27 goku /kernel: Card NEXTQSCB = 30 Jan 24 12:10:27 goku /kernel: QINFIFO entries: Jan 24 12:10:27 goku /kernel: Waiting Queue entries: Jan 24 12:10:27 goku /kernel: Disconnected Queue entries: 12:13 9:46 8:35 15:3 10:8 Jan 24 12:10:27 goku /kernel: QOUTFIFO entries: Jan 24 12:10:27 goku /kernel: Sequencer Free SCB List: 0 13 7 4 3 14 11 5 1 2 6 Jan 24 12:10:27 goku /kernel: Sequencer SCB Info: 0(c 0x68, s 0x17, l 0, t 0xff) 1(c 0x68, s 0x17, l 0, t 0xff) 2(c 0x68, s 0x17, l 0, t 0xff ) 3(c 0x68, s 0x17, l 0, t 0xff) 4(c 0x68, s 0x17, l 0, t 0xff) 5(c 0x68, s 0x17, l 0, t 0xff) 6(c 0x68, s 0x17, l 0, t 0xff) 7(c 0x68, s 0x1 7, l 0, t 0xff) 8(c 0x6c, s 0x17, l 0, t 0x23) 9(c 0x6c, s 0x17, l 0, t 0x2e) 10(c 0x6c, s 0x17, l 0, t 0x8) 11(c 0x68, s 0x17, l 0, t 0xff) 12(c 0x6c, s 0x17, l 0, t 0xd) 13(c 0x68, s 0x17, l 0, t 0xff) 14(c 0x68, s 0x17, l 0, t 0xff) 15(c 0x6c, s 0x17, l 0, t 0x3) Jan 24 12:10:27 goku /kernel: Pending list: 32(c 0x6c, s 0x17, l 0), 5(c 0x6c, s 0x17, l 0), 20(c 0x6c, s 0x17, l 0), 59(c 0x6c, s 0x17, l 0) , 4(c 0x6c, s 0x17, l 0), 49(c 0x6c, s 0x17, l 0), 58(c 0x6c, s 0x17, l 0), 25(c 0x6c, s 0x17, l 0), 13(c 0x68, s 0x17, l 0), 46(c 0x68, s 0x 17, l 0), 35(c 0x68, s 0x17, l 0), 3(c 0x68, s 0x17, l 0), 8(c 0x68, s 0x17, l 0) Jan 24 12:10:27 goku /kernel: Kernel Free SCB list: 17 26 23 52 12 7 53 44 47 34 22 27 54 41 6 43 14 51 69 38 57 10 40 2 62 55 71 36 42 24 18 0 15 39 48 19 67 37 31 45 29 1 28 68 16 33 11 21 56 50 9 66 72 73 74 75 76 77 78 79 60 61 64 63 65 70 Jan 24 12:10:27 goku /kernel: sg[0] - Addr 0x7aa8000 : Length 4096 Jan 24 12:10:27 goku /kernel: sg[1] - Addr 0x7b69000 : Length 4096 Jan 24 12:10:27 goku /kernel: (da1:ahc0:0:1:0): Queuing a BDR SCB Jan 24 12:10:27 goku /kernel: (da1:ahc0:0:1:0): Bus Device Reset Message Sent Jan 24 12:10:27 goku /kernel: (da1:ahc0:0:1:0): no longer in timeout, status = 34b Jan 24 12:10:27 goku /kernel: ahc0: Bus Device Reset on A:1. 13 SCBs aborted Jan 24 15:19:46 goku login: ROOT LOGIN (root) ON ttyv0 Jan 24 18:22:36 goku /kernel: (da1:ahc0:0:1:0): Unexpected busfree in Data-in phase Jan 24 18:22:36 goku /kernel: SEQADDR == 0x6d Jan 24 18:22:43 goku /kernel: (da1:ahc0:0:1:0): Unexpected busfree in Data-in phase Jan 24 18:22:43 goku /kernel: SEQADDR == 0x6d Jan 24 18:22:50 goku /kernel: (da1:ahc0:0:1:0): Unexpected busfree in Data-in phase Jan 24 18:22:51 goku /kernel: SEQADDR == 0x6c Jan 24 18:22:51 goku /kernel: (da1:ahc0:0:1:0): Invalidating pack Jan 24 18:23:43 goku /kernel: (da1:ahc0:0:1:0): SCB 0xe - timed out Jan 24 18:23:43 goku /kernel: ahc0: Dumping Card State while idle, at SEQADDR 0x8 Jan 24 18:23:43 goku /kernel: ACCUM = 0x29, SINDEX = 0x38, DINDEX = 0x8c, ARG_2 = 0x0 Jan 24 18:23:43 goku /kernel: HCNT = 0x0 SCBPTR = 0x0 Jan 24 18:23:43 goku /kernel: SCSISEQ = 0x12, SBLKCTL = 0x2 Jan 24 18:23:43 goku /kernel: DFCNTRL = 0x0, DFSTATUS = 0x29 Jan 24 18:23:43 goku /kernel: LASTPHASE = 0x1, SCSISIGI = 0x0, SXFRCTL0 = 0x80 Jan 24 18:23:43 goku /kernel: SSTAT0 = 0x5, SSTAT1 = 0xa Jan 24 18:23:43 goku /kernel: STACK == 0x3, 0xec, 0x147, 0xcb Jan 24 18:23:43 goku /kernel: SCB count = 80 Jan 24 18:23:43 goku /kernel: Kernel NEXTQSCB = 78 Jan 24 18:23:43 goku /kernel: Card NEXTQSCB = 78 Jan 24 18:23:43 goku /kernel: QINFIFO entries: Jan 24 18:23:43 goku /kernel: Waiting Queue entries: Jan 24 18:23:43 goku /kernel: Disconnected Queue entries: 13:12 6:40 5:41 7:48 10:14 Jan 24 18:23:43 goku /kernel: QOUTFIFO entries: Jan 24 18:23:43 goku /kernel: Sequencer Free SCB List: 0 1 8 15 11 2 12 3 14 4 9 Jan 24 18:23:43 goku /kernel: Sequencer SCB Info: 0(c 0x68, s 0x7, l 0, t 0xff) 1(c 0x68, s 0x7, l 0, t 0xff) 2(c 0x68, s 0x7, l 0, t 0xff) 3 (c 0x68, s 0x7, l 0, t 0xff) 4(c 0x68, s 0x7, l 0, t 0xff) 5(c 0x6c, s 0x17, l 0, t 0x29) 6(c 0x6c, s 0x17, l 0, t 0x28) 7(c 0x6c, s 0x17, l 0, t 0x30) 8(c 0x68, s 0x7, l 0, t 0xff) 9(c 0x68, s 0x7, l 0, t 0xff) 10(c 0x6c, s 0x17, l 0, t 0xe) 11(c 0x68, s 0x7, l 0, t 0xff) 12(c 0x6 8, s 0x7, l 0, t 0xff) 13(c 0x6c, s 0x17, l 0, t 0xc) 14(c 0x68, s 0x7, l 0, t 0xff) 15(c 0x68, s 0x7, l 0, t 0xff) Jan 24 18:23:43 goku /kernel: Pending list: 12(c 0x68, s 0x17, l 0), 40(c 0x68, s 0x17, l 0), 41(c 0x68, s 0x17, l 0), 48(c 0x68, s 0x17, l 0 ), 14(c 0x68, s 0x17, l 0) Jan 24 18:23:43 goku /kernel: Kernel Free SCB list: 56 49 4 20 31 76 52 51 8 13 57 77 55 26 53 18 19 16 68 36 42 10 5 47 2 79 34 44 6 1 74 72 33 73 3 25 45 67 23 30 35 59 38 62 50 75 9 7 29 71 46 32 27 21 39 58 0 69 22 54 11 15 66 37 43 28 17 24 60 61 64 63 65 70 Jan 24 18:23:43 goku /kernel: sg[0] - Addr 0x73e5000 : Length 4096 Jan 24 18:23:43 goku /kernel: sg[1] - Addr 0x3586000 : Length 4096 Jan 24 18:23:43 goku /kernel: sg[2] - Addr 0x4b27000 : Length 4096 Jan 24 18:23:44 goku /kernel: sg[3] - Addr 0x1e08000 : Length 4096 Jan 24 18:23:44 goku /kernel: sg[4] - Addr 0x2709000 : Length 4096 Jan 24 18:23:44 goku /kernel: sg[5] - Addr 0x128a000 : Length 4096 Jan 24 18:23:44 goku /kernel: sg[6] - Addr 0x358b000 : Length 4096 Jan 24 18:23:44 goku /kernel: sg[7] - Addr 0x472c000 : Length 4096 Jan 24 18:23:44 goku /kernel: sg[8] - Addr 0x2a4d000 : Length 4096 Jan 24 18:23:44 goku /kernel: sg[9] - Addr 0x68ce000 : Length 4096 Jan 24 18:23:44 goku /kernel: sg[10] - Addr 0x340f000 : Length 4096 Jan 24 18:23:44 goku /kernel: sg[11] - Addr 0x6f0000 : Length 4096 Jan 24 18:23:44 goku /kernel: sg[12] - Addr 0x44b1000 : Length 4096 Jan 24 18:23:44 goku /kernel: sg[13] - Addr 0x5452000 : Length 4096 Jan 24 18:23:44 goku /kernel: sg[14] - Addr 0xa93000 : Length 4096 Jan 24 18:23:44 goku /kernel: sg[15] - Addr 0x3514000 : Length 4096 Jan 24 18:23:44 goku /kernel: (da1:ahc0:0:1:0): Queuing a BDR SCB Jan 24 18:23:44 goku /kernel: (da1:ahc0:0:1:0): Bus Device Reset Message Sent Jan 24 18:23:44 goku /kernel: (da1:ahc0:0:1:0): no longer in timeout, status = 34b Jan 24 18:23:44 goku /kernel: ahc0: Bus Device Reset on A:1. 5 SCBs aborted Here's the system configuration: # camcontrol devlist <IBM DDRS-34560W S92A> at scbus0 target 0 lun 0 (pass0,da0) <IBM DNES-318350W SA30> at scbus0 target 1 lun 0 (pass1,da1) <TEAC CD-R55S 1.0R> at scbus0 target 2 lun 0 (pass2,cd0) <PLEXTOR CD-ROM PX-32TS 1.02> at scbus0 target 3 lun 0 (pass3,cd1) # dmesg | grep ahc0: ahc0: <Adaptec 2940 Ultra SCSI adapter> port 0xd400-0xd4ff mem 0xde000000-0xde000fff irq 10 at device 11.0 on pci0 # uname -a FreeBSD goku.kasby 4.7-STABLE FreeBSD 4.7-STABLE #1: Thu Dec 26 12:47:59 CET 2002 root@goku.kasby:/usr/obj/usr/src/sys/GOKU i386 Francesco Casadei -- You can download my public key from http://digilander.libero.it/fcasadei/ or retrieve it from a keyserver (pgpkeys.mit.edu, wwwkeys.pgp.net, ...) Key fingerprint is: 1671 9A23 ACB4 520A E7EE 00B0 7EC3 375F 164E B17B [-- Attachment #2 --] -----BEGIN PGP SIGNATURE----- Version: GnuPG v1.2.1 (FreeBSD) iD8DBQE+YIItfsM3XxZOsXsRAvlLAJ4r6A0bkQcyg1EcGgJy5pJ7iOb4EwCglaq9 K5lsU3Aew4vSq2zvpzCdjUY= =OJMp -----END PGP SIGNATURE-----
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?20030301094934.GA547>