[ 9.724995][ T179] ip (179) used greatest stack depth: 24248 bytes left [ 9.725014][ T179] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.725016][ T179] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 179, name: ip [ 9.725018][ T179] preempt_count: 2, expected: 0 [ 9.725019][ T179] RCU nest depth: 0, expected: 0 [ 9.725020][ T179] locks held by ip/179: 5, last CPU#0: [ 9.725022][ T179] #0: ffffffffb6a127b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 9.725034][ T179] #1: ffffffffb6b79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.725040][ T179] #2: ffffffffb6b79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.725044][ T179] #3: ffffffffb6a99660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.725048][ T179] #4: ffffffffb6a99560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.725052][ T179] irq event stamp: 27678 [ 9.725052][ T179] hardirqs last enabled at (27677): [] __down_trylock_console_sem+0x86/0xa0 [ 9.725055][ T179] hardirqs last disabled at (27678): [] console_emit_next_record+0x3d4/0x4c0 [ 9.725057][ T179] softirqs last enabled at (26122): [] netlink_release+0x17b/0xcf0 [ 9.725061][ T179] softirqs last disabled at (26120): [] netlink_release+0xd2/0xcf0 [ 9.725064][ T179] Preemption disabled at: [ 9.725065][ T179] [<0000000000000000>] 0x0 [ 9.725071][ T179] CPU: 0 UID: 0 PID: 179 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.725075][ T179] Tainted: [W]=WARN [ 9.725076][ T179] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.725077][ T179] Call Trace: [ 9.725079][ T179] [ 9.725080][ T179] dump_stack_lvl+0x6f/0xa0 [ 9.725087][ T179] __might_resched.cold+0x1fe/0x2c1 [ 9.725091][ T179] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.725095][ T179] ? __kmalloc_noprof+0xdb/0x760 [ 9.725100][ T179] __kmalloc_noprof+0x443/0x760 [ 9.725103][ T179] ? alloc_buf.isra.0+0x4b/0x260 [ 9.725108][ T179] ? do_raw_spin_unlock+0x59/0x250 [ 9.725111][ T179] alloc_buf.isra.0+0x4b/0x260 [ 9.725114][ T179] put_chars+0x1e1/0x2f0 [ 9.725117][ T179] ? __send_to_port+0x420/0x420 [ 9.725119][ T179] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.725124][ T179] ? validate_chain+0x38b/0xc20 [ 9.725129][ T179] hvc_console_print+0x292/0x780 [ 9.725136][ T179] ? hvc_write+0x3a0/0x3a0 [ 9.725139][ T179] ? rcu_is_watching+0x16/0xd0 [ 9.725141][ T179] ? lock_acquire+0x13c/0x160 [ 9.725145][ T179] console_emit_next_record+0x22f/0x4c0 [ 9.725149][ T179] ? devkmsg_read+0x4b0/0x4b0 [ 9.725151][ T179] ? console_flush_one_record+0x106/0x710 [ 9.725154][ T179] ? rcu_is_watching+0x16/0xd0 [ 9.725156][ T179] ? lock_acquire+0x13c/0x160 [ 9.725160][ T179] console_flush_one_record+0x46f/0x710 [ 9.725164][ T179] ? console_emit_next_record+0x4c0/0x4c0 [ 9.725166][ T179] ? __lock_acquire+0x518/0xc20 [ 9.725171][ T179] console_unlock+0xee/0x1f0 [ 9.725174][ T179] ? console_flush_one_record+0x710/0x710 [ 9.725176][ T179] ? rcu_is_watching+0x16/0xd0 [ 9.725179][ T179] ? lock_acquire+0x60/0x160 [ 9.725182][ T179] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.725184][ T179] ? vprintk_emit+0x320/0x3e0 [ 9.725187][ T179] vprintk_emit+0x37c/0x3e0 [ 9.725191][ T179] ? wake_up_klogd_work_func+0x90/0x90 [ 9.725195][ T179] ? __lock_acquire+0x518/0xc20 [ 9.725198][ T179] _printk+0xc7/0x100 [ 9.725202][ T179] ? snapshot_read.cold+0x21/0x21 [ 9.725205][ T179] ? do_raw_spin_lock+0x131/0x280 [ 9.725207][ T179] ? __rwlock_init+0x150/0x150 [ 9.725211][ T179] ? do_raw_spin_lock+0x131/0x280 [ 9.725214][ T179] do_exit.cold+0x82/0x9c [ 9.725218][ T179] ? exit_notify+0x890/0x890 [ 9.725219][ T179] ? __lock_release.isra.0+0x69/0x1a0 [ 9.725221][ T179] ? rcu_is_watching+0x16/0xd0 [ 9.725226][ T179] do_group_exit+0xb8/0x370 [ 9.725229][ T179] __x64_sys_exit_group+0x3c/0x50 [ 9.725231][ T179] x64_sys_call+0x1567/0x1570 [ 9.725233][ T179] do_syscall_64+0xff/0x530 [ 9.725237][ T179] ? exc_page_fault+0xee/0x100 [ 9.725240][ T179] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.725242][ T179] RIP: 0033:0x7f4c699251b8 [ 9.725244][ T179] Code: Unable to access opcode bytes at 0x7f4c6992518e. [ 9.725245][ T179] RSP: 002b:00007ffee2cdf598 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 9.725248][ T179] RAX: ffffffffffffffda RBX: 00007f4c69a55f88 RCX: 00007f4c699251b8 [ 9.725249][ T179] RDX: 00007f4c6966ffc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 9.725250][ T179] RBP: 00007ffee2cdf5f0 R08: 0000000000000000 R09: 0000000000000050 [ 9.725251][ T179] R10: 00007ffee2cdf3b0 R11: 0000000000000246 R12: 0000000000000001 [ 9.725252][ T179] R13: 0000000000000000 R14: 00007f4c69a54680 R15: 00007f4c69a55fa0 [ 9.725258][ T179] [ 9.910624][ T182] PPP generic driver version 2.4.2 [ 9.935766][ T182] NET: Registered PF_PPPOX protocol family [ 9.955969][ T182] l2tp_core: L2TP core driver, V2.0 [ 9.962386][ T182] l2tp_ppp: PPPoL2TP kernel driver, V2.0 [ 10.023420][ T183] ip (183) used greatest stack depth: 24016 bytes left [ 15.129149][ T256] iperf3 (256) used greatest stack depth: 22680 bytes left [ 15.129170][ T256] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 15.129172][ T256] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 256, name: iperf3 [ 15.129174][ T256] preempt_count: 2, expected: 0 [ 15.129175][ T256] RCU nest depth: 0, expected: 0 [ 15.129176][ T256] locks held by iperf3/256: 5, last CPU#3: [ 15.129178][ T256] #0: ffffffffb6a127b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 15.129190][ T256] #1: ffffffffb6b79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 15.129196][ T256] #2: ffffffffb6b79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 15.129200][ T256] #3: ffffffffb6a99660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 15.129204][ T256] #4: ffffffffb6a99560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 15.129208][ T256] irq event stamp: 1399988 [ 15.129209][ T256] hardirqs last enabled at (1399987): [] __down_trylock_console_sem+0x86/0xa0 [ 15.129212][ T256] hardirqs last disabled at (1399988): [] console_emit_next_record+0x3d4/0x4c0 [ 15.129214][ T256] softirqs last enabled at (1399632): [] handle_softirqs+0x67c/0x900 [ 15.129216][ T256] softirqs last disabled at (1399589): [] __irq_exit_rcu+0x145/0x1c0 [ 15.129219][ T256] Preemption disabled at: [ 15.129219][ T256] [<0000000000000000>] 0x0 [ 15.129226][ T256] CPU: 3 UID: 0 PID: 256 Comm: iperf3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.129229][ T256] Tainted: [W]=WARN [ 15.129230][ T256] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.129233][ T256] Call Trace: [ 15.129234][ T256] [ 15.129236][ T256] dump_stack_lvl+0x6f/0xa0 [ 15.129242][ T256] __might_resched.cold+0x1fe/0x2c1 [ 15.129247][ T256] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 15.129251][ T256] ? __kmalloc_noprof+0xdb/0x760 [ 15.129256][ T256] __kmalloc_noprof+0x443/0x760 [ 15.129259][ T256] ? alloc_buf.isra.0+0x4b/0x260 [ 15.129265][ T256] ? do_raw_spin_unlock+0x59/0x250 [ 15.129268][ T256] alloc_buf.isra.0+0x4b/0x260 [ 15.129271][ T256] put_chars+0x1e1/0x2f0 [ 15.129274][ T256] ? prb_final_commit+0x50/0x50 [ 15.129276][ T256] ? __send_to_port+0x420/0x420 [ 15.129279][ T256] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.129284][ T256] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.129286][ T256] ? validate_chain+0x38b/0xc20 [ 15.129290][ T256] hvc_console_print+0x292/0x780 [ 15.129294][ T256] ? mark_usage+0x61/0x170 [ 15.129295][ T256] ? __lock_acquire+0x518/0xc20 [ 15.129297][ T256] ? __lock_acquire+0x518/0xc20 [ 15.129301][ T256] ? hvc_write+0x3a0/0x3a0 [ 15.129303][ T256] ? console_emit_next_record+0x1df/0x4c0 [ 15.129306][ T256] ? rcu_is_watching+0x16/0xd0 [ 15.129309][ T256] ? lock_acquire+0x13c/0x160 [ 15.129313][ T256] console_emit_next_record+0x22f/0x4c0 [ 15.129317][ T256] ? devkmsg_read+0x4b0/0x4b0 [ 15.129319][ T256] ? console_flush_one_record+0x106/0x710 [ 15.129322][ T256] ? rcu_is_watching+0x16/0xd0 [ 15.129325][ T256] ? lock_acquire+0x13c/0x160 [ 15.129329][ T256] console_flush_one_record+0x46f/0x710 [ 15.129333][ T256] ? console_emit_next_record+0x4c0/0x4c0 [ 15.129335][ T256] ? __lock_acquire+0x518/0xc20 [ 15.129340][ T256] console_unlock+0xee/0x1f0 [ 15.129343][ T256] ? console_flush_one_record+0x710/0x710 [ 15.129345][ T256] ? rcu_is_watching+0x16/0xd0 [ 15.129347][ T256] ? lock_acquire+0x60/0x160 [ 15.129351][ T256] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.129353][ T256] ? vprintk_emit+0x320/0x3e0 [ 15.129356][ T256] vprintk_emit+0x37c/0x3e0 [ 15.129360][ T256] ? wake_up_klogd_work_func+0x90/0x90 [ 15.129364][ T256] ? __lock_acquire+0x518/0xc20 [ 15.129368][ T256] _printk+0xc7/0x100 [ 15.129372][ T256] ? snapshot_read.cold+0x21/0x21 [ 15.129374][ T256] ? do_raw_spin_lock+0x131/0x280 [ 15.129377][ T256] ? __rwlock_init+0x150/0x150 [ 15.129381][ T256] ? do_raw_spin_lock+0x131/0x280 [ 15.129384][ T256] do_exit.cold+0x82/0x9c [ 15.129388][ T256] ? exit_notify+0x890/0x890 [ 15.129393][ T256] __x64_sys_exit+0x42/0x50 [ 15.129395][ T256] x64_sys_call+0x156c/0x1570 [ 15.129398][ T256] do_syscall_64+0xff/0x530 [ 15.129402][ T256] ? exc_page_fault+0xee/0x100 [ 15.129405][ T256] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.129407][ T256] RIP: 0033:0x7f828dbf5c49 [ 15.129410][ T256] Code: Unable to access opcode bytes at 0x7f828dbf5c1f. [ 15.129411][ T256] RSP: 002b:00007f828d3b4e60 EFLAGS: 00000246 ORIG_RAX: 000000000000003c [ 15.129414][ T256] RAX: ffffffffffffffda RBX: 00007f828d3b56c0 RCX: 00007f828dbf5c49 [ 15.129415][ T256] RDX: 0000000000000000 RSI: 0000000000801000 RDI: 0000000000000000 [ 15.129416][ T256] RBP: 00007f828d3b4f70 R08: 0000000000000001 R09: 00007f828d3b56c0 [ 15.129417][ T256] R10: 0000000000000008 R11: 0000000000000246 R12: 00007f828cbb5000 [ 15.129418][ T256] R13: 00007ffd063b7300 R14: 0000000000000002 R15: 00007ffd063b7407 [ 15.129424][ T256] [ 15.231912][ C2] ppp0: recursion detected [ 15.231972][ C2] [ 15.231973][ C2] ======================================================== [ 15.231975][ C2] WARNING: possible irq lock inversion dependency detected [ 15.231977][ C2] 7.2.0-virtme #1 Tainted: G W [ 15.231979][ C2] -------------------------------------------------------- [ 15.231980][ C2] swapper/2/0 just changed the state of lock: [ 15.231982][ C2] ffffffffb6a99660 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 15.231998][ C2] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 15.231999][ C2] (fs_reclaim){+.+.}-{0:0} [ 15.232001][ C2] [ 15.232001][ C2] [ 15.232001][ C2] and interrupts could create inverse lock ordering between them. [ 15.232001][ C2] [ 15.232003][ C2] [ 15.232003][ C2] other info that might help us debug this: [ 15.232004][ C2] Possible interrupt unsafe locking scenario: [ 15.232004][ C2] [ 15.232004][ C2] CPU0 CPU1 [ 15.232005][ C2] ---- ---- [ 15.232006][ C2] lock(fs_reclaim); [ 15.232008][ C2] local_irq_disable(); [ 15.232008][ C2] lock(console_owner); [ 15.232010][ C2] lock(fs_reclaim); [ 15.232012][ C2] [ 15.232012][ C2] lock(console_owner); [ 15.232014][ C2] [ 15.232014][ C2] *** DEADLOCK *** [ 15.232014][ C2] [ 15.232015][ C2] locks held by swapper/2/0: 14, last CPU#2: [ 15.232017][ C2] #0: ffa0000000228c90 ((&ndev->rs_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 15.232024][ C2] #1: ffffffffb6b90b00 (rcu_read_lock){....}-{1:3}, at: ndisc_send_skb+0x175/0x1520 [ 15.232031][ C2] #2: ffffffffb6b90b00 (rcu_read_lock){....}-{1:3}, at: ip6_output+0x11d/0x7f0 [ 15.232037][ C2] #3: ffffffffb6b90aa0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x1fc/0x1b80 [ 15.232044][ C2] #4: ffd1ffffffd063d8 (&xmit_recursion->bh_lock){+.-.}-{3:3}, at: ppp_xmit_process+0x1d2/0x8e0 [ppp_generic] [ 15.232052][ C2] #5: ff1100000ca3afd0 (&ppp->wlock){+.-.}-{3:3}, at: ppp_xmit_process+0x292/0x8e0 [ppp_generic] [ 15.232058][ C2] #6: ff1100000cdb11d0 (&pch->downl){+.-.}-{3:3}, at: ppp_push+0xae/0x2d0 [ppp_generic] [ 15.232063][ C2] #7: ff110000059bd0d8 (slock-AF_INET/2){+.-.}-{3:3}, at: l2tp_xmit_core.constprop.0+0x480/0x19b0 [l2tp_core] [ 15.232073][ C2] #8: ffffffffb6b90b00 (rcu_read_lock){....}-{1:3}, at: __ip_queue_xmit+0x63/0x1650 [ 15.232079][ C2] #9: ffffffffb6b90b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 15.232084][ C2] #10: ffffffffb6b90b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 15.232089][ C2] #11: ffffffffb6b90aa0 (rcu_read_lock_bh){....}-{1:3}, at: __dev_queue_xmit+0x1fc/0x1b80 [ 15.232094][ C2] #12: ffffffffb6b79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 15.232099][ C2] #13: ffffffffb6b79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 15.232104][ C2] [ 15.232104][ C2] the shortest dependencies between 2nd lock and 1st lock: [ 15.232112][ C2] -> (fs_reclaim){+.+.}-{0:0} { [ 15.232115][ C2] HARDIRQ-ON-W at: [ 15.232117][ C2] __lock_acquire+0x388/0xc20 [ 15.232121][ C2] lock_acquire.part.0+0xd4/0x280 [ 15.232123][ C2] fs_reclaim_acquire+0xd5/0x120 [ 15.232127][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 15.232130][ C2] kthread_create_worker_on_node+0xea/0x210 [ 15.232133][ C2] workqueue_init+0x2a/0x680 [ 15.232137][ C2] kernel_init_freeable+0x2fe/0x630 [ 15.232141][ C2] kernel_init+0x21/0x150 [ 15.232144][ C2] ret_from_fork+0x474/0x6b0 [ 15.232148][ C2] ret_from_fork_asm+0x11/0x20 [ 15.232151][ C2] SOFTIRQ-ON-W at: [ 15.232152][ C2] __lock_acquire+0x388/0xc20 [ 15.232154][ C2] lock_acquire.part.0+0xd4/0x280 [ 15.232157][ C2] fs_reclaim_acquire+0xd5/0x120 [ 15.232158][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 15.232160][ C2] kthread_create_worker_on_node+0xea/0x210 [ 15.232162][ C2] workqueue_init+0x2a/0x680 [ 15.232164][ C2] kernel_init_freeable+0x2fe/0x630 [ 15.232167][ C2] kernel_init+0x21/0x150 [ 15.232168][ C2] ret_from_fork+0x474/0x6b0 [ 15.232170][ C2] ret_from_fork_asm+0x11/0x20 [ 15.232172][ C2] INITIAL USE at: [ 15.232173][ C2] __lock_acquire+0x388/0xc20 [ 15.232175][ C2] lock_acquire.part.0+0xd4/0x280 [ 15.232177][ C2] fs_reclaim_acquire+0xd5/0x120 [ 15.232179][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 15.232181][ C2] kthread_create_worker_on_node+0xea/0x210 [ 15.232183][ C2] workqueue_init+0x2a/0x680 [ 15.232184][ C2] kernel_init_freeable+0x2fe/0x630 [ 15.232187][ C2] kernel_init+0x21/0x150 [ 15.232189][ C2] ret_from_fork+0x474/0x6b0 [ 15.232190][ C2] ret_from_fork_asm+0x11/0x20 [ 15.232192][ C2] } [ 15.232193][ C2] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 15.232198][ C2] ... acquired at: [ 15.232199][ C2] __lock_acquire+0x518/0xc20 [ 15.232201][ C2] lock_acquire.part.0+0xd4/0x280 [ 15.232203][ C2] fs_reclaim_acquire+0xd5/0x120 [ 15.232204][ C2] __kmalloc_noprof+0xd3/0x760 [ 15.232206][ C2] alloc_buf.isra.0+0x4b/0x260 [ 15.232210][ C2] put_chars+0x1e1/0x2f0 [ 15.232212][ C2] hvc_console_print+0x292/0x780 [ 15.232215][ C2] console_emit_next_record+0x22f/0x4c0 [ 15.232217][ C2] console_flush_one_record+0x46f/0x710 [ 15.232220][ C2] console_unlock+0xee/0x1f0 [ 15.232222][ C2] vprintk_emit+0x37c/0x3e0 [ 15.232224][ C2] _printk+0xc7/0x100 [ 15.232228][ C2] dm_interface_init+0x50/0x60 [ 15.232232][ C2] dm_init+0x51/0xd0 [ 15.232234][ C2] do_one_initcall+0x124/0x4f0 [ 15.232237][ C2] kernel_init_freeable+0x596/0x630 [ 15.232239][ C2] kernel_init+0x21/0x150 [ 15.232241][ C2] ret_from_fork+0x474/0x6b0 [ 15.232242][ C2] ret_from_fork_asm+0x11/0x20 [ 15.232244][ C2] [ 15.232245][ C2] -> (console_owner){..-.}-{0:0} { [ 15.232248][ C2] IN-SOFTIRQ-W at: [ 15.232249][ C2] __lock_acquire+0x388/0xc20 [ 15.232251][ C2] lock_acquire.part.0+0xd4/0x280 [ 15.232253][ C2] console_lock_spinning_enable+0x5c/0x60 [ 15.232255][ C2] console_emit_next_record+0x1d1/0x4c0 [ 15.232258][ C2] console_flush_one_record+0x46f/0x710 [ 15.232260][ C2] console_unlock+0xee/0x1f0 [ 15.232262][ C2] vprintk_emit+0x37c/0x3e0 [ 15.232264][ C2] _printk+0xc7/0x100 [ 15.232266][ C2] netdev_err+0xe2/0x120 [ 15.232269][ C2] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 15.232273][ C2] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.232276][ C2] dev_hard_start_xmit+0xf4/0x620 [ 15.232278][ C2] __dev_queue_xmit+0xc0a/0x1b80 [ 15.232281][ C2] ip_finish_output2+0x5b9/0x1bc0 [ 15.232283][ C2] ip_output+0x1ca/0x660 [ 15.232285][ C2] __ip_queue_xmit+0x774/0x1650 [ 15.232287][ C2] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 15.232291][ C2] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.232296][ C2] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.232299][ C2] ppp_push+0x165/0x2d0 [ppp_generic] [ 15.232302][ C2] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 15.232305][ C2] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.232308][ C2] dev_hard_start_xmit+0xf4/0x620 [ 15.232310][ C2] __dev_queue_xmit+0xc0a/0x1b80 [ 15.232312][ C2] ip6_finish_output2+0x423/0x12a0 [ 15.232315][ C2] ip6_finish_output+0x646/0xda0 [ 15.232316][ C2] ip6_output+0x23f/0x7f0 [ 15.232318][ C2] ndisc_send_skb+0xba3/0x1520 [ 15.232320][ C2] addrconf_rs_timer+0x26f/0x770 [ 15.232323][ C2] call_timer_fn+0x160/0x4d0 [ 15.232325][ C2] __run_timers+0x68f/0xaa0 [ 15.232328][ C2] run_timer_softirq+0xf0/0x160 [ 15.232330][ C2] handle_softirqs+0x1d3/0x900 [ 15.232333][ C2] __irq_exit_rcu+0x145/0x1c0 [ 15.232335][ C2] irq_exit_rcu+0xe/0x30 [ 15.232337][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 15.232340][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 15.232342][ C2] pv_native_safe_halt+0xf/0x10 [ 15.232344][ C2] default_idle+0x9/0x10 [ 15.232346][ C2] default_idle_call+0x6e/0xb0 [ 15.232348][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 15.232351][ C2] do_idle+0xd8/0x190 [ 15.232352][ C2] cpu_startup_entry+0x53/0x70 [ 15.232354][ C2] start_secondary+0x204/0x2b0 [ 15.232357][ C2] common_startup_64+0x13e/0x148 [ 15.232360][ C2] INITIAL USE at: [ 15.232361][ C2] } [ 15.232362][ C2] ... key at: [] console_owner_dep_map+0x0/0x60 [ 15.232367][ C2] ... acquired at: [ 15.232367][ C2] mark_lock+0x1d7/0xa00 [ 15.232369][ C2] mark_usage+0x42/0x170 [ 15.232371][ C2] __lock_acquire+0x388/0xc20 [ 15.232373][ C2] lock_acquire.part.0+0xd4/0x280 [ 15.232375][ C2] console_lock_spinning_enable+0x5c/0x60 [ 15.232377][ C2] console_emit_next_record+0x1d1/0x4c0 [ 15.232379][ C2] console_flush_one_record+0x46f/0x710 [ 15.232382][ C2] console_unlock+0xee/0x1f0 [ 15.232384][ C2] vprintk_emit+0x37c/0x3e0 [ 15.232386][ C2] _printk+0xc7/0x100 [ 15.232388][ C2] netdev_err+0xe2/0x120 [ 15.232389][ C2] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 15.232392][ C2] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.232395][ C2] dev_hard_start_xmit+0xf4/0x620 [ 15.232398][ C2] __dev_queue_xmit+0xc0a/0x1b80 [ 15.232400][ C2] ip_finish_output2+0x5b9/0x1bc0 [ 15.232402][ C2] ip_output+0x1ca/0x660 [ 15.232404][ C2] __ip_queue_xmit+0x774/0x1650 [ 15.232406][ C2] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 15.232410][ C2] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.232414][ C2] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.232416][ C2] ppp_push+0x165/0x2d0 [ppp_generic] [ 15.232419][ C2] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 15.232422][ C2] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.232425][ C2] dev_hard_start_xmit+0xf4/0x620 [ 15.232427][ C2] __dev_queue_xmit+0xc0a/0x1b80 [ 15.232430][ C2] ip6_finish_output2+0x423/0x12a0 [ 15.232432][ C2] ip6_finish_output+0x646/0xda0 [ 15.232434][ C2] ip6_output+0x23f/0x7f0 [ 15.232436][ C2] ndisc_send_skb+0xba3/0x1520 [ 15.232437][ C2] addrconf_rs_timer+0x26f/0x770 [ 15.232440][ C2] call_timer_fn+0x160/0x4d0 [ 15.232442][ C2] __run_timers+0x68f/0xaa0 [ 15.232444][ C2] run_timer_softirq+0xf0/0x160 [ 15.232446][ C2] handle_softirqs+0x1d3/0x900 [ 15.232448][ C2] __irq_exit_rcu+0x145/0x1c0 [ 15.232450][ C2] irq_exit_rcu+0xe/0x30 [ 15.232452][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 15.232453][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 15.232455][ C2] pv_native_safe_halt+0xf/0x10 [ 15.232457][ C2] default_idle+0x9/0x10 [ 15.232459][ C2] default_idle_call+0x6e/0xb0 [ 15.232460][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 15.232462][ C2] do_idle+0xd8/0x190 [ 15.232464][ C2] cpu_startup_entry+0x53/0x70 [ 15.232466][ C2] start_secondary+0x204/0x2b0 [ 15.232467][ C2] common_startup_64+0x13e/0x148 [ 15.232469][ C2] [ 15.232470][ C2] [ 15.232470][ C2] stack backtrace: [ 15.232473][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.232478][ C2] Tainted: [W]=WARN [ 15.232479][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.232481][ C2] Call Trace: [ 15.232483][ C2] [ 15.232485][ C2] dump_stack_lvl+0x6f/0xa0 [ 15.232490][ C2] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 15.232493][ C2] mark_lock_irq+0x989/0x9c0 [ 15.232498][ C2] mark_lock+0x1d7/0xa00 [ 15.232501][ C2] mark_usage+0x42/0x170 [ 15.232503][ C2] __lock_acquire+0x388/0xc20 [ 15.232507][ C2] lock_acquire.part.0+0xd4/0x280 [ 15.232509][ C2] ? console_lock_spinning_enable+0x40/0x60 [ 15.232512][ C2] ? rcu_is_watching+0x16/0xd0 [ 15.232516][ C2] ? lock_acquire+0x13c/0x160 [ 15.232519][ C2] console_lock_spinning_enable+0x5c/0x60 [ 15.232522][ C2] ? console_lock_spinning_enable+0x40/0x60 [ 15.232524][ C2] console_emit_next_record+0x1d1/0x4c0 [ 15.232527][ C2] ? devkmsg_read+0x4b0/0x4b0 [ 15.232530][ C2] ? console_flush_one_record+0x106/0x710 [ 15.232533][ C2] ? rcu_is_watching+0x16/0xd0 [ 15.232536][ C2] ? lock_acquire+0x13c/0x160 [ 15.232539][ C2] console_flush_one_record+0x46f/0x710 [ 15.232542][ C2] ? console_emit_next_record+0x4c0/0x4c0 [ 15.232544][ C2] ? __lock_acquire+0x518/0xc20 [ 15.232548][ C2] console_unlock+0xee/0x1f0 [ 15.232551][ C2] ? console_flush_one_record+0x710/0x710 [ 15.232553][ C2] ? rcu_is_watching+0x16/0xd0 [ 15.232556][ C2] ? lock_acquire+0x60/0x160 [ 15.232559][ C2] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.232561][ C2] ? vprintk_emit+0x320/0x3e0 [ 15.232564][ C2] vprintk_emit+0x37c/0x3e0 [ 15.232567][ C2] ? wake_up_klogd_work_func+0x90/0x90 [ 15.232570][ C2] ? mark_lock+0x3bb/0xa00 [ 15.232573][ C2] _printk+0xc7/0x100 [ 15.232576][ C2] ? snapshot_read.cold+0x21/0x21 [ 15.232579][ C2] ? lock_acquire.part.0+0xd4/0x280 [ 15.232581][ C2] ? find_held_lock+0x2b/0x80 [ 15.232583][ C2] ? netdev_name+0x43/0x60 [ 15.232586][ C2] ? __netdev_printk+0x17f/0x1d0 [ 15.232588][ C2] netdev_err+0xe2/0x120 [ 15.232591][ C2] ? netdev_crit+0x120/0x120 [ 15.232592][ C2] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 15.232596][ C2] ? ___ratelimit+0x4e7/0xa00 [ 15.232600][ C2] ? idr_get_free+0x830/0x830 [ 15.232603][ C2] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 15.232607][ C2] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 15.232610][ C2] ? dst_release+0x54/0x240 [ 15.232614][ C2] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.232618][ C2] ? dev_nit_active_rcu+0x1f/0xf0 [ 15.232622][ C2] ? rcu_is_watching+0x16/0xd0 [ 15.232624][ C2] dev_hard_start_xmit+0xf4/0x620 [ 15.232627][ C2] ? rcu_is_watching+0x16/0xd0 [ 15.232631][ C2] __dev_queue_xmit+0xc0a/0x1b80 [ 15.232634][ C2] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 15.232636][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 15.232638][ C2] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 15.232640][ C2] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 15.232642][ C2] ? ref_tracker_dir_print+0x100/0x100 [ 15.232647][ C2] ? dst_init+0x84/0x530 [ 15.232649][ C2] ? dst_alloc+0x97/0x160 [ 15.232651][ C2] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 15.232654][ C2] ? ip_route_output_flow+0x21/0x150 [ 15.232656][ C2] ? __ip_queue_xmit+0xdc7/0x1650 [ 15.232658][ C2] ? __lock_acquire+0x518/0xc20 [ 15.232660][ C2] ? down_read_nested+0xe7/0x500 [ 15.232663][ C2] ? lock_acquire.part.0+0xd4/0x280 [ 15.232665][ C2] ? ip_finish_output2+0x25c/0x1bc0 [ 15.232668][ C2] ? rcu_is_watching+0x16/0xd0 [ 15.232672][ C2] ip_finish_output2+0x5b9/0x1bc0 [ 15.232675][ C2] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 15.232678][ C2] ? lock_acquire.part.0+0xd4/0x280 [ 15.232686][ C2] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 15.232690][ C2] ip_output+0x1ca/0x660 [ 15.232694][ C2] ? ip_finish_output+0x460/0x460 [ 15.232697][ C2] ? find_held_lock+0x2b/0x80 [ 15.232700][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 15.232703][ C2] ? ip4_dst_hoplimit+0x15b/0x320 [ 15.232707][ C2] __ip_queue_xmit+0x774/0x1650 [ 15.232712][ C2] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 15.232721][ C2] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 15.232727][ C2] ? l2tp_xmit_core.constprop.0+0x19b0/0x19b0 [l2tp_core] [ 15.232733][ C2] ? do_raw_spin_lock+0x131/0x280 [ 15.232738][ C2] ? pppol2tp_xmit+0x423/0x690 [l2tp_ppp] [ 15.232741][ C2] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 15.232745][ C2] ppp_push+0x165/0x2d0 [ppp_generic] [ 15.232750][ C2] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 15.232754][ C2] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 15.232759][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 15.232761][ C2] ? dst_release+0x1ac/0x240 [ 15.232766][ C2] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 15.232770][ C2] ? dev_nit_active_rcu+0x1f/0xf0 [ 15.232773][ C2] ? rcu_is_watching+0x16/0xd0 [ 15.232777][ C2] dev_hard_start_xmit+0xf4/0x620 [ 15.232781][ C2] ? rcu_is_watching+0x16/0xd0 [ 15.232784][ C2] __dev_queue_xmit+0xc0a/0x1b80 [ 15.232789][ C2] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.232793][ C2] ? stack_depot_save_flags+0x38e/0x790 [ 15.232796][ C2] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 15.232800][ C2] ? kasan_save_stack+0x3d/0x50 [ 15.232804][ C2] ? kasan_save_stack+0x2f/0x50 [ 15.232807][ C2] ? kasan_save_track+0x14/0x30 [ 15.232809][ C2] ? __kasan_kmalloc+0x7b/0x90 [ 15.232812][ C2] ? __kmalloc_cache_noprof+0x29a/0x620 [ 15.232815][ C2] ? dst_cow_metrics_generic+0x56/0x1b0 [ 15.232818][ C2] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.232821][ C2] ? ___neigh_lookup_noref.constprop.0+0x4e1/0x8e0 [ 15.232824][ C2] ? default_idle+0x9/0x10 [ 15.232826][ C2] ? default_idle_call+0x6e/0xb0 [ 15.232829][ C2] ip6_finish_output2+0x423/0x12a0 [ 15.232833][ C2] ? ip6_dst_lookup+0x80/0x80 [ 15.232836][ C2] ? find_held_lock+0x2b/0x80 [ 15.232839][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 15.232842][ C2] ? ip6_mtu+0x15d/0x310 [ 15.232846][ C2] ip6_finish_output+0x646/0xda0 [ 15.232849][ C2] ip6_output+0x23f/0x7f0 [ 15.232852][ C2] ? ip6_finish_output+0xda0/0xda0 [ 15.232855][ C2] ? lock_acquire.part.0+0xd4/0x280 [ 15.232857][ C2] ? find_held_lock+0x2b/0x80 [ 15.232860][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 15.232862][ C2] ? __local_bh_enable_ip+0xaa/0x150 [ 15.232866][ C2] ndisc_send_skb+0xba3/0x1520 [ 15.232870][ C2] ? ndisc_recv_na+0xea0/0xea0 [ 15.232872][ C2] ? ipv6_get_lladdr+0x3f0/0x3f0 [ 15.232881][ C2] ? ndisc_alloc_skb+0x2da/0x490 [ 15.232883][ C2] ? __local_bh_enable_ip+0xaa/0x150 [ 15.232886][ C2] ? ipv6_get_lladdr+0x3f0/0x3f0 [ 15.232889][ C2] addrconf_rs_timer+0x26f/0x770 [ 15.232893][ C2] ? ipv6_get_lladdr+0x3f0/0x3f0 [ 15.232897][ C2] ? lock_acquire+0x13c/0x160 [ 15.232900][ C2] ? ipv6_get_lladdr+0x3f0/0x3f0 [ 15.232903][ C2] call_timer_fn+0x160/0x4d0 [ 15.232907][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 15.232910][ C2] ? debug_object_active_state+0x430/0x430 [ 15.232914][ C2] ? find_held_lock+0x2b/0x80 [ 15.232918][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 15.232921][ C2] ? rcu_is_watching+0x16/0xd0 [ 15.232925][ C2] __run_timers+0x68f/0xaa0 [ 15.232928][ C2] ? ipv6_get_lladdr+0x3f0/0x3f0 [ 15.232933][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 15.232936][ C2] ? __lock_acquire+0x518/0xc20 [ 15.232941][ C2] ? __rwlock_init+0x150/0x150 [ 15.232946][ C2] run_timer_softirq+0xf0/0x160 [ 15.232949][ C2] ? __run_timers+0xaa0/0xaa0 [ 15.232952][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 15.232956][ C2] ? rcu_is_watching+0x16/0xd0 [ 15.232960][ C2] handle_softirqs+0x1d3/0x900 [ 15.232963][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 15.232966][ C2] ? _local_bh_enable+0xc0/0xc0 [ 15.232970][ C2] __irq_exit_rcu+0x145/0x1c0 [ 15.232973][ C2] irq_exit_rcu+0xe/0x30 [ 15.232975][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 15.232978][ C2] [ 15.232979][ C2] [ 15.232980][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 15.232983][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 15.232986][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 15.232989][ C2] Code: 48 8b 3d 94 12 06 02 e8 1f 00 00 00 48 2b 05 58 13 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 b6 17 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 15.232993][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 15.232996][ C2] RAX: 0000000000062bff RBX: ff11000001bf46c0 RCX: ffffffffb36fa307 [ 15.232998][ C2] RDX: ff11000001bf46c0 RSI: ffffffffb6678e7e RDI: ffffffffb608dce0 [ 15.233000][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 15.233002][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 15.233003][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 15.233006][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 15.233010][ C2] default_idle+0x9/0x10 [ 15.233012][ C2] default_idle_call+0x6e/0xb0 [ 15.233015][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 15.233018][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 15.233021][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 15.233024][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 15.233026][ C2] ? rcu_is_watching+0x16/0xd0 [ 15.233030][ C2] do_idle+0xd8/0x190 [ 15.233032][ C2] cpu_startup_entry+0x53/0x70 [ 15.233035][ C2] start_secondary+0x204/0x2b0 [ 15.233038][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 15.233042][ C2] common_startup_64+0x13e/0x148 [ 15.233047][ C2] [ 15.375832][ T260] ppp0: recursion detected [ 16.428128][ T220] ppp0: recursion detected [ 16.428144][ T220] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 16.428146][ T220] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 220, name: xl2tpd [ 16.428148][ T220] preempt_count: a04, expected: 0 [ 16.428149][ T220] RCU nest depth: 5, expected: 0 [ 16.428150][ T220] INFO: lockdep is turned off. [ 16.428152][ T220] irq event stamp: 36758 [ 16.428152][ T220] hardirqs last enabled at (36757): [] _raw_spin_unlock_irqrestore+0x53/0x80 [ 16.428162][ T220] hardirqs last disabled at (36758): [] __schedule+0x1246/0x20e0 [ 16.428164][ T220] softirqs last enabled at (36564): [] __skb_recv_udp+0x5a2/0x800 [ 16.428168][ T220] softirqs last disabled at (36562): [] __skb_recv_udp+0x178/0x800 [ 16.428170][ T220] Preemption disabled at: [ 16.428171][ T220] [] __dev_queue_xmit+0x204/0x1b80 [ 16.428179][ T220] CPU: 3 UID: 0 PID: 220 Comm: xl2tpd Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 16.428183][ T220] Tainted: [W]=WARN [ 16.428184][ T220] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.428186][ T220] Call Trace: [ 16.428187][ T220] [ 16.428189][ T220] dump_stack_lvl+0x6f/0xa0 [ 16.428195][ T220] ? __dev_queue_xmit+0x204/0x1b80 [ 16.428197][ T220] __might_resched.cold+0x1fe/0x2c1 [ 16.428201][ T220] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 16.428205][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428209][ T220] __kmalloc_noprof+0x443/0x760 [ 16.428212][ T220] ? __rwlock_init+0x150/0x150 [ 16.428215][ T220] ? alloc_buf.isra.0+0x4b/0x260 [ 16.428219][ T220] ? do_raw_spin_unlock+0x59/0x250 [ 16.428221][ T220] alloc_buf.isra.0+0x4b/0x260 [ 16.428223][ T220] put_chars+0x1e1/0x2f0 [ 16.428226][ T220] ? __send_to_port+0x420/0x420 [ 16.428228][ T220] ? console_prepend_replay+0x20/0x20 [ 16.428231][ T220] hvc_console_print+0x292/0x780 [ 16.428235][ T220] ? hvc_write+0x3a0/0x3a0 [ 16.428237][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428239][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428241][ T220] ? lock_acquire+0x13c/0x160 [ 16.428243][ T220] console_emit_next_record+0x22f/0x4c0 [ 16.428246][ T220] ? devkmsg_read+0x4b0/0x4b0 [ 16.428248][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428250][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428251][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428253][ T220] ? lock_acquire+0x13c/0x160 [ 16.428255][ T220] ? console_flush_one_record+0x111/0x710 [ 16.428257][ T220] console_flush_one_record+0x46f/0x710 [ 16.428259][ T220] ? console_emit_next_record+0x4c0/0x4c0 [ 16.428262][ T220] console_unlock+0xee/0x1f0 [ 16.428264][ T220] ? lock_acquire+0x13c/0x160 [ 16.428266][ T220] ? console_flush_one_record+0x710/0x710 [ 16.428267][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428269][ T220] ? lock_release+0x184/0x1f0 [ 16.428271][ T220] ? lock_acquire+0x60/0x160 [ 16.428273][ T220] ? __rcu_read_lock+0x30/0x70 [ 16.428275][ T220] ? __down_trylock_console_sem+0x5e/0xa0 [ 16.428277][ T220] ? vprintk_emit+0x320/0x3e0 [ 16.428279][ T220] vprintk_emit+0x37c/0x3e0 [ 16.428282][ T220] ? wake_up_klogd_work_func+0x90/0x90 [ 16.428284][ T220] ? l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 16.428289][ T220] ? ppp_push+0x165/0x2d0 [ppp_generic] [ 16.428292][ T220] ? ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 16.428294][ T220] ? ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 16.428296][ T220] ? dev_hard_start_xmit+0xf4/0x620 [ 16.428298][ T220] ? __dev_queue_xmit+0xc0a/0x1b80 [ 16.428300][ T220] ? ip_finish_output2+0x5b9/0x1bc0 [ 16.428304][ T220] _printk+0xc7/0x100 [ 16.428307][ T220] ? snapshot_read.cold+0x21/0x21 [ 16.428310][ T220] ? netdev_name+0x43/0x60 [ 16.428312][ T220] ? __netdev_printk+0x17f/0x1d0 [ 16.428314][ T220] netdev_err+0xe2/0x120 [ 16.428316][ T220] ? netdev_crit+0x120/0x120 [ 16.428317][ T220] ? trace_hardirqs_on+0x36/0x40 [ 16.428321][ T220] ? ___ratelimit+0x4e7/0xa00 [ 16.428323][ T220] ? trace_hardirqs_on+0x36/0x40 [ 16.428325][ T220] ? idr_get_free+0x830/0x830 [ 16.428327][ T220] ? trace_irq_enable.constprop.0+0x30/0x160 [ 16.428328][ T220] ppp_xmit_process.cold+0x13/0x18 [ppp_generic] [ 16.428331][ T220] ? __module_address+0x36/0x330 [ 16.428335][ T220] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 16.428337][ T220] ? lock_acquire+0x13c/0x160 [ 16.428338][ T220] ? netif_skb_features+0x658/0x1430 [ 16.428340][ T220] ? dst_release+0x54/0x240 [ 16.428343][ T220] ? lock_release+0x184/0x1f0 [ 16.428345][ T220] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 16.428347][ T220] ? dev_nit_active_rcu+0x1f/0xf0 [ 16.428350][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428352][ T220] dev_hard_start_xmit+0xf4/0x620 [ 16.428355][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428357][ T220] __dev_queue_xmit+0xc0a/0x1b80 [ 16.428359][ T220] ? write_profile+0xf0/0xf0 [ 16.428360][ T220] ? unwind_get_return_address+0x67/0xd0 [ 16.428363][ T220] ? arch_stack_walk+0xed/0x130 [ 16.428366][ T220] ? do_raw_spin_trylock+0xb6/0x180 [ 16.428368][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428370][ T220] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 16.428372][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428374][ T220] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 16.428375][ T220] ? do_raw_spin_unlock+0x59/0x250 [ 16.428377][ T220] ? trace_hardirqs_on+0x36/0x40 [ 16.428378][ T220] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 16.428379][ T220] ? stack_depot_save_flags+0x552/0x790 [ 16.428383][ T220] ? pskb_expand_head+0x359/0xf50 [ 16.428385][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428387][ T220] ? lock_acquire+0x13c/0x160 [ 16.428388][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428390][ T220] ip_finish_output2+0x5b9/0x1bc0 [ 16.428392][ T220] ? ip_dst_mtu_maybe_forward+0x7c/0x670 [ 16.428394][ T220] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 16.428395][ T220] ? udp_sendmsg+0x164e/0x2670 [ 16.428397][ T220] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 16.428399][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428401][ T220] ip_output+0x1ca/0x660 [ 16.428403][ T220] ? ip_finish_output+0x460/0x460 [ 16.428405][ T220] ? lock_acquire+0x13c/0x160 [ 16.428407][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428408][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428410][ T220] ? lock_acquire+0x13c/0x160 [ 16.428412][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428413][ T220] ? lock_release+0x184/0x1f0 [ 16.428415][ T220] __ip_queue_xmit+0x774/0x1650 [ 16.428418][ T220] l2tp_xmit_core.constprop.0+0x999/0x19b0 [l2tp_core] [ 16.428421][ T220] ? lock_acquire+0x13c/0x160 [ 16.428423][ T220] l2tp_xmit_skb+0x77/0x1d0 [l2tp_core] [ 16.428425][ T220] ? l2tp_xmit_core.constprop.0+0x19b0/0x19b0 [l2tp_core] [ 16.428428][ T220] ? do_raw_spin_lock+0x131/0x280 [ 16.428430][ T220] ? pppol2tp_xmit+0x423/0x690 [l2tp_ppp] [ 16.428432][ T220] pppol2tp_xmit+0x436/0x690 [l2tp_ppp] [ 16.428434][ T220] ppp_push+0x165/0x2d0 [ppp_generic] [ 16.428437][ T220] ppp_xmit_process+0x318/0x8e0 [ppp_generic] [ 16.428439][ T220] ? ppp_prepare_tx_skb+0xcf0/0xcf0 [ppp_generic] [ 16.428441][ T220] ? netif_skb_features+0x658/0x1430 [ 16.428443][ T220] ? dst_release+0x54/0x240 [ 16.428444][ T220] ? csum_partial+0xc/0x20 [ 16.428446][ T220] ppp_start_xmit+0x4a0/0x7c0 [ppp_generic] [ 16.428448][ T220] ? dev_nit_active_rcu+0x1f/0xf0 [ 16.428450][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428452][ T220] dev_hard_start_xmit+0xf4/0x620 [ 16.428454][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428456][ T220] __dev_queue_xmit+0xc0a/0x1b80 [ 16.428458][ T220] ? sk_reset_timer+0x90/0x90 [ 16.428461][ T220] ? alloc_skb_with_frags+0xcc/0x6c0 [ 16.428463][ T220] ? ip_generic_getfrag+0x11b/0x200 [ 16.428465][ T220] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 16.428467][ T220] ? ip_frag_init+0x4f0/0x4f0 [ 16.428469][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428470][ T220] ? lock_acquire+0x13c/0x160 [ 16.428472][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428474][ T220] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 16.428475][ T220] ? trace_hardirqs_on+0x36/0x40 [ 16.428476][ T220] ? get_random_u16+0x740/0x740 [ 16.428478][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428479][ T220] ? lock_acquire+0x13c/0x160 [ 16.428481][ T220] ? lock_acquire+0x13c/0x160 [ 16.428482][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428484][ T220] ip_finish_output2+0x5b9/0x1bc0 [ 16.428486][ T220] ? ip_dst_mtu_maybe_forward+0x7c/0x670 [ 16.428488][ T220] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 16.428490][ T220] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 16.428491][ T220] ? __ip_make_skb+0x106a/0x2300 [ 16.428493][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428495][ T220] ip_output+0x1ca/0x660 [ 16.428497][ T220] ? ip_finish_output+0x460/0x460 [ 16.428499][ T220] ? ip_frag_init+0x4f0/0x4f0 [ 16.428500][ T220] ? ip_flush_pending_frames+0x20/0x20 [ 16.428503][ T220] ip_send_skb+0xa3/0x1c0 [ 16.428505][ T220] udp_send_skb+0x87a/0x1270 [ 16.428507][ T220] udp_sendmsg+0x164e/0x2670 [ 16.428509][ T220] ? __rcu_read_lock+0x30/0x70 [ 16.428511][ T220] ? udp_cmsg_send+0x360/0x360 [ 16.428513][ T220] ? __mutex_lock+0x1db/0x1ea0 [ 16.428514][ T220] ? __mutex_lock+0x9a3/0x1ea0 [ 16.428516][ T220] ? anon_pipe_prefill_and_lock+0x436/0x5a0 [ 16.428520][ T220] ? ww_mutex_lock+0x160/0x160 [ 16.428522][ T220] ? skb_attempt_defer_free+0x4d5/0x790 [ 16.428523][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428525][ T220] ? __mutex_unlock_slowpath+0x9e/0x740 [ 16.428527][ T220] ? __mutex_unlock_slowpath+0x5e5/0x740 [ 16.428529][ T220] ? lock_release+0x184/0x1f0 [ 16.428531][ T220] ? __import_iovec+0x36c/0x620 [ 16.428534][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428536][ T220] ? lock_release+0x184/0x1f0 [ 16.428538][ T220] ____sys_sendmsg+0x415/0x880 [ 16.428541][ T220] ? copy_msghdr_from_user+0x279/0x420 [ 16.428543][ T220] ? get_timestamp.constprop.0+0x390/0x390 [ 16.428544][ T220] ? move_addr_to_kernel+0x40/0x40 [ 16.428547][ T220] ___sys_sendmsg+0x14e/0x1d0 [ 16.428549][ T220] ? anon_pipe_prefill_and_lock+0x5a0/0x5a0 [ 16.428551][ T220] ? copy_msghdr_from_user+0x420/0x420 [ 16.428552][ T220] ? kfree+0x22/0x590 [ 16.428554][ T220] ? _copy_to_user+0x4b/0x70 [ 16.428556][ T220] ? poll_select_finish+0x41f/0x520 [ 16.428561][ T220] __sys_sendmsg+0x12c/0x1d0 [ 16.428563][ T220] ? __sys_sendmsg_sock+0x20/0x20 [ 16.428566][ T220] ? rcu_is_watching+0x16/0xd0 [ 16.428568][ T220] do_syscall_64+0xff/0x530 [ 16.428570][ T220] ? exc_page_fault+0xee/0x100 [ 16.428572][ T220] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 16.428574][ T220] RIP: 0033:0x7fd8d8a5854e [ 16.428576][ T220] 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 [ 16.428578][ T220] RSP: 002b:00007fff65b14610 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 16.428582][ T220] RAX: ffffffffffffffda RBX: 0000000024fb55d0 RCX: 00007fd8d8a5854e [ 16.428583][ T220] RDX: 0000000000000000 RSI: 00007fff65b146a8 RDI: 0000000000000005 [ 16.428584][ T220] RBP: 00007fff65b14620 R08: 0000000000000000 R09: 0000000000000000 [ 16.428585][ T220] R10: 0000000000000000 R11: 0000000000000202 R12: 0000000024fb55a0 [ 16.428586][ T220] R13: 00007fff65b146a8 R14: 00007fff65b14688 R15: 0000000000000000 [ 16.428588][ T220] [ 16.559585][ T220] xl2tpd (220) used greatest stack depth: 21992 bytes left