Re: linux-next 5/5 does not boot

[Daniel Hazelton]

On Tuesday 06 May 2008 01:37:46 Stephen Rothwell wrote:
> cc'ing Ingo ...

About this error - should I bother trying the 5/6 tree? If I'm going to be 
stuck with a lock-up during boot I'm wondering if I should try a test before 
seeing any action on this bug. (I realize I could just turn off the ftrace 
code, but I am attempting to do some "regular use" testing of the -next tree 
with new options (well, those that I think might become defaulted to "on") 
enabled)

DRH

> On Mon, 5 May 2008 20:04:33 -0400 Daniel Hazelton <dhazelton@xxxxxxxxx> 
wrote:
> > This may be related to one of the stack dumps I reported earlier as
> > occurring when booting the 4/30 linux-next tree...
> >
> > During two attempted boots of the 5/5 tree using the same config as the
> > 4/30 tree the system locked solid at "testing tracer ftrace".
> >
> > The 4/30 tree produced the following dump (the 5/5 tree locks solid as
> > soon as the test is started):
> > [   10.238230] Testing tracer ftrace: <4>------------[ cut here
> > ]------------ [   10.356558] WARNING: at kernel/lockdep.c:2712
> > check_flags+0xfb/0x180() [   10.356726] Modules linked in:
> > [   10.356915] Pid: 1, comm: swapper Not tainted 2.6.25-next-20080430 #2
> > [   10.357718]
> > [   10.357722] Call Trace:
> > [   10.357939]  <IRQ>  [<ffffffff8023d5f4>] warn_on_slowpath+0x64/0x90
> > [   10.358174]  [<ffffffff8025f789>] ? tick_get_tick_sched+0x9/0x30
> > [   10.358337]  [<ffffffff8022f048>] ? __update_rq_clock+0x68/0x140
> > [   10.358501]  [<ffffffff8026173d>] ? trace_hardirqs_off+0xd/0x10
> > [   10.358665]  [<ffffffff8026173d>] ? trace_hardirqs_off+0xd/0x10
> > [   10.358827]  [<ffffffff802953ce>] ? function_trace_call+0x9e/0xa0
> > [   10.358992]  [<ffffffff8020c4b4>] ? ftrace_call+0x5/0x2b
> > [   10.359148]  [<ffffffff8023177a>] ? cpu_clock+0xda/0x190
> > [   10.359302]  [<ffffffff80262d7b>] check_flags+0xfb/0x180
> > [   10.359457]  [<ffffffff80265635>] lock_acquire+0x65/0xd0
> > [   10.359614]  [<ffffffff80231799>] cpu_clock+0xf9/0x190
> > [   10.359769]  [<ffffffff80293de9>] ftrace_now+0x9/0x10
> > [   10.359920]  [<ffffffff80295194>] trace_function+0xc4/0x260
> > [   10.360079]  [<ffffffff80243723>] ? _local_bh_enable+0x63/0xc0
> > [   10.360240]  [<ffffffff804aeec9>] ? sub_preempt_count+0x9/0x90
> > [   10.360400]  [<ffffffff80243723>] ? _local_bh_enable+0x63/0xc0
> > [   10.360561]  [<ffffffff804aeec9>] ? sub_preempt_count+0x9/0x90
> > [   10.360721]  [<ffffffff8029539c>] function_trace_call+0x6c/0xa0
> > [   10.360883]  [<ffffffff8020c4b4>] ftrace_call+0x5/0x2b
> > [   10.361038]  [<ffffffff804aeec9>] ? sub_preempt_count+0x9/0x90
> > [   10.361199]  [<ffffffff80243723>] _local_bh_enable+0x63/0xc0
> > [   10.361357]  [<ffffffff8024399e>] __do_softirq+0xfe/0x150
> > [   10.361514]  [<ffffffff8020d93c>] call_softirq+0x1c/0x30
> > [   10.361669]  [<ffffffff8020f76a>] do_softirq+0x6a/0xb0
> > [   10.361823]  [<ffffffff80244155>] irq_exit+0xa5/0xb0
> > [   10.361975]  [<ffffffff802204ee>] smp_apic_timer_interrupt+0x9e/0xf0
> > [   10.362143]  [<ffffffff8020d3fb>] apic_timer_interrupt+0x6b/0x70
> > [   10.362301]  <EOI>  [<ffffffff804ab808>] ?
> > _spin_unlock_irqrestore+0x68/0x80
> > [   10.362543]  [<ffffffff8023540b>] ? try_to_wake_up+0xab/0x270
> > [   10.362704]  [<ffffffff8020c4b4>] ? ftrace_call+0x5/0x2b
> > [   10.362860]  [<ffffffff80235625>] ? wake_up_process+0x15/0x20
> > [   10.362979]  [<ffffffff80255264>] ? kthread_create+0xa4/0x120
> > [   10.362979]  [<ffffffff8027a390>] ? do_stop+0x0/0x190
> > [   10.362979]  [<ffffffff80265a6e>] ? mark_held_locks+0x3e/0x80
> > [   10.362979]  [<ffffffff80265d4d>] ? trace_hardirqs_on+0xd/0x10
> > [   10.362979]  [<ffffffff80265c85>] ?
> > trace_hardirqs_on_caller+0xf5/0x1b0 [   10.362979]  [<ffffffff80263f2b>]
> > ? lockdep_init_map+0x4b/0x140 [   10.362979]  [<ffffffff8037d6f8>] ?
> > __spin_lock_init+0x38/0x70 [   10.362979]  [<ffffffff80255714>] ?
> > init_waitqueue_head+0x24/0x40 [   10.362979]  [<ffffffff8028ffc0>] ?
> > __ftrace_modify_code+0x0/0xa0 [   10.362979]  [<ffffffff8027a185>] ?
> > __stop_machine_run+0x85/0xf0 [   10.362979]  [<ffffffff8028ffc0>] ?
> > __ftrace_modify_code+0x0/0xa0 [   10.362979]  [<ffffffff8028ffc0>] ?
> > __ftrace_modify_code+0x0/0xa0 [   10.362979]  [<ffffffff8028ffc0>] ?
> > __ftrace_modify_code+0x0/0xa0 [   10.362979]  [<ffffffff8027a223>] ?
> > stop_machine_run+0x33/0x60 [   10.362979]  [<ffffffff8028f3a0>] ?
> > ftrace_run_update_code+0x20/0x30 [   10.362979]  [<ffffffff8028f7ea>] ?
> > unregister_ftrace_function+0x14a/0x190 [   10.362979] 
> > [<ffffffff80292e70>] ? tracing_stop_function_trace+0x10/0x20 [  
> > 10.362979]  [<ffffffff80296759>] ? stop_function_trace+0x9/0x20 [  
> > 10.362979]  [<ffffffff80296787>] ? function_trace_ctrl_update+0x17/0x20 [
> >   10.362979]  [<ffffffff80292d5e>] ?
> > trace_selftest_startup_function+0xae/0x140
> > [   10.362979]  [<ffffffff8029220d>] ? register_tracer+0x11d/0x1f0
> > [   10.362979]  [<ffffffff80665d50>] ? init_function_trace+0x10/0x20
> > [   10.362979]  [<ffffffff8064eee4>] ? kernel_init+0x164/0x2f0
> > [   10.362979]  [<ffffffff804aeec9>] ? sub_preempt_count+0x9/0x90
> > [   10.362979]  [<ffffffff804ab77b>] ? _spin_unlock_irq+0x3b/0x60
> > [   10.362979]  [<ffffffff804aab1d>] ? trace_hardirqs_on_thunk+0x3a/0x3f
> > [   10.362979]  [<ffffffff80265c85>] ?
> > trace_hardirqs_on_caller+0xf5/0x1b0 [   10.362979]  [<ffffffff8020d5d8>]
> > ? child_rip+0xa/0x12
> > [   10.362979]  [<ffffffff8020cc63>] ? restore_args+0x0/0x30
> > [   10.362979]  [<ffffffff8064ed80>] ? kernel_init+0x0/0x2f0
> > [   10.362979]  [<ffffffff8020d5ce>] ? child_rip+0x0/0x12
> > [   10.362979]
> > [   10.362979] ---[ end trace 5a5d197966b56a2e ]---
> > [   10.362979] irq event stamp: 3391956
> > [   10.362979] hardirqs last  enabled at (3391954): [<ffffffff80265d4d>]
> > trace_hardirqs_on+0xd/0x10
> > [   10.362979] hardirqs last disabled at (3391955): [<ffffffff8026173d>]
> > trace_hardirqs_off+0xd/0x10
> > [   10.362979] softirqs last  enabled at (3391956): [<ffffffff8024399e>]
> > __do_softirq+0xfe/0x150
> > [   10.362979] softirqs last disabled at (3391933): [<ffffffff8020d93c>]
> > call_softirq+0x1c/0x30
> > [   10.376978] kstopmachine used greatest stack depth: 6000 bytes left
> > [   10.377650] PASSED
> >
> > config for 5/5 tree will be provided on request. Dmesg from 4/30 tree is
> > also available. System had not mounted the root drive before the ftrace
> > crash, so I have no available logs or dmesg from the 5/5 tree. If asked,
> > I will attempt to setup a netconsole and try to capture the logs there.
> >
> > DRH



-- 
Dialup is like pissing through a pipette. Slow and excruciatingly painful.
--
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/