[ 11.330337][ T256] veth0: renamed from veth-rt-1 [ 11.330400][ T256] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.330402][ T256] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 256, name: ip [ 11.330404][ T256] preempt_count: 1, expected: 0 [ 11.330405][ T256] RCU nest depth: 0, expected: 0 [ 11.330406][ T256] locks held by ip/256: 5, last CPU#2: [ 11.330408][ T256] #0: ffffffffacb1b740 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11b0 [ 11.330420][ T256] #1: ffffffffac37ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.330425][ T256] #2: ffffffffac37de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.330429][ T256] #3: ffffffffac29d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.330433][ T256] #4: ffffffffac29d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.330437][ T256] irq event stamp: 20618 [ 11.330438][ T256] hardirqs last enabled at (20617): [] __down_trylock_console_sem+0x86/0xa0 [ 11.330441][ T256] hardirqs last disabled at (20618): [] console_emit_next_record+0x3d4/0x4c0 [ 11.330443][ T256] softirqs last enabled at (20612): [] netif_change_name+0x216/0x8c0 [ 11.330446][ T256] softirqs last disabled at (20610): [] netif_change_name+0x1ad/0x8c0 [ 11.330449][ T256] Preemption disabled at: [ 11.330450][ T256] [] vprintk_emit+0x31b/0x3e0 [ 11.330455][ T256] CPU: 2 UID: 0 PID: 256 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.330458][ T256] Tainted: [W]=WARN [ 11.330459][ T256] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.330461][ T256] Call Trace: [ 11.330462][ T256] [ 11.330464][ T256] dump_stack_lvl+0x6f/0xa0 [ 11.330470][ T256] ? vprintk_emit+0x31b/0x3e0 [ 11.330472][ T256] __might_resched.cold+0x1fe/0x2c1 [ 11.330476][ T256] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.330481][ T256] ? __kmalloc_noprof+0xdb/0x760 [ 11.330491][ T256] __kmalloc_noprof+0x443/0x760 [ 11.330494][ T256] ? alloc_buf.isra.0+0x4b/0x260 [ 11.330501][ T256] ? do_raw_spin_unlock+0x59/0x250 [ 11.330504][ T256] alloc_buf.isra.0+0x4b/0x260 [ 11.330508][ T256] put_chars+0x1e1/0x2f0 [ 11.330510][ T256] ? __send_to_port+0x420/0x420 [ 11.330517][ T256] hvc_console_print+0x292/0x780 [ 11.330525][ T256] ? hvc_write+0x3a0/0x3a0 [ 11.330528][ T256] ? lock_acquire+0x13c/0x160 [ 11.330533][ T256] console_emit_next_record+0x22f/0x4c0 [ 11.330536][ T256] ? devkmsg_read+0x4b0/0x4b0 [ 11.330538][ T256] ? console_flush_one_record+0x106/0x710 [ 11.330542][ T256] ? rcu_is_watching+0x16/0xd0 [ 11.330544][ T256] ? lock_acquire+0x13c/0x160 [ 11.330548][ T256] console_flush_one_record+0x46f/0x710 [ 11.330553][ T256] ? console_emit_next_record+0x4c0/0x4c0 [ 11.330555][ T256] ? __lock_acquire+0x518/0xc20 [ 11.330560][ T256] console_unlock+0xee/0x1f0 [ 11.330563][ T256] ? console_flush_one_record+0x710/0x710 [ 11.330565][ T256] ? rcu_is_watching+0x16/0xd0 [ 11.330567][ T256] ? lock_acquire+0xe0/0x160 [ 11.330571][ T256] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.330572][ T256] ? vprintk_emit+0x320/0x3e0 [ 11.330575][ T256] vprintk_emit+0x37c/0x3e0 [ 11.330578][ T256] ? wake_up_klogd_work_func+0x90/0x90 [ 11.330581][ T256] ? save_trace+0x68/0x280 [ 11.330585][ T256] _printk+0xc7/0x100 [ 11.330588][ T256] ? snapshot_read.cold+0x21/0x21 [ 11.330592][ T256] ? validate_chain+0x34a/0xc20 [ 11.330594][ T256] ? netdev_name+0x43/0x60 [ 11.330598][ T256] ? __netdev_printk+0x17f/0x1d0 [ 11.330601][ T256] ? do_setlink.isra.0+0x1f7d/0x2a60 [ 11.330603][ T256] netdev_info+0xe2/0x116 [ 11.330606][ T256] ? netdev_notice+0x120/0x120 [ 11.330609][ T256] ? find_held_lock+0x2b/0x80 [ 11.330612][ T256] ? __lock_release.isra.0+0x69/0x1a0 [ 11.330614][ T256] ? mark_held_locks+0x40/0x70 [ 11.330618][ T256] ? do_setlink.isra.0+0x1f7d/0x2a60 [ 11.330620][ T256] netif_change_name.cold+0x4f/0x89 [ 11.330622][ T256] ? is_bpf_text_address+0x68/0x110 [ 11.330628][ T256] ? netdev_adjacent_rename_links+0x470/0x470 [ 11.330630][ T256] ? __kernel_text_address+0x12/0x30 [ 11.330633][ T256] ? write_profile+0xf0/0xf0 [ 11.330637][ T256] ? __asan_memset+0x27/0x50 [ 11.330643][ T256] do_setlink.isra.0+0x1f7d/0x2a60 [ 11.330646][ T256] ? stack_trace_save+0x93/0xc0 [ 11.330649][ T256] ? rtnl_link_get_size+0x350/0x350 [ 11.330650][ T256] ? rcu_read_lock_any_held+0x66/0x90 [ 11.330653][ T256] ? stack_depot_save_flags+0x38e/0x790 [ 11.330656][ T256] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.330658][ T256] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.330660][ T256] ? validate_chain+0x38b/0xc20 [ 11.330663][ T256] ? kasan_save_stack+0x3d/0x50 [ 11.330664][ T256] ? kasan_save_stack+0x2f/0x50 [ 11.330666][ T256] ? kasan_save_track+0x14/0x30 [ 11.330667][ T256] ? __kasan_kmalloc+0x7b/0x90 [ 11.330669][ T256] ? __lock_acquire+0x518/0xc20 [ 11.330671][ T256] ? snd_ac97_proc_read_main+0x4de/0x1f00 [ 11.330674][ T256] ? ___sys_sendmsg+0xd0/0x1d0 [ 11.330678][ T256] ? lock_acquire.part.0+0xd4/0x280 [ 11.330680][ T256] ? rtnl_newlink+0x9a8/0x11b0 [ 11.330683][ T256] ? rcu_is_watching+0x16/0xd0 [ 11.330685][ T256] ? lock_acquire+0x13c/0x160 [ 11.330687][ T256] ? rcu_is_watching+0x16/0xd0 [ 11.330688][ T256] ? rcu_is_watching+0x16/0xd0 [ 11.330689][ T256] ? trace_contention_end+0xb3/0x180 [ 11.330692][ T256] ? __mutex_lock+0x1db/0x1ea0 [ 11.330694][ T256] ? __mutex_lock+0x9a3/0x1ea0 [ 11.330696][ T256] ? rtnl_newlink+0x9a8/0x11b0 [ 11.330700][ T256] ? ww_mutex_lock+0x160/0x160 [ 11.330701][ T256] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.330706][ T256] ? rcu_is_watching+0x16/0xd0 [ 11.330708][ T256] ? __rtnl_newlink+0x3fa/0xa50 [ 11.330714][ T256] rtnl_newlink+0x9f1/0x11b0 [ 11.330719][ T256] ? rtnl_bridge_getlink+0x850/0x850 [ 11.330721][ T256] ? __lock_acquire+0x518/0xc20 [ 11.330726][ T256] ? lock_acquire.part.0+0xd4/0x280 [ 11.330728][ T256] ? find_held_lock+0x2b/0x80 [ 11.330730][ T256] ? rtnl_bridge_getlink+0x850/0x850 [ 11.330732][ T256] ? __lock_release.isra.0+0x69/0x1a0 [ 11.330735][ T256] ? rtnl_bridge_getlink+0x850/0x850 [ 11.330737][ T256] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.330741][ T256] ? rtnl_link_fill+0x920/0x920 [ 11.330742][ T256] ? __lock_acquire+0x518/0xc20 [ 11.330747][ T256] ? lock_acquire.part.0+0xd4/0x280 [ 11.330749][ T256] ? find_held_lock+0x2b/0x80 [ 11.330752][ T256] netlink_rcv_skb+0x14e/0x3a0 [ 11.330755][ T256] ? rtnl_link_fill+0x920/0x920 [ 11.330758][ T256] ? netlink_ack+0xcf0/0xcf0 [ 11.330765][ T256] ? netlink_deliver_tap+0xc5/0x330 [ 11.330767][ T256] ? netlink_deliver_tap+0x13c/0x330 [ 11.330772][ T256] netlink_unicast+0x486/0x750 [ 11.330776][ T256] ? netlink_attachskb+0x810/0x810 [ 11.330778][ T256] ? find_held_lock+0x2b/0x80 [ 11.330782][ T256] netlink_sendmsg+0x75b/0xc90 [ 11.330786][ T256] ? netlink_unicast+0x750/0x750 [ 11.330790][ T256] ? __lock_release.isra.0+0x69/0x1a0 [ 11.330792][ T256] ? __import_iovec+0x36c/0x620 [ 11.330797][ T256] __sock_sendmsg+0xce/0x190 [ 11.330800][ T256] ____sys_sendmsg+0x61a/0x860 [ 11.330804][ T256] ? copy_msghdr_from_user+0x279/0x420 [ 11.330807][ T256] ? sock_write_iter+0x400/0x400 [ 11.330810][ T256] ? move_addr_to_kernel+0x40/0x40 [ 11.330820][ T256] ___sys_sendmsg+0x14e/0x1d0 [ 11.330823][ T256] ? copy_msghdr_from_user+0x420/0x420 [ 11.330840][ T256] __sys_sendmsg+0x12c/0x1d0 [ 11.330843][ T256] ? __sys_sendmsg_sock+0x20/0x20 [ 11.330849][ T256] ? rcu_is_watching+0x16/0xd0 [ 11.330852][ T256] do_syscall_64+0xff/0x530 [ 11.330855][ T256] ? exc_page_fault+0xee/0x100 [ 11.330858][ T256] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.330860][ T256] RIP: 0033:0x7fc8cfed554e [ 11.330864][ T256] 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 [ 11.330866][ T256] RSP: 002b:00007ffd240dcff0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.330869][ T256] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007fc8cfed554e [ 11.330870][ T256] RDX: 0000000000000000 RSI: 00007ffd240dd0a0 RDI: 0000000000000005 [ 11.330871][ T256] RBP: 00007ffd240dd000 R08: 0000000000000000 R09: 0000000000000000 [ 11.330872][ T256] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91a9ac [ 11.330873][ T256] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffd240dd770 [ 11.330880][ T256] [ 11.389142][ T256] ip (256) used greatest stack depth: 23208 bytes left [ 11.691861][ T266] veth0: renamed from veth-rt-2 [ 118.536980][ C0] clocksource: Watchdog remote CPU 3 read timed out [ 118.537178][ C0] [ 118.537180][ C0] ======================================================== [ 118.537182][ C0] WARNING: possible irq lock inversion dependency detected [ 118.537184][ C0] 7.2.0-virtme #1 Tainted: G W [ 118.537186][ C0] -------------------------------------------------------- [ 118.537187][ C0] swapper/0/0 just changed the state of lock: [ 118.537189][ C0] ffffffffac29d760 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 118.537204][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 118.537206][ C0] (fs_reclaim){+.+.}-{0:0} [ 118.537208][ C0] [ 118.537208][ C0] [ 118.537208][ C0] and interrupts could create inverse lock ordering between them. [ 118.537208][ C0] [ 118.537210][ C0] [ 118.537210][ C0] other info that might help us debug this: [ 118.537210][ C0] Possible interrupt unsafe locking scenario: [ 118.537210][ C0] [ 118.537212][ C0] CPU0 CPU1 [ 118.537212][ C0] ---- ---- [ 118.537213][ C0] lock(fs_reclaim); [ 118.537215][ C0] local_irq_disable(); [ 118.537216][ C0] lock(console_owner); [ 118.537217][ C0] lock(fs_reclaim); [ 118.537219][ C0] [ 118.537220][ C0] lock(console_owner); [ 118.537222][ C0] [ 118.537222][ C0] *** DEADLOCK *** [ 118.537222][ C0] [ 118.537222][ C0] locks held by swapper/0/0: 4, last CPU#0: [ 118.537224][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 118.537232][ C0] #1: ffffffffac3e29f8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 118.537238][ C0] #2: ffffffffac37ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 118.537244][ C0] #3: ffffffffac37de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 118.537249][ C0] [ 118.537249][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 118.537256][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 118.537259][ C0] HARDIRQ-ON-W at: [ 118.537262][ C0] __lock_acquire+0x388/0xc20 [ 118.537266][ C0] lock_acquire.part.0+0xd4/0x280 [ 118.537269][ C0] fs_reclaim_acquire+0xd5/0x120 [ 118.537273][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 118.537275][ C0] kthread_create_worker_on_node+0xea/0x210 [ 118.537279][ C0] workqueue_init+0x2a/0x680 [ 118.537284][ C0] kernel_init_freeable+0x2fe/0x630 [ 118.537287][ C0] kernel_init+0x21/0x150 [ 118.537292][ C0] ret_from_fork+0x474/0x6b0 [ 118.537295][ C0] ret_from_fork_asm+0x11/0x20 [ 118.537299][ C0] SOFTIRQ-ON-W at: [ 118.537300][ C0] __lock_acquire+0x388/0xc20 [ 118.537302][ C0] lock_acquire.part.0+0xd4/0x280 [ 118.537304][ C0] fs_reclaim_acquire+0xd5/0x120 [ 118.537306][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 118.537308][ C0] kthread_create_worker_on_node+0xea/0x210 [ 118.537310][ C0] workqueue_init+0x2a/0x680 [ 118.537312][ C0] kernel_init_freeable+0x2fe/0x630 [ 118.537314][ C0] kernel_init+0x21/0x150 [ 118.537316][ C0] ret_from_fork+0x474/0x6b0 [ 118.537318][ C0] ret_from_fork_asm+0x11/0x20 [ 118.537320][ C0] INITIAL USE at: [ 118.537321][ C0] __lock_acquire+0x388/0xc20 [ 118.537323][ C0] lock_acquire.part.0+0xd4/0x280 [ 118.537325][ C0] fs_reclaim_acquire+0xd5/0x120 [ 118.537327][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 118.537328][ C0] kthread_create_worker_on_node+0xea/0x210 [ 118.537330][ C0] workqueue_init+0x2a/0x680 [ 118.537332][ C0] kernel_init_freeable+0x2fe/0x630 [ 118.537334][ C0] kernel_init+0x21/0x150 [ 118.537336][ C0] ret_from_fork+0x474/0x6b0 [ 118.537338][ C0] ret_from_fork_asm+0x11/0x20 [ 118.537340][ C0] } [ 118.537340][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 118.537345][ C0] ... acquired at: [ 118.537346][ C0] __lock_acquire+0x518/0xc20 [ 118.537348][ C0] lock_acquire.part.0+0xd4/0x280 [ 118.537351][ C0] fs_reclaim_acquire+0xd5/0x120 [ 118.537353][ C0] __kmalloc_noprof+0xd3/0x760 [ 118.537355][ C0] alloc_buf.isra.0+0x4b/0x260 [ 118.537359][ C0] put_chars+0x1e1/0x2f0 [ 118.537361][ C0] hvc_console_print+0x292/0x780 [ 118.537365][ C0] console_emit_next_record+0x22f/0x4c0 [ 118.537368][ C0] console_flush_one_record+0x46f/0x710 [ 118.537370][ C0] console_unlock+0xee/0x1f0 [ 118.537373][ C0] vprintk_emit+0x37c/0x3e0 [ 118.537374][ C0] _printk+0xc7/0x100 [ 118.537379][ C0] i8042_pnp_init+0xf7/0x3c0 [ 118.537382][ C0] i8042_platform_init+0x3f9/0x460 [ 118.537385][ C0] i8042_init+0x45/0x130 [ 118.537387][ C0] do_one_initcall+0x124/0x4f0 [ 118.537389][ C0] kernel_init_freeable+0x596/0x630 [ 118.537391][ C0] kernel_init+0x21/0x150 [ 118.537394][ C0] ret_from_fork+0x474/0x6b0 [ 118.537396][ C0] ret_from_fork_asm+0x11/0x20 [ 118.537398][ C0] [ 118.537398][ C0] -> (console_owner){..-.}-{0:0} { [ 118.537401][ C0] IN-SOFTIRQ-W at: [ 118.537403][ C0] __lock_acquire+0x388/0xc20 [ 118.537405][ C0] lock_acquire.part.0+0xd4/0x280 [ 118.537407][ C0] console_lock_spinning_enable+0x5c/0x60 [ 118.537410][ C0] console_emit_next_record+0x1d1/0x4c0 [ 118.537412][ C0] console_flush_one_record+0x46f/0x710 [ 118.537415][ C0] console_unlock+0xee/0x1f0 [ 118.537417][ C0] vprintk_emit+0x37c/0x3e0 [ 118.537419][ C0] _printk+0xc7/0x100 [ 118.537421][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 118.537424][ C0] call_timer_fn+0x160/0x4d0 [ 118.537427][ C0] __run_timers+0x68f/0xaa0 [ 118.537429][ C0] run_timer_softirq+0xf0/0x160 [ 118.537432][ C0] handle_softirqs+0x1d3/0x900 [ 118.537436][ C0] __irq_exit_rcu+0x145/0x1c0 [ 118.537438][ C0] irq_exit_rcu+0xe/0x30 [ 118.537440][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 118.537442][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 118.537445][ C0] pv_native_safe_halt+0xf/0x10 [ 118.537447][ C0] default_idle+0x9/0x10 [ 118.537449][ C0] default_idle_call+0x6e/0xb0 [ 118.537452][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 118.537455][ C0] do_idle+0xd8/0x190 [ 118.537457][ C0] cpu_startup_entry+0x53/0x70 [ 118.537459][ C0] rest_init+0x279/0x280 [ 118.537462][ C0] start_kernel+0x3b9/0x3c0 [ 118.537464][ C0] x86_64_start_reservations+0x24/0x30 [ 118.537467][ C0] x86_64_start_kernel+0x12b/0x130 [ 118.537469][ C0] common_startup_64+0x13e/0x148 [ 118.537473][ C0] INITIAL USE at: [ 118.537474][ C0] } [ 118.537475][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 118.537478][ C0] ... acquired at: [ 118.537479][ C0] mark_lock+0x1d7/0xa00 [ 118.537480][ C0] mark_usage+0x42/0x170 [ 118.537482][ C0] __lock_acquire+0x388/0xc20 [ 118.537484][ C0] lock_acquire.part.0+0xd4/0x280 [ 118.537486][ C0] console_lock_spinning_enable+0x5c/0x60 [ 118.537489][ C0] console_emit_next_record+0x1d1/0x4c0 [ 118.537491][ C0] console_flush_one_record+0x46f/0x710 [ 118.537493][ C0] console_unlock+0xee/0x1f0 [ 118.537495][ C0] vprintk_emit+0x37c/0x3e0 [ 118.537497][ C0] _printk+0xc7/0x100 [ 118.537498][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 118.537500][ C0] call_timer_fn+0x160/0x4d0 [ 118.537502][ C0] __run_timers+0x68f/0xaa0 [ 118.537504][ C0] run_timer_softirq+0xf0/0x160 [ 118.537506][ C0] handle_softirqs+0x1d3/0x900 [ 118.537508][ C0] __irq_exit_rcu+0x145/0x1c0 [ 118.537510][ C0] irq_exit_rcu+0xe/0x30 [ 118.537512][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 118.537513][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 118.537515][ C0] pv_native_safe_halt+0xf/0x10 [ 118.537517][ C0] default_idle+0x9/0x10 [ 118.537519][ C0] default_idle_call+0x6e/0xb0 [ 118.537520][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 118.537522][ C0] do_idle+0xd8/0x190 [ 118.537524][ C0] cpu_startup_entry+0x53/0x70 [ 118.537526][ C0] rest_init+0x279/0x280 [ 118.537527][ C0] start_kernel+0x3b9/0x3c0 [ 118.537529][ C0] x86_64_start_reservations+0x24/0x30 [ 118.537531][ C0] x86_64_start_kernel+0x12b/0x130 [ 118.537533][ C0] common_startup_64+0x13e/0x148 [ 118.537535][ C0] [ 118.537535][ C0] [ 118.537535][ C0] stack backtrace: [ 118.537538][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 118.537543][ C0] Tainted: [W]=WARN [ 118.537544][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 118.537546][ C0] Call Trace: [ 118.537548][ C0] [ 118.537549][ C0] dump_stack_lvl+0x6f/0xa0 [ 118.537555][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 118.537559][ C0] mark_lock_irq+0x989/0x9c0 [ 118.537561][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 118.537566][ C0] mark_lock+0x1d7/0xa00 [ 118.537569][ C0] mark_usage+0x42/0x170 [ 118.537572][ C0] __lock_acquire+0x388/0xc20 [ 118.537576][ C0] lock_acquire.part.0+0xd4/0x280 [ 118.537579][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 118.537582][ C0] ? rcu_is_watching+0x16/0xd0 [ 118.537585][ C0] ? lock_acquire+0x13c/0x160 [ 118.537588][ C0] console_lock_spinning_enable+0x5c/0x60 [ 118.537591][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 118.537594][ C0] console_emit_next_record+0x1d1/0x4c0 [ 118.537597][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 118.537600][ C0] ? console_flush_one_record+0x106/0x710 [ 118.537603][ C0] ? rcu_is_watching+0x16/0xd0 [ 118.537605][ C0] ? lock_acquire+0x13c/0x160 [ 118.537608][ C0] console_flush_one_record+0x46f/0x710 [ 118.537612][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 118.537615][ C0] ? __lock_acquire+0x518/0xc20 [ 118.537618][ C0] console_unlock+0xee/0x1f0 [ 118.537621][ C0] ? console_flush_one_record+0x710/0x710 [ 118.537623][ C0] ? rcu_is_watching+0x16/0xd0 [ 118.537625][ C0] ? lock_acquire+0xe0/0x160 [ 118.537628][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 118.537630][ C0] ? vprintk_emit+0x320/0x3e0 [ 118.537633][ C0] vprintk_emit+0x37c/0x3e0 [ 118.537635][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 118.537638][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 118.537641][ C0] _printk+0xc7/0x100 [ 118.537643][ C0] ? snapshot_read.cold+0x21/0x21 [ 118.537646][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 118.537648][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 118.537652][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 118.537654][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 118.537657][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 118.537659][ C0] call_timer_fn+0x160/0x4d0 [ 118.537662][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 118.537664][ C0] ? debug_object_active_state+0x430/0x430 [ 118.537668][ C0] ? find_held_lock+0x2b/0x80 [ 118.537670][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 118.537673][ C0] ? rcu_is_watching+0x16/0xd0 [ 118.537675][ C0] __run_timers+0x68f/0xaa0 [ 118.537678][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 118.537682][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 118.537685][ C0] ? __lock_acquire+0x518/0xc20 [ 118.537689][ C0] ? __rwlock_init+0x150/0x150 [ 118.537694][ C0] run_timer_softirq+0xf0/0x160 [ 118.537697][ C0] ? __run_timers+0xaa0/0xaa0 [ 118.537700][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 118.537703][ C0] ? rcu_is_watching+0x16/0xd0 [ 118.537705][ C0] handle_softirqs+0x1d3/0x900 [ 118.537708][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 118.537711][ C0] ? _local_bh_enable+0xc0/0xc0 [ 118.537715][ C0] __irq_exit_rcu+0x145/0x1c0 [ 118.537717][ C0] irq_exit_rcu+0xe/0x30 [ 118.537719][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 118.537722][ C0] [ 118.537723][ C0] [ 118.537723][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 118.537726][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 118.537729][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 118.537732][ 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 [ 118.537734][ C0] RSP: 0018:ffffffffac007cf8 EFLAGS: 00000296 [ 118.537737][ C0] RAX: 000000000019c009 RBX: ffffffffac030600 RCX: ffffffffa8706247 [ 118.537739][ C0] RDX: ffffffffac030600 RSI: ffffffffab911011 RDI: ffffffffab2949e0 [ 118.537741][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 118.537743][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff5800fa2 [ 118.537744][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 118.537747][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 118.537750][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 118.537753][ C0] default_idle+0x9/0x10 [ 118.537755][ C0] default_idle_call+0x6e/0xb0 [ 118.537757][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 118.537760][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 118.537763][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 118.537766][ C0] ? rcu_is_watching+0x16/0xd0 [ 118.537769][ C0] do_idle+0xd8/0x190 [ 118.537772][ C0] cpu_startup_entry+0x53/0x70 [ 118.537774][ C0] rest_init+0x279/0x280 [ 118.537777][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 118.537781][ C0] ? rest_init+0x280/0x280 [ 118.537783][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 118.537788][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 118.537790][ C0] ? acpi_enable+0x1e4/0x330 [ 118.537794][ C0] start_kernel+0x3b9/0x3c0 [ 118.537797][ C0] x86_64_start_reservations+0x24/0x30 [ 118.537799][ C0] x86_64_start_kernel+0x12b/0x130 [ 118.537802][ C0] common_startup_64+0x13e/0x148 [ 118.537807][ C0] [ 118.537816][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 118.537818][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 118.537820][ C0] preempt_count: 103, expected: 0 [ 118.537821][ C0] RCU nest depth: 0, expected: 0 [ 118.537822][ C0] INFO: lockdep is turned off. [ 118.537823][ C0] irq event stamp: 1687577 [ 118.537824][ C0] hardirqs last enabled at (1687576): [] __down_trylock_console_sem+0x86/0xa0 [ 118.537828][ C0] hardirqs last disabled at (1687577): [] console_emit_next_record+0x3d4/0x4c0 [ 118.537831][ C0] softirqs last enabled at (1687554): [] handle_softirqs+0x67c/0x900 [ 118.537834][ C0] softirqs last disabled at (1687563): [] __irq_exit_rcu+0x145/0x1c0 [ 118.537836][ C0] Preemption disabled at: [ 118.537837][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 118.537842][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 118.537845][ C0] Tainted: [W]=WARN [ 118.537846][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 118.537847][ C0] Call Trace: [ 118.537848][ C0] [ 118.537849][ C0] dump_stack_lvl+0x6f/0xa0 [ 118.537852][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 118.537855][ C0] __might_resched.cold+0x1fe/0x2c1 [ 118.537858][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 118.537863][ C0] ? rcu_is_watching+0x16/0xd0 [ 118.537865][ C0] __kmalloc_noprof+0x443/0x760 [ 118.537867][ C0] ? __rwlock_init+0x150/0x150 [ 118.537870][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 118.537873][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 118.537876][ C0] alloc_buf.isra.0+0x4b/0x260 [ 118.537879][ C0] put_chars+0x1e1/0x2f0 [ 118.537882][ C0] ? __send_to_port+0x420/0x420 [ 118.537884][ C0] ? mark_lock+0x1d7/0xa00 [ 118.537887][ C0] hvc_console_print+0x292/0x780 [ 118.537892][ C0] ? hvc_write+0x3a0/0x3a0 [ 118.537894][ C0] ? rcu_is_watching+0x16/0xd0 [ 118.537896][ C0] ? rcu_is_watching+0x16/0xd0 [ 118.537899][ C0] console_emit_next_record+0x22f/0x4c0 [ 118.537902][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 118.537904][ C0] ? console_flush_one_record+0x106/0x710 [ 118.537907][ C0] ? rcu_is_watching+0x16/0xd0 [ 118.537909][ C0] ? lock_acquire+0x13c/0x160 [ 118.537912][ C0] console_flush_one_record+0x46f/0x710 [ 118.537915][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 118.537917][ C0] ? __lock_acquire+0x518/0xc20 [ 118.537921][ C0] console_unlock+0xee/0x1f0 [ 118.537924][ C0] ? console_flush_one_record+0x710/0x710 [ 118.537926][ C0] ? rcu_is_watching+0x16/0xd0 [ 118.537928][ C0] ? lock_acquire+0xe0/0x160 [ 118.537931][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 118.537933][ C0] ? vprintk_emit+0x320/0x3e0 [ 118.537935][ C0] vprintk_emit+0x37c/0x3e0 [ 118.537938][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 118.537941][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 118.537944][ C0] _printk+0xc7/0x100 [ 118.537947][ C0] ? snapshot_read.cold+0x21/0x21 [ 118.537950][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 118.537952][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 118.537956][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 118.537959][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 118.537961][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 118.537964][ C0] call_timer_fn+0x160/0x4d0 [ 118.537967][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 118.537970][ C0] ? debug_object_active_state+0x430/0x430 [ 118.537972][ C0] ? find_held_lock+0x2b/0x80 [ 118.537975][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 118.537978][ C0] ? rcu_is_watching+0x16/0xd0 [ 118.537981][ C0] __run_timers+0x68f/0xaa0 [ 118.537984][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 118.537987][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 118.537990][ C0] ? __lock_acquire+0x518/0xc20 [ 118.537995][ C0] ? __rwlock_init+0x150/0x150 [ 118.537998][ C0] run_timer_softirq+0xf0/0x160 [ 118.538002][ C0] ? __run_timers+0xaa0/0xaa0 [ 118.538004][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 118.538007][ C0] ? rcu_is_watching+0x16/0xd0 [ 118.538008][ C0] handle_softirqs+0x1d3/0x900 [ 118.538011][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 118.538014][ C0] ? _local_bh_enable+0xc0/0xc0 [ 118.538017][ C0] __irq_exit_rcu+0x145/0x1c0 [ 118.538020][ C0] irq_exit_rcu+0xe/0x30 [ 118.538022][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 118.538024][ C0] [ 118.538025][ C0] [ 118.538026][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 118.538029][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 118.538037][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 118.538039][ 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 [ 118.538041][ C0] RSP: 0018:ffffffffac007cf8 EFLAGS: 00000296 [ 118.538043][ C0] RAX: 000000000019c009 RBX: ffffffffac030600 RCX: ffffffffa8706247 [ 118.538044][ C0] RDX: ffffffffac030600 RSI: ffffffffab911011 RDI: ffffffffab2949e0 [ 118.538045][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 118.538047][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff5800fa2 [ 118.538048][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 118.538051][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 118.538054][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 118.538056][ C0] default_idle+0x9/0x10 [ 118.538059][ C0] default_idle_call+0x6e/0xb0 [ 118.538061][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 118.538064][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 118.538066][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 118.538069][ C0] ? rcu_is_watching+0x16/0xd0 [ 118.538072][ C0] do_idle+0xd8/0x190 [ 118.538074][ C0] cpu_startup_entry+0x53/0x70 [ 118.538077][ C0] rest_init+0x279/0x280 [ 118.538080][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 118.538084][ C0] ? rest_init+0x280/0x280 [ 118.538087][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 118.538090][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 118.538092][ C0] ? acpi_enable+0x1e4/0x330 [ 118.538095][ C0] start_kernel+0x3b9/0x3c0 [ 118.538098][ C0] x86_64_start_reservations+0x24/0x30 [ 118.538100][ C0] x86_64_start_kernel+0x12b/0x130 [ 118.538103][ C0] common_startup_64+0x13e/0x148 [ 118.538108][ C0]