[ 10.730087][ T180] PPP generic driver version 2.4.2 [ 10.730104][ T180] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.730106][ T180] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 180, name: modprobe [ 10.730107][ T180] preempt_count: 1, expected: 0 [ 10.730108][ T180] RCU nest depth: 0, expected: 0 [ 10.730109][ T180] locks held by modprobe/180: 4, last CPU#0: [ 10.730112][ T180] #0: ffffffffa1b79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.730126][ T180] #1: ffffffffa1b79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.730130][ T180] #2: ffffffffa1a99660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.730135][ T180] #3: ffffffffa1a99560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.730138][ T180] irq event stamp: 42192 [ 10.730139][ T180] hardirqs last enabled at (42191): [] __down_trylock_console_sem+0x86/0xa0 [ 10.730142][ T180] hardirqs last disabled at (42192): [] console_emit_next_record+0x3d4/0x4c0 [ 10.730144][ T180] softirqs last enabled at (40286): [] handle_softirqs+0x67c/0x900 [ 10.730148][ T180] softirqs last disabled at (39407): [] __irq_exit_rcu+0x145/0x1c0 [ 10.730150][ T180] Preemption disabled at: [ 10.730151][ T180] [] vprintk_emit+0x31b/0x3e0 [ 10.730157][ T180] CPU: 0 UID: 0 PID: 180 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.730160][ T180] Tainted: [W]=WARN [ 10.730161][ T180] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.730163][ T180] Call Trace: [ 10.730165][ T180] [ 10.730166][ T180] dump_stack_lvl+0x6f/0xa0 [ 10.730172][ T180] ? vprintk_emit+0x31b/0x3e0 [ 10.730174][ T180] __might_resched.cold+0x1fe/0x2c1 [ 10.730179][ T180] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.730183][ T180] ? __kmalloc_noprof+0xdb/0x760 [ 10.730188][ T180] __kmalloc_noprof+0x443/0x760 [ 10.730190][ T180] ? alloc_buf.isra.0+0x4b/0x260 [ 10.730196][ T180] ? do_raw_spin_unlock+0x59/0x250 [ 10.730199][ T180] alloc_buf.isra.0+0x4b/0x260 [ 10.730202][ T180] put_chars+0x1e1/0x2f0 [ 10.730205][ T180] ? __send_to_port+0x420/0x420 [ 10.730207][ T180] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.730212][ T180] ? rcu_read_lock_any_held+0x3c/0x90 [ 10.730214][ T180] ? validate_chain+0x38b/0xc20 [ 10.730219][ T180] hvc_console_print+0x292/0x780 [ 10.730222][ T180] ? __lock_acquire+0x518/0xc20 [ 10.730227][ T180] ? hvc_write+0x3a0/0x3a0 [ 10.730230][ T180] ? rcu_is_watching+0x16/0xd0 [ 10.730236][ T180] console_emit_next_record+0x22f/0x4c0 [ 10.730241][ T180] ? devkmsg_read+0x4b0/0x4b0 [ 10.730243][ T180] ? console_flush_one_record+0x106/0x710 [ 10.730246][ T180] ? rcu_is_watching+0x16/0xd0 [ 10.730249][ T180] ? lock_acquire+0x13c/0x160 [ 10.730252][ T180] console_flush_one_record+0x46f/0x710 [ 10.730257][ T180] ? console_emit_next_record+0x4c0/0x4c0 [ 10.730259][ T180] ? __lock_acquire+0x518/0xc20 [ 10.730264][ T180] console_unlock+0xee/0x1f0 [ 10.730267][ T180] ? console_flush_one_record+0x710/0x710 [ 10.730268][ T180] ? rcu_is_watching+0x16/0xd0 [ 10.730271][ T180] ? lock_acquire+0x60/0x160 [ 10.730274][ T180] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.730276][ T180] ? vprintk_emit+0x320/0x3e0 [ 10.730280][ T180] vprintk_emit+0x37c/0x3e0 [ 10.730284][ T180] ? wake_up_klogd_work_func+0x90/0x90 [ 10.730289][ T180] ? ppp_cleanup+0x920/0x920 [ppp_generic] [ 10.730294][ T180] _printk+0xc7/0x100 [ 10.730297][ T180] ? snapshot_read.cold+0x21/0x21 [ 10.730303][ T180] ? do_one_initcall+0x113/0x4f0 [ 10.730307][ T180] ppp_init+0x16/0x1000 [ppp_generic] [ 10.730310][ T180] do_one_initcall+0x124/0x4f0 [ 10.730313][ T180] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 10.730318][ T180] ? do_init_module+0xae/0x8a0 [ 10.730321][ T180] ? kasan_unpoison+0x44/0x70 [ 10.730325][ T180] do_init_module+0x2ad/0x8a0 [ 10.730329][ T180] ? lockdep_hardirqs_on+0x91/0x130 [ 10.730333][ T180] ? free_module+0x470/0x470 [ 10.730336][ T180] ? kfree+0x123/0x590 [ 10.730340][ T180] load_module+0x1424/0x1bb0 [ 10.730347][ T180] ? layout_and_allocate.isra.0+0x680/0x680 [ 10.730353][ T180] ? backing_file_read_iter+0x541/0x790 [ 10.730356][ T180] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.730360][ T180] init_module_from_file+0x11f/0x1a0 [ 10.730363][ T180] ? __do_sys_init_module+0x220/0x220 [ 10.730371][ T180] ? __rwlock_init+0x150/0x150 [ 10.730375][ T180] ? do_raw_spin_unlock+0x59/0x250 [ 10.730378][ T180] idempotent_init_module+0x37b/0x7c0 [ 10.730381][ T180] ? init_module_from_file+0x1a0/0x1a0 [ 10.730387][ T180] ? rcu_is_watching+0x16/0xd0 [ 10.730390][ T180] ? cap_capable+0x1d7/0x3d0 [ 10.730396][ T180] __x64_sys_finit_module+0xdf/0x190 [ 10.730399][ T180] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 10.730408][ T180] do_syscall_64+0xff/0x530 [ 10.730410][ T180] ? exc_page_fault+0xee/0x100 [ 10.730413][ T180] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.730415][ T180] RIP: 0033:0x7f185b38237d [ 10.730419][ 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.730421][ T180] RSP: 002b:00007ffc9258eca8 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 10.730423][ T180] RAX: ffffffffffffffda RBX: 0000557b7ac47d10 RCX: 00007f185b38237d [ 10.730425][ T180] RDX: 0000000000000000 RSI: 0000557b69da0ffd RDI: 0000000000000006 [ 10.730426][ T180] RBP: 00007ffc9258ed40 R08: 0000000000000000 R09: 0000000000000000 [ 10.730426][ T180] R10: 0000000000000000 R11: 0000000000000246 R12: 0000557b69da0ffd [ 10.730427][ T180] R13: 0000557b7ac47cc0 R14: 0000000000040000 R15: 0000000000000000 [ 10.730434][ T180] [ 10.766438][ T180] NET: Registered PF_PPPOX protocol family [ 10.907621][ T183] ip (183) used greatest stack depth: 23696 bytes left [ 18.931094][ T235] iperf3 (235) used greatest stack depth: 23056 bytes left [ 18.931121][ T235] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 18.931123][ T235] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 235, name: iperf3 [ 18.931126][ T235] preempt_count: 2, expected: 0 [ 18.931127][ T235] RCU nest depth: 0, expected: 0 [ 18.931129][ T235] locks held by iperf3/235: 5, last CPU#2: [ 18.931132][ T235] #0: ffffffffa1a127b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 18.931146][ T235] #1: ffffffffa1b79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 18.931155][ T235] #2: ffffffffa1b79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 18.931161][ T235] #3: ffffffffa1a99660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 18.931167][ T235] #4: ffffffffa1a99560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 18.931173][ T235] irq event stamp: 1178868 [ 18.931174][ T235] hardirqs last enabled at (1178867): [] __down_trylock_console_sem+0x86/0xa0 [ 18.931178][ T235] hardirqs last disabled at (1178868): [] console_emit_next_record+0x3d4/0x4c0 [ 18.931181][ T235] softirqs last enabled at (1178316): [] handle_softirqs+0x67c/0x900 [ 18.931184][ T235] softirqs last disabled at (1177415): [] __irq_exit_rcu+0x145/0x1c0 [ 18.931187][ T235] Preemption disabled at: [ 18.931188][ T235] [<0000000000000000>] 0x0 [ 18.931197][ T235] CPU: 2 UID: 0 PID: 235 Comm: iperf3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 18.931201][ T235] Tainted: [W]=WARN [ 18.931203][ T235] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 18.931205][ T235] Call Trace: [ 18.931207][ T235] [ 18.931209][ T235] dump_stack_lvl+0x6f/0xa0 [ 18.931217][ T235] __might_resched.cold+0x1fe/0x2c1 [ 18.931224][ T235] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 18.931230][ T235] ? __kmalloc_noprof+0xdb/0x760 [ 18.931238][ T235] __kmalloc_noprof+0x443/0x760 [ 18.931242][ T235] ? alloc_buf.isra.0+0x4b/0x260 [ 18.931252][ T235] ? do_raw_spin_unlock+0x59/0x250 [ 18.931256][ T235] alloc_buf.isra.0+0x4b/0x260 [ 18.931261][ T235] put_chars+0x1e1/0x2f0 [ 18.931264][ T235] ? prb_final_commit+0x50/0x50 [ 18.931268][ T235] ? __send_to_port+0x420/0x420 [ 18.931273][ T235] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 18.931280][ T235] ? rcu_read_lock_any_held+0x3c/0x90 [ 18.931283][ T235] ? validate_chain+0x38b/0xc20 [ 18.931289][ T235] hvc_console_print+0x292/0x780 [ 18.931294][ T235] ? mark_usage+0x61/0x170 [ 18.931297][ T235] ? __lock_acquire+0x518/0xc20 [ 18.931299][ T235] ? __lock_acquire+0x518/0xc20 [ 18.931306][ T235] ? hvc_write+0x3a0/0x3a0 [ 18.931309][ T235] ? console_emit_next_record+0x1df/0x4c0 [ 18.931314][ T235] ? rcu_is_watching+0x16/0xd0 [ 18.931318][ T235] ? lock_acquire+0x13c/0x160 [ 18.931325][ T235] console_emit_next_record+0x22f/0x4c0 [ 18.931331][ T235] ? devkmsg_read+0x4b0/0x4b0 [ 18.931334][ T235] ? console_flush_one_record+0x106/0x710 [ 18.931339][ T235] ? rcu_is_watching+0x16/0xd0 [ 18.931343][ T235] ? lock_acquire+0x13c/0x160 [ 18.931350][ T235] console_flush_one_record+0x46f/0x710 [ 18.931357][ T235] ? console_emit_next_record+0x4c0/0x4c0 [ 18.931360][ T235] ? __lock_acquire+0x518/0xc20 [ 18.931369][ T235] console_unlock+0xee/0x1f0 [ 18.931373][ T235] ? console_flush_one_record+0x710/0x710 [ 18.931376][ T235] ? rcu_is_watching+0x16/0xd0 [ 18.931379][ T235] ? lock_acquire+0x60/0x160 [ 18.931386][ T235] ? __down_trylock_console_sem+0x5e/0xa0 [ 18.931388][ T235] ? vprintk_emit+0x320/0x3e0 [ 18.931394][ T235] vprintk_emit+0x37c/0x3e0 [ 18.931400][ T235] ? wake_up_klogd_work_func+0x90/0x90 [ 18.931405][ T235] ? __lock_acquire+0x518/0xc20 [ 18.931412][ T235] _printk+0xc7/0x100 [ 18.931416][ T235] ? snapshot_read.cold+0x21/0x21 [ 18.931421][ T235] ? do_raw_spin_lock+0x131/0x280 [ 18.931425][ T235] ? __rwlock_init+0x150/0x150 [ 18.931436][ T235] ? do_raw_spin_lock+0x131/0x280 [ 18.931441][ T235] do_exit.cold+0x82/0x9c [ 18.931446][ T235] ? exit_notify+0x890/0x890 [ 18.931456][ T235] __x64_sys_exit+0x42/0x50 [ 18.931459][ T235] x64_sys_call+0x156c/0x1570 [ 18.931463][ T235] do_syscall_64+0xff/0x530 [ 18.931468][ T235] ? exc_page_fault+0xee/0x100 [ 18.931472][ T235] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 18.931476][ T235] RIP: 0033:0x7fc8cd504c49 [ 18.931479][ T235] Code: Unable to access opcode bytes at 0x7fc8cd504c1f. [ 18.931481][ T235] RSP: 002b:00007fc8cccc3e60 EFLAGS: 00000246 ORIG_RAX: 000000000000003c [ 18.931485][ T235] RAX: ffffffffffffffda RBX: 00007fc8cccc46c0 RCX: 00007fc8cd504c49 [ 18.931487][ T235] RDX: 0000000000000000 RSI: 0000000000801000 RDI: 0000000000000000 [ 18.931488][ T235] RBP: 00007fc8cccc3f70 R08: 0000000000000001 R09: 00007fc8cccc46c0 [ 18.931490][ T235] R10: 0000000000000008 R11: 0000000000000246 R12: 00007fc8cc4c4000 [ 18.931491][ T235] R13: 00007ffe29961880 R14: 0000000000000002 R15: 00007ffe29961987 [ 18.931504][ T235]