[ 9.082224][ T175] tun: Universal TUN/TAP device driver, 1.6 [ 9.082242][ T175] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.082244][ T175] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 175, name: modprobe [ 9.082246][ T175] preempt_count: 1, expected: 0 [ 9.082247][ T175] RCU nest depth: 0, expected: 0 [ 9.082247][ T175] locks held by modprobe/175: 4, last CPU#2: [ 9.082250][ T175] #0: ffffffff8b36a440 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.082261][ T175] #1: ffffffff8b36a4b8 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.082265][ T175] #2: ffffffff8b289de0 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.082268][ T175] #3: ffffffff8b289ce0 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.082272][ T175] irq event stamp: 31138 [ 9.082272][ T175] hardirqs last enabled at (31137): [] __down_trylock_console_sem+0x86/0xa0 [ 9.082276][ T175] hardirqs last disabled at (31138): [] console_emit_next_record+0x3d4/0x4c0 [ 9.082277][ T175] softirqs last enabled at (28180): [] handle_softirqs+0x67c/0x900 [ 9.082281][ T175] softirqs last disabled at (27609): [] __irq_exit_rcu+0x145/0x1c0 [ 9.082283][ T175] Preemption disabled at: [ 9.082284][ T175] [] vprintk_emit+0x31b/0x3e0 [ 9.082289][ T175] CPU: 2 UID: 0 PID: 175 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.082292][ T175] Tainted: [W]=WARN [ 9.082293][ T175] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.082295][ T175] Call Trace: [ 9.082297][ T175] [ 9.082298][ T175] dump_stack_lvl+0x6f/0xa0 [ 9.082302][ T175] ? vprintk_emit+0x31b/0x3e0 [ 9.082304][ T175] __might_resched.cold+0x1fe/0x2c1 [ 9.082309][ T175] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.082312][ T175] ? __kmalloc_noprof+0xdb/0x760 [ 9.082317][ T175] __kmalloc_noprof+0x443/0x760 [ 9.082320][ T175] ? alloc_buf.isra.0+0x4b/0x260 [ 9.082326][ T175] ? do_raw_spin_unlock+0x59/0x250 [ 9.082329][ T175] alloc_buf.isra.0+0x4b/0x260 [ 9.082332][ T175] put_chars+0x1e1/0x2f0 [ 9.082335][ T175] ? __send_to_port+0x420/0x420 [ 9.082338][ T175] ? validate_chain+0x38b/0xc20 [ 9.082343][ T175] hvc_console_print+0x292/0x780 [ 9.082350][ T175] ? hvc_write+0x3a0/0x3a0 [ 9.082352][ T175] ? rcu_is_watching+0x16/0xd0 [ 9.082355][ T175] ? lock_acquire+0x13c/0x160 [ 9.082360][ T175] console_emit_next_record+0x22f/0x4c0 [ 9.082363][ T175] ? devkmsg_read+0x4b0/0x4b0 [ 9.082364][ T175] ? console_flush_one_record+0x106/0x710 [ 9.082367][ T175] ? rcu_is_watching+0x16/0xd0 [ 9.082368][ T175] ? lock_acquire+0x13c/0x160 [ 9.082372][ T175] console_flush_one_record+0x46f/0x710 [ 9.082376][ T175] ? console_emit_next_record+0x4c0/0x4c0 [ 9.082377][ T175] ? __lock_acquire+0x518/0xc20 [ 9.082382][ T175] console_unlock+0xee/0x1f0 [ 9.082385][ T175] ? console_flush_one_record+0x710/0x710 [ 9.082386][ T175] ? rcu_is_watching+0x16/0xd0 [ 9.082388][ T175] ? lock_acquire+0x60/0x160 [ 9.082391][ T175] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.082393][ T175] ? vprintk_emit+0x320/0x3e0 [ 9.082396][ T175] vprintk_emit+0x37c/0x3e0 [ 9.082400][ T175] ? wake_up_klogd_work_func+0x90/0x90 [ 9.082405][ T175] ? tun_cleanup+0x630/0x630 [tun] [ 9.082408][ T175] _printk+0xc7/0x100 [ 9.082412][ T175] ? snapshot_read.cold+0x21/0x21 [ 9.082418][ T175] ? do_one_initcall+0x113/0x4f0 [ 9.082422][ T175] tun_init+0x24/0x1000 [tun] [ 9.082425][ T175] do_one_initcall+0x124/0x4f0 [ 9.082428][ T175] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 9.082432][ T175] ? do_init_module+0xae/0x8a0 [ 9.082435][ T175] ? kasan_unpoison+0x44/0x70 [ 9.082439][ T175] do_init_module+0x2ad/0x8a0 [ 9.082443][ T175] ? lockdep_hardirqs_on+0x91/0x130 [ 9.082447][ T175] ? free_module+0x470/0x470 [ 9.082450][ T175] ? kfree+0x123/0x590 [ 9.082454][ T175] load_module+0x154b/0x1d60 [ 9.082461][ T175] ? layout_and_allocate.isra.0+0x680/0x680 [ 9.082468][ T175] init_module_from_file+0x11f/0x1a0 [ 9.082470][ T175] ? __ia32_sys_init_module+0xc0/0xc0 [ 9.082478][ T175] ? __rwlock_init+0x150/0x150 [ 9.082482][ T175] ? do_raw_spin_unlock+0x59/0x250 [ 9.082485][ T175] idempotent_init_module+0x37b/0x7c0 [ 9.082488][ T175] ? init_module_from_file+0x1a0/0x1a0 [ 9.082493][ T175] ? rcu_is_watching+0x16/0xd0 [ 9.082495][ T175] ? cap_capable+0x1d7/0x3d0 [ 9.082500][ T175] __x64_sys_finit_module+0xdf/0x190 [ 9.082502][ T175] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 9.082506][ T175] do_syscall_64+0x10e/0x570 [ 9.082508][ T175] ? exc_page_fault+0xee/0x100 [ 9.082511][ T175] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.082513][ T175] RIP: 0033:0x7f1ba986a37d [ 9.082516][ T175] 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.082518][ T175] RSP: 002b:00007ffc4e61f578 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 9.082520][ T175] RAX: ffffffffffffffda RBX: 0000561fe29ff980 RCX: 00007f1ba986a37d [ 9.082522][ T175] RDX: 0000000000000000 RSI: 0000561fb7cb9ffd RDI: 0000000000000005 [ 9.082522][ T175] RBP: 00007ffc4e61f610 R08: 0000000000000000 R09: 0000000000000000 [ 9.082523][ T175] R10: 0000000000000000 R11: 0000000000000246 R12: 0000561fb7cb9ffd [ 9.082524][ T175] R13: 0000561fe29ffaa0 R14: 0000000000040000 R15: 0000000000000000 [ 9.082531][ T175] [ 10.180838][ T185] packetdrill (185) used greatest stack depth: 24456 bytes left [ 10.180853][ T185] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.180855][ T185] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 185, name: packetdrill [ 10.180857][ T185] preempt_count: 2, expected: 0 [ 10.180857][ T185] RCU nest depth: 0, expected: 0 [ 10.180858][ T185] locks held by packetdrill/185: 5, last CPU#2: [ 10.180860][ T185] #0: ffffffff8b202f58 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x8e7/0xef0 [ 10.180872][ T185] #1: ffffffff8b36a440 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.180877][ T185] #2: ffffffff8b36a4b8 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.180883][ T185] #3: ffffffff8b289de0 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.180886][ T185] #4: ffffffff8b289ce0 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.180889][ T185] irq event stamp: 4336432 [ 10.180890][ T185] hardirqs last enabled at (4336431): [] __down_trylock_console_sem+0x86/0xa0 [ 10.180894][ T185] hardirqs last disabled at (4336432): [] console_emit_next_record+0x3d4/0x4c0 [ 10.180895][ T185] softirqs last enabled at (4336208): [] tcp_close+0x2b/0xb0 [ 10.180899][ T185] softirqs last disabled at (4336206): [] release_sock+0x21/0x240 [ 10.180903][ T185] Preemption disabled at: [ 10.180903][ T185] [<0000000000000000>] 0x0 [ 10.180910][ T185] CPU: 2 UID: 0 PID: 185 Comm: packetdrill Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.180913][ T185] Tainted: [W]=WARN [ 10.180914][ T185] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.180916][ T185] Call Trace: [ 10.180917][ T185] [ 10.180919][ T185] dump_stack_lvl+0x6f/0xa0 [ 10.180924][ T185] __might_resched.cold+0x1fe/0x2c1 [ 10.180928][ T185] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.180931][ T185] ? __kmalloc_noprof+0xdb/0x760 [ 10.180936][ T185] __kmalloc_noprof+0x443/0x760 [ 10.180939][ T185] ? alloc_buf.isra.0+0x4b/0x260 [ 10.180945][ T185] ? do_raw_spin_unlock+0x59/0x250 [ 10.180947][ T185] alloc_buf.isra.0+0x4b/0x260 [ 10.180951][ T185] put_chars+0x1e1/0x2f0 [ 10.180954][ T185] ? __send_to_port+0x420/0x420 [ 10.180960][ T185] hvc_console_print+0x292/0x780 [ 10.180967][ T185] ? hvc_write+0x3a0/0x3a0 [ 10.180970][ T185] ? lock_acquire+0x13c/0x160 [ 10.180974][ T185] console_emit_next_record+0x22f/0x4c0 [ 10.180977][ T185] ? devkmsg_read+0x4b0/0x4b0 [ 10.180979][ T185] ? console_flush_one_record+0x106/0x710 [ 10.180981][ T185] ? rcu_is_watching+0x16/0xd0 [ 10.180985][ T185] ? lock_acquire+0x13c/0x160 [ 10.180989][ T185] console_flush_one_record+0x46f/0x710 [ 10.180992][ T185] ? console_emit_next_record+0x4c0/0x4c0 [ 10.180994][ T185] ? __lock_acquire+0x518/0xc20 [ 10.180999][ T185] console_unlock+0xee/0x1f0 [ 10.181001][ T185] ? console_flush_one_record+0x710/0x710 [ 10.181002][ T185] ? rcu_is_watching+0x16/0xd0 [ 10.181004][ T185] ? lock_acquire+0x60/0x160 [ 10.181008][ T185] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.181010][ T185] ? vprintk_emit+0x320/0x3e0 [ 10.181012][ T185] vprintk_emit+0x37c/0x3e0 [ 10.181016][ T185] ? wake_up_klogd_work_func+0x90/0x90 [ 10.181019][ T185] ? __lock_acquire+0x518/0xc20 [ 10.181022][ T185] _printk+0xc7/0x100 [ 10.181026][ T185] ? snapshot_read.cold+0x21/0x21 [ 10.181028][ T185] ? do_raw_spin_lock+0x131/0x280 [ 10.181031][ T185] ? __rwlock_init+0x150/0x150 [ 10.181035][ T185] ? do_raw_spin_lock+0x131/0x280 [ 10.181038][ T185] do_exit.cold+0x82/0x9c [ 10.181042][ T185] ? exit_notify+0x890/0x890 [ 10.181043][ T185] ? __lock_release.isra.0+0x69/0x1a0 [ 10.181046][ T185] ? rcu_is_watching+0x16/0xd0 [ 10.181049][ T185] do_group_exit+0xb8/0x370 [ 10.181052][ T185] __x64_sys_exit_group+0x3c/0x50 [ 10.181054][ T185] x64_sys_call+0x1567/0x1570 [ 10.181057][ T185] do_syscall_64+0x10e/0x570 [ 10.181060][ T185] ? irq_exit_rcu+0x1a/0x30 [ 10.181063][ T185] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.181066][ T185] RIP: 0033:0x479678 [ 10.181068][ T185] Code: Unable to access opcode bytes at 0x47964e. [ 10.181069][ T185] RSP: 002b:00007fff6467f128 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 10.181071][ T185] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 0000000000479678 [ 10.181072][ T185] RDX: 0000000032067708 RSI: fffffffffffffff0 RDI: 0000000000000000 [ 10.181073][ T185] RBP: 00007fff6467f180 R08: 0000000000000000 R09: 0000000032068450 [ 10.181074][ T185] R10: 0000000000000110 R11: 0000000000000246 R12: 00007fff6467f2b8 [ 10.181075][ T185] R13: 00007fff6467f2f8 R14: 0000000000000000 R15: 000000000054d560 [ 10.181082][ T185] [ 10.844428][ T211] packetdrill (211) used greatest stack depth: 23136 bytes left