From owner-freebsd-bugs@FreeBSD.ORG Thu Sep 30 12:50:25 2004 Return-Path: Delivered-To: freebsd-bugs@hub.freebsd.org Received: from mx1.FreeBSD.org (mx1.freebsd.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id 2037B16A4CE for ; Thu, 30 Sep 2004 12:50:25 +0000 (GMT) Received: from freefall.freebsd.org (freefall.freebsd.org [216.136.204.21]) by mx1.FreeBSD.org (Postfix) with ESMTP id D878A43D5E for ; Thu, 30 Sep 2004 12:50:24 +0000 (GMT) (envelope-from gnats@FreeBSD.org) Received: from freefall.freebsd.org (gnats@localhost [127.0.0.1]) by freefall.freebsd.org (8.12.11/8.12.11) with ESMTP id i8UCoO8w056857 for ; Thu, 30 Sep 2004 12:50:24 GMT (envelope-from gnats@freefall.freebsd.org) Received: (from gnats@localhost) by freefall.freebsd.org (8.12.11/8.12.11/Submit) id i8UCoO2V056856; Thu, 30 Sep 2004 12:50:24 GMT (envelope-from gnats) Resent-Date: Thu, 30 Sep 2004 12:50:24 GMT Resent-Message-Id: <200409301250.i8UCoO2V056856@freefall.freebsd.org> Resent-From: FreeBSD-gnats-submit@FreeBSD.org (GNATS Filer) Resent-To: freebsd-bugs@FreeBSD.org Resent-Reply-To: FreeBSD-gnats-submit@FreeBSD.org, Stephane Legrand Received: from mx1.FreeBSD.org (mx1.freebsd.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id E145216A4CE for ; Thu, 30 Sep 2004 12:43:02 +0000 (GMT) Received: from postfix3-1.free.fr (postfix3-1.free.fr [213.228.0.44]) by mx1.FreeBSD.org (Postfix) with ESMTP id DE8ED43D48 for ; Thu, 30 Sep 2004 12:43:01 +0000 (GMT) (envelope-from stephane@freebsd-fr.org) Received: from sequoia.mondomaineamoi.megalo (lns-vlq-17f-81-56-170-180.adsl.proxad.net [81.56.170.180]) by postfix3-1.free.fr (Postfix) with ESMTP id 01BE2173501 for ; Thu, 30 Sep 2004 14:42:59 +0200 (CEST) Received: from sequoia.mondomaineamoi.megalo (localhost.mondomaineamoi.megalo [127.0.0.1])i8UCgjND094430 for ; Thu, 30 Sep 2004 14:42:45 +0200 (CEST) (envelope-from stephane@sequoia.mondomaineamoi.megalo) Received: (from stephane@localhost)i8UCgjXV094429; Thu, 30 Sep 2004 14:42:45 +0200 (CEST) (envelope-from stephane) Message-Id: <200409301242.i8UCgjXV094429@sequoia.mondomaineamoi.megalo> Date: Thu, 30 Sep 2004 14:42:45 +0200 (CEST) From: Stephane Legrand To: FreeBSD-gnats-submit@FreeBSD.org X-Send-Pr-Version: 3.113 Subject: kern/72194: stack backtrace after wakeup from sleeping state X-BeenThere: freebsd-bugs@freebsd.org X-Mailman-Version: 2.1.1 Precedence: list Reply-To: Stephane Legrand List-Id: Bug reports List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Thu, 30 Sep 2004 12:50:25 -0000 >Number: 72194 >Category: kern >Synopsis: stack backtrace after wakeup from sleeping state >Confidential: no >Severity: non-critical >Priority: low >Responsible: freebsd-bugs >State: open >Quarter: >Keywords: >Date-Required: >Class: sw-bug >Submitter-Id: current-users >Arrival-Date: Thu Sep 30 12:50:23 GMT 2004 >Closed-Date: >Last-Modified: >Originator: Stephane Legrand >Release: FreeBSD 5.3-BETA6 i386 >Organization: >Environment: System: FreeBSD 5.3-BETA6 #6: Sun Sep 26 19:22:55 CEST 2004 Motherboard: ASUS P2B # dmesg | grep -i ata atapci0: port 0xd800-0xd80f,0x376,0x170-0x177,0x3f6,0x1f0-0x1f7 at device 4.1 on pci0 ata0: channel #0 on atapci0 ata1: channel #1 on atapci0 atapci1: port 0xa400-0xa40f,0xa800-0xa803,0xb000-0xb007,0xb400-0xb403,0xb800-0xb807 mem 0xe1800000-0xe1803fff irq 12 at device 10.0 on pci0 ata2: channel #0 on atapci1 ata3: channel #1 on atapci1 ata4: at port 0x36e-0x36f,0x168-0x16f irq 10 on isa0 ad0: 1226MB [2492/16/63] at ata0-master WDMA2 ATAPI_RESET time = 110us acd0: CDRW at ata1-master UDMA33 ad4: 117800MB [239340/16/63] at ata2-master UDMA100 ad6: 12949MB [26310/16/63] at ata3-master UDMA66 ATAPI_RESET time = 110us # atacontrol list ATA channel 0: Master: ad0 ATA/ATAPI revision 0 Slave: no device present ATA channel 1: Master: acd0 ATA/ATAPI revision 6 Slave: no device present ATA channel 2: Master: ad4 ATA/ATAPI revision 6 Slave: no device present ATA channel 3: Master: ad6 ATA/ATAPI revision 4 Slave: no device present ATA channel 4: Master: no device present Slave: no device present >Description: After waking up my system from standby mode, i noticed the following message in /var/log/messages: Sep 30 11:13:14 sequoia kernel: wakeup from sleeping state (slept 10:44:04) Sep 30 11:13:14 sequoia kernel: sio0: 1 more silo overflow (total 3) Sep 30 11:13:14 sequoia kernel: ATAPI_RESET time = 110us Sep 30 11:16:38 sequoia kernel: KDB: stack backtrace: Sep 30 11:16:38 sequoia kernel: kdb_backtrace(c06d0578,2,c71d30d4,0,22) at kdb_backtrace+0x2e Sep 30 11:16:38 sequoia kernel: getdirtybuf(d2656bbc,0,1,c71d30d4,1) at getdirtybuf+0x2b Sep 30 11:16:38 sequoia kernel: flush_deplist(c21dbb4c,1,d2656be4,d2656be8,0) at flush_deplist+0x57 Sep 30 11:16:38 sequoia kernel: flush_inodedep_deps(c170f000,5c86,c1d0d820,c1cca840,c1cca840) at flush_in odedep_deps+0x9e Sep 30 11:16:38 sequoia kernel: softdep_sync_metadata(d2656ca8,0,c06a2e84,122,d2656c7c) at softdep_sync_m etadata+0x9d Sep 30 11:16:38 sequoia kernel: ffs_fsync(d2656ca8,0,c069bd0a,bfe,0) at ffs_fsync+0x3e9 Sep 30 11:16:38 sequoia kernel: fsync(c1d0d820,d2656d14,c06ab2a9,3df,1) at fsync+0x181 Sep 30 11:16:38 sequoia kernel: syscall(2f,bfbf002f,d265002f,bfbfac80,bfbfac78) at syscall+0x2a0 Sep 30 11:16:38 sequoia kernel: Xint0x80_syscall() at Xint0x80_syscall+0x1f Sep 30 11:16:38 sequoia kernel: --- syscall (95, FreeBSD ELF32, fsync), eip = 0x282bed2f, esp = 0xbfbfac4 c, ebp = 0xbfbfb538 --- Sep 30 11:16:40 sequoia kernel: KDB: stack backtrace: Sep 30 11:16:40 sequoia kernel: kdb_backtrace(c06d0578,2,c7122088,0,22) at kdb_backtrace+0x2e Sep 30 11:16:40 sequoia kernel: getdirtybuf(d2656bbc,0,1,c7122088,1) at getdirtybuf+0x2b Sep 30 11:16:40 sequoia kernel: flush_deplist(c24a18cc,1,d2656be4,d2656be8,0) at flush_deplist+0x57 Sep 30 11:16:40 sequoia kernel: flush_inodedep_deps(c170f000,5c89,8,c06a2e84,c1f06108) at flush_inodedep_ deps+0x9e Sep 30 11:16:40 sequoia kernel: softdep_sync_metadata(d2656ca8,0,c06a2e84,122,0) at softdep_sync_metadata +0x9d Sep 30 11:16:40 sequoia kernel: ffs_fsync(d2656ca8,0,c069bd0a,bfe,0) at ffs_fsync+0x3e9 Sep 30 11:16:40 sequoia kernel: fsync(c1d0d820,d2656d14,c06ab2a9,3df,1) at fsync+0x181 Sep 30 11:16:40 sequoia kernel: syscall(2f,bfbf002f,d265002f,bfbfac80,bfbfac78) at syscall+0x2a0 Sep 30 11:16:40 sequoia kernel: Xint0x80_syscall() at Xint0x80_syscall+0x1f Sep 30 11:16:40 sequoia kernel: --- syscall (95, FreeBSD ELF32, fsync), eip = 0x282bed2f, esp = 0xbfbfac4 c, ebp = 0xbfbfb538 --- Sep 30 11:16:44 sequoia kernel: KDB: stack backtrace: Sep 30 11:16:44 sequoia kernel: kdb_backtrace(c06d0578,2,c7122088,0,22) at kdb_backtrace+0x2e Sep 30 11:16:44 sequoia kernel: getdirtybuf(d2656bbc,0,1,c7122088,1) at getdirtybuf+0x2b Sep 30 11:16:44 sequoia kernel: flush_deplist(c1c08a4c,1,d2656be4,d2656be8,0) at flush_deplist+0x57 Sep 30 11:16:44 sequoia kernel: flush_inodedep_deps(c170f000,5c92,c1d0d820,c1e91840,c1e91840) at flush_in odedep_deps+0x9e Sep 30 11:16:44 sequoia kernel: softdep_sync_metadata(d2656ca8,0,c06a2e84,122,d2656c7c) at softdep_sync_m etadata+0x9d Sep 30 11:16:44 sequoia kernel: ffs_fsync(d2656ca8,0,c069bd0a,bfe,0) at ffs_fsync+0x3e9 Sep 30 11:16:44 sequoia kernel: fsync(c1d0d820,d2656d14,c06ab2a9,3df,1) at fsync+0x181 Sep 30 11:16:44 sequoia kernel: syscall(2f,bfbf002f,d265002f,bfbfac80,bfbfac78) at syscall+0x2a0 Sep 30 11:16:44 sequoia kernel: Xint0x80_syscall() at Xint0x80_syscall+0x1f Sep 30 11:16:44 sequoia kernel: --- syscall (95, FreeBSD ELF32, fsync), eip = 0x282bed2f, esp = 0xbfbfac4 c, ebp = 0xbfbfb538 --- Note that my system works perfectly fine (no panic, no reboot) despite these messages so i'm not even sure this log can show some kind of bug. I use the following script to put my system in standby mode (ataidle comes from the port sysutils/ataidle): apm -e 1; sync;sync;sync; ataidle -s 3 0; ataidle -s 2 0; sleep 2; apm -Z; This is the first time i have such a backtrace. But i also noticed i've sometimes an ATA timeout after waking: Sep 29 11:31:16 sequoia kernel: wakeup from sleeping state (slept 09:44:23) Sep 29 11:31:16 sequoia kernel: sio0: 1 more silo overflow (total 2) Sep 29 11:31:22 sequoia kernel: ATAPI_RESET time = 110us Sep 29 11:31:22 sequoia kernel: ad4: TIMEOUT - WRITE_DMA retrying (2 retries left) LBA=225685383 >How-To-Repeat: Put the system in standby mode and wake it up. This doesn't happen at each waking though. >Fix: >Release-Note: >Audit-Trail: >Unformatted: