RELENG_5: ata interrupt problems

Sun Aug 22 07:05:35 PDT 2004

On 2004.08.22 15:17:54 +0200, Søren Schmidt wrote:
> Simon L. Nielsen wrote:
> >On 2004.08.22 14:27:37 +0200, Søren Schmidt wrote:
> >
> >>Randy Bush wrote:
> >>
> >>>>The actual errors from a 5.3-BETA from today (hand transcribed):
> >>>
> >>>>ad0: 73863MB <HTS548080M9AT00/MG40A50A> [150071/16/63] at ata0-master 
> >>>>UDMA100
> >>>>ATAPI_RESET time = 150us
> >>>>ata1-slave: FAILURE - ATAPI_IDENTIFY timed out
> >>>>ATAPI_RESET time = 150us
> >>>>acd0: CDRW <UJDA740 DVD/CDRW/1.04> at ata1-master UDMA33
> >>>>Mounting root from ufs:/dev/ad0s2a
> >>>>[...]
> >>>>ad0: WARNING - READ_DME interupt was seen but timeout fired LBA=41574224
> >>>>[...]
> >>>>ad0: WARNING - READ_DME interupt was seen but timeout fired LBA=41562160
> >>>>ad0: WARNING - READ_DME interupt was seen but timeout fired LBA=41562160
> >>>>ad0: WARNING - READ_DME interupt was seen but taskqueue stalled 
> >>>>LBA=41562160
> >>>>Slab at 0xc1c3ff70, freei 3 = 0
> >>>>
> >>>>The "[...]" is just normal startup.  It goes as far as the script that
> >>>>attaches my GDBE partition before it panics.
> >>>>
> >>>>And then it panics like below:
> >>>
> >>>yep.  same on a thinkpad t40p
> 
> Could I have you try the below patch and mail me the output from dmesg 
> on a failed boot please?. I need to know what kind of state those damned 
> fakeslave devices are in to be able to fix this problem.

It just says "status=00 error=00", but complete dmesg is attached (as
much as dcons(4) has on boot).  BTW. this is RELENG_5.

> Or someone get me one of those crappy drives that does this here in the 
> lab to disect...

Since the drives use some special laptop connector it's probably not
really useful without a compatible IBM laptop...

-- 
Simon L. Nielsen
FreeBSD Documentation Team
-------------- next part --------------
[dcons connected]
npx0: [FAST]
npx0: <math processor> on motherboard
npx0: INT 16 interface
acpi0: <IBM TP-1P> on motherboard
acpi_ec0: <Embedded Controller: GPE 0x1c, ECDT> port 0x66,0x62 on acpi0
acpi0: Power Button (fixed)
Timecounter "ACPI-fast" frequency 3579545 Hz quality 1000
acpi_timer0: <24-bit timer at 3.579545MHz> port 0x1008-0x100b on acpi0
cpu0: <ACPI CPU (3 Cx states)> on acpi0
acpi_tz0: <Thermal Zone> on acpi0
acpi_lid0: <Control Method Lid Switch> on acpi0
acpi_button0: <Sleep Button> on acpi0
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
pci0: <ACPI PCI bus> on pcib0
agp0: <Intel 82855 host to AGP bridge> mem 0xd0000000-0xdfffffff at device 0.0 on pci0
pcib1: <ACPI PCI-PCI bridge> at device 1.0 on pci0
pci1: <ACPI PCI bus> on pcib1
drm0: <ATI Radeon LW Mobility 7500 M7> port 0x3000-0x30ff mem 0xc0100000-0xc010ffff,0xe0000000-0xe7ffffff irq 11 at device 0.0 on pci1
info: [drm] AGP at 0xd0000000 256MB
info: [drm] Initialized radeon 1.11.0 20020828 on minor 0
uhci0: <Intel 82801DB (ICH4) USB controller USB-A> port 0x1800-0x181f irq 11 at device 29.0 on pci0
uhci0: [GIANT-LOCKED]
usb0: <Intel 82801DB (ICH4) USB controller USB-A> on uhci0
usb0: USB revision 1.0
uhub0: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub0: 2 ports with 2 removable, self powered
uhci1: <Intel 82801DB (ICH4) USB controller USB-B> port 0x1820-0x183f irq 11 at device 29.1 on pci0
uhci1: [GIANT-LOCKED]
usb1: <Intel 82801DB (ICH4) USB controller USB-B> on uhci1
usb1: USB revision 1.0
uhub1: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub1: 2 ports with 2 removable, self powered
uhci2: <Intel 82801DB (ICH4) USB controller USB-C> port 0x1840-0x185f irq 11 at device 29.2 on pci0
uhci2: [GIANT-LOCKED]
usb2: <Intel 82801DB (ICH4) USB controller USB-C> on uhci2
usb2: USB revision 1.0
uhub2: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
uhub2: 2 ports with 2 removable, self powered
pci0: <serial bus, USB> at device 29.7 (no driver attached)
pcib2: <ACPI PCI-PCI bridge> at device 30.0 on pci0
pci2: <ACPI PCI bus> on pcib2
cbb0: <TI1510 PCI-CardBus Bridge> irq 11 at device 0.0 on pci2
cardbus0: <CardBus bus> on cbb0
pccard0: <16-bit PCCard bus> on cbb0
pci2: <network> at device 2.0 (no driver attached)
fwohci0: <Texas Instruments TSB43AB21/A/AI/A-EP> mem 0xc0200000-0xc0203fff,0xc0206000-0xc02067ff irq 11 at device 7.0 on pci2
fwohci0: [GIANT-LOCKED]
fwohci0: OHCI version 1.10 (ROM=0)
fwohci0: No. of Isochronous channels is 4.
fwohci0: EUI64 00:06:1b:00:20:08:fc:24
fwohci0: Phy 1394a available S400, 1 ports.
fwohci0: Link S400, max_rec 2048 bytes.
firewire0: <IEEE1394(FireWire) bus> on fwohci0
dcons_crom0: <dcons configuration ROM> on firewire0
dcons_crom0: bus_addr 0x1f3ca000
fwohci0: Initiate bus reset
fwohci0: node_id=0x8000ffc0, gen=1, non CYCLEMASTER mode
firewire0: 2 nodes, maxhop <= 1, cable IRM = 1
fxp0: <Intel 82801DB (ICH4) Pro/100 VE Ethernet> port 0x8000-0x803f mem 0xc0205000-0xc0205fff irq 11 at device 8.0 on pci2
miibus0: <MII bus> on fxp0
inphy0: <i82562ET 10/100 media interface> on miibus0
inphy0:  10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
fxp0: Ethernet address: 00:06:1b:d2:d5:6f
fxp0: [GIANT-LOCKED]
isab0: <PCI-ISA bridge> at device 31.0 on pci0
isa0: <ISA bus> on isab0
atapci0: <Intel ICH4 UDMA100 controller> port 0x1860-0x186f,0x376,0x170-0x177,0x3f6,0x1f0-0x1f7 at device 31.1 on pci0
ata0: channel #0 on atapci0
ata1: channel #1 on atapci0
pci0: <serial bus, SMBus> at device 31.3 (no driver attached)
pcm0: <Intel ICH4 (82801DB)> port 0x18c0-0x18ff,0x1c00-0x1cff mem 0xc0000800-0xc00008ff,0xc0000c00-0xc0000dff irq 11 at device 31.5 on pci0
pcm0: [GIANT-LOCKED]
pcm0: <Analog Devices AD1981B AC97 Codec>
pci0: <simple comms> at device 31.6 (no driver attached)
atkbdc0: <Keyboard controller (i8042)> port 0x64,0x60 irq 1 on acpi0
atkbd0: <AT Keyboard> irq 1 on atkbdc0
kbd0 at atkbd0
atkbd0: [GIANT-LOCKED]
psm0: <PS/2 Mouse> irq 12 on atkbdc0
psm0: [GIANT-LOCKED]
psm0: model Generic PS/2 mouse, device ID 0
sio0: configured irq 4 not in bitmap of probed irqs 0
sio0: port may not be enabled
sio0 port 0x3f8-0x3ff irq 4 on acpi0
sio0: type 8250 or not responding
ppc0 port 0x3bc-0x3be irq 7 on acpi0
ppc0: Generic chipset (NIBBLE-only) in COMPATIBLE mode
ppbus0: <Parallel port bus> on ppc0
lpt0: <Printer> on ppbus0
lpt0: Interrupt-driven port
ppi0: <Parallel I/O> on ppbus0
sio1 port 0x2f8-0x2ff irq 3 drq 3 on acpi0
sio1: type 16550A
acpi_cmbat0: <Control Method Battery> on acpi0
acpi_acad0: <AC Adapter> on acpi0
pmtimer0 on isa0
orm0: <ISA Option ROMs> at iomem 0xdc000-0xdffff,0xd1000-0xd1fff,0xd0000-0xd0fff on isa0
sc0: <System console> on isa0
sc0: VGA <16 virtual consoles, flags=0x200>
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
fdc0: ready for input in output
fdc0: cmd 3 failed at out byte 1 of 3
Timecounter "TSC" frequency 1498732135 Hz quality 800
Timecounters tick every 10.000 msec
IPsec: Initialized Security Association Processing.
ipfw2 initialized, divert enabled, rule-based forwarding disabled, default to deny, logging limited to 100 packets/entry by default
acpi_cpu: throttling enabled, 8 steps (100% to 12.5%), currently 100.0%
ad0: 73863MB <HTS548080M9AT00/MG4OA50A> [150071/16/63] at ata0-master UDMA100
firewire0: bus manager 1 
firewire0: New S400 device ID:00e01800000516f6
status=00 error=00
ATAPI_RESET time = 270us
ata1-slave: FAILURE - ATAPI_IDENTIFY timed out
ATAPI_RESET time = 270us
acd0: CDRW <UJDA740 DVD/CDRW/1.04> at ata1-master UDMA33
Mounting root from ufs:/dev/ad0s2a
Pre-seeding PRNG:ad0: WARNING - READ_DMA interrupt was seen but timeout fired LBA=41560848
 kickstart.
Loading configuration files.
Entropy harvesting: interrupts ethernet point_to_point kickstart.
kernel dumps on /dev/ad0s2b
Configuring Disk Encryption for /dev/ad0s2f.bde.
Enter passphrase: ad0: WARNING - READ_DMA interrupt was seen but timeout fired LBA=41562160
ad0: WARNING - READ_DMA interrupt was seen but timeout fired LBA=41562160
ad0: WARNING - READ_DMA interrupt was seen but taskqueue stalled LBA=41562160
Slab at 0xc1d1bf70, freei 18 = 0.
panic: Duplicate free of item 0xc1d1b948 from zone 0xc198f160(g_bio)

cpuid = 0; 
KDB: enter: panic
[thread 100024]
Stopped at      kdb_enter+0x30: leave
db> trace
kdb_enter(c0778205,0,c0795b72,d41dcb90,c198b2c0) at kdb_enter+0x30
panic(c0795b72,c1d1b948,c198f160,c077327e,8b0) at panic+0x14a
uma_dbg_free(c198f160,0,c1d1b948,8b0,0) at uma_dbg_free+0x126
uma_zfree_arg(c198f160,c1d1b948,0,d41dcc20,c0523e9c) at uma_zfree_arg+0x128
g_destroy_bio(c1d1b948,c0772b0c,c1d1b948,cbe54138,0) at g_destroy_bio+0x22
g_dev_done(c1d1b948,0,c077eda3,bb8) at g_dev_done+0x7c
biodone(c1d1b948,1,c1d18438,c1d18438,d41dcc84) at biodone+0x72
ad_done(c1d18438,c1d18438,0,a,d41dcc90) at ad_done+0x32
ata_completed(c1d18438,0,0,7,c1d18438) at ata_completed+0x45d
ata_timeout(c1d18438,0,c07793e9,f7,1) at ata_timeout+0x11c
softclock(0,0,c07759aa,268,0) at softclock+0x1b6
ithread_loop(c19b3580,d41dcd48,c0775795,32c,c19b3580) at ithread_loop+0x15f
fork_exit(c0549319,c19b3580,d41dcd48) at fork_exit+0xc7
fork_trampoline() at fork_trampoline+0x8
--- trap 0x1, eip = 0, esp = 0xd41dcd7c, ebp = 0 ---
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-signature
Size: 187 bytes
Desc: not available
Url : http://lists.freebsd.org/pipermail/freebsd-current/attachments/20040822/88adcaae/attachment.bin