scsi card dumps
Dan Langille
dan at langille.org
Mon Sep 18 07:19:32 PDT 2006
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
More information about the freebsd-scsi
mailing list