[ 309.323077][ T5718] IPsec XFRM device driver [ 319.248503][ T5758] ip (5758) used greatest stack depth: 24288 bytes left [ 342.147442][ T5849] CAN device driver interface [ 342.161998][ T5849] vxcan: Virtual CAN Tunnel driver [ 361.166462][ C0] [ 361.166680][ C0] ================================ [ 361.166892][ C0] WARNING: inconsistent lock state [ 361.167099][ C0] 6.15.0-virtme #1 Not tainted [ 361.167307][ C0] -------------------------------- [ 361.167525][ C0] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 361.167782][ C0] python3/5664 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 361.168039][ C0] ffffffffb85daf78 (pin_fs_lock){+.?.}-{3:3}, at: simple_pin_fs+0x24/0x150 [ 361.168404][ C0] {SOFTIRQ-ON-W} state was registered at: [ 361.168607][ C0] __lock_acquire+0x20b/0x7e0 [ 361.168823][ C0] lock_acquire.part.0+0xb6/0x240 [ 361.169031][ C0] _raw_spin_lock+0x33/0x40 [ 361.169240][ C0] simple_pin_fs+0x24/0x150 [ 361.169446][ C0] start_creating.part.0+0x52/0x310 [ 361.169658][ C0] debugfs_create_dir+0x6a/0x540 [ 361.169867][ C0] component_debug_init+0x17/0x30 [ 361.170075][ C0] do_one_initcall+0x8f/0x1e0 [ 361.170285][ C0] do_initcalls+0x176/0x280 [ 361.170499][ C0] kernel_init_freeable+0x22d/0x300 [ 361.170708][ C0] kernel_init+0x20/0x200 [ 361.170915][ C0] ret_from_fork+0x240/0x320 [ 361.171123][ C0] ret_from_fork_asm+0x1a/0x30 [ 361.171333][ C0] irq event stamp: 2139418 [ 361.171541][ C0] hardirqs last enabled at (2139418): [] _raw_spin_unlock_irqrestore+0x5d/0x80 [ 361.171969][ C0] hardirqs last disabled at (2139417): [] _raw_spin_lock_irqsave+0x5b/0x60 [ 361.172387][ C0] softirqs last enabled at (2138768): [] handle_softirqs+0x358/0x620 [ 361.172746][ C0] softirqs last disabled at (2139387): [] __irq_exit_rcu+0xfa/0x160 [ 361.173106][ C0] [ 361.173106][ C0] other info that might help us debug this: [ 361.173407][ C0] Possible unsafe locking scenario: [ 361.173407][ C0] [ 361.173711][ C0] CPU0 [ 361.173865][ C0] ---- [ 361.174018][ C0] lock(pin_fs_lock); [ 361.174174][ C0] [ 361.174326][ C0] lock(pin_fs_lock); [ 361.174482][ C0] [ 361.174482][ C0] *** DEADLOCK *** [ 361.174482][ C0] [ 361.174786][ C0] 2 locks held by python3/5664: [ 361.174991][ C0] #0: ffff88800dc34120 (&mm->mmap_lock){++++}-{4:4}, at: exit_mmap+0x190/0x800 [ 361.175359][ C0] #1: ffffffffb838b540 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x2e4/0xf20 [ 361.175726][ C0] [ 361.175726][ C0] stack backtrace: [ 361.175982][ C0] CPU: 0 UID: 0 PID: 5664 Comm: python3 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 361.175986][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 361.175988][ C0] Call Trace: [ 361.175990][ C0] [ 361.175992][ C0] dump_stack_lvl+0x82/0xd0 [ 361.175998][ C0] print_usage_bug.part.0+0x25f/0x360 [ 361.176002][ C0] mark_lock_irq+0x343/0x400 [ 361.176006][ C0] ? __pfx_unwind_next_frame+0x10/0x10 [ 361.176010][ C0] ? save_trace+0x52/0x170 [ 361.176016][ C0] mark_lock+0x174/0x2f0 [ 361.176019][ C0] mark_usage+0x46/0x150 [ 361.176022][ C0] __lock_acquire+0x20b/0x7e0 [ 361.176025][ C0] ? __lock_acquire+0x44d/0x7e0 [ 361.176029][ C0] lock_acquire.part.0+0xb6/0x240 [ 361.176032][ C0] ? simple_pin_fs+0x24/0x150 [ 361.176035][ C0] ? rcu_is_watching+0x12/0xc0 [ 361.176038][ C0] ? lock_acquire+0x10c/0x170 [ 361.176041][ C0] ? simple_pin_fs+0x24/0x150 [ 361.176044][ C0] _raw_spin_lock+0x33/0x40 [ 361.176047][ C0] ? simple_pin_fs+0x24/0x150 [ 361.176050][ C0] simple_pin_fs+0x24/0x150 [ 361.176053][ C0] debugfs_remove+0x35/0x70 [ 361.176056][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 361.176061][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 361.176064][ C0] ? __sk_destruct+0x383/0x780 [ 361.176068][ C0] ? rcu_do_batch+0x34f/0xf20 [ 361.176072][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 361.176074][ C0] ? irq_exit_rcu+0xe/0x30 [ 361.176077][ C0] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 361.176081][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 361.176085][ C0] ? _raw_spin_unlock_irqrestore+0x41/0x80 [ 361.176088][ C0] ? __create_object+0x5e/0xb0 [ 361.176091][ C0] ? __kmalloc_cache_noprof+0x2bb/0x360 [ 361.176095][ C0] ? kmem_cache_free+0xf3/0x330 [ 361.176097][ C0] ? __put_anon_vma+0xf4/0x320 [ 361.176101][ C0] ? unlink_anon_vmas+0x4ef/0x920 [ 361.176104][ C0] ? free_pgtables+0x25b/0x6e0 [ 361.176108][ C0] ? exit_mmap+0x30d/0x800 [ 361.176113][ C0] __put_net+0x19/0x60 [ 361.176119][ C0] __sk_destruct+0x58a/0x780 [ 361.176122][ C0] ? rcu_do_batch+0x34a/0xf20 [ 361.176126][ C0] rcu_do_batch+0x34f/0xf20 [ 361.176130][ C0] ? find_held_lock+0x2b/0x80 [ 361.176135][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 361.176139][ C0] ? _raw_spin_unlock_irqrestore+0x5d/0x80 [ 361.176142][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 361.176146][ C0] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 361.176150][ C0] rcu_core+0x2bb/0x500 [ 361.176155][ C0] handle_softirqs+0x21b/0x620 [ 361.176160][ C0] __irq_exit_rcu+0xfa/0x160 [ 361.176162][ C0] irq_exit_rcu+0xe/0x30 [ 361.176165][ C0] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 361.176168][ C0] [ 361.176169][ C0] [ 361.176171][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 361.176173][ C0] RIP: 0010:_raw_spin_unlock_irqrestore+0x41/0x80 [ 361.176177][ C0] Code: 74 24 10 e8 81 4c 56 fd 48 89 ef e8 39 9f 56 fd 81 e3 00 02 00 00 75 29 9c 58 f6 c4 02 75 35 48 85 db 74 01 fb bf 01 00 00 00 ba 9c 4a fd 65 8b 05 f3 3c 1f 03 85 c0 74 0e 5b 5d e9 98 34 00 [ 361.176180][ C0] RSP: 0018:ffffc900036179b0 EFLAGS: 00000206 [ 361.176183][ C0] RAX: 0000000000000006 RBX: 0000000000000200 RCX: 0000000000000040 [ 361.176184][ C0] RDX: 0000000000000000 RSI: ffffffffb7915cea RDI: 0000000000000001 [ 361.176186][ C0] RBP: ffffffffb859e4a0 R08: 0000000000000001 R09: 0000000000000001 [ 361.176188][ C0] R10: ffffffffb8e7c7d7 R11: ffffffffb859e4b8 R12: ffff88800ab9d3e0 [ 361.176189][ C0] R13: 0000000000000020 R14: 0000000000000001 R15: 0000000000000282 [ 361.176195][ C0] __create_object+0x5e/0xb0 [ 361.176199][ C0] __kmalloc_cache_noprof+0x2bb/0x360 [ 361.176202][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 361.176207][ C0] kmem_cache_free+0xf3/0x330 [ 361.176210][ C0] ? __put_anon_vma+0xf4/0x320 [ 361.176214][ C0] __put_anon_vma+0xf4/0x320 [ 361.176217][ C0] unlink_anon_vmas+0x4ef/0x920 [ 361.176223][ C0] free_pgtables+0x25b/0x6e0 [ 361.176228][ C0] ? __pfx_free_pgtables+0x10/0x10 [ 361.176231][ C0] ? lock_acquire+0x10c/0x170 [ 361.176233][ C0] ? exit_mmap+0x190/0x800 [ 361.176237][ C0] ? down_write+0x152/0x210 [ 361.176240][ C0] ? __pfx_down_write+0x10/0x10 [ 361.176245][ C0] exit_mmap+0x30d/0x800 [ 361.176248][ C0] ? __pfx_exit_mmap+0x10/0x10 [ 361.176254][ C0] ? __mutex_unlock_slowpath+0x144/0x400 [ 361.176264][ C0] mmput+0xb8/0x3f0 [ 361.176268][ C0] exit_mm+0x146/0x1d0 [ 361.176272][ C0] do_exit+0x433/0xc20 [ 361.176275][ C0] ? _raw_spin_unlock_irq+0x28/0x50 [ 361.176279][ C0] do_group_exit+0xb8/0x260 [ 361.176283][ C0] __x64_sys_exit_group+0x3e/0x50 [ 361.176287][ C0] x64_sys_call+0xf76/0x18a0 [ 361.176290][ C0] do_syscall_64+0xc1/0x380 [ 361.176296][ C0] entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 361.176298][ C0] RIP: 0033:0x7f7812716abd [ 361.176301][ C0] Code: Unable to access opcode bytes at 0x7f7812716a93. [ 361.176303][ C0] RSP: 002b:00007ffefeff2808 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 361.176305][ C0] RAX: ffffffffffffffda RBX: 00007f78127f39c0 RCX: 00007f7812716abd [ 361.176307][ C0] RDX: 00000000000000e7 RSI: ffffffffffffff80 RDI: 0000000000000000 [ 361.176309][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000100 [ 361.176310][ C0] R10: 00007ffefeff24f0 R11: 0000000000000246 R12: 00007f78127f39c0 [ 361.176312][ C0] R13: 00007f78127f8ee0 R14: 0000000000000002 R15: 00007f78127f8ec8 [ 361.176318][ C0] [ 361.198057][ C0] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 361.198400][ C0] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 5664, name: python3 [ 361.198740][ C0] preempt_count: 101, expected: 0 [ 361.198935][ C0] RCU nest depth: 0, expected: 0 [ 361.199129][ C0] INFO: lockdep is turned off. [ 361.199323][ C0] Preemption disabled at: [ 361.199325][ C0] [<0000000000000000>] 0x0 [ 361.199686][ C0] CPU: 0 UID: 0 PID: 5664 Comm: python3 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 361.199690][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 361.199692][ C0] Call Trace: [ 361.199694][ C0] [ 361.199695][ C0] dump_stack_lvl+0xb0/0xd0 [ 361.199702][ C0] __might_resched+0x360/0x5a0 [ 361.199709][ C0] down_write+0x72/0x210 [ 361.199712][ C0] ? __pfx_down_write+0x10/0x10 [ 361.199715][ C0] ? simple_recursive_removal+0x33/0x610 [ 361.199719][ C0] ? rcu_is_watching+0x12/0xc0 [ 361.199723][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 361.199728][ C0] simple_recursive_removal+0x6a/0x610 [ 361.199731][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 361.199734][ C0] ? __pfx_remove_one+0x10/0x10 [ 361.199740][ C0] debugfs_remove+0x44/0x70 [ 361.199744][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 361.199748][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 361.199751][ C0] ? __sk_destruct+0x383/0x780 [ 361.199754][ C0] ? rcu_do_batch+0x34f/0xf20 [ 361.199759][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 361.199761][ C0] ? irq_exit_rcu+0xe/0x30 [ 361.199765][ C0] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 361.199769][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 361.199773][ C0] ? _raw_spin_unlock_irqrestore+0x41/0x80 [ 361.199776][ C0] ? __create_object+0x5e/0xb0 [ 361.199779][ C0] ? __kmalloc_cache_noprof+0x2bb/0x360 [ 361.199784][ C0] ? kmem_cache_free+0xf3/0x330 [ 361.199786][ C0] ? __put_anon_vma+0xf4/0x320 [ 361.199790][ C0] ? unlink_anon_vmas+0x4ef/0x920 [ 361.199793][ C0] ? free_pgtables+0x25b/0x6e0 [ 361.199797][ C0] ? exit_mmap+0x30d/0x800 [ 361.199802][ C0] __put_net+0x19/0x60 [ 361.199808][ C0] __sk_destruct+0x58a/0x780 [ 361.199811][ C0] ? rcu_do_batch+0x34a/0xf20 [ 361.199814][ C0] rcu_do_batch+0x34f/0xf20 [ 361.199819][ C0] ? find_held_lock+0x2b/0x80 [ 361.199823][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 361.199827][ C0] ? _raw_spin_unlock_irqrestore+0x5d/0x80 [ 361.199831][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 361.199834][ C0] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 361.199839][ C0] rcu_core+0x2bb/0x500 [ 361.199843][ C0] handle_softirqs+0x21b/0x620 [ 361.199848][ C0] __irq_exit_rcu+0xfa/0x160 [ 361.199850][ C0] irq_exit_rcu+0xe/0x30 [ 361.199853][ C0] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 361.199856][ C0] [ 361.199857][ C0] [ 361.199859][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 361.199861][ C0] RIP: 0010:_raw_spin_unlock_irqrestore+0x41/0x80 [ 361.199865][ C0] Code: 74 24 10 e8 81 4c 56 fd 48 89 ef e8 39 9f 56 fd 81 e3 00 02 00 00 75 29 9c 58 f6 c4 02 75 35 48 85 db 74 01 fb bf 01 00 00 00 ba 9c 4a fd 65 8b 05 f3 3c 1f 03 85 c0 74 0e 5b 5d e9 98 34 00 [ 361.199867][ C0] RSP: 0018:ffffc900036179b0 EFLAGS: 00000206 [ 361.199870][ C0] RAX: 0000000000000006 RBX: 0000000000000200 RCX: 0000000000000040 [ 361.199872][ C0] RDX: 0000000000000000 RSI: ffffffffb7915cea RDI: 0000000000000001 [ 361.199874][ C0] RBP: ffffffffb859e4a0 R08: 0000000000000001 R09: 0000000000000001 [ 361.199876][ C0] R10: ffffffffb8e7c7d7 R11: ffffffffb859e4b8 R12: ffff88800ab9d3e0 [ 361.199878][ C0] R13: 0000000000000020 R14: 0000000000000001 R15: 0000000000000282 [ 361.199884][ C0] __create_object+0x5e/0xb0 [ 361.199888][ C0] __kmalloc_cache_noprof+0x2bb/0x360 [ 361.199891][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 361.199896][ C0] kmem_cache_free+0xf3/0x330 [ 361.199899][ C0] ? __put_anon_vma+0xf4/0x320 [ 361.199903][ C0] __put_anon_vma+0xf4/0x320 [ 361.199906][ C0] unlink_anon_vmas+0x4ef/0x920 [ 361.199911][ C0] free_pgtables+0x25b/0x6e0 [ 361.199917][ C0] ? __pfx_free_pgtables+0x10/0x10 [ 361.199920][ C0] ? lock_acquire+0x10c/0x170 [ 361.199922][ C0] ? exit_mmap+0x190/0x800 [ 361.199926][ C0] ? down_write+0x152/0x210 [ 361.199929][ C0] ? __pfx_down_write+0x10/0x10 [ 361.199933][ C0] exit_mmap+0x30d/0x800 [ 361.199937][ C0] ? __pfx_exit_mmap+0x10/0x10 [ 361.199942][ C0] ? __mutex_unlock_slowpath+0x144/0x400 [ 361.199952][ C0] mmput+0xb8/0x3f0 [ 361.199957][ C0] exit_mm+0x146/0x1d0 [ 361.199961][ C0] do_exit+0x433/0xc20 [ 361.199964][ C0] ? _raw_spin_unlock_irq+0x28/0x50 [ 361.199968][ C0] do_group_exit+0xb8/0x260 [ 361.199972][ C0] __x64_sys_exit_group+0x3e/0x50 [ 361.199976][ C0] x64_sys_call+0xf76/0x18a0 [ 361.199980][ C0] do_syscall_64+0xc1/0x380 [ 361.199986][ C0] entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 361.199988][ C0] RIP: 0033:0x7f7812716abd [ 361.199991][ C0] Code: Unable to access opcode bytes at 0x7f7812716a93. [ 361.199992][ C0] RSP: 002b:00007ffefeff2808 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 361.199994][ C0] RAX: ffffffffffffffda RBX: 00007f78127f39c0 RCX: 00007f7812716abd [ 361.199996][ C0] RDX: 00000000000000e7 RSI: ffffffffffffff80 RDI: 0000000000000000 [ 361.199998][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000100 [ 361.199999][ C0] R10: 00007ffefeff24f0 R11: 0000000000000246 R12: 00007f78127f39c0 [ 361.200000][ C0] R13: 00007f78127f8ee0 R14: 0000000000000002 R15: 00007f78127f8ec8 [ 361.200006][ C0]