[ 8.839276][ T179] PPP generic driver version 2.4.2 [ 8.839290][ T179] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 8.839292][ T179] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 179, name: modprobe [ 8.839294][ T179] preempt_count: 1, expected: 0 [ 8.839295][ T179] RCU nest depth: 0, expected: 0 [ 8.839296][ T179] locks held by modprobe/179: 4, last CPU#3: [ 8.839298][ T179] #0: ffffffff8c379cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 8.839310][ T179] #1: ffffffff8c379d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 8.839314][ T179] #2: ffffffff8c299660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 8.839318][ T179] #3: ffffffff8c299560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 8.839322][ T179] irq event stamp: 40456 [ 8.839322][ T179] hardirqs last enabled at (40455): [] __down_trylock_console_sem+0x86/0xa0 [ 8.839325][ T179] hardirqs last disabled at (40456): [] console_emit_next_record+0x3d4/0x4c0 [ 8.839327][ T179] softirqs last enabled at (39598): [] handle_softirqs+0x67c/0x900 [ 8.839330][ T179] softirqs last disabled at (39591): [] __irq_exit_rcu+0x145/0x1c0 [ 8.839332][ T179] Preemption disabled at: [ 8.839333][ T179] [] vprintk_emit+0x31b/0x3e0 [ 8.839338][ T179] CPU: 3 UID: 0 PID: 179 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 8.839342][ T179] Tainted: [W]=WARN [ 8.839343][ T179] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 8.839344][ T179] Call Trace: [ 8.839346][ T179] [ 8.839347][ T179] dump_stack_lvl+0x6f/0xa0 [ 8.839353][ T179] ? vprintk_emit+0x31b/0x3e0 [ 8.839355][ T179] __might_resched.cold+0x1fe/0x2c1 [ 8.839359][ T179] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 8.839363][ T179] ? __kmalloc_noprof+0xdb/0x760 [ 8.839368][ T179] __kmalloc_noprof+0x443/0x760 [ 8.839370][ T179] ? alloc_buf.isra.0+0x4b/0x260 [ 8.839376][ T179] ? do_raw_spin_unlock+0x59/0x250 [ 8.839378][ T179] alloc_buf.isra.0+0x4b/0x260 [ 8.839382][ T179] put_chars+0x1e1/0x2f0 [ 8.839384][ T179] ? __send_to_port+0x420/0x420 [ 8.839386][ T179] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 8.839390][ T179] ? rcu_read_lock_any_held+0x3c/0x90 [ 8.839393][ T179] ? validate_chain+0x38b/0xc20 [ 8.839397][ T179] hvc_console_print+0x292/0x780 [ 8.839400][ T179] ? __lock_acquire+0x518/0xc20 [ 8.839405][ T179] ? hvc_write+0x3a0/0x3a0 [ 8.839408][ T179] ? rcu_is_watching+0x16/0xd0 [ 8.839412][ T179] console_emit_next_record+0x22f/0x4c0 [ 8.839416][ T179] ? devkmsg_read+0x4b0/0x4b0 [ 8.839418][ T179] ? console_flush_one_record+0x106/0x710 [ 8.839421][ T179] ? rcu_is_watching+0x16/0xd0 [ 8.839423][ T179] ? lock_acquire+0x13c/0x160 [ 8.839427][ T179] console_flush_one_record+0x46f/0x710 [ 8.839431][ T179] ? console_emit_next_record+0x4c0/0x4c0 [ 8.839433][ T179] ? __lock_acquire+0x518/0xc20 [ 8.839437][ T179] console_unlock+0xee/0x1f0 [ 8.839440][ T179] ? console_flush_one_record+0x710/0x710 [ 8.839442][ T179] ? rcu_is_watching+0x16/0xd0 [ 8.839444][ T179] ? lock_acquire+0x60/0x160 [ 8.839447][ T179] ? __down_trylock_console_sem+0x5e/0xa0 [ 8.839449][ T179] ? vprintk_emit+0x320/0x3e0 [ 8.839452][ T179] vprintk_emit+0x37c/0x3e0 [ 8.839456][ T179] ? wake_up_klogd_work_func+0x90/0x90 [ 8.839461][ T179] ? ppp_cleanup+0x920/0x920 [ppp_generic] [ 8.839465][ T179] _printk+0xc7/0x100 [ 8.839469][ T179] ? snapshot_read.cold+0x21/0x21 [ 8.839474][ T179] ? do_one_initcall+0x113/0x4f0 [ 8.839478][ T179] ppp_init+0x16/0x1000 [ppp_generic] [ 8.839481][ T179] do_one_initcall+0x124/0x4f0 [ 8.839484][ T179] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 8.839488][ T179] ? do_init_module+0xae/0x8a0 [ 8.839491][ T179] ? kasan_unpoison+0x44/0x70 [ 8.839499][ T179] do_init_module+0x2ad/0x8a0 [ 8.839503][ T179] ? lockdep_hardirqs_on+0x91/0x130 [ 8.839507][ T179] ? free_module+0x470/0x470 [ 8.839510][ T179] ? kfree+0x123/0x590 [ 8.839513][ T179] load_module+0x1424/0x1bb0 [ 8.839522][ T179] ? layout_and_allocate.isra.0+0x680/0x680 [ 8.839527][ T179] ? backing_file_read_iter+0x541/0x790 [ 8.839530][ T179] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 8.839534][ T179] init_module_from_file+0x11f/0x1a0 [ 8.839537][ T179] ? __do_sys_init_module+0x220/0x220 [ 8.839545][ T179] ? __rwlock_init+0x150/0x150 [ 8.839548][ T179] ? do_raw_spin_unlock+0x59/0x250 [ 8.839551][ T179] idempotent_init_module+0x37b/0x7c0 [ 8.839555][ T179] ? init_module_from_file+0x1a0/0x1a0 [ 8.839561][ T179] ? rcu_is_watching+0x16/0xd0 [ 8.839563][ T179] ? cap_capable+0x1d7/0x3d0 [ 8.839569][ T179] __x64_sys_finit_module+0xdf/0x190 [ 8.839572][ T179] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 8.839575][ T179] do_syscall_64+0xff/0x530 [ 8.839578][ T179] ? exc_page_fault+0xee/0x100 [ 8.839580][ T179] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 8.839582][ T179] RIP: 0033:0x7f2daef7a37d [ 8.839586][ 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 [ 8.839588][ T179] RSP: 002b:00007ffed7a48a28 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 8.839590][ T179] RAX: ffffffffffffffda RBX: 000055bb166741d0 RCX: 00007f2daef7a37d [ 8.839591][ T179] RDX: 0000000000000000 RSI: 000055bb0bed8ffd RDI: 0000000000000006 [ 8.839592][ T179] RBP: 00007ffed7a48ac0 R08: 0000000000000000 R09: 0000000000000000 [ 8.839593][ T179] R10: 0000000000000000 R11: 0000000000000246 R12: 000055bb0bed8ffd [ 8.839594][ T179] R13: 000055bb16674180 R14: 0000000000040000 R15: 0000000000000000 [ 8.839600][ T179] [ 8.868117][ T179] NET: Registered PF_PPPOX protocol family [ 8.899785][ T179] l2tp_core: L2TP core driver, V2.0 [ 8.911738][ T179] l2tp_ppp: PPPoL2TP kernel driver, V2.0 [ 8.965574][ T183] ip (183) used greatest stack depth: 24800 bytes left [ 14.004222][ T241] iperf3 (241) used greatest stack depth: 22680 bytes left [ 14.004241][ T241] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 14.004243][ T241] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 241, name: iperf3 [ 14.004245][ T241] preempt_count: 2, expected: 0 [ 14.004245][ T241] RCU nest depth: 0, expected: 0 [ 14.004246][ T241] locks held by iperf3/241: 5, last CPU#0: [ 14.004248][ T241] #0: ffffffff8c2127b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 14.004260][ T241] #1: ffffffff8c379cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 14.004265][ T241] #2: ffffffff8c379d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 14.004269][ T241] #3: ffffffff8c299660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 14.004272][ T241] #4: ffffffff8c299560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 14.004276][ T241] irq event stamp: 1712066 [ 14.004277][ T241] hardirqs last enabled at (1712065): [] __down_trylock_console_sem+0x86/0xa0 [ 14.004280][ T241] hardirqs last disabled at (1712066): [] console_emit_next_record+0x3d4/0x4c0 [ 14.004282][ T241] softirqs last enabled at (1711282): [] handle_softirqs+0x67c/0x900 [ 14.004284][ T241] softirqs last disabled at (1711277): [] __irq_exit_rcu+0x145/0x1c0 [ 14.004286][ T241] Preemption disabled at: [ 14.004287][ T241] [<0000000000000000>] 0x0 [ 14.004293][ T241] CPU: 0 UID: 0 PID: 241 Comm: iperf3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 14.004297][ T241] Tainted: [W]=WARN [ 14.004297][ T241] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.004299][ T241] Call Trace: [ 14.004301][ T241] [ 14.004302][ T241] dump_stack_lvl+0x6f/0xa0 [ 14.004309][ T241] __might_resched.cold+0x1fe/0x2c1 [ 14.004313][ T241] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 14.004317][ T241] ? __kmalloc_noprof+0xdb/0x760 [ 14.004322][ T241] __kmalloc_noprof+0x443/0x760 [ 14.004324][ T241] ? alloc_buf.isra.0+0x4b/0x260 [ 14.004330][ T241] ? do_raw_spin_unlock+0x59/0x250 [ 14.004333][ T241] alloc_buf.isra.0+0x4b/0x260 [ 14.004336][ T241] put_chars+0x1e1/0x2f0 [ 14.004338][ T241] ? prb_final_commit+0x50/0x50 [ 14.004340][ T241] ? __send_to_port+0x420/0x420 [ 14.004343][ T241] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.004347][ T241] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.004350][ T241] ? validate_chain+0x38b/0xc20 [ 14.004353][ T241] hvc_console_print+0x292/0x780 [ 14.004356][ T241] ? mark_usage+0x61/0x170 [ 14.004358][ T241] ? __lock_acquire+0x518/0xc20 [ 14.004360][ T241] ? __lock_acquire+0x518/0xc20 [ 14.004363][ T241] ? hvc_write+0x3a0/0x3a0 [ 14.004366][ T241] ? console_emit_next_record+0x1df/0x4c0 [ 14.004369][ T241] ? rcu_is_watching+0x16/0xd0 [ 14.004371][ T241] ? lock_acquire+0x13c/0x160 [ 14.004375][ T241] console_emit_next_record+0x22f/0x4c0 [ 14.004378][ T241] ? devkmsg_read+0x4b0/0x4b0 [ 14.004380][ T241] ? console_flush_one_record+0x106/0x710 [ 14.004384][ T241] ? rcu_is_watching+0x16/0xd0 [ 14.004386][ T241] ? lock_acquire+0x13c/0x160 [ 14.004390][ T241] console_flush_one_record+0x46f/0x710 [ 14.004394][ T241] ? console_emit_next_record+0x4c0/0x4c0 [ 14.004395][ T241] ? __lock_acquire+0x518/0xc20 [ 14.004400][ T241] console_unlock+0xee/0x1f0 [ 14.004403][ T241] ? console_flush_one_record+0x710/0x710 [ 14.004405][ T241] ? rcu_is_watching+0x16/0xd0 [ 14.004407][ T241] ? lock_acquire+0x60/0x160 [ 14.004411][ T241] ? __down_trylock_console_sem+0x5e/0xa0 [ 14.004412][ T241] ? vprintk_emit+0x320/0x3e0 [ 14.004415][ T241] vprintk_emit+0x37c/0x3e0 [ 14.004419][ T241] ? wake_up_klogd_work_func+0x90/0x90 [ 14.004422][ T241] ? __lock_acquire+0x518/0xc20 [ 14.004426][ T241] _printk+0xc7/0x100 [ 14.004429][ T241] ? snapshot_read.cold+0x21/0x21 [ 14.004432][ T241] ? do_raw_spin_lock+0x131/0x280 [ 14.004434][ T241] ? __rwlock_init+0x150/0x150 [ 14.004438][ T241] ? do_raw_spin_lock+0x131/0x280 [ 14.004441][ T241] do_exit.cold+0x82/0x9c [ 14.004445][ T241] ? exit_notify+0x890/0x890 [ 14.004449][ T241] __x64_sys_exit+0x42/0x50 [ 14.004451][ T241] x64_sys_call+0x156c/0x1570 [ 14.004454][ T241] do_syscall_64+0xff/0x530 [ 14.004457][ T241] ? exc_page_fault+0xee/0x100 [ 14.004460][ T241] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.004463][ T241] RIP: 0033:0x7fa04220ec49 [ 14.004465][ T241] Code: Unable to access opcode bytes at 0x7fa04220ec1f. [ 14.004466][ T241] RSP: 002b:00007fa0419cde60 EFLAGS: 00000246 ORIG_RAX: 000000000000003c [ 14.004469][ T241] RAX: ffffffffffffffda RBX: 00007fa0419ce6c0 RCX: 00007fa04220ec49 [ 14.004470][ T241] RDX: 0000000000000000 RSI: 0000000000801000 RDI: 0000000000000000 [ 14.004471][ T241] RBP: 00007fa0419cdf70 R08: 0000000000000001 R09: 00007fa0419ce6c0 [ 14.004472][ T241] R10: 0000000000000008 R11: 0000000000000246 R12: 00007fa0411ce000 [ 14.004473][ T241] R13: 00007fffd7c2be70 R14: 0000000000000002 R15: 00007fffd7c2bf77 [ 14.004479][ T241] [ 14.139795][ T245] ppp0: recursion detected [ 14.139825][ T245] [ 14.139826][ T245] ===================================================== [ 14.139827][ T245] WARNING: SOFTIRQ-safe -> SOFTIRQ-unsafe lock order detected [ 14.139829][ T245] 7.2.0-virtme #1 Tainted: G W [ 14.139830][ T245] ----------------------------------------------------- [ 14.139831][ T245] ping/245 [HC0[0]:SC0[10]:HE0:SE0] is trying to acquire: [ 14.139833][ T245] ffffffff8c299660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 14.139842][ T245] [ 14.139842][ T245] and this task is already holding: [ 14.139842][ T245] ff11000005902518 (slock-AF_INET/2){+.-.}-{3:3}, at: l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.139850][ T245] which would create a new lock dependency: [ 14.139851][ T245] (slock-AF_INET/2){+.-.}-{3:3} -> (console_owner){....}-{0:0} [ 14.139853][ T245] [ 14.139853][ T245] but this new dependency connects a SOFTIRQ-irq-safe lock: [ 14.139854][ T245] (slock-AF_INET/2){+.-.}-{3:3} [ 14.139855][ T245] [ 14.139855][ T245] ... which became SOFTIRQ-irq-safe at: [ 14.139856][ T245] __lock_acquire+0x388/0xc20 [ 14.139860][ T245] lock_acquire.part.0+0xd4/0x280 [ 14.139861][ T245] _raw_spin_lock_nested+0x3d/0x50 [ 14.139864][ T245] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.139868][ T245] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.139870][ T245] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.139872][ T245] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.139875][ T245] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.139877][ T245] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.139878][ T245] dev_hard_start_xmit+0xf4/0x620 [ 14.139882][ T245] __dev_queue_xmit+0xc0a/0x1b80 [ 14.139883][ T245] ip_finish_output2+0x5b9/0x1bc0 [ 14.139887][ T245] ip_output+0x1ca/0x660 [ 14.139888][ T245] ip_push_pending_frames+0xf6/0x250 [ 14.139890][ T245] icmp_reply+0x94d/0xa50 [ 14.139892][ T245] icmp_echo+0x2ab/0x4b0 [ 14.139894][ T245] icmp_rcv+0x92f/0xfb0 [ 14.139895][ T245] ip_protocol_deliver_rcu+0x82/0x350 [ 14.139897][ T245] ip_local_deliver_finish+0x36f/0x610 [ 14.139898][ T245] ip_local_deliver+0x184/0x4c0 [ 14.139900][ T245] ip_rcv+0xdc/0x3d0 [ 14.139901][ T245] __netif_receive_skb_one_core+0xfc/0x180 [ 14.139903][ T245] process_backlog+0x431/0x14c0 [ 14.139905][ T245] __napi_poll+0xa7/0x3b0 [ 14.139906][ T245] net_rx_action+0x513/0xf50 [ 14.139908][ T245] handle_softirqs+0x1d3/0x900 [ 14.139910][ T245] do_softirq+0xac/0xe0 [ 14.139911][ T245] __local_bh_enable_ip+0x118/0x150 [ 14.139913][ T245] __dev_queue_xmit+0x979/0x1b80 [ 14.139914][ T245] ip_finish_output2+0x5b9/0x1bc0 [ 14.139916][ T245] ip_output+0x1ca/0x660 [ 14.139917][ T245] ip_push_pending_frames+0xf6/0x250 [ 14.139918][ T245] raw_sendmsg+0x1169/0x1cc0 [ 14.139920][ T245] __sys_sendto+0x2aa/0x400 [ 14.139923][ T245] __x64_sys_sendto+0xe4/0x1f0 [ 14.139924][ T245] do_syscall_64+0xff/0x530 [ 14.139927][ T245] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.139929][ T245] [ 14.139929][ T245] to a SOFTIRQ-irq-unsafe lock: [ 14.139930][ T245] (fs_reclaim){+.+.}-{0:0} [ 14.139931][ T245] [ 14.139931][ T245] ... which became SOFTIRQ-irq-unsafe at: [ 14.139931][ T245] ... [ 14.139932][ T245] __lock_acquire+0x388/0xc20 [ 14.139934][ T245] lock_acquire.part.0+0xd4/0x280 [ 14.139935][ T245] fs_reclaim_acquire+0xd5/0x120 [ 14.139938][ T245] __kmalloc_cache_noprof+0x6e/0x620 [ 14.139940][ T245] kthread_create_worker_on_node+0xea/0x210 [ 14.139943][ T245] workqueue_init+0x2a/0x680 [ 14.139946][ T245] kernel_init_freeable+0x2fe/0x630 [ 14.139949][ T245] kernel_init+0x21/0x150 [ 14.139951][ T245] ret_from_fork+0x474/0x6b0 [ 14.139953][ T245] ret_from_fork_asm+0x11/0x20 [ 14.139956][ T245] [ 14.139956][ T245] other info that might help us debug this: [ 14.139956][ T245] [ 14.139957][ T245] Chain exists of: [ 14.139957][ T245] slock-AF_INET/2 --> console_owner --> fs_reclaim [ 14.139957][ T245] [ 14.139959][ T245] Possible interrupt unsafe locking scenario: [ 14.139959][ T245] [ 14.139959][ T245] CPU0 CPU1 [ 14.139960][ T245] ---- ---- [ 14.139961][ T245] lock(fs_reclaim); [ 14.139961][ T245] local_irq_disable(); [ 14.139962][ T245] lock(slock-AF_INET/2); [ 14.139963][ T245] lock(console_owner); [ 14.139964][ T245] [ 14.139964][ T245] lock(slock-AF_INET/2); [ 14.139965][ T245] [ 14.139965][ T245] *** DEADLOCK *** [ 14.139965][ T245] [ 14.139966][ T245] locks held by ping/245: 14, last CPU#2: [ 14.139967][ T245] #0: ff110000058de290 (sk_lock-AF_INET){+.+.}-{0:0}, at: raw_sendmsg+0xba5/0x1cc0 [ 14.139970][ T245] #1: ffffffff8c390b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 14.139973][ T245] #2: ffffffff8c390b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 14.139976][ T245] #3: ffffffff8c390aa0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x1fc/0x1b80 [ 14.139979][ T245] #4: ffd1ffffffd06088 (&xmit_recursion->bh_lock){+.-.}-{3:3}, at: ppp_xmit_process+0x1d2/0x8e0 [ppp_generic] [ 14.139982][ T245] #5: ff1100000c922fd0 (&ppp->wlock){+.-.}-{3:3}, at: ppp_xmit_process+0x292/0x8e0 [ppp_generic] [ 14.139985][ T245] #6: ff1100000d6e91d0 (&pch->downl){+.-.}-{3:3}, at: ppp_push+0xae/0x2d0 [ppp_generic] [ 14.139988][ T245] #7: ff11000005902518 (slock-AF_INET/2){+.-.}-{3:3}, at: l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.139993][ T245] #8: ffffffff8c390b00 (rcu_read_lock){....}-{1:3}, at: __ip_queue_xmit+0x63/0x1650 [ 14.139996][ T245] #9: ffffffff8c390b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 14.139999][ T245] #10: ffffffff8c390b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 14.140002][ T245] #11: ffffffff8c390aa0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x1fc/0x1b80 [ 14.140005][ T245] #12: ffffffff8c379cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 14.140008][ T245] #13: ffffffff8c379d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 14.140011][ T245] [ 14.140011][ T245] the dependencies between SOFTIRQ-irq-safe lock and the holding lock: [ 14.140012][ T245] -> (slock-AF_INET/2){+.-.}-{3:3} { [ 14.140014][ T245] HARDIRQ-ON-W at: [ 14.140015][ T245] __lock_acquire+0x388/0xc20 [ 14.140016][ T245] lock_acquire.part.0+0xd4/0x280 [ 14.140018][ T245] _raw_spin_lock_nested+0x3d/0x50 [ 14.140019][ T245] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.140022][ T245] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.140024][ T245] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.140025][ T245] __ppp_channel_push+0xed/0x280 [ppp_generic] [ 14.140027][ T245] ppp_channel_push+0x269/0x7b0 [ppp_generic] [ 14.140029][ T245] ppp_write+0x26b/0x350 [ppp_generic] [ 14.140031][ T245] vfs_write+0x1a4/0xbd0 [ 14.140033][ T245] ksys_write+0x116/0x250 [ 14.140035][ T245] do_syscall_64+0xff/0x530 [ 14.140036][ T245] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.140038][ T245] IN-SOFTIRQ-W at: [ 14.140038][ T245] __lock_acquire+0x388/0xc20 [ 14.140040][ T245] lock_acquire.part.0+0xd4/0x280 [ 14.140041][ T245] _raw_spin_lock_nested+0x3d/0x50 [ 14.140042][ T245] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.140045][ T245] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.140047][ T245] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.140048][ T245] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.140050][ T245] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.140052][ T245] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.140053][ T245] dev_hard_start_xmit+0xf4/0x620 [ 14.140055][ T245] __dev_queue_xmit+0xc0a/0x1b80 [ 14.140056][ T245] ip_finish_output2+0x5b9/0x1bc0 [ 14.140058][ T245] ip_output+0x1ca/0x660 [ 14.140059][ T245] ip_push_pending_frames+0xf6/0x250 [ 14.140060][ T245] icmp_reply+0x94d/0xa50 [ 14.140062][ T245] icmp_echo+0x2ab/0x4b0 [ 14.140063][ T245] icmp_rcv+0x92f/0xfb0 [ 14.140065][ T245] ip_protocol_deliver_rcu+0x82/0x350 [ 14.140066][ T245] ip_local_deliver_finish+0x36f/0x610 [ 14.140067][ T245] ip_local_deliver+0x184/0x4c0 [ 14.140069][ T245] ip_rcv+0xdc/0x3d0 [ 14.140070][ T245] __netif_receive_skb_one_core+0xfc/0x180 [ 14.140072][ T245] process_backlog+0x431/0x14c0 [ 14.140073][ T245] __napi_poll+0xa7/0x3b0 [ 14.140075][ T245] net_rx_action+0x513/0xf50 [ 14.140076][ T245] handle_softirqs+0x1d3/0x900 [ 14.140078][ T245] do_softirq+0xac/0xe0 [ 14.140079][ T245] __local_bh_enable_ip+0x118/0x150 [ 14.140080][ T245] __dev_queue_xmit+0x979/0x1b80 [ 14.140081][ T245] ip_finish_output2+0x5b9/0x1bc0 [ 14.140083][ T245] ip_output+0x1ca/0x660 [ 14.140084][ T245] ip_push_pending_frames+0xf6/0x250 [ 14.140085][ T245] raw_sendmsg+0x1169/0x1cc0 [ 14.140087][ T245] __sys_sendto+0x2aa/0x400 [ 14.140088][ T245] __x64_sys_sendto+0xe4/0x1f0 [ 14.140090][ T245] do_syscall_64+0xff/0x530 [ 14.140091][ T245] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.140092][ T245] INITIAL USE at: [ 14.140093][ T245] __lock_acquire+0x388/0xc20 [ 14.140094][ T245] lock_acquire.part.0+0xd4/0x280 [ 14.140095][ T245] _raw_spin_lock_nested+0x3d/0x50 [ 14.140096][ T245] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.140099][ T245] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.140102][ T245] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.140103][ T245] __ppp_channel_push+0xed/0x280 [ppp_generic] [ 14.140104][ T245] ppp_channel_push+0x269/0x7b0 [ppp_generic] [ 14.140106][ T245] ppp_write+0x26b/0x350 [ppp_generic] [ 14.140108][ T245] vfs_write+0x1a4/0xbd0 [ 14.140109][ T245] ksys_write+0x116/0x250 [ 14.140111][ T245] do_syscall_64+0xff/0x530 [ 14.140112][ T245] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.140113][ T245] } [ 14.140114][ T245] ... key at: [] af_family_slock_keys+0x22/0x300 [ 14.140117][ T245] [ 14.140117][ T245] the dependencies between the lock to be acquired [ 14.140117][ T245] and SOFTIRQ-irq-unsafe lock: [ 14.140122][ T245] -> (fs_reclaim){+.+.}-{0:0} { [ 14.140124][ T245] HARDIRQ-ON-W at: [ 14.140125][ T245] __lock_acquire+0x388/0xc20 [ 14.140126][ T245] lock_acquire.part.0+0xd4/0x280 [ 14.140127][ T245] fs_reclaim_acquire+0xd5/0x120 [ 14.140129][ T245] __kmalloc_cache_noprof+0x6e/0x620 [ 14.140130][ T245] kthread_create_worker_on_node+0xea/0x210 [ 14.140131][ T245] workqueue_init+0x2a/0x680 [ 14.140132][ T245] kernel_init_freeable+0x2fe/0x630 [ 14.140134][ T245] kernel_init+0x21/0x150 [ 14.140135][ T245] ret_from_fork+0x474/0x6b0 [ 14.140136][ T245] ret_from_fork_asm+0x11/0x20 [ 14.140137][ T245] SOFTIRQ-ON-W at: [ 14.140138][ T245] __lock_acquire+0x388/0xc20 [ 14.140139][ T245] lock_acquire.part.0+0xd4/0x280 [ 14.140141][ T245] fs_reclaim_acquire+0xd5/0x120 [ 14.140142][ T245] __kmalloc_cache_noprof+0x6e/0x620 [ 14.140143][ T245] kthread_create_worker_on_node+0xea/0x210 [ 14.140144][ T245] workqueue_init+0x2a/0x680 [ 14.140146][ T245] kernel_init_freeable+0x2fe/0x630 [ 14.140147][ T245] kernel_init+0x21/0x150 [ 14.140148][ T245] ret_from_fork+0x474/0x6b0 [ 14.140149][ T245] ret_from_fork_asm+0x11/0x20 [ 14.140151][ T245] INITIAL USE at: [ 14.140151][ T245] __lock_acquire+0x388/0xc20 [ 14.140152][ T245] lock_acquire.part.0+0xd4/0x280 [ 14.140154][ T245] fs_reclaim_acquire+0xd5/0x120 [ 14.140155][ T245] __kmalloc_cache_noprof+0x6e/0x620 [ 14.140156][ T245] kthread_create_worker_on_node+0xea/0x210 [ 14.140157][ T245] workqueue_init+0x2a/0x680 [ 14.140158][ T245] kernel_init_freeable+0x2fe/0x630 [ 14.140160][ T245] kernel_init+0x21/0x150 [ 14.140161][ T245] ret_from_fork+0x474/0x6b0 [ 14.140162][ T245] ret_from_fork_asm+0x11/0x20 [ 14.140163][ T245] } [ 14.140164][ T245] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 14.140167][ T245] ... acquired at: [ 14.140168][ T245] __lock_acquire+0x518/0xc20 [ 14.140169][ T245] lock_acquire.part.0+0xd4/0x280 [ 14.140171][ T245] fs_reclaim_acquire+0xd5/0x120 [ 14.140172][ T245] __kmalloc_noprof+0xd3/0x760 [ 14.140173][ T245] alloc_buf.isra.0+0x4b/0x260 [ 14.140176][ T245] put_chars+0x1e1/0x2f0 [ 14.140177][ T245] hvc_console_print+0x292/0x780 [ 14.140179][ T245] console_emit_next_record+0x22f/0x4c0 [ 14.140181][ T245] console_flush_one_record+0x46f/0x710 [ 14.140182][ T245] console_unlock+0xee/0x1f0 [ 14.140184][ T245] vprintk_emit+0x37c/0x3e0 [ 14.140185][ T245] dev_vprintk_emit+0x27f/0x2c0 [ 14.140188][ T245] dev_printk_emit+0xb9/0xee [ 14.140190][ T245] _dev_info+0xe2/0x116 [ 14.140191][ T245] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 14.140194][ T245] cmos_do_probe+0x73b/0x98a [ 14.140195][ T245] platform_probe+0xfe/0x1f0 [ 14.140197][ T245] call_driver_probe+0x61/0x1c0 [ 14.140200][ T245] really_probe+0x199/0x760 [ 14.140201][ T245] __driver_probe_device+0x24f/0x440 [ 14.140203][ T245] driver_probe_device+0x4a/0xf0 [ 14.140204][ T245] __driver_attach+0x1b8/0x540 [ 14.140206][ T245] bus_for_each_dev+0x130/0x1e0 [ 14.140207][ T245] bus_add_driver+0x2c8/0x530 [ 14.140208][ T245] driver_register+0x1a3/0x390 [ 14.140210][ T245] __platform_driver_probe+0x13f/0x270 [ 14.140211][ T245] cmos_init+0x31/0x40 [ 14.140214][ T245] do_one_initcall+0x124/0x4f0 [ 14.140216][ T245] kernel_init_freeable+0x596/0x630 [ 14.140218][ T245] kernel_init+0x21/0x150 [ 14.140219][ T245] ret_from_fork+0x474/0x6b0 [ 14.140220][ T245] ret_from_fork_asm+0x11/0x20 [ 14.140221][ T245] [ 14.140222][ T245] -> (console_owner){....}-{0:0} { [ 14.140223][ T245] INITIAL USE at: [ 14.140224][ T245] } [ 14.140224][ T245] ... key at: [] console_owner_dep_map+0x0/0x60 [ 14.140228][ T245] ... acquired at: [ 14.140228][ T245] check_prev_add+0xef/0xe90 [ 14.140229][ T245] validate_chain+0x6b8/0xc20 [ 14.140230][ T245] __lock_acquire+0x518/0xc20 [ 14.140232][ T245] lock_acquire.part.0+0xd4/0x280 [ 14.140233][ T245] console_lock_spinning_enable+0x5c/0x60 [ 14.140234][ T245] console_emit_next_record+0x1d1/0x4c0 [ 14.140236][ T245] console_flush_one_record+0x46f/0x710 [ 14.140237][ T245] console_unlock+0xee/0x1f0 [ 14.140239][ T245] vprintk_emit+0x37c/0x3e0 [ 14.140240][ T245] _printk+0xc7/0x100 [ 14.140243][ T245] netdev_err+0xe2/0x120 [ 14.140245][ T245] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 14.140247][ T245] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.140248][ T245] dev_hard_start_xmit+0xf4/0x620 [ 14.140250][ T245] __dev_queue_xmit+0xc0a/0x1b80 [ 14.140252][ T245] ip_finish_output2+0x5b9/0x1bc0 [ 14.140253][ T245] ip_output+0x1ca/0x660 [ 14.140254][ T245] __ip_queue_xmit+0x774/0x1650 [ 14.140256][ T245] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 14.140258][ T245] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.140261][ T245] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.140262][ T245] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.140263][ T245] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.140265][ T245] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.140267][ T245] dev_hard_start_xmit+0xf4/0x620 [ 14.140268][ T245] __dev_queue_xmit+0xc0a/0x1b80 [ 14.140270][ T245] ip_finish_output2+0x5b9/0x1bc0 [ 14.140271][ T245] ip_output+0x1ca/0x660 [ 14.140272][ T245] ip_push_pending_frames+0xf6/0x250 [ 14.140274][ T245] raw_sendmsg+0x1169/0x1cc0 [ 14.140275][ T245] __sys_sendto+0x2aa/0x400 [ 14.140276][ T245] __x64_sys_sendto+0xe4/0x1f0 [ 14.140278][ T245] do_syscall_64+0xff/0x530 [ 14.140279][ T245] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.140280][ T245] [ 14.140281][ T245] [ 14.140281][ T245] stack backtrace: [ 14.140283][ T245] CPU: 2 UID: 0 PID: 245 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 14.140286][ T245] Tainted: [W]=WARN [ 14.140287][ T245] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.140288][ T245] Call Trace: [ 14.140289][ T245] [ 14.140290][ T245] dump_stack_lvl+0x6f/0xa0 [ 14.140294][ T245] print_bad_irq_dependency+0x3e4/0x410 [ 14.140297][ T245] check_irq_usage+0x403/0x570 [ 14.140298][ T245] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.140302][ T245] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.140305][ T245] ? check_path.constprop.0+0x24/0x50 [ 14.140307][ T245] ? check_prev_add+0xef/0xe90 [ 14.140308][ T245] check_prev_add+0xef/0xe90 [ 14.140310][ T245] validate_chain+0x6b8/0xc20 [ 14.140312][ T245] __lock_acquire+0x518/0xc20 [ 14.140315][ T245] lock_acquire.part.0+0xd4/0x280 [ 14.140316][ T245] ? console_lock_spinning_enable+0x40/0x60 [ 14.140318][ T245] ? rcu_is_watching+0x16/0xd0 [ 14.140320][ T245] ? lock_acquire+0x13c/0x160 [ 14.140322][ T245] console_lock_spinning_enable+0x5c/0x60 [ 14.140324][ T245] ? console_lock_spinning_enable+0x40/0x60 [ 14.140325][ T245] console_emit_next_record+0x1d1/0x4c0 [ 14.140327][ T245] ? devkmsg_read+0x4b0/0x4b0 [ 14.140329][ T245] ? console_flush_one_record+0x106/0x710 [ 14.140330][ T245] ? rcu_is_watching+0x16/0xd0 [ 14.140332][ T245] ? lock_acquire+0x13c/0x160 [ 14.140334][ T245] console_flush_one_record+0x46f/0x710 [ 14.140336][ T245] ? console_emit_next_record+0x4c0/0x4c0 [ 14.140338][ T245] ? __lock_acquire+0x518/0xc20 [ 14.140340][ T245] console_unlock+0xee/0x1f0 [ 14.140342][ T245] ? console_flush_one_record+0x710/0x710 [ 14.140343][ T245] ? rcu_is_watching+0x16/0xd0 [ 14.140345][ T245] ? lock_acquire+0x60/0x160 [ 14.140347][ T245] ? __down_trylock_console_sem+0x5e/0xa0 [ 14.140348][ T245] ? vprintk_emit+0x320/0x3e0 [ 14.140350][ T245] vprintk_emit+0x37c/0x3e0 [ 14.140352][ T245] ? wake_up_klogd_work_func+0x90/0x90 [ 14.140354][ T245] ? mark_lock+0x3bb/0xa00 [ 14.140356][ T245] _printk+0xc7/0x100 [ 14.140358][ T245] ? snapshot_read.cold+0x21/0x21 [ 14.140360][ T245] ? lock_acquire.part.0+0xd4/0x280 [ 14.140361][ T245] ? find_held_lock+0x2b/0x80 [ 14.140362][ T245] ? netdev_name+0x43/0x60 [ 14.140364][ T245] ? __netdev_printk+0x17f/0x1d0 [ 14.140365][ T245] netdev_err+0xe2/0x120 [ 14.140367][ T245] ? netdev_crit+0x120/0x120 [ 14.140368][ T245] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 14.140369][ T245] ? ___ratelimit+0x4e7/0xa00 [ 14.140372][ T245] ? idr_get_free+0x830/0x830 [ 14.140374][ T245] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 14.140377][ T245] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 14.140379][ T245] ? dst_release+0x54/0x240 [ 14.140381][ T245] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.140383][ T245] ? dev_nit_active_rcu+0x1f/0xf0 [ 14.140386][ T245] ? rcu_is_watching+0x16/0xd0 [ 14.140388][ T245] dev_hard_start_xmit+0xf4/0x620 [ 14.140390][ T245] ? rcu_is_watching+0x16/0xd0 [ 14.140392][ T245] __dev_queue_xmit+0xc0a/0x1b80 [ 14.140394][ T245] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 14.140396][ T245] ? lockdep_hardirqs_on+0x91/0x130 [ 14.140397][ T245] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 14.140398][ T245] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 14.140400][ T245] ? ref_tracker_dir_print+0x100/0x100 [ 14.140403][ T245] ? dst_init+0x84/0x530 [ 14.140404][ T245] ? dst_alloc+0x97/0x160 [ 14.140406][ T245] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 14.140408][ T245] ? ip_route_output_flow+0x21/0x150 [ 14.140409][ T245] ? __ip_queue_xmit+0xdc7/0x1650 [ 14.140410][ T245] ? __lock_acquire+0x518/0xc20 [ 14.140412][ T245] ? down_read_nested+0xe7/0x500 [ 14.140413][ T245] ? lock_acquire.part.0+0xd4/0x280 [ 14.140415][ T245] ? ip_finish_output2+0x25c/0x1bc0 [ 14.140417][ T245] ? rcu_is_watching+0x16/0xd0 [ 14.140419][ T245] ip_finish_output2+0x5b9/0x1bc0 [ 14.140421][ T245] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 14.140422][ T245] ? lock_acquire.part.0+0xd4/0x280 [ 14.140424][ T245] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 14.140426][ T245] ip_output+0x1ca/0x660 [ 14.140428][ T245] ? ip_finish_output+0x460/0x460 [ 14.140430][ T245] ? find_held_lock+0x2b/0x80 [ 14.140431][ T245] ? __lock_release.isra.0+0x69/0x1a0 [ 14.140433][ T245] ? ip4_dst_hoplimit+0x15b/0x320 [ 14.140435][ T245] __ip_queue_xmit+0x774/0x1650 [ 14.140437][ T245] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 14.140441][ T245] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.140445][ T245] ? l2tp_xmit_core.constprop.0+0x19b0/0x19b0 [l2tp_core] [ 14.140448][ T245] ? do_raw_spin_lock+0x131/0x280 [ 14.140450][ T245] ? pppol2tp_xmit+0x423/0x690 [l2tp_ppp] [ 14.140451][ T245] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.140453][ T245] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.140455][ T245] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.140457][ T245] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 14.140459][ T245] ? dst_release+0x54/0x240 [ 14.140461][ T245] ? down_read_nested+0x369/0x500 [ 14.140462][ T245] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.140464][ T245] ? dev_nit_active_rcu+0x1f/0xf0 [ 14.140466][ T245] ? rcu_is_watching+0x16/0xd0 [ 14.140468][ T245] dev_hard_start_xmit+0xf4/0x620 [ 14.140470][ T245] ? rcu_is_watching+0x16/0xd0 [ 14.140472][ T245] __dev_queue_xmit+0xc0a/0x1b80 [ 14.140474][ T245] ? sock_queue_err_skb+0x680/0x680 [ 14.140476][ T245] ? kmem_cache_alloc_node_noprof+0x2a6/0x660 [ 14.140478][ T245] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.140480][ T245] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.140481][ T245] ? validate_chain+0x38b/0xc20 [ 14.140483][ T245] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 14.140485][ T245] ? __lock_acquire+0x518/0xc20 [ 14.140486][ T245] ? down_read_nested+0xe7/0x500 [ 14.140488][ T245] ? lock_acquire.part.0+0xd4/0x280 [ 14.140489][ T245] ? ip_finish_output2+0x25c/0x1bc0 [ 14.140491][ T245] ? rcu_is_watching+0x16/0xd0 [ 14.140493][ T245] ip_finish_output2+0x5b9/0x1bc0 [ 14.140495][ T245] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 14.140496][ T245] ? lock_acquire.part.0+0xd4/0x280 [ 14.140498][ T245] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 14.140500][ T245] ? rcu_is_watching+0x16/0xd0 [ 14.140502][ T245] ip_output+0x1ca/0x660 [ 14.140504][ T245] ? ip_finish_output+0x460/0x460 [ 14.140505][ T245] ? __ip_make_skb+0x106a/0x2300 [ 14.140508][ T245] ip_push_pending_frames+0xf6/0x250 [ 14.140510][ T245] raw_sendmsg+0x1169/0x1cc0 [ 14.140512][ T245] ? raw_send_hdrinc+0x1740/0x1740 [ 14.140513][ T245] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.140517][ T245] ? __might_fault+0x97/0x140 [ 14.140527][ T245] ? __might_fault+0x97/0x140 [ 14.140529][ T245] ? __might_fault+0x97/0x140 [ 14.140531][ T245] __sys_sendto+0x2aa/0x400 [ 14.140532][ T245] ? __ia32_sys_getpeername+0xd0/0xd0 [ 14.140536][ T245] ? exc_page_fault+0x87/0x100 [ 14.140538][ T245] __x64_sys_sendto+0xe4/0x1f0 [ 14.140540][ T245] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 14.140543][ T245] ? lockdep_hardirqs_on+0x91/0x130 [ 14.140544][ T245] ? do_syscall_64+0xa6/0x530 [ 14.140545][ T245] do_syscall_64+0xff/0x530 [ 14.140547][ T245] ? exc_page_fault+0xee/0x100 [ 14.140548][ T245] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.140550][ T245] RIP: 0033:0x7f31201da54e [ 14.140552][ T245] 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.140554][ T245] RSP: 002b:00007fffeeb8c3b0 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 14.140556][ T245] RAX: ffffffffffffffda RBX: 0000000000000040 RCX: 00007f31201da54e [ 14.140557][ T245] RDX: 0000000000000040 RSI: 00005583809a70a4 RDI: 0000000000000005 [ 14.140558][ T245] RBP: 00007fffeeb8c3c0 R08: 00005583809b9320 R09: 0000000000000010 [ 14.140559][ T245] R10: 0000000000000000 R11: 0000000000000202 R12: 431bde82d7b634db [ 14.140560][ T245] R13: 0000000000000000 R14: 00005583809ba3e0 R15: 00005583809a70a0 [ 14.140562][ T245] [ 15.146988][ T245] ping (245) used greatest stack depth: 22536 bytes left [ 15.146995][ T245] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 15.146996][ T245] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 245, name: ping [ 15.146998][ T245] preempt_count: 2, expected: 0 [ 15.146999][ T245] RCU nest depth: 0, expected: 0 [ 15.146999][ T245] INFO: lockdep is turned off. [ 15.147000][ T245] irq event stamp: 27303 [ 15.147001][ T245] hardirqs last enabled at (27302): [] __down_trylock_console_sem+0x86/0xa0 [ 15.147007][ T245] hardirqs last disabled at (27303): [] console_emit_next_record+0x3d4/0x4c0 [ 15.147009][ T245] softirqs last enabled at (27206): [] __alloc_skb+0x4c2/0x5f0 [ 15.147012][ T245] softirqs last disabled at (27216): [] __dev_queue_xmit+0x1fc/0x1b80 [ 15.147015][ T245] Preemption disabled at: [ 15.147016][ T245] [<0000000000000000>] 0x0 [ 15.147020][ T245] CPU: 2 UID: 0 PID: 245 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.147023][ T245] Tainted: [W]=WARN [ 15.147024][ T245] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.147025][ T245] Call Trace: [ 15.147026][ T245] [ 15.147027][ T245] dump_stack_lvl+0x6f/0xa0 [ 15.147031][ T245] __might_resched.cold+0x1fe/0x2c1 [ 15.147035][ T245] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 15.147038][ T245] ? __asan_memcpy+0x3c/0x60 [ 15.147041][ T245] ? rcu_is_watching+0x16/0xd0 [ 15.147044][ T245] __kmalloc_noprof+0x443/0x760 [ 15.147046][ T245] ? __rwlock_init+0x150/0x150 [ 15.147048][ T245] ? alloc_buf.isra.0+0x4b/0x260 [ 15.147051][ T245] ? do_raw_spin_unlock+0x59/0x250 [ 15.147053][ T245] alloc_buf.isra.0+0x4b/0x260 [ 15.147055][ T245] put_chars+0x1e1/0x2f0 [ 15.147057][ T245] ? __send_to_port+0x420/0x420 [ 15.147059][ T245] ? console_prepend_replay+0x20/0x20 [ 15.147061][ T245] hvc_console_print+0x292/0x780 [ 15.147064][ T245] ? hvc_write+0x3a0/0x3a0 [ 15.147066][ T245] ? rcu_is_watching+0x16/0xd0 [ 15.147067][ T245] ? lock_acquire+0x13c/0x160 [ 15.147070][ T245] console_emit_next_record+0x22f/0x4c0 [ 15.147072][ T245] ? devkmsg_read+0x4b0/0x4b0 [ 15.147074][ T245] ? rcu_is_watching+0x16/0xd0 [ 15.147075][ T245] ? rcu_is_watching+0x16/0xd0 [ 15.147077][ T245] ? rcu_is_watching+0x16/0xd0 [ 15.147078][ T245] ? lock_acquire+0x13c/0x160 [ 15.147080][ T245] ? console_flush_one_record+0x111/0x710 [ 15.147082][ T245] console_flush_one_record+0x46f/0x710 [ 15.147084][ T245] ? console_emit_next_record+0x4c0/0x4c0 [ 15.147087][ T245] console_unlock+0xee/0x1f0 [ 15.147088][ T245] ? lock_acquire+0x13c/0x160 [ 15.147090][ T245] ? console_flush_one_record+0x710/0x710 [ 15.147091][ T245] ? rcu_is_watching+0x16/0xd0 [ 15.147093][ T245] ? lock_release+0x184/0x1f0 [ 15.147094][ T245] ? lock_acquire+0x60/0x160 [ 15.147095][ T245] ? __rcu_read_lock+0x30/0x70 [ 15.147097][ T245] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.147099][ T245] ? vprintk_emit+0x320/0x3e0 [ 15.147101][ T245] vprintk_emit+0x37c/0x3e0 [ 15.147103][ T245] ? wake_up_klogd_work_func+0x90/0x90 [ 15.147105][ T245] ? do_raw_read_unlock+0x70/0x70 [ 15.147106][ T245] ? forget_original_parent+0x5c/0xdb0 [ 15.147110][ T245] _printk+0xc7/0x100 [ 15.147112][ T245] ? snapshot_read.cold+0x21/0x21 [ 15.147114][ T245] ? do_raw_spin_lock+0x131/0x280 [ 15.147116][ T245] ? do_raw_spin_lock+0x131/0x280 [ 15.147117][ T245] ? __rwlock_init+0x150/0x150 [ 15.147119][ T245] ? do_raw_spin_lock+0x131/0x280 [ 15.147121][ T245] do_exit.cold+0x82/0x9c [ 15.147124][ T245] ? exit_notify+0x890/0x890 [ 15.147125][ T245] ? posixtimer_sig_unignore.isra.0+0x5f0/0x5f0 [ 15.147127][ T245] ? rcu_is_watching+0x16/0xd0 [ 15.147129][ T245] do_group_exit+0xb8/0x370 [ 15.147131][ T245] __x64_sys_exit_group+0x3c/0x50 [ 15.147132][ T245] x64_sys_call+0x1567/0x1570 [ 15.147134][ T245] do_syscall_64+0xff/0x530 [ 15.147137][ T245] ? exc_page_fault+0xee/0x100 [ 15.147139][ T245] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.147141][ T245] RIP: 0033:0x7f312022b1b8 [ 15.147142][ T245] Code: Unable to access opcode bytes at 0x7f312022b18e. [ 15.147143][ T245] RSP: 002b:00007fffeeb8db48 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 15.147146][ T245] RAX: ffffffffffffffda RBX: 00007f312035bf88 RCX: 00007f312022b1b8 [ 15.147147][ T245] RDX: 00007f311ffbc7c8 RSI: ffffffffffffffb0 RDI: 0000000000000001 [ 15.147148][ T245] RBP: 00007fffeeb8dba0 R08: 0000000000000000 R09: 0000000000001010 [ 15.147149][ T245] R10: 00007fffeeb8d9c0 R11: 0000000000000246 R12: 0000000000000002 [ 15.147150][ T245] R13: 0000000000000001 R14: 00007f312035a680 R15: 00007f312035bfa0 [ 15.147152][ T245] [ 15.189084][ T206] ppp0: recursion detected