[ 18.097842][ C2] [ 18.098018][ C2] ================================ [ 18.098249][ C2] WARNING: inconsistent lock state [ 18.098475][ C2] 6.15.0-rc7-virtme #1 Not tainted [ 18.098697][ C2] -------------------------------- [ 18.098914][ C2] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 18.099178][ C2] packetdrill/227 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 18.099451][ C2] ffffffffae5bff78 (pin_fs_lock){+.?.}-{3:3}, at: simple_pin_fs+0x24/0x150 [ 18.099955][ C2] {SOFTIRQ-ON-W} state was registered at: [ 18.100163][ C2] __lock_acquire+0x3eb/0x9d0 [ 18.100378][ C2] lock_acquire.part.0+0xb6/0x240 [ 18.100586][ C2] _raw_spin_lock+0x33/0x40 [ 18.100795][ C2] simple_pin_fs+0x24/0x150 [ 18.100997][ C2] start_creating.part.0+0x3f/0x2f0 [ 18.101207][ C2] debugfs_create_dir+0x6a/0x540 [ 18.101422][ C2] component_debug_init+0x17/0x30 [ 18.101634][ C2] do_one_initcall+0x8f/0x1e0 [ 18.101862][ C2] do_initcalls+0x176/0x280 [ 18.102073][ C2] kernel_init_freeable+0x22d/0x300 [ 18.102281][ C2] kernel_init+0x20/0x200 [ 18.102500][ C2] ret_from_fork+0x31/0x70 [ 18.102709][ C2] ret_from_fork_asm+0x1a/0x30 [ 18.102928][ C2] irq event stamp: 1497978 [ 18.103134][ C2] hardirqs last enabled at (1497978): [] _raw_spin_unlock_irqrestore+0x5d/0x80 [ 18.103556][ C2] hardirqs last disabled at (1497977): [] _raw_spin_lock_irqsave+0x5b/0x60 [ 18.103977][ C2] softirqs last enabled at (1496618): [] handle_softirqs+0x358/0x620 [ 18.104344][ C2] softirqs last disabled at (1496653): [] __irq_exit_rcu+0xfa/0x160 [ 18.104708][ C2] [ 18.104708][ C2] other info that might help us debug this: [ 18.105012][ C2] Possible unsafe locking scenario: [ 18.105012][ C2] [ 18.105326][ C2] CPU0 [ 18.105483][ C2] ---- [ 18.105637][ C2] lock(pin_fs_lock); [ 18.105794][ C2] [ 18.105959][ C2] lock(pin_fs_lock); [ 18.106116][ C2] [ 18.106116][ C2] *** DEADLOCK *** [ 18.106116][ C2] [ 18.106438][ C2] 1 lock held by packetdrill/227: [ 18.106653][ C2] #0: ffffffffae3749c0 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x2e4/0xf20 [ 18.107040][ C2] [ 18.107040][ C2] stack backtrace: [ 18.107301][ C2] CPU: 2 UID: 0 PID: 227 Comm: packetdrill Not tainted 6.15.0-rc7-virtme #1 PREEMPT(full) [ 18.107306][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 18.107309][ C2] Call Trace: [ 18.107310][ C2] [ 18.107312][ C2] dump_stack_lvl+0x82/0xd0 [ 18.107318][ C2] print_usage_bug.part.0+0x25f/0x360 [ 18.107323][ C2] mark_lock_irq+0x343/0x400 [ 18.107328][ C2] ? __pfx_unwind_next_frame+0x10/0x10 [ 18.107334][ C2] ? save_trace+0x52/0x170 [ 18.107339][ C2] mark_lock+0x174/0x2f0 [ 18.107343][ C2] mark_usage+0x46/0x150 [ 18.107347][ C2] __lock_acquire+0x3eb/0x9d0 [ 18.107352][ C2] lock_acquire.part.0+0xb6/0x240 [ 18.107355][ C2] ? simple_pin_fs+0x24/0x150 [ 18.107358][ C2] ? rcu_is_watching+0x12/0xc0 [ 18.107364][ C2] ? lock_acquire+0x10c/0x170 [ 18.107367][ C2] ? simple_pin_fs+0x24/0x150 [ 18.107373][ C2] _raw_spin_lock+0x33/0x40 [ 18.107378][ C2] ? simple_pin_fs+0x24/0x150 [ 18.107382][ C2] simple_pin_fs+0x24/0x150 [ 18.107387][ C2] debugfs_remove+0x35/0x70 [ 18.107393][ C2] ref_tracker_dir_exit+0xcf/0x740 [ 18.107398][ C2] ? __pfx_ref_tracker_free+0x10/0x10 [ 18.107401][ C2] ? __sk_destruct+0x383/0x780 [ 18.107406][ C2] ? rcu_do_batch+0x34f/0xf20 [ 18.107409][ C2] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 18.107412][ C2] ? irq_exit_rcu+0xe/0x30 [ 18.107415][ C2] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 18.107419][ C2] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 18.107423][ C2] ? _raw_write_unlock_irq+0x2e/0x50 [ 18.107427][ C2] ? exit_notify+0x207/0x700 [ 18.107430][ C2] ? do_exit+0x514/0xc10 [ 18.107433][ C2] ? do_group_exit+0xb8/0x260 [ 18.107435][ C2] ? __x64_sys_exit_group+0x3e/0x50 [ 18.107438][ C2] ? x64_sys_call+0xf76/0x18a0 [ 18.107441][ C2] ? do_syscall_64+0xc1/0x1d0 [ 18.107444][ C2] ? entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 18.107449][ C2] __put_net+0x19/0x60 [ 18.107452][ C2] __sk_destruct+0x58a/0x780 [ 18.107456][ C2] ? rcu_do_batch+0x34a/0xf20 [ 18.107459][ C2] rcu_do_batch+0x34f/0xf20 [ 18.107464][ C2] ? __pfx_rcu_do_batch+0x10/0x10 [ 18.107468][ C2] ? rcu_is_watching+0x12/0xc0 [ 18.107472][ C2] ? note_gp_changes+0x182/0x1e0 [ 18.107476][ C2] ? lockdep_hardirqs_on+0x7c/0x110 [ 18.107481][ C2] rcu_core+0x2bb/0x500 [ 18.107484][ C2] handle_softirqs+0x218/0x620 [ 18.107489][ C2] __irq_exit_rcu+0xfa/0x160 [ 18.107491][ C2] irq_exit_rcu+0xe/0x30 [ 18.107493][ C2] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 18.107497][ C2] [ 18.107498][ C2] [ 18.107499][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 18.107502][ C2] RIP: 0010:_raw_write_unlock_irq+0x2e/0x50 [ 18.107506][ C2] Code: 0f 1f 44 00 00 55 48 8b 74 24 08 48 89 fd 48 83 c7 18 e8 d5 eb 85 fd 48 89 ef e8 8d 48 86 fd e8 f8 9a ab fd fb bf 01 00 00 00 7d 46 7a fd 65 8b 05 26 e0 98 02 85 c0 74 06 5d e9 8c 2c 00 00 [ 18.107509][ C2] RSP: 0018:ffffc90000a47de8 EFLAGS: 00000206 [ 18.107512][ C2] RAX: 000000000016d64b RBX: ffff888009167300 RCX: ffffffffaae369df [ 18.107515][ C2] RDX: 0000000000000000 RSI: ffffffffade87785 RDI: 0000000000000001 [ 18.107516][ C2] RBP: ffffffffae00c080 R08: 0000000000000001 R09: 0000000000000001 [ 18.107518][ C2] R10: ffffffffaedda697 R11: ffffffffae374ae0 R12: ffff888009167310 [ 18.107520][ C2] R13: ffff888009df0570 R14: ffffc90000a47e30 R15: 1ffff92000148fc2 [ 18.107524][ C2] ? trace_irq_enable.constprop.0+0x2f/0x130 [ 18.107530][ C2] ? _raw_write_unlock_irq+0x28/0x50 [ 18.107533][ C2] exit_notify+0x207/0x700 [ 18.107537][ C2] ? __pfx_exit_notify+0x10/0x10 [ 18.107540][ C2] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 18.107544][ C2] ? exit_tasks_rcu_start+0x215/0x340 [ 18.107548][ C2] do_exit+0x514/0xc10 [ 18.107551][ C2] ? _raw_spin_unlock_irq+0x28/0x50 [ 18.107555][ C2] do_group_exit+0xb8/0x260 [ 18.107559][ C2] __x64_sys_exit_group+0x3e/0x50 [ 18.107562][ C2] x64_sys_call+0xf76/0x18a0 [ 18.107565][ C2] do_syscall_64+0xc1/0x1d0 [ 18.107568][ C2] entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 18.107570][ C2] RIP: 0033:0x48991d [ 18.107574][ C2] Code: Unable to access opcode bytes at 0x4898f3. [ 18.107575][ C2] RSP: 002b:00007ffe38bff2b8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 18.107578][ C2] RAX: ffffffffffffffda RBX: 00000000005564b0 RCX: 000000000048991d [ 18.107580][ C2] RDX: 00000000000000e7 RSI: ffffffffffffffa8 RDI: 0000000000000000 [ 18.107581][ C2] RBP: 0000000000000000 R08: 0000000036920620 R09: 0000000000000000 [ 18.107583][ C2] R10: 0000000000000400 R11: 0000000000000246 R12: 00000000005564b0 [ 18.107585][ C2] R13: 0000000000000000 R14: 0000000000556f60 R15: 000000000042d8e0 [ 18.107590][ C2] [ 18.128560][ C2] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 18.128970][ C2] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 227, name: packetdrill [ 18.129367][ C2] preempt_count: 101, expected: 0 [ 18.129613][ C2] RCU nest depth: 0, expected: 0 [ 18.129838][ C2] INFO: lockdep is turned off. [ 18.130315][ C2] Preemption disabled at: [ 18.130317][ C2] [<0000000000000000>] 0x0 [ 18.130738][ C2] CPU: 2 UID: 0 PID: 227 Comm: packetdrill Not tainted 6.15.0-rc7-virtme #1 PREEMPT(full) [ 18.130743][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 18.130745][ C2] Call Trace: [ 18.130747][ C2] [ 18.130748][ C2] dump_stack_lvl+0xb0/0xd0 [ 18.130756][ C2] __might_resched+0x360/0x5a0 [ 18.130763][ C2] down_write+0x72/0x210 [ 18.130767][ C2] ? __pfx_down_write+0x10/0x10 [ 18.130771][ C2] ? simple_recursive_removal+0x3a/0x860 [ 18.130775][ C2] ? rcu_is_watching+0x12/0xc0 [ 18.130782][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 18.130787][ C2] simple_recursive_removal+0x166/0x860 [ 18.130789][ C2] ? __pfx_remove_one+0x10/0x10 [ 18.130794][ C2] ? do_raw_spin_unlock+0x58/0x220 [ 18.130798][ C2] debugfs_remove+0x44/0x70 [ 18.130802][ C2] ref_tracker_dir_exit+0xcf/0x740 [ 18.130807][ C2] ? __pfx_ref_tracker_free+0x10/0x10 [ 18.130810][ C2] ? __sk_destruct+0x383/0x780 [ 18.130815][ C2] ? rcu_do_batch+0x34f/0xf20 [ 18.130819][ C2] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 18.130822][ C2] ? irq_exit_rcu+0xe/0x30 [ 18.130826][ C2] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 18.130830][ C2] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 18.130834][ C2] ? _raw_write_unlock_irq+0x2e/0x50 [ 18.130838][ C2] ? exit_notify+0x207/0x700 [ 18.130842][ C2] ? do_exit+0x514/0xc10 [ 18.130844][ C2] ? do_group_exit+0xb8/0x260 [ 18.130847][ C2] ? __x64_sys_exit_group+0x3e/0x50 [ 18.130850][ C2] ? x64_sys_call+0xf76/0x18a0 [ 18.130856][ C2] ? do_syscall_64+0xc1/0x1d0 [ 18.130858][ C2] ? entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 18.130863][ C2] __put_net+0x19/0x60 [ 18.130866][ C2] __sk_destruct+0x58a/0x780 [ 18.130870][ C2] ? rcu_do_batch+0x34a/0xf20 [ 18.130873][ C2] rcu_do_batch+0x34f/0xf20 [ 18.130878][ C2] ? __pfx_rcu_do_batch+0x10/0x10 [ 18.130882][ C2] ? rcu_is_watching+0x12/0xc0 [ 18.130886][ C2] ? note_gp_changes+0x182/0x1e0 [ 18.130890][ C2] ? lockdep_hardirqs_on+0x7c/0x110 [ 18.130894][ C2] rcu_core+0x2bb/0x500 [ 18.130898][ C2] handle_softirqs+0x218/0x620 [ 18.130902][ C2] __irq_exit_rcu+0xfa/0x160 [ 18.130905][ C2] irq_exit_rcu+0xe/0x30 [ 18.130907][ C2] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 18.130911][ C2] [ 18.130912][ C2] [ 18.130914][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 18.130916][ C2] RIP: 0010:_raw_write_unlock_irq+0x2e/0x50 [ 18.130921][ C2] Code: 0f 1f 44 00 00 55 48 8b 74 24 08 48 89 fd 48 83 c7 18 e8 d5 eb 85 fd 48 89 ef e8 8d 48 86 fd e8 f8 9a ab fd fb bf 01 00 00 00 7d 46 7a fd 65 8b 05 26 e0 98 02 85 c0 74 06 5d e9 8c 2c 00 00 [ 18.130923][ C2] RSP: 0018:ffffc90000a47de8 EFLAGS: 00000206 [ 18.130927][ C2] RAX: 000000000016d64b RBX: ffff888009167300 RCX: ffffffffaae369df [ 18.130929][ C2] RDX: 0000000000000000 RSI: ffffffffade87785 RDI: 0000000000000001 [ 18.130931][ C2] RBP: ffffffffae00c080 R08: 0000000000000001 R09: 0000000000000001 [ 18.130932][ C2] R10: ffffffffaedda697 R11: ffffffffae374ae0 R12: ffff888009167310 [ 18.130935][ C2] R13: ffff888009df0570 R14: ffffc90000a47e30 R15: 1ffff92000148fc2 [ 18.130938][ C2] ? trace_irq_enable.constprop.0+0x2f/0x130 [ 18.130945][ C2] ? _raw_write_unlock_irq+0x28/0x50 [ 18.130948][ C2] exit_notify+0x207/0x700 [ 18.130952][ C2] ? __pfx_exit_notify+0x10/0x10 [ 18.130955][ C2] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 18.130959][ C2] ? exit_tasks_rcu_start+0x215/0x340 [ 18.130963][ C2] do_exit+0x514/0xc10 [ 18.130966][ C2] ? _raw_spin_unlock_irq+0x28/0x50 [ 18.130970][ C2] do_group_exit+0xb8/0x260 [ 18.130974][ C2] __x64_sys_exit_group+0x3e/0x50 [ 18.130977][ C2] x64_sys_call+0xf76/0x18a0 [ 18.130980][ C2] do_syscall_64+0xc1/0x1d0 [ 18.130983][ C2] entry_SYSCALL_64_after_hwframe+0x77/0x7f [ 18.130985][ C2] RIP: 0033:0x48991d [ 18.130988][ C2] Code: Unable to access opcode bytes at 0x4898f3. [ 18.130989][ C2] RSP: 002b:00007ffe38bff2b8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 18.130992][ C2] RAX: ffffffffffffffda RBX: 00000000005564b0 RCX: 000000000048991d [ 18.130994][ C2] RDX: 00000000000000e7 RSI: ffffffffffffffa8 RDI: 0000000000000000 [ 18.130996][ C2] RBP: 0000000000000000 R08: 0000000036920620 R09: 0000000000000000 [ 18.130998][ C2] R10: 0000000000000400 R11: 0000000000000246 R12: 00000000005564b0 [ 18.131000][ C2] R13: 0000000000000000 R14: 0000000000556f60 R15: 000000000042d8e0 [ 18.131005][ C2] [ 20.780878][ C0] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 20.781330][ C0] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0 [ 20.781728][ C0] preempt_count: 101, expected: 0 [ 20.781947][ C0] RCU nest depth: 0, expected: 0 [ 20.782162][ C0] INFO: lockdep is turned off. [ 20.782380][ C0] Preemption disabled at: [ 20.782382][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 20.782908][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 6.15.0-rc7-virtme #1 PREEMPT(full) [ 20.782914][ C0] Tainted: [W]=WARN [ 20.782916][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 20.782918][ C0] Call Trace: [ 20.782920][ C0] [ 20.782922][ C0] dump_stack_lvl+0xb0/0xd0 [ 20.782928][ C0] __might_resched+0x360/0x5a0 [ 20.782934][ C0] down_write+0x72/0x210 [ 20.782938][ C0] ? __pfx_down_write+0x10/0x10 [ 20.782941][ C0] ? simple_recursive_removal+0x3a/0x860 [ 20.782946][ C0] ? rcu_is_watching+0x12/0xc0 [ 20.782953][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 20.782958][ C0] simple_recursive_removal+0x166/0x860 [ 20.782961][ C0] ? __pfx_remove_one+0x10/0x10 [ 20.782965][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 20.782970][ C0] debugfs_remove+0x44/0x70 [ 20.782973][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 20.782978][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 20.782981][ C0] ? __sk_destruct+0x383/0x780 [ 20.782986][ C0] ? rcu_do_batch+0x34f/0xf20 [ 20.782990][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 20.782993][ C0] ? irq_exit_rcu+0xe/0x30 [ 20.782998][ C0] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 20.783002][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 20.783006][ C0] ? pv_native_safe_halt+0xf/0x20 [ 20.783009][ C0] ? default_idle+0x9/0x10 [ 20.783011][ C0] ? default_idle_call+0x6d/0xb0 [ 20.783014][ C0] ? cpuidle_idle_call+0x26f/0x3a0 [ 20.783018][ C0] ? do_idle+0xf6/0x160 [ 20.783021][ C0] ? cpu_startup_entry+0x54/0x60 [ 20.783024][ C0] ? rest_init+0x14b/0x260 [ 20.783027][ C0] ? start_kernel+0x350/0x410 [ 20.783032][ C0] ? bpf_sk_storage_free+0x9f/0x1c0 [ 20.783037][ C0] ? rcu_is_watching+0x12/0xc0 [ 20.783042][ C0] __put_net+0x19/0x60 [ 20.783045][ C0] __sk_destruct+0x58a/0x780 [ 20.783049][ C0] ? rcu_do_batch+0x34a/0xf20 [ 20.783052][ C0] rcu_do_batch+0x34f/0xf20 [ 20.783057][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 20.783061][ C0] ? trace_irq_enable.constprop.0+0xd4/0x130 [ 20.783066][ C0] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 20.783071][ C0] rcu_core+0x2bb/0x500 [ 20.783076][ C0] handle_softirqs+0x218/0x620 [ 20.783080][ C0] __irq_exit_rcu+0xfa/0x160 [ 20.783083][ C0] irq_exit_rcu+0xe/0x30 [ 20.783085][ C0] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 20.783089][ C0] [ 20.783090][ C0] [ 20.783092][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 20.783094][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 20.783099][ C0] Code: 60 b8 00 e9 c3 57 02 00 0f 1f 00 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 f3 0f 1e fa 66 90 0f 00 2d c3 75 13 00 fb f4 cc cc cc cc 66 2e 0f 1f 84 00 00 00 00 00 66 90 90 90 90 90 90 [ 20.783102][ C0] RSP: 0018:ffffffffae007df8 EFLAGS: 00000246 [ 20.783106][ C0] RAX: 0000000000000000 RBX: 1ffffffff5c00fc3 RCX: ffffffffad35ba19 [ 20.783108][ C0] RDX: ffffed1006c0667b RSI: ffffffffad6511e0 RDI: ffffffffaab8633f [ 20.783110][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: ffffed1006c0667a [ 20.783111][ C0] R10: ffff8880360333d3 R11: ffff888008978558 R12: 0000000000000000 [ 20.783114][ C0] R13: ffffffffae01f240 R14: dffffc0000000000 R15: 0000000000014770 [ 20.783118][ C0] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 20.783121][ C0] ? cpuidle_idle_call+0x26f/0x3a0 [ 20.783125][ C0] default_idle+0x9/0x10 [ 20.783128][ C0] default_idle_call+0x6d/0xb0 [ 20.783130][ C0] cpuidle_idle_call+0x26f/0x3a0 [ 20.783134][ C0] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 20.783138][ C0] ? tsc_verify_tsc_adjust+0x98/0x300 [ 20.783144][ C0] do_idle+0xf6/0x160 [ 20.783148][ C0] cpu_startup_entry+0x54/0x60 [ 20.783152][ C0] rest_init+0x14b/0x260 [ 20.783155][ C0] start_kernel+0x350/0x410 [ 20.783159][ C0] x86_64_start_reservations+0x18/0x30 [ 20.783164][ C0] x86_64_start_kernel+0xba/0x110 [ 20.783168][ C0] common_startup_64+0x13e/0x148 [ 20.783176][ C0]