[ 9.237306][ T202] ip (202) used greatest stack depth: 24208 bytes left [ 9.237321][ T202] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.237323][ T202] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 202, name: ip [ 9.237325][ T202] preempt_count: 2, expected: 0 [ 9.237325][ T202] RCU nest depth: 0, expected: 0 [ 9.237326][ T202] locks held by ip/202: 5, last CPU#2: [ 9.237328][ T202] #0: ffffffffba0167b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 9.237344][ T202] #1: ffffffffba17ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.237348][ T202] #2: ffffffffba17de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.237353][ T202] #3: ffffffffba09d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.237356][ T202] #4: ffffffffba09d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.237360][ T202] irq event stamp: 42632 [ 9.237361][ T202] hardirqs last enabled at (42631): [] __down_trylock_console_sem+0x86/0xa0 [ 9.237364][ T202] hardirqs last disabled at (42632): [] console_emit_next_record+0x3d4/0x4c0 [ 9.237366][ T202] softirqs last enabled at (41316): [] netlink_release+0x17b/0xcf0 [ 9.237371][ T202] softirqs last disabled at (41314): [] netlink_release+0xd2/0xcf0 [ 9.237373][ T202] Preemption disabled at: [ 9.237374][ T202] [<0000000000000000>] 0x0 [ 9.237380][ T202] CPU: 2 UID: 0 PID: 202 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.237384][ T202] Tainted: [W]=WARN [ 9.237385][ T202] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.237388][ T202] Call Trace: [ 9.237390][ T202] [ 9.237391][ T202] dump_stack_lvl+0x6f/0xa0 [ 9.237398][ T202] __might_resched.cold+0x1fe/0x2c1 [ 9.237402][ T202] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.237406][ T202] ? __kmalloc_noprof+0xdb/0x760 [ 9.237410][ T202] __kmalloc_noprof+0x443/0x760 [ 9.237412][ T202] ? alloc_buf.isra.0+0x4b/0x260 [ 9.237418][ T202] ? do_raw_spin_unlock+0x59/0x250 [ 9.237421][ T202] alloc_buf.isra.0+0x4b/0x260 [ 9.237424][ T202] put_chars+0x1e1/0x2f0 [ 9.237427][ T202] ? __send_to_port+0x420/0x420 [ 9.237428][ T202] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.237431][ T202] ? validate_chain+0x38b/0xc20 [ 9.237437][ T202] hvc_console_print+0x292/0x780 [ 9.237444][ T202] ? hvc_write+0x3a0/0x3a0 [ 9.237446][ T202] ? rcu_is_watching+0x16/0xd0 [ 9.237448][ T202] ? lock_acquire+0x13c/0x160 [ 9.237452][ T202] console_emit_next_record+0x22f/0x4c0 [ 9.237456][ T202] ? devkmsg_read+0x4b0/0x4b0 [ 9.237458][ T202] ? console_flush_one_record+0x106/0x710 [ 9.237461][ T202] ? rcu_is_watching+0x16/0xd0 [ 9.237463][ T202] ? lock_acquire+0x13c/0x160 [ 9.237466][ T202] console_flush_one_record+0x46f/0x710 [ 9.237470][ T202] ? console_emit_next_record+0x4c0/0x4c0 [ 9.237472][ T202] ? __lock_acquire+0x518/0xc20 [ 9.237477][ T202] console_unlock+0xee/0x1f0 [ 9.237480][ T202] ? console_flush_one_record+0x710/0x710 [ 9.237482][ T202] ? rcu_is_watching+0x16/0xd0 [ 9.237484][ T202] ? lock_acquire+0xe0/0x160 [ 9.237487][ T202] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.237489][ T202] ? vprintk_emit+0x320/0x3e0 [ 9.237492][ T202] vprintk_emit+0x37c/0x3e0 [ 9.237495][ T202] ? wake_up_klogd_work_func+0x90/0x90 [ 9.237497][ T202] ? __lock_acquire+0x518/0xc20 [ 9.237501][ T202] _printk+0xc7/0x100 [ 9.237505][ T202] ? snapshot_read.cold+0x21/0x21 [ 9.237507][ T202] ? do_raw_spin_lock+0x131/0x280 [ 9.237510][ T202] ? __rwlock_init+0x150/0x150 [ 9.237513][ T202] ? do_raw_spin_lock+0x131/0x280 [ 9.237516][ T202] do_exit.cold+0x82/0x9c [ 9.237520][ T202] ? exit_notify+0x890/0x890 [ 9.237521][ T202] ? __lock_release.isra.0+0x69/0x1a0 [ 9.237524][ T202] ? rcu_is_watching+0x16/0xd0 [ 9.237527][ T202] do_group_exit+0xb8/0x370 [ 9.237530][ T202] __x64_sys_exit_group+0x3c/0x50 [ 9.237532][ T202] x64_sys_call+0x1567/0x1570 [ 9.237534][ T202] do_syscall_64+0xff/0x530 [ 9.237538][ T202] ? exc_page_fault+0xee/0x100 [ 9.237541][ T202] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.237543][ T202] RIP: 0033:0x7eff487e41b8 [ 9.237545][ T202] Code: Unable to access opcode bytes at 0x7eff487e418e. [ 9.237546][ T202] RSP: 002b:00007ffd057e3048 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 9.237548][ T202] RAX: ffffffffffffffda RBX: 00007eff48914f88 RCX: 00007eff487e41b8 [ 9.237550][ T202] RDX: 00007eff4852efc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 9.237550][ T202] RBP: 00007ffd057e30a0 R08: 0000000000000000 R09: 0000000000000000 [ 9.237551][ T202] R10: 00007ffd057e2e60 R11: 0000000000000246 R12: 0000000000000001 [ 9.237552][ T202] R13: 0000000000000000 R14: 00007eff48913680 R15: 00007eff48914fa0 [ 9.237559][ T202] [ 9.702546][ T225] ip (225) used greatest stack depth: 23728 bytes left [ 10.218921][ T239] ip (239) used greatest stack depth: 23296 bytes left [ 10.238595][ T240] veth0: renamed from ioam-veth-alpha [ 10.238651][ T240] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.238653][ T240] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 240, name: ip [ 10.238654][ T240] preempt_count: 1, expected: 0 [ 10.238655][ T240] RCU nest depth: 0, expected: 0 [ 10.238656][ T240] locks held by ip/240: 5, last CPU#0: [ 10.238658][ T240] #0: ffffffffba91b740 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11b0 [ 10.238670][ T240] #1: ffffffffba17ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.238675][ T240] #2: ffffffffba17de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.238679][ T240] #3: ffffffffba09d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.238683][ T240] #4: ffffffffba09d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.238687][ T240] irq event stamp: 16382 [ 10.238688][ T240] hardirqs last enabled at (16381): [] __down_trylock_console_sem+0x86/0xa0 [ 10.238691][ T240] hardirqs last disabled at (16382): [] console_emit_next_record+0x3d4/0x4c0 [ 10.238693][ T240] softirqs last enabled at (16376): [] netif_change_name+0x216/0x8c0 [ 10.238696][ T240] softirqs last disabled at (16374): [] netif_change_name+0x1ad/0x8c0 [ 10.238699][ T240] Preemption disabled at: [ 10.238699][ T240] [] vprintk_emit+0x31b/0x3e0 [ 10.238704][ T240] CPU: 0 UID: 0 PID: 240 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.238707][ T240] Tainted: [W]=WARN [ 10.238708][ T240] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.238710][ T240] Call Trace: [ 10.238711][ T240] [ 10.238712][ T240] dump_stack_lvl+0x6f/0xa0 [ 10.238718][ T240] ? vprintk_emit+0x31b/0x3e0 [ 10.238720][ T240] __might_resched.cold+0x1fe/0x2c1 [ 10.238724][ T240] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.238728][ T240] ? __kmalloc_noprof+0xdb/0x760 [ 10.238732][ T240] __kmalloc_noprof+0x443/0x760 [ 10.238734][ T240] ? alloc_buf.isra.0+0x4b/0x260 [ 10.238741][ T240] ? do_raw_spin_unlock+0x59/0x250 [ 10.238744][ T240] alloc_buf.isra.0+0x4b/0x260 [ 10.238747][ T240] put_chars+0x1e1/0x2f0 [ 10.238749][ T240] ? __send_to_port+0x420/0x420 [ 10.238756][ T240] hvc_console_print+0x292/0x780 [ 10.238763][ T240] ? hvc_write+0x3a0/0x3a0 [ 10.238767][ T240] ? lock_acquire+0x13c/0x160 [ 10.238771][ T240] console_emit_next_record+0x22f/0x4c0 [ 10.238775][ T240] ? devkmsg_read+0x4b0/0x4b0 [ 10.238777][ T240] ? console_flush_one_record+0x106/0x710 [ 10.238780][ T240] ? rcu_is_watching+0x16/0xd0 [ 10.238782][ T240] ? lock_acquire+0x13c/0x160 [ 10.238786][ T240] console_flush_one_record+0x46f/0x710 [ 10.238790][ T240] ? console_emit_next_record+0x4c0/0x4c0 [ 10.238792][ T240] ? __lock_acquire+0x518/0xc20 [ 10.238798][ T240] console_unlock+0xee/0x1f0 [ 10.238800][ T240] ? console_flush_one_record+0x710/0x710 [ 10.238802][ T240] ? rcu_is_watching+0x16/0xd0 [ 10.238804][ T240] ? lock_acquire+0xe0/0x160 [ 10.238807][ T240] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.238809][ T240] ? vprintk_emit+0x320/0x3e0 [ 10.238812][ T240] vprintk_emit+0x37c/0x3e0 [ 10.238815][ T240] ? wake_up_klogd_work_func+0x90/0x90 [ 10.238817][ T240] ? save_trace+0x68/0x280 [ 10.238821][ T240] _printk+0xc7/0x100 [ 10.238825][ T240] ? snapshot_read.cold+0x21/0x21 [ 10.238828][ T240] ? validate_chain+0x34a/0xc20 [ 10.238830][ T240] ? netdev_name+0x43/0x60 [ 10.238834][ T240] ? __netdev_printk+0x17f/0x1d0 [ 10.238837][ T240] ? do_setlink.isra.0+0x1f7d/0x2a60 [ 10.238839][ T240] netdev_info+0xe2/0x116 [ 10.238842][ T240] ? netdev_notice+0x120/0x120 [ 10.238844][ T240] ? find_held_lock+0x2b/0x80 [ 10.238847][ T240] ? __lock_release.isra.0+0x69/0x1a0 [ 10.238850][ T240] ? mark_held_locks+0x40/0x70 [ 10.238853][ T240] ? do_setlink.isra.0+0x1f7d/0x2a60 [ 10.238855][ T240] netif_change_name.cold+0x4f/0x89 [ 10.238857][ T240] ? is_bpf_text_address+0x68/0x110 [ 10.238863][ T240] ? netdev_adjacent_rename_links+0x470/0x470 [ 10.238865][ T240] ? __kernel_text_address+0x12/0x30 [ 10.238868][ T240] ? write_profile+0xf0/0xf0 [ 10.238872][ T240] ? __asan_memset+0x27/0x50 [ 10.238878][ T240] do_setlink.isra.0+0x1f7d/0x2a60 [ 10.238881][ T240] ? stack_trace_save+0x93/0xc0 [ 10.238883][ T240] ? rtnl_link_get_size+0x350/0x350 [ 10.238884][ T240] ? rcu_read_lock_any_held+0x66/0x90 [ 10.238887][ T240] ? stack_depot_save_flags+0x38e/0x790 [ 10.238890][ T240] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.238892][ T240] ? rcu_read_lock_any_held+0x3c/0x90 [ 10.238894][ T240] ? validate_chain+0x38b/0xc20 [ 10.238896][ T240] ? kasan_save_stack+0x3d/0x50 [ 10.238898][ T240] ? kasan_save_stack+0x2f/0x50 [ 10.238899][ T240] ? kasan_save_track+0x14/0x30 [ 10.238900][ T240] ? __kasan_kmalloc+0x7b/0x90 [ 10.238903][ T240] ? __lock_acquire+0x518/0xc20 [ 10.238904][ T240] ? snd_ac97_proc_read_main+0x4de/0x1f00 [ 10.238908][ T240] ? ___sys_sendmsg+0xd0/0x1d0 [ 10.238911][ T240] ? lock_acquire.part.0+0xd4/0x280 [ 10.238913][ T240] ? rtnl_newlink+0x9a8/0x11b0 [ 10.238916][ T240] ? rcu_is_watching+0x16/0xd0 [ 10.238918][ T240] ? lock_acquire+0x13c/0x160 [ 10.238919][ T240] ? rcu_is_watching+0x16/0xd0 [ 10.238920][ T240] ? rcu_is_watching+0x16/0xd0 [ 10.238922][ T240] ? trace_contention_end+0xb3/0x180 [ 10.238924][ T240] ? __mutex_lock+0x1db/0x1ea0 [ 10.238927][ T240] ? __mutex_lock+0x9a3/0x1ea0 [ 10.238929][ T240] ? rtnl_newlink+0x9a8/0x11b0 [ 10.238932][ T240] ? ww_mutex_lock+0x160/0x160 [ 10.238933][ T240] ? nla_get_range_signed+0x3d0/0x3d0 [ 10.238938][ T240] ? rcu_is_watching+0x16/0xd0 [ 10.238940][ T240] ? __rtnl_newlink+0x3fa/0xa50 [ 10.238945][ T240] rtnl_newlink+0x9f1/0x11b0 [ 10.238951][ T240] ? rtnl_bridge_getlink+0x850/0x850 [ 10.238953][ T240] ? __lock_acquire+0x518/0xc20 [ 10.238957][ T240] ? lock_acquire.part.0+0xd4/0x280 [ 10.238959][ T240] ? find_held_lock+0x2b/0x80 [ 10.238961][ T240] ? rtnl_bridge_getlink+0x850/0x850 [ 10.238963][ T240] ? __lock_release.isra.0+0x69/0x1a0 [ 10.238966][ T240] ? rtnl_bridge_getlink+0x850/0x850 [ 10.238968][ T240] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 10.238972][ T240] ? rtnl_link_fill+0x920/0x920 [ 10.238973][ T240] ? __lock_acquire+0x518/0xc20 [ 10.238977][ T240] ? lock_acquire.part.0+0xd4/0x280 [ 10.238979][ T240] ? find_held_lock+0x2b/0x80 [ 10.238983][ T240] netlink_rcv_skb+0x14e/0x3a0 [ 10.238986][ T240] ? rtnl_link_fill+0x920/0x920 [ 10.238989][ T240] ? netlink_ack+0xcf0/0xcf0 [ 10.238995][ T240] ? netlink_deliver_tap+0xc5/0x330 [ 10.238997][ T240] ? netlink_deliver_tap+0x13c/0x330 [ 10.239002][ T240] netlink_unicast+0x486/0x750 [ 10.239006][ T240] ? netlink_attachskb+0x810/0x810 [ 10.239008][ T240] ? find_held_lock+0x2b/0x80 [ 10.239012][ T240] netlink_sendmsg+0x75b/0xc90 [ 10.239016][ T240] ? netlink_unicast+0x750/0x750 [ 10.239020][ T240] ? __lock_release.isra.0+0x69/0x1a0 [ 10.239022][ T240] ? __import_iovec+0x36c/0x620 [ 10.239027][ T240] __sock_sendmsg+0xce/0x190 [ 10.239030][ T240] ____sys_sendmsg+0x61a/0x860 [ 10.239032][ T240] ? copy_msghdr_from_user+0x279/0x420 [ 10.239034][ T240] ? sock_write_iter+0x400/0x400 [ 10.239036][ T240] ? move_addr_to_kernel+0x40/0x40 [ 10.239043][ T240] ___sys_sendmsg+0x14e/0x1d0 [ 10.239046][ T240] ? copy_msghdr_from_user+0x420/0x420 [ 10.239062][ T240] __sys_sendmsg+0x12c/0x1d0 [ 10.239065][ T240] ? __sys_sendmsg_sock+0x20/0x20 [ 10.239071][ T240] ? rcu_is_watching+0x16/0xd0 [ 10.239074][ T240] do_syscall_64+0xff/0x530 [ 10.239077][ T240] ? exc_page_fault+0xee/0x100 [ 10.239080][ T240] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.239082][ T240] RIP: 0033:0x7f0e6d94754e [ 10.239086][ T240] 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.239087][ T240] RSP: 002b:00007ffea2ca5d90 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 10.239090][ T240] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f0e6d94754e [ 10.239091][ T240] RDX: 0000000000000000 RSI: 00007ffea2ca5e40 RDI: 0000000000000005 [ 10.239092][ T240] RBP: 00007ffea2ca5da0 R08: 0000000000000000 R09: 0000000000000000 [ 10.239093][ T240] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a916be6 [ 10.239094][ T240] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffea2ca6510 [ 10.239101][ T240] [ 10.283946][ T240] ip (240) used greatest stack depth: 23208 bytes left [ 10.309970][ T241] veth0: renamed from ioam-veth-betaL [ 10.343464][ T242] veth1: renamed from ioam-veth-betaR [ 10.367594][ T243] veth0: renamed from ioam-veth-gamma [ 58.497528][ C0] clocksource: Watchdog remote CPU 3 read timed out [ 58.497716][ C0] [ 58.497718][ C0] ======================================================== [ 58.497719][ C0] WARNING: possible irq lock inversion dependency detected [ 58.497721][ C0] 7.2.0-virtme #1 Tainted: G W [ 58.497722][ C0] -------------------------------------------------------- [ 58.497723][ C0] swapper/0/0 just changed the state of lock: [ 58.497724][ C0] ffffffffba09d760 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 58.497736][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 58.497737][ C0] (fs_reclaim){+.+.}-{0:0} [ 58.497739][ C0] [ 58.497739][ C0] [ 58.497739][ C0] and interrupts could create inverse lock ordering between them. [ 58.497739][ C0] [ 58.497740][ C0] [ 58.497740][ C0] other info that might help us debug this: [ 58.497740][ C0] Possible interrupt unsafe locking scenario: [ 58.497740][ C0] [ 58.497741][ C0] CPU0 CPU1 [ 58.497741][ C0] ---- ---- [ 58.497741][ C0] lock(fs_reclaim); [ 58.497742][ C0] local_irq_disable(); [ 58.497743][ C0] lock(console_owner); [ 58.497744][ C0] lock(fs_reclaim); [ 58.497745][ C0] [ 58.497745][ C0] lock(console_owner); [ 58.497746][ C0] [ 58.497746][ C0] *** DEADLOCK *** [ 58.497746][ C0] [ 58.497746][ C0] locks held by swapper/0/0: 4, last CPU#0: [ 58.497747][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 58.497753][ C0] #1: ffffffffba1e29f8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 58.497757][ C0] #2: ffffffffba17ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 58.497759][ C0] #3: ffffffffba17de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 58.497763][ C0] [ 58.497763][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 58.497767][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 58.497769][ C0] HARDIRQ-ON-W at: [ 58.497770][ C0] __lock_acquire+0x388/0xc20 [ 58.497774][ C0] lock_acquire.part.0+0xd4/0x280 [ 58.497775][ C0] fs_reclaim_acquire+0xd5/0x120 [ 58.497778][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 58.497780][ C0] kthread_create_worker_on_node+0xea/0x210 [ 58.497783][ C0] workqueue_init+0x2a/0x680 [ 58.497787][ C0] kernel_init_freeable+0x2fe/0x630 [ 58.497790][ C0] kernel_init+0x21/0x150 [ 58.497794][ C0] ret_from_fork+0x474/0x6b0 [ 58.497797][ C0] ret_from_fork_asm+0x11/0x20 [ 58.497800][ C0] SOFTIRQ-ON-W at: [ 58.497801][ C0] __lock_acquire+0x388/0xc20 [ 58.497802][ C0] lock_acquire.part.0+0xd4/0x280 [ 58.497804][ C0] fs_reclaim_acquire+0xd5/0x120 [ 58.497805][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 58.497806][ C0] kthread_create_worker_on_node+0xea/0x210 [ 58.497807][ C0] workqueue_init+0x2a/0x680 [ 58.497809][ C0] kernel_init_freeable+0x2fe/0x630 [ 58.497810][ C0] kernel_init+0x21/0x150 [ 58.497812][ C0] ret_from_fork+0x474/0x6b0 [ 58.497813][ C0] ret_from_fork_asm+0x11/0x20 [ 58.497814][ C0] INITIAL USE at: [ 58.497815][ C0] __lock_acquire+0x388/0xc20 [ 58.497816][ C0] lock_acquire.part.0+0xd4/0x280 [ 58.497817][ C0] fs_reclaim_acquire+0xd5/0x120 [ 58.497819][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 58.497820][ C0] kthread_create_worker_on_node+0xea/0x210 [ 58.497821][ C0] workqueue_init+0x2a/0x680 [ 58.497823][ C0] kernel_init_freeable+0x2fe/0x630 [ 58.497824][ C0] kernel_init+0x21/0x150 [ 58.497825][ C0] ret_from_fork+0x474/0x6b0 [ 58.497827][ C0] ret_from_fork_asm+0x11/0x20 [ 58.497828][ C0] } [ 58.497828][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 58.497832][ C0] ... acquired at: [ 58.497833][ C0] __lock_acquire+0x518/0xc20 [ 58.497835][ C0] lock_acquire.part.0+0xd4/0x280 [ 58.497836][ C0] fs_reclaim_acquire+0xd5/0x120 [ 58.497837][ C0] __kmalloc_noprof+0xd3/0x760 [ 58.497838][ C0] alloc_buf.isra.0+0x4b/0x260 [ 58.497842][ C0] put_chars+0x1e1/0x2f0 [ 58.497843][ C0] hvc_console_print+0x292/0x780 [ 58.497846][ C0] console_emit_next_record+0x22f/0x4c0 [ 58.497847][ C0] console_flush_one_record+0x46f/0x710 [ 58.497849][ C0] console_unlock+0xee/0x1f0 [ 58.497851][ C0] vprintk_emit+0x37c/0x3e0 [ 58.497851][ C0] _printk+0xc7/0x100 [ 58.497854][ C0] i8042_pnp_init+0xf7/0x3c0 [ 58.497857][ C0] i8042_platform_init+0x3f9/0x460 [ 58.497858][ C0] i8042_init+0x45/0x130 [ 58.497859][ C0] do_one_initcall+0x124/0x4f0 [ 58.497861][ C0] kernel_init_freeable+0x596/0x630 [ 58.497862][ C0] kernel_init+0x21/0x150 [ 58.497864][ C0] ret_from_fork+0x474/0x6b0 [ 58.497865][ C0] ret_from_fork_asm+0x11/0x20 [ 58.497866][ C0] [ 58.497866][ C0] -> (console_owner){..-.}-{0:0} { [ 58.497868][ C0] IN-SOFTIRQ-W at: [ 58.497869][ C0] __lock_acquire+0x388/0xc20 [ 58.497870][ C0] lock_acquire.part.0+0xd4/0x280 [ 58.497872][ C0] console_lock_spinning_enable+0x5c/0x60 [ 58.497873][ C0] console_emit_next_record+0x1d1/0x4c0 [ 58.497875][ C0] console_flush_one_record+0x46f/0x710 [ 58.497876][ C0] console_unlock+0xee/0x1f0 [ 58.497878][ C0] vprintk_emit+0x37c/0x3e0 [ 58.497879][ C0] _printk+0xc7/0x100 [ 58.497880][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 58.497882][ C0] call_timer_fn+0x160/0x4d0 [ 58.497884][ C0] __run_timers+0x68f/0xaa0 [ 58.497886][ C0] run_timer_softirq+0xf0/0x160 [ 58.497887][ C0] handle_softirqs+0x1d3/0x900 [ 58.497889][ C0] __irq_exit_rcu+0x145/0x1c0 [ 58.497891][ C0] irq_exit_rcu+0xe/0x30 [ 58.497892][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 58.497893][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 58.497895][ C0] pv_native_safe_halt+0xf/0x10 [ 58.497897][ C0] default_idle+0x9/0x10 [ 58.497898][ C0] default_idle_call+0x6e/0xb0 [ 58.497899][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 58.497901][ C0] do_idle+0xd8/0x190 [ 58.497903][ C0] cpu_startup_entry+0x53/0x70 [ 58.497904][ C0] rest_init+0x279/0x280 [ 58.497905][ C0] start_kernel+0x3b9/0x3c0 [ 58.497907][ C0] x86_64_start_reservations+0x24/0x30 [ 58.497908][ C0] x86_64_start_kernel+0x12b/0x130 [ 58.497909][ C0] common_startup_64+0x13e/0x148 [ 58.497912][ C0] INITIAL USE at: [ 58.497913][ C0] } [ 58.497913][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 58.497916][ C0] ... acquired at: [ 58.497916][ C0] mark_lock+0x1d7/0xa00 [ 58.497917][ C0] mark_usage+0x42/0x170 [ 58.497919][ C0] __lock_acquire+0x388/0xc20 [ 58.497920][ C0] lock_acquire.part.0+0xd4/0x280 [ 58.497921][ C0] console_lock_spinning_enable+0x5c/0x60 [ 58.497923][ C0] console_emit_next_record+0x1d1/0x4c0 [ 58.497924][ C0] console_flush_one_record+0x46f/0x710 [ 58.497926][ C0] console_unlock+0xee/0x1f0 [ 58.497928][ C0] vprintk_emit+0x37c/0x3e0 [ 58.497928][ C0] _printk+0xc7/0x100 [ 58.497930][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 58.497931][ C0] call_timer_fn+0x160/0x4d0 [ 58.497932][ C0] __run_timers+0x68f/0xaa0 [ 58.497934][ C0] run_timer_softirq+0xf0/0x160 [ 58.497935][ C0] handle_softirqs+0x1d3/0x900 [ 58.497937][ C0] __irq_exit_rcu+0x145/0x1c0 [ 58.497938][ C0] irq_exit_rcu+0xe/0x30 [ 58.497939][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 58.497940][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 58.497941][ C0] pv_native_safe_halt+0xf/0x10 [ 58.497942][ C0] default_idle+0x9/0x10 [ 58.497943][ C0] default_idle_call+0x6e/0xb0 [ 58.497945][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 58.497946][ C0] do_idle+0xd8/0x190 [ 58.497947][ C0] cpu_startup_entry+0x53/0x70 [ 58.497948][ C0] rest_init+0x279/0x280 [ 58.497949][ C0] start_kernel+0x3b9/0x3c0 [ 58.497951][ C0] x86_64_start_reservations+0x24/0x30 [ 58.497952][ C0] x86_64_start_kernel+0x12b/0x130 [ 58.497953][ C0] common_startup_64+0x13e/0x148 [ 58.497954][ C0] [ 58.497955][ C0] [ 58.497955][ C0] stack backtrace: [ 58.497957][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 58.497960][ C0] Tainted: [W]=WARN [ 58.497961][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 58.497963][ C0] Call Trace: [ 58.497964][ C0] [ 58.497965][ C0] dump_stack_lvl+0x6f/0xa0 [ 58.497969][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 58.497972][ C0] mark_lock_irq+0x989/0x9c0 [ 58.497973][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 58.497976][ C0] mark_lock+0x1d7/0xa00 [ 58.497978][ C0] mark_usage+0x42/0x170 [ 58.497980][ C0] __lock_acquire+0x388/0xc20 [ 58.497982][ C0] lock_acquire.part.0+0xd4/0x280 [ 58.497984][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 58.497986][ C0] ? rcu_is_watching+0x16/0xd0 [ 58.497988][ C0] ? lock_acquire+0x13c/0x160 [ 58.497990][ C0] console_lock_spinning_enable+0x5c/0x60 [ 58.497991][ C0] ? console_lock_spinning_enable+0x40/0x60 [ 58.497993][ C0] console_emit_next_record+0x1d1/0x4c0 [ 58.497995][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 58.497997][ C0] ? console_flush_one_record+0x106/0x710 [ 58.497999][ C0] ? rcu_is_watching+0x16/0xd0 [ 58.498000][ C0] ? lock_acquire+0x13c/0x160 [ 58.498002][ C0] console_flush_one_record+0x46f/0x710 [ 58.498004][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 58.498006][ C0] ? __lock_acquire+0x518/0xc20 [ 58.498008][ C0] console_unlock+0xee/0x1f0 [ 58.498010][ C0] ? console_flush_one_record+0x710/0x710 [ 58.498012][ C0] ? rcu_is_watching+0x16/0xd0 [ 58.498013][ C0] ? lock_acquire+0xe0/0x160 [ 58.498015][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 58.498017][ C0] ? vprintk_emit+0x320/0x3e0 [ 58.498018][ C0] vprintk_emit+0x37c/0x3e0 [ 58.498019][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 58.498021][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 58.498023][ C0] _printk+0xc7/0x100 [ 58.498025][ C0] ? snapshot_read.cold+0x21/0x21 [ 58.498027][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 58.498028][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 58.498030][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 58.498032][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 58.498033][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 58.498035][ C0] call_timer_fn+0x160/0x4d0 [ 58.498037][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 58.498039][ C0] ? debug_object_active_state+0x430/0x430 [ 58.498041][ C0] ? find_held_lock+0x2b/0x80 [ 58.498043][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 58.498045][ C0] ? rcu_is_watching+0x16/0xd0 [ 58.498046][ C0] __run_timers+0x68f/0xaa0 [ 58.498048][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 58.498050][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 58.498052][ C0] ? __lock_acquire+0x518/0xc20 [ 58.498055][ C0] ? __rwlock_init+0x150/0x150 [ 58.498057][ C0] run_timer_softirq+0xf0/0x160 [ 58.498059][ C0] ? __run_timers+0xaa0/0xaa0 [ 58.498061][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 58.498064][ C0] ? rcu_is_watching+0x16/0xd0 [ 58.498065][ C0] handle_softirqs+0x1d3/0x900 [ 58.498067][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 58.498069][ C0] ? _local_bh_enable+0xc0/0xc0 [ 58.498071][ C0] __irq_exit_rcu+0x145/0x1c0 [ 58.498072][ C0] irq_exit_rcu+0xe/0x30 [ 58.498074][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 58.498075][ C0] [ 58.498076][ C0] [ 58.498076][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 58.498078][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 58.498079][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 58.498082][ 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 [ 58.498083][ C0] RSP: 0018:ffffffffb9e07cf8 EFLAGS: 00000296 [ 58.498085][ C0] RAX: 00000000003c4aa1 RBX: ffffffffb9e30600 RCX: ffffffffb6506247 [ 58.498087][ C0] RDX: ffffffffb9e30600 RSI: ffffffffb9711027 RDI: ffffffffb90949e0 [ 58.498087][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 58.498088][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff73c0fa2 [ 58.498089][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 58.498090][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 58.498093][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 58.498094][ C0] default_idle+0x9/0x10 [ 58.498096][ C0] default_idle_call+0x6e/0xb0 [ 58.498097][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 58.498099][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 58.498100][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 58.498103][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 58.498104][ C0] ? rcu_is_watching+0x16/0xd0 [ 58.498105][ C0] do_idle+0xd8/0x190 [ 58.498107][ C0] cpu_startup_entry+0x53/0x70 [ 58.498108][ C0] rest_init+0x279/0x280 [ 58.498110][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 58.498112][ C0] ? rest_init+0x280/0x280 [ 58.498114][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 58.498117][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 58.498119][ C0] ? acpi_enable+0x1e4/0x330 [ 58.498121][ C0] start_kernel+0x3b9/0x3c0 [ 58.498122][ C0] x86_64_start_reservations+0x24/0x30 [ 58.498124][ C0] x86_64_start_kernel+0x12b/0x130 [ 58.498125][ C0] common_startup_64+0x13e/0x148 [ 58.498128][ C0] [ 58.498133][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 58.498134][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 58.498135][ C0] preempt_count: 103, expected: 0 [ 58.498136][ C0] RCU nest depth: 0, expected: 0 [ 58.498137][ C0] INFO: lockdep is turned off. [ 58.498137][ C0] irq event stamp: 3951281 [ 58.498138][ C0] hardirqs last enabled at (3951280): [] __down_trylock_console_sem+0x86/0xa0 [ 58.498140][ C0] hardirqs last disabled at (3951281): [] console_emit_next_record+0x3d4/0x4c0 [ 58.498142][ C0] softirqs last enabled at (3951254): [] handle_softirqs+0x67c/0x900 [ 58.498144][ C0] softirqs last disabled at (3951267): [] __irq_exit_rcu+0x145/0x1c0 [ 58.498145][ C0] Preemption disabled at: [ 58.498146][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 58.498149][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 58.498151][ C0] Tainted: [W]=WARN [ 58.498151][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 58.498152][ C0] Call Trace: [ 58.498152][ C0] [ 58.498153][ C0] dump_stack_lvl+0x6f/0xa0 [ 58.498155][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 58.498157][ C0] __might_resched.cold+0x1fe/0x2c1 [ 58.498160][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 58.498163][ C0] ? rcu_is_watching+0x16/0xd0 [ 58.498164][ C0] __kmalloc_noprof+0x443/0x760 [ 58.498165][ C0] ? __rwlock_init+0x150/0x150 [ 58.498167][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 58.498169][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 58.498171][ C0] alloc_buf.isra.0+0x4b/0x260 [ 58.498173][ C0] put_chars+0x1e1/0x2f0 [ 58.498175][ C0] ? __send_to_port+0x420/0x420 [ 58.498177][ C0] ? mark_lock+0x1d7/0xa00 [ 58.498179][ C0] hvc_console_print+0x292/0x780 [ 58.498181][ C0] ? hvc_write+0x3a0/0x3a0 [ 58.498183][ C0] ? rcu_is_watching+0x16/0xd0 [ 58.498184][ C0] ? rcu_is_watching+0x16/0xd0 [ 58.498186][ C0] console_emit_next_record+0x22f/0x4c0 [ 58.498188][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 58.498190][ C0] ? console_flush_one_record+0x106/0x710 [ 58.498192][ C0] ? rcu_is_watching+0x16/0xd0 [ 58.498193][ C0] ? lock_acquire+0x13c/0x160 [ 58.498195][ C0] console_flush_one_record+0x46f/0x710 [ 58.498197][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 58.498199][ C0] ? __lock_acquire+0x518/0xc20 [ 58.498201][ C0] console_unlock+0xee/0x1f0 [ 58.498203][ C0] ? console_flush_one_record+0x710/0x710 [ 58.498204][ C0] ? rcu_is_watching+0x16/0xd0 [ 58.498205][ C0] ? lock_acquire+0xe0/0x160 [ 58.498207][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 58.498209][ C0] ? vprintk_emit+0x320/0x3e0 [ 58.498210][ C0] vprintk_emit+0x37c/0x3e0 [ 58.498211][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 58.498213][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 58.498215][ C0] _printk+0xc7/0x100 [ 58.498216][ C0] ? snapshot_read.cold+0x21/0x21 [ 58.498218][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 58.498220][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 58.498222][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 58.498223][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 58.498225][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 58.498227][ C0] call_timer_fn+0x160/0x4d0 [ 58.498229][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 58.498231][ C0] ? debug_object_active_state+0x430/0x430 [ 58.498232][ C0] ? find_held_lock+0x2b/0x80 [ 58.498233][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 58.498235][ C0] ? rcu_is_watching+0x16/0xd0 [ 58.498237][ C0] __run_timers+0x68f/0xaa0 [ 58.498238][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 58.498241][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 58.498242][ C0] ? __lock_acquire+0x518/0xc20 [ 58.498245][ C0] ? __rwlock_init+0x150/0x150 [ 58.498247][ C0] run_timer_softirq+0xf0/0x160 [ 58.498249][ C0] ? __run_timers+0xaa0/0xaa0 [ 58.498251][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 58.498252][ C0] ? rcu_is_watching+0x16/0xd0 [ 58.498254][ C0] handle_softirqs+0x1d3/0x900 [ 58.498255][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 58.498257][ C0] ? _local_bh_enable+0xc0/0xc0 [ 58.498259][ C0] __irq_exit_rcu+0x145/0x1c0 [ 58.498261][ C0] irq_exit_rcu+0xe/0x30 [ 58.498262][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 58.498264][ C0] [ 58.498264][ C0] [ 58.498264][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 58.498266][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 58.498267][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 58.498269][ 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 [ 58.498270][ C0] RSP: 0018:ffffffffb9e07cf8 EFLAGS: 00000296 [ 58.498271][ C0] RAX: 00000000003c4aa1 RBX: ffffffffb9e30600 RCX: ffffffffb6506247 [ 58.498272][ C0] RDX: ffffffffb9e30600 RSI: ffffffffb9711027 RDI: ffffffffb90949e0 [ 58.498273][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 58.498273][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff73c0fa2 [ 58.498274][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 58.498275][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 58.498278][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 58.498279][ C0] default_idle+0x9/0x10 [ 58.498280][ C0] default_idle_call+0x6e/0xb0 [ 58.498282][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 58.498283][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 58.498285][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 58.498286][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 58.498288][ C0] ? rcu_is_watching+0x16/0xd0 [ 58.498289][ C0] do_idle+0xd8/0x190 [ 58.498290][ C0] cpu_startup_entry+0x53/0x70 [ 58.498292][ C0] rest_init+0x279/0x280 [ 58.498293][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 58.498296][ C0] ? rest_init+0x280/0x280 [ 58.498297][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 58.498299][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 58.498300][ C0] ? acpi_enable+0x1e4/0x330 [ 58.498302][ C0] start_kernel+0x3b9/0x3c0 [ 58.498304][ C0] x86_64_start_reservations+0x24/0x30 [ 58.498305][ C0] x86_64_start_kernel+0x12b/0x130 [ 58.498307][ C0] common_startup_64+0x13e/0x148 [ 58.498310][ C0]