ad0 READ_DMA TIMEOUT errors on install of 7.0-RELEASE

Gianni Doe gdoe6545 at yahoo.it
Fri Mar 21 10:45:29 PDT 2008


I'm also experiencing this issue after upgrading to 7.0-RELEASE from 6.3

I've got 2 Western Digital 5000YS hard drives in a GEOM Raid 1  
configuration and connected to a Promise on-board SATA controller,  
this has worked flawlessly under 6.3 but since upgrading to 7.0 I'm  
getting the DMA timeouts under intense write activity. I get the  
errors below and then after having to hard-reset the mirror rebuilds  
from scratch :(

I've pasted dmesg below if that is of any help.
Really desperate for a solution here as I don't fancy reverting to  
6.3, let me know if there is any other info I can provide that may  
help identify the problem.
-Gianni


Mar 21 17:50:07 kananga kernel: ad4: WARNING - SETFEATURES SET  
TRANSFER MODE taskqueue timeout - completing request directly
Mar 21 17:50:11 kananga kernel: ad4: WARNING - SETFEATURES SET  
TRANSFER MODE taskqueue timeout - completing request directly
Mar 21 17:50:15 kananga kernel: ad4: WARNING - SETFEATURES ENABLE  
RCACHE taskqueue timeout - completing request directly
Mar 21 17:50:19 kananga kernel: ad4: WARNING - SETFEATURES ENABLE  
WCACHE taskqueue timeout - completing request directly
Mar 21 17:50:23 kananga kernel: ad4: WARNING - SET_MULTI taskqueue  
timeout - completing request directly
Mar 21 17:50:23 kananga kernel: ad4: TIMEOUT - READ_DMA retrying (1  
retry left) LBA=193407827
Mar 21 17:50:27 kananga kernel: ad6: WARNING - SETFEATURES SET  
TRANSFER MODE taskqueue timeout - completing request directly
Mar 21 17:50:31 kananga kernel: ad6: WARNING - SETFEATURES SET  
TRANSFER MODE taskqueue timeout - completing request directly
Mar 21 17:50:35 kananga kernel: ad6: WARNING - SETFEATURES ENABLE  
RCACHE taskqueue timeout - completing request directly
Mar 21 17:50:39 kananga kernel: ad6: WARNING - SETFEATURES ENABLE  
WCACHE taskqueue timeout - completing request directly
Mar 21 17:50:43 kananga kernel: ad6: WARNING - SET_MULTI taskqueue  
timeout - completing request directly
Mar 21 17:50:43 kananga kernel: ad6: TIMEOUT - WRITE_DMA retrying (1  
retry left) LBA=193297119
Mar 21 17:50:47 kananga kernel: ad4: WARNING - SETFEATURES SET  
TRANSFER MODE taskqueue timeout - completing request directly
Mar 21 17:50:51 kananga kernel: ad4: WARNING - SETFEATURES SET  
TRANSFER MODE taskqueue timeout - completing request directly
Mar 21 17:50:55 kananga kernel: ad4: WARNING - SETFEATURES ENABLE  
RCACHE taskqueue timeout - completing request directly
Mar 21 17:50:59 kananga kernel: ad4: WARNING - SETFEATURES ENABLE  
WCACHE taskqueue timeout - completing request directly
Mar 21 17:51:03 kananga kernel: ad4: WARNING - SET_MULTI taskqueue  
timeout - completing request directly
Mar 21 17:51:03 kananga kernel: ad4: TIMEOUT - WRITE_DMA retrying (1  
retry left) LBA=193297119
Mar 21 17:51:07 kananga kernel: ad6: WARNING - SETFEATURES SET  
TRANSFER MODE taskqueue timeout - completing request directly
Mar 21 17:51:11 kananga kernel: ad6: WARNING - SETFEATURES SET  
TRANSFER MODE taskqueue timeout - completing request directly
Mar 21 17:51:15 kananga kernel: ad6: WARNING - SETFEATURES ENABLE  
RCACHE taskqueue timeout - completing request directly
Mar 21 17:51:19 kananga kernel: ad6: WARNING - SETFEATURES ENABLE  
WCACHE taskqueue timeout - completing request directly


Copyright (c) 1992-2008 The FreeBSD Project.
Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
	The Regents of the University of California. All rights reserved.
FreeBSD is a registered trademark of The FreeBSD Foundation.
FreeBSD 7.0-STABLE #2: Sun Mar 16 22:48:09 CET 2008
     root at kananga.smersh.casa:/usr/obj/usr/src/sys/KANANGA
Timecounter "i8254" frequency 1193182 Hz quality 0
CPU: AMD Athlon(tm) 64 X2 Dual Core Processor 3800+ (2002.58-MHz K8- 
class CPU)
   Origin = "AuthenticAMD"  Id = 0x20fb1  Stepping = 1
    
Features 
= 
0x178bfbff 
< 
FPU 
,VME 
,DE 
,PSE 
,TSC 
,MSR 
,PAE 
,MCE 
,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CLFLUSH,MMX,FXSR,SSE,SSE2,HTT>
   Features2=0x1<SSE3>
   AMD Features=0xe2500800<SYSCALL,NX,MMX+,FFXSR,LM,3DNow!+,3DNow!>
   AMD Features2=0x3<LAHF,CMP>
   Cores per package: 2
usable memory = 1063124992 (1013 MB)
avail memory  = 1024499712 (977 MB)
ACPI APIC Table: <A M I  OEMAPIC >
FreeBSD/SMP: Multiprocessor System Detected: 2 CPUs
  cpu0 (BSP): APIC ID:  0
  cpu1 (AP): APIC ID:  1
MADT: Forcing active-low polarity and level trigger for SCI
ioapic0 <Version 0.3> irqs 0-23 on motherboard
kbd1 at kbdmux0
cryptosoft0: <software crypto> on motherboard
acpi0: <A M I OEMXSDT> on motherboard
acpi0: [ITHREAD]
acpi0: Power Button (fixed)
acpi0: reservation of 0, a0000 (3) failed
acpi0: reservation of 100000, 3fef0000 (3) failed
Timecounter "ACPI-fast" frequency 3579545 Hz quality 1000
acpi_timer0: <24-bit timer at 3.579545MHz> port 0x808-0x80b on acpi0
cpu0: <ACPI CPU> on acpi0
powernow0: <Cool`n'Quiet K8> on cpu0
cpu1: <ACPI CPU> on acpi0
powernow1: <Cool`n'Quiet K8> on cpu1
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
pci0: <ACPI PCI bus> on pcib0
agp0: <VIA K8T800Pro host to PCI bridge> on hostb0
pcib1: <ACPI PCI-PCI bridge> at device 1.0 on pci0
pci1: <ACPI PCI bus> on pcib1
vgapci0: <VGA-compatible display> port 0xe000-0xe0ff mem  
0xe8000000-0xefffffff,0xfbe00000-0xfbe0ffff irq 16 at device 0.0 on pci1
vgapci1: <VGA-compatible display> mem 0xf0000000-0xf7ffffff, 
0xfbf00000-0xfbf0ffff at device 0.1 on pci1
fwohci0: <VIA Fire II (VT6306)> port 0x8400-0x847f mem  
0xfb300000-0xfb3007ff irq 16 at device 7.0 on pci0
fwohci0: [FILTER]
fwohci0: OHCI version 1.0 (ROM=1)
fwohci0: No. of Isochronous channels is 4.
fwohci0: EUI64 00:11:d8:00:00:1b:2a:01
fwohci0: Phy 1394a available S400, 2 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 0x24e8000
sbp0: <SBP-2/SCSI over FireWire> on firewire0
fwohci0: Initiate bus reset
fwohci0: BUS reset
fwohci0: node_id=0xc800ffc0, gen=1, CYCLEMASTER mode
atapci0: <Promise PDC20378 SATA150 controller> port 0x9400-0x943f, 
0x9000-0x900f,0x8800-0x887f mem 0xfb500000-0xfb500fff, 
0xfb400000-0xfb41ffff irq 18 at device 8.0 on pci0
atapci0: [ITHREAD]
atapci0: [ITHREAD]
ata2: <ATA channel 0> on atapci0
ata2: [ITHREAD]
ata3: <ATA channel 1> on atapci0
ata3: [ITHREAD]
ata4: <ATA channel 2> on atapci0
ata4: [ITHREAD]
em0: <Intel(R) PRO/1000 Network Connection Version - 6.7.3> port  
0x9800-0x983f mem 0xfb800000-0xfb81ffff,0xfb700000-0xfb71ffff irq 17  
at device 12.0 on pci0
em0: Ethernet address: 00:0e:0c:ab:ad:42
em0: [FILTER]
ahc0: <Adaptec 29160 Ultra160 SCSI adapter> port 0xa000-0xa0ff mem  
0xfba00000-0xfba00fff irq 19 at device 14.0 on pci0
ahc0: [ITHREAD]
aic7892: Ultra160 Wide Channel A, SCSI Id=7, 32/253 SCBs
atapci1: <VIA 6420 SATA150 controller> port  
0xc000-0xc007,0xb800-0xb803,0xb400-0xb407,0xb000-0xb003,0xa800-0xa80f, 
0xa400-0xa4ff irq 20 at device 15.0 on pci0
atapci1: [ITHREAD]
ata5: <ATA channel 0> on atapci1
ata5: [ITHREAD]
ata6: <ATA channel 1> on atapci1
ata6: [ITHREAD]
atapci2: <VIA 8237 UDMA133 controller> port  
0x1f0-0x1f7,0x3f6,0x170-0x177,0x376,0xfc00-0xfc0f at device 15.1 on pci0
ata0: <ATA channel 0> on atapci2
ata0: [ITHREAD]
ata1: <ATA channel 1> on atapci2
ata1: [ITHREAD]
uhci0: <VIA 83C572 USB controller> port 0xc400-0xc41f irq 21 at device  
16.0 on pci0
uhci0: [GIANT-LOCKED]
uhci0: [ITHREAD]
usb0: <VIA 83C572 USB controller> on uhci0
usb0: USB revision 1.0
uhub0: <VIA UHCI root hub, class 9/0, rev 1.00/1.00, addr 1> on usb0
uhub0: 2 ports with 2 removable, self powered
uhci1: <VIA 83C572 USB controller> port 0xc800-0xc81f irq 21 at device  
16.1 on pci0
uhci1: [GIANT-LOCKED]
uhci1: [ITHREAD]
usb1: <VIA 83C572 USB controller> on uhci1
usb1: USB revision 1.0
uhub1: <VIA UHCI root hub, class 9/0, rev 1.00/1.00, addr 1> on usb1
uhub1: 2 ports with 2 removable, self powered
uhci2: <VIA 83C572 USB controller> port 0xd000-0xd01f irq 21 at device  
16.2 on pci0
uhci2: [GIANT-LOCKED]
uhci2: [ITHREAD]
usb2: <VIA 83C572 USB controller> on uhci2
usb2: USB revision 1.0
uhub2: <VIA UHCI root hub, class 9/0, rev 1.00/1.00, addr 1> on usb2
uhub2: 2 ports with 2 removable, self powered
uhci3: <VIA 83C572 USB controller> port 0xd400-0xd41f irq 21 at device  
16.3 on pci0
uhci3: [GIANT-LOCKED]
uhci3: [ITHREAD]
usb3: <VIA 83C572 USB controller> on uhci3
usb3: USB revision 1.0
uhub3: <VIA UHCI root hub, class 9/0, rev 1.00/1.00, addr 1> on usb3
uhub3: 2 ports with 2 removable, self powered
ehci0: <VIA VT6202 USB 2.0 controller> mem 0xfbc00000-0xfbc000ff irq  
21 at device 16.4 on pci0
ehci0: [GIANT-LOCKED]
ehci0: [ITHREAD]
usb4: waiting for BIOS to give up control
usb4: EHCI version 1.0
usb4: companion controllers, 2 ports each: usb0 usb1 usb2 usb3
usb4: <VIA VT6202 USB 2.0 controller> on ehci0
usb4: USB revision 2.0
uhub4: <VIA EHCI root hub, class 9/0, rev 2.00/1.00, addr 1> on usb4
uhub4: 8 ports with 8 removable, self powered
uhub5: <vendor 0x04cc product 0x1520, class 9/0, rev 2.00/2.00, addr  
2> on uhub4
uhub5: single transaction translator
uhub5: 3 ports with 2 removable, self powered
ukbd0: <No brand KVM, class 0/0, rev 1.10/0.00, addr 3> on uhub5
kbd2 at ukbd0
ums0: <No brand KVM, class 0/0, rev 1.10/0.00, addr 3> on uhub5
ums0: 5 buttons and Z dir.
isab0: <PCI-ISA bridge> at device 17.0 on pci0
isa0: <ISA bus> on isab0
pci0: <multimedia, audio> at device 17.5 (no driver attached)
acpi_button0: <Power Button> on acpi0
acpi_button1: <Sleep Button> on acpi0
atkbdc0: <Keyboard controller (i8042)> port 0x60,0x64 irq 1 on acpi0
atkbd0: <AT Keyboard> irq 1 on atkbdc0
kbd0 at atkbd0
atkbd0: [GIANT-LOCKED]
atkbd0: [ITHREAD]
fdc0: <floppy drive controller (FDE)> port 0x3f0-0x3f5,0x3f7 irq 6 drq  
2 on acpi0
fdc0: [FILTER]
fd0: <1440-KB 3.5" drive> on fdc0 drive 0
sio0: configured irq 3 not in bitmap of probed irqs 0
sio0: port may not be enabled
sio0: configured irq 3 not in bitmap of probed irqs 0
sio0: port may not be enabled
sio0: <16550A-compatible COM port> port 0x2f8-0x2ff irq 3 flags 0x10  
on acpi0
sio0: type 16550A
sio0: [FILTER]
sio1: configured irq 4 not in bitmap of probed irqs 0
sio1: port may not be enabled
sio1: configured irq 4 not in bitmap of probed irqs 0
sio1: port may not be enabled
sio1: <16550A-compatible COM port> port 0x3f8-0x3ff irq 4 on acpi0
sio1: type 16550A
sio1: [FILTER]
orm0: <ISA Option ROMs> at iomem 0xc0000-0xccfff,0xcd000-0xd0fff, 
0xd1000-0xd1fff on isa0
ppc0: cannot reserve I/O port range
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=0x300>
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on  
isa0
ugen0: <American Power Conversion Smart-UPS 1000 FW:600.3.I USB FW: 
1.5, class 0/0, rev 1.10/0.06, addr 2> on uhub0
Timecounters tick every 1.000 msec
Fast IPsec: Initialized Security Association Processing.
firewire0: 1 nodes, maxhop <= 0, cable IRM = 0 (me)
firewire0: bus manager 0 (me)
acd0: DVDR <PHILIPS SPD2414T/P1.0> at ata0-master UDMA66
ad4: 476940MB <WDC WD5000YS-01MPB1 09.02E09> at ata2-master SATA150
ad6: 476940MB <WDC WD5000YS-01MPB1 09.02E09> at ata3-master SATA150
ad10: 114473MB <Seagate ST3120827AS 3.42> at ata5-master SATA150
ad12: 152627MB <Seagate ST3160827AS 3.42> at ata6-master SATA150
Waiting 5 seconds for SCSI devices to settle
GEOM_MIRROR: Device mirror/gm2 launched (2/2).
GEOM_MIRROR: Device mirror/gm1s1 launched (1/2).
GEOM_MIRROR: Device gm1s1: rebuilding provider ad4s1.
acd0: FAILURE - INQUIRY ILLEGAL REQUEST asc=0x24 ascq=0x00
acd0: FAILURE - INQUIRY ILLEGAL REQUEST asc=0x24 ascq=0x00
sa0 at ahc0 bus 0 target 15 lun 0
sa0: <SEAGATE DAT    DAT72-000 A060> Removable Sequential Access  
SCSI-3 device
sa0: 80.000MB/s transfers (40.000MHz, offset 32, 16bit)
cd0 at ata0 bus 0 target 0 lun 0
cd0: <PHILIPSS MSPP:D 2A4P1 4CTP UP 1#.01>  LRaeumnocvhaebdl!e
  CD-ROM SCSI-0 device
cd0: 66.000MB/s transfers
cd0: Attempt to query device size failed: NOT READY, Medium not  
present - tray closed
Trying to mount root from ufs:/dev/mirror/gm2s1a
WARNING: /home was not properly dismounted
WARNING: /spare was not properly dismounted
WARNING: /usr was not properly dismounted
WARNING: /var was not properly dismounted
WARNING: /data was not properly dismounted
kqemu version 0x00010300
kqemu: KQEMU installed, max_locked_mem=519104kB.



More information about the freebsd-stable mailing list