[ 12.580299][ T269] br1: port 1(vx1) entered blocking state [ 12.580445][ T269] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.580447][ T269] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 269, name: ip [ 12.580448][ T269] preempt_count: 1, expected: 0 [ 12.580449][ T269] RCU nest depth: 0, expected: 0 [ 12.580450][ T269] locks held by ip/269: 5, last CPU#2: [ 12.580453][ T269] #0: ffffffff89ad2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 12.580464][ T269] #1: ffffffff89369cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.580471][ T269] #2: ffffffff89369d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.580474][ T269] #3: ffffffff89289660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.580478][ T269] #4: ffffffff89289560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 12.580482][ T269] irq event stamp: 13018 [ 12.580483][ T269] hardirqs last enabled at (13017): [] __down_trylock_console_sem+0x86/0xa0 [ 12.580485][ T269] hardirqs last disabled at (13018): [] console_emit_next_record+0x3d4/0x4c0 [ 12.580487][ T269] softirqs last enabled at (12928): [] __alloc_skb+0x4c2/0x5f0 [ 12.580490][ T269] softirqs last disabled at (12926): [] __alloc_skb+0x4c2/0x5f0 [ 12.580493][ T269] Preemption disabled at: [ 12.580493][ T269] [] vprintk_emit+0x31b/0x3e0 [ 12.580499][ T269] CPU: 2 UID: 0 PID: 269 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.580502][ T269] Tainted: [W]=WARN [ 12.580503][ T269] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.580505][ T269] Call Trace: [ 12.580506][ T269] [ 12.580508][ T269] dump_stack_lvl+0x6f/0xa0 [ 12.580514][ T269] ? vprintk_emit+0x31b/0x3e0 [ 12.580516][ T269] __might_resched.cold+0x1fe/0x2c1 [ 12.580520][ T269] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.580525][ T269] ? __kmalloc_noprof+0xdb/0x760 [ 12.580530][ T269] __kmalloc_noprof+0x443/0x760 [ 12.580532][ T269] ? alloc_buf.isra.0+0x4b/0x260 [ 12.580537][ T269] ? do_raw_spin_unlock+0x59/0x250 [ 12.580540][ T269] alloc_buf.isra.0+0x4b/0x260 [ 12.580543][ T269] put_chars+0x1e1/0x2f0 [ 12.580546][ T269] ? __send_to_port+0x420/0x420 [ 12.580550][ T269] ? validate_chain+0x34a/0xc20 [ 12.580554][ T269] hvc_console_print+0x292/0x780 [ 12.580556][ T269] ? mark_usage+0x61/0x170 [ 12.580558][ T269] ? __lock_acquire+0x518/0xc20 [ 12.580560][ T269] ? __lock_acquire+0x518/0xc20 [ 12.580564][ T269] ? hvc_write+0x3a0/0x3a0 [ 12.580566][ T269] ? console_emit_next_record+0x1df/0x4c0 [ 12.580569][ T269] ? rcu_is_watching+0x16/0xd0 [ 12.580573][ T269] ? lock_acquire+0x13c/0x160 [ 12.580577][ T269] console_emit_next_record+0x22f/0x4c0 [ 12.580580][ T269] ? devkmsg_read+0x4b0/0x4b0 [ 12.580582][ T269] ? console_flush_one_record+0x106/0x710 [ 12.580585][ T269] ? rcu_is_watching+0x16/0xd0 [ 12.580588][ T269] ? lock_acquire+0x13c/0x160 [ 12.580592][ T269] console_flush_one_record+0x46f/0x710 [ 12.580596][ T269] ? console_emit_next_record+0x4c0/0x4c0 [ 12.580597][ T269] ? __lock_acquire+0x518/0xc20 [ 12.580602][ T269] console_unlock+0xee/0x1f0 [ 12.580605][ T269] ? console_flush_one_record+0x710/0x710 [ 12.580607][ T269] ? rcu_is_watching+0x16/0xd0 [ 12.580609][ T269] ? lock_acquire+0x60/0x160 [ 12.580613][ T269] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.580615][ T269] ? vprintk_emit+0x320/0x3e0 [ 12.580618][ T269] vprintk_emit+0x37c/0x3e0 [ 12.580622][ T269] ? wake_up_klogd_work_func+0x90/0x90 [ 12.580624][ T269] ? __lock_release.isra.0+0x69/0x1a0 [ 12.580626][ T269] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 12.580630][ T269] ? mark_held_locks+0x40/0x70 [ 12.580634][ T269] _printk+0xc7/0x100 [ 12.580637][ T269] ? snapshot_read.cold+0x21/0x21 [ 12.580641][ T269] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 12.580654][ T269] ? do_setlink.isra.0+0xa31/0x2750 [ 12.580655][ T269] ? rtnl_newlink+0x9f1/0x11c0 [ 12.580657][ T269] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 12.580661][ T269] br_set_state+0x22f/0x430 [bridge] [ 12.580671][ T269] br_init_port+0xc4/0x200 [bridge] [ 12.580679][ T269] new_nbp+0x39c/0x580 [bridge] [ 12.580688][ T269] br_add_if+0x212/0x1320 [bridge] [ 12.580695][ T269] ? is_bpf_text_address+0x72/0x110 [ 12.580699][ T269] ? kernel_text_address+0x149/0x170 [ 12.580702][ T269] ? __kernel_text_address+0x12/0x30 [ 12.580706][ T269] do_set_master+0x357/0x580 [ 12.580710][ T269] do_setlink.isra.0+0xa31/0x2750 [ 12.580713][ T269] ? stack_trace_save+0x93/0xc0 [ 12.580716][ T269] ? rtnl_link_get_size+0x350/0x350 [ 12.580718][ T269] ? rcu_read_lock_any_held+0x66/0x90 [ 12.580720][ T269] ? stack_depot_save_flags+0x38e/0x790 [ 12.580723][ T269] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 12.580726][ T269] ? rcu_read_lock_any_held+0x3c/0x90 [ 12.580728][ T269] ? validate_chain+0x38b/0xc20 [ 12.580730][ T269] ? kasan_save_stack+0x3d/0x50 [ 12.580733][ T269] ? kasan_save_stack+0x2f/0x50 [ 12.580735][ T269] ? kasan_save_track+0x14/0x30 [ 12.580738][ T269] ? __lock_acquire+0x518/0xc20 [ 12.580739][ T269] ? netlink_seq_next+0x4e/0x60 [ 12.580742][ T269] ? ___sys_sendmsg+0xb0/0x1d0 [ 12.580747][ T269] ? lock_acquire.part.0+0xd4/0x280 [ 12.580749][ T269] ? rtnl_newlink+0x9a8/0x11c0 [ 12.580751][ T269] ? rcu_is_watching+0x16/0xd0 [ 12.580753][ T269] ? lock_acquire+0x13c/0x160 [ 12.580755][ T269] ? rcu_is_watching+0x16/0xd0 [ 12.580757][ T269] ? rcu_is_watching+0x16/0xd0 [ 12.580759][ T269] ? trace_contention_end+0xb3/0x180 [ 12.580763][ T269] ? __mutex_lock+0x1db/0x1ea0 [ 12.580764][ T269] ? __mutex_lock+0x9a3/0x1ea0 [ 12.580766][ T269] ? rtnl_newlink+0x9a8/0x11c0 [ 12.580769][ T269] ? ww_mutex_lock+0x160/0x160 [ 12.580771][ T269] ? nla_get_range_signed+0x3d0/0x3d0 [ 12.580776][ T269] ? __rtnl_newlink+0x3fa/0xa50 [ 12.580781][ T269] rtnl_newlink+0x9f1/0x11c0 [ 12.580786][ T269] ? rtnl_bridge_getlink+0x850/0x850 [ 12.580788][ T269] ? __lock_acquire+0x518/0xc20 [ 12.580792][ T269] ? lock_acquire.part.0+0xd4/0x280 [ 12.580794][ T269] ? find_held_lock+0x2b/0x80 [ 12.580796][ T269] ? rtnl_bridge_getlink+0x850/0x850 [ 12.580798][ T269] ? __lock_release.isra.0+0x69/0x1a0 [ 12.580802][ T269] ? rtnl_bridge_getlink+0x850/0x850 [ 12.580804][ T269] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 12.580807][ T269] ? rtnl_link_fill+0x920/0x920 [ 12.580808][ T269] ? __lock_acquire+0x518/0xc20 [ 12.580812][ T269] ? lock_acquire.part.0+0xd4/0x280 [ 12.580814][ T269] ? find_held_lock+0x2b/0x80 [ 12.580817][ T269] netlink_rcv_skb+0x14e/0x3a0 [ 12.580819][ T269] ? rtnl_link_fill+0x920/0x920 [ 12.580822][ T269] ? netlink_ack+0xcf0/0xcf0 [ 12.580828][ T269] ? netlink_deliver_tap+0xc5/0x330 [ 12.580829][ T269] ? netlink_deliver_tap+0x13c/0x330 [ 12.580833][ T269] netlink_unicast+0x486/0x750 [ 12.580837][ T269] ? netlink_attachskb+0x810/0x810 [ 12.580839][ T269] ? __lock_acquire+0x518/0xc20 [ 12.580843][ T269] netlink_sendmsg+0x735/0xc60 [ 12.580847][ T269] ? netlink_unicast+0x750/0x750 [ 12.580851][ T269] ? __might_fault+0x97/0x140 [ 12.580856][ T269] ____sys_sendmsg+0x415/0x880 [ 12.580858][ T269] ? copy_msghdr_from_user+0x279/0x420 [ 12.580860][ T269] ? get_timestamp.constprop.0+0x390/0x390 [ 12.580862][ T269] ? move_addr_to_kernel+0x40/0x40 [ 12.580869][ T269] ___sys_sendmsg+0x14e/0x1d0 [ 12.580872][ T269] ? copy_msghdr_from_user+0x420/0x420 [ 12.580887][ T269] __sys_sendmsg+0x12c/0x1d0 [ 12.580890][ T269] ? __sys_sendmsg_sock+0x20/0x20 [ 12.580896][ T269] ? rcu_is_watching+0x16/0xd0 [ 12.580899][ T269] do_syscall_64+0xff/0x530 [ 12.580902][ T269] ? exc_page_fault+0xee/0x100 [ 12.580905][ T269] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 12.580907][ T269] RIP: 0033:0x7fe42998154e [ 12.580910][ T269] 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 [ 12.580912][ T269] RSP: 002b:00007ffc76d708d0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 12.580915][ T269] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007fe42998154e [ 12.580916][ T269] RDX: 0000000000000000 RSI: 00007ffc76d70980 RDI: 0000000000000005 [ 12.580917][ T269] RBP: 00007ffc76d708e0 R08: 0000000000000000 R09: 0000000000000000 [ 12.580918][ T269] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a915f63 [ 12.580918][ T269] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffc76d71040 [ 12.580925][ T269] [ 12.628856][ T269] br1: port 1(vx1) entered disabled state [ 12.629281][ T269] vx1: entered allmulticast mode [ 12.630894][ T269] vx1: entered promiscuous mode [ 12.631952][ T269] br1: port 1(vx1) entered blocking state [ 12.632648][ T269] br1: port 1(vx1) entered forwarding state [ 12.647498][ T269] ip (269) used greatest stack depth: 23336 bytes left [ 12.676582][ T270] br1: port 2(veth1) entered blocking state [ 12.676902][ T270] br1: port 2(veth1) entered disabled state [ 12.677373][ T270] veth1: entered allmulticast mode [ 12.678867][ T270] veth1: entered promiscuous mode [ 12.703369][ T38] br1: port 2(veth1) entered blocking state [ 12.703813][ T38] br1: port 2(veth1) entered forwarding state [ 12.754323][ T273] br1: port 3(veth2) entered blocking state [ 12.754677][ T273] br1: port 3(veth2) entered disabled state [ 12.755031][ T273] veth2: entered allmulticast mode [ 12.757167][ T273] veth2: entered promiscuous mode [ 12.797253][ T63] br1: port 3(veth2) entered blocking state [ 12.797616][ T63] br1: port 3(veth2) entered forwarding state [ 14.193854][ T318] br2: port 1(w1) entered blocking state [ 14.193873][ T318] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 14.193875][ T318] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 318, name: ip [ 14.193876][ T318] preempt_count: 1, expected: 0 [ 14.193877][ T318] RCU nest depth: 0, expected: 0 [ 14.193878][ T318] locks held by ip/318: 5, last CPU#0: [ 14.193881][ T318] #0: ffffffff89ad2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 14.193893][ T318] #1: ffffffff89369cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 14.193899][ T318] #2: ffffffff89369d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 14.193903][ T318] #3: ffffffff89289660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 14.193907][ T318] #4: ffffffff89289560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 14.193911][ T318] irq event stamp: 19318 [ 14.193912][ T318] hardirqs last enabled at (19317): [] __down_trylock_console_sem+0x86/0xa0 [ 14.193914][ T318] hardirqs last disabled at (19318): [] console_emit_next_record+0x3d4/0x4c0 [ 14.193917][ T318] softirqs last enabled at (19284): [] handle_softirqs+0x67c/0x900 [ 14.193919][ T318] softirqs last disabled at (18505): [] __irq_exit_rcu+0x145/0x1c0 [ 14.193922][ T318] Preemption disabled at: [ 14.193922][ T318] [] vprintk_emit+0x31b/0x3e0 [ 14.193928][ T318] CPU: 0 UID: 0 PID: 318 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 14.193932][ T318] Tainted: [W]=WARN [ 14.193933][ T318] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.193934][ T318] Call Trace: [ 14.193936][ T318] [ 14.193937][ T318] dump_stack_lvl+0x6f/0xa0 [ 14.193943][ T318] ? vprintk_emit+0x31b/0x3e0 [ 14.193945][ T318] __might_resched.cold+0x1fe/0x2c1 [ 14.193950][ T318] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 14.193954][ T318] ? __kmalloc_noprof+0xdb/0x760 [ 14.193959][ T318] __kmalloc_noprof+0x443/0x760 [ 14.193961][ T318] ? alloc_buf.isra.0+0x4b/0x260 [ 14.193967][ T318] ? do_raw_spin_unlock+0x59/0x250 [ 14.193970][ T318] alloc_buf.isra.0+0x4b/0x260 [ 14.193973][ T318] put_chars+0x1e1/0x2f0 [ 14.193975][ T318] ? prb_final_commit+0x50/0x50 [ 14.193977][ T318] ? __send_to_port+0x420/0x420 [ 14.193980][ T318] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.193985][ T318] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.193987][ T318] ? validate_chain+0x38b/0xc20 [ 14.193991][ T318] hvc_console_print+0x292/0x780 [ 14.193994][ T318] ? mark_usage+0x61/0x170 [ 14.193995][ T318] ? __lock_acquire+0x518/0xc20 [ 14.193997][ T318] ? __lock_acquire+0x518/0xc20 [ 14.194001][ T318] ? hvc_write+0x3a0/0x3a0 [ 14.194003][ T318] ? console_emit_next_record+0x1df/0x4c0 [ 14.194006][ T318] ? rcu_is_watching+0x16/0xd0 [ 14.194008][ T318] ? lock_acquire+0x13c/0x160 [ 14.194012][ T318] console_emit_next_record+0x22f/0x4c0 [ 14.194016][ T318] ? devkmsg_read+0x4b0/0x4b0 [ 14.194018][ T318] ? console_flush_one_record+0x106/0x710 [ 14.194022][ T318] ? rcu_is_watching+0x16/0xd0 [ 14.194024][ T318] ? lock_acquire+0x13c/0x160 [ 14.194028][ T318] console_flush_one_record+0x46f/0x710 [ 14.194032][ T318] ? console_emit_next_record+0x4c0/0x4c0 [ 14.194034][ T318] ? __lock_acquire+0x518/0xc20 [ 14.194039][ T318] console_unlock+0xee/0x1f0 [ 14.194042][ T318] ? console_flush_one_record+0x710/0x710 [ 14.194044][ T318] ? rcu_is_watching+0x16/0xd0 [ 14.194046][ T318] ? lock_acquire+0x60/0x160 [ 14.194050][ T318] ? __down_trylock_console_sem+0x5e/0xa0 [ 14.194054][ T318] ? vprintk_emit+0x320/0x3e0 [ 14.194057][ T318] vprintk_emit+0x37c/0x3e0 [ 14.194061][ T318] ? wake_up_klogd_work_func+0x90/0x90 [ 14.194063][ T318] ? __lock_release.isra.0+0x69/0x1a0 [ 14.194066][ T318] ? mark_held_locks+0x40/0x70 [ 14.194070][ T318] _printk+0xc7/0x100 [ 14.194073][ T318] ? snapshot_read.cold+0x21/0x21 [ 14.194077][ T318] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 14.194090][ T318] ? do_setlink.isra.0+0xa31/0x2750 [ 14.194091][ T318] ? rtnl_newlink+0x9f1/0x11c0 [ 14.194092][ T318] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 14.194097][ T318] br_set_state+0x22f/0x430 [bridge] [ 14.194107][ T318] br_init_port+0xc4/0x200 [bridge] [ 14.194115][ T318] new_nbp+0x39c/0x580 [bridge] [ 14.194125][ T318] br_add_if+0x212/0x1320 [bridge] [ 14.194132][ T318] ? is_bpf_text_address+0x72/0x110 [ 14.194136][ T318] ? kernel_text_address+0x149/0x170 [ 14.194139][ T318] ? __kernel_text_address+0x12/0x30 [ 14.194143][ T318] do_set_master+0x357/0x580 [ 14.194147][ T318] do_setlink.isra.0+0xa31/0x2750 [ 14.194151][ T318] ? stack_trace_save+0x93/0xc0 [ 14.194155][ T318] ? rtnl_link_get_size+0x350/0x350 [ 14.194156][ T318] ? rcu_read_lock_any_held+0x66/0x90 [ 14.194158][ T318] ? stack_depot_save_flags+0x38e/0x790 [ 14.194161][ T318] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.194164][ T318] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.194166][ T318] ? validate_chain+0x38b/0xc20 [ 14.194168][ T318] ? kasan_save_stack+0x3d/0x50 [ 14.194171][ T318] ? kasan_save_stack+0x2f/0x50 [ 14.194173][ T318] ? kasan_save_track+0x14/0x30 [ 14.194176][ T318] ? __lock_acquire+0x518/0xc20 [ 14.194177][ T318] ? netlink_seq_next+0x4e/0x60 [ 14.194180][ T318] ? ___sys_sendmsg+0xb0/0x1d0 [ 14.194185][ T318] ? lock_acquire.part.0+0xd4/0x280 [ 14.194187][ T318] ? rtnl_newlink+0x9a8/0x11c0 [ 14.194190][ T318] ? rcu_is_watching+0x16/0xd0 [ 14.194192][ T318] ? lock_acquire+0x13c/0x160 [ 14.194193][ T318] ? rcu_is_watching+0x16/0xd0 [ 14.194195][ T318] ? rcu_is_watching+0x16/0xd0 [ 14.194198][ T318] ? trace_contention_end+0xb3/0x180 [ 14.194201][ T318] ? __mutex_lock+0x1db/0x1ea0 [ 14.194204][ T318] ? __mutex_lock+0x9a3/0x1ea0 [ 14.194206][ T318] ? rtnl_newlink+0x9a8/0x11c0 [ 14.194210][ T318] ? ww_mutex_lock+0x160/0x160 [ 14.194211][ T318] ? nla_get_range_signed+0x3d0/0x3d0 [ 14.194216][ T318] ? __rtnl_newlink+0x3fa/0xa50 [ 14.194221][ T318] rtnl_newlink+0x9f1/0x11c0 [ 14.194226][ T318] ? rtnl_bridge_getlink+0x850/0x850 [ 14.194228][ T318] ? __lock_acquire+0x518/0xc20 [ 14.194232][ T318] ? lock_acquire.part.0+0xd4/0x280 [ 14.194234][ T318] ? find_held_lock+0x2b/0x80 [ 14.194236][ T318] ? rtnl_bridge_getlink+0x850/0x850 [ 14.194238][ T318] ? __lock_release.isra.0+0x69/0x1a0 [ 14.194242][ T318] ? rtnl_bridge_getlink+0x850/0x850 [ 14.194244][ T318] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 14.194247][ T318] ? rtnl_link_fill+0x920/0x920 [ 14.194248][ T318] ? __lock_acquire+0x518/0xc20 [ 14.194252][ T318] ? lock_acquire.part.0+0xd4/0x280 [ 14.194254][ T318] ? find_held_lock+0x2b/0x80 [ 14.194257][ T318] netlink_rcv_skb+0x14e/0x3a0 [ 14.194259][ T318] ? rtnl_link_fill+0x920/0x920 [ 14.194262][ T318] ? netlink_ack+0xcf0/0xcf0 [ 14.194268][ T318] ? netlink_deliver_tap+0xc5/0x330 [ 14.194270][ T318] ? netlink_deliver_tap+0x13c/0x330 [ 14.194274][ T318] netlink_unicast+0x486/0x750 [ 14.194277][ T318] ? netlink_attachskb+0x810/0x810 [ 14.194280][ T318] ? __lock_acquire+0x518/0xc20 [ 14.194284][ T318] netlink_sendmsg+0x735/0xc60 [ 14.194288][ T318] ? netlink_unicast+0x750/0x750 [ 14.194291][ T318] ? __might_fault+0x97/0x140 [ 14.194302][ T318] ____sys_sendmsg+0x415/0x880 [ 14.194304][ T318] ? copy_msghdr_from_user+0x279/0x420 [ 14.194307][ T318] ? get_timestamp.constprop.0+0x390/0x390 [ 14.194308][ T318] ? move_addr_to_kernel+0x40/0x40 [ 14.194315][ T318] ___sys_sendmsg+0x14e/0x1d0 [ 14.194318][ T318] ? copy_msghdr_from_user+0x420/0x420 [ 14.194333][ T318] __sys_sendmsg+0x12c/0x1d0 [ 14.194336][ T318] ? __sys_sendmsg_sock+0x20/0x20 [ 14.194342][ T318] ? rcu_is_watching+0x16/0xd0 [ 14.194346][ T318] do_syscall_64+0xff/0x530 [ 14.194348][ T318] ? exc_page_fault+0xee/0x100 [ 14.194351][ T318] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.194353][ T318] RIP: 0033:0x7f703de1b54e [ 14.194357][ T318] 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 [ 14.194359][ T318] RSP: 002b:00007fffa675ced0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 14.194362][ T318] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f703de1b54e [ 14.194363][ T318] RDX: 0000000000000000 RSI: 00007fffa675cf80 RDI: 0000000000000005 [ 14.194364][ T318] RBP: 00007fffa675cee0 R08: 0000000000000000 R09: 0000000000000000 [ 14.194365][ T318] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a915f65 [ 14.194365][ T318] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fffa675d640 [ 14.194372][ T318] [ 14.235812][ T318] br2: port 1(w1) entered disabled state [ 14.236286][ T318] w1: entered allmulticast mode [ 14.239484][ T318] w1: entered promiscuous mode [ 14.282269][ T319] br2: port 1(w1) entered blocking state [ 14.282549][ T319] br2: port 1(w1) entered forwarding state [ 14.440905][ T324] br2: port 2(vx2) entered blocking state [ 14.441191][ T324] br2: port 2(vx2) entered disabled state [ 14.441457][ T324] vx2: entered allmulticast mode [ 14.442982][ T324] vx2: entered promiscuous mode [ 14.443908][ T324] br2: port 2(vx2) entered blocking state [ 14.444152][ T324] br2: port 2(vx2) entered forwarding state [ 15.324229][ T357] br2: port 1(w1) entered blocking state [ 15.324249][ T357] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 15.324252][ T357] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 357, name: ip [ 15.324254][ T357] preempt_count: 1, expected: 0 [ 15.324256][ T357] RCU nest depth: 0, expected: 0 [ 15.324257][ T357] locks held by ip/357: 5, last CPU#3: [ 15.324260][ T357] #0: ffffffff89ad2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 15.324276][ T357] #1: ffffffff89369cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 15.324285][ T357] #2: ffffffff89369d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 15.324291][ T357] #3: ffffffff89289660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 15.324301][ T357] #4: ffffffff89289560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 15.324308][ T357] irq event stamp: 12176 [ 15.324309][ T357] hardirqs last enabled at (12175): [] __down_trylock_console_sem+0x86/0xa0 [ 15.324312][ T357] hardirqs last disabled at (12176): [] console_emit_next_record+0x3d4/0x4c0 [ 15.324316][ T357] softirqs last enabled at (12116): [] __alloc_skb+0x4c2/0x5f0 [ 15.324320][ T357] softirqs last disabled at (12114): [] __alloc_skb+0x4c2/0x5f0 [ 15.324323][ T357] Preemption disabled at: [ 15.324324][ T357] [] vprintk_emit+0x31b/0x3e0 [ 15.324332][ T357] CPU: 3 UID: 0 PID: 357 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.324337][ T357] Tainted: [W]=WARN [ 15.324338][ T357] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.324340][ T357] Call Trace: [ 15.324342][ T357] [ 15.324344][ T357] dump_stack_lvl+0x6f/0xa0 [ 15.324352][ T357] ? vprintk_emit+0x31b/0x3e0 [ 15.324355][ T357] __might_resched.cold+0x1fe/0x2c1 [ 15.324362][ T357] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 15.324368][ T357] ? __kmalloc_noprof+0xdb/0x760 [ 15.324375][ T357] __kmalloc_noprof+0x443/0x760 [ 15.324379][ T357] ? alloc_buf.isra.0+0x4b/0x260 [ 15.324388][ T357] ? do_raw_spin_unlock+0x59/0x250 [ 15.324392][ T357] alloc_buf.isra.0+0x4b/0x260 [ 15.324397][ T357] put_chars+0x1e1/0x2f0 [ 15.324400][ T357] ? prb_final_commit+0x50/0x50 [ 15.324403][ T357] ? __send_to_port+0x420/0x420 [ 15.324409][ T357] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.324414][ T357] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.324418][ T357] ? validate_chain+0x38b/0xc20 [ 15.324425][ T357] hvc_console_print+0x292/0x780 [ 15.324429][ T357] ? mark_usage+0x61/0x170 [ 15.324431][ T357] ? __lock_acquire+0x518/0xc20 [ 15.324434][ T357] ? __lock_acquire+0x518/0xc20 [ 15.324441][ T357] ? hvc_write+0x3a0/0x3a0 [ 15.324444][ T357] ? console_emit_next_record+0x1df/0x4c0 [ 15.324450][ T357] ? rcu_is_watching+0x16/0xd0 [ 15.324453][ T357] ? lock_acquire+0x13c/0x160 [ 15.324460][ T357] console_emit_next_record+0x22f/0x4c0 [ 15.324467][ T357] ? devkmsg_read+0x4b0/0x4b0 [ 15.324469][ T357] ? console_flush_one_record+0x106/0x710 [ 15.324475][ T357] ? rcu_is_watching+0x16/0xd0 [ 15.324478][ T357] ? lock_acquire+0x13c/0x160 [ 15.324485][ T357] console_flush_one_record+0x46f/0x710 [ 15.324492][ T357] ? console_emit_next_record+0x4c0/0x4c0 [ 15.324495][ T357] ? __lock_acquire+0x518/0xc20 [ 15.324505][ T357] console_unlock+0xee/0x1f0 [ 15.324510][ T357] ? console_flush_one_record+0x710/0x710 [ 15.324512][ T357] ? rcu_is_watching+0x16/0xd0 [ 15.324516][ T357] ? lock_acquire+0x60/0x160 [ 15.324522][ T357] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.324525][ T357] ? vprintk_emit+0x320/0x3e0 [ 15.324531][ T357] vprintk_emit+0x37c/0x3e0 [ 15.324537][ T357] ? wake_up_klogd_work_func+0x90/0x90 [ 15.324541][ T357] ? __lock_release.isra.0+0x69/0x1a0 [ 15.324546][ T357] ? mark_held_locks+0x40/0x70 [ 15.324551][ T357] _printk+0xc7/0x100 [ 15.324557][ T357] ? snapshot_read.cold+0x21/0x21 [ 15.324563][ T357] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 15.324582][ T357] ? do_setlink.isra.0+0xa31/0x2750 [ 15.324585][ T357] ? rtnl_newlink+0x9f1/0x11c0 [ 15.324587][ T357] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 15.324595][ T357] br_set_state+0x22f/0x430 [bridge] [ 15.324614][ T357] br_init_port+0xc4/0x200 [bridge] [ 15.324630][ T357] new_nbp+0x39c/0x580 [bridge] [ 15.324647][ T357] br_add_if+0x212/0x1320 [bridge] [ 15.324660][ T357] ? is_bpf_text_address+0x72/0x110 [ 15.324666][ T357] ? kernel_text_address+0x149/0x170 [ 15.324670][ T357] ? __kernel_text_address+0x12/0x30 [ 15.324677][ T357] do_set_master+0x357/0x580 [ 15.324685][ T357] do_setlink.isra.0+0xa31/0x2750 [ 15.324691][ T357] ? stack_trace_save+0x93/0xc0 [ 15.324695][ T357] ? rtnl_link_get_size+0x350/0x350 [ 15.324698][ T357] ? rcu_read_lock_any_held+0x66/0x90 [ 15.324701][ T357] ? stack_depot_save_flags+0x38e/0x790 [ 15.324705][ T357] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.324709][ T357] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.324712][ T357] ? validate_chain+0x38b/0xc20 [ 15.324717][ T357] ? kasan_save_stack+0x3d/0x50 [ 15.324721][ T357] ? kasan_save_stack+0x2f/0x50 [ 15.324723][ T357] ? kasan_save_track+0x14/0x30 [ 15.324727][ T357] ? __lock_acquire+0x518/0xc20 [ 15.324730][ T357] ? netlink_seq_next+0x4e/0x60 [ 15.324734][ T357] ? ___sys_sendmsg+0xb0/0x1d0 [ 15.324741][ T357] ? lock_acquire.part.0+0xd4/0x280 [ 15.324744][ T357] ? rtnl_newlink+0x9a8/0x11c0 [ 15.324749][ T357] ? rcu_is_watching+0x16/0xd0 [ 15.324753][ T357] ? lock_acquire+0x13c/0x160 [ 15.324755][ T357] ? rcu_is_watching+0x16/0xd0 [ 15.324758][ T357] ? rcu_is_watching+0x16/0xd0 [ 15.324761][ T357] ? trace_contention_end+0xb3/0x180 [ 15.324767][ T357] ? __mutex_lock+0x1db/0x1ea0 [ 15.324771][ T357] ? __mutex_lock+0x9a3/0x1ea0 [ 15.324776][ T357] ? rtnl_newlink+0x9a8/0x11c0 [ 15.324782][ T357] ? ww_mutex_lock+0x160/0x160 [ 15.324785][ T357] ? nla_get_range_signed+0x3d0/0x3d0 [ 15.324794][ T357] ? __rtnl_newlink+0x3fa/0xa50 [ 15.324803][ T357] rtnl_newlink+0x9f1/0x11c0 [ 15.324813][ T357] ? rtnl_bridge_getlink+0x850/0x850 [ 15.324817][ T357] ? __lock_acquire+0x518/0xc20 [ 15.324824][ T357] ? lock_acquire.part.0+0xd4/0x280 [ 15.324827][ T357] ? find_held_lock+0x2b/0x80 [ 15.324831][ T357] ? rtnl_bridge_getlink+0x850/0x850 [ 15.324834][ T357] ? __lock_release.isra.0+0x69/0x1a0 [ 15.324841][ T357] ? rtnl_bridge_getlink+0x850/0x850 [ 15.324845][ T357] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 15.324850][ T357] ? rtnl_link_fill+0x920/0x920 [ 15.324852][ T357] ? __lock_acquire+0x518/0xc20 [ 15.324860][ T357] ? lock_acquire.part.0+0xd4/0x280 [ 15.324863][ T357] ? find_held_lock+0x2b/0x80 [ 15.324869][ T357] netlink_rcv_skb+0x14e/0x3a0 [ 15.324872][ T357] ? rtnl_link_fill+0x920/0x920 [ 15.324877][ T357] ? netlink_ack+0xcf0/0xcf0 [ 15.324889][ T357] ? netlink_deliver_tap+0xc5/0x330 [ 15.324891][ T357] ? netlink_deliver_tap+0x13c/0x330 [ 15.324899][ T357] netlink_unicast+0x486/0x750 [ 15.324906][ T357] ? netlink_attachskb+0x810/0x810 [ 15.324910][ T357] ? __lock_acquire+0x518/0xc20 [ 15.324918][ T357] netlink_sendmsg+0x735/0xc60 [ 15.324924][ T357] ? netlink_unicast+0x750/0x750 [ 15.324931][ T357] ? __might_fault+0x97/0x140 [ 15.324940][ T357] ____sys_sendmsg+0x415/0x880 [ 15.324944][ T357] ? copy_msghdr_from_user+0x279/0x420 [ 15.324947][ T357] ? get_timestamp.constprop.0+0x390/0x390 [ 15.324950][ T357] ? move_addr_to_kernel+0x40/0x40 [ 15.324964][ T357] ___sys_sendmsg+0x14e/0x1d0 [ 15.324968][ T357] ? copy_msghdr_from_user+0x420/0x420 [ 15.324998][ T357] __sys_sendmsg+0x12c/0x1d0 [ 15.325003][ T357] ? __sys_sendmsg_sock+0x20/0x20 [ 15.325014][ T357] ? rcu_is_watching+0x16/0xd0 [ 15.325021][ T357] do_syscall_64+0xff/0x530 [ 15.325024][ T357] ? exc_page_fault+0xee/0x100 [ 15.325029][ T357] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.325033][ T357] RIP: 0033:0x7f9fcdab554e [ 15.325037][ T357] 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 [ 15.325040][ T357] RSP: 002b:00007ffd52e0b940 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 15.325044][ T357] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f9fcdab554e [ 15.325046][ T357] RDX: 0000000000000000 RSI: 00007ffd52e0b9f0 RDI: 0000000000000005 [ 15.325047][ T357] RBP: 00007ffd52e0b950 R08: 0000000000000000 R09: 0000000000000000 [ 15.325049][ T357] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a915f66 [ 15.325053][ T357] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffd52e0c0b0 [ 15.325067][ T357] [ 15.388115][ T357] br2: port 1(w1) entered disabled state [ 15.388558][ T357] w1: entered allmulticast mode [ 15.391439][ T357] w1: entered promiscuous mode [ 15.437585][ T358] br2: port 1(w1) entered blocking state [ 15.437999][ T358] br2: port 1(w1) entered forwarding state [ 15.635774][ T363] br2: port 2(vx2) entered blocking state [ 15.636103][ T363] br2: port 2(vx2) entered disabled state [ 15.636418][ T363] vx2: entered allmulticast mode [ 15.638025][ T363] vx2: entered promiscuous mode [ 15.639201][ T363] br2: port 2(vx2) entered blocking state [ 15.639494][ T363] br2: port 2(vx2) entered forwarding state [ 23.148523][ T445] GACT probability NOT on [ 23.148544][ T445] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 23.148546][ T445] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 445, name: modprobe [ 23.148548][ T445] preempt_count: 1, expected: 0 [ 23.148549][ T445] RCU nest depth: 0, expected: 0 [ 23.148550][ T445] locks held by modprobe/445: 4, last CPU#2: [ 23.148552][ T445] #0: ffffffff89369cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 23.148565][ T445] #1: ffffffff89369d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 23.148569][ T445] #2: ffffffff89289660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 23.148573][ T445] #3: ffffffff89289560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 23.148577][ T445] irq event stamp: 18366 [ 23.148578][ T445] hardirqs last enabled at (18365): [] __down_trylock_console_sem+0x86/0xa0 [ 23.148581][ T445] hardirqs last disabled at (18366): [] console_emit_next_record+0x3d4/0x4c0 [ 23.148583][ T445] softirqs last enabled at (17184): [] handle_softirqs+0x67c/0x900 [ 23.148586][ T445] softirqs last disabled at (16703): [] __irq_exit_rcu+0x145/0x1c0 [ 23.148588][ T445] Preemption disabled at: [ 23.148589][ T445] [] vprintk_emit+0x31b/0x3e0 [ 23.148594][ T445] CPU: 2 UID: 0 PID: 445 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 23.148598][ T445] Tainted: [W]=WARN [ 23.148599][ T445] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 23.148601][ T445] Call Trace: [ 23.148602][ T445] [ 23.148604][ T445] dump_stack_lvl+0x6f/0xa0 [ 23.148609][ T445] ? vprintk_emit+0x31b/0x3e0 [ 23.148612][ T445] __might_resched.cold+0x1fe/0x2c1 [ 23.148616][ T445] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 23.148620][ T445] ? __kmalloc_noprof+0xdb/0x760 [ 23.148625][ T445] __kmalloc_noprof+0x443/0x760 [ 23.148627][ T445] ? alloc_buf.isra.0+0x4b/0x260 [ 23.148634][ T445] ? do_raw_spin_unlock+0x59/0x250 [ 23.148636][ T445] alloc_buf.isra.0+0x4b/0x260 [ 23.148639][ T445] put_chars+0x1e1/0x2f0 [ 23.148641][ T445] ? desc_read_finalized_seq+0x79/0x120 [ 23.148643][ T445] ? __send_to_port+0x420/0x420 [ 23.148646][ T445] ? rcu_read_lock_any_held+0x3c/0x90 [ 23.148650][ T445] ? validate_chain+0x38b/0xc20 [ 23.148654][ T445] hvc_console_print+0x292/0x780 [ 23.148657][ T445] ? __lock_acquire+0x518/0xc20 [ 23.148658][ T445] ? __lock_acquire+0x518/0xc20 [ 23.148663][ T445] ? hvc_write+0x3a0/0x3a0 [ 23.148664][ T445] ? console_emit_next_record+0x1df/0x4c0 [ 23.148667][ T445] ? rcu_is_watching+0x16/0xd0 [ 23.148670][ T445] ? lock_acquire+0x13c/0x160 [ 23.148674][ T445] console_emit_next_record+0x22f/0x4c0 [ 23.148678][ T445] ? devkmsg_read+0x4b0/0x4b0 [ 23.148680][ T445] ? console_flush_one_record+0x106/0x710 [ 23.148683][ T445] ? rcu_is_watching+0x16/0xd0 [ 23.148685][ T445] ? lock_acquire+0x13c/0x160 [ 23.148689][ T445] console_flush_one_record+0x46f/0x710 [ 23.148693][ T445] ? console_emit_next_record+0x4c0/0x4c0 [ 23.148695][ T445] ? __lock_acquire+0x518/0xc20 [ 23.148700][ T445] console_unlock+0xee/0x1f0 [ 23.148703][ T445] ? console_flush_one_record+0x710/0x710 [ 23.148704][ T445] ? rcu_is_watching+0x16/0xd0 [ 23.148707][ T445] ? lock_acquire+0x60/0x160 [ 23.148710][ T445] ? __down_trylock_console_sem+0x5e/0xa0 [ 23.148712][ T445] ? vprintk_emit+0x320/0x3e0 [ 23.148715][ T445] vprintk_emit+0x37c/0x3e0 [ 23.148720][ T445] ? wake_up_klogd_work_func+0x90/0x90 [ 23.148726][ T445] ? llc_exit+0x3e0/0x3e0 [llc] [ 23.148729][ T445] _printk+0xc7/0x100 [ 23.148732][ T445] ? snapshot_read.cold+0x21/0x21 [ 23.148739][ T445] ? do_one_initcall+0x113/0x4f0 [ 23.148742][ T445] gact_init_module+0x15/0x1000 [act_gact] [ 23.148745][ T445] do_one_initcall+0x124/0x4f0 [ 23.148747][ T445] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 23.148752][ T445] ? do_init_module+0xae/0x8a0 [ 23.148755][ T445] ? kasan_unpoison+0x44/0x70 [ 23.148760][ T445] do_init_module+0x2ad/0x8a0 [ 23.148764][ T445] ? lockdep_hardirqs_on+0x91/0x130 [ 23.148768][ T445] ? free_module+0x470/0x470 [ 23.148771][ T445] ? kfree+0x123/0x590 [ 23.148775][ T445] load_module+0x1424/0x1bb0 [ 23.148782][ T445] ? layout_and_allocate.isra.0+0x680/0x680 [ 23.148786][ T445] ? __ia32_sys_fsconfig+0x180/0x180 [ 23.148789][ T445] ? ovl_real_file+0x172/0x1e0 [ 23.148793][ T445] ? backing_file_read_iter+0x541/0x790 [ 23.148796][ T445] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 23.148800][ T445] init_module_from_file+0x11f/0x1a0 [ 23.148803][ T445] ? __do_sys_init_module+0x220/0x220 [ 23.148811][ T445] ? __rwlock_init+0x150/0x150 [ 23.148815][ T445] ? do_raw_spin_unlock+0x59/0x250 [ 23.148818][ T445] idempotent_init_module+0x37b/0x7c0 [ 23.148821][ T445] ? init_module_from_file+0x1a0/0x1a0 [ 23.148828][ T445] ? rcu_is_watching+0x16/0xd0 [ 23.148830][ T445] ? cap_capable+0x1d7/0x3d0 [ 23.148836][ T445] __x64_sys_finit_module+0xdf/0x190 [ 23.148838][ T445] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 23.148842][ T445] do_syscall_64+0xff/0x530 [ 23.148844][ T445] ? exc_page_fault+0xee/0x100 [ 23.148847][ T445] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 23.148849][ T445] RIP: 0033:0x7fcefc50e37d [ 23.148852][ T445] Code: ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 4b aa 0f 00 f7 d8 64 89 01 48 [ 23.148854][ T445] RSP: 002b:00007ffc299a7c18 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 23.148857][ T445] RAX: ffffffffffffffda RBX: 0000555a3991e810 RCX: 00007fcefc50e37d [ 23.148858][ T445] RDX: 0000000000000000 RSI: 0000555a0d137ffd RDI: 0000000000000000 [ 23.148859][ T445] RBP: 00007ffc299a7cb0 R08: 0000000000000000 R09: 0000000000000000 [ 23.148860][ T445] R10: 0000000000000000 R11: 0000000000000246 R12: 0000555a0d137ffd [ 23.148861][ T445] R13: 0000555a3991e960 R14: 0000000000040000 R15: 0000000000000000 [ 23.148867][ T445] [ 28.025149][ C3] [ 28.025169][ C3] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 28.025172][ C3] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/3 [ 28.025174][ C3] preempt_count: 104, expected: 0 [ 28.025176][ C3] RCU nest depth: 0, expected: 0 [ 28.025178][ C3] INFO: lockdep is turned off. [ 28.025179][ C3] irq event stamp: 830166 [ 28.025180][ C3] hardirqs last enabled at (830166): [] _raw_spin_unlock_irq+0x28/0x50 [ 28.025191][ C3] hardirqs last disabled at (830165): [] _raw_spin_lock_irq+0x4a/0x50 [ 28.025193][ C3] softirqs last enabled at (830150): [] handle_softirqs+0x67c/0x900 [ 28.025198][ C3] softirqs last disabled at (830163): [] __irq_exit_rcu+0x145/0x1c0 [ 28.025201][ C3] Preemption disabled at: [ 28.025202][ C3] [<0000000000000000>] 0x0 [ 28.025211][ C3] CPU: 3 UID: 0 PID: 0 Comm: swapper/3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 28.025216][ C3] Tainted: [W]=WARN [ 28.025217][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 28.025220][ C3] Call Trace: [ 28.025222][ C3] [ 28.025225][ C3] dump_stack_lvl+0x6f/0xa0 [ 28.025231][ C3] __might_resched.cold+0x1fe/0x2c1 [ 28.025236][ C3] ? find_held_lock+0x2b/0x80 [ 28.025239][ C3] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 28.025243][ C3] ? __asan_memcpy+0x3c/0x60 [ 28.025246][ C3] ? rcu_is_watching+0x16/0xd0 [ 28.025252][ C3] __kmalloc_noprof+0x443/0x760 [ 28.025256][ C3] ? __rwlock_init+0x150/0x150 [ 28.025258][ C3] ? alloc_buf.isra.0+0x4b/0x260 [ 28.025263][ C3] ? do_raw_spin_unlock+0x59/0x250 [ 28.025265][ C3] alloc_buf.isra.0+0x4b/0x260 [ 28.025268][ C3] put_chars+0x1e1/0x2f0 [ 28.025271][ C3] ? __send_to_port+0x420/0x420 [ 28.025274][ C3] ? console_prepend_replay+0x20/0x20 [ 28.025279][ C3] hvc_console_print+0x292/0x780 [ 28.025284][ C3] ? hvc_write+0x3a0/0x3a0 [ 28.025286][ C3] ? rcu_is_watching+0x16/0xd0 [ 28.025288][ C3] ? lock_acquire+0x13c/0x160 [ 28.025292][ C3] console_emit_next_record+0x22f/0x4c0 [ 28.025295][ C3] ? devkmsg_read+0x4b0/0x4b0 [ 28.025298][ C3] ? rcu_is_watching+0x16/0xd0 [ 28.025300][ C3] ? rcu_is_watching+0x16/0xd0 [ 28.025303][ C3] ? lock_acquire+0x13c/0x160 [ 28.025306][ C3] ? console_flush_one_record+0x111/0x710 [ 28.025308][ C3] console_flush_one_record+0x46f/0x710 [ 28.025311][ C3] ? console_emit_next_record+0x4c0/0x4c0 [ 28.025315][ C3] console_unlock+0xee/0x1f0 [ 28.025318][ C3] ? lock_acquire+0x13c/0x160 [ 28.025320][ C3] ? console_flush_one_record+0x710/0x710 [ 28.025322][ C3] ? rcu_is_watching+0x16/0xd0 [ 28.025324][ C3] ? lock_release+0x184/0x1f0 [ 28.025327][ C3] ? lock_acquire+0x60/0x160 [ 28.025330][ C3] ? __down_trylock_console_sem+0x5e/0xa0 [ 28.025332][ C3] ? vprintk_emit+0x320/0x3e0 [ 28.025335][ C3] vprintk_emit+0x37c/0x3e0 [ 28.025339][ C3] ? wake_up_klogd_work_func+0x90/0x90 [ 28.025347][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.025364][ C3] ? lock_release+0x184/0x1f0 [ 28.025366][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.025377][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.025388][ C3] ? is_module_text_address+0x154/0x250 [ 28.025393][ C3] _printk+0xc7/0x100 [ 28.025397][ C3] ? snapshot_read.cold+0x21/0x21 [ 28.025399][ C3] ? arch_stack_walk+0xd7/0x130 [ 28.025405][ C3] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 28.025408][ C3] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 28.025410][ C3] print_irq_inversion_bug.part.0+0x32/0xc0 [ 28.025414][ C3] mark_lock_irq+0x989/0x9c0 [ 28.025418][ C3] mark_lock+0x1d7/0xa00 [ 28.025421][ C3] mark_usage+0x42/0x170 [ 28.025424][ C3] __lock_acquire+0x388/0xc20 [ 28.025427][ C3] lock_acquire.part.0+0xd4/0x280 [ 28.025430][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.025441][ C3] ? rcu_is_watching+0x16/0xd0 [ 28.025443][ C3] ? lock_acquire+0x13c/0x160 [ 28.025446][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.025457][ C3] _raw_spin_lock+0x33/0x40 [ 28.025460][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.025471][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.025483][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.025493][ C3] call_timer_fn+0x160/0x4d0 [ 28.025497][ C3] ? detach_if_pending+0x1d0/0x1d0 [ 28.025500][ C3] ? debug_object_active_state+0x430/0x430 [ 28.025504][ C3] ? find_held_lock+0x2b/0x80 [ 28.025507][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 28.025510][ C3] ? rcu_is_watching+0x16/0xd0 [ 28.025513][ C3] __run_timers+0x68f/0xaa0 [ 28.025516][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.025527][ C3] ? __bpf_trace_itimer_expire+0x10/0x10 [ 28.025530][ C3] ? __lock_acquire+0x518/0xc20 [ 28.025534][ C3] ? __rwlock_init+0x150/0x150 [ 28.025538][ C3] run_timer_softirq+0xf0/0x160 [ 28.025541][ C3] ? __run_timers+0xaa0/0xaa0 [ 28.025543][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 28.025546][ C3] ? rcu_is_watching+0x16/0xd0 [ 28.025549][ C3] handle_softirqs+0x1d3/0x900 [ 28.025552][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 28.025554][ C3] ? _local_bh_enable+0xc0/0xc0 [ 28.025557][ C3] __irq_exit_rcu+0x145/0x1c0 [ 28.025560][ C3] irq_exit_rcu+0xe/0x30 [ 28.025562][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 28.025566][ C3] [ 28.025567][ C3] [ 28.025568][ C3] ? lockdep_hardirqs_on+0x91/0x130 [ 28.025570][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 28.025573][ C3] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 28.025577][ 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 [ 28.025580][ C3] RSP: 0018:ffa0000000167e00 EFLAGS: 00000282 [ 28.025584][ C3] RAX: 00000000000caad1 RBX: ff11000001c88040 RCX: ffffffff860f0307 [ 28.025585][ C3] RDX: ff11000001c88040 RSI: ffffffff88e38b01 RDI: ffffffff8888d8e0 [ 28.025587][ C3] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 28.025588][ C3] R10: 0000000000000003 R11: 0000000000000001 R12: 1ff400000002cfc3 [ 28.025590][ C3] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 28.025592][ C3] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 28.025596][ C3] default_idle+0x9/0x10 [ 28.025599][ C3] default_idle_call+0x6e/0xb0 [ 28.025601][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 28.025604][ C3] ? arch_cpu_idle_exit+0x40/0x40 [ 28.025606][ C3] ? mark_tsc_async_resets+0x30/0x30 [ 28.025609][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 28.025611][ C3] ? rcu_is_watching+0x16/0xd0 [ 28.025614][ C3] do_idle+0xd8/0x190 [ 28.025616][ C3] cpu_startup_entry+0x53/0x70 [ 28.025618][ C3] start_secondary+0x204/0x2b0 [ 28.025621][ C3] ? set_cpu_sibling_map+0x2130/0x2130 [ 28.025624][ C3] common_startup_64+0x13e/0x148 [ 28.025630][ C3] [ 28.058468][ C3] ======================================================== [ 28.058854][ C3] WARNING: possible irq lock inversion dependency detected [ 28.059164][ C3] 7.2.0-virtme #1 Tainted: G W [ 28.059421][ C3] -------------------------------------------------------- [ 28.059794][ C3] swapper/3/0 just changed the state of lock: [ 28.060116][ C3] ff11000013aaae58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.060603][ C3] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 28.060967][ C3] (fs_reclaim){+.+.}-{0:0} [ 28.060972][ C3] [ 28.060972][ C3] [ 28.060972][ C3] and interrupts could create inverse lock ordering between them. [ 28.060972][ C3] [ 28.061779][ C3] [ 28.061779][ C3] other info that might help us debug this: [ 28.062146][ C3] Chain exists of: [ 28.062146][ C3] &br->lock --> console_owner --> fs_reclaim [ 28.062146][ C3] [ 28.062675][ C3] Possible interrupt unsafe locking scenario: [ 28.062675][ C3] [ 28.063049][ C3] CPU0 CPU1 [ 28.063317][ C3] ---- ---- [ 28.063521][ C3] lock(fs_reclaim); [ 28.063672][ C3] local_irq_disable(); [ 28.063997][ C3] lock(&br->lock); [ 28.064319][ C3] lock(console_owner); [ 28.064584][ C3] [ 28.064804][ C3] lock(&br->lock); [ 28.064960][ C3] [ 28.064960][ C3] *** DEADLOCK *** [ 28.064960][ C3] [ 28.065322][ C3] locks held by swapper/3/0: 1, last CPU#3: [ 28.065581][ C3] #0: ffa0000000280c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 28.066059][ C3] [ 28.066059][ C3] the shortest dependencies between 2nd lock and 1st lock: [ 28.066479][ C3] -> (fs_reclaim){+.+.}-{0:0} { [ 28.066761][ C3] HARDIRQ-ON-W at: [ 28.066918][ C3] __lock_acquire+0x388/0xc20 [ 28.067252][ C3] lock_acquire.part.0+0xd4/0x280 [ 28.067506][ C3] fs_reclaim_acquire+0xd5/0x120 [ 28.067835][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 28.068169][ C3] kthread_create_worker_on_node+0xea/0x210 [ 28.068533][ C3] workqueue_init+0x2a/0x680 [ 28.068781][ C3] kernel_init_freeable+0x2fe/0x630 [ 28.069154][ C3] kernel_init+0x21/0x150 [ 28.069486][ C3] ret_from_fork+0x474/0x6b0 [ 28.069742][ C3] ret_from_fork_asm+0x11/0x20 [ 28.070062][ C3] SOFTIRQ-ON-W at: [ 28.070213][ C3] __lock_acquire+0x388/0xc20 [ 28.070544][ C3] lock_acquire.part.0+0xd4/0x280 [ 28.070794][ C3] fs_reclaim_acquire+0xd5/0x120 [ 28.071119][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 28.071502][ C3] kthread_create_worker_on_node+0xea/0x210 [ 28.071874][ C3] workqueue_init+0x2a/0x680 [ 28.072123][ C3] kernel_init_freeable+0x2fe/0x630 [ 28.072508][ C3] kernel_init+0x21/0x150 [ 28.072837][ C3] ret_from_fork+0x474/0x6b0 [ 28.073086][ C3] ret_from_fork_asm+0x11/0x20 [ 28.073416][ C3] INITIAL USE at: [ 28.073567][ C3] __lock_acquire+0x388/0xc20 [ 28.073892][ C3] lock_acquire.part.0+0xd4/0x280 [ 28.074143][ C3] fs_reclaim_acquire+0xd5/0x120 [ 28.074471][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 28.074849][ C3] kthread_create_worker_on_node+0xea/0x210 [ 28.075219][ C3] workqueue_init+0x2a/0x680 [ 28.075474][ C3] kernel_init_freeable+0x2fe/0x630 [ 28.075795][ C3] kernel_init+0x21/0x150 [ 28.076043][ C3] ret_from_fork+0x474/0x6b0 [ 28.076370][ C3] ret_from_fork_asm+0x11/0x20 [ 28.076699][ C3] } [ 28.076805][ C3] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 28.077179][ C3] ... acquired at: [ 28.077335][ C3] __lock_acquire+0x518/0xc20 [ 28.077622][ C3] lock_acquire.part.0+0xd4/0x280 [ 28.077821][ C3] fs_reclaim_acquire+0xd5/0x120 [ 28.078090][ C3] __kmalloc_noprof+0xd3/0x760 [ 28.078291][ C3] alloc_buf.isra.0+0x4b/0x260 [ 28.078569][ C3] put_chars+0x1e1/0x2f0 [ 28.078768][ C3] hvc_console_print+0x292/0x780 [ 28.079041][ C3] console_emit_next_record+0x22f/0x4c0 [ 28.079244][ C3] console_flush_one_record+0x46f/0x710 [ 28.079517][ C3] console_unlock+0xee/0x1f0 [ 28.079712][ C3] vprintk_emit+0x37c/0x3e0 [ 28.079980][ C3] dev_vprintk_emit+0x27f/0x2c0 [ 28.080186][ C3] dev_printk_emit+0xb9/0xee [ 28.080463][ C3] _dev_info+0xe2/0x116 [ 28.080613][ C3] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 28.080934][ C3] cmos_do_probe+0x73b/0x98a [ 28.081135][ C3] platform_probe+0xfe/0x1f0 [ 28.081414][ C3] call_driver_probe+0x61/0x1c0 [ 28.081614][ C3] really_probe+0x199/0x760 [ 28.081885][ C3] __driver_probe_device+0x24f/0x440 [ 28.082085][ C3] driver_probe_device+0x4a/0xf0 [ 28.082360][ C3] __driver_attach+0x1b8/0x540 [ 28.082561][ C3] bus_for_each_dev+0x130/0x1e0 [ 28.082832][ C3] bus_add_driver+0x2c8/0x530 [ 28.083035][ C3] driver_register+0x1a3/0x390 [ 28.083282][ C3] __platform_driver_probe+0x13f/0x270 [ 28.083457][ C3] cmos_init+0x31/0x40 [ 28.083585][ C3] do_one_initcall+0x124/0x4f0 [ 28.083851][ C3] kernel_init_freeable+0x596/0x630 [ 28.084048][ C3] kernel_init+0x21/0x150 [ 28.084325][ C3] ret_from_fork+0x474/0x6b0 [ 28.084528][ C3] ret_from_fork_asm+0x11/0x20 [ 28.084802][ C3] [ 28.084904][ C3] -> (console_owner){....}-{0:0} { [ 28.085180][ C3] INITIAL USE at: [ 28.085330][ C3] } [ 28.085434][ C3] ... key at: [] console_owner_dep_map+0x0/0x60 [ 28.085807][ C3] ... acquired at: [ 28.085955][ C3] __lock_acquire+0x518/0xc20 [ 28.086227][ C3] lock_acquire.part.0+0xd4/0x280 [ 28.086426][ C3] console_lock_spinning_enable+0x5c/0x60 [ 28.086746][ C3] console_emit_next_record+0x1d1/0x4c0 [ 28.087014][ C3] console_flush_one_record+0x46f/0x710 [ 28.087211][ C3] console_unlock+0xee/0x1f0 [ 28.087489][ C3] vprintk_emit+0x37c/0x3e0 [ 28.087690][ C3] _printk+0xc7/0x100 [ 28.087839][ C3] br_set_state+0x22f/0x430 [bridge] [ 28.088120][ C3] br_init_port+0xc4/0x200 [bridge] [ 28.088325][ C3] br_stp_enable_port+0x12/0x50 [bridge] [ 28.088657][ C3] br_add_if+0xde2/0x1320 [bridge] [ 28.088934][ C3] do_set_master+0x357/0x580 [ 28.089137][ C3] do_setlink.isra.0+0xa31/0x2750 [ 28.089407][ C3] rtnl_newlink+0x9f1/0x11c0 [ 28.089604][ C3] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 28.089872][ C3] netlink_rcv_skb+0x14e/0x3a0 [ 28.090070][ C3] netlink_unicast+0x486/0x750 [ 28.090335][ C3] netlink_sendmsg+0x735/0xc60 [ 28.090535][ C3] ____sys_sendmsg+0x415/0x880 [ 28.090805][ C3] ___sys_sendmsg+0x14e/0x1d0 [ 28.091005][ C3] __sys_sendmsg+0x12c/0x1d0 [ 28.091280][ C3] do_syscall_64+0xff/0x530 [ 28.091524][ C3] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 28.091843][ C3] [ 28.091942][ C3] -> (&br->lock){+.-.}-{3:3} { [ 28.092144][ C3] HARDIRQ-ON-W at: [ 28.092374][ C3] __lock_acquire+0x388/0xc20 [ 28.092620][ C3] lock_acquire.part.0+0xd4/0x280 [ 28.092941][ C3] _raw_spin_lock_bh+0x38/0x50 [ 28.093259][ C3] br_set_mac_address+0xe6/0x3b0 [bridge] [ 28.093577][ C3] netif_set_mac_address+0x282/0x410 [ 28.093901][ C3] do_setlink.isra.0+0x706/0x2750 [ 28.094224][ C3] rtnl_newlink+0x9f1/0x11c0 [ 28.094483][ C3] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 28.094803][ C3] netlink_rcv_skb+0x14e/0x3a0 [ 28.095126][ C3] netlink_unicast+0x486/0x750 [ 28.095383][ C3] netlink_sendmsg+0x735/0xc60 [ 28.095706][ C3] ____sys_sendmsg+0x415/0x880 [ 28.095956][ C3] ___sys_sendmsg+0x14e/0x1d0 [ 28.096289][ C3] __sys_sendmsg+0x12c/0x1d0 [ 28.096615][ C3] do_syscall_64+0xff/0x530 [ 28.096864][ C3] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 28.097244][ C3] IN-SOFTIRQ-W at: [ 28.097400][ C3] __lock_acquire+0x388/0xc20 [ 28.097725][ C3] lock_acquire.part.0+0xd4/0x280 [ 28.098050][ C3] _raw_spin_lock+0x33/0x40 [ 28.098299][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.098665][ C3] call_timer_fn+0x160/0x4d0 [ 28.098992][ C3] __run_timers+0x68f/0xaa0 [ 28.099248][ C3] run_timer_softirq+0xf0/0x160 [ 28.099577][ C3] handle_softirqs+0x1d3/0x900 [ 28.099906][ C3] __irq_exit_rcu+0x145/0x1c0 [ 28.100160][ C3] irq_exit_rcu+0xe/0x30 [ 28.100484][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 28.100868][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 28.101177][ C3] pv_native_safe_halt+0xf/0x10 [ 28.101510][ C3] default_idle+0x9/0x10 [ 28.101833][ C3] default_idle_call+0x6e/0xb0 [ 28.102090][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 28.102475][ C3] do_idle+0xd8/0x190 [ 28.102674][ C3] cpu_startup_entry+0x53/0x70 [ 28.103003][ C3] start_secondary+0x204/0x2b0 [ 28.103331][ C3] common_startup_64+0x13e/0x148 [ 28.103589][ C3] INITIAL USE at: [ 28.103814][ C3] __lock_acquire+0x388/0xc20 [ 28.104069][ C3] lock_acquire.part.0+0xd4/0x280 [ 28.104398][ C3] _raw_spin_lock_bh+0x38/0x50 [ 28.104724][ C3] br_set_mac_address+0xe6/0x3b0 [bridge] [ 28.105039][ C3] netif_set_mac_address+0x282/0x410 [ 28.105369][ C3] do_setlink.isra.0+0x706/0x2750 [ 28.105693][ C3] rtnl_newlink+0x9f1/0x11c0 [ 28.105946][ C3] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 28.106272][ C3] netlink_rcv_skb+0x14e/0x3a0 [ 28.106537][ C3] netlink_unicast+0x486/0x750 [ 28.106863][ C3] netlink_sendmsg+0x735/0xc60 [ 28.107110][ C3] ____sys_sendmsg+0x415/0x880 [ 28.107364][ C3] ___sys_sendmsg+0x14e/0x1d0 [ 28.107695][ C3] __sys_sendmsg+0x12c/0x1d0 [ 28.108017][ C3] do_syscall_64+0xff/0x530 [ 28.108267][ C3] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 28.108650][ C3] } [ 28.108750][ C3] ... key at: [] __key.7+0x0/0x40 [bridge] [ 28.109128][ C3] ... acquired at: [ 28.109276][ C3] mark_lock+0x1d7/0xa00 [ 28.109554][ C3] mark_usage+0x42/0x170 [ 28.109753][ C3] __lock_acquire+0x388/0xc20 [ 28.110019][ C3] lock_acquire.part.0+0xd4/0x280 [ 28.110219][ C3] _raw_spin_lock+0x33/0x40 [ 28.110520][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.110780][ C3] call_timer_fn+0x160/0x4d0 [ 28.111050][ C3] __run_timers+0x68f/0xaa0 [ 28.111249][ C3] run_timer_softirq+0xf0/0x160 [ 28.111525][ C3] handle_softirqs+0x1d3/0x900 [ 28.111724][ C3] __irq_exit_rcu+0x145/0x1c0 [ 28.111997][ C3] irq_exit_rcu+0xe/0x30 [ 28.112198][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 28.112524][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 28.112840][ C3] pv_native_safe_halt+0xf/0x10 [ 28.113038][ C3] default_idle+0x9/0x10 [ 28.113315][ C3] default_idle_call+0x6e/0xb0 [ 28.113525][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 28.113845][ C3] do_idle+0xd8/0x190 [ 28.113993][ C3] cpu_startup_entry+0x53/0x70 [ 28.114260][ C3] start_secondary+0x204/0x2b0 [ 28.114455][ C3] common_startup_64+0x13e/0x148 [ 28.114728][ C3] [ 28.114829][ C3] [ 28.114829][ C3] stack backtrace: [ 28.115148][ C3] CPU: 3 UID: 0 PID: 0 Comm: swapper/3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 28.115154][ C3] Tainted: [W]=WARN [ 28.115155][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 28.115157][ C3] Call Trace: [ 28.115159][ C3] [ 28.115161][ C3] dump_stack_lvl+0x6f/0xa0 [ 28.115167][ C3] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 28.115171][ C3] mark_lock_irq+0x989/0x9c0 [ 28.115176][ C3] mark_lock+0x1d7/0xa00 [ 28.115179][ C3] mark_usage+0x42/0x170 [ 28.115181][ C3] __lock_acquire+0x388/0xc20 [ 28.115185][ C3] lock_acquire.part.0+0xd4/0x280 [ 28.115187][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.115202][ C3] ? rcu_is_watching+0x16/0xd0 [ 28.115206][ C3] ? lock_acquire+0x13c/0x160 [ 28.115209][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.115221][ C3] _raw_spin_lock+0x33/0x40 [ 28.115224][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.115235][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 28.115246][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.115257][ C3] call_timer_fn+0x160/0x4d0 [ 28.115261][ C3] ? detach_if_pending+0x1d0/0x1d0 [ 28.115264][ C3] ? debug_object_active_state+0x430/0x430 [ 28.115269][ C3] ? find_held_lock+0x2b/0x80 [ 28.115272][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 28.115274][ C3] ? rcu_is_watching+0x16/0xd0 [ 28.115278][ C3] __run_timers+0x68f/0xaa0 [ 28.115280][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 28.115292][ C3] ? __bpf_trace_itimer_expire+0x10/0x10 [ 28.115295][ C3] ? __lock_acquire+0x518/0xc20 [ 28.115299][ C3] ? __rwlock_init+0x150/0x150 [ 28.115303][ C3] run_timer_softirq+0xf0/0x160 [ 28.115305][ C3] ? __run_timers+0xaa0/0xaa0 [ 28.115308][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 28.115311][ C3] ? rcu_is_watching+0x16/0xd0 [ 28.115314][ C3] handle_softirqs+0x1d3/0x900 [ 28.115317][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 28.115320][ C3] ? _local_bh_enable+0xc0/0xc0 [ 28.115323][ C3] __irq_exit_rcu+0x145/0x1c0 [ 28.115325][ C3] irq_exit_rcu+0xe/0x30 [ 28.115327][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 28.115330][ C3] [ 28.115331][ C3] [ 28.115332][ C3] ? lockdep_hardirqs_on+0x91/0x130 [ 28.115335][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 28.115338][ C3] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 28.115346][ 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 [ 28.115349][ C3] RSP: 0018:ffa0000000167e00 EFLAGS: 00000282 [ 28.115352][ C3] RAX: 00000000000caad1 RBX: ff11000001c88040 RCX: ffffffff860f0307 [ 28.115354][ C3] RDX: ff11000001c88040 RSI: ffffffff88e38b01 RDI: ffffffff8888d8e0 [ 28.115355][ C3] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 28.115357][ C3] R10: 0000000000000003 R11: 0000000000000001 R12: 1ff400000002cfc3 [ 28.115358][ C3] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 28.115361][ C3] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 28.115364][ C3] default_idle+0x9/0x10 [ 28.115367][ C3] default_idle_call+0x6e/0xb0 [ 28.115369][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 28.115372][ C3] ? arch_cpu_idle_exit+0x40/0x40 [ 28.115374][ C3] ? mark_tsc_async_resets+0x30/0x30 [ 28.115377][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 28.115379][ C3] ? rcu_is_watching+0x16/0xd0 [ 28.115382][ C3] do_idle+0xd8/0x190 [ 28.115384][ C3] cpu_startup_entry+0x53/0x70 [ 28.115386][ C3] start_secondary+0x204/0x2b0 [ 28.115389][ C3] ? set_cpu_sibling_map+0x2130/0x2130 [ 28.115392][ C3] common_startup_64+0x13e/0x148 [ 28.115398][ C3] [ 66.503210][ T70] vx2: left allmulticast mode [ 66.503226][ T70] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 66.503229][ T70] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 70, name: kworker/u16:1 [ 66.503230][ T70] preempt_count: 1, expected: 0 [ 66.503231][ T70] RCU nest depth: 0, expected: 0 [ 66.503232][ T70] INFO: lockdep is turned off. [ 66.503233][ T70] irq event stamp: 26362 [ 66.503234][ T70] hardirqs last enabled at (26361): [] _raw_spin_unlock_irq+0x28/0x50 [ 66.503242][ T70] hardirqs last disabled at (26362): [] __schedule+0x1246/0x20e0 [ 66.503244][ T70] softirqs last enabled at (26200): [] __fib6_clean_all+0xff/0x290 [ 66.503247][ T70] softirqs last disabled at (26194): [] __fib6_clean_all+0xd9/0x290 [ 66.503250][ T70] Preemption disabled at: [ 66.503250][ T70] [] vprintk_emit+0x31b/0x3e0 [ 66.503258][ T70] CPU: 0 UID: 0 PID: 70 Comm: kworker/u16:1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 66.503262][ T70] Tainted: [W]=WARN [ 66.503262][ T70] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 66.503264][ T70] Workqueue: netns cleanup_net [ 66.503270][ T70] Call Trace: [ 66.503271][ T70] [ 66.503273][ T70] dump_stack_lvl+0x6f/0xa0 [ 66.503278][ T70] ? vprintk_emit+0x31b/0x3e0 [ 66.503280][ T70] __might_resched.cold+0x1fe/0x2c1 [ 66.503284][ T70] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 66.503287][ T70] ? rcu_is_watching+0x16/0xd0 [ 66.503291][ T70] __kmalloc_noprof+0x443/0x760 [ 66.503294][ T70] ? __rwlock_init+0x150/0x150 [ 66.503297][ T70] ? alloc_buf.isra.0+0x4b/0x260 [ 66.503301][ T70] ? do_raw_spin_unlock+0x59/0x250 [ 66.503302][ T70] alloc_buf.isra.0+0x4b/0x260 [ 66.503304][ T70] put_chars+0x1e1/0x2f0 [ 66.503306][ T70] ? desc_read_finalized_seq+0x79/0x120 [ 66.503308][ T70] ? __send_to_port+0x420/0x420 [ 66.503309][ T70] ? console_prepend_replay+0x20/0x20 [ 66.503312][ T70] hvc_console_print+0x292/0x780 [ 66.503315][ T70] ? hvc_write+0x3a0/0x3a0 [ 66.503316][ T70] ? do_raw_spin_lock+0x131/0x280 [ 66.503318][ T70] ? __rwlock_init+0x150/0x150 [ 66.503319][ T70] ? rcu_is_watching+0x16/0xd0 [ 66.503321][ T70] ? rcu_is_watching+0x16/0xd0 [ 66.503323][ T70] ? lock_acquire+0x13c/0x160 [ 66.503325][ T70] console_emit_next_record+0x22f/0x4c0 [ 66.503327][ T70] ? devkmsg_read+0x4b0/0x4b0 [ 66.503329][ T70] ? rcu_is_watching+0x16/0xd0 [ 66.503331][ T70] ? rcu_is_watching+0x16/0xd0 [ 66.503332][ T70] ? rcu_is_watching+0x16/0xd0 [ 66.503334][ T70] ? lock_acquire+0x13c/0x160 [ 66.503336][ T70] ? console_flush_one_record+0x111/0x710 [ 66.503338][ T70] console_flush_one_record+0x46f/0x710 [ 66.503340][ T70] ? console_emit_next_record+0x4c0/0x4c0 [ 66.503343][ T70] console_unlock+0xee/0x1f0 [ 66.503344][ T70] ? lock_acquire+0x13c/0x160 [ 66.503346][ T70] ? console_flush_one_record+0x710/0x710 [ 66.503347][ T70] ? rcu_is_watching+0x16/0xd0 [ 66.503349][ T70] ? lock_release+0x184/0x1f0 [ 66.503350][ T70] ? lock_acquire+0x60/0x160 [ 66.503352][ T70] ? __rcu_read_lock+0x30/0x70 [ 66.503354][ T70] ? __down_trylock_console_sem+0x5e/0xa0 [ 66.503356][ T70] ? vprintk_emit+0x320/0x3e0 [ 66.503358][ T70] vprintk_emit+0x37c/0x3e0 [ 66.503360][ T70] ? wake_up_klogd_work_func+0x90/0x90 [ 66.503362][ T70] ? process_one_work+0xe3e/0x1560 [ 66.503365][ T70] ? kthread+0x367/0x460 [ 66.503367][ T70] ? ret_from_fork+0x474/0x6b0 [ 66.503369][ T70] ? ret_from_fork_asm+0x11/0x20 [ 66.503371][ T70] _printk+0xc7/0x100 [ 66.503374][ T70] ? snapshot_read.cold+0x21/0x21 [ 66.503376][ T70] ? netdev_name+0x43/0x60 [ 66.503379][ T70] ? __netdev_printk+0x17f/0x1d0 [ 66.503381][ T70] netdev_info+0xe2/0x116 [ 66.503383][ T70] ? netdev_notice+0x120/0x120 [ 66.503384][ T70] ? rcu_is_watching+0x16/0xd0 [ 66.503386][ T70] ? __up_read+0x355/0x9e0 [ 66.503387][ T70] ? trace_hardirqs_on+0x36/0x40 [ 66.503390][ T70] ? down_write_nested+0x200/0x200 [ 66.503391][ T70] ? mutex_is_locked+0x1c/0x50 [ 66.503394][ T70] netif_set_allmulti.cold+0x7c/0x166 [ 66.503397][ T70] dev_set_allmulti+0xa2/0x150 [ 66.503400][ T70] del_nbp+0xb7/0xc10 [bridge] [ 66.503413][ T70] br_dev_delete+0x9d/0x1e0 [bridge] [ 66.503420][ T70] br_net_exit_rtnl+0xb8/0x110 [bridge] [ 66.503427][ T70] ops_undo_list+0x5be/0x8f0 [ 66.503429][ T70] ? rtnl_net_dumpid_one+0x2d0/0x2d0 [ 66.503431][ T70] ? idr_destroy+0x121/0x250 [ 66.503435][ T70] cleanup_net+0x431/0x890 [ 66.503436][ T70] ? net_passive_dec+0x230/0x230 [ 66.503438][ T70] ? lock_acquire+0x13c/0x160 [ 66.503440][ T70] ? rcu_is_watching+0x16/0xd0 [ 66.503442][ T70] process_one_work+0xe3e/0x1560 [ 66.503445][ T70] ? __queue_delayed_work+0x470/0x470 [ 66.503447][ T70] ? find_held_lock+0x2b/0x80 [ 66.503449][ T70] ? __rwlock_init+0x150/0x150 [ 66.503451][ T70] worker_thread+0x4f1/0xd60 [ 66.503453][ T70] ? rescuer_thread+0x1340/0x1340 [ 66.503455][ T70] ? __kthread_parkme+0xbd/0x210 [ 66.503457][ T70] ? rescuer_thread+0x1340/0x1340 [ 66.503459][ T70] ? rescuer_thread+0x1340/0x1340 [ 66.503460][ T70] kthread+0x367/0x460 [ 66.503462][ T70] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 66.503463][ T70] ? kthread_affine_preferred+0x4c0/0x4c0 [ 66.503465][ T70] ret_from_fork+0x474/0x6b0 [ 66.503472][ T70] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 66.503475][ T70] ? __switch_to+0x5a3/0xe00 [ 66.503478][ T70] ? kthread_affine_preferred+0x4c0/0x4c0 [ 66.503480][ T70] ret_from_fork_asm+0x11/0x20 [ 66.503483][ T70] [ 66.522948][ T70] vx2: left promiscuous mode [ 66.523175][ T70] br2: port 2(vx2) entered disabled state [ 66.525634][ T70] w1: left allmulticast mode [ 66.525891][ T70] w1: left promiscuous mode [ 66.527731][ T70] br2: port 1(w1) entered disabled state [ 66.611389][ T70] vx2: left allmulticast mode [ 66.611578][ T70] vx2: left promiscuous mode [ 66.611790][ T70] br2: port 2(vx2) entered disabled state [ 66.613224][ T70] w1: left allmulticast mode [ 66.613377][ T70] w1: left promiscuous mode [ 66.613573][ T70] br2: port 1(w1) entered disabled state [ 67.042002][ T577] br1: port 3(veth2) entered disabled state [ 67.058708][ T578] veth2: left allmulticast mode [ 67.058884][ T578] veth2: left promiscuous mode [ 67.059105][ T578] br1: port 3(veth2) entered disabled state [ 67.102635][ T580] br1: port 2(veth1) entered disabled state [ 67.117928][ T581] veth1: left allmulticast mode [ 67.118986][ T581] veth1: left promiscuous mode [ 67.119205][ T581] br1: port 2(veth1) entered disabled state [ 67.138805][ T582] vx1: left allmulticast mode [ 67.139009][ T582] vx1: left promiscuous mode [ 67.139664][ T582] br1: port 1(vx1) entered disabled state