From owner-freebsd-current  Wed Jul 24 19:15:57 2002
Delivered-To: freebsd-current@freebsd.org
Received: from mx1.FreeBSD.org (mx1.FreeBSD.org [216.136.204.125])
	by hub.freebsd.org (Postfix) with ESMTP id DAB7437B400
	for <current@freebsd.org>; Wed, 24 Jul 2002 19:15:46 -0700 (PDT)
Received: from scaup.mail.pas.earthlink.net (scaup.mail.pas.earthlink.net [207.217.120.49])
	by mx1.FreeBSD.org (Postfix) with ESMTP id 3AC2D43E6A
	for <current@freebsd.org>; Wed, 24 Jul 2002 19:15:46 -0700 (PDT)
	(envelope-from cd5697@albany.edu)
Received: from dhcp160-88-151-24.wd01-c3.cpe.charter-ne.com ([24.151.88.160] helo=ftp.crustpunk.org)
	by scaup.mail.pas.earthlink.net with esmtp (Exim 3.33 #1)
	id 17XYAL-0004Kb-00
	for current@freebsd.org; Wed, 24 Jul 2002 19:15:45 -0700
Received: from craig by ftp.crustpunk.org with local (Exim 3.35 #1 (Debian))
	id 17XYpv-0004Du-00
	for <current@FreeBSD.org>; Wed, 24 Jul 2002 21:58:43 -0500
Date: Wed, 24 Jul 2002 21:58:43 -0500
To: current@FreeBSD.org
Subject: Re: Here's my most recent crash..
Message-ID: <20020725025843.GA16221@ftp.crustpunk.org>
References: <20020724234939.GA422@blarf.homeip.net>
Mime-Version: 1.0
Content-Type: text/plain; charset=us-ascii
Content-Disposition: inline
In-Reply-To: <20020724234939.GA422@blarf.homeip.net>
User-Agent: Mutt/1.4i
From: Craig Dooley <cd5697@albany.edu>
Sender: owner-freebsd-current@FreeBSD.ORG
Precedence: bulk
List-ID: <freebsd-current.FreeBSD.ORG>
List-Archive: <http://docs.freebsd.org/mail/> (Web Archive)
List-Help: <mailto:majordomo@FreeBSD.ORG?subject=help> (List Instructions)
List-Subscribe: <mailto:majordomo@FreeBSD.ORG?subject=subscribe%20freebsd-current>
List-Unsubscribe: <mailto:majordomo@FreeBSD.ORG?subject=unsubscribe%20freebsd-current>
X-Loop: FreeBSD.ORG

I have the same problem.  heres my debug dump.  It seems to happen to me
always when sound is playing.  I also get pages of warnings about pcm
and could sleep with lock.

/usr/src/sys/vm/uma_core.c:1332: could sleep with "pcm0:play:0" locked from /usr/src/sys/dev/sound/pcm/dsp.c:690
/usr/src/sys/vm/uma_core.c:1332: could sleep with "pcm0:play:0" locked from /usr/src/sys/dev/sound/pcm/dsp.c:713
/usr/src/sys/vm/uma_core.c:1332: could sleep with "pcm0:play:0" locked from /usr/src/sys/dev/sound/pcm/sound.c:191
and so on. actually it's flooded dmesg enough that I cant get to any
useful information without going back a couple message.x's.  buildworld
and kernel built from yesterdays sources.  

dmesg:
Jul 24 18:09:49 filth kernel: FreeBSD 5.0-CURRENT #0: Tue Jul 16 22:17:19 EDT 2002
Jul 24 18:09:50 filth kernel: root@:/usr/obj/usr/src/sys/CUSTOM
Jul 24 18:09:50 filth kernel: Preloaded elf kernel "/boot/kernel/kernel" at 0xc04d3000.
Jul 24 18:09:50 filth kernel: Preloaded elf module "/boot/kernel/acpi.ko" at 0xc04d30a8.
Jul 24 18:09:50 filth kernel: Timecounter "i8254"  frequency 1193182 Hz
Jul 24 18:09:50 filth kernel: Timecounter "TSC"  frequency 1000040473 Hz
Jul 24 18:09:50 filth kernel: CPU: AMD Athlon(tm) Processor (1000.04-MHz 686-class CPU)
Jul 24 18:09:50 filth kernel: Origin = "AuthenticAMD"  Id = 0x642 Stepping = 2
Jul 24 18:09:50 filth kernel: Features=0x183f9ff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE, CX8,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,MMX,FXSR> Jul 24 18:09:50 filth kernel: AMD Features=0xffffffffc0440000<RSVD,AMIE,DSP,3DNow!>
Jul 24 18:09:50 filth kernel: real memory  = 536805376 (524224K bytes)
Jul 24 18:09:50 filth kernel: avail memory = 515616768 (503532K bytes)
Jul 24 18:09:50 filth kernel: Pentium Pro MTRR support enabled
Jul 24 18:09:50 filth kernel: Using $PIR table, 9 entries at 0xc00fde50
Jul 24 18:09:50 filth kernel: npx0: <math processor> on motherboard
Jul 24 18:09:50 filth kernel: npx0: INT 16 interface
Jul 24 18:09:50 filth kernel: acpi0: <VT8371 AWRDACPI> on motherboard
Jul 24 18:09:50 filth kernel: acpi0: power button is handled as a fixed feature programming model.
Jul 24 18:09:50 filth kernel: Timecounter "ACPI-fast"  frequency 3579545 Hz
Jul 24 18:09:50 filth kernel: acpi_timer0: <24-bit timer at 3.579545MHz> port 0x 4008-0x400b on acpi0
Jul 24 18:09:50 filth kernel: acpi_cpu0: <CPU> on acpi0
Jul 24 18:09:50 filth kernel: acpi_button0: <Power Button> on acpi0
Jul 24 18:09:50 filth kernel: acpi_button1: <Sleep Button> on acpi0
Jul 24 18:09:50 filth kernel: acpi_pcib0: <Host-PCI bridge> port 0x6000-0x607f,0 x5000-0x500f,0x4080-0x40ff,0x4000-0x407f,0xcf8-0xcff on acpi0
Jul 24 18:09:50 filth kernel: pci0: <PCI bus> on acpi_pcib0
Jul 24 18:09:50 filth kernel: pcib1: <PCI-PCI bridge> at device 1.0 on pci0
Jul 24 18:09:50 filth kernel: pci1: <PCI bus> on pcib1
Jul 24 18:09:50 filth kernel: pci1: <display, VGA> at device 0.0 (no driver attached)
Jul 24 18:09:50 filth kernel: isab0: <PCI-ISA bridge> at device 7.0 on pci0
Jul 24 18:09:50 filth kernel: isa0: <ISA bus> on isab0
Jul 24 18:09:50 filth kernel: atapci0: <VIA 82C686 ATA100 controller> port 0xd00 0-0xd00f at device 7.1 on pci0
Jul 24 18:09:50 filth kernel: atapci0: Correcting VIA config for southbridge data corruption bug
Jul 24 18:09:50 filth kernel: ata0: at 0x1f0 irq 14 on atapci0
Jul 24 18:09:50 filth kernel: ata1: at 0x170 irq 15 on atapci0
Jul 24 18:09:50 filth kernel: uhci0: <VIA 83C572 USB controller> port 0xd400-0xd41f irq 5 at device 7.2 on pci0
Jul 24 18:09:50 filth kernel: usb0: <VIA 83C572 USB controller> on uhci0
Jul 24 18:09:50 filth kernel: usb0: USB revision 1.0
Jul 24 18:09:50 filth kernel: uhub0: VIA UHCI root hub, class 9/0, rev 1.00/1.00, addr 1
Jul 24 18:09:50 filth kernel: uhub0: 2 ports with 2 removable, self powered
Jul 24 18:09:50 filth kernel: uhub0: port error, restarting port 1
Jul 24 18:09:50 filth kernel: uhub0: port error, giving up port 1
Jul 24 18:09:50 filth kernel: ums0: Microsoft Microsoft IntelliMouse<AE> Explorer, rev 1.10/1.07, addr 2, iclass 3/1
Jul 24 18:09:50 filth kernel: ums0: 5 buttons and Z dir.
Jul 24 18:09:50 filth kernel: uhub0: port error, restarting port 2
Jul 24 18:09:50 filth kernel: uhub0: port error, giving up port 2
Jul 24 18:09:50 filth kernel: uhub1: Texas Instruments UT-USB41 hub, class 9/0, rev 1.10/1.10, addr 3
Jul 24 18:09:50 filth kernel: uhub1: 4 ports with 4 removable, self powered
Jul 24 18:09:50 filth kernel: ugen0: Gravis GamePad Pro USB, rev 1.00/2.00, addr 4
Jul 24 18:09:50 filth kernel: uhci1: <VIA 83C572 USB controller> port 0xd800-0xd81f irq 5 at device 7.3 on pci0
Jul 24 18:09:50 filth kernel: usb1: <VIA 83C572 USB controller> on uhci1
Jul 24 18:09:50 filth kernel: usb1: USB revision 1.0
Jul 24 18:09:50 filth kernel: uhub2: VIA UHCI root hub, class 9/0, rev 1.00/1.00 , addr 1
Jul 24 18:09:50 filth kernel: uhub2: 2 ports with 2 removable, self powered
Jul 24 18:09:50 filth kernel: uhub2: port error, restarting port 1
Jul 24 18:09:50 filth kernel: uhub2: port error, giving up port 1
Jul 24 18:09:50 filth kernel: uhub2: port error, restarting port 2
Jul 24 18:09:50 filth kernel: uhub2: port error, giving up port 2
Jul 24 18:09:50 filth kernel: pcm0: <Creative EMU10K1> port 0xdc00-0xdc1f irq 5 at device 8.0 on pci0
Jul 24 18:09:50 filth kernel: pci0: <multimedia, video> at device 9.0 (no driver attached)
Jul 24 18:09:50 filth kernel: pci0: <multimedia> at device 9.1 (no driver attach ed)
Jul 24 18:09:50 filth kernel: dc0: <Intel 21143 10/100BaseTX> port 0xe400-0xe47f mem 0xdf001000-0xdf00107f irq 10 at device 13.0 on pci0
Jul 24 18:09:50 filth kernel: dc0: Ethernet address: 00:40:f6:84:cc:e8
Jul 24 18:09:50 filth kernel: miibus0: <MII bus> on dc0
Jul 24 18:09:50 filth kernel: dcphy0: <Intel 21143 NWAY media interface> on miibus0
Jul 24 18:09:50 filth kernel: dcphy0:  10baseT, 10baseT-FDX, 100baseTX, 100baseT X-FDX, auto
Jul 24 18:09:50 filth kernel: ahc0: <Adaptec 2940 Ultra2 SCSI adapter (OEM)> por t 0xe800-0xe8ff mem 0xdf003000-0xdf003fff irq 11 at device 17.0 on pci0
Jul 24 18:09:50 filth kernel: aic7890/91: Ultra2 Wide Channel A, SCSI Id=7, 32/2 53 SCBs
Jul 24 18:09:50 filth kernel: sio0 port 0x3f8-0x3ff irq 4 on acpi0
Jul 24 18:09:50 filth kernel: sio0: type 16550A
Jul 24 18:09:50 filth kernel: atkbdc0: <Keyboard controller (i8042)> port 0x64,0 x60 irq 1 on acpi0
Jul 24 18:09:50 filth kernel: atkbd0: <AT Keyboard> flags 0x1 irq 1 on atkbdc0
Jul 24 18:09:50 filth kernel: kbd0 at atkbd0
Jul 24 18:09:50 filth kernel: orm0: <Option ROMs> at iomem 0xcc000-0xd0fff,0xc00 00-0xcbfff on isa0
Jul 24 18:09:50 filth kernel: fdc0: ready for input in output
Jul 24 18:09:50 filth kernel: fdc0: cmd 3 failed at out byte 1 of 3
Jul 24 18:09:50 filth kernel: pmtimer0 on isa0
Jul 24 18:09:50 filth kernel: sc0: <System console> at flags 0x100 on isa0
Jul 24 18:09:50 filth kernel: sc0: VGA <16 virtual consoles, flags=0x300>
Jul 24 18:09:50 filth kernel: sio1: configured irq 3 not in bitmap of probed irqs 0
Jul 24 18:09:50 filth kernel: sio1: port may not be enabled
Jul 24 18:09:50 filth kernel: vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
Jul 24 18:09:50 filth kernel: Timecounters tick every 10.000 msec
Jul 24 18:09:50 filth kernel: acpi_cpu: CPU throttling enabled, 2 steps from 100 % to 50.0%
Jul 24 18:09:50 filth kernel: ad0: 26059MB <Maxtor 92732U8> [52946/16/63] at ata0-master UDMA66
Jul 24 18:09:50 filth kernel: acd0: CD-RW <PLEXTOR CD-R PX-320A> at ata1-master PIO4
Jul 24 18:09:50 filth kernel: Waiting 7 seconds for SCSI devices to settle
Jul 24 18:09:50 filth kernel: da0 at ahc0 bus 0 target 0 lun 0
Jul 24 18:09:50 filth kernel: da0: <SEAGATE ST118202LW 0005> Fixed Direct Access SCSI-2 device 
Jul 24 18:09:50 filth kernel: da0: 80.000MB/s transfers (40.000MHz, offset 15, 16bit), Tagged Queueing Enabled
Jul 24 18:09:50 filth kernel: da0: 17366MB (35566480 512 byte sectors: 255H 63S/T 2213C)



GNU gdb 5.2.0 (FreeBSD) 20020627
Copyright 2002 Free Software Foundation, Inc.
GDB is free software, covered by the GNU General Public License, and you
are
welcome to change it and/or distribute copies of it under certain
conditions.
Type "show copying" to see the conditions.
There is absolutely no warranty for GDB.  Type "show warranty" for
details.
This GDB was configured as "i386-undermydesk-freebsd"...
panic: bremfree: bp 0xce66b4e4 not locked
panic messages:
---
panic: blockable sleep lock (sleep mutex) process lock @
/usr/src/sys/i386/i386/
trap.c:730

syncing disks... panic: bremfree: bp 0xce66b4e4 not locked
Uptime: 1h28m44s
Dumping 511 MB
 16 32 48 64 80 96 112 128 144 160 176 192 208 224 240 256 272 288 304
 320 336 3 52 368 384 400 416 432 448 464 480 496
 ---
 #0  doadump () at /usr/src/sys/kern/kern_shutdown.c:213
 213             dumping++;
 (kgdb) bt
 #0  doadump () at /usr/src/sys/kern/kern_shutdown.c:213
 #1  0xc01eec21 in boot (howto=260) at
 /usr/src/sys/kern/kern_shutdown.c:345
 #2  0xc01eede1 in panic () at /usr/src/sys/kern/kern_shutdown.c:493
 #3  0xc0222775 in bremfree (bp=0xce66b4e4) at
 /usr/src/sys/kern/vfs_bio.c:633
 #4  0xc02b4abd in ffs_fsync (ap=0xda068ac4) at /usr/src/sys/ufs/ffs/ffs_vnops.c:195
 #5  0xc02b2b15 in ffs_sync (mp=0xc408aa00, waitfor=2, cred=0xc158ae80, td=0xc037ef20) at vnode_if.h:463
 #6  0xc0231554 in sync (td=0xc037ef20, uap=0x0) at /usr/src/sys/kern/vfs_syscalls.c:127
 #7  0xc01ee88d in boot (howto=256) at /usr/src/sys/kern/kern_shutdown.c:254
 #8  0xc01eede1 in panic () at /usr/src/sys/kern/kern_shutdown.c:493
 #9  0xc0207eaf in witness_lock (lock=0xc4b68c10, flags=8, file=0xc034f669 "/usr/src/sys/i386/i386/trap.c", line=730) at /usr/src/sys/kern/subr_witness.c:490
 #10 0xc01e720b in _mtx_lock_flags (m=0xc4b68c10, opts=0, file=0xc034f669 "/usr/src/sys/i386/i386/trap.c", line=730) at /usr/src/sys/kern/kern_mutex.c:322
 #11 0xc02f6c04 in trap_pfault (frame=0xda068c3c, usermode=0, eva=100) at /usr/src/sys/i386/i386/trap.c:730
 #12 0xc02f68dd in trap (frame= {tf_fs = 24, tf_es = 16, tf_ds = 16, tf_edi = -989407948, tf_esi = 772, tf _ebp = -637105008, tf_isp = -637105048, tf_ebx = -995972480, tf_edx = 2, tf_ecx = 0, tf_eax = 0, tf_trapno = 12, tf_err = 0, tf_eip = -1071745702, tf_cs = 8, tf_eflags = 66118, tf_esp = 0, tf_ss = 2}) at /usr/src/sys/i386/i386/trap.c:445
 #13 0xc02ea6a8 in calltrap () at {standard input}:98
 #14 0xc01e71f9 in _mtx_lock_flags (m=0xc4a2aa80, opts=0, file=0xc506d534 "\20028<C0>c<A7>2<C0>c<A7>2<C0>", line=772) at /usr/src/sys/kern/kern_mutex.c:319
 #15 0xc01d7a9d in close (td=0xc4a2aa80, uap=0x0) at /usr/src/sys/kern/kern_descrip.c:772
 #16 0xc02f7244 in syscall (frame= {tf_fs = 47, tf_es = 47, tf_ds = 47, tf_edi = 7, tf_esi = 135214852, tf_eb p = -1077944872, tf_isp = -637104780, tf_ebx = 135215236, tf_edx = 135183744, tf _ecx = 1, tf_eax = 6, tf_trapno = 12, tf_err = 2, tf_eip = 134704123, tf_cs = 31 , tf_eflags = 658, tf_esp = -1077944932, tf_ss = 47}) at /usr/src/sys/i386/i386/trap.c:1049
 #17 0xc02ea6dd in syscall_with_err_pushed () at {standard input}:128
 ---Can't read userspace from dump, or kernel process---

(kgdb) print bp
$1 = (struct buf *) 0xce66b4e4
(kgdb) print bp->b_lock
$2 = {lk_interlock = 0xc03b61bc, lk_flags = 0, lk_sharecount = 0, lk_waitcount = 0, lk_exclusivecount = 0, lk_prio = 80, lk_wmesg = 0xc0335098 "bufwait", lk_timo = 0, lk_lockholder = -1}




On Wed, Jul 24, 2002 at 04:49:40PM -0700, Alex Zepeda wrote:
> My system is down to one cpu (the first slot is appears to have eaten
> itself and using it results in interesting smells from the power supply),
> but I'm still running a SMP kernel.
> 
> Anything else I should probe with gdb?
> 
> FreeBSD blarf.homeip.net 5.0-CURRENT FreeBSD 5.0-CURRENT #3: Wed Jul 24 13:13:30 PDT 2002     alex@blarf.homeip.net:/usr/src/sys/i386/compile/ZIPPY_SMP_WITNESS i386
> 
> 
> GNU gdb 5.2 (FreeBSD)
> Copyright 2002 Free Software Foundation, Inc.
> GDB is free software, covered by the GNU General Public License, and you are
> welcome to change it and/or distribute copies of it under certain conditions.
> Type "show copying" to see the conditions.
> There is absolutely no warranty for GDB.  Type "show warranty" for details.
> This GDB was configured as "i386-portbld-freebsd5.0"...
> IdlePTD at phsyical address 0x0056e000
> initial pcb at physical address 0x004564c0
> panicstr: bremfree: bp 0xc4209bd4 not locked
> panic messages:
> ---
> panic: Most recently used by none
> 
> cpuid = 0; lapic.id = 00000000
> 
> syncing disks... panic: bremfree: bp 0xc4209bd4 not locked
> cpuid = 0; lapic.id = 00000000
> Uptime: 3h13m48s
> Dumping 127 MB
> ata0: resetting devices ..
> done
>  16 32 48 64 80 96 112
> ---
> #0  doadump () at ../../../kern/kern_shutdown.c:213
> 213		dumping++;
> (kgdb) bt
> #0  doadump () at ../../../kern/kern_shutdown.c:213
> #1  0xc02220bd in boot (howto=260) at ../../../kern/kern_shutdown.c:345
> #2  0xc02222ce in poweroff_wait (junk=0xc03b0785, howto=-1004495916)
>     at ../../../kern/kern_shutdown.c:493
> #3  0xc02551d7 in bremfree (bp=0xc03b0785) at ../../../kern/vfs_bio.c:633
> #4  0xc025746e in getblk (vp=0xc1b1b000, blkno=196720, size=8192, slpflag=0, 
>     slptimeo=0) at ../../../kern/vfs_bio.c:2318
> #5  0xc02552b7 in breadn (vp=0xc1b1b000, blkno=196720, size=8192, rablkno=0x0, 
>     rabsize=0x0, cnt=0, cred=0x0, bpp=0x0) at ../../../kern/vfs_bio.c:691
> #6  0xc0255286 in bread (vp=0xc1b1b000, blkno=196720, size=8192, cred=0x0, 
>     bpp=0xc8a5cad0) at ../../../kern/vfs_bio.c:673
> #7  0xc02f0894 in ffs_update (vp=0xc1df2a50, waitfor=0)
>     at ../../../ufs/ffs/ffs_inode.c:102
> #8  0xc0301f1a in ffs_fsync (ap=0xc8a5cb48) at ../../../ufs/ffs/ffs_vnops.c:272
> #9  0xc02ffee8 in ffs_sync (mp=0xc1b15800, waitfor=2, cred=0xc0bace80, 
>     td=0xc040f260) at vnode_if.h:463
> #10 0xc0263bbe in sync (td=0xc040f260, uap=0x0)
>     at ../../../kern/vfs_syscalls.c:127
> #11 0xc0221d2c in boot (howto=256) at ../../../kern/kern_shutdown.c:254
> #12 0xc02222ce in poweroff_wait (junk=0xc03cd8e8, howto=-1069754141)
>     at ../../../kern/kern_shutdown.c:493
> #13 0xc032022a in mtrash_ctor (mem=0x100, size=0, arg=0x0)
>     at ../../../vm/uma_dbg.c:135
> #14 0xc0320294 in mtrash_fini (mem=0xc215d000, size=8192)
>     at ../../../vm/uma_dbg.c:186
> #15 0xc031e576 in zone_drain (zone=0xc0bbfcc0) at ../../../vm/uma_core.c:646
> #16 0xc031ee8c in zone_foreach (zfunc=0xc031e2e0 <zone_drain>)
>     at ../../../vm/uma_core.c:1167
> #17 0xc031fd90 in uma_reclaim () at ../../../vm/uma_core.c:1980
> #18 0xc031be72 in vm_pageout_scan (pass=0) at ../../../vm/vm_pageout.c:654
> #19 0xc031cbea in vm_pageout () at ../../../vm/vm_pageout.c:1434
> #20 0xc021303e in fork_exit (callout=0xc031c9c0 <vm_pageout>, arg=0x0, 
>     frame=0xc8a5cd48) at ../../../kern/kern_fork.c:861
> (kgdb) quit
> 
> The most applicable dmesg output I could find was:
> 
> Memory modified after free 0xc215d000(8188)
> panic: Most recently used by none
> 
> cpuid = 0; lapic.id = 00000000
> 
> syncing disks... panic: bremfree: bp 0xc4209bd4 not locked
> cpuid = 0; lapic.id = 00000000
> Uptime: 3h13m48s
> Dumping 127 MB
> ata0: resetting devices ..
> done
>  16 32 48 64 80 96 112
> 
> 
> To Unsubscribe: send mail to majordomo@FreeBSD.org
> with "unsubscribe freebsd-current" in the body of the message

To Unsubscribe: send mail to majordomo@FreeBSD.org
with "unsubscribe freebsd-current" in the body of the message