From owner-freebsd-current@FreeBSD.ORG Mon Mar 10 22:22:00 2008 Return-Path: Delivered-To: current@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:4f8:fff6::34]) by hub.freebsd.org (Postfix) with ESMTP id 8F0AE1065671 for ; Mon, 10 Mar 2008 22:22:00 +0000 (UTC) (envelope-from david@catwhisker.org) Received: from bunrab.catwhisker.org (adsl-63-193-123-122.dsl.snfc21.pacbell.net [63.193.123.122]) by mx1.freebsd.org (Postfix) with ESMTP id 1E92F8FC1C for ; Mon, 10 Mar 2008 22:22:00 +0000 (UTC) (envelope-from david@catwhisker.org) Received: from bunrab.catwhisker.org (localhost [127.0.0.1]) by bunrab.catwhisker.org (8.13.3/8.13.3) with ESMTP id m2AMLvfk098069; Mon, 10 Mar 2008 15:21:57 -0700 (PDT) (envelope-from david@bunrab.catwhisker.org) Received: (from david@localhost) by bunrab.catwhisker.org (8.13.3/8.13.1/Submit) id m2AMLv9p098068; Mon, 10 Mar 2008 15:21:57 -0700 (PDT) (envelope-from david) Date: Mon, 10 Mar 2008 15:21:57 -0700 From: David Wolfskill To: "Bjoern A. Zeeb" Message-ID: <20080310222157.GG53010@bunrab.catwhisker.org> Mail-Followup-To: David Wolfskill , "Bjoern A. Zeeb" , Ed Schouten , FreeBSD current mailing list References: <20080310154947.GB53010@bunrab.catwhisker.org> <20080310155244.GV80576@hoeg.nl> <20080310201930.L50685@maildrop.int.zabbadoz.net> Mime-Version: 1.0 Content-Type: multipart/signed; micalg=pgp-sha1; protocol="application/pgp-signature"; boundary="z0PJazCl8/BmicYQ" Content-Disposition: inline In-Reply-To: <20080310201930.L50685@maildrop.int.zabbadoz.net> User-Agent: Mutt/1.4.2.1i Cc: Ed Schouten , FreeBSD current mailing list Subject: Re: panic: Invalid priority on timeshare runq X-BeenThere: freebsd-current@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: Discussions about the use of FreeBSD-current List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Mon, 10 Mar 2008 22:22:00 -0000 --z0PJazCl8/BmicYQ Content-Type: text/plain; charset=us-ascii Content-Disposition: inline Content-Transfer-Encoding: quoted-printable On Mon, Mar 10, 2008 at 08:19:59PM +0000, Bjoern A. Zeeb wrote: > On Mon, 10 Mar 2008, Ed Schouten wrote: >=20 > Can any of you reproduce this easily? Certainly. I just re-booted my laptop (with serial console this time): /boot/kernel/acpi.ko text=3D0x53f4c data=3D0x25e0+0x186c syms=3D[0x4+0x8a20= +0x4+0xbbc7] GDB: no debug ports present KDB: debugger backends: ddb KDB: current backend: ddb SMAP type=3D01 base=3D0000000000000000 len=3D000000000009fc00 SMAP type=3D02 base=3D000000000009fc00 len=3D0000000000000400 SMAP type=3D01 base=3D0000000000100000 len=3D000000003fee2800 SMAP type=3D02 base=3D000000003ffe2800 len=3D000000000001d800 SMAP type=3D02 base=3D00000000feda0000 len=3D0000000000060000 SMAP type=3D02 base=3D00000000ffb80000 len=3D0000000000480000 Copyright (c) 1992-2008 The FreeBSD Project. Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994 The Regents of the University of California. All rights reserved. FreeBSD is a registered trademark of The FreeBSD Foundation. FreeBSD 8.0-CURRENT #709: Mon Mar 10 08:00:39 PDT 2008 root@g1-37.catwhisker.org:/common/S4/obj/usr/src/sys/CANARY WARNING: WITNESS option enabled, expect reduced performance. Preloaded elf kernel "/boot/kernel/kernel" at 0xc0e0d000. Preloaded elf module "/boot/kernel/linux.ko" at 0xc0e0d14c. Preloaded elf module "/boot/kernel/if_an.ko" at 0xc0e0d1f8. Preloaded elf module "/boot/kernel/acpi.ko" at 0xc0e0d2a4. Calibrating clock(s) ... i8254 clock: 1193159 Hz CLK_USE_I8254_CALIBRATION not specified - using default frequency Timecounter "i8254" frequency 1193182 Hz quality 0 Calibrating TSC clock ... TSC clock: 2392951268 Hz CPU: Mobile Intel(R) Pentium(R) 4 - M CPU 2.40GHz (2392.95-MHz 686-class CP= U) Origin =3D "GenuineIntel" Id =3D 0xf27 Stepping =3D 7 Features=3D0xbfebf9ff Features2=3D0x400 Instruction TLB: 4 KB, 2 MB or 4 MB pages, fully associative, 128 entries Data TLB: 4 KB or 4 MB pages, fully associative, 64 entries 1st-level data cache: 8 KB, 4-way set associative, sectored cache, 64 byte = line size Trace cache: 12K-uops, 8-way set associative 2nd-level cache: 512 KB, 8-way set associative, sectored cache, 64 byte lin= e size real memory =3D 1073618944 (1023 MB) Physical memory chunk(s): 0x0000000000001000 - 0x000000000009efff, 647168 bytes (158 pages) 0x0000000000100000 - 0x00000000003fffff, 3145728 bytes (768 pages) 0x0000000001025000 - 0x000000003ee99fff, 1038569472 bytes (253557 pages) avail memory =3D 1038028800 (989 MB) =2E.. start_init: trying /sbin/init lock order reversal: 1st 0xc3e26044 user map (user map) @ /usr/src/sys/vm/vm_map.c:3111 2nd 0xc409c7c8 ufs (ufs) @ /usr/src/sys/kern/vfs_subr.c:2063 KDB: stack backtrace: db_trace_self_wrapper(c0ac289c,e28869c4,c077d75e,c0ac4dc5,c409c7c8,...) at = db_trace_self_wrapper+0x26 kdb_backtrace(c0ac4dc5,c409c7c8,c0ab9cd6,c0ab9cd6,c0acb253,...) at kdb_back= trace+0x29 witness_checkorder(c409c7c8,1,c0acb253,80f,e28869e8,...) at witness_checkor= der+0x6de _lockmgr_args(c409c7c8,30041,c409c7f8,0,ffffffff,...) at _lockmgr_args+0x1d5 ffs_lock(e2886a78,c073562d,c0bdc094,30041,c409c770,...) at ffs_lock+0xa3 VOP_LOCK1_APV(c0b994a0,e2886a78,c0ab9421,3,c409c7f8,...) at VOP_LOCK1_APV+0= xa5 _vn_lock(c409c770,30041,c0acb253,80f,0,...) at _vn_lock+0xf7 vget(c409c770,30041,c3e22d20,4a9,c1460580,...) at vget+0x10b vnode_pager_lock(c1460400,0,c0ae3288,127,e2886be8,...) at vnode_pager_lock+= 0x1ad vm_fault(c3e26000,80d3000,2,8,80d3a20,...) at vm_fault+0x1df trap_pfault(5,0,c0aefc15,2c8,c3e20ab0,...) at trap_pfault+0x118 trap(e2886d38) at trap+0x267 calltrap() at calltrap+0x6 --- trap 0xc, eip =3D 0x80480e5, esp =3D 0xbfbfeef0, ebp =3D 0xbfbfef10 --- xl0: link state changed to DOWN lock order reversal: 1st 0xc4377c08 pseudofs (pseudofs) @ /usr/src/sys/kern/vfs_subr.c:2063 2nd 0xc4382538 vfslock (vfslock) @ /usr/src/sys/kern/vfs_subr.c:364 KDB: stack backtrace: db_trace_self_wrapper(c0ac289c,e6586a18,c077d75e,c0ac4dc5,c4382538,...) at = db_trace_self_wrapper+0x26 kdb_backtrace(c0ac4dc5,c4382538,c0acacb6,c0acacb6,c0acb253,...) at kdb_back= trace+0x29 witness_checkorder(c4382538,1,c0acb253,16c,e6586a58,...) at witness_checkor= der+0x6de _lockmgr_args(c4382538,20001,c4382568,0,ffffffff,...) at _lockmgr_args+0x1d5 vfs_busy(c4382538,10,0,c427cd20,8,...) at vfs_busy+0x1b0 vfs_donmount(810f080,c,e6586c70,c4276600,810be68,...) at vfs_donmount+0xdb5 nmount(c427cd20,e6586cfc,c,c0ac5a81,c0b79030,...) at nmount+0xb2 syscall(e6586d38) at syscall+0x2b3 Xint0x80_syscall() at Xint0x80_syscall+0x20 --- syscall (378, FreeBSD ELF32, nmount), eip =3D 0x280d715b, esp =3D 0xbfb= fe96c, ebp =3D 0xbfbfedb8 --- panic: Invalid priority 155 on timeshare runq cpuid =3D 0 KDB: enter: panic [thread pid 955 tid 100064 ] Stopped at kdb_enter+0x3a: movl $0,kdb_why db> bt Tracing pid 955 tid 100064 td 0xc43bad20 kdb_enter(c0abfa2f,c0abfa2f,c0ac19ab,e65bc908,0,...) at kdb_enter+0x3a panic(c0ac19ab,9b,c0ac14d3,183,c0bd2f80,...) at panic+0x12c sched_switch(c43bad20,0,6,184,e47c0566,...) at sched_switch+0x1d4 mi_switch(6,0,c0ac1873,b6,0,...) at mi_switch+0x223 critical_exit(c0bb00c0,c0c226e0,c0ae2a57,c43bad20,c3e1e680,...) at critical= _exit+0x99 intr_execute_handlers(c0bb00c0,e65bc9dc,e65bc9e0,c073594c,c43bad20,...) at = intr_execute_handlers+0x14b atpic_handle_intr(0,e65bc9dc) at atpic_handle_intr+0xba Xatpic_intr0() at Xatpic_intr0+0x21 --- interrupt, eip =3D 0xc0a2993b, esp =3D 0xe65bca1c, ebp =3D 0xe65bca20 -= -- spinlock_exit(c0bdc094,4,c0abe5d9,f4) at spinlock_exit+0x2b _mtx_unlock_spin_flags(c0bdc094,0,c0ac44a0,3ca,c0ae389a,...) at _mtx_unlock= _spin_flags+0xfd witness_checkorder(c1474088,9,c0ae2a57,984,c147108c,...) at witness_checkor= der+0x316 _mtx_lock_flags(c1474088,0,c0ae2a57,984,c43f6580,...) at _mtx_lock_flags+0x= bc uma_zfree_internal(0,2,22) at uma_zfree_internal+0x87 uma_large_free(c43f6580,e65bcb04,4,c3f3c600,0,...) at uma_large_free+0xa9 free(c442a000,c0b7b5a0,0,e65bcba4,e65bcb40,...) at free+0xdb cpufreq_curr_sysctl(c3f43740,c3f41c00,0,e65bcba4,e65bcba4,...) at cpufreq_c= urr_sysctl+0x28f sysctl_root(e65bcba4,0,c0ac05ea,587,c43bad20,...) at sysctl_root+0x137 userland_sysctl(c43bad20,e65bcc14,4,bfbfee3c,bfbfee2c,...) at userland_sysc= tl+0x115 __sysctl(c43bad20,e65bccfc,18,c0a7f2eb,c0b77fb0,...) at __sysctl+0xbc syscall(e65bcd38) at syscall+0x2b3 Xint0x80_syscall() at Xint0x80_syscall+0x20 --- syscall (202, FreeBSD ELF32, __sysctl), eip =3D 0x28167e0f, esp =3D 0xb= fbfe8bc, ebp =3D 0xbfbfe8e8 --- db> ps pid ppid pgrp uid state wmesg wchan cmd 1022 35 35 0 R+ sh 955 1 955 0 Rs powerd 899 1 897 0 S select 0xc408e828 snmpd 882 877 877 0 S - 0xc421ce00 nfsd 881 877 877 0 S - 0xc43aa000 nfsd 880 877 877 0 S - 0xc43aa400 nfsd 879 877 877 0 S - 0xc43aa600 nfsd 877 1 877 0 Ss select 0xc4219aa8 nfsd 868 1 868 0 Ss select 0xc42199a8 mountd 837 1 837 0 Ss select 0xc4219828 rpcbind 818 1 818 0 Ss select 0xc408e3e8 syslogd 786 0 0 0 SL mdwait 0xc4399000 [md0] 723 1 723 0 Ss select 0xc42635a8 devd 35 1 35 0 Ss+ wait 0xc423d000 sh 34 0 0 0 SL sdflush 0xc0c2ea70 [softdepflush] 33 0 0 0 SL vlruwt 0xc423d558 [vnlru] 32 0 0 0 SL syncer 0xc0bd134c [syncer] 31 0 0 0 SL psleep 0xc0c22cc4 [bufdaemon] 30 0 0 0 SL pgzero 0xc0c2f660 [pagezero] 29 0 0 0 SL psleep 0xc0c2f27c [vmdaemon] 28 0 0 0 SL psleep 0xc0c2f244 [pagedaemon] 27 0 0 0 SL - 0xc41a8700 [dummynet] 26 0 0 0 SL waiting_ 0xc0c24f4c [sctp_iterator] 25 0 0 0 SL - 0xc405e83c [fdc0] 24 0 0 0 SL tzpoll 0xc0df5f80 [acpi_thermal] 23 0 0 0 SL cbb cv 0xc3f0d3f0 [cbb2 event thread] 22 0 0 0 SL - 0xc3f7d000 [fw0_probe] 21 0 0 0 SL - 0xc3f61780 [fw0_taskq] 20 0 0 0 SL cbb cv 0xc3f0c3f0 [cbb1 event thread] 19 0 0 0 SL cbb cv 0xc3f10bf0 [cbb0 event thread] 18 0 0 0 SL usbevt 0xc3f59210 [usb1] 17 0 0 0 SL usbtsk 0xc0bcec14 [usbtask-dr] 16 0 0 0 SL usbtsk 0xc0bcec00 [usbtask-hc] 15 0 0 0 SL usbevt 0xc3f18210 [usb0] 14 0 0 0 SL ccb_scan 0xc0bb1d54 [xpt_thrd] 9 0 0 0 SL - 0xc3e7c780 [kqueue taskq] 8 0 0 0 SL - 0xc3e7c900 [acpi_task_2] 7 0 0 0 SL - 0xc3e7c900 [acpi_task_1] 6 0 0 0 SL - 0xc3e7c900 [acpi_task_0] 5 0 0 0 SL - 0xc3e7ca80 [thread taskq] 13 0 0 0 SL - 0xc0bd1354 [yarrow] 4 0 0 0 SL - 0xc0bcf34c [g_down] 3 0 0 0 SL - 0xc0bcf348 [g_up] 2 0 0 0 SL - 0xc0bcf340 [g_event] 12 0 0 0 RL (threaded) intr 100039 I [irq7: ppbus0 ppc0] 100038 I [swi0: sio] 100036 I [irq12: psm0] 100035 I [irq1: atkbd0] 100033 I [irq15: ata1] 100032 I [irq14: ata0] 100022 I [irq11: cbb0 cbb1+*] 100021 I [irq9: pcm0 acpi0] 100020 I [swi2: cambio] 100017 I [swi6: task queue] 100013 I [swi6: Giant taskq] 100011 I [swi5: +] 100006 I [swi3: vm] 100005 RunQ [swi4: clock sio] 100004 I [swi1: net] 11 0 0 0 RL [idle: cpu0] 1 0 1 0 SLs wait 0xc3e20ab0 [init] 10 0 0 0 SL audit_wo 0xc0c2e4d0 [audit] 0 0 0 0 WLs [swapper] db>=20 [Sorry about the delay; been in meetings quite a bit today.] Peace, david --=20 David H. Wolfskill david@catwhisker.org I submit that "conspiracy" would be an appropriate collective noun for cats. See http://www.catwhisker.org/~david/publickey.gpg for my public key. --z0PJazCl8/BmicYQ Content-Type: application/pgp-signature Content-Disposition: inline -----BEGIN PGP SIGNATURE----- Version: GnuPG v1.4.0 (FreeBSD) iEYEARECAAYFAkfVtIQACgkQmprOCmdXAD0VJQCeP9i9AYFjzAfto9zSkWofqMsL HB0AnREpI95rBD/2zd7aaR6npp8dImnZ =LvGJ -----END PGP SIGNATURE----- --z0PJazCl8/BmicYQ--