Re: rtmutex assert failure (was [Patch] restore the RCU callback...)

From: Robert Crocombe
Date: Wed Aug 23 2006 - 21:19:26 EST


On 8/23/06, hui Bill Huey <billh@xxxxxxxxxxxxxxxxx> wrote:
On Wed, Aug 23, 2006 at 02:05:58PM -0700, Bill Huey wrote:
> I'm on irc.oftc.net as "bh" if you want somebody that's interactive. It
> might help with things here.

#kernelnewbies is the channel.

Firewall where only port 80 is open. I tried this thing:

http://www.polarhome.com/cgi-bin/chat/irc.cgi

but it looks like it connects to the wrong port (8888 vs 6667?), or
something. I see that some Windows *shudder* client supposedly
supports thru-firewill connections, and additionally some instructions
about port-forwarding off an outside machine, but I'm kinda stuck for
the moment.

Okay, tried the patch. I actually made it through one full kernel
compile without going bang, but did crap out on the second iteration
(after a 'make clean').

I'll post the config, too, just so there's a clear understanding of
where we are.

--
Robert Crocombe
rcrocomb@xxxxxxxxxxxxxxxxxx/1061[CPU#3]: BUG in debug_rt_mutex_unlock
at kernel/rtmutex-debug.c:471

Call Trace:
<ffffffff802616af>{_raw_spin_lock_irqsave+29}
<ffffffff80280b6f>{__WARN_ON+100}
<ffffffff80280b2a>{__WARN_ON+31}
<ffffffff80295ef9>{debug_rt_mutex_unlock+199}
<ffffffff80260920>{rt_lock_slowunlock+25}
<ffffffff80261451>{__lock_text_start+9}
<ffffffff8020a188>{kmem_cache_alloc+202}
<ffffffff8029f849>{mempool_alloc_slab+17}
<ffffffff802222f4>{mempool_alloc+75}
<ffffffff80261ac8>{_raw_spin_unlock+46}
<ffffffff80260948>{rt_lock_slowunlock+65}
<ffffffff80219d1a>{bio_alloc_bioset+35}
<ffffffff802b5654>{bio_clone+29}
<ffffffff803a581f>{clone_bio+51}
<ffffffff803a64d7>{__split_bio+399}
<ffffffff803a6cc8>{dm_request+453}
<ffffffff8021b42a>{generic_make_request+375}
<ffffffff80232b94>{submit_bio+192}
<ffffffff80219c68>{submit_bh+262}
<ffffffff802166da>{ll_rw_block+161}
<ffffffff802ec25f>{journal_commit_transaction+990}
<ffffffff80261ac8>{_raw_spin_unlock+46}
<ffffffff80260948>{rt_lock_slowunlock+65}
<ffffffff8028f22b>{autoremove_wake_function+0}
<ffffffff8024b4c7>{try_to_del_timer_sync+85}
<ffffffff802f0679>{kjournald+199}
<ffffffff8028f22b>{autoremove_wake_function+0}
<ffffffff802f05b2>{kjournald+0}
<ffffffff8028f027>{keventd_create_kthread+0}
<ffffffff802321b7>{kthread+219}
<ffffffff802268f9>{schedule_tail+185}
<ffffffff8025d31e>{child_rip+8}
<ffffffff8028f027>{keventd_create_kthread+0}
<ffffffff802320dc>{kthread+0}
<ffffffff8025d316>{child_rip+0}
---------------------------
| preempt count: 00000002 ]
| 2-level deep critical section nesting:
----------------------------------------
.. [<ffffffff802615e9>] .... _raw_spin_lock+0x16/0x23
.....[<ffffffff80260918>] .. ( <= rt_lock_slowunlock+0x11/0x6b)
.. [<ffffffff802616af>] .... _raw_spin_lock_irqsave+0x1d/0x2e
.....[<ffffffff80280b2a>] .. ( <= __WARN_ON+0x1f/0x82)

kjournald/1061[CPU#3]: BUG in debug_rt_mutex_unlock at
kernel/rtmutex-debug.c:472

Call Trace:
<ffffffff802616af>{_raw_spin_lock_irqsave+29}
<ffffffff80280b6f>{__WARN_ON+100}
<ffffffff80280b2a>{__WARN_ON+31}
<ffffffff80295f9b>{debug_rt_mutex_unlock+361}
<ffffffff80260920>{rt_lock_slowunlock+25}
<ffffffff80261451>{__lock_text_start+9}
<ffffffff8020a188>{kmem_cache_alloc+202}
<ffffffff8029f849>{mempool_alloc_slab+17}
<ffffffff802222f4>{mempool_alloc+75}
<ffffffff80261ac8>{_raw_spin_unlock+46}
<ffffffff80260948>{rt_lock_slowunlock+65}
<ffffffff80219d1a>{bio_alloc_bioset+35}
<ffffffff802b5654>{bio_clone+29}
<ffffffff803a581f>{clone_bio+51}
<ffffffff803a64d7>{__split_bio+399}
<ffffffff803a6cc8>{dm_request+453}
<ffffffff8021b42a>{generic_make_request+375}
<ffffffff80232b94>{submit_bio+192}
<ffffffff80219c68>{submit_bh+262}
<ffffffff802166da>{ll_rw_block+161}
<ffffffff802ec25f>{journal_commit_transaction+990}
<ffffffff80261ac8>{_raw_spin_unlock+46}
<ffffffff80260948>{rt_lock_slowunlock+65}
<ffffffff8028f22b>{autoremove_wake_function+0}
<ffffffff8024b4c7>{try_to_del_timer_sync+85}
<ffffffff802f0679>{kjournald+199}
<ffffffff8028f22b>{autoremove_wake_function+0}
<ffffffff802f05b2>{kjournald+0}
<ffffffff8028f027>{keventd_create_kthread+0}
<ffffffff802321b7>{kthread+219}
<ffffffff802268f9>{schedule_tail+185}
<ffffffff8025d31e>{child_rip+8}
<ffffffff8028f027>{keventd_create_kthread+0}
<ffffffff802320dc>{kthread+0}
<ffffffff8025d316>{child_rip+0}
---------------------------
| preempt count: 00000002 ]
| 2-level deep critical section nesting:
----------------------------------------
.. [<ffffffff802615e9>] .... _raw_spin_lock+0x16/0x23
.....[<ffffffff80260918>] .. ( <= rt_lock_slowunlock+0x11/0x6b)
.. [<ffffffff802616af>] .... _raw_spin_lock_irqsave+0x1d/0x2e
.....[<ffffffff80280b2a>] .. ( <= __WARN_ON+0x1f/0x82)

----------- [cut here ] --------- [please bite here ] ---------
Kernel BUG at kernel/rtmutex.c:639
invalid opcode: 0000 [1] PREEMPT SMP
CPU 3
Modules linked in: tg3
Pid: 1061, comm: kjournald Not tainted 2.6.17-rt8_t5_00 #3
RIP: 0010:[<ffffffff80260a8f>] <ffffffff80260a8f>{rt_lock_slowlock+181}
RSP: 0018:ffff8104002319d8 EFLAGS: 00010246
RAX: ffff810200361560 RBX: 0000000000000010 RCX: ffffffff805c3800
RDX: ffff810200361560 RSI: ffffffff8020a10d RDI: ffff810600115b00
RBP: ffff810400231a98 R08: ffff8101e7c766c0 R09: ffff8104002319d8
R10: ffff8104000d74f0 R11: 0000000000000093 R12: ffff810600115b00
R13: ffff810200031c40 R14: ffffffff8020a10d R15: 0000000000000010
FS: 00002b45110964a0(0000) GS:ffff810600210340(0000) knlGS:0000000000000000
CS: 0010 DS: 0018 ES: 0018 CR0: 000000008005003b
CR2: 00002b45111a10f8 CR3: 00000001e5fe1000 CR4: 00000000000006e0
Process kjournald (pid: 1061, threadinfo ffff810400230000, task
ffff810200361560)
Stack: 111111110000008c ffff8104002319e0 ffff8104002319e0 ffff8104002319f0
ffff8104002319f0 0000000000000000 111111110000008c ffff810400231a10
ffff810400231a10 ffff810400231a20
Call Trace:
<ffffffff80261460>{rt_lock+13}
<ffffffff8020a10d>{kmem_cache_alloc+79}
<ffffffff8029f849>{mempool_alloc_slab+17}
<ffffffff802222f4>{mempool_alloc+75}
<ffffffff80261ac8>{_raw_spin_unlock+46}
<ffffffff80260948>{rt_lock_slowunlock+65}
<ffffffff80219d89>{bio_alloc_bioset+146}
<ffffffff802b5654>{bio_clone+29}
<ffffffff803a581f>{clone_bio+51}
<ffffffff803a64d7>{__split_bio+399}
<ffffffff803a6cc8>{dm_request+453}
<ffffffff8021b42a>{generic_make_request+375}
<ffffffff80232b94>{submit_bio+192}
<ffffffff80219c68>{submit_bh+262}
<ffffffff802166da>{ll_rw_block+161}
<ffffffff802ec25f>{journal_commit_transaction+990}
<ffffffff80261ac8>{_raw_spin_unlock+46}
<ffffffff80260948>{rt_lock_slowunlock+65}
<ffffffff8028f22b>{autoremove_wake_function+0}
<ffffffff8024b4c7>{try_to_del_timer_sync+85}
<ffffffff802f0679>{kjournald+199}
<ffffffff8028f22b>{autoremove_wake_function+0}
<ffffffff802f05b2>{kjournald+0}
<ffffffff8028f027>{keventd_create_kthread+0}
<ffffffff802321b7>{kthread+219}
<ffffffff802268f9>{schedule_tail+185}
<ffffffff8025d31e>{child_rip+8}
<ffffffff8028f027>{keventd_create_kthread+0}
<ffffffff802320dc>{kthread+0}
<ffffffff8025d316>{child_rip+0}
---------------------------
| preempt count: 00000002 ]
| 2-level deep critical section nesting:
----------------------------------------
.. [<ffffffff802615e9>] .... _raw_spin_lock+0x16/0x23
.....[<ffffffff80260a10>] .. ( <= rt_lock_slowlock+0x36/0x20e)
.. [<ffffffff802618a9>] .... _raw_spin_trylock+0x16/0x5a
.....[<ffffffff80265982>] .. ( <= oops_begin+0x23/0x72)


Code: 0f 0b 68 84 5b 40 80 c2 7f 02 65 48 8b 04 25 00 00 00 00 41
RIP <ffffffff80260a8f>{rt_lock_slowlock+181} RSP <ffff8104002319d8>

Attachment: 2.6.17-rt8-t5-config
Description: Binary data