From owner-freebsd-stable@FreeBSD.ORG Tue Aug 31 18:35:52 2004 Return-Path: Delivered-To: freebsd-stable@freebsd.org Received: from mx1.FreeBSD.org (mx1.freebsd.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id 3F67616A4CE for ; Tue, 31 Aug 2004 18:35:52 +0000 (GMT) Received: from cliffclavin.cs.rpi.edu (cliffclavin.cs.rpi.edu [128.213.1.9]) by mx1.FreeBSD.org (Postfix) with ESMTP id C8E6043D48 for ; Tue, 31 Aug 2004 18:35:51 +0000 (GMT) (envelope-from crossd@cs.rpi.edu) Received: from monica.cs.rpi.edu (root@monica.cs.rpi.edu [128.213.7.2]) i7VIZoRs060038 (version=TLSv1/SSLv3 cipher=DHE-RSA-AES256-SHA bits=256 verify=NO) for ; Tue, 31 Aug 2004 14:35:50 -0400 (EDT) Received: from monica.cs.rpi.edu (crossd@localhost [127.0.0.1]) by monica.cs.rpi.edu (8.12.9p2/8.12.6) with ESMTP id i7VIZowC022896 for ; Tue, 31 Aug 2004 14:35:50 -0400 (EDT) (envelope-from crossd@monica.cs.rpi.edu) Received: from localhost (crossd@localhost)i7VIZoR4022893 for ; Tue, 31 Aug 2004 14:35:50 -0400 (EDT) (envelope-from crossd@monica.cs.rpi.edu) Date: Tue, 31 Aug 2004 14:35:50 -0400 (EDT) From: "David E. Cross" To: freebsd-stable@freebsd.org Message-ID: <20040831142831.D22883@monica.cs.rpi.edu> MIME-Version: 1.0 Content-Type: TEXT/PLAIN; charset=US-ASCII X-Scanned-By: MIMEDefang 2.43 Subject: READ_DMA interrupt was seen but timeout fired LBA=#### X-BeenThere: freebsd-stable@freebsd.org X-Mailman-Version: 2.1.1 Precedence: list List-Id: Production branch of FreeBSD source code List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Tue, 31 Aug 2004 18:35:52 -0000 I searched the mailing list an didn't see anything listed for August, so I thought I would write. Upon switching to 5.3-BETA2 I see these a LOT on my IBM TP T30. In fact BETA2 panics shortly after boot, and once in DDB I can't actually do anything ('panic' doesn't work, 'call boot(0)' doesn't work, eventually DDB itself just locks). Upgrading to RELENG_5 makes it a bit better, I still see oodles of those messages and WRITE_DMA messages also, but it doesn't panic. Eventually everything will lock behind "ufs" in a wait channel. Booting to single-user seems to make it better (I can run commands, etc and not get stuck in [ufs]). ACPI doesn't seem to make a difference. Also, the GENERIC on the CD loader doesn't seem to exhibit any of these problems. Below is a copy of my DMESG; ideas? Any additional information required? Aug 30 23:13:44 syslogd: kernel boot file is /boot/kernel/kernel Aug 30 23:13:44 kernel: Copyright (c) 1992-2004 The FreeBSD Project. Aug 30 23:13:44 kernel: Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994 Aug 30 23:13:44 kernel: The Regents of the University of California. All rights reserved. Aug 30 23:13:44 kernel: FreeBSD 5.3-BETA2 #1: Sat Aug 28 21:29:15 UTC 2004 Aug 30 23:13:44 kernel: root@mack.dcsl.buffalo.edu:/usr/obj/usr/src/sys/GENERICAug 30 23:13:44 kernel: WARNING: WITNESS option enabled, expect reduced performance. Aug 30 23:13:44 kernel: Timecounter "i8254" frequency 1193182 Hz quality 0 Aug 30 23:13:44 kernel: CPU: Mobile Intel(R) Pentium(R) 4 - M CPU 2.00GHz (1998.31-MHz 686-class CPU) Aug 30 23:13:44 kernel: Origin = "GenuineIntel" Id = 0xf27 Stepping = 7 Aug 30 23:13:44 kernel: Features=0xbfebf9ff Aug 30 23:13:44 kernel: real memory = 267780096 (255 MB) Aug 30 23:13:44 kernel: avail memory = 252379136 (240 MB) Aug 30 23:13:44 kernel: npx0: [FAST] Aug 30 23:13:44 kernel: npx0: on motherboard Aug 30 23:13:44 kernel: npx0: INT 16 interface Aug 30 23:13:44 kernel: acpi0: on motherboard Aug 30 23:13:44 kernel: acpi_ec_ecdt_probe: can't get handle Aug 30 23:13:44 kernel: ACPI-0358: *** Error: Region EmbeddedControl(3) has no handler Aug 30 23:13:44 kernel: ACPI-1303: *** Error: Method execution failed [\_SB_.PCI0.LPC_.FDC_._INI] (Node 0xc15e6420), AE_NOT_EXIST Aug 30 23:13:44 kernel: ACPI-0358: *** Error: Region EmbeddedControl(3) has no handler Aug 30 23:13:44 kernel: ACPI-1303: *** Error: Method execution failed [\_SB_.PCI0.LPC_.EC__._INI] (Node 0xc15dab00), AE_NOT_EXIST Aug 30 23:13:44 kernel: ACPI-0358: *** Error: Region EmbeddedControl(3) has no handler Aug 30 23:13:44 kernel: ACPI-1303: *** Error: Method execution failed [\_SB_.PCI0.LPC_.EC__.BAT0._STA] (Node 0xc15dc6a0), AE_NOT_EXIST Aug 30 23:13:44 kernel: ACPI-0239: *** Error: Method execution failed [\_SB_.PCI0.LPC_.EC__.BAT0._STA] (Node 0xc15dc6a0), AE_NOT_EXIST Aug 30 23:13:44 kernel: ACPI-0358: *** Error: Region EmbeddedControl(3) has no handler Aug 30 23:13:44 kernel: ACPI-1303: *** Error: Method execution failed [\_SB_.PCI0.LPC_.EC__.BAT1._STA] (Node 0xc15dc520), AE_NOT_EXIST Aug 30 23:13:44 kernel: ACPI-0239: *** Error: Method execution failed [\_SB_.PCI0.LPC_.EC__.BAT1._STA] (Node 0xc15dc520), AE_NOT_EXIST Aug 30 23:13:44 kernel: ACPI-0358: *** Error: Region EmbeddedControl(3) has no handler Aug 30 23:13:44 kernel: ACPI-1303: *** Error: Method execution failed [\_SB_.PCI0.LPC_.EC__.BGID] (Node 0xc15e6560), AE_NOT_EXIST Aug 30 23:13:44 kernel: ACPI-1303: *** Error: Method execution failed [\_SB_.PCI0.LPC_.EC__.BINI] (Node 0xc15e6580), AE_NOT_EXIST Aug 30 23:13:44 kernel: ACPI-1303: *** Error: Method execution failed [\_SB_.PCI0.LPC_.EC__.BSTA] (Node 0xc15e65c0), AE_NOT_EXIST Aug 30 23:13:44 kernel: ACPI-1303: *** Error: Method execution failed [\_SB_.PCI0.IDE0.SCND.MSTR._STA] (Node 0xc15e6480), AE_NOT_EXIST Aug 30 23:13:44 kernel: ACPI-0239: *** Error: Method execution failed [\_SB_.PCI0.IDE0.SCND.MSTR._STA] (Node 0xc15e6480), AE_NOT_EXIST Aug 30 23:13:44 kernel: ACPI-0358: *** Error: Region EmbeddedControl(3) has no handler Aug 30 23:13:44 kernel: ACPI-1303: *** Error: Method execution failed [\_SB_.PCI0.LPC_.EC__.BGID] (Node 0xc15e6560), AE_NOT_EXIST Aug 30 23:13:44 kernel: ACPI-1303: *** Error: Method execution failed [\_SB_.PCI0.LPC_.EC__.BINI] (Node 0xc15e6580), AE_NOT_EXIST Aug 30 23:13:44 kernel: ACPI-1303: *** Error: Method execution failed [\_SB_.PCI0.LPC_.EC__.BSTA] (Node 0xc15e65c0), AE_NOT_EXIST Aug 30 23:13:44 kernel: ACPI-1303: *** Error: Method execution failed [\_SB_.PCI0.USB0.URTH.UNST._STA] (Node 0xc15e26e0), AE_NOT_EXIST Aug 30 23:13:44 kernel: ACPI-0239: *** Error: Method execution failed [\_SB_.PCI0.USB0.URTH.UNST._STA] (Node 0xc15e26e0), AE_NOT_EXIST Aug 30 23:13:44 kernel: ACPI-0358: *** Error: Region EmbeddedControl(3) has no handler Aug 30 23:13:44 kernel: ACPI-1303: *** Error: Method execution failed [\_SB_.PCI0.LPC_.EC__.BGID] (Node 0xc15e6560), AE_NOT_EXIST Aug 30 23:13:44 kernel: ACPI-1303: *** Error: Method execution failed [\_SB_.PCI0.LPC_.EC__.BINI] (Node 0xc15e6580), AE_NOT_EXIST Aug 30 23:13:44 kernel: ACPI-1303: *** Error: Method execution failed [\_SB_.PCI0.LPC_.EC__.BSTA] (Node 0xc15e65c0), AE_NOT_EXIST Aug 30 23:13:44 kernel: ACPI-1303: *** Error: Method execution failed [\_SB_.NEST._STA] (Node 0xc15e62e0), AE_NOT_EXIST Aug 30 23:13:44 kernel: ACPI-0239: *** Error: Method execution failed [\_SB_.NEST._STA] (Node 0xc15e62e0), AE_NOT_EXIST Aug 30 23:13:44 kernel: acpi0: Power Button (fixed) Aug 30 23:13:44 kernel: acpi_ec0: port 0x66,0x62 on acpi0 Aug 30 23:13:44 kernel: Timecounter "ACPI-fast" frequency 3579545 Hz quality 1000 Aug 30 23:13:44 kernel: acpi_timer0: <24-bit timer at 3.579545MHz> port 0x1008-0x100b on acpi0 Aug 30 23:13:44 kernel: cpu0: on acpi0 Aug 30 23:13:44 kernel: acpi_tz0: on acpi0 Aug 30 23:13:44 kernel: acpi_lid0: on acpi0 Aug 30 23:13:44 kernel: acpi_button0: on acpi0 Aug 30 23:13:44 kernel: pcib0: port 0xcf8-0xcff on acpi0 Aug 30 23:13:44 kernel: acpi link get: empty IRQ resource Aug 30 23:13:44 kernel: pci0: on pcib0 Aug 30 23:13:44 kernel: agp0: mem 0xe0000000-0xe3ffffff at device 0.0 on pci0 Aug 30 23:13:44 kernel: pcib1: at device 1.0 on pci0 Aug 30 23:13:44 kernel: pci1: on pcib1 Aug 30 23:13:44 kernel: pci1: at device 0.0 (no driver attached)Aug 30 23:13:44 kernel: uhci0: port 0x1800-0x181f irq 11 at device 29.0 on pci0 Aug 30 23:13:44 kernel: uhci0: [GIANT-LOCKED] Aug 30 23:13:44 kernel: usb0: on uhci0 Aug 30 23:13:44 kernel: usb0: USB revision 1.0 Aug 30 23:13:44 kernel: uhub0: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1 Aug 30 23:13:44 kernel: uhub0: 2 ports with 2 removable, self powered Aug 30 23:13:44 kernel: uhci1: port 0x1820-0x183f irq 11 at device 29.1 on pci0 Aug 30 23:13:44 kernel: uhci1: [GIANT-LOCKED] Aug 30 23:13:44 kernel: usb1: on uhci1 Aug 30 23:13:44 kernel: usb1: USB revision 1.0 Aug 30 23:13:44 kernel: uhub1: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1 Aug 30 23:13:44 kernel: uhub1: 2 ports with 2 removable, self powered Aug 30 23:13:44 kernel: uhci2: port 0x1840-0x185f irq 11 at device 29.2 on pci0 Aug 30 23:13:44 kernel: uhci2: [GIANT-LOCKED] Aug 30 23:13:44 kernel: usb2: on uhci2 Aug 30 23:13:44 kernel: usb2: USB revision 1.0 Aug 30 23:13:44 kernel: uhub2: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1 Aug 30 23:13:44 kernel: uhub2: 2 ports with 2 removable, self powered Aug 30 23:13:44 kernel: pcib2: at device 30.0 on pci0 Aug 30 23:13:44 kernel: pci2: on pcib2 Aug 30 23:13:44 kernel: cbb0: mem 0x50000000-0x50000fff irq 11 at device 0.0 on pci2 Aug 30 23:13:44 kernel: cardbus0: on cbb0 Aug 30 23:13:44 kernel: pccard0: <16-bit PCCard bus> on cbb0 Aug 30 23:13:44 kernel: cbb1: mem 0x51000000-0x51000fff irq 5 at device 0.1 on pci2 Aug 30 23:13:44 kernel: cardbus1: on cbb1 Aug 30 23:13:44 kernel: pccard1: <16-bit PCCard bus> on cbb1 Aug 30 23:13:44 kernel: wi0: mem 0xf8000000-0xf8000fff irq 11 at device 2.0 on pci2 Aug 30 23:13:44 kernel: wi0: [GIANT-LOCKED] Aug 30 23:13:44 kernel: wi0: using RF:PRISM2.5 MAC:ISL3874A(Mini-PCI) Aug 30 23:13:44 kernel: wi0: Intersil Firmware: Primary (1.1.0), Station (1.4.9) Aug 30 23:13:44 kernel: wi0: Ethernet address: 00:05:3c:04:7a:fc Aug 30 23:13:44 kernel: wi0: 11b rates: 1Mbps 2Mbps 5.5Mbps 11Mbps Aug 30 23:13:44 kernel: fxp0: port 0x8000-0x803f mem 0xd0200000-0xd0200fff irq 10 at device 8.0 on pci2 Aug 30 23:13:44 kernel: miibus0: on fxp0 Aug 30 23:13:44 kernel: inphy0: on miibus0 Aug 30 23:13:44 kernel: inphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto Aug 30 23:13:44 kernel: fxp0: Ethernet address: 00:09:6b:02:99:7f Aug 30 23:13:44 kernel: fxp0: [GIANT-LOCKED] Aug 30 23:13:44 kernel: isab0: at device 31.0 on pci0 Aug 30 23:13:44 kernel: isa0: on isab0 Aug 30 23:13:44 kernel: atapci0: port 0x1860-0x186f,0x376,0x170-0x177,0x3f6,0x1f0-0x1f7 at device 31.1 on pci0 Aug 30 23:13:44 kernel: ata0: channel #0 on atapci0 Aug 30 23:13:44 kernel: ata1: channel #1 on atapci0 Aug 30 23:13:44 kernel: pci0: at device 31.3 (no driver attached) Aug 30 23:13:44 kernel: pci0: at device 31.5 (no driver attached) Aug 30 23:13:44 kernel: pci0: at device 31.6 (no driver attached) Aug 30 23:13:44 kernel: atkbdc0: port 0x64,0x60 irq 1 on acpi0 Aug 30 23:13:44 kernel: atkbd0: irq 1 on atkbdc0 Aug 30 23:13:44 kernel: kbd0 at atkbd0 Aug 30 23:13:44 kernel: atkbd0: [GIANT-LOCKED] Aug 30 23:13:44 kernel: psm0: irq 12 on atkbdc0 Aug 30 23:13:44 kernel: psm0: [GIANT-LOCKED] Aug 30 23:13:44 kernel: psm0: model Generic PS/2 mouse, device ID 0 Aug 30 23:13:44 kernel: fdc0: port 0x3f7,0x3f0-0x3f5 irq 6 drq 2 on acpi0 Aug 30 23:13:44 kernel: sio0 port 0x3f8-0x3ff irq 4 on acpi0 Aug 30 23:13:44 kernel: sio0: type 16550A Aug 30 23:13:44 kernel: ppc0 port 0x3bc-0x3be irq 7 on acpi0 Aug 30 23:13:44 kernel: ppc0: Generic chipset (NIBBLE-only) in COMPATIBLE mode Aug 30 23:13:44 kernel: ppbus0: on ppc0 Aug 30 23:13:44 kernel: plip0: on ppbus0 Aug 30 23:13:44 kernel: lpt0: on ppbus0 Aug 30 23:13:44 kernel: lpt0: Interrupt-driven port Aug 30 23:13:44 kernel: ppi0: on ppbus0 Aug 30 23:13:44 kernel: sio1 port 0x2f8-0x2ff irq 3 drq 3 on acpi0 Aug 30 23:13:44 kernel: sio1: type 16550A Aug 30 23:13:44 kernel: acpi_cmbat0: on acpi0 Aug 30 23:13:44 kernel: acpi_acad0: on acpi0 Aug 30 23:13:44 kernel: orm0: at iomem 0xdc000-0xdffff,0xd1000-0xd1fff,0xd0000-0xd0fff on isa0 Aug 30 23:13:44 kernel: pmtimer0 on isa0 Aug 30 23:13:44 kernel: sc0: at flags 0x100 on isa0 Aug 30 23:13:44 kernel: sc0: VGA <16 virtual consoles, flags=0x300> Aug 30 23:13:44 kernel: vga0: at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0 Aug 30 23:13:44 kernel: Timecounter "TSC" frequency 1998312320 Hz quality 800 Aug 30 23:13:44 kernel: Timecounters tick every 10.000 msec Aug 30 23:13:44 kernel: acpi_cpu: throttling enabled, 8 steps (100% to 12.5%), currently 100.0% Aug 30 23:13:44 kernel: ad0: 38154MB [77520/16/63] at ata0-master UDMA100 Aug 30 23:13:44 kernel: ATAPI_RESET time = 170us Aug 30 23:13:44 kernel: ata1-slave: FAILURE - ATAPI_IDENTIFY timed out Aug 30 23:13:44 kernel: ATAPI_RESET time = 330us Aug 30 23:13:44 kernel: acd0: CDRW at ata1-master UDMA33 Aug 30 23:13:44 kernel: Mounting root from ufs:/dev/ad0s3a Aug 30 23:13:45 kernel: ad0: WARNING - READ_DMA interrupt was seen but timeout -- David E. Cross