[ 12.491068][ C0] clocksource: Watchdog remote CPU 3 read timed out [ 12.491186][ C0] [ 12.491187][ C0] ======================================================== [ 12.491188][ C0] WARNING: possible irq lock inversion dependency detected [ 12.491190][ C0] 7.2.0-virtme #1 Tainted: G W [ 12.491191][ C0] -------------------------------------------------------- [ 12.491192][ C0] swapper/0/0 just changed the state of lock: [ 12.491193][ C0] ffffffffa7c9d760 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.491205][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 12.491206][ C0] (fs_reclaim){+.+.}-{0:0} [ 12.491208][ C0] [ 12.491208][ C0] [ 12.491208][ C0] and interrupts could create inverse lock ordering between them. [ 12.491208][ C0] [ 12.491208][ C0] [ 12.491208][ C0] other info that might help us debug this: [ 12.491209][ C0] Possible interrupt unsafe locking scenario: [ 12.491209][ C0] [ 12.491210][ C0] CPU0 CPU1 [ 12.491210][ C0] ---- ---- [ 12.491211][ C0] lock(fs_reclaim); [ 12.491212][ C0] local_irq_disable(); [ 12.491212][ C0] lock(console_owner); [ 12.491213][ C0] lock(fs_reclaim); [ 12.491214][ C0] [ 12.491214][ C0] lock(console_owner); [ 12.491215][ C0] [ 12.491215][ C0] *** DEADLOCK *** [ 12.491215][ C0] [ 12.491216][ C0] locks held by swapper/0/0: 4, last CPU#0: [ 12.491217][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 12.491223][ C0] #1: ffffffffa7de29f8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 12.491227][ C0] #2: ffffffffa7d7ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.491230][ C0] #3: ffffffffa7d7de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.491233][ C0] [ 12.491233][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 12.491237][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 12.491239][ C0] HARDIRQ-ON-W at: [ 12.491241][ C0] __lock_acquire+0x388/0xc20 [ 12.491244][ C0] lock_acquire.part.0+0xd4/0x280 [ 12.491245][ C0] fs_reclaim_acquire+0xd5/0x120 [ 12.491249][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 12.491251][ C0] kthread_create_worker_on_node+0xea/0x210 [ 12.491254][ C0] workqueue_init+0x2a/0x680 [ 12.491258][ C0] kernel_init_freeable+0x2fe/0x630 [ 12.491261][ C0] kernel_init+0x21/0x150 [ 12.491265][ C0] ret_from_fork+0x474/0x6b0 [ 12.491268][ C0] ret_from_fork_asm+0x11/0x20 [ 12.491271][ C0] SOFTIRQ-ON-W at: [ 12.491271][ C0] __lock_acquire+0x388/0xc20 [ 12.491273][ C0] lock_acquire.part.0+0xd4/0x280 [ 12.491274][ C0] fs_reclaim_acquire+0xd5/0x120 [ 12.491276][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 12.491277][ C0] kthread_create_worker_on_node+0xea/0x210 [ 12.491278][ C0] workqueue_init+0x2a/0x680 [ 12.491280][ C0] kernel_init_freeable+0x2fe/0x630 [ 12.491281][ C0] kernel_init+0x21/0x150 [ 12.491282][ C0] ret_from_fork+0x474/0x6b0 [ 12.491284][ C0] ret_from_fork_asm+0x11/0x20 [ 12.491285][ C0] INITIAL USE at: [ 12.491286][ C0] __lock_acquire+0x388/0xc20 [ 12.491287][ C0] lock_acquire.part.0+0xd4/0x280 [ 12.491288][ C0] fs_reclaim_acquire+0xd5/0x120 [ 12.491290][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 12.491291][ C0] kthread_create_worker_on_node+0xea/0x210 [ 12.491292][ C0] workqueue_init+0x2a/0x680 [ 12.491293][ C0] kernel_init_freeable+0x2fe/0x630 [ 12.491295][ C0] kernel_init+0x21/0x150 [ 12.491296][ C0] ret_from_fork+0x474/0x6b0 [ 12.491297][ C0] ret_from_fork_asm+0x11/0x20 [ 12.491299][ C0] } [ 12.491299][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 12.491303][ C0] ... acquired at: [ 12.491304][ C0] __lock_acquire+0x518/0xc20 [ 12.491305][ C0] lock_acquire.part.0+0xd4/0x280 [ 12.491307][ C0] fs_reclaim_acquire+0xd5/0x120 [ 12.491308][ C0] __kmalloc_noprof+0xd3/0x760 [ 12.491309][ C0] alloc_buf.isra.0+0x4b/0x260 [ 12.491313][ C0] put_chars+0x1e1/0x2f0 [ 12.491314][ C0] hvc_console_print+0x292/0x780 [ 12.491317][ C0] console_emit_next_record+0x22f/0x4c0 [ 12.491319][ C0] console_flush_one_record+0x46f/0x710 [ 12.491320][ C0] console_unlock+0xee/0x1f0 [ 12.491322][ C0] vprintk_emit+0x37c/0x3e0 [ 12.491323][ C0] _printk+0xc7/0x100 [ 12.491326][ C0] tun_init+0x24/0xb0 [ 12.491328][ C0] do_one_initcall+0x124/0x4f0 [ 12.491329][ C0] kernel_init_freeable+0x596/0x630 [ 12.491331][ C0] kernel_init+0x21/0x150 [ 12.491332][ C0] ret_from_fork+0x474/0x6b0 [ 12.491333][ C0] ret_from_fork_asm+0x11/0x20 [ 12.491334][ C0] [ 12.491335][ C0] -> (console_owner){..-.}-{0:0} { [ 12.491336][ C0] IN-SOFTIRQ-W at: [ 12.491337][ C0] __lock_acquire+0x388/0xc20 [ 12.491339][ C0] lock_acquire.part.0+0xd4/0x280 [ 12.491340][ C0] console_lock_spinning_enable+0x5c/0x60 [ 12.491342][ C0] console_emit_next_record+0x1d1/0x4c0 [ 12.491343][ C0] console_flush_one_record+0x46f/0x710 [ 12.491345][ C0] console_unlock+0xee/0x1f0 [ 12.491346][ C0] vprintk_emit+0x37c/0x3e0 [ 12.491347][ C0] _printk+0xc7/0x100 [ 12.491348][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 12.491351][ C0] call_timer_fn+0x160/0x4d0 [ 12.491352][ C0] __run_timers+0x68f/0xaa0 [ 12.491354][ C0] run_timer_softirq+0xf0/0x160 [ 12.491356][ C0] handle_softirqs+0x1d3/0x900 [ 12.491358][ C0] __irq_exit_rcu+0x145/0x1c0 [ 12.491359][ C0] irq_exit_rcu+0xe/0x30 [ 12.491360][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 12.491362][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 12.491363][ C0] pv_native_safe_halt+0xf/0x10 [ 12.491365][ C0] default_idle+0x9/0x10 [ 12.491366][ C0] default_idle_call+0x6e/0xb0 [ 12.491367][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 12.491370][ C0] do_idle+0xd8/0x190 [ 12.491371][ C0] cpu_startup_entry+0x53/0x70 [ 12.491372][ C0] rest_init+0x279/0x280 [ 12.491374][ C0] start_kernel+0x3b9/0x3c0 [ 12.491375][ C0] x86_64_start_reservations+0x24/0x30 [ 12.491377][ C0] x86_64_start_kernel+0x12b/0x130 [ 12.491378][ C0] common_startup_64+0x13e/0x148 [ 12.491381][ C0] INITIAL USE at: [ 12.491382][ C0] } [ 12.491382][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 12.491384][ C0] ... acquired at: [ 12.491390][ C0] mark_lock+0x1d7/0xa00 [ 12.491391][ C0] mark_usage+0x42/0x170 [ 12.491392][ C0] __lock_acquire+0x388/0xc20 [ 12.491394][ C0] lock_acquire.part.0+0xd4/0x280 [ 12.491395][ C0] console_lock_spinning_enable+0x5c/0x60 [ 12.491397][ C0] console_emit_next_record+0x1d1/0x4c0 [ 12.491398][ C0] console_flush_one_record+0x46f/0x710 [ 12.491400][ C0] console_unlock+0xee/0x1f0 [ 12.491401][ C0] vprintk_emit+0x37c/0x3e0 [ 12.491402][ C0] _printk+0xc7/0x100 [ 12.491403][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 12.491404][ C0] call_timer_fn+0x160/0x4d0 [ 12.491406][ C0] __run_timers+0x68f/0xaa0 [ 12.491407][ C0] run_timer_softirq+0xf0/0x160 [ 12.491409][ C0] handle_softirqs+0x1d3/0x900 [ 12.491410][ C0] __irq_exit_rcu+0x145/0x1c0 [ 12.491411][ C0] irq_exit_rcu+0xe/0x30 [ 12.491412][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 12.491413][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 12.491414][ C0] pv_native_safe_halt+0xf/0x10 [ 12.491415][ C0] default_idle+0x9/0x10 [ 12.491417][ C0] default_idle_call+0x6e/0xb0 [ 12.491418][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 12.491419][ C0] do_idle+0xd8/0x190 [ 12.491420][ C0] cpu_startup_entry+0x53/0x70 [ 12.491421][ C0] rest_init+0x279/0x280 [ 12.491423][ C0] start_kernel+0x3b9/0x3c0 [ 12.491424][ C0] x86_64_start_reservations+0x24/0x30 [ 12.491425][ C0] x86_64_start_kernel+0x12b/0x130 [ 12.491426][ C0] common_startup_64+0x13e/0x148 [ 12.491428][ C0] [ 12.491428][ C0] [ 12.491428][ C0] stack backtrace: [ 12.491431][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.491433][ C0] Tainted: [W]=WARN [ 12.491434][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.491436][ C0] Call Trace: [ 12.491437][ C0] [ 12.491438][ C0] dump_stack_lvl+0x6f/0xa0 [ 12.491442][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 12.491445][ C0] mark_lock_irq+0x989/0x9c0 [ 12.491448][ C0] mark_lock+0x1d7/0xa00 [ 12.491450][ C0] mark_usage+0x42/0x170 [ 12.491452][ C0] __lock_acquire+0x388/0xc20 [ 12.491454][ C0] lock_acquire.part.0+0xd4/0x280 [ 12.491456][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 12.491458][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.491460][ C0] ? lock_acquire+0x13c/0x160 [ 12.491462][ C0] console_lock_spinning_enable+0x5c/0x60 [ 12.491464][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 12.491465][ C0] console_emit_next_record+0x1d1/0x4c0 [ 12.491467][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 12.491469][ C0] ? console_flush_one_record+0x106/0x710 [ 12.491471][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.491472][ C0] ? lock_acquire+0x13c/0x160 [ 12.491474][ C0] console_flush_one_record+0x46f/0x710 [ 12.491477][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 12.491478][ C0] ? __lock_acquire+0x518/0xc20 [ 12.491481][ C0] console_unlock+0xee/0x1f0 [ 12.491483][ C0] ? console_flush_one_record+0x710/0x710 [ 12.491484][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.491485][ C0] ? lock_acquire+0xe0/0x160 [ 12.491487][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.491489][ C0] ? vprintk_emit+0x320/0x3e0 [ 12.491490][ C0] vprintk_emit+0x37c/0x3e0 [ 12.491492][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 12.491494][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 12.491495][ C0] _printk+0xc7/0x100 [ 12.491497][ C0] ? snapshot_read.cold+0x21/0x21 [ 12.491499][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 12.491500][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 12.491503][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 12.491504][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 12.491506][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 12.491507][ C0] call_timer_fn+0x160/0x4d0 [ 12.491509][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 12.491511][ C0] ? debug_object_active_state+0x430/0x430 [ 12.491514][ C0] ? find_held_lock+0x2b/0x80 [ 12.491516][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 12.491517][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.491519][ C0] __run_timers+0x68f/0xaa0 [ 12.491521][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 12.491523][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 12.491525][ C0] ? __lock_acquire+0x518/0xc20 [ 12.491528][ C0] ? __rwlock_init+0x150/0x150 [ 12.491530][ C0] run_timer_softirq+0xf0/0x160 [ 12.491532][ C0] ? __run_timers+0xaa0/0xaa0 [ 12.491534][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 12.491537][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.491538][ C0] handle_softirqs+0x1d3/0x900 [ 12.491540][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 12.491542][ C0] ? _local_bh_enable+0xc0/0xc0 [ 12.491544][ C0] __irq_exit_rcu+0x145/0x1c0 [ 12.491545][ C0] irq_exit_rcu+0xe/0x30 [ 12.491547][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 12.491548][ C0] [ 12.491549][ C0] [ 12.491549][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 12.491551][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 12.491553][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 12.491555][ 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 [ 12.491557][ C0] RSP: 0018:ffffffffa7a07cf8 EFLAGS: 00000296 [ 12.491559][ C0] RAX: 000000000007c73d RBX: ffffffffa7a30600 RCX: ffffffffa4106247 [ 12.491560][ C0] RDX: ffffffffa7a30600 RSI: ffffffffa7311027 RDI: ffffffffa6c949e0 [ 12.491561][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 12.491561][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff4f40fa2 [ 12.491562][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 12.491564][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 12.491566][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 12.491567][ C0] default_idle+0x9/0x10 [ 12.491569][ C0] default_idle_call+0x6e/0xb0 [ 12.491570][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 12.491572][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 12.491573][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 12.491576][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 12.491577][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.491579][ C0] do_idle+0xd8/0x190 [ 12.491580][ C0] cpu_startup_entry+0x53/0x70 [ 12.491582][ C0] rest_init+0x279/0x280 [ 12.491583][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 12.491586][ C0] ? rest_init+0x280/0x280 [ 12.491587][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 12.491591][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 12.491592][ C0] ? acpi_enable+0x1e4/0x330 [ 12.491594][ C0] start_kernel+0x3b9/0x3c0 [ 12.491596][ C0] x86_64_start_reservations+0x24/0x30 [ 12.491598][ C0] x86_64_start_kernel+0x12b/0x130 [ 12.491599][ C0] common_startup_64+0x13e/0x148 [ 12.491602][ C0] [ 12.491606][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.491607][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 12.491609][ C0] preempt_count: 103, expected: 0 [ 12.491609][ C0] RCU nest depth: 0, expected: 0 [ 12.491610][ C0] INFO: lockdep is turned off. [ 12.491610][ C0] irq event stamp: 509773 [ 12.491611][ C0] hardirqs last enabled at (509772): [] __down_trylock_console_sem+0x86/0xa0 [ 12.491613][ C0] hardirqs last disabled at (509773): [] console_emit_next_record+0x3d4/0x4c0 [ 12.491615][ C0] softirqs last enabled at (509752): [] handle_softirqs+0x67c/0x900 [ 12.491617][ C0] softirqs last disabled at (509759): [] __irq_exit_rcu+0x145/0x1c0 [ 12.491618][ C0] Preemption disabled at: [ 12.491619][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 12.491622][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.491624][ C0] Tainted: [W]=WARN [ 12.491625][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.491625][ C0] Call Trace: [ 12.491626][ C0] [ 12.491626][ C0] dump_stack_lvl+0x6f/0xa0 [ 12.491628][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 12.491630][ C0] __might_resched.cold+0x1fe/0x2c1 [ 12.491633][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.491636][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.491637][ C0] __kmalloc_noprof+0x443/0x760 [ 12.491638][ C0] ? __rwlock_init+0x150/0x150 [ 12.491640][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 12.491642][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 12.491644][ C0] alloc_buf.isra.0+0x4b/0x260 [ 12.491646][ C0] put_chars+0x1e1/0x2f0 [ 12.491648][ C0] ? __send_to_port+0x420/0x420 [ 12.491650][ C0] ? mark_lock+0x1d7/0xa00 [ 12.491652][ C0] hvc_console_print+0x292/0x780 [ 12.491655][ C0] ? hvc_write+0x3a0/0x3a0 [ 12.491657][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.491657][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.491659][ C0] console_emit_next_record+0x22f/0x4c0 [ 12.491661][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 12.491663][ C0] ? console_flush_one_record+0x106/0x710 [ 12.491665][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.491666][ C0] ? lock_acquire+0x13c/0x160 [ 12.491668][ C0] console_flush_one_record+0x46f/0x710 [ 12.491670][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 12.491672][ C0] ? __lock_acquire+0x518/0xc20 [ 12.491674][ C0] console_unlock+0xee/0x1f0 [ 12.491676][ C0] ? console_flush_one_record+0x710/0x710 [ 12.491678][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.491679][ C0] ? lock_acquire+0xe0/0x160 [ 12.491681][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.491683][ C0] ? vprintk_emit+0x320/0x3e0 [ 12.491684][ C0] vprintk_emit+0x37c/0x3e0 [ 12.491685][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 12.491687][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 12.491689][ C0] _printk+0xc7/0x100 [ 12.491690][ C0] ? snapshot_read.cold+0x21/0x21 [ 12.491692][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 12.491694][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 12.491696][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 12.491697][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 12.491699][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 12.491701][ C0] call_timer_fn+0x160/0x4d0 [ 12.491703][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 12.491705][ C0] ? debug_object_active_state+0x430/0x430 [ 12.491706][ C0] ? find_held_lock+0x2b/0x80 [ 12.491707][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 12.491709][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.491711][ C0] __run_timers+0x68f/0xaa0 [ 12.491712][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 12.491715][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 12.491716][ C0] ? __lock_acquire+0x518/0xc20 [ 12.491719][ C0] ? __rwlock_init+0x150/0x150 [ 12.491721][ C0] run_timer_softirq+0xf0/0x160 [ 12.491723][ C0] ? __run_timers+0xaa0/0xaa0 [ 12.491725][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 12.491726][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.491728][ C0] handle_softirqs+0x1d3/0x900 [ 12.491729][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 12.491731][ C0] ? _local_bh_enable+0xc0/0xc0 [ 12.491733][ C0] __irq_exit_rcu+0x145/0x1c0 [ 12.491735][ C0] irq_exit_rcu+0xe/0x30 [ 12.491736][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 12.491738][ C0] [ 12.491738][ C0] [ 12.491739][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 12.491740][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 12.491742][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 12.491743][ 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 [ 12.491744][ C0] RSP: 0018:ffffffffa7a07cf8 EFLAGS: 00000296 [ 12.491745][ C0] RAX: 000000000007c73d RBX: ffffffffa7a30600 RCX: ffffffffa4106247 [ 12.491746][ C0] RDX: ffffffffa7a30600 RSI: ffffffffa7311027 RDI: ffffffffa6c949e0 [ 12.491746][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 12.491747][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff4f40fa2 [ 12.491748][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 12.491749][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 12.491751][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 12.491753][ C0] default_idle+0x9/0x10 [ 12.491754][ C0] default_idle_call+0x6e/0xb0 [ 12.491756][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 12.491757][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 12.491759][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 12.491760][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 12.491762][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.491763][ C0] do_idle+0xd8/0x190 [ 12.491765][ C0] cpu_startup_entry+0x53/0x70 [ 12.491766][ C0] rest_init+0x279/0x280 [ 12.491768][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 12.491770][ C0] ? rest_init+0x280/0x280 [ 12.491772][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 12.491774][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 12.491775][ C0] ? acpi_enable+0x1e4/0x330 [ 12.491776][ C0] start_kernel+0x3b9/0x3c0 [ 12.491778][ C0] x86_64_start_reservations+0x24/0x30 [ 12.491780][ C0] x86_64_start_kernel+0x12b/0x130 [ 12.491781][ C0] common_startup_64+0x13e/0x148 [ 12.491784][ C0] [ 15.123232][ T435] Initializing XFRM netlink socket [ 15.123245][ T435] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 15.123247][ T435] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 435, name: modprobe [ 15.123249][ T435] preempt_count: 1, expected: 0 [ 15.123250][ T435] RCU nest depth: 0, expected: 0 [ 15.123251][ T435] INFO: lockdep is turned off. [ 15.123252][ T435] irq event stamp: 0 [ 15.123252][ T435] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 15.123256][ T435] hardirqs last disabled at (0): [] copy_process+0x1d3b/0x62b0 [ 15.123262][ T435] softirqs last enabled at (0): [] copy_process+0x1d8f/0x62b0 [ 15.123264][ T435] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 15.123266][ T435] Preemption disabled at: [ 15.123266][ T435] [] vprintk_emit+0x31b/0x3e0 [ 15.123273][ T435] CPU: 2 UID: 0 PID: 435 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.123276][ T435] Tainted: [W]=WARN [ 15.123277][ T435] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.123279][ T435] Call Trace: [ 15.123280][ T435] [ 15.123281][ T435] dump_stack_lvl+0x6f/0xa0 [ 15.123287][ T435] ? vprintk_emit+0x31b/0x3e0 [ 15.123288][ T435] __might_resched.cold+0x1fe/0x2c1 [ 15.123292][ T435] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 15.123295][ T435] ? rcu_is_watching+0x16/0xd0 [ 15.123297][ T435] __kmalloc_noprof+0x443/0x760 [ 15.123300][ T435] ? __rwlock_init+0x150/0x150 [ 15.123303][ T435] ? alloc_buf.isra.0+0x4b/0x260 [ 15.123308][ T435] ? do_raw_spin_unlock+0x59/0x250 [ 15.123310][ T435] alloc_buf.isra.0+0x4b/0x260 [ 15.123313][ T435] put_chars+0x1e1/0x2f0 [ 15.123314][ T435] ? desc_read_finalized_seq+0x79/0x120 [ 15.123316][ T435] ? __send_to_port+0x420/0x420 [ 15.123318][ T435] ? console_prepend_replay+0x20/0x20 [ 15.123320][ T435] hvc_console_print+0x292/0x780 [ 15.123326][ T435] ? hvc_write+0x3a0/0x3a0 [ 15.123328][ T435] ? do_raw_spin_lock+0x131/0x280 [ 15.123330][ T435] ? __rwlock_init+0x150/0x150 [ 15.123331][ T435] ? rcu_is_watching+0x16/0xd0 [ 15.123332][ T435] ? rcu_is_watching+0x16/0xd0 [ 15.123333][ T435] ? lock_acquire+0x13c/0x160 [ 15.123336][ T435] console_emit_next_record+0x22f/0x4c0 [ 15.123338][ T435] ? devkmsg_read+0x4b0/0x4b0 [ 15.123340][ T435] ? rcu_is_watching+0x16/0xd0 [ 15.123341][ T435] ? rcu_is_watching+0x16/0xd0 [ 15.123342][ T435] ? rcu_is_watching+0x16/0xd0 [ 15.123343][ T435] ? lock_acquire+0x13c/0x160 [ 15.123345][ T435] ? console_flush_one_record+0x111/0x710 [ 15.123347][ T435] console_flush_one_record+0x46f/0x710 [ 15.123349][ T435] ? console_emit_next_record+0x4c0/0x4c0 [ 15.123352][ T435] console_unlock+0xee/0x1f0 [ 15.123353][ T435] ? lock_acquire+0x13c/0x160 [ 15.123355][ T435] ? console_flush_one_record+0x710/0x710 [ 15.123357][ T435] ? rcu_is_watching+0x16/0xd0 [ 15.123358][ T435] ? lock_release+0x184/0x1f0 [ 15.123359][ T435] ? lock_acquire+0xe0/0x160 [ 15.123360][ T435] ? __rcu_read_lock+0x70/0x70 [ 15.123362][ T435] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.123364][ T435] ? vprintk_emit+0x320/0x3e0 [ 15.123365][ T435] vprintk_emit+0x37c/0x3e0 [ 15.123367][ T435] ? wake_up_klogd_work_func+0x90/0x90 [ 15.123369][ T435] ? xfrm_user_exit+0x8a0/0x8a0 [xfrm_user] [ 15.123372][ T435] _printk+0xc7/0x100 [ 15.123375][ T435] ? snapshot_read.cold+0x21/0x21 [ 15.123377][ T435] ? do_one_initcall+0x113/0x4f0 [ 15.123380][ T435] xfrm_user_init+0x15/0x1000 [xfrm_user] [ 15.123382][ T435] do_one_initcall+0x124/0x4f0 [ 15.123384][ T435] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 15.123387][ T435] ? do_init_module+0xae/0x8a0 [ 15.123389][ T435] ? kasan_unpoison+0x44/0x70 [ 15.123392][ T435] do_init_module+0x2ad/0x8a0 [ 15.123398][ T435] ? free_module+0x470/0x470 [ 15.123399][ T435] ? kfree+0x123/0x590 [ 15.123401][ T435] load_module+0x1424/0x1bb0 [ 15.123403][ T435] ? security_kernel_post_read_file+0x2f/0x60 [ 15.123407][ T435] ? layout_and_allocate.isra.0+0x680/0x680 [ 15.123409][ T435] ? lock_acquire+0x13c/0x160 [ 15.123411][ T435] init_module_from_file+0x11f/0x1a0 [ 15.123412][ T435] ? __do_sys_init_module+0x270/0x270 [ 15.123413][ T435] ? rcu_read_unlock+0x20/0x70 [ 15.123416][ T435] ? handle_pte_fault+0x426/0x710 [ 15.123418][ T435] ? rcu_is_watching+0x16/0xd0 [ 15.123418][ T435] ? ovl_mmap+0x270/0x270 [ 15.123422][ T435] ? __rwlock_init+0x150/0x150 [ 15.123423][ T435] ? rcu_is_watching+0x16/0xd0 [ 15.123424][ T435] ? lock_release+0x184/0x1f0 [ 15.123426][ T435] ? do_raw_spin_unlock+0x59/0x250 [ 15.123428][ T435] idempotent_init_module+0x37b/0x7c0 [ 15.123429][ T435] ? init_module_from_file+0x1a0/0x1a0 [ 15.123431][ T435] ? cap_capable+0x1d7/0x3d0 [ 15.123433][ T435] ? lock_release+0x184/0x1f0 [ 15.123435][ T435] __x64_sys_finit_module+0xdf/0x190 [ 15.123436][ T435] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 15.123439][ T435] do_syscall_64+0xff/0x530 [ 15.123443][ T435] ? exc_page_fault+0xee/0x100 [ 15.123445][ T435] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.123447][ T435] RIP: 0033:0x7f229f8f837d [ 15.123449][ T435] 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 [ 15.123451][ T435] RSP: 002b:00007ffd9ce1e998 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 15.123454][ T435] RAX: ffffffffffffffda RBX: 000055a63cc14810 RCX: 00007f229f8f837d [ 15.123455][ T435] RDX: 0000000000000000 RSI: 000055a6050b1ffd RDI: 0000000000000000 [ 15.123456][ T435] RBP: 00007ffd9ce1ea30 R08: 0000000000000000 R09: 0000000000000000 [ 15.123457][ T435] R10: 0000000000000000 R11: 0000000000000246 R12: 000055a6050b1ffd [ 15.123458][ T435] R13: 000055a63cc14960 R14: 0000000000040000 R15: 0000000000000000 [ 15.123460][ T435] [ 27.963280][ T858] ip (858) used greatest stack depth: 23552 bytes left [ 27.963294][ T858] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 27.963296][ T858] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 858, name: ip [ 27.963298][ T858] preempt_count: 2, expected: 0 [ 27.963299][ T858] RCU nest depth: 0, expected: 0 [ 27.963299][ T858] INFO: lockdep is turned off. [ 27.963300][ T858] irq event stamp: 0 [ 27.963301][ T858] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 27.963305][ T858] hardirqs last disabled at (0): [] copy_process+0x1d3b/0x62b0 [ 27.963311][ T858] softirqs last enabled at (0): [] copy_process+0x1d8f/0x62b0 [ 27.963313][ T858] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 27.963314][ T858] Preemption disabled at: [ 27.963315][ T858] [<0000000000000000>] 0x0 [ 27.963320][ T858] CPU: 1 UID: 0 PID: 858 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.963323][ T858] Tainted: [W]=WARN [ 27.963324][ T858] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.963326][ T858] Call Trace: [ 27.963327][ T858] [ 27.963329][ T858] dump_stack_lvl+0x6f/0xa0 [ 27.963335][ T858] __might_resched.cold+0x1fe/0x2c1 [ 27.963339][ T858] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 27.963342][ T858] ? __asan_memcpy+0x3c/0x60 [ 27.963345][ T858] ? rcu_is_watching+0x16/0xd0 [ 27.963348][ T858] __kmalloc_noprof+0x443/0x760 [ 27.963351][ T858] ? __rwlock_init+0x150/0x150 [ 27.963354][ T858] ? alloc_buf.isra.0+0x4b/0x260 [ 27.963358][ T858] ? do_raw_spin_unlock+0x59/0x250 [ 27.963360][ T858] alloc_buf.isra.0+0x4b/0x260 [ 27.963363][ T858] put_chars+0x1e1/0x2f0 [ 27.963364][ T858] ? __send_to_port+0x420/0x420 [ 27.963366][ T858] ? console_prepend_replay+0x20/0x20 [ 27.963369][ T858] hvc_console_print+0x292/0x780 [ 27.963373][ T858] ? hvc_write+0x3a0/0x3a0 [ 27.963375][ T858] ? rcu_is_watching+0x16/0xd0 [ 27.963376][ T858] ? lock_acquire+0x13c/0x160 [ 27.963379][ T858] console_emit_next_record+0x22f/0x4c0 [ 27.963382][ T858] ? devkmsg_read+0x4b0/0x4b0 [ 27.963384][ T858] ? rcu_is_watching+0x16/0xd0 [ 27.963384][ T858] ? rcu_is_watching+0x16/0xd0 [ 27.963385][ T858] ? rcu_is_watching+0x16/0xd0 [ 27.963386][ T858] ? lock_acquire+0x13c/0x160 [ 27.963388][ T858] ? console_flush_one_record+0x111/0x710 [ 27.963390][ T858] console_flush_one_record+0x46f/0x710 [ 27.963392][ T858] ? console_emit_next_record+0x4c0/0x4c0 [ 27.963395][ T858] console_unlock+0xee/0x1f0 [ 27.963397][ T858] ? lock_acquire+0x13c/0x160 [ 27.963399][ T858] ? console_flush_one_record+0x710/0x710 [ 27.963400][ T858] ? rcu_is_watching+0x16/0xd0 [ 27.963401][ T858] ? lock_release+0x184/0x1f0 [ 27.963402][ T858] ? lock_acquire+0xe0/0x160 [ 27.963404][ T858] ? __rcu_read_lock+0x70/0x70 [ 27.963405][ T858] ? __down_trylock_console_sem+0x5e/0xa0 [ 27.963407][ T858] ? vprintk_emit+0x320/0x3e0 [ 27.963409][ T858] vprintk_emit+0x37c/0x3e0 [ 27.963411][ T858] ? wake_up_klogd_work_func+0x90/0x90 [ 27.963412][ T858] ? do_raw_read_unlock+0x70/0x70 [ 27.963414][ T858] ? forget_original_parent+0x5c/0xdb0 [ 27.963416][ T858] _printk+0xc7/0x100 [ 27.963419][ T858] ? snapshot_read.cold+0x21/0x21 [ 27.963420][ T858] ? do_raw_spin_lock+0x131/0x280 [ 27.963422][ T858] ? do_raw_spin_lock+0x131/0x280 [ 27.963424][ T858] ? __rwlock_init+0x150/0x150 [ 27.963426][ T858] ? do_raw_spin_lock+0x131/0x280 [ 27.963428][ T858] do_exit.cold+0x82/0x9c [ 27.963430][ T858] ? exit_notify+0x890/0x890 [ 27.963432][ T858] ? posixtimer_sig_unignore.isra.0+0x5f0/0x5f0 [ 27.963434][ T858] ? rcu_is_watching+0x16/0xd0 [ 27.963436][ T858] do_group_exit+0xb8/0x370 [ 27.963437][ T858] __x64_sys_exit_group+0x3c/0x50 [ 27.963439][ T858] x64_sys_call+0x1567/0x1570 [ 27.963449][ T858] do_syscall_64+0xff/0x530 [ 27.963452][ T858] ? exc_page_fault+0xee/0x100 [ 27.963454][ T858] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.963456][ T858] RIP: 0033:0x7f643994a1b8 [ 27.963458][ T858] Code: Unable to access opcode bytes at 0x7f643994a18e. [ 27.963459][ T858] RSP: 002b:00007ffcb8a8ca08 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 27.963462][ T858] RAX: ffffffffffffffda RBX: 00007f6439a7af88 RCX: 00007f643994a1b8 [ 27.963463][ T858] RDX: 00007f6439694fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 27.963464][ T858] RBP: 00007ffcb8a8ca60 R08: 0000000000000000 R09: 0000000000000050 [ 27.963465][ T858] R10: 00007ffcb8a8c820 R11: 0000000000000246 R12: 0000000000000001 [ 27.963465][ T858] R13: 0000000000000000 R14: 00007f6439a79680 R15: 00007f6439a7afa0 [ 27.963468][ T858]