Re: ata request_sense illegal request

From: Kevin Oberman <oberman_at_es.net>
Date: Mon, 01 Mar 2004 10:18:25 -0800
> From: "Mike Schreckengost" <mrpolitics_at_hotmail.com>
> Date: Sun, 29 Feb 2004 18:29:44 -0500
> Sender: owner-freebsd-current_at_freebsd.org
> 
> I am having *exactly* the same problem on my system. Without CDs in the 
> drive, the system just hangs. With CDs in the drive, it boots (with errors, 
> as shown below) but for some reason it suddenly locked up hard when starting 
> KDE ... don't know if this is a related problem or not.
> 
> 'boot -v' provides an endless string of:
> (probe0:ata1:0:0:0): Retrying Command
> 
> ... messages (without CDs in the drive). I hope this helps, if anyone needs 
> more information don't hesitate to ask. :-)
> 
> Thanks,
> Mike
> 
> Feb 29 18:16:00 ulb kernel: Copyright (c) 1992-2004 The FreeBSD Project.
> Feb 29 18:16:00 ulb kernel: Copyright (c) 1979, 1980, 1983, 1986, 1988, 
> 1989, 19
> 91, 1992, 1993, 1994
> Feb 29 18:16:00 ulb kernel: The Regents of the University of California. All 
> rig
> hts reserved.
> Feb 29 18:16:00 ulb kernel: FreeBSD 5.2-CURRENT #20: Sun Feb 29 17:31:10 EST 
> 200
> 4
> Feb 29 18:16:00 ulb kernel: root_at_ulb.home.net:/usr/obj/usr/src/sys/ULB
> Feb 29 18:16:00 ulb kernel: WARNING: WITNESS option enabled, expect reduced 
> perf
> ormance.
> Feb 29 18:16:00 ulb kernel: Preloaded elf kernel "/boot/kernel/kernel" at 
> 0xc09f
> b000.
> Feb 29 18:16:00 ulb kernel: Preloaded elf module 
> "/boot/kernel/snd_emu10k1.ko" a
> t 0xc09fb294.
> Feb 29 18:16:00 ulb kernel: Preloaded elf module "/boot/kernel/snd_pcm.ko" 
> at 0x
> c09fb344.
> Feb 29 18:16:00 ulb kernel: Preloaded elf module "/boot/modules/nvidia.ko" 
> at 0x
> c09fb3f0.
> Feb 29 18:16:00 ulb kernel: Preloaded elf module "/boot/kernel/linprocfs.ko" 
> at
> 0xc09fb49c.
> Feb 29 18:16:00 ulb kernel: Preloaded elf module "/boot/kernel/acpi.ko" at 
> 0xc09
> fb54c.
> Feb 29 18:16:00 ulb kernel: ACPI APIC Table: <PTLTD      APIC  >
> Feb 29 18:16:00 ulb kernel: Timecounter "i8254" frequency 1193182 Hz quality 
> 0
> Feb 29 18:16:00 ulb kernel: CPU: AMD Athlon(tm) MP 2100+ (1733.41-MHz 
> 686-class
> CPU)
> Feb 29 18:16:00 ulb kernel: Origin = "AuthenticAMD"  Id = 0x662  Stepping = 
> 2
> Feb 29 18:16:00 ulb kernel: 
> Features=0x383fbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX
> 8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,MMX,FXSR,SSE>
> Feb 29 18:16:00 ulb kernel: AMD Features=0xc0480000<MP,AMIE,DSP,3DNow!>
> Feb 29 18:16:00 ulb kernel: real memory  = 1073217536 (1023 MB)
> Feb 29 18:16:00 ulb kernel: avail memory = 1040629760 (992 MB)
> Feb 29 18:16:00 ulb kernel: FreeBSD/SMP: Multiprocessor System Detected: 2 
> CPUs
> Feb 29 18:16:00 ulb kernel: cpu0 (BSP): APIC ID:  1
> Feb 29 18:16:00 ulb kernel: cpu1 (AP): APIC ID:  0
> Feb 29 18:16:00 ulb kernel: ioapic0 <Version 1.1> irqs 0-23 on motherboard
> Feb 29 18:16:00 ulb kernel: Pentium Pro MTRR support enabled
> Feb 29 18:16:00 ulb kernel: npx0: [FAST]
> Feb 29 18:16:00 ulb kernel: npx0: <math processor> on motherboard
> Feb 29 18:16:00 ulb kernel: npx0: INT 16 interface
> Feb 29 18:16:00 ulb kernel: acpi0: <PTLTD   RSDT> on motherboard
> Feb 29 18:16:00 ulb kernel: pcibios: BIOS version 2.10
> Feb 29 18:16:00 ulb kernel: Found $PIR table, 14 entries at 0xc00fdee0
> Feb 29 18:16:00 ulb kernel: acpi0: Power Button (fixed)
> Feb 29 18:16:00 ulb kernel: acpi0: Sleep Button (fixed)
> Feb 29 18:16:00 ulb kernel: Timecounter "ACPI-fast" frequency 3579545 Hz 
> quality
> 1000
> Feb 29 18:16:00 ulb kernel: acpi_timer0: <24-bit timer at 3.579545MHz> port 
> 0x80
> 08-0x800b on acpi0
> Feb 29 18:16:00 ulb kernel: acpi_cpu0: <CPU> on acpi0
> Feb 29 18:16:00 ulb kernel: acpi_cpu1: <CPU> on acpi0
> Feb 29 18:16:00 ulb kernel: acpi_button0: <Power Button> on acpi0
> Feb 29 18:16:00 ulb kernel: pcib0: <ACPI Host-PCI bridge> port 
> 0x8080-0x80ff,0x8
> 000-0x807f,0xcf8-0xcff iomem 0xd8000-0xdbfff on acpi0
> Feb 29 18:16:00 ulb kernel: pci0: <ACPI PCI bus> on pcib0
> Feb 29 18:16:00 ulb kernel: agp0: <AMD 762 host to AGP bridge> port 
> 0x1010-0x101
> 3 mem 0xeb200000-0xeb200fff,0xec000000-0xefffffff at device 0.0 on pci0
> Feb 29 18:16:00 ulb kernel: pcib1: <ACPI PCI-PCI bridge> at device 1.0 on 
> pci0
> Feb 29 18:16:00 ulb kernel: pci1: <ACPI PCI bus> on pcib1
> Feb 29 18:16:00 ulb kernel: nvidia0: <GeForce4 Ti 4600> mem 
> 0xf8000000-0xf807fff
> f,0xf0000000-0xf7ffffff,0xe8000000-0xe8ffffff irq 17 at device 5.0 on pci1
> Feb 29 18:16:00 ulb kernel: isab0: <PCI-ISA bridge> at device 7.0 on pci0
> Feb 29 18:16:00 ulb kernel: isa0: <ISA bus> on isab0
> Feb 29 18:16:00 ulb kernel: atapci0: <AMD 768 UDMA100 controller> port 
> 0xf000-0x
> f00f at device 7.1 on pci0
> Feb 29 18:16:00 ulb kernel: ata0: at 0x1f0 irq 14 on atapci0
> Feb 29 18:16:00 ulb kernel: ata0: [MPSAFE]
> Feb 29 18:16:00 ulb kernel: ata1: at 0x170 irq 15 on atapci0
> Feb 29 18:16:00 ulb kernel: ata1: [MPSAFE]
> Feb 29 18:16:00 ulb kernel: pci0: <bridge, PCI-unknown> at device 7.3 (no 
> driver
> attached)
> Feb 29 18:16:00 ulb kernel: pcib2: <ACPI PCI-PCI bridge> at device 16.0 on 
> pci0
> Feb 29 18:16:00 ulb kernel: pci2: <ACPI PCI bus> on pcib2
> Feb 29 18:16:00 ulb kernel: pcm0: <Creative EMU10K1> port 0x2800-0x281f irq 
> 16 a
> t device 4.0 on pci2
> Feb 29 18:16:00 ulb kernel: pcm0: <SigmaTel STAC9708/11 AC97 Codec>
> Feb 29 18:16:00 ulb kernel: ohci0: <NEC uPD 9210 USB controller> mem 
> 0xe9001000-
> 0xe9001fff irq 17 at device 5.0 on pci2
> Feb 29 18:16:00 ulb kernel: usb0: OHCI version 1.0
> Feb 29 18:16:00 ulb kernel: usb0: <NEC uPD 9210 USB controller> on ohci0
> Feb 29 18:16:00 ulb kernel: usb0: USB revision 1.0
> Feb 29 18:16:00 ulb kernel: uhub0: NEC OHCI root hub, class 9/0, rev 
> 1.00/1.00,
> addr 1
> Feb 29 18:16:00 ulb kernel: uhub0: 3 ports with 3 removable, self powered
> Feb 29 18:16:00 ulb kernel: ums0: Logitech USB Mouse, rev 1.10/6.10, addr 2, 
> icl
> ass 3/1
> Feb 29 18:16:00 ulb kernel: ums0: 3 buttons and Z dir.
> Feb 29 18:16:00 ulb kernel: umass0: Behavior Tech. Computer USB 2.0 Storage 
> Devi
> ce, rev 2.00/1.03, addr 3
> Feb 29 18:16:00 ulb kernel: ohci1: <NEC uPD 9210 USB controller> mem 
> 0xe9002000-
> 0xe9002fff irq 18 at device 5.1 on pci2
> Feb 29 18:16:00 ulb kernel: usb1: OHCI version 1.0
> Feb 29 18:16:00 ulb kernel: usb1: <NEC uPD 9210 USB controller> on ohci1
> Feb 29 18:16:00 ulb kernel: usb1: USB revision 1.0
> Feb 29 18:16:00 ulb kernel: uhub1: NEC OHCI root hub, class 9/0, rev 
> 1.00/1.00,
> addr 1
> Feb 29 18:16:00 ulb kernel: uhub1: 2 ports with 2 removable, self powered
> Feb 29 18:16:00 ulb kernel: uscanner0: Canon CanoScan, rev 1.00/1.00, addr 2
> Feb 29 18:16:00 ulb kernel: pci2: <serial bus, USB> at device 5.2 (no driver 
> att
> ached)
> Feb 29 18:16:00 ulb kernel: atapci1: <HighPoint HPT370 UDMA100 controller> 
> port
> 0x2000-0x20ff,0x2828-0x282b,0x2830-0x2837,0x282c-0x282f,0x2838-0x283f irq 18 
> at
> device 6.0 on pci2
> Feb 29 18:16:00 ulb kernel: atapci1: [MPSAFE]
> Feb 29 18:16:00 ulb kernel: ata2: at 0x2838 on atapci1
> Feb 29 18:16:00 ulb kernel: ata2: [MPSAFE]
> Feb 29 18:16:00 ulb kernel: ata3: at 0x2830 on atapci1
> Feb 29 18:16:00 ulb kernel: ata3: [MPSAFE]
> Feb 29 18:16:00 ulb kernel: pci2: <display, VGA> at device 7.0 (no driver 
> attach
> ed)
> Feb 29 18:16:00 ulb kernel: xl0: <3Com 3c980C Fast Etherlink XL> port 
> 0x2400-0x2
> 47f mem 0xe9003400-0xe900347f irq 18 at device 8.0 on pci2
> Feb 29 18:16:00 ulb kernel: xl0: Ethernet address: 00:e0:81:22:5b:f4
> Feb 29 18:16:00 ulb kernel: miibus0: <MII bus> on xl0
> Feb 29 18:16:00 ulb kernel: ukphy0: <Generic IEEE 802.3u media interface> on 
> mii
> bus0
> Feb 29 18:16:00 ulb kernel: ukphy0:  10baseT, 10baseT-FDX, 100baseTX, 
> 100baseTX-
> FDX, auto
> Feb 29 18:16:00 ulb kernel: xl1: <3Com 3c980C Fast Etherlink XL> port 
> 0x2480-0x2
> 4ff mem 0xe9003800-0xe900387f irq 19 at device 9.0 on pci2
> Feb 29 18:16:00 ulb kernel: xl1: Ethernet address: 00:e0:81:22:5b:f5
> Feb 29 18:16:00 ulb kernel: miibus1: <MII bus> on xl1
> Feb 29 18:16:00 ulb kernel: ukphy1: <Generic IEEE 802.3u media interface> on 
> mii
> bus1
> Feb 29 18:16:00 ulb kernel: ukphy1:  10baseT, 10baseT-FDX, 100baseTX, 
> 100baseTX-
> FDX, auto
> Feb 29 18:16:00 ulb kernel: atkbdc0: <Keyboard controller (i8042)> port 
> 0x64,0x6
> 0 irq 1 on acpi0
> Feb 29 18:16:00 ulb kernel: atkbd0: <AT Keyboard> flags 0x1 irq 1 on atkbdc0
> Feb 29 18:16:00 ulb kernel: kbd0 at atkbd0
> Feb 29 18:16:00 ulb kernel: sio0 port 0x3f8-0x3ff irq 4 on acpi0
> Feb 29 18:16:00 ulb kernel: sio0: type 16550A
> Feb 29 18:16:00 ulb kernel: sio1 port 0x2f8-0x2ff irq 3 on acpi0
> Feb 29 18:16:00 ulb kernel: sio1: type 16550A
> Feb 29 18:16:00 ulb kernel: ppc0 port 0x778-0x77f,0x378-0x37f irq 7 drq 3 on 
> acp
> i0
> Feb 29 18:16:00 ulb kernel: ppc0: SMC-like chipset (ECP/EPP/PS2/NIBBLE) in 
> COMPA
> TIBLE mode
> Feb 29 18:16:00 ulb kernel: ppc0: FIFO with 16/16/9 bytes threshold
> Feb 29 18:16:00 ulb kernel: ppbus0: <Parallel port bus> on ppc0
> Feb 29 18:16:00 ulb kernel: lpt0: <Printer> on ppbus0
> Feb 29 18:16:00 ulb kernel: lpt0: Interrupt-driven port
> Feb 29 18:16:00 ulb kernel: fdc0: <Enhanced floppy controller (i82077, 
> NE72065 o
> r clone)> port 0x3f7,0x3f0-0x3f5 irq 6 drq 2 on acpi0
> Feb 29 18:16:00 ulb kernel: fdc0: FIFO enabled, 8 bytes threshold
> Feb 29 18:16:00 ulb kernel: fd0: <1440-KB 3.5" drive> on fdc0 drive 0
> Feb 29 18:16:00 ulb kernel: orm0: <Option ROMs> at iomem 
> 0xe0000-0xe3fff,0xcf800
> -0xcffff,0xcf000-0xcf7ff on isa0
> Feb 29 18:16:00 ulb kernel: pmtimer0 on isa0
> Feb 29 18:16:00 ulb kernel: sc0: <System console> at flags 0x100 on isa0
> Feb 29 18:16:00 ulb kernel: sc0: VGA <16 virtual consoles, flags=0x300>
> Feb 29 18:16:00 ulb kernel: vga0: <Generic ISA VGA> at port 0x3c0-0x3df 
> iomem 0x
> a0000-0xbffff on isa0
> Feb 29 18:16:00 ulb kernel: Timecounters tick every 10.000 msec
> Feb 29 18:16:00 ulb kernel: afd0: REMOVABLE <IOMEGA ZIP 250 ATAPI> at 
> ata0-maste
> r PIO3
> Feb 29 18:16:00 ulb kernel: acd0: CDRW <CD-RW IDE5232> at ata0-slave PIO4
> Feb 29 18:16:00 ulb kernel: acd1: CDRW <Memorex DVD+R/RW 2.4x8AA> at 
> ata1-master
> PIO4
> Feb 29 18:16:00 ulb kernel: ad3: 114473MB <WDC WD1200BB-00CAA1> 
> [232581/16/63] a
> t ata1-slave UDMA100
> Feb 29 18:16:00 ulb kernel: ad4: 114473MB <WDC WD1200JB-00CRA1> 
> [232581/16/63] a
> t ata2-master UDMA100
> Feb 29 18:16:00 ulb kernel: ad6: 76319MB <WDC WD800BB-00CAA0> [155061/16/63] 
> at
> ata3-master UDMA100
> Feb 29 18:16:00 ulb kernel: acd1: FAILURE - REQUEST_SENSE ILLEGAL REQUEST 
> asc=0x
> 24 ascq=0x00 status=50<READY,DSC> error=0
> Feb 29 18:16:00 ulb kernel: acd0: FAILURE - REQUEST_SENSE ILLEGAL REQUEST 
> asc=0x
> 24 ascq=0x00 status=50<READY,DSC> error=0
> Feb 29 18:16:00 ulb kernel: afd0: FAILURE - REQUEST_SENSE ILLEGAL REQUEST 
> asc=0x
> 24 ascq=0x00 status=50<READY,DSC> error=0
> Feb 29 18:16:00 ulb kernel: acd0: FAILURE - REQUEST_SENSE ILLEGAL REQUEST 
> asc=0x
> 24 ascq=0x00 status=50<READY,DSC> error=0
> Feb 29 18:16:00 ulb kernel: afd0: FAILURE - REQUEST_SENSE ILLEGAL REQUEST 
> asc=0x
> 24 ascq=0x00 status=50<READY,DSC> error=0
> Feb 29 18:16:00 ulb kernel: SMP: AP CPU #1 Launched!
> Feb 29 18:16:00 ulb kernel: da0 at ata0 bus 0 target 0 lun 0
> Feb 29 18:16:00 ulb kernel: da0: <IOMEGA ZIP 250 42.S> Removable Direct 
> Access S
> CSI-0 device
> Feb 29 18:16:00 ulb kernel: da0: 11.000MB/s transfers
> Feb 29 18:16:00 ulb kernel: da0: 239MB (489532 512 byte sectors: 64H 32S/T 
> 239C)
> Feb 29 18:16:00 ulb kernel: cd2 at ata1 bus 0 target 0 lun 0
> Feb 29 18:16:00 ulb kernel: cd2: <Memorex DVD+R/RW 2.4x8AA 1.51> Removable 
> CD-RO
> M SCSI-0 device
> Feb 29 18:16:00 ulb kernel: cd2: 16.000MB/s transfers
> Feb 29 18:16:00 ulb kernel: cd2: cd present [1 x 2048 byte records]
> Feb 29 18:16:00 ulb kernel: cd1 at ata0 bus 0 target 1 lun 0
> Feb 29 18:16:00 ulb kernel: cd1: <CDWRITER IDE5232 000F> Removable CD-ROM 
> SCSI-0
> device
> Feb 29 18:16:00 ulb kernel: cd1: 16.000MB/s transfers
> Feb 29 18:16:00 ulb kernel: cd1: cd present [104672 x 2048 byte records]
> Feb 29 18:16:00 ulb kernel: cd0 at umass-sim0 bus 0 target 0 lun 0
> Feb 29 18:16:00 ulb kernel: cd0: <CDWRITER IDE5232 0009> Removable CD-ROM 
> SCSI-0
> device
> Feb 29 18:16:00 ulb kernel: cd0: 1.000MB/s transfers
> Feb 29 18:16:00 ulb kernel: cd0: Attempt to query device size failed: NOT 
> READY,
> Medium not present - tray closed
> Feb 29 18:16:00 ulb kernel: acd1: FAILURE - REQUEST_SENSE ILLEGAL REQUEST 
> asc=0x
> 24 ascq=0x00 status=50<READY,DSC> error=0
> Feb 29 18:16:00 ulb last message repeated 2 times
> Feb 29 18:16:00 ulb kernel: acd1: FAILURE - REQUEST_SENSE ILLEGAL REQUEST 
> asc=0x
> 64 ascq=0x01 status=50<READY,DSC> error=0
> Feb 29 18:16:00 ulb kernel: acd1: FAILURE - REQUEST_SENSE ILLEGAL REQUEST 
> asc=0x
> 24 ascq=0x00 status=50<READY,DSC> error=0
> Feb 29 18:16:00 ulb last message repeated 2 times
> Feb 29 18:16:00 ulb kernel: acd1: FAILURE - REQUEST_SENSE ILLEGAL REQUEST 
> asc=0x
> 64 ascq=0x01 status=50<READY,DSC> error=0
> Feb 29 18:16:00 ulb kernel: Mounting root from ufs:/dev/ad3s2a
> 
> Paulius Bulotas <paulius_at_kaktusas.org> wrote:
> 
> >Hello,
> >
> >after upgrading to new ata code (REQUEST_SENSE feature), I'm unable to
> >boot from disk without media in my cdrom ;)
> >Everything looks like:
> >acd0: CDRW <TOSHIBA DVD-ROM SD-R9012> at ata1-master UDMA33
> >acd0: FAILURE - REQUEST_SENSE ILLEGAL REQUEST asc=0x24 ascq=0x00
> >sks=0x40 0x00 0x01 status=50<READY,DSC> error=0
> >and pause [if I boot with -v, I see a lot of
> >(probe0:ata1:0:0:0): Retrying Command
> >]
> >
> >after inserting cd, everything goes almost fine:
> >acd0: FAILURE - REQUEST_SENSE ILLEGAL REQUEST asc=0x24 ascq=0x00
> >sks=0x40 0x00 0

Me too. :-)

Back out the patches sos made on Feb 28 (ata-all.h 1.74 and ata-queue.c
1.22 and it works again. (cvsup src/sys/dev/ata with a date of
2004.02.28.17.30.00)

These patches:
Issue a request sense command automagically when ATAPI commands fail
with a valid sense key.

Sounds like it's getting something it thinks is and invalid status and
just keeps asking for status forever. I'm reading over the patch now,
but I have no idea if I can figure out what's wrong. It will probably
have to wait for sos to fix it. It's almost certainly HW specific.
-- 
R. Kevin Oberman, Network Engineer
Energy Sciences Network (ESnet)
Ernest O. Lawrence Berkeley National Laboratory (Berkeley Lab)
E-mail: oberman_at_es.net			Phone: +1 510 486-8634
Received on Mon Mar 01 2004 - 09:18:28 UTC

This archive was generated by hypermail 2.4.0 : Wed May 19 2021 - 11:37:45 UTC