======================================
| [   11.910028][  T256] softirqs last disabled at (28781): __irq_exit_rcu (kernel/softirq.c:679 kernel/softirq.c:519 kernel/softirq.c:767)
| [   11.910030][  T256] Preemption disabled at:
| [   11.910031][  T256] vprintk_emit (kernel/printk/printk.c:2471)
| [   11.910040][  T256] Tainted: [W]=WARN
[   11.910041][  T256] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011
[   11.910043][  T256] Call Trace:
[   11.910044][  T256]  <TASK>
[   11.910046][  T256]  dump_stack_lvl (lib/dump_stack.c:94 lib/dump_stack.c:120)
[   11.910051][  T256]  ? vprintk_emit (kernel/printk/printk.c:2471)
[   11.910054][  T256]  __might_resched.cold (kernel/sched/core.c:9234)
[   11.910058][  T256]  ? perf_trace_sched_switch (./include/linux/list.h:1037 (discriminator 3))
[   11.910062][  T256]  ? __kmalloc_noprof (./include/linux/sched/mm.h:317 mm/slub.c:4636 mm/slub.c:4974 mm/slub.c:5413 mm/slub.c:5439)
[   11.910067][  T256]  __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))
[   11.910069][  T256]  ? alloc_buf.isra.0 (./include/linux/slab.h:995 drivers/char/virtio_console.c:429)
[   11.910075][  T256]  ? 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)
[   11.910078][  T256]  alloc_buf.isra.0 (./include/linux/slab.h:995 drivers/char/virtio_console.c:429)
[   11.910081][  T256]  put_chars (drivers/char/virtio_console.c:1176)
[   11.910084][  T256]  ? __send_to_port (drivers/char/virtio_console.c:654)
[   11.910085][  T256]  ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4116 (discriminator 3) kernel/rcu/tree.c:4108 (discriminator 3))
[   11.910090][  T256]  ? rcu_read_lock_any_held (./include/linux/lockdep.h:249 kernel/rcu/update.c:386 kernel/rcu/update.c:380)
[   11.910092][  T256]  ? validate_chain (kernel/locking/lockdep.c:3817 (discriminator 2) kernel/locking/lockdep.c:3837 (discriminator 2) kernel/locking/lockdep.c:3892 (discriminator 2))
[   11.910096][  T256]  hvc_console_print (drivers/tty/hvc/hvc_console.c:177 (discriminator 2))
[   11.910099][  T256]  ? __lock_acquire (kernel/locking/lockdep.c:5254)
[   11.910104][  T256]  ? hvc_write (drivers/tty/hvc/hvc_console.c:501)
[   11.910106][  T256]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   11.910111][  T256]  console_emit_next_record (kernel/printk/printk.c:3183)
[   11.910115][  T256]  ? devkmsg_read (./arch/x86/include/asm/atomic64_64.h:20)
[   11.910117][  T256]  ? console_flush_one_record (./include/linux/rcupdate.h:314 (discriminator 2) ./include/linux/srcu.h:439 (discriminator 2) kernel/printk/printk.c:291 (discriminator 2) kernel/printk/printk.c:3246 (discriminator 2))
[   11.910120][  T256]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   11.910122][  T256]  ? lock_acquire (./include/trace/events/lock.h:23 (discriminator 22) kernel/locking/lockdep.c:5871 (discriminator 22))
[   11.910126][  T256]  console_flush_one_record (kernel/printk/printk.c:3268)
[   11.910130][  T256]  ? console_emit_next_record (kernel/printk/printk.c:3150)
[   11.910132][  T256]  ? __lock_acquire (kernel/locking/lockdep.c:5254)
[   11.910136][  T256]  console_unlock (kernel/printk/printk.c:3350 kernel/printk/printk.c:3380 kernel/printk/printk.c:3420)
[   11.910139][  T256]  ? console_flush_one_record (kernel/printk/printk.c:3316)
[   11.910141][  T256]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   11.910143][  T256]  ? lock_acquire (kernel/locking/lockdep.c:5913)
[   11.910147][  T256]  ? __down_trylock_console_sem (kernel/printk/printk.c:334)
[   11.910148][  T256]  ? vprintk_emit (kernel/printk/printk.c:2478)
[   11.910152][  T256]  vprintk_emit (kernel/printk/printk.c:2479)
[   11.910156][  T256]  ? wake_up_klogd_work_func (kernel/printk/printk.c:4603 (discriminator 126))
[   11.910158][  T256]  ? netdev_run_todo (net/core/dev.c:11776)
[   11.910163][  T256]  ? mirred_device_event (net/sched/act_mirred.c:559 (discriminator 1)) act_mirred
[   11.910165][  T256]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   11.910169][  T256]  ? mirred_cleanup_module (./include/net/netlink.h:1107 (discriminator 1) net/sched/act_mirred.c:549 (discriminator 1)) act_mirred
[   11.910172][  T256]  _printk (kernel/printk/printk.c:2504)
[   11.910175][  T256]  ? snapshot_read.cold (user.c:?)
[   11.910177][  T256]  ? __lock_release.isra.0 (kernel/locking/lockdep.c:5554)
[   11.910180][  T256]  ? mirred_cleanup_module (./include/net/netlink.h:1107 (discriminator 1) net/sched/act_mirred.c:549 (discriminator 1)) act_mirred
[   11.910182][  T256]  ? __up_write (kernel/locking/rwsem.c:1415)
[   11.910183][  T256]  ? __up_write (kernel/locking/rwsem.c:1422 (discriminator 1))
[   11.910187][  T256]  ? __up_write (./include/linux/instrumented.h:112 ./include/linux/atomic/atomic-instrumented.h:3390 kernel/locking/rwsem.c:1417)
[   11.910189][  T256] mirred_init_module (net/sched/act_mirred.c:508) act_mirred
[   11.910191][  T256]  do_one_initcall (init/main.c:1357)
[   11.910195][  T256]  ? trace_event_raw_event_initcall_level (./include/trace/events/initcall.h:10)
[   11.910199][  T256]  ? do_init_module (./include/linux/slab.h:991 (discriminator 1) kernel/module/main.c:3105 (discriminator 1))
[   11.910202][  T256]  ? kasan_unpoison (mm/kasan/shadow.c:146 mm/kasan/shadow.c:178)
[   11.910207][  T256]  do_init_module (kernel/module/main.c:3117)
[   11.910211][  T256]  ? lockdep_hardirqs_on (kernel/locking/lockdep.c:4488)
[   11.910215][  T256]  ? free_module (./include/trace/events/module.h:50 (discriminator 19))
[   11.910218][  T256]  ? kfree (./include/linux/kasan.h:235 mm/slub.c:2748 mm/slub.c:6499 mm/slub.c:6792)
[   11.910222][  T256]  load_module (kernel/module/main.c:3593)
[   11.910229][  T256]  ? layout_and_allocate.isra.0 (kernel/module/main.c:3016)
[   11.910233][  T256]  ? __ia32_sys_fsconfig (??:?)
[   11.910236][  T256]  ? ovl_real_file (fs/overlayfs/file.c:195)
[   11.910240][  T256]  ? backing_file_read_iter (fs/backing-file.c:211)
[   11.910243][  T256]  ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4116 (discriminator 3) kernel/rcu/tree.c:4108 (discriminator 3))
[   11.910246][  T256]  init_module_from_file (kernel/module/main.c:3790)
[   11.910249][  T256]  ? __do_sys_init_module (kernel/module/main.c:3664)
[   11.910257][  T256]  ? __rwlock_init (kernel/locking/spinlock_debug.c:48)
[   11.910261][  T256]  ? 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)
[   11.910264][  T256]  idempotent_init_module (kernel/module/main.c:3802)
[   11.910267][  T256]  ? init_module_from_file (kernel/module/main.c:3791)
[   11.910273][  T256]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   11.910276][  T256]  ? cap_capable (./include/trace/events/capability.h:26 (discriminator 22) security/commoncap.c:130 (discriminator 22))
[   11.910281][  T256]  __x64_sys_finit_module (kernel/module/main.c:3828 kernel/module/main.c:3812 kernel/module/main.c:3812)
[   11.910283][  T256]  ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 22))
[   11.910287][  T256]  do_syscall_64 (arch/x86/entry/syscall_64.c:61 arch/x86/entry/syscall_64.c:84)
[   11.910289][  T256]  ? exc_page_fault (arch/x86/mm/fault.c:1489 (discriminator 3) arch/x86/mm/fault.c:1536 (discriminator 3))
[   11.910291][  T256]  entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:121)
[   11.910294][  T256] RIP: 0033:0x7fc5e5c6637d
[   11.910297][  T256] Code: ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 4b aa 0f 00 f7 d8 64 89 01 48
All code
========
   0:	ff c3                	inc    %ebx
   2:	66 2e 0f 1f 84 00 00 	cs nopw 0x0(%rax,%rax,1)
   9:	00 00 00 
   c:	90                   	nop
   d:	f3 0f 1e fa          	endbr64
  11:	48 89 f8             	mov    %rdi,%rax
  14:	48 89 f7             	mov    %rsi,%rdi
  17:	48 89 d6             	mov    %rdx,%rsi
  1a:	48 89 ca             	mov    %rcx,%rdx
  1d:	4d 89 c2             	mov    %r8,%r10
  20:	4d 89 c8             	mov    %r9,%r8
  23:	4c 8b 4c 24 08       	mov    0x8(%rsp),%r9
  28:	0f 05                	syscall
  2a:*	48 3d 01 f0 ff ff    	cmp    $0xfffffffffffff001,%rax		<-- trapping instruction
  30:	73 01                	jae    0x33
  32:	c3                   	ret
  33:	48 8b 0d 4b aa 0f 00 	mov    0xfaa4b(%rip),%rcx        # 0xfaa85
  3a:	f7 d8                	neg    %eax
  3c:	64 89 01             	mov    %eax,%fs:(%rcx)
  3f:	48                   	rex.W

Code starting with the faulting instruction
===========================================
   0:	48 3d 01 f0 ff ff    	cmp    $0xfffffffffffff001,%rax
   6:	73 01                	jae    0x9
   8:	c3                   	ret
   9:	48 8b 0d 4b aa 0f 00 	mov    0xfaa4b(%rip),%rcx        # 0xfaa5b
  10:	f7 d8                	neg    %eax
  12:	64 89 01             	mov    %eax,%fs:(%rcx)
  15:	48                   	rex.W
[   11.910298][  T256] RSP: 002b:00007fff70a73808 EFLAGS: 00000246 ORIG_RAX: 0000000000000139
[   11.910301][  T256] RAX: ffffffffffffffda RBX: 000055fff65d5810 RCX: 00007fc5e5c6637d
[   11.910302][  T256] RDX: 0000000000000000 RSI: 000055ffe9abbffd RDI: 0000000000000000
[   11.910303][  T256] RBP: 00007fff70a738a0 R08: 0000000000000000 R09: 0000000000000000
[   11.910304][  T256] R10: 0000000000000000 R11: 0000000000000246 R12: 000055ffe9abbffd
[   11.910305][  T256] R13: 000055fff65d5960 R14: 0000000000040000 R15: 0000000000000000
| [   11.915265][    C0]
| [   11.915265][    C0]
| [   11.915265][    C0] stack backtrace:
| [   11.915270][    C0] Tainted: [W]=WARN
[   11.915271][    C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011
[   11.915271][    C0] Call Trace:
[   11.915272][    C0]  <IRQ>
[   11.915273][    C0]  dump_stack_lvl (lib/dump_stack.c:94 lib/dump_stack.c:120)
[   11.915276][    C0]  print_irq_inversion_bug.part.0.cold (kernel/locking/lockdep.c:4141)
[   11.915279][    C0]  mark_lock_irq (kernel/locking/lockdep.c:4095 kernel/locking/lockdep.c:4174 kernel/locking/lockdep.c:4310)
[   11.915280][    C0]  ? add_lock_to_list (./include/linux/find.h:391 kernel/locking/lockdep.c:1419 kernel/locking/lockdep.c:1450)
[   11.915283][    C0]  mark_lock (kernel/locking/lockdep.c:4769)
[   11.915285][    C0]  mark_usage (kernel/locking/lockdep.c:4658)
[   11.915286][    C0]  __lock_acquire (kernel/locking/lockdep.c:5208)
[   11.915289][    C0]  lock_acquire.part.0 (kernel/locking/lockdep.c:5908 (discriminator 2))
[   11.915290][    C0]  ? console_trylock_spinning (kernel/printk/printk.c:2026 (discriminator 2))
[   11.915292][    C0]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   11.915295][    C0]  ? lock_acquire (./include/trace/events/lock.h:23 (discriminator 22) kernel/locking/lockdep.c:5871 (discriminator 22))
[   11.915296][    C0]  console_trylock_spinning (kernel/printk/printk.c:2026 (discriminator 2))
[   11.915298][    C0]  ? console_trylock_spinning (kernel/printk/printk.c:2026 (discriminator 2))
[   11.915300][    C0]  vprintk_emit (kernel/printk/printk.c:2478)
[   11.915302][    C0]  ? wake_up_klogd_work_func (kernel/printk/printk.c:4603 (discriminator 126))
[   11.915304][    C0]  ? clocksource_watchdog.part.0 (kernel/time/clocksource.c:531 (discriminator 1) kernel/time/clocksource.c:608 (discriminator 1) kernel/time/clocksource.c:667 (discriminator 1))
[   11.915306][    C0]  _printk (kernel/printk/printk.c:2504)
[   11.915307][    C0]  ? snapshot_read.cold (user.c:?)
[   11.915309][    C0]  ? clocksource_watchdog.part.0 (kernel/time/clocksource.c:531 (discriminator 1) kernel/time/clocksource.c:608 (discriminator 1) kernel/time/clocksource.c:667 (discriminator 1))
[   11.915311][    C0]  ? __lock_release.isra.0 (kernel/locking/lockdep.c:5554)
[   11.915313][    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))
[   11.915315][    C0]  ? clocksource_watchdog.part.0 (./include/linux/find.h:67 (discriminator 4))
[   11.915316][    C0]  ? clocksource_watchdog.part.0 (./include/linux/find.h:67 (discriminator 4))
[   11.915318][    C0]  call_timer_fn (kernel/time/timer.c:1748)
[   11.915319][    C0]  ? detach_if_pending (./include/linux/list.h:1159)
[   11.915321][    C0]  ? debug_object_active_state (lib/debugobjects.c:1122)
[   11.915324][    C0]  ? find_held_lock (kernel/locking/lockdep.c:5367)
[   11.915326][    C0]  ? __lock_release.isra.0 (kernel/locking/lockdep.c:5554)
[   11.915328][    C0]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   11.915330][    C0]  __run_timers (kernel/time/timer.c:1799 kernel/time/timer.c:2374)
[   11.915331][    C0]  ? clocksource_watchdog.part.0 (./include/linux/find.h:67 (discriminator 4))
[   11.915333][    C0]  ? __bpf_trace_itimer_expire (./include/trace/events/timer.h:407)
[   11.915335][    C0]  ? __lock_acquire (kernel/locking/lockdep.c:5254)
[   11.915338][    C0]  ? __rwlock_init (kernel/locking/spinlock_debug.c:48)
[   11.915340][    C0]  run_timer_softirq (kernel/time/timer.c:2386 kernel/time/timer.c:2395 kernel/time/timer.c:2403)
[   11.915342][    C0]  ? __run_timers (./include/trace/events/timer.h:138 (discriminator 19))
[   11.915343][    C0]  ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 22))
[   11.915345][    C0]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   11.915347][    C0]  handle_softirqs (kernel/softirq.c:645)
[   11.915349][    C0]  ? __lock_release.isra.0 (kernel/locking/lockdep.c:5554)
[   11.915350][    C0]  ? _local_bh_enable (kernel/softirq.c:428 (discriminator 1))
[   11.915352][    C0]  __irq_exit_rcu (kernel/softirq.c:679 kernel/softirq.c:519 kernel/softirq.c:767)
[   11.915353][    C0]  irq_exit_rcu (kernel/softirq.c:784)
[   11.915355][    C0]  sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1062 (discriminator 37) arch/x86/kernel/apic/apic.c:1062 (discriminator 37))
[   11.915357][    C0]  </IRQ>
[   11.915357][    C0]  <TASK>
[   11.915358][    C0]  ? lockdep_hardirqs_on_prepare.part.0 (kernel/locking/lockdep.c:470 (discriminator 2) kernel/locking/lockdep.c:4427 (discriminator 2))
[   11.915359][    C0]  asm_sysvec_apic_timer_interrupt (./arch/x86/include/asm/idtentry.h:674)
[   11.915361][    C0] RIP: 0010:pv_native_safe_halt (./arch/x86/include/asm/irqflags.h:48 arch/x86/kernel/paravirt.c:63)
[   11.915363][    C0] Code: 48 8b 3d 94 e2 f5 01 e8 1f 00 00 00 48 2b 05 58 a3 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 96 11 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 e2 f5 01 	mov    0x1f5e294(%rip),%rdi        # 0x1f5e29b
   7:	e8 1f 00 00 00       	call   0x2b
   c:	48 2b 05 58 a3 95 00 	sub    0x95a358(%rip),%rax        # 0x95a36b
  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 96 11 00 	verw   0x119613(%rip)        # 0x11963b
  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
[   11.915364][    C0] RSP: 0018:ffffffff94207cf8 EFLAGS: 00000296
[   11.915366][    C0] RAX: 0000000000073ca7 RBX: ffffffff9421c600 RCX: ffffffff912f0307
[   11.915367][    C0] RDX: ffffffff9421c600 RSI: ffffffff94038b01 RDI: ffffffff93a8d8e0
[   11.915368][    C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[   11.915369][    C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff2840fa2
[   11.915370][    C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770
[   11.915371][    C0]  ? cpuidle_idle_call.constprop.0 (kernel/sched/idle.c:199)
[   11.915373][    C0]  ? lockdep_hardirqs_on (kernel/locking/lockdep.c:4488)
[   11.915375][    C0]  default_idle (./arch/x86/include/asm/paravirt.h:62 arch/x86/kernel/process.c:768)
[   11.915377][    C0]  default_idle_call (kernel/sched/idle.c:122)
[   11.915378][    C0]  cpuidle_idle_call.constprop.0 (kernel/sched/idle.c:199)
[   11.915380][    C0]  ? arch_cpu_idle_exit+0x40/0x40
[   11.915381][    C0]  ? mark_tsc_async_resets (arch/x86/kernel/tsc_sync.c:49)
[   11.915384][    C0]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   11.915385][    C0]  do_idle (kernel/sched/idle.c:355)
[   11.915387][    C0]  cpu_startup_entry (kernel/sched/idle.c:454)
[   11.915388][    C0]  rest_init (init/main.c:727)
[   11.915390][    C0]  ? __cpuidle_text_end (??:?)
[   11.915393][    C0]  ? rest_init (init/main.c:727)
[   11.915395][    C0]  ? acpi_hw_set_mode (drivers/acpi/acpica/hwacpi.c:72 (discriminator 1))
[   11.915398][    C0]  ? cpus_read_unlock (./include/linux/percpu-rwsem.h:125 (discriminator 1) kernel/cpu.c:502 (discriminator 1))
[   11.915400][    C0]  ? acpi_enable (drivers/acpi/acpica/evxfevnt.c:47)
[   11.915403][    C0]  start_kernel (init/main.c:1185)
[   11.915404][    C0]  x86_64_start_reservations (arch/x86/kernel/head64.c:310)
[   11.915406][    C0]  x86_64_start_kernel (arch/x86/kernel/head64.c:291)


Finger prints:
mark_lock_irq:mark_lock:mark_usage:__lock_acquire:console_trylock_spinning
__irq_exit_rcu:vprintk_emit:dump_stack_lvl:__kmalloc_noprof:put_chars