[ 15.355019][ T300] ip (300) used greatest stack depth: 23856 bytes left [ 15.355034][ T300] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 15.355036][ T300] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 300, name: ip [ 15.355037][ T300] preempt_count: 2, expected: 0 [ 15.355038][ T300] RCU nest depth: 0, expected: 0 [ 15.355039][ T300] locks held by ip/300: 5, last CPU#2: [ 15.355041][ T300] #0: ffffffffb64127b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 15.355052][ T300] #1: ffffffffb6579cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 15.355058][ T300] #2: ffffffffb6579d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 15.355061][ T300] #3: ffffffffb6499660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 15.355065][ T300] #4: ffffffffb6499560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 15.355069][ T300] irq event stamp: 37424 [ 15.355070][ T300] hardirqs last enabled at (37423): [] __down_trylock_console_sem+0x86/0xa0 [ 15.355072][ T300] hardirqs last disabled at (37424): [] console_emit_next_record+0x3d4/0x4c0 [ 15.355074][ T300] softirqs last enabled at (36138): [] netlink_release+0x17b/0xcf0 [ 15.355078][ T300] softirqs last disabled at (36136): [] netlink_release+0xd2/0xcf0 [ 15.355081][ T300] Preemption disabled at: [ 15.355081][ T300] [<0000000000000000>] 0x0 [ 15.355088][ T300] CPU: 2 UID: 0 PID: 300 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.355091][ T300] Tainted: [W]=WARN [ 15.355092][ T300] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.355093][ T300] Call Trace: [ 15.355095][ T300] [ 15.355097][ T300] dump_stack_lvl+0x6f/0xa0 [ 15.355102][ T300] __might_resched.cold+0x1fe/0x2c1 [ 15.355107][ T300] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 15.355111][ T300] ? __kmalloc_noprof+0xdb/0x760 [ 15.355116][ T300] __kmalloc_noprof+0x443/0x760 [ 15.355118][ T300] ? alloc_buf.isra.0+0x4b/0x260 [ 15.355124][ T300] ? do_raw_spin_unlock+0x59/0x250 [ 15.355126][ T300] alloc_buf.isra.0+0x4b/0x260 [ 15.355130][ T300] put_chars+0x1e1/0x2f0 [ 15.355133][ T300] ? __send_to_port+0x420/0x420 [ 15.355134][ T300] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.355139][ T300] ? validate_chain+0x38b/0xc20 [ 15.355144][ T300] hvc_console_print+0x292/0x780 [ 15.355151][ T300] ? hvc_write+0x3a0/0x3a0 [ 15.355153][ T300] ? rcu_is_watching+0x16/0xd0 [ 15.355155][ T300] ? lock_acquire+0x13c/0x160 [ 15.355159][ T300] console_emit_next_record+0x22f/0x4c0 [ 15.355163][ T300] ? devkmsg_read+0x4b0/0x4b0 [ 15.355165][ T300] ? console_flush_one_record+0x106/0x710 [ 15.355168][ T300] ? rcu_is_watching+0x16/0xd0 [ 15.355170][ T300] ? lock_acquire+0x13c/0x160 [ 15.355174][ T300] console_flush_one_record+0x46f/0x710 [ 15.355178][ T300] ? console_emit_next_record+0x4c0/0x4c0 [ 15.355179][ T300] ? __lock_acquire+0x518/0xc20 [ 15.355184][ T300] console_unlock+0xee/0x1f0 [ 15.355187][ T300] ? console_flush_one_record+0x710/0x710 [ 15.355189][ T300] ? rcu_is_watching+0x16/0xd0 [ 15.355191][ T300] ? lock_acquire+0x60/0x160 [ 15.355195][ T300] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.355196][ T300] ? vprintk_emit+0x320/0x3e0 [ 15.355199][ T300] vprintk_emit+0x37c/0x3e0 [ 15.355203][ T300] ? wake_up_klogd_work_func+0x90/0x90 [ 15.355206][ T300] ? __lock_acquire+0x518/0xc20 [ 15.355210][ T300] _printk+0xc7/0x100 [ 15.355213][ T300] ? snapshot_read.cold+0x21/0x21 [ 15.355216][ T300] ? do_raw_spin_lock+0x131/0x280 [ 15.355218][ T300] ? __rwlock_init+0x150/0x150 [ 15.355222][ T300] ? do_raw_spin_lock+0x131/0x280 [ 15.355225][ T300] do_exit.cold+0x82/0x9c [ 15.355229][ T300] ? exit_notify+0x890/0x890 [ 15.355230][ T300] ? __lock_release.isra.0+0x69/0x1a0 [ 15.355232][ T300] ? rcu_is_watching+0x16/0xd0 [ 15.355236][ T300] do_group_exit+0xb8/0x370 [ 15.355241][ T300] __x64_sys_exit_group+0x3c/0x50 [ 15.355243][ T300] x64_sys_call+0x1567/0x1570 [ 15.355246][ T300] do_syscall_64+0xff/0x530 [ 15.355249][ T300] ? exc_page_fault+0xee/0x100 [ 15.355252][ T300] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.355254][ T300] RIP: 0033:0x7fe59fd471b8 [ 15.355257][ T300] Code: Unable to access opcode bytes at 0x7fe59fd4718e. [ 15.355258][ T300] RSP: 002b:00007ffc3bc2d3f8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 15.355260][ T300] RAX: ffffffffffffffda RBX: 00007fe59fe77f88 RCX: 00007fe59fd471b8 [ 15.355262][ T300] RDX: 00007fe59fa91fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 15.355263][ T300] RBP: 00007ffc3bc2d450 R08: 0000000000000000 R09: 0000000000000000 [ 15.355264][ T300] R10: 00007ffc3bc2d210 R11: 0000000000000246 R12: 0000000000000001 [ 15.355264][ T300] R13: 0000000000000000 R14: 00007fe59fe76680 R15: 00007fe59fe77fa0 [ 15.355271][ T300] [ 29.925809][ T752] iperf3 (752) used greatest stack depth: 23824 bytes left [ 29.925828][ T752] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 29.925831][ T752] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 752, name: iperf3 [ 29.925832][ T752] preempt_count: 2, expected: 0 [ 29.925833][ T752] RCU nest depth: 0, expected: 0 [ 29.925834][ T752] locks held by iperf3/752: 5, last CPU#0: [ 29.925836][ T752] #0: ffffffffb64127b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 29.925848][ T752] #1: ffffffffb6579cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 29.925854][ T752] #2: ffffffffb6579d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 29.925857][ T752] #3: ffffffffb6499660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 29.925861][ T752] #4: ffffffffb6499560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 29.925865][ T752] irq event stamp: 7494276 [ 29.925866][ T752] hardirqs last enabled at (7494275): [] __down_trylock_console_sem+0x86/0xa0 [ 29.925868][ T752] hardirqs last disabled at (7494276): [] console_emit_next_record+0x3d4/0x4c0 [ 29.925870][ T752] softirqs last enabled at (7493946): [] handle_softirqs+0x67c/0x900 [ 29.925872][ T752] softirqs last disabled at (7493855): [] __irq_exit_rcu+0x145/0x1c0 [ 29.925874][ T752] Preemption disabled at: [ 29.925875][ T752] [<0000000000000000>] 0x0 [ 29.925882][ T752] CPU: 0 UID: 0 PID: 752 Comm: iperf3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 29.925885][ T752] Tainted: [W]=WARN [ 29.925886][ T752] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 29.925888][ T752] Call Trace: [ 29.925890][ T752] [ 29.925891][ T752] dump_stack_lvl+0x6f/0xa0 [ 29.925898][ T752] __might_resched.cold+0x1fe/0x2c1 [ 29.925902][ T752] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 29.925906][ T752] ? __kmalloc_noprof+0xdb/0x760 [ 29.925911][ T752] __kmalloc_noprof+0x443/0x760 [ 29.925913][ T752] ? alloc_buf.isra.0+0x4b/0x260 [ 29.925919][ T752] ? do_raw_spin_unlock+0x59/0x250 [ 29.925921][ T752] alloc_buf.isra.0+0x4b/0x260 [ 29.925925][ T752] put_chars+0x1e1/0x2f0 [ 29.925927][ T752] ? prb_final_commit+0x50/0x50 [ 29.925929][ T752] ? __send_to_port+0x420/0x420 [ 29.925932][ T752] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 29.925936][ T752] ? rcu_read_lock_any_held+0x3c/0x90 [ 29.925939][ T752] ? validate_chain+0x38b/0xc20 [ 29.925942][ T752] hvc_console_print+0x292/0x780 [ 29.925945][ T752] ? mark_usage+0x61/0x170 [ 29.925947][ T752] ? __lock_acquire+0x518/0xc20 [ 29.925949][ T752] ? __lock_acquire+0x518/0xc20 [ 29.925952][ T752] ? hvc_write+0x3a0/0x3a0 [ 29.925954][ T752] ? console_emit_next_record+0x1df/0x4c0 [ 29.925958][ T752] ? rcu_is_watching+0x16/0xd0 [ 29.925960][ T752] ? lock_acquire+0x13c/0x160 [ 29.925964][ T752] console_emit_next_record+0x22f/0x4c0 [ 29.925968][ T752] ? devkmsg_read+0x4b0/0x4b0 [ 29.925970][ T752] ? console_flush_one_record+0x106/0x710 [ 29.925973][ T752] ? rcu_is_watching+0x16/0xd0 [ 29.925975][ T752] ? lock_acquire+0x13c/0x160 [ 29.925979][ T752] console_flush_one_record+0x46f/0x710 [ 29.925983][ T752] ? console_emit_next_record+0x4c0/0x4c0 [ 29.925985][ T752] ? __lock_acquire+0x518/0xc20 [ 29.925989][ T752] console_unlock+0xee/0x1f0 [ 29.925992][ T752] ? console_flush_one_record+0x710/0x710 [ 29.925994][ T752] ? rcu_is_watching+0x16/0xd0 [ 29.925996][ T752] ? lock_acquire+0x60/0x160 [ 29.926000][ T752] ? __down_trylock_console_sem+0x5e/0xa0 [ 29.926001][ T752] ? vprintk_emit+0x320/0x3e0 [ 29.926004][ T752] vprintk_emit+0x37c/0x3e0 [ 29.926008][ T752] ? wake_up_klogd_work_func+0x90/0x90 [ 29.926011][ T752] ? __lock_acquire+0x518/0xc20 [ 29.926015][ T752] _printk+0xc7/0x100 [ 29.926018][ T752] ? snapshot_read.cold+0x21/0x21 [ 29.926021][ T752] ? do_raw_spin_lock+0x131/0x280 [ 29.926023][ T752] ? __rwlock_init+0x150/0x150 [ 29.926027][ T752] ? do_raw_spin_lock+0x131/0x280 [ 29.926030][ T752] do_exit.cold+0x82/0x9c [ 29.926033][ T752] ? exit_notify+0x890/0x890 [ 29.926038][ T752] __x64_sys_exit+0x42/0x50 [ 29.926040][ T752] x64_sys_call+0x156c/0x1570 [ 29.926043][ T752] do_syscall_64+0xff/0x530 [ 29.926046][ T752] ? exc_page_fault+0xee/0x100 [ 29.926049][ T752] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.926051][ T752] RIP: 0033:0x7fbb712d0c49 [ 29.926054][ T752] Code: Unable to access opcode bytes at 0x7fbb712d0c1f. [ 29.926055][ T752] RSP: 002b:00007fbb70a8fe60 EFLAGS: 00000246 ORIG_RAX: 000000000000003c [ 29.926057][ T752] RAX: ffffffffffffffda RBX: 00007fbb70a906c0 RCX: 00007fbb712d0c49 [ 29.926058][ T752] RDX: 0000000000000000 RSI: 0000000000801000 RDI: 0000000000000000 [ 29.926059][ T752] RBP: 00007fbb70a8ff70 R08: 0000000000000001 R09: 00007fbb70a906c0 [ 29.926060][ T752] R10: 0000000000000008 R11: 0000000000000246 R12: 00007fbb70290000 [ 29.926061][ T752] R13: 00007ffd1d414d30 R14: 0000000000000002 R15: 00007ffd1d414e37 [ 29.926067][ T752] [ 31.788753][ T846] tun0: deleting peer with id 1, reason 1 [ 31.788802][ T846] [ 31.788804][ T846] ===================================================== [ 31.788805][ T846] WARNING: SOFTIRQ-safe -> SOFTIRQ-unsafe lock order detected [ 31.788807][ T846] 7.2.0-virtme #1 Tainted: G W [ 31.788809][ T846] ----------------------------------------------------- [ 31.788809][ T846] ovpn-cli/846 [HC0[0]:SC0[2]:HE0:SE0] is trying to acquire: [ 31.788811][ T846] ffffffffb6499660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 31.788823][ T846] [ 31.788823][ T846] and this task is already holding: [ 31.788823][ T846] ff1100000d2a4e68 (&ovpn->lock){+.-.}-{3:3}, at: ovpn_peer_del+0xa0/0x590 [ovpn] [ 31.788831][ T846] which would create a new lock dependency: [ 31.788831][ T846] (&ovpn->lock){+.-.}-{3:3} -> (console_owner){....}-{0:0} [ 31.788834][ T846] [ 31.788834][ T846] but this new dependency connects a SOFTIRQ-irq-safe lock: [ 31.788834][ T846] (&ovpn->lock){+.-.}-{3:3} [ 31.788835][ T846] [ 31.788835][ T846] ... which became SOFTIRQ-irq-safe at: [ 31.788837][ T846] __lock_acquire+0x388/0xc20 [ 31.788840][ T846] lock_acquire.part.0+0xd4/0x280 [ 31.788842][ T846] _raw_spin_lock_bh+0x38/0x50 [ 31.788845][ T846] ovpn_peer_endpoints_update+0xbc6/0x1ba0 [ovpn] [ 31.788848][ T846] ovpn_decrypt_post+0x74a/0x13e0 [ovpn] [ 31.788851][ T846] ovpn_udp_encap_recv+0x295/0x610 [ovpn] [ 31.788853][ T846] udp_queue_rcv_one_skb+0x645/0xb00 [ 31.788857][ T846] udp_unicast_rcv_skb+0x366/0x450 [ 31.788858][ T846] udp_rcv+0xaba/0x1d90 [ 31.788859][ T846] ip_protocol_deliver_rcu+0x82/0x350 [ 31.788862][ T846] ip_local_deliver_finish+0x36f/0x610 [ 31.788863][ T846] ip_local_deliver+0x184/0x4c0 [ 31.788865][ T846] ip_rcv+0xdc/0x3d0 [ 31.788866][ T846] __netif_receive_skb_one_core+0xfc/0x180 [ 31.788869][ T846] process_backlog+0x431/0x14c0 [ 31.788871][ T846] __napi_poll+0xa7/0x3b0 [ 31.788873][ T846] net_rx_action+0x513/0xf50 [ 31.788874][ T846] handle_softirqs+0x1d3/0x900 [ 31.788877][ T846] do_softirq+0xac/0xe0 [ 31.788878][ T846] __local_bh_enable_ip+0x118/0x150 [ 31.788879][ T846] __dev_queue_xmit+0x979/0x1b80 [ 31.788881][ T846] ip_finish_output2+0x5b9/0x1bc0 [ 31.788883][ T846] ip_output+0x1ca/0x660 [ 31.788884][ T846] ip_push_pending_frames+0xf6/0x250 [ 31.788886][ T846] raw_sendmsg+0x1169/0x1cc0 [ 31.788887][ T846] __sys_sendto+0x2aa/0x400 [ 31.788890][ T846] __x64_sys_sendto+0xe4/0x1f0 [ 31.788892][ T846] do_syscall_64+0xff/0x530 [ 31.788894][ T846] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 31.788897][ T846] [ 31.788897][ T846] to a SOFTIRQ-irq-unsafe lock: [ 31.788897][ T846] (fs_reclaim){+.+.}-{0:0} [ 31.788899][ T846] [ 31.788899][ T846] ... which became SOFTIRQ-irq-unsafe at: [ 31.788899][ T846] ... [ 31.788900][ T846] __lock_acquire+0x388/0xc20 [ 31.788902][ T846] lock_acquire.part.0+0xd4/0x280 [ 31.788903][ T846] fs_reclaim_acquire+0xd5/0x120 [ 31.788905][ T846] __kmalloc_cache_noprof+0x6e/0x620 [ 31.788908][ T846] kthread_create_worker_on_node+0xea/0x210 [ 31.788910][ T846] workqueue_init+0x2a/0x680 [ 31.788913][ T846] kernel_init_freeable+0x2fe/0x630 [ 31.788916][ T846] kernel_init+0x21/0x150 [ 31.788918][ T846] ret_from_fork+0x474/0x6b0 [ 31.788920][ T846] ret_from_fork_asm+0x11/0x20 [ 31.788923][ T846] [ 31.788923][ T846] other info that might help us debug this: [ 31.788923][ T846] [ 31.788923][ T846] Chain exists of: [ 31.788923][ T846] &ovpn->lock --> console_owner --> fs_reclaim [ 31.788923][ T846] [ 31.788925][ T846] Possible interrupt unsafe locking scenario: [ 31.788925][ T846] [ 31.788926][ T846] CPU0 CPU1 [ 31.788927][ T846] ---- ---- [ 31.788927][ T846] lock(fs_reclaim); [ 31.788928][ T846] local_irq_disable(); [ 31.788928][ T846] lock(&ovpn->lock); [ 31.788929][ T846] lock(console_owner); [ 31.788930][ T846] [ 31.788930][ T846] lock(&ovpn->lock); [ 31.788931][ T846] [ 31.788931][ T846] *** DEADLOCK *** [ 31.788931][ T846] [ 31.788931][ T846] locks held by ovpn-cli/846: 4, last CPU#3: [ 31.788933][ T846] #0: ffffffffb6d44b68 (cb_lock){++++}-{4:4}, at: genl_rcv+0x19/0x40 [ 31.788938][ T846] #1: ff1100000d2a4e68 (&ovpn->lock){+.-.}-{3:3}, at: ovpn_peer_del+0xa0/0x590 [ovpn] [ 31.788942][ T846] #2: ffffffffb6579cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 31.788945][ T846] #3: ffffffffb6579d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 31.788948][ T846] [ 31.788948][ T846] the dependencies between SOFTIRQ-irq-safe lock and the holding lock: [ 31.788949][ T846] -> (&ovpn->lock){+.-.}-{3:3} { [ 31.788951][ T846] HARDIRQ-ON-W at: [ 31.788952][ T846] __lock_acquire+0x388/0xc20 [ 31.788954][ T846] lock_acquire.part.0+0xd4/0x280 [ 31.788955][ T846] _raw_spin_lock_bh+0x38/0x50 [ 31.788956][ T846] ovpn_peer_add_mp+0xad/0x6a0 [ovpn] [ 31.788958][ T846] ovpn_peer_add+0x152/0x330 [ovpn] [ 31.788961][ T846] ovpn_nl_peer_new_doit+0x54f/0xcf0 [ovpn] [ 31.788963][ T846] genl_family_rcv_msg_doit+0x206/0x300 [ 31.788965][ T846] genl_family_rcv_msg+0x3a4/0x640 [ 31.788966][ T846] genl_rcv_msg+0xbb/0x160 [ 31.788968][ T846] netlink_rcv_skb+0x14e/0x3a0 [ 31.788969][ T846] genl_rcv+0x28/0x40 [ 31.788971][ T846] netlink_unicast+0x486/0x750 [ 31.788972][ T846] netlink_sendmsg+0x735/0xc60 [ 31.788974][ T846] ____sys_sendmsg+0x415/0x880 [ 31.788975][ T846] ___sys_sendmsg+0x14e/0x1d0 [ 31.788977][ T846] __sys_sendmsg+0x12c/0x1d0 [ 31.788979][ T846] do_syscall_64+0xff/0x530 [ 31.788980][ T846] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 31.788981][ T846] IN-SOFTIRQ-W at: [ 31.788982][ T846] __lock_acquire+0x388/0xc20 [ 31.788983][ T846] lock_acquire.part.0+0xd4/0x280 [ 31.788984][ T846] _raw_spin_lock_bh+0x38/0x50 [ 31.788985][ T846] ovpn_peer_endpoints_update+0xbc6/0x1ba0 [ovpn] [ 31.788988][ T846] ovpn_decrypt_post+0x74a/0x13e0 [ovpn] [ 31.788990][ T846] ovpn_udp_encap_recv+0x295/0x610 [ovpn] [ 31.788992][ T846] udp_queue_rcv_one_skb+0x645/0xb00 [ 31.788993][ T846] udp_unicast_rcv_skb+0x366/0x450 [ 31.788995][ T846] udp_rcv+0xaba/0x1d90 [ 31.788996][ T846] ip_protocol_deliver_rcu+0x82/0x350 [ 31.788997][ T846] ip_local_deliver_finish+0x36f/0x610 [ 31.788998][ T846] ip_local_deliver+0x184/0x4c0 [ 31.789000][ T846] ip_rcv+0xdc/0x3d0 [ 31.789001][ T846] __netif_receive_skb_one_core+0xfc/0x180 [ 31.789003][ T846] process_backlog+0x431/0x14c0 [ 31.789004][ T846] __napi_poll+0xa7/0x3b0 [ 31.789006][ T846] net_rx_action+0x513/0xf50 [ 31.789007][ T846] handle_softirqs+0x1d3/0x900 [ 31.789008][ T846] do_softirq+0xac/0xe0 [ 31.789010][ T846] __local_bh_enable_ip+0x118/0x150 [ 31.789011][ T846] __dev_queue_xmit+0x979/0x1b80 [ 31.789012][ T846] ip_finish_output2+0x5b9/0x1bc0 [ 31.789014][ T846] ip_output+0x1ca/0x660 [ 31.789015][ T846] ip_push_pending_frames+0xf6/0x250 [ 31.789016][ T846] raw_sendmsg+0x1169/0x1cc0 [ 31.789017][ T846] __sys_sendto+0x2aa/0x400 [ 31.789019][ T846] __x64_sys_sendto+0xe4/0x1f0 [ 31.789020][ T846] do_syscall_64+0xff/0x530 [ 31.789022][ T846] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 31.789023][ T846] INITIAL USE at: [ 31.789023][ T846] __lock_acquire+0x388/0xc20 [ 31.789025][ T846] lock_acquire.part.0+0xd4/0x280 [ 31.789026][ T846] _raw_spin_lock_bh+0x38/0x50 [ 31.789027][ T846] ovpn_peer_add_mp+0xad/0x6a0 [ovpn] [ 31.789029][ T846] ovpn_peer_add+0x152/0x330 [ovpn] [ 31.789031][ T846] ovpn_nl_peer_new_doit+0x54f/0xcf0 [ovpn] [ 31.789033][ T846] genl_family_rcv_msg_doit+0x206/0x300 [ 31.789035][ T846] genl_family_rcv_msg+0x3a4/0x640 [ 31.789036][ T846] genl_rcv_msg+0xbb/0x160 [ 31.789038][ T846] netlink_rcv_skb+0x14e/0x3a0 [ 31.789039][ T846] genl_rcv+0x28/0x40 [ 31.789041][ T846] netlink_unicast+0x486/0x750 [ 31.789042][ T846] netlink_sendmsg+0x735/0xc60 [ 31.789044][ T846] ____sys_sendmsg+0x415/0x880 [ 31.789045][ T846] ___sys_sendmsg+0x14e/0x1d0 [ 31.789047][ T846] __sys_sendmsg+0x12c/0x1d0 [ 31.789048][ T846] do_syscall_64+0xff/0x530 [ 31.789049][ T846] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 31.789051][ T846] } [ 31.789051][ T846] ... key at: [] __key.84+0x0/0x40 [ovpn] [ 31.789054][ T846] [ 31.789054][ T846] the dependencies between the lock to be acquired [ 31.789054][ T846] and SOFTIRQ-irq-unsafe lock: [ 31.789059][ T846] -> (fs_reclaim){+.+.}-{0:0} { [ 31.789061][ T846] HARDIRQ-ON-W at: [ 31.789061][ T846] __lock_acquire+0x388/0xc20 [ 31.789063][ T846] lock_acquire.part.0+0xd4/0x280 [ 31.789064][ T846] fs_reclaim_acquire+0xd5/0x120 [ 31.789065][ T846] __kmalloc_cache_noprof+0x6e/0x620 [ 31.789066][ T846] kthread_create_worker_on_node+0xea/0x210 [ 31.789068][ T846] workqueue_init+0x2a/0x680 [ 31.789069][ T846] kernel_init_freeable+0x2fe/0x630 [ 31.789070][ T846] kernel_init+0x21/0x150 [ 31.789072][ T846] ret_from_fork+0x474/0x6b0 [ 31.789073][ T846] ret_from_fork_asm+0x11/0x20 [ 31.789074][ T846] SOFTIRQ-ON-W at: [ 31.789075][ T846] __lock_acquire+0x388/0xc20 [ 31.789076][ T846] lock_acquire.part.0+0xd4/0x280 [ 31.789077][ T846] fs_reclaim_acquire+0xd5/0x120 [ 31.789078][ T846] __kmalloc_cache_noprof+0x6e/0x620 [ 31.789079][ T846] kthread_create_worker_on_node+0xea/0x210 [ 31.789081][ T846] workqueue_init+0x2a/0x680 [ 31.789082][ T846] kernel_init_freeable+0x2fe/0x630 [ 31.789083][ T846] kernel_init+0x21/0x150 [ 31.789085][ T846] ret_from_fork+0x474/0x6b0 [ 31.789086][ T846] ret_from_fork_asm+0x11/0x20 [ 31.789087][ T846] INITIAL USE at: [ 31.789087][ T846] __lock_acquire+0x388/0xc20 [ 31.789089][ T846] lock_acquire.part.0+0xd4/0x280 [ 31.789090][ T846] fs_reclaim_acquire+0xd5/0x120 [ 31.789091][ T846] __kmalloc_cache_noprof+0x6e/0x620 [ 31.789092][ T846] kthread_create_worker_on_node+0xea/0x210 [ 31.789094][ T846] workqueue_init+0x2a/0x680 [ 31.789094][ T846] kernel_init_freeable+0x2fe/0x630 [ 31.789096][ T846] kernel_init+0x21/0x150 [ 31.789097][ T846] ret_from_fork+0x474/0x6b0 [ 31.789098][ T846] ret_from_fork_asm+0x11/0x20 [ 31.789099][ T846] } [ 31.789100][ T846] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 31.789103][ T846] ... acquired at: [ 31.789104][ T846] __lock_acquire+0x518/0xc20 [ 31.789105][ T846] lock_acquire.part.0+0xd4/0x280 [ 31.789106][ T846] fs_reclaim_acquire+0xd5/0x120 [ 31.789107][ T846] __kmalloc_noprof+0xd3/0x760 [ 31.789109][ T846] alloc_buf.isra.0+0x4b/0x260 [ 31.789112][ T846] put_chars+0x1e1/0x2f0 [ 31.789113][ T846] hvc_console_print+0x292/0x780 [ 31.789116][ T846] console_emit_next_record+0x22f/0x4c0 [ 31.789117][ T846] console_flush_one_record+0x46f/0x710 [ 31.789119][ T846] console_unlock+0xee/0x1f0 [ 31.789120][ T846] vprintk_emit+0x37c/0x3e0 [ 31.789122][ T846] _printk+0xc7/0x100 [ 31.789124][ T846] nf_conntrack_irc_init+0x1d/0x130 [ 31.789127][ T846] do_one_initcall+0x124/0x4f0 [ 31.789128][ T846] kernel_init_freeable+0x596/0x630 [ 31.789130][ T846] kernel_init+0x21/0x150 [ 31.789131][ T846] ret_from_fork+0x474/0x6b0 [ 31.789132][ T846] ret_from_fork_asm+0x11/0x20 [ 31.789133][ T846] [ 31.789134][ T846] -> (console_owner){....}-{0:0} { [ 31.789135][ T846] INITIAL USE at: [ 31.789136][ T846] } [ 31.789136][ T846] ... key at: [] console_owner_dep_map+0x0/0x60 [ 31.789140][ T846] ... acquired at: [ 31.789140][ T846] check_prev_add+0xef/0xe90 [ 31.789141][ T846] validate_chain+0x6b8/0xc20 [ 31.789143][ T846] __lock_acquire+0x518/0xc20 [ 31.789144][ T846] lock_acquire.part.0+0xd4/0x280 [ 31.789145][ T846] console_lock_spinning_enable+0x5c/0x60 [ 31.789147][ T846] console_emit_next_record+0x1d1/0x4c0 [ 31.789148][ T846] console_flush_one_record+0x46f/0x710 [ 31.789150][ T846] console_unlock+0xee/0x1f0 [ 31.789151][ T846] vprintk_emit+0x37c/0x3e0 [ 31.789152][ T846] _printk+0xc7/0x100 [ 31.789154][ T846] netdev_info+0xe2/0x116 [ 31.789156][ T846] ovpn_nl_peer_del_notify+0x115/0x503 [ovpn] [ 31.789159][ T846] ovpn_peer_remove+0x394/0x640 [ovpn] [ 31.789161][ T846] ovpn_peer_del+0x1e5/0x590 [ovpn] [ 31.789164][ T846] ovpn_nl_peer_del_doit+0x25c/0x6c0 [ovpn] [ 31.789166][ T846] genl_family_rcv_msg_doit+0x206/0x300 [ 31.789167][ T846] genl_family_rcv_msg+0x3a4/0x640 [ 31.789169][ T846] genl_rcv_msg+0xbb/0x160 [ 31.789170][ T846] netlink_rcv_skb+0x14e/0x3a0 [ 31.789172][ T846] genl_rcv+0x28/0x40 [ 31.789173][ T846] netlink_unicast+0x486/0x750 [ 31.789175][ T846] netlink_sendmsg+0x735/0xc60 [ 31.789176][ T846] ____sys_sendmsg+0x415/0x880 [ 31.789178][ T846] ___sys_sendmsg+0x14e/0x1d0 [ 31.789179][ T846] __sys_sendmsg+0x12c/0x1d0 [ 31.789181][ T846] do_syscall_64+0xff/0x530 [ 31.789182][ T846] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 31.789183][ T846] [ 31.789183][ T846] [ 31.789183][ T846] stack backtrace: [ 31.789186][ T846] CPU: 3 UID: 0 PID: 846 Comm: ovpn-cli Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 31.789189][ T846] Tainted: [W]=WARN [ 31.789190][ T846] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 31.789191][ T846] Call Trace: [ 31.789193][ T846] [ 31.789194][ T846] dump_stack_lvl+0x6f/0xa0 [ 31.789198][ T846] print_bad_irq_dependency+0x3e4/0x410 [ 31.789200][ T846] check_irq_usage+0x403/0x570 [ 31.789202][ T846] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 31.789206][ T846] ? rcu_read_lock_any_held+0x3c/0x90 [ 31.789208][ T846] ? check_path.constprop.0+0x24/0x50 [ 31.789211][ T846] ? check_prev_add+0xef/0xe90 [ 31.789212][ T846] check_prev_add+0xef/0xe90 [ 31.789214][ T846] validate_chain+0x6b8/0xc20 [ 31.789216][ T846] __lock_acquire+0x518/0xc20 [ 31.789218][ T846] lock_acquire.part.0+0xd4/0x280 [ 31.789220][ T846] ? console_lock_spinning_enable+0x40/0x60 [ 31.789222][ T846] ? rcu_is_watching+0x16/0xd0 [ 31.789224][ T846] ? lock_acquire+0x13c/0x160 [ 31.789226][ T846] console_lock_spinning_enable+0x5c/0x60 [ 31.789227][ T846] ? console_lock_spinning_enable+0x40/0x60 [ 31.789229][ T846] console_emit_next_record+0x1d1/0x4c0 [ 31.789231][ T846] ? devkmsg_read+0x4b0/0x4b0 [ 31.789233][ T846] ? console_flush_one_record+0x106/0x710 [ 31.789235][ T846] ? rcu_is_watching+0x16/0xd0 [ 31.789236][ T846] ? lock_acquire+0x13c/0x160 [ 31.789240][ T846] console_flush_one_record+0x46f/0x710 [ 31.789243][ T846] ? console_emit_next_record+0x4c0/0x4c0 [ 31.789244][ T846] ? __lock_acquire+0x518/0xc20 [ 31.789246][ T846] console_unlock+0xee/0x1f0 [ 31.789248][ T846] ? console_flush_one_record+0x710/0x710 [ 31.789250][ T846] ? rcu_is_watching+0x16/0xd0 [ 31.789251][ T846] ? lock_acquire+0x60/0x160 [ 31.789253][ T846] ? __down_trylock_console_sem+0x5e/0xa0 [ 31.789255][ T846] ? vprintk_emit+0x320/0x3e0 [ 31.789257][ T846] vprintk_emit+0x37c/0x3e0 [ 31.789259][ T846] ? wake_up_klogd_work_func+0x90/0x90 [ 31.789260][ T846] ? kernel_text_address+0x149/0x170 [ 31.789262][ T846] ? __kernel_text_address+0x12/0x30 [ 31.789263][ T846] ? write_profile+0xf0/0xf0 [ 31.789265][ T846] ? unwind_get_return_address+0x67/0xd0 [ 31.789268][ T846] _printk+0xc7/0x100 [ 31.789269][ T846] ? snapshot_read.cold+0x21/0x21 [ 31.789272][ T846] ? mark_usage+0x61/0x170 [ 31.789273][ T846] ? netdev_name+0x43/0x60 [ 31.789274][ T846] ? __netdev_printk+0x17f/0x1d0 [ 31.789276][ T846] netdev_info+0xe2/0x116 [ 31.789277][ T846] ? netdev_notice+0x120/0x120 [ 31.789278][ T846] ? find_held_lock+0x2b/0x80 [ 31.789280][ T846] ? mark_usage+0x61/0x170 [ 31.789281][ T846] ? __lock_acquire+0x518/0xc20 [ 31.789284][ T846] ovpn_nl_peer_del_notify+0x115/0x503 [ovpn] [ 31.789287][ T846] ? ovpn_nl_key_new_doit.cold+0x24/0x24 [ovpn] [ 31.789289][ T846] ? ovpn_peer_get_by_id+0x1fd/0x7a0 [ovpn] [ 31.789292][ T846] ovpn_peer_remove+0x394/0x640 [ovpn] [ 31.789295][ T846] ? do_raw_spin_lock+0x131/0x280 [ 31.789297][ T846] ? __ovpn_peer_hash_transp_addr+0x650/0x650 [ovpn] [ 31.789300][ T846] ovpn_peer_del+0x1e5/0x590 [ovpn] [ 31.789303][ T846] ? ovpn_peer_add+0x330/0x330 [ovpn] [ 31.789306][ T846] ovpn_nl_peer_del_doit+0x25c/0x6c0 [ovpn] [ 31.789308][ T846] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 31.789309][ T846] ? ovpn_nl_peer_get_dumpit+0xa40/0xa40 [ovpn] [ 31.789312][ T846] ? ref_tracker_alloc+0x28d/0x430 [ 31.789317][ T846] ? ovpn_get_dev_from_attrs+0x170/0x660 [ovpn] [ 31.789320][ T846] ? ovpn_get_dev_from_attrs+0x17a/0x660 [ovpn] [ 31.789322][ T846] genl_family_rcv_msg_doit+0x206/0x300 [ 31.789325][ T846] ? genl_family_rcv_msg_attrs_parse.isra.0+0x330/0x330 [ 31.789327][ T846] ? __lock_release.isra.0+0x69/0x1a0 [ 31.789329][ T846] ? rcu_is_watching+0x16/0xd0 [ 31.789331][ T846] ? cap_capable+0x1d7/0x3d0 [ 31.789334][ T846] ? write_profile+0xf0/0xf0 [ 31.789335][ T846] ? unwind_get_return_address+0x67/0xd0 [ 31.789337][ T846] genl_family_rcv_msg+0x3a4/0x640 [ 31.789339][ T846] ? genl_family_rcv_msg_dumpit+0x340/0x340 [ 31.789341][ T846] ? rcu_read_lock_any_held+0x3c/0x90 [ 31.789342][ T846] ? ovpn_nl_send_peer+0x1300/0x1300 [ovpn] [ 31.789345][ T846] ? ovpn_nl_peer_get_dumpit+0xa40/0xa40 [ovpn] [ 31.789347][ T846] ? ovpn_nl_pre_doit+0x90/0x90 [ovpn] [ 31.789349][ T846] ? __lock_acquire+0x518/0xc20 [ 31.789352][ T846] genl_rcv_msg+0xbb/0x160 [ 31.789354][ T846] netlink_rcv_skb+0x14e/0x3a0 [ 31.789355][ T846] ? genl_family_rcv_msg+0x640/0x640 [ 31.789357][ T846] ? netlink_ack+0xcf0/0xcf0 [ 31.789360][ T846] ? netlink_deliver_tap+0xc5/0x330 [ 31.789362][ T846] ? netlink_deliver_tap+0x13c/0x330 [ 31.789364][ T846] genl_rcv+0x28/0x40 [ 31.789366][ T846] netlink_unicast+0x486/0x750 [ 31.789368][ T846] ? netlink_attachskb+0x810/0x810 [ 31.789370][ T846] ? __lock_acquire+0x518/0xc20 [ 31.789372][ T846] netlink_sendmsg+0x735/0xc60 [ 31.789374][ T846] ? netlink_unicast+0x750/0x750 [ 31.789376][ T846] ? __might_fault+0x97/0x140 [ 31.789380][ T846] ____sys_sendmsg+0x415/0x880 [ 31.789381][ T846] ? copy_msghdr_from_user+0x279/0x420 [ 31.789383][ T846] ? get_timestamp.constprop.0+0x390/0x390 [ 31.789384][ T846] ? move_addr_to_kernel+0x40/0x40 [ 31.789387][ T846] ___sys_sendmsg+0x14e/0x1d0 [ 31.789389][ T846] ? copy_msghdr_from_user+0x420/0x420 [ 31.789391][ T846] ? kfree+0x22/0x590 [ 31.789395][ T846] __sys_sendmsg+0x12c/0x1d0 [ 31.789397][ T846] ? __sys_sendmsg_sock+0x20/0x20 [ 31.789400][ T846] ? rcu_is_watching+0x16/0xd0 [ 31.789402][ T846] ? rcu_is_watching+0x16/0xd0 [ 31.789404][ T846] do_syscall_64+0xff/0x530 [ 31.789405][ T846] ? exc_page_fault+0xee/0x100 [ 31.789407][ T846] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 31.789408][ T846] RIP: 0033:0x7f142af9054e [ 31.789411][ T846] 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 [ 31.789413][ T846] RSP: 002b:00007ffc12856570 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 31.789415][ T846] RAX: ffffffffffffffda RBX: 000000003592d130 RCX: 00007f142af9054e [ 31.789417][ T846] RDX: 0000000000000000 RSI: 00007ffc12856610 RDI: 0000000000000005 [ 31.789418][ T846] RBP: 00007ffc12856580 R08: 0000000000000000 R09: 0000000000000000 [ 31.789418][ T846] R10: 0000000000000000 R11: 0000000000000202 R12: 000000003592d200 [ 31.789419][ T846] R13: 00007ffc12856610 R14: 000000003592e260 R15: 000000000040bda8 [ 31.789422][ T846] [ 31.789425][ T846] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 31.789427][ T846] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 846, name: ovpn-cli [ 31.789428][ T846] preempt_count: 202, expected: 0 [ 31.789429][ T846] RCU nest depth: 0, expected: 0 [ 31.789429][ T846] INFO: lockdep is turned off. [ 31.789430][ T846] irq event stamp: 30537 [ 31.789430][ T846] hardirqs last enabled at (30536): [] __down_trylock_console_sem+0x86/0xa0 [ 31.789433][ T846] hardirqs last disabled at (30537): [] console_emit_next_record+0x3d4/0x4c0 [ 31.789434][ T846] softirqs last enabled at (30504): [] __alloc_skb+0x4c2/0x5f0 [ 31.789437][ T846] softirqs last disabled at (30532): [] ovpn_peer_del+0xa0/0x590 [ovpn] [ 31.789440][ T846] Preemption disabled at: [ 31.789440][ T846] [<0000000000000000>] 0x0 [ 31.789442][ T846] CPU: 3 UID: 0 PID: 846 Comm: ovpn-cli Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 31.789444][ T846] Tainted: [W]=WARN [ 31.789445][ T846] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 31.789445][ T846] Call Trace: [ 31.789446][ T846] [ 31.789446][ T846] dump_stack_lvl+0x6f/0xa0 [ 31.789448][ T846] __might_resched.cold+0x1fe/0x2c1 [ 31.789451][ T846] ? check_irq_usage+0x403/0x570 [ 31.789452][ T846] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 31.789456][ T846] ? rcu_is_watching+0x16/0xd0 [ 31.789458][ T846] __kmalloc_noprof+0x443/0x760 [ 31.789459][ T846] ? __rwlock_init+0x150/0x150 [ 31.789461][ T846] ? alloc_buf.isra.0+0x4b/0x260 [ 31.789463][ T846] ? do_raw_spin_unlock+0x59/0x250 [ 31.789464][ T846] alloc_buf.isra.0+0x4b/0x260 [ 31.789466][ T846] put_chars+0x1e1/0x2f0 [ 31.789468][ T846] ? __send_to_port+0x420/0x420 [ 31.789469][ T846] ? check_prev_add+0xef/0xe90 [ 31.789471][ T846] ? validate_chain+0x6b8/0xc20 [ 31.789473][ T846] hvc_console_print+0x292/0x780 [ 31.789474][ T846] ? __lock_acquire+0x518/0xc20 [ 31.789476][ T846] ? hvc_write+0x3a0/0x3a0 [ 31.789478][ T846] ? rcu_is_watching+0x16/0xd0 [ 31.789480][ T846] ? lock_acquire+0x13c/0x160 [ 31.789482][ T846] console_emit_next_record+0x22f/0x4c0 [ 31.789484][ T846] ? devkmsg_read+0x4b0/0x4b0 [ 31.789485][ T846] ? console_flush_one_record+0x106/0x710 [ 31.789487][ T846] ? rcu_is_watching+0x16/0xd0 [ 31.789489][ T846] ? lock_acquire+0x13c/0x160 [ 31.789491][ T846] console_flush_one_record+0x46f/0x710 [ 31.789493][ T846] ? console_emit_next_record+0x4c0/0x4c0 [ 31.789495][ T846] ? __lock_acquire+0x518/0xc20 [ 31.789497][ T846] console_unlock+0xee/0x1f0 [ 31.789499][ T846] ? console_flush_one_record+0x710/0x710 [ 31.789500][ T846] ? rcu_is_watching+0x16/0xd0 [ 31.789502][ T846] ? lock_acquire+0x60/0x160 [ 31.789504][ T846] ? __down_trylock_console_sem+0x5e/0xa0 [ 31.789505][ T846] ? vprintk_emit+0x320/0x3e0 [ 31.789507][ T846] vprintk_emit+0x37c/0x3e0 [ 31.789509][ T846] ? wake_up_klogd_work_func+0x90/0x90 [ 31.789510][ T846] ? kernel_text_address+0x149/0x170 [ 31.789512][ T846] ? __kernel_text_address+0x12/0x30 [ 31.789513][ T846] ? write_profile+0xf0/0xf0 [ 31.789514][ T846] ? unwind_get_return_address+0x67/0xd0 [ 31.789516][ T846] _printk+0xc7/0x100 [ 31.789517][ T846] ? snapshot_read.cold+0x21/0x21 [ 31.789519][ T846] ? mark_usage+0x61/0x170 [ 31.789521][ T846] ? netdev_name+0x43/0x60 [ 31.789522][ T846] ? __netdev_printk+0x17f/0x1d0 [ 31.789524][ T846] netdev_info+0xe2/0x116 [ 31.789525][ T846] ? netdev_notice+0x120/0x120 [ 31.789526][ T846] ? find_held_lock+0x2b/0x80 [ 31.789527][ T846] ? mark_usage+0x61/0x170 [ 31.789529][ T846] ? __lock_acquire+0x518/0xc20 [ 31.789531][ T846] ovpn_nl_peer_del_notify+0x115/0x503 [ovpn] [ 31.789534][ T846] ? ovpn_nl_key_new_doit.cold+0x24/0x24 [ovpn] [ 31.789537][ T846] ? ovpn_peer_get_by_id+0x1fd/0x7a0 [ovpn] [ 31.789540][ T846] ovpn_peer_remove+0x394/0x640 [ovpn] [ 31.789542][ T846] ? do_raw_spin_lock+0x131/0x280 [ 31.789544][ T846] ? __ovpn_peer_hash_transp_addr+0x650/0x650 [ovpn] [ 31.789547][ T846] ovpn_peer_del+0x1e5/0x590 [ovpn] [ 31.789550][ T846] ? ovpn_peer_add+0x330/0x330 [ovpn] [ 31.789553][ T846] ovpn_nl_peer_del_doit+0x25c/0x6c0 [ovpn] [ 31.789555][ T846] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 31.789556][ T846] ? ovpn_nl_peer_get_dumpit+0xa40/0xa40 [ovpn] [ 31.789559][ T846] ? ref_tracker_alloc+0x28d/0x430 [ 31.789562][ T846] ? ovpn_get_dev_from_attrs+0x170/0x660 [ovpn] [ 31.789565][ T846] ? ovpn_get_dev_from_attrs+0x17a/0x660 [ovpn] [ 31.789568][ T846] genl_family_rcv_msg_doit+0x206/0x300 [ 31.789570][ T846] ? genl_family_rcv_msg_attrs_parse.isra.0+0x330/0x330 [ 31.789572][ T846] ? __lock_release.isra.0+0x69/0x1a0 [ 31.789574][ T846] ? rcu_is_watching+0x16/0xd0 [ 31.789576][ T846] ? cap_capable+0x1d7/0x3d0 [ 31.789577][ T846] ? write_profile+0xf0/0xf0 [ 31.789579][ T846] ? unwind_get_return_address+0x67/0xd0 [ 31.789580][ T846] genl_family_rcv_msg+0x3a4/0x640 [ 31.789582][ T846] ? genl_family_rcv_msg_dumpit+0x340/0x340 [ 31.789584][ T846] ? rcu_read_lock_any_held+0x3c/0x90 [ 31.789586][ T846] ? ovpn_nl_send_peer+0x1300/0x1300 [ovpn] [ 31.789588][ T846] ? ovpn_nl_peer_get_dumpit+0xa40/0xa40 [ovpn] [ 31.789590][ T846] ? ovpn_nl_pre_doit+0x90/0x90 [ovpn] [ 31.789592][ T846] ? __lock_acquire+0x518/0xc20 [ 31.789595][ T846] genl_rcv_msg+0xbb/0x160 [ 31.789597][ T846] netlink_rcv_skb+0x14e/0x3a0 [ 31.789599][ T846] ? genl_family_rcv_msg+0x640/0x640 [ 31.789601][ T846] ? netlink_ack+0xcf0/0xcf0 [ 31.789603][ T846] ? netlink_deliver_tap+0xc5/0x330 [ 31.789605][ T846] ? netlink_deliver_tap+0x13c/0x330 [ 31.789607][ T846] genl_rcv+0x28/0x40 [ 31.789609][ T846] netlink_unicast+0x486/0x750 [ 31.789611][ T846] ? netlink_attachskb+0x810/0x810 [ 31.789613][ T846] ? __lock_acquire+0x518/0xc20 [ 31.789614][ T846] netlink_sendmsg+0x735/0xc60 [ 31.789617][ T846] ? netlink_unicast+0x750/0x750 [ 31.789619][ T846] ? __might_fault+0x97/0x140 [ 31.789621][ T846] ____sys_sendmsg+0x415/0x880 [ 31.789622][ T846] ? copy_msghdr_from_user+0x279/0x420 [ 31.789624][ T846] ? get_timestamp.constprop.0+0x390/0x390 [ 31.789625][ T846] ? move_addr_to_kernel+0x40/0x40 [ 31.789628][ T846] ___sys_sendmsg+0x14e/0x1d0 [ 31.789630][ T846] ? copy_msghdr_from_user+0x420/0x420 [ 31.789636][ T846] ? kfree+0x22/0x590 [ 31.789641][ T846] __sys_sendmsg+0x12c/0x1d0 [ 31.789643][ T846] ? __sys_sendmsg_sock+0x20/0x20 [ 31.789646][ T846] ? rcu_is_watching+0x16/0xd0 [ 31.789647][ T846] ? rcu_is_watching+0x16/0xd0 [ 31.789649][ T846] do_syscall_64+0xff/0x530 [ 31.789651][ T846] ? exc_page_fault+0xee/0x100 [ 31.789652][ T846] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 31.789654][ T846] RIP: 0033:0x7f142af9054e [ 31.789655][ T846] 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 [ 31.789656][ T846] RSP: 002b:00007ffc12856570 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 31.789657][ T846] RAX: ffffffffffffffda RBX: 000000003592d130 RCX: 00007f142af9054e [ 31.789658][ T846] RDX: 0000000000000000 RSI: 00007ffc12856610 RDI: 0000000000000005 [ 31.789658][ T846] RBP: 00007ffc12856580 R08: 0000000000000000 R09: 0000000000000000 [ 31.789659][ T846] R10: 0000000000000000 R11: 0000000000000202 R12: 000000003592d200 [ 31.789660][ T846] R13: 00007ffc12856610 R14: 000000003592e260 R15: 000000000040bda8 [ 31.789662][ T846] [ 31.897907][ T846] ovpn-cli (846) used greatest stack depth: 23432 bytes left [ 31.943373][ T850] tun1: deleting peer with id 10, reason 1 [ 34.529200][ T898] tun0: deleting peer with id 2, reason 1 [ 34.529217][ T898] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 34.529219][ T898] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 898, name: ovpn-cli [ 34.529220][ T898] preempt_count: 202, expected: 0 [ 34.529221][ T898] RCU nest depth: 0, expected: 0 [ 34.529222][ T898] INFO: lockdep is turned off. [ 34.529223][ T898] irq event stamp: 0 [ 34.529224][ T898] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 34.529228][ T898] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 34.529234][ T898] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 34.529236][ T898] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 34.529240][ T898] Preemption disabled at: [ 34.529240][ T898] [<0000000000000000>] 0x0 [ 34.529245][ T898] CPU: 2 UID: 0 PID: 898 Comm: ovpn-cli Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 34.529248][ T898] Tainted: [W]=WARN [ 34.529249][ T898] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 34.529251][ T898] Call Trace: [ 34.529252][ T898] [ 34.529253][ T898] dump_stack_lvl+0x6f/0xa0 [ 34.529259][ T898] __might_resched.cold+0x1fe/0x2c1 [ 34.529263][ T898] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 34.529267][ T898] ? rcu_is_watching+0x16/0xd0 [ 34.529271][ T898] __kmalloc_noprof+0x443/0x760 [ 34.529275][ T898] ? __rwlock_init+0x150/0x150 [ 34.529277][ T898] ? alloc_buf.isra.0+0x4b/0x260 [ 34.529281][ T898] ? do_raw_spin_unlock+0x59/0x250 [ 34.529283][ T898] alloc_buf.isra.0+0x4b/0x260 [ 34.529285][ T898] put_chars+0x1e1/0x2f0 [ 34.529287][ T898] ? __send_to_port+0x420/0x420 [ 34.529289][ T898] ? printk_get_next_message+0x240/0x650 [ 34.529292][ T898] hvc_console_print+0x292/0x780 [ 34.529296][ T898] ? hvc_write+0x3a0/0x3a0 [ 34.529298][ T898] ? __rwlock_init+0x150/0x150 [ 34.529299][ T898] ? rcu_is_watching+0x16/0xd0 [ 34.529301][ T898] ? lock_acquire+0x13c/0x160 [ 34.529303][ T898] console_emit_next_record+0x22f/0x4c0 [ 34.529306][ T898] ? devkmsg_read+0x4b0/0x4b0 [ 34.529307][ T898] ? rcu_is_watching+0x16/0xd0 [ 34.529309][ T898] ? rcu_is_watching+0x16/0xd0 [ 34.529311][ T898] ? rcu_is_watching+0x16/0xd0 [ 34.529312][ T898] ? lock_acquire+0x13c/0x160 [ 34.529314][ T898] ? console_flush_one_record+0x111/0x710 [ 34.529316][ T898] console_flush_one_record+0x46f/0x710 [ 34.529318][ T898] ? console_emit_next_record+0x4c0/0x4c0 [ 34.529321][ T898] console_unlock+0xee/0x1f0 [ 34.529322][ T898] ? lock_acquire+0x13c/0x160 [ 34.529324][ T898] ? console_flush_one_record+0x710/0x710 [ 34.529325][ T898] ? rcu_is_watching+0x16/0xd0 [ 34.529327][ T898] ? lock_release+0x184/0x1f0 [ 34.529328][ T898] ? lock_acquire+0x60/0x160 [ 34.529330][ T898] ? __rcu_read_lock+0x30/0x70 [ 34.529332][ T898] ? __down_trylock_console_sem+0x5e/0xa0 [ 34.529334][ T898] ? vprintk_emit+0x320/0x3e0 [ 34.529336][ T898] vprintk_emit+0x37c/0x3e0 [ 34.529338][ T898] ? wake_up_klogd_work_func+0x90/0x90 [ 34.529340][ T898] ? kernel_text_address+0x149/0x170 [ 34.529342][ T898] ? __kernel_text_address+0x12/0x30 [ 34.529343][ T898] ? write_profile+0xf0/0xf0 [ 34.529345][ T898] ? unwind_get_return_address+0x67/0xd0 [ 34.529348][ T898] _printk+0xc7/0x100 [ 34.529350][ T898] ? snapshot_read.cold+0x21/0x21 [ 34.529353][ T898] ? netdev_name+0x43/0x60 [ 34.529355][ T898] ? __netdev_printk+0x17f/0x1d0 [ 34.529357][ T898] netdev_info+0xe2/0x116 [ 34.529358][ T898] ? netdev_notice+0x120/0x120 [ 34.529360][ T898] ? ovpn_nl_pre_doit+0x44/0x90 [ovpn] [ 34.529365][ T898] ? __module_address+0x36/0x330 [ 34.529368][ T898] ? lock_acquire+0x13c/0x160 [ 34.529370][ T898] ? rcu_is_watching+0x16/0xd0 [ 34.529371][ T898] ? lock_release+0x184/0x1f0 [ 34.529373][ T898] ? ovpn_peer_get_by_id+0x38a/0x7a0 [ovpn] [ 34.529376][ T898] ovpn_nl_peer_del_notify+0x115/0x503 [ovpn] [ 34.529380][ T898] ? ovpn_nl_key_new_doit.cold+0x24/0x24 [ovpn] [ 34.529383][ T898] ? lock_acquire+0x13c/0x160 [ 34.529384][ T898] ? rcu_is_watching+0x16/0xd0 [ 34.529386][ T898] ? lock_release+0x184/0x1f0 [ 34.529388][ T898] ovpn_peer_remove+0x394/0x640 [ovpn] [ 34.529391][ T898] ? do_raw_spin_lock+0x131/0x280 [ 34.529393][ T898] ? __ovpn_peer_hash_transp_addr+0x650/0x650 [ovpn] [ 34.529396][ T898] ovpn_peer_del+0x1e5/0x590 [ovpn] [ 34.529399][ T898] ? ovpn_peer_add+0x330/0x330 [ovpn] [ 34.529402][ T898] ovpn_nl_peer_del_doit+0x25c/0x6c0 [ovpn] [ 34.529405][ T898] ? trace_hardirqs_on+0x36/0x40 [ 34.529407][ T898] ? ovpn_nl_peer_get_dumpit+0xa40/0xa40 [ovpn] [ 34.529410][ T898] ? ref_tracker_alloc+0x28d/0x430 [ 34.529415][ T898] ? lock_release+0x184/0x1f0 [ 34.529417][ T898] ? ovpn_get_dev_from_attrs+0x17a/0x660 [ovpn] [ 34.529420][ T898] genl_family_rcv_msg_doit+0x206/0x300 [ 34.529424][ T898] ? genl_family_rcv_msg_attrs_parse.isra.0+0x330/0x330 [ 34.529426][ T898] ? rcu_is_watching+0x16/0xd0 [ 34.529428][ T898] ? rcu_is_watching+0x16/0xd0 [ 34.529430][ T898] ? cap_capable+0x1d7/0x3d0 [ 34.529434][ T898] ? write_profile+0xf0/0xf0 [ 34.529435][ T898] ? unwind_get_return_address+0x67/0xd0 [ 34.529437][ T898] genl_family_rcv_msg+0x3a4/0x640 [ 34.529439][ T898] ? genl_family_rcv_msg_dumpit+0x340/0x340 [ 34.529441][ T898] ? ovpn_nl_send_peer+0x1300/0x1300 [ovpn] [ 34.529443][ T898] ? ovpn_nl_peer_get_dumpit+0xa40/0xa40 [ovpn] [ 34.529446][ T898] ? ovpn_nl_pre_doit+0x90/0x90 [ovpn] [ 34.529448][ T898] ? filter_irq_stacks+0xd0/0xd0 [ 34.529450][ T898] ? stack_depot_save_flags+0x1bc/0x790 [ 34.529452][ T898] genl_rcv_msg+0xbb/0x160 [ 34.529454][ T898] netlink_rcv_skb+0x14e/0x3a0 [ 34.529456][ T898] ? genl_family_rcv_msg+0x640/0x640 [ 34.529458][ T898] ? netlink_ack+0xcf0/0xcf0 [ 34.529461][ T898] ? lock_acquire+0x13c/0x160 [ 34.529462][ T898] ? rcu_is_watching+0x16/0xd0 [ 34.529464][ T898] ? lock_release+0x184/0x1f0 [ 34.529465][ T898] genl_rcv+0x28/0x40 [ 34.529467][ T898] netlink_unicast+0x486/0x750 [ 34.529470][ T898] ? netlink_attachskb+0x810/0x810 [ 34.529471][ T898] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 34.529473][ T898] ? trace_hardirqs_on+0x36/0x40 [ 34.529474][ T898] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 34.529477][ T898] netlink_sendmsg+0x735/0xc60 [ 34.529479][ T898] ? netlink_unicast+0x750/0x750 [ 34.529481][ T898] ? rcu_is_watching+0x16/0xd0 [ 34.529483][ T898] ? lock_release+0x184/0x1f0 [ 34.529485][ T898] ____sys_sendmsg+0x415/0x880 [ 34.529488][ T898] ? copy_msghdr_from_user+0x279/0x420 [ 34.529490][ T898] ? get_timestamp.constprop.0+0x390/0x390 [ 34.529492][ T898] ? move_addr_to_kernel+0x40/0x40 [ 34.529495][ T898] ___sys_sendmsg+0x14e/0x1d0 [ 34.529496][ T898] ? copy_msghdr_from_user+0x420/0x420 [ 34.529498][ T898] ? kfree+0x22/0x590 [ 34.529502][ T898] __sys_sendmsg+0x12c/0x1d0 [ 34.529505][ T898] ? __sys_sendmsg_sock+0x20/0x20 [ 34.529507][ T898] ? rcu_is_watching+0x16/0xd0 [ 34.529509][ T898] ? rcu_is_watching+0x16/0xd0 [ 34.529511][ T898] do_syscall_64+0xff/0x530 [ 34.529514][ T898] ? exc_page_fault+0xee/0x100 [ 34.529516][ T898] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 34.529517][ T898] RIP: 0033:0x7f08084cf54e [ 34.529519][ T898] 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 [ 34.529521][ T898] RSP: 002b:00007ffc317b3670 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 34.529524][ T898] RAX: ffffffffffffffda RBX: 00000000019cf130 RCX: 00007f08084cf54e [ 34.529526][ T898] RDX: 0000000000000000 RSI: 00007ffc317b3710 RDI: 0000000000000005 [ 34.529527][ T898] RBP: 00007ffc317b3680 R08: 0000000000000000 R09: 0000000000000000 [ 34.529527][ T898] R10: 0000000000000000 R11: 0000000000000202 R12: 00000000019cf200 [ 34.529528][ T898] R13: 00007ffc317b3710 R14: 00000000019d0260 R15: 000000000040bda8 [ 34.529530][ T898] [ 34.621193][ T902] tun2: deleting peer with id 11, reason 1 [ 39.283269][ T52] tun0: deleting peer with id 4, reason 2 [ 39.283290][ T52] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 39.283293][ T52] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 52, name: kworker/3:1 [ 39.283295][ T52] preempt_count: 202, expected: 0 [ 39.283295][ T52] RCU nest depth: 0, expected: 0 [ 39.283296][ T52] INFO: lockdep is turned off. [ 39.283297][ T52] irq event stamp: 44202 [ 39.283298][ T52] hardirqs last enabled at (44201): [] _raw_spin_unlock_irq+0x28/0x50 [ 39.283306][ T52] hardirqs last disabled at (44202): [] __schedule+0x1246/0x20e0 [ 39.283309][ T52] softirqs last enabled at (44154): [] handle_softirqs+0x67c/0x900 [ 39.283313][ T52] softirqs last disabled at (44149): [] __irq_exit_rcu+0x145/0x1c0 [ 39.283315][ T52] Preemption disabled at: [ 39.283316][ T52] [<0000000000000000>] 0x0 [ 39.283323][ T52] CPU: 3 UID: 0 PID: 52 Comm: kworker/3:1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 39.283327][ T52] Tainted: [W]=WARN [ 39.283328][ T52] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 39.283330][ T52] Workqueue: ovpn ovpn_peer_keepalive_work [ovpn] [ 39.283340][ T52] Call Trace: [ 39.283342][ T52] [ 39.283344][ T52] dump_stack_lvl+0x6f/0xa0 [ 39.283350][ T52] __might_resched.cold+0x1fe/0x2c1 [ 39.283353][ T52] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 39.283357][ T52] ? prb_next_reserve_seq+0x2d0/0x2d0 [ 39.283359][ T52] ? rcu_is_watching+0x16/0xd0 [ 39.283363][ T52] __kmalloc_noprof+0x443/0x760 [ 39.283367][ T52] ? __rwlock_init+0x150/0x150 [ 39.283369][ T52] ? alloc_buf.isra.0+0x4b/0x260 [ 39.283373][ T52] ? do_raw_spin_unlock+0x59/0x250 [ 39.283374][ T52] alloc_buf.isra.0+0x4b/0x260 [ 39.283377][ T52] put_chars+0x1e1/0x2f0 [ 39.283378][ T52] ? prb_final_commit+0x50/0x50 [ 39.283380][ T52] ? __send_to_port+0x420/0x420 [ 39.283382][ T52] ? printk_get_next_message+0x240/0x650 [ 39.283385][ T52] hvc_console_print+0x292/0x780 [ 39.283388][ T52] ? hvc_write+0x3a0/0x3a0 [ 39.283390][ T52] ? do_raw_spin_lock+0x131/0x280 [ 39.283391][ T52] ? vsnprintf+0x527/0x1700 [ 39.283393][ T52] ? __rwlock_init+0x150/0x150 [ 39.283395][ T52] ? rcu_is_watching+0x16/0xd0 [ 39.283397][ T52] ? lock_acquire+0x13c/0x160 [ 39.283399][ T52] console_emit_next_record+0x22f/0x4c0 [ 39.283401][ T52] ? devkmsg_read+0x4b0/0x4b0 [ 39.283403][ T52] ? rcu_is_watching+0x16/0xd0 [ 39.283405][ T52] ? rcu_is_watching+0x16/0xd0 [ 39.283406][ T52] ? rcu_is_watching+0x16/0xd0 [ 39.283408][ T52] ? lock_acquire+0x13c/0x160 [ 39.283410][ T52] ? console_flush_one_record+0x111/0x710 [ 39.283411][ T52] console_flush_one_record+0x46f/0x710 [ 39.283413][ T52] ? console_emit_next_record+0x4c0/0x4c0 [ 39.283416][ T52] console_unlock+0xee/0x1f0 [ 39.283418][ T52] ? lock_acquire+0x13c/0x160 [ 39.283419][ T52] ? console_flush_one_record+0x710/0x710 [ 39.283421][ T52] ? rcu_is_watching+0x16/0xd0 [ 39.283422][ T52] ? lock_release+0x184/0x1f0 [ 39.283424][ T52] ? lock_acquire+0x60/0x160 [ 39.283425][ T52] ? __rcu_read_lock+0x30/0x70 [ 39.283427][ T52] ? __down_trylock_console_sem+0x5e/0xa0 [ 39.283429][ T52] ? vprintk_emit+0x320/0x3e0 [ 39.283431][ T52] vprintk_emit+0x37c/0x3e0 [ 39.283433][ T52] ? wake_up_klogd_work_func+0x90/0x90 [ 39.283435][ T52] ? queued_spin_lock_slowpath+0x103/0xa60 [ 39.283437][ T52] ? rcu_is_watching+0x16/0xd0 [ 39.283439][ T52] _printk+0xc7/0x100 [ 39.283441][ T52] ? snapshot_read.cold+0x21/0x21 [ 39.283443][ T52] ? __add_to_free_list+0x81/0x5c0 [ 39.283445][ T52] ? __mod_zone_page_state+0xe1/0x190 [ 39.283448][ T52] ? netdev_name+0x43/0x60 [ 39.283451][ T52] ? __netdev_printk+0x17f/0x1d0 [ 39.283453][ T52] netdev_info+0xe2/0x116 [ 39.283455][ T52] ? netdev_notice+0x120/0x120 [ 39.283456][ T52] ? free_pcppages_bulk+0x363/0x6e0 [ 39.283458][ T52] ? rcu_is_watching+0x16/0xd0 [ 39.283460][ T52] ? trace_irq_enable.constprop.0+0x30/0x160 [ 39.283463][ T52] ovpn_nl_peer_del_notify+0x115/0x503 [ovpn] [ 39.283467][ T52] ? ovpn_nl_key_new_doit.cold+0x24/0x24 [ovpn] [ 39.283469][ T52] ? __rwlock_init+0x150/0x150 [ 39.283471][ T52] ? lock_acquire+0x13c/0x160 [ 39.283472][ T52] ? rcu_is_watching+0x16/0xd0 [ 39.283474][ T52] ovpn_peer_remove+0x394/0x640 [ovpn] [ 39.283477][ T52] ? do_raw_spin_lock+0x131/0x280 [ 39.283479][ T52] ? __ovpn_peer_hash_transp_addr+0x650/0x650 [ovpn] [ 39.283481][ T52] ? __rwlock_init+0x150/0x150 [ 39.283483][ T52] ? rcu_is_watching+0x16/0xd0 [ 39.283485][ T52] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 39.283486][ T52] ? trace_irq_disable.constprop.0+0x9b/0x160 [ 39.283487][ T52] ovpn_peer_keepalive_work_single+0x416/0x600 [ovpn] [ 39.283490][ T52] ? ovpn_peers_release_mp+0x490/0x490 [ovpn] [ 39.283493][ T52] ? __rwlock_init+0x150/0x150 [ 39.283495][ T52] ovpn_peer_keepalive_work+0x253/0x580 [ovpn] [ 39.283498][ T52] ? ovpn_peers_free+0xb0/0xb0 [ovpn] [ 39.283500][ T52] ? rcu_is_watching+0x16/0xd0 [ 39.283502][ T52] ? lock_acquire+0x13c/0x160 [ 39.283503][ T52] ? rcu_is_watching+0x16/0xd0 [ 39.283506][ T52] process_one_work+0xe3e/0x1560 [ 39.283510][ T52] ? __queue_delayed_work+0x470/0x470 [ 39.283512][ T52] ? find_held_lock+0x2b/0x80 [ 39.283514][ T52] ? __rwlock_init+0x150/0x150 [ 39.283516][ T52] worker_thread+0x4f1/0xd60 [ 39.283518][ T52] ? rescuer_thread+0x1340/0x1340 [ 39.283520][ T52] ? __kthread_parkme+0xbd/0x210 [ 39.283523][ T52] ? rescuer_thread+0x1340/0x1340 [ 39.283524][ T52] ? rescuer_thread+0x1340/0x1340 [ 39.283526][ T52] kthread+0x367/0x460 [ 39.283528][ T52] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 39.283529][ T52] ? kthread_affine_preferred+0x4c0/0x4c0 [ 39.283531][ T52] ret_from_fork+0x474/0x6b0 [ 39.283534][ T52] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 39.283536][ T52] ? __switch_to+0x5a3/0xe00 [ 39.283538][ T52] ? kthread_affine_preferred+0x4c0/0x4c0 [ 39.283540][ T52] ret_from_fork_asm+0x11/0x20 [ 39.283544][ T52] [ 39.302195][ T52] tun0: deleting peer with id 3, reason 2 [ 40.308265][ T52] tun0: deleting peer with id 5, reason 2 [ 40.308274][ T52] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 40.308276][ T52] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 52, name: kworker/3:1 [ 40.308277][ T52] preempt_count: 202, expected: 0 [ 40.308278][ T52] RCU nest depth: 0, expected: 0 [ 40.308279][ T52] INFO: lockdep is turned off. [ 40.308280][ T52] irq event stamp: 44202 [ 40.308281][ T52] hardirqs last enabled at (44201): [] _raw_spin_unlock_irq+0x28/0x50 [ 40.308285][ T52] hardirqs last disabled at (44202): [] __schedule+0x1246/0x20e0 [ 40.308287][ T52] softirqs last enabled at (44154): [] handle_softirqs+0x67c/0x900 [ 40.308291][ T52] softirqs last disabled at (44149): [] __irq_exit_rcu+0x145/0x1c0 [ 40.308292][ T52] Preemption disabled at: [ 40.308293][ T52] [<0000000000000000>] 0x0 [ 40.308298][ T52] CPU: 3 UID: 0 PID: 52 Comm: kworker/3:1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 40.308301][ T52] Tainted: [W]=WARN [ 40.308302][ T52] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 40.308303][ T52] Workqueue: ovpn ovpn_peer_keepalive_work [ovpn] [ 40.308310][ T52] Call Trace: [ 40.308311][ T52] [ 40.308313][ T52] dump_stack_lvl+0x6f/0xa0 [ 40.308317][ T52] __might_resched.cold+0x1fe/0x2c1 [ 40.308321][ T52] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 40.308324][ T52] ? prb_next_reserve_seq+0x2d0/0x2d0 [ 40.308326][ T52] ? rcu_is_watching+0x16/0xd0 [ 40.308329][ T52] __kmalloc_noprof+0x443/0x760 [ 40.308333][ T52] ? __rwlock_init+0x150/0x150 [ 40.308335][ T52] ? alloc_buf.isra.0+0x4b/0x260 [ 40.308339][ T52] ? do_raw_spin_unlock+0x59/0x250 [ 40.308340][ T52] alloc_buf.isra.0+0x4b/0x260 [ 40.308342][ T52] put_chars+0x1e1/0x2f0 [ 40.308344][ T52] ? prb_final_commit+0x50/0x50 [ 40.308345][ T52] ? __send_to_port+0x420/0x420 [ 40.308347][ T52] ? printk_get_next_message+0x240/0x650 [ 40.308350][ T52] hvc_console_print+0x292/0x780 [ 40.308354][ T52] ? hvc_write+0x3a0/0x3a0 [ 40.308355][ T52] ? do_raw_spin_lock+0x131/0x280 [ 40.308357][ T52] ? vsnprintf+0x527/0x1700 [ 40.308359][ T52] ? __rwlock_init+0x150/0x150 [ 40.308360][ T52] ? rcu_is_watching+0x16/0xd0 [ 40.308362][ T52] ? lock_acquire+0x13c/0x160 [ 40.308364][ T52] console_emit_next_record+0x22f/0x4c0 [ 40.308366][ T52] ? devkmsg_read+0x4b0/0x4b0 [ 40.308368][ T52] ? rcu_is_watching+0x16/0xd0 [ 40.308370][ T52] ? rcu_is_watching+0x16/0xd0 [ 40.308371][ T52] ? rcu_is_watching+0x16/0xd0 [ 40.308373][ T52] ? lock_acquire+0x13c/0x160 [ 40.308375][ T52] ? console_flush_one_record+0x111/0x710 [ 40.308376][ T52] console_flush_one_record+0x46f/0x710 [ 40.308378][ T52] ? console_emit_next_record+0x4c0/0x4c0 [ 40.308381][ T52] console_unlock+0xee/0x1f0 [ 40.308383][ T52] ? lock_acquire+0x13c/0x160 [ 40.308384][ T52] ? console_flush_one_record+0x710/0x710 [ 40.308386][ T52] ? rcu_is_watching+0x16/0xd0 [ 40.308387][ T52] ? lock_release+0x184/0x1f0 [ 40.308389][ T52] ? lock_acquire+0x60/0x160 [ 40.308390][ T52] ? __rcu_read_lock+0x30/0x70 [ 40.308392][ T52] ? __down_trylock_console_sem+0x5e/0xa0 [ 40.308394][ T52] ? vprintk_emit+0x320/0x3e0 [ 40.308396][ T52] vprintk_emit+0x37c/0x3e0 [ 40.308398][ T52] ? wake_up_klogd_work_func+0x90/0x90 [ 40.308400][ T52] ? arch_stack_walk+0xd7/0x130 [ 40.308403][ T52] ? queued_spin_lock_slowpath+0x103/0xa60 [ 40.308404][ T52] ? rcu_is_watching+0x16/0xd0 [ 40.308406][ T52] _printk+0xc7/0x100 [ 40.308408][ T52] ? snapshot_read.cold+0x21/0x21 [ 40.308410][ T52] ? __add_to_free_list+0x81/0x5c0 [ 40.308412][ T52] ? __mod_zone_page_state+0xe1/0x190 [ 40.308414][ T52] ? netdev_name+0x43/0x60 [ 40.308416][ T52] ? __netdev_printk+0x17f/0x1d0 [ 40.308418][ T52] netdev_info+0xe2/0x116 [ 40.308420][ T52] ? netdev_notice+0x120/0x120 [ 40.308421][ T52] ? free_pcppages_bulk+0x363/0x6e0 [ 40.308423][ T52] ? rcu_is_watching+0x16/0xd0 [ 40.308425][ T52] ? trace_irq_enable.constprop.0+0x30/0x160 [ 40.308427][ T52] ovpn_nl_peer_del_notify+0x115/0x503 [ovpn] [ 40.308431][ T52] ? ovpn_nl_key_new_doit.cold+0x24/0x24 [ovpn] [ 40.308433][ T52] ? __rwlock_init+0x150/0x150 [ 40.308435][ T52] ? lock_acquire+0x13c/0x160 [ 40.308436][ T52] ? rcu_is_watching+0x16/0xd0 [ 40.308438][ T52] ovpn_peer_remove+0x394/0x640 [ovpn] [ 40.308442][ T52] ? do_raw_spin_lock+0x131/0x280 [ 40.308443][ T52] ? __ovpn_peer_hash_transp_addr+0x650/0x650 [ovpn] [ 40.308446][ T52] ? __rwlock_init+0x150/0x150 [ 40.308447][ T52] ? rcu_is_watching+0x16/0xd0 [ 40.308449][ T52] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 40.308450][ T52] ? trace_irq_disable.constprop.0+0x9b/0x160 [ 40.308452][ T52] ovpn_peer_keepalive_work_single+0x416/0x600 [ovpn] [ 40.308455][ T52] ? ovpn_peers_release_mp+0x490/0x490 [ovpn] [ 40.308457][ T52] ? __rwlock_init+0x150/0x150 [ 40.308459][ T52] ovpn_peer_keepalive_work+0x253/0x580 [ovpn] [ 40.308462][ T52] ? ovpn_peers_free+0xb0/0xb0 [ovpn] [ 40.308465][ T52] ? rcu_is_watching+0x16/0xd0 [ 40.308466][ T52] ? lock_acquire+0x13c/0x160 [ 40.308468][ T52] ? rcu_is_watching+0x16/0xd0 [ 40.308470][ T52] process_one_work+0xe3e/0x1560 [ 40.308473][ T52] ? __queue_delayed_work+0x470/0x470 [ 40.308475][ T52] ? find_held_lock+0x2b/0x80 [ 40.308477][ T52] ? __rwlock_init+0x150/0x150 [ 40.308479][ T52] worker_thread+0x4f1/0xd60 [ 40.308481][ T52] ? rescuer_thread+0x1340/0x1340 [ 40.308483][ T52] ? __kthread_parkme+0xbd/0x210 [ 40.308485][ T52] ? rescuer_thread+0x1340/0x1340 [ 40.308487][ T52] ? rescuer_thread+0x1340/0x1340 [ 40.308489][ T52] kthread+0x367/0x460 [ 40.308491][ T52] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 40.308492][ T52] ? kthread_affine_preferred+0x4c0/0x4c0 [ 40.308493][ T52] ret_from_fork+0x474/0x6b0 [ 40.308495][ T52] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 40.308497][ T52] ? __switch_to+0x5a3/0xe00 [ 40.308499][ T52] ? kthread_affine_preferred+0x4c0/0x4c0 [ 40.308501][ T52] ret_from_fork_asm+0x11/0x20 [ 40.308505][ T52] [ 40.327160][ T52] tun0: deleting peer with id 6, reason 2 [ 45.363301][ T52] tun3: deleting peer with id 12, reason 2 [ 45.363321][ T52] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 45.363324][ T52] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 52, name: kworker/3:1 [ 45.363326][ T52] preempt_count: 202, expected: 0 [ 45.363327][ T52] RCU nest depth: 0, expected: 0 [ 45.363328][ T52] INFO: lockdep is turned off. [ 45.363329][ T52] irq event stamp: 44202 [ 45.363329][ T52] hardirqs last enabled at (44201): [] _raw_spin_unlock_irq+0x28/0x50 [ 45.363337][ T52] hardirqs last disabled at (44202): [] __schedule+0x1246/0x20e0 [ 45.363340][ T52] softirqs last enabled at (44154): [] handle_softirqs+0x67c/0x900 [ 45.363344][ T52] softirqs last disabled at (44149): [] __irq_exit_rcu+0x145/0x1c0 [ 45.363346][ T52] Preemption disabled at: [ 45.363347][ T52] [<0000000000000000>] 0x0 [ 45.363355][ T52] CPU: 3 UID: 0 PID: 52 Comm: kworker/3:1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 45.363358][ T52] Tainted: [W]=WARN [ 45.363359][ T52] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 45.363362][ T52] Workqueue: ovpn ovpn_peer_keepalive_work [ovpn] [ 45.363370][ T52] Call Trace: [ 45.363372][ T52] [ 45.363373][ T52] dump_stack_lvl+0x6f/0xa0 [ 45.363379][ T52] __might_resched.cold+0x1fe/0x2c1 [ 45.363383][ T52] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 45.363387][ T52] ? prb_next_reserve_seq+0x2d0/0x2d0 [ 45.363389][ T52] ? rcu_is_watching+0x16/0xd0 [ 45.363393][ T52] __kmalloc_noprof+0x443/0x760 [ 45.363396][ T52] ? __rwlock_init+0x150/0x150 [ 45.363399][ T52] ? alloc_buf.isra.0+0x4b/0x260 [ 45.363403][ T52] ? do_raw_spin_unlock+0x59/0x250 [ 45.363405][ T52] alloc_buf.isra.0+0x4b/0x260 [ 45.363407][ T52] put_chars+0x1e1/0x2f0 [ 45.363409][ T52] ? prb_final_commit+0x50/0x50 [ 45.363410][ T52] ? __send_to_port+0x420/0x420 [ 45.363412][ T52] ? printk_get_next_message+0x240/0x650 [ 45.363415][ T52] hvc_console_print+0x292/0x780 [ 45.363419][ T52] ? hvc_write+0x3a0/0x3a0 [ 45.363420][ T52] ? do_raw_spin_lock+0x131/0x280 [ 45.363422][ T52] ? vsnprintf+0x527/0x1700 [ 45.363423][ T52] ? __rwlock_init+0x150/0x150 [ 45.363425][ T52] ? rcu_is_watching+0x16/0xd0 [ 45.363427][ T52] ? lock_acquire+0x13c/0x160 [ 45.363429][ T52] console_emit_next_record+0x22f/0x4c0 [ 45.363432][ T52] ? devkmsg_read+0x4b0/0x4b0 [ 45.363434][ T52] ? rcu_is_watching+0x16/0xd0 [ 45.363435][ T52] ? rcu_is_watching+0x16/0xd0 [ 45.363437][ T52] ? rcu_is_watching+0x16/0xd0 [ 45.363439][ T52] ? lock_acquire+0x13c/0x160 [ 45.363440][ T52] ? console_flush_one_record+0x111/0x710 [ 45.363442][ T52] console_flush_one_record+0x46f/0x710 [ 45.363444][ T52] ? console_emit_next_record+0x4c0/0x4c0 [ 45.363447][ T52] console_unlock+0xee/0x1f0 [ 45.363448][ T52] ? lock_acquire+0x13c/0x160 [ 45.363450][ T52] ? console_flush_one_record+0x710/0x710 [ 45.363451][ T52] ? rcu_is_watching+0x16/0xd0 [ 45.363453][ T52] ? lock_release+0x184/0x1f0 [ 45.363454][ T52] ? lock_acquire+0x60/0x160 [ 45.363456][ T52] ? __rcu_read_lock+0x30/0x70 [ 45.363458][ T52] ? __down_trylock_console_sem+0x5e/0xa0 [ 45.363459][ T52] ? vprintk_emit+0x320/0x3e0 [ 45.363461][ T52] vprintk_emit+0x37c/0x3e0 [ 45.363464][ T52] ? wake_up_klogd_work_func+0x90/0x90 [ 45.363465][ T52] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 45.363467][ T52] ? rcu_is_watching+0x16/0xd0 [ 45.363468][ T52] ? trace_sched_wakeup+0x9b/0x160 [ 45.363471][ T52] _printk+0xc7/0x100 [ 45.363474][ T52] ? snapshot_read.cold+0x21/0x21 [ 45.363475][ T52] ? try_to_wake_up+0x153/0x1130 [ 45.363477][ T52] ? select_fallback_rq+0x510/0x510 [ 45.363478][ T52] ? netdev_name+0x43/0x60 [ 45.363481][ T52] ? __netdev_printk+0x17f/0x1d0 [ 45.363483][ T52] netdev_info+0xe2/0x116 [ 45.363485][ T52] ? netdev_notice+0x120/0x120 [ 45.363486][ T52] ? rcu_is_watching+0x16/0xd0 [ 45.363488][ T52] ? __queue_work+0x3ab/0xd30 [ 45.363491][ T52] ? trace_irq_enable.constprop.0+0x30/0x160 [ 45.363494][ T52] ovpn_nl_peer_del_notify+0x115/0x503 [ovpn] [ 45.363498][ T52] ? ovpn_nl_key_new_doit.cold+0x24/0x24 [ovpn] [ 45.363500][ T52] ? rcu_is_watching+0x16/0xd0 [ 45.363502][ T52] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 45.363504][ T52] ovpn_peer_remove+0x394/0x640 [ovpn] [ 45.363507][ T52] ? do_raw_spin_lock+0x131/0x280 [ 45.363509][ T52] ? __ovpn_peer_hash_transp_addr+0x650/0x650 [ovpn] [ 45.363512][ T52] ? __rwlock_init+0x150/0x150 [ 45.363513][ T52] ? rcu_is_watching+0x16/0xd0 [ 45.363515][ T52] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 45.363516][ T52] ? trace_irq_disable.constprop.0+0x9b/0x160 [ 45.363517][ T52] ovpn_peer_keepalive_work_single+0x416/0x600 [ovpn] [ 45.363520][ T52] ? ovpn_peers_release_mp+0x490/0x490 [ovpn] [ 45.363522][ T52] ? __rwlock_init+0x150/0x150 [ 45.363525][ T52] ovpn_peer_keepalive_work+0x389/0x580 [ovpn] [ 45.363528][ T52] ? ovpn_peers_free+0xb0/0xb0 [ovpn] [ 45.363530][ T52] ? rcu_is_watching+0x16/0xd0 [ 45.363532][ T52] ? lock_acquire+0x13c/0x160 [ 45.363533][ T52] ? rcu_is_watching+0x16/0xd0 [ 45.363535][ T52] ? process_one_work+0xdfc/0x1560 [ 45.363537][ T52] process_one_work+0xe3e/0x1560 [ 45.363539][ T52] ? __queue_delayed_work+0x470/0x470 [ 45.363541][ T52] ? find_held_lock+0x2b/0x80 [ 45.363543][ T52] ? __rwlock_init+0x150/0x150 [ 45.363545][ T52] worker_thread+0x4f1/0xd60 [ 45.363547][ T52] ? rescuer_thread+0x1340/0x1340 [ 45.363549][ T52] ? __kthread_parkme+0xbd/0x210 [ 45.363551][ T52] ? rescuer_thread+0x1340/0x1340 [ 45.363553][ T52] ? rescuer_thread+0x1340/0x1340 [ 45.363554][ T52] kthread+0x367/0x460 [ 45.363556][ T52] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 45.363557][ T52] ? kthread_affine_preferred+0x4c0/0x4c0 [ 45.363559][ T52] ret_from_fork+0x474/0x6b0 [ 45.363561][ T52] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 45.363563][ T52] ? __switch_to+0x5a3/0xe00 [ 45.363565][ T52] ? kthread_affine_preferred+0x4c0/0x4c0 [ 45.363567][ T52] ret_from_fork_asm+0x11/0x20 [ 45.363570][ T52] [ 45.427280][ T10] tun4: deleting peer with id 13, reason 2 [ 45.492270][ T52] tun5: deleting peer with id 14, reason 2 [ 45.555269][ T63] tun6: deleting peer with id 15, reason 2