[ 9.745235][ T196] ip (196) used greatest stack depth: 23808 bytes left [ 9.745252][ T196] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.745254][ T196] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 196, name: ip [ 9.745256][ T196] preempt_count: 2, expected: 0 [ 9.745257][ T196] RCU nest depth: 0, expected: 0 [ 9.745258][ T196] locks held by ip/196: 5, last CPU#2: [ 9.745260][ T196] #0: ffffffff8da027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 9.745272][ T196] #1: ffffffff8db69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.745278][ T196] #2: ffffffff8db69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.745282][ T196] #3: ffffffff8da89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.745286][ T196] #4: ffffffff8da89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.745290][ T196] irq event stamp: 31784 [ 9.745291][ T196] hardirqs last enabled at (31783): [] __down_trylock_console_sem+0x86/0xa0 [ 9.745293][ T196] hardirqs last disabled at (31784): [] console_emit_next_record+0x3d4/0x4c0 [ 9.745295][ T196] softirqs last enabled at (31242): [] handle_softirqs+0x67c/0x900 [ 9.745297][ T196] softirqs last disabled at (31235): [] __irq_exit_rcu+0x145/0x1c0 [ 9.745300][ T196] Preemption disabled at: [ 9.745300][ T196] [<0000000000000000>] 0x0 [ 9.745309][ T196] CPU: 2 UID: 0 PID: 196 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.745313][ T196] Tainted: [W]=WARN [ 9.745313][ T196] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.745315][ T196] Call Trace: [ 9.745317][ T196] [ 9.745318][ T196] dump_stack_lvl+0x6f/0xa0 [ 9.745324][ T196] __might_resched.cold+0x1fe/0x2c1 [ 9.745329][ T196] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.745333][ T196] ? __kmalloc_noprof+0xdb/0x760 [ 9.745338][ T196] __kmalloc_noprof+0x443/0x760 [ 9.745341][ T196] ? alloc_buf.isra.0+0x4b/0x260 [ 9.745347][ T196] ? do_raw_spin_unlock+0x59/0x250 [ 9.745349][ T196] alloc_buf.isra.0+0x4b/0x260 [ 9.745352][ T196] put_chars+0x1e1/0x2f0 [ 9.745355][ T196] ? __send_to_port+0x420/0x420 [ 9.745357][ T196] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.745362][ T196] ? validate_chain+0x38b/0xc20 [ 9.745367][ T196] hvc_console_print+0x292/0x780 [ 9.745373][ T196] ? hvc_write+0x3a0/0x3a0 [ 9.745375][ T196] ? rcu_is_watching+0x16/0xd0 [ 9.745377][ T196] ? lock_acquire+0x13c/0x160 [ 9.745381][ T196] console_emit_next_record+0x22f/0x4c0 [ 9.745385][ T196] ? devkmsg_read+0x4b0/0x4b0 [ 9.745387][ T196] ? console_flush_one_record+0x106/0x710 [ 9.745390][ T196] ? rcu_is_watching+0x16/0xd0 [ 9.745392][ T196] ? lock_acquire+0x13c/0x160 [ 9.745396][ T196] console_flush_one_record+0x46f/0x710 [ 9.745400][ T196] ? console_emit_next_record+0x4c0/0x4c0 [ 9.745402][ T196] ? __lock_acquire+0x518/0xc20 [ 9.745407][ T196] console_unlock+0xee/0x1f0 [ 9.745410][ T196] ? console_flush_one_record+0x710/0x710 [ 9.745412][ T196] ? rcu_is_watching+0x16/0xd0 [ 9.745414][ T196] ? lock_acquire+0x60/0x160 [ 9.745418][ T196] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.745420][ T196] ? vprintk_emit+0x320/0x3e0 [ 9.745423][ T196] vprintk_emit+0x37c/0x3e0 [ 9.745427][ T196] ? wake_up_klogd_work_func+0x90/0x90 [ 9.745430][ T196] ? __lock_acquire+0x518/0xc20 [ 9.745434][ T196] _printk+0xc7/0x100 [ 9.745438][ T196] ? snapshot_read.cold+0x21/0x21 [ 9.745440][ T196] ? do_raw_spin_lock+0x131/0x280 [ 9.745443][ T196] ? __rwlock_init+0x150/0x150 [ 9.745447][ T196] ? do_raw_spin_lock+0x131/0x280 [ 9.745449][ T196] do_exit.cold+0x82/0x9c [ 9.745453][ T196] ? exit_notify+0x890/0x890 [ 9.745455][ T196] ? __lock_release.isra.0+0x69/0x1a0 [ 9.745458][ T196] ? rcu_is_watching+0x16/0xd0 [ 9.745462][ T196] do_group_exit+0xb8/0x370 [ 9.745465][ T196] __x64_sys_exit_group+0x3c/0x50 [ 9.745467][ T196] x64_sys_call+0x1567/0x1570 [ 9.745469][ T196] do_syscall_64+0xff/0x530 [ 9.745472][ T196] ? exc_page_fault+0xee/0x100 [ 9.745475][ T196] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.745477][ T196] RIP: 0033:0x7fe4403861b8 [ 9.745479][ T196] Code: Unable to access opcode bytes at 0x7fe44038618e. [ 9.745481][ T196] RSP: 002b:00007ffd0051f148 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 9.745483][ T196] RAX: ffffffffffffffda RBX: 00007fe4404b6f88 RCX: 00007fe4403861b8 [ 9.745484][ T196] RDX: 00007fe4400d0fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 9.745485][ T196] RBP: 00007ffd0051f1a0 R08: 0000000000000000 R09: 0000000000008010 [ 9.745486][ T196] R10: 00007ffd0051ef60 R11: 0000000000000246 R12: 0000000000000001 [ 9.745487][ T196] R13: 0000000000000000 R14: 00007fe4404b5680 R15: 00007fe4404b6fa0 [ 9.745494][ T196] [ 10.458219][ T227] 8021q: 802.1Q VLAN Support v1.8 [ 11.188046][ T251] br0: port 1(veth1) entered blocking state [ 11.188196][ T251] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.188198][ T251] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 251, name: ip [ 11.188200][ T251] preempt_count: 1, expected: 0 [ 11.188201][ T251] RCU nest depth: 0, expected: 0 [ 11.188202][ T251] locks held by ip/251: 5, last CPU#1: [ 11.188204][ T251] #0: ffffffff8e2d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 11.188216][ T251] #1: ffffffff8db69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.188223][ T251] #2: ffffffff8db69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.188227][ T251] #3: ffffffff8da89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.188231][ T251] #4: ffffffff8da89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.188235][ T251] irq event stamp: 13200 [ 11.188235][ T251] hardirqs last enabled at (13199): [] __down_trylock_console_sem+0x86/0xa0 [ 11.188238][ T251] hardirqs last disabled at (13200): [] console_emit_next_record+0x3d4/0x4c0 [ 11.188240][ T251] softirqs last enabled at (13110): [] __alloc_skb+0x4c2/0x5f0 [ 11.188243][ T251] softirqs last disabled at (13108): [] __alloc_skb+0x4c2/0x5f0 [ 11.188246][ T251] Preemption disabled at: [ 11.188246][ T251] [] vprintk_emit+0x31b/0x3e0 [ 11.188252][ T251] CPU: 1 UID: 0 PID: 251 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.188255][ T251] Tainted: [W]=WARN [ 11.188256][ T251] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.188258][ T251] Call Trace: [ 11.188260][ T251] [ 11.188261][ T251] dump_stack_lvl+0x6f/0xa0 [ 11.188267][ T251] ? vprintk_emit+0x31b/0x3e0 [ 11.188269][ T251] __might_resched.cold+0x1fe/0x2c1 [ 11.188274][ T251] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.188278][ T251] ? __kmalloc_noprof+0xdb/0x760 [ 11.188283][ T251] __kmalloc_noprof+0x443/0x760 [ 11.188285][ T251] ? alloc_buf.isra.0+0x4b/0x260 [ 11.188291][ T251] ? do_raw_spin_unlock+0x59/0x250 [ 11.188294][ T251] alloc_buf.isra.0+0x4b/0x260 [ 11.188297][ T251] put_chars+0x1e1/0x2f0 [ 11.188300][ T251] ? __send_to_port+0x420/0x420 [ 11.188308][ T251] ? validate_chain+0x34a/0xc20 [ 11.188312][ T251] hvc_console_print+0x292/0x780 [ 11.188315][ T251] ? mark_usage+0x61/0x170 [ 11.188317][ T251] ? __lock_acquire+0x518/0xc20 [ 11.188318][ T251] ? __lock_acquire+0x518/0xc20 [ 11.188322][ T251] ? hvc_write+0x3a0/0x3a0 [ 11.188324][ T251] ? console_emit_next_record+0x1df/0x4c0 [ 11.188327][ T251] ? rcu_is_watching+0x16/0xd0 [ 11.188331][ T251] ? lock_acquire+0x13c/0x160 [ 11.188335][ T251] console_emit_next_record+0x22f/0x4c0 [ 11.188338][ T251] ? devkmsg_read+0x4b0/0x4b0 [ 11.188340][ T251] ? console_flush_one_record+0x106/0x710 [ 11.188343][ T251] ? rcu_is_watching+0x16/0xd0 [ 11.188345][ T251] ? lock_acquire+0x13c/0x160 [ 11.188349][ T251] console_flush_one_record+0x46f/0x710 [ 11.188353][ T251] ? console_emit_next_record+0x4c0/0x4c0 [ 11.188355][ T251] ? __lock_acquire+0x518/0xc20 [ 11.188360][ T251] console_unlock+0xee/0x1f0 [ 11.188363][ T251] ? console_flush_one_record+0x710/0x710 [ 11.188365][ T251] ? rcu_is_watching+0x16/0xd0 [ 11.188367][ T251] ? lock_acquire+0x60/0x160 [ 11.188370][ T251] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.188372][ T251] ? vprintk_emit+0x320/0x3e0 [ 11.188375][ T251] vprintk_emit+0x37c/0x3e0 [ 11.188379][ T251] ? wake_up_klogd_work_func+0x90/0x90 [ 11.188382][ T251] ? __lock_release.isra.0+0x69/0x1a0 [ 11.188383][ T251] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 11.188388][ T251] ? mark_held_locks+0x40/0x70 [ 11.188391][ T251] _printk+0xc7/0x100 [ 11.188394][ T251] ? snapshot_read.cold+0x21/0x21 [ 11.188398][ T251] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 11.188411][ T251] ? do_setlink.isra.0+0xa31/0x2750 [ 11.188412][ T251] ? rtnl_newlink+0x9f1/0x11c0 [ 11.188413][ T251] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.188418][ T251] br_set_state+0x22f/0x430 [bridge] [ 11.188428][ T251] br_init_port+0xc4/0x200 [bridge] [ 11.188436][ T251] new_nbp+0x39c/0x580 [bridge] [ 11.188445][ T251] br_add_if+0x212/0x1320 [bridge] [ 11.188452][ T251] ? is_bpf_text_address+0x72/0x110 [ 11.188456][ T251] ? kernel_text_address+0x149/0x170 [ 11.188459][ T251] ? __kernel_text_address+0x12/0x30 [ 11.188463][ T251] do_set_master+0x357/0x580 [ 11.188468][ T251] do_setlink.isra.0+0xa31/0x2750 [ 11.188471][ T251] ? stack_trace_save+0x93/0xc0 [ 11.188474][ T251] ? rtnl_link_get_size+0x350/0x350 [ 11.188475][ T251] ? rcu_read_lock_any_held+0x66/0x90 [ 11.188478][ T251] ? stack_depot_save_flags+0x38e/0x790 [ 11.188481][ T251] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.188484][ T251] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.188485][ T251] ? validate_chain+0x38b/0xc20 [ 11.188488][ T251] ? kasan_save_stack+0x3d/0x50 [ 11.188492][ T251] ? kasan_save_stack+0x2f/0x50 [ 11.188493][ T251] ? kasan_save_track+0x14/0x30 [ 11.188496][ T251] ? __lock_acquire+0x518/0xc20 [ 11.188498][ T251] ? netlink_seq_next+0x4e/0x60 [ 11.188501][ T251] ? ___sys_sendmsg+0xb0/0x1d0 [ 11.188505][ T251] ? lock_acquire.part.0+0xd4/0x280 [ 11.188507][ T251] ? rtnl_newlink+0x9a8/0x11c0 [ 11.188510][ T251] ? rcu_is_watching+0x16/0xd0 [ 11.188512][ T251] ? lock_acquire+0x13c/0x160 [ 11.188514][ T251] ? rcu_is_watching+0x16/0xd0 [ 11.188516][ T251] ? rcu_is_watching+0x16/0xd0 [ 11.188518][ T251] ? trace_contention_end+0xb3/0x180 [ 11.188522][ T251] ? __mutex_lock+0x1db/0x1ea0 [ 11.188523][ T251] ? __mutex_lock+0x9a3/0x1ea0 [ 11.188525][ T251] ? rtnl_newlink+0x9a8/0x11c0 [ 11.188528][ T251] ? ww_mutex_lock+0x160/0x160 [ 11.188530][ T251] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.188535][ T251] ? __rtnl_newlink+0x3fa/0xa50 [ 11.188540][ T251] rtnl_newlink+0x9f1/0x11c0 [ 11.188545][ T251] ? rtnl_bridge_getlink+0x850/0x850 [ 11.188547][ T251] ? __lock_acquire+0x518/0xc20 [ 11.188551][ T251] ? lock_acquire.part.0+0xd4/0x280 [ 11.188553][ T251] ? find_held_lock+0x2b/0x80 [ 11.188555][ T251] ? rtnl_bridge_getlink+0x850/0x850 [ 11.188557][ T251] ? __lock_release.isra.0+0x69/0x1a0 [ 11.188561][ T251] ? rtnl_bridge_getlink+0x850/0x850 [ 11.188563][ T251] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.188566][ T251] ? rtnl_link_fill+0x920/0x920 [ 11.188567][ T251] ? __lock_acquire+0x518/0xc20 [ 11.188571][ T251] ? lock_acquire.part.0+0xd4/0x280 [ 11.188573][ T251] ? find_held_lock+0x2b/0x80 [ 11.188576][ T251] netlink_rcv_skb+0x14e/0x3a0 [ 11.188578][ T251] ? rtnl_link_fill+0x920/0x920 [ 11.188581][ T251] ? netlink_ack+0xcf0/0xcf0 [ 11.188587][ T251] ? netlink_deliver_tap+0xc5/0x330 [ 11.188588][ T251] ? netlink_deliver_tap+0x13c/0x330 [ 11.188593][ T251] netlink_unicast+0x486/0x750 [ 11.188596][ T251] ? netlink_attachskb+0x810/0x810 [ 11.188599][ T251] ? __lock_acquire+0x518/0xc20 [ 11.188603][ T251] netlink_sendmsg+0x735/0xc60 [ 11.188607][ T251] ? netlink_unicast+0x750/0x750 [ 11.188610][ T251] ? __might_fault+0x97/0x140 [ 11.188616][ T251] ____sys_sendmsg+0x415/0x880 [ 11.188618][ T251] ? copy_msghdr_from_user+0x279/0x420 [ 11.188620][ T251] ? get_timestamp.constprop.0+0x390/0x390 [ 11.188622][ T251] ? move_addr_to_kernel+0x40/0x40 [ 11.188629][ T251] ___sys_sendmsg+0x14e/0x1d0 [ 11.188632][ T251] ? copy_msghdr_from_user+0x420/0x420 [ 11.188647][ T251] __sys_sendmsg+0x12c/0x1d0 [ 11.188649][ T251] ? __sys_sendmsg_sock+0x20/0x20 [ 11.188656][ T251] ? rcu_is_watching+0x16/0xd0 [ 11.188659][ T251] do_syscall_64+0xff/0x530 [ 11.188661][ T251] ? exc_page_fault+0xee/0x100 [ 11.188664][ T251] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.188667][ T251] RIP: 0033:0x7f1e91c2254e [ 11.188670][ T251] 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 [ 11.188672][ T251] RSP: 002b:00007ffd67a530c0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.188675][ T251] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f1e91c2254e [ 11.188676][ T251] RDX: 0000000000000000 RSI: 00007ffd67a53170 RDI: 0000000000000005 [ 11.188677][ T251] RBP: 00007ffd67a530d0 R08: 0000000000000000 R09: 0000000000000000 [ 11.188678][ T251] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a9160e2 [ 11.188679][ T251] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffd67a53830 [ 11.188685][ T251] [ 11.230650][ T251] br0: port 1(veth1) entered disabled state [ 11.231347][ T251] veth1: entered allmulticast mode [ 11.248285][ T251] veth1: entered promiscuous mode [ 11.259525][ T251] ip (251) used greatest stack depth: 23336 bytes left [ 11.315189][ T253] br0: port 2(veth2) entered blocking state [ 11.316345][ T253] br0: port 2(veth2) entered disabled state [ 11.316865][ T253] veth2: entered allmulticast mode [ 11.319441][ T253] veth2: entered promiscuous mode [ 11.389258][ T103] br0: port 1(veth1) entered blocking state [ 11.389706][ T103] br0: port 1(veth1) entered forwarding state [ 11.433318][ T71] br0: port 2(veth2) entered blocking state [ 11.433649][ T71] br0: port 2(veth2) entered forwarding state [ 26.502366][ C3] [ 26.502384][ C3] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 26.502387][ C3] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/3 [ 26.502388][ C3] preempt_count: 104, expected: 0 [ 26.502389][ C3] RCU nest depth: 0, expected: 0 [ 26.502390][ C3] INFO: lockdep is turned off. [ 26.502391][ C3] irq event stamp: 643420 [ 26.502392][ C3] hardirqs last enabled at (643420): [] _raw_spin_unlock_irq+0x28/0x50 [ 26.502400][ C3] hardirqs last disabled at (643419): [] _raw_spin_lock_irq+0x4a/0x50 [ 26.502402][ C3] softirqs last enabled at (643386): [] handle_softirqs+0x67c/0x900 [ 26.502406][ C3] softirqs last disabled at (643417): [] __irq_exit_rcu+0x145/0x1c0 [ 26.502408][ C3] Preemption disabled at: [ 26.502408][ C3] [] preempt_schedule_thunk+0x16/0x30 [ 26.502417][ C3] CPU: 3 UID: 0 PID: 0 Comm: swapper/3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.502421][ C3] Tainted: [W]=WARN [ 26.502422][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.502424][ C3] Call Trace: [ 26.502425][ C3] [ 26.502427][ C3] dump_stack_lvl+0x6f/0xa0 [ 26.502432][ C3] ? preempt_schedule_thunk+0x16/0x30 [ 26.502433][ C3] __might_resched.cold+0x1fe/0x2c1 [ 26.502437][ C3] ? unwind_next_frame+0x691/0x1ea0 [ 26.502439][ C3] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 26.502443][ C3] ? __asan_memcpy+0x3c/0x60 [ 26.502445][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.502449][ C3] __kmalloc_noprof+0x443/0x760 [ 26.502452][ C3] ? __rwlock_init+0x150/0x150 [ 26.502455][ C3] ? alloc_buf.isra.0+0x4b/0x260 [ 26.502458][ C3] ? do_raw_spin_unlock+0x59/0x250 [ 26.502460][ C3] alloc_buf.isra.0+0x4b/0x260 [ 26.502462][ C3] put_chars+0x1e1/0x2f0 [ 26.502464][ C3] ? __send_to_port+0x420/0x420 [ 26.502465][ C3] ? console_prepend_replay+0x20/0x20 [ 26.502469][ C3] hvc_console_print+0x292/0x780 [ 26.502472][ C3] ? hvc_write+0x3a0/0x3a0 [ 26.502473][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.502475][ C3] ? lock_acquire+0x13c/0x160 [ 26.502477][ C3] console_emit_next_record+0x22f/0x4c0 [ 26.502479][ C3] ? devkmsg_read+0x4b0/0x4b0 [ 26.502481][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.502483][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.502484][ C3] ? lock_acquire+0x13c/0x160 [ 26.502486][ C3] ? console_flush_one_record+0x111/0x710 [ 26.502488][ C3] console_flush_one_record+0x46f/0x710 [ 26.502490][ C3] ? console_emit_next_record+0x4c0/0x4c0 [ 26.502493][ C3] console_unlock+0xee/0x1f0 [ 26.502494][ C3] ? lock_acquire+0x13c/0x160 [ 26.502496][ C3] ? console_flush_one_record+0x710/0x710 [ 26.502497][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.502499][ C3] ? lock_release+0x184/0x1f0 [ 26.502500][ C3] ? lock_acquire+0x60/0x160 [ 26.502502][ C3] ? __down_trylock_console_sem+0x5e/0xa0 [ 26.502504][ C3] ? vprintk_emit+0x320/0x3e0 [ 26.502506][ C3] vprintk_emit+0x37c/0x3e0 [ 26.502508][ C3] ? wake_up_klogd_work_func+0x90/0x90 [ 26.502510][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.502522][ C3] ? lock_release+0x184/0x1f0 [ 26.502523][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.502529][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.502535][ C3] ? is_module_text_address+0x154/0x250 [ 26.502539][ C3] _printk+0xc7/0x100 [ 26.502542][ C3] ? snapshot_read.cold+0x21/0x21 [ 26.502543][ C3] ? arch_stack_walk+0xd7/0x130 [ 26.502547][ C3] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 26.502549][ C3] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 26.502551][ C3] print_irq_inversion_bug.part.0+0x32/0xc0 [ 26.502553][ C3] mark_lock_irq+0x989/0x9c0 [ 26.502556][ C3] mark_lock+0x1d7/0xa00 [ 26.502558][ C3] mark_usage+0x42/0x170 [ 26.502559][ C3] __lock_acquire+0x388/0xc20 [ 26.502561][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.502563][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.502569][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.502571][ C3] ? lock_acquire+0x13c/0x160 [ 26.502573][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.502579][ C3] _raw_spin_lock+0x33/0x40 [ 26.502580][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.502586][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.502592][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.502598][ C3] call_timer_fn+0x160/0x4d0 [ 26.502601][ C3] ? detach_if_pending+0x1d0/0x1d0 [ 26.502603][ C3] ? debug_object_active_state+0x430/0x430 [ 26.502607][ C3] ? find_held_lock+0x2b/0x80 [ 26.502609][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 26.502610][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.502612][ C3] __run_timers+0x68f/0xaa0 [ 26.502614][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.502620][ C3] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.502622][ C3] ? __lock_acquire+0x518/0xc20 [ 26.502625][ C3] ? __rwlock_init+0x150/0x150 [ 26.502627][ C3] run_timer_softirq+0xf0/0x160 [ 26.502629][ C3] ? __run_timers+0xaa0/0xaa0 [ 26.502630][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.502632][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.502634][ C3] handle_softirqs+0x1d3/0x900 [ 26.502636][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 26.502637][ C3] ? _local_bh_enable+0xc0/0xc0 [ 26.502639][ C3] __irq_exit_rcu+0x145/0x1c0 [ 26.502641][ C3] irq_exit_rcu+0xe/0x30 [ 26.502642][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.502645][ C3] [ 26.502645][ C3] [ 26.502646][ C3] ? lockdep_hardirqs_on+0x91/0x130 [ 26.502648][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.502650][ C3] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.502653][ C3] Code: 48 8b 3d 94 e2 f5 01 e8 1f 00 00 00 48 2b 05 58 a3 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 96 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 [ 26.502654][ C3] RSP: 0018:ffa0000000167e00 EFLAGS: 00000282 [ 26.502657][ C3] RAX: 000000000009d157 RBX: ff11000001c88040 RCX: ffffffff8a8f0307 [ 26.502659][ C3] RDX: ff11000001c88040 RSI: ffffffff8d638b01 RDI: ffffffff8d08d8e0 [ 26.502659][ C3] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.502660][ C3] R10: 0000000000000003 R11: 0000000000000001 R12: 1ff400000002cfc3 [ 26.502661][ C3] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 26.502663][ C3] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.502666][ C3] default_idle+0x9/0x10 [ 26.502668][ C3] default_idle_call+0x6e/0xb0 [ 26.502669][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.502671][ C3] ? arch_cpu_idle_exit+0x40/0x40 [ 26.502672][ C3] ? mark_tsc_async_resets+0x30/0x30 [ 26.502674][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.502675][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.502677][ C3] do_idle+0xd8/0x190 [ 26.502679][ C3] cpu_startup_entry+0x53/0x70 [ 26.502680][ C3] start_secondary+0x204/0x2b0 [ 26.502682][ C3] ? set_cpu_sibling_map+0x2130/0x2130 [ 26.502684][ C3] common_startup_64+0x13e/0x148 [ 26.502688][ C3] [ 26.521868][ C3] ======================================================== [ 26.522110][ C3] WARNING: possible irq lock inversion dependency detected [ 26.522347][ C3] 7.2.0-virtme #1 Tainted: G W [ 26.522555][ C3] -------------------------------------------------------- [ 26.522751][ C3] swapper/3/0 just changed the state of lock: [ 26.522973][ C3] ff1100000c8dae58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.523299][ C3] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 26.523544][ C3] (fs_reclaim){+.+.}-{0:0} [ 26.523546][ C3] [ 26.523546][ C3] [ 26.523546][ C3] and interrupts could create inverse lock ordering between them. [ 26.523546][ C3] [ 26.524125][ C3] [ 26.524125][ C3] other info that might help us debug this: [ 26.524324][ C3] Chain exists of: [ 26.524324][ C3] &br->lock --> console_owner --> fs_reclaim [ 26.524324][ C3] [ 26.524671][ C3] Possible interrupt unsafe locking scenario: [ 26.524671][ C3] [ 26.524921][ C3] CPU0 CPU1 [ 26.525107][ C3] ---- ---- [ 26.525239][ C3] lock(fs_reclaim); [ 26.525388][ C3] local_irq_disable(); [ 26.525557][ C3] lock(&br->lock); [ 26.525774][ C3] lock(console_owner); [ 26.525943][ C3] [ 26.526091][ C3] lock(&br->lock); [ 26.526195][ C3] [ 26.526195][ C3] *** DEADLOCK *** [ 26.526195][ C3] [ 26.526437][ C3] locks held by swapper/3/0: 1, last CPU#3: [ 26.526648][ C3] #0: ffa0000000280c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 26.526970][ C3] [ 26.526970][ C3] the shortest dependencies between 2nd lock and 1st lock: [ 26.527204][ C3] -> (fs_reclaim){+.+.}-{0:0} { [ 26.527386][ C3] HARDIRQ-ON-W at: [ 26.527492][ C3] __lock_acquire+0x388/0xc20 [ 26.527710][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.527926][ C3] fs_reclaim_acquire+0xd5/0x120 [ 26.528095][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 26.528346][ C3] kthread_create_worker_on_node+0xea/0x210 [ 26.528593][ C3] workqueue_init+0x2a/0x680 [ 26.528760][ C3] kernel_init_freeable+0x2fe/0x630 [ 26.529010][ C3] kernel_init+0x21/0x150 [ 26.529228][ C3] ret_from_fork+0x474/0x6b0 [ 26.529398][ C3] ret_from_fork_asm+0x11/0x20 [ 26.529612][ C3] SOFTIRQ-ON-W at: [ 26.529713][ C3] __lock_acquire+0x388/0xc20 [ 26.529935][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.530149][ C3] fs_reclaim_acquire+0xd5/0x120 [ 26.530318][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 26.530569][ C3] kthread_create_worker_on_node+0xea/0x210 [ 26.530825][ C3] workqueue_init+0x2a/0x680 [ 26.530995][ C3] kernel_init_freeable+0x2fe/0x630 [ 26.531247][ C3] kernel_init+0x21/0x150 [ 26.531466][ C3] ret_from_fork+0x474/0x6b0 [ 26.531633][ C3] ret_from_fork_asm+0x11/0x20 [ 26.531853][ C3] INITIAL USE at: [ 26.531956][ C3] __lock_acquire+0x388/0xc20 [ 26.532168][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.532382][ C3] fs_reclaim_acquire+0xd5/0x120 [ 26.532549][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 26.532804][ C3] kthread_create_worker_on_node+0xea/0x210 [ 26.533053][ C3] workqueue_init+0x2a/0x680 [ 26.533222][ C3] kernel_init_freeable+0x2fe/0x630 [ 26.533435][ C3] kernel_init+0x21/0x150 [ 26.533652][ C3] ret_from_fork+0x474/0x6b0 [ 26.533828][ C3] ret_from_fork_asm+0x11/0x20 [ 26.534039][ C3] } [ 26.534111][ C3] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 26.534363][ C3] ... acquired at: [ 26.534464][ C3] __lock_acquire+0x518/0xc20 [ 26.534662][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.534803][ C3] fs_reclaim_acquire+0xd5/0x120 [ 26.534985][ C3] __kmalloc_noprof+0xd3/0x760 [ 26.535122][ C3] alloc_buf.isra.0+0x4b/0x260 [ 26.535305][ C3] put_chars+0x1e1/0x2f0 [ 26.535442][ C3] hvc_console_print+0x292/0x780 [ 26.535626][ C3] console_emit_next_record+0x22f/0x4c0 [ 26.535764][ C3] console_flush_one_record+0x46f/0x710 [ 26.535948][ C3] console_unlock+0xee/0x1f0 [ 26.536085][ C3] vprintk_emit+0x37c/0x3e0 [ 26.536269][ C3] _printk+0xc7/0x100 [ 26.536372][ C3] print_ipi_mode+0x2e/0x40 [ 26.536553][ C3] do_one_initcall+0x124/0x4f0 [ 26.536690][ C3] kernel_init_freeable+0x596/0x630 [ 26.536871][ C3] kernel_init+0x21/0x150 [ 26.537002][ C3] ret_from_fork+0x474/0x6b0 [ 26.537183][ C3] ret_from_fork_asm+0x11/0x20 [ 26.537318][ C3] [ 26.537390][ C3] -> (console_owner){....}-{0:0} { [ 26.537574][ C3] INITIAL USE at: [ 26.537676][ C3] } [ 26.537745][ C3] ... key at: [] console_owner_dep_map+0x0/0x60 [ 26.537993][ C3] ... acquired at: [ 26.538139][ C3] __lock_acquire+0x518/0xc20 [ 26.538274][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.538454][ C3] console_lock_spinning_enable+0x5c/0x60 [ 26.538624][ C3] console_emit_next_record+0x1d1/0x4c0 [ 26.538811][ C3] console_flush_one_record+0x46f/0x710 [ 26.542851][ C3] console_unlock+0xee/0x1f0 [ 26.543054][ C3] vprintk_emit+0x37c/0x3e0 [ 26.543194][ C3] _printk+0xc7/0x100 [ 26.543346][ C3] br_set_state+0x22f/0x430 [bridge] [ 26.543501][ C3] br_init_port+0xc4/0x200 [bridge] [ 26.543705][ C3] br_stp_enable_port+0x12/0x50 [bridge] [ 26.543893][ C3] br_port_carrier_check+0x220/0x430 [bridge] [ 26.544123][ C3] br_device_event+0x52d/0x8f0 [bridge] [ 26.544265][ C3] notifier_call_chain+0xae/0x300 [ 26.544446][ C3] netif_state_change+0x139/0x340 [ 26.544582][ C3] __linkwatch_run_queue+0x34c/0x750 [ 26.544768][ C3] linkwatch_event+0x7f/0xb0 [ 26.544901][ C3] process_one_work+0xe3e/0x1560 [ 26.545081][ C3] worker_thread+0x4f1/0xd60 [ 26.545214][ C3] kthread+0x367/0x460 [ 26.545359][ C3] ret_from_fork+0x474/0x6b0 [ 26.545493][ C3] ret_from_fork_asm+0x11/0x20 [ 26.545674][ C3] [ 26.545746][ C3] -> (&br->lock){+.-.}-{3:3} { [ 26.545896][ C3] HARDIRQ-ON-W at: [ 26.546044][ C3] __lock_acquire+0x388/0xc20 [ 26.546216][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.546431][ C3] _raw_spin_lock_bh+0x38/0x50 [ 26.546649][ C3] recalculate_group_addr+0x51/0x120 [bridge] [ 26.546864][ C3] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 26.547119][ C3] br_changelink+0x575/0x16e0 [bridge] [ 26.547342][ C3] br_dev_newlink+0xeb/0x160 [bridge] [ 26.547519][ C3] rtnl_newlink_create+0x2d0/0x750 [ 26.547734][ C3] __rtnl_newlink+0x22b/0xa50 [ 26.547958][ C3] rtnl_newlink+0x9f1/0x11c0 [ 26.548127][ C3] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.548340][ C3] netlink_rcv_skb+0x14e/0x3a0 [ 26.548562][ C3] netlink_unicast+0x486/0x750 [ 26.548729][ C3] netlink_sendmsg+0x735/0xc60 [ 26.548947][ C3] ____sys_sendmsg+0x415/0x880 [ 26.549119][ C3] ___sys_sendmsg+0x14e/0x1d0 [ 26.549332][ C3] __sys_sendmsg+0x12c/0x1d0 [ 26.549546][ C3] do_syscall_64+0xff/0x530 [ 26.549716][ C3] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.549968][ C3] IN-SOFTIRQ-W at: [ 26.550069][ C3] __lock_acquire+0x388/0xc20 [ 26.550284][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.550499][ C3] _raw_spin_lock+0x33/0x40 [ 26.550666][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.550920][ C3] call_timer_fn+0x160/0x4d0 [ 26.551139][ C3] __run_timers+0x68f/0xaa0 [ 26.551308][ C3] run_timer_softirq+0xf0/0x160 [ 26.551524][ C3] handle_softirqs+0x1d3/0x900 [ 26.551742][ C3] __irq_exit_rcu+0x145/0x1c0 [ 26.551915][ C3] irq_exit_rcu+0xe/0x30 [ 26.552127][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.552326][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.552527][ C3] pv_native_safe_halt+0xf/0x10 [ 26.552743][ C3] default_idle+0x9/0x10 [ 26.552979][ C3] default_idle_call+0x6e/0xb0 [ 26.553152][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.553401][ C3] do_idle+0xd8/0x190 [ 26.553537][ C3] cpu_startup_entry+0x53/0x70 [ 26.553752][ C3] start_secondary+0x204/0x2b0 [ 26.553976][ C3] common_startup_64+0x13e/0x148 [ 26.554147][ C3] INITIAL USE at: [ 26.554293][ C3] __lock_acquire+0x388/0xc20 [ 26.554460][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.554670][ C3] _raw_spin_lock_bh+0x38/0x50 [ 26.554889][ C3] recalculate_group_addr+0x51/0x120 [bridge] [ 26.555110][ C3] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 26.555365][ C3] br_changelink+0x575/0x16e0 [bridge] [ 26.555585][ C3] br_dev_newlink+0xeb/0x160 [bridge] [ 26.555757][ C3] rtnl_newlink_create+0x2d0/0x750 [ 26.555981][ C3] __rtnl_newlink+0x22b/0xa50 [ 26.556197][ C3] rtnl_newlink+0x9f1/0x11c0 [ 26.556364][ C3] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.556574][ C3] netlink_rcv_skb+0x14e/0x3a0 [ 26.556742][ C3] netlink_unicast+0x486/0x750 [ 26.556962][ C3] netlink_sendmsg+0x735/0xc60 [ 26.557181][ C3] ____sys_sendmsg+0x415/0x880 [ 26.557350][ C3] ___sys_sendmsg+0x14e/0x1d0 [ 26.557564][ C3] __sys_sendmsg+0x12c/0x1d0 [ 26.557785][ C3] do_syscall_64+0xff/0x530 [ 26.557953][ C3] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.558205][ C3] } [ 26.558275][ C3] ... key at: [] __key.7+0x0/0x40 [bridge] [ 26.558528][ C3] ... acquired at: [ 26.558630][ C3] mark_lock+0x1d7/0xa00 [ 26.558818][ C3] mark_usage+0x42/0x170 [ 26.558953][ C3] __lock_acquire+0x388/0xc20 [ 26.559134][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.559268][ C3] _raw_spin_lock+0x33/0x40 [ 26.559405][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.559579][ C3] call_timer_fn+0x160/0x4d0 [ 26.559757][ C3] __run_timers+0x68f/0xaa0 [ 26.559901][ C3] run_timer_softirq+0xf0/0x160 [ 26.560082][ C3] handle_softirqs+0x1d3/0x900 [ 26.560218][ C3] __irq_exit_rcu+0x145/0x1c0 [ 26.560396][ C3] irq_exit_rcu+0xe/0x30 [ 26.560531][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.560743][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.560961][ C3] pv_native_safe_halt+0xf/0x10 [ 26.561096][ C3] default_idle+0x9/0x10 [ 26.561278][ C3] default_idle_call+0x6e/0xb0 [ 26.561413][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.561640][ C3] do_idle+0xd8/0x190 [ 26.561774][ C3] cpu_startup_entry+0x53/0x70 [ 26.561961][ C3] start_secondary+0x204/0x2b0 [ 26.562103][ C3] common_startup_64+0x13e/0x148 [ 26.562286][ C3] [ 26.562355][ C3] [ 26.562355][ C3] stack backtrace: [ 26.562570][ C3] CPU: 3 UID: 0 PID: 0 Comm: swapper/3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.562574][ C3] Tainted: [W]=WARN [ 26.562575][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.562576][ C3] Call Trace: [ 26.562578][ C3] [ 26.562579][ C3] dump_stack_lvl+0x6f/0xa0 [ 26.562584][ C3] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 26.562587][ C3] mark_lock_irq+0x989/0x9c0 [ 26.562590][ C3] mark_lock+0x1d7/0xa00 [ 26.562592][ C3] mark_usage+0x42/0x170 [ 26.562593][ C3] __lock_acquire+0x388/0xc20 [ 26.562596][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.562597][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.562605][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.562608][ C3] ? lock_acquire+0x13c/0x160 [ 26.562610][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.562616][ C3] _raw_spin_lock+0x33/0x40 [ 26.562617][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.562623][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.562630][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.562636][ C3] call_timer_fn+0x160/0x4d0 [ 26.562638][ C3] ? detach_if_pending+0x1d0/0x1d0 [ 26.562639][ C3] ? debug_object_active_state+0x430/0x430 [ 26.562644][ C3] ? find_held_lock+0x2b/0x80 [ 26.562645][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 26.562647][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.562649][ C3] __run_timers+0x68f/0xaa0 [ 26.562651][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.562657][ C3] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.562659][ C3] ? __lock_acquire+0x518/0xc20 [ 26.562662][ C3] ? __rwlock_init+0x150/0x150 [ 26.562664][ C3] run_timer_softirq+0xf0/0x160 [ 26.562666][ C3] ? __run_timers+0xaa0/0xaa0 [ 26.562668][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.562670][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.562672][ C3] handle_softirqs+0x1d3/0x900 [ 26.562674][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 26.562675][ C3] ? _local_bh_enable+0xc0/0xc0 [ 26.562677][ C3] __irq_exit_rcu+0x145/0x1c0 [ 26.562679][ C3] irq_exit_rcu+0xe/0x30 [ 26.562680][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.562682][ C3] [ 26.562682][ C3] [ 26.562683][ C3] ? lockdep_hardirqs_on+0x91/0x130 [ 26.562684][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.562686][ C3] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.562689][ C3] Code: 48 8b 3d 94 e2 f5 01 e8 1f 00 00 00 48 2b 05 58 a3 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 96 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 [ 26.562690][ C3] RSP: 0018:ffa0000000167e00 EFLAGS: 00000282 [ 26.562693][ C3] RAX: 000000000009d157 RBX: ff11000001c88040 RCX: ffffffff8a8f0307 [ 26.562694][ C3] RDX: ff11000001c88040 RSI: ffffffff8d638b01 RDI: ffffffff8d08d8e0 [ 26.562695][ C3] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.562696][ C3] R10: 0000000000000003 R11: 0000000000000001 R12: 1ff400000002cfc3 [ 26.562697][ C3] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 26.562699][ C3] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.562701][ C3] default_idle+0x9/0x10 [ 26.562703][ C3] default_idle_call+0x6e/0xb0 [ 26.562704][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.562706][ C3] ? arch_cpu_idle_exit+0x40/0x40 [ 26.562707][ C3] ? mark_tsc_async_resets+0x30/0x30 [ 26.562709][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.562710][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.562712][ C3] do_idle+0xd8/0x190 [ 26.562713][ C3] cpu_startup_entry+0x53/0x70 [ 26.562715][ C3] start_secondary+0x204/0x2b0 [ 26.562716][ C3] ? set_cpu_sibling_map+0x2130/0x2130 [ 26.562718][ C3] common_startup_64+0x13e/0x148 [ 26.562721][ C3] [ 38.047531][ T350] br0: port 2(veth2) entered disabled state [ 38.047549][ T350] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 38.047551][ T350] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 350, name: ip [ 38.047553][ T350] preempt_count: 202, expected: 0 [ 38.047554][ T350] RCU nest depth: 0, expected: 0 [ 38.047555][ T350] INFO: lockdep is turned off. [ 38.047556][ T350] irq event stamp: 0 [ 38.047557][ T350] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 38.047562][ T350] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 38.047568][ T350] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 38.047569][ T350] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 38.047571][ T350] Preemption disabled at: [ 38.047572][ T350] [<0000000000000000>] 0x0 [ 38.047577][ T350] CPU: 2 UID: 0 PID: 350 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 38.047580][ T350] Tainted: [W]=WARN [ 38.047581][ T350] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 38.047583][ T350] Call Trace: [ 38.047585][ T350] [ 38.047586][ T350] dump_stack_lvl+0x6f/0xa0 [ 38.047592][ T350] __might_resched.cold+0x1fe/0x2c1 [ 38.047596][ T350] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 38.047599][ T350] ? rcu_is_watching+0x16/0xd0 [ 38.047604][ T350] __kmalloc_noprof+0x443/0x760 [ 38.047607][ T350] ? __rwlock_init+0x150/0x150 [ 38.047610][ T350] ? alloc_buf.isra.0+0x4b/0x260 [ 38.047614][ T350] ? do_raw_spin_unlock+0x59/0x250 [ 38.047616][ T350] alloc_buf.isra.0+0x4b/0x260 [ 38.047618][ T350] put_chars+0x1e1/0x2f0 [ 38.047620][ T350] ? __send_to_port+0x420/0x420 [ 38.047621][ T350] ? printk_get_next_message+0x240/0x650 [ 38.047625][ T350] hvc_console_print+0x292/0x780 [ 38.047628][ T350] ? hvc_write+0x3a0/0x3a0 [ 38.047631][ T350] ? __rwlock_init+0x150/0x150 [ 38.047632][ T350] ? rcu_is_watching+0x16/0xd0 [ 38.047634][ T350] ? lock_acquire+0x13c/0x160 [ 38.047636][ T350] console_emit_next_record+0x22f/0x4c0 [ 38.047638][ T350] ? devkmsg_read+0x4b0/0x4b0 [ 38.047640][ T350] ? rcu_is_watching+0x16/0xd0 [ 38.047642][ T350] ? rcu_is_watching+0x16/0xd0 [ 38.047643][ T350] ? rcu_is_watching+0x16/0xd0 [ 38.047645][ T350] ? lock_acquire+0x13c/0x160 [ 38.047647][ T350] ? console_flush_one_record+0x111/0x710 [ 38.047649][ T350] console_flush_one_record+0x46f/0x710 [ 38.047651][ T350] ? console_emit_next_record+0x4c0/0x4c0 [ 38.047653][ T350] console_unlock+0xee/0x1f0 [ 38.047655][ T350] ? lock_acquire+0x13c/0x160 [ 38.047656][ T350] ? console_flush_one_record+0x710/0x710 [ 38.047658][ T350] ? rcu_is_watching+0x16/0xd0 [ 38.047660][ T350] ? lock_release+0x184/0x1f0 [ 38.047661][ T350] ? lock_acquire+0x60/0x160 [ 38.047662][ T350] ? __rcu_read_lock+0x30/0x70 [ 38.047664][ T350] ? __down_trylock_console_sem+0x5e/0xa0 [ 38.047666][ T350] ? vprintk_emit+0x320/0x3e0 [ 38.047668][ T350] vprintk_emit+0x37c/0x3e0 [ 38.047671][ T350] ? wake_up_klogd_work_func+0x90/0x90 [ 38.047672][ T350] ? rcu_is_watching+0x16/0xd0 [ 38.047674][ T350] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 38.047677][ T350] ? trace_hardirqs_on+0x36/0x40 [ 38.047678][ T350] _printk+0xc7/0x100 [ 38.047681][ T350] ? snapshot_read.cold+0x21/0x21 [ 38.047683][ T350] ? ipv6_mc_down+0x17e/0x2d0 [ 38.047688][ T350] br_set_state+0x22f/0x430 [bridge] [ 38.047702][ T350] br_stp_disable_port+0x11f/0x390 [bridge] [ 38.047710][ T350] br_device_event+0x7b5/0x8f0 [bridge] [ 38.047718][ T350] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 38.047721][ T350] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 38.047724][ T350] ? addrconf_notify+0x2c8/0xf30 [ 38.047727][ T350] notifier_call_chain+0xae/0x300 [ 38.047730][ T350] __dev_notify_flags+0x23c/0x3c0 [ 38.047734][ T350] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 38.047735][ T350] ? netif_change_name+0x8c0/0x8c0 [ 38.047737][ T350] ? netif_set_allmulti+0x250/0x250 [ 38.047739][ T350] ? __kernel_text_address+0x12/0x30 [ 38.047741][ T350] ? write_profile+0xf0/0xf0 [ 38.047744][ T350] ? unwind_get_return_address+0x67/0xd0 [ 38.047746][ T350] netif_change_flags+0xfe/0x190 [ 38.047749][ T350] do_setlink.isra.0+0x1cc3/0x2750 [ 38.047751][ T350] ? stack_trace_save+0x93/0xc0 [ 38.047753][ T350] ? rtnl_link_get_size+0x350/0x350 [ 38.047754][ T350] ? rcu_is_watching+0x16/0xd0 [ 38.047756][ T350] ? stack_depot_save_flags+0x1bc/0x790 [ 38.047759][ T350] ? kasan_save_stack+0x3d/0x50 [ 38.047762][ T350] ? kasan_save_stack+0x2f/0x50 [ 38.047764][ T350] ? kasan_save_track+0x14/0x30 [ 38.047765][ T350] ? __kasan_kmalloc+0x7b/0x90 [ 38.047767][ T350] ? __kmalloc_cache_noprof+0x29a/0x620 [ 38.047768][ T350] ? rtnl_newlink+0x124/0x11c0 [ 38.047769][ T350] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 38.047771][ T350] ? netlink_rcv_skb+0x14e/0x3a0 [ 38.047773][ T350] ? netlink_unicast+0x486/0x750 [ 38.047775][ T350] ? netlink_sendmsg+0x735/0xc60 [ 38.047776][ T350] ? ____sys_sendmsg+0x415/0x880 [ 38.047779][ T350] ? ___sys_sendmsg+0x14e/0x1d0 [ 38.047780][ T350] ? __sys_sendmsg+0x12c/0x1d0 [ 38.047782][ T350] ? do_syscall_64+0xff/0x530 [ 38.047784][ T350] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 38.047786][ T350] ? rcu_is_watching+0x16/0xd0 [ 38.047788][ T350] ? lock_acquire+0x13c/0x160 [ 38.047789][ T350] ? rcu_is_watching+0x16/0xd0 [ 38.047791][ T350] ? rcu_is_watching+0x16/0xd0 [ 38.047793][ T350] ? trace_contention_end+0xb3/0x180 [ 38.047796][ T350] ? __mutex_lock+0x1db/0x1ea0 [ 38.047797][ T350] ? __mutex_lock+0x9a3/0x1ea0 [ 38.047798][ T350] ? rtnl_newlink+0x9a8/0x11c0 [ 38.047800][ T350] ? ww_mutex_lock+0x160/0x160 [ 38.047802][ T350] ? __rtnl_newlink+0x3fa/0xa50 [ 38.047810][ T350] rtnl_newlink+0x9f1/0x11c0 [ 38.047812][ T350] ? rcu_is_watching+0x16/0xd0 [ 38.047814][ T350] ? rtnl_bridge_getlink+0x850/0x850 [ 38.047815][ T350] ? lock_acquire+0x13c/0x160 [ 38.047817][ T350] ? rcu_is_watching+0x16/0xd0 [ 38.047819][ T350] ? is_bpf_text_address+0x3e/0x110 [ 38.047822][ T350] ? is_bpf_text_address+0x72/0x110 [ 38.047824][ T350] ? kernel_text_address+0x149/0x170 [ 38.047825][ T350] ? __kernel_text_address+0x12/0x30 [ 38.047826][ T350] ? write_profile+0xf0/0xf0 [ 38.047827][ T350] ? unwind_get_return_address+0x67/0xd0 [ 38.047828][ T350] ? rcu_is_watching+0x16/0xd0 [ 38.047830][ T350] ? rcu_is_watching+0x16/0xd0 [ 38.047832][ T350] ? cap_capable+0x1d7/0x3d0 [ 38.047835][ T350] ? lock_acquire+0x13c/0x160 [ 38.047837][ T350] ? rcu_is_watching+0x16/0xd0 [ 38.047839][ T350] ? rtnl_bridge_getlink+0x850/0x850 [ 38.047840][ T350] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 38.047842][ T350] ? rtnl_link_fill+0x920/0x920 [ 38.047843][ T350] ? filter_irq_stacks+0xd0/0xd0 [ 38.047844][ T350] ? stack_depot_save_flags+0x1bc/0x790 [ 38.047847][ T350] netlink_rcv_skb+0x14e/0x3a0 [ 38.047848][ T350] ? rtnl_link_fill+0x920/0x920 [ 38.047849][ T350] ? lock_release+0x184/0x1f0 [ 38.047851][ T350] ? netlink_ack+0xcf0/0xcf0 [ 38.047852][ T350] ? __netlink_lookup+0xb9/0x6b0 [ 38.047855][ T350] ? rcu_is_watching+0x16/0xd0 [ 38.047856][ T350] ? lock_release+0x184/0x1f0 [ 38.047858][ T350] netlink_unicast+0x486/0x750 [ 38.047860][ T350] ? netlink_attachskb+0x810/0x810 [ 38.047862][ T350] ? rcu_is_watching+0x16/0xd0 [ 38.047863][ T350] ? rcu_is_watching+0x16/0xd0 [ 38.047865][ T350] ? lock_acquire+0x13c/0x160 [ 38.047867][ T350] netlink_sendmsg+0x735/0xc60 [ 38.047869][ T350] ? netlink_unicast+0x750/0x750 [ 38.047871][ T350] ? rcu_is_watching+0x16/0xd0 [ 38.047872][ T350] ? lock_release+0x184/0x1f0 [ 38.047874][ T350] ____sys_sendmsg+0x415/0x880 [ 38.047876][ T350] ? copy_msghdr_from_user+0x279/0x420 [ 38.047878][ T350] ? get_timestamp.constprop.0+0x390/0x390 [ 38.047879][ T350] ? move_addr_to_kernel+0x40/0x40 [ 38.047880][ T350] ? filter_irq_stacks+0xd0/0xd0 [ 38.047883][ T350] ___sys_sendmsg+0x14e/0x1d0 [ 38.047885][ T350] ? copy_msghdr_from_user+0x420/0x420 [ 38.047889][ T350] __sys_sendmsg+0x12c/0x1d0 [ 38.047891][ T350] ? __sys_sendmsg_sock+0x20/0x20 [ 38.047894][ T350] ? rcu_is_watching+0x16/0xd0 [ 38.047896][ T350] do_syscall_64+0xff/0x530 [ 38.047897][ T350] ? exc_page_fault+0xee/0x100 [ 38.047900][ T350] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 38.047901][ T350] RIP: 0033:0x7f872d42254e [ 38.047903][ T350] 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 [ 38.047905][ T350] RSP: 002b:00007fffe960b610 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 38.047909][ T350] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f872d42254e [ 38.047910][ T350] RDX: 0000000000000000 RSI: 00007fffe960b6c0 RDI: 0000000000000005 [ 38.047911][ T350] RBP: 00007fffe960b620 R08: 0000000000000000 R09: 0000000000000000 [ 38.047912][ T350] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a9160fd [ 38.047912][ T350] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fffe960bd80 [ 38.047915][ T350] [ 38.083821][ T350] ip (350) used greatest stack depth: 23016 bytes left [ 38.104905][ T351] br0: port 1(veth1) entered disabled state [ 38.124890][ T352] veth2: left allmulticast mode [ 38.125051][ T352] veth2: left promiscuous mode [ 38.125257][ T352] br0: port 2(veth2) entered disabled state [ 38.125830][ T352] veth1: left allmulticast mode [ 38.125965][ T352] veth1: left promiscuous mode [ 38.126153][ T352] br0: port 1(veth1) entered disabled state