[ 12.009790][ T270] br1: port 1(vx1) entered blocking state [ 12.009903][ T270] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.009905][ T270] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 270, name: ip [ 12.009907][ T270] preempt_count: 1, expected: 0 [ 12.009908][ T270] RCU nest depth: 0, expected: 0 [ 12.009909][ T270] locks held by ip/270: 5, last CPU#3: [ 12.009911][ T270] #0: ffffffff894d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 12.009923][ T270] #1: ffffffff88d69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.009930][ T270] #2: ffffffff88d69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.009933][ T270] #3: ffffffff88c89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.009937][ T270] #4: ffffffff88c89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 12.009941][ T270] irq event stamp: 24180 [ 12.009942][ T270] hardirqs last enabled at (24179): [] __down_trylock_console_sem+0x86/0xa0 [ 12.009945][ T270] hardirqs last disabled at (24180): [] console_emit_next_record+0x3d4/0x4c0 [ 12.009948][ T270] softirqs last enabled at (24090): [] __alloc_skb+0x4c2/0x5f0 [ 12.009952][ T270] softirqs last disabled at (24088): [] __alloc_skb+0x4c2/0x5f0 [ 12.009955][ T270] Preemption disabled at: [ 12.009956][ T270] [] vprintk_emit+0x31b/0x3e0 [ 12.009962][ T270] CPU: 3 UID: 0 PID: 270 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.009966][ T270] Tainted: [W]=WARN [ 12.009967][ T270] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.009969][ T270] Call Trace: [ 12.009971][ T270] [ 12.009973][ T270] dump_stack_lvl+0x6f/0xa0 [ 12.009978][ T270] ? vprintk_emit+0x31b/0x3e0 [ 12.009981][ T270] __might_resched.cold+0x1fe/0x2c1 [ 12.009985][ T270] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.009989][ T270] ? __kmalloc_noprof+0xdb/0x760 [ 12.009994][ T270] __kmalloc_noprof+0x443/0x760 [ 12.009997][ T270] ? alloc_buf.isra.0+0x4b/0x260 [ 12.010002][ T270] ? do_raw_spin_unlock+0x59/0x250 [ 12.010004][ T270] alloc_buf.isra.0+0x4b/0x260 [ 12.010007][ T270] put_chars+0x1e1/0x2f0 [ 12.010010][ T270] ? __send_to_port+0x420/0x420 [ 12.010014][ T270] ? validate_chain+0x34a/0xc20 [ 12.010018][ T270] hvc_console_print+0x292/0x780 [ 12.010021][ T270] ? mark_usage+0x61/0x170 [ 12.010023][ T270] ? __lock_acquire+0x518/0xc20 [ 12.010024][ T270] ? __lock_acquire+0x518/0xc20 [ 12.010028][ T270] ? hvc_write+0x3a0/0x3a0 [ 12.010030][ T270] ? console_emit_next_record+0x1df/0x4c0 [ 12.010033][ T270] ? rcu_is_watching+0x16/0xd0 [ 12.010038][ T270] ? lock_acquire+0x13c/0x160 [ 12.010042][ T270] console_emit_next_record+0x22f/0x4c0 [ 12.010045][ T270] ? devkmsg_read+0x4b0/0x4b0 [ 12.010047][ T270] ? console_flush_one_record+0x106/0x710 [ 12.010050][ T270] ? rcu_is_watching+0x16/0xd0 [ 12.010053][ T270] ? lock_acquire+0x13c/0x160 [ 12.010057][ T270] console_flush_one_record+0x46f/0x710 [ 12.010061][ T270] ? console_emit_next_record+0x4c0/0x4c0 [ 12.010063][ T270] ? __lock_acquire+0x518/0xc20 [ 12.010068][ T270] console_unlock+0xee/0x1f0 [ 12.010071][ T270] ? console_flush_one_record+0x710/0x710 [ 12.010072][ T270] ? rcu_is_watching+0x16/0xd0 [ 12.010075][ T270] ? lock_acquire+0x60/0x160 [ 12.010078][ T270] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.010080][ T270] ? vprintk_emit+0x320/0x3e0 [ 12.010084][ T270] vprintk_emit+0x37c/0x3e0 [ 12.010087][ T270] ? wake_up_klogd_work_func+0x90/0x90 [ 12.010090][ T270] ? __lock_release.isra.0+0x69/0x1a0 [ 12.010092][ T270] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 12.010096][ T270] ? mark_held_locks+0x40/0x70 [ 12.010100][ T270] _printk+0xc7/0x100 [ 12.010103][ T270] ? snapshot_read.cold+0x21/0x21 [ 12.010107][ T270] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 12.010120][ T270] ? do_setlink.isra.0+0xa31/0x2750 [ 12.010121][ T270] ? rtnl_newlink+0x9f1/0x11c0 [ 12.010123][ T270] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 12.010127][ T270] br_set_state+0x22f/0x430 [bridge] [ 12.010137][ T270] br_init_port+0xc4/0x200 [bridge] [ 12.010146][ T270] new_nbp+0x39c/0x580 [bridge] [ 12.010155][ T270] br_add_if+0x212/0x1320 [bridge] [ 12.010162][ T270] ? is_bpf_text_address+0x72/0x110 [ 12.010167][ T270] ? kernel_text_address+0x149/0x170 [ 12.010170][ T270] ? __kernel_text_address+0x12/0x30 [ 12.010173][ T270] do_set_master+0x357/0x580 [ 12.010178][ T270] do_setlink.isra.0+0xa31/0x2750 [ 12.010181][ T270] ? stack_trace_save+0x93/0xc0 [ 12.010184][ T270] ? rtnl_link_get_size+0x350/0x350 [ 12.010186][ T270] ? rcu_read_lock_any_held+0x66/0x90 [ 12.010188][ T270] ? stack_depot_save_flags+0x38e/0x790 [ 12.010192][ T270] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 12.010195][ T270] ? rcu_read_lock_any_held+0x3c/0x90 [ 12.010196][ T270] ? validate_chain+0x38b/0xc20 [ 12.010199][ T270] ? kasan_save_stack+0x3d/0x50 [ 12.010202][ T270] ? kasan_save_stack+0x2f/0x50 [ 12.010204][ T270] ? kasan_save_track+0x14/0x30 [ 12.010207][ T270] ? __lock_acquire+0x518/0xc20 [ 12.010208][ T270] ? netlink_seq_next+0xe/0x60 [ 12.010212][ T270] ? ___sys_sendmsg+0xb0/0x1d0 [ 12.010216][ T270] ? lock_acquire.part.0+0xd4/0x280 [ 12.010218][ T270] ? rtnl_newlink+0x9a8/0x11c0 [ 12.010221][ T270] ? rcu_is_watching+0x16/0xd0 [ 12.010223][ T270] ? lock_acquire+0x13c/0x160 [ 12.010225][ T270] ? rcu_is_watching+0x16/0xd0 [ 12.010227][ T270] ? rcu_is_watching+0x16/0xd0 [ 12.010229][ T270] ? trace_contention_end+0xb3/0x180 [ 12.010233][ T270] ? __mutex_lock+0x1db/0x1ea0 [ 12.010235][ T270] ? __mutex_lock+0x9a3/0x1ea0 [ 12.010237][ T270] ? rtnl_newlink+0x9a8/0x11c0 [ 12.010241][ T270] ? ww_mutex_lock+0x160/0x160 [ 12.010242][ T270] ? nla_get_range_signed+0x3d0/0x3d0 [ 12.010247][ T270] ? __rtnl_newlink+0x3fa/0xa50 [ 12.010252][ T270] rtnl_newlink+0x9f1/0x11c0 [ 12.010258][ T270] ? rtnl_bridge_getlink+0x850/0x850 [ 12.010260][ T270] ? __lock_acquire+0x518/0xc20 [ 12.010264][ T270] ? lock_acquire.part.0+0xd4/0x280 [ 12.010266][ T270] ? find_held_lock+0x2b/0x80 [ 12.010269][ T270] ? rtnl_bridge_getlink+0x850/0x850 [ 12.010272][ T270] ? __lock_release.isra.0+0x69/0x1a0 [ 12.010278][ T270] ? rtnl_bridge_getlink+0x850/0x850 [ 12.010280][ T270] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 12.010283][ T270] ? rtnl_link_fill+0x920/0x920 [ 12.010285][ T270] ? __lock_acquire+0x518/0xc20 [ 12.010289][ T270] ? lock_acquire.part.0+0xd4/0x280 [ 12.010291][ T270] ? find_held_lock+0x2b/0x80 [ 12.010294][ T270] netlink_rcv_skb+0x14e/0x3a0 [ 12.010296][ T270] ? rtnl_link_fill+0x920/0x920 [ 12.010306][ T270] ? netlink_ack+0xcf0/0xcf0 [ 12.010312][ T270] ? netlink_deliver_tap+0xc5/0x330 [ 12.010314][ T270] ? netlink_deliver_tap+0x13c/0x330 [ 12.010318][ T270] netlink_unicast+0x486/0x750 [ 12.010322][ T270] ? netlink_attachskb+0x810/0x810 [ 12.010325][ T270] ? __lock_acquire+0x518/0xc20 [ 12.010329][ T270] netlink_sendmsg+0x735/0xc60 [ 12.010333][ T270] ? netlink_unicast+0x750/0x750 [ 12.010336][ T270] ? __might_fault+0x97/0x140 [ 12.010342][ T270] ____sys_sendmsg+0x415/0x880 [ 12.010344][ T270] ? copy_msghdr_from_user+0x279/0x420 [ 12.010347][ T270] ? get_timestamp.constprop.0+0x390/0x390 [ 12.010348][ T270] ? move_addr_to_kernel+0x40/0x40 [ 12.010356][ T270] ___sys_sendmsg+0x14e/0x1d0 [ 12.010359][ T270] ? copy_msghdr_from_user+0x420/0x420 [ 12.010374][ T270] __sys_sendmsg+0x12c/0x1d0 [ 12.010377][ T270] ? __sys_sendmsg_sock+0x20/0x20 [ 12.010383][ T270] ? rcu_is_watching+0x16/0xd0 [ 12.010387][ T270] do_syscall_64+0xff/0x530 [ 12.010390][ T270] ? exc_page_fault+0xee/0x100 [ 12.010394][ T270] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 12.010398][ T270] RIP: 0033:0x7f69c04a654e [ 12.010402][ T270] 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.010404][ T270] RSP: 002b:00007fff22e80400 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 12.010407][ T270] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f69c04a654e [ 12.010408][ T270] RDX: 0000000000000000 RSI: 00007fff22e804b0 RDI: 0000000000000005 [ 12.010409][ T270] RBP: 00007fff22e80410 R08: 0000000000000000 R09: 0000000000000000 [ 12.010410][ T270] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91a9cc [ 12.010411][ T270] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fff22e80b70 [ 12.010418][ T270] [ 12.049964][ T270] br1: port 1(vx1) entered disabled state [ 12.050322][ T270] vx1: entered allmulticast mode [ 12.052048][ T270] vx1: entered promiscuous mode [ 12.053178][ T270] br1: port 1(vx1) entered blocking state [ 12.053541][ T270] br1: port 1(vx1) entered forwarding state [ 12.062251][ T270] ip (270) used greatest stack depth: 23336 bytes left [ 12.088182][ T271] br1: port 2(veth1) entered blocking state [ 12.088552][ T271] br1: port 2(veth1) entered disabled state [ 12.089554][ T271] veth1: entered allmulticast mode [ 12.091238][ T271] veth1: entered promiscuous mode [ 12.123904][ T93] br1: port 2(veth1) entered blocking state [ 12.124459][ T93] br1: port 2(veth1) entered forwarding state [ 12.186088][ T274] br1: port 3(veth2) entered blocking state [ 12.186459][ T274] br1: port 3(veth2) entered disabled state [ 12.187140][ T274] veth2: entered allmulticast mode [ 12.189032][ T274] veth2: entered promiscuous mode [ 12.224085][ T93] br1: port 3(veth2) entered blocking state [ 12.224511][ T93] br1: port 3(veth2) entered forwarding state [ 13.630300][ T319] br2: port 1(w1) entered blocking state [ 13.630327][ T319] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 13.630329][ T319] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 319, name: ip [ 13.630331][ T319] preempt_count: 1, expected: 0 [ 13.630332][ T319] RCU nest depth: 0, expected: 0 [ 13.630332][ T319] locks held by ip/319: 5, last CPU#3: [ 13.630335][ T319] #0: ffffffff894d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 13.630349][ T319] #1: ffffffff88d69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 13.630356][ T319] #2: ffffffff88d69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 13.630360][ T319] #3: ffffffff88c89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 13.630364][ T319] #4: ffffffff88c89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 13.630368][ T319] irq event stamp: 20508 [ 13.630369][ T319] hardirqs last enabled at (20507): [] __down_trylock_console_sem+0x86/0xa0 [ 13.630371][ T319] hardirqs last disabled at (20508): [] console_emit_next_record+0x3d4/0x4c0 [ 13.630373][ T319] softirqs last enabled at (20448): [] __alloc_skb+0x4c2/0x5f0 [ 13.630377][ T319] softirqs last disabled at (20446): [] __alloc_skb+0x4c2/0x5f0 [ 13.630379][ T319] Preemption disabled at: [ 13.630380][ T319] [] vprintk_emit+0x31b/0x3e0 [ 13.630386][ T319] CPU: 3 UID: 0 PID: 319 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 13.630390][ T319] Tainted: [W]=WARN [ 13.630391][ T319] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 13.630393][ T319] Call Trace: [ 13.630395][ T319] [ 13.630396][ T319] dump_stack_lvl+0x6f/0xa0 [ 13.630402][ T319] ? vprintk_emit+0x31b/0x3e0 [ 13.630405][ T319] __might_resched.cold+0x1fe/0x2c1 [ 13.630409][ T319] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 13.630413][ T319] ? __kmalloc_noprof+0xdb/0x760 [ 13.630418][ T319] __kmalloc_noprof+0x443/0x760 [ 13.630421][ T319] ? alloc_buf.isra.0+0x4b/0x260 [ 13.630426][ T319] ? do_raw_spin_unlock+0x59/0x250 [ 13.630429][ T319] alloc_buf.isra.0+0x4b/0x260 [ 13.630432][ T319] put_chars+0x1e1/0x2f0 [ 13.630434][ T319] ? prb_final_commit+0x50/0x50 [ 13.630436][ T319] ? __send_to_port+0x420/0x420 [ 13.630439][ T319] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.630443][ T319] ? rcu_read_lock_any_held+0x3c/0x90 [ 13.630446][ T319] ? validate_chain+0x38b/0xc20 [ 13.630452][ T319] hvc_console_print+0x292/0x780 [ 13.630455][ T319] ? mark_usage+0x61/0x170 [ 13.630457][ T319] ? __lock_acquire+0x518/0xc20 [ 13.630459][ T319] ? __lock_acquire+0x518/0xc20 [ 13.630463][ T319] ? hvc_write+0x3a0/0x3a0 [ 13.630465][ T319] ? console_emit_next_record+0x1df/0x4c0 [ 13.630468][ T319] ? rcu_is_watching+0x16/0xd0 [ 13.630470][ T319] ? lock_acquire+0x13c/0x160 [ 13.630474][ T319] console_emit_next_record+0x22f/0x4c0 [ 13.630478][ T319] ? devkmsg_read+0x4b0/0x4b0 [ 13.630480][ T319] ? console_flush_one_record+0x106/0x710 [ 13.630483][ T319] ? rcu_is_watching+0x16/0xd0 [ 13.630486][ T319] ? lock_acquire+0x13c/0x160 [ 13.630490][ T319] console_flush_one_record+0x46f/0x710 [ 13.630495][ T319] ? console_emit_next_record+0x4c0/0x4c0 [ 13.630498][ T319] ? __lock_acquire+0x518/0xc20 [ 13.630504][ T319] console_unlock+0xee/0x1f0 [ 13.630507][ T319] ? console_flush_one_record+0x710/0x710 [ 13.630509][ T319] ? rcu_is_watching+0x16/0xd0 [ 13.630511][ T319] ? lock_acquire+0x60/0x160 [ 13.630515][ T319] ? __down_trylock_console_sem+0x5e/0xa0 [ 13.630517][ T319] ? vprintk_emit+0x320/0x3e0 [ 13.630520][ T319] vprintk_emit+0x37c/0x3e0 [ 13.630524][ T319] ? wake_up_klogd_work_func+0x90/0x90 [ 13.630527][ T319] ? __lock_release.isra.0+0x69/0x1a0 [ 13.630529][ T319] ? mark_held_locks+0x40/0x70 [ 13.630533][ T319] _printk+0xc7/0x100 [ 13.630537][ T319] ? snapshot_read.cold+0x21/0x21 [ 13.630542][ T319] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 13.630556][ T319] ? do_setlink.isra.0+0xa31/0x2750 [ 13.630558][ T319] ? rtnl_newlink+0x9f1/0x11c0 [ 13.630559][ T319] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 13.630563][ T319] br_set_state+0x22f/0x430 [bridge] [ 13.630573][ T319] br_init_port+0xc4/0x200 [bridge] [ 13.630582][ T319] new_nbp+0x39c/0x580 [bridge] [ 13.630595][ T319] br_add_if+0x212/0x1320 [bridge] [ 13.630602][ T319] ? is_bpf_text_address+0x72/0x110 [ 13.630606][ T319] ? kernel_text_address+0x149/0x170 [ 13.630609][ T319] ? __kernel_text_address+0x12/0x30 [ 13.630613][ T319] do_set_master+0x357/0x580 [ 13.630618][ T319] do_setlink.isra.0+0xa31/0x2750 [ 13.630621][ T319] ? stack_trace_save+0x93/0xc0 [ 13.630624][ T319] ? rtnl_link_get_size+0x350/0x350 [ 13.630625][ T319] ? rcu_read_lock_any_held+0x66/0x90 [ 13.630627][ T319] ? stack_depot_save_flags+0x38e/0x790 [ 13.630631][ T319] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.630635][ T319] ? rcu_read_lock_any_held+0x3c/0x90 [ 13.630638][ T319] ? validate_chain+0x38b/0xc20 [ 13.630641][ T319] ? kasan_save_stack+0x3d/0x50 [ 13.630644][ T319] ? kasan_save_stack+0x2f/0x50 [ 13.630646][ T319] ? kasan_save_track+0x14/0x30 [ 13.630648][ T319] ? __lock_acquire+0x518/0xc20 [ 13.630650][ T319] ? netlink_seq_next+0xe/0x60 [ 13.630653][ T319] ? ___sys_sendmsg+0xb0/0x1d0 [ 13.630658][ T319] ? lock_acquire.part.0+0xd4/0x280 [ 13.630660][ T319] ? rtnl_newlink+0x9a8/0x11c0 [ 13.630662][ T319] ? rcu_is_watching+0x16/0xd0 [ 13.630665][ T319] ? lock_acquire+0x13c/0x160 [ 13.630666][ T319] ? rcu_is_watching+0x16/0xd0 [ 13.630668][ T319] ? rcu_is_watching+0x16/0xd0 [ 13.630671][ T319] ? trace_contention_end+0xb3/0x180 [ 13.630674][ T319] ? __mutex_lock+0x1db/0x1ea0 [ 13.630678][ T319] ? __mutex_lock+0x9a3/0x1ea0 [ 13.630684][ T319] ? rtnl_newlink+0x9a8/0x11c0 [ 13.630687][ T319] ? ww_mutex_lock+0x160/0x160 [ 13.630689][ T319] ? nla_get_range_signed+0x3d0/0x3d0 [ 13.630694][ T319] ? __rtnl_newlink+0x3fa/0xa50 [ 13.630699][ T319] rtnl_newlink+0x9f1/0x11c0 [ 13.630705][ T319] ? rtnl_bridge_getlink+0x850/0x850 [ 13.630707][ T319] ? __lock_acquire+0x518/0xc20 [ 13.630711][ T319] ? lock_acquire.part.0+0xd4/0x280 [ 13.630713][ T319] ? find_held_lock+0x2b/0x80 [ 13.630715][ T319] ? rtnl_bridge_getlink+0x850/0x850 [ 13.630717][ T319] ? __lock_release.isra.0+0x69/0x1a0 [ 13.630720][ T319] ? rtnl_bridge_getlink+0x850/0x850 [ 13.630722][ T319] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 13.630726][ T319] ? rtnl_link_fill+0x920/0x920 [ 13.630728][ T319] ? __lock_acquire+0x518/0xc20 [ 13.630734][ T319] ? lock_acquire.part.0+0xd4/0x280 [ 13.630736][ T319] ? find_held_lock+0x2b/0x80 [ 13.630740][ T319] netlink_rcv_skb+0x14e/0x3a0 [ 13.630742][ T319] ? rtnl_link_fill+0x920/0x920 [ 13.630745][ T319] ? netlink_ack+0xcf0/0xcf0 [ 13.630751][ T319] ? netlink_deliver_tap+0xc5/0x330 [ 13.630752][ T319] ? netlink_deliver_tap+0x13c/0x330 [ 13.630757][ T319] netlink_unicast+0x486/0x750 [ 13.630760][ T319] ? netlink_attachskb+0x810/0x810 [ 13.630763][ T319] ? __lock_acquire+0x518/0xc20 [ 13.630767][ T319] netlink_sendmsg+0x735/0xc60 [ 13.630771][ T319] ? netlink_unicast+0x750/0x750 [ 13.630777][ T319] ? __might_fault+0x97/0x140 [ 13.630784][ T319] ____sys_sendmsg+0x415/0x880 [ 13.630787][ T319] ? copy_msghdr_from_user+0x279/0x420 [ 13.630789][ T319] ? get_timestamp.constprop.0+0x390/0x390 [ 13.630791][ T319] ? move_addr_to_kernel+0x40/0x40 [ 13.630798][ T319] ___sys_sendmsg+0x14e/0x1d0 [ 13.630801][ T319] ? copy_msghdr_from_user+0x420/0x420 [ 13.630816][ T319] __sys_sendmsg+0x12c/0x1d0 [ 13.630819][ T319] ? __sys_sendmsg_sock+0x20/0x20 [ 13.630826][ T319] ? rcu_is_watching+0x16/0xd0 [ 13.630830][ T319] do_syscall_64+0xff/0x530 [ 13.630833][ T319] ? exc_page_fault+0xee/0x100 [ 13.630837][ T319] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 13.630840][ T319] RIP: 0033:0x7f5f9eac054e [ 13.630844][ T319] 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 [ 13.630845][ T319] RSP: 002b:00007fffbb420bd0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 13.630848][ T319] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f5f9eac054e [ 13.630850][ T319] RDX: 0000000000000000 RSI: 00007fffbb420c80 RDI: 0000000000000005 [ 13.630851][ T319] RBP: 00007fffbb420be0 R08: 0000000000000000 R09: 0000000000000000 [ 13.630852][ T319] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91a9cd [ 13.630852][ T319] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fffbb421340 [ 13.630859][ T319] [ 13.677837][ T319] br2: port 1(w1) entered disabled state [ 13.678378][ T319] w1: entered allmulticast mode [ 13.681658][ T319] w1: entered promiscuous mode [ 13.716743][ T320] br2: port 1(w1) entered blocking state [ 13.717047][ T320] br2: port 1(w1) entered forwarding state [ 13.883582][ T325] br2: port 2(vx2) entered blocking state [ 13.884489][ T325] br2: port 2(vx2) entered disabled state [ 13.884848][ T325] vx2: entered allmulticast mode [ 13.886586][ T325] vx2: entered promiscuous mode [ 13.887378][ T325] br2: port 2(vx2) entered blocking state [ 13.887703][ T325] br2: port 2(vx2) entered forwarding state [ 14.759851][ T357] br2: port 1(w1) entered blocking state [ 14.759868][ T357] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 14.759870][ T357] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 357, name: ip [ 14.759871][ T357] preempt_count: 1, expected: 0 [ 14.759872][ T357] RCU nest depth: 0, expected: 0 [ 14.759873][ T357] locks held by ip/357: 5, last CPU#3: [ 14.759876][ T357] #0: ffffffff894d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 14.759887][ T357] #1: ffffffff88d69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 14.759894][ T357] #2: ffffffff88d69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 14.759898][ T357] #3: ffffffff88c89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 14.759902][ T357] #4: ffffffff88c89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 14.759906][ T357] irq event stamp: 13410 [ 14.759907][ T357] hardirqs last enabled at (13409): [] __down_trylock_console_sem+0x86/0xa0 [ 14.759910][ T357] hardirqs last disabled at (13410): [] console_emit_next_record+0x3d4/0x4c0 [ 14.759912][ T357] softirqs last enabled at (13350): [] __alloc_skb+0x4c2/0x5f0 [ 14.759915][ T357] softirqs last disabled at (13348): [] __alloc_skb+0x4c2/0x5f0 [ 14.759917][ T357] Preemption disabled at: [ 14.759918][ T357] [] vprintk_emit+0x31b/0x3e0 [ 14.759923][ T357] CPU: 3 UID: 0 PID: 357 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 14.759927][ T357] Tainted: [W]=WARN [ 14.759927][ T357] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.759929][ T357] Call Trace: [ 14.759931][ T357] [ 14.759932][ T357] dump_stack_lvl+0x6f/0xa0 [ 14.759938][ T357] ? vprintk_emit+0x31b/0x3e0 [ 14.759940][ T357] __might_resched.cold+0x1fe/0x2c1 [ 14.759944][ T357] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 14.759949][ T357] ? __kmalloc_noprof+0xdb/0x760 [ 14.759954][ T357] __kmalloc_noprof+0x443/0x760 [ 14.759956][ T357] ? alloc_buf.isra.0+0x4b/0x260 [ 14.759962][ T357] ? do_raw_spin_unlock+0x59/0x250 [ 14.759964][ T357] alloc_buf.isra.0+0x4b/0x260 [ 14.759968][ T357] put_chars+0x1e1/0x2f0 [ 14.759969][ T357] ? prb_final_commit+0x50/0x50 [ 14.759972][ T357] ? __send_to_port+0x420/0x420 [ 14.759974][ T357] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.759979][ T357] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.759981][ T357] ? validate_chain+0x38b/0xc20 [ 14.759985][ T357] hvc_console_print+0x292/0x780 [ 14.759988][ T357] ? mark_usage+0x61/0x170 [ 14.759990][ T357] ? __lock_acquire+0x518/0xc20 [ 14.759991][ T357] ? __lock_acquire+0x518/0xc20 [ 14.759995][ T357] ? hvc_write+0x3a0/0x3a0 [ 14.759997][ T357] ? console_emit_next_record+0x1df/0x4c0 [ 14.760000][ T357] ? rcu_is_watching+0x16/0xd0 [ 14.760003][ T357] ? lock_acquire+0x13c/0x160 [ 14.760007][ T357] console_emit_next_record+0x22f/0x4c0 [ 14.760011][ T357] ? devkmsg_read+0x4b0/0x4b0 [ 14.760012][ T357] ? console_flush_one_record+0x106/0x710 [ 14.760015][ T357] ? rcu_is_watching+0x16/0xd0 [ 14.760018][ T357] ? lock_acquire+0x13c/0x160 [ 14.760022][ T357] console_flush_one_record+0x46f/0x710 [ 14.760026][ T357] ? console_emit_next_record+0x4c0/0x4c0 [ 14.760028][ T357] ? __lock_acquire+0x518/0xc20 [ 14.760033][ T357] console_unlock+0xee/0x1f0 [ 14.760036][ T357] ? console_flush_one_record+0x710/0x710 [ 14.760038][ T357] ? rcu_is_watching+0x16/0xd0 [ 14.760040][ T357] ? lock_acquire+0x60/0x160 [ 14.760044][ T357] ? __down_trylock_console_sem+0x5e/0xa0 [ 14.760045][ T357] ? vprintk_emit+0x320/0x3e0 [ 14.760049][ T357] vprintk_emit+0x37c/0x3e0 [ 14.760053][ T357] ? wake_up_klogd_work_func+0x90/0x90 [ 14.760055][ T357] ? __lock_release.isra.0+0x69/0x1a0 [ 14.760058][ T357] ? mark_held_locks+0x40/0x70 [ 14.760061][ T357] _printk+0xc7/0x100 [ 14.760065][ T357] ? snapshot_read.cold+0x21/0x21 [ 14.760069][ T357] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 14.760082][ T357] ? do_setlink.isra.0+0xa31/0x2750 [ 14.760083][ T357] ? rtnl_newlink+0x9f1/0x11c0 [ 14.760085][ T357] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 14.760089][ T357] br_set_state+0x22f/0x430 [bridge] [ 14.760099][ T357] br_init_port+0xc4/0x200 [bridge] [ 14.760107][ T357] new_nbp+0x39c/0x580 [bridge] [ 14.760116][ T357] br_add_if+0x212/0x1320 [bridge] [ 14.760123][ T357] ? is_bpf_text_address+0x72/0x110 [ 14.760127][ T357] ? kernel_text_address+0x149/0x170 [ 14.760130][ T357] ? __kernel_text_address+0x12/0x30 [ 14.760134][ T357] do_set_master+0x357/0x580 [ 14.760139][ T357] do_setlink.isra.0+0xa31/0x2750 [ 14.760142][ T357] ? stack_trace_save+0x93/0xc0 [ 14.760145][ T357] ? rtnl_link_get_size+0x350/0x350 [ 14.760146][ T357] ? rcu_read_lock_any_held+0x66/0x90 [ 14.760148][ T357] ? stack_depot_save_flags+0x38e/0x790 [ 14.760152][ T357] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.760154][ T357] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.760156][ T357] ? validate_chain+0x38b/0xc20 [ 14.760158][ T357] ? kasan_save_stack+0x3d/0x50 [ 14.760162][ T357] ? kasan_save_stack+0x2f/0x50 [ 14.760163][ T357] ? kasan_save_track+0x14/0x30 [ 14.760166][ T357] ? __lock_acquire+0x518/0xc20 [ 14.760168][ T357] ? netlink_seq_next+0xe/0x60 [ 14.760171][ T357] ? ___sys_sendmsg+0xb0/0x1d0 [ 14.760176][ T357] ? lock_acquire.part.0+0xd4/0x280 [ 14.760178][ T357] ? rtnl_newlink+0x9a8/0x11c0 [ 14.760180][ T357] ? rcu_is_watching+0x16/0xd0 [ 14.760183][ T357] ? lock_acquire+0x13c/0x160 [ 14.760184][ T357] ? rcu_is_watching+0x16/0xd0 [ 14.760186][ T357] ? rcu_is_watching+0x16/0xd0 [ 14.760188][ T357] ? trace_contention_end+0xb3/0x180 [ 14.760192][ T357] ? __mutex_lock+0x1db/0x1ea0 [ 14.760195][ T357] ? __mutex_lock+0x9a3/0x1ea0 [ 14.760197][ T357] ? rtnl_newlink+0x9a8/0x11c0 [ 14.760200][ T357] ? ww_mutex_lock+0x160/0x160 [ 14.760202][ T357] ? nla_get_range_signed+0x3d0/0x3d0 [ 14.760207][ T357] ? __rtnl_newlink+0x3fa/0xa50 [ 14.760212][ T357] rtnl_newlink+0x9f1/0x11c0 [ 14.760217][ T357] ? rtnl_bridge_getlink+0x850/0x850 [ 14.760219][ T357] ? __lock_acquire+0x518/0xc20 [ 14.760223][ T357] ? lock_acquire.part.0+0xd4/0x280 [ 14.760225][ T357] ? find_held_lock+0x2b/0x80 [ 14.760227][ T357] ? rtnl_bridge_getlink+0x850/0x850 [ 14.760229][ T357] ? __lock_release.isra.0+0x69/0x1a0 [ 14.760233][ T357] ? rtnl_bridge_getlink+0x850/0x850 [ 14.760234][ T357] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 14.760237][ T357] ? rtnl_link_fill+0x920/0x920 [ 14.760239][ T357] ? __lock_acquire+0x518/0xc20 [ 14.760243][ T357] ? lock_acquire.part.0+0xd4/0x280 [ 14.760245][ T357] ? find_held_lock+0x2b/0x80 [ 14.760248][ T357] netlink_rcv_skb+0x14e/0x3a0 [ 14.760250][ T357] ? rtnl_link_fill+0x920/0x920 [ 14.760253][ T357] ? netlink_ack+0xcf0/0xcf0 [ 14.760259][ T357] ? netlink_deliver_tap+0xc5/0x330 [ 14.760260][ T357] ? netlink_deliver_tap+0x13c/0x330 [ 14.760265][ T357] netlink_unicast+0x486/0x750 [ 14.760268][ T357] ? netlink_attachskb+0x810/0x810 [ 14.760271][ T357] ? __lock_acquire+0x518/0xc20 [ 14.760275][ T357] netlink_sendmsg+0x735/0xc60 [ 14.760279][ T357] ? netlink_unicast+0x750/0x750 [ 14.760283][ T357] ? __might_fault+0x97/0x140 [ 14.760288][ T357] ____sys_sendmsg+0x415/0x880 [ 14.760290][ T357] ? copy_msghdr_from_user+0x279/0x420 [ 14.760292][ T357] ? get_timestamp.constprop.0+0x390/0x390 [ 14.760294][ T357] ? move_addr_to_kernel+0x40/0x40 [ 14.760301][ T357] ___sys_sendmsg+0x14e/0x1d0 [ 14.760304][ T357] ? copy_msghdr_from_user+0x420/0x420 [ 14.760325][ T357] __sys_sendmsg+0x12c/0x1d0 [ 14.760328][ T357] ? __sys_sendmsg_sock+0x20/0x20 [ 14.760334][ T357] ? rcu_is_watching+0x16/0xd0 [ 14.760337][ T357] do_syscall_64+0xff/0x530 [ 14.760340][ T357] ? exc_page_fault+0xee/0x100 [ 14.760343][ T357] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.760345][ T357] RIP: 0033:0x7f41e9a1d54e [ 14.760349][ T357] Code: 4d 89 d8 e8 b4 bd 00 00 4c 8b 5d f8 41 8b 93 08 03 00 00 59 5e 48 83 f8 fc 74 11 c9 c3 0f 1f 80 00 00 00 00 48 8b 45 10 0f 05 c3 83 e2 39 83 fa 08 75 e7 e8 03 ff ff ff 0f 1f 00 f3 0f 1e fa [ 14.760351][ T357] RSP: 002b:00007ffcfe72aec0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 14.760354][ T357] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f41e9a1d54e [ 14.760355][ T357] RDX: 0000000000000000 RSI: 00007ffcfe72af70 RDI: 0000000000000005 [ 14.760356][ T357] RBP: 00007ffcfe72aed0 R08: 0000000000000000 R09: 0000000000000000 [ 14.760357][ T357] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91a9cf [ 14.760357][ T357] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffcfe72b630 [ 14.760364][ T357] [ 14.807698][ T357] br2: port 1(w1) entered disabled state [ 14.808040][ T357] w1: entered allmulticast mode [ 14.810717][ T357] w1: entered promiscuous mode [ 14.850058][ T358] br2: port 1(w1) entered blocking state [ 14.850367][ T358] br2: port 1(w1) entered forwarding state [ 15.011543][ T363] br2: port 2(vx2) entered blocking state [ 15.011818][ T363] br2: port 2(vx2) entered disabled state [ 15.012072][ T363] vx2: entered allmulticast mode [ 15.013576][ T363] vx2: entered promiscuous mode [ 15.014216][ T363] br2: port 2(vx2) entered blocking state [ 15.014456][ T363] br2: port 2(vx2) entered forwarding state [ 22.327908][ T446] GACT probability NOT on [ 22.327926][ T446] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 22.327929][ T446] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 446, name: modprobe [ 22.327931][ T446] preempt_count: 1, expected: 0 [ 22.327932][ T446] RCU nest depth: 0, expected: 0 [ 22.327933][ T446] locks held by modprobe/446: 4, last CPU#1: [ 22.327935][ T446] #0: ffffffff88d69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 22.327949][ T446] #1: ffffffff88d69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 22.327953][ T446] #2: ffffffff88c89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 22.327958][ T446] #3: ffffffff88c89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 22.327964][ T446] irq event stamp: 25720 [ 22.327965][ T446] hardirqs last enabled at (25719): [] __down_trylock_console_sem+0x86/0xa0 [ 22.327967][ T446] hardirqs last disabled at (25720): [] console_emit_next_record+0x3d4/0x4c0 [ 22.327970][ T446] softirqs last enabled at (23360): [] handle_softirqs+0x67c/0x900 [ 22.327973][ T446] softirqs last disabled at (22361): [] __irq_exit_rcu+0x145/0x1c0 [ 22.327975][ T446] Preemption disabled at: [ 22.327976][ T446] [] vprintk_emit+0x31b/0x3e0 [ 22.327981][ T446] CPU: 1 UID: 0 PID: 446 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 22.327985][ T446] Tainted: [W]=WARN [ 22.327986][ T446] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 22.327987][ T446] Call Trace: [ 22.327989][ T446] [ 22.327991][ T446] dump_stack_lvl+0x6f/0xa0 [ 22.327996][ T446] ? vprintk_emit+0x31b/0x3e0 [ 22.327998][ T446] __might_resched.cold+0x1fe/0x2c1 [ 22.328003][ T446] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 22.328007][ T446] ? __kmalloc_noprof+0xdb/0x760 [ 22.328012][ T446] __kmalloc_noprof+0x443/0x760 [ 22.328014][ T446] ? alloc_buf.isra.0+0x4b/0x260 [ 22.328020][ T446] ? do_raw_spin_unlock+0x59/0x250 [ 22.328024][ T446] alloc_buf.isra.0+0x4b/0x260 [ 22.328028][ T446] put_chars+0x1e1/0x2f0 [ 22.328030][ T446] ? desc_read_finalized_seq+0x79/0x120 [ 22.328032][ T446] ? __send_to_port+0x420/0x420 [ 22.328035][ T446] ? rcu_read_lock_any_held+0x3c/0x90 [ 22.328038][ T446] ? validate_chain+0x38b/0xc20 [ 22.328042][ T446] hvc_console_print+0x292/0x780 [ 22.328045][ T446] ? __lock_acquire+0x518/0xc20 [ 22.328047][ T446] ? __lock_acquire+0x518/0xc20 [ 22.328051][ T446] ? hvc_write+0x3a0/0x3a0 [ 22.328052][ T446] ? console_emit_next_record+0x1df/0x4c0 [ 22.328056][ T446] ? rcu_is_watching+0x16/0xd0 [ 22.328059][ T446] ? lock_acquire+0x13c/0x160 [ 22.328063][ T446] console_emit_next_record+0x22f/0x4c0 [ 22.328067][ T446] ? devkmsg_read+0x4b0/0x4b0 [ 22.328068][ T446] ? console_flush_one_record+0x106/0x710 [ 22.328071][ T446] ? rcu_is_watching+0x16/0xd0 [ 22.328074][ T446] ? lock_acquire+0x13c/0x160 [ 22.328078][ T446] console_flush_one_record+0x46f/0x710 [ 22.328083][ T446] ? console_emit_next_record+0x4c0/0x4c0 [ 22.328086][ T446] ? __lock_acquire+0x518/0xc20 [ 22.328094][ T446] console_unlock+0xee/0x1f0 [ 22.328098][ T446] ? console_flush_one_record+0x710/0x710 [ 22.328101][ T446] ? rcu_is_watching+0x16/0xd0 [ 22.328104][ T446] ? lock_acquire+0x60/0x160 [ 22.328108][ T446] ? __down_trylock_console_sem+0x5e/0xa0 [ 22.328110][ T446] ? vprintk_emit+0x320/0x3e0 [ 22.328113][ T446] vprintk_emit+0x37c/0x3e0 [ 22.328118][ T446] ? wake_up_klogd_work_func+0x90/0x90 [ 22.328123][ T446] ? llc_exit+0x3e0/0x3e0 [llc] [ 22.328127][ T446] _printk+0xc7/0x100 [ 22.328130][ T446] ? snapshot_read.cold+0x21/0x21 [ 22.328137][ T446] ? do_one_initcall+0x113/0x4f0 [ 22.328140][ T446] gact_init_module+0x15/0x1000 [act_gact] [ 22.328143][ T446] do_one_initcall+0x124/0x4f0 [ 22.328145][ T446] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 22.328150][ T446] ? do_init_module+0xae/0x8a0 [ 22.328153][ T446] ? kasan_unpoison+0x44/0x70 [ 22.328158][ T446] do_init_module+0x2ad/0x8a0 [ 22.328162][ T446] ? lockdep_hardirqs_on+0x91/0x130 [ 22.328166][ T446] ? free_module+0x470/0x470 [ 22.328169][ T446] ? kfree+0x123/0x590 [ 22.328173][ T446] load_module+0x1424/0x1bb0 [ 22.328181][ T446] ? layout_and_allocate.isra.0+0x680/0x680 [ 22.328185][ T446] ? __ia32_sys_fsconfig+0x180/0x180 [ 22.328188][ T446] ? find_held_lock+0x2b/0x80 [ 22.328190][ T446] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 22.328194][ T446] init_module_from_file+0x11f/0x1a0 [ 22.328198][ T446] ? __do_sys_init_module+0x220/0x220 [ 22.328206][ T446] ? __rwlock_init+0x150/0x150 [ 22.328209][ T446] ? do_raw_spin_unlock+0x59/0x250 [ 22.328213][ T446] idempotent_init_module+0x37b/0x7c0 [ 22.328219][ T446] ? init_module_from_file+0x1a0/0x1a0 [ 22.328226][ T446] ? rcu_is_watching+0x16/0xd0 [ 22.328229][ T446] ? cap_capable+0x1d7/0x3d0 [ 22.328235][ T446] __x64_sys_finit_module+0xdf/0x190 [ 22.328237][ T446] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 22.328241][ T446] do_syscall_64+0xff/0x530 [ 22.328242][ T446] ? exc_page_fault+0xee/0x100 [ 22.328245][ T446] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 22.328247][ T446] RIP: 0033:0x7f73e10c937d [ 22.328251][ T446] Code: ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 4b aa 0f 00 f7 d8 64 89 01 48 [ 22.328253][ T446] RSP: 002b:00007ffcc3c6c4c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 22.328255][ T446] RAX: ffffffffffffffda RBX: 000055e5241f0810 RCX: 00007f73e10c937d [ 22.328257][ T446] RDX: 0000000000000000 RSI: 000055e4fe869ffd RDI: 0000000000000000 [ 22.328258][ T446] RBP: 00007ffcc3c6c560 R08: 0000000000000000 R09: 0000000000000000 [ 22.328259][ T446] R10: 0000000000000000 R11: 0000000000000246 R12: 000055e4fe869ffd [ 22.328260][ T446] R13: 000055e5241f0960 R14: 0000000000040000 R15: 0000000000000000 [ 22.328266][ T446] [ 27.065768][ C3] [ 27.065787][ C3] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 27.065790][ C3] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/3 [ 27.065792][ C3] preempt_count: 104, expected: 0 [ 27.065793][ C3] RCU nest depth: 0, expected: 0 [ 27.065795][ C3] INFO: lockdep is turned off. [ 27.065796][ C3] irq event stamp: 891384 [ 27.065797][ C3] hardirqs last enabled at (891384): [] _raw_spin_unlock_irq+0x28/0x50 [ 27.065808][ C3] hardirqs last disabled at (891383): [] _raw_spin_lock_irq+0x4a/0x50 [ 27.065811][ C3] softirqs last enabled at (891332): [] handle_softirqs+0x67c/0x900 [ 27.065815][ C3] softirqs last disabled at (891381): [] __irq_exit_rcu+0x145/0x1c0 [ 27.065818][ C3] Preemption disabled at: [ 27.065819][ C3] [<0000000000000000>] 0x0 [ 27.065827][ C3] CPU: 3 UID: 0 PID: 0 Comm: swapper/3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.065832][ C3] Tainted: [W]=WARN [ 27.065833][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.065836][ C3] Call Trace: [ 27.065838][ C3] [ 27.065840][ C3] dump_stack_lvl+0x6f/0xa0 [ 27.065846][ C3] __might_resched.cold+0x1fe/0x2c1 [ 27.065851][ C3] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 27.065855][ C3] ? __asan_memcpy+0x3c/0x60 [ 27.065859][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.065864][ C3] __kmalloc_noprof+0x443/0x760 [ 27.065868][ C3] ? __rwlock_init+0x150/0x150 [ 27.065871][ C3] ? alloc_buf.isra.0+0x4b/0x260 [ 27.065876][ C3] ? do_raw_spin_unlock+0x59/0x250 [ 27.065878][ C3] alloc_buf.isra.0+0x4b/0x260 [ 27.065881][ C3] put_chars+0x1e1/0x2f0 [ 27.065884][ C3] ? __send_to_port+0x420/0x420 [ 27.065887][ C3] ? console_prepend_replay+0x20/0x20 [ 27.065891][ C3] hvc_console_print+0x292/0x780 [ 27.065896][ C3] ? hvc_write+0x3a0/0x3a0 [ 27.065898][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.065901][ C3] ? lock_acquire+0x13c/0x160 [ 27.065904][ C3] console_emit_next_record+0x22f/0x4c0 [ 27.065908][ C3] ? devkmsg_read+0x4b0/0x4b0 [ 27.065910][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.065913][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.065915][ C3] ? lock_acquire+0x13c/0x160 [ 27.065918][ C3] ? console_flush_one_record+0x111/0x710 [ 27.065920][ C3] console_flush_one_record+0x46f/0x710 [ 27.065924][ C3] ? console_emit_next_record+0x4c0/0x4c0 [ 27.065928][ C3] console_unlock+0xee/0x1f0 [ 27.065930][ C3] ? lock_acquire+0x13c/0x160 [ 27.065933][ C3] ? console_flush_one_record+0x710/0x710 [ 27.065935][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.065937][ C3] ? lock_release+0x184/0x1f0 [ 27.065939][ C3] ? lock_acquire+0x60/0x160 [ 27.065942][ C3] ? __down_trylock_console_sem+0x5e/0xa0 [ 27.065945][ C3] ? vprintk_emit+0x320/0x3e0 [ 27.065948][ C3] vprintk_emit+0x37c/0x3e0 [ 27.065951][ C3] ? wake_up_klogd_work_func+0x90/0x90 [ 27.065954][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.065971][ C3] ? lock_release+0x184/0x1f0 [ 27.065973][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.065984][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.065995][ C3] ? is_module_text_address+0x154/0x250 [ 27.065999][ C3] _printk+0xc7/0x100 [ 27.066003][ C3] ? snapshot_read.cold+0x21/0x21 [ 27.066006][ C3] ? arch_stack_walk+0xd7/0x130 [ 27.066011][ C3] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 27.066014][ C3] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 27.066017][ C3] print_irq_inversion_bug.part.0+0x32/0xc0 [ 27.066020][ C3] mark_lock_irq+0x989/0x9c0 [ 27.066025][ C3] mark_lock+0x1d7/0xa00 [ 27.066028][ C3] mark_usage+0x42/0x170 [ 27.066031][ C3] __lock_acquire+0x388/0xc20 [ 27.066034][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.066037][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.066048][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.066050][ C3] ? lock_acquire+0x13c/0x160 [ 27.066053][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.066064][ C3] _raw_spin_lock+0x33/0x40 [ 27.066066][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.066077][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.066088][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.066099][ C3] call_timer_fn+0x160/0x4d0 [ 27.066103][ C3] ? detach_if_pending+0x1d0/0x1d0 [ 27.066105][ C3] ? debug_object_active_state+0x430/0x430 [ 27.066110][ C3] ? find_held_lock+0x2b/0x80 [ 27.066112][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 27.066115][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.066118][ C3] __run_timers+0x68f/0xaa0 [ 27.066121][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.066133][ C3] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.066135][ C3] ? __lock_acquire+0x518/0xc20 [ 27.066140][ C3] ? __rwlock_init+0x150/0x150 [ 27.066143][ C3] run_timer_softirq+0xf0/0x160 [ 27.066146][ C3] ? __run_timers+0xaa0/0xaa0 [ 27.066149][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.066152][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.066155][ C3] handle_softirqs+0x1d3/0x900 [ 27.066158][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 27.066160][ C3] ? _local_bh_enable+0xc0/0xc0 [ 27.066163][ C3] __irq_exit_rcu+0x145/0x1c0 [ 27.066165][ C3] irq_exit_rcu+0xe/0x30 [ 27.066168][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.066171][ C3] [ 27.066172][ C3] [ 27.066173][ C3] ? lockdep_hardirqs_on+0x91/0x130 [ 27.066176][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.066179][ C3] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.066182][ C3] Code: 48 8b 3d 94 d2 f5 01 e8 1f 00 00 00 48 2b 05 58 93 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 86 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.066185][ C3] RSP: 0018:ffa0000000167e00 EFLAGS: 00000282 [ 27.066189][ C3] RAX: 00000000000d99f3 RBX: ff11000001c88040 RCX: ffffffff85af0307 [ 27.066191][ C3] RDX: ff11000001c88040 RSI: ffffffff88838af6 RDI: ffffffff8828d8e0 [ 27.066192][ C3] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.066194][ C3] R10: 0000000000000003 R11: 0000000000000001 R12: 1ff400000002cfc3 [ 27.066195][ C3] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 27.066197][ C3] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.066202][ C3] default_idle+0x9/0x10 [ 27.066205][ C3] default_idle_call+0x6e/0xb0 [ 27.066207][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.066209][ C3] ? arch_cpu_idle_exit+0x40/0x40 [ 27.066212][ C3] ? mark_tsc_async_resets+0x30/0x30 [ 27.066215][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.066217][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.066220][ C3] do_idle+0xd8/0x190 [ 27.066222][ C3] cpu_startup_entry+0x53/0x70 [ 27.066225][ C3] start_secondary+0x204/0x2b0 [ 27.066227][ C3] ? set_cpu_sibling_map+0x2130/0x2130 [ 27.066230][ C3] common_startup_64+0x13e/0x148 [ 27.066236][ C3] [ 27.093762][ C3] ======================================================== [ 27.094151][ C3] WARNING: possible irq lock inversion dependency detected [ 27.094470][ C3] 7.2.0-virtme #1 Tainted: G W [ 27.094786][ C3] -------------------------------------------------------- [ 27.095155][ C3] swapper/3/0 just changed the state of lock: [ 27.095407][ C3] ff1100000c732e58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.095897][ C3] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 27.096263][ C3] (fs_reclaim){+.+.}-{0:0} [ 27.096268][ C3] [ 27.096268][ C3] [ 27.096268][ C3] and interrupts could create inverse lock ordering between them. [ 27.096268][ C3] [ 27.097150][ C3] [ 27.097150][ C3] other info that might help us debug this: [ 27.097519][ C3] Chain exists of: [ 27.097519][ C3] &br->lock --> console_owner --> fs_reclaim [ 27.097519][ C3] [ 27.098053][ C3] Possible interrupt unsafe locking scenario: [ 27.098053][ C3] [ 27.098427][ C3] CPU0 CPU1 [ 27.098629][ C3] ---- ---- [ 27.098909][ C3] lock(fs_reclaim); [ 27.099064][ C3] local_irq_disable(); [ 27.099393][ C3] lock(&br->lock); [ 27.099646][ C3] lock(console_owner); [ 27.099972][ C3] [ 27.100126][ C3] lock(&br->lock); [ 27.100357][ C3] [ 27.100357][ C3] *** DEADLOCK *** [ 27.100357][ C3] [ 27.100658][ C3] locks held by swapper/3/0: 1, last CPU#3: [ 27.100986][ C3] #0: ffa0000000280c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 27.101475][ C3] [ 27.101475][ C3] the shortest dependencies between 2nd lock and 1st lock: [ 27.101893][ C3] -> (fs_reclaim){+.+.}-{0:0} { [ 27.102107][ C3] HARDIRQ-ON-W at: [ 27.102336][ C3] __lock_acquire+0x388/0xc20 [ 27.102602][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.102950][ C3] fs_reclaim_acquire+0xd5/0x120 [ 27.103284][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 27.103628][ C3] kthread_create_worker_on_node+0xea/0x210 [ 27.104002][ C3] workqueue_init+0x2a/0x680 [ 27.104364][ C3] kernel_init_freeable+0x2fe/0x630 [ 27.104694][ C3] kernel_init+0x21/0x150 [ 27.105060][ C3] ret_from_fork+0x474/0x6b0 [ 27.105432][ C3] ret_from_fork_asm+0x11/0x20 [ 27.105684][ C3] SOFTIRQ-ON-W at: [ 27.105882][ C3] __lock_acquire+0x388/0xc20 [ 27.106117][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.106446][ C3] fs_reclaim_acquire+0xd5/0x120 [ 27.106775][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 27.107074][ C3] kthread_create_worker_on_node+0xea/0x210 [ 27.107452][ C3] workqueue_init+0x2a/0x680 [ 27.107778][ C3] kernel_init_freeable+0x2fe/0x630 [ 27.108073][ C3] kernel_init+0x21/0x150 [ 27.108395][ C3] ret_from_fork+0x474/0x6b0 [ 27.108727][ C3] ret_from_fork_asm+0x11/0x20 [ 27.108979][ C3] INITIAL USE at: [ 27.109206][ C3] __lock_acquire+0x388/0xc20 [ 27.109460][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.109786][ C3] fs_reclaim_acquire+0xd5/0x120 [ 27.110114][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 27.110420][ C3] kthread_create_worker_on_node+0xea/0x210 [ 27.110801][ C3] workqueue_init+0x2a/0x680 [ 27.111127][ C3] kernel_init_freeable+0x2fe/0x630 [ 27.111383][ C3] kernel_init+0x21/0x150 [ 27.111714][ C3] ret_from_fork+0x474/0x6b0 [ 27.112040][ C3] ret_from_fork_asm+0x11/0x20 [ 27.112291][ C3] } [ 27.112401][ C3] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 27.112776][ C3] ... acquired at: [ 27.112994][ C3] __lock_acquire+0x518/0xc20 [ 27.113197][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.113485][ C3] fs_reclaim_acquire+0xd5/0x120 [ 27.113685][ C3] __kmalloc_noprof+0xd3/0x760 [ 27.113957][ C3] alloc_buf.isra.0+0x4b/0x260 [ 27.114155][ C3] put_chars+0x1e1/0x2f0 [ 27.114458][ C3] hvc_console_print+0x292/0x780 [ 27.114658][ C3] console_emit_next_record+0x22f/0x4c0 [ 27.114928][ C3] console_flush_one_record+0x46f/0x710 [ 27.115129][ C3] console_unlock+0xee/0x1f0 [ 27.115408][ C3] vprintk_emit+0x37c/0x3e0 [ 27.115611][ C3] dev_vprintk_emit+0x27f/0x2c0 [ 27.115883][ C3] dev_printk_emit+0xb9/0xee [ 27.116083][ C3] _dev_info+0xe2/0x116 [ 27.116234][ C3] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 27.116565][ C3] cmos_do_probe+0x73b/0x98a [ 27.116842][ C3] platform_probe+0xfe/0x1f0 [ 27.117043][ C3] call_driver_probe+0x61/0x1c0 [ 27.117318][ C3] really_probe+0x199/0x760 [ 27.117520][ C3] __driver_probe_device+0x24f/0x440 [ 27.117790][ C3] driver_probe_device+0x4a/0xf0 [ 27.117989][ C3] __driver_attach+0x1b8/0x540 [ 27.118262][ C3] bus_for_each_dev+0x130/0x1e0 [ 27.118464][ C3] bus_add_driver+0x2c8/0x530 [ 27.118739][ C3] driver_register+0x1a3/0x390 [ 27.118937][ C3] __platform_driver_probe+0x13f/0x270 [ 27.119207][ C3] cmos_init+0x31/0x40 [ 27.119360][ C3] do_one_initcall+0x124/0x4f0 [ 27.119562][ C3] kernel_init_freeable+0x596/0x630 [ 27.119832][ C3] kernel_init+0x21/0x150 [ 27.120032][ C3] ret_from_fork+0x474/0x6b0 [ 27.120306][ C3] ret_from_fork_asm+0x11/0x20 [ 27.120515][ C3] [ 27.120691][ C3] -> (console_owner){....}-{0:0} { [ 27.120898][ C3] INITIAL USE at: [ 27.121126][ C3] } [ 27.121227][ C3] ... key at: [] console_owner_dep_map+0x0/0x60 [ 27.121601][ C3] ... acquired at: [ 27.121751][ C3] __lock_acquire+0x518/0xc20 [ 27.121953][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.122228][ C3] console_lock_spinning_enable+0x5c/0x60 [ 27.122555][ C3] console_emit_next_record+0x1d1/0x4c0 [ 27.122755][ C3] console_flush_one_record+0x46f/0x710 [ 27.123017][ C3] console_unlock+0xee/0x1f0 [ 27.123202][ C3] vprintk_emit+0x37c/0x3e0 [ 27.123479][ C3] _printk+0xc7/0x100 [ 27.123635][ C3] br_set_state+0x22f/0x430 [bridge] [ 27.123847][ C3] br_init_port+0xc4/0x200 [bridge] [ 27.124129][ C3] br_stp_enable_port+0x12/0x50 [bridge] [ 27.124462][ C3] br_add_if+0xde2/0x1320 [bridge] [ 27.124674][ C3] do_set_master+0x357/0x580 [ 27.124950][ C3] do_setlink.isra.0+0xa31/0x2750 [ 27.125149][ C3] rtnl_newlink+0x9f1/0x11c0 [ 27.125434][ C3] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.125633][ C3] netlink_rcv_skb+0x14e/0x3a0 [ 27.125908][ C3] netlink_unicast+0x486/0x750 [ 27.126105][ C3] netlink_sendmsg+0x735/0xc60 [ 27.126377][ C3] ____sys_sendmsg+0x415/0x880 [ 27.126576][ C3] ___sys_sendmsg+0x14e/0x1d0 [ 27.126848][ C3] __sys_sendmsg+0x12c/0x1d0 [ 27.127046][ C3] do_syscall_64+0xff/0x530 [ 27.127324][ C3] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.127521][ C3] [ 27.127587][ C3] -> (&br->lock){+.-.}-{3:3} { [ 27.127772][ C3] HARDIRQ-ON-W at: [ 27.127872][ C3] __lock_acquire+0x388/0xc20 [ 27.128169][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.128420][ C3] _raw_spin_lock_bh+0x38/0x50 [ 27.128592][ C3] br_set_mac_address+0xe6/0x3b0 [bridge] [ 27.128801][ C3] netif_set_mac_address+0x282/0x410 [ 27.129016][ C3] do_setlink.isra.0+0x706/0x2750 [ 27.129183][ C3] rtnl_newlink+0x9f1/0x11c0 [ 27.129401][ C3] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.129570][ C3] netlink_rcv_skb+0x14e/0x3a0 [ 27.129785][ C3] netlink_unicast+0x486/0x750 [ 27.129998][ C3] netlink_sendmsg+0x735/0xc60 [ 27.130161][ C3] ____sys_sendmsg+0x415/0x880 [ 27.130383][ C3] ___sys_sendmsg+0x14e/0x1d0 [ 27.130596][ C3] __sys_sendmsg+0x12c/0x1d0 [ 27.130763][ C3] do_syscall_64+0xff/0x530 [ 27.130980][ C3] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.131223][ C3] IN-SOFTIRQ-W at: [ 27.131325][ C3] __lock_acquire+0x388/0xc20 [ 27.131548][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.131716][ C3] _raw_spin_lock+0x33/0x40 [ 27.131929][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.132185][ C3] call_timer_fn+0x160/0x4d0 [ 27.132354][ C3] __run_timers+0x68f/0xaa0 [ 27.132576][ C3] run_timer_softirq+0xf0/0x160 [ 27.132743][ C3] handle_softirqs+0x1d3/0x900 [ 27.132959][ C3] __irq_exit_rcu+0x145/0x1c0 [ 27.133175][ C3] irq_exit_rcu+0xe/0x30 [ 27.133343][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.133599][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.133844][ C3] pv_native_safe_halt+0xf/0x10 [ 27.134011][ C3] default_idle+0x9/0x10 [ 27.134223][ C3] default_idle_call+0x6e/0xb0 [ 27.134455][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.134655][ C3] do_idle+0xd8/0x190 [ 27.134791][ C3] cpu_startup_entry+0x53/0x70 [ 27.135043][ C3] start_secondary+0x204/0x2b0 [ 27.135211][ C3] common_startup_64+0x13e/0x148 [ 27.135436][ C3] INITIAL USE at: [ 27.135534][ C3] __lock_acquire+0x388/0xc20 [ 27.135744][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.135909][ C3] _raw_spin_lock_bh+0x38/0x50 [ 27.136124][ C3] br_set_mac_address+0xe6/0x3b0 [bridge] [ 27.136380][ C3] netif_set_mac_address+0x282/0x410 [ 27.136557][ C3] do_setlink.isra.0+0x706/0x2750 [ 27.136768][ C3] rtnl_newlink+0x9f1/0x11c0 [ 27.136980][ C3] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.137146][ C3] netlink_rcv_skb+0x14e/0x3a0 [ 27.137360][ C3] netlink_unicast+0x486/0x750 [ 27.137582][ C3] netlink_sendmsg+0x735/0xc60 [ 27.137754][ C3] ____sys_sendmsg+0x415/0x880 [ 27.137966][ C3] ___sys_sendmsg+0x14e/0x1d0 [ 27.138138][ C3] __sys_sendmsg+0x12c/0x1d0 [ 27.138351][ C3] do_syscall_64+0xff/0x530 [ 27.138573][ C3] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.138771][ C3] } [ 27.138884][ C3] ... key at: [] __key.7+0x0/0x40 [bridge] [ 27.139085][ C3] ... acquired at: [ 27.139231][ C3] mark_lock+0x1d7/0xa00 [ 27.139368][ C3] mark_usage+0x42/0x170 [ 27.139554][ C3] __lock_acquire+0x388/0xc20 [ 27.139688][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.139867][ C3] _raw_spin_lock+0x33/0x40 [ 27.140002][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.140215][ C3] call_timer_fn+0x160/0x4d0 [ 27.140350][ C3] __run_timers+0x68f/0xaa0 [ 27.140535][ C3] run_timer_softirq+0xf0/0x160 [ 27.140669][ C3] handle_softirqs+0x1d3/0x900 [ 27.140801][ C3] __irq_exit_rcu+0x145/0x1c0 [ 27.140932][ C3] irq_exit_rcu+0xe/0x30 [ 27.141109][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.141323][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.141496][ C3] pv_native_safe_halt+0xf/0x10 [ 27.141672][ C3] default_idle+0x9/0x10 [ 27.141805][ C3] default_idle_call+0x6e/0xb0 [ 27.141984][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.142146][ C3] do_idle+0xd8/0x190 [ 27.142290][ C3] cpu_startup_entry+0x53/0x70 [ 27.142434][ C3] start_secondary+0x204/0x2b0 [ 27.142615][ C3] common_startup_64+0x13e/0x148 [ 27.142748][ C3] [ 27.142815][ C3] [ 27.142815][ C3] stack backtrace: [ 27.143026][ C3] CPU: 3 UID: 0 PID: 0 Comm: swapper/3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.143030][ C3] Tainted: [W]=WARN [ 27.143031][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.143033][ C3] Call Trace: [ 27.143034][ C3] [ 27.143036][ C3] dump_stack_lvl+0x6f/0xa0 [ 27.143040][ C3] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 27.143043][ C3] mark_lock_irq+0x989/0x9c0 [ 27.143046][ C3] mark_lock+0x1d7/0xa00 [ 27.143048][ C3] mark_usage+0x42/0x170 [ 27.143050][ C3] __lock_acquire+0x388/0xc20 [ 27.143052][ C3] lock_acquire.part.0+0xd4/0x280 [ 27.143054][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.143061][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.143064][ C3] ? lock_acquire+0x13c/0x160 [ 27.143066][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.143072][ C3] _raw_spin_lock+0x33/0x40 [ 27.143074][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.143080][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.143086][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.143092][ C3] call_timer_fn+0x160/0x4d0 [ 27.143094][ C3] ? detach_if_pending+0x1d0/0x1d0 [ 27.143096][ C3] ? debug_object_active_state+0x430/0x430 [ 27.143099][ C3] ? find_held_lock+0x2b/0x80 [ 27.143101][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 27.143103][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.143105][ C3] __run_timers+0x68f/0xaa0 [ 27.143107][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.143114][ C3] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.143116][ C3] ? __lock_acquire+0x518/0xc20 [ 27.143118][ C3] ? __rwlock_init+0x150/0x150 [ 27.143121][ C3] run_timer_softirq+0xf0/0x160 [ 27.143123][ C3] ? __run_timers+0xaa0/0xaa0 [ 27.143124][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.143127][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.143128][ C3] handle_softirqs+0x1d3/0x900 [ 27.143131][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 27.143132][ C3] ? _local_bh_enable+0xc0/0xc0 [ 27.143134][ C3] __irq_exit_rcu+0x145/0x1c0 [ 27.143136][ C3] irq_exit_rcu+0xe/0x30 [ 27.143137][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.143139][ C3] [ 27.143139][ C3] [ 27.143140][ C3] ? lockdep_hardirqs_on+0x91/0x130 [ 27.143141][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.143143][ C3] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.143146][ C3] Code: 48 8b 3d 94 d2 f5 01 e8 1f 00 00 00 48 2b 05 58 93 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 86 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.143147][ C3] RSP: 0018:ffa0000000167e00 EFLAGS: 00000282 [ 27.143150][ C3] RAX: 00000000000d99f3 RBX: ff11000001c88040 RCX: ffffffff85af0307 [ 27.143151][ C3] RDX: ff11000001c88040 RSI: ffffffff88838af6 RDI: ffffffff8828d8e0 [ 27.143152][ C3] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.143153][ C3] R10: 0000000000000003 R11: 0000000000000001 R12: 1ff400000002cfc3 [ 27.143153][ C3] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 27.143155][ C3] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.143157][ C3] default_idle+0x9/0x10 [ 27.143159][ C3] default_idle_call+0x6e/0xb0 [ 27.143161][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.143162][ C3] ? arch_cpu_idle_exit+0x40/0x40 [ 27.143164][ C3] ? mark_tsc_async_resets+0x30/0x30 [ 27.143166][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.143167][ C3] ? rcu_is_watching+0x16/0xd0 [ 27.143169][ C3] do_idle+0xd8/0x190 [ 27.143171][ C3] cpu_startup_entry+0x53/0x70 [ 27.143172][ C3] start_secondary+0x204/0x2b0 [ 27.143174][ C3] ? set_cpu_sibling_map+0x2130/0x2130 [ 27.143175][ C3] common_startup_64+0x13e/0x148 [ 27.143178][ C3] [ 65.752423][ T70] vx2: left allmulticast mode [ 65.752442][ T70] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 65.752445][ T70] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 70, name: kworker/u16:1 [ 65.752448][ T70] preempt_count: 1, expected: 0 [ 65.752449][ T70] RCU nest depth: 0, expected: 0 [ 65.752451][ T70] INFO: lockdep is turned off. [ 65.752452][ T70] irq event stamp: 29452 [ 65.752454][ T70] hardirqs last enabled at (29451): [] _raw_spin_unlock_irq+0x28/0x50 [ 65.752464][ T70] hardirqs last disabled at (29452): [] __schedule+0x1246/0x20e0 [ 65.752467][ T70] softirqs last enabled at (29286): [] __fib6_clean_all+0xff/0x290 [ 65.752471][ T70] softirqs last disabled at (29280): [] __fib6_clean_all+0xd9/0x290 [ 65.752475][ T70] Preemption disabled at: [ 65.752476][ T70] [] vprintk_emit+0x31b/0x3e0 [ 65.752485][ T70] CPU: 2 UID: 0 PID: 70 Comm: kworker/u16:1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 65.752490][ T70] Tainted: [W]=WARN [ 65.752492][ T70] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 65.752494][ T70] Workqueue: netns cleanup_net [ 65.752501][ T70] Call Trace: [ 65.752503][ T70] [ 65.752505][ T70] dump_stack_lvl+0x6f/0xa0 [ 65.752511][ T70] ? vprintk_emit+0x31b/0x3e0 [ 65.752514][ T70] __might_resched.cold+0x1fe/0x2c1 [ 65.752519][ T70] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 65.752523][ T70] ? rcu_is_watching+0x16/0xd0 [ 65.752528][ T70] __kmalloc_noprof+0x443/0x760 [ 65.752533][ T70] ? __rwlock_init+0x150/0x150 [ 65.752536][ T70] ? alloc_buf.isra.0+0x4b/0x260 [ 65.752540][ T70] ? do_raw_spin_unlock+0x59/0x250 [ 65.752543][ T70] alloc_buf.isra.0+0x4b/0x260 [ 65.752546][ T70] put_chars+0x1e1/0x2f0 [ 65.752549][ T70] ? desc_read_finalized_seq+0x79/0x120 [ 65.752551][ T70] ? __send_to_port+0x420/0x420 [ 65.752554][ T70] ? console_prepend_replay+0x20/0x20 [ 65.752558][ T70] hvc_console_print+0x292/0x780 [ 65.752563][ T70] ? hvc_write+0x3a0/0x3a0 [ 65.752565][ T70] ? do_raw_spin_lock+0x131/0x280 [ 65.752567][ T70] ? __rwlock_init+0x150/0x150 [ 65.752577][ T70] ? rcu_is_watching+0x16/0xd0 [ 65.752579][ T70] ? rcu_is_watching+0x16/0xd0 [ 65.752582][ T70] ? lock_acquire+0x13c/0x160 [ 65.752586][ T70] console_emit_next_record+0x22f/0x4c0 [ 65.752590][ T70] ? devkmsg_read+0x4b0/0x4b0 [ 65.752593][ T70] ? rcu_is_watching+0x16/0xd0 [ 65.752595][ T70] ? rcu_is_watching+0x16/0xd0 [ 65.752598][ T70] ? rcu_is_watching+0x16/0xd0 [ 65.752600][ T70] ? lock_acquire+0x13c/0x160 [ 65.752604][ T70] ? console_flush_one_record+0x111/0x710 [ 65.752606][ T70] console_flush_one_record+0x46f/0x710 [ 65.752610][ T70] ? console_emit_next_record+0x4c0/0x4c0 [ 65.752614][ T70] console_unlock+0xee/0x1f0 [ 65.752616][ T70] ? lock_acquire+0x13c/0x160 [ 65.752619][ T70] ? console_flush_one_record+0x710/0x710 [ 65.752621][ T70] ? rcu_is_watching+0x16/0xd0 [ 65.752623][ T70] ? lock_release+0x184/0x1f0 [ 65.752626][ T70] ? lock_acquire+0x60/0x160 [ 65.752628][ T70] ? __rcu_read_lock+0x30/0x70 [ 65.752631][ T70] ? __down_trylock_console_sem+0x5e/0xa0 [ 65.752634][ T70] ? vprintk_emit+0x320/0x3e0 [ 65.752637][ T70] vprintk_emit+0x37c/0x3e0 [ 65.752641][ T70] ? wake_up_klogd_work_func+0x90/0x90 [ 65.752643][ T70] ? process_one_work+0xe3e/0x1560 [ 65.752647][ T70] ? kthread+0x367/0x460 [ 65.752649][ T70] ? ret_from_fork+0x474/0x6b0 [ 65.752653][ T70] ? ret_from_fork_asm+0x11/0x20 [ 65.752657][ T70] _printk+0xc7/0x100 [ 65.752661][ T70] ? snapshot_read.cold+0x21/0x21 [ 65.752665][ T70] ? netdev_name+0x43/0x60 [ 65.752668][ T70] ? __netdev_printk+0x17f/0x1d0 [ 65.752671][ T70] netdev_info+0xe2/0x116 [ 65.752674][ T70] ? netdev_notice+0x120/0x120 [ 65.752677][ T70] ? rcu_is_watching+0x16/0xd0 [ 65.752682][ T70] ? __up_read+0x355/0x9e0 [ 65.752684][ T70] ? trace_hardirqs_on+0x36/0x40 [ 65.752688][ T70] ? down_write_nested+0x200/0x200 [ 65.752690][ T70] ? mutex_is_locked+0x1c/0x50 [ 65.752694][ T70] netif_set_allmulti.cold+0x7c/0x166 [ 65.752699][ T70] dev_set_allmulti+0xa2/0x150 [ 65.752703][ T70] del_nbp+0xb7/0xc10 [bridge] [ 65.752723][ T70] br_dev_delete+0x9d/0x1e0 [bridge] [ 65.752737][ T70] br_net_exit_rtnl+0xb8/0x110 [bridge] [ 65.752750][ T70] ops_undo_list+0x5be/0x8f0 [ 65.752753][ T70] ? rtnl_net_dumpid_one+0x2d0/0x2d0 [ 65.752756][ T70] ? idr_destroy+0x121/0x250 [ 65.752761][ T70] cleanup_net+0x431/0x890 [ 65.752764][ T70] ? net_passive_dec+0x230/0x230 [ 65.752767][ T70] ? lock_acquire+0x13c/0x160 [ 65.752770][ T70] ? rcu_is_watching+0x16/0xd0 [ 65.752773][ T70] process_one_work+0xe3e/0x1560 [ 65.752777][ T70] ? __queue_delayed_work+0x470/0x470 [ 65.752780][ T70] ? find_held_lock+0x2b/0x80 [ 65.752783][ T70] ? __rwlock_init+0x150/0x150 [ 65.752787][ T70] worker_thread+0x4f1/0xd60 [ 65.752791][ T70] ? rescuer_thread+0x1340/0x1340 [ 65.752793][ T70] ? __kthread_parkme+0xbd/0x210 [ 65.752796][ T70] ? rescuer_thread+0x1340/0x1340 [ 65.752799][ T70] ? rescuer_thread+0x1340/0x1340 [ 65.752801][ T70] kthread+0x367/0x460 [ 65.752803][ T70] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 65.752806][ T70] ? kthread_affine_preferred+0x4c0/0x4c0 [ 65.752808][ T70] ret_from_fork+0x474/0x6b0 [ 65.752811][ T70] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 65.752814][ T70] ? __switch_to+0x5a3/0xe00 [ 65.752818][ T70] ? kthread_affine_preferred+0x4c0/0x4c0 [ 65.752820][ T70] ret_from_fork_asm+0x11/0x20 [ 65.752825][ T70] [ 65.778644][ T70] vx2: left promiscuous mode [ 65.778939][ T70] br2: port 2(vx2) entered disabled state [ 65.780090][ T70] w1: left allmulticast mode [ 65.780274][ T70] w1: left promiscuous mode [ 65.780525][ T70] br2: port 1(w1) entered disabled state [ 65.853239][ T70] vx2: left allmulticast mode [ 65.853444][ T70] vx2: left promiscuous mode [ 65.853764][ T70] br2: port 2(vx2) entered disabled state [ 65.854805][ T70] w1: left allmulticast mode [ 65.854957][ T70] w1: left promiscuous mode [ 65.855156][ T70] br2: port 1(w1) entered disabled state [ 66.271746][ T578] br1: port 3(veth2) entered disabled state [ 66.298514][ T579] veth2: left allmulticast mode [ 66.298772][ T579] veth2: left promiscuous mode [ 66.299084][ T579] br1: port 3(veth2) entered disabled state [ 66.346352][ T581] br1: port 2(veth1) entered disabled state [ 66.366036][ T582] veth1: left allmulticast mode [ 66.366202][ T582] veth1: left promiscuous mode [ 66.366410][ T582] br1: port 2(veth1) entered disabled state [ 66.381873][ T583] vx1: left allmulticast mode [ 66.382078][ T583] vx1: left promiscuous mode [ 66.382343][ T583] br1: port 1(vx1) entered disabled state