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