[ 8.997092][ T180] PPP generic driver version 2.4.2 [ 8.997107][ T180] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 8.997110][ T180] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 180, name: modprobe [ 8.997111][ T180] preempt_count: 1, expected: 0 [ 8.997112][ T180] RCU nest depth: 0, expected: 0 [ 8.997113][ T180] locks held by modprobe/180: 4, last CPU#3: [ 8.997115][ T180] #0: ffffffff96f79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 8.997127][ T180] #1: ffffffff96f79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 8.997132][ T180] #2: ffffffff96e99660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 8.997136][ T180] #3: ffffffff96e99560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 8.997140][ T180] irq event stamp: 54792 [ 8.997140][ T180] hardirqs last enabled at (54791): [] __down_trylock_console_sem+0x86/0xa0 [ 8.997143][ T180] hardirqs last disabled at (54792): [] console_emit_next_record+0x3d4/0x4c0 [ 8.997145][ T180] softirqs last enabled at (53456): [] handle_softirqs+0x67c/0x900 [ 8.997148][ T180] softirqs last disabled at (51755): [] __irq_exit_rcu+0x145/0x1c0 [ 8.997150][ T180] Preemption disabled at: [ 8.997151][ T180] [] vprintk_emit+0x31b/0x3e0 [ 8.997156][ T180] CPU: 3 UID: 0 PID: 180 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 8.997160][ T180] Tainted: [W]=WARN [ 8.997161][ T180] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 8.997162][ T180] Call Trace: [ 8.997164][ T180] [ 8.997165][ T180] dump_stack_lvl+0x6f/0xa0 [ 8.997171][ T180] ? vprintk_emit+0x31b/0x3e0 [ 8.997173][ T180] __might_resched.cold+0x1fe/0x2c1 [ 8.997177][ T180] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 8.997181][ T180] ? __kmalloc_noprof+0xdb/0x760 [ 8.997186][ T180] __kmalloc_noprof+0x443/0x760 [ 8.997188][ T180] ? alloc_buf.isra.0+0x4b/0x260 [ 8.997194][ T180] ? do_raw_spin_unlock+0x59/0x250 [ 8.997197][ T180] alloc_buf.isra.0+0x4b/0x260 [ 8.997200][ T180] put_chars+0x1e1/0x2f0 [ 8.997203][ T180] ? __send_to_port+0x420/0x420 [ 8.997205][ T180] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 8.997210][ T180] ? rcu_read_lock_any_held+0x3c/0x90 [ 8.997212][ T180] ? validate_chain+0x38b/0xc20 [ 8.997216][ T180] hvc_console_print+0x292/0x780 [ 8.997219][ T180] ? __lock_acquire+0x518/0xc20 [ 8.997224][ T180] ? hvc_write+0x3a0/0x3a0 [ 8.997227][ T180] ? rcu_is_watching+0x16/0xd0 [ 8.997232][ T180] console_emit_next_record+0x22f/0x4c0 [ 8.997235][ T180] ? devkmsg_read+0x4b0/0x4b0 [ 8.997237][ T180] ? console_flush_one_record+0x106/0x710 [ 8.997240][ T180] ? rcu_is_watching+0x16/0xd0 [ 8.997243][ T180] ? lock_acquire+0x13c/0x160 [ 8.997247][ T180] console_flush_one_record+0x46f/0x710 [ 8.997251][ T180] ? console_emit_next_record+0x4c0/0x4c0 [ 8.997252][ T180] ? __lock_acquire+0x518/0xc20 [ 8.997257][ T180] console_unlock+0xee/0x1f0 [ 8.997260][ T180] ? console_flush_one_record+0x710/0x710 [ 8.997262][ T180] ? rcu_is_watching+0x16/0xd0 [ 8.997264][ T180] ? lock_acquire+0x60/0x160 [ 8.997268][ T180] ? __down_trylock_console_sem+0x5e/0xa0 [ 8.997269][ T180] ? vprintk_emit+0x320/0x3e0 [ 8.997273][ T180] vprintk_emit+0x37c/0x3e0 [ 8.997277][ T180] ? wake_up_klogd_work_func+0x90/0x90 [ 8.997282][ T180] ? ppp_cleanup+0x920/0x920 [ppp_generic] [ 8.997287][ T180] _printk+0xc7/0x100 [ 8.997290][ T180] ? snapshot_read.cold+0x21/0x21 [ 8.997297][ T180] ? do_one_initcall+0x113/0x4f0 [ 8.997300][ T180] ppp_init+0x16/0x1000 [ppp_generic] [ 8.997303][ T180] do_one_initcall+0x124/0x4f0 [ 8.997306][ T180] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 8.997310][ T180] ? do_init_module+0xae/0x8a0 [ 8.997314][ T180] ? kasan_unpoison+0x44/0x70 [ 8.997318][ T180] do_init_module+0x2ad/0x8a0 [ 8.997322][ T180] ? lockdep_hardirqs_on+0x91/0x130 [ 8.997326][ T180] ? free_module+0x470/0x470 [ 8.997329][ T180] ? kfree+0x123/0x590 [ 8.997333][ T180] load_module+0x1424/0x1bb0 [ 8.997340][ T180] ? layout_and_allocate.isra.0+0x680/0x680 [ 8.997345][ T180] ? backing_file_read_iter+0x541/0x790 [ 8.997348][ T180] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 8.997352][ T180] init_module_from_file+0x11f/0x1a0 [ 8.997355][ T180] ? __do_sys_init_module+0x220/0x220 [ 8.997363][ T180] ? __rwlock_init+0x150/0x150 [ 8.997367][ T180] ? do_raw_spin_unlock+0x59/0x250 [ 8.997370][ T180] idempotent_init_module+0x37b/0x7c0 [ 8.997373][ T180] ? init_module_from_file+0x1a0/0x1a0 [ 8.997379][ T180] ? rcu_is_watching+0x16/0xd0 [ 8.997382][ T180] ? cap_capable+0x1d7/0x3d0 [ 8.997388][ T180] __x64_sys_finit_module+0xdf/0x190 [ 8.997390][ T180] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 8.997393][ T180] do_syscall_64+0xff/0x530 [ 8.997396][ T180] ? exc_page_fault+0xee/0x100 [ 8.997398][ T180] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 8.997401][ T180] RIP: 0033:0x7fe35125937d [ 8.997404][ T180] 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.997405][ T180] RSP: 002b:00007ffe7bc81c08 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 8.997408][ T180] RAX: ffffffffffffffda RBX: 0000559b3fcdc1d0 RCX: 00007fe35125937d [ 8.997409][ T180] RDX: 0000000000000000 RSI: 0000559b30d99ffd RDI: 0000000000000006 [ 8.997410][ T180] RBP: 00007ffe7bc81ca0 R08: 0000000000000000 R09: 0000000000000000 [ 8.997411][ T180] R10: 0000000000000000 R11: 0000000000000246 R12: 0000559b30d99ffd [ 8.997412][ T180] R13: 0000559b3fcdc180 R14: 0000000000040000 R15: 0000000000000000 [ 8.997418][ T180] [ 9.026341][ T180] NET: Registered PF_PPPOX protocol family [ 9.059937][ T180] l2tp_core: L2TP core driver, V2.0 [ 9.071076][ T180] l2tp_ppp: PPPoL2TP kernel driver, V2.0 [ 14.294357][ T255] iperf3 (255) used greatest stack depth: 22680 bytes left [ 14.294376][ T255] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 14.294378][ T255] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 255, name: iperf3 [ 14.294380][ T255] preempt_count: 2, expected: 0 [ 14.294381][ T255] RCU nest depth: 0, expected: 0 [ 14.294382][ T255] locks held by iperf3/255: 5, last CPU#1: [ 14.294384][ T255] #0: ffffffff96e127b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 14.294396][ T255] #1: ffffffff96f79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 14.294402][ T255] #2: ffffffff96f79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 14.294406][ T255] #3: ffffffff96e99660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 14.294410][ T255] #4: ffffffff96e99560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 14.294414][ T255] irq event stamp: 1693536 [ 14.294414][ T255] hardirqs last enabled at (1693535): [] __down_trylock_console_sem+0x86/0xa0 [ 14.294417][ T255] hardirqs last disabled at (1693536): [] console_emit_next_record+0x3d4/0x4c0 [ 14.294419][ T255] softirqs last enabled at (1693112): [] fpu__clear_user_states+0x104/0x290 [ 14.294422][ T255] softirqs last disabled at (1693110): [] fpu__clear_user_states+0x6d/0x290 [ 14.294425][ T255] Preemption disabled at: [ 14.294425][ T255] [<0000000000000000>] 0x0 [ 14.294433][ T255] CPU: 1 UID: 0 PID: 255 Comm: iperf3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 14.294436][ T255] Tainted: [W]=WARN [ 14.294437][ T255] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.294439][ T255] Call Trace: [ 14.294440][ T255] [ 14.294442][ T255] dump_stack_lvl+0x6f/0xa0 [ 14.294447][ T255] __might_resched.cold+0x1fe/0x2c1 [ 14.294452][ T255] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 14.294456][ T255] ? __kmalloc_noprof+0xdb/0x760 [ 14.294461][ T255] __kmalloc_noprof+0x443/0x760 [ 14.294463][ T255] ? alloc_buf.isra.0+0x4b/0x260 [ 14.294469][ T255] ? do_raw_spin_unlock+0x59/0x250 [ 14.294472][ T255] alloc_buf.isra.0+0x4b/0x260 [ 14.294475][ T255] put_chars+0x1e1/0x2f0 [ 14.294478][ T255] ? prb_final_commit+0x50/0x50 [ 14.294480][ T255] ? __send_to_port+0x420/0x420 [ 14.294486][ T255] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.294491][ T255] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.294493][ T255] ? validate_chain+0x38b/0xc20 [ 14.294496][ T255] hvc_console_print+0x292/0x780 [ 14.294500][ T255] ? mark_usage+0x61/0x170 [ 14.294502][ T255] ? __lock_acquire+0x518/0xc20 [ 14.294503][ T255] ? __lock_acquire+0x518/0xc20 [ 14.294507][ T255] ? hvc_write+0x3a0/0x3a0 [ 14.294509][ T255] ? console_emit_next_record+0x1df/0x4c0 [ 14.294512][ T255] ? rcu_is_watching+0x16/0xd0 [ 14.294515][ T255] ? lock_acquire+0x13c/0x160 [ 14.294518][ T255] console_emit_next_record+0x22f/0x4c0 [ 14.294522][ T255] ? devkmsg_read+0x4b0/0x4b0 [ 14.294524][ T255] ? console_flush_one_record+0x106/0x710 [ 14.294527][ T255] ? rcu_is_watching+0x16/0xd0 [ 14.294529][ T255] ? lock_acquire+0x13c/0x160 [ 14.294533][ T255] console_flush_one_record+0x46f/0x710 [ 14.294537][ T255] ? console_emit_next_record+0x4c0/0x4c0 [ 14.294539][ T255] ? __lock_acquire+0x518/0xc20 [ 14.294544][ T255] console_unlock+0xee/0x1f0 [ 14.294547][ T255] ? console_flush_one_record+0x710/0x710 [ 14.294548][ T255] ? rcu_is_watching+0x16/0xd0 [ 14.294551][ T255] ? lock_acquire+0x60/0x160 [ 14.294554][ T255] ? __down_trylock_console_sem+0x5e/0xa0 [ 14.294556][ T255] ? vprintk_emit+0x320/0x3e0 [ 14.294559][ T255] vprintk_emit+0x37c/0x3e0 [ 14.294563][ T255] ? wake_up_klogd_work_func+0x90/0x90 [ 14.294566][ T255] ? __lock_acquire+0x518/0xc20 [ 14.294569][ T255] _printk+0xc7/0x100 [ 14.294573][ T255] ? snapshot_read.cold+0x21/0x21 [ 14.294575][ T255] ? do_raw_spin_lock+0x131/0x280 [ 14.294578][ T255] ? __rwlock_init+0x150/0x150 [ 14.294581][ T255] ? do_raw_spin_lock+0x131/0x280 [ 14.294584][ T255] do_exit.cold+0x82/0x9c [ 14.294588][ T255] ? exit_notify+0x890/0x890 [ 14.294591][ T255] ? fpregs_restore_userregs+0x14b/0x300 [ 14.294594][ T255] __x64_sys_exit+0x42/0x50 [ 14.294596][ T255] x64_sys_call+0x156c/0x1570 [ 14.294598][ T255] do_syscall_64+0xff/0x530 [ 14.294602][ T255] ? exc_page_fault+0xee/0x100 [ 14.294605][ T255] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.294608][ T255] RIP: 0033:0x7f5e59485c49 [ 14.294610][ T255] Code: Unable to access opcode bytes at 0x7f5e59485c1f. [ 14.294611][ T255] RSP: 002b:00007f5e58c44e60 EFLAGS: 00000246 ORIG_RAX: 000000000000003c [ 14.294613][ T255] RAX: ffffffffffffffda RBX: 00007f5e58c456c0 RCX: 00007f5e59485c49 [ 14.294614][ T255] RDX: 0000000000000000 RSI: 0000000000801000 RDI: 0000000000000000 [ 14.294615][ T255] RBP: 00007f5e58c44f70 R08: 0000000000000001 R09: 00007f5e58c456c0 [ 14.294616][ T255] R10: 0000000000000008 R11: 0000000000000246 R12: 00007f5e58445000 [ 14.294617][ T255] R13: 00007fffcc6b92b0 R14: 0000000000000002 R15: 00007fffcc6b93b7 [ 14.294623][ T255] [ 14.416465][ T259] ppp0: recursion detected [ 14.416545][ T259] [ 14.416547][ T259] ===================================================== [ 14.416548][ T259] WARNING: SOFTIRQ-safe -> SOFTIRQ-unsafe lock order detected [ 14.416550][ T259] 7.2.0-virtme #1 Tainted: G W [ 14.416552][ T259] ----------------------------------------------------- [ 14.416552][ T259] ping/259 [HC0[0]:SC0[10]:HE0:SE0] is trying to acquire: [ 14.416554][ T259] ffffffff96e99660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 14.416566][ T259] [ 14.416566][ T259] and this task is already holding: [ 14.416566][ T259] ff110000059050d8 (slock-AF_INET/2){+.-.}-{3:3}, at: l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.416574][ T259] which would create a new lock dependency: [ 14.416575][ T259] (slock-AF_INET/2){+.-.}-{3:3} -> (console_owner){....}-{0:0} [ 14.416578][ T259] [ 14.416578][ T259] but this new dependency connects a SOFTIRQ-irq-safe lock: [ 14.416578][ T259] (slock-AF_INET/2){+.-.}-{3:3} [ 14.416579][ T259] [ 14.416579][ T259] ... which became SOFTIRQ-irq-safe at: [ 14.416581][ T259] __lock_acquire+0x388/0xc20 [ 14.416584][ T259] lock_acquire.part.0+0xd4/0x280 [ 14.416586][ T259] _raw_spin_lock_nested+0x3d/0x50 [ 14.416589][ T259] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.416592][ T259] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.416595][ T259] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.416597][ T259] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.416599][ T259] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.416601][ T259] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.416602][ T259] dev_hard_start_xmit+0xf4/0x620 [ 14.416606][ T259] __dev_queue_xmit+0xc0a/0x1b80 [ 14.416607][ T259] ip_finish_output2+0x5b9/0x1bc0 [ 14.416610][ T259] ip_output+0x1ca/0x660 [ 14.416612][ T259] ip_push_pending_frames+0xf6/0x250 [ 14.416613][ T259] icmp_reply+0x94d/0xa50 [ 14.416616][ T259] icmp_echo+0x2ab/0x4b0 [ 14.416618][ T259] icmp_rcv+0x92f/0xfb0 [ 14.416619][ T259] ip_protocol_deliver_rcu+0x82/0x350 [ 14.416621][ T259] ip_local_deliver_finish+0x36f/0x610 [ 14.416622][ T259] ip_local_deliver+0x184/0x4c0 [ 14.416623][ T259] ip_rcv+0xdc/0x3d0 [ 14.416625][ T259] __netif_receive_skb_one_core+0xfc/0x180 [ 14.416626][ T259] process_backlog+0x431/0x14c0 [ 14.416628][ T259] __napi_poll+0xa7/0x3b0 [ 14.416630][ T259] net_rx_action+0x513/0xf50 [ 14.416631][ T259] handle_softirqs+0x1d3/0x900 [ 14.416634][ T259] do_softirq+0xac/0xe0 [ 14.416635][ T259] __local_bh_enable_ip+0x118/0x150 [ 14.416636][ T259] __dev_queue_xmit+0x979/0x1b80 [ 14.416638][ T259] ip_finish_output2+0x5b9/0x1bc0 [ 14.416639][ T259] ip_output+0x1ca/0x660 [ 14.416641][ T259] ip_push_pending_frames+0xf6/0x250 [ 14.416642][ T259] raw_sendmsg+0x1169/0x1cc0 [ 14.416644][ T259] __sys_sendto+0x2aa/0x400 [ 14.416646][ T259] __x64_sys_sendto+0xe4/0x1f0 [ 14.416648][ T259] do_syscall_64+0xff/0x530 [ 14.416651][ T259] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.416653][ T259] [ 14.416653][ T259] to a SOFTIRQ-irq-unsafe lock: [ 14.416654][ T259] (fs_reclaim){+.+.}-{0:0} [ 14.416655][ T259] [ 14.416655][ T259] ... which became SOFTIRQ-irq-unsafe at: [ 14.416656][ T259] ... [ 14.416657][ T259] __lock_acquire+0x388/0xc20 [ 14.416658][ T259] lock_acquire.part.0+0xd4/0x280 [ 14.416659][ T259] fs_reclaim_acquire+0xd5/0x120 [ 14.416662][ T259] __kmalloc_cache_noprof+0x6e/0x620 [ 14.416664][ T259] kthread_create_worker_on_node+0xea/0x210 [ 14.416667][ T259] workqueue_init+0x2a/0x680 [ 14.416670][ T259] kernel_init_freeable+0x2fe/0x630 [ 14.416673][ T259] kernel_init+0x21/0x150 [ 14.416674][ T259] ret_from_fork+0x474/0x6b0 [ 14.416677][ T259] ret_from_fork_asm+0x11/0x20 [ 14.416680][ T259] [ 14.416680][ T259] other info that might help us debug this: [ 14.416680][ T259] [ 14.416680][ T259] Chain exists of: [ 14.416680][ T259] slock-AF_INET/2 --> console_owner --> fs_reclaim [ 14.416680][ T259] [ 14.416682][ T259] Possible interrupt unsafe locking scenario: [ 14.416682][ T259] [ 14.416683][ T259] CPU0 CPU1 [ 14.416683][ T259] ---- ---- [ 14.416684][ T259] lock(fs_reclaim); [ 14.416685][ T259] local_irq_disable(); [ 14.416685][ T259] lock(slock-AF_INET/2); [ 14.416686][ T259] lock(console_owner); [ 14.416687][ T259] [ 14.416687][ T259] lock(slock-AF_INET/2); [ 14.416688][ T259] [ 14.416688][ T259] *** DEADLOCK *** [ 14.416688][ T259] [ 14.416689][ T259] locks held by ping/259: 14, last CPU#2: [ 14.416690][ T259] #0: ff110000058fe290 (sk_lock-AF_INET){+.+.}-{0:0}, at: raw_sendmsg+0xba5/0x1cc0 [ 14.416693][ T259] #1: ffffffff96f90b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 14.416696][ T259] #2: ffffffff96f90b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 14.416699][ T259] #3: ffffffff96f90aa0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x1fc/0x1b80 [ 14.416702][ T259] #4: ffd1ffffffd06130 (&xmit_recursion->bh_lock){+.-.}-{3:3}, at: ppp_xmit_process+0x1d2/0x8e0 [ppp_generic] [ 14.416705][ T259] #5: ff1100000c812fd0 (&ppp->wlock){+.-.}-{3:3}, at: ppp_xmit_process+0x292/0x8e0 [ppp_generic] [ 14.416708][ T259] #6: ff1100000c91d9d0 (&pch->downl){+.-.}-{3:3}, at: ppp_push+0xae/0x2d0 [ppp_generic] [ 14.416711][ T259] #7: ff110000059050d8 (slock-AF_INET/2){+.-.}-{3:3}, at: l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.416716][ T259] #8: ffffffff96f90b00 (rcu_read_lock){....}-{1:3}, at: __ip_queue_xmit+0x63/0x1650 [ 14.416719][ T259] #9: ffffffff96f90b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 14.416722][ T259] #10: ffffffff96f90b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 14.416725][ T259] #11: ffffffff96f90aa0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x1fc/0x1b80 [ 14.416728][ T259] #12: ffffffff96f79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 14.416731][ T259] #13: ffffffff96f79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 14.416734][ T259] [ 14.416734][ T259] the dependencies between SOFTIRQ-irq-safe lock and the holding lock: [ 14.416735][ T259] -> (slock-AF_INET/2){+.-.}-{3:3} { [ 14.416737][ T259] HARDIRQ-ON-W at: [ 14.416738][ T259] __lock_acquire+0x388/0xc20 [ 14.416740][ T259] lock_acquire.part.0+0xd4/0x280 [ 14.416741][ T259] _raw_spin_lock_nested+0x3d/0x50 [ 14.416742][ T259] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.416745][ T259] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.416747][ T259] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.416748][ T259] __ppp_channel_push+0xed/0x280 [ppp_generic] [ 14.416750][ T259] ppp_channel_push+0x269/0x7b0 [ppp_generic] [ 14.416752][ T259] ppp_write+0x26b/0x350 [ppp_generic] [ 14.416754][ T259] vfs_write+0x1a4/0xbd0 [ 14.416756][ T259] ksys_write+0x116/0x250 [ 14.416758][ T259] do_syscall_64+0xff/0x530 [ 14.416759][ T259] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.416761][ T259] IN-SOFTIRQ-W at: [ 14.416761][ T259] __lock_acquire+0x388/0xc20 [ 14.416763][ T259] lock_acquire.part.0+0xd4/0x280 [ 14.416764][ T259] _raw_spin_lock_nested+0x3d/0x50 [ 14.416765][ T259] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.416767][ T259] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.416770][ T259] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.416771][ T259] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.416773][ T259] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.416774][ T259] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.416776][ T259] dev_hard_start_xmit+0xf4/0x620 [ 14.416778][ T259] __dev_queue_xmit+0xc0a/0x1b80 [ 14.416779][ T259] ip_finish_output2+0x5b9/0x1bc0 [ 14.416781][ T259] ip_output+0x1ca/0x660 [ 14.416782][ T259] ip_push_pending_frames+0xf6/0x250 [ 14.416784][ T259] icmp_reply+0x94d/0xa50 [ 14.416785][ T259] icmp_echo+0x2ab/0x4b0 [ 14.416787][ T259] icmp_rcv+0x92f/0xfb0 [ 14.416788][ T259] ip_protocol_deliver_rcu+0x82/0x350 [ 14.416789][ T259] ip_local_deliver_finish+0x36f/0x610 [ 14.416791][ T259] ip_local_deliver+0x184/0x4c0 [ 14.416792][ T259] ip_rcv+0xdc/0x3d0 [ 14.416793][ T259] __netif_receive_skb_one_core+0xfc/0x180 [ 14.416795][ T259] process_backlog+0x431/0x14c0 [ 14.416796][ T259] __napi_poll+0xa7/0x3b0 [ 14.416798][ T259] net_rx_action+0x513/0xf50 [ 14.416800][ T259] handle_softirqs+0x1d3/0x900 [ 14.416801][ T259] do_softirq+0xac/0xe0 [ 14.416802][ T259] __local_bh_enable_ip+0x118/0x150 [ 14.416803][ T259] __dev_queue_xmit+0x979/0x1b80 [ 14.416805][ T259] ip_finish_output2+0x5b9/0x1bc0 [ 14.416806][ T259] ip_output+0x1ca/0x660 [ 14.416807][ T259] ip_push_pending_frames+0xf6/0x250 [ 14.416809][ T259] raw_sendmsg+0x1169/0x1cc0 [ 14.416810][ T259] __sys_sendto+0x2aa/0x400 [ 14.416811][ T259] __x64_sys_sendto+0xe4/0x1f0 [ 14.416813][ T259] do_syscall_64+0xff/0x530 [ 14.416814][ T259] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.416815][ T259] INITIAL USE at: [ 14.416816][ T259] __lock_acquire+0x388/0xc20 [ 14.416817][ T259] lock_acquire.part.0+0xd4/0x280 [ 14.416818][ T259] _raw_spin_lock_nested+0x3d/0x50 [ 14.416820][ T259] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.416822][ T259] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.416825][ T259] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.416826][ T259] __ppp_channel_push+0xed/0x280 [ppp_generic] [ 14.416827][ T259] ppp_channel_push+0x269/0x7b0 [ppp_generic] [ 14.416829][ T259] ppp_write+0x26b/0x350 [ppp_generic] [ 14.416831][ T259] vfs_write+0x1a4/0xbd0 [ 14.416832][ T259] ksys_write+0x116/0x250 [ 14.416834][ T259] do_syscall_64+0xff/0x530 [ 14.416835][ T259] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.416836][ T259] } [ 14.416837][ T259] ... key at: [] af_family_slock_keys+0x22/0x300 [ 14.416840][ T259] [ 14.416840][ T259] the dependencies between the lock to be acquired [ 14.416840][ T259] and SOFTIRQ-irq-unsafe lock: [ 14.416845][ T259] -> (fs_reclaim){+.+.}-{0:0} { [ 14.416847][ T259] HARDIRQ-ON-W at: [ 14.416848][ T259] __lock_acquire+0x388/0xc20 [ 14.416849][ T259] lock_acquire.part.0+0xd4/0x280 [ 14.416850][ T259] fs_reclaim_acquire+0xd5/0x120 [ 14.416851][ T259] __kmalloc_cache_noprof+0x6e/0x620 [ 14.416853][ T259] kthread_create_worker_on_node+0xea/0x210 [ 14.416854][ T259] workqueue_init+0x2a/0x680 [ 14.416855][ T259] kernel_init_freeable+0x2fe/0x630 [ 14.416857][ T259] kernel_init+0x21/0x150 [ 14.416858][ T259] ret_from_fork+0x474/0x6b0 [ 14.416859][ T259] ret_from_fork_asm+0x11/0x20 [ 14.416860][ T259] SOFTIRQ-ON-W at: [ 14.416861][ T259] __lock_acquire+0x388/0xc20 [ 14.416863][ T259] lock_acquire.part.0+0xd4/0x280 [ 14.416864][ T259] fs_reclaim_acquire+0xd5/0x120 [ 14.416865][ T259] __kmalloc_cache_noprof+0x6e/0x620 [ 14.416866][ T259] kthread_create_worker_on_node+0xea/0x210 [ 14.416867][ T259] workqueue_init+0x2a/0x680 [ 14.416868][ T259] kernel_init_freeable+0x2fe/0x630 [ 14.416870][ T259] kernel_init+0x21/0x150 [ 14.416871][ T259] ret_from_fork+0x474/0x6b0 [ 14.416872][ T259] ret_from_fork_asm+0x11/0x20 [ 14.416873][ T259] INITIAL USE at: [ 14.416874][ T259] __lock_acquire+0x388/0xc20 [ 14.416875][ T259] lock_acquire.part.0+0xd4/0x280 [ 14.416876][ T259] fs_reclaim_acquire+0xd5/0x120 [ 14.416877][ T259] __kmalloc_cache_noprof+0x6e/0x620 [ 14.416879][ T259] kthread_create_worker_on_node+0xea/0x210 [ 14.416880][ T259] workqueue_init+0x2a/0x680 [ 14.416881][ T259] kernel_init_freeable+0x2fe/0x630 [ 14.416882][ T259] kernel_init+0x21/0x150 [ 14.416884][ T259] ret_from_fork+0x474/0x6b0 [ 14.416884][ T259] ret_from_fork_asm+0x11/0x20 [ 14.416886][ T259] } [ 14.416886][ T259] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 14.416889][ T259] ... acquired at: [ 14.416890][ T259] __lock_acquire+0x518/0xc20 [ 14.416892][ T259] lock_acquire.part.0+0xd4/0x280 [ 14.416893][ T259] fs_reclaim_acquire+0xd5/0x120 [ 14.416894][ T259] __kmalloc_noprof+0xd3/0x760 [ 14.416895][ T259] alloc_buf.isra.0+0x4b/0x260 [ 14.416898][ T259] put_chars+0x1e1/0x2f0 [ 14.416900][ T259] hvc_console_print+0x292/0x780 [ 14.416902][ T259] console_emit_next_record+0x22f/0x4c0 [ 14.416903][ T259] console_flush_one_record+0x46f/0x710 [ 14.416905][ T259] console_unlock+0xee/0x1f0 [ 14.416906][ T259] vprintk_emit+0x37c/0x3e0 [ 14.416908][ T259] dev_vprintk_emit+0x27f/0x2c0 [ 14.416911][ T259] dev_printk_emit+0xb9/0xee [ 14.416912][ T259] _dev_info+0xe2/0x116 [ 14.416914][ T259] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 14.416917][ T259] cmos_do_probe+0x73b/0x98a [ 14.416918][ T259] platform_probe+0xfe/0x1f0 [ 14.416920][ T259] call_driver_probe+0x61/0x1c0 [ 14.416923][ T259] really_probe+0x199/0x760 [ 14.416924][ T259] __driver_probe_device+0x24f/0x440 [ 14.416926][ T259] driver_probe_device+0x4a/0xf0 [ 14.416927][ T259] __driver_attach+0x1b8/0x540 [ 14.416929][ T259] bus_for_each_dev+0x130/0x1e0 [ 14.416930][ T259] bus_add_driver+0x2c8/0x530 [ 14.416931][ T259] driver_register+0x1a3/0x390 [ 14.416933][ T259] __platform_driver_probe+0x13f/0x270 [ 14.416934][ T259] cmos_init+0x31/0x40 [ 14.416937][ T259] do_one_initcall+0x124/0x4f0 [ 14.416939][ T259] kernel_init_freeable+0x596/0x630 [ 14.416940][ T259] kernel_init+0x21/0x150 [ 14.416941][ T259] ret_from_fork+0x474/0x6b0 [ 14.416943][ T259] ret_from_fork_asm+0x11/0x20 [ 14.416944][ T259] [ 14.416944][ T259] -> (console_owner){....}-{0:0} { [ 14.416946][ T259] INITIAL USE at: [ 14.416946][ T259] } [ 14.416947][ T259] ... key at: [] console_owner_dep_map+0x0/0x60 [ 14.416950][ T259] ... acquired at: [ 14.416951][ T259] check_prev_add+0xef/0xe90 [ 14.416952][ T259] validate_chain+0x6b8/0xc20 [ 14.416953][ T259] __lock_acquire+0x518/0xc20 [ 14.416954][ T259] lock_acquire.part.0+0xd4/0x280 [ 14.416956][ T259] console_lock_spinning_enable+0x5c/0x60 [ 14.416957][ T259] console_emit_next_record+0x1d1/0x4c0 [ 14.416959][ T259] console_flush_one_record+0x46f/0x710 [ 14.416960][ T259] console_unlock+0xee/0x1f0 [ 14.416962][ T259] vprintk_emit+0x37c/0x3e0 [ 14.416963][ T259] _printk+0xc7/0x100 [ 14.416966][ T259] netdev_err+0xe2/0x120 [ 14.416968][ T259] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 14.416970][ T259] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.416971][ T259] dev_hard_start_xmit+0xf4/0x620 [ 14.416973][ T259] __dev_queue_xmit+0xc0a/0x1b80 [ 14.416975][ T259] ip_finish_output2+0x5b9/0x1bc0 [ 14.416976][ T259] ip_output+0x1ca/0x660 [ 14.416977][ T259] __ip_queue_xmit+0x774/0x1650 [ 14.416979][ T259] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 14.416981][ T259] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.416984][ T259] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.416985][ T259] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.416987][ T259] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.416988][ T259] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.416990][ T259] dev_hard_start_xmit+0xf4/0x620 [ 14.416992][ T259] __dev_queue_xmit+0xc0a/0x1b80 [ 14.416993][ T259] ip_finish_output2+0x5b9/0x1bc0 [ 14.416995][ T259] ip_output+0x1ca/0x660 [ 14.416996][ T259] ip_push_pending_frames+0xf6/0x250 [ 14.416997][ T259] raw_sendmsg+0x1169/0x1cc0 [ 14.416999][ T259] __sys_sendto+0x2aa/0x400 [ 14.417000][ T259] __x64_sys_sendto+0xe4/0x1f0 [ 14.417002][ T259] do_syscall_64+0xff/0x530 [ 14.417003][ T259] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.417004][ T259] [ 14.417005][ T259] [ 14.417005][ T259] stack backtrace: [ 14.417007][ T259] CPU: 2 UID: 0 PID: 259 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 14.417011][ T259] Tainted: [W]=WARN [ 14.417012][ T259] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.417013][ T259] Call Trace: [ 14.417014][ T259] [ 14.417015][ T259] dump_stack_lvl+0x6f/0xa0 [ 14.417019][ T259] print_bad_irq_dependency+0x3e4/0x410 [ 14.417022][ T259] check_irq_usage+0x403/0x570 [ 14.417023][ T259] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.417027][ T259] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.417030][ T259] ? check_path.constprop.0+0x24/0x50 [ 14.417032][ T259] ? check_prev_add+0xef/0xe90 [ 14.417034][ T259] check_prev_add+0xef/0xe90 [ 14.417036][ T259] validate_chain+0x6b8/0xc20 [ 14.417038][ T259] __lock_acquire+0x518/0xc20 [ 14.417040][ T259] lock_acquire.part.0+0xd4/0x280 [ 14.417041][ T259] ? console_lock_spinning_enable+0x40/0x60 [ 14.417043][ T259] ? rcu_is_watching+0x16/0xd0 [ 14.417045][ T259] ? lock_acquire+0x13c/0x160 [ 14.417047][ T259] console_lock_spinning_enable+0x5c/0x60 [ 14.417049][ T259] ? console_lock_spinning_enable+0x40/0x60 [ 14.417051][ T259] console_emit_next_record+0x1d1/0x4c0 [ 14.417053][ T259] ? devkmsg_read+0x4b0/0x4b0 [ 14.417054][ T259] ? console_flush_one_record+0x106/0x710 [ 14.417056][ T259] ? rcu_is_watching+0x16/0xd0 [ 14.417058][ T259] ? lock_acquire+0x13c/0x160 [ 14.417060][ T259] console_flush_one_record+0x46f/0x710 [ 14.417062][ T259] ? console_emit_next_record+0x4c0/0x4c0 [ 14.417064][ T259] ? __lock_acquire+0x518/0xc20 [ 14.417065][ T259] ? insn_get_immediate.part.0+0x287b/0x29e0 [ 14.417068][ T259] console_unlock+0xee/0x1f0 [ 14.417069][ T259] ? console_flush_one_record+0x710/0x710 [ 14.417071][ T259] ? rcu_is_watching+0x16/0xd0 [ 14.417073][ T259] ? lock_acquire+0x60/0x160 [ 14.417075][ T259] ? __down_trylock_console_sem+0x5e/0xa0 [ 14.417076][ T259] ? vprintk_emit+0x320/0x3e0 [ 14.417078][ T259] vprintk_emit+0x37c/0x3e0 [ 14.417080][ T259] ? wake_up_klogd_work_func+0x90/0x90 [ 14.417082][ T259] ? mark_lock+0x3bb/0xa00 [ 14.417084][ T259] _printk+0xc7/0x100 [ 14.417086][ T259] ? snapshot_read.cold+0x21/0x21 [ 14.417088][ T259] ? lock_acquire.part.0+0xd4/0x280 [ 14.417089][ T259] ? find_held_lock+0x2b/0x80 [ 14.417090][ T259] ? netdev_name+0x43/0x60 [ 14.417092][ T259] ? __netdev_printk+0x17f/0x1d0 [ 14.417093][ T259] netdev_err+0xe2/0x120 [ 14.417098][ T259] ? netdev_crit+0x120/0x120 [ 14.417099][ T259] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 14.417101][ T259] ? ___ratelimit+0x4e7/0xa00 [ 14.417104][ T259] ? idr_get_free+0x830/0x830 [ 14.417106][ T259] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 14.417109][ T259] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 14.417111][ T259] ? dst_release+0x54/0x240 [ 14.417113][ T259] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.417115][ T259] ? dev_nit_active_rcu+0x1f/0xf0 [ 14.417118][ T259] ? rcu_is_watching+0x16/0xd0 [ 14.417120][ T259] dev_hard_start_xmit+0xf4/0x620 [ 14.417122][ T259] ? rcu_is_watching+0x16/0xd0 [ 14.417124][ T259] __dev_queue_xmit+0xc0a/0x1b80 [ 14.417126][ T259] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 14.417128][ T259] ? lockdep_hardirqs_on+0x91/0x130 [ 14.417129][ T259] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 14.417130][ T259] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 14.417132][ T259] ? ref_tracker_dir_print+0x100/0x100 [ 14.417135][ T259] ? dst_init+0x84/0x530 [ 14.417136][ T259] ? dst_alloc+0x97/0x160 [ 14.417138][ T259] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 14.417139][ T259] ? ip_route_output_flow+0x21/0x150 [ 14.417141][ T259] ? __ip_queue_xmit+0xdc7/0x1650 [ 14.417142][ T259] ? __lock_acquire+0x518/0xc20 [ 14.417144][ T259] ? down_read_nested+0xe7/0x500 [ 14.417145][ T259] ? lock_acquire.part.0+0xd4/0x280 [ 14.417147][ T259] ? ip_finish_output2+0x25c/0x1bc0 [ 14.417149][ T259] ? rcu_is_watching+0x16/0xd0 [ 14.417151][ T259] ip_finish_output2+0x5b9/0x1bc0 [ 14.417153][ T259] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 14.417154][ T259] ? lock_acquire.part.0+0xd4/0x280 [ 14.417156][ T259] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 14.417158][ T259] ip_output+0x1ca/0x660 [ 14.417160][ T259] ? ip_finish_output+0x460/0x460 [ 14.417162][ T259] ? find_held_lock+0x2b/0x80 [ 14.417163][ T259] ? __lock_release.isra.0+0x69/0x1a0 [ 14.417165][ T259] ? ip4_dst_hoplimit+0x15b/0x320 [ 14.417167][ T259] __ip_queue_xmit+0x774/0x1650 [ 14.417169][ T259] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 14.417173][ T259] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.417177][ T259] ? l2tp_xmit_core.constprop.0+0x19b0/0x19b0 [l2tp_core] [ 14.417180][ T259] ? do_raw_spin_lock+0x131/0x280 [ 14.417182][ T259] ? pppol2tp_xmit+0x423/0x690 [l2tp_ppp] [ 14.417184][ T259] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.417185][ T259] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.417188][ T259] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.417190][ T259] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 14.417192][ T259] ? dst_release+0x54/0x240 [ 14.417193][ T259] ? down_read_nested+0x36b/0x500 [ 14.417195][ T259] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.417197][ T259] ? dev_nit_active_rcu+0x1f/0xf0 [ 14.417199][ T259] ? rcu_is_watching+0x16/0xd0 [ 14.417201][ T259] dev_hard_start_xmit+0xf4/0x620 [ 14.417203][ T259] ? rcu_is_watching+0x16/0xd0 [ 14.417205][ T259] __dev_queue_xmit+0xc0a/0x1b80 [ 14.417207][ T259] ? sock_queue_err_skb+0x680/0x680 [ 14.417209][ T259] ? kmem_cache_alloc_node_noprof+0x2a6/0x660 [ 14.417211][ T259] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.417213][ T259] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.417214][ T259] ? validate_chain+0x38b/0xc20 [ 14.417216][ T259] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 14.417217][ T259] ? __lock_acquire+0x518/0xc20 [ 14.417219][ T259] ? down_read_nested+0xe7/0x500 [ 14.417220][ T259] ? lock_acquire.part.0+0xd4/0x280 [ 14.417222][ T259] ? ip_finish_output2+0x25c/0x1bc0 [ 14.417224][ T259] ? rcu_is_watching+0x16/0xd0 [ 14.417226][ T259] ip_finish_output2+0x5b9/0x1bc0 [ 14.417228][ T259] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 14.417229][ T259] ? lock_acquire.part.0+0xd4/0x280 [ 14.417231][ T259] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 14.417232][ T259] ? rcu_is_watching+0x16/0xd0 [ 14.417235][ T259] ip_output+0x1ca/0x660 [ 14.417237][ T259] ? ip_finish_output+0x460/0x460 [ 14.417238][ T259] ? __ip_make_skb+0x106a/0x2300 [ 14.417241][ T259] ip_push_pending_frames+0xf6/0x250 [ 14.417243][ T259] raw_sendmsg+0x1169/0x1cc0 [ 14.417245][ T259] ? raw_send_hdrinc+0x1740/0x1740 [ 14.417246][ T259] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.417250][ T259] ? __might_fault+0x97/0x140 [ 14.417254][ T259] ? __might_fault+0x97/0x140 [ 14.417255][ T259] ? __might_fault+0x97/0x140 [ 14.417257][ T259] __sys_sendto+0x2aa/0x400 [ 14.417259][ T259] ? __ia32_sys_getpeername+0xd0/0xd0 [ 14.417263][ T259] ? exc_page_fault+0x87/0x100 [ 14.417265][ T259] __x64_sys_sendto+0xe4/0x1f0 [ 14.417266][ T259] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 14.417269][ T259] ? lockdep_hardirqs_on+0x91/0x130 [ 14.417270][ T259] ? do_syscall_64+0xa6/0x530 [ 14.417272][ T259] do_syscall_64+0xff/0x530 [ 14.417273][ T259] ? exc_page_fault+0xee/0x100 [ 14.417274][ T259] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.417276][ T259] RIP: 0033:0x7fef3b56054e [ 14.417279][ T259] 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.417280][ T259] RSP: 002b:00007ffea6e367f0 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 14.417283][ T259] RAX: ffffffffffffffda RBX: 0000000000000040 RCX: 00007fef3b56054e [ 14.417284][ T259] RDX: 0000000000000040 RSI: 0000563ac39dc0a4 RDI: 0000000000000005 [ 14.417285][ T259] RBP: 00007ffea6e36800 R08: 0000563ac39ee320 R09: 0000000000000010 [ 14.417286][ T259] R10: 0000000000000000 R11: 0000000000000202 R12: 431bde82d7b634db [ 14.417287][ T259] R13: 0000000000000000 R14: 0000563ac39ef3e0 R15: 0000563ac39dc0a0 [ 14.417289][ T259] [ 15.423259][ T259] ping (259) used greatest stack depth: 22536 bytes left [ 15.423275][ T259] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 15.423277][ T259] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 259, name: ping [ 15.423278][ T259] preempt_count: 2, expected: 0 [ 15.423279][ T259] RCU nest depth: 0, expected: 0 [ 15.423280][ T259] INFO: lockdep is turned off. [ 15.423281][ T259] irq event stamp: 27573 [ 15.423282][ T259] hardirqs last enabled at (27572): [] irqentry_exit+0x21c/0x790 [ 15.423290][ T259] hardirqs last disabled at (27573): [] console_emit_next_record+0x3d4/0x4c0 [ 15.423294][ T259] softirqs last enabled at (27470): [] __alloc_skb+0x4c2/0x5f0 [ 15.423297][ T259] softirqs last disabled at (27480): [] __dev_queue_xmit+0x1fc/0x1b80 [ 15.423301][ T259] Preemption disabled at: [ 15.423302][ T259] [<0000000000000000>] 0x0 [ 15.423308][ T259] CPU: 2 UID: 0 PID: 259 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.423311][ T259] Tainted: [W]=WARN [ 15.423312][ T259] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.423313][ T259] Call Trace: [ 15.423315][ T259] [ 15.423316][ T259] dump_stack_lvl+0x6f/0xa0 [ 15.423321][ T259] __might_resched.cold+0x1fe/0x2c1 [ 15.423324][ T259] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 15.423327][ T259] ? __asan_memcpy+0x3c/0x60 [ 15.423331][ T259] ? rcu_is_watching+0x16/0xd0 [ 15.423334][ T259] __kmalloc_noprof+0x443/0x760 [ 15.423337][ T259] ? __rwlock_init+0x150/0x150 [ 15.423339][ T259] ? alloc_buf.isra.0+0x4b/0x260 [ 15.423343][ T259] ? do_raw_spin_unlock+0x59/0x250 [ 15.423345][ T259] alloc_buf.isra.0+0x4b/0x260 [ 15.423346][ T259] put_chars+0x1e1/0x2f0 [ 15.423349][ T259] ? __send_to_port+0x420/0x420 [ 15.423350][ T259] ? console_prepend_replay+0x20/0x20 [ 15.423353][ T259] hvc_console_print+0x292/0x780 [ 15.423356][ T259] ? hvc_write+0x3a0/0x3a0 [ 15.423358][ T259] ? rcu_is_watching+0x16/0xd0 [ 15.423359][ T259] ? lock_acquire+0x13c/0x160 [ 15.423362][ T259] console_emit_next_record+0x22f/0x4c0 [ 15.423364][ T259] ? devkmsg_read+0x4b0/0x4b0 [ 15.423366][ T259] ? rcu_is_watching+0x16/0xd0 [ 15.423368][ T259] ? rcu_is_watching+0x16/0xd0 [ 15.423369][ T259] ? rcu_is_watching+0x16/0xd0 [ 15.423371][ T259] ? lock_acquire+0x13c/0x160 [ 15.423372][ T259] ? console_flush_one_record+0x111/0x710 [ 15.423374][ T259] console_flush_one_record+0x46f/0x710 [ 15.423376][ T259] ? console_emit_next_record+0x4c0/0x4c0 [ 15.423379][ T259] console_unlock+0xee/0x1f0 [ 15.423380][ T259] ? lock_acquire+0x13c/0x160 [ 15.423382][ T259] ? console_flush_one_record+0x710/0x710 [ 15.423383][ T259] ? rcu_is_watching+0x16/0xd0 [ 15.423385][ T259] ? lock_release+0x184/0x1f0 [ 15.423387][ T259] ? lock_acquire+0x60/0x160 [ 15.423388][ T259] ? __rcu_read_lock+0x30/0x70 [ 15.423390][ T259] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.423392][ T259] ? vprintk_emit+0x320/0x3e0 [ 15.423394][ T259] vprintk_emit+0x37c/0x3e0 [ 15.423396][ T259] ? wake_up_klogd_work_func+0x90/0x90 [ 15.423398][ T259] ? do_raw_read_unlock+0x70/0x70 [ 15.423399][ T259] ? forget_original_parent+0x5c/0xdb0 [ 15.423403][ T259] _printk+0xc7/0x100 [ 15.423406][ T259] ? snapshot_read.cold+0x21/0x21 [ 15.423407][ T259] ? do_raw_spin_lock+0x131/0x280 [ 15.423409][ T259] ? do_raw_spin_lock+0x131/0x280 [ 15.423411][ T259] ? __rwlock_init+0x150/0x150 [ 15.423412][ T259] ? do_raw_spin_lock+0x131/0x280 [ 15.423414][ T259] do_exit.cold+0x82/0x9c [ 15.423417][ T259] ? exit_notify+0x890/0x890 [ 15.423418][ T259] ? posixtimer_sig_unignore.isra.0+0x5f0/0x5f0 [ 15.423420][ T259] ? rcu_is_watching+0x16/0xd0 [ 15.423422][ T259] do_group_exit+0xb8/0x370 [ 15.423424][ T259] __x64_sys_exit_group+0x3c/0x50 [ 15.423425][ T259] x64_sys_call+0x1567/0x1570 [ 15.423428][ T259] do_syscall_64+0xff/0x530 [ 15.423430][ T259] ? exc_page_fault+0xee/0x100 [ 15.423432][ T259] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.423434][ T259] RIP: 0033:0x7fef3b5b11b8 [ 15.423436][ T259] Code: Unable to access opcode bytes at 0x7fef3b5b118e. [ 15.423437][ T259] RSP: 002b:00007ffea6e37f88 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 15.423440][ T259] RAX: ffffffffffffffda RBX: 00007fef3b6e1f88 RCX: 00007fef3b5b11b8 [ 15.423441][ T259] RDX: 00007fef3b3427c8 RSI: ffffffffffffffb0 RDI: 0000000000000001 [ 15.423442][ T259] RBP: 00007ffea6e37fe0 R08: 0000000000000000 R09: 0000000000001010 [ 15.423443][ T259] R10: 00007ffea6e37e00 R11: 0000000000000246 R12: 0000000000000002 [ 15.423444][ T259] R13: 0000000000000001 R14: 00007fef3b6e0680 R15: 00007fef3b6e1fa0 [ 15.423446][ T259] [ 15.461614][ T214] ppp0: recursion detected