[ 9.677476][ T200] ip (200) used greatest stack depth: 23800 bytes left [ 9.677493][ T200] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.677495][ T200] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 200, name: ip [ 9.677497][ T200] preempt_count: 2, expected: 0 [ 9.677497][ T200] RCU nest depth: 0, expected: 0 [ 9.677498][ T200] locks held by ip/200: 5, last CPU#0: [ 9.677500][ T200] #0: ffffffffa02167b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 9.677512][ T200] #1: ffffffffa037ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.677517][ T200] #2: ffffffffa037de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.677522][ T200] #3: ffffffffa029d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.677525][ T200] #4: ffffffffa029d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.677529][ T200] irq event stamp: 28738 [ 9.677530][ T200] hardirqs last enabled at (28737): [] __down_trylock_console_sem+0x86/0xa0 [ 9.677533][ T200] hardirqs last disabled at (28738): [] console_emit_next_record+0x3d4/0x4c0 [ 9.677535][ T200] softirqs last enabled at (28488): [] handle_softirqs+0x67c/0x900 [ 9.677538][ T200] softirqs last disabled at (27209): [] __irq_exit_rcu+0x145/0x1c0 [ 9.677540][ T200] Preemption disabled at: [ 9.677541][ T200] [<0000000000000000>] 0x0 [ 9.677547][ T200] CPU: 0 UID: 0 PID: 200 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.677551][ T200] Tainted: [W]=WARN [ 9.677552][ T200] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.677554][ T200] Call Trace: [ 9.677555][ T200] [ 9.677556][ T200] dump_stack_lvl+0x6f/0xa0 [ 9.677563][ T200] __might_resched.cold+0x1fe/0x2c1 [ 9.677568][ T200] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.677572][ T200] ? __kmalloc_noprof+0xdb/0x760 [ 9.677577][ T200] __kmalloc_noprof+0x443/0x760 [ 9.677579][ T200] ? alloc_buf.isra.0+0x4b/0x260 [ 9.677586][ T200] ? do_raw_spin_unlock+0x59/0x250 [ 9.677589][ T200] alloc_buf.isra.0+0x4b/0x260 [ 9.677593][ T200] put_chars+0x1e1/0x2f0 [ 9.677595][ T200] ? __send_to_port+0x420/0x420 [ 9.677597][ T200] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.677600][ T200] ? validate_chain+0x38b/0xc20 [ 9.677606][ T200] hvc_console_print+0x292/0x780 [ 9.677614][ T200] ? hvc_write+0x3a0/0x3a0 [ 9.677617][ T200] ? rcu_is_watching+0x16/0xd0 [ 9.677619][ T200] ? lock_acquire+0x13c/0x160 [ 9.677623][ T200] console_emit_next_record+0x22f/0x4c0 [ 9.677628][ T200] ? devkmsg_read+0x4b0/0x4b0 [ 9.677630][ T200] ? console_flush_one_record+0x106/0x710 [ 9.677633][ T200] ? rcu_is_watching+0x16/0xd0 [ 9.677635][ T200] ? lock_acquire+0x13c/0x160 [ 9.677639][ T200] console_flush_one_record+0x46f/0x710 [ 9.677643][ T200] ? console_emit_next_record+0x4c0/0x4c0 [ 9.677645][ T200] ? __lock_acquire+0x518/0xc20 [ 9.677651][ T200] console_unlock+0xee/0x1f0 [ 9.677654][ T200] ? console_flush_one_record+0x710/0x710 [ 9.677656][ T200] ? rcu_is_watching+0x16/0xd0 [ 9.677658][ T200] ? lock_acquire+0xe0/0x160 [ 9.677662][ T200] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.677664][ T200] ? vprintk_emit+0x320/0x3e0 [ 9.677667][ T200] vprintk_emit+0x37c/0x3e0 [ 9.677670][ T200] ? wake_up_klogd_work_func+0x90/0x90 [ 9.677674][ T200] ? __lock_acquire+0x518/0xc20 [ 9.677678][ T200] _printk+0xc7/0x100 [ 9.677682][ T200] ? snapshot_read.cold+0x21/0x21 [ 9.677685][ T200] ? do_raw_spin_lock+0x131/0x280 [ 9.677688][ T200] ? __rwlock_init+0x150/0x150 [ 9.677692][ T200] ? do_raw_spin_lock+0x131/0x280 [ 9.677694][ T200] do_exit.cold+0x82/0x9c [ 9.677699][ T200] ? exit_notify+0x890/0x890 [ 9.677700][ T200] ? __lock_release.isra.0+0x69/0x1a0 [ 9.677703][ T200] ? rcu_is_watching+0x16/0xd0 [ 9.677707][ T200] do_group_exit+0xb8/0x370 [ 9.677710][ T200] __x64_sys_exit_group+0x3c/0x50 [ 9.677712][ T200] x64_sys_call+0x1567/0x1570 [ 9.677715][ T200] do_syscall_64+0xff/0x530 [ 9.677719][ T200] ? exc_page_fault+0xee/0x100 [ 9.677722][ T200] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.677724][ T200] RIP: 0033:0x7f5855ccd1b8 [ 9.677726][ T200] Code: Unable to access opcode bytes at 0x7f5855ccd18e. [ 9.677727][ T200] RSP: 002b:00007fff864f4098 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 9.677730][ T200] RAX: ffffffffffffffda RBX: 00007f5855dfdf88 RCX: 00007f5855ccd1b8 [ 9.677731][ T200] RDX: 00007f5855a17fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 9.677732][ T200] RBP: 00007fff864f40f0 R08: 0000000000000000 R09: 0000000000000050 [ 9.677733][ T200] R10: 00007fff864f3eb0 R11: 0000000000000246 R12: 0000000000000001 [ 9.677734][ T200] R13: 0000000000000000 R14: 00007f5855dfc680 R15: 00007f5855dfdfa0 [ 9.677744][ T200] [ 12.057574][ T268] Initializing XFRM netlink socket [ 12.057591][ T268] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.057594][ T268] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 268, name: modprobe [ 12.057595][ T268] preempt_count: 1, expected: 0 [ 12.057596][ T268] RCU nest depth: 0, expected: 0 [ 12.057597][ T268] locks held by modprobe/268: 4, last CPU#3: [ 12.057599][ T268] #0: ffffffffa037ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.057611][ T268] #1: ffffffffa037de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.057615][ T268] #2: ffffffffa029d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.057619][ T268] #3: ffffffffa029d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 12.057624][ T268] irq event stamp: 20862 [ 12.057624][ T268] hardirqs last enabled at (20861): [] __down_trylock_console_sem+0x86/0xa0 [ 12.057628][ T268] hardirqs last disabled at (20862): [] console_emit_next_record+0x3d4/0x4c0 [ 12.057630][ T268] softirqs last enabled at (19192): [] handle_softirqs+0x67c/0x900 [ 12.057633][ T268] softirqs last disabled at (18571): [] __irq_exit_rcu+0x145/0x1c0 [ 12.057636][ T268] Preemption disabled at: [ 12.057636][ T268] [] vprintk_emit+0x31b/0x3e0 [ 12.057641][ T268] CPU: 3 UID: 0 PID: 268 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.057645][ T268] Tainted: [W]=WARN [ 12.057646][ T268] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.057647][ T268] Call Trace: [ 12.057649][ T268] [ 12.057650][ T268] dump_stack_lvl+0x6f/0xa0 [ 12.057656][ T268] ? vprintk_emit+0x31b/0x3e0 [ 12.057658][ T268] __might_resched.cold+0x1fe/0x2c1 [ 12.057662][ T268] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.057666][ T268] ? __kmalloc_noprof+0xdb/0x760 [ 12.057671][ T268] __kmalloc_noprof+0x443/0x760 [ 12.057673][ T268] ? alloc_buf.isra.0+0x4b/0x260 [ 12.057680][ T268] ? do_raw_spin_unlock+0x59/0x250 [ 12.057682][ T268] alloc_buf.isra.0+0x4b/0x260 [ 12.057686][ T268] put_chars+0x1e1/0x2f0 [ 12.057688][ T268] ? desc_read_finalized_seq+0x79/0x120 [ 12.057690][ T268] ? __send_to_port+0x420/0x420 [ 12.057693][ T268] ? rcu_read_lock_any_held+0x3c/0x90 [ 12.057696][ T268] ? validate_chain+0x38b/0xc20 [ 12.057701][ T268] hvc_console_print+0x292/0x780 [ 12.057705][ T268] ? __lock_acquire+0x518/0xc20 [ 12.057707][ T268] ? __lock_acquire+0x518/0xc20 [ 12.057711][ T268] ? hvc_write+0x3a0/0x3a0 [ 12.057713][ T268] ? console_emit_next_record+0x1df/0x4c0 [ 12.057716][ T268] ? rcu_is_watching+0x16/0xd0 [ 12.057719][ T268] ? lock_acquire+0x13c/0x160 [ 12.057723][ T268] console_emit_next_record+0x22f/0x4c0 [ 12.057727][ T268] ? devkmsg_read+0x4b0/0x4b0 [ 12.057728][ T268] ? console_flush_one_record+0x106/0x710 [ 12.057732][ T268] ? rcu_is_watching+0x16/0xd0 [ 12.057733][ T268] ? lock_acquire+0x13c/0x160 [ 12.057737][ T268] console_flush_one_record+0x46f/0x710 [ 12.057744][ T268] ? console_emit_next_record+0x4c0/0x4c0 [ 12.057747][ T268] ? __lock_acquire+0x518/0xc20 [ 12.057752][ T268] console_unlock+0xee/0x1f0 [ 12.057756][ T268] ? console_flush_one_record+0x710/0x710 [ 12.057758][ T268] ? rcu_is_watching+0x16/0xd0 [ 12.057759][ T268] ? lock_acquire+0xe0/0x160 [ 12.057763][ T268] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.057765][ T268] ? vprintk_emit+0x320/0x3e0 [ 12.057768][ T268] vprintk_emit+0x37c/0x3e0 [ 12.057771][ T268] ? wake_up_klogd_work_func+0x90/0x90 [ 12.057777][ T268] ? nft_chain_route_fini+0xcc0/0xcc0 [nf_tables] [ 12.057784][ T268] _printk+0xc7/0x100 [ 12.057788][ T268] ? snapshot_read.cold+0x21/0x21 [ 12.057794][ T268] ? do_one_initcall+0x113/0x4f0 [ 12.057798][ T268] xfrm_user_init+0x15/0x1000 [xfrm_user] [ 12.057801][ T268] do_one_initcall+0x124/0x4f0 [ 12.057804][ T268] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 12.057809][ T268] ? do_init_module+0xae/0x8a0 [ 12.057811][ T268] ? kasan_unpoison+0x44/0x70 [ 12.057817][ T268] do_init_module+0x2ad/0x8a0 [ 12.057820][ T268] ? lockdep_hardirqs_on+0x91/0x130 [ 12.057824][ T268] ? free_module+0x470/0x470 [ 12.057827][ T268] ? kfree+0x123/0x590 [ 12.057830][ T268] load_module+0x1424/0x1bb0 [ 12.057835][ T268] ? security_kernel_post_read_file+0x2f/0x60 [ 12.057840][ T268] ? layout_and_allocate.isra.0+0x680/0x680 [ 12.057845][ T268] ? backing_file_read_iter+0x541/0x790 [ 12.057849][ T268] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 12.057852][ T268] init_module_from_file+0x11f/0x1a0 [ 12.057855][ T268] ? __do_sys_init_module+0x270/0x270 [ 12.057862][ T268] ? __rwlock_init+0x150/0x150 [ 12.057866][ T268] ? do_raw_spin_unlock+0x59/0x250 [ 12.057869][ T268] idempotent_init_module+0x37b/0x7c0 [ 12.057872][ T268] ? init_module_from_file+0x1a0/0x1a0 [ 12.057878][ T268] ? cap_capable+0x1d7/0x3d0 [ 12.057880][ T268] ? __x64_sys_pread64+0x18d/0x250 [ 12.057885][ T268] __x64_sys_finit_module+0xdf/0x190 [ 12.057887][ T268] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 12.057891][ T268] do_syscall_64+0xff/0x530 [ 12.057894][ T268] ? exc_page_fault+0xee/0x100 [ 12.057896][ T268] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 12.057899][ T268] RIP: 0033:0x7fa2ada4037d [ 12.057902][ T268] Code: ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 4b aa 0f 00 f7 d8 64 89 01 48 [ 12.057903][ T268] RSP: 002b:00007ffdc507a778 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 12.057906][ T268] RAX: ffffffffffffffda RBX: 0000557cf21f2810 RCX: 00007fa2ada4037d [ 12.057907][ T268] RDX: 0000000000000000 RSI: 0000557ce02b1ffd RDI: 0000000000000000 [ 12.057908][ T268] RBP: 00007ffdc507a810 R08: 0000000000000000 R09: 0000000000000000 [ 12.057909][ T268] R10: 0000000000000000 R11: 0000000000000246 R12: 0000557ce02b1ffd [ 12.057910][ T268] R13: 0000557cf21f2960 R14: 0000000000040000 R15: 0000000000000000 [ 12.057917][ T268] [ 23.497879][ C0] clocksource: Watchdog remote CPU 1 read timed out [ 23.497986][ C0] [ 23.497988][ C0] ======================================================== [ 23.497989][ C0] WARNING: possible irq lock inversion dependency detected [ 23.497991][ C0] 7.2.0-virtme #1 Tainted: G W [ 23.497992][ C0] -------------------------------------------------------- [ 23.497993][ C0] swapper/0/0 just changed the state of lock: [ 23.497994][ C0] ffffffffa029d760 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 23.498007][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 23.498008][ C0] (fs_reclaim){+.+.}-{0:0} [ 23.498009][ C0] [ 23.498009][ C0] [ 23.498009][ C0] and interrupts could create inverse lock ordering between them. [ 23.498009][ C0] [ 23.498010][ C0] [ 23.498010][ C0] other info that might help us debug this: [ 23.498011][ C0] Possible interrupt unsafe locking scenario: [ 23.498011][ C0] [ 23.498011][ C0] CPU0 CPU1 [ 23.498012][ C0] ---- ---- [ 23.498012][ C0] lock(fs_reclaim); [ 23.498013][ C0] local_irq_disable(); [ 23.498014][ C0] lock(console_owner); [ 23.498015][ C0] lock(fs_reclaim); [ 23.498015][ C0] [ 23.498016][ C0] lock(console_owner); [ 23.498017][ C0] [ 23.498017][ C0] *** DEADLOCK *** [ 23.498017][ C0] [ 23.498017][ C0] locks held by swapper/0/0: 4, last CPU#0: [ 23.498018][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 23.498024][ C0] #1: ffffffffa03e29f8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 23.498028][ C0] #2: ffffffffa037ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 23.498031][ C0] #3: ffffffffa037de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 23.498035][ C0] [ 23.498035][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 23.498040][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 23.498042][ C0] HARDIRQ-ON-W at: [ 23.498043][ C0] __lock_acquire+0x388/0xc20 [ 23.498047][ C0] lock_acquire.part.0+0xd4/0x280 [ 23.498049][ C0] fs_reclaim_acquire+0xd5/0x120 [ 23.498052][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 23.498054][ C0] kthread_create_worker_on_node+0xea/0x210 [ 23.498057][ C0] workqueue_init+0x2a/0x680 [ 23.498061][ C0] kernel_init_freeable+0x2fe/0x630 [ 23.498065][ C0] kernel_init+0x21/0x150 [ 23.498068][ C0] ret_from_fork+0x474/0x6b0 [ 23.498072][ C0] ret_from_fork_asm+0x11/0x20 [ 23.498075][ C0] SOFTIRQ-ON-W at: [ 23.498076][ C0] __lock_acquire+0x388/0xc20 [ 23.498077][ C0] lock_acquire.part.0+0xd4/0x280 [ 23.498079][ C0] fs_reclaim_acquire+0xd5/0x120 [ 23.498080][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 23.498081][ C0] kthread_create_worker_on_node+0xea/0x210 [ 23.498083][ C0] workqueue_init+0x2a/0x680 [ 23.498085][ C0] kernel_init_freeable+0x2fe/0x630 [ 23.498086][ C0] kernel_init+0x21/0x150 [ 23.498088][ C0] ret_from_fork+0x474/0x6b0 [ 23.498089][ C0] ret_from_fork_asm+0x11/0x20 [ 23.498090][ C0] INITIAL USE at: [ 23.498091][ C0] __lock_acquire+0x388/0xc20 [ 23.498093][ C0] lock_acquire.part.0+0xd4/0x280 [ 23.498094][ C0] fs_reclaim_acquire+0xd5/0x120 [ 23.498096][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 23.498097][ C0] kthread_create_worker_on_node+0xea/0x210 [ 23.498098][ C0] workqueue_init+0x2a/0x680 [ 23.498100][ C0] kernel_init_freeable+0x2fe/0x630 [ 23.498102][ C0] kernel_init+0x21/0x150 [ 23.498103][ C0] ret_from_fork+0x474/0x6b0 [ 23.498104][ C0] ret_from_fork_asm+0x11/0x20 [ 23.498106][ C0] } [ 23.498106][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 23.498110][ C0] ... acquired at: [ 23.498111][ C0] __lock_acquire+0x518/0xc20 [ 23.498112][ C0] lock_acquire.part.0+0xd4/0x280 [ 23.498114][ C0] fs_reclaim_acquire+0xd5/0x120 [ 23.498115][ C0] __kmalloc_noprof+0xd3/0x760 [ 23.498116][ C0] alloc_buf.isra.0+0x4b/0x260 [ 23.498121][ C0] put_chars+0x1e1/0x2f0 [ 23.498122][ C0] hvc_console_print+0x292/0x780 [ 23.498125][ C0] console_emit_next_record+0x22f/0x4c0 [ 23.498127][ C0] console_flush_one_record+0x46f/0x710 [ 23.498129][ C0] console_unlock+0xee/0x1f0 [ 23.498131][ C0] vprintk_emit+0x37c/0x3e0 [ 23.498132][ C0] _printk+0xc7/0x100 [ 23.498135][ C0] tun_init+0x24/0xb0 [ 23.498138][ C0] do_one_initcall+0x124/0x4f0 [ 23.498139][ C0] kernel_init_freeable+0x596/0x630 [ 23.498140][ C0] kernel_init+0x21/0x150 [ 23.498142][ C0] ret_from_fork+0x474/0x6b0 [ 23.498143][ C0] ret_from_fork_asm+0x11/0x20 [ 23.498144][ C0] [ 23.498145][ C0] -> (console_owner){..-.}-{0:0} { [ 23.498147][ C0] IN-SOFTIRQ-W at: [ 23.498148][ C0] __lock_acquire+0x388/0xc20 [ 23.498149][ C0] lock_acquire.part.0+0xd4/0x280 [ 23.498151][ C0] console_lock_spinning_enable+0x5c/0x60 [ 23.498152][ C0] console_emit_next_record+0x1d1/0x4c0 [ 23.498154][ C0] console_flush_one_record+0x46f/0x710 [ 23.498156][ C0] console_unlock+0xee/0x1f0 [ 23.498157][ C0] vprintk_emit+0x37c/0x3e0 [ 23.498158][ C0] _printk+0xc7/0x100 [ 23.498160][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 23.498162][ C0] call_timer_fn+0x160/0x4d0 [ 23.498164][ C0] __run_timers+0x68f/0xaa0 [ 23.498165][ C0] run_timer_softirq+0xf0/0x160 [ 23.498167][ C0] handle_softirqs+0x1d3/0x900 [ 23.498170][ C0] __irq_exit_rcu+0x145/0x1c0 [ 23.498171][ C0] irq_exit_rcu+0xe/0x30 [ 23.498173][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 23.498174][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 23.498176][ C0] pv_native_safe_halt+0xf/0x10 [ 23.498178][ C0] default_idle+0x9/0x10 [ 23.498179][ C0] default_idle_call+0x6e/0xb0 [ 23.498180][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 23.498183][ C0] do_idle+0xd8/0x190 [ 23.498184][ C0] cpu_startup_entry+0x53/0x70 [ 23.498186][ C0] rest_init+0x279/0x280 [ 23.498187][ C0] start_kernel+0x3b9/0x3c0 [ 23.498189][ C0] x86_64_start_reservations+0x24/0x30 [ 23.498191][ C0] x86_64_start_kernel+0x12b/0x130 [ 23.498192][ C0] common_startup_64+0x13e/0x148 [ 23.498195][ C0] INITIAL USE at: [ 23.498196][ C0] } [ 23.498197][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 23.498199][ C0] ... acquired at: [ 23.498200][ C0] mark_lock+0x1d7/0xa00 [ 23.498201][ C0] mark_usage+0x42/0x170 [ 23.498202][ C0] __lock_acquire+0x388/0xc20 [ 23.498204][ C0] lock_acquire.part.0+0xd4/0x280 [ 23.498205][ C0] console_lock_spinning_enable+0x5c/0x60 [ 23.498207][ C0] console_emit_next_record+0x1d1/0x4c0 [ 23.498209][ C0] console_flush_one_record+0x46f/0x710 [ 23.498210][ C0] console_unlock+0xee/0x1f0 [ 23.498212][ C0] vprintk_emit+0x37c/0x3e0 [ 23.498213][ C0] _printk+0xc7/0x100 [ 23.498214][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 23.498215][ C0] call_timer_fn+0x160/0x4d0 [ 23.498217][ C0] __run_timers+0x68f/0xaa0 [ 23.498218][ C0] run_timer_softirq+0xf0/0x160 [ 23.498220][ C0] handle_softirqs+0x1d3/0x900 [ 23.498221][ C0] __irq_exit_rcu+0x145/0x1c0 [ 23.498223][ C0] irq_exit_rcu+0xe/0x30 [ 23.498224][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 23.498225][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 23.498226][ C0] pv_native_safe_halt+0xf/0x10 [ 23.498228][ C0] default_idle+0x9/0x10 [ 23.498229][ C0] default_idle_call+0x6e/0xb0 [ 23.498230][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 23.498232][ C0] do_idle+0xd8/0x190 [ 23.498233][ C0] cpu_startup_entry+0x53/0x70 [ 23.498234][ C0] rest_init+0x279/0x280 [ 23.498236][ C0] start_kernel+0x3b9/0x3c0 [ 23.498237][ C0] x86_64_start_reservations+0x24/0x30 [ 23.498238][ C0] x86_64_start_kernel+0x12b/0x130 [ 23.498239][ C0] common_startup_64+0x13e/0x148 [ 23.498241][ C0] [ 23.498241][ C0] [ 23.498241][ C0] stack backtrace: [ 23.498244][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 23.498247][ C0] Tainted: [W]=WARN [ 23.498248][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 23.498250][ C0] Call Trace: [ 23.498251][ C0] [ 23.498252][ C0] dump_stack_lvl+0x6f/0xa0 [ 23.498257][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 23.498259][ C0] mark_lock_irq+0x989/0x9c0 [ 23.498261][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 23.498264][ C0] mark_lock+0x1d7/0xa00 [ 23.498266][ C0] mark_usage+0x42/0x170 [ 23.498268][ C0] __lock_acquire+0x388/0xc20 [ 23.498270][ C0] lock_acquire.part.0+0xd4/0x280 [ 23.498272][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 23.498275][ C0] ? rcu_is_watching+0x16/0xd0 [ 23.498277][ C0] ? lock_acquire+0x13c/0x160 [ 23.498279][ C0] console_lock_spinning_enable+0x5c/0x60 [ 23.498281][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 23.498283][ C0] console_emit_next_record+0x1d1/0x4c0 [ 23.498285][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 23.498287][ C0] ? console_flush_one_record+0x106/0x710 [ 23.498289][ C0] ? rcu_is_watching+0x16/0xd0 [ 23.498290][ C0] ? lock_acquire+0x13c/0x160 [ 23.498293][ C0] console_flush_one_record+0x46f/0x710 [ 23.498295][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 23.498297][ C0] ? __lock_acquire+0x518/0xc20 [ 23.498300][ C0] console_unlock+0xee/0x1f0 [ 23.498302][ C0] ? console_flush_one_record+0x710/0x710 [ 23.498304][ C0] ? rcu_is_watching+0x16/0xd0 [ 23.498305][ C0] ? lock_acquire+0xe0/0x160 [ 23.498307][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 23.498309][ C0] ? vprintk_emit+0x320/0x3e0 [ 23.498311][ C0] vprintk_emit+0x37c/0x3e0 [ 23.498313][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 23.498315][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 23.498317][ C0] _printk+0xc7/0x100 [ 23.498318][ C0] ? snapshot_read.cold+0x21/0x21 [ 23.498320][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 23.498322][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 23.498325][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 23.498326][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 23.498328][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 23.498330][ C0] call_timer_fn+0x160/0x4d0 [ 23.498332][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 23.498334][ C0] ? debug_object_active_state+0x430/0x430 [ 23.498337][ C0] ? find_held_lock+0x2b/0x80 [ 23.498339][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 23.498341][ C0] ? rcu_is_watching+0x16/0xd0 [ 23.498343][ C0] __run_timers+0x68f/0xaa0 [ 23.498345][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 23.498347][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 23.498354][ C0] ? __lock_acquire+0x518/0xc20 [ 23.498357][ C0] ? __rwlock_init+0x150/0x150 [ 23.498360][ C0] run_timer_softirq+0xf0/0x160 [ 23.498362][ C0] ? __run_timers+0xaa0/0xaa0 [ 23.498364][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 23.498367][ C0] ? rcu_is_watching+0x16/0xd0 [ 23.498368][ C0] handle_softirqs+0x1d3/0x900 [ 23.498370][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 23.498372][ C0] ? _local_bh_enable+0xc0/0xc0 [ 23.498375][ C0] __irq_exit_rcu+0x145/0x1c0 [ 23.498376][ C0] irq_exit_rcu+0xe/0x30 [ 23.498378][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 23.498380][ C0] [ 23.498380][ C0] [ 23.498381][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 23.498383][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 23.498385][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 23.498387][ 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 [ 23.498389][ C0] RSP: 0018:ffffffffa0007cf8 EFLAGS: 00000296 [ 23.498391][ C0] RAX: 00000000001ed663 RBX: ffffffffa0030600 RCX: ffffffff9c706247 [ 23.498392][ C0] RDX: ffffffffa0030600 RSI: ffffffff9f911011 RDI: ffffffff9f2949e0 [ 23.498393][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 23.498394][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff4000fa2 [ 23.498395][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 23.498397][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 23.498399][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 23.498400][ C0] default_idle+0x9/0x10 [ 23.498402][ C0] default_idle_call+0x6e/0xb0 [ 23.498404][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 23.498406][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 23.498407][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 23.498410][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 23.498411][ C0] ? rcu_is_watching+0x16/0xd0 [ 23.498413][ C0] do_idle+0xd8/0x190 [ 23.498415][ C0] cpu_startup_entry+0x53/0x70 [ 23.498416][ C0] rest_init+0x279/0x280 [ 23.498418][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 23.498421][ C0] ? rest_init+0x280/0x280 [ 23.498423][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 23.498426][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 23.498428][ C0] ? acpi_enable+0x1e4/0x330 [ 23.498430][ C0] start_kernel+0x3b9/0x3c0 [ 23.498432][ C0] x86_64_start_reservations+0x24/0x30 [ 23.498434][ C0] x86_64_start_kernel+0x12b/0x130 [ 23.498435][ C0] common_startup_64+0x13e/0x148 [ 23.498439][ C0] [ 23.498443][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 23.498444][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 23.498445][ C0] preempt_count: 103, expected: 0 [ 23.498446][ C0] RCU nest depth: 0, expected: 0 [ 23.498447][ C0] INFO: lockdep is turned off. [ 23.498447][ C0] irq event stamp: 2020981 [ 23.498448][ C0] hardirqs last enabled at (2020980): [] __down_trylock_console_sem+0x86/0xa0 [ 23.498451][ C0] hardirqs last disabled at (2020981): [] console_emit_next_record+0x3d4/0x4c0 [ 23.498453][ C0] softirqs last enabled at (2020946): [] handle_softirqs+0x67c/0x900 [ 23.498455][ C0] softirqs last disabled at (2020965): [] __irq_exit_rcu+0x145/0x1c0 [ 23.498456][ C0] Preemption disabled at: [ 23.498457][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 23.498460][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 23.498462][ C0] Tainted: [W]=WARN [ 23.498463][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 23.498464][ C0] Call Trace: [ 23.498464][ C0] [ 23.498465][ C0] dump_stack_lvl+0x6f/0xa0 [ 23.498467][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 23.498469][ C0] __might_resched.cold+0x1fe/0x2c1 [ 23.498472][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 23.498475][ C0] ? rcu_is_watching+0x16/0xd0 [ 23.498477][ C0] __kmalloc_noprof+0x443/0x760 [ 23.498478][ C0] ? __rwlock_init+0x150/0x150 [ 23.498480][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 23.498482][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 23.498484][ C0] alloc_buf.isra.0+0x4b/0x260 [ 23.498487][ C0] put_chars+0x1e1/0x2f0 [ 23.498488][ C0] ? __send_to_port+0x420/0x420 [ 23.498490][ C0] ? mark_lock+0x1d7/0xa00 [ 23.498492][ C0] hvc_console_print+0x292/0x780 [ 23.498496][ C0] ? hvc_write+0x3a0/0x3a0 [ 23.498498][ C0] ? rcu_is_watching+0x16/0xd0 [ 23.498499][ C0] ? rcu_is_watching+0x16/0xd0 [ 23.498501][ C0] console_emit_next_record+0x22f/0x4c0 [ 23.498503][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 23.498505][ C0] ? console_flush_one_record+0x106/0x710 [ 23.498507][ C0] ? rcu_is_watching+0x16/0xd0 [ 23.498508][ C0] ? lock_acquire+0x13c/0x160 [ 23.498510][ C0] console_flush_one_record+0x46f/0x710 [ 23.498512][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 23.498514][ C0] ? __lock_acquire+0x518/0xc20 [ 23.498517][ C0] console_unlock+0xee/0x1f0 [ 23.498519][ C0] ? console_flush_one_record+0x710/0x710 [ 23.498521][ C0] ? rcu_is_watching+0x16/0xd0 [ 23.498522][ C0] ? lock_acquire+0xe0/0x160 [ 23.498524][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 23.498525][ C0] ? vprintk_emit+0x320/0x3e0 [ 23.498527][ C0] vprintk_emit+0x37c/0x3e0 [ 23.498528][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 23.498530][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 23.498532][ C0] _printk+0xc7/0x100 [ 23.498534][ C0] ? snapshot_read.cold+0x21/0x21 [ 23.498535][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 23.498537][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 23.498540][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 23.498541][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 23.498543][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 23.498545][ C0] call_timer_fn+0x160/0x4d0 [ 23.498547][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 23.498549][ C0] ? debug_object_active_state+0x430/0x430 [ 23.498550][ C0] ? find_held_lock+0x2b/0x80 [ 23.498552][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 23.498554][ C0] ? rcu_is_watching+0x16/0xd0 [ 23.498555][ C0] __run_timers+0x68f/0xaa0 [ 23.498557][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 23.498559][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 23.498561][ C0] ? __lock_acquire+0x518/0xc20 [ 23.498564][ C0] ? __rwlock_init+0x150/0x150 [ 23.498566][ C0] run_timer_softirq+0xf0/0x160 [ 23.498569][ C0] ? __run_timers+0xaa0/0xaa0 [ 23.498571][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 23.498572][ C0] ? rcu_is_watching+0x16/0xd0 [ 23.498573][ C0] handle_softirqs+0x1d3/0x900 [ 23.498575][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 23.498577][ C0] ? _local_bh_enable+0xc0/0xc0 [ 23.498579][ C0] __irq_exit_rcu+0x145/0x1c0 [ 23.498581][ C0] irq_exit_rcu+0xe/0x30 [ 23.498582][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 23.498584][ C0] [ 23.498584][ C0] [ 23.498585][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 23.498586][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 23.498588][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 23.498590][ 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 [ 23.498591][ C0] RSP: 0018:ffffffffa0007cf8 EFLAGS: 00000296 [ 23.498592][ C0] RAX: 00000000001ed663 RBX: ffffffffa0030600 RCX: ffffffff9c706247 [ 23.498593][ C0] RDX: ffffffffa0030600 RSI: ffffffff9f911011 RDI: ffffffff9f2949e0 [ 23.498593][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 23.498594][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff4000fa2 [ 23.498595][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 23.498596][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 23.498598][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 23.498600][ C0] default_idle+0x9/0x10 [ 23.498601][ C0] default_idle_call+0x6e/0xb0 [ 23.498603][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 23.498605][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 23.498606][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 23.498608][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 23.498609][ C0] ? rcu_is_watching+0x16/0xd0 [ 23.498611][ C0] do_idle+0xd8/0x190 [ 23.498612][ C0] cpu_startup_entry+0x53/0x70 [ 23.498614][ C0] rest_init+0x279/0x280 [ 23.498616][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 23.498618][ C0] ? rest_init+0x280/0x280 [ 23.498641][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 23.498643][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 23.498645][ C0] ? acpi_enable+0x1e4/0x330 [ 23.498646][ C0] start_kernel+0x3b9/0x3c0 [ 23.498648][ C0] x86_64_start_reservations+0x24/0x30 [ 23.498650][ C0] x86_64_start_kernel+0x12b/0x130 [ 23.498652][ C0] common_startup_64+0x13e/0x148 [ 23.498655][ C0]