[ 8.444680][ T140] netdevsim netdevsim1338 nsim1338: renamed from eth0 [ 8.444738][ T140] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 8.444741][ T140] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 140, name: ip [ 8.444742][ T140] preempt_count: 1, expected: 0 [ 8.444743][ T140] RCU nest depth: 0, expected: 0 [ 8.444744][ T140] locks held by ip/140: 6, last CPU#2: [ 8.444746][ T140] #0: ffffffffb2ccfcc0 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11b0 [ 8.444757][ T140] #1: ff1100000c71add0 (&dev_instance_lock_key#2){+.+.}-{4:4}, at: do_setlink.isra.0+0x27a/0x2a60 [ 8.444762][ T140] #2: ffffffffb2569cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 8.444767][ T140] #3: ffffffffb2569d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 8.444771][ T140] #4: ffffffffb2489660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 8.444774][ T140] #5: ffffffffb2489560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 8.444777][ T140] irq event stamp: 16578 [ 8.444778][ T140] hardirqs last enabled at (16577): [] __down_trylock_console_sem+0x86/0xa0 [ 8.444780][ T140] hardirqs last disabled at (16578): [] console_emit_next_record+0x3d4/0x4c0 [ 8.444782][ T140] softirqs last enabled at (16572): [] netif_change_name+0x216/0x8c0 [ 8.444785][ T140] softirqs last disabled at (16570): [] netif_change_name+0x1ad/0x8c0 [ 8.444786][ T140] Preemption disabled at: [ 8.444787][ T140] [] vprintk_emit+0x31b/0x3e0 [ 8.444792][ T140] CPU: 2 UID: 0 PID: 140 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 8.444795][ T140] Tainted: [W]=WARN [ 8.444796][ T140] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 8.444798][ T140] Call Trace: [ 8.444800][ T140] [ 8.444801][ T140] dump_stack_lvl+0x6f/0xa0 [ 8.444806][ T140] ? vprintk_emit+0x31b/0x3e0 [ 8.444808][ T140] __might_resched.cold+0x1fe/0x2c1 [ 8.444813][ T140] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 8.444817][ T140] ? __kmalloc_noprof+0xdb/0x760 [ 8.444822][ T140] __kmalloc_noprof+0x443/0x760 [ 8.444824][ T140] ? alloc_buf.isra.0+0x4b/0x260 [ 8.444830][ T140] ? do_raw_spin_unlock+0x59/0x250 [ 8.444832][ T140] alloc_buf.isra.0+0x4b/0x260 [ 8.444835][ T140] put_chars+0x1e1/0x2f0 [ 8.444838][ T140] ? __send_to_port+0x420/0x420 [ 8.444845][ T140] ? validate_chain+0x34a/0xc20 [ 8.444848][ T140] hvc_console_print+0x292/0x780 [ 8.444852][ T140] ? __lock_acquire+0x518/0xc20 [ 8.444853][ T140] ? __lock_acquire+0x518/0xc20 [ 8.444857][ T140] ? hvc_write+0x3a0/0x3a0 [ 8.444861][ T140] ? rcu_is_watching+0x16/0xd0 [ 8.444865][ T140] ? lock_acquire+0x13c/0x160 [ 8.444868][ T140] console_emit_next_record+0x22f/0x4c0 [ 8.444872][ T140] ? devkmsg_read+0x4b0/0x4b0 [ 8.444873][ T140] ? console_flush_one_record+0x106/0x710 [ 8.444876][ T140] ? rcu_is_watching+0x16/0xd0 [ 8.444878][ T140] ? lock_acquire+0x13c/0x160 [ 8.444882][ T140] console_flush_one_record+0x46f/0x710 [ 8.444885][ T140] ? console_emit_next_record+0x4c0/0x4c0 [ 8.444887][ T140] ? __lock_acquire+0x518/0xc20 [ 8.444892][ T140] console_unlock+0xee/0x1f0 [ 8.444894][ T140] ? console_flush_one_record+0x710/0x710 [ 8.444896][ T140] ? rcu_is_watching+0x16/0xd0 [ 8.444898][ T140] ? lock_acquire+0x60/0x160 [ 8.444901][ T140] ? __down_trylock_console_sem+0x5e/0xa0 [ 8.444902][ T140] ? vprintk_emit+0x320/0x3e0 [ 8.444905][ T140] vprintk_emit+0x37c/0x3e0 [ 8.444908][ T140] ? wake_up_klogd_work_func+0x90/0x90 [ 8.444910][ T140] ? __kernel_text_address+0x12/0x30 [ 8.444913][ T140] ? unwind_get_return_address+0x67/0xd0 [ 8.444917][ T140] ? arch_stack_walk+0xed/0x130 [ 8.444922][ T140] dev_vprintk_emit+0x27f/0x2c0 [ 8.444927][ T140] ? device_rename.cold+0xa/0xa [ 8.444935][ T140] dev_printk_emit+0xb9/0xee [ 8.444937][ T140] ? dev_vprintk_emit+0x2c0/0x2c0 [ 8.444941][ T140] ? check_prev_add+0x316/0xe90 [ 8.444946][ T140] __netdev_printk+0x160/0x1d0 [ 8.444951][ T140] netdev_info+0xe2/0x116 [ 8.444953][ T140] ? netdev_notice+0x120/0x120 [ 8.444955][ T140] ? find_held_lock+0x2b/0x80 [ 8.444959][ T140] ? __lock_release.isra.0+0x69/0x1a0 [ 8.444961][ T140] ? mark_held_locks+0x40/0x70 [ 8.444964][ T140] ? do_setlink.isra.0+0x1f7d/0x2a60 [ 8.444967][ T140] netif_change_name.cold+0x4f/0x89 [ 8.444972][ T140] ? netdev_adjacent_rename_links+0x470/0x470 [ 8.444974][ T140] ? __kernel_text_address+0x12/0x30 [ 8.444976][ T140] ? write_profile+0xf0/0xf0 [ 8.444980][ T140] ? __asan_memset+0x27/0x50 [ 8.444985][ T140] do_setlink.isra.0+0x1f7d/0x2a60 [ 8.444989][ T140] ? stack_trace_save+0x93/0xc0 [ 8.444991][ T140] ? rtnl_link_get_size+0x350/0x350 [ 8.444993][ T140] ? rcu_read_lock_any_held+0x66/0x90 [ 8.444995][ T140] ? stack_depot_save_flags+0x38e/0x790 [ 8.444999][ T140] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 8.445001][ T140] ? rcu_read_lock_any_held+0x3c/0x90 [ 8.445003][ T140] ? validate_chain+0x38b/0xc20 [ 8.445005][ T140] ? kasan_save_stack+0x3d/0x50 [ 8.445007][ T140] ? kasan_save_stack+0x2f/0x50 [ 8.445008][ T140] ? kasan_save_track+0x14/0x30 [ 8.445011][ T140] ? __lock_acquire+0x518/0xc20 [ 8.445012][ T140] ? do_one_broadcast+0xc9e/0x1130 [ 8.445016][ T140] ? ___sys_sendmsg+0x100/0x1d0 [ 8.445021][ T140] ? lock_acquire.part.0+0xd4/0x280 [ 8.445023][ T140] ? rtnl_newlink+0x9a8/0x11b0 [ 8.445025][ T140] ? rcu_is_watching+0x16/0xd0 [ 8.445027][ T140] ? lock_acquire+0x13c/0x160 [ 8.445029][ T140] ? rcu_is_watching+0x16/0xd0 [ 8.445030][ T140] ? rcu_is_watching+0x16/0xd0 [ 8.445032][ T140] ? trace_contention_end+0xb3/0x180 [ 8.445035][ T140] ? __mutex_lock+0x1db/0x1ea0 [ 8.445039][ T140] ? __mutex_lock+0x9a3/0x1ea0 [ 8.445042][ T140] ? rtnl_newlink+0x9a8/0x11b0 [ 8.445045][ T140] ? ww_mutex_lock+0x160/0x160 [ 8.445047][ T140] ? nla_get_range_signed+0x3d0/0x3d0 [ 8.445053][ T140] ? __rtnl_newlink+0x3fa/0xa50 [ 8.445058][ T140] rtnl_newlink+0x9f1/0x11b0 [ 8.445063][ T140] ? rtnl_bridge_getlink+0x850/0x850 [ 8.445065][ T140] ? __lock_acquire+0x518/0xc20 [ 8.445069][ T140] ? lock_acquire.part.0+0xd4/0x280 [ 8.445071][ T140] ? find_held_lock+0x2b/0x80 [ 8.445073][ T140] ? rtnl_bridge_getlink+0x850/0x850 [ 8.445075][ T140] ? __lock_release.isra.0+0x69/0x1a0 [ 8.445079][ T140] ? rtnl_bridge_getlink+0x850/0x850 [ 8.445081][ T140] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 8.445084][ T140] ? rtnl_link_fill+0x900/0x900 [ 8.445085][ T140] ? __lock_acquire+0x518/0xc20 [ 8.445089][ T140] ? lock_acquire.part.0+0xd4/0x280 [ 8.445091][ T140] ? find_held_lock+0x2b/0x80 [ 8.445095][ T140] netlink_rcv_skb+0x14e/0x3a0 [ 8.445097][ T140] ? rtnl_link_fill+0x900/0x900 [ 8.445100][ T140] ? netlink_ack+0xcd0/0xcd0 [ 8.445106][ T140] ? netlink_deliver_tap+0xc5/0x330 [ 8.445107][ T140] ? netlink_deliver_tap+0x13c/0x330 [ 8.445111][ T140] netlink_unicast+0x486/0x750 [ 8.445115][ T140] ? netlink_attachskb+0x810/0x810 [ 8.445118][ T140] ? __lock_acquire+0x518/0xc20 [ 8.445122][ T140] netlink_sendmsg+0x735/0xc60 [ 8.445125][ T140] ? netlink_unicast+0x750/0x750 [ 8.445129][ T140] ? __might_fault+0x97/0x140 [ 8.445134][ T140] ____sys_sendmsg+0x415/0x880 [ 8.445137][ T140] ? copy_msghdr_from_user+0x279/0x420 [ 8.445139][ T140] ? get_timestamp.constprop.0+0x3a0/0x3a0 [ 8.445141][ T140] ? move_addr_to_kernel+0x40/0x40 [ 8.445148][ T140] ___sys_sendmsg+0x14e/0x1d0 [ 8.445151][ T140] ? copy_msghdr_from_user+0x420/0x420 [ 8.445167][ T140] __sys_sendmsg+0x12c/0x1d0 [ 8.445170][ T140] ? __sys_sendmsg_sock+0x20/0x20 [ 8.445180][ T140] ? rcu_is_watching+0x16/0xd0 [ 8.445183][ T140] do_syscall_64+0xff/0x530 [ 8.445186][ T140] ? exc_page_fault+0xee/0x100 [ 8.445189][ T140] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 8.445191][ T140] RIP: 0033:0x7fd813d5154e [ 8.445195][ T140] 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 [ 8.445196][ T140] RSP: 002b:00007ffdc62018b0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 8.445199][ T140] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007fd813d5154e [ 8.445200][ T140] RDX: 0000000000000000 RSI: 00007ffdc6201960 RDI: 0000000000000003 [ 8.445201][ T140] RBP: 00007ffdc62018c0 R08: 0000000000000000 R09: 0000000000000000 [ 8.445202][ T140] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91252a [ 8.445203][ T140] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffdc6202030 [ 8.445209][ T140] [ 8.500529][ T140] ip (140) used greatest stack depth: 22888 bytes left [ 18.391972][ C0] clocksource: Watchdog remote CPU 3 read timed out [ 18.392011][ C0] [ 18.392013][ C0] ======================================================== [ 18.392014][ C0] WARNING: possible irq lock inversion dependency detected [ 18.392016][ C0] 7.2.0-virtme #1 Tainted: G W [ 18.392018][ C0] -------------------------------------------------------- [ 18.392018][ C0] swapper/0/0 just changed the state of lock: [ 18.392019][ C0] ffffffffb2489660 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 18.392032][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 18.392033][ C0] (fs_reclaim){+.+.}-{0:0} [ 18.392034][ C0] [ 18.392034][ C0] [ 18.392034][ C0] and interrupts could create inverse lock ordering between them. [ 18.392034][ C0] [ 18.392035][ C0] [ 18.392035][ C0] other info that might help us debug this: [ 18.392036][ C0] Possible interrupt unsafe locking scenario: [ 18.392036][ C0] [ 18.392036][ C0] CPU0 CPU1 [ 18.392037][ C0] ---- ---- [ 18.392037][ C0] lock(fs_reclaim); [ 18.392038][ C0] local_irq_disable(); [ 18.392039][ C0] lock(console_owner); [ 18.392039][ C0] lock(fs_reclaim); [ 18.392040][ C0] [ 18.392040][ C0] lock(console_owner); [ 18.392041][ C0] [ 18.392041][ C0] *** DEADLOCK *** [ 18.392041][ C0] [ 18.392042][ C0] locks held by swapper/0/0: 4, last CPU#0: [ 18.392043][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 18.392048][ C0] #1: ffffffffb25ce8b8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 18.392052][ C0] #2: ffffffffb2569cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 18.392055][ C0] #3: ffffffffb2569d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 18.392058][ C0] [ 18.392058][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 18.392062][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 18.392064][ C0] HARDIRQ-ON-W at: [ 18.392066][ C0] __lock_acquire+0x388/0xc20 [ 18.392068][ C0] lock_acquire.part.0+0xd4/0x280 [ 18.392069][ C0] fs_reclaim_acquire+0xd5/0x120 [ 18.392073][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 18.392076][ C0] kthread_create_worker_on_node+0xea/0x210 [ 18.392078][ C0] workqueue_init+0x2a/0x680 [ 18.392082][ C0] kernel_init_freeable+0x2fe/0x630 [ 18.392085][ C0] kernel_init+0x21/0x150 [ 18.392088][ C0] ret_from_fork+0x474/0x6b0 [ 18.392092][ C0] ret_from_fork_asm+0x11/0x20 [ 18.392094][ C0] SOFTIRQ-ON-W at: [ 18.392095][ C0] __lock_acquire+0x388/0xc20 [ 18.392096][ C0] lock_acquire.part.0+0xd4/0x280 [ 18.392098][ C0] fs_reclaim_acquire+0xd5/0x120 [ 18.392099][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 18.392100][ C0] kthread_create_worker_on_node+0xea/0x210 [ 18.392102][ C0] workqueue_init+0x2a/0x680 [ 18.392103][ C0] kernel_init_freeable+0x2fe/0x630 [ 18.392104][ C0] kernel_init+0x21/0x150 [ 18.392106][ C0] ret_from_fork+0x474/0x6b0 [ 18.392107][ C0] ret_from_fork_asm+0x11/0x20 [ 18.392108][ C0] INITIAL USE at: [ 18.392109][ C0] __lock_acquire+0x388/0xc20 [ 18.392110][ C0] lock_acquire.part.0+0xd4/0x280 [ 18.392111][ C0] fs_reclaim_acquire+0xd5/0x120 [ 18.392113][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 18.392114][ C0] kthread_create_worker_on_node+0xea/0x210 [ 18.392115][ C0] workqueue_init+0x2a/0x680 [ 18.392116][ C0] kernel_init_freeable+0x2fe/0x630 [ 18.392117][ C0] kernel_init+0x21/0x150 [ 18.392119][ C0] ret_from_fork+0x474/0x6b0 [ 18.392120][ C0] ret_from_fork_asm+0x11/0x20 [ 18.392121][ C0] } [ 18.392122][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 18.392126][ C0] ... acquired at: [ 18.392126][ C0] __lock_acquire+0x518/0xc20 [ 18.392128][ C0] lock_acquire.part.0+0xd4/0x280 [ 18.392129][ C0] fs_reclaim_acquire+0xd5/0x120 [ 18.392130][ C0] __kmalloc_noprof+0xd3/0x760 [ 18.392131][ C0] alloc_buf.isra.0+0x4b/0x260 [ 18.392134][ C0] put_chars+0x1e1/0x2f0 [ 18.392136][ C0] hvc_console_print+0x292/0x780 [ 18.392139][ C0] console_emit_next_record+0x22f/0x4c0 [ 18.392140][ C0] console_flush_one_record+0x46f/0x710 [ 18.392141][ C0] console_unlock+0xee/0x1f0 [ 18.392142][ C0] vprintk_emit+0x37c/0x3e0 [ 18.392144][ C0] dev_vprintk_emit+0x27f/0x2c0 [ 18.392147][ C0] dev_printk_emit+0xb9/0xee [ 18.392148][ C0] _dev_info+0xe2/0x116 [ 18.392149][ C0] __devm_rtc_register_device.cold+0x338/0x3a8 [ 18.392152][ C0] cmos_do_probe+0x73b/0x98a [ 18.392153][ C0] platform_probe+0xfe/0x1f0 [ 18.392155][ C0] call_driver_probe+0x61/0x1c0 [ 18.392157][ C0] really_probe+0x199/0x760 [ 18.392158][ C0] __driver_probe_device+0x24f/0x440 [ 18.392159][ C0] driver_probe_device+0x4a/0xf0 [ 18.392160][ C0] __driver_attach+0x1b8/0x540 [ 18.392161][ C0] bus_for_each_dev+0x130/0x1e0 [ 18.392163][ C0] bus_add_driver+0x2c8/0x530 [ 18.392164][ C0] driver_register+0x1a3/0x390 [ 18.392165][ C0] __platform_driver_probe+0x13f/0x270 [ 18.392167][ C0] cmos_init+0x31/0x40 [ 18.392169][ C0] do_one_initcall+0x124/0x4f0 [ 18.392171][ C0] kernel_init_freeable+0x596/0x630 [ 18.392173][ C0] kernel_init+0x21/0x150 [ 18.392174][ C0] ret_from_fork+0x474/0x6b0 [ 18.392175][ C0] ret_from_fork_asm+0x11/0x20 [ 18.392176][ C0] [ 18.392177][ C0] -> (console_owner){..-.}-{0:0} { [ 18.392179][ C0] IN-SOFTIRQ-W at: [ 18.392179][ C0] __lock_acquire+0x388/0xc20 [ 18.392180][ C0] lock_acquire.part.0+0xd4/0x280 [ 18.392181][ C0] console_lock_spinning_enable+0x5c/0x60 [ 18.392183][ C0] console_emit_next_record+0x1d1/0x4c0 [ 18.392184][ C0] console_flush_one_record+0x46f/0x710 [ 18.392185][ C0] console_unlock+0xee/0x1f0 [ 18.392187][ C0] vprintk_emit+0x37c/0x3e0 [ 18.392188][ C0] _printk+0xc7/0x100 [ 18.392191][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 18.392193][ C0] call_timer_fn+0x160/0x4d0 [ 18.392194][ C0] __run_timers+0x68f/0xaa0 [ 18.392195][ C0] run_timer_softirq+0xf0/0x160 [ 18.392197][ C0] handle_softirqs+0x1d3/0x900 [ 18.392199][ C0] __irq_exit_rcu+0x145/0x1c0 [ 18.392201][ C0] irq_exit_rcu+0xe/0x30 [ 18.392203][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 18.392204][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 18.392206][ C0] pv_native_safe_halt+0xf/0x10 [ 18.392207][ C0] default_idle+0x9/0x10 [ 18.392209][ C0] default_idle_call+0x6e/0xb0 [ 18.392210][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 18.392213][ C0] do_idle+0xd8/0x190 [ 18.392214][ C0] cpu_startup_entry+0x53/0x70 [ 18.392216][ C0] rest_init+0x279/0x280 [ 18.392217][ C0] start_kernel+0x3af/0x3b0 [ 18.392219][ C0] x86_64_start_reservations+0x24/0x30 [ 18.392220][ C0] x86_64_start_kernel+0x12b/0x130 [ 18.392221][ C0] common_startup_64+0x13e/0x148 [ 18.392224][ C0] INITIAL USE at: [ 18.392225][ C0] } [ 18.392226][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 18.392229][ C0] ... acquired at: [ 18.392229][ C0] mark_lock+0x1d7/0xa00 [ 18.392230][ C0] mark_usage+0x42/0x170 [ 18.392231][ C0] __lock_acquire+0x388/0xc20 [ 18.392232][ C0] lock_acquire.part.0+0xd4/0x280 [ 18.392233][ C0] console_lock_spinning_enable+0x5c/0x60 [ 18.392235][ C0] console_emit_next_record+0x1d1/0x4c0 [ 18.392236][ C0] console_flush_one_record+0x46f/0x710 [ 18.392237][ C0] console_unlock+0xee/0x1f0 [ 18.392238][ C0] vprintk_emit+0x37c/0x3e0 [ 18.392239][ C0] _printk+0xc7/0x100 [ 18.392241][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 18.392242][ C0] call_timer_fn+0x160/0x4d0 [ 18.392243][ C0] __run_timers+0x68f/0xaa0 [ 18.392244][ C0] run_timer_softirq+0xf0/0x160 [ 18.392245][ C0] handle_softirqs+0x1d3/0x900 [ 18.392247][ C0] __irq_exit_rcu+0x145/0x1c0 [ 18.392254][ C0] irq_exit_rcu+0xe/0x30 [ 18.392255][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 18.392256][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 18.392258][ C0] pv_native_safe_halt+0xf/0x10 [ 18.392259][ C0] default_idle+0x9/0x10 [ 18.392260][ C0] default_idle_call+0x6e/0xb0 [ 18.392261][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 18.392263][ C0] do_idle+0xd8/0x190 [ 18.392264][ C0] cpu_startup_entry+0x53/0x70 [ 18.392266][ C0] rest_init+0x279/0x280 [ 18.392267][ C0] start_kernel+0x3af/0x3b0 [ 18.392268][ C0] x86_64_start_reservations+0x24/0x30 [ 18.392269][ C0] x86_64_start_kernel+0x12b/0x130 [ 18.392270][ C0] common_startup_64+0x13e/0x148 [ 18.392272][ C0] [ 18.392272][ C0] [ 18.392272][ C0] stack backtrace: [ 18.392275][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 18.392278][ C0] Tainted: [W]=WARN [ 18.392279][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 18.392281][ C0] Call Trace: [ 18.392282][ C0] [ 18.392283][ C0] dump_stack_lvl+0x6f/0xa0 [ 18.392286][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 18.392289][ C0] mark_lock_irq+0x989/0x9c0 [ 18.392290][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 18.392294][ C0] mark_lock+0x1d7/0xa00 [ 18.392295][ C0] mark_usage+0x42/0x170 [ 18.392297][ C0] __lock_acquire+0x388/0xc20 [ 18.392298][ C0] lock_acquire.part.0+0xd4/0x280 [ 18.392300][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 18.392302][ C0] ? rcu_is_watching+0x16/0xd0 [ 18.392304][ C0] ? lock_acquire+0x13c/0x160 [ 18.392306][ C0] console_lock_spinning_enable+0x5c/0x60 [ 18.392307][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 18.392308][ C0] console_emit_next_record+0x1d1/0x4c0 [ 18.392310][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 18.392312][ C0] ? console_flush_one_record+0x106/0x710 [ 18.392313][ C0] ? rcu_is_watching+0x16/0xd0 [ 18.392315][ C0] ? lock_acquire+0x13c/0x160 [ 18.392316][ C0] console_flush_one_record+0x46f/0x710 [ 18.392318][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 18.392320][ C0] ? __lock_acquire+0x518/0xc20 [ 18.392322][ C0] console_unlock+0xee/0x1f0 [ 18.392323][ C0] ? console_flush_one_record+0x710/0x710 [ 18.392325][ C0] ? rcu_is_watching+0x16/0xd0 [ 18.392326][ C0] ? lock_acquire+0x60/0x160 [ 18.392327][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 18.392329][ C0] ? vprintk_emit+0x320/0x3e0 [ 18.392331][ C0] vprintk_emit+0x37c/0x3e0 [ 18.392332][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 18.392335][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 18.392336][ C0] _printk+0xc7/0x100 [ 18.392338][ C0] ? snapshot_read.cold+0x21/0x21 [ 18.392340][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 18.392341][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 18.392343][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 18.392344][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 18.392346][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 18.392347][ C0] call_timer_fn+0x160/0x4d0 [ 18.392349][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 18.392350][ C0] ? debug_object_active_state+0x430/0x430 [ 18.392353][ C0] ? find_held_lock+0x2b/0x80 [ 18.392355][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 18.392357][ C0] ? rcu_is_watching+0x16/0xd0 [ 18.392359][ C0] __run_timers+0x68f/0xaa0 [ 18.392360][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 18.392362][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 18.392363][ C0] ? __lock_acquire+0x518/0xc20 [ 18.392366][ C0] ? __rwlock_init+0x150/0x150 [ 18.392368][ C0] run_timer_softirq+0xf0/0x160 [ 18.392370][ C0] ? __run_timers+0xaa0/0xaa0 [ 18.392371][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 18.392374][ C0] ? rcu_is_watching+0x16/0xd0 [ 18.392376][ C0] handle_softirqs+0x1d3/0x900 [ 18.392378][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 18.392379][ C0] ? _local_bh_enable+0xc0/0xc0 [ 18.392382][ C0] __irq_exit_rcu+0x145/0x1c0 [ 18.392383][ C0] irq_exit_rcu+0xe/0x30 [ 18.392385][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 18.392387][ C0] [ 18.392387][ C0] [ 18.392388][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 18.392389][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 18.392391][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 18.392393][ C0] Code: 48 8b 3d 94 72 f5 01 e8 1f 00 00 00 48 2b 05 58 43 99 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 c6 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 18.392395][ C0] RSP: 0018:ffffffffb2207cf8 EFLAGS: 00000296 [ 18.392397][ C0] RAX: 0000000000075be3 RBX: ffffffffb221c600 RCX: ffffffffaf2ee307 [ 18.392398][ C0] RDX: ffffffffb221c600 RSI: ffffffffb206ef66 RDI: ffffffffb1a8d8e0 [ 18.392399][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 18.392400][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff6440fa2 [ 18.392400][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 18.392402][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 18.392404][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 18.392406][ C0] default_idle+0x9/0x10 [ 18.392407][ C0] default_idle_call+0x6e/0xb0 [ 18.392409][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 18.392411][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 18.392413][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 18.392415][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 18.392417][ C0] ? rcu_is_watching+0x16/0xd0 [ 18.392419][ C0] do_idle+0xd8/0x190 [ 18.392421][ C0] cpu_startup_entry+0x53/0x70 [ 18.392422][ C0] rest_init+0x279/0x280 [ 18.392424][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 18.392426][ C0] ? rest_init+0x280/0x280 [ 18.392428][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 18.392431][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 18.392433][ C0] ? acpi_enable+0x1e4/0x330 [ 18.392435][ C0] start_kernel+0x3af/0x3b0 [ 18.392437][ C0] x86_64_start_reservations+0x24/0x30 [ 18.392438][ C0] x86_64_start_kernel+0x12b/0x130 [ 18.392440][ C0] common_startup_64+0x13e/0x148 [ 18.392443][ C0] [ 18.392447][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 18.392449][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 18.392450][ C0] preempt_count: 103, expected: 0 [ 18.392451][ C0] RCU nest depth: 0, expected: 0 [ 18.392452][ C0] INFO: lockdep is turned off. [ 18.392452][ C0] irq event stamp: 482291 [ 18.392453][ C0] hardirqs last enabled at (482290): [] __down_trylock_console_sem+0x86/0xa0 [ 18.392455][ C0] hardirqs last disabled at (482291): [] console_emit_next_record+0x3d4/0x4c0 [ 18.392456][ C0] softirqs last enabled at (482258): [] handle_softirqs+0x67c/0x900 [ 18.392458][ C0] softirqs last disabled at (482277): [] __irq_exit_rcu+0x145/0x1c0 [ 18.392460][ C0] Preemption disabled at: [ 18.392461][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 18.392464][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 18.392466][ C0] Tainted: [W]=WARN [ 18.392466][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 18.392467][ C0] Call Trace: [ 18.392467][ C0] [ 18.392468][ C0] dump_stack_lvl+0x6f/0xa0 [ 18.392470][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 18.392471][ C0] __might_resched.cold+0x1fe/0x2c1 [ 18.392474][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 18.392477][ C0] ? rcu_is_watching+0x16/0xd0 [ 18.392479][ C0] __kmalloc_noprof+0x443/0x760 [ 18.392480][ C0] ? __rwlock_init+0x150/0x150 [ 18.392481][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 18.392484][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 18.392485][ C0] alloc_buf.isra.0+0x4b/0x260 [ 18.392487][ C0] put_chars+0x1e1/0x2f0 [ 18.392489][ C0] ? __send_to_port+0x420/0x420 [ 18.392492][ C0] ? mark_lock+0x1d7/0xa00 [ 18.392493][ C0] hvc_console_print+0x292/0x780 [ 18.392496][ C0] ? hvc_write+0x3a0/0x3a0 [ 18.392498][ C0] ? rcu_is_watching+0x16/0xd0 [ 18.392499][ C0] ? rcu_is_watching+0x16/0xd0 [ 18.392501][ C0] console_emit_next_record+0x22f/0x4c0 [ 18.392503][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 18.392504][ C0] ? console_flush_one_record+0x106/0x710 [ 18.392506][ C0] ? rcu_is_watching+0x16/0xd0 [ 18.392507][ C0] ? lock_acquire+0x13c/0x160 [ 18.392509][ C0] console_flush_one_record+0x46f/0x710 [ 18.392511][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 18.392512][ C0] ? __lock_acquire+0x518/0xc20 [ 18.392514][ C0] console_unlock+0xee/0x1f0 [ 18.392515][ C0] ? console_flush_one_record+0x710/0x710 [ 18.392517][ C0] ? rcu_is_watching+0x16/0xd0 [ 18.392518][ C0] ? lock_acquire+0x60/0x160 [ 18.392520][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 18.392521][ C0] ? vprintk_emit+0x320/0x3e0 [ 18.392523][ C0] vprintk_emit+0x37c/0x3e0 [ 18.392524][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 18.392526][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 18.392528][ C0] _printk+0xc7/0x100 [ 18.392529][ C0] ? snapshot_read.cold+0x21/0x21 [ 18.392531][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 18.392532][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 18.392535][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 18.392536][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 18.392537][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 18.392538][ C0] call_timer_fn+0x160/0x4d0 [ 18.392540][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 18.392541][ C0] ? debug_object_active_state+0x430/0x430 [ 18.392543][ C0] ? find_held_lock+0x2b/0x80 [ 18.392545][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 18.392546][ C0] ? rcu_is_watching+0x16/0xd0 [ 18.392548][ C0] __run_timers+0x68f/0xaa0 [ 18.392549][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 18.392551][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 18.392552][ C0] ? __lock_acquire+0x518/0xc20 [ 18.392555][ C0] ? __rwlock_init+0x150/0x150 [ 18.392557][ C0] run_timer_softirq+0xf0/0x160 [ 18.392558][ C0] ? __run_timers+0xaa0/0xaa0 [ 18.392560][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 18.392561][ C0] ? rcu_is_watching+0x16/0xd0 [ 18.392563][ C0] handle_softirqs+0x1d3/0x900 [ 18.392565][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 18.392566][ C0] ? _local_bh_enable+0xc0/0xc0 [ 18.392569][ C0] __irq_exit_rcu+0x145/0x1c0 [ 18.392570][ C0] irq_exit_rcu+0xe/0x30 [ 18.392572][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 18.392574][ C0] [ 18.392574][ C0] [ 18.392574][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 18.392576][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 18.392577][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 18.392579][ C0] Code: 48 8b 3d 94 72 f5 01 e8 1f 00 00 00 48 2b 05 58 43 99 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 c6 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 18.392580][ C0] RSP: 0018:ffffffffb2207cf8 EFLAGS: 00000296 [ 18.392581][ C0] RAX: 0000000000075be3 RBX: ffffffffb221c600 RCX: ffffffffaf2ee307 [ 18.392582][ C0] RDX: ffffffffb221c600 RSI: ffffffffb206ef66 RDI: ffffffffb1a8d8e0 [ 18.392583][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 18.392583][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff6440fa2 [ 18.392584][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 18.392585][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 18.392588][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 18.392589][ C0] default_idle+0x9/0x10 [ 18.392590][ C0] default_idle_call+0x6e/0xb0 [ 18.392592][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 18.392594][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 18.392595][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 18.392597][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 18.392599][ C0] ? rcu_is_watching+0x16/0xd0 [ 18.392600][ C0] do_idle+0xd8/0x190 [ 18.392602][ C0] cpu_startup_entry+0x53/0x70 [ 18.392604][ C0] rest_init+0x279/0x280 [ 18.392606][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 18.392608][ C0] ? rest_init+0x280/0x280 [ 18.392609][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 18.392611][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 18.392613][ C0] ? acpi_enable+0x1e4/0x330 [ 18.392614][ C0] start_kernel+0x3af/0x3b0 [ 18.392616][ C0] x86_64_start_reservations+0x24/0x30 [ 18.392617][ C0] x86_64_start_kernel+0x12b/0x130 [ 18.392619][ C0] common_startup_64+0x13e/0x148 [ 18.392622][ C0]