[ 14.280311][ C0] [ 14.280482][ C0] ================================ [ 14.280713][ C0] WARNING: inconsistent lock state [ 14.280949][ C0] 6.15.0-virtme #1 Not tainted [ 14.281183][ C0] -------------------------------- [ 14.281410][ C0] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 14.281692][ C0] swapper/0/0 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 14.281974][ C0] ffffffff9e1c5af8 (pin_fs_lock){+.?.}-{3:3}, at: simple_pin_fs+0x24/0x150 [ 14.282373][ C0] {SOFTIRQ-ON-W} state was registered at: [ 14.282614][ C0] __lock_acquire+0x20b/0x7e0 [ 14.282849][ C0] lock_acquire.part.0+0xb6/0x240 [ 14.283072][ C0] _raw_spin_lock+0x33/0x40 [ 14.283298][ C0] simple_pin_fs+0x24/0x150 [ 14.283524][ C0] start_creating.part.0+0x52/0x310 [ 14.283752][ C0] debugfs_create_dir+0x6a/0x540 [ 14.283977][ C0] component_debug_init+0x17/0x30 [ 14.284206][ C0] do_one_initcall+0x8f/0x1e0 [ 14.284451][ C0] do_initcalls+0x176/0x280 [ 14.284679][ C0] kernel_init_freeable+0x22d/0x300 [ 14.284907][ C0] kernel_init+0x20/0x200 [ 14.285131][ C0] ret_from_fork+0x240/0x320 [ 14.285359][ C0] ret_from_fork_asm+0x1a/0x30 [ 14.285580][ C0] irq event stamp: 410868 [ 14.285750][ C0] hardirqs last enabled at (410868): [] _raw_spin_unlock_irqrestore+0x5d/0x80 [ 14.286200][ C0] hardirqs last disabled at (410867): [] _raw_spin_lock_irqsave+0x5b/0x60 [ 14.286653][ C0] softirqs last enabled at (409604): [] handle_softirqs+0x358/0x620 [ 14.287061][ C0] softirqs last disabled at (409635): [] __irq_exit_rcu+0xfa/0x160 [ 14.287449][ C0] [ 14.287449][ C0] other info that might help us debug this: [ 14.287787][ C0] Possible unsafe locking scenario: [ 14.287787][ C0] [ 14.288113][ C0] CPU0 [ 14.288282][ C0] ---- [ 14.288449][ C0] lock(pin_fs_lock); [ 14.288625][ C0] [ 14.288791][ C0] lock(pin_fs_lock); [ 14.288961][ C0] [ 14.288961][ C0] *** DEADLOCK *** [ 14.288961][ C0] [ 14.289292][ C0] 1 lock held by swapper/0/0: [ 14.289518][ C0] #0: ffffffff9df774c0 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x2e4/0xf20 [ 14.289918][ C0] [ 14.289918][ C0] stack backtrace: [ 14.290195][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 14.290200][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.290203][ C0] Call Trace: [ 14.290205][ C0] [ 14.290207][ C0] dump_stack_lvl+0x82/0xd0 [ 14.290215][ C0] print_usage_bug.part.0+0x25f/0x360 [ 14.290220][ C0] mark_lock_irq+0x343/0x400 [ 14.290224][ C0] ? is_bpf_text_address+0x67/0x120 [ 14.290230][ C0] ? __lock_release+0x5d/0x170 [ 14.290235][ C0] ? save_trace+0x52/0x170 [ 14.290239][ C0] mark_lock+0x174/0x2f0 [ 14.290243][ C0] mark_usage+0x46/0x150 [ 14.290246][ C0] __lock_acquire+0x20b/0x7e0 [ 14.290250][ C0] ? __lock_acquire+0x44d/0x7e0 [ 14.290254][ C0] lock_acquire.part.0+0xb6/0x240 [ 14.290258][ C0] ? simple_pin_fs+0x24/0x150 [ 14.290262][ C0] ? rcu_is_watching+0x12/0xc0 [ 14.290268][ C0] ? lock_acquire+0x10c/0x170 [ 14.290271][ C0] ? simple_pin_fs+0x24/0x150 [ 14.290275][ C0] _raw_spin_lock+0x33/0x40 [ 14.290278][ C0] ? simple_pin_fs+0x24/0x150 [ 14.290280][ C0] simple_pin_fs+0x24/0x150 [ 14.290283][ C0] debugfs_remove+0x35/0x70 [ 14.290288][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 14.290294][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 14.290296][ C0] ? __sk_destruct+0x383/0x780 [ 14.290303][ C0] ? rcu_do_batch+0x34c/0xf20 [ 14.290306][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 14.290309][ C0] ? irq_exit_rcu+0xe/0x30 [ 14.290312][ C0] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 14.290317][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 14.290322][ C0] ? pv_native_safe_halt+0xf/0x20 [ 14.290325][ C0] ? default_idle+0x9/0x10 [ 14.290327][ C0] ? default_idle_call+0x6d/0xb0 [ 14.290330][ C0] ? cpuidle_idle_call+0x26f/0x3a0 [ 14.290336][ C0] ? do_idle+0xf6/0x160 [ 14.290338][ C0] ? cpu_startup_entry+0x54/0x60 [ 14.290342][ C0] ? rest_init+0x14b/0x260 [ 14.290344][ C0] ? start_kernel+0x350/0x410 [ 14.290350][ C0] __put_net+0x19/0x60 [ 14.290355][ C0] __sk_destruct+0x58a/0x780 [ 14.290359][ C0] ? rcu_do_batch+0x34a/0xf20 [ 14.290362][ C0] rcu_do_batch+0x34c/0xf20 [ 14.290366][ C0] ? find_held_lock+0x2b/0x80 [ 14.290369][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 14.290373][ C0] ? _raw_spin_unlock_irqrestore+0x5d/0x80 [ 14.290376][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 14.290380][ C0] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 14.290385][ C0] rcu_core+0x2bb/0x500 [ 14.290390][ C0] handle_softirqs+0x218/0x620 [ 14.290395][ C0] __irq_exit_rcu+0xfa/0x160 [ 14.290399][ C0] irq_exit_rcu+0xe/0x30 [ 14.290402][ C0] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 14.290406][ C0] [ 14.290407][ C0] [ 14.290409][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 14.290412][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 14.290416][ C0] Code: a2 b4 00 c3 cc cc cc cc 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 83 17 0f 00 fb f4 fc 54 02 00 66 2e 0f 1f 84 00 00 00 00 00 66 90 90 90 90 90 90 [ 14.290419][ C0] RSP: 0018:ffffffff9dc07df8 EFLAGS: 00000246 [ 14.290423][ C0] RAX: 0000000000064021 RBX: 1ffffffff3b80fc3 RCX: ffffffff9cfa25d9 [ 14.290425][ C0] RDX: 0000000000000000 RSI: ffffffff9da9175c RDI: ffffffff9d253ca0 [ 14.290427][ C0] RBP: 0000000000000000 R08: 0000000000000001 R09: ffffed1006c066fa [ 14.290428][ C0] R10: ffff8880360337d3 R11: ffffffff9dc11288 R12: 0000000000000000 [ 14.290430][ C0] R13: ffffffff9dc1f240 R14: dffffc0000000000 R15: 0000000000014770 [ 14.290434][ C0] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 14.290439][ C0] default_idle+0x9/0x10 [ 14.290442][ C0] default_idle_call+0x6d/0xb0 [ 14.290444][ C0] cpuidle_idle_call+0x26f/0x3a0 [ 14.290447][ C0] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 14.290452][ C0] ? tsc_verify_tsc_adjust+0x98/0x310 [ 14.290458][ C0] do_idle+0xf6/0x160 [ 14.290462][ C0] cpu_startup_entry+0x54/0x60 [ 14.290465][ C0] rest_init+0x14b/0x260 [ 14.290469][ C0] start_kernel+0x350/0x410 [ 14.290473][ C0] x86_64_start_reservations+0x18/0x30 [ 14.290478][ C0] x86_64_start_kernel+0xf8/0x150 [ 14.290482][ C0] common_startup_64+0x13e/0x148 [ 14.290493][ C0] [ 14.310793][ C0] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 14.311158][ C0] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 0, name: swapper/0 [ 14.311801][ C0] preempt_count: 101, expected: 0 [ 14.312021][ C0] RCU nest depth: 0, expected: 0 [ 14.312234][ C0] INFO: lockdep is turned off. [ 14.312449][ C0] Preemption disabled at: [ 14.312451][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 14.313197][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 14.313202][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.313204][ C0] Call Trace: [ 14.313206][ C0] [ 14.313209][ C0] dump_stack_lvl+0xb0/0xd0 [ 14.313215][ C0] __might_resched+0x360/0x5a0 [ 14.313225][ C0] down_write+0x72/0x210 [ 14.313229][ C0] ? __pfx_down_write+0x10/0x10 [ 14.313232][ C0] ? simple_recursive_removal+0x33/0x610 [ 14.313238][ C0] ? rcu_is_watching+0x12/0xc0 [ 14.313246][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 14.313250][ C0] simple_recursive_removal+0x6a/0x610 [ 14.313253][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 14.313256][ C0] ? __pfx_remove_one+0x10/0x10 [ 14.313262][ C0] debugfs_remove+0x44/0x70 [ 14.313266][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 14.313271][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 14.313274][ C0] ? __sk_destruct+0x383/0x780 [ 14.313280][ C0] ? rcu_do_batch+0x34c/0xf20 [ 14.313285][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 14.313288][ C0] ? irq_exit_rcu+0xe/0x30 [ 14.313295][ C0] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 14.313300][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 14.313305][ C0] ? pv_native_safe_halt+0xf/0x20 [ 14.313308][ C0] ? default_idle+0x9/0x10 [ 14.313311][ C0] ? default_idle_call+0x6d/0xb0 [ 14.313313][ C0] ? cpuidle_idle_call+0x26f/0x3a0 [ 14.313318][ C0] ? do_idle+0xf6/0x160 [ 14.313321][ C0] ? cpu_startup_entry+0x54/0x60 [ 14.313323][ C0] ? rest_init+0x14b/0x260 [ 14.313326][ C0] ? start_kernel+0x350/0x410 [ 14.313336][ C0] __put_net+0x19/0x60 [ 14.313341][ C0] __sk_destruct+0x58a/0x780 [ 14.313345][ C0] ? rcu_do_batch+0x34a/0xf20 [ 14.313348][ C0] rcu_do_batch+0x34c/0xf20 [ 14.313352][ C0] ? find_held_lock+0x2b/0x80 [ 14.313357][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 14.313361][ C0] ? _raw_spin_unlock_irqrestore+0x5d/0x80 [ 14.313366][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 14.313370][ C0] ? _raw_spin_unlock_irqrestore+0x46/0x80 [ 14.313374][ C0] rcu_core+0x2bb/0x500 [ 14.313380][ C0] handle_softirqs+0x218/0x620 [ 14.313385][ C0] __irq_exit_rcu+0xfa/0x160 [ 14.313389][ C0] irq_exit_rcu+0xe/0x30 [ 14.313392][ C0] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 14.313395][ C0] [ 14.313396][ C0] [ 14.313398][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 14.313400][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x20 [ 14.313405][ C0] Code: a2 b4 00 c3 cc cc cc cc 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 83 17 0f 00 fb f4 fc 54 02 00 66 2e 0f 1f 84 00 00 00 00 00 66 90 90 90 90 90 90 [ 14.313407][ C0] RSP: 0018:ffffffff9dc07df8 EFLAGS: 00000246 [ 14.313411][ C0] RAX: 0000000000064021 RBX: 1ffffffff3b80fc3 RCX: ffffffff9cfa25d9 [ 14.313413][ C0] RDX: 0000000000000000 RSI: ffffffff9da9175c RDI: ffffffff9d253ca0 [ 14.313415][ C0] RBP: 0000000000000000 R08: 0000000000000001 R09: ffffed1006c066fa [ 14.313416][ C0] R10: ffff8880360337d3 R11: ffffffff9dc11288 R12: 0000000000000000 [ 14.313418][ C0] R13: ffffffff9dc1f240 R14: dffffc0000000000 R15: 0000000000014770 [ 14.313422][ C0] ? ct_kernel_exit.constprop.0+0x109/0x160 [ 14.313427][ C0] default_idle+0x9/0x10 [ 14.313429][ C0] default_idle_call+0x6d/0xb0 [ 14.313432][ C0] cpuidle_idle_call+0x26f/0x3a0 [ 14.313435][ C0] ? __pfx_cpuidle_idle_call+0x10/0x10 [ 14.313439][ C0] ? tsc_verify_tsc_adjust+0x98/0x310 [ 14.313446][ C0] do_idle+0xf6/0x160 [ 14.313450][ C0] cpu_startup_entry+0x54/0x60 [ 14.313453][ C0] rest_init+0x14b/0x260 [ 14.313456][ C0] start_kernel+0x350/0x410 [ 14.313460][ C0] x86_64_start_reservations+0x18/0x30 [ 14.313465][ C0] x86_64_start_kernel+0xf8/0x150 [ 14.313470][ C0] common_startup_64+0x13e/0x148 [ 14.313479][ C0] [ 14.896297][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 15.938031][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 15.940783][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 16.961956][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 16.963778][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 17.985974][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 17.987621][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 19.010041][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 19.012758][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 20.033959][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 20.035546][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 21.057980][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK