[ 11.723942][ T279] ip (279) used greatest stack depth: 24000 bytes left [ 11.723960][ T279] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.723962][ T279] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 279, name: ip [ 11.723963][ T279] preempt_count: 2, expected: 0 [ 11.723964][ T279] RCU nest depth: 0, expected: 0 [ 11.723965][ T279] locks held by ip/279: 5, last CPU#3: [ 11.723967][ T279] #0: ffffffffb86167b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 11.723979][ T279] #1: ffffffffb877ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.723984][ T279] #2: ffffffffb877de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.723988][ T279] #3: ffffffffb869d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.723992][ T279] #4: ffffffffb869d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.723996][ T279] irq event stamp: 27146 [ 11.723996][ T279] hardirqs last enabled at (27145): [] __down_trylock_console_sem+0x86/0xa0 [ 11.723999][ T279] hardirqs last disabled at (27146): [] console_emit_next_record+0x3d4/0x4c0 [ 11.724002][ T279] softirqs last enabled at (25934): [] handle_softirqs+0x67c/0x900 [ 11.724004][ T279] softirqs last disabled at (24615): [] __irq_exit_rcu+0x145/0x1c0 [ 11.724006][ T279] Preemption disabled at: [ 11.724007][ T279] [<0000000000000000>] 0x0 [ 11.724014][ T279] CPU: 3 UID: 0 PID: 279 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.724017][ T279] Tainted: [W]=WARN [ 11.724018][ T279] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.724020][ T279] Call Trace: [ 11.724021][ T279] [ 11.724022][ T279] dump_stack_lvl+0x6f/0xa0 [ 11.724029][ T279] __might_resched.cold+0x1fe/0x2c1 [ 11.724034][ T279] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.724038][ T279] ? __kmalloc_noprof+0xdb/0x760 [ 11.724042][ T279] __kmalloc_noprof+0x443/0x760 [ 11.724045][ T279] ? alloc_buf.isra.0+0x4b/0x260 [ 11.724051][ T279] ? do_raw_spin_unlock+0x59/0x250 [ 11.724054][ T279] alloc_buf.isra.0+0x4b/0x260 [ 11.724058][ T279] put_chars+0x1e1/0x2f0 [ 11.724060][ T279] ? __send_to_port+0x420/0x420 [ 11.724061][ T279] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.724065][ T279] ? validate_chain+0x38b/0xc20 [ 11.724070][ T279] hvc_console_print+0x292/0x780 [ 11.724078][ T279] ? hvc_write+0x3a0/0x3a0 [ 11.724081][ T279] ? rcu_is_watching+0x16/0xd0 [ 11.724083][ T279] ? lock_acquire+0x13c/0x160 [ 11.724087][ T279] console_emit_next_record+0x22f/0x4c0 [ 11.724091][ T279] ? devkmsg_read+0x4b0/0x4b0 [ 11.724093][ T279] ? console_flush_one_record+0x106/0x710 [ 11.724096][ T279] ? rcu_is_watching+0x16/0xd0 [ 11.724098][ T279] ? lock_acquire+0x13c/0x160 [ 11.724102][ T279] console_flush_one_record+0x46f/0x710 [ 11.724106][ T279] ? console_emit_next_record+0x4c0/0x4c0 [ 11.724108][ T279] ? __lock_acquire+0x518/0xc20 [ 11.724113][ T279] console_unlock+0xee/0x1f0 [ 11.724116][ T279] ? console_flush_one_record+0x710/0x710 [ 11.724118][ T279] ? rcu_is_watching+0x16/0xd0 [ 11.724120][ T279] ? lock_acquire+0xe0/0x160 [ 11.724124][ T279] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.724125][ T279] ? vprintk_emit+0x320/0x3e0 [ 11.724128][ T279] vprintk_emit+0x37c/0x3e0 [ 11.724131][ T279] ? wake_up_klogd_work_func+0x90/0x90 [ 11.724134][ T279] ? __lock_acquire+0x518/0xc20 [ 11.724138][ T279] _printk+0xc7/0x100 [ 11.724142][ T279] ? snapshot_read.cold+0x21/0x21 [ 11.724145][ T279] ? do_raw_spin_lock+0x131/0x280 [ 11.724147][ T279] ? __rwlock_init+0x150/0x150 [ 11.724151][ T279] ? do_raw_spin_lock+0x131/0x280 [ 11.724154][ T279] do_exit.cold+0x82/0x9c [ 11.724158][ T279] ? exit_notify+0x890/0x890 [ 11.724164][ T279] ? __lock_release.isra.0+0x69/0x1a0 [ 11.724167][ T279] ? rcu_is_watching+0x16/0xd0 [ 11.724171][ T279] do_group_exit+0xb8/0x370 [ 11.724174][ T279] __x64_sys_exit_group+0x3c/0x50 [ 11.724176][ T279] x64_sys_call+0x1567/0x1570 [ 11.724178][ T279] do_syscall_64+0xff/0x530 [ 11.724182][ T279] ? exc_page_fault+0xee/0x100 [ 11.724185][ T279] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.724187][ T279] RIP: 0033:0x7f197a7201b8 [ 11.724189][ T279] Code: Unable to access opcode bytes at 0x7f197a72018e. [ 11.724191][ T279] RSP: 002b:00007ffd292aad88 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 11.724193][ T279] RAX: ffffffffffffffda RBX: 00007f197a850f88 RCX: 00007f197a7201b8 [ 11.724194][ T279] RDX: 00007f197a46afc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 11.724195][ T279] RBP: 00007ffd292aade0 R08: 0000000000000000 R09: 0000000000000050 [ 11.724196][ T279] R10: 00007ffd292aaba0 R11: 0000000000000246 R12: 0000000000000001 [ 11.724197][ T279] R13: 0000000000000000 R14: 00007f197a84f680 R15: 00007f197a850fa0 [ 11.724204][ T279] [ 73.805129][ T1414] mpls_gso: MPLS GSO support [ 73.805144][ T1414] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 73.805147][ T1414] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 1414, name: modprobe [ 73.805149][ T1414] preempt_count: 1, expected: 0 [ 73.805149][ T1414] RCU nest depth: 0, expected: 0 [ 73.805150][ T1414] locks held by modprobe/1414: 4, last CPU#1: [ 73.805153][ T1414] #0: ffffffffb877ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 73.805164][ T1414] #1: ffffffffb877de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 73.805169][ T1414] #2: ffffffffb869d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 73.805172][ T1414] #3: ffffffffb869d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 73.805176][ T1414] irq event stamp: 63228 [ 73.805177][ T1414] hardirqs last enabled at (63227): [] __down_trylock_console_sem+0x86/0xa0 [ 73.805180][ T1414] hardirqs last disabled at (63228): [] console_emit_next_record+0x3d4/0x4c0 [ 73.805182][ T1414] softirqs last enabled at (62450): [] handle_softirqs+0x67c/0x900 [ 73.805186][ T1414] softirqs last disabled at (62443): [] __irq_exit_rcu+0x145/0x1c0 [ 73.805188][ T1414] Preemption disabled at: [ 73.805188][ T1414] [] vprintk_emit+0x31b/0x3e0 [ 73.805193][ T1414] CPU: 1 UID: 0 PID: 1414 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 73.805196][ T1414] Tainted: [W]=WARN [ 73.805197][ T1414] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 73.805199][ T1414] Call Trace: [ 73.805201][ T1414] [ 73.805202][ T1414] dump_stack_lvl+0x6f/0xa0 [ 73.805208][ T1414] ? vprintk_emit+0x31b/0x3e0 [ 73.805209][ T1414] __might_resched.cold+0x1fe/0x2c1 [ 73.805214][ T1414] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 73.805217][ T1414] ? __kmalloc_noprof+0xdb/0x760 [ 73.805222][ T1414] __kmalloc_noprof+0x443/0x760 [ 73.805224][ T1414] ? alloc_buf.isra.0+0x4b/0x260 [ 73.805230][ T1414] ? do_raw_spin_unlock+0x59/0x250 [ 73.805233][ T1414] alloc_buf.isra.0+0x4b/0x260 [ 73.805236][ T1414] put_chars+0x1e1/0x2f0 [ 73.805238][ T1414] ? desc_read_finalized_seq+0x79/0x120 [ 73.805240][ T1414] ? __send_to_port+0x420/0x420 [ 73.805243][ T1414] ? rcu_read_lock_any_held+0x3c/0x90 [ 73.805246][ T1414] ? validate_chain+0x38b/0xc20 [ 73.805250][ T1414] hvc_console_print+0x292/0x780 [ 73.805254][ T1414] ? __lock_acquire+0x518/0xc20 [ 73.805256][ T1414] ? __lock_acquire+0x518/0xc20 [ 73.805260][ T1414] ? hvc_write+0x3a0/0x3a0 [ 73.805262][ T1414] ? console_emit_next_record+0x1df/0x4c0 [ 73.805265][ T1414] ? rcu_is_watching+0x16/0xd0 [ 73.805267][ T1414] ? lock_acquire+0x13c/0x160 [ 73.805271][ T1414] console_emit_next_record+0x22f/0x4c0 [ 73.805275][ T1414] ? devkmsg_read+0x4b0/0x4b0 [ 73.805277][ T1414] ? console_flush_one_record+0x106/0x710 [ 73.805280][ T1414] ? rcu_is_watching+0x16/0xd0 [ 73.805282][ T1414] ? lock_acquire+0x13c/0x160 [ 73.805285][ T1414] console_flush_one_record+0x46f/0x710 [ 73.805289][ T1414] ? console_emit_next_record+0x4c0/0x4c0 [ 73.805291][ T1414] ? __lock_acquire+0x518/0xc20 [ 73.805296][ T1414] console_unlock+0xee/0x1f0 [ 73.805299][ T1414] ? console_flush_one_record+0x710/0x710 [ 73.805301][ T1414] ? rcu_is_watching+0x16/0xd0 [ 73.805302][ T1414] ? lock_acquire+0xe0/0x160 [ 73.805306][ T1414] ? __down_trylock_console_sem+0x5e/0xa0 [ 73.805307][ T1414] ? vprintk_emit+0x320/0x3e0 [ 73.805310][ T1414] vprintk_emit+0x37c/0x3e0 [ 73.805313][ T1414] ? wake_up_klogd_work_func+0x90/0x90 [ 73.805318][ T1414] ? mpls_exit+0xaf0/0xaf0 [mpls_router] [ 73.805321][ T1414] _printk+0xc7/0x100 [ 73.805325][ T1414] ? snapshot_read.cold+0x21/0x21 [ 73.805331][ T1414] ? do_one_initcall+0x113/0x4f0 [ 73.805334][ T1414] mpls_gso_init+0x15/0x1000 [mpls_gso] [ 73.805337][ T1414] do_one_initcall+0x124/0x4f0 [ 73.805339][ T1414] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 73.805344][ T1414] ? do_init_module+0xae/0x8a0 [ 73.805346][ T1414] ? kasan_unpoison+0x44/0x70 [ 73.805351][ T1414] do_init_module+0x2ad/0x8a0 [ 73.805354][ T1414] ? lockdep_hardirqs_on+0x91/0x130 [ 73.805358][ T1414] ? free_module+0x470/0x470 [ 73.805361][ T1414] ? kfree+0x123/0x590 [ 73.805364][ T1414] load_module+0x1424/0x1bb0 [ 73.805369][ T1414] ? security_kernel_post_read_file+0x2f/0x60 [ 73.805374][ T1414] ? layout_and_allocate.isra.0+0x680/0x680 [ 73.805377][ T1414] ? __ia32_sys_fsconfig+0x180/0x180 [ 73.805381][ T1414] ? backing_file_read_iter+0x541/0x790 [ 73.805384][ T1414] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 73.805393][ T1414] init_module_from_file+0x11f/0x1a0 [ 73.805395][ T1414] ? __do_sys_init_module+0x270/0x270 [ 73.805402][ T1414] ? __rwlock_init+0x150/0x150 [ 73.805406][ T1414] ? do_raw_spin_unlock+0x59/0x250 [ 73.805409][ T1414] idempotent_init_module+0x37b/0x7c0 [ 73.805412][ T1414] ? init_module_from_file+0x1a0/0x1a0 [ 73.805417][ T1414] ? cap_capable+0x1d7/0x3d0 [ 73.805419][ T1414] ? __x64_sys_pread64+0x18d/0x250 [ 73.805424][ T1414] __x64_sys_finit_module+0xdf/0x190 [ 73.805426][ T1414] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 73.805430][ T1414] do_syscall_64+0xff/0x530 [ 73.805432][ T1414] ? exc_page_fault+0xee/0x100 [ 73.805435][ T1414] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 73.805437][ T1414] RIP: 0033:0x7fbac642637d [ 73.805440][ T1414] 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 [ 73.805442][ T1414] RSP: 002b:00007ffeda416348 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 73.805444][ T1414] RAX: ffffffffffffffda RBX: 0000561d075e5bc0 RCX: 00007fbac642637d [ 73.805446][ T1414] RDX: 0000000000000000 RSI: 0000561d0535affd RDI: 0000000000000002 [ 73.805446][ T1414] RBP: 00007ffeda4163e0 R08: 0000000000000000 R09: 0000000000000000 [ 73.805447][ T1414] R10: 0000000000000000 R11: 0000000000000246 R12: 0000561d0535affd [ 73.805448][ T1414] R13: 0000561d075e5cf0 R14: 0000000000040000 R15: 0000000000000000 [ 73.805454][ T1414] [ 127.507800][ C0] clocksource: Watchdog remote CPU 1 read timed out [ 127.507880][ C0] [ 127.507882][ C0] ======================================================== [ 127.507883][ C0] WARNING: possible irq lock inversion dependency detected [ 127.507885][ C0] 7.2.0-virtme #1 Tainted: G W [ 127.507886][ C0] -------------------------------------------------------- [ 127.507887][ C0] ping/2959 just changed the state of lock: [ 127.507888][ C0] ffffffffb869d760 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 127.507900][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 127.507901][ C0] (fs_reclaim){+.+.}-{0:0} [ 127.507902][ C0] [ 127.507902][ C0] [ 127.507902][ C0] and interrupts could create inverse lock ordering between them. [ 127.507902][ C0] [ 127.507903][ C0] [ 127.507903][ C0] other info that might help us debug this: [ 127.507904][ C0] Possible interrupt unsafe locking scenario: [ 127.507904][ C0] [ 127.507904][ C0] CPU0 CPU1 [ 127.507905][ C0] ---- ---- [ 127.507905][ C0] lock(fs_reclaim); [ 127.507906][ C0] local_irq_disable(); [ 127.507907][ C0] lock(console_owner); [ 127.507908][ C0] lock(fs_reclaim); [ 127.507909][ C0] [ 127.507909][ C0] lock(console_owner); [ 127.507910][ C0] [ 127.507910][ C0] *** DEADLOCK *** [ 127.507910][ C0] [ 127.507910][ C0] locks held by ping/2959: 7, last CPU#0: [ 127.507911][ C0] #0: ff1100001c524a90 (sk_lock-AF_INET){+.+.}-{0:0}, at: raw_sendmsg+0xbb5/0x1cd0 [ 127.507917][ C0] #1: ffffffffb8794c00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 127.507921][ C0] #2: ffffffffb8794c00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x2c6/0x1c60 [ 127.507924][ C0] #3: ffa0000000007ca8 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 127.507929][ C0] #4: ffffffffb87e29f8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 127.507932][ C0] #5: ffffffffb877ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 127.507935][ C0] #6: ffffffffb877de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 127.507938][ C0] [ 127.507938][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 127.507942][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 127.507944][ C0] HARDIRQ-ON-W at: [ 127.507946][ C0] __lock_acquire+0x388/0xc20 [ 127.507949][ C0] lock_acquire.part.0+0xd4/0x280 [ 127.507950][ C0] fs_reclaim_acquire+0xd5/0x120 [ 127.507953][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 127.507955][ C0] kthread_create_worker_on_node+0xea/0x210 [ 127.507958][ C0] workqueue_init+0x2a/0x680 [ 127.507962][ C0] kernel_init_freeable+0x2fe/0x630 [ 127.507965][ C0] kernel_init+0x21/0x150 [ 127.507968][ C0] ret_from_fork+0x474/0x6b0 [ 127.507971][ C0] ret_from_fork_asm+0x11/0x20 [ 127.507974][ C0] SOFTIRQ-ON-W at: [ 127.507975][ C0] __lock_acquire+0x388/0xc20 [ 127.507977][ C0] lock_acquire.part.0+0xd4/0x280 [ 127.507978][ C0] fs_reclaim_acquire+0xd5/0x120 [ 127.507979][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 127.507980][ C0] kthread_create_worker_on_node+0xea/0x210 [ 127.507982][ C0] workqueue_init+0x2a/0x680 [ 127.507983][ C0] kernel_init_freeable+0x2fe/0x630 [ 127.507985][ C0] kernel_init+0x21/0x150 [ 127.507986][ C0] ret_from_fork+0x474/0x6b0 [ 127.507987][ C0] ret_from_fork_asm+0x11/0x20 [ 127.507988][ C0] INITIAL USE at: [ 127.507989][ C0] __lock_acquire+0x388/0xc20 [ 127.507990][ C0] lock_acquire.part.0+0xd4/0x280 [ 127.507992][ C0] fs_reclaim_acquire+0xd5/0x120 [ 127.507993][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 127.507994][ C0] kthread_create_worker_on_node+0xea/0x210 [ 127.507995][ C0] workqueue_init+0x2a/0x680 [ 127.507997][ C0] kernel_init_freeable+0x2fe/0x630 [ 127.507998][ C0] kernel_init+0x21/0x150 [ 127.508000][ C0] ret_from_fork+0x474/0x6b0 [ 127.508001][ C0] ret_from_fork_asm+0x11/0x20 [ 127.508002][ C0] } [ 127.508003][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 127.508006][ C0] ... acquired at: [ 127.508007][ C0] __lock_acquire+0x518/0xc20 [ 127.508009][ C0] lock_acquire.part.0+0xd4/0x280 [ 127.508010][ C0] fs_reclaim_acquire+0xd5/0x120 [ 127.508011][ C0] __kmalloc_noprof+0xd3/0x760 [ 127.508012][ C0] alloc_buf.isra.0+0x4b/0x260 [ 127.508016][ C0] put_chars+0x1e1/0x2f0 [ 127.508017][ C0] hvc_console_print+0x292/0x780 [ 127.508020][ C0] console_emit_next_record+0x22f/0x4c0 [ 127.508022][ C0] console_flush_one_record+0x46f/0x710 [ 127.508024][ C0] console_unlock+0xee/0x1f0 [ 127.508025][ C0] vprintk_emit+0x37c/0x3e0 [ 127.508026][ C0] _printk+0xc7/0x100 [ 127.508029][ C0] tun_init+0x24/0xb0 [ 127.508032][ C0] do_one_initcall+0x124/0x4f0 [ 127.508033][ C0] kernel_init_freeable+0x596/0x630 [ 127.508035][ C0] kernel_init+0x21/0x150 [ 127.508036][ C0] ret_from_fork+0x474/0x6b0 [ 127.508037][ C0] ret_from_fork_asm+0x11/0x20 [ 127.508038][ C0] [ 127.508039][ C0] -> (console_owner){..-.}-{0:0} { [ 127.508040][ C0] IN-SOFTIRQ-W at: [ 127.508041][ C0] __lock_acquire+0x388/0xc20 [ 127.508042][ C0] lock_acquire.part.0+0xd4/0x280 [ 127.508044][ C0] console_lock_spinning_enable+0x5c/0x60 [ 127.508045][ C0] console_emit_next_record+0x1d1/0x4c0 [ 127.508047][ C0] console_flush_one_record+0x46f/0x710 [ 127.508048][ C0] console_unlock+0xee/0x1f0 [ 127.508050][ C0] vprintk_emit+0x37c/0x3e0 [ 127.508051][ C0] _printk+0xc7/0x100 [ 127.508052][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 127.508055][ C0] call_timer_fn+0x160/0x4d0 [ 127.508056][ C0] __run_timers+0x68f/0xaa0 [ 127.508058][ C0] run_timer_softirq+0xf0/0x160 [ 127.508059][ C0] handle_softirqs+0x1d3/0x900 [ 127.508062][ C0] do_softirq+0xac/0xe0 [ 127.508063][ C0] __local_bh_enable_ip+0x118/0x150 [ 127.508065][ C0] __dev_queue_xmit+0x989/0x1b90 [ 127.508068][ C0] ip_finish_output2+0x684/0x1c60 [ 127.508069][ C0] ip_output+0x1ca/0x660 [ 127.508070][ C0] ip_push_pending_frames+0xf6/0x250 [ 127.508071][ C0] raw_sendmsg+0x1179/0x1cd0 [ 127.508073][ C0] __sock_sendmsg+0xce/0x190 [ 127.508075][ C0] __sys_sendto+0x260/0x320 [ 127.508077][ C0] __x64_sys_sendto+0xe4/0x1f0 [ 127.508078][ C0] do_syscall_64+0xff/0x530 [ 127.508080][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 127.508082][ C0] INITIAL USE at: [ 127.508083][ C0] } [ 127.508083][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 127.508086][ C0] ... acquired at: [ 127.508086][ C0] mark_lock+0x1d7/0xa00 [ 127.508087][ C0] mark_usage+0x42/0x170 [ 127.508089][ C0] __lock_acquire+0x388/0xc20 [ 127.508090][ C0] lock_acquire.part.0+0xd4/0x280 [ 127.508091][ C0] console_lock_spinning_enable+0x5c/0x60 [ 127.508093][ C0] console_emit_next_record+0x1d1/0x4c0 [ 127.508094][ C0] console_flush_one_record+0x46f/0x710 [ 127.508096][ C0] console_unlock+0xee/0x1f0 [ 127.508097][ C0] vprintk_emit+0x37c/0x3e0 [ 127.508098][ C0] _printk+0xc7/0x100 [ 127.508099][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 127.508100][ C0] call_timer_fn+0x160/0x4d0 [ 127.508102][ C0] __run_timers+0x68f/0xaa0 [ 127.508103][ C0] run_timer_softirq+0xf0/0x160 [ 127.508105][ C0] handle_softirqs+0x1d3/0x900 [ 127.508106][ C0] do_softirq+0xac/0xe0 [ 127.508107][ C0] __local_bh_enable_ip+0x118/0x150 [ 127.508108][ C0] __dev_queue_xmit+0x989/0x1b90 [ 127.508110][ C0] ip_finish_output2+0x684/0x1c60 [ 127.508111][ C0] ip_output+0x1ca/0x660 [ 127.508112][ C0] ip_push_pending_frames+0xf6/0x250 [ 127.508113][ C0] raw_sendmsg+0x1179/0x1cd0 [ 127.508114][ C0] __sock_sendmsg+0xce/0x190 [ 127.508115][ C0] __sys_sendto+0x260/0x320 [ 127.508116][ C0] __x64_sys_sendto+0xe4/0x1f0 [ 127.508118][ C0] do_syscall_64+0xff/0x530 [ 127.508119][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 127.508120][ C0] [ 127.508120][ C0] [ 127.508120][ C0] stack backtrace: [ 127.508123][ C0] CPU: 0 UID: 0 PID: 2959 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 127.508126][ C0] Tainted: [W]=WARN [ 127.508127][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 127.508129][ C0] Call Trace: [ 127.508130][ C0] [ 127.508131][ C0] dump_stack_lvl+0x6f/0xa0 [ 127.508135][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 127.508138][ C0] mark_lock_irq+0x989/0x9c0 [ 127.508141][ C0] mark_lock+0x1d7/0xa00 [ 127.508142][ C0] mark_usage+0x42/0x170 [ 127.508144][ C0] __lock_acquire+0x388/0xc20 [ 127.508146][ C0] lock_acquire.part.0+0xd4/0x280 [ 127.508148][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 127.508150][ C0] ? rcu_is_watching+0x16/0xd0 [ 127.508152][ C0] ? lock_acquire+0x13c/0x160 [ 127.508154][ C0] console_lock_spinning_enable+0x5c/0x60 [ 127.508156][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 127.508158][ C0] console_emit_next_record+0x1d1/0x4c0 [ 127.508160][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 127.508161][ C0] ? console_flush_one_record+0x106/0x710 [ 127.508163][ C0] ? rcu_is_watching+0x16/0xd0 [ 127.508164][ C0] ? lock_acquire+0x13c/0x160 [ 127.508166][ C0] console_flush_one_record+0x46f/0x710 [ 127.508169][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 127.508170][ C0] ? __lock_acquire+0x518/0xc20 [ 127.508172][ C0] console_unlock+0xee/0x1f0 [ 127.508174][ C0] ? console_flush_one_record+0x710/0x710 [ 127.508176][ C0] ? rcu_is_watching+0x16/0xd0 [ 127.508177][ C0] ? lock_acquire+0xe0/0x160 [ 127.508179][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 127.508181][ C0] ? vprintk_emit+0x320/0x3e0 [ 127.508182][ C0] vprintk_emit+0x37c/0x3e0 [ 127.508184][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 127.508185][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 127.508187][ C0] _printk+0xc7/0x100 [ 127.508189][ C0] ? snapshot_read.cold+0x21/0x21 [ 127.508190][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 127.508192][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 127.508194][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 127.508196][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 127.508198][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 127.508199][ C0] call_timer_fn+0x160/0x4d0 [ 127.508201][ C0] ? mark_held_locks+0x40/0x70 [ 127.508203][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 127.508204][ C0] ? find_held_lock+0x2b/0x80 [ 127.508206][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 127.508208][ C0] ? mark_held_locks+0x40/0x70 [ 127.508210][ C0] __run_timers+0x68f/0xaa0 [ 127.508211][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 127.508214][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 127.508216][ C0] ? __lock_acquire+0x518/0xc20 [ 127.508218][ C0] ? __rwlock_init+0x150/0x150 [ 127.508221][ C0] run_timer_softirq+0xf0/0x160 [ 127.508223][ C0] ? __run_timers+0xaa0/0xaa0 [ 127.508225][ C0] ? rcu_is_watching+0x16/0xd0 [ 127.508226][ C0] handle_softirqs+0x1d3/0x900 [ 127.508228][ C0] ? _local_bh_enable+0xc0/0xc0 [ 127.508229][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 127.508231][ C0] ? rcu_is_watching+0x16/0xd0 [ 127.508232][ C0] ? __dev_queue_xmit+0x974/0x1b90 [ 127.508234][ C0] do_softirq+0xac/0xe0 [ 127.508235][ C0] [ 127.508236][ C0] [ 127.508236][ C0] __local_bh_enable_ip+0x118/0x150 [ 127.508238][ C0] __dev_queue_xmit+0x989/0x1b90 [ 127.508240][ C0] ? __lock_acquire+0x518/0xc20 [ 127.508242][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 127.508243][ C0] ? find_held_lock+0x2b/0x80 [ 127.508245][ C0] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 127.508247][ C0] ? __asan_memcpy+0x3c/0x60 [ 127.508250][ C0] ? eth_header+0x14c/0x180 [ 127.508253][ C0] ? neigh_resolve_output.part.0+0x344/0x740 [ 127.508256][ C0] ip_finish_output2+0x684/0x1c60 [ 127.508257][ C0] ? ip_dst_mtu_maybe_forward+0x2c7/0x720 [ 127.508259][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 127.508260][ C0] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 127.508262][ C0] ? rcu_is_watching+0x16/0xd0 [ 127.508263][ C0] ip_output+0x1ca/0x660 [ 127.508265][ C0] ? ip_finish_output+0x460/0x460 [ 127.508266][ C0] ? __ip_make_skb+0x106a/0x2300 [ 127.508268][ C0] ip_push_pending_frames+0xf6/0x250 [ 127.508270][ C0] raw_sendmsg+0x1179/0x1cd0 [ 127.508272][ C0] ? raw_send_hdrinc+0x1740/0x1740 [ 127.508274][ C0] ? __might_fault+0x97/0x140 [ 127.508276][ C0] ? _copy_to_user+0x4b/0x70 [ 127.508279][ C0] ? validate_chain+0x38b/0xc20 [ 127.508281][ C0] ? import_iovec+0x4e/0xa0 [ 127.508283][ C0] ? __lock_acquire+0x518/0xc20 [ 127.508285][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 127.508287][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 127.508289][ C0] __sock_sendmsg+0xce/0x190 [ 127.508291][ C0] ? fdget+0x4f/0x1e0 [ 127.508293][ C0] __sys_sendto+0x260/0x320 [ 127.508295][ C0] ? __ia32_sys_getpeername+0xd0/0xd0 [ 127.508297][ C0] ? fsnotify+0x3610/0x3610 [ 127.508301][ C0] ? ksys_write+0x116/0x250 [ 127.508304][ C0] __x64_sys_sendto+0xe4/0x1f0 [ 127.508306][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 127.508309][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 127.508310][ C0] ? do_syscall_64+0xa6/0x530 [ 127.508312][ C0] do_syscall_64+0xff/0x530 [ 127.508313][ C0] ? irq_exit_rcu+0x1a/0x30 [ 127.508315][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 127.508316][ C0] RIP: 0033:0x7f01114f654e [ 127.508319][ 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 [ 127.508321][ C0] RSP: 002b:00007ffe186cc130 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 127.508323][ C0] RAX: ffffffffffffffda RBX: 0000000000000040 RCX: 00007f01114f654e [ 127.508325][ C0] RDX: 0000000000000040 RSI: 000055d5cdfa10a4 RDI: 0000000000000005 [ 127.508325][ C0] RBP: 00007ffe186cc140 R08: 000055d5cdfb3320 R09: 0000000000000010 [ 127.508326][ C0] R10: 0000000000000000 R11: 0000000000000202 R12: 431bde82d7b634db [ 127.508327][ C0] R13: 0000000000000000 R14: 000055d5cdfb43e0 R15: 000055d5cdfa10a0 [ 127.508329][ C0] [ 127.508333][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 127.508334][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 2959, name: ping [ 127.508335][ C0] preempt_count: 103, expected: 0 [ 127.508336][ C0] RCU nest depth: 2, expected: 0 [ 127.508337][ C0] INFO: lockdep is turned off. [ 127.508337][ C0] irq event stamp: 25382731 [ 127.508338][ C0] hardirqs last enabled at (25382730): [] __down_trylock_console_sem+0x86/0xa0 [ 127.508340][ C0] hardirqs last disabled at (25382731): [] console_emit_next_record+0x3d4/0x4c0 [ 127.508342][ C0] softirqs last enabled at (25382610): [] __dev_queue_xmit+0x974/0x1b90 [ 127.508344][ C0] softirqs last disabled at (25382611): [] do_softirq+0xac/0xe0 [ 127.508345][ C0] Preemption disabled at: [ 127.508346][ C0] [] __dev_queue_xmit+0x20c/0x1b90 [ 127.508348][ C0] CPU: 0 UID: 0 PID: 2959 Comm: ping Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 127.508350][ C0] Tainted: [W]=WARN [ 127.508351][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 127.508351][ C0] Call Trace: [ 127.508352][ C0] [ 127.508352][ C0] dump_stack_lvl+0x6f/0xa0 [ 127.508354][ C0] ? __dev_queue_xmit+0x20c/0x1b90 [ 127.508356][ C0] __might_resched.cold+0x1fe/0x2c1 [ 127.508359][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 127.508362][ C0] ? rcu_is_watching+0x16/0xd0 [ 127.508363][ C0] __kmalloc_noprof+0x443/0x760 [ 127.508365][ C0] ? __rwlock_init+0x150/0x150 [ 127.508366][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 127.508368][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 127.508370][ C0] alloc_buf.isra.0+0x4b/0x260 [ 127.508372][ C0] put_chars+0x1e1/0x2f0 [ 127.508374][ C0] ? __send_to_port+0x420/0x420 [ 127.508376][ C0] hvc_console_print+0x292/0x780 [ 127.508378][ C0] ? __lock_acquire+0x388/0xc20 [ 127.508380][ C0] ? hvc_write+0x3a0/0x3a0 [ 127.508382][ C0] ? rcu_is_watching+0x16/0xd0 [ 127.508383][ C0] ? lock_acquire+0x13c/0x160 [ 127.508385][ C0] console_emit_next_record+0x22f/0x4c0 [ 127.508388][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 127.508389][ C0] ? console_flush_one_record+0x106/0x710 [ 127.508391][ C0] ? rcu_is_watching+0x16/0xd0 [ 127.508392][ C0] ? lock_acquire+0x13c/0x160 [ 127.508394][ C0] console_flush_one_record+0x46f/0x710 [ 127.508396][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 127.508398][ C0] ? __lock_acquire+0x518/0xc20 [ 127.508400][ C0] console_unlock+0xee/0x1f0 [ 127.508402][ C0] ? console_flush_one_record+0x710/0x710 [ 127.508404][ C0] ? rcu_is_watching+0x16/0xd0 [ 127.508405][ C0] ? lock_acquire+0xe0/0x160 [ 127.508407][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 127.508408][ C0] ? vprintk_emit+0x320/0x3e0 [ 127.508410][ C0] vprintk_emit+0x37c/0x3e0 [ 127.508411][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 127.508413][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 127.508414][ C0] _printk+0xc7/0x100 [ 127.508416][ C0] ? snapshot_read.cold+0x21/0x21 [ 127.508418][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 127.508419][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 127.508422][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 127.508423][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 127.508425][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 127.508426][ C0] call_timer_fn+0x160/0x4d0 [ 127.508428][ C0] ? mark_held_locks+0x40/0x70 [ 127.508430][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 127.508431][ C0] ? find_held_lock+0x2b/0x80 [ 127.508433][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 127.508435][ C0] ? mark_held_locks+0x40/0x70 [ 127.508437][ C0] __run_timers+0x68f/0xaa0 [ 127.508438][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 127.508440][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 127.508442][ C0] ? __lock_acquire+0x518/0xc20 [ 127.508445][ C0] ? __rwlock_init+0x150/0x150 [ 127.508447][ C0] run_timer_softirq+0xf0/0x160 [ 127.508449][ C0] ? __run_timers+0xaa0/0xaa0 [ 127.508451][ C0] ? rcu_is_watching+0x16/0xd0 [ 127.508452][ C0] handle_softirqs+0x1d3/0x900 [ 127.508454][ C0] ? _local_bh_enable+0xc0/0xc0 [ 127.508455][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 127.508457][ C0] ? rcu_is_watching+0x16/0xd0 [ 127.508458][ C0] ? __dev_queue_xmit+0x974/0x1b90 [ 127.508460][ C0] do_softirq+0xac/0xe0 [ 127.508461][ C0] [ 127.508462][ C0] [ 127.508462][ C0] __local_bh_enable_ip+0x118/0x150 [ 127.508464][ C0] __dev_queue_xmit+0x989/0x1b90 [ 127.508466][ C0] ? __lock_acquire+0x518/0xc20 [ 127.508468][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 127.508469][ C0] ? find_held_lock+0x2b/0x80 [ 127.508471][ C0] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 127.508472][ C0] ? __asan_memcpy+0x3c/0x60 [ 127.508474][ C0] ? eth_header+0x14c/0x180 [ 127.508475][ C0] ? neigh_resolve_output.part.0+0x344/0x740 [ 127.508478][ C0] ip_finish_output2+0x684/0x1c60 [ 127.508479][ C0] ? ip_dst_mtu_maybe_forward+0x2c7/0x720 [ 127.508481][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 127.508482][ C0] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 127.508484][ C0] ? rcu_is_watching+0x16/0xd0 [ 127.508485][ C0] ip_output+0x1ca/0x660 [ 127.508487][ C0] ? ip_finish_output+0x460/0x460 [ 127.508488][ C0] ? __ip_make_skb+0x106a/0x2300 [ 127.508490][ C0] ip_push_pending_frames+0xf6/0x250 [ 127.508492][ C0] raw_sendmsg+0x1179/0x1cd0 [ 127.508494][ C0] ? raw_send_hdrinc+0x1740/0x1740 [ 127.508496][ C0] ? __might_fault+0x97/0x140 [ 127.508497][ C0] ? _copy_to_user+0x4b/0x70 [ 127.508499][ C0] ? validate_chain+0x38b/0xc20 [ 127.508500][ C0] ? import_iovec+0x4e/0xa0 [ 127.508502][ C0] ? __lock_acquire+0x518/0xc20 [ 127.508504][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 127.508506][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 127.508508][ C0] __sock_sendmsg+0xce/0x190 [ 127.508510][ C0] ? fdget+0x4f/0x1e0 [ 127.508511][ C0] __sys_sendto+0x260/0x320 [ 127.508513][ C0] ? __ia32_sys_getpeername+0xd0/0xd0 [ 127.508515][ C0] ? fsnotify+0x3610/0x3610 [ 127.508518][ C0] ? ksys_write+0x116/0x250 [ 127.508520][ C0] __x64_sys_sendto+0xe4/0x1f0 [ 127.508522][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 127.508523][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 127.508524][ C0] ? do_syscall_64+0xa6/0x530 [ 127.508526][ C0] do_syscall_64+0xff/0x530 [ 127.508527][ C0] ? irq_exit_rcu+0x1a/0x30 [ 127.508529][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 127.508530][ C0] RIP: 0033:0x7f01114f654e [ 127.508531][ 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 [ 127.508532][ C0] RSP: 002b:00007ffe186cc130 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 127.508534][ C0] RAX: ffffffffffffffda RBX: 0000000000000040 RCX: 00007f01114f654e [ 127.508534][ C0] RDX: 0000000000000040 RSI: 000055d5cdfa10a4 RDI: 0000000000000005 [ 127.508535][ C0] RBP: 00007ffe186cc140 R08: 000055d5cdfb3320 R09: 0000000000000010 [ 127.508536][ C0] R10: 0000000000000000 R11: 0000000000000202 R12: 431bde82d7b634db [ 127.508536][ C0] R13: 0000000000000000 R14: 000055d5cdfb43e0 R15: 000055d5cdfa10a0 [ 127.508538][ C0]