[ 13.375245][ T259] veth0: renamed from veth-rt-1 [ 13.375387][ T259] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 13.375390][ T259] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 259, name: ip [ 13.375391][ T259] preempt_count: 1, expected: 0 [ 13.375392][ T259] RCU nest depth: 0, expected: 0 [ 13.375393][ T259] locks held by ip/259: 5, last CPU#1: [ 13.375395][ T259] #0: ffffffff9431b740 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11b0 [ 13.375407][ T259] #1: ffffffff93b7ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 13.375413][ T259] #2: ffffffff93b7de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 13.375417][ T259] #3: ffffffff93a9d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 13.375421][ T259] #4: ffffffff93a9d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 13.375425][ T259] irq event stamp: 26320 [ 13.375426][ T259] hardirqs last enabled at (26319): [] __down_trylock_console_sem+0x86/0xa0 [ 13.375429][ T259] hardirqs last disabled at (26320): [] console_emit_next_record+0x3d4/0x4c0 [ 13.375431][ T259] softirqs last enabled at (26314): [] netif_change_name+0x216/0x8c0 [ 13.375435][ T259] softirqs last disabled at (26312): [] netif_change_name+0x1ad/0x8c0 [ 13.375438][ T259] Preemption disabled at: [ 13.375438][ T259] [] vprintk_emit+0x31b/0x3e0 [ 13.375444][ T259] CPU: 1 UID: 0 PID: 259 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 13.375448][ T259] Tainted: [W]=WARN [ 13.375449][ T259] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 13.375450][ T259] Call Trace: [ 13.375452][ T259] [ 13.375454][ T259] dump_stack_lvl+0x6f/0xa0 [ 13.375460][ T259] ? vprintk_emit+0x31b/0x3e0 [ 13.375461][ T259] __might_resched.cold+0x1fe/0x2c1 [ 13.375466][ T259] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 13.375471][ T259] ? __kmalloc_noprof+0xdb/0x760 [ 13.375476][ T259] __kmalloc_noprof+0x443/0x760 [ 13.375477][ T259] ? alloc_buf.isra.0+0x4b/0x260 [ 13.375484][ T259] ? do_raw_spin_unlock+0x59/0x250 [ 13.375487][ T259] alloc_buf.isra.0+0x4b/0x260 [ 13.375491][ T259] put_chars+0x1e1/0x2f0 [ 13.375494][ T259] ? __send_to_port+0x420/0x420 [ 13.375500][ T259] hvc_console_print+0x292/0x780 [ 13.375508][ T259] ? hvc_write+0x3a0/0x3a0 [ 13.375512][ T259] ? lock_acquire+0x13c/0x160 [ 13.375516][ T259] console_emit_next_record+0x22f/0x4c0 [ 13.375520][ T259] ? devkmsg_read+0x4b0/0x4b0 [ 13.375522][ T259] ? console_flush_one_record+0x106/0x710 [ 13.375525][ T259] ? rcu_is_watching+0x16/0xd0 [ 13.375528][ T259] ? lock_acquire+0x13c/0x160 [ 13.375532][ T259] console_flush_one_record+0x46f/0x710 [ 13.375537][ T259] ? console_emit_next_record+0x4c0/0x4c0 [ 13.375538][ T259] ? __lock_acquire+0x518/0xc20 [ 13.375544][ T259] console_unlock+0xee/0x1f0 [ 13.375547][ T259] ? console_flush_one_record+0x710/0x710 [ 13.375549][ T259] ? rcu_is_watching+0x16/0xd0 [ 13.375550][ T259] ? lock_acquire+0xe0/0x160 [ 13.375554][ T259] ? __down_trylock_console_sem+0x5e/0xa0 [ 13.375556][ T259] ? vprintk_emit+0x320/0x3e0 [ 13.375559][ T259] vprintk_emit+0x37c/0x3e0 [ 13.375562][ T259] ? wake_up_klogd_work_func+0x90/0x90 [ 13.375564][ T259] ? save_trace+0x68/0x280 [ 13.375569][ T259] _printk+0xc7/0x100 [ 13.375572][ T259] ? snapshot_read.cold+0x21/0x21 [ 13.375576][ T259] ? validate_chain+0x34a/0xc20 [ 13.375578][ T259] ? netdev_name+0x43/0x60 [ 13.375583][ T259] ? __netdev_printk+0x17f/0x1d0 [ 13.375585][ T259] ? do_setlink.isra.0+0x1f7d/0x2a60 [ 13.375588][ T259] netdev_info+0xe2/0x116 [ 13.375591][ T259] ? netdev_notice+0x120/0x120 [ 13.375593][ T259] ? find_held_lock+0x2b/0x80 [ 13.375600][ T259] ? __lock_release.isra.0+0x69/0x1a0 [ 13.375602][ T259] ? mark_held_locks+0x40/0x70 [ 13.375606][ T259] ? do_setlink.isra.0+0x1f7d/0x2a60 [ 13.375608][ T259] netif_change_name.cold+0x4f/0x89 [ 13.375610][ T259] ? is_bpf_text_address+0x68/0x110 [ 13.375616][ T259] ? netdev_adjacent_rename_links+0x470/0x470 [ 13.375618][ T259] ? __kernel_text_address+0x12/0x30 [ 13.375621][ T259] ? write_profile+0xf0/0xf0 [ 13.375626][ T259] ? __asan_memset+0x27/0x50 [ 13.375631][ T259] do_setlink.isra.0+0x1f7d/0x2a60 [ 13.375635][ T259] ? stack_trace_save+0x93/0xc0 [ 13.375637][ T259] ? rtnl_link_get_size+0x350/0x350 [ 13.375638][ T259] ? rcu_read_lock_any_held+0x66/0x90 [ 13.375641][ T259] ? stack_depot_save_flags+0x38e/0x790 [ 13.375645][ T259] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.375647][ T259] ? rcu_read_lock_any_held+0x3c/0x90 [ 13.375649][ T259] ? validate_chain+0x38b/0xc20 [ 13.375651][ T259] ? kasan_save_stack+0x3d/0x50 [ 13.375653][ T259] ? kasan_save_stack+0x2f/0x50 [ 13.375654][ T259] ? kasan_save_track+0x14/0x30 [ 13.375656][ T259] ? __kasan_kmalloc+0x7b/0x90 [ 13.375658][ T259] ? __lock_acquire+0x518/0xc20 [ 13.375660][ T259] ? snd_ac97_proc_read_main+0x4de/0x1f00 [ 13.375663][ T259] ? ___sys_sendmsg+0xd0/0x1d0 [ 13.375667][ T259] ? lock_acquire.part.0+0xd4/0x280 [ 13.375669][ T259] ? rtnl_newlink+0x9a8/0x11b0 [ 13.375671][ T259] ? rcu_is_watching+0x16/0xd0 [ 13.375673][ T259] ? lock_acquire+0x13c/0x160 [ 13.375675][ T259] ? rcu_is_watching+0x16/0xd0 [ 13.375676][ T259] ? rcu_is_watching+0x16/0xd0 [ 13.375677][ T259] ? trace_contention_end+0xb3/0x180 [ 13.375680][ T259] ? __mutex_lock+0x1db/0x1ea0 [ 13.375682][ T259] ? __mutex_lock+0x9a3/0x1ea0 [ 13.375684][ T259] ? rtnl_newlink+0x9a8/0x11b0 [ 13.375688][ T259] ? ww_mutex_lock+0x160/0x160 [ 13.375689][ T259] ? nla_get_range_signed+0x3d0/0x3d0 [ 13.375694][ T259] ? rcu_is_watching+0x16/0xd0 [ 13.375696][ T259] ? __rtnl_newlink+0x3fa/0xa50 [ 13.375701][ T259] rtnl_newlink+0x9f1/0x11b0 [ 13.375706][ T259] ? rtnl_bridge_getlink+0x850/0x850 [ 13.375708][ T259] ? __lock_acquire+0x518/0xc20 [ 13.375713][ T259] ? lock_acquire.part.0+0xd4/0x280 [ 13.375715][ T259] ? find_held_lock+0x2b/0x80 [ 13.375717][ T259] ? rtnl_bridge_getlink+0x850/0x850 [ 13.375719][ T259] ? __lock_release.isra.0+0x69/0x1a0 [ 13.375723][ T259] ? rtnl_bridge_getlink+0x850/0x850 [ 13.375725][ T259] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 13.375728][ T259] ? rtnl_link_fill+0x920/0x920 [ 13.375729][ T259] ? __lock_acquire+0x518/0xc20 [ 13.375734][ T259] ? lock_acquire.part.0+0xd4/0x280 [ 13.375736][ T259] ? find_held_lock+0x2b/0x80 [ 13.375739][ T259] netlink_rcv_skb+0x14e/0x3a0 [ 13.375743][ T259] ? rtnl_link_fill+0x920/0x920 [ 13.375746][ T259] ? netlink_ack+0xcf0/0xcf0 [ 13.375752][ T259] ? netlink_deliver_tap+0xc5/0x330 [ 13.375754][ T259] ? netlink_deliver_tap+0x13c/0x330 [ 13.375759][ T259] netlink_unicast+0x486/0x750 [ 13.375763][ T259] ? netlink_attachskb+0x810/0x810 [ 13.375766][ T259] ? find_held_lock+0x2b/0x80 [ 13.375770][ T259] netlink_sendmsg+0x75b/0xc90 [ 13.375774][ T259] ? netlink_unicast+0x750/0x750 [ 13.375778][ T259] ? __lock_release.isra.0+0x69/0x1a0 [ 13.375779][ T259] ? __import_iovec+0x36c/0x620 [ 13.375785][ T259] __sock_sendmsg+0xce/0x190 [ 13.375788][ T259] ____sys_sendmsg+0x61a/0x860 [ 13.375790][ T259] ? copy_msghdr_from_user+0x279/0x420 [ 13.375792][ T259] ? sock_write_iter+0x400/0x400 [ 13.375794][ T259] ? move_addr_to_kernel+0x40/0x40 [ 13.375801][ T259] ___sys_sendmsg+0x14e/0x1d0 [ 13.375804][ T259] ? copy_msghdr_from_user+0x420/0x420 [ 13.375820][ T259] __sys_sendmsg+0x12c/0x1d0 [ 13.375823][ T259] ? __sys_sendmsg_sock+0x20/0x20 [ 13.375829][ T259] ? rcu_is_watching+0x16/0xd0 [ 13.375832][ T259] do_syscall_64+0xff/0x530 [ 13.375836][ T259] ? exc_page_fault+0xee/0x100 [ 13.375838][ T259] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 13.375840][ T259] RIP: 0033:0x7fc14b44e54e [ 13.375845][ T259] 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 [ 13.375846][ T259] RSP: 002b:00007ffe1a9a11e0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 13.375849][ T259] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007fc14b44e54e [ 13.375850][ T259] RDX: 0000000000000000 RSI: 00007ffe1a9a1290 RDI: 0000000000000005 [ 13.375851][ T259] RBP: 00007ffe1a9a11f0 R08: 0000000000000000 R09: 0000000000000000 [ 13.375852][ T259] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a90d9c1 [ 13.375853][ T259] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffe1a9a1960 [ 13.375860][ T259] [ 13.426862][ T259] ip (259) used greatest stack depth: 23208 bytes left [ 13.804500][ T269] veth0: renamed from veth-rt-2 [ 170.028730][ C0] clocksource: Watchdog remote CPU 2 read timed out [ 170.028927][ C0] [ 170.028928][ C0] ======================================================== [ 170.028929][ C0] WARNING: possible irq lock inversion dependency detected [ 170.028932][ C0] 7.2.0-virtme #1 Tainted: G W [ 170.028933][ C0] -------------------------------------------------------- [ 170.028934][ C0] swapper/0/0 just changed the state of lock: [ 170.028935][ C0] ffffffff93a9d760 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 170.028947][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 170.028948][ C0] (fs_reclaim){+.+.}-{0:0} [ 170.028950][ C0] [ 170.028950][ C0] [ 170.028950][ C0] and interrupts could create inverse lock ordering between them. [ 170.028950][ C0] [ 170.028951][ C0] [ 170.028951][ C0] other info that might help us debug this: [ 170.028952][ C0] Possible interrupt unsafe locking scenario: [ 170.028952][ C0] [ 170.028952][ C0] CPU0 CPU1 [ 170.028953][ C0] ---- ---- [ 170.028953][ C0] lock(fs_reclaim); [ 170.028954][ C0] local_irq_disable(); [ 170.028955][ C0] lock(console_owner); [ 170.028956][ C0] lock(fs_reclaim); [ 170.028956][ C0] [ 170.028957][ C0] lock(console_owner); [ 170.028957][ C0] [ 170.028957][ C0] *** DEADLOCK *** [ 170.028957][ C0] [ 170.028958][ C0] locks held by swapper/0/0: 4, last CPU#0: [ 170.028959][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 170.028965][ C0] #1: ffffffff93be29f8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 170.028968][ C0] #2: ffffffff93b7ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 170.028971][ C0] #3: ffffffff93b7de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 170.028975][ C0] [ 170.028975][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 170.028979][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 170.028981][ C0] HARDIRQ-ON-W at: [ 170.028983][ C0] __lock_acquire+0x388/0xc20 [ 170.028986][ C0] lock_acquire.part.0+0xd4/0x280 [ 170.028988][ C0] fs_reclaim_acquire+0xd5/0x120 [ 170.028991][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 170.028993][ C0] kthread_create_worker_on_node+0xea/0x210 [ 170.028996][ C0] workqueue_init+0x2a/0x680 [ 170.029000][ C0] kernel_init_freeable+0x2fe/0x630 [ 170.029003][ C0] kernel_init+0x21/0x150 [ 170.029007][ C0] ret_from_fork+0x474/0x6b0 [ 170.029010][ C0] ret_from_fork_asm+0x11/0x20 [ 170.029013][ C0] SOFTIRQ-ON-W at: [ 170.029013][ C0] __lock_acquire+0x388/0xc20 [ 170.029015][ C0] lock_acquire.part.0+0xd4/0x280 [ 170.029016][ C0] fs_reclaim_acquire+0xd5/0x120 [ 170.029018][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 170.029019][ C0] kthread_create_worker_on_node+0xea/0x210 [ 170.029020][ C0] workqueue_init+0x2a/0x680 [ 170.029022][ C0] kernel_init_freeable+0x2fe/0x630 [ 170.029023][ C0] kernel_init+0x21/0x150 [ 170.029025][ C0] ret_from_fork+0x474/0x6b0 [ 170.029026][ C0] ret_from_fork_asm+0x11/0x20 [ 170.029027][ C0] INITIAL USE at: [ 170.029028][ C0] __lock_acquire+0x388/0xc20 [ 170.029029][ C0] lock_acquire.part.0+0xd4/0x280 [ 170.029031][ C0] fs_reclaim_acquire+0xd5/0x120 [ 170.029032][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 170.029033][ C0] kthread_create_worker_on_node+0xea/0x210 [ 170.029034][ C0] workqueue_init+0x2a/0x680 [ 170.029036][ C0] kernel_init_freeable+0x2fe/0x630 [ 170.029037][ C0] kernel_init+0x21/0x150 [ 170.029038][ C0] ret_from_fork+0x474/0x6b0 [ 170.029040][ C0] ret_from_fork_asm+0x11/0x20 [ 170.029041][ C0] } [ 170.029042][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 170.029045][ C0] ... acquired at: [ 170.029046][ C0] __lock_acquire+0x518/0xc20 [ 170.029047][ C0] lock_acquire.part.0+0xd4/0x280 [ 170.029049][ C0] fs_reclaim_acquire+0xd5/0x120 [ 170.029050][ C0] __kmalloc_noprof+0xd3/0x760 [ 170.029051][ C0] alloc_buf.isra.0+0x4b/0x260 [ 170.029055][ C0] put_chars+0x1e1/0x2f0 [ 170.029056][ C0] hvc_console_print+0x292/0x780 [ 170.029058][ C0] console_emit_next_record+0x22f/0x4c0 [ 170.029060][ C0] console_flush_one_record+0x46f/0x710 [ 170.029062][ C0] console_unlock+0xee/0x1f0 [ 170.029063][ C0] vprintk_emit+0x37c/0x3e0 [ 170.029064][ C0] _printk+0xc7/0x100 [ 170.029067][ C0] sock_register+0x12d/0x290 [ 170.029070][ C0] inet6_init+0x1a6/0x400 [ 170.029073][ C0] do_one_initcall+0x124/0x4f0 [ 170.029074][ C0] kernel_init_freeable+0x596/0x630 [ 170.029076][ C0] kernel_init+0x21/0x150 [ 170.029077][ C0] ret_from_fork+0x474/0x6b0 [ 170.029078][ C0] ret_from_fork_asm+0x11/0x20 [ 170.029079][ C0] [ 170.029080][ C0] -> (console_owner){..-.}-{0:0} { [ 170.029081][ C0] IN-SOFTIRQ-W at: [ 170.029082][ C0] __lock_acquire+0x388/0xc20 [ 170.029084][ C0] lock_acquire.part.0+0xd4/0x280 [ 170.029085][ C0] console_lock_spinning_enable+0x5c/0x60 [ 170.029087][ C0] console_emit_next_record+0x1d1/0x4c0 [ 170.029088][ C0] console_flush_one_record+0x46f/0x710 [ 170.029090][ C0] console_unlock+0xee/0x1f0 [ 170.029091][ C0] vprintk_emit+0x37c/0x3e0 [ 170.029092][ C0] _printk+0xc7/0x100 [ 170.029094][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 170.029096][ C0] call_timer_fn+0x160/0x4d0 [ 170.029097][ C0] __run_timers+0x68f/0xaa0 [ 170.029099][ C0] run_timer_softirq+0xf0/0x160 [ 170.029101][ C0] handle_softirqs+0x1d3/0x900 [ 170.029103][ C0] __irq_exit_rcu+0x145/0x1c0 [ 170.029104][ C0] irq_exit_rcu+0xe/0x30 [ 170.029106][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 170.029107][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 170.029109][ C0] pv_native_safe_halt+0xf/0x10 [ 170.029110][ C0] default_idle+0x9/0x10 [ 170.029111][ C0] default_idle_call+0x6e/0xb0 [ 170.029113][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 170.029115][ C0] do_idle+0xd8/0x190 [ 170.029116][ C0] cpu_startup_entry+0x53/0x70 [ 170.029118][ C0] rest_init+0x279/0x280 [ 170.029119][ C0] start_kernel+0x3b9/0x3c0 [ 170.029120][ C0] x86_64_start_reservations+0x24/0x30 [ 170.029122][ C0] x86_64_start_kernel+0x12b/0x130 [ 170.029123][ C0] common_startup_64+0x13e/0x148 [ 170.029126][ C0] INITIAL USE at: [ 170.029127][ C0] } [ 170.029127][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 170.029134][ C0] ... acquired at: [ 170.029134][ C0] mark_lock+0x1d7/0xa00 [ 170.029136][ C0] mark_usage+0x42/0x170 [ 170.029137][ C0] __lock_acquire+0x388/0xc20 [ 170.029139][ C0] lock_acquire.part.0+0xd4/0x280 [ 170.029140][ C0] console_lock_spinning_enable+0x5c/0x60 [ 170.029142][ C0] console_emit_next_record+0x1d1/0x4c0 [ 170.029143][ C0] console_flush_one_record+0x46f/0x710 [ 170.029145][ C0] console_unlock+0xee/0x1f0 [ 170.029146][ C0] vprintk_emit+0x37c/0x3e0 [ 170.029147][ C0] _printk+0xc7/0x100 [ 170.029148][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 170.029149][ C0] call_timer_fn+0x160/0x4d0 [ 170.029151][ C0] __run_timers+0x68f/0xaa0 [ 170.029152][ C0] run_timer_softirq+0xf0/0x160 [ 170.029154][ C0] handle_softirqs+0x1d3/0x900 [ 170.029155][ C0] __irq_exit_rcu+0x145/0x1c0 [ 170.029156][ C0] irq_exit_rcu+0xe/0x30 [ 170.029158][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 170.029159][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 170.029160][ C0] pv_native_safe_halt+0xf/0x10 [ 170.029161][ C0] default_idle+0x9/0x10 [ 170.029162][ C0] default_idle_call+0x6e/0xb0 [ 170.029163][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 170.029165][ C0] do_idle+0xd8/0x190 [ 170.029166][ C0] cpu_startup_entry+0x53/0x70 [ 170.029167][ C0] rest_init+0x279/0x280 [ 170.029169][ C0] start_kernel+0x3b9/0x3c0 [ 170.029170][ C0] x86_64_start_reservations+0x24/0x30 [ 170.029171][ C0] x86_64_start_kernel+0x12b/0x130 [ 170.029172][ C0] common_startup_64+0x13e/0x148 [ 170.029173][ C0] [ 170.029174][ C0] [ 170.029174][ C0] stack backtrace: [ 170.029177][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 170.029180][ C0] Tainted: [W]=WARN [ 170.029181][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 170.029183][ C0] Call Trace: [ 170.029184][ C0] [ 170.029185][ C0] dump_stack_lvl+0x6f/0xa0 [ 170.029189][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 170.029192][ C0] mark_lock_irq+0x989/0x9c0 [ 170.029193][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 170.029196][ C0] mark_lock+0x1d7/0xa00 [ 170.029198][ C0] mark_usage+0x42/0x170 [ 170.029200][ C0] __lock_acquire+0x388/0xc20 [ 170.029202][ C0] lock_acquire.part.0+0xd4/0x280 [ 170.029204][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 170.029206][ C0] ? rcu_is_watching+0x16/0xd0 [ 170.029208][ C0] ? lock_acquire+0x13c/0x160 [ 170.029210][ C0] console_lock_spinning_enable+0x5c/0x60 [ 170.029212][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 170.029214][ C0] console_emit_next_record+0x1d1/0x4c0 [ 170.029216][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 170.029217][ C0] ? console_flush_one_record+0x106/0x710 [ 170.029219][ C0] ? rcu_is_watching+0x16/0xd0 [ 170.029221][ C0] ? lock_acquire+0x13c/0x160 [ 170.029223][ C0] console_flush_one_record+0x46f/0x710 [ 170.029225][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 170.029226][ C0] ? __lock_acquire+0x518/0xc20 [ 170.029229][ C0] console_unlock+0xee/0x1f0 [ 170.029231][ C0] ? console_flush_one_record+0x710/0x710 [ 170.029232][ C0] ? rcu_is_watching+0x16/0xd0 [ 170.029234][ C0] ? lock_acquire+0xe0/0x160 [ 170.029235][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 170.029237][ C0] ? vprintk_emit+0x320/0x3e0 [ 170.029239][ C0] vprintk_emit+0x37c/0x3e0 [ 170.029240][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 170.029242][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 170.029244][ C0] _printk+0xc7/0x100 [ 170.029245][ C0] ? snapshot_read.cold+0x21/0x21 [ 170.029247][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 170.029249][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 170.029252][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 170.029253][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 170.029255][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 170.029256][ C0] call_timer_fn+0x160/0x4d0 [ 170.029258][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 170.029260][ C0] ? debug_object_active_state+0x430/0x430 [ 170.029263][ C0] ? find_held_lock+0x2b/0x80 [ 170.029265][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 170.029267][ C0] ? rcu_is_watching+0x16/0xd0 [ 170.029268][ C0] __run_timers+0x68f/0xaa0 [ 170.029270][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 170.029272][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 170.029274][ C0] ? __lock_acquire+0x518/0xc20 [ 170.029277][ C0] ? __rwlock_init+0x150/0x150 [ 170.029279][ C0] run_timer_softirq+0xf0/0x160 [ 170.029281][ C0] ? __run_timers+0xaa0/0xaa0 [ 170.029283][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 170.029286][ C0] ? rcu_is_watching+0x16/0xd0 [ 170.029287][ C0] handle_softirqs+0x1d3/0x900 [ 170.029289][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 170.029290][ C0] ? _local_bh_enable+0xc0/0xc0 [ 170.029293][ C0] __irq_exit_rcu+0x145/0x1c0 [ 170.029294][ C0] irq_exit_rcu+0xe/0x30 [ 170.029296][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 170.029297][ C0] [ 170.029298][ C0] [ 170.029298][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 170.029300][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 170.029301][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 170.029304][ C0] Code: 48 8b 3d 94 92 68 02 e8 1f 00 00 00 48 2b 05 58 e3 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 16 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 [ 170.029306][ C0] RSP: 0018:ffffffff93807cf8 EFLAGS: 00000296 [ 170.029308][ C0] RAX: 00000000002451a7 RBX: ffffffff93830600 RCX: ffffffff8ff06247 [ 170.029309][ C0] RDX: ffffffff93830600 RSI: ffffffff93111042 RDI: ffffffff92a949e0 [ 170.029310][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 170.029311][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff2700fa2 [ 170.029311][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 170.029313][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 170.029315][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 170.029317][ C0] default_idle+0x9/0x10 [ 170.029318][ C0] default_idle_call+0x6e/0xb0 [ 170.029320][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 170.029321][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 170.029323][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 170.029325][ C0] ? rcu_is_watching+0x16/0xd0 [ 170.029327][ C0] do_idle+0xd8/0x190 [ 170.029328][ C0] cpu_startup_entry+0x53/0x70 [ 170.029330][ C0] rest_init+0x279/0x280 [ 170.029332][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 170.029334][ C0] ? rest_init+0x280/0x280 [ 170.029336][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 170.029339][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 170.029341][ C0] ? acpi_enable+0x1e4/0x330 [ 170.029343][ C0] start_kernel+0x3b9/0x3c0 [ 170.029345][ C0] x86_64_start_reservations+0x24/0x30 [ 170.029346][ C0] x86_64_start_kernel+0x12b/0x130 [ 170.029348][ C0] common_startup_64+0x13e/0x148 [ 170.029351][ C0] [ 170.029355][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 170.029357][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 170.029358][ C0] preempt_count: 103, expected: 0 [ 170.029359][ C0] RCU nest depth: 0, expected: 0 [ 170.029360][ C0] INFO: lockdep is turned off. [ 170.029360][ C0] irq event stamp: 2380215 [ 170.029361][ C0] hardirqs last enabled at (2380214): [] __down_trylock_console_sem+0x86/0xa0 [ 170.029364][ C0] hardirqs last disabled at (2380215): [] console_emit_next_record+0x3d4/0x4c0 [ 170.029366][ C0] softirqs last enabled at (2380182): [] handle_softirqs+0x67c/0x900 [ 170.029367][ C0] softirqs last disabled at (2380201): [] __irq_exit_rcu+0x145/0x1c0 [ 170.029369][ C0] Preemption disabled at: [ 170.029369][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 170.029373][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 170.029375][ C0] Tainted: [W]=WARN [ 170.029375][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 170.029376][ C0] Call Trace: [ 170.029376][ C0] [ 170.029377][ C0] dump_stack_lvl+0x6f/0xa0 [ 170.029379][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 170.029381][ C0] __might_resched.cold+0x1fe/0x2c1 [ 170.029384][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 170.029387][ C0] ? rcu_is_watching+0x16/0xd0 [ 170.029388][ C0] __kmalloc_noprof+0x443/0x760 [ 170.029390][ C0] ? __rwlock_init+0x150/0x150 [ 170.029391][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 170.029394][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 170.029395][ C0] alloc_buf.isra.0+0x4b/0x260 [ 170.029398][ C0] put_chars+0x1e1/0x2f0 [ 170.029400][ C0] ? __send_to_port+0x420/0x420 [ 170.029401][ C0] ? mark_lock+0x1d7/0xa00 [ 170.029403][ C0] hvc_console_print+0x292/0x780 [ 170.029407][ C0] ? hvc_write+0x3a0/0x3a0 [ 170.029408][ C0] ? rcu_is_watching+0x16/0xd0 [ 170.029409][ C0] ? rcu_is_watching+0x16/0xd0 [ 170.029411][ C0] console_emit_next_record+0x22f/0x4c0 [ 170.029413][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 170.029415][ C0] ? console_flush_one_record+0x106/0x710 [ 170.029417][ C0] ? rcu_is_watching+0x16/0xd0 [ 170.029418][ C0] ? lock_acquire+0x13c/0x160 [ 170.029420][ C0] console_flush_one_record+0x46f/0x710 [ 170.029422][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 170.029424][ C0] ? __lock_acquire+0x518/0xc20 [ 170.029426][ C0] console_unlock+0xee/0x1f0 [ 170.029428][ C0] ? console_flush_one_record+0x710/0x710 [ 170.029430][ C0] ? rcu_is_watching+0x16/0xd0 [ 170.029431][ C0] ? lock_acquire+0xe0/0x160 [ 170.029433][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 170.029434][ C0] ? vprintk_emit+0x320/0x3e0 [ 170.029435][ C0] vprintk_emit+0x37c/0x3e0 [ 170.029437][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 170.029439][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 170.029440][ C0] _printk+0xc7/0x100 [ 170.029442][ C0] ? snapshot_read.cold+0x21/0x21 [ 170.029444][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 170.029445][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 170.029448][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 170.029449][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 170.029451][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 170.029452][ C0] call_timer_fn+0x160/0x4d0 [ 170.029455][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 170.029456][ C0] ? debug_object_active_state+0x430/0x430 [ 170.029458][ C0] ? find_held_lock+0x2b/0x80 [ 170.029459][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 170.029461][ C0] ? rcu_is_watching+0x16/0xd0 [ 170.029463][ C0] __run_timers+0x68f/0xaa0 [ 170.029464][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 170.029466][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 170.029468][ C0] ? __lock_acquire+0x518/0xc20 [ 170.029471][ C0] ? __rwlock_init+0x150/0x150 [ 170.029473][ C0] run_timer_softirq+0xf0/0x160 [ 170.029475][ C0] ? __run_timers+0xaa0/0xaa0 [ 170.029477][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 170.029478][ C0] ? rcu_is_watching+0x16/0xd0 [ 170.029479][ C0] handle_softirqs+0x1d3/0x900 [ 170.029481][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 170.029483][ C0] ? _local_bh_enable+0xc0/0xc0 [ 170.029485][ C0] __irq_exit_rcu+0x145/0x1c0 [ 170.029486][ C0] irq_exit_rcu+0xe/0x30 [ 170.029488][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 170.029489][ C0] [ 170.029490][ C0] [ 170.029490][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 170.029492][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 170.029493][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 170.029495][ C0] Code: 48 8b 3d 94 92 68 02 e8 1f 00 00 00 48 2b 05 58 e3 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 16 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 [ 170.029496][ C0] RSP: 0018:ffffffff93807cf8 EFLAGS: 00000296 [ 170.029497][ C0] RAX: 00000000002451a7 RBX: ffffffff93830600 RCX: ffffffff8ff06247 [ 170.029498][ C0] RDX: ffffffff93830600 RSI: ffffffff93111042 RDI: ffffffff92a949e0 [ 170.029499][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 170.029499][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff2700fa2 [ 170.029500][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 170.029501][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 170.029504][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 170.029505][ C0] default_idle+0x9/0x10 [ 170.029506][ C0] default_idle_call+0x6e/0xb0 [ 170.029508][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 170.029509][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 170.029511][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 170.029512][ C0] ? rcu_is_watching+0x16/0xd0 [ 170.029514][ C0] do_idle+0xd8/0x190 [ 170.029515][ C0] cpu_startup_entry+0x53/0x70 [ 170.029517][ C0] rest_init+0x279/0x280 [ 170.029518][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 170.029521][ C0] ? rest_init+0x280/0x280 [ 170.029522][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 170.029524][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 170.029525][ C0] ? acpi_enable+0x1e4/0x330 [ 170.029527][ C0] start_kernel+0x3b9/0x3c0 [ 170.029529][ C0] x86_64_start_reservations+0x24/0x30 [ 170.029530][ C0] x86_64_start_kernel+0x12b/0x130 [ 170.029532][ C0] common_startup_64+0x13e/0x148 [ 170.029535][ C0]