[ 18.785371][ C2] tun0: no available key for peer 3, key-id: 0 [ 18.785405][ C2] [ 18.785406][ C2] ======================================================== [ 18.785407][ C2] WARNING: possible irq lock inversion dependency detected [ 18.785409][ C2] 7.2.0-virtme #1 Tainted: G W [ 18.785411][ C2] -------------------------------------------------------- [ 18.785412][ C2] ovpn-cli/365 just changed the state of lock: [ 18.785413][ C2] ffffffffa2a99660 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 18.785429][ C2] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 18.785431][ C2] (fs_reclaim){+.+.}-{0:0} [ 18.785432][ C2] [ 18.785432][ C2] [ 18.785432][ C2] and interrupts could create inverse lock ordering between them. [ 18.785432][ C2] [ 18.785433][ C2] [ 18.785433][ C2] other info that might help us debug this: [ 18.785433][ C2] Possible interrupt unsafe locking scenario: [ 18.785433][ C2] [ 18.785434][ C2] CPU0 CPU1 [ 18.785434][ C2] ---- ---- [ 18.785435][ C2] lock(fs_reclaim); [ 18.785436][ C2] local_irq_disable(); [ 18.785436][ C2] lock(console_owner); [ 18.785437][ C2] lock(fs_reclaim); [ 18.785438][ C2] [ 18.785439][ C2] lock(console_owner); [ 18.785439][ C2] [ 18.785439][ C2] *** DEADLOCK *** [ 18.785439][ C2] [ 18.785440][ C2] locks held by ovpn-cli/365: 6, last CPU#2: [ 18.785441][ C2] #0: ffffffffa2b90b00 (rcu_read_lock){....}-{1:3}, at: process_backlog+0x3f1/0x14c0 [ 18.785447][ C2] #1: ffffffffa2b90b00 (rcu_read_lock){....}-{1:3}, at: ip_local_deliver_finish+0x2ba/0x610 [ 18.785452][ C2] #2: ff1100001027d318 (slock-AF_INET/1){+.-.}-{3:3}, at: tcp_v4_rcv+0x1a81/0x2b70 [ 18.785457][ C2] #3: ffffffffa2b90b00 (rcu_read_lock){....}-{1:3}, at: ovpn_tcp_data_ready+0x62/0x390 [ovpn] [ 18.785463][ C2] #4: ffffffffa2b79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 18.785466][ C2] #5: ffffffffa2b79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 18.785469][ C2] [ 18.785469][ C2] the shortest dependencies between 2nd lock and 1st lock: [ 18.785474][ C2] -> (fs_reclaim){+.+.}-{0:0} { [ 18.785477][ C2] HARDIRQ-ON-W at: [ 18.785478][ C2] __lock_acquire+0x388/0xc20 [ 18.785481][ C2] lock_acquire.part.0+0xd4/0x280 [ 18.785483][ C2] fs_reclaim_acquire+0xd5/0x120 [ 18.785486][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 18.785488][ C2] kthread_create_worker_on_node+0xea/0x210 [ 18.785491][ C2] workqueue_init+0x2a/0x680 [ 18.785494][ C2] kernel_init_freeable+0x2fe/0x630 [ 18.785497][ C2] kernel_init+0x21/0x150 [ 18.785500][ C2] ret_from_fork+0x474/0x6b0 [ 18.785502][ C2] ret_from_fork_asm+0x11/0x20 [ 18.785505][ C2] SOFTIRQ-ON-W at: [ 18.785506][ C2] __lock_acquire+0x388/0xc20 [ 18.785508][ C2] lock_acquire.part.0+0xd4/0x280 [ 18.785509][ C2] fs_reclaim_acquire+0xd5/0x120 [ 18.785510][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 18.785511][ C2] kthread_create_worker_on_node+0xea/0x210 [ 18.785513][ C2] workqueue_init+0x2a/0x680 [ 18.785514][ C2] kernel_init_freeable+0x2fe/0x630 [ 18.785515][ C2] kernel_init+0x21/0x150 [ 18.785517][ C2] ret_from_fork+0x474/0x6b0 [ 18.785518][ C2] ret_from_fork_asm+0x11/0x20 [ 18.785519][ C2] INITIAL USE at: [ 18.785520][ C2] __lock_acquire+0x388/0xc20 [ 18.785521][ C2] lock_acquire.part.0+0xd4/0x280 [ 18.785522][ C2] fs_reclaim_acquire+0xd5/0x120 [ 18.785523][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 18.785525][ C2] kthread_create_worker_on_node+0xea/0x210 [ 18.785526][ C2] workqueue_init+0x2a/0x680 [ 18.785527][ C2] kernel_init_freeable+0x2fe/0x630 [ 18.785529][ C2] kernel_init+0x21/0x150 [ 18.785530][ C2] ret_from_fork+0x474/0x6b0 [ 18.785531][ C2] ret_from_fork_asm+0x11/0x20 [ 18.785532][ C2] } [ 18.785533][ C2] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 18.785536][ C2] ... acquired at: [ 18.785537][ C2] __lock_acquire+0x518/0xc20 [ 18.785539][ C2] lock_acquire.part.0+0xd4/0x280 [ 18.785540][ C2] fs_reclaim_acquire+0xd5/0x120 [ 18.785541][ C2] __kmalloc_noprof+0xd3/0x760 [ 18.785542][ C2] alloc_buf.isra.0+0x4b/0x260 [ 18.785546][ C2] put_chars+0x1e1/0x2f0 [ 18.785547][ C2] hvc_console_print+0x292/0x780 [ 18.785549][ C2] console_emit_next_record+0x22f/0x4c0 [ 18.785551][ C2] console_flush_one_record+0x46f/0x710 [ 18.785553][ C2] console_unlock+0xee/0x1f0 [ 18.785556][ C2] vprintk_emit+0x37c/0x3e0 [ 18.785557][ C2] dev_vprintk_emit+0x27f/0x2c0 [ 18.785561][ C2] dev_printk_emit+0xb9/0xee [ 18.785562][ C2] _dev_info+0xe2/0x116 [ 18.785564][ C2] cmos_do_probe+0x8be/0x98a [ 18.785567][ C2] platform_probe+0xfe/0x1f0 [ 18.785569][ C2] call_driver_probe+0x61/0x1c0 [ 18.785571][ C2] really_probe+0x199/0x760 [ 18.785573][ C2] __driver_probe_device+0x24f/0x440 [ 18.785574][ C2] driver_probe_device+0x4a/0xf0 [ 18.785576][ C2] __driver_attach+0x1b8/0x540 [ 18.785577][ C2] bus_for_each_dev+0x130/0x1e0 [ 18.785579][ C2] bus_add_driver+0x2c8/0x530 [ 18.785580][ C2] driver_register+0x1a3/0x390 [ 18.785582][ C2] __platform_driver_probe+0x13f/0x270 [ 18.785583][ C2] cmos_init+0x31/0x40 [ 18.785585][ C2] do_one_initcall+0x124/0x4f0 [ 18.785587][ C2] kernel_init_freeable+0x596/0x630 [ 18.785588][ C2] kernel_init+0x21/0x150 [ 18.785590][ C2] ret_from_fork+0x474/0x6b0 [ 18.785591][ C2] ret_from_fork_asm+0x11/0x20 [ 18.785592][ C2] [ 18.785592][ C2] -> (console_owner){..-.}-{0:0} { [ 18.785594][ C2] IN-SOFTIRQ-W at: [ 18.785595][ C2] __lock_acquire+0x388/0xc20 [ 18.785596][ C2] lock_acquire.part.0+0xd4/0x280 [ 18.785597][ C2] console_lock_spinning_enable+0x5c/0x60 [ 18.785599][ C2] console_emit_next_record+0x1d1/0x4c0 [ 18.785601][ C2] console_flush_one_record+0x46f/0x710 [ 18.785602][ C2] console_unlock+0xee/0x1f0 [ 18.785604][ C2] vprintk_emit+0x37c/0x3e0 [ 18.785605][ C2] _printk+0xc7/0x100 [ 18.785608][ C2] ovpn_recv.cold+0x7e/0x94 [ovpn] [ 18.785611][ C2] ovpn_tcp_rcv+0x274/0x9a0 [ovpn] [ 18.785613][ C2] __strp_recv+0x20f/0x1c20 [ 18.785616][ C2] __tcp_read_sock+0x1c7/0x850 [ 18.785618][ C2] strp_read_sock+0x20f/0x2b0 [ 18.785620][ C2] strp_data_ready+0x18e/0x2c0 [ 18.785622][ C2] ovpn_tcp_data_ready+0xf1/0x390 [ovpn] [ 18.785624][ C2] tcp_rcv_established+0x1e1d/0x3910 [ 18.785626][ C2] tcp_v4_do_rcv+0x4f6/0xb90 [ 18.785628][ C2] tcp_v4_rcv+0x1caa/0x2b70 [ 18.785629][ C2] ip_protocol_deliver_rcu+0x82/0x350 [ 18.785631][ C2] ip_local_deliver_finish+0x36f/0x610 [ 18.785632][ C2] ip_local_deliver+0x184/0x4c0 [ 18.785633][ C2] ip_rcv+0xdc/0x3d0 [ 18.785635][ C2] __netif_receive_skb_one_core+0xfc/0x180 [ 18.785636][ C2] process_backlog+0x431/0x14c0 [ 18.785638][ C2] __napi_poll+0xa7/0x3b0 [ 18.785640][ C2] net_rx_action+0x513/0xf50 [ 18.785641][ C2] handle_softirqs+0x1d3/0x900 [ 18.785644][ C2] do_softirq+0xac/0xe0 [ 18.785645][ C2] __local_bh_enable_ip+0x118/0x150 [ 18.785646][ C2] ovpn_tcp_sendmsg+0x2c2/0x7d0 [ovpn] [ 18.785648][ C2] __sys_sendto+0x2aa/0x400 [ 18.785651][ C2] __x64_sys_sendto+0xe4/0x1f0 [ 18.785653][ C2] do_syscall_64+0xff/0x530 [ 18.785655][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 18.785657][ C2] INITIAL USE at: [ 18.785658][ C2] } [ 18.785658][ C2] ... key at: [] console_owner_dep_map+0x0/0x60 [ 18.785662][ C2] ... acquired at: [ 18.785662][ C2] mark_lock+0x1d7/0xa00 [ 18.785664][ C2] mark_usage+0x42/0x170 [ 18.785665][ C2] __lock_acquire+0x388/0xc20 [ 18.785666][ C2] lock_acquire.part.0+0xd4/0x280 [ 18.785668][ C2] console_lock_spinning_enable+0x5c/0x60 [ 18.785669][ C2] console_emit_next_record+0x1d1/0x4c0 [ 18.785671][ C2] console_flush_one_record+0x46f/0x710 [ 18.785672][ C2] console_unlock+0xee/0x1f0 [ 18.785673][ C2] vprintk_emit+0x37c/0x3e0 [ 18.785675][ C2] _printk+0xc7/0x100 [ 18.785676][ C2] ovpn_recv.cold+0x7e/0x94 [ovpn] [ 18.785678][ C2] ovpn_tcp_rcv+0x274/0x9a0 [ovpn] [ 18.785681][ C2] __strp_recv+0x20f/0x1c20 [ 18.785682][ C2] __tcp_read_sock+0x1c7/0x850 [ 18.785683][ C2] strp_read_sock+0x20f/0x2b0 [ 18.785685][ C2] strp_data_ready+0x18e/0x2c0 [ 18.785687][ C2] ovpn_tcp_data_ready+0xf1/0x390 [ovpn] [ 18.785689][ C2] tcp_rcv_established+0x1e1d/0x3910 [ 18.785690][ C2] tcp_v4_do_rcv+0x4f6/0xb90 [ 18.785692][ C2] tcp_v4_rcv+0x1caa/0x2b70 [ 18.785693][ C2] ip_protocol_deliver_rcu+0x82/0x350 [ 18.785694][ C2] ip_local_deliver_finish+0x36f/0x610 [ 18.785696][ C2] ip_local_deliver+0x184/0x4c0 [ 18.785697][ C2] ip_rcv+0xdc/0x3d0 [ 18.785698][ C2] __netif_receive_skb_one_core+0xfc/0x180 [ 18.785700][ C2] process_backlog+0x431/0x14c0 [ 18.785701][ C2] __napi_poll+0xa7/0x3b0 [ 18.785703][ C2] net_rx_action+0x513/0xf50 [ 18.785704][ C2] handle_softirqs+0x1d3/0x900 [ 18.785706][ C2] do_softirq+0xac/0xe0 [ 18.785707][ C2] __local_bh_enable_ip+0x118/0x150 [ 18.785708][ C2] ovpn_tcp_sendmsg+0x2c2/0x7d0 [ovpn] [ 18.785710][ C2] __sys_sendto+0x2aa/0x400 [ 18.785711][ C2] __x64_sys_sendto+0xe4/0x1f0 [ 18.785713][ C2] do_syscall_64+0xff/0x530 [ 18.785714][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 18.785715][ C2] [ 18.785716][ C2] [ 18.785716][ C2] stack backtrace: [ 18.785719][ C2] CPU: 2 UID: 0 PID: 365 Comm: ovpn-cli Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 18.785722][ C2] Tainted: [W]=WARN [ 18.785723][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 18.785724][ C2] Call Trace: [ 18.785725][ C2] [ 18.785726][ C2] dump_stack_lvl+0x6f/0xa0 [ 18.785730][ C2] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 18.785733][ C2] mark_lock_irq+0x989/0x9c0 [ 18.785736][ C2] mark_lock+0x1d7/0xa00 [ 18.785737][ C2] mark_usage+0x42/0x170 [ 18.785739][ C2] __lock_acquire+0x388/0xc20 [ 18.785741][ C2] lock_acquire.part.0+0xd4/0x280 [ 18.785743][ C2] ? console_lock_spinning_enable+0x40/0x60 [ 18.785745][ C2] ? rcu_is_watching+0x16/0xd0 [ 18.785749][ C2] ? lock_acquire+0x13c/0x160 [ 18.785751][ C2] console_lock_spinning_enable+0x5c/0x60 [ 18.785752][ C2] ? console_lock_spinning_enable+0x40/0x60 [ 18.785754][ C2] console_emit_next_record+0x1d1/0x4c0 [ 18.785756][ C2] ? devkmsg_read+0x4b0/0x4b0 [ 18.785758][ C2] ? console_flush_one_record+0x106/0x710 [ 18.785760][ C2] ? rcu_is_watching+0x16/0xd0 [ 18.785761][ C2] ? lock_acquire+0x13c/0x160 [ 18.785763][ C2] console_flush_one_record+0x46f/0x710 [ 18.785766][ C2] ? console_emit_next_record+0x4c0/0x4c0 [ 18.785767][ C2] ? __lock_acquire+0x518/0xc20 [ 18.785769][ C2] console_unlock+0xee/0x1f0 [ 18.785771][ C2] ? console_flush_one_record+0x710/0x710 [ 18.785773][ C2] ? rcu_is_watching+0x16/0xd0 [ 18.785775][ C2] ? lock_acquire+0x60/0x160 [ 18.785777][ C2] ? __down_trylock_console_sem+0x5e/0xa0 [ 18.785778][ C2] ? vprintk_emit+0x320/0x3e0 [ 18.785780][ C2] vprintk_emit+0x37c/0x3e0 [ 18.785782][ C2] ? wake_up_klogd_work_func+0x90/0x90 [ 18.785784][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 18.785787][ C2] _printk+0xc7/0x100 [ 18.785788][ C2] ? snapshot_read.cold+0x21/0x21 [ 18.785790][ C2] ? ___ratelimit+0x4e7/0xa00 [ 18.785793][ C2] ? ovpn_netdev_write+0x730/0x730 [ovpn] [ 18.785797][ C2] ? idr_get_free+0x830/0x830 [ 18.785799][ C2] ovpn_recv.cold+0x7e/0x94 [ovpn] [ 18.785802][ C2] ovpn_tcp_rcv+0x274/0x9a0 [ovpn] [ 18.785805][ C2] ? ovpn_tcp_write_space+0x2e0/0x2e0 [ovpn] [ 18.785807][ C2] ? __copy_skb_header+0xb7/0x510 [ 18.785810][ C2] ? ovpn_tcp_disconnect+0x10/0x10 [ovpn] [ 18.785812][ C2] ? sock_queue_err_skb+0xb0/0x680 [ 18.785814][ C2] __strp_recv+0x20f/0x1c20 [ 18.785818][ C2] __tcp_read_sock+0x1c7/0x850 [ 18.785819][ C2] ? strp_process+0xb0/0xb0 [ 18.785821][ C2] ? __tcp_transmit_skb+0x1b6e/0x3c10 [ 18.785823][ C2] ? tcp_cleanup_rbuf+0x1c0/0x1c0 [ 18.785824][ C2] ? mark_usage+0x61/0x170 [ 18.785826][ C2] strp_read_sock+0x20f/0x2b0 [ 18.785828][ C2] ? strp_stop+0x50/0x50 [ 18.785830][ C2] ? rcu_is_watching+0x16/0xd0 [ 18.785832][ C2] ? lock_acquire+0x13c/0x160 [ 18.785834][ C2] strp_data_ready+0x18e/0x2c0 [ 18.785836][ C2] ovpn_tcp_data_ready+0xf1/0x390 [ovpn] [ 18.785839][ C2] tcp_rcv_established+0x1e1d/0x3910 [ 18.785841][ C2] ? __tcp_check_space+0x600/0x600 [ 18.785843][ C2] ? tcp_v4_rcv+0x1a81/0x2b70 [ 18.785845][ C2] ? do_raw_spin_lock+0x131/0x280 [ 18.785847][ C2] tcp_v4_do_rcv+0x4f6/0xb90 [ 18.785849][ C2] tcp_v4_rcv+0x1caa/0x2b70 [ 18.785852][ C2] ? mark_usage+0x61/0x170 [ 18.785853][ C2] ? tcp_add_backlog+0x1e30/0x1e30 [ 18.785856][ C2] ? lock_acquire.part.0+0xd4/0x280 [ 18.785857][ C2] ? ip_local_deliver_finish+0x2ba/0x610 [ 18.785859][ C2] ip_protocol_deliver_rcu+0x82/0x350 [ 18.785861][ C2] ? process_backlog+0x3f1/0x14c0 [ 18.785863][ C2] ip_local_deliver_finish+0x36f/0x610 [ 18.785865][ C2] ip_local_deliver+0x184/0x4c0 [ 18.785866][ C2] ? ip_local_deliver_finish+0x610/0x610 [ 18.785868][ C2] ? ip_rcv_finish_core+0x6ed/0x14c0 [ 18.785870][ C2] ? process_backlog+0x3f1/0x14c0 [ 18.785872][ C2] ip_rcv+0xdc/0x3d0 [ 18.785874][ C2] ? ip_local_deliver+0x4c0/0x4c0 [ 18.785876][ C2] ? mark_usage+0x61/0x170 [ 18.785877][ C2] ? __lock_acquire+0x518/0xc20 [ 18.785879][ C2] __netif_receive_skb_one_core+0xfc/0x180 [ 18.785881][ C2] ? lock_acquire.part.0+0xd4/0x280 [ 18.785882][ C2] ? process_backlog+0x3f1/0x14c0 [ 18.785884][ C2] ? __netif_receive_skb_list_core+0x9e0/0x9e0 [ 18.785886][ C2] ? rcu_is_watching+0x16/0xd0 [ 18.785889][ C2] process_backlog+0x431/0x14c0 [ 18.785891][ C2] __napi_poll+0xa7/0x3b0 [ 18.785894][ C2] net_rx_action+0x513/0xf50 [ 18.785896][ C2] ? __napi_poll+0x3b0/0x3b0 [ 18.785898][ C2] ? mark_held_locks+0x40/0x70 [ 18.785901][ C2] ? __run_timers+0xaa0/0xaa0 [ 18.785904][ C2] ? rcu_is_watching+0x16/0xd0 [ 18.785906][ C2] ? rcu_is_watching+0x16/0xd0 [ 18.785908][ C2] handle_softirqs+0x1d3/0x900 [ 18.785910][ C2] ? _local_bh_enable+0xc0/0xc0 [ 18.785911][ C2] ? _local_bh_enable+0xc0/0xc0 [ 18.785913][ C2] do_softirq+0xac/0xe0 [ 18.785915][ C2] [ 18.785915][ C2] [ 18.785916][ C2] __local_bh_enable_ip+0x118/0x150 [ 18.785917][ C2] ovpn_tcp_sendmsg+0x2c2/0x7d0 [ovpn] [ 18.785920][ C2] ? ovpn_tcp_recvmsg+0x6d0/0x6d0 [ovpn] [ 18.785924][ C2] __sys_sendto+0x2aa/0x400 [ 18.785926][ C2] ? __ia32_sys_getpeername+0xd0/0xd0 [ 18.785930][ C2] ? fput_close_sync+0xde/0x1b0 [ 18.785932][ C2] ? alloc_file_clone+0xe0/0xe0 [ 18.785934][ C2] __x64_sys_sendto+0xe4/0x1f0 [ 18.785935][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 18.785938][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 18.785939][ C2] ? do_syscall_64+0xa6/0x530 [ 18.785941][ C2] do_syscall_64+0xff/0x530 [ 18.785942][ C2] ? exc_page_fault+0xee/0x100 [ 18.785943][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 18.785945][ C2] RIP: 0033:0x7f3d3058654e [ 18.785948][ C2] 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 [ 18.785950][ C2] RSP: 002b:00007ffc54b91d20 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 18.785952][ C2] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f3d3058654e [ 18.785953][ C2] RDX: 00000000000003ea RSI: 00007ffc54b91d90 RDI: 0000000000000005 [ 18.785954][ C2] RBP: 00007ffc54b91d30 R08: 0000000000000000 R09: 0000000000000000 [ 18.785955][ C2] R10: 0000000000004000 R11: 0000000000000202 R12: 00007ffc54b926b8 [ 18.785956][ C2] R13: 0000000000000008 R14: 00007f3d30871000 R15: 000000000040bda8 [ 18.785958][ C2] [ 18.785962][ C2] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 18.785963][ C2] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 365, name: ovpn-cli [ 18.785965][ C2] preempt_count: 103, expected: 0 [ 18.785965][ C2] RCU nest depth: 3, expected: 0 [ 18.785966][ C2] INFO: lockdep is turned off. [ 18.785967][ C2] irq event stamp: 39141 [ 18.785967][ C2] hardirqs last enabled at (39140): [] __down_trylock_console_sem+0x86/0xa0 [ 18.785970][ C2] hardirqs last disabled at (39141): [] console_emit_next_record+0x3d4/0x4c0 [ 18.785971][ C2] softirqs last enabled at (39024): [] ovpn_tcp_sendmsg+0x2c2/0x7d0 [ovpn] [ 18.785974][ C2] softirqs last disabled at (39025): [] do_softirq+0xac/0xe0 [ 18.785975][ C2] Preemption disabled at: [ 18.785976][ C2] [<0000000000000000>] 0x0 [ 18.785978][ C2] CPU: 2 UID: 0 PID: 365 Comm: ovpn-cli Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 18.785980][ C2] Tainted: [W]=WARN [ 18.785980][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 18.785981][ C2] Call Trace: [ 18.785981][ C2] [ 18.785982][ C2] dump_stack_lvl+0x6f/0xa0 [ 18.785984][ C2] __might_resched.cold+0x1fe/0x2c1 [ 18.785987][ C2] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 18.785990][ C2] ? rcu_is_watching+0x16/0xd0 [ 18.785992][ C2] __kmalloc_noprof+0x443/0x760 [ 18.785994][ C2] ? __rwlock_init+0x150/0x150 [ 18.785995][ C2] ? alloc_buf.isra.0+0x4b/0x260 [ 18.785997][ C2] ? do_raw_spin_unlock+0x59/0x250 [ 18.785999][ C2] alloc_buf.isra.0+0x4b/0x260 [ 18.786001][ C2] put_chars+0x1e1/0x2f0 [ 18.786003][ C2] ? __send_to_port+0x420/0x420 [ 18.786005][ C2] hvc_console_print+0x292/0x780 [ 18.786007][ C2] ? __lock_acquire+0x388/0xc20 [ 18.786009][ C2] ? hvc_write+0x3a0/0x3a0 [ 18.786011][ C2] ? rcu_is_watching+0x16/0xd0 [ 18.786013][ C2] ? lock_acquire+0x13c/0x160 [ 18.786015][ C2] console_emit_next_record+0x22f/0x4c0 [ 18.786017][ C2] ? devkmsg_read+0x4b0/0x4b0 [ 18.786018][ C2] ? console_flush_one_record+0x106/0x710 [ 18.786020][ C2] ? rcu_is_watching+0x16/0xd0 [ 18.786022][ C2] ? lock_acquire+0x13c/0x160 [ 18.786024][ C2] console_flush_one_record+0x46f/0x710 [ 18.786026][ C2] ? console_emit_next_record+0x4c0/0x4c0 [ 18.786028][ C2] ? __lock_acquire+0x518/0xc20 [ 18.786030][ C2] console_unlock+0xee/0x1f0 [ 18.786032][ C2] ? console_flush_one_record+0x710/0x710 [ 18.786033][ C2] ? rcu_is_watching+0x16/0xd0 [ 18.786035][ C2] ? lock_acquire+0x60/0x160 [ 18.786037][ C2] ? __down_trylock_console_sem+0x5e/0xa0 [ 18.786038][ C2] ? vprintk_emit+0x320/0x3e0 [ 18.786040][ C2] vprintk_emit+0x37c/0x3e0 [ 18.786043][ C2] ? wake_up_klogd_work_func+0x90/0x90 [ 18.786044][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 18.786047][ C2] _printk+0xc7/0x100 [ 18.786048][ C2] ? snapshot_read.cold+0x21/0x21 [ 18.786050][ C2] ? ___ratelimit+0x4e7/0xa00 [ 18.786052][ C2] ? ovpn_netdev_write+0x730/0x730 [ovpn] [ 18.786054][ C2] ? idr_get_free+0x830/0x830 [ 18.786057][ C2] ovpn_recv.cold+0x7e/0x94 [ovpn] [ 18.786059][ C2] ovpn_tcp_rcv+0x274/0x9a0 [ovpn] [ 18.786062][ C2] ? ovpn_tcp_write_space+0x2e0/0x2e0 [ovpn] [ 18.786064][ C2] ? __copy_skb_header+0xb7/0x510 [ 18.786066][ C2] ? ovpn_tcp_disconnect+0x10/0x10 [ovpn] [ 18.786069][ C2] ? sock_queue_err_skb+0xb0/0x680 [ 18.786071][ C2] __strp_recv+0x20f/0x1c20 [ 18.786074][ C2] __tcp_read_sock+0x1c7/0x850 [ 18.786075][ C2] ? strp_process+0xb0/0xb0 [ 18.786077][ C2] ? __tcp_transmit_skb+0x1b6e/0x3c10 [ 18.786079][ C2] ? tcp_cleanup_rbuf+0x1c0/0x1c0 [ 18.786080][ C2] ? mark_usage+0x61/0x170 [ 18.786082][ C2] strp_read_sock+0x20f/0x2b0 [ 18.786084][ C2] ? strp_stop+0x50/0x50 [ 18.786086][ C2] ? rcu_is_watching+0x16/0xd0 [ 18.786088][ C2] ? lock_acquire+0x13c/0x160 [ 18.786090][ C2] strp_data_ready+0x18e/0x2c0 [ 18.786092][ C2] ovpn_tcp_data_ready+0xf1/0x390 [ovpn] [ 18.786095][ C2] tcp_rcv_established+0x1e1d/0x3910 [ 18.786097][ C2] ? __tcp_check_space+0x600/0x600 [ 18.786099][ C2] ? tcp_v4_rcv+0x1a81/0x2b70 [ 18.786101][ C2] ? do_raw_spin_lock+0x131/0x280 [ 18.786103][ C2] tcp_v4_do_rcv+0x4f6/0xb90 [ 18.786105][ C2] tcp_v4_rcv+0x1caa/0x2b70 [ 18.786107][ C2] ? mark_usage+0x61/0x170 [ 18.786109][ C2] ? tcp_add_backlog+0x1e30/0x1e30 [ 18.786111][ C2] ? lock_acquire.part.0+0xd4/0x280 [ 18.786113][ C2] ? ip_local_deliver_finish+0x2ba/0x610 [ 18.786115][ C2] ip_protocol_deliver_rcu+0x82/0x350 [ 18.786116][ C2] ? process_backlog+0x3f1/0x14c0 [ 18.786118][ C2] ip_local_deliver_finish+0x36f/0x610 [ 18.786120][ C2] ip_local_deliver+0x184/0x4c0 [ 18.786122][ C2] ? ip_local_deliver_finish+0x610/0x610 [ 18.786123][ C2] ? ip_rcv_finish_core+0x6ed/0x14c0 [ 18.786126][ C2] ? process_backlog+0x3f1/0x14c0 [ 18.786127][ C2] ip_rcv+0xdc/0x3d0 [ 18.786129][ C2] ? ip_local_deliver+0x4c0/0x4c0 [ 18.786131][ C2] ? mark_usage+0x61/0x170 [ 18.786132][ C2] ? __lock_acquire+0x518/0xc20 [ 18.786134][ C2] __netif_receive_skb_one_core+0xfc/0x180 [ 18.786136][ C2] ? lock_acquire.part.0+0xd4/0x280 [ 18.786138][ C2] ? process_backlog+0x3f1/0x14c0 [ 18.786140][ C2] ? __netif_receive_skb_list_core+0x9e0/0x9e0 [ 18.786142][ C2] ? rcu_is_watching+0x16/0xd0 [ 18.786144][ C2] process_backlog+0x431/0x14c0 [ 18.786147][ C2] __napi_poll+0xa7/0x3b0 [ 18.786149][ C2] net_rx_action+0x513/0xf50 [ 18.786152][ C2] ? __napi_poll+0x3b0/0x3b0 [ 18.786153][ C2] ? mark_held_locks+0x40/0x70 [ 18.786156][ C2] ? __run_timers+0xaa0/0xaa0 [ 18.786158][ C2] ? rcu_is_watching+0x16/0xd0 [ 18.786160][ C2] ? rcu_is_watching+0x16/0xd0 [ 18.786162][ C2] handle_softirqs+0x1d3/0x900 [ 18.786164][ C2] ? _local_bh_enable+0xc0/0xc0 [ 18.786165][ C2] ? _local_bh_enable+0xc0/0xc0 [ 18.786167][ C2] do_softirq+0xac/0xe0 [ 18.786168][ C2] [ 18.786169][ C2] [ 18.786169][ C2] __local_bh_enable_ip+0x118/0x150 [ 18.786171][ C2] ovpn_tcp_sendmsg+0x2c2/0x7d0 [ovpn] [ 18.786174][ C2] ? ovpn_tcp_recvmsg+0x6d0/0x6d0 [ovpn] [ 18.786177][ C2] __sys_sendto+0x2aa/0x400 [ 18.786179][ C2] ? __ia32_sys_getpeername+0xd0/0xd0 [ 18.786183][ C2] ? fput_close_sync+0xde/0x1b0 [ 18.786184][ C2] ? alloc_file_clone+0xe0/0xe0 [ 18.786186][ C2] __x64_sys_sendto+0xe4/0x1f0 [ 18.786187][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 18.786189][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 18.786190][ C2] ? do_syscall_64+0xa6/0x530 [ 18.786191][ C2] do_syscall_64+0xff/0x530 [ 18.786193][ C2] ? exc_page_fault+0xee/0x100 [ 18.786194][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 18.786195][ C2] RIP: 0033:0x7f3d3058654e [ 18.786197][ C2] 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 [ 18.786198][ C2] RSP: 002b:00007ffc54b91d20 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 18.786199][ C2] RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f3d3058654e [ 18.786200][ C2] RDX: 00000000000003ea RSI: 00007ffc54b91d90 RDI: 0000000000000005 [ 18.786200][ C2] RBP: 00007ffc54b91d30 R08: 0000000000000000 R09: 0000000000000000 [ 18.786201][ C2] R10: 0000000000004000 R11: 0000000000000202 R12: 00007ffc54b926b8 [ 18.786202][ C2] R13: 0000000000000008 R14: 00007f3d30871000 R15: 000000000040bda8 [ 18.786204][ C2] [ 18.947305][ C0] tun0: no available key for peer 4, key-id: 0 [ 19.071167][ C1] tun0: no available key for peer 6, key-id: 0 [ 24.388984][ T479] iperf3 (479) used greatest stack depth: 23128 bytes left [ 24.389001][ T479] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 24.389004][ T479] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 479, name: iperf3 [ 24.389006][ T479] preempt_count: 2, expected: 0 [ 24.389007][ T479] RCU nest depth: 0, expected: 0 [ 24.389007][ T479] INFO: lockdep is turned off. [ 24.389008][ T479] irq event stamp: 0 [ 24.389009][ T479] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 24.389014][ T479] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 24.389020][ T479] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 24.389022][ T479] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 24.389024][ T479] Preemption disabled at: [ 24.389024][ T479] [<0000000000000000>] 0x0 [ 24.389030][ T479] CPU: 3 UID: 0 PID: 479 Comm: iperf3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 24.389033][ T479] Tainted: [W]=WARN [ 24.389034][ T479] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 24.389036][ T479] Call Trace: [ 24.389037][ T479] [ 24.389038][ T479] dump_stack_lvl+0x6f/0xa0 [ 24.389044][ T479] __might_resched.cold+0x1fe/0x2c1 [ 24.389048][ T479] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 24.389052][ T479] ? prb_next_reserve_seq+0x2d0/0x2d0 [ 24.389054][ T479] ? rcu_is_watching+0x16/0xd0 [ 24.389058][ T479] __kmalloc_noprof+0x443/0x760 [ 24.389062][ T479] ? __rwlock_init+0x150/0x150 [ 24.389064][ T479] ? alloc_buf.isra.0+0x4b/0x260 [ 24.389069][ T479] ? do_raw_spin_unlock+0x59/0x250 [ 24.389071][ T479] alloc_buf.isra.0+0x4b/0x260 [ 24.389073][ T479] put_chars+0x1e1/0x2f0 [ 24.389075][ T479] ? prb_final_commit+0x50/0x50 [ 24.389076][ T479] ? __send_to_port+0x420/0x420 [ 24.389078][ T479] ? printk_get_next_message+0x240/0x650 [ 24.389081][ T479] hvc_console_print+0x292/0x780 [ 24.389085][ T479] ? hvc_write+0x3a0/0x3a0 [ 24.389087][ T479] ? do_raw_spin_lock+0x131/0x280 [ 24.389088][ T479] ? vsnprintf+0x668/0x1700 [ 24.389091][ T479] ? __rwlock_init+0x150/0x150 [ 24.389093][ T479] ? rcu_is_watching+0x16/0xd0 [ 24.389095][ T479] ? lock_acquire+0x13c/0x160 [ 24.389097][ T479] console_emit_next_record+0x22f/0x4c0 [ 24.389100][ T479] ? devkmsg_read+0x4b0/0x4b0 [ 24.389102][ T479] ? rcu_is_watching+0x16/0xd0 [ 24.389103][ T479] ? rcu_is_watching+0x16/0xd0 [ 24.389105][ T479] ? rcu_is_watching+0x16/0xd0 [ 24.389107][ T479] ? lock_acquire+0x13c/0x160 [ 24.389109][ T479] ? console_flush_one_record+0x111/0x710 [ 24.389110][ T479] console_flush_one_record+0x46f/0x710 [ 24.389113][ T479] ? console_emit_next_record+0x4c0/0x4c0 [ 24.389115][ T479] console_unlock+0xee/0x1f0 [ 24.389117][ T479] ? lock_acquire+0x13c/0x160 [ 24.389119][ T479] ? console_flush_one_record+0x710/0x710 [ 24.389120][ T479] ? rcu_is_watching+0x16/0xd0 [ 24.389122][ T479] ? lock_release+0x184/0x1f0 [ 24.389124][ T479] ? lock_acquire+0x60/0x160 [ 24.389125][ T479] ? __rcu_read_lock+0x30/0x70 [ 24.389127][ T479] ? __down_trylock_console_sem+0x5e/0xa0 [ 24.389129][ T479] ? vprintk_emit+0x320/0x3e0 [ 24.389131][ T479] vprintk_emit+0x37c/0x3e0 [ 24.389133][ T479] ? wake_up_klogd_work_func+0x90/0x90 [ 24.389136][ T479] _printk+0xc7/0x100 [ 24.389140][ T479] ? snapshot_read.cold+0x21/0x21 [ 24.389141][ T479] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 24.389144][ T479] ? do_raw_spin_lock+0x131/0x280 [ 24.389146][ T479] ? __rwlock_init+0x150/0x150 [ 24.389148][ T479] ? do_raw_spin_lock+0x131/0x280 [ 24.389150][ T479] do_exit.cold+0x82/0x9c [ 24.389153][ T479] ? exit_notify+0x890/0x890 [ 24.389155][ T479] __x64_sys_exit+0x42/0x50 [ 24.389156][ T479] x64_sys_call+0x156c/0x1570 [ 24.389159][ T479] do_syscall_64+0xff/0x530 [ 24.389161][ T479] ? exc_page_fault+0xee/0x100 [ 24.389163][ T479] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 24.389165][ T479] RIP: 0033:0x7fd135ac1c49 [ 24.389167][ T479] Code: Unable to access opcode bytes at 0x7fd135ac1c1f. [ 24.389168][ T479] RSP: 002b:00007fd135280e60 EFLAGS: 00000246 ORIG_RAX: 000000000000003c [ 24.389172][ T479] RAX: ffffffffffffffda RBX: 00007fd1352816c0 RCX: 00007fd135ac1c49 [ 24.389173][ T479] RDX: 0000000000000000 RSI: 0000000000801000 RDI: 0000000000000000 [ 24.389174][ T479] RBP: 00007fd135280f70 R08: 0000000000000001 R09: 00007fd1352816c0 [ 24.389175][ T479] R10: 0000000000000008 R11: 0000000000000246 R12: 00007fd134a81000 [ 24.389175][ T479] R13: 00007ffff042cb60 R14: 0000000000000002 R15: 00007ffff042cc67 [ 24.389178][ T479] [ 24.548534][ T352] tun1: deleting peer with id 10, reason 4 [ 24.549672][ T359] tun2: deleting peer with id 11, reason 4 [ 24.549927][ T373] tun4: deleting peer with id 13, reason 4 [ 24.549940][ T380] tun5: deleting peer with id 14, reason 4 [ 24.550622][ T387] tun6: deleting peer with id 15, reason 4 [ 24.554155][ T366] tun3: deleting peer with id 12, reason 4 [ 24.554433][ T388] tun0: deleting peer with id 6, reason 4 [ 24.558799][ T388] tun0: deleting peer with id 5, reason 4 [ 24.560774][ T388] tun0: deleting peer with id 4, reason 4 [ 24.563780][ T388] tun0: deleting peer with id 3, reason 4 [ 24.571777][ T388] tun0: deleting peer with id 2, reason 4 [ 24.585817][ T388] tun0: deleting peer with id 1, reason 4