From owner-freebsd-mobile@FreeBSD.ORG Tue Jan 24 04:14:01 2006 Return-Path: X-Original-To: freebsd-mobile@freebsd.org Delivered-To: freebsd-mobile@freebsd.org Received: from mx1.FreeBSD.org (mx1.freebsd.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id 755DA16A4CB for ; Tue, 24 Jan 2006 04:13:58 +0000 (GMT) (envelope-from drgerlists@gmail.com) Received: from zproxy.gmail.com (zproxy.gmail.com [64.233.162.197]) by mx1.FreeBSD.org (Postfix) with ESMTP id C10DE43E5F for ; Tue, 24 Jan 2006 03:41:42 +0000 (GMT) (envelope-from drgerlists@gmail.com) Received: by zproxy.gmail.com with SMTP id 9so1051514nzo for ; Mon, 23 Jan 2006 19:41:42 -0800 (PST) DomainKey-Signature: a=rsa-sha1; q=dns; c=nofws; s=beta; d=gmail.com; h=received:date:from:organization:reply-to:to:subject:message-id:user-agent:mime-version:content-type:content-transfer-encoding; b=aoVZ4ObZGT8aH5alZ0x6Yffgms0FgzS7pis7bHjamC7StuRMmtzFcb7NWEbiaue3iR/udyYgieeYpUwn3CQ+tukbucfRra3oEYrgpiuV8rQbuz+/DIzbDL+18vQDs/RmJwCDnCxeAFj1Ig2sO7DO6XmP3WeuzR/Nf/4Qur57/Qs= Received: by 10.36.101.4 with SMTP id y4mr4348469nzb; Mon, 23 Jan 2006 19:41:42 -0800 (PST) Received: from lmrmac.uhw.utoledo.edu ( [131.183.85.137]) by mx.gmail.com with ESMTP id 39sm1269719nzk.2006.01.23.19.41.41; Mon, 23 Jan 2006 19:41:41 -0800 (PST) Date: Mon, 23 Jan 2006 22:41:40 -0500 From: drgerlists@gmail.com (Dr. Gary E. RAFE) Organization: Dr. Gary E. RAFE To: freebsd-mobile@freebsd.org Message-ID: <43d5a1f4.EU4xdsl9oE8JEkxMUMkwHEdR@lmrmac.uhw.utoledo.edu> User-Agent: nail 11.25 7/29/05 MIME-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Transfer-Encoding: 7bit Subject: ad0: TIMEOUT - READ_DMA retrying ... on a 5.4-RELEASE system X-BeenThere: freebsd-mobile@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list Reply-To: drgerlists@gmail.com List-Id: Mobile computing with FreeBSD List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Tue, 24 Jan 2006 04:14:04 -0000 I had a bit of a scare last week when my Toshiba Satellite Pro 6100 running 5.4-RELEASE (since May 2005 without any major difficulties) resumed from an APM suspend with some odd disk behavior, and the rather scary error message: "ad0: TIMEOUT - READ_DMA retrying (2 retries left) LBA=12578911". Relevent messages from /var/log/message follow: [Power button actuated following overnight APM suspend] Jan 17 09:16:56 MyHost kernel: pir0: ROUTE_INTERRUPT on resume for link 0x62 failed. Jan 17 09:16:56 MyHost kernel: pir0: ROUTE_INTERRUPT on resume for link 0x61 failed. Jan 17 09:16:56 MyHost kernel: pir0: ROUTE_INTERRUPT on resume for link 0x63 failed. Jan 17 09:16:56 MyHost kernel: wakeup from sleeping state (slept 08:22:31) Jan 17 09:16:55 MyHost apmd[324]: apmevent 0003 index 24 Jan 17 09:17:05 MyHost kernel: ad0: TIMEOUT - READ_DMA retrying (2 retries left) LBA=12578911 Jan 17 09:17:05 MyHost apmd: resumed at 20060117 09:17:05 Jan 17 09:17:05 MyHost kernel: uhci0: port 0xefe0-0xefff irq 11 at device 29.0 on pci0 Jan 17 09:17:05 MyHost kernel: usb0: on uhci0 Jan 17 09:17:05 MyHost kernel: usb0: USB revision 1.0 Jan 17 09:17:05 MyHost kernel: uhub0: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1 Jan 17 09:17:05 MyHost kernel: uhub0: 2 ports with 2 removable, self powered Jan 17 09:17:06 MyHost kernel: uhci1: port 0xef80-0xef9f irq 11 at device 29.1 on pci0 Jan 17 09:17:06 MyHost kernel: usb1: on uhci1 Jan 17 09:17:06 MyHost kernel: usb1: USB revision 1.0 Jan 17 09:17:06 MyHost kernel: uhub1: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1 Jan 17 09:17:06 MyHost kernel: uhub1: 2 ports with 2 removable, self powered Jan 17 09:17:06 MyHost kernel: uhci2: port 0xef60-0xef7f irq 11 at device 29.2 on pci0 Jan 17 09:17:06 MyHost kernel: usb2: on uhci2 Jan 17 09:17:06 MyHost kernel: usb2: USB revision 1.0 Jan 17 09:17:06 MyHost kernel: uhub2: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1 Jan 17 09:17:06 MyHost kernel: uhub2: 2 ports with 2 removable, self powered Jan 17 09:17:08 MyHost kernel: uhub3: Atmel Standard USB Hub, class 9/0, rev 1.10/3.00, addr 2 Jan 17 09:17:08 MyHost kernel: uhub3: 4 ports with 4 removable, bus powered Jan 17 09:17:09 MyHost kernel: ums0: Logitech USB-PS/2 Optical Mouse, rev 2.00/11.10, addr 3, iclass 3/1 Jan 17 09:17:09 MyHost kernel: ums0: 3 buttons and Z dir. Jan 17 09:17:24 MyHost kernel: ad0: TIMEOUT - READ_DMA retrying (2 retries left) LBA=52265546 Jan 17 09:17:24 MyHost kernel: ad0: TIMEOUT - READ_DMA retrying (1 retry left) LBA=52265546 Jan 17 09:17:24 MyHost kernel: ad0: FAILURE - READ_DMA timed out Jan 17 09:18:56 MyHost kernel: ad0: TIMEOUT - READ_DMA retrying (2 retries left) LBA=53769542 Jan 17 09:19:06 MyHost kernel: ad0: TIMEOUT - READ_DMA retrying (1 retry left) LBA=53769542 Jan 17 09:19:06 MyHost kernel: ad0: FAILURE - READ_DMA timed out ... When the system was "stable", I ran "shutdown -p now" for a hard boot. Subsequent /var/log/messages from this boot follow: Jan 17 09:21:34 MyHost syslogd: kernel boot file is /boot/kernel/kernel Jan 17 09:21:34 MyHost kernel: Copyright (c) 1992-2005 The FreeBSD Project. Jan 17 09:21:34 MyHost kernel: Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994 Jan 17 09:21:34 MyHost kernel: The Regents of the University of California. All rights reserved. Jan 17 09:21:34 MyHost kernel: FreeBSD 5.4-RELEASE #6: Sat Dec 31 22:59:00 EST 2005 Jan 17 09:21:34 MyHost kernel: root@MyHost:/usr/src/sys/i386/compile/TSP6100B Jan 17 09:21:34 MyHost kernel: Timecounter "i8254" frequency 1193182 Hz quality 0 Jan 17 09:21:34 MyHost kernel: CPU: Intel(R) Pentium(R) 4 Mobile CPU 2.00GHz (1993.54-MHz 686-class CPU) Jan 17 09:21:34 MyHost kernel: Origin = "GenuineIntel" Id = 0xf24 Stepping = 4 Jan 17 09:21:34 MyHost kernel: Features=0x3febf9ff Jan 17 09:21:34 MyHost kernel: real memory = 536084480 (511 MB) Jan 17 09:21:34 MyHost kernel: avail memory = 519127040 (495 MB) Jan 17 09:21:34 MyHost kernel: npx0: on motherboard Jan 17 09:21:34 MyHost kernel: npx0: INT 16 interface Jan 17 09:21:34 MyHost kernel: cpu0 on motherboard Jan 17 09:21:34 MyHost kernel: apm0: on motherboard Jan 17 09:21:34 MyHost kernel: apm0: found APM BIOS v1.2, connected at v1.2 Jan 17 09:21:34 MyHost kernel: pcib0: pcibus 0 on motherboard Jan 17 09:21:34 MyHost kernel: pir0: on motherboard Jan 17 09:21:34 MyHost kernel: pci0: on pcib0 Jan 17 09:21:34 MyHost kernel: pcib1: at device 1.0 on pci0 Jan 17 09:21:34 MyHost kernel: pci1: on pcib1 Jan 17 09:21:34 MyHost kernel: pci1: at device 0.0 (no driver attached) Jan 17 09:21:34 MyHost kernel: pci0: at device 29.0 (no driver attached) Jan 17 09:21:34 MyHost kernel: pci0: at device 29.1 (no driver attached) Jan 17 09:21:34 MyHost kernel: pci0: at device 29.2 (no driver attached) Jan 17 09:21:34 MyHost kernel: pcib2: at device 30.0 on pci0 Jan 17 09:21:34 MyHost kernel: pci2: on pcib2 Jan 17 09:21:34 MyHost kernel: fxp0: port 0xdf40-0xdf7f mem 0xfceff000-0xfcefffff irq 11 at device 8.0 on pci2 Jan 17 09:21:34 MyHost kernel: miibus0: on fxp0 Jan 17 09:21:34 MyHost kernel: inphy0: on miibus0 Jan 17 09:21:34 MyHost kernel: inphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto Jan 17 09:21:34 MyHost kernel: fxp0: Ethernet address: 00:00:39:xx:xx:xx Jan 17 09:21:34 MyHost kernel: wi0: mem 0xdbdff000-0xdbdfffff irq 11 at device 10.0 on pci2 Jan 17 09:21:34 MyHost kernel: wi0: using RF:PRISM2.5 MAC:ISL3874A(Mini-PCI) Jan 17 09:21:34 MyHost kernel: wi0: Intersil Firmware: Primary (1.1.0), Station (1.4.9) Jan 17 09:21:34 MyHost kernel: wi0: Ethernet address: 00:02:6f:xx:xx:xx Jan 17 09:21:34 MyHost kernel: wi0: 11b rates: 1Mbps 2Mbps 5.5Mbps 11Mbps Jan 17 09:21:34 MyHost kernel: cbb0: irq 11 at device 11.0 on pci2 Jan 17 09:21:34 MyHost kernel: cardbus0: on cbb0 Jan 17 09:21:34 MyHost kernel: pccard0: <16-bit PCCard bus> on cbb0 Jan 17 09:21:34 MyHost kernel: cbb1: irq 11 at device 11.1 on pci2 Jan 17 09:21:34 MyHost kernel: cardbus1: on cbb1 Jan 17 09:21:34 MyHost kernel: pccard1: <16-bit PCCard bus> on cbb1 Jan 17 09:21:34 MyHost kernel: pci2: at device 13.0 (no driver attached) Jan 17 09:21:34 MyHost kernel: isab0: at device 31.0 on pci0 Jan 17 09:21:34 MyHost kernel: isa0: on isab0 Jan 17 09:21:34 MyHost kernel: atapci0: port 0xcfa0-0xcfaf,0x376,0x170-0x177,0x3f6,0x1f0-0x1f7 irq 11 at device 31.1 on pci0 Jan 17 09:21:34 MyHost kernel: ata0: channel #0 on atapci0 Jan 17 09:21:34 MyHost kernel: ata1: channel #1 on atapci0 Jan 17 09:21:34 MyHost kernel: pcm0: port 0xcdc0-0xcdff,0xce00-0xceff irq 11 at device 31.5 on pci0 Jan 17 09:21:34 MyHost kernel: pcm0: Jan 17 09:21:34 MyHost kernel: pci0: at device 31.6 (no driver attached) Jan 17 09:21:34 MyHost kernel: orm0: at iomem 0xe0000-0xeffff,0xc0000-0xcffff on isa0 Jan 17 09:21:34 MyHost kernel: pmtimer0 on isa0 Jan 17 09:21:34 MyHost kernel: atkbdc0: at port 0x64,0x60 on isa0 Jan 17 09:21:34 MyHost kernel: atkbd0: irq 1 on atkbdc0 Jan 17 09:21:34 MyHost kernel: kbd0 at atkbd0 Jan 17 09:21:34 MyHost kernel: psm0: irq 12 on atkbdc0 Jan 17 09:21:34 MyHost kernel: psm0: model IntelliMouse, device ID 3 Jan 17 09:21:34 MyHost kernel: sc0: at flags 0x100 on isa0 Jan 17 09:21:34 MyHost kernel: sc0: VGA <16 virtual consoles, flags=0x300> Jan 17 09:21:34 MyHost kernel: sio0 at port 0x3f8-0x3ff irq 4 flags 0x10 on isa0 Jan 17 09:21:34 MyHost kernel: sio0: type 16550A Jan 17 09:21:34 MyHost kernel: vga0: at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0 Jan 17 09:21:34 MyHost kernel: unknown: can't assign resources (memory) Jan 17 09:21:34 MyHost kernel: speaker0: at port 0x61 on isa0 Jan 17 09:21:34 MyHost kernel: unknown: can't assign resources (port) Jan 17 09:21:34 MyHost kernel: unknown: can't assign resources (irq) Jan 17 09:21:34 MyHost kernel: unknown: can't assign resources (port) Jan 17 09:21:34 MyHost kernel: Timecounters tick every 10.000 msec Jan 17 09:21:34 MyHost kernel: ad0: 57231MB [116280/16/63] at ata0-master UDMA100 Jan 17 09:21:34 MyHost kernel: acd0: CDRW at ata1-master PIO4 Jan 17 09:21:34 MyHost kernel: cd0 at ata1 bus 0 target 0 lun 0 Jan 17 09:21:34 MyHost kernel: cd0: Removable CD-ROM SCSI-0 device Jan 17 09:21:34 MyHost kernel: cd0: 16.000MB/s transfers Jan 17 09:21:34 MyHost kernel: cd0: Attempt to query device size failed: NOT READY, Medium not present Jan 17 09:21:34 MyHost kernel: Mounting root from ufs:/dev/ad0s3a Jan 17 09:21:35 MyHost apmd[319]: start Jan 17 09:21:36 MyHost kernel: uhci0: port 0xefe0-0xefff irq 11 at device 29.0 on pci0 Jan 17 09:21:36 MyHost kernel: usb0: on uhci0 Jan 17 09:21:36 MyHost kernel: usb0: USB revision 1.0 Jan 17 09:21:36 MyHost kernel: uhub0: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1 Jan 17 09:21:36 MyHost kernel: uhub0: 2 ports with 2 removable, self powered Jan 17 09:21:37 MyHost kernel: uhci1: port 0xef80-0xef9f irq 11 at device 29.1 on pci0 Jan 17 09:21:37 MyHost kernel: usb1: on uhci1 Jan 17 09:21:37 MyHost kernel: usb1: USB revision 1.0 Jan 17 09:21:37 MyHost kernel: uhub1: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1 Jan 17 09:21:37 MyHost kernel: uhub1: 2 ports with 2 removable, self powered Jan 17 09:21:37 MyHost kernel: uhci2: port 0xef60-0xef7f irq 11 at device 29.2 on pci0 Jan 17 09:21:37 MyHost kernel: usb2: on uhci2 Jan 17 09:21:37 MyHost kernel: usb2: USB revision 1.0 Jan 17 09:21:37 MyHost kernel: uhub2: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1 Jan 17 09:21:37 MyHost kernel: uhub2: 2 ports with 2 removable, self powered Jan 17 09:21:39 MyHost kernel: uhub3: Atmel Standard USB Hub, class 9/0, rev 1.10/3.00, addr 2 Jan 17 09:21:39 MyHost kernel: uhub3: 4 ports with 4 removable, bus powered Jan 17 09:21:40 MyHost kernel: ums0: Logitech USB-PS/2 Optical Mouse, rev 2.00/11.10, addr 3, iclass 3/1 Jan 17 09:21:40 MyHost kernel: ums0: 3 buttons and Z dir. Jan 17 09:22:12 MyHost kernel: ad0: TIMEOUT - READ_DMA retrying (2 retries left) LBA=50817898 Jan 17 09:22:36 MyHost kernel: ad0: TIMEOUT - READ_DMA retrying (2 retries left) LBA=50819690 Jan 17 09:22:36 MyHost kernel: ad0: TIMEOUT - READ_DMA retrying (1 retry left) LBA=50817898 Jan 17 09:22:36 MyHost kernel: ad0: TIMEOUT - READ_DMA retrying (1 retry left) LBA=50819690 Jan 17 09:22:36 MyHost kernel: ad0: FAILURE - READ_DMA timed out Jan 17 09:22:43 MyHost kernel: ad0: TIMEOUT - READ_DMA retrying (2 retries left) LBA=50807210 Jan 17 09:22:53 MyHost kernel: ad0: TIMEOUT - READ_DMA retrying (2 retries left) LBA=50803050 Jan 17 09:23:02 MyHost kernel: ad0: TIMEOUT - READ_DMA retrying (2 retries left) LBA=50803082 Jan 17 09:23:22 MyHost kernel: ad0: TIMEOUT - READ_DMA retrying (2 retries left) LBA=54088010 Jan 17 09:23:32 MyHost kernel: ad0: TIMEOUT - READ_DMA retrying (1 retry left) LBA=54088010 Jan 17 09:23:32 MyHost kernel: ad0: FAILURE - READ_DMA timed out Jan 17 09:23:39 MyHost kernel: ad0: TIMEOUT - READ_DMA retrying (2 retries left) LBA=54088130 Jan 17 09:23:44 MyHost kernel: ad0: TIMEOUT - READ_DMA retrying (2 retries left) LBA=54087850 Jan 17 09:24:08 MyHost login: ROOT LOGIN (root) ON ttyv0 Jan 17 09:28:32 MyHost kernel: ad0: TIMEOUT - READ_DMA retrying (2 retries left) LBA=99169729 [system suspended here with "apm -z"] The "ad0: TIMEOUT - READ_DMA retrying" errors came back following a reboot later that evening. Since then, the system has behaved more-or-less flawlessly, as though nothing had happened... I'm at a loss to explain what might have happened. If anyone can offer any insight into this (sporadic hiccough, impending hardware failure, etc.), I, and possibly other list readers, will be grateful. -- Dr Gary E RAFE: drgerlists at gmail dot com