[ 9.861452][ T174] tun: Universal TUN/TAP device driver, 1.6 [ 9.861472][ T174] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.861474][ T174] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 174, name: modprobe [ 9.861476][ T174] preempt_count: 1, expected: 0 [ 9.861477][ T174] RCU nest depth: 0, expected: 0 [ 9.861478][ T174] locks held by modprobe/174: 4, last CPU#2: [ 9.861481][ T174] #0: ffffffff9476a440 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.861493][ T174] #1: ffffffff9476a4b8 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.861497][ T174] #2: ffffffff94689de0 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.861500][ T174] #3: ffffffff94689ce0 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.861504][ T174] irq event stamp: 34966 [ 9.861505][ T174] hardirqs last enabled at (34965): [] __down_trylock_console_sem+0x86/0xa0 [ 9.861509][ T174] hardirqs last disabled at (34966): [] console_emit_next_record+0x3d4/0x4c0 [ 9.861510][ T174] softirqs last enabled at (31736): [] handle_softirqs+0x67c/0x900 [ 9.861514][ T174] softirqs last disabled at (31729): [] __irq_exit_rcu+0x145/0x1c0 [ 9.861517][ T174] Preemption disabled at: [ 9.861517][ T174] [] vprintk_emit+0x31b/0x3e0 [ 9.861522][ T174] CPU: 2 UID: 0 PID: 174 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.861526][ T174] Tainted: [W]=WARN [ 9.861527][ T174] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.861528][ T174] Call Trace: [ 9.861530][ T174] [ 9.861532][ T174] dump_stack_lvl+0x6f/0xa0 [ 9.861537][ T174] ? vprintk_emit+0x31b/0x3e0 [ 9.861538][ T174] __might_resched.cold+0x1fe/0x2c1 [ 9.861543][ T174] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.861546][ T174] ? __kmalloc_noprof+0xdb/0x760 [ 9.861551][ T174] __kmalloc_noprof+0x443/0x760 [ 9.861554][ T174] ? alloc_buf.isra.0+0x4b/0x260 [ 9.861560][ T174] ? do_raw_spin_unlock+0x59/0x250 [ 9.861563][ T174] alloc_buf.isra.0+0x4b/0x260 [ 9.861566][ T174] put_chars+0x1e1/0x2f0 [ 9.861569][ T174] ? __send_to_port+0x420/0x420 [ 9.861572][ T174] ? validate_chain+0x38b/0xc20 [ 9.861578][ T174] hvc_console_print+0x292/0x780 [ 9.861585][ T174] ? hvc_write+0x3a0/0x3a0 [ 9.861587][ T174] ? rcu_is_watching+0x16/0xd0 [ 9.861591][ T174] ? lock_acquire+0x13c/0x160 [ 9.861595][ T174] console_emit_next_record+0x22f/0x4c0 [ 9.861598][ T174] ? devkmsg_read+0x4b0/0x4b0 [ 9.861600][ T174] ? console_flush_one_record+0x106/0x710 [ 9.861602][ T174] ? rcu_is_watching+0x16/0xd0 [ 9.861604][ T174] ? lock_acquire+0x13c/0x160 [ 9.861608][ T174] console_flush_one_record+0x46f/0x710 [ 9.861612][ T174] ? console_emit_next_record+0x4c0/0x4c0 [ 9.861613][ T174] ? __lock_acquire+0x518/0xc20 [ 9.861621][ T174] console_unlock+0xee/0x1f0 [ 9.861623][ T174] ? console_flush_one_record+0x710/0x710 [ 9.861625][ T174] ? rcu_is_watching+0x16/0xd0 [ 9.861627][ T174] ? lock_acquire+0x60/0x160 [ 9.861631][ T174] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.861633][ T174] ? vprintk_emit+0x320/0x3e0 [ 9.861636][ T174] vprintk_emit+0x37c/0x3e0 [ 9.861639][ T174] ? wake_up_klogd_work_func+0x90/0x90 [ 9.861644][ T174] ? tun_cleanup+0x630/0x630 [tun] [ 9.861649][ T174] _printk+0xc7/0x100 [ 9.861653][ T174] ? snapshot_read.cold+0x21/0x21 [ 9.861659][ T174] ? do_one_initcall+0x113/0x4f0 [ 9.861663][ T174] tun_init+0x24/0x1000 [tun] [ 9.861666][ T174] do_one_initcall+0x124/0x4f0 [ 9.861670][ T174] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 9.861674][ T174] ? do_init_module+0xae/0x8a0 [ 9.861677][ T174] ? kasan_unpoison+0x44/0x70 [ 9.861682][ T174] do_init_module+0x2ad/0x8a0 [ 9.861685][ T174] ? lockdep_hardirqs_on+0x91/0x130 [ 9.861690][ T174] ? free_module+0x470/0x470 [ 9.861693][ T174] ? kfree+0x123/0x590 [ 9.861697][ T174] load_module+0x154b/0x1d60 [ 9.861704][ T174] ? layout_and_allocate.isra.0+0x680/0x680 [ 9.861711][ T174] init_module_from_file+0x11f/0x1a0 [ 9.861714][ T174] ? __ia32_sys_init_module+0xc0/0xc0 [ 9.861722][ T174] ? __rwlock_init+0x150/0x150 [ 9.861726][ T174] ? do_raw_spin_unlock+0x59/0x250 [ 9.861729][ T174] idempotent_init_module+0x37b/0x7c0 [ 9.861732][ T174] ? init_module_from_file+0x1a0/0x1a0 [ 9.861738][ T174] ? rcu_is_watching+0x16/0xd0 [ 9.861740][ T174] ? cap_capable+0x1d7/0x3d0 [ 9.861745][ T174] __x64_sys_finit_module+0xdf/0x190 [ 9.861747][ T174] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 9.861751][ T174] do_syscall_64+0x10e/0x570 [ 9.861753][ T174] ? exc_page_fault+0xee/0x100 [ 9.861756][ T174] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.861758][ T174] RIP: 0033:0x7fdde46aa37d [ 9.861762][ 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.861764][ T174] RSP: 002b:00007ffca12cf628 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 9.861766][ T174] RAX: ffffffffffffffda RBX: 000055c416f7e980 RCX: 00007fdde46aa37d [ 9.861767][ T174] RDX: 0000000000000000 RSI: 000055c3dda66ffd RDI: 0000000000000005 [ 9.861768][ T174] RBP: 00007ffca12cf6c0 R08: 0000000000000000 R09: 0000000000000000 [ 9.861769][ T174] R10: 0000000000000000 R11: 0000000000000246 R12: 000055c3dda66ffd [ 9.861770][ T174] R13: 000055c416f7eaa0 R14: 0000000000040000 R15: 0000000000000000 [ 9.861777][ T174] [ 11.180563][ T185] packetdrill (185) used greatest stack depth: 23880 bytes left [ 11.180579][ T185] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.180581][ T185] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 185, name: packetdrill [ 11.180583][ T185] preempt_count: 2, expected: 0 [ 11.180584][ T185] RCU nest depth: 0, expected: 0 [ 11.180584][ T185] locks held by packetdrill/185: 5, last CPU#2: [ 11.180587][ T185] #0: ffffffff94602f58 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x8e7/0xef0 [ 11.180599][ T185] #1: ffffffff9476a440 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.180604][ T185] #2: ffffffff9476a4b8 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.180607][ T185] #3: ffffffff94689de0 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.180611][ T185] #4: ffffffff94689ce0 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.180614][ T185] irq event stamp: 3886918 [ 11.180618][ T185] hardirqs last enabled at (3886917): [] __down_trylock_console_sem+0x86/0xa0 [ 11.180622][ T185] hardirqs last disabled at (3886918): [] console_emit_next_record+0x3d4/0x4c0 [ 11.180623][ T185] softirqs last enabled at (3886694): [] tcp_close+0x2b/0xb0 [ 11.180627][ T185] softirqs last disabled at (3886692): [] release_sock+0x21/0x240 [ 11.180631][ T185] Preemption disabled at: [ 11.180632][ T185] [<0000000000000000>] 0x0 [ 11.180639][ T185] CPU: 2 UID: 0 PID: 185 Comm: packetdrill Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.180643][ T185] Tainted: [W]=WARN [ 11.180644][ T185] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.180646][ T185] Call Trace: [ 11.180647][ T185] [ 11.180649][ T185] dump_stack_lvl+0x6f/0xa0 [ 11.180654][ T185] __might_resched.cold+0x1fe/0x2c1 [ 11.180659][ T185] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.180662][ T185] ? __kmalloc_noprof+0xdb/0x760 [ 11.180668][ T185] __kmalloc_noprof+0x443/0x760 [ 11.180670][ T185] ? alloc_buf.isra.0+0x4b/0x260 [ 11.180677][ T185] ? do_raw_spin_unlock+0x59/0x250 [ 11.180680][ T185] alloc_buf.isra.0+0x4b/0x260 [ 11.180683][ T185] put_chars+0x1e1/0x2f0 [ 11.180686][ T185] ? __send_to_port+0x420/0x420 [ 11.180693][ T185] hvc_console_print+0x292/0x780 [ 11.180700][ T185] ? hvc_write+0x3a0/0x3a0 [ 11.180702][ T185] ? lock_acquire+0x13c/0x160 [ 11.180707][ T185] console_emit_next_record+0x22f/0x4c0 [ 11.180711][ T185] ? devkmsg_read+0x4b0/0x4b0 [ 11.180712][ T185] ? console_flush_one_record+0x106/0x710 [ 11.180715][ T185] ? rcu_is_watching+0x16/0xd0 [ 11.180718][ T185] ? lock_acquire+0x13c/0x160 [ 11.180722][ T185] console_flush_one_record+0x46f/0x710 [ 11.180726][ T185] ? console_emit_next_record+0x4c0/0x4c0 [ 11.180727][ T185] ? __lock_acquire+0x518/0xc20 [ 11.180733][ T185] console_unlock+0xee/0x1f0 [ 11.180735][ T185] ? console_flush_one_record+0x710/0x710 [ 11.180737][ T185] ? rcu_is_watching+0x16/0xd0 [ 11.180738][ T185] ? lock_acquire+0x60/0x160 [ 11.180742][ T185] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.180744][ T185] ? vprintk_emit+0x320/0x3e0 [ 11.180747][ T185] vprintk_emit+0x37c/0x3e0 [ 11.180750][ T185] ? wake_up_klogd_work_func+0x90/0x90 [ 11.180753][ T185] ? __lock_acquire+0x518/0xc20 [ 11.180757][ T185] _printk+0xc7/0x100 [ 11.180761][ T185] ? snapshot_read.cold+0x21/0x21 [ 11.180764][ T185] ? do_raw_spin_lock+0x131/0x280 [ 11.180767][ T185] ? __rwlock_init+0x150/0x150 [ 11.180771][ T185] ? do_raw_spin_lock+0x131/0x280 [ 11.180774][ T185] do_exit.cold+0x82/0x9c [ 11.180778][ T185] ? exit_notify+0x890/0x890 [ 11.180779][ T185] ? __lock_release.isra.0+0x69/0x1a0 [ 11.180782][ T185] ? rcu_is_watching+0x16/0xd0 [ 11.180786][ T185] do_group_exit+0xb8/0x370 [ 11.180789][ T185] __x64_sys_exit_group+0x3c/0x50 [ 11.180791][ T185] x64_sys_call+0x1567/0x1570 [ 11.180794][ T185] do_syscall_64+0x10e/0x570 [ 11.180797][ T185] ? irq_exit_rcu+0x1a/0x30 [ 11.180801][ T185] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.180803][ T185] RIP: 0033:0x479678 [ 11.180805][ T185] Code: Unable to access opcode bytes at 0x47964e. [ 11.180806][ T185] RSP: 002b:00007ffe23a5c298 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 11.180809][ T185] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 0000000000479678 [ 11.180810][ T185] RDX: 00000000291e8708 RSI: fffffffffffffff0 RDI: 0000000000000000 [ 11.180811][ T185] RBP: 00007ffe23a5c2f0 R08: 0000000000000000 R09: 00000000291e9450 [ 11.180812][ T185] R10: 0000000000000110 R11: 0000000000000246 R12: 00007ffe23a5c428 [ 11.180813][ T185] R13: 00007ffe23a5c468 R14: 0000000000000000 R15: 000000000054d560 [ 11.180819][ T185] [ 12.047376][ T211] packetdrill (211) used greatest stack depth: 23680 bytes left