[ 9.322218][ T177] ip (177) used greatest stack depth: 23864 bytes left [ 9.322235][ T177] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.322237][ T177] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 177, name: ip [ 9.322239][ T177] preempt_count: 2, expected: 0 [ 9.322239][ T177] RCU nest depth: 0, expected: 0 [ 9.322240][ T177] locks held by ip/177: 5, last CPU#0: [ 9.322243][ T177] #0: ffffffff8d6127b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 9.322254][ T177] #1: ffffffff8d779cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.322260][ T177] #2: ffffffff8d779d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.322264][ T177] #3: ffffffff8d699660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.322268][ T177] #4: ffffffff8d699560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.322272][ T177] irq event stamp: 21974 [ 9.322273][ T177] hardirqs last enabled at (21973): [] __down_trylock_console_sem+0x86/0xa0 [ 9.322275][ T177] hardirqs last disabled at (21974): [] console_emit_next_record+0x3d4/0x4c0 [ 9.322277][ T177] softirqs last enabled at (20418): [] handle_softirqs+0x67c/0x900 [ 9.322279][ T177] softirqs last disabled at (20159): [] __irq_exit_rcu+0x145/0x1c0 [ 9.322281][ T177] Preemption disabled at: [ 9.322282][ T177] [<0000000000000000>] 0x0 [ 9.322289][ T177] CPU: 0 UID: 0 PID: 177 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.322292][ T177] Tainted: [W]=WARN [ 9.322293][ T177] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.322299][ T177] Call Trace: [ 9.322301][ T177] [ 9.322303][ T177] dump_stack_lvl+0x6f/0xa0 [ 9.322309][ T177] __might_resched.cold+0x1fe/0x2c1 [ 9.322314][ T177] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.322318][ T177] ? __kmalloc_noprof+0xdb/0x760 [ 9.322323][ T177] __kmalloc_noprof+0x443/0x760 [ 9.322325][ T177] ? alloc_buf.isra.0+0x4b/0x260 [ 9.322331][ T177] ? do_raw_spin_unlock+0x59/0x250 [ 9.322334][ T177] alloc_buf.isra.0+0x4b/0x260 [ 9.322337][ T177] put_chars+0x1e1/0x2f0 [ 9.322340][ T177] ? __send_to_port+0x420/0x420 [ 9.322341][ T177] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.322346][ T177] ? validate_chain+0x38b/0xc20 [ 9.322351][ T177] hvc_console_print+0x292/0x780 [ 9.322357][ T177] ? hvc_write+0x3a0/0x3a0 [ 9.322360][ T177] ? rcu_is_watching+0x16/0xd0 [ 9.322362][ T177] ? lock_acquire+0x13c/0x160 [ 9.322366][ T177] console_emit_next_record+0x22f/0x4c0 [ 9.322370][ T177] ? devkmsg_read+0x4b0/0x4b0 [ 9.322372][ T177] ? console_flush_one_record+0x106/0x710 [ 9.322375][ T177] ? rcu_is_watching+0x16/0xd0 [ 9.322377][ T177] ? lock_acquire+0x13c/0x160 [ 9.322381][ T177] console_flush_one_record+0x46f/0x710 [ 9.322385][ T177] ? console_emit_next_record+0x4c0/0x4c0 [ 9.322387][ T177] ? __lock_acquire+0x518/0xc20 [ 9.322392][ T177] console_unlock+0xee/0x1f0 [ 9.322394][ T177] ? console_flush_one_record+0x710/0x710 [ 9.322396][ T177] ? rcu_is_watching+0x16/0xd0 [ 9.322399][ T177] ? lock_acquire+0x60/0x160 [ 9.322402][ T177] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.322404][ T177] ? vprintk_emit+0x320/0x3e0 [ 9.322407][ T177] vprintk_emit+0x37c/0x3e0 [ 9.322411][ T177] ? wake_up_klogd_work_func+0x90/0x90 [ 9.322414][ T177] ? __lock_acquire+0x518/0xc20 [ 9.322418][ T177] _printk+0xc7/0x100 [ 9.322421][ T177] ? snapshot_read.cold+0x21/0x21 [ 9.322424][ T177] ? do_raw_spin_lock+0x131/0x280 [ 9.322426][ T177] ? __rwlock_init+0x150/0x150 [ 9.322430][ T177] ? do_raw_spin_lock+0x131/0x280 [ 9.322433][ T177] do_exit.cold+0x82/0x9c [ 9.322436][ T177] ? exit_notify+0x890/0x890 [ 9.322437][ T177] ? __lock_release.isra.0+0x69/0x1a0 [ 9.322440][ T177] ? rcu_is_watching+0x16/0xd0 [ 9.322444][ T177] do_group_exit+0xb8/0x370 [ 9.322447][ T177] __x64_sys_exit_group+0x3c/0x50 [ 9.322449][ T177] x64_sys_call+0x1567/0x1570 [ 9.322451][ T177] do_syscall_64+0xff/0x530 [ 9.322455][ T177] ? exc_page_fault+0xee/0x100 [ 9.322458][ T177] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.322460][ T177] RIP: 0033:0x7f03251a91b8 [ 9.322462][ T177] Code: Unable to access opcode bytes at 0x7f03251a918e. [ 9.322464][ T177] RSP: 002b:00007ffe6e868c08 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 9.322466][ T177] RAX: ffffffffffffffda RBX: 00007f03252d9f88 RCX: 00007f03251a91b8 [ 9.322467][ T177] RDX: 00007f0324ef3fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 9.322468][ T177] RBP: 00007ffe6e868c60 R08: 0000000000000000 R09: 0000000000000050 [ 9.322469][ T177] R10: 00007ffe6e868a20 R11: 0000000000000246 R12: 0000000000000001 [ 9.322470][ T177] R13: 0000000000000000 R14: 00007f03252d8680 R15: 00007f03252d9fa0 [ 9.322477][ T177] [ 9.483712][ T180] PPP generic driver version 2.4.2 [ 9.491695][ T180] NET: Registered PF_PPPOX protocol family [ 9.520399][ T180] l2tp_core: L2TP core driver, V2.0 [ 9.526497][ T180] l2tp_ppp: PPPoL2TP kernel driver, V2.0 [ 9.584215][ T182] ip (182) used greatest stack depth: 23824 bytes left [ 14.720014][ T257] iperf3 (257) used greatest stack depth: 22864 bytes left [ 14.720035][ T257] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 14.720037][ T257] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 257, name: iperf3 [ 14.720039][ T257] preempt_count: 2, expected: 0 [ 14.720040][ T257] RCU nest depth: 0, expected: 0 [ 14.720041][ T257] locks held by iperf3/257: 5, last CPU#0: [ 14.720042][ T257] #0: ffffffff8d6127b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 14.720053][ T257] #1: ffffffff8d779cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 14.720059][ T257] #2: ffffffff8d779d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 14.720063][ T257] #3: ffffffff8d699660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 14.720067][ T257] #4: ffffffff8d699560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 14.720071][ T257] irq event stamp: 1530094 [ 14.720072][ T257] hardirqs last enabled at (1530093): [] __down_trylock_console_sem+0x86/0xa0 [ 14.720074][ T257] hardirqs last disabled at (1530094): [] console_emit_next_record+0x3d4/0x4c0 [ 14.720076][ T257] softirqs last enabled at (1529562): [] handle_softirqs+0x67c/0x900 [ 14.720078][ T257] softirqs last disabled at (1529293): [] __irq_exit_rcu+0x145/0x1c0 [ 14.720080][ T257] Preemption disabled at: [ 14.720081][ T257] [<0000000000000000>] 0x0 [ 14.720088][ T257] CPU: 0 UID: 0 PID: 257 Comm: iperf3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 14.720092][ T257] Tainted: [W]=WARN [ 14.720092][ T257] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.720094][ T257] Call Trace: [ 14.720096][ T257] [ 14.720097][ T257] dump_stack_lvl+0x6f/0xa0 [ 14.720103][ T257] __might_resched.cold+0x1fe/0x2c1 [ 14.720108][ T257] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 14.720112][ T257] ? __kmalloc_noprof+0xdb/0x760 [ 14.720117][ T257] __kmalloc_noprof+0x443/0x760 [ 14.720119][ T257] ? alloc_buf.isra.0+0x4b/0x260 [ 14.720125][ T257] ? do_raw_spin_unlock+0x59/0x250 [ 14.720128][ T257] alloc_buf.isra.0+0x4b/0x260 [ 14.720131][ T257] put_chars+0x1e1/0x2f0 [ 14.720133][ T257] ? prb_final_commit+0x50/0x50 [ 14.720135][ T257] ? __send_to_port+0x420/0x420 [ 14.720138][ T257] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.720143][ T257] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.720145][ T257] ? validate_chain+0x38b/0xc20 [ 14.720148][ T257] hvc_console_print+0x292/0x780 [ 14.720152][ T257] ? mark_usage+0x61/0x170 [ 14.720154][ T257] ? __lock_acquire+0x518/0xc20 [ 14.720155][ T257] ? __lock_acquire+0x518/0xc20 [ 14.720159][ T257] ? hvc_write+0x3a0/0x3a0 [ 14.720161][ T257] ? console_emit_next_record+0x1df/0x4c0 [ 14.720164][ T257] ? rcu_is_watching+0x16/0xd0 [ 14.720167][ T257] ? lock_acquire+0x13c/0x160 [ 14.720170][ T257] console_emit_next_record+0x22f/0x4c0 [ 14.720175][ T257] ? devkmsg_read+0x4b0/0x4b0 [ 14.720177][ T257] ? console_flush_one_record+0x106/0x710 [ 14.720180][ T257] ? rcu_is_watching+0x16/0xd0 [ 14.720182][ T257] ? lock_acquire+0x13c/0x160 [ 14.720186][ T257] console_flush_one_record+0x46f/0x710 [ 14.720190][ T257] ? console_emit_next_record+0x4c0/0x4c0 [ 14.720192][ T257] ? __lock_acquire+0x518/0xc20 [ 14.720197][ T257] console_unlock+0xee/0x1f0 [ 14.720199][ T257] ? console_flush_one_record+0x710/0x710 [ 14.720201][ T257] ? rcu_is_watching+0x16/0xd0 [ 14.720204][ T257] ? lock_acquire+0x60/0x160 [ 14.720207][ T257] ? __down_trylock_console_sem+0x5e/0xa0 [ 14.720209][ T257] ? vprintk_emit+0x320/0x3e0 [ 14.720212][ T257] vprintk_emit+0x37c/0x3e0 [ 14.720216][ T257] ? wake_up_klogd_work_func+0x90/0x90 [ 14.720219][ T257] ? __lock_acquire+0x518/0xc20 [ 14.720222][ T257] _printk+0xc7/0x100 [ 14.720226][ T257] ? snapshot_read.cold+0x21/0x21 [ 14.720229][ T257] ? do_raw_spin_lock+0x131/0x280 [ 14.720231][ T257] ? __rwlock_init+0x150/0x150 [ 14.720235][ T257] ? do_raw_spin_lock+0x131/0x280 [ 14.720237][ T257] do_exit.cold+0x82/0x9c [ 14.720241][ T257] ? exit_notify+0x890/0x890 [ 14.720246][ T257] __x64_sys_exit+0x42/0x50 [ 14.720248][ T257] x64_sys_call+0x156c/0x1570 [ 14.720250][ T257] do_syscall_64+0xff/0x530 [ 14.720254][ T257] ? exc_page_fault+0xee/0x100 [ 14.720257][ T257] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.720259][ T257] RIP: 0033:0x7f8c24dd4c49 [ 14.720261][ T257] Code: Unable to access opcode bytes at 0x7f8c24dd4c1f. [ 14.720262][ T257] RSP: 002b:00007f8c24593e60 EFLAGS: 00000246 ORIG_RAX: 000000000000003c [ 14.720265][ T257] RAX: ffffffffffffffda RBX: 00007f8c245946c0 RCX: 00007f8c24dd4c49 [ 14.720266][ T257] RDX: 0000000000000000 RSI: 0000000000801000 RDI: 0000000000000000 [ 14.720267][ T257] RBP: 00007f8c24593f70 R08: 0000000000000001 R09: 00007f8c245946c0 [ 14.720268][ T257] R10: 0000000000000008 R11: 0000000000000246 R12: 00007f8c23d94000 [ 14.720269][ T257] R13: 00007ffdeac4a710 R14: 0000000000000002 R15: 00007ffdeac4a817 [ 14.720275][ T257] [ 14.863413][ T261] ppp0: recursion detected [ 14.863462][ T261] [ 14.863463][ T261] ===================================================== [ 14.863464][ T261] WARNING: SOFTIRQ-safe -> SOFTIRQ-unsafe lock order detected [ 14.863467][ T261] 7.2.0-virtme #1 Tainted: G W [ 14.863469][ T261] ----------------------------------------------------- [ 14.863469][ T261] ping/261 [HC0[0]:SC0[10]:HE0:SE0] is trying to acquire: [ 14.863471][ T261] ffffffff8d699660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 14.863483][ T261] [ 14.863483][ T261] and this task is already holding: [ 14.863483][ T261] ff1100000599d0d8 (slock-AF_INET/2){+.-.}-{3:3}, at: l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.863492][ T261] which would create a new lock dependency: [ 14.863492][ T261] (slock-AF_INET/2){+.-.}-{3:3} -> (console_owner){....}-{0:0} [ 14.863495][ T261] [ 14.863495][ T261] but this new dependency connects a SOFTIRQ-irq-safe lock: [ 14.863496][ T261] (slock-AF_INET/2){+.-.}-{3:3} [ 14.863497][ T261] [ 14.863497][ T261] ... which became SOFTIRQ-irq-safe at: [ 14.863498][ T261] __lock_acquire+0x388/0xc20 [ 14.863501][ T261] lock_acquire.part.0+0xd4/0x280 [ 14.863502][ T261] _raw_spin_lock_nested+0x3d/0x50 [ 14.863506][ T261] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.863508][ T261] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.863510][ T261] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.863512][ T261] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.863515][ T261] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.863517][ T261] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.863518][ T261] dev_hard_start_xmit+0xf4/0x620 [ 14.863522][ T261] __dev_queue_xmit+0xc0a/0x1b80 [ 14.863524][ T261] ip_finish_output2+0x5b9/0x1bc0 [ 14.863527][ T261] ip_output+0x1ca/0x660 [ 14.863528][ T261] ip_push_pending_frames+0xf6/0x250 [ 14.863530][ T261] icmp_reply+0x94d/0xa50 [ 14.863532][ T261] icmp_echo+0x2ab/0x4b0 [ 14.863534][ T261] icmp_rcv+0x92f/0xfb0 [ 14.863535][ T261] ip_protocol_deliver_rcu+0x82/0x350 [ 14.863537][ T261] ip_local_deliver_finish+0x36f/0x610 [ 14.863538][ T261] ip_local_deliver+0x184/0x4c0 [ 14.863539][ T261] ip_rcv+0xdc/0x3d0 [ 14.863541][ T261] __netif_receive_skb_one_core+0xfc/0x180 [ 14.863543][ T261] process_backlog+0x431/0x14c0 [ 14.863544][ T261] __napi_poll+0xa7/0x3b0 [ 14.863546][ T261] net_rx_action+0x513/0xf50 [ 14.863547][ T261] handle_softirqs+0x1d3/0x900 [ 14.863550][ T261] do_softirq+0xac/0xe0 [ 14.863551][ T261] __local_bh_enable_ip+0x118/0x150 [ 14.863552][ T261] __dev_queue_xmit+0x979/0x1b80 [ 14.863554][ T261] ip_finish_output2+0x5b9/0x1bc0 [ 14.863555][ T261] ip_output+0x1ca/0x660 [ 14.863557][ T261] ip_push_pending_frames+0xf6/0x250 [ 14.863558][ T261] raw_sendmsg+0x1169/0x1cc0 [ 14.863560][ T261] __sys_sendto+0x2aa/0x400 [ 14.863562][ T261] __x64_sys_sendto+0xe4/0x1f0 [ 14.863564][ T261] do_syscall_64+0xff/0x530 [ 14.863567][ T261] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.863569][ T261] [ 14.863569][ T261] to a SOFTIRQ-irq-unsafe lock: [ 14.863570][ T261] (fs_reclaim){+.+.}-{0:0} [ 14.863571][ T261] [ 14.863571][ T261] ... which became SOFTIRQ-irq-unsafe at: [ 14.863572][ T261] ... [ 14.863572][ T261] __lock_acquire+0x388/0xc20 [ 14.863574][ T261] lock_acquire.part.0+0xd4/0x280 [ 14.863575][ T261] fs_reclaim_acquire+0xd5/0x120 [ 14.863578][ T261] __kmalloc_cache_noprof+0x6e/0x620 [ 14.863580][ T261] kthread_create_worker_on_node+0xea/0x210 [ 14.863583][ T261] workqueue_init+0x2a/0x680 [ 14.863586][ T261] kernel_init_freeable+0x2fe/0x630 [ 14.863589][ T261] kernel_init+0x21/0x150 [ 14.863591][ T261] ret_from_fork+0x474/0x6b0 [ 14.863593][ T261] ret_from_fork_asm+0x11/0x20 [ 14.863596][ T261] [ 14.863596][ T261] other info that might help us debug this: [ 14.863596][ T261] [ 14.863597][ T261] Chain exists of: [ 14.863597][ T261] slock-AF_INET/2 --> console_owner --> fs_reclaim [ 14.863597][ T261] [ 14.863599][ T261] Possible interrupt unsafe locking scenario: [ 14.863599][ T261] [ 14.863599][ T261] CPU0 CPU1 [ 14.863600][ T261] ---- ---- [ 14.863600][ T261] lock(fs_reclaim); [ 14.863601][ T261] local_irq_disable(); [ 14.863602][ T261] lock(slock-AF_INET/2); [ 14.863603][ T261] lock(console_owner); [ 14.863603][ T261] [ 14.863604][ T261] lock(slock-AF_INET/2); [ 14.863605][ T261] [ 14.863605][ T261] *** DEADLOCK *** [ 14.863605][ T261] [ 14.863605][ T261] locks held by ping/261: 14, last CPU#2: [ 14.863606][ T261] #0: ff11000005996290 (sk_lock-AF_INET){+.+.}-{0:0}, at: raw_sendmsg+0xba5/0x1cc0 [ 14.863609][ T261] #1: ffffffff8d790b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 14.863612][ T261] #2: ffffffff8d790b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 14.863615][ T261] #3: ffffffff8d790aa0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x1fc/0x1b80 [ 14.863618][ T261] #4: ffd1ffffffd06218 (&xmit_recursion->bh_lock){+.-.}-{3:3}, at: ppp_xmit_process+0x1d2/0x8e0 [ppp_generic] [ 14.863622][ T261] #5: ff1100000c9dafd0 (&ppp->wlock){+.-.}-{3:3}, at: ppp_xmit_process+0x292/0x8e0 [ppp_generic] [ 14.863625][ T261] #6: ff1100000d4711d0 (&pch->downl){+.-.}-{3:3}, at: ppp_push+0xae/0x2d0 [ppp_generic] [ 14.863628][ T261] #7: ff1100000599d0d8 (slock-AF_INET/2){+.-.}-{3:3}, at: l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.863632][ T261] #8: ffffffff8d790b00 (rcu_read_lock){....}-{1:3}, at: __ip_queue_xmit+0x63/0x1650 [ 14.863635][ T261] #9: ffffffff8d790b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 14.863638][ T261] #10: ffffffff8d790b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 14.863641][ T261] #11: ffffffff8d790aa0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x1fc/0x1b80 [ 14.863644][ T261] #12: ffffffff8d779cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 14.863647][ T261] #13: ffffffff8d779d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 14.863650][ T261] [ 14.863650][ T261] the dependencies between SOFTIRQ-irq-safe lock and the holding lock: [ 14.863651][ T261] -> (slock-AF_INET/2){+.-.}-{3:3} { [ 14.863653][ T261] HARDIRQ-ON-W at: [ 14.863654][ T261] __lock_acquire+0x388/0xc20 [ 14.863656][ T261] lock_acquire.part.0+0xd4/0x280 [ 14.863657][ T261] _raw_spin_lock_nested+0x3d/0x50 [ 14.863659][ T261] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.863661][ T261] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.863663][ T261] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.863664][ T261] __ppp_channel_push+0xed/0x280 [ppp_generic] [ 14.863666][ T261] ppp_channel_push+0x269/0x7b0 [ppp_generic] [ 14.863668][ T261] ppp_write+0x26b/0x350 [ppp_generic] [ 14.863669][ T261] vfs_write+0x1a4/0xbd0 [ 14.863672][ T261] ksys_write+0x116/0x250 [ 14.863674][ T261] do_syscall_64+0xff/0x530 [ 14.863675][ T261] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.863676][ T261] IN-SOFTIRQ-W at: [ 14.863677][ T261] __lock_acquire+0x388/0xc20 [ 14.863678][ T261] lock_acquire.part.0+0xd4/0x280 [ 14.863680][ T261] _raw_spin_lock_nested+0x3d/0x50 [ 14.863681][ T261] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.863683][ T261] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.863685][ T261] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.863686][ T261] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.863688][ T261] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.863689][ T261] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.863691][ T261] dev_hard_start_xmit+0xf4/0x620 [ 14.863693][ T261] __dev_queue_xmit+0xc0a/0x1b80 [ 14.863694][ T261] ip_finish_output2+0x5b9/0x1bc0 [ 14.863696][ T261] ip_output+0x1ca/0x660 [ 14.863697][ T261] ip_push_pending_frames+0xf6/0x250 [ 14.863699][ T261] icmp_reply+0x94d/0xa50 [ 14.863700][ T261] icmp_echo+0x2ab/0x4b0 [ 14.863702][ T261] icmp_rcv+0x92f/0xfb0 [ 14.863703][ T261] ip_protocol_deliver_rcu+0x82/0x350 [ 14.863704][ T261] ip_local_deliver_finish+0x36f/0x610 [ 14.863706][ T261] ip_local_deliver+0x184/0x4c0 [ 14.863707][ T261] ip_rcv+0xdc/0x3d0 [ 14.863708][ T261] __netif_receive_skb_one_core+0xfc/0x180 [ 14.863710][ T261] process_backlog+0x431/0x14c0 [ 14.863712][ T261] __napi_poll+0xa7/0x3b0 [ 14.863713][ T261] net_rx_action+0x513/0xf50 [ 14.863715][ T261] handle_softirqs+0x1d3/0x900 [ 14.863716][ T261] do_softirq+0xac/0xe0 [ 14.863717][ T261] __local_bh_enable_ip+0x118/0x150 [ 14.863718][ T261] __dev_queue_xmit+0x979/0x1b80 [ 14.863720][ T261] ip_finish_output2+0x5b9/0x1bc0 [ 14.863721][ T261] ip_output+0x1ca/0x660 [ 14.863722][ T261] ip_push_pending_frames+0xf6/0x250 [ 14.863724][ T261] raw_sendmsg+0x1169/0x1cc0 [ 14.863725][ T261] __sys_sendto+0x2aa/0x400 [ 14.863727][ T261] __x64_sys_sendto+0xe4/0x1f0 [ 14.863728][ T261] do_syscall_64+0xff/0x530 [ 14.863730][ T261] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.863731][ T261] INITIAL USE at: [ 14.863731][ T261] __lock_acquire+0x388/0xc20 [ 14.863733][ T261] lock_acquire.part.0+0xd4/0x280 [ 14.863734][ T261] _raw_spin_lock_nested+0x3d/0x50 [ 14.863735][ T261] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.863737][ T261] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.863739][ T261] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.863740][ T261] __ppp_channel_push+0xed/0x280 [ppp_generic] [ 14.863742][ T261] ppp_channel_push+0x269/0x7b0 [ppp_generic] [ 14.863744][ T261] ppp_write+0x26b/0x350 [ppp_generic] [ 14.863745][ T261] vfs_write+0x1a4/0xbd0 [ 14.863747][ T261] ksys_write+0x116/0x250 [ 14.863748][ T261] do_syscall_64+0xff/0x530 [ 14.863750][ T261] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.863751][ T261] } [ 14.863751][ T261] ... key at: [] af_family_slock_keys+0x22/0x300 [ 14.863754][ T261] [ 14.863754][ T261] the dependencies between the lock to be acquired [ 14.863755][ T261] and SOFTIRQ-irq-unsafe lock: [ 14.863760][ T261] -> (fs_reclaim){+.+.}-{0:0} { [ 14.863762][ T261] HARDIRQ-ON-W at: [ 14.863762][ T261] __lock_acquire+0x388/0xc20 [ 14.863764][ T261] lock_acquire.part.0+0xd4/0x280 [ 14.863765][ T261] fs_reclaim_acquire+0xd5/0x120 [ 14.863766][ T261] __kmalloc_cache_noprof+0x6e/0x620 [ 14.863768][ T261] kthread_create_worker_on_node+0xea/0x210 [ 14.863769][ T261] workqueue_init+0x2a/0x680 [ 14.863770][ T261] kernel_init_freeable+0x2fe/0x630 [ 14.863772][ T261] kernel_init+0x21/0x150 [ 14.863773][ T261] ret_from_fork+0x474/0x6b0 [ 14.863774][ T261] ret_from_fork_asm+0x11/0x20 [ 14.863776][ T261] SOFTIRQ-ON-W at: [ 14.863776][ T261] __lock_acquire+0x388/0xc20 [ 14.863778][ T261] lock_acquire.part.0+0xd4/0x280 [ 14.863779][ T261] fs_reclaim_acquire+0xd5/0x120 [ 14.863780][ T261] __kmalloc_cache_noprof+0x6e/0x620 [ 14.863781][ T261] kthread_create_worker_on_node+0xea/0x210 [ 14.863782][ T261] workqueue_init+0x2a/0x680 [ 14.863783][ T261] kernel_init_freeable+0x2fe/0x630 [ 14.863785][ T261] kernel_init+0x21/0x150 [ 14.863786][ T261] ret_from_fork+0x474/0x6b0 [ 14.863787][ T261] ret_from_fork_asm+0x11/0x20 [ 14.863788][ T261] INITIAL USE at: [ 14.863789][ T261] __lock_acquire+0x388/0xc20 [ 14.863790][ T261] lock_acquire.part.0+0xd4/0x280 [ 14.863792][ T261] fs_reclaim_acquire+0xd5/0x120 [ 14.863793][ T261] __kmalloc_cache_noprof+0x6e/0x620 [ 14.863794][ T261] kthread_create_worker_on_node+0xea/0x210 [ 14.863795][ T261] workqueue_init+0x2a/0x680 [ 14.863796][ T261] kernel_init_freeable+0x2fe/0x630 [ 14.863798][ T261] kernel_init+0x21/0x150 [ 14.863799][ T261] ret_from_fork+0x474/0x6b0 [ 14.863800][ T261] ret_from_fork_asm+0x11/0x20 [ 14.863801][ T261] } [ 14.863802][ T261] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 14.863805][ T261] ... acquired at: [ 14.863806][ T261] __lock_acquire+0x518/0xc20 [ 14.863807][ T261] lock_acquire.part.0+0xd4/0x280 [ 14.863808][ T261] fs_reclaim_acquire+0xd5/0x120 [ 14.863809][ T261] __kmalloc_noprof+0xd3/0x760 [ 14.863811][ T261] alloc_buf.isra.0+0x4b/0x260 [ 14.863814][ T261] put_chars+0x1e1/0x2f0 [ 14.863815][ T261] hvc_console_print+0x292/0x780 [ 14.863818][ T261] console_emit_next_record+0x22f/0x4c0 [ 14.863819][ T261] console_flush_one_record+0x46f/0x710 [ 14.863821][ T261] console_unlock+0xee/0x1f0 [ 14.863822][ T261] vprintk_emit+0x37c/0x3e0 [ 14.863824][ T261] _printk+0xc7/0x100 [ 14.863826][ T261] loop_init+0x12a/0x130 [ 14.863829][ T261] do_one_initcall+0x124/0x4f0 [ 14.863830][ T261] kernel_init_freeable+0x596/0x630 [ 14.863832][ T261] kernel_init+0x21/0x150 [ 14.863833][ T261] ret_from_fork+0x474/0x6b0 [ 14.863834][ T261] ret_from_fork_asm+0x11/0x20 [ 14.863838][ T261] [ 14.863838][ T261] -> (console_owner){....}-{0:0} { [ 14.863840][ T261] INITIAL USE at: [ 14.863841][ T261] } [ 14.863841][ T261] ... key at: [] console_owner_dep_map+0x0/0x60 [ 14.863844][ T261] ... acquired at: [ 14.863845][ T261] check_prev_add+0xef/0xe90 [ 14.863846][ T261] validate_chain+0x6b8/0xc20 [ 14.863847][ T261] __lock_acquire+0x518/0xc20 [ 14.863848][ T261] lock_acquire.part.0+0xd4/0x280 [ 14.863850][ T261] console_lock_spinning_enable+0x5c/0x60 [ 14.863851][ T261] console_emit_next_record+0x1d1/0x4c0 [ 14.863853][ T261] console_flush_one_record+0x46f/0x710 [ 14.863854][ T261] console_unlock+0xee/0x1f0 [ 14.863856][ T261] vprintk_emit+0x37c/0x3e0 [ 14.863857][ T261] _printk+0xc7/0x100 [ 14.863859][ T261] netdev_err+0xe2/0x120 [ 14.863861][ T261] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 14.863863][ T261] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.863865][ T261] dev_hard_start_xmit+0xf4/0x620 [ 14.863866][ T261] __dev_queue_xmit+0xc0a/0x1b80 [ 14.863868][ T261] ip_finish_output2+0x5b9/0x1bc0 [ 14.863869][ T261] ip_output+0x1ca/0x660 [ 14.863871][ T261] __ip_queue_xmit+0x774/0x1650 [ 14.863872][ T261] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 14.863874][ T261] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.863876][ T261] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.863877][ T261] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.863879][ T261] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.863880][ T261] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.863882][ T261] dev_hard_start_xmit+0xf4/0x620 [ 14.863884][ T261] __dev_queue_xmit+0xc0a/0x1b80 [ 14.863885][ T261] ip_finish_output2+0x5b9/0x1bc0 [ 14.863887][ T261] ip_output+0x1ca/0x660 [ 14.863888][ T261] ip_push_pending_frames+0xf6/0x250 [ 14.863889][ T261] raw_sendmsg+0x1169/0x1cc0 [ 14.863890][ T261] __sys_sendto+0x2aa/0x400 [ 14.863892][ T261] __x64_sys_sendto+0xe4/0x1f0 [ 14.863893][ T261] do_syscall_64+0xff/0x530 [ 14.863895][ T261] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.863896][ T261] [ 14.863896][ T261] [ 14.863896][ T261] stack backtrace: [ 14.863899][ T261] CPU: 2 UID: 0 PID: 261 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 14.863902][ T261] Tainted: [W]=WARN [ 14.863903][ T261] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.863905][ T261] Call Trace: [ 14.863906][ T261] [ 14.863907][ T261] dump_stack_lvl+0x6f/0xa0 [ 14.863911][ T261] print_bad_irq_dependency+0x3e4/0x410 [ 14.863913][ T261] check_irq_usage+0x403/0x570 [ 14.863915][ T261] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.863919][ T261] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.863921][ T261] ? check_path.constprop.0+0x24/0x50 [ 14.863924][ T261] ? check_prev_add+0xef/0xe90 [ 14.863925][ T261] check_prev_add+0xef/0xe90 [ 14.863927][ T261] validate_chain+0x6b8/0xc20 [ 14.863929][ T261] __lock_acquire+0x518/0xc20 [ 14.863931][ T261] lock_acquire.part.0+0xd4/0x280 [ 14.863933][ T261] ? console_lock_spinning_enable+0x40/0x60 [ 14.863935][ T261] ? rcu_is_watching+0x16/0xd0 [ 14.863937][ T261] ? lock_acquire+0x13c/0x160 [ 14.863939][ T261] console_lock_spinning_enable+0x5c/0x60 [ 14.863940][ T261] ? console_lock_spinning_enable+0x40/0x60 [ 14.863942][ T261] console_emit_next_record+0x1d1/0x4c0 [ 14.863944][ T261] ? devkmsg_read+0x4b0/0x4b0 [ 14.863945][ T261] ? console_flush_one_record+0x106/0x710 [ 14.863947][ T261] ? rcu_is_watching+0x16/0xd0 [ 14.863949][ T261] ? lock_acquire+0x13c/0x160 [ 14.863951][ T261] console_flush_one_record+0x46f/0x710 [ 14.863953][ T261] ? console_emit_next_record+0x4c0/0x4c0 [ 14.863955][ T261] ? __lock_acquire+0x518/0xc20 [ 14.863957][ T261] console_unlock+0xee/0x1f0 [ 14.863959][ T261] ? console_flush_one_record+0x710/0x710 [ 14.863961][ T261] ? rcu_is_watching+0x16/0xd0 [ 14.863962][ T261] ? lock_acquire+0x60/0x160 [ 14.863964][ T261] ? __down_trylock_console_sem+0x5e/0xa0 [ 14.863966][ T261] ? vprintk_emit+0x320/0x3e0 [ 14.863968][ T261] vprintk_emit+0x37c/0x3e0 [ 14.863970][ T261] ? wake_up_klogd_work_func+0x90/0x90 [ 14.863971][ T261] ? mark_lock+0x3bb/0xa00 [ 14.863973][ T261] _printk+0xc7/0x100 [ 14.863975][ T261] ? snapshot_read.cold+0x21/0x21 [ 14.863977][ T261] ? lock_acquire.part.0+0xd4/0x280 [ 14.863978][ T261] ? find_held_lock+0x2b/0x80 [ 14.863980][ T261] ? netdev_name+0x43/0x60 [ 14.863981][ T261] ? __netdev_printk+0x17f/0x1d0 [ 14.863983][ T261] netdev_err+0xe2/0x120 [ 14.863984][ T261] ? netdev_crit+0x120/0x120 [ 14.863985][ T261] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 14.863987][ T261] ? ___ratelimit+0x4e7/0xa00 [ 14.863990][ T261] ? idr_get_free+0x830/0x830 [ 14.863992][ T261] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 14.863994][ T261] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 14.863996][ T261] ? dst_release+0x54/0x240 [ 14.863999][ T261] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.864001][ T261] ? dev_nit_active_rcu+0x1f/0xf0 [ 14.864004][ T261] ? rcu_is_watching+0x16/0xd0 [ 14.864005][ T261] dev_hard_start_xmit+0xf4/0x620 [ 14.864008][ T261] ? rcu_is_watching+0x16/0xd0 [ 14.864009][ T261] __dev_queue_xmit+0xc0a/0x1b80 [ 14.864011][ T261] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 14.864013][ T261] ? lockdep_hardirqs_on+0x91/0x130 [ 14.864015][ T261] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 14.864016][ T261] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 14.864017][ T261] ? ref_tracker_dir_print+0x100/0x100 [ 14.864021][ T261] ? dst_init+0x84/0x530 [ 14.864022][ T261] ? dst_alloc+0x97/0x160 [ 14.864023][ T261] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 14.864025][ T261] ? ip_route_output_flow+0x21/0x150 [ 14.864027][ T261] ? __ip_queue_xmit+0xdc7/0x1650 [ 14.864028][ T261] ? __lock_acquire+0x518/0xc20 [ 14.864029][ T261] ? down_read_nested+0xe7/0x500 [ 14.864031][ T261] ? lock_acquire.part.0+0xd4/0x280 [ 14.864032][ T261] ? ip_finish_output2+0x25c/0x1bc0 [ 14.864034][ T261] ? rcu_is_watching+0x16/0xd0 [ 14.864036][ T261] ip_finish_output2+0x5b9/0x1bc0 [ 14.864038][ T261] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 14.864040][ T261] ? lock_acquire.part.0+0xd4/0x280 [ 14.864041][ T261] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 14.864044][ T261] ip_output+0x1ca/0x660 [ 14.864046][ T261] ? ip_finish_output+0x460/0x460 [ 14.864047][ T261] ? find_held_lock+0x2b/0x80 [ 14.864049][ T261] ? __lock_release.isra.0+0x69/0x1a0 [ 14.864051][ T261] ? ip4_dst_hoplimit+0x15b/0x320 [ 14.864053][ T261] __ip_queue_xmit+0x774/0x1650 [ 14.864055][ T261] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 14.864058][ T261] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.864061][ T261] ? l2tp_xmit_core.constprop.0+0x19b0/0x19b0 [l2tp_core] [ 14.864064][ T261] ? do_raw_spin_lock+0x131/0x280 [ 14.864066][ T261] ? pppol2tp_xmit+0x423/0x690 [l2tp_ppp] [ 14.864067][ T261] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.864069][ T261] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.864071][ T261] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.864073][ T261] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 14.864075][ T261] ? dst_release+0x54/0x240 [ 14.864077][ T261] ? down_read_nested+0x36d/0x500 [ 14.864078][ T261] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.864080][ T261] ? dev_nit_active_rcu+0x1f/0xf0 [ 14.864082][ T261] ? rcu_is_watching+0x16/0xd0 [ 14.864084][ T261] dev_hard_start_xmit+0xf4/0x620 [ 14.864086][ T261] ? rcu_is_watching+0x16/0xd0 [ 14.864088][ T261] __dev_queue_xmit+0xc0a/0x1b80 [ 14.864090][ T261] ? sock_queue_err_skb+0x680/0x680 [ 14.864092][ T261] ? kmem_cache_alloc_node_noprof+0x2a6/0x660 [ 14.864094][ T261] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.864096][ T261] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.864097][ T261] ? validate_chain+0x38b/0xc20 [ 14.864098][ T261] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 14.864100][ T261] ? __lock_acquire+0x518/0xc20 [ 14.864102][ T261] ? down_read_nested+0xe7/0x500 [ 14.864103][ T261] ? lock_acquire.part.0+0xd4/0x280 [ 14.864105][ T261] ? ip_finish_output2+0x25c/0x1bc0 [ 14.864107][ T261] ? rcu_is_watching+0x16/0xd0 [ 14.864109][ T261] ip_finish_output2+0x5b9/0x1bc0 [ 14.864111][ T261] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 14.864112][ T261] ? lock_acquire.part.0+0xd4/0x280 [ 14.864114][ T261] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 14.864116][ T261] ? rcu_is_watching+0x16/0xd0 [ 14.864118][ T261] ip_output+0x1ca/0x660 [ 14.864120][ T261] ? ip_finish_output+0x460/0x460 [ 14.864121][ T261] ? __ip_make_skb+0x106a/0x2300 [ 14.864124][ T261] ip_push_pending_frames+0xf6/0x250 [ 14.864126][ T261] raw_sendmsg+0x1169/0x1cc0 [ 14.864128][ T261] ? raw_send_hdrinc+0x1740/0x1740 [ 14.864129][ T261] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.864133][ T261] ? __might_fault+0x97/0x140 [ 14.864137][ T261] ? __might_fault+0x97/0x140 [ 14.864138][ T261] ? __might_fault+0x97/0x140 [ 14.864140][ T261] __sys_sendto+0x2aa/0x400 [ 14.864142][ T261] ? __ia32_sys_getpeername+0xd0/0xd0 [ 14.864146][ T261] ? exc_page_fault+0x87/0x100 [ 14.864148][ T261] __x64_sys_sendto+0xe4/0x1f0 [ 14.864149][ T261] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 14.864152][ T261] ? lockdep_hardirqs_on+0x91/0x130 [ 14.864153][ T261] ? do_syscall_64+0xa6/0x530 [ 14.864154][ T261] do_syscall_64+0xff/0x530 [ 14.864156][ T261] ? exc_page_fault+0xee/0x100 [ 14.864157][ T261] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.864159][ T261] RIP: 0033:0x7f4b4f8fb54e [ 14.864162][ T261] 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.864163][ T261] RSP: 002b:00007fff49f8ba10 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 14.864166][ T261] RAX: ffffffffffffffda RBX: 0000000000000040 RCX: 00007f4b4f8fb54e [ 14.864167][ T261] RDX: 0000000000000040 RSI: 0000559cca2020a4 RDI: 0000000000000005 [ 14.864168][ T261] RBP: 00007fff49f8ba20 R08: 0000559cca214320 R09: 0000000000000010 [ 14.864169][ T261] R10: 0000000000000000 R11: 0000000000000202 R12: 431bde82d7b634db [ 14.864170][ T261] R13: 0000000000000000 R14: 0000559cca2153e0 R15: 0000559cca2020a0 [ 14.864173][ T261] [ 15.878398][ T261] ping (261) used greatest stack depth: 22536 bytes left [ 15.878418][ T261] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 15.878421][ T261] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 261, name: ping [ 15.878423][ T261] preempt_count: 2, expected: 0 [ 15.878424][ T261] RCU nest depth: 0, expected: 0 [ 15.878425][ T261] INFO: lockdep is turned off. [ 15.878427][ T261] irq event stamp: 28253 [ 15.878428][ T261] hardirqs last enabled at (28252): [] __down_trylock_console_sem+0x86/0xa0 [ 15.878438][ T261] hardirqs last disabled at (28253): [] console_emit_next_record+0x3d4/0x4c0 [ 15.878441][ T261] softirqs last enabled at (28156): [] __alloc_skb+0x4c2/0x5f0 [ 15.878447][ T261] softirqs last disabled at (28166): [] __dev_queue_xmit+0x1fc/0x1b80 [ 15.878451][ T261] Preemption disabled at: [ 15.878452][ T261] [<0000000000000000>] 0x0 [ 15.878461][ T261] CPU: 0 UID: 0 PID: 261 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.878466][ T261] Tainted: [W]=WARN [ 15.878467][ T261] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.878469][ T261] Call Trace: [ 15.878472][ T261] [ 15.878473][ T261] dump_stack_lvl+0x6f/0xa0 [ 15.878480][ T261] __might_resched.cold+0x1fe/0x2c1 [ 15.878484][ T261] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 15.878488][ T261] ? __asan_memcpy+0x3c/0x60 [ 15.878492][ T261] ? rcu_is_watching+0x16/0xd0 [ 15.878497][ T261] __kmalloc_noprof+0x443/0x760 [ 15.878501][ T261] ? __rwlock_init+0x150/0x150 [ 15.878504][ T261] ? alloc_buf.isra.0+0x4b/0x260 [ 15.878509][ T261] ? do_raw_spin_unlock+0x59/0x250 [ 15.878512][ T261] alloc_buf.isra.0+0x4b/0x260 [ 15.878515][ T261] put_chars+0x1e1/0x2f0 [ 15.878518][ T261] ? __send_to_port+0x420/0x420 [ 15.878521][ T261] ? console_prepend_replay+0x20/0x20 [ 15.878525][ T261] hvc_console_print+0x292/0x780 [ 15.878530][ T261] ? hvc_write+0x3a0/0x3a0 [ 15.878532][ T261] ? rcu_is_watching+0x16/0xd0 [ 15.878535][ T261] ? lock_acquire+0x13c/0x160 [ 15.878538][ T261] console_emit_next_record+0x22f/0x4c0 [ 15.878542][ T261] ? devkmsg_read+0x4b0/0x4b0 [ 15.878545][ T261] ? rcu_is_watching+0x16/0xd0 [ 15.878547][ T261] ? rcu_is_watching+0x16/0xd0 [ 15.878550][ T261] ? rcu_is_watching+0x16/0xd0 [ 15.878552][ T261] ? lock_acquire+0x13c/0x160 [ 15.878555][ T261] ? console_flush_one_record+0x111/0x710 [ 15.878557][ T261] console_flush_one_record+0x46f/0x710 [ 15.878561][ T261] ? console_emit_next_record+0x4c0/0x4c0 [ 15.878565][ T261] console_unlock+0xee/0x1f0 [ 15.878567][ T261] ? lock_acquire+0x13c/0x160 [ 15.878570][ T261] ? console_flush_one_record+0x710/0x710 [ 15.878572][ T261] ? rcu_is_watching+0x16/0xd0 [ 15.878575][ T261] ? lock_release+0x184/0x1f0 [ 15.878577][ T261] ? lock_acquire+0x60/0x160 [ 15.878579][ T261] ? __rcu_read_lock+0x30/0x70 [ 15.878582][ T261] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.878584][ T261] ? vprintk_emit+0x320/0x3e0 [ 15.878587][ T261] vprintk_emit+0x37c/0x3e0 [ 15.878590][ T261] ? wake_up_klogd_work_func+0x90/0x90 [ 15.878593][ T261] ? do_raw_read_unlock+0x70/0x70 [ 15.878596][ T261] ? forget_original_parent+0x5c/0xdb0 [ 15.878601][ T261] _printk+0xc7/0x100 [ 15.878604][ T261] ? snapshot_read.cold+0x21/0x21 [ 15.878607][ T261] ? do_raw_spin_lock+0x131/0x280 [ 15.878609][ T261] ? do_raw_spin_lock+0x131/0x280 [ 15.878612][ T261] ? __rwlock_init+0x150/0x150 [ 15.878615][ T261] ? do_raw_spin_lock+0x131/0x280 [ 15.878618][ T261] do_exit.cold+0x82/0x9c [ 15.878621][ T261] ? exit_notify+0x890/0x890 [ 15.878624][ T261] ? posixtimer_sig_unignore.isra.0+0x5f0/0x5f0 [ 15.878627][ T261] ? rcu_is_watching+0x16/0xd0 [ 15.878630][ T261] do_group_exit+0xb8/0x370 [ 15.878633][ T261] __x64_sys_exit_group+0x3c/0x50 [ 15.878635][ T261] x64_sys_call+0x1567/0x1570 [ 15.878638][ T261] do_syscall_64+0xff/0x530 [ 15.878642][ T261] ? exc_page_fault+0xee/0x100 [ 15.878645][ T261] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.878648][ T261] RIP: 0033:0x7f4b4f94c1b8 [ 15.878651][ T261] Code: Unable to access opcode bytes at 0x7f4b4f94c18e. [ 15.878652][ T261] RSP: 002b:00007fff49f8d1a8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 15.878657][ T261] RAX: ffffffffffffffda RBX: 00007f4b4fa7cf88 RCX: 00007f4b4f94c1b8 [ 15.878659][ T261] RDX: 00007f4b4f6dd7c8 RSI: ffffffffffffffb0 RDI: 0000000000000001 [ 15.878660][ T261] RBP: 00007fff49f8d200 R08: 0000000000000000 R09: 0000000000001010 [ 15.878662][ T261] R10: 00007fff49f8d020 R11: 0000000000000246 R12: 0000000000000002 [ 15.878663][ T261] R13: 0000000000000001 R14: 00007f4b4fa7b680 R15: 00007f4b4fa7cfa0 [ 15.878667][ T261] [ 15.930404][ T221] ppp0: recursion detected