[ 10.695592][ T204] ip (204) used greatest stack depth: 24592 bytes left [ 10.695612][ T204] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.695614][ T204] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 204, name: ip [ 10.695616][ T204] preempt_count: 2, expected: 0 [ 10.695616][ T204] RCU nest depth: 0, expected: 0 [ 10.695617][ T204] locks held by ip/204: 5, last CPU#3: [ 10.695619][ T204] #0: ffffffff9ec167b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 10.695631][ T204] #1: ffffffff9ed7ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.695635][ T204] #2: ffffffff9ed7de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.695640][ T204] #3: ffffffff9ec9d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.695643][ T204] #4: ffffffff9ec9d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.695647][ T204] irq event stamp: 27078 [ 10.695648][ T204] hardirqs last enabled at (27077): [] __down_trylock_console_sem+0x86/0xa0 [ 10.695652][ T204] hardirqs last disabled at (27078): [] console_emit_next_record+0x3d4/0x4c0 [ 10.695654][ T204] softirqs last enabled at (25538): [] handle_softirqs+0x67c/0x900 [ 10.695656][ T204] softirqs last disabled at (24295): [] __irq_exit_rcu+0x145/0x1c0 [ 10.695658][ T204] Preemption disabled at: [ 10.695659][ T204] [<0000000000000000>] 0x0 [ 10.695666][ T204] CPU: 3 UID: 0 PID: 204 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.695669][ T204] Tainted: [W]=WARN [ 10.695670][ T204] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.695671][ T204] Call Trace: [ 10.695673][ T204] [ 10.695675][ T204] dump_stack_lvl+0x6f/0xa0 [ 10.695681][ T204] __might_resched.cold+0x1fe/0x2c1 [ 10.695686][ T204] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.695690][ T204] ? __kmalloc_noprof+0xdb/0x760 [ 10.695695][ T204] __kmalloc_noprof+0x443/0x760 [ 10.695696][ T204] ? alloc_buf.isra.0+0x4b/0x260 [ 10.695704][ T204] ? do_raw_spin_unlock+0x59/0x250 [ 10.695707][ T204] alloc_buf.isra.0+0x4b/0x260 [ 10.695711][ T204] put_chars+0x1e1/0x2f0 [ 10.695713][ T204] ? __send_to_port+0x420/0x420 [ 10.695714][ T204] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.695718][ T204] ? validate_chain+0x38b/0xc20 [ 10.695723][ T204] hvc_console_print+0x292/0x780 [ 10.695731][ T204] ? hvc_write+0x3a0/0x3a0 [ 10.695734][ T204] ? rcu_is_watching+0x16/0xd0 [ 10.695736][ T204] ? lock_acquire+0x13c/0x160 [ 10.695740][ T204] console_emit_next_record+0x22f/0x4c0 [ 10.695744][ T204] ? devkmsg_read+0x4b0/0x4b0 [ 10.695746][ T204] ? console_flush_one_record+0x106/0x710 [ 10.695749][ T204] ? rcu_is_watching+0x16/0xd0 [ 10.695751][ T204] ? lock_acquire+0x13c/0x160 [ 10.695755][ T204] console_flush_one_record+0x46f/0x710 [ 10.695759][ T204] ? console_emit_next_record+0x4c0/0x4c0 [ 10.695761][ T204] ? __lock_acquire+0x518/0xc20 [ 10.695766][ T204] console_unlock+0xee/0x1f0 [ 10.695769][ T204] ? console_flush_one_record+0x710/0x710 [ 10.695771][ T204] ? rcu_is_watching+0x16/0xd0 [ 10.695773][ T204] ? lock_acquire+0xe0/0x160 [ 10.695777][ T204] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.695778][ T204] ? vprintk_emit+0x320/0x3e0 [ 10.695781][ T204] vprintk_emit+0x37c/0x3e0 [ 10.695784][ T204] ? wake_up_klogd_work_func+0x90/0x90 [ 10.695787][ T204] ? __lock_acquire+0x518/0xc20 [ 10.695791][ T204] _printk+0xc7/0x100 [ 10.695795][ T204] ? snapshot_read.cold+0x21/0x21 [ 10.695797][ T204] ? do_raw_spin_lock+0x131/0x280 [ 10.695800][ T204] ? __rwlock_init+0x150/0x150 [ 10.695804][ T204] ? do_raw_spin_lock+0x131/0x280 [ 10.695807][ T204] do_exit.cold+0x82/0x9c [ 10.695811][ T204] ? exit_notify+0x890/0x890 [ 10.695812][ T204] ? __lock_release.isra.0+0x69/0x1a0 [ 10.695815][ T204] ? rcu_is_watching+0x16/0xd0 [ 10.695819][ T204] do_group_exit+0xb8/0x370 [ 10.695822][ T204] __x64_sys_exit_group+0x3c/0x50 [ 10.695823][ T204] x64_sys_call+0x1567/0x1570 [ 10.695826][ T204] do_syscall_64+0xff/0x530 [ 10.695830][ T204] ? exc_page_fault+0xee/0x100 [ 10.695833][ T204] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.695835][ T204] RIP: 0033:0x7f079d4371b8 [ 10.695837][ T204] Code: Unable to access opcode bytes at 0x7f079d43718e. [ 10.695838][ T204] RSP: 002b:00007fff0555bd08 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 10.695840][ T204] RAX: ffffffffffffffda RBX: 00007f079d567f88 RCX: 00007f079d4371b8 [ 10.695842][ T204] RDX: 00007f079d181fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 10.695843][ T204] RBP: 00007fff0555bd60 R08: 0000000000000000 R09: 0000000000000050 [ 10.695843][ T204] R10: 00007fff0555bb20 R11: 0000000000000246 R12: 0000000000000001 [ 10.695844][ T204] R13: 0000000000000000 R14: 00007f079d566680 R15: 00007f079d567fa0 [ 10.695851][ T204] [ 11.078955][ T214] ip (214) used greatest stack depth: 24024 bytes left [ 75.437309][ T1143] ip (1143) used greatest stack depth: 23808 bytes left [ 75.437327][ T1143] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 75.437329][ T1143] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1143, name: ip [ 75.437331][ T1143] preempt_count: 2, expected: 0 [ 75.437332][ T1143] RCU nest depth: 0, expected: 0 [ 75.437333][ T1143] locks held by ip/1143: 5, last CPU#2: [ 75.437334][ T1143] #0: ffffffff9ec167b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 75.437346][ T1143] #1: ffffffff9ed7ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 75.437351][ T1143] #2: ffffffff9ed7de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 75.437355][ T1143] #3: ffffffff9ec9d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 75.437359][ T1143] #4: ffffffff9ec9d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 75.437364][ T1143] irq event stamp: 26236 [ 75.437365][ T1143] hardirqs last enabled at (26235): [] __down_trylock_console_sem+0x86/0xa0 [ 75.437368][ T1143] hardirqs last disabled at (26236): [] console_emit_next_record+0x3d4/0x4c0 [ 75.437370][ T1143] softirqs last enabled at (24666): [] netlink_release+0x17b/0xcf0 [ 75.437374][ T1143] softirqs last disabled at (24664): [] netlink_release+0xd2/0xcf0 [ 75.437377][ T1143] Preemption disabled at: [ 75.437378][ T1143] [<0000000000000000>] 0x0 [ 75.437385][ T1143] CPU: 2 UID: 0 PID: 1143 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 75.437388][ T1143] Tainted: [W]=WARN [ 75.437389][ T1143] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 75.437391][ T1143] Call Trace: [ 75.437392][ T1143] [ 75.437393][ T1143] dump_stack_lvl+0x6f/0xa0 [ 75.437400][ T1143] __might_resched.cold+0x1fe/0x2c1 [ 75.437405][ T1143] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 75.437409][ T1143] ? __kmalloc_noprof+0xdb/0x760 [ 75.437414][ T1143] __kmalloc_noprof+0x443/0x760 [ 75.437416][ T1143] ? alloc_buf.isra.0+0x4b/0x260 [ 75.437422][ T1143] ? do_raw_spin_unlock+0x59/0x250 [ 75.437425][ T1143] alloc_buf.isra.0+0x4b/0x260 [ 75.437429][ T1143] put_chars+0x1e1/0x2f0 [ 75.437432][ T1143] ? __send_to_port+0x420/0x420 [ 75.437433][ T1143] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 75.437436][ T1143] ? validate_chain+0x38b/0xc20 [ 75.437442][ T1143] hvc_console_print+0x292/0x780 [ 75.437449][ T1143] ? hvc_write+0x3a0/0x3a0 [ 75.437452][ T1143] ? rcu_is_watching+0x16/0xd0 [ 75.437454][ T1143] ? lock_acquire+0x13c/0x160 [ 75.437458][ T1143] console_emit_next_record+0x22f/0x4c0 [ 75.437463][ T1143] ? devkmsg_read+0x4b0/0x4b0 [ 75.437464][ T1143] ? console_flush_one_record+0x106/0x710 [ 75.437468][ T1143] ? rcu_is_watching+0x16/0xd0 [ 75.437470][ T1143] ? lock_acquire+0x13c/0x160 [ 75.437474][ T1143] console_flush_one_record+0x46f/0x710 [ 75.437478][ T1143] ? console_emit_next_record+0x4c0/0x4c0 [ 75.437480][ T1143] ? __lock_acquire+0x518/0xc20 [ 75.437485][ T1143] console_unlock+0xee/0x1f0 [ 75.437488][ T1143] ? console_flush_one_record+0x710/0x710 [ 75.437490][ T1143] ? rcu_is_watching+0x16/0xd0 [ 75.437492][ T1143] ? lock_acquire+0xe0/0x160 [ 75.437495][ T1143] ? __down_trylock_console_sem+0x5e/0xa0 [ 75.437497][ T1143] ? vprintk_emit+0x320/0x3e0 [ 75.437500][ T1143] vprintk_emit+0x37c/0x3e0 [ 75.437503][ T1143] ? wake_up_klogd_work_func+0x90/0x90 [ 75.437506][ T1143] ? __lock_acquire+0x518/0xc20 [ 75.437510][ T1143] _printk+0xc7/0x100 [ 75.437514][ T1143] ? snapshot_read.cold+0x21/0x21 [ 75.437517][ T1143] ? do_raw_spin_lock+0x131/0x280 [ 75.437519][ T1143] ? __rwlock_init+0x150/0x150 [ 75.437523][ T1143] ? do_raw_spin_lock+0x131/0x280 [ 75.437526][ T1143] do_exit.cold+0x82/0x9c [ 75.437529][ T1143] ? exit_notify+0x890/0x890 [ 75.437531][ T1143] ? __lock_release.isra.0+0x69/0x1a0 [ 75.437534][ T1143] ? rcu_is_watching+0x16/0xd0 [ 75.437539][ T1143] do_group_exit+0xb8/0x370 [ 75.437542][ T1143] __x64_sys_exit_group+0x3c/0x50 [ 75.437544][ T1143] x64_sys_call+0x1567/0x1570 [ 75.437547][ T1143] do_syscall_64+0xff/0x530 [ 75.437551][ T1143] ? exc_page_fault+0xee/0x100 [ 75.437555][ T1143] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 75.437557][ T1143] RIP: 0033:0x7fc19a0391b8 [ 75.437559][ T1143] Code: Unable to access opcode bytes at 0x7fc19a03918e. [ 75.437560][ T1143] RSP: 002b:00007fff07f0d868 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 75.437563][ T1143] RAX: ffffffffffffffda RBX: 00007fc19a169f88 RCX: 00007fc19a0391b8 [ 75.437564][ T1143] RDX: 00007fc199d83fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 75.437565][ T1143] RBP: 00007fff07f0d8c0 R08: 0000000000000000 R09: 0000000000008010 [ 75.437566][ T1143] R10: 00007fff07f0d680 R11: 0000000000000246 R12: 0000000000000001 [ 75.437567][ T1143] R13: 0000000000000000 R14: 00007fc19a168680 R15: 00007fc19a169fa0 [ 75.437574][ T1143] [ 83.258043][ T1416] mpls_gso: MPLS GSO support [ 83.258059][ T1416] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 83.258061][ T1416] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1416, name: modprobe [ 83.258063][ T1416] preempt_count: 1, expected: 0 [ 83.258063][ T1416] RCU nest depth: 0, expected: 0 [ 83.258064][ T1416] locks held by modprobe/1416: 4, last CPU#2: [ 83.258066][ T1416] #0: ffffffff9ed7ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 83.258077][ T1416] #1: ffffffff9ed7de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 83.258082][ T1416] #2: ffffffff9ec9d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 83.258086][ T1416] #3: ffffffff9ec9d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 83.258089][ T1416] irq event stamp: 64654 [ 83.258090][ T1416] hardirqs last enabled at (64653): [] __down_trylock_console_sem+0x86/0xa0 [ 83.258093][ T1416] hardirqs last disabled at (64654): [] console_emit_next_record+0x3d4/0x4c0 [ 83.258100][ T1416] softirqs last enabled at (63878): [] handle_softirqs+0x67c/0x900 [ 83.258104][ T1416] softirqs last disabled at (63869): [] __irq_exit_rcu+0x145/0x1c0 [ 83.258106][ T1416] Preemption disabled at: [ 83.258107][ T1416] [] vprintk_emit+0x31b/0x3e0 [ 83.258112][ T1416] CPU: 2 UID: 0 PID: 1416 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 83.258115][ T1416] Tainted: [W]=WARN [ 83.258116][ T1416] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 83.258117][ T1416] Call Trace: [ 83.258119][ T1416] [ 83.258121][ T1416] dump_stack_lvl+0x6f/0xa0 [ 83.258127][ T1416] ? vprintk_emit+0x31b/0x3e0 [ 83.258128][ T1416] __might_resched.cold+0x1fe/0x2c1 [ 83.258133][ T1416] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 83.258136][ T1416] ? __kmalloc_noprof+0xdb/0x760 [ 83.258141][ T1416] __kmalloc_noprof+0x443/0x760 [ 83.258142][ T1416] ? alloc_buf.isra.0+0x4b/0x260 [ 83.258149][ T1416] ? do_raw_spin_unlock+0x59/0x250 [ 83.258152][ T1416] alloc_buf.isra.0+0x4b/0x260 [ 83.258155][ T1416] put_chars+0x1e1/0x2f0 [ 83.258157][ T1416] ? desc_read_finalized_seq+0x79/0x120 [ 83.258159][ T1416] ? __send_to_port+0x420/0x420 [ 83.258162][ T1416] ? rcu_read_lock_any_held+0x3c/0x90 [ 83.258165][ T1416] ? validate_chain+0x38b/0xc20 [ 83.258170][ T1416] hvc_console_print+0x292/0x780 [ 83.258173][ T1416] ? __lock_acquire+0x518/0xc20 [ 83.258175][ T1416] ? __lock_acquire+0x518/0xc20 [ 83.258179][ T1416] ? hvc_write+0x3a0/0x3a0 [ 83.258181][ T1416] ? console_emit_next_record+0x1df/0x4c0 [ 83.258184][ T1416] ? rcu_is_watching+0x16/0xd0 [ 83.258186][ T1416] ? lock_acquire+0x13c/0x160 [ 83.258190][ T1416] console_emit_next_record+0x22f/0x4c0 [ 83.258194][ T1416] ? devkmsg_read+0x4b0/0x4b0 [ 83.258196][ T1416] ? console_flush_one_record+0x106/0x710 [ 83.258199][ T1416] ? rcu_is_watching+0x16/0xd0 [ 83.258201][ T1416] ? lock_acquire+0x13c/0x160 [ 83.258204][ T1416] console_flush_one_record+0x46f/0x710 [ 83.258209][ T1416] ? console_emit_next_record+0x4c0/0x4c0 [ 83.258211][ T1416] ? __lock_acquire+0x518/0xc20 [ 83.258216][ T1416] console_unlock+0xee/0x1f0 [ 83.258218][ T1416] ? console_flush_one_record+0x710/0x710 [ 83.258220][ T1416] ? rcu_is_watching+0x16/0xd0 [ 83.258222][ T1416] ? lock_acquire+0xe0/0x160 [ 83.258225][ T1416] ? __down_trylock_console_sem+0x5e/0xa0 [ 83.258227][ T1416] ? vprintk_emit+0x320/0x3e0 [ 83.258230][ T1416] vprintk_emit+0x37c/0x3e0 [ 83.258233][ T1416] ? wake_up_klogd_work_func+0x90/0x90 [ 83.258238][ T1416] ? mpls_exit+0xaf0/0xaf0 [mpls_router] [ 83.258241][ T1416] _printk+0xc7/0x100 [ 83.258245][ T1416] ? snapshot_read.cold+0x21/0x21 [ 83.258250][ T1416] ? do_one_initcall+0x113/0x4f0 [ 83.258254][ T1416] mpls_gso_init+0x15/0x1000 [mpls_gso] [ 83.258256][ T1416] do_one_initcall+0x124/0x4f0 [ 83.258258][ T1416] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 83.258263][ T1416] ? do_init_module+0xae/0x8a0 [ 83.258265][ T1416] ? kasan_unpoison+0x44/0x70 [ 83.258271][ T1416] do_init_module+0x2ad/0x8a0 [ 83.258274][ T1416] ? lockdep_hardirqs_on+0x91/0x130 [ 83.258278][ T1416] ? free_module+0x470/0x470 [ 83.258280][ T1416] ? kfree+0x123/0x590 [ 83.258284][ T1416] load_module+0x1424/0x1bb0 [ 83.258288][ T1416] ? security_kernel_post_read_file+0x2f/0x60 [ 83.258296][ T1416] ? layout_and_allocate.isra.0+0x680/0x680 [ 83.258299][ T1416] ? __ia32_sys_fsconfig+0x180/0x180 [ 83.258303][ T1416] ? backing_file_read_iter+0x541/0x790 [ 83.258307][ T1416] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 83.258310][ T1416] init_module_from_file+0x11f/0x1a0 [ 83.258313][ T1416] ? __do_sys_init_module+0x270/0x270 [ 83.258320][ T1416] ? __rwlock_init+0x150/0x150 [ 83.258323][ T1416] ? do_raw_spin_unlock+0x59/0x250 [ 83.258327][ T1416] idempotent_init_module+0x37b/0x7c0 [ 83.258329][ T1416] ? init_module_from_file+0x1a0/0x1a0 [ 83.258335][ T1416] ? cap_capable+0x1d7/0x3d0 [ 83.258337][ T1416] ? __x64_sys_pread64+0x18d/0x250 [ 83.258342][ T1416] __x64_sys_finit_module+0xdf/0x190 [ 83.258343][ T1416] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 83.258348][ T1416] do_syscall_64+0xff/0x530 [ 83.258350][ T1416] ? exc_page_fault+0xee/0x100 [ 83.258353][ T1416] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 83.258355][ T1416] RIP: 0033:0x7f2fda91837d [ 83.258358][ T1416] 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 [ 83.258360][ T1416] RSP: 002b:00007ffd7c3467f8 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 83.258363][ T1416] RAX: ffffffffffffffda RBX: 00005642249e0bc0 RCX: 00007f2fda91837d [ 83.258364][ T1416] RDX: 0000000000000000 RSI: 00005641f32aaffd RDI: 0000000000000002 [ 83.258365][ T1416] RBP: 00007ffd7c346890 R08: 0000000000000000 R09: 0000000000000000 [ 83.258366][ T1416] R10: 0000000000000000 R11: 0000000000000246 R12: 00005641f32aaffd [ 83.258367][ T1416] R13: 00005642249e0cf0 R14: 0000000000040000 R15: 0000000000000000 [ 83.258373][ T1416] [ 115.651990][ T2793] ip (2793) used greatest stack depth: 23584 bytes left [ 115.652007][ T2793] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 115.652009][ T2793] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 2793, name: ip [ 115.652010][ T2793] preempt_count: 2, expected: 0 [ 115.652011][ T2793] RCU nest depth: 0, expected: 0 [ 115.652012][ T2793] locks held by ip/2793: 5, last CPU#3: [ 115.652014][ T2793] #0: ffffffff9ec167b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 115.652025][ T2793] #1: ffffffff9ed7ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 115.652030][ T2793] #2: ffffffff9ed7de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 115.652034][ T2793] #3: ffffffff9ec9d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 115.652038][ T2793] #4: ffffffff9ec9d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 115.652042][ T2793] irq event stamp: 39992 [ 115.652043][ T2793] hardirqs last enabled at (39991): [] __down_trylock_console_sem+0x86/0xa0 [ 115.652046][ T2793] hardirqs last disabled at (39992): [] console_emit_next_record+0x3d4/0x4c0 [ 115.652048][ T2793] softirqs last enabled at (38990): [] handle_softirqs+0x67c/0x900 [ 115.652050][ T2793] softirqs last disabled at (38985): [] __irq_exit_rcu+0x145/0x1c0 [ 115.652053][ T2793] Preemption disabled at: [ 115.652053][ T2793] [<0000000000000000>] 0x0 [ 115.652060][ T2793] CPU: 3 UID: 0 PID: 2793 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 115.652063][ T2793] Tainted: [W]=WARN [ 115.652064][ T2793] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 115.652066][ T2793] Call Trace: [ 115.652068][ T2793] [ 115.652069][ T2793] dump_stack_lvl+0x6f/0xa0 [ 115.652075][ T2793] __might_resched.cold+0x1fe/0x2c1 [ 115.652080][ T2793] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 115.652084][ T2793] ? __kmalloc_noprof+0xdb/0x760 [ 115.652088][ T2793] __kmalloc_noprof+0x443/0x760 [ 115.652090][ T2793] ? alloc_buf.isra.0+0x4b/0x260 [ 115.652097][ T2793] ? do_raw_spin_unlock+0x59/0x250 [ 115.652100][ T2793] alloc_buf.isra.0+0x4b/0x260 [ 115.652104][ T2793] put_chars+0x1e1/0x2f0 [ 115.652106][ T2793] ? __send_to_port+0x420/0x420 [ 115.652107][ T2793] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 115.652111][ T2793] ? validate_chain+0x38b/0xc20 [ 115.652116][ T2793] hvc_console_print+0x292/0x780 [ 115.652124][ T2793] ? hvc_write+0x3a0/0x3a0 [ 115.652127][ T2793] ? rcu_is_watching+0x16/0xd0 [ 115.652129][ T2793] ? lock_acquire+0x13c/0x160 [ 115.652133][ T2793] console_emit_next_record+0x22f/0x4c0 [ 115.652137][ T2793] ? devkmsg_read+0x4b0/0x4b0 [ 115.652139][ T2793] ? console_flush_one_record+0x106/0x710 [ 115.652142][ T2793] ? rcu_is_watching+0x16/0xd0 [ 115.652144][ T2793] ? lock_acquire+0x13c/0x160 [ 115.652148][ T2793] console_flush_one_record+0x46f/0x710 [ 115.652157][ T2793] ? console_emit_next_record+0x4c0/0x4c0 [ 115.652159][ T2793] ? __lock_acquire+0x518/0xc20 [ 115.652164][ T2793] console_unlock+0xee/0x1f0 [ 115.652167][ T2793] ? console_flush_one_record+0x710/0x710 [ 115.652169][ T2793] ? rcu_is_watching+0x16/0xd0 [ 115.652171][ T2793] ? lock_acquire+0xe0/0x160 [ 115.652175][ T2793] ? __down_trylock_console_sem+0x5e/0xa0 [ 115.652177][ T2793] ? vprintk_emit+0x320/0x3e0 [ 115.652179][ T2793] vprintk_emit+0x37c/0x3e0 [ 115.652183][ T2793] ? wake_up_klogd_work_func+0x90/0x90 [ 115.652185][ T2793] ? __lock_acquire+0x518/0xc20 [ 115.652189][ T2793] _printk+0xc7/0x100 [ 115.652193][ T2793] ? snapshot_read.cold+0x21/0x21 [ 115.652195][ T2793] ? do_raw_spin_lock+0x131/0x280 [ 115.652198][ T2793] ? __rwlock_init+0x150/0x150 [ 115.652202][ T2793] ? do_raw_spin_lock+0x131/0x280 [ 115.652205][ T2793] do_exit.cold+0x82/0x9c [ 115.652208][ T2793] ? exit_notify+0x890/0x890 [ 115.652210][ T2793] ? __lock_release.isra.0+0x69/0x1a0 [ 115.652212][ T2793] ? rcu_is_watching+0x16/0xd0 [ 115.652216][ T2793] do_group_exit+0xb8/0x370 [ 115.652219][ T2793] __x64_sys_exit_group+0x3c/0x50 [ 115.652221][ T2793] x64_sys_call+0x1567/0x1570 [ 115.652223][ T2793] do_syscall_64+0xff/0x530 [ 115.652227][ T2793] ? exc_page_fault+0xee/0x100 [ 115.652230][ T2793] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 115.652232][ T2793] RIP: 0033:0x7f88c53a21b8 [ 115.652234][ T2793] Code: Unable to access opcode bytes at 0x7f88c53a218e. [ 115.652235][ T2793] RSP: 002b:00007fff642c15d8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 115.652237][ T2793] RAX: ffffffffffffffda RBX: 00007f88c54d2f88 RCX: 00007f88c53a21b8 [ 115.652239][ T2793] RDX: 00007f88c50ecfc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 115.652240][ T2793] RBP: 00007fff642c1630 R08: 0000000000000000 R09: 0000000000008010 [ 115.652240][ T2793] R10: 00007fff642c13f0 R11: 0000000000000246 R12: 0000000000000001 [ 115.652241][ T2793] R13: 0000000000000000 R14: 00007f88c54d1680 R15: 00007f88c54d2fa0 [ 115.652248][ T2793] [ 221.328832][ T6042] ip (6042) used greatest stack depth: 23312 bytes left [ 221.328851][ T6042] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 221.328853][ T6042] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 6042, name: ip [ 221.328855][ T6042] preempt_count: 2, expected: 0 [ 221.328856][ T6042] RCU nest depth: 0, expected: 0 [ 221.328857][ T6042] locks held by ip/6042: 5, last CPU#1: [ 221.328859][ T6042] #0: ffffffff9ec167b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 221.328870][ T6042] #1: ffffffff9ed7ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 221.328875][ T6042] #2: ffffffff9ed7de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 221.328879][ T6042] #3: ffffffff9ec9d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 221.328883][ T6042] #4: ffffffff9ec9d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 221.328887][ T6042] irq event stamp: 19048 [ 221.328888][ T6042] hardirqs last enabled at (19047): [] __down_trylock_console_sem+0x86/0xa0 [ 221.328891][ T6042] hardirqs last disabled at (19048): [] console_emit_next_record+0x3d4/0x4c0 [ 221.328893][ T6042] softirqs last enabled at (18820): [] handle_softirqs+0x67c/0x900 [ 221.328895][ T6042] softirqs last disabled at (18551): [] __irq_exit_rcu+0x145/0x1c0 [ 221.328897][ T6042] Preemption disabled at: [ 221.328898][ T6042] [<0000000000000000>] 0x0 [ 221.328905][ T6042] CPU: 1 UID: 0 PID: 6042 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 221.328908][ T6042] Tainted: [W]=WARN [ 221.328909][ T6042] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 221.328910][ T6042] Call Trace: [ 221.328912][ T6042] [ 221.328914][ T6042] dump_stack_lvl+0x6f/0xa0 [ 221.328920][ T6042] __might_resched.cold+0x1fe/0x2c1 [ 221.328924][ T6042] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 221.328928][ T6042] ? __kmalloc_noprof+0xdb/0x760 [ 221.328933][ T6042] __kmalloc_noprof+0x443/0x760 [ 221.328935][ T6042] ? alloc_buf.isra.0+0x4b/0x260 [ 221.328941][ T6042] ? do_raw_spin_unlock+0x59/0x250 [ 221.328944][ T6042] alloc_buf.isra.0+0x4b/0x260 [ 221.328948][ T6042] put_chars+0x1e1/0x2f0 [ 221.328950][ T6042] ? __send_to_port+0x420/0x420 [ 221.328951][ T6042] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 221.328954][ T6042] ? validate_chain+0x38b/0xc20 [ 221.328960][ T6042] hvc_console_print+0x292/0x780 [ 221.328967][ T6042] ? hvc_write+0x3a0/0x3a0 [ 221.328970][ T6042] ? rcu_is_watching+0x16/0xd0 [ 221.328971][ T6042] ? lock_acquire+0x13c/0x160 [ 221.328975][ T6042] console_emit_next_record+0x22f/0x4c0 [ 221.328979][ T6042] ? devkmsg_read+0x4b0/0x4b0 [ 221.328981][ T6042] ? console_flush_one_record+0x106/0x710 [ 221.328984][ T6042] ? rcu_is_watching+0x16/0xd0 [ 221.328986][ T6042] ? lock_acquire+0x13c/0x160 [ 221.328990][ T6042] console_flush_one_record+0x46f/0x710 [ 221.328994][ T6042] ? console_emit_next_record+0x4c0/0x4c0 [ 221.328996][ T6042] ? __lock_acquire+0x518/0xc20 [ 221.329001][ T6042] console_unlock+0xee/0x1f0 [ 221.329004][ T6042] ? console_flush_one_record+0x710/0x710 [ 221.329005][ T6042] ? rcu_is_watching+0x16/0xd0 [ 221.329007][ T6042] ? lock_acquire+0xe0/0x160 [ 221.329011][ T6042] ? __down_trylock_console_sem+0x5e/0xa0 [ 221.329012][ T6042] ? vprintk_emit+0x320/0x3e0 [ 221.329015][ T6042] vprintk_emit+0x37c/0x3e0 [ 221.329018][ T6042] ? wake_up_klogd_work_func+0x90/0x90 [ 221.329021][ T6042] ? __lock_acquire+0x518/0xc20 [ 221.329024][ T6042] _printk+0xc7/0x100 [ 221.329028][ T6042] ? snapshot_read.cold+0x21/0x21 [ 221.329030][ T6042] ? do_raw_spin_lock+0x131/0x280 [ 221.329033][ T6042] ? __rwlock_init+0x150/0x150 [ 221.329037][ T6042] ? do_raw_spin_lock+0x131/0x280 [ 221.329039][ T6042] do_exit.cold+0x82/0x9c [ 221.329043][ T6042] ? exit_notify+0x890/0x890 [ 221.329044][ T6042] ? __lock_release.isra.0+0x69/0x1a0 [ 221.329047][ T6042] ? rcu_is_watching+0x16/0xd0 [ 221.329050][ T6042] do_group_exit+0xb8/0x370 [ 221.329053][ T6042] __x64_sys_exit_group+0x3c/0x50 [ 221.329055][ T6042] x64_sys_call+0x1567/0x1570 [ 221.329057][ T6042] do_syscall_64+0xff/0x530 [ 221.329061][ T6042] ? exc_page_fault+0xee/0x100 [ 221.329064][ T6042] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 221.329066][ T6042] RIP: 0033:0x7fec7f78e1b8 [ 221.329068][ T6042] Code: Unable to access opcode bytes at 0x7fec7f78e18e. [ 221.329069][ T6042] RSP: 002b:00007ffe3b317578 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 221.329072][ T6042] RAX: ffffffffffffffda RBX: 00007fec7f8bef88 RCX: 00007fec7f78e1b8 [ 221.329073][ T6042] RDX: 00007fec7f4d8fc8 RSI: fffffffffffffeb8 RDI: 0000000000000002 [ 221.329074][ T6042] RBP: 00007ffe3b3175d0 R08: 0000000000000000 R09: 0000000000008010 [ 221.329075][ T6042] R10: 00007ffe3b317390 R11: 0000000000000246 R12: 0000000000000001 [ 221.329076][ T6042] R13: 0000000000000002 R14: 00007fec7f8bd680 R15: 00007fec7f8befa0 [ 221.329082][ T6042] [ 235.513735][ C0] clocksource: Watchdog remote CPU 1 read timed out [ 235.513787][ C0] [ 235.513788][ C0] ======================================================== [ 235.513789][ C0] WARNING: possible irq lock inversion dependency detected [ 235.513791][ C0] 7.2.0-virtme #1 Tainted: G W [ 235.513793][ C0] -------------------------------------------------------- [ 235.513793][ C0] ping/2946 just changed the state of lock: [ 235.513795][ C0] ffffffff9ec9d760 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 235.513807][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 235.513808][ C0] (fs_reclaim){+.+.}-{0:0} [ 235.513810][ C0] [ 235.513810][ C0] [ 235.513810][ C0] and interrupts could create inverse lock ordering between them. [ 235.513810][ C0] [ 235.513810][ C0] [ 235.513810][ C0] other info that might help us debug this: [ 235.513811][ C0] Possible interrupt unsafe locking scenario: [ 235.513811][ C0] [ 235.513811][ C0] CPU0 CPU1 [ 235.513812][ C0] ---- ---- [ 235.513812][ C0] lock(fs_reclaim); [ 235.513813][ C0] local_irq_disable(); [ 235.513813][ C0] lock(console_owner); [ 235.513814][ C0] lock(fs_reclaim); [ 235.513815][ C0] [ 235.513815][ C0] lock(console_owner); [ 235.513816][ C0] [ 235.513816][ C0] *** DEADLOCK *** [ 235.513816][ C0] [ 235.513816][ C0] locks held by ping/2946: 7, last CPU#0: [ 235.513818][ C0] #0: ff1100001d204a90 (sk_lock-AF_INET){+.+.}-{0:0}, at: raw_sendmsg+0xbb5/0x1cd0 [ 235.513824][ C0] #1: ffffffff9ed94c00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 235.513828][ C0] #2: ffffffff9ed94c00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x2c6/0x1c60 [ 235.513831][ C0] #3: ffa0000000007ca8 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 235.513836][ C0] #4: ffffffff9ede29f8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 235.513839][ C0] #5: ffffffff9ed7ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 235.513842][ C0] #6: ffffffff9ed7de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 235.513845][ C0] [ 235.513845][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 235.513849][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 235.513851][ C0] HARDIRQ-ON-W at: [ 235.513852][ C0] __lock_acquire+0x388/0xc20 [ 235.513855][ C0] lock_acquire.part.0+0xd4/0x280 [ 235.513857][ C0] fs_reclaim_acquire+0xd5/0x120 [ 235.513860][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 235.513862][ C0] kthread_create_worker_on_node+0xea/0x210 [ 235.513865][ C0] workqueue_init+0x2a/0x680 [ 235.513869][ C0] kernel_init_freeable+0x2fe/0x630 [ 235.513872][ C0] kernel_init+0x21/0x150 [ 235.513875][ C0] ret_from_fork+0x474/0x6b0 [ 235.513879][ C0] ret_from_fork_asm+0x11/0x20 [ 235.513882][ C0] SOFTIRQ-ON-W at: [ 235.513882][ C0] __lock_acquire+0x388/0xc20 [ 235.513884][ C0] lock_acquire.part.0+0xd4/0x280 [ 235.513885][ C0] fs_reclaim_acquire+0xd5/0x120 [ 235.513887][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 235.513888][ C0] kthread_create_worker_on_node+0xea/0x210 [ 235.513889][ C0] workqueue_init+0x2a/0x680 [ 235.513890][ C0] kernel_init_freeable+0x2fe/0x630 [ 235.513892][ C0] kernel_init+0x21/0x150 [ 235.513893][ C0] ret_from_fork+0x474/0x6b0 [ 235.513894][ C0] ret_from_fork_asm+0x11/0x20 [ 235.513896][ C0] INITIAL USE at: [ 235.513896][ C0] __lock_acquire+0x388/0xc20 [ 235.513898][ C0] lock_acquire.part.0+0xd4/0x280 [ 235.513899][ C0] fs_reclaim_acquire+0xd5/0x120 [ 235.513900][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 235.513901][ C0] kthread_create_worker_on_node+0xea/0x210 [ 235.513903][ C0] workqueue_init+0x2a/0x680 [ 235.513904][ C0] kernel_init_freeable+0x2fe/0x630 [ 235.513906][ C0] kernel_init+0x21/0x150 [ 235.513907][ C0] ret_from_fork+0x474/0x6b0 [ 235.513908][ C0] ret_from_fork_asm+0x11/0x20 [ 235.513910][ C0] } [ 235.513910][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 235.513914][ C0] ... acquired at: [ 235.513915][ C0] __lock_acquire+0x518/0xc20 [ 235.513916][ C0] lock_acquire.part.0+0xd4/0x280 [ 235.513918][ C0] fs_reclaim_acquire+0xd5/0x120 [ 235.513919][ C0] __kmalloc_noprof+0xd3/0x760 [ 235.513920][ C0] alloc_buf.isra.0+0x4b/0x260 [ 235.513924][ C0] put_chars+0x1e1/0x2f0 [ 235.513925][ C0] hvc_console_print+0x292/0x780 [ 235.513928][ C0] console_emit_next_record+0x22f/0x4c0 [ 235.513930][ C0] console_flush_one_record+0x46f/0x710 [ 235.513931][ C0] console_unlock+0xee/0x1f0 [ 235.513933][ C0] vprintk_emit+0x37c/0x3e0 [ 235.513934][ C0] _printk+0xc7/0x100 [ 235.513937][ C0] loop_init+0x12a/0x130 [ 235.513939][ C0] do_one_initcall+0x124/0x4f0 [ 235.513941][ C0] kernel_init_freeable+0x596/0x630 [ 235.513942][ C0] kernel_init+0x21/0x150 [ 235.513943][ C0] ret_from_fork+0x474/0x6b0 [ 235.513944][ C0] ret_from_fork_asm+0x11/0x20 [ 235.513946][ C0] [ 235.513946][ C0] -> (console_owner){..-.}-{0:0} { [ 235.513948][ C0] IN-SOFTIRQ-W at: [ 235.513948][ C0] __lock_acquire+0x388/0xc20 [ 235.513950][ C0] lock_acquire.part.0+0xd4/0x280 [ 235.513951][ C0] console_lock_spinning_enable+0x5c/0x60 [ 235.513953][ C0] console_emit_next_record+0x1d1/0x4c0 [ 235.513954][ C0] console_flush_one_record+0x46f/0x710 [ 235.513956][ C0] console_unlock+0xee/0x1f0 [ 235.513958][ C0] vprintk_emit+0x37c/0x3e0 [ 235.513958][ C0] _printk+0xc7/0x100 [ 235.513960][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 235.513962][ C0] call_timer_fn+0x160/0x4d0 [ 235.513963][ C0] __run_timers+0x68f/0xaa0 [ 235.513965][ C0] run_timer_softirq+0xf0/0x160 [ 235.513967][ C0] handle_softirqs+0x1d3/0x900 [ 235.513969][ C0] do_softirq+0xac/0xe0 [ 235.513971][ C0] __local_bh_enable_ip+0x118/0x150 [ 235.513972][ C0] __dev_queue_xmit+0x989/0x1b90 [ 235.513975][ C0] ip_finish_output2+0x7c4/0x1c60 [ 235.513976][ C0] ip_output+0x1ca/0x660 [ 235.513977][ C0] ip_push_pending_frames+0xf6/0x250 [ 235.513978][ C0] raw_sendmsg+0x1179/0x1cd0 [ 235.513980][ C0] __sock_sendmsg+0xce/0x190 [ 235.513982][ C0] __sys_sendto+0x260/0x320 [ 235.513984][ C0] __x64_sys_sendto+0xe4/0x1f0 [ 235.513985][ C0] do_syscall_64+0xff/0x530 [ 235.513987][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 235.513989][ C0] INITIAL USE at: [ 235.513990][ C0] } [ 235.513990][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 235.513993][ C0] ... acquired at: [ 235.513993][ C0] mark_lock+0x1d7/0xa00 [ 235.513994][ C0] mark_usage+0x42/0x170 [ 235.513996][ C0] __lock_acquire+0x388/0xc20 [ 235.513997][ C0] lock_acquire.part.0+0xd4/0x280 [ 235.513998][ C0] console_lock_spinning_enable+0x5c/0x60 [ 235.514000][ C0] console_emit_next_record+0x1d1/0x4c0 [ 235.514001][ C0] console_flush_one_record+0x46f/0x710 [ 235.514003][ C0] console_unlock+0xee/0x1f0 [ 235.514004][ C0] vprintk_emit+0x37c/0x3e0 [ 235.514005][ C0] _printk+0xc7/0x100 [ 235.514006][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 235.514007][ C0] call_timer_fn+0x160/0x4d0 [ 235.514009][ C0] __run_timers+0x68f/0xaa0 [ 235.514010][ C0] run_timer_softirq+0xf0/0x160 [ 235.514012][ C0] handle_softirqs+0x1d3/0x900 [ 235.514013][ C0] do_softirq+0xac/0xe0 [ 235.514014][ C0] __local_bh_enable_ip+0x118/0x150 [ 235.514015][ C0] __dev_queue_xmit+0x989/0x1b90 [ 235.514017][ C0] ip_finish_output2+0x7c4/0x1c60 [ 235.514018][ C0] ip_output+0x1ca/0x660 [ 235.514019][ C0] ip_push_pending_frames+0xf6/0x250 [ 235.514020][ C0] raw_sendmsg+0x1179/0x1cd0 [ 235.514021][ C0] __sock_sendmsg+0xce/0x190 [ 235.514022][ C0] __sys_sendto+0x260/0x320 [ 235.514024][ C0] __x64_sys_sendto+0xe4/0x1f0 [ 235.514025][ C0] do_syscall_64+0xff/0x530 [ 235.514027][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 235.514028][ C0] [ 235.514028][ C0] [ 235.514028][ C0] stack backtrace: [ 235.514031][ C0] CPU: 0 UID: 0 PID: 2946 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 235.514033][ C0] Tainted: [W]=WARN [ 235.514034][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 235.514036][ C0] Call Trace: [ 235.514037][ C0] [ 235.514038][ C0] dump_stack_lvl+0x6f/0xa0 [ 235.514042][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 235.514045][ C0] mark_lock_irq+0x989/0x9c0 [ 235.514048][ C0] mark_lock+0x1d7/0xa00 [ 235.514050][ C0] mark_usage+0x42/0x170 [ 235.514051][ C0] __lock_acquire+0x388/0xc20 [ 235.514053][ C0] lock_acquire.part.0+0xd4/0x280 [ 235.514055][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 235.514057][ C0] ? rcu_is_watching+0x16/0xd0 [ 235.514059][ C0] ? lock_acquire+0x13c/0x160 [ 235.514061][ C0] console_lock_spinning_enable+0x5c/0x60 [ 235.514063][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 235.514065][ C0] console_emit_next_record+0x1d1/0x4c0 [ 235.514067][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 235.514068][ C0] ? console_flush_one_record+0x106/0x710 [ 235.514070][ C0] ? rcu_is_watching+0x16/0xd0 [ 235.514072][ C0] ? lock_acquire+0x13c/0x160 [ 235.514073][ C0] console_flush_one_record+0x46f/0x710 [ 235.514076][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 235.514077][ C0] ? __lock_acquire+0x518/0xc20 [ 235.514080][ C0] console_unlock+0xee/0x1f0 [ 235.514082][ C0] ? console_flush_one_record+0x710/0x710 [ 235.514083][ C0] ? rcu_is_watching+0x16/0xd0 [ 235.514084][ C0] ? lock_acquire+0xe0/0x160 [ 235.514086][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 235.514088][ C0] ? vprintk_emit+0x320/0x3e0 [ 235.514090][ C0] vprintk_emit+0x37c/0x3e0 [ 235.514091][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 235.514093][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 235.514095][ C0] _printk+0xc7/0x100 [ 235.514096][ C0] ? snapshot_read.cold+0x21/0x21 [ 235.514098][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 235.514100][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 235.514102][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 235.514103][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 235.514105][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 235.514107][ C0] call_timer_fn+0x160/0x4d0 [ 235.514109][ C0] ? mark_held_locks+0x40/0x70 [ 235.514110][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 235.514112][ C0] ? find_held_lock+0x2b/0x80 [ 235.514114][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 235.514115][ C0] ? mark_held_locks+0x40/0x70 [ 235.514117][ C0] __run_timers+0x68f/0xaa0 [ 235.514119][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 235.514121][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 235.514123][ C0] ? __lock_acquire+0x518/0xc20 [ 235.514126][ C0] ? __rwlock_init+0x150/0x150 [ 235.514128][ C0] run_timer_softirq+0xf0/0x160 [ 235.514130][ C0] ? __run_timers+0xaa0/0xaa0 [ 235.514132][ C0] ? rcu_is_watching+0x16/0xd0 [ 235.514133][ C0] handle_softirqs+0x1d3/0x900 [ 235.514135][ C0] ? _local_bh_enable+0xc0/0xc0 [ 235.514137][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 235.514139][ C0] ? rcu_is_watching+0x16/0xd0 [ 235.514139][ C0] ? __dev_queue_xmit+0x974/0x1b90 [ 235.514141][ C0] do_softirq+0xac/0xe0 [ 235.514143][ C0] [ 235.514143][ C0] [ 235.514144][ C0] __local_bh_enable_ip+0x118/0x150 [ 235.514146][ C0] __dev_queue_xmit+0x989/0x1b90 [ 235.514147][ C0] ? sock_queue_err_skb+0x680/0x680 [ 235.514150][ C0] ? irq_matrix_allocated+0xe0/0x120 [ 235.514153][ C0] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 235.514155][ C0] ? lock_acquire.part.0+0x60/0x280 [ 235.514156][ C0] ? find_held_lock+0x2b/0x80 [ 235.514158][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 235.514159][ C0] ? rcu_is_watching+0x16/0xd0 [ 235.514160][ C0] ? mark_held_locks+0x40/0x70 [ 235.514162][ C0] ? __asan_memcpy+0x3c/0x60 [ 235.514165][ C0] ? neigh_hh_output+0x152/0x4c0 [ 235.514167][ C0] ip_finish_output2+0x7c4/0x1c60 [ 235.514168][ C0] ? ip_dst_mtu_maybe_forward+0x2c7/0x720 [ 235.514169][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 235.514171][ C0] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 235.514172][ C0] ? rcu_is_watching+0x16/0xd0 [ 235.514174][ C0] ip_output+0x1ca/0x660 [ 235.514176][ C0] ? ip_finish_output+0x460/0x460 [ 235.514177][ C0] ? __ip_make_skb+0x106a/0x2300 [ 235.514179][ C0] ip_push_pending_frames+0xf6/0x250 [ 235.514181][ C0] raw_sendmsg+0x1179/0x1cd0 [ 235.514183][ C0] ? raw_send_hdrinc+0x1740/0x1740 [ 235.514185][ C0] ? __might_fault+0x97/0x140 [ 235.514187][ C0] ? _copy_to_user+0x4b/0x70 [ 235.514191][ C0] ? validate_chain+0x38b/0xc20 [ 235.514192][ C0] ? import_iovec+0x4e/0xa0 [ 235.514194][ C0] ? __lock_acquire+0x518/0xc20 [ 235.514196][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 235.514198][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 235.514200][ C0] __sock_sendmsg+0xce/0x190 [ 235.514202][ C0] ? fdget+0x4f/0x1e0 [ 235.514205][ C0] __sys_sendto+0x260/0x320 [ 235.514207][ C0] ? __ia32_sys_getpeername+0xd0/0xd0 [ 235.514208][ C0] ? fsnotify+0x3610/0x3610 [ 235.514211][ C0] ? __schedule+0xfa5/0x20e0 [ 235.514215][ C0] ? ksys_write+0x116/0x250 [ 235.514217][ C0] __x64_sys_sendto+0xe4/0x1f0 [ 235.514219][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 235.514222][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 235.514223][ C0] ? do_syscall_64+0xa6/0x530 [ 235.514225][ C0] do_syscall_64+0xff/0x530 [ 235.514227][ C0] ? irq_exit_rcu+0x1a/0x30 [ 235.514228][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 235.514230][ C0] RIP: 0033:0x7f0c0af5354e [ 235.514233][ C0] Code: 4d 89 d8 e8 b4 bd 00 00 4c 8b 5d f8 41 8b 93 08 03 00 00 59 5e 48 83 f8 fc 74 11 c9 c3 0f 1f 80 00 00 00 00 48 8b 45 10 0f 05 c3 83 e2 39 83 fa 08 75 e7 e8 03 ff ff ff 0f 1f 00 f3 0f 1e fa [ 235.514234][ C0] RSP: 002b:00007fffd9886170 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 235.514237][ C0] RAX: ffffffffffffffda RBX: 0000000000000040 RCX: 00007f0c0af5354e [ 235.514238][ C0] RDX: 0000000000000040 RSI: 000055fb2cb370a4 RDI: 0000000000000005 [ 235.514239][ C0] RBP: 00007fffd9886180 R08: 000055fb2cb49320 R09: 0000000000000010 [ 235.514240][ C0] R10: 0000000000000000 R11: 0000000000000202 R12: 431bde82d7b634db [ 235.514241][ C0] R13: 00007f0c0ad35470 R14: 000055fb2cb4a3e0 R15: 000055fb2cb370a0 [ 235.514243][ C0] [ 235.514246][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 235.514248][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 2946, name: ping [ 235.514249][ C0] preempt_count: 103, expected: 0 [ 235.514250][ C0] RCU nest depth: 2, expected: 0 [ 235.514250][ C0] INFO: lockdep is turned off. [ 235.514251][ C0] irq event stamp: 133070723 [ 235.514252][ C0] hardirqs last enabled at (133070722): [] irqentry_exit+0x21c/0x790 [ 235.514254][ C0] hardirqs last disabled at (133070723): [] console_emit_next_record+0x3d4/0x4c0 [ 235.514256][ C0] softirqs last enabled at (133070598): [] __dev_queue_xmit+0x974/0x1b90 [ 235.514258][ C0] softirqs last disabled at (133070599): [] do_softirq+0xac/0xe0 [ 235.514259][ C0] Preemption disabled at: [ 235.514260][ C0] [] __dev_queue_xmit+0x20c/0x1b90 [ 235.514263][ C0] CPU: 0 UID: 0 PID: 2946 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 235.514264][ C0] Tainted: [W]=WARN [ 235.514265][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 235.514265][ C0] Call Trace: [ 235.514266][ C0] [ 235.514267][ C0] dump_stack_lvl+0x6f/0xa0 [ 235.514269][ C0] ? __dev_queue_xmit+0x20c/0x1b90 [ 235.514271][ C0] __might_resched.cold+0x1fe/0x2c1 [ 235.514273][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 235.514276][ C0] ? rcu_is_watching+0x16/0xd0 [ 235.514278][ C0] __kmalloc_noprof+0x443/0x760 [ 235.514279][ C0] ? __rwlock_init+0x150/0x150 [ 235.514280][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 235.514283][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 235.514286][ C0] alloc_buf.isra.0+0x4b/0x260 [ 235.514288][ C0] put_chars+0x1e1/0x2f0 [ 235.514290][ C0] ? __send_to_port+0x420/0x420 [ 235.514292][ C0] hvc_console_print+0x292/0x780 [ 235.514295][ C0] ? __lock_acquire+0x388/0xc20 [ 235.514297][ C0] ? hvc_write+0x3a0/0x3a0 [ 235.514299][ C0] ? rcu_is_watching+0x16/0xd0 [ 235.514300][ C0] ? lock_acquire+0x13c/0x160 [ 235.514302][ C0] console_emit_next_record+0x22f/0x4c0 [ 235.514304][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 235.514306][ C0] ? console_flush_one_record+0x106/0x710 [ 235.514308][ C0] ? rcu_is_watching+0x16/0xd0 [ 235.514309][ C0] ? lock_acquire+0x13c/0x160 [ 235.514311][ C0] console_flush_one_record+0x46f/0x710 [ 235.514313][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 235.514315][ C0] ? __lock_acquire+0x518/0xc20 [ 235.514317][ C0] console_unlock+0xee/0x1f0 [ 235.514319][ C0] ? console_flush_one_record+0x710/0x710 [ 235.514320][ C0] ? rcu_is_watching+0x16/0xd0 [ 235.514321][ C0] ? lock_acquire+0xe0/0x160 [ 235.514323][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 235.514325][ C0] ? vprintk_emit+0x320/0x3e0 [ 235.514326][ C0] vprintk_emit+0x37c/0x3e0 [ 235.514327][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 235.514329][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 235.514331][ C0] _printk+0xc7/0x100 [ 235.514332][ C0] ? snapshot_read.cold+0x21/0x21 [ 235.514334][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 235.514336][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 235.514338][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 235.514339][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 235.514341][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 235.514343][ C0] call_timer_fn+0x160/0x4d0 [ 235.514345][ C0] ? mark_held_locks+0x40/0x70 [ 235.514346][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 235.514348][ C0] ? find_held_lock+0x2b/0x80 [ 235.514349][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 235.514351][ C0] ? mark_held_locks+0x40/0x70 [ 235.514357][ C0] __run_timers+0x68f/0xaa0 [ 235.514358][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 235.514361][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 235.514362][ C0] ? __lock_acquire+0x518/0xc20 [ 235.514365][ C0] ? __rwlock_init+0x150/0x150 [ 235.514367][ C0] run_timer_softirq+0xf0/0x160 [ 235.514369][ C0] ? __run_timers+0xaa0/0xaa0 [ 235.514371][ C0] ? rcu_is_watching+0x16/0xd0 [ 235.514372][ C0] handle_softirqs+0x1d3/0x900 [ 235.514374][ C0] ? _local_bh_enable+0xc0/0xc0 [ 235.514376][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 235.514377][ C0] ? rcu_is_watching+0x16/0xd0 [ 235.514378][ C0] ? __dev_queue_xmit+0x974/0x1b90 [ 235.514380][ C0] do_softirq+0xac/0xe0 [ 235.514382][ C0] [ 235.514382][ C0] [ 235.514383][ C0] __local_bh_enable_ip+0x118/0x150 [ 235.514384][ C0] __dev_queue_xmit+0x989/0x1b90 [ 235.514386][ C0] ? sock_queue_err_skb+0x680/0x680 [ 235.514388][ C0] ? irq_matrix_allocated+0xe0/0x120 [ 235.514390][ C0] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 235.514392][ C0] ? lock_acquire.part.0+0x60/0x280 [ 235.514393][ C0] ? find_held_lock+0x2b/0x80 [ 235.514394][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 235.514396][ C0] ? rcu_is_watching+0x16/0xd0 [ 235.514397][ C0] ? mark_held_locks+0x40/0x70 [ 235.514399][ C0] ? __asan_memcpy+0x3c/0x60 [ 235.514400][ C0] ? neigh_hh_output+0x152/0x4c0 [ 235.514402][ C0] ip_finish_output2+0x7c4/0x1c60 [ 235.514403][ C0] ? ip_dst_mtu_maybe_forward+0x2c7/0x720 [ 235.514405][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 235.514406][ C0] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 235.514408][ C0] ? rcu_is_watching+0x16/0xd0 [ 235.514409][ C0] ip_output+0x1ca/0x660 [ 235.514411][ C0] ? ip_finish_output+0x460/0x460 [ 235.514412][ C0] ? __ip_make_skb+0x106a/0x2300 [ 235.514414][ C0] ip_push_pending_frames+0xf6/0x250 [ 235.514416][ C0] raw_sendmsg+0x1179/0x1cd0 [ 235.514418][ C0] ? raw_send_hdrinc+0x1740/0x1740 [ 235.514420][ C0] ? __might_fault+0x97/0x140 [ 235.514421][ C0] ? _copy_to_user+0x4b/0x70 [ 235.514423][ C0] ? validate_chain+0x38b/0xc20 [ 235.514425][ C0] ? import_iovec+0x4e/0xa0 [ 235.514426][ C0] ? __lock_acquire+0x518/0xc20 [ 235.514428][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 235.514431][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 235.514433][ C0] __sock_sendmsg+0xce/0x190 [ 235.514434][ C0] ? fdget+0x4f/0x1e0 [ 235.514436][ C0] __sys_sendto+0x260/0x320 [ 235.514438][ C0] ? __ia32_sys_getpeername+0xd0/0xd0 [ 235.514439][ C0] ? fsnotify+0x3610/0x3610 [ 235.514441][ C0] ? __schedule+0xfa5/0x20e0 [ 235.514444][ C0] ? ksys_write+0x116/0x250 [ 235.514446][ C0] __x64_sys_sendto+0xe4/0x1f0 [ 235.514448][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 235.514449][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 235.514450][ C0] ? do_syscall_64+0xa6/0x530 [ 235.514452][ C0] do_syscall_64+0xff/0x530 [ 235.514453][ C0] ? irq_exit_rcu+0x1a/0x30 [ 235.514455][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 235.514456][ C0] RIP: 0033:0x7f0c0af5354e [ 235.514457][ C0] Code: 4d 89 d8 e8 b4 bd 00 00 4c 8b 5d f8 41 8b 93 08 03 00 00 59 5e 48 83 f8 fc 74 11 c9 c3 0f 1f 80 00 00 00 00 48 8b 45 10 0f 05 c3 83 e2 39 83 fa 08 75 e7 e8 03 ff ff ff 0f 1f 00 f3 0f 1e fa [ 235.514459][ C0] RSP: 002b:00007fffd9886170 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 235.514460][ C0] RAX: ffffffffffffffda RBX: 0000000000000040 RCX: 00007f0c0af5354e [ 235.514460][ C0] RDX: 0000000000000040 RSI: 000055fb2cb370a4 RDI: 0000000000000005 [ 235.514461][ C0] RBP: 00007fffd9886180 R08: 000055fb2cb49320 R09: 0000000000000010 [ 235.514462][ C0] R10: 0000000000000000 R11: 0000000000000202 R12: 431bde82d7b634db [ 235.514463][ C0] R13: 00007f0c0ad35470 R14: 000055fb2cb4a3e0 R15: 000055fb2cb370a0 [ 235.514465][ C0] WAIT TIMEOUT stderr Ctrl-C stderr Ctrl-C stderr