[ 10.305925][ T179] PPP generic driver version 2.4.2 [ 10.305944][ T179] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.305946][ T179] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 179, name: modprobe [ 10.305948][ T179] preempt_count: 1, expected: 0 [ 10.305948][ T179] RCU nest depth: 0, expected: 0 [ 10.305949][ T179] locks held by modprobe/179: 4, last CPU#2: [ 10.305952][ T179] #0: ffffffff86779cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.305965][ T179] #1: ffffffff86779d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.305970][ T179] #2: ffffffff86699660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.305974][ T179] #3: ffffffff86699560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.305979][ T179] irq event stamp: 34256 [ 10.305979][ T179] hardirqs last enabled at (34255): [] __down_trylock_console_sem+0x86/0xa0 [ 10.305982][ T179] hardirqs last disabled at (34256): [] console_emit_next_record+0x3d4/0x4c0 [ 10.305984][ T179] softirqs last enabled at (32578): [] handle_softirqs+0x67c/0x900 [ 10.305988][ T179] softirqs last disabled at (31371): [] __irq_exit_rcu+0x145/0x1c0 [ 10.305990][ T179] Preemption disabled at: [ 10.305991][ T179] [] vprintk_emit+0x31b/0x3e0 [ 10.305997][ T179] CPU: 2 UID: 0 PID: 179 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.306001][ T179] Tainted: [W]=WARN [ 10.306002][ T179] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.306003][ T179] Call Trace: [ 10.306005][ T179] [ 10.306007][ T179] dump_stack_lvl+0x6f/0xa0 [ 10.306012][ T179] ? vprintk_emit+0x31b/0x3e0 [ 10.306014][ T179] __might_resched.cold+0x1fe/0x2c1 [ 10.306019][ T179] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.306024][ T179] ? __kmalloc_noprof+0xdb/0x760 [ 10.306029][ T179] __kmalloc_noprof+0x443/0x760 [ 10.306031][ T179] ? alloc_buf.isra.0+0x4b/0x260 [ 10.306038][ T179] ? do_raw_spin_unlock+0x59/0x250 [ 10.306041][ T179] alloc_buf.isra.0+0x4b/0x260 [ 10.306044][ T179] put_chars+0x1e1/0x2f0 [ 10.306047][ T179] ? __send_to_port+0x420/0x420 [ 10.306049][ T179] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.306056][ T179] ? rcu_read_lock_any_held+0x3c/0x90 [ 10.306060][ T179] ? validate_chain+0x38b/0xc20 [ 10.306066][ T179] hvc_console_print+0x292/0x780 [ 10.306069][ T179] ? __lock_acquire+0x518/0xc20 [ 10.306074][ T179] ? hvc_write+0x3a0/0x3a0 [ 10.306077][ T179] ? rcu_is_watching+0x16/0xd0 [ 10.306083][ T179] console_emit_next_record+0x22f/0x4c0 [ 10.306087][ T179] ? devkmsg_read+0x4b0/0x4b0 [ 10.306089][ T179] ? console_flush_one_record+0x106/0x710 [ 10.306093][ T179] ? rcu_is_watching+0x16/0xd0 [ 10.306095][ T179] ? lock_acquire+0x13c/0x160 [ 10.306100][ T179] console_flush_one_record+0x46f/0x710 [ 10.306104][ T179] ? console_emit_next_record+0x4c0/0x4c0 [ 10.306106][ T179] ? __lock_acquire+0x518/0xc20 [ 10.306112][ T179] console_unlock+0xee/0x1f0 [ 10.306115][ T179] ? console_flush_one_record+0x710/0x710 [ 10.306117][ T179] ? rcu_is_watching+0x16/0xd0 [ 10.306120][ T179] ? lock_acquire+0x60/0x160 [ 10.306124][ T179] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.306126][ T179] ? vprintk_emit+0x320/0x3e0 [ 10.306129][ T179] vprintk_emit+0x37c/0x3e0 [ 10.306134][ T179] ? wake_up_klogd_work_func+0x90/0x90 [ 10.306140][ T179] ? ppp_cleanup+0x920/0x920 [ppp_generic] [ 10.306144][ T179] _printk+0xc7/0x100 [ 10.306148][ T179] ? snapshot_read.cold+0x21/0x21 [ 10.306155][ T179] ? do_one_initcall+0x113/0x4f0 [ 10.306159][ T179] ppp_init+0x16/0x1000 [ppp_generic] [ 10.306162][ T179] do_one_initcall+0x124/0x4f0 [ 10.306165][ T179] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 10.306170][ T179] ? do_init_module+0xae/0x8a0 [ 10.306174][ T179] ? kasan_unpoison+0x44/0x70 [ 10.306179][ T179] do_init_module+0x2ad/0x8a0 [ 10.306183][ T179] ? lockdep_hardirqs_on+0x91/0x130 [ 10.306187][ T179] ? free_module+0x470/0x470 [ 10.306190][ T179] ? kfree+0x123/0x590 [ 10.306195][ T179] load_module+0x1424/0x1bb0 [ 10.306203][ T179] ? layout_and_allocate.isra.0+0x680/0x680 [ 10.306208][ T179] ? backing_file_read_iter+0x541/0x790 [ 10.306212][ T179] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.306216][ T179] init_module_from_file+0x11f/0x1a0 [ 10.306219][ T179] ? __do_sys_init_module+0x220/0x220 [ 10.306228][ T179] ? __rwlock_init+0x150/0x150 [ 10.306232][ T179] ? do_raw_spin_unlock+0x59/0x250 [ 10.306235][ T179] idempotent_init_module+0x37b/0x7c0 [ 10.306239][ T179] ? init_module_from_file+0x1a0/0x1a0 [ 10.306246][ T179] ? rcu_is_watching+0x16/0xd0 [ 10.306248][ T179] ? cap_capable+0x1d7/0x3d0 [ 10.306255][ T179] __x64_sys_finit_module+0xdf/0x190 [ 10.306257][ T179] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 10.306261][ T179] do_syscall_64+0xff/0x530 [ 10.306264][ T179] ? exc_page_fault+0xee/0x100 [ 10.306267][ T179] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.306269][ T179] RIP: 0033:0x7fb82de2337d [ 10.306273][ T179] 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.306275][ T179] RSP: 002b:00007ffd62a5e8e8 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 10.306278][ T179] RAX: ffffffffffffffda RBX: 000055f2262501d0 RCX: 00007fb82de2337d [ 10.306279][ T179] RDX: 0000000000000000 RSI: 000055f210a3effd RDI: 0000000000000006 [ 10.306280][ T179] RBP: 00007ffd62a5e980 R08: 0000000000000000 R09: 0000000000000000 [ 10.306281][ T179] R10: 0000000000000000 R11: 0000000000000246 R12: 000055f210a3effd [ 10.306282][ T179] R13: 000055f226250180 R14: 0000000000040000 R15: 0000000000000000 [ 10.306289][ T179] [ 10.350670][ T179] NET: Registered PF_PPPOX protocol family [ 10.374118][ T179] l2tp_core: L2TP core driver, V2.0 [ 10.380046][ T179] l2tp_ppp: PPPoL2TP kernel driver, V2.0 [ 15.621515][ T262] iperf3 (262) used greatest stack depth: 22976 bytes left [ 15.621539][ T262] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 15.621542][ T262] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 262, name: iperf3 [ 15.621545][ T262] preempt_count: 2, expected: 0 [ 15.621546][ T262] RCU nest depth: 0, expected: 0 [ 15.621547][ T262] locks held by iperf3/262: 5, last CPU#0: [ 15.621550][ T262] #0: ffffffff866127b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 15.621565][ T262] #1: ffffffff86779cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 15.621574][ T262] #2: ffffffff86779d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 15.621580][ T262] #3: ffffffff86699660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 15.621586][ T262] #4: ffffffff86699560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 15.621592][ T262] irq event stamp: 1631266 [ 15.621593][ T262] hardirqs last enabled at (1631265): [] __down_trylock_console_sem+0x86/0xa0 [ 15.621597][ T262] hardirqs last disabled at (1631266): [] console_emit_next_record+0x3d4/0x4c0 [ 15.621600][ T262] softirqs last enabled at (1631164): [] tcp_sendmsg+0x39/0x50 [ 15.621605][ T262] softirqs last disabled at (1631162): [] __release_sock+0x205/0x3a0 [ 15.621609][ T262] Preemption disabled at: [ 15.621610][ T262] [<0000000000000000>] 0x0 [ 15.621619][ T262] CPU: 0 UID: 0 PID: 262 Comm: iperf3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.621624][ T262] Tainted: [W]=WARN [ 15.621625][ T262] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.621628][ T262] Call Trace: [ 15.621630][ T262] [ 15.621632][ T262] dump_stack_lvl+0x6f/0xa0 [ 15.621640][ T262] __might_resched.cold+0x1fe/0x2c1 [ 15.621646][ T262] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 15.621652][ T262] ? __kmalloc_noprof+0xdb/0x760 [ 15.621659][ T262] __kmalloc_noprof+0x443/0x760 [ 15.621663][ T262] ? alloc_buf.isra.0+0x4b/0x260 [ 15.621673][ T262] ? do_raw_spin_unlock+0x59/0x250 [ 15.621678][ T262] alloc_buf.isra.0+0x4b/0x260 [ 15.621683][ T262] put_chars+0x1e1/0x2f0 [ 15.621686][ T262] ? prb_final_commit+0x50/0x50 [ 15.621690][ T262] ? __send_to_port+0x420/0x420 [ 15.621695][ T262] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.621701][ T262] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.621705][ T262] ? validate_chain+0x38b/0xc20 [ 15.621711][ T262] hvc_console_print+0x292/0x780 [ 15.621716][ T262] ? mark_usage+0x61/0x170 [ 15.621718][ T262] ? __lock_acquire+0x518/0xc20 [ 15.621721][ T262] ? __lock_acquire+0x518/0xc20 [ 15.621727][ T262] ? hvc_write+0x3a0/0x3a0 [ 15.621731][ T262] ? console_emit_next_record+0x1df/0x4c0 [ 15.621737][ T262] ? rcu_is_watching+0x16/0xd0 [ 15.621740][ T262] ? lock_acquire+0x13c/0x160 [ 15.621747][ T262] console_emit_next_record+0x22f/0x4c0 [ 15.621754][ T262] ? devkmsg_read+0x4b0/0x4b0 [ 15.621757][ T262] ? console_flush_one_record+0x106/0x710 [ 15.621762][ T262] ? rcu_is_watching+0x16/0xd0 [ 15.621765][ T262] ? lock_acquire+0x13c/0x160 [ 15.621772][ T262] console_flush_one_record+0x46f/0x710 [ 15.621779][ T262] ? console_emit_next_record+0x4c0/0x4c0 [ 15.621782][ T262] ? __lock_acquire+0x518/0xc20 [ 15.621791][ T262] console_unlock+0xee/0x1f0 [ 15.621795][ T262] ? console_flush_one_record+0x710/0x710 [ 15.621798][ T262] ? rcu_is_watching+0x16/0xd0 [ 15.621802][ T262] ? lock_acquire+0x60/0x160 [ 15.621809][ T262] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.621812][ T262] ? vprintk_emit+0x320/0x3e0 [ 15.621818][ T262] vprintk_emit+0x37c/0x3e0 [ 15.621824][ T262] ? wake_up_klogd_work_func+0x90/0x90 [ 15.621830][ T262] ? __lock_acquire+0x518/0xc20 [ 15.621843][ T262] _printk+0xc7/0x100 [ 15.621849][ T262] ? snapshot_read.cold+0x21/0x21 [ 15.621853][ T262] ? do_raw_spin_lock+0x131/0x280 [ 15.621858][ T262] ? __rwlock_init+0x150/0x150 [ 15.621864][ T262] ? do_raw_spin_lock+0x131/0x280 [ 15.621869][ T262] do_exit.cold+0x82/0x9c [ 15.621875][ T262] ? exit_notify+0x890/0x890 [ 15.621879][ T262] ? __ia32_sys_sendfile+0x200/0x200 [ 15.621884][ T262] ? restore_fpregs_from_fpstate+0x44/0x130 [ 15.621893][ T262] __x64_sys_exit+0x42/0x50 [ 15.621896][ T262] x64_sys_call+0x156c/0x1570 [ 15.621900][ T262] do_syscall_64+0xff/0x530 [ 15.621905][ T262] ? exc_page_fault+0xee/0x100 [ 15.621909][ T262] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.621913][ T262] RIP: 0033:0x7fcd647c6c49 [ 15.621916][ T262] Code: Unable to access opcode bytes at 0x7fcd647c6c1f. [ 15.621918][ T262] RSP: 002b:00007fcd63f85e60 EFLAGS: 00000246 ORIG_RAX: 000000000000003c [ 15.621922][ T262] RAX: ffffffffffffffda RBX: 00007fcd63f866c0 RCX: 00007fcd647c6c49 [ 15.621924][ T262] RDX: 0000000000000000 RSI: 0000000000801000 RDI: 0000000000000000 [ 15.621925][ T262] RBP: 00007fcd63f85f70 R08: 0000000000000001 R09: 00007fcd63f866c0 [ 15.621927][ T262] R10: 0000000000000008 R11: 0000000000000246 R12: 00007fcd63786000 [ 15.621928][ T262] R13: 00007ffe0cf5b4f0 R14: 0000000000000002 R15: 00007ffe0cf5b5f7 [ 15.621942][ T262] [ 15.791870][ T266] ppp0: recursion detected [ 15.791908][ T266] [ 15.791910][ T266] ===================================================== [ 15.791911][ T266] WARNING: SOFTIRQ-safe -> SOFTIRQ-unsafe lock order detected [ 15.791914][ T266] 7.2.0-virtme #1 Tainted: G W [ 15.791916][ T266] ----------------------------------------------------- [ 15.791916][ T266] ping/266 [HC0[0]:SC0[10]:HE0:SE0] is trying to acquire: [ 15.791918][ T266] ffffffff86699660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 15.791931][ T266] [ 15.791931][ T266] and this task is already holding: [ 15.791931][ T266] ff110000059950d8 (slock-AF_INET/2){+.-.}-{3:3}, at: l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.791939][ T266] which would create a new lock dependency: [ 15.791940][ T266] (slock-AF_INET/2){+.-.}-{3:3} -> (console_owner){....}-{0:0} [ 15.791942][ T266] [ 15.791942][ T266] but this new dependency connects a SOFTIRQ-irq-safe lock: [ 15.791943][ T266] (slock-AF_INET/2){+.-.}-{3:3} [ 15.791944][ T266] [ 15.791944][ T266] ... which became SOFTIRQ-irq-safe at: [ 15.791946][ T266] __lock_acquire+0x388/0xc20 [ 15.791949][ T266] lock_acquire.part.0+0xd4/0x280 [ 15.791951][ T266] _raw_spin_lock_nested+0x3d/0x50 [ 15.791954][ T266] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.791956][ T266] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.791959][ T266] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.791961][ T266] ppp_push+0x165/0x2d0 [ppp_generic] [ 15.791964][ T266] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 15.791965][ T266] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.791967][ T266] dev_hard_start_xmit+0xf4/0x620 [ 15.791971][ T266] __dev_queue_xmit+0xc0a/0x1b80 [ 15.791973][ T266] ip_finish_output2+0x5b9/0x1bc0 [ 15.791976][ T266] ip_output+0x1ca/0x660 [ 15.791977][ T266] ip_push_pending_frames+0xf6/0x250 [ 15.791979][ T266] icmp_reply+0x94d/0xa50 [ 15.791982][ T266] icmp_echo+0x2ab/0x4b0 [ 15.791983][ T266] icmp_rcv+0x92f/0xfb0 [ 15.791985][ T266] ip_protocol_deliver_rcu+0x82/0x350 [ 15.791986][ T266] ip_local_deliver_finish+0x36f/0x610 [ 15.791988][ T266] ip_local_deliver+0x184/0x4c0 [ 15.791989][ T266] ip_rcv+0xdc/0x3d0 [ 15.791990][ T266] __netif_receive_skb_one_core+0xfc/0x180 [ 15.791992][ T266] process_backlog+0x431/0x14c0 [ 15.791994][ T266] __napi_poll+0xa7/0x3b0 [ 15.791996][ T266] net_rx_action+0x513/0xf50 [ 15.791998][ T266] handle_softirqs+0x1d3/0x900 [ 15.792001][ T266] do_softirq+0xac/0xe0 [ 15.792002][ T266] __local_bh_enable_ip+0x118/0x150 [ 15.792003][ T266] __dev_queue_xmit+0x979/0x1b80 [ 15.792005][ T266] ip_finish_output2+0x5b9/0x1bc0 [ 15.792006][ T266] ip_output+0x1ca/0x660 [ 15.792008][ T266] ip_push_pending_frames+0xf6/0x250 [ 15.792009][ T266] raw_sendmsg+0x1169/0x1cc0 [ 15.792011][ T266] __sys_sendto+0x2aa/0x400 [ 15.792014][ T266] __x64_sys_sendto+0xe4/0x1f0 [ 15.792015][ T266] do_syscall_64+0xff/0x530 [ 15.792018][ T266] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.792021][ T266] [ 15.792021][ T266] to a SOFTIRQ-irq-unsafe lock: [ 15.792021][ T266] (fs_reclaim){+.+.}-{0:0} [ 15.792023][ T266] [ 15.792023][ T266] ... which became SOFTIRQ-irq-unsafe at: [ 15.792023][ T266] ... [ 15.792024][ T266] __lock_acquire+0x388/0xc20 [ 15.792026][ T266] lock_acquire.part.0+0xd4/0x280 [ 15.792027][ T266] fs_reclaim_acquire+0xd5/0x120 [ 15.792030][ T266] __kmalloc_cache_noprof+0x6e/0x620 [ 15.792032][ T266] kthread_create_worker_on_node+0xea/0x210 [ 15.792035][ T266] workqueue_init+0x2a/0x680 [ 15.792039][ T266] kernel_init_freeable+0x2fe/0x630 [ 15.792042][ T266] kernel_init+0x21/0x150 [ 15.792044][ T266] ret_from_fork+0x474/0x6b0 [ 15.792046][ T266] ret_from_fork_asm+0x11/0x20 [ 15.792049][ T266] [ 15.792049][ T266] other info that might help us debug this: [ 15.792049][ T266] [ 15.792050][ T266] Chain exists of: [ 15.792050][ T266] slock-AF_INET/2 --> console_owner --> fs_reclaim [ 15.792050][ T266] [ 15.792052][ T266] Possible interrupt unsafe locking scenario: [ 15.792052][ T266] [ 15.792053][ T266] CPU0 CPU1 [ 15.792054][ T266] ---- ---- [ 15.792054][ T266] lock(fs_reclaim); [ 15.792055][ T266] local_irq_disable(); [ 15.792056][ T266] lock(slock-AF_INET/2); [ 15.792058][ T266] lock(console_owner); [ 15.792059][ T266] [ 15.792059][ T266] lock(slock-AF_INET/2); [ 15.792060][ T266] [ 15.792060][ T266] *** DEADLOCK *** [ 15.792060][ T266] [ 15.792061][ T266] locks held by ping/266: 14, last CPU#2: [ 15.792062][ T266] #0: ff1100000598e290 (sk_lock-AF_INET){+.+.}-{0:0}, at: raw_sendmsg+0xba5/0x1cc0 [ 15.792065][ T266] #1: ffffffff86790b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 15.792069][ T266] #2: ffffffff86790b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 15.792072][ T266] #3: ffffffff86790aa0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x1fc/0x1b80 [ 15.792075][ T266] #4: ffd1ffffffd06118 (&xmit_recursion->bh_lock){+.-.}-{3:3}, at: ppp_xmit_process+0x1d2/0x8e0 [ppp_generic] [ 15.792078][ T266] #5: ff1100000cd02fd0 (&ppp->wlock){+.-.}-{3:3}, at: ppp_xmit_process+0x292/0x8e0 [ppp_generic] [ 15.792082][ T266] #6: ff110000020b05d0 (&pch->downl){+.-.}-{3:3}, at: ppp_push+0xae/0x2d0 [ppp_generic] [ 15.792085][ T266] #7: ff110000059950d8 (slock-AF_INET/2){+.-.}-{3:3}, at: l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.792089][ T266] #8: ffffffff86790b00 (rcu_read_lock){....}-{1:3}, at: __ip_queue_xmit+0x63/0x1650 [ 15.792092][ T266] #9: ffffffff86790b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 15.792095][ T266] #10: ffffffff86790b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 15.792098][ T266] #11: ffffffff86790aa0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x1fc/0x1b80 [ 15.792101][ T266] #12: ffffffff86779cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 15.792105][ T266] #13: ffffffff86779d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 15.792108][ T266] [ 15.792108][ T266] the dependencies between SOFTIRQ-irq-safe lock and the holding lock: [ 15.792109][ T266] -> (slock-AF_INET/2){+.-.}-{3:3} { [ 15.792111][ T266] HARDIRQ-ON-W at: [ 15.792112][ T266] __lock_acquire+0x388/0xc20 [ 15.792114][ T266] lock_acquire.part.0+0xd4/0x280 [ 15.792115][ T266] _raw_spin_lock_nested+0x3d/0x50 [ 15.792116][ T266] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.792118][ T266] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.792121][ T266] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.792122][ T266] __ppp_channel_push+0xed/0x280 [ppp_generic] [ 15.792123][ T266] ppp_channel_push+0x269/0x7b0 [ppp_generic] [ 15.792125][ T266] ppp_write+0x26b/0x350 [ppp_generic] [ 15.792127][ T266] vfs_write+0x1a4/0xbd0 [ 15.792130][ T266] ksys_write+0x116/0x250 [ 15.792132][ T266] do_syscall_64+0xff/0x530 [ 15.792133][ T266] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.792134][ T266] IN-SOFTIRQ-W at: [ 15.792135][ T266] __lock_acquire+0x388/0xc20 [ 15.792136][ T266] lock_acquire.part.0+0xd4/0x280 [ 15.792138][ T266] _raw_spin_lock_nested+0x3d/0x50 [ 15.792139][ T266] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.792141][ T266] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.792143][ T266] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.792144][ T266] ppp_push+0x165/0x2d0 [ppp_generic] [ 15.792146][ T266] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 15.792148][ T266] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.792149][ T266] dev_hard_start_xmit+0xf4/0x620 [ 15.792151][ T266] __dev_queue_xmit+0xc0a/0x1b80 [ 15.792152][ T266] ip_finish_output2+0x5b9/0x1bc0 [ 15.792154][ T266] ip_output+0x1ca/0x660 [ 15.792155][ T266] ip_push_pending_frames+0xf6/0x250 [ 15.792157][ T266] icmp_reply+0x94d/0xa50 [ 15.792161][ T266] icmp_echo+0x2ab/0x4b0 [ 15.792162][ T266] icmp_rcv+0x92f/0xfb0 [ 15.792164][ T266] ip_protocol_deliver_rcu+0x82/0x350 [ 15.792166][ T266] ip_local_deliver_finish+0x36f/0x610 [ 15.792167][ T266] ip_local_deliver+0x184/0x4c0 [ 15.792168][ T266] ip_rcv+0xdc/0x3d0 [ 15.792169][ T266] __netif_receive_skb_one_core+0xfc/0x180 [ 15.792171][ T266] process_backlog+0x431/0x14c0 [ 15.792173][ T266] __napi_poll+0xa7/0x3b0 [ 15.792175][ T266] net_rx_action+0x513/0xf50 [ 15.792176][ T266] handle_softirqs+0x1d3/0x900 [ 15.792177][ T266] do_softirq+0xac/0xe0 [ 15.792179][ T266] __local_bh_enable_ip+0x118/0x150 [ 15.792180][ T266] __dev_queue_xmit+0x979/0x1b80 [ 15.792181][ T266] ip_finish_output2+0x5b9/0x1bc0 [ 15.792183][ T266] ip_output+0x1ca/0x660 [ 15.792184][ T266] ip_push_pending_frames+0xf6/0x250 [ 15.792186][ T266] raw_sendmsg+0x1169/0x1cc0 [ 15.792187][ T266] __sys_sendto+0x2aa/0x400 [ 15.792188][ T266] __x64_sys_sendto+0xe4/0x1f0 [ 15.792190][ T266] do_syscall_64+0xff/0x530 [ 15.792191][ T266] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.792192][ T266] INITIAL USE at: [ 15.792193][ T266] __lock_acquire+0x388/0xc20 [ 15.792195][ T266] lock_acquire.part.0+0xd4/0x280 [ 15.792196][ T266] _raw_spin_lock_nested+0x3d/0x50 [ 15.792197][ T266] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.792199][ T266] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.792201][ T266] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.792202][ T266] __ppp_channel_push+0xed/0x280 [ppp_generic] [ 15.792204][ T266] ppp_channel_push+0x269/0x7b0 [ppp_generic] [ 15.792206][ T266] ppp_write+0x26b/0x350 [ppp_generic] [ 15.792207][ T266] vfs_write+0x1a4/0xbd0 [ 15.792209][ T266] ksys_write+0x116/0x250 [ 15.792210][ T266] do_syscall_64+0xff/0x530 [ 15.792212][ T266] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.792213][ T266] } [ 15.792213][ T266] ... key at: [] af_family_slock_keys+0x22/0x300 [ 15.792217][ T266] [ 15.792217][ T266] the dependencies between the lock to be acquired [ 15.792217][ T266] and SOFTIRQ-irq-unsafe lock: [ 15.792222][ T266] -> (fs_reclaim){+.+.}-{0:0} { [ 15.792224][ T266] HARDIRQ-ON-W at: [ 15.792225][ T266] __lock_acquire+0x388/0xc20 [ 15.792226][ T266] lock_acquire.part.0+0xd4/0x280 [ 15.792228][ T266] fs_reclaim_acquire+0xd5/0x120 [ 15.792229][ T266] __kmalloc_cache_noprof+0x6e/0x620 [ 15.792230][ T266] kthread_create_worker_on_node+0xea/0x210 [ 15.792232][ T266] workqueue_init+0x2a/0x680 [ 15.792233][ T266] kernel_init_freeable+0x2fe/0x630 [ 15.792235][ T266] kernel_init+0x21/0x150 [ 15.792236][ T266] ret_from_fork+0x474/0x6b0 [ 15.792237][ T266] ret_from_fork_asm+0x11/0x20 [ 15.792238][ T266] SOFTIRQ-ON-W at: [ 15.792239][ T266] __lock_acquire+0x388/0xc20 [ 15.792241][ T266] lock_acquire.part.0+0xd4/0x280 [ 15.792242][ T266] fs_reclaim_acquire+0xd5/0x120 [ 15.792243][ T266] __kmalloc_cache_noprof+0x6e/0x620 [ 15.792244][ T266] kthread_create_worker_on_node+0xea/0x210 [ 15.792246][ T266] workqueue_init+0x2a/0x680 [ 15.792247][ T266] kernel_init_freeable+0x2fe/0x630 [ 15.792248][ T266] kernel_init+0x21/0x150 [ 15.792250][ T266] ret_from_fork+0x474/0x6b0 [ 15.792251][ T266] ret_from_fork_asm+0x11/0x20 [ 15.792252][ T266] INITIAL USE at: [ 15.792253][ T266] __lock_acquire+0x388/0xc20 [ 15.792254][ T266] lock_acquire.part.0+0xd4/0x280 [ 15.792255][ T266] fs_reclaim_acquire+0xd5/0x120 [ 15.792257][ T266] __kmalloc_cache_noprof+0x6e/0x620 [ 15.792258][ T266] kthread_create_worker_on_node+0xea/0x210 [ 15.792259][ T266] workqueue_init+0x2a/0x680 [ 15.792260][ T266] kernel_init_freeable+0x2fe/0x630 [ 15.792262][ T266] kernel_init+0x21/0x150 [ 15.792263][ T266] ret_from_fork+0x474/0x6b0 [ 15.792264][ T266] ret_from_fork_asm+0x11/0x20 [ 15.792265][ T266] } [ 15.792266][ T266] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 15.792269][ T266] ... acquired at: [ 15.792270][ T266] __lock_acquire+0x518/0xc20 [ 15.792271][ T266] lock_acquire.part.0+0xd4/0x280 [ 15.792273][ T266] fs_reclaim_acquire+0xd5/0x120 [ 15.792274][ T266] __kmalloc_noprof+0xd3/0x760 [ 15.792275][ T266] alloc_buf.isra.0+0x4b/0x260 [ 15.792278][ T266] put_chars+0x1e1/0x2f0 [ 15.792280][ T266] hvc_console_print+0x292/0x780 [ 15.792282][ T266] console_emit_next_record+0x22f/0x4c0 [ 15.792284][ T266] console_flush_one_record+0x46f/0x710 [ 15.792285][ T266] console_unlock+0xee/0x1f0 [ 15.792287][ T266] vprintk_emit+0x37c/0x3e0 [ 15.792288][ T266] dev_vprintk_emit+0x27f/0x2c0 [ 15.792292][ T266] dev_printk_emit+0xb9/0xee [ 15.792293][ T266] _dev_info+0xe2/0x116 [ 15.792295][ T266] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 15.792298][ T266] cmos_do_probe+0x73b/0x98a [ 15.792299][ T266] platform_probe+0xfe/0x1f0 [ 15.792302][ T266] call_driver_probe+0x61/0x1c0 [ 15.792304][ T266] really_probe+0x199/0x760 [ 15.792306][ T266] __driver_probe_device+0x24f/0x440 [ 15.792307][ T266] driver_probe_device+0x4a/0xf0 [ 15.792309][ T266] __driver_attach+0x1b8/0x540 [ 15.792310][ T266] bus_for_each_dev+0x130/0x1e0 [ 15.792312][ T266] bus_add_driver+0x2c8/0x530 [ 15.792313][ T266] driver_register+0x1a3/0x390 [ 15.792315][ T266] __platform_driver_probe+0x13f/0x270 [ 15.792316][ T266] cmos_init+0x31/0x40 [ 15.792319][ T266] do_one_initcall+0x124/0x4f0 [ 15.792321][ T266] kernel_init_freeable+0x596/0x630 [ 15.792322][ T266] kernel_init+0x21/0x150 [ 15.792324][ T266] ret_from_fork+0x474/0x6b0 [ 15.792325][ T266] ret_from_fork_asm+0x11/0x20 [ 15.792326][ T266] [ 15.792326][ T266] -> (console_owner){....}-{0:0} { [ 15.792328][ T266] INITIAL USE at: [ 15.792329][ T266] } [ 15.792329][ T266] ... key at: [] console_owner_dep_map+0x0/0x60 [ 15.792333][ T266] ... acquired at: [ 15.792333][ T266] check_prev_add+0xef/0xe90 [ 15.792335][ T266] validate_chain+0x6b8/0xc20 [ 15.792336][ T266] __lock_acquire+0x518/0xc20 [ 15.792337][ T266] lock_acquire.part.0+0xd4/0x280 [ 15.792338][ T266] console_lock_spinning_enable+0x5c/0x60 [ 15.792340][ T266] console_emit_next_record+0x1d1/0x4c0 [ 15.792342][ T266] console_flush_one_record+0x46f/0x710 [ 15.792343][ T266] console_unlock+0xee/0x1f0 [ 15.792345][ T266] vprintk_emit+0x37c/0x3e0 [ 15.792346][ T266] _printk+0xc7/0x100 [ 15.792349][ T266] netdev_err+0xe2/0x120 [ 15.792351][ T266] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 15.792353][ T266] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.792355][ T266] dev_hard_start_xmit+0xf4/0x620 [ 15.792356][ T266] __dev_queue_xmit+0xc0a/0x1b80 [ 15.792358][ T266] ip_finish_output2+0x5b9/0x1bc0 [ 15.792359][ T266] ip_output+0x1ca/0x660 [ 15.792361][ T266] __ip_queue_xmit+0x774/0x1650 [ 15.792362][ T266] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 15.792365][ T266] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.792367][ T266] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.792368][ T266] ppp_push+0x165/0x2d0 [ppp_generic] [ 15.792370][ T266] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 15.792371][ T266] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.792373][ T266] dev_hard_start_xmit+0xf4/0x620 [ 15.792374][ T266] __dev_queue_xmit+0xc0a/0x1b80 [ 15.792376][ T266] ip_finish_output2+0x5b9/0x1bc0 [ 15.792377][ T266] ip_output+0x1ca/0x660 [ 15.792379][ T266] ip_push_pending_frames+0xf6/0x250 [ 15.792380][ T266] raw_sendmsg+0x1169/0x1cc0 [ 15.792381][ T266] __sys_sendto+0x2aa/0x400 [ 15.792383][ T266] __x64_sys_sendto+0xe4/0x1f0 [ 15.792385][ T266] do_syscall_64+0xff/0x530 [ 15.792386][ T266] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.792387][ T266] [ 15.792388][ T266] [ 15.792388][ T266] stack backtrace: [ 15.792391][ T266] CPU: 2 UID: 0 PID: 266 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.792394][ T266] Tainted: [W]=WARN [ 15.792395][ T266] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.792397][ T266] Call Trace: [ 15.792398][ T266] [ 15.792400][ T266] dump_stack_lvl+0x6f/0xa0 [ 15.792404][ T266] print_bad_irq_dependency+0x3e4/0x410 [ 15.792406][ T266] check_irq_usage+0x403/0x570 [ 15.792408][ T266] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.792412][ T266] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.792414][ T266] ? check_path.constprop.0+0x24/0x50 [ 15.792417][ T266] ? check_prev_add+0xef/0xe90 [ 15.792418][ T266] check_prev_add+0xef/0xe90 [ 15.792420][ T266] validate_chain+0x6b8/0xc20 [ 15.792422][ T266] __lock_acquire+0x518/0xc20 [ 15.792424][ T266] lock_acquire.part.0+0xd4/0x280 [ 15.792426][ T266] ? console_lock_spinning_enable+0x40/0x60 [ 15.792428][ T266] ? rcu_is_watching+0x16/0xd0 [ 15.792430][ T266] ? lock_acquire+0x13c/0x160 [ 15.792432][ T266] console_lock_spinning_enable+0x5c/0x60 [ 15.792434][ T266] ? console_lock_spinning_enable+0x40/0x60 [ 15.792435][ T266] console_emit_next_record+0x1d1/0x4c0 [ 15.792437][ T266] ? devkmsg_read+0x4b0/0x4b0 [ 15.792439][ T266] ? console_flush_one_record+0x106/0x710 [ 15.792441][ T266] ? rcu_is_watching+0x16/0xd0 [ 15.792443][ T266] ? lock_acquire+0x13c/0x160 [ 15.792444][ T266] console_flush_one_record+0x46f/0x710 [ 15.792447][ T266] ? console_emit_next_record+0x4c0/0x4c0 [ 15.792448][ T266] ? __lock_acquire+0x518/0xc20 [ 15.792450][ T266] console_unlock+0xee/0x1f0 [ 15.792452][ T266] ? console_flush_one_record+0x710/0x710 [ 15.792454][ T266] ? rcu_is_watching+0x16/0xd0 [ 15.792456][ T266] ? lock_acquire+0x60/0x160 [ 15.792457][ T266] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.792459][ T266] ? vprintk_emit+0x320/0x3e0 [ 15.792461][ T266] vprintk_emit+0x37c/0x3e0 [ 15.792463][ T266] ? wake_up_klogd_work_func+0x90/0x90 [ 15.792465][ T266] ? mark_lock+0x3bb/0xa00 [ 15.792467][ T266] _printk+0xc7/0x100 [ 15.792469][ T266] ? snapshot_read.cold+0x21/0x21 [ 15.792471][ T266] ? lock_acquire.part.0+0xd4/0x280 [ 15.792472][ T266] ? find_held_lock+0x2b/0x80 [ 15.792473][ T266] ? netdev_name+0x43/0x60 [ 15.792475][ T266] ? __netdev_printk+0x17f/0x1d0 [ 15.792476][ T266] netdev_err+0xe2/0x120 [ 15.792478][ T266] ? netdev_crit+0x120/0x120 [ 15.792479][ T266] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 15.792481][ T266] ? ___ratelimit+0x4e7/0xa00 [ 15.792484][ T266] ? idr_get_free+0x830/0x830 [ 15.792486][ T266] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 15.792488][ T266] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 15.792490][ T266] ? dst_release+0x54/0x240 [ 15.792493][ T266] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.792495][ T266] ? dev_nit_active_rcu+0x1f/0xf0 [ 15.792498][ T266] ? rcu_is_watching+0x16/0xd0 [ 15.792499][ T266] dev_hard_start_xmit+0xf4/0x620 [ 15.792502][ T266] ? rcu_is_watching+0x16/0xd0 [ 15.792504][ T266] __dev_queue_xmit+0xc0a/0x1b80 [ 15.792506][ T266] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 15.792507][ T266] ? lockdep_hardirqs_on+0x91/0x130 [ 15.792508][ T266] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 15.792510][ T266] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 15.792511][ T266] ? ref_tracker_dir_print+0x100/0x100 [ 15.792515][ T266] ? dst_init+0x84/0x530 [ 15.792516][ T266] ? dst_alloc+0x97/0x160 [ 15.792517][ T266] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 15.792519][ T266] ? ip_route_output_flow+0x21/0x150 [ 15.792520][ T266] ? __ip_queue_xmit+0xdc7/0x1650 [ 15.792522][ T266] ? __lock_acquire+0x518/0xc20 [ 15.792523][ T266] ? down_read_nested+0xe7/0x500 [ 15.792525][ T266] ? lock_acquire.part.0+0xd4/0x280 [ 15.792526][ T266] ? ip_finish_output2+0x25c/0x1bc0 [ 15.792528][ T266] ? rcu_is_watching+0x16/0xd0 [ 15.792531][ T266] ip_finish_output2+0x5b9/0x1bc0 [ 15.792532][ T266] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 15.792534][ T266] ? lock_acquire.part.0+0xd4/0x280 [ 15.792536][ T266] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 15.792538][ T266] ip_output+0x1ca/0x660 [ 15.792540][ T266] ? ip_finish_output+0x460/0x460 [ 15.792542][ T266] ? find_held_lock+0x2b/0x80 [ 15.792543][ T266] ? __lock_release.isra.0+0x69/0x1a0 [ 15.792545][ T266] ? ip4_dst_hoplimit+0x15b/0x320 [ 15.792547][ T266] __ip_queue_xmit+0x774/0x1650 [ 15.792549][ T266] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 15.792553][ T266] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.792556][ T266] ? l2tp_xmit_core.constprop.0+0x19b0/0x19b0 [l2tp_core] [ 15.792559][ T266] ? do_raw_spin_lock+0x131/0x280 [ 15.792561][ T266] ? pppol2tp_xmit+0x423/0x690 [l2tp_ppp] [ 15.792562][ T266] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.792564][ T266] ppp_push+0x165/0x2d0 [ppp_generic] [ 15.792566][ T266] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 15.792568][ T266] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 15.792570][ T266] ? dst_release+0x54/0x240 [ 15.792572][ T266] ? down_read_nested+0x36a/0x500 [ 15.792573][ T266] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.792575][ T266] ? dev_nit_active_rcu+0x1f/0xf0 [ 15.792577][ T266] ? rcu_is_watching+0x16/0xd0 [ 15.792579][ T266] dev_hard_start_xmit+0xf4/0x620 [ 15.792581][ T266] ? rcu_is_watching+0x16/0xd0 [ 15.792583][ T266] __dev_queue_xmit+0xc0a/0x1b80 [ 15.792585][ T266] ? sock_queue_err_skb+0x680/0x680 [ 15.792588][ T266] ? kmem_cache_alloc_node_noprof+0x2a6/0x660 [ 15.792589][ T266] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.792591][ T266] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.792592][ T266] ? validate_chain+0x38b/0xc20 [ 15.792594][ T266] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 15.792596][ T266] ? __lock_acquire+0x518/0xc20 [ 15.792598][ T266] ? down_read_nested+0xe7/0x500 [ 15.792599][ T266] ? lock_acquire.part.0+0xd4/0x280 [ 15.792601][ T266] ? ip_finish_output2+0x25c/0x1bc0 [ 15.792602][ T266] ? rcu_is_watching+0x16/0xd0 [ 15.792605][ T266] ip_finish_output2+0x5b9/0x1bc0 [ 15.792607][ T266] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 15.792608][ T266] ? lock_acquire.part.0+0xd4/0x280 [ 15.792610][ T266] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 15.792612][ T266] ? rcu_is_watching+0x16/0xd0 [ 15.792614][ T266] ip_output+0x1ca/0x660 [ 15.792616][ T266] ? ip_finish_output+0x460/0x460 [ 15.792617][ T266] ? __ip_make_skb+0x106a/0x2300 [ 15.792620][ T266] ip_push_pending_frames+0xf6/0x250 [ 15.792622][ T266] raw_sendmsg+0x1169/0x1cc0 [ 15.792624][ T266] ? raw_send_hdrinc+0x1740/0x1740 [ 15.792626][ T266] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.792629][ T266] ? __might_fault+0x97/0x140 [ 15.792633][ T266] ? __might_fault+0x97/0x140 [ 15.792635][ T266] ? __might_fault+0x97/0x140 [ 15.792636][ T266] __sys_sendto+0x2aa/0x400 [ 15.792638][ T266] ? __ia32_sys_getpeername+0xd0/0xd0 [ 15.792642][ T266] ? exc_page_fault+0x87/0x100 [ 15.792644][ T266] __x64_sys_sendto+0xe4/0x1f0 [ 15.792646][ T266] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 15.792649][ T266] ? lockdep_hardirqs_on+0x91/0x130 [ 15.792650][ T266] ? do_syscall_64+0xa6/0x530 [ 15.792651][ T266] do_syscall_64+0xff/0x530 [ 15.792653][ T266] ? exc_page_fault+0xee/0x100 [ 15.792654][ T266] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.792655][ T266] RIP: 0033:0x7f66c1c6454e [ 15.792658][ T266] 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.792661][ T266] RSP: 002b:00007ffeeb7a81e0 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 15.792663][ T266] RAX: ffffffffffffffda RBX: 0000000000000040 RCX: 00007f66c1c6454e [ 15.792665][ T266] RDX: 0000000000000040 RSI: 0000561ed6f7a0a4 RDI: 0000000000000005 [ 15.792666][ T266] RBP: 00007ffeeb7a81f0 R08: 0000561ed6f8c320 R09: 0000000000000010 [ 15.792667][ T266] R10: 0000000000000000 R11: 0000000000000202 R12: 431bde82d7b634db [ 15.792668][ T266] R13: 0000000000000000 R14: 0000561ed6f8d3e0 R15: 0000561ed6f7a0a0 [ 15.792670][ T266] [ 15.887174][ C2] ppp0: recursion detected [ 16.802240][ T266] ping (266) used greatest stack depth: 22536 bytes left [ 16.802259][ T266] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 16.802261][ T266] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 266, name: ping [ 16.802263][ T266] preempt_count: 2, expected: 0 [ 16.802264][ T266] RCU nest depth: 0, expected: 0 [ 16.802265][ T266] INFO: lockdep is turned off. [ 16.802266][ T266] irq event stamp: 25705 [ 16.802267][ T266] hardirqs last enabled at (25704): [] __down_trylock_console_sem+0x86/0xa0 [ 16.802276][ T266] hardirqs last disabled at (25705): [] console_emit_next_record+0x3d4/0x4c0 [ 16.802279][ T266] softirqs last enabled at (25604): [] __alloc_skb+0x4c2/0x5f0 [ 16.802283][ T266] softirqs last disabled at (25614): [] __dev_queue_xmit+0x1fc/0x1b80 [ 16.802287][ T266] Preemption disabled at: [ 16.802288][ T266] [<0000000000000000>] 0x0 [ 16.802294][ T266] CPU: 2 UID: 0 PID: 266 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 16.802297][ T266] Tainted: [W]=WARN [ 16.802298][ T266] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.802300][ T266] Call Trace: [ 16.802302][ T266] [ 16.802303][ T266] dump_stack_lvl+0x6f/0xa0 [ 16.802308][ T266] __might_resched.cold+0x1fe/0x2c1 [ 16.802312][ T266] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 16.802315][ T266] ? __asan_memcpy+0x3c/0x60 [ 16.802318][ T266] ? rcu_is_watching+0x16/0xd0 [ 16.802322][ T266] __kmalloc_noprof+0x443/0x760 [ 16.802325][ T266] ? __rwlock_init+0x150/0x150 [ 16.802328][ T266] ? alloc_buf.isra.0+0x4b/0x260 [ 16.802332][ T266] ? do_raw_spin_unlock+0x59/0x250 [ 16.802333][ T266] alloc_buf.isra.0+0x4b/0x260 [ 16.802335][ T266] put_chars+0x1e1/0x2f0 [ 16.802338][ T266] ? __send_to_port+0x420/0x420 [ 16.802339][ T266] ? console_prepend_replay+0x20/0x20 [ 16.802342][ T266] hvc_console_print+0x292/0x780 [ 16.802346][ T266] ? hvc_write+0x3a0/0x3a0 [ 16.802347][ T266] ? rcu_is_watching+0x16/0xd0 [ 16.802349][ T266] ? lock_acquire+0x13c/0x160 [ 16.802351][ T266] console_emit_next_record+0x22f/0x4c0 [ 16.802354][ T266] ? devkmsg_read+0x4b0/0x4b0 [ 16.802356][ T266] ? rcu_is_watching+0x16/0xd0 [ 16.802357][ T266] ? rcu_is_watching+0x16/0xd0 [ 16.802359][ T266] ? rcu_is_watching+0x16/0xd0 [ 16.802361][ T266] ? lock_acquire+0x13c/0x160 [ 16.802363][ T266] ? console_flush_one_record+0x111/0x710 [ 16.802364][ T266] console_flush_one_record+0x46f/0x710 [ 16.802366][ T266] ? console_emit_next_record+0x4c0/0x4c0 [ 16.802369][ T266] console_unlock+0xee/0x1f0 [ 16.802371][ T266] ? lock_acquire+0x13c/0x160 [ 16.802372][ T266] ? console_flush_one_record+0x710/0x710 [ 16.802374][ T266] ? rcu_is_watching+0x16/0xd0 [ 16.802376][ T266] ? lock_release+0x184/0x1f0 [ 16.802377][ T266] ? lock_acquire+0x60/0x160 [ 16.802379][ T266] ? __rcu_read_lock+0x30/0x70 [ 16.802381][ T266] ? __down_trylock_console_sem+0x5e/0xa0 [ 16.802382][ T266] ? vprintk_emit+0x320/0x3e0 [ 16.802384][ T266] vprintk_emit+0x37c/0x3e0 [ 16.802387][ T266] ? wake_up_klogd_work_func+0x90/0x90 [ 16.802389][ T266] ? do_raw_read_unlock+0x70/0x70 [ 16.802390][ T266] ? forget_original_parent+0x5c/0xdb0 [ 16.802394][ T266] _printk+0xc7/0x100 [ 16.802398][ T266] ? snapshot_read.cold+0x21/0x21 [ 16.802399][ T266] ? do_raw_spin_lock+0x131/0x280 [ 16.802401][ T266] ? do_raw_spin_lock+0x131/0x280 [ 16.802403][ T266] ? __rwlock_init+0x150/0x150 [ 16.802405][ T266] ? do_raw_spin_lock+0x131/0x280 [ 16.802406][ T266] do_exit.cold+0x82/0x9c [ 16.802409][ T266] ? exit_notify+0x890/0x890 [ 16.802411][ T266] ? posixtimer_sig_unignore.isra.0+0x5f0/0x5f0 [ 16.802413][ T266] ? rcu_is_watching+0x16/0xd0 [ 16.802415][ T266] do_group_exit+0xb8/0x370 [ 16.802417][ T266] __x64_sys_exit_group+0x3c/0x50 [ 16.802418][ T266] x64_sys_call+0x1567/0x1570 [ 16.802420][ T266] do_syscall_64+0xff/0x530 [ 16.802424][ T266] ? exc_page_fault+0xee/0x100 [ 16.802426][ T266] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 16.802428][ T266] RIP: 0033:0x7f66c1cb51b8 [ 16.802432][ T266] Code: Unable to access opcode bytes at 0x7f66c1cb518e. [ 16.802433][ T266] RSP: 002b:00007ffeeb7a9978 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 16.802437][ T266] RAX: ffffffffffffffda RBX: 00007f66c1de5f88 RCX: 00007f66c1cb51b8 [ 16.802438][ T266] RDX: 00007f66c1a467c8 RSI: ffffffffffffffb0 RDI: 0000000000000001 [ 16.802439][ T266] RBP: 00007ffeeb7a99d0 R08: 0000000000000000 R09: 0000000000001010 [ 16.802440][ T266] R10: 00007ffeeb7a97f0 R11: 0000000000000246 R12: 0000000000000002 [ 16.802441][ T266] R13: 0000000000000001 R14: 00007f66c1de4680 R15: 00007f66c1de5fa0 [ 16.802443][ T266] [ 16.851884][ T221] ppp0: recursion detected