From owner-freebsd-scsi@FreeBSD.ORG Thu Mar 10 00:54:20 2005 Return-Path: Delivered-To: freebsd-scsi@freebsd.org Received: from mx1.FreeBSD.org (mx1.freebsd.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id D721E16A4CE for ; Thu, 10 Mar 2005 00:54:20 +0000 (GMT) Received: from ack.Berkeley.EDU (ack.Berkeley.EDU [128.32.206.66]) by mx1.FreeBSD.org (Postfix) with ESMTP id 9956C43D1F for ; Thu, 10 Mar 2005 00:54:20 +0000 (GMT) (envelope-from mhunter@ack.Berkeley.EDU) Received: (from mhunter@localhost) by ack.Berkeley.EDU (8.11.3/8.11.3) id j2A0sKL21214 for freebsd-scsi@freebsd.org; Wed, 9 Mar 2005 16:54:20 -0800 (PST) Date: Wed, 9 Mar 2005 16:54:20 -0800 From: Mike Hunter To: freebsd-scsi@freebsd.org Message-ID: <20050310005420.GB20136@ack.Berkeley.EDU> Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline User-Agent: Mutt/1.5.6i Subject: Dump card states w/ increasing frequency (FBSD 4.8, dual Xeon) X-BeenThere: freebsd-scsi@freebsd.org X-Mailman-Version: 2.1.1 Precedence: list List-Id: SCSI subsystem List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Thu, 10 Mar 2005 00:54:21 -0000 Hi everybody, About 3 weeks ago, one of my boxes started doing frequent (once or twice a day) dump card states. It's a fbsd machine running 4.8-RELEASE-p16. This was a machine that did a lot of dump card states right when I bought it, which was tracked down to an incompatibility with the builtin adaptec controller, and I switched to an add-on card (29160/N/LP Ultra160 (AIC-7892A) SCSI Host Adapter). I haven't updated the OS recently so it seems unlikely that it's FBSD related, unless increasing load on the box is revealing a previously unseen issue. All the dump card states relate to da2, a 70G seagate disk. Would a disk that's flaking out give these kinds of messages, or is this some kind of controller / OS issue? I'm wondering if I should try replacing the disk or if there any other causes I can investigate. Thanks for any advice, Mike Mar 9 09:55:08 mybox0r /kernel: (da2:ahc0:0:2:0): SCB 0x14 - timed out Mar 9 09:55:23 mybox0r /kernel: >>>>>>>>>>>>>>>>>> Dump Card State Begins <<<<<<<<<<<<<<<<< Mar 9 09:55:23 mybox0r /kernel: ahc0: Dumping Card State while idle, at SEQADDR 0x8 Mar 9 09:55:23 mybox0r /kernel: Card was paused Mar 9 09:55:23 mybox0r /kernel: ACCUM = 0x0, SINDEX = 0x3e, DINDEX = 0xe4, ARG_2 = 0x0 Mar 9 09:55:23 mybox0r /kernel: HCNT = 0x0 SCBPTR = 0x1e Mar 9 09:55:23 mybox0r /kernel: SCSIPHASE[0x0] SCSISIGI[0x0] ERROR[0x0] SCSIBUSL[0x0] Mar 9 09:55:23 mybox0r /kernel: LASTPHASE[0x1]:(P_BUSFREE) SCSISEQ[0x12]:(ENAUTOATNP|ENRSELI) Mar 9 09:55:23 mybox0r /kernel: SBLKCTL[0x6]:(SELWIDE|ENAB20) SCSIRATE[0x0] SEQCTL[0x10]:(FASTMODE) Mar 9 09:55:23 mybox0r /kernel: SEQ_FLAGS[0xc0]:(NO_CDB_SENT|NOT_IDENTIFIED) SSTAT0[0x0] Mar 9 09:55:23 mybox0r /kernel: SSTAT1[0x8]:(BUSFREE) SSTAT2[0x0] SSTAT3[0x0] SIMODE0[0x8]:(ENSWRAP) Mar 9 09:55:23 mybox0r /kernel: SIMODE1[0xa4]:(ENSCSIPERR|ENSCSIRST|ENSELTIMO) SXFRCTL0[0x80]:(DFON) Mar 9 09:55:23 mybox0r /kernel: DFCNTRL[0x0] DFSTATUS[0x89]:(FIFOEMP|HDONE|PRELOAD_AVAIL) Mar 9 09:55:23 mybox0r /kernel: STACK: 0xe9 0x16b 0x111 0x3 Mar 9 09:55:23 mybox0r /kernel: SCB count = 110 Mar 9 09:55:23 mybox0r /kernel: Kernel NEXTQSCB = 16 Mar 9 09:55:23 mybox0r /kernel: Card NEXTQSCB = 16 Mar 9 09:55:23 mybox0r /kernel: QINFIFO entries: Mar 9 09:55:23 mybox0r /kernel: Waiting Queue entries: Mar 9 09:55:23 mybox0r /kernel: Disconnected Queue entries: 25:12 28:90 1:82 4:89 24:57 17:35 18:10 2:13 5:77 3:31 11:39 9:38 8:69 23:78 26:7 13:14 14:20 Mar 9 09:55:23 mybox0r /kernel: QOUTFIFO entries: Mar 9 09:55:23 mybox0r /kernel: Sequencer Free SCB List: 30 12 19 27 22 15 10 21 20 16 31 0 7 6 29 Mar 9 09:55:23 mybox0r /kernel: Sequencer SCB Info: Mar 9 09:55:23 mybox0r /kernel: 0 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] Mar 9 09:55:23 mybox0r /kernel: 1 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x52] Mar 9 09:55:23 mybox0r /kernel: 2 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xd] Mar 9 09:55:23 mybox0r /kernel: 3 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x1f] Mar 9 09:55:23 mybox0r /kernel: 4 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x59] Mar 9 09:55:23 mybox0r /kernel: 5 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x4d] Mar 9 09:55:23 mybox0r /kernel: 6 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] Mar 9 09:55:23 mybox0r /kernel: 7 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] Mar 9 09:55:23 mybox0r /kernel: 8 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x45] Mar 9 09:55:23 mybox0r /kernel: 9 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x26] Mar 9 09:55:23 mybox0r /kernel: 10 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] Mar 9 09:55:23 mybox0r /kernel: 11 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x27] Mar 9 09:55:23 mybox0r /kernel: 12 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x27] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] Mar 9 09:55:23 mybox0r /kernel: 13 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xe] Mar 9 09:55:23 mybox0r /kernel: 14 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x14] Mar 9 09:55:23 mybox0r /kernel: 15 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x7] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] Mar 9 09:55:23 mybox0r /kernel: 16 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] Mar 9 09:55:23 mybox0r /kernel: 17 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x23] Mar 9 09:55:23 mybox0r /kernel: 18 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xa] Mar 9 09:55:23 mybox0r /kernel: 19 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x7] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] Mar 9 09:55:23 mybox0r /kernel: 20 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] Mar 9 09:55:23 mybox0r /kernel: 21 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] Mar 9 09:55:23 mybox0r /kernel: 22 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x7] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] Mar 9 09:55:23 mybox0r /kernel: 23 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x4e] Mar 9 09:55:23 mybox0r /kernel: 24 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x39] Mar 9 09:55:23 mybox0r /kernel: 25 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xc] Mar 9 09:55:23 mybox0r /kernel: 26 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x7] Mar 9 09:55:23 mybox0r /kernel: 27 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x7] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] Mar 9 09:55:23 mybox0r /kernel: 28 SCB_CONTROL[0x64]:(DISCONNECTED|TAG_ENB|DISCENB) SCB_SCSIID[0x27] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0x5a] Mar 9 09:55:23 mybox0r /kernel: 29 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x27] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] Mar 9 09:55:23 mybox0r /kernel: 30 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] Mar 9 09:55:23 mybox0r /kernel: 31 SCB_CONTROL[0xe0]:(TAG_ENB|DISCENB|TARGET_SCB) SCB_SCSIID[0x17] Mar 9 09:55:23 mybox0r /kernel: SCB_LUN[0x0] SCB_TAG[0xff] Mar 9 09:55:23 mybox0r /kernel: Pending list: Mar 9 09:55:23 mybox0r /kernel: 12 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] Mar 9 09:55:23 mybox0r /kernel: 90 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] Mar 9 09:55:23 mybox0r /kernel: 82 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] Mar 9 09:55:23 mybox0r /kernel: 89 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] Mar 9 09:55:23 mybox0r /kernel: 57 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] Mar 9 09:55:23 mybox0r /kernel: 35 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] Mar 9 09:55:23 mybox0r /kernel: 10 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] Mar 9 09:55:23 mybox0r /kernel: 13 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] Mar 9 09:55:23 mybox0r /kernel: 77 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] Mar 9 09:55:23 mybox0r /kernel: 31 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] Mar 9 09:55:23 mybox0r /kernel: 39 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] Mar 9 09:55:23 mybox0r /kernel: 38 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] Mar 9 09:55:23 mybox0r /kernel: 69 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] Mar 9 09:55:23 mybox0r /kernel: 78 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] Mar 9 09:55:23 mybox0r /kernel: 7 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] Mar 9 09:55:23 mybox0r /kernel: 14 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] Mar 9 09:55:23 mybox0r /kernel: 20 SCB_CONTROL[0x60]:(TAG_ENB|DISCENB) SCB_SCSIID[0x27] SCB_LUN[0x0] Mar 9 09:55:23 mybox0r /kernel: Kernel Free SCB list: 62 36 4 8 43 6 29 19 1 32 15 74 30 83 50 37 47 73 54 88 105 79 42 86 5 99 68 94 28 26 11 80 84 95 18 25 81 55 109 91 58 92 44 85 61 64 21 87 75 66 60 65 52 49 24 9 45 40 53 96 22 23 98 27 104 34 56 17 106 2 59 71 107 67 93 72 63 97 3 108 33 51 70 76 46 41 0 48 103 102 101 100 Mar 9 09:55:23 mybox0r /kernel: Mar 9 09:55:23 mybox0r /kernel: <<<<<<<<<<<<<<<< Dump Card State Ends >>>>>>>>>>>>>>>>>> Mar 9 09:55:23 mybox0r /kernel: sg[0] - Addr 0xe421a000 : Length 4096 Mar 9 09:55:23 mybox0r /kernel: sg[1] - Addr 0x73dbb000 : Length 4096 Mar 9 09:55:23 mybox0r /kernel: sg[2] - Addr 0xb741c000 : Length 4096 Mar 9 09:55:23 mybox0r /kernel: sg[3] - Addr 0x28a9d000 : Length 4096 Mar 9 09:55:23 mybox0r /kernel: sg[4] - Addr 0x6837e000 : Length 4096 Mar 9 09:55:23 mybox0r /kernel: sg[5] - Addr 0x67d1f000 : Length 4096 Mar 9 09:55:23 mybox0r /kernel: sg[6] - Addr 0xeda20000 : Length 4096 Mar 9 09:55:23 mybox0r /kernel: sg[7] - Addr 0xae41000 : Length 4096 Mar 9 09:55:23 mybox0r /kernel: sg[8] - Addr 0xd7f42000 : Length 4096 Mar 9 09:55:23 mybox0r /kernel: sg[9] - Addr 0xdb2e3000 : Length 4096 Mar 9 09:55:23 mybox0r /kernel: sg[10] - Addr 0xa0ba4000 : Length 4096 Mar 9 09:55:23 mybox0r /kernel: sg[11] - Addr 0x74105000 : Length 4096 Mar 9 09:55:23 mybox0r /kernel: sg[12] - Addr 0x96e26000 : Length 4096 Mar 9 09:55:23 mybox0r /kernel: sg[13] - Addr 0x36b47000 : Length 4096 Mar 9 09:55:23 mybox0r /kernel: sg[14] - Addr 0xd348000 : Length 4096 Mar 9 09:55:23 mybox0r /kernel: sg[15] - Addr 0x8cd69000 : Length 4096 Mar 9 09:55:23 mybox0r /kernel: (da2:ahc0:0:2:0): Queuing a BDR SCB Mar 9 09:55:23 mybox0r /kernel: (da2:ahc0:0:2:0): Bus Device Reset Message Sent Mar 9 09:55:23 mybox0r /kernel: (da2:ahc0:0:2:0): no longer in timeout, status = 34b Mar 9 09:55:23 mybox0r /kernel: ahc0: Bus Device Reset on A:2. 17 SCBs aborted ----- End forwarded message -----