[ 11.376483][ T242] br1: port 1(veth1) entered blocking state [ 11.376566][ T242] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.376568][ T242] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 242, name: ip [ 11.376569][ T242] preempt_count: 1, expected: 0 [ 11.376571][ T242] RCU nest depth: 0, expected: 0 [ 11.376572][ T242] locks held by ip/242: 5, last CPU#0: [ 11.376574][ T242] #0: ffffffffb9ed2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 11.376586][ T242] #1: ffffffffb9769cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.376593][ T242] #2: ffffffffb9769d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.376597][ T242] #3: ffffffffb9689660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.376600][ T242] #4: ffffffffb9689560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.376604][ T242] irq event stamp: 21794 [ 11.376605][ T242] hardirqs last enabled at (21793): [] __down_trylock_console_sem+0x86/0xa0 [ 11.376608][ T242] hardirqs last disabled at (21794): [] console_emit_next_record+0x3d4/0x4c0 [ 11.376610][ T242] softirqs last enabled at (21704): [] __alloc_skb+0x4c2/0x5f0 [ 11.376613][ T242] softirqs last disabled at (21702): [] __alloc_skb+0x4c2/0x5f0 [ 11.376615][ T242] Preemption disabled at: [ 11.376616][ T242] [] vprintk_emit+0x31b/0x3e0 [ 11.376622][ T242] CPU: 0 UID: 0 PID: 242 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.376625][ T242] Tainted: [W]=WARN [ 11.376626][ T242] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.376628][ T242] Call Trace: [ 11.376629][ T242] [ 11.376631][ T242] dump_stack_lvl+0x6f/0xa0 [ 11.376637][ T242] ? vprintk_emit+0x31b/0x3e0 [ 11.376639][ T242] __might_resched.cold+0x1fe/0x2c1 [ 11.376644][ T242] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.376648][ T242] ? __kmalloc_noprof+0xdb/0x760 [ 11.376653][ T242] __kmalloc_noprof+0x443/0x760 [ 11.376656][ T242] ? alloc_buf.isra.0+0x4b/0x260 [ 11.376662][ T242] ? do_raw_spin_unlock+0x59/0x250 [ 11.376664][ T242] alloc_buf.isra.0+0x4b/0x260 [ 11.376667][ T242] put_chars+0x1e1/0x2f0 [ 11.376670][ T242] ? __send_to_port+0x420/0x420 [ 11.376675][ T242] ? validate_chain+0x34a/0xc20 [ 11.376678][ T242] hvc_console_print+0x292/0x780 [ 11.376681][ T242] ? mark_usage+0x61/0x170 [ 11.376683][ T242] ? __lock_acquire+0x518/0xc20 [ 11.376685][ T242] ? __lock_acquire+0x518/0xc20 [ 11.376689][ T242] ? hvc_write+0x3a0/0x3a0 [ 11.376691][ T242] ? console_emit_next_record+0x1df/0x4c0 [ 11.376694][ T242] ? rcu_is_watching+0x16/0xd0 [ 11.376698][ T242] ? lock_acquire+0x13c/0x160 [ 11.376702][ T242] console_emit_next_record+0x22f/0x4c0 [ 11.376706][ T242] ? devkmsg_read+0x4b0/0x4b0 [ 11.376708][ T242] ? console_flush_one_record+0x106/0x710 [ 11.376711][ T242] ? rcu_is_watching+0x16/0xd0 [ 11.376713][ T242] ? lock_acquire+0x13c/0x160 [ 11.376717][ T242] console_flush_one_record+0x46f/0x710 [ 11.376721][ T242] ? console_emit_next_record+0x4c0/0x4c0 [ 11.376723][ T242] ? __lock_acquire+0x518/0xc20 [ 11.376729][ T242] console_unlock+0xee/0x1f0 [ 11.376731][ T242] ? console_flush_one_record+0x710/0x710 [ 11.376733][ T242] ? rcu_is_watching+0x16/0xd0 [ 11.376736][ T242] ? lock_acquire+0x60/0x160 [ 11.376739][ T242] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.376741][ T242] ? vprintk_emit+0x320/0x3e0 [ 11.376744][ T242] vprintk_emit+0x37c/0x3e0 [ 11.376748][ T242] ? wake_up_klogd_work_func+0x90/0x90 [ 11.376751][ T242] ? __lock_release.isra.0+0x69/0x1a0 [ 11.376753][ T242] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 11.376757][ T242] ? mark_held_locks+0x40/0x70 [ 11.376760][ T242] _printk+0xc7/0x100 [ 11.376764][ T242] ? snapshot_read.cold+0x21/0x21 [ 11.376768][ T242] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 11.376780][ T242] ? do_setlink.isra.0+0xa31/0x2750 [ 11.376782][ T242] ? rtnl_newlink+0x9f1/0x11c0 [ 11.376783][ T242] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.376793][ T242] br_set_state+0x22f/0x430 [bridge] [ 11.376802][ T242] br_init_port+0xc4/0x200 [bridge] [ 11.376811][ T242] new_nbp+0x39c/0x580 [bridge] [ 11.376819][ T242] br_add_if+0x212/0x1320 [bridge] [ 11.376826][ T242] ? is_bpf_text_address+0x72/0x110 [ 11.376830][ T242] ? kernel_text_address+0x149/0x170 [ 11.376833][ T242] ? __kernel_text_address+0x12/0x30 [ 11.376837][ T242] do_set_master+0x357/0x580 [ 11.376842][ T242] do_setlink.isra.0+0xa31/0x2750 [ 11.376845][ T242] ? stack_trace_save+0x93/0xc0 [ 11.376848][ T242] ? rtnl_link_get_size+0x350/0x350 [ 11.376850][ T242] ? rcu_read_lock_any_held+0x66/0x90 [ 11.376852][ T242] ? stack_depot_save_flags+0x38e/0x790 [ 11.376855][ T242] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.376858][ T242] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.376860][ T242] ? validate_chain+0x38b/0xc20 [ 11.376862][ T242] ? kasan_save_stack+0x3d/0x50 [ 11.376865][ T242] ? kasan_save_stack+0x2f/0x50 [ 11.376867][ T242] ? kasan_save_track+0x14/0x30 [ 11.376870][ T242] ? __lock_acquire+0x518/0xc20 [ 11.376871][ T242] ? netlink_seq_next+0x4e/0x60 [ 11.376874][ T242] ? ___sys_sendmsg+0xb0/0x1d0 [ 11.376879][ T242] ? lock_acquire.part.0+0xd4/0x280 [ 11.376881][ T242] ? rtnl_newlink+0x9a8/0x11c0 [ 11.376884][ T242] ? rcu_is_watching+0x16/0xd0 [ 11.376886][ T242] ? lock_acquire+0x13c/0x160 [ 11.376887][ T242] ? rcu_is_watching+0x16/0xd0 [ 11.376889][ T242] ? rcu_is_watching+0x16/0xd0 [ 11.376892][ T242] ? trace_contention_end+0xb3/0x180 [ 11.376895][ T242] ? __mutex_lock+0x1db/0x1ea0 [ 11.376897][ T242] ? __mutex_lock+0x9a3/0x1ea0 [ 11.376899][ T242] ? rtnl_newlink+0x9a8/0x11c0 [ 11.376903][ T242] ? ww_mutex_lock+0x160/0x160 [ 11.376904][ T242] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.376909][ T242] ? __rtnl_newlink+0x3fa/0xa50 [ 11.376914][ T242] rtnl_newlink+0x9f1/0x11c0 [ 11.376920][ T242] ? rtnl_bridge_getlink+0x850/0x850 [ 11.376922][ T242] ? __lock_acquire+0x518/0xc20 [ 11.376926][ T242] ? lock_acquire.part.0+0xd4/0x280 [ 11.376928][ T242] ? find_held_lock+0x2b/0x80 [ 11.376930][ T242] ? rtnl_bridge_getlink+0x850/0x850 [ 11.376932][ T242] ? __lock_release.isra.0+0x69/0x1a0 [ 11.376936][ T242] ? rtnl_bridge_getlink+0x850/0x850 [ 11.376938][ T242] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.376941][ T242] ? rtnl_link_fill+0x920/0x920 [ 11.376942][ T242] ? __lock_acquire+0x518/0xc20 [ 11.376947][ T242] ? lock_acquire.part.0+0xd4/0x280 [ 11.376948][ T242] ? find_held_lock+0x2b/0x80 [ 11.376952][ T242] netlink_rcv_skb+0x14e/0x3a0 [ 11.376954][ T242] ? rtnl_link_fill+0x920/0x920 [ 11.376956][ T242] ? netlink_ack+0xcf0/0xcf0 [ 11.376963][ T242] ? netlink_deliver_tap+0xc5/0x330 [ 11.376964][ T242] ? netlink_deliver_tap+0x13c/0x330 [ 11.376968][ T242] netlink_unicast+0x486/0x750 [ 11.376972][ T242] ? netlink_attachskb+0x810/0x810 [ 11.376975][ T242] ? __lock_acquire+0x518/0xc20 [ 11.376979][ T242] netlink_sendmsg+0x735/0xc60 [ 11.376983][ T242] ? netlink_unicast+0x750/0x750 [ 11.376987][ T242] ? __might_fault+0x97/0x140 [ 11.376992][ T242] ____sys_sendmsg+0x415/0x880 [ 11.376994][ T242] ? copy_msghdr_from_user+0x279/0x420 [ 11.376996][ T242] ? get_timestamp.constprop.0+0x390/0x390 [ 11.376998][ T242] ? move_addr_to_kernel+0x40/0x40 [ 11.377005][ T242] ___sys_sendmsg+0x14e/0x1d0 [ 11.377008][ T242] ? copy_msghdr_from_user+0x420/0x420 [ 11.377024][ T242] __sys_sendmsg+0x12c/0x1d0 [ 11.377027][ T242] ? __sys_sendmsg_sock+0x20/0x20 [ 11.377033][ T242] ? rcu_is_watching+0x16/0xd0 [ 11.377037][ T242] do_syscall_64+0xff/0x530 [ 11.377039][ T242] ? exc_page_fault+0xee/0x100 [ 11.377042][ T242] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.377044][ T242] RIP: 0033:0x7f74de3b354e [ 11.377047][ T242] 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.377049][ T242] RSP: 002b:00007ffc8b5e4a20 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.377052][ T242] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f74de3b354e [ 11.377053][ T242] RDX: 0000000000000000 RSI: 00007ffc8b5e4ad0 RDI: 0000000000000005 [ 11.377054][ T242] RBP: 00007ffc8b5e4a30 R08: 0000000000000000 R09: 0000000000000000 [ 11.377055][ T242] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a912ec6 [ 11.377056][ T242] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffc8b5e5190 [ 11.377063][ T242] [ 11.414254][ T242] br1: port 1(veth1) entered disabled state [ 11.414578][ T242] veth1: entered allmulticast mode [ 11.416141][ T242] veth1: entered promiscuous mode [ 11.423063][ T242] ip (242) used greatest stack depth: 23336 bytes left [ 11.448042][ T39] br1: port 1(veth1) entered blocking state [ 11.448511][ T39] br1: port 1(veth1) entered forwarding state [ 11.477750][ T244] br1: port 2(veth2) entered blocking state [ 11.478102][ T244] br1: port 2(veth2) entered disabled state [ 11.478766][ T244] veth2: entered allmulticast mode [ 11.480407][ T244] veth2: entered promiscuous mode [ 11.523026][ T39] br1: port 2(veth2) entered blocking state [ 11.523349][ T39] br1: port 2(veth2) entered forwarding state [ 26.473846][ T624] br1: port 2(veth2) entered disabled state [ 26.473906][ T624] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 26.473908][ T624] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 624, name: ip [ 26.473910][ T624] preempt_count: 202, expected: 0 [ 26.473911][ T624] RCU nest depth: 0, expected: 0 [ 26.473912][ T624] locks held by ip/624: 6, last CPU#2: [ 26.473915][ T624] #0: ffffffffb9ed2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 26.473927][ T624] #1: ff1100000b712e58 (&br->lock){+...}-{3:3}, at: br_device_event+0x1d7/0x8f0 [bridge] [ 26.473942][ T624] #2: ffffffffb9769cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 26.473948][ T624] #3: ffffffffb9769d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 26.473952][ T624] #4: ffffffffb9689660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 26.473956][ T624] #5: ffffffffb9689560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 26.473960][ T624] irq event stamp: 15575 [ 26.473961][ T624] hardirqs last enabled at (15574): [] __down_trylock_console_sem+0x86/0xa0 [ 26.473964][ T624] hardirqs last disabled at (15575): [] console_emit_next_record+0x3d4/0x4c0 [ 26.473966][ T624] softirqs last enabled at (15558): [] ipv6_mc_down+0x134/0x2d0 [ 26.473970][ T624] softirqs last disabled at (15570): [] br_device_event+0x1d7/0x8f0 [bridge] [ 26.473978][ T624] Preemption disabled at: [ 26.473979][ T624] [<0000000000000000>] 0x0 [ 26.473985][ T624] CPU: 2 UID: 0 PID: 624 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.473989][ T624] Tainted: [W]=WARN [ 26.473990][ T624] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.473992][ T624] Call Trace: [ 26.473993][ T624] [ 26.473995][ T624] dump_stack_lvl+0x6f/0xa0 [ 26.474002][ T624] __might_resched.cold+0x1fe/0x2c1 [ 26.474007][ T624] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 26.474011][ T624] ? __kmalloc_noprof+0xdb/0x760 [ 26.474017][ T624] __kmalloc_noprof+0x443/0x760 [ 26.474019][ T624] ? alloc_buf.isra.0+0x4b/0x260 [ 26.474025][ T624] ? do_raw_spin_unlock+0x59/0x250 [ 26.474028][ T624] alloc_buf.isra.0+0x4b/0x260 [ 26.474031][ T624] put_chars+0x1e1/0x2f0 [ 26.474034][ T624] ? __send_to_port+0x420/0x420 [ 26.474035][ T624] ? check_prev_add+0xef/0xe90 [ 26.474039][ T624] ? validate_chain+0x34a/0xc20 [ 26.474043][ T624] hvc_console_print+0x292/0x780 [ 26.474046][ T624] ? mark_usage+0x61/0x170 [ 26.474048][ T624] ? __lock_acquire+0x518/0xc20 [ 26.474049][ T624] ? __lock_acquire+0x518/0xc20 [ 26.474054][ T624] ? hvc_write+0x3a0/0x3a0 [ 26.474055][ T624] ? lock_acquire.part.0+0xd4/0x280 [ 26.474059][ T624] ? lock_acquire+0x13c/0x160 [ 26.474063][ T624] console_emit_next_record+0x22f/0x4c0 [ 26.474067][ T624] ? devkmsg_read+0x4b0/0x4b0 [ 26.474069][ T624] ? console_flush_one_record+0x106/0x710 [ 26.474073][ T624] ? rcu_is_watching+0x16/0xd0 [ 26.474077][ T624] ? lock_acquire+0x13c/0x160 [ 26.474081][ T624] console_flush_one_record+0x46f/0x710 [ 26.474085][ T624] ? console_emit_next_record+0x4c0/0x4c0 [ 26.474087][ T624] ? __lock_acquire+0x518/0xc20 [ 26.474092][ T624] console_unlock+0xee/0x1f0 [ 26.474095][ T624] ? console_flush_one_record+0x710/0x710 [ 26.474097][ T624] ? rcu_is_watching+0x16/0xd0 [ 26.474099][ T624] ? lock_acquire+0x60/0x160 [ 26.474103][ T624] ? __down_trylock_console_sem+0x5e/0xa0 [ 26.474105][ T624] ? vprintk_emit+0x320/0x3e0 [ 26.474108][ T624] vprintk_emit+0x37c/0x3e0 [ 26.474112][ T624] ? wake_up_klogd_work_func+0x90/0x90 [ 26.474114][ T624] ? mark_held_locks+0x40/0x70 [ 26.474117][ T624] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 26.474120][ T624] ? rcu_read_lock_any_held+0x3c/0x90 [ 26.474124][ T624] _printk+0xc7/0x100 [ 26.474128][ T624] ? snapshot_read.cold+0x21/0x21 [ 26.474136][ T624] br_set_state+0x22f/0x430 [bridge] [ 26.474147][ T624] br_stp_disable_port+0x11f/0x390 [bridge] [ 26.474159][ T624] br_device_event+0x7b5/0x8f0 [bridge] [ 26.474167][ T624] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 26.474171][ T624] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 26.474178][ T624] notifier_call_chain+0xae/0x300 [ 26.474184][ T624] __dev_notify_flags+0x23c/0x3c0 [ 26.474189][ T624] ? netif_change_name+0x8c0/0x8c0 [ 26.474192][ T624] ? netif_set_allmulti+0x250/0x250 [ 26.474195][ T624] ? __kernel_text_address+0x12/0x30 [ 26.474198][ T624] ? write_profile+0xf0/0xf0 [ 26.474200][ T624] ? unwind_get_return_address+0x67/0xd0 [ 26.474205][ T624] netif_change_flags+0xfe/0x190 [ 26.474209][ T624] do_setlink.isra.0+0x1cc3/0x2750 [ 26.474213][ T624] ? stack_trace_save+0x93/0xc0 [ 26.474215][ T624] ? rtnl_link_get_size+0x350/0x350 [ 26.474217][ T624] ? rcu_read_lock_any_held+0x66/0x90 [ 26.474219][ T624] ? stack_depot_save_flags+0x38e/0x790 [ 26.474222][ T624] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 26.474225][ T624] ? rcu_read_lock_any_held+0x3c/0x90 [ 26.474227][ T624] ? validate_chain+0x38b/0xc20 [ 26.474230][ T624] ? kasan_save_stack+0x3d/0x50 [ 26.474233][ T624] ? kasan_save_stack+0x2f/0x50 [ 26.474235][ T624] ? kasan_save_track+0x14/0x30 [ 26.474238][ T624] ? __lock_acquire+0x518/0xc20 [ 26.474239][ T624] ? netlink_seq_next+0x4e/0x60 [ 26.474242][ T624] ? ___sys_sendmsg+0xb0/0x1d0 [ 26.474247][ T624] ? lock_acquire.part.0+0xd4/0x280 [ 26.474249][ T624] ? rtnl_newlink+0x9a8/0x11c0 [ 26.474252][ T624] ? rcu_is_watching+0x16/0xd0 [ 26.474254][ T624] ? lock_acquire+0x13c/0x160 [ 26.474256][ T624] ? rcu_is_watching+0x16/0xd0 [ 26.474258][ T624] ? rcu_is_watching+0x16/0xd0 [ 26.474260][ T624] ? trace_contention_end+0xb3/0x180 [ 26.474264][ T624] ? __mutex_lock+0x1db/0x1ea0 [ 26.474266][ T624] ? __mutex_lock+0x9a3/0x1ea0 [ 26.474268][ T624] ? rtnl_newlink+0x9a8/0x11c0 [ 26.474272][ T624] ? ww_mutex_lock+0x160/0x160 [ 26.474277][ T624] ? __rtnl_newlink+0x3fa/0xa50 [ 26.474282][ T624] rtnl_newlink+0x9f1/0x11c0 [ 26.474288][ T624] ? rtnl_bridge_getlink+0x850/0x850 [ 26.474290][ T624] ? __lock_acquire+0x518/0xc20 [ 26.474295][ T624] ? lock_acquire.part.0+0xd4/0x280 [ 26.474297][ T624] ? find_held_lock+0x2b/0x80 [ 26.474299][ T624] ? rtnl_bridge_getlink+0x850/0x850 [ 26.474301][ T624] ? __lock_release.isra.0+0x69/0x1a0 [ 26.474305][ T624] ? rtnl_bridge_getlink+0x850/0x850 [ 26.474307][ T624] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.474310][ T624] ? rtnl_link_fill+0x920/0x920 [ 26.474311][ T624] ? __lock_acquire+0x518/0xc20 [ 26.474316][ T624] ? lock_acquire.part.0+0xd4/0x280 [ 26.474318][ T624] ? find_held_lock+0x2b/0x80 [ 26.474321][ T624] netlink_rcv_skb+0x14e/0x3a0 [ 26.474323][ T624] ? rtnl_link_fill+0x920/0x920 [ 26.474326][ T624] ? netlink_ack+0xcf0/0xcf0 [ 26.474333][ T624] ? netlink_deliver_tap+0xc5/0x330 [ 26.474334][ T624] ? netlink_deliver_tap+0x13c/0x330 [ 26.474339][ T624] netlink_unicast+0x486/0x750 [ 26.474342][ T624] ? netlink_attachskb+0x810/0x810 [ 26.474345][ T624] ? __lock_acquire+0x518/0xc20 [ 26.474349][ T624] netlink_sendmsg+0x735/0xc60 [ 26.474353][ T624] ? netlink_unicast+0x750/0x750 [ 26.474357][ T624] ? __might_fault+0x97/0x140 [ 26.474363][ T624] ____sys_sendmsg+0x415/0x880 [ 26.474365][ T624] ? copy_msghdr_from_user+0x279/0x420 [ 26.474368][ T624] ? get_timestamp.constprop.0+0x390/0x390 [ 26.474370][ T624] ? move_addr_to_kernel+0x40/0x40 [ 26.474377][ T624] ___sys_sendmsg+0x14e/0x1d0 [ 26.474380][ T624] ? copy_msghdr_from_user+0x420/0x420 [ 26.474396][ T624] __sys_sendmsg+0x12c/0x1d0 [ 26.474399][ T624] ? __sys_sendmsg_sock+0x20/0x20 [ 26.474405][ T624] ? rcu_is_watching+0x16/0xd0 [ 26.474409][ T624] do_syscall_64+0xff/0x530 [ 26.474411][ T624] ? exc_page_fault+0xee/0x100 [ 26.474415][ T624] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.474417][ T624] RIP: 0033:0x7fa326a9b54e [ 26.474419][ T624] 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 [ 26.474421][ T624] RSP: 002b:00007ffc2bb797a0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 26.474424][ T624] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007fa326a9b54e [ 26.474425][ T624] RDX: 0000000000000000 RSI: 00007ffc2bb79850 RDI: 0000000000000005 [ 26.474426][ T624] RBP: 00007ffc2bb797b0 R08: 0000000000000000 R09: 0000000000000000 [ 26.474427][ T624] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a912ed5 [ 26.474428][ T624] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffc2bb79f10 [ 26.474435][ T624] [ 26.490196][ C3] [ 26.502779][ T624] ip (624) used greatest stack depth: 23016 bytes left [ 26.502907][ C3] ======================================================== [ 26.502909][ C3] WARNING: possible irq lock inversion dependency detected [ 26.502911][ C3] 7.2.0-virtme #1 Tainted: G W [ 26.511910][ C3] -------------------------------------------------------- [ 26.512155][ C3] swapper/3/0 just changed the state of lock: [ 26.512366][ C3] ff1100000b712e58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.512699][ C3] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 26.512906][ C3] (fs_reclaim){+.+.}-{0:0} [ 26.512908][ C3] [ 26.512908][ C3] [ 26.512908][ C3] and interrupts could create inverse lock ordering between them. [ 26.512908][ C3] [ 26.513498][ C3] [ 26.513498][ C3] other info that might help us debug this: [ 26.513748][ C3] Chain exists of: [ 26.513748][ C3] &br->lock --> console_owner --> fs_reclaim [ 26.513748][ C3] [ 26.514104][ C3] Possible interrupt unsafe locking scenario: [ 26.514104][ C3] [ 26.514355][ C3] CPU0 CPU1 [ 26.514494][ C3] ---- ---- [ 26.514684][ C3] lock(fs_reclaim); [ 26.514791][ C3] local_irq_disable(); [ 26.515011][ C3] lock(&br->lock); [ 26.515183][ C3] lock(console_owner); [ 26.515355][ C3] [ 26.515507][ C3] lock(&br->lock); [ 26.515612][ C3] [ 26.515612][ C3] *** DEADLOCK *** [ 26.515612][ C3] [ 26.515864][ C3] locks held by swapper/3/0: 1, last CPU#3: [ 26.516033][ C3] #0: ffa0000000280c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 26.516354][ C3] [ 26.516354][ C3] the shortest dependencies between 2nd lock and 1st lock: [ 26.516637][ C3] -> (fs_reclaim){+.+.}-{0:0} { [ 26.516828][ C3] HARDIRQ-ON-W at: [ 26.516934][ C3] __lock_acquire+0x388/0xc20 [ 26.517161][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.517332][ C3] fs_reclaim_acquire+0xd5/0x120 [ 26.517553][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 26.517804][ C3] kthread_create_worker_on_node+0xea/0x210 [ 26.518017][ C3] workqueue_init+0x2a/0x680 [ 26.518238][ C3] kernel_init_freeable+0x2fe/0x630 [ 26.518495][ C3] kernel_init+0x21/0x150 [ 26.518669][ C3] ret_from_fork+0x474/0x6b0 [ 26.518889][ C3] ret_from_fork_asm+0x11/0x20 [ 26.519108][ C3] SOFTIRQ-ON-W at: [ 26.519213][ C3] __lock_acquire+0x388/0xc20 [ 26.519432][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.519604][ C3] fs_reclaim_acquire+0xd5/0x120 [ 26.519825][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 26.520076][ C3] kthread_create_worker_on_node+0xea/0x210 [ 26.520283][ C3] workqueue_init+0x2a/0x680 [ 26.520508][ C3] kernel_init_freeable+0x2fe/0x630 [ 26.520758][ C3] kernel_init+0x21/0x150 [ 26.520933][ C3] ret_from_fork+0x474/0x6b0 [ 26.521149][ C3] ret_from_fork_asm+0x11/0x20 [ 26.521370][ C3] INITIAL USE at: [ 26.521472][ C3] __lock_acquire+0x388/0xc20 [ 26.521691][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.521865][ C3] fs_reclaim_acquire+0xd5/0x120 [ 26.522083][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 26.522338][ C3] kthread_create_worker_on_node+0xea/0x210 [ 26.522542][ C3] workqueue_init+0x2a/0x680 [ 26.522759][ C3] kernel_init_freeable+0x2fe/0x630 [ 26.522981][ C3] kernel_init+0x21/0x150 [ 26.523151][ C3] ret_from_fork+0x474/0x6b0 [ 26.523367][ C3] ret_from_fork_asm+0x11/0x20 [ 26.523590][ C3] } [ 26.523662][ C3] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 26.523916][ C3] ... acquired at: [ 26.524019][ C3] __lock_acquire+0x518/0xc20 [ 26.524157][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.524338][ C3] fs_reclaim_acquire+0xd5/0x120 [ 26.524475][ C3] __kmalloc_noprof+0xd3/0x760 [ 26.524656][ C3] alloc_buf.isra.0+0x4b/0x260 [ 26.524792][ C3] put_chars+0x1e1/0x2f0 [ 26.524978][ C3] hvc_console_print+0x292/0x780 [ 26.525112][ C3] console_emit_next_record+0x22f/0x4c0 [ 26.525292][ C3] console_flush_one_record+0x46f/0x710 [ 26.525428][ C3] console_unlock+0xee/0x1f0 [ 26.525613][ C3] vprintk_emit+0x37c/0x3e0 [ 26.525747][ C3] _printk+0xc7/0x100 [ 26.525900][ C3] dm_interface_init+0x50/0x60 [ 26.526038][ C3] dm_init+0x51/0xd0 [ 26.526187][ C3] do_one_initcall+0x124/0x4f0 [ 26.526322][ C3] kernel_init_freeable+0x596/0x630 [ 26.526505][ C3] kernel_init+0x21/0x150 [ 26.526639][ C3] ret_from_fork+0x474/0x6b0 [ 26.526826][ C3] ret_from_fork_asm+0x11/0x20 [ 26.526963][ C3] [ 26.527036][ C3] -> (console_owner){....}-{0:0} { [ 26.527221][ C3] INITIAL USE at: [ 26.527324][ C3] } [ 26.527440][ C3] ... key at: [] console_owner_dep_map+0x0/0x60 [ 26.527640][ C3] ... acquired at: [ 26.527785][ C3] __lock_acquire+0x518/0xc20 [ 26.527926][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.528110][ C3] console_lock_spinning_enable+0x5c/0x60 [ 26.528279][ C3] console_emit_next_record+0x1d1/0x4c0 [ 26.528459][ C3] console_flush_one_record+0x46f/0x710 [ 26.528594][ C3] console_unlock+0xee/0x1f0 [ 26.528778][ C3] vprintk_emit+0x37c/0x3e0 [ 26.528917][ C3] _printk+0xc7/0x100 [ 26.529065][ C3] br_set_state+0x22f/0x430 [bridge] [ 26.529206][ C3] br_init_port+0xc4/0x200 [bridge] [ 26.529395][ C3] br_stp_enable_port+0x12/0x50 [bridge] [ 26.529570][ C3] br_port_carrier_check+0x220/0x430 [bridge] [ 26.529793][ C3] br_device_event+0x52d/0x8f0 [bridge] [ 26.529939][ C3] notifier_call_chain+0xae/0x300 [ 26.530122][ C3] netif_state_change+0x139/0x340 [ 26.530258][ C3] __linkwatch_run_queue+0x34c/0x750 [ 26.530438][ C3] linkwatch_event+0x7f/0xb0 [ 26.530572][ C3] process_one_work+0xe3e/0x1560 [ 26.530757][ C3] worker_thread+0x4f1/0xd60 [ 26.530896][ C3] kthread+0x367/0x460 [ 26.531042][ C3] ret_from_fork+0x474/0x6b0 [ 26.531175][ C3] ret_from_fork_asm+0x11/0x20 [ 26.531359][ C3] [ 26.531427][ C3] -> (&br->lock){+.-.}-{3:3} { [ 26.531615][ C3] HARDIRQ-ON-W at: [ 26.531717][ C3] __lock_acquire+0x388/0xc20 [ 26.531943][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.532113][ C3] _raw_spin_lock_bh+0x38/0x50 [ 26.532332][ C3] recalculate_group_addr+0x51/0x120 [bridge] [ 26.532596][ C3] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 26.532806][ C3] br_changelink+0x575/0x16e0 [bridge] [ 26.533036][ C3] br_dev_newlink+0xeb/0x160 [bridge] [ 26.533258][ C3] rtnl_newlink_create+0x2d0/0x750 [ 26.533427][ C3] __rtnl_newlink+0x22b/0xa50 [ 26.533649][ C3] rtnl_newlink+0x9f1/0x11c0 [ 26.533818][ C3] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.534040][ C3] netlink_rcv_skb+0x14e/0x3a0 [ 26.534213][ C3] netlink_unicast+0x486/0x750 [ 26.534384][ C3] netlink_sendmsg+0x735/0xc60 [ 26.534606][ C3] ____sys_sendmsg+0x415/0x880 [ 26.534830][ C3] ___sys_sendmsg+0x14e/0x1d0 [ 26.535003][ C3] __sys_sendmsg+0x12c/0x1d0 [ 26.535224][ C3] do_syscall_64+0xff/0x530 [ 26.535394][ C3] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.535649][ C3] IN-SOFTIRQ-W at: [ 26.535799][ C3] __lock_acquire+0x388/0xc20 [ 26.535977][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.536192][ C3] _raw_spin_lock+0x33/0x40 [ 26.536365][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.536627][ C3] call_timer_fn+0x160/0x4d0 [ 26.536849][ C3] __run_timers+0x68f/0xaa0 [ 26.537020][ C3] run_timer_softirq+0xf0/0x160 [ 26.537241][ C3] handle_softirqs+0x1d3/0x900 [ 26.537457][ C3] __irq_exit_rcu+0x145/0x1c0 [ 26.537628][ C3] irq_exit_rcu+0xe/0x30 [ 26.537854][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.538102][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.538304][ C3] pv_native_safe_halt+0xf/0x10 [ 26.538522][ C3] default_idle+0x9/0x10 [ 26.538736][ C3] default_idle_call+0x6e/0xb0 [ 26.538911][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.539160][ C3] do_idle+0xd8/0x190 [ 26.539341][ C3] cpu_startup_entry+0x53/0x70 [ 26.539517][ C3] start_secondary+0x204/0x2b0 [ 26.539737][ C3] common_startup_64+0x13e/0x148 [ 26.539913][ C3] INITIAL USE at: [ 26.540061][ C3] __lock_acquire+0x388/0xc20 [ 26.540229][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.540443][ C3] _raw_spin_lock_bh+0x38/0x50 [ 26.540659][ C3] recalculate_group_addr+0x51/0x120 [bridge] [ 26.540873][ C3] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 26.541129][ C3] br_changelink+0x575/0x16e0 [bridge] [ 26.541349][ C3] br_dev_newlink+0xeb/0x160 [bridge] [ 26.541524][ C3] rtnl_newlink_create+0x2d0/0x750 [ 26.541745][ C3] __rtnl_newlink+0x22b/0xa50 [ 26.541964][ C3] rtnl_newlink+0x9f1/0x11c0 [ 26.542135][ C3] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.542351][ C3] netlink_rcv_skb+0x14e/0x3a0 [ 26.542569][ C3] netlink_unicast+0x486/0x750 [ 26.542738][ C3] netlink_sendmsg+0x735/0xc60 [ 26.542964][ C3] ____sys_sendmsg+0x415/0x880 [ 26.543134][ C3] ___sys_sendmsg+0x14e/0x1d0 [ 26.543351][ C3] __sys_sendmsg+0x12c/0x1d0 [ 26.543572][ C3] do_syscall_64+0xff/0x530 [ 26.543742][ C3] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.543994][ C3] } [ 26.544064][ C3] ... key at: [] __key.7+0x0/0x40 [bridge] [ 26.544313][ C3] ... acquired at: [ 26.544413][ C3] mark_lock+0x1d7/0xa00 [ 26.544597][ C3] mark_usage+0x42/0x170 [ 26.544730][ C3] __lock_acquire+0x388/0xc20 [ 26.544915][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.545048][ C3] _raw_spin_lock+0x33/0x40 [ 26.545234][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.545453][ C3] call_timer_fn+0x160/0x4d0 [ 26.545591][ C3] __run_timers+0x68f/0xaa0 [ 26.545769][ C3] run_timer_softirq+0xf0/0x160 [ 26.545907][ C3] handle_softirqs+0x1d3/0x900 [ 26.546086][ C3] __irq_exit_rcu+0x145/0x1c0 [ 26.546219][ C3] irq_exit_rcu+0xe/0x30 [ 26.546395][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.546565][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.546778][ C3] pv_native_safe_halt+0xf/0x10 [ 26.546918][ C3] default_idle+0x9/0x10 [ 26.547098][ C3] default_idle_call+0x6e/0xb0 [ 26.547233][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.547448][ C3] do_idle+0xd8/0x190 [ 26.547552][ C3] cpu_startup_entry+0x53/0x70 [ 26.547732][ C3] start_secondary+0x204/0x2b0 [ 26.547873][ C3] common_startup_64+0x13e/0x148 [ 26.548053][ C3] [ 26.548119][ C3] [ 26.548119][ C3] stack backtrace: [ 26.548330][ C3] CPU: 3 UID: 0 PID: 0 Comm: swapper/3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.548334][ C3] Tainted: [W]=WARN [ 26.548335][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.548336][ C3] Call Trace: [ 26.548338][ C3] [ 26.548339][ C3] dump_stack_lvl+0x6f/0xa0 [ 26.548344][ C3] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 26.548347][ C3] mark_lock_irq+0x989/0x9c0 [ 26.548350][ C3] mark_lock+0x1d7/0xa00 [ 26.548352][ C3] mark_usage+0x42/0x170 [ 26.548353][ C3] __lock_acquire+0x388/0xc20 [ 26.548356][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.548357][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.548365][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.548368][ C3] ? lock_acquire+0x13c/0x160 [ 26.548370][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.548377][ C3] _raw_spin_lock+0x33/0x40 [ 26.548379][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.548385][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.548392][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.548399][ C3] call_timer_fn+0x160/0x4d0 [ 26.548401][ C3] ? detach_if_pending+0x1d0/0x1d0 [ 26.548403][ C3] ? debug_object_active_state+0x430/0x430 [ 26.548406][ C3] ? find_held_lock+0x2b/0x80 [ 26.548408][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 26.548410][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.548413][ C3] __run_timers+0x68f/0xaa0 [ 26.548414][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.548421][ C3] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.548423][ C3] ? __lock_acquire+0x518/0xc20 [ 26.548426][ C3] ? __rwlock_init+0x150/0x150 [ 26.548428][ C3] run_timer_softirq+0xf0/0x160 [ 26.548430][ C3] ? __run_timers+0xaa0/0xaa0 [ 26.548432][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.548435][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.548436][ C3] handle_softirqs+0x1d3/0x900 [ 26.548438][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 26.548440][ C3] ? _local_bh_enable+0xc0/0xc0 [ 26.548442][ C3] __irq_exit_rcu+0x145/0x1c0 [ 26.548443][ C3] irq_exit_rcu+0xe/0x30 [ 26.548445][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.548447][ C3] [ 26.548447][ C3] [ 26.548448][ C3] ? lockdep_hardirqs_on+0x91/0x130 [ 26.548450][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.548451][ C3] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.548454][ 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.548456][ C3] RSP: 0018:ffa0000000167e00 EFLAGS: 00000282 [ 26.548458][ C3] RAX: 00000000000e8b2f RBX: ff11000001c88040 RCX: ffffffffb64f0307 [ 26.548460][ C3] RDX: ff11000001c88040 RSI: ffffffffb9238b01 RDI: ffffffffb8c8d8e0 [ 26.548460][ C3] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.548462][ C3] R10: 0000000000000003 R11: 0000000000000001 R12: 1ff400000002cfc3 [ 26.548462][ C3] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 26.548465][ C3] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.548467][ C3] default_idle+0x9/0x10 [ 26.548469][ C3] default_idle_call+0x6e/0xb0 [ 26.548471][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.548473][ C3] ? arch_cpu_idle_exit+0x40/0x40 [ 26.548474][ C3] ? mark_tsc_async_resets+0x30/0x30 [ 26.548476][ C3] ? default_idle_call+0x98/0xb0 [ 26.548478][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.548480][ C3] do_idle+0xd8/0x190 [ 26.548481][ C3] cpu_startup_entry+0x53/0x70 [ 26.548483][ C3] start_secondary+0x204/0x2b0 [ 26.548484][ C3] ? set_cpu_sibling_map+0x2130/0x2130 [ 26.548486][ C3] common_startup_64+0x13e/0x148 [ 26.548489][ C3] [ 26.585538][ T625] veth2: left allmulticast mode [ 26.585708][ T625] veth2: left promiscuous mode [ 26.585927][ T625] br1: port 2(veth2) entered disabled state [ 26.602725][ T626] br1: port 1(veth1) entered disabled state [ 26.619960][ T627] veth1: left allmulticast mode [ 26.620169][ T627] veth1: left promiscuous mode [ 26.620430][ T627] br1: port 1(veth1) entered disabled state