[ 17.283059][ T454] ip (454) used greatest stack depth: 23920 bytes left [ 17.283078][ T454] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 17.283080][ T454] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 454, name: ip [ 17.283082][ T454] preempt_count: 2, expected: 0 [ 17.283083][ T454] RCU nest depth: 0, expected: 0 [ 17.283084][ T454] locks held by ip/454: 5, last CPU#2: [ 17.283086][ T454] #0: ffffffff9ba167b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 17.283097][ T454] #1: ffffffff9bb7ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 17.283102][ T454] #2: ffffffff9bb7de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 17.283106][ T454] #3: ffffffff9ba9d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 17.283110][ T454] #4: ffffffff9ba9d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 17.283114][ T454] irq event stamp: 27244 [ 17.283115][ T454] hardirqs last enabled at (27243): [] __down_trylock_console_sem+0x86/0xa0 [ 17.283118][ T454] hardirqs last disabled at (27244): [] console_emit_next_record+0x3d4/0x4c0 [ 17.283120][ T454] softirqs last enabled at (25966): [] handle_softirqs+0x67c/0x900 [ 17.283122][ T454] softirqs last disabled at (24611): [] __irq_exit_rcu+0x145/0x1c0 [ 17.283125][ T454] Preemption disabled at: [ 17.283125][ T454] [<0000000000000000>] 0x0 [ 17.283133][ T454] CPU: 2 UID: 0 PID: 454 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 17.283136][ T454] Tainted: [W]=WARN [ 17.283137][ T454] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 17.283139][ T454] Call Trace: [ 17.283140][ T454] [ 17.283142][ T454] dump_stack_lvl+0x6f/0xa0 [ 17.283148][ T454] __might_resched.cold+0x1fe/0x2c1 [ 17.283153][ T454] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 17.283157][ T454] ? __kmalloc_noprof+0xdb/0x760 [ 17.283161][ T454] __kmalloc_noprof+0x443/0x760 [ 17.283163][ T454] ? alloc_buf.isra.0+0x4b/0x260 [ 17.283170][ T454] ? do_raw_spin_unlock+0x59/0x250 [ 17.283173][ T454] alloc_buf.isra.0+0x4b/0x260 [ 17.283176][ T454] put_chars+0x1e1/0x2f0 [ 17.283179][ T454] ? __send_to_port+0x420/0x420 [ 17.283180][ T454] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 17.283184][ T454] ? validate_chain+0x38b/0xc20 [ 17.283189][ T454] hvc_console_print+0x292/0x780 [ 17.283197][ T454] ? hvc_write+0x3a0/0x3a0 [ 17.283200][ T454] ? rcu_is_watching+0x16/0xd0 [ 17.283202][ T454] ? lock_acquire+0x13c/0x160 [ 17.283206][ T454] console_emit_next_record+0x22f/0x4c0 [ 17.283210][ T454] ? devkmsg_read+0x4b0/0x4b0 [ 17.283212][ T454] ? console_flush_one_record+0x106/0x710 [ 17.283215][ T454] ? rcu_is_watching+0x16/0xd0 [ 17.283217][ T454] ? lock_acquire+0x13c/0x160 [ 17.283225][ T454] console_flush_one_record+0x46f/0x710 [ 17.283230][ T454] ? console_emit_next_record+0x4c0/0x4c0 [ 17.283232][ T454] ? __lock_acquire+0x518/0xc20 [ 17.283237][ T454] console_unlock+0xee/0x1f0 [ 17.283240][ T454] ? console_flush_one_record+0x710/0x710 [ 17.283242][ T454] ? rcu_is_watching+0x16/0xd0 [ 17.283244][ T454] ? lock_acquire+0xe0/0x160 [ 17.283247][ T454] ? __down_trylock_console_sem+0x5e/0xa0 [ 17.283249][ T454] ? vprintk_emit+0x320/0x3e0 [ 17.283252][ T454] vprintk_emit+0x37c/0x3e0 [ 17.283255][ T454] ? wake_up_klogd_work_func+0x90/0x90 [ 17.283258][ T454] ? __lock_acquire+0x518/0xc20 [ 17.283262][ T454] _printk+0xc7/0x100 [ 17.283265][ T454] ? snapshot_read.cold+0x21/0x21 [ 17.283271][ T454] ? do_raw_spin_lock+0x131/0x280 [ 17.283273][ T454] ? __rwlock_init+0x150/0x150 [ 17.283277][ T454] ? do_raw_spin_lock+0x131/0x280 [ 17.283280][ T454] do_exit.cold+0x82/0x9c [ 17.283284][ T454] ? exit_notify+0x890/0x890 [ 17.283286][ T454] ? __lock_release.isra.0+0x69/0x1a0 [ 17.283289][ T454] ? rcu_is_watching+0x16/0xd0 [ 17.283292][ T454] do_group_exit+0xb8/0x370 [ 17.283295][ T454] __x64_sys_exit_group+0x3c/0x50 [ 17.283297][ T454] x64_sys_call+0x1567/0x1570 [ 17.283299][ T454] do_syscall_64+0xff/0x530 [ 17.283303][ T454] ? exc_page_fault+0xee/0x100 [ 17.283306][ T454] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 17.283308][ T454] RIP: 0033:0x7f5ad1eb11b8 [ 17.283310][ T454] Code: Unable to access opcode bytes at 0x7f5ad1eb118e. [ 17.283312][ T454] RSP: 002b:00007ffd6fad1a98 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 17.283314][ T454] RAX: ffffffffffffffda RBX: 00007f5ad1fe1f88 RCX: 00007f5ad1eb11b8 [ 17.283315][ T454] RDX: 00007f5ad1bfbfc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 17.283316][ T454] RBP: 00007ffd6fad1af0 R08: 0000000000000000 R09: 0000000000000050 [ 17.283317][ T454] R10: 00007ffd6fad18b0 R11: 0000000000000246 R12: 0000000000000001 [ 17.283318][ T454] R13: 0000000000000000 R14: 00007f5ad1fe0680 R15: 00007f5ad1fe1fa0 [ 17.283325][ T454] [ 20.465726][ T568] ip (568) used greatest stack depth: 23616 bytes left [ 20.465746][ T568] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 20.465748][ T568] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 568, name: ip [ 20.465750][ T568] preempt_count: 2, expected: 0 [ 20.465751][ T568] RCU nest depth: 0, expected: 0 [ 20.465752][ T568] locks held by ip/568: 5, last CPU#3: [ 20.465754][ T568] #0: ffffffff9ba167b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 20.465766][ T568] #1: ffffffff9bb7ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 20.465771][ T568] #2: ffffffff9bb7de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 20.465775][ T568] #3: ffffffff9ba9d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 20.465779][ T568] #4: ffffffff9ba9d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 20.465784][ T568] irq event stamp: 20036 [ 20.465785][ T568] hardirqs last enabled at (20035): [] __down_trylock_console_sem+0x86/0xa0 [ 20.465788][ T568] hardirqs last disabled at (20036): [] console_emit_next_record+0x3d4/0x4c0 [ 20.465790][ T568] softirqs last enabled at (18770): [] handle_softirqs+0x67c/0x900 [ 20.465793][ T568] softirqs last disabled at (17821): [] __irq_exit_rcu+0x145/0x1c0 [ 20.465795][ T568] Preemption disabled at: [ 20.465796][ T568] [<0000000000000000>] 0x0 [ 20.465803][ T568] CPU: 3 UID: 0 PID: 568 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 20.465806][ T568] Tainted: [W]=WARN [ 20.465807][ T568] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 20.465809][ T568] Call Trace: [ 20.465810][ T568] [ 20.465812][ T568] dump_stack_lvl+0x6f/0xa0 [ 20.465818][ T568] __might_resched.cold+0x1fe/0x2c1 [ 20.465823][ T568] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 20.465828][ T568] ? __kmalloc_noprof+0xdb/0x760 [ 20.465832][ T568] __kmalloc_noprof+0x443/0x760 [ 20.465834][ T568] ? alloc_buf.isra.0+0x4b/0x260 [ 20.465842][ T568] ? do_raw_spin_unlock+0x59/0x250 [ 20.465845][ T568] alloc_buf.isra.0+0x4b/0x260 [ 20.465849][ T568] put_chars+0x1e1/0x2f0 [ 20.465852][ T568] ? __send_to_port+0x420/0x420 [ 20.465853][ T568] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 20.465857][ T568] ? validate_chain+0x38b/0xc20 [ 20.465864][ T568] hvc_console_print+0x292/0x780 [ 20.465872][ T568] ? hvc_write+0x3a0/0x3a0 [ 20.465874][ T568] ? rcu_is_watching+0x16/0xd0 [ 20.465876][ T568] ? lock_acquire+0x13c/0x160 [ 20.465881][ T568] console_emit_next_record+0x22f/0x4c0 [ 20.465886][ T568] ? devkmsg_read+0x4b0/0x4b0 [ 20.465888][ T568] ? console_flush_one_record+0x106/0x710 [ 20.465892][ T568] ? rcu_is_watching+0x16/0xd0 [ 20.465894][ T568] ? lock_acquire+0x13c/0x160 [ 20.465899][ T568] console_flush_one_record+0x46f/0x710 [ 20.465904][ T568] ? console_emit_next_record+0x4c0/0x4c0 [ 20.465906][ T568] ? __lock_acquire+0x518/0xc20 [ 20.465912][ T568] console_unlock+0xee/0x1f0 [ 20.465916][ T568] ? console_flush_one_record+0x710/0x710 [ 20.465918][ T568] ? rcu_is_watching+0x16/0xd0 [ 20.465920][ T568] ? lock_acquire+0xe0/0x160 [ 20.465924][ T568] ? __down_trylock_console_sem+0x5e/0xa0 [ 20.465926][ T568] ? vprintk_emit+0x320/0x3e0 [ 20.465929][ T568] vprintk_emit+0x37c/0x3e0 [ 20.465933][ T568] ? wake_up_klogd_work_func+0x90/0x90 [ 20.465936][ T568] ? __lock_acquire+0x518/0xc20 [ 20.465940][ T568] _printk+0xc7/0x100 [ 20.465944][ T568] ? snapshot_read.cold+0x21/0x21 [ 20.465947][ T568] ? do_raw_spin_lock+0x131/0x280 [ 20.465950][ T568] ? __rwlock_init+0x150/0x150 [ 20.465954][ T568] ? do_raw_spin_lock+0x131/0x280 [ 20.465956][ T568] do_exit.cold+0x82/0x9c [ 20.465960][ T568] ? exit_notify+0x890/0x890 [ 20.465962][ T568] ? __lock_release.isra.0+0x69/0x1a0 [ 20.465965][ T568] ? rcu_is_watching+0x16/0xd0 [ 20.465969][ T568] do_group_exit+0xb8/0x370 [ 20.465972][ T568] __x64_sys_exit_group+0x3c/0x50 [ 20.465974][ T568] x64_sys_call+0x1567/0x1570 [ 20.465976][ T568] do_syscall_64+0xff/0x530 [ 20.465980][ T568] ? exc_page_fault+0xee/0x100 [ 20.465983][ T568] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 20.465986][ T568] RIP: 0033:0x7f83eafad1b8 [ 20.465988][ T568] Code: Unable to access opcode bytes at 0x7f83eafad18e. [ 20.465989][ T568] RSP: 002b:00007ffe4541bd38 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 20.465992][ T568] RAX: ffffffffffffffda RBX: 00007f83eb0ddf88 RCX: 00007f83eafad1b8 [ 20.465993][ T568] RDX: 00007f83eacf7fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 20.465994][ T568] RBP: 00007ffe4541bd90 R08: 0000000000000000 R09: 0000000000000050 [ 20.465995][ T568] R10: 00007ffe4541bb50 R11: 0000000000000246 R12: 0000000000000001 [ 20.465996][ T568] R13: 0000000000000000 R14: 00007f83eb0dc680 R15: 00007f83eb0ddfa0 [ 20.466004][ T568] [ 32.064401][ C0] clocksource: Watchdog remote CPU 2 read timed out [ 32.064614][ C0] [ 32.064616][ C0] ======================================================== [ 32.064617][ C0] WARNING: possible irq lock inversion dependency detected [ 32.064619][ C0] 7.2.0-virtme #1 Tainted: G W [ 32.064621][ C0] -------------------------------------------------------- [ 32.064621][ C0] swapper/0/0 just changed the state of lock: [ 32.064623][ C0] ffffffff9ba9d760 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 32.064635][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 32.064636][ C0] (fs_reclaim){+.+.}-{0:0} [ 32.064637][ C0] [ 32.064637][ C0] [ 32.064637][ C0] and interrupts could create inverse lock ordering between them. [ 32.064637][ C0] [ 32.064638][ C0] [ 32.064638][ C0] other info that might help us debug this: [ 32.064639][ C0] Possible interrupt unsafe locking scenario: [ 32.064639][ C0] [ 32.064639][ C0] CPU0 CPU1 [ 32.064640][ C0] ---- ---- [ 32.064640][ C0] lock(fs_reclaim); [ 32.064641][ C0] local_irq_disable(); [ 32.064642][ C0] lock(console_owner); [ 32.064643][ C0] lock(fs_reclaim); [ 32.064644][ C0] [ 32.064644][ C0] lock(console_owner); [ 32.064645][ C0] [ 32.064645][ C0] *** DEADLOCK *** [ 32.064645][ C0] [ 32.064646][ C0] locks held by swapper/0/0: 4, last CPU#0: [ 32.064647][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 32.064653][ C0] #1: ffffffff9bbe29f8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 32.064657][ C0] #2: ffffffff9bb7ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 32.064661][ C0] #3: ffffffff9bb7de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 32.064664][ C0] [ 32.064664][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 32.064669][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 32.064671][ C0] HARDIRQ-ON-W at: [ 32.064673][ C0] __lock_acquire+0x388/0xc20 [ 32.064676][ C0] lock_acquire.part.0+0xd4/0x280 [ 32.064678][ C0] fs_reclaim_acquire+0xd5/0x120 [ 32.064681][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 32.064683][ C0] kthread_create_worker_on_node+0xea/0x210 [ 32.064686][ C0] workqueue_init+0x2a/0x680 [ 32.064691][ C0] kernel_init_freeable+0x2fe/0x630 [ 32.064694][ C0] kernel_init+0x21/0x150 [ 32.064698][ C0] ret_from_fork+0x474/0x6b0 [ 32.064701][ C0] ret_from_fork_asm+0x11/0x20 [ 32.064704][ C0] SOFTIRQ-ON-W at: [ 32.064705][ C0] __lock_acquire+0x388/0xc20 [ 32.064707][ C0] lock_acquire.part.0+0xd4/0x280 [ 32.064709][ C0] fs_reclaim_acquire+0xd5/0x120 [ 32.064710][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 32.064711][ C0] kthread_create_worker_on_node+0xea/0x210 [ 32.064713][ C0] workqueue_init+0x2a/0x680 [ 32.064714][ C0] kernel_init_freeable+0x2fe/0x630 [ 32.064716][ C0] kernel_init+0x21/0x150 [ 32.064718][ C0] ret_from_fork+0x474/0x6b0 [ 32.064719][ C0] ret_from_fork_asm+0x11/0x20 [ 32.064720][ C0] INITIAL USE at: [ 32.064721][ C0] __lock_acquire+0x388/0xc20 [ 32.064723][ C0] lock_acquire.part.0+0xd4/0x280 [ 32.064724][ C0] fs_reclaim_acquire+0xd5/0x120 [ 32.064726][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 32.064727][ C0] kthread_create_worker_on_node+0xea/0x210 [ 32.064729][ C0] workqueue_init+0x2a/0x680 [ 32.064730][ C0] kernel_init_freeable+0x2fe/0x630 [ 32.064732][ C0] kernel_init+0x21/0x150 [ 32.064733][ C0] ret_from_fork+0x474/0x6b0 [ 32.064735][ C0] ret_from_fork_asm+0x11/0x20 [ 32.064736][ C0] } [ 32.064737][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 32.064740][ C0] ... acquired at: [ 32.064741][ C0] __lock_acquire+0x518/0xc20 [ 32.064743][ C0] lock_acquire.part.0+0xd4/0x280 [ 32.064744][ C0] fs_reclaim_acquire+0xd5/0x120 [ 32.064746][ C0] __kmalloc_noprof+0xd3/0x760 [ 32.064747][ C0] alloc_buf.isra.0+0x4b/0x260 [ 32.064751][ C0] put_chars+0x1e1/0x2f0 [ 32.064752][ C0] hvc_console_print+0x292/0x780 [ 32.064756][ C0] console_emit_next_record+0x22f/0x4c0 [ 32.064757][ C0] console_flush_one_record+0x46f/0x710 [ 32.064759][ C0] console_unlock+0xee/0x1f0 [ 32.064761][ C0] vprintk_emit+0x37c/0x3e0 [ 32.064762][ C0] _printk+0xc7/0x100 [ 32.064765][ C0] seg6_init+0x57/0x80 [ 32.064768][ C0] inet6_init+0x2eb/0x400 [ 32.064770][ C0] do_one_initcall+0x124/0x4f0 [ 32.064771][ C0] kernel_init_freeable+0x596/0x630 [ 32.064773][ C0] kernel_init+0x21/0x150 [ 32.064774][ C0] ret_from_fork+0x474/0x6b0 [ 32.064775][ C0] ret_from_fork_asm+0x11/0x20 [ 32.064777][ C0] [ 32.064777][ C0] -> (console_owner){..-.}-{0:0} { [ 32.064779][ C0] IN-SOFTIRQ-W at: [ 32.064780][ C0] __lock_acquire+0x388/0xc20 [ 32.064782][ C0] lock_acquire.part.0+0xd4/0x280 [ 32.064783][ C0] console_lock_spinning_enable+0x5c/0x60 [ 32.064785][ C0] console_emit_next_record+0x1d1/0x4c0 [ 32.064787][ C0] console_flush_one_record+0x46f/0x710 [ 32.064788][ C0] console_unlock+0xee/0x1f0 [ 32.064790][ C0] vprintk_emit+0x37c/0x3e0 [ 32.064791][ C0] _printk+0xc7/0x100 [ 32.064793][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 32.064795][ C0] call_timer_fn+0x160/0x4d0 [ 32.064797][ C0] __run_timers+0x68f/0xaa0 [ 32.064798][ C0] run_timer_softirq+0xf0/0x160 [ 32.064800][ C0] handle_softirqs+0x1d3/0x900 [ 32.064803][ C0] __irq_exit_rcu+0x145/0x1c0 [ 32.064805][ C0] irq_exit_rcu+0xe/0x30 [ 32.064806][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 32.064808][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 32.064809][ C0] pv_native_safe_halt+0xf/0x10 [ 32.064811][ C0] default_idle+0x9/0x10 [ 32.064812][ C0] default_idle_call+0x6e/0xb0 [ 32.064814][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 32.064816][ C0] do_idle+0xd8/0x190 [ 32.064818][ C0] cpu_startup_entry+0x53/0x70 [ 32.064819][ C0] rest_init+0x279/0x280 [ 32.064820][ C0] start_kernel+0x3b9/0x3c0 [ 32.064822][ C0] x86_64_start_reservations+0x24/0x30 [ 32.064824][ C0] x86_64_start_kernel+0x12b/0x130 [ 32.064825][ C0] common_startup_64+0x13e/0x148 [ 32.064828][ C0] INITIAL USE at: [ 32.064829][ C0] } [ 32.064829][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 32.064832][ C0] ... acquired at: [ 32.064832][ C0] mark_lock+0x1d7/0xa00 [ 32.064834][ C0] mark_usage+0x42/0x170 [ 32.064835][ C0] __lock_acquire+0x388/0xc20 [ 32.064837][ C0] lock_acquire.part.0+0xd4/0x280 [ 32.064838][ C0] console_lock_spinning_enable+0x5c/0x60 [ 32.064840][ C0] console_emit_next_record+0x1d1/0x4c0 [ 32.064841][ C0] console_flush_one_record+0x46f/0x710 [ 32.064843][ C0] console_unlock+0xee/0x1f0 [ 32.064845][ C0] vprintk_emit+0x37c/0x3e0 [ 32.064845][ C0] _printk+0xc7/0x100 [ 32.064847][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 32.064848][ C0] call_timer_fn+0x160/0x4d0 [ 32.064849][ C0] __run_timers+0x68f/0xaa0 [ 32.064851][ C0] run_timer_softirq+0xf0/0x160 [ 32.064853][ C0] handle_softirqs+0x1d3/0x900 [ 32.064854][ C0] __irq_exit_rcu+0x145/0x1c0 [ 32.064855][ C0] irq_exit_rcu+0xe/0x30 [ 32.064857][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 32.064858][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 32.064859][ C0] pv_native_safe_halt+0xf/0x10 [ 32.064860][ C0] default_idle+0x9/0x10 [ 32.064862][ C0] default_idle_call+0x6e/0xb0 [ 32.064863][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 32.064864][ C0] do_idle+0xd8/0x190 [ 32.064866][ C0] cpu_startup_entry+0x53/0x70 [ 32.064867][ C0] rest_init+0x279/0x280 [ 32.064868][ C0] start_kernel+0x3b9/0x3c0 [ 32.064870][ C0] x86_64_start_reservations+0x24/0x30 [ 32.064871][ C0] x86_64_start_kernel+0x12b/0x130 [ 32.064872][ C0] common_startup_64+0x13e/0x148 [ 32.064874][ C0] [ 32.064874][ C0] [ 32.064874][ C0] stack backtrace: [ 32.064877][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 32.064880][ C0] Tainted: [W]=WARN [ 32.064881][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 32.064883][ C0] Call Trace: [ 32.064884][ C0] [ 32.064885][ C0] dump_stack_lvl+0x6f/0xa0 [ 32.064889][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 32.064892][ C0] mark_lock_irq+0x989/0x9c0 [ 32.064894][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 32.064897][ C0] mark_lock+0x1d7/0xa00 [ 32.064899][ C0] mark_usage+0x42/0x170 [ 32.064901][ C0] __lock_acquire+0x388/0xc20 [ 32.064903][ C0] lock_acquire.part.0+0xd4/0x280 [ 32.064905][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 32.064908][ C0] ? rcu_is_watching+0x16/0xd0 [ 32.064910][ C0] ? lock_acquire+0x13c/0x160 [ 32.064912][ C0] console_lock_spinning_enable+0x5c/0x60 [ 32.064914][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 32.064916][ C0] console_emit_next_record+0x1d1/0x4c0 [ 32.064918][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 32.064920][ C0] ? console_flush_one_record+0x106/0x710 [ 32.064922][ C0] ? rcu_is_watching+0x16/0xd0 [ 32.064924][ C0] ? lock_acquire+0x13c/0x160 [ 32.064926][ C0] console_flush_one_record+0x46f/0x710 [ 32.064928][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 32.064930][ C0] ? __lock_acquire+0x518/0xc20 [ 32.064933][ C0] console_unlock+0xee/0x1f0 [ 32.064935][ C0] ? console_flush_one_record+0x710/0x710 [ 32.064937][ C0] ? rcu_is_watching+0x16/0xd0 [ 32.064938][ C0] ? lock_acquire+0xe0/0x160 [ 32.064940][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 32.064942][ C0] ? vprintk_emit+0x320/0x3e0 [ 32.064944][ C0] vprintk_emit+0x37c/0x3e0 [ 32.064945][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 32.064947][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 32.064949][ C0] _printk+0xc7/0x100 [ 32.064951][ C0] ? snapshot_read.cold+0x21/0x21 [ 32.064953][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 32.064955][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 32.064958][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 32.064959][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 32.064961][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 32.064963][ C0] call_timer_fn+0x160/0x4d0 [ 32.064965][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 32.064967][ C0] ? debug_object_active_state+0x430/0x430 [ 32.064970][ C0] ? find_held_lock+0x2b/0x80 [ 32.064972][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 32.064974][ C0] ? rcu_is_watching+0x16/0xd0 [ 32.064976][ C0] __run_timers+0x68f/0xaa0 [ 32.064978][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 32.064980][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 32.064982][ C0] ? __lock_acquire+0x518/0xc20 [ 32.064985][ C0] ? __rwlock_init+0x150/0x150 [ 32.064989][ C0] run_timer_softirq+0xf0/0x160 [ 32.064991][ C0] ? __run_timers+0xaa0/0xaa0 [ 32.064993][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 32.064996][ C0] ? rcu_is_watching+0x16/0xd0 [ 32.064997][ C0] handle_softirqs+0x1d3/0x900 [ 32.064999][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 32.065001][ C0] ? _local_bh_enable+0xc0/0xc0 [ 32.065003][ C0] __irq_exit_rcu+0x145/0x1c0 [ 32.065005][ C0] irq_exit_rcu+0xe/0x30 [ 32.065006][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 32.065008][ C0] [ 32.065009][ C0] [ 32.065009][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 32.065011][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 32.065013][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 32.065016][ C0] Code: 48 8b 3d 94 82 68 02 e8 1f 00 00 00 48 2b 05 58 d3 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 06 0e 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 32.065018][ C0] RSP: 0018:ffffffff9b807cf8 EFLAGS: 00000296 [ 32.065020][ C0] RAX: 00000000001d765d RBX: ffffffff9b830600 RCX: ffffffff97f06247 [ 32.065021][ C0] RDX: ffffffff9b830600 RSI: ffffffff9b111027 RDI: ffffffff9aa949e0 [ 32.065022][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 32.065023][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff3700fa2 [ 32.065024][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 32.065026][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 32.065028][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 32.065030][ C0] default_idle+0x9/0x10 [ 32.065031][ C0] default_idle_call+0x6e/0xb0 [ 32.065033][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 32.065035][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 32.065037][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 32.065039][ C0] ? rcu_is_watching+0x16/0xd0 [ 32.065041][ C0] do_idle+0xd8/0x190 [ 32.065043][ C0] cpu_startup_entry+0x53/0x70 [ 32.065044][ C0] rest_init+0x279/0x280 [ 32.065046][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 32.065049][ C0] ? rest_init+0x280/0x280 [ 32.065051][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 32.065054][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 32.065056][ C0] ? acpi_enable+0x1e4/0x330 [ 32.065058][ C0] start_kernel+0x3b9/0x3c0 [ 32.065060][ C0] x86_64_start_reservations+0x24/0x30 [ 32.065062][ C0] x86_64_start_kernel+0x12b/0x130 [ 32.065064][ C0] common_startup_64+0x13e/0x148 [ 32.065067][ C0] [ 32.065071][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 32.065073][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 32.065074][ C0] preempt_count: 103, expected: 0 [ 32.065075][ C0] RCU nest depth: 0, expected: 0 [ 32.065076][ C0] INFO: lockdep is turned off. [ 32.065076][ C0] irq event stamp: 1930861 [ 32.065077][ C0] hardirqs last enabled at (1930860): [] __down_trylock_console_sem+0x86/0xa0 [ 32.065080][ C0] hardirqs last disabled at (1930861): [] console_emit_next_record+0x3d4/0x4c0 [ 32.065082][ C0] softirqs last enabled at (1930840): [] handle_softirqs+0x67c/0x900 [ 32.065084][ C0] softirqs last disabled at (1930847): [] __irq_exit_rcu+0x145/0x1c0 [ 32.065085][ C0] Preemption disabled at: [ 32.065086][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 32.065090][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 32.065092][ C0] Tainted: [W]=WARN [ 32.065092][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 32.065093][ C0] Call Trace: [ 32.065094][ C0] [ 32.065094][ C0] dump_stack_lvl+0x6f/0xa0 [ 32.065097][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 32.065099][ C0] __might_resched.cold+0x1fe/0x2c1 [ 32.065102][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 32.065105][ C0] ? rcu_is_watching+0x16/0xd0 [ 32.065107][ C0] __kmalloc_noprof+0x443/0x760 [ 32.065108][ C0] ? __rwlock_init+0x150/0x150 [ 32.065110][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 32.065112][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 32.065114][ C0] alloc_buf.isra.0+0x4b/0x260 [ 32.065117][ C0] put_chars+0x1e1/0x2f0 [ 32.065119][ C0] ? __send_to_port+0x420/0x420 [ 32.065121][ C0] ? mark_lock+0x1d7/0xa00 [ 32.065123][ C0] hvc_console_print+0x292/0x780 [ 32.065126][ C0] ? hvc_write+0x3a0/0x3a0 [ 32.065129][ C0] ? rcu_is_watching+0x16/0xd0 [ 32.065130][ C0] ? rcu_is_watching+0x16/0xd0 [ 32.065132][ C0] console_emit_next_record+0x22f/0x4c0 [ 32.065134][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 32.065136][ C0] ? console_flush_one_record+0x106/0x710 [ 32.065138][ C0] ? rcu_is_watching+0x16/0xd0 [ 32.065139][ C0] ? lock_acquire+0x13c/0x160 [ 32.065142][ C0] console_flush_one_record+0x46f/0x710 [ 32.065144][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 32.065146][ C0] ? __lock_acquire+0x518/0xc20 [ 32.065149][ C0] console_unlock+0xee/0x1f0 [ 32.065151][ C0] ? console_flush_one_record+0x710/0x710 [ 32.065153][ C0] ? rcu_is_watching+0x16/0xd0 [ 32.065154][ C0] ? lock_acquire+0xe0/0x160 [ 32.065156][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 32.065158][ C0] ? vprintk_emit+0x320/0x3e0 [ 32.065160][ C0] vprintk_emit+0x37c/0x3e0 [ 32.065161][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 32.065163][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 32.065165][ C0] _printk+0xc7/0x100 [ 32.065167][ C0] ? snapshot_read.cold+0x21/0x21 [ 32.065169][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 32.065171][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 32.065173][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 32.065175][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 32.065177][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 32.065179][ C0] call_timer_fn+0x160/0x4d0 [ 32.065181][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 32.065183][ C0] ? debug_object_active_state+0x430/0x430 [ 32.065185][ C0] ? find_held_lock+0x2b/0x80 [ 32.065186][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 32.065188][ C0] ? rcu_is_watching+0x16/0xd0 [ 32.065190][ C0] __run_timers+0x68f/0xaa0 [ 32.065192][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 32.065195][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 32.065197][ C0] ? __lock_acquire+0x518/0xc20 [ 32.065200][ C0] ? __rwlock_init+0x150/0x150 [ 32.065202][ C0] run_timer_softirq+0xf0/0x160 [ 32.065204][ C0] ? __run_timers+0xaa0/0xaa0 [ 32.065206][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 32.065208][ C0] ? rcu_is_watching+0x16/0xd0 [ 32.065209][ C0] handle_softirqs+0x1d3/0x900 [ 32.065211][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 32.065213][ C0] ? _local_bh_enable+0xc0/0xc0 [ 32.065215][ C0] __irq_exit_rcu+0x145/0x1c0 [ 32.065217][ C0] irq_exit_rcu+0xe/0x30 [ 32.065219][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 32.065221][ C0] [ 32.065221][ C0] [ 32.065222][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 32.065224][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 32.065225][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 32.065227][ C0] Code: 48 8b 3d 94 82 68 02 e8 1f 00 00 00 48 2b 05 58 d3 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 06 0e 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 32.065228][ C0] RSP: 0018:ffffffff9b807cf8 EFLAGS: 00000296 [ 32.065229][ C0] RAX: 00000000001d765d RBX: ffffffff9b830600 RCX: ffffffff97f06247 [ 32.065230][ C0] RDX: ffffffff9b830600 RSI: ffffffff9b111027 RDI: ffffffff9aa949e0 [ 32.065231][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 32.065232][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff3700fa2 [ 32.065232][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 32.065234][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 32.065236][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 32.065238][ C0] default_idle+0x9/0x10 [ 32.065243][ C0] default_idle_call+0x6e/0xb0 [ 32.065245][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 32.065247][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 32.065249][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 32.065250][ C0] ? rcu_is_watching+0x16/0xd0 [ 32.065252][ C0] do_idle+0xd8/0x190 [ 32.065253][ C0] cpu_startup_entry+0x53/0x70 [ 32.065255][ C0] rest_init+0x279/0x280 [ 32.065257][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 32.065259][ C0] ? rest_init+0x280/0x280 [ 32.065261][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 32.065264][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 32.065269][ C0] ? acpi_enable+0x1e4/0x330 [ 32.065271][ C0] start_kernel+0x3b9/0x3c0 [ 32.065273][ C0] x86_64_start_reservations+0x24/0x30 [ 32.065275][ C0] x86_64_start_kernel+0x12b/0x130 [ 32.065277][ C0] common_startup_64+0x13e/0x148 [ 32.065280][ C0]