tracing self-test lockup on one testbox: [ 15.733021] initcall taskstats_init+0x0/0x7c returned 0 after 3906 usecs [ 15.740021] calling clear_boot_tracer+0x0/0x29 @ 1 [ 15.745022] initcall clear_boot_tracer+0x0/0x29 returned 0 after 0 usecs [ 15.752022] calling event_trace_self_tests_init+0x0/0x2f @ 1 [ 15.757020] Running tests on trace events: [ 15.761020] Testing event kfree_skb: OK [ 15.769251] Testing event kmalloc: OK [ 15.776252] Testing event kmem_cache_alloc: OK [ 15.784250] Testing event kmalloc_node: OK [ 15.792251] Testing event kmem_cache_alloc_node: OK [ 15.800254] Testing event kfree: OK [ 15.807254] Testing event kmem_cache_free: OK [ 15.815251] Testing event irq_handler_exit: OK [ 15.823251] Testing event irq_handler_entry: OK [ 15.831252] Testing event softirq_entry: OK [ 15.839256] Testing event softirq_exit: OK [ 15.847250] Testing event lock_acquired: OK [ 15.855257] Testing event lock_acquire: OK [ 15.863271] Testing event lock_release: OK [ 15.871268] Testing event lock_contended: OK [ 15.879256] Testing event sched_kthread_stop: OK [ 15.887249] Testing event sched_kthread_stop_ret: OK [ 15.895256] Testing event sched_wait_task: OK [ 15.903250] Testing event sched_wakeup: OK [ 15.911257] Testing event sched_wakeup_new: OK [ 15.919251] Testing event sched_switch: OK [ 15.927252] Testing event sched_migrate_task: OK [ 15.935254] Testing event sched_process_free: OK [ 15.943251] Testing event sched_process_exit: OK [ 15.951255] Testing event sched_process_wait: OK [ 15.959254] Testing event sched_process_fork: OK [ 15.967248] Testing event sched_signal_send: OK [ 15.975255] Running tests on trace event systems: [ 15.979021] Testing event system skb: OK [ 15.987254] Testing event system kmem: OK [ 15.995376] Testing event system irq: OK [ 16.003287] Testing event system lockdep: OK [ 16.011348] Testing event system sched: OK [ 16.019385] Running tests on all trace events: [ 16.023020] Testing all events: OK [ 16.033417] Running tests again, along with the function tracer [ 16.039141] Running tests on trace events: [ 16.043063] Testing event kfree_skb: OK [ 16.052521] Testing event kmalloc: OK [ 16.061510] Testing event kmem_cache_alloc: OK [ 16.071902] Testing event kmalloc_node: OK [ 16.081756] Testing event kmem_cache_alloc_node: OK [ 16.091759] Testing event kfree: OK [ 16.100517] Testing event kmem_cache_free: OK [ 16.110523] Testing event irq_handler_exit: OK [ 16.120524] Testing event irq_handler_entry: OK [ 16.130521] Testing event softirq_entry: OK [ 16.139833] Testing event softirq_exit: OK [ 16.149597] Testing event lock_acquired: OK [ 16.160062] Testing event lock_acquire: OK [ 16.171719] Testing event lock_release: <0>BUG: spinlock lockup on CPU#0, swapper/0, 80cd0240 [ 16.176013] Pid: 0, comm: swapper Not tainted 2.6.30-rc2-tip-01538-ge8201b9-dirty #34096 [ 16.176013] Call Trace: [ 16.176013] [<80944340>] ? printk+0x15/0x1d [ 16.176013] [<803a410a>] _raw_spin_lock+0x19a/0x1b0 [ 16.176013] [<80947bb7>] _spin_lock_irqsave+0x57/0x70 [ 16.176013] [<8012b2ca>] __wake_up+0x1a/0x50 [ 16.176013] [<8019392f>] trace_wake_up+0x2f/0x40 [ 16.176013] [<80193984>] trace_current_buffer_unlock_commit+0x44/0x50 [ 16.176013] [<8019cf15>] function_test_events_call+0xb5/0xc0 [ 16.176013] [<80393a05>] ? vbin_printf+0x105/0x1e0 [ 16.176013] [<803953d4>] ? strlen+0x4/0x20 [ 16.176013] [<80103eff>] ftrace_call+0x5/0x8 [ 16.176013] [<803953d9>] ? strlen+0x9/0x20 [ 16.176013] [<80393a05>] vbin_printf+0x105/0x1e0 [ 16.176013] [<80192824>] ? trace_vbprintk+0xf4/0x220 [ 16.176013] [<801968e5>] ? __trace_bprintk+0x45/0x50 [ 16.176013] [<8015a222>] ? clocksource_get_next+0x42/0x50 [ 16.176013] [<80192845>] trace_vbprintk+0x115/0x220 [ 16.176013] [<809478bd>] ? _spin_unlock_irqrestore+0x4d/0x60 [ 16.176013] [<80161e85>] ? lock_release+0x35/0x1f0 [ 16.176013] [<8012b2e7>] ? __wake_up+0x37/0x50 [ 16.176013] [<8015a222>] ? clocksource_get_next+0x42/0x50 [ 16.176013] [<801968e5>] __trace_bprintk+0x45/0x50 [ 16.176013] [<8015f295>] ftrace_event_lock_release+0x25/0x30 [ 16.176013] [<80161e85>] ? lock_release+0x35/0x1f0 [ 16.176013] [<8015f270>] ? ftrace_event_lock_release+0x0/0x30 [ 16.176013] [<80161e85>] lock_release+0x35/0x1f0 [ 16.176013] [<8094788e>] _spin_unlock_irqrestore+0x1e/0x60 [ 16.176013] [<8015a222>] clocksource_get_next+0x42/0x50 [ 16.176013] [<80158bc1>] update_wall_time+0x3f1/0x8b0 [ 16.176013] [<80143e39>] ? do_timer+0x9/0x110 [ 16.176013] [<80143e53>] do_timer+0x23/0x110 [ 16.176013] [<8015c688>] tick_periodic+0x68/0x80 [ 16.176013] [<8015c6be>] tick_handle_periodic+0x1e/0x80 [ 16.176013] [<80117036>] smp_apic_timer_interrupt+0x56/0x90 [ 16.176013] [<80103c76>] apic_timer_interrupt+0x36/0x40 [ 16.176013] [<8011dd6a>] ? native_safe_halt+0xa/0x10 [ 16.176013] [<8010a34d>] default_idle+0x9d/0x150 [ 16.176013] [<80197c6e>] ? stop_critical_timings+0x6e/0x120 [ 16.176013] [<8010200d>] ? cpu_idle+0xbd/0x100 [ 16.176013] [<80911c98>] ? rest_init+0x58/0x60 [ 16.176013] [<80102013>] cpu_idle+0xc3/0x100 [ 16.176013] [<80911c98>] rest_init+0x58/0x60 [ 16.176013] [<80d9b819>] start_kernel+0x2fc/0x339 [ 16.176013] [<80d9b037>] __init_begin+0x37/0x3c config attached. v2.6.30-rc2-1538-ge8201b9. Ingo