[ 10.515192][ T180] PPP generic driver version 2.4.2 [ 10.515214][ T180] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.515217][ T180] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 180, name: modprobe [ 10.515219][ T180] preempt_count: 1, expected: 0 [ 10.515220][ T180] RCU nest depth: 0, expected: 0 [ 10.515221][ T180] locks held by modprobe/180: 4, last CPU#3: [ 10.515223][ T180] #0: ffffffff92f79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.515236][ T180] #1: ffffffff92f79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.515240][ T180] #2: ffffffff92e99660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.515244][ T180] #3: ffffffff92e99560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.515248][ T180] irq event stamp: 59652 [ 10.515249][ T180] hardirqs last enabled at (59651): [] irqentry_exit+0x21c/0x790 [ 10.515253][ T180] hardirqs last disabled at (59652): [] console_emit_next_record+0x3d4/0x4c0 [ 10.515256][ T180] softirqs last enabled at (57588): [] handle_softirqs+0x67c/0x900 [ 10.515259][ T180] softirqs last disabled at (57229): [] __irq_exit_rcu+0x145/0x1c0 [ 10.515261][ T180] Preemption disabled at: [ 10.515262][ T180] [] vprintk_emit+0x31b/0x3e0 [ 10.515267][ T180] CPU: 3 UID: 0 PID: 180 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.515270][ T180] Tainted: [W]=WARN [ 10.515271][ T180] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.515273][ T180] Call Trace: [ 10.515275][ T180] [ 10.515276][ T180] dump_stack_lvl+0x6f/0xa0 [ 10.515284][ T180] ? vprintk_emit+0x31b/0x3e0 [ 10.515286][ T180] __might_resched.cold+0x1fe/0x2c1 [ 10.515291][ T180] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.515295][ T180] ? __kmalloc_noprof+0xdb/0x760 [ 10.515300][ T180] __kmalloc_noprof+0x443/0x760 [ 10.515302][ T180] ? alloc_buf.isra.0+0x4b/0x260 [ 10.515308][ T180] ? do_raw_spin_unlock+0x59/0x250 [ 10.515311][ T180] alloc_buf.isra.0+0x4b/0x260 [ 10.515314][ T180] put_chars+0x1e1/0x2f0 [ 10.515317][ T180] ? __send_to_port+0x420/0x420 [ 10.515319][ T180] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.515324][ T180] ? rcu_read_lock_any_held+0x3c/0x90 [ 10.515326][ T180] ? validate_chain+0x38b/0xc20 [ 10.515331][ T180] hvc_console_print+0x292/0x780 [ 10.515334][ T180] ? __lock_acquire+0x518/0xc20 [ 10.515339][ T180] ? hvc_write+0x3a0/0x3a0 [ 10.515342][ T180] ? rcu_is_watching+0x16/0xd0 [ 10.515347][ T180] console_emit_next_record+0x22f/0x4c0 [ 10.515350][ T180] ? devkmsg_read+0x4b0/0x4b0 [ 10.515352][ T180] ? console_flush_one_record+0x106/0x710 [ 10.515356][ T180] ? rcu_is_watching+0x16/0xd0 [ 10.515358][ T180] ? lock_acquire+0x13c/0x160 [ 10.515362][ T180] console_flush_one_record+0x46f/0x710 [ 10.515366][ T180] ? console_emit_next_record+0x4c0/0x4c0 [ 10.515368][ T180] ? __lock_acquire+0x518/0xc20 [ 10.515373][ T180] console_unlock+0xee/0x1f0 [ 10.515376][ T180] ? console_flush_one_record+0x710/0x710 [ 10.515378][ T180] ? rcu_is_watching+0x16/0xd0 [ 10.515380][ T180] ? lock_acquire+0x60/0x160 [ 10.515383][ T180] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.515385][ T180] ? vprintk_emit+0x320/0x3e0 [ 10.515389][ T180] vprintk_emit+0x37c/0x3e0 [ 10.515393][ T180] ? wake_up_klogd_work_func+0x90/0x90 [ 10.515398][ T180] ? ppp_cleanup+0x920/0x920 [ppp_generic] [ 10.515402][ T180] _printk+0xc7/0x100 [ 10.515406][ T180] ? snapshot_read.cold+0x21/0x21 [ 10.515412][ T180] ? do_one_initcall+0x113/0x4f0 [ 10.515415][ T180] ppp_init+0x16/0x1000 [ppp_generic] [ 10.515419][ T180] do_one_initcall+0x124/0x4f0 [ 10.515421][ T180] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 10.515426][ T180] ? do_init_module+0xae/0x8a0 [ 10.515429][ T180] ? kasan_unpoison+0x44/0x70 [ 10.515434][ T180] do_init_module+0x2ad/0x8a0 [ 10.515437][ T180] ? lockdep_hardirqs_on+0x91/0x130 [ 10.515440][ T180] ? free_module+0x470/0x470 [ 10.515443][ T180] ? kfree+0x123/0x590 [ 10.515447][ T180] load_module+0x1424/0x1bb0 [ 10.515454][ T180] ? layout_and_allocate.isra.0+0x680/0x680 [ 10.515459][ T180] ? backing_file_read_iter+0x541/0x790 [ 10.515463][ T180] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.515467][ T180] init_module_from_file+0x11f/0x1a0 [ 10.515470][ T180] ? __do_sys_init_module+0x220/0x220 [ 10.515478][ T180] ? __rwlock_init+0x150/0x150 [ 10.515481][ T180] ? do_raw_spin_unlock+0x59/0x250 [ 10.515484][ T180] idempotent_init_module+0x37b/0x7c0 [ 10.515488][ T180] ? init_module_from_file+0x1a0/0x1a0 [ 10.515494][ T180] ? rcu_is_watching+0x16/0xd0 [ 10.515497][ T180] ? cap_capable+0x1d7/0x3d0 [ 10.515503][ T180] __x64_sys_finit_module+0xdf/0x190 [ 10.515505][ T180] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 10.515509][ T180] do_syscall_64+0xff/0x530 [ 10.515512][ T180] ? exc_page_fault+0xee/0x100 [ 10.515514][ T180] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.515516][ T180] RIP: 0033:0x7f9cf751337d [ 10.515520][ 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 [ 10.515521][ T180] RSP: 002b:00007ffd3cc53c18 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 10.515524][ T180] RAX: ffffffffffffffda RBX: 000055c2df44d1d0 RCX: 00007f9cf751337d [ 10.515525][ T180] RDX: 0000000000000000 RSI: 000055c2bd159ffd RDI: 0000000000000006 [ 10.515526][ T180] RBP: 00007ffd3cc53cb0 R08: 0000000000000000 R09: 0000000000000000 [ 10.515527][ T180] R10: 0000000000000000 R11: 0000000000000246 R12: 000055c2bd159ffd [ 10.515528][ T180] R13: 000055c2df44d180 R14: 0000000000040000 R15: 0000000000000000 [ 10.515535][ T180] [ 10.558210][ T180] NET: Registered PF_PPPOX protocol family [ 10.579059][ T180] l2tp_core: L2TP core driver, V2.0 [ 10.585387][ T180] l2tp_ppp: PPPoL2TP kernel driver, V2.0 [ 10.592500][ T180] modprobe (180) used greatest stack depth: 24656 bytes left [ 15.791831][ T246] iperf3 (246) used greatest stack depth: 23216 bytes left [ 15.791860][ T246] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 15.791864][ T246] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 246, name: iperf3 [ 15.791866][ T246] preempt_count: 2, expected: 0 [ 15.791867][ T246] RCU nest depth: 0, expected: 0 [ 15.791868][ T246] locks held by iperf3/246: 5, last CPU#0: [ 15.791871][ T246] #0: ffffffff92e127b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 15.791885][ T246] #1: ffffffff92f79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 15.791893][ T246] #2: ffffffff92f79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 15.791899][ T246] #3: ffffffff92e99660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 15.791905][ T246] #4: ffffffff92e99560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 15.791911][ T246] irq event stamp: 1477204 [ 15.791913][ T246] hardirqs last enabled at (1477203): [] __down_trylock_console_sem+0x86/0xa0 [ 15.791916][ T246] hardirqs last disabled at (1477204): [] console_emit_next_record+0x3d4/0x4c0 [ 15.791919][ T246] softirqs last enabled at (1476794): [] fpu__clear_user_states+0x104/0x290 [ 15.791924][ T246] softirqs last disabled at (1476792): [] fpu__clear_user_states+0x6d/0x290 [ 15.791928][ T246] Preemption disabled at: [ 15.791929][ T246] [<0000000000000000>] 0x0 [ 15.791937][ T246] CPU: 0 UID: 0 PID: 246 Comm: iperf3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.791942][ T246] Tainted: [W]=WARN [ 15.791943][ T246] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.791945][ T246] Call Trace: [ 15.791948][ T246] [ 15.791950][ T246] dump_stack_lvl+0x6f/0xa0 [ 15.791958][ T246] __might_resched.cold+0x1fe/0x2c1 [ 15.791964][ T246] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 15.791969][ T246] ? __kmalloc_noprof+0xdb/0x760 [ 15.791977][ T246] __kmalloc_noprof+0x443/0x760 [ 15.791980][ T246] ? alloc_buf.isra.0+0x4b/0x260 [ 15.791989][ T246] ? do_raw_spin_unlock+0x59/0x250 [ 15.791993][ T246] alloc_buf.isra.0+0x4b/0x260 [ 15.791998][ T246] put_chars+0x1e1/0x2f0 [ 15.792001][ T246] ? prb_final_commit+0x50/0x50 [ 15.792005][ T246] ? __send_to_port+0x420/0x420 [ 15.792010][ T246] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.792016][ T246] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.792019][ T246] ? validate_chain+0x38b/0xc20 [ 15.792025][ T246] hvc_console_print+0x292/0x780 [ 15.792030][ T246] ? mark_usage+0x61/0x170 [ 15.792033][ T246] ? __lock_acquire+0x518/0xc20 [ 15.792036][ T246] ? __lock_acquire+0x518/0xc20 [ 15.792042][ T246] ? hvc_write+0x3a0/0x3a0 [ 15.792046][ T246] ? console_emit_next_record+0x1df/0x4c0 [ 15.792051][ T246] ? rcu_is_watching+0x16/0xd0 [ 15.792055][ T246] ? lock_acquire+0x13c/0x160 [ 15.792062][ T246] console_emit_next_record+0x22f/0x4c0 [ 15.792068][ T246] ? devkmsg_read+0x4b0/0x4b0 [ 15.792070][ T246] ? console_flush_one_record+0x106/0x710 [ 15.792076][ T246] ? rcu_is_watching+0x16/0xd0 [ 15.792079][ T246] ? lock_acquire+0x13c/0x160 [ 15.792086][ T246] console_flush_one_record+0x46f/0x710 [ 15.792093][ T246] ? console_emit_next_record+0x4c0/0x4c0 [ 15.792095][ T246] ? __lock_acquire+0x518/0xc20 [ 15.792104][ T246] console_unlock+0xee/0x1f0 [ 15.792109][ T246] ? console_flush_one_record+0x710/0x710 [ 15.792112][ T246] ? rcu_is_watching+0x16/0xd0 [ 15.792115][ T246] ? lock_acquire+0x60/0x160 [ 15.792122][ T246] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.792124][ T246] ? vprintk_emit+0x320/0x3e0 [ 15.792130][ T246] vprintk_emit+0x37c/0x3e0 [ 15.792136][ T246] ? wake_up_klogd_work_func+0x90/0x90 [ 15.792141][ T246] ? __lock_acquire+0x518/0xc20 [ 15.792148][ T246] _printk+0xc7/0x100 [ 15.792153][ T246] ? snapshot_read.cold+0x21/0x21 [ 15.792157][ T246] ? do_raw_spin_lock+0x131/0x280 [ 15.792161][ T246] ? __rwlock_init+0x150/0x150 [ 15.792168][ T246] ? do_raw_spin_lock+0x131/0x280 [ 15.792172][ T246] do_exit.cold+0x82/0x9c [ 15.792177][ T246] ? exit_notify+0x890/0x890 [ 15.792187][ T246] __x64_sys_exit+0x42/0x50 [ 15.792190][ T246] x64_sys_call+0x156c/0x1570 [ 15.792194][ T246] do_syscall_64+0xff/0x530 [ 15.792199][ T246] ? exc_page_fault+0xee/0x100 [ 15.792203][ T246] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.792207][ T246] RIP: 0033:0x7fde510c5c49 [ 15.792210][ T246] Code: Unable to access opcode bytes at 0x7fde510c5c1f. [ 15.792211][ T246] RSP: 002b:00007fde50884e60 EFLAGS: 00000246 ORIG_RAX: 000000000000003c [ 15.792215][ T246] RAX: ffffffffffffffda RBX: 00007fde508856c0 RCX: 00007fde510c5c49 [ 15.792217][ T246] RDX: 0000000000000000 RSI: 0000000000801000 RDI: 0000000000000000 [ 15.792218][ T246] RBP: 00007fde50884f70 R08: 0000000000000001 R09: 00007fde508856c0 [ 15.792220][ T246] R10: 0000000000000008 R11: 0000000000000246 R12: 00007fde50085000 [ 15.792221][ T246] R13: 00007ffef79d58e0 R14: 0000000000000002 R15: 00007ffef79d59e7 [ 15.792234][ T246] [ 15.977011][ T250] ppp0: recursion detected [ 15.977055][ T250] [ 15.977057][ T250] ===================================================== [ 15.977058][ T250] WARNING: SOFTIRQ-safe -> SOFTIRQ-unsafe lock order detected [ 15.977060][ T250] 7.2.0-virtme #1 Tainted: G W [ 15.977062][ T250] ----------------------------------------------------- [ 15.977062][ T250] ping/250 [HC0[0]:SC0[10]:HE0:SE0] is trying to acquire: [ 15.977064][ T250] ffffffff92e99660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 15.977077][ T250] [ 15.977077][ T250] and this task is already holding: [ 15.977077][ T250] ff1100000590d0d8 (slock-AF_INET/2){+.-.}-{3:3}, at: l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.977084][ T250] which would create a new lock dependency: [ 15.977085][ T250] (slock-AF_INET/2){+.-.}-{3:3} -> (console_owner){....}-{0:0} [ 15.977088][ T250] [ 15.977088][ T250] but this new dependency connects a SOFTIRQ-irq-safe lock: [ 15.977088][ T250] (slock-AF_INET/2){+.-.}-{3:3} [ 15.977090][ T250] [ 15.977090][ T250] ... which became SOFTIRQ-irq-safe at: [ 15.977091][ T250] __lock_acquire+0x388/0xc20 [ 15.977094][ T250] lock_acquire.part.0+0xd4/0x280 [ 15.977096][ T250] _raw_spin_lock_nested+0x3d/0x50 [ 15.977099][ T250] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.977101][ T250] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.977104][ T250] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.977106][ T250] ppp_push+0x165/0x2d0 [ppp_generic] [ 15.977108][ T250] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 15.977110][ T250] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.977112][ T250] dev_hard_start_xmit+0xf4/0x620 [ 15.977115][ T250] __dev_queue_xmit+0xc0a/0x1b80 [ 15.977117][ T250] ip_finish_output2+0x5b9/0x1bc0 [ 15.977120][ T250] ip_output+0x1ca/0x660 [ 15.977121][ T250] ip_push_pending_frames+0xf6/0x250 [ 15.977123][ T250] icmp_reply+0x94d/0xa50 [ 15.977126][ T250] icmp_echo+0x2ab/0x4b0 [ 15.977127][ T250] icmp_rcv+0x92f/0xfb0 [ 15.977129][ T250] ip_protocol_deliver_rcu+0x82/0x350 [ 15.977131][ T250] ip_local_deliver_finish+0x36f/0x610 [ 15.977132][ T250] ip_local_deliver+0x184/0x4c0 [ 15.977133][ T250] ip_rcv+0xdc/0x3d0 [ 15.977134][ T250] __netif_receive_skb_one_core+0xfc/0x180 [ 15.977136][ T250] process_backlog+0x431/0x14c0 [ 15.977138][ T250] __napi_poll+0xa7/0x3b0 [ 15.977140][ T250] net_rx_action+0x513/0xf50 [ 15.977141][ T250] handle_softirqs+0x1d3/0x900 [ 15.977144][ T250] do_softirq+0xac/0xe0 [ 15.977145][ T250] __local_bh_enable_ip+0x118/0x150 [ 15.977146][ T250] __dev_queue_xmit+0x979/0x1b80 [ 15.977148][ T250] ip_finish_output2+0x5b9/0x1bc0 [ 15.977149][ T250] ip_output+0x1ca/0x660 [ 15.977151][ T250] ip_push_pending_frames+0xf6/0x250 [ 15.977152][ T250] raw_sendmsg+0x1169/0x1cc0 [ 15.977154][ T250] __sys_sendto+0x2aa/0x400 [ 15.977157][ T250] __x64_sys_sendto+0xe4/0x1f0 [ 15.977158][ T250] do_syscall_64+0xff/0x530 [ 15.977161][ T250] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.977163][ T250] [ 15.977163][ T250] to a SOFTIRQ-irq-unsafe lock: [ 15.977164][ T250] (fs_reclaim){+.+.}-{0:0} [ 15.977165][ T250] [ 15.977165][ T250] ... which became SOFTIRQ-irq-unsafe at: [ 15.977165][ T250] ... [ 15.977166][ T250] __lock_acquire+0x388/0xc20 [ 15.977168][ T250] lock_acquire.part.0+0xd4/0x280 [ 15.977169][ T250] fs_reclaim_acquire+0xd5/0x120 [ 15.977171][ T250] __kmalloc_cache_noprof+0x6e/0x620 [ 15.977174][ T250] kthread_create_worker_on_node+0xea/0x210 [ 15.977176][ T250] workqueue_init+0x2a/0x680 [ 15.977180][ T250] kernel_init_freeable+0x2fe/0x630 [ 15.977182][ T250] kernel_init+0x21/0x150 [ 15.977184][ T250] ret_from_fork+0x474/0x6b0 [ 15.977187][ T250] ret_from_fork_asm+0x11/0x20 [ 15.977190][ T250] [ 15.977190][ T250] other info that might help us debug this: [ 15.977190][ T250] [ 15.977190][ T250] Chain exists of: [ 15.977190][ T250] slock-AF_INET/2 --> console_owner --> fs_reclaim [ 15.977190][ T250] [ 15.977193][ T250] Possible interrupt unsafe locking scenario: [ 15.977193][ T250] [ 15.977193][ T250] CPU0 CPU1 [ 15.977194][ T250] ---- ---- [ 15.977194][ T250] lock(fs_reclaim); [ 15.977195][ T250] local_irq_disable(); [ 15.977195][ T250] lock(slock-AF_INET/2); [ 15.977197][ T250] lock(console_owner); [ 15.977197][ T250] [ 15.977198][ T250] lock(slock-AF_INET/2); [ 15.977199][ T250] [ 15.977199][ T250] *** DEADLOCK *** [ 15.977199][ T250] [ 15.977199][ T250] locks held by ping/250: 14, last CPU#1: [ 15.977201][ T250] #0: ff11000005906290 (sk_lock-AF_INET){+.+.}-{0:0}, at: raw_sendmsg+0xba5/0x1cc0 [ 15.977204][ T250] #1: ffffffff92f90b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 15.977207][ T250] #2: ffffffff92f90b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 15.977210][ T250] #3: ffffffff92f90aa0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x1fc/0x1b80 [ 15.977213][ T250] #4: ffd1ffffffc85b08 (&xmit_recursion->bh_lock){+.-.}-{3:3}, at: ppp_xmit_process+0x1d2/0x8e0 [ppp_generic] [ 15.977216][ T250] #5: ff1100000f5cafd0 (&ppp->wlock){+.-.}-{3:3}, at: ppp_xmit_process+0x292/0x8e0 [ppp_generic] [ 15.977219][ T250] #6: ff1100000d5a65d0 (&pch->downl){+.-.}-{3:3}, at: ppp_push+0xae/0x2d0 [ppp_generic] [ 15.977223][ T250] #7: ff1100000590d0d8 (slock-AF_INET/2){+.-.}-{3:3}, at: l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.977227][ T250] #8: ffffffff92f90b00 (rcu_read_lock){....}-{1:3}, at: __ip_queue_xmit+0x63/0x1650 [ 15.977230][ T250] #9: ffffffff92f90b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 15.977233][ T250] #10: ffffffff92f90b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 15.977236][ T250] #11: ffffffff92f90aa0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x1fc/0x1b80 [ 15.977239][ T250] #12: ffffffff92f79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 15.977242][ T250] #13: ffffffff92f79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 15.977245][ T250] [ 15.977245][ T250] the dependencies between SOFTIRQ-irq-safe lock and the holding lock: [ 15.977246][ T250] -> (slock-AF_INET/2){+.-.}-{3:3} { [ 15.977248][ T250] HARDIRQ-ON-W at: [ 15.977249][ T250] __lock_acquire+0x388/0xc20 [ 15.977251][ T250] lock_acquire.part.0+0xd4/0x280 [ 15.977252][ T250] _raw_spin_lock_nested+0x3d/0x50 [ 15.977253][ T250] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.977256][ T250] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.977258][ T250] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.977259][ T250] __ppp_channel_push+0xed/0x280 [ppp_generic] [ 15.977261][ T250] ppp_channel_push+0x269/0x7b0 [ppp_generic] [ 15.977263][ T250] ppp_write+0x26b/0x350 [ppp_generic] [ 15.977264][ T250] vfs_write+0x1a4/0xbd0 [ 15.977267][ T250] ksys_write+0x116/0x250 [ 15.977269][ T250] do_syscall_64+0xff/0x530 [ 15.977270][ T250] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.977272][ T250] IN-SOFTIRQ-W at: [ 15.977272][ T250] __lock_acquire+0x388/0xc20 [ 15.977274][ T250] lock_acquire.part.0+0xd4/0x280 [ 15.977275][ T250] _raw_spin_lock_nested+0x3d/0x50 [ 15.977276][ T250] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.977278][ T250] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.977284][ T250] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.977285][ T250] ppp_push+0x165/0x2d0 [ppp_generic] [ 15.977287][ T250] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 15.977289][ T250] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.977291][ T250] dev_hard_start_xmit+0xf4/0x620 [ 15.977292][ T250] __dev_queue_xmit+0xc0a/0x1b80 [ 15.977294][ T250] ip_finish_output2+0x5b9/0x1bc0 [ 15.977295][ T250] ip_output+0x1ca/0x660 [ 15.977297][ T250] ip_push_pending_frames+0xf6/0x250 [ 15.977298][ T250] icmp_reply+0x94d/0xa50 [ 15.977300][ T250] icmp_echo+0x2ab/0x4b0 [ 15.977301][ T250] icmp_rcv+0x92f/0xfb0 [ 15.977303][ T250] ip_protocol_deliver_rcu+0x82/0x350 [ 15.977304][ T250] ip_local_deliver_finish+0x36f/0x610 [ 15.977306][ T250] ip_local_deliver+0x184/0x4c0 [ 15.977307][ T250] ip_rcv+0xdc/0x3d0 [ 15.977308][ T250] __netif_receive_skb_one_core+0xfc/0x180 [ 15.977310][ T250] process_backlog+0x431/0x14c0 [ 15.977312][ T250] __napi_poll+0xa7/0x3b0 [ 15.977313][ T250] net_rx_action+0x513/0xf50 [ 15.977315][ T250] handle_softirqs+0x1d3/0x900 [ 15.977316][ T250] do_softirq+0xac/0xe0 [ 15.977317][ T250] __local_bh_enable_ip+0x118/0x150 [ 15.977318][ T250] __dev_queue_xmit+0x979/0x1b80 [ 15.977320][ T250] ip_finish_output2+0x5b9/0x1bc0 [ 15.977321][ T250] ip_output+0x1ca/0x660 [ 15.977323][ T250] ip_push_pending_frames+0xf6/0x250 [ 15.977324][ T250] raw_sendmsg+0x1169/0x1cc0 [ 15.977325][ T250] __sys_sendto+0x2aa/0x400 [ 15.977327][ T250] __x64_sys_sendto+0xe4/0x1f0 [ 15.977328][ T250] do_syscall_64+0xff/0x530 [ 15.977330][ T250] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.977331][ T250] INITIAL USE at: [ 15.977332][ T250] __lock_acquire+0x388/0xc20 [ 15.977333][ T250] lock_acquire.part.0+0xd4/0x280 [ 15.977334][ T250] _raw_spin_lock_nested+0x3d/0x50 [ 15.977335][ T250] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.977337][ T250] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.977340][ T250] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.977341][ T250] __ppp_channel_push+0xed/0x280 [ppp_generic] [ 15.977342][ T250] ppp_channel_push+0x269/0x7b0 [ppp_generic] [ 15.977344][ T250] ppp_write+0x26b/0x350 [ppp_generic] [ 15.977345][ T250] vfs_write+0x1a4/0xbd0 [ 15.977347][ T250] ksys_write+0x116/0x250 [ 15.977349][ T250] do_syscall_64+0xff/0x530 [ 15.977350][ T250] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.977351][ T250] } [ 15.977352][ T250] ... key at: [] af_family_slock_keys+0x22/0x300 [ 15.977355][ T250] [ 15.977355][ T250] the dependencies between the lock to be acquired [ 15.977355][ T250] and SOFTIRQ-irq-unsafe lock: [ 15.977361][ T250] -> (fs_reclaim){+.+.}-{0:0} { [ 15.977362][ T250] HARDIRQ-ON-W at: [ 15.977363][ T250] __lock_acquire+0x388/0xc20 [ 15.977364][ T250] lock_acquire.part.0+0xd4/0x280 [ 15.977366][ T250] fs_reclaim_acquire+0xd5/0x120 [ 15.977367][ T250] __kmalloc_cache_noprof+0x6e/0x620 [ 15.977368][ T250] kthread_create_worker_on_node+0xea/0x210 [ 15.977370][ T250] workqueue_init+0x2a/0x680 [ 15.977371][ T250] kernel_init_freeable+0x2fe/0x630 [ 15.977372][ T250] kernel_init+0x21/0x150 [ 15.977374][ T250] ret_from_fork+0x474/0x6b0 [ 15.977375][ T250] ret_from_fork_asm+0x11/0x20 [ 15.977376][ T250] SOFTIRQ-ON-W at: [ 15.977377][ T250] __lock_acquire+0x388/0xc20 [ 15.977378][ T250] lock_acquire.part.0+0xd4/0x280 [ 15.977380][ T250] fs_reclaim_acquire+0xd5/0x120 [ 15.977381][ T250] __kmalloc_cache_noprof+0x6e/0x620 [ 15.977382][ T250] kthread_create_worker_on_node+0xea/0x210 [ 15.977383][ T250] workqueue_init+0x2a/0x680 [ 15.977384][ T250] kernel_init_freeable+0x2fe/0x630 [ 15.977386][ T250] kernel_init+0x21/0x150 [ 15.977387][ T250] ret_from_fork+0x474/0x6b0 [ 15.977388][ T250] ret_from_fork_asm+0x11/0x20 [ 15.977390][ T250] INITIAL USE at: [ 15.977390][ T250] __lock_acquire+0x388/0xc20 [ 15.977392][ T250] lock_acquire.part.0+0xd4/0x280 [ 15.977393][ T250] fs_reclaim_acquire+0xd5/0x120 [ 15.977394][ T250] __kmalloc_cache_noprof+0x6e/0x620 [ 15.977396][ T250] kthread_create_worker_on_node+0xea/0x210 [ 15.977397][ T250] workqueue_init+0x2a/0x680 [ 15.977398][ T250] kernel_init_freeable+0x2fe/0x630 [ 15.977400][ T250] kernel_init+0x21/0x150 [ 15.977401][ T250] ret_from_fork+0x474/0x6b0 [ 15.977402][ T250] ret_from_fork_asm+0x11/0x20 [ 15.977404][ T250] } [ 15.977404][ T250] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 15.977408][ T250] ... acquired at: [ 15.977409][ T250] __lock_acquire+0x518/0xc20 [ 15.977410][ T250] lock_acquire.part.0+0xd4/0x280 [ 15.977411][ T250] fs_reclaim_acquire+0xd5/0x120 [ 15.977412][ T250] __kmalloc_noprof+0xd3/0x760 [ 15.977414][ T250] alloc_buf.isra.0+0x4b/0x260 [ 15.977417][ T250] put_chars+0x1e1/0x2f0 [ 15.977418][ T250] hvc_console_print+0x292/0x780 [ 15.977421][ T250] console_emit_next_record+0x22f/0x4c0 [ 15.977422][ T250] console_flush_one_record+0x46f/0x710 [ 15.977424][ T250] console_unlock+0xee/0x1f0 [ 15.977425][ T250] vprintk_emit+0x37c/0x3e0 [ 15.977427][ T250] dev_vprintk_emit+0x27f/0x2c0 [ 15.977430][ T250] dev_printk_emit+0xb9/0xee [ 15.977431][ T250] _dev_info+0xe2/0x116 [ 15.977433][ T250] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 15.977436][ T250] cmos_do_probe+0x73b/0x98a [ 15.977437][ T250] platform_probe+0xfe/0x1f0 [ 15.977439][ T250] call_driver_probe+0x61/0x1c0 [ 15.977442][ T250] really_probe+0x199/0x760 [ 15.977443][ T250] __driver_probe_device+0x24f/0x440 [ 15.977445][ T250] driver_probe_device+0x4a/0xf0 [ 15.977447][ T250] __driver_attach+0x1b8/0x540 [ 15.977448][ T250] bus_for_each_dev+0x130/0x1e0 [ 15.977449][ T250] bus_add_driver+0x2c8/0x530 [ 15.977451][ T250] driver_register+0x1a3/0x390 [ 15.977453][ T250] __platform_driver_probe+0x13f/0x270 [ 15.977454][ T250] cmos_init+0x31/0x40 [ 15.977456][ T250] do_one_initcall+0x124/0x4f0 [ 15.977458][ T250] kernel_init_freeable+0x596/0x630 [ 15.977460][ T250] kernel_init+0x21/0x150 [ 15.977461][ T250] ret_from_fork+0x474/0x6b0 [ 15.977462][ T250] ret_from_fork_asm+0x11/0x20 [ 15.977463][ T250] [ 15.977464][ T250] -> (console_owner){....}-{0:0} { [ 15.977465][ T250] INITIAL USE at: [ 15.977466][ T250] } [ 15.977467][ T250] ... key at: [] console_owner_dep_map+0x0/0x60 [ 15.977470][ T250] ... acquired at: [ 15.977471][ T250] check_prev_add+0xef/0xe90 [ 15.977472][ T250] validate_chain+0x6b8/0xc20 [ 15.977473][ T250] __lock_acquire+0x518/0xc20 [ 15.977475][ T250] lock_acquire.part.0+0xd4/0x280 [ 15.977476][ T250] console_lock_spinning_enable+0x5c/0x60 [ 15.977478][ T250] console_emit_next_record+0x1d1/0x4c0 [ 15.977479][ T250] console_flush_one_record+0x46f/0x710 [ 15.977481][ T250] console_unlock+0xee/0x1f0 [ 15.977482][ T250] vprintk_emit+0x37c/0x3e0 [ 15.977484][ T250] _printk+0xc7/0x100 [ 15.977486][ T250] netdev_err+0xe2/0x120 [ 15.977488][ T250] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 15.977490][ T250] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.977492][ T250] dev_hard_start_xmit+0xf4/0x620 [ 15.977494][ T250] __dev_queue_xmit+0xc0a/0x1b80 [ 15.977495][ T250] ip_finish_output2+0x5b9/0x1bc0 [ 15.977497][ T250] ip_output+0x1ca/0x660 [ 15.977498][ T250] __ip_queue_xmit+0x774/0x1650 [ 15.977500][ T250] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 15.977502][ T250] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.977504][ T250] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.977505][ T250] ppp_push+0x165/0x2d0 [ppp_generic] [ 15.977507][ T250] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 15.977508][ T250] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.977510][ T250] dev_hard_start_xmit+0xf4/0x620 [ 15.977511][ T250] __dev_queue_xmit+0xc0a/0x1b80 [ 15.977513][ T250] ip_finish_output2+0x5b9/0x1bc0 [ 15.977514][ T250] ip_output+0x1ca/0x660 [ 15.977516][ T250] ip_push_pending_frames+0xf6/0x250 [ 15.977517][ T250] raw_sendmsg+0x1169/0x1cc0 [ 15.977518][ T250] __sys_sendto+0x2aa/0x400 [ 15.977520][ T250] __x64_sys_sendto+0xe4/0x1f0 [ 15.977521][ T250] do_syscall_64+0xff/0x530 [ 15.977523][ T250] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.977524][ T250] [ 15.977524][ T250] [ 15.977524][ T250] stack backtrace: [ 15.977527][ T250] CPU: 1 UID: 0 PID: 250 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.977530][ T250] Tainted: [W]=WARN [ 15.977531][ T250] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.977533][ T250] Call Trace: [ 15.977534][ T250] [ 15.977536][ T250] dump_stack_lvl+0x6f/0xa0 [ 15.977540][ T250] print_bad_irq_dependency+0x3e4/0x410 [ 15.977542][ T250] check_irq_usage+0x403/0x570 [ 15.977544][ T250] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.977548][ T250] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.977551][ T250] ? check_path.constprop.0+0x24/0x50 [ 15.977553][ T250] ? check_prev_add+0xef/0xe90 [ 15.977554][ T250] check_prev_add+0xef/0xe90 [ 15.977556][ T250] validate_chain+0x6b8/0xc20 [ 15.977558][ T250] __lock_acquire+0x518/0xc20 [ 15.977561][ T250] lock_acquire.part.0+0xd4/0x280 [ 15.977562][ T250] ? console_lock_spinning_enable+0x40/0x60 [ 15.977564][ T250] ? rcu_is_watching+0x16/0xd0 [ 15.977566][ T250] ? lock_acquire+0x13c/0x160 [ 15.977568][ T250] console_lock_spinning_enable+0x5c/0x60 [ 15.977570][ T250] ? console_lock_spinning_enable+0x40/0x60 [ 15.977572][ T250] console_emit_next_record+0x1d1/0x4c0 [ 15.977574][ T250] ? devkmsg_read+0x4b0/0x4b0 [ 15.977576][ T250] ? console_flush_one_record+0x106/0x710 [ 15.977578][ T250] ? rcu_is_watching+0x16/0xd0 [ 15.977579][ T250] ? lock_acquire+0x13c/0x160 [ 15.977581][ T250] console_flush_one_record+0x46f/0x710 [ 15.977583][ T250] ? console_emit_next_record+0x4c0/0x4c0 [ 15.977585][ T250] ? __lock_acquire+0x518/0xc20 [ 15.977587][ T250] console_unlock+0xee/0x1f0 [ 15.977589][ T250] ? console_flush_one_record+0x710/0x710 [ 15.977591][ T250] ? rcu_is_watching+0x16/0xd0 [ 15.977593][ T250] ? lock_acquire+0x60/0x160 [ 15.977594][ T250] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.977596][ T250] ? vprintk_emit+0x320/0x3e0 [ 15.977598][ T250] vprintk_emit+0x37c/0x3e0 [ 15.977600][ T250] ? wake_up_klogd_work_func+0x90/0x90 [ 15.977602][ T250] ? mark_lock+0x3bb/0xa00 [ 15.977604][ T250] _printk+0xc7/0x100 [ 15.977606][ T250] ? snapshot_read.cold+0x21/0x21 [ 15.977608][ T250] ? lock_acquire.part.0+0xd4/0x280 [ 15.977609][ T250] ? find_held_lock+0x2b/0x80 [ 15.977610][ T250] ? netdev_name+0x43/0x60 [ 15.977612][ T250] ? __netdev_printk+0x17f/0x1d0 [ 15.977614][ T250] netdev_err+0xe2/0x120 [ 15.977615][ T250] ? netdev_crit+0x120/0x120 [ 15.977616][ T250] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 15.977618][ T250] ? ___ratelimit+0x4e7/0xa00 [ 15.977621][ T250] ? idr_get_free+0x830/0x830 [ 15.977623][ T250] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 15.977626][ T250] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 15.977628][ T250] ? dst_release+0x54/0x240 [ 15.977630][ T250] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.977632][ T250] ? dev_nit_active_rcu+0x1f/0xf0 [ 15.977635][ T250] ? rcu_is_watching+0x16/0xd0 [ 15.977637][ T250] dev_hard_start_xmit+0xf4/0x620 [ 15.977639][ T250] ? rcu_is_watching+0x16/0xd0 [ 15.977641][ T250] __dev_queue_xmit+0xc0a/0x1b80 [ 15.977643][ T250] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 15.977645][ T250] ? lockdep_hardirqs_on+0x91/0x130 [ 15.977646][ T250] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 15.977647][ T250] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 15.977649][ T250] ? ref_tracker_dir_print+0x100/0x100 [ 15.977653][ T250] ? dst_init+0x84/0x530 [ 15.977654][ T250] ? dst_alloc+0x97/0x160 [ 15.977655][ T250] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 15.977657][ T250] ? ip_route_output_flow+0x21/0x150 [ 15.977659][ T250] ? __ip_queue_xmit+0xdc7/0x1650 [ 15.977660][ T250] ? __lock_acquire+0x518/0xc20 [ 15.977661][ T250] ? down_read_nested+0xe7/0x500 [ 15.977663][ T250] ? lock_acquire.part.0+0xd4/0x280 [ 15.977665][ T250] ? ip_finish_output2+0x25c/0x1bc0 [ 15.977666][ T250] ? rcu_is_watching+0x16/0xd0 [ 15.977669][ T250] ip_finish_output2+0x5b9/0x1bc0 [ 15.977671][ T250] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 15.977672][ T250] ? lock_acquire.part.0+0xd4/0x280 [ 15.977674][ T250] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 15.977676][ T250] ip_output+0x1ca/0x660 [ 15.977678][ T250] ? ip_finish_output+0x460/0x460 [ 15.977680][ T250] ? find_held_lock+0x2b/0x80 [ 15.977681][ T250] ? __lock_release.isra.0+0x69/0x1a0 [ 15.977683][ T250] ? ip4_dst_hoplimit+0x15b/0x320 [ 15.977685][ T250] __ip_queue_xmit+0x774/0x1650 [ 15.977688][ T250] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 15.977691][ T250] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.977694][ T250] ? l2tp_xmit_core.constprop.0+0x19b0/0x19b0 [l2tp_core] [ 15.977697][ T250] ? do_raw_spin_lock+0x131/0x280 [ 15.977699][ T250] ? pppol2tp_xmit+0x423/0x690 [l2tp_ppp] [ 15.977700][ T250] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.977702][ T250] ppp_push+0x165/0x2d0 [ppp_generic] [ 15.977704][ T250] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 15.977707][ T250] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 15.977709][ T250] ? dst_release+0x54/0x240 [ 15.977710][ T250] ? down_read_nested+0x36c/0x500 [ 15.977712][ T250] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.977714][ T250] ? dev_nit_active_rcu+0x1f/0xf0 [ 15.977716][ T250] ? rcu_is_watching+0x16/0xd0 [ 15.977717][ T250] dev_hard_start_xmit+0xf4/0x620 [ 15.977720][ T250] ? rcu_is_watching+0x16/0xd0 [ 15.977722][ T250] __dev_queue_xmit+0xc0a/0x1b80 [ 15.977724][ T250] ? sock_queue_err_skb+0x680/0x680 [ 15.977726][ T250] ? kmem_cache_alloc_node_noprof+0x2a6/0x660 [ 15.977728][ T250] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.977730][ T250] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.977731][ T250] ? validate_chain+0x38b/0xc20 [ 15.977733][ T250] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 15.977735][ T250] ? __lock_acquire+0x518/0xc20 [ 15.977736][ T250] ? down_read_nested+0xe7/0x500 [ 15.977738][ T250] ? lock_acquire.part.0+0xd4/0x280 [ 15.977739][ T250] ? ip_finish_output2+0x25c/0x1bc0 [ 15.977741][ T250] ? rcu_is_watching+0x16/0xd0 [ 15.977743][ T250] ip_finish_output2+0x5b9/0x1bc0 [ 15.977745][ T250] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 15.977747][ T250] ? lock_acquire.part.0+0xd4/0x280 [ 15.977748][ T250] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 15.977750][ T250] ? rcu_is_watching+0x16/0xd0 [ 15.977752][ T250] ip_output+0x1ca/0x660 [ 15.977754][ T250] ? ip_finish_output+0x460/0x460 [ 15.977756][ T250] ? __ip_make_skb+0x106a/0x2300 [ 15.977759][ T250] ip_push_pending_frames+0xf6/0x250 [ 15.977761][ T250] raw_sendmsg+0x1169/0x1cc0 [ 15.977763][ T250] ? raw_send_hdrinc+0x1740/0x1740 [ 15.977764][ T250] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.977768][ T250] ? __might_fault+0x97/0x140 [ 15.977772][ T250] ? __might_fault+0x97/0x140 [ 15.977773][ T250] ? __might_fault+0x97/0x140 [ 15.977775][ T250] __sys_sendto+0x2aa/0x400 [ 15.977777][ T250] ? __ia32_sys_getpeername+0xd0/0xd0 [ 15.977781][ T250] ? exc_page_fault+0x87/0x100 [ 15.977783][ T250] __x64_sys_sendto+0xe4/0x1f0 [ 15.977785][ T250] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 15.977787][ T250] ? lockdep_hardirqs_on+0x91/0x130 [ 15.977788][ T250] ? do_syscall_64+0xa6/0x530 [ 15.977790][ T250] do_syscall_64+0xff/0x530 [ 15.977792][ T250] ? exc_page_fault+0xee/0x100 [ 15.977793][ T250] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.977795][ T250] RIP: 0033:0x7ff53e99054e [ 15.977797][ T250] 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 [ 15.977799][ T250] RSP: 002b:00007ffcf6d8ac30 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 15.977802][ T250] RAX: ffffffffffffffda RBX: 0000000000000040 RCX: 00007ff53e99054e [ 15.977803][ T250] RDX: 0000000000000040 RSI: 00005627630910a4 RDI: 0000000000000005 [ 15.977804][ T250] RBP: 00007ffcf6d8ac40 R08: 00005627630a3320 R09: 0000000000000010 [ 15.977805][ T250] R10: 0000000000000000 R11: 0000000000000202 R12: 431bde82d7b634db [ 15.977806][ T250] R13: 0000000000000000 R14: 00005627630a43e0 R15: 00005627630910a0 [ 15.977808][ T250] [ 16.987968][ T250] ping (250) used greatest stack depth: 22536 bytes left [ 16.987986][ T250] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 16.987989][ T250] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 250, name: ping [ 16.987991][ T250] preempt_count: 2, expected: 0 [ 16.987991][ T250] RCU nest depth: 0, expected: 0 [ 16.987992][ T250] INFO: lockdep is turned off. [ 16.987993][ T250] irq event stamp: 27989 [ 16.987994][ T250] hardirqs last enabled at (27988): [] __down_trylock_console_sem+0x86/0xa0 [ 16.988002][ T250] hardirqs last disabled at (27989): [] console_emit_next_record+0x3d4/0x4c0 [ 16.988005][ T250] softirqs last enabled at (27892): [] __alloc_skb+0x4c2/0x5f0 [ 16.988009][ T250] softirqs last disabled at (27902): [] __dev_queue_xmit+0x1fc/0x1b80 [ 16.988013][ T250] Preemption disabled at: [ 16.988014][ T250] [<0000000000000000>] 0x0 [ 16.988020][ T250] CPU: 3 UID: 0 PID: 250 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 16.988023][ T250] Tainted: [W]=WARN [ 16.988024][ T250] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.988026][ T250] Call Trace: [ 16.988027][ T250] [ 16.988029][ T250] dump_stack_lvl+0x6f/0xa0 [ 16.988034][ T250] __might_resched.cold+0x1fe/0x2c1 [ 16.988038][ T250] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 16.988041][ T250] ? __asan_memcpy+0x3c/0x60 [ 16.988043][ T250] ? rcu_is_watching+0x16/0xd0 [ 16.988047][ T250] __kmalloc_noprof+0x443/0x760 [ 16.988050][ T250] ? __rwlock_init+0x150/0x150 [ 16.988053][ T250] ? alloc_buf.isra.0+0x4b/0x260 [ 16.988056][ T250] ? do_raw_spin_unlock+0x59/0x250 [ 16.988058][ T250] alloc_buf.isra.0+0x4b/0x260 [ 16.988060][ T250] put_chars+0x1e1/0x2f0 [ 16.988062][ T250] ? __send_to_port+0x420/0x420 [ 16.988064][ T250] ? console_prepend_replay+0x20/0x20 [ 16.988067][ T250] hvc_console_print+0x292/0x780 [ 16.988070][ T250] ? hvc_write+0x3a0/0x3a0 [ 16.988072][ T250] ? rcu_is_watching+0x16/0xd0 [ 16.988074][ T250] ? lock_acquire+0x13c/0x160 [ 16.988076][ T250] console_emit_next_record+0x22f/0x4c0 [ 16.988079][ T250] ? devkmsg_read+0x4b0/0x4b0 [ 16.988081][ T250] ? rcu_is_watching+0x16/0xd0 [ 16.988082][ T250] ? rcu_is_watching+0x16/0xd0 [ 16.988084][ T250] ? rcu_is_watching+0x16/0xd0 [ 16.988085][ T250] ? lock_acquire+0x13c/0x160 [ 16.988087][ T250] ? console_flush_one_record+0x111/0x710 [ 16.988089][ T250] console_flush_one_record+0x46f/0x710 [ 16.988091][ T250] ? console_emit_next_record+0x4c0/0x4c0 [ 16.988094][ T250] console_unlock+0xee/0x1f0 [ 16.988096][ T250] ? lock_acquire+0x13c/0x160 [ 16.988097][ T250] ? console_flush_one_record+0x710/0x710 [ 16.988099][ T250] ? rcu_is_watching+0x16/0xd0 [ 16.988100][ T250] ? lock_release+0x184/0x1f0 [ 16.988102][ T250] ? lock_acquire+0x60/0x160 [ 16.988103][ T250] ? __rcu_read_lock+0x30/0x70 [ 16.988105][ T250] ? __down_trylock_console_sem+0x5e/0xa0 [ 16.988107][ T250] ? vprintk_emit+0x320/0x3e0 [ 16.988109][ T250] vprintk_emit+0x37c/0x3e0 [ 16.988111][ T250] ? wake_up_klogd_work_func+0x90/0x90 [ 16.988113][ T250] ? do_raw_read_unlock+0x70/0x70 [ 16.988115][ T250] ? forget_original_parent+0x5c/0xdb0 [ 16.988118][ T250] _printk+0xc7/0x100 [ 16.988121][ T250] ? snapshot_read.cold+0x21/0x21 [ 16.988123][ T250] ? do_raw_spin_lock+0x131/0x280 [ 16.988124][ T250] ? do_raw_spin_lock+0x131/0x280 [ 16.988126][ T250] ? __rwlock_init+0x150/0x150 [ 16.988128][ T250] ? do_raw_spin_lock+0x131/0x280 [ 16.988130][ T250] do_exit.cold+0x82/0x9c [ 16.988133][ T250] ? exit_notify+0x890/0x890 [ 16.988134][ T250] ? posixtimer_sig_unignore.isra.0+0x5f0/0x5f0 [ 16.988136][ T250] ? rcu_is_watching+0x16/0xd0 [ 16.988138][ T250] do_group_exit+0xb8/0x370 [ 16.988140][ T250] __x64_sys_exit_group+0x3c/0x50 [ 16.988141][ T250] x64_sys_call+0x1567/0x1570 [ 16.988144][ T250] do_syscall_64+0xff/0x530 [ 16.988147][ T250] ? exc_page_fault+0xee/0x100 [ 16.988149][ T250] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 16.988151][ T250] RIP: 0033:0x7ff53e9e11b8 [ 16.988153][ T250] Code: Unable to access opcode bytes at 0x7ff53e9e118e. [ 16.988154][ T250] RSP: 002b:00007ffcf6d8c3c8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 16.988158][ T250] RAX: ffffffffffffffda RBX: 00007ff53eb11f88 RCX: 00007ff53e9e11b8 [ 16.988159][ T250] RDX: 00007ff53e7727c8 RSI: ffffffffffffffb0 RDI: 0000000000000001 [ 16.988160][ T250] RBP: 00007ffcf6d8c420 R08: 0000000000000000 R09: 0000000000001010 [ 16.988161][ T250] R10: 00007ffcf6d8c240 R11: 0000000000000246 R12: 0000000000000002 [ 16.988162][ T250] R13: 0000000000000001 R14: 00007ff53eb10680 R15: 00007ff53eb11fa0 [ 16.988164][ T250] [ 17.047216][ T212] ppp0: recursion detected