From owner-freebsd-scsi@FreeBSD.ORG Mon Sep 18 14:19:32 2006 Return-Path: X-Original-To: scsi@freebsd.org 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 3083616A4E0 for ; Mon, 18 Sep 2006 14:19:32 +0000 (UTC) (envelope-from dan@langille.org) Received: from m21.unixathome.org (m21.unixathome.org [205.150.199.217]) by mx1.FreeBSD.org (Postfix) with ESMTP id 1E5C943E19 for ; Mon, 18 Sep 2006 14:18:18 +0000 (GMT) (envelope-from dan@langille.org) Received: from localhost (localhost [205.150.199.217]) by m21.unixathome.org (Postfix) with ESMTP id 3D45EBFAF for ; Mon, 18 Sep 2006 10:18:21 -0400 (EDT) Received: from m21.unixathome.org ([205.150.199.217]) by localhost (m21.unixathome.org [205.150.199.217]) (amavisd-new, port 10024) with ESMTP id 22629-10 for ; Mon, 18 Sep 2006 10:18:15 -0400 (EDT) Received: from bast.unixathome.org (bast.unixathome.org [70.26.229.230]) by m21.unixathome.org (Postfix) with ESMTP id 85FB3BF9B for ; Mon, 18 Sep 2006 10:18:14 -0400 (EDT) Received: from [10.55.0.99] (wocker.unixathome.org [10.55.0.99]) by bast.unixathome.org (Postfix) with ESMTP id 41EC7B820 for ; Mon, 18 Sep 2006 10:18:09 -0400 (EDT) From: "Dan Langille" To: scsi@freebsd.org Date: Mon, 18 Sep 2006 10:18:09 -0400 MIME-Version: 1.0 Message-ID: <450E7261.24139.1A72417C@dan.langille.org> Priority: normal X-mailer: Pegasus Mail for Windows (4.31) Content-type: text/plain; charset=US-ASCII Content-transfer-encoding: 7BIT Content-description: Mail message body X-Virus-Scanned: by amavisd-new-20030616-p10 (Debian) at unixathome.org Cc: Subject: scsi card dumps 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, 18 Sep 2006 14:19:32 -0000 Hi folks, I'm working with a DLT drive and I'm wondering why two tapes out of the pack of ten new tapes causes a problem like this. The following messages appear in /var/log/messages after attemping a "mt -f /dev/sa0 rewind". Other tapes that work without problem. Any suggestions as to the cause of this problem? Should the following log be mangled, you can also view it at: http://www.freebsddiary.org/phorum/read.php?f=1&i=10217&t=10217 After the above dump to /var/log/messages occurs, the mt command is frozen. A "camcontrol rescan status" (which takes quite some time to run) unfreezes the mt command and that terminal returns to a command prompt. During this time, another card dump occurs. # first card dump, during mt rewind Sep 18 10:11:23 lux /kernel: >>>>>>>>>>>>>>>>>> Dump Card State Begins <<<<<<<<<<<<<<<<< Sep 18 10:11:23 lux /kernel: ahc0: Dumping Card State while idle, at SEQADDR 0x7 Sep 18 10:11:23 lux /kernel: Card was paused Sep 18 10:11:23 lux /kernel: ACCUM = 0xb6, SINDEX = 0x67, DINDEX = 0x27, ARG_2 = 0x0 Sep 18 10:11:23 lux /kernel: HCNT = 0x0 SCBPTR = 0x0 Sep 18 10:11:23 lux /kernel: SCSISIGI[0x0] ERROR[0x0] SCSIBUSL[0x0] LASTPHASE[0x1] Sep 18 10:11:23 lux /kernel: SCSISEQ[0x12] SBLKCTL[0x2] SCSIRATE[0x0] SEQCTL[0x10] Sep 18 10:11:23 lux /kernel: SEQ_FLAGS[0xc0] SSTAT0[0x5] SSTAT1[0xa] SSTAT2[0x0] Sep 18 10:11:23 lux /kernel: SSTAT3[0x0] SIMODE0[0x0] SIMODE1[0xa4] SXFRCTL0[0x80] Sep 18 10:11:23 lux /kernel: DFCNTRL[0x0] DFSTATUS[0x2d] Sep 18 10:11:23 lux /kernel: STACK: 0x0 0x16a 0x19a 0x3 Sep 18 10:11:23 lux /kernel: SCB count = 20 Sep 18 10:11:23 lux /kernel: Kernel NEXTQSCB = 14 Sep 18 10:11:23 lux /kernel: Card NEXTQSCB = 14 Sep 18 10:11:23 lux /kernel: QINFIFO entries: Sep 18 10:11:23 lux /kernel: Waiting Queue entries: Sep 18 10:11:23 lux /kernel: Disconnected Queue entries: 0:3 Sep 18 10:11:23 lux /kernel: QOUTFIFO entries: Sep 18 10:11:23 lux /kernel: Sequencer Free SCB List: 1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 Sep 18 10:11:24 lux /kernel: Sequencer SCB Info: Sep 18 10:11:24 lux /kernel: 0 SCB_CONTROL[0x44] SCB_SCSIID[0x67] SCB_LUN[0x0] SCB_TAG[0x3] Sep 18 10:11:24 lux /kernel: 1 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:24 lux /kernel: 2 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:24 lux /kernel: 3 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:24 lux /kernel: 4 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:24 lux /kernel: 5 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:24 lux /kernel: 6 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:24 lux /kernel: 7 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:24 lux /kernel: 8 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:24 lux /kernel: 9 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:24 lux /kernel: 10 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:24 lux /kernel: 11 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:24 lux /kernel: 12 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:24 lux /kernel: 13 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:24 lux /kernel: 14 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:24 lux /kernel: 15 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:24 lux /kernel: Pending list: Sep 18 10:11:24 lux /kernel: 3 SCB_CONTROL[0x40] SCB_SCSIID[0x67] SCB_LUN[0x0] Sep 18 10:11:24 lux /kernel: Kernel Free SCB list: 15 16 17 18 19 0 1 2 4 5 6 7 8 9 13 12 11 10 Sep 18 10:11:24 lux /kernel: Untagged Q(6): 3 Sep 18 10:11:24 lux /kernel: Sep 18 10:11:24 lux /kernel: <<<<<<<<<<<<<<<<< Dump Card State Ends >>>>>>>>>>>>>>>>>> Sep 18 10:11:24 lux /kernel: (sa0:ahc0:0:6:0): SCB 0x3 - timed out Sep 18 10:11:24 lux /kernel: sg[0] - Addr 0x5ee7000 : Length 4096 Sep 18 10:11:24 lux /kernel: sg[1] - Addr 0x5f48000 : Length 4096 Sep 18 10:11:24 lux /kernel: (sa0:ahc0:0:6:0): Queuing a BDR SCB Sep 18 10:11:24 lux /kernel: ahc0: Timedout SCBs already complete. Interrupts may not be functioning. Sep 18 10:11:24 lux /kernel: (sa0:ahc0:0:6:0): Bus Device Reset Message Sent Sep 18 10:11:24 lux /kernel: (sa0:ahc0:0:6:0): no longer in timeout, status = 34b Sep 18 10:11:24 lux /kernel: ahc0: Bus Device Reset on A:6. 1 SCBs aborted Sep 18 10:11:28 lux /kernel: ahc0: Recovery Initiated Sep 18 10:11:28 lux /kernel: >>>>>>>>>>>>>>>>>> Dump Card State Begins <<<<<<<<<<<<<<<<< Sep 18 10:11:28 lux /kernel: ahc0: Dumping Card State in Command phase, at SEQADDR 0x170 Sep 18 10:11:28 lux /kernel: Card was paused Sep 18 10:11:28 lux /kernel: ACCUM = 0x80, SINDEX = 0xac, DINDEX = 0xc0, ARG_2 = 0x3 Sep 18 10:11:28 lux /kernel: HCNT = 0x0 SCBPTR = 0x0 Sep 18 10:11:28 lux /kernel: SCSISIGI[0x84] ERROR[0x0] SCSIBUSL[0x8] LASTPHASE[0x80] Sep 18 10:11:28 lux /kernel: SCSISEQ[0x12] SBLKCTL[0x2] SCSIRATE[0x88] SEQCTL[0x10] Sep 18 10:11:28 lux /kernel: SEQ_FLAGS[0x0] SSTAT0[0x7] SSTAT1[0x2] SSTAT2[0x0] Sep 18 10:11:28 lux /kernel: SSTAT3[0x0] SIMODE0[0x0] SIMODE1[0xac] SXFRCTL0[0x88] Sep 18 10:11:28 lux /kernel: DFCNTRL[0x4] DFSTATUS[0x6d] Sep 18 10:11:28 lux /kernel: STACK: 0x37 0xe5 0x0 0x19a Sep 18 10:11:28 lux /kernel: SCB count = 20 Sep 18 10:11:28 lux /kernel: Kernel NEXTQSCB = 3 Sep 18 10:11:28 lux /kernel: Card NEXTQSCB = 3 Sep 18 10:11:28 lux /kernel: QINFIFO entries: Sep 18 10:11:28 lux /kernel: Waiting Queue entries: Sep 18 10:11:28 lux /kernel: Disconnected Queue entries: Sep 18 10:11:28 lux /kernel: QOUTFIFO entries: Sep 18 10:11:28 lux /kernel: Sequencer Free SCB List: 1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 Sep 18 10:11:28 lux /kernel: Sequencer SCB Info: Sep 18 10:11:28 lux /kernel: 0 SCB_CONTROL[0x0] SCB_SCSIID[0x67] SCB_LUN[0x0] SCB_TAG[0xe] Sep 18 10:11:28 lux /kernel: 1 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:28 lux /kernel: 2 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:28 lux /kernel: 3 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:28 lux /kernel: 4 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:28 lux /kernel: 5 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:28 lux /kernel: 6 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:28 lux /kernel: 7 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:28 lux /kernel: 8 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:28 lux /kernel: 9 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:28 lux /kernel: 10 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:29 lux /kernel: 11 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:29 lux /kernel: 12 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:29 lux /kernel: 13 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:29 lux /kernel: 14 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:29 lux /kernel: 15 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:11:29 lux /kernel: Pending list: Sep 18 10:11:29 lux /kernel: 14 SCB_CONTROL[0x0] SCB_SCSIID[0x67] SCB_LUN[0x0] Sep 18 10:11:29 lux /kernel: Kernel Free SCB list: 15 16 17 18 19 0 1 2 4 5 6 7 8 9 13 12 11 10 Sep 18 10:11:29 lux /kernel: Untagged Q(6): 14 Sep 18 10:11:29 lux /kernel: Sep 18 10:11:29 lux /kernel: <<<<<<<<<<<<<<<<< Dump Card State Ends >>>>>>>>>>>>>>>>>> Sep 18 10:11:29 lux /kernel: (sa0:ahc0:0:6:0): SCB 0xe - timed out Sep 18 10:11:29 lux /kernel: sg[0] - Addr 0xa040 : Length 32 Sep 18 10:11:29 lux /kernel: (sa0:ahc0:0:6:0): BDR message in message buffer Sep 18 10:11:29 lux /kernel: (sa0:ahc0:0:6:0): (sa0:ahc0:0:6:0): no longer in timeout, status = 34c Sep 18 10:11:29 lux /kernel: Unexpected busfree in Command phase Sep 18 10:11:29 lux /kernel: SEQADDR == 0x170 Sep 18 10:11:29 lux /kernel: ahc0: Timedout SCBs already complete. Interrupts may not be functioning. # second card dump, during camcontrol rescan status Sep 18 10:15:54 lux /kernel: ahc0: Recovery Initiated Sep 18 10:15:55 lux /kernel: >>>>>>>>>>>>>>>>>> Dump Card State Begins <<<<<<<<<<<<<<<<< Sep 18 10:15:55 lux /kernel: ahc0: Dumping Card State while idle, at SEQADDR 0x18 Sep 18 10:15:55 lux /kernel: Card was paused Sep 18 10:15:55 lux /kernel: ACCUM = 0x3, SINDEX = 0x20, DINDEX = 0xc0, ARG_2 = 0x0 Sep 18 10:15:55 lux /kernel: HCNT = 0x0 SCBPTR = 0x0 Sep 18 10:15:55 lux /kernel: SCSISIGI[0xc] ERROR[0x0] SCSIBUSL[0x0] LASTPHASE[0x1] Sep 18 10:15:55 lux /kernel: SCSISEQ[0x1a] SBLKCTL[0x2] SCSIRATE[0x0] SEQCTL[0x10] Sep 18 10:15:55 lux /kernel: SEQ_FLAGS[0xc0] SSTAT0[0x5] SSTAT1[0x2] SSTAT2[0x0] Sep 18 10:15:55 lux /kernel: SSTAT3[0x0] SIMODE0[0x0] SIMODE1[0xa4] SXFRCTL0[0x80] Sep 18 10:15:55 lux /kernel: DFCNTRL[0x4] DFSTATUS[0x6d] Sep 18 10:15:55 lux /kernel: STACK: 0x37 0xe5 0x19a 0x17 Sep 18 10:15:55 lux /kernel: SCB count = 20 Sep 18 10:15:55 lux /kernel: Kernel NEXTQSCB = 9 Sep 18 10:15:55 lux /kernel: Card NEXTQSCB = 14 Sep 18 10:15:55 lux /kernel: QINFIFO entries: 14 15 16 17 18 19 0 1 2 4 5 6 7 8 Sep 18 10:15:55 lux /kernel: Waiting Queue entries: 0:3 Sep 18 10:15:55 lux /kernel: Disconnected Queue entries: Sep 18 10:15:55 lux /kernel: QOUTFIFO entries: Sep 18 10:15:55 lux /kernel: Sequencer Free SCB List: 1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 Sep 18 10:15:55 lux /kernel: Sequencer SCB Info: Sep 18 10:15:55 lux /kernel: 0 SCB_CONTROL[0x50] SCB_SCSIID[0x67] SCB_LUN[0x0] SCB_TAG[0x3] Sep 18 10:15:55 lux /kernel: 1 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:55 lux /kernel: 2 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:55 lux /kernel: 3 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:55 lux /kernel: 4 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:55 lux /kernel: 5 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:55 lux /kernel: 6 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:55 lux /kernel: 7 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:55 lux /kernel: 8 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:55 lux /kernel: 9 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:55 lux /kernel: 10 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:55 lux /kernel: 11 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:55 lux /kernel: 12 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:55 lux /kernel: 13 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:55 lux /kernel: 14 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:55 lux /kernel: 15 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:55 lux /kernel: Pending list: Sep 18 10:15:55 lux /kernel: 8 SCB_CONTROL[0x0] SCB_SCSIID[0xf7] SCB_LUN[0x0] Sep 18 10:15:55 lux /kernel: 7 SCB_CONTROL[0x0] SCB_SCSIID[0xe7] SCB_LUN[0x0] Sep 18 10:15:55 lux /kernel: 6 SCB_CONTROL[0x0] SCB_SCSIID[0xd7] SCB_LUN[0x0] Sep 18 10:15:55 lux /kernel: 5 SCB_CONTROL[0x0] SCB_SCSIID[0xc7] SCB_LUN[0x0] Sep 18 10:15:55 lux /kernel: 4 SCB_CONTROL[0x0] SCB_SCSIID[0xb7] SCB_LUN[0x0] Sep 18 10:15:55 lux /kernel: 2 SCB_CONTROL[0x0] SCB_SCSIID[0xa7] SCB_LUN[0x0] Sep 18 10:15:55 lux /kernel: 1 SCB_CONTROL[0x0] SCB_SCSIID[0x97] SCB_LUN[0x0] Sep 18 10:15:55 lux /kernel: 0 SCB_CONTROL[0x0] SCB_SCSIID[0x87] SCB_LUN[0x0] Sep 18 10:15:55 lux /kernel: 19 SCB_CONTROL[0x0] SCB_SCSIID[0x57] SCB_LUN[0x0] Sep 18 10:15:55 lux /kernel: 18 SCB_CONTROL[0x0] SCB_SCSIID[0x47] SCB_LUN[0x0] Sep 18 10:15:55 lux /kernel: 17 SCB_CONTROL[0x0] SCB_SCSIID[0x37] SCB_LUN[0x0] Sep 18 10:15:55 lux /kernel: 16 SCB_CONTROL[0x0] SCB_SCSIID[0x27] SCB_LUN[0x0] Sep 18 10:15:55 lux /kernel: 15 SCB_CONTROL[0x0] SCB_SCSIID[0x17] SCB_LUN[0x0] Sep 18 10:15:55 lux /kernel: 14 SCB_CONTROL[0x0] SCB_SCSIID[0x7] SCB_LUN[0x0] Sep 18 10:15:55 lux /kernel: 3 SCB_CONTROL[0x50] SCB_SCSIID[0x67] SCB_LUN[0x0] Sep 18 10:15:55 lux /kernel: Kernel Free SCB list: 13 12 11 10 Sep 18 10:15:55 lux /kernel: Untagged Q(0): 14 Sep 18 10:15:55 lux /kernel: Untagged Q(1): 15 Sep 18 10:15:55 lux /kernel: Untagged Q(2): 16 Sep 18 10:15:55 lux /kernel: Untagged Q(3): 17 Sep 18 10:15:55 lux /kernel: Untagged Q(4): 18 Sep 18 10:15:55 lux /kernel: Untagged Q(5): 19 Sep 18 10:15:55 lux /kernel: Untagged Q(6): 3 Sep 18 10:15:55 lux /kernel: Untagged Q(8): 0 Sep 18 10:15:55 lux /kernel: Untagged Q(9): 1 Sep 18 10:15:55 lux /kernel: Untagged Q(10): 2 Sep 18 10:15:55 lux /kernel: Untagged Q(11): 4 Sep 18 10:15:55 lux /kernel: Untagged Q(12): 5 Sep 18 10:15:55 lux /kernel: Untagged Q(13): 6 Sep 18 10:15:55 lux /kernel: Untagged Q(14): 7 Sep 18 10:15:55 lux /kernel: Untagged Q(15): 8 Sep 18 10:15:55 lux /kernel: Sep 18 10:15:55 lux /kernel: <<<<<<<<<<<<<<<<< Dump Card State Ends >>>>>>>>>>>>>>>>>> Sep 18 10:15:56 lux /kernel: (probe14:ahc0:0:15:0): SCB 0x8 - timed out Sep 18 10:15:56 lux /kernel: sg[0] - Addr 0x412ee84 : Length 36 Sep 18 10:15:56 lux /kernel: (probe14:ahc0:0:15:0): SCB 8: Immediate reset. Flags = 0x620 Sep 18 10:15:56 lux /kernel: (probe14:ahc0:0:15:0): no longer in timeout, status = 35b Sep 18 10:15:56 lux /kernel: ahc0: Issued Channel A Bus Reset. 15 SCBs aborted Sep 18 10:15:56 lux /kernel: Infinite interrupt loop, INTSTAT = 60ahc0: Timedout SCBs already complete. Interrupts may not be functioning. Sep 18 10:15:56 lux /kernel: ahc0: Recovery Initiated Sep 18 10:15:56 lux /kernel: >>>>>>>>>>>>>>>>>> Dump Card State Begins <<<<<<<<<<<<<<<<< Sep 18 10:15:56 lux /kernel: ahc0: Dumping Card State while idle, at SEQADDR 0x49 Sep 18 10:15:56 lux /kernel: Card was paused Sep 18 10:15:56 lux /kernel: ACCUM = 0x9, SINDEX = 0x20, DINDEX = 0xc0, ARG_2 = 0x2 Sep 18 10:15:56 lux /kernel: HCNT = 0x0 SCBPTR = 0x0 Sep 18 10:15:56 lux /kernel: SCSISIGI[0x0] ERROR[0x0] SCSIBUSL[0x0] LASTPHASE[0x1] Sep 18 10:15:56 lux /kernel: SCSISEQ[0x12] SBLKCTL[0x2] SCSIRATE[0x0] SEQCTL[0x10] Sep 18 10:15:56 lux /kernel: SEQ_FLAGS[0xc0] SSTAT0[0x5] SSTAT1[0x0] SSTAT2[0x0] Sep 18 10:15:56 lux /kernel: SSTAT3[0x0] SIMODE0[0x0] SIMODE1[0xa4] SXFRCTL0[0x80] Sep 18 10:15:56 lux /kernel: DFCNTRL[0x0] DFSTATUS[0x2d] Sep 18 10:15:56 lux /kernel: STACK: 0x3 0x37 0xe5 0x19a Sep 18 10:15:56 lux /kernel: SCB count = 20 Sep 18 10:15:56 lux /kernel: Kernel NEXTQSCB = 14 Sep 18 10:15:56 lux /kernel: Card NEXTQSCB = 3 Sep 18 10:15:56 lux /kernel: QINFIFO entries: 3 8 7 6 5 4 2 1 0 19 18 17 16 15 Sep 18 10:15:56 lux /kernel: Waiting Queue entries: Sep 18 10:15:56 lux /kernel: Disconnected Queue entries: Sep 18 10:15:56 lux /kernel: QOUTFIFO entries: Sep 18 10:15:56 lux /kernel: Sequencer Free SCB List: 0 1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 Sep 18 10:15:56 lux /kernel: Sequencer SCB Info: Sep 18 10:15:56 lux /kernel: 0 SCB_CONTROL[0x0] SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 1 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 2 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 3 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 4 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 5 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 6 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 7 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 8 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 9 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 10 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 11 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 12 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 13 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 14 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 15 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: Pending list: Sep 18 10:15:56 lux /kernel: 15 SCB_CONTROL[0x50] SCB_SCSIID[0x67] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 16 SCB_CONTROL[0x0] SCB_SCSIID[0xf7] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 17 SCB_CONTROL[0x0] SCB_SCSIID[0xe7] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 18 SCB_CONTROL[0x0] SCB_SCSIID[0xd7] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 19 SCB_CONTROL[0x0] SCB_SCSIID[0xc7] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 0 SCB_CONTROL[0x0] SCB_SCSIID[0xb7] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 1 SCB_CONTROL[0x0] SCB_SCSIID[0xa7] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 2 SCB_CONTROL[0x0] SCB_SCSIID[0x97] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 4 SCB_CONTROL[0x0] SCB_SCSIID[0x87] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 5 SCB_CONTROL[0x0] SCB_SCSIID[0x57] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 6 SCB_CONTROL[0x0] SCB_SCSIID[0x47] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 7 SCB_CONTROL[0x0] SCB_SCSIID[0x37] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 8 SCB_CONTROL[0x0] SCB_SCSIID[0x27] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 3 SCB_CONTROL[0x0] SCB_SCSIID[0x17] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: Kernel Free SCB list: 9 13 12 11 10 Sep 18 10:15:56 lux /kernel: Untagged Q(1): 3 Sep 18 10:15:56 lux /kernel: Untagged Q(2): 8 Sep 18 10:15:56 lux /kernel: Untagged Q(3): 7 Sep 18 10:15:56 lux /kernel: Untagged Q(4): 6 Sep 18 10:15:56 lux /kernel: Untagged Q(5): 5 Sep 18 10:15:56 lux /kernel: Untagged Q(6): 15 Sep 18 10:15:56 lux /kernel: Untagged Q(8): 4 Sep 18 10:15:56 lux /kernel: Untagged Q(9): 2 Sep 18 10:15:56 lux /kernel: Untagged Q(10): 1 Sep 18 10:15:56 lux /kernel: Untagged Q(11): 0 Sep 18 10:15:56 lux /kernel: Untagged Q(12): 19 Sep 18 10:15:56 lux /kernel: Untagged Q(13): 18 Sep 18 10:15:56 lux /kernel: Untagged Q(14): 17 Sep 18 10:15:56 lux /kernel: Untagged Q(15): 16 Sep 18 10:15:56 lux /kernel: Sep 18 10:15:56 lux /kernel: <<<<<<<<<<<<<<<<< Dump Card State Ends >>>>>>>>>>>>>>>>>> Sep 18 10:15:56 lux /kernel: (probe1:ahc0:0:1:0): SCB 0x3 - timed out Sep 18 10:15:56 lux /kernel: sg[0] - Addr 0x5be4884 : Length 36 Sep 18 10:15:56 lux /kernel: (probe1:ahc0:0:1:0): SCB 3: Immediate reset. Flags = 0x620 Sep 18 10:15:56 lux /kernel: (probe1:ahc0:0:1:0): no longer in timeout, status = 35b Sep 18 10:15:56 lux /kernel: ahc0: Issued Channel A Bus Reset. 14 SCBs aborted Sep 18 10:15:56 lux /kernel: Infinite interrupt loop, INTSTAT = 60ahc0: Timedout SCBs already complete. Interrupts may not be functioning. Sep 18 10:15:56 lux /kernel: ahc0: Recovery Initiated Sep 18 10:15:56 lux /kernel: >>>>>>>>>>>>>>>>>> Dump Card State Begins <<<<<<<<<<<<<<<<< Sep 18 10:15:56 lux /kernel: ahc0: Dumping Card State while idle, at SEQADDR 0x4a Sep 18 10:15:56 lux /kernel: Card was paused Sep 18 10:15:56 lux /kernel: ACCUM = 0xe, SINDEX = 0x20, DINDEX = 0xc0, ARG_2 = 0x3 Sep 18 10:15:56 lux /kernel: HCNT = 0x0 SCBPTR = 0x0 Sep 18 10:15:56 lux /kernel: SCSISIGI[0x0] ERROR[0x0] SCSIBUSL[0x0] LASTPHASE[0x1] Sep 18 10:15:56 lux /kernel: SCSISEQ[0x12] SBLKCTL[0x2] SCSIRATE[0x0] SEQCTL[0x10] Sep 18 10:15:56 lux /kernel: SEQ_FLAGS[0xc0] SSTAT0[0x5] SSTAT1[0x0] SSTAT2[0x0] Sep 18 10:15:56 lux /kernel: SSTAT3[0x0] SIMODE0[0x0] SIMODE1[0xa4] SXFRCTL0[0x80] Sep 18 10:15:56 lux /kernel: DFCNTRL[0x0] DFSTATUS[0x2d] Sep 18 10:15:56 lux /kernel: STACK: 0x3 0x3 0x37 0x19a Sep 18 10:15:56 lux /kernel: SCB count = 20 Sep 18 10:15:56 lux /kernel: Kernel NEXTQSCB = 3 Sep 18 10:15:56 lux /kernel: Card NEXTQSCB = 15 Sep 18 10:15:56 lux /kernel: QINFIFO entries: 15 16 17 18 19 0 1 2 4 5 6 7 8 Sep 18 10:15:56 lux /kernel: Waiting Queue entries: Sep 18 10:15:56 lux /kernel: Disconnected Queue entries: Sep 18 10:15:56 lux /kernel: QOUTFIFO entries: Sep 18 10:15:56 lux /kernel: Sequencer Free SCB List: 0 1 2 3 4 5 6 7 8 9 10 11 12 13 14 15 Sep 18 10:15:56 lux /kernel: Sequencer SCB Info: Sep 18 10:15:56 lux /kernel: 0 SCB_CONTROL[0x0] SCB_SCSIID[0x17] SCB_LUN[0x0] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 1 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 2 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 3 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 4 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 5 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 6 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 7 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 8 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 9 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 10 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 11 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 12 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 13 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 14 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: 15 SCB_CONTROL[0x0] SCB_SCSIID[0xff] SCB_LUN[0xff] SCB_TAG[0xff] Sep 18 10:15:56 lux /kernel: Pending list: Sep 18 10:15:56 lux /kernel: 8 SCB_CONTROL[0x50] SCB_SCSIID[0x67] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 7 SCB_CONTROL[0x0] SCB_SCSIID[0xf7] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 6 SCB_CONTROL[0x0] SCB_SCSIID[0xe7] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 5 SCB_CONTROL[0x0] SCB_SCSIID[0xd7] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 4 SCB_CONTROL[0x0] SCB_SCSIID[0xc7] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 2 SCB_CONTROL[0x0] SCB_SCSIID[0xb7] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 1 SCB_CONTROL[0x0] SCB_SCSIID[0xa7] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 0 SCB_CONTROL[0x0] SCB_SCSIID[0x97] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 19 SCB_CONTROL[0x0] SCB_SCSIID[0x87] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 18 SCB_CONTROL[0x0] SCB_SCSIID[0x57] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 17 SCB_CONTROL[0x0] SCB_SCSIID[0x47] SCB_LUN[0x0] Sep 18 10:15:56 lux /kernel: 16 SCB_CONTROL[0x0] SCB_SCSIID[0x37] SCB_LUN[0x0] Sep 18 10:15:57 lux /kernel: 15 SCB_CONTROL[0x0] SCB_SCSIID[0x27] SCB_LUN[0x0] Sep 18 10:15:57 lux /kernel: Kernel Free SCB list: 14 9 13 12 11 10 Sep 18 10:15:57 lux /kernel: Untagged Q(2): 15 Sep 18 10:15:57 lux /kernel: Untagged Q(3): 16 Sep 18 10:15:57 lux /kernel: Untagged Q(4): 17 Sep 18 10:15:57 lux /kernel: Untagged Q(5): 18 Sep 18 10:15:57 lux /kernel: Untagged Q(6): 8 Sep 18 10:15:57 lux /kernel: Untagged Q(8): 19 Sep 18 10:15:57 lux /kernel: Untagged Q(9): 0 Sep 18 10:15:57 lux /kernel: Untagged Q(10): 1 Sep 18 10:15:57 lux /kernel: Untagged Q(11): 2 Sep 18 10:15:57 lux /kernel: Untagged Q(12): 4 Sep 18 10:15:57 lux /kernel: Untagged Q(13): 5 Sep 18 10:15:57 lux /kernel: Untagged Q(14): 6 Sep 18 10:15:57 lux /kernel: Untagged Q(15): 7 Sep 18 10:15:57 lux /kernel: Sep 18 10:15:57 lux /kernel: <<<<<<<<<<<<<<<<< Dump Card State Ends >>>>>>>>>>>>>>>>>> Sep 18 10:15:57 lux /kernel: (sa0:ahc0:0:6:0): SCB 0x8 - timed out Sep 18 10:15:57 lux /kernel: (sa0:ahc0:0:6:0): SCB 8: Immediate reset. Flags = 0x660 Sep 18 10:15:57 lux /kernel: (sa0:ahc0:0:6:0): no longer in timeout, status = 35b Sep 18 10:15:57 lux /kernel: ahc0: Issued Channel A Bus Reset. 13 SCBs aborted Sep 18 10:15:57 lux /kernel: Infinite interrupt loop, INTSTAT = 60ahc0: Timedout SCBs already complete. Interrupts may not be functioning. -- Dan Langille : Software Developer looking for work my resume: http://www.freebsddiary.org/dan_langille.php