[ 13.138290][ C0] [ 13.138434][ C0] ================================ [ 13.138634][ C0] WARNING: inconsistent lock state [ 13.138828][ C0] 6.15.0-virtme #1 Not tainted [ 13.139020][ C0] -------------------------------- [ 13.139209][ C0] inconsistent {SOFTIRQ-ON-W} -> {IN-SOFTIRQ-W} usage. [ 13.139457][ C0] rcu_exp_gp_kthr/17 [HC0[0]:SC1[1]:HE1:SE0] takes: [ 13.139703][ C0] ffffffffa5fc5af8 (pin_fs_lock){+.?.}-{3:3}, at: simple_pin_fs+0x24/0x150 [ 13.140056][ C0] {SOFTIRQ-ON-W} state was registered at: [ 13.140253][ C0] __lock_acquire+0x20b/0x7e0 [ 13.140450][ C0] lock_acquire.part.0+0xb6/0x240 [ 13.140642][ C0] _raw_spin_lock+0x33/0x40 [ 13.140843][ C0] simple_pin_fs+0x24/0x150 [ 13.141037][ C0] start_creating.part.0+0x52/0x310 [ 13.141233][ C0] debugfs_create_dir+0x6a/0x540 [ 13.141425][ C0] component_debug_init+0x17/0x30 [ 13.141622][ C0] do_one_initcall+0x8f/0x1e0 [ 13.141821][ C0] do_initcalls+0x176/0x280 [ 13.142015][ C0] kernel_init_freeable+0x22d/0x300 [ 13.142215][ C0] kernel_init+0x20/0x200 [ 13.142408][ C0] ret_from_fork+0x240/0x320 [ 13.142608][ C0] ret_from_fork_asm+0x1a/0x30 [ 13.142804][ C0] irq event stamp: 1246 [ 13.142953][ C0] hardirqs last enabled at (1246): [] _raw_spin_unlock_irqrestore+0x5d/0x80 [ 13.143335][ C0] hardirqs last disabled at (1245): [] _raw_spin_lock_irqsave+0x5b/0x60 [ 13.143679][ C0] softirqs last enabled at (0): [] copy_process+0x1594/0x5210 [ 13.144018][ C0] softirqs last disabled at (663): [] __irq_exit_rcu+0xfa/0x160 [ 13.144364][ C0] [ 13.144364][ C0] other info that might help us debug this: [ 13.144654][ C0] Possible unsafe locking scenario: [ 13.144654][ C0] [ 13.144940][ C0] CPU0 [ 13.145085][ C0] ---- [ 13.145233][ C0] lock(pin_fs_lock); [ 13.145383][ C0] [ 13.145529][ C0] lock(pin_fs_lock); [ 13.145679][ C0] [ 13.145679][ C0] *** DEADLOCK *** [ 13.145679][ C0] [ 13.145961][ C0] 1 lock held by rcu_exp_gp_kthr/17: [ 13.146153][ C0] #0: ffffffffa5d774c0 (rcu_callback){....}-{0:0}, at: rcu_do_batch+0x2e4/0xf20 [ 13.146503][ C0] [ 13.146503][ C0] stack backtrace: [ 13.146747][ C0] CPU: 0 UID: 0 PID: 17 Comm: rcu_exp_gp_kthr Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 13.146752][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 13.146754][ C0] Call Trace: [ 13.146756][ C0] [ 13.146759][ C0] dump_stack_lvl+0x82/0xd0 [ 13.146764][ C0] print_usage_bug.part.0+0x25f/0x360 [ 13.146769][ C0] mark_lock_irq+0x343/0x400 [ 13.146774][ C0] ? __pfx_unwind_next_frame+0x10/0x10 [ 13.146779][ C0] ? save_trace+0x52/0x170 [ 13.146783][ C0] mark_lock+0x174/0x2f0 [ 13.146787][ C0] mark_usage+0x46/0x150 [ 13.146790][ C0] __lock_acquire+0x20b/0x7e0 [ 13.146793][ C0] ? __lock_acquire+0x44d/0x7e0 [ 13.146798][ C0] lock_acquire.part.0+0xb6/0x240 [ 13.146801][ C0] ? simple_pin_fs+0x24/0x150 [ 13.146804][ C0] ? rcu_is_watching+0x12/0xc0 [ 13.146809][ C0] ? lock_acquire+0x10c/0x170 [ 13.146812][ C0] ? simple_pin_fs+0x24/0x150 [ 13.146816][ C0] _raw_spin_lock+0x33/0x40 [ 13.146819][ C0] ? simple_pin_fs+0x24/0x150 [ 13.146821][ C0] simple_pin_fs+0x24/0x150 [ 13.146824][ C0] debugfs_remove+0x35/0x70 [ 13.146828][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 13.146832][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 13.146835][ C0] ? __sk_destruct+0x383/0x780 [ 13.146840][ C0] ? rcu_do_batch+0x34c/0xf20 [ 13.146843][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 13.146846][ C0] ? irq_exit_rcu+0xe/0x30 [ 13.146849][ C0] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 13.146854][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 13.146857][ C0] ? finish_task_switch.isra.0+0x251/0x970 [ 13.146862][ C0] ? __schedule+0x86a/0x1b00 [ 13.146866][ C0] ? schedule+0xe2/0x270 [ 13.146869][ C0] ? kthread_worker_fn+0x3c6/0x760 [ 13.146872][ C0] ? kthread+0x37e/0x600 [ 13.146875][ C0] ? ret_from_fork+0x240/0x320 [ 13.146878][ C0] ? ret_from_fork_asm+0x1a/0x30 [ 13.146883][ C0] __put_net+0x19/0x60 [ 13.146887][ C0] __sk_destruct+0x58a/0x780 [ 13.146891][ C0] ? rcu_do_batch+0x34a/0xf20 [ 13.146894][ C0] rcu_do_batch+0x34c/0xf20 [ 13.146899][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 13.146903][ C0] ? rcu_is_watching+0x12/0xc0 [ 13.146906][ C0] ? note_gp_changes+0x182/0x1e0 [ 13.146910][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 13.146915][ C0] rcu_core+0x2bb/0x500 [ 13.146919][ C0] handle_softirqs+0x218/0x620 [ 13.146925][ C0] __irq_exit_rcu+0xfa/0x160 [ 13.146929][ C0] irq_exit_rcu+0xe/0x30 [ 13.146932][ C0] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 13.146935][ C0] [ 13.146936][ C0] [ 13.146938][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 13.146941][ C0] RIP: 0010:finish_task_switch.isra.0+0x251/0x970 [ 13.146944][ C0] Code: 4c 89 ff 48 c7 03 00 00 00 00 e8 f2 ed cc 1d 4d 85 e4 75 ba 4c 89 ff e8 ad 04 89 02 e8 98 1f 31 00 fb 65 48 8b 1d f7 d2 1f 05 <66> 90 48 83 7d d0 00 74 55 65 48 8b 1d e6 d2 1f 05 48 8d bb 18 05 [ 13.146947][ C0] RSP: 0018:ffffc90000117c68 EFLAGS: 00000206 [ 13.146950][ C0] RAX: 0000000000000295 RBX: ffff888001bea300 RCX: ffffffffa2844b9f [ 13.146952][ C0] RDX: 0000000000000000 RSI: ffffffffa589175c RDI: ffffffffa5053ca0 [ 13.146954][ C0] RBP: ffffc90000117ca8 R08: 0000000000000001 R09: 0000000000000001 [ 13.146956][ C0] R10: ffffffffa67e7cd7 R11: ffff888036038dd8 R12: ffff888036038dd8 [ 13.146957][ C0] R13: ffff888001b1c5c0 R14: ffff888036139968 R15: ffff888036038dc0 [ 13.146961][ C0] ? trace_irq_enable.constprop.0+0x2f/0x130 [ 13.146967][ C0] ? finish_task_switch.isra.0+0x248/0x970 [ 13.146970][ C0] ? __switch_to+0x503/0xd60 [ 13.146974][ C0] __schedule+0x86a/0x1b00 [ 13.146980][ C0] ? __pfx___schedule+0x10/0x10 [ 13.146986][ C0] ? schedule+0x1ee/0x270 [ 13.146989][ C0] ? __lock_release+0x5d/0x170 [ 13.146994][ C0] ? __pfx_wait_rcu_exp_gp+0x10/0x10 [ 13.146997][ C0] schedule+0xe2/0x270 [ 13.147000][ C0] kthread_worker_fn+0x3c6/0x760 [ 13.147004][ C0] ? __pfx_kthread_worker_fn+0x10/0x10 [ 13.147007][ C0] kthread+0x37e/0x600 [ 13.147010][ C0] ? __pfx_kthread+0x10/0x10 [ 13.147013][ C0] ? ret_from_fork+0x1b/0x320 [ 13.147016][ C0] ? __lock_release+0x5d/0x170 [ 13.147019][ C0] ? rcu_is_watching+0x12/0xc0 [ 13.147022][ C0] ? __pfx_kthread+0x10/0x10 [ 13.147026][ C0] ret_from_fork+0x240/0x320 [ 13.147028][ C0] ? __pfx_kthread+0x10/0x10 [ 13.147031][ C0] ret_from_fork_asm+0x1a/0x30 [ 13.147038][ C0] [ 13.164189][ C0] BUG: sleeping function called from invalid context at kernel/locking/rwsem.c:1576 [ 13.164618][ C0] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 17, name: rcu_exp_gp_kthr [ 13.164937][ C0] preempt_count: 101, expected: 0 [ 13.165119][ C0] RCU nest depth: 0, expected: 0 [ 13.165296][ C0] INFO: lockdep is turned off. [ 13.165472][ C0] Preemption disabled at: [ 13.165474][ C0] [] schedule+0xdb/0x270 [ 13.165846][ C0] CPU: 0 UID: 0 PID: 17 Comm: rcu_exp_gp_kthr Not tainted 6.15.0-virtme #1 PREEMPT(full) [ 13.165851][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 13.165853][ C0] Call Trace: [ 13.165855][ C0] [ 13.165857][ C0] dump_stack_lvl+0xb0/0xd0 [ 13.165862][ C0] __might_resched+0x360/0x5a0 [ 13.165869][ C0] down_write+0x72/0x210 [ 13.165873][ C0] ? __pfx_down_write+0x10/0x10 [ 13.165875][ C0] ? simple_recursive_removal+0x33/0x610 [ 13.165880][ C0] ? rcu_is_watching+0x12/0xc0 [ 13.165885][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 13.165889][ C0] simple_recursive_removal+0x6a/0x610 [ 13.165892][ C0] ? do_raw_spin_unlock+0x58/0x220 [ 13.165894][ C0] ? __pfx_remove_one+0x10/0x10 [ 13.165900][ C0] debugfs_remove+0x44/0x70 [ 13.165903][ C0] ref_tracker_dir_exit+0xcf/0x740 [ 13.165907][ C0] ? __pfx_ref_tracker_free+0x10/0x10 [ 13.165910][ C0] ? __sk_destruct+0x383/0x780 [ 13.165914][ C0] ? rcu_do_batch+0x34c/0xf20 [ 13.165917][ C0] ? __pfx_ref_tracker_dir_exit+0x10/0x10 [ 13.165920][ C0] ? irq_exit_rcu+0xe/0x30 [ 13.165924][ C0] ? sysvec_apic_timer_interrupt+0xa8/0xc0 [ 13.165929][ C0] ? asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 13.165933][ C0] ? finish_task_switch.isra.0+0x251/0x970 [ 13.165937][ C0] ? __schedule+0x86a/0x1b00 [ 13.165940][ C0] ? schedule+0xe2/0x270 [ 13.165943][ C0] ? kthread_worker_fn+0x3c6/0x760 [ 13.165946][ C0] ? kthread+0x37e/0x600 [ 13.165948][ C0] ? ret_from_fork+0x240/0x320 [ 13.165952][ C0] ? ret_from_fork_asm+0x1a/0x30 [ 13.165958][ C0] __put_net+0x19/0x60 [ 13.165962][ C0] __sk_destruct+0x58a/0x780 [ 13.165966][ C0] ? rcu_do_batch+0x34a/0xf20 [ 13.165968][ C0] rcu_do_batch+0x34c/0xf20 [ 13.165973][ C0] ? __pfx_rcu_do_batch+0x10/0x10 [ 13.165977][ C0] ? rcu_is_watching+0x12/0xc0 [ 13.165980][ C0] ? note_gp_changes+0x182/0x1e0 [ 13.165983][ C0] ? lockdep_hardirqs_on+0x7c/0x110 [ 13.165988][ C0] rcu_core+0x2bb/0x500 [ 13.165992][ C0] handle_softirqs+0x218/0x620 [ 13.165998][ C0] __irq_exit_rcu+0xfa/0x160 [ 13.166001][ C0] irq_exit_rcu+0xe/0x30 [ 13.166004][ C0] sysvec_apic_timer_interrupt+0xa8/0xc0 [ 13.166008][ C0] [ 13.166009][ C0] [ 13.166010][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 13.166013][ C0] RIP: 0010:finish_task_switch.isra.0+0x251/0x970 [ 13.166016][ C0] Code: 4c 89 ff 48 c7 03 00 00 00 00 e8 f2 ed cc 1d 4d 85 e4 75 ba 4c 89 ff e8 ad 04 89 02 e8 98 1f 31 00 fb 65 48 8b 1d f7 d2 1f 05 <66> 90 48 83 7d d0 00 74 55 65 48 8b 1d e6 d2 1f 05 48 8d bb 18 05 [ 13.166019][ C0] RSP: 0018:ffffc90000117c68 EFLAGS: 00000206 [ 13.166022][ C0] RAX: 0000000000000295 RBX: ffff888001bea300 RCX: ffffffffa2844b9f [ 13.166024][ C0] RDX: 0000000000000000 RSI: ffffffffa589175c RDI: ffffffffa5053ca0 [ 13.166026][ C0] RBP: ffffc90000117ca8 R08: 0000000000000001 R09: 0000000000000001 [ 13.166027][ C0] R10: ffffffffa67e7cd7 R11: ffff888036038dd8 R12: ffff888036038dd8 [ 13.166029][ C0] R13: ffff888001b1c5c0 R14: ffff888036139968 R15: ffff888036038dc0 [ 13.166033][ C0] ? trace_irq_enable.constprop.0+0x2f/0x130 [ 13.166038][ C0] ? finish_task_switch.isra.0+0x248/0x970 [ 13.166041][ C0] ? __switch_to+0x503/0xd60 [ 13.166046][ C0] __schedule+0x86a/0x1b00 [ 13.166051][ C0] ? __pfx___schedule+0x10/0x10 [ 13.166058][ C0] ? schedule+0x1ee/0x270 [ 13.166060][ C0] ? __lock_release+0x5d/0x170 [ 13.166066][ C0] ? __pfx_wait_rcu_exp_gp+0x10/0x10 [ 13.166069][ C0] schedule+0xe2/0x270 [ 13.166073][ C0] kthread_worker_fn+0x3c6/0x760 [ 13.166076][ C0] ? __pfx_kthread_worker_fn+0x10/0x10 [ 13.166080][ C0] kthread+0x37e/0x600 [ 13.166083][ C0] ? __pfx_kthread+0x10/0x10 [ 13.166085][ C0] ? ret_from_fork+0x1b/0x320 [ 13.166088][ C0] ? __lock_release+0x5d/0x170 [ 13.166092][ C0] ? rcu_is_watching+0x12/0xc0 [ 13.166095][ C0] ? __pfx_kthread+0x10/0x10 [ 13.166098][ C0] ret_from_fork+0x240/0x320 [ 13.166101][ C0] ? __pfx_kthread+0x10/0x10 [ 13.166104][ C0] ret_from_fork_asm+0x1a/0x30 [ 13.166110][ C0] [ 13.646267][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 14.685975][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 14.687564][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 15.709996][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 15.711551][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 16.733982][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 16.735532][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 17.757995][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 17.759521][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 18.781910][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 18.783362][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 19.805980][ C0] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK