[ 10.306430][ T239] veth0: renamed from ioam-veth-alpha [ 10.306492][ T239] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.306494][ T239] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 239, name: ip [ 10.306495][ T239] preempt_count: 1, expected: 0 [ 10.306496][ T239] RCU nest depth: 0, expected: 0 [ 10.306497][ T239] locks held by ip/239: 5, last CPU#0: [ 10.306499][ T239] #0: ffffffffb2b1b740 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11b0 [ 10.306510][ T239] #1: ffffffffb237ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.306516][ T239] #2: ffffffffb237de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.306520][ T239] #3: ffffffffb229d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.306523][ T239] #4: ffffffffb229d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.306529][ T239] irq event stamp: 13086 [ 10.306530][ T239] hardirqs last enabled at (13085): [] __down_trylock_console_sem+0x86/0xa0 [ 10.306533][ T239] hardirqs last disabled at (13086): [] console_emit_next_record+0x3d4/0x4c0 [ 10.306535][ T239] softirqs last enabled at (13080): [] netif_change_name+0x216/0x8c0 [ 10.306538][ T239] softirqs last disabled at (13078): [] netif_change_name+0x1ad/0x8c0 [ 10.306541][ T239] Preemption disabled at: [ 10.306541][ T239] [] vprintk_emit+0x31b/0x3e0 [ 10.306546][ T239] CPU: 0 UID: 0 PID: 239 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.306549][ T239] Tainted: [W]=WARN [ 10.306550][ T239] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.306552][ T239] Call Trace: [ 10.306553][ T239] [ 10.306555][ T239] dump_stack_lvl+0x6f/0xa0 [ 10.306560][ T239] ? vprintk_emit+0x31b/0x3e0 [ 10.306562][ T239] __might_resched.cold+0x1fe/0x2c1 [ 10.306566][ T239] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.306570][ T239] ? __kmalloc_noprof+0xdb/0x760 [ 10.306575][ T239] __kmalloc_noprof+0x443/0x760 [ 10.306577][ T239] ? alloc_buf.isra.0+0x4b/0x260 [ 10.306583][ T239] ? do_raw_spin_unlock+0x59/0x250 [ 10.306585][ T239] alloc_buf.isra.0+0x4b/0x260 [ 10.306589][ T239] put_chars+0x1e1/0x2f0 [ 10.306591][ T239] ? __send_to_port+0x420/0x420 [ 10.306597][ T239] hvc_console_print+0x292/0x780 [ 10.306605][ T239] ? hvc_write+0x3a0/0x3a0 [ 10.306608][ T239] ? lock_acquire+0x13c/0x160 [ 10.306612][ T239] console_emit_next_record+0x22f/0x4c0 [ 10.306615][ T239] ? devkmsg_read+0x4b0/0x4b0 [ 10.306617][ T239] ? console_flush_one_record+0x106/0x710 [ 10.306620][ T239] ? rcu_is_watching+0x16/0xd0 [ 10.306623][ T239] ? lock_acquire+0x13c/0x160 [ 10.306627][ T239] console_flush_one_record+0x46f/0x710 [ 10.306631][ T239] ? console_emit_next_record+0x4c0/0x4c0 [ 10.306633][ T239] ? __lock_acquire+0x518/0xc20 [ 10.306638][ T239] console_unlock+0xee/0x1f0 [ 10.306640][ T239] ? console_flush_one_record+0x710/0x710 [ 10.306642][ T239] ? rcu_is_watching+0x16/0xd0 [ 10.306644][ T239] ? lock_acquire+0xe0/0x160 [ 10.306648][ T239] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.306649][ T239] ? vprintk_emit+0x320/0x3e0 [ 10.306652][ T239] vprintk_emit+0x37c/0x3e0 [ 10.306655][ T239] ? wake_up_klogd_work_func+0x90/0x90 [ 10.306657][ T239] ? save_trace+0x68/0x280 [ 10.306661][ T239] _printk+0xc7/0x100 [ 10.306665][ T239] ? snapshot_read.cold+0x21/0x21 [ 10.306668][ T239] ? validate_chain+0x34a/0xc20 [ 10.306670][ T239] ? netdev_name+0x43/0x60 [ 10.306673][ T239] ? __netdev_printk+0x17f/0x1d0 [ 10.306676][ T239] ? do_setlink.isra.0+0x1f7d/0x2a60 [ 10.306678][ T239] netdev_info+0xe2/0x116 [ 10.306680][ T239] ? netdev_notice+0x120/0x120 [ 10.306683][ T239] ? find_held_lock+0x2b/0x80 [ 10.306686][ T239] ? __lock_release.isra.0+0x69/0x1a0 [ 10.306689][ T239] ? mark_held_locks+0x40/0x70 [ 10.306692][ T239] ? do_setlink.isra.0+0x1f7d/0x2a60 [ 10.306694][ T239] netif_change_name.cold+0x4f/0x89 [ 10.306696][ T239] ? is_bpf_text_address+0x68/0x110 [ 10.306701][ T239] ? netdev_adjacent_rename_links+0x470/0x470 [ 10.306704][ T239] ? __kernel_text_address+0x12/0x30 [ 10.306706][ T239] ? write_profile+0xf0/0xf0 [ 10.306710][ T239] ? __asan_memset+0x27/0x50 [ 10.306716][ T239] do_setlink.isra.0+0x1f7d/0x2a60 [ 10.306719][ T239] ? stack_trace_save+0x93/0xc0 [ 10.306721][ T239] ? rtnl_link_get_size+0x350/0x350 [ 10.306722][ T239] ? rcu_read_lock_any_held+0x66/0x90 [ 10.306725][ T239] ? stack_depot_save_flags+0x38e/0x790 [ 10.306728][ T239] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.306730][ T239] ? rcu_read_lock_any_held+0x3c/0x90 [ 10.306732][ T239] ? validate_chain+0x38b/0xc20 [ 10.306734][ T239] ? kasan_save_stack+0x3d/0x50 [ 10.306736][ T239] ? kasan_save_stack+0x2f/0x50 [ 10.306737][ T239] ? kasan_save_track+0x14/0x30 [ 10.306738][ T239] ? __kasan_kmalloc+0x7b/0x90 [ 10.306740][ T239] ? __lock_acquire+0x518/0xc20 [ 10.306742][ T239] ? snd_ac97_proc_read_main+0x4de/0x1f00 [ 10.306745][ T239] ? ___sys_sendmsg+0xd0/0x1d0 [ 10.306749][ T239] ? lock_acquire.part.0+0xd4/0x280 [ 10.306750][ T239] ? rtnl_newlink+0x9a8/0x11b0 [ 10.306753][ T239] ? rcu_is_watching+0x16/0xd0 [ 10.306755][ T239] ? lock_acquire+0x13c/0x160 [ 10.306756][ T239] ? rcu_is_watching+0x16/0xd0 [ 10.306757][ T239] ? rcu_is_watching+0x16/0xd0 [ 10.306759][ T239] ? trace_contention_end+0xb3/0x180 [ 10.306761][ T239] ? __mutex_lock+0x1db/0x1ea0 [ 10.306764][ T239] ? __mutex_lock+0x9a3/0x1ea0 [ 10.306766][ T239] ? rtnl_newlink+0x9a8/0x11b0 [ 10.306769][ T239] ? ww_mutex_lock+0x160/0x160 [ 10.306770][ T239] ? nla_get_range_signed+0x3d0/0x3d0 [ 10.306775][ T239] ? rcu_is_watching+0x16/0xd0 [ 10.306777][ T239] ? __rtnl_newlink+0x3fa/0xa50 [ 10.306782][ T239] rtnl_newlink+0x9f1/0x11b0 [ 10.306787][ T239] ? rtnl_bridge_getlink+0x850/0x850 [ 10.306789][ T239] ? __lock_acquire+0x518/0xc20 [ 10.306794][ T239] ? lock_acquire.part.0+0xd4/0x280 [ 10.306796][ T239] ? find_held_lock+0x2b/0x80 [ 10.306798][ T239] ? rtnl_bridge_getlink+0x850/0x850 [ 10.306799][ T239] ? __lock_release.isra.0+0x69/0x1a0 [ 10.306803][ T239] ? rtnl_bridge_getlink+0x850/0x850 [ 10.306805][ T239] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 10.306808][ T239] ? rtnl_link_fill+0x920/0x920 [ 10.306809][ T239] ? __lock_acquire+0x518/0xc20 [ 10.306814][ T239] ? lock_acquire.part.0+0xd4/0x280 [ 10.306815][ T239] ? find_held_lock+0x2b/0x80 [ 10.306819][ T239] netlink_rcv_skb+0x14e/0x3a0 [ 10.306822][ T239] ? rtnl_link_fill+0x920/0x920 [ 10.306825][ T239] ? netlink_ack+0xcf0/0xcf0 [ 10.306831][ T239] ? netlink_deliver_tap+0xc5/0x330 [ 10.306833][ T239] ? netlink_deliver_tap+0x13c/0x330 [ 10.306837][ T239] netlink_unicast+0x486/0x750 [ 10.306841][ T239] ? netlink_attachskb+0x810/0x810 [ 10.306843][ T239] ? find_held_lock+0x2b/0x80 [ 10.306848][ T239] netlink_sendmsg+0x75b/0xc90 [ 10.306851][ T239] ? netlink_unicast+0x750/0x750 [ 10.306855][ T239] ? __lock_release.isra.0+0x69/0x1a0 [ 10.306857][ T239] ? __import_iovec+0x36c/0x620 [ 10.306862][ T239] __sock_sendmsg+0xce/0x190 [ 10.306865][ T239] ____sys_sendmsg+0x61a/0x860 [ 10.306867][ T239] ? copy_msghdr_from_user+0x279/0x420 [ 10.306869][ T239] ? sock_write_iter+0x400/0x400 [ 10.306871][ T239] ? move_addr_to_kernel+0x40/0x40 [ 10.306878][ T239] ___sys_sendmsg+0x14e/0x1d0 [ 10.306881][ T239] ? copy_msghdr_from_user+0x420/0x420 [ 10.306896][ T239] __sys_sendmsg+0x12c/0x1d0 [ 10.306899][ T239] ? __sys_sendmsg_sock+0x20/0x20 [ 10.306905][ T239] ? rcu_is_watching+0x16/0xd0 [ 10.306907][ T239] do_syscall_64+0xff/0x530 [ 10.306911][ T239] ? exc_page_fault+0xee/0x100 [ 10.306913][ T239] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.306915][ T239] RIP: 0033:0x7f2a336f054e [ 10.306919][ T239] 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 [ 10.306921][ T239] RSP: 002b:00007ffc307c9280 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 10.306923][ T239] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f2a336f054e [ 10.306925][ T239] RDX: 0000000000000000 RSI: 00007ffc307c9330 RDI: 0000000000000005 [ 10.306926][ T239] RBP: 00007ffc307c9290 R08: 0000000000000000 R09: 0000000000000000 [ 10.306926][ T239] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a90edeb [ 10.306927][ T239] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffc307c9a00 [ 10.306934][ T239] [ 10.351703][ T239] ip (239) used greatest stack depth: 23208 bytes left [ 10.380647][ T240] veth0: renamed from ioam-veth-betaL [ 10.412323][ T241] veth1: renamed from ioam-veth-betaR [ 10.438610][ T242] veth0: renamed from ioam-veth-gamma [ 80.000147][ C0] clocksource: Watchdog remote CPU 2 read timed out [ 80.000195][ C0] [ 80.000196][ C0] ======================================================== [ 80.000197][ C0] WARNING: possible irq lock inversion dependency detected [ 80.000199][ C0] 7.2.0-virtme #1 Tainted: G W [ 80.000201][ C0] -------------------------------------------------------- [ 80.000201][ C0] swapper/0/0 just changed the state of lock: [ 80.000202][ C0] ffffffffb229d760 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 80.000214][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 80.000216][ C0] (fs_reclaim){+.+.}-{0:0} [ 80.000217][ C0] [ 80.000217][ C0] [ 80.000217][ C0] and interrupts could create inverse lock ordering between them. [ 80.000217][ C0] [ 80.000218][ C0] [ 80.000218][ C0] other info that might help us debug this: [ 80.000218][ C0] Possible interrupt unsafe locking scenario: [ 80.000218][ C0] [ 80.000219][ C0] CPU0 CPU1 [ 80.000219][ C0] ---- ---- [ 80.000220][ C0] lock(fs_reclaim); [ 80.000221][ C0] local_irq_disable(); [ 80.000221][ C0] lock(console_owner); [ 80.000222][ C0] lock(fs_reclaim); [ 80.000223][ C0] [ 80.000223][ C0] lock(console_owner); [ 80.000224][ C0] [ 80.000224][ C0] *** DEADLOCK *** [ 80.000224][ C0] [ 80.000224][ C0] locks held by swapper/0/0: 4, last CPU#0: [ 80.000226][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 80.000231][ C0] #1: ffffffffb23e29f8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 80.000235][ C0] #2: ffffffffb237ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 80.000237][ C0] #3: ffffffffb237de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 80.000241][ C0] [ 80.000241][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 80.000245][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 80.000247][ C0] HARDIRQ-ON-W at: [ 80.000249][ C0] __lock_acquire+0x388/0xc20 [ 80.000252][ C0] lock_acquire.part.0+0xd4/0x280 [ 80.000254][ C0] fs_reclaim_acquire+0xd5/0x120 [ 80.000257][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 80.000259][ C0] kthread_create_worker_on_node+0xea/0x210 [ 80.000262][ C0] workqueue_init+0x2a/0x680 [ 80.000266][ C0] kernel_init_freeable+0x2fe/0x630 [ 80.000269][ C0] kernel_init+0x21/0x150 [ 80.000272][ C0] ret_from_fork+0x474/0x6b0 [ 80.000276][ C0] ret_from_fork_asm+0x11/0x20 [ 80.000279][ C0] SOFTIRQ-ON-W at: [ 80.000279][ C0] __lock_acquire+0x388/0xc20 [ 80.000281][ C0] lock_acquire.part.0+0xd4/0x280 [ 80.000282][ C0] fs_reclaim_acquire+0xd5/0x120 [ 80.000283][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 80.000284][ C0] kthread_create_worker_on_node+0xea/0x210 [ 80.000286][ C0] workqueue_init+0x2a/0x680 [ 80.000287][ C0] kernel_init_freeable+0x2fe/0x630 [ 80.000289][ C0] kernel_init+0x21/0x150 [ 80.000290][ C0] ret_from_fork+0x474/0x6b0 [ 80.000291][ C0] ret_from_fork_asm+0x11/0x20 [ 80.000292][ C0] INITIAL USE at: [ 80.000293][ C0] __lock_acquire+0x388/0xc20 [ 80.000295][ C0] lock_acquire.part.0+0xd4/0x280 [ 80.000296][ C0] fs_reclaim_acquire+0xd5/0x120 [ 80.000297][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 80.000298][ C0] kthread_create_worker_on_node+0xea/0x210 [ 80.000300][ C0] workqueue_init+0x2a/0x680 [ 80.000301][ C0] kernel_init_freeable+0x2fe/0x630 [ 80.000303][ C0] kernel_init+0x21/0x150 [ 80.000304][ C0] ret_from_fork+0x474/0x6b0 [ 80.000305][ C0] ret_from_fork_asm+0x11/0x20 [ 80.000306][ C0] } [ 80.000307][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 80.000310][ C0] ... acquired at: [ 80.000311][ C0] __lock_acquire+0x518/0xc20 [ 80.000313][ C0] lock_acquire.part.0+0xd4/0x280 [ 80.000314][ C0] fs_reclaim_acquire+0xd5/0x120 [ 80.000315][ C0] __kmalloc_noprof+0xd3/0x760 [ 80.000316][ C0] alloc_buf.isra.0+0x4b/0x260 [ 80.000320][ C0] put_chars+0x1e1/0x2f0 [ 80.000321][ C0] hvc_console_print+0x292/0x780 [ 80.000324][ C0] console_emit_next_record+0x22f/0x4c0 [ 80.000326][ C0] console_flush_one_record+0x46f/0x710 [ 80.000327][ C0] console_unlock+0xee/0x1f0 [ 80.000329][ C0] vprintk_emit+0x37c/0x3e0 [ 80.000329][ C0] dev_vprintk_emit+0x27f/0x2c0 [ 80.000332][ C0] dev_printk_emit+0xb9/0xee [ 80.000334][ C0] _dev_info+0xe2/0x116 [ 80.000335][ C0] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 80.000338][ C0] cmos_do_probe+0x73b/0x98a [ 80.000340][ C0] platform_probe+0xfe/0x1f0 [ 80.000342][ C0] call_driver_probe+0x61/0x1c0 [ 80.000344][ C0] really_probe+0x199/0x760 [ 80.000345][ C0] __driver_probe_device+0x24f/0x440 [ 80.000346][ C0] driver_probe_device+0x4a/0xf0 [ 80.000355][ C0] __driver_attach+0x1b8/0x540 [ 80.000356][ C0] bus_for_each_dev+0x130/0x1e0 [ 80.000358][ C0] bus_add_driver+0x2c8/0x530 [ 80.000359][ C0] driver_register+0x1a3/0x390 [ 80.000360][ C0] __platform_driver_probe+0x13f/0x270 [ 80.000362][ C0] cmos_init+0x31/0x40 [ 80.000364][ C0] do_one_initcall+0x124/0x4f0 [ 80.000366][ C0] kernel_init_freeable+0x596/0x630 [ 80.000367][ C0] kernel_init+0x21/0x150 [ 80.000368][ C0] ret_from_fork+0x474/0x6b0 [ 80.000369][ C0] ret_from_fork_asm+0x11/0x20 [ 80.000371][ C0] [ 80.000371][ C0] -> (console_owner){..-.}-{0:0} { [ 80.000373][ C0] IN-SOFTIRQ-W at: [ 80.000374][ C0] __lock_acquire+0x388/0xc20 [ 80.000375][ C0] lock_acquire.part.0+0xd4/0x280 [ 80.000376][ C0] console_lock_spinning_enable+0x5c/0x60 [ 80.000378][ C0] console_emit_next_record+0x1d1/0x4c0 [ 80.000380][ C0] console_flush_one_record+0x46f/0x710 [ 80.000381][ C0] console_unlock+0xee/0x1f0 [ 80.000383][ C0] vprintk_emit+0x37c/0x3e0 [ 80.000384][ C0] _printk+0xc7/0x100 [ 80.000386][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 80.000389][ C0] call_timer_fn+0x160/0x4d0 [ 80.000391][ C0] __run_timers+0x68f/0xaa0 [ 80.000392][ C0] run_timer_softirq+0xf0/0x160 [ 80.000394][ C0] handle_softirqs+0x1d3/0x900 [ 80.000396][ C0] __irq_exit_rcu+0x145/0x1c0 [ 80.000397][ C0] irq_exit_rcu+0xe/0x30 [ 80.000399][ C0] common_interrupt+0xb5/0xf0 [ 80.000400][ C0] asm_common_interrupt+0x26/0x40 [ 80.000402][ C0] lock_is_held_type+0x11d/0x1a0 [ 80.000403][ C0] schedule_debug+0x21b/0x3c0 [ 80.000406][ C0] __schedule+0x11b/0x20e0 [ 80.000408][ C0] schedule_idle+0x59/0x90 [ 80.000409][ C0] cpu_startup_entry+0x53/0x70 [ 80.000412][ C0] rest_init+0x279/0x280 [ 80.000413][ C0] start_kernel+0x3b9/0x3c0 [ 80.000414][ C0] x86_64_start_reservations+0x24/0x30 [ 80.000416][ C0] x86_64_start_kernel+0x12b/0x130 [ 80.000417][ C0] common_startup_64+0x13e/0x148 [ 80.000420][ C0] INITIAL USE at: [ 80.000421][ C0] } [ 80.000421][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 80.000423][ C0] ... acquired at: [ 80.000424][ C0] mark_lock+0x1d7/0xa00 [ 80.000425][ C0] mark_usage+0x42/0x170 [ 80.000426][ C0] __lock_acquire+0x388/0xc20 [ 80.000428][ C0] lock_acquire.part.0+0xd4/0x280 [ 80.000429][ C0] console_lock_spinning_enable+0x5c/0x60 [ 80.000432][ C0] console_emit_next_record+0x1d1/0x4c0 [ 80.000434][ C0] console_flush_one_record+0x46f/0x710 [ 80.000436][ C0] console_unlock+0xee/0x1f0 [ 80.000438][ C0] vprintk_emit+0x37c/0x3e0 [ 80.000439][ C0] _printk+0xc7/0x100 [ 80.000441][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 80.000443][ C0] call_timer_fn+0x160/0x4d0 [ 80.000445][ C0] __run_timers+0x68f/0xaa0 [ 80.000447][ C0] run_timer_softirq+0xf0/0x160 [ 80.000449][ C0] handle_softirqs+0x1d3/0x900 [ 80.000451][ C0] __irq_exit_rcu+0x145/0x1c0 [ 80.000452][ C0] irq_exit_rcu+0xe/0x30 [ 80.000454][ C0] common_interrupt+0xb5/0xf0 [ 80.000456][ C0] asm_common_interrupt+0x26/0x40 [ 80.000457][ C0] lock_is_held_type+0x11d/0x1a0 [ 80.000459][ C0] schedule_debug+0x21b/0x3c0 [ 80.000461][ C0] __schedule+0x11b/0x20e0 [ 80.000463][ C0] schedule_idle+0x59/0x90 [ 80.000465][ C0] cpu_startup_entry+0x53/0x70 [ 80.000467][ C0] rest_init+0x279/0x280 [ 80.000468][ C0] start_kernel+0x3b9/0x3c0 [ 80.000470][ C0] x86_64_start_reservations+0x24/0x30 [ 80.000472][ C0] x86_64_start_kernel+0x12b/0x130 [ 80.000474][ C0] common_startup_64+0x13e/0x148 [ 80.000476][ C0] [ 80.000476][ C0] [ 80.000476][ C0] stack backtrace: [ 80.000480][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 80.000483][ C0] Tainted: [W]=WARN [ 80.000485][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 80.000487][ C0] Call Trace: [ 80.000488][ C0] [ 80.000490][ C0] dump_stack_lvl+0x6f/0xa0 [ 80.000495][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 80.000498][ C0] mark_lock_irq+0x989/0x9c0 [ 80.000501][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 80.000505][ C0] mark_lock+0x1d7/0xa00 [ 80.000508][ C0] mark_usage+0x42/0x170 [ 80.000510][ C0] __lock_acquire+0x388/0xc20 [ 80.000513][ C0] lock_acquire.part.0+0xd4/0x280 [ 80.000516][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 80.000519][ C0] ? rcu_is_watching+0x16/0xd0 [ 80.000521][ C0] ? lock_acquire+0x13c/0x160 [ 80.000528][ C0] console_lock_spinning_enable+0x5c/0x60 [ 80.000531][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 80.000533][ C0] console_emit_next_record+0x1d1/0x4c0 [ 80.000536][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 80.000539][ C0] ? console_flush_one_record+0x106/0x710 [ 80.000541][ C0] ? rcu_is_watching+0x16/0xd0 [ 80.000543][ C0] ? lock_acquire+0x13c/0x160 [ 80.000546][ C0] console_flush_one_record+0x46f/0x710 [ 80.000549][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 80.000552][ C0] ? __lock_acquire+0x518/0xc20 [ 80.000555][ C0] console_unlock+0xee/0x1f0 [ 80.000558][ C0] ? console_flush_one_record+0x710/0x710 [ 80.000561][ C0] ? rcu_is_watching+0x16/0xd0 [ 80.000563][ C0] ? lock_acquire+0xe0/0x160 [ 80.000566][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 80.000568][ C0] ? vprintk_emit+0x320/0x3e0 [ 80.000570][ C0] vprintk_emit+0x37c/0x3e0 [ 80.000573][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 80.000576][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 80.000579][ C0] _printk+0xc7/0x100 [ 80.000581][ C0] ? snapshot_read.cold+0x21/0x21 [ 80.000584][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 80.000586][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 80.000590][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 80.000592][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 80.000595][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 80.000597][ C0] call_timer_fn+0x160/0x4d0 [ 80.000600][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 80.000603][ C0] ? debug_object_active_state+0x430/0x430 [ 80.000607][ C0] ? find_held_lock+0x2b/0x80 [ 80.000610][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 80.000612][ C0] ? rcu_is_watching+0x16/0xd0 [ 80.000615][ C0] __run_timers+0x68f/0xaa0 [ 80.000617][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 80.000621][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 80.000623][ C0] ? __lock_acquire+0x518/0xc20 [ 80.000626][ C0] ? trace_rcu_watching+0x20/0x1f0 [ 80.000631][ C0] ? __rwlock_init+0x150/0x150 [ 80.000635][ C0] run_timer_softirq+0xf0/0x160 [ 80.000638][ C0] ? __run_timers+0xaa0/0xaa0 [ 80.000641][ C0] ? handle_softirqs+0x19c/0x900 [ 80.000643][ C0] ? rcu_is_watching+0x16/0xd0 [ 80.000645][ C0] handle_softirqs+0x1d3/0x900 [ 80.000648][ C0] ? _local_bh_enable+0xc0/0xc0 [ 80.000651][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 80.000654][ C0] __irq_exit_rcu+0x145/0x1c0 [ 80.000656][ C0] irq_exit_rcu+0xe/0x30 [ 80.000658][ C0] common_interrupt+0xb5/0xf0 [ 80.000661][ C0] [ 80.000661][ C0] [ 80.000663][ C0] asm_common_interrupt+0x26/0x40 [ 80.000665][ C0] RIP: 0010:lock_is_held_type+0x11d/0x1a0 [ 80.000668][ C0] Code: ff ff ff 65 0f c1 15 12 8e 68 02 83 fa 01 8b 44 24 04 75 55 9c 5a 80 e6 02 75 6f 41 f7 c6 00 02 00 00 74 2c fb 48 8b 5c 24 08 <4c> 8b 74 24 28 4c 8b 7c 24 30 48 8b 6c 24 10 48 83 c4 38 c3 b8 ff [ 80.000670][ C0] RSP: 0018:ffffffffb2007c38 EFLAGS: 00000206 [ 80.000673][ C0] RAX: 0000000000000000 RBX: ffffffffb2030600 RCX: 0000000000000000 [ 80.000675][ C0] RDX: 0000000000000046 RSI: ffffffffb18f9f34 RDI: ffffffffb12949e0 [ 80.000676][ C0] RBP: ffffffffb2030600 R08: 0000000000000001 R09: 1ffffffff65aa89b [ 80.000678][ C0] R10: 0000000000000000 R11: fffffbfff65aa89c R12: 0000000000000000 [ 80.000679][ C0] R13: ff1100006c038290 R14: 0000000000000246 R15: 00000000ffffffff [ 80.000683][ C0] ? rcu_is_watching+0x16/0xd0 [ 80.000685][ C0] schedule_debug+0x21b/0x3c0 [ 80.000688][ C0] __schedule+0x11b/0x20e0 [ 80.000691][ C0] ? trace_rcu_watching+0xd1/0x1f0 [ 80.000693][ C0] ? ct_kernel_enter_state+0x14/0x50 [ 80.000695][ C0] ? rcu_is_watching+0x16/0xd0 [ 80.000697][ C0] ? trace_cpu_idle+0xb3/0x1b0 [ 80.000700][ C0] ? io_schedule_timeout+0x140/0x140 [ 80.000702][ C0] ? default_idle_call+0x98/0xb0 [ 80.000705][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 80.000707][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 80.000711][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 80.000715][ C0] schedule_idle+0x59/0x90 [ 80.000718][ C0] cpu_startup_entry+0x53/0x70 [ 80.000720][ C0] rest_init+0x279/0x280 [ 80.000723][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 80.000726][ C0] ? rest_init+0x280/0x280 [ 80.000729][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 80.000733][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 80.000736][ C0] ? acpi_enable+0x1e4/0x330 [ 80.000739][ C0] start_kernel+0x3b9/0x3c0 [ 80.000742][ C0] x86_64_start_reservations+0x24/0x30 [ 80.000744][ C0] x86_64_start_kernel+0x12b/0x130 [ 80.000746][ C0] common_startup_64+0x13e/0x148 [ 80.000751][ C0] [ 80.000756][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 80.000758][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 80.000760][ C0] preempt_count: 103, expected: 0 [ 80.000761][ C0] RCU nest depth: 0, expected: 0 [ 80.000762][ C0] INFO: lockdep is turned off. [ 80.000763][ C0] irq event stamp: 4900365 [ 80.000764][ C0] hardirqs last enabled at (4900364): [] __down_trylock_console_sem+0x86/0xa0 [ 80.000768][ C0] hardirqs last disabled at (4900365): [] console_emit_next_record+0x3d4/0x4c0 [ 80.000770][ C0] softirqs last enabled at (4900312): [] handle_softirqs+0x67c/0x900 [ 80.000773][ C0] softirqs last disabled at (4900317): [] __irq_exit_rcu+0x145/0x1c0 [ 80.000775][ C0] Preemption disabled at: [ 80.000776][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 80.000780][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 80.000783][ C0] Tainted: [W]=WARN [ 80.000784][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 80.000785][ C0] Call Trace: [ 80.000786][ C0] [ 80.000787][ C0] dump_stack_lvl+0x6f/0xa0 [ 80.000791][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 80.000793][ C0] __might_resched.cold+0x1fe/0x2c1 [ 80.000797][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 80.000800][ C0] ? rcu_is_watching+0x16/0xd0 [ 80.000802][ C0] __kmalloc_noprof+0x443/0x760 [ 80.000804][ C0] ? __rwlock_init+0x150/0x150 [ 80.000807][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 80.000811][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 80.000813][ C0] alloc_buf.isra.0+0x4b/0x260 [ 80.000816][ C0] put_chars+0x1e1/0x2f0 [ 80.000819][ C0] ? __send_to_port+0x420/0x420 [ 80.000821][ C0] ? mark_lock+0x1d7/0xa00 [ 80.000824][ C0] hvc_console_print+0x292/0x780 [ 80.000829][ C0] ? hvc_write+0x3a0/0x3a0 [ 80.000832][ C0] ? rcu_is_watching+0x16/0xd0 [ 80.000833][ C0] ? rcu_is_watching+0x16/0xd0 [ 80.000836][ C0] console_emit_next_record+0x22f/0x4c0 [ 80.000840][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 80.000842][ C0] ? console_flush_one_record+0x106/0x710 [ 80.000845][ C0] ? rcu_is_watching+0x16/0xd0 [ 80.000847][ C0] ? lock_acquire+0x13c/0x160 [ 80.000850][ C0] console_flush_one_record+0x46f/0x710 [ 80.000853][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 80.000856][ C0] ? __lock_acquire+0x518/0xc20 [ 80.000859][ C0] console_unlock+0xee/0x1f0 [ 80.000862][ C0] ? console_flush_one_record+0x710/0x710 [ 80.000864][ C0] ? rcu_is_watching+0x16/0xd0 [ 80.000866][ C0] ? lock_acquire+0xe0/0x160 [ 80.000869][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 80.000871][ C0] ? vprintk_emit+0x320/0x3e0 [ 80.000874][ C0] vprintk_emit+0x37c/0x3e0 [ 80.000876][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 80.000879][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 80.000881][ C0] _printk+0xc7/0x100 [ 80.000884][ C0] ? snapshot_read.cold+0x21/0x21 [ 80.000887][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 80.000889][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 80.000893][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 80.000895][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 80.000898][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 80.000900][ C0] call_timer_fn+0x160/0x4d0 [ 80.000903][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 80.000905][ C0] ? debug_object_active_state+0x430/0x430 [ 80.000907][ C0] ? find_held_lock+0x2b/0x80 [ 80.000910][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 80.000912][ C0] ? rcu_is_watching+0x16/0xd0 [ 80.000915][ C0] __run_timers+0x68f/0xaa0 [ 80.000917][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 80.000921][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 80.000923][ C0] ? __lock_acquire+0x518/0xc20 [ 80.000926][ C0] ? trace_rcu_watching+0x20/0x1f0 [ 80.000929][ C0] ? __rwlock_init+0x150/0x150 [ 80.000933][ C0] run_timer_softirq+0xf0/0x160 [ 80.000936][ C0] ? __run_timers+0xaa0/0xaa0 [ 80.000938][ C0] ? handle_softirqs+0x19c/0x900 [ 80.000940][ C0] ? rcu_is_watching+0x16/0xd0 [ 80.000942][ C0] handle_softirqs+0x1d3/0x900 [ 80.000945][ C0] ? _local_bh_enable+0xc0/0xc0 [ 80.000948][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 80.000951][ C0] __irq_exit_rcu+0x145/0x1c0 [ 80.000953][ C0] irq_exit_rcu+0xe/0x30 [ 80.000955][ C0] common_interrupt+0xb5/0xf0 [ 80.000957][ C0] [ 80.000958][ C0] [ 80.000959][ C0] asm_common_interrupt+0x26/0x40 [ 80.000961][ C0] RIP: 0010:lock_is_held_type+0x11d/0x1a0 [ 80.000963][ C0] Code: ff ff ff 65 0f c1 15 12 8e 68 02 83 fa 01 8b 44 24 04 75 55 9c 5a 80 e6 02 75 6f 41 f7 c6 00 02 00 00 74 2c fb 48 8b 5c 24 08 <4c> 8b 74 24 28 4c 8b 7c 24 30 48 8b 6c 24 10 48 83 c4 38 c3 b8 ff [ 80.000965][ C0] RSP: 0018:ffffffffb2007c38 EFLAGS: 00000206 [ 80.000966][ C0] RAX: 0000000000000000 RBX: ffffffffb2030600 RCX: 0000000000000000 [ 80.000968][ C0] RDX: 0000000000000046 RSI: ffffffffb18f9f34 RDI: ffffffffb12949e0 [ 80.000969][ C0] RBP: ffffffffb2030600 R08: 0000000000000001 R09: 1ffffffff65aa89b [ 80.000970][ C0] R10: 0000000000000000 R11: fffffbfff65aa89c R12: 0000000000000000 [ 80.000971][ C0] R13: ff1100006c038290 R14: 0000000000000246 R15: 00000000ffffffff [ 80.000975][ C0] ? rcu_is_watching+0x16/0xd0 [ 80.000977][ C0] schedule_debug+0x21b/0x3c0 [ 80.000980][ C0] __schedule+0x11b/0x20e0 [ 80.000982][ C0] ? trace_rcu_watching+0xd1/0x1f0 [ 80.000984][ C0] ? ct_kernel_enter_state+0x14/0x50 [ 80.000986][ C0] ? rcu_is_watching+0x16/0xd0 [ 80.000988][ C0] ? trace_cpu_idle+0xb3/0x1b0 [ 80.000990][ C0] ? io_schedule_timeout+0x140/0x140 [ 80.000993][ C0] ? default_idle_call+0x98/0xb0 [ 80.000995][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 80.000997][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 80.000999][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 80.001002][ C0] schedule_idle+0x59/0x90 [ 80.001005][ C0] cpu_startup_entry+0x53/0x70 [ 80.001007][ C0] rest_init+0x279/0x280 [ 80.001009][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 80.001013][ C0] ? rest_init+0x280/0x280 [ 80.001015][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 80.001018][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 80.001020][ C0] ? acpi_enable+0x1e4/0x330 [ 80.001023][ C0] start_kernel+0x3b9/0x3c0 [ 80.001025][ C0] x86_64_start_reservations+0x24/0x30 [ 80.001027][ C0] x86_64_start_kernel+0x12b/0x130 [ 80.001030][ C0] common_startup_64+0x13e/0x148 [ 80.001034][ C0]