Re: panic: Invalid priority <number> on timeshare runq

From: Jeff Roberson <jroberson_at_chesapeake.net>
Date: Mon, 10 Mar 2008 14:12:16 -1000 (HST)
On Mon, 10 Mar 2008, David Wolfskill wrote:

> On Mon, Mar 10, 2008 at 08:19:59PM +0000, Bjoern A. Zeeb wrote:
>> On Mon, 10 Mar 2008, Ed Schouten wrote:
>>
>> Can any of you reproduce this easily?
>
> Certainly.  I just re-booted my laptop (with serial console this time):
>
> /boot/kernel/acpi.ko text=0x53f4c data=0x25e0+0x186c syms=[0x4+0x8a20+0x4+0xbbc7]
> GDB: no debug ports present
> KDB: debugger backends: ddb
> KDB: current backend: ddb
> SMAP type=01 base=0000000000000000 len=000000000009fc00
> SMAP type=02 base=000000000009fc00 len=0000000000000400
> SMAP type=01 base=0000000000100000 len=000000003fee2800
> SMAP type=02 base=000000003ffe2800 len=000000000001d800
> SMAP type=02 base=00000000feda0000 len=0000000000060000
> SMAP type=02 base=00000000ffb80000 len=0000000000480000
> 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_at_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 CPU)
>  Origin = "GenuineIntel"  Id = 0xf27  Stepping = 7
>  Features=0xbfebf9ff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CLFLUSH,DTS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE>
>  Features2=0x400<CNXT-ID>
>
> 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 line size
> real memory  = 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 = 1038028800 (989 MB)
> ...
> start_init: trying /sbin/init
> lock order reversal:
> 1st 0xc3e26044 user map (user map) _at_ /usr/src/sys/vm/vm_map.c:3111
> 2nd 0xc409c7c8 ufs (ufs) _at_ /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_backtrace+0x29
> witness_checkorder(c409c7c8,1,c0acb253,80f,e28869e8,...) at witness_checkorder+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+0xa5
> _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 = 0x80480e5, esp = 0xbfbfeef0, ebp = 0xbfbfef10 ---
> xl0: link state changed to DOWN
> lock order reversal:
> 1st 0xc4377c08 pseudofs (pseudofs) _at_ /usr/src/sys/kern/vfs_subr.c:2063
> 2nd 0xc4382538 vfslock (vfslock) _at_ /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_backtrace+0x29
> witness_checkorder(c4382538,1,c0acb253,16c,e6586a58,...) at witness_checkorder+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 = 0x280d715b, esp = 0xbfbfe96c, ebp = 0xbfbfedb8 ---
> panic: Invalid priority 155 on timeshare runq
> cpuid = 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 = 0xc0a2993b, esp = 0xe65bca1c, ebp = 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_checkorder+0x316
> _mtx_lock_flags(c1474088,0,c0ae2a57,984,c43f6580,...) at _mtx_lock_flags+0xbc
> 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_curr_sysctl+0x28f
> sysctl_root(e65bcba4,0,c0ac05ea,587,c43bad20,...) at sysctl_root+0x137
> userland_sysctl(c43bad20,e65bcc14,4,bfbfee3c,bfbfee2c,...) at userland_sysctl+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 = 0x28167e0f, esp = 0xbfbfe8bc, ebp = 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>
>
> [Sorry about the delay; been in meetings quite a bit today.]

Thank you for your efforts.  There is a fix in CVS now.

>
> Peace,
> david
> -- 
> David H. Wolfskill				david_at_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.
>
Received on Mon Mar 10 2008 - 23:13:21 UTC

This archive was generated by hypermail 2.4.0 : Wed May 19 2021 - 11:39:28 UTC