[ 10.030052][ T202] ip (202) used greatest stack depth: 24376 bytes left [ 10.030071][ T202] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.030073][ T202] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 202, name: ip [ 10.030075][ T202] preempt_count: 2, expected: 0 [ 10.030076][ T202] RCU nest depth: 0, expected: 0 [ 10.030077][ T202] locks held by ip/202: 5, last CPU#3: [ 10.030079][ T202] #0: ffffffffa9c267b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 10.030092][ T202] #1: ffffffffa9d8dcc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.030097][ T202] #2: ffffffffa9d8dd38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.030101][ T202] #3: ffffffffa9cad660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.030105][ T202] #4: ffffffffa9cad560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.030109][ T202] irq event stamp: 28758 [ 10.030110][ T202] hardirqs last enabled at (28757): [] __down_trylock_console_sem+0x86/0xa0 [ 10.030112][ T202] hardirqs last disabled at (28758): [] console_emit_next_record+0x3d4/0x4c0 [ 10.030114][ T202] softirqs last enabled at (28312): [] handle_softirqs+0x67c/0x900 [ 10.030116][ T202] softirqs last disabled at (28307): [] __irq_exit_rcu+0x145/0x1c0 [ 10.030118][ T202] Preemption disabled at: [ 10.030119][ T202] [<0000000000000000>] 0x0 [ 10.030126][ T202] CPU: 3 UID: 0 PID: 202 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.030130][ T202] Tainted: [W]=WARN [ 10.030131][ T202] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.030133][ T202] Call Trace: [ 10.030134][ T202] [ 10.030136][ T202] dump_stack_lvl+0x6f/0xa0 [ 10.030142][ T202] __might_resched.cold+0x1fe/0x2c1 [ 10.030147][ T202] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.030150][ T202] ? __kmalloc_noprof+0xdb/0x760 [ 10.030155][ T202] __kmalloc_noprof+0x443/0x760 [ 10.030157][ T202] ? alloc_buf.isra.0+0x4b/0x260 [ 10.030164][ T202] ? do_raw_spin_unlock+0x59/0x250 [ 10.030166][ T202] alloc_buf.isra.0+0x4b/0x260 [ 10.030170][ T202] put_chars+0x1e1/0x2f0 [ 10.030173][ T202] ? __send_to_port+0x420/0x420 [ 10.030175][ T202] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.030180][ T202] ? validate_chain+0x38b/0xc20 [ 10.030185][ T202] hvc_console_print+0x292/0x780 [ 10.030192][ T202] ? hvc_write+0x3a0/0x3a0 [ 10.030195][ T202] ? rcu_is_watching+0x16/0xd0 [ 10.030197][ T202] ? lock_acquire+0x13c/0x160 [ 10.030201][ T202] console_emit_next_record+0x22f/0x4c0 [ 10.030205][ T202] ? devkmsg_read+0x4b0/0x4b0 [ 10.030207][ T202] ? console_flush_one_record+0x106/0x710 [ 10.030210][ T202] ? rcu_is_watching+0x16/0xd0 [ 10.030212][ T202] ? lock_acquire+0x13c/0x160 [ 10.030216][ T202] console_flush_one_record+0x46f/0x710 [ 10.030220][ T202] ? console_emit_next_record+0x4c0/0x4c0 [ 10.030222][ T202] ? __lock_acquire+0x518/0xc20 [ 10.030227][ T202] console_unlock+0xee/0x1f0 [ 10.030230][ T202] ? console_flush_one_record+0x710/0x710 [ 10.030231][ T202] ? rcu_is_watching+0x16/0xd0 [ 10.030234][ T202] ? lock_acquire+0x60/0x160 [ 10.030237][ T202] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.030239][ T202] ? vprintk_emit+0x320/0x3e0 [ 10.030242][ T202] vprintk_emit+0x37c/0x3e0 [ 10.030245][ T202] ? wake_up_klogd_work_func+0x90/0x90 [ 10.030249][ T202] ? __lock_acquire+0x518/0xc20 [ 10.030252][ T202] _printk+0xc7/0x100 [ 10.030256][ T202] ? snapshot_read.cold+0x21/0x21 [ 10.030259][ T202] ? do_raw_spin_lock+0x131/0x280 [ 10.030261][ T202] ? __rwlock_init+0x150/0x150 [ 10.030265][ T202] ? do_raw_spin_lock+0x131/0x280 [ 10.030268][ T202] do_exit.cold+0x82/0x9c [ 10.030272][ T202] ? exit_notify+0x890/0x890 [ 10.030273][ T202] ? __lock_release.isra.0+0x69/0x1a0 [ 10.030276][ T202] ? rcu_is_watching+0x16/0xd0 [ 10.030280][ T202] do_group_exit+0xb8/0x370 [ 10.030283][ T202] __x64_sys_exit_group+0x3c/0x50 [ 10.030286][ T202] x64_sys_call+0x1567/0x1570 [ 10.030288][ T202] do_syscall_64+0xff/0x530 [ 10.030291][ T202] ? exc_page_fault+0xee/0x100 [ 10.030295][ T202] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.030297][ T202] RIP: 0033:0x7fdc5ef1c1b8 [ 10.030300][ T202] Code: Unable to access opcode bytes at 0x7fdc5ef1c18e. [ 10.030301][ T202] RSP: 002b:00007ffea0202c98 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 10.030303][ T202] RAX: ffffffffffffffda RBX: 00007fdc5f04cf88 RCX: 00007fdc5ef1c1b8 [ 10.030305][ T202] RDX: 00007fdc5ec66fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 10.030305][ T202] RBP: 00007ffea0202cf0 R08: 0000000000000000 R09: 0000000000000050 [ 10.030306][ T202] R10: 00007ffea0202ab0 R11: 0000000000000246 R12: 0000000000000001 [ 10.030307][ T202] R13: 0000000000000000 R14: 00007fdc5f04b680 R15: 00007fdc5f04cfa0 [ 10.030314][ T202] [ 10.392355][ T210] ip (210) used greatest stack depth: 23904 bytes left [ 10.434737][ T211] ip (211) used greatest stack depth: 23696 bytes left [ 22.389960][ C0] clocksource: Watchdog remote CPU 3 read timed out [ 22.390009][ C0] [ 22.390011][ C0] ======================================================== [ 22.390012][ C0] WARNING: possible irq lock inversion dependency detected [ 22.390015][ C0] 7.2.0-virtme #1 Tainted: G W [ 22.390017][ C0] -------------------------------------------------------- [ 22.390018][ C0] mptcp_connect/686 just changed the state of lock: [ 22.390020][ C0] ffffffffa9cad660 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 22.390035][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 22.390037][ C0] (fs_reclaim){+.+.}-{0:0} [ 22.390039][ C0] [ 22.390039][ C0] [ 22.390039][ C0] and interrupts could create inverse lock ordering between them. [ 22.390039][ C0] [ 22.390040][ C0] [ 22.390040][ C0] other info that might help us debug this: [ 22.390041][ C0] Possible interrupt unsafe locking scenario: [ 22.390041][ C0] [ 22.390042][ C0] CPU0 CPU1 [ 22.390043][ C0] ---- ---- [ 22.390044][ C0] lock(fs_reclaim); [ 22.390046][ C0] local_irq_disable(); [ 22.390046][ C0] lock(console_owner); [ 22.390048][ C0] lock(fs_reclaim); [ 22.390050][ C0] [ 22.390050][ C0] lock(console_owner); [ 22.390052][ C0] [ 22.390052][ C0] *** DEADLOCK *** [ 22.390052][ C0] [ 22.390052][ C0] locks held by mptcp_connect/686: 6, last CPU#0: [ 22.390055][ C0] #0: ff1100000fc19b90 (sk_lock-AF_INET){+.+.}-{0:0}, at: mptcp_sendmsg+0x12d/0x1de0 [ 22.390063][ C0] #1: ff1100000ce23890 (k-sk_lock-AF_INET){+.+.}-{0:0}, at: __mptcp_push_pending+0x231/0x510 [ 22.390069][ C0] #2: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 22.390076][ C0] #3: ffffffffa9df28b8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 22.390082][ C0] #4: ffffffffa9d8dcc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 22.390087][ C0] #5: ffffffffa9d8dd38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 22.390092][ C0] [ 22.390092][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 22.390098][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 22.390101][ C0] HARDIRQ-ON-W at: [ 22.390103][ C0] __lock_acquire+0x388/0xc20 [ 22.390107][ C0] lock_acquire.part.0+0xd4/0x280 [ 22.390109][ C0] fs_reclaim_acquire+0xd5/0x120 [ 22.390113][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 22.390116][ C0] kthread_create_worker_on_node+0xea/0x210 [ 22.390120][ C0] workqueue_init+0x2a/0x680 [ 22.390124][ C0] kernel_init_freeable+0x2fe/0x630 [ 22.390127][ C0] kernel_init+0x21/0x150 [ 22.390130][ C0] ret_from_fork+0x474/0x6b0 [ 22.390134][ C0] ret_from_fork_asm+0x11/0x20 [ 22.390138][ C0] SOFTIRQ-ON-W at: [ 22.390139][ C0] __lock_acquire+0x388/0xc20 [ 22.390141][ C0] lock_acquire.part.0+0xd4/0x280 [ 22.390143][ C0] fs_reclaim_acquire+0xd5/0x120 [ 22.390146][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 22.390148][ C0] kthread_create_worker_on_node+0xea/0x210 [ 22.390150][ C0] workqueue_init+0x2a/0x680 [ 22.390151][ C0] kernel_init_freeable+0x2fe/0x630 [ 22.390153][ C0] kernel_init+0x21/0x150 [ 22.390155][ C0] ret_from_fork+0x474/0x6b0 [ 22.390157][ C0] ret_from_fork_asm+0x11/0x20 [ 22.390159][ C0] INITIAL USE at: [ 22.390160][ C0] __lock_acquire+0x388/0xc20 [ 22.390162][ C0] lock_acquire.part.0+0xd4/0x280 [ 22.390164][ C0] fs_reclaim_acquire+0xd5/0x120 [ 22.390166][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 22.390168][ C0] kthread_create_worker_on_node+0xea/0x210 [ 22.390170][ C0] workqueue_init+0x2a/0x680 [ 22.390171][ C0] kernel_init_freeable+0x2fe/0x630 [ 22.390173][ C0] kernel_init+0x21/0x150 [ 22.390175][ C0] ret_from_fork+0x474/0x6b0 [ 22.390178][ C0] ret_from_fork_asm+0x11/0x20 [ 22.390179][ C0] } [ 22.390180][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 22.390185][ C0] ... acquired at: [ 22.390186][ C0] __lock_acquire+0x518/0xc20 [ 22.390188][ C0] lock_acquire.part.0+0xd4/0x280 [ 22.390190][ C0] fs_reclaim_acquire+0xd5/0x120 [ 22.390192][ C0] __kmalloc_noprof+0xd3/0x760 [ 22.390194][ C0] alloc_buf.isra.0+0x4b/0x260 [ 22.390198][ C0] put_chars+0x1e1/0x2f0 [ 22.390201][ C0] hvc_console_print+0x292/0x780 [ 22.390204][ C0] console_emit_next_record+0x22f/0x4c0 [ 22.390207][ C0] console_flush_one_record+0x46f/0x710 [ 22.390209][ C0] console_unlock+0xee/0x1f0 [ 22.390211][ C0] vprintk_emit+0x37c/0x3e0 [ 22.390213][ C0] dev_vprintk_emit+0x27f/0x2c0 [ 22.390217][ C0] dev_printk_emit+0xb9/0xee [ 22.390220][ C0] _dev_info+0xe2/0x116 [ 22.390222][ C0] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 22.390225][ C0] cmos_do_probe+0x73b/0x98a [ 22.390226][ C0] platform_probe+0xfe/0x1f0 [ 22.390230][ C0] call_driver_probe+0x61/0x1c0 [ 22.390233][ C0] really_probe+0x199/0x760 [ 22.390234][ C0] __driver_probe_device+0x24f/0x440 [ 22.390236][ C0] driver_probe_device+0x4a/0xf0 [ 22.390238][ C0] __driver_attach+0x1b8/0x540 [ 22.390240][ C0] bus_for_each_dev+0x130/0x1e0 [ 22.390242][ C0] bus_add_driver+0x2c8/0x530 [ 22.390243][ C0] driver_register+0x1a3/0x390 [ 22.390246][ C0] __platform_driver_probe+0x13f/0x270 [ 22.390248][ C0] cmos_init+0x31/0x40 [ 22.390251][ C0] do_one_initcall+0x124/0x4f0 [ 22.390253][ C0] kernel_init_freeable+0x596/0x630 [ 22.390255][ C0] kernel_init+0x21/0x150 [ 22.390257][ C0] ret_from_fork+0x474/0x6b0 [ 22.390259][ C0] ret_from_fork_asm+0x11/0x20 [ 22.390261][ C0] [ 22.390262][ C0] -> (console_owner){..-.}-{0:0} { [ 22.390265][ C0] IN-SOFTIRQ-W at: [ 22.390266][ C0] __lock_acquire+0x388/0xc20 [ 22.390268][ C0] lock_acquire.part.0+0xd4/0x280 [ 22.390270][ C0] console_lock_spinning_enable+0x5c/0x60 [ 22.390272][ C0] console_emit_next_record+0x1d1/0x4c0 [ 22.390274][ C0] console_flush_one_record+0x46f/0x710 [ 22.390276][ C0] console_unlock+0xee/0x1f0 [ 22.390278][ C0] vprintk_emit+0x37c/0x3e0 [ 22.390280][ C0] _printk+0xc7/0x100 [ 22.390284][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 22.390287][ C0] call_timer_fn+0x160/0x4d0 [ 22.390289][ C0] __run_timers+0x68f/0xaa0 [ 22.390291][ C0] run_timer_softirq+0xf0/0x160 [ 22.390293][ C0] handle_softirqs+0x1d3/0x900 [ 22.390296][ C0] __irq_exit_rcu+0x145/0x1c0 [ 22.390297][ C0] irq_exit_rcu+0xe/0x30 [ 22.390299][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 22.390301][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 22.390304][ C0] __tcp_transmit_skb+0x1b21/0x3ac0 [ 22.390307][ C0] tcp_write_xmit+0x5a4/0x3080 [ 22.390309][ C0] __tcp_push_pending_frames+0x8f/0x3b0 [ 22.390311][ C0] tcp_rcv_established+0x713/0x3910 [ 22.390314][ C0] tcp_v4_do_rcv+0x4f6/0xb90 [ 22.390316][ C0] __release_sock+0x135/0x3a0 [ 22.390320][ C0] release_sock+0x19c/0x240 [ 22.390322][ C0] __mptcp_push_pending+0x36e/0x510 [ 22.390325][ C0] mptcp_sendmsg+0xb57/0x1de0 [ 22.390327][ C0] sock_write_iter+0x290/0x480 [ 22.390330][ C0] new_sync_write+0x400/0x760 [ 22.390333][ C0] vfs_write+0x6a2/0xbd0 [ 22.390336][ C0] ksys_write+0x1ac/0x250 [ 22.390338][ C0] do_syscall_64+0xff/0x530 [ 22.390340][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 22.390342][ C0] INITIAL USE at: [ 22.390343][ C0] } [ 22.390344][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 22.390348][ C0] ... acquired at: [ 22.390348][ C0] mark_lock+0x1d7/0xa00 [ 22.390351][ C0] mark_usage+0x42/0x170 [ 22.390353][ C0] __lock_acquire+0x388/0xc20 [ 22.390354][ C0] lock_acquire.part.0+0xd4/0x280 [ 22.390356][ C0] console_lock_spinning_enable+0x5c/0x60 [ 22.390358][ C0] console_emit_next_record+0x1d1/0x4c0 [ 22.390360][ C0] console_flush_one_record+0x46f/0x710 [ 22.390362][ C0] console_unlock+0xee/0x1f0 [ 22.390364][ C0] vprintk_emit+0x37c/0x3e0 [ 22.390366][ C0] _printk+0xc7/0x100 [ 22.390368][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 22.390370][ C0] call_timer_fn+0x160/0x4d0 [ 22.390372][ C0] __run_timers+0x68f/0xaa0 [ 22.390374][ C0] run_timer_softirq+0xf0/0x160 [ 22.390376][ C0] handle_softirqs+0x1d3/0x900 [ 22.390378][ C0] __irq_exit_rcu+0x145/0x1c0 [ 22.390379][ C0] irq_exit_rcu+0xe/0x30 [ 22.390381][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 22.390383][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 22.390385][ C0] __tcp_transmit_skb+0x1b21/0x3ac0 [ 22.390387][ C0] tcp_write_xmit+0x5a4/0x3080 [ 22.390388][ C0] __tcp_push_pending_frames+0x8f/0x3b0 [ 22.390390][ C0] tcp_rcv_established+0x713/0x3910 [ 22.390392][ C0] tcp_v4_do_rcv+0x4f6/0xb90 [ 22.390394][ C0] __release_sock+0x135/0x3a0 [ 22.390396][ C0] release_sock+0x19c/0x240 [ 22.390399][ C0] __mptcp_push_pending+0x36e/0x510 [ 22.390401][ C0] mptcp_sendmsg+0xb57/0x1de0 [ 22.390403][ C0] sock_write_iter+0x290/0x480 [ 22.390405][ C0] new_sync_write+0x400/0x760 [ 22.390407][ C0] vfs_write+0x6a2/0xbd0 [ 22.390409][ C0] ksys_write+0x1ac/0x250 [ 22.390411][ C0] do_syscall_64+0xff/0x530 [ 22.390412][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 22.390414][ C0] [ 22.390415][ C0] [ 22.390415][ C0] stack backtrace: [ 22.390420][ C0] CPU: 0 UID: 0 PID: 686 Comm: mptcp_connect Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 22.390424][ C0] Tainted: [W]=WARN [ 22.390425][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 22.390428][ C0] Call Trace: [ 22.390429][ C0] [ 22.390431][ C0] dump_stack_lvl+0x6f/0xa0 [ 22.390436][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 22.390440][ C0] mark_lock_irq+0x989/0x9c0 [ 22.390442][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 22.390446][ C0] mark_lock+0x1d7/0xa00 [ 22.390449][ C0] mark_usage+0x42/0x170 [ 22.390451][ C0] __lock_acquire+0x388/0xc20 [ 22.390454][ C0] lock_acquire.part.0+0xd4/0x280 [ 22.390456][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 22.390459][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.390462][ C0] ? lock_acquire+0x13c/0x160 [ 22.390465][ C0] console_lock_spinning_enable+0x5c/0x60 [ 22.390467][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 22.390470][ C0] console_emit_next_record+0x1d1/0x4c0 [ 22.390473][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 22.390475][ C0] ? console_flush_one_record+0x106/0x710 [ 22.390478][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.390485][ C0] ? lock_acquire+0x13c/0x160 [ 22.390488][ C0] console_flush_one_record+0x46f/0x710 [ 22.390491][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 22.390493][ C0] ? __lock_acquire+0x518/0xc20 [ 22.390497][ C0] console_unlock+0xee/0x1f0 [ 22.390499][ C0] ? console_flush_one_record+0x710/0x710 [ 22.390501][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.390504][ C0] ? lock_acquire+0x60/0x160 [ 22.390506][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 22.390509][ C0] ? vprintk_emit+0x320/0x3e0 [ 22.390511][ C0] vprintk_emit+0x37c/0x3e0 [ 22.390515][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 22.390518][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 22.390521][ C0] _printk+0xc7/0x100 [ 22.390524][ C0] ? snapshot_read.cold+0x21/0x21 [ 22.390527][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 22.390529][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 22.390532][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 22.390534][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 22.390537][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 22.390539][ C0] call_timer_fn+0x160/0x4d0 [ 22.390542][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 22.390544][ C0] ? debug_object_active_state+0x430/0x430 [ 22.390548][ C0] ? find_held_lock+0x2b/0x80 [ 22.390552][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 22.390555][ C0] ? mark_held_locks+0x40/0x70 [ 22.390558][ C0] __run_timers+0x68f/0xaa0 [ 22.390560][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 22.390563][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 22.390566][ C0] ? __lock_acquire+0x518/0xc20 [ 22.390570][ C0] ? __rwlock_init+0x150/0x150 [ 22.390573][ C0] run_timer_softirq+0xf0/0x160 [ 22.390576][ C0] ? __run_timers+0xaa0/0xaa0 [ 22.390579][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.390581][ C0] handle_softirqs+0x1d3/0x900 [ 22.390585][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 22.390587][ C0] ? _local_bh_enable+0xc0/0xc0 [ 22.390590][ C0] __irq_exit_rcu+0x145/0x1c0 [ 22.390592][ C0] irq_exit_rcu+0xe/0x30 [ 22.390594][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 22.390597][ C0] [ 22.390598][ C0] [ 22.390599][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 22.390602][ C0] RIP: 0010:__tcp_transmit_skb+0x1b21/0x3ac0 [ 22.390605][ C0] Code: 00 00 00 fc ff df 4c 89 e2 48 c1 ea 03 80 3c 02 00 0f 85 16 17 00 00 48 8d 93 e8 05 00 00 48 89 ee 48 89 df 41 ff 14 24 85 c0 <0f> 8f 18 0e 00 00 0f 94 c2 48 8b 74 24 08 48 85 f6 0f 84 39 02 00 [ 22.390608][ C0] RSP: 0018:ffa000000242f4d0 EFLAGS: 00000246 [ 22.390611][ C0] RAX: 0000000000000000 RBX: ff1100000ce23640 RCX: 0000000000000000 [ 22.390613][ C0] RDX: 0000000000000007 RSI: ffffffffa9840585 RDI: ff1100000d714b44 [ 22.390615][ C0] RBP: ff1100000fc06a68 R08: 0000000000000000 R09: 0000000000000000 [ 22.390616][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: ffffffffa98fe340 [ 22.390617][ C0] R13: ff1100000d0fc880 R14: 000000000000058c R15: ff1100000fc06a98 [ 22.390626][ C0] ? __tcp_select_window+0x1040/0x1040 [ 22.390628][ C0] ? find_held_lock+0x2b/0x80 [ 22.390632][ C0] ? kvm_clock_get_cycles+0x19/0x30 [ 22.390637][ C0] tcp_write_xmit+0x5a4/0x3080 [ 22.390641][ C0] ? tcp_current_mss+0x1b0/0x340 [ 22.390643][ C0] ? tcp_retrans_try_collapse+0x1090/0x1090 [ 22.390647][ C0] __tcp_push_pending_frames+0x8f/0x3b0 [ 22.390649][ C0] tcp_rcv_established+0x713/0x3910 [ 22.390653][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 22.390655][ C0] ? find_held_lock+0x2b/0x80 [ 22.390658][ C0] ? __tcp_check_space+0x600/0x600 [ 22.390661][ C0] ? ipv4_dst_check+0x177/0x2e0 [ 22.390664][ C0] tcp_v4_do_rcv+0x4f6/0xb90 [ 22.390667][ C0] ? __local_bh_enable_ip+0xaa/0x150 [ 22.390669][ C0] __release_sock+0x135/0x3a0 [ 22.390674][ C0] release_sock+0x19c/0x240 [ 22.390676][ C0] ? mptcp_push_release.isra.0+0xbb/0x110 [ 22.390679][ C0] __mptcp_push_pending+0x36e/0x510 [ 22.390682][ C0] ? __mptcp_wr_shutdown+0x2d0/0x2d0 [ 22.390687][ C0] mptcp_sendmsg+0xb57/0x1de0 [ 22.390691][ C0] ? mptcp_sendmsg_fastopen.constprop.0+0x500/0x500 [ 22.390694][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 22.390698][ C0] sock_write_iter+0x290/0x480 [ 22.390701][ C0] ? __get_unmapped_area+0x190/0x2c0 [ 22.390705][ C0] ? ____sys_recvmsg+0x710/0x710 [ 22.390709][ C0] ? __ia32_sys_brk+0x50/0x50 [ 22.390711][ C0] ? ____sys_recvmsg+0x710/0x710 [ 22.390714][ C0] new_sync_write+0x400/0x760 [ 22.390717][ C0] ? find_held_lock+0x2b/0x80 [ 22.390720][ C0] ? new_sync_read+0x750/0x750 [ 22.390723][ C0] ? __up_write+0x23d/0x5f0 [ 22.390725][ C0] ? __up_write+0x2e5/0x5f0 [ 22.390729][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 22.390732][ C0] vfs_write+0x6a2/0xbd0 [ 22.390735][ C0] ksys_write+0x1ac/0x250 [ 22.390738][ C0] ? __ia32_sys_read+0xc0/0xc0 [ 22.390740][ C0] ? ksys_mmap_pgoff+0x1fd/0x560 [ 22.390742][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.390745][ C0] do_syscall_64+0xff/0x530 [ 22.390747][ C0] ? exc_page_fault+0xee/0x100 [ 22.390749][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 22.390751][ C0] RIP: 0033:0x7fac5ef5554e [ 22.390755][ 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 [ 22.390757][ C0] RSP: 002b:00007ffd98a969e0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 22.390759][ C0] RAX: ffffffffffffffda RBX: 00000000001ec37c RCX: 00007fac5ef5554e [ 22.390761][ C0] RDX: 00000000001ec37c RSI: 00007fac5ecf4000 RDI: 0000000000000006 [ 22.390762][ C0] RBP: 00007ffd98a969f0 R08: 0000000000000000 R09: 0000000000000000 [ 22.390763][ C0] R10: 0000000000000000 R11: 0000000000000202 R12: 0000000000000006 [ 22.390764][ C0] R13: 00007fac5ecf4000 R14: 00000000001ec37c R15: 00007ffd98a96cd0 [ 22.390768][ C0] [ 22.390774][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 22.390776][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 686, name: mptcp_connect [ 22.390778][ C0] preempt_count: 102, expected: 0 [ 22.390779][ C0] RCU nest depth: 0, expected: 0 [ 22.390780][ C0] INFO: lockdep is turned off. [ 22.390781][ C0] irq event stamp: 47661 [ 22.390782][ C0] hardirqs last enabled at (47660): [] __down_trylock_console_sem+0x86/0xa0 [ 22.390785][ C0] hardirqs last disabled at (47661): [] console_emit_next_record+0x3d4/0x4c0 [ 22.390787][ C0] softirqs last enabled at (47642): [] handle_softirqs+0x67c/0x900 [ 22.390789][ C0] softirqs last disabled at (47645): [] __irq_exit_rcu+0x145/0x1c0 [ 22.390791][ C0] Preemption disabled at: [ 22.390792][ C0] [] handle_softirqs+0xf6/0x900 [ 22.390796][ C0] CPU: 0 UID: 0 PID: 686 Comm: mptcp_connect Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 22.390799][ C0] Tainted: [W]=WARN [ 22.390800][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 22.390801][ C0] Call Trace: [ 22.390803][ C0] [ 22.390804][ C0] dump_stack_lvl+0x6f/0xa0 [ 22.390807][ C0] ? handle_softirqs+0xf6/0x900 [ 22.390809][ C0] __might_resched.cold+0x1fe/0x2c1 [ 22.390813][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 22.390816][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.390819][ C0] __kmalloc_noprof+0x443/0x760 [ 22.390822][ C0] ? __rwlock_init+0x150/0x150 [ 22.390824][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 22.390829][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 22.390831][ C0] alloc_buf.isra.0+0x4b/0x260 [ 22.390835][ C0] put_chars+0x1e1/0x2f0 [ 22.390838][ C0] ? __send_to_port+0x420/0x420 [ 22.390841][ C0] ? mark_lock+0x1d7/0xa00 [ 22.390844][ C0] hvc_console_print+0x292/0x780 [ 22.390850][ C0] ? hvc_write+0x3a0/0x3a0 [ 22.390852][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.390854][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.390858][ C0] console_emit_next_record+0x22f/0x4c0 [ 22.390861][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 22.390863][ C0] ? console_flush_one_record+0x106/0x710 [ 22.390865][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.390868][ C0] ? lock_acquire+0x13c/0x160 [ 22.390871][ C0] console_flush_one_record+0x46f/0x710 [ 22.390874][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 22.390876][ C0] ? __lock_acquire+0x518/0xc20 [ 22.390879][ C0] console_unlock+0xee/0x1f0 [ 22.390882][ C0] ? console_flush_one_record+0x710/0x710 [ 22.390884][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.390886][ C0] ? lock_acquire+0x60/0x160 [ 22.390889][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 22.390891][ C0] ? vprintk_emit+0x320/0x3e0 [ 22.390894][ C0] vprintk_emit+0x37c/0x3e0 [ 22.390897][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 22.390900][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 22.390903][ C0] _printk+0xc7/0x100 [ 22.390906][ C0] ? snapshot_read.cold+0x21/0x21 [ 22.390908][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 22.390911][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 22.390914][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 22.390917][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 22.390920][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 22.390922][ C0] call_timer_fn+0x160/0x4d0 [ 22.390925][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 22.390927][ C0] ? debug_object_active_state+0x430/0x430 [ 22.390931][ C0] ? find_held_lock+0x2b/0x80 [ 22.390933][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 22.390936][ C0] ? mark_held_locks+0x40/0x70 [ 22.390939][ C0] __run_timers+0x68f/0xaa0 [ 22.390941][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 22.390944][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 22.390947][ C0] ? __lock_acquire+0x518/0xc20 [ 22.390951][ C0] ? __rwlock_init+0x150/0x150 [ 22.390954][ C0] run_timer_softirq+0xf0/0x160 [ 22.390956][ C0] ? __run_timers+0xaa0/0xaa0 [ 22.390959][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.390962][ C0] handle_softirqs+0x1d3/0x900 [ 22.390964][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 22.390966][ C0] ? _local_bh_enable+0xc0/0xc0 [ 22.390969][ C0] __irq_exit_rcu+0x145/0x1c0 [ 22.390971][ C0] irq_exit_rcu+0xe/0x30 [ 22.390973][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 22.390976][ C0] [ 22.390977][ C0] [ 22.390978][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 22.390980][ C0] RIP: 0010:__tcp_transmit_skb+0x1b21/0x3ac0 [ 22.390983][ C0] Code: 00 00 00 fc ff df 4c 89 e2 48 c1 ea 03 80 3c 02 00 0f 85 16 17 00 00 48 8d 93 e8 05 00 00 48 89 ee 48 89 df 41 ff 14 24 85 c0 <0f> 8f 18 0e 00 00 0f 94 c2 48 8b 74 24 08 48 85 f6 0f 84 39 02 00 [ 22.390984][ C0] RSP: 0018:ffa000000242f4d0 EFLAGS: 00000246 [ 22.390986][ C0] RAX: 0000000000000000 RBX: ff1100000ce23640 RCX: 0000000000000000 [ 22.390987][ C0] RDX: 0000000000000007 RSI: ffffffffa9840585 RDI: ff1100000d714b44 [ 22.390988][ C0] RBP: ff1100000fc06a68 R08: 0000000000000000 R09: 0000000000000000 [ 22.390990][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: ffffffffa98fe340 [ 22.390991][ C0] R13: ff1100000d0fc880 R14: 000000000000058c R15: ff1100000fc06a98 [ 22.390996][ C0] ? __tcp_select_window+0x1040/0x1040 [ 22.390998][ C0] ? find_held_lock+0x2b/0x80 [ 22.391003][ C0] ? kvm_clock_get_cycles+0x19/0x30 [ 22.391007][ C0] tcp_write_xmit+0x5a4/0x3080 [ 22.391011][ C0] ? tcp_current_mss+0x1b0/0x340 [ 22.391013][ C0] ? tcp_retrans_try_collapse+0x1090/0x1090 [ 22.391017][ C0] __tcp_push_pending_frames+0x8f/0x3b0 [ 22.391020][ C0] tcp_rcv_established+0x713/0x3910 [ 22.391023][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 22.391025][ C0] ? find_held_lock+0x2b/0x80 [ 22.391028][ C0] ? __tcp_check_space+0x600/0x600 [ 22.391031][ C0] ? ipv4_dst_check+0x177/0x2e0 [ 22.391034][ C0] tcp_v4_do_rcv+0x4f6/0xb90 [ 22.391036][ C0] ? __local_bh_enable_ip+0xaa/0x150 [ 22.391039][ C0] __release_sock+0x135/0x3a0 [ 22.391043][ C0] release_sock+0x19c/0x240 [ 22.391045][ C0] ? mptcp_push_release.isra.0+0xbb/0x110 [ 22.391048][ C0] __mptcp_push_pending+0x36e/0x510 [ 22.391052][ C0] ? __mptcp_wr_shutdown+0x2d0/0x2d0 [ 22.391056][ C0] mptcp_sendmsg+0xb57/0x1de0 [ 22.391061][ C0] ? mptcp_sendmsg_fastopen.constprop.0+0x500/0x500 [ 22.391064][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 22.391067][ C0] sock_write_iter+0x290/0x480 [ 22.391070][ C0] ? __get_unmapped_area+0x190/0x2c0 [ 22.391073][ C0] ? ____sys_recvmsg+0x710/0x710 [ 22.391077][ C0] ? __ia32_sys_brk+0x50/0x50 [ 22.391079][ C0] ? ____sys_recvmsg+0x710/0x710 [ 22.391082][ C0] new_sync_write+0x400/0x760 [ 22.391085][ C0] ? find_held_lock+0x2b/0x80 [ 22.391088][ C0] ? new_sync_read+0x750/0x750 [ 22.391090][ C0] ? __up_write+0x23d/0x5f0 [ 22.391093][ C0] ? __up_write+0x2e5/0x5f0 [ 22.391097][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 22.391100][ C0] vfs_write+0x6a2/0xbd0 [ 22.391103][ C0] ksys_write+0x1ac/0x250 [ 22.391105][ C0] ? __ia32_sys_read+0xc0/0xc0 [ 22.391108][ C0] ? ksys_mmap_pgoff+0x1fd/0x560 [ 22.391110][ C0] ? rcu_is_watching+0x16/0xd0 [ 22.391113][ C0] do_syscall_64+0xff/0x530 [ 22.391115][ C0] ? exc_page_fault+0xee/0x100 [ 22.391118][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 22.391120][ C0] RIP: 0033:0x7fac5ef5554e [ 22.391122][ 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 [ 22.391124][ C0] RSP: 002b:00007ffd98a969e0 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 22.391126][ C0] RAX: ffffffffffffffda RBX: 00000000001ec37c RCX: 00007fac5ef5554e [ 22.391127][ C0] RDX: 00000000001ec37c RSI: 00007fac5ecf4000 RDI: 0000000000000006 [ 22.391128][ C0] RBP: 00007ffd98a969f0 R08: 0000000000000000 R09: 0000000000000000 [ 22.391129][ C0] R10: 0000000000000000 R11: 0000000000000202 R12: 0000000000000006 [ 22.391130][ C0] R13: 00007fac5ecf4000 R14: 00000000001ec37c R15: 00007ffd98a96cd0 [ 22.391134][ C0]