[ 11.942581][ T307] br: port 1(veth1) entered blocking state [ 11.942659][ T307] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.942661][ T307] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 307, name: ip [ 11.942662][ T307] preempt_count: 1, expected: 0 [ 11.942663][ T307] RCU nest depth: 0, expected: 0 [ 11.942665][ T307] locks held by ip/307: 5, last CPU#2: [ 11.942667][ T307] #0: ffffffff9dad2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 11.942679][ T307] #1: ffffffff9d369cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.942685][ T307] #2: ffffffff9d369d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.942689][ T307] #3: ffffffff9d289660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.942692][ T307] #4: ffffffff9d289560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.942696][ T307] irq event stamp: 22142 [ 11.942697][ T307] hardirqs last enabled at (22141): [] __down_trylock_console_sem+0x86/0xa0 [ 11.942699][ T307] hardirqs last disabled at (22142): [] console_emit_next_record+0x3d4/0x4c0 [ 11.942701][ T307] softirqs last enabled at (22072): [] addrconf_permanent_addr+0x2b1/0x9c0 [ 11.942707][ T307] softirqs last disabled at (22070): [] addrconf_permanent_addr+0x108/0x9c0 [ 11.942710][ T307] Preemption disabled at: [ 11.942711][ T307] [] vprintk_emit+0x31b/0x3e0 [ 11.942717][ T307] CPU: 2 UID: 0 PID: 307 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.942720][ T307] Tainted: [W]=WARN [ 11.942721][ T307] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.942723][ T307] Call Trace: [ 11.942724][ T307] [ 11.942726][ T307] dump_stack_lvl+0x6f/0xa0 [ 11.942732][ T307] ? vprintk_emit+0x31b/0x3e0 [ 11.942734][ T307] __might_resched.cold+0x1fe/0x2c1 [ 11.942738][ T307] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.942742][ T307] ? __kmalloc_noprof+0xdb/0x760 [ 11.942748][ T307] __kmalloc_noprof+0x443/0x760 [ 11.942750][ T307] ? alloc_buf.isra.0+0x4b/0x260 [ 11.942756][ T307] ? do_raw_spin_unlock+0x59/0x250 [ 11.942758][ T307] alloc_buf.isra.0+0x4b/0x260 [ 11.942762][ T307] put_chars+0x1e1/0x2f0 [ 11.942764][ T307] ? __send_to_port+0x420/0x420 [ 11.942768][ T307] ? validate_chain+0x34a/0xc20 [ 11.942772][ T307] hvc_console_print+0x292/0x780 [ 11.942775][ T307] ? mark_usage+0x61/0x170 [ 11.942777][ T307] ? __lock_acquire+0x518/0xc20 [ 11.942778][ T307] ? __lock_acquire+0x518/0xc20 [ 11.942782][ T307] ? hvc_write+0x3a0/0x3a0 [ 11.942784][ T307] ? console_emit_next_record+0x1df/0x4c0 [ 11.942787][ T307] ? rcu_is_watching+0x16/0xd0 [ 11.942791][ T307] ? lock_acquire+0x13c/0x160 [ 11.942795][ T307] console_emit_next_record+0x22f/0x4c0 [ 11.942798][ T307] ? devkmsg_read+0x4b0/0x4b0 [ 11.942800][ T307] ? console_flush_one_record+0x106/0x710 [ 11.942803][ T307] ? rcu_is_watching+0x16/0xd0 [ 11.942805][ T307] ? lock_acquire+0x13c/0x160 [ 11.942809][ T307] console_flush_one_record+0x46f/0x710 [ 11.942813][ T307] ? console_emit_next_record+0x4c0/0x4c0 [ 11.942815][ T307] ? __lock_acquire+0x518/0xc20 [ 11.942820][ T307] console_unlock+0xee/0x1f0 [ 11.942823][ T307] ? console_flush_one_record+0x710/0x710 [ 11.942825][ T307] ? rcu_is_watching+0x16/0xd0 [ 11.942827][ T307] ? lock_acquire+0x60/0x160 [ 11.942830][ T307] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.942832][ T307] ? vprintk_emit+0x320/0x3e0 [ 11.942835][ T307] vprintk_emit+0x37c/0x3e0 [ 11.942839][ T307] ? wake_up_klogd_work_func+0x90/0x90 [ 11.942841][ T307] ? __lock_release.isra.0+0x69/0x1a0 [ 11.942843][ T307] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 11.942847][ T307] ? mark_held_locks+0x40/0x70 [ 11.942850][ T307] _printk+0xc7/0x100 [ 11.942854][ T307] ? snapshot_read.cold+0x21/0x21 [ 11.942857][ T307] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 11.942870][ T307] ? do_setlink.isra.0+0xa31/0x2750 [ 11.942871][ T307] ? rtnl_newlink+0x9f1/0x11c0 [ 11.942872][ T307] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.942876][ T307] br_set_state+0x22f/0x430 [bridge] [ 11.942886][ T307] br_init_port+0xc4/0x200 [bridge] [ 11.942894][ T307] new_nbp+0x39c/0x580 [bridge] [ 11.942903][ T307] br_add_if+0x212/0x1320 [bridge] [ 11.942910][ T307] ? netif_set_allmulti+0x250/0x250 [ 11.942914][ T307] ? kernel_text_address+0x149/0x170 [ 11.942917][ T307] ? __kernel_text_address+0x12/0x30 [ 11.942920][ T307] do_set_master+0x357/0x580 [ 11.942923][ T307] ? netif_change_flags+0xfe/0x190 [ 11.942926][ T307] do_setlink.isra.0+0xa31/0x2750 [ 11.942930][ T307] ? stack_trace_save+0x93/0xc0 [ 11.942934][ T307] ? rtnl_link_get_size+0x350/0x350 [ 11.942935][ T307] ? rcu_read_lock_any_held+0x66/0x90 [ 11.942938][ T307] ? stack_depot_save_flags+0x38e/0x790 [ 11.942941][ T307] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.942944][ T307] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.942946][ T307] ? validate_chain+0x38b/0xc20 [ 11.942948][ T307] ? kasan_save_stack+0x3d/0x50 [ 11.942952][ T307] ? kasan_save_stack+0x2f/0x50 [ 11.942953][ T307] ? kasan_save_track+0x14/0x30 [ 11.942956][ T307] ? __lock_acquire+0x518/0xc20 [ 11.942958][ T307] ? netlink_seq_next+0x4e/0x60 [ 11.942961][ T307] ? ___sys_sendmsg+0xb0/0x1d0 [ 11.942965][ T307] ? lock_acquire.part.0+0xd4/0x280 [ 11.942967][ T307] ? rtnl_newlink+0x9a8/0x11c0 [ 11.942970][ T307] ? rcu_is_watching+0x16/0xd0 [ 11.942972][ T307] ? lock_acquire+0x13c/0x160 [ 11.942973][ T307] ? rcu_is_watching+0x16/0xd0 [ 11.942975][ T307] ? rcu_is_watching+0x16/0xd0 [ 11.942977][ T307] ? trace_contention_end+0xb3/0x180 [ 11.942981][ T307] ? __mutex_lock+0x1db/0x1ea0 [ 11.942983][ T307] ? __mutex_lock+0x9a3/0x1ea0 [ 11.942985][ T307] ? rtnl_newlink+0x9a8/0x11c0 [ 11.942988][ T307] ? ww_mutex_lock+0x160/0x160 [ 11.942990][ T307] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.942994][ T307] ? __rtnl_newlink+0x3fa/0xa50 [ 11.943000][ T307] rtnl_newlink+0x9f1/0x11c0 [ 11.943005][ T307] ? rtnl_bridge_getlink+0x850/0x850 [ 11.943007][ T307] ? __lock_acquire+0x518/0xc20 [ 11.943011][ T307] ? lock_acquire.part.0+0xd4/0x280 [ 11.943013][ T307] ? find_held_lock+0x2b/0x80 [ 11.943015][ T307] ? rtnl_bridge_getlink+0x850/0x850 [ 11.943017][ T307] ? __lock_release.isra.0+0x69/0x1a0 [ 11.943020][ T307] ? rtnl_bridge_getlink+0x850/0x850 [ 11.943022][ T307] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.943025][ T307] ? rtnl_link_fill+0x920/0x920 [ 11.943027][ T307] ? __lock_acquire+0x518/0xc20 [ 11.943031][ T307] ? lock_acquire.part.0+0xd4/0x280 [ 11.943033][ T307] ? find_held_lock+0x2b/0x80 [ 11.943036][ T307] netlink_rcv_skb+0x14e/0x3a0 [ 11.943038][ T307] ? rtnl_link_fill+0x920/0x920 [ 11.943041][ T307] ? netlink_ack+0xcf0/0xcf0 [ 11.943047][ T307] ? netlink_deliver_tap+0xc5/0x330 [ 11.943048][ T307] ? netlink_deliver_tap+0x13c/0x330 [ 11.943052][ T307] netlink_unicast+0x486/0x750 [ 11.943056][ T307] ? netlink_attachskb+0x810/0x810 [ 11.943059][ T307] ? __lock_acquire+0x518/0xc20 [ 11.943063][ T307] netlink_sendmsg+0x735/0xc60 [ 11.943066][ T307] ? netlink_unicast+0x750/0x750 [ 11.943070][ T307] ? __might_fault+0x97/0x140 [ 11.943076][ T307] ____sys_sendmsg+0x415/0x880 [ 11.943078][ T307] ? copy_msghdr_from_user+0x279/0x420 [ 11.943080][ T307] ? get_timestamp.constprop.0+0x390/0x390 [ 11.943082][ T307] ? move_addr_to_kernel+0x40/0x40 [ 11.943089][ T307] ___sys_sendmsg+0x14e/0x1d0 [ 11.943092][ T307] ? copy_msghdr_from_user+0x420/0x420 [ 11.943107][ T307] __sys_sendmsg+0x12c/0x1d0 [ 11.943110][ T307] ? __sys_sendmsg_sock+0x20/0x20 [ 11.943116][ T307] ? rcu_is_watching+0x16/0xd0 [ 11.943120][ T307] do_syscall_64+0xff/0x530 [ 11.943122][ T307] ? exc_page_fault+0xee/0x100 [ 11.943125][ T307] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.943128][ T307] RIP: 0033:0x7fc55a85354e [ 11.943131][ T307] 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.943133][ T307] RSP: 002b:00007ffe0ce04000 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.943136][ T307] RAX: ffffffffffffffda RBX: 0000000000000005 RCX: 00007fc55a85354e [ 11.943137][ T307] RDX: 0000000000000000 RSI: 00007ffe0ce040b0 RDI: 0000000000000005 [ 11.943138][ T307] RBP: 00007ffe0ce04010 R08: 0000000000000000 R09: 0000000000000000 [ 11.943139][ T307] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a9109ce [ 11.943140][ T307] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffe0ce04770 [ 11.943146][ T307] [ 11.992266][ T307] br: port 1(veth1) entered disabled state [ 11.992697][ T307] veth1: entered allmulticast mode [ 11.995865][ T307] veth1: entered promiscuous mode [ 12.000283][ T38] br: port 1(veth1) entered blocking state [ 12.000681][ T38] br: port 1(veth1) entered forwarding state [ 12.006042][ T307] ip (307) used greatest stack depth: 23336 bytes left [ 12.048303][ T312] br: port 2(veth3) entered blocking state [ 12.048700][ T312] br: port 2(veth3) entered disabled state [ 12.049062][ T312] veth3: entered allmulticast mode [ 12.050631][ T312] veth3: entered promiscuous mode [ 12.052877][ T38] br: port 2(veth3) entered blocking state [ 12.053203][ T38] br: port 2(veth3) entered forwarding state [ 12.097733][ T317] br: port 3(veth4) entered blocking state [ 12.098086][ T317] br: port 3(veth4) entered disabled state [ 12.098468][ T317] veth4: entered allmulticast mode [ 12.100029][ T317] veth4: entered promiscuous mode [ 12.108646][ T38] br: port 3(veth4) entered blocking state [ 12.108983][ T38] br: port 3(veth4) entered forwarding state [ 27.496764][ C2] [ 27.496781][ C2] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 27.496784][ C2] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/2 [ 27.496785][ C2] preempt_count: 104, expected: 0 [ 27.496787][ C2] RCU nest depth: 0, expected: 0 [ 27.496788][ C2] INFO: lockdep is turned off. [ 27.496789][ C2] irq event stamp: 798836 [ 27.496790][ C2] hardirqs last enabled at (798836): [] _raw_spin_unlock_irq+0x28/0x50 [ 27.496799][ C2] hardirqs last disabled at (798835): [] _raw_spin_lock_irq+0x4a/0x50 [ 27.496801][ C2] softirqs last enabled at (798826): [] handle_softirqs+0x67c/0x900 [ 27.496805][ C2] softirqs last disabled at (798833): [] __irq_exit_rcu+0x145/0x1c0 [ 27.496807][ C2] Preemption disabled at: [ 27.496808][ C2] [<0000000000000000>] 0x0 [ 27.496815][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.496818][ C2] Tainted: [W]=WARN [ 27.496819][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.496821][ C2] Call Trace: [ 27.496823][ C2] [ 27.496825][ C2] dump_stack_lvl+0x6f/0xa0 [ 27.496831][ C2] __might_resched.cold+0x1fe/0x2c1 [ 27.496835][ C2] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 27.496838][ C2] ? __asan_memcpy+0x3c/0x60 [ 27.496841][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.496845][ C2] __kmalloc_noprof+0x443/0x760 [ 27.496848][ C2] ? __rwlock_init+0x150/0x150 [ 27.496851][ C2] ? alloc_buf.isra.0+0x4b/0x260 [ 27.496854][ C2] ? do_raw_spin_unlock+0x59/0x250 [ 27.496856][ C2] alloc_buf.isra.0+0x4b/0x260 [ 27.496858][ C2] put_chars+0x1e1/0x2f0 [ 27.496860][ C2] ? __send_to_port+0x420/0x420 [ 27.496862][ C2] ? console_prepend_replay+0x20/0x20 [ 27.496866][ C2] hvc_console_print+0x292/0x780 [ 27.496869][ C2] ? hvc_write+0x3a0/0x3a0 [ 27.496871][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.496873][ C2] ? lock_acquire+0x13c/0x160 [ 27.496875][ C2] console_emit_next_record+0x22f/0x4c0 [ 27.496877][ C2] ? devkmsg_read+0x4b0/0x4b0 [ 27.496879][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.496881][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.496883][ C2] ? lock_acquire+0x13c/0x160 [ 27.496885][ C2] ? console_flush_one_record+0x111/0x710 [ 27.496886][ C2] console_flush_one_record+0x46f/0x710 [ 27.496889][ C2] ? console_emit_next_record+0x4c0/0x4c0 [ 27.496892][ C2] console_unlock+0xee/0x1f0 [ 27.496893][ C2] ? lock_acquire+0x13c/0x160 [ 27.496895][ C2] ? console_flush_one_record+0x710/0x710 [ 27.496897][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.496898][ C2] ? lock_release+0x184/0x1f0 [ 27.496900][ C2] ? lock_acquire+0x60/0x160 [ 27.496902][ C2] ? __down_trylock_console_sem+0x5e/0xa0 [ 27.496904][ C2] ? vprintk_emit+0x320/0x3e0 [ 27.496906][ C2] vprintk_emit+0x37c/0x3e0 [ 27.496908][ C2] ? wake_up_klogd_work_func+0x90/0x90 [ 27.496910][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.496922][ C2] ? lock_release+0x184/0x1f0 [ 27.496924][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.496930][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.496936][ C2] ? is_module_text_address+0x154/0x250 [ 27.496940][ C2] _printk+0xc7/0x100 [ 27.496943][ C2] ? snapshot_read.cold+0x21/0x21 [ 27.496945][ C2] ? arch_stack_walk+0xd7/0x130 [ 27.496949][ C2] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 27.496951][ C2] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 27.496953][ C2] print_irq_inversion_bug.part.0+0x32/0xc0 [ 27.496956][ C2] mark_lock_irq+0x989/0x9c0 [ 27.496958][ C2] mark_lock+0x1d7/0xa00 [ 27.496960][ C2] mark_usage+0x42/0x170 [ 27.496962][ C2] __lock_acquire+0x388/0xc20 [ 27.496964][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.496966][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.496972][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.496974][ C2] ? lock_acquire+0x13c/0x160 [ 27.496976][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.496982][ C2] _raw_spin_lock+0x33/0x40 [ 27.496984][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.496990][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.496997][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.497003][ C2] call_timer_fn+0x160/0x4d0 [ 27.497005][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 27.497007][ C2] ? debug_object_active_state+0x430/0x430 [ 27.497011][ C2] ? find_held_lock+0x2b/0x80 [ 27.497012][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 27.497014][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.497016][ C2] __run_timers+0x68f/0xaa0 [ 27.497018][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.497025][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.497027][ C2] ? __lock_acquire+0x518/0xc20 [ 27.497031][ C2] ? __rwlock_init+0x150/0x150 [ 27.497033][ C2] run_timer_softirq+0xf0/0x160 [ 27.497035][ C2] ? __run_timers+0xaa0/0xaa0 [ 27.497037][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.497039][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.497041][ C2] handle_softirqs+0x1d3/0x900 [ 27.497043][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 27.497045][ C2] ? _local_bh_enable+0xc0/0xc0 [ 27.497047][ C2] __irq_exit_rcu+0x145/0x1c0 [ 27.497048][ C2] irq_exit_rcu+0xe/0x30 [ 27.497050][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.497053][ C2] [ 27.497054][ C2] [ 27.497054][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 27.497056][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.497059][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.497061][ 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 [ 27.497063][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 27.497066][ C2] RAX: 00000000000c306f RBX: ff11000001bec6c0 RCX: ffffffff9a0f0307 [ 27.497068][ C2] RDX: ff11000001bec6c0 RSI: ffffffff9ce38b01 RDI: ffffffff9c88d8e0 [ 27.497069][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.497069][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 27.497070][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 27.497072][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.497075][ C2] default_idle+0x9/0x10 [ 27.497077][ C2] default_idle_call+0x6e/0xb0 [ 27.497079][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.497080][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 27.497082][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 27.497084][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.497085][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.497087][ C2] do_idle+0xd8/0x190 [ 27.497089][ C2] cpu_startup_entry+0x53/0x70 [ 27.497091][ C2] start_secondary+0x204/0x2b0 [ 27.497093][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 27.497095][ C2] common_startup_64+0x13e/0x148 [ 27.497099][ C2] [ 27.518380][ C2] ======================================================== [ 27.518582][ C2] WARNING: possible irq lock inversion dependency detected [ 27.518826][ C2] 7.2.0-virtme #1 Tainted: G W [ 27.519041][ C2] -------------------------------------------------------- [ 27.519242][ C2] swapper/2/0 just changed the state of lock: [ 27.519458][ C2] ff11000002732e58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.519782][ C2] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 27.520030][ C2] (fs_reclaim){+.+.}-{0:0} [ 27.520033][ C2] [ 27.520033][ C2] [ 27.520033][ C2] and interrupts could create inverse lock ordering between them. [ 27.520033][ C2] [ 27.520619][ C2] [ 27.520619][ C2] other info that might help us debug this: [ 27.520864][ C2] Chain exists of: [ 27.520864][ C2] &br->lock --> console_owner --> fs_reclaim [ 27.520864][ C2] [ 27.521216][ C2] Possible interrupt unsafe locking scenario: [ 27.521216][ C2] [ 27.521424][ C2] CPU0 CPU1 [ 27.521613][ C2] ---- ---- [ 27.521754][ C2] lock(fs_reclaim); [ 27.521907][ C2] local_irq_disable(); [ 27.522077][ C2] lock(&br->lock); [ 27.522291][ C2] lock(console_owner); [ 27.522515][ C2] [ 27.522622][ C2] lock(&br->lock); [ 27.522728][ C2] [ 27.522728][ C2] *** DEADLOCK *** [ 27.522728][ C2] [ 27.522973][ C2] locks held by swapper/2/0: 1, last CPU#2: [ 27.523184][ C2] #0: ffa0000000228c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 27.523513][ C2] [ 27.523513][ C2] the shortest dependencies between 2nd lock and 1st lock: [ 27.523793][ C2] -> (fs_reclaim){+.+.}-{0:0} { [ 27.523940][ C2] HARDIRQ-ON-W at: [ 27.524097][ C2] __lock_acquire+0x388/0xc20 [ 27.524276][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.524495][ C2] fs_reclaim_acquire+0xd5/0x120 [ 27.524672][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 27.524921][ C2] kthread_create_worker_on_node+0xea/0x210 [ 27.525175][ C2] workqueue_init+0x2a/0x680 [ 27.525402][ C2] kernel_init_freeable+0x2fe/0x630 [ 27.525607][ C2] kernel_init+0x21/0x150 [ 27.525829][ C2] ret_from_fork+0x474/0x6b0 [ 27.526048][ C2] ret_from_fork_asm+0x11/0x20 [ 27.526218][ C2] SOFTIRQ-ON-W at: [ 27.526321][ C2] __lock_acquire+0x388/0xc20 [ 27.526494][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.526751][ C2] fs_reclaim_acquire+0xd5/0x120 [ 27.526925][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 27.527178][ C2] kthread_create_worker_on_node+0xea/0x210 [ 27.527434][ C2] workqueue_init+0x2a/0x680 [ 27.527648][ C2] kernel_init_freeable+0x2fe/0x630 [ 27.527853][ C2] kernel_init+0x21/0x150 [ 27.528066][ C2] ret_from_fork+0x474/0x6b0 [ 27.528278][ C2] ret_from_fork_asm+0x11/0x20 [ 27.528451][ C2] INITIAL USE at: [ 27.528554][ C2] __lock_acquire+0x388/0xc20 [ 27.528770][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.528988][ C2] fs_reclaim_acquire+0xd5/0x120 [ 27.529157][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 27.529410][ C2] kthread_create_worker_on_node+0xea/0x210 [ 27.529659][ C2] workqueue_init+0x2a/0x680 [ 27.529877][ C2] kernel_init_freeable+0x2fe/0x630 [ 27.530047][ C2] kernel_init+0x21/0x150 [ 27.530261][ C2] ret_from_fork+0x474/0x6b0 [ 27.530436][ C2] ret_from_fork_asm+0x11/0x20 [ 27.530652][ C2] } [ 27.530724][ C2] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 27.530973][ C2] ... acquired at: [ 27.531080][ C2] __lock_acquire+0x518/0xc20 [ 27.531262][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.531401][ C2] fs_reclaim_acquire+0xd5/0x120 [ 27.531579][ C2] __kmalloc_noprof+0xd3/0x760 [ 27.531722][ C2] alloc_buf.isra.0+0x4b/0x260 [ 27.531901][ C2] put_chars+0x1e1/0x2f0 [ 27.532033][ C2] hvc_console_print+0x292/0x780 [ 27.532217][ C2] console_emit_next_record+0x22f/0x4c0 [ 27.532352][ C2] console_flush_one_record+0x46f/0x710 [ 27.532537][ C2] console_unlock+0xee/0x1f0 [ 27.532669][ C2] vprintk_emit+0x37c/0x3e0 [ 27.532846][ C2] dev_vprintk_emit+0x27f/0x2c0 [ 27.532983][ C2] dev_printk_emit+0xb9/0xee [ 27.533161][ C2] _dev_info+0xe2/0x116 [ 27.533264][ C2] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 27.533484][ C2] cmos_do_probe+0x73b/0x98a [ 27.533619][ C2] platform_probe+0xfe/0x1f0 [ 27.533800][ C2] call_driver_probe+0x61/0x1c0 [ 27.533933][ C2] really_probe+0x199/0x760 [ 27.534115][ C2] __driver_probe_device+0x24f/0x440 [ 27.534251][ C2] driver_probe_device+0x4a/0xf0 [ 27.534436][ C2] __driver_attach+0x1b8/0x540 [ 27.534570][ C2] bus_for_each_dev+0x130/0x1e0 [ 27.534749][ C2] bus_add_driver+0x2c8/0x530 [ 27.534885][ C2] driver_register+0x1a3/0x390 [ 27.535064][ C2] __platform_driver_probe+0x13f/0x270 [ 27.535198][ C2] cmos_init+0x31/0x40 [ 27.535346][ C2] do_one_initcall+0x124/0x4f0 [ 27.535484][ C2] kernel_init_freeable+0x596/0x630 [ 27.535619][ C2] kernel_init+0x21/0x150 [ 27.535752][ C2] ret_from_fork+0x474/0x6b0 [ 27.535947][ C2] ret_from_fork_asm+0x11/0x20 [ 27.536081][ C2] [ 27.536197][ C2] -> (console_owner){....}-{0:0} { [ 27.536336][ C2] INITIAL USE at: [ 27.536442][ C2] } [ 27.536554][ C2] ... key at: [] console_owner_dep_map+0x0/0x60 [ 27.536756][ C2] ... acquired at: [ 27.536902][ C2] __lock_acquire+0x518/0xc20 [ 27.537034][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.537214][ C2] console_lock_spinning_enable+0x5c/0x60 [ 27.537387][ C2] console_emit_next_record+0x1d1/0x4c0 [ 27.537567][ C2] console_flush_one_record+0x46f/0x710 [ 27.537699][ C2] console_unlock+0xee/0x1f0 [ 27.537834][ C2] vprintk_emit+0x37c/0x3e0 [ 27.537970][ C2] _printk+0xc7/0x100 [ 27.538120][ C2] br_set_state+0x22f/0x430 [bridge] [ 27.538260][ C2] br_init_port+0xc4/0x200 [bridge] [ 27.538451][ C2] br_stp_enable_port+0x12/0x50 [bridge] [ 27.538621][ C2] br_port_carrier_check+0x220/0x430 [bridge] [ 27.538838][ C2] br_device_event+0x52d/0x8f0 [bridge] [ 27.539024][ C2] notifier_call_chain+0xae/0x300 [ 27.539159][ C2] netif_state_change+0x139/0x340 [ 27.539342][ C2] __linkwatch_run_queue+0x34c/0x750 [ 27.539482][ C2] linkwatch_event+0x7f/0xb0 [ 27.539664][ C2] process_one_work+0xe3e/0x1560 [ 27.539798][ C2] worker_thread+0x4f1/0xd60 [ 27.539979][ C2] kthread+0x367/0x460 [ 27.540084][ C2] ret_from_fork+0x474/0x6b0 [ 27.540216][ C2] ret_from_fork_asm+0x11/0x20 [ 27.540399][ C2] [ 27.540465][ C2] -> (&br->lock){+.-.}-{3:3} { [ 27.540646][ C2] HARDIRQ-ON-W at: [ 27.540746][ C2] __lock_acquire+0x388/0xc20 [ 27.540960][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.541128][ C2] _raw_spin_lock_bh+0x38/0x50 [ 27.541300][ C2] br_stp_enable_bridge+0x23/0x340 [bridge] [ 27.541607][ C2] br_dev_open+0x94/0x120 [bridge] [ 27.541784][ C2] __dev_open+0x2f3/0x610 [ 27.542000][ C2] __dev_change_flags+0x4a4/0x660 [ 27.542218][ C2] rtnl_configure_link+0x15f/0x410 [ 27.542395][ C2] rtnl_newlink_create+0x2ec/0x750 [ 27.542564][ C2] __rtnl_newlink+0x22b/0xa50 [ 27.542729][ C2] rtnl_newlink+0x9f1/0x11c0 [ 27.542942][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.543157][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 27.543325][ C2] netlink_unicast+0x486/0x750 [ 27.543549][ C2] netlink_sendmsg+0x735/0xc60 [ 27.543717][ C2] ____sys_sendmsg+0x415/0x880 [ 27.543886][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 27.544101][ C2] __sys_sendmsg+0x12c/0x1d0 [ 27.544269][ C2] do_syscall_64+0xff/0x530 [ 27.544489][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.544741][ C2] IN-SOFTIRQ-W at: [ 27.544888][ C2] __lock_acquire+0x388/0xc20 [ 27.545105][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.545272][ C2] _raw_spin_lock+0x33/0x40 [ 27.545492][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.545748][ C2] call_timer_fn+0x160/0x4d0 [ 27.545916][ C2] __run_timers+0x68f/0xaa0 [ 27.546131][ C2] run_timer_softirq+0xf0/0x160 [ 27.546351][ C2] handle_softirqs+0x1d3/0x900 [ 27.546525][ C2] __irq_exit_rcu+0x145/0x1c0 [ 27.546739][ C2] irq_exit_rcu+0xe/0x30 [ 27.546961][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.547162][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.547465][ C2] pv_native_safe_halt+0xf/0x10 [ 27.547681][ C2] default_idle+0x9/0x10 [ 27.547849][ C2] default_idle_call+0x6e/0xb0 [ 27.548065][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.548318][ C2] do_idle+0xd8/0x190 [ 27.548456][ C2] cpu_startup_entry+0x53/0x70 [ 27.548668][ C2] start_secondary+0x204/0x2b0 [ 27.548855][ C2] common_startup_64+0x13e/0x148 [ 27.549070][ C2] INITIAL USE at: [ 27.549169][ C2] __lock_acquire+0x388/0xc20 [ 27.549389][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.549556][ C2] _raw_spin_lock_bh+0x38/0x50 [ 27.549722][ C2] br_stp_enable_bridge+0x23/0x340 [bridge] [ 27.549975][ C2] br_dev_open+0x94/0x120 [bridge] [ 27.550195][ C2] __dev_open+0x2f3/0x610 [ 27.550364][ C2] __dev_change_flags+0x4a4/0x660 [ 27.550583][ C2] rtnl_configure_link+0x15f/0x410 [ 27.550753][ C2] rtnl_newlink_create+0x2ec/0x750 [ 27.550920][ C2] __rtnl_newlink+0x22b/0xa50 [ 27.551133][ C2] rtnl_newlink+0x9f1/0x11c0 [ 27.551301][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.551520][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 27.551735][ C2] netlink_unicast+0x486/0x750 [ 27.551903][ C2] netlink_sendmsg+0x735/0xc60 [ 27.552117][ C2] ____sys_sendmsg+0x415/0x880 [ 27.552333][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 27.552504][ C2] __sys_sendmsg+0x12c/0x1d0 [ 27.552722][ C2] do_syscall_64+0xff/0x530 [ 27.552887][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.553135][ C2] } [ 27.553204][ C2] ... key at: [] __key.7+0x0/0x40 [bridge] [ 27.553458][ C2] ... acquired at: [ 27.553602][ C2] mark_lock+0x1d7/0xa00 [ 27.553737][ C2] mark_usage+0x42/0x170 [ 27.553918][ C2] __lock_acquire+0x388/0xc20 [ 27.554050][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.556642][ C2] _raw_spin_lock+0x33/0x40 [ 27.556792][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.557014][ C2] call_timer_fn+0x160/0x4d0 [ 27.557149][ C2] __run_timers+0x68f/0xaa0 [ 27.557327][ C2] run_timer_softirq+0xf0/0x160 [ 27.557469][ C2] handle_softirqs+0x1d3/0x900 [ 27.557654][ C2] __irq_exit_rcu+0x145/0x1c0 [ 27.557784][ C2] irq_exit_rcu+0xe/0x30 [ 27.557963][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.558130][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.558342][ C2] pv_native_safe_halt+0xf/0x10 [ 27.558479][ C2] default_idle+0x9/0x10 [ 27.558659][ C2] default_idle_call+0x6e/0xb0 [ 27.558795][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.558961][ C2] do_idle+0xd8/0x190 [ 27.559058][ C2] cpu_startup_entry+0x53/0x70 [ 27.559237][ C2] start_secondary+0x204/0x2b0 [ 27.559378][ C2] common_startup_64+0x13e/0x148 [ 27.559557][ C2] [ 27.559624][ C2] [ 27.559624][ C2] stack backtrace: [ 27.559797][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.559801][ C2] Tainted: [W]=WARN [ 27.559802][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.559803][ C2] Call Trace: [ 27.559805][ C2] [ 27.559806][ C2] dump_stack_lvl+0x6f/0xa0 [ 27.559811][ C2] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 27.559814][ C2] mark_lock_irq+0x989/0x9c0 [ 27.559818][ C2] mark_lock+0x1d7/0xa00 [ 27.559820][ C2] mark_usage+0x42/0x170 [ 27.559822][ C2] __lock_acquire+0x388/0xc20 [ 27.559824][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.559826][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.559833][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.559836][ C2] ? lock_acquire+0x13c/0x160 [ 27.559838][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.559844][ C2] _raw_spin_lock+0x33/0x40 [ 27.559845][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.559851][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.559858][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.559864][ C2] call_timer_fn+0x160/0x4d0 [ 27.559866][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 27.559868][ C2] ? debug_object_active_state+0x430/0x430 [ 27.559872][ C2] ? find_held_lock+0x2b/0x80 [ 27.559874][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 27.559876][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.559878][ C2] __run_timers+0x68f/0xaa0 [ 27.559880][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.559886][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.559888][ C2] ? __lock_acquire+0x518/0xc20 [ 27.559891][ C2] ? __rwlock_init+0x150/0x150 [ 27.559894][ C2] run_timer_softirq+0xf0/0x160 [ 27.559896][ C2] ? __run_timers+0xaa0/0xaa0 [ 27.559897][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.559900][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.559901][ C2] handle_softirqs+0x1d3/0x900 [ 27.559903][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 27.559905][ C2] ? _local_bh_enable+0xc0/0xc0 [ 27.559907][ C2] __irq_exit_rcu+0x145/0x1c0 [ 27.559909][ C2] irq_exit_rcu+0xe/0x30 [ 27.559910][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.559912][ C2] [ 27.559912][ C2] [ 27.559913][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 27.559915][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.559916][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.559918][ 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 [ 27.559920][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 27.559924][ C2] RAX: 00000000000c306f RBX: ff11000001bec6c0 RCX: ffffffff9a0f0307 [ 27.559925][ C2] RDX: ff11000001bec6c0 RSI: ffffffff9ce38b01 RDI: ffffffff9c88d8e0 [ 27.559926][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.559927][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 27.559928][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 27.559929][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.559932][ C2] default_idle+0x9/0x10 [ 27.559933][ C2] default_idle_call+0x6e/0xb0 [ 27.559935][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.559937][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 27.559938][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 27.559940][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.559941][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.559943][ C2] do_idle+0xd8/0x190 [ 27.559945][ C2] cpu_startup_entry+0x53/0x70 [ 27.559946][ C2] start_secondary+0x204/0x2b0 [ 27.559948][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 27.559950][ C2] common_startup_64+0x13e/0x148 [ 27.559953][ C2] [ 89.515434][ T755] Mirror/redirect action on [ 89.515450][ T755] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 89.515453][ T755] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 755, name: modprobe [ 89.515455][ T755] preempt_count: 1, expected: 0 [ 89.515457][ T755] RCU nest depth: 0, expected: 0 [ 89.515458][ T755] INFO: lockdep is turned off. [ 89.515460][ T755] irq event stamp: 0 [ 89.515461][ T755] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 89.515465][ T755] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 89.515473][ T755] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 89.515475][ T755] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 89.515477][ T755] Preemption disabled at: [ 89.515478][ T755] [] vprintk_emit+0x31b/0x3e0 [ 89.515488][ T755] CPU: 3 UID: 0 PID: 755 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 89.515492][ T755] Tainted: [W]=WARN [ 89.515494][ T755] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 89.515496][ T755] Call Trace: [ 89.515498][ T755] [ 89.515499][ T755] dump_stack_lvl+0x6f/0xa0 [ 89.515506][ T755] ? vprintk_emit+0x31b/0x3e0 [ 89.515509][ T755] __might_resched.cold+0x1fe/0x2c1 [ 89.515514][ T755] ? prb_next_reserve_seq+0x2d0/0x2d0 [ 89.515517][ T755] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 89.515521][ T755] ? rcu_is_watching+0x16/0xd0 [ 89.515525][ T755] __kmalloc_noprof+0x443/0x760 [ 89.515530][ T755] ? __rwlock_init+0x150/0x150 [ 89.515533][ T755] ? alloc_buf.isra.0+0x4b/0x260 [ 89.515538][ T755] ? do_raw_spin_unlock+0x59/0x250 [ 89.515541][ T755] alloc_buf.isra.0+0x4b/0x260 [ 89.515549][ T755] put_chars+0x1e1/0x2f0 [ 89.515552][ T755] ? __send_to_port+0x420/0x420 [ 89.515555][ T755] ? console_prepend_replay+0x20/0x20 [ 89.515558][ T755] hvc_console_print+0x292/0x780 [ 89.515563][ T755] ? hvc_write+0x3a0/0x3a0 [ 89.515565][ T755] ? vsnprintf+0x668/0x1700 [ 89.515570][ T755] ? __rwlock_init+0x150/0x150 [ 89.515572][ T755] ? rcu_is_watching+0x16/0xd0 [ 89.515574][ T755] ? rcu_is_watching+0x16/0xd0 [ 89.515578][ T755] console_emit_next_record+0x22f/0x4c0 [ 89.515581][ T755] ? devkmsg_read+0x4b0/0x4b0 [ 89.515584][ T755] ? rcu_is_watching+0x16/0xd0 [ 89.515587][ T755] ? rcu_is_watching+0x16/0xd0 [ 89.515589][ T755] ? rcu_is_watching+0x16/0xd0 [ 89.515591][ T755] ? lock_acquire+0x13c/0x160 [ 89.515594][ T755] ? console_flush_one_record+0x111/0x710 [ 89.515597][ T755] console_flush_one_record+0x46f/0x710 [ 89.515600][ T755] ? console_emit_next_record+0x4c0/0x4c0 [ 89.515604][ T755] console_unlock+0xee/0x1f0 [ 89.515607][ T755] ? lock_acquire+0x13c/0x160 [ 89.515609][ T755] ? console_flush_one_record+0x710/0x710 [ 89.515611][ T755] ? rcu_is_watching+0x16/0xd0 [ 89.515614][ T755] ? lock_release+0x184/0x1f0 [ 89.515616][ T755] ? lock_acquire+0x60/0x160 [ 89.515618][ T755] ? __rcu_read_lock+0x30/0x70 [ 89.515621][ T755] ? __down_trylock_console_sem+0x5e/0xa0 [ 89.515624][ T755] ? vprintk_emit+0x320/0x3e0 [ 89.515627][ T755] vprintk_emit+0x37c/0x3e0 [ 89.515631][ T755] ? wake_up_klogd_work_func+0x90/0x90 [ 89.515633][ T755] ? netdev_run_todo+0x2a8/0xfd0 [ 89.515638][ T755] ? mirred_device_event+0x3d/0x1d0 [act_mirred] [ 89.515643][ T755] ? mirred_cleanup_module+0x750/0x750 [act_mirred] [ 89.515646][ T755] _printk+0xc7/0x100 [ 89.515650][ T755] ? snapshot_read.cold+0x21/0x21 [ 89.515652][ T755] ? rcu_is_watching+0x16/0xd0 [ 89.515655][ T755] ? mirred_cleanup_module+0x750/0x750 [act_mirred] [ 89.515658][ T755] ? __up_write+0x23d/0x5f0 [ 89.515660][ T755] ? __up_write+0x2e5/0x5f0 [ 89.515662][ T755] ? lock_release+0x184/0x1f0 [ 89.515666][ T755] ? __up_write+0x2c7/0x5f0 [ 89.515668][ T755] mirred_init_module+0x28/0x1000 [act_mirred] [ 89.515671][ T755] do_one_initcall+0x124/0x4f0 [ 89.515674][ T755] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 89.515678][ T755] ? do_init_module+0xae/0x8a0 [ 89.515682][ T755] ? kasan_unpoison+0x44/0x70 [ 89.515686][ T755] do_init_module+0x2ad/0x8a0 [ 89.515689][ T755] ? free_module+0x470/0x470 [ 89.515692][ T755] ? kfree+0x123/0x590 [ 89.515696][ T755] load_module+0x1424/0x1bb0 [ 89.515701][ T755] ? layout_and_allocate.isra.0+0x680/0x680 [ 89.515704][ T755] ? __ia32_sys_fsconfig+0x180/0x180 [ 89.515710][ T755] ? ovl_real_file+0x172/0x1e0 [ 89.515714][ T755] ? backing_file_read_iter+0x541/0x790 [ 89.515718][ T755] init_module_from_file+0x11f/0x1a0 [ 89.515722][ T755] ? __do_sys_init_module+0x220/0x220 [ 89.515725][ T755] ? ovl_mmap+0x270/0x270 [ 89.515729][ T755] ? __rwlock_init+0x150/0x150 [ 89.515731][ T755] ? rcu_is_watching+0x16/0xd0 [ 89.515734][ T755] ? lock_release+0x184/0x1f0 [ 89.515736][ T755] ? do_raw_spin_unlock+0x59/0x250 [ 89.515739][ T755] idempotent_init_module+0x37b/0x7c0 [ 89.515742][ T755] ? init_module_from_file+0x1a0/0x1a0 [ 89.515747][ T755] ? rcu_is_watching+0x16/0xd0 [ 89.515749][ T755] ? cap_capable+0x1d7/0x3d0 [ 89.515754][ T755] __x64_sys_finit_module+0xdf/0x190 [ 89.515757][ T755] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 89.515760][ T755] do_syscall_64+0xff/0x530 [ 89.515763][ T755] ? exc_page_fault+0xee/0x100 [ 89.515766][ T755] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 89.515769][ T755] RIP: 0033:0x7fa2e0fd737d [ 89.515772][ T755] 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 [ 89.515774][ T755] RSP: 002b:00007fffa0ccfe88 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 89.515778][ T755] RAX: ffffffffffffffda RBX: 0000557ed7a39810 RCX: 00007fa2e0fd737d [ 89.515780][ T755] RDX: 0000000000000000 RSI: 0000557e9da82ffd RDI: 0000000000000000 [ 89.515782][ T755] RBP: 00007fffa0ccff20 R08: 0000000000000000 R09: 0000000000000000 [ 89.515783][ T755] R10: 0000000000000000 R11: 0000000000000246 R12: 0000557e9da82ffd [ 89.515784][ T755] R13: 0000557ed7a39960 R14: 0000000000040000 R15: 0000000000000000 [ 89.515788][ T755] [ 118.460841][ T932] br: port 3(veth4) entered disabled state [ 118.460858][ T932] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 118.460860][ T932] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 932, name: ip [ 118.460862][ T932] preempt_count: 202, expected: 0 [ 118.460863][ T932] RCU nest depth: 0, expected: 0 [ 118.460864][ T932] INFO: lockdep is turned off. [ 118.460865][ T932] irq event stamp: 0 [ 118.460866][ T932] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 118.460871][ T932] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 118.460877][ T932] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 118.460878][ T932] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 118.460880][ T932] Preemption disabled at: [ 118.460881][ T932] [<0000000000000000>] 0x0 [ 118.460886][ T932] CPU: 2 UID: 0 PID: 932 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 118.460889][ T932] Tainted: [W]=WARN [ 118.460890][ T932] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 118.460891][ T932] Call Trace: [ 118.460893][ T932] [ 118.460894][ T932] dump_stack_lvl+0x6f/0xa0 [ 118.460899][ T932] __might_resched.cold+0x1fe/0x2c1 [ 118.460903][ T932] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 118.460907][ T932] ? rcu_is_watching+0x16/0xd0 [ 118.460911][ T932] __kmalloc_noprof+0x443/0x760 [ 118.460914][ T932] ? __rwlock_init+0x150/0x150 [ 118.460917][ T932] ? alloc_buf.isra.0+0x4b/0x260 [ 118.460921][ T932] ? do_raw_spin_unlock+0x59/0x250 [ 118.460923][ T932] alloc_buf.isra.0+0x4b/0x260 [ 118.460924][ T932] put_chars+0x1e1/0x2f0 [ 118.460926][ T932] ? __send_to_port+0x420/0x420 [ 118.460928][ T932] ? printk_get_next_message+0x240/0x650 [ 118.460932][ T932] hvc_console_print+0x292/0x780 [ 118.460936][ T932] ? hvc_write+0x3a0/0x3a0 [ 118.460938][ T932] ? __rwlock_init+0x150/0x150 [ 118.460939][ T932] ? rcu_is_watching+0x16/0xd0 [ 118.460941][ T932] ? lock_acquire+0x13c/0x160 [ 118.460943][ T932] console_emit_next_record+0x22f/0x4c0 [ 118.460946][ T932] ? devkmsg_read+0x4b0/0x4b0 [ 118.460947][ T932] ? rcu_is_watching+0x16/0xd0 [ 118.460949][ T932] ? rcu_is_watching+0x16/0xd0 [ 118.460951][ T932] ? rcu_is_watching+0x16/0xd0 [ 118.460952][ T932] ? lock_acquire+0x13c/0x160 [ 118.460954][ T932] ? console_flush_one_record+0x111/0x710 [ 118.460956][ T932] console_flush_one_record+0x46f/0x710 [ 118.460958][ T932] ? console_emit_next_record+0x4c0/0x4c0 [ 118.460961][ T932] console_unlock+0xee/0x1f0 [ 118.460962][ T932] ? lock_acquire+0x13c/0x160 [ 118.460964][ T932] ? console_flush_one_record+0x710/0x710 [ 118.460966][ T932] ? rcu_is_watching+0x16/0xd0 [ 118.460967][ T932] ? lock_release+0x184/0x1f0 [ 118.460968][ T932] ? lock_acquire+0x60/0x160 [ 118.460970][ T932] ? __rcu_read_lock+0x30/0x70 [ 118.460972][ T932] ? __down_trylock_console_sem+0x5e/0xa0 [ 118.460974][ T932] ? vprintk_emit+0x320/0x3e0 [ 118.460976][ T932] vprintk_emit+0x37c/0x3e0 [ 118.460978][ T932] ? wake_up_klogd_work_func+0x90/0x90 [ 118.460980][ T932] ? rcu_is_watching+0x16/0xd0 [ 118.460982][ T932] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 118.460984][ T932] ? trace_hardirqs_on+0x36/0x40 [ 118.460986][ T932] _printk+0xc7/0x100 [ 118.460989][ T932] ? snapshot_read.cold+0x21/0x21 [ 118.460991][ T932] ? ipv6_mc_down+0x17e/0x2d0 [ 118.460996][ T932] br_set_state+0x22f/0x430 [bridge] [ 118.461008][ T932] br_stp_disable_port+0x11f/0x390 [bridge] [ 118.461016][ T932] br_device_event+0x7b5/0x8f0 [bridge] [ 118.461025][ T932] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 118.461029][ T932] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 118.461031][ T932] ? addrconf_notify+0x2c8/0xf30 [ 118.461035][ T932] notifier_call_chain+0xae/0x300 [ 118.461038][ T932] __dev_notify_flags+0x23c/0x3c0 [ 118.461042][ T932] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 118.461043][ T932] ? netif_change_name+0x8c0/0x8c0 [ 118.461045][ T932] ? netif_set_allmulti+0x250/0x250 [ 118.461047][ T932] ? __kernel_text_address+0x12/0x30 [ 118.461049][ T932] ? write_profile+0xf0/0xf0 [ 118.461051][ T932] ? unwind_get_return_address+0x67/0xd0 [ 118.461054][ T932] netif_change_flags+0xfe/0x190 [ 118.461056][ T932] do_setlink.isra.0+0x1cc3/0x2750 [ 118.461059][ T932] ? stack_trace_save+0x93/0xc0 [ 118.461061][ T932] ? rtnl_link_get_size+0x350/0x350 [ 118.461062][ T932] ? rcu_is_watching+0x16/0xd0 [ 118.461064][ T932] ? stack_depot_save_flags+0x1bc/0x790 [ 118.461067][ T932] ? kasan_save_stack+0x3d/0x50 [ 118.461070][ T932] ? kasan_save_stack+0x2f/0x50 [ 118.461072][ T932] ? kasan_save_track+0x14/0x30 [ 118.461073][ T932] ? __kasan_kmalloc+0x7b/0x90 [ 118.461075][ T932] ? __kmalloc_cache_noprof+0x29a/0x620 [ 118.461077][ T932] ? rtnl_newlink+0x124/0x11c0 [ 118.461078][ T932] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 118.461079][ T932] ? netlink_rcv_skb+0x14e/0x3a0 [ 118.461081][ T932] ? netlink_unicast+0x486/0x750 [ 118.461083][ T932] ? netlink_sendmsg+0x735/0xc60 [ 118.461084][ T932] ? ____sys_sendmsg+0x415/0x880 [ 118.461087][ T932] ? ___sys_sendmsg+0x14e/0x1d0 [ 118.461088][ T932] ? __sys_sendmsg+0x12c/0x1d0 [ 118.461090][ T932] ? do_syscall_64+0xff/0x530 [ 118.461091][ T932] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 118.461094][ T932] ? rcu_is_watching+0x16/0xd0 [ 118.461096][ T932] ? lock_acquire+0x13c/0x160 [ 118.461097][ T932] ? rcu_is_watching+0x16/0xd0 [ 118.461099][ T932] ? rcu_is_watching+0x16/0xd0 [ 118.461100][ T932] ? trace_contention_end+0xb3/0x180 [ 118.461103][ T932] ? __mutex_lock+0x1db/0x1ea0 [ 118.461105][ T932] ? __mutex_lock+0x9a3/0x1ea0 [ 118.461106][ T932] ? rtnl_newlink+0x9a8/0x11c0 [ 118.461108][ T932] ? ww_mutex_lock+0x160/0x160 [ 118.461109][ T932] ? nla_get_range_signed+0x3d0/0x3d0 [ 118.461111][ T932] ? __rtnl_newlink+0x3fa/0xa50 [ 118.461113][ T932] rtnl_newlink+0x9f1/0x11c0 [ 118.461115][ T932] ? rcu_is_watching+0x16/0xd0 [ 118.461117][ T932] ? rtnl_bridge_getlink+0x850/0x850 [ 118.461118][ T932] ? lock_acquire+0x13c/0x160 [ 118.461119][ T932] ? rcu_is_watching+0x16/0xd0 [ 118.461121][ T932] ? is_bpf_text_address+0x3e/0x110 [ 118.461124][ T932] ? is_bpf_text_address+0x72/0x110 [ 118.461126][ T932] ? kernel_text_address+0x149/0x170 [ 118.461127][ T932] ? __kernel_text_address+0x12/0x30 [ 118.461129][ T932] ? write_profile+0xf0/0xf0 [ 118.461130][ T932] ? unwind_get_return_address+0x67/0xd0 [ 118.461131][ T932] ? rcu_is_watching+0x16/0xd0 [ 118.461133][ T932] ? rcu_is_watching+0x16/0xd0 [ 118.461134][ T932] ? cap_capable+0x1d7/0x3d0 [ 118.461138][ T932] ? lock_acquire+0x13c/0x160 [ 118.461139][ T932] ? rcu_is_watching+0x16/0xd0 [ 118.461141][ T932] ? rtnl_bridge_getlink+0x850/0x850 [ 118.461142][ T932] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 118.461144][ T932] ? rtnl_link_fill+0x920/0x920 [ 118.461145][ T932] ? filter_irq_stacks+0xd0/0xd0 [ 118.461147][ T932] ? stack_depot_save_flags+0x1bc/0x790 [ 118.461149][ T932] netlink_rcv_skb+0x14e/0x3a0 [ 118.461150][ T932] ? rtnl_link_fill+0x920/0x920 [ 118.461152][ T932] ? lock_release+0x184/0x1f0 [ 118.461153][ T932] ? netlink_ack+0xcf0/0xcf0 [ 118.461154][ T932] ? __netlink_lookup+0xb9/0x6b0 [ 118.461157][ T932] ? rcu_is_watching+0x16/0xd0 [ 118.461159][ T932] ? lock_release+0x184/0x1f0 [ 118.461160][ T932] netlink_unicast+0x486/0x750 [ 118.461162][ T932] ? netlink_attachskb+0x810/0x810 [ 118.461164][ T932] ? rcu_is_watching+0x16/0xd0 [ 118.461165][ T932] ? rcu_is_watching+0x16/0xd0 [ 118.461167][ T932] ? lock_acquire+0x13c/0x160 [ 118.461169][ T932] netlink_sendmsg+0x735/0xc60 [ 118.461171][ T932] ? netlink_unicast+0x750/0x750 [ 118.461173][ T932] ? rcu_is_watching+0x16/0xd0 [ 118.461174][ T932] ? lock_release+0x184/0x1f0 [ 118.461176][ T932] ____sys_sendmsg+0x415/0x880 [ 118.461178][ T932] ? copy_msghdr_from_user+0x279/0x420 [ 118.461179][ T932] ? get_timestamp.constprop.0+0x390/0x390 [ 118.461181][ T932] ? move_addr_to_kernel+0x40/0x40 [ 118.461182][ T932] ? filter_irq_stacks+0xd0/0xd0 [ 118.461185][ T932] ___sys_sendmsg+0x14e/0x1d0 [ 118.461186][ T932] ? copy_msghdr_from_user+0x420/0x420 [ 118.461191][ T932] __sys_sendmsg+0x12c/0x1d0 [ 118.461193][ T932] ? __sys_sendmsg_sock+0x20/0x20 [ 118.461196][ T932] ? rcu_is_watching+0x16/0xd0 [ 118.461198][ T932] do_syscall_64+0xff/0x530 [ 118.461199][ T932] ? exc_page_fault+0xee/0x100 [ 118.461201][ T932] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 118.461203][ T932] RIP: 0033:0x7f86f23f354e [ 118.461205][ T932] 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 [ 118.461206][ T932] RSP: 002b:00007fff75d16340 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 118.461210][ T932] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f86f23f354e [ 118.461212][ T932] RDX: 0000000000000000 RSI: 00007fff75d163f0 RDI: 0000000000000005 [ 118.461213][ T932] RBP: 00007fff75d16350 R08: 0000000000000000 R09: 0000000000000000 [ 118.461213][ T932] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a910a38 [ 118.461214][ T932] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fff75d16ab0 [ 118.461216][ T932] [ 118.492481][ T932] veth4: left allmulticast mode [ 118.492666][ T932] veth4: left promiscuous mode [ 118.492978][ T932] br: port 3(veth4) entered disabled state [ 118.502615][ T932] ip (932) used greatest stack depth: 23016 bytes left [ 118.518493][ T934] br: port 2(veth3) entered disabled state [ 118.518868][ T934] veth3: left allmulticast mode [ 118.519047][ T934] veth3: left promiscuous mode [ 118.519286][ T934] br: port 2(veth3) entered disabled state [ 118.541721][ T936] br: port 1(veth1) entered disabled state [ 118.542051][ T936] veth1: left allmulticast mode [ 118.545920][ T936] veth1: left promiscuous mode [ 118.546112][ T936] br: port 1(veth1) entered disabled state