[ 10.097146][ T194] ip (194) used greatest stack depth: 24776 bytes left [ 10.097163][ T194] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.097165][ T194] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 194, name: ip [ 10.097167][ T194] preempt_count: 2, expected: 0 [ 10.097168][ T194] RCU nest depth: 0, expected: 0 [ 10.097168][ T194] locks held by ip/194: 5, last CPU#0: [ 10.097170][ T194] #0: ffffffffb00267b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 10.097182][ T194] #1: ffffffffb018dcc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.097187][ T194] #2: ffffffffb018dd38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.097191][ T194] #3: ffffffffb00ad660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.097195][ T194] #4: ffffffffb00ad560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.097199][ T194] irq event stamp: 21426 [ 10.097200][ T194] hardirqs last enabled at (21425): [] __down_trylock_console_sem+0x86/0xa0 [ 10.097202][ T194] hardirqs last disabled at (21426): [] console_emit_next_record+0x3d4/0x4c0 [ 10.097204][ T194] softirqs last enabled at (19854): [] netlink_release+0x17b/0xcf0 [ 10.097208][ T194] softirqs last disabled at (19852): [] netlink_release+0xd2/0xcf0 [ 10.097211][ T194] Preemption disabled at: [ 10.097211][ T194] [<0000000000000000>] 0x0 [ 10.097218][ T194] CPU: 0 UID: 0 PID: 194 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.097222][ T194] Tainted: [W]=WARN [ 10.097222][ T194] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.097224][ T194] Call Trace: [ 10.097226][ T194] [ 10.097227][ T194] dump_stack_lvl+0x6f/0xa0 [ 10.097233][ T194] __might_resched.cold+0x1fe/0x2c1 [ 10.097238][ T194] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.097242][ T194] ? __kmalloc_noprof+0xdb/0x760 [ 10.097247][ T194] __kmalloc_noprof+0x443/0x760 [ 10.097249][ T194] ? alloc_buf.isra.0+0x4b/0x260 [ 10.097255][ T194] ? do_raw_spin_unlock+0x59/0x250 [ 10.097257][ T194] alloc_buf.isra.0+0x4b/0x260 [ 10.097261][ T194] put_chars+0x1e1/0x2f0 [ 10.097264][ T194] ? __send_to_port+0x420/0x420 [ 10.097266][ T194] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.097270][ T194] ? validate_chain+0x38b/0xc20 [ 10.097275][ T194] hvc_console_print+0x292/0x780 [ 10.097283][ T194] ? hvc_write+0x3a0/0x3a0 [ 10.097285][ T194] ? rcu_is_watching+0x16/0xd0 [ 10.097287][ T194] ? lock_acquire+0x13c/0x160 [ 10.097291][ T194] console_emit_next_record+0x22f/0x4c0 [ 10.097295][ T194] ? devkmsg_read+0x4b0/0x4b0 [ 10.097297][ T194] ? console_flush_one_record+0x106/0x710 [ 10.097300][ T194] ? rcu_is_watching+0x16/0xd0 [ 10.097302][ T194] ? lock_acquire+0x13c/0x160 [ 10.097305][ T194] console_flush_one_record+0x46f/0x710 [ 10.097309][ T194] ? console_emit_next_record+0x4c0/0x4c0 [ 10.097311][ T194] ? __lock_acquire+0x518/0xc20 [ 10.097316][ T194] console_unlock+0xee/0x1f0 [ 10.097318][ T194] ? console_flush_one_record+0x710/0x710 [ 10.097320][ T194] ? rcu_is_watching+0x16/0xd0 [ 10.097322][ T194] ? lock_acquire+0x60/0x160 [ 10.097326][ T194] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.097327][ T194] ? vprintk_emit+0x320/0x3e0 [ 10.097330][ T194] vprintk_emit+0x37c/0x3e0 [ 10.097334][ T194] ? wake_up_klogd_work_func+0x90/0x90 [ 10.097337][ T194] ? __lock_acquire+0x518/0xc20 [ 10.097340][ T194] _printk+0xc7/0x100 [ 10.097344][ T194] ? snapshot_read.cold+0x21/0x21 [ 10.097347][ T194] ? do_raw_spin_lock+0x131/0x280 [ 10.097349][ T194] ? __rwlock_init+0x150/0x150 [ 10.097353][ T194] ? do_raw_spin_lock+0x131/0x280 [ 10.097355][ T194] do_exit.cold+0x82/0x9c [ 10.097359][ T194] ? exit_notify+0x890/0x890 [ 10.097361][ T194] ? __lock_release.isra.0+0x69/0x1a0 [ 10.097363][ T194] ? rcu_is_watching+0x16/0xd0 [ 10.097367][ T194] do_group_exit+0xb8/0x370 [ 10.097370][ T194] __x64_sys_exit_group+0x3c/0x50 [ 10.097373][ T194] x64_sys_call+0x1567/0x1570 [ 10.097375][ T194] do_syscall_64+0xff/0x530 [ 10.097378][ T194] ? exc_page_fault+0xee/0x100 [ 10.097382][ T194] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.097384][ T194] RIP: 0033:0x7fab5d2531b8 [ 10.097386][ T194] Code: Unable to access opcode bytes at 0x7fab5d25318e. [ 10.097387][ T194] RSP: 002b:00007fff45dbae78 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 10.097389][ T194] RAX: ffffffffffffffda RBX: 00007fab5d383f88 RCX: 00007fab5d2531b8 [ 10.097390][ T194] RDX: 00007fab5cf9dfc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 10.097391][ T194] RBP: 00007fff45dbaed0 R08: 0000000000000000 R09: 0000000000000050 [ 10.097392][ T194] R10: 00007fff45dbac90 R11: 0000000000000246 R12: 0000000000000001 [ 10.097393][ T194] R13: 0000000000000000 R14: 00007fab5d382680 R15: 00007fab5d383fa0 [ 10.097400][ T194] [ 10.674572][ T207] ip (207) used greatest stack depth: 24464 bytes left [ 11.087533][ T220] ip (220) used greatest stack depth: 24096 bytes left [ 30.493637][ T1082] ip (1082) used greatest stack depth: 23664 bytes left [ 30.493653][ T1082] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 30.493656][ T1082] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1082, name: ip [ 30.493657][ T1082] preempt_count: 2, expected: 0 [ 30.493658][ T1082] RCU nest depth: 0, expected: 0 [ 30.493659][ T1082] locks held by ip/1082: 5, last CPU#0: [ 30.493661][ T1082] #0: ffffffffb00267b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 30.493674][ T1082] #1: ffffffffb018dcc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 30.493679][ T1082] #2: ffffffffb018dd38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 30.493683][ T1082] #3: ffffffffb00ad660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 30.493686][ T1082] #4: ffffffffb00ad560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 30.493690][ T1082] irq event stamp: 34946 [ 30.493691][ T1082] hardirqs last enabled at (34945): [] __down_trylock_console_sem+0x86/0xa0 [ 30.493693][ T1082] hardirqs last disabled at (34946): [] console_emit_next_record+0x3d4/0x4c0 [ 30.493695][ T1082] softirqs last enabled at (34930): [] handle_softirqs+0x67c/0x900 [ 30.493697][ T1082] softirqs last disabled at (34925): [] __irq_exit_rcu+0x145/0x1c0 [ 30.493699][ T1082] Preemption disabled at: [ 30.493700][ T1082] [<0000000000000000>] 0x0 [ 30.493707][ T1082] CPU: 0 UID: 0 PID: 1082 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 30.493710][ T1082] Tainted: [W]=WARN [ 30.493711][ T1082] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 30.493713][ T1082] Call Trace: [ 30.493714][ T1082] [ 30.493716][ T1082] dump_stack_lvl+0x6f/0xa0 [ 30.493722][ T1082] __might_resched.cold+0x1fe/0x2c1 [ 30.493726][ T1082] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 30.493730][ T1082] ? __kmalloc_noprof+0xdb/0x760 [ 30.493735][ T1082] __kmalloc_noprof+0x443/0x760 [ 30.493737][ T1082] ? alloc_buf.isra.0+0x4b/0x260 [ 30.493743][ T1082] ? do_raw_spin_unlock+0x59/0x250 [ 30.493746][ T1082] alloc_buf.isra.0+0x4b/0x260 [ 30.493749][ T1082] put_chars+0x1e1/0x2f0 [ 30.493752][ T1082] ? __send_to_port+0x420/0x420 [ 30.493754][ T1082] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 30.493758][ T1082] ? validate_chain+0x38b/0xc20 [ 30.493764][ T1082] hvc_console_print+0x292/0x780 [ 30.493771][ T1082] ? hvc_write+0x3a0/0x3a0 [ 30.493773][ T1082] ? rcu_is_watching+0x16/0xd0 [ 30.493775][ T1082] ? lock_acquire+0x13c/0x160 [ 30.493779][ T1082] console_emit_next_record+0x22f/0x4c0 [ 30.493783][ T1082] ? devkmsg_read+0x4b0/0x4b0 [ 30.493784][ T1082] ? console_flush_one_record+0x106/0x710 [ 30.493787][ T1082] ? rcu_is_watching+0x16/0xd0 [ 30.493789][ T1082] ? lock_acquire+0x13c/0x160 [ 30.493793][ T1082] console_flush_one_record+0x46f/0x710 [ 30.493797][ T1082] ? console_emit_next_record+0x4c0/0x4c0 [ 30.493799][ T1082] ? __lock_acquire+0x518/0xc20 [ 30.493803][ T1082] console_unlock+0xee/0x1f0 [ 30.493806][ T1082] ? console_flush_one_record+0x710/0x710 [ 30.493808][ T1082] ? rcu_is_watching+0x16/0xd0 [ 30.493810][ T1082] ? lock_acquire+0x60/0x160 [ 30.493813][ T1082] ? __down_trylock_console_sem+0x5e/0xa0 [ 30.493814][ T1082] ? vprintk_emit+0x320/0x3e0 [ 30.493818][ T1082] vprintk_emit+0x37c/0x3e0 [ 30.493821][ T1082] ? wake_up_klogd_work_func+0x90/0x90 [ 30.493824][ T1082] ? __lock_acquire+0x518/0xc20 [ 30.493828][ T1082] _printk+0xc7/0x100 [ 30.493831][ T1082] ? snapshot_read.cold+0x21/0x21 [ 30.493834][ T1082] ? do_raw_spin_lock+0x131/0x280 [ 30.493836][ T1082] ? __rwlock_init+0x150/0x150 [ 30.493840][ T1082] ? do_raw_spin_lock+0x131/0x280 [ 30.493842][ T1082] do_exit.cold+0x82/0x9c [ 30.493846][ T1082] ? exit_notify+0x890/0x890 [ 30.493848][ T1082] ? __lock_release.isra.0+0x69/0x1a0 [ 30.493850][ T1082] ? rcu_is_watching+0x16/0xd0 [ 30.493854][ T1082] do_group_exit+0xb8/0x370 [ 30.493858][ T1082] __x64_sys_exit_group+0x3c/0x50 [ 30.493860][ T1082] x64_sys_call+0x1567/0x1570 [ 30.493862][ T1082] do_syscall_64+0xff/0x530 [ 30.493865][ T1082] ? exc_page_fault+0xee/0x100 [ 30.493869][ T1082] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 30.493871][ T1082] RIP: 0033:0x7fda6ffcd1b8 [ 30.493874][ T1082] Code: Unable to access opcode bytes at 0x7fda6ffcd18e. [ 30.493875][ T1082] RSP: 002b:00007ffcd9cb9ac8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 30.493877][ T1082] RAX: ffffffffffffffda RBX: 00007fda700fdf88 RCX: 00007fda6ffcd1b8 [ 30.493878][ T1082] RDX: 00007fda6fd17fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 30.493879][ T1082] RBP: 00007ffcd9cb9b20 R08: 0000000000000000 R09: 0000000000000000 [ 30.493880][ T1082] R10: 00007ffcd9cb98e0 R11: 0000000000000246 R12: 0000000000000001 [ 30.493881][ T1082] R13: 0000000000000000 R14: 00007fda700fc680 R15: 00007fda700fdfa0 [ 30.493888][ T1082] [ 88.408463][ T2748] ip (2748) used greatest stack depth: 23312 bytes left [ 88.408484][ T2748] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 88.408486][ T2748] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 2748, name: ip [ 88.408487][ T2748] preempt_count: 2, expected: 0 [ 88.408488][ T2748] RCU nest depth: 0, expected: 0 [ 88.408489][ T2748] locks held by ip/2748: 5, last CPU#3: [ 88.408491][ T2748] #0: ffffffffb00267b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 88.408504][ T2748] #1: ffffffffb018dcc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 88.408510][ T2748] #2: ffffffffb018dd38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 88.408514][ T2748] #3: ffffffffb00ad660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 88.408518][ T2748] #4: ffffffffb00ad560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 88.408522][ T2748] irq event stamp: 32884 [ 88.408523][ T2748] hardirqs last enabled at (32883): [] __down_trylock_console_sem+0x86/0xa0 [ 88.408525][ T2748] hardirqs last disabled at (32884): [] console_emit_next_record+0x3d4/0x4c0 [ 88.408527][ T2748] softirqs last enabled at (31332): [] netlink_release+0x17b/0xcf0 [ 88.408531][ T2748] softirqs last disabled at (31330): [] netlink_release+0xd2/0xcf0 [ 88.408534][ T2748] Preemption disabled at: [ 88.408534][ T2748] [<0000000000000000>] 0x0 [ 88.408541][ T2748] CPU: 3 UID: 0 PID: 2748 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 88.408545][ T2748] Tainted: [W]=WARN [ 88.408545][ T2748] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 88.408547][ T2748] Call Trace: [ 88.408548][ T2748] [ 88.408550][ T2748] dump_stack_lvl+0x6f/0xa0 [ 88.408556][ T2748] __might_resched.cold+0x1fe/0x2c1 [ 88.408561][ T2748] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 88.408565][ T2748] ? __kmalloc_noprof+0xdb/0x760 [ 88.408569][ T2748] __kmalloc_noprof+0x443/0x760 [ 88.408571][ T2748] ? alloc_buf.isra.0+0x4b/0x260 [ 88.408578][ T2748] ? do_raw_spin_unlock+0x59/0x250 [ 88.408580][ T2748] alloc_buf.isra.0+0x4b/0x260 [ 88.408584][ T2748] put_chars+0x1e1/0x2f0 [ 88.408587][ T2748] ? __send_to_port+0x420/0x420 [ 88.408589][ T2748] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 88.408594][ T2748] ? validate_chain+0x38b/0xc20 [ 88.408599][ T2748] hvc_console_print+0x292/0x780 [ 88.408608][ T2748] ? hvc_write+0x3a0/0x3a0 [ 88.408610][ T2748] ? rcu_is_watching+0x16/0xd0 [ 88.408613][ T2748] ? lock_acquire+0x13c/0x160 [ 88.408617][ T2748] console_emit_next_record+0x22f/0x4c0 [ 88.408620][ T2748] ? devkmsg_read+0x4b0/0x4b0 [ 88.408622][ T2748] ? console_flush_one_record+0x106/0x710 [ 88.408625][ T2748] ? rcu_is_watching+0x16/0xd0 [ 88.408627][ T2748] ? lock_acquire+0x13c/0x160 [ 88.408631][ T2748] console_flush_one_record+0x46f/0x710 [ 88.408635][ T2748] ? console_emit_next_record+0x4c0/0x4c0 [ 88.408636][ T2748] ? __lock_acquire+0x518/0xc20 [ 88.408641][ T2748] console_unlock+0xee/0x1f0 [ 88.408644][ T2748] ? console_flush_one_record+0x710/0x710 [ 88.408646][ T2748] ? rcu_is_watching+0x16/0xd0 [ 88.408648][ T2748] ? lock_acquire+0x60/0x160 [ 88.408651][ T2748] ? __down_trylock_console_sem+0x5e/0xa0 [ 88.408653][ T2748] ? vprintk_emit+0x320/0x3e0 [ 88.408656][ T2748] vprintk_emit+0x37c/0x3e0 [ 88.408660][ T2748] ? wake_up_klogd_work_func+0x90/0x90 [ 88.408663][ T2748] ? __lock_acquire+0x518/0xc20 [ 88.408666][ T2748] _printk+0xc7/0x100 [ 88.408670][ T2748] ? snapshot_read.cold+0x21/0x21 [ 88.408673][ T2748] ? do_raw_spin_lock+0x131/0x280 [ 88.408675][ T2748] ? __rwlock_init+0x150/0x150 [ 88.408679][ T2748] ? do_raw_spin_lock+0x131/0x280 [ 88.408681][ T2748] do_exit.cold+0x82/0x9c [ 88.408685][ T2748] ? exit_notify+0x890/0x890 [ 88.408687][ T2748] ? __lock_release.isra.0+0x69/0x1a0 [ 88.408689][ T2748] ? rcu_is_watching+0x16/0xd0 [ 88.408693][ T2748] do_group_exit+0xb8/0x370 [ 88.408697][ T2748] __x64_sys_exit_group+0x3c/0x50 [ 88.408699][ T2748] x64_sys_call+0x1567/0x1570 [ 88.408701][ T2748] do_syscall_64+0xff/0x530 [ 88.408704][ T2748] ? exc_page_fault+0xee/0x100 [ 88.408708][ T2748] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 88.408710][ T2748] RIP: 0033:0x7f5d071ca1b8 [ 88.408712][ T2748] Code: Unable to access opcode bytes at 0x7f5d071ca18e. [ 88.408713][ T2748] RSP: 002b:00007ffc55e5ba98 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 88.408716][ T2748] RAX: ffffffffffffffda RBX: 00007f5d072faf88 RCX: 00007f5d071ca1b8 [ 88.408717][ T2748] RDX: 00007f5d06f14fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 88.408718][ T2748] RBP: 00007ffc55e5baf0 R08: 0000000000000000 R09: 0000000000008010 [ 88.408719][ T2748] R10: 00007ffc55e5b8b0 R11: 0000000000000246 R12: 0000000000000001 [ 88.408720][ T2748] R13: 0000000000000000 R14: 00007f5d072f9680 R15: 00007f5d072fafa0 [ 88.408726][ T2748] [ 1056.979509][T26081] ip (26081) used greatest stack depth: 23264 bytes left [ 1056.979527][T26081] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 1056.979529][T26081] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 26081, name: ip [ 1056.979531][T26081] preempt_count: 2, expected: 0 [ 1056.979532][T26081] RCU nest depth: 0, expected: 0 [ 1056.979533][T26081] locks held by ip/26081: 5, last CPU#1: [ 1056.979534][T26081] #0: ffffffffb00267b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 1056.979547][T26081] #1: ffffffffb018dcc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 1056.979552][T26081] #2: ffffffffb018dd38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 1056.979557][T26081] #3: ffffffffb00ad660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 1056.979560][T26081] #4: ffffffffb00ad560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 1056.979564][T26081] irq event stamp: 26760 [ 1056.979564][T26081] hardirqs last enabled at (26759): [] __down_trylock_console_sem+0x86/0xa0 [ 1056.979567][T26081] hardirqs last disabled at (26760): [] console_emit_next_record+0x3d4/0x4c0 [ 1056.979568][T26081] softirqs last enabled at (26080): [] handle_softirqs+0x67c/0x900 [ 1056.979571][T26081] softirqs last disabled at (26075): [] __irq_exit_rcu+0x145/0x1c0 [ 1056.979573][T26081] Preemption disabled at: [ 1056.979574][T26081] [<0000000000000000>] 0x0 [ 1056.979581][T26081] CPU: 1 UID: 0 PID: 26081 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 1056.979584][T26081] Tainted: [W]=WARN [ 1056.979585][T26081] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 1056.979587][T26081] Call Trace: [ 1056.979588][T26081] [ 1056.979590][T26081] dump_stack_lvl+0x6f/0xa0 [ 1056.979596][T26081] __might_resched.cold+0x1fe/0x2c1 [ 1056.979601][T26081] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 1056.979608][T26081] ? __kmalloc_noprof+0xdb/0x760 [ 1056.979613][T26081] __kmalloc_noprof+0x443/0x760 [ 1056.979615][T26081] ? alloc_buf.isra.0+0x4b/0x260 [ 1056.979621][T26081] ? do_raw_spin_unlock+0x59/0x250 [ 1056.979624][T26081] alloc_buf.isra.0+0x4b/0x260 [ 1056.979627][T26081] put_chars+0x1e1/0x2f0 [ 1056.979630][T26081] ? __send_to_port+0x420/0x420 [ 1056.979632][T26081] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 1056.979637][T26081] ? validate_chain+0x38b/0xc20 [ 1056.979642][T26081] hvc_console_print+0x292/0x780 [ 1056.979649][T26081] ? hvc_write+0x3a0/0x3a0 [ 1056.979652][T26081] ? rcu_is_watching+0x16/0xd0 [ 1056.979654][T26081] ? lock_acquire+0x13c/0x160 [ 1056.979657][T26081] console_emit_next_record+0x22f/0x4c0 [ 1056.979661][T26081] ? devkmsg_read+0x4b0/0x4b0 [ 1056.979663][T26081] ? console_flush_one_record+0x106/0x710 [ 1056.979666][T26081] ? rcu_is_watching+0x16/0xd0 [ 1056.979668][T26081] ? lock_acquire+0x13c/0x160 [ 1056.979671][T26081] console_flush_one_record+0x46f/0x710 [ 1056.979675][T26081] ? console_emit_next_record+0x4c0/0x4c0 [ 1056.979677][T26081] ? __lock_acquire+0x518/0xc20 [ 1056.979682][T26081] console_unlock+0xee/0x1f0 [ 1056.979684][T26081] ? console_flush_one_record+0x710/0x710 [ 1056.979686][T26081] ? rcu_is_watching+0x16/0xd0 [ 1056.979688][T26081] ? lock_acquire+0x60/0x160 [ 1056.979691][T26081] ? __down_trylock_console_sem+0x5e/0xa0 [ 1056.979693][T26081] ? vprintk_emit+0x320/0x3e0 [ 1056.979696][T26081] vprintk_emit+0x37c/0x3e0 [ 1056.979699][T26081] ? wake_up_klogd_work_func+0x90/0x90 [ 1056.979702][T26081] ? __lock_acquire+0x518/0xc20 [ 1056.979706][T26081] _printk+0xc7/0x100 [ 1056.979709][T26081] ? snapshot_read.cold+0x21/0x21 [ 1056.979712][T26081] ? do_raw_spin_lock+0x131/0x280 [ 1056.979714][T26081] ? __rwlock_init+0x150/0x150 [ 1056.979718][T26081] ? do_raw_spin_lock+0x131/0x280 [ 1056.979720][T26081] do_exit.cold+0x82/0x9c [ 1056.979724][T26081] ? exit_notify+0x890/0x890 [ 1056.979726][T26081] ? __lock_release.isra.0+0x69/0x1a0 [ 1056.979728][T26081] ? rcu_is_watching+0x16/0xd0 [ 1056.979732][T26081] do_group_exit+0xb8/0x370 [ 1056.979736][T26081] __x64_sys_exit_group+0x3c/0x50 [ 1056.979738][T26081] x64_sys_call+0x1567/0x1570 [ 1056.979740][T26081] do_syscall_64+0xff/0x530 [ 1056.979743][T26081] ? exc_page_fault+0xee/0x100 [ 1056.979746][T26081] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1056.979749][T26081] RIP: 0033:0x7faeed6ed1b8 [ 1056.979751][T26081] Code: Unable to access opcode bytes at 0x7faeed6ed18e. [ 1056.979752][T26081] RSP: 002b:00007fff8f3b5ba8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 1056.979755][T26081] RAX: ffffffffffffffda RBX: 00007faeed81df88 RCX: 00007faeed6ed1b8 [ 1056.979756][T26081] RDX: 00007faeed437fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 1056.979757][T26081] RBP: 00007fff8f3b5c00 R08: 0000000000000000 R09: 0000000000000050 [ 1056.979758][T26081] R10: 00007fff8f3b59c0 R11: 0000000000000246 R12: 0000000000000001 [ 1056.979758][T26081] R13: 0000000000000000 R14: 00007faeed81c680 R15: 00007faeed81dfa0 [ 1056.979765][T26081] [ 1090.688253][T27741] pm_nl_ctl (27741) used greatest stack depth: 22080 bytes left [ 1090.688276][T27741] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 1090.688278][T27741] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 27741, name: pm_nl_ctl [ 1090.688281][T27741] preempt_count: 2, expected: 0 [ 1090.688282][T27741] RCU nest depth: 0, expected: 0 [ 1090.688283][T27741] locks held by pm_nl_ctl/27741: 5, last CPU#0: [ 1090.688286][T27741] #0: ffffffffb00267b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 1090.688301][T27741] #1: ffffffffb018dcc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 1090.688309][T27741] #2: ffffffffb018dd38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 1090.688315][T27741] #3: ffffffffb00ad660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 1090.688320][T27741] #4: ffffffffb00ad560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 1090.688331][T27741] irq event stamp: 30362 [ 1090.688332][T27741] hardirqs last enabled at (30361): [] __down_trylock_console_sem+0x86/0xa0 [ 1090.688335][T27741] hardirqs last disabled at (30362): [] console_emit_next_record+0x3d4/0x4c0 [ 1090.688338][T27741] softirqs last enabled at (29954): [] handle_softirqs+0x67c/0x900 [ 1090.688341][T27741] softirqs last disabled at (28921): [] __irq_exit_rcu+0x145/0x1c0 [ 1090.688344][T27741] Preemption disabled at: [ 1090.688345][T27741] [<0000000000000000>] 0x0 [ 1090.688353][T27741] CPU: 0 UID: 0 PID: 27741 Comm: pm_nl_ctl Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 1090.688358][T27741] Tainted: [W]=WARN [ 1090.688359][T27741] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 1090.688361][T27741] Call Trace: [ 1090.688363][T27741] [ 1090.688365][T27741] dump_stack_lvl+0x6f/0xa0 [ 1090.688373][T27741] __might_resched.cold+0x1fe/0x2c1 [ 1090.688379][T27741] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 1090.688385][T27741] ? __kmalloc_noprof+0xdb/0x760 [ 1090.688392][T27741] __kmalloc_noprof+0x443/0x760 [ 1090.688395][T27741] ? alloc_buf.isra.0+0x4b/0x260 [ 1090.688405][T27741] ? do_raw_spin_unlock+0x59/0x250 [ 1090.688408][T27741] alloc_buf.isra.0+0x4b/0x260 [ 1090.688413][T27741] put_chars+0x1e1/0x2f0 [ 1090.688418][T27741] ? __send_to_port+0x420/0x420 [ 1090.688421][T27741] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 1090.688427][T27741] ? validate_chain+0x38b/0xc20 [ 1090.688436][T27741] hvc_console_print+0x292/0x780 [ 1090.688447][T27741] ? hvc_write+0x3a0/0x3a0 [ 1090.688451][T27741] ? rcu_is_watching+0x16/0xd0 [ 1090.688454][T27741] ? lock_acquire+0x13c/0x160 [ 1090.688461][T27741] console_emit_next_record+0x22f/0x4c0 [ 1090.688467][T27741] ? devkmsg_read+0x4b0/0x4b0 [ 1090.688469][T27741] ? console_flush_one_record+0x106/0x710 [ 1090.688474][T27741] ? rcu_is_watching+0x16/0xd0 [ 1090.688478][T27741] ? lock_acquire+0x13c/0x160 [ 1090.688484][T27741] console_flush_one_record+0x46f/0x710 [ 1090.688491][T27741] ? console_emit_next_record+0x4c0/0x4c0 [ 1090.688493][T27741] ? __lock_acquire+0x518/0xc20 [ 1090.688502][T27741] console_unlock+0xee/0x1f0 [ 1090.688506][T27741] ? console_flush_one_record+0x710/0x710 [ 1090.688509][T27741] ? rcu_is_watching+0x16/0xd0 [ 1090.688512][T27741] ? lock_acquire+0x60/0x160 [ 1090.688518][T27741] ? __down_trylock_console_sem+0x5e/0xa0 [ 1090.688521][T27741] ? vprintk_emit+0x320/0x3e0 [ 1090.688526][T27741] vprintk_emit+0x37c/0x3e0 [ 1090.688532][T27741] ? wake_up_klogd_work_func+0x90/0x90 [ 1090.688538][T27741] ? __lock_acquire+0x518/0xc20 [ 1090.688544][T27741] _printk+0xc7/0x100 [ 1090.688549][T27741] ? snapshot_read.cold+0x21/0x21 [ 1090.688553][T27741] ? do_raw_spin_lock+0x131/0x280 [ 1090.688557][T27741] ? __rwlock_init+0x150/0x150 [ 1090.688564][T27741] ? do_raw_spin_lock+0x131/0x280 [ 1090.688568][T27741] do_exit.cold+0x82/0x9c [ 1090.688574][T27741] ? exit_notify+0x890/0x890 [ 1090.688577][T27741] ? __lock_release.isra.0+0x69/0x1a0 [ 1090.688581][T27741] ? rcu_is_watching+0x16/0xd0 [ 1090.688588][T27741] do_group_exit+0xb8/0x370 [ 1090.688593][T27741] __x64_sys_exit_group+0x3c/0x50 [ 1090.688597][T27741] x64_sys_call+0x1567/0x1570 [ 1090.688599][T27741] do_syscall_64+0xff/0x530 [ 1090.688606][T27741] ? exc_page_fault+0xee/0x100 [ 1090.688611][T27741] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 1090.688615][T27741] RIP: 0033:0x7f6554d4a1b8 [ 1090.688618][T27741] Code: Unable to access opcode bytes at 0x7f6554d4a18e. [ 1090.688619][T27741] RSP: 002b:00007ffe301d9888 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 1090.688623][T27741] RAX: ffffffffffffffda RBX: 00007f6554e7af88 RCX: 00007f6554d4a1b8 [ 1090.688625][T27741] RDX: 00007f6554c87a48 RSI: ffffffffffffffb0 RDI: 0000000000000000 [ 1090.688626][T27741] RBP: 00007ffe301d98e0 R08: 0000000000000000 R09: 0000000000000000 [ 1090.688627][T27741] R10: 00007ffe301d9720 R11: 0000000000000246 R12: 0000000000000001 [ 1090.688629][T27741] R13: 0000000000000000 R14: 00007f6554e79680 R15: 00007f6554e7afa0 [ 1090.688641][T27741] [ 1117.456860][ C0] clocksource: Watchdog remote CPU 1 read timed out [ 1117.457087][ C0] [ 1117.457089][ C0] ======================================================== [ 1117.457090][ C0] WARNING: possible irq lock inversion dependency detected [ 1117.457093][ C0] 7.2.0-virtme #1 Tainted: G W [ 1117.457094][ C0] -------------------------------------------------------- [ 1117.457095][ C0] mptcp_join.sh/28978 just changed the state of lock: [ 1117.457096][ C0] ffffffffb00ad660 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 1117.457108][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 1117.457109][ C0] (fs_reclaim){+.+.}-{0:0} [ 1117.457111][ C0] [ 1117.457111][ C0] [ 1117.457111][ C0] and interrupts could create inverse lock ordering between them. [ 1117.457111][ C0] [ 1117.457112][ C0] [ 1117.457112][ C0] other info that might help us debug this: [ 1117.457112][ C0] Possible interrupt unsafe locking scenario: [ 1117.457112][ C0] [ 1117.457113][ C0] CPU0 CPU1 [ 1117.457113][ C0] ---- ---- [ 1117.457114][ C0] lock(fs_reclaim); [ 1117.457115][ C0] local_irq_disable(); [ 1117.457115][ C0] lock(console_owner); [ 1117.457116][ C0] lock(fs_reclaim); [ 1117.457117][ C0] [ 1117.457117][ C0] lock(console_owner); [ 1117.457118][ C0] [ 1117.457118][ C0] *** DEADLOCK *** [ 1117.457118][ C0] [ 1117.457118][ C0] locks held by mptcp_join.sh/28978: 5, last CPU#0: [ 1117.457119][ C0] #0: ff11000015aca7c8 (vm_lock){++++}-{0:0}, at: lock_vma_under_rcu+0x12a/0x410 [ 1117.457125][ C0] #1: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 1117.457130][ C0] #2: ffffffffb01f28b8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 1117.457134][ C0] #3: ffffffffb018dcc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 1117.457137][ C0] #4: ffffffffb018dd38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 1117.457140][ C0] [ 1117.457140][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 1117.457145][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 1117.457147][ C0] HARDIRQ-ON-W at: [ 1117.457148][ C0] __lock_acquire+0x388/0xc20 [ 1117.457151][ C0] lock_acquire.part.0+0xd4/0x280 [ 1117.457153][ C0] fs_reclaim_acquire+0xd5/0x120 [ 1117.457156][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 1117.457158][ C0] kthread_create_worker_on_node+0xea/0x210 [ 1117.457161][ C0] workqueue_init+0x2a/0x680 [ 1117.457164][ C0] kernel_init_freeable+0x2fe/0x630 [ 1117.457166][ C0] kernel_init+0x21/0x150 [ 1117.457170][ C0] ret_from_fork+0x474/0x6b0 [ 1117.457174][ C0] ret_from_fork_asm+0x11/0x20 [ 1117.457177][ C0] SOFTIRQ-ON-W at: [ 1117.457177][ C0] __lock_acquire+0x388/0xc20 [ 1117.457179][ C0] lock_acquire.part.0+0xd4/0x280 [ 1117.457180][ C0] fs_reclaim_acquire+0xd5/0x120 [ 1117.457181][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 1117.457182][ C0] kthread_create_worker_on_node+0xea/0x210 [ 1117.457184][ C0] workqueue_init+0x2a/0x680 [ 1117.457185][ C0] kernel_init_freeable+0x2fe/0x630 [ 1117.457186][ C0] kernel_init+0x21/0x150 [ 1117.457187][ C0] ret_from_fork+0x474/0x6b0 [ 1117.457189][ C0] ret_from_fork_asm+0x11/0x20 [ 1117.457190][ C0] INITIAL USE at: [ 1117.457191][ C0] __lock_acquire+0x388/0xc20 [ 1117.457192][ C0] lock_acquire.part.0+0xd4/0x280 [ 1117.457193][ C0] fs_reclaim_acquire+0xd5/0x120 [ 1117.457195][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 1117.457196][ C0] kthread_create_worker_on_node+0xea/0x210 [ 1117.457197][ C0] workqueue_init+0x2a/0x680 [ 1117.457198][ C0] kernel_init_freeable+0x2fe/0x630 [ 1117.457199][ C0] kernel_init+0x21/0x150 [ 1117.457201][ C0] ret_from_fork+0x474/0x6b0 [ 1117.457202][ C0] ret_from_fork_asm+0x11/0x20 [ 1117.457203][ C0] } [ 1117.457204][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 1117.457207][ C0] ... acquired at: [ 1117.457208][ C0] __lock_acquire+0x518/0xc20 [ 1117.457210][ C0] lock_acquire.part.0+0xd4/0x280 [ 1117.457211][ C0] fs_reclaim_acquire+0xd5/0x120 [ 1117.457212][ C0] __kmalloc_noprof+0xd3/0x760 [ 1117.457213][ C0] alloc_buf.isra.0+0x4b/0x260 [ 1117.457217][ C0] put_chars+0x1e1/0x2f0 [ 1117.457219][ C0] hvc_console_print+0x292/0x780 [ 1117.457222][ C0] console_emit_next_record+0x22f/0x4c0 [ 1117.457223][ C0] console_flush_one_record+0x46f/0x710 [ 1117.457225][ C0] console_unlock+0xee/0x1f0 [ 1117.457226][ C0] vprintk_emit+0x37c/0x3e0 [ 1117.457227][ C0] dev_vprintk_emit+0x27f/0x2c0 [ 1117.457231][ C0] dev_printk_emit+0xb9/0xee [ 1117.457232][ C0] _dev_info+0xe2/0x116 [ 1117.457234][ C0] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 1117.457236][ C0] cmos_do_probe+0x73b/0x98a [ 1117.457237][ C0] platform_probe+0xfe/0x1f0 [ 1117.457239][ C0] call_driver_probe+0x61/0x1c0 [ 1117.457241][ C0] really_probe+0x199/0x760 [ 1117.457242][ C0] __driver_probe_device+0x24f/0x440 [ 1117.457244][ C0] driver_probe_device+0x4a/0xf0 [ 1117.457245][ C0] __driver_attach+0x1b8/0x540 [ 1117.457246][ C0] bus_for_each_dev+0x130/0x1e0 [ 1117.457247][ C0] bus_add_driver+0x2c8/0x530 [ 1117.457248][ C0] driver_register+0x1a3/0x390 [ 1117.457250][ C0] __platform_driver_probe+0x13f/0x270 [ 1117.457251][ C0] cmos_init+0x31/0x40 [ 1117.457254][ C0] do_one_initcall+0x124/0x4f0 [ 1117.457255][ C0] kernel_init_freeable+0x596/0x630 [ 1117.457256][ C0] kernel_init+0x21/0x150 [ 1117.457257][ C0] ret_from_fork+0x474/0x6b0 [ 1117.457259][ C0] ret_from_fork_asm+0x11/0x20 [ 1117.457260][ C0] [ 1117.457260][ C0] -> (console_owner){..-.}-{0:0} { [ 1117.457262][ C0] IN-SOFTIRQ-W at: [ 1117.457263][ C0] __lock_acquire+0x388/0xc20 [ 1117.457264][ C0] lock_acquire.part.0+0xd4/0x280 [ 1117.457265][ C0] console_lock_spinning_enable+0x5c/0x60 [ 1117.457266][ C0] console_emit_next_record+0x1d1/0x4c0 [ 1117.457268][ C0] console_flush_one_record+0x46f/0x710 [ 1117.457269][ C0] console_unlock+0xee/0x1f0 [ 1117.457270][ C0] vprintk_emit+0x37c/0x3e0 [ 1117.457272][ C0] _printk+0xc7/0x100 [ 1117.457275][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 1117.457277][ C0] call_timer_fn+0x160/0x4d0 [ 1117.457278][ C0] __run_timers+0x68f/0xaa0 [ 1117.457280][ C0] run_timer_softirq+0xf0/0x160 [ 1117.457281][ C0] handle_softirqs+0x1d3/0x900 [ 1117.457283][ C0] __irq_exit_rcu+0x145/0x1c0 [ 1117.457284][ C0] irq_exit_rcu+0xe/0x30 [ 1117.457285][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 1117.457287][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 1117.457289][ C0] do_fault_around+0x300/0x5a0 [ 1117.457291][ C0] do_pte_missing+0x86f/0xcc0 [ 1117.457292][ C0] handle_pte_fault+0x426/0x710 [ 1117.457294][ C0] __handle_mm_fault+0x3b7/0x5b0 [ 1117.457295][ C0] handle_mm_fault+0x182/0x4a0 [ 1117.457297][ C0] do_user_addr_fault+0x46c/0xe20 [ 1117.457299][ C0] exc_page_fault+0x87/0x100 [ 1117.457301][ C0] asm_exc_page_fault+0x26/0x30 [ 1117.457302][ C0] INITIAL USE at: [ 1117.457303][ C0] } [ 1117.457303][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 1117.457306][ C0] ... acquired at: [ 1117.457306][ C0] mark_lock+0x1d7/0xa00 [ 1117.457308][ C0] mark_usage+0x42/0x170 [ 1117.457309][ C0] __lock_acquire+0x388/0xc20 [ 1117.457310][ C0] lock_acquire.part.0+0xd4/0x280 [ 1117.457311][ C0] console_lock_spinning_enable+0x5c/0x60 [ 1117.457312][ C0] console_emit_next_record+0x1d1/0x4c0 [ 1117.457314][ C0] console_flush_one_record+0x46f/0x710 [ 1117.457315][ C0] console_unlock+0xee/0x1f0 [ 1117.457316][ C0] vprintk_emit+0x37c/0x3e0 [ 1117.457318][ C0] _printk+0xc7/0x100 [ 1117.457319][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 1117.457320][ C0] call_timer_fn+0x160/0x4d0 [ 1117.457321][ C0] __run_timers+0x68f/0xaa0 [ 1117.457330][ C0] run_timer_softirq+0xf0/0x160 [ 1117.457331][ C0] handle_softirqs+0x1d3/0x900 [ 1117.457332][ C0] __irq_exit_rcu+0x145/0x1c0 [ 1117.457333][ C0] irq_exit_rcu+0xe/0x30 [ 1117.457334][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 1117.457336][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 1117.457337][ C0] do_fault_around+0x300/0x5a0 [ 1117.457338][ C0] do_pte_missing+0x86f/0xcc0 [ 1117.457340][ C0] handle_pte_fault+0x426/0x710 [ 1117.457341][ C0] __handle_mm_fault+0x3b7/0x5b0 [ 1117.457342][ C0] handle_mm_fault+0x182/0x4a0 [ 1117.457344][ C0] do_user_addr_fault+0x46c/0xe20 [ 1117.457345][ C0] exc_page_fault+0x87/0x100 [ 1117.457346][ C0] asm_exc_page_fault+0x26/0x30 [ 1117.457347][ C0] [ 1117.457348][ C0] [ 1117.457348][ C0] stack backtrace: [ 1117.457350][ C0] CPU: 0 UID: 0 PID: 28978 Comm: mptcp_join.sh Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 1117.457354][ C0] Tainted: [W]=WARN [ 1117.457354][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 1117.457356][ C0] Call Trace: [ 1117.457357][ C0] [ 1117.457359][ C0] dump_stack_lvl+0x6f/0xa0 [ 1117.457362][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 1117.457364][ C0] mark_lock_irq+0x989/0x9c0 [ 1117.457366][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 1117.457368][ C0] mark_lock+0x1d7/0xa00 [ 1117.457370][ C0] mark_usage+0x42/0x170 [ 1117.457371][ C0] __lock_acquire+0x388/0xc20 [ 1117.457373][ C0] lock_acquire.part.0+0xd4/0x280 [ 1117.457375][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 1117.457376][ C0] ? rcu_is_watching+0x16/0xd0 [ 1117.457379][ C0] ? lock_acquire+0x13c/0x160 [ 1117.457381][ C0] console_lock_spinning_enable+0x5c/0x60 [ 1117.457382][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 1117.457384][ C0] console_emit_next_record+0x1d1/0x4c0 [ 1117.457386][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 1117.457387][ C0] ? console_flush_one_record+0x106/0x710 [ 1117.457389][ C0] ? rcu_is_watching+0x16/0xd0 [ 1117.457391][ C0] ? lock_acquire+0x13c/0x160 [ 1117.457392][ C0] console_flush_one_record+0x46f/0x710 [ 1117.457394][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 1117.457396][ C0] ? __lock_acquire+0x518/0xc20 [ 1117.457397][ C0] ? insn_get_modrm+0x98b/0xb40 [ 1117.457399][ C0] console_unlock+0xee/0x1f0 [ 1117.457401][ C0] ? console_flush_one_record+0x710/0x710 [ 1117.457402][ C0] ? rcu_is_watching+0x16/0xd0 [ 1117.457404][ C0] ? lock_acquire+0x60/0x160 [ 1117.457405][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 1117.457407][ C0] ? vprintk_emit+0x320/0x3e0 [ 1117.457408][ C0] vprintk_emit+0x37c/0x3e0 [ 1117.457410][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 1117.457413][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 1117.457414][ C0] _printk+0xc7/0x100 [ 1117.457416][ C0] ? snapshot_read.cold+0x21/0x21 [ 1117.457418][ C0] ? _raw_spin_unlock_irq_enable+0x6d/0x90 [ 1117.457421][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 1117.457422][ C0] ? __rwlock_init+0x150/0x150 [ 1117.457424][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 1117.457425][ C0] call_timer_fn+0x160/0x4d0 [ 1117.457427][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 1117.457429][ C0] ? _raw_spin_unlock_irq+0x28/0x50 [ 1117.457431][ C0] __run_timers+0x68f/0xaa0 [ 1117.457433][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 1117.457435][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 1117.457436][ C0] ? __lock_acquire+0x518/0xc20 [ 1117.457439][ C0] ? __rwlock_init+0x150/0x150 [ 1117.457441][ C0] run_timer_softirq+0xf0/0x160 [ 1117.457442][ C0] ? __run_timers+0xaa0/0xaa0 [ 1117.457444][ C0] ? handle_softirqs+0x19c/0x900 [ 1117.457445][ C0] ? rcu_is_watching+0x16/0xd0 [ 1117.457446][ C0] handle_softirqs+0x1d3/0x900 [ 1117.457448][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 1117.457449][ C0] ? _local_bh_enable+0xc0/0xc0 [ 1117.457451][ C0] __irq_exit_rcu+0x145/0x1c0 [ 1117.457452][ C0] irq_exit_rcu+0xe/0x30 [ 1117.457453][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 1117.457455][ C0] [ 1117.457456][ C0] [ 1117.457456][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 1117.457458][ C0] RIP: 0010:do_fault_around+0x300/0x5a0 [ 1117.457461][ C0] Code: 01 e6 48 01 ea ff d0 89 c3 e8 5c b4 de 01 85 c0 75 27 48 c7 c7 00 4b 1a b0 48 8d 35 00 00 00 00 e8 b5 70 90 ff e8 a0 21 9a ff <48> 83 c4 30 89 d8 5b 5d 41 5c 41 5d 41 5e c3 e8 cc 01 99 ff 84 c0 [ 1117.457462][ C0] RSP: 0000:ffa0000003a67be8 EFLAGS: 00000293 [ 1117.457465][ C0] RAX: ff1100000c4146c0 RBX: 0000000000000100 RCX: 0000000000000000 [ 1117.457466][ C0] RDX: 0000000000000007 RSI: ffffffffafc40617 RDI: ff1100000c414b44 [ 1117.457467][ C0] RBP: 00000000000000d0 R08: ffffffffad359644 R09: 1ffffffff615a97b [ 1117.457468][ C0] R10: 0000000000000000 R11: fffffbfff615a97c R12: 00000000000000c0 [ 1117.457468][ C0] R13: ffa0000003a67d98 R14: 00000000000000c9 R15: 0000000000000000 [ 1117.457470][ C0] ? trace_mm_filemap_map_pages+0x34/0x1a0 [ 1117.457474][ C0] ? do_fault_around+0x300/0x5a0 [ 1117.457476][ C0] ? filemap_update_page+0xdd0/0xdd0 [ 1117.457478][ C0] do_pte_missing+0x86f/0xcc0 [ 1117.457480][ C0] ? rcu_read_unlock+0x20/0x70 [ 1117.457481][ C0] handle_pte_fault+0x426/0x710 [ 1117.457483][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 1117.457485][ C0] ? irqentry_exit+0x21c/0x790 [ 1117.457486][ C0] ? irqentry_exit+0x21c/0x790 [ 1117.457488][ C0] ? do_numa_page.isra.0+0xa70/0xa70 [ 1117.457489][ C0] ? sysvec_apic_timer_interrupt+0xaa/0xe0 [ 1117.457491][ C0] ? mksaveddirty_shift+0x30/0x30 [ 1117.457492][ C0] ? lock_vma_under_rcu+0x159/0x410 [ 1117.457494][ C0] __handle_mm_fault+0x3b7/0x5b0 [ 1117.457496][ C0] ? __pmd_alloc+0x840/0x840 [ 1117.457498][ C0] ? lock_release+0xdc/0x1f0 [ 1117.457501][ C0] handle_mm_fault+0x182/0x4a0 [ 1117.457503][ C0] do_user_addr_fault+0x46c/0xe20 [ 1117.457505][ C0] ? irqentry_enter+0x9f/0xd0 [ 1117.457507][ C0] exc_page_fault+0x87/0x100 [ 1117.457508][ C0] asm_exc_page_fault+0x26/0x30 [ 1117.457510][ C0] RIP: 0033:0x7f19996c9126 [ 1117.457513][ C0] Code: 7d e0 e8 7d af f5 ff 45 31 c0 31 d2 31 f6 64 48 8b 04 25 10 00 00 00 bf 11 00 20 01 4c 8d 90 d0 02 00 00 b8 38 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 5a 89 c3 85 c0 75 2f 64 48 8b 04 25 10 00 00 [ 1117.457513][ C0] RSP: 002b:00007ffd5f08bed0 EFLAGS: 00010246 [ 1117.457515][ C0] RAX: 0000000000000000 RBX: 0000000000000001 RCX: 00007f19996c9126 [ 1117.457515][ C0] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000001200011 [ 1117.457516][ C0] RBP: 00007ffd5f08bef0 R08: 0000000000000000 R09: 0000000000000020 [ 1117.457517][ C0] R10: 00007f1999607e50 R11: 0000000000000246 R12: 0000560b4c73d2b0 [ 1117.457518][ C0] R13: 0000560b4c79dde0 R14: 0000000000000000 R15: 00000000ffffffff [ 1117.457520][ C0] [ 1117.457524][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 1117.457526][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 28978, name: mptcp_join.sh [ 1117.457527][ C0] preempt_count: 102, expected: 0 [ 1117.457528][ C0] RCU nest depth: 0, expected: 0 [ 1117.457529][ C0] INFO: lockdep is turned off. [ 1117.457529][ C0] irq event stamp: 807 [ 1117.457530][ C0] hardirqs last enabled at (806): [] irqentry_exit+0x21c/0x790 [ 1117.457532][ C0] hardirqs last disabled at (807): [] console_emit_next_record+0x3d4/0x4c0 [ 1117.457534][ C0] softirqs last enabled at (490): [] handle_softirqs+0x67c/0x900 [ 1117.457535][ C0] softirqs last disabled at (713): [] __irq_exit_rcu+0x145/0x1c0 [ 1117.457536][ C0] Preemption disabled at: [ 1117.457537][ C0] [] handle_softirqs+0xf6/0x900 [ 1117.457539][ C0] CPU: 0 UID: 0 PID: 28978 Comm: mptcp_join.sh Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 1117.457541][ C0] Tainted: [W]=WARN [ 1117.457541][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 1117.457542][ C0] Call Trace: [ 1117.457542][ C0] [ 1117.457543][ C0] dump_stack_lvl+0x6f/0xa0 [ 1117.457545][ C0] ? handle_softirqs+0xf6/0x900 [ 1117.457546][ C0] __might_resched.cold+0x1fe/0x2c1 [ 1117.457549][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 1117.457552][ C0] ? rcu_is_watching+0x16/0xd0 [ 1117.457554][ C0] __kmalloc_noprof+0x443/0x760 [ 1117.457555][ C0] ? __rwlock_init+0x150/0x150 [ 1117.457557][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 1117.457559][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 1117.457560][ C0] alloc_buf.isra.0+0x4b/0x260 [ 1117.457563][ C0] put_chars+0x1e1/0x2f0 [ 1117.457565][ C0] ? __send_to_port+0x420/0x420 [ 1117.457568][ C0] ? mark_lock+0x1d7/0xa00 [ 1117.457570][ C0] hvc_console_print+0x292/0x780 [ 1117.457573][ C0] ? hvc_write+0x3a0/0x3a0 [ 1117.457576][ C0] ? rcu_is_watching+0x16/0xd0 [ 1117.457577][ C0] ? rcu_is_watching+0x16/0xd0 [ 1117.457579][ C0] console_emit_next_record+0x22f/0x4c0 [ 1117.457581][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 1117.457583][ C0] ? console_flush_one_record+0x106/0x710 [ 1117.457585][ C0] ? rcu_is_watching+0x16/0xd0 [ 1117.457586][ C0] ? lock_acquire+0x13c/0x160 [ 1117.457588][ C0] console_flush_one_record+0x46f/0x710 [ 1117.457590][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 1117.457592][ C0] ? __lock_acquire+0x518/0xc20 [ 1117.457593][ C0] ? insn_get_modrm+0x98b/0xb40 [ 1117.457595][ C0] console_unlock+0xee/0x1f0 [ 1117.457596][ C0] ? console_flush_one_record+0x710/0x710 [ 1117.457598][ C0] ? rcu_is_watching+0x16/0xd0 [ 1117.457599][ C0] ? lock_acquire+0x60/0x160 [ 1117.457606][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 1117.457607][ C0] ? vprintk_emit+0x320/0x3e0 [ 1117.457609][ C0] vprintk_emit+0x37c/0x3e0 [ 1117.457611][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 1117.457613][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 1117.457615][ C0] _printk+0xc7/0x100 [ 1117.457616][ C0] ? snapshot_read.cold+0x21/0x21 [ 1117.457619][ C0] ? _raw_spin_unlock_irq_enable+0x6d/0x90 [ 1117.457621][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 1117.457622][ C0] ? __rwlock_init+0x150/0x150 [ 1117.457624][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 1117.457625][ C0] call_timer_fn+0x160/0x4d0 [ 1117.457627][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 1117.457629][ C0] ? _raw_spin_unlock_irq+0x28/0x50 [ 1117.457631][ C0] __run_timers+0x68f/0xaa0 [ 1117.457633][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 1117.457635][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 1117.457636][ C0] ? __lock_acquire+0x518/0xc20 [ 1117.457638][ C0] ? __rwlock_init+0x150/0x150 [ 1117.457640][ C0] run_timer_softirq+0xf0/0x160 [ 1117.457642][ C0] ? __run_timers+0xaa0/0xaa0 [ 1117.457644][ C0] ? handle_softirqs+0x19c/0x900 [ 1117.457645][ C0] ? rcu_is_watching+0x16/0xd0 [ 1117.457646][ C0] handle_softirqs+0x1d3/0x900 [ 1117.457648][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 1117.457649][ C0] ? _local_bh_enable+0xc0/0xc0 [ 1117.457651][ C0] __irq_exit_rcu+0x145/0x1c0 [ 1117.457652][ C0] irq_exit_rcu+0xe/0x30 [ 1117.457653][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 1117.457655][ C0] [ 1117.457656][ C0] [ 1117.457656][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 1117.457658][ C0] RIP: 0010:do_fault_around+0x300/0x5a0 [ 1117.457659][ C0] Code: 01 e6 48 01 ea ff d0 89 c3 e8 5c b4 de 01 85 c0 75 27 48 c7 c7 00 4b 1a b0 48 8d 35 00 00 00 00 e8 b5 70 90 ff e8 a0 21 9a ff <48> 83 c4 30 89 d8 5b 5d 41 5c 41 5d 41 5e c3 e8 cc 01 99 ff 84 c0 [ 1117.457660][ C0] RSP: 0000:ffa0000003a67be8 EFLAGS: 00000293 [ 1117.457661][ C0] RAX: ff1100000c4146c0 RBX: 0000000000000100 RCX: 0000000000000000 [ 1117.457662][ C0] RDX: 0000000000000007 RSI: ffffffffafc40617 RDI: ff1100000c414b44 [ 1117.457663][ C0] RBP: 00000000000000d0 R08: ffffffffad359644 R09: 1ffffffff615a97b [ 1117.457664][ C0] R10: 0000000000000000 R11: fffffbfff615a97c R12: 00000000000000c0 [ 1117.457664][ C0] R13: ffa0000003a67d98 R14: 00000000000000c9 R15: 0000000000000000 [ 1117.457665][ C0] ? trace_mm_filemap_map_pages+0x34/0x1a0 [ 1117.457668][ C0] ? do_fault_around+0x300/0x5a0 [ 1117.457670][ C0] ? filemap_update_page+0xdd0/0xdd0 [ 1117.457671][ C0] do_pte_missing+0x86f/0xcc0 [ 1117.457673][ C0] ? rcu_read_unlock+0x20/0x70 [ 1117.457675][ C0] handle_pte_fault+0x426/0x710 [ 1117.457676][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 1117.457678][ C0] ? irqentry_exit+0x21c/0x790 [ 1117.457679][ C0] ? irqentry_exit+0x21c/0x790 [ 1117.457681][ C0] ? do_numa_page.isra.0+0xa70/0xa70 [ 1117.457683][ C0] ? sysvec_apic_timer_interrupt+0xaa/0xe0 [ 1117.457684][ C0] ? mksaveddirty_shift+0x30/0x30 [ 1117.457685][ C0] ? lock_vma_under_rcu+0x159/0x410 [ 1117.457687][ C0] __handle_mm_fault+0x3b7/0x5b0 [ 1117.457689][ C0] ? __pmd_alloc+0x840/0x840 [ 1117.457691][ C0] ? lock_release+0xdc/0x1f0 [ 1117.457693][ C0] handle_mm_fault+0x182/0x4a0 [ 1117.457696][ C0] do_user_addr_fault+0x46c/0xe20 [ 1117.457698][ C0] ? irqentry_enter+0x9f/0xd0 [ 1117.457699][ C0] exc_page_fault+0x87/0x100 [ 1117.457701][ C0] asm_exc_page_fault+0x26/0x30 [ 1117.457702][ C0] RIP: 0033:0x7f19996c9126 [ 1117.457703][ C0] Code: 7d e0 e8 7d af f5 ff 45 31 c0 31 d2 31 f6 64 48 8b 04 25 10 00 00 00 bf 11 00 20 01 4c 8d 90 d0 02 00 00 b8 38 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 5a 89 c3 85 c0 75 2f 64 48 8b 04 25 10 00 00 [ 1117.457704][ C0] RSP: 002b:00007ffd5f08bed0 EFLAGS: 00010246 [ 1117.457705][ C0] RAX: 0000000000000000 RBX: 0000000000000001 RCX: 00007f19996c9126 [ 1117.457706][ C0] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000001200011 [ 1117.457706][ C0] RBP: 00007ffd5f08bef0 R08: 0000000000000000 R09: 0000000000000020 [ 1117.457707][ C0] R10: 00007f1999607e50 R11: 0000000000000246 R12: 0000560b4c73d2b0 [ 1117.457708][ C0] R13: 0000560b4c79dde0 R14: 0000000000000000 R15: 00000000ffffffff [ 1117.457710][ C0]