[ 10.225161][ T180] PPP generic driver version 2.4.2 [ 10.225180][ T180] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.225182][ T180] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 180, name: modprobe [ 10.225183][ T180] preempt_count: 1, expected: 0 [ 10.225184][ T180] RCU nest depth: 0, expected: 0 [ 10.225185][ T180] locks held by modprobe/180: 4, last CPU#2: [ 10.225187][ T180] #0: ffffffffadd79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.225201][ T180] #1: ffffffffadd79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.225205][ T180] #2: ffffffffadc99660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.225209][ T180] #3: ffffffffadc99560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.225213][ T180] irq event stamp: 37360 [ 10.225214][ T180] hardirqs last enabled at (37359): [] __down_trylock_console_sem+0x86/0xa0 [ 10.225217][ T180] hardirqs last disabled at (37360): [] console_emit_next_record+0x3d4/0x4c0 [ 10.225219][ T180] softirqs last enabled at (35414): [] handle_softirqs+0x67c/0x900 [ 10.225222][ T180] softirqs last disabled at (34223): [] __irq_exit_rcu+0x145/0x1c0 [ 10.225224][ T180] Preemption disabled at: [ 10.225225][ T180] [] vprintk_emit+0x31b/0x3e0 [ 10.225231][ T180] CPU: 2 UID: 0 PID: 180 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.225234][ T180] Tainted: [W]=WARN [ 10.225236][ T180] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.225237][ T180] Call Trace: [ 10.225239][ T180] [ 10.225241][ T180] dump_stack_lvl+0x6f/0xa0 [ 10.225246][ T180] ? vprintk_emit+0x31b/0x3e0 [ 10.225248][ T180] __might_resched.cold+0x1fe/0x2c1 [ 10.225253][ T180] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.225257][ T180] ? __kmalloc_noprof+0xdb/0x760 [ 10.225262][ T180] __kmalloc_noprof+0x443/0x760 [ 10.225265][ T180] ? alloc_buf.isra.0+0x4b/0x260 [ 10.225271][ T180] ? do_raw_spin_unlock+0x59/0x250 [ 10.225273][ T180] alloc_buf.isra.0+0x4b/0x260 [ 10.225277][ T180] put_chars+0x1e1/0x2f0 [ 10.225280][ T180] ? __send_to_port+0x420/0x420 [ 10.225282][ T180] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.225287][ T180] ? rcu_read_lock_any_held+0x3c/0x90 [ 10.225290][ T180] ? validate_chain+0x38b/0xc20 [ 10.225294][ T180] hvc_console_print+0x292/0x780 [ 10.225298][ T180] ? __lock_acquire+0x518/0xc20 [ 10.225303][ T180] ? hvc_write+0x3a0/0x3a0 [ 10.225306][ T180] ? rcu_is_watching+0x16/0xd0 [ 10.225311][ T180] console_emit_next_record+0x22f/0x4c0 [ 10.225315][ T180] ? devkmsg_read+0x4b0/0x4b0 [ 10.225317][ T180] ? console_flush_one_record+0x106/0x710 [ 10.225320][ T180] ? rcu_is_watching+0x16/0xd0 [ 10.225322][ T180] ? lock_acquire+0x13c/0x160 [ 10.225326][ T180] console_flush_one_record+0x46f/0x710 [ 10.225330][ T180] ? console_emit_next_record+0x4c0/0x4c0 [ 10.225332][ T180] ? __lock_acquire+0x518/0xc20 [ 10.225337][ T180] console_unlock+0xee/0x1f0 [ 10.225340][ T180] ? console_flush_one_record+0x710/0x710 [ 10.225342][ T180] ? rcu_is_watching+0x16/0xd0 [ 10.225345][ T180] ? lock_acquire+0x60/0x160 [ 10.225348][ T180] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.225350][ T180] ? vprintk_emit+0x320/0x3e0 [ 10.225354][ T180] vprintk_emit+0x37c/0x3e0 [ 10.225358][ T180] ? wake_up_klogd_work_func+0x90/0x90 [ 10.225364][ T180] ? ppp_cleanup+0x920/0x920 [ppp_generic] [ 10.225368][ T180] _printk+0xc7/0x100 [ 10.225372][ T180] ? snapshot_read.cold+0x21/0x21 [ 10.225378][ T180] ? do_one_initcall+0x113/0x4f0 [ 10.225382][ T180] ppp_init+0x16/0x1000 [ppp_generic] [ 10.225385][ T180] do_one_initcall+0x124/0x4f0 [ 10.225388][ T180] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 10.225393][ T180] ? do_init_module+0xae/0x8a0 [ 10.225396][ T180] ? kasan_unpoison+0x44/0x70 [ 10.225401][ T180] do_init_module+0x2ad/0x8a0 [ 10.225404][ T180] ? lockdep_hardirqs_on+0x91/0x130 [ 10.225408][ T180] ? free_module+0x470/0x470 [ 10.225412][ T180] ? kfree+0x123/0x590 [ 10.225416][ T180] load_module+0x1424/0x1bb0 [ 10.225423][ T180] ? layout_and_allocate.isra.0+0x680/0x680 [ 10.225429][ T180] ? backing_file_read_iter+0x541/0x790 [ 10.225432][ T180] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.225436][ T180] init_module_from_file+0x11f/0x1a0 [ 10.225439][ T180] ? __do_sys_init_module+0x220/0x220 [ 10.225448][ T180] ? __rwlock_init+0x150/0x150 [ 10.225451][ T180] ? do_raw_spin_unlock+0x59/0x250 [ 10.225455][ T180] idempotent_init_module+0x37b/0x7c0 [ 10.225458][ T180] ? init_module_from_file+0x1a0/0x1a0 [ 10.225465][ T180] ? rcu_is_watching+0x16/0xd0 [ 10.225467][ T180] ? cap_capable+0x1d7/0x3d0 [ 10.225474][ T180] __x64_sys_finit_module+0xdf/0x190 [ 10.225476][ T180] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 10.225480][ T180] do_syscall_64+0xff/0x530 [ 10.225482][ T180] ? exc_page_fault+0xee/0x100 [ 10.225485][ T180] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.225493][ T180] RIP: 0033:0x7f06cd36c37d [ 10.225496][ T180] Code: ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 4b aa 0f 00 f7 d8 64 89 01 48 [ 10.225498][ T180] RSP: 002b:00007ffea03b2828 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 10.225501][ T180] RAX: ffffffffffffffda RBX: 00005561e65bcd10 RCX: 00007f06cd36c37d [ 10.225502][ T180] RDX: 0000000000000000 RSI: 00005561e3845ffd RDI: 0000000000000006 [ 10.225503][ T180] RBP: 00007ffea03b28c0 R08: 0000000000000000 R09: 0000000000000000 [ 10.225504][ T180] R10: 0000000000000000 R11: 0000000000000246 R12: 00005561e3845ffd [ 10.225505][ T180] R13: 00005561e65bccc0 R14: 0000000000040000 R15: 0000000000000000 [ 10.225512][ T180] [ 10.275096][ T180] NET: Registered PF_PPPOX protocol family [ 10.376728][ T183] ip (183) used greatest stack depth: 24208 bytes left [ 18.402462][ T241] iperf3 (241) used greatest stack depth: 23424 bytes left [ 18.402482][ T241] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 18.402485][ T241] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 241, name: iperf3 [ 18.402487][ T241] preempt_count: 2, expected: 0 [ 18.402487][ T241] RCU nest depth: 0, expected: 0 [ 18.402489][ T241] locks held by iperf3/241: 5, last CPU#1: [ 18.402491][ T241] #0: ffffffffadc127b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 18.402503][ T241] #1: ffffffffadd79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 18.402509][ T241] #2: ffffffffadd79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 18.402513][ T241] #3: ffffffffadc99660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 18.402526][ T241] #4: ffffffffadc99560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 18.402530][ T241] irq event stamp: 1519878 [ 18.402530][ T241] hardirqs last enabled at (1519877): [] __down_trylock_console_sem+0x86/0xa0 [ 18.402533][ T241] hardirqs last disabled at (1519878): [] console_emit_next_record+0x3d4/0x4c0 [ 18.402535][ T241] softirqs last enabled at (1519118): [] handle_softirqs+0x67c/0x900 [ 18.402538][ T241] softirqs last disabled at (1519113): [] __irq_exit_rcu+0x145/0x1c0 [ 18.402540][ T241] Preemption disabled at: [ 18.402541][ T241] [<0000000000000000>] 0x0 [ 18.402548][ T241] CPU: 1 UID: 0 PID: 241 Comm: iperf3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 18.402552][ T241] Tainted: [W]=WARN [ 18.402553][ T241] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 18.402555][ T241] Call Trace: [ 18.402556][ T241] [ 18.402558][ T241] dump_stack_lvl+0x6f/0xa0 [ 18.402564][ T241] __might_resched.cold+0x1fe/0x2c1 [ 18.402569][ T241] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 18.402573][ T241] ? __kmalloc_noprof+0xdb/0x760 [ 18.402579][ T241] __kmalloc_noprof+0x443/0x760 [ 18.402581][ T241] ? alloc_buf.isra.0+0x4b/0x260 [ 18.402587][ T241] ? do_raw_spin_unlock+0x59/0x250 [ 18.402590][ T241] alloc_buf.isra.0+0x4b/0x260 [ 18.402594][ T241] put_chars+0x1e1/0x2f0 [ 18.402596][ T241] ? prb_final_commit+0x50/0x50 [ 18.402598][ T241] ? __send_to_port+0x420/0x420 [ 18.402602][ T241] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 18.402606][ T241] ? rcu_read_lock_any_held+0x3c/0x90 [ 18.402609][ T241] ? validate_chain+0x38b/0xc20 [ 18.402612][ T241] hvc_console_print+0x292/0x780 [ 18.402616][ T241] ? mark_usage+0x61/0x170 [ 18.402618][ T241] ? __lock_acquire+0x518/0xc20 [ 18.402619][ T241] ? __lock_acquire+0x518/0xc20 [ 18.402623][ T241] ? hvc_write+0x3a0/0x3a0 [ 18.402626][ T241] ? console_emit_next_record+0x1df/0x4c0 [ 18.402629][ T241] ? rcu_is_watching+0x16/0xd0 [ 18.402632][ T241] ? lock_acquire+0x13c/0x160 [ 18.402636][ T241] console_emit_next_record+0x22f/0x4c0 [ 18.402639][ T241] ? devkmsg_read+0x4b0/0x4b0 [ 18.402641][ T241] ? console_flush_one_record+0x106/0x710 [ 18.402645][ T241] ? rcu_is_watching+0x16/0xd0 [ 18.402647][ T241] ? lock_acquire+0x13c/0x160 [ 18.402651][ T241] console_flush_one_record+0x46f/0x710 [ 18.402656][ T241] ? console_emit_next_record+0x4c0/0x4c0 [ 18.402657][ T241] ? __lock_acquire+0x518/0xc20 [ 18.402663][ T241] console_unlock+0xee/0x1f0 [ 18.402666][ T241] ? console_flush_one_record+0x710/0x710 [ 18.402668][ T241] ? rcu_is_watching+0x16/0xd0 [ 18.402670][ T241] ? lock_acquire+0x60/0x160 [ 18.402674][ T241] ? __down_trylock_console_sem+0x5e/0xa0 [ 18.402676][ T241] ? vprintk_emit+0x320/0x3e0 [ 18.402679][ T241] vprintk_emit+0x37c/0x3e0 [ 18.402683][ T241] ? wake_up_klogd_work_func+0x90/0x90 [ 18.402687][ T241] ? __lock_acquire+0x518/0xc20 [ 18.402690][ T241] _printk+0xc7/0x100 [ 18.402694][ T241] ? snapshot_read.cold+0x21/0x21 [ 18.402697][ T241] ? do_raw_spin_lock+0x131/0x280 [ 18.402699][ T241] ? __rwlock_init+0x150/0x150 [ 18.402703][ T241] ? do_raw_spin_lock+0x131/0x280 [ 18.402706][ T241] do_exit.cold+0x82/0x9c [ 18.402710][ T241] ? exit_notify+0x890/0x890 [ 18.402715][ T241] __x64_sys_exit+0x42/0x50 [ 18.402717][ T241] x64_sys_call+0x156c/0x1570 [ 18.402720][ T241] do_syscall_64+0xff/0x530 [ 18.402724][ T241] ? exc_page_fault+0xee/0x100 [ 18.402726][ T241] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 18.402729][ T241] RIP: 0033:0x7f0165b08c49 [ 18.402731][ T241] Code: Unable to access opcode bytes at 0x7f0165b08c1f. [ 18.402732][ T241] RSP: 002b:00007f01652c7e60 EFLAGS: 00000246 ORIG_RAX: 000000000000003c [ 18.402735][ T241] RAX: ffffffffffffffda RBX: 00007f01652c86c0 RCX: 00007f0165b08c49 [ 18.402736][ T241] RDX: 0000000000000000 RSI: 0000000000801000 RDI: 0000000000000000 [ 18.402737][ T241] RBP: 00007f01652c7f70 R08: 0000000000000001 R09: 00007f01652c86c0 [ 18.402738][ T241] R10: 0000000000000008 R11: 0000000000000246 R12: 00007f0164ac8000 [ 18.402739][ T241] R13: 00007fff2e8895f0 R14: 0000000000000002 R15: 00007fff2e8896f7 [ 18.402746][ T241]