[ 9.056622][ T180] PPP generic driver version 2.4.2 [ 9.056637][ T180] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.056638][ T180] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 180, name: modprobe [ 9.056640][ T180] preempt_count: 1, expected: 0 [ 9.056641][ T180] RCU nest depth: 0, expected: 0 [ 9.056642][ T180] locks held by modprobe/180: 4, last CPU#0: [ 9.056644][ T180] #0: ffffffff92b79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.056656][ T180] #1: ffffffff92b79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.056660][ T180] #2: ffffffff92a99660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.056664][ T180] #3: ffffffff92a99560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.056668][ T180] irq event stamp: 39120 [ 9.056669][ T180] hardirqs last enabled at (39119): [] __down_trylock_console_sem+0x86/0xa0 [ 9.056672][ T180] hardirqs last disabled at (39120): [] console_emit_next_record+0x3d4/0x4c0 [ 9.056674][ T180] softirqs last enabled at (37478): [] handle_softirqs+0x67c/0x900 [ 9.056677][ T180] softirqs last disabled at (37471): [] __irq_exit_rcu+0x145/0x1c0 [ 9.056680][ T180] Preemption disabled at: [ 9.056680][ T180] [] vprintk_emit+0x31b/0x3e0 [ 9.056686][ T180] CPU: 0 UID: 0 PID: 180 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.056689][ T180] Tainted: [W]=WARN [ 9.056690][ T180] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.056691][ T180] Call Trace: [ 9.056693][ T180] [ 9.056694][ T180] dump_stack_lvl+0x6f/0xa0 [ 9.056700][ T180] ? vprintk_emit+0x31b/0x3e0 [ 9.056702][ T180] __might_resched.cold+0x1fe/0x2c1 [ 9.056707][ T180] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.056711][ T180] ? __kmalloc_noprof+0xdb/0x760 [ 9.056716][ T180] __kmalloc_noprof+0x443/0x760 [ 9.056719][ T180] ? alloc_buf.isra.0+0x4b/0x260 [ 9.056725][ T180] ? do_raw_spin_unlock+0x59/0x250 [ 9.056727][ T180] alloc_buf.isra.0+0x4b/0x260 [ 9.056731][ T180] put_chars+0x1e1/0x2f0 [ 9.056734][ T180] ? __send_to_port+0x420/0x420 [ 9.056736][ T180] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.056741][ T180] ? rcu_read_lock_any_held+0x3c/0x90 [ 9.056743][ T180] ? validate_chain+0x38b/0xc20 [ 9.056748][ T180] hvc_console_print+0x292/0x780 [ 9.056752][ T180] ? __lock_acquire+0x518/0xc20 [ 9.056757][ T180] ? hvc_write+0x3a0/0x3a0 [ 9.056760][ T180] ? rcu_is_watching+0x16/0xd0 [ 9.056766][ T180] console_emit_next_record+0x22f/0x4c0 [ 9.056770][ T180] ? devkmsg_read+0x4b0/0x4b0 [ 9.056772][ T180] ? console_flush_one_record+0x106/0x710 [ 9.056775][ T180] ? rcu_is_watching+0x16/0xd0 [ 9.056778][ T180] ? lock_acquire+0x13c/0x160 [ 9.056782][ T180] console_flush_one_record+0x46f/0x710 [ 9.056786][ T180] ? console_emit_next_record+0x4c0/0x4c0 [ 9.056788][ T180] ? __lock_acquire+0x518/0xc20 [ 9.056794][ T180] console_unlock+0xee/0x1f0 [ 9.056797][ T180] ? console_flush_one_record+0x710/0x710 [ 9.056799][ T180] ? rcu_is_watching+0x16/0xd0 [ 9.056801][ T180] ? lock_acquire+0x60/0x160 [ 9.056805][ T180] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.056807][ T180] ? vprintk_emit+0x320/0x3e0 [ 9.056811][ T180] vprintk_emit+0x37c/0x3e0 [ 9.056815][ T180] ? wake_up_klogd_work_func+0x90/0x90 [ 9.056821][ T180] ? ppp_cleanup+0x920/0x920 [ppp_generic] [ 9.056825][ T180] _printk+0xc7/0x100 [ 9.056829][ T180] ? snapshot_read.cold+0x21/0x21 [ 9.056836][ T180] ? do_one_initcall+0x113/0x4f0 [ 9.056840][ T180] ppp_init+0x16/0x1000 [ppp_generic] [ 9.056844][ T180] do_one_initcall+0x124/0x4f0 [ 9.056847][ T180] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 9.056852][ T180] ? do_init_module+0xae/0x8a0 [ 9.056855][ T180] ? kasan_unpoison+0x44/0x70 [ 9.056860][ T180] do_init_module+0x2ad/0x8a0 [ 9.056864][ T180] ? lockdep_hardirqs_on+0x91/0x130 [ 9.056868][ T180] ? free_module+0x470/0x470 [ 9.056872][ T180] ? kfree+0x123/0x590 [ 9.056876][ T180] load_module+0x1424/0x1bb0 [ 9.056883][ T180] ? layout_and_allocate.isra.0+0x680/0x680 [ 9.056889][ T180] ? backing_file_read_iter+0x541/0x790 [ 9.056892][ T180] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.056896][ T180] init_module_from_file+0x11f/0x1a0 [ 9.056900][ T180] ? __do_sys_init_module+0x220/0x220 [ 9.056909][ T180] ? __rwlock_init+0x150/0x150 [ 9.056912][ T180] ? do_raw_spin_unlock+0x59/0x250 [ 9.056916][ T180] idempotent_init_module+0x37b/0x7c0 [ 9.056919][ T180] ? init_module_from_file+0x1a0/0x1a0 [ 9.056926][ T180] ? rcu_is_watching+0x16/0xd0 [ 9.056929][ T180] ? cap_capable+0x1d7/0x3d0 [ 9.056936][ T180] __x64_sys_finit_module+0xdf/0x190 [ 9.056938][ T180] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 9.056942][ T180] do_syscall_64+0xff/0x530 [ 9.056944][ T180] ? exc_page_fault+0xee/0x100 [ 9.056947][ T180] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.056949][ T180] RIP: 0033:0x7fe67af7037d [ 9.056953][ 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 [ 9.056955][ T180] RSP: 002b:00007ffc1e47ca38 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 9.056957][ T180] RAX: ffffffffffffffda RBX: 000055ec1e6691d0 RCX: 00007fe67af7037d [ 9.056959][ T180] RDX: 0000000000000000 RSI: 000055ec16d0cffd RDI: 0000000000000006 [ 9.056960][ T180] RBP: 00007ffc1e47cad0 R08: 0000000000000000 R09: 0000000000000000 [ 9.056960][ T180] R10: 0000000000000000 R11: 0000000000000246 R12: 000055ec16d0cffd [ 9.056961][ T180] R13: 000055ec1e669180 R14: 0000000000040000 R15: 0000000000000000 [ 9.056968][ T180] [ 9.096937][ T180] NET: Registered PF_PPPOX protocol family [ 9.121754][ T180] l2tp_core: L2TP core driver, V2.0 [ 9.129068][ T180] l2tp_ppp: PPPoL2TP kernel driver, V2.0 [ 9.176922][ T181] ip (181) used greatest stack depth: 23952 bytes left [ 14.380093][ T262] iperf3 (262) used greatest stack depth: 22616 bytes left [ 14.380111][ T262] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 14.380114][ T262] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 262, name: iperf3 [ 14.380116][ T262] preempt_count: 2, expected: 0 [ 14.380116][ T262] RCU nest depth: 0, expected: 0 [ 14.380117][ T262] locks held by iperf3/262: 5, last CPU#0: [ 14.380120][ T262] #0: ffffffff92a127b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 14.380131][ T262] #1: ffffffff92b79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 14.380137][ T262] #2: ffffffff92b79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 14.380140][ T262] #3: ffffffff92a99660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 14.380144][ T262] #4: ffffffff92a99560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 14.380148][ T262] irq event stamp: 1685594 [ 14.380149][ T262] hardirqs last enabled at (1685593): [] __down_trylock_console_sem+0x86/0xa0 [ 14.380151][ T262] hardirqs last disabled at (1685594): [] console_emit_next_record+0x3d4/0x4c0 [ 14.380154][ T262] softirqs last enabled at (1684388): [] handle_softirqs+0x67c/0x900 [ 14.380156][ T262] softirqs last disabled at (1684381): [] __irq_exit_rcu+0x145/0x1c0 [ 14.380158][ T262] Preemption disabled at: [ 14.380159][ T262] [<0000000000000000>] 0x0 [ 14.380165][ T262] CPU: 0 UID: 0 PID: 262 Comm: iperf3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 14.380169][ T262] Tainted: [W]=WARN [ 14.380170][ T262] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.380171][ T262] Call Trace: [ 14.380173][ T262] [ 14.380174][ T262] dump_stack_lvl+0x6f/0xa0 [ 14.380180][ T262] __might_resched.cold+0x1fe/0x2c1 [ 14.380185][ T262] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 14.380188][ T262] ? __kmalloc_noprof+0xdb/0x760 [ 14.380193][ T262] __kmalloc_noprof+0x443/0x760 [ 14.380195][ T262] ? alloc_buf.isra.0+0x4b/0x260 [ 14.380201][ T262] ? do_raw_spin_unlock+0x59/0x250 [ 14.380204][ T262] alloc_buf.isra.0+0x4b/0x260 [ 14.380207][ T262] put_chars+0x1e1/0x2f0 [ 14.380209][ T262] ? prb_final_commit+0x50/0x50 [ 14.380211][ T262] ? __send_to_port+0x420/0x420 [ 14.380214][ T262] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.380219][ T262] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.380221][ T262] ? validate_chain+0x38b/0xc20 [ 14.380224][ T262] hvc_console_print+0x292/0x780 [ 14.380228][ T262] ? mark_usage+0x61/0x170 [ 14.380229][ T262] ? __lock_acquire+0x518/0xc20 [ 14.380231][ T262] ? __lock_acquire+0x518/0xc20 [ 14.380234][ T262] ? hvc_write+0x3a0/0x3a0 [ 14.380237][ T262] ? console_emit_next_record+0x1df/0x4c0 [ 14.380240][ T262] ? rcu_is_watching+0x16/0xd0 [ 14.380242][ T262] ? lock_acquire+0x13c/0x160 [ 14.380246][ T262] console_emit_next_record+0x22f/0x4c0 [ 14.380249][ T262] ? devkmsg_read+0x4b0/0x4b0 [ 14.380251][ T262] ? console_flush_one_record+0x106/0x710 [ 14.380254][ T262] ? rcu_is_watching+0x16/0xd0 [ 14.380256][ T262] ? lock_acquire+0x13c/0x160 [ 14.380260][ T262] console_flush_one_record+0x46f/0x710 [ 14.380264][ T262] ? console_emit_next_record+0x4c0/0x4c0 [ 14.380266][ T262] ? __lock_acquire+0x518/0xc20 [ 14.380271][ T262] console_unlock+0xee/0x1f0 [ 14.380273][ T262] ? console_flush_one_record+0x710/0x710 [ 14.380275][ T262] ? rcu_is_watching+0x16/0xd0 [ 14.380277][ T262] ? lock_acquire+0x60/0x160 [ 14.380281][ T262] ? __down_trylock_console_sem+0x5e/0xa0 [ 14.380282][ T262] ? vprintk_emit+0x320/0x3e0 [ 14.380286][ T262] vprintk_emit+0x37c/0x3e0 [ 14.380289][ T262] ? wake_up_klogd_work_func+0x90/0x90 [ 14.380293][ T262] ? __lock_acquire+0x518/0xc20 [ 14.380296][ T262] _printk+0xc7/0x100 [ 14.380299][ T262] ? snapshot_read.cold+0x21/0x21 [ 14.380302][ T262] ? do_raw_spin_lock+0x131/0x280 [ 14.380304][ T262] ? __rwlock_init+0x150/0x150 [ 14.380308][ T262] ? do_raw_spin_lock+0x131/0x280 [ 14.380311][ T262] do_exit.cold+0x82/0x9c [ 14.380314][ T262] ? exit_notify+0x890/0x890 [ 14.380319][ T262] __x64_sys_exit+0x42/0x50 [ 14.380321][ T262] x64_sys_call+0x156c/0x1570 [ 14.380323][ T262] do_syscall_64+0xff/0x530 [ 14.380327][ T262] ? exc_page_fault+0xee/0x100 [ 14.380330][ T262] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.380332][ T262] RIP: 0033:0x7fab5a3a7c49 [ 14.380334][ T262] Code: Unable to access opcode bytes at 0x7fab5a3a7c1f. [ 14.380335][ T262] RSP: 002b:00007fab59b66e60 EFLAGS: 00000246 ORIG_RAX: 000000000000003c [ 14.380338][ T262] RAX: ffffffffffffffda RBX: 00007fab59b676c0 RCX: 00007fab5a3a7c49 [ 14.380339][ T262] RDX: 0000000000000000 RSI: 0000000000801000 RDI: 0000000000000000 [ 14.380340][ T262] RBP: 00007fab59b66f70 R08: 0000000000000001 R09: 00007fab59b676c0 [ 14.380341][ T262] R10: 0000000000000008 R11: 0000000000000246 R12: 00007fab59367000 [ 14.380341][ T262] R13: 00007ffdb1aec4f0 R14: 0000000000000002 R15: 00007ffdb1aec5f7 [ 14.380348][ T262] [ 14.518009][ T266] ppp0: recursion detected [ 14.518043][ T266] [ 14.518044][ T266] ===================================================== [ 14.518045][ T266] WARNING: SOFTIRQ-safe -> SOFTIRQ-unsafe lock order detected [ 14.518048][ T266] 7.2.0-virtme #1 Tainted: G W [ 14.518049][ T266] ----------------------------------------------------- [ 14.518050][ T266] ping/266 [HC0[0]:SC0[10]:HE0:SE0] is trying to acquire: [ 14.518051][ T266] ffffffff92a99660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 14.518063][ T266] [ 14.518063][ T266] and this task is already holding: [ 14.518063][ T266] ff1100000590d0d8 (slock-AF_INET/2){+.-.}-{3:3}, at: l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.518071][ T266] which would create a new lock dependency: [ 14.518072][ T266] (slock-AF_INET/2){+.-.}-{3:3} -> (console_owner){....}-{0:0} [ 14.518074][ T266] [ 14.518074][ T266] but this new dependency connects a SOFTIRQ-irq-safe lock: [ 14.518075][ T266] (slock-AF_INET/2){+.-.}-{3:3} [ 14.518076][ T266] [ 14.518076][ T266] ... which became SOFTIRQ-irq-safe at: [ 14.518078][ T266] __lock_acquire+0x388/0xc20 [ 14.518081][ T266] lock_acquire.part.0+0xd4/0x280 [ 14.518082][ T266] _raw_spin_lock_nested+0x3d/0x50 [ 14.518085][ T266] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.518089][ T266] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.518091][ T266] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.518093][ T266] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.518096][ T266] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.518097][ T266] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.518099][ T266] dev_hard_start_xmit+0xf4/0x620 [ 14.518102][ T266] __dev_queue_xmit+0xc0a/0x1b80 [ 14.518104][ T266] ip_finish_output2+0x5b9/0x1bc0 [ 14.518107][ T266] ip_output+0x1ca/0x660 [ 14.518108][ T266] ip_push_pending_frames+0xf6/0x250 [ 14.518110][ T266] icmp_reply+0x94d/0xa50 [ 14.518113][ T266] icmp_echo+0x2ab/0x4b0 [ 14.518114][ T266] icmp_rcv+0x92f/0xfb0 [ 14.518116][ T266] ip_protocol_deliver_rcu+0x82/0x350 [ 14.518117][ T266] ip_local_deliver_finish+0x36f/0x610 [ 14.518119][ T266] ip_local_deliver+0x184/0x4c0 [ 14.518120][ T266] ip_rcv+0xdc/0x3d0 [ 14.518121][ T266] __netif_receive_skb_one_core+0xfc/0x180 [ 14.518123][ T266] process_backlog+0x431/0x14c0 [ 14.518125][ T266] __napi_poll+0xa7/0x3b0 [ 14.518127][ T266] net_rx_action+0x513/0xf50 [ 14.518128][ T266] handle_softirqs+0x1d3/0x900 [ 14.518131][ T266] do_softirq+0xac/0xe0 [ 14.518132][ T266] __local_bh_enable_ip+0x118/0x150 [ 14.518133][ T266] __dev_queue_xmit+0x979/0x1b80 [ 14.518135][ T266] ip_finish_output2+0x5b9/0x1bc0 [ 14.518136][ T266] ip_output+0x1ca/0x660 [ 14.518138][ T266] ip_push_pending_frames+0xf6/0x250 [ 14.518139][ T266] raw_sendmsg+0x1169/0x1cc0 [ 14.518141][ T266] __sys_sendto+0x2aa/0x400 [ 14.518144][ T266] __x64_sys_sendto+0xe4/0x1f0 [ 14.518145][ T266] do_syscall_64+0xff/0x530 [ 14.518148][ T266] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.518150][ T266] [ 14.518150][ T266] to a SOFTIRQ-irq-unsafe lock: [ 14.518151][ T266] (fs_reclaim){+.+.}-{0:0} [ 14.518152][ T266] [ 14.518152][ T266] ... which became SOFTIRQ-irq-unsafe at: [ 14.518152][ T266] ... [ 14.518153][ T266] __lock_acquire+0x388/0xc20 [ 14.518155][ T266] lock_acquire.part.0+0xd4/0x280 [ 14.518156][ T266] fs_reclaim_acquire+0xd5/0x120 [ 14.518159][ T266] __kmalloc_cache_noprof+0x6e/0x620 [ 14.518161][ T266] kthread_create_worker_on_node+0xea/0x210 [ 14.518163][ T266] workqueue_init+0x2a/0x680 [ 14.518167][ T266] kernel_init_freeable+0x2fe/0x630 [ 14.518169][ T266] kernel_init+0x21/0x150 [ 14.518171][ T266] ret_from_fork+0x474/0x6b0 [ 14.518174][ T266] ret_from_fork_asm+0x11/0x20 [ 14.518176][ T266] [ 14.518176][ T266] other info that might help us debug this: [ 14.518176][ T266] [ 14.518177][ T266] Chain exists of: [ 14.518177][ T266] slock-AF_INET/2 --> console_owner --> fs_reclaim [ 14.518177][ T266] [ 14.518179][ T266] Possible interrupt unsafe locking scenario: [ 14.518179][ T266] [ 14.518180][ T266] CPU0 CPU1 [ 14.518180][ T266] ---- ---- [ 14.518181][ T266] lock(fs_reclaim); [ 14.518181][ T266] local_irq_disable(); [ 14.518182][ T266] lock(slock-AF_INET/2); [ 14.518183][ T266] lock(console_owner); [ 14.518184][ T266] [ 14.518184][ T266] lock(slock-AF_INET/2); [ 14.518185][ T266] [ 14.518185][ T266] *** DEADLOCK *** [ 14.518185][ T266] [ 14.518185][ T266] locks held by ping/266: 14, last CPU#3: [ 14.518186][ T266] #0: ff11000005906290 (sk_lock-AF_INET){+.+.}-{0:0}, at: raw_sendmsg+0xba5/0x1cc0 [ 14.518190][ T266] #1: ffffffff92b90b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 14.518192][ T266] #2: ffffffff92b90b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 14.518195][ T266] #3: ffffffff92b90aa0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x1fc/0x1b80 [ 14.518198][ T266] #4: ffd1ffffffd83288 (&xmit_recursion->bh_lock){+.-.}-{3:3}, at: ppp_xmit_process+0x1d2/0x8e0 [ppp_generic] [ 14.518202][ T266] #5: ff1100000d532fd0 (&ppp->wlock){+.-.}-{3:3}, at: ppp_xmit_process+0x292/0x8e0 [ppp_generic] [ 14.518205][ T266] #6: ff1100001002c1d0 (&pch->downl){+.-.}-{3:3}, at: ppp_push+0xae/0x2d0 [ppp_generic] [ 14.518208][ T266] #7: ff1100000590d0d8 (slock-AF_INET/2){+.-.}-{3:3}, at: l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.518212][ T266] #8: ffffffff92b90b00 (rcu_read_lock){....}-{1:3}, at: __ip_queue_xmit+0x63/0x1650 [ 14.518215][ T266] #9: ffffffff92b90b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 14.518218][ T266] #10: ffffffff92b90b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 14.518221][ T266] #11: ffffffff92b90aa0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x1fc/0x1b80 [ 14.518224][ T266] #12: ffffffff92b79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 14.518227][ T266] #13: ffffffff92b79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 14.518230][ T266] [ 14.518230][ T266] the dependencies between SOFTIRQ-irq-safe lock and the holding lock: [ 14.518231][ T266] -> (slock-AF_INET/2){+.-.}-{3:3} { [ 14.518233][ T266] HARDIRQ-ON-W at: [ 14.518234][ T266] __lock_acquire+0x388/0xc20 [ 14.518236][ T266] lock_acquire.part.0+0xd4/0x280 [ 14.518237][ T266] _raw_spin_lock_nested+0x3d/0x50 [ 14.518238][ T266] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.518241][ T266] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.518244][ T266] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.518245][ T266] __ppp_channel_push+0xed/0x280 [ppp_generic] [ 14.518247][ T266] ppp_channel_push+0x269/0x7b0 [ppp_generic] [ 14.518249][ T266] ppp_write+0x26b/0x350 [ppp_generic] [ 14.518250][ T266] vfs_write+0x1a4/0xbd0 [ 14.518253][ T266] ksys_write+0x116/0x250 [ 14.518254][ T266] do_syscall_64+0xff/0x530 [ 14.518256][ T266] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.518257][ T266] IN-SOFTIRQ-W at: [ 14.518258][ T266] __lock_acquire+0x388/0xc20 [ 14.518259][ T266] lock_acquire.part.0+0xd4/0x280 [ 14.518260][ T266] _raw_spin_lock_nested+0x3d/0x50 [ 14.518261][ T266] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.518264][ T266] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.518267][ T266] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.518268][ T266] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.518269][ T266] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.518271][ T266] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.518273][ T266] dev_hard_start_xmit+0xf4/0x620 [ 14.518274][ T266] __dev_queue_xmit+0xc0a/0x1b80 [ 14.518276][ T266] ip_finish_output2+0x5b9/0x1bc0 [ 14.518277][ T266] ip_output+0x1ca/0x660 [ 14.518279][ T266] ip_push_pending_frames+0xf6/0x250 [ 14.518280][ T266] icmp_reply+0x94d/0xa50 [ 14.518281][ T266] icmp_echo+0x2ab/0x4b0 [ 14.518283][ T266] icmp_rcv+0x92f/0xfb0 [ 14.518284][ T266] ip_protocol_deliver_rcu+0x82/0x350 [ 14.518286][ T266] ip_local_deliver_finish+0x36f/0x610 [ 14.518287][ T266] ip_local_deliver+0x184/0x4c0 [ 14.518288][ T266] ip_rcv+0xdc/0x3d0 [ 14.518289][ T266] __netif_receive_skb_one_core+0xfc/0x180 [ 14.518291][ T266] process_backlog+0x431/0x14c0 [ 14.518292][ T266] __napi_poll+0xa7/0x3b0 [ 14.518294][ T266] net_rx_action+0x513/0xf50 [ 14.518295][ T266] handle_softirqs+0x1d3/0x900 [ 14.518297][ T266] do_softirq+0xac/0xe0 [ 14.518298][ T266] __local_bh_enable_ip+0x118/0x150 [ 14.518299][ T266] __dev_queue_xmit+0x979/0x1b80 [ 14.518300][ T266] ip_finish_output2+0x5b9/0x1bc0 [ 14.518302][ T266] ip_output+0x1ca/0x660 [ 14.518303][ T266] ip_push_pending_frames+0xf6/0x250 [ 14.518304][ T266] raw_sendmsg+0x1169/0x1cc0 [ 14.518305][ T266] __sys_sendto+0x2aa/0x400 [ 14.518307][ T266] __x64_sys_sendto+0xe4/0x1f0 [ 14.518308][ T266] do_syscall_64+0xff/0x530 [ 14.518310][ T266] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.518311][ T266] INITIAL USE at: [ 14.518312][ T266] __lock_acquire+0x388/0xc20 [ 14.518313][ T266] lock_acquire.part.0+0xd4/0x280 [ 14.518314][ T266] _raw_spin_lock_nested+0x3d/0x50 [ 14.518315][ T266] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 14.518318][ T266] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.518320][ T266] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.518321][ T266] __ppp_channel_push+0xed/0x280 [ppp_generic] [ 14.518323][ T266] ppp_channel_push+0x269/0x7b0 [ppp_generic] [ 14.518325][ T266] ppp_write+0x26b/0x350 [ppp_generic] [ 14.518326][ T266] vfs_write+0x1a4/0xbd0 [ 14.518328][ T266] ksys_write+0x116/0x250 [ 14.518329][ T266] do_syscall_64+0xff/0x530 [ 14.518330][ T266] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.518331][ T266] } [ 14.518332][ T266] ... key at: [] af_family_slock_keys+0x22/0x300 [ 14.518335][ T266] [ 14.518335][ T266] the dependencies between the lock to be acquired [ 14.518336][ T266] and SOFTIRQ-irq-unsafe lock: [ 14.518340][ T266] -> (fs_reclaim){+.+.}-{0:0} { [ 14.518342][ T266] HARDIRQ-ON-W at: [ 14.518343][ T266] __lock_acquire+0x388/0xc20 [ 14.518344][ T266] lock_acquire.part.0+0xd4/0x280 [ 14.518346][ T266] fs_reclaim_acquire+0xd5/0x120 [ 14.518347][ T266] __kmalloc_cache_noprof+0x6e/0x620 [ 14.518348][ T266] kthread_create_worker_on_node+0xea/0x210 [ 14.518349][ T266] workqueue_init+0x2a/0x680 [ 14.518350][ T266] kernel_init_freeable+0x2fe/0x630 [ 14.518352][ T266] kernel_init+0x21/0x150 [ 14.518353][ T266] ret_from_fork+0x474/0x6b0 [ 14.518354][ T266] ret_from_fork_asm+0x11/0x20 [ 14.518355][ T266] SOFTIRQ-ON-W at: [ 14.518356][ T266] __lock_acquire+0x388/0xc20 [ 14.518357][ T266] lock_acquire.part.0+0xd4/0x280 [ 14.518358][ T266] fs_reclaim_acquire+0xd5/0x120 [ 14.518359][ T266] __kmalloc_cache_noprof+0x6e/0x620 [ 14.518361][ T266] kthread_create_worker_on_node+0xea/0x210 [ 14.518362][ T266] workqueue_init+0x2a/0x680 [ 14.518363][ T266] kernel_init_freeable+0x2fe/0x630 [ 14.518365][ T266] kernel_init+0x21/0x150 [ 14.518366][ T266] ret_from_fork+0x474/0x6b0 [ 14.518367][ T266] ret_from_fork_asm+0x11/0x20 [ 14.518368][ T266] INITIAL USE at: [ 14.518369][ T266] __lock_acquire+0x388/0xc20 [ 14.518370][ T266] lock_acquire.part.0+0xd4/0x280 [ 14.518371][ T266] fs_reclaim_acquire+0xd5/0x120 [ 14.518372][ T266] __kmalloc_cache_noprof+0x6e/0x620 [ 14.518374][ T266] kthread_create_worker_on_node+0xea/0x210 [ 14.518375][ T266] workqueue_init+0x2a/0x680 [ 14.518376][ T266] kernel_init_freeable+0x2fe/0x630 [ 14.518377][ T266] kernel_init+0x21/0x150 [ 14.518379][ T266] ret_from_fork+0x474/0x6b0 [ 14.518380][ T266] ret_from_fork_asm+0x11/0x20 [ 14.518381][ T266] } [ 14.518381][ T266] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 14.518384][ T266] ... acquired at: [ 14.518385][ T266] __lock_acquire+0x518/0xc20 [ 14.518386][ T266] lock_acquire.part.0+0xd4/0x280 [ 14.518388][ T266] fs_reclaim_acquire+0xd5/0x120 [ 14.518389][ T266] __kmalloc_noprof+0xd3/0x760 [ 14.518390][ T266] alloc_buf.isra.0+0x4b/0x260 [ 14.518393][ T266] put_chars+0x1e1/0x2f0 [ 14.518394][ T266] hvc_console_print+0x292/0x780 [ 14.518396][ T266] console_emit_next_record+0x22f/0x4c0 [ 14.518398][ T266] console_flush_one_record+0x46f/0x710 [ 14.518400][ T266] console_unlock+0xee/0x1f0 [ 14.518401][ T266] vprintk_emit+0x37c/0x3e0 [ 14.518402][ T266] dev_vprintk_emit+0x27f/0x2c0 [ 14.518406][ T266] dev_printk_emit+0xb9/0xee [ 14.518407][ T266] _dev_info+0xe2/0x116 [ 14.518409][ T266] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 14.518411][ T266] cmos_do_probe+0x73b/0x98a [ 14.518412][ T266] platform_probe+0xfe/0x1f0 [ 14.518415][ T266] call_driver_probe+0x61/0x1c0 [ 14.518417][ T266] really_probe+0x199/0x760 [ 14.518418][ T266] __driver_probe_device+0x24f/0x440 [ 14.518420][ T266] driver_probe_device+0x4a/0xf0 [ 14.518421][ T266] __driver_attach+0x1b8/0x540 [ 14.518423][ T266] bus_for_each_dev+0x130/0x1e0 [ 14.518424][ T266] bus_add_driver+0x2c8/0x530 [ 14.518425][ T266] driver_register+0x1a3/0x390 [ 14.518427][ T266] __platform_driver_probe+0x13f/0x270 [ 14.518428][ T266] cmos_init+0x31/0x40 [ 14.518431][ T266] do_one_initcall+0x124/0x4f0 [ 14.518433][ T266] kernel_init_freeable+0x596/0x630 [ 14.518434][ T266] kernel_init+0x21/0x150 [ 14.518436][ T266] ret_from_fork+0x474/0x6b0 [ 14.518437][ T266] ret_from_fork_asm+0x11/0x20 [ 14.518438][ T266] [ 14.518438][ T266] -> (console_owner){....}-{0:0} { [ 14.518440][ T266] INITIAL USE at: [ 14.518440][ T266] } [ 14.518441][ T266] ... key at: [] console_owner_dep_map+0x0/0x60 [ 14.518444][ T266] ... acquired at: [ 14.518445][ T266] check_prev_add+0xef/0xe90 [ 14.518446][ T266] validate_chain+0x6b8/0xc20 [ 14.518447][ T266] __lock_acquire+0x518/0xc20 [ 14.518448][ T266] lock_acquire.part.0+0xd4/0x280 [ 14.518449][ T266] console_lock_spinning_enable+0x5c/0x60 [ 14.518451][ T266] console_emit_next_record+0x1d1/0x4c0 [ 14.518452][ T266] console_flush_one_record+0x46f/0x710 [ 14.518454][ T266] console_unlock+0xee/0x1f0 [ 14.518455][ T266] vprintk_emit+0x37c/0x3e0 [ 14.518457][ T266] _printk+0xc7/0x100 [ 14.518459][ T266] netdev_err+0xe2/0x120 [ 14.518461][ T266] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 14.518463][ T266] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.518465][ T266] dev_hard_start_xmit+0xf4/0x620 [ 14.518467][ T266] __dev_queue_xmit+0xc0a/0x1b80 [ 14.518468][ T266] ip_finish_output2+0x5b9/0x1bc0 [ 14.518469][ T266] ip_output+0x1ca/0x660 [ 14.518471][ T266] __ip_queue_xmit+0x774/0x1650 [ 14.518472][ T266] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 14.518475][ T266] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.518477][ T266] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.518479][ T266] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.518480][ T266] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.518482][ T266] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.518483][ T266] dev_hard_start_xmit+0xf4/0x620 [ 14.518485][ T266] __dev_queue_xmit+0xc0a/0x1b80 [ 14.518486][ T266] ip_finish_output2+0x5b9/0x1bc0 [ 14.518488][ T266] ip_output+0x1ca/0x660 [ 14.518489][ T266] ip_push_pending_frames+0xf6/0x250 [ 14.518490][ T266] raw_sendmsg+0x1169/0x1cc0 [ 14.518491][ T266] __sys_sendto+0x2aa/0x400 [ 14.518493][ T266] __x64_sys_sendto+0xe4/0x1f0 [ 14.518494][ T266] do_syscall_64+0xff/0x530 [ 14.518496][ T266] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.518497][ T266] [ 14.518497][ T266] [ 14.518497][ T266] stack backtrace: [ 14.518500][ T266] CPU: 3 UID: 0 PID: 266 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 14.518503][ T266] Tainted: [W]=WARN [ 14.518504][ T266] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.518505][ T266] Call Trace: [ 14.518507][ T266] [ 14.518508][ T266] dump_stack_lvl+0x6f/0xa0 [ 14.518512][ T266] print_bad_irq_dependency+0x3e4/0x410 [ 14.518514][ T266] check_irq_usage+0x403/0x570 [ 14.518516][ T266] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.518520][ T266] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.518522][ T266] ? check_path.constprop.0+0x24/0x50 [ 14.518525][ T266] ? check_prev_add+0xef/0xe90 [ 14.518526][ T266] check_prev_add+0xef/0xe90 [ 14.518528][ T266] validate_chain+0x6b8/0xc20 [ 14.518530][ T266] __lock_acquire+0x518/0xc20 [ 14.518532][ T266] lock_acquire.part.0+0xd4/0x280 [ 14.518534][ T266] ? console_lock_spinning_enable+0x40/0x60 [ 14.518536][ T266] ? rcu_is_watching+0x16/0xd0 [ 14.518538][ T266] ? lock_acquire+0x13c/0x160 [ 14.518540][ T266] console_lock_spinning_enable+0x5c/0x60 [ 14.518541][ T266] ? console_lock_spinning_enable+0x40/0x60 [ 14.518543][ T266] console_emit_next_record+0x1d1/0x4c0 [ 14.518545][ T266] ? devkmsg_read+0x4b0/0x4b0 [ 14.518546][ T266] ? console_flush_one_record+0x106/0x710 [ 14.518554][ T266] ? rcu_is_watching+0x16/0xd0 [ 14.518556][ T266] ? lock_acquire+0x13c/0x160 [ 14.518558][ T266] console_flush_one_record+0x46f/0x710 [ 14.518560][ T266] ? console_emit_next_record+0x4c0/0x4c0 [ 14.518562][ T266] ? __lock_acquire+0x518/0xc20 [ 14.518564][ T266] console_unlock+0xee/0x1f0 [ 14.518566][ T266] ? console_flush_one_record+0x710/0x710 [ 14.518567][ T266] ? rcu_is_watching+0x16/0xd0 [ 14.518569][ T266] ? lock_acquire+0x60/0x160 [ 14.518571][ T266] ? __down_trylock_console_sem+0x5e/0xa0 [ 14.518572][ T266] ? vprintk_emit+0x320/0x3e0 [ 14.518574][ T266] vprintk_emit+0x37c/0x3e0 [ 14.518576][ T266] ? wake_up_klogd_work_func+0x90/0x90 [ 14.518578][ T266] ? mark_lock+0x3bb/0xa00 [ 14.518580][ T266] _printk+0xc7/0x100 [ 14.518582][ T266] ? snapshot_read.cold+0x21/0x21 [ 14.518584][ T266] ? lock_acquire.part.0+0xd4/0x280 [ 14.518585][ T266] ? find_held_lock+0x2b/0x80 [ 14.518587][ T266] ? netdev_name+0x43/0x60 [ 14.518588][ T266] ? __netdev_printk+0x17f/0x1d0 [ 14.518590][ T266] netdev_err+0xe2/0x120 [ 14.518591][ T266] ? netdev_crit+0x120/0x120 [ 14.518592][ T266] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 14.518594][ T266] ? ___ratelimit+0x4e7/0xa00 [ 14.518597][ T266] ? idr_get_free+0x830/0x830 [ 14.518599][ T266] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 14.518601][ T266] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 14.518603][ T266] ? dst_release+0x54/0x240 [ 14.518606][ T266] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.518611][ T266] ? dev_nit_active_rcu+0x1f/0xf0 [ 14.518614][ T266] ? rcu_is_watching+0x16/0xd0 [ 14.518615][ T266] dev_hard_start_xmit+0xf4/0x620 [ 14.518618][ T266] ? rcu_is_watching+0x16/0xd0 [ 14.518620][ T266] __dev_queue_xmit+0xc0a/0x1b80 [ 14.518622][ T266] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 14.518623][ T266] ? lockdep_hardirqs_on+0x91/0x130 [ 14.518625][ T266] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 14.518626][ T266] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 14.518627][ T266] ? ref_tracker_dir_print+0x100/0x100 [ 14.518631][ T266] ? dst_init+0x84/0x530 [ 14.518632][ T266] ? dst_alloc+0x97/0x160 [ 14.518633][ T266] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 14.518635][ T266] ? ip_route_output_flow+0x21/0x150 [ 14.518636][ T266] ? __ip_queue_xmit+0xdc7/0x1650 [ 14.518638][ T266] ? __lock_acquire+0x518/0xc20 [ 14.518639][ T266] ? down_read_nested+0xe7/0x500 [ 14.518641][ T266] ? lock_acquire.part.0+0xd4/0x280 [ 14.518642][ T266] ? ip_finish_output2+0x25c/0x1bc0 [ 14.518644][ T266] ? rcu_is_watching+0x16/0xd0 [ 14.518647][ T266] ip_finish_output2+0x5b9/0x1bc0 [ 14.518648][ T266] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 14.518650][ T266] ? lock_acquire.part.0+0xd4/0x280 [ 14.518652][ T266] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 14.518654][ T266] ip_output+0x1ca/0x660 [ 14.518656][ T266] ? ip_finish_output+0x460/0x460 [ 14.518657][ T266] ? find_held_lock+0x2b/0x80 [ 14.518659][ T266] ? __lock_release.isra.0+0x69/0x1a0 [ 14.518661][ T266] ? ip4_dst_hoplimit+0x15b/0x320 [ 14.518663][ T266] __ip_queue_xmit+0x774/0x1650 [ 14.518665][ T266] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 14.518669][ T266] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 14.518673][ T266] ? l2tp_xmit_core.constprop.0+0x19b0/0x19b0 [l2tp_core] [ 14.518676][ T266] ? do_raw_spin_lock+0x131/0x280 [ 14.518678][ T266] ? pppol2tp_xmit+0x423/0x690 [l2tp_ppp] [ 14.518679][ T266] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 14.518681][ T266] ppp_push+0x165/0x2d0 [ppp_generic] [ 14.518683][ T266] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 14.518685][ T266] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 14.518687][ T266] ? dst_release+0x54/0x240 [ 14.518689][ T266] ? down_read_nested+0x36c/0x500 [ 14.518690][ T266] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 14.518692][ T266] ? dev_nit_active_rcu+0x1f/0xf0 [ 14.518694][ T266] ? rcu_is_watching+0x16/0xd0 [ 14.518696][ T266] dev_hard_start_xmit+0xf4/0x620 [ 14.518698][ T266] ? rcu_is_watching+0x16/0xd0 [ 14.518700][ T266] __dev_queue_xmit+0xc0a/0x1b80 [ 14.518702][ T266] ? sock_queue_err_skb+0x680/0x680 [ 14.518704][ T266] ? kmem_cache_alloc_node_noprof+0x2a6/0x660 [ 14.518706][ T266] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.518708][ T266] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.518709][ T266] ? validate_chain+0x38b/0xc20 [ 14.518711][ T266] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 14.518713][ T266] ? __lock_acquire+0x518/0xc20 [ 14.518714][ T266] ? down_read_nested+0xe7/0x500 [ 14.518716][ T266] ? lock_acquire.part.0+0xd4/0x280 [ 14.518717][ T266] ? ip_finish_output2+0x25c/0x1bc0 [ 14.518719][ T266] ? rcu_is_watching+0x16/0xd0 [ 14.518721][ T266] ip_finish_output2+0x5b9/0x1bc0 [ 14.518723][ T266] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 14.518724][ T266] ? lock_acquire.part.0+0xd4/0x280 [ 14.518726][ T266] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 14.518728][ T266] ? rcu_is_watching+0x16/0xd0 [ 14.518730][ T266] ip_output+0x1ca/0x660 [ 14.518732][ T266] ? ip_finish_output+0x460/0x460 [ 14.518733][ T266] ? __ip_make_skb+0x106a/0x2300 [ 14.518736][ T266] ip_push_pending_frames+0xf6/0x250 [ 14.518738][ T266] raw_sendmsg+0x1169/0x1cc0 [ 14.518740][ T266] ? raw_send_hdrinc+0x1740/0x1740 [ 14.518741][ T266] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.518745][ T266] ? __might_fault+0x97/0x140 [ 14.518749][ T266] ? __might_fault+0x97/0x140 [ 14.518750][ T266] ? __might_fault+0x97/0x140 [ 14.518752][ T266] __sys_sendto+0x2aa/0x400 [ 14.518754][ T266] ? __ia32_sys_getpeername+0xd0/0xd0 [ 14.518758][ T266] ? exc_page_fault+0x87/0x100 [ 14.518759][ T266] __x64_sys_sendto+0xe4/0x1f0 [ 14.518761][ T266] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 14.518764][ T266] ? lockdep_hardirqs_on+0x91/0x130 [ 14.518765][ T266] ? do_syscall_64+0xa6/0x530 [ 14.518766][ T266] do_syscall_64+0xff/0x530 [ 14.518768][ T266] ? exc_page_fault+0xee/0x100 [ 14.518769][ T266] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.518771][ T266] RIP: 0033:0x7f48fb85154e [ 14.518773][ T266] 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.518775][ T266] RSP: 002b:00007ffe25920a00 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 14.518778][ T266] RAX: ffffffffffffffda RBX: 0000000000000040 RCX: 00007f48fb85154e [ 14.518779][ T266] RDX: 0000000000000040 RSI: 000055c686b1b0a4 RDI: 0000000000000005 [ 14.518780][ T266] RBP: 00007ffe25920a10 R08: 000055c686b2d320 R09: 0000000000000010 [ 14.518781][ T266] R10: 0000000000000000 R11: 0000000000000202 R12: 431bde82d7b634db [ 14.518781][ T266] R13: 0000000000000000 R14: 000055c686b2e3e0 R15: 000055c686b1b0a0 [ 14.518784][ T266] [ 15.525361][ T266] ping (266) used greatest stack depth: 22536 bytes left [ 15.525376][ T266] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 15.525379][ T266] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 266, name: ping [ 15.525380][ T266] preempt_count: 2, expected: 0 [ 15.525381][ T266] RCU nest depth: 0, expected: 0 [ 15.525382][ T266] INFO: lockdep is turned off. [ 15.525383][ T266] irq event stamp: 24793 [ 15.525384][ T266] hardirqs last enabled at (24792): [] __down_trylock_console_sem+0x86/0xa0 [ 15.525392][ T266] hardirqs last disabled at (24793): [] console_emit_next_record+0x3d4/0x4c0 [ 15.525395][ T266] softirqs last enabled at (24696): [] __alloc_skb+0x4c2/0x5f0 [ 15.525398][ T266] softirqs last disabled at (24706): [] __dev_queue_xmit+0x1fc/0x1b80 [ 15.525402][ T266] Preemption disabled at: [ 15.525402][ T266] [<0000000000000000>] 0x0 [ 15.525409][ T266] CPU: 3 UID: 0 PID: 266 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.525413][ T266] Tainted: [W]=WARN [ 15.525414][ T266] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.525416][ T266] Call Trace: [ 15.525417][ T266] [ 15.525418][ T266] dump_stack_lvl+0x6f/0xa0 [ 15.525424][ T266] __might_resched.cold+0x1fe/0x2c1 [ 15.525427][ T266] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 15.525430][ T266] ? __asan_memcpy+0x3c/0x60 [ 15.525433][ T266] ? rcu_is_watching+0x16/0xd0 [ 15.525437][ T266] __kmalloc_noprof+0x443/0x760 [ 15.525440][ T266] ? __rwlock_init+0x150/0x150 [ 15.525442][ T266] ? alloc_buf.isra.0+0x4b/0x260 [ 15.525446][ T266] ? do_raw_spin_unlock+0x59/0x250 [ 15.525447][ T266] alloc_buf.isra.0+0x4b/0x260 [ 15.525449][ T266] put_chars+0x1e1/0x2f0 [ 15.525452][ T266] ? __send_to_port+0x420/0x420 [ 15.525453][ T266] ? console_prepend_replay+0x20/0x20 [ 15.525456][ T266] hvc_console_print+0x292/0x780 [ 15.525459][ T266] ? hvc_write+0x3a0/0x3a0 [ 15.525461][ T266] ? rcu_is_watching+0x16/0xd0 [ 15.525463][ T266] ? lock_acquire+0x13c/0x160 [ 15.525465][ T266] console_emit_next_record+0x22f/0x4c0 [ 15.525468][ T266] ? devkmsg_read+0x4b0/0x4b0 [ 15.525470][ T266] ? rcu_is_watching+0x16/0xd0 [ 15.525471][ T266] ? rcu_is_watching+0x16/0xd0 [ 15.525473][ T266] ? rcu_is_watching+0x16/0xd0 [ 15.525474][ T266] ? lock_acquire+0x13c/0x160 [ 15.525476][ T266] ? console_flush_one_record+0x111/0x710 [ 15.525478][ T266] console_flush_one_record+0x46f/0x710 [ 15.525480][ T266] ? console_emit_next_record+0x4c0/0x4c0 [ 15.525483][ T266] console_unlock+0xee/0x1f0 [ 15.525484][ T266] ? lock_acquire+0x13c/0x160 [ 15.525486][ T266] ? console_flush_one_record+0x710/0x710 [ 15.525487][ T266] ? rcu_is_watching+0x16/0xd0 [ 15.525489][ T266] ? lock_release+0x184/0x1f0 [ 15.525490][ T266] ? lock_acquire+0x60/0x160 [ 15.525492][ T266] ? __rcu_read_lock+0x30/0x70 [ 15.525494][ T266] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.525495][ T266] ? vprintk_emit+0x320/0x3e0 [ 15.525497][ T266] vprintk_emit+0x37c/0x3e0 [ 15.525499][ T266] ? wake_up_klogd_work_func+0x90/0x90 [ 15.525501][ T266] ? do_raw_read_unlock+0x70/0x70 [ 15.525503][ T266] ? forget_original_parent+0x5c/0xdb0 [ 15.525507][ T266] _printk+0xc7/0x100 [ 15.525510][ T266] ? snapshot_read.cold+0x21/0x21 [ 15.525511][ T266] ? do_raw_spin_lock+0x131/0x280 [ 15.525513][ T266] ? do_raw_spin_lock+0x131/0x280 [ 15.525514][ T266] ? __rwlock_init+0x150/0x150 [ 15.525516][ T266] ? do_raw_spin_lock+0x131/0x280 [ 15.525518][ T266] do_exit.cold+0x82/0x9c [ 15.525521][ T266] ? exit_notify+0x890/0x890 [ 15.525522][ T266] ? posixtimer_sig_unignore.isra.0+0x5f0/0x5f0 [ 15.525524][ T266] ? rcu_is_watching+0x16/0xd0 [ 15.525526][ T266] do_group_exit+0xb8/0x370 [ 15.525528][ T266] __x64_sys_exit_group+0x3c/0x50 [ 15.525529][ T266] x64_sys_call+0x1567/0x1570 [ 15.525531][ T266] do_syscall_64+0xff/0x530 [ 15.525535][ T266] ? exc_page_fault+0xee/0x100 [ 15.525537][ T266] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.525539][ T266] RIP: 0033:0x7f48fb8a21b8 [ 15.525540][ T266] Code: Unable to access opcode bytes at 0x7f48fb8a218e. [ 15.525542][ T266] RSP: 002b:00007ffe25922198 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 15.525545][ T266] RAX: ffffffffffffffda RBX: 00007f48fb9d2f88 RCX: 00007f48fb8a21b8 [ 15.525546][ T266] RDX: 00007f48fb6337c8 RSI: ffffffffffffffb0 RDI: 0000000000000001 [ 15.525547][ T266] RBP: 00007ffe259221f0 R08: 0000000000000000 R09: 0000000000001010 [ 15.525548][ T266] R10: 00007ffe25922010 R11: 0000000000000246 R12: 0000000000000002 [ 15.525549][ T266] R13: 0000000000000001 R14: 00007f48fb9d1680 R15: 00007f48fb9d2fa0 [ 15.525551][ T266] [ 15.571979][ T219] ppp0: recursion detected