[ 10.241570][ T181] PPP generic driver version 2.4.2 [ 10.241589][ T181] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.241591][ T181] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 181, name: modprobe [ 10.241593][ T181] preempt_count: 1, expected: 0 [ 10.241594][ T181] RCU nest depth: 0, expected: 0 [ 10.241594][ T181] locks held by modprobe/181: 4, last CPU#1: [ 10.241597][ T181] #0: ffffffffba579cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.241609][ T181] #1: ffffffffba579d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.241614][ T181] #2: ffffffffba499660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.241618][ T181] #3: ffffffffba499560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.241622][ T181] irq event stamp: 50458 [ 10.241623][ T181] hardirqs last enabled at (50457): [] __down_trylock_console_sem+0x86/0xa0 [ 10.241625][ T181] hardirqs last disabled at (50458): [] console_emit_next_record+0x3d4/0x4c0 [ 10.241627][ T181] softirqs last enabled at (48260): [] handle_softirqs+0x67c/0x900 [ 10.241630][ T181] softirqs last disabled at (48169): [] __irq_exit_rcu+0x145/0x1c0 [ 10.241633][ T181] Preemption disabled at: [ 10.241633][ T181] [] vprintk_emit+0x31b/0x3e0 [ 10.241639][ T181] CPU: 1 UID: 0 PID: 181 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.241643][ T181] Tainted: [W]=WARN [ 10.241644][ T181] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.241645][ T181] Call Trace: [ 10.241647][ T181] [ 10.241649][ T181] dump_stack_lvl+0x6f/0xa0 [ 10.241655][ T181] ? vprintk_emit+0x31b/0x3e0 [ 10.241657][ T181] __might_resched.cold+0x1fe/0x2c1 [ 10.241662][ T181] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.241666][ T181] ? __kmalloc_noprof+0xdb/0x760 [ 10.241671][ T181] __kmalloc_noprof+0x443/0x760 [ 10.241673][ T181] ? alloc_buf.isra.0+0x4b/0x260 [ 10.241679][ T181] ? do_raw_spin_unlock+0x59/0x250 [ 10.241682][ T181] alloc_buf.isra.0+0x4b/0x260 [ 10.241686][ T181] put_chars+0x1e1/0x2f0 [ 10.241689][ T181] ? __send_to_port+0x420/0x420 [ 10.241690][ T181] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.241696][ T181] ? rcu_read_lock_any_held+0x3c/0x90 [ 10.241698][ T181] ? validate_chain+0x38b/0xc20 [ 10.241703][ T181] hvc_console_print+0x292/0x780 [ 10.241706][ T181] ? __lock_acquire+0x518/0xc20 [ 10.241711][ T181] ? hvc_write+0x3a0/0x3a0 [ 10.241714][ T181] ? rcu_is_watching+0x16/0xd0 [ 10.241720][ T181] console_emit_next_record+0x22f/0x4c0 [ 10.241724][ T181] ? devkmsg_read+0x4b0/0x4b0 [ 10.241726][ T181] ? console_flush_one_record+0x106/0x710 [ 10.241729][ T181] ? rcu_is_watching+0x16/0xd0 [ 10.241732][ T181] ? lock_acquire+0x13c/0x160 [ 10.241736][ T181] console_flush_one_record+0x46f/0x710 [ 10.241740][ T181] ? console_emit_next_record+0x4c0/0x4c0 [ 10.241742][ T181] ? __lock_acquire+0x518/0xc20 [ 10.241747][ T181] console_unlock+0xee/0x1f0 [ 10.241750][ T181] ? console_flush_one_record+0x710/0x710 [ 10.241752][ T181] ? rcu_is_watching+0x16/0xd0 [ 10.241754][ T181] ? lock_acquire+0x60/0x160 [ 10.241758][ T181] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.241760][ T181] ? vprintk_emit+0x320/0x3e0 [ 10.241763][ T181] vprintk_emit+0x37c/0x3e0 [ 10.241768][ T181] ? wake_up_klogd_work_func+0x90/0x90 [ 10.241774][ T181] ? ppp_cleanup+0x920/0x920 [ppp_generic] [ 10.241779][ T181] _printk+0xc7/0x100 [ 10.241782][ T181] ? snapshot_read.cold+0x21/0x21 [ 10.241789][ T181] ? do_one_initcall+0x113/0x4f0 [ 10.241792][ T181] ppp_init+0x16/0x1000 [ppp_generic] [ 10.241796][ T181] do_one_initcall+0x124/0x4f0 [ 10.241798][ T181] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 10.241803][ T181] ? do_init_module+0xae/0x8a0 [ 10.241806][ T181] ? kasan_unpoison+0x44/0x70 [ 10.241811][ T181] do_init_module+0x2ad/0x8a0 [ 10.241815][ T181] ? lockdep_hardirqs_on+0x91/0x130 [ 10.241819][ T181] ? free_module+0x470/0x470 [ 10.241822][ T181] ? kfree+0x123/0x590 [ 10.241826][ T181] load_module+0x1424/0x1bb0 [ 10.241834][ T181] ? layout_and_allocate.isra.0+0x680/0x680 [ 10.241840][ T181] ? backing_file_read_iter+0x541/0x790 [ 10.241843][ T181] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.241847][ T181] init_module_from_file+0x11f/0x1a0 [ 10.241850][ T181] ? __do_sys_init_module+0x220/0x220 [ 10.241859][ T181] ? __rwlock_init+0x150/0x150 [ 10.241862][ T181] ? do_raw_spin_unlock+0x59/0x250 [ 10.241866][ T181] idempotent_init_module+0x37b/0x7c0 [ 10.241869][ T181] ? init_module_from_file+0x1a0/0x1a0 [ 10.241876][ T181] ? rcu_is_watching+0x16/0xd0 [ 10.241878][ T181] ? cap_capable+0x1d7/0x3d0 [ 10.241885][ T181] __x64_sys_finit_module+0xdf/0x190 [ 10.241887][ T181] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 10.241891][ T181] do_syscall_64+0xff/0x530 [ 10.241893][ T181] ? exc_page_fault+0xee/0x100 [ 10.241896][ T181] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.241898][ T181] RIP: 0033:0x7f48351be37d [ 10.241902][ T181] Code: ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 4b aa 0f 00 f7 d8 64 89 01 48 [ 10.241903][ T181] RSP: 002b:00007fff3acc9898 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 10.241906][ T181] RAX: ffffffffffffffda RBX: 00005579ab3ee1d0 RCX: 00007f48351be37d [ 10.241908][ T181] RDX: 0000000000000000 RSI: 0000557985434ffd RDI: 0000000000000006 [ 10.241909][ T181] RBP: 00007fff3acc9930 R08: 0000000000000000 R09: 0000000000000000 [ 10.241909][ T181] R10: 0000000000000000 R11: 0000000000000246 R12: 0000557985434ffd [ 10.241910][ T181] R13: 00005579ab3ee180 R14: 0000000000040000 R15: 0000000000000000 [ 10.241917][ T181] [ 10.276337][ T181] NET: Registered PF_PPPOX protocol family [ 10.311577][ T181] l2tp_core: L2TP core driver, V2.0 [ 10.318318][ T181] l2tp_ppp: PPPoL2TP kernel driver, V2.0 [ 10.328741][ T181] modprobe (181) used greatest stack depth: 24144 bytes left [ 15.680569][ T263] iperf3 (263) used greatest stack depth: 23176 bytes left [ 15.680593][ T263] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 15.680596][ T263] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 263, name: iperf3 [ 15.680599][ T263] preempt_count: 2, expected: 0 [ 15.680600][ T263] RCU nest depth: 0, expected: 0 [ 15.680602][ T263] locks held by iperf3/263: 5, last CPU#0: [ 15.680605][ T263] #0: ffffffffba4127b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 15.680618][ T263] #1: ffffffffba579cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 15.680626][ T263] #2: ffffffffba579d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 15.680632][ T263] #3: ffffffffba499660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 15.680639][ T263] #4: ffffffffba499560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 15.680645][ T263] irq event stamp: 1451720 [ 15.680646][ T263] hardirqs last enabled at (1451719): [] __down_trylock_console_sem+0x86/0xa0 [ 15.680650][ T263] hardirqs last disabled at (1451720): [] console_emit_next_record+0x3d4/0x4c0 [ 15.680653][ T263] softirqs last enabled at (1451298): [] fpu__clear_user_states+0x104/0x290 [ 15.680657][ T263] softirqs last disabled at (1451296): [] fpu__clear_user_states+0x6d/0x290 [ 15.680661][ T263] Preemption disabled at: [ 15.680662][ T263] [<0000000000000000>] 0x0 [ 15.680671][ T263] CPU: 0 UID: 0 PID: 263 Comm: iperf3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.680675][ T263] Tainted: [W]=WARN [ 15.680677][ T263] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.680679][ T263] Call Trace: [ 15.680681][ T263] [ 15.680683][ T263] dump_stack_lvl+0x6f/0xa0 [ 15.680691][ T263] __might_resched.cold+0x1fe/0x2c1 [ 15.680697][ T263] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 15.680702][ T263] ? __kmalloc_noprof+0xdb/0x760 [ 15.680710][ T263] __kmalloc_noprof+0x443/0x760 [ 15.680713][ T263] ? alloc_buf.isra.0+0x4b/0x260 [ 15.680722][ T263] ? do_raw_spin_unlock+0x59/0x250 [ 15.680726][ T263] alloc_buf.isra.0+0x4b/0x260 [ 15.680731][ T263] put_chars+0x1e1/0x2f0 [ 15.680734][ T263] ? prb_final_commit+0x50/0x50 [ 15.680737][ T263] ? __send_to_port+0x420/0x420 [ 15.680743][ T263] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.680749][ T263] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.680752][ T263] ? validate_chain+0x38b/0xc20 [ 15.680758][ T263] hvc_console_print+0x292/0x780 [ 15.680763][ T263] ? mark_usage+0x61/0x170 [ 15.680765][ T263] ? __lock_acquire+0x518/0xc20 [ 15.680768][ T263] ? __lock_acquire+0x518/0xc20 [ 15.680774][ T263] ? hvc_write+0x3a0/0x3a0 [ 15.680778][ T263] ? console_emit_next_record+0x1df/0x4c0 [ 15.680783][ T263] ? rcu_is_watching+0x16/0xd0 [ 15.680787][ T263] ? lock_acquire+0x13c/0x160 [ 15.680793][ T263] console_emit_next_record+0x22f/0x4c0 [ 15.680800][ T263] ? devkmsg_read+0x4b0/0x4b0 [ 15.680802][ T263] ? console_flush_one_record+0x106/0x710 [ 15.680807][ T263] ? rcu_is_watching+0x16/0xd0 [ 15.680811][ T263] ? lock_acquire+0x13c/0x160 [ 15.680818][ T263] console_flush_one_record+0x46f/0x710 [ 15.680824][ T263] ? console_emit_next_record+0x4c0/0x4c0 [ 15.680827][ T263] ? __lock_acquire+0x518/0xc20 [ 15.680836][ T263] console_unlock+0xee/0x1f0 [ 15.680841][ T263] ? console_flush_one_record+0x710/0x710 [ 15.680843][ T263] ? rcu_is_watching+0x16/0xd0 [ 15.680847][ T263] ? lock_acquire+0x60/0x160 [ 15.680853][ T263] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.680856][ T263] ? vprintk_emit+0x320/0x3e0 [ 15.680861][ T263] vprintk_emit+0x37c/0x3e0 [ 15.680867][ T263] ? wake_up_klogd_work_func+0x90/0x90 [ 15.680873][ T263] ? __lock_acquire+0x518/0xc20 [ 15.680879][ T263] _printk+0xc7/0x100 [ 15.680884][ T263] ? snapshot_read.cold+0x21/0x21 [ 15.680889][ T263] ? do_raw_spin_lock+0x131/0x280 [ 15.680893][ T263] ? __rwlock_init+0x150/0x150 [ 15.680899][ T263] ? do_raw_spin_lock+0x131/0x280 [ 15.680903][ T263] do_exit.cold+0x82/0x9c [ 15.680909][ T263] ? exit_notify+0x890/0x890 [ 15.680918][ T263] __x64_sys_exit+0x42/0x50 [ 15.680921][ T263] x64_sys_call+0x156c/0x1570 [ 15.680924][ T263] do_syscall_64+0xff/0x530 [ 15.680929][ T263] ? exc_page_fault+0xee/0x100 [ 15.680934][ T263] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.680937][ T263] RIP: 0033:0x7f883c0edc49 [ 15.680940][ T263] Code: Unable to access opcode bytes at 0x7f883c0edc1f. [ 15.680942][ T263] RSP: 002b:00007f883b8ace60 EFLAGS: 00000246 ORIG_RAX: 000000000000003c [ 15.680945][ T263] RAX: ffffffffffffffda RBX: 00007f883b8ad6c0 RCX: 00007f883c0edc49 [ 15.680947][ T263] RDX: 0000000000000000 RSI: 0000000000801000 RDI: 0000000000000000 [ 15.680949][ T263] RBP: 00007f883b8acf70 R08: 0000000000000001 R09: 00007f883b8ad6c0 [ 15.680950][ T263] R10: 0000000000000008 R11: 0000000000000246 R12: 00007f883b0ad000 [ 15.680951][ T263] R13: 00007ffc649ee290 R14: 0000000000000002 R15: 00007ffc649ee397 [ 15.680964][ T263] [ 15.868572][ T267] ppp0: recursion detected [ 15.868609][ T267] [ 15.868611][ T267] ===================================================== [ 15.868612][ T267] WARNING: SOFTIRQ-safe -> SOFTIRQ-unsafe lock order detected [ 15.868615][ T267] 7.2.0-virtme #1 Tainted: G W [ 15.868616][ T267] ----------------------------------------------------- [ 15.868617][ T267] ping/267 [HC0[0]:SC0[10]:HE0:SE0] is trying to acquire: [ 15.868619][ T267] ffffffffba499660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 15.868631][ T267] [ 15.868631][ T267] and this task is already holding: [ 15.868632][ T267] ff11000005895998 (slock-AF_INET/2){+.-.}-{3:3}, at: l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.868639][ T267] which would create a new lock dependency: [ 15.868640][ T267] (slock-AF_INET/2){+.-.}-{3:3} -> (console_owner){....}-{0:0} [ 15.868643][ T267] [ 15.868643][ T267] but this new dependency connects a SOFTIRQ-irq-safe lock: [ 15.868644][ T267] (slock-AF_INET/2){+.-.}-{3:3} [ 15.868645][ T267] [ 15.868645][ T267] ... which became SOFTIRQ-irq-safe at: [ 15.868646][ T267] __lock_acquire+0x388/0xc20 [ 15.868649][ T267] lock_acquire.part.0+0xd4/0x280 [ 15.868651][ T267] _raw_spin_lock_nested+0x3d/0x50 [ 15.868654][ T267] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.868657][ T267] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.868660][ T267] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.868662][ T267] ppp_push+0x165/0x2d0 [ppp_generic] [ 15.868665][ T267] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 15.868667][ T267] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.868669][ T267] dev_hard_start_xmit+0xf4/0x620 [ 15.868672][ T267] __dev_queue_xmit+0xc0a/0x1b80 [ 15.868674][ T267] ip_finish_output2+0x5b9/0x1bc0 [ 15.868677][ T267] ip_output+0x1ca/0x660 [ 15.868678][ T267] ip_push_pending_frames+0xf6/0x250 [ 15.868680][ T267] icmp_reply+0x94d/0xa50 [ 15.868683][ T267] icmp_echo+0x2ab/0x4b0 [ 15.868684][ T267] icmp_rcv+0x92f/0xfb0 [ 15.868686][ T267] ip_protocol_deliver_rcu+0x82/0x350 [ 15.868687][ T267] ip_local_deliver_finish+0x36f/0x610 [ 15.868688][ T267] ip_local_deliver+0x184/0x4c0 [ 15.868690][ T267] ip_rcv+0xdc/0x3d0 [ 15.868691][ T267] __netif_receive_skb_one_core+0xfc/0x180 [ 15.868693][ T267] process_backlog+0x431/0x14c0 [ 15.868694][ T267] __napi_poll+0xa7/0x3b0 [ 15.868696][ T267] net_rx_action+0x513/0xf50 [ 15.868698][ T267] handle_softirqs+0x1d3/0x900 [ 15.868700][ T267] do_softirq+0xac/0xe0 [ 15.868701][ T267] __local_bh_enable_ip+0x118/0x150 [ 15.868703][ T267] __dev_queue_xmit+0x979/0x1b80 [ 15.868705][ T267] ip_finish_output2+0x5b9/0x1bc0 [ 15.868706][ T267] ip_output+0x1ca/0x660 [ 15.868707][ T267] ip_push_pending_frames+0xf6/0x250 [ 15.868709][ T267] raw_sendmsg+0x1169/0x1cc0 [ 15.868710][ T267] __sys_sendto+0x2aa/0x400 [ 15.868713][ T267] __x64_sys_sendto+0xe4/0x1f0 [ 15.868714][ T267] do_syscall_64+0xff/0x530 [ 15.868717][ T267] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.868719][ T267] [ 15.868719][ T267] to a SOFTIRQ-irq-unsafe lock: [ 15.868720][ T267] (fs_reclaim){+.+.}-{0:0} [ 15.868721][ T267] [ 15.868721][ T267] ... which became SOFTIRQ-irq-unsafe at: [ 15.868722][ T267] ... [ 15.868722][ T267] __lock_acquire+0x388/0xc20 [ 15.868724][ T267] lock_acquire.part.0+0xd4/0x280 [ 15.868725][ T267] fs_reclaim_acquire+0xd5/0x120 [ 15.868728][ T267] __kmalloc_cache_noprof+0x6e/0x620 [ 15.868730][ T267] kthread_create_worker_on_node+0xea/0x210 [ 15.868733][ T267] workqueue_init+0x2a/0x680 [ 15.868735][ T267] kernel_init_freeable+0x2fe/0x630 [ 15.868738][ T267] kernel_init+0x21/0x150 [ 15.868740][ T267] ret_from_fork+0x474/0x6b0 [ 15.868743][ T267] ret_from_fork_asm+0x11/0x20 [ 15.868746][ T267] [ 15.868746][ T267] other info that might help us debug this: [ 15.868746][ T267] [ 15.868746][ T267] Chain exists of: [ 15.868746][ T267] slock-AF_INET/2 --> console_owner --> fs_reclaim [ 15.868746][ T267] [ 15.868749][ T267] Possible interrupt unsafe locking scenario: [ 15.868749][ T267] [ 15.868749][ T267] CPU0 CPU1 [ 15.868750][ T267] ---- ---- [ 15.868750][ T267] lock(fs_reclaim); [ 15.868751][ T267] local_irq_disable(); [ 15.868752][ T267] lock(slock-AF_INET/2); [ 15.868753][ T267] lock(console_owner); [ 15.868754][ T267] [ 15.868754][ T267] lock(slock-AF_INET/2); [ 15.868755][ T267] [ 15.868755][ T267] *** DEADLOCK *** [ 15.868755][ T267] [ 15.868756][ T267] locks held by ping/267: 14, last CPU#3: [ 15.868757][ T267] #0: ff1100000588e290 (sk_lock-AF_INET){+.+.}-{0:0}, at: raw_sendmsg+0xba5/0x1cc0 [ 15.868760][ T267] #1: ffffffffba590b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 15.868763][ T267] #2: ffffffffba590b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 15.868766][ T267] #3: ffffffffba590aa0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x1fc/0x1b80 [ 15.868769][ T267] #4: ffd1ffffffd85fb0 (&xmit_recursion->bh_lock){+.-.}-{3:3}, at: ppp_xmit_process+0x1d2/0x8e0 [ppp_generic] [ 15.868773][ T267] #5: ff1100000f522fd0 (&ppp->wlock){+.-.}-{3:3}, at: ppp_xmit_process+0x292/0x8e0 [ppp_generic] [ 15.868776][ T267] #6: ff1100000986cdd0 (&pch->downl){+.-.}-{3:3}, at: ppp_push+0xae/0x2d0 [ppp_generic] [ 15.868779][ T267] #7: ff11000005895998 (slock-AF_INET/2){+.-.}-{3:3}, at: l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.868784][ T267] #8: ffffffffba590b00 (rcu_read_lock){....}-{1:3}, at: __ip_queue_xmit+0x63/0x1650 [ 15.868787][ T267] #9: ffffffffba590b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 15.868790][ T267] #10: ffffffffba590b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 15.868793][ T267] #11: ffffffffba590aa0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x1fc/0x1b80 [ 15.868796][ T267] #12: ffffffffba579cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 15.868799][ T267] #13: ffffffffba579d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 15.868802][ T267] [ 15.868802][ T267] the dependencies between SOFTIRQ-irq-safe lock and the holding lock: [ 15.868803][ T267] -> (slock-AF_INET/2){+.-.}-{3:3} { [ 15.868805][ T267] HARDIRQ-ON-W at: [ 15.868806][ T267] __lock_acquire+0x388/0xc20 [ 15.868808][ T267] lock_acquire.part.0+0xd4/0x280 [ 15.868809][ T267] _raw_spin_lock_nested+0x3d/0x50 [ 15.868810][ T267] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.868813][ T267] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.868816][ T267] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.868817][ T267] __ppp_channel_push+0xed/0x280 [ppp_generic] [ 15.868818][ T267] ppp_channel_push+0x269/0x7b0 [ppp_generic] [ 15.868820][ T267] ppp_write+0x26b/0x350 [ppp_generic] [ 15.868822][ T267] vfs_write+0x1a4/0xbd0 [ 15.868825][ T267] ksys_write+0x116/0x250 [ 15.868827][ T267] do_syscall_64+0xff/0x530 [ 15.868828][ T267] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.868829][ T267] IN-SOFTIRQ-W at: [ 15.868830][ T267] __lock_acquire+0x388/0xc20 [ 15.868831][ T267] lock_acquire.part.0+0xd4/0x280 [ 15.868833][ T267] _raw_spin_lock_nested+0x3d/0x50 [ 15.868834][ T267] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.868836][ T267] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.868839][ T267] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.868840][ T267] ppp_push+0x165/0x2d0 [ppp_generic] [ 15.868842][ T267] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 15.868844][ T267] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.868845][ T267] dev_hard_start_xmit+0xf4/0x620 [ 15.868847][ T267] __dev_queue_xmit+0xc0a/0x1b80 [ 15.868849][ T267] ip_finish_output2+0x5b9/0x1bc0 [ 15.868850][ T267] ip_output+0x1ca/0x660 [ 15.868851][ T267] ip_push_pending_frames+0xf6/0x250 [ 15.868853][ T267] icmp_reply+0x94d/0xa50 [ 15.868854][ T267] icmp_echo+0x2ab/0x4b0 [ 15.868856][ T267] icmp_rcv+0x92f/0xfb0 [ 15.868857][ T267] ip_protocol_deliver_rcu+0x82/0x350 [ 15.868859][ T267] ip_local_deliver_finish+0x36f/0x610 [ 15.868860][ T267] ip_local_deliver+0x184/0x4c0 [ 15.868861][ T267] ip_rcv+0xdc/0x3d0 [ 15.868863][ T267] __netif_receive_skb_one_core+0xfc/0x180 [ 15.868864][ T267] process_backlog+0x431/0x14c0 [ 15.868866][ T267] __napi_poll+0xa7/0x3b0 [ 15.868867][ T267] net_rx_action+0x513/0xf50 [ 15.868869][ T267] handle_softirqs+0x1d3/0x900 [ 15.868870][ T267] do_softirq+0xac/0xe0 [ 15.868871][ T267] __local_bh_enable_ip+0x118/0x150 [ 15.868872][ T267] __dev_queue_xmit+0x979/0x1b80 [ 15.868874][ T267] ip_finish_output2+0x5b9/0x1bc0 [ 15.868875][ T267] ip_output+0x1ca/0x660 [ 15.868877][ T267] ip_push_pending_frames+0xf6/0x250 [ 15.868878][ T267] raw_sendmsg+0x1169/0x1cc0 [ 15.868880][ T267] __sys_sendto+0x2aa/0x400 [ 15.868881][ T267] __x64_sys_sendto+0xe4/0x1f0 [ 15.868883][ T267] do_syscall_64+0xff/0x530 [ 15.868884][ T267] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.868885][ T267] INITIAL USE at: [ 15.868886][ T267] __lock_acquire+0x388/0xc20 [ 15.868887][ T267] lock_acquire.part.0+0xd4/0x280 [ 15.868888][ T267] _raw_spin_lock_nested+0x3d/0x50 [ 15.868889][ T267] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.868892][ T267] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.868895][ T267] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.868896][ T267] __ppp_channel_push+0xed/0x280 [ppp_generic] [ 15.868898][ T267] ppp_channel_push+0x269/0x7b0 [ppp_generic] [ 15.868899][ T267] ppp_write+0x26b/0x350 [ppp_generic] [ 15.868901][ T267] vfs_write+0x1a4/0xbd0 [ 15.868902][ T267] ksys_write+0x116/0x250 [ 15.868904][ T267] do_syscall_64+0xff/0x530 [ 15.868905][ T267] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.868906][ T267] } [ 15.868907][ T267] ... key at: [] af_family_slock_keys+0x22/0x300 [ 15.868910][ T267] [ 15.868910][ T267] the dependencies between the lock to be acquired [ 15.868910][ T267] and SOFTIRQ-irq-unsafe lock: [ 15.868916][ T267] -> (fs_reclaim){+.+.}-{0:0} { [ 15.868917][ T267] HARDIRQ-ON-W at: [ 15.868918][ T267] __lock_acquire+0x388/0xc20 [ 15.868920][ T267] lock_acquire.part.0+0xd4/0x280 [ 15.868921][ T267] fs_reclaim_acquire+0xd5/0x120 [ 15.868922][ T267] __kmalloc_cache_noprof+0x6e/0x620 [ 15.868923][ T267] kthread_create_worker_on_node+0xea/0x210 [ 15.868925][ T267] workqueue_init+0x2a/0x680 [ 15.868926][ T267] kernel_init_freeable+0x2fe/0x630 [ 15.868927][ T267] kernel_init+0x21/0x150 [ 15.868929][ T267] ret_from_fork+0x474/0x6b0 [ 15.868930][ T267] ret_from_fork_asm+0x11/0x20 [ 15.868931][ T267] SOFTIRQ-ON-W at: [ 15.868932][ T267] __lock_acquire+0x388/0xc20 [ 15.868933][ T267] lock_acquire.part.0+0xd4/0x280 [ 15.868934][ T267] fs_reclaim_acquire+0xd5/0x120 [ 15.868935][ T267] __kmalloc_cache_noprof+0x6e/0x620 [ 15.868936][ T267] kthread_create_worker_on_node+0xea/0x210 [ 15.868938][ T267] workqueue_init+0x2a/0x680 [ 15.868939][ T267] kernel_init_freeable+0x2fe/0x630 [ 15.868940][ T267] kernel_init+0x21/0x150 [ 15.868942][ T267] ret_from_fork+0x474/0x6b0 [ 15.868943][ T267] ret_from_fork_asm+0x11/0x20 [ 15.868944][ T267] INITIAL USE at: [ 15.868945][ T267] __lock_acquire+0x388/0xc20 [ 15.868946][ T267] lock_acquire.part.0+0xd4/0x280 [ 15.868947][ T267] fs_reclaim_acquire+0xd5/0x120 [ 15.868948][ T267] __kmalloc_cache_noprof+0x6e/0x620 [ 15.868950][ T267] kthread_create_worker_on_node+0xea/0x210 [ 15.868951][ T267] workqueue_init+0x2a/0x680 [ 15.868952][ T267] kernel_init_freeable+0x2fe/0x630 [ 15.868953][ T267] kernel_init+0x21/0x150 [ 15.868955][ T267] ret_from_fork+0x474/0x6b0 [ 15.868956][ T267] ret_from_fork_asm+0x11/0x20 [ 15.868957][ T267] } [ 15.868957][ T267] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 15.868961][ T267] ... acquired at: [ 15.868961][ T267] __lock_acquire+0x518/0xc20 [ 15.868963][ T267] lock_acquire.part.0+0xd4/0x280 [ 15.868964][ T267] fs_reclaim_acquire+0xd5/0x120 [ 15.868965][ T267] __kmalloc_noprof+0xd3/0x760 [ 15.868966][ T267] alloc_buf.isra.0+0x4b/0x260 [ 15.868969][ T267] put_chars+0x1e1/0x2f0 [ 15.868971][ T267] hvc_console_print+0x292/0x780 [ 15.868973][ T267] console_emit_next_record+0x22f/0x4c0 [ 15.868975][ T267] console_flush_one_record+0x46f/0x710 [ 15.868976][ T267] console_unlock+0xee/0x1f0 [ 15.868978][ T267] vprintk_emit+0x37c/0x3e0 [ 15.868979][ T267] _printk+0xc7/0x100 [ 15.868982][ T267] dm_interface_init+0x50/0x60 [ 15.868985][ T267] dm_init+0x51/0xd0 [ 15.868986][ T267] do_one_initcall+0x124/0x4f0 [ 15.868988][ T267] kernel_init_freeable+0x596/0x630 [ 15.868990][ T267] kernel_init+0x21/0x150 [ 15.868991][ T267] ret_from_fork+0x474/0x6b0 [ 15.868992][ T267] ret_from_fork_asm+0x11/0x20 [ 15.868993][ T267] [ 15.868993][ T267] -> (console_owner){....}-{0:0} { [ 15.868995][ T267] INITIAL USE at: [ 15.868996][ T267] } [ 15.868996][ T267] ... key at: [] console_owner_dep_map+0x0/0x60 [ 15.869000][ T267] ... acquired at: [ 15.869000][ T267] check_prev_add+0xef/0xe90 [ 15.869001][ T267] validate_chain+0x6b8/0xc20 [ 15.869003][ T267] __lock_acquire+0x518/0xc20 [ 15.869004][ T267] lock_acquire.part.0+0xd4/0x280 [ 15.869005][ T267] console_lock_spinning_enable+0x5c/0x60 [ 15.869007][ T267] console_emit_next_record+0x1d1/0x4c0 [ 15.869008][ T267] console_flush_one_record+0x46f/0x710 [ 15.869010][ T267] console_unlock+0xee/0x1f0 [ 15.869011][ T267] vprintk_emit+0x37c/0x3e0 [ 15.869013][ T267] _printk+0xc7/0x100 [ 15.869014][ T267] netdev_err+0xe2/0x120 [ 15.869017][ T267] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 15.869019][ T267] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.869020][ T267] dev_hard_start_xmit+0xf4/0x620 [ 15.869022][ T267] __dev_queue_xmit+0xc0a/0x1b80 [ 15.869024][ T267] ip_finish_output2+0x5b9/0x1bc0 [ 15.869025][ T267] ip_output+0x1ca/0x660 [ 15.869026][ T267] __ip_queue_xmit+0x774/0x1650 [ 15.869028][ T267] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 15.869031][ T267] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.869033][ T267] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.869034][ T267] ppp_push+0x165/0x2d0 [ppp_generic] [ 15.869036][ T267] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 15.869038][ T267] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.869039][ T267] dev_hard_start_xmit+0xf4/0x620 [ 15.869041][ T267] __dev_queue_xmit+0xc0a/0x1b80 [ 15.869042][ T267] ip_finish_output2+0x5b9/0x1bc0 [ 15.869044][ T267] ip_output+0x1ca/0x660 [ 15.869045][ T267] ip_push_pending_frames+0xf6/0x250 [ 15.869047][ T267] raw_sendmsg+0x1169/0x1cc0 [ 15.869048][ T267] __sys_sendto+0x2aa/0x400 [ 15.869049][ T267] __x64_sys_sendto+0xe4/0x1f0 [ 15.869051][ T267] do_syscall_64+0xff/0x530 [ 15.869052][ T267] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.869053][ T267] [ 15.869054][ T267] [ 15.869054][ T267] stack backtrace: [ 15.869057][ T267] CPU: 3 UID: 0 PID: 267 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.869060][ T267] Tainted: [W]=WARN [ 15.869061][ T267] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.869063][ T267] Call Trace: [ 15.869064][ T267] [ 15.869065][ T267] dump_stack_lvl+0x6f/0xa0 [ 15.869069][ T267] print_bad_irq_dependency+0x3e4/0x410 [ 15.869072][ T267] check_irq_usage+0x403/0x570 [ 15.869074][ T267] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.869078][ T267] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.869080][ T267] ? check_path.constprop.0+0x24/0x50 [ 15.869083][ T267] ? check_prev_add+0xef/0xe90 [ 15.869085][ T267] check_prev_add+0xef/0xe90 [ 15.869086][ T267] validate_chain+0x6b8/0xc20 [ 15.869089][ T267] __lock_acquire+0x518/0xc20 [ 15.869091][ T267] lock_acquire.part.0+0xd4/0x280 [ 15.869092][ T267] ? console_lock_spinning_enable+0x40/0x60 [ 15.869094][ T267] ? rcu_is_watching+0x16/0xd0 [ 15.869096][ T267] ? lock_acquire+0x13c/0x160 [ 15.869098][ T267] console_lock_spinning_enable+0x5c/0x60 [ 15.869100][ T267] ? console_lock_spinning_enable+0x40/0x60 [ 15.869102][ T267] console_emit_next_record+0x1d1/0x4c0 [ 15.869104][ T267] ? devkmsg_read+0x4b0/0x4b0 [ 15.869105][ T267] ? console_flush_one_record+0x106/0x710 [ 15.869107][ T267] ? rcu_is_watching+0x16/0xd0 [ 15.869109][ T267] ? lock_acquire+0x13c/0x160 [ 15.869111][ T267] console_flush_one_record+0x46f/0x710 [ 15.869113][ T267] ? console_emit_next_record+0x4c0/0x4c0 [ 15.869115][ T267] ? __lock_acquire+0x518/0xc20 [ 15.869117][ T267] console_unlock+0xee/0x1f0 [ 15.869119][ T267] ? console_flush_one_record+0x710/0x710 [ 15.869121][ T267] ? rcu_is_watching+0x16/0xd0 [ 15.869122][ T267] ? lock_acquire+0x60/0x160 [ 15.869124][ T267] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.869126][ T267] ? vprintk_emit+0x320/0x3e0 [ 15.869128][ T267] vprintk_emit+0x37c/0x3e0 [ 15.869130][ T267] ? wake_up_klogd_work_func+0x90/0x90 [ 15.869132][ T267] ? mark_lock+0x3bb/0xa00 [ 15.869134][ T267] _printk+0xc7/0x100 [ 15.869135][ T267] ? snapshot_read.cold+0x21/0x21 [ 15.869137][ T267] ? lock_acquire.part.0+0xd4/0x280 [ 15.869139][ T267] ? find_held_lock+0x2b/0x80 [ 15.869140][ T267] ? netdev_name+0x43/0x60 [ 15.869141][ T267] ? __netdev_printk+0x17f/0x1d0 [ 15.869143][ T267] netdev_err+0xe2/0x120 [ 15.869145][ T267] ? netdev_crit+0x120/0x120 [ 15.869146][ T267] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 15.869148][ T267] ? ___ratelimit+0x4e7/0xa00 [ 15.869151][ T267] ? idr_get_free+0x830/0x830 [ 15.869153][ T267] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 15.869155][ T267] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 15.869157][ T267] ? dst_release+0x54/0x240 [ 15.869160][ T267] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.869162][ T267] ? dev_nit_active_rcu+0x1f/0xf0 [ 15.869165][ T267] ? rcu_is_watching+0x16/0xd0 [ 15.869166][ T267] dev_hard_start_xmit+0xf4/0x620 [ 15.869169][ T267] ? rcu_is_watching+0x16/0xd0 [ 15.869171][ T267] __dev_queue_xmit+0xc0a/0x1b80 [ 15.869172][ T267] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 15.869174][ T267] ? lockdep_hardirqs_on+0x91/0x130 [ 15.869175][ T267] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 15.869177][ T267] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 15.869178][ T267] ? ref_tracker_dir_print+0x100/0x100 [ 15.869182][ T267] ? dst_init+0x84/0x530 [ 15.869183][ T267] ? dst_alloc+0x97/0x160 [ 15.869184][ T267] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 15.869186][ T267] ? ip_route_output_flow+0x21/0x150 [ 15.869187][ T267] ? __ip_queue_xmit+0xdc7/0x1650 [ 15.869189][ T267] ? __lock_acquire+0x518/0xc20 [ 15.869190][ T267] ? down_read_nested+0xe7/0x500 [ 15.869192][ T267] ? lock_acquire.part.0+0xd4/0x280 [ 15.869193][ T267] ? ip_finish_output2+0x25c/0x1bc0 [ 15.869195][ T267] ? rcu_is_watching+0x16/0xd0 [ 15.869198][ T267] ip_finish_output2+0x5b9/0x1bc0 [ 15.869199][ T267] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 15.869201][ T267] ? lock_acquire.part.0+0xd4/0x280 [ 15.869203][ T267] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 15.869205][ T267] ip_output+0x1ca/0x660 [ 15.869207][ T267] ? ip_finish_output+0x460/0x460 [ 15.869208][ T267] ? find_held_lock+0x2b/0x80 [ 15.869210][ T267] ? __lock_release.isra.0+0x69/0x1a0 [ 15.869212][ T267] ? ip4_dst_hoplimit+0x15b/0x320 [ 15.869214][ T267] __ip_queue_xmit+0x774/0x1650 [ 15.869216][ T267] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 15.869220][ T267] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.869224][ T267] ? l2tp_xmit_core.constprop.0+0x19b0/0x19b0 [l2tp_core] [ 15.869227][ T267] ? do_raw_spin_lock+0x131/0x280 [ 15.869229][ T267] ? pppol2tp_xmit+0x423/0x690 [l2tp_ppp] [ 15.869230][ T267] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.869232][ T267] ppp_push+0x165/0x2d0 [ppp_generic] [ 15.869235][ T267] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 15.869237][ T267] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 15.869239][ T267] ? dst_release+0x54/0x240 [ 15.869240][ T267] ? down_read_nested+0x369/0x500 [ 15.869242][ T267] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.869244][ T267] ? dev_nit_active_rcu+0x1f/0xf0 [ 15.869246][ T267] ? rcu_is_watching+0x16/0xd0 [ 15.869247][ T267] dev_hard_start_xmit+0xf4/0x620 [ 15.869250][ T267] ? rcu_is_watching+0x16/0xd0 [ 15.869252][ T267] __dev_queue_xmit+0xc0a/0x1b80 [ 15.869254][ T267] ? sock_queue_err_skb+0x680/0x680 [ 15.869256][ T267] ? kmem_cache_alloc_node_noprof+0x2a6/0x660 [ 15.869258][ T267] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.869260][ T267] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.869261][ T267] ? validate_chain+0x38b/0xc20 [ 15.869263][ T267] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 15.869264][ T267] ? __lock_acquire+0x518/0xc20 [ 15.869266][ T267] ? down_read_nested+0xe7/0x500 [ 15.869267][ T267] ? lock_acquire.part.0+0xd4/0x280 [ 15.869269][ T267] ? ip_finish_output2+0x25c/0x1bc0 [ 15.869271][ T267] ? rcu_is_watching+0x16/0xd0 [ 15.869273][ T267] ip_finish_output2+0x5b9/0x1bc0 [ 15.869275][ T267] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 15.869276][ T267] ? lock_acquire.part.0+0xd4/0x280 [ 15.869278][ T267] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 15.869280][ T267] ? rcu_is_watching+0x16/0xd0 [ 15.869282][ T267] ip_output+0x1ca/0x660 [ 15.869284][ T267] ? ip_finish_output+0x460/0x460 [ 15.869285][ T267] ? __ip_make_skb+0x106a/0x2300 [ 15.869288][ T267] ip_push_pending_frames+0xf6/0x250 [ 15.869290][ T267] raw_sendmsg+0x1169/0x1cc0 [ 15.869292][ T267] ? raw_send_hdrinc+0x1740/0x1740 [ 15.869294][ T267] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.869297][ T267] ? __might_fault+0x97/0x140 [ 15.869301][ T267] ? __might_fault+0x97/0x140 [ 15.869302][ T267] ? __might_fault+0x97/0x140 [ 15.869304][ T267] __sys_sendto+0x2aa/0x400 [ 15.869306][ T267] ? __ia32_sys_getpeername+0xd0/0xd0 [ 15.869310][ T267] ? exc_page_fault+0x87/0x100 [ 15.869312][ T267] __x64_sys_sendto+0xe4/0x1f0 [ 15.869314][ T267] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 15.869316][ T267] ? lockdep_hardirqs_on+0x91/0x130 [ 15.869317][ T267] ? do_syscall_64+0xa6/0x530 [ 15.869319][ T267] do_syscall_64+0xff/0x530 [ 15.869320][ T267] ? exc_page_fault+0xee/0x100 [ 15.869322][ T267] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.869323][ T267] RIP: 0033:0x7f06950a954e [ 15.869326][ T267] 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 [ 15.869328][ T267] RSP: 002b:00007ffe11abf3d0 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 15.869330][ T267] RAX: ffffffffffffffda RBX: 0000000000000040 RCX: 00007f06950a954e [ 15.869332][ T267] RDX: 0000000000000040 RSI: 00005645621620a4 RDI: 0000000000000005 [ 15.869333][ T267] RBP: 00007ffe11abf3e0 R08: 0000564562174320 R09: 0000000000000010 [ 15.869333][ T267] R10: 0000000000000000 R11: 0000000000000202 R12: 431bde82d7b634db [ 15.869334][ T267] R13: 0000000000000000 R14: 00005645621753e0 R15: 00005645621620a0 [ 15.869337][ T267] [ 16.875131][ T267] ping (267) used greatest stack depth: 22536 bytes left [ 16.875148][ T267] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 16.875151][ T267] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 267, name: ping [ 16.875152][ T267] preempt_count: 2, expected: 0 [ 16.875153][ T267] RCU nest depth: 0, expected: 0 [ 16.875154][ T267] INFO: lockdep is turned off. [ 16.875155][ T267] irq event stamp: 27623 [ 16.875156][ T267] hardirqs last enabled at (27622): [] __down_trylock_console_sem+0x86/0xa0 [ 16.875165][ T267] hardirqs last disabled at (27623): [] console_emit_next_record+0x3d4/0x4c0 [ 16.875168][ T267] softirqs last enabled at (27522): [] __alloc_skb+0x4c2/0x5f0 [ 16.875172][ T267] softirqs last disabled at (27532): [] __dev_queue_xmit+0x1fc/0x1b80 [ 16.875175][ T267] Preemption disabled at: [ 16.875176][ T267] [<0000000000000000>] 0x0 [ 16.875182][ T267] CPU: 3 UID: 0 PID: 267 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 16.875186][ T267] Tainted: [W]=WARN [ 16.875186][ T267] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.875188][ T267] Call Trace: [ 16.875190][ T267] [ 16.875191][ T267] dump_stack_lvl+0x6f/0xa0 [ 16.875196][ T267] __might_resched.cold+0x1fe/0x2c1 [ 16.875200][ T267] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 16.875203][ T267] ? __asan_memcpy+0x3c/0x60 [ 16.875205][ T267] ? rcu_is_watching+0x16/0xd0 [ 16.875209][ T267] __kmalloc_noprof+0x443/0x760 [ 16.875212][ T267] ? __rwlock_init+0x150/0x150 [ 16.875215][ T267] ? alloc_buf.isra.0+0x4b/0x260 [ 16.875218][ T267] ? do_raw_spin_unlock+0x59/0x250 [ 16.875220][ T267] alloc_buf.isra.0+0x4b/0x260 [ 16.875222][ T267] put_chars+0x1e1/0x2f0 [ 16.875224][ T267] ? __send_to_port+0x420/0x420 [ 16.875226][ T267] ? console_prepend_replay+0x20/0x20 [ 16.875228][ T267] hvc_console_print+0x292/0x780 [ 16.875231][ T267] ? hvc_write+0x3a0/0x3a0 [ 16.875233][ T267] ? rcu_is_watching+0x16/0xd0 [ 16.875235][ T267] ? lock_acquire+0x13c/0x160 [ 16.875237][ T267] console_emit_next_record+0x22f/0x4c0 [ 16.875239][ T267] ? devkmsg_read+0x4b0/0x4b0 [ 16.875241][ T267] ? rcu_is_watching+0x16/0xd0 [ 16.875243][ T267] ? rcu_is_watching+0x16/0xd0 [ 16.875244][ T267] ? rcu_is_watching+0x16/0xd0 [ 16.875246][ T267] ? lock_acquire+0x13c/0x160 [ 16.875248][ T267] ? console_flush_one_record+0x111/0x710 [ 16.875249][ T267] console_flush_one_record+0x46f/0x710 [ 16.875251][ T267] ? console_emit_next_record+0x4c0/0x4c0 [ 16.875254][ T267] console_unlock+0xee/0x1f0 [ 16.875255][ T267] ? lock_acquire+0x13c/0x160 [ 16.875257][ T267] ? console_flush_one_record+0x710/0x710 [ 16.875258][ T267] ? rcu_is_watching+0x16/0xd0 [ 16.875260][ T267] ? lock_release+0x184/0x1f0 [ 16.875261][ T267] ? lock_acquire+0x60/0x160 [ 16.875263][ T267] ? __rcu_read_lock+0x30/0x70 [ 16.875265][ T267] ? __down_trylock_console_sem+0x5e/0xa0 [ 16.875266][ T267] ? vprintk_emit+0x320/0x3e0 [ 16.875268][ T267] vprintk_emit+0x37c/0x3e0 [ 16.875270][ T267] ? wake_up_klogd_work_func+0x90/0x90 [ 16.875272][ T267] ? do_raw_read_unlock+0x70/0x70 [ 16.875274][ T267] ? forget_original_parent+0x5c/0xdb0 [ 16.875278][ T267] _printk+0xc7/0x100 [ 16.875280][ T267] ? snapshot_read.cold+0x21/0x21 [ 16.875282][ T267] ? do_raw_spin_lock+0x131/0x280 [ 16.875283][ T267] ? do_raw_spin_lock+0x131/0x280 [ 16.875285][ T267] ? __rwlock_init+0x150/0x150 [ 16.875287][ T267] ? do_raw_spin_lock+0x131/0x280 [ 16.875289][ T267] do_exit.cold+0x82/0x9c [ 16.875292][ T267] ? exit_notify+0x890/0x890 [ 16.875293][ T267] ? posixtimer_sig_unignore.isra.0+0x5f0/0x5f0 [ 16.875296][ T267] ? rcu_is_watching+0x16/0xd0 [ 16.875298][ T267] do_group_exit+0xb8/0x370 [ 16.875299][ T267] __x64_sys_exit_group+0x3c/0x50 [ 16.875301][ T267] x64_sys_call+0x1567/0x1570 [ 16.875303][ T267] do_syscall_64+0xff/0x530 [ 16.875306][ T267] ? exc_page_fault+0xee/0x100 [ 16.875308][ T267] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 16.875311][ T267] RIP: 0033:0x7f06950fa1b8 [ 16.875313][ T267] Code: Unable to access opcode bytes at 0x7f06950fa18e. [ 16.875314][ T267] RSP: 002b:00007ffe11ac0b68 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 16.875317][ T267] RAX: ffffffffffffffda RBX: 00007f069522af88 RCX: 00007f06950fa1b8 [ 16.875319][ T267] RDX: 00007f0694e8b7c8 RSI: ffffffffffffffb0 RDI: 0000000000000001 [ 16.875319][ T267] RBP: 00007ffe11ac0bc0 R08: 0000000000000000 R09: 0000000000001010 [ 16.875320][ T267] R10: 00007ffe11ac09e0 R11: 0000000000000246 R12: 0000000000000002 [ 16.875321][ T267] R13: 0000000000000001 R14: 00007f0695229680 R15: 00007f069522afa0 [ 16.875324][ T267] [ 16.916316][ T221] ppp0: recursion detected