From owner-freebsd-stable@FreeBSD.ORG Fri Mar 21 17:45:29 2008 Return-Path: Delivered-To: freebsd-stable@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:4f8:fff6::34]) by hub.freebsd.org (Postfix) with ESMTP id A8B6B106566B for ; Fri, 21 Mar 2008 17:45:29 +0000 (UTC) (envelope-from gdoe6545@yahoo.it) Received: from mx.smershnet.com (mx.smershnet.com [87.117.208.209]) by mx1.freebsd.org (Postfix) with ESMTP id DDD808FC21 for ; Fri, 21 Mar 2008 17:45:28 +0000 (UTC) (envelope-from gdoe6545@yahoo.it) Received: from localhost (mx.smershnet.com [87.117.208.209]) by mx.smershnet.com (Postfix) with ESMTP id 0FEB72754881 for ; Fri, 21 Mar 2008 17:27:24 +0000 (GMT) X-Virus-Scanned: amavisd-new at smershnet.com Received: from mx.smershnet.com ([87.117.208.209]) by localhost (mx.smershnet.com [87.117.208.209]) (amavisd-new, port 10024) with ESMTP id k+WE7mRVf8LI for ; Fri, 21 Mar 2008 17:27:21 +0000 (GMT) Received: from kananga.hq.smershnet.com (217-133-13-140.b2b.tiscali.it [217.133.13.140]) (using TLSv1 with cipher ADH-AES256-SHA (256/256 bits)) (No client certificate requested) by mx.smershnet.com (Postfix) with ESMTP id C20BA2754880 for ; Fri, 21 Mar 2008 17:27:20 +0000 (GMT) Received: from zao.smersh.casa (zao.smersh.casa [192.168.200.16]) (using TLSv1 with cipher AES128-SHA (128/128 bits)) (No client certificate requested) by kananga.hq.smershnet.com (Postfix) with ESMTPS id 4E60756446 for ; Fri, 21 Mar 2008 18:26:54 +0100 (CET) Message-Id: From: Gianni Doe To: freebsd-stable@freebsd.org Content-Type: text/plain; charset=US-ASCII; format=flowed; delsp=yes Content-Transfer-Encoding: 7bit Mime-Version: 1.0 (Apple Message framework v919.2) Date: Fri, 21 Mar 2008 18:27:19 +0100 X-Mailer: Apple Mail (2.919.2) Subject: ad0 READ_DMA TIMEOUT errors on install of 7.0-RELEASE X-BeenThere: freebsd-stable@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: Production branch of FreeBSD source code List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Fri, 21 Mar 2008 17:45:29 -0000 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@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 AMD Features=0xe2500800 AMD Features2=0x3 Cores per package: 2 usable memory = 1063124992 (1013 MB) avail memory = 1024499712 (977 MB) ACPI APIC Table: 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 irqs 0-23 on motherboard kbd1 at kbdmux0 cryptosoft0: on motherboard acpi0: 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: on acpi0 powernow0: on cpu0 cpu1: on acpi0 powernow1: on cpu1 pcib0: port 0xcf8-0xcff on acpi0 pci0: on pcib0 agp0: on hostb0 pcib1: at device 1.0 on pci0 pci1: on pcib1 vgapci0: port 0xe000-0xe0ff mem 0xe8000000-0xefffffff,0xfbe00000-0xfbe0ffff irq 16 at device 0.0 on pci1 vgapci1: mem 0xf0000000-0xf7ffffff, 0xfbf00000-0xfbf0ffff at device 0.1 on pci1 fwohci0: 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: on fwohci0 dcons_crom0: on firewire0 dcons_crom0: bus_addr 0x24e8000 sbp0: on firewire0 fwohci0: Initiate bus reset fwohci0: BUS reset fwohci0: node_id=0xc800ffc0, gen=1, CYCLEMASTER mode atapci0: 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: on atapci0 ata2: [ITHREAD] ata3: on atapci0 ata3: [ITHREAD] ata4: on atapci0 ata4: [ITHREAD] em0: 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: 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: port 0xc000-0xc007,0xb800-0xb803,0xb400-0xb407,0xb000-0xb003,0xa800-0xa80f, 0xa400-0xa4ff irq 20 at device 15.0 on pci0 atapci1: [ITHREAD] ata5: on atapci1 ata5: [ITHREAD] ata6: on atapci1 ata6: [ITHREAD] atapci2: port 0x1f0-0x1f7,0x3f6,0x170-0x177,0x376,0xfc00-0xfc0f at device 15.1 on pci0 ata0: on atapci2 ata0: [ITHREAD] ata1: on atapci2 ata1: [ITHREAD] uhci0: port 0xc400-0xc41f irq 21 at device 16.0 on pci0 uhci0: [GIANT-LOCKED] uhci0: [ITHREAD] usb0: on uhci0 usb0: USB revision 1.0 uhub0: on usb0 uhub0: 2 ports with 2 removable, self powered uhci1: port 0xc800-0xc81f irq 21 at device 16.1 on pci0 uhci1: [GIANT-LOCKED] uhci1: [ITHREAD] usb1: on uhci1 usb1: USB revision 1.0 uhub1: on usb1 uhub1: 2 ports with 2 removable, self powered uhci2: port 0xd000-0xd01f irq 21 at device 16.2 on pci0 uhci2: [GIANT-LOCKED] uhci2: [ITHREAD] usb2: on uhci2 usb2: USB revision 1.0 uhub2: on usb2 uhub2: 2 ports with 2 removable, self powered uhci3: port 0xd400-0xd41f irq 21 at device 16.3 on pci0 uhci3: [GIANT-LOCKED] uhci3: [ITHREAD] usb3: on uhci3 usb3: USB revision 1.0 uhub3: on usb3 uhub3: 2 ports with 2 removable, self powered ehci0: 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: on ehci0 usb4: USB revision 2.0 uhub4: on usb4 uhub4: 8 ports with 8 removable, self powered uhub5: on uhub4 uhub5: single transaction translator uhub5: 3 ports with 2 removable, self powered ukbd0: on uhub5 kbd2 at ukbd0 ums0: on uhub5 ums0: 5 buttons and Z dir. isab0: at device 17.0 on pci0 isa0: on isab0 pci0: at device 17.5 (no driver attached) acpi_button0: on acpi0 acpi_button1: on acpi0 atkbdc0: port 0x60,0x64 irq 1 on acpi0 atkbd0: irq 1 on atkbdc0 kbd0 at atkbd0 atkbd0: [GIANT-LOCKED] atkbd0: [ITHREAD] fdc0: 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: at iomem 0xc0000-0xccfff,0xcd000-0xd0fff, 0xd1000-0xd1fff on isa0 ppc0: cannot reserve I/O port range sc0: at flags 0x100 on isa0 sc0: VGA <16 virtual consoles, flags=0x300> vga0: at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0 ugen0: 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 at ata0-master UDMA66 ad4: 476940MB at ata2-master SATA150 ad6: 476940MB at ata3-master SATA150 ad10: 114473MB at ata5-master SATA150 ad12: 152627MB 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: 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: 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.