Date: Thu, 30 Sep 2004 14:42:45 +0200 (CEST) From: Stephane Legrand <stephane@FreeBSD.org> To: FreeBSD-gnats-submit@FreeBSD.org Subject: kern/72194: stack backtrace after wakeup from sleeping state Message-ID: <200409301242.i8UCgjXV094429@sequoia.mondomaineamoi.megalo> Resent-Message-ID: <200409301250.i8UCoO2V056856@freefall.freebsd.org>
next in thread | raw e-mail | index | archive | help
>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: <Intel PIIX4 UDMA33 controller> 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: <Promise PDC20268 UDMA100 controller> 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: <Generic ESDI/IDE/ATA controller> at port 0x36e-0x36f,0x168-0x16f irq 10 on isa0
ad0: 1226MB <QUANTUM BIGFOOT1280A/A01.03> [2492/16/63] at ata0-master WDMA2
ATAPI_RESET time = 110us
acd0: CDRW <HL-DT-ST GCE-8526B/1.02> at ata1-master UDMA33
ad4: 117800MB <HDS722512VLAT20/V33OA63A> [239340/16/63] at ata2-master UDMA100
ad6: 12949MB <IBM-DJNA-371350/J76OA30K> [26310/16/63] at ata3-master UDMA66
ATAPI_RESET time = 110us
# atacontrol list
ATA channel 0:
Master: ad0 <QUANTUM BIGFOOT1280A/A01.03> ATA/ATAPI revision 0
Slave: no device present
ATA channel 1:
Master: acd0 <HL-DT-ST GCE-8526B/1.02> ATA/ATAPI revision 6
Slave: no device present
ATA channel 2:
Master: ad4 <HDS722512VLAT20/V33OA63A> ATA/ATAPI revision 6
Slave: no device present
ATA channel 3:
Master: ad6 <IBM-DJNA-371350/J76OA30K> 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:
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?200409301242.i8UCgjXV094429>