[ 9.133934][ T136] readlink (136) used greatest stack depth: 24496 bytes left [ 9.133954][ T136] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.133956][ T136] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 136, name: readlink [ 9.133958][ T136] preempt_count: 2, expected: 0 [ 9.133959][ T136] RCU nest depth: 0, expected: 0 [ 9.133960][ T136] locks held by readlink/136: 5, last CPU#3: [ 9.133962][ T136] #0: ffffffff9c4027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 9.133975][ T136] #1: ffffffff9c599cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.133980][ T136] #2: ffffffff9c599d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.133984][ T136] #3: ffffffff9c489660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.133988][ T136] #4: ffffffff9c489560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 9.133991][ T136] irq event stamp: 15652 [ 9.133992][ T136] hardirqs last enabled at (15651): [] __down_trylock_console_sem+0x86/0xa0 [ 9.133994][ T136] hardirqs last disabled at (15652): [] console_emit_next_record+0x3f8/0x4f0 [ 9.133996][ T136] softirqs last enabled at (15494): [] handle_softirqs+0x67c/0x900 [ 9.133999][ T136] softirqs last disabled at (14969): [] __irq_exit_rcu+0x145/0x1c0 [ 9.134002][ T136] Preemption disabled at: [ 9.134002][ T136] [<0000000000000000>] 0x0 [ 9.134010][ T136] CPU: 3 UID: 0 PID: 136 Comm: readlink Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.134013][ T136] Tainted: [W]=WARN [ 9.134014][ T136] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.134015][ T136] Call Trace: [ 9.134017][ T136] [ 9.134019][ T136] dump_stack_lvl+0x6f/0xa0 [ 9.134025][ T136] __might_resched.cold+0x1fe/0x2c1 [ 9.134030][ T136] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.134034][ T136] ? __kmalloc_noprof+0xdb/0x760 [ 9.134039][ T136] __kmalloc_noprof+0x443/0x760 [ 9.134041][ T136] ? alloc_buf.isra.0+0x4b/0x260 [ 9.134047][ T136] ? do_raw_spin_unlock+0x59/0x250 [ 9.134049][ T136] alloc_buf.isra.0+0x4b/0x260 [ 9.134052][ T136] put_chars+0x1e1/0x2f0 [ 9.134055][ T136] ? __send_to_port+0x420/0x420 [ 9.134056][ T136] ? printk_get_next_message+0x2fe/0x7d0 [ 9.134059][ T136] ? rcu_read_lock_any_held+0x3c/0x90 [ 9.134062][ T136] ? validate_chain+0x38b/0xc20 [ 9.134066][ T136] hvc_console_print+0x292/0x780 [ 9.134069][ T136] ? __lock_acquire+0x518/0xc20 [ 9.134071][ T136] ? __lock_acquire+0x518/0xc20 [ 9.134075][ T136] ? hvc_write+0x3a0/0x3a0 [ 9.134077][ T136] ? rcu_is_watching+0x16/0xd0 [ 9.134081][ T136] ? lock_acquire+0x13c/0x160 [ 9.134084][ T136] console_emit_next_record+0x252/0x4f0 [ 9.134088][ T136] ? devkmsg_read+0x4e0/0x4e0 [ 9.134092][ T136] ? rcu_is_watching+0x16/0xd0 [ 9.134094][ T136] ? lock_acquire+0x13c/0x160 [ 9.134097][ T136] console_flush_one_record+0x46f/0x710 [ 9.134101][ T136] ? console_emit_next_record+0x4f0/0x4f0 [ 9.134103][ T136] ? __lock_acquire+0x518/0xc20 [ 9.134107][ T136] console_unlock+0xee/0x1f0 [ 9.134110][ T136] ? console_flush_one_record+0x710/0x710 [ 9.134111][ T136] ? rcu_is_watching+0x16/0xd0 [ 9.134113][ T136] ? lock_acquire+0x60/0x160 [ 9.134117][ T136] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.134118][ T136] ? vprintk_emit+0x320/0x3e0 [ 9.134121][ T136] vprintk_emit+0x37c/0x3e0 [ 9.134124][ T136] ? wake_up_klogd_work_func+0x90/0x90 [ 9.134128][ T136] ? __lock_acquire+0x518/0xc20 [ 9.134131][ T136] _printk+0xc7/0x100 [ 9.134134][ T136] ? snapshot_read.cold+0x21/0x21 [ 9.134137][ T136] ? do_raw_spin_lock+0x131/0x280 [ 9.134140][ T136] ? __rwlock_init+0x150/0x150 [ 9.134144][ T136] ? do_raw_spin_lock+0x131/0x280 [ 9.134146][ T136] do_exit.cold+0x82/0x9c [ 9.134150][ T136] ? exit_notify+0x890/0x890 [ 9.134152][ T136] ? __lock_release.isra.0+0x69/0x1a0 [ 9.134155][ T136] ? rcu_is_watching+0x16/0xd0 [ 9.134158][ T136] do_group_exit+0xb8/0x370 [ 9.134162][ T136] __x64_sys_exit_group+0x3c/0x50 [ 9.134164][ T136] x64_sys_call+0x1567/0x1570 [ 9.134166][ T136] do_syscall_64+0xff/0x530 [ 9.134170][ T136] ? exc_page_fault+0xee/0x100 [ 9.134173][ T136] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.134175][ T136] RIP: 0033:0x7fd9f7ff41b8 [ 9.134178][ T136] Code: Unable to access opcode bytes at 0x7fd9f7ff418e. [ 9.134179][ T136] RSP: 002b:00007fff50d51798 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 9.134181][ T136] RAX: ffffffffffffffda RBX: 00007fd9f8124f88 RCX: 00007fd9f7ff41b8 [ 9.134182][ T136] RDX: 00007fd9f7f31a48 RSI: ffffffffffffffb0 RDI: 0000000000000000 [ 9.134183][ T136] RBP: 00007fff50d517f0 R08: 0000000000000000 R09: 0000000000001010 [ 9.134184][ T136] R10: 00007fff50d51650 R11: 0000000000000246 R12: 0000000000000002 [ 9.134185][ T136] R13: 0000000000000000 R14: 00007fd9f8123680 R15: 00007fd9f8124fa0 [ 9.134192][ T136] [ 9.729533][ T84] netdevsim netdevsim641 eni641np1: renamed from eth1 [ 9.733048][ T82] netdevsim netdevsim275 eni275np1: renamed from eth0 [ 10.434994][ T166] Could not find device with ifindex 2000 in netnsfd 11 [ 10.435053][ T166] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.435055][ T166] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 166, name: peer.sh [ 10.435056][ T166] preempt_count: 1, expected: 0 [ 10.435057][ T166] RCU nest depth: 0, expected: 0 [ 10.435058][ T166] locks held by peer.sh/166: 8, last CPU#1: [ 10.435061][ T166] #0: ff11000009ee9430 (sb_writers#7){.+.+}-{0:0}, at: ksys_write+0x116/0x250 [ 10.435073][ T166] #1: ff1100000298ac80 (&of->mutex){+.+.}-{4:4}, at: kernfs_fop_write_iter+0x247/0x510 [ 10.435079][ T166] #2: ff11000009044370 (kn->active#38){.+.+}-{0:0}, at: kernfs_fop_write_iter+0x29e/0x510 [ 10.435083][ T166] #3: ffffffff9ccfe280 (rtnl_mutex){+.+.}-{4:4}, at: link_device_store+0x111/0x580 [netdevsim] [ 10.435091][ T166] #4: ffffffff9c599cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.435097][ T166] #5: ffffffff9c599d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.435100][ T166] #6: ffffffff9c489660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.435104][ T166] #7: ffffffff9c489560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 10.435108][ T166] irq event stamp: 66622 [ 10.435108][ T166] hardirqs last enabled at (66621): [] __down_trylock_console_sem+0x86/0xa0 [ 10.435111][ T166] hardirqs last disabled at (66622): [] console_emit_next_record+0x3f8/0x4f0 [ 10.435112][ T166] softirqs last enabled at (66322): [] restore_fpregs_from_user+0x1cf/0x490 [ 10.435116][ T166] softirqs last disabled at (66320): [] restore_fpregs_from_user+0xc3/0x490 [ 10.435118][ T166] Preemption disabled at: [ 10.435119][ T166] [] vprintk_emit+0x31b/0x3e0 [ 10.435124][ T166] CPU: 1 UID: 0 PID: 166 Comm: peer.sh Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.435128][ T166] Tainted: [W]=WARN [ 10.435129][ T166] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.435130][ T166] Call Trace: [ 10.435132][ T166] [ 10.435134][ T166] dump_stack_lvl+0x6f/0xa0 [ 10.435139][ T166] ? vprintk_emit+0x31b/0x3e0 [ 10.435141][ T166] __might_resched.cold+0x1fe/0x2c1 [ 10.435146][ T166] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.435150][ T166] ? __kmalloc_noprof+0xdb/0x760 [ 10.435154][ T166] __kmalloc_noprof+0x443/0x760 [ 10.435156][ T166] ? alloc_buf.isra.0+0x4b/0x260 [ 10.435161][ T166] ? do_raw_spin_unlock+0x59/0x250 [ 10.435164][ T166] alloc_buf.isra.0+0x4b/0x260 [ 10.435167][ T166] put_chars+0x1e1/0x2f0 [ 10.435170][ T166] ? __send_to_port+0x420/0x420 [ 10.435177][ T166] hvc_console_print+0x292/0x780 [ 10.435184][ T166] ? hvc_write+0x3a0/0x3a0 [ 10.435186][ T166] ? lock_acquire+0x13c/0x160 [ 10.435190][ T166] console_emit_next_record+0x252/0x4f0 [ 10.435194][ T166] ? devkmsg_read+0x4e0/0x4e0 [ 10.435198][ T166] ? rcu_is_watching+0x16/0xd0 [ 10.435202][ T166] ? lock_acquire+0x13c/0x160 [ 10.435206][ T166] console_flush_one_record+0x46f/0x710 [ 10.435210][ T166] ? console_emit_next_record+0x4f0/0x4f0 [ 10.435212][ T166] ? __lock_acquire+0x518/0xc20 [ 10.435217][ T166] console_unlock+0xee/0x1f0 [ 10.435219][ T166] ? console_flush_one_record+0x710/0x710 [ 10.435221][ T166] ? rcu_is_watching+0x16/0xd0 [ 10.435223][ T166] ? lock_acquire+0x60/0x160 [ 10.435227][ T166] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.435228][ T166] ? vprintk_emit+0x320/0x3e0 [ 10.435232][ T166] vprintk_emit+0x37c/0x3e0 [ 10.435236][ T166] ? wake_up_klogd_work_func+0x90/0x90 [ 10.435238][ T166] ? lock_acquire.part.0+0xd4/0x280 [ 10.435240][ T166] ? ww_mutex_lock+0x160/0x160 [ 10.435246][ T166] ? sysfs_file_ops+0x140/0x140 [ 10.435248][ T166] _printk+0xc7/0x100 [ 10.435251][ T166] ? snapshot_read.cold+0x21/0x21 [ 10.435254][ T166] ? __mutex_lock+0x9a3/0x1ea0 [ 10.435256][ T166] ? mark_usage+0x61/0x170 [ 10.435258][ T166] ? kernfs_test_super+0x157/0x1c0 [ 10.435262][ T166] ? trace_contention_end+0x34/0x180 [ 10.435266][ T166] link_device_store.cold+0x18a/0x1a9 [netdevsim] [ 10.435273][ T166] ? unlink_device_store+0x5f0/0x5f0 [netdevsim] [ 10.435276][ T166] ? __lock_release.isra.0+0x69/0x1a0 [ 10.435279][ T166] ? sysfs_file_kobj+0xb3/0x1e0 [ 10.435282][ T166] ? sysfs_file_ops+0x140/0x140 [ 10.435284][ T166] ? sysfs_file_kobj+0xbd/0x1e0 [ 10.435288][ T166] ? sysfs_file_ops+0x140/0x140 [ 10.435289][ T166] kernfs_fop_write_iter+0x335/0x510 [ 10.435291][ T166] ? kernfs_ops+0xf0/0xf0 [ 10.435294][ T166] new_sync_write+0x33e/0x760 [ 10.435297][ T166] ? __lock_acquire+0x518/0xc20 [ 10.435299][ T166] ? new_sync_read+0x750/0x750 [ 10.435302][ T166] ? lock_acquire.part.0+0xd4/0x280 [ 10.435304][ T166] ? ksys_write+0x116/0x250 [ 10.435311][ T166] vfs_write+0x6a2/0xbd0 [ 10.435315][ T166] ksys_write+0x116/0x250 [ 10.435318][ T166] ? __ia32_sys_read+0xc0/0xc0 [ 10.435320][ T166] ? rcu_is_watching+0x16/0xd0 [ 10.435322][ T166] ? rcu_is_watching+0x16/0xd0 [ 10.435326][ T166] do_syscall_64+0xff/0x530 [ 10.435329][ T166] ? exc_page_fault+0xee/0x100 [ 10.435332][ T166] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.435334][ T166] RIP: 0033:0x7fa494d0e54e [ 10.435338][ T166] 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.435340][ T166] RSP: 002b:00007ffe22c0f780 EFLAGS: 00000202 ORIG_RAX: 0000000000000001 [ 10.435342][ T166] RAX: ffffffffffffffda RBX: 00007fa494e8f580 RCX: 00007fa494d0e54e [ 10.435344][ T166] RDX: 000000000000000d RSI: 000056274ea477a0 RDI: 0000000000000001 [ 10.435345][ T166] RBP: 00007ffe22c0f790 R08: 0000000000000000 R09: 0000000000000000 [ 10.435346][ T166] R10: 0000000000000000 R11: 0000000000000202 R12: 000000000000000d [ 10.435347][ T166] R13: 000000000000000d R14: 000056274ea477a0 R15: 0000000000000000 [ 10.435354][ T166] [ 10.458623][ T166] Could not find netns with fd: 2000 [ 10.459347][ T166] Cannot link a netdevsim to itself [ 10.459938][ T166] Format for linking two devices is "netnsfd_a:ifidx_a netnsfd_b:ifidx_b" (int uint int uint). [ 11.952591][ C0] clocksource: Watchdog remote CPU 2 read timed out [ 11.952665][ C0] [ 11.952668][ C0] ======================================================== [ 11.952669][ C0] WARNING: possible irq lock inversion dependency detected [ 11.952671][ C0] 7.2.0-virtme #1 Tainted: G W [ 11.952673][ C0] -------------------------------------------------------- [ 11.952673][ C0] swapper/0/0 just changed the state of lock: [ 11.952674][ C0] ffffffff9c489660 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.952688][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 11.952689][ C0] (fs_reclaim){+.+.}-{0:0} [ 11.952691][ C0] [ 11.952691][ C0] [ 11.952691][ C0] and interrupts could create inverse lock ordering between them. [ 11.952691][ C0] [ 11.952691][ C0] [ 11.952691][ C0] other info that might help us debug this: [ 11.952692][ C0] Possible interrupt unsafe locking scenario: [ 11.952692][ C0] [ 11.952693][ C0] CPU0 CPU1 [ 11.952693][ C0] ---- ---- [ 11.952694][ C0] lock(fs_reclaim); [ 11.952695][ C0] local_irq_disable(); [ 11.952695][ C0] lock(console_owner); [ 11.952696][ C0] lock(fs_reclaim); [ 11.952697][ C0] [ 11.952697][ C0] lock(console_owner); [ 11.952698][ C0] [ 11.952698][ C0] *** DEADLOCK *** [ 11.952698][ C0] [ 11.952699][ C0] locks held by swapper/0/0: 4, last CPU#0: [ 11.952700][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 11.952705][ C0] #1: ffffffff9c5fe8b8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 11.952709][ C0] #2: ffffffff9c599cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.952712][ C0] #3: ffffffff9c599d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.952715][ C0] [ 11.952715][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 11.952720][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 11.952722][ C0] HARDIRQ-ON-W at: [ 11.952724][ C0] __lock_acquire+0x388/0xc20 [ 11.952726][ C0] lock_acquire.part.0+0xd4/0x280 [ 11.952727][ C0] fs_reclaim_acquire+0xd5/0x120 [ 11.952731][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 11.952733][ C0] kthread_create_worker_on_node+0xea/0x210 [ 11.952735][ C0] workqueue_init+0x2a/0x680 [ 11.952739][ C0] kernel_init_freeable+0x2fe/0x630 [ 11.952741][ C0] kernel_init+0x21/0x150 [ 11.952745][ C0] ret_from_fork+0x474/0x6b0 [ 11.952748][ C0] ret_from_fork_asm+0x11/0x20 [ 11.952751][ C0] SOFTIRQ-ON-W at: [ 11.952751][ C0] __lock_acquire+0x388/0xc20 [ 11.952753][ C0] lock_acquire.part.0+0xd4/0x280 [ 11.952754][ C0] fs_reclaim_acquire+0xd5/0x120 [ 11.952755][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 11.952756][ C0] kthread_create_worker_on_node+0xea/0x210 [ 11.952757][ C0] workqueue_init+0x2a/0x680 [ 11.952758][ C0] kernel_init_freeable+0x2fe/0x630 [ 11.952759][ C0] kernel_init+0x21/0x150 [ 11.952761][ C0] ret_from_fork+0x474/0x6b0 [ 11.952762][ C0] ret_from_fork_asm+0x11/0x20 [ 11.952763][ C0] INITIAL USE at: [ 11.952764][ C0] __lock_acquire+0x388/0xc20 [ 11.952765][ C0] lock_acquire.part.0+0xd4/0x280 [ 11.952766][ C0] fs_reclaim_acquire+0xd5/0x120 [ 11.952768][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 11.952769][ C0] kthread_create_worker_on_node+0xea/0x210 [ 11.952770][ C0] workqueue_init+0x2a/0x680 [ 11.952771][ C0] kernel_init_freeable+0x2fe/0x630 [ 11.952772][ C0] kernel_init+0x21/0x150 [ 11.952774][ C0] ret_from_fork+0x474/0x6b0 [ 11.952775][ C0] ret_from_fork_asm+0x11/0x20 [ 11.952776][ C0] } [ 11.952777][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 11.952780][ C0] ... acquired at: [ 11.952781][ C0] __lock_acquire+0x518/0xc20 [ 11.952782][ C0] lock_acquire.part.0+0xd4/0x280 [ 11.952783][ C0] fs_reclaim_acquire+0xd5/0x120 [ 11.952785][ C0] __kmalloc_noprof+0xd3/0x760 [ 11.952786][ C0] alloc_buf.isra.0+0x4b/0x260 [ 11.952789][ C0] put_chars+0x1e1/0x2f0 [ 11.952790][ C0] hvc_console_print+0x292/0x780 [ 11.952792][ C0] console_emit_next_record+0x252/0x4f0 [ 11.952793][ C0] console_flush_one_record+0x46f/0x710 [ 11.952794][ C0] console_unlock+0xee/0x1f0 [ 11.952796][ C0] vprintk_emit+0x37c/0x3e0 [ 11.952797][ C0] dev_vprintk_emit+0x27f/0x2c0 [ 11.952800][ C0] dev_printk_emit+0xb9/0xee [ 11.952802][ C0] _dev_info+0xe2/0x116 [ 11.952803][ C0] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 11.952805][ C0] cmos_do_probe+0x73b/0x98a [ 11.952807][ C0] platform_probe+0xfe/0x1f0 [ 11.952809][ C0] call_driver_probe+0x61/0x1c0 [ 11.952811][ C0] really_probe+0x199/0x760 [ 11.952812][ C0] __driver_probe_device+0x24f/0x440 [ 11.952814][ C0] driver_probe_device+0x4a/0xf0 [ 11.952815][ C0] __driver_attach+0x1b8/0x540 [ 11.952816][ C0] bus_for_each_dev+0x130/0x1e0 [ 11.952817][ C0] bus_add_driver+0x2c8/0x530 [ 11.952818][ C0] driver_register+0x1a3/0x390 [ 11.952820][ C0] __platform_driver_probe+0x13f/0x270 [ 11.952821][ C0] cmos_init+0x31/0x40 [ 11.952824][ C0] do_one_initcall+0x124/0x4f0 [ 11.952826][ C0] kernel_init_freeable+0x596/0x630 [ 11.952827][ C0] kernel_init+0x21/0x150 [ 11.952828][ C0] ret_from_fork+0x474/0x6b0 [ 11.952830][ C0] ret_from_fork_asm+0x11/0x20 [ 11.952831][ C0] [ 11.952831][ C0] -> (console_owner){..-.}-{0:0} { [ 11.952833][ C0] IN-SOFTIRQ-W at: [ 11.952834][ C0] __lock_acquire+0x388/0xc20 [ 11.952835][ C0] lock_acquire.part.0+0xd4/0x280 [ 11.952836][ C0] console_lock_spinning_enable+0x5c/0x60 [ 11.952837][ C0] console_emit_next_record+0x1f4/0x4f0 [ 11.952838][ C0] console_flush_one_record+0x46f/0x710 [ 11.952840][ C0] console_unlock+0xee/0x1f0 [ 11.952841][ C0] vprintk_emit+0x37c/0x3e0 [ 11.952842][ C0] _printk+0xc7/0x100 [ 11.952845][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 11.952848][ C0] call_timer_fn+0x160/0x4d0 [ 11.952849][ C0] __run_timers+0x68f/0xaa0 [ 11.952850][ C0] run_timer_softirq+0xf0/0x160 [ 11.952851][ C0] handle_softirqs+0x1d3/0x900 [ 11.952854][ C0] __irq_exit_rcu+0x145/0x1c0 [ 11.952856][ C0] irq_exit_rcu+0xe/0x30 [ 11.952858][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 11.952860][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 11.952862][ C0] pv_native_safe_halt+0xf/0x10 [ 11.952863][ C0] default_idle+0x9/0x10 [ 11.952864][ C0] default_idle_call+0x6e/0xb0 [ 11.952866][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 11.952869][ C0] do_idle+0xd8/0x190 [ 11.952870][ C0] cpu_startup_entry+0x53/0x70 [ 11.952872][ C0] rest_init+0x279/0x280 [ 11.952873][ C0] start_kernel+0x3af/0x3b0 [ 11.952874][ C0] x86_64_start_reservations+0x24/0x30 [ 11.952876][ C0] x86_64_start_kernel+0x12b/0x130 [ 11.952878][ C0] common_startup_64+0x13e/0x148 [ 11.952880][ C0] INITIAL USE at: [ 11.952880][ C0] } [ 11.952881][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 11.952884][ C0] ... acquired at: [ 11.952884][ C0] mark_lock+0x1d7/0xa00 [ 11.952885][ C0] mark_usage+0x42/0x170 [ 11.952886][ C0] __lock_acquire+0x388/0xc20 [ 11.952887][ C0] lock_acquire.part.0+0xd4/0x280 [ 11.952888][ C0] console_lock_spinning_enable+0x5c/0x60 [ 11.952890][ C0] console_emit_next_record+0x1f4/0x4f0 [ 11.952891][ C0] console_flush_one_record+0x46f/0x710 [ 11.952892][ C0] console_unlock+0xee/0x1f0 [ 11.952893][ C0] vprintk_emit+0x37c/0x3e0 [ 11.952895][ C0] _printk+0xc7/0x100 [ 11.952896][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 11.952897][ C0] call_timer_fn+0x160/0x4d0 [ 11.952898][ C0] __run_timers+0x68f/0xaa0 [ 11.952899][ C0] run_timer_softirq+0xf0/0x160 [ 11.952901][ C0] handle_softirqs+0x1d3/0x900 [ 11.952902][ C0] __irq_exit_rcu+0x145/0x1c0 [ 11.952904][ C0] irq_exit_rcu+0xe/0x30 [ 11.952905][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 11.952907][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 11.952908][ C0] pv_native_safe_halt+0xf/0x10 [ 11.952909][ C0] default_idle+0x9/0x10 [ 11.952910][ C0] default_idle_call+0x6e/0xb0 [ 11.952912][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 11.952913][ C0] do_idle+0xd8/0x190 [ 11.952915][ C0] cpu_startup_entry+0x53/0x70 [ 11.952917][ C0] rest_init+0x279/0x280 [ 11.952918][ C0] start_kernel+0x3af/0x3b0 [ 11.952919][ C0] x86_64_start_reservations+0x24/0x30 [ 11.952921][ C0] x86_64_start_kernel+0x12b/0x130 [ 11.952922][ C0] common_startup_64+0x13e/0x148 [ 11.952923][ C0] [ 11.952923][ C0] [ 11.952923][ C0] stack backtrace: [ 11.952927][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.952930][ C0] Tainted: [W]=WARN [ 11.952930][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.952932][ C0] Call Trace: [ 11.952934][ C0] [ 11.952935][ C0] dump_stack_lvl+0x6f/0xa0 [ 11.952938][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 11.952941][ C0] mark_lock_irq+0x989/0x9c0 [ 11.952942][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 11.952945][ C0] mark_lock+0x1d7/0xa00 [ 11.952947][ C0] mark_usage+0x42/0x170 [ 11.952948][ C0] __lock_acquire+0x388/0xc20 [ 11.952950][ C0] lock_acquire.part.0+0xd4/0x280 [ 11.952952][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 11.952953][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.952956][ C0] ? lock_acquire+0x13c/0x160 [ 11.952957][ C0] console_lock_spinning_enable+0x5c/0x60 [ 11.952959][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 11.952960][ C0] console_emit_next_record+0x1f4/0x4f0 [ 11.952962][ C0] ? devkmsg_read+0x4e0/0x4e0 [ 11.952964][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.952966][ C0] ? lock_acquire+0x13c/0x160 [ 11.952967][ C0] console_flush_one_record+0x46f/0x710 [ 11.952969][ C0] ? console_emit_next_record+0x4f0/0x4f0 [ 11.952971][ C0] ? __lock_acquire+0x518/0xc20 [ 11.952973][ C0] console_unlock+0xee/0x1f0 [ 11.952974][ C0] ? console_flush_one_record+0x710/0x710 [ 11.952976][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.952977][ C0] ? lock_acquire+0x60/0x160 [ 11.952979][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.952980][ C0] ? vprintk_emit+0x320/0x3e0 [ 11.952982][ C0] vprintk_emit+0x37c/0x3e0 [ 11.952983][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 11.952986][ C0] ? clocksource_watchdog.part.0+0x4d0/0x540 [ 11.952987][ C0] _printk+0xc7/0x100 [ 11.952989][ C0] ? snapshot_read.cold+0x21/0x21 [ 11.952991][ C0] ? clocksource_watchdog.part.0+0x4d0/0x540 [ 11.952992][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 11.952994][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 11.952995][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 11.952997][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 11.952998][ C0] call_timer_fn+0x160/0x4d0 [ 11.953000][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 11.953001][ C0] ? debug_object_active_state+0x430/0x430 [ 11.953005][ C0] ? find_held_lock+0x2b/0x80 [ 11.953007][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 11.953008][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.953010][ C0] __run_timers+0x68f/0xaa0 [ 11.953011][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 11.953013][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 11.953015][ C0] ? __lock_acquire+0x518/0xc20 [ 11.953017][ C0] ? __rwlock_init+0x150/0x150 [ 11.953019][ C0] run_timer_softirq+0xf0/0x160 [ 11.953021][ C0] ? __run_timers+0xaa0/0xaa0 [ 11.953022][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 11.953025][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.953027][ C0] handle_softirqs+0x1d3/0x900 [ 11.953029][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 11.953030][ C0] ? _local_bh_enable+0xc0/0xc0 [ 11.953033][ C0] __irq_exit_rcu+0x145/0x1c0 [ 11.953035][ C0] irq_exit_rcu+0xe/0x30 [ 11.953036][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 11.953038][ C0] [ 11.953039][ C0] [ 11.953039][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 11.953041][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 11.953042][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 11.953045][ C0] Code: 48 8b 3d 94 f2 f7 01 e8 1f 00 00 00 48 2b 05 58 b3 98 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 16 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 [ 11.953047][ C0] RSP: 0018:ffffffff9c207cf8 EFLAGS: 00000296 [ 11.953049][ C0] RAX: 000000000006177d RBX: ffffffff9c21c600 RCX: ffffffff992ed307 [ 11.953050][ C0] RDX: ffffffff9c21c600 RSI: ffffffff9c07097f RDI: ffffffff9ba8f560 [ 11.953051][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 11.953052][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff3840fa2 [ 11.953052][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 11.953054][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 11.953057][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 11.953058][ C0] default_idle+0x9/0x10 [ 11.953060][ C0] default_idle_call+0x6e/0xb0 [ 11.953061][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 11.953063][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 11.953065][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 11.953068][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.953070][ C0] do_idle+0xd8/0x190 [ 11.953072][ C0] cpu_startup_entry+0x53/0x70 [ 11.953074][ C0] rest_init+0x279/0x280 [ 11.953076][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 11.953079][ C0] ? rest_init+0x280/0x280 [ 11.953080][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 11.953084][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 11.953086][ C0] ? acpi_enable+0x1e4/0x330 [ 11.953088][ C0] start_kernel+0x3af/0x3b0 [ 11.953090][ C0] x86_64_start_reservations+0x24/0x30 [ 11.953092][ C0] x86_64_start_kernel+0x12b/0x130 [ 11.953094][ C0] common_startup_64+0x13e/0x148 [ 11.953096][ C0] [ 11.953100][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.953102][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 11.953103][ C0] preempt_count: 103, expected: 0 [ 11.953104][ C0] RCU nest depth: 0, expected: 0 [ 11.953105][ C0] INFO: lockdep is turned off. [ 11.953105][ C0] irq event stamp: 399245 [ 11.953106][ C0] hardirqs last enabled at (399244): [] __down_trylock_console_sem+0x86/0xa0 [ 11.953108][ C0] hardirqs last disabled at (399245): [] console_emit_next_record+0x3f8/0x4f0 [ 11.953109][ C0] softirqs last enabled at (399224): [] handle_softirqs+0x67c/0x900 [ 11.953111][ C0] softirqs last disabled at (399231): [] __irq_exit_rcu+0x145/0x1c0 [ 11.953113][ C0] Preemption disabled at: [ 11.953114][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 11.953116][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.953118][ C0] Tainted: [W]=WARN [ 11.953119][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.953119][ C0] Call Trace: [ 11.953120][ C0] [ 11.953120][ C0] dump_stack_lvl+0x6f/0xa0 [ 11.953122][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 11.953124][ C0] __might_resched.cold+0x1fe/0x2c1 [ 11.953126][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.953129][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.953131][ C0] __kmalloc_noprof+0x443/0x760 [ 11.953132][ C0] ? __rwlock_init+0x150/0x150 [ 11.953133][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 11.953135][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 11.953136][ C0] alloc_buf.isra.0+0x4b/0x260 [ 11.953139][ C0] put_chars+0x1e1/0x2f0 [ 11.953141][ C0] ? __send_to_port+0x420/0x420 [ 11.953144][ C0] hvc_console_print+0x292/0x780 [ 11.953145][ C0] ? mark_usage+0x42/0x170 [ 11.953146][ C0] ? __lock_acquire+0x388/0xc20 [ 11.953148][ C0] ? hvc_write+0x3a0/0x3a0 [ 11.953149][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 11.953151][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.953152][ C0] ? lock_acquire+0x13c/0x160 [ 11.953154][ C0] console_emit_next_record+0x252/0x4f0 [ 11.953156][ C0] ? devkmsg_read+0x4e0/0x4e0 [ 11.953158][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.953159][ C0] ? lock_acquire+0x13c/0x160 [ 11.953161][ C0] console_flush_one_record+0x46f/0x710 [ 11.953163][ C0] ? console_emit_next_record+0x4f0/0x4f0 [ 11.953164][ C0] ? __lock_acquire+0x518/0xc20 [ 11.953166][ C0] console_unlock+0xee/0x1f0 [ 11.953168][ C0] ? console_flush_one_record+0x710/0x710 [ 11.953169][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.953171][ C0] ? lock_acquire+0x60/0x160 [ 11.953172][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.953174][ C0] ? vprintk_emit+0x320/0x3e0 [ 11.953175][ C0] vprintk_emit+0x37c/0x3e0 [ 11.953177][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 11.953179][ C0] ? clocksource_watchdog.part.0+0x4d0/0x540 [ 11.953181][ C0] _printk+0xc7/0x100 [ 11.953182][ C0] ? snapshot_read.cold+0x21/0x21 [ 11.953184][ C0] ? clocksource_watchdog.part.0+0x4d0/0x540 [ 11.953185][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 11.953187][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 11.953189][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 11.953190][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 11.953191][ C0] call_timer_fn+0x160/0x4d0 [ 11.953193][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 11.953194][ C0] ? debug_object_active_state+0x430/0x430 [ 11.953196][ C0] ? find_held_lock+0x2b/0x80 [ 11.953198][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 11.953200][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.953202][ C0] __run_timers+0x68f/0xaa0 [ 11.953203][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 11.953205][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 11.953206][ C0] ? __lock_acquire+0x518/0xc20 [ 11.953208][ C0] ? __rwlock_init+0x150/0x150 [ 11.953210][ C0] run_timer_softirq+0xf0/0x160 [ 11.953212][ C0] ? __run_timers+0xaa0/0xaa0 [ 11.953213][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 11.953215][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.953217][ C0] handle_softirqs+0x1d3/0x900 [ 11.953219][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 11.953220][ C0] ? _local_bh_enable+0xc0/0xc0 [ 11.953222][ C0] __irq_exit_rcu+0x145/0x1c0 [ 11.953224][ C0] irq_exit_rcu+0xe/0x30 [ 11.953226][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 11.953228][ C0] [ 11.953228][ C0] [ 11.953229][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 11.953230][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 11.953232][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 11.953233][ C0] Code: 48 8b 3d 94 f2 f7 01 e8 1f 00 00 00 48 2b 05 58 b3 98 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 16 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 [ 11.953234][ C0] RSP: 0018:ffffffff9c207cf8 EFLAGS: 00000296 [ 11.953235][ C0] RAX: 000000000006177d RBX: ffffffff9c21c600 RCX: ffffffff992ed307 [ 11.953236][ C0] RDX: ffffffff9c21c600 RSI: ffffffff9c07097f RDI: ffffffff9ba8f560 [ 11.953237][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 11.953238][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff3840fa2 [ 11.953238][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 11.953240][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 11.953242][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 11.953244][ C0] default_idle+0x9/0x10 [ 11.953245][ C0] default_idle_call+0x6e/0xb0 [ 11.953247][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 11.953249][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 11.953251][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 11.953253][ C0] ? rcu_is_watching+0x16/0xd0 [ 11.953255][ C0] do_idle+0xd8/0x190 [ 11.953257][ C0] cpu_startup_entry+0x53/0x70 [ 11.953259][ C0] rest_init+0x279/0x280 [ 11.953261][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 11.953263][ C0] ? rest_init+0x280/0x280 [ 11.953265][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 11.953267][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 11.953268][ C0] ? acpi_enable+0x1e4/0x330 [ 11.953270][ C0] start_kernel+0x3af/0x3b0 [ 11.953272][ C0] x86_64_start_reservations+0x24/0x30 [ 11.953273][ C0] x86_64_start_kernel+0x12b/0x130 [ 11.953275][ C0] common_startup_64+0x13e/0x148 [ 11.953278][ C0]