[ 9.887888][ T187] ip (187) used greatest stack depth: 24032 bytes left [ 9.887903][ T187] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.887905][ T187] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 187, name: ip [ 9.887907][ T187] preempt_count: 2, expected: 0 [ 9.887908][ T187] RCU nest depth: 0, expected: 0 [ 9.887909][ T187] locks held by ip/187: 5, last CPU#2: [ 9.887911][ T187] #0: ffffffff89a027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 9.887923][ T187] #1: ffffffff89b69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.887929][ T187] #2: ffffffff89b69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.887932][ T187] #3: ffffffff89a89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.887936][ T187] #4: ffffffff89a89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.887940][ T187] irq event stamp: 35702 [ 9.887941][ T187] hardirqs last enabled at (35701): [] __down_trylock_console_sem+0x86/0xa0 [ 9.887943][ T187] hardirqs last disabled at (35702): [] console_emit_next_record+0x3d4/0x4c0 [ 9.887945][ T187] softirqs last enabled at (35076): [] handle_softirqs+0x67c/0x900 [ 9.887947][ T187] softirqs last disabled at (35071): [] __irq_exit_rcu+0x145/0x1c0 [ 9.887949][ T187] Preemption disabled at: [ 9.887950][ T187] [<0000000000000000>] 0x0 [ 9.887956][ T187] CPU: 2 UID: 0 PID: 187 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.887960][ T187] Tainted: [W]=WARN [ 9.887961][ T187] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.887962][ T187] Call Trace: [ 9.887964][ T187] [ 9.887965][ T187] dump_stack_lvl+0x6f/0xa0 [ 9.887971][ T187] __might_resched.cold+0x1fe/0x2c1 [ 9.887976][ T187] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.887980][ T187] ? __kmalloc_noprof+0xdb/0x760 [ 9.887985][ T187] __kmalloc_noprof+0x443/0x760 [ 9.887987][ T187] ? alloc_buf.isra.0+0x4b/0x260 [ 9.887993][ T187] ? do_raw_spin_unlock+0x59/0x250 [ 9.887996][ T187] alloc_buf.isra.0+0x4b/0x260 [ 9.887999][ T187] put_chars+0x1e1/0x2f0 [ 9.888002][ T187] ? __send_to_port+0x420/0x420 [ 9.888003][ T187] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.888008][ T187] ? validate_chain+0x38b/0xc20 [ 9.888013][ T187] hvc_console_print+0x292/0x780 [ 9.888019][ T187] ? hvc_write+0x3a0/0x3a0 [ 9.888021][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.888024][ T187] ? lock_acquire+0x13c/0x160 [ 9.888028][ T187] console_emit_next_record+0x22f/0x4c0 [ 9.888031][ T187] ? devkmsg_read+0x4b0/0x4b0 [ 9.888033][ T187] ? console_flush_one_record+0x106/0x710 [ 9.888036][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.888039][ T187] ? lock_acquire+0x13c/0x160 [ 9.888043][ T187] console_flush_one_record+0x46f/0x710 [ 9.888047][ T187] ? console_emit_next_record+0x4c0/0x4c0 [ 9.888049][ T187] ? __lock_acquire+0x518/0xc20 [ 9.888054][ T187] console_unlock+0xee/0x1f0 [ 9.888057][ T187] ? console_flush_one_record+0x710/0x710 [ 9.888058][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.888061][ T187] ? lock_acquire+0x60/0x160 [ 9.888065][ T187] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.888067][ T187] ? vprintk_emit+0x320/0x3e0 [ 9.888071][ T187] vprintk_emit+0x37c/0x3e0 [ 9.888075][ T187] ? wake_up_klogd_work_func+0x90/0x90 [ 9.888079][ T187] ? __lock_acquire+0x518/0xc20 [ 9.888082][ T187] _printk+0xc7/0x100 [ 9.888086][ T187] ? snapshot_read.cold+0x21/0x21 [ 9.888089][ T187] ? do_raw_spin_lock+0x131/0x280 [ 9.888091][ T187] ? __rwlock_init+0x150/0x150 [ 9.888095][ T187] ? do_raw_spin_lock+0x131/0x280 [ 9.888098][ T187] do_exit.cold+0x82/0x9c [ 9.888101][ T187] ? exit_notify+0x890/0x890 [ 9.888103][ T187] ? __lock_release.isra.0+0x69/0x1a0 [ 9.888106][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.888110][ T187] do_group_exit+0xb8/0x370 [ 9.888113][ T187] __x64_sys_exit_group+0x3c/0x50 [ 9.888115][ T187] x64_sys_call+0x1567/0x1570 [ 9.888117][ T187] do_syscall_64+0xff/0x530 [ 9.888120][ T187] ? exc_page_fault+0xee/0x100 [ 9.888123][ T187] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.888125][ T187] RIP: 0033:0x7fdfd79021b8 [ 9.888127][ T187] Code: Unable to access opcode bytes at 0x7fdfd790218e. [ 9.888129][ T187] RSP: 002b:00007ffc753b2e38 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 9.888131][ T187] RAX: ffffffffffffffda RBX: 00007fdfd7a32f88 RCX: 00007fdfd79021b8 [ 9.888133][ T187] RDX: 00007fdfd764cfc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 9.888134][ T187] RBP: 00007ffc753b2e90 R08: 0000000000000000 R09: 0000000000008010 [ 9.888134][ T187] R10: 00007ffc753b2c50 R11: 0000000000000246 R12: 0000000000000001 [ 9.888135][ T187] R13: 0000000000000000 R14: 00007fdfd7a31680 R15: 00007fdfd7a32fa0 [ 9.888142][ T187] [ 9.911945][ C0] clocksource: Watchdog remote CPU 2 read timed out [ 9.911976][ C0] [ 9.911977][ C0] ======================================================== [ 9.911978][ C0] WARNING: possible irq lock inversion dependency detected [ 9.911980][ C0] 7.2.0-virtme #1 Tainted: G W [ 9.911982][ C0] -------------------------------------------------------- [ 9.911983][ C0] ip/190 just changed the state of lock: [ 9.911984][ C0] ffffffff89a89660 (console_owner){..-.}-{0:0}, at: console_trylock_spinning+0xa4/0x1e0 [ 9.911996][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 9.911998][ C0] (fs_reclaim){+.+.}-{0:0} [ 9.911999][ C0] [ 9.911999][ C0] [ 9.911999][ C0] and interrupts could create inverse lock ordering between them. [ 9.911999][ C0] [ 9.912000][ C0] [ 9.912000][ C0] other info that might help us debug this: [ 9.912001][ C0] Possible interrupt unsafe locking scenario: [ 9.912001][ C0] [ 9.912002][ C0] CPU0 CPU1 [ 9.912003][ C0] ---- ---- [ 9.912003][ C0] lock(fs_reclaim); [ 9.912004][ C0] local_irq_disable(); [ 9.912005][ C0] lock(console_owner); [ 9.912005][ C0] lock(fs_reclaim); [ 9.912006][ C0] [ 9.912007][ C0] lock(console_owner); [ 9.912007][ C0] [ 9.912007][ C0] *** DEADLOCK *** [ 9.912007][ C0] [ 9.912008][ C0] locks held by ip/190: 4, last CPU#0: [ 9.912009][ C0] #0: ff110000017f9720 (&ovl_i_mutex_dir_key[depth]){++++}-{4:4}, at: lookup_slow+0x44/0x80 [ 9.912014][ C0] #1: ff11000001787878 (&type->i_mutex_dir_key#3){++++}-{4:4}, at: lookup_slow+0x44/0x80 [ 9.912018][ C0] #2: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 9.912023][ C0] #3: ffffffff89bce8b8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 9.912027][ C0] [ 9.912027][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 9.912032][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 9.912034][ C0] HARDIRQ-ON-W at: [ 9.912036][ C0] __lock_acquire+0x388/0xc20 [ 9.912039][ C0] lock_acquire.part.0+0xd4/0x280 [ 9.912041][ C0] fs_reclaim_acquire+0xd5/0x120 [ 9.912043][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 9.912045][ C0] kthread_create_worker_on_node+0xea/0x210 [ 9.912048][ C0] workqueue_init+0x2a/0x680 [ 9.912052][ C0] kernel_init_freeable+0x2fe/0x630 [ 9.912054][ C0] kernel_init+0x21/0x150 [ 9.912058][ C0] ret_from_fork+0x474/0x6b0 [ 9.912061][ C0] ret_from_fork_asm+0x11/0x20 [ 9.912063][ C0] SOFTIRQ-ON-W at: [ 9.912064][ C0] __lock_acquire+0x388/0xc20 [ 9.912066][ C0] lock_acquire.part.0+0xd4/0x280 [ 9.912067][ C0] fs_reclaim_acquire+0xd5/0x120 [ 9.912069][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 9.912071][ C0] kthread_create_worker_on_node+0xea/0x210 [ 9.912072][ C0] workqueue_init+0x2a/0x680 [ 9.912073][ C0] kernel_init_freeable+0x2fe/0x630 [ 9.912074][ C0] kernel_init+0x21/0x150 [ 9.912076][ C0] ret_from_fork+0x474/0x6b0 [ 9.912077][ C0] ret_from_fork_asm+0x11/0x20 [ 9.912079][ C0] INITIAL USE at: [ 9.912079][ C0] __lock_acquire+0x388/0xc20 [ 9.912081][ C0] lock_acquire.part.0+0xd4/0x280 [ 9.912082][ C0] fs_reclaim_acquire+0xd5/0x120 [ 9.912083][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 9.912085][ C0] kthread_create_worker_on_node+0xea/0x210 [ 9.912086][ C0] workqueue_init+0x2a/0x680 [ 9.912087][ C0] kernel_init_freeable+0x2fe/0x630 [ 9.912088][ C0] kernel_init+0x21/0x150 [ 9.912090][ C0] ret_from_fork+0x474/0x6b0 [ 9.912091][ C0] ret_from_fork_asm+0x11/0x20 [ 9.912092][ C0] } [ 9.912092][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 9.912097][ C0] ... acquired at: [ 9.912098][ C0] __lock_acquire+0x518/0xc20 [ 9.912099][ C0] lock_acquire.part.0+0xd4/0x280 [ 9.912100][ C0] fs_reclaim_acquire+0xd5/0x120 [ 9.912101][ C0] __kmalloc_noprof+0xd3/0x760 [ 9.912103][ C0] alloc_buf.isra.0+0x4b/0x260 [ 9.912106][ C0] put_chars+0x1e1/0x2f0 [ 9.912107][ C0] hvc_console_print+0x292/0x780 [ 9.912109][ C0] console_emit_next_record+0x22f/0x4c0 [ 9.912111][ C0] console_flush_one_record+0x46f/0x710 [ 9.912113][ C0] console_unlock+0xee/0x1f0 [ 9.912114][ C0] vprintk_emit+0x37c/0x3e0 [ 9.912116][ C0] dev_vprintk_emit+0x27f/0x2c0 [ 9.912120][ C0] dev_printk_emit+0xb9/0xee [ 9.912121][ C0] _dev_info+0xe2/0x116 [ 9.912123][ C0] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 9.912126][ C0] cmos_do_probe+0x73b/0x98a [ 9.912127][ C0] platform_probe+0xfe/0x1f0 [ 9.912130][ C0] call_driver_probe+0x61/0x1c0 [ 9.912132][ C0] really_probe+0x199/0x760 [ 9.912134][ C0] __driver_probe_device+0x24f/0x440 [ 9.912135][ C0] driver_probe_device+0x4a/0xf0 [ 9.912137][ C0] __driver_attach+0x1b8/0x540 [ 9.912138][ C0] bus_for_each_dev+0x130/0x1e0 [ 9.912139][ C0] bus_add_driver+0x2c8/0x530 [ 9.912140][ C0] driver_register+0x1a3/0x390 [ 9.912142][ C0] __platform_driver_probe+0x13f/0x270 [ 9.912144][ C0] cmos_init+0x31/0x40 [ 9.912147][ C0] do_one_initcall+0x124/0x4f0 [ 9.912148][ C0] kernel_init_freeable+0x596/0x630 [ 9.912149][ C0] kernel_init+0x21/0x150 [ 9.912151][ C0] ret_from_fork+0x474/0x6b0 [ 9.912152][ C0] ret_from_fork_asm+0x11/0x20 [ 9.912153][ C0] [ 9.912154][ C0] -> (console_owner){..-.}-{0:0} { [ 9.912156][ C0] IN-SOFTIRQ-W at: [ 9.912156][ C0] __lock_acquire+0x388/0xc20 [ 9.912158][ C0] lock_acquire.part.0+0xd4/0x280 [ 9.912159][ C0] console_trylock_spinning+0xb5/0x1e0 [ 9.912161][ C0] vprintk_emit+0x320/0x3e0 [ 9.912162][ C0] _printk+0xc7/0x100 [ 9.912165][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 9.912168][ C0] call_timer_fn+0x160/0x4d0 [ 9.912169][ C0] __run_timers+0x68f/0xaa0 [ 9.912171][ C0] run_timer_softirq+0xf0/0x160 [ 9.912172][ C0] handle_softirqs+0x1d3/0x900 [ 9.912175][ C0] __irq_exit_rcu+0x145/0x1c0 [ 9.912176][ C0] irq_exit_rcu+0xe/0x30 [ 9.912177][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 9.912179][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 9.912181][ C0] _raw_spin_unlock_irqrestore+0x36/0x80 [ 9.912183][ C0] get_from_partial_node+0x21d/0x500 [ 9.912185][ C0] ___slab_alloc+0x71/0x2f0 [ 9.912186][ C0] kmem_cache_alloc_noprof+0x2fc/0x5c0 [ 9.912187][ C0] fuse_request_alloc+0x27/0x210 [ 9.912190][ C0] fuse_get_req+0x1e5/0x370 [ 9.912191][ C0] fuse_chan_send+0x56c/0x7a0 [ 9.912193][ C0] fuse_lookup_name+0x392/0x840 [ 9.912194][ C0] fuse_lookup+0x221/0x4e0 [ 9.912195][ C0] __lookup_slow+0x108/0x270 [ 9.912196][ C0] lookup_slow+0x54/0x80 [ 9.912198][ C0] ovl_lookup_single+0x29a/0xed0 [ 9.912200][ C0] ovl_lookup_layer+0x364/0x4e0 [ 9.912202][ C0] ovl_lookup_layers+0xee3/0x29c0 [ 9.912203][ C0] ovl_lookup+0x4f1/0x810 [ 9.912204][ C0] __lookup_slow+0x108/0x270 [ 9.912205][ C0] lookup_slow+0x54/0x80 [ 9.912206][ C0] link_path_walk+0xa38/0x1900 [ 9.912207][ C0] path_openat+0x14a/0x3b0 [ 9.912209][ C0] do_file_open+0x209/0x480 [ 9.912210][ C0] do_sys_openat2+0xe0/0x170 [ 9.912213][ C0] __x64_sys_openat+0x10e/0x210 [ 9.912214][ C0] do_syscall_64+0xff/0x530 [ 9.912216][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.912217][ C0] INITIAL USE at: [ 9.912218][ C0] } [ 9.912218][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 9.912222][ C0] ... acquired at: [ 9.912222][ C0] mark_lock+0x1d7/0xa00 [ 9.912224][ C0] mark_usage+0x42/0x170 [ 9.912225][ C0] __lock_acquire+0x388/0xc20 [ 9.912226][ C0] lock_acquire.part.0+0xd4/0x280 [ 9.912227][ C0] console_trylock_spinning+0xb5/0x1e0 [ 9.912229][ C0] vprintk_emit+0x320/0x3e0 [ 9.912230][ C0] _printk+0xc7/0x100 [ 9.912232][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 9.912233][ C0] call_timer_fn+0x160/0x4d0 [ 9.912234][ C0] __run_timers+0x68f/0xaa0 [ 9.912236][ C0] run_timer_softirq+0xf0/0x160 [ 9.912237][ C0] handle_softirqs+0x1d3/0x900 [ 9.912238][ C0] __irq_exit_rcu+0x145/0x1c0 [ 9.912240][ C0] irq_exit_rcu+0xe/0x30 [ 9.912241][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 9.912242][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 9.912243][ C0] _raw_spin_unlock_irqrestore+0x36/0x80 [ 9.912245][ C0] get_from_partial_node+0x21d/0x500 [ 9.912246][ C0] ___slab_alloc+0x71/0x2f0 [ 9.912247][ C0] kmem_cache_alloc_noprof+0x2fc/0x5c0 [ 9.912248][ C0] fuse_request_alloc+0x27/0x210 [ 9.912249][ C0] fuse_get_req+0x1e5/0x370 [ 9.912250][ C0] fuse_chan_send+0x56c/0x7a0 [ 9.912251][ C0] fuse_lookup_name+0x392/0x840 [ 9.912253][ C0] fuse_lookup+0x221/0x4e0 [ 9.912254][ C0] __lookup_slow+0x108/0x270 [ 9.912255][ C0] lookup_slow+0x54/0x80 [ 9.912256][ C0] ovl_lookup_single+0x29a/0xed0 [ 9.912258][ C0] ovl_lookup_layer+0x364/0x4e0 [ 9.912259][ C0] ovl_lookup_layers+0xee3/0x29c0 [ 9.912260][ C0] ovl_lookup+0x4f1/0x810 [ 9.912261][ C0] __lookup_slow+0x108/0x270 [ 9.912262][ C0] lookup_slow+0x54/0x80 [ 9.912263][ C0] link_path_walk+0xa38/0x1900 [ 9.912264][ C0] path_openat+0x14a/0x3b0 [ 9.912265][ C0] do_file_open+0x209/0x480 [ 9.912267][ C0] do_sys_openat2+0xe0/0x170 [ 9.912268][ C0] __x64_sys_openat+0x10e/0x210 [ 9.912269][ C0] do_syscall_64+0xff/0x530 [ 9.912270][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.912271][ C0] [ 9.912272][ C0] [ 9.912272][ C0] stack backtrace: [ 9.912274][ C0] CPU: 0 UID: 0 PID: 190 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.912278][ C0] Tainted: [W]=WARN [ 9.912279][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.912280][ C0] Call Trace: [ 9.912282][ C0] [ 9.912283][ C0] dump_stack_lvl+0x6f/0xa0 [ 9.912287][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 9.912290][ C0] mark_lock_irq+0x989/0x9c0 [ 9.912291][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 9.912295][ C0] mark_lock+0x1d7/0xa00 [ 9.912296][ C0] mark_usage+0x42/0x170 [ 9.912298][ C0] __lock_acquire+0x388/0xc20 [ 9.912300][ C0] lock_acquire.part.0+0xd4/0x280 [ 9.912302][ C0] ? console_trylock_spinning+0xa4/0x1e0 [ 9.912304][ C0] ? rcu_is_watching+0x16/0xd0 [ 9.912307][ C0] ? lock_acquire+0x13c/0x160 [ 9.912309][ C0] console_trylock_spinning+0xb5/0x1e0 [ 9.912311][ C0] ? console_trylock_spinning+0xa4/0x1e0 [ 9.912313][ C0] vprintk_emit+0x320/0x3e0 [ 9.912315][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 9.912317][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 9.912319][ C0] _printk+0xc7/0x100 [ 9.912321][ C0] ? snapshot_read.cold+0x21/0x21 [ 9.912323][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 9.912324][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 9.912327][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 9.912328][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 9.912330][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 9.912331][ C0] call_timer_fn+0x160/0x4d0 [ 9.912333][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 9.912335][ C0] ? debug_object_active_state+0x430/0x430 [ 9.912338][ C0] ? find_held_lock+0x2b/0x80 [ 9.912340][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 9.912342][ C0] ? mark_held_locks+0x40/0x70 [ 9.912344][ C0] __run_timers+0x68f/0xaa0 [ 9.912345][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 9.912348][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 9.912349][ C0] ? __lock_acquire+0x518/0xc20 [ 9.912352][ C0] ? __rwlock_init+0x150/0x150 [ 9.912354][ C0] run_timer_softirq+0xf0/0x160 [ 9.912356][ C0] ? __run_timers+0xaa0/0xaa0 [ 9.912358][ C0] ? rcu_is_watching+0x16/0xd0 [ 9.912360][ C0] handle_softirqs+0x1d3/0x900 [ 9.912362][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 9.912363][ C0] ? _local_bh_enable+0xc0/0xc0 [ 9.912365][ C0] __irq_exit_rcu+0x145/0x1c0 [ 9.912367][ C0] irq_exit_rcu+0xe/0x30 [ 9.912368][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 9.912370][ C0] [ 9.912370][ C0] [ 9.912371][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 9.912373][ C0] RIP: 0010:_raw_spin_unlock_irqrestore+0x36/0x80 [ 9.912376][ C0] Code: f5 53 48 8b 74 24 10 48 89 fb 48 83 c7 18 e8 c1 bf ba fd 48 89 df e8 c9 15 bb fd f7 c5 00 02 00 00 75 1f 9c 58 f6 c4 02 75 2f 01 00 00 00 e8 70 df ac fd 65 48 83 3d af 1b f3 01 00 74 12 5b [ 9.912377][ C0] RSP: 0018:ffa00000004b6ea8 EFLAGS: 00000246 [ 9.912380][ C0] RAX: 0000000000000096 RBX: ff11000005875bc0 RCX: ffffffff88da7483 [ 9.912381][ C0] RDX: ff1100000ccac6c0 RSI: ffffffff89638b1c RDI: ffffffff8908d8e0 [ 9.912382][ C0] RBP: 0000000000000246 R08: 0000000000000000 R09: 0000000000000000 [ 9.912383][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: ff11000005875bc0 [ 9.912383][ C0] R13: ffd40000002c9690 R14: ffd40000002c9680 R15: ff1100000b25a568 [ 9.912385][ C0] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 9.912388][ C0] get_from_partial_node+0x21d/0x500 [ 9.912389][ C0] ? find_held_lock+0x2b/0x80 [ 9.912391][ C0] ___slab_alloc+0x71/0x2f0 [ 9.912393][ C0] ? __pcs_replace_empty_main+0x42f/0x6a0 [ 9.912395][ C0] kmem_cache_alloc_noprof+0x2fc/0x5c0 [ 9.912397][ C0] ? fuse_request_alloc+0x27/0x210 [ 9.912398][ C0] fuse_request_alloc+0x27/0x210 [ 9.912400][ C0] fuse_get_req+0x1e5/0x370 [ 9.912401][ C0] ? fuse_dev_open+0x60/0x60 [ 9.912403][ C0] ? from_vfsuid+0xe0/0xe0 [ 9.912405][ C0] ? kasan_save_track+0x14/0x30 [ 9.912408][ C0] ? __kasan_kmalloc+0x7b/0x90 [ 9.912410][ C0] fuse_chan_send+0x56c/0x7a0 [ 9.912412][ C0] fuse_lookup_name+0x392/0x840 [ 9.912414][ C0] ? fuse_create+0x20/0x20 [ 9.912416][ C0] ? validate_chain+0x38b/0xc20 [ 9.912419][ C0] ? find_held_lock+0x2b/0x80 [ 9.912420][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 9.912422][ C0] ? __d_lookup_rcu+0x2e2/0x410 [ 9.912424][ C0] fuse_lookup+0x221/0x4e0 [ 9.912426][ C0] ? d_alloc_parallel+0x41a/0x1130 [ 9.912427][ C0] ? fuse_lookup_name+0x840/0x840 [ 9.912429][ C0] ? lookup_slow+0x44/0x80 [ 9.912431][ C0] ? down_read+0xc1/0x540 [ 9.912433][ C0] ? down_read+0x14c/0x540 [ 9.912434][ C0] ? down_write_killable_nested+0x270/0x270 [ 9.912436][ C0] __lookup_slow+0x108/0x270 [ 9.912438][ C0] lookup_slow+0x54/0x80 [ 9.912439][ C0] ovl_lookup_single+0x29a/0xed0 [ 9.912442][ C0] ? ovl_acceptable+0xf0/0xf0 [ 9.912445][ C0] ovl_lookup_layer+0x364/0x4e0 [ 9.912447][ C0] ? rcu_is_watching+0x16/0xd0 [ 9.912449][ C0] ? trace_kmalloc+0xf9/0x120 [ 9.912451][ C0] ? __kasan_kmalloc+0x7b/0x90 [ 9.912452][ C0] ? ovl_lookup_single+0xed0/0xed0 [ 9.912454][ C0] ? ovl_lookup_layers+0x1942/0x29c0 [ 9.912456][ C0] ovl_lookup_layers+0xee3/0x29c0 [ 9.912459][ C0] ? mark_held_locks+0x40/0x70 [ 9.912461][ C0] ? ovl_lookup_index+0x520/0x520 [ 9.912462][ C0] ? __lock_acquire+0x518/0xc20 [ 9.912464][ C0] ? find_held_lock+0x2b/0x80 [ 9.912466][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 9.912467][ C0] ? __d_lookup_rcu+0x2e2/0x410 [ 9.912469][ C0] ovl_lookup+0x4f1/0x810 [ 9.912470][ C0] ? d_alloc_parallel+0x41a/0x1130 [ 9.912471][ C0] ? d_alloc_parallel+0xafd/0x1130 [ 9.912473][ C0] ? ovl_verify_lowerdata+0x6e0/0x6e0 [ 9.912475][ C0] ? __d_lookup_rcu+0x410/0x410 [ 9.912476][ C0] ? rcu_is_watching+0x16/0xd0 [ 9.912478][ C0] ? down_read+0xc1/0x540 [ 9.912480][ C0] ? down_read+0x14c/0x540 [ 9.912481][ C0] ? down_write_killable_nested+0x270/0x270 [ 9.912483][ C0] __lookup_slow+0x108/0x270 [ 9.912484][ C0] lookup_slow+0x54/0x80 [ 9.912486][ C0] link_path_walk+0xa38/0x1900 [ 9.912488][ C0] ? path_init+0x77c/0x1a60 [ 9.912489][ C0] ? __raw_spin_lock_init+0x44/0x120 [ 9.912491][ C0] ? vfs_rename+0x1b40/0x1b40 [ 9.912492][ C0] ? __asan_memset+0x27/0x50 [ 9.912494][ C0] ? init_file.isra.0+0x30a/0x3e0 [ 9.912496][ C0] path_openat+0x14a/0x3b0 [ 9.912498][ C0] ? kernel_tmpfile_open+0x70/0x70 [ 9.912500][ C0] ? _raw_write_unlock_irqrestore+0x80/0x80 [ 9.912501][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.912504][ C0] do_file_open+0x209/0x480 [ 9.912506][ C0] ? path_openat+0x3b0/0x3b0 [ 9.912507][ C0] ? __lock_acquire+0x518/0xc20 [ 9.912511][ C0] ? _raw_spin_unlock+0x2d/0x50 [ 9.912512][ C0] ? alloc_fd+0x33a/0x600 [ 9.912515][ C0] do_sys_openat2+0xe0/0x170 [ 9.912517][ C0] ? build_open_flags+0x6e0/0x6e0 [ 9.912519][ C0] ? find_held_lock+0x2b/0x80 [ 9.912521][ C0] __x64_sys_openat+0x10e/0x210 [ 9.912523][ C0] ? exc_page_fault+0x87/0x100 [ 9.912525][ C0] ? __ia32_sys_open+0x1b0/0x1b0 [ 9.912526][ C0] ? rcu_is_watching+0x16/0xd0 [ 9.912528][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 9.912530][ C0] do_syscall_64+0xff/0x530 [ 9.912532][ C0] ? exc_page_fault+0xee/0x100 [ 9.912533][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.912535][ C0] RIP: 0033:0x7f143c70e54e [ 9.912537][ 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 [ 9.912538][ C0] RSP: 002b:00007ffcb0273940 EFLAGS: 00000202 ORIG_RAX: 0000000000000101 [ 9.912540][ C0] RAX: ffffffffffffffda RBX: 000000003f7ba010 RCX: 00007f143c70e54e [ 9.912541][ C0] RDX: 0000000000000000 RSI: 0000000000480922 RDI: ffffffffffffff9c [ 9.912542][ C0] RBP: 00007ffcb0273950 R08: 0000000000000000 R09: 0000000000000000 [ 9.912542][ C0] R10: 0000000000000000 R11: 0000000000000202 R12: 0000000000000008 [ 9.912543][ C0] R13: 0000000000480dc3 R14: 0000000000480dc3 R15: 0000000000000001 [ 9.912545][ C0] [ 15.654646][ T246] GACT probability NOT on [ 15.654661][ T246] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 15.654664][ T246] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 246, name: modprobe [ 15.654666][ T246] preempt_count: 1, expected: 0 [ 15.654667][ T246] RCU nest depth: 0, expected: 0 [ 15.654668][ T246] INFO: lockdep is turned off. [ 15.654669][ T246] irq event stamp: 0 [ 15.654670][ T246] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 15.654674][ T246] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 15.654680][ T246] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 15.654682][ T246] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 15.654684][ T246] Preemption disabled at: [ 15.654684][ T246] [] vprintk_emit+0x31b/0x3e0 [ 15.654691][ T246] CPU: 2 UID: 0 PID: 246 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.654695][ T246] Tainted: [W]=WARN [ 15.654696][ T246] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.654698][ T246] Call Trace: [ 15.654700][ T246] [ 15.654701][ T246] dump_stack_lvl+0x6f/0xa0 [ 15.654706][ T246] ? vprintk_emit+0x31b/0x3e0 [ 15.654708][ T246] __might_resched.cold+0x1fe/0x2c1 [ 15.654712][ T246] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 15.654715][ T246] ? rcu_is_watching+0x16/0xd0 [ 15.654719][ T246] __kmalloc_noprof+0x443/0x760 [ 15.654723][ T246] ? __rwlock_init+0x150/0x150 [ 15.654726][ T246] ? alloc_buf.isra.0+0x4b/0x260 [ 15.654730][ T246] ? do_raw_spin_unlock+0x59/0x250 [ 15.654732][ T246] alloc_buf.isra.0+0x4b/0x260 [ 15.654734][ T246] put_chars+0x1e1/0x2f0 [ 15.654735][ T246] ? desc_read_finalized_seq+0x79/0x120 [ 15.654737][ T246] ? __send_to_port+0x420/0x420 [ 15.654739][ T246] ? console_prepend_replay+0x20/0x20 [ 15.654741][ T246] hvc_console_print+0x292/0x780 [ 15.654745][ T246] ? hvc_write+0x3a0/0x3a0 [ 15.654746][ T246] ? do_raw_spin_lock+0x131/0x280 [ 15.654748][ T246] ? __rwlock_init+0x150/0x150 [ 15.654749][ T246] ? rcu_is_watching+0x16/0xd0 [ 15.654751][ T246] ? rcu_is_watching+0x16/0xd0 [ 15.654753][ T246] ? lock_acquire+0x13c/0x160 [ 15.654755][ T246] console_emit_next_record+0x22f/0x4c0 [ 15.654757][ T246] ? devkmsg_read+0x4b0/0x4b0 [ 15.654759][ T246] ? rcu_is_watching+0x16/0xd0 [ 15.654761][ T246] ? rcu_is_watching+0x16/0xd0 [ 15.654763][ T246] ? rcu_is_watching+0x16/0xd0 [ 15.654764][ T246] ? lock_acquire+0x13c/0x160 [ 15.654766][ T246] ? console_flush_one_record+0x111/0x710 [ 15.654768][ T246] console_flush_one_record+0x46f/0x710 [ 15.654770][ T246] ? console_emit_next_record+0x4c0/0x4c0 [ 15.654773][ T246] console_unlock+0xee/0x1f0 [ 15.654775][ T246] ? lock_acquire+0x13c/0x160 [ 15.654776][ T246] ? console_flush_one_record+0x710/0x710 [ 15.654778][ T246] ? rcu_is_watching+0x16/0xd0 [ 15.654780][ T246] ? lock_release+0x184/0x1f0 [ 15.654781][ T246] ? lock_acquire+0x60/0x160 [ 15.654782][ T246] ? __rcu_read_lock+0x30/0x70 [ 15.654784][ T246] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.654787][ T246] ? vprintk_emit+0x320/0x3e0 [ 15.654789][ T246] vprintk_emit+0x37c/0x3e0 [ 15.654791][ T246] ? wake_up_klogd_work_func+0x90/0x90 [ 15.654796][ T246] ? gact_cleanup_module+0xf0/0xf0 [act_gact] [ 15.654799][ T246] _printk+0xc7/0x100 [ 15.654802][ T246] ? snapshot_read.cold+0x21/0x21 [ 15.654806][ T246] ? do_one_initcall+0x113/0x4f0 [ 15.654808][ T246] gact_init_module+0x15/0x1000 [act_gact] [ 15.654810][ T246] do_one_initcall+0x124/0x4f0 [ 15.654812][ T246] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 15.654815][ T246] ? do_init_module+0xae/0x8a0 [ 15.654818][ T246] ? kasan_unpoison+0x44/0x70 [ 15.654820][ T246] do_init_module+0x2ad/0x8a0 [ 15.654823][ T246] ? free_module+0x470/0x470 [ 15.654825][ T246] ? kfree+0x123/0x590 [ 15.654827][ T246] load_module+0x1424/0x1bb0 [ 15.654831][ T246] ? layout_and_allocate.isra.0+0x680/0x680 [ 15.654833][ T246] ? __ia32_sys_fsconfig+0x180/0x180 [ 15.654836][ T246] ? ovl_real_file+0x172/0x1e0 [ 15.654839][ T246] ? backing_file_read_iter+0x541/0x790 [ 15.654842][ T246] init_module_from_file+0x11f/0x1a0 [ 15.654844][ T246] ? __do_sys_init_module+0x220/0x220 [ 15.654846][ T246] ? ovl_mmap+0x270/0x270 [ 15.654849][ T246] ? __rwlock_init+0x150/0x150 [ 15.654851][ T246] ? rcu_is_watching+0x16/0xd0 [ 15.654853][ T246] ? lock_release+0x184/0x1f0 [ 15.654854][ T246] ? do_raw_spin_unlock+0x59/0x250 [ 15.654856][ T246] idempotent_init_module+0x37b/0x7c0 [ 15.654858][ T246] ? init_module_from_file+0x1a0/0x1a0 [ 15.654861][ T246] ? rcu_is_watching+0x16/0xd0 [ 15.654863][ T246] ? cap_capable+0x1d7/0x3d0 [ 15.654867][ T246] __x64_sys_finit_module+0xdf/0x190 [ 15.654869][ T246] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 15.654871][ T246] do_syscall_64+0xff/0x530 [ 15.654875][ T246] ? exc_page_fault+0xee/0x100 [ 15.654877][ T246] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.654879][ T246] RIP: 0033:0x7f6f6d08437d [ 15.654881][ T246] 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.654883][ T246] RSP: 002b:00007fff2a19d828 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 15.654886][ T246] RAX: ffffffffffffffda RBX: 0000559b31448810 RCX: 00007f6f6d08437d [ 15.654888][ T246] RDX: 0000000000000000 RSI: 0000559b150bdffd RDI: 0000000000000000 [ 15.654889][ T246] RBP: 00007fff2a19d8c0 R08: 0000000000000000 R09: 0000000000000000 [ 15.654890][ T246] R10: 0000000000000000 R11: 0000000000000246 R12: 0000559b150bdffd [ 15.654890][ T246] R13: 0000559b31448960 R14: 0000000000040000 R15: 0000000000000000 [ 15.654898][ T246]