[ 9.231821][ T181] PPP generic driver version 2.4.2 [ 9.231836][ T181] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.231838][ T181] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 181, name: modprobe [ 9.231840][ T181] preempt_count: 1, expected: 0 [ 9.231841][ T181] RCU nest depth: 0, expected: 0 [ 9.231842][ T181] locks held by modprobe/181: 4, last CPU#3: [ 9.231844][ T181] #0: ffffffffb6d79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.231856][ T181] #1: ffffffffb6d79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.231860][ T181] #2: ffffffffb6c99660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.231864][ T181] #3: ffffffffb6c99560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.231868][ T181] irq event stamp: 47388 [ 9.231868][ T181] hardirqs last enabled at (47387): [] __down_trylock_console_sem+0x86/0xa0 [ 9.231871][ T181] hardirqs last disabled at (47388): [] console_emit_next_record+0x3d4/0x4c0 [ 9.231873][ T181] softirqs last enabled at (45582): [] handle_softirqs+0x67c/0x900 [ 9.231876][ T181] softirqs last disabled at (45575): [] __irq_exit_rcu+0x145/0x1c0 [ 9.231878][ T181] Preemption disabled at: [ 9.231879][ T181] [] vprintk_emit+0x31b/0x3e0 [ 9.231884][ T181] CPU: 3 UID: 0 PID: 181 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.231887][ T181] Tainted: [W]=WARN [ 9.231889][ T181] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.231890][ T181] Call Trace: [ 9.231892][ T181] [ 9.231893][ T181] dump_stack_lvl+0x6f/0xa0 [ 9.231899][ T181] ? vprintk_emit+0x31b/0x3e0 [ 9.231901][ T181] __might_resched.cold+0x1fe/0x2c1 [ 9.231905][ T181] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.231909][ T181] ? __kmalloc_noprof+0xdb/0x760 [ 9.231914][ T181] __kmalloc_noprof+0x443/0x760 [ 9.231916][ T181] ? alloc_buf.isra.0+0x4b/0x260 [ 9.231922][ T181] ? do_raw_spin_unlock+0x59/0x250 [ 9.231924][ T181] alloc_buf.isra.0+0x4b/0x260 [ 9.231927][ T181] put_chars+0x1e1/0x2f0 [ 9.231930][ T181] ? __send_to_port+0x420/0x420 [ 9.231932][ T181] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.231937][ T181] ? rcu_read_lock_any_held+0x3c/0x90 [ 9.231939][ T181] ? validate_chain+0x38b/0xc20 [ 9.231943][ T181] hvc_console_print+0x292/0x780 [ 9.231946][ T181] ? __lock_acquire+0x518/0xc20 [ 9.231951][ T181] ? hvc_write+0x3a0/0x3a0 [ 9.231954][ T181] ? rcu_is_watching+0x16/0xd0 [ 9.231959][ T181] console_emit_next_record+0x22f/0x4c0 [ 9.231962][ T181] ? devkmsg_read+0x4b0/0x4b0 [ 9.231964][ T181] ? console_flush_one_record+0x106/0x710 [ 9.231967][ T181] ? rcu_is_watching+0x16/0xd0 [ 9.231970][ T181] ? lock_acquire+0x13c/0x160 [ 9.231973][ T181] console_flush_one_record+0x46f/0x710 [ 9.231978][ T181] ? console_emit_next_record+0x4c0/0x4c0 [ 9.231980][ T181] ? __lock_acquire+0x518/0xc20 [ 9.231985][ T181] console_unlock+0xee/0x1f0 [ 9.231987][ T181] ? console_flush_one_record+0x710/0x710 [ 9.231989][ T181] ? rcu_is_watching+0x16/0xd0 [ 9.231991][ T181] ? lock_acquire+0x60/0x160 [ 9.231995][ T181] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.232001][ T181] ? vprintk_emit+0x320/0x3e0 [ 9.232004][ T181] vprintk_emit+0x37c/0x3e0 [ 9.232008][ T181] ? wake_up_klogd_work_func+0x90/0x90 [ 9.232013][ T181] ? ppp_cleanup+0x920/0x920 [ppp_generic] [ 9.232018][ T181] _printk+0xc7/0x100 [ 9.232022][ T181] ? snapshot_read.cold+0x21/0x21 [ 9.232027][ T181] ? do_one_initcall+0x113/0x4f0 [ 9.232031][ T181] ppp_init+0x16/0x1000 [ppp_generic] [ 9.232034][ T181] do_one_initcall+0x124/0x4f0 [ 9.232037][ T181] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 9.232041][ T181] ? do_init_module+0xae/0x8a0 [ 9.232044][ T181] ? kasan_unpoison+0x44/0x70 [ 9.232048][ T181] do_init_module+0x2ad/0x8a0 [ 9.232052][ T181] ? lockdep_hardirqs_on+0x91/0x130 [ 9.232055][ T181] ? free_module+0x470/0x470 [ 9.232059][ T181] ? kfree+0x123/0x590 [ 9.232063][ T181] load_module+0x1424/0x1bb0 [ 9.232069][ T181] ? layout_and_allocate.isra.0+0x680/0x680 [ 9.232074][ T181] ? backing_file_read_iter+0x541/0x790 [ 9.232077][ T181] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.232081][ T181] init_module_from_file+0x11f/0x1a0 [ 9.232084][ T181] ? __do_sys_init_module+0x220/0x220 [ 9.232092][ T181] ? __rwlock_init+0x150/0x150 [ 9.232095][ T181] ? do_raw_spin_unlock+0x59/0x250 [ 9.232098][ T181] idempotent_init_module+0x37b/0x7c0 [ 9.232101][ T181] ? init_module_from_file+0x1a0/0x1a0 [ 9.232107][ T181] ? rcu_is_watching+0x16/0xd0 [ 9.232110][ T181] ? cap_capable+0x1d7/0x3d0 [ 9.232116][ T181] __x64_sys_finit_module+0xdf/0x190 [ 9.232118][ T181] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 9.232121][ T181] do_syscall_64+0xff/0x530 [ 9.232124][ T181] ? exc_page_fault+0xee/0x100 [ 9.232126][ T181] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.232129][ T181] RIP: 0033:0x7faeda94737d [ 9.232132][ 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 [ 9.232134][ T181] RSP: 002b:00007fff7c1741f8 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 9.232136][ T181] RAX: ffffffffffffffda RBX: 000055a91f7411d0 RCX: 00007faeda94737d [ 9.232138][ T181] RDX: 0000000000000000 RSI: 000055a8e0dfcffd RDI: 0000000000000006 [ 9.232138][ T181] RBP: 00007fff7c174290 R08: 0000000000000000 R09: 0000000000000000 [ 9.232139][ T181] R10: 0000000000000000 R11: 0000000000000246 R12: 000055a8e0dfcffd [ 9.232140][ T181] R13: 000055a91f741180 R14: 0000000000040000 R15: 0000000000000000 [ 9.232147][ T181] [ 9.274035][ T181] NET: Registered PF_PPPOX protocol family [ 9.293103][ T181] l2tp_core: L2TP core driver, V2.0 [ 9.299316][ T181] l2tp_ppp: PPPoL2TP kernel driver, V2.0 [ 14.350090][ T258] iperf3 (258) used greatest stack depth: 23040 bytes left [ 14.350109][ T258] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 14.350111][ T258] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 258, name: iperf3 [ 14.350113][ T258] preempt_count: 2, expected: 0 [ 14.350114][ T258] RCU nest depth: 0, expected: 0 [ 14.350115][ T258] locks held by iperf3/258: 5, last CPU#1: [ 14.350117][ T258] #0: ffffffffb6c127b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 14.350128][ T258] #1: ffffffffb6d79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 14.350134][ T258] #2: ffffffffb6d79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 14.350138][ T258] #3: ffffffffb6c99660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 14.350141][ T258] #4: ffffffffb6c99560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 14.350145][ T258] irq event stamp: 1646762 [ 14.350146][ T258] hardirqs last enabled at (1646761): [] __down_trylock_console_sem+0x86/0xa0 [ 14.350148][ T258] hardirqs last disabled at (1646762): [] console_emit_next_record+0x3d4/0x4c0 [ 14.350150][ T258] softirqs last enabled at (1646706): [] handle_softirqs+0x67c/0x900 [ 14.350153][ T258] softirqs last disabled at (1646615): [] __irq_exit_rcu+0x145/0x1c0 [ 14.350155][ T258] Preemption disabled at: [ 14.350156][ T258] [<0000000000000000>] 0x0 [ 14.350162][ T258] CPU: 1 UID: 0 PID: 258 Comm: iperf3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 14.350166][ T258] Tainted: [W]=WARN [ 14.350167][ T258] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.350168][ T258] Call Trace: [ 14.350170][ T258] [ 14.350171][ T258] dump_stack_lvl+0x6f/0xa0 [ 14.350178][ T258] __might_resched.cold+0x1fe/0x2c1 [ 14.350182][ T258] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 14.350186][ T258] ? __kmalloc_noprof+0xdb/0x760 [ 14.350191][ T258] __kmalloc_noprof+0x443/0x760 [ 14.350193][ T258] ? alloc_buf.isra.0+0x4b/0x260 [ 14.350199][ T258] ? do_raw_spin_unlock+0x59/0x250 [ 14.350202][ T258] alloc_buf.isra.0+0x4b/0x260 [ 14.350205][ T258] put_chars+0x1e1/0x2f0 [ 14.350207][ T258] ? prb_final_commit+0x50/0x50 [ 14.350209][ T258] ? __send_to_port+0x420/0x420 [ 14.350213][ T258] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.350217][ T258] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.350219][ T258] ? validate_chain+0x38b/0xc20 [ 14.350223][ T258] hvc_console_print+0x292/0x780 [ 14.350226][ T258] ? mark_usage+0x61/0x170 [ 14.350228][ T258] ? __lock_acquire+0x518/0xc20 [ 14.350230][ T258] ? __lock_acquire+0x518/0xc20 [ 14.350233][ T258] ? hvc_write+0x3a0/0x3a0 [ 14.350236][ T258] ? console_emit_next_record+0x1df/0x4c0 [ 14.350239][ T258] ? rcu_is_watching+0x16/0xd0 [ 14.350241][ T258] ? lock_acquire+0x13c/0x160 [ 14.350245][ T258] console_emit_next_record+0x22f/0x4c0 [ 14.350249][ T258] ? devkmsg_read+0x4b0/0x4b0 [ 14.350250][ T258] ? console_flush_one_record+0x106/0x710 [ 14.350253][ T258] ? rcu_is_watching+0x16/0xd0 [ 14.350256][ T258] ? lock_acquire+0x13c/0x160 [ 14.350259][ T258] console_flush_one_record+0x46f/0x710 [ 14.350263][ T258] ? console_emit_next_record+0x4c0/0x4c0 [ 14.350265][ T258] ? __lock_acquire+0x518/0xc20 [ 14.350270][ T258] console_unlock+0xee/0x1f0 [ 14.350273][ T258] ? console_flush_one_record+0x710/0x710 [ 14.350275][ T258] ? rcu_is_watching+0x16/0xd0 [ 14.350277][ T258] ? lock_acquire+0x60/0x160 [ 14.350280][ T258] ? __down_trylock_console_sem+0x5e/0xa0 [ 14.350282][ T258] ? vprintk_emit+0x320/0x3e0 [ 14.350285][ T258] vprintk_emit+0x37c/0x3e0 [ 14.350289][ T258] ? wake_up_klogd_work_func+0x90/0x90 [ 14.350293][ T258] ? __lock_acquire+0x518/0xc20 [ 14.350296][ T258] _printk+0xc7/0x100 [ 14.350300][ T258] ? snapshot_read.cold+0x21/0x21 [ 14.350302][ T258] ? do_raw_spin_lock+0x131/0x280 [ 14.350305][ T258] ? __rwlock_init+0x150/0x150 [ 14.350309][ T258] ? do_raw_spin_lock+0x131/0x280 [ 14.350311][ T258] do_exit.cold+0x82/0x9c [ 14.350315][ T258] ? exit_notify+0x890/0x890 [ 14.350320][ T258] __x64_sys_exit+0x42/0x50 [ 14.350322][ T258] x64_sys_call+0x156c/0x1570 [ 14.350324][ T258] do_syscall_64+0xff/0x530 [ 14.350328][ T258] ? exc_page_fault+0xee/0x100 [ 14.350331][ T258] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.350333][ T258] RIP: 0033:0x7fa80a1a6c49 [ 14.350335][ T258] Code: Unable to access opcode bytes at 0x7fa80a1a6c1f. [ 14.350336][ T258] RSP: 002b:00007fa809965e60 EFLAGS: 00000246 ORIG_RAX: 000000000000003c [ 14.350339][ T258] RAX: ffffffffffffffda RBX: 00007fa8099666c0 RCX: 00007fa80a1a6c49 [ 14.350340][ T258] RDX: 0000000000000000 RSI: 0000000000801000 RDI: 0000000000000000 [ 14.350341][ T258] RBP: 00007fa809965f70 R08: 0000000000000001 R09: 00007fa8099666c0 [ 14.350342][ T258] R10: 0000000000000008 R11: 0000000000000246 R12: 00007fa809166000 [ 14.350343][ T258] R13: 00007ffc79e354c0 R14: 0000000000000002 R15: 00007ffc79e355c7 [ 14.350349][ T258] [ 14.470320][ T262] ppp0: recursion detected [ 14.470351][ T262] [ 14.470352][ T262] ===================================================== [ 14.470353][ T262] WARNING: SOFTIRQ-safe -> SOFTIRQ-unsafe lock order detected [ 14.470356][ T262] 7.2.0-virtme #1 Tainted: G W [ 14.470357][ T262] ----------------------------------------------------- [ 14.470358][ T262] ping/262 [HC0[0]:SC0[10]:HE0:SE0] is trying to acquire: [ 14.470359][ T262] ffffffffb6c99660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 14.470369][ T262] [ 14.470369][ T262] and this task is already holding: [ 14.470370][ T262] ff110000059450d8 (slock-AF_INET/2){+.-.}-{3:3}, at: l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.470377][ T262] which would create a new lock dependency: [ 14.470378][ T262] (slock-AF_INET/2){+.-.}-{3:3} -> (console_owner){....}-{0:0} [ 14.470380][ T262] [ 14.470380][ T262] but this new dependency connects a SOFTIRQ-irq-safe lock: [ 14.470381][ T262] (slock-AF_INET/2){+.-.}-{3:3} [ 14.470382][ T262] [ 14.470382][ T262] ... which became SOFTIRQ-irq-safe at: [ 14.470384][ T262] __lock_acquire+0x388/0xc20 [ 14.470387][ T262] lock_acquire.part.0+0xd4/0x280 [ 14.470388][ T262] _raw_spin_lock_nested+0x3d/0x50 [ 14.470391][ T262] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.470394][ T262] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.470396][ T262] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.470398][ T262] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.470400][ T262] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.470402][ T262] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.470404][ T262] dev_hard_start_xmit+0xf4/0x620 [ 14.470407][ T262] __dev_queue_xmit+0xc0a/0x1b80 [ 14.470408][ T262] ip_finish_output2+0x5b9/0x1bc0 [ 14.470411][ T262] ip_output+0x1ca/0x660 [ 14.470413][ T262] ip_push_pending_frames+0xf6/0x250 [ 14.470415][ T262] icmp_reply+0x94d/0xa50 [ 14.470418][ T262] icmp_echo+0x2ab/0x4b0 [ 14.470419][ T262] icmp_rcv+0x92f/0xfb0 [ 14.470420][ T262] ip_protocol_deliver_rcu+0x82/0x350 [ 14.470422][ T262] ip_local_deliver_finish+0x36f/0x610 [ 14.470423][ T262] ip_local_deliver+0x184/0x4c0 [ 14.470425][ T262] ip_rcv+0xdc/0x3d0 [ 14.470426][ T262] __netif_receive_skb_one_core+0xfc/0x180 [ 14.470428][ T262] process_backlog+0x431/0x14c0 [ 14.470429][ T262] __napi_poll+0xa7/0x3b0 [ 14.470431][ T262] net_rx_action+0x513/0xf50 [ 14.470433][ T262] handle_softirqs+0x1d3/0x900 [ 14.470435][ T262] do_softirq+0xac/0xe0 [ 14.470437][ T262] __local_bh_enable_ip+0x118/0x150 [ 14.470438][ T262] __dev_queue_xmit+0x979/0x1b80 [ 14.470439][ T262] ip_finish_output2+0x5b9/0x1bc0 [ 14.470441][ T262] ip_output+0x1ca/0x660 [ 14.470442][ T262] ip_push_pending_frames+0xf6/0x250 [ 14.470444][ T262] raw_sendmsg+0x1169/0x1cc0 [ 14.470445][ T262] __sys_sendto+0x2aa/0x400 [ 14.470448][ T262] __x64_sys_sendto+0xe4/0x1f0 [ 14.470450][ T262] do_syscall_64+0xff/0x530 [ 14.470452][ T262] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.470455][ T262] [ 14.470455][ T262] to a SOFTIRQ-irq-unsafe lock: [ 14.470455][ T262] (fs_reclaim){+.+.}-{0:0} [ 14.470456][ T262] [ 14.470456][ T262] ... which became SOFTIRQ-irq-unsafe at: [ 14.470457][ T262] ... [ 14.470458][ T262] __lock_acquire+0x388/0xc20 [ 14.470459][ T262] lock_acquire.part.0+0xd4/0x280 [ 14.470460][ T262] fs_reclaim_acquire+0xd5/0x120 [ 14.470463][ T262] __kmalloc_cache_noprof+0x6e/0x620 [ 14.470465][ T262] kthread_create_worker_on_node+0xea/0x210 [ 14.470468][ T262] workqueue_init+0x2a/0x680 [ 14.470471][ T262] kernel_init_freeable+0x2fe/0x630 [ 14.470474][ T262] kernel_init+0x21/0x150 [ 14.470476][ T262] ret_from_fork+0x474/0x6b0 [ 14.470478][ T262] ret_from_fork_asm+0x11/0x20 [ 14.470481][ T262] [ 14.470481][ T262] other info that might help us debug this: [ 14.470481][ T262] [ 14.470482][ T262] Chain exists of: [ 14.470482][ T262] slock-AF_INET/2 --> console_owner --> fs_reclaim [ 14.470482][ T262] [ 14.470484][ T262] Possible interrupt unsafe locking scenario: [ 14.470484][ T262] [ 14.470484][ T262] CPU0 CPU1 [ 14.470485][ T262] ---- ---- [ 14.470485][ T262] lock(fs_reclaim); [ 14.470486][ T262] local_irq_disable(); [ 14.470487][ T262] lock(slock-AF_INET/2); [ 14.470488][ T262] lock(console_owner); [ 14.470488][ T262] [ 14.470489][ T262] lock(slock-AF_INET/2); [ 14.470490][ T262] [ 14.470490][ T262] *** DEADLOCK *** [ 14.470490][ T262] [ 14.470490][ T262] locks held by ping/262: 14, last CPU#2: [ 14.470492][ T262] #0: ff1100000591e290 (sk_lock-AF_INET){+.+.}-{0:0}, at: raw_sendmsg+0xba5/0x1cc0 [ 14.470495][ T262] #1: ffffffffb6d90b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 14.470498][ T262] #2: ffffffffb6d90b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 14.470501][ T262] #3: ffffffffb6d90aa0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x1fc/0x1b80 [ 14.470504][ T262] #4: ffd1ffffffd061b8 (&xmit_recursion->bh_lock){+.-.}-{3:3}, at: ppp_xmit_process+0x1d2/0x8e0 [ppp_generic] [ 14.470507][ T262] #5: ff1100000d042fd0 (&ppp->wlock){+.-.}-{3:3}, at: ppp_xmit_process+0x292/0x8e0 [ppp_generic] [ 14.470510][ T262] #6: ff1100000d2041d0 (&pch->downl){+.-.}-{3:3}, at: ppp_push+0xae/0x2d0 [ppp_generic] [ 14.470513][ T262] #7: ff110000059450d8 (slock-AF_INET/2){+.-.}-{3:3}, at: l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.470517][ T262] #8: ffffffffb6d90b00 (rcu_read_lock){....}-{1:3}, at: __ip_queue_xmit+0x63/0x1650 [ 14.470520][ T262] #9: ffffffffb6d90b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 14.470523][ T262] #10: ffffffffb6d90b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 14.470526][ T262] #11: ffffffffb6d90aa0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x1fc/0x1b80 [ 14.470529][ T262] #12: ffffffffb6d79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 14.470534][ T262] #13: ffffffffb6d79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 14.470537][ T262] [ 14.470537][ T262] the dependencies between SOFTIRQ-irq-safe lock and the holding lock: [ 14.470538][ T262] -> (slock-AF_INET/2){+.-.}-{3:3} { [ 14.470540][ T262] HARDIRQ-ON-W at: [ 14.470541][ T262] __lock_acquire+0x388/0xc20 [ 14.470543][ T262] lock_acquire.part.0+0xd4/0x280 [ 14.470544][ T262] _raw_spin_lock_nested+0x3d/0x50 [ 14.470545][ T262] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.470547][ T262] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.470549][ T262] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.470551][ T262] __ppp_channel_push+0xed/0x280 [ppp_generic] [ 14.470552][ T262] ppp_channel_push+0x269/0x7b0 [ppp_generic] [ 14.470554][ T262] ppp_write+0x26b/0x350 [ppp_generic] [ 14.470556][ T262] vfs_write+0x1a4/0xbd0 [ 14.470559][ T262] ksys_write+0x116/0x250 [ 14.470560][ T262] do_syscall_64+0xff/0x530 [ 14.470562][ T262] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.470563][ T262] IN-SOFTIRQ-W at: [ 14.470564][ T262] __lock_acquire+0x388/0xc20 [ 14.470565][ T262] lock_acquire.part.0+0xd4/0x280 [ 14.470566][ T262] _raw_spin_lock_nested+0x3d/0x50 [ 14.470567][ T262] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.470570][ T262] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.470572][ T262] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.470573][ T262] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.470574][ T262] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.470576][ T262] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.470578][ T262] dev_hard_start_xmit+0xf4/0x620 [ 14.470579][ T262] __dev_queue_xmit+0xc0a/0x1b80 [ 14.470581][ T262] ip_finish_output2+0x5b9/0x1bc0 [ 14.470582][ T262] ip_output+0x1ca/0x660 [ 14.470584][ T262] ip_push_pending_frames+0xf6/0x250 [ 14.470585][ T262] icmp_reply+0x94d/0xa50 [ 14.470587][ T262] icmp_echo+0x2ab/0x4b0 [ 14.470588][ T262] icmp_rcv+0x92f/0xfb0 [ 14.470590][ T262] ip_protocol_deliver_rcu+0x82/0x350 [ 14.470591][ T262] ip_local_deliver_finish+0x36f/0x610 [ 14.470592][ T262] ip_local_deliver+0x184/0x4c0 [ 14.470594][ T262] ip_rcv+0xdc/0x3d0 [ 14.470595][ T262] __netif_receive_skb_one_core+0xfc/0x180 [ 14.470597][ T262] process_backlog+0x431/0x14c0 [ 14.470598][ T262] __napi_poll+0xa7/0x3b0 [ 14.470600][ T262] net_rx_action+0x513/0xf50 [ 14.470602][ T262] handle_softirqs+0x1d3/0x900 [ 14.470603][ T262] do_softirq+0xac/0xe0 [ 14.470604][ T262] __local_bh_enable_ip+0x118/0x150 [ 14.470605][ T262] __dev_queue_xmit+0x979/0x1b80 [ 14.470607][ T262] ip_finish_output2+0x5b9/0x1bc0 [ 14.470608][ T262] ip_output+0x1ca/0x660 [ 14.470609][ T262] ip_push_pending_frames+0xf6/0x250 [ 14.470611][ T262] raw_sendmsg+0x1169/0x1cc0 [ 14.470612][ T262] __sys_sendto+0x2aa/0x400 [ 14.470614][ T262] __x64_sys_sendto+0xe4/0x1f0 [ 14.470615][ T262] do_syscall_64+0xff/0x530 [ 14.470616][ T262] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.470618][ T262] INITIAL USE at: [ 14.470618][ T262] __lock_acquire+0x388/0xc20 [ 14.470620][ T262] lock_acquire.part.0+0xd4/0x280 [ 14.470621][ T262] _raw_spin_lock_nested+0x3d/0x50 [ 14.470622][ T262] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.470624][ T262] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.470626][ T262] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.470627][ T262] __ppp_channel_push+0xed/0x280 [ppp_generic] [ 14.470629][ T262] ppp_channel_push+0x269/0x7b0 [ppp_generic] [ 14.470630][ T262] ppp_write+0x26b/0x350 [ppp_generic] [ 14.470632][ T262] vfs_write+0x1a4/0xbd0 [ 14.470633][ T262] ksys_write+0x116/0x250 [ 14.470635][ T262] do_syscall_64+0xff/0x530 [ 14.470636][ T262] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.470637][ T262] } [ 14.470638][ T262] ... key at: [] af_family_slock_keys+0x22/0x300 [ 14.470641][ T262] [ 14.470641][ T262] the dependencies between the lock to be acquired [ 14.470642][ T262] and SOFTIRQ-irq-unsafe lock: [ 14.470647][ T262] -> (fs_reclaim){+.+.}-{0:0} { [ 14.470648][ T262] HARDIRQ-ON-W at: [ 14.470649][ T262] __lock_acquire+0x388/0xc20 [ 14.470650][ T262] lock_acquire.part.0+0xd4/0x280 [ 14.470652][ T262] fs_reclaim_acquire+0xd5/0x120 [ 14.470653][ T262] __kmalloc_cache_noprof+0x6e/0x620 [ 14.470654][ T262] kthread_create_worker_on_node+0xea/0x210 [ 14.470656][ T262] workqueue_init+0x2a/0x680 [ 14.470657][ T262] kernel_init_freeable+0x2fe/0x630 [ 14.470658][ T262] kernel_init+0x21/0x150 [ 14.470659][ T262] ret_from_fork+0x474/0x6b0 [ 14.470661][ T262] ret_from_fork_asm+0x11/0x20 [ 14.470662][ T262] SOFTIRQ-ON-W at: [ 14.470662][ T262] __lock_acquire+0x388/0xc20 [ 14.470664][ T262] lock_acquire.part.0+0xd4/0x280 [ 14.470665][ T262] fs_reclaim_acquire+0xd5/0x120 [ 14.470666][ T262] __kmalloc_cache_noprof+0x6e/0x620 [ 14.470667][ T262] kthread_create_worker_on_node+0xea/0x210 [ 14.470669][ T262] workqueue_init+0x2a/0x680 [ 14.470670][ T262] kernel_init_freeable+0x2fe/0x630 [ 14.470671][ T262] kernel_init+0x21/0x150 [ 14.470673][ T262] ret_from_fork+0x474/0x6b0 [ 14.470674][ T262] ret_from_fork_asm+0x11/0x20 [ 14.470675][ T262] INITIAL USE at: [ 14.470675][ T262] __lock_acquire+0x388/0xc20 [ 14.470677][ T262] lock_acquire.part.0+0xd4/0x280 [ 14.470678][ T262] fs_reclaim_acquire+0xd5/0x120 [ 14.470679][ T262] __kmalloc_cache_noprof+0x6e/0x620 [ 14.470680][ T262] kthread_create_worker_on_node+0xea/0x210 [ 14.470682][ T262] workqueue_init+0x2a/0x680 [ 14.470683][ T262] kernel_init_freeable+0x2fe/0x630 [ 14.470684][ T262] kernel_init+0x21/0x150 [ 14.470685][ T262] ret_from_fork+0x474/0x6b0 [ 14.470687][ T262] ret_from_fork_asm+0x11/0x20 [ 14.470688][ T262] } [ 14.470688][ T262] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 14.470691][ T262] ... acquired at: [ 14.470692][ T262] __lock_acquire+0x518/0xc20 [ 14.470693][ T262] lock_acquire.part.0+0xd4/0x280 [ 14.470695][ T262] fs_reclaim_acquire+0xd5/0x120 [ 14.470696][ T262] __kmalloc_noprof+0xd3/0x760 [ 14.470697][ T262] alloc_buf.isra.0+0x4b/0x260 [ 14.470700][ T262] put_chars+0x1e1/0x2f0 [ 14.470702][ T262] hvc_console_print+0x292/0x780 [ 14.470704][ T262] console_emit_next_record+0x22f/0x4c0 [ 14.470706][ T262] console_flush_one_record+0x46f/0x710 [ 14.470707][ T262] console_unlock+0xee/0x1f0 [ 14.470709][ T262] vprintk_emit+0x37c/0x3e0 [ 14.470710][ T262] _printk+0xc7/0x100 [ 14.470713][ T262] dm_interface_init+0x50/0x60 [ 14.470716][ T262] dm_init+0x51/0xd0 [ 14.470717][ T262] do_one_initcall+0x124/0x4f0 [ 14.470719][ T262] kernel_init_freeable+0x596/0x630 [ 14.470721][ T262] kernel_init+0x21/0x150 [ 14.470722][ T262] ret_from_fork+0x474/0x6b0 [ 14.470723][ T262] ret_from_fork_asm+0x11/0x20 [ 14.470724][ T262] [ 14.470725][ T262] -> (console_owner){....}-{0:0} { [ 14.470726][ T262] INITIAL USE at: [ 14.470727][ T262] } [ 14.470727][ T262] ... key at: [] console_owner_dep_map+0x0/0x60 [ 14.470731][ T262] ... acquired at: [ 14.470731][ T262] check_prev_add+0xef/0xe90 [ 14.470732][ T262] validate_chain+0x6b8/0xc20 [ 14.470733][ T262] __lock_acquire+0x518/0xc20 [ 14.470735][ T262] lock_acquire.part.0+0xd4/0x280 [ 14.470736][ T262] console_lock_spinning_enable+0x5c/0x60 [ 14.470738][ T262] console_emit_next_record+0x1d1/0x4c0 [ 14.470739][ T262] console_flush_one_record+0x46f/0x710 [ 14.470741][ T262] console_unlock+0xee/0x1f0 [ 14.470742][ T262] vprintk_emit+0x37c/0x3e0 [ 14.470743][ T262] _printk+0xc7/0x100 [ 14.470745][ T262] netdev_err+0xe2/0x120 [ 14.470747][ T262] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 14.470749][ T262] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.470751][ T262] dev_hard_start_xmit+0xf4/0x620 [ 14.470752][ T262] __dev_queue_xmit+0xc0a/0x1b80 [ 14.470754][ T262] ip_finish_output2+0x5b9/0x1bc0 [ 14.470755][ T262] ip_output+0x1ca/0x660 [ 14.470757][ T262] __ip_queue_xmit+0x774/0x1650 [ 14.470758][ T262] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 14.470760][ T262] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.470762][ T262] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.470763][ T262] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.470765][ T262] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.470766][ T262] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.470768][ T262] dev_hard_start_xmit+0xf4/0x620 [ 14.470769][ T262] __dev_queue_xmit+0xc0a/0x1b80 [ 14.470771][ T262] ip_finish_output2+0x5b9/0x1bc0 [ 14.470772][ T262] ip_output+0x1ca/0x660 [ 14.470774][ T262] ip_push_pending_frames+0xf6/0x250 [ 14.470775][ T262] raw_sendmsg+0x1169/0x1cc0 [ 14.470776][ T262] __sys_sendto+0x2aa/0x400 [ 14.470778][ T262] __x64_sys_sendto+0xe4/0x1f0 [ 14.470779][ T262] do_syscall_64+0xff/0x530 [ 14.470780][ T262] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.470782][ T262] [ 14.470782][ T262] [ 14.470782][ T262] stack backtrace: [ 14.470784][ T262] CPU: 2 UID: 0 PID: 262 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 14.470787][ T262] Tainted: [W]=WARN [ 14.470787][ T262] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.470789][ T262] Call Trace: [ 14.470790][ T262] [ 14.470791][ T262] dump_stack_lvl+0x6f/0xa0 [ 14.470794][ T262] print_bad_irq_dependency+0x3e4/0x410 [ 14.470797][ T262] check_irq_usage+0x403/0x570 [ 14.470799][ T262] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.470803][ T262] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.470805][ T262] ? check_path.constprop.0+0x24/0x50 [ 14.470808][ T262] ? check_prev_add+0xef/0xe90 [ 14.470809][ T262] check_prev_add+0xef/0xe90 [ 14.470811][ T262] validate_chain+0x6b8/0xc20 [ 14.470813][ T262] __lock_acquire+0x518/0xc20 [ 14.470815][ T262] lock_acquire.part.0+0xd4/0x280 [ 14.470817][ T262] ? console_lock_spinning_enable+0x40/0x60 [ 14.470819][ T262] ? rcu_is_watching+0x16/0xd0 [ 14.470821][ T262] ? lock_acquire+0x13c/0x160 [ 14.470822][ T262] console_lock_spinning_enable+0x5c/0x60 [ 14.470824][ T262] ? console_lock_spinning_enable+0x40/0x60 [ 14.470826][ T262] console_emit_next_record+0x1d1/0x4c0 [ 14.470828][ T262] ? devkmsg_read+0x4b0/0x4b0 [ 14.470829][ T262] ? console_flush_one_record+0x106/0x710 [ 14.470831][ T262] ? rcu_is_watching+0x16/0xd0 [ 14.470833][ T262] ? lock_acquire+0x13c/0x160 [ 14.470835][ T262] console_flush_one_record+0x46f/0x710 [ 14.470837][ T262] ? console_emit_next_record+0x4c0/0x4c0 [ 14.470839][ T262] ? __lock_acquire+0x518/0xc20 [ 14.470841][ T262] console_unlock+0xee/0x1f0 [ 14.470843][ T262] ? console_flush_one_record+0x710/0x710 [ 14.470844][ T262] ? rcu_is_watching+0x16/0xd0 [ 14.470846][ T262] ? lock_acquire+0x60/0x160 [ 14.470848][ T262] ? __down_trylock_console_sem+0x5e/0xa0 [ 14.470849][ T262] ? vprintk_emit+0x320/0x3e0 [ 14.470851][ T262] vprintk_emit+0x37c/0x3e0 [ 14.470853][ T262] ? wake_up_klogd_work_func+0x90/0x90 [ 14.470855][ T262] ? mark_lock+0x3bb/0xa00 [ 14.470857][ T262] _printk+0xc7/0x100 [ 14.470859][ T262] ? snapshot_read.cold+0x21/0x21 [ 14.470860][ T262] ? lock_acquire.part.0+0xd4/0x280 [ 14.470862][ T262] ? find_held_lock+0x2b/0x80 [ 14.470863][ T262] ? netdev_name+0x43/0x60 [ 14.470864][ T262] ? __netdev_printk+0x17f/0x1d0 [ 14.470866][ T262] netdev_err+0xe2/0x120 [ 14.470867][ T262] ? netdev_crit+0x120/0x120 [ 14.470869][ T262] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 14.470870][ T262] ? ___ratelimit+0x4e7/0xa00 [ 14.470873][ T262] ? idr_get_free+0x830/0x830 [ 14.470875][ T262] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 14.470877][ T262] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 14.470879][ T262] ? dst_release+0x54/0x240 [ 14.470882][ T262] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.470884][ T262] ? dev_nit_active_rcu+0x1f/0xf0 [ 14.470886][ T262] ? rcu_is_watching+0x16/0xd0 [ 14.470888][ T262] dev_hard_start_xmit+0xf4/0x620 [ 14.470890][ T262] ? rcu_is_watching+0x16/0xd0 [ 14.470892][ T262] __dev_queue_xmit+0xc0a/0x1b80 [ 14.470894][ T262] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 14.470896][ T262] ? lockdep_hardirqs_on+0x91/0x130 [ 14.470897][ T262] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 14.470899][ T262] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 14.470900][ T262] ? ref_tracker_dir_print+0x100/0x100 [ 14.470904][ T262] ? dst_init+0x84/0x530 [ 14.470905][ T262] ? dst_alloc+0x97/0x160 [ 14.470906][ T262] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 14.470908][ T262] ? ip_route_output_flow+0x21/0x150 [ 14.470909][ T262] ? __ip_queue_xmit+0xdc7/0x1650 [ 14.470911][ T262] ? __lock_acquire+0x518/0xc20 [ 14.470912][ T262] ? down_read_nested+0xe7/0x500 [ 14.470914][ T262] ? lock_acquire.part.0+0xd4/0x280 [ 14.470915][ T262] ? ip_finish_output2+0x25c/0x1bc0 [ 14.470917][ T262] ? rcu_is_watching+0x16/0xd0 [ 14.470919][ T262] ip_finish_output2+0x5b9/0x1bc0 [ 14.470921][ T262] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 14.470923][ T262] ? lock_acquire.part.0+0xd4/0x280 [ 14.470924][ T262] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 14.470927][ T262] ip_output+0x1ca/0x660 [ 14.470929][ T262] ? ip_finish_output+0x460/0x460 [ 14.470930][ T262] ? find_held_lock+0x2b/0x80 [ 14.470932][ T262] ? __lock_release.isra.0+0x69/0x1a0 [ 14.470933][ T262] ? ip4_dst_hoplimit+0x15b/0x320 [ 14.470935][ T262] __ip_queue_xmit+0x774/0x1650 [ 14.470938][ T262] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 14.470941][ T262] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.470944][ T262] ? l2tp_xmit_core.constprop.0+0x19b0/0x19b0 [l2tp_core] [ 14.470947][ T262] ? do_raw_spin_lock+0x131/0x280 [ 14.470949][ T262] ? pppol2tp_xmit+0x423/0x690 [l2tp_ppp] [ 14.470950][ T262] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.470952][ T262] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.470954][ T262] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.470956][ T262] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 14.470958][ T262] ? dst_release+0x54/0x240 [ 14.470960][ T262] ? down_read_nested+0x36a/0x500 [ 14.470961][ T262] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.470963][ T262] ? dev_nit_active_rcu+0x1f/0xf0 [ 14.470965][ T262] ? rcu_is_watching+0x16/0xd0 [ 14.470967][ T262] dev_hard_start_xmit+0xf4/0x620 [ 14.470969][ T262] ? rcu_is_watching+0x16/0xd0 [ 14.470971][ T262] __dev_queue_xmit+0xc0a/0x1b80 [ 14.470973][ T262] ? sock_queue_err_skb+0x680/0x680 [ 14.470975][ T262] ? kmem_cache_alloc_node_noprof+0x2a6/0x660 [ 14.470977][ T262] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.470979][ T262] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.470980][ T262] ? validate_chain+0x38b/0xc20 [ 14.470982][ T262] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 14.470983][ T262] ? __lock_acquire+0x518/0xc20 [ 14.470985][ T262] ? down_read_nested+0xe7/0x500 [ 14.470986][ T262] ? lock_acquire.part.0+0xd4/0x280 [ 14.470988][ T262] ? ip_finish_output2+0x25c/0x1bc0 [ 14.470990][ T262] ? rcu_is_watching+0x16/0xd0 [ 14.470992][ T262] ip_finish_output2+0x5b9/0x1bc0 [ 14.470994][ T262] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 14.470995][ T262] ? lock_acquire.part.0+0xd4/0x280 [ 14.470997][ T262] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 14.470998][ T262] ? rcu_is_watching+0x16/0xd0 [ 14.471001][ T262] ip_output+0x1ca/0x660 [ 14.471003][ T262] ? ip_finish_output+0x460/0x460 [ 14.471004][ T262] ? __ip_make_skb+0x106a/0x2300 [ 14.471007][ T262] ip_push_pending_frames+0xf6/0x250 [ 14.471009][ T262] raw_sendmsg+0x1169/0x1cc0 [ 14.471011][ T262] ? raw_send_hdrinc+0x1740/0x1740 [ 14.471012][ T262] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.471016][ T262] ? __might_fault+0x97/0x140 [ 14.471019][ T262] ? __might_fault+0x97/0x140 [ 14.471021][ T262] ? __might_fault+0x97/0x140 [ 14.471023][ T262] __sys_sendto+0x2aa/0x400 [ 14.471025][ T262] ? __ia32_sys_getpeername+0xd0/0xd0 [ 14.471032][ T262] ? exc_page_fault+0x87/0x100 [ 14.471034][ T262] __x64_sys_sendto+0xe4/0x1f0 [ 14.471036][ T262] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 14.471038][ T262] ? lockdep_hardirqs_on+0x91/0x130 [ 14.471040][ T262] ? do_syscall_64+0xa6/0x530 [ 14.471041][ T262] do_syscall_64+0xff/0x530 [ 14.471043][ T262] ? exc_page_fault+0xee/0x100 [ 14.471044][ T262] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.471046][ T262] RIP: 0033:0x7f8ec984a54e [ 14.471048][ T262] 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 [ 14.471050][ T262] RSP: 002b:00007fff2922e480 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 14.471052][ T262] RAX: ffffffffffffffda RBX: 0000000000000040 RCX: 00007f8ec984a54e [ 14.471053][ T262] RDX: 0000000000000040 RSI: 000055a816bc40a4 RDI: 0000000000000005 [ 14.471054][ T262] RBP: 00007fff2922e490 R08: 000055a816bd6320 R09: 0000000000000010 [ 14.471055][ T262] R10: 0000000000000000 R11: 0000000000000202 R12: 431bde82d7b634db [ 14.471056][ T262] R13: 0000000000000000 R14: 000055a816bd73e0 R15: 000055a816bc40a0 [ 14.471058][ T262] [ 14.699642][ C3] ppp0: recursion detected [ 15.474955][ T262] ping (262) used greatest stack depth: 22536 bytes left [ 15.474962][ T262] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 15.474965][ T262] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 262, name: ping [ 15.474966][ T262] preempt_count: 2, expected: 0 [ 15.474967][ T262] RCU nest depth: 0, expected: 0 [ 15.474968][ T262] INFO: lockdep is turned off. [ 15.474969][ T262] irq event stamp: 30699 [ 15.474969][ T262] hardirqs last enabled at (30698): [] __down_trylock_console_sem+0x86/0xa0 [ 15.474977][ T262] hardirqs last disabled at (30699): [] console_emit_next_record+0x3d4/0x4c0 [ 15.474979][ T262] softirqs last enabled at (30594): [] __alloc_skb+0x4c2/0x5f0 [ 15.474983][ T262] softirqs last disabled at (30610): [] __dev_queue_xmit+0x1fc/0x1b80 [ 15.474986][ T262] Preemption disabled at: [ 15.474987][ T262] [<0000000000000000>] 0x0 [ 15.474992][ T262] CPU: 2 UID: 0 PID: 262 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.474996][ T262] Tainted: [W]=WARN [ 15.474997][ T262] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.474998][ T262] Call Trace: [ 15.475000][ T262] [ 15.475001][ T262] dump_stack_lvl+0x6f/0xa0 [ 15.475006][ T262] __might_resched.cold+0x1fe/0x2c1 [ 15.475010][ T262] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 15.475013][ T262] ? __asan_memcpy+0x3c/0x60 [ 15.475016][ T262] ? rcu_is_watching+0x16/0xd0 [ 15.475020][ T262] __kmalloc_noprof+0x443/0x760 [ 15.475023][ T262] ? __rwlock_init+0x150/0x150 [ 15.475025][ T262] ? alloc_buf.isra.0+0x4b/0x260 [ 15.475029][ T262] ? do_raw_spin_unlock+0x59/0x250 [ 15.475031][ T262] alloc_buf.isra.0+0x4b/0x260 [ 15.475036][ T262] put_chars+0x1e1/0x2f0 [ 15.475038][ T262] ? __send_to_port+0x420/0x420 [ 15.475040][ T262] ? console_prepend_replay+0x20/0x20 [ 15.475043][ T262] hvc_console_print+0x292/0x780 [ 15.475046][ T262] ? hvc_write+0x3a0/0x3a0 [ 15.475048][ T262] ? rcu_is_watching+0x16/0xd0 [ 15.475049][ T262] ? lock_acquire+0x13c/0x160 [ 15.475052][ T262] console_emit_next_record+0x22f/0x4c0 [ 15.475054][ T262] ? devkmsg_read+0x4b0/0x4b0 [ 15.475056][ T262] ? rcu_is_watching+0x16/0xd0 [ 15.475058][ T262] ? rcu_is_watching+0x16/0xd0 [ 15.475059][ T262] ? rcu_is_watching+0x16/0xd0 [ 15.475061][ T262] ? lock_acquire+0x13c/0x160 [ 15.475062][ T262] ? console_flush_one_record+0x111/0x710 [ 15.475064][ T262] console_flush_one_record+0x46f/0x710 [ 15.475066][ T262] ? console_emit_next_record+0x4c0/0x4c0 [ 15.475069][ T262] console_unlock+0xee/0x1f0 [ 15.475070][ T262] ? lock_acquire+0x13c/0x160 [ 15.475072][ T262] ? console_flush_one_record+0x710/0x710 [ 15.475073][ T262] ? rcu_is_watching+0x16/0xd0 [ 15.475075][ T262] ? lock_release+0x184/0x1f0 [ 15.475076][ T262] ? lock_acquire+0x60/0x160 [ 15.475078][ T262] ? __rcu_read_lock+0x30/0x70 [ 15.475080][ T262] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.475081][ T262] ? vprintk_emit+0x320/0x3e0 [ 15.475083][ T262] vprintk_emit+0x37c/0x3e0 [ 15.475085][ T262] ? wake_up_klogd_work_func+0x90/0x90 [ 15.475088][ T262] ? do_raw_read_unlock+0x70/0x70 [ 15.475089][ T262] ? forget_original_parent+0x5c/0xdb0 [ 15.475093][ T262] _printk+0xc7/0x100 [ 15.475095][ T262] ? snapshot_read.cold+0x21/0x21 [ 15.475097][ T262] ? do_raw_spin_lock+0x131/0x280 [ 15.475099][ T262] ? do_raw_spin_lock+0x131/0x280 [ 15.475100][ T262] ? __rwlock_init+0x150/0x150 [ 15.475102][ T262] ? do_raw_spin_lock+0x131/0x280 [ 15.475104][ T262] do_exit.cold+0x82/0x9c [ 15.475107][ T262] ? exit_notify+0x890/0x890 [ 15.475108][ T262] ? posixtimer_sig_unignore.isra.0+0x5f0/0x5f0 [ 15.475110][ T262] ? rcu_is_watching+0x16/0xd0 [ 15.475112][ T262] do_group_exit+0xb8/0x370 [ 15.475114][ T262] __x64_sys_exit_group+0x3c/0x50 [ 15.475115][ T262] x64_sys_call+0x1567/0x1570 [ 15.475118][ T262] do_syscall_64+0xff/0x530 [ 15.475121][ T262] ? exc_page_fault+0xee/0x100 [ 15.475123][ T262] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.475125][ T262] RIP: 0033:0x7f8ec989b1b8 [ 15.475127][ T262] Code: Unable to access opcode bytes at 0x7f8ec989b18e. [ 15.475128][ T262] RSP: 002b:00007fff2922fc18 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 15.475130][ T262] RAX: ffffffffffffffda RBX: 00007f8ec99cbf88 RCX: 00007f8ec989b1b8 [ 15.475132][ T262] RDX: 00007f8ec962c7c8 RSI: ffffffffffffffb0 RDI: 0000000000000001 [ 15.475132][ T262] RBP: 00007fff2922fc70 R08: 0000000000000000 R09: 0000000000001010 [ 15.475133][ T262] R10: 00007fff2922fa90 R11: 0000000000000246 R12: 0000000000000002 [ 15.475134][ T262] R13: 0000000000000001 R14: 00007f8ec99ca680 R15: 00007f8ec99cbfa0 [ 15.475136][ T262] [ 15.505940][ T221] ppp0: recursion detected