[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

i386/3548: ahc scsi errors when copying from cd to hard disk



>Number:         3548
>Category:       i386
>Synopsis:       ahc scsi errors when copying from cd to hard disk
>Confidential:   yes
>Severity:       serious
>Priority:       low
>Responsible:    bugs
>State:          open
>Quarter:        
>Keywords:       
>Date-Required:
>Class:          sw-bug
>Submitter-Id:   net
>Arrival-Date:   Sun Nov 02 16:50:02 GMT 2003
>Closed-Date:
>Last-Modified:
>Originator:     Drew Einhorn
>Release:        3.4
>Organization:
net
>Environment:
	System      : OpenBSD 3.4
	Architecture: OpenBSD.i386
	Machine     : i386
>Description:
	ahc scsi errors when copying from cd to hard disk
>How-To-Repeat:
I'm having scsi problems on a elderly Dell box with the release 3.4.

It has a pci scsi host adapter: aha-2940u/uw dual scsi bios v1.33s2,
a single scsi hard drive: seagate st39204lw,
and a scsi cd-rom: teac cd-532s.

Booted the cd34.iso cd without a problem.  First sign of problems was errors 
while loading the install sets from cd.  I thought maybe I blew it creating 
the install sets cd.  So I did an ftp install instead.  That worked without
problems. 

Then I optimistically added a line to /etc/fstab:

  /dev/cd0a /mnt cd9660 ro,nodev,nosuid 1 2

The I did a: mount /mnt

Then I tried to copy a largefile from an old official obsd cd1 to
hard disk.  Lots of scsi errors.  Copied the dmesg and errors from
/var/log/messages to another system.  Will include them at the end
of this note.

Had been running 3.3 on this box.  Re installed 3.3. Saw similar problems,
guess I just never used the cd when i was running 3.3 on the box.

Put Redhat 9 on the box and was able to copy from cd to hard disk.
So it is not a hardware problem.  

Here'e the 3.4 release dmesg:

OpenBSD 3.4 (GENERIC) #18: Wed Sep 17 03:34:47 MDT 2003
    deraadt@i386.openbsd.org:/usr/src/sys/arch/i386/compile/GENERIC
cpu0: Intel Pentium II ("GenuineIntel" 686-class, 512KB L2 cache) 397 MHz
cpu0: FPU,V86,DE,PSE,TSC,MSR,PAE,MCE,CX8,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,MMX,FXSR
real mem  = 335126528 (327272K)
avail mem = 304189440 (297060K)
using 4116 buffers containing 16859136 bytes (16464K) of memory
mainbus0 (root)
bios0 at mainbus0: AT/286+(00) BIOS, date 07/12/00, BIOS32 rev. 0 @ 0xffe90
apm0 at bios0: Power Management spec V1.2
apm0: AC on, battery charge unknown
pcibios0 at bios0: rev. 2.1 @ 0xf0000/0x10000
pcibios0: PCI IRQ Routing Table rev. 1.0 @ 0xfc670/176 (9 entries)
pcibios0: PCI Interrupt Router at 000:07:0 ("Intel 82371AB PIIX4 ISA" rev 0x00)
pcibios0: PCI bus #2 is the last bus
bios0: ROM list: 0xc0000/0x8000 0xc8000/0x4800 0xd0000/0x8000
pci0 at mainbus0 bus 0: configuration mode 1 (no bios)
pchb0 at pci0 dev 0 function 0 "Intel 82443BX PCI-AGP" rev 0x02
ppb0 at pci0 dev 1 function 0 "Intel 82443BX AGP" rev 0x02
pci1 at ppb0 bus 1
vga1 at pci1 dev 0 function 0 "ATI Rage Pro" rev 0x5c
wsdisplay0 at vga1: console (80x25, vt100 emulation)
wsdisplay0: screen 1-5 added (80x25, vt100 emulation)
pcib0 at pci0 dev 7 function 0 "Intel 82371AB PIIX4 ISA" rev 0x02
pciide0 at pci0 dev 7 function 1 "Intel 82371AB IDE" rev 0x01: DMA, channel 0 wired to compatibility, channel 1 wired to compatibility
pciide0: channel 0 ignored (disabled)
pciide0: channel 1 ignored (disabled)
uhci0 at pci0 dev 7 function 2 "Intel 82371AB USB" rev 0x01: irq 14
usb0 at uhci0: USB revision 1.0
uhub0 at usb0
uhub0: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub0: 2 ports with 2 removable, self powered
"Intel 82371AB Power Mgmt" rev 0x02 at pci0 dev 7 function 3 not configured
ahc1 at pci0 dev 13 function 0 "Adaptec AIC-7895" rev 0x03: irq 11
ahc1: aic7895: Ultra Wide Channel A, SCSI Id=7, 32/255 SCBs
scsibus0 at ahc1: 16 targets
ahc1: target 0 synchronous at 20.0MHz, offset = 0x8
ahc1: target 0 using tagged queuing
sd0 at scsibus0 targ 0 lun 0: <SEAGATE, ST39204LW, 0002> SCSI3 0/direct fixed
sd0: 8750MB, 14384 cyl, 3 head, 415 sec, 512 bytes/sec, 17921835 sec total
ahc1: target 0 using asynchronous transfers
ahc1: target 0 synchronous at 20.0MHz, offset = 0x8
ahc1: target 0 using asynchronous transfers
ahc1: target 0 synchronous at 20.0MHz, offset = 0x8
ahc1: target 0 using asynchronous transfers
ahc1: target 0 synchronous at 20.0MHz, offset = 0x8
ahc1: target 0 using asynchronous transfers
ahc1: target 0 synchronous at 20.0MHz, offset = 0x8
ahc1: target 0 using asynchronous transfers
ahc1: target 0 synchronous at 20.0MHz, offset = 0x8
ahc1: target 0 using asynchronous transfers
ahc1: target 0 synchronous at 20.0MHz, offset = 0x8
ahc1: target 0 using asynchronous transfers
ahc1: target 0 synchronous at 20.0MHz, offset = 0x8
ahc1: target 3 synchronous at 20.0MHz, offset = 0xf
cd0 at scsibus0 targ 3 lun 0: <TEAC, CD-ROM CD-532S, 1.0A> SCSI2 5/cdrom removable
ahc2 at pci0 dev 13 function 1 "Adaptec AIC-7895" rev 0x03: irq 10
ahc2: aic7895: Ultra Single Channel B, SCSI Id=7, 32/255 SCBs
scsibus1 at ahc2: 8 targets
ppb1 at pci0 dev 15 function 0 "DEC 21152 PCI-PCI" rev 0x03
pci2 at ppb1 bus 2
xl0 at pci0 dev 17 function 0 "3Com 3c905B 100Base-TX" rev 0x24: irq 14 address 00:c0:4f:7b:20:75
exphy0 at xl0 phy 24: 3Com internal media interface
isa0 at pcib0
isadma0 at isa0
pckbc0 at isa0 port 0x60/5
pckbd0 at pckbc0 (kbd slot)
pckbc0: using irq 1 for kbd slot
wskbd0 at pckbd0: console keyboard, using wsdisplay0
pms0 at pckbc0 (aux slot)
pckbc0: using irq 12 for aux slot
wsmouse0 at pms0 mux 0
pcppi0 at isa0 port 0x61
midi0 at pcppi0: <PC speaker>
sysbeep0 at pcppi0
lpt0 at isa0 port 0x378/4 irq 7
npx0 at isa0 port 0xf0/16: using exception 16
fdc0 at isa0 port 0x3f0/6 irq 6 drq 2
fd0 at fdc0 drive 0: 1.44MB 80 cyl, 2 head, 18 sec
biomask 4c40 netmask 4c40 ttymask 5cc2
pctr: 686-class user-level performance counters enabled
mtrr: Pentium Pro MTRR support
dkcsum: sd0 matched BIOS disk 80
root on sd0a
rootdev=0x400 rrootdev=0xd00 rawdev=0xd02
WARNING: / was not properly unmounted


And here's the start of the error messages from /var/log/messages:


Nov  1 16:35:55 hermes2 /bsd: ahc1:A:0: no active SCB for reconnecting target - issuing BUS DEVICE RESET
Nov  1 16:35:59 hermes2 /bsd: SAVED_SCSIID == 0x07, SAVED_LUN == 0x0, ARG_1 == 0x4 ACCUM = 0x0
Nov  1 16:35:59 hermes2 /bsd: SEQ_FLAGS == 0x10, SCBPTR == 0xb, BTT == 0x0, SINDEX == 0x31
Nov  1 16:35:59 hermes2 /bsd: SCSIID == 0x37, SCB_SCSIID == 0x37, SCB_LUN == 0x0, SCB_TAG == 0xff, SCB_CONTROL == 0x48
Nov  1 16:35:59 hermes2 /bsd: SCSIBUSL == 0x80, SCSISIGI == 0xe6
Nov  1 16:35:59 hermes2 /bsd: SXFRCTL0 == 0x88
Nov  1 16:35:59 hermes2 /bsd: SEQCTL == 0x10
Nov  1 16:35:59 hermes2 /bsd: ahc1: Dumping Card State in Message-in phase, at SEQADDR 0x1c3
Nov  1 16:35:59 hermes2 /bsd: ACCUM = 0x0, SINDEX = 0x31, DINDEX = 0xe4, ARG_2 = 0x1
Nov  1 16:35:59 hermes2 /bsd: HCNT = 0x0
Nov  1 16:35:59 hermes2 /bsd: SCSISEQ = 0x12, SBLKCTL = 0x2
Nov  1 16:35:59 hermes2 /bsd:  DFCNTRL = 0x0, DFSTATUS = 0x28
Nov  1 16:35:59 hermes2 /bsd: LASTPHASE = 0xe0, SCSISIGI = 0xe6, SXFRCTL0 = 0x88
Nov  1 16:35:59 hermes2 /bsd: SSTAT0 = 0x7, SSTAT1 = 0x3
Nov  1 16:35:59 hermes2 /bsd: STACK == 0x197, 0x181, 0x0, 0x15d
Nov  1 16:35:59 hermes2 /bsd: SCB count = 16
Nov  1 16:35:59 hermes2 /bsd: Kernel NEXTQSCB = 0
Nov  1 16:35:59 hermes2 /bsd: Card NEXTQSCB = 4
Nov  1 16:35:59 hermes2 /bsd: QINFIFO entries: 4 
Nov  1 16:35:59 hermes2 /bsd: Waiting Queue entries: 
Nov  1 16:35:59 hermes2 /bsd: Disconnected Queue entries: 13:13 
Nov  1 16:35:59 hermes2 /bsd: QOUTFIFO entries: 
Nov  1 16:35:59 hermes2 /bsd: Sequencer Free SCB List: 11 5 1 14 12 10 9 8 3 7 0 6 4 2 15 16 17 18 19 20 21 22 23 24 25 26 27 28 29 30 31 
Nov  1 16:35:59 hermes2 /bsd: Pending list: 4, 13
Nov  1 16:35:59 hermes2 /bsd: Kernel Free SCB list: 1 3 11 2 5 9 7 6 15 12 10 8 14 
Nov  1 16:35:59 hermes2 /bsd: Untagged Q(3): 4 
Nov  1 16:35:59 hermes2 /bsd: FEATURES = 0x16035, FLAGS = 0x485148, CHIP = 0x408 BUGS =0x35
Nov  1 16:35:59 hermes2 /bsd: ahc1: target 0 using asynchronous transfers
Nov  1 16:35:59 hermes2 /bsd: ahc1: Bus Device Reset on A:0. 1 SCBs aborted
Nov  1 16:35:59 hermes2 /bsd: ahc1: target 0 synchronous at 20.0MHz, offset = 0x8
Nov  1 16:35:59 hermes2 /bsd: ahc1: target 0 using asynchronous transfers
Nov  1 16:35:59 hermes2 /bsd: ahc1: target 0 synchronous at 20.0MHz, offset = 0x8
Nov  1 16:35:59 hermes2 /bsd: ahc1:A:0: no active SCB for reconnecting target - issuing BUS DEVICE RESET
Nov  1 16:35:59 hermes2 /bsd: SAVED_SCSIID == 0x07, SAVED_LUN == 0x0, ARG_1 == 0xb ACCUM = 0x0
Nov  1 16:35:59 hermes2 /bsd: SEQ_FLAGS == 0x10, SCBPTR == 0x1, BTT == 0xb, SINDEX == 0x31
Nov  1 16:35:59 hermes2 /bsd: SCSIID == 0x37, SCB_SCSIID == 0x37, SCB_LUN == 0x0, SCB_TAG == 0xff, SCB_CONTROL == 0x48
Nov  1 16:35:59 hermes2 /bsd: SCSIBUSL == 0x80, SCSISIGI == 0xe6
Nov  1 16:35:59 hermes2 /bsd: SXFRCTL0 == 0x88
Nov  1 16:35:59 hermes2 /bsd: SEQCTL == 0x10
Nov  1 16:35:59 hermes2 /bsd: ahc1: Dumping Card State in Message-in phase, at SEQADDR 0x1c3
Nov  1 16:35:59 hermes2 /bsd: ACCUM = 0x0, SINDEX = 0x31, DINDEX = 0xe4, ARG_2 = 0x2
Nov  1 16:35:59 hermes2 /bsd: HCNT = 0x0
Nov  1 16:35:59 hermes2 /bsd: SCSISEQ = 0x12, SBLKCTL = 0x2
Nov  1 16:35:59 hermes2 /bsd:  DFCNTRL = 0x0, DFSTATUS = 0x28
Nov  1 16:35:59 hermes2 /bsd: LASTPHASE = 0xe0, SCSISIGI = 0xe6, SXFRCTL0 = 0x88
Nov  1 16:35:59 hermes2 /bsd: SSTAT0 = 0x7, SSTAT1 = 0x3
Nov  1 16:35:59 hermes2 /bsd: STACK == 0x197, 0x181, 0xf8, 0x15d
Nov  1 16:35:59 hermes2 /bsd: SCB count = 16
Nov  1 16:35:59 hermes2 /bsd: Kernel NEXTQSCB = 11
Nov  1 16:35:59 hermes2 /bsd: Card NEXTQSCB = 11
Nov  1 16:35:59 hermes2 /bsd: QINFIFO entries: 
Nov  1 16:35:59 hermes2 /bsd: Waiting Queue entries: 14:2 
Nov  1 16:35:59 hermes2 /bsd: Disconnected Queue entries: 13:0 
Nov  1 16:35:59 hermes2 /bsd: QOUTFIFO entries: 
Nov  1 16:35:59 hermes2 /bsd: Sequencer Free SCB List: 1 5 11 12 10 9 8 3 7 0 6 4 2 15 16 17 18 19 20 21 22 23 24 25 26 27 28 29 30 31 
Nov  1 16:35:59 hermes2 /bsd: Pending list: 2, 0
Nov  1 16:35:59 hermes2 /bsd: Kernel Free SCB list: 4 3 13 5 1 9 7 6 15 12 10 8 14 
Nov  1 16:35:59 hermes2 /bsd: Untagged Q(3): 2 
Nov  1 16:35:59 hermes2 /bsd: FEATURES = 0x16035, FLAGS = 0x485148, CHIP = 0x408 BUGS =0x35
Nov  1 16:35:59 hermes2 /bsd: ahc1: target 0 using asynchronous transfers
Nov  1 16:36:00 hermes2 /bsd: ahc1: Bus Device Reset on A:0. 1 SCBs aborted
Nov  1 16:36:00 hermes2 /bsd: ahc1: target 0 synchronous at 20.0MHz, offset = 0x8
Nov  1 16:36:00 hermes2 /bsd: ahc1:A:0: no active SCB for reconnecting target - issuing BUS DEVICE RESET
Nov  1 16:36:00 hermes2 /bsd: SAVED_SCSIID == 0x07, SAVED_LUN == 0x0, ARG_1 == 0x8 ACCUM = 0x0
Nov  1 16:36:00 hermes2 /bsd: SEQ_FLAGS == 0x10, SCBPTR == 0x11, BTT == 0x8, SINDEX == 0x31
Nov  1 16:36:00 hermes2 /bsd: SCSIID == 0x37, SCB_SCSIID == 0x37, SCB_LUN == 0x0, SCB_TAG == 0xff, SCB_CONTROL == 0x48
Nov  1 16:36:00 hermes2 /bsd: SCSIBUSL == 0x80, SCSISIGI == 0xe6
Nov  1 16:36:00 hermes2 /bsd: SXFRCTL0 == 0x88
Nov  1 16:36:00 hermes2 /bsd: SEQCTL == 0x10
Nov  1 16:36:00 hermes2 /bsd: ahc1: Dumping Card State in Message-in phase, at SEQADDR 0x1c3
Nov  1 16:36:00 hermes2 /bsd: ACCUM = 0x0, SINDEX = 0x31, DINDEX = 0xe4, ARG_2 = 0x2
Nov  1 16:36:00 hermes2 /bsd: HCNT = 0x0
Nov  1 16:36:00 hermes2 /bsd: SCSISEQ = 0x12, SBLKCTL = 0x2
Nov  1 16:36:00 hermes2 /bsd:  DFCNTRL = 0x0, DFSTATUS = 0x28
Nov  1 16:36:00 hermes2 /bsd: LASTPHASE = 0xe0, SCSISIGI = 0xe6, SXFRCTL0 = 0x88
Nov  1 16:36:00 hermes2 /bsd: SSTAT0 = 0x7, SSTAT1 = 0x3
Nov  1 16:36:00 hermes2 /bsd: STACK == 0x197, 0x181, 0xf8, 0x15d
Nov  1 16:36:00 hermes2 /bsd: SCB count = 32
Nov  1 16:36:00 hermes2 /bsd: Kernel NEXTQSCB = 29
Nov  1 16:36:00 hermes2 /bsd: Card NEXTQSCB = 29
Nov  1 16:36:00 hermes2 /bsd: QINFIFO entries: 
Nov  1 16:36:00 hermes2 /bsd: Waiting Queue entries: 16:30 
Nov  1 16:36:00 hermes2 /bsd: Disconnected Queue entries: 15:31 2:10 4:14 6:12 0:15 7:6 3:1 8:7 9:13 10:9 12:5 11:4 5:11 1:3 13:2 14:0 
Nov  1 16:36:00 hermes2 /bsd: QOUTFIFO entries: 
Nov  1 16:36:00 hermes2 /bsd: Sequencer Free SCB List: 17 18 19 20 21 22 23 24 25 26 27 28 29 30 31 
Nov  1 16:36:00 hermes2 /bsd: Pending list: 8, 30, 31, 10, 14, 12, 15, 6, 1, 7, 13, 9, 5, 4, 11, 3, 2, 0
Nov  1 16:36:00 hermes2 /bsd: Kernel Free SCB list: 28 27 26 25 24 23 22 21 20 19 18 17 16 
Nov  1 16:36:00 hermes2 /bsd: Untagged Q(3): 30 8 
Nov  1 16:36:00 hermes2 /bsd: FEATURES = 0x16035, FLAGS = 0x485148, CHIP = 0x408 BUGS =0x35
Nov  1 16:36:00 hermes2 /bsd: ahc1: target 0 using asynchronous transfers
Nov  1 16:36:00 hermes2 /bsd: ahc1: Bus Device Reset on A:0. 16 SCBs aborted
Nov  1 16:36:00 hermes2 /bsd: ahc1: target 0 synchronous at 20.0MHz, offset = 0x8
>Fix:


>Release-Note:
>Audit-Trail:
>Unformatted: