Return to BSD News archive
Path: euryale.cc.adfa.oz.au!platinum.sge.net!como.dpie.gov.au!news.gan.net.au!act.news.telstra.net!vic.news.telstra.net!news.mira.net.au!news.netspace.net.au!news.melbpc.org.au!news.mel.connect.com.au!munnari.OZ.AU!uunet!in1.uu.net!160.45.4.4!fu-berlin.de!nntprelay.mathworks.com!europa.clark.net!newsfeed.internetmci.com!news.cmc.net!sloth.swcp.com!not-for-mail
From: crs@quail.swcp.com (Charlie Sorsby)
Newsgroups: comp.unix.bsd.freebsd.misc
Subject: Possible CDROM Drive Problem???
Date: 30 Jun 1997 15:31:50 -0600
Organization: Just me, Los Alamos, NM
Lines: 322
Message-ID: <5p98k6$319@quail.swcp.com>
Reply-To: crs@swcp.com
NNTP-Posting-Host: lappp17.swcp.com
Xref: euryale.cc.adfa.oz.au comp.unix.bsd.freebsd.misc:43748
Before I begin, don't assume that I know diddly about what's going
on. If you can help, please try to explain in terms that one who
is not at all familiar with PCs can understand. I'm not stupid but
I am ignorant about some things.
And, due to included information, this is kind of long... :(
Yesterday, I was trying to read a text file from a CDROM with vi.
Sometimes I could; sometimes not. Once, vi dumped core.
I'm running FreeBSD 2.1.5 on a SCSI system. The SCSI host adapter
is an Adaptec 2940. The CDROM drive is a Sony (CDU76S-81 if the
_User's Guide_ is to be believed). The SCSI devices are the hard
drive, the CDROM drive, and an Archive Viper 150 QIC tape drive.
Everything had been working fine except for netscape (3.01 Gold)
occasionally hanging and/or dumping core.
Upon leaving XWindows for another reason, I found the following
messages on the console screen. Some of the messages were in bright
white and others the off-white/gray that normally shows up part way
through booting. I'll identify which were which:
Bright white:
cd0(ahc0:1:0): MEDIUM ERROR info:38e06 asc:2,0 No seek complete
vm_fault: pager input (probably hardware) error, PID 282 failure
Gray:
Jun 29 14:48:10 quail /kernel: pid 282 (vi), uid 1000: exited on signal 11
These messages were followed (again in bright white) with four more
occurrences of the first (cd0...) message. The first two were
identical with the first; the other two were identical except that
instead of "info:38e06" it was "info:38dac" -- all else was the
same.
Today, I tried to play an audio CD using workman. When first I ran
workman, its window came up with the data that I had entered about
that CD so I assume that it recognized it. But when I tried to play
it, the window changed and complained that there was no CD in the
drive. That was, perhaps, half an hour ago. Just now, I tried
again to remind me of the details of the "no CD" message and it
began to play just fine. Sigh...
After playing for a while, when it tried to go to another song (in
shuffle mode) it did the same thing--the window says "No artist,"
"No CD name," and where the buttons for selection numbers is
normally, "No CD in drive," and the drive makes a sound for,
perhaps, a second. After a while, it reverts to the original
workman window with the "artists names" and the "CD name" but it
doesn't resume playing.
All this caused me to look at dmesg. The following is the entire
output of dmesg:
sio2: disabled, not probed.
sio3: disabled, not probed.
lpt0 at 0x378-0x37f on isa
lpt1 at 0x278-0x27f on isa
psm0 at 0x60-0x63 irq 12 on motherboard
fdc0 at 0x3f0-0x3f7 irq 6 drq 2 on isa
fdc0: NEC 72065B
fd0: 1.44MB 3.5in
npx0 on motherboard
npx0: INT 16 interface
sb0 at 0x220 irq 5 drq 1 on isa
sb0: <SoundBlaster 16 4.13>
sbxvi0 at 0x0 drq 5 on isa
sbxvo0: <SoundBlaster 16 4.13>
sbmidi0 at 0x330 on isa
<SoundBlaster MPU-401>
opl0 at 0x388 on isa
opl0: <Yamaha OPL-3 FM>
WARNING: / was not properly dismounted.
cd0(ahc0:1:0): MEDIUM ERROR info:38e06 asc:2,0 No seek complete
vm_fault: pager input (probably hardware) error, PID 282 failure
pid 282 (vi), uid 1000: exited on signal 11
cd0(ahc0:1:0): MEDIUM ERROR info:38e06 asc:2,0 No seek complete
cd0(ahc0:1:0): MEDIUM ERROR info:38e06 asc:2,0 No seek complete
cd0(ahc0:1:0): MEDIUM ERROR info:38dac asc:2,0 No seek complete
cd0(ahc0:1:0): MEDIUM ERROR info:38dac asc:2,0 No seek complete
st0(ahc0:4:0): MEDIUM ERROR info:7a asc:0,3d
st0(ahc0:4:0): MEDIUM ERROR info:67 asc:0,3d
cd0(ahc0:1:0): timed out in command phase, SCSISIGI == 0x84
cd0(ahc0:1:0): asserted ATN - device reset in message buffer
cd0(ahc0:1:0): timed out in dataout phase, SCSISIGI == 0x0
ahc0: Issued Channel A Bus Reset #1. 2 SCBs aborted
ahc0:A:4: refuses syncronous negotiation. Using asyncronous transfers
st0(ahc0:4:0): UNIT ATTENTION
cd0(ahc0:1:0): UNIT ATTENTION asc:29,0
cd0(ahc0:1:0): Power on, reset, or bus device reset occurred
sd0(ahc0:0:0): UNIT ATTENTION asc:29,0
sd0(ahc0:0:0): Power on, reset, or bus device reset occurred
, retries:4
cd0(ahc0:1:0): timed out in command phase, SCSISIGI == 0x84
cd0(ahc0:1:0): asserted ATN - device reset in message buffer
cd0(ahc0:1:0): timed out in dataout phase, SCSISIGI == 0x0
ahc0: Issued Channel A Bus Reset #1. 2 SCBs aborted
cd0(ahc0:1:0): UNIT ATTENTION asc:29,0
cd0(ahc0:1:0): Power on, reset, or bus device reset occurred
ahc0:A:4: refuses syncronous negotiation. Using asyncronous transfers
sd0(ahc0:0:0): UNIT ATTENTION asc:29,0
sd0(ahc0:0:0): Power on, reset, or bus device reset occurred
, retries:4
cd0(ahc0:1:0): timed out in command phase, SCSISIGI == 0x84
cd0(ahc0:1:0): asserted ATN - device reset in message buffer
cd0(ahc0:1:0): timed out in dataout phase, SCSISIGI == 0x0
ahc0: Issued Channel A Bus Reset #1. 1 SCBs aborted
cd0(ahc0:1:0): UNIT ATTENTION asc:29,0
cd0(ahc0:1:0): Power on, reset, or bus device reset occurred
sd0(ahc0:0:0): UNIT ATTENTION asc:29,0
sd0(ahc0:0:0): Power on, reset, or bus device reset occurred
, retries:4
sd0(ahc0:0:0): NOT READY asc:4,1
sd0(ahc0:0:0): Logical unit is in process of becoming ready
, retries:3
sd0(ahc0:0:0): NOT READY asc:4,1
sd0(ahc0:0:0): Logical unit is in process of becoming ready
, retries:2
sd0(ahc0:0:0): NOT READY asc:4,1
sd0(ahc0:0:0): Logical unit is in process of becoming ready
, retries:1
sd0(ahc0:0:0): NOT READY asc:4,1
sd0(ahc0:0:0): Logical unit is in process of becoming ready
, FAILURE
sd0(ahc0:0:0): NOT READY asc:4,1
sd0(ahc0:0:0): Logical unit is in process of becoming ready
, retries:4
sd0(ahc0:0:0): NOT READY asc:4,1
sd0(ahc0:0:0): Logical unit is in process of becoming ready
, retries:3
sd0(ahc0:0:0): NOT READY asc:4,1
sd0(ahc0:0:0): Logical unit is in process of becoming ready
, retries:2
sd0(ahc0:0:0): NOT READY asc:4,1
sd0(ahc0:0:0): Logical unit is in process of becoming ready
, retries:1
sd0(ahc0:0:0): NOT READY asc:4,1
sd0(ahc0:0:0): Logical unit is in process of becoming ready
, FAILURE
sd0(ahc0:0:0): NOT READY asc:4,1
sd0(ahc0:0:0): Logical unit is in process of becoming ready
, retries:4
sd0(ahc0:0:0): NOT READY asc:4,1
sd0(ahc0:0:0): Logical unit is in process of becoming ready
, retries:3
sd0(ahc0:0:0): NOT READY asc:4,1
sd0(ahc0:0:0): Logical unit is in process of becoming ready
, retries:2
sd0(ahc0:0:0): NOT READY asc:4,1
sd0(ahc0:0:0): Logical unit is in process of becoming ready
, retries:1
sd0(ahc0:0:0): NOT READY asc:4,1
sd0(ahc0:0:0): Logical unit is in process of becoming ready
, FAILURE
cd0(ahc0:1:0): NOT READY asc:3a,0 Medium not present
Here are some more lines from dmesg that have been added while I've
been typing and listening to the CD when it will play (they don't
necessarily follow immediately after the above--I just grabbed a
screenful with the mouse):
sd0(ahc0:0:0): Power on, reset, or bus device reset occurred
, retries:4
cd0(ahc0:1:0): timed out in command phase, SCSISIGI == 0x84
cd0(ahc0:1:0): asserted ATN - device reset in message buffer
cd0(ahc0:1:0): timed out in dataout phase, SCSISIGI == 0x0
ahc0: Issued Channel A Bus Reset #1. 1 SCBs aborted
cd0(ahc0:1:0): UNIT ATTENTION asc:29,0
cd0(ahc0:1:0): Power on, reset, or bus device reset occurred
sd0(ahc0:0:0): UNIT ATTENTION asc:29,0
sd0(ahc0:0:0): Power on, reset, or bus device reset occurred
, retries:4
cd0(ahc0:1:0): timed out in command phase, SCSISIGI == 0x84
cd0(ahc0:1:0): asserted ATN - device reset in message buffer
cd0(ahc0:1:0): timed out in dataout phase, SCSISIGI == 0x0
ahc0: Issued Channel A Bus Reset #1. 1 SCBs aborted
cd0(ahc0:1:0): UNIT ATTENTION asc:29,0
cd0(ahc0:1:0): Power on, reset, or bus device reset occurred
sd0(ahc0:0:0): UNIT ATTENTION asc:29,0
sd0(ahc0:0:0): Power on, reset, or bus device reset occurred
, retries:4
cd0(ahc0:1:0): timed out in command phase, SCSISIGI == 0x84
cd0(ahc0:1:0): asserted ATN - device reset in message buffer
cd0(ahc0:1:0): timed out in dataout phase, SCSISIGI == 0x0
ahc0: Issued Channel A Bus Reset #1. 1 SCBs aborted
cd0(ahc0:1:0): UNIT ATTENTION asc:29,0
cd0(ahc0:1:0): Power on, reset, or bus device reset occurred
sd0(ahc0:0:0): UNIT ATTENTION asc:29,0
sd0(ahc0:0:0): Power on, reset, or bus device reset occurred
, retries:4
cd0(ahc0:1:0): timed out in command phase, SCSISIGI == 0x84
cd0(ahc0:1:0): asserted ATN - device reset in message buffer
cd0(ahc0:1:0): timed out in dataout phase, SCSISIGI == 0x0
ahc0: Issued Channel A Bus Reset #1. 1 SCBs aborted
cd0(ahc0:1:0): UNIT ATTENTION asc:29,0
cd0(ahc0:1:0): Power on, reset, or bus device reset occurred
sd0(ahc0:0:0): UNIT ATTENTION asc:29,0
sd0(ahc0:0:0): Power on, reset, or bus device reset occurred
, retries:4
cd0(ahc0:1:0): timed out in command phase, SCSISIGI == 0x84
cd0(ahc0:1:0): asserted ATN - device reset in message buffer
cd0(ahc0:1:0): timed out in dataout phase, SCSISIGI == 0x0
ahc0: Issued Channel A Bus Reset #1. 1 SCBs aborted
ahc0:A:1: Warning - unknown message recieved from target (0x1 - 0x1). Rejecting
cd0(ahc0:1:0): UNIT ATTENTION asc:29,0
cd0(ahc0:1:0): Power on, reset, or bus device reset occurred
sd0(ahc0:0:0): UNIT ATTENTION asc:29,0
sd0(ahc0:0:0): Power on, reset, or bus device reset occurred
, retries:4
And here is /var/log/messages. To shorten this a little, I'll
delete lines that only announce that I ran sudo or that I su'ed:
[...]
Jun 29 14:14:57 quail /kernel: cd0(ahc0:1:0): MEDIUM ERROR info:38e06 asc:2,0 No seek complete
Jun 29 14:14:57 quail /kernel: vm_fault: pager input (probably hardware) error, PID 11984 failure
Jun 29 14:14:58 quail /kernel: pid 11984 (vi), uid 1000: exited on signal 11
Jun 29 14:15:23 quail /kernel: cd0(ahc0:1:0): MEDIUM ERROR info:38e06 asc:2,0 No seek complete
Jun 29 14:15:23 quail /kernel: vm_fault: pager input (probably hardware) error, PID 11991 failure
Jun 29 14:15:23 quail /kernel: pid 11991 (vi), uid 1000: exited on signal 11
Jun 29 14:24:33 quail /kernel: cd0(ahc0:1:0): MEDIUM ERROR info:38e2a asc:2,0 No seek complete
Jun 29 14:27:31 quail /kernel: FreeBSD 2.1.5-RELEASE #0: Thu May 22 16:44:13 MDT 1997
Jun 29 14:27:31 quail /kernel: crs@quail.swcp.com:/usr/src/sys/compile/KERNEL.CRS
Jun 29 14:27:31 quail /kernel: CPU: 120-MHz Pentium 735\90 or 815\100 (Pentium-class CPU)
Jun 29 14:27:31 quail /kernel: Origin = "GenuineIntel" Id = 0x526 Stepping=6
Jun 29 14:27:31 quail /kernel: Features=0x1bf<FPU,VME,DE,PSE,TSC,MSR,MCE,CX8>
Jun 29 14:27:31 quail /kernel: real memory = 50331648 (49152K bytes)
Jun 29 14:27:31 quail /kernel: avail memory = 46788608 (45692K bytes)
Jun 29 14:27:31 quail /kernel: Probing for devices on PCI bus 0:
Jun 29 14:27:31 quail /kernel: chip0 <Intel 82439> rev 1 on pci0:0
Jun 29 14:27:31 quail /kernel: chip1 <Intel 82371 PCI-ISA bridge> rev 1 on pci0:7:0
Jun 29 14:27:31 quail /kernel: chip2 <Intel 82371 Bus-Master IDE controller> rev 0 on pci0:7:1
Jun 29 14:27:31 quail /kernel: vga0 <VGA-compatible display device> rev 0 int a irq ?? on pci0:10
Jun 29 14:27:32 quail /kernel: ahc0 <Adaptec 2940 SCSI host adapter> rev 0 int a irq 11 on pci0:12
Jun 29 14:27:32 quail /kernel: ahc0: aic7870 Single Channel, SCSI Id=7, 16 SCBs
Jun 29 14:27:32 quail /kernel: ahc0 waiting for scsi devices to settle
Jun 29 14:27:32 quail /kernel: (ahc0:0:0): "IBM DORS-32160 WA0A" type 0 fixed SCSI 2
Jun 29 14:27:32 quail /kernel: sd0(ahc0:0:0): Direct-Access 2063MB (4226725 512 byte sectors)
Jun 29 14:27:32 quail /kernel: (ahc0:1:0): "SONY CD-ROM CDU-76S 1.1c" type 5 removable SCSI 2
Jun 29 14:27:32 quail /kernel: cd0(ahc0:1:0): CD-ROM
Jun 29 14:27:33 quail /kernel: cd0(ahc0:1:0): UNIT ATTENTION asc:28,0
Jun 29 14:27:33 quail /kernel: cd0(ahc0:1:0): Not ready to ready transition, medium may have changed
Jun 29 14:27:33 quail /kernel: cd present.[400000 x 2048 byte records]
Jun 29 14:27:33 quail /kernel: ahc0:A:4: refuses syncronous negotiation. Using asyncronous transfers
Jun 29 14:27:33 quail /kernel: (ahc0:4:0): "ARCHIVE VIPER 150 20000 -000" type 1 removable SCSI 1
Jun 29 14:27:33 quail /kernel: st0(ahc0:4:0): Sequential-Access st0: Archive Viper 150 is a known rogue
Jun 29 14:27:33 quail /kernel: density code 0x0, 512-byte blocks, write-protected
Jun 29 14:27:33 quail /kernel: Probing for devices on the ISA bus:
Jun 29 14:27:33 quail /kernel: sc0 at 0x60-0x6f irq 1 on motherboard
Jun 29 14:27:33 quail /kernel: sc0: VGA color <16 virtual consoles, flags=0x0>
Jun 29 14:27:33 quail /kernel: sio0 at 0x3f8-0x3ff irq 4 on isa
Jun 29 14:27:33 quail /kernel: sio0: type 16550A
Jun 29 14:27:33 quail /kernel: sio1 at 0x2f8-0x2ff irq 3 on isa
Jun 29 14:27:34 quail /kernel: sio1: type 16550A
Jun 29 14:27:34 quail /kernel: sio2: disabled, not probed.
Jun 29 14:27:34 quail /kernel: sio3: disabled, not probed.
Jun 29 14:27:34 quail /kernel: lpt0 at 0x378-0x37f on isa
Jun 29 14:27:34 quail /kernel: lpt1 at 0x278-0x27f on isa
Jun 29 14:27:34 quail /kernel: psm0 at 0x60-0x63 irq 12 on motherboard
Jun 29 14:27:34 quail /kernel: fdc0 at 0x3f0-0x3f7 irq 6 drq 2 on isa
Jun 29 14:27:34 quail /kernel: fdc0: NEC 72065B
Jun 29 14:27:34 quail /kernel: fd0: 1.44MB 3.5in
Jun 29 14:27:35 quail /kernel: npx0 on motherboard
Jun 29 14:27:35 quail /kernel: npx0: INT 16 interface
Jun 29 14:27:35 quail /kernel: sb0 at 0x220 irq 5 drq 1 on isa
Jun 29 14:27:35 quail /kernel: sb0: <SoundBlaster 16 4.13>
Jun 29 14:27:35 quail /kernel: sbxvi0 at 0x0 drq 5 on isa
Jun 29 14:27:35 quail /kernel: sbxvo0: <SoundBlaster 16 4.13>
Jun 29 14:27:36 quail /kernel: sbmidi0 at 0x330 on isa
Jun 29 14:27:36 quail /kernel: <SoundBlaster MPU-401>
Jun 29 14:27:36 quail /kernel: opl0 at 0x388 on isa
Jun 29 14:27:36 quail /kernel: opl0: <Yamaha OPL-3 FM>
Jun 29 14:27:36 quail /kernel: WARNING: / was not properly dismounted.
Jun 29 14:27:32 quail lpd[97]: restarted
Jun 29 14:28:48 quail login: login on ttyv0 as crs
[...]
Jun 29 14:48:10 quail /kernel: cd0(ahc0:1:0): MEDIUM ERROR info:38e06 asc:2,0 No seek complete
Jun 29 14:48:10 quail /kernel: vm_fault: pager input (probably hardware) error, PID 282 failure
Jun 29 14:48:10 quail /kernel: pid 282 (vi), uid 1000: exited on signal 11
Jun 29 14:49:05 quail /kernel: cd0(ahc0:1:0): MEDIUM ERROR info:38e06 asc:2,0 No seek complete
Jun 29 14:49:09 quail /kernel: cd0(ahc0:1:0): MEDIUM ERROR info:38e06 asc:2,0 No seek complete
Jun 29 14:49:42 quail /kernel: cd0(ahc0:1:0): MEDIUM ERROR info:38dac asc:2,0 No seek complete
Jun 29 14:49:47 quail /kernel: cd0(ahc0:1:0): MEDIUM ERROR info:38dac asc:2,0 No seek complete
[...]
Jun 30 12:55:31 quail login: login on ttyv0 as crs
Jun 30 14:03:19 quail /kernel: st0(ahc0:4:0): MEDIUM ERROR info:7a asc:0,3d
Jun 30 14:03:19 quail /kernel: st0(ahc0:4:0): MEDIUM ERROR info:67 asc:0,3d
[...]
Jun 30 14:30:15 quail /kernel: cd0(ahc0:1:0): timed out in command phase, SCSISIGI == 0x84
Jun 30 14:30:15 quail /kernel: cd0(ahc0:1:0): asserted ATN - device reset in message buffer
Jun 30 14:30:17 quail /kernel: cd0(ahc0:1:0): timed out in dataout phase, SCSISIGI == 0x0
Jun 30 14:30:17 quail /kernel: ahc0: Issued Channel A Bus Reset #1. 2 SCBs aborted
Jun 30 14:30:17 quail /kernel: ahc0:A:4: refuses syncronous negotiation. Using asyncronous transfers
Jun 30 14:30:17 quail /kernel: st0(ahc0:4:0): UNIT ATTENTION
Jun 30 14:30:17 quail /kernel: cd0(ahc0:1:0): UNIT ATTENTION asc:29,0
Jun 30 14:30:17 quail /kernel: cd0(ahc0:1:0): Power on, reset, or bus device reset occurred
Jun 30 14:30:17 quail /kernel: sd0(ahc0:0:0): UNIT ATTENTION asc:29,0
Jun 30 14:30:17 quail /kernel: sd0(ahc0:0:0): Power on, reset, or bus device reset occurred
Jun 30 14:30:18 quail /kernel: , retries:4
Jun 30 14:30:39 quail /kernel: cd0(ahc0:1:0): timed out in command phase, SCSISIGI == 0x84
Jun 30 14:30:39 quail /kernel: cd0(ahc0:1:0): asserted ATN - device reset in message buffer
Jun 30 14:30:41 quail /kernel: cd0(ahc0:1:0): timed out in dataout phase, SCSISIGI == 0x0
Jun 30 14:30:41 quail /kernel: ahc0: Issued Channel A Bus Reset #1. 2 SCBs aborted
Jun 30 14:30:41 quail /kernel: cd0(ahc0:1:0): UNIT ATTENTION asc:29,0
Jun 30 14:30:41 quail /kernel: cd0(ahc0:1:0): Power on, reset, or bus device reset occurred
Jun 30 14:30:41 quail /kernel: ahc0:A:4: refuses syncronous negotiation. Using asyncronous transfers
Jun 30 14:30:41 quail /kernel: sd0(ahc0:0:0): UNIT ATTENTION asc:29,0
Jun 30 14:30:41 quail /kernel: sd0(ahc0:0:0): Power on, reset, or bus device reset occurred
Jun 30 14:30:41 quail /kernel: , retries:4
Jun 30 14:32:41 quail /kernel: cd0(ahc0:1:0): timed out in command phase, SCSISIGI == 0x84
Jun 30 14:32:41 quail /kernel: cd0(ahc0:1:0): asserted ATN - device reset in message buffer
--
Best regards,
Charlie "Older than dirt" Sorsby Los Alamos, NM "I'm the NRA!"
crs@swcp.com www.swcp.com/~crs Life Member since 1965