Date: Mon, 18 Sep 2006 10:18:09 -0400 From: "Dan Langille" <dan@langille.org> To: scsi@freebsd.org Subject: scsi card dumps Message-ID: <450E7261.24139.1A72417C@dan.langille.org>
next in thread | raw e-mail | index | archive | help
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
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?450E7261.24139.1A72417C>