====================================== | [ 10.873060][ T210] softirqs last disabled at (19909): __irq_exit_rcu (kernel/softirq.c:679 kernel/softirq.c:519 kernel/softirq.c:767) | [ 10.873063][ T210] Preemption disabled at: | [ 10.873063][ T210] 0x0 | [ 10.873074][ T210] Tainted: [W]=WARN [ 10.873075][ T210] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.873077][ T210] Call Trace: [ 10.873079][ T210] [ 10.873080][ T210] dump_stack_lvl (lib/dump_stack.c:94 lib/dump_stack.c:120) [ 10.873086][ T210] __might_resched.cold (kernel/sched/core.c:9234) [ 10.873090][ T210] ? perf_trace_sched_switch (./include/linux/list.h:1037 (discriminator 3)) [ 10.873094][ T210] ? __kmalloc_noprof (./include/linux/sched/mm.h:317 mm/slub.c:4636 mm/slub.c:4974 mm/slub.c:5413 mm/slub.c:5439) [ 10.873099][ T210] __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)) [ 10.873102][ T210] ? alloc_buf.isra.0 (./include/linux/slab.h:995 drivers/char/virtio_console.c:429) [ 10.873108][ T210] ? 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) [ 10.873111][ T210] alloc_buf.isra.0 (./include/linux/slab.h:995 drivers/char/virtio_console.c:429) [ 10.873115][ T210] put_chars (drivers/char/virtio_console.c:1176) [ 10.873118][ T210] ? __send_to_port (drivers/char/virtio_console.c:654) [ 10.873120][ T210] ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4116 (discriminator 3) kernel/rcu/tree.c:4108 (discriminator 3)) [ 10.873124][ T210] ? validate_chain (kernel/locking/lockdep.c:3817 (discriminator 2) kernel/locking/lockdep.c:3837 (discriminator 2) kernel/locking/lockdep.c:3892 (discriminator 2)) [ 10.873129][ T210] hvc_console_print (drivers/tty/hvc/hvc_console.c:177 (discriminator 2)) [ 10.873136][ T210] ? hvc_write (drivers/tty/hvc/hvc_console.c:501) [ 10.873138][ T210] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3)) [ 10.873140][ T210] ? lock_acquire (./include/trace/events/lock.h:23 (discriminator 22) kernel/locking/lockdep.c:5871 (discriminator 22)) [ 10.873145][ T210] console_emit_next_record (kernel/printk/printk.c:3183) [ 10.873148][ T210] ? devkmsg_read (./arch/x86/include/asm/atomic64_64.h:20) [ 10.873150][ T210] ? 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)) [ 10.873152][ T210] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3)) [ 10.873154][ T210] ? lock_acquire (./include/trace/events/lock.h:23 (discriminator 22) kernel/locking/lockdep.c:5871 (discriminator 22)) [ 10.873158][ T210] console_flush_one_record (kernel/printk/printk.c:3268) [ 10.873162][ T210] ? console_emit_next_record (kernel/printk/printk.c:3150) [ 10.873163][ T210] ? __lock_acquire (kernel/locking/lockdep.c:5254) [ 10.873169][ T210] console_unlock (kernel/printk/printk.c:3350 kernel/printk/printk.c:3380 kernel/printk/printk.c:3420) [ 10.873172][ T210] ? console_flush_one_record (kernel/printk/printk.c:3316) [ 10.873173][ T210] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3)) [ 10.873175][ T210] ? lock_acquire (kernel/locking/lockdep.c:5913) [ 10.873179][ T210] ? __down_trylock_console_sem (kernel/printk/printk.c:334) [ 10.873181][ T210] ? vprintk_emit (kernel/printk/printk.c:2478) [ 10.873184][ T210] vprintk_emit (kernel/printk/printk.c:2479) [ 10.873187][ T210] ? wake_up_klogd_work_func (kernel/printk/printk.c:4603 (discriminator 126)) [ 10.873190][ T210] ? __lock_acquire (kernel/locking/lockdep.c:5254) [ 10.873194][ T210] _printk (kernel/printk/printk.c:2504) [ 10.873198][ T210] ? snapshot_read.cold (user.c:?) [ 10.873201][ T210] ? 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) [ 10.873204][ T210] ? __rwlock_init (kernel/locking/spinlock_debug.c:48) [ 10.873208][ T210] ? 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) [ 10.873211][ T210] do_exit.cold (kernel/exit.c:889 (discriminator 4) kernel/exit.c:1043 (discriminator 4)) [ 10.873215][ T210] ? exit_notify (kernel/exit.c:780) [ 10.873216][ T210] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5554) [ 10.873219][ T210] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3)) [ 10.873223][ T210] do_group_exit (kernel/exit.c:1151) [ 10.873236][ T210] __x64_sys_exit_group (kernel/exit.c:1162 kernel/exit.c:1160 kernel/exit.c:1160) [ 10.873238][ T210] x64_sys_call (./arch/x86/include/generated/asm/syscalls_64.h:232) [ 10.873241][ T210] do_syscall_64 (arch/x86/entry/syscall_64.c:61 arch/x86/entry/syscall_64.c:84) [ 10.873245][ T210] ? exc_page_fault (arch/x86/mm/fault.c:1489 (discriminator 3) arch/x86/mm/fault.c:1536 (discriminator 3)) [ 10.873248][ T210] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:121) [ 10.873251][ T210] RIP: 0033:0x7fbdd8b7c1b8 [ 10.873253][ T210] Code: Unable to access opcode bytes at 0x7fbdd8b7c18e. Code starting with the faulting instruction =========================================== [ 10.873254][ T210] RSP: 002b:00007fff30ecf698 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 10.873257][ T210] RAX: ffffffffffffffda RBX: 00007fbdd8cacf88 RCX: 00007fbdd8b7c1b8 [ 10.873258][ T210] RDX: 00007fbdd88c6fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 10.873259][ T210] RBP: 00007fff30ecf6f0 R08: 0000000000000000 R09: 0000000000008010 [ 10.873260][ T210] R10: 00007fff30ecf4b0 R11: 0000000000000246 R12: 0000000000000001 [ 10.873261][ T210] R13: 0000000000000000 R14: 00007fbdd8cab680 R15: 00007fbdd8cacfa0 | [ 11.416147][ C0] | [ 11.416147][ C0] | [ 11.416147][ C0] stack backtrace: | [ 11.416153][ C0] Tainted: [W]=WARN [ 11.416154][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.416156][ C0] Call Trace: [ 11.416157][ C0] [ 11.416158][ C0] dump_stack_lvl (lib/dump_stack.c:94 lib/dump_stack.c:120) [ 11.416162][ C0] print_irq_inversion_bug.part.0.cold (kernel/locking/lockdep.c:4141) [ 11.416164][ C0] mark_lock_irq (kernel/locking/lockdep.c:4095 kernel/locking/lockdep.c:4174 kernel/locking/lockdep.c:4310) [ 11.416166][ C0] ? add_lock_to_list (./include/linux/find.h:391 kernel/locking/lockdep.c:1419 kernel/locking/lockdep.c:1450) [ 11.416169][ C0] mark_lock (kernel/locking/lockdep.c:4769) [ 11.416171][ C0] mark_usage (kernel/locking/lockdep.c:4658) [ 11.416173][ C0] __lock_acquire (kernel/locking/lockdep.c:5208) [ 11.416176][ C0] lock_acquire.part.0 (kernel/locking/lockdep.c:5908 (discriminator 2)) [ 11.416177][ C0] ? console_lock_spinning_enable (kernel/printk/printk.c:1902 (discriminator 2)) [ 11.416179][ C0] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3)) [ 11.416181][ C0] ? lock_acquire (./include/trace/events/lock.h:23 (discriminator 22) kernel/locking/lockdep.c:5871 (discriminator 22)) [ 11.416184][ C0] console_lock_spinning_enable (kernel/printk/printk.c:1902 (discriminator 2)) [ 11.416185][ C0] ? console_lock_spinning_enable (kernel/printk/printk.c:1902 (discriminator 2)) [ 11.416186][ C0] console_emit_next_record (kernel/printk/printk.c:3177) [ 11.416187][ C0] ? devkmsg_read (./arch/x86/include/asm/atomic64_64.h:20) [ 11.416189][ 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)) [ 11.416190][ C0] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3)) [ 11.416191][ C0] ? lock_acquire (./include/trace/events/lock.h:23 (discriminator 22) kernel/locking/lockdep.c:5871 (discriminator 22)) [ 11.416193][ C0] console_flush_one_record (kernel/printk/printk.c:3268) [ 11.416195][ C0] ? console_emit_next_record (kernel/printk/printk.c:3150) [ 11.416196][ C0] ? __lock_acquire (kernel/locking/lockdep.c:5254) [ 11.416199][ C0] console_unlock (kernel/printk/printk.c:3350 kernel/printk/printk.c:3380 kernel/printk/printk.c:3420) [ 11.416200][ C0] ? console_flush_one_record (kernel/printk/printk.c:3316) [ 11.416201][ C0] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3)) [ 11.416203][ C0] ? lock_acquire (kernel/locking/lockdep.c:5913) [ 11.416205][ C0] ? __down_trylock_console_sem (kernel/printk/printk.c:334) [ 11.416207][ C0] ? vprintk_emit (kernel/printk/printk.c:2478) [ 11.416209][ C0] vprintk_emit (kernel/printk/printk.c:2479) [ 11.416210][ C0] ? wake_up_klogd_work_func (kernel/printk/printk.c:4603 (discriminator 126)) [ 11.416212][ 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.416214][ C0] _printk (kernel/printk/printk.c:2504) [ 11.416216][ C0] ? snapshot_read.cold (user.c:?) [ 11.416218][ 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.416219][ C0] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5554) [ 11.416222][ 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.416223][ C0] ? clocksource_watchdog.part.0 (./include/linux/find.h:67 (discriminator 4)) [ 11.416225][ C0] ? clocksource_watchdog.part.0 (./include/linux/find.h:67 (discriminator 4)) [ 11.416233][ C0] call_timer_fn (kernel/time/timer.c:1748) [ 11.416235][ C0] ? detach_if_pending (./include/linux/list.h:1159) [ 11.416236][ C0] ? debug_object_active_state (lib/debugobjects.c:1122) [ 11.416239][ C0] ? find_held_lock (kernel/locking/lockdep.c:5367) [ 11.416241][ C0] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5554) [ 11.416243][ C0] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3)) [ 11.416244][ C0] __run_timers (kernel/time/timer.c:1799 kernel/time/timer.c:2374) [ 11.416245][ C0] ? clocksource_watchdog.part.0 (./include/linux/find.h:67 (discriminator 4)) [ 11.416248][ C0] ? __bpf_trace_itimer_expire (./include/trace/events/timer.h:407) [ 11.416249][ C0] ? __lock_acquire (kernel/locking/lockdep.c:5254) [ 11.416252][ C0] ? __rwlock_init (kernel/locking/spinlock_debug.c:48) [ 11.416255][ C0] run_timer_softirq (kernel/time/timer.c:2386 kernel/time/timer.c:2395 kernel/time/timer.c:2403) [ 11.416256][ C0] ? __run_timers (./include/trace/events/timer.h:138 (discriminator 19)) [ 11.416257][ C0] ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 22)) [ 11.416260][ C0] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3)) [ 11.416261][ C0] handle_softirqs (kernel/softirq.c:645) [ 11.416263][ C0] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5554) [ 11.416265][ C0] ? _local_bh_enable (kernel/softirq.c:428 (discriminator 1)) [ 11.416267][ C0] __irq_exit_rcu (kernel/softirq.c:679 kernel/softirq.c:519 kernel/softirq.c:767) [ 11.416269][ C0] irq_exit_rcu (kernel/softirq.c:784) [ 11.416270][ C0] sysvec_apic_timer_interrupt (arch/x86/kernel/apic/apic.c:1062 (discriminator 37) arch/x86/kernel/apic/apic.c:1062 (discriminator 37)) [ 11.416272][ C0] [ 11.416272][ C0] [ 11.416273][ C0] ? lockdep_hardirqs_on_prepare.part.0 (kernel/locking/lockdep.c:470 (discriminator 2) kernel/locking/lockdep.c:4427 (discriminator 2)) [ 11.416275][ C0] asm_sysvec_apic_timer_interrupt (./arch/x86/include/asm/idtentry.h:674) [ 11.416276][ C0] RIP: 0010:pv_native_safe_halt (./arch/x86/include/asm/irqflags.h:48 arch/x86/kernel/paravirt.c:63) [ 11.416279][ C0] Code: 48 8b 3d 94 e2 f8 01 e8 1f 00 00 00 48 2b 05 58 e3 99 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 d6 16 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 94 e2 f8 01 mov 0x1f8e294(%rip),%rdi # 0x1f8e29b 7: e8 1f 00 00 00 call 0x2b c: 48 2b 05 58 e3 99 00 sub 0x99e358(%rip),%rax # 0x99e36b 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 d6 16 00 verw 0x16d613(%rip) # 0x16d63b 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.416281][ C0] RSP: 0018:ffffffff96c07cf8 EFLAGS: 00000296 [ 11.416283][ C0] RAX: 00000000000651f1 RBX: ffffffff96c1c600 RCX: ffffffff93ced307 [ 11.416284][ C0] RDX: ffffffff96c1c600 RSI: ffffffff96a29f9e RDI: ffffffff9648d8e0 [ 11.416285][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 11.416286][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff2d80fa2 [ 11.416287][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 11.416288][ C0] ? cpuidle_idle_call.constprop.0 (kernel/sched/idle.c:199) [ 11.416291][ C0] ? lockdep_hardirqs_on (kernel/locking/lockdep.c:4488) [ 11.416292][ C0] default_idle (./arch/x86/include/asm/paravirt.h:62 arch/x86/kernel/process.c:768) [ 11.416293][ C0] default_idle_call (kernel/sched/idle.c:122) [ 11.416295][ C0] cpuidle_idle_call.constprop.0 (kernel/sched/idle.c:199) [ 11.416296][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 11.416298][ C0] ? mark_tsc_async_resets (arch/x86/kernel/tsc_sync.c:49) [ 11.416301][ C0] ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 22)) [ 11.416303][ C0] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 3) kernel/rcu/tree.c:753 (discriminator 3)) [ 11.416304][ C0] do_idle (kernel/sched/idle.c:355) [ 11.416306][ C0] cpu_startup_entry (kernel/sched/idle.c:454) [ 11.416307][ C0] rest_init (init/main.c:727) [ 11.416309][ C0] ? __cpuidle_text_end (??:?) [ 11.416311][ C0] ? rest_init (init/main.c:727) [ 11.416313][ C0] ? acpi_hw_set_mode (drivers/acpi/acpica/hwacpi.c:72 (discriminator 1)) [ 11.416316][ C0] ? cpus_read_unlock (./include/linux/percpu-rwsem.h:125 (discriminator 1) kernel/cpu.c:502 (discriminator 1)) [ 11.416318][ C0] ? acpi_enable (drivers/acpi/acpica/evxfevnt.c:47) [ 11.416321][ C0] start_kernel (init/main.c:1185) [ 11.416322][ C0] x86_64_start_reservations (arch/x86/kernel/head64.c:310) [ 11.416324][ C0] x86_64_start_kernel (arch/x86/kernel/head64.c:291) Finger prints: __irq_exit_rcu:dump_stack_lvl:__kmalloc_noprof:put_chars:hvc_console_print mark_lock_irq:mark_lock:mark_usage:__lock_acquire:console_lock_spinning_enable