======================================
| [   40.993699][    C0]
| [   40.993700][    C0]
| [   40.993700][    C0] stack backtrace:
| [   40.993706][    C0] Tainted: [W]=WARN
[   40.993707][    C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011
[   40.993709][    C0] Call Trace:
[   40.993710][    C0]  <TASK>
[   40.993711][    C0]  dump_stack_lvl (lib/dump_stack.c:94 lib/dump_stack.c:120)
[   40.993715][    C0]  print_irq_inversion_bug.part.0.cold (kernel/locking/lockdep.c:4141)
[   40.993718][    C0]  mark_lock_irq (kernel/locking/lockdep.c:4095 kernel/locking/lockdep.c:4174 kernel/locking/lockdep.c:4310)
[   40.993720][    C0]  mark_lock (kernel/locking/lockdep.c:4769)
[   40.993722][    C0]  mark_usage (kernel/locking/lockdep.c:4658)
[   40.993724][    C0]  __lock_acquire (kernel/locking/lockdep.c:5208)
[   40.993726][    C0]  lock_acquire.part.0 (kernel/locking/lockdep.c:5908 (discriminator 2))
[   40.993728][    C0]  ? console_lock_spinning_enable (kernel/printk/printk.c:1902 (discriminator 2))
[   40.993730][    C0]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   40.993733][    C0]  ? lock_acquire (./include/trace/events/lock.h:23 (discriminator 22) kernel/locking/lockdep.c:5871 (discriminator 22))
[   40.993735][    C0]  console_lock_spinning_enable (kernel/printk/printk.c:1902 (discriminator 2))
[   40.993736][    C0]  ? console_lock_spinning_enable (kernel/printk/printk.c:1902 (discriminator 2))
[   40.993738][    C0]  console_emit_next_record (kernel/printk/printk.c:3177)
[   40.993740][    C0]  ? devkmsg_read (./arch/x86/include/asm/atomic64_64.h:20)
[   40.993742][    C0]  ? 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))
[   40.993744][    C0]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   40.993746][    C0]  ? lock_acquire (./include/trace/events/lock.h:23 (discriminator 22) kernel/locking/lockdep.c:5871 (discriminator 22))
[   40.993747][    C0]  console_flush_one_record (kernel/printk/printk.c:3268)
[   40.993750][    C0]  ? console_emit_next_record (kernel/printk/printk.c:3150)
[   40.993751][    C0]  ? __lock_acquire (kernel/locking/lockdep.c:5254)
[   40.993753][    C0]  console_unlock (kernel/printk/printk.c:3350 kernel/printk/printk.c:3380 kernel/printk/printk.c:3420)
[   40.993755][    C0]  ? console_flush_one_record (kernel/printk/printk.c:3316)
[   40.993757][    C0]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   40.993759][    C0]  ? lock_acquire (kernel/locking/lockdep.c:5913)
[   40.993761][    C0]  ? __down_trylock_console_sem (kernel/printk/printk.c:334)
[   40.993762][    C0]  ? vprintk_emit (kernel/printk/printk.c:2478)
[   40.993764][    C0]  vprintk_emit (kernel/printk/printk.c:2479)
[   40.993766][    C0]  ? wake_up_klogd_work_func (kernel/printk/printk.c:4603 (discriminator 126))
[   40.993768][    C0]  ? rcu_read_lock_any_held (./include/linux/lockdep.h:249 kernel/rcu/update.c:386 kernel/rcu/update.c:380)
[   40.993770][    C0]  ? validate_chain (kernel/locking/lockdep.c:3817 (discriminator 2) kernel/locking/lockdep.c:3837 (discriminator 2) kernel/locking/lockdep.c:3892 (discriminator 2))
[   40.993772][    C0]  ? validate_chain (kernel/locking/lockdep.c:3817 (discriminator 2) kernel/locking/lockdep.c:3837 (discriminator 2) kernel/locking/lockdep.c:3892 (discriminator 2))
[   40.993773][    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))
[   40.993775][    C0]  _printk (kernel/printk/printk.c:2504)
[   40.993777][    C0]  ? snapshot_read.cold (user.c:?)
[   40.993779][    C0]  ? lockdep_hardirqs_on (kernel/locking/lockdep.c:4488)
[   40.993781][    C0]  ? watchdog_check_freq (kernel/time/clocksource.c:493)
[   40.993784][    C0]  clocksource_watchdog.part.0.cold (kernel/time/clocksource.c:582 (discriminator 1) kernel/time/clocksource.c:627 (discriminator 1) kernel/time/clocksource.c:667 (discriminator 1))
[   40.993785][    C0]  ? clocksource_watchdog.part.0 (./include/linux/find.h:67 (discriminator 4))
[   40.993787][    C0]  ? clocksource_watchdog.part.0 (./include/linux/find.h:67 (discriminator 4))
[   40.993788][    C0]  call_timer_fn (kernel/time/timer.c:1748)
[   40.993790][    C0]  ? detach_if_pending (./include/linux/list.h:1159)
[   40.993792][    C0]  ? find_held_lock (kernel/locking/lockdep.c:5367)
[   40.993794][    C0]  ? __lock_release.isra.0 (kernel/locking/lockdep.c:5554)
[   40.993796][    C0]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   40.993798][    C0]  __run_timers (kernel/time/timer.c:1799 kernel/time/timer.c:2374)
[   40.993800][    C0]  ? clocksource_watchdog.part.0 (./include/linux/find.h:67 (discriminator 4))
[   40.993802][    C0]  ? __bpf_trace_itimer_expire (./include/trace/events/timer.h:407)
[   40.993804][    C0]  ? __lock_acquire (kernel/locking/lockdep.c:5254)
[   40.993806][    C0]  ? __rwlock_init (kernel/locking/spinlock_debug.c:48)
[   40.993809][    C0]  run_timer_softirq (kernel/time/timer.c:2386 kernel/time/timer.c:2395 kernel/time/timer.c:2403)
[   40.993811][    C0]  ? __run_timers (./include/trace/events/timer.h:138 (discriminator 19))
[   40.993812][    C0]  ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 22))
[   40.993815][    C0]  ? trace_irq_disable.constprop.0 (./include/trace/events/preemptirq.h:36 (discriminator 22))
[   40.993816][    C0]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   40.993818][    C0]  handle_softirqs (kernel/softirq.c:645)
[   40.993820][    C0]  ? _local_bh_enable (kernel/softirq.c:428 (discriminator 1))
[   40.993822][    C0]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   40.993824][    C0]  run_ksoftirqd (kernel/softirq.c:1108 kernel/softirq.c:1100)
[   40.993825][    C0]  smpboot_thread_fn (kernel/smpboot.c:160)
[   40.993827][    C0]  ? sort_range (kernel/range.c:164)
[   40.993829][    C0]  kthread (kernel/kthread.c:436)
[   40.993830][    C0]  ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 22))
[   40.993831][    C0]  ? kthread_affine_preferred (kernel/kthread.c:857)
[   40.993833][    C0]  ret_from_fork (arch/x86/kernel/process.c:158)
[   40.993835][    C0]  ? arch_exit_to_user_mode_prepare.isra.0 (arch/x86/entry/vdso/vma.c:80 (discriminator 1))
[   40.993837][    C0]  ? __switch_to (arch/x86/kernel/process_64.c:403 arch/x86/kernel/process_64.c:663)
[   40.993839][    C0]  ? kthread_affine_preferred (kernel/kthread.c:857)
[   40.993841][    C0]  ret_from_fork_asm (arch/x86/entry/entry_64.S:245)
| [   40.993864][    C0] softirqs last disabled at (1166417): run_ksoftirqd (kernel/softirq.c:1108 kernel/softirq.c:1100)
| [   40.993865][    C0] Preemption disabled at:
| [   40.993866][    C0] handle_softirqs (kernel/softirq.c:486 (discriminator 1) kernel/softirq.c:621 (discriminator 1))
| [   40.993871][    C0] Tainted: [W]=WARN
[   40.993871][    C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011
[   40.993872][    C0] Call Trace:
[   40.993872][    C0]  <TASK>
[   40.993873][    C0]  dump_stack_lvl (lib/dump_stack.c:94 lib/dump_stack.c:120)
[   40.993875][    C0]  ? handle_softirqs (kernel/softirq.c:486 (discriminator 1) kernel/softirq.c:621 (discriminator 1))
[   40.993876][    C0]  __might_resched.cold (kernel/sched/core.c:9234)
[   40.993879][    C0]  ? perf_trace_sched_switch (./include/linux/list.h:1037 (discriminator 3))
[   40.993882][    C0]  ? nbcon_get_cpu_emergency_nesting (kernel/printk/nbcon.c:1424)
[   40.993884][    C0]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   40.993886][    C0]  __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))
[   40.993888][    C0]  ? __rwlock_init (kernel/locking/spinlock_debug.c:48)
[   40.993889][    C0]  ? alloc_buf.isra.0 (./include/linux/slab.h:995 drivers/char/virtio_console.c:429)
[   40.993891][    C0]  ? 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)
[   40.993892][    C0]  alloc_buf.isra.0 (./include/linux/slab.h:995 drivers/char/virtio_console.c:429)
[   40.993894][    C0]  put_chars (drivers/char/virtio_console.c:1176)
[   40.993896][    C0]  ? __send_to_port (drivers/char/virtio_console.c:654)
[   40.993897][    C0]  ? mark_lock (kernel/locking/lockdep.c:4769)
[   40.993899][    C0]  hvc_console_print (drivers/tty/hvc/hvc_console.c:177 (discriminator 2))
[   40.993902][    C0]  ? hvc_write (drivers/tty/hvc/hvc_console.c:501)
[   40.993903][    C0]  ? lock_acquire (./include/trace/events/lock.h:23 (discriminator 22) kernel/locking/lockdep.c:5871 (discriminator 22))
[   40.993905][    C0]  console_emit_next_record (kernel/printk/printk.c:3183)
[   40.993907][    C0]  ? devkmsg_read (./arch/x86/include/asm/atomic64_64.h:20)
[   40.993909][    C0]  ? 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))
[   40.993911][    C0]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   40.993912][    C0]  ? lock_acquire (./include/trace/events/lock.h:23 (discriminator 22) kernel/locking/lockdep.c:5871 (discriminator 22))
[   40.993914][    C0]  console_flush_one_record (kernel/printk/printk.c:3268)
[   40.993916][    C0]  ? console_emit_next_record (kernel/printk/printk.c:3150)
[   40.993918][    C0]  ? __lock_acquire (kernel/locking/lockdep.c:5254)
[   40.993920][    C0]  console_unlock (kernel/printk/printk.c:3350 kernel/printk/printk.c:3380 kernel/printk/printk.c:3420)
[   40.993922][    C0]  ? console_flush_one_record (kernel/printk/printk.c:3316)
[   40.993924][    C0]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   40.993925][    C0]  ? lock_acquire (kernel/locking/lockdep.c:5913)
[   40.993927][    C0]  ? __down_trylock_console_sem (kernel/printk/printk.c:334)
[   40.993929][    C0]  ? vprintk_emit (kernel/printk/printk.c:2478)
[   40.993930][    C0]  vprintk_emit (kernel/printk/printk.c:2479)
[   40.993933][    C0]  ? wake_up_klogd_work_func (kernel/printk/printk.c:4603 (discriminator 126))
[   40.993934][    C0]  ? rcu_read_lock_any_held (./include/linux/lockdep.h:249 kernel/rcu/update.c:386 kernel/rcu/update.c:380)
[   40.993936][    C0]  ? validate_chain (kernel/locking/lockdep.c:3817 (discriminator 2) kernel/locking/lockdep.c:3837 (discriminator 2) kernel/locking/lockdep.c:3892 (discriminator 2))
[   40.993937][    C0]  ? validate_chain (kernel/locking/lockdep.c:3817 (discriminator 2) kernel/locking/lockdep.c:3837 (discriminator 2) kernel/locking/lockdep.c:3892 (discriminator 2))
[   40.993939][    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))
[   40.993940][    C0]  _printk (kernel/printk/printk.c:2504)
[   40.993942][    C0]  ? snapshot_read.cold (user.c:?)
[   40.993944][    C0]  ? lockdep_hardirqs_on (kernel/locking/lockdep.c:4488)
[   40.993945][    C0]  ? watchdog_check_freq (kernel/time/clocksource.c:493)
[   40.993948][    C0]  clocksource_watchdog.part.0.cold (kernel/time/clocksource.c:582 (discriminator 1) kernel/time/clocksource.c:627 (discriminator 1) kernel/time/clocksource.c:667 (discriminator 1))
[   40.993949][    C0]  ? clocksource_watchdog.part.0 (./include/linux/find.h:67 (discriminator 4))
[   40.993951][    C0]  ? clocksource_watchdog.part.0 (./include/linux/find.h:67 (discriminator 4))
[   40.993952][    C0]  call_timer_fn (kernel/time/timer.c:1748)
[   40.993954][    C0]  ? detach_if_pending (./include/linux/list.h:1159)
[   40.993956][    C0]  ? find_held_lock (kernel/locking/lockdep.c:5367)
[   40.993957][    C0]  ? __lock_release.isra.0 (kernel/locking/lockdep.c:5554)
[   40.993959][    C0]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   40.993961][    C0]  __run_timers (kernel/time/timer.c:1799 kernel/time/timer.c:2374)
[   40.993963][    C0]  ? clocksource_watchdog.part.0 (./include/linux/find.h:67 (discriminator 4))
[   40.993965][    C0]  ? __bpf_trace_itimer_expire (./include/trace/events/timer.h:407)
[   40.993967][    C0]  ? __lock_acquire (kernel/locking/lockdep.c:5254)
[   40.993969][    C0]  ? __rwlock_init (kernel/locking/spinlock_debug.c:48)
[   40.993971][    C0]  run_timer_softirq (kernel/time/timer.c:2386 kernel/time/timer.c:2395 kernel/time/timer.c:2403)
[   40.993973][    C0]  ? __run_timers (./include/trace/events/timer.h:138 (discriminator 19))
[   40.993975][    C0]  ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 22))
[   40.993976][    C0]  ? trace_irq_disable.constprop.0 (./include/trace/events/preemptirq.h:36 (discriminator 22))
[   40.993977][    C0]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   40.993979][    C0]  handle_softirqs (kernel/softirq.c:645)
[   40.993981][    C0]  ? _local_bh_enable (kernel/softirq.c:428 (discriminator 1))
[   40.993982][    C0]  ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3))
[   40.993984][    C0]  run_ksoftirqd (kernel/softirq.c:1108 kernel/softirq.c:1100)
[   40.993986][    C0]  smpboot_thread_fn (kernel/smpboot.c:160)
[   40.993988][    C0]  ? sort_range (kernel/range.c:164)
[   40.993989][    C0]  kthread (kernel/kthread.c:436)
[   40.993991][    C0]  ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 22))
[   40.993992][    C0]  ? kthread_affine_preferred (kernel/kthread.c:857)
[   40.993993][    C0]  ret_from_fork (arch/x86/kernel/process.c:158)
[   40.993995][    C0]  ? arch_exit_to_user_mode_prepare.isra.0 (arch/x86/entry/vdso/vma.c:80 (discriminator 1))
[   40.993996][    C0]  ? __switch_to (arch/x86/kernel/process_64.c:403 arch/x86/kernel/process_64.c:663)
[   40.993998][    C0]  ? kthread_affine_preferred (kernel/kthread.c:857)


Finger prints:
mark_lock_irq:mark_lock:mark_usage:__lock_acquire:console_lock_spinning_enable
run_ksoftirqd:handle_softirqs:dump_stack_lvl:__kmalloc_noprof:put_chars