Re: rcu: BUG: spinlock recursion on CPU#3, trinity-child19/5970

Paul E. McKenney
2012-06-30T13:16:01Z

On Sat, Jun 30, 2012 at 01:36:48PM +0200, Sasha Levin wrote:
> Hi Paul,
>
> On Fri, 2012-06-29 at 10:23 -0700, Paul E. McKenney wrote:
> > Please see below for an untested patch that gets RCU out of this loop,
> > but it is quite possible that something else is involved here, so it would
> > be very good to get a lockdep run, if possible. My concern stems from the
> > fact that interrupts were enabled during the call to __rcu_read_unlock()
> > -- otherwise it would not have been preempted -- so the runqueue locks
> > were presumably not held on entry to __rcu_read_unlock().
>
> I'll continue with the good news/bad news theme.
>
> Good news - the original problem I've reported seems to be gone.
>
> Bad news - I'm getting the following instead:

Interesting...

You are still running with lockdep?

Thanx, Paul

> [ 6331.841058] BUG: spinlock recursion on CPU#3, trinity-child45/25254
> [ 6331.841066] lock: 0xffff880035bd6340, .magic: dead4ead, .owner: trinity-child45/25254, .owner_cpu: 3
> [ 6331.841066] Pid: 25254, comm: trinity-child45 Tainted: G W 3.5.0-rc4-next-20120629-sasha-00007-g3fd449c #488
> [ 6331.841066] Call Trace:
> [ 6331.841079] [<ffffffff8199ba38>] spin_dump+0x78/0xc0
> [ 6331.841079] [<ffffffff8199baab>] spin_bug+0x2b/0x40
> [ 6331.841079] [<ffffffff8199bbee>] do_raw_spin_lock+0x4e/0x140
> [ 6331.841079] [<ffffffff8372bb9b>] _raw_spin_lock+0x5b/0x70
> [ 6331.841091] [<ffffffff81130291>] ? rt_mutex_setprio+0x81/0x2c0
> [ 6331.841096] [<ffffffff81130291>] rt_mutex_setprio+0x81/0x2c0
> [ 6331.841096] [<ffffffff8116a0a0>] __rt_mutex_adjust_prio+0x20/0x30
> [ 6331.841096] [<ffffffff8372b1d4>] rt_mutex_slowunlock+0x104/0x130
> [ 6331.841096] [<ffffffff8372b209>] rt_mutex_unlock+0x9/0x10
> [ 6331.841098] [<ffffffff811a8d10>] rcu_read_unlock_special+0x350/0x400
> [ 6331.841101] [<ffffffff8113fe58>] ? update_curr_rt+0x228/0x270
> [ 6331.841104] [<ffffffff8115d35a>] ? get_lock_stats+0x2a/0x60
> [ 6331.841106] [<ffffffff811a8fea>] rcu_preempt_note_context_switch+0x22a/0x300
> [ 6331.841108] [<ffffffff8372a93a>] __schedule+0x76a/0x880
> [ 6331.841111] [<ffffffff8372ab41>] preempt_schedule+0x51/0x70
> [ 6331.841115] [<ffffffff81071640>] ? read_persistent_clock+0x30/0x30
> [ 6331.841119] [<ffffffff81984e23>] delay_tsc+0x123/0x140
> [ 6331.841121] [<ffffffff81984cf8>] __const_udelay+0x28/0x30
> [ 6331.841125] [<ffffffff81115b54>] __rcu_read_unlock+0x44/0xb0
> [ 6331.841129] [<ffffffff8126943d>] __d_lookup+0x2dd/0x2f0
> [ 6331.841131] [<ffffffff8199bb90>] ? do_raw_spin_unlock+0xd0/0xe0
> [ 6331.841134] [<ffffffff8125c8ae>] lookup_fast+0x1ae/0x2e0
> [ 6331.841139] [<ffffffff818c61fd>] ? security_inode_permission+0x1d/0x20
> [ 6331.841141] [<ffffffff8125d4e4>] link_path_walk+0x204/0x420
> [ 6331.841144] [<ffffffff8125a0a0>] ? generic_readlink+0xb0/0xb0
> [ 6331.841146] [<ffffffff8125e024>] path_lookupat+0x54/0x700
> [ 6331.841150] [<ffffffff8123a134>] ? kmem_cache_alloc+0x154/0x240
> [ 6331.841153] [<ffffffff8111f168>] ? __hrtimer_start_range_ns+0x448/0x490
> [ 6331.841156] [<ffffffff8125e6fc>] do_path_lookup+0x2c/0xc0
> [ 6331.841158] [<ffffffff8115d35a>] ? get_lock_stats+0x2a/0x60
> [ 6331.841161] [<ffffffff8125f6bc>] user_path_at_empty+0x6c/0xb0
> [ 6331.841163] [<ffffffff8116227d>] ? trace_hardirqs_on+0xd/0x10
> [ 6331.841166] [<ffffffff8372c6e9>] ? _raw_spin_unlock_irq+0x59/0x80
> [ 6331.841169] [<ffffffff8125f70c>] user_path_at+0xc/0x10
> [ 6331.841172] [<ffffffff81274d61>] sys_setxattr+0x41/0xb0
> [ 6331.841174] [<ffffffff81985f3e>] ? trace_hardirqs_on_thunk+0x3a/0x3f
> [ 6331.841177] [<ffffffff8372d5b9>] system_call_fastpath+0x16/0x1b
> [ 6331.906964] hardirqs last enabled at (1166831): [<ffffffff83728ef5>] __mutex_unlock_slowpath+0x1a5/0x200
> [ 6331.906964] hardirqs last disabled at (1166832): [<ffffffff8372bce4>] _raw_spin_lock_irqsave+0x54/0xc0
> [ 6331.906964] softirqs last enabled at (1166798): [<ffffffff8319b5c4>] unlist_netdevice+0xb4/0xe0
> [ 6331.906964] softirqs last disabled at (1166796): [<ffffffff8372bf43>] _raw_write_lock_bh+0x13/0x80
> [ 6331.954033] BUG: spinlock lockup suspected on CPU#2, swapper/2/0
> [ 6331.954033] lock: 0xffff88000d9d6340, .magic: dead4ead, .owner: rcub/5/12, .owner_cpu: 0
> [ 6331.954033] Pid: 0, comm: swapper/2 Tainted: G W 3.5.0-rc4-next-20120629-sasha-00007-g3fd449c #488
> [ 6331.954033] Call Trace:
> [ 6331.954033] <IRQ> [<ffffffff8199ba38>] spin_dump+0x78/0xc0
> [ 6331.954033] [<ffffffff8199bc9e>] do_raw_spin_lock+0xfe/0x140
> [ 6331.954033] [<ffffffff8372bb9b>] _raw_spin_lock+0x5b/0x70
> [ 6331.954033] [<ffffffff8113a150>] ? load_balance+0x2b0/0x6e0
> [ 6331.954033] [<ffffffff8113a150>] load_balance+0x2b0/0x6e0
> [ 6331.954033] [<ffffffff8113ab2e>] ? rebalance_domains+0x17e/0x290
> [ 6331.954033] [<ffffffff81137f20>] ? find_busiest_group+0x2a0/0x2a0
> [ 6331.954033] [<ffffffff8113ab6d>] rebalance_domains+0x1bd/0x290
> [ 6331.954033] [<ffffffff8113a9b0>] ? update_shares+0x240/0x240
> [ 6331.954033] [<ffffffff810705bd>] ? sched_clock+0x1d/0x30
> [ 6331.954033] [<ffffffff8113ae53>] run_rebalance_domains+0x43/0x60
> [ 6331.954033] [<ffffffff810f5a81>] __do_softirq+0x221/0x460
> [ 6331.954033] [<ffffffff8109d616>] ? kvm_clock_read+0x46/0x80
> [ 6331.954033] [<ffffffff8372eb6c>] call_softirq+0x1c/0x30
> [ 6331.954033] [<ffffffff8106a215>] do_softirq+0x75/0x120
> [ 6331.954033] [<ffffffff810f4a8b>] irq_exit+0x5b/0xf0
> [ 6331.954033] [<ffffffff81090f5a>] smp_apic_timer_interrupt+0x8a/0xa0
> [ 6331.954033] [<ffffffff8372e16f>] apic_timer_interrupt+0x6f/0x80
> [ 6331.954033] <EOI> [<ffffffff81072eb8>] ? default_idle+0x1a8/0x390
> [ 6331.954033] [<ffffffff8109d7e6>] ? native_safe_halt+0x6/0x10
> [ 6331.954033] [<ffffffff8116227d>] ? trace_hardirqs_on+0xd/0x10
> [ 6331.954033] [<ffffffff81072ed5>] default_idle+0x1c5/0x390
> [ 6331.954033] [<ffffffff8107244f>] cpu_idle+0x11f/0x180
> [ 6331.954033] [<ffffffff8371fed7>] ? setup_APIC_timer+0x88/0x8d
> [ 6331.954033] [<ffffffff8371f512>] start_secondary+0xd7/0xde
> [ 6331.954033] sending NMI to all CPUs:
> [ 6331.954033] NMI backtrace for cpu 2
> [ 6331.954033] CPU 2
> [ 6331.954033] Pid: 0, comm: swapper/2 Tainted: G W 3.5.0-rc4-next-20120629-sasha-00007-g3fd449c #488
> [ 6331.954033] RIP: 0010:[<ffffffff8109d6aa>] [<ffffffff8109d6aa>] native_write_msr_safe+0xa/0x10
> [ 6331.954033] RSP: 0018:ffff880029803bf0 EFLAGS: 00000086
> [ 6331.954033] RAX: 0000000000000400 RBX: 0000000000000002 RCX: 0000000000000830
> [ 6331.954033] RDX: 0000000000000002 RSI: 0000000000000400 RDI: 0000000000000830
> [ 6331.954033] RBP: ffff880029803bf0 R08: ffffffff84ee09c0 R09: 0000000000000000
> [ 6331.954033] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000002
> [ 6331.954033] R13: ffffffff84ee09c0 R14: 0000000000000002 R15: 000000000000b024
> [ 6331.954033] FS: 0000000000000000(0000) GS:ffff880029800000(0000) knlGS:0000000000000000
> [ 6331.954033] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b
> [ 6331.954033] CR2: 0000000000000004 CR3: 000000001527e000 CR4: 00000000000406e0
> [ 6331.954033] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
> [ 6331.954033] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
> [ 6331.954033] Process swapper/2 (pid: 0, threadinfo ffff88000d5ca000, task ffff88000d5c3000)
> [ 6331.954033] Stack:
> [ 6331.954033] ffff880029803c60 ffffffff8109602d 0000000000000000 ffff880029803d60
> [ 6331.954033] ffff880029803c70 0000000000000086 0000000000000002 0000000200080000
> [ 6331.954033] ffff880029803c40 0000000000000000 000000009486a4e0 000000009486a4e0
> [ 6331.954033] Call Trace:
> [ 6331.954033] <IRQ>
> [ 6331.954033] [<ffffffff8109602d>] __x2apic_send_IPI_mask+0xdd/0x1b0
> [ 6331.954033] [<ffffffff81096117>] x2apic_send_IPI_all+0x17/0x20
> [ 6331.954033] [<ffffffff810917b4>] arch_trigger_all_cpu_backtrace+0x64/0xb0
> [ 6331.954033] [<ffffffff8199bca3>] do_raw_spin_lock+0x103/0x140
> [ 6331.954033] [<ffffffff8372bb9b>] _raw_spin_lock+0x5b/0x70
> [ 6331.954033] [<ffffffff8113a150>] ? load_balance+0x2b0/0x6e0
> [ 6331.954033] [<ffffffff8113a150>] load_balance+0x2b0/0x6e0
> [ 6331.954033] [<ffffffff8113ab2e>] ? rebalance_domains+0x17e/0x290
> [ 6331.954033] [<ffffffff81137f20>] ? find_busiest_group+0x2a0/0x2a0
> [ 6331.954033] [<ffffffff8113ab6d>] rebalance_domains+0x1bd/0x290
> [ 6331.954033] [<ffffffff8113a9b0>] ? update_shares+0x240/0x240
> [ 6331.954033] [<ffffffff810705bd>] ? sched_clock+0x1d/0x30
> [ 6331.954033] [<ffffffff8113ae53>] run_rebalance_domains+0x43/0x60
> [ 6331.954033] [<ffffffff810f5a81>] __do_softirq+0x221/0x460
> [ 6331.954033] [<ffffffff8109d616>] ? kvm_clock_read+0x46/0x80
> [ 6331.954033] [<ffffffff8372eb6c>] call_softirq+0x1c/0x30
> [ 6331.954033] [<ffffffff8106a215>] do_softirq+0x75/0x120
> [ 6331.954033] [<ffffffff810f4a8b>] irq_exit+0x5b/0xf0
> [ 6331.954033] [<ffffffff81090f5a>] smp_apic_timer_interrupt+0x8a/0xa0
> [ 6331.954033] [<ffffffff8372e16f>] apic_timer_interrupt+0x6f/0x80
> [ 6331.954033] <EOI>
> [ 6331.954033] [<ffffffff81072eb8>] ? default_idle+0x1a8/0x390
> [ 6331.954033] [<ffffffff8109d7e6>] ? native_safe_halt+0x6/0x10
> [ 6331.954033] [<ffffffff8116227d>] ? trace_hardirqs_on+0xd/0x10
> [ 6331.954033] [<ffffffff81072ed5>] default_idle+0x1c5/0x390
> [ 6331.954033] [<ffffffff8107244f>] cpu_idle+0x11f/0x180
> [ 6331.954033] [<ffffffff8371fed7>] ? setup_APIC_timer+0x88/0x8d
> [ 6331.954033] [<ffffffff8371f512>] start_secondary+0xd7/0xde
> [ 6331.954033] Code: 00 55 89 f9 48 89 e5 0f 32 45 31 c0 89 c7 44 89 06 48 89 d0 89 f9 48 c1 e0 20 c9 48 09 c8 c3 66 90 55 89 f0 48 89 e5 89 f9 0f 30 <31> c0 c9 c3 66 90 55 89 f9 48 89 e5 0f 33 89 c7 c9 48 89 d0 89
> [ 6331.906944] NMI backtrace for cpu 0
> [ 6331.906944] CPU 0
> [ 6331.906944] Pid: 12, comm: rcub/5 Tainted: G W 3.5.0-rc4-next-20120629-sasha-00007-g3fd449c #488
> [ 6331.906944] RIP: 0010:[<ffffffff8199d552>] [<ffffffff8199d552>] debug_smp_processor_id+0x102/0x110
> [ 6331.906944] RSP: 0018:ffff88000d5799f0 EFLAGS: 00000002
> [ 6331.906944] RAX: 0000000000000000 RBX: 0000000000000000 RCX: 0000000000000000
> [ 6331.906944] RDX: 000000002acf2ace RSI: 0000000000000003 RDI: 0000000000000001
> [ 6331.906944] RBP: ffff88000d579a10 R08: 0000000000000000 R09: 0000000000000001
> [ 6331.906944] R10: 0000000000000000 R11: 0000000000000001 R12: ffff88000d579fd8
> [ 6331.906944] R13: 0000000000000001 R14: 0000000000000001 R15: ffff880035bd6340
> [ 6331.906944] FS: 0000000000000000(0000) GS:ffff88000d800000(0000) knlGS:0000000000000000
> [ 6331.906944] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b
> [ 6331.906944] CR2: 0000000000000800 CR3: 000000001a3ec000 CR4: 00000000000406f0
> [ 6331.906944] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
> [ 6331.906944] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
> [ 6331.906944] Process rcub/5 (pid: 12, threadinfo ffff88000d578000, task ffff88000d573000)
> [ 6331.906944] Stack:
> [ 6331.906944] ffff88000d579a70 ffff880035bd6340 000000008b304187 0000000000000001
> [ 6331.906944] ffff88000d579a50 ffffffff81984d23 ffff88000d579aa0 ffff880035bd6340
> [ 6331.906944] 000000008b304187 000000009486a4e0 0000000000000001 ffff880035bd6340
> [ 6331.906944] Call Trace:
> [ 6331.906944] [<ffffffff81984d23>] delay_tsc+0x23/0x140
> [ 6331.906944] [<ffffffff81984cca>] __delay+0xa/0x10
> [ 6331.906944] [<ffffffff8199bc7d>] do_raw_spin_lock+0xdd/0x140
> [ 6331.906944] [<ffffffff8372bb18>] _raw_spin_lock_nested+0x68/0x90
> [ 6331.906944] [<ffffffff8113f7a2>] ? pull_rt_task+0xe2/0x270
> [ 6331.906944] [<ffffffff8372bba7>] ? _raw_spin_lock+0x67/0x70
> [ 6331.906944] [<ffffffff8113f795>] ? pull_rt_task+0xd5/0x270
> [ 6331.906944] [<ffffffff8113f7a2>] pull_rt_task+0xe2/0x270
> [ 6331.906944] [<ffffffff8113f9e4>] pre_schedule_rt+0x14/0x20
> [ 6331.906944] [<ffffffff8372a429>] __schedule+0x259/0x880
> [ 6331.906944] [<ffffffff8372abb5>] schedule+0x55/0x60
> [ 6331.906944] [<ffffffff8372b2bf>] __rt_mutex_slowlock+0xaf/0x110
> [ 6331.906944] [<ffffffff8372b40f>] rt_mutex_slowlock+0xef/0x180
> [ 6331.906944] [<ffffffff8372b520>] rt_mutex_lock+0x30/0x40
> [ 6331.906944] [<ffffffff811a406a>] rcu_boost+0xca/0x110
> [ 6331.906944] [<ffffffff8372b101>] ? rt_mutex_slowunlock+0x31/0x130
> [ 6331.906944] [<ffffffff811ab972>] rcu_boost_kthread+0x222/0x390
> [ 6331.906944] [<ffffffff811ab750>] ? synchronize_rcu_expedited+0x220/0x220
> [ 6331.906944] [<ffffffff81119e92>] kthread+0xb2/0xc0
> [ 6331.906944] [<ffffffff8372ea74>] kernel_thread_helper+0x4/0x10
> [ 6331.906944] [<ffffffff8372ccf4>] ? retint_restore_args+0x13/0x13
> [ 6331.906944] [<ffffffff81119de0>] ? __init_kthread_worker+0x70/0x70
> [ 6331.906944] [<ffffffff8372ea70>] ? gs_change+0x13/0x13
> [ 6331.906944] Code: d8 01 48 8b 75 08 48 c7 c7 d6 2b 36 84 e8 27 11 7d ff e8 4e ab d8 01 65 48 8b 04 25 88 d9 00 00 ff 88 44 e0 ff ff 0f 1f 00 89 d8 <4c> 8b 65 f0 48 8b 5d e8 4c 8b 6d f8 c9 c3 55 48 89 e5 48 83 ec
> [ 6331.955620] NMI backtrace for cpu 1
> [ 6331.955620] CPU 1
> [ 6331.955620] Pid: 24853, comm: trinity-child19 Tainted: G W 3.5.0-rc4-next-20120629-sasha-00007-g3fd449c #488
> [ 6331.955620] RIP: 0010:[<ffffffff81132ac2>] [<ffffffff81132ac2>] try_to_wake_up+0x132/0x290
> [ 6331.955620] RSP: 0018:ffff880012af9af8 EFLAGS: 00000002
> [ 6331.955620] RAX: ffff88000d578000 RBX: ffff88000d573000 RCX: 0000000003530352
> [ 6331.955620] RDX: 0000000000000002 RSI: 0000000000000001 RDI: 0000000000000086
> [ 6331.955620] RBP: ffff880012af9b38 R08: 0000000000000000 R09: 0000000000000000
> [ 6331.955620] R10: 0000000000000000 R11: 0000000000000000 R12: ffff88000d573838
> [ 6331.955620] R13: 0000000000000000 R14: 0000000000000286 R15: 0000000000000000
> [ 6331.955620] FS: 00007f9e2b631700(0000) GS:ffff88001b800000(0000) knlGS:0000000000000000
> [ 6331.955620] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> [ 6331.955620] CR2: 0000000000000001 CR3: 0000000022870000 CR4: 00000000000406e0
> [ 6331.955620] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
> [ 6331.955620] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
> [ 6331.955620] Process trinity-child19 (pid: 24853, threadinfo ffff880012af8000, task ffff880012b10000)
> [ 6331.955620] Stack:
> [ 6331.955620] ffff880012af9b18 ffff880012b10838 ffff880012b10838 ffff88000d579d50
> [ 6331.955620] ffff880012b10838 ffff880012b10000 ffff88000d579c70 0000000000000286
> [ 6331.955620] ffff880012af9b48 ffffffff81132c50 ffff880012af9b88 ffffffff8372b1b9
> [ 6331.955620] Call Trace:
> [ 6331.955620] [<ffffffff81132c50>] wake_up_process+0x10/0x20
> [ 6331.955620] [<ffffffff8372b1b9>] rt_mutex_slowunlock+0xe9/0x130
> [ 6331.955620] [<ffffffff8372b209>] rt_mutex_unlock+0x9/0x10
> [ 6331.955620] [<ffffffff811a8d10>] rcu_read_unlock_special+0x350/0x400
> [ 6331.955620] [<ffffffff812c6870>] ? proc_sys_compare+0xe0/0x130
> [ 6331.955620] [<ffffffff81115b67>] __rcu_read_unlock+0x57/0xb0
> [ 6331.955620] [<ffffffff8126943d>] __d_lookup+0x2dd/0x2f0
> [ 6331.955620] [<ffffffff8199bb90>] ? do_raw_spin_unlock+0xd0/0xe0
> [ 6331.955620] [<ffffffff8125c8ae>] lookup_fast+0x1ae/0x2e0
> [ 6331.955620] [<ffffffff818c61fd>] ? security_inode_permission+0x1d/0x20
> [ 6331.955620] [<ffffffff8125d4e4>] link_path_walk+0x204/0x420
> [ 6331.955620] [<ffffffff8125a0a0>] ? generic_readlink+0xb0/0xb0
> [ 6331.955620] [<ffffffff810f342b>] ? do_setitimer+0x23b/0x290
> [ 6331.955620] [<ffffffff8125e024>] path_lookupat+0x54/0x700
> [ 6331.955620] [<ffffffff8123a134>] ? kmem_cache_alloc+0x154/0x240
> [ 6331.955620] [<ffffffff8125e6fc>] do_path_lookup+0x2c/0xc0
> [ 6331.955620] [<ffffffff8125f6bc>] user_path_at_empty+0x6c/0xb0
> [ 6331.955620] [<ffffffff8116227d>] ? trace_hardirqs_on+0xd/0x10
> [ 6331.955620] [<ffffffff8372c6e9>] ? _raw_spin_unlock_irq+0x59/0x80
> [ 6331.955620] [<ffffffff8372d5e5>] ? sysret_check+0x22/0x5d
> [ 6331.955620] [<ffffffff8125f70c>] user_path_at+0xc/0x10
> [ 6331.955620] [<ffffffff81273d30>] sys_removexattr+0x30/0x80
> [ 6331.955620] [<ffffffff8372d5b9>] system_call_fastpath+0x16/0x1b
> [ 6331.955620] Code: da ff ff 48 8b 45 c8 48 89 c7 e8 ca 9a 5f 02 e9 31 01 00 00 0f 1f 44 00 00 48 89 c7 e8 b8 9a 5f 02 eb 08 66 0f 1f 44 00 00 f3 90 <8b> 73 28 85 f6 75 f7 31 c0 48 8b 13 83 e2 02 74 0a 0f b7 43 16
> [ 6331.847432] NMI backtrace for cpu 3
> [ 6331.847432] CPU 3
> [ 6331.847432] Pid: 25254, comm: trinity-child45 Tainted: G W 3.5.0-rc4-next-20120629-sasha-00007-g3fd449c #488
> [ 6331.847432] RIP: 0010:[<ffffffff81984d29>] [<ffffffff81984d29>] delay_tsc+0x29/0x140
> [ 6331.847432] RSP: 0018:ffff88000bd938a8 EFLAGS: 00000006
> [ 6331.847432] RAX: 0000000000000003 RBX: ffff880035bd6340 RCX: 0000000000000000
> [ 6331.847432] RDX: 000000002acf2ace RSI: 0000000010000004 RDI: 0000000000000001
> [ 6331.847432] RBP: ffff88000bd938d8 R08: 0000000000000000 R09: 0000000000000000
> [ 6331.847432] R10: 0000000000000000 R11: 000000000000ab08 R12: 000000008aa97388
> [ 6331.847432] R13: 0000000000000001 R14: 0000000000000001 R15: 0000000000000086
> [ 6331.847432] FS: 00007f9e2b631700(0000) GS:ffff880035a00000(0000) knlGS:0000000000000000
> [ 6331.847432] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> [ 6331.847432] CR2: 0000000000000008 CR3: 0000000013c12000 CR4: 00000000000406e0
> [ 6331.847432] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
> [ 6331.847432] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
> [ 6331.847432] Process trinity-child45 (pid: 25254, threadinfo ffff88000bd92000, task ffff88000aea0000)
> [ 6331.847432] Stack:
> [ 6331.847432] ffff88000bd938c8 ffff880035bd6340 000000008aa97388 000000009486a4e0
> [ 6331.847432] 0000000000000001 0000000000000086 ffff88000bd938e8 ffffffff81984cca
> [ 6331.847432] ffff88000bd93928 ffffffff8199bc7d ffff88000bd93928 0000000000000086
> [ 6331.847432] Call Trace:
> [ 6331.847432] [<ffffffff81984cca>] __delay+0xa/0x10
> [ 6331.847432] [<ffffffff8199bc7d>] do_raw_spin_lock+0xdd/0x140
> [ 6331.847432] [<ffffffff8372bb9b>] _raw_spin_lock+0x5b/0x70
> [ 6331.847432] [<ffffffff81130291>] ? rt_mutex_setprio+0x81/0x2c0
> [ 6331.847432] [<ffffffff81130291>] rt_mutex_setprio+0x81/0x2c0
> [ 6331.847432] [<ffffffff8116a0a0>] __rt_mutex_adjust_prio+0x20/0x30
> [ 6331.847432] [<ffffffff8372b1d4>] rt_mutex_slowunlock+0x104/0x130
> [ 6331.847432] [<ffffffff8372b209>] rt_mutex_unlock+0x9/0x10
> [ 6331.847432] [<ffffffff811a8d10>] rcu_read_unlock_special+0x350/0x400
> [ 6331.847432] [<ffffffff8113fe58>] ? update_curr_rt+0x228/0x270
> [ 6331.847432] [<ffffffff8115d35a>] ? get_lock_stats+0x2a/0x60
> [ 6331.847432] [<ffffffff811a8fea>] rcu_preempt_note_context_switch+0x22a/0x300
> [ 6331.847432] [<ffffffff8372a93a>] __schedule+0x76a/0x880
> [ 6331.847432] [<ffffffff8372ab41>] preempt_schedule+0x51/0x70
> [ 6331.847432] [<ffffffff81071640>] ? read_persistent_clock+0x30/0x30
> [ 6331.847432] [<ffffffff81984e23>] delay_tsc+0x123/0x140
> [ 6331.847432] [<ffffffff81984cf8>] __const_udelay+0x28/0x30
> [ 6331.847432] [<ffffffff81115b54>] __rcu_read_unlock+0x44/0xb0
> [ 6331.847432] [<ffffffff8126943d>] __d_lookup+0x2dd/0x2f0
> [ 6331.847432] [<ffffffff8199bb90>] ? do_raw_spin_unlock+0xd0/0xe0
> [ 6331.847432] [<ffffffff8125c8ae>] lookup_fast+0x1ae/0x2e0
> [ 6331.847432] [<ffffffff818c61fd>] ? security_inode_permission+0x1d/0x20
> [ 6331.847432] [<ffffffff8125d4e4>] link_path_walk+0x204/0x420
> [ 6331.847432] [<ffffffff8125a0a0>] ? generic_readlink+0xb0/0xb0
> [ 6331.847432] [<ffffffff8125e024>] path_lookupat+0x54/0x700
> [ 6331.847432] [<ffffffff8123a134>] ? kmem_cache_alloc+0x154/0x240
> [ 6331.847432] [<ffffffff8111f168>] ? __hrtimer_start_range_ns+0x448/0x490
> [ 6331.847432] [<ffffffff8125e6fc>] do_path_lookup+0x2c/0xc0
> [ 6331.847432] [<ffffffff8115d35a>] ? get_lock_stats+0x2a/0x60
> [ 6331.847432] [<ffffffff8125f6bc>] user_path_at_empty+0x6c/0xb0
> [ 6331.847432] [<ffffffff8116227d>] ? trace_hardirqs_on+0xd/0x10
> [ 6331.847432] [<ffffffff8372c6e9>] ? _raw_spin_unlock_irq+0x59/0x80
> [ 6331.847432] [<ffffffff8125f70c>] user_path_at+0xc/0x10
> [ 6331.847432] [<ffffffff81274d61>] sys_setxattr+0x41/0xb0
> [ 6331.847432] [<ffffffff81985f3e>] ? trace_hardirqs_on_thunk+0x3a/0x3f
> [ 6331.847432] [<ffffffff8372d5b9>] system_call_fastpath+0x16/0x1b
> [ 6331.847432] Code: 00 00 55 48 89 e5 41 57 41 56 41 55 49 89 fd 41 54 bf 01 00 00 00 53 48 83 ec 08 e8 52 49 7a ff e8 2d 87 01 00 0f 1f 00 0f ae e8 <48> 83 3d 07 15 ec 02 00 75 07 0f 0b 0f 1f 00 eb fe 41 89 c6 e8
> [ 6331.906944] BUG: spinlock lockup suspected on CPU#0, rcub/5/12
> [ 6331.906944] lock: 0xffff880035bd6340, .magic: dead4ead, .owner: trinity-child45/25254, .owner_cpu: 3
> [ 6331.906944] Pid: 12, comm: rcub/5 Tainted: G W 3.5.0-rc4-next-20120629-sasha-00007-g3fd449c #488
> [ 6331.906944] Call Trace:
> [ 6331.906944] [<ffffffff8199ba38>] spin_dump+0x78/0xc0
> [ 6331.906944] [<ffffffff8199bc9e>] do_raw_spin_lock+0xfe/0x140
> [ 6331.906944] [<ffffffff8372bb18>] _raw_spin_lock_nested+0x68/0x90
> [ 6331.906944] [<ffffffff8113f7a2>] ? pull_rt_task+0xe2/0x270
> [ 6331.906944] [<ffffffff8372bba7>] ? _raw_spin_lock+0x67/0x70
> [ 6331.906944] [<ffffffff8113f795>] ? pull_rt_task+0xd5/0x270
> [ 6331.906944] [<ffffffff8113f7a2>] pull_rt_task+0xe2/0x270
> [ 6331.906944] [<ffffffff8113f9e4>] pre_schedule_rt+0x14/0x20
> [ 6331.906944] [<ffffffff8372a429>] __schedule+0x259/0x880
> [ 6331.906944] [<ffffffff8372abb5>] schedule+0x55/0x60
> [ 6331.906944] [<ffffffff8372b2bf>] __rt_mutex_slowlock+0xaf/0x110
> [ 6331.906944] [<ffffffff8372b40f>] rt_mutex_slowlock+0xef/0x180
> [ 6331.906944] [<ffffffff8372b520>] rt_mutex_lock+0x30/0x40
> [ 6331.906944] [<ffffffff811a406a>] rcu_boost+0xca/0x110
> [ 6331.906944] [<ffffffff8372b101>] ? rt_mutex_slowunlock+0x31/0x130
> [ 6331.906944] [<ffffffff811ab972>] rcu_boost_kthread+0x222/0x390
> [ 6331.906944] [<ffffffff811ab750>] ? synchronize_rcu_expedited+0x220/0x220
> [ 6331.906944] [<ffffffff81119e92>] kthread+0xb2/0xc0
> [ 6331.906944] [<ffffffff8372ea74>] kernel_thread_helper+0x4/0x10
> [ 6331.906944] [<ffffffff8372ccf4>] ? retint_restore_args+0x13/0x13
> [ 6331.906944] [<ffffffff81119de0>] ? __init_kthread_worker+0x70/0x70
> [ 6331.906944] [<ffffffff8372ea70>] ? gs_change+0x13/0x13
> [ 6331.906944] sending NMI to all CPUs:
> [ 6331.906944] NMI backtrace for cpu 0
> [ 6331.906944] CPU 0
> [ 6331.906944] Pid: 12, comm: rcub/5 Tainted: G W 3.5.0-rc4-next-20120629-sasha-00007-g3fd449c #488
> [ 6331.906944] RIP: 0010:[<ffffffff8109d6aa>] [<ffffffff8109d6aa>] native_write_msr_safe+0xa/0x10
> [ 6331.906944] RSP: 0018:ffff88000d5799c0 EFLAGS: 00000086
> [ 6331.906944] RAX: 0000000000000400 RBX: 0000000000000000 RCX: 0000000000000830
> [ 6331.906944] RDX: 0000000000000000 RSI: 0000000000000400 RDI: 0000000000000830
> [ 6331.906944] RBP: ffff88000d5799c0 R08: ffffffff84ee09c0 R09: 0000000000000000
> [ 6331.906944] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000
> [ 6331.906944] R13: ffffffff84ee09c0 R14: 0000000000000000 R15: 000000000000b024
> [ 6331.906944] FS: 0000000000000000(0000) GS:ffff88000d800000(0000) knlGS:0000000000000000
> [ 6331.906944] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b
> [ 6331.906944] CR2: 0000000000000800 CR3: 000000001a3ec000 CR4: 00000000000406f0
> [ 6331.906944] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
> [ 6331.906944] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
> [ 6331.906944] Process rcub/5 (pid: 12, threadinfo ffff88000d578000, task ffff88000d573000)
> [ 6331.906944] Stack:
> [ 6331.906944] ffff88000d579a30 ffffffff8109602d 0000000000000000 ffff880035bd6340
> [ 6331.906944] ffff88000d579a40 0000000000000086 0000000000000002 0000000200080000
> [ 6331.906944] ffff88000d579a10 0000000000000000 000000009486a4e0 000000009486a4e0
> [ 6331.906944] Call Trace:
> [ 6331.906944] [<ffffffff8109602d>] __x2apic_send_IPI_mask+0xdd/0x1b0
> [ 6331.906944] [<ffffffff81096117>] x2apic_send_IPI_all+0x17/0x20
> [ 6331.906944] [<ffffffff810917b4>] arch_trigger_all_cpu_backtrace+0x64/0xb0
> [ 6331.906944] [<ffffffff8199bca3>] do_raw_spin_lock+0x103/0x140
> [ 6331.906944] [<ffffffff8372bb18>] _raw_spin_lock_nested+0x68/0x90
> [ 6331.906944] [<ffffffff8113f7a2>] ? pull_rt_task+0xe2/0x270
> [ 6331.906944] [<ffffffff8372bba7>] ? _raw_spin_lock+0x67/0x70
> [ 6331.906944] [<ffffffff8113f795>] ? pull_rt_task+0xd5/0x270
> [ 6331.906944] [<ffffffff8113f7a2>] pull_rt_task+0xe2/0x270
> [ 6331.906944] [<ffffffff8113f9e4>] pre_schedule_rt+0x14/0x20
> [ 6331.906944] [<ffffffff8372a429>] __schedule+0x259/0x880
> [ 6331.906944] [<ffffffff8372abb5>] schedule+0x55/0x60
> [ 6331.906944] [<ffffffff8372b2bf>] __rt_mutex_slowlock+0xaf/0x110
> [ 6331.906944] [<ffffffff8372b40f>] rt_mutex_slowlock+0xef/0x180
> [ 6331.906944] [<ffffffff8372b520>] rt_mutex_lock+0x30/0x40
> [ 6331.906944] [<ffffffff811a406a>] rcu_boost+0xca/0x110
> [ 6331.906944] [<ffffffff8372b101>] ? rt_mutex_slowunlock+0x31/0x130
> [ 6331.906944] [<ffffffff811ab972>] rcu_boost_kthread+0x222/0x390
> [ 6331.906944] [<ffffffff811ab750>] ? synchronize_rcu_expedited+0x220/0x220
> [ 6331.906944] [<ffffffff81119e92>] kthread+0xb2/0xc0
> [ 6331.906944] [<ffffffff8372ea74>] kernel_thread_helper+0x4/0x10
> [ 6331.906944] [<ffffffff8372ccf4>] ? retint_restore_args+0x13/0x13
> [ 6331.906944] [<ffffffff81119de0>] ? __init_kthread_worker+0x70/0x70
> [ 6331.906944] [<ffffffff8372ea70>] ? gs_change+0x13/0x13
> [ 6331.906944] Code: 00 55 89 f9 48 89 e5 0f 32 45 31 c0 89 c7 44 89 06 48 89 d0 89 f9 48 c1 e0 20 c9 48 09 c8 c3 66 90 55 89 f0 48 89 e5 89 f9 0f 30 <31> c0 c9 c3 66 90 55 89 f9 48 89 e5 0f 33 89 c7 c9 48 89 d0 89
> [ 6331.955620] NMI backtrace for cpu 1
> [ 6331.955620] CPU 1
> [ 6331.955620] Pid: 24853, comm: trinity-child19 Tainted: G W 3.5.0-rc4-next-20120629-sasha-00007-g3fd449c #488
> [ 6331.955620] RIP: 0010:[<ffffffff81132ac5>] [<ffffffff81132ac5>] try_to_wake_up+0x135/0x290
> [ 6331.955620] RSP: 0018:ffff880012af9af8 EFLAGS: 00000002
> [ 6331.955620] RAX: ffff88000d578000 RBX: ffff88000d573000 RCX: 0000000003530352
> [ 6331.955620] RDX: 0000000000000002 RSI: 0000000000000001 RDI: 0000000000000086
> [ 6331.955620] RBP: ffff880012af9b38 R08: 0000000000000000 R09: 0000000000000000
> [ 6331.955620] R10: 0000000000000000 R11: 0000000000000000 R12: ffff88000d573838
> [ 6331.955620] R13: 0000000000000000 R14: 0000000000000286 R15: 0000000000000000
> [ 6331.955620] FS: 00007f9e2b631700(0000) GS:ffff88001b800000(0000) knlGS:0000000000000000
> [ 6331.955620] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> [ 6331.955620] CR2: 0000000000000001 CR3: 0000000022870000 CR4: 00000000000406e0
> [ 6331.955620] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
> [ 6331.955620] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
> [ 6331.955620] Process trinity-child19 (pid: 24853, threadinfo ffff880012af8000, task ffff880012b10000)
> [ 6331.955620] Stack:
> [ 6331.955620] ffff880012af9b18 ffff880012b10838 ffff880012b10838 ffff88000d579d50
> [ 6331.955620] ffff880012b10838 ffff880012b10000 ffff88000d579c70 0000000000000286
> [ 6331.955620] ffff880012af9b48 ffffffff81132c50 ffff880012af9b88 ffffffff8372b1b9
> [ 6331.955620] Call Trace:
> [ 6331.955620] [<ffffffff81132c50>] wake_up_process+0x10/0x20
> [ 6331.955620] [<ffffffff8372b1b9>] rt_mutex_slowunlock+0xe9/0x130
> [ 6331.955620] [<ffffffff8372b209>] rt_mutex_unlock+0x9/0x10
> [ 6331.955620] [<ffffffff811a8d10>] rcu_read_unlock_special+0x350/0x400
> [ 6331.955620] [<ffffffff812c6870>] ? proc_sys_compare+0xe0/0x130
> [ 6331.955620] [<ffffffff81115b67>] __rcu_read_unlock+0x57/0xb0
> [ 6331.955620] [<ffffffff8126943d>] __d_lookup+0x2dd/0x2f0
> [ 6331.955620] [<ffffffff8199bb90>] ? do_raw_spin_unlock+0xd0/0xe0
> [ 6331.955620] [<ffffffff8125c8ae>] lookup_fast+0x1ae/0x2e0
> [ 6331.955620] [<ffffffff818c61fd>] ? security_inode_permission+0x1d/0x20
> [ 6331.955620] [<ffffffff8125d4e4>] link_path_walk+0x204/0x420
> [ 6331.955620] [<ffffffff8125a0a0>] ? generic_readlink+0xb0/0xb0
> [ 6331.955620] [<ffffffff810f342b>] ? do_setitimer+0x23b/0x290
> [ 6331.955620] [<ffffffff8125e024>] path_lookupat+0x54/0x700
> [ 6331.955620] [<ffffffff8123a134>] ? kmem_cache_alloc+0x154/0x240
> [ 6331.955620] [<ffffffff8125e6fc>] do_path_lookup+0x2c/0xc0
> [ 6331.955620] [<ffffffff8125f6bc>] user_path_at_empty+0x6c/0xb0
> [ 6331.955620] [<ffffffff8116227d>] ? trace_hardirqs_on+0xd/0x10
> [ 6331.955620] [<ffffffff8372c6e9>] ? _raw_spin_unlock_irq+0x59/0x80
> [ 6331.955620] [<ffffffff8372d5e5>] ? sysret_check+0x22/0x5d
> [ 6331.955620] [<ffffffff8125f70c>] user_path_at+0xc/0x10
> [ 6331.955620] [<ffffffff81273d30>] sys_removexattr+0x30/0x80
> [ 6331.955620] [<ffffffff8372d5b9>] system_call_fastpath+0x16/0x1b
> [ 6331.955620] Code: 48 8b 45 c8 48 89 c7 e8 ca 9a 5f 02 e9 31 01 00 00 0f 1f 44 00 00 48 89 c7 e8 b8 9a 5f 02 eb 08 66 0f 1f 44 00 00 f3 90 8b 73 28 <85> f6 75 f7 31 c0 48 8b 13 83 e2 02 74 0a 0f b7 43 16 83 f0 01
> [ 6331.954033] NMI backtrace for cpu 2
> [ 6331.954033] CPU 2
> [ 6331.954033] Pid: 0, comm: swapper/2 Tainted: G W 3.5.0-rc4-next-20120629-sasha-00007-g3fd449c #488
> [ 6331.954033] RIP: 0010:[<ffffffff81071646>] [<ffffffff81071646>] native_read_tsc+0x6/0x20
> [ 6331.954033] RSP: 0018:ffff880029803c40 EFLAGS: 00000082
> [ 6331.954033] RAX: 00000000ad32893d RBX: 00000000ad328911 RCX: 00000000ad328911
> [ 6331.954033] RDX: 0000000000000eb4 RSI: 0000000000000104 RDI: 0000000000000001
> [ 6331.954033] RBP: ffff880029803c40 R08: 0000000000000000 R09: 0000000000000000
> [ 6331.954033] R10: 0000000000000000 R11: 0000000000000000 R12: ffff88000d5ca010
> [ 6331.954033] R13: 0000000000000001 R14: 0000000000000002 R15: ffff880029803d60
> [ 6331.954033] FS: 0000000000000000(0000) GS:ffff880029800000(0000) knlGS:0000000000000000
> [ 6331.954033] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b
> [ 6331.954033] CR2: 0000000000000004 CR3: 000000001527e000 CR4: 00000000000406e0
> [ 6331.954033] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
> [ 6331.954033] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
> [ 6331.954033] Process swapper/2 (pid: 0, threadinfo ffff88000d5ca000, task ffff88000d5c3000)
> [ 6331.954033] Stack:
> [ 6331.954033] ffff880029803c80 ffffffff81984d85 0000000000000000 ffff88000d9d6340
> [ 6331.954033] 0000000009a52e9c 000000009486a4e0 0000000000000000 ffff880029803d60
> [ 6331.954033] ffff880029803c90 ffffffff81984cca ffff880029803cd0 ffffffff8199bc7d
> [ 6331.954033] Call Trace:
> [ 6331.954033] <IRQ>
> [ 6331.954033] [<ffffffff81984d85>] delay_tsc+0x85/0x140
> [ 6331.954033] [<ffffffff81984cca>] __delay+0xa/0x10
> [ 6331.954033] [<ffffffff8199bc7d>] do_raw_spin_lock+0xdd/0x140
> [ 6331.954033] [<ffffffff8372bb9b>] _raw_spin_lock+0x5b/0x70
> [ 6331.954033] [<ffffffff8113a150>] ? load_balance+0x2b0/0x6e0
> [ 6331.954033] [<ffffffff8113a150>] load_balance+0x2b0/0x6e0
> [ 6331.954033] [<ffffffff8113ab2e>] ? rebalance_domains+0x17e/0x290
> [ 6331.954033] [<ffffffff81137f20>] ? find_busiest_group+0x2a0/0x2a0
> [ 6331.954033] [<ffffffff8113ab6d>] rebalance_domains+0x1bd/0x290
> [ 6331.954033] [<ffffffff8113a9b0>] ? update_shares+0x240/0x240
> [ 6331.954033] [<ffffffff810705bd>] ? sched_clock+0x1d/0x30
> [ 6331.954033] [<ffffffff8113ae53>] run_rebalance_domains+0x43/0x60
> [ 6331.954033] [<ffffffff810f5a81>] __do_softirq+0x221/0x460
> [ 6331.954033] [<ffffffff8109d616>] ? kvm_clock_read+0x46/0x80
> [ 6331.954033] [<ffffffff8372eb6c>] call_softirq+0x1c/0x30
> [ 6331.954033] [<ffffffff8106a215>] do_softirq+0x75/0x120
> [ 6331.954033] [<ffffffff810f4a8b>] irq_exit+0x5b/0xf0
> [ 6331.954033] [<ffffffff81090f5a>] smp_apic_timer_interrupt+0x8a/0xa0
> [ 6331.954033] [<ffffffff8372e16f>] apic_timer_interrupt+0x6f/0x80
> [ 6331.954033] <EOI>
> [ 6331.954033] [<ffffffff81072eb8>] ? default_idle+0x1a8/0x390
> [ 6331.954033] [<ffffffff8109d7e6>] ? native_safe_halt+0x6/0x10
> [ 6331.954033] [<ffffffff8116227d>] ? trace_hardirqs_on+0xd/0x10
> [ 6331.954033] [<ffffffff81072ed5>] default_idle+0x1c5/0x390
> [ 6331.954033] [<ffffffff8107244f>] cpu_idle+0x11f/0x180
> [ 6331.954033] [<ffffffff8371fed7>] ? setup_APIC_timer+0x88/0x8d
> [ 6331.954033] [<ffffffff8371f512>] start_secondary+0xd7/0xde
> [ 6331.954033] Code: 08 ff 15 e6 ae 7c 03 48 c7 43 08 00 00 00 00 48 89 03 48 83 c4 08 5b c9 c3 66 66 66 2e 0f 1f 84 00 00 00 00 00 55 48 89 e5 0f 31 <89> c1 c9 48 89 d0 89 c9 48 c1 e0 20 48 09 c8 c3 66 2e 0f 1f 84
> [ 6331.847432] NMI backtrace for cpu 3
> [ 6331.847432] CPU 3
> [ 6331.847432] Pid: 25254, comm: trinity-child45 Tainted: G W 3.5.0-rc4-next-20120629-sasha-00007-g3fd449c #488
> [ 6331.847432] RIP: 0010:[<ffffffff8112960b>] [<ffffffff8112960b>] sub_preempt_count+0x4b/0xb0
> [ 6331.847432] RSP: 0018:ffff88000bd93898 EFLAGS: 00000006
> [ 6331.847432] RAX: ffff88000bd93fd8 RBX: 00000000ad329a05 RCX: 00000000ad329a2e
> [ 6331.847432] RDX: 0000000000000000 RSI: 0000000010000004 RDI: 0000000000000001
> [ 6331.847432] RBP: ffff88000bd93898 R08: 0000000000000000 R09: 0000000000000000
> [ 6331.847432] R10: 0000000000000000 R11: 000000000000ab08 R12: ffff88000bd92010
> [ 6331.847432] R13: 0000000000000001 R14: 0000000000000003 R15: 00000000ad329a2e
> [ 6331.847432] FS: 00007f9e2b631700(0000) GS:ffff880035a00000(0000) knlGS:0000000000000000
> [ 6331.847432] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> [ 6331.847432] CR2: 0000000000000008 CR3: 0000000013c12000 CR4: 00000000000406e0
> [ 6331.847432] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
> [ 6331.847432] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
> [ 6331.847432] Process trinity-child45 (pid: 25254, threadinfo ffff88000bd92000, task ffff88000aea0000)
> [ 6331.847432] Stack:
> [ 6331.847432] ffff88000bd938d8 ffffffff81984e0a ffff88000bd938c8 ffff880035bd6340
> [ 6331.847432] 0000000093c44cd0 000000009486a4e0 0000000000000001 0000000000000086
> [ 6331.847432] ffff88000bd938e8 ffffffff81984cca ffff88000bd93928 ffffffff8199bc7d
> [ 6331.847432] Call Trace:
> [ 6331.847432] [<ffffffff81984e0a>] delay_tsc+0x10a/0x140
> [ 6331.847432] [<ffffffff81984cca>] __delay+0xa/0x10
> [ 6331.847432] [<ffffffff8199bc7d>] do_raw_spin_lock+0xdd/0x140
> [ 6331.847432] [<ffffffff8372bb9b>] _raw_spin_lock+0x5b/0x70
> [ 6331.847432] [<ffffffff81130291>] ? rt_mutex_setprio+0x81/0x2c0
> [ 6331.847432] [<ffffffff81130291>] rt_mutex_setprio+0x81/0x2c0
> [ 6331.847432] [<ffffffff8116a0a0>] __rt_mutex_adjust_prio+0x20/0x30
> [ 6331.847432] [<ffffffff8372b1d4>] rt_mutex_slowunlock+0x104/0x130
> [ 6331.847432] [<ffffffff8372b209>] rt_mutex_unlock+0x9/0x10
> [ 6331.847432] [<ffffffff811a8d10>] rcu_read_unlock_special+0x350/0x400
> [ 6331.847432] [<ffffffff8113fe58>] ? update_curr_rt+0x228/0x270
> [ 6331.847432] [<ffffffff8115d35a>] ? get_lock_stats+0x2a/0x60
> [ 6331.847432] [<ffffffff811a8fea>] rcu_preempt_note_context_switch+0x22a/0x300
> [ 6331.847432] [<ffffffff8372a93a>] __schedule+0x76a/0x880
> [ 6331.847432] [<ffffffff8372ab41>] preempt_schedule+0x51/0x70
> [ 6331.847432] [<ffffffff81071640>] ? read_persistent_clock+0x30/0x30
> [ 6331.847432] [<ffffffff81984e23>] delay_tsc+0x123/0x140
> [ 6331.847432] [<ffffffff81984cf8>] __const_udelay+0x28/0x30
> [ 6331.847432] [<ffffffff81115b54>] __rcu_read_unlock+0x44/0xb0
> [ 6331.847432] [<ffffffff8126943d>] __d_lookup+0x2dd/0x2f0
> [ 6331.847432] [<ffffffff8199bb90>] ? do_raw_spin_unlock+0xd0/0xe0
> [ 6331.847432] [<ffffffff8125c8ae>] lookup_fast+0x1ae/0x2e0
> [ 6331.847432] [<ffffffff818c61fd>] ? security_inode_permission+0x1d/0x20
> [ 6331.847432] [<ffffffff8125d4e4>] link_path_walk+0x204/0x420
> [ 6331.847432] [<ffffffff8125a0a0>] ? generic_readlink+0xb0/0xb0
> [ 6331.847432] [<ffffffff8125e024>] path_lookupat+0x54/0x700
> [ 6331.847432] [<ffffffff8123a134>] ? kmem_cache_alloc+0x154/0x240
> [ 6331.847432] [<ffffffff8111f168>] ? __hrtimer_start_range_ns+0x448/0x490
> [ 6331.847432] [<ffffffff8125e6fc>] do_path_lookup+0x2c/0xc0
> [ 6331.847432] [<ffffffff8115d35a>] ? get_lock_stats+0x2a/0x60
> [ 6331.847432] [<ffffffff8125f6bc>] user_path_at_empty+0x6c/0xb0
> [ 6331.847432] [<ffffffff8116227d>] ? trace_hardirqs_on+0xd/0x10
> [ 6331.847432] [<ffffffff8372c6e9>] ? _raw_spin_unlock_irq+0x59/0x80
> [ 6331.847432] [<ffffffff8125f70c>] user_path_at+0xc/0x10
> [ 6331.847432] [<ffffffff81274d61>] sys_setxattr+0x41/0xb0
> [ 6331.847432] [<ffffffff81985f3e>] ? trace_hardirqs_on_thunk+0x3a/0x3f
> [ 6331.847432] [<ffffffff8372d5b9>] system_call_fastpath+0x16/0x1b
> [ 6331.847432] Code: 4c d8 85 00 85 c0 74 78 83 3d 11 85 ce 04 00 75 6f be 48 0c 00 00 48 c7 c7 e1 a2 27 84 e8 8e 1a fc ff eb 5c 80 b8 44 e0 ff ff 00 <75> 2b 0f 1f 00 e8 1b d8 85 00 85 c0 74 47 83 3d e0 84 ce 04 00
> [ 6331.847432] BUG: spinlock lockup suspected on CPU#3, trinity-child45/25254
> [ 6331.847432] lock: 0xffff880035bd6340, .magic: dead4ead, .owner: trinity-child45/25254, .owner_cpu: 3
> [ 6331.847432] Pid: 25254, comm: trinity-child45 Tainted: G W 3.5.0-rc4-next-20120629-sasha-00007-g3fd449c #488
> [ 6331.847432] Call Trace:
> [ 6331.847432] [<ffffffff8199ba38>] spin_dump+0x78/0xc0
> [ 6331.847432] [<ffffffff8199bc9e>] do_raw_spin_lock+0xfe/0x140
> [ 6331.847432] [<ffffffff8372bb9b>] _raw_spin_lock+0x5b/0x70
> [ 6331.847432] [<ffffffff81130291>] ? rt_mutex_setprio+0x81/0x2c0
> [ 6331.847432] [<ffffffff81130291>] rt_mutex_setprio+0x81/0x2c0
> [ 6331.847432] [<ffffffff8116a0a0>] __rt_mutex_adjust_prio+0x20/0x30
> [ 6331.847432] [<ffffffff8372b1d4>] rt_mutex_slowunlock+0x104/0x130
> [ 6331.847432] [<ffffffff8372b209>] rt_mutex_unlock+0x9/0x10
> [ 6331.847432] [<ffffffff811a8d10>] rcu_read_unlock_special+0x350/0x400
> [ 6331.847432] [<ffffffff8113fe58>] ? update_curr_rt+0x228/0x270
> [ 6331.847432] [<ffffffff8115d35a>] ? get_lock_stats+0x2a/0x60
> [ 6331.847432] [<ffffffff811a8fea>] rcu_preempt_note_context_switch+0x22a/0x300
> [ 6331.847432] [<ffffffff8372a93a>] __schedule+0x76a/0x880
> [ 6331.847432] [<ffffffff8372ab41>] preempt_schedule+0x51/0x70
> [ 6331.847432] [<ffffffff81071640>] ? read_persistent_clock+0x30/0x30
> [ 6331.847432] [<ffffffff81984e23>] delay_tsc+0x123/0x140
> [ 6331.847432] [<ffffffff81984cf8>] __const_udelay+0x28/0x30
> [ 6331.847432] [<ffffffff81115b54>] __rcu_read_unlock+0x44/0xb0
> [ 6331.847432] [<ffffffff8126943d>] __d_lookup+0x2dd/0x2f0
> [ 6331.847432] [<ffffffff8199bb90>] ? do_raw_spin_unlock+0xd0/0xe0
> [ 6331.847432] [<ffffffff8125c8ae>] lookup_fast+0x1ae/0x2e0
> [ 6331.847432] [<ffffffff818c61fd>] ? security_inode_permission+0x1d/0x20
> [ 6331.847432] [<ffffffff8125d4e4>] link_path_walk+0x204/0x420
> [ 6331.847432] [<ffffffff8125a0a0>] ? generic_readlink+0xb0/0xb0
> [ 6331.847432] [<ffffffff8125e024>] path_lookupat+0x54/0x700
> [ 6331.847432] [<ffffffff8123a134>] ? kmem_cache_alloc+0x154/0x240
> [ 6331.847432] [<ffffffff8111f168>] ? __hrtimer_start_range_ns+0x448/0x490
> [ 6331.847432] [<ffffffff8125e6fc>] do_path_lookup+0x2c/0xc0
> [ 6331.847432] [<ffffffff8115d35a>] ? get_lock_stats+0x2a/0x60
> [ 6331.847432] [<ffffffff8125f6bc>] user_path_at_empty+0x6c/0xb0
> [ 6331.847432] [<ffffffff8116227d>] ? trace_hardirqs_on+0xd/0x10
> [ 6331.847432] [<ffffffff8372c6e9>] ? _raw_spin_unlock_irq+0x59/0x80
> [ 6331.847432] [<ffffffff8125f70c>] user_path_at+0xc/0x10
> [ 6331.847432] [<ffffffff81274d61>] sys_setxattr+0x41/0xb0
> [ 6331.847432] [<ffffffff81985f3e>] ? trace_hardirqs_on_thunk+0x3a/0x3f
> [ 6331.847432] [<ffffffff8372d5b9>] system_call_fastpath+0x16/0x1b
> [ 6331.847432] sending NMI to all CPUs:
> [ 6331.906944] NMI backtrace for cpu 0
> [ 6331.906944] CPU 0
> [ 6331.906944] Pid: 12, comm: rcub/5 Tainted: G W 3.5.0-rc4-next-20120629-sasha-00007-g3fd449c #488
> [ 6331.906944] RIP: 0010:[<ffffffff81984d29>] [<ffffffff81984d29>] delay_tsc+0x29/0x140
> [ 6331.906944] RSP: 0018:ffff88000d579a20 EFLAGS: 00000002
> [ 6331.906944] RAX: 0000000000000000 RBX: ffff880035bd6340 RCX: 0000000000000000
> [ 6331.906944] RDX: 000000002acf2ace RSI: 0000000000000003 RDI: 0000000000000001
> [ 6331.906944] RBP: ffff88000d579a50 R08: 0000000000000000 R09: 0000000000000000
> [ 6331.906944] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000d775c3
> [ 6331.906944] R13: 0000000000000001 R14: 0000000000000000 R15: ffff880035bd6340
> [ 6331.906944] FS: 0000000000000000(0000) GS:ffff88000d800000(0000) knlGS:0000000000000000
> [ 6331.906944] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b
> [ 6331.906944] CR2: 0000000000000800 CR3: 000000001a3ec000 CR4: 00000000000406f0
> [ 6331.906944] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
> [ 6331.906944] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
> [ 6331.906944] Process rcub/5 (pid: 12, threadinfo ffff88000d578000, task ffff88000d573000)
> [ 6331.906944] Stack:
> [ 6331.906944] 0000000000000000 ffff880035bd6340 0000000000d775c3 000000009486a4e0
> [ 6331.906944] 0000000000000000 ffff880035bd6340 ffff88000d579a60 ffffffff81984cca
> [ 6331.906944] ffff88000d579aa0 ffffffff8199bc7d ffff88000d579aa0 0000000000000086
> [ 6331.906944] Call Trace:
> [ 6331.906944] [<ffffffff81984cca>] __delay+0xa/0x10
> [ 6331.906944] [<ffffffff8199bc7d>] do_raw_spin_lock+0xdd/0x140
> [ 6331.906944] [<ffffffff8372bb18>] _raw_spin_lock_nested+0x68/0x90
> [ 6331.906944] [<ffffffff8113f7a2>] ? pull_rt_task+0xe2/0x270
> [ 6331.906944] [<ffffffff8372bba7>] ? _raw_spin_lock+0x67/0x70
> [ 6331.906944] [<ffffffff8113f795>] ? pull_rt_task+0xd5/0x270
> [ 6331.906944] [<ffffffff8113f7a2>] pull_rt_task+0xe2/0x270
> [ 6331.906944] [<ffffffff8113f9e4>] pre_schedule_rt+0x14/0x20
> [ 6331.906944] [<ffffffff8372a429>] __schedule+0x259/0x880
> [ 6331.906944] [<ffffffff8372abb5>] schedule+0x55/0x60
> [ 6331.906944] [<ffffffff8372b2bf>] __rt_mutex_slowlock+0xaf/0x110
> [ 6331.906944] [<ffffffff8372b40f>] rt_mutex_slowlock+0xef/0x180
> [ 6331.906944] [<ffffffff8372b520>] rt_mutex_lock+0x30/0x40
> [ 6331.906944] [<ffffffff811a406a>] rcu_boost+0xca/0x110
> [ 6331.906944] [<ffffffff8372b101>] ? rt_mutex_slowunlock+0x31/0x130
> [ 6331.906944] [<ffffffff811ab972>] rcu_boost_kthread+0x222/0x390
> [ 6331.906944] [<ffffffff811ab750>] ? synchronize_rcu_expedited+0x220/0x220
> [ 6331.906944] [<ffffffff81119e92>] kthread+0xb2/0xc0
> [ 6331.906944] [<ffffffff8372ea74>] kernel_thread_helper+0x4/0x10
> [ 6331.906944] [<ffffffff8372ccf4>] ? retint_restore_args+0x13/0x13
> [ 6331.906944] [<ffffffff81119de0>] ? __init_kthread_worker+0x70/0x70
> [ 6331.906944] [<ffffffff8372ea70>] ? gs_change+0x13/0x13
> [ 6331.906944] Code: 00 00 55 48 89 e5 41 57 41 56 41 55 49 89 fd 41 54 bf 01 00 00 00 53 48 83 ec 08 e8 52 49 7a ff e8 2d 87 01 00 0f 1f 00 0f ae e8 <48> 83 3d 07 15 ec 02 00 75 07 0f 0b 0f 1f 00 eb fe 41 89 c6 e8
> [ 6331.847432] NMI backtrace for cpu 3
> [ 6331.847432] CPU 3
> [ 6331.847432] Pid: 25254, comm: trinity-child45 Tainted: G W 3.5.0-rc4-next-20120629-sasha-00007-g3fd449c #488
> [ 6331.847432] RIP: 0010:[<ffffffff8109d6aa>] [<ffffffff8109d6aa>] native_write_msr_safe+0xa/0x10
> [ 6331.847432] RSP: 0018:ffff88000bd93848 EFLAGS: 00000086
> [ 6331.847432] RAX: 0000000000000400 RBX: 0000000000000003 RCX: 0000000000000830
> [ 6331.847432] RDX: 0000000000000003 RSI: 0000000000000400 RDI: 0000000000000830
> [ 6331.847432] RBP: ffff88000bd93848 R08: ffffffff84ee09c0 R09: 0000000000000000
> [ 6331.847432] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000003
> [ 6331.847432] R13: ffffffff84ee09c0 R14: 0000000000000003 R15: 000000000000b024
> [ 6331.847432] FS: 00007f9e2b631700(0000) GS:ffff880035a00000(0000) knlGS:0000000000000000
> [ 6331.847432] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> [ 6331.847432] CR2: 0000000000000008 CR3: 0000000013c12000 CR4: 00000000000406e0
> [ 6331.847432] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
> [ 6331.847432] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
> [ 6331.847432] Process trinity-child45 (pid: 25254, threadinfo ffff88000bd92000, task ffff88000aea0000)
> [ 6331.847432] Stack:
> [ 6331.847432] ffff88000bd938b8 ffffffff8109602d 0000000000000000 0000000000000086
> [ 6331.847432] ffff88000bd938c8 0000000000000086 0000000000000002 0000000200080000
> [ 6331.847432] ffff88000bd93898 0000000000000000 000000009486a4e0 000000009486a4e0
> [ 6331.847432] Call Trace:
> [ 6331.847432] [<ffffffff8109602d>] __x2apic_send_IPI_mask+0xdd/0x1b0
> [ 6331.847432] [<ffffffff81096117>] x2apic_send_IPI_all+0x17/0x20
> [ 6331.847432] [<ffffffff810917b4>] arch_trigger_all_cpu_backtrace+0x64/0xb0
> [ 6331.847432] [<ffffffff8199bca3>] do_raw_spin_lock+0x103/0x140
> [ 6331.847432] [<ffffffff8372bb9b>] _raw_spin_lock+0x5b/0x70
> [ 6331.847432] [<ffffffff81130291>] ? rt_mutex_setprio+0x81/0x2c0
> [ 6331.847432] [<ffffffff81130291>] rt_mutex_setprio+0x81/0x2c0
> [ 6331.847432] [<ffffffff8116a0a0>] __rt_mutex_adjust_prio+0x20/0x30
> [ 6331.847432] [<ffffffff8372b1d4>] rt_mutex_slowunlock+0x104/0x130
> [ 6331.847432] [<ffffffff8372b209>] rt_mutex_unlock+0x9/0x10
> [ 6331.847432] [<ffffffff811a8d10>] rcu_read_unlock_special+0x350/0x400
> [ 6331.847432] [<ffffffff8113fe58>] ? update_curr_rt+0x228/0x270
> [ 6331.847432] [<ffffffff8115d35a>] ? get_lock_stats+0x2a/0x60
> [ 6331.847432] [<ffffffff811a8fea>] rcu_preempt_note_context_switch+0x22a/0x300
> [ 6331.847432] [<ffffffff8372a93a>] __schedule+0x76a/0x880
> [ 6331.847432] [<ffffffff8372ab41>] preempt_schedule+0x51/0x70
> [ 6331.847432] [<ffffffff81071640>] ? read_persistent_clock+0x30/0x30
> [ 6331.847432] [<ffffffff81984e23>] delay_tsc+0x123/0x140
> [ 6331.847432] [<ffffffff81984cf8>] __const_udelay+0x28/0x30
> [ 6331.847432] [<ffffffff81115b54>] __rcu_read_unlock+0x44/0xb0
> [ 6331.847432] [<ffffffff8126943d>] __d_lookup+0x2dd/0x2f0
> [ 6331.847432] [<ffffffff8199bb90>] ? do_raw_spin_unlock+0xd0/0xe0
> [ 6331.847432] [<ffffffff8125c8ae>] lookup_fast+0x1ae/0x2e0
> [ 6331.847432] [<ffffffff818c61fd>] ? security_inode_permission+0x1d/0x20
> [ 6331.847432] [<ffffffff8125d4e4>] link_path_walk+0x204/0x420
> [ 6331.847432] [<ffffffff8125a0a0>] ? generic_readlink+0xb0/0xb0
> [ 6331.847432] [<ffffffff8125e024>] path_lookupat+0x54/0x700
> [ 6331.847432] [<ffffffff8123a134>] ? kmem_cache_alloc+0x154/0x240
> [ 6331.847432] [<ffffffff8111f168>] ? __hrtimer_start_range_ns+0x448/0x490
> [ 6331.847432] [<ffffffff8125e6fc>] do_path_lookup+0x2c/0xc0
> [ 6331.847432] [<ffffffff8115d35a>] ? get_lock_stats+0x2a/0x60
> [ 6331.847432] [<ffffffff8125f6bc>] user_path_at_empty+0x6c/0xb0
> [ 6331.847432] [<ffffffff8116227d>] ? trace_hardirqs_on+0xd/0x10
> [ 6331.847432] [<ffffffff8372c6e9>] ? _raw_spin_unlock_irq+0x59/0x80
> [ 6331.847432] [<ffffffff8125f70c>] user_path_at+0xc/0x10
> [ 6331.847432] [<ffffffff81274d61>] sys_setxattr+0x41/0xb0
> [ 6331.847432] [<ffffffff81985f3e>] ? trace_hardirqs_on_thunk+0x3a/0x3f
> [ 6331.847432] [<ffffffff8372d5b9>] system_call_fastpath+0x16/0x1b
> [ 6331.847432] Code: 00 55 89 f9 48 89 e5 0f 32 45 31 c0 89 c7 44 89 06 48 89 d0 89 f9 48 c1 e0 20 c9 48 09 c8 c3 66 90 55 89 f0 48 89 e5 89 f9 0f 30 <31> c0 c9 c3 66 90 55 89 f9 48 89 e5 0f 33 89 c7 c9 48 89 d0 89
> [ 6331.954033] NMI backtrace for cpu 2
> [ 6331.954033] CPU 2
> [ 6331.954033] Pid: 0, comm: swapper/2 Tainted: G W 3.5.0-rc4-next-20120629-sasha-00007-g3fd449c #488
> [ 6331.954033] RIP: 0010:[<ffffffff81129604>] [<ffffffff81129604>] sub_preempt_count+0x44/0xb0
> [ 6331.954033] RSP: 0018:ffff880029803c40 EFLAGS: 00000097
> [ 6331.954033] RAX: ffff88000d5cbfd8 RBX: 000000006ce53fcd RCX: 000000006ce53feb
> [ 6331.954033] RDX: 0000000000000000 RSI: 0000000000000104 RDI: 0000000000000001
> [ 6331.954033] RBP: ffff880029803c40 R08: 0000000000000000 R09: 0000000000000000
> [ 6331.954033] R10: 0000000000000000 R11: 0000000000000000 R12: ffff88000d5ca010
> [ 6331.954033] R13: 0000000000000001 R14: 0000000000000002 R15: 000000006ce53feb
> [ 6331.954033] FS: 0000000000000000(0000) GS:ffff880029800000(0000) knlGS:0000000000000000
> [ 6331.954033] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b
> [ 6331.954033] CR2: 0000000000000004 CR3: 000000001527e000 CR4: 00000000000406e0
> [ 6331.954033] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
> [ 6331.954033] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
> [ 6331.954033] Process swapper/2 (pid: 0, threadinfo ffff88000d5ca000, task ffff88000d5c3000)
> [ 6331.954033] Stack:
> [ 6331.954033] ffff880029803c80 ffffffff81984e0a 0000000000000000 ffff88000d9d6340
> [ 6331.954033] 000000000a9671ab 000000009486a4e0 0000000000000000 ffff880029803d60
> [ 6331.954033] ffff880029803c90 ffffffff81984cca ffff880029803cd0 ffffffff8199bc7d
> [ 6331.954033] Call Trace:
> [ 6331.954033] <IRQ>
> [ 6331.954033] [<ffffffff81984e0a>] delay_tsc+0x10a/0x140
> [ 6331.954033] [<ffffffff81984cca>] __delay+0xa/0x10
> [ 6331.954033] [<ffffffff8199bc7d>] do_raw_spin_lock+0xdd/0x140
> [ 6331.954033] [<ffffffff8372bb9b>] _raw_spin_lock+0x5b/0x70
> [ 6331.954033] [<ffffffff8113a150>] ? load_balance+0x2b0/0x6e0
> [ 6331.954033] [<ffffffff8113a150>] load_balance+0x2b0/0x6e0
> [ 6331.954033] [<ffffffff8113ab2e>] ? rebalance_domains+0x17e/0x290
> [ 6331.954033] [<ffffffff81137f20>] ? find_busiest_group+0x2a0/0x2a0
> [ 6331.954033] [<ffffffff8113ab6d>] rebalance_domains+0x1bd/0x290
> [ 6331.954033] [<ffffffff8113a9b0>] ? update_shares+0x240/0x240
> [ 6331.954033] [<ffffffff810705bd>] ? sched_clock+0x1d/0x30
> [ 6331.954033] [<ffffffff8113ae53>] run_rebalance_domains+0x43/0x60
> [ 6331.954033] [<ffffffff810f5a81>] __do_softirq+0x221/0x460
> [ 6331.954033] [<ffffffff8109d616>] ? kvm_clock_read+0x46/0x80
> [ 6331.954033] [<ffffffff8372eb6c>] call_softirq+0x1c/0x30
> [ 6331.954033] [<ffffffff8106a215>] do_softirq+0x75/0x120
> [ 6331.954033] [<ffffffff810f4a8b>] irq_exit+0x5b/0xf0
> [ 6331.954033] [<ffffffff81090f5a>] smp_apic_timer_interrupt+0x8a/0xa0
> [ 6331.954033] [<ffffffff8372e16f>] apic_timer_interrupt+0x6f/0x80
> [ 6331.954033] <EOI>
> [ 6331.954033] [<ffffffff81072eb8>] ? default_idle+0x1a8/0x390
> [ 6331.954033] [<ffffffff8109d7e6>] ? native_safe_halt+0x6/0x10
> [ 6331.954033] [<ffffffff8116227d>] ? trace_hardirqs_on+0xd/0x10
> [ 6331.954033] [<ffffffff81072ed5>] default_idle+0x1c5/0x390
> [ 6331.954033] [<ffffffff8107244f>] cpu_idle+0x11f/0x180
> [ 6331.954033] [<ffffffff8371fed7>] ? setup_APIC_timer+0x88/0x8d
> [ 6331.954033] [<ffffffff8371f512>] start_secondary+0xd7/0xde
> [ 6331.954033] Code: 44 e0 ff ff 7d 71 e8 4c d8 85 00 85 c0 74 78 83 3d 11 85 ce 04 00 75 6f be 48 0c 00 00 48 c7 c7 e1 a2 27 84 e8 8e 1a fc ff eb 5c <80> b8 44 e0 ff ff 00 75 2b 0f 1f 00 e8 1b d8 85 00 85 c0 74 47
> [ 6331.955620] NMI backtrace for cpu 1
> [ 6331.955620] CPU 1
> [ 6331.955620] Pid: 24853, comm: trinity-child19 Tainted: G W 3.5.0-rc4-next-20120629-sasha-00007-g3fd449c #488
> [ 6331.955620] RIP: 0010:[<ffffffff81132ac5>] [<ffffffff81132ac5>] try_to_wake_up+0x135/0x290
> [ 6331.955620] RSP: 0018:ffff880012af9af8 EFLAGS: 00000002
> [ 6331.955620] RAX: ffff88000d578000 RBX: ffff88000d573000 RCX: 0000000003530352
> [ 6331.955620] RDX: 0000000000000002 RSI: 0000000000000001 RDI: 0000000000000086
> [ 6331.955620] RBP: ffff880012af9b38 R08: 0000000000000000 R09: 0000000000000000
> [ 6331.955620] R10: 0000000000000000 R11: 0000000000000000 R12: ffff88000d573838
> [ 6331.955620] R13: 0000000000000000 R14: 0000000000000286 R15: 0000000000000000
> [ 6331.955620] FS: 00007f9e2b631700(0000) GS:ffff88001b800000(0000) knlGS:0000000000000000
> [ 6331.955620] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
> [ 6331.955620] CR2: 0000000000000001 CR3: 0000000022870000 CR4: 00000000000406e0
> [ 6331.955620] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
> [ 6331.955620] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
> [ 6331.955620] Process trinity-child19 (pid: 24853, threadinfo ffff880012af8000, task ffff880012b10000)
> [ 6331.955620] Stack:
> [ 6331.955620] ffff880012af9b18 ffff880012b10838 ffff880012b10838 ffff88000d579d50
> [ 6331.955620] ffff880012b10838 ffff880012b10000 ffff88000d579c70 0000000000000286
> [ 6331.955620] ffff880012af9b48 ffffffff81132c50 ffff880012af9b88 ffffffff8372b1b9
> [ 6331.955620] Call Trace:
> [ 6331.955620] [<ffffffff81132c50>] wake_up_process+0x10/0x20
> [ 6331.955620] [<ffffffff8372b1b9>] rt_mutex_slowunlock+0xe9/0x130
> [ 6331.955620] [<ffffffff8372b209>] rt_mutex_unlock+0x9/0x10
> [ 6331.955620] [<ffffffff811a8d10>] rcu_read_unlock_special+0x350/0x400
> [ 6331.955620] [<ffffffff812c6870>] ? proc_sys_compare+0xe0/0x130
> [ 6331.955620] [<ffffffff81115b67>] __rcu_read_unlock+0x57/0xb0
> [ 6331.955620] [<ffffffff8126943d>] __d_lookup+0x2dd/0x2f0
> [ 6331.955620] [<ffffffff8199bb90>] ? do_raw_spin_unlock+0xd0/0xe0
> [ 6331.955620] [<ffffffff8125c8ae>] lookup_fast+0x1ae/0x2e0
> [ 6331.955620] [<ffffffff818c61fd>] ? security_inode_permission+0x1d/0x20
> [ 6331.955620] [<ffffffff8125d4e4>] link_path_walk+0x204/0x420
> [ 6331.955620] [<ffffffff8125a0a0>] ? generic_readlink+0xb0/0xb0
> [ 6331.955620] [<ffffffff810f342b>] ? do_setitimer+0x23b/0x290
> [ 6331.955620] [<ffffffff8125e024>] path_lookupat+0x54/0x700
> [ 6331.955620] [<ffffffff8123a134>] ? kmem_cache_alloc+0x154/0x240
> [ 6331.955620] [<ffffffff8125e6fc>] do_path_lookup+0x2c/0xc0
> [ 6331.955620] [<ffffffff8125f6bc>] user_path_at_empty+0x6c/0xb0
> [ 6331.955620] [<ffffffff8116227d>] ? trace_hardirqs_on+0xd/0x10
> [ 6331.955620] [<ffffffff8372c6e9>] ? _raw_spin_unlock_irq+0x59/0x80
> [ 6331.955620] [<ffffffff8372d5e5>] ? sysret_check+0x22/0x5d
> [ 6331.955620] [<ffffffff8125f70c>] user_path_at+0xc/0x10
> [ 6331.955620] [<ffffffff81273d30>] sys_removexattr+0x30/0x80
> [ 6331.955620] [<ffffffff8372d5b9>] system_call_fastpath+0x16/0x1b
> [ 6331.955620] Code: 48 8b 45 c8 48 89 c7 e8 ca 9a 5f 02 e9 31 01 00 00 0f 1f 44 00 00 48 89 c7 e8 b8 9a 5f 02 eb 08 66 0f 1f 44 00 00 f3 90 8b 73 28 <85> f6 75 f7 31 c0 48 8b 13 83 e2 02 74 0a 0f b7 43 16 83 f0 01
>