======================================
| [ 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