Subject: ahc -> Problem on 2.0/i386
To: None <netbsd-users@netbsd.org>
From: Florian Stoehr <netbsd@wolfnode.de>
List: netbsd-users
Date: 12/14/2004 11:07:24
Hi,
encountered the following problem yesterday. Never seen this before
and I wasn't able to reproduce it.
Occured during large untar operation:
Dec 13 19:40:48 server /netbsd: ahc1:A:0: Missed busfree. Lastphase =
0xe0, Curphase = 0x0
Dec 13 19:40:49 server /netbsd: ahc1:Unexpected busfree in Message-out
phase
Dec 13 19:40:49 server /netbsd: SEQADDR == 0x173
Dec 13 19:40:49 server /netbsd: sd0(ahc1:0:0:0): Check Condition on CDB:
0x2a 00 00 8c 1e 70 00 00 80 00
Dec 13 19:40:49 server /netbsd: SENSE KEY: Aborted Command
Dec 13 19:40:49 server /netbsd: ASC/ASCQ: Overlapped Commands
Attempted
Dec 13 19:40:49 server /netbsd:
Dec 13 19:41:48 server /netbsd: ahc1:SCB 0x0 - timed out
Dec 13 19:41:49 server /netbsd: >>>>>>>>>>>>>>>>>> Dump Card State Begins
<<<<<<<<<<<<<<<<<
Dec 13 19:41:49 server /netbsd: ahc1: Dumping Card State while idle, at
SEQADDR 0x9
Dec 13 19:41:49 server /netbsd: Card was paused
Dec 13 19:41:49 server /netbsd: ACCUM = 0x0, SINDEX = 0x7, DINDEX = 0xe4,
ARG_2 = 0x0
Dec 13 19:41:49 server /netbsd: HCNT = 0x0 SCBPTR = 0x0
Dec 13 19:41:49 server /netbsd: SCSIPHASE[0x0] SCSISIGI[0x0] ERROR[0x0]
SCSIBUSL[0x0]
Dec 13 19:41:49 server /netbsd: LASTPHASE[0x1] SCSISEQ[0x12] SBLKCTL[0xa]
SCSIRATE[0x0]
Dec 13 19:41:49 server /netbsd: SEQCTL[0x10] SEQ_FLAGS[0xc0] SSTAT0[0x0]
SSTAT1[0x8]
Dec 13 19:41:49 server /netbsd: SSTAT2[0x0] SSTAT3[0x0] SIMODE0[0x8]
SIMODE1[0xa4]
Dec 13 19:41:49 server /netbsd: SXFRCTL0[0x80] DFCNTRL[0x0] DFSTATUS[0x89]
Dec 13 19:41:49 server /netbsd: STACK: 0xd0 0x16b 0x111 0x3
Dec 13 19:41:49 server /netbsd: SCB count = 32
Dec 13 19:41:49 server /netbsd: Kernel NEXTQSCB = 6
Dec 13 19:41:49 server /netbsd: Card NEXTQSCB = 6
Dec 13 19:41:49 server /netbsd: QINFIFO entries:
Dec 13 19:41:49 server /netbsd: Waiting Queue entries:
Dec 13 19:41:49 server /netbsd: Disconnected Queue entries: 3:0
Dec 13 19:41:49 server /netbsd: QOUTFIFO entries:
Dec 13 19:41:49 server /netbsd: Sequencer Free SCB List: 0 15 11 6 7 12 9
1 14 5 4 2 13 10 8 16 17 18 19 20 21 22 23 24 25 26 27 28 29 30 31
Dec 13 19:41:49 server /netbsd: Sequencer SCB Info:
Dec 13 19:41:49 server /netbsd: 0 SCB_CONTROL[0xe0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 1 SCB_CONTROL[0xe0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 2 SCB_CONTROL[0xe0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 3 SCB_CONTROL[0x64]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0x0]
Dec 13 19:41:49 server /netbsd: 4 SCB_CONTROL[0xe0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 5 SCB_CONTROL[0xe0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 6 SCB_CONTROL[0xe0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 7 SCB_CONTROL[0xe0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 8 SCB_CONTROL[0xe0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 9 SCB_CONTROL[0xe0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 10 SCB_CONTROL[0xe0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 11 SCB_CONTROL[0xe0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 12 SCB_CONTROL[0xe0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 13 SCB_CONTROL[0xe0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 14 SCB_CONTROL[0xe0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 15 SCB_CONTROL[0xe0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0x7] SCB_LUN[0x0] SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 16 SCB_CONTROL[0x0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0xff] SCB_LUN[0xff]
SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 17 SCB_CONTROL[0x0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0xff] SCB_LUN[0xff]
SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 18 SCB_CONTROL[0x0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0xff] SCB_LUN[0xff]
SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 19 SCB_CONTROL[0x0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0xff] SCB_LUN[0xff]
SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 20 SCB_CONTROL[0x0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0xff] SCB_LUN[0xff]
SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 21 SCB_CONTROL[0x0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0xff] SCB_LUN[0xff]
SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 22 SCB_CONTROL[0x0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0xff] SCB_LUN[0xff]
SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 23 SCB_CONTROL[0x0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0xff] SCB_LUN[0xff]
SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 24 SCB_CONTROL[0x0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0xff] SCB_LUN[0xff]
SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 25 SCB_CONTROL[0x0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0xff] SCB_LUN[0xff]
SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 26 SCB_CONTROL[0x0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0xff] SCB_LUN[0xff]
SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 27 SCB_CONTROL[0x0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0xff] SCB_LUN[0xff]
SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 28 SCB_CONTROL[0x0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0xff] SCB_LUN[0xff]
SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 29 SCB_CONTROL[0x0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0xff] SCB_LUN[0xff]
SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 30 SCB_CONTROL[0x0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0xff] SCB_LUN[0xff]
SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: 31 SCB_CONTROL[0x0]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0xff] SCB_LUN[0xff]
SCB_TAG[0xff]
Dec 13 19:41:49 server /netbsd: Pending list:
Dec 13 19:41:49 server /netbsd: 0 SCB_CONTROL[0x60]
Dec 13 19:41:49 server /netbsd: SCB_SCSIID[0x7] SCB_LUN[0x0]
Dec 13 19:41:49 server /netbsd: Kernel Free SCB list: 7 8 31 3 10 14 12 4
9 5 1 2 11 15 13 30 29 28 27 26 25 24 23 22 21 20 19 18 17 16
Dec 13 19:41:49 server /netbsd:
Dec 13 19:41:49 server /netbsd: <<<<<<<<<<<<<<<< Dump Card State Ends
>>>>>>>>>>>>>>>>>>
Dec 13 19:41:49 server /netbsd: sg[0] - Addr 0x4a41000 : Length 4096
Dec 13 19:41:49 server /netbsd: sg[1] - Addr 0x7a82000 : Length 4096
Dec 13 19:41:49 server /netbsd: sg[2] - Addr 0x3544000 : Length 4096
Dec 13 19:41:49 server /netbsd: sg[3] - Addr 0x7945000 : Length 4096
Dec 13 19:41:49 server /netbsd: sg[4] - Addr 0x4668000 : Length 4096
Dec 13 19:41:49 server /netbsd: sg[5] - Addr 0x772b000 : Length 4096
Dec 13 19:41:49 server /netbsd: sg[6] - Addr 0x48ec000 : Length 4096
Dec 13 19:41:49 server /netbsd: sg[7] - Addr 0x474d000 : Length 4096
Dec 13 19:41:49 server /netbsd: sg[8] - Addr 0x4d2e000 : Length 4096
Dec 13 19:41:49 server /netbsd: sg[9] - Addr 0x306f000 : Length 4096
Dec 13 19:41:49 server /netbsd: sg[10] - Addr 0x4910000 : Length 4096
Dec 13 19:41:49 server /netbsd: sg[11] - Addr 0x3e31000 : Length 4096
Dec 13 19:41:49 server /netbsd: sg[12] - Addr 0x4892000 : Length 4096
Dec 13 19:41:49 server /netbsd: sg[13] - Addr 0x45d3000 : Length 4096
Dec 13 19:41:49 server /netbsd: sg[14] - Addr 0x7a34000 : Length 4096
Dec 13 19:41:49 server /netbsd: sg[15] - Addr 0x7955000 : Length 4096
Dec 13 19:41:49 server /netbsd: ahc1:Queuing a BDR SCB
Dec 13 19:41:49 server /netbsd: ahc1:Bus Device Reset Message Sent
Dec 13 19:41:49 server /netbsd: sd0(ahc1:0:0:0): ahc1: no longer in
timeout, status = 0
Dec 13 19:41:49 server /netbsd: ahc1: Bus Device Reset on A:0. 1 SCBs
aborted
Perhaps it's a bug?
-Florian