======================================
| [ 497.040290][ C3] common_startup_64 (arch/x86/kernel/head_64.S:419)
| [ 497.040292][ C3]
| [ 497.040293][ C3]
| [ 497.040293][ C3] stack backtrace:
[ 497.040300][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011
[ 497.040302][ C3] Call Trace:
[ 497.040303][ C3]
[ 497.040305][ C3] dump_stack_lvl (lib/dump_stack.c:123)
[ 497.040310][ C3] print_irq_inversion_bug.part.0 (kernel/locking/lockdep.c:4127)
[ 497.040314][ C3] mark_lock_irq (kernel/locking/lockdep.c:4295)
[ 497.040321][ C3] mark_lock (kernel/locking/lockdep.c:4753)
[ 497.040324][ C3] mark_usage (kernel/locking/lockdep.c:4639)
[ 497.040327][ C3] __lock_acquire (kernel/locking/lockdep.c:5191)
[ 497.040332][ C3] lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870)
[ 497.040334][ C3] ? console_lock_spinning_enable (kernel/printk/printk.c:1924)
[ 497.040338][ C3] ? rcu_is_watching (./include/linux/context_tracking.h:128 kernel/rcu/tree.c:751)
[ 497.040344][ C3] ? lock_acquire (./include/trace/events/lock.h:24 kernel/locking/lockdep.c:5831)
[ 497.040346][ C3] ? console_lock_spinning_enable (kernel/printk/printk.c:1924)
[ 497.040350][ C3] console_lock_spinning_enable (kernel/printk/printk.c:1925)
[ 497.040353][ C3] ? console_lock_spinning_enable (kernel/printk/printk.c:1924)
[ 497.040356][ C3] console_emit_next_record (kernel/printk/printk.c:3050 (discriminator 3) kernel/printk/printk.c:3137 (discriminator 3))
[ 497.040360][ C3] ? devkmsg_read (kernel/printk/printk.c:3082)
[ 497.040364][ C3] ? rcu_is_watching (./include/linux/context_tracking.h:128 kernel/rcu/tree.c:751)
[ 497.040369][ C3] console_flush_all (kernel/printk/printk.c:3227)
[ 497.040373][ C3] ? console_emit_next_record (kernel/printk/printk.c:3188)
[ 497.040376][ C3] ? __lock_acquire (kernel/locking/lockdep.c:5237)
[ 497.040381][ C3] console_unlock (kernel/printk/printk.c:3286 kernel/printk/printk.c:3325)
[ 497.040384][ C3] ? console_flush_all (kernel/printk/printk.c:3320)
[ 497.040387][ C3] ? rcu_is_watching (./include/linux/context_tracking.h:128 kernel/rcu/tree.c:751)
[ 497.040391][ C3] ? irq_work_single (kernel/irq_work.c:222)
[ 497.040394][ C3] ? irq_work_single (kernel/irq_work.c:222)
[ 497.040398][ C3] wake_up_klogd_work_func (kernel/printk/printk.c:4529)
[ 497.040402][ C3] irq_work_single (kernel/irq_work.c:222)
[ 497.040405][ C3] irq_work_run_list (kernel/irq_work.c:251 (discriminator 3))
[ 497.040408][ C3] ? irq_work_tick (kernel/irq_work.c:277)
[ 497.040412][ C3] update_process_times (kernel/time/timer.c:2478)
[ 497.040416][ C3] tick_nohz_handler (kernel/time/tick-sched.c:277 kernel/time/tick-sched.c:297)
[ 497.040419][ C3] ? rcu_is_watching (./include/linux/context_tracking.h:128 kernel/rcu/tree.c:751)
[ 497.040423][ C3] __run_hrtimer (kernel/time/hrtimer.c:1761)
[ 497.040426][ C3] ? tick_do_update_jiffies64.part.0 (kernel/time/tick-sched.c:285)
[ 497.040430][ C3] __hrtimer_run_queues (./include/linux/timerqueue.h:25 kernel/time/hrtimer.c:1805)
[ 497.040434][ C3] ? hrtimer_interrupt (kernel/time/hrtimer.c:640 (discriminator 2) kernel/time/hrtimer.c:1869 (discriminator 2))
[ 497.040438][ C3] ? __run_hrtimer (kernel/time/hrtimer.c:1795)
[ 497.040441][ C3] ? kvm_clock_get_cycles (./arch/x86/include/asm/preempt.h:95 arch/x86/kernel/kvmclock.c:80 arch/x86/kernel/kvmclock.c:86)
[ 497.040448][ C3] hrtimer_interrupt (kernel/time/hrtimer.c:1890)
[ 497.040455][ C3] __sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1057)
[ 497.040459][ C3] sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1050 arch/x86/kernel/apic/apic.c:1050)
[ 497.040463][ C3]
[ 497.040464][ C3]
[ 497.040465][ C3] asm_sysvec_apic_timer_interrupt (./arch/x86/include/asm/idtentry.h:702)
[ 497.040468][ C3] RIP: 0010:finish_task_switch.isra.0 (./arch/x86/include/asm/jump_label.h:36 kernel/sched/core.c:4934 kernel/sched/core.c:5235)
[ 497.040472][ C3] Code: 4c 8b 23 4c 89 ff 48 c7 03 00 00 00 00 ff d2 4d 85 e4 75 bd 4c 89 ff e8 c0 5f 11 02 e8 db ae 2e 00 fb 65 48 8b 1d 72 7f 14 04 <66> 90 48 83 7d d0 00 74 55 65 48 8b 1d 61 7f 14 04 48 8d bb 18 05
All code
========
0: 4c 8b 23 mov (%rbx),%r12
3: 4c 89 ff mov %r15,%rdi
6: 48 c7 03 00 00 00 00 movq $0x0,(%rbx)
d: ff d2 call *%rdx
f: 4d 85 e4 test %r12,%r12
12: 75 bd jne 0xffffffffffffffd1
14: 4c 89 ff mov %r15,%rdi
17: e8 c0 5f 11 02 call 0x2115fdc
1c: e8 db ae 2e 00 call 0x2eaefc
21: fb sti
22: 65 48 8b 1d 72 7f 14 mov %gs:0x4147f72(%rip),%rbx # 0x4147f9c
29: 04
2a:* 66 90 xchg %ax,%ax <-- trapping instruction
2c: 48 83 7d d0 00 cmpq $0x0,-0x30(%rbp)
31: 74 55 je 0x88
33: 65 48 8b 1d 61 7f 14 mov %gs:0x4147f61(%rip),%rbx # 0x4147f9c
3a: 04
3b: 48 rex.W
3c: 8d .byte 0x8d
3d: bb .byte 0xbb
3e: 18 .byte 0x18
3f: 05 .byte 0x5
Code starting with the faulting instruction
===========================================
0: 66 90 xchg %ax,%ax
2: 48 83 7d d0 00 cmpq $0x0,-0x30(%rbp)
7: 74 55 je 0x5e
9: 65 48 8b 1d 61 7f 14 mov %gs:0x4147f61(%rip),%rbx # 0x4147f72
10: 04
11: 48 rex.W
12: 8d .byte 0x8d
13: bb .byte 0xbb
14: 18 .byte 0x18
15: 05 .byte 0x5
[ 497.040474][ C3] RSP: 0018:ffffc90000157cd0 EFLAGS: 00000202
[ 497.040477][ C3] RAX: 0000000000794c73 RBX: ffff888001ab45c0 RCX: ffffffffb25cce7f
[ 497.040479][ C3] RDX: 0000000000000000 RSI: ffffffffb4beb6fc RDI: ffffffffb4657ae0
[ 497.040481][ C3] RBP: ffffc90000157d10 R08: 0000000000000001 R09: 0000000000000001
[ 497.040483][ C3] R10: ffffffffb5a1d717 R11: ffff8880363b6958 R12: ffff8880363b6958
[ 497.040484][ C3] R13: ffff8880144e2300 R14: ffff8880363b74a8 R15: ffff8880363b6940
[ 497.040488][ C3] ? trace_irq_enable.constprop.0 (./arch/x86/include/asm/bitops.h:227 ./arch/x86/include/asm/bitops.h:239 ./include/asm-generic/bitops/instrumented-non-atomic.h:142 ./include/linux/cpumask.h:646 ./include/linux/cpumask.h:1205 ./include/trace/events/preemptirq.h:40)
[ 497.040494][ C3] ? __switch_to (./arch/x86/include/asm/cpufeature.h:101 arch/x86/kernel/process_64.c:378 arch/x86/kernel/process_64.c:666)
[ 497.040498][ C3] __schedule (kernel/sched/core.c:6971)
[ 497.040503][ C3] ? io_schedule_timeout (kernel/sched/core.c:6817)
[ 497.040506][ C3] ? trace_rcu_watching (./include/trace/events/rcu.h:480 (discriminator 21))
[ 497.040510][ C3] ? rcu_is_watching (./include/linux/context_tracking.h:128 kernel/rcu/tree.c:751)
[ 497.040514][ C3] ? default_idle_call (./arch/x86/include/asm/irqflags.h:42 ./arch/x86/include/asm/irqflags.h:119 kernel/sched/idle.c:129)
[ 497.040516][ C3] ? lockdep_hardirqs_on (kernel/locking/lockdep.c:4472)
[ 497.040521][ C3] ? arch_cpu_idle_exit+0x40/0x40
[ 497.040523][ C3] ? trace_irq_enable.constprop.0 (./arch/x86/include/asm/bitops.h:227 ./arch/x86/include/asm/bitops.h:239 ./include/asm-generic/bitops/instrumented-non-atomic.h:142 ./include/linux/cpumask.h:646 ./include/linux/cpumask.h:1205 ./include/trace/events/preemptirq.h:40)
[ 497.040527][ C3] schedule_idle (./arch/x86/include/asm/bitops.h:206 (discriminator 1) ./arch/x86/include/asm/bitops.h:238 (discriminator 1) ./include/linux/thread_info.h:192 (discriminator 1) ./include/linux/thread_info.h:208 (discriminator 1) ./include/linux/sched.h:2211 (discriminator 1) kernel/sched/core.c:7085 (discriminator 1))
[ 497.040531][ C3] do_idle (kernel/sched/idle.c:362)
[ 497.040534][ C3] cpu_startup_entry (kernel/sched/idle.c:427 (discriminator 1))
[ 497.040536][ C3] start_secondary (arch/x86/kernel/smpboot.c:203 arch/x86/kernel/smpboot.c:283)
[ 497.040540][ C3] ? set_cpu_sibling_map (arch/x86/kernel/smpboot.c:233)
Finger prints:
common_startup_64:dump_stack_lvl:mark_lock_irq:mark_lock:mark_usage