======================================
| [   13.767477][  T250] softirqs last disabled at (31445): __irq_exit_rcu (kernel/softirq.c:679 kernel/softirq.c:519 kernel/softirq.c:767)
| [   13.767479][  T250] Preemption disabled at:
| [   13.767480][  T250] 0x0
| [   13.767491][  T250] Tainted: [W]=WARN
[   13.767492][  T250] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011
[   13.767493][  T250] Call Trace:
[   13.767495][  T250]  <TASK>
[   13.767496][  T250]  dump_stack_lvl (lib/dump_stack.c:94 lib/dump_stack.c:120)
[   13.767502][  T250]  __might_resched.cold (kernel/sched/core.c:9234)
[   13.767507][  T250]  ? perf_trace_sched_switch (./include/linux/list.h:1037 (discriminator 3))
[   13.767511][  T250]  ? __kmalloc_noprof (./include/linux/sched/mm.h:317 mm/slub.c:4636 mm/slub.c:4974 mm/slub.c:5413 mm/slub.c:5439)
[   13.767516][  T250]  __kmalloc_noprof (./include/linux/sched/mm.h:322 (discriminator 2) mm/slub.c:4636 (discriminator 2) mm/slub.c:4974 (discriminator 2) mm/slub.c:5413 (discriminator 2) mm/slub.c:5439 (discriminator 2))
[   13.767518][  T250]  ? alloc_buf.isra.0 (./include/linux/slab.h:995 drivers/char/virtio_console.c:429)
[   13.767525][  T250]  ? do_raw_spin_unlock (./include/linux/instrumented.h:82 ./include/linux/atomic/atomic-instrumented.h:32 ./include/asm-generic/qspinlock.h:58 kernel/locking/spinlock_debug.c:101 kernel/locking/spinlock_debug.c:141)
[   13.767527][  T250]  alloc_buf.isra.0 (./include/linux/slab.h:995 drivers/char/virtio_console.c:429)
[   13.767531][  T250]  put_chars (drivers/char/virtio_console.c:1176)
[   13.767534][  T250]  ? __send_to_port (drivers/char/virtio_console.c:654)
[   13.767536][  T250]  ? printk_get_next_message (kernel/printk/printk.c:3072)
[   13.767539][  T250]  ? rcu_read_lock_any_held (./include/linux/lockdep.h:249 kernel/rcu/update.c:386 kernel/rcu/update.c:380)
[   13.767542][  T250]  ? validate_chain (kernel/locking/lockdep.c:3817 (discriminator 2) kernel/locking/lockdep.c:3837 (discriminator 2) kernel/locking/lockdep.c:3892 (discriminator 2))
[   13.767546][  T250]  hvc_console_print (drivers/tty/hvc/hvc_console.c:177 (discriminator 2))
[   13.767550][  T250]  ? __lock_acquire (kernel/locking/lockdep.c:5254)
[   13.767551][  T250]  ? __lock_acquire (kernel/locking/lockdep.c:5254)
[   13.767556][  T250]  ? hvc_write (drivers/tty/hvc/hvc_console.c:501)
[   13.767559][  T250]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   13.767562][  T250]  ? lock_acquire (./include/trace/events/lock.h:23 (discriminator 22) kernel/locking/lockdep.c:5871 (discriminator 22))
[   13.767566][  T250]  console_emit_next_record (kernel/printk/printk.c:3183)
[   13.767570][  T250]  ? devkmsg_read (./arch/x86/include/asm/atomic64_64.h:20)
[   13.767574][  T250]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   13.767576][  T250]  ? lock_acquire (./include/trace/events/lock.h:23 (discriminator 22) kernel/locking/lockdep.c:5871 (discriminator 22))
[   13.767580][  T250]  console_flush_one_record (kernel/printk/printk.c:3268)
[   13.767584][  T250]  ? console_emit_next_record (kernel/printk/printk.c:3150)
[   13.767586][  T250]  ? __lock_acquire (kernel/locking/lockdep.c:5254)
[   13.767591][  T250]  console_unlock (kernel/printk/printk.c:3350 kernel/printk/printk.c:3380 kernel/printk/printk.c:3420)
[   13.767593][  T250]  ? console_flush_one_record (kernel/printk/printk.c:3316)
[   13.767595][  T250]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   13.767597][  T250]  ? lock_acquire (./include/trace/events/lock.h:23 (discriminator 18) ./include/trace/events/lock.h:23 (discriminator 18) kernel/locking/lockdep.c:5871 (discriminator 18))
[   13.767601][  T250]  ? __down_trylock_console_sem (kernel/printk/printk.c:334)
[   13.767603][  T250]  ? vprintk_emit (kernel/printk/printk.c:2478)
[   13.767606][  T250]  vprintk_emit (kernel/printk/printk.c:2479)
[   13.767610][  T250]  ? wake_up_klogd_work_func (kernel/printk/printk.c:4603 (discriminator 126))
[   13.767613][  T250]  ? __lock_acquire (kernel/locking/lockdep.c:5254)
[   13.767616][  T250]  _printk (kernel/printk/printk.c:2504)
[   13.767620][  T250]  ? snapshot_read.cold (user.c:?)
[   13.767623][  T250]  ? do_raw_spin_lock (./include/linux/instrumented.h:55 ./include/linux/atomic/atomic-instrumented.h:1301 ./include/asm-generic/qspinlock.h:112 kernel/locking/spinlock_debug.c:116)
[   13.767626][  T250]  ? __rwlock_init (kernel/locking/spinlock_debug.c:48)
[   13.767629][  T250]  ? do_raw_spin_lock (./include/linux/instrumented.h:55 ./include/linux/atomic/atomic-instrumented.h:1301 ./include/asm-generic/qspinlock.h:112 kernel/locking/spinlock_debug.c:116)
[   13.767632][  T250]  do_exit.cold (kernel/exit.c:889 (discriminator 4) kernel/exit.c:1043 (discriminator 4))
[   13.767636][  T250]  ? exit_notify (kernel/exit.c:780)
[   13.767637][  T250]  ? __lock_release.isra.0 (kernel/locking/lockdep.c:5554)
[   13.767640][  T250]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   13.767644][  T250]  do_group_exit (kernel/exit.c:1151)
[   13.767646][  T250]  __x64_sys_exit_group (kernel/exit.c:1162 kernel/exit.c:1160 kernel/exit.c:1160)
[   13.767648][  T250]  x64_sys_call (./arch/x86/include/generated/asm/syscalls_64.h:232)
[   13.767650][  T250]  do_syscall_64 (arch/x86/entry/syscall_64.c:61 arch/x86/entry/syscall_64.c:84)
[   13.767654][  T250]  ? exc_page_fault (arch/x86/mm/fault.c:1489 (discriminator 3) arch/x86/mm/fault.c:1536 (discriminator 3))
[   13.767657][  T250]  entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:121)
[   13.767660][  T250] RIP: 0033:0x7f589e3e31b8
[   13.767662][  T250] Code: Unable to access opcode bytes at 0x7f589e3e318e.

Code starting with the faulting instruction
===========================================
[   13.767663][  T250] RSP: 002b:00007ffc17da0e58 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7
[   13.767665][  T250] RAX: ffffffffffffffda RBX: 00007f589e513f88 RCX: 00007f589e3e31b8
[   13.767667][  T250] RDX: 00007f589e12dfc8 RSI: fffffffffffffeb8 RDI: 0000000000000000
[   13.767668][  T250] RBP: 00007ffc17da0eb0 R08: 0000000000000000 R09: 0000000000000050
[   13.767668][  T250] R10: 00007ffc17da0c70 R11: 0000000000000246 R12: 0000000000000001
[   13.767669][  T250] R13: 0000000000000000 R14: 00007f589e512680 R15: 00007f589e513fa0
| [   13.897480][    C0]
| [   13.897481][    C0]
| [   13.897481][    C0] stack backtrace:
| [   13.897487][    C0] Tainted: [W]=WARN
[   13.897488][    C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011
[   13.897489][    C0] Call Trace:
[   13.897490][    C0]  <IRQ>
[   13.897491][    C0]  dump_stack_lvl (lib/dump_stack.c:94 lib/dump_stack.c:120)
[   13.897496][    C0]  print_irq_inversion_bug.part.0.cold (kernel/locking/lockdep.c:4141)
[   13.897498][    C0]  mark_lock_irq (kernel/locking/lockdep.c:4095 kernel/locking/lockdep.c:4174 kernel/locking/lockdep.c:4310)
[   13.897500][    C0]  ? add_lock_to_list (./include/linux/find.h:391 kernel/locking/lockdep.c:1419 kernel/locking/lockdep.c:1450)
[   13.897502][    C0]  mark_lock (kernel/locking/lockdep.c:4769)
[   13.897504][    C0]  mark_usage (kernel/locking/lockdep.c:4658)
[   13.897506][    C0]  __lock_acquire (kernel/locking/lockdep.c:5208)
[   13.897508][    C0]  lock_acquire.part.0 (kernel/locking/lockdep.c:5908 (discriminator 2))
[   13.897510][    C0]  ? console_lock_spinning_enable (kernel/printk/printk.c:1902 (discriminator 2))
[   13.897512][    C0]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   13.897514][    C0]  ? lock_acquire (./include/trace/events/lock.h:23 (discriminator 22) kernel/locking/lockdep.c:5871 (discriminator 22))
[   13.897516][    C0]  console_lock_spinning_enable (kernel/printk/printk.c:1902 (discriminator 2))
[   13.897518][    C0]  ? console_lock_spinning_enable (kernel/printk/printk.c:1902 (discriminator 2))
[   13.897520][    C0]  console_emit_next_record (kernel/printk/printk.c:3177)
[   13.897522][    C0]  ? devkmsg_read (./arch/x86/include/asm/atomic64_64.h:20)
[   13.897524][    C0]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   13.897526][    C0]  ? lock_acquire (./include/trace/events/lock.h:23 (discriminator 22) kernel/locking/lockdep.c:5871 (discriminator 22))
[   13.897528][    C0]  console_flush_one_record (kernel/printk/printk.c:3268)
[   13.897530][    C0]  ? console_emit_next_record (kernel/printk/printk.c:3150)
[   13.897532][    C0]  ? __lock_acquire (kernel/locking/lockdep.c:5254)
[   13.897534][    C0]  console_unlock (kernel/printk/printk.c:3350 kernel/printk/printk.c:3380 kernel/printk/printk.c:3420)
[   13.897536][    C0]  ? console_flush_one_record (kernel/printk/printk.c:3316)
[   13.897537][    C0]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   13.897539][    C0]  ? lock_acquire (./include/trace/events/lock.h:23 (discriminator 18) ./include/trace/events/lock.h:23 (discriminator 18) kernel/locking/lockdep.c:5871 (discriminator 18))
[   13.897541][    C0]  ? __down_trylock_console_sem (kernel/printk/printk.c:334)
[   13.897542][    C0]  ? vprintk_emit (kernel/printk/printk.c:2478)
[   13.897544][    C0]  vprintk_emit (kernel/printk/printk.c:2479)
[   13.897547][    C0]  ? wake_up_klogd_work_func (kernel/printk/printk.c:4603 (discriminator 126))
[   13.897549][    C0]  ? clocksource_watchdog.part.0 (kernel/time/clocksource.c:434 kernel/time/clocksource.c:664)
[   13.897551][    C0]  _printk (kernel/printk/printk.c:2504)
[   13.897552][    C0]  ? snapshot_read.cold (user.c:?)
[   13.897554][    C0]  ? clocksource_watchdog.part.0 (kernel/time/clocksource.c:434 kernel/time/clocksource.c:664)
[   13.897556][    C0]  ? __lock_release.isra.0 (kernel/locking/lockdep.c:5554)
[   13.897558][    C0]  clocksource_watchdog.part.0.cold (kernel/time/clocksource.c:589 (discriminator 10) kernel/time/clocksource.c:632 (discriminator 10) kernel/time/clocksource.c:667 (discriminator 10))
[   13.897559][    C0]  ? clocksource_watchdog.part.0 (./include/linux/find.h:67 (discriminator 4))
[   13.897561][    C0]  ? clocksource_watchdog.part.0 (./include/linux/find.h:67 (discriminator 4))
[   13.897563][    C0]  call_timer_fn (kernel/time/timer.c:1748)
[   13.897565][    C0]  ? detach_if_pending (./include/linux/list.h:1159)
[   13.897566][    C0]  ? debug_object_active_state (lib/debugobjects.c:1122)
[   13.897569][    C0]  ? find_held_lock (kernel/locking/lockdep.c:5367)
[   13.897571][    C0]  ? __lock_release.isra.0 (kernel/locking/lockdep.c:5554)
[   13.897573][    C0]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   13.897575][    C0]  __run_timers (kernel/time/timer.c:1799 kernel/time/timer.c:2374)
[   13.897577][    C0]  ? clocksource_watchdog.part.0 (./include/linux/find.h:67 (discriminator 4))
[   13.897579][    C0]  ? __bpf_trace_itimer_expire (./include/trace/events/timer.h:407)
[   13.897581][    C0]  ? __lock_acquire (kernel/locking/lockdep.c:5254)
[   13.897583][    C0]  ? __rwlock_init (kernel/locking/spinlock_debug.c:48)
[   13.897586][    C0]  run_timer_softirq (kernel/time/timer.c:2386 kernel/time/timer.c:2395 kernel/time/timer.c:2403)
[   13.897588][    C0]  ? __run_timers (./include/trace/events/timer.h:138 (discriminator 19))
[   13.897590][    C0]  ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 22))
[   13.897592][    C0]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   13.897594][    C0]  handle_softirqs (kernel/softirq.c:645)
[   13.897596][    C0]  ? __lock_release.isra.0 (kernel/locking/lockdep.c:5554)
[   13.897598][    C0]  ? _local_bh_enable (kernel/softirq.c:428 (discriminator 1))
[   13.897600][    C0]  __irq_exit_rcu (kernel/softirq.c:679 kernel/softirq.c:519 kernel/softirq.c:767)
[   13.897601][    C0]  irq_exit_rcu (kernel/softirq.c:784)
[   13.897603][    C0]  sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1062 (discriminator 37) arch/x86/kernel/apic/apic.c:1062 (discriminator 37))
[   13.897605][    C0]  </IRQ>
[   13.897605][    C0]  <TASK>
[   13.897606][    C0]  ? _raw_spin_unlock_irqrestore (./include/linux/spinlock_api_smp.h:211 (discriminator 1) kernel/locking/spinlock.c:221 (discriminator 1))
[   13.897608][    C0]  asm_sysvec_apic_timer_interrupt (./arch/x86/include/asm/idtentry.h:674)
[   13.897609][    C0] RIP: 0010:pv_native_safe_halt (./arch/x86/include/asm/irqflags.h:48 arch/x86/kernel/paravirt.c:63)
[   13.897612][    C0] Code: 48 8b 3d 94 82 66 02 e8 1f 00 00 00 48 2b 05 58 83 a5 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 f6 19 00 fb f4 <c3> 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 94 82 66 02 	mov    0x2668294(%rip),%rdi        # 0x266829b
   7:	e8 1f 00 00 00       	call   0x2b
   c:	48 2b 05 58 83 a5 00 	sub    0xa58358(%rip),%rax        # 0xa5836b
  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 13 f6 19 00 	verw   0x19f613(%rip)        # 0x19f63b
  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
[   13.897614][    C0] RSP: 0018:ffffffff97c07cf8 EFLAGS: 00000296
[   13.897616][    C0] RAX: 000000000006f0f5 RBX: ffffffff97c1c600 RCX: ffffffff944f73a7
[   13.897617][    C0] RDX: ffffffff97c1c600 RSI: ffffffff974ad54f RDI: ffffffff96e913e0
[   13.897618][    C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[   13.897619][    C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff2f80fa2
[   13.897619][    C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770
[   13.897621][    C0]  ? cpuidle_idle_call.constprop.0 (kernel/sched/idle.c:199)
[   13.897623][    C0]  ? lockdep_hardirqs_on (kernel/locking/lockdep.c:4488)
[   13.897625][    C0]  default_idle (./arch/x86/include/asm/paravirt.h:62 arch/x86/kernel/process.c:768)
[   13.897627][    C0]  default_idle_call (kernel/sched/idle.c:122)
[   13.897628][    C0]  cpuidle_idle_call.constprop.0 (kernel/sched/idle.c:199)
[   13.897630][    C0]  ? arch_cpu_idle_exit+0x40/0x40
[   13.897632][    C0]  ? mark_tsc_async_resets (arch/x86/kernel/tsc_sync.c:49)
[   13.897634][    C0]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   13.897636][    C0]  do_idle (kernel/sched/idle.c:355)
[   13.897637][    C0]  cpu_startup_entry (kernel/sched/idle.c:454)
[   13.897639][    C0]  rest_init (init/main.c:727)
[   13.897640][    C0]  ? __cpuidle_text_end (??:?)
[   13.897642][    C0]  ? rest_init (init/main.c:727)
[   13.897643][    C0]  ? acpi_hw_set_mode (drivers/acpi/acpica/hwacpi.c:72 (discriminator 1))
[   13.897647][    C0]  ? cpus_read_unlock (./include/linux/percpu-rwsem.h:125 (discriminator 1) kernel/cpu.c:502 (discriminator 1))
[   13.897649][    C0]  ? acpi_enable (drivers/acpi/acpica/evxfevnt.c:47)
[   13.897652][    C0]  start_kernel (init/main.c:1185)
[   13.897653][    C0]  x86_64_start_reservations (arch/x86/kernel/head64.c:310)
[   13.897655][    C0]  x86_64_start_kernel (arch/x86/kernel/head64.c:291)


Finger prints:
mark_lock_irq:mark_lock:mark_usage:__lock_acquire:console_lock_spinning_enable
__irq_exit_rcu:dump_stack_lvl:__kmalloc_noprof:put_chars:hvc_console_print