[ 9.775542][ T188] ip (188) used greatest stack depth: 23968 bytes left [ 9.775557][ T188] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.775559][ T188] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 188, name: ip [ 9.775560][ T188] preempt_count: 2, expected: 0 [ 9.775561][ T188] RCU nest depth: 0, expected: 0 [ 9.775562][ T188] locks held by ip/188: 5, last CPU#2: [ 9.775564][ T188] #0: ffffffff918027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 9.775577][ T188] #1: ffffffff91969cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.775582][ T188] #2: ffffffff91969d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.775586][ T188] #3: ffffffff91889660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.775590][ T188] #4: ffffffff91889560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.775594][ T188] irq event stamp: 35190 [ 9.775594][ T188] hardirqs last enabled at (35189): [] __down_trylock_console_sem+0x86/0xa0 [ 9.775597][ T188] hardirqs last disabled at (35190): [] console_emit_next_record+0x3d4/0x4c0 [ 9.775599][ T188] softirqs last enabled at (34826): [] handle_softirqs+0x67c/0x900 [ 9.775601][ T188] softirqs last disabled at (34815): [] __irq_exit_rcu+0x145/0x1c0 [ 9.775603][ T188] Preemption disabled at: [ 9.775603][ T188] [<0000000000000000>] 0x0 [ 9.775610][ T188] CPU: 2 UID: 0 PID: 188 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.775614][ T188] Tainted: [W]=WARN [ 9.775615][ T188] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.775616][ T188] Call Trace: [ 9.775618][ T188] [ 9.775620][ T188] dump_stack_lvl+0x6f/0xa0 [ 9.775627][ T188] __might_resched.cold+0x1fe/0x2c1 [ 9.775631][ T188] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.775636][ T188] ? __kmalloc_noprof+0xdb/0x760 [ 9.775641][ T188] __kmalloc_noprof+0x443/0x760 [ 9.775643][ T188] ? alloc_buf.isra.0+0x4b/0x260 [ 9.775649][ T188] ? do_raw_spin_unlock+0x59/0x250 [ 9.775652][ T188] alloc_buf.isra.0+0x4b/0x260 [ 9.775655][ T188] put_chars+0x1e1/0x2f0 [ 9.775658][ T188] ? __send_to_port+0x420/0x420 [ 9.775660][ T188] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.775665][ T188] ? validate_chain+0x38b/0xc20 [ 9.775670][ T188] hvc_console_print+0x292/0x780 [ 9.775676][ T188] ? hvc_write+0x3a0/0x3a0 [ 9.775678][ T188] ? rcu_is_watching+0x16/0xd0 [ 9.775681][ T188] ? lock_acquire+0x13c/0x160 [ 9.775684][ T188] console_emit_next_record+0x22f/0x4c0 [ 9.775688][ T188] ? devkmsg_read+0x4b0/0x4b0 [ 9.775690][ T188] ? console_flush_one_record+0x106/0x710 [ 9.775693][ T188] ? rcu_is_watching+0x16/0xd0 [ 9.775695][ T188] ? lock_acquire+0x13c/0x160 [ 9.775699][ T188] console_flush_one_record+0x46f/0x710 [ 9.775703][ T188] ? console_emit_next_record+0x4c0/0x4c0 [ 9.775705][ T188] ? __lock_acquire+0x518/0xc20 [ 9.775710][ T188] console_unlock+0xee/0x1f0 [ 9.775713][ T188] ? console_flush_one_record+0x710/0x710 [ 9.775715][ T188] ? rcu_is_watching+0x16/0xd0 [ 9.775717][ T188] ? lock_acquire+0x60/0x160 [ 9.775721][ T188] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.775722][ T188] ? vprintk_emit+0x320/0x3e0 [ 9.775726][ T188] vprintk_emit+0x37c/0x3e0 [ 9.775729][ T188] ? wake_up_klogd_work_func+0x90/0x90 [ 9.775733][ T188] ? __lock_acquire+0x518/0xc20 [ 9.775736][ T188] _printk+0xc7/0x100 [ 9.775740][ T188] ? snapshot_read.cold+0x21/0x21 [ 9.775743][ T188] ? do_raw_spin_lock+0x131/0x280 [ 9.775746][ T188] ? __rwlock_init+0x150/0x150 [ 9.775749][ T188] ? do_raw_spin_lock+0x131/0x280 [ 9.775752][ T188] do_exit.cold+0x82/0x9c [ 9.775756][ T188] ? exit_notify+0x890/0x890 [ 9.775758][ T188] ? __lock_release.isra.0+0x69/0x1a0 [ 9.775760][ T188] ? rcu_is_watching+0x16/0xd0 [ 9.775764][ T188] do_group_exit+0xb8/0x370 [ 9.775767][ T188] __x64_sys_exit_group+0x3c/0x50 [ 9.775769][ T188] x64_sys_call+0x1567/0x1570 [ 9.775771][ T188] do_syscall_64+0xff/0x530 [ 9.775774][ T188] ? exc_page_fault+0xee/0x100 [ 9.775777][ T188] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.775780][ T188] RIP: 0033:0x7fd8d4a7a1b8 [ 9.775782][ T188] Code: Unable to access opcode bytes at 0x7fd8d4a7a18e. [ 9.775783][ T188] RSP: 002b:00007fff122ec588 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 9.775785][ T188] RAX: ffffffffffffffda RBX: 00007fd8d4baaf88 RCX: 00007fd8d4a7a1b8 [ 9.775787][ T188] RDX: 00007fd8d47c4fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 9.775788][ T188] RBP: 00007fff122ec5e0 R08: 0000000000000000 R09: 0000000000008010 [ 9.775788][ T188] R10: 00007fff122ec3a0 R11: 0000000000000246 R12: 0000000000000001 [ 9.775789][ T188] R13: 0000000000000000 R14: 00007fd8d4ba9680 R15: 00007fd8d4baafa0 [ 9.775796][ T188] [ 11.169774][ T262] br1: port 1(veth1) entered blocking state [ 11.169946][ T262] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.169948][ T262] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 262, name: ip [ 11.169950][ T262] preempt_count: 1, expected: 0 [ 11.169951][ T262] RCU nest depth: 0, expected: 0 [ 11.169952][ T262] locks held by ip/262: 5, last CPU#3: [ 11.169954][ T262] #0: ffffffff920d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 11.169966][ T262] #1: ffffffff91969cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.169972][ T262] #2: ffffffff91969d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.169976][ T262] #3: ffffffff91889660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.169980][ T262] #4: ffffffff91889560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.169984][ T262] irq event stamp: 14880 [ 11.169985][ T262] hardirqs last enabled at (14879): [] __down_trylock_console_sem+0x86/0xa0 [ 11.169987][ T262] hardirqs last disabled at (14880): [] console_emit_next_record+0x3d4/0x4c0 [ 11.169989][ T262] softirqs last enabled at (14792): [] __alloc_skb+0x4c2/0x5f0 [ 11.169992][ T262] softirqs last disabled at (14790): [] __alloc_skb+0x4c2/0x5f0 [ 11.169995][ T262] Preemption disabled at: [ 11.169995][ T262] [] vprintk_emit+0x31b/0x3e0 [ 11.170001][ T262] CPU: 3 UID: 0 PID: 262 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.170005][ T262] Tainted: [W]=WARN [ 11.170006][ T262] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.170007][ T262] Call Trace: [ 11.170009][ T262] [ 11.170010][ T262] dump_stack_lvl+0x6f/0xa0 [ 11.170016][ T262] ? vprintk_emit+0x31b/0x3e0 [ 11.170018][ T262] __might_resched.cold+0x1fe/0x2c1 [ 11.170023][ T262] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.170027][ T262] ? __kmalloc_noprof+0xdb/0x760 [ 11.170032][ T262] __kmalloc_noprof+0x443/0x760 [ 11.170034][ T262] ? alloc_buf.isra.0+0x4b/0x260 [ 11.170040][ T262] ? do_raw_spin_unlock+0x59/0x250 [ 11.170042][ T262] alloc_buf.isra.0+0x4b/0x260 [ 11.170045][ T262] put_chars+0x1e1/0x2f0 [ 11.170048][ T262] ? __send_to_port+0x420/0x420 [ 11.170053][ T262] ? validate_chain+0x34a/0xc20 [ 11.170056][ T262] hvc_console_print+0x292/0x780 [ 11.170059][ T262] ? mark_usage+0x61/0x170 [ 11.170061][ T262] ? __lock_acquire+0x518/0xc20 [ 11.170062][ T262] ? __lock_acquire+0x518/0xc20 [ 11.170066][ T262] ? hvc_write+0x3a0/0x3a0 [ 11.170068][ T262] ? console_emit_next_record+0x1df/0x4c0 [ 11.170071][ T262] ? rcu_is_watching+0x16/0xd0 [ 11.170075][ T262] ? lock_acquire+0x13c/0x160 [ 11.170079][ T262] console_emit_next_record+0x22f/0x4c0 [ 11.170082][ T262] ? devkmsg_read+0x4b0/0x4b0 [ 11.170084][ T262] ? console_flush_one_record+0x106/0x710 [ 11.170087][ T262] ? rcu_is_watching+0x16/0xd0 [ 11.170090][ T262] ? lock_acquire+0x13c/0x160 [ 11.170093][ T262] console_flush_one_record+0x46f/0x710 [ 11.170097][ T262] ? console_emit_next_record+0x4c0/0x4c0 [ 11.170099][ T262] ? __lock_acquire+0x518/0xc20 [ 11.170104][ T262] console_unlock+0xee/0x1f0 [ 11.170107][ T262] ? console_flush_one_record+0x710/0x710 [ 11.170109][ T262] ? rcu_is_watching+0x16/0xd0 [ 11.170111][ T262] ? lock_acquire+0x60/0x160 [ 11.170114][ T262] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.170116][ T262] ? vprintk_emit+0x320/0x3e0 [ 11.170119][ T262] vprintk_emit+0x37c/0x3e0 [ 11.170123][ T262] ? wake_up_klogd_work_func+0x90/0x90 [ 11.170126][ T262] ? __lock_release.isra.0+0x69/0x1a0 [ 11.170127][ T262] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 11.170132][ T262] ? mark_held_locks+0x40/0x70 [ 11.170135][ T262] _printk+0xc7/0x100 [ 11.170138][ T262] ? snapshot_read.cold+0x21/0x21 [ 11.170142][ T262] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 11.170155][ T262] ? do_setlink.isra.0+0xa31/0x2750 [ 11.170156][ T262] ? rtnl_newlink+0x9f1/0x11c0 [ 11.170157][ T262] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.170162][ T262] br_set_state+0x22f/0x430 [bridge] [ 11.170171][ T262] br_init_port+0xc4/0x200 [bridge] [ 11.170179][ T262] new_nbp+0x39c/0x580 [bridge] [ 11.170188][ T262] br_add_if+0x212/0x1320 [bridge] [ 11.170195][ T262] ? is_bpf_text_address+0x72/0x110 [ 11.170199][ T262] ? kernel_text_address+0x149/0x170 [ 11.170202][ T262] ? __kernel_text_address+0x12/0x30 [ 11.170206][ T262] do_set_master+0x357/0x580 [ 11.170210][ T262] do_setlink.isra.0+0xa31/0x2750 [ 11.170213][ T262] ? stack_trace_save+0x93/0xc0 [ 11.170216][ T262] ? rtnl_link_get_size+0x350/0x350 [ 11.170218][ T262] ? rcu_read_lock_any_held+0x66/0x90 [ 11.170220][ T262] ? stack_depot_save_flags+0x38e/0x790 [ 11.170224][ T262] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.170227][ T262] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.170228][ T262] ? validate_chain+0x38b/0xc20 [ 11.170231][ T262] ? kasan_save_stack+0x3d/0x50 [ 11.170234][ T262] ? kasan_save_stack+0x2f/0x50 [ 11.170235][ T262] ? kasan_save_track+0x14/0x30 [ 11.170238][ T262] ? __lock_acquire+0x518/0xc20 [ 11.170240][ T262] ? netlink_seq_next+0x4e/0x60 [ 11.170243][ T262] ? ___sys_sendmsg+0xb0/0x1d0 [ 11.170247][ T262] ? lock_acquire.part.0+0xd4/0x280 [ 11.170249][ T262] ? rtnl_newlink+0x9a8/0x11c0 [ 11.170251][ T262] ? rcu_is_watching+0x16/0xd0 [ 11.170254][ T262] ? lock_acquire+0x13c/0x160 [ 11.170255][ T262] ? rcu_is_watching+0x16/0xd0 [ 11.170257][ T262] ? rcu_is_watching+0x16/0xd0 [ 11.170260][ T262] ? trace_contention_end+0xb3/0x180 [ 11.170263][ T262] ? __mutex_lock+0x1db/0x1ea0 [ 11.170265][ T262] ? __mutex_lock+0x9a3/0x1ea0 [ 11.170267][ T262] ? rtnl_newlink+0x9a8/0x11c0 [ 11.170270][ T262] ? ww_mutex_lock+0x160/0x160 [ 11.170272][ T262] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.170277][ T262] ? __rtnl_newlink+0x3fa/0xa50 [ 11.170282][ T262] rtnl_newlink+0x9f1/0x11c0 [ 11.170287][ T262] ? rtnl_bridge_getlink+0x850/0x850 [ 11.170289][ T262] ? __lock_acquire+0x518/0xc20 [ 11.170293][ T262] ? lock_acquire.part.0+0xd4/0x280 [ 11.170295][ T262] ? find_held_lock+0x2b/0x80 [ 11.170297][ T262] ? rtnl_bridge_getlink+0x850/0x850 [ 11.170299][ T262] ? __lock_release.isra.0+0x69/0x1a0 [ 11.170302][ T262] ? rtnl_bridge_getlink+0x850/0x850 [ 11.170304][ T262] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.170307][ T262] ? rtnl_link_fill+0x920/0x920 [ 11.170309][ T262] ? __lock_acquire+0x518/0xc20 [ 11.170313][ T262] ? lock_acquire.part.0+0xd4/0x280 [ 11.170315][ T262] ? find_held_lock+0x2b/0x80 [ 11.170318][ T262] netlink_rcv_skb+0x14e/0x3a0 [ 11.170320][ T262] ? rtnl_link_fill+0x920/0x920 [ 11.170323][ T262] ? netlink_ack+0xcf0/0xcf0 [ 11.170328][ T262] ? netlink_deliver_tap+0xc5/0x330 [ 11.170330][ T262] ? netlink_deliver_tap+0x13c/0x330 [ 11.170334][ T262] netlink_unicast+0x486/0x750 [ 11.170338][ T262] ? netlink_attachskb+0x810/0x810 [ 11.170340][ T262] ? __lock_acquire+0x518/0xc20 [ 11.170344][ T262] netlink_sendmsg+0x735/0xc60 [ 11.170348][ T262] ? netlink_unicast+0x750/0x750 [ 11.170352][ T262] ? __might_fault+0x97/0x140 [ 11.170357][ T262] ____sys_sendmsg+0x415/0x880 [ 11.170359][ T262] ? copy_msghdr_from_user+0x279/0x420 [ 11.170361][ T262] ? get_timestamp.constprop.0+0x390/0x390 [ 11.170363][ T262] ? move_addr_to_kernel+0x40/0x40 [ 11.170370][ T262] ___sys_sendmsg+0x14e/0x1d0 [ 11.170373][ T262] ? copy_msghdr_from_user+0x420/0x420 [ 11.170388][ T262] __sys_sendmsg+0x12c/0x1d0 [ 11.170391][ T262] ? __sys_sendmsg_sock+0x20/0x20 [ 11.170397][ T262] ? rcu_is_watching+0x16/0xd0 [ 11.170400][ T262] do_syscall_64+0xff/0x530 [ 11.170402][ T262] ? exc_page_fault+0xee/0x100 [ 11.170405][ T262] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.170408][ T262] RIP: 0033:0x7fbf6eceb54e [ 11.170412][ T262] 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.170414][ T262] RSP: 002b:00007fff7938a600 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.170417][ T262] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007fbf6eceb54e [ 11.170418][ T262] RDX: 0000000000000000 RSI: 00007fff7938a6b0 RDI: 0000000000000005 [ 11.170419][ T262] RBP: 00007fff7938a610 R08: 0000000000000000 R09: 0000000000000000 [ 11.170420][ T262] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a913787 [ 11.170420][ T262] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fff7938ad70 [ 11.170427][ T262] [ 11.209727][ T262] br1: port 1(veth1) entered disabled state [ 11.210061][ T262] veth1: entered allmulticast mode [ 11.212328][ T262] veth1: entered promiscuous mode [ 11.226569][ T262] ip (262) used greatest stack depth: 23336 bytes left [ 11.290463][ T92] br1: port 1(veth1) entered blocking state [ 11.290859][ T92] br1: port 1(veth1) entered forwarding state [ 11.344450][ T275] br1: port 2(veth2) entered blocking state [ 11.344798][ T275] br1: port 2(veth2) entered disabled state [ 11.345166][ T275] veth2: entered allmulticast mode [ 11.346696][ T275] veth2: entered promiscuous mode [ 11.429968][ T92] br1: port 2(veth2) entered blocking state [ 11.430329][ T92] br1: port 2(veth2) entered forwarding state [ 26.490919][ C2] [ 26.490935][ C2] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 26.490937][ C2] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/2 [ 26.490939][ C2] preempt_count: 104, expected: 0 [ 26.490940][ C2] RCU nest depth: 0, expected: 0 [ 26.490941][ C2] INFO: lockdep is turned off. [ 26.490942][ C2] irq event stamp: 1009584 [ 26.490946][ C2] hardirqs last enabled at (1009584): [] _raw_spin_unlock_irq+0x28/0x50 [ 26.490954][ C2] hardirqs last disabled at (1009583): [] _raw_spin_lock_irq+0x4a/0x50 [ 26.490956][ C2] softirqs last enabled at (1009574): [] handle_softirqs+0x67c/0x900 [ 26.490960][ C2] softirqs last disabled at (1009581): [] __irq_exit_rcu+0x145/0x1c0 [ 26.490962][ C2] Preemption disabled at: [ 26.490963][ C2] [<0000000000000000>] 0x0 [ 26.490969][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.490972][ C2] Tainted: [W]=WARN [ 26.490973][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.490975][ C2] Call Trace: [ 26.490977][ C2] [ 26.490978][ C2] dump_stack_lvl+0x6f/0xa0 [ 26.490984][ C2] __might_resched.cold+0x1fe/0x2c1 [ 26.490987][ C2] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 26.490991][ C2] ? __asan_memcpy+0x3c/0x60 [ 26.490993][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.490997][ C2] __kmalloc_noprof+0x443/0x760 [ 26.491000][ C2] ? __rwlock_init+0x150/0x150 [ 26.491003][ C2] ? alloc_buf.isra.0+0x4b/0x260 [ 26.491006][ C2] ? do_raw_spin_unlock+0x59/0x250 [ 26.491008][ C2] alloc_buf.isra.0+0x4b/0x260 [ 26.491010][ C2] put_chars+0x1e1/0x2f0 [ 26.491012][ C2] ? __send_to_port+0x420/0x420 [ 26.491013][ C2] ? console_prepend_replay+0x20/0x20 [ 26.491017][ C2] hvc_console_print+0x292/0x780 [ 26.491020][ C2] ? hvc_write+0x3a0/0x3a0 [ 26.491021][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.491023][ C2] ? lock_acquire+0x13c/0x160 [ 26.491025][ C2] console_emit_next_record+0x22f/0x4c0 [ 26.491027][ C2] ? devkmsg_read+0x4b0/0x4b0 [ 26.491029][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.491031][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.491032][ C2] ? lock_acquire+0x13c/0x160 [ 26.491034][ C2] ? console_flush_one_record+0x111/0x710 [ 26.491036][ C2] console_flush_one_record+0x46f/0x710 [ 26.491038][ C2] ? console_emit_next_record+0x4c0/0x4c0 [ 26.491041][ C2] console_unlock+0xee/0x1f0 [ 26.491042][ C2] ? lock_acquire+0x13c/0x160 [ 26.491044][ C2] ? console_flush_one_record+0x710/0x710 [ 26.491045][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.491047][ C2] ? lock_release+0x184/0x1f0 [ 26.491048][ C2] ? lock_acquire+0x60/0x160 [ 26.491050][ C2] ? __down_trylock_console_sem+0x5e/0xa0 [ 26.491052][ C2] ? vprintk_emit+0x320/0x3e0 [ 26.491054][ C2] vprintk_emit+0x37c/0x3e0 [ 26.491056][ C2] ? wake_up_klogd_work_func+0x90/0x90 [ 26.491058][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.491070][ C2] ? lock_release+0x184/0x1f0 [ 26.491071][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.491078][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.491083][ C2] ? is_module_text_address+0x154/0x250 [ 26.491087][ C2] _printk+0xc7/0x100 [ 26.491090][ C2] ? snapshot_read.cold+0x21/0x21 [ 26.491092][ C2] ? arch_stack_walk+0xd7/0x130 [ 26.491096][ C2] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 26.491098][ C2] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 26.491099][ C2] print_irq_inversion_bug.part.0+0x32/0xc0 [ 26.491102][ C2] mark_lock_irq+0x989/0x9c0 [ 26.491105][ C2] mark_lock+0x1d7/0xa00 [ 26.491107][ C2] mark_usage+0x42/0x170 [ 26.491108][ C2] __lock_acquire+0x388/0xc20 [ 26.491110][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.491112][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.491118][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.491120][ C2] ? lock_acquire+0x13c/0x160 [ 26.491122][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.491127][ C2] _raw_spin_lock+0x33/0x40 [ 26.491129][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.491135][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.491141][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.491147][ C2] call_timer_fn+0x160/0x4d0 [ 26.491150][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 26.491152][ C2] ? debug_object_active_state+0x430/0x430 [ 26.491155][ C2] ? find_held_lock+0x2b/0x80 [ 26.491157][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 26.491159][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.491161][ C2] __run_timers+0x68f/0xaa0 [ 26.491163][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.491169][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.491171][ C2] ? __lock_acquire+0x518/0xc20 [ 26.491173][ C2] ? __rwlock_init+0x150/0x150 [ 26.491176][ C2] run_timer_softirq+0xf0/0x160 [ 26.491178][ C2] ? __run_timers+0xaa0/0xaa0 [ 26.491179][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.491182][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.491183][ C2] handle_softirqs+0x1d3/0x900 [ 26.491185][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 26.491187][ C2] ? _local_bh_enable+0xc0/0xc0 [ 26.491189][ C2] __irq_exit_rcu+0x145/0x1c0 [ 26.491190][ C2] irq_exit_rcu+0xe/0x30 [ 26.491191][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.491194][ C2] [ 26.491195][ C2] [ 26.491195][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.491198][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.491200][ C2] 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.491202][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 26.491205][ C2] RAX: 00000000000f67ab RBX: ff11000001bec6c0 RCX: ffffffff8e6f0307 [ 26.491207][ C2] RDX: ff11000001bec6c0 RSI: ffffffff91438b01 RDI: ffffffff90e8d8e0 [ 26.491208][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.491209][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 26.491209][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 26.491211][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.491214][ C2] default_idle+0x9/0x10 [ 26.491216][ C2] default_idle_call+0x6e/0xb0 [ 26.491217][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.491219][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 26.491220][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 26.491223][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.491224][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.491226][ C2] do_idle+0xd8/0x190 [ 26.491227][ C2] cpu_startup_entry+0x53/0x70 [ 26.491229][ C2] start_secondary+0x204/0x2b0 [ 26.491231][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 26.491233][ C2] common_startup_64+0x13e/0x148 [ 26.491236][ C2] [ 26.510357][ C2] ======================================================== [ 26.510558][ C2] WARNING: possible irq lock inversion dependency detected [ 26.510798][ C2] 7.2.0-virtme #1 Tainted: G W [ 26.511008][ C2] -------------------------------------------------------- [ 26.511204][ C2] swapper/2/0 just changed the state of lock: [ 26.511410][ C2] ff1100000c812e58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.511725][ C2] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 26.511970][ C2] (fs_reclaim){+.+.}-{0:0} [ 26.511972][ C2] [ 26.511972][ C2] [ 26.511972][ C2] and interrupts could create inverse lock ordering between them. [ 26.511972][ C2] [ 26.512540][ C2] [ 26.512540][ C2] other info that might help us debug this: [ 26.512743][ C2] Chain exists of: [ 26.512743][ C2] &br->lock --> console_owner --> fs_reclaim [ 26.512743][ C2] [ 26.513133][ C2] Possible interrupt unsafe locking scenario: [ 26.513133][ C2] [ 26.513331][ C2] CPU0 CPU1 [ 26.513512][ C2] ---- ---- [ 26.513644][ C2] lock(fs_reclaim); [ 26.513793][ C2] local_irq_disable(); [ 26.513965][ C2] lock(&br->lock); [ 26.514173][ C2] lock(console_owner); [ 26.514379][ C2] [ 26.514481][ C2] lock(&br->lock); [ 26.514581][ C2] [ 26.514581][ C2] *** DEADLOCK *** [ 26.514581][ C2] [ 26.514816][ C2] locks held by swapper/2/0: 1, last CPU#2: [ 26.515030][ C2] #0: ffa0000000228c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 26.515337][ C2] [ 26.515337][ C2] the shortest dependencies between 2nd lock and 1st lock: [ 26.515603][ C2] -> (fs_reclaim){+.+.}-{0:0} { [ 26.515740][ C2] HARDIRQ-ON-W at: [ 26.515843][ C2] __lock_acquire+0x388/0xc20 [ 26.516067][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.516279][ C2] fs_reclaim_acquire+0xd5/0x120 [ 26.516444][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 26.516690][ C2] kthread_create_worker_on_node+0xea/0x210 [ 26.516936][ C2] workqueue_init+0x2a/0x680 [ 26.517109][ C2] kernel_init_freeable+0x2fe/0x630 [ 26.517354][ C2] kernel_init+0x21/0x150 [ 26.517564][ C2] ret_from_fork+0x474/0x6b0 [ 26.517730][ C2] ret_from_fork_asm+0x11/0x20 [ 26.517949][ C2] SOFTIRQ-ON-W at: [ 26.518049][ C2] __lock_acquire+0x388/0xc20 [ 26.518258][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.518493][ C2] fs_reclaim_acquire+0xd5/0x120 [ 26.518658][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 26.518900][ C2] kthread_create_worker_on_node+0xea/0x210 [ 26.519153][ C2] workqueue_init+0x2a/0x680 [ 26.519319][ C2] kernel_init_freeable+0x2fe/0x630 [ 26.519563][ C2] kernel_init+0x21/0x150 [ 26.519775][ C2] ret_from_fork+0x474/0x6b0 [ 26.519942][ C2] ret_from_fork_asm+0x11/0x20 [ 26.520157][ C2] INITIAL USE at: [ 26.520254][ C2] __lock_acquire+0x388/0xc20 [ 26.520466][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.520675][ C2] fs_reclaim_acquire+0xd5/0x120 [ 26.520839][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 26.521092][ C2] kthread_create_worker_on_node+0xea/0x210 [ 26.521336][ C2] workqueue_init+0x2a/0x680 [ 26.521502][ C2] kernel_init_freeable+0x2fe/0x630 [ 26.521714][ C2] kernel_init+0x21/0x150 [ 26.521928][ C2] ret_from_fork+0x474/0x6b0 [ 26.522098][ C2] ret_from_fork_asm+0x11/0x20 [ 26.522309][ C2] } [ 26.522379][ C2] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 26.522621][ C2] ... acquired at: [ 26.522721][ C2] __lock_acquire+0x518/0xc20 [ 26.522902][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.523034][ C2] fs_reclaim_acquire+0xd5/0x120 [ 26.523212][ C2] __kmalloc_noprof+0xd3/0x760 [ 26.523342][ C2] alloc_buf.isra.0+0x4b/0x260 [ 26.523519][ C2] put_chars+0x1e1/0x2f0 [ 26.523650][ C2] hvc_console_print+0x292/0x780 [ 26.523827][ C2] console_emit_next_record+0x22f/0x4c0 [ 26.523965][ C2] console_flush_one_record+0x46f/0x710 [ 26.524141][ C2] console_unlock+0xee/0x1f0 [ 26.524274][ C2] vprintk_emit+0x37c/0x3e0 [ 26.524449][ C2] _printk+0xc7/0x100 [ 26.524549][ C2] sched_clock_init_late+0x14c/0x170 [ 26.524724][ C2] do_one_initcall+0x124/0x4f0 [ 26.524855][ C2] kernel_init_freeable+0x596/0x630 [ 26.525033][ C2] kernel_init+0x21/0x150 [ 26.525163][ C2] ret_from_fork+0x474/0x6b0 [ 26.525337][ C2] ret_from_fork_asm+0x11/0x20 [ 26.525468][ C2] [ 26.525537][ C2] -> (console_owner){....}-{0:0} { [ 26.525715][ C2] INITIAL USE at: [ 26.525815][ C2] } [ 26.525927][ C2] ... key at: [] console_owner_dep_map+0x0/0x60 [ 26.526127][ C2] ... acquired at: [ 26.526267][ C2] __lock_acquire+0x518/0xc20 [ 26.526400][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.526576][ C2] console_lock_spinning_enable+0x5c/0x60 [ 26.526740][ C2] console_emit_next_record+0x1d1/0x4c0 [ 26.526916][ C2] console_flush_one_record+0x46f/0x710 [ 26.527053][ C2] console_unlock+0xee/0x1f0 [ 26.527229][ C2] vprintk_emit+0x37c/0x3e0 [ 26.527361][ C2] _printk+0xc7/0x100 [ 26.527508][ C2] br_set_state+0x22f/0x430 [bridge] [ 26.527646][ C2] br_init_port+0xc4/0x200 [bridge] [ 26.527827][ C2] br_stp_enable_port+0x12/0x50 [bridge] [ 26.528000][ C2] br_port_carrier_check+0x220/0x430 [bridge] [ 26.528212][ C2] br_device_event+0x52d/0x8f0 [bridge] [ 26.528348][ C2] notifier_call_chain+0xae/0x300 [ 26.528524][ C2] netif_state_change+0x139/0x340 [ 26.528656][ C2] __linkwatch_run_queue+0x34c/0x750 [ 26.528833][ C2] linkwatch_event+0x7f/0xb0 [ 26.528970][ C2] process_one_work+0xe3e/0x1560 [ 26.529145][ C2] worker_thread+0x4f1/0xd60 [ 26.529277][ C2] kthread+0x367/0x460 [ 26.529419][ C2] ret_from_fork+0x474/0x6b0 [ 26.529549][ C2] ret_from_fork_asm+0x11/0x20 [ 26.529728][ C2] [ 26.529794][ C2] -> (&br->lock){+.-.}-{3:3} { [ 26.529977][ C2] HARDIRQ-ON-W at: [ 26.530080][ C2] __lock_acquire+0x388/0xc20 [ 26.530293][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.530460][ C2] _raw_spin_lock_bh+0x38/0x50 [ 26.530669][ C2] br_set_ageing_time+0x2d/0xf0 [bridge] [ 26.530872][ C2] br_changelink+0x240/0x16e0 [bridge] [ 26.531050][ C2] br_dev_newlink+0xeb/0x160 [bridge] [ 26.531218][ C2] rtnl_newlink_create+0x2d0/0x750 [ 26.531381][ C2] __rtnl_newlink+0x22b/0xa50 [ 26.531589][ C2] rtnl_newlink+0x9f1/0x11c0 [ 26.531800][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.531971][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 26.535963][ C2] netlink_unicast+0x486/0x750 [ 26.536183][ C2] netlink_sendmsg+0x735/0xc60 [ 26.536348][ C2] ____sys_sendmsg+0x415/0x880 [ 26.536561][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 26.536726][ C2] __sys_sendmsg+0x12c/0x1d0 [ 26.536941][ C2] do_syscall_64+0xff/0x530 [ 26.537163][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.537364][ C2] IN-SOFTIRQ-W at: [ 26.537514][ C2] __lock_acquire+0x388/0xc20 [ 26.537684][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.537900][ C2] _raw_spin_lock+0x33/0x40 [ 26.538117][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.538324][ C2] call_timer_fn+0x160/0x4d0 [ 26.538544][ C2] __run_timers+0x68f/0xaa0 [ 26.538758][ C2] run_timer_softirq+0xf0/0x160 [ 26.538926][ C2] handle_softirqs+0x1d3/0x900 [ 26.539145][ C2] __irq_exit_rcu+0x145/0x1c0 [ 26.539359][ C2] irq_exit_rcu+0xe/0x30 [ 26.539527][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.539772][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.540029][ C2] pv_native_safe_halt+0xf/0x10 [ 26.540197][ C2] default_idle+0x9/0x10 [ 26.540412][ C2] default_idle_call+0x6e/0xb0 [ 26.540622][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.540824][ C2] do_idle+0xd8/0x190 [ 26.541009][ C2] cpu_startup_entry+0x53/0x70 [ 26.541175][ C2] start_secondary+0x204/0x2b0 [ 26.541390][ C2] common_startup_64+0x13e/0x148 [ 26.541605][ C2] INITIAL USE at: [ 26.541705][ C2] __lock_acquire+0x388/0xc20 [ 26.541917][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.542085][ C2] _raw_spin_lock_bh+0x38/0x50 [ 26.542294][ C2] br_set_ageing_time+0x2d/0xf0 [bridge] [ 26.542563][ C2] br_changelink+0x240/0x16e0 [bridge] [ 26.542733][ C2] br_dev_newlink+0xeb/0x160 [bridge] [ 26.542951][ C2] rtnl_newlink_create+0x2d0/0x750 [ 26.543166][ C2] __rtnl_newlink+0x22b/0xa50 [ 26.543328][ C2] rtnl_newlink+0x9f1/0x11c0 [ 26.543538][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.543702][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 26.543913][ C2] netlink_unicast+0x486/0x750 [ 26.544126][ C2] netlink_sendmsg+0x735/0xc60 [ 26.544292][ C2] ____sys_sendmsg+0x415/0x880 [ 26.544501][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 26.544713][ C2] __sys_sendmsg+0x12c/0x1d0 [ 26.544883][ C2] do_syscall_64+0xff/0x530 [ 26.545098][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.545341][ C2] } [ 26.545410][ C2] ... key at: [] __key.7+0x0/0x40 [bridge] [ 26.545655][ C2] ... acquired at: [ 26.545754][ C2] mark_lock+0x1d7/0xa00 [ 26.545890][ C2] mark_usage+0x42/0x170 [ 26.546066][ C2] __lock_acquire+0x388/0xc20 [ 26.546198][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.546373][ C2] _raw_spin_lock+0x33/0x40 [ 26.546508][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.546721][ C2] call_timer_fn+0x160/0x4d0 [ 26.546898][ C2] __run_timers+0x68f/0xaa0 [ 26.547037][ C2] run_timer_softirq+0xf0/0x160 [ 26.547212][ C2] handle_softirqs+0x1d3/0x900 [ 26.547346][ C2] __irq_exit_rcu+0x145/0x1c0 [ 26.547520][ C2] irq_exit_rcu+0xe/0x30 [ 26.547650][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.547857][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.548025][ C2] pv_native_safe_halt+0xf/0x10 [ 26.548201][ C2] default_idle+0x9/0x10 [ 26.548333][ C2] default_idle_call+0x6e/0xb0 [ 26.548509][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.548672][ C2] do_idle+0xd8/0x190 [ 26.548814][ C2] cpu_startup_entry+0x53/0x70 [ 26.548952][ C2] start_secondary+0x204/0x2b0 [ 26.549128][ C2] common_startup_64+0x13e/0x148 [ 26.549261][ C2] [ 26.549372][ C2] [ 26.549372][ C2] stack backtrace: [ 26.549542][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.549545][ C2] Tainted: [W]=WARN [ 26.549546][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.549547][ C2] Call Trace: [ 26.549549][ C2] [ 26.549550][ C2] dump_stack_lvl+0x6f/0xa0 [ 26.549555][ C2] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 26.549558][ C2] mark_lock_irq+0x989/0x9c0 [ 26.549560][ C2] mark_lock+0x1d7/0xa00 [ 26.549562][ C2] mark_usage+0x42/0x170 [ 26.549564][ C2] __lock_acquire+0x388/0xc20 [ 26.549566][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.549568][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.549575][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.549578][ C2] ? lock_acquire+0x13c/0x160 [ 26.549580][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.549586][ C2] _raw_spin_lock+0x33/0x40 [ 26.549588][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.549594][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.549601][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.549607][ C2] call_timer_fn+0x160/0x4d0 [ 26.549609][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 26.549611][ C2] ? debug_object_active_state+0x430/0x430 [ 26.549614][ C2] ? find_held_lock+0x2b/0x80 [ 26.549616][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 26.549617][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.549620][ C2] __run_timers+0x68f/0xaa0 [ 26.549621][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.549628][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.549629][ C2] ? __lock_acquire+0x518/0xc20 [ 26.549632][ C2] ? __rwlock_init+0x150/0x150 [ 26.549634][ C2] run_timer_softirq+0xf0/0x160 [ 26.549636][ C2] ? __run_timers+0xaa0/0xaa0 [ 26.549638][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.549640][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.549642][ C2] handle_softirqs+0x1d3/0x900 [ 26.549644][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 26.549645][ C2] ? _local_bh_enable+0xc0/0xc0 [ 26.549647][ C2] __irq_exit_rcu+0x145/0x1c0 [ 26.549648][ C2] irq_exit_rcu+0xe/0x30 [ 26.549650][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.549651][ C2] [ 26.549652][ C2] [ 26.549653][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.549654][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.549657][ C2] 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.549658][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 26.549660][ C2] RAX: 00000000000f67ab RBX: ff11000001bec6c0 RCX: ffffffff8e6f0307 [ 26.549662][ C2] RDX: ff11000001bec6c0 RSI: ffffffff91438b01 RDI: ffffffff90e8d8e0 [ 26.549662][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.549663][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 26.549664][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 26.549665][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.549668][ C2] default_idle+0x9/0x10 [ 26.549669][ C2] default_idle_call+0x6e/0xb0 [ 26.549671][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.549672][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 26.549674][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 26.549676][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.549677][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.549679][ C2] do_idle+0xd8/0x190 [ 26.549680][ C2] cpu_startup_entry+0x53/0x70 [ 26.549682][ C2] start_secondary+0x204/0x2b0 [ 26.549683][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 26.549685][ C2] common_startup_64+0x13e/0x148 [ 26.549688][ C2] [ 29.329384][ T729] br1: port 2(veth2) entered disabled state [ 29.329403][ T729] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 29.329405][ T729] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 729, name: ip [ 29.329407][ T729] preempt_count: 202, expected: 0 [ 29.329408][ T729] RCU nest depth: 0, expected: 0 [ 29.329409][ T729] INFO: lockdep is turned off. [ 29.329410][ T729] irq event stamp: 0 [ 29.329411][ T729] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 29.329415][ T729] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 29.329422][ T729] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 29.329423][ T729] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 29.329425][ T729] Preemption disabled at: [ 29.329426][ T729] [<0000000000000000>] 0x0 [ 29.329431][ T729] CPU: 2 UID: 0 PID: 729 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 29.329434][ T729] Tainted: [W]=WARN [ 29.329435][ T729] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 29.329437][ T729] Call Trace: [ 29.329439][ T729] [ 29.329440][ T729] dump_stack_lvl+0x6f/0xa0 [ 29.329446][ T729] __might_resched.cold+0x1fe/0x2c1 [ 29.329450][ T729] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 29.329453][ T729] ? rcu_is_watching+0x16/0xd0 [ 29.329457][ T729] __kmalloc_noprof+0x443/0x760 [ 29.329461][ T729] ? __rwlock_init+0x150/0x150 [ 29.329465][ T729] ? alloc_buf.isra.0+0x4b/0x260 [ 29.329470][ T729] ? do_raw_spin_unlock+0x59/0x250 [ 29.329472][ T729] alloc_buf.isra.0+0x4b/0x260 [ 29.329475][ T729] put_chars+0x1e1/0x2f0 [ 29.329477][ T729] ? __send_to_port+0x420/0x420 [ 29.329478][ T729] ? printk_get_next_message+0x240/0x650 [ 29.329481][ T729] hvc_console_print+0x292/0x780 [ 29.329485][ T729] ? hvc_write+0x3a0/0x3a0 [ 29.329487][ T729] ? __rwlock_init+0x150/0x150 [ 29.329488][ T729] ? rcu_is_watching+0x16/0xd0 [ 29.329490][ T729] ? lock_acquire+0x13c/0x160 [ 29.329492][ T729] console_emit_next_record+0x22f/0x4c0 [ 29.329495][ T729] ? devkmsg_read+0x4b0/0x4b0 [ 29.329497][ T729] ? rcu_is_watching+0x16/0xd0 [ 29.329498][ T729] ? rcu_is_watching+0x16/0xd0 [ 29.329500][ T729] ? rcu_is_watching+0x16/0xd0 [ 29.329501][ T729] ? lock_acquire+0x13c/0x160 [ 29.329503][ T729] ? console_flush_one_record+0x111/0x710 [ 29.329505][ T729] console_flush_one_record+0x46f/0x710 [ 29.329507][ T729] ? console_emit_next_record+0x4c0/0x4c0 [ 29.329509][ T729] console_unlock+0xee/0x1f0 [ 29.329511][ T729] ? lock_acquire+0x13c/0x160 [ 29.329512][ T729] ? console_flush_one_record+0x710/0x710 [ 29.329514][ T729] ? rcu_is_watching+0x16/0xd0 [ 29.329516][ T729] ? lock_release+0x184/0x1f0 [ 29.329517][ T729] ? lock_acquire+0x60/0x160 [ 29.329518][ T729] ? __rcu_read_lock+0x30/0x70 [ 29.329520][ T729] ? __down_trylock_console_sem+0x5e/0xa0 [ 29.329522][ T729] ? vprintk_emit+0x320/0x3e0 [ 29.329524][ T729] vprintk_emit+0x37c/0x3e0 [ 29.329526][ T729] ? wake_up_klogd_work_func+0x90/0x90 [ 29.329528][ T729] ? rcu_is_watching+0x16/0xd0 [ 29.329530][ T729] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 29.329532][ T729] ? trace_hardirqs_on+0x36/0x40 [ 29.329534][ T729] _printk+0xc7/0x100 [ 29.329537][ T729] ? snapshot_read.cold+0x21/0x21 [ 29.329539][ T729] ? ipv6_mc_down+0x17e/0x2d0 [ 29.329544][ T729] br_set_state+0x22f/0x430 [bridge] [ 29.329557][ T729] br_stp_disable_port+0x11f/0x390 [bridge] [ 29.329565][ T729] br_device_event+0x7b5/0x8f0 [bridge] [ 29.329574][ T729] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 29.329577][ T729] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 29.329579][ T729] ? addrconf_notify+0x2c8/0xf30 [ 29.329583][ T729] notifier_call_chain+0xae/0x300 [ 29.329586][ T729] __dev_notify_flags+0x23c/0x3c0 [ 29.329590][ T729] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 29.329591][ T729] ? netif_change_name+0x8c0/0x8c0 [ 29.329593][ T729] ? netif_set_allmulti+0x250/0x250 [ 29.329595][ T729] ? __kernel_text_address+0x12/0x30 [ 29.329597][ T729] ? write_profile+0xf0/0xf0 [ 29.329600][ T729] ? unwind_get_return_address+0x67/0xd0 [ 29.329603][ T729] netif_change_flags+0xfe/0x190 [ 29.329605][ T729] do_setlink.isra.0+0x1cc3/0x2750 [ 29.329608][ T729] ? stack_trace_save+0x93/0xc0 [ 29.329609][ T729] ? rtnl_link_get_size+0x350/0x350 [ 29.329610][ T729] ? rcu_is_watching+0x16/0xd0 [ 29.329612][ T729] ? stack_depot_save_flags+0x1bc/0x790 [ 29.329615][ T729] ? kasan_save_stack+0x3d/0x50 [ 29.329618][ T729] ? kasan_save_stack+0x2f/0x50 [ 29.329620][ T729] ? kasan_save_track+0x14/0x30 [ 29.329621][ T729] ? __kasan_kmalloc+0x7b/0x90 [ 29.329623][ T729] ? __kmalloc_cache_noprof+0x29a/0x620 [ 29.329624][ T729] ? rtnl_newlink+0x124/0x11c0 [ 29.329625][ T729] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 29.329626][ T729] ? netlink_rcv_skb+0x14e/0x3a0 [ 29.329629][ T729] ? netlink_unicast+0x486/0x750 [ 29.329630][ T729] ? netlink_sendmsg+0x735/0xc60 [ 29.329631][ T729] ? ____sys_sendmsg+0x415/0x880 [ 29.329634][ T729] ? ___sys_sendmsg+0x14e/0x1d0 [ 29.329635][ T729] ? __sys_sendmsg+0x12c/0x1d0 [ 29.329637][ T729] ? do_syscall_64+0xff/0x530 [ 29.329638][ T729] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.329641][ T729] ? rcu_is_watching+0x16/0xd0 [ 29.329643][ T729] ? lock_acquire+0x13c/0x160 [ 29.329644][ T729] ? rcu_is_watching+0x16/0xd0 [ 29.329646][ T729] ? rcu_is_watching+0x16/0xd0 [ 29.329647][ T729] ? trace_contention_end+0xb3/0x180 [ 29.329650][ T729] ? __mutex_lock+0x1db/0x1ea0 [ 29.329652][ T729] ? __mutex_lock+0x9a3/0x1ea0 [ 29.329653][ T729] ? rtnl_newlink+0x9a8/0x11c0 [ 29.329654][ T729] ? ww_mutex_lock+0x160/0x160 [ 29.329657][ T729] ? __rtnl_newlink+0x3fa/0xa50 [ 29.329658][ T729] rtnl_newlink+0x9f1/0x11c0 [ 29.329660][ T729] ? rcu_is_watching+0x16/0xd0 [ 29.329662][ T729] ? rtnl_bridge_getlink+0x850/0x850 [ 29.329663][ T729] ? lock_acquire+0x13c/0x160 [ 29.329664][ T729] ? rcu_is_watching+0x16/0xd0 [ 29.329666][ T729] ? is_bpf_text_address+0x3e/0x110 [ 29.329670][ T729] ? is_bpf_text_address+0x72/0x110 [ 29.329671][ T729] ? kernel_text_address+0x149/0x170 [ 29.329673][ T729] ? __kernel_text_address+0x12/0x30 [ 29.329674][ T729] ? write_profile+0xf0/0xf0 [ 29.329675][ T729] ? unwind_get_return_address+0x67/0xd0 [ 29.329676][ T729] ? rcu_is_watching+0x16/0xd0 [ 29.329678][ T729] ? rcu_is_watching+0x16/0xd0 [ 29.329679][ T729] ? cap_capable+0x1d7/0x3d0 [ 29.329683][ T729] ? lock_acquire+0x13c/0x160 [ 29.329684][ T729] ? rcu_is_watching+0x16/0xd0 [ 29.329686][ T729] ? rtnl_bridge_getlink+0x850/0x850 [ 29.329687][ T729] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 29.329689][ T729] ? rtnl_link_fill+0x920/0x920 [ 29.329690][ T729] ? filter_irq_stacks+0xd0/0xd0 [ 29.329691][ T729] ? stack_depot_save_flags+0x1bc/0x790 [ 29.329693][ T729] netlink_rcv_skb+0x14e/0x3a0 [ 29.329695][ T729] ? rtnl_link_fill+0x920/0x920 [ 29.329696][ T729] ? lock_release+0x184/0x1f0 [ 29.329697][ T729] ? netlink_ack+0xcf0/0xcf0 [ 29.329699][ T729] ? __netlink_lookup+0xb9/0x6b0 [ 29.329701][ T729] ? rcu_is_watching+0x16/0xd0 [ 29.329703][ T729] ? lock_release+0x184/0x1f0 [ 29.329705][ T729] netlink_unicast+0x486/0x750 [ 29.329706][ T729] ? netlink_attachskb+0x810/0x810 [ 29.329708][ T729] ? rcu_is_watching+0x16/0xd0 [ 29.329710][ T729] ? rcu_is_watching+0x16/0xd0 [ 29.329711][ T729] ? lock_acquire+0x13c/0x160 [ 29.329713][ T729] netlink_sendmsg+0x735/0xc60 [ 29.329715][ T729] ? netlink_unicast+0x750/0x750 [ 29.329717][ T729] ? rcu_is_watching+0x16/0xd0 [ 29.329718][ T729] ? lock_release+0x184/0x1f0 [ 29.329720][ T729] ____sys_sendmsg+0x415/0x880 [ 29.329721][ T729] ? copy_msghdr_from_user+0x279/0x420 [ 29.329723][ T729] ? get_timestamp.constprop.0+0x390/0x390 [ 29.329725][ T729] ? move_addr_to_kernel+0x40/0x40 [ 29.329726][ T729] ? filter_irq_stacks+0xd0/0xd0 [ 29.329728][ T729] ___sys_sendmsg+0x14e/0x1d0 [ 29.329730][ T729] ? copy_msghdr_from_user+0x420/0x420 [ 29.329735][ T729] __sys_sendmsg+0x12c/0x1d0 [ 29.329736][ T729] ? __sys_sendmsg_sock+0x20/0x20 [ 29.329739][ T729] ? rcu_is_watching+0x16/0xd0 [ 29.329741][ T729] do_syscall_64+0xff/0x530 [ 29.329742][ T729] ? exc_page_fault+0xee/0x100 [ 29.329745][ T729] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.329746][ T729] RIP: 0033:0x7fc3fedec54e [ 29.329748][ T729] 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 [ 29.329750][ T729] RSP: 002b:00007ffe6d3d1010 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 29.329754][ T729] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007fc3fedec54e [ 29.329755][ T729] RDX: 0000000000000000 RSI: 00007ffe6d3d10c0 RDI: 0000000000000005 [ 29.329756][ T729] RBP: 00007ffe6d3d1020 R08: 0000000000000000 R09: 0000000000000000 [ 29.329757][ T729] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a913799 [ 29.329758][ T729] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffe6d3d1780 [ 29.329760][ T729] [ 29.363676][ T729] ip (729) used greatest stack depth: 23016 bytes left [ 29.384680][ T731] veth2: left allmulticast mode [ 29.384885][ T731] veth2: left promiscuous mode [ 29.385150][ T731] br1: port 2(veth2) entered disabled state [ 29.404450][ T733] br1: port 1(veth1) entered disabled state [ 29.433018][ T735] veth1: left allmulticast mode [ 29.433238][ T735] veth1: left promiscuous mode [ 29.433540][ T735] br1: port 1(veth1) entered disabled state