[ 9.255841][ T174] tun: Universal TUN/TAP device driver, 1.6 [ 9.255860][ T174] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.255862][ T174] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 174, name: modprobe [ 9.255863][ T174] preempt_count: 1, expected: 0 [ 9.255864][ T174] RCU nest depth: 0, expected: 0 [ 9.255865][ T174] locks held by modprobe/174: 4, last CPU#2: [ 9.255867][ T174] #0: ffffffffaa76a440 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.255879][ T174] #1: ffffffffaa76a4b8 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.255883][ T174] #2: ffffffffaa689de0 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.255886][ T174] #3: ffffffffaa689ce0 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.255889][ T174] irq event stamp: 35418 [ 9.255890][ T174] hardirqs last enabled at (35417): [] __down_trylock_console_sem+0x86/0xa0 [ 9.255893][ T174] hardirqs last disabled at (35418): [] console_emit_next_record+0x3d4/0x4c0 [ 9.255895][ T174] softirqs last enabled at (33128): [] handle_softirqs+0x67c/0x900 [ 9.255898][ T174] softirqs last disabled at (32587): [] __irq_exit_rcu+0x145/0x1c0 [ 9.255901][ T174] Preemption disabled at: [ 9.255902][ T174] [] vprintk_emit+0x31b/0x3e0 [ 9.255907][ T174] CPU: 2 UID: 0 PID: 174 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.255911][ T174] Tainted: [W]=WARN [ 9.255912][ T174] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.255913][ T174] Call Trace: [ 9.255915][ T174] [ 9.255916][ T174] dump_stack_lvl+0x6f/0xa0 [ 9.255921][ T174] ? vprintk_emit+0x31b/0x3e0 [ 9.255923][ T174] __might_resched.cold+0x1fe/0x2c1 [ 9.255927][ T174] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.255931][ T174] ? __kmalloc_noprof+0xdb/0x760 [ 9.255936][ T174] __kmalloc_noprof+0x443/0x760 [ 9.255938][ T174] ? alloc_buf.isra.0+0x4b/0x260 [ 9.255944][ T174] ? do_raw_spin_unlock+0x59/0x250 [ 9.255947][ T174] alloc_buf.isra.0+0x4b/0x260 [ 9.255951][ T174] put_chars+0x1e1/0x2f0 [ 9.255953][ T174] ? __send_to_port+0x420/0x420 [ 9.255957][ T174] ? validate_chain+0x38b/0xc20 [ 9.255962][ T174] hvc_console_print+0x292/0x780 [ 9.255969][ T174] ? hvc_write+0x3a0/0x3a0 [ 9.255971][ T174] ? rcu_is_watching+0x16/0xd0 [ 9.255974][ T174] ? lock_acquire+0x13c/0x160 [ 9.255978][ T174] console_emit_next_record+0x22f/0x4c0 [ 9.255982][ T174] ? devkmsg_read+0x4b0/0x4b0 [ 9.255983][ T174] ? console_flush_one_record+0x106/0x710 [ 9.255986][ T174] ? rcu_is_watching+0x16/0xd0 [ 9.255987][ T174] ? lock_acquire+0x13c/0x160 [ 9.255992][ T174] console_flush_one_record+0x46f/0x710 [ 9.255995][ T174] ? console_emit_next_record+0x4c0/0x4c0 [ 9.255997][ T174] ? __lock_acquire+0x518/0xc20 [ 9.256002][ T174] console_unlock+0xee/0x1f0 [ 9.256004][ T174] ? console_flush_one_record+0x710/0x710 [ 9.256005][ T174] ? rcu_is_watching+0x16/0xd0 [ 9.256007][ T174] ? lock_acquire+0x60/0x160 [ 9.256011][ T174] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.256013][ T174] ? vprintk_emit+0x320/0x3e0 [ 9.256016][ T174] vprintk_emit+0x37c/0x3e0 [ 9.256019][ T174] ? wake_up_klogd_work_func+0x90/0x90 [ 9.256024][ T174] ? tun_cleanup+0x630/0x630 [tun] [ 9.256028][ T174] _printk+0xc7/0x100 [ 9.256032][ T174] ? snapshot_read.cold+0x21/0x21 [ 9.256038][ T174] ? do_one_initcall+0x113/0x4f0 [ 9.256042][ T174] tun_init+0x24/0x1000 [tun] [ 9.256045][ T174] do_one_initcall+0x124/0x4f0 [ 9.256048][ T174] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 9.256052][ T174] ? do_init_module+0xae/0x8a0 [ 9.256055][ T174] ? kasan_unpoison+0x44/0x70 [ 9.256060][ T174] do_init_module+0x2ad/0x8a0 [ 9.256063][ T174] ? lockdep_hardirqs_on+0x91/0x130 [ 9.256067][ T174] ? free_module+0x470/0x470 [ 9.256070][ T174] ? kfree+0x123/0x590 [ 9.256074][ T174] load_module+0x154b/0x1d60 [ 9.256080][ T174] ? layout_and_allocate.isra.0+0x680/0x680 [ 9.256088][ T174] init_module_from_file+0x11f/0x1a0 [ 9.256090][ T174] ? __ia32_sys_init_module+0xc0/0xc0 [ 9.256098][ T174] ? __rwlock_init+0x150/0x150 [ 9.256102][ T174] ? do_raw_spin_unlock+0x59/0x250 [ 9.256105][ T174] idempotent_init_module+0x37b/0x7c0 [ 9.256108][ T174] ? init_module_from_file+0x1a0/0x1a0 [ 9.256113][ T174] ? rcu_is_watching+0x16/0xd0 [ 9.256115][ T174] ? cap_capable+0x1d7/0x3d0 [ 9.256125][ T174] __x64_sys_finit_module+0xdf/0x190 [ 9.256126][ T174] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 9.256130][ T174] do_syscall_64+0x10e/0x570 [ 9.256132][ T174] ? exc_page_fault+0xee/0x100 [ 9.256135][ T174] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.256138][ T174] RIP: 0033:0x7fa44b2e637d [ 9.256141][ T174] Code: ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 4b aa 0f 00 f7 d8 64 89 01 48 [ 9.256143][ T174] RSP: 002b:00007ffee72e64b8 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 9.256145][ T174] RAX: ffffffffffffffda RBX: 000055d8aae8a980 RCX: 00007fa44b2e637d [ 9.256147][ T174] RDX: 0000000000000000 RSI: 000055d89273bffd RDI: 0000000000000005 [ 9.256147][ T174] RBP: 00007ffee72e6550 R08: 0000000000000000 R09: 0000000000000000 [ 9.256148][ T174] R10: 0000000000000000 R11: 0000000000000246 R12: 000055d89273bffd [ 9.256149][ T174] R13: 000055d8aae8aaa0 R14: 0000000000040000 R15: 0000000000000000 [ 9.256156][ T174] [ 10.297396][ T184] packetdrill (184) used greatest stack depth: 24248 bytes left [ 10.297411][ T184] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.297413][ T184] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 184, name: packetdrill [ 10.297415][ T184] preempt_count: 2, expected: 0 [ 10.297415][ T184] RCU nest depth: 0, expected: 0 [ 10.297416][ T184] locks held by packetdrill/184: 5, last CPU#3: [ 10.297419][ T184] #0: ffffffffaa602f58 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x8e7/0xef0 [ 10.297430][ T184] #1: ffffffffaa76a440 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.297435][ T184] #2: ffffffffaa76a4b8 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.297438][ T184] #3: ffffffffaa689de0 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.297442][ T184] #4: ffffffffaa689ce0 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.297445][ T184] irq event stamp: 3958272 [ 10.297446][ T184] hardirqs last enabled at (3958271): [] __down_trylock_console_sem+0x86/0xa0 [ 10.297449][ T184] hardirqs last disabled at (3958272): [] console_emit_next_record+0x3d4/0x4c0 [ 10.297451][ T184] softirqs last enabled at (3958046): [] tcp_close+0x2b/0xb0 [ 10.297454][ T184] softirqs last disabled at (3958044): [] release_sock+0x21/0x240 [ 10.297458][ T184] Preemption disabled at: [ 10.297459][ T184] [<0000000000000000>] 0x0 [ 10.297465][ T184] CPU: 3 UID: 0 PID: 184 Comm: packetdrill Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.297469][ T184] Tainted: [W]=WARN [ 10.297470][ T184] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.297471][ T184] Call Trace: [ 10.297473][ T184] [ 10.297475][ T184] dump_stack_lvl+0x6f/0xa0 [ 10.297480][ T184] __might_resched.cold+0x1fe/0x2c1 [ 10.297485][ T184] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.297488][ T184] ? __kmalloc_noprof+0xdb/0x760 [ 10.297494][ T184] __kmalloc_noprof+0x443/0x760 [ 10.297496][ T184] ? alloc_buf.isra.0+0x4b/0x260 [ 10.297502][ T184] ? do_raw_spin_unlock+0x59/0x250 [ 10.297505][ T184] alloc_buf.isra.0+0x4b/0x260 [ 10.297508][ T184] put_chars+0x1e1/0x2f0 [ 10.297511][ T184] ? __send_to_port+0x420/0x420 [ 10.297518][ T184] hvc_console_print+0x292/0x780 [ 10.297525][ T184] ? hvc_write+0x3a0/0x3a0 [ 10.297528][ T184] ? lock_acquire+0x13c/0x160 [ 10.297532][ T184] console_emit_next_record+0x22f/0x4c0 [ 10.297536][ T184] ? devkmsg_read+0x4b0/0x4b0 [ 10.297537][ T184] ? console_flush_one_record+0x106/0x710 [ 10.297540][ T184] ? rcu_is_watching+0x16/0xd0 [ 10.297543][ T184] ? lock_acquire+0x13c/0x160 [ 10.297547][ T184] console_flush_one_record+0x46f/0x710 [ 10.297551][ T184] ? console_emit_next_record+0x4c0/0x4c0 [ 10.297552][ T184] ? __lock_acquire+0x518/0xc20 [ 10.297558][ T184] console_unlock+0xee/0x1f0 [ 10.297560][ T184] ? console_flush_one_record+0x710/0x710 [ 10.297561][ T184] ? rcu_is_watching+0x16/0xd0 [ 10.297563][ T184] ? lock_acquire+0x60/0x160 [ 10.297567][ T184] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.297569][ T184] ? vprintk_emit+0x320/0x3e0 [ 10.297575][ T184] vprintk_emit+0x37c/0x3e0 [ 10.297578][ T184] ? wake_up_klogd_work_func+0x90/0x90 [ 10.297581][ T184] ? __lock_acquire+0x518/0xc20 [ 10.297585][ T184] _printk+0xc7/0x100 [ 10.297589][ T184] ? snapshot_read.cold+0x21/0x21 [ 10.297592][ T184] ? do_raw_spin_lock+0x131/0x280 [ 10.297594][ T184] ? __rwlock_init+0x150/0x150 [ 10.297598][ T184] ? do_raw_spin_lock+0x131/0x280 [ 10.297601][ T184] do_exit.cold+0x82/0x9c [ 10.297605][ T184] ? exit_notify+0x890/0x890 [ 10.297607][ T184] ? __lock_release.isra.0+0x69/0x1a0 [ 10.297609][ T184] ? rcu_is_watching+0x16/0xd0 [ 10.297613][ T184] do_group_exit+0xb8/0x370 [ 10.297616][ T184] __x64_sys_exit_group+0x3c/0x50 [ 10.297618][ T184] x64_sys_call+0x1567/0x1570 [ 10.297621][ T184] do_syscall_64+0x10e/0x570 [ 10.297624][ T184] ? irq_exit_rcu+0x1a/0x30 [ 10.297627][ T184] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.297629][ T184] RIP: 0033:0x479678 [ 10.297632][ T184] Code: Unable to access opcode bytes at 0x47964e. [ 10.297633][ T184] RSP: 002b:00007ffee1198f98 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 10.297635][ T184] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 0000000000479678 [ 10.297636][ T184] RDX: 000000001e45a708 RSI: fffffffffffffff0 RDI: 0000000000000000 [ 10.297637][ T184] RBP: 00007ffee1198ff0 R08: 0000000000000000 R09: 000000001e45b450 [ 10.297638][ T184] R10: 0000000000000110 R11: 0000000000000246 R12: 00007ffee1199128 [ 10.297639][ T184] R13: 00007ffee1199168 R14: 0000000000000000 R15: 000000000054d560 [ 10.297646][ T184] [ 10.971585][ T210] packetdrill (210) used greatest stack depth: 23520 bytes left