Another Lock Order Reversal -- sysctl?

From: Brad Pitney <pitney.brad_at_googlemail.com>
Date: Mon, 17 Mar 2008 06:28:34 +0000
Hi,

this one is different, I also had debugging on and I got more details (I hope)

lock order reversal:
 1st 0xc474281c process slock (process slock) _at_
/usr/src/sys/kern/kern_proc.c:710
 2nd 0xc07bf5d0 scrlock (scrlock) _at_ /usr/src/sys/dev/syscons/syscons.c:2526
KDB: stack backtrace:
db_trace_self_wrapper(c0746b40,e6fdd3ec,c057644e,c0748ee1,c07bf5d0,...)
at db_trace_self_wrapper+0x26
kdb_backtrace(c0748ee1,c07bf5d0,c073a70f,c073a70f,c073a69b,...) at
kdb_backtrace+0x29
witness_checkorder(c07bf5d0,9,c073a69b,9de,c07bf5d0,...) at
witness_checkorder+0x6de
_mtx_lock_spin_flags(c07bf5d0,0,c073a69b,9de,c07bf4c0,...) at
_mtx_lock_spin_flags+0xc2
sc_puts(c07bf4c0,e6fdd447,1,6380c3a0,c077c9e0,...) at sc_puts+0x7e
sc_cnputc(c077c9e0,63,e6fdd5fc,1,a,...) at sc_cnputc+0xc3
cnputc(63,0,c074b14e,28e,e6fdd6fc,...) at cnputc+0x5f
cnputs(e6fdd5fc,e6fdd6fc,e6fdd4bc,c05696f1,a,...) at cnputs+0x58
putcons(a,0,1fdd593,c4742a2c,c07439b3,...) at putcons+0x67
putchar(a,e6fdd6fc,0,6,c4742a2c,...) at putchar+0x61
kvprintf(c074396a,c0569690,e6fdd6fc,a,e6fdd744,...) at kvprintf+0x75
printf(c074396a,1ef,0,1b6,0,...) at printf+0x6c
calcru1(e6fdda30,4,c0743806,345,c07432db,2c6) at calcru1+0x25e
calcru(c4742804,e6fdda28,e6fdda30,2c6,c4864aa8,...) at calcru+0x12d
fill_kinfo_proc_only(c4742894,4,c07432db,38a,0,...) at
fill_kinfo_proc_only+0x3b7
sysctl_out_proc(c4a35420,c4742804,c07432db,401,0,...) at sysctl_out_proc+0x68
sysctl_kern_proc(c07863e0,0,0,e6fddba8,e6fddba8,...) at sysctl_kern_proc+0x4fd
sysctl_root(e6fddba8,0,c0744bd6,574,c4a35420,...) at sysctl_root+0x137
userland_sysctl(c4a35420,e6fddc18,3,0,bfbfe744,...) at userland_sysctl+0x115
__sysctl(c4a35420,e6fddcfc,18,c0749bc1,c07807d0,...) at __sysctl+0xbc
syscall(e6fddd38) at syscall+0x253
Xint0x80_syscall() at Xint0x80_syscall+0x20
--- syscall (202, FreeBSD ELF32, __sysctl), eip = 0x281bc823, esp =
0xbfbfe66c, ebp = 0xbfbfe698 ---
KDB: enter: witness_checkorder

db> show pcpu
cpuid         = 0
curthread     = 0xc4a35420: pid 5757 "top"
curpcb        = 0xe6fddd90
fpcurthread   = 0xc4a35420: pid 5757 "top"
idlethread    = 0xc43e3c60: pid 10 "idle"
APIC ID       = 0
currentldt    = 0x50
spin locks held:
exclusive spin mutex process slock r = 0 (0xc474281c) locked _at_
/usr/src/sys/kern/kern_proc.c:710

db> show alllocks
Process 5757 (top) thread 0xc4a35420 (100074)
exclusive sleep mutex process lock r = 0 (0xc4742894) locked _at_
/usr/src/sys/kern/kern_proc.c:1025
shared sx allproc r = 0 (0xc07c16fc) locked _at_ /usr/src/sys/kern/kern_proc.c:238
exclusive sx sysctl lock r = 0 (0xc07c1bf4) locked _at_
/usr/src/sys/kern/kern_sysctl.c:1396
exclusive sleep mutex Giant r = 0 (0xc07c11f0) locked _at_
/usr/src/sys/kern/kern_sysctl.c:1334
exclusive spin mutex process slock r = 0 (0xc474281c) locked _at_
/usr/src/sys/kern/kern_proc.c:710
exclusive sx so_rcv_sx r = 0 (0xc4b2ab60) locked _at_
/usr/src/sys/kern/uipc_sockbuf.c:148

the actual top command was: top -SCats1

-- 
Best regards,
 Brad
Received on Mon Mar 17 2008 - 05:28:35 UTC

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