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

["Robert Crocombe" <rcrocomb@gmail.com>]

On 8/23/06, hui Bill Huey <billh@gnuppy.monkey.org> 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@gmail.comkjournald/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