kern/72194: stack backtrace after wakeup from sleeping state

Stephane Legrand stephane at FreeBSD.org
Thu Sep 30 05:50:25 PDT 2004


>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:


More information about the freebsd-bugs mailing list