[ 11.465035][ T255] br0: port 1(veth1) entered blocking state [ 11.465159][ T255] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.465162][ T255] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 255, name: ip [ 11.465163][ T255] preempt_count: 1, expected: 0 [ 11.465164][ T255] RCU nest depth: 0, expected: 0 [ 11.465165][ T255] locks held by ip/255: 5, last CPU#3: [ 11.465168][ T255] #0: ffffffffbccd2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 11.465179][ T255] #1: ffffffffbc569cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.465186][ T255] #2: ffffffffbc569d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.465190][ T255] #3: ffffffffbc489660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.465194][ T255] #4: ffffffffbc489560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.465198][ T255] irq event stamp: 24532 [ 11.465199][ T255] hardirqs last enabled at (24531): [] __down_trylock_console_sem+0x86/0xa0 [ 11.465202][ T255] hardirqs last disabled at (24532): [] console_emit_next_record+0x3d4/0x4c0 [ 11.465204][ T255] softirqs last enabled at (24444): [] __alloc_skb+0x4c2/0x5f0 [ 11.465207][ T255] softirqs last disabled at (24442): [] __alloc_skb+0x4c2/0x5f0 [ 11.465210][ T255] Preemption disabled at: [ 11.465211][ T255] [] vprintk_emit+0x31b/0x3e0 [ 11.465216][ T255] CPU: 3 UID: 0 PID: 255 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.465221][ T255] Tainted: [W]=WARN [ 11.465222][ T255] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.465224][ T255] Call Trace: [ 11.465225][ T255] [ 11.465227][ T255] dump_stack_lvl+0x6f/0xa0 [ 11.465233][ T255] ? vprintk_emit+0x31b/0x3e0 [ 11.465235][ T255] __might_resched.cold+0x1fe/0x2c1 [ 11.465240][ T255] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.465244][ T255] ? __kmalloc_noprof+0xdb/0x760 [ 11.465249][ T255] __kmalloc_noprof+0x443/0x760 [ 11.465252][ T255] ? alloc_buf.isra.0+0x4b/0x260 [ 11.465258][ T255] ? do_raw_spin_unlock+0x59/0x250 [ 11.465260][ T255] alloc_buf.isra.0+0x4b/0x260 [ 11.465264][ T255] put_chars+0x1e1/0x2f0 [ 11.465267][ T255] ? __send_to_port+0x420/0x420 [ 11.465271][ T255] ? validate_chain+0x34a/0xc20 [ 11.465275][ T255] hvc_console_print+0x292/0x780 [ 11.465277][ T255] ? mark_usage+0x61/0x170 [ 11.465279][ T255] ? __lock_acquire+0x518/0xc20 [ 11.465281][ T255] ? __lock_acquire+0x518/0xc20 [ 11.465285][ T255] ? hvc_write+0x3a0/0x3a0 [ 11.465287][ T255] ? console_emit_next_record+0x1df/0x4c0 [ 11.465290][ T255] ? rcu_is_watching+0x16/0xd0 [ 11.465294][ T255] ? lock_acquire+0x13c/0x160 [ 11.465298][ T255] console_emit_next_record+0x22f/0x4c0 [ 11.465302][ T255] ? devkmsg_read+0x4b0/0x4b0 [ 11.465304][ T255] ? console_flush_one_record+0x106/0x710 [ 11.465307][ T255] ? rcu_is_watching+0x16/0xd0 [ 11.465309][ T255] ? lock_acquire+0x13c/0x160 [ 11.465313][ T255] console_flush_one_record+0x46f/0x710 [ 11.465318][ T255] ? console_emit_next_record+0x4c0/0x4c0 [ 11.465319][ T255] ? __lock_acquire+0x518/0xc20 [ 11.465325][ T255] console_unlock+0xee/0x1f0 [ 11.465327][ T255] ? console_flush_one_record+0x710/0x710 [ 11.465329][ T255] ? rcu_is_watching+0x16/0xd0 [ 11.465332][ T255] ? lock_acquire+0x60/0x160 [ 11.465335][ T255] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.465337][ T255] ? vprintk_emit+0x320/0x3e0 [ 11.465340][ T255] vprintk_emit+0x37c/0x3e0 [ 11.465344][ T255] ? wake_up_klogd_work_func+0x90/0x90 [ 11.465347][ T255] ? __lock_release.isra.0+0x69/0x1a0 [ 11.465349][ T255] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 11.465353][ T255] ? mark_held_locks+0x40/0x70 [ 11.465357][ T255] _printk+0xc7/0x100 [ 11.465360][ T255] ? snapshot_read.cold+0x21/0x21 [ 11.465364][ T255] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 11.465378][ T255] ? do_setlink.isra.0+0xa31/0x2750 [ 11.465379][ T255] ? rtnl_newlink+0x9f1/0x11c0 [ 11.465380][ T255] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.465385][ T255] br_set_state+0x22f/0x430 [bridge] [ 11.465395][ T255] br_init_port+0xc4/0x200 [bridge] [ 11.465404][ T255] new_nbp+0x39c/0x580 [bridge] [ 11.465413][ T255] br_add_if+0x212/0x1320 [bridge] [ 11.465421][ T255] ? is_bpf_text_address+0x72/0x110 [ 11.465425][ T255] ? kernel_text_address+0x149/0x170 [ 11.465428][ T255] ? __kernel_text_address+0x12/0x30 [ 11.465432][ T255] do_set_master+0x357/0x580 [ 11.465437][ T255] do_setlink.isra.0+0xa31/0x2750 [ 11.465440][ T255] ? stack_trace_save+0x93/0xc0 [ 11.465444][ T255] ? rtnl_link_get_size+0x350/0x350 [ 11.465445][ T255] ? rcu_read_lock_any_held+0x66/0x90 [ 11.465448][ T255] ? stack_depot_save_flags+0x38e/0x790 [ 11.465451][ T255] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.465454][ T255] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.465456][ T255] ? validate_chain+0x38b/0xc20 [ 11.465459][ T255] ? kasan_save_stack+0x3d/0x50 [ 11.465462][ T255] ? kasan_save_stack+0x2f/0x50 [ 11.465463][ T255] ? kasan_save_track+0x14/0x30 [ 11.465466][ T255] ? __lock_acquire+0x518/0xc20 [ 11.465468][ T255] ? netlink_seq_next+0x4e/0x60 [ 11.465471][ T255] ? ___sys_sendmsg+0xb0/0x1d0 [ 11.465476][ T255] ? lock_acquire.part.0+0xd4/0x280 [ 11.465478][ T255] ? rtnl_newlink+0x9a8/0x11c0 [ 11.465480][ T255] ? rcu_is_watching+0x16/0xd0 [ 11.465483][ T255] ? lock_acquire+0x13c/0x160 [ 11.465484][ T255] ? rcu_is_watching+0x16/0xd0 [ 11.465486][ T255] ? rcu_is_watching+0x16/0xd0 [ 11.465489][ T255] ? trace_contention_end+0xb3/0x180 [ 11.465493][ T255] ? __mutex_lock+0x1db/0x1ea0 [ 11.465495][ T255] ? __mutex_lock+0x9a3/0x1ea0 [ 11.465497][ T255] ? rtnl_newlink+0x9a8/0x11c0 [ 11.465501][ T255] ? ww_mutex_lock+0x160/0x160 [ 11.465502][ T255] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.465507][ T255] ? __rtnl_newlink+0x3fa/0xa50 [ 11.465513][ T255] rtnl_newlink+0x9f1/0x11c0 [ 11.465518][ T255] ? rtnl_bridge_getlink+0x850/0x850 [ 11.465520][ T255] ? __lock_acquire+0x518/0xc20 [ 11.465524][ T255] ? lock_acquire.part.0+0xd4/0x280 [ 11.465526][ T255] ? find_held_lock+0x2b/0x80 [ 11.465528][ T255] ? rtnl_bridge_getlink+0x850/0x850 [ 11.465530][ T255] ? __lock_release.isra.0+0x69/0x1a0 [ 11.465534][ T255] ? rtnl_bridge_getlink+0x850/0x850 [ 11.465536][ T255] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.465539][ T255] ? rtnl_link_fill+0x920/0x920 [ 11.465540][ T255] ? __lock_acquire+0x518/0xc20 [ 11.465545][ T255] ? lock_acquire.part.0+0xd4/0x280 [ 11.465547][ T255] ? find_held_lock+0x2b/0x80 [ 11.465550][ T255] netlink_rcv_skb+0x14e/0x3a0 [ 11.465552][ T255] ? rtnl_link_fill+0x920/0x920 [ 11.465555][ T255] ? netlink_ack+0xcf0/0xcf0 [ 11.465561][ T255] ? netlink_deliver_tap+0xc5/0x330 [ 11.465563][ T255] ? netlink_deliver_tap+0x13c/0x330 [ 11.465567][ T255] netlink_unicast+0x486/0x750 [ 11.465571][ T255] ? netlink_attachskb+0x810/0x810 [ 11.465574][ T255] ? __lock_acquire+0x518/0xc20 [ 11.465578][ T255] netlink_sendmsg+0x735/0xc60 [ 11.465582][ T255] ? netlink_unicast+0x750/0x750 [ 11.465588][ T255] ? __might_fault+0x97/0x140 [ 11.465594][ T255] ____sys_sendmsg+0x415/0x880 [ 11.465596][ T255] ? copy_msghdr_from_user+0x279/0x420 [ 11.465599][ T255] ? get_timestamp.constprop.0+0x390/0x390 [ 11.465600][ T255] ? move_addr_to_kernel+0x40/0x40 [ 11.465608][ T255] ___sys_sendmsg+0x14e/0x1d0 [ 11.465611][ T255] ? copy_msghdr_from_user+0x420/0x420 [ 11.465628][ T255] __sys_sendmsg+0x12c/0x1d0 [ 11.465631][ T255] ? __sys_sendmsg_sock+0x20/0x20 [ 11.465638][ T255] ? rcu_is_watching+0x16/0xd0 [ 11.465642][ T255] do_syscall_64+0xff/0x530 [ 11.465644][ T255] ? exc_page_fault+0xee/0x100 [ 11.465647][ T255] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.465650][ T255] RIP: 0033:0x7f54291dc54e [ 11.465653][ T255] 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.465655][ T255] RSP: 002b:00007ffc48180ce0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.465657][ T255] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f54291dc54e [ 11.465659][ T255] RDX: 0000000000000000 RSI: 00007ffc48180d90 RDI: 0000000000000005 [ 11.465660][ T255] RBP: 00007ffc48180cf0 R08: 0000000000000000 R09: 0000000000000000 [ 11.465661][ T255] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91041d [ 11.465662][ T255] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffc48181450 [ 11.465669][ T255] [ 11.512912][ T255] br0: port 1(veth1) entered disabled state [ 11.513331][ T255] veth1: entered allmulticast mode [ 11.515083][ T255] veth1: entered promiscuous mode [ 11.525489][ T255] ip (255) used greatest stack depth: 23336 bytes left [ 11.557655][ T257] br0: port 2(veth2) entered blocking state [ 11.558006][ T257] br0: port 2(veth2) entered disabled state [ 11.558314][ T257] veth2: entered allmulticast mode [ 11.560105][ T257] veth2: entered promiscuous mode [ 11.591183][ T258] br0: port 3(veth4) entered blocking state [ 11.591519][ T258] br0: port 3(veth4) entered disabled state [ 11.591937][ T258] veth4: entered allmulticast mode [ 11.593721][ T258] veth4: entered promiscuous mode [ 11.838819][ T39] br0: port 1(veth1) entered blocking state [ 11.839480][ T39] br0: port 1(veth1) entered forwarding state [ 11.872154][ T38] br0: port 2(veth2) entered blocking state [ 11.872690][ T38] br0: port 2(veth2) entered forwarding state [ 11.903585][ T38] br0: port 3(veth4) entered blocking state [ 11.903947][ T38] br0: port 3(veth4) entered forwarding state [ 25.874106][ T330] veth3: entered promiscuous mode [ 25.874127][ T330] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 25.874130][ T330] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 330, name: ip [ 25.874132][ T330] preempt_count: 1, expected: 0 [ 25.874133][ T330] RCU nest depth: 0, expected: 0 [ 25.874134][ T330] locks held by ip/330: 5, last CPU#3: [ 25.874136][ T330] #0: ffffffffbccd2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 25.874148][ T330] #1: ffffffffbc569cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 25.874155][ T330] #2: ffffffffbc569d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 25.874159][ T330] #3: ffffffffbc489660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 25.874163][ T330] #4: ffffffffbc489560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 25.874167][ T330] irq event stamp: 21218 [ 25.874168][ T330] hardirqs last enabled at (21217): [] __down_trylock_console_sem+0x86/0xa0 [ 25.874170][ T330] hardirqs last disabled at (21218): [] console_emit_next_record+0x3d4/0x4c0 [ 25.874172][ T330] softirqs last enabled at (21212): [] __dev_change_flags+0x20b/0x660 [ 25.874175][ T330] softirqs last disabled at (21210): [] dev_set_rx_mode+0x56/0xa0 [ 25.874178][ T330] Preemption disabled at: [ 25.874179][ T330] [] vprintk_emit+0x31b/0x3e0 [ 25.874185][ T330] CPU: 3 UID: 0 PID: 330 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 25.874189][ T330] Tainted: [W]=WARN [ 25.874190][ T330] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 25.874192][ T330] Call Trace: [ 25.874193][ T330] [ 25.874195][ T330] dump_stack_lvl+0x6f/0xa0 [ 25.874201][ T330] ? vprintk_emit+0x31b/0x3e0 [ 25.874203][ T330] __might_resched.cold+0x1fe/0x2c1 [ 25.874208][ T330] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 25.874212][ T330] ? __kmalloc_noprof+0xdb/0x760 [ 25.874217][ T330] __kmalloc_noprof+0x443/0x760 [ 25.874220][ T330] ? alloc_buf.isra.0+0x4b/0x260 [ 25.874225][ T330] ? do_raw_spin_unlock+0x59/0x250 [ 25.874228][ T330] alloc_buf.isra.0+0x4b/0x260 [ 25.874231][ T330] put_chars+0x1e1/0x2f0 [ 25.874233][ T330] ? desc_read_finalized_seq+0x79/0x120 [ 25.874235][ T330] ? __send_to_port+0x420/0x420 [ 25.874239][ T330] ? rcu_read_lock_any_held+0x3c/0x90 [ 25.874242][ T330] ? validate_chain+0x38b/0xc20 [ 25.874246][ T330] hvc_console_print+0x292/0x780 [ 25.874249][ T330] ? __lock_acquire+0x518/0xc20 [ 25.874250][ T330] ? __lock_acquire+0x518/0xc20 [ 25.874255][ T330] ? hvc_write+0x3a0/0x3a0 [ 25.874256][ T330] ? console_emit_next_record+0x1df/0x4c0 [ 25.874260][ T330] ? rcu_is_watching+0x16/0xd0 [ 25.874263][ T330] ? lock_acquire+0x13c/0x160 [ 25.874267][ T330] console_emit_next_record+0x22f/0x4c0 [ 25.874271][ T330] ? devkmsg_read+0x4b0/0x4b0 [ 25.874273][ T330] ? console_flush_one_record+0x106/0x710 [ 25.874276][ T330] ? rcu_is_watching+0x16/0xd0 [ 25.874278][ T330] ? lock_acquire+0x13c/0x160 [ 25.874282][ T330] console_flush_one_record+0x46f/0x710 [ 25.874287][ T330] ? console_emit_next_record+0x4c0/0x4c0 [ 25.874288][ T330] ? __lock_acquire+0x518/0xc20 [ 25.874294][ T330] console_unlock+0xee/0x1f0 [ 25.874297][ T330] ? console_flush_one_record+0x710/0x710 [ 25.874298][ T330] ? rcu_is_watching+0x16/0xd0 [ 25.874301][ T330] ? lock_acquire+0x60/0x160 [ 25.874304][ T330] ? __down_trylock_console_sem+0x5e/0xa0 [ 25.874306][ T330] ? vprintk_emit+0x320/0x3e0 [ 25.874310][ T330] vprintk_emit+0x37c/0x3e0 [ 25.874313][ T330] ? wake_up_klogd_work_func+0x90/0x90 [ 25.874315][ T330] ? mark_usage+0x61/0x170 [ 25.874318][ T330] ? __lock_acquire+0x518/0xc20 [ 25.874319][ T330] ? validate_chain+0x737/0xc20 [ 25.874321][ T330] ? find_held_lock+0x2/0x80 [ 25.874325][ T330] _printk+0xc7/0x100 [ 25.874328][ T330] ? snapshot_read.cold+0x21/0x21 [ 25.874332][ T330] ? rcu_read_lock_any_held+0x3c/0x90 [ 25.874334][ T330] ? validate_chain+0x38b/0xc20 [ 25.874336][ T330] ? netdev_name+0x43/0x60 [ 25.874339][ T330] ? __netdev_printk+0x17f/0x1d0 [ 25.874343][ T330] netdev_info+0xe2/0x116 [ 25.874345][ T330] ? netdev_notice+0x120/0x120 [ 25.874350][ T330] ? do_raw_spin_lock+0x131/0x280 [ 25.874351][ T330] ? find_held_lock+0x2b/0x80 [ 25.874354][ T330] ? __lock_release.isra.0+0x69/0x1a0 [ 25.874356][ T330] ? mutex_is_locked+0x1c/0x50 [ 25.874360][ T330] __dev_set_promiscuity.cold+0x95/0x104 [ 25.874364][ T330] __dev_change_flags+0x396/0x660 [ 25.874368][ T330] ? netif_set_allmulti+0x250/0x250 [ 25.874370][ T330] ? kernel_text_address+0x149/0x170 [ 25.874373][ T330] ? __kernel_text_address+0x12/0x30 [ 25.874374][ T330] ? write_profile+0xf0/0xf0 [ 25.874377][ T330] ? unwind_get_return_address+0x67/0xd0 [ 25.874382][ T330] netif_change_flags+0x87/0x190 [ 25.874386][ T330] do_setlink.isra.0+0x1cc3/0x2750 [ 25.874391][ T330] ? stack_trace_save+0x93/0xc0 [ 25.874393][ T330] ? rtnl_link_get_size+0x350/0x350 [ 25.874394][ T330] ? rcu_read_lock_any_held+0x66/0x90 [ 25.874396][ T330] ? stack_depot_save_flags+0x38e/0x790 [ 25.874400][ T330] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 25.874403][ T330] ? rcu_read_lock_any_held+0x3c/0x90 [ 25.874405][ T330] ? validate_chain+0x38b/0xc20 [ 25.874409][ T330] ? kasan_save_stack+0x3d/0x50 [ 25.874413][ T330] ? kasan_save_stack+0x2f/0x50 [ 25.874415][ T330] ? kasan_save_track+0x14/0x30 [ 25.874419][ T330] ? __lock_acquire+0x518/0xc20 [ 25.874422][ T330] ? netlink_seq_next+0x4e/0x60 [ 25.874426][ T330] ? ___sys_sendmsg+0xb0/0x1d0 [ 25.874432][ T330] ? lock_acquire.part.0+0xd4/0x280 [ 25.874435][ T330] ? rtnl_newlink+0x9a8/0x11c0 [ 25.874439][ T330] ? rcu_is_watching+0x16/0xd0 [ 25.874443][ T330] ? lock_acquire+0x13c/0x160 [ 25.874445][ T330] ? rcu_is_watching+0x16/0xd0 [ 25.874447][ T330] ? rcu_is_watching+0x16/0xd0 [ 25.874451][ T330] ? trace_contention_end+0xb3/0x180 [ 25.874455][ T330] ? __mutex_lock+0x1db/0x1ea0 [ 25.874459][ T330] ? __mutex_lock+0x9a3/0x1ea0 [ 25.874461][ T330] ? rtnl_newlink+0x9a8/0x11c0 [ 25.874464][ T330] ? ww_mutex_lock+0x160/0x160 [ 25.874470][ T330] ? __rtnl_newlink+0x3fa/0xa50 [ 25.874475][ T330] rtnl_newlink+0x9f1/0x11c0 [ 25.874480][ T330] ? rtnl_bridge_getlink+0x850/0x850 [ 25.874482][ T330] ? __lock_acquire+0x518/0xc20 [ 25.874487][ T330] ? lock_acquire.part.0+0xd4/0x280 [ 25.874489][ T330] ? find_held_lock+0x2b/0x80 [ 25.874492][ T330] ? rtnl_bridge_getlink+0x850/0x850 [ 25.874494][ T330] ? __lock_release.isra.0+0x69/0x1a0 [ 25.874497][ T330] ? rtnl_bridge_getlink+0x850/0x850 [ 25.874499][ T330] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 25.874503][ T330] ? rtnl_link_fill+0x920/0x920 [ 25.874504][ T330] ? __lock_acquire+0x518/0xc20 [ 25.874508][ T330] ? lock_acquire.part.0+0xd4/0x280 [ 25.874510][ T330] ? find_held_lock+0x2b/0x80 [ 25.874514][ T330] netlink_rcv_skb+0x14e/0x3a0 [ 25.874515][ T330] ? rtnl_link_fill+0x920/0x920 [ 25.874518][ T330] ? netlink_ack+0xcf0/0xcf0 [ 25.874524][ T330] ? netlink_deliver_tap+0xc5/0x330 [ 25.874526][ T330] ? netlink_deliver_tap+0x13c/0x330 [ 25.874530][ T330] netlink_unicast+0x486/0x750 [ 25.874534][ T330] ? netlink_attachskb+0x810/0x810 [ 25.874537][ T330] ? __lock_acquire+0x518/0xc20 [ 25.874541][ T330] netlink_sendmsg+0x735/0xc60 [ 25.874545][ T330] ? netlink_unicast+0x750/0x750 [ 25.874549][ T330] ? __might_fault+0x97/0x140 [ 25.874554][ T330] ____sys_sendmsg+0x415/0x880 [ 25.874556][ T330] ? copy_msghdr_from_user+0x279/0x420 [ 25.874559][ T330] ? get_timestamp.constprop.0+0x390/0x390 [ 25.874560][ T330] ? move_addr_to_kernel+0x40/0x40 [ 25.874568][ T330] ___sys_sendmsg+0x14e/0x1d0 [ 25.874570][ T330] ? copy_msghdr_from_user+0x420/0x420 [ 25.874588][ T330] __sys_sendmsg+0x12c/0x1d0 [ 25.874591][ T330] ? __sys_sendmsg_sock+0x20/0x20 [ 25.874598][ T330] ? rcu_is_watching+0x16/0xd0 [ 25.874601][ T330] do_syscall_64+0xff/0x530 [ 25.874604][ T330] ? exc_page_fault+0xee/0x100 [ 25.874607][ T330] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 25.874609][ T330] RIP: 0033:0x7fcb1f02154e [ 25.874614][ T330] 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 [ 25.874615][ T330] RSP: 002b:00007ffe3d6814f0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 25.874618][ T330] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007fcb1f02154e [ 25.874620][ T330] RDX: 0000000000000000 RSI: 00007ffe3d6815a0 RDI: 0000000000000005 [ 25.874621][ T330] RBP: 00007ffe3d681500 R08: 0000000000000000 R09: 0000000000000000 [ 25.874621][ T330] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91042c [ 25.874622][ T330] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffe3d681c60 [ 25.874629][ T330] [ 25.920639][ T330] ip (330) used greatest stack depth: 23208 bytes left [ 26.137052][ T336] GACT probability NOT on [ 27.005665][ C3] [ 27.005684][ C3] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 27.005686][ C3] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/3 [ 27.005688][ C3] preempt_count: 104, expected: 0 [ 27.005689][ C3] RCU nest depth: 0, expected: 0 [ 27.005690][ C3] INFO: lockdep is turned off. [ 27.005691][ C3] irq event stamp: 564218 [ 27.005692][ C3] hardirqs last enabled at (564218): [] _raw_spin_unlock_irq+0x28/0x50 [ 27.005702][ C3] hardirqs last disabled at (564217): [] _raw_spin_lock_irq+0x4a/0x50 [ 27.005703][ C3] softirqs last enabled at (564166): [] handle_softirqs+0x67c/0x900 [ 27.005707][ C3] softirqs last disabled at (564215): [] __irq_exit_rcu+0x145/0x1c0 [ 27.005709][ C3] Preemption disabled at: [ 27.005710][ C3] [<0000000000000000>] 0x0 [ 27.005718][ C3] CPU: 3 UID: 0 PID: 0 Comm: swapper/3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.005722][ C3] Tainted: [W]=WARN [ 27.005723][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.005725][ C3] Call Trace: [ 27.005727][ C3] [ 27.005730][ C3] dump_stack_lvl+0x6f/0xa0 [ 27.005735][ C3] __might_resched.cold+0x1fe/0x2c1 [ 27.005739][ C3] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 27.005743][ C3] ? __asan_memcpy+0x3c/0x60 [ 27.005745][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.005749][ C3] __kmalloc_noprof+0x443/0x760 [ 27.005752][ C3] ? __rwlock_init+0x150/0x150 [ 27.005755][ C3] ? alloc_buf.isra.0+0x4b/0x260 [ 27.005759][ C3] ? do_raw_spin_unlock+0x59/0x250 [ 27.005760][ C3] alloc_buf.isra.0+0x4b/0x260 [ 27.005762][ C3] put_chars+0x1e1/0x2f0 [ 27.005764][ C3] ? __send_to_port+0x420/0x420 [ 27.005766][ C3] ? console_prepend_replay+0x20/0x20 [ 27.005769][ C3] hvc_console_print+0x292/0x780 [ 27.005773][ C3] ? hvc_write+0x3a0/0x3a0 [ 27.005774][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.005776][ C3] ? lock_acquire+0x13c/0x160 [ 27.005778][ C3] console_emit_next_record+0x22f/0x4c0 [ 27.005780][ C3] ? devkmsg_read+0x4b0/0x4b0 [ 27.005782][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.005784][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.005786][ C3] ? lock_acquire+0x13c/0x160 [ 27.005787][ C3] ? console_flush_one_record+0x111/0x710 [ 27.005789][ C3] console_flush_one_record+0x46f/0x710 [ 27.005791][ C3] ? console_emit_next_record+0x4c0/0x4c0 [ 27.005794][ C3] console_unlock+0xee/0x1f0 [ 27.005796][ C3] ? lock_acquire+0x13c/0x160 [ 27.005797][ C3] ? console_flush_one_record+0x710/0x710 [ 27.005799][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.005800][ C3] ? lock_release+0x184/0x1f0 [ 27.005802][ C3] ? lock_acquire+0x60/0x160 [ 27.005803][ C3] ? __down_trylock_console_sem+0x5e/0xa0 [ 27.005806][ C3] ? vprintk_emit+0x320/0x3e0 [ 27.005807][ C3] vprintk_emit+0x37c/0x3e0 [ 27.005810][ C3] ? wake_up_klogd_work_func+0x90/0x90 [ 27.005812][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.005824][ C3] ? lock_release+0x184/0x1f0 [ 27.005825][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.005832][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.005838][ C3] ? is_module_text_address+0x154/0x250 [ 27.005842][ C3] _printk+0xc7/0x100 [ 27.005845][ C3] ? snapshot_read.cold+0x21/0x21 [ 27.005846][ C3] ? arch_stack_walk+0xd7/0x130 [ 27.005851][ C3] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 27.005853][ C3] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 27.005854][ C3] print_irq_inversion_bug.part.0+0x32/0xc0 [ 27.005857][ C3] mark_lock_irq+0x989/0x9c0 [ 27.005859][ C3] mark_lock+0x1d7/0xa00 [ 27.005861][ C3] mark_usage+0x42/0x170 [ 27.005863][ C3] __lock_acquire+0x388/0xc20 [ 27.005865][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.005867][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.005873][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.005875][ C3] ? lock_acquire+0x13c/0x160 [ 27.005876][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.005883][ C3] _raw_spin_lock+0x33/0x40 [ 27.005884][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.005890][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.005897][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.005904][ C3] call_timer_fn+0x160/0x4d0 [ 27.005906][ C3] ? detach_if_pending+0x1d0/0x1d0 [ 27.005908][ C3] ? debug_object_active_state+0x430/0x430 [ 27.005912][ C3] ? find_held_lock+0x2b/0x80 [ 27.005914][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 27.005915][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.005918][ C3] __run_timers+0x68f/0xaa0 [ 27.005919][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.005926][ C3] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.005928][ C3] ? __lock_acquire+0x518/0xc20 [ 27.005931][ C3] ? __rwlock_init+0x150/0x150 [ 27.005933][ C3] run_timer_softirq+0xf0/0x160 [ 27.005935][ C3] ? __run_timers+0xaa0/0xaa0 [ 27.005937][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.005939][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.005941][ C3] handle_softirqs+0x1d3/0x900 [ 27.005942][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 27.005944][ C3] ? _local_bh_enable+0xc0/0xc0 [ 27.005946][ C3] __irq_exit_rcu+0x145/0x1c0 [ 27.005947][ C3] irq_exit_rcu+0xe/0x30 [ 27.005949][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.005952][ C3] [ 27.005952][ C3] [ 27.005953][ C3] ? lockdep_hardirqs_on+0x91/0x130 [ 27.005955][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.005957][ C3] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.005960][ 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 [ 27.005961][ C3] RSP: 0018:ffa0000000167e00 EFLAGS: 00000282 [ 27.005964][ C3] RAX: 0000000000089bf5 RBX: ff11000001c88040 RCX: ffffffffb92f0307 [ 27.005966][ C3] RDX: ff11000001c88040 RSI: ffffffffbc038b01 RDI: ffffffffbba8d8e0 [ 27.005966][ C3] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.005967][ C3] R10: 0000000000000003 R11: 0000000000000001 R12: 1ff400000002cfc3 [ 27.005968][ C3] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 27.005969][ C3] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.005979][ C3] default_idle+0x9/0x10 [ 27.005981][ C3] default_idle_call+0x6e/0xb0 [ 27.005983][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.005984][ C3] ? arch_cpu_idle_exit+0x40/0x40 [ 27.005986][ C3] ? mark_tsc_async_resets+0x30/0x30 [ 27.005989][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.005991][ C3] do_idle+0xd8/0x190 [ 27.005992][ C3] cpu_startup_entry+0x53/0x70 [ 27.005994][ C3] start_secondary+0x204/0x2b0 [ 27.005996][ C3] ? set_cpu_sibling_map+0x2130/0x2130 [ 27.005998][ C3] common_startup_64+0x13e/0x148 [ 27.006002][ C3] [ 27.028663][ C3] ======================================================== [ 27.028865][ C3] WARNING: possible irq lock inversion dependency detected [ 27.029074][ C3] 7.2.0-virtme #1 Tainted: G W [ 27.029243][ C3] -------------------------------------------------------- [ 27.029441][ C3] swapper/3/0 just changed the state of lock: [ 27.029611][ C3] ff1100001000ae58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.029892][ C3] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 27.030104][ C3] (fs_reclaim){+.+.}-{0:0} [ 27.030106][ C3] [ 27.030106][ C3] [ 27.030106][ C3] and interrupts could create inverse lock ordering between them. [ 27.030106][ C3] [ 27.030695][ C3] [ 27.030695][ C3] other info that might help us debug this: [ 27.030935][ C3] Chain exists of: [ 27.030935][ C3] &br->lock --> console_owner --> fs_reclaim [ 27.030935][ C3] [ 27.031292][ C3] Possible interrupt unsafe locking scenario: [ 27.031292][ C3] [ 27.031491][ C3] CPU0 CPU1 [ 27.031672][ C3] ---- ---- [ 27.031811][ C3] lock(fs_reclaim); [ 27.031961][ C3] local_irq_disable(); [ 27.032135][ C3] lock(&br->lock); [ 27.032350][ C3] lock(console_owner); [ 27.032563][ C3] [ 27.032665][ C3] lock(&br->lock); [ 27.032767][ C3] [ 27.032767][ C3] *** DEADLOCK *** [ 27.032767][ C3] [ 27.033018][ C3] locks held by swapper/3/0: 1, last CPU#3: [ 27.033230][ C3] #0: ffa0000000280c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 27.033548][ C3] [ 27.033548][ C3] the shortest dependencies between 2nd lock and 1st lock: [ 27.033828][ C3] -> (fs_reclaim){+.+.}-{0:0} { [ 27.033965][ C3] HARDIRQ-ON-W at: [ 27.034119][ C3] __lock_acquire+0x388/0xc20 [ 27.034295][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.034509][ C3] fs_reclaim_acquire+0xd5/0x120 [ 27.034681][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 27.034929][ C3] kthread_create_worker_on_node+0xea/0x210 [ 27.035182][ C3] workqueue_init+0x2a/0x680 [ 27.035401][ C3] kernel_init_freeable+0x2fe/0x630 [ 27.035609][ C3] kernel_init+0x21/0x150 [ 27.035824][ C3] ret_from_fork+0x474/0x6b0 [ 27.036046][ C3] ret_from_fork_asm+0x11/0x20 [ 27.036217][ C3] SOFTIRQ-ON-W at: [ 27.036363][ C3] __lock_acquire+0x388/0xc20 [ 27.036532][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.036700][ C3] fs_reclaim_acquire+0xd5/0x120 [ 27.036868][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 27.037119][ C3] kthread_create_worker_on_node+0xea/0x210 [ 27.037367][ C3] workqueue_init+0x2a/0x680 [ 27.037582][ C3] kernel_init_freeable+0x2fe/0x630 [ 27.037789][ C3] kernel_init+0x21/0x150 [ 27.038010][ C3] ret_from_fork+0x474/0x6b0 [ 27.038228][ C3] ret_from_fork_asm+0x11/0x20 [ 27.038401][ C3] INITIAL USE at: [ 27.038548][ C3] __lock_acquire+0x388/0xc20 [ 27.038720][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.038931][ C3] fs_reclaim_acquire+0xd5/0x120 [ 27.039106][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 27.039358][ C3] kthread_create_worker_on_node+0xea/0x210 [ 27.039611][ C3] workqueue_init+0x2a/0x680 [ 27.039825][ C3] kernel_init_freeable+0x2fe/0x630 [ 27.040003][ C3] kernel_init+0x21/0x150 [ 27.040218][ C3] ret_from_fork+0x474/0x6b0 [ 27.040383][ C3] ret_from_fork_asm+0x11/0x20 [ 27.040602][ C3] } [ 27.040672][ C3] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 27.040917][ C3] ... acquired at: [ 27.041025][ C3] __lock_acquire+0x518/0xc20 [ 27.041209][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.041342][ C3] fs_reclaim_acquire+0xd5/0x120 [ 27.041520][ C3] __kmalloc_noprof+0xd3/0x760 [ 27.041660][ C3] alloc_buf.isra.0+0x4b/0x260 [ 27.041838][ C3] put_chars+0x1e1/0x2f0 [ 27.041975][ C3] hvc_console_print+0x292/0x780 [ 27.042154][ C3] console_emit_next_record+0x22f/0x4c0 [ 27.042287][ C3] console_flush_one_record+0x46f/0x710 [ 27.042463][ C3] console_unlock+0xee/0x1f0 [ 27.042597][ C3] vprintk_emit+0x37c/0x3e0 [ 27.042727][ C3] dev_vprintk_emit+0x27f/0x2c0 [ 27.042861][ C3] dev_printk_emit+0xb9/0xee [ 27.043043][ C3] _dev_info+0xe2/0x116 [ 27.043143][ C3] cmos_do_probe+0x8be/0x98a [ 27.043320][ C3] platform_probe+0xfe/0x1f0 [ 27.043453][ C3] call_driver_probe+0x61/0x1c0 [ 27.043630][ C3] really_probe+0x199/0x760 [ 27.043760][ C3] __driver_probe_device+0x24f/0x440 [ 27.043939][ C3] driver_probe_device+0x4a/0xf0 [ 27.044076][ C3] __driver_attach+0x1b8/0x540 [ 27.044252][ C3] bus_for_each_dev+0x130/0x1e0 [ 27.044386][ C3] bus_add_driver+0x2c8/0x530 [ 27.044574][ C3] driver_register+0x1a3/0x390 [ 27.044708][ C3] __platform_driver_probe+0x13f/0x270 [ 27.044884][ C3] cmos_init+0x31/0x40 [ 27.044992][ C3] do_one_initcall+0x124/0x4f0 [ 27.045169][ C3] kernel_init_freeable+0x596/0x630 [ 27.045301][ C3] kernel_init+0x21/0x150 [ 27.045480][ C3] ret_from_fork+0x474/0x6b0 [ 27.045615][ C3] ret_from_fork_asm+0x11/0x20 [ 27.045791][ C3] [ 27.045859][ C3] -> (console_owner){....}-{0:0} { [ 27.046001][ C3] INITIAL USE at: [ 27.046146][ C3] } [ 27.046218][ C3] ... key at: [] console_owner_dep_map+0x0/0x60 [ 27.046458][ C3] ... acquired at: [ 27.046557][ C3] __lock_acquire+0x518/0xc20 [ 27.046736][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.046870][ C3] console_lock_spinning_enable+0x5c/0x60 [ 27.047087][ C3] console_emit_next_record+0x1d1/0x4c0 [ 27.047222][ C3] console_flush_one_record+0x46f/0x710 [ 27.047399][ C3] console_unlock+0xee/0x1f0 [ 27.047534][ C3] vprintk_emit+0x37c/0x3e0 [ 27.047669][ C3] _printk+0xc7/0x100 [ 27.047772][ C3] br_set_state+0x22f/0x430 [bridge] [ 27.047959][ C3] br_init_port+0xc4/0x200 [bridge] [ 27.048107][ C3] br_stp_enable_port+0x12/0x50 [bridge] [ 27.048322][ C3] br_port_carrier_check+0x220/0x430 [bridge] [ 27.048494][ C3] br_device_event+0x52d/0x8f0 [bridge] [ 27.048676][ C3] notifier_call_chain+0xae/0x300 [ 27.048810][ C3] netif_state_change+0x139/0x340 [ 27.048994][ C3] __linkwatch_run_queue+0x34c/0x750 [ 27.049127][ C3] linkwatch_event+0x7f/0xb0 [ 27.049303][ C3] process_one_work+0xe3e/0x1560 [ 27.049440][ C3] worker_thread+0x4f1/0xd60 [ 27.049573][ C3] kthread+0x367/0x460 [ 27.049673][ C3] ret_from_fork+0x474/0x6b0 [ 27.049850][ C3] ret_from_fork_asm+0x11/0x20 [ 27.049990][ C3] [ 27.050100][ C3] -> (&br->lock){+.-.}-{3:3} { [ 27.050235][ C3] HARDIRQ-ON-W at: [ 27.050334][ C3] __lock_acquire+0x388/0xc20 [ 27.050554][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.050768][ C3] _raw_spin_lock_bh+0x38/0x50 [ 27.050933][ C3] br_add_if+0x8c1/0x1320 [bridge] [ 27.051152][ C3] do_set_master+0x357/0x580 [ 27.051366][ C3] do_setlink.isra.0+0xa31/0x2750 [ 27.051533][ C3] rtnl_newlink+0x9f1/0x11c0 [ 27.051714][ C3] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.051880][ C3] netlink_rcv_skb+0x14e/0x3a0 [ 27.052098][ C3] netlink_unicast+0x486/0x750 [ 27.052308][ C3] netlink_sendmsg+0x735/0xc60 [ 27.052477][ C3] ____sys_sendmsg+0x415/0x880 [ 27.052693][ C3] ___sys_sendmsg+0x14e/0x1d0 [ 27.052907][ C3] __sys_sendmsg+0x12c/0x1d0 [ 27.053080][ C3] do_syscall_64+0xff/0x530 [ 27.053291][ C3] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.053536][ C3] IN-SOFTIRQ-W at: [ 27.053637][ C3] __lock_acquire+0x388/0xc20 [ 27.053849][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.054020][ C3] _raw_spin_lock+0x33/0x40 [ 27.054232][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.054480][ C3] call_timer_fn+0x160/0x4d0 [ 27.054653][ C3] __run_timers+0x68f/0xaa0 [ 27.054862][ C3] run_timer_softirq+0xf0/0x160 [ 27.055034][ C3] handle_softirqs+0x1d3/0x900 [ 27.055249][ C3] __irq_exit_rcu+0x145/0x1c0 [ 27.055468][ C3] irq_exit_rcu+0xe/0x30 [ 27.055639][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.055882][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.056132][ C3] pv_native_safe_halt+0xf/0x10 [ 27.056302][ C3] default_idle+0x9/0x10 [ 27.056515][ C3] default_idle_call+0x6e/0xb0 [ 27.056686][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.056887][ C3] do_idle+0xd8/0x190 [ 27.057070][ C3] cpu_startup_entry+0x53/0x70 [ 27.057284][ C3] start_secondary+0x204/0x2b0 [ 27.057462][ C3] common_startup_64+0x13e/0x148 [ 27.057682][ C3] INITIAL USE at: [ 27.057782][ C3] __lock_acquire+0x388/0xc20 [ 27.058003][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.058176][ C3] _raw_spin_lock_bh+0x38/0x50 [ 27.058394][ C3] br_add_if+0x8c1/0x1320 [bridge] [ 27.058621][ C3] do_set_master+0x357/0x580 [ 27.058795][ C3] do_setlink.isra.0+0xa31/0x2750 [ 27.059021][ C3] rtnl_newlink+0x9f1/0x11c0 [ 27.059238][ C3] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.059411][ C3] netlink_rcv_skb+0x14e/0x3a0 [ 27.059636][ C3] netlink_unicast+0x486/0x750 [ 27.059806][ C3] netlink_sendmsg+0x735/0xc60 [ 27.060035][ C3] ____sys_sendmsg+0x415/0x880 [ 27.060257][ C3] ___sys_sendmsg+0x14e/0x1d0 [ 27.060429][ C3] __sys_sendmsg+0x12c/0x1d0 [ 27.060652][ C3] do_syscall_64+0xff/0x530 [ 27.060875][ C3] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.061085][ C3] } [ 27.061200][ C3] ... key at: [] __key.7+0x0/0x40 [bridge] [ 27.061413][ C3] ... acquired at: [ 27.061565][ C3] mark_lock+0x1d7/0xa00 [ 27.061705][ C3] mark_usage+0x42/0x170 [ 27.061889][ C3] __lock_acquire+0x388/0xc20 [ 27.062033][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.062212][ C3] _raw_spin_lock+0x33/0x40 [ 27.062348][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.062570][ C3] call_timer_fn+0x160/0x4d0 [ 27.062710][ C3] __run_timers+0x68f/0xaa0 [ 27.062891][ C3] run_timer_softirq+0xf0/0x160 [ 27.063033][ C3] handle_softirqs+0x1d3/0x900 [ 27.063213][ C3] __irq_exit_rcu+0x145/0x1c0 [ 27.063350][ C3] irq_exit_rcu+0xe/0x30 [ 27.063531][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.063699][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.063915][ C3] pv_native_safe_halt+0xf/0x10 [ 27.064102][ C3] default_idle+0x9/0x10 [ 27.064237][ C3] default_idle_call+0x6e/0xb0 [ 27.064418][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.064593][ C3] do_idle+0xd8/0x190 [ 27.064742][ C3] cpu_startup_entry+0x53/0x70 [ 27.064877][ C3] start_secondary+0x204/0x2b0 [ 27.065062][ C3] common_startup_64+0x13e/0x148 [ 27.065210][ C3] [ 27.065279][ C3] [ 27.065279][ C3] stack backtrace: [ 27.065500][ C3] CPU: 3 UID: 0 PID: 0 Comm: swapper/3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.065504][ C3] Tainted: [W]=WARN [ 27.065505][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.065506][ C3] Call Trace: [ 27.065508][ C3] [ 27.065509][ C3] dump_stack_lvl+0x6f/0xa0 [ 27.065514][ C3] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 27.065517][ C3] mark_lock_irq+0x989/0x9c0 [ 27.065520][ C3] mark_lock+0x1d7/0xa00 [ 27.065522][ C3] mark_usage+0x42/0x170 [ 27.065524][ C3] __lock_acquire+0x388/0xc20 [ 27.065526][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.065528][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.065536][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.065540][ C3] ? lock_acquire+0x13c/0x160 [ 27.065542][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.065550][ C3] _raw_spin_lock+0x33/0x40 [ 27.065552][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.065559][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.065566][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.065573][ C3] call_timer_fn+0x160/0x4d0 [ 27.065575][ C3] ? detach_if_pending+0x1d0/0x1d0 [ 27.065577][ C3] ? debug_object_active_state+0x430/0x430 [ 27.065581][ C3] ? find_held_lock+0x2b/0x80 [ 27.065583][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 27.065585][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.065588][ C3] __run_timers+0x68f/0xaa0 [ 27.065590][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.065599][ C3] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.065600][ C3] ? __lock_acquire+0x518/0xc20 [ 27.065603][ C3] ? __rwlock_init+0x150/0x150 [ 27.065606][ C3] run_timer_softirq+0xf0/0x160 [ 27.065608][ C3] ? __run_timers+0xaa0/0xaa0 [ 27.065610][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.065612][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.065614][ C3] handle_softirqs+0x1d3/0x900 [ 27.065616][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 27.065618][ C3] ? _local_bh_enable+0xc0/0xc0 [ 27.065620][ C3] __irq_exit_rcu+0x145/0x1c0 [ 27.065621][ C3] irq_exit_rcu+0xe/0x30 [ 27.065623][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.065625][ C3] [ 27.065625][ C3] [ 27.065626][ C3] ? lockdep_hardirqs_on+0x91/0x130 [ 27.065628][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.065630][ C3] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.065632][ 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 [ 27.065634][ C3] RSP: 0018:ffa0000000167e00 EFLAGS: 00000282 [ 27.065637][ C3] RAX: 0000000000089bf5 RBX: ff11000001c88040 RCX: ffffffffb92f0307 [ 27.065638][ C3] RDX: ff11000001c88040 RSI: ffffffffbc038b01 RDI: ffffffffbba8d8e0 [ 27.065639][ C3] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.065640][ C3] R10: 0000000000000003 R11: 0000000000000001 R12: 1ff400000002cfc3 [ 27.065641][ C3] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 27.065642][ C3] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.065645][ C3] default_idle+0x9/0x10 [ 27.065646][ C3] default_idle_call+0x6e/0xb0 [ 27.065648][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.065650][ C3] ? arch_cpu_idle_exit+0x40/0x40 [ 27.065651][ C3] ? mark_tsc_async_resets+0x30/0x30 [ 27.065653][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.065655][ C3] do_idle+0xd8/0x190 [ 27.065657][ C3] cpu_startup_entry+0x53/0x70 [ 27.065658][ C3] start_secondary+0x204/0x2b0 [ 27.065660][ C3] ? set_cpu_sibling_map+0x2130/0x2130 [ 27.065662][ C3] common_startup_64+0x13e/0x148 [ 27.065665][ C3] [ 27.305159][ T345] veth3: left promiscuous mode [ 27.320231][ T346] veth3: entered promiscuous mode [ 28.500504][ T355] veth3: left promiscuous mode [ 28.500521][ T355] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 28.500523][ T355] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 355, name: ip [ 28.500525][ T355] preempt_count: 1, expected: 0 [ 28.500526][ T355] RCU nest depth: 0, expected: 0 [ 28.500527][ T355] INFO: lockdep is turned off. [ 28.500529][ T355] irq event stamp: 0 [ 28.500529][ T355] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 28.500534][ T355] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 28.500540][ T355] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 28.500542][ T355] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 28.500544][ T355] Preemption disabled at: [ 28.500545][ T355] [] vprintk_emit+0x31b/0x3e0 [ 28.500552][ T355] CPU: 0 UID: 0 PID: 355 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 28.500557][ T355] Tainted: [W]=WARN [ 28.500558][ T355] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 28.500560][ T355] Call Trace: [ 28.500561][ T355] [ 28.500562][ T355] dump_stack_lvl+0x6f/0xa0 [ 28.500568][ T355] ? vprintk_emit+0x31b/0x3e0 [ 28.500570][ T355] __might_resched.cold+0x1fe/0x2c1 [ 28.500574][ T355] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 28.500577][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500581][ T355] __kmalloc_noprof+0x443/0x760 [ 28.500588][ T355] ? __rwlock_init+0x150/0x150 [ 28.500590][ T355] ? alloc_buf.isra.0+0x4b/0x260 [ 28.500594][ T355] ? do_raw_spin_unlock+0x59/0x250 [ 28.500596][ T355] alloc_buf.isra.0+0x4b/0x260 [ 28.500598][ T355] put_chars+0x1e1/0x2f0 [ 28.500600][ T355] ? desc_read_finalized_seq+0x79/0x120 [ 28.500601][ T355] ? __send_to_port+0x420/0x420 [ 28.500603][ T355] ? console_prepend_replay+0x20/0x20 [ 28.500606][ T355] hvc_console_print+0x292/0x780 [ 28.500609][ T355] ? hvc_write+0x3a0/0x3a0 [ 28.500610][ T355] ? do_raw_spin_lock+0x131/0x280 [ 28.500612][ T355] ? __rwlock_init+0x150/0x150 [ 28.500613][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500615][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500617][ T355] ? lock_acquire+0x13c/0x160 [ 28.500619][ T355] console_emit_next_record+0x22f/0x4c0 [ 28.500622][ T355] ? devkmsg_read+0x4b0/0x4b0 [ 28.500624][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500625][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500627][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500629][ T355] ? lock_acquire+0x13c/0x160 [ 28.500631][ T355] ? console_flush_one_record+0x111/0x710 [ 28.500632][ T355] console_flush_one_record+0x46f/0x710 [ 28.500635][ T355] ? console_emit_next_record+0x4c0/0x4c0 [ 28.500638][ T355] console_unlock+0xee/0x1f0 [ 28.500639][ T355] ? lock_acquire+0x13c/0x160 [ 28.500641][ T355] ? console_flush_one_record+0x710/0x710 [ 28.500643][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500644][ T355] ? lock_release+0x184/0x1f0 [ 28.500646][ T355] ? lock_acquire+0x60/0x160 [ 28.500647][ T355] ? __rcu_read_lock+0x30/0x70 [ 28.500649][ T355] ? __down_trylock_console_sem+0x5e/0xa0 [ 28.500652][ T355] ? vprintk_emit+0x320/0x3e0 [ 28.500654][ T355] vprintk_emit+0x37c/0x3e0 [ 28.500656][ T355] ? wake_up_klogd_work_func+0x90/0x90 [ 28.500658][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500660][ T355] ? lock_acquire+0x13c/0x160 [ 28.500661][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500663][ T355] _printk+0xc7/0x100 [ 28.500666][ T355] ? snapshot_read.cold+0x21/0x21 [ 28.500668][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500670][ T355] ? lock_acquire+0x13c/0x160 [ 28.500672][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500673][ T355] ? netdev_name+0x43/0x60 [ 28.500676][ T355] ? __netdev_printk+0x17f/0x1d0 [ 28.500678][ T355] netdev_info+0xe2/0x116 [ 28.500680][ T355] ? netdev_notice+0x120/0x120 [ 28.500682][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500684][ T355] ? do_raw_spin_lock+0x131/0x280 [ 28.500685][ T355] ? __rwlock_init+0x150/0x150 [ 28.500687][ T355] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 28.500689][ T355] ? mutex_is_locked+0x1c/0x50 [ 28.500692][ T355] __dev_set_promiscuity.cold+0x95/0x104 [ 28.500695][ T355] __dev_change_flags+0x396/0x660 [ 28.500699][ T355] ? netif_set_allmulti+0x250/0x250 [ 28.500701][ T355] ? kernel_text_address+0x149/0x170 [ 28.500703][ T355] ? __kernel_text_address+0x12/0x30 [ 28.500704][ T355] ? write_profile+0xf0/0xf0 [ 28.500707][ T355] ? unwind_get_return_address+0x67/0xd0 [ 28.500710][ T355] netif_change_flags+0x87/0x190 [ 28.500712][ T355] do_setlink.isra.0+0x1cc3/0x2750 [ 28.500716][ T355] ? stack_trace_save+0x93/0xc0 [ 28.500717][ T355] ? rtnl_link_get_size+0x350/0x350 [ 28.500718][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500720][ T355] ? stack_depot_save_flags+0x1bc/0x790 [ 28.500724][ T355] ? kasan_save_stack+0x3d/0x50 [ 28.500727][ T355] ? kasan_save_stack+0x2f/0x50 [ 28.500729][ T355] ? kasan_save_track+0x14/0x30 [ 28.500730][ T355] ? __kasan_kmalloc+0x7b/0x90 [ 28.500732][ T355] ? __kmalloc_cache_noprof+0x29a/0x620 [ 28.500733][ T355] ? rtnl_newlink+0x124/0x11c0 [ 28.500734][ T355] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 28.500736][ T355] ? netlink_rcv_skb+0x14e/0x3a0 [ 28.500738][ T355] ? netlink_unicast+0x486/0x750 [ 28.500740][ T355] ? netlink_sendmsg+0x735/0xc60 [ 28.500741][ T355] ? ____sys_sendmsg+0x415/0x880 [ 28.500744][ T355] ? ___sys_sendmsg+0x14e/0x1d0 [ 28.500745][ T355] ? __sys_sendmsg+0x12c/0x1d0 [ 28.500747][ T355] ? do_syscall_64+0xff/0x530 [ 28.500749][ T355] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 28.500752][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500753][ T355] ? lock_acquire+0x13c/0x160 [ 28.500755][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500756][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500758][ T355] ? trace_contention_end+0xb3/0x180 [ 28.500760][ T355] ? __mutex_lock+0x1db/0x1ea0 [ 28.500763][ T355] ? __mutex_lock+0x9a3/0x1ea0 [ 28.500764][ T355] ? rtnl_newlink+0x9a8/0x11c0 [ 28.500765][ T355] ? ww_mutex_lock+0x160/0x160 [ 28.500768][ T355] ? __rtnl_newlink+0x3fa/0xa50 [ 28.500770][ T355] rtnl_newlink+0x9f1/0x11c0 [ 28.500772][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500774][ T355] ? rtnl_bridge_getlink+0x850/0x850 [ 28.500775][ T355] ? lock_acquire+0x13c/0x160 [ 28.500776][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500778][ T355] ? is_bpf_text_address+0x3e/0x110 [ 28.500782][ T355] ? is_bpf_text_address+0x72/0x110 [ 28.500783][ T355] ? kernel_text_address+0x149/0x170 [ 28.500785][ T355] ? __kernel_text_address+0x12/0x30 [ 28.500786][ T355] ? write_profile+0xf0/0xf0 [ 28.500787][ T355] ? unwind_get_return_address+0x67/0xd0 [ 28.500788][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500790][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500792][ T355] ? cap_capable+0x1d7/0x3d0 [ 28.500796][ T355] ? lock_acquire+0x13c/0x160 [ 28.500797][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500799][ T355] ? rtnl_bridge_getlink+0x850/0x850 [ 28.500800][ T355] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 28.500802][ T355] ? rtnl_link_fill+0x920/0x920 [ 28.500803][ T355] ? filter_irq_stacks+0xd0/0xd0 [ 28.500805][ T355] ? stack_depot_save_flags+0x1bc/0x790 [ 28.500807][ T355] netlink_rcv_skb+0x14e/0x3a0 [ 28.500809][ T355] ? rtnl_link_fill+0x920/0x920 [ 28.500810][ T355] ? lock_release+0x184/0x1f0 [ 28.500812][ T355] ? netlink_ack+0xcf0/0xcf0 [ 28.500813][ T355] ? __netlink_lookup+0xb9/0x6b0 [ 28.500816][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500817][ T355] ? lock_release+0x184/0x1f0 [ 28.500819][ T355] netlink_unicast+0x486/0x750 [ 28.500821][ T355] ? netlink_attachskb+0x810/0x810 [ 28.500823][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500825][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500826][ T355] ? lock_acquire+0x13c/0x160 [ 28.500828][ T355] netlink_sendmsg+0x735/0xc60 [ 28.500830][ T355] ? netlink_unicast+0x750/0x750 [ 28.500832][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500834][ T355] ? lock_release+0x184/0x1f0 [ 28.500836][ T355] ____sys_sendmsg+0x415/0x880 [ 28.500837][ T355] ? copy_msghdr_from_user+0x279/0x420 [ 28.500839][ T355] ? get_timestamp.constprop.0+0x390/0x390 [ 28.500841][ T355] ? move_addr_to_kernel+0x40/0x40 [ 28.500842][ T355] ? filter_irq_stacks+0xd0/0xd0 [ 28.500845][ T355] ___sys_sendmsg+0x14e/0x1d0 [ 28.500847][ T355] ? copy_msghdr_from_user+0x420/0x420 [ 28.500852][ T355] __sys_sendmsg+0x12c/0x1d0 [ 28.500853][ T355] ? __sys_sendmsg_sock+0x20/0x20 [ 28.500856][ T355] ? rcu_is_watching+0x16/0xd0 [ 28.500858][ T355] do_syscall_64+0xff/0x530 [ 28.500860][ T355] ? exc_page_fault+0xee/0x100 [ 28.500862][ T355] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 28.500864][ T355] RIP: 0033:0x7f6e10be754e [ 28.500865][ T355] 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 [ 28.500867][ T355] RSP: 002b:00007ffda24c8700 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 28.500871][ T355] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f6e10be754e [ 28.500873][ T355] RDX: 0000000000000000 RSI: 00007ffda24c87b0 RDI: 0000000000000005 [ 28.500874][ T355] RBP: 00007ffda24c8710 R08: 0000000000000000 R09: 0000000000000000 [ 28.500874][ T355] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91042e [ 28.500875][ T355] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffda24c8e70 [ 28.500878][ T355] [ 28.568555][ T363] br0: port 3(veth4) entered disabled state [ 28.590530][ T364] br0: port 2(veth2) entered disabled state [ 28.613218][ T365] br0: port 1(veth1) entered disabled state [ 28.628442][ T366] veth4: left allmulticast mode [ 28.628659][ T366] veth4: left promiscuous mode [ 28.628953][ T366] br0: port 3(veth4) entered disabled state [ 28.630887][ T366] veth2: left allmulticast mode [ 28.631035][ T366] veth2: left promiscuous mode [ 28.631221][ T366] br0: port 2(veth2) entered disabled state [ 28.632118][ T366] veth1: left allmulticast mode [ 28.632249][ T366] veth1: left promiscuous mode [ 28.632430][ T366] br0: port 1(veth1) entered disabled state