[ 29.422876][ C0] clocksource: Watchdog remote CPU 1 read timed out [ 29.423036][ C0] [ 29.423038][ C0] ======================================================== [ 29.423039][ C0] WARNING: possible irq lock inversion dependency detected [ 29.423041][ C0] 7.2.0-virtme #1 Tainted: G W [ 29.423043][ C0] -------------------------------------------------------- [ 29.423043][ C0] swapper/0/0 just changed the state of lock: [ 29.423045][ C0] ffffffffaf699660 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 29.423058][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 29.423060][ C0] (fs_reclaim){+.+.}-{0:0} [ 29.423061][ C0] [ 29.423061][ C0] [ 29.423061][ C0] and interrupts could create inverse lock ordering between them. [ 29.423061][ C0] [ 29.423062][ C0] [ 29.423062][ C0] other info that might help us debug this: [ 29.423063][ C0] Possible interrupt unsafe locking scenario: [ 29.423063][ C0] [ 29.423064][ C0] CPU0 CPU1 [ 29.423064][ C0] ---- ---- [ 29.423065][ C0] lock(fs_reclaim); [ 29.423066][ C0] local_irq_disable(); [ 29.423066][ C0] lock(console_owner); [ 29.423067][ C0] lock(fs_reclaim); [ 29.423068][ C0] [ 29.423068][ C0] lock(console_owner); [ 29.423069][ C0] [ 29.423069][ C0] *** DEADLOCK *** [ 29.423069][ C0] [ 29.423070][ C0] locks held by swapper/0/0: 4, last CPU#0: [ 29.423071][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 29.423077][ C0] #1: ffffffffaf7de8b8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 29.423081][ C0] #2: ffffffffaf779cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 29.423085][ C0] #3: ffffffffaf779d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 29.423088][ C0] [ 29.423088][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 29.423093][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 29.423095][ C0] HARDIRQ-ON-W at: [ 29.423097][ C0] __lock_acquire+0x388/0xc20 [ 29.423099][ C0] lock_acquire.part.0+0xd4/0x280 [ 29.423101][ C0] fs_reclaim_acquire+0xd5/0x120 [ 29.423104][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 29.423106][ C0] kthread_create_worker_on_node+0xea/0x210 [ 29.423109][ C0] workqueue_init+0x2a/0x680 [ 29.423113][ C0] kernel_init_freeable+0x2fe/0x630 [ 29.423115][ C0] kernel_init+0x21/0x150 [ 29.423119][ C0] ret_from_fork+0x474/0x6b0 [ 29.423122][ C0] ret_from_fork_asm+0x11/0x20 [ 29.423125][ C0] SOFTIRQ-ON-W at: [ 29.423126][ C0] __lock_acquire+0x388/0xc20 [ 29.423127][ C0] lock_acquire.part.0+0xd4/0x280 [ 29.423129][ C0] fs_reclaim_acquire+0xd5/0x120 [ 29.423130][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 29.423131][ C0] kthread_create_worker_on_node+0xea/0x210 [ 29.423132][ C0] workqueue_init+0x2a/0x680 [ 29.423133][ C0] kernel_init_freeable+0x2fe/0x630 [ 29.423135][ C0] kernel_init+0x21/0x150 [ 29.423136][ C0] ret_from_fork+0x474/0x6b0 [ 29.423138][ C0] ret_from_fork_asm+0x11/0x20 [ 29.423139][ C0] INITIAL USE at: [ 29.423140][ C0] __lock_acquire+0x388/0xc20 [ 29.423141][ C0] lock_acquire.part.0+0xd4/0x280 [ 29.423142][ C0] fs_reclaim_acquire+0xd5/0x120 [ 29.423143][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 29.423145][ C0] kthread_create_worker_on_node+0xea/0x210 [ 29.423146][ C0] workqueue_init+0x2a/0x680 [ 29.423147][ C0] kernel_init_freeable+0x2fe/0x630 [ 29.423149][ C0] kernel_init+0x21/0x150 [ 29.423150][ C0] ret_from_fork+0x474/0x6b0 [ 29.423151][ C0] ret_from_fork_asm+0x11/0x20 [ 29.423153][ C0] } [ 29.423153][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 29.423157][ C0] ... acquired at: [ 29.423158][ C0] __lock_acquire+0x518/0xc20 [ 29.423159][ C0] lock_acquire.part.0+0xd4/0x280 [ 29.423161][ C0] fs_reclaim_acquire+0xd5/0x120 [ 29.423162][ C0] __kmalloc_noprof+0xd3/0x760 [ 29.423163][ C0] alloc_buf.isra.0+0x4b/0x260 [ 29.423166][ C0] put_chars+0x1e1/0x2f0 [ 29.423167][ C0] hvc_console_print+0x292/0x780 [ 29.423170][ C0] console_emit_next_record+0x22f/0x4c0 [ 29.423171][ C0] console_flush_one_record+0x46f/0x710 [ 29.423173][ C0] console_unlock+0xee/0x1f0 [ 29.423175][ C0] vprintk_emit+0x37c/0x3e0 [ 29.423176][ C0] dev_vprintk_emit+0x27f/0x2c0 [ 29.423179][ C0] dev_printk_emit+0xb9/0xee [ 29.423181][ C0] _dev_info+0xe2/0x116 [ 29.423183][ C0] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 29.423185][ C0] cmos_do_probe+0x73b/0x98a [ 29.423187][ C0] platform_probe+0xfe/0x1f0 [ 29.423189][ C0] call_driver_probe+0x61/0x1c0 [ 29.423191][ C0] really_probe+0x199/0x760 [ 29.423193][ C0] __driver_probe_device+0x24f/0x440 [ 29.423194][ C0] driver_probe_device+0x4a/0xf0 [ 29.423196][ C0] __driver_attach+0x1b8/0x540 [ 29.423198][ C0] bus_for_each_dev+0x130/0x1e0 [ 29.423199][ C0] bus_add_driver+0x2c8/0x530 [ 29.423200][ C0] driver_register+0x1a3/0x390 [ 29.423202][ C0] __platform_driver_probe+0x13f/0x270 [ 29.423203][ C0] cmos_init+0x31/0x40 [ 29.423206][ C0] do_one_initcall+0x124/0x4f0 [ 29.423207][ C0] kernel_init_freeable+0x596/0x630 [ 29.423209][ C0] kernel_init+0x21/0x150 [ 29.423210][ C0] ret_from_fork+0x474/0x6b0 [ 29.423211][ C0] ret_from_fork_asm+0x11/0x20 [ 29.423212][ C0] [ 29.423213][ C0] -> (console_owner){..-.}-{0:0} { [ 29.423215][ C0] IN-SOFTIRQ-W at: [ 29.423215][ C0] __lock_acquire+0x388/0xc20 [ 29.423217][ C0] lock_acquire.part.0+0xd4/0x280 [ 29.423218][ C0] console_lock_spinning_enable+0x5c/0x60 [ 29.423220][ C0] console_emit_next_record+0x1d1/0x4c0 [ 29.423221][ C0] console_flush_one_record+0x46f/0x710 [ 29.423223][ C0] console_unlock+0xee/0x1f0 [ 29.423224][ C0] vprintk_emit+0x37c/0x3e0 [ 29.423226][ C0] _printk+0xc7/0x100 [ 29.423229][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 29.423231][ C0] call_timer_fn+0x160/0x4d0 [ 29.423233][ C0] __run_timers+0x68f/0xaa0 [ 29.423234][ C0] run_timer_softirq+0xf0/0x160 [ 29.423236][ C0] handle_softirqs+0x1d3/0x900 [ 29.423238][ C0] __irq_exit_rcu+0x145/0x1c0 [ 29.423240][ C0] irq_exit_rcu+0xe/0x30 [ 29.423241][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 29.423243][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 29.423244][ C0] pv_native_safe_halt+0xf/0x10 [ 29.423246][ C0] default_idle+0x9/0x10 [ 29.423247][ C0] default_idle_call+0x6e/0xb0 [ 29.423248][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 29.423250][ C0] do_idle+0xd8/0x190 [ 29.423251][ C0] cpu_startup_entry+0x53/0x70 [ 29.423253][ C0] rest_init+0x279/0x280 [ 29.423254][ C0] start_kernel+0x3af/0x3b0 [ 29.423256][ C0] x86_64_start_reservations+0x24/0x30 [ 29.423258][ C0] x86_64_start_kernel+0x12b/0x130 [ 29.423259][ C0] common_startup_64+0x13e/0x148 [ 29.423262][ C0] INITIAL USE at: [ 29.423263][ C0] } [ 29.423264][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 29.423267][ C0] ... acquired at: [ 29.423267][ C0] mark_lock+0x1d7/0xa00 [ 29.423269][ C0] mark_usage+0x42/0x170 [ 29.423270][ C0] __lock_acquire+0x388/0xc20 [ 29.423271][ C0] lock_acquire.part.0+0xd4/0x280 [ 29.423273][ C0] console_lock_spinning_enable+0x5c/0x60 [ 29.423274][ C0] console_emit_next_record+0x1d1/0x4c0 [ 29.423276][ C0] console_flush_one_record+0x46f/0x710 [ 29.423277][ C0] console_unlock+0xee/0x1f0 [ 29.423279][ C0] vprintk_emit+0x37c/0x3e0 [ 29.423280][ C0] _printk+0xc7/0x100 [ 29.423282][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 29.423283][ C0] call_timer_fn+0x160/0x4d0 [ 29.423284][ C0] __run_timers+0x68f/0xaa0 [ 29.423286][ C0] run_timer_softirq+0xf0/0x160 [ 29.423287][ C0] handle_softirqs+0x1d3/0x900 [ 29.423288][ C0] __irq_exit_rcu+0x145/0x1c0 [ 29.423290][ C0] irq_exit_rcu+0xe/0x30 [ 29.423291][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 29.423292][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 29.423293][ C0] pv_native_safe_halt+0xf/0x10 [ 29.423294][ C0] default_idle+0x9/0x10 [ 29.423295][ C0] default_idle_call+0x6e/0xb0 [ 29.423296][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 29.423297][ C0] do_idle+0xd8/0x190 [ 29.423299][ C0] cpu_startup_entry+0x53/0x70 [ 29.423300][ C0] rest_init+0x279/0x280 [ 29.423301][ C0] start_kernel+0x3af/0x3b0 [ 29.423303][ C0] x86_64_start_reservations+0x24/0x30 [ 29.423304][ C0] x86_64_start_kernel+0x12b/0x130 [ 29.423305][ C0] common_startup_64+0x13e/0x148 [ 29.423306][ C0] [ 29.423307][ C0] [ 29.423307][ C0] stack backtrace: [ 29.423310][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 29.423313][ C0] Tainted: [W]=WARN [ 29.423314][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 29.423315][ C0] Call Trace: [ 29.423317][ C0] [ 29.423318][ C0] dump_stack_lvl+0x6f/0xa0 [ 29.423322][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 29.423324][ C0] mark_lock_irq+0x989/0x9c0 [ 29.423326][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 29.423329][ C0] mark_lock+0x1d7/0xa00 [ 29.423331][ C0] mark_usage+0x42/0x170 [ 29.423332][ C0] __lock_acquire+0x388/0xc20 [ 29.423335][ C0] lock_acquire.part.0+0xd4/0x280 [ 29.423336][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 29.423338][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.423342][ C0] ? lock_acquire+0x13c/0x160 [ 29.423344][ C0] console_lock_spinning_enable+0x5c/0x60 [ 29.423345][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 29.423347][ C0] console_emit_next_record+0x1d1/0x4c0 [ 29.423349][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 29.423351][ C0] ? console_flush_one_record+0x106/0x710 [ 29.423353][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.423355][ C0] ? lock_acquire+0x13c/0x160 [ 29.423357][ C0] console_flush_one_record+0x46f/0x710 [ 29.423359][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 29.423361][ C0] ? __lock_acquire+0x518/0xc20 [ 29.423363][ C0] console_unlock+0xee/0x1f0 [ 29.423365][ C0] ? console_flush_one_record+0x710/0x710 [ 29.423366][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.423368][ C0] ? lock_acquire+0x60/0x160 [ 29.423370][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 29.423372][ C0] ? vprintk_emit+0x320/0x3e0 [ 29.423374][ C0] vprintk_emit+0x37c/0x3e0 [ 29.423376][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 29.423378][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 29.423380][ C0] _printk+0xc7/0x100 [ 29.423382][ C0] ? snapshot_read.cold+0x21/0x21 [ 29.423384][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 29.423385][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 29.423388][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 29.423389][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 29.423391][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 29.423393][ C0] call_timer_fn+0x160/0x4d0 [ 29.423395][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 29.423396][ C0] ? debug_object_active_state+0x430/0x430 [ 29.423400][ C0] ? find_held_lock+0x2b/0x80 [ 29.423402][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 29.423404][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.423406][ C0] __run_timers+0x68f/0xaa0 [ 29.423408][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 29.423410][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 29.423412][ C0] ? __lock_acquire+0x518/0xc20 [ 29.423414][ C0] ? __rwlock_init+0x150/0x150 [ 29.423417][ C0] run_timer_softirq+0xf0/0x160 [ 29.423419][ C0] ? __run_timers+0xaa0/0xaa0 [ 29.423421][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 29.423423][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.423425][ C0] handle_softirqs+0x1d3/0x900 [ 29.423426][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 29.423428][ C0] ? _local_bh_enable+0xc0/0xc0 [ 29.423430][ C0] __irq_exit_rcu+0x145/0x1c0 [ 29.423432][ C0] irq_exit_rcu+0xe/0x30 [ 29.423433][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 29.423434][ C0] [ 29.423435][ C0] [ 29.423435][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 29.423437][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 29.423439][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 29.423441][ C0] Code: 48 8b 3d 94 12 06 02 e8 1f 00 00 00 48 2b 05 58 13 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 b6 17 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 29.423443][ C0] RSP: 0018:ffffffffaf407cf8 EFLAGS: 00000296 [ 29.423445][ C0] RAX: 000000000014b08f RBX: ffffffffaf42c600 RCX: ffffffffac2fa307 [ 29.423447][ C0] RDX: ffffffffaf42c600 RSI: ffffffffaf278e9d RDI: ffffffffaec8dce0 [ 29.423447][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 29.423448][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff5e80fa2 [ 29.423449][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 29.423451][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 29.423453][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 29.423454][ C0] default_idle+0x9/0x10 [ 29.423455][ C0] default_idle_call+0x6e/0xb0 [ 29.423457][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 29.423458][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 29.423460][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 29.423462][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.423464][ C0] do_idle+0xd8/0x190 [ 29.423466][ C0] cpu_startup_entry+0x53/0x70 [ 29.423467][ C0] rest_init+0x279/0x280 [ 29.423469][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 29.423471][ C0] ? rest_init+0x280/0x280 [ 29.423473][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 29.423476][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 29.423479][ C0] ? acpi_enable+0x1e4/0x330 [ 29.423482][ C0] start_kernel+0x3af/0x3b0 [ 29.423484][ C0] x86_64_start_reservations+0x24/0x30 [ 29.423486][ C0] x86_64_start_kernel+0x12b/0x130 [ 29.423488][ C0] common_startup_64+0x13e/0x148 [ 29.423491][ C0] [ 29.423495][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 29.423497][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 29.423498][ C0] preempt_count: 103, expected: 0 [ 29.423499][ C0] RCU nest depth: 0, expected: 0 [ 29.423500][ C0] INFO: lockdep is turned off. [ 29.423501][ C0] irq event stamp: 1355937 [ 29.423501][ C0] hardirqs last enabled at (1355936): [] asm_sysvec_call_function+0x1a/0x20 [ 29.423504][ C0] hardirqs last disabled at (1355937): [] console_emit_next_record+0x3d4/0x4c0 [ 29.423505][ C0] softirqs last enabled at (1355884): [] handle_softirqs+0x67c/0x900 [ 29.423507][ C0] softirqs last disabled at (1355921): [] __irq_exit_rcu+0x145/0x1c0 [ 29.423509][ C0] Preemption disabled at: [ 29.423509][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 29.423512][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 29.423514][ C0] Tainted: [W]=WARN [ 29.423515][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 29.423515][ C0] Call Trace: [ 29.423516][ C0] [ 29.423516][ C0] dump_stack_lvl+0x6f/0xa0 [ 29.423518][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 29.423520][ C0] __might_resched.cold+0x1fe/0x2c1 [ 29.423523][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 29.423526][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.423528][ C0] __kmalloc_noprof+0x443/0x760 [ 29.423530][ C0] ? __rwlock_init+0x150/0x150 [ 29.423532][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 29.423534][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 29.423535][ C0] alloc_buf.isra.0+0x4b/0x260 [ 29.423537][ C0] put_chars+0x1e1/0x2f0 [ 29.423539][ C0] ? __send_to_port+0x420/0x420 [ 29.423541][ C0] ? mark_lock+0x1d7/0xa00 [ 29.423543][ C0] hvc_console_print+0x292/0x780 [ 29.423546][ C0] ? hvc_write+0x3a0/0x3a0 [ 29.423547][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.423549][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.423551][ C0] console_emit_next_record+0x22f/0x4c0 [ 29.423554][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 29.423555][ C0] ? console_flush_one_record+0x106/0x710 [ 29.423557][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.423559][ C0] ? lock_acquire+0x13c/0x160 [ 29.423561][ C0] console_flush_one_record+0x46f/0x710 [ 29.423563][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 29.423565][ C0] ? __lock_acquire+0x518/0xc20 [ 29.423567][ C0] console_unlock+0xee/0x1f0 [ 29.423569][ C0] ? console_flush_one_record+0x710/0x710 [ 29.423571][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.423573][ C0] ? lock_acquire+0x60/0x160 [ 29.423575][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 29.423576][ C0] ? vprintk_emit+0x320/0x3e0 [ 29.423578][ C0] vprintk_emit+0x37c/0x3e0 [ 29.423580][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 29.423583][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 29.423584][ C0] _printk+0xc7/0x100 [ 29.423586][ C0] ? snapshot_read.cold+0x21/0x21 [ 29.423588][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 29.423590][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 29.423592][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 29.423593][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 29.423595][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 29.423597][ C0] call_timer_fn+0x160/0x4d0 [ 29.423599][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 29.423600][ C0] ? debug_object_active_state+0x430/0x430 [ 29.423602][ C0] ? find_held_lock+0x2b/0x80 [ 29.423604][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 29.423605][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.423608][ C0] __run_timers+0x68f/0xaa0 [ 29.423609][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 29.423612][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 29.423613][ C0] ? __lock_acquire+0x518/0xc20 [ 29.423616][ C0] ? __rwlock_init+0x150/0x150 [ 29.423618][ C0] run_timer_softirq+0xf0/0x160 [ 29.423620][ C0] ? __run_timers+0xaa0/0xaa0 [ 29.423622][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 29.423623][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.423625][ C0] handle_softirqs+0x1d3/0x900 [ 29.423627][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 29.423628][ C0] ? _local_bh_enable+0xc0/0xc0 [ 29.423630][ C0] __irq_exit_rcu+0x145/0x1c0 [ 29.423632][ C0] irq_exit_rcu+0xe/0x30 [ 29.423633][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 29.423634][ C0] [ 29.423635][ C0] [ 29.423635][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 29.423643][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 29.423645][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 29.423646][ C0] Code: 48 8b 3d 94 12 06 02 e8 1f 00 00 00 48 2b 05 58 13 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 b6 17 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 29.423647][ C0] RSP: 0018:ffffffffaf407cf8 EFLAGS: 00000296 [ 29.423649][ C0] RAX: 000000000014b08f RBX: ffffffffaf42c600 RCX: ffffffffac2fa307 [ 29.423650][ C0] RDX: ffffffffaf42c600 RSI: ffffffffaf278e9d RDI: ffffffffaec8dce0 [ 29.423650][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 29.423651][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff5e80fa2 [ 29.423652][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 29.423653][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 29.423655][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 29.423657][ C0] default_idle+0x9/0x10 [ 29.423658][ C0] default_idle_call+0x6e/0xb0 [ 29.423659][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 29.423661][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 29.423663][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 29.423664][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.423666][ C0] do_idle+0xd8/0x190 [ 29.423668][ C0] cpu_startup_entry+0x53/0x70 [ 29.423669][ C0] rest_init+0x279/0x280 [ 29.423671][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 29.423673][ C0] ? rest_init+0x280/0x280 [ 29.423675][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 29.423676][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 29.423678][ C0] ? acpi_enable+0x1e4/0x330 [ 29.423680][ C0] start_kernel+0x3af/0x3b0 [ 29.423682][ C0] x86_64_start_reservations+0x24/0x30 [ 29.423684][ C0] x86_64_start_kernel+0x12b/0x130 [ 29.423686][ C0] common_startup_64+0x13e/0x148 [ 29.423689][ C0] [ 35.280496][ T847] tun0: deleting peer with id 1, reason 1 [ 35.280512][ T847] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 35.280515][ T847] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 847, name: ovpn-cli [ 35.280516][ T847] preempt_count: 202, expected: 0 [ 35.280517][ T847] RCU nest depth: 0, expected: 0 [ 35.280518][ T847] INFO: lockdep is turned off. [ 35.280519][ T847] irq event stamp: 0 [ 35.280520][ T847] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 35.280524][ T847] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 35.280531][ T847] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 35.280532][ T847] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 35.280534][ T847] Preemption disabled at: [ 35.280535][ T847] [<0000000000000000>] 0x0 [ 35.280539][ T847] CPU: 2 UID: 0 PID: 847 Comm: ovpn-cli Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 35.280543][ T847] Tainted: [W]=WARN [ 35.280544][ T847] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 35.280546][ T847] Call Trace: [ 35.280547][ T847] [ 35.280548][ T847] dump_stack_lvl+0x6f/0xa0 [ 35.280554][ T847] __might_resched.cold+0x1fe/0x2c1 [ 35.280558][ T847] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 35.280562][ T847] ? rcu_is_watching+0x16/0xd0 [ 35.280566][ T847] __kmalloc_noprof+0x443/0x760 [ 35.280570][ T847] ? __rwlock_init+0x150/0x150 [ 35.280573][ T847] ? alloc_buf.isra.0+0x4b/0x260 [ 35.280577][ T847] ? do_raw_spin_unlock+0x59/0x250 [ 35.280578][ T847] alloc_buf.isra.0+0x4b/0x260 [ 35.280580][ T847] put_chars+0x1e1/0x2f0 [ 35.280582][ T847] ? __send_to_port+0x420/0x420 [ 35.280584][ T847] ? printk_get_next_message+0x240/0x650 [ 35.280588][ T847] hvc_console_print+0x292/0x780 [ 35.280592][ T847] ? hvc_write+0x3a0/0x3a0 [ 35.280593][ T847] ? __rwlock_init+0x150/0x150 [ 35.280595][ T847] ? rcu_is_watching+0x16/0xd0 [ 35.280597][ T847] ? lock_acquire+0x13c/0x160 [ 35.280599][ T847] console_emit_next_record+0x22f/0x4c0 [ 35.280602][ T847] ? devkmsg_read+0x4b0/0x4b0 [ 35.280604][ T847] ? rcu_is_watching+0x16/0xd0 [ 35.280606][ T847] ? rcu_is_watching+0x16/0xd0 [ 35.280607][ T847] ? rcu_is_watching+0x16/0xd0 [ 35.280609][ T847] ? lock_acquire+0x13c/0x160 [ 35.280611][ T847] ? console_flush_one_record+0x111/0x710 [ 35.280613][ T847] console_flush_one_record+0x46f/0x710 [ 35.280615][ T847] ? console_emit_next_record+0x4c0/0x4c0 [ 35.280618][ T847] console_unlock+0xee/0x1f0 [ 35.280619][ T847] ? lock_acquire+0x13c/0x160 [ 35.280621][ T847] ? console_flush_one_record+0x710/0x710 [ 35.280623][ T847] ? rcu_is_watching+0x16/0xd0 [ 35.280624][ T847] ? lock_release+0x184/0x1f0 [ 35.280626][ T847] ? lock_acquire+0x60/0x160 [ 35.280627][ T847] ? __rcu_read_lock+0x30/0x70 [ 35.280629][ T847] ? __down_trylock_console_sem+0x5e/0xa0 [ 35.280631][ T847] ? vprintk_emit+0x320/0x3e0 [ 35.280633][ T847] vprintk_emit+0x37c/0x3e0 [ 35.280636][ T847] ? wake_up_klogd_work_func+0x90/0x90 [ 35.280643][ T847] ? kernel_text_address+0x149/0x170 [ 35.280645][ T847] ? __kernel_text_address+0x12/0x30 [ 35.280646][ T847] ? write_profile+0xf0/0xf0 [ 35.280648][ T847] ? unwind_get_return_address+0x67/0xd0 [ 35.280651][ T847] _printk+0xc7/0x100 [ 35.280655][ T847] ? snapshot_read.cold+0x21/0x21 [ 35.280657][ T847] ? netdev_name+0x43/0x60 [ 35.280660][ T847] ? __netdev_printk+0x17f/0x1d0 [ 35.280661][ T847] netdev_info+0xe2/0x116 [ 35.280663][ T847] ? netdev_notice+0x120/0x120 [ 35.280664][ T847] ? ovpn_nl_pre_doit+0x44/0x90 [ovpn] [ 35.280671][ T847] ? __module_address+0x36/0x330 [ 35.280674][ T847] ? lock_acquire+0x13c/0x160 [ 35.280676][ T847] ? rcu_is_watching+0x16/0xd0 [ 35.280678][ T847] ? lock_release+0x184/0x1f0 [ 35.280680][ T847] ? ovpn_peer_get_by_id+0x38a/0x7a0 [ovpn] [ 35.280683][ T847] ovpn_nl_peer_del_notify+0x115/0x503 [ovpn] [ 35.280687][ T847] ? ovpn_nl_key_new_doit.cold+0x24/0x24 [ovpn] [ 35.280690][ T847] ? lock_acquire+0x13c/0x160 [ 35.280692][ T847] ? rcu_is_watching+0x16/0xd0 [ 35.280694][ T847] ? lock_release+0x184/0x1f0 [ 35.280696][ T847] ovpn_peer_remove+0x394/0x640 [ovpn] [ 35.280699][ T847] ? do_raw_spin_lock+0x131/0x280 [ 35.280701][ T847] ? __ovpn_peer_hash_transp_addr+0x650/0x650 [ovpn] [ 35.280704][ T847] ovpn_peer_del+0x1e5/0x590 [ovpn] [ 35.280708][ T847] ? ovpn_peer_add+0x330/0x330 [ovpn] [ 35.280711][ T847] ovpn_nl_peer_del_doit+0x25c/0x6c0 [ovpn] [ 35.280714][ T847] ? trace_hardirqs_on+0x36/0x40 [ 35.280717][ T847] ? ovpn_nl_peer_get_dumpit+0xa40/0xa40 [ovpn] [ 35.280719][ T847] ? ref_tracker_alloc+0x28d/0x430 [ 35.280724][ T847] ? lock_release+0x184/0x1f0 [ 35.280726][ T847] ? ovpn_get_dev_from_attrs+0x17a/0x660 [ovpn] [ 35.280729][ T847] genl_family_rcv_msg_doit+0x206/0x300 [ 35.280734][ T847] ? genl_family_rcv_msg_attrs_parse.isra.0+0x330/0x330 [ 35.280736][ T847] ? rcu_is_watching+0x16/0xd0 [ 35.280740][ T847] ? rcu_is_watching+0x16/0xd0 [ 35.280742][ T847] ? cap_capable+0x1d7/0x3d0 [ 35.280745][ T847] ? write_profile+0xf0/0xf0 [ 35.280747][ T847] ? unwind_get_return_address+0x67/0xd0 [ 35.280748][ T847] genl_family_rcv_msg+0x3a4/0x640 [ 35.280751][ T847] ? genl_family_rcv_msg_dumpit+0x340/0x340 [ 35.280753][ T847] ? ovpn_nl_send_peer+0x1300/0x1300 [ovpn] [ 35.280755][ T847] ? ovpn_nl_peer_get_dumpit+0xa40/0xa40 [ovpn] [ 35.280758][ T847] ? ovpn_nl_pre_doit+0x90/0x90 [ovpn] [ 35.280760][ T847] ? filter_irq_stacks+0xd0/0xd0 [ 35.280762][ T847] ? stack_depot_save_flags+0x1bc/0x790 [ 35.280764][ T847] genl_rcv_msg+0xbb/0x160 [ 35.280766][ T847] netlink_rcv_skb+0x14e/0x3a0 [ 35.280768][ T847] ? genl_family_rcv_msg+0x640/0x640 [ 35.280771][ T847] ? netlink_ack+0xcf0/0xcf0 [ 35.280773][ T847] ? lock_acquire+0x13c/0x160 [ 35.280775][ T847] ? rcu_is_watching+0x16/0xd0 [ 35.280777][ T847] ? lock_release+0x184/0x1f0 [ 35.280778][ T847] genl_rcv+0x28/0x40 [ 35.280780][ T847] netlink_unicast+0x486/0x750 [ 35.280783][ T847] ? netlink_attachskb+0x810/0x810 [ 35.280784][ T847] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 35.280786][ T847] ? trace_hardirqs_on+0x36/0x40 [ 35.280787][ T847] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 35.280791][ T847] netlink_sendmsg+0x735/0xc60 [ 35.280794][ T847] ? netlink_unicast+0x750/0x750 [ 35.280796][ T847] ? rcu_is_watching+0x16/0xd0 [ 35.280797][ T847] ? lock_release+0x184/0x1f0 [ 35.280799][ T847] ____sys_sendmsg+0x415/0x880 [ 35.280803][ T847] ? copy_msghdr_from_user+0x279/0x420 [ 35.280805][ T847] ? get_timestamp.constprop.0+0x390/0x390 [ 35.280806][ T847] ? move_addr_to_kernel+0x40/0x40 [ 35.280809][ T847] ___sys_sendmsg+0x14e/0x1d0 [ 35.280811][ T847] ? copy_msghdr_from_user+0x420/0x420 [ 35.280813][ T847] ? kfree+0x22/0x590 [ 35.280818][ T847] __sys_sendmsg+0x12c/0x1d0 [ 35.280820][ T847] ? __sys_sendmsg_sock+0x20/0x20 [ 35.280823][ T847] ? rcu_is_watching+0x16/0xd0 [ 35.280825][ T847] ? rcu_is_watching+0x16/0xd0 [ 35.280827][ T847] do_syscall_64+0xff/0x530 [ 35.280830][ T847] ? exc_page_fault+0xee/0x100 [ 35.280832][ T847] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 35.280834][ T847] RIP: 0033:0x7fa62594754e [ 35.280836][ T847] 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 [ 35.280837][ T847] RSP: 002b:00007ffcb100a9e0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 35.280841][ T847] RAX: ffffffffffffffda RBX: 0000000034dfd130 RCX: 00007fa62594754e [ 35.280843][ T847] RDX: 0000000000000000 RSI: 00007ffcb100aa80 RDI: 0000000000000005 [ 35.280844][ T847] RBP: 00007ffcb100a9f0 R08: 0000000000000000 R09: 0000000000000000 [ 35.280844][ T847] R10: 0000000000000000 R11: 0000000000000202 R12: 0000000034dfd200 [ 35.280845][ T847] R13: 00007ffcb100aa80 R14: 0000000034dfe260 R15: 000000000040bda8 [ 35.280848][ T847] [ 35.323490][ T847] ovpn-cli (847) used greatest stack depth: 23528 bytes left [ 35.363481][ T851] tun1: deleting peer with id 10, reason 1 [ 37.988564][ T899] tun0: deleting peer with id 2, reason 1 [ 37.988583][ T899] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 37.988586][ T899] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 899, name: ovpn-cli [ 37.988588][ T899] preempt_count: 202, expected: 0 [ 37.988590][ T899] RCU nest depth: 0, expected: 0 [ 37.988591][ T899] INFO: lockdep is turned off. [ 37.988596][ T899] irq event stamp: 0 [ 37.988597][ T899] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 37.988602][ T899] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 37.988609][ T899] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 37.988612][ T899] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 37.988614][ T899] Preemption disabled at: [ 37.988615][ T899] [<0000000000000000>] 0x0 [ 37.988622][ T899] CPU: 2 UID: 0 PID: 899 Comm: ovpn-cli Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 37.988627][ T899] Tainted: [W]=WARN [ 37.988628][ T899] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 37.988630][ T899] Call Trace: [ 37.988632][ T899] [ 37.988634][ T899] dump_stack_lvl+0x6f/0xa0 [ 37.988647][ T899] __might_resched.cold+0x1fe/0x2c1 [ 37.988652][ T899] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 37.988657][ T899] ? rcu_is_watching+0x16/0xd0 [ 37.988662][ T899] __kmalloc_noprof+0x443/0x760 [ 37.988666][ T899] ? __rwlock_init+0x150/0x150 [ 37.988670][ T899] ? alloc_buf.isra.0+0x4b/0x260 [ 37.988675][ T899] ? do_raw_spin_unlock+0x59/0x250 [ 37.988677][ T899] alloc_buf.isra.0+0x4b/0x260 [ 37.988681][ T899] put_chars+0x1e1/0x2f0 [ 37.988684][ T899] ? __send_to_port+0x420/0x420 [ 37.988687][ T899] ? printk_get_next_message+0x240/0x650 [ 37.988692][ T899] hvc_console_print+0x292/0x780 [ 37.988697][ T899] ? hvc_write+0x3a0/0x3a0 [ 37.988700][ T899] ? __rwlock_init+0x150/0x150 [ 37.988702][ T899] ? rcu_is_watching+0x16/0xd0 [ 37.988705][ T899] ? lock_acquire+0x13c/0x160 [ 37.988709][ T899] console_emit_next_record+0x22f/0x4c0 [ 37.988712][ T899] ? devkmsg_read+0x4b0/0x4b0 [ 37.988715][ T899] ? rcu_is_watching+0x16/0xd0 [ 37.988717][ T899] ? rcu_is_watching+0x16/0xd0 [ 37.988720][ T899] ? rcu_is_watching+0x16/0xd0 [ 37.988722][ T899] ? lock_acquire+0x13c/0x160 [ 37.988725][ T899] ? console_flush_one_record+0x111/0x710 [ 37.988728][ T899] console_flush_one_record+0x46f/0x710 [ 37.988731][ T899] ? console_emit_next_record+0x4c0/0x4c0 [ 37.988735][ T899] console_unlock+0xee/0x1f0 [ 37.988738][ T899] ? lock_acquire+0x13c/0x160 [ 37.988740][ T899] ? console_flush_one_record+0x710/0x710 [ 37.988743][ T899] ? rcu_is_watching+0x16/0xd0 [ 37.988745][ T899] ? lock_release+0x184/0x1f0 [ 37.988747][ T899] ? lock_acquire+0x60/0x160 [ 37.988749][ T899] ? __rcu_read_lock+0x30/0x70 [ 37.988752][ T899] ? __down_trylock_console_sem+0x5e/0xa0 [ 37.988755][ T899] ? vprintk_emit+0x320/0x3e0 [ 37.988758][ T899] vprintk_emit+0x37c/0x3e0 [ 37.988762][ T899] ? wake_up_klogd_work_func+0x90/0x90 [ 37.988764][ T899] ? kernel_text_address+0x149/0x170 [ 37.988767][ T899] ? __kernel_text_address+0x12/0x30 [ 37.988769][ T899] ? write_profile+0xf0/0xf0 [ 37.988772][ T899] ? unwind_get_return_address+0x67/0xd0 [ 37.988777][ T899] _printk+0xc7/0x100 [ 37.988781][ T899] ? snapshot_read.cold+0x21/0x21 [ 37.988784][ T899] ? netdev_name+0x43/0x60 [ 37.988787][ T899] ? __netdev_printk+0x17f/0x1d0 [ 37.988790][ T899] netdev_info+0xe2/0x116 [ 37.988793][ T899] ? netdev_notice+0x120/0x120 [ 37.988795][ T899] ? ovpn_nl_pre_doit+0x44/0x90 [ovpn] [ 37.988802][ T899] ? __module_address+0x36/0x330 [ 37.988807][ T899] ? lock_acquire+0x13c/0x160 [ 37.988809][ T899] ? rcu_is_watching+0x16/0xd0 [ 37.988812][ T899] ? lock_release+0x184/0x1f0 [ 37.988815][ T899] ? ovpn_peer_get_by_id+0x38a/0x7a0 [ovpn] [ 37.988820][ T899] ovpn_nl_peer_del_notify+0x115/0x503 [ovpn] [ 37.988826][ T899] ? ovpn_nl_key_new_doit.cold+0x24/0x24 [ovpn] [ 37.988830][ T899] ? lock_acquire+0x13c/0x160 [ 37.988833][ T899] ? rcu_is_watching+0x16/0xd0 [ 37.988835][ T899] ? lock_release+0x184/0x1f0 [ 37.988838][ T899] ovpn_peer_remove+0x394/0x640 [ovpn] [ 37.988843][ T899] ? do_raw_spin_lock+0x131/0x280 [ 37.988846][ T899] ? __ovpn_peer_hash_transp_addr+0x650/0x650 [ovpn] [ 37.988852][ T899] ovpn_peer_del+0x1e5/0x590 [ovpn] [ 37.988857][ T899] ? ovpn_peer_add+0x330/0x330 [ovpn] [ 37.988863][ T899] ovpn_nl_peer_del_doit+0x25c/0x6c0 [ovpn] [ 37.988868][ T899] ? trace_hardirqs_on+0x36/0x40 [ 37.988872][ T899] ? ovpn_nl_peer_get_dumpit+0xa40/0xa40 [ovpn] [ 37.988876][ T899] ? ref_tracker_alloc+0x28d/0x430 [ 37.988884][ T899] ? lock_release+0x184/0x1f0 [ 37.988886][ T899] ? ovpn_get_dev_from_attrs+0x17a/0x660 [ovpn] [ 37.988892][ T899] genl_family_rcv_msg_doit+0x206/0x300 [ 37.988897][ T899] ? genl_family_rcv_msg_attrs_parse.isra.0+0x330/0x330 [ 37.988900][ T899] ? rcu_is_watching+0x16/0xd0 [ 37.988904][ T899] ? rcu_is_watching+0x16/0xd0 [ 37.988907][ T899] ? cap_capable+0x1d7/0x3d0 [ 37.988911][ T899] ? write_profile+0xf0/0xf0 [ 37.988913][ T899] ? unwind_get_return_address+0x67/0xd0 [ 37.988916][ T899] genl_family_rcv_msg+0x3a4/0x640 [ 37.988919][ T899] ? genl_family_rcv_msg_dumpit+0x340/0x340 [ 37.988922][ T899] ? ovpn_nl_send_peer+0x1300/0x1300 [ovpn] [ 37.988926][ T899] ? ovpn_nl_peer_get_dumpit+0xa40/0xa40 [ovpn] [ 37.988930][ T899] ? ovpn_nl_pre_doit+0x90/0x90 [ovpn] [ 37.988935][ T899] ? filter_irq_stacks+0xd0/0xd0 [ 37.988937][ T899] ? stack_depot_save_flags+0x1bc/0x790 [ 37.988941][ T899] genl_rcv_msg+0xbb/0x160 [ 37.988944][ T899] netlink_rcv_skb+0x14e/0x3a0 [ 37.988947][ T899] ? genl_family_rcv_msg+0x640/0x640 [ 37.988950][ T899] ? netlink_ack+0xcf0/0xcf0 [ 37.988954][ T899] ? lock_acquire+0x13c/0x160 [ 37.988957][ T899] ? rcu_is_watching+0x16/0xd0 [ 37.988959][ T899] ? lock_release+0x184/0x1f0 [ 37.988962][ T899] genl_rcv+0x28/0x40 [ 37.988965][ T899] netlink_unicast+0x486/0x750 [ 37.988968][ T899] ? netlink_attachskb+0x810/0x810 [ 37.988970][ T899] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 37.988973][ T899] ? trace_hardirqs_on+0x36/0x40 [ 37.988974][ T899] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 37.988979][ T899] netlink_sendmsg+0x735/0xc60 [ 37.988983][ T899] ? netlink_unicast+0x750/0x750 [ 37.988986][ T899] ? rcu_is_watching+0x16/0xd0 [ 37.988989][ T899] ? lock_release+0x184/0x1f0 [ 37.988991][ T899] ____sys_sendmsg+0x415/0x880 [ 37.988996][ T899] ? copy_msghdr_from_user+0x279/0x420 [ 37.988999][ T899] ? get_timestamp.constprop.0+0x390/0x390 [ 37.989001][ T899] ? move_addr_to_kernel+0x40/0x40 [ 37.989006][ T899] ___sys_sendmsg+0x14e/0x1d0 [ 37.989009][ T899] ? copy_msghdr_from_user+0x420/0x420 [ 37.989011][ T899] ? kfree+0x22/0x590 [ 37.989019][ T899] __sys_sendmsg+0x12c/0x1d0 [ 37.989022][ T899] ? __sys_sendmsg_sock+0x20/0x20 [ 37.989026][ T899] ? rcu_is_watching+0x16/0xd0 [ 37.989029][ T899] ? rcu_is_watching+0x16/0xd0 [ 37.989032][ T899] do_syscall_64+0xff/0x530 [ 37.989036][ T899] ? exc_page_fault+0xee/0x100 [ 37.989039][ T899] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 37.989041][ T899] RIP: 0033:0x7fa8a401754e [ 37.989044][ T899] 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 [ 37.989046][ T899] RSP: 002b:00007ffd7e149070 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 37.989051][ T899] RAX: ffffffffffffffda RBX: 00000000137b2130 RCX: 00007fa8a401754e [ 37.989053][ T899] RDX: 0000000000000000 RSI: 00007ffd7e149110 RDI: 0000000000000005 [ 37.989054][ T899] RBP: 00007ffd7e149080 R08: 0000000000000000 R09: 0000000000000000 [ 37.989055][ T899] R10: 0000000000000000 R11: 0000000000000202 R12: 00000000137b2200 [ 37.989057][ T899] R13: 00007ffd7e149110 R14: 00000000137b3260 R15: 000000000040bda8 [ 37.989061][ T899] [ 38.091497][ T903] tun2: deleting peer with id 11, reason 1 [ 43.903803][ T53] tun0: deleting peer with id 4, reason 2 [ 43.903825][ T53] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 43.903828][ T53] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 53, name: kworker/3:1 [ 43.903830][ T53] preempt_count: 202, expected: 0 [ 43.903831][ T53] RCU nest depth: 0, expected: 0 [ 43.903832][ T53] INFO: lockdep is turned off. [ 43.903833][ T53] irq event stamp: 21956 [ 43.903834][ T53] hardirqs last enabled at (21955): [] _raw_spin_unlock_irq+0x28/0x50 [ 43.903842][ T53] hardirqs last disabled at (21956): [] __schedule+0x1246/0x20e0 [ 43.903845][ T53] softirqs last enabled at (21948): [] handle_softirqs+0x67c/0x900 [ 43.903849][ T53] softirqs last disabled at (21941): [] __irq_exit_rcu+0x145/0x1c0 [ 43.903852][ T53] Preemption disabled at: [ 43.903852][ T53] [<0000000000000000>] 0x0 [ 43.903860][ T53] CPU: 3 UID: 0 PID: 53 Comm: kworker/3:1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 43.903864][ T53] Tainted: [W]=WARN [ 43.903865][ T53] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 43.903867][ T53] Workqueue: ovpn ovpn_peer_keepalive_work [ovpn] [ 43.903879][ T53] Call Trace: [ 43.903880][ T53] [ 43.903883][ T53] dump_stack_lvl+0x6f/0xa0 [ 43.903889][ T53] __might_resched.cold+0x1fe/0x2c1 [ 43.903893][ T53] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 43.903896][ T53] ? prb_next_reserve_seq+0x2d0/0x2d0 [ 43.903899][ T53] ? rcu_is_watching+0x16/0xd0 [ 43.903903][ T53] __kmalloc_noprof+0x443/0x760 [ 43.903908][ T53] ? __rwlock_init+0x150/0x150 [ 43.903911][ T53] ? alloc_buf.isra.0+0x4b/0x260 [ 43.903915][ T53] ? do_raw_spin_unlock+0x59/0x250 [ 43.903917][ T53] alloc_buf.isra.0+0x4b/0x260 [ 43.903919][ T53] put_chars+0x1e1/0x2f0 [ 43.903922][ T53] ? prb_final_commit+0x50/0x50 [ 43.903923][ T53] ? __send_to_port+0x420/0x420 [ 43.903925][ T53] ? printk_get_next_message+0x240/0x650 [ 43.903929][ T53] hvc_console_print+0x292/0x780 [ 43.903933][ T53] ? hvc_write+0x3a0/0x3a0 [ 43.903935][ T53] ? do_raw_spin_lock+0x131/0x280 [ 43.903937][ T53] ? vsnprintf+0x527/0x1700 [ 43.903939][ T53] ? __rwlock_init+0x150/0x150 [ 43.903940][ T53] ? rcu_is_watching+0x16/0xd0 [ 43.903943][ T53] ? lock_acquire+0x13c/0x160 [ 43.903945][ T53] console_emit_next_record+0x22f/0x4c0 [ 43.903948][ T53] ? devkmsg_read+0x4b0/0x4b0 [ 43.903950][ T53] ? rcu_is_watching+0x16/0xd0 [ 43.903952][ T53] ? rcu_is_watching+0x16/0xd0 [ 43.903954][ T53] ? rcu_is_watching+0x16/0xd0 [ 43.903956][ T53] ? lock_acquire+0x13c/0x160 [ 43.903958][ T53] ? console_flush_one_record+0x111/0x710 [ 43.903960][ T53] console_flush_one_record+0x46f/0x710 [ 43.903962][ T53] ? console_emit_next_record+0x4c0/0x4c0 [ 43.903965][ T53] console_unlock+0xee/0x1f0 [ 43.903968][ T53] ? lock_acquire+0x13c/0x160 [ 43.903969][ T53] ? console_flush_one_record+0x710/0x710 [ 43.903971][ T53] ? rcu_is_watching+0x16/0xd0 [ 43.903973][ T53] ? lock_release+0x184/0x1f0 [ 43.903975][ T53] ? lock_acquire+0x60/0x160 [ 43.903976][ T53] ? __rcu_read_lock+0x30/0x70 [ 43.903978][ T53] ? __down_trylock_console_sem+0x5e/0xa0 [ 43.903980][ T53] ? vprintk_emit+0x320/0x3e0 [ 43.903982][ T53] vprintk_emit+0x37c/0x3e0 [ 43.903985][ T53] ? wake_up_klogd_work_func+0x90/0x90 [ 43.903987][ T53] ? ret_from_fork_asm+0x11/0x20 [ 43.903990][ T53] ? rcu_is_watching+0x16/0xd0 [ 43.903992][ T53] ? stack_access_ok+0x1e0/0x1e0 [ 43.903995][ T53] _printk+0xc7/0x100 [ 43.903998][ T53] ? snapshot_read.cold+0x21/0x21 [ 43.904000][ T53] ? __add_to_free_list+0x81/0x5c0 [ 43.904002][ T53] ? do_raw_spin_lock+0x131/0x280 [ 43.904004][ T53] ? netdev_name+0x43/0x60 [ 43.904007][ T53] ? __netdev_printk+0x17f/0x1d0 [ 43.904008][ T53] netdev_info+0xe2/0x116 [ 43.904010][ T53] ? netdev_notice+0x120/0x120 [ 43.904012][ T53] ? debug_object_free+0x530/0x530 [ 43.904016][ T53] ? __rwlock_init+0x150/0x150 [ 43.904019][ T53] ? trace_irq_enable.constprop.0+0x30/0x160 [ 43.904021][ T53] ovpn_nl_peer_del_notify+0x115/0x503 [ovpn] [ 43.904026][ T53] ? ovpn_nl_key_new_doit.cold+0x24/0x24 [ovpn] [ 43.904029][ T53] ? remove_and_enqueue_same_base+0x200/0x730 [ 43.904032][ T53] ? do_raw_spin_lock+0x131/0x280 [ 43.904035][ T53] ovpn_peer_remove+0x394/0x640 [ovpn] [ 43.904038][ T53] ? do_raw_spin_lock+0x131/0x280 [ 43.904040][ T53] ? __ovpn_peer_hash_transp_addr+0x650/0x650 [ovpn] [ 43.904043][ T53] ? __rwlock_init+0x150/0x150 [ 43.904045][ T53] ? rcu_is_watching+0x16/0xd0 [ 43.904047][ T53] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 43.904048][ T53] ? trace_irq_disable.constprop.0+0x9b/0x160 [ 43.904050][ T53] ovpn_peer_keepalive_work_single+0x416/0x600 [ovpn] [ 43.904053][ T53] ? ovpn_peers_release_mp+0x490/0x490 [ovpn] [ 43.904056][ T53] ? __rwlock_init+0x150/0x150 [ 43.904058][ T53] ovpn_peer_keepalive_work+0x253/0x580 [ovpn] [ 43.904062][ T53] ? ovpn_peers_free+0xb0/0xb0 [ovpn] [ 43.904065][ T53] ? rcu_is_watching+0x16/0xd0 [ 43.904067][ T53] ? lock_acquire+0x13c/0x160 [ 43.904069][ T53] ? rcu_is_watching+0x16/0xd0 [ 43.904071][ T53] process_one_work+0xe3e/0x1560 [ 43.904075][ T53] ? __queue_delayed_work+0x470/0x470 [ 43.904078][ T53] ? find_held_lock+0x2b/0x80 [ 43.904080][ T53] ? __rwlock_init+0x150/0x150 [ 43.904082][ T53] worker_thread+0x4f1/0xd60 [ 43.904085][ T53] ? rescuer_thread+0x1340/0x1340 [ 43.904087][ T53] ? __kthread_parkme+0xbd/0x210 [ 43.904090][ T53] ? rescuer_thread+0x1340/0x1340 [ 43.904092][ T53] ? rescuer_thread+0x1340/0x1340 [ 43.904094][ T53] kthread+0x367/0x460 [ 43.904096][ T53] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 43.904097][ T53] ? kthread_affine_preferred+0x4c0/0x4c0 [ 43.904099][ T53] ret_from_fork+0x474/0x6b0 [ 43.904103][ T53] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 43.904105][ T53] ? __switch_to+0x5a3/0xe00 [ 43.904108][ T53] ? kthread_affine_preferred+0x4c0/0x4c0 [ 43.904110][ T53] ret_from_fork_asm+0x11/0x20 [ 43.904113][ T53] [ 43.923709][ T53] tun0: deleting peer with id 3, reason 2 [ 43.923909][ T53] tun0: deleting peer with id 5, reason 2 [ 43.924134][ T53] tun0: deleting peer with id 6, reason 2 [ 49.086799][ T53] tun3: deleting peer with id 12, reason 2 [ 49.086818][ T53] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 49.086821][ T53] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 53, name: kworker/3:1 [ 49.086823][ T53] preempt_count: 202, expected: 0 [ 49.086823][ T53] RCU nest depth: 0, expected: 0 [ 49.086824][ T53] INFO: lockdep is turned off. [ 49.086826][ T53] irq event stamp: 21956 [ 49.086826][ T53] hardirqs last enabled at (21955): [] _raw_spin_unlock_irq+0x28/0x50 [ 49.086834][ T53] hardirqs last disabled at (21956): [] __schedule+0x1246/0x20e0 [ 49.086837][ T53] softirqs last enabled at (21948): [] handle_softirqs+0x67c/0x900 [ 49.086841][ T53] softirqs last disabled at (21941): [] __irq_exit_rcu+0x145/0x1c0 [ 49.086843][ T53] Preemption disabled at: [ 49.086844][ T53] [<0000000000000000>] 0x0 [ 49.086850][ T53] CPU: 3 UID: 0 PID: 53 Comm: kworker/3:1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 49.086854][ T53] Tainted: [W]=WARN [ 49.086855][ T53] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 49.086857][ T53] Workqueue: ovpn ovpn_peer_keepalive_work [ovpn] [ 49.086865][ T53] Call Trace: [ 49.086867][ T53] [ 49.086869][ T53] dump_stack_lvl+0x6f/0xa0 [ 49.086874][ T53] __might_resched.cold+0x1fe/0x2c1 [ 49.086878][ T53] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 49.086881][ T53] ? prb_next_reserve_seq+0x2d0/0x2d0 [ 49.086884][ T53] ? rcu_is_watching+0x16/0xd0 [ 49.086888][ T53] __kmalloc_noprof+0x443/0x760 [ 49.086891][ T53] ? __rwlock_init+0x150/0x150 [ 49.086894][ T53] ? alloc_buf.isra.0+0x4b/0x260 [ 49.086897][ T53] ? do_raw_spin_unlock+0x59/0x250 [ 49.086899][ T53] alloc_buf.isra.0+0x4b/0x260 [ 49.086901][ T53] put_chars+0x1e1/0x2f0 [ 49.086903][ T53] ? prb_final_commit+0x50/0x50 [ 49.086905][ T53] ? __send_to_port+0x420/0x420 [ 49.086906][ T53] ? printk_get_next_message+0x240/0x650 [ 49.086909][ T53] hvc_console_print+0x292/0x780 [ 49.086913][ T53] ? hvc_write+0x3a0/0x3a0 [ 49.086915][ T53] ? do_raw_spin_lock+0x131/0x280 [ 49.086916][ T53] ? vsnprintf+0x527/0x1700 [ 49.086918][ T53] ? __rwlock_init+0x150/0x150 [ 49.086920][ T53] ? rcu_is_watching+0x16/0xd0 [ 49.086922][ T53] ? lock_acquire+0x13c/0x160 [ 49.086924][ T53] console_emit_next_record+0x22f/0x4c0 [ 49.086927][ T53] ? devkmsg_read+0x4b0/0x4b0 [ 49.086929][ T53] ? rcu_is_watching+0x16/0xd0 [ 49.086930][ T53] ? rcu_is_watching+0x16/0xd0 [ 49.086932][ T53] ? rcu_is_watching+0x16/0xd0 [ 49.086934][ T53] ? lock_acquire+0x13c/0x160 [ 49.086935][ T53] ? console_flush_one_record+0x111/0x710 [ 49.086937][ T53] console_flush_one_record+0x46f/0x710 [ 49.086939][ T53] ? console_emit_next_record+0x4c0/0x4c0 [ 49.086942][ T53] console_unlock+0xee/0x1f0 [ 49.086944][ T53] ? lock_acquire+0x13c/0x160 [ 49.086945][ T53] ? console_flush_one_record+0x710/0x710 [ 49.086947][ T53] ? rcu_is_watching+0x16/0xd0 [ 49.086949][ T53] ? lock_release+0x184/0x1f0 [ 49.086950][ T53] ? lock_acquire+0x60/0x160 [ 49.086951][ T53] ? __rcu_read_lock+0x30/0x70 [ 49.086953][ T53] ? __down_trylock_console_sem+0x5e/0xa0 [ 49.086955][ T53] ? vprintk_emit+0x320/0x3e0 [ 49.086957][ T53] vprintk_emit+0x37c/0x3e0 [ 49.086960][ T53] ? wake_up_klogd_work_func+0x90/0x90 [ 49.086961][ T53] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 49.086963][ T53] ? rcu_is_watching+0x16/0xd0 [ 49.086965][ T53] ? trace_sched_wakeup+0x9b/0x160 [ 49.086967][ T53] _printk+0xc7/0x100 [ 49.086970][ T53] ? snapshot_read.cold+0x21/0x21 [ 49.086971][ T53] ? try_to_wake_up+0x153/0x1130 [ 49.086973][ T53] ? select_fallback_rq+0x510/0x510 [ 49.086975][ T53] ? netdev_name+0x43/0x60 [ 49.086977][ T53] ? __netdev_printk+0x17f/0x1d0 [ 49.086979][ T53] netdev_info+0xe2/0x116 [ 49.086981][ T53] ? netdev_notice+0x120/0x120 [ 49.086982][ T53] ? rcu_is_watching+0x16/0xd0 [ 49.086984][ T53] ? __queue_work+0x3ab/0xd30 [ 49.086988][ T53] ? trace_irq_enable.constprop.0+0x30/0x160 [ 49.086991][ T53] ovpn_nl_peer_del_notify+0x115/0x503 [ovpn] [ 49.086995][ T53] ? ovpn_nl_key_new_doit.cold+0x24/0x24 [ovpn] [ 49.086997][ T53] ? rcu_is_watching+0x16/0xd0 [ 49.086999][ T53] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 49.087001][ T53] ovpn_peer_remove+0x394/0x640 [ovpn] [ 49.087004][ T53] ? do_raw_spin_lock+0x131/0x280 [ 49.087006][ T53] ? __ovpn_peer_hash_transp_addr+0x650/0x650 [ovpn] [ 49.087008][ T53] ? __rwlock_init+0x150/0x150 [ 49.087010][ T53] ? rcu_is_watching+0x16/0xd0 [ 49.087012][ T53] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 49.087013][ T53] ? trace_irq_disable.constprop.0+0x9b/0x160 [ 49.087014][ T53] ovpn_peer_keepalive_work_single+0x416/0x600 [ovpn] [ 49.087017][ T53] ? ovpn_peers_release_mp+0x490/0x490 [ovpn] [ 49.087020][ T53] ? __rwlock_init+0x150/0x150 [ 49.087022][ T53] ovpn_peer_keepalive_work+0x389/0x580 [ovpn] [ 49.087025][ T53] ? ovpn_peers_free+0xb0/0xb0 [ovpn] [ 49.087028][ T53] ? rcu_is_watching+0x16/0xd0 [ 49.087029][ T53] ? lock_acquire+0x13c/0x160 [ 49.087031][ T53] ? rcu_is_watching+0x16/0xd0 [ 49.087033][ T53] ? process_one_work+0xdfc/0x1560 [ 49.087035][ T53] process_one_work+0xe3e/0x1560 [ 49.087037][ T53] ? __queue_delayed_work+0x470/0x470 [ 49.087039][ T53] ? find_held_lock+0x2b/0x80 [ 49.087041][ T53] ? __rwlock_init+0x150/0x150 [ 49.087043][ T53] worker_thread+0x4f1/0xd60 [ 49.087046][ T53] ? rescuer_thread+0x1340/0x1340 [ 49.087047][ T53] ? __kthread_parkme+0xbd/0x210 [ 49.087050][ T53] ? rescuer_thread+0x1340/0x1340 [ 49.087052][ T53] ? rescuer_thread+0x1340/0x1340 [ 49.087053][ T53] kthread+0x367/0x460 [ 49.087055][ T53] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 49.087056][ T53] ? kthread_affine_preferred+0x4c0/0x4c0 [ 49.087058][ T53] ret_from_fork+0x474/0x6b0 [ 49.087060][ T53] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 49.087062][ T53] ? __switch_to+0x5a3/0xe00 [ 49.087064][ T53] ? kthread_affine_preferred+0x4c0/0x4c0 [ 49.087066][ T53] ret_from_fork_asm+0x11/0x20 [ 49.087070][ T53] [ 49.150842][ T10] tun5: deleting peer with id 14, reason 2 [ 49.151771][ T53] tun4: deleting peer with id 13, reason 2 [ 49.214772][ T10] tun6: deleting peer with id 15, reason 2