[ 10.483642][ C0] clocksource: Watchdog remote CPU 3 read timed out [ 10.483903][ C0] [ 10.483904][ C0] ======================================================== [ 10.483906][ C0] WARNING: possible irq lock inversion dependency detected [ 10.483908][ C0] 7.2.0-virtme #1 Tainted: G W [ 10.483909][ C0] -------------------------------------------------------- [ 10.483910][ C0] modprobe/226 just changed the state of lock: [ 10.483911][ C0] ffffffff8d69d760 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.483923][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 10.483924][ C0] (fs_reclaim){+.+.}-{0:0} [ 10.483926][ C0] [ 10.483926][ C0] [ 10.483926][ C0] and interrupts could create inverse lock ordering between them. [ 10.483926][ C0] [ 10.483926][ C0] [ 10.483926][ C0] other info that might help us debug this: [ 10.483927][ C0] Possible interrupt unsafe locking scenario: [ 10.483927][ C0] [ 10.483928][ C0] CPU0 CPU1 [ 10.483928][ C0] ---- ---- [ 10.483928][ C0] lock(fs_reclaim); [ 10.483929][ C0] local_irq_disable(); [ 10.483930][ C0] lock(console_owner); [ 10.483931][ C0] lock(fs_reclaim); [ 10.483932][ C0] [ 10.483932][ C0] lock(console_owner); [ 10.483933][ C0] [ 10.483933][ C0] *** DEADLOCK *** [ 10.483933][ C0] [ 10.483933][ C0] locks held by modprobe/226: 4, last CPU#0: [ 10.483934][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 10.483940][ C0] #1: ffffffff8d7e29f8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 10.483944][ C0] #2: ffffffff8d77ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.483947][ C0] #3: ffffffff8d77de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.483950][ C0] [ 10.483950][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 10.483955][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 10.483957][ C0] HARDIRQ-ON-W at: [ 10.483959][ C0] __lock_acquire+0x388/0xc20 [ 10.483962][ C0] lock_acquire.part.0+0xd4/0x280 [ 10.483963][ C0] fs_reclaim_acquire+0xd5/0x120 [ 10.483967][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 10.483968][ C0] kthread_create_worker_on_node+0xea/0x210 [ 10.483972][ C0] workqueue_init+0x2a/0x680 [ 10.483976][ C0] kernel_init_freeable+0x2fe/0x630 [ 10.483979][ C0] kernel_init+0x21/0x150 [ 10.483982][ C0] ret_from_fork+0x474/0x6b0 [ 10.483985][ C0] ret_from_fork_asm+0x11/0x20 [ 10.483988][ C0] SOFTIRQ-ON-W at: [ 10.483989][ C0] __lock_acquire+0x388/0xc20 [ 10.483990][ C0] lock_acquire.part.0+0xd4/0x280 [ 10.483992][ C0] fs_reclaim_acquire+0xd5/0x120 [ 10.483993][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 10.483994][ C0] kthread_create_worker_on_node+0xea/0x210 [ 10.483995][ C0] workqueue_init+0x2a/0x680 [ 10.483997][ C0] kernel_init_freeable+0x2fe/0x630 [ 10.483998][ C0] kernel_init+0x21/0x150 [ 10.484000][ C0] ret_from_fork+0x474/0x6b0 [ 10.484001][ C0] ret_from_fork_asm+0x11/0x20 [ 10.484002][ C0] INITIAL USE at: [ 10.484003][ C0] __lock_acquire+0x388/0xc20 [ 10.484004][ C0] lock_acquire.part.0+0xd4/0x280 [ 10.484006][ C0] fs_reclaim_acquire+0xd5/0x120 [ 10.484007][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 10.484008][ C0] kthread_create_worker_on_node+0xea/0x210 [ 10.484009][ C0] workqueue_init+0x2a/0x680 [ 10.484011][ C0] kernel_init_freeable+0x2fe/0x630 [ 10.484012][ C0] kernel_init+0x21/0x150 [ 10.484014][ C0] ret_from_fork+0x474/0x6b0 [ 10.484015][ C0] ret_from_fork_asm+0x11/0x20 [ 10.484016][ C0] } [ 10.484016][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 10.484020][ C0] ... acquired at: [ 10.484021][ C0] __lock_acquire+0x518/0xc20 [ 10.484023][ C0] lock_acquire.part.0+0xd4/0x280 [ 10.484024][ C0] fs_reclaim_acquire+0xd5/0x120 [ 10.484025][ C0] __kmalloc_noprof+0xd3/0x760 [ 10.484026][ C0] alloc_buf.isra.0+0x4b/0x260 [ 10.484030][ C0] put_chars+0x1e1/0x2f0 [ 10.484031][ C0] hvc_console_print+0x292/0x780 [ 10.484035][ C0] console_emit_next_record+0x22f/0x4c0 [ 10.484036][ C0] console_flush_one_record+0x46f/0x710 [ 10.484038][ C0] console_unlock+0xee/0x1f0 [ 10.484039][ C0] vprintk_emit+0x37c/0x3e0 [ 10.484040][ C0] dev_vprintk_emit+0x27f/0x2c0 [ 10.484044][ C0] dev_printk_emit+0xb9/0xee [ 10.484045][ C0] _dev_info+0xe2/0x116 [ 10.484046][ C0] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 10.484049][ C0] cmos_do_probe+0x73b/0x98a [ 10.484051][ C0] platform_probe+0xfe/0x1f0 [ 10.484054][ C0] call_driver_probe+0x61/0x1c0 [ 10.484055][ C0] really_probe+0x199/0x760 [ 10.484057][ C0] __driver_probe_device+0x24f/0x440 [ 10.484058][ C0] driver_probe_device+0x4a/0xf0 [ 10.484059][ C0] __driver_attach+0x1b8/0x540 [ 10.484060][ C0] bus_for_each_dev+0x130/0x1e0 [ 10.484062][ C0] bus_add_driver+0x2c8/0x530 [ 10.484063][ C0] driver_register+0x1a3/0x390 [ 10.484064][ C0] __platform_driver_probe+0x13f/0x270 [ 10.484066][ C0] cmos_init+0x31/0x40 [ 10.484068][ C0] do_one_initcall+0x124/0x4f0 [ 10.484069][ C0] kernel_init_freeable+0x596/0x630 [ 10.484071][ C0] kernel_init+0x21/0x150 [ 10.484072][ C0] ret_from_fork+0x474/0x6b0 [ 10.484073][ C0] ret_from_fork_asm+0x11/0x20 [ 10.484075][ C0] [ 10.484075][ C0] -> (console_owner){..-.}-{0:0} { [ 10.484077][ C0] IN-SOFTIRQ-W at: [ 10.484077][ C0] __lock_acquire+0x388/0xc20 [ 10.484079][ C0] lock_acquire.part.0+0xd4/0x280 [ 10.484080][ C0] console_lock_spinning_enable+0x5c/0x60 [ 10.484082][ C0] console_emit_next_record+0x1d1/0x4c0 [ 10.484083][ C0] console_flush_one_record+0x46f/0x710 [ 10.484085][ C0] console_unlock+0xee/0x1f0 [ 10.484086][ C0] vprintk_emit+0x37c/0x3e0 [ 10.484087][ C0] _printk+0xc7/0x100 [ 10.484090][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 10.484092][ C0] call_timer_fn+0x160/0x4d0 [ 10.484094][ C0] __run_timers+0x68f/0xaa0 [ 10.484095][ C0] run_timer_softirq+0xf0/0x160 [ 10.484097][ C0] handle_softirqs+0x1d3/0x900 [ 10.484100][ C0] __irq_exit_rcu+0x145/0x1c0 [ 10.484101][ C0] irq_exit_rcu+0xe/0x30 [ 10.484102][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 10.484104][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 10.484105][ C0] unwind_next_frame+0x127/0x1ea0 [ 10.484108][ C0] arch_stack_walk+0xd7/0x130 [ 10.484109][ C0] stack_trace_save+0x93/0xc0 [ 10.484111][ C0] set_track_prepare+0x62/0x90 [ 10.484113][ C0] ___slab_alloc+0xbf/0x2f0 [ 10.484114][ C0] kmem_cache_alloc_noprof+0x2fc/0x5c0 [ 10.484115][ C0] alloc_empty_backing_file+0x46/0x170 [ 10.484118][ C0] backing_file_open+0x5a/0xf0 [ 10.484121][ C0] ovl_open_realfile+0x2bb/0x370 [ 10.484123][ C0] ovl_open+0x1ab/0x2f0 [ 10.484124][ C0] do_dentry_open+0x413/0x1270 [ 10.484127][ C0] vfs_open+0x7a/0x440 [ 10.484128][ C0] do_open+0x565/0xd50 [ 10.484130][ C0] path_openat+0x1d0/0x3b0 [ 10.484131][ C0] do_file_open+0x209/0x480 [ 10.484132][ C0] do_sys_openat2+0xe0/0x170 [ 10.484133][ C0] __x64_sys_openat+0x10e/0x210 [ 10.484134][ C0] do_syscall_64+0xff/0x530 [ 10.484137][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.484138][ C0] INITIAL USE at: [ 10.484139][ C0] } [ 10.484139][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 10.484141][ C0] ... acquired at: [ 10.484142][ C0] mark_lock+0x1d7/0xa00 [ 10.484143][ C0] mark_usage+0x42/0x170 [ 10.484144][ C0] __lock_acquire+0x388/0xc20 [ 10.484146][ C0] lock_acquire.part.0+0xd4/0x280 [ 10.484147][ C0] console_lock_spinning_enable+0x5c/0x60 [ 10.484149][ C0] console_emit_next_record+0x1d1/0x4c0 [ 10.484150][ C0] console_flush_one_record+0x46f/0x710 [ 10.484152][ C0] console_unlock+0xee/0x1f0 [ 10.484153][ C0] vprintk_emit+0x37c/0x3e0 [ 10.484154][ C0] _printk+0xc7/0x100 [ 10.484155][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 10.484156][ C0] call_timer_fn+0x160/0x4d0 [ 10.484158][ C0] __run_timers+0x68f/0xaa0 [ 10.484159][ C0] run_timer_softirq+0xf0/0x160 [ 10.484161][ C0] handle_softirqs+0x1d3/0x900 [ 10.484162][ C0] __irq_exit_rcu+0x145/0x1c0 [ 10.484163][ C0] irq_exit_rcu+0xe/0x30 [ 10.484164][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 10.484165][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 10.484167][ C0] unwind_next_frame+0x127/0x1ea0 [ 10.484168][ C0] arch_stack_walk+0xd7/0x130 [ 10.484169][ C0] stack_trace_save+0x93/0xc0 [ 10.484170][ C0] set_track_prepare+0x62/0x90 [ 10.484171][ C0] ___slab_alloc+0xbf/0x2f0 [ 10.484173][ C0] kmem_cache_alloc_noprof+0x2fc/0x5c0 [ 10.484173][ C0] alloc_empty_backing_file+0x46/0x170 [ 10.484175][ C0] backing_file_open+0x5a/0xf0 [ 10.484176][ C0] ovl_open_realfile+0x2bb/0x370 [ 10.484177][ C0] ovl_open+0x1ab/0x2f0 [ 10.484178][ C0] do_dentry_open+0x413/0x1270 [ 10.484179][ C0] vfs_open+0x7a/0x440 [ 10.484180][ C0] do_open+0x565/0xd50 [ 10.484182][ C0] path_openat+0x1d0/0x3b0 [ 10.484182][ C0] do_file_open+0x209/0x480 [ 10.484183][ C0] do_sys_openat2+0xe0/0x170 [ 10.484184][ C0] __x64_sys_openat+0x10e/0x210 [ 10.484185][ C0] do_syscall_64+0xff/0x530 [ 10.484187][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.484187][ C0] [ 10.484188][ C0] [ 10.484188][ C0] stack backtrace: [ 10.484191][ C0] CPU: 0 UID: 0 PID: 226 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.484194][ C0] Tainted: [W]=WARN [ 10.484194][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.484196][ C0] Call Trace: [ 10.484197][ C0] [ 10.484198][ C0] dump_stack_lvl+0x6f/0xa0 [ 10.484203][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 10.484205][ C0] mark_lock_irq+0x989/0x9c0 [ 10.484207][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 10.484209][ C0] mark_lock+0x1d7/0xa00 [ 10.484211][ C0] mark_usage+0x42/0x170 [ 10.484213][ C0] __lock_acquire+0x388/0xc20 [ 10.484215][ C0] lock_acquire.part.0+0xd4/0x280 [ 10.484217][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 10.484219][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.484221][ C0] ? lock_acquire+0x13c/0x160 [ 10.484223][ C0] console_lock_spinning_enable+0x5c/0x60 [ 10.484225][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 10.484226][ C0] console_emit_next_record+0x1d1/0x4c0 [ 10.484228][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 10.484231][ C0] ? console_flush_one_record+0x106/0x710 [ 10.484233][ C0] console_flush_one_record+0x46f/0x710 [ 10.484235][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 10.484237][ C0] ? __lock_acquire+0x518/0xc20 [ 10.484239][ C0] console_unlock+0xee/0x1f0 [ 10.484241][ C0] ? console_flush_one_record+0x710/0x710 [ 10.484243][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.484244][ C0] ? lock_acquire+0xe0/0x160 [ 10.484245][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.484248][ C0] ? vprintk_emit+0x320/0x3e0 [ 10.484249][ C0] vprintk_emit+0x37c/0x3e0 [ 10.484250][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 10.484252][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 10.484254][ C0] _printk+0xc7/0x100 [ 10.484255][ C0] ? snapshot_read.cold+0x21/0x21 [ 10.484257][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 10.484259][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 10.484261][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 10.484263][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 10.484264][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 10.484266][ C0] call_timer_fn+0x160/0x4d0 [ 10.484268][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 10.484270][ C0] ? debug_object_active_state+0x430/0x430 [ 10.484272][ C0] ? find_held_lock+0x2b/0x80 [ 10.484274][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 10.484276][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.484277][ C0] __run_timers+0x68f/0xaa0 [ 10.484279][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 10.484281][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 10.484283][ C0] ? __lock_acquire+0x518/0xc20 [ 10.484286][ C0] ? __rwlock_init+0x150/0x150 [ 10.484288][ C0] run_timer_softirq+0xf0/0x160 [ 10.484290][ C0] ? __run_timers+0xaa0/0xaa0 [ 10.484292][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 10.484295][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.484296][ C0] handle_softirqs+0x1d3/0x900 [ 10.484298][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 10.484299][ C0] ? _local_bh_enable+0xc0/0xc0 [ 10.484301][ C0] __irq_exit_rcu+0x145/0x1c0 [ 10.484303][ C0] irq_exit_rcu+0xe/0x30 [ 10.484304][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 10.484306][ C0] [ 10.484306][ C0] [ 10.484307][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 10.484309][ C0] RIP: 0010:unwind_next_frame+0x127/0x1ea0 [ 10.484311][ C0] Code: 48 8b ac 24 d0 00 00 00 4c 8b a4 24 d8 00 00 00 4c 8b ac 24 e0 00 00 00 48 81 c4 f8 00 00 00 c3 48 b8 00 00 00 00 00 fc ff df <4d> 8d 65 58 4c 89 e2 48 c1 ea 03 80 3c 02 00 0f 85 74 17 00 00 49 [ 10.484312][ C0] RSP: 0018:ffa0000000527528 EFLAGS: 00000202 [ 10.484315][ C0] RAX: dffffc0000000000 RBX: 1ff40000000a4eae RCX: dffffc0000000001 [ 10.484316][ C0] RDX: dffffc0000000000 RSI: 0000000000000010 RDI: ffa0000000527628 [ 10.484317][ C0] RBP: 0000000000000001 R08: 0000000000000000 R09: ffffffff8e288ee8 [ 10.484317][ C0] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000 [ 10.484318][ C0] R13: ffa0000000527628 R14: ff1100000feac740 R15: 0000000000000000 [ 10.484320][ C0] ? unwind_next_frame+0x69b/0x1ea0 [ 10.484322][ C0] ? backing_file_open+0x5a/0xf0 [ 10.484324][ C0] ? stack_access_ok+0x1e0/0x1e0 [ 10.484325][ C0] ? mark_usage+0x61/0x170 [ 10.484327][ C0] ? __unwind_start+0x42e/0x900 [ 10.484329][ C0] ? write_profile+0xf0/0xf0 [ 10.484330][ C0] arch_stack_walk+0xd7/0x130 [ 10.484332][ C0] ? ovl_open_realfile+0x2bb/0x370 [ 10.484334][ C0] ? alloc_empty_backing_file+0x46/0x170 [ 10.484335][ C0] stack_trace_save+0x93/0xc0 [ 10.484337][ C0] ? filter_irq_stacks+0xd0/0xd0 [ 10.484338][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.484339][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 10.484341][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 10.484342][ C0] set_track_prepare+0x62/0x90 [ 10.484344][ C0] ? alloc_empty_backing_file+0x46/0x170 [ 10.484345][ C0] ? backing_file_open+0x5a/0xf0 [ 10.484347][ C0] ? ovl_open_realfile+0x2bb/0x370 [ 10.484349][ C0] ___slab_alloc+0xbf/0x2f0 [ 10.484351][ C0] ? __pcs_replace_empty_main+0x42f/0x6a0 [ 10.484352][ C0] kmem_cache_alloc_noprof+0x2fc/0x5c0 [ 10.484353][ C0] ? alloc_empty_backing_file+0x46/0x170 [ 10.484355][ C0] alloc_empty_backing_file+0x46/0x170 [ 10.484357][ C0] backing_file_open+0x5a/0xf0 [ 10.484359][ C0] ? ns_capable+0x59/0xb0 [ 10.484361][ C0] ovl_open_realfile+0x2bb/0x370 [ 10.484363][ C0] ovl_open+0x1ab/0x2f0 [ 10.484364][ C0] ? ovl_llseek+0x2e0/0x2e0 [ 10.484366][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 10.484367][ C0] ? ovl_llseek+0x2e0/0x2e0 [ 10.484369][ C0] do_dentry_open+0x413/0x1270 [ 10.484371][ C0] ? inode_permission+0x2d8/0x510 [ 10.484373][ C0] vfs_open+0x7a/0x440 [ 10.484374][ C0] ? security_inode_permission+0x5b/0x90 [ 10.484376][ C0] ? may_open+0xe6/0x330 [ 10.484378][ C0] do_open+0x565/0xd50 [ 10.484380][ C0] ? open_last_lookups+0x7e3/0x1030 [ 10.484382][ C0] path_openat+0x1d0/0x3b0 [ 10.484383][ C0] ? rcu_read_lock_any_held+0x3c/0x90 [ 10.484385][ C0] ? kernel_tmpfile_open+0x70/0x70 [ 10.484387][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.484388][ C0] do_file_open+0x209/0x480 [ 10.484390][ C0] ? path_openat+0x3b0/0x3b0 [ 10.484391][ C0] ? __lock_acquire+0x518/0xc20 [ 10.484394][ C0] ? _raw_spin_unlock+0x2d/0x50 [ 10.484396][ C0] ? alloc_fd+0x33a/0x600 [ 10.484400][ C0] do_sys_openat2+0xe0/0x170 [ 10.484401][ C0] ? build_open_flags+0x6e0/0x6e0 [ 10.484403][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.484404][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 10.484405][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 10.484407][ C0] __x64_sys_openat+0x10e/0x210 [ 10.484408][ C0] ? fput_close_sync+0xde/0x1b0 [ 10.484410][ C0] ? __ia32_sys_open+0x1b0/0x1b0 [ 10.484411][ C0] ? fpregs_restore_userregs+0x14b/0x300 [ 10.484413][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.484414][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 10.484416][ C0] do_syscall_64+0xff/0x530 [ 10.484417][ C0] ? exc_page_fault+0xee/0x100 [ 10.484419][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.484420][ C0] RIP: 0033:0x7f40af57f54e [ 10.484423][ C0] Code: 4d 89 d8 e8 b4 bd 00 00 4c 8b 5d f8 41 8b 93 08 03 00 00 59 5e 48 83 f8 fc 74 11 c9 c3 0f 1f 80 00 00 00 00 48 8b 45 10 0f 05 c3 83 e2 39 83 fa 08 75 e7 e8 03 ff ff ff 0f 1f 00 f3 0f 1e fa [ 10.484424][ C0] RSP: 002b:00007ffd0d1ed450 EFLAGS: 00000202 ORIG_RAX: 0000000000000101 [ 10.484425][ C0] RAX: ffffffffffffffda RBX: 00005589a845e120 RCX: 00007f40af57f54e [ 10.484426][ C0] RDX: 0000000000080000 RSI: 00007ffd0d1ee6a0 RDI: ffffffffffffff9c [ 10.484427][ C0] RBP: 00007ffd0d1ed460 R08: 0000000000000000 R09: 0000000000000000 [ 10.484428][ C0] R10: 0000000000000000 R11: 0000000000000202 R12: 0000000000000001 [ 10.484428][ C0] R13: 0000558995674bd0 R14: 0000000000000000 R15: 0000000000000001 [ 10.484430][ C0] [ 10.484434][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.484435][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 226, name: modprobe [ 10.484437][ C0] preempt_count: 102, expected: 0 [ 10.484437][ C0] RCU nest depth: 0, expected: 0 [ 10.484438][ C0] INFO: lockdep is turned off. [ 10.484439][ C0] irq event stamp: 15459 [ 10.484439][ C0] hardirqs last enabled at (15458): [] irqentry_exit+0x21c/0x790 [ 10.484441][ C0] hardirqs last disabled at (15459): [] console_emit_next_record+0x3d4/0x4c0 [ 10.484443][ C0] softirqs last enabled at (14806): [] handle_softirqs+0x67c/0x900 [ 10.484445][ C0] softirqs last disabled at (15443): [] __irq_exit_rcu+0x145/0x1c0 [ 10.484446][ C0] Preemption disabled at: [ 10.484447][ C0] [] handle_softirqs+0xf6/0x900 [ 10.484449][ C0] CPU: 0 UID: 0 PID: 226 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.484451][ C0] Tainted: [W]=WARN [ 10.484452][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.484452][ C0] Call Trace: [ 10.484453][ C0] [ 10.484453][ C0] dump_stack_lvl+0x6f/0xa0 [ 10.484456][ C0] ? handle_softirqs+0xf6/0x900 [ 10.484457][ C0] __might_resched.cold+0x1fe/0x2c1 [ 10.484460][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.484463][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.484464][ C0] __kmalloc_noprof+0x443/0x760 [ 10.484465][ C0] ? __rwlock_init+0x150/0x150 [ 10.484467][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 10.484469][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 10.484471][ C0] alloc_buf.isra.0+0x4b/0x260 [ 10.484473][ C0] put_chars+0x1e1/0x2f0 [ 10.484475][ C0] ? __send_to_port+0x420/0x420 [ 10.484477][ C0] ? mark_lock+0x1d7/0xa00 [ 10.484479][ C0] hvc_console_print+0x292/0x780 [ 10.484482][ C0] ? hvc_write+0x3a0/0x3a0 [ 10.484483][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.484484][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.484486][ C0] console_emit_next_record+0x22f/0x4c0 [ 10.484488][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 10.484491][ C0] ? console_flush_one_record+0x106/0x710 [ 10.484493][ C0] console_flush_one_record+0x46f/0x710 [ 10.484495][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 10.484497][ C0] ? __lock_acquire+0x518/0xc20 [ 10.484499][ C0] console_unlock+0xee/0x1f0 [ 10.484501][ C0] ? console_flush_one_record+0x710/0x710 [ 10.484502][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.484503][ C0] ? lock_acquire+0xe0/0x160 [ 10.484505][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.484507][ C0] ? vprintk_emit+0x320/0x3e0 [ 10.484508][ C0] vprintk_emit+0x37c/0x3e0 [ 10.484512][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 10.484513][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 10.484515][ C0] _printk+0xc7/0x100 [ 10.484517][ C0] ? snapshot_read.cold+0x21/0x21 [ 10.484519][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 10.484520][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 10.484523][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 10.484524][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 10.484526][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 10.484527][ C0] call_timer_fn+0x160/0x4d0 [ 10.484529][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 10.484531][ C0] ? debug_object_active_state+0x430/0x430 [ 10.484532][ C0] ? find_held_lock+0x2b/0x80 [ 10.484534][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 10.484536][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.484537][ C0] __run_timers+0x68f/0xaa0 [ 10.484539][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 10.484541][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 10.484543][ C0] ? __lock_acquire+0x518/0xc20 [ 10.484545][ C0] ? __rwlock_init+0x150/0x150 [ 10.484548][ C0] run_timer_softirq+0xf0/0x160 [ 10.484550][ C0] ? __run_timers+0xaa0/0xaa0 [ 10.484551][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 10.484553][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.484554][ C0] handle_softirqs+0x1d3/0x900 [ 10.484556][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 10.484557][ C0] ? _local_bh_enable+0xc0/0xc0 [ 10.484559][ C0] __irq_exit_rcu+0x145/0x1c0 [ 10.484561][ C0] irq_exit_rcu+0xe/0x30 [ 10.484562][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 10.484564][ C0] [ 10.484564][ C0] [ 10.484565][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 10.484566][ C0] RIP: 0010:unwind_next_frame+0x127/0x1ea0 [ 10.484568][ C0] Code: 48 8b ac 24 d0 00 00 00 4c 8b a4 24 d8 00 00 00 4c 8b ac 24 e0 00 00 00 48 81 c4 f8 00 00 00 c3 48 b8 00 00 00 00 00 fc ff df <4d> 8d 65 58 4c 89 e2 48 c1 ea 03 80 3c 02 00 0f 85 74 17 00 00 49 [ 10.484569][ C0] RSP: 0018:ffa0000000527528 EFLAGS: 00000202 [ 10.484570][ C0] RAX: dffffc0000000000 RBX: 1ff40000000a4eae RCX: dffffc0000000001 [ 10.484570][ C0] RDX: dffffc0000000000 RSI: 0000000000000010 RDI: ffa0000000527628 [ 10.484571][ C0] RBP: 0000000000000001 R08: 0000000000000000 R09: ffffffff8e288ee8 [ 10.484572][ C0] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000 [ 10.484572][ C0] R13: ffa0000000527628 R14: ff1100000feac740 R15: 0000000000000000 [ 10.484574][ C0] ? unwind_next_frame+0x69b/0x1ea0 [ 10.484576][ C0] ? backing_file_open+0x5a/0xf0 [ 10.484578][ C0] ? stack_access_ok+0x1e0/0x1e0 [ 10.484579][ C0] ? mark_usage+0x61/0x170 [ 10.484581][ C0] ? __unwind_start+0x42e/0x900 [ 10.484583][ C0] ? write_profile+0xf0/0xf0 [ 10.484584][ C0] arch_stack_walk+0xd7/0x130 [ 10.484586][ C0] ? ovl_open_realfile+0x2bb/0x370 [ 10.484588][ C0] ? alloc_empty_backing_file+0x46/0x170 [ 10.484589][ C0] stack_trace_save+0x93/0xc0 [ 10.484591][ C0] ? filter_irq_stacks+0xd0/0xd0 [ 10.484592][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.484593][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 10.484595][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 10.484596][ C0] set_track_prepare+0x62/0x90 [ 10.484598][ C0] ? alloc_empty_backing_file+0x46/0x170 [ 10.484599][ C0] ? backing_file_open+0x5a/0xf0 [ 10.484600][ C0] ? ovl_open_realfile+0x2bb/0x370 [ 10.484602][ C0] ___slab_alloc+0xbf/0x2f0 [ 10.484604][ C0] ? __pcs_replace_empty_main+0x42f/0x6a0 [ 10.484606][ C0] kmem_cache_alloc_noprof+0x2fc/0x5c0 [ 10.484607][ C0] ? alloc_empty_backing_file+0x46/0x170 [ 10.484609][ C0] alloc_empty_backing_file+0x46/0x170 [ 10.484611][ C0] backing_file_open+0x5a/0xf0 [ 10.484612][ C0] ? ns_capable+0x59/0xb0 [ 10.484614][ C0] ovl_open_realfile+0x2bb/0x370 [ 10.484616][ C0] ovl_open+0x1ab/0x2f0 [ 10.484618][ C0] ? ovl_llseek+0x2e0/0x2e0 [ 10.484619][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 10.484621][ C0] ? ovl_llseek+0x2e0/0x2e0 [ 10.484622][ C0] do_dentry_open+0x413/0x1270 [ 10.484624][ C0] ? inode_permission+0x2d8/0x510 [ 10.484626][ C0] vfs_open+0x7a/0x440 [ 10.484627][ C0] ? security_inode_permission+0x5b/0x90 [ 10.484629][ C0] ? may_open+0xe6/0x330 [ 10.484630][ C0] do_open+0x565/0xd50 [ 10.484632][ C0] ? open_last_lookups+0x7e3/0x1030 [ 10.484634][ C0] path_openat+0x1d0/0x3b0 [ 10.484635][ C0] ? rcu_read_lock_any_held+0x3c/0x90 [ 10.484637][ C0] ? kernel_tmpfile_open+0x70/0x70 [ 10.484638][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.484640][ C0] do_file_open+0x209/0x480 [ 10.484641][ C0] ? path_openat+0x3b0/0x3b0 [ 10.484642][ C0] ? __lock_acquire+0x518/0xc20 [ 10.484646][ C0] ? _raw_spin_unlock+0x2d/0x50 [ 10.484647][ C0] ? alloc_fd+0x33a/0x600 [ 10.484649][ C0] do_sys_openat2+0xe0/0x170 [ 10.484650][ C0] ? build_open_flags+0x6e0/0x6e0 [ 10.484652][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.484653][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 10.484654][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 10.484656][ C0] __x64_sys_openat+0x10e/0x210 [ 10.484657][ C0] ? fput_close_sync+0xde/0x1b0 [ 10.484659][ C0] ? __ia32_sys_open+0x1b0/0x1b0 [ 10.484660][ C0] ? fpregs_restore_userregs+0x14b/0x300 [ 10.484661][ C0] ? rcu_is_watching+0x16/0xd0 [ 10.484663][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 10.484664][ C0] do_syscall_64+0xff/0x530 [ 10.484666][ C0] ? exc_page_fault+0xee/0x100 [ 10.484667][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.484668][ C0] RIP: 0033:0x7f40af57f54e [ 10.484669][ C0] Code: 4d 89 d8 e8 b4 bd 00 00 4c 8b 5d f8 41 8b 93 08 03 00 00 59 5e 48 83 f8 fc 74 11 c9 c3 0f 1f 80 00 00 00 00 48 8b 45 10 0f 05 c3 83 e2 39 83 fa 08 75 e7 e8 03 ff ff ff 0f 1f 00 f3 0f 1e fa [ 10.484670][ C0] RSP: 002b:00007ffd0d1ed450 EFLAGS: 00000202 ORIG_RAX: 0000000000000101 [ 10.484671][ C0] RAX: ffffffffffffffda RBX: 00005589a845e120 RCX: 00007f40af57f54e [ 10.484672][ C0] RDX: 0000000000080000 RSI: 00007ffd0d1ee6a0 RDI: ffffffffffffff9c [ 10.484673][ C0] RBP: 00007ffd0d1ed460 R08: 0000000000000000 R09: 0000000000000000 [ 10.484673][ C0] R10: 0000000000000000 R11: 0000000000000202 R12: 0000000000000001 [ 10.484674][ C0] R13: 0000558995674bd0 R14: 0000000000000000 R15: 0000000000000001 [ 10.484676][ C0]