[ 10.136197][ T179] PPP generic driver version 2.4.2 [ 10.136214][ T179] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.136217][ T179] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 179, name: modprobe [ 10.136218][ T179] preempt_count: 1, expected: 0 [ 10.136219][ T179] RCU nest depth: 0, expected: 0 [ 10.136220][ T179] locks held by modprobe/179: 4, last CPU#1: [ 10.136222][ T179] #0: ffffffffba979cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.136235][ T179] #1: ffffffffba979d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.136239][ T179] #2: ffffffffba899660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.136243][ T179] #3: ffffffffba899560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.136247][ T179] irq event stamp: 38388 [ 10.136248][ T179] hardirqs last enabled at (38387): [] __down_trylock_console_sem+0x86/0xa0 [ 10.136251][ T179] hardirqs last disabled at (38388): [] console_emit_next_record+0x3d4/0x4c0 [ 10.136253][ T179] softirqs last enabled at (35758): [] handle_softirqs+0x67c/0x900 [ 10.136256][ T179] softirqs last disabled at (35217): [] __irq_exit_rcu+0x145/0x1c0 [ 10.136258][ T179] Preemption disabled at: [ 10.136259][ T179] [] vprintk_emit+0x31b/0x3e0 [ 10.136265][ T179] CPU: 1 UID: 0 PID: 179 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.136268][ T179] Tainted: [W]=WARN [ 10.136269][ T179] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.136271][ T179] Call Trace: [ 10.136272][ T179] [ 10.136274][ T179] dump_stack_lvl+0x6f/0xa0 [ 10.136280][ T179] ? vprintk_emit+0x31b/0x3e0 [ 10.136282][ T179] __might_resched.cold+0x1fe/0x2c1 [ 10.136286][ T179] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.136291][ T179] ? __kmalloc_noprof+0xdb/0x760 [ 10.136296][ T179] __kmalloc_noprof+0x443/0x760 [ 10.136298][ T179] ? alloc_buf.isra.0+0x4b/0x260 [ 10.136304][ T179] ? do_raw_spin_unlock+0x59/0x250 [ 10.136307][ T179] alloc_buf.isra.0+0x4b/0x260 [ 10.136311][ T179] put_chars+0x1e1/0x2f0 [ 10.136314][ T179] ? __send_to_port+0x420/0x420 [ 10.136315][ T179] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.136320][ T179] ? rcu_read_lock_any_held+0x3c/0x90 [ 10.136323][ T179] ? validate_chain+0x38b/0xc20 [ 10.136327][ T179] hvc_console_print+0x292/0x780 [ 10.136331][ T179] ? __lock_acquire+0x518/0xc20 [ 10.136336][ T179] ? hvc_write+0x3a0/0x3a0 [ 10.136339][ T179] ? rcu_is_watching+0x16/0xd0 [ 10.136345][ T179] console_emit_next_record+0x22f/0x4c0 [ 10.136349][ T179] ? devkmsg_read+0x4b0/0x4b0 [ 10.136350][ T179] ? console_flush_one_record+0x106/0x710 [ 10.136354][ T179] ? rcu_is_watching+0x16/0xd0 [ 10.136356][ T179] ? lock_acquire+0x13c/0x160 [ 10.136360][ T179] console_flush_one_record+0x46f/0x710 [ 10.136365][ T179] ? console_emit_next_record+0x4c0/0x4c0 [ 10.136367][ T179] ? __lock_acquire+0x518/0xc20 [ 10.136372][ T179] console_unlock+0xee/0x1f0 [ 10.136375][ T179] ? console_flush_one_record+0x710/0x710 [ 10.136377][ T179] ? rcu_is_watching+0x16/0xd0 [ 10.136379][ T179] ? lock_acquire+0x60/0x160 [ 10.136383][ T179] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.136385][ T179] ? vprintk_emit+0x320/0x3e0 [ 10.136388][ T179] vprintk_emit+0x37c/0x3e0 [ 10.136392][ T179] ? wake_up_klogd_work_func+0x90/0x90 [ 10.136398][ T179] ? ppp_cleanup+0x920/0x920 [ppp_generic] [ 10.136403][ T179] _printk+0xc7/0x100 [ 10.136407][ T179] ? snapshot_read.cold+0x21/0x21 [ 10.136414][ T179] ? do_one_initcall+0x113/0x4f0 [ 10.136417][ T179] ppp_init+0x16/0x1000 [ppp_generic] [ 10.136421][ T179] do_one_initcall+0x124/0x4f0 [ 10.136423][ T179] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 10.136428][ T179] ? do_init_module+0xae/0x8a0 [ 10.136431][ T179] ? kasan_unpoison+0x44/0x70 [ 10.136436][ T179] do_init_module+0x2ad/0x8a0 [ 10.136440][ T179] ? lockdep_hardirqs_on+0x91/0x130 [ 10.136444][ T179] ? free_module+0x470/0x470 [ 10.136447][ T179] ? kfree+0x123/0x590 [ 10.136452][ T179] load_module+0x1424/0x1bb0 [ 10.136459][ T179] ? layout_and_allocate.isra.0+0x680/0x680 [ 10.136464][ T179] ? backing_file_read_iter+0x541/0x790 [ 10.136468][ T179] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.136472][ T179] init_module_from_file+0x11f/0x1a0 [ 10.136475][ T179] ? __do_sys_init_module+0x220/0x220 [ 10.136483][ T179] ? __rwlock_init+0x150/0x150 [ 10.136487][ T179] ? do_raw_spin_unlock+0x59/0x250 [ 10.136490][ T179] idempotent_init_module+0x37b/0x7c0 [ 10.136494][ T179] ? init_module_from_file+0x1a0/0x1a0 [ 10.136500][ T179] ? rcu_is_watching+0x16/0xd0 [ 10.136503][ T179] ? cap_capable+0x1d7/0x3d0 [ 10.136509][ T179] __x64_sys_finit_module+0xdf/0x190 [ 10.136512][ T179] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 10.136516][ T179] do_syscall_64+0xff/0x530 [ 10.136518][ T179] ? exc_page_fault+0xee/0x100 [ 10.136521][ T179] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.136523][ T179] RIP: 0033:0x7f4de620837d [ 10.136527][ T179] Code: ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 4b aa 0f 00 f7 d8 64 89 01 48 [ 10.136529][ T179] RSP: 002b:00007ffddaccc278 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 10.136531][ T179] RAX: ffffffffffffffda RBX: 000055deeb5ef1d0 RCX: 00007f4de620837d [ 10.136533][ T179] RDX: 0000000000000000 RSI: 000055dec08f3ffd RDI: 0000000000000006 [ 10.136534][ T179] RBP: 00007ffddaccc310 R08: 0000000000000000 R09: 0000000000000000 [ 10.136534][ T179] R10: 0000000000000000 R11: 0000000000000246 R12: 000055dec08f3ffd [ 10.136535][ T179] R13: 000055deeb5ef180 R14: 0000000000040000 R15: 0000000000000000 [ 10.136542][ T179] [ 10.189978][ T179] NET: Registered PF_PPPOX protocol family [ 10.232367][ T179] l2tp_core: L2TP core driver, V2.0 [ 10.240011][ T179] l2tp_ppp: PPPoL2TP kernel driver, V2.0 [ 15.587893][ T263] iperf3 (263) used greatest stack depth: 23192 bytes left [ 15.587917][ T263] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 15.587920][ T263] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 263, name: iperf3 [ 15.587922][ T263] preempt_count: 2, expected: 0 [ 15.587924][ T263] RCU nest depth: 0, expected: 0 [ 15.587925][ T263] locks held by iperf3/263: 5, last CPU#0: [ 15.587928][ T263] #0: ffffffffba8127b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 15.587943][ T263] #1: ffffffffba979cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 15.587951][ T263] #2: ffffffffba979d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 15.587957][ T263] #3: ffffffffba899660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 15.587963][ T263] #4: ffffffffba899560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 15.587973][ T263] irq event stamp: 1557410 [ 15.587974][ T263] hardirqs last enabled at (1557409): [] __down_trylock_console_sem+0x86/0xa0 [ 15.587978][ T263] hardirqs last disabled at (1557410): [] console_emit_next_record+0x3d4/0x4c0 [ 15.587981][ T263] softirqs last enabled at (1557008): [] fpu__clear_user_states+0x104/0x290 [ 15.587986][ T263] softirqs last disabled at (1557006): [] fpu__clear_user_states+0x6d/0x290 [ 15.587989][ T263] Preemption disabled at: [ 15.587990][ T263] [<0000000000000000>] 0x0 [ 15.587999][ T263] CPU: 0 UID: 0 PID: 263 Comm: iperf3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.588004][ T263] Tainted: [W]=WARN [ 15.588005][ T263] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.588007][ T263] Call Trace: [ 15.588009][ T263] [ 15.588012][ T263] dump_stack_lvl+0x6f/0xa0 [ 15.588020][ T263] __might_resched.cold+0x1fe/0x2c1 [ 15.588027][ T263] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 15.588033][ T263] ? __kmalloc_noprof+0xdb/0x760 [ 15.588040][ T263] __kmalloc_noprof+0x443/0x760 [ 15.588044][ T263] ? alloc_buf.isra.0+0x4b/0x260 [ 15.588054][ T263] ? do_raw_spin_unlock+0x59/0x250 [ 15.588057][ T263] alloc_buf.isra.0+0x4b/0x260 [ 15.588063][ T263] put_chars+0x1e1/0x2f0 [ 15.588066][ T263] ? prb_final_commit+0x50/0x50 [ 15.588070][ T263] ? __send_to_port+0x420/0x420 [ 15.588076][ T263] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.588082][ T263] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.588085][ T263] ? validate_chain+0x38b/0xc20 [ 15.588092][ T263] hvc_console_print+0x292/0x780 [ 15.588096][ T263] ? mark_usage+0x61/0x170 [ 15.588100][ T263] ? __lock_acquire+0x518/0xc20 [ 15.588102][ T263] ? __lock_acquire+0x518/0xc20 [ 15.588109][ T263] ? hvc_write+0x3a0/0x3a0 [ 15.588113][ T263] ? console_emit_next_record+0x1df/0x4c0 [ 15.588119][ T263] ? rcu_is_watching+0x16/0xd0 [ 15.588123][ T263] ? lock_acquire+0x13c/0x160 [ 15.588130][ T263] console_emit_next_record+0x22f/0x4c0 [ 15.588136][ T263] ? devkmsg_read+0x4b0/0x4b0 [ 15.588139][ T263] ? console_flush_one_record+0x106/0x710 [ 15.588145][ T263] ? rcu_is_watching+0x16/0xd0 [ 15.588149][ T263] ? lock_acquire+0x13c/0x160 [ 15.588156][ T263] console_flush_one_record+0x46f/0x710 [ 15.588163][ T263] ? console_emit_next_record+0x4c0/0x4c0 [ 15.588166][ T263] ? __lock_acquire+0x518/0xc20 [ 15.588175][ T263] console_unlock+0xee/0x1f0 [ 15.588180][ T263] ? console_flush_one_record+0x710/0x710 [ 15.588183][ T263] ? rcu_is_watching+0x16/0xd0 [ 15.588186][ T263] ? lock_acquire+0x60/0x160 [ 15.588193][ T263] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.588196][ T263] ? vprintk_emit+0x320/0x3e0 [ 15.588201][ T263] vprintk_emit+0x37c/0x3e0 [ 15.588207][ T263] ? wake_up_klogd_work_func+0x90/0x90 [ 15.588213][ T263] ? __lock_acquire+0x518/0xc20 [ 15.588220][ T263] _printk+0xc7/0x100 [ 15.588225][ T263] ? snapshot_read.cold+0x21/0x21 [ 15.588229][ T263] ? do_raw_spin_lock+0x131/0x280 [ 15.588233][ T263] ? __rwlock_init+0x150/0x150 [ 15.588240][ T263] ? do_raw_spin_lock+0x131/0x280 [ 15.588245][ T263] do_exit.cold+0x82/0x9c [ 15.588250][ T263] ? exit_notify+0x890/0x890 [ 15.588260][ T263] __x64_sys_exit+0x42/0x50 [ 15.588263][ T263] x64_sys_call+0x156c/0x1570 [ 15.588267][ T263] do_syscall_64+0xff/0x530 [ 15.588271][ T263] ? exc_page_fault+0xee/0x100 [ 15.588276][ T263] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.588280][ T263] RIP: 0033:0x7fcf89294c49 [ 15.588283][ T263] Code: Unable to access opcode bytes at 0x7fcf89294c1f. [ 15.588285][ T263] RSP: 002b:00007fcf88a53e60 EFLAGS: 00000246 ORIG_RAX: 000000000000003c [ 15.588289][ T263] RAX: ffffffffffffffda RBX: 00007fcf88a546c0 RCX: 00007fcf89294c49 [ 15.588291][ T263] RDX: 0000000000000000 RSI: 0000000000801000 RDI: 0000000000000000 [ 15.588292][ T263] RBP: 00007fcf88a53f70 R08: 0000000000000001 R09: 00007fcf88a546c0 [ 15.588294][ T263] R10: 0000000000000008 R11: 0000000000000246 R12: 00007fcf88254000 [ 15.588295][ T263] R13: 00007fff6f2b6310 R14: 0000000000000002 R15: 00007fff6f2b6417 [ 15.588308][ T263] [ 15.755297][ T268] ppp0: recursion detected [ 15.755340][ T268] [ 15.755341][ T268] ===================================================== [ 15.755343][ T268] WARNING: SOFTIRQ-safe -> SOFTIRQ-unsafe lock order detected [ 15.755345][ T268] 7.2.0-virtme #1 Tainted: G W [ 15.755347][ T268] ----------------------------------------------------- [ 15.755347][ T268] ping/268 [HC0[0]:SC0[10]:HE0:SE0] is trying to acquire: [ 15.755349][ T268] ffffffffba899660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 15.755361][ T268] [ 15.755361][ T268] and this task is already holding: [ 15.755362][ T268] ff110000059450d8 (slock-AF_INET/2){+.-.}-{3:3}, at: l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.755370][ T268] which would create a new lock dependency: [ 15.755370][ T268] (slock-AF_INET/2){+.-.}-{3:3} -> (console_owner){....}-{0:0} [ 15.755373][ T268] [ 15.755373][ T268] but this new dependency connects a SOFTIRQ-irq-safe lock: [ 15.755374][ T268] (slock-AF_INET/2){+.-.}-{3:3} [ 15.755375][ T268] [ 15.755375][ T268] ... which became SOFTIRQ-irq-safe at: [ 15.755377][ T268] __lock_acquire+0x388/0xc20 [ 15.755380][ T268] lock_acquire.part.0+0xd4/0x280 [ 15.755382][ T268] _raw_spin_lock_nested+0x3d/0x50 [ 15.755385][ T268] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.755388][ T268] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.755391][ T268] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.755393][ T268] ppp_push+0x165/0x2d0 [ppp_generic] [ 15.755396][ T268] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 15.755398][ T268] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.755399][ T268] dev_hard_start_xmit+0xf4/0x620 [ 15.755403][ T268] __dev_queue_xmit+0xc0a/0x1b80 [ 15.755404][ T268] ip_finish_output2+0x5b9/0x1bc0 [ 15.755408][ T268] ip_output+0x1ca/0x660 [ 15.755409][ T268] ip_push_pending_frames+0xf6/0x250 [ 15.755411][ T268] icmp_reply+0x94d/0xa50 [ 15.755414][ T268] icmp_echo+0x2ab/0x4b0 [ 15.755415][ T268] icmp_rcv+0x92f/0xfb0 [ 15.755417][ T268] ip_protocol_deliver_rcu+0x82/0x350 [ 15.755419][ T268] ip_local_deliver_finish+0x36f/0x610 [ 15.755420][ T268] ip_local_deliver+0x184/0x4c0 [ 15.755421][ T268] ip_rcv+0xdc/0x3d0 [ 15.755423][ T268] __netif_receive_skb_one_core+0xfc/0x180 [ 15.755424][ T268] process_backlog+0x431/0x14c0 [ 15.755426][ T268] __napi_poll+0xa7/0x3b0 [ 15.755428][ T268] net_rx_action+0x513/0xf50 [ 15.755430][ T268] handle_softirqs+0x1d3/0x900 [ 15.755432][ T268] do_softirq+0xac/0xe0 [ 15.755434][ T268] __local_bh_enable_ip+0x118/0x150 [ 15.755435][ T268] __dev_queue_xmit+0x979/0x1b80 [ 15.755437][ T268] ip_finish_output2+0x5b9/0x1bc0 [ 15.755438][ T268] ip_output+0x1ca/0x660 [ 15.755440][ T268] ip_push_pending_frames+0xf6/0x250 [ 15.755441][ T268] raw_sendmsg+0x1169/0x1cc0 [ 15.755443][ T268] __sys_sendto+0x2aa/0x400 [ 15.755446][ T268] __x64_sys_sendto+0xe4/0x1f0 [ 15.755447][ T268] do_syscall_64+0xff/0x530 [ 15.755450][ T268] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.755453][ T268] [ 15.755453][ T268] to a SOFTIRQ-irq-unsafe lock: [ 15.755453][ T268] (fs_reclaim){+.+.}-{0:0} [ 15.755454][ T268] [ 15.755454][ T268] ... which became SOFTIRQ-irq-unsafe at: [ 15.755455][ T268] ... [ 15.755456][ T268] __lock_acquire+0x388/0xc20 [ 15.755457][ T268] lock_acquire.part.0+0xd4/0x280 [ 15.755459][ T268] fs_reclaim_acquire+0xd5/0x120 [ 15.755462][ T268] __kmalloc_cache_noprof+0x6e/0x620 [ 15.755464][ T268] kthread_create_worker_on_node+0xea/0x210 [ 15.755467][ T268] workqueue_init+0x2a/0x680 [ 15.755470][ T268] kernel_init_freeable+0x2fe/0x630 [ 15.755473][ T268] kernel_init+0x21/0x150 [ 15.755475][ T268] ret_from_fork+0x474/0x6b0 [ 15.755478][ T268] ret_from_fork_asm+0x11/0x20 [ 15.755481][ T268] [ 15.755481][ T268] other info that might help us debug this: [ 15.755481][ T268] [ 15.755481][ T268] Chain exists of: [ 15.755481][ T268] slock-AF_INET/2 --> console_owner --> fs_reclaim [ 15.755481][ T268] [ 15.755484][ T268] Possible interrupt unsafe locking scenario: [ 15.755484][ T268] [ 15.755484][ T268] CPU0 CPU1 [ 15.755485][ T268] ---- ---- [ 15.755485][ T268] lock(fs_reclaim); [ 15.755486][ T268] local_irq_disable(); [ 15.755487][ T268] lock(slock-AF_INET/2); [ 15.755488][ T268] lock(console_owner); [ 15.755489][ T268] [ 15.755489][ T268] lock(slock-AF_INET/2); [ 15.755490][ T268] [ 15.755490][ T268] *** DEADLOCK *** [ 15.755490][ T268] [ 15.755491][ T268] locks held by ping/268: 14, last CPU#3: [ 15.755492][ T268] #0: ff1100000591e290 (sk_lock-AF_INET){+.+.}-{0:0}, at: raw_sendmsg+0xba5/0x1cc0 [ 15.755495][ T268] #1: ffffffffba990b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 15.755498][ T268] #2: ffffffffba990b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 15.755501][ T268] #3: ffffffffba990aa0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x1fc/0x1b80 [ 15.755505][ T268] #4: ffd1ffffffd86388 (&xmit_recursion->bh_lock){+.-.}-{3:3}, at: ppp_xmit_process+0x1d2/0x8e0 [ppp_generic] [ 15.755508][ T268] #5: ff110000142aafd0 (&ppp->wlock){+.-.}-{3:3}, at: ppp_xmit_process+0x292/0x8e0 [ppp_generic] [ 15.755511][ T268] #6: ff1100001421c1d0 (&pch->downl){+.-.}-{3:3}, at: ppp_push+0xae/0x2d0 [ppp_generic] [ 15.755515][ T268] #7: ff110000059450d8 (slock-AF_INET/2){+.-.}-{3:3}, at: l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.755520][ T268] #8: ffffffffba990b00 (rcu_read_lock){....}-{1:3}, at: __ip_queue_xmit+0x63/0x1650 [ 15.755523][ T268] #9: ffffffffba990b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 15.755526][ T268] #10: ffffffffba990b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 15.755529][ T268] #11: ffffffffba990aa0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x1fc/0x1b80 [ 15.755532][ T268] #12: ffffffffba979cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 15.755536][ T268] #13: ffffffffba979d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 15.755539][ T268] [ 15.755539][ T268] the dependencies between SOFTIRQ-irq-safe lock and the holding lock: [ 15.755540][ T268] -> (slock-AF_INET/2){+.-.}-{3:3} { [ 15.755542][ T268] HARDIRQ-ON-W at: [ 15.755543][ T268] __lock_acquire+0x388/0xc20 [ 15.755544][ T268] lock_acquire.part.0+0xd4/0x280 [ 15.755546][ T268] _raw_spin_lock_nested+0x3d/0x50 [ 15.755547][ T268] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.755550][ T268] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.755552][ T268] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.755554][ T268] __ppp_channel_push+0xed/0x280 [ppp_generic] [ 15.755555][ T268] ppp_channel_push+0x269/0x7b0 [ppp_generic] [ 15.755558][ T268] ppp_write+0x26b/0x350 [ppp_generic] [ 15.755559][ T268] vfs_write+0x1a4/0xbd0 [ 15.755563][ T268] ksys_write+0x116/0x250 [ 15.755564][ T268] do_syscall_64+0xff/0x530 [ 15.755566][ T268] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.755567][ T268] IN-SOFTIRQ-W at: [ 15.755568][ T268] __lock_acquire+0x388/0xc20 [ 15.755569][ T268] lock_acquire.part.0+0xd4/0x280 [ 15.755570][ T268] _raw_spin_lock_nested+0x3d/0x50 [ 15.755571][ T268] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.755574][ T268] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.755577][ T268] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.755578][ T268] ppp_push+0x165/0x2d0 [ppp_generic] [ 15.755580][ T268] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 15.755581][ T268] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.755583][ T268] dev_hard_start_xmit+0xf4/0x620 [ 15.755585][ T268] __dev_queue_xmit+0xc0a/0x1b80 [ 15.755587][ T268] ip_finish_output2+0x5b9/0x1bc0 [ 15.755588][ T268] ip_output+0x1ca/0x660 [ 15.755590][ T268] ip_push_pending_frames+0xf6/0x250 [ 15.755591][ T268] icmp_reply+0x94d/0xa50 [ 15.755593][ T268] icmp_echo+0x2ab/0x4b0 [ 15.755594][ T268] icmp_rcv+0x92f/0xfb0 [ 15.755596][ T268] ip_protocol_deliver_rcu+0x82/0x350 [ 15.755597][ T268] ip_local_deliver_finish+0x36f/0x610 [ 15.755598][ T268] ip_local_deliver+0x184/0x4c0 [ 15.755599][ T268] ip_rcv+0xdc/0x3d0 [ 15.755601][ T268] __netif_receive_skb_one_core+0xfc/0x180 [ 15.755602][ T268] process_backlog+0x431/0x14c0 [ 15.755604][ T268] __napi_poll+0xa7/0x3b0 [ 15.755606][ T268] net_rx_action+0x513/0xf50 [ 15.755607][ T268] handle_softirqs+0x1d3/0x900 [ 15.755609][ T268] do_softirq+0xac/0xe0 [ 15.755610][ T268] __local_bh_enable_ip+0x118/0x150 [ 15.755611][ T268] __dev_queue_xmit+0x979/0x1b80 [ 15.755613][ T268] ip_finish_output2+0x5b9/0x1bc0 [ 15.755614][ T268] ip_output+0x1ca/0x660 [ 15.755615][ T268] ip_push_pending_frames+0xf6/0x250 [ 15.755617][ T268] raw_sendmsg+0x1169/0x1cc0 [ 15.755618][ T268] __sys_sendto+0x2aa/0x400 [ 15.755619][ T268] __x64_sys_sendto+0xe4/0x1f0 [ 15.755621][ T268] do_syscall_64+0xff/0x530 [ 15.755622][ T268] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.755624][ T268] INITIAL USE at: [ 15.755624][ T268] __lock_acquire+0x388/0xc20 [ 15.755632][ T268] lock_acquire.part.0+0xd4/0x280 [ 15.755633][ T268] _raw_spin_lock_nested+0x3d/0x50 [ 15.755634][ T268] l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.755637][ T268] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.755639][ T268] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.755641][ T268] __ppp_channel_push+0xed/0x280 [ppp_generic] [ 15.755642][ T268] ppp_channel_push+0x269/0x7b0 [ppp_generic] [ 15.755644][ T268] ppp_write+0x26b/0x350 [ppp_generic] [ 15.755646][ T268] vfs_write+0x1a4/0xbd0 [ 15.755647][ T268] ksys_write+0x116/0x250 [ 15.755649][ T268] do_syscall_64+0xff/0x530 [ 15.755650][ T268] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.755651][ T268] } [ 15.755652][ T268] ... key at: [] af_family_slock_keys+0x22/0x300 [ 15.755655][ T268] [ 15.755655][ T268] the dependencies between the lock to be acquired [ 15.755656][ T268] and SOFTIRQ-irq-unsafe lock: [ 15.755661][ T268] -> (fs_reclaim){+.+.}-{0:0} { [ 15.755662][ T268] HARDIRQ-ON-W at: [ 15.755663][ T268] __lock_acquire+0x388/0xc20 [ 15.755664][ T268] lock_acquire.part.0+0xd4/0x280 [ 15.755666][ T268] fs_reclaim_acquire+0xd5/0x120 [ 15.755667][ T268] __kmalloc_cache_noprof+0x6e/0x620 [ 15.755668][ T268] kthread_create_worker_on_node+0xea/0x210 [ 15.755670][ T268] workqueue_init+0x2a/0x680 [ 15.755671][ T268] kernel_init_freeable+0x2fe/0x630 [ 15.755673][ T268] kernel_init+0x21/0x150 [ 15.755674][ T268] ret_from_fork+0x474/0x6b0 [ 15.755675][ T268] ret_from_fork_asm+0x11/0x20 [ 15.755677][ T268] SOFTIRQ-ON-W at: [ 15.755677][ T268] __lock_acquire+0x388/0xc20 [ 15.755679][ T268] lock_acquire.part.0+0xd4/0x280 [ 15.755680][ T268] fs_reclaim_acquire+0xd5/0x120 [ 15.755681][ T268] __kmalloc_cache_noprof+0x6e/0x620 [ 15.755682][ T268] kthread_create_worker_on_node+0xea/0x210 [ 15.755684][ T268] workqueue_init+0x2a/0x680 [ 15.755685][ T268] kernel_init_freeable+0x2fe/0x630 [ 15.755687][ T268] kernel_init+0x21/0x150 [ 15.755688][ T268] ret_from_fork+0x474/0x6b0 [ 15.755689][ T268] ret_from_fork_asm+0x11/0x20 [ 15.755691][ T268] INITIAL USE at: [ 15.755691][ T268] __lock_acquire+0x388/0xc20 [ 15.755693][ T268] lock_acquire.part.0+0xd4/0x280 [ 15.755694][ T268] fs_reclaim_acquire+0xd5/0x120 [ 15.755695][ T268] __kmalloc_cache_noprof+0x6e/0x620 [ 15.755696][ T268] kthread_create_worker_on_node+0xea/0x210 [ 15.755698][ T268] workqueue_init+0x2a/0x680 [ 15.755699][ T268] kernel_init_freeable+0x2fe/0x630 [ 15.755700][ T268] kernel_init+0x21/0x150 [ 15.755702][ T268] ret_from_fork+0x474/0x6b0 [ 15.755703][ T268] ret_from_fork_asm+0x11/0x20 [ 15.755705][ T268] } [ 15.755705][ T268] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 15.755709][ T268] ... acquired at: [ 15.755709][ T268] __lock_acquire+0x518/0xc20 [ 15.755711][ T268] lock_acquire.part.0+0xd4/0x280 [ 15.755712][ T268] fs_reclaim_acquire+0xd5/0x120 [ 15.755713][ T268] __kmalloc_noprof+0xd3/0x760 [ 15.755714][ T268] alloc_buf.isra.0+0x4b/0x260 [ 15.755718][ T268] put_chars+0x1e1/0x2f0 [ 15.755719][ T268] hvc_console_print+0x292/0x780 [ 15.755722][ T268] console_emit_next_record+0x22f/0x4c0 [ 15.755723][ T268] console_flush_one_record+0x46f/0x710 [ 15.755725][ T268] console_unlock+0xee/0x1f0 [ 15.755726][ T268] vprintk_emit+0x37c/0x3e0 [ 15.755728][ T268] dev_vprintk_emit+0x27f/0x2c0 [ 15.755731][ T268] dev_printk_emit+0xb9/0xee [ 15.755733][ T268] _dev_info+0xe2/0x116 [ 15.755734][ T268] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 15.755739][ T268] cmos_do_probe+0x73b/0x98a [ 15.755740][ T268] platform_probe+0xfe/0x1f0 [ 15.755743][ T268] call_driver_probe+0x61/0x1c0 [ 15.755745][ T268] really_probe+0x199/0x760 [ 15.755747][ T268] __driver_probe_device+0x24f/0x440 [ 15.755748][ T268] driver_probe_device+0x4a/0xf0 [ 15.755750][ T268] __driver_attach+0x1b8/0x540 [ 15.755751][ T268] bus_for_each_dev+0x130/0x1e0 [ 15.755753][ T268] bus_add_driver+0x2c8/0x530 [ 15.755754][ T268] driver_register+0x1a3/0x390 [ 15.755756][ T268] __platform_driver_probe+0x13f/0x270 [ 15.755757][ T268] cmos_init+0x31/0x40 [ 15.755760][ T268] do_one_initcall+0x124/0x4f0 [ 15.755762][ T268] kernel_init_freeable+0x596/0x630 [ 15.755764][ T268] kernel_init+0x21/0x150 [ 15.755765][ T268] ret_from_fork+0x474/0x6b0 [ 15.755766][ T268] ret_from_fork_asm+0x11/0x20 [ 15.755767][ T268] [ 15.755768][ T268] -> (console_owner){....}-{0:0} { [ 15.755769][ T268] INITIAL USE at: [ 15.755770][ T268] } [ 15.755770][ T268] ... key at: [] console_owner_dep_map+0x0/0x60 [ 15.755774][ T268] ... acquired at: [ 15.755775][ T268] check_prev_add+0xef/0xe90 [ 15.755776][ T268] validate_chain+0x6b8/0xc20 [ 15.755778][ T268] __lock_acquire+0x518/0xc20 [ 15.755779][ T268] lock_acquire.part.0+0xd4/0x280 [ 15.755780][ T268] console_lock_spinning_enable+0x5c/0x60 [ 15.755782][ T268] console_emit_next_record+0x1d1/0x4c0 [ 15.755783][ T268] console_flush_one_record+0x46f/0x710 [ 15.755785][ T268] console_unlock+0xee/0x1f0 [ 15.755786][ T268] vprintk_emit+0x37c/0x3e0 [ 15.755788][ T268] _printk+0xc7/0x100 [ 15.755790][ T268] netdev_err+0xe2/0x120 [ 15.755792][ T268] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 15.755795][ T268] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.755796][ T268] dev_hard_start_xmit+0xf4/0x620 [ 15.755798][ T268] __dev_queue_xmit+0xc0a/0x1b80 [ 15.755800][ T268] ip_finish_output2+0x5b9/0x1bc0 [ 15.755801][ T268] ip_output+0x1ca/0x660 [ 15.755803][ T268] __ip_queue_xmit+0x774/0x1650 [ 15.755804][ T268] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 15.755807][ T268] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.755810][ T268] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.755811][ T268] ppp_push+0x165/0x2d0 [ppp_generic] [ 15.755812][ T268] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 15.755814][ T268] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.755816][ T268] dev_hard_start_xmit+0xf4/0x620 [ 15.755818][ T268] __dev_queue_xmit+0xc0a/0x1b80 [ 15.755819][ T268] ip_finish_output2+0x5b9/0x1bc0 [ 15.755821][ T268] ip_output+0x1ca/0x660 [ 15.755822][ T268] ip_push_pending_frames+0xf6/0x250 [ 15.755823][ T268] raw_sendmsg+0x1169/0x1cc0 [ 15.755825][ T268] __sys_sendto+0x2aa/0x400 [ 15.755826][ T268] __x64_sys_sendto+0xe4/0x1f0 [ 15.755828][ T268] do_syscall_64+0xff/0x530 [ 15.755829][ T268] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.755830][ T268] [ 15.755831][ T268] [ 15.755831][ T268] stack backtrace: [ 15.755833][ T268] CPU: 3 UID: 0 PID: 268 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.755837][ T268] Tainted: [W]=WARN [ 15.755838][ T268] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.755839][ T268] Call Trace: [ 15.755841][ T268] [ 15.755842][ T268] dump_stack_lvl+0x6f/0xa0 [ 15.755846][ T268] print_bad_irq_dependency+0x3e4/0x410 [ 15.755849][ T268] check_irq_usage+0x403/0x570 [ 15.755850][ T268] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.755854][ T268] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.755857][ T268] ? check_path.constprop.0+0x24/0x50 [ 15.755859][ T268] ? check_prev_add+0xef/0xe90 [ 15.755861][ T268] check_prev_add+0xef/0xe90 [ 15.755863][ T268] validate_chain+0x6b8/0xc20 [ 15.755865][ T268] __lock_acquire+0x518/0xc20 [ 15.755867][ T268] lock_acquire.part.0+0xd4/0x280 [ 15.755869][ T268] ? console_lock_spinning_enable+0x40/0x60 [ 15.755871][ T268] ? rcu_is_watching+0x16/0xd0 [ 15.755873][ T268] ? lock_acquire+0x13c/0x160 [ 15.755875][ T268] console_lock_spinning_enable+0x5c/0x60 [ 15.755877][ T268] ? console_lock_spinning_enable+0x40/0x60 [ 15.755878][ T268] console_emit_next_record+0x1d1/0x4c0 [ 15.755881][ T268] ? devkmsg_read+0x4b0/0x4b0 [ 15.755882][ T268] ? console_flush_one_record+0x106/0x710 [ 15.755884][ T268] ? rcu_is_watching+0x16/0xd0 [ 15.755886][ T268] ? lock_acquire+0x13c/0x160 [ 15.755888][ T268] console_flush_one_record+0x46f/0x710 [ 15.755890][ T268] ? console_emit_next_record+0x4c0/0x4c0 [ 15.755892][ T268] ? __lock_acquire+0x518/0xc20 [ 15.755894][ T268] console_unlock+0xee/0x1f0 [ 15.755896][ T268] ? console_flush_one_record+0x710/0x710 [ 15.755898][ T268] ? rcu_is_watching+0x16/0xd0 [ 15.755900][ T268] ? lock_acquire+0x60/0x160 [ 15.755902][ T268] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.755903][ T268] ? vprintk_emit+0x320/0x3e0 [ 15.755905][ T268] vprintk_emit+0x37c/0x3e0 [ 15.755908][ T268] ? wake_up_klogd_work_func+0x90/0x90 [ 15.755910][ T268] ? mark_lock+0x3bb/0xa00 [ 15.755912][ T268] _printk+0xc7/0x100 [ 15.755913][ T268] ? snapshot_read.cold+0x21/0x21 [ 15.755915][ T268] ? lock_acquire.part.0+0xd4/0x280 [ 15.755917][ T268] ? find_held_lock+0x2b/0x80 [ 15.755918][ T268] ? netdev_name+0x43/0x60 [ 15.755919][ T268] ? __netdev_printk+0x17f/0x1d0 [ 15.755921][ T268] netdev_err+0xe2/0x120 [ 15.755923][ T268] ? netdev_crit+0x120/0x120 [ 15.755924][ T268] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 15.755926][ T268] ? ___ratelimit+0x4e7/0xa00 [ 15.755929][ T268] ? idr_get_free+0x830/0x830 [ 15.755931][ T268] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 15.755933][ T268] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 15.755935][ T268] ? dst_release+0x54/0x240 [ 15.755938][ T268] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.755940][ T268] ? dev_nit_active_rcu+0x1f/0xf0 [ 15.755943][ T268] ? rcu_is_watching+0x16/0xd0 [ 15.755945][ T268] dev_hard_start_xmit+0xf4/0x620 [ 15.755947][ T268] ? rcu_is_watching+0x16/0xd0 [ 15.755949][ T268] __dev_queue_xmit+0xc0a/0x1b80 [ 15.755951][ T268] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 15.755953][ T268] ? lockdep_hardirqs_on+0x91/0x130 [ 15.755954][ T268] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 15.755955][ T268] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 15.755957][ T268] ? ref_tracker_dir_print+0x100/0x100 [ 15.755960][ T268] ? dst_init+0x84/0x530 [ 15.755962][ T268] ? dst_alloc+0x97/0x160 [ 15.755963][ T268] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 15.755965][ T268] ? ip_route_output_flow+0x21/0x150 [ 15.755966][ T268] ? __ip_queue_xmit+0xdc7/0x1650 [ 15.755968][ T268] ? __lock_acquire+0x518/0xc20 [ 15.755969][ T268] ? down_read_nested+0xe7/0x500 [ 15.755971][ T268] ? lock_acquire.part.0+0xd4/0x280 [ 15.755972][ T268] ? ip_finish_output2+0x25c/0x1bc0 [ 15.755974][ T268] ? rcu_is_watching+0x16/0xd0 [ 15.755977][ T268] ip_finish_output2+0x5b9/0x1bc0 [ 15.755979][ T268] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 15.755980][ T268] ? lock_acquire.part.0+0xd4/0x280 [ 15.755982][ T268] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 15.755985][ T268] ip_output+0x1ca/0x660 [ 15.755987][ T268] ? ip_finish_output+0x460/0x460 [ 15.755988][ T268] ? find_held_lock+0x2b/0x80 [ 15.755990][ T268] ? __lock_release.isra.0+0x69/0x1a0 [ 15.755991][ T268] ? ip4_dst_hoplimit+0x15b/0x320 [ 15.755994][ T268] __ip_queue_xmit+0x774/0x1650 [ 15.755996][ T268] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 15.756000][ T268] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.756004][ T268] ? l2tp_xmit_core.constprop.0+0x19b0/0x19b0 [l2tp_core] [ 15.756007][ T268] ? do_raw_spin_lock+0x131/0x280 [ 15.756009][ T268] ? pppol2tp_xmit+0x423/0x690 [l2tp_ppp] [ 15.756011][ T268] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.756012][ T268] ppp_push+0x165/0x2d0 [ppp_generic] [ 15.756015][ T268] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 15.756017][ T268] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 15.756019][ T268] ? dst_release+0x54/0x240 [ 15.756021][ T268] ? down_read_nested+0x369/0x500 [ 15.756022][ T268] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.756024][ T268] ? dev_nit_active_rcu+0x1f/0xf0 [ 15.756026][ T268] ? rcu_is_watching+0x16/0xd0 [ 15.756028][ T268] dev_hard_start_xmit+0xf4/0x620 [ 15.756030][ T268] ? rcu_is_watching+0x16/0xd0 [ 15.756032][ T268] __dev_queue_xmit+0xc0a/0x1b80 [ 15.756034][ T268] ? sock_queue_err_skb+0x680/0x680 [ 15.756037][ T268] ? kmem_cache_alloc_node_noprof+0x2a6/0x660 [ 15.756038][ T268] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.756040][ T268] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.756042][ T268] ? validate_chain+0x38b/0xc20 [ 15.756044][ T268] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 15.756045][ T268] ? __lock_acquire+0x518/0xc20 [ 15.756047][ T268] ? down_read_nested+0xe7/0x500 [ 15.756048][ T268] ? lock_acquire.part.0+0xd4/0x280 [ 15.756050][ T268] ? ip_finish_output2+0x25c/0x1bc0 [ 15.756052][ T268] ? rcu_is_watching+0x16/0xd0 [ 15.756054][ T268] ip_finish_output2+0x5b9/0x1bc0 [ 15.756056][ T268] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 15.756058][ T268] ? lock_acquire.part.0+0xd4/0x280 [ 15.756059][ T268] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 15.756061][ T268] ? rcu_is_watching+0x16/0xd0 [ 15.756063][ T268] ip_output+0x1ca/0x660 [ 15.756065][ T268] ? ip_finish_output+0x460/0x460 [ 15.756067][ T268] ? __ip_make_skb+0x106a/0x2300 [ 15.756070][ T268] ip_push_pending_frames+0xf6/0x250 [ 15.756071][ T268] raw_sendmsg+0x1169/0x1cc0 [ 15.756073][ T268] ? raw_send_hdrinc+0x1740/0x1740 [ 15.756075][ T268] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.756079][ T268] ? __might_fault+0x97/0x140 [ 15.756083][ T268] ? __might_fault+0x97/0x140 [ 15.756084][ T268] ? __might_fault+0x97/0x140 [ 15.756086][ T268] __sys_sendto+0x2aa/0x400 [ 15.756088][ T268] ? __ia32_sys_getpeername+0xd0/0xd0 [ 15.756092][ T268] ? exc_page_fault+0x87/0x100 [ 15.756094][ T268] __x64_sys_sendto+0xe4/0x1f0 [ 15.756096][ T268] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 15.756098][ T268] ? lockdep_hardirqs_on+0x91/0x130 [ 15.756100][ T268] ? do_syscall_64+0xa6/0x530 [ 15.756101][ T268] do_syscall_64+0xff/0x530 [ 15.756103][ T268] ? exc_page_fault+0xee/0x100 [ 15.756104][ T268] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.756106][ T268] RIP: 0033:0x7f4e3b8b454e [ 15.756109][ T268] 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.756110][ T268] RSP: 002b:00007ffed9fe9e50 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 15.756113][ T268] RAX: ffffffffffffffda RBX: 0000000000000040 RCX: 00007f4e3b8b454e [ 15.756115][ T268] RDX: 0000000000000040 RSI: 0000556f0412c0a4 RDI: 0000000000000005 [ 15.756116][ T268] RBP: 00007ffed9fe9e60 R08: 0000556f0413e320 R09: 0000000000000010 [ 15.756116][ T268] R10: 0000000000000000 R11: 0000000000000202 R12: 431bde82d7b634db [ 15.756117][ T268] R13: 0000000000000000 R14: 0000556f0413f3e0 R15: 0000556f0412c0a0 [ 15.756120][ T268] [ 16.762392][ T268] ping (268) used greatest stack depth: 22536 bytes left [ 16.762410][ T268] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 16.762413][ T268] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 268, name: ping [ 16.762414][ T268] preempt_count: 2, expected: 0 [ 16.762415][ T268] RCU nest depth: 0, expected: 0 [ 16.762416][ T268] INFO: lockdep is turned off. [ 16.762417][ T268] irq event stamp: 24493 [ 16.762418][ T268] hardirqs last enabled at (24492): [] __down_trylock_console_sem+0x86/0xa0 [ 16.762427][ T268] hardirqs last disabled at (24493): [] console_emit_next_record+0x3d4/0x4c0 [ 16.762430][ T268] softirqs last enabled at (24396): [] __alloc_skb+0x4c2/0x5f0 [ 16.762434][ T268] softirqs last disabled at (24406): [] __dev_queue_xmit+0x1fc/0x1b80 [ 16.762438][ T268] Preemption disabled at: [ 16.762438][ T268] [<0000000000000000>] 0x0 [ 16.762445][ T268] CPU: 2 UID: 0 PID: 268 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 16.762449][ T268] Tainted: [W]=WARN [ 16.762450][ T268] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.762452][ T268] Call Trace: [ 16.762453][ T268] [ 16.762454][ T268] dump_stack_lvl+0x6f/0xa0 [ 16.762459][ T268] __might_resched.cold+0x1fe/0x2c1 [ 16.762463][ T268] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 16.762466][ T268] ? __asan_memcpy+0x3c/0x60 [ 16.762469][ T268] ? rcu_is_watching+0x16/0xd0 [ 16.762474][ T268] __kmalloc_noprof+0x443/0x760 [ 16.762477][ T268] ? __rwlock_init+0x150/0x150 [ 16.762479][ T268] ? alloc_buf.isra.0+0x4b/0x260 [ 16.762483][ T268] ? do_raw_spin_unlock+0x59/0x250 [ 16.762485][ T268] alloc_buf.isra.0+0x4b/0x260 [ 16.762487][ T268] put_chars+0x1e1/0x2f0 [ 16.762489][ T268] ? __send_to_port+0x420/0x420 [ 16.762491][ T268] ? console_prepend_replay+0x20/0x20 [ 16.762493][ T268] hvc_console_print+0x292/0x780 [ 16.762497][ T268] ? hvc_write+0x3a0/0x3a0 [ 16.762498][ T268] ? rcu_is_watching+0x16/0xd0 [ 16.762500][ T268] ? lock_acquire+0x13c/0x160 [ 16.762502][ T268] console_emit_next_record+0x22f/0x4c0 [ 16.762505][ T268] ? devkmsg_read+0x4b0/0x4b0 [ 16.762507][ T268] ? rcu_is_watching+0x16/0xd0 [ 16.762508][ T268] ? rcu_is_watching+0x16/0xd0 [ 16.762510][ T268] ? rcu_is_watching+0x16/0xd0 [ 16.762511][ T268] ? lock_acquire+0x13c/0x160 [ 16.762513][ T268] ? console_flush_one_record+0x111/0x710 [ 16.762515][ T268] console_flush_one_record+0x46f/0x710 [ 16.762517][ T268] ? console_emit_next_record+0x4c0/0x4c0 [ 16.762519][ T268] console_unlock+0xee/0x1f0 [ 16.762521][ T268] ? lock_acquire+0x13c/0x160 [ 16.762522][ T268] ? console_flush_one_record+0x710/0x710 [ 16.762524][ T268] ? rcu_is_watching+0x16/0xd0 [ 16.762526][ T268] ? lock_release+0x184/0x1f0 [ 16.762527][ T268] ? lock_acquire+0x60/0x160 [ 16.762528][ T268] ? __rcu_read_lock+0x30/0x70 [ 16.762530][ T268] ? __down_trylock_console_sem+0x5e/0xa0 [ 16.762532][ T268] ? vprintk_emit+0x320/0x3e0 [ 16.762534][ T268] vprintk_emit+0x37c/0x3e0 [ 16.762536][ T268] ? wake_up_klogd_work_func+0x90/0x90 [ 16.762538][ T268] ? do_raw_read_unlock+0x70/0x70 [ 16.762540][ T268] ? forget_original_parent+0x5c/0xdb0 [ 16.762543][ T268] _printk+0xc7/0x100 [ 16.762546][ T268] ? snapshot_read.cold+0x21/0x21 [ 16.762548][ T268] ? do_raw_spin_lock+0x131/0x280 [ 16.762550][ T268] ? do_raw_spin_lock+0x131/0x280 [ 16.762551][ T268] ? __rwlock_init+0x150/0x150 [ 16.762553][ T268] ? do_raw_spin_lock+0x131/0x280 [ 16.762555][ T268] do_exit.cold+0x82/0x9c [ 16.762558][ T268] ? exit_notify+0x890/0x890 [ 16.762559][ T268] ? posixtimer_sig_unignore.isra.0+0x5f0/0x5f0 [ 16.762561][ T268] ? rcu_is_watching+0x16/0xd0 [ 16.762564][ T268] do_group_exit+0xb8/0x370 [ 16.762565][ T268] __x64_sys_exit_group+0x3c/0x50 [ 16.762566][ T268] x64_sys_call+0x1567/0x1570 [ 16.762569][ T268] do_syscall_64+0xff/0x530 [ 16.762572][ T268] ? exc_page_fault+0xee/0x100 [ 16.762574][ T268] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 16.762576][ T268] RIP: 0033:0x7f4e3b9051b8 [ 16.762578][ T268] Code: Unable to access opcode bytes at 0x7f4e3b90518e. [ 16.762579][ T268] RSP: 002b:00007ffed9feb5e8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 16.762583][ T268] RAX: ffffffffffffffda RBX: 00007f4e3ba35f88 RCX: 00007f4e3b9051b8 [ 16.762584][ T268] RDX: 00007f4e3b6967c8 RSI: ffffffffffffffb0 RDI: 0000000000000001 [ 16.762585][ T268] RBP: 00007ffed9feb640 R08: 0000000000000000 R09: 0000000000001010 [ 16.762586][ T268] R10: 00007ffed9feb460 R11: 0000000000000246 R12: 0000000000000002 [ 16.762587][ T268] R13: 0000000000000001 R14: 00007f4e3ba34680 R15: 00007f4e3ba35fa0 [ 16.762589][ T268] [ 16.807660][ T218] ppp0: recursion detected