[ 12.399619][ C0] clocksource: Watchdog remote CPU 3 read timed out [ 12.399787][ C0] [ 12.399789][ C0] ======================================================== [ 12.399790][ C0] WARNING: possible irq lock inversion dependency detected [ 12.399792][ C0] 7.2.0-virtme #1 Tainted: G W [ 12.399793][ C0] -------------------------------------------------------- [ 12.399794][ C0] swapper/0/0 just changed the state of lock: [ 12.399795][ C0] ffffffffb1e99660 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.399808][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 12.399810][ C0] (fs_reclaim){+.+.}-{0:0} [ 12.399811][ C0] [ 12.399811][ C0] [ 12.399811][ C0] and interrupts could create inverse lock ordering between them. [ 12.399811][ C0] [ 12.399812][ C0] [ 12.399812][ C0] other info that might help us debug this: [ 12.399813][ C0] Possible interrupt unsafe locking scenario: [ 12.399813][ C0] [ 12.399813][ C0] CPU0 CPU1 [ 12.399814][ C0] ---- ---- [ 12.399814][ C0] lock(fs_reclaim); [ 12.399815][ C0] local_irq_disable(); [ 12.399816][ C0] lock(console_owner); [ 12.399817][ C0] lock(fs_reclaim); [ 12.399818][ C0] [ 12.399818][ C0] lock(console_owner); [ 12.399819][ C0] [ 12.399819][ C0] *** DEADLOCK *** [ 12.399819][ C0] [ 12.399820][ C0] locks held by swapper/0/0: 4, last CPU#0: [ 12.399821][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 12.399826][ C0] #1: ffffffffb1fde8b8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 12.399830][ C0] #2: ffffffffb1f79cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.399833][ C0] #3: ffffffffb1f79d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.399836][ C0] [ 12.399836][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 12.399841][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 12.399843][ C0] HARDIRQ-ON-W at: [ 12.399845][ C0] __lock_acquire+0x388/0xc20 [ 12.399847][ C0] lock_acquire.part.0+0xd4/0x280 [ 12.399849][ C0] fs_reclaim_acquire+0xd5/0x120 [ 12.399852][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 12.399854][ C0] kthread_create_worker_on_node+0xea/0x210 [ 12.399857][ C0] workqueue_init+0x2a/0x680 [ 12.399860][ C0] kernel_init_freeable+0x2fe/0x630 [ 12.399863][ C0] kernel_init+0x21/0x150 [ 12.399866][ C0] ret_from_fork+0x474/0x6b0 [ 12.399869][ C0] ret_from_fork_asm+0x11/0x20 [ 12.399872][ C0] SOFTIRQ-ON-W at: [ 12.399873][ C0] __lock_acquire+0x388/0xc20 [ 12.399874][ C0] lock_acquire.part.0+0xd4/0x280 [ 12.399876][ C0] fs_reclaim_acquire+0xd5/0x120 [ 12.399877][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 12.399878][ C0] kthread_create_worker_on_node+0xea/0x210 [ 12.399879][ C0] workqueue_init+0x2a/0x680 [ 12.399880][ C0] kernel_init_freeable+0x2fe/0x630 [ 12.399882][ C0] kernel_init+0x21/0x150 [ 12.399883][ C0] ret_from_fork+0x474/0x6b0 [ 12.399884][ C0] ret_from_fork_asm+0x11/0x20 [ 12.399885][ C0] INITIAL USE at: [ 12.399886][ C0] __lock_acquire+0x388/0xc20 [ 12.399887][ C0] lock_acquire.part.0+0xd4/0x280 [ 12.399889][ C0] fs_reclaim_acquire+0xd5/0x120 [ 12.399890][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 12.399891][ C0] kthread_create_worker_on_node+0xea/0x210 [ 12.399892][ C0] workqueue_init+0x2a/0x680 [ 12.399893][ C0] kernel_init_freeable+0x2fe/0x630 [ 12.399895][ C0] kernel_init+0x21/0x150 [ 12.399896][ C0] ret_from_fork+0x474/0x6b0 [ 12.399897][ C0] ret_from_fork_asm+0x11/0x20 [ 12.399898][ C0] } [ 12.399899][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 12.399902][ C0] ... acquired at: [ 12.399903][ C0] __lock_acquire+0x518/0xc20 [ 12.399905][ C0] lock_acquire.part.0+0xd4/0x280 [ 12.399906][ C0] fs_reclaim_acquire+0xd5/0x120 [ 12.399907][ C0] __kmalloc_noprof+0xd3/0x760 [ 12.399908][ C0] alloc_buf.isra.0+0x4b/0x260 [ 12.399911][ C0] put_chars+0x1e1/0x2f0 [ 12.399913][ C0] hvc_console_print+0x292/0x780 [ 12.399915][ C0] console_emit_next_record+0x22f/0x4c0 [ 12.399917][ C0] console_flush_one_record+0x46f/0x710 [ 12.399918][ C0] console_unlock+0xee/0x1f0 [ 12.399920][ C0] vprintk_emit+0x37c/0x3e0 [ 12.399921][ C0] dev_vprintk_emit+0x27f/0x2c0 [ 12.399925][ C0] dev_printk_emit+0xb9/0xee [ 12.399926][ C0] _dev_info+0xe2/0x116 [ 12.399928][ C0] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 12.399930][ C0] cmos_do_probe+0x73b/0x98a [ 12.399932][ C0] platform_probe+0xfe/0x1f0 [ 12.399934][ C0] call_driver_probe+0x61/0x1c0 [ 12.399937][ C0] really_probe+0x199/0x760 [ 12.399938][ C0] __driver_probe_device+0x24f/0x440 [ 12.399940][ C0] driver_probe_device+0x4a/0xf0 [ 12.399941][ C0] __driver_attach+0x1b8/0x540 [ 12.399943][ C0] bus_for_each_dev+0x130/0x1e0 [ 12.399944][ C0] bus_add_driver+0x2c8/0x530 [ 12.399945][ C0] driver_register+0x1a3/0x390 [ 12.399947][ C0] __platform_driver_probe+0x13f/0x270 [ 12.399948][ C0] cmos_init+0x31/0x40 [ 12.399951][ C0] do_one_initcall+0x124/0x4f0 [ 12.399952][ C0] kernel_init_freeable+0x596/0x630 [ 12.399954][ C0] kernel_init+0x21/0x150 [ 12.399955][ C0] ret_from_fork+0x474/0x6b0 [ 12.399956][ C0] ret_from_fork_asm+0x11/0x20 [ 12.399957][ C0] [ 12.399958][ C0] -> (console_owner){..-.}-{0:0} { [ 12.399959][ C0] IN-SOFTIRQ-W at: [ 12.399960][ C0] __lock_acquire+0x388/0xc20 [ 12.399961][ C0] lock_acquire.part.0+0xd4/0x280 [ 12.399962][ C0] console_lock_spinning_enable+0x5c/0x60 [ 12.399964][ C0] console_emit_next_record+0x1d1/0x4c0 [ 12.399966][ C0] console_flush_one_record+0x46f/0x710 [ 12.399967][ C0] console_unlock+0xee/0x1f0 [ 12.399969][ C0] vprintk_emit+0x37c/0x3e0 [ 12.399970][ C0] _printk+0xc7/0x100 [ 12.399973][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 12.399975][ C0] call_timer_fn+0x160/0x4d0 [ 12.399977][ C0] __run_timers+0x68f/0xaa0 [ 12.399979][ C0] run_timer_softirq+0xf0/0x160 [ 12.399980][ C0] handle_softirqs+0x1d3/0x900 [ 12.399983][ C0] __irq_exit_rcu+0x145/0x1c0 [ 12.399984][ C0] irq_exit_rcu+0xe/0x30 [ 12.399985][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 12.399987][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 12.399989][ C0] pv_native_safe_halt+0xf/0x10 [ 12.399990][ C0] default_idle+0x9/0x10 [ 12.399991][ C0] default_idle_call+0x6e/0xb0 [ 12.399992][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 12.399995][ C0] do_idle+0xd8/0x190 [ 12.399996][ C0] cpu_startup_entry+0x53/0x70 [ 12.399997][ C0] rest_init+0x279/0x280 [ 12.399998][ C0] start_kernel+0x3af/0x3b0 [ 12.400000][ C0] x86_64_start_reservations+0x24/0x30 [ 12.400002][ C0] x86_64_start_kernel+0x12b/0x130 [ 12.400003][ C0] common_startup_64+0x13e/0x148 [ 12.400006][ C0] INITIAL USE at: [ 12.400006][ C0] } [ 12.400007][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 12.400010][ C0] ... acquired at: [ 12.400011][ C0] mark_lock+0x1d7/0xa00 [ 12.400012][ C0] mark_usage+0x42/0x170 [ 12.400013][ C0] __lock_acquire+0x388/0xc20 [ 12.400014][ C0] lock_acquire.part.0+0xd4/0x280 [ 12.400016][ C0] console_lock_spinning_enable+0x5c/0x60 [ 12.400017][ C0] console_emit_next_record+0x1d1/0x4c0 [ 12.400019][ C0] console_flush_one_record+0x46f/0x710 [ 12.400020][ C0] console_unlock+0xee/0x1f0 [ 12.400022][ C0] vprintk_emit+0x37c/0x3e0 [ 12.400023][ C0] _printk+0xc7/0x100 [ 12.400024][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 12.400025][ C0] call_timer_fn+0x160/0x4d0 [ 12.400027][ C0] __run_timers+0x68f/0xaa0 [ 12.400028][ C0] run_timer_softirq+0xf0/0x160 [ 12.400030][ C0] handle_softirqs+0x1d3/0x900 [ 12.400031][ C0] __irq_exit_rcu+0x145/0x1c0 [ 12.400032][ C0] irq_exit_rcu+0xe/0x30 [ 12.400033][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 12.400034][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 12.400035][ C0] pv_native_safe_halt+0xf/0x10 [ 12.400036][ C0] default_idle+0x9/0x10 [ 12.400037][ C0] default_idle_call+0x6e/0xb0 [ 12.400038][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 12.400040][ C0] do_idle+0xd8/0x190 [ 12.400041][ C0] cpu_startup_entry+0x53/0x70 [ 12.400042][ C0] rest_init+0x279/0x280 [ 12.400043][ C0] start_kernel+0x3af/0x3b0 [ 12.400044][ C0] x86_64_start_reservations+0x24/0x30 [ 12.400046][ C0] x86_64_start_kernel+0x12b/0x130 [ 12.400047][ C0] common_startup_64+0x13e/0x148 [ 12.400048][ C0] [ 12.400048][ C0] [ 12.400048][ C0] stack backtrace: [ 12.400051][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.400054][ C0] Tainted: [W]=WARN [ 12.400055][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.400057][ C0] Call Trace: [ 12.400058][ C0] [ 12.400059][ C0] dump_stack_lvl+0x6f/0xa0 [ 12.400063][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 12.400065][ C0] mark_lock_irq+0x989/0x9c0 [ 12.400067][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 12.400070][ C0] mark_lock+0x1d7/0xa00 [ 12.400072][ C0] mark_usage+0x42/0x170 [ 12.400073][ C0] __lock_acquire+0x388/0xc20 [ 12.400075][ C0] lock_acquire.part.0+0xd4/0x280 [ 12.400077][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 12.400079][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.400082][ C0] ? lock_acquire+0x13c/0x160 [ 12.400084][ C0] console_lock_spinning_enable+0x5c/0x60 [ 12.400086][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 12.400087][ C0] console_emit_next_record+0x1d1/0x4c0 [ 12.400089][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 12.400091][ C0] ? console_flush_one_record+0x106/0x710 [ 12.400093][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.400094][ C0] ? lock_acquire+0x13c/0x160 [ 12.400096][ C0] console_flush_one_record+0x46f/0x710 [ 12.400098][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 12.400100][ C0] ? __lock_acquire+0x518/0xc20 [ 12.400102][ C0] console_unlock+0xee/0x1f0 [ 12.400104][ C0] ? console_flush_one_record+0x710/0x710 [ 12.400106][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.400107][ C0] ? lock_acquire+0x60/0x160 [ 12.400109][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.400111][ C0] ? vprintk_emit+0x320/0x3e0 [ 12.400112][ C0] vprintk_emit+0x37c/0x3e0 [ 12.400114][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 12.400117][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 12.400118][ C0] _printk+0xc7/0x100 [ 12.400120][ C0] ? snapshot_read.cold+0x21/0x21 [ 12.400122][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 12.400123][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 12.400126][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 12.400127][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 12.400129][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 12.400130][ C0] call_timer_fn+0x160/0x4d0 [ 12.400132][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 12.400134][ C0] ? debug_object_active_state+0x430/0x430 [ 12.400138][ C0] ? find_held_lock+0x2b/0x80 [ 12.400139][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 12.400141][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.400143][ C0] __run_timers+0x68f/0xaa0 [ 12.400145][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 12.400147][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 12.400149][ C0] ? __lock_acquire+0x518/0xc20 [ 12.400151][ C0] ? __rwlock_init+0x150/0x150 [ 12.400154][ C0] run_timer_softirq+0xf0/0x160 [ 12.400155][ C0] ? __run_timers+0xaa0/0xaa0 [ 12.400157][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 12.400159][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.400161][ C0] handle_softirqs+0x1d3/0x900 [ 12.400163][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 12.400164][ C0] ? _local_bh_enable+0xc0/0xc0 [ 12.400166][ C0] __irq_exit_rcu+0x145/0x1c0 [ 12.400168][ C0] irq_exit_rcu+0xe/0x30 [ 12.400169][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 12.400170][ C0] [ 12.400171][ C0] [ 12.400171][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 12.400173][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 12.400174][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 12.400176][ C0] Code: 48 8b 3d 94 02 06 02 e8 1f 00 00 00 48 2b 05 58 03 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 a6 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 [ 12.400178][ C0] RSP: 0018:ffffffffb1c07cf8 EFLAGS: 00000296 [ 12.400180][ C0] RAX: 000000000006e453 RBX: ffffffffb1c2c600 RCX: ffffffffaeafa307 [ 12.400182][ C0] RDX: ffffffffb1c2c600 RSI: ffffffffb1a78e7e RDI: ffffffffb148dce0 [ 12.400182][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 12.400183][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff6380fa2 [ 12.400184][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 12.400185][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 12.400188][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 12.400189][ C0] default_idle+0x9/0x10 [ 12.400190][ C0] default_idle_call+0x6e/0xb0 [ 12.400191][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 12.400193][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 12.400198][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 12.400200][ C0] ? check_preemption_disabled+0xf/0xe0 [ 12.400201][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.400203][ C0] do_idle+0xd8/0x190 [ 12.400205][ C0] cpu_startup_entry+0x53/0x70 [ 12.400206][ C0] rest_init+0x279/0x280 [ 12.400208][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 12.400210][ C0] ? rest_init+0x280/0x280 [ 12.400211][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 12.400214][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 12.400217][ C0] ? acpi_enable+0x1e4/0x330 [ 12.400220][ C0] start_kernel+0x3af/0x3b0 [ 12.400222][ C0] x86_64_start_reservations+0x24/0x30 [ 12.400224][ C0] x86_64_start_kernel+0x12b/0x130 [ 12.400225][ C0] common_startup_64+0x13e/0x148 [ 12.400228][ C0] [ 12.400232][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.400234][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 12.400235][ C0] preempt_count: 103, expected: 0 [ 12.400236][ C0] RCU nest depth: 0, expected: 0 [ 12.400236][ C0] INFO: lockdep is turned off. [ 12.400237][ C0] irq event stamp: 451683 [ 12.400238][ C0] hardirqs last enabled at (451682): [] __down_trylock_console_sem+0x86/0xa0 [ 12.400240][ C0] hardirqs last disabled at (451683): [] console_emit_next_record+0x3d4/0x4c0 [ 12.400242][ C0] softirqs last enabled at (451656): [] handle_softirqs+0x67c/0x900 [ 12.400243][ C0] softirqs last disabled at (451669): [] __irq_exit_rcu+0x145/0x1c0 [ 12.400245][ C0] Preemption disabled at: [ 12.400245][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 12.400248][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.400250][ C0] Tainted: [W]=WARN [ 12.400250][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.400251][ C0] Call Trace: [ 12.400252][ C0] [ 12.400252][ C0] dump_stack_lvl+0x6f/0xa0 [ 12.400254][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 12.400256][ C0] __might_resched.cold+0x1fe/0x2c1 [ 12.400258][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.400261][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.400263][ C0] __kmalloc_noprof+0x443/0x760 [ 12.400265][ C0] ? __rwlock_init+0x150/0x150 [ 12.400267][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 12.400268][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 12.400270][ C0] alloc_buf.isra.0+0x4b/0x260 [ 12.400272][ C0] put_chars+0x1e1/0x2f0 [ 12.400274][ C0] ? __send_to_port+0x420/0x420 [ 12.400276][ C0] ? mark_lock+0x1d7/0xa00 [ 12.400278][ C0] hvc_console_print+0x292/0x780 [ 12.400280][ C0] ? hvc_write+0x3a0/0x3a0 [ 12.400282][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.400283][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.400286][ C0] console_emit_next_record+0x22f/0x4c0 [ 12.400288][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 12.400289][ C0] ? console_flush_one_record+0x106/0x710 [ 12.400291][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.400293][ C0] ? lock_acquire+0x13c/0x160 [ 12.400295][ C0] console_flush_one_record+0x46f/0x710 [ 12.400297][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 12.400298][ C0] ? __lock_acquire+0x518/0xc20 [ 12.400301][ C0] console_unlock+0xee/0x1f0 [ 12.400302][ C0] ? console_flush_one_record+0x710/0x710 [ 12.400304][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.400306][ C0] ? lock_acquire+0x60/0x160 [ 12.400307][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.400309][ C0] ? vprintk_emit+0x320/0x3e0 [ 12.400311][ C0] vprintk_emit+0x37c/0x3e0 [ 12.400313][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 12.400315][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 12.400317][ C0] _printk+0xc7/0x100 [ 12.400318][ C0] ? snapshot_read.cold+0x21/0x21 [ 12.400320][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 12.400322][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 12.400324][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 12.400325][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 12.400327][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 12.400328][ C0] call_timer_fn+0x160/0x4d0 [ 12.400330][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 12.400332][ C0] ? debug_object_active_state+0x430/0x430 [ 12.400334][ C0] ? find_held_lock+0x2b/0x80 [ 12.400335][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 12.400337][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.400339][ C0] __run_timers+0x68f/0xaa0 [ 12.400341][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 12.400343][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 12.400344][ C0] ? __lock_acquire+0x518/0xc20 [ 12.400347][ C0] ? __rwlock_init+0x150/0x150 [ 12.400349][ C0] run_timer_softirq+0xf0/0x160 [ 12.400351][ C0] ? __run_timers+0xaa0/0xaa0 [ 12.400353][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 12.400354][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.400356][ C0] handle_softirqs+0x1d3/0x900 [ 12.400357][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 12.400359][ C0] ? _local_bh_enable+0xc0/0xc0 [ 12.400361][ C0] __irq_exit_rcu+0x145/0x1c0 [ 12.400362][ C0] irq_exit_rcu+0xe/0x30 [ 12.400364][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 12.400365][ C0] [ 12.400365][ C0] [ 12.400366][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 12.400368][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 12.400369][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 12.400370][ C0] Code: 48 8b 3d 94 02 06 02 e8 1f 00 00 00 48 2b 05 58 03 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 a6 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 [ 12.400371][ C0] RSP: 0018:ffffffffb1c07cf8 EFLAGS: 00000296 [ 12.400372][ C0] RAX: 000000000006e453 RBX: ffffffffb1c2c600 RCX: ffffffffaeafa307 [ 12.400373][ C0] RDX: ffffffffb1c2c600 RSI: ffffffffb1a78e7e RDI: ffffffffb148dce0 [ 12.400374][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 12.400374][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff6380fa2 [ 12.400375][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 12.400377][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 12.400379][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 12.400380][ C0] default_idle+0x9/0x10 [ 12.400381][ C0] default_idle_call+0x6e/0xb0 [ 12.400382][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 12.400384][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 12.400385][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 12.400387][ C0] ? check_preemption_disabled+0xf/0xe0 [ 12.400388][ C0] ? rcu_is_watching+0x16/0xd0 [ 12.400390][ C0] do_idle+0xd8/0x190 [ 12.400391][ C0] cpu_startup_entry+0x53/0x70 [ 12.400393][ C0] rest_init+0x279/0x280 [ 12.400394][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 12.400396][ C0] ? rest_init+0x280/0x280 [ 12.400398][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 12.400400][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 12.400401][ C0] ? acpi_enable+0x1e4/0x330 [ 12.400403][ C0] start_kernel+0x3af/0x3b0 [ 12.400405][ C0] x86_64_start_reservations+0x24/0x30 [ 12.400407][ C0] x86_64_start_kernel+0x12b/0x130 [ 12.400409][ C0] common_startup_64+0x13e/0x148 [ 12.400412][ C0] [ 26.384133][ T759] tun0: deleting peer with id 1, reason 1 [ 26.384150][ T759] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 26.384153][ T759] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 759, name: ovpn-cli [ 26.384155][ T759] preempt_count: 202, expected: 0 [ 26.384155][ T759] RCU nest depth: 0, expected: 0 [ 26.384156][ T759] INFO: lockdep is turned off. [ 26.384158][ T759] irq event stamp: 0 [ 26.384158][ T759] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 26.384162][ T759] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 26.384168][ T759] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 26.384170][ T759] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 26.384171][ T759] Preemption disabled at: [ 26.384172][ T759] [<0000000000000000>] 0x0 [ 26.384177][ T759] CPU: 3 UID: 0 PID: 759 Comm: ovpn-cli Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.384180][ T759] Tainted: [W]=WARN [ 26.384181][ T759] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.384183][ T759] Call Trace: [ 26.384184][ T759] [ 26.384185][ T759] dump_stack_lvl+0x6f/0xa0 [ 26.384191][ T759] __might_resched.cold+0x1fe/0x2c1 [ 26.384195][ T759] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 26.384198][ T759] ? rcu_is_watching+0x16/0xd0 [ 26.384202][ T759] __kmalloc_noprof+0x443/0x760 [ 26.384205][ T759] ? __rwlock_init+0x150/0x150 [ 26.384208][ T759] ? alloc_buf.isra.0+0x4b/0x260 [ 26.384212][ T759] ? do_raw_spin_unlock+0x59/0x250 [ 26.384213][ T759] alloc_buf.isra.0+0x4b/0x260 [ 26.384216][ T759] put_chars+0x1e1/0x2f0 [ 26.384218][ T759] ? __send_to_port+0x420/0x420 [ 26.384225][ T759] ? printk_get_next_message+0x240/0x650 [ 26.384228][ T759] hvc_console_print+0x292/0x780 [ 26.384232][ T759] ? hvc_write+0x3a0/0x3a0 [ 26.384233][ T759] ? __rwlock_init+0x150/0x150 [ 26.384235][ T759] ? rcu_is_watching+0x16/0xd0 [ 26.384237][ T759] ? lock_acquire+0x13c/0x160 [ 26.384239][ T759] console_emit_next_record+0x22f/0x4c0 [ 26.384241][ T759] ? devkmsg_read+0x4b0/0x4b0 [ 26.384243][ T759] ? rcu_is_watching+0x16/0xd0 [ 26.384245][ T759] ? rcu_is_watching+0x16/0xd0 [ 26.384246][ T759] ? rcu_is_watching+0x16/0xd0 [ 26.384248][ T759] ? lock_acquire+0x13c/0x160 [ 26.384250][ T759] ? console_flush_one_record+0x111/0x710 [ 26.384251][ T759] console_flush_one_record+0x46f/0x710 [ 26.384254][ T759] ? console_emit_next_record+0x4c0/0x4c0 [ 26.384256][ T759] console_unlock+0xee/0x1f0 [ 26.384258][ T759] ? lock_acquire+0x13c/0x160 [ 26.384259][ T759] ? console_flush_one_record+0x710/0x710 [ 26.384261][ T759] ? rcu_is_watching+0x16/0xd0 [ 26.384263][ T759] ? lock_release+0x184/0x1f0 [ 26.384264][ T759] ? lock_acquire+0x60/0x160 [ 26.384265][ T759] ? __rcu_read_lock+0x30/0x70 [ 26.384267][ T759] ? __down_trylock_console_sem+0x5e/0xa0 [ 26.384269][ T759] ? vprintk_emit+0x320/0x3e0 [ 26.384271][ T759] vprintk_emit+0x37c/0x3e0 [ 26.384273][ T759] ? wake_up_klogd_work_func+0x90/0x90 [ 26.384275][ T759] ? kernel_text_address+0x149/0x170 [ 26.384277][ T759] ? __kernel_text_address+0x12/0x30 [ 26.384278][ T759] ? write_profile+0xf0/0xf0 [ 26.384280][ T759] ? unwind_get_return_address+0x67/0xd0 [ 26.384283][ T759] _printk+0xc7/0x100 [ 26.384286][ T759] ? snapshot_read.cold+0x21/0x21 [ 26.384288][ T759] ? netdev_name+0x43/0x60 [ 26.384291][ T759] ? __netdev_printk+0x17f/0x1d0 [ 26.384292][ T759] netdev_info+0xe2/0x116 [ 26.384294][ T759] ? netdev_notice+0x120/0x120 [ 26.384295][ T759] ? ovpn_nl_pre_doit+0x44/0x90 [ovpn] [ 26.384301][ T759] ? __module_address+0x36/0x330 [ 26.384304][ T759] ? lock_acquire+0x13c/0x160 [ 26.384305][ T759] ? rcu_is_watching+0x16/0xd0 [ 26.384307][ T759] ? lock_release+0x184/0x1f0 [ 26.384309][ T759] ? ovpn_peer_get_by_id+0x38a/0x7a0 [ovpn] [ 26.384312][ T759] ovpn_nl_peer_del_notify+0x115/0x503 [ovpn] [ 26.384316][ T759] ? ovpn_nl_key_new_doit.cold+0x24/0x24 [ovpn] [ 26.384318][ T759] ? lock_acquire+0x13c/0x160 [ 26.384320][ T759] ? rcu_is_watching+0x16/0xd0 [ 26.384322][ T759] ? lock_release+0x184/0x1f0 [ 26.384323][ T759] ovpn_peer_remove+0x394/0x640 [ovpn] [ 26.384326][ T759] ? do_raw_spin_lock+0x131/0x280 [ 26.384328][ T759] ? __ovpn_peer_hash_transp_addr+0x650/0x650 [ovpn] [ 26.384331][ T759] ovpn_peer_del+0x1e5/0x590 [ovpn] [ 26.384334][ T759] ? ovpn_peer_add+0x330/0x330 [ovpn] [ 26.384337][ T759] ovpn_nl_peer_del_doit+0x25c/0x6c0 [ovpn] [ 26.384340][ T759] ? trace_hardirqs_on+0x36/0x40 [ 26.384342][ T759] ? ovpn_nl_peer_get_dumpit+0xa40/0xa40 [ovpn] [ 26.384345][ T759] ? ref_tracker_alloc+0x28d/0x430 [ 26.384350][ T759] ? lock_release+0x184/0x1f0 [ 26.384351][ T759] ? ovpn_get_dev_from_attrs+0x17a/0x660 [ovpn] [ 26.384355][ T759] genl_family_rcv_msg_doit+0x206/0x300 [ 26.384359][ T759] ? genl_family_rcv_msg_attrs_parse.isra.0+0x330/0x330 [ 26.384361][ T759] ? rcu_is_watching+0x16/0xd0 [ 26.384363][ T759] ? rcu_is_watching+0x16/0xd0 [ 26.384365][ T759] ? cap_capable+0x1d7/0x3d0 [ 26.384368][ T759] ? write_profile+0xf0/0xf0 [ 26.384369][ T759] ? unwind_get_return_address+0x67/0xd0 [ 26.384371][ T759] genl_family_rcv_msg+0x3a4/0x640 [ 26.384373][ T759] ? genl_family_rcv_msg_dumpit+0x340/0x340 [ 26.384375][ T759] ? ovpn_nl_send_peer+0x1300/0x1300 [ovpn] [ 26.384378][ T759] ? ovpn_nl_peer_get_dumpit+0xa40/0xa40 [ovpn] [ 26.384380][ T759] ? ovpn_nl_pre_doit+0x90/0x90 [ovpn] [ 26.384382][ T759] ? filter_irq_stacks+0xd0/0xd0 [ 26.384384][ T759] ? stack_depot_save_flags+0x1bc/0x790 [ 26.384386][ T759] genl_rcv_msg+0xbb/0x160 [ 26.384388][ T759] netlink_rcv_skb+0x14e/0x3a0 [ 26.384390][ T759] ? genl_family_rcv_msg+0x640/0x640 [ 26.384392][ T759] ? netlink_ack+0xcf0/0xcf0 [ 26.384395][ T759] ? lock_acquire+0x13c/0x160 [ 26.384396][ T759] ? rcu_is_watching+0x16/0xd0 [ 26.384398][ T759] ? lock_release+0x184/0x1f0 [ 26.384399][ T759] genl_rcv+0x28/0x40 [ 26.384401][ T759] netlink_unicast+0x486/0x750 [ 26.384403][ T759] ? netlink_attachskb+0x810/0x810 [ 26.384405][ T759] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.384406][ T759] ? trace_hardirqs_on+0x36/0x40 [ 26.384408][ T759] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 26.384411][ T759] netlink_sendmsg+0x735/0xc60 [ 26.384413][ T759] ? netlink_unicast+0x750/0x750 [ 26.384415][ T759] ? rcu_is_watching+0x16/0xd0 [ 26.384417][ T759] ? lock_release+0x184/0x1f0 [ 26.384419][ T759] ____sys_sendmsg+0x415/0x880 [ 26.384422][ T759] ? copy_msghdr_from_user+0x279/0x420 [ 26.384423][ T759] ? get_timestamp.constprop.0+0x390/0x390 [ 26.384425][ T759] ? move_addr_to_kernel+0x40/0x40 [ 26.384428][ T759] ___sys_sendmsg+0x14e/0x1d0 [ 26.384430][ T759] ? copy_msghdr_from_user+0x420/0x420 [ 26.384431][ T759] ? kfree+0x22/0x590 [ 26.384436][ T759] __sys_sendmsg+0x12c/0x1d0 [ 26.384438][ T759] ? __sys_sendmsg_sock+0x20/0x20 [ 26.384441][ T759] ? rcu_is_watching+0x16/0xd0 [ 26.384442][ T759] ? rcu_is_watching+0x16/0xd0 [ 26.384444][ T759] do_syscall_64+0xff/0x530 [ 26.384447][ T759] ? exc_page_fault+0xee/0x100 [ 26.384449][ T759] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.384451][ T759] RIP: 0033:0x7f8883c0054e [ 26.384453][ T759] 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 [ 26.384455][ T759] RSP: 002b:00007ffc98b468a0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 26.384459][ T759] RAX: ffffffffffffffda RBX: 000000002ef20130 RCX: 00007f8883c0054e [ 26.384460][ T759] RDX: 0000000000000000 RSI: 00007ffc98b46940 RDI: 0000000000000005 [ 26.384461][ T759] RBP: 00007ffc98b468b0 R08: 0000000000000000 R09: 0000000000000000 [ 26.384462][ T759] R10: 0000000000000000 R11: 0000000000000202 R12: 000000002ef20200 [ 26.384463][ T759] R13: 00007ffc98b46940 R14: 000000002ef21260 R15: 000000000040bda8 [ 26.384465][ T759] [ 26.424548][ T759] ovpn-cli (759) used greatest stack depth: 23528 bytes left [ 26.468015][ T763] tun1: deleting peer with id 1, reason 1 [ 29.076059][ T811] tun0: deleting peer with id 2, reason 1 [ 29.076077][ T811] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 29.076080][ T811] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 811, name: ovpn-cli [ 29.076081][ T811] preempt_count: 202, expected: 0 [ 29.076082][ T811] RCU nest depth: 0, expected: 0 [ 29.076083][ T811] INFO: lockdep is turned off. [ 29.076084][ T811] irq event stamp: 0 [ 29.076085][ T811] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 29.076090][ T811] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 29.076095][ T811] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 29.076097][ T811] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 29.076099][ T811] Preemption disabled at: [ 29.076099][ T811] [<0000000000000000>] 0x0 [ 29.076104][ T811] CPU: 2 UID: 0 PID: 811 Comm: ovpn-cli Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 29.076108][ T811] Tainted: [W]=WARN [ 29.076109][ T811] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 29.076110][ T811] Call Trace: [ 29.076112][ T811] [ 29.076113][ T811] dump_stack_lvl+0x6f/0xa0 [ 29.076118][ T811] __might_resched.cold+0x1fe/0x2c1 [ 29.076122][ T811] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 29.076126][ T811] ? rcu_is_watching+0x16/0xd0 [ 29.076130][ T811] __kmalloc_noprof+0x443/0x760 [ 29.076133][ T811] ? __rwlock_init+0x150/0x150 [ 29.076136][ T811] ? alloc_buf.isra.0+0x4b/0x260 [ 29.076140][ T811] ? do_raw_spin_unlock+0x59/0x250 [ 29.076141][ T811] alloc_buf.isra.0+0x4b/0x260 [ 29.076143][ T811] put_chars+0x1e1/0x2f0 [ 29.076146][ T811] ? __send_to_port+0x420/0x420 [ 29.076147][ T811] ? printk_get_next_message+0x240/0x650 [ 29.076151][ T811] hvc_console_print+0x292/0x780 [ 29.076154][ T811] ? hvc_write+0x3a0/0x3a0 [ 29.076156][ T811] ? __rwlock_init+0x150/0x150 [ 29.076158][ T811] ? rcu_is_watching+0x16/0xd0 [ 29.076159][ T811] ? lock_acquire+0x13c/0x160 [ 29.076162][ T811] console_emit_next_record+0x22f/0x4c0 [ 29.076164][ T811] ? devkmsg_read+0x4b0/0x4b0 [ 29.076166][ T811] ? rcu_is_watching+0x16/0xd0 [ 29.076168][ T811] ? rcu_is_watching+0x16/0xd0 [ 29.076169][ T811] ? rcu_is_watching+0x16/0xd0 [ 29.076171][ T811] ? lock_acquire+0x13c/0x160 [ 29.076173][ T811] ? console_flush_one_record+0x111/0x710 [ 29.076174][ T811] console_flush_one_record+0x46f/0x710 [ 29.076177][ T811] ? console_emit_next_record+0x4c0/0x4c0 [ 29.076179][ T811] console_unlock+0xee/0x1f0 [ 29.076181][ T811] ? lock_acquire+0x13c/0x160 [ 29.076183][ T811] ? console_flush_one_record+0x710/0x710 [ 29.076184][ T811] ? rcu_is_watching+0x16/0xd0 [ 29.076186][ T811] ? lock_release+0x184/0x1f0 [ 29.076187][ T811] ? lock_acquire+0x60/0x160 [ 29.076188][ T811] ? __rcu_read_lock+0x30/0x70 [ 29.076191][ T811] ? __down_trylock_console_sem+0x5e/0xa0 [ 29.076192][ T811] ? vprintk_emit+0x320/0x3e0 [ 29.076194][ T811] vprintk_emit+0x37c/0x3e0 [ 29.076197][ T811] ? wake_up_klogd_work_func+0x90/0x90 [ 29.076198][ T811] ? kernel_text_address+0x149/0x170 [ 29.076201][ T811] ? __kernel_text_address+0x12/0x30 [ 29.076202][ T811] ? write_profile+0xf0/0xf0 [ 29.076204][ T811] ? unwind_get_return_address+0x67/0xd0 [ 29.076207][ T811] _printk+0xc7/0x100 [ 29.076210][ T811] ? snapshot_read.cold+0x21/0x21 [ 29.076212][ T811] ? netdev_name+0x43/0x60 [ 29.076215][ T811] ? __netdev_printk+0x17f/0x1d0 [ 29.076217][ T811] netdev_info+0xe2/0x116 [ 29.076218][ T811] ? netdev_notice+0x120/0x120 [ 29.076219][ T811] ? ovpn_nl_pre_doit+0x44/0x90 [ovpn] [ 29.076231][ T811] ? __module_address+0x36/0x330 [ 29.076234][ T811] ? lock_acquire+0x13c/0x160 [ 29.076235][ T811] ? rcu_is_watching+0x16/0xd0 [ 29.076237][ T811] ? lock_release+0x184/0x1f0 [ 29.076239][ T811] ? ovpn_peer_get_by_id+0x38a/0x7a0 [ovpn] [ 29.076242][ T811] ovpn_nl_peer_del_notify+0x115/0x503 [ovpn] [ 29.076246][ T811] ? ovpn_nl_key_new_doit.cold+0x24/0x24 [ovpn] [ 29.076248][ T811] ? lock_acquire+0x13c/0x160 [ 29.076250][ T811] ? rcu_is_watching+0x16/0xd0 [ 29.076252][ T811] ? lock_release+0x184/0x1f0 [ 29.076253][ T811] ovpn_peer_remove+0x394/0x640 [ovpn] [ 29.076256][ T811] ? do_raw_spin_lock+0x131/0x280 [ 29.076258][ T811] ? __ovpn_peer_hash_transp_addr+0x650/0x650 [ovpn] [ 29.076261][ T811] ovpn_peer_del+0x1e5/0x590 [ovpn] [ 29.076264][ T811] ? ovpn_peer_add+0x330/0x330 [ovpn] [ 29.076267][ T811] ovpn_nl_peer_del_doit+0x25c/0x6c0 [ovpn] [ 29.076270][ T811] ? trace_hardirqs_on+0x36/0x40 [ 29.076273][ T811] ? ovpn_nl_peer_get_dumpit+0xa40/0xa40 [ovpn] [ 29.076275][ T811] ? ref_tracker_alloc+0x28d/0x430 [ 29.076280][ T811] ? lock_release+0x184/0x1f0 [ 29.076282][ T811] ? ovpn_get_dev_from_attrs+0x17a/0x660 [ovpn] [ 29.076285][ T811] genl_family_rcv_msg_doit+0x206/0x300 [ 29.076289][ T811] ? genl_family_rcv_msg_attrs_parse.isra.0+0x330/0x330 [ 29.076291][ T811] ? rcu_is_watching+0x16/0xd0 [ 29.076294][ T811] ? rcu_is_watching+0x16/0xd0 [ 29.076295][ T811] ? cap_capable+0x1d7/0x3d0 [ 29.076299][ T811] ? write_profile+0xf0/0xf0 [ 29.076300][ T811] ? unwind_get_return_address+0x67/0xd0 [ 29.076301][ T811] genl_family_rcv_msg+0x3a4/0x640 [ 29.076304][ T811] ? genl_family_rcv_msg_dumpit+0x340/0x340 [ 29.076306][ T811] ? ovpn_nl_send_peer+0x1300/0x1300 [ovpn] [ 29.076308][ T811] ? ovpn_nl_peer_get_dumpit+0xa40/0xa40 [ovpn] [ 29.076310][ T811] ? ovpn_nl_pre_doit+0x90/0x90 [ovpn] [ 29.076313][ T811] ? filter_irq_stacks+0xd0/0xd0 [ 29.076314][ T811] ? stack_depot_save_flags+0x1bc/0x790 [ 29.076316][ T811] genl_rcv_msg+0xbb/0x160 [ 29.076319][ T811] netlink_rcv_skb+0x14e/0x3a0 [ 29.076320][ T811] ? genl_family_rcv_msg+0x640/0x640 [ 29.076322][ T811] ? netlink_ack+0xcf0/0xcf0 [ 29.076325][ T811] ? lock_acquire+0x13c/0x160 [ 29.076327][ T811] ? rcu_is_watching+0x16/0xd0 [ 29.076328][ T811] ? lock_release+0x184/0x1f0 [ 29.076330][ T811] genl_rcv+0x28/0x40 [ 29.076332][ T811] netlink_unicast+0x486/0x750 [ 29.076334][ T811] ? netlink_attachskb+0x810/0x810 [ 29.076336][ T811] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 29.076337][ T811] ? trace_hardirqs_on+0x36/0x40 [ 29.076338][ T811] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 29.076342][ T811] netlink_sendmsg+0x735/0xc60 [ 29.076344][ T811] ? netlink_unicast+0x750/0x750 [ 29.076346][ T811] ? rcu_is_watching+0x16/0xd0 [ 29.076348][ T811] ? lock_release+0x184/0x1f0 [ 29.076350][ T811] ____sys_sendmsg+0x415/0x880 [ 29.076353][ T811] ? copy_msghdr_from_user+0x279/0x420 [ 29.076355][ T811] ? get_timestamp.constprop.0+0x390/0x390 [ 29.076356][ T811] ? move_addr_to_kernel+0x40/0x40 [ 29.076359][ T811] ___sys_sendmsg+0x14e/0x1d0 [ 29.076361][ T811] ? copy_msghdr_from_user+0x420/0x420 [ 29.076362][ T811] ? kfree+0x22/0x590 [ 29.076367][ T811] __sys_sendmsg+0x12c/0x1d0 [ 29.076370][ T811] ? __sys_sendmsg_sock+0x20/0x20 [ 29.076372][ T811] ? rcu_is_watching+0x16/0xd0 [ 29.076374][ T811] ? rcu_is_watching+0x16/0xd0 [ 29.076376][ T811] do_syscall_64+0xff/0x530 [ 29.076379][ T811] ? exc_page_fault+0xee/0x100 [ 29.076381][ T811] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.076383][ T811] RIP: 0033:0x7f0760aed54e [ 29.076385][ T811] 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 [ 29.076387][ T811] RSP: 002b:00007ffcf83f88d0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 29.076390][ T811] RAX: ffffffffffffffda RBX: 0000000022391130 RCX: 00007f0760aed54e [ 29.076392][ T811] RDX: 0000000000000000 RSI: 00007ffcf83f8970 RDI: 0000000000000005 [ 29.076393][ T811] RBP: 00007ffcf83f88e0 R08: 0000000000000000 R09: 0000000000000000 [ 29.076393][ T811] R10: 0000000000000000 R11: 0000000000000202 R12: 0000000022391200 [ 29.076394][ T811] R13: 00007ffcf83f8970 R14: 0000000022392260 R15: 000000000040bda8 [ 29.076396][ T811] [ 29.165452][ T815] tun2: deleting peer with id 2, reason 1 [ 34.791527][ T10] tun0: deleting peer with id 4, reason 2 [ 34.791548][ T10] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 34.791551][ T10] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 10, name: kworker/0:1 [ 34.791553][ T10] preempt_count: 202, expected: 0 [ 34.791554][ T10] RCU nest depth: 0, expected: 0 [ 34.791554][ T10] INFO: lockdep is turned off. [ 34.791556][ T10] irq event stamp: 8482 [ 34.791556][ T10] hardirqs last enabled at (8481): [] _raw_spin_unlock_irq+0x28/0x50 [ 34.791565][ T10] hardirqs last disabled at (8482): [] __schedule+0x1246/0x20e0 [ 34.791568][ T10] softirqs last enabled at (8442): [] handle_softirqs+0x67c/0x900 [ 34.791572][ T10] softirqs last disabled at (8433): [] __irq_exit_rcu+0x145/0x1c0 [ 34.791574][ T10] Preemption disabled at: [ 34.791575][ T10] [<0000000000000000>] 0x0 [ 34.791581][ T10] CPU: 0 UID: 0 PID: 10 Comm: kworker/0:1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 34.791585][ T10] Tainted: [W]=WARN [ 34.791586][ T10] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 34.791588][ T10] Workqueue: ovpn ovpn_peer_keepalive_work [ovpn] [ 34.791598][ T10] Call Trace: [ 34.791600][ T10] [ 34.791601][ T10] dump_stack_lvl+0x6f/0xa0 [ 34.791607][ T10] __might_resched.cold+0x1fe/0x2c1 [ 34.791611][ T10] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 34.791614][ T10] ? prb_next_reserve_seq+0x2d0/0x2d0 [ 34.791616][ T10] ? rcu_is_watching+0x16/0xd0 [ 34.791620][ T10] __kmalloc_noprof+0x443/0x760 [ 34.791624][ T10] ? __rwlock_init+0x150/0x150 [ 34.791626][ T10] ? alloc_buf.isra.0+0x4b/0x260 [ 34.791630][ T10] ? do_raw_spin_unlock+0x59/0x250 [ 34.791632][ T10] alloc_buf.isra.0+0x4b/0x260 [ 34.791634][ T10] put_chars+0x1e1/0x2f0 [ 34.791636][ T10] ? prb_final_commit+0x50/0x50 [ 34.791637][ T10] ? __send_to_port+0x420/0x420 [ 34.791639][ T10] ? printk_get_next_message+0x240/0x650 [ 34.791642][ T10] hvc_console_print+0x292/0x780 [ 34.791646][ T10] ? hvc_write+0x3a0/0x3a0 [ 34.791647][ T10] ? do_raw_spin_lock+0x131/0x280 [ 34.791649][ T10] ? vsnprintf+0x527/0x1700 [ 34.791651][ T10] ? __rwlock_init+0x150/0x150 [ 34.791652][ T10] ? rcu_is_watching+0x16/0xd0 [ 34.791654][ T10] ? lock_acquire+0x13c/0x160 [ 34.791656][ T10] console_emit_next_record+0x22f/0x4c0 [ 34.791659][ T10] ? devkmsg_read+0x4b0/0x4b0 [ 34.791661][ T10] ? rcu_is_watching+0x16/0xd0 [ 34.791663][ T10] ? rcu_is_watching+0x16/0xd0 [ 34.791664][ T10] ? rcu_is_watching+0x16/0xd0 [ 34.791666][ T10] ? lock_acquire+0x13c/0x160 [ 34.791668][ T10] ? console_flush_one_record+0x111/0x710 [ 34.791669][ T10] console_flush_one_record+0x46f/0x710 [ 34.791671][ T10] ? console_emit_next_record+0x4c0/0x4c0 [ 34.791674][ T10] console_unlock+0xee/0x1f0 [ 34.791676][ T10] ? lock_acquire+0x13c/0x160 [ 34.791677][ T10] ? console_flush_one_record+0x710/0x710 [ 34.791679][ T10] ? rcu_is_watching+0x16/0xd0 [ 34.791680][ T10] ? lock_release+0x184/0x1f0 [ 34.791682][ T10] ? lock_acquire+0x60/0x160 [ 34.791683][ T10] ? __rcu_read_lock+0x30/0x70 [ 34.791685][ T10] ? __down_trylock_console_sem+0x5e/0xa0 [ 34.791687][ T10] ? vprintk_emit+0x320/0x3e0 [ 34.791689][ T10] vprintk_emit+0x37c/0x3e0 [ 34.791691][ T10] ? wake_up_klogd_work_func+0x90/0x90 [ 34.791693][ T10] ? ret_from_fork_asm+0x11/0x20 [ 34.791696][ T10] ? queued_spin_lock_slowpath+0x103/0xa60 [ 34.791697][ T10] ? rcu_is_watching+0x16/0xd0 [ 34.791699][ T10] ? stack_access_ok+0x1e0/0x1e0 [ 34.791701][ T10] _printk+0xc7/0x100 [ 34.791704][ T10] ? snapshot_read.cold+0x21/0x21 [ 34.791706][ T10] ? __add_to_free_list+0x81/0x5c0 [ 34.791708][ T10] ? do_raw_spin_lock+0x131/0x280 [ 34.791710][ T10] ? netdev_name+0x43/0x60 [ 34.791712][ T10] ? __netdev_printk+0x17f/0x1d0 [ 34.791714][ T10] netdev_info+0xe2/0x116 [ 34.791716][ T10] ? netdev_notice+0x120/0x120 [ 34.791718][ T10] ? debug_object_free+0x530/0x530 [ 34.791721][ T10] ? __rwlock_init+0x150/0x150 [ 34.791723][ T10] ? trace_irq_enable.constprop.0+0x30/0x160 [ 34.791726][ T10] ovpn_nl_peer_del_notify+0x115/0x503 [ovpn] [ 34.791731][ T10] ? ovpn_nl_key_new_doit.cold+0x24/0x24 [ovpn] [ 34.791733][ T10] ? remove_and_enqueue_same_base+0x200/0x730 [ 34.791736][ T10] ? do_raw_spin_lock+0x131/0x280 [ 34.791738][ T10] ovpn_peer_remove+0x394/0x640 [ovpn] [ 34.791741][ T10] ? do_raw_spin_lock+0x131/0x280 [ 34.791743][ T10] ? __ovpn_peer_hash_transp_addr+0x650/0x650 [ovpn] [ 34.791746][ T10] ? __rwlock_init+0x150/0x150 [ 34.791747][ T10] ? rcu_is_watching+0x16/0xd0 [ 34.791749][ T10] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 34.791750][ T10] ? trace_irq_disable.constprop.0+0x9b/0x160 [ 34.791752][ T10] ovpn_peer_keepalive_work_single+0x416/0x600 [ovpn] [ 34.791755][ T10] ? ovpn_peers_release_mp+0x490/0x490 [ovpn] [ 34.791757][ T10] ? __rwlock_init+0x150/0x150 [ 34.791759][ T10] ovpn_peer_keepalive_work+0x253/0x580 [ovpn] [ 34.791763][ T10] ? ovpn_peers_free+0xb0/0xb0 [ovpn] [ 34.791765][ T10] ? rcu_is_watching+0x16/0xd0 [ 34.791767][ T10] ? lock_acquire+0x13c/0x160 [ 34.791768][ T10] ? rcu_is_watching+0x16/0xd0 [ 34.791770][ T10] process_one_work+0xe3e/0x1560 [ 34.791774][ T10] ? __queue_delayed_work+0x470/0x470 [ 34.791776][ T10] ? find_held_lock+0x2b/0x80 [ 34.791778][ T10] ? __rwlock_init+0x150/0x150 [ 34.791780][ T10] worker_thread+0x4f1/0xd60 [ 34.791783][ T10] ? rescuer_thread+0x1340/0x1340 [ 34.791784][ T10] ? __kthread_parkme+0xbd/0x210 [ 34.791787][ T10] ? rescuer_thread+0x1340/0x1340 [ 34.791788][ T10] ? rescuer_thread+0x1340/0x1340 [ 34.791790][ T10] kthread+0x367/0x460 [ 34.791792][ T10] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 34.791793][ T10] ? kthread_affine_preferred+0x4c0/0x4c0 [ 34.791795][ T10] ret_from_fork+0x474/0x6b0 [ 34.791798][ T10] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 34.791799][ T10] ? __switch_to+0x5a3/0xe00 [ 34.791802][ T10] ? kthread_affine_preferred+0x4c0/0x4c0 [ 34.791803][ T10] ret_from_fork_asm+0x11/0x20 [ 34.791807][ T10] [ 34.806192][ T10] tun0: deleting peer with id 3, reason 2 [ 34.806366][ T10] tun0: deleting peer with id 5, reason 2 [ 34.806582][ T10] tun0: deleting peer with id 6, reason 2 [ 39.912576][ T34] tun3: deleting peer with id 3, reason 2 [ 39.912597][ T34] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 39.912600][ T34] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 34, name: kworker/3:0 [ 39.912602][ T34] preempt_count: 202, expected: 0 [ 39.912603][ T34] RCU nest depth: 0, expected: 0 [ 39.912604][ T34] INFO: lockdep is turned off. [ 39.912605][ T34] irq event stamp: 3540 [ 39.912606][ T34] hardirqs last enabled at (3539): [] _raw_spin_unlock_irq+0x28/0x50 [ 39.912614][ T34] hardirqs last disabled at (3540): [] __schedule+0x1246/0x20e0 [ 39.912618][ T34] softirqs last enabled at (3202): [] handle_softirqs+0x67c/0x900 [ 39.912622][ T34] softirqs last disabled at (2107): [] __irq_exit_rcu+0x145/0x1c0 [ 39.912624][ T34] Preemption disabled at: [ 39.912625][ T34] [<0000000000000000>] 0x0 [ 39.912633][ T34] CPU: 3 UID: 0 PID: 34 Comm: kworker/3:0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 39.912637][ T34] Tainted: [W]=WARN [ 39.912637][ T34] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 39.912640][ T34] Workqueue: ovpn ovpn_peer_keepalive_work [ovpn] [ 39.912648][ T34] Call Trace: [ 39.912650][ T34] [ 39.912652][ T34] dump_stack_lvl+0x6f/0xa0 [ 39.912657][ T34] __might_resched.cold+0x1fe/0x2c1 [ 39.912661][ T34] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 39.912665][ T34] ? prb_next_reserve_seq+0x2d0/0x2d0 [ 39.912667][ T34] ? rcu_is_watching+0x16/0xd0 [ 39.912671][ T34] __kmalloc_noprof+0x443/0x760 [ 39.912675][ T34] ? __rwlock_init+0x150/0x150 [ 39.912678][ T34] ? alloc_buf.isra.0+0x4b/0x260 [ 39.912681][ T34] ? do_raw_spin_unlock+0x59/0x250 [ 39.912683][ T34] alloc_buf.isra.0+0x4b/0x260 [ 39.912685][ T34] put_chars+0x1e1/0x2f0 [ 39.912687][ T34] ? prb_final_commit+0x50/0x50 [ 39.912689][ T34] ? __send_to_port+0x420/0x420 [ 39.912690][ T34] ? printk_get_next_message+0x240/0x650 [ 39.912693][ T34] hvc_console_print+0x292/0x780 [ 39.912697][ T34] ? hvc_write+0x3a0/0x3a0 [ 39.912699][ T34] ? do_raw_spin_lock+0x131/0x280 [ 39.912700][ T34] ? vsnprintf+0x527/0x1700 [ 39.912702][ T34] ? __rwlock_init+0x150/0x150 [ 39.912704][ T34] ? rcu_is_watching+0x16/0xd0 [ 39.912705][ T34] ? lock_acquire+0x13c/0x160 [ 39.912708][ T34] console_emit_next_record+0x22f/0x4c0 [ 39.912710][ T34] ? devkmsg_read+0x4b0/0x4b0 [ 39.912712][ T34] ? rcu_is_watching+0x16/0xd0 [ 39.912714][ T34] ? rcu_is_watching+0x16/0xd0 [ 39.912715][ T34] ? rcu_is_watching+0x16/0xd0 [ 39.912717][ T34] ? lock_acquire+0x13c/0x160 [ 39.912719][ T34] ? console_flush_one_record+0x111/0x710 [ 39.912720][ T34] console_flush_one_record+0x46f/0x710 [ 39.912723][ T34] ? console_emit_next_record+0x4c0/0x4c0 [ 39.912725][ T34] console_unlock+0xee/0x1f0 [ 39.912727][ T34] ? lock_acquire+0x13c/0x160 [ 39.912728][ T34] ? console_flush_one_record+0x710/0x710 [ 39.912730][ T34] ? rcu_is_watching+0x16/0xd0 [ 39.912732][ T34] ? lock_release+0x184/0x1f0 [ 39.912733][ T34] ? lock_acquire+0x60/0x160 [ 39.912734][ T34] ? __rcu_read_lock+0x30/0x70 [ 39.912736][ T34] ? __down_trylock_console_sem+0x5e/0xa0 [ 39.912738][ T34] ? vprintk_emit+0x320/0x3e0 [ 39.912740][ T34] vprintk_emit+0x37c/0x3e0 [ 39.912742][ T34] ? wake_up_klogd_work_func+0x90/0x90 [ 39.912744][ T34] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 39.912746][ T34] ? rcu_is_watching+0x16/0xd0 [ 39.912747][ T34] ? trace_sched_wakeup+0x9b/0x160 [ 39.912750][ T34] _printk+0xc7/0x100 [ 39.912753][ T34] ? snapshot_read.cold+0x21/0x21 [ 39.912754][ T34] ? try_to_wake_up+0x153/0x1130 [ 39.912756][ T34] ? select_fallback_rq+0x510/0x510 [ 39.912758][ T34] ? netdev_name+0x43/0x60 [ 39.912760][ T34] ? __netdev_printk+0x17f/0x1d0 [ 39.912762][ T34] netdev_info+0xe2/0x116 [ 39.912763][ T34] ? netdev_notice+0x120/0x120 [ 39.912765][ T34] ? rcu_is_watching+0x16/0xd0 [ 39.912767][ T34] ? __queue_work+0x3ab/0xd30 [ 39.912771][ T34] ? trace_irq_enable.constprop.0+0x30/0x160 [ 39.912774][ T34] ovpn_nl_peer_del_notify+0x115/0x503 [ovpn] [ 39.912777][ T34] ? ovpn_nl_key_new_doit.cold+0x24/0x24 [ovpn] [ 39.912780][ T34] ? rcu_is_watching+0x16/0xd0 [ 39.912781][ T34] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 39.912783][ T34] ovpn_peer_remove+0x394/0x640 [ovpn] [ 39.912787][ T34] ? do_raw_spin_lock+0x131/0x280 [ 39.912788][ T34] ? __ovpn_peer_hash_transp_addr+0x650/0x650 [ovpn] [ 39.912791][ T34] ? __rwlock_init+0x150/0x150 [ 39.912792][ T34] ? rcu_is_watching+0x16/0xd0 [ 39.912794][ T34] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 39.912795][ T34] ? trace_irq_disable.constprop.0+0x9b/0x160 [ 39.912797][ T34] ovpn_peer_keepalive_work_single+0x416/0x600 [ovpn] [ 39.912799][ T34] ? ovpn_peers_release_mp+0x490/0x490 [ovpn] [ 39.912802][ T34] ? __rwlock_init+0x150/0x150 [ 39.912804][ T34] ovpn_peer_keepalive_work+0x389/0x580 [ovpn] [ 39.912807][ T34] ? ovpn_peers_free+0xb0/0xb0 [ovpn] [ 39.912810][ T34] ? rcu_is_watching+0x16/0xd0 [ 39.912811][ T34] ? lock_acquire+0x13c/0x160 [ 39.912813][ T34] ? rcu_is_watching+0x16/0xd0 [ 39.912815][ T34] ? process_one_work+0xdfc/0x1560 [ 39.912817][ T34] process_one_work+0xe3e/0x1560 [ 39.912819][ T34] ? __queue_delayed_work+0x470/0x470 [ 39.912821][ T34] ? find_held_lock+0x2b/0x80 [ 39.912823][ T34] ? __rwlock_init+0x150/0x150 [ 39.912825][ T34] worker_thread+0x4f1/0xd60 [ 39.912828][ T34] ? rescuer_thread+0x1340/0x1340 [ 39.912830][ T34] kthread+0x367/0x460 [ 39.912832][ T34] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 39.912833][ T34] ? kthread_affine_preferred+0x4c0/0x4c0 [ 39.912835][ T34] ret_from_fork+0x474/0x6b0 [ 39.912838][ T34] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 39.912839][ T34] ? __switch_to+0x5a3/0xe00 [ 39.912842][ T34] ? kthread_affine_preferred+0x4c0/0x4c0 [ 39.912844][ T34] ret_from_fork_asm+0x11/0x20 [ 39.912848][ T34] [ 39.975527][ T10] tun4: deleting peer with id 4, reason 2 [ 40.040534][ T34] tun5: deleting peer with id 5, reason 2 [ 40.103522][ T10] tun6: deleting peer with id 6, reason 2