Re: [BUG] soft lockup detected with ipcs

[Jiri Kosina]

On Mon, 3 Mar 2008, Jiri Olsa wrote:

> your patch with LOCKDEP enabled:
> ipcs.strace.1		- strace of ipcs from the first run
> ipcs.kernel.out.1 	- kernel logs from 1st strace ipcs run

> [   49.465371] info: dee9fca4
> [   49.465397] BUG: unable to handle kernel NULL pointer dereference at virtual address 000000bf
> [   49.465411] printing eip: c0133034 *pde = 00000000
> [   49.465432] Oops: 0002 [#1]
> [   49.465445] Modules linked in: netconsole i915 drm configfs e1000 snd_intel8x0 snd_ac97_codec ac97_bus snd_pcm snd_timer snd snd_page_alloc
> [   49.465534]
> [   49.465542] Pid: 4763, comm: ipcs Not tainted (2.6.24.3test-dirty #22)
> [   49.465550] EIP: 0060:[<c0133034>] EFLAGS: 00010086 CPU: 0
> [   49.465568] EIP is at __lock_acquire+0x319/0xc20
> [   49.465575] EAX: 00000002 EBX: 00000246 ECX: dee9fcb4 EDX: 00000002
> [   49.465583] ESI: ffffffff EDI: 00000000 EBP: d8c9fe74 ESP: d8c9fe18
> [   49.465591]  DS: 007b ES: 007b FS: 0000 GS: 0033 SS: 0068
> [   49.465599] Process ipcs (pid: 4763, ti=d8c9e000 task=d8d181b0 task.ti=d8c9e000)
> [   49.465606] Stack: 00000012 c0456032 d8c9fea4 00000246 d8c9fe66 c03875bc 00000002 00000000
> [   49.465667]        00000000 dee9fcb4 845dad28 d8d181b0 00000031 00000000 000001e1 00000246
> [   49.465731]        00000021 845dad28 1bbd0328 373c0000 00000246 00000000 00000000 d8c9fe9c
> [   49.465792] Call Trace:
> [   49.465803]  [<c0103e9f>] show_trace_log_lvl+0x1a/0x2f
> [   49.465820]  [<c0103f51>] show_stack_log_lvl+0x9d/0xa5
> [   49.465841]  [<c0104006>] show_registers+0xad/0x17c
> [   49.465859]  [<c01041cc>] die+0xf7/0x1c8
> [   49.465872]  [<c0110e31>] do_page_fault+0x464/0x54b
> [   49.465886]  [<c030108a>] error_code+0x6a/0x70
> [   49.465904]  [<c01339b3>] lock_acquire+0x78/0x91
> [   49.465918]  [<c0300a55>] _spin_lock+0x2e/0x58
> [   49.465931]  [<c01b8e91>] sys_shmctl+0x70b/0x78a
> [   49.465947]  [<c0106d33>] sys_ipc+0x19f/0x1b5
> [   49.465964]  [<c0102fa6>] syscall_call+0x7/0xb
> [   49.465977]  =======================
> [   49.465984] Code: 00 85 c0 0f 84 1d 09 00 00 83 3d 00 bf 6f c0 00 0f 85 
> 10 09 00 00 c7 44 24 0c c8 8b 30 c0 c7 44 24 08 26 03 00 00 e9 8b 07 00 
> 00 <ff> 86 c0 00 00 00 8b 45 d0 8b 80 54 06 00 00 83 f8 1d 89 45 cc

OK, so the actual NULL pointer dereference happens inside atomic_inc(), 
during incrementing of counter->v (as %esi == 0xffffffff). So apparently 
someone corrupted spinlock_t for shmem_inode_info.lock, which is being 
obtained in shm_get_stat().

-- 
Jiri Kosina
SUSE Labs
--
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at  http://vger.kernel.org/majordomo-info.html
Please read the FAQ at  http://www.tux.org/lkml/