[ 12.145189][ T271] bond0: (slave veth0): Enslaving as a backup interface with a down link [ 12.145246][ T271] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.145248][ T271] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 271, name: ip [ 12.145250][ T271] preempt_count: 1, expected: 0 [ 12.145251][ T271] RCU nest depth: 0, expected: 0 [ 12.145252][ T271] locks held by ip/271: 5, last CPU#0: [ 12.145254][ T271] #0: ffffffff92122700 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11b0 [ 12.145267][ T271] #1: ffffffff91999cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.145273][ T271] #2: ffffffff91999d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.145277][ T271] #3: ffffffff91889660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.145280][ T271] #4: ffffffff91889560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 12.145283][ T271] irq event stamp: 16646 [ 12.145284][ T271] hardirqs last enabled at (16645): [] __down_trylock_console_sem+0x86/0xa0 [ 12.145288][ T271] hardirqs last disabled at (16646): [] console_emit_next_record+0x3f8/0x4f0 [ 12.145289][ T271] softirqs last enabled at (16616): [] dev_mc_add+0xd2/0x110 [ 12.145292][ T271] softirqs last disabled at (16600): [] dev_mc_add+0x62/0x110 [ 12.145295][ T271] Preemption disabled at: [ 12.145296][ T271] [] vprintk_emit+0x31b/0x3e0 [ 12.145301][ T271] CPU: 0 UID: 0 PID: 271 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.145305][ T271] Tainted: [W]=WARN [ 12.145306][ T271] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.145308][ T271] Call Trace: [ 12.145309][ T271] [ 12.145311][ T271] dump_stack_lvl+0x6f/0xa0 [ 12.145316][ T271] ? vprintk_emit+0x31b/0x3e0 [ 12.145318][ T271] __might_resched.cold+0x1fe/0x2c1 [ 12.145323][ T271] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.145327][ T271] ? __kmalloc_noprof+0xdb/0x760 [ 12.145332][ T271] __kmalloc_noprof+0x443/0x760 [ 12.145335][ T271] ? alloc_buf.isra.0+0x4b/0x260 [ 12.145342][ T271] ? do_raw_spin_unlock+0x59/0x250 [ 12.145344][ T271] alloc_buf.isra.0+0x4b/0x260 [ 12.145347][ T271] put_chars+0x1e1/0x2f0 [ 12.145351][ T271] ? __send_to_port+0x420/0x420 [ 12.145358][ T271] hvc_console_print+0x292/0x780 [ 12.145366][ T271] ? hvc_write+0x3a0/0x3a0 [ 12.145369][ T271] ? lock_acquire+0x13c/0x160 [ 12.145374][ T271] console_emit_next_record+0x252/0x4f0 [ 12.145377][ T271] ? devkmsg_read+0x4e0/0x4e0 [ 12.145381][ T271] ? rcu_is_watching+0x16/0xd0 [ 12.145385][ T271] ? lock_acquire+0x13c/0x160 [ 12.145389][ T271] console_flush_one_record+0x46f/0x710 [ 12.145393][ T271] ? console_emit_next_record+0x4f0/0x4f0 [ 12.145394][ T271] ? __lock_acquire+0x518/0xc20 [ 12.145400][ T271] console_unlock+0xee/0x1f0 [ 12.145402][ T271] ? console_flush_one_record+0x710/0x710 [ 12.145404][ T271] ? rcu_is_watching+0x16/0xd0 [ 12.145406][ T271] ? lock_acquire+0x60/0x160 [ 12.145410][ T271] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.145412][ T271] ? vprintk_emit+0x320/0x3e0 [ 12.145415][ T271] vprintk_emit+0x37c/0x3e0 [ 12.145418][ T271] ? wake_up_klogd_work_func+0x90/0x90 [ 12.145421][ T271] ? packet_sendmsg_spkt+0x1350/0x1350 [ 12.145427][ T271] _printk+0xc7/0x100 [ 12.145430][ T271] ? snapshot_read.cold+0x21/0x21 [ 12.145434][ T271] ? __mutex_unlock_slowpath+0x9e/0x740 [ 12.145438][ T271] ? netdev_name+0x43/0x60 [ 12.145441][ T271] ? __netdev_printk+0x17f/0x1d0 [ 12.145444][ T271] netdev_info+0xe2/0x116 [ 12.145446][ T271] ? netdev_notice+0x120/0x120 [ 12.145451][ T271] ? bond_lower_state_changed+0x160/0x160 [ 12.145454][ T271] ? __lock_release.isra.0+0x69/0x1a0 [ 12.145460][ T271] bond_enslave.cold+0x156/0x4f7 [ 12.145468][ T271] ? find_held_lock+0x2b/0x80 [ 12.145471][ T271] ? bond_update_slave_arr+0xcf0/0xcf0 [ 12.145472][ T271] ? mark_usage+0x61/0x170 [ 12.145474][ T271] ? __lock_acquire+0x518/0xc20 [ 12.145476][ T271] ? __lock_acquire+0x500/0xc20 [ 12.145481][ T271] ? lock_acquire.part.0+0xd4/0x280 [ 12.145488][ T271] ? is_bpf_text_address+0x72/0x110 [ 12.145492][ T271] ? kernel_text_address+0x149/0x170 [ 12.145498][ T271] do_set_master+0x3b2/0x780 [ 12.145502][ T271] do_setlink.isra.0+0x9ec/0x2a60 [ 12.145506][ T271] ? stack_trace_save+0x93/0xc0 [ 12.145509][ T271] ? rtnl_link_get_size+0x350/0x350 [ 12.145511][ T271] ? rcu_read_lock_any_held+0x66/0x90 [ 12.145514][ T271] ? stack_depot_save_flags+0x38e/0x790 [ 12.145517][ T271] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 12.145520][ T271] ? rcu_read_lock_any_held+0x3c/0x90 [ 12.145522][ T271] ? validate_chain+0x38b/0xc20 [ 12.145525][ T271] ? kasan_save_stack+0x3d/0x50 [ 12.145527][ T271] ? kasan_save_stack+0x2f/0x50 [ 12.145528][ T271] ? kasan_save_track+0x14/0x30 [ 12.145531][ T271] ? __lock_acquire+0x518/0xc20 [ 12.145533][ T271] ? netlink_seq_next+0x1e/0x60 [ 12.145536][ T271] ? ___sys_sendmsg+0xc0/0x1d0 [ 12.145541][ T271] ? lock_acquire.part.0+0xd4/0x280 [ 12.145543][ T271] ? rtnl_newlink+0x9a8/0x11b0 [ 12.145546][ T271] ? rcu_is_watching+0x16/0xd0 [ 12.145548][ T271] ? lock_acquire+0x13c/0x160 [ 12.145550][ T271] ? rcu_is_watching+0x16/0xd0 [ 12.145551][ T271] ? rcu_is_watching+0x16/0xd0 [ 12.145553][ T271] ? trace_contention_end+0xb3/0x180 [ 12.145556][ T271] ? __mutex_lock+0x1db/0x1ea0 [ 12.145557][ T271] ? __mutex_lock+0x9a3/0x1ea0 [ 12.145559][ T271] ? rtnl_newlink+0x9a8/0x11b0 [ 12.145563][ T271] ? ww_mutex_lock+0x160/0x160 [ 12.145565][ T271] ? nla_get_range_signed+0x3d0/0x3d0 [ 12.145571][ T271] ? __rtnl_newlink+0x3fa/0xa50 [ 12.145577][ T271] rtnl_newlink+0x9f1/0x11b0 [ 12.145583][ T271] ? rtnl_bridge_getlink+0x850/0x850 [ 12.145585][ T271] ? __lock_acquire+0x518/0xc20 [ 12.145590][ T271] ? lock_acquire.part.0+0xd4/0x280 [ 12.145592][ T271] ? find_held_lock+0x2b/0x80 [ 12.145594][ T271] ? rtnl_bridge_getlink+0x850/0x850 [ 12.145596][ T271] ? __lock_release.isra.0+0x69/0x1a0 [ 12.145600][ T271] ? rtnl_bridge_getlink+0x850/0x850 [ 12.145603][ T271] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 12.145607][ T271] ? rtnl_link_fill+0x900/0x900 [ 12.145608][ T271] ? __lock_acquire+0x518/0xc20 [ 12.145613][ T271] ? lock_acquire.part.0+0xd4/0x280 [ 12.145615][ T271] ? find_held_lock+0x2b/0x80 [ 12.145619][ T271] netlink_rcv_skb+0x14e/0x3a0 [ 12.145621][ T271] ? rtnl_link_fill+0x900/0x900 [ 12.145624][ T271] ? netlink_ack+0xcd0/0xcd0 [ 12.145631][ T271] ? netlink_deliver_tap+0xc5/0x330 [ 12.145632][ T271] ? netlink_deliver_tap+0x13c/0x330 [ 12.145637][ T271] netlink_unicast+0x486/0x750 [ 12.145641][ T271] ? netlink_attachskb+0x810/0x810 [ 12.145643][ T271] ? __lock_acquire+0x518/0xc20 [ 12.145648][ T271] netlink_sendmsg+0x735/0xc60 [ 12.145652][ T271] ? netlink_unicast+0x750/0x750 [ 12.145656][ T271] ? __might_fault+0x97/0x140 [ 12.145662][ T271] ____sys_sendmsg+0x415/0x880 [ 12.145664][ T271] ? copy_msghdr_from_user+0x279/0x420 [ 12.145666][ T271] ? get_timestamp.constprop.0+0x3a0/0x3a0 [ 12.145667][ T271] ? move_addr_to_kernel+0x40/0x40 [ 12.145674][ T271] ___sys_sendmsg+0x14e/0x1d0 [ 12.145677][ T271] ? copy_msghdr_from_user+0x420/0x420 [ 12.145693][ T271] __sys_sendmsg+0x12c/0x1d0 [ 12.145696][ T271] ? __sys_sendmsg_sock+0x20/0x20 [ 12.145702][ T271] ? rcu_is_watching+0x16/0xd0 [ 12.145705][ T271] do_syscall_64+0xff/0x530 [ 12.145707][ T271] ? exc_page_fault+0xee/0x100 [ 12.145711][ T271] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 12.145713][ T271] RIP: 0033:0x7f22e2d8b54e [ 12.145717][ T271] 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.145719][ T271] RSP: 002b:00007fff18caee00 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 12.145722][ T271] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f22e2d8b54e [ 12.145723][ T271] RDX: 0000000000000000 RSI: 00007fff18caeeb0 RDI: 0000000000000005 [ 12.145724][ T271] RBP: 00007fff18caee10 R08: 0000000000000000 R09: 0000000000000000 [ 12.145725][ T271] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a918ba3 [ 12.145725][ T271] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fff18caf580 [ 12.145732][ T271] [ 12.193232][ T271] ip (271) used greatest stack depth: 22824 bytes left [ 12.224449][ T272] bond0: (slave veth1): Enslaving as a backup interface with a down link [ 12.265029][ T273] br0: port 1(veth0) entered blocking state [ 12.265325][ T273] br0: port 1(veth0) entered disabled state [ 12.265625][ T273] veth0: entered allmulticast mode [ 12.266848][ T273] veth0: entered promiscuous mode [ 12.296619][ T274] br0: port 2(veth1) entered blocking state [ 12.296912][ T274] br0: port 2(veth1) entered disabled state [ 12.297218][ T274] veth1: entered allmulticast mode [ 12.304352][ T274] veth1: entered promiscuous mode [ 13.051331][ T59] br0: port 1(veth0) entered blocking state [ 13.051820][ T59] br0: port 1(veth0) entered forwarding state [ 13.053210][ T59] br0: port 2(veth1) entered blocking state [ 13.053527][ T59] br0: port 2(veth1) entered forwarding state [ 14.203446][ T72] bond0: (slave veth1): link status definitely up [ 14.203526][ T72] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 14.203528][ T72] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 72, name: kworker/u16:1 [ 14.203531][ T72] preempt_count: 1, expected: 0 [ 14.203532][ T72] RCU nest depth: 0, expected: 0 [ 14.203533][ T72] locks held by kworker/u16:1/72: 7, last CPU#3: [ 14.203535][ T72] #0: ff1100000d371d40 ((wq_completion)bond0){+.+.}-{0:0}, at: process_one_work+0xd9a/0x1560 [ 14.203547][ T72] #1: ffa00000004d7d10 ((work_completion)(&(&bond->arp_work)->work)){+.+.}-{0:0}, at: process_one_work+0xdfc/0x1560 [ 14.203551][ T72] #2: ffffffff92122700 (rtnl_mutex){+.+.}-{4:4}, at: bond_activebackup_arp_mon+0x3a2/0xb40 [ 14.203557][ T72] #3: ffffffff91999cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 14.203562][ T72] #4: ffffffff91999d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 14.203566][ T72] #5: ffffffff91889660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 14.203569][ T72] #6: ffffffff91889560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 14.203572][ T72] irq event stamp: 2694 [ 14.203573][ T72] hardirqs last enabled at (2693): [] __down_trylock_console_sem+0x86/0xa0 [ 14.203577][ T72] hardirqs last disabled at (2694): [] console_emit_next_record+0x3f8/0x4f0 [ 14.203578][ T72] softirqs last enabled at (2614): [] inet6_fill_ifla6_attrs+0x1ac2/0x2140 [ 14.203582][ T72] softirqs last disabled at (2612): [] inet6_fill_ifla6_attrs+0x1a72/0x2140 [ 14.203585][ T72] Preemption disabled at: [ 14.203586][ T72] [] vprintk_emit+0x31b/0x3e0 [ 14.203591][ T72] CPU: 3 UID: 0 PID: 72 Comm: kworker/u16:1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 14.203595][ T72] Tainted: [W]=WARN [ 14.203596][ T72] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.203598][ T72] Workqueue: bond0 bond_arp_monitor [ 14.203601][ T72] Call Trace: [ 14.203603][ T72] [ 14.203605][ T72] dump_stack_lvl+0x6f/0xa0 [ 14.203610][ T72] ? vprintk_emit+0x31b/0x3e0 [ 14.203612][ T72] __might_resched.cold+0x1fe/0x2c1 [ 14.203616][ T72] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 14.203620][ T72] ? __kmalloc_noprof+0xdb/0x760 [ 14.203625][ T72] __kmalloc_noprof+0x443/0x760 [ 14.203628][ T72] ? alloc_buf.isra.0+0x4b/0x260 [ 14.203634][ T72] ? do_raw_spin_unlock+0x59/0x250 [ 14.203636][ T72] alloc_buf.isra.0+0x4b/0x260 [ 14.203640][ T72] put_chars+0x1e1/0x2f0 [ 14.203643][ T72] ? __send_to_port+0x420/0x420 [ 14.203650][ T72] hvc_console_print+0x292/0x780 [ 14.203658][ T72] ? hvc_write+0x3a0/0x3a0 [ 14.203661][ T72] ? lock_acquire+0x13c/0x160 [ 14.203666][ T72] console_emit_next_record+0x252/0x4f0 [ 14.203669][ T72] ? devkmsg_read+0x4e0/0x4e0 [ 14.203673][ T72] ? rcu_is_watching+0x16/0xd0 [ 14.203677][ T72] ? lock_acquire+0x13c/0x160 [ 14.203681][ T72] console_flush_one_record+0x46f/0x710 [ 14.203685][ T72] ? console_emit_next_record+0x4f0/0x4f0 [ 14.203686][ T72] ? __lock_acquire+0x518/0xc20 [ 14.203692][ T72] console_unlock+0xee/0x1f0 [ 14.203695][ T72] ? console_flush_one_record+0x710/0x710 [ 14.203696][ T72] ? rcu_is_watching+0x16/0xd0 [ 14.203698][ T72] ? lock_acquire+0x60/0x160 [ 14.203702][ T72] ? __down_trylock_console_sem+0x5e/0xa0 [ 14.203704][ T72] ? vprintk_emit+0x320/0x3e0 [ 14.203707][ T72] vprintk_emit+0x37c/0x3e0 [ 14.203710][ T72] ? wake_up_klogd_work_func+0x90/0x90 [ 14.203713][ T72] ? packet_notifier+0x1bb/0xbd0 [ 14.203718][ T72] _printk+0xc7/0x100 [ 14.203722][ T72] ? snapshot_read.cold+0x21/0x21 [ 14.203726][ T72] ? netdev_name+0x43/0x60 [ 14.203729][ T72] ? __netdev_printk+0x17f/0x1d0 [ 14.203733][ T72] netdev_info+0xe2/0x116 [ 14.203735][ T72] ? netdev_notice+0x120/0x120 [ 14.203738][ T72] ? netdev_offload_xstats_get+0x1d0/0x1d0 [ 14.203743][ T72] ? mark_held_locks+0x40/0x70 [ 14.203746][ T72] ? lockdep_hardirqs_on+0x91/0x130 [ 14.203749][ T72] ? queue_delayed_work_on+0x95/0x100 [ 14.203752][ T72] bond_ab_arp_commit.cold+0x194/0x714 [ 14.203758][ T72] ? bond_select_active_slave+0x4f0/0x4f0 [ 14.203760][ T72] ? find_held_lock+0x2b/0x80 [ 14.203763][ T72] ? wait_for_completion_io+0x331/0x400 [ 14.203765][ T72] ? __lock_release.isra.0+0x69/0x1a0 [ 14.203770][ T72] ? bond_activebackup_arp_mon+0x390/0xb40 [ 14.203772][ T72] bond_activebackup_arp_mon+0x4f6/0xb40 [ 14.203776][ T72] ? bond_ab_arp_probe+0xae0/0xae0 [ 14.203779][ T72] ? rcu_is_watching+0x16/0xd0 [ 14.203781][ T72] ? rcu_is_watching+0x16/0xd0 [ 14.203782][ T72] ? lock_acquire+0x13c/0x160 [ 14.203784][ T72] ? rcu_is_watching+0x16/0xd0 [ 14.203788][ T72] process_one_work+0xe3e/0x1560 [ 14.203794][ T72] ? __queue_delayed_work+0x470/0x470 [ 14.203797][ T72] ? lock_acquire.part.0+0xd4/0x280 [ 14.203804][ T72] worker_thread+0x4f1/0xd60 [ 14.203808][ T72] ? rescuer_thread+0x1340/0x1340 [ 14.203810][ T72] ? __kthread_parkme+0xbd/0x210 [ 14.203813][ T72] ? rescuer_thread+0x1340/0x1340 [ 14.203815][ T72] ? rescuer_thread+0x1340/0x1340 [ 14.203817][ T72] kthread+0x367/0x460 [ 14.203819][ T72] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 14.203823][ T72] ? kthread_affine_preferred+0x4c0/0x4c0 [ 14.203826][ T72] ret_from_fork+0x474/0x6b0 [ 14.203831][ T72] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 14.203834][ T72] ? __switch_to+0x5a3/0xe00 [ 14.203838][ T72] ? kthread_affine_preferred+0x4c0/0x4c0 [ 14.203841][ T72] ret_from_fork_asm+0x11/0x20 [ 14.203850][ T72] [ 14.226156][ T72] bond0: (slave veth1): making interface the new active one [ 14.227043][ T72] bond0: active interface up! [ 14.421414][ T346] veth1: left allmulticast mode [ 14.421982][ T346] veth1: left promiscuous mode [ 14.422595][ T346] br0: port 2(veth1) entered disabled state [ 14.426165][ T346] veth0: left allmulticast mode [ 14.426623][ T346] veth0: left promiscuous mode [ 14.427259][ T346] br0: port 1(veth0) entered disabled state [ 14.458505][ T346] bond0: (slave veth1): Releasing backup interface [ 14.469378][ T346] bond0: (slave veth0): Releasing backup interface [ 14.505658][ T346] bond0 (unregistering): Released all slaves