[ 9.840559][ T210] fab-br0: port 1(fbond) entered blocking state [ 9.840654][ T210] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.840657][ T210] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 210, name: ip [ 9.840659][ T210] preempt_count: 1, expected: 0 [ 9.840660][ T210] RCU nest depth: 0, expected: 0 [ 9.840662][ T210] locks held by ip/210: 5, last CPU#0: [ 9.840665][ T210] #0: ffffffffb4322700 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11b0 [ 9.840681][ T210] #1: ffffffffb3b99cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.840690][ T210] #2: ffffffffb3b99d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.840696][ T210] #3: ffffffffb3a89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.840701][ T210] #4: ffffffffb3a89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x202/0x4f0 [ 9.840707][ T210] irq event stamp: 29716 [ 9.840708][ T210] hardirqs last enabled at (29715): [] __down_trylock_console_sem+0x86/0xa0 [ 9.840712][ T210] hardirqs last disabled at (29716): [] console_emit_next_record+0x3f8/0x4f0 [ 9.840715][ T210] softirqs last enabled at (29628): [] __alloc_skb+0x4c5/0x5f0 [ 9.840719][ T210] softirqs last disabled at (29626): [] __alloc_skb+0x4c5/0x5f0 [ 9.840722][ T210] Preemption disabled at: [ 9.840723][ T210] [] vprintk_emit+0x31b/0x3e0 [ 9.840731][ T210] CPU: 0 UID: 0 PID: 210 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.840735][ T210] Tainted: [W]=WARN [ 9.840736][ T210] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.840738][ T210] Call Trace: [ 9.840741][ T210] [ 9.840743][ T210] dump_stack_lvl+0x6f/0xa0 [ 9.840750][ T210] ? vprintk_emit+0x31b/0x3e0 [ 9.840753][ T210] __might_resched.cold+0x1fe/0x2c1 [ 9.840759][ T210] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.840765][ T210] ? __kmalloc_noprof+0xdb/0x760 [ 9.840773][ T210] __kmalloc_noprof+0x443/0x760 [ 9.840777][ T210] ? alloc_buf.isra.0+0x4b/0x260 [ 9.840787][ T210] ? do_raw_spin_unlock+0x59/0x250 [ 9.840790][ T210] alloc_buf.isra.0+0x4b/0x260 [ 9.840796][ T210] put_chars+0x1e1/0x2f0 [ 9.840799][ T210] ? printk_get_next_message+0x2fe/0x7d0 [ 9.840803][ T210] ? __send_to_port+0x420/0x420 [ 9.840809][ T210] ? validate_chain+0x34a/0xc20 [ 9.840819][ T210] hvc_console_print+0x292/0x780 [ 9.840831][ T210] ? hvc_write+0x3a0/0x3a0 [ 9.840835][ T210] ? rcu_is_watching+0x16/0xd0 [ 9.840840][ T210] ? lock_acquire+0x13c/0x160 [ 9.840848][ T210] console_emit_next_record+0x252/0x4f0 [ 9.840854][ T210] ? devkmsg_read+0x4e0/0x4e0 [ 9.840861][ T210] ? rcu_is_watching+0x16/0xd0 [ 9.840865][ T210] ? lock_acquire+0x13c/0x160 [ 9.840872][ T210] console_flush_one_record+0x46f/0x710 [ 9.840879][ T210] ? console_emit_next_record+0x4f0/0x4f0 [ 9.840882][ T210] ? __lock_acquire+0x518/0xc20 [ 9.840892][ T210] console_unlock+0xee/0x1f0 [ 9.840896][ T210] ? console_flush_one_record+0x710/0x710 [ 9.840899][ T210] ? rcu_is_watching+0x16/0xd0 [ 9.840902][ T210] ? lock_acquire+0x60/0x160 [ 9.840909][ T210] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.840912][ T210] ? vprintk_emit+0x320/0x3e0 [ 9.840917][ T210] vprintk_emit+0x37c/0x3e0 [ 9.840923][ T210] ? wake_up_klogd_work_func+0x90/0x90 [ 9.840926][ T210] ? __lock_release.isra.0+0x69/0x1a0 [ 9.840929][ T210] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 9.840935][ T210] ? bond_poll_controller+0x480/0x480 [ 9.840940][ T210] ? mark_held_locks+0x40/0x70 [ 9.840946][ T210] _printk+0xc7/0x100 [ 9.840951][ T210] ? snapshot_read.cold+0x21/0x21 [ 9.840957][ T210] ? br_multicast_flood+0x960/0x960 [ 9.840962][ T210] ? do_setlink.isra.0+0x9ec/0x2a60 [ 9.840965][ T210] ? rtnl_newlink+0x9f1/0x11b0 [ 9.840967][ T210] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 9.840976][ T210] br_set_state+0x22f/0x410 [ 9.840982][ T210] br_init_port+0xc4/0x200 [ 9.840987][ T210] new_nbp+0x3a0/0x650 [ 9.840994][ T210] br_add_if+0x212/0x1360 [ 9.841000][ T210] ? kernel_text_address+0x149/0x170 [ 9.841009][ T210] do_set_master+0x3b2/0x780 [ 9.841017][ T210] do_setlink.isra.0+0x9ec/0x2a60 [ 9.841023][ T210] ? stack_trace_save+0x93/0xc0 [ 9.841028][ T210] ? rtnl_link_get_size+0x350/0x350 [ 9.841031][ T210] ? rcu_read_lock_any_held+0x66/0x90 [ 9.841035][ T210] ? stack_depot_save_flags+0x38e/0x790 [ 9.841040][ T210] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.841044][ T210] ? rcu_read_lock_any_held+0x3c/0x90 [ 9.841047][ T210] ? validate_chain+0x38b/0xc20 [ 9.841052][ T210] ? kasan_save_stack+0x3d/0x50 [ 9.841055][ T210] ? kasan_save_stack+0x2f/0x50 [ 9.841057][ T210] ? kasan_save_track+0x14/0x30 [ 9.841061][ T210] ? __lock_acquire+0x518/0xc20 [ 9.841064][ T210] ? netlink_seq_next+0x5e/0x60 [ 9.841069][ T210] ? ___sys_sendmsg+0xc0/0x1d0 [ 9.841075][ T210] ? lock_acquire.part.0+0xd4/0x280 [ 9.841079][ T210] ? rtnl_newlink+0x9a8/0x11b0 [ 9.841084][ T210] ? rcu_is_watching+0x16/0xd0 [ 9.841087][ T210] ? lock_acquire+0x13c/0x160 [ 9.841090][ T210] ? rcu_is_watching+0x16/0xd0 [ 9.841092][ T210] ? rcu_is_watching+0x16/0xd0 [ 9.841095][ T210] ? trace_contention_end+0xb3/0x180 [ 9.841100][ T210] ? __mutex_lock+0x1db/0x1ea0 [ 9.841102][ T210] ? __mutex_lock+0x9a3/0x1ea0 [ 9.841106][ T210] ? rtnl_newlink+0x9a8/0x11b0 [ 9.841113][ T210] ? ww_mutex_lock+0x160/0x160 [ 9.841115][ T210] ? nla_get_range_signed+0x3d0/0x3d0 [ 9.841126][ T210] ? __rtnl_newlink+0x3fa/0xa50 [ 9.841136][ T210] rtnl_newlink+0x9f1/0x11b0 [ 9.841146][ T210] ? rtnl_bridge_getlink+0x850/0x850 [ 9.841150][ T210] ? __lock_acquire+0x518/0xc20 [ 9.841158][ T210] ? lock_acquire.part.0+0xd4/0x280 [ 9.841161][ T210] ? find_held_lock+0x2b/0x80 [ 9.841165][ T210] ? rtnl_bridge_getlink+0x850/0x850 [ 9.841169][ T210] ? __lock_release.isra.0+0x69/0x1a0 [ 9.841176][ T210] ? rtnl_bridge_getlink+0x850/0x850 [ 9.841185][ T210] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 9.841191][ T210] ? rtnl_link_fill+0x900/0x900 [ 9.841193][ T210] ? __lock_acquire+0x518/0xc20 [ 9.841202][ T210] ? lock_acquire.part.0+0xd4/0x280 [ 9.841205][ T210] ? find_held_lock+0x2b/0x80 [ 9.841212][ T210] netlink_rcv_skb+0x14e/0x3a0 [ 9.841215][ T210] ? rtnl_link_fill+0x900/0x900 [ 9.841221][ T210] ? netlink_ack+0xcd0/0xcd0 [ 9.841233][ T210] ? netlink_deliver_tap+0xc5/0x330 [ 9.841235][ T210] ? netlink_deliver_tap+0x13c/0x330 [ 9.841243][ T210] netlink_unicast+0x486/0x750 [ 9.841251][ T210] ? netlink_attachskb+0x810/0x810 [ 9.841255][ T210] ? __lock_acquire+0x518/0xc20 [ 9.841263][ T210] netlink_sendmsg+0x735/0xc60 [ 9.841271][ T210] ? netlink_unicast+0x750/0x750 [ 9.841278][ T210] ? __might_fault+0x97/0x140 [ 9.841287][ T210] ____sys_sendmsg+0x415/0x880 [ 9.841291][ T210] ? copy_msghdr_from_user+0x279/0x420 [ 9.841294][ T210] ? get_timestamp.constprop.0+0x3a0/0x3a0 [ 9.841297][ T210] ? move_addr_to_kernel+0x40/0x40 [ 9.841311][ T210] ___sys_sendmsg+0x14e/0x1d0 [ 9.841316][ T210] ? copy_msghdr_from_user+0x420/0x420 [ 9.841350][ T210] __sys_sendmsg+0x12c/0x1d0 [ 9.841355][ T210] ? __sys_sendmsg_sock+0x20/0x20 [ 9.841366][ T210] ? rcu_is_watching+0x16/0xd0 [ 9.841372][ T210] do_syscall_64+0xff/0x530 [ 9.841375][ T210] ? exc_page_fault+0xee/0x100 [ 9.841381][ T210] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.841384][ T210] RIP: 0033:0x7f062b40454e [ 9.841390][ T210] 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 [ 9.841392][ T210] RSP: 002b:00007ffc268453e0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 9.841397][ T210] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f062b40454e [ 9.841399][ T210] RDX: 0000000000000000 RSI: 00007ffc26845490 RDI: 0000000000000005 [ 9.841400][ T210] RBP: 00007ffc268453f0 R08: 0000000000000000 R09: 0000000000000000 [ 9.841402][ T210] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a911115 [ 9.841403][ T210] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffc26845b50 [ 9.841417][ T210] [ 9.905427][ T210] fab-br0: port 1(fbond) entered disabled state [ 9.906022][ T210] fbond: entered allmulticast mode [ 9.908776][ T210] fbond: entered promiscuous mode [ 9.933680][ T210] ip (210) used greatest stack depth: 23336 bytes left [ 9.957098][ T215] veth1-bond: entered promiscuous mode [ 9.957468][ T215] veth1-bond: entered allmulticast mode [ 9.964498][ T215] fbond: (slave veth1-bond): Enslaving as a backup interface with a down link [ 10.038912][ T217] veth2-bond: entered promiscuous mode [ 10.039282][ T217] veth2-bond: entered allmulticast mode [ 10.039745][ T217] fbond: (slave veth2-bond): Enslaving as a backup interface with a down link [ 10.079399][ T72] fbond: Warning: No 802.3ad response from the link partner for any adapters in the bond [ 10.159624][ T222] fab-br0: port 1(fbond) entered blocking state [ 10.160148][ T222] fab-br0: port 1(fbond) entered forwarding state [ 10.167651][ T72] fbond: (slave veth1-bond): link status definitely up, 10000 Mbps full duplex [ 10.168724][ T72] fbond: (slave veth2-bond): link status definitely up, 10000 Mbps full duplex [ 10.169433][ T72] fbond: active interface up! [ 10.312370][ C2] [ 10.312478][ C2] ======================================================== [ 10.312706][ C2] WARNING: possible irq lock inversion dependency detected [ 10.312906][ C2] 7.2.0-virtme #1 Tainted: G W [ 10.313075][ C2] -------------------------------------------------------- [ 10.313280][ C2] swapper/2/0 just changed the state of lock: [ 10.313450][ C2] ff1100000cb52e58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [ 10.313706][ C2] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 10.313909][ C2] (fs_reclaim){+.+.}-{0:0} [ 10.313912][ C2] [ 10.313912][ C2] [ 10.313912][ C2] and interrupts could create inverse lock ordering between them. [ 10.313912][ C2] [ 10.314409][ C2] [ 10.314409][ C2] other info that might help us debug this: [ 10.314614][ C2] Chain exists of: [ 10.314614][ C2] &br->lock --> console_owner --> fs_reclaim [ 10.314614][ C2] [ 10.314925][ C2] Possible interrupt unsafe locking scenario: [ 10.314925][ C2] [ 10.315131][ C2] CPU0 CPU1 [ 10.315273][ C2] ---- ---- [ 10.315411][ C2] lock(fs_reclaim); [ 10.315517][ C2] local_irq_disable(); [ 10.315688][ C2] lock(&br->lock); [ 10.315860][ C2] lock(console_owner); [ 10.316031][ C2] [ 10.316138][ C2] lock(&br->lock); [ 10.316247][ C2] [ 10.316247][ C2] *** DEADLOCK *** [ 10.316247][ C2] [ 10.316447][ C2] locks held by swapper/2/0: 1, last CPU#2: [ 10.316621][ C2] #0: ffa0000000228c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 10.316899][ C2] [ 10.316899][ C2] the shortest dependencies between 2nd lock and 1st lock: [ 10.317136][ C2] -> (fs_reclaim){+.+.}-{0:0} { [ 10.317282][ C2] HARDIRQ-ON-W at: [ 10.317389][ C2] __lock_acquire+0x388/0xc20 [ 10.317571][ C2] lock_acquire.part.0+0xd4/0x280 [ 10.317742][ C2] fs_reclaim_acquire+0xd5/0x120 [ 10.317918][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 10.318125][ C2] kthread_create_worker_on_node+0xea/0x210 [ 10.318337][ C2] workqueue_init+0x2a/0x680 [ 10.318512][ C2] kernel_init_freeable+0x2fe/0x630 [ 10.318755][ C2] kernel_init+0x21/0x150 [ 10.318931][ C2] ret_from_fork+0x474/0x6b0 [ 10.319107][ C2] ret_from_fork_asm+0x11/0x20 [ 10.319288][ C2] SOFTIRQ-ON-W at: [ 10.319395][ C2] __lock_acquire+0x388/0xc20 [ 10.319578][ C2] lock_acquire.part.0+0xd4/0x280 [ 10.319764][ C2] fs_reclaim_acquire+0xd5/0x120 [ 10.319937][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 10.323691][ C2] kthread_create_worker_on_node+0xea/0x210 [ 10.323909][ C2] workqueue_init+0x2a/0x680 [ 10.324086][ C2] kernel_init_freeable+0x2fe/0x630 [ 10.324296][ C2] kernel_init+0x21/0x150 [ 10.324469][ C2] ret_from_fork+0x474/0x6b0 [ 10.324639][ C2] ret_from_fork_asm+0x11/0x20 [ 10.324812][ C2] INITIAL USE at: [ 10.324916][ C2] __lock_acquire+0x388/0xc20 [ 10.325087][ C2] lock_acquire.part.0+0xd4/0x280 [ 10.325262][ C2] fs_reclaim_acquire+0xd5/0x120 [ 10.325431][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 10.325635][ C2] kthread_create_worker_on_node+0xea/0x210 [ 10.325836][ C2] workqueue_init+0x2a/0x680 [ 10.326003][ C2] kernel_init_freeable+0x2fe/0x630 [ 10.326174][ C2] kernel_init+0x21/0x150 [ 10.326348][ C2] ret_from_fork+0x474/0x6b0 [ 10.326518][ C2] ret_from_fork_asm+0x11/0x20 [ 10.326692][ C2] } [ 10.326764][ C2] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 10.326965][ C2] ... acquired at: [ 10.327066][ C2] __lock_acquire+0x518/0xc20 [ 10.327210][ C2] lock_acquire.part.0+0xd4/0x280 [ 10.327346][ C2] fs_reclaim_acquire+0xd5/0x120 [ 10.327481][ C2] __kmalloc_noprof+0xd3/0x760 [ 10.327614][ C2] alloc_buf.isra.0+0x4b/0x260 [ 10.327750][ C2] put_chars+0x1e1/0x2f0 [ 10.327882][ C2] hvc_console_print+0x292/0x780 [ 10.328015][ C2] console_emit_next_record+0x252/0x4f0 [ 10.328153][ C2] console_flush_one_record+0x46f/0x710 [ 10.328291][ C2] console_unlock+0xee/0x1f0 [ 10.328423][ C2] vprintk_emit+0x37c/0x3e0 [ 10.328555][ C2] _printk+0xc7/0x100 [ 10.328658][ C2] i8042_pnp_init+0xf7/0x3c0 [ 10.328793][ C2] i8042_platform_init+0x3f9/0x460 [ 10.328926][ C2] i8042_init+0x45/0x130 [ 10.329057][ C2] do_one_initcall+0x124/0x4f0 [ 10.329197][ C2] kernel_init_freeable+0x596/0x630 [ 10.329332][ C2] kernel_init+0x21/0x150 [ 10.329467][ C2] ret_from_fork+0x474/0x6b0 [ 10.329600][ C2] ret_from_fork_asm+0x11/0x20 [ 10.329733][ C2] [ 10.329800][ C2] -> (console_owner){....}-{0:0} { [ 10.329939][ C2] INITIAL USE at: [ 10.330039][ C2] } [ 10.330108][ C2] ... key at: [] console_owner_dep_map+0x0/0x60 [ 10.330308][ C2] ... acquired at: [ 10.330410][ C2] __lock_acquire+0x518/0xc20 [ 10.330544][ C2] lock_acquire.part.0+0xd4/0x280 [ 10.330679][ C2] console_lock_spinning_enable+0x5c/0x60 [ 10.330843][ C2] console_emit_next_record+0x1f4/0x4f0 [ 10.330979][ C2] console_flush_one_record+0x46f/0x710 [ 10.331114][ C2] console_unlock+0xee/0x1f0 [ 10.331252][ C2] vprintk_emit+0x37c/0x3e0 [ 10.331385][ C2] _printk+0xc7/0x100 [ 10.331487][ C2] br_set_state+0x22f/0x410 [ 10.331623][ C2] br_init_port+0xc4/0x200 [ 10.331758][ C2] br_stp_enable_bridge+0x1fc/0x340 [ 10.331892][ C2] br_dev_open+0x94/0x120 [ 10.332027][ C2] __dev_open+0x322/0x7a0 [ 10.332161][ C2] __dev_change_flags+0x4a4/0x6d0 [ 10.332298][ C2] netif_change_flags+0x87/0x190 [ 10.332433][ C2] do_setlink.isra.0+0x1d25/0x2a60 [ 10.332571][ C2] rtnl_newlink+0x9f1/0x11b0 [ 10.332706][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 10.332839][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 10.332973][ C2] netlink_unicast+0x486/0x750 [ 10.333106][ C2] netlink_sendmsg+0x735/0xc60 [ 10.333246][ C2] ____sys_sendmsg+0x415/0x880 [ 10.333380][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 10.333515][ C2] __sys_sendmsg+0x12c/0x1d0 [ 10.333648][ C2] do_syscall_64+0xff/0x530 [ 10.333782][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.333950][ C2] [ 10.334017][ C2] -> (&br->lock){+.-.}-{3:3} { [ 10.334175][ C2] HARDIRQ-ON-W at: [ 10.334282][ C2] __lock_acquire+0x388/0xc20 [ 10.334452][ C2] lock_acquire.part.0+0xd4/0x280 [ 10.334620][ C2] _raw_spin_lock_bh+0x38/0x50 [ 10.334794][ C2] br_dev_newlink+0xa6/0x160 [ 10.338806][ C2] rtnl_newlink_create+0x2d0/0x850 [ 10.338983][ C2] __rtnl_newlink+0x22b/0xa50 [ 10.339156][ C2] rtnl_newlink+0x9f1/0x11b0 [ 10.339329][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 10.339503][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 10.339671][ C2] netlink_unicast+0x486/0x750 [ 10.339838][ C2] netlink_sendmsg+0x735/0xc60 [ 10.340006][ C2] ____sys_sendmsg+0x415/0x880 [ 10.340173][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 10.340344][ C2] __sys_sendmsg+0x12c/0x1d0 [ 10.340514][ C2] do_syscall_64+0xff/0x530 [ 10.340682][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.340883][ C2] IN-SOFTIRQ-W at: [ 10.340985][ C2] __lock_acquire+0x388/0xc20 [ 10.341155][ C2] lock_acquire.part.0+0xd4/0x280 [ 10.341324][ C2] _raw_spin_lock+0x33/0x40 [ 10.341492][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [ 10.341692][ C2] call_timer_fn+0x160/0x4d0 [ 10.341860][ C2] __run_timers+0x68f/0xaa0 [ 10.342026][ C2] run_timer_softirq+0xf0/0x160 [ 10.342195][ C2] handle_softirqs+0x1d3/0x900 [ 10.342365][ C2] __irq_exit_rcu+0x145/0x1c0 [ 10.342532][ C2] irq_exit_rcu+0xe/0x30 [ 10.342700][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 10.342901][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 10.343106][ C2] pv_native_safe_halt+0xf/0x10 [ 10.343279][ C2] default_idle+0x9/0x10 [ 10.343445][ C2] default_idle_call+0x6e/0xb0 [ 10.343612][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 10.343811][ C2] do_idle+0xd8/0x190 [ 10.343946][ C2] cpu_startup_entry+0x53/0x70 [ 10.344113][ C2] start_secondary+0x204/0x2b0 [ 10.344289][ C2] common_startup_64+0x13e/0x148 [ 10.344459][ C2] INITIAL USE at: [ 10.344562][ C2] __lock_acquire+0x388/0xc20 [ 10.344730][ C2] lock_acquire.part.0+0xd4/0x280 [ 10.344900][ C2] _raw_spin_lock_bh+0x38/0x50 [ 10.345068][ C2] br_dev_newlink+0xa6/0x160 [ 10.345239][ C2] rtnl_newlink_create+0x2d0/0x850 [ 10.345411][ C2] __rtnl_newlink+0x22b/0xa50 [ 10.345578][ C2] rtnl_newlink+0x9f1/0x11b0 [ 10.345745][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 10.345914][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 10.346082][ C2] netlink_unicast+0x486/0x750 [ 10.346250][ C2] netlink_sendmsg+0x735/0xc60 [ 10.346417][ C2] ____sys_sendmsg+0x415/0x880 [ 10.346588][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 10.346754][ C2] __sys_sendmsg+0x12c/0x1d0 [ 10.346919][ C2] do_syscall_64+0xff/0x530 [ 10.347088][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.347290][ C2] } [ 10.347358][ C2] ... key at: [] __key.7+0x0/0x40 [ 10.347530][ C2] ... acquired at: [ 10.347633][ C2] mark_lock+0x1d7/0xa00 [ 10.347767][ C2] mark_usage+0x42/0x170 [ 10.347898][ C2] __lock_acquire+0x388/0xc20 [ 10.348027][ C2] lock_acquire.part.0+0xd4/0x280 [ 10.348160][ C2] _raw_spin_lock+0x33/0x40 [ 10.348297][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [ 10.348467][ C2] call_timer_fn+0x160/0x4d0 [ 10.348602][ C2] __run_timers+0x68f/0xaa0 [ 10.348735][ C2] run_timer_softirq+0xf0/0x160 [ 10.348868][ C2] handle_softirqs+0x1d3/0x900 [ 10.349002][ C2] __irq_exit_rcu+0x145/0x1c0 [ 10.349135][ C2] irq_exit_rcu+0xe/0x30 [ 10.349273][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 10.349439][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 10.349606][ C2] pv_native_safe_halt+0xf/0x10 [ 10.349740][ C2] default_idle+0x9/0x10 [ 10.349869][ C2] default_idle_call+0x6e/0xb0 [ 10.350002][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 10.350165][ C2] do_idle+0xd8/0x190 [ 10.350271][ C2] cpu_startup_entry+0x53/0x70 [ 10.350403][ C2] start_secondary+0x204/0x2b0 [ 10.350536][ C2] common_startup_64+0x13e/0x148 [ 10.350667][ C2] [ 10.350734][ C2] [ 10.350734][ C2] stack backtrace: [ 10.350897][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.350901][ C2] Tainted: [W]=WARN [ 10.350902][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.350904][ C2] Call Trace: [ 10.350905][ C2] [ 10.350907][ C2] dump_stack_lvl+0x6f/0xa0 [ 10.350911][ C2] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 10.350914][ C2] mark_lock_irq+0x989/0x9c0 [ 10.350917][ C2] mark_lock+0x1d7/0xa00 [ 10.350920][ C2] mark_usage+0x42/0x170 [ 10.350921][ C2] __lock_acquire+0x388/0xc20 [ 10.350924][ C2] lock_acquire.part.0+0xd4/0x280 [ 10.350926][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [ 10.350928][ C2] ? rcu_is_watching+0x16/0xd0 [ 10.350930][ C2] ? lock_acquire+0x13c/0x160 [ 10.350933][ C2] ? br_message_age_timer_expired+0x70/0x70 [ 10.350934][ C2] _raw_spin_lock+0x33/0x40 [ 10.350936][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [ 10.350937][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [ 10.350939][ C2] ? br_message_age_timer_expired+0x70/0x70 [ 10.350940][ C2] call_timer_fn+0x160/0x4d0 [ 10.350942][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 10.350943][ C2] ? debug_object_active_state+0x430/0x430 [ 10.350946][ C2] ? find_held_lock+0x2b/0x80 [ 10.350948][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 10.350950][ C2] ? rcu_is_watching+0x16/0xd0 [ 10.350952][ C2] __run_timers+0x68f/0xaa0 [ 10.350953][ C2] ? br_message_age_timer_expired+0x70/0x70 [ 10.350956][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 10.350957][ C2] ? __lock_acquire+0x518/0xc20 [ 10.350960][ C2] ? __rwlock_init+0x150/0x150 [ 10.350962][ C2] run_timer_softirq+0xf0/0x160 [ 10.350964][ C2] ? __run_timers+0xaa0/0xaa0 [ 10.350965][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 10.350968][ C2] ? rcu_is_watching+0x16/0xd0 [ 10.350969][ C2] handle_softirqs+0x1d3/0x900 [ 10.350972][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 10.350974][ C2] ? _local_bh_enable+0xc0/0xc0 [ 10.350976][ C2] __irq_exit_rcu+0x145/0x1c0 [ 10.350978][ C2] irq_exit_rcu+0xe/0x30 [ 10.350979][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 10.350981][ C2] [ 10.350982][ C2] [ 10.350982][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 10.350984][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 10.350986][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 10.350988][ C2] Code: 48 8b 3d 94 12 09 02 e8 1f 00 00 00 48 2b 05 58 a3 a6 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 56 1b 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 10.350990][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 10.350992][ C2] RAX: 000000000004464d RBX: ff11000001bf46c0 RCX: ffffffffb06fa307 [ 10.350994][ C2] RDX: ff11000001bf46c0 RSI: ffffffffb36a7f75 RDI: ffffffffb308f560 [ 10.350994][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 10.350995][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 10.350996][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 10.350998][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 10.351000][ C2] default_idle+0x9/0x10 [ 10.351002][ C2] default_idle_call+0x6e/0xb0 [ 10.351004][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 10.351005][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 10.351007][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 10.351009][ C2] ? rcu_is_watching+0x16/0xd0 [ 10.351011][ C2] do_idle+0xd8/0x190 [ 10.351013][ C2] cpu_startup_entry+0x53/0x70 [ 10.351014][ C2] start_secondary+0x204/0x2b0 [ 10.351016][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 10.351018][ C2] common_startup_64+0x13e/0x148 [ 10.351022][ C2] [ 12.349237][ T328] fbond: left allmulticast mode [ 12.349254][ T328] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.349256][ T328] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 328, name: ip [ 12.349258][ T328] preempt_count: 1, expected: 0 [ 12.349259][ T328] RCU nest depth: 0, expected: 0 [ 12.349260][ T328] INFO: lockdep is turned off. [ 12.349261][ T328] irq event stamp: 0 [ 12.349262][ T328] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 12.349267][ T328] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 12.349273][ T328] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 12.349275][ T328] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 12.349277][ T328] Preemption disabled at: [ 12.349278][ T328] [] vprintk_emit+0x31b/0x3e0 [ 12.349285][ T328] CPU: 0 UID: 0 PID: 328 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.349289][ T328] Tainted: [W]=WARN [ 12.349290][ T328] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.349292][ T328] Call Trace: [ 12.349294][ T328] [ 12.349295][ T328] dump_stack_lvl+0x6f/0xa0 [ 12.349300][ T328] ? vprintk_emit+0x31b/0x3e0 [ 12.349301][ T328] __might_resched.cold+0x1fe/0x2c1 [ 12.349305][ T328] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.349308][ T328] ? rcu_is_watching+0x16/0xd0 [ 12.349311][ T328] __kmalloc_noprof+0x443/0x760 [ 12.349315][ T328] ? __rwlock_init+0x150/0x150 [ 12.349317][ T328] ? alloc_buf.isra.0+0x4b/0x260 [ 12.349321][ T328] ? do_raw_spin_unlock+0x59/0x250 [ 12.349323][ T328] alloc_buf.isra.0+0x4b/0x260 [ 12.349325][ T328] put_chars+0x1e1/0x2f0 [ 12.349327][ T328] ? printk_get_next_message+0x2fe/0x7d0 [ 12.349329][ T328] ? __send_to_port+0x420/0x420 [ 12.349333][ T328] ? desc_read+0x2f0/0x2f0 [ 12.349335][ T328] ? desc_read+0x219/0x2f0 [ 12.349337][ T328] hvc_console_print+0x292/0x780 [ 12.349342][ T328] ? hvc_write+0x3a0/0x3a0 [ 12.349343][ T328] ? rcu_is_watching+0x16/0xd0 [ 12.349345][ T328] ? lock_acquire+0x13c/0x160 [ 12.349348][ T328] console_emit_next_record+0x252/0x4f0 [ 12.349350][ T328] ? devkmsg_read+0x4e0/0x4e0 [ 12.349351][ T328] ? _prb_read_valid+0x6e0/0x6e0 [ 12.349353][ T328] ? __asan_memmove+0x3c/0x60 [ 12.349356][ T328] ? rcu_is_watching+0x16/0xd0 [ 12.349357][ T328] ? rcu_is_watching+0x16/0xd0 [ 12.349358][ T328] ? rcu_is_watching+0x16/0xd0 [ 12.349359][ T328] ? lock_acquire+0x13c/0x160 [ 12.349361][ T328] ? console_flush_one_record+0x111/0x710 [ 12.349362][ T328] console_flush_one_record+0x46f/0x710 [ 12.349364][ T328] ? console_emit_next_record+0x4f0/0x4f0 [ 12.349367][ T328] console_unlock+0xee/0x1f0 [ 12.349368][ T328] ? lock_acquire+0x13c/0x160 [ 12.349370][ T328] ? console_flush_one_record+0x710/0x710 [ 12.349371][ T328] ? rcu_is_watching+0x16/0xd0 [ 12.349372][ T328] ? lock_release+0x184/0x1f0 [ 12.349374][ T328] ? lock_acquire+0x60/0x160 [ 12.349376][ T328] ? sync_exp_reset_tree_hotplug+0x3a0/0x3f0 [ 12.349377][ T328] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.349380][ T328] ? vprintk_emit+0x320/0x3e0 [ 12.349381][ T328] vprintk_emit+0x37c/0x3e0 [ 12.349383][ T328] ? wake_up_klogd_work_func+0x90/0x90 [ 12.349384][ T328] ? netlink_rcv_skb+0x14e/0x3a0 [ 12.349388][ T328] ? netlink_sendmsg+0x735/0xc60 [ 12.349389][ T328] ? ____sys_sendmsg+0x415/0x880 [ 12.349392][ T328] ? ___sys_sendmsg+0x14e/0x1d0 [ 12.349393][ T328] ? __sys_sendmsg+0x12c/0x1d0 [ 12.349395][ T328] ? do_syscall_64+0xff/0x530 [ 12.349397][ T328] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 12.349399][ T328] _printk+0xc7/0x100 [ 12.349403][ T328] ? snapshot_read.cold+0x21/0x21 [ 12.349405][ T328] ? netdev_name+0x43/0x60 [ 12.349407][ T328] ? __netdev_printk+0x17f/0x1d0 [ 12.349409][ T328] netdev_info+0xe2/0x116 [ 12.349411][ T328] ? netdev_notice+0x120/0x120 [ 12.349413][ T328] ? rcu_is_watching+0x16/0xd0 [ 12.349414][ T328] ? __up_read+0x355/0x9e0 [ 12.349416][ T328] ? trace_hardirqs_on+0x36/0x40 [ 12.349419][ T328] ? down_write_nested+0x200/0x200 [ 12.349420][ T328] ? mutex_is_locked+0x1c/0x50 [ 12.349422][ T328] netif_set_allmulti.cold+0x7c/0x166 [ 12.349425][ T328] dev_set_allmulti+0xa6/0x240 [ 12.349428][ T328] del_nbp+0xb7/0xc10 [ 12.349432][ T328] ? __asan_memset+0x27/0x50 [ 12.349434][ T328] br_dev_delete+0x9d/0x1d0 [ 12.349436][ T328] rtnl_dellink+0x48f/0xae0 [ 12.349439][ T328] ? lock_release+0x184/0x1f0 [ 12.349440][ T328] ? rcu_is_watching+0x16/0xd0 [ 12.349442][ T328] ? rtnl_link_get_net_capable.constprop.0+0x3d0/0x3d0 [ 12.349444][ T328] ? rcu_is_watching+0x16/0xd0 [ 12.349449][ T328] ? unwind_next_frame+0x69b/0x1ea0 [ 12.349452][ T328] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 12.349454][ T328] ? rcu_is_watching+0x16/0xd0 [ 12.349455][ T328] ? rcu_is_watching+0x16/0xd0 [ 12.349456][ T328] ? lock_acquire+0x13c/0x160 [ 12.349458][ T328] ? rcu_is_watching+0x16/0xd0 [ 12.349459][ T328] ? lock_release+0x184/0x1f0 [ 12.349461][ T328] ? is_bpf_text_address+0x3e/0x110 [ 12.349464][ T328] ? is_bpf_text_address+0x72/0x110 [ 12.349465][ T328] ? kernel_text_address+0x149/0x170 [ 12.349468][ T328] ? __kernel_text_address+0x12/0x30 [ 12.349469][ T328] ? write_profile+0xf0/0xf0 [ 12.349472][ T328] ? unwind_get_return_address+0x67/0xd0 [ 12.349474][ T328] ? rcu_is_watching+0x16/0xd0 [ 12.349475][ T328] ? rcu_is_watching+0x16/0xd0 [ 12.349476][ T328] ? cap_capable+0x1d7/0x3d0 [ 12.349480][ T328] ? lock_acquire+0x13c/0x160 [ 12.349482][ T328] ? rcu_is_watching+0x16/0xd0 [ 12.349483][ T328] ? rtnl_link_get_net_capable.constprop.0+0x3d0/0x3d0 [ 12.349485][ T328] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 12.349488][ T328] ? rtnl_link_fill+0x900/0x900 [ 12.349489][ T328] ? filter_irq_stacks+0xd0/0xd0 [ 12.349491][ T328] ? stack_depot_save_flags+0x1bc/0x790 [ 12.349495][ T328] netlink_rcv_skb+0x14e/0x3a0 [ 12.349496][ T328] ? rtnl_link_fill+0x900/0x900 [ 12.349498][ T328] ? lock_release+0x184/0x1f0 [ 12.349500][ T328] ? netlink_ack+0xcd0/0xcd0 [ 12.349501][ T328] ? __netlink_lookup+0xb9/0x6b0 [ 12.349504][ T328] ? rcu_is_watching+0x16/0xd0 [ 12.349505][ T328] ? lock_release+0x184/0x1f0 [ 12.349507][ T328] netlink_unicast+0x486/0x750 [ 12.349509][ T328] ? netlink_attachskb+0x810/0x810 [ 12.349511][ T328] ? rcu_is_watching+0x16/0xd0 [ 12.349512][ T328] ? rcu_is_watching+0x16/0xd0 [ 12.349513][ T328] ? lock_acquire+0x13c/0x160 [ 12.349515][ T328] netlink_sendmsg+0x735/0xc60 [ 12.349518][ T328] ? netlink_unicast+0x750/0x750 [ 12.349519][ T328] ? rcu_is_watching+0x16/0xd0 [ 12.349521][ T328] ? lock_release+0x184/0x1f0 [ 12.349523][ T328] ____sys_sendmsg+0x415/0x880 [ 12.349524][ T328] ? copy_msghdr_from_user+0x279/0x420 [ 12.349526][ T328] ? get_timestamp.constprop.0+0x3a0/0x3a0 [ 12.349527][ T328] ? move_addr_to_kernel+0x40/0x40 [ 12.349528][ T328] ? filter_irq_stacks+0xd0/0xd0 [ 12.349531][ T328] ___sys_sendmsg+0x14e/0x1d0 [ 12.349532][ T328] ? copy_msghdr_from_user+0x420/0x420 [ 12.349537][ T328] __sys_sendmsg+0x12c/0x1d0 [ 12.349539][ T328] ? __sys_sendmsg_sock+0x20/0x20 [ 12.349541][ T328] ? rcu_is_watching+0x16/0xd0 [ 12.349543][ T328] do_syscall_64+0xff/0x530 [ 12.349544][ T328] ? exc_page_fault+0xee/0x100 [ 12.349546][ T328] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 12.349548][ T328] RIP: 0033:0x7fdb7c50554e [ 12.349550][ T328] 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.349551][ T328] RSP: 002b:00007ffd6f6866c0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 12.349555][ T328] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007fdb7c50554e [ 12.349556][ T328] RDX: 0000000000000000 RSI: 00007ffd6f686770 RDI: 0000000000000005 [ 12.349557][ T328] RBP: 00007ffd6f6866d0 R08: 0000000000000000 R09: 0000000000000000 [ 12.349558][ T328] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a911117 [ 12.349559][ T328] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffd6f686e30 [ 12.349561][ T328] [ 12.374695][ T328] veth1-bond: left allmulticast mode [ 12.374844][ T328] veth2-bond: left allmulticast mode [ 12.374997][ T328] fbond: left promiscuous mode [ 12.375125][ T328] veth1-bond: left promiscuous mode [ 12.375345][ T328] veth2-bond: left promiscuous mode [ 12.375602][ T328] fab-br0: port 1(fbond) entered disabled state [ 12.412626][ T330] fbond (unregistering): (slave veth1-bond): Removing an active aggregator [ 12.413023][ T330] fbond (unregistering): (slave veth1-bond): Releasing backup interface [ 12.420792][ T330] fbond (unregistering): (slave veth2-bond): Removing an active aggregator [ 12.421151][ T330] fbond (unregistering): (slave veth2-bond): Releasing backup interface [ 12.430674][ T330] fbond (unregistering): Released all slaves