[ 9.404099][ T181] ip (181) used greatest stack depth: 24136 bytes left [ 9.404114][ T181] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.404116][ T181] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 181, name: ip [ 9.404118][ T181] preempt_count: 2, expected: 0 [ 9.404119][ T181] RCU nest depth: 0, expected: 0 [ 9.404120][ T181] locks held by ip/181: 5, last CPU#2: [ 9.404122][ T181] #0: ffffffff90e267b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 9.404134][ T181] #1: ffffffff90f8dcc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.404140][ T181] #2: ffffffff90f8dd38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.404144][ T181] #3: ffffffff90ead660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.404147][ T181] #4: ffffffff90ead560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.404151][ T181] irq event stamp: 21560 [ 9.404151][ T181] hardirqs last enabled at (21559): [] __down_trylock_console_sem+0x86/0xa0 [ 9.404154][ T181] hardirqs last disabled at (21560): [] console_emit_next_record+0x3d4/0x4c0 [ 9.404156][ T181] softirqs last enabled at (20000): [] netlink_release+0x17b/0xcf0 [ 9.404160][ T181] softirqs last disabled at (19998): [] netlink_release+0xd2/0xcf0 [ 9.404162][ T181] Preemption disabled at: [ 9.404163][ T181] [<0000000000000000>] 0x0 [ 9.404170][ T181] CPU: 2 UID: 0 PID: 181 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.404173][ T181] Tainted: [W]=WARN [ 9.404174][ T181] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.404176][ T181] Call Trace: [ 9.404177][ T181] [ 9.404179][ T181] dump_stack_lvl+0x6f/0xa0 [ 9.404185][ T181] __might_resched.cold+0x1fe/0x2c1 [ 9.404189][ T181] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.404193][ T181] ? __kmalloc_noprof+0xdb/0x760 [ 9.404198][ T181] __kmalloc_noprof+0x443/0x760 [ 9.404200][ T181] ? alloc_buf.isra.0+0x4b/0x260 [ 9.404206][ T181] ? do_raw_spin_unlock+0x59/0x250 [ 9.404209][ T181] alloc_buf.isra.0+0x4b/0x260 [ 9.404212][ T181] put_chars+0x1e1/0x2f0 [ 9.404215][ T181] ? __send_to_port+0x420/0x420 [ 9.404217][ T181] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.404222][ T181] ? validate_chain+0x38b/0xc20 [ 9.404227][ T181] hvc_console_print+0x292/0x780 [ 9.404239][ T181] ? hvc_write+0x3a0/0x3a0 [ 9.404242][ T181] ? rcu_is_watching+0x16/0xd0 [ 9.404244][ T181] ? lock_acquire+0x13c/0x160 [ 9.404248][ T181] console_emit_next_record+0x22f/0x4c0 [ 9.404252][ T181] ? devkmsg_read+0x4b0/0x4b0 [ 9.404254][ T181] ? console_flush_one_record+0x106/0x710 [ 9.404257][ T181] ? rcu_is_watching+0x16/0xd0 [ 9.404259][ T181] ? lock_acquire+0x13c/0x160 [ 9.404262][ T181] console_flush_one_record+0x46f/0x710 [ 9.404266][ T181] ? console_emit_next_record+0x4c0/0x4c0 [ 9.404268][ T181] ? __lock_acquire+0x518/0xc20 [ 9.404273][ T181] console_unlock+0xee/0x1f0 [ 9.404275][ T181] ? console_flush_one_record+0x710/0x710 [ 9.404277][ T181] ? rcu_is_watching+0x16/0xd0 [ 9.404279][ T181] ? lock_acquire+0x60/0x160 [ 9.404282][ T181] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.404284][ T181] ? vprintk_emit+0x320/0x3e0 [ 9.404287][ T181] vprintk_emit+0x37c/0x3e0 [ 9.404290][ T181] ? wake_up_klogd_work_func+0x90/0x90 [ 9.404294][ T181] ? __lock_acquire+0x518/0xc20 [ 9.404297][ T181] _printk+0xc7/0x100 [ 9.404301][ T181] ? snapshot_read.cold+0x21/0x21 [ 9.404304][ T181] ? do_raw_spin_lock+0x131/0x280 [ 9.404306][ T181] ? __rwlock_init+0x150/0x150 [ 9.404310][ T181] ? do_raw_spin_lock+0x131/0x280 [ 9.404312][ T181] do_exit.cold+0x82/0x9c [ 9.404316][ T181] ? exit_notify+0x890/0x890 [ 9.404318][ T181] ? __lock_release.isra.0+0x69/0x1a0 [ 9.404320][ T181] ? rcu_is_watching+0x16/0xd0 [ 9.404324][ T181] do_group_exit+0xb8/0x370 [ 9.404327][ T181] __x64_sys_exit_group+0x3c/0x50 [ 9.404330][ T181] x64_sys_call+0x1567/0x1570 [ 9.404331][ T181] do_syscall_64+0xff/0x530 [ 9.404335][ T181] ? exc_page_fault+0xee/0x100 [ 9.404338][ T181] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.404340][ T181] RIP: 0033:0x7fee823a11b8 [ 9.404342][ T181] Code: Unable to access opcode bytes at 0x7fee823a118e. [ 9.404344][ T181] RSP: 002b:00007ffc0640a938 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 9.404346][ T181] RAX: ffffffffffffffda RBX: 00007fee824d1f88 RCX: 00007fee823a11b8 [ 9.404347][ T181] RDX: 00007fee820ebfc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 9.404348][ T181] RBP: 00007ffc0640a990 R08: 0000000000000000 R09: 0000000000000050 [ 9.404349][ T181] R10: 00007ffc0640a750 R11: 0000000000000246 R12: 0000000000000001 [ 9.404350][ T181] R13: 0000000000000000 R14: 00007fee824d0680 R15: 00007fee824d1fa0 [ 9.404357][ T181] [ 26.338714][ T700] pm_nl_ctl (700) used greatest stack depth: 23280 bytes left [ 26.338733][ T700] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 26.338735][ T700] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 700, name: pm_nl_ctl [ 26.338737][ T700] preempt_count: 2, expected: 0 [ 26.338738][ T700] RCU nest depth: 0, expected: 0 [ 26.338739][ T700] locks held by pm_nl_ctl/700: 5, last CPU#3: [ 26.338741][ T700] #0: ffffffff90e267b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 26.338754][ T700] #1: ffffffff90f8dcc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 26.338759][ T700] #2: ffffffff90f8dd38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 26.338763][ T700] #3: ffffffff90ead660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 26.338767][ T700] #4: ffffffff90ead560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 26.338771][ T700] irq event stamp: 38034 [ 26.338771][ T700] hardirqs last enabled at (38033): [] __down_trylock_console_sem+0x86/0xa0 [ 26.338774][ T700] hardirqs last disabled at (38034): [] console_emit_next_record+0x3d4/0x4c0 [ 26.338776][ T700] softirqs last enabled at (37366): [] netlink_release+0x17b/0xcf0 [ 26.338780][ T700] softirqs last disabled at (37364): [] netlink_release+0xd2/0xcf0 [ 26.338783][ T700] Preemption disabled at: [ 26.338783][ T700] [<0000000000000000>] 0x0 [ 26.338790][ T700] CPU: 3 UID: 0 PID: 700 Comm: pm_nl_ctl Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.338794][ T700] Tainted: [W]=WARN [ 26.338794][ T700] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.338796][ T700] Call Trace: [ 26.338798][ T700] [ 26.338799][ T700] dump_stack_lvl+0x6f/0xa0 [ 26.338805][ T700] __might_resched.cold+0x1fe/0x2c1 [ 26.338810][ T700] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 26.338814][ T700] ? __kmalloc_noprof+0xdb/0x760 [ 26.338818][ T700] __kmalloc_noprof+0x443/0x760 [ 26.338821][ T700] ? alloc_buf.isra.0+0x4b/0x260 [ 26.338827][ T700] ? do_raw_spin_unlock+0x59/0x250 [ 26.338830][ T700] alloc_buf.isra.0+0x4b/0x260 [ 26.338834][ T700] put_chars+0x1e1/0x2f0 [ 26.338837][ T700] ? __send_to_port+0x420/0x420 [ 26.338840][ T700] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 26.338844][ T700] ? validate_chain+0x38b/0xc20 [ 26.338850][ T700] hvc_console_print+0x292/0x780 [ 26.338857][ T700] ? hvc_write+0x3a0/0x3a0 [ 26.338860][ T700] ? rcu_is_watching+0x16/0xd0 [ 26.338862][ T700] ? lock_acquire+0x13c/0x160 [ 26.338866][ T700] console_emit_next_record+0x22f/0x4c0 [ 26.338869][ T700] ? devkmsg_read+0x4b0/0x4b0 [ 26.338871][ T700] ? console_flush_one_record+0x106/0x710 [ 26.338874][ T700] ? rcu_is_watching+0x16/0xd0 [ 26.338876][ T700] ? lock_acquire+0x13c/0x160 [ 26.338880][ T700] console_flush_one_record+0x46f/0x710 [ 26.338884][ T700] ? console_emit_next_record+0x4c0/0x4c0 [ 26.338886][ T700] ? __lock_acquire+0x518/0xc20 [ 26.338891][ T700] console_unlock+0xee/0x1f0 [ 26.338893][ T700] ? console_flush_one_record+0x710/0x710 [ 26.338895][ T700] ? rcu_is_watching+0x16/0xd0 [ 26.338898][ T700] ? lock_acquire+0x60/0x160 [ 26.338901][ T700] ? __down_trylock_console_sem+0x5e/0xa0 [ 26.338903][ T700] ? vprintk_emit+0x320/0x3e0 [ 26.338906][ T700] vprintk_emit+0x37c/0x3e0 [ 26.338909][ T700] ? wake_up_klogd_work_func+0x90/0x90 [ 26.338913][ T700] ? __lock_acquire+0x518/0xc20 [ 26.338916][ T700] _printk+0xc7/0x100 [ 26.338920][ T700] ? snapshot_read.cold+0x21/0x21 [ 26.338923][ T700] ? do_raw_spin_lock+0x131/0x280 [ 26.338925][ T700] ? __rwlock_init+0x150/0x150 [ 26.338929][ T700] ? do_raw_spin_lock+0x131/0x280 [ 26.338931][ T700] do_exit.cold+0x82/0x9c [ 26.338935][ T700] ? exit_notify+0x890/0x890 [ 26.338937][ T700] ? __lock_release.isra.0+0x69/0x1a0 [ 26.338940][ T700] ? rcu_is_watching+0x16/0xd0 [ 26.338944][ T700] do_group_exit+0xb8/0x370 [ 26.338947][ T700] __x64_sys_exit_group+0x3c/0x50 [ 26.338949][ T700] x64_sys_call+0x1567/0x1570 [ 26.338951][ T700] do_syscall_64+0xff/0x530 [ 26.338955][ T700] ? exc_page_fault+0xee/0x100 [ 26.338958][ T700] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.338961][ T700] RIP: 0033:0x7f2133af61b8 [ 26.338963][ T700] Code: Unable to access opcode bytes at 0x7f2133af618e. [ 26.338964][ T700] RSP: 002b:00007fffc204b688 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 26.338967][ T700] RAX: ffffffffffffffda RBX: 00007f2133c26f88 RCX: 00007f2133af61b8 [ 26.338968][ T700] RDX: 00007f2133a33a48 RSI: ffffffffffffffb0 RDI: 0000000000000000 [ 26.338969][ T700] RBP: 00007fffc204b6e0 R08: 0000000000000000 R09: 0000000000000000 [ 26.338970][ T700] R10: 00007fffc204b520 R11: 0000000000000246 R12: 0000000000000001 [ 26.338970][ T700] R13: 0000000000000000 R14: 00007f2133c25680 R15: 00007f2133c26fa0 [ 26.338977][ T700] [ 33.916584][ C0] clocksource: Watchdog remote CPU 2 read timed out [ 33.916629][ C0] [ 33.916631][ C0] ======================================================== [ 33.916632][ C0] WARNING: possible irq lock inversion dependency detected [ 33.916634][ C0] 7.2.0-virtme #1 Tainted: G W [ 33.916635][ C0] -------------------------------------------------------- [ 33.916636][ C0] swapper/0/0 just changed the state of lock: [ 33.916637][ C0] ffffffff90ead660 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 33.916650][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 33.916651][ C0] (fs_reclaim){+.+.}-{0:0} [ 33.916652][ C0] [ 33.916652][ C0] [ 33.916652][ C0] and interrupts could create inverse lock ordering between them. [ 33.916652][ C0] [ 33.916653][ C0] [ 33.916653][ C0] other info that might help us debug this: [ 33.916654][ C0] Possible interrupt unsafe locking scenario: [ 33.916654][ C0] [ 33.916655][ C0] CPU0 CPU1 [ 33.916655][ C0] ---- ---- [ 33.916656][ C0] lock(fs_reclaim); [ 33.916656][ C0] local_irq_disable(); [ 33.916657][ C0] lock(console_owner); [ 33.916658][ C0] lock(fs_reclaim); [ 33.916659][ C0] [ 33.916659][ C0] lock(console_owner); [ 33.916660][ C0] [ 33.916660][ C0] *** DEADLOCK *** [ 33.916660][ C0] [ 33.916660][ C0] locks held by swapper/0/0: 4, last CPU#0: [ 33.916661][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 33.916667][ C0] #1: ffffffff90ff28b8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 33.916671][ C0] #2: ffffffff90f8dcc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 33.916675][ C0] #3: ffffffff90f8dd38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 33.916678][ C0] [ 33.916678][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 33.916683][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 33.916685][ C0] HARDIRQ-ON-W at: [ 33.916686][ C0] __lock_acquire+0x388/0xc20 [ 33.916689][ C0] lock_acquire.part.0+0xd4/0x280 [ 33.916690][ C0] fs_reclaim_acquire+0xd5/0x120 [ 33.916694][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 33.916697][ C0] kthread_create_worker_on_node+0xea/0x210 [ 33.916700][ C0] workqueue_init+0x2a/0x680 [ 33.916703][ C0] kernel_init_freeable+0x2fe/0x630 [ 33.916705][ C0] kernel_init+0x21/0x150 [ 33.916709][ C0] ret_from_fork+0x474/0x6b0 [ 33.916713][ C0] ret_from_fork_asm+0x11/0x20 [ 33.916716][ C0] SOFTIRQ-ON-W at: [ 33.916717][ C0] __lock_acquire+0x388/0xc20 [ 33.916718][ C0] lock_acquire.part.0+0xd4/0x280 [ 33.916719][ C0] fs_reclaim_acquire+0xd5/0x120 [ 33.916721][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 33.916722][ C0] kthread_create_worker_on_node+0xea/0x210 [ 33.916724][ C0] workqueue_init+0x2a/0x680 [ 33.916725][ C0] kernel_init_freeable+0x2fe/0x630 [ 33.916726][ C0] kernel_init+0x21/0x150 [ 33.916727][ C0] ret_from_fork+0x474/0x6b0 [ 33.916729][ C0] ret_from_fork_asm+0x11/0x20 [ 33.916730][ C0] INITIAL USE at: [ 33.916731][ C0] __lock_acquire+0x388/0xc20 [ 33.916732][ C0] lock_acquire.part.0+0xd4/0x280 [ 33.916733][ C0] fs_reclaim_acquire+0xd5/0x120 [ 33.916735][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 33.916736][ C0] kthread_create_worker_on_node+0xea/0x210 [ 33.916738][ C0] workqueue_init+0x2a/0x680 [ 33.916739][ C0] kernel_init_freeable+0x2fe/0x630 [ 33.916740][ C0] kernel_init+0x21/0x150 [ 33.916741][ C0] ret_from_fork+0x474/0x6b0 [ 33.916743][ C0] ret_from_fork_asm+0x11/0x20 [ 33.916744][ C0] } [ 33.916744][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 33.916748][ C0] ... acquired at: [ 33.916749][ C0] __lock_acquire+0x518/0xc20 [ 33.916751][ C0] lock_acquire.part.0+0xd4/0x280 [ 33.916752][ C0] fs_reclaim_acquire+0xd5/0x120 [ 33.916753][ C0] __kmalloc_noprof+0xd3/0x760 [ 33.916755][ C0] alloc_buf.isra.0+0x4b/0x260 [ 33.916758][ C0] put_chars+0x1e1/0x2f0 [ 33.916760][ C0] hvc_console_print+0x292/0x780 [ 33.916763][ C0] console_emit_next_record+0x22f/0x4c0 [ 33.916764][ C0] console_flush_one_record+0x46f/0x710 [ 33.916766][ C0] console_unlock+0xee/0x1f0 [ 33.916767][ C0] vprintk_emit+0x37c/0x3e0 [ 33.916768][ C0] dev_vprintk_emit+0x27f/0x2c0 [ 33.916772][ C0] dev_printk_emit+0xb9/0xee [ 33.916774][ C0] _dev_info+0xe2/0x116 [ 33.916775][ C0] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 33.916778][ C0] cmos_do_probe+0x73b/0x98a [ 33.916779][ C0] platform_probe+0xfe/0x1f0 [ 33.916782][ C0] call_driver_probe+0x61/0x1c0 [ 33.916784][ C0] really_probe+0x199/0x760 [ 33.916785][ C0] __driver_probe_device+0x24f/0x440 [ 33.916786][ C0] driver_probe_device+0x4a/0xf0 [ 33.916788][ C0] __driver_attach+0x1b8/0x540 [ 33.916789][ C0] bus_for_each_dev+0x130/0x1e0 [ 33.916790][ C0] bus_add_driver+0x2c8/0x530 [ 33.916791][ C0] driver_register+0x1a3/0x390 [ 33.916793][ C0] __platform_driver_probe+0x13f/0x270 [ 33.916794][ C0] cmos_init+0x31/0x40 [ 33.916797][ C0] do_one_initcall+0x124/0x4f0 [ 33.916798][ C0] kernel_init_freeable+0x596/0x630 [ 33.916799][ C0] kernel_init+0x21/0x150 [ 33.916801][ C0] ret_from_fork+0x474/0x6b0 [ 33.916803][ C0] ret_from_fork_asm+0x11/0x20 [ 33.916804][ C0] [ 33.916804][ C0] -> (console_owner){..-.}-{0:0} { [ 33.916806][ C0] IN-SOFTIRQ-W at: [ 33.916806][ C0] __lock_acquire+0x388/0xc20 [ 33.916808][ C0] lock_acquire.part.0+0xd4/0x280 [ 33.916809][ C0] console_lock_spinning_enable+0x5c/0x60 [ 33.916810][ C0] console_emit_next_record+0x1d1/0x4c0 [ 33.916812][ C0] console_flush_one_record+0x46f/0x710 [ 33.916813][ C0] console_unlock+0xee/0x1f0 [ 33.916815][ C0] vprintk_emit+0x37c/0x3e0 [ 33.916816][ C0] _printk+0xc7/0x100 [ 33.916819][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 33.916822][ C0] call_timer_fn+0x160/0x4d0 [ 33.916823][ C0] __run_timers+0x68f/0xaa0 [ 33.916824][ C0] run_timer_softirq+0xf0/0x160 [ 33.916826][ C0] handle_softirqs+0x1d3/0x900 [ 33.916828][ C0] __irq_exit_rcu+0x145/0x1c0 [ 33.916829][ C0] irq_exit_rcu+0xe/0x30 [ 33.916830][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 33.916832][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 33.916834][ C0] pv_native_safe_halt+0xf/0x10 [ 33.916836][ C0] default_idle+0x9/0x10 [ 33.916838][ C0] default_idle_call+0x6e/0xb0 [ 33.916839][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 33.916841][ C0] do_idle+0xd8/0x190 [ 33.916842][ C0] cpu_startup_entry+0x53/0x70 [ 33.916844][ C0] rest_init+0x279/0x280 [ 33.916845][ C0] start_kernel+0x3af/0x3b0 [ 33.916847][ C0] x86_64_start_reservations+0x24/0x30 [ 33.916849][ C0] x86_64_start_kernel+0x12b/0x130 [ 33.916851][ C0] common_startup_64+0x13e/0x148 [ 33.916853][ C0] INITIAL USE at: [ 33.916854][ C0] } [ 33.916855][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 33.916857][ C0] ... acquired at: [ 33.916858][ C0] mark_lock+0x1d7/0xa00 [ 33.916859][ C0] mark_usage+0x42/0x170 [ 33.916860][ C0] __lock_acquire+0x388/0xc20 [ 33.916861][ C0] lock_acquire.part.0+0xd4/0x280 [ 33.916863][ C0] console_lock_spinning_enable+0x5c/0x60 [ 33.916864][ C0] console_emit_next_record+0x1d1/0x4c0 [ 33.916865][ C0] console_flush_one_record+0x46f/0x710 [ 33.916867][ C0] console_unlock+0xee/0x1f0 [ 33.916868][ C0] vprintk_emit+0x37c/0x3e0 [ 33.916870][ C0] _printk+0xc7/0x100 [ 33.916871][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 33.916872][ C0] call_timer_fn+0x160/0x4d0 [ 33.916873][ C0] __run_timers+0x68f/0xaa0 [ 33.916875][ C0] run_timer_softirq+0xf0/0x160 [ 33.916876][ C0] handle_softirqs+0x1d3/0x900 [ 33.916877][ C0] __irq_exit_rcu+0x145/0x1c0 [ 33.916878][ C0] irq_exit_rcu+0xe/0x30 [ 33.916879][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 33.916880][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 33.916882][ C0] pv_native_safe_halt+0xf/0x10 [ 33.916883][ C0] default_idle+0x9/0x10 [ 33.916885][ C0] default_idle_call+0x6e/0xb0 [ 33.916886][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 33.916887][ C0] do_idle+0xd8/0x190 [ 33.916888][ C0] cpu_startup_entry+0x53/0x70 [ 33.916889][ C0] rest_init+0x279/0x280 [ 33.916891][ C0] start_kernel+0x3af/0x3b0 [ 33.916892][ C0] x86_64_start_reservations+0x24/0x30 [ 33.916894][ C0] x86_64_start_kernel+0x12b/0x130 [ 33.916895][ C0] common_startup_64+0x13e/0x148 [ 33.916896][ C0] [ 33.916896][ C0] [ 33.916896][ C0] stack backtrace: [ 33.916899][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 33.916903][ C0] Tainted: [W]=WARN [ 33.916904][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 33.916905][ C0] Call Trace: [ 33.916906][ C0] [ 33.916907][ C0] dump_stack_lvl+0x6f/0xa0 [ 33.916912][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 33.916914][ C0] mark_lock_irq+0x989/0x9c0 [ 33.916916][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 33.916918][ C0] mark_lock+0x1d7/0xa00 [ 33.916920][ C0] mark_usage+0x42/0x170 [ 33.916921][ C0] __lock_acquire+0x388/0xc20 [ 33.916924][ C0] lock_acquire.part.0+0xd4/0x280 [ 33.916925][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 33.916927][ C0] ? rcu_is_watching+0x16/0xd0 [ 33.916930][ C0] ? lock_acquire+0x13c/0x160 [ 33.916932][ C0] console_lock_spinning_enable+0x5c/0x60 [ 33.916933][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 33.916935][ C0] console_emit_next_record+0x1d1/0x4c0 [ 33.916937][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 33.916938][ C0] ? console_flush_one_record+0x106/0x710 [ 33.916940][ C0] ? rcu_is_watching+0x16/0xd0 [ 33.916942][ C0] ? lock_acquire+0x13c/0x160 [ 33.916944][ C0] console_flush_one_record+0x46f/0x710 [ 33.916946][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 33.916947][ C0] ? __lock_acquire+0x518/0xc20 [ 33.916949][ C0] console_unlock+0xee/0x1f0 [ 33.916951][ C0] ? console_flush_one_record+0x710/0x710 [ 33.916953][ C0] ? rcu_is_watching+0x16/0xd0 [ 33.916954][ C0] ? lock_acquire+0x60/0x160 [ 33.916956][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 33.916958][ C0] ? vprintk_emit+0x320/0x3e0 [ 33.916960][ C0] vprintk_emit+0x37c/0x3e0 [ 33.916961][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 33.916964][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 33.916965][ C0] _printk+0xc7/0x100 [ 33.916967][ C0] ? snapshot_read.cold+0x21/0x21 [ 33.916969][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 33.916971][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 33.916973][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 33.916974][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 33.916976][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 33.916977][ C0] call_timer_fn+0x160/0x4d0 [ 33.916979][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 33.916981][ C0] ? debug_object_active_state+0x430/0x430 [ 33.916984][ C0] ? find_held_lock+0x2b/0x80 [ 33.916987][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 33.916988][ C0] ? rcu_is_watching+0x16/0xd0 [ 33.916991][ C0] __run_timers+0x68f/0xaa0 [ 33.916992][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 33.916994][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 33.916996][ C0] ? __lock_acquire+0x518/0xc20 [ 33.916998][ C0] ? __rwlock_init+0x150/0x150 [ 33.917001][ C0] run_timer_softirq+0xf0/0x160 [ 33.917002][ C0] ? __run_timers+0xaa0/0xaa0 [ 33.917004][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 33.917007][ C0] ? rcu_is_watching+0x16/0xd0 [ 33.917009][ C0] handle_softirqs+0x1d3/0x900 [ 33.917011][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 33.917012][ C0] ? _local_bh_enable+0xc0/0xc0 [ 33.917014][ C0] __irq_exit_rcu+0x145/0x1c0 [ 33.917015][ C0] irq_exit_rcu+0xe/0x30 [ 33.917016][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 33.917018][ C0] [ 33.917019][ C0] [ 33.917019][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 33.917021][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 33.917023][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 33.917025][ C0] Code: 48 8b 3d 94 b2 10 02 e8 1f 00 00 00 48 2b 05 58 03 ac 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 e6 25 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 33.917027][ C0] RSP: 0018:ffffffff90c07cf8 EFLAGS: 00000296 [ 33.917029][ C0] RAX: 00000000001320fb RBX: ffffffff90c40600 RCX: ffffffff8daf3307 [ 33.917031][ C0] RDX: ffffffff90c40600 RSI: ffffffff90a575f8 RDI: ffffffff9048d8e0 [ 33.917031][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 33.917033][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff2180fa2 [ 33.917033][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 33.917035][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 33.917037][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 33.917039][ C0] default_idle+0x9/0x10 [ 33.917041][ C0] default_idle_call+0x6e/0xb0 [ 33.917042][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 33.917044][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 33.917045][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 33.917049][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 33.917050][ C0] ? rcu_is_watching+0x16/0xd0 [ 33.917052][ C0] do_idle+0xd8/0x190 [ 33.917054][ C0] cpu_startup_entry+0x53/0x70 [ 33.917055][ C0] rest_init+0x279/0x280 [ 33.917057][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 33.917060][ C0] ? rest_init+0x280/0x280 [ 33.917062][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 33.917065][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 33.917068][ C0] ? acpi_enable+0x1e4/0x330 [ 33.917070][ C0] start_kernel+0x3af/0x3b0 [ 33.917072][ C0] x86_64_start_reservations+0x24/0x30 [ 33.917073][ C0] x86_64_start_kernel+0x12b/0x130 [ 33.917075][ C0] common_startup_64+0x13e/0x148 [ 33.917078][ C0] [ 33.917082][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 33.917084][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 33.917085][ C0] preempt_count: 103, expected: 0 [ 33.917086][ C0] RCU nest depth: 0, expected: 0 [ 33.917086][ C0] INFO: lockdep is turned off. [ 33.917087][ C0] irq event stamp: 1253643 [ 33.917087][ C0] hardirqs last enabled at (1253642): [] __down_trylock_console_sem+0x86/0xa0 [ 33.917090][ C0] hardirqs last disabled at (1253643): [] console_emit_next_record+0x3d4/0x4c0 [ 33.917091][ C0] softirqs last enabled at (1253604): [] handle_softirqs+0x67c/0x900 [ 33.917093][ C0] softirqs last disabled at (1253629): [] __irq_exit_rcu+0x145/0x1c0 [ 33.917094][ C0] Preemption disabled at: [ 33.917095][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 33.917098][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 33.917099][ C0] Tainted: [W]=WARN [ 33.917100][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 33.917100][ C0] Call Trace: [ 33.917101][ C0] [ 33.917102][ C0] dump_stack_lvl+0x6f/0xa0 [ 33.917104][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 33.917105][ C0] __might_resched.cold+0x1fe/0x2c1 [ 33.917108][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 33.917110][ C0] ? rcu_is_watching+0x16/0xd0 [ 33.917112][ C0] __kmalloc_noprof+0x443/0x760 [ 33.917114][ C0] ? __rwlock_init+0x150/0x150 [ 33.917115][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 33.917118][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 33.917119][ C0] alloc_buf.isra.0+0x4b/0x260 [ 33.917122][ C0] put_chars+0x1e1/0x2f0 [ 33.917124][ C0] ? __send_to_port+0x420/0x420 [ 33.917127][ C0] ? mark_lock+0x1d7/0xa00 [ 33.917128][ C0] hvc_console_print+0x292/0x780 [ 33.917132][ C0] ? hvc_write+0x3a0/0x3a0 [ 33.917134][ C0] ? rcu_is_watching+0x16/0xd0 [ 33.917135][ C0] ? rcu_is_watching+0x16/0xd0 [ 33.917138][ C0] console_emit_next_record+0x22f/0x4c0 [ 33.917140][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 33.917141][ C0] ? console_flush_one_record+0x106/0x710 [ 33.917143][ C0] ? rcu_is_watching+0x16/0xd0 [ 33.917145][ C0] ? lock_acquire+0x13c/0x160 [ 33.917147][ C0] console_flush_one_record+0x46f/0x710 [ 33.917149][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 33.917150][ C0] ? __lock_acquire+0x518/0xc20 [ 33.917152][ C0] console_unlock+0xee/0x1f0 [ 33.917154][ C0] ? console_flush_one_record+0x710/0x710 [ 33.917156][ C0] ? rcu_is_watching+0x16/0xd0 [ 33.917157][ C0] ? lock_acquire+0x60/0x160 [ 33.917159][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 33.917160][ C0] ? vprintk_emit+0x320/0x3e0 [ 33.917162][ C0] vprintk_emit+0x37c/0x3e0 [ 33.917164][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 33.917167][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 33.917168][ C0] _printk+0xc7/0x100 [ 33.917170][ C0] ? snapshot_read.cold+0x21/0x21 [ 33.917172][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 33.917173][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 33.917175][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 33.917177][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 33.917179][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 33.917180][ C0] call_timer_fn+0x160/0x4d0 [ 33.917182][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 33.917183][ C0] ? debug_object_active_state+0x430/0x430 [ 33.917185][ C0] ? find_held_lock+0x2b/0x80 [ 33.917187][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 33.917189][ C0] ? rcu_is_watching+0x16/0xd0 [ 33.917191][ C0] __run_timers+0x68f/0xaa0 [ 33.917192][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 33.917194][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 33.917196][ C0] ? __lock_acquire+0x518/0xc20 [ 33.917198][ C0] ? __rwlock_init+0x150/0x150 [ 33.917201][ C0] run_timer_softirq+0xf0/0x160 [ 33.917202][ C0] ? __run_timers+0xaa0/0xaa0 [ 33.917204][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 33.917206][ C0] ? rcu_is_watching+0x16/0xd0 [ 33.917207][ C0] handle_softirqs+0x1d3/0x900 [ 33.917209][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 33.917211][ C0] ? _local_bh_enable+0xc0/0xc0 [ 33.917212][ C0] __irq_exit_rcu+0x145/0x1c0 [ 33.917214][ C0] irq_exit_rcu+0xe/0x30 [ 33.917215][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 33.917217][ C0] [ 33.917217][ C0] [ 33.917218][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 33.917219][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 33.917221][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 33.917223][ C0] Code: 48 8b 3d 94 b2 10 02 e8 1f 00 00 00 48 2b 05 58 03 ac 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 e6 25 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 33.917224][ C0] RSP: 0018:ffffffff90c07cf8 EFLAGS: 00000296 [ 33.917225][ C0] RAX: 00000000001320fb RBX: ffffffff90c40600 RCX: ffffffff8daf3307 [ 33.917226][ C0] RDX: ffffffff90c40600 RSI: ffffffff90a575f8 RDI: ffffffff9048d8e0 [ 33.917227][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 33.917234][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff2180fa2 [ 33.917234][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 33.917236][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 33.917238][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 33.917240][ C0] default_idle+0x9/0x10 [ 33.917241][ C0] default_idle_call+0x6e/0xb0 [ 33.917243][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 33.917245][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 33.917246][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 33.917248][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 33.917250][ C0] ? rcu_is_watching+0x16/0xd0 [ 33.917252][ C0] do_idle+0xd8/0x190 [ 33.917253][ C0] cpu_startup_entry+0x53/0x70 [ 33.917254][ C0] rest_init+0x279/0x280 [ 33.917256][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 33.917259][ C0] ? rest_init+0x280/0x280 [ 33.917261][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 33.917263][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 33.917265][ C0] ? acpi_enable+0x1e4/0x330 [ 33.917266][ C0] start_kernel+0x3af/0x3b0 [ 33.917268][ C0] x86_64_start_reservations+0x24/0x30 [ 33.917270][ C0] x86_64_start_kernel+0x12b/0x130 [ 33.917272][ C0] common_startup_64+0x13e/0x148 [ 33.917274][ C0]