[ 10.500333][ C0] clocksource: Watchdog remote CPU 3 read timed out [ 10.500453][ C0] [ 10.500454][ C0] ======================================================== [ 10.500455][ C0] WARNING: possible irq lock inversion dependency detected [ 10.500458][ C0] 7.2.0-virtme #1 Tainted: G W [ 10.500459][ C0] -------------------------------------------------------- [ 10.500460][ C0] swapper/0/0 just changed the state of lock: [ 10.500461][ C0] ffffffffb4a9d760 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.500473][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 10.500474][ C0] (fs_reclaim){+.+.}-{0:0} [ 10.500476][ C0] [ 10.500476][ C0] [ 10.500476][ C0] and interrupts could create inverse lock ordering between them. [ 10.500476][ C0] [ 10.500477][ C0] [ 10.500477][ C0] other info that might help us debug this: [ 10.500477][ C0] Possible interrupt unsafe locking scenario: [ 10.500477][ C0] [ 10.500478][ C0] CPU0 CPU1 [ 10.500478][ C0] ---- ---- [ 10.500479][ C0] lock(fs_reclaim); [ 10.500480][ C0] local_irq_disable(); [ 10.500480][ C0] lock(console_owner); [ 10.500481][ C0] lock(fs_reclaim); [ 10.500482][ C0] [ 10.500482][ C0] lock(console_owner); [ 10.500483][ C0] [ 10.500483][ C0] *** DEADLOCK *** [ 10.500483][ C0] [ 10.500484][ C0] locks held by swapper/0/0: 4, last CPU#0: [ 10.500485][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 10.500491][ C0] #1: ffffffffb4be29f8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 10.500495][ C0] #2: ffffffffb4b7ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.500499][ C0] #3: ffffffffb4b7de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.500502][ C0] [ 10.500502][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 10.500507][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 10.500509][ C0] HARDIRQ-ON-W at: [ 10.500511][ C0] __lock_acquire+0x388/0xc20 [ 10.500514][ C0] lock_acquire.part.0+0xd4/0x280 [ 10.500516][ C0] fs_reclaim_acquire+0xd5/0x120 [ 10.500519][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 10.500521][ C0] kthread_create_worker_on_node+0xea/0x210 [ 10.500524][ C0] workqueue_init+0x2a/0x680 [ 10.500529][ C0] kernel_init_freeable+0x2fe/0x630 [ 10.500532][ C0] kernel_init+0x21/0x150 [ 10.500535][ C0] ret_from_fork+0x474/0x6b0 [ 10.500539][ C0] ret_from_fork_asm+0x11/0x20 [ 10.500542][ C0] SOFTIRQ-ON-W at: [ 10.500543][ C0] __lock_acquire+0x388/0xc20 [ 10.500544][ C0] lock_acquire.part.0+0xd4/0x280 [ 10.500546][ C0] fs_reclaim_acquire+0xd5/0x120 [ 10.500547][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 10.500548][ C0] kthread_create_worker_on_node+0xea/0x210 [ 10.500550][ C0] workqueue_init+0x2a/0x680 [ 10.500552][ C0] kernel_init_freeable+0x2fe/0x630 [ 10.500553][ C0] kernel_init+0x21/0x150 [ 10.500555][ C0] ret_from_fork+0x474/0x6b0 [ 10.500556][ C0] ret_from_fork_asm+0x11/0x20 [ 10.500558][ C0] INITIAL USE at: [ 10.500559][ C0] __lock_acquire+0x388/0xc20 [ 10.500560][ C0] lock_acquire.part.0+0xd4/0x280 [ 10.500562][ C0] fs_reclaim_acquire+0xd5/0x120 [ 10.500564][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 10.500565][ C0] kthread_create_worker_on_node+0xea/0x210 [ 10.500566][ C0] workqueue_init+0x2a/0x680 [ 10.500568][ C0] kernel_init_freeable+0x2fe/0x630 [ 10.500570][ C0] kernel_init+0x21/0x150 [ 10.500572][ C0] ret_from_fork+0x474/0x6b0 [ 10.500573][ C0] ret_from_fork_asm+0x11/0x20 [ 10.500575][ C0] } [ 10.500575][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 10.500579][ C0] ... acquired at: [ 10.500580][ C0] __lock_acquire+0x518/0xc20 [ 10.500582][ C0] lock_acquire.part.0+0xd4/0x280 [ 10.500583][ C0] fs_reclaim_acquire+0xd5/0x120 [ 10.500584][ C0] __kmalloc_noprof+0xd3/0x760 [ 10.500586][ C0] alloc_buf.isra.0+0x4b/0x260 [ 10.500590][ C0] put_chars+0x1e1/0x2f0 [ 10.500591][ C0] hvc_console_print+0x292/0x780 [ 10.500594][ C0] console_emit_next_record+0x22f/0x4c0 [ 10.500596][ C0] console_flush_one_record+0x46f/0x710 [ 10.500597][ C0] console_unlock+0xee/0x1f0 [ 10.500599][ C0] vprintk_emit+0x37c/0x3e0 [ 10.500600][ C0] _printk+0xc7/0x100 [ 10.500603][ C0] tun_init+0x24/0xb0 [ 10.500606][ C0] do_one_initcall+0x124/0x4f0 [ 10.500607][ C0] kernel_init_freeable+0x596/0x630 [ 10.500609][ C0] kernel_init+0x21/0x150 [ 10.500610][ C0] ret_from_fork+0x474/0x6b0 [ 10.500611][ C0] ret_from_fork_asm+0x11/0x20 [ 10.500613][ C0] [ 10.500613][ C0] -> (console_owner){..-.}-{0:0} { [ 10.500615][ C0] IN-SOFTIRQ-W at: [ 10.500616][ C0] __lock_acquire+0x388/0xc20 [ 10.500617][ C0] lock_acquire.part.0+0xd4/0x280 [ 10.500619][ C0] console_lock_spinning_enable+0x5c/0x60 [ 10.500621][ C0] console_emit_next_record+0x1d1/0x4c0 [ 10.500623][ C0] console_flush_one_record+0x46f/0x710 [ 10.500624][ C0] console_unlock+0xee/0x1f0 [ 10.500626][ C0] vprintk_emit+0x37c/0x3e0 [ 10.500627][ C0] _printk+0xc7/0x100 [ 10.500628][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 10.500631][ C0] call_timer_fn+0x160/0x4d0 [ 10.500632][ C0] __run_timers+0x68f/0xaa0 [ 10.500634][ C0] run_timer_softirq+0xf0/0x160 [ 10.500636][ C0] handle_softirqs+0x1d3/0x900 [ 10.500639][ C0] __irq_exit_rcu+0x145/0x1c0 [ 10.500640][ C0] irq_exit_rcu+0xe/0x30 [ 10.500641][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 10.500643][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 10.500645][ C0] pv_native_safe_halt+0xf/0x10 [ 10.500646][ C0] default_idle+0x9/0x10 [ 10.500647][ C0] default_idle_call+0x6e/0xb0 [ 10.500649][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 10.500651][ C0] do_idle+0xd8/0x190 [ 10.500652][ C0] cpu_startup_entry+0x53/0x70 [ 10.500654][ C0] rest_init+0x279/0x280 [ 10.500655][ C0] start_kernel+0x3b9/0x3c0 [ 10.500657][ C0] x86_64_start_reservations+0x24/0x30 [ 10.500659][ C0] x86_64_start_kernel+0x12b/0x130 [ 10.500660][ C0] common_startup_64+0x13e/0x148 [ 10.500663][ C0] INITIAL USE at: [ 10.500664][ C0] } [ 10.500664][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 10.500667][ C0] ... acquired at: [ 10.500667][ C0] mark_lock+0x1d7/0xa00 [ 10.500668][ C0] mark_usage+0x42/0x170 [ 10.500670][ C0] __lock_acquire+0x388/0xc20 [ 10.500671][ C0] lock_acquire.part.0+0xd4/0x280 [ 10.500673][ C0] console_lock_spinning_enable+0x5c/0x60 [ 10.500674][ C0] console_emit_next_record+0x1d1/0x4c0 [ 10.500676][ C0] console_flush_one_record+0x46f/0x710 [ 10.500677][ C0] console_unlock+0xee/0x1f0 [ 10.500679][ C0] vprintk_emit+0x37c/0x3e0 [ 10.500680][ C0] _printk+0xc7/0x100 [ 10.500681][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 10.500682][ C0] call_timer_fn+0x160/0x4d0 [ 10.500684][ C0] __run_timers+0x68f/0xaa0 [ 10.500685][ C0] run_timer_softirq+0xf0/0x160 [ 10.500687][ C0] handle_softirqs+0x1d3/0x900 [ 10.500688][ C0] __irq_exit_rcu+0x145/0x1c0 [ 10.500689][ C0] irq_exit_rcu+0xe/0x30 [ 10.500691][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 10.500692][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 10.500693][ C0] pv_native_safe_halt+0xf/0x10 [ 10.500694][ C0] default_idle+0x9/0x10 [ 10.500696][ C0] default_idle_call+0x6e/0xb0 [ 10.500697][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 10.500698][ C0] do_idle+0xd8/0x190 [ 10.500699][ C0] cpu_startup_entry+0x53/0x70 [ 10.500700][ C0] rest_init+0x279/0x280 [ 10.500702][ C0] start_kernel+0x3b9/0x3c0 [ 10.500703][ C0] x86_64_start_reservations+0x24/0x30 [ 10.500704][ C0] x86_64_start_kernel+0x12b/0x130 [ 10.500706][ C0] common_startup_64+0x13e/0x148 [ 10.500707][ C0] [ 10.500707][ C0] [ 10.500707][ C0] stack backtrace: [ 10.500710][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.500714][ C0] Tainted: [W]=WARN [ 10.500714][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.500717][ C0] Call Trace: [ 10.500718][ C0] [ 10.500719][ C0] dump_stack_lvl+0x6f/0xa0 [ 10.500724][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 10.500726][ C0] mark_lock_irq+0x989/0x9c0 [ 10.500728][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 10.500731][ C0] mark_lock+0x1d7/0xa00 [ 10.500733][ C0] mark_usage+0x42/0x170 [ 10.500735][ C0] __lock_acquire+0x388/0xc20 [ 10.500737][ C0] lock_acquire.part.0+0xd4/0x280 [ 10.500739][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 10.500741][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.500743][ C0] ? lock_acquire+0x13c/0x160 [ 10.500746][ C0] console_lock_spinning_enable+0x5c/0x60 [ 10.500747][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 10.500749][ C0] console_emit_next_record+0x1d1/0x4c0 [ 10.500751][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 10.500753][ C0] ? console_flush_one_record+0x106/0x710 [ 10.500755][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.500756][ C0] ? lock_acquire+0x13c/0x160 [ 10.500758][ C0] console_flush_one_record+0x46f/0x710 [ 10.500761][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 10.500763][ C0] ? __lock_acquire+0x518/0xc20 [ 10.500765][ C0] console_unlock+0xee/0x1f0 [ 10.500767][ C0] ? console_flush_one_record+0x710/0x710 [ 10.500769][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.500770][ C0] ? lock_acquire+0xe0/0x160 [ 10.500772][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.500774][ C0] ? vprintk_emit+0x320/0x3e0 [ 10.500775][ C0] vprintk_emit+0x37c/0x3e0 [ 10.500777][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 10.500778][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 10.500780][ C0] _printk+0xc7/0x100 [ 10.500782][ C0] ? snapshot_read.cold+0x21/0x21 [ 10.500784][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 10.500786][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 10.500788][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 10.500790][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 10.500792][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 10.500793][ C0] call_timer_fn+0x160/0x4d0 [ 10.500796][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 10.500797][ C0] ? debug_object_active_state+0x430/0x430 [ 10.500800][ C0] ? find_held_lock+0x2b/0x80 [ 10.500803][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 10.500804][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.500806][ C0] __run_timers+0x68f/0xaa0 [ 10.500808][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 10.500811][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 10.500813][ C0] ? __lock_acquire+0x518/0xc20 [ 10.500815][ C0] ? __rwlock_init+0x150/0x150 [ 10.500818][ C0] run_timer_softirq+0xf0/0x160 [ 10.500821][ C0] ? __run_timers+0xaa0/0xaa0 [ 10.500823][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 10.500831][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.500832][ C0] handle_softirqs+0x1d3/0x900 [ 10.500834][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 10.500836][ C0] ? _local_bh_enable+0xc0/0xc0 [ 10.500838][ C0] __irq_exit_rcu+0x145/0x1c0 [ 10.500840][ C0] irq_exit_rcu+0xe/0x30 [ 10.500842][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 10.500843][ C0] [ 10.500844][ C0] [ 10.500845][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 10.500847][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 10.500848][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 10.500851][ C0] Code: 48 8b 3d 94 82 68 02 e8 1f 00 00 00 48 2b 05 58 d3 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 06 0e 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 10.500853][ C0] RSP: 0018:ffffffffb4807cf8 EFLAGS: 00000296 [ 10.500855][ C0] RAX: 000000000004c8e1 RBX: ffffffffb4830600 RCX: ffffffffb0f06247 [ 10.500856][ C0] RDX: ffffffffb4830600 RSI: ffffffffb4111011 RDI: ffffffffb3a949e0 [ 10.500857][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 10.500858][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff6900fa2 [ 10.500859][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 10.500861][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 10.500863][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 10.500865][ C0] default_idle+0x9/0x10 [ 10.500866][ C0] default_idle_call+0x6e/0xb0 [ 10.500868][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 10.500870][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 10.500871][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 10.500874][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.500876][ C0] do_idle+0xd8/0x190 [ 10.500877][ C0] cpu_startup_entry+0x53/0x70 [ 10.500879][ C0] rest_init+0x279/0x280 [ 10.500881][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 10.500884][ C0] ? rest_init+0x280/0x280 [ 10.500885][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 10.500889][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 10.500891][ C0] ? acpi_enable+0x1e4/0x330 [ 10.500893][ C0] start_kernel+0x3b9/0x3c0 [ 10.500895][ C0] x86_64_start_reservations+0x24/0x30 [ 10.500896][ C0] x86_64_start_kernel+0x12b/0x130 [ 10.500898][ C0] common_startup_64+0x13e/0x148 [ 10.500901][ C0] [ 10.500905][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.500907][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 10.500908][ C0] preempt_count: 103, expected: 0 [ 10.500909][ C0] RCU nest depth: 0, expected: 0 [ 10.500910][ C0] INFO: lockdep is turned off. [ 10.500911][ C0] irq event stamp: 313585 [ 10.500911][ C0] hardirqs last enabled at (313584): [] __down_trylock_console_sem+0x86/0xa0 [ 10.500914][ C0] hardirqs last disabled at (313585): [] console_emit_next_record+0x3d4/0x4c0 [ 10.500916][ C0] softirqs last enabled at (313558): [] handle_softirqs+0x67c/0x900 [ 10.500917][ C0] softirqs last disabled at (313571): [] __irq_exit_rcu+0x145/0x1c0 [ 10.500919][ C0] Preemption disabled at: [ 10.500920][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 10.500923][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.500925][ C0] Tainted: [W]=WARN [ 10.500926][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.500926][ C0] Call Trace: [ 10.500927][ C0] [ 10.500928][ C0] dump_stack_lvl+0x6f/0xa0 [ 10.500930][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 10.500932][ C0] __might_resched.cold+0x1fe/0x2c1 [ 10.500935][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.500938][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.500940][ C0] __kmalloc_noprof+0x443/0x760 [ 10.500941][ C0] ? __rwlock_init+0x150/0x150 [ 10.500943][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 10.500946][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 10.500947][ C0] alloc_buf.isra.0+0x4b/0x260 [ 10.500950][ C0] put_chars+0x1e1/0x2f0 [ 10.500952][ C0] ? __send_to_port+0x420/0x420 [ 10.500954][ C0] ? mark_lock+0x1d7/0xa00 [ 10.500956][ C0] hvc_console_print+0x292/0x780 [ 10.500959][ C0] ? hvc_write+0x3a0/0x3a0 [ 10.500961][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.500962][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.500964][ C0] console_emit_next_record+0x22f/0x4c0 [ 10.500967][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 10.500968][ C0] ? console_flush_one_record+0x106/0x710 [ 10.500971][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.500972][ C0] ? lock_acquire+0x13c/0x160 [ 10.500974][ C0] console_flush_one_record+0x46f/0x710 [ 10.500976][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 10.500978][ C0] ? __lock_acquire+0x518/0xc20 [ 10.500981][ C0] console_unlock+0xee/0x1f0 [ 10.500983][ C0] ? console_flush_one_record+0x710/0x710 [ 10.500985][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.500986][ C0] ? lock_acquire+0xe0/0x160 [ 10.500988][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.500990][ C0] ? vprintk_emit+0x320/0x3e0 [ 10.500991][ C0] vprintk_emit+0x37c/0x3e0 [ 10.500993][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 10.500995][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 10.500997][ C0] _printk+0xc7/0x100 [ 10.500999][ C0] ? snapshot_read.cold+0x21/0x21 [ 10.501001][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 10.501003][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 10.501005][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 10.501007][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 10.501009][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 10.501010][ C0] call_timer_fn+0x160/0x4d0 [ 10.501013][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 10.501014][ C0] ? debug_object_active_state+0x430/0x430 [ 10.501016][ C0] ? find_held_lock+0x2b/0x80 [ 10.501018][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 10.501020][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.501022][ C0] __run_timers+0x68f/0xaa0 [ 10.501024][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 10.501026][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 10.501028][ C0] ? __lock_acquire+0x518/0xc20 [ 10.501031][ C0] ? __rwlock_init+0x150/0x150 [ 10.501034][ C0] run_timer_softirq+0xf0/0x160 [ 10.501036][ C0] ? __run_timers+0xaa0/0xaa0 [ 10.501038][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 10.501039][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.501040][ C0] handle_softirqs+0x1d3/0x900 [ 10.501043][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 10.501044][ C0] ? _local_bh_enable+0xc0/0xc0 [ 10.501047][ C0] __irq_exit_rcu+0x145/0x1c0 [ 10.501048][ C0] irq_exit_rcu+0xe/0x30 [ 10.501050][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 10.501051][ C0] [ 10.501052][ C0] [ 10.501052][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 10.501054][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 10.501056][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 10.501058][ C0] Code: 48 8b 3d 94 82 68 02 e8 1f 00 00 00 48 2b 05 58 d3 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 06 0e 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 10.501059][ C0] RSP: 0018:ffffffffb4807cf8 EFLAGS: 00000296 [ 10.501060][ C0] RAX: 000000000004c8e1 RBX: ffffffffb4830600 RCX: ffffffffb0f06247 [ 10.501061][ C0] RDX: ffffffffb4830600 RSI: ffffffffb4111011 RDI: ffffffffb3a949e0 [ 10.501061][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 10.501062][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff6900fa2 [ 10.501063][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 10.501064][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 10.501067][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 10.501068][ C0] default_idle+0x9/0x10 [ 10.501070][ C0] default_idle_call+0x6e/0xb0 [ 10.501071][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 10.501073][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 10.501075][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 10.501076][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.501078][ C0] do_idle+0xd8/0x190 [ 10.501079][ C0] cpu_startup_entry+0x53/0x70 [ 10.501081][ C0] rest_init+0x279/0x280 [ 10.501083][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 10.501085][ C0] ? rest_init+0x280/0x280 [ 10.501087][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 10.501089][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 10.501091][ C0] ? acpi_enable+0x1e4/0x330 [ 10.501093][ C0] start_kernel+0x3b9/0x3c0 [ 10.501095][ C0] x86_64_start_reservations+0x24/0x30 [ 10.501096][ C0] x86_64_start_kernel+0x12b/0x130 [ 10.501098][ C0] common_startup_64+0x13e/0x148 [ 10.501101][ C0] [ 11.002871][ T217] gre: GRE over IPv4 demultiplexer driver [ 11.034233][ T217] ip_gre: GRE over IPv4 tunneling driver [ 16.810438][ T253] ip6_gre: GRE over IPv6 tunneling driver [ 16.810455][ T253] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 16.810458][ T253] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 253, name: modprobe [ 16.810459][ T253] preempt_count: 1, expected: 0 [ 16.810460][ T253] RCU nest depth: 0, expected: 0 [ 16.810461][ T253] INFO: lockdep is turned off. [ 16.810462][ T253] irq event stamp: 0 [ 16.810462][ T253] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 16.810466][ T253] hardirqs last disabled at (0): [] copy_process+0x1d3b/0x62b0 [ 16.810472][ T253] softirqs last enabled at (0): [] copy_process+0x1d8f/0x62b0 [ 16.810474][ T253] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 16.810476][ T253] Preemption disabled at: [ 16.810476][ T253] [] vprintk_emit+0x31b/0x3e0 [ 16.810484][ T253] CPU: 2 UID: 0 PID: 253 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 16.810487][ T253] Tainted: [W]=WARN [ 16.810488][ T253] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.810490][ T253] Call Trace: [ 16.810492][ T253] [ 16.810493][ T253] dump_stack_lvl+0x6f/0xa0 [ 16.810499][ T253] ? vprintk_emit+0x31b/0x3e0 [ 16.810500][ T253] __might_resched.cold+0x1fe/0x2c1 [ 16.810504][ T253] ? prb_next_reserve_seq+0x2d0/0x2d0 [ 16.810506][ T253] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 16.810509][ T253] ? rcu_is_watching+0x16/0xd0 [ 16.810511][ T253] __kmalloc_noprof+0x443/0x760 [ 16.810515][ T253] ? __rwlock_init+0x150/0x150 [ 16.810518][ T253] ? alloc_buf.isra.0+0x4b/0x260 [ 16.810523][ T253] ? do_raw_spin_unlock+0x59/0x250 [ 16.810524][ T253] alloc_buf.isra.0+0x4b/0x260 [ 16.810527][ T253] put_chars+0x1e1/0x2f0 [ 16.810528][ T253] ? __send_to_port+0x420/0x420 [ 16.810530][ T253] ? console_prepend_replay+0x20/0x20 [ 16.810532][ T253] hvc_console_print+0x292/0x780 [ 16.810537][ T253] ? hvc_write+0x3a0/0x3a0 [ 16.810539][ T253] ? vsnprintf+0x668/0x1700 [ 16.810542][ T253] ? __rwlock_init+0x150/0x150 [ 16.810544][ T253] ? rcu_is_watching+0x16/0xd0 [ 16.810545][ T253] ? rcu_is_watching+0x16/0xd0 [ 16.810546][ T253] console_emit_next_record+0x22f/0x4c0 [ 16.810549][ T253] ? devkmsg_read+0x4b0/0x4b0 [ 16.810551][ T253] ? rcu_is_watching+0x16/0xd0 [ 16.810552][ T253] ? rcu_is_watching+0x16/0xd0 [ 16.810553][ T253] ? rcu_is_watching+0x16/0xd0 [ 16.810554][ T253] ? lock_acquire+0x13c/0x160 [ 16.810556][ T253] ? console_flush_one_record+0x111/0x710 [ 16.810558][ T253] console_flush_one_record+0x46f/0x710 [ 16.810560][ T253] ? console_emit_next_record+0x4c0/0x4c0 [ 16.810563][ T253] console_unlock+0xee/0x1f0 [ 16.810564][ T253] ? lock_acquire+0x13c/0x160 [ 16.810566][ T253] ? console_flush_one_record+0x710/0x710 [ 16.810567][ T253] ? rcu_is_watching+0x16/0xd0 [ 16.810568][ T253] ? lock_release+0x184/0x1f0 [ 16.810570][ T253] ? lock_acquire+0xe0/0x160 [ 16.810571][ T253] ? __rcu_read_lock+0x70/0x70 [ 16.810573][ T253] ? __down_trylock_console_sem+0x5e/0xa0 [ 16.810575][ T253] ? vprintk_emit+0x320/0x3e0 [ 16.810576][ T253] vprintk_emit+0x37c/0x3e0 [ 16.810578][ T253] ? wake_up_klogd_work_func+0x90/0x90 [ 16.810580][ T253] ? ip6gre_fini+0xf40/0xf40 [ip6_gre] [ 16.810583][ T253] _printk+0xc7/0x100 [ 16.810587][ T253] ? snapshot_read.cold+0x21/0x21 [ 16.810589][ T253] ? do_one_initcall+0x113/0x4f0 [ 16.810592][ T253] ip6gre_init+0x16/0x1000 [ip6_gre] [ 16.810594][ T253] do_one_initcall+0x124/0x4f0 [ 16.810596][ T253] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 16.810598][ T253] ? do_init_module+0xae/0x8a0 [ 16.810601][ T253] ? kasan_unpoison+0x44/0x70 [ 16.810604][ T253] do_init_module+0x2ad/0x8a0 [ 16.810606][ T253] ? free_module+0x470/0x470 [ 16.810607][ T253] ? kfree+0x123/0x590 [ 16.810609][ T253] load_module+0x1424/0x1bb0 [ 16.810611][ T253] ? security_kernel_post_read_file+0x2f/0x60 [ 16.810615][ T253] ? layout_and_allocate.isra.0+0x680/0x680 [ 16.810617][ T253] ? __ia32_sys_fsconfig+0x180/0x180 [ 16.810620][ T253] ? backing_file_read_iter+0x541/0x790 [ 16.810623][ T253] init_module_from_file+0x11f/0x1a0 [ 16.810625][ T253] ? __do_sys_init_module+0x270/0x270 [ 16.810626][ T253] ? ovl_mmap+0x270/0x270 [ 16.810629][ T253] ? __rwlock_init+0x150/0x150 [ 16.810631][ T253] ? rcu_is_watching+0x16/0xd0 [ 16.810632][ T253] ? lock_release+0x184/0x1f0 [ 16.810634][ T253] ? do_raw_spin_unlock+0x59/0x250 [ 16.810636][ T253] idempotent_init_module+0x37b/0x7c0 [ 16.810637][ T253] ? init_module_from_file+0x1a0/0x1a0 [ 16.810639][ T253] ? cap_capable+0x1d7/0x3d0 [ 16.810641][ T253] ? __x64_sys_pread64+0x18d/0x250 [ 16.810644][ T253] __x64_sys_finit_module+0xdf/0x190 [ 16.810645][ T253] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 16.810648][ T253] do_syscall_64+0xff/0x530 [ 16.810650][ T253] ? exc_page_fault+0xee/0x100 [ 16.810652][ T253] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 16.810654][ T253] RIP: 0033:0x7f7eaa92e37d [ 16.810657][ T253] 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 [ 16.810658][ T253] RSP: 002b:00007ffcdbcf1d48 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 16.810661][ T253] RAX: ffffffffffffffda RBX: 000055fddb061810 RCX: 00007f7eaa92e37d [ 16.810663][ T253] RDX: 0000000000000000 RSI: 000055fdac534ffd RDI: 0000000000000000 [ 16.810664][ T253] RBP: 00007ffcdbcf1de0 R08: 0000000000000000 R09: 0000000000000000 [ 16.810665][ T253] R10: 0000000000000000 R11: 0000000000000246 R12: 000055fdac534ffd [ 16.810665][ T253] R13: 000055fddb0619a0 R14: 0000000000040000 R15: 0000000000000000 [ 16.810668][ T253]