From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1752815Ab2F3NQB (ORCPT ); Sat, 30 Jun 2012 09:16:01 -0400 Received: from e33.co.us.ibm.com ([32.97.110.151]:50310 "EHLO e33.co.us.ibm.com" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1751520Ab2F3NP7 (ORCPT ); Sat, 30 Jun 2012 09:15:59 -0400 Date: Sat, 30 Jun 2012 06:14:14 -0700 From: "Paul E. McKenney" To: Sasha Levin Cc: Dave Jones , "linux-kernel@vger.kernel.org" , Peter Zijlstra Subject: Re: rcu: BUG: spinlock recursion on CPU#3, trinity-child19/5970 Message-ID: <20120630131414.GB2907@linux.vnet.ibm.com> Reply-To: paulmck@linux.vnet.ibm.com References: <1340964584.2936.40.camel@lappy> <20120629172320.GA2416@linux.vnet.ibm.com> <1341056208.27537.3.camel@lappy> MIME-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline In-Reply-To: <1341056208.27537.3.camel@lappy> User-Agent: Mutt/1.5.21 (2010-09-15) x-cbid: 12063013-2398-0000-0000-0000080A688E Sender: linux-kernel-owner@vger.kernel.org List-ID: X-Mailing-List: linux-kernel@vger.kernel.org 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] [] spin_dump+0x78/0xc0 > [ 6331.841079] [] spin_bug+0x2b/0x40 > [ 6331.841079] [] do_raw_spin_lock+0x4e/0x140 > [ 6331.841079] [] _raw_spin_lock+0x5b/0x70 > [ 6331.841091] [] ? rt_mutex_setprio+0x81/0x2c0 > [ 6331.841096] [] rt_mutex_setprio+0x81/0x2c0 > [ 6331.841096] [] __rt_mutex_adjust_prio+0x20/0x30 > [ 6331.841096] [] rt_mutex_slowunlock+0x104/0x130 > [ 6331.841096] [] rt_mutex_unlock+0x9/0x10 > [ 6331.841098] [] rcu_read_unlock_special+0x350/0x400 > [ 6331.841101] [] ? update_curr_rt+0x228/0x270 > [ 6331.841104] [] ? get_lock_stats+0x2a/0x60 > [ 6331.841106] [] rcu_preempt_note_context_switch+0x22a/0x300 > [ 6331.841108] [] __schedule+0x76a/0x880 > [ 6331.841111] [] preempt_schedule+0x51/0x70 > [ 6331.841115] [] ? read_persistent_clock+0x30/0x30 > [ 6331.841119] [] delay_tsc+0x123/0x140 > [ 6331.841121] [] __const_udelay+0x28/0x30 > [ 6331.841125] [] __rcu_read_unlock+0x44/0xb0 > [ 6331.841129] [] __d_lookup+0x2dd/0x2f0 > [ 6331.841131] [] ? do_raw_spin_unlock+0xd0/0xe0 > [ 6331.841134] [] lookup_fast+0x1ae/0x2e0 > [ 6331.841139] [] ? security_inode_permission+0x1d/0x20 > [ 6331.841141] [] link_path_walk+0x204/0x420 > [ 6331.841144] [] ? generic_readlink+0xb0/0xb0 > [ 6331.841146] [] path_lookupat+0x54/0x700 > [ 6331.841150] [] ? kmem_cache_alloc+0x154/0x240 > [ 6331.841153] [] ? __hrtimer_start_range_ns+0x448/0x490 > [ 6331.841156] [] do_path_lookup+0x2c/0xc0 > [ 6331.841158] [] ? get_lock_stats+0x2a/0x60 > [ 6331.841161] [] user_path_at_empty+0x6c/0xb0 > [ 6331.841163] [] ? trace_hardirqs_on+0xd/0x10 > [ 6331.841166] [] ? _raw_spin_unlock_irq+0x59/0x80 > [ 6331.841169] [] user_path_at+0xc/0x10 > [ 6331.841172] [] sys_setxattr+0x41/0xb0 > [ 6331.841174] [] ? trace_hardirqs_on_thunk+0x3a/0x3f > [ 6331.841177] [] system_call_fastpath+0x16/0x1b > [ 6331.906964] hardirqs last enabled at (1166831): [] __mutex_unlock_slowpath+0x1a5/0x200 > [ 6331.906964] hardirqs last disabled at (1166832): [] _raw_spin_lock_irqsave+0x54/0xc0 > [ 6331.906964] softirqs last enabled at (1166798): [] unlist_netdevice+0xb4/0xe0 > [ 6331.906964] softirqs last disabled at (1166796): [] _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] [] spin_dump+0x78/0xc0 > [ 6331.954033] [] do_raw_spin_lock+0xfe/0x140 > [ 6331.954033] [] _raw_spin_lock+0x5b/0x70 > [ 6331.954033] [] ? load_balance+0x2b0/0x6e0 > [ 6331.954033] [] load_balance+0x2b0/0x6e0 > [ 6331.954033] [] ? rebalance_domains+0x17e/0x290 > [ 6331.954033] [] ? find_busiest_group+0x2a0/0x2a0 > [ 6331.954033] [] rebalance_domains+0x1bd/0x290 > [ 6331.954033] [] ? update_shares+0x240/0x240 > [ 6331.954033] [] ? sched_clock+0x1d/0x30 > [ 6331.954033] [] run_rebalance_domains+0x43/0x60 > [ 6331.954033] [] __do_softirq+0x221/0x460 > [ 6331.954033] [] ? kvm_clock_read+0x46/0x80 > [ 6331.954033] [] call_softirq+0x1c/0x30 > [ 6331.954033] [] do_softirq+0x75/0x120 > [ 6331.954033] [] irq_exit+0x5b/0xf0 > [ 6331.954033] [] smp_apic_timer_interrupt+0x8a/0xa0 > [ 6331.954033] [] apic_timer_interrupt+0x6f/0x80 > [ 6331.954033] [] ? default_idle+0x1a8/0x390 > [ 6331.954033] [] ? native_safe_halt+0x6/0x10 > [ 6331.954033] [] ? trace_hardirqs_on+0xd/0x10 > [ 6331.954033] [] default_idle+0x1c5/0x390 > [ 6331.954033] [] cpu_idle+0x11f/0x180 > [ 6331.954033] [] ? setup_APIC_timer+0x88/0x8d > [ 6331.954033] [] 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:[] [] 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] > [ 6331.954033] [] __x2apic_send_IPI_mask+0xdd/0x1b0 > [ 6331.954033] [] x2apic_send_IPI_all+0x17/0x20 > [ 6331.954033] [] arch_trigger_all_cpu_backtrace+0x64/0xb0 > [ 6331.954033] [] do_raw_spin_lock+0x103/0x140 > [ 6331.954033] [] _raw_spin_lock+0x5b/0x70 > [ 6331.954033] [] ? load_balance+0x2b0/0x6e0 > [ 6331.954033] [] load_balance+0x2b0/0x6e0 > [ 6331.954033] [] ? rebalance_domains+0x17e/0x290 > [ 6331.954033] [] ? find_busiest_group+0x2a0/0x2a0 > [ 6331.954033] [] rebalance_domains+0x1bd/0x290 > [ 6331.954033] [] ? update_shares+0x240/0x240 > [ 6331.954033] [] ? sched_clock+0x1d/0x30 > [ 6331.954033] [] run_rebalance_domains+0x43/0x60 > [ 6331.954033] [] __do_softirq+0x221/0x460 > [ 6331.954033] [] ? kvm_clock_read+0x46/0x80 > [ 6331.954033] [] call_softirq+0x1c/0x30 > [ 6331.954033] [] do_softirq+0x75/0x120 > [ 6331.954033] [] irq_exit+0x5b/0xf0 > [ 6331.954033] [] smp_apic_timer_interrupt+0x8a/0xa0 > [ 6331.954033] [] apic_timer_interrupt+0x6f/0x80 > [ 6331.954033] > [ 6331.954033] [] ? default_idle+0x1a8/0x390 > [ 6331.954033] [] ? native_safe_halt+0x6/0x10 > [ 6331.954033] [] ? trace_hardirqs_on+0xd/0x10 > [ 6331.954033] [] default_idle+0x1c5/0x390 > [ 6331.954033] [] cpu_idle+0x11f/0x180 > [ 6331.954033] [] ? setup_APIC_timer+0x88/0x8d > [ 6331.954033] [] 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:[] [] 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] [] delay_tsc+0x23/0x140 > [ 6331.906944] [] __delay+0xa/0x10 > [ 6331.906944] [] do_raw_spin_lock+0xdd/0x140 > [ 6331.906944] [] _raw_spin_lock_nested+0x68/0x90 > [ 6331.906944] [] ? pull_rt_task+0xe2/0x270 > [ 6331.906944] [] ? _raw_spin_lock+0x67/0x70 > [ 6331.906944] [] ? pull_rt_task+0xd5/0x270 > [ 6331.906944] [] pull_rt_task+0xe2/0x270 > [ 6331.906944] [] pre_schedule_rt+0x14/0x20 > [ 6331.906944] [] __schedule+0x259/0x880 > [ 6331.906944] [] schedule+0x55/0x60 > [ 6331.906944] [] __rt_mutex_slowlock+0xaf/0x110 > [ 6331.906944] [] rt_mutex_slowlock+0xef/0x180 > [ 6331.906944] [] rt_mutex_lock+0x30/0x40 > [ 6331.906944] [] rcu_boost+0xca/0x110 > [ 6331.906944] [] ? rt_mutex_slowunlock+0x31/0x130 > [ 6331.906944] [] rcu_boost_kthread+0x222/0x390 > [ 6331.906944] [] ? synchronize_rcu_expedited+0x220/0x220 > [ 6331.906944] [] kthread+0xb2/0xc0 > [ 6331.906944] [] kernel_thread_helper+0x4/0x10 > [ 6331.906944] [] ? retint_restore_args+0x13/0x13 > [ 6331.906944] [] ? __init_kthread_worker+0x70/0x70 > [ 6331.906944] [] ? 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:[] [] 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] [] wake_up_process+0x10/0x20 > [ 6331.955620] [] rt_mutex_slowunlock+0xe9/0x130 > [ 6331.955620] [] rt_mutex_unlock+0x9/0x10 > [ 6331.955620] [] rcu_read_unlock_special+0x350/0x400 > [ 6331.955620] [] ? proc_sys_compare+0xe0/0x130 > [ 6331.955620] [] __rcu_read_unlock+0x57/0xb0 > [ 6331.955620] [] __d_lookup+0x2dd/0x2f0 > [ 6331.955620] [] ? do_raw_spin_unlock+0xd0/0xe0 > [ 6331.955620] [] lookup_fast+0x1ae/0x2e0 > [ 6331.955620] [] ? security_inode_permission+0x1d/0x20 > [ 6331.955620] [] link_path_walk+0x204/0x420 > [ 6331.955620] [] ? generic_readlink+0xb0/0xb0 > [ 6331.955620] [] ? do_setitimer+0x23b/0x290 > [ 6331.955620] [] path_lookupat+0x54/0x700 > [ 6331.955620] [] ? kmem_cache_alloc+0x154/0x240 > [ 6331.955620] [] do_path_lookup+0x2c/0xc0 > [ 6331.955620] [] user_path_at_empty+0x6c/0xb0 > [ 6331.955620] [] ? trace_hardirqs_on+0xd/0x10 > [ 6331.955620] [] ? _raw_spin_unlock_irq+0x59/0x80 > [ 6331.955620] [] ? sysret_check+0x22/0x5d > [ 6331.955620] [] user_path_at+0xc/0x10 > [ 6331.955620] [] sys_removexattr+0x30/0x80 > [ 6331.955620] [] 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:[] [] 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] [] __delay+0xa/0x10 > [ 6331.847432] [] do_raw_spin_lock+0xdd/0x140 > [ 6331.847432] [] _raw_spin_lock+0x5b/0x70 > [ 6331.847432] [] ? rt_mutex_setprio+0x81/0x2c0 > [ 6331.847432] [] rt_mutex_setprio+0x81/0x2c0 > [ 6331.847432] [] __rt_mutex_adjust_prio+0x20/0x30 > [ 6331.847432] [] rt_mutex_slowunlock+0x104/0x130 > [ 6331.847432] [] rt_mutex_unlock+0x9/0x10 > [ 6331.847432] [] rcu_read_unlock_special+0x350/0x400 > [ 6331.847432] [] ? update_curr_rt+0x228/0x270 > [ 6331.847432] [] ? get_lock_stats+0x2a/0x60 > [ 6331.847432] [] rcu_preempt_note_context_switch+0x22a/0x300 > [ 6331.847432] [] __schedule+0x76a/0x880 > [ 6331.847432] [] preempt_schedule+0x51/0x70 > [ 6331.847432] [] ? read_persistent_clock+0x30/0x30 > [ 6331.847432] [] delay_tsc+0x123/0x140 > [ 6331.847432] [] __const_udelay+0x28/0x30 > [ 6331.847432] [] __rcu_read_unlock+0x44/0xb0 > [ 6331.847432] [] __d_lookup+0x2dd/0x2f0 > [ 6331.847432] [] ? do_raw_spin_unlock+0xd0/0xe0 > [ 6331.847432] [] lookup_fast+0x1ae/0x2e0 > [ 6331.847432] [] ? security_inode_permission+0x1d/0x20 > [ 6331.847432] [] link_path_walk+0x204/0x420 > [ 6331.847432] [] ? generic_readlink+0xb0/0xb0 > [ 6331.847432] [] path_lookupat+0x54/0x700 > [ 6331.847432] [] ? kmem_cache_alloc+0x154/0x240 > [ 6331.847432] [] ? __hrtimer_start_range_ns+0x448/0x490 > [ 6331.847432] [] do_path_lookup+0x2c/0xc0 > [ 6331.847432] [] ? get_lock_stats+0x2a/0x60 > [ 6331.847432] [] user_path_at_empty+0x6c/0xb0 > [ 6331.847432] [] ? trace_hardirqs_on+0xd/0x10 > [ 6331.847432] [] ? _raw_spin_unlock_irq+0x59/0x80 > [ 6331.847432] [] user_path_at+0xc/0x10 > [ 6331.847432] [] sys_setxattr+0x41/0xb0 > [ 6331.847432] [] ? trace_hardirqs_on_thunk+0x3a/0x3f > [ 6331.847432] [] 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] [] spin_dump+0x78/0xc0 > [ 6331.906944] [] do_raw_spin_lock+0xfe/0x140 > [ 6331.906944] [] _raw_spin_lock_nested+0x68/0x90 > [ 6331.906944] [] ? pull_rt_task+0xe2/0x270 > [ 6331.906944] [] ? _raw_spin_lock+0x67/0x70 > [ 6331.906944] [] ? pull_rt_task+0xd5/0x270 > [ 6331.906944] [] pull_rt_task+0xe2/0x270 > [ 6331.906944] [] pre_schedule_rt+0x14/0x20 > [ 6331.906944] [] __schedule+0x259/0x880 > [ 6331.906944] [] schedule+0x55/0x60 > [ 6331.906944] [] __rt_mutex_slowlock+0xaf/0x110 > [ 6331.906944] [] rt_mutex_slowlock+0xef/0x180 > [ 6331.906944] [] rt_mutex_lock+0x30/0x40 > [ 6331.906944] [] rcu_boost+0xca/0x110 > [ 6331.906944] [] ? rt_mutex_slowunlock+0x31/0x130 > [ 6331.906944] [] rcu_boost_kthread+0x222/0x390 > [ 6331.906944] [] ? synchronize_rcu_expedited+0x220/0x220 > [ 6331.906944] [] kthread+0xb2/0xc0 > [ 6331.906944] [] kernel_thread_helper+0x4/0x10 > [ 6331.906944] [] ? retint_restore_args+0x13/0x13 > [ 6331.906944] [] ? __init_kthread_worker+0x70/0x70 > [ 6331.906944] [] ? 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:[] [] 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] [] __x2apic_send_IPI_mask+0xdd/0x1b0 > [ 6331.906944] [] x2apic_send_IPI_all+0x17/0x20 > [ 6331.906944] [] arch_trigger_all_cpu_backtrace+0x64/0xb0 > [ 6331.906944] [] do_raw_spin_lock+0x103/0x140 > [ 6331.906944] [] _raw_spin_lock_nested+0x68/0x90 > [ 6331.906944] [] ? pull_rt_task+0xe2/0x270 > [ 6331.906944] [] ? _raw_spin_lock+0x67/0x70 > [ 6331.906944] [] ? pull_rt_task+0xd5/0x270 > [ 6331.906944] [] pull_rt_task+0xe2/0x270 > [ 6331.906944] [] pre_schedule_rt+0x14/0x20 > [ 6331.906944] [] __schedule+0x259/0x880 > [ 6331.906944] [] schedule+0x55/0x60 > [ 6331.906944] [] __rt_mutex_slowlock+0xaf/0x110 > [ 6331.906944] [] rt_mutex_slowlock+0xef/0x180 > [ 6331.906944] [] rt_mutex_lock+0x30/0x40 > [ 6331.906944] [] rcu_boost+0xca/0x110 > [ 6331.906944] [] ? rt_mutex_slowunlock+0x31/0x130 > [ 6331.906944] [] rcu_boost_kthread+0x222/0x390 > [ 6331.906944] [] ? synchronize_rcu_expedited+0x220/0x220 > [ 6331.906944] [] kthread+0xb2/0xc0 > [ 6331.906944] [] kernel_thread_helper+0x4/0x10 > [ 6331.906944] [] ? retint_restore_args+0x13/0x13 > [ 6331.906944] [] ? __init_kthread_worker+0x70/0x70 > [ 6331.906944] [] ? 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:[] [] 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] [] wake_up_process+0x10/0x20 > [ 6331.955620] [] rt_mutex_slowunlock+0xe9/0x130 > [ 6331.955620] [] rt_mutex_unlock+0x9/0x10 > [ 6331.955620] [] rcu_read_unlock_special+0x350/0x400 > [ 6331.955620] [] ? proc_sys_compare+0xe0/0x130 > [ 6331.955620] [] __rcu_read_unlock+0x57/0xb0 > [ 6331.955620] [] __d_lookup+0x2dd/0x2f0 > [ 6331.955620] [] ? do_raw_spin_unlock+0xd0/0xe0 > [ 6331.955620] [] lookup_fast+0x1ae/0x2e0 > [ 6331.955620] [] ? security_inode_permission+0x1d/0x20 > [ 6331.955620] [] link_path_walk+0x204/0x420 > [ 6331.955620] [] ? generic_readlink+0xb0/0xb0 > [ 6331.955620] [] ? do_setitimer+0x23b/0x290 > [ 6331.955620] [] path_lookupat+0x54/0x700 > [ 6331.955620] [] ? kmem_cache_alloc+0x154/0x240 > [ 6331.955620] [] do_path_lookup+0x2c/0xc0 > [ 6331.955620] [] user_path_at_empty+0x6c/0xb0 > [ 6331.955620] [] ? trace_hardirqs_on+0xd/0x10 > [ 6331.955620] [] ? _raw_spin_unlock_irq+0x59/0x80 > [ 6331.955620] [] ? sysret_check+0x22/0x5d > [ 6331.955620] [] user_path_at+0xc/0x10 > [ 6331.955620] [] sys_removexattr+0x30/0x80 > [ 6331.955620] [] 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:[] [] 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] > [ 6331.954033] [] delay_tsc+0x85/0x140 > [ 6331.954033] [] __delay+0xa/0x10 > [ 6331.954033] [] do_raw_spin_lock+0xdd/0x140 > [ 6331.954033] [] _raw_spin_lock+0x5b/0x70 > [ 6331.954033] [] ? load_balance+0x2b0/0x6e0 > [ 6331.954033] [] load_balance+0x2b0/0x6e0 > [ 6331.954033] [] ? rebalance_domains+0x17e/0x290 > [ 6331.954033] [] ? find_busiest_group+0x2a0/0x2a0 > [ 6331.954033] [] rebalance_domains+0x1bd/0x290 > [ 6331.954033] [] ? update_shares+0x240/0x240 > [ 6331.954033] [] ? sched_clock+0x1d/0x30 > [ 6331.954033] [] run_rebalance_domains+0x43/0x60 > [ 6331.954033] [] __do_softirq+0x221/0x460 > [ 6331.954033] [] ? kvm_clock_read+0x46/0x80 > [ 6331.954033] [] call_softirq+0x1c/0x30 > [ 6331.954033] [] do_softirq+0x75/0x120 > [ 6331.954033] [] irq_exit+0x5b/0xf0 > [ 6331.954033] [] smp_apic_timer_interrupt+0x8a/0xa0 > [ 6331.954033] [] apic_timer_interrupt+0x6f/0x80 > [ 6331.954033] > [ 6331.954033] [] ? default_idle+0x1a8/0x390 > [ 6331.954033] [] ? native_safe_halt+0x6/0x10 > [ 6331.954033] [] ? trace_hardirqs_on+0xd/0x10 > [ 6331.954033] [] default_idle+0x1c5/0x390 > [ 6331.954033] [] cpu_idle+0x11f/0x180 > [ 6331.954033] [] ? setup_APIC_timer+0x88/0x8d > [ 6331.954033] [] 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:[] [] 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] [] delay_tsc+0x10a/0x140 > [ 6331.847432] [] __delay+0xa/0x10 > [ 6331.847432] [] do_raw_spin_lock+0xdd/0x140 > [ 6331.847432] [] _raw_spin_lock+0x5b/0x70 > [ 6331.847432] [] ? rt_mutex_setprio+0x81/0x2c0 > [ 6331.847432] [] rt_mutex_setprio+0x81/0x2c0 > [ 6331.847432] [] __rt_mutex_adjust_prio+0x20/0x30 > [ 6331.847432] [] rt_mutex_slowunlock+0x104/0x130 > [ 6331.847432] [] rt_mutex_unlock+0x9/0x10 > [ 6331.847432] [] rcu_read_unlock_special+0x350/0x400 > [ 6331.847432] [] ? update_curr_rt+0x228/0x270 > [ 6331.847432] [] ? get_lock_stats+0x2a/0x60 > [ 6331.847432] [] rcu_preempt_note_context_switch+0x22a/0x300 > [ 6331.847432] [] __schedule+0x76a/0x880 > [ 6331.847432] [] preempt_schedule+0x51/0x70 > [ 6331.847432] [] ? read_persistent_clock+0x30/0x30 > [ 6331.847432] [] delay_tsc+0x123/0x140 > [ 6331.847432] [] __const_udelay+0x28/0x30 > [ 6331.847432] [] __rcu_read_unlock+0x44/0xb0 > [ 6331.847432] [] __d_lookup+0x2dd/0x2f0 > [ 6331.847432] [] ? do_raw_spin_unlock+0xd0/0xe0 > [ 6331.847432] [] lookup_fast+0x1ae/0x2e0 > [ 6331.847432] [] ? security_inode_permission+0x1d/0x20 > [ 6331.847432] [] link_path_walk+0x204/0x420 > [ 6331.847432] [] ? generic_readlink+0xb0/0xb0 > [ 6331.847432] [] path_lookupat+0x54/0x700 > [ 6331.847432] [] ? kmem_cache_alloc+0x154/0x240 > [ 6331.847432] [] ? __hrtimer_start_range_ns+0x448/0x490 > [ 6331.847432] [] do_path_lookup+0x2c/0xc0 > [ 6331.847432] [] ? get_lock_stats+0x2a/0x60 > [ 6331.847432] [] user_path_at_empty+0x6c/0xb0 > [ 6331.847432] [] ? trace_hardirqs_on+0xd/0x10 > [ 6331.847432] [] ? _raw_spin_unlock_irq+0x59/0x80 > [ 6331.847432] [] user_path_at+0xc/0x10 > [ 6331.847432] [] sys_setxattr+0x41/0xb0 > [ 6331.847432] [] ? trace_hardirqs_on_thunk+0x3a/0x3f > [ 6331.847432] [] 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] [] spin_dump+0x78/0xc0 > [ 6331.847432] [] do_raw_spin_lock+0xfe/0x140 > [ 6331.847432] [] _raw_spin_lock+0x5b/0x70 > [ 6331.847432] [] ? rt_mutex_setprio+0x81/0x2c0 > [ 6331.847432] [] rt_mutex_setprio+0x81/0x2c0 > [ 6331.847432] [] __rt_mutex_adjust_prio+0x20/0x30 > [ 6331.847432] [] rt_mutex_slowunlock+0x104/0x130 > [ 6331.847432] [] rt_mutex_unlock+0x9/0x10 > [ 6331.847432] [] rcu_read_unlock_special+0x350/0x400 > [ 6331.847432] [] ? update_curr_rt+0x228/0x270 > [ 6331.847432] [] ? get_lock_stats+0x2a/0x60 > [ 6331.847432] [] rcu_preempt_note_context_switch+0x22a/0x300 > [ 6331.847432] [] __schedule+0x76a/0x880 > [ 6331.847432] [] preempt_schedule+0x51/0x70 > [ 6331.847432] [] ? read_persistent_clock+0x30/0x30 > [ 6331.847432] [] delay_tsc+0x123/0x140 > [ 6331.847432] [] __const_udelay+0x28/0x30 > [ 6331.847432] [] __rcu_read_unlock+0x44/0xb0 > [ 6331.847432] [] __d_lookup+0x2dd/0x2f0 > [ 6331.847432] [] ? do_raw_spin_unlock+0xd0/0xe0 > [ 6331.847432] [] lookup_fast+0x1ae/0x2e0 > [ 6331.847432] [] ? security_inode_permission+0x1d/0x20 > [ 6331.847432] [] link_path_walk+0x204/0x420 > [ 6331.847432] [] ? generic_readlink+0xb0/0xb0 > [ 6331.847432] [] path_lookupat+0x54/0x700 > [ 6331.847432] [] ? kmem_cache_alloc+0x154/0x240 > [ 6331.847432] [] ? __hrtimer_start_range_ns+0x448/0x490 > [ 6331.847432] [] do_path_lookup+0x2c/0xc0 > [ 6331.847432] [] ? get_lock_stats+0x2a/0x60 > [ 6331.847432] [] user_path_at_empty+0x6c/0xb0 > [ 6331.847432] [] ? trace_hardirqs_on+0xd/0x10 > [ 6331.847432] [] ? _raw_spin_unlock_irq+0x59/0x80 > [ 6331.847432] [] user_path_at+0xc/0x10 > [ 6331.847432] [] sys_setxattr+0x41/0xb0 > [ 6331.847432] [] ? trace_hardirqs_on_thunk+0x3a/0x3f > [ 6331.847432] [] 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:[] [] 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] [] __delay+0xa/0x10 > [ 6331.906944] [] do_raw_spin_lock+0xdd/0x140 > [ 6331.906944] [] _raw_spin_lock_nested+0x68/0x90 > [ 6331.906944] [] ? pull_rt_task+0xe2/0x270 > [ 6331.906944] [] ? _raw_spin_lock+0x67/0x70 > [ 6331.906944] [] ? pull_rt_task+0xd5/0x270 > [ 6331.906944] [] pull_rt_task+0xe2/0x270 > [ 6331.906944] [] pre_schedule_rt+0x14/0x20 > [ 6331.906944] [] __schedule+0x259/0x880 > [ 6331.906944] [] schedule+0x55/0x60 > [ 6331.906944] [] __rt_mutex_slowlock+0xaf/0x110 > [ 6331.906944] [] rt_mutex_slowlock+0xef/0x180 > [ 6331.906944] [] rt_mutex_lock+0x30/0x40 > [ 6331.906944] [] rcu_boost+0xca/0x110 > [ 6331.906944] [] ? rt_mutex_slowunlock+0x31/0x130 > [ 6331.906944] [] rcu_boost_kthread+0x222/0x390 > [ 6331.906944] [] ? synchronize_rcu_expedited+0x220/0x220 > [ 6331.906944] [] kthread+0xb2/0xc0 > [ 6331.906944] [] kernel_thread_helper+0x4/0x10 > [ 6331.906944] [] ? retint_restore_args+0x13/0x13 > [ 6331.906944] [] ? __init_kthread_worker+0x70/0x70 > [ 6331.906944] [] ? 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:[] [] 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] [] __x2apic_send_IPI_mask+0xdd/0x1b0 > [ 6331.847432] [] x2apic_send_IPI_all+0x17/0x20 > [ 6331.847432] [] arch_trigger_all_cpu_backtrace+0x64/0xb0 > [ 6331.847432] [] do_raw_spin_lock+0x103/0x140 > [ 6331.847432] [] _raw_spin_lock+0x5b/0x70 > [ 6331.847432] [] ? rt_mutex_setprio+0x81/0x2c0 > [ 6331.847432] [] rt_mutex_setprio+0x81/0x2c0 > [ 6331.847432] [] __rt_mutex_adjust_prio+0x20/0x30 > [ 6331.847432] [] rt_mutex_slowunlock+0x104/0x130 > [ 6331.847432] [] rt_mutex_unlock+0x9/0x10 > [ 6331.847432] [] rcu_read_unlock_special+0x350/0x400 > [ 6331.847432] [] ? update_curr_rt+0x228/0x270 > [ 6331.847432] [] ? get_lock_stats+0x2a/0x60 > [ 6331.847432] [] rcu_preempt_note_context_switch+0x22a/0x300 > [ 6331.847432] [] __schedule+0x76a/0x880 > [ 6331.847432] [] preempt_schedule+0x51/0x70 > [ 6331.847432] [] ? read_persistent_clock+0x30/0x30 > [ 6331.847432] [] delay_tsc+0x123/0x140 > [ 6331.847432] [] __const_udelay+0x28/0x30 > [ 6331.847432] [] __rcu_read_unlock+0x44/0xb0 > [ 6331.847432] [] __d_lookup+0x2dd/0x2f0 > [ 6331.847432] [] ? do_raw_spin_unlock+0xd0/0xe0 > [ 6331.847432] [] lookup_fast+0x1ae/0x2e0 > [ 6331.847432] [] ? security_inode_permission+0x1d/0x20 > [ 6331.847432] [] link_path_walk+0x204/0x420 > [ 6331.847432] [] ? generic_readlink+0xb0/0xb0 > [ 6331.847432] [] path_lookupat+0x54/0x700 > [ 6331.847432] [] ? kmem_cache_alloc+0x154/0x240 > [ 6331.847432] [] ? __hrtimer_start_range_ns+0x448/0x490 > [ 6331.847432] [] do_path_lookup+0x2c/0xc0 > [ 6331.847432] [] ? get_lock_stats+0x2a/0x60 > [ 6331.847432] [] user_path_at_empty+0x6c/0xb0 > [ 6331.847432] [] ? trace_hardirqs_on+0xd/0x10 > [ 6331.847432] [] ? _raw_spin_unlock_irq+0x59/0x80 > [ 6331.847432] [] user_path_at+0xc/0x10 > [ 6331.847432] [] sys_setxattr+0x41/0xb0 > [ 6331.847432] [] ? trace_hardirqs_on_thunk+0x3a/0x3f > [ 6331.847432] [] 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:[] [] 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] > [ 6331.954033] [] delay_tsc+0x10a/0x140 > [ 6331.954033] [] __delay+0xa/0x10 > [ 6331.954033] [] do_raw_spin_lock+0xdd/0x140 > [ 6331.954033] [] _raw_spin_lock+0x5b/0x70 > [ 6331.954033] [] ? load_balance+0x2b0/0x6e0 > [ 6331.954033] [] load_balance+0x2b0/0x6e0 > [ 6331.954033] [] ? rebalance_domains+0x17e/0x290 > [ 6331.954033] [] ? find_busiest_group+0x2a0/0x2a0 > [ 6331.954033] [] rebalance_domains+0x1bd/0x290 > [ 6331.954033] [] ? update_shares+0x240/0x240 > [ 6331.954033] [] ? sched_clock+0x1d/0x30 > [ 6331.954033] [] run_rebalance_domains+0x43/0x60 > [ 6331.954033] [] __do_softirq+0x221/0x460 > [ 6331.954033] [] ? kvm_clock_read+0x46/0x80 > [ 6331.954033] [] call_softirq+0x1c/0x30 > [ 6331.954033] [] do_softirq+0x75/0x120 > [ 6331.954033] [] irq_exit+0x5b/0xf0 > [ 6331.954033] [] smp_apic_timer_interrupt+0x8a/0xa0 > [ 6331.954033] [] apic_timer_interrupt+0x6f/0x80 > [ 6331.954033] > [ 6331.954033] [] ? default_idle+0x1a8/0x390 > [ 6331.954033] [] ? native_safe_halt+0x6/0x10 > [ 6331.954033] [] ? trace_hardirqs_on+0xd/0x10 > [ 6331.954033] [] default_idle+0x1c5/0x390 > [ 6331.954033] [] cpu_idle+0x11f/0x180 > [ 6331.954033] [] ? setup_APIC_timer+0x88/0x8d > [ 6331.954033] [] 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:[] [] 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] [] wake_up_process+0x10/0x20 > [ 6331.955620] [] rt_mutex_slowunlock+0xe9/0x130 > [ 6331.955620] [] rt_mutex_unlock+0x9/0x10 > [ 6331.955620] [] rcu_read_unlock_special+0x350/0x400 > [ 6331.955620] [] ? proc_sys_compare+0xe0/0x130 > [ 6331.955620] [] __rcu_read_unlock+0x57/0xb0 > [ 6331.955620] [] __d_lookup+0x2dd/0x2f0 > [ 6331.955620] [] ? do_raw_spin_unlock+0xd0/0xe0 > [ 6331.955620] [] lookup_fast+0x1ae/0x2e0 > [ 6331.955620] [] ? security_inode_permission+0x1d/0x20 > [ 6331.955620] [] link_path_walk+0x204/0x420 > [ 6331.955620] [] ? generic_readlink+0xb0/0xb0 > [ 6331.955620] [] ? do_setitimer+0x23b/0x290 > [ 6331.955620] [] path_lookupat+0x54/0x700 > [ 6331.955620] [] ? kmem_cache_alloc+0x154/0x240 > [ 6331.955620] [] do_path_lookup+0x2c/0xc0 > [ 6331.955620] [] user_path_at_empty+0x6c/0xb0 > [ 6331.955620] [] ? trace_hardirqs_on+0xd/0x10 > [ 6331.955620] [] ? _raw_spin_unlock_irq+0x59/0x80 > [ 6331.955620] [] ? sysret_check+0x22/0x5d > [ 6331.955620] [] user_path_at+0xc/0x10 > [ 6331.955620] [] sys_removexattr+0x30/0x80 > [ 6331.955620] [] 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 >