[ 9.173130][ T179] PPP generic driver version 2.4.2 [ 9.173146][ T179] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.173150][ T179] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 179, name: modprobe [ 9.173151][ T179] preempt_count: 1, expected: 0 [ 9.173152][ T179] RCU nest depth: 0, expected: 0 [ 9.173153][ T179] locks held by modprobe/179: 4, last CPU#0: [ 9.173156][ T179] #0: ffffffff85979cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.173167][ T179] #1: ffffffff85979d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.173171][ T179] #2: ffffffff85899660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.173175][ T179] #3: ffffffff85899560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.173178][ T179] irq event stamp: 46426 [ 9.173179][ T179] hardirqs last enabled at (46425): [] __down_trylock_console_sem+0x86/0xa0 [ 9.173182][ T179] hardirqs last disabled at (46426): [] console_emit_next_record+0x3d4/0x4c0 [ 9.173184][ T179] softirqs last enabled at (44976): [] handle_softirqs+0x67c/0x900 [ 9.173187][ T179] softirqs last disabled at (44075): [] __irq_exit_rcu+0x145/0x1c0 [ 9.173189][ T179] Preemption disabled at: [ 9.173189][ T179] [] vprintk_emit+0x31b/0x3e0 [ 9.173194][ T179] CPU: 0 UID: 0 PID: 179 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.173201][ T179] Tainted: [W]=WARN [ 9.173202][ T179] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.173204][ T179] Call Trace: [ 9.173205][ T179] [ 9.173207][ T179] dump_stack_lvl+0x6f/0xa0 [ 9.173212][ T179] ? vprintk_emit+0x31b/0x3e0 [ 9.173215][ T179] __might_resched.cold+0x1fe/0x2c1 [ 9.173219][ T179] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.173223][ T179] ? __kmalloc_noprof+0xdb/0x760 [ 9.173228][ T179] __kmalloc_noprof+0x443/0x760 [ 9.173230][ T179] ? alloc_buf.isra.0+0x4b/0x260 [ 9.173235][ T179] ? do_raw_spin_unlock+0x59/0x250 [ 9.173238][ T179] alloc_buf.isra.0+0x4b/0x260 [ 9.173241][ T179] put_chars+0x1e1/0x2f0 [ 9.173244][ T179] ? __send_to_port+0x420/0x420 [ 9.173246][ T179] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.173251][ T179] ? rcu_read_lock_any_held+0x3c/0x90 [ 9.173253][ T179] ? validate_chain+0x38b/0xc20 [ 9.173257][ T179] hvc_console_print+0x292/0x780 [ 9.173260][ T179] ? __lock_acquire+0x518/0xc20 [ 9.173265][ T179] ? hvc_write+0x3a0/0x3a0 [ 9.173267][ T179] ? rcu_is_watching+0x16/0xd0 [ 9.173272][ T179] console_emit_next_record+0x22f/0x4c0 [ 9.173276][ T179] ? devkmsg_read+0x4b0/0x4b0 [ 9.173278][ T179] ? console_flush_one_record+0x106/0x710 [ 9.173281][ T179] ? rcu_is_watching+0x16/0xd0 [ 9.173283][ T179] ? lock_acquire+0x13c/0x160 [ 9.173287][ T179] console_flush_one_record+0x46f/0x710 [ 9.173290][ T179] ? console_emit_next_record+0x4c0/0x4c0 [ 9.173292][ T179] ? __lock_acquire+0x518/0xc20 [ 9.173297][ T179] console_unlock+0xee/0x1f0 [ 9.173300][ T179] ? console_flush_one_record+0x710/0x710 [ 9.173302][ T179] ? rcu_is_watching+0x16/0xd0 [ 9.173304][ T179] ? lock_acquire+0x60/0x160 [ 9.173307][ T179] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.173309][ T179] ? vprintk_emit+0x320/0x3e0 [ 9.173312][ T179] vprintk_emit+0x37c/0x3e0 [ 9.173315][ T179] ? wake_up_klogd_work_func+0x90/0x90 [ 9.173321][ T179] ? ppp_cleanup+0x920/0x920 [ppp_generic] [ 9.173325][ T179] _printk+0xc7/0x100 [ 9.173328][ T179] ? snapshot_read.cold+0x21/0x21 [ 9.173334][ T179] ? do_one_initcall+0x113/0x4f0 [ 9.173337][ T179] ppp_init+0x16/0x1000 [ppp_generic] [ 9.173340][ T179] do_one_initcall+0x124/0x4f0 [ 9.173343][ T179] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 9.173347][ T179] ? do_init_module+0xae/0x8a0 [ 9.173350][ T179] ? kasan_unpoison+0x44/0x70 [ 9.173355][ T179] do_init_module+0x2ad/0x8a0 [ 9.173359][ T179] ? lockdep_hardirqs_on+0x91/0x130 [ 9.173362][ T179] ? free_module+0x470/0x470 [ 9.173365][ T179] ? kfree+0x123/0x590 [ 9.173369][ T179] load_module+0x1424/0x1bb0 [ 9.173376][ T179] ? layout_and_allocate.isra.0+0x680/0x680 [ 9.173381][ T179] ? backing_file_read_iter+0x541/0x790 [ 9.173384][ T179] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.173388][ T179] init_module_from_file+0x11f/0x1a0 [ 9.173391][ T179] ? __do_sys_init_module+0x220/0x220 [ 9.173398][ T179] ? __rwlock_init+0x150/0x150 [ 9.173402][ T179] ? do_raw_spin_unlock+0x59/0x250 [ 9.173405][ T179] idempotent_init_module+0x37b/0x7c0 [ 9.173408][ T179] ? init_module_from_file+0x1a0/0x1a0 [ 9.173414][ T179] ? rcu_is_watching+0x16/0xd0 [ 9.173416][ T179] ? cap_capable+0x1d7/0x3d0 [ 9.173422][ T179] __x64_sys_finit_module+0xdf/0x190 [ 9.173425][ T179] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 9.173428][ T179] do_syscall_64+0xff/0x530 [ 9.173430][ T179] ? exc_page_fault+0xee/0x100 [ 9.173433][ T179] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.173435][ T179] RIP: 0033:0x7f17eedad37d [ 9.173438][ 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 [ 9.173440][ T179] RSP: 002b:00007ffea682a5d8 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 9.173442][ T179] RAX: ffffffffffffffda RBX: 000055eb2787c1d0 RCX: 00007f17eedad37d [ 9.173443][ T179] RDX: 0000000000000000 RSI: 000055eb15273ffd RDI: 0000000000000006 [ 9.173444][ T179] RBP: 00007ffea682a670 R08: 0000000000000000 R09: 0000000000000000 [ 9.173445][ T179] R10: 0000000000000000 R11: 0000000000000246 R12: 000055eb15273ffd [ 9.173446][ T179] R13: 000055eb2787c180 R14: 0000000000040000 R15: 0000000000000000 [ 9.173452][ T179] [ 9.206953][ T179] NET: Registered PF_PPPOX protocol family [ 9.231604][ T179] l2tp_core: L2TP core driver, V2.0 [ 9.238085][ T179] l2tp_ppp: PPPoL2TP kernel driver, V2.0 [ 14.361354][ T250] iperf3 (250) used greatest stack depth: 23056 bytes left [ 14.361373][ T250] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 14.361375][ T250] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 250, name: iperf3 [ 14.361377][ T250] preempt_count: 2, expected: 0 [ 14.361378][ T250] RCU nest depth: 0, expected: 0 [ 14.361379][ T250] locks held by iperf3/250: 5, last CPU#3: [ 14.361381][ T250] #0: ffffffff858127b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 14.361392][ T250] #1: ffffffff85979cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 14.361398][ T250] #2: ffffffff85979d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 14.361402][ T250] #3: ffffffff85899660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 14.361406][ T250] #4: ffffffff85899560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 14.361410][ T250] irq event stamp: 1714692 [ 14.361410][ T250] hardirqs last enabled at (1714691): [] __down_trylock_console_sem+0x86/0xa0 [ 14.361413][ T250] hardirqs last disabled at (1714692): [] console_emit_next_record+0x3d4/0x4c0 [ 14.361415][ T250] softirqs last enabled at (1714636): [] handle_softirqs+0x67c/0x900 [ 14.361417][ T250] softirqs last disabled at (1714227): [] __irq_exit_rcu+0x145/0x1c0 [ 14.361420][ T250] Preemption disabled at: [ 14.361420][ T250] [<0000000000000000>] 0x0 [ 14.361427][ T250] CPU: 3 UID: 0 PID: 250 Comm: iperf3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 14.361430][ T250] Tainted: [W]=WARN [ 14.361431][ T250] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.361433][ T250] Call Trace: [ 14.361434][ T250] [ 14.361436][ T250] dump_stack_lvl+0x6f/0xa0 [ 14.361442][ T250] __might_resched.cold+0x1fe/0x2c1 [ 14.361447][ T250] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 14.361451][ T250] ? __kmalloc_noprof+0xdb/0x760 [ 14.361456][ T250] __kmalloc_noprof+0x443/0x760 [ 14.361458][ T250] ? alloc_buf.isra.0+0x4b/0x260 [ 14.361464][ T250] ? do_raw_spin_unlock+0x59/0x250 [ 14.361466][ T250] alloc_buf.isra.0+0x4b/0x260 [ 14.361470][ T250] put_chars+0x1e1/0x2f0 [ 14.361472][ T250] ? prb_final_commit+0x50/0x50 [ 14.361474][ T250] ? __send_to_port+0x420/0x420 [ 14.361477][ T250] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.361481][ T250] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.361483][ T250] ? validate_chain+0x38b/0xc20 [ 14.361487][ T250] hvc_console_print+0x292/0x780 [ 14.361490][ T250] ? mark_usage+0x61/0x170 [ 14.361492][ T250] ? __lock_acquire+0x518/0xc20 [ 14.361494][ T250] ? __lock_acquire+0x518/0xc20 [ 14.361497][ T250] ? hvc_write+0x3a0/0x3a0 [ 14.361500][ T250] ? console_emit_next_record+0x1df/0x4c0 [ 14.361503][ T250] ? rcu_is_watching+0x16/0xd0 [ 14.361505][ T250] ? lock_acquire+0x13c/0x160 [ 14.361509][ T250] console_emit_next_record+0x22f/0x4c0 [ 14.361512][ T250] ? devkmsg_read+0x4b0/0x4b0 [ 14.361514][ T250] ? console_flush_one_record+0x106/0x710 [ 14.361517][ T250] ? rcu_is_watching+0x16/0xd0 [ 14.361520][ T250] ? lock_acquire+0x13c/0x160 [ 14.361523][ T250] console_flush_one_record+0x46f/0x710 [ 14.361527][ T250] ? console_emit_next_record+0x4c0/0x4c0 [ 14.361529][ T250] ? __lock_acquire+0x518/0xc20 [ 14.361534][ T250] console_unlock+0xee/0x1f0 [ 14.361537][ T250] ? console_flush_one_record+0x710/0x710 [ 14.361538][ T250] ? rcu_is_watching+0x16/0xd0 [ 14.361541][ T250] ? lock_acquire+0x60/0x160 [ 14.361544][ T250] ? __down_trylock_console_sem+0x5e/0xa0 [ 14.361546][ T250] ? vprintk_emit+0x320/0x3e0 [ 14.361549][ T250] vprintk_emit+0x37c/0x3e0 [ 14.361552][ T250] ? wake_up_klogd_work_func+0x90/0x90 [ 14.361556][ T250] ? __lock_acquire+0x518/0xc20 [ 14.361559][ T250] _printk+0xc7/0x100 [ 14.361563][ T250] ? snapshot_read.cold+0x21/0x21 [ 14.361566][ T250] ? do_raw_spin_lock+0x131/0x280 [ 14.361568][ T250] ? __rwlock_init+0x150/0x150 [ 14.361572][ T250] ? do_raw_spin_lock+0x131/0x280 [ 14.361574][ T250] do_exit.cold+0x82/0x9c [ 14.361578][ T250] ? exit_notify+0x890/0x890 [ 14.361583][ T250] __x64_sys_exit+0x42/0x50 [ 14.361585][ T250] x64_sys_call+0x156c/0x1570 [ 14.361587][ T250] do_syscall_64+0xff/0x530 [ 14.361591][ T250] ? exc_page_fault+0xee/0x100 [ 14.361594][ T250] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.361596][ T250] RIP: 0033:0x7fa89dcaec49 [ 14.361598][ T250] Code: Unable to access opcode bytes at 0x7fa89dcaec1f. [ 14.361599][ T250] RSP: 002b:00007fa89d46de60 EFLAGS: 00000246 ORIG_RAX: 000000000000003c [ 14.361602][ T250] RAX: ffffffffffffffda RBX: 00007fa89d46e6c0 RCX: 00007fa89dcaec49 [ 14.361603][ T250] RDX: 0000000000000000 RSI: 0000000000801000 RDI: 0000000000000000 [ 14.361604][ T250] RBP: 00007fa89d46df70 R08: 0000000000000001 R09: 00007fa89d46e6c0 [ 14.361605][ T250] R10: 0000000000000008 R11: 0000000000000246 R12: 00007fa89cc6e000 [ 14.361606][ T250] R13: 00007ffe151f8e80 R14: 0000000000000002 R15: 00007ffe151f8f87 [ 14.361612][ T250] [ 14.477976][ T254] ppp0: recursion detected [ 14.478005][ T254] [ 14.478006][ T254] ===================================================== [ 14.478007][ T254] WARNING: SOFTIRQ-safe -> SOFTIRQ-unsafe lock order detected [ 14.478010][ T254] 7.2.0-virtme #1 Tainted: G W [ 14.478011][ T254] ----------------------------------------------------- [ 14.478012][ T254] ping/254 [HC0[0]:SC0[10]:HE0:SE0] is trying to acquire: [ 14.478014][ T254] ffffffff85899660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 14.478024][ T254] [ 14.478024][ T254] and this task is already holding: [ 14.478024][ T254] ff110000058e50d8 (slock-AF_INET/2){+.-.}-{3:3}, at: l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.478034][ T254] which would create a new lock dependency: [ 14.478034][ T254] (slock-AF_INET/2){+.-.}-{3:3} -> (console_owner){....}-{0:0} [ 14.478037][ T254] [ 14.478037][ T254] but this new dependency connects a SOFTIRQ-irq-safe lock: [ 14.478038][ T254] (slock-AF_INET/2){+.-.}-{3:3} [ 14.478039][ T254] [ 14.478039][ T254] ... which became SOFTIRQ-irq-safe at: [ 14.478040][ T254] __lock_acquire+0x388/0xc20 [ 14.478043][ T254] lock_acquire.part.0+0xd4/0x280 [ 14.478045][ T254] _raw_spin_lock_nested+0x3d/0x50 [ 14.478048][ T254] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.478050][ T254] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.478053][ T254] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.478055][ T254] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.478057][ T254] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.478059][ T254] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.478060][ T254] dev_hard_start_xmit+0xf4/0x620 [ 14.478064][ T254] __dev_queue_xmit+0xc0a/0x1b80 [ 14.478066][ T254] ip_finish_output2+0x5b9/0x1bc0 [ 14.478069][ T254] ip_output+0x1ca/0x660 [ 14.478070][ T254] ip_push_pending_frames+0xf6/0x250 [ 14.478072][ T254] icmp_reply+0x94d/0xa50 [ 14.478074][ T254] icmp_echo+0x2ab/0x4b0 [ 14.478076][ T254] icmp_rcv+0x92f/0xfb0 [ 14.478077][ T254] ip_protocol_deliver_rcu+0x82/0x350 [ 14.478079][ T254] ip_local_deliver_finish+0x36f/0x610 [ 14.478080][ T254] ip_local_deliver+0x184/0x4c0 [ 14.478082][ T254] ip_rcv+0xdc/0x3d0 [ 14.478083][ T254] __netif_receive_skb_one_core+0xfc/0x180 [ 14.478085][ T254] process_backlog+0x431/0x14c0 [ 14.478086][ T254] __napi_poll+0xa7/0x3b0 [ 14.478088][ T254] net_rx_action+0x513/0xf50 [ 14.478089][ T254] handle_softirqs+0x1d3/0x900 [ 14.478092][ T254] do_softirq+0xac/0xe0 [ 14.478093][ T254] __local_bh_enable_ip+0x118/0x150 [ 14.478094][ T254] __dev_queue_xmit+0x979/0x1b80 [ 14.478096][ T254] ip_finish_output2+0x5b9/0x1bc0 [ 14.478097][ T254] ip_output+0x1ca/0x660 [ 14.478099][ T254] ip_push_pending_frames+0xf6/0x250 [ 14.478100][ T254] raw_sendmsg+0x1169/0x1cc0 [ 14.478102][ T254] __sys_sendto+0x2aa/0x400 [ 14.478104][ T254] __x64_sys_sendto+0xe4/0x1f0 [ 14.478106][ T254] do_syscall_64+0xff/0x530 [ 14.478108][ T254] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.478110][ T254] [ 14.478110][ T254] to a SOFTIRQ-irq-unsafe lock: [ 14.478111][ T254] (fs_reclaim){+.+.}-{0:0} [ 14.478112][ T254] [ 14.478112][ T254] ... which became SOFTIRQ-irq-unsafe at: [ 14.478113][ T254] ... [ 14.478113][ T254] __lock_acquire+0x388/0xc20 [ 14.478115][ T254] lock_acquire.part.0+0xd4/0x280 [ 14.478116][ T254] fs_reclaim_acquire+0xd5/0x120 [ 14.478119][ T254] __kmalloc_cache_noprof+0x6e/0x620 [ 14.478121][ T254] kthread_create_worker_on_node+0xea/0x210 [ 14.478123][ T254] workqueue_init+0x2a/0x680 [ 14.478126][ T254] kernel_init_freeable+0x2fe/0x630 [ 14.478129][ T254] kernel_init+0x21/0x150 [ 14.478131][ T254] ret_from_fork+0x474/0x6b0 [ 14.478133][ T254] ret_from_fork_asm+0x11/0x20 [ 14.478136][ T254] [ 14.478136][ T254] other info that might help us debug this: [ 14.478136][ T254] [ 14.478137][ T254] Chain exists of: [ 14.478137][ T254] slock-AF_INET/2 --> console_owner --> fs_reclaim [ 14.478137][ T254] [ 14.478139][ T254] Possible interrupt unsafe locking scenario: [ 14.478139][ T254] [ 14.478140][ T254] CPU0 CPU1 [ 14.478140][ T254] ---- ---- [ 14.478141][ T254] lock(fs_reclaim); [ 14.478141][ T254] local_irq_disable(); [ 14.478142][ T254] lock(slock-AF_INET/2); [ 14.478143][ T254] lock(console_owner); [ 14.478144][ T254] [ 14.478144][ T254] lock(slock-AF_INET/2); [ 14.478145][ T254] [ 14.478145][ T254] *** DEADLOCK *** [ 14.478145][ T254] [ 14.478145][ T254] locks held by ping/254: 14, last CPU#3: [ 14.478147][ T254] #0: ff110000058de290 (sk_lock-AF_INET){+.+.}-{0:0}, at: raw_sendmsg+0xba5/0x1cc0 [ 14.478150][ T254] #1: ffffffff85990b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 14.478153][ T254] #2: ffffffff85990b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 14.478156][ T254] #3: ffffffff85990aa0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x1fc/0x1b80 [ 14.478159][ T254] #4: ffd1ffffffd85fc8 (&xmit_recursion->bh_lock){+.-.}-{3:3}, at: ppp_xmit_process+0x1d2/0x8e0 [ppp_generic] [ 14.478162][ T254] #5: ff1100000d3bafd0 (&ppp->wlock){+.-.}-{3:3}, at: ppp_xmit_process+0x292/0x8e0 [ppp_generic] [ 14.478165][ T254] #6: ff1100000c6291d0 (&pch->downl){+.-.}-{3:3}, at: ppp_push+0xae/0x2d0 [ppp_generic] [ 14.478168][ T254] #7: ff110000058e50d8 (slock-AF_INET/2){+.-.}-{3:3}, at: l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.478172][ T254] #8: ffffffff85990b00 (rcu_read_lock){....}-{1:3}, at: __ip_queue_xmit+0x63/0x1650 [ 14.478175][ T254] #9: ffffffff85990b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 14.478178][ T254] #10: ffffffff85990b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 14.478181][ T254] #11: ffffffff85990aa0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x1fc/0x1b80 [ 14.478184][ T254] #12: ffffffff85979cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 14.478187][ T254] #13: ffffffff85979d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 14.478190][ T254] [ 14.478190][ T254] the dependencies between SOFTIRQ-irq-safe lock and the holding lock: [ 14.478191][ T254] -> (slock-AF_INET/2){+.-.}-{3:3} { [ 14.478193][ T254] HARDIRQ-ON-W at: [ 14.478194][ T254] __lock_acquire+0x388/0xc20 [ 14.478195][ T254] lock_acquire.part.0+0xd4/0x280 [ 14.478197][ T254] _raw_spin_lock_nested+0x3d/0x50 [ 14.478198][ T254] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.478200][ T254] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.478202][ T254] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.478203][ T254] __ppp_channel_push+0xed/0x280 [ppp_generic] [ 14.478205][ T254] ppp_channel_push+0x269/0x7b0 [ppp_generic] [ 14.478207][ T254] ppp_write+0x26b/0x350 [ppp_generic] [ 14.478208][ T254] vfs_write+0x1a4/0xbd0 [ 14.478211][ T254] ksys_write+0x116/0x250 [ 14.478213][ T254] do_syscall_64+0xff/0x530 [ 14.478214][ T254] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.478216][ T254] IN-SOFTIRQ-W at: [ 14.478216][ T254] __lock_acquire+0x388/0xc20 [ 14.478218][ T254] lock_acquire.part.0+0xd4/0x280 [ 14.478219][ T254] _raw_spin_lock_nested+0x3d/0x50 [ 14.478220][ T254] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.478223][ T254] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.478225][ T254] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.478226][ T254] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.478228][ T254] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.478233][ T254] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.478235][ T254] dev_hard_start_xmit+0xf4/0x620 [ 14.478236][ T254] __dev_queue_xmit+0xc0a/0x1b80 [ 14.478238][ T254] ip_finish_output2+0x5b9/0x1bc0 [ 14.478239][ T254] ip_output+0x1ca/0x660 [ 14.478241][ T254] ip_push_pending_frames+0xf6/0x250 [ 14.478242][ T254] icmp_reply+0x94d/0xa50 [ 14.478244][ T254] icmp_echo+0x2ab/0x4b0 [ 14.478245][ T254] icmp_rcv+0x92f/0xfb0 [ 14.478247][ T254] ip_protocol_deliver_rcu+0x82/0x350 [ 14.478248][ T254] ip_local_deliver_finish+0x36f/0x610 [ 14.478249][ T254] ip_local_deliver+0x184/0x4c0 [ 14.478250][ T254] ip_rcv+0xdc/0x3d0 [ 14.478252][ T254] __netif_receive_skb_one_core+0xfc/0x180 [ 14.478253][ T254] process_backlog+0x431/0x14c0 [ 14.478255][ T254] __napi_poll+0xa7/0x3b0 [ 14.478257][ T254] net_rx_action+0x513/0xf50 [ 14.478258][ T254] handle_softirqs+0x1d3/0x900 [ 14.478259][ T254] do_softirq+0xac/0xe0 [ 14.478261][ T254] __local_bh_enable_ip+0x118/0x150 [ 14.478262][ T254] __dev_queue_xmit+0x979/0x1b80 [ 14.478263][ T254] ip_finish_output2+0x5b9/0x1bc0 [ 14.478265][ T254] ip_output+0x1ca/0x660 [ 14.478266][ T254] ip_push_pending_frames+0xf6/0x250 [ 14.478267][ T254] raw_sendmsg+0x1169/0x1cc0 [ 14.478269][ T254] __sys_sendto+0x2aa/0x400 [ 14.478270][ T254] __x64_sys_sendto+0xe4/0x1f0 [ 14.478272][ T254] do_syscall_64+0xff/0x530 [ 14.478273][ T254] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.478274][ T254] INITIAL USE at: [ 14.478275][ T254] __lock_acquire+0x388/0xc20 [ 14.478276][ T254] lock_acquire.part.0+0xd4/0x280 [ 14.478278][ T254] _raw_spin_lock_nested+0x3d/0x50 [ 14.478279][ T254] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.478281][ T254] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.478283][ T254] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.478284][ T254] __ppp_channel_push+0xed/0x280 [ppp_generic] [ 14.478286][ T254] ppp_channel_push+0x269/0x7b0 [ppp_generic] [ 14.478287][ T254] ppp_write+0x26b/0x350 [ppp_generic] [ 14.478289][ T254] vfs_write+0x1a4/0xbd0 [ 14.478290][ T254] ksys_write+0x116/0x250 [ 14.478292][ T254] do_syscall_64+0xff/0x530 [ 14.478293][ T254] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.478294][ T254] } [ 14.478295][ T254] ... key at: [] af_family_slock_keys+0x22/0x300 [ 14.478298][ T254] [ 14.478298][ T254] the dependencies between the lock to be acquired [ 14.478299][ T254] and SOFTIRQ-irq-unsafe lock: [ 14.478304][ T254] -> (fs_reclaim){+.+.}-{0:0} { [ 14.478305][ T254] HARDIRQ-ON-W at: [ 14.478306][ T254] __lock_acquire+0x388/0xc20 [ 14.478308][ T254] lock_acquire.part.0+0xd4/0x280 [ 14.478309][ T254] fs_reclaim_acquire+0xd5/0x120 [ 14.478310][ T254] __kmalloc_cache_noprof+0x6e/0x620 [ 14.478311][ T254] kthread_create_worker_on_node+0xea/0x210 [ 14.478313][ T254] workqueue_init+0x2a/0x680 [ 14.478314][ T254] kernel_init_freeable+0x2fe/0x630 [ 14.478315][ T254] kernel_init+0x21/0x150 [ 14.478316][ T254] ret_from_fork+0x474/0x6b0 [ 14.478317][ T254] ret_from_fork_asm+0x11/0x20 [ 14.478319][ T254] SOFTIRQ-ON-W at: [ 14.478319][ T254] __lock_acquire+0x388/0xc20 [ 14.478321][ T254] lock_acquire.part.0+0xd4/0x280 [ 14.478322][ T254] fs_reclaim_acquire+0xd5/0x120 [ 14.478323][ T254] __kmalloc_cache_noprof+0x6e/0x620 [ 14.478324][ T254] kthread_create_worker_on_node+0xea/0x210 [ 14.478326][ T254] workqueue_init+0x2a/0x680 [ 14.478326][ T254] kernel_init_freeable+0x2fe/0x630 [ 14.478328][ T254] kernel_init+0x21/0x150 [ 14.478329][ T254] ret_from_fork+0x474/0x6b0 [ 14.478330][ T254] ret_from_fork_asm+0x11/0x20 [ 14.478331][ T254] INITIAL USE at: [ 14.478332][ T254] __lock_acquire+0x388/0xc20 [ 14.478333][ T254] lock_acquire.part.0+0xd4/0x280 [ 14.478335][ T254] fs_reclaim_acquire+0xd5/0x120 [ 14.478336][ T254] __kmalloc_cache_noprof+0x6e/0x620 [ 14.478337][ T254] kthread_create_worker_on_node+0xea/0x210 [ 14.478338][ T254] workqueue_init+0x2a/0x680 [ 14.478339][ T254] kernel_init_freeable+0x2fe/0x630 [ 14.478341][ T254] kernel_init+0x21/0x150 [ 14.478342][ T254] ret_from_fork+0x474/0x6b0 [ 14.478343][ T254] ret_from_fork_asm+0x11/0x20 [ 14.478344][ T254] } [ 14.478345][ T254] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 14.478348][ T254] ... acquired at: [ 14.478349][ T254] __lock_acquire+0x518/0xc20 [ 14.478350][ T254] lock_acquire.part.0+0xd4/0x280 [ 14.478352][ T254] fs_reclaim_acquire+0xd5/0x120 [ 14.478353][ T254] __kmalloc_noprof+0xd3/0x760 [ 14.478354][ T254] alloc_buf.isra.0+0x4b/0x260 [ 14.478357][ T254] put_chars+0x1e1/0x2f0 [ 14.478358][ T254] hvc_console_print+0x292/0x780 [ 14.478360][ T254] console_emit_next_record+0x22f/0x4c0 [ 14.478362][ T254] console_flush_one_record+0x46f/0x710 [ 14.478364][ T254] console_unlock+0xee/0x1f0 [ 14.478365][ T254] vprintk_emit+0x37c/0x3e0 [ 14.478367][ T254] _printk+0xc7/0x100 [ 14.478369][ T254] dm_interface_init+0x50/0x60 [ 14.478372][ T254] dm_init+0x51/0xd0 [ 14.478373][ T254] do_one_initcall+0x124/0x4f0 [ 14.478375][ T254] kernel_init_freeable+0x596/0x630 [ 14.478377][ T254] kernel_init+0x21/0x150 [ 14.478378][ T254] ret_from_fork+0x474/0x6b0 [ 14.478379][ T254] ret_from_fork_asm+0x11/0x20 [ 14.478380][ T254] [ 14.478381][ T254] -> (console_owner){....}-{0:0} { [ 14.478382][ T254] INITIAL USE at: [ 14.478383][ T254] } [ 14.478383][ T254] ... key at: [] console_owner_dep_map+0x0/0x60 [ 14.478387][ T254] ... acquired at: [ 14.478387][ T254] check_prev_add+0xef/0xe90 [ 14.478389][ T254] validate_chain+0x6b8/0xc20 [ 14.478390][ T254] __lock_acquire+0x518/0xc20 [ 14.478391][ T254] lock_acquire.part.0+0xd4/0x280 [ 14.478392][ T254] console_lock_spinning_enable+0x5c/0x60 [ 14.478394][ T254] console_emit_next_record+0x1d1/0x4c0 [ 14.478395][ T254] console_flush_one_record+0x46f/0x710 [ 14.478397][ T254] console_unlock+0xee/0x1f0 [ 14.478398][ T254] vprintk_emit+0x37c/0x3e0 [ 14.478400][ T254] _printk+0xc7/0x100 [ 14.478401][ T254] netdev_err+0xe2/0x120 [ 14.478403][ T254] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 14.478405][ T254] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.478407][ T254] dev_hard_start_xmit+0xf4/0x620 [ 14.478408][ T254] __dev_queue_xmit+0xc0a/0x1b80 [ 14.478410][ T254] ip_finish_output2+0x5b9/0x1bc0 [ 14.478411][ T254] ip_output+0x1ca/0x660 [ 14.478412][ T254] __ip_queue_xmit+0x774/0x1650 [ 14.478414][ T254] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 14.478416][ T254] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.478418][ T254] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.478419][ T254] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.478421][ T254] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.478422][ T254] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.478424][ T254] dev_hard_start_xmit+0xf4/0x620 [ 14.478425][ T254] __dev_queue_xmit+0xc0a/0x1b80 [ 14.478427][ T254] ip_finish_output2+0x5b9/0x1bc0 [ 14.478428][ T254] ip_output+0x1ca/0x660 [ 14.478429][ T254] ip_push_pending_frames+0xf6/0x250 [ 14.478431][ T254] raw_sendmsg+0x1169/0x1cc0 [ 14.478432][ T254] __sys_sendto+0x2aa/0x400 [ 14.478434][ T254] __x64_sys_sendto+0xe4/0x1f0 [ 14.478435][ T254] do_syscall_64+0xff/0x530 [ 14.478436][ T254] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.478437][ T254] [ 14.478438][ T254] [ 14.478438][ T254] stack backtrace: [ 14.478440][ T254] CPU: 3 UID: 0 PID: 254 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 14.478443][ T254] Tainted: [W]=WARN [ 14.478444][ T254] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.478445][ T254] Call Trace: [ 14.478446][ T254] [ 14.478447][ T254] dump_stack_lvl+0x6f/0xa0 [ 14.478450][ T254] print_bad_irq_dependency+0x3e4/0x410 [ 14.478453][ T254] check_irq_usage+0x403/0x570 [ 14.478455][ T254] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.478458][ T254] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.478461][ T254] ? check_path.constprop.0+0x24/0x50 [ 14.478464][ T254] ? check_prev_add+0xef/0xe90 [ 14.478465][ T254] check_prev_add+0xef/0xe90 [ 14.478467][ T254] validate_chain+0x6b8/0xc20 [ 14.478469][ T254] __lock_acquire+0x518/0xc20 [ 14.478471][ T254] lock_acquire.part.0+0xd4/0x280 [ 14.478472][ T254] ? console_lock_spinning_enable+0x40/0x60 [ 14.478474][ T254] ? rcu_is_watching+0x16/0xd0 [ 14.478476][ T254] ? lock_acquire+0x13c/0x160 [ 14.478478][ T254] console_lock_spinning_enable+0x5c/0x60 [ 14.478480][ T254] ? console_lock_spinning_enable+0x40/0x60 [ 14.478481][ T254] console_emit_next_record+0x1d1/0x4c0 [ 14.478484][ T254] ? devkmsg_read+0x4b0/0x4b0 [ 14.478485][ T254] ? console_flush_one_record+0x106/0x710 [ 14.478487][ T254] ? rcu_is_watching+0x16/0xd0 [ 14.478489][ T254] ? lock_acquire+0x13c/0x160 [ 14.478491][ T254] console_flush_one_record+0x46f/0x710 [ 14.478493][ T254] ? console_emit_next_record+0x4c0/0x4c0 [ 14.478494][ T254] ? __lock_acquire+0x518/0xc20 [ 14.478497][ T254] console_unlock+0xee/0x1f0 [ 14.478498][ T254] ? console_flush_one_record+0x710/0x710 [ 14.478500][ T254] ? rcu_is_watching+0x16/0xd0 [ 14.478502][ T254] ? lock_acquire+0x60/0x160 [ 14.478503][ T254] ? __down_trylock_console_sem+0x5e/0xa0 [ 14.478505][ T254] ? vprintk_emit+0x320/0x3e0 [ 14.478507][ T254] vprintk_emit+0x37c/0x3e0 [ 14.478509][ T254] ? wake_up_klogd_work_func+0x90/0x90 [ 14.478511][ T254] ? mark_lock+0x3bb/0xa00 [ 14.478513][ T254] _printk+0xc7/0x100 [ 14.478515][ T254] ? snapshot_read.cold+0x21/0x21 [ 14.478516][ T254] ? lock_acquire.part.0+0xd4/0x280 [ 14.478518][ T254] ? find_held_lock+0x2b/0x80 [ 14.478519][ T254] ? netdev_name+0x43/0x60 [ 14.478520][ T254] ? __netdev_printk+0x17f/0x1d0 [ 14.478522][ T254] netdev_err+0xe2/0x120 [ 14.478524][ T254] ? netdev_crit+0x120/0x120 [ 14.478525][ T254] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 14.478526][ T254] ? ___ratelimit+0x4e7/0xa00 [ 14.478529][ T254] ? idr_get_free+0x830/0x830 [ 14.478531][ T254] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 14.478534][ T254] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 14.478536][ T254] ? dst_release+0x54/0x240 [ 14.478538][ T254] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.478540][ T254] ? dev_nit_active_rcu+0x1f/0xf0 [ 14.478543][ T254] ? rcu_is_watching+0x16/0xd0 [ 14.478545][ T254] dev_hard_start_xmit+0xf4/0x620 [ 14.478547][ T254] ? rcu_is_watching+0x16/0xd0 [ 14.478549][ T254] __dev_queue_xmit+0xc0a/0x1b80 [ 14.478551][ T254] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 14.478552][ T254] ? lockdep_hardirqs_on+0x91/0x130 [ 14.478554][ T254] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 14.478555][ T254] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 14.478557][ T254] ? ref_tracker_dir_print+0x100/0x100 [ 14.478560][ T254] ? dst_init+0x84/0x530 [ 14.478561][ T254] ? dst_alloc+0x97/0x160 [ 14.478563][ T254] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 14.478564][ T254] ? ip_route_output_flow+0x21/0x150 [ 14.478566][ T254] ? __ip_queue_xmit+0xdc7/0x1650 [ 14.478567][ T254] ? __lock_acquire+0x518/0xc20 [ 14.478569][ T254] ? down_read_nested+0xe7/0x500 [ 14.478570][ T254] ? lock_acquire.part.0+0xd4/0x280 [ 14.478572][ T254] ? ip_finish_output2+0x25c/0x1bc0 [ 14.478573][ T254] ? rcu_is_watching+0x16/0xd0 [ 14.478576][ T254] ip_finish_output2+0x5b9/0x1bc0 [ 14.478578][ T254] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 14.478579][ T254] ? lock_acquire.part.0+0xd4/0x280 [ 14.478581][ T254] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 14.478583][ T254] ip_output+0x1ca/0x660 [ 14.478585][ T254] ? ip_finish_output+0x460/0x460 [ 14.478587][ T254] ? find_held_lock+0x2b/0x80 [ 14.478588][ T254] ? __lock_release.isra.0+0x69/0x1a0 [ 14.478590][ T254] ? ip4_dst_hoplimit+0x15b/0x320 [ 14.478592][ T254] __ip_queue_xmit+0x774/0x1650 [ 14.478594][ T254] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 14.478598][ T254] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.478601][ T254] ? l2tp_xmit_core.constprop.0+0x19b0/0x19b0 [l2tp_core] [ 14.478603][ T254] ? do_raw_spin_lock+0x131/0x280 [ 14.478605][ T254] ? pppol2tp_xmit+0x423/0x690 [l2tp_ppp] [ 14.478606][ T254] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.478608][ T254] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.478610][ T254] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.478613][ T254] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 14.478615][ T254] ? dst_release+0x54/0x240 [ 14.478616][ T254] ? down_read_nested+0x36c/0x500 [ 14.478618][ T254] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.478620][ T254] ? dev_nit_active_rcu+0x1f/0xf0 [ 14.478621][ T254] ? rcu_is_watching+0x16/0xd0 [ 14.478623][ T254] dev_hard_start_xmit+0xf4/0x620 [ 14.478625][ T254] ? rcu_is_watching+0x16/0xd0 [ 14.478627][ T254] __dev_queue_xmit+0xc0a/0x1b80 [ 14.478629][ T254] ? sock_queue_err_skb+0x680/0x680 [ 14.478632][ T254] ? kmem_cache_alloc_node_noprof+0x2a6/0x660 [ 14.478633][ T254] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.478635][ T254] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.478637][ T254] ? validate_chain+0x38b/0xc20 [ 14.478638][ T254] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 14.478640][ T254] ? __lock_acquire+0x518/0xc20 [ 14.478641][ T254] ? down_read_nested+0xe7/0x500 [ 14.478643][ T254] ? lock_acquire.part.0+0xd4/0x280 [ 14.478644][ T254] ? ip_finish_output2+0x25c/0x1bc0 [ 14.478646][ T254] ? rcu_is_watching+0x16/0xd0 [ 14.478648][ T254] ip_finish_output2+0x5b9/0x1bc0 [ 14.478650][ T254] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 14.478652][ T254] ? lock_acquire.part.0+0xd4/0x280 [ 14.478653][ T254] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 14.478655][ T254] ? rcu_is_watching+0x16/0xd0 [ 14.478657][ T254] ip_output+0x1ca/0x660 [ 14.478659][ T254] ? ip_finish_output+0x460/0x460 [ 14.478661][ T254] ? __ip_make_skb+0x106a/0x2300 [ 14.478664][ T254] ip_push_pending_frames+0xf6/0x250 [ 14.478665][ T254] raw_sendmsg+0x1169/0x1cc0 [ 14.478667][ T254] ? raw_send_hdrinc+0x1740/0x1740 [ 14.478669][ T254] ? do_raw_spin_unlock+0x59/0x250 [ 14.478670][ T254] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.478674][ T254] ? __might_fault+0x97/0x140 [ 14.478677][ T254] ? __might_fault+0x97/0x140 [ 14.478679][ T254] ? __might_fault+0x97/0x140 [ 14.478681][ T254] __sys_sendto+0x2aa/0x400 [ 14.478683][ T254] ? __ia32_sys_getpeername+0xd0/0xd0 [ 14.478686][ T254] ? exc_page_fault+0x87/0x100 [ 14.478688][ T254] __x64_sys_sendto+0xe4/0x1f0 [ 14.478690][ T254] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 14.478693][ T254] ? lockdep_hardirqs_on+0x91/0x130 [ 14.478694][ T254] ? do_syscall_64+0xa6/0x530 [ 14.478695][ T254] do_syscall_64+0xff/0x530 [ 14.478697][ T254] ? exc_page_fault+0xee/0x100 [ 14.478698][ T254] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.478700][ T254] RIP: 0033:0x7fb56f35c54e [ 14.478702][ T254] 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.478704][ T254] RSP: 002b:00007ffe1b9f1da0 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 14.478706][ T254] RAX: ffffffffffffffda RBX: 0000000000000040 RCX: 00007fb56f35c54e [ 14.478707][ T254] RDX: 0000000000000040 RSI: 00005588990ae0a4 RDI: 0000000000000005 [ 14.478708][ T254] RBP: 00007ffe1b9f1db0 R08: 00005588990c0320 R09: 0000000000000010 [ 14.478709][ T254] R10: 0000000000000000 R11: 0000000000000202 R12: 431bde82d7b634db [ 14.478710][ T254] R13: 0000000000000000 R14: 00005588990c13e0 R15: 00005588990ae0a0 [ 14.478712][ T254] [ 15.482727][ T254] ping (254) used greatest stack depth: 22536 bytes left [ 15.482743][ T254] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 15.482745][ T254] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 254, name: ping [ 15.482747][ T254] preempt_count: 2, expected: 0 [ 15.482747][ T254] RCU nest depth: 0, expected: 0 [ 15.482748][ T254] INFO: lockdep is turned off. [ 15.482749][ T254] irq event stamp: 24613 [ 15.482750][ T254] hardirqs last enabled at (24612): [] __down_trylock_console_sem+0x86/0xa0 [ 15.482758][ T254] hardirqs last disabled at (24613): [] console_emit_next_record+0x3d4/0x4c0 [ 15.482761][ T254] softirqs last enabled at (24516): [] __alloc_skb+0x4c2/0x5f0 [ 15.482766][ T254] softirqs last disabled at (24526): [] __dev_queue_xmit+0x1fc/0x1b80 [ 15.482769][ T254] Preemption disabled at: [ 15.482770][ T254] [<0000000000000000>] 0x0 [ 15.482776][ T254] CPU: 1 UID: 0 PID: 254 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.482780][ T254] Tainted: [W]=WARN [ 15.482780][ T254] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.482782][ T254] Call Trace: [ 15.482784][ T254] [ 15.482785][ T254] dump_stack_lvl+0x6f/0xa0 [ 15.482790][ T254] __might_resched.cold+0x1fe/0x2c1 [ 15.482794][ T254] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 15.482797][ T254] ? __asan_memcpy+0x3c/0x60 [ 15.482800][ T254] ? rcu_is_watching+0x16/0xd0 [ 15.482804][ T254] __kmalloc_noprof+0x443/0x760 [ 15.482807][ T254] ? __rwlock_init+0x150/0x150 [ 15.482809][ T254] ? alloc_buf.isra.0+0x4b/0x260 [ 15.482813][ T254] ? do_raw_spin_unlock+0x59/0x250 [ 15.482815][ T254] alloc_buf.isra.0+0x4b/0x260 [ 15.482817][ T254] put_chars+0x1e1/0x2f0 [ 15.482819][ T254] ? __send_to_port+0x420/0x420 [ 15.482821][ T254] ? console_prepend_replay+0x20/0x20 [ 15.482823][ T254] hvc_console_print+0x292/0x780 [ 15.482827][ T254] ? hvc_write+0x3a0/0x3a0 [ 15.482828][ T254] ? rcu_is_watching+0x16/0xd0 [ 15.482830][ T254] ? lock_acquire+0x13c/0x160 [ 15.482832][ T254] console_emit_next_record+0x22f/0x4c0 [ 15.482835][ T254] ? devkmsg_read+0x4b0/0x4b0 [ 15.482836][ T254] ? rcu_is_watching+0x16/0xd0 [ 15.482838][ T254] ? rcu_is_watching+0x16/0xd0 [ 15.482840][ T254] ? rcu_is_watching+0x16/0xd0 [ 15.482841][ T254] ? lock_acquire+0x13c/0x160 [ 15.482843][ T254] ? console_flush_one_record+0x111/0x710 [ 15.482845][ T254] console_flush_one_record+0x46f/0x710 [ 15.482847][ T254] ? console_emit_next_record+0x4c0/0x4c0 [ 15.482850][ T254] console_unlock+0xee/0x1f0 [ 15.482851][ T254] ? lock_acquire+0x13c/0x160 [ 15.482853][ T254] ? console_flush_one_record+0x710/0x710 [ 15.482855][ T254] ? rcu_is_watching+0x16/0xd0 [ 15.482856][ T254] ? lock_release+0x184/0x1f0 [ 15.482857][ T254] ? lock_acquire+0x60/0x160 [ 15.482859][ T254] ? __rcu_read_lock+0x30/0x70 [ 15.482861][ T254] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.482862][ T254] ? vprintk_emit+0x320/0x3e0 [ 15.482864][ T254] vprintk_emit+0x37c/0x3e0 [ 15.482867][ T254] ? wake_up_klogd_work_func+0x90/0x90 [ 15.482869][ T254] ? do_raw_read_unlock+0x70/0x70 [ 15.482870][ T254] ? forget_original_parent+0x5c/0xdb0 [ 15.482874][ T254] _printk+0xc7/0x100 [ 15.482877][ T254] ? snapshot_read.cold+0x21/0x21 [ 15.482879][ T254] ? do_raw_spin_lock+0x131/0x280 [ 15.482880][ T254] ? do_raw_spin_lock+0x131/0x280 [ 15.482882][ T254] ? __rwlock_init+0x150/0x150 [ 15.482884][ T254] ? do_raw_spin_lock+0x131/0x280 [ 15.482886][ T254] do_exit.cold+0x82/0x9c [ 15.482889][ T254] ? exit_notify+0x890/0x890 [ 15.482890][ T254] ? posixtimer_sig_unignore.isra.0+0x5f0/0x5f0 [ 15.482892][ T254] ? rcu_is_watching+0x16/0xd0 [ 15.482894][ T254] do_group_exit+0xb8/0x370 [ 15.482896][ T254] __x64_sys_exit_group+0x3c/0x50 [ 15.482897][ T254] x64_sys_call+0x1567/0x1570 [ 15.482899][ T254] do_syscall_64+0xff/0x530 [ 15.482903][ T254] ? exc_page_fault+0xee/0x100 [ 15.482904][ T254] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.482906][ T254] RIP: 0033:0x7fb56f3ad1b8 [ 15.482908][ T254] Code: Unable to access opcode bytes at 0x7fb56f3ad18e. [ 15.482909][ T254] RSP: 002b:00007ffe1b9f3538 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 15.482913][ T254] RAX: ffffffffffffffda RBX: 00007fb56f4ddf88 RCX: 00007fb56f3ad1b8 [ 15.482914][ T254] RDX: 00007fb56f13e7c8 RSI: ffffffffffffffb0 RDI: 0000000000000001 [ 15.482916][ T254] RBP: 00007ffe1b9f3590 R08: 0000000000000000 R09: 0000000000001010 [ 15.482917][ T254] R10: 00007ffe1b9f33b0 R11: 0000000000000246 R12: 0000000000000002 [ 15.482918][ T254] R13: 0000000000000001 R14: 00007fb56f4dc680 R15: 00007fb56f4ddfa0 [ 15.482920][ T254] [ 15.531041][ T214] ppp0: recursion detected