====================================== | [ 49.927997][ C1] 1 lock held by swapper/1/0: | [ 49.928082][ C1] #0: ffffffffa3577c00 (rcu_callback){....}-{0:0}, at: rcu_do_batch (./include/linux/rcupdate.h:300 (discriminator 2) kernel/rcu/tree.c:2611 (discriminator 2)) | [ 49.928239][ C1] | [ 49.928239][ C1] stack backtrace: [ 49.928350][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 49.928352][ C1] Call Trace: [ 49.928353][ C1] [ 49.928355][ C1] dump_stack_lvl (lib/dump_stack.c:94 lib/dump_stack.c:120) [ 49.928360][ C1] print_usage_bug.part.0.cold (kernel/locking/lockdep.c:4042) [ 49.928362][ C1] ? filter_irq_stacks (kernel/stacktrace.c:402) [ 49.928366][ C1] mark_lock_irq (kernel/locking/lockdep.c:4013 kernel/locking/lockdep.c:4056 kernel/locking/lockdep.c:4267) [ 49.928367][ C1] ? sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1061 (discriminator 37) arch/x86/kernel/apic/apic.c:1061 (discriminator 37)) [ 49.928369][ C1] ? asm_sysvec_apic_timer_interrupt (./arch/x86/include/asm/idtentry.h:697) [ 49.928371][ C1] ? save_trace (kernel/locking/lockdep.c:589) [ 49.928373][ C1] mark_lock (kernel/locking/lockdep.c:4753) [ 49.928375][ C1] mark_usage (kernel/locking/lockdep.c:4642) [ 49.928377][ C1] __lock_acquire (kernel/locking/lockdep.c:5191) [ 49.928378][ C1] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:752 (discriminator 3)) [ 49.928380][ C1] ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 22)) [ 49.928384][ C1] lock_acquire.part.0 (kernel/locking/lockdep.c:5868 (discriminator 1)) [ 49.928385][ C1] ? tcf_mirred_release (./include/linux/spinlock.h:342 net/sched/act_mirred.c:78) act_mirred [ 49.928387][ C1] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:752 (discriminator 3)) [ 49.928389][ C1] ? lock_acquire (./include/trace/events/lock.h:24 (discriminator 22) kernel/locking/lockdep.c:5831 (discriminator 22)) [ 49.928391][ C1] _raw_spin_lock (./include/linux/spinlock_api_smp.h:158 (discriminator 1) kernel/locking/spinlock.c:158 (discriminator 1)) [ 49.928393][ C1] ? tcf_mirred_release (./include/linux/spinlock.h:342 net/sched/act_mirred.c:78) act_mirred [ 49.928394][ C1] tcf_mirred_release (./include/linux/spinlock.h:342 net/sched/act_mirred.c:78) act_mirred [ 49.928396][ C1] tcf_action_rcu_free (net/sched/act_api.c:367 net/sched/act_api.c:378) [ 49.928398][ C1] ? rcu_do_batch (./include/linux/rcupdate.h:310 (discriminator 2) kernel/rcu/tree.c:2619 (discriminator 2)) [ 49.928399][ C1] rcu_do_batch (kernel/rcu/tree.c:2617) [ 49.928401][ C1] ? lock_acquire.part.0 (kernel/locking/lockdep.c:5868 (discriminator 1)) [ 49.928403][ C1] ? trace_rcu_batch_end (./include/trace/events/rcu.h:714 (discriminator 19)) [ 49.928405][ C1] ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 22)) [ 49.928407][ C1] ? do_raw_spin_unlock (./include/linux/instrumented.h:82 ./include/linux/atomic/atomic-instrumented.h:32 ./include/asm-generic/qspinlock.h:57 kernel/locking/spinlock_debug.c:101 kernel/locking/spinlock_debug.c:141) [ 49.928408][ C1] ? lockdep_hardirqs_on_prepare.part.0 (kernel/locking/lockdep.c:470 (discriminator 2) kernel/locking/lockdep.c:4411 (discriminator 2)) [ 49.928410][ C1] ? lockdep_hardirqs_on (kernel/locking/lockdep.c:4472) [ 49.928411][ C1] ? _raw_spin_unlock_irqrestore (./include/linux/spinlock_api_smp.h:178 (discriminator 6) kernel/locking/spinlock.c:198 (discriminator 6)) [ 49.928413][ C1] ? _raw_spin_unlock_irqrestore (./include/linux/spinlock_api_smp.h:179 (discriminator 1) kernel/locking/spinlock.c:198 (discriminator 1)) [ 49.928415][ C1] rcu_core (kernel/rcu/tree.c:2869) [ 49.928417][ C1] handle_softirqs (kernel/softirq.c:622) [ 49.928418][ C1] ? find_held_lock (kernel/locking/lockdep.c:5350) [ 49.928421][ C1] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5535) [ 49.928423][ C1] ? _local_bh_enable (kernel/softirq.c:405 (discriminator 1)) [ 49.928425][ C1] __irq_exit_rcu (kernel/softirq.c:656 kernel/softirq.c:496 kernel/softirq.c:735) [ 49.928426][ C1] irq_exit_rcu (kernel/softirq.c:752) [ 49.928428][ C1] sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1061 (discriminator 37) arch/x86/kernel/apic/apic.c:1061 (discriminator 37)) [ 49.928429][ C1] [ 49.928430][ C1] [ 49.928431][ C1] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:752 (discriminator 3)) [ 49.928432][ C1] asm_sysvec_apic_timer_interrupt (./arch/x86/include/asm/idtentry.h:697) [ 49.928434][ C1] RIP: 0010:pv_native_safe_halt (./arch/x86/include/asm/irqflags.h:48 arch/x86/kernel/paravirt.c:62) [ 49.928436][ C1] Code: 48 8b 3d 44 d3 00 02 e8 1f 00 00 00 48 2b 05 c8 71 a3 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 43 f4 22 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 All code ======== 0: 48 8b 3d 44 d3 00 02 mov 0x200d344(%rip),%rdi # 0x200d34b 7: e8 1f 00 00 00 call 0x2b c: 48 2b 05 c8 71 a3 00 sub 0xa371c8(%rip),%rax # 0xa371db 13: c3 ret 14: 0f 1f 80 00 00 00 00 nopl 0x0(%rax) 1b: f3 0f 1e fa endbr64 1f: eb 07 jmp 0x28 21: 0f 00 2d 43 f4 22 00 verw 0x22f443(%rip) # 0x22f46b 28: fb sti 29: f4 hlt 2a:* c3 ret <-- trapping instruction 2b: 0f 1f 40 d6 nopl -0x2a(%rax) 2f: 48 83 ec 20 sub $0x20,%rsp 33: 8b 17 mov (%rdi),%edx 35: 49 89 f8 mov %rdi,%r8 38: 83 e2 fe and $0xfffffffe,%edx 3b: 41 89 d2 mov %edx,%r10d 3e: 0f .byte 0xf 3f: 01 .byte 0x1 Code starting with the faulting instruction =========================================== 0: c3 ret 1: 0f 1f 40 d6 nopl -0x2a(%rax) 5: 48 83 ec 20 sub $0x20,%rsp 9: 8b 17 mov (%rdi),%edx b: 49 89 f8 mov %rdi,%r8 e: 83 e2 fe and $0xfffffffe,%edx 11: 41 89 d2 mov %edx,%r10d 14: 0f .byte 0xf 15: 01 .byte 0x1 [ 49.928438][ C1] RSP: 0018:ffa0000000147de8 EFLAGS: 00000296 [ 49.928440][ C1] RAX: 0000000000120b87 RBX: ff11000001bb2340 RCX: ffffffffa02d9667 [ 49.928442][ C1] RDX: ff11000001bb2340 RSI: ffffffffa3001bcb RDI: ffffffffa2a6f6c0 [ 49.928443][ C1] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 49.928444][ C1] R10: 0000000000000001 R11: 0000000000000001 R12: 1ff4000000028fc0 [ 49.928445][ C1] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 49.928446][ C1] ? cpuidle_idle_call.constprop.0 (kernel/sched/idle.c:199) [ 49.928449][ C1] ? lockdep_hardirqs_on (kernel/locking/lockdep.c:4472) [ 49.928451][ C1] default_idle (./arch/x86/include/asm/paravirt.h:62 arch/x86/kernel/process.c:767) [ 49.928453][ C1] default_idle_call (kernel/sched/idle.c:122) [ 49.928454][ C1] cpuidle_idle_call.constprop.0 (kernel/sched/idle.c:199) [ 49.928456][ C1] ? arch_cpu_idle_exit+0x40/0x40 [ 49.928457][ C1] ? mark_tsc_async_resets (arch/x86/kernel/tsc_sync.c:49) [ 49.928460][ C1] ? debug_smp_processor_id (lib/smp_processor_id.c:57) [ 49.928462][ C1] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:752 (discriminator 3)) [ 49.928463][ C1] do_idle (kernel/sched/idle.c:352) [ 49.928465][ C1] cpu_startup_entry (kernel/sched/idle.c:451) [ 49.928467][ C1] start_secondary (arch/x86/kernel/smpboot.c:312) [ 49.928468][ C1] ? set_cpu_sibling_map (./arch/x86/include/asm/smp.h:148 (discriminator 4)) [ 49.928470][ C1] common_startup_64 (arch/x86/kernel/head_64.S:418) | [ 56.479412][ C1] RCU nest depth: 0, expected: 0 | [ 56.479490][ C1] INFO: lockdep is turned off. | [ 56.479567][ C1] Preemption disabled at: | [ 56.479568][ C1] handle_softirqs (kernel/softirq.c:463 (discriminator 1) kernel/softirq.c:598 (discriminator 1)) [ 56.479736][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 56.479738][ C1] Call Trace: [ 56.479740][ C1] [ 56.479741][ C1] dump_stack_lvl (lib/dump_stack.c:94 lib/dump_stack.c:120) [ 56.479746][ C1] ? handle_softirqs (kernel/softirq.c:463 (discriminator 1) kernel/softirq.c:598 (discriminator 1)) [ 56.479748][ C1] __might_resched.cold (kernel/sched/core.c:9163) [ 56.479752][ C1] ? perf_trace_sched_switch (./include/linux/list.h:1021 (discriminator 3)) [ 56.479756][ C1] __mutex_lock (kernel/locking/mutex.c:623 kernel/locking/mutex.c:820) [ 56.479760][ C1] ? __tcf_chain_put (net/sched/cls_api.c:694) [ 56.479764][ C1] ? ww_mutex_lock (kernel/locking/mutex.c:933) [ 56.479767][ C1] ? __tcf_chain_put (net/sched/cls_api.c:694) [ 56.479768][ C1] __tcf_chain_put (net/sched/cls_api.c:694) [ 56.479770][ C1] ? free_percpu.part.0 (./include/linux/spinlock.h:408 (discriminator 3) mm/percpu.c:2283 (discriminator 3)) [ 56.479774][ C1] tcf_action_rcu_free (net/sched/act_api.c:130 net/sched/act_api.c:370 net/sched/act_api.c:378) [ 56.479776][ C1] ? rcu_do_batch (./include/linux/rcupdate.h:310 (discriminator 2) kernel/rcu/tree.c:2619 (discriminator 2)) [ 56.479779][ C1] rcu_do_batch (kernel/rcu/tree.c:2617) [ 56.479782][ C1] ? trace_rcu_batch_end (./include/trace/events/rcu.h:714 (discriminator 19)) [ 56.479784][ C1] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:752 (discriminator 3)) [ 56.479786][ C1] ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 22)) [ 56.479789][ C1] ? trace_irq_disable.constprop.0 (./include/trace/events/preemptirq.h:36 (discriminator 22)) [ 56.479791][ C1] rcu_core (kernel/rcu/tree.c:2869) [ 56.479793][ C1] handle_softirqs (kernel/softirq.c:622) [ 56.479794][ C1] ? clockevents_tick_resume (kernel/time/clockevents.c:193) [ 56.479797][ C1] ? _local_bh_enable (kernel/softirq.c:405 (discriminator 1)) [ 56.479799][ C1] ? lock_release (./include/trace/events/lock.h:69 (discriminator 22) kernel/locking/lockdep.c:5879 (discriminator 22)) [ 56.479801][ C1] __irq_exit_rcu (kernel/softirq.c:656 kernel/softirq.c:496 kernel/softirq.c:735) [ 56.479803][ C1] irq_exit_rcu (kernel/softirq.c:752) [ 56.479804][ C1] sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1061 (discriminator 16) arch/x86/kernel/apic/apic.c:1061 (discriminator 16)) [ 56.479807][ C1] asm_sysvec_apic_timer_interrupt (./arch/x86/include/asm/idtentry.h:697) [ 56.479809][ C1] RIP: 0033:0x7f681684c238 [ 56.479812][ C1] Code: 00 00 f3 0f 1e fa 55 48 89 e5 53 48 89 fb 48 83 ec 18 66 83 3d bc 58 25 00 00 48 8b 47 08 74 0c eb 38 66 83 3d ac 58 25 00 00 <75> 2e 48 89 df ff 50 10 48 89 43 08 48 85 c0 75 e7 8b 83 4c 05 00 All code ======== 0: 00 00 add %al,(%rax) 2: f3 0f 1e fa endbr64 6: 55 push %rbp 7: 48 89 e5 mov %rsp,%rbp a: 53 push %rbx b: 48 89 fb mov %rdi,%rbx e: 48 83 ec 18 sub $0x18,%rsp 12: 66 83 3d bc 58 25 00 cmpw $0x0,0x2558bc(%rip) # 0x2558d6 19: 00 1a: 48 8b 47 08 mov 0x8(%rdi),%rax 1e: 74 0c je 0x2c 20: eb 38 jmp 0x5a 22: 66 83 3d ac 58 25 00 cmpw $0x0,0x2558ac(%rip) # 0x2558d6 29: 00 2a:* 75 2e jne 0x5a <-- trapping instruction 2c: 48 89 df mov %rbx,%rdi 2f: ff 50 10 call *0x10(%rax) 32: 48 89 43 08 mov %rax,0x8(%rbx) 36: 48 85 c0 test %rax,%rax 39: 75 e7 jne 0x22 3b: 8b .byte 0x8b 3c: 83 .byte 0x83 3d: 4c rex.WR 3e: 05 .byte 0x5 ... Code starting with the faulting instruction =========================================== 0: 75 2e jne 0x30 2: 48 89 df mov %rbx,%rdi 5: ff 50 10 call *0x10(%rax) 8: 48 89 43 08 mov %rax,0x8(%rbx) c: 48 85 c0 test %rax,%rax f: 75 e7 jne 0xfffffffffffffff8 11: 8b .byte 0x8b 12: 83 .byte 0x83 13: 4c rex.WR 14: 05 .byte 0x5 ... [ 56.479814][ C1] RSP: 002b:00007ffe115114d0 EFLAGS: 00000246 [ 56.479818][ C1] RAX: 000055880e249f90 RBX: 000055880e0ef010 RCX: 000055880e128241 [ 56.479819][ C1] RDX: 000055880e24a258 RSI: 000055880e10f980 RDI: 000055880e0ef010 [ 56.479820][ C1] RBP: 00007ffe115114f0 R08: 0000000000000000 R09: 0000000000000000 [ 56.479821][ C1] R10: 0000000000000000 R11: 0000000000000202 R12: 00007ffe115117b8 Finger prints: handle_softirqs:dump_stack_lvl:__mutex_lock:__tcf_chain_put:tcf_action_rcu_free mark_lock_irq:mark_lock:mark_usage:__lock_acquire:_raw_spin_lock