[ 11.079081][ T208] ip (208) used greatest stack depth: 23824 bytes left [ 11.079098][ T208] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.079100][ T208] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 208, name: ip [ 11.079101][ T208] preempt_count: 2, expected: 0 [ 11.079102][ T208] RCU nest depth: 0, expected: 0 [ 11.079103][ T208] locks held by ip/208: 5, last CPU#0: [ 11.079106][ T208] #0: ffffffff98e027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 11.079118][ T208] #1: ffffffff98f69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.079124][ T208] #2: ffffffff98f69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.079128][ T208] #3: ffffffff98e89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.079132][ T208] #4: ffffffff98e89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.079136][ T208] irq event stamp: 35018 [ 11.079137][ T208] hardirqs last enabled at (35017): [] __down_trylock_console_sem+0x86/0xa0 [ 11.079139][ T208] hardirqs last disabled at (35018): [] console_emit_next_record+0x3d4/0x4c0 [ 11.079141][ T208] softirqs last enabled at (33936): [] handle_softirqs+0x67c/0x900 [ 11.079143][ T208] softirqs last disabled at (33931): [] __irq_exit_rcu+0x145/0x1c0 [ 11.079145][ T208] Preemption disabled at: [ 11.079146][ T208] [<0000000000000000>] 0x0 [ 11.079153][ T208] CPU: 0 UID: 0 PID: 208 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.079156][ T208] Tainted: [W]=WARN [ 11.079157][ T208] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.079159][ T208] Call Trace: [ 11.079160][ T208] [ 11.079161][ T208] dump_stack_lvl+0x6f/0xa0 [ 11.079168][ T208] __might_resched.cold+0x1fe/0x2c1 [ 11.079172][ T208] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.079177][ T208] ? __kmalloc_noprof+0xdb/0x760 [ 11.079182][ T208] __kmalloc_noprof+0x443/0x760 [ 11.079184][ T208] ? alloc_buf.isra.0+0x4b/0x260 [ 11.079190][ T208] ? do_raw_spin_unlock+0x59/0x250 [ 11.079192][ T208] alloc_buf.isra.0+0x4b/0x260 [ 11.079195][ T208] put_chars+0x1e1/0x2f0 [ 11.079198][ T208] ? __send_to_port+0x420/0x420 [ 11.079199][ T208] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.079204][ T208] ? validate_chain+0x38b/0xc20 [ 11.079209][ T208] hvc_console_print+0x292/0x780 [ 11.079215][ T208] ? hvc_write+0x3a0/0x3a0 [ 11.079217][ T208] ? rcu_is_watching+0x16/0xd0 [ 11.079219][ T208] ? lock_acquire+0x13c/0x160 [ 11.079223][ T208] console_emit_next_record+0x22f/0x4c0 [ 11.079227][ T208] ? devkmsg_read+0x4b0/0x4b0 [ 11.079229][ T208] ? console_flush_one_record+0x106/0x710 [ 11.079232][ T208] ? rcu_is_watching+0x16/0xd0 [ 11.079234][ T208] ? lock_acquire+0x13c/0x160 [ 11.079238][ T208] console_flush_one_record+0x46f/0x710 [ 11.079242][ T208] ? console_emit_next_record+0x4c0/0x4c0 [ 11.079244][ T208] ? __lock_acquire+0x518/0xc20 [ 11.079249][ T208] console_unlock+0xee/0x1f0 [ 11.079252][ T208] ? console_flush_one_record+0x710/0x710 [ 11.079253][ T208] ? rcu_is_watching+0x16/0xd0 [ 11.079256][ T208] ? lock_acquire+0x60/0x160 [ 11.079259][ T208] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.079261][ T208] ? vprintk_emit+0x320/0x3e0 [ 11.079264][ T208] vprintk_emit+0x37c/0x3e0 [ 11.079268][ T208] ? wake_up_klogd_work_func+0x90/0x90 [ 11.079271][ T208] ? __lock_acquire+0x518/0xc20 [ 11.079275][ T208] _printk+0xc7/0x100 [ 11.079281][ T208] ? snapshot_read.cold+0x21/0x21 [ 11.079283][ T208] ? do_raw_spin_lock+0x131/0x280 [ 11.079286][ T208] ? __rwlock_init+0x150/0x150 [ 11.079290][ T208] ? do_raw_spin_lock+0x131/0x280 [ 11.079292][ T208] do_exit.cold+0x82/0x9c [ 11.079296][ T208] ? exit_notify+0x890/0x890 [ 11.079298][ T208] ? __lock_release.isra.0+0x69/0x1a0 [ 11.079301][ T208] ? rcu_is_watching+0x16/0xd0 [ 11.079305][ T208] do_group_exit+0xb8/0x370 [ 11.079307][ T208] __x64_sys_exit_group+0x3c/0x50 [ 11.079309][ T208] x64_sys_call+0x1567/0x1570 [ 11.079311][ T208] do_syscall_64+0xff/0x530 [ 11.079314][ T208] ? exc_page_fault+0xee/0x100 [ 11.079317][ T208] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.079319][ T208] RIP: 0033:0x7ff348fc91b8 [ 11.079322][ T208] Code: Unable to access opcode bytes at 0x7ff348fc918e. [ 11.079323][ T208] RSP: 002b:00007ffd424565d8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 11.079326][ T208] RAX: ffffffffffffffda RBX: 00007ff3490f9f88 RCX: 00007ff348fc91b8 [ 11.079327][ T208] RDX: 00007ff348d13fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 11.079328][ T208] RBP: 00007ffd42456630 R08: 0000000000000000 R09: 0000000000008010 [ 11.079329][ T208] R10: 00007ffd424563f0 R11: 0000000000000246 R12: 0000000000000001 [ 11.079330][ T208] R13: 0000000000000000 R14: 00007ff3490f8680 R15: 00007ff3490f9fa0 [ 11.079336][ T208] [ 12.065616][ T238] 8021q: 802.1Q VLAN Support v1.8 [ 13.632468][ T284] br1: port 1(vx10) entered blocking state [ 13.632648][ T284] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 13.632650][ T284] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 284, name: ip [ 13.632652][ T284] preempt_count: 1, expected: 0 [ 13.632653][ T284] RCU nest depth: 0, expected: 0 [ 13.632654][ T284] locks held by ip/284: 5, last CPU#3: [ 13.632656][ T284] #0: ffffffff996d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 13.632668][ T284] #1: ffffffff98f69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 13.632675][ T284] #2: ffffffff98f69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 13.632678][ T284] #3: ffffffff98e89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 13.632682][ T284] #4: ffffffff98e89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 13.632686][ T284] irq event stamp: 20090 [ 13.632687][ T284] hardirqs last enabled at (20089): [] __down_trylock_console_sem+0x86/0xa0 [ 13.632689][ T284] hardirqs last disabled at (20090): [] console_emit_next_record+0x3d4/0x4c0 [ 13.632691][ T284] softirqs last enabled at (20002): [] __alloc_skb+0x4c2/0x5f0 [ 13.632694][ T284] softirqs last disabled at (20000): [] __alloc_skb+0x4c2/0x5f0 [ 13.632697][ T284] Preemption disabled at: [ 13.632698][ T284] [] vprintk_emit+0x31b/0x3e0 [ 13.632704][ T284] CPU: 3 UID: 0 PID: 284 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 13.632707][ T284] Tainted: [W]=WARN [ 13.632708][ T284] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 13.632710][ T284] Call Trace: [ 13.632712][ T284] [ 13.632713][ T284] dump_stack_lvl+0x6f/0xa0 [ 13.632719][ T284] ? vprintk_emit+0x31b/0x3e0 [ 13.632721][ T284] __might_resched.cold+0x1fe/0x2c1 [ 13.632726][ T284] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 13.632730][ T284] ? __kmalloc_noprof+0xdb/0x760 [ 13.632735][ T284] __kmalloc_noprof+0x443/0x760 [ 13.632737][ T284] ? alloc_buf.isra.0+0x4b/0x260 [ 13.632743][ T284] ? do_raw_spin_unlock+0x59/0x250 [ 13.632745][ T284] alloc_buf.isra.0+0x4b/0x260 [ 13.632749][ T284] put_chars+0x1e1/0x2f0 [ 13.632751][ T284] ? __send_to_port+0x420/0x420 [ 13.632756][ T284] ? validate_chain+0x34a/0xc20 [ 13.632759][ T284] hvc_console_print+0x292/0x780 [ 13.632762][ T284] ? mark_usage+0x61/0x170 [ 13.632764][ T284] ? __lock_acquire+0x518/0xc20 [ 13.632766][ T284] ? __lock_acquire+0x518/0xc20 [ 13.632770][ T284] ? hvc_write+0x3a0/0x3a0 [ 13.632772][ T284] ? console_emit_next_record+0x1df/0x4c0 [ 13.632775][ T284] ? rcu_is_watching+0x16/0xd0 [ 13.632779][ T284] ? lock_acquire+0x13c/0x160 [ 13.632783][ T284] console_emit_next_record+0x22f/0x4c0 [ 13.632786][ T284] ? devkmsg_read+0x4b0/0x4b0 [ 13.632788][ T284] ? console_flush_one_record+0x106/0x710 [ 13.632791][ T284] ? rcu_is_watching+0x16/0xd0 [ 13.632794][ T284] ? lock_acquire+0x13c/0x160 [ 13.632798][ T284] console_flush_one_record+0x46f/0x710 [ 13.632802][ T284] ? console_emit_next_record+0x4c0/0x4c0 [ 13.632803][ T284] ? __lock_acquire+0x518/0xc20 [ 13.632809][ T284] console_unlock+0xee/0x1f0 [ 13.632812][ T284] ? console_flush_one_record+0x710/0x710 [ 13.632813][ T284] ? rcu_is_watching+0x16/0xd0 [ 13.632816][ T284] ? lock_acquire+0x60/0x160 [ 13.632819][ T284] ? __down_trylock_console_sem+0x5e/0xa0 [ 13.632821][ T284] ? vprintk_emit+0x320/0x3e0 [ 13.632824][ T284] vprintk_emit+0x37c/0x3e0 [ 13.632829][ T284] ? wake_up_klogd_work_func+0x90/0x90 [ 13.632831][ T284] ? __lock_release.isra.0+0x69/0x1a0 [ 13.632833][ T284] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 13.632837][ T284] ? mark_held_locks+0x40/0x70 [ 13.632841][ T284] _printk+0xc7/0x100 [ 13.632844][ T284] ? snapshot_read.cold+0x21/0x21 [ 13.632848][ T284] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 13.632861][ T284] ? do_setlink.isra.0+0xa31/0x2750 [ 13.632862][ T284] ? rtnl_newlink+0x9f1/0x11c0 [ 13.632864][ T284] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 13.632868][ T284] br_set_state+0x22f/0x430 [bridge] [ 13.632878][ T284] br_init_port+0xc4/0x200 [bridge] [ 13.632887][ T284] new_nbp+0x39c/0x580 [bridge] [ 13.632897][ T284] br_add_if+0x212/0x1320 [bridge] [ 13.632911][ T284] ? is_bpf_text_address+0x72/0x110 [ 13.632915][ T284] ? kernel_text_address+0x149/0x170 [ 13.632918][ T284] ? __kernel_text_address+0x12/0x30 [ 13.632921][ T284] do_set_master+0x357/0x580 [ 13.632926][ T284] do_setlink.isra.0+0xa31/0x2750 [ 13.632929][ T284] ? stack_trace_save+0x93/0xc0 [ 13.632932][ T284] ? rtnl_link_get_size+0x350/0x350 [ 13.632934][ T284] ? rcu_read_lock_any_held+0x66/0x90 [ 13.632936][ T284] ? stack_depot_save_flags+0x38e/0x790 [ 13.632940][ T284] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.632943][ T284] ? rcu_read_lock_any_held+0x3c/0x90 [ 13.632944][ T284] ? validate_chain+0x38b/0xc20 [ 13.632947][ T284] ? kasan_save_stack+0x3d/0x50 [ 13.632950][ T284] ? kasan_save_stack+0x2f/0x50 [ 13.632952][ T284] ? kasan_save_track+0x14/0x30 [ 13.632954][ T284] ? __lock_acquire+0x518/0xc20 [ 13.632956][ T284] ? netlink_seq_next+0xe/0x60 [ 13.632959][ T284] ? ___sys_sendmsg+0xb0/0x1d0 [ 13.632964][ T284] ? lock_acquire.part.0+0xd4/0x280 [ 13.632965][ T284] ? rtnl_newlink+0x9a8/0x11c0 [ 13.632968][ T284] ? rcu_is_watching+0x16/0xd0 [ 13.632970][ T284] ? lock_acquire+0x13c/0x160 [ 13.632972][ T284] ? rcu_is_watching+0x16/0xd0 [ 13.632974][ T284] ? rcu_is_watching+0x16/0xd0 [ 13.632976][ T284] ? trace_contention_end+0xb3/0x180 [ 13.632980][ T284] ? __mutex_lock+0x1db/0x1ea0 [ 13.632981][ T284] ? __mutex_lock+0x9a3/0x1ea0 [ 13.632984][ T284] ? rtnl_newlink+0x9a8/0x11c0 [ 13.632987][ T284] ? ww_mutex_lock+0x160/0x160 [ 13.632989][ T284] ? nla_get_range_signed+0x3d0/0x3d0 [ 13.632993][ T284] ? __rtnl_newlink+0x3fa/0xa50 [ 13.632999][ T284] rtnl_newlink+0x9f1/0x11c0 [ 13.633004][ T284] ? rtnl_bridge_getlink+0x850/0x850 [ 13.633006][ T284] ? __lock_acquire+0x518/0xc20 [ 13.633010][ T284] ? lock_acquire.part.0+0xd4/0x280 [ 13.633012][ T284] ? find_held_lock+0x2b/0x80 [ 13.633014][ T284] ? rtnl_bridge_getlink+0x850/0x850 [ 13.633016][ T284] ? __lock_release.isra.0+0x69/0x1a0 [ 13.633019][ T284] ? rtnl_bridge_getlink+0x850/0x850 [ 13.633021][ T284] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 13.633024][ T284] ? rtnl_link_fill+0x920/0x920 [ 13.633026][ T284] ? __lock_acquire+0x518/0xc20 [ 13.633030][ T284] ? lock_acquire.part.0+0xd4/0x280 [ 13.633032][ T284] ? find_held_lock+0x2b/0x80 [ 13.633035][ T284] netlink_rcv_skb+0x14e/0x3a0 [ 13.633037][ T284] ? rtnl_link_fill+0x920/0x920 [ 13.633040][ T284] ? netlink_ack+0xcf0/0xcf0 [ 13.633046][ T284] ? netlink_deliver_tap+0xc5/0x330 [ 13.633047][ T284] ? netlink_deliver_tap+0x13c/0x330 [ 13.633051][ T284] netlink_unicast+0x486/0x750 [ 13.633055][ T284] ? netlink_attachskb+0x810/0x810 [ 13.633058][ T284] ? __lock_acquire+0x518/0xc20 [ 13.633062][ T284] netlink_sendmsg+0x735/0xc60 [ 13.633065][ T284] ? netlink_unicast+0x750/0x750 [ 13.633069][ T284] ? __might_fault+0x97/0x140 [ 13.633075][ T284] ____sys_sendmsg+0x415/0x880 [ 13.633077][ T284] ? copy_msghdr_from_user+0x279/0x420 [ 13.633079][ T284] ? get_timestamp.constprop.0+0x390/0x390 [ 13.633081][ T284] ? move_addr_to_kernel+0x40/0x40 [ 13.633088][ T284] ___sys_sendmsg+0x14e/0x1d0 [ 13.633091][ T284] ? copy_msghdr_from_user+0x420/0x420 [ 13.633106][ T284] __sys_sendmsg+0x12c/0x1d0 [ 13.633109][ T284] ? __sys_sendmsg_sock+0x20/0x20 [ 13.633115][ T284] ? rcu_is_watching+0x16/0xd0 [ 13.633119][ T284] do_syscall_64+0xff/0x530 [ 13.633121][ T284] ? exc_page_fault+0xee/0x100 [ 13.633124][ T284] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 13.633127][ T284] RIP: 0033:0x7fea0b91954e [ 13.633130][ T284] 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.633132][ T284] RSP: 002b:00007ffe38e8b7d0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 13.633135][ T284] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007fea0b91954e [ 13.633136][ T284] RDX: 0000000000000000 RSI: 00007ffe38e8b880 RDI: 0000000000000005 [ 13.633137][ T284] RBP: 00007ffe38e8b7e0 R08: 0000000000000000 R09: 0000000000000000 [ 13.633138][ T284] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91b677 [ 13.633139][ T284] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffe38e8bf40 [ 13.633145][ T284] [ 13.674998][ T284] br1: port 1(vx10) entered disabled state [ 13.675357][ T284] vx10: entered allmulticast mode [ 13.676980][ T284] vx10: entered promiscuous mode [ 13.677573][ T284] br1: port 1(vx10) entered blocking state [ 13.678032][ T284] br1: port 1(vx10) entered forwarding state [ 13.694493][ T284] ip (284) used greatest stack depth: 23336 bytes left [ 13.828021][ T288] br1: port 2(vx20) entered blocking state [ 13.828633][ T288] br1: port 2(vx20) entered disabled state [ 13.829229][ T288] vx20: entered allmulticast mode [ 13.841411][ T288] vx20: entered promiscuous mode [ 13.842379][ T288] br1: port 2(vx20) entered blocking state [ 13.842949][ T288] br1: port 2(vx20) entered forwarding state [ 13.922118][ T290] br1: port 3(veth1) entered blocking state [ 13.922475][ T290] br1: port 3(veth1) entered disabled state [ 13.922829][ T290] veth1: entered allmulticast mode [ 13.924726][ T290] veth1: entered promiscuous mode [ 13.965602][ T46] br1: port 3(veth1) entered blocking state [ 13.966413][ T46] br1: port 3(veth1) entered forwarding state [ 14.110752][ T295] br1: port 4(veth2) entered blocking state [ 14.111305][ T295] br1: port 4(veth2) entered disabled state [ 14.111815][ T295] veth2: entered allmulticast mode [ 14.114786][ T295] veth2: entered promiscuous mode [ 14.157515][ T38] br1: port 4(veth2) entered blocking state [ 14.158107][ T38] br1: port 4(veth2) entered forwarding state [ 15.778920][ T346] br2: port 1(w1) entered blocking state [ 15.778941][ T346] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 15.778944][ T346] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 346, name: ip [ 15.778946][ T346] preempt_count: 1, expected: 0 [ 15.778946][ T346] RCU nest depth: 0, expected: 0 [ 15.778947][ T346] locks held by ip/346: 5, last CPU#1: [ 15.778950][ T346] #0: ffffffff996d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 15.778962][ T346] #1: ffffffff98f69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 15.778969][ T346] #2: ffffffff98f69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 15.778973][ T346] #3: ffffffff98e89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 15.778977][ T346] #4: ffffffff98e89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 15.778981][ T346] irq event stamp: 11702 [ 15.778982][ T346] hardirqs last enabled at (11701): [] __down_trylock_console_sem+0x86/0xa0 [ 15.778984][ T346] hardirqs last disabled at (11702): [] console_emit_next_record+0x3d4/0x4c0 [ 15.778986][ T346] softirqs last enabled at (11644): [] __alloc_skb+0x4c2/0x5f0 [ 15.778989][ T346] softirqs last disabled at (11642): [] __alloc_skb+0x4c2/0x5f0 [ 15.778992][ T346] Preemption disabled at: [ 15.778992][ T346] [] vprintk_emit+0x31b/0x3e0 [ 15.778998][ T346] CPU: 1 UID: 0 PID: 346 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.779002][ T346] Tainted: [W]=WARN [ 15.779003][ T346] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.779005][ T346] Call Trace: [ 15.779007][ T346] [ 15.779008][ T346] dump_stack_lvl+0x6f/0xa0 [ 15.779014][ T346] ? vprintk_emit+0x31b/0x3e0 [ 15.779016][ T346] __might_resched.cold+0x1fe/0x2c1 [ 15.779021][ T346] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 15.779025][ T346] ? __kmalloc_noprof+0xdb/0x760 [ 15.779030][ T346] __kmalloc_noprof+0x443/0x760 [ 15.779032][ T346] ? alloc_buf.isra.0+0x4b/0x260 [ 15.779038][ T346] ? do_raw_spin_unlock+0x59/0x250 [ 15.779041][ T346] alloc_buf.isra.0+0x4b/0x260 [ 15.779044][ T346] put_chars+0x1e1/0x2f0 [ 15.779046][ T346] ? prb_final_commit+0x50/0x50 [ 15.779048][ T346] ? __send_to_port+0x420/0x420 [ 15.779051][ T346] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.779056][ T346] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.779058][ T346] ? validate_chain+0x38b/0xc20 [ 15.779062][ T346] hvc_console_print+0x292/0x780 [ 15.779065][ T346] ? mark_usage+0x61/0x170 [ 15.779067][ T346] ? __lock_acquire+0x518/0xc20 [ 15.779068][ T346] ? __lock_acquire+0x518/0xc20 [ 15.779072][ T346] ? hvc_write+0x3a0/0x3a0 [ 15.779074][ T346] ? console_emit_next_record+0x1df/0x4c0 [ 15.779078][ T346] ? rcu_is_watching+0x16/0xd0 [ 15.779080][ T346] ? lock_acquire+0x13c/0x160 [ 15.779084][ T346] console_emit_next_record+0x22f/0x4c0 [ 15.779088][ T346] ? devkmsg_read+0x4b0/0x4b0 [ 15.779090][ T346] ? console_flush_one_record+0x106/0x710 [ 15.779093][ T346] ? rcu_is_watching+0x16/0xd0 [ 15.779096][ T346] ? lock_acquire+0x13c/0x160 [ 15.779100][ T346] console_flush_one_record+0x46f/0x710 [ 15.779104][ T346] ? console_emit_next_record+0x4c0/0x4c0 [ 15.779106][ T346] ? __lock_acquire+0x518/0xc20 [ 15.779111][ T346] console_unlock+0xee/0x1f0 [ 15.779114][ T346] ? console_flush_one_record+0x710/0x710 [ 15.779116][ T346] ? rcu_is_watching+0x16/0xd0 [ 15.779118][ T346] ? lock_acquire+0x60/0x160 [ 15.779122][ T346] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.779124][ T346] ? vprintk_emit+0x320/0x3e0 [ 15.779127][ T346] vprintk_emit+0x37c/0x3e0 [ 15.779132][ T346] ? wake_up_klogd_work_func+0x90/0x90 [ 15.779135][ T346] ? __lock_release.isra.0+0x69/0x1a0 [ 15.779137][ T346] ? mark_held_locks+0x40/0x70 [ 15.779141][ T346] _printk+0xc7/0x100 [ 15.779145][ T346] ? snapshot_read.cold+0x21/0x21 [ 15.779149][ T346] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 15.779162][ T346] ? do_setlink.isra.0+0xa31/0x2750 [ 15.779163][ T346] ? rtnl_newlink+0x9f1/0x11c0 [ 15.779164][ T346] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 15.779169][ T346] br_set_state+0x22f/0x430 [bridge] [ 15.779179][ T346] br_init_port+0xc4/0x200 [bridge] [ 15.779188][ T346] new_nbp+0x39c/0x580 [bridge] [ 15.779198][ T346] br_add_if+0x212/0x1320 [bridge] [ 15.779205][ T346] ? is_bpf_text_address+0x72/0x110 [ 15.779209][ T346] ? kernel_text_address+0x149/0x170 [ 15.779212][ T346] ? __kernel_text_address+0x12/0x30 [ 15.779216][ T346] do_set_master+0x357/0x580 [ 15.779220][ T346] do_setlink.isra.0+0xa31/0x2750 [ 15.779223][ T346] ? stack_trace_save+0x93/0xc0 [ 15.779227][ T346] ? rtnl_link_get_size+0x350/0x350 [ 15.779228][ T346] ? rcu_read_lock_any_held+0x66/0x90 [ 15.779230][ T346] ? stack_depot_save_flags+0x38e/0x790 [ 15.779233][ T346] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 15.779236][ T346] ? rcu_read_lock_any_held+0x3c/0x90 [ 15.779238][ T346] ? validate_chain+0x38b/0xc20 [ 15.779240][ T346] ? kasan_save_stack+0x3d/0x50 [ 15.779243][ T346] ? kasan_save_stack+0x2f/0x50 [ 15.779245][ T346] ? kasan_save_track+0x14/0x30 [ 15.779248][ T346] ? __lock_acquire+0x518/0xc20 [ 15.779250][ T346] ? netlink_seq_next+0xe/0x60 [ 15.779253][ T346] ? ___sys_sendmsg+0xb0/0x1d0 [ 15.779257][ T346] ? lock_acquire.part.0+0xd4/0x280 [ 15.779259][ T346] ? rtnl_newlink+0x9a8/0x11c0 [ 15.779262][ T346] ? rcu_is_watching+0x16/0xd0 [ 15.779264][ T346] ? lock_acquire+0x13c/0x160 [ 15.779266][ T346] ? rcu_is_watching+0x16/0xd0 [ 15.779268][ T346] ? rcu_is_watching+0x16/0xd0 [ 15.779270][ T346] ? trace_contention_end+0xb3/0x180 [ 15.779274][ T346] ? __mutex_lock+0x1db/0x1ea0 [ 15.779281][ T346] ? __mutex_lock+0x9a3/0x1ea0 [ 15.779283][ T346] ? rtnl_newlink+0x9a8/0x11c0 [ 15.779287][ T346] ? ww_mutex_lock+0x160/0x160 [ 15.779288][ T346] ? nla_get_range_signed+0x3d0/0x3d0 [ 15.779293][ T346] ? __rtnl_newlink+0x3fa/0xa50 [ 15.779299][ T346] rtnl_newlink+0x9f1/0x11c0 [ 15.779304][ T346] ? rtnl_bridge_getlink+0x850/0x850 [ 15.779306][ T346] ? __lock_acquire+0x518/0xc20 [ 15.779310][ T346] ? lock_acquire.part.0+0xd4/0x280 [ 15.779312][ T346] ? find_held_lock+0x2b/0x80 [ 15.779314][ T346] ? rtnl_bridge_getlink+0x850/0x850 [ 15.779316][ T346] ? __lock_release.isra.0+0x69/0x1a0 [ 15.779319][ T346] ? rtnl_bridge_getlink+0x850/0x850 [ 15.779322][ T346] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 15.779325][ T346] ? rtnl_link_fill+0x920/0x920 [ 15.779326][ T346] ? __lock_acquire+0x518/0xc20 [ 15.779330][ T346] ? lock_acquire.part.0+0xd4/0x280 [ 15.779332][ T346] ? find_held_lock+0x2b/0x80 [ 15.779336][ T346] netlink_rcv_skb+0x14e/0x3a0 [ 15.779337][ T346] ? rtnl_link_fill+0x920/0x920 [ 15.779340][ T346] ? netlink_ack+0xcf0/0xcf0 [ 15.779346][ T346] ? netlink_deliver_tap+0xc5/0x330 [ 15.779348][ T346] ? netlink_deliver_tap+0x13c/0x330 [ 15.779352][ T346] netlink_unicast+0x486/0x750 [ 15.779356][ T346] ? netlink_attachskb+0x810/0x810 [ 15.779359][ T346] ? __lock_acquire+0x518/0xc20 [ 15.779363][ T346] netlink_sendmsg+0x735/0xc60 [ 15.779366][ T346] ? netlink_unicast+0x750/0x750 [ 15.779370][ T346] ? __might_fault+0x97/0x140 [ 15.779375][ T346] ____sys_sendmsg+0x415/0x880 [ 15.779378][ T346] ? copy_msghdr_from_user+0x279/0x420 [ 15.779380][ T346] ? get_timestamp.constprop.0+0x390/0x390 [ 15.779382][ T346] ? move_addr_to_kernel+0x40/0x40 [ 15.779389][ T346] ___sys_sendmsg+0x14e/0x1d0 [ 15.779392][ T346] ? copy_msghdr_from_user+0x420/0x420 [ 15.779407][ T346] __sys_sendmsg+0x12c/0x1d0 [ 15.779410][ T346] ? __sys_sendmsg_sock+0x20/0x20 [ 15.779417][ T346] ? rcu_is_watching+0x16/0xd0 [ 15.779420][ T346] do_syscall_64+0xff/0x530 [ 15.779423][ T346] ? exc_page_fault+0xee/0x100 [ 15.779426][ T346] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 15.779428][ T346] RIP: 0033:0x7f93d06aa54e [ 15.779432][ T346] 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 [ 15.779434][ T346] RSP: 002b:00007ffcaa4cd040 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 15.779437][ T346] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f93d06aa54e [ 15.779438][ T346] RDX: 0000000000000000 RSI: 00007ffcaa4cd0f0 RDI: 0000000000000005 [ 15.779439][ T346] RBP: 00007ffcaa4cd050 R08: 0000000000000000 R09: 0000000000000000 [ 15.779440][ T346] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91b679 [ 15.779441][ T346] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffcaa4cd7b0 [ 15.779448][ T346] [ 15.821442][ T346] br2: port 1(w1) entered disabled state [ 15.821825][ T346] w1: entered allmulticast mode [ 15.823598][ T346] w1: entered promiscuous mode [ 15.867753][ T347] br2: port 1(w1) entered blocking state [ 15.868050][ T347] br2: port 1(w1) entered forwarding state [ 16.088589][ T354] br2: port 2(vx10) entered blocking state [ 16.088984][ T354] br2: port 2(vx10) entered disabled state [ 16.089803][ T354] vx10: entered allmulticast mode [ 16.091442][ T354] vx10: entered promiscuous mode [ 16.092034][ T354] br2: port 2(vx10) entered blocking state [ 16.092395][ T354] br2: port 2(vx10) entered forwarding state [ 16.350896][ T361] br2: port 3(vx20) entered blocking state [ 16.351812][ T361] br2: port 3(vx20) entered disabled state [ 16.352223][ T361] vx20: entered allmulticast mode [ 16.353918][ T361] vx20: entered promiscuous mode [ 16.354477][ T361] br2: port 3(vx20) entered blocking state [ 16.354823][ T361] br2: port 3(vx20) entered forwarding state [ 17.499418][ T400] br2: port 1(w1) entered blocking state [ 17.499438][ T400] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 17.499441][ T400] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 400, name: ip [ 17.499443][ T400] preempt_count: 1, expected: 0 [ 17.499444][ T400] RCU nest depth: 0, expected: 0 [ 17.499445][ T400] locks held by ip/400: 5, last CPU#2: [ 17.499447][ T400] #0: ffffffff996d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 17.499459][ T400] #1: ffffffff98f69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 17.499466][ T400] #2: ffffffff98f69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 17.499469][ T400] #3: ffffffff98e89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 17.499473][ T400] #4: ffffffff98e89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 17.499477][ T400] irq event stamp: 14592 [ 17.499478][ T400] hardirqs last enabled at (14591): [] __down_trylock_console_sem+0x86/0xa0 [ 17.499481][ T400] hardirqs last disabled at (14592): [] console_emit_next_record+0x3d4/0x4c0 [ 17.499483][ T400] softirqs last enabled at (14532): [] __alloc_skb+0x4c2/0x5f0 [ 17.499486][ T400] softirqs last disabled at (14530): [] __alloc_skb+0x4c2/0x5f0 [ 17.499488][ T400] Preemption disabled at: [ 17.499489][ T400] [] vprintk_emit+0x31b/0x3e0 [ 17.499495][ T400] CPU: 2 UID: 0 PID: 400 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 17.499498][ T400] Tainted: [W]=WARN [ 17.499499][ T400] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 17.499501][ T400] Call Trace: [ 17.499503][ T400] [ 17.499505][ T400] dump_stack_lvl+0x6f/0xa0 [ 17.499510][ T400] ? vprintk_emit+0x31b/0x3e0 [ 17.499512][ T400] __might_resched.cold+0x1fe/0x2c1 [ 17.499517][ T400] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 17.499521][ T400] ? __kmalloc_noprof+0xdb/0x760 [ 17.499526][ T400] __kmalloc_noprof+0x443/0x760 [ 17.499529][ T400] ? alloc_buf.isra.0+0x4b/0x260 [ 17.499535][ T400] ? do_raw_spin_unlock+0x59/0x250 [ 17.499538][ T400] alloc_buf.isra.0+0x4b/0x260 [ 17.499541][ T400] put_chars+0x1e1/0x2f0 [ 17.499543][ T400] ? prb_final_commit+0x50/0x50 [ 17.499545][ T400] ? __send_to_port+0x420/0x420 [ 17.499548][ T400] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 17.499552][ T400] ? rcu_read_lock_any_held+0x3c/0x90 [ 17.499555][ T400] ? validate_chain+0x38b/0xc20 [ 17.499558][ T400] hvc_console_print+0x292/0x780 [ 17.499562][ T400] ? mark_usage+0x61/0x170 [ 17.499563][ T400] ? __lock_acquire+0x518/0xc20 [ 17.499565][ T400] ? __lock_acquire+0x518/0xc20 [ 17.499569][ T400] ? hvc_write+0x3a0/0x3a0 [ 17.499571][ T400] ? console_emit_next_record+0x1df/0x4c0 [ 17.499574][ T400] ? rcu_is_watching+0x16/0xd0 [ 17.499577][ T400] ? lock_acquire+0x13c/0x160 [ 17.499581][ T400] console_emit_next_record+0x22f/0x4c0 [ 17.499585][ T400] ? devkmsg_read+0x4b0/0x4b0 [ 17.499586][ T400] ? console_flush_one_record+0x106/0x710 [ 17.499590][ T400] ? rcu_is_watching+0x16/0xd0 [ 17.499592][ T400] ? lock_acquire+0x13c/0x160 [ 17.499596][ T400] console_flush_one_record+0x46f/0x710 [ 17.499600][ T400] ? console_emit_next_record+0x4c0/0x4c0 [ 17.499602][ T400] ? __lock_acquire+0x518/0xc20 [ 17.499607][ T400] console_unlock+0xee/0x1f0 [ 17.499610][ T400] ? console_flush_one_record+0x710/0x710 [ 17.499612][ T400] ? rcu_is_watching+0x16/0xd0 [ 17.499614][ T400] ? lock_acquire+0x60/0x160 [ 17.499618][ T400] ? __down_trylock_console_sem+0x5e/0xa0 [ 17.499620][ T400] ? vprintk_emit+0x320/0x3e0 [ 17.499623][ T400] vprintk_emit+0x37c/0x3e0 [ 17.499628][ T400] ? wake_up_klogd_work_func+0x90/0x90 [ 17.499630][ T400] ? __lock_release.isra.0+0x69/0x1a0 [ 17.499633][ T400] ? mark_held_locks+0x40/0x70 [ 17.499637][ T400] _printk+0xc7/0x100 [ 17.499640][ T400] ? snapshot_read.cold+0x21/0x21 [ 17.499644][ T400] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 17.499657][ T400] ? do_setlink.isra.0+0xa31/0x2750 [ 17.499659][ T400] ? rtnl_newlink+0x9f1/0x11c0 [ 17.499660][ T400] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 17.499664][ T400] br_set_state+0x22f/0x430 [bridge] [ 17.499675][ T400] br_init_port+0xc4/0x200 [bridge] [ 17.499684][ T400] new_nbp+0x39c/0x580 [bridge] [ 17.499693][ T400] br_add_if+0x212/0x1320 [bridge] [ 17.499700][ T400] ? is_bpf_text_address+0x72/0x110 [ 17.499705][ T400] ? kernel_text_address+0x149/0x170 [ 17.499707][ T400] ? __kernel_text_address+0x12/0x30 [ 17.499711][ T400] do_set_master+0x357/0x580 [ 17.499716][ T400] do_setlink.isra.0+0xa31/0x2750 [ 17.499719][ T400] ? stack_trace_save+0x93/0xc0 [ 17.499722][ T400] ? rtnl_link_get_size+0x350/0x350 [ 17.499723][ T400] ? rcu_read_lock_any_held+0x66/0x90 [ 17.499725][ T400] ? stack_depot_save_flags+0x38e/0x790 [ 17.499729][ T400] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 17.499731][ T400] ? rcu_read_lock_any_held+0x3c/0x90 [ 17.499733][ T400] ? validate_chain+0x38b/0xc20 [ 17.499736][ T400] ? kasan_save_stack+0x3d/0x50 [ 17.499739][ T400] ? kasan_save_stack+0x2f/0x50 [ 17.499740][ T400] ? kasan_save_track+0x14/0x30 [ 17.499743][ T400] ? __lock_acquire+0x518/0xc20 [ 17.499745][ T400] ? netlink_seq_next+0xe/0x60 [ 17.499748][ T400] ? ___sys_sendmsg+0xb0/0x1d0 [ 17.499752][ T400] ? lock_acquire.part.0+0xd4/0x280 [ 17.499754][ T400] ? rtnl_newlink+0x9a8/0x11c0 [ 17.499757][ T400] ? rcu_is_watching+0x16/0xd0 [ 17.499759][ T400] ? lock_acquire+0x13c/0x160 [ 17.499761][ T400] ? rcu_is_watching+0x16/0xd0 [ 17.499762][ T400] ? rcu_is_watching+0x16/0xd0 [ 17.499765][ T400] ? trace_contention_end+0xb3/0x180 [ 17.499768][ T400] ? __mutex_lock+0x1db/0x1ea0 [ 17.499771][ T400] ? __mutex_lock+0x9a3/0x1ea0 [ 17.499773][ T400] ? rtnl_newlink+0x9a8/0x11c0 [ 17.499777][ T400] ? ww_mutex_lock+0x160/0x160 [ 17.499778][ T400] ? nla_get_range_signed+0x3d0/0x3d0 [ 17.499783][ T400] ? __rtnl_newlink+0x3fa/0xa50 [ 17.499789][ T400] rtnl_newlink+0x9f1/0x11c0 [ 17.499794][ T400] ? rtnl_bridge_getlink+0x850/0x850 [ 17.499796][ T400] ? __lock_acquire+0x518/0xc20 [ 17.499800][ T400] ? lock_acquire.part.0+0xd4/0x280 [ 17.499802][ T400] ? find_held_lock+0x2b/0x80 [ 17.499804][ T400] ? rtnl_bridge_getlink+0x850/0x850 [ 17.499806][ T400] ? __lock_release.isra.0+0x69/0x1a0 [ 17.499810][ T400] ? rtnl_bridge_getlink+0x850/0x850 [ 17.499812][ T400] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 17.499815][ T400] ? rtnl_link_fill+0x920/0x920 [ 17.499816][ T400] ? __lock_acquire+0x518/0xc20 [ 17.499821][ T400] ? lock_acquire.part.0+0xd4/0x280 [ 17.499823][ T400] ? find_held_lock+0x2b/0x80 [ 17.499826][ T400] netlink_rcv_skb+0x14e/0x3a0 [ 17.499828][ T400] ? rtnl_link_fill+0x920/0x920 [ 17.499831][ T400] ? netlink_ack+0xcf0/0xcf0 [ 17.499837][ T400] ? netlink_deliver_tap+0xc5/0x330 [ 17.499839][ T400] ? netlink_deliver_tap+0x13c/0x330 [ 17.499843][ T400] netlink_unicast+0x486/0x750 [ 17.499847][ T400] ? netlink_attachskb+0x810/0x810 [ 17.499850][ T400] ? __lock_acquire+0x518/0xc20 [ 17.499854][ T400] netlink_sendmsg+0x735/0xc60 [ 17.499857][ T400] ? netlink_unicast+0x750/0x750 [ 17.499861][ T400] ? __might_fault+0x97/0x140 [ 17.499867][ T400] ____sys_sendmsg+0x415/0x880 [ 17.499869][ T400] ? copy_msghdr_from_user+0x279/0x420 [ 17.499871][ T400] ? get_timestamp.constprop.0+0x390/0x390 [ 17.499873][ T400] ? move_addr_to_kernel+0x40/0x40 [ 17.499880][ T400] ___sys_sendmsg+0x14e/0x1d0 [ 17.499883][ T400] ? copy_msghdr_from_user+0x420/0x420 [ 17.499899][ T400] __sys_sendmsg+0x12c/0x1d0 [ 17.499902][ T400] ? __sys_sendmsg_sock+0x20/0x20 [ 17.499908][ T400] ? rcu_is_watching+0x16/0xd0 [ 17.499912][ T400] do_syscall_64+0xff/0x530 [ 17.499921][ T400] ? exc_page_fault+0xee/0x100 [ 17.499924][ T400] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 17.499927][ T400] RIP: 0033:0x7f1ae056d54e [ 17.499930][ T400] 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 [ 17.499932][ T400] RSP: 002b:00007fff4388f1d0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 17.499935][ T400] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f1ae056d54e [ 17.499937][ T400] RDX: 0000000000000000 RSI: 00007fff4388f280 RDI: 0000000000000005 [ 17.499937][ T400] RBP: 00007fff4388f1e0 R08: 0000000000000000 R09: 0000000000000000 [ 17.499938][ T400] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91b67a [ 17.499939][ T400] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fff4388f940 [ 17.499946][ T400] [ 17.554302][ T400] br2: port 1(w1) entered disabled state [ 17.554709][ T400] w1: entered allmulticast mode [ 17.557083][ T400] w1: entered promiscuous mode [ 17.593991][ T401] br2: port 1(w1) entered blocking state [ 17.594274][ T401] br2: port 1(w1) entered forwarding state [ 17.621775][ T46] br2: port 1(w1) entered disabled state [ 17.805821][ T408] br2: port 2(vx10) entered blocking state [ 17.806215][ T408] br2: port 2(vx10) entered disabled state [ 17.806984][ T408] vx10: entered allmulticast mode [ 17.808602][ T408] vx10: entered promiscuous mode [ 17.809188][ T408] br2: port 2(vx10) entered blocking state [ 17.809543][ T408] br2: port 2(vx10) entered forwarding state [ 18.023815][ T415] br2: port 3(vx20) entered blocking state [ 18.024179][ T415] br2: port 3(vx20) entered disabled state [ 18.024513][ T415] vx20: entered allmulticast mode [ 18.026126][ T415] vx20: entered promiscuous mode [ 18.026611][ T415] br2: port 3(vx20) entered blocking state [ 18.026912][ T415] br2: port 3(vx20) entered forwarding state [ 18.307750][ T71] br2: port 1(w1) entered blocking state [ 18.308232][ T71] br2: port 1(w1) entered forwarding state [ 29.044373][ C0] [ 29.044390][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 29.044392][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 29.044394][ C0] preempt_count: 104, expected: 0 [ 29.044395][ C0] RCU nest depth: 0, expected: 0 [ 29.044396][ C0] INFO: lockdep is turned off. [ 29.044397][ C0] irq event stamp: 870228 [ 29.044398][ C0] hardirqs last enabled at (870228): [] _raw_spin_unlock_irq+0x28/0x50 [ 29.044407][ C0] hardirqs last disabled at (870227): [] _raw_spin_lock_irq+0x4a/0x50 [ 29.044408][ C0] softirqs last enabled at (870188): [] handle_softirqs+0x67c/0x900 [ 29.044412][ C0] softirqs last disabled at (870225): [] __irq_exit_rcu+0x145/0x1c0 [ 29.044414][ C0] Preemption disabled at: [ 29.044415][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 29.044421][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 29.044425][ C0] Tainted: [W]=WARN [ 29.044426][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 29.044428][ C0] Call Trace: [ 29.044429][ C0] [ 29.044431][ C0] dump_stack_lvl+0x6f/0xa0 [ 29.044436][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 29.044437][ C0] __might_resched.cold+0x1fe/0x2c1 [ 29.044441][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 29.044445][ C0] ? __asan_memcpy+0x3c/0x60 [ 29.044447][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.044451][ C0] __kmalloc_noprof+0x443/0x760 [ 29.044454][ C0] ? __rwlock_init+0x150/0x150 [ 29.044457][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 29.044460][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 29.044462][ C0] alloc_buf.isra.0+0x4b/0x260 [ 29.044464][ C0] put_chars+0x1e1/0x2f0 [ 29.044466][ C0] ? __send_to_port+0x420/0x420 [ 29.044468][ C0] ? console_prepend_replay+0x20/0x20 [ 29.044471][ C0] hvc_console_print+0x292/0x780 [ 29.044475][ C0] ? hvc_write+0x3a0/0x3a0 [ 29.044476][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.044477][ C0] ? lock_acquire+0x13c/0x160 [ 29.044480][ C0] console_emit_next_record+0x22f/0x4c0 [ 29.044482][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 29.044484][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.044486][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.044487][ C0] ? lock_acquire+0x13c/0x160 [ 29.044489][ C0] ? console_flush_one_record+0x111/0x710 [ 29.044491][ C0] console_flush_one_record+0x46f/0x710 [ 29.044493][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 29.044496][ C0] console_unlock+0xee/0x1f0 [ 29.044497][ C0] ? lock_acquire+0x13c/0x160 [ 29.044499][ C0] ? console_flush_one_record+0x710/0x710 [ 29.044500][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.044502][ C0] ? lock_release+0x184/0x1f0 [ 29.044503][ C0] ? lock_acquire+0x60/0x160 [ 29.044505][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 29.044507][ C0] ? vprintk_emit+0x320/0x3e0 [ 29.044509][ C0] vprintk_emit+0x37c/0x3e0 [ 29.044511][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 29.044513][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.044515][ C0] ? lock_release+0x184/0x1f0 [ 29.044516][ C0] ? x86_64_start_kernel+0xe0/0x130 [ 29.044521][ C0] _printk+0xc7/0x100 [ 29.044524][ C0] ? snapshot_read.cold+0x21/0x21 [ 29.044526][ C0] ? arch_stack_walk+0xd7/0x130 [ 29.044530][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 29.044532][ C0] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 29.044534][ C0] print_irq_inversion_bug.part.0+0x32/0xc0 [ 29.044536][ C0] mark_lock_irq+0x989/0x9c0 [ 29.044538][ C0] ? _raw_write_unlock_irqrestore+0x80/0x80 [ 29.044541][ C0] mark_lock+0x1d7/0xa00 [ 29.044543][ C0] mark_usage+0x42/0x170 [ 29.044544][ C0] __lock_acquire+0x388/0xc20 [ 29.044546][ C0] lock_acquire.part.0+0xd4/0x280 [ 29.044548][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.044561][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.044563][ C0] ? lock_acquire+0x13c/0x160 [ 29.044565][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 29.044571][ C0] _raw_spin_lock+0x33/0x40 [ 29.044573][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.044579][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.044585][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 29.044591][ C0] call_timer_fn+0x160/0x4d0 [ 29.044595][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 29.044596][ C0] ? debug_object_active_state+0x430/0x430 [ 29.044600][ C0] ? find_held_lock+0x2b/0x80 [ 29.044602][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 29.044603][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.044605][ C0] __run_timers+0x68f/0xaa0 [ 29.044607][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 29.044614][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 29.044616][ C0] ? __lock_acquire+0x518/0xc20 [ 29.044619][ C0] ? __rwlock_init+0x150/0x150 [ 29.044621][ C0] run_timer_softirq+0xf0/0x160 [ 29.044623][ C0] ? __run_timers+0xaa0/0xaa0 [ 29.044625][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 29.044627][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.044629][ C0] handle_softirqs+0x1d3/0x900 [ 29.044631][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 29.044632][ C0] ? _local_bh_enable+0xc0/0xc0 [ 29.044634][ C0] __irq_exit_rcu+0x145/0x1c0 [ 29.044636][ C0] irq_exit_rcu+0xe/0x30 [ 29.044637][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 29.044640][ C0] [ 29.044640][ C0] [ 29.044641][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 29.044643][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 29.044645][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 29.044648][ C0] 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 [ 29.044650][ C0] RSP: 0018:ffffffff98c07cf8 EFLAGS: 00000296 [ 29.044653][ C0] RAX: 00000000000d474f RBX: ffffffff98c1c600 RCX: ffffffff95cf0307 [ 29.044654][ C0] RDX: ffffffff98c1c600 RSI: ffffffff98a38af6 RDI: ffffffff9848d8e0 [ 29.044655][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 29.044656][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff3180fa2 [ 29.044657][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 29.044658][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 29.044661][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 29.044663][ C0] default_idle+0x9/0x10 [ 29.044664][ C0] default_idle_call+0x6e/0xb0 [ 29.044666][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 29.044668][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 29.044669][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 29.044672][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.044674][ C0] do_idle+0xd8/0x190 [ 29.044675][ C0] cpu_startup_entry+0x53/0x70 [ 29.044677][ C0] rest_init+0x279/0x280 [ 29.044679][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 29.044682][ C0] ? rest_init+0x280/0x280 [ 29.044684][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 29.044687][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 29.044690][ C0] ? acpi_enable+0x1e4/0x330 [ 29.044692][ C0] start_kernel+0x3af/0x3b0 [ 29.044694][ C0] x86_64_start_reservations+0x24/0x30 [ 29.044696][ C0] x86_64_start_kernel+0x12b/0x130 [ 29.044698][ C0] common_startup_64+0x13e/0x148 [ 29.044702][ C0] [ 29.067157][ C0] ======================================================== [ 29.067430][ C0] WARNING: possible irq lock inversion dependency detected [ 29.067711][ C0] 7.2.0-virtme #1 Tainted: G W [ 29.067900][ C0] -------------------------------------------------------- [ 29.068174][ C0] swapper/0/0 just changed the state of lock: [ 29.068405][ C0] ff1100000ea52e58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.068761][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 29.069036][ C0] (fs_reclaim){+.+.}-{0:0} [ 29.069038][ C0] [ 29.069038][ C0] [ 29.069038][ C0] and interrupts could create inverse lock ordering between them. [ 29.069038][ C0] [ 29.069634][ C0] [ 29.069634][ C0] other info that might help us debug this: [ 29.069908][ C0] Chain exists of: [ 29.069908][ C0] &br->lock --> console_owner --> fs_reclaim [ 29.069908][ C0] [ 29.070297][ C0] Possible interrupt unsafe locking scenario: [ 29.070297][ C0] [ 29.070575][ C0] CPU0 CPU1 [ 29.070776][ C0] ---- ---- [ 29.070928][ C0] lock(fs_reclaim); [ 29.071050][ C0] local_irq_disable(); [ 29.071291][ C0] lock(&br->lock); [ 29.071531][ C0] lock(console_owner); [ 29.071717][ C0] [ 29.071884][ C0] lock(&br->lock); [ 29.072004][ C0] [ 29.072004][ C0] *** DEADLOCK *** [ 29.072004][ C0] [ 29.072270][ C0] locks held by swapper/0/0: 1, last CPU#0: [ 29.072460][ C0] #0: ffa0000000007c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 29.072815][ C0] [ 29.072815][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 29.073129][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 29.073337][ C0] HARDIRQ-ON-W at: [ 29.073461][ C0] __lock_acquire+0x388/0xc20 [ 29.073708][ C0] lock_acquire.part.0+0xd4/0x280 [ 29.073896][ C0] fs_reclaim_acquire+0xd5/0x120 [ 29.074142][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 29.074415][ C0] kthread_create_worker_on_node+0xea/0x210 [ 29.074691][ C0] workqueue_init+0x2a/0x680 [ 29.074889][ C0] kernel_init_freeable+0x2fe/0x630 [ 29.075166][ C0] kernel_init+0x21/0x150 [ 29.075402][ C0] ret_from_fork+0x474/0x6b0 [ 29.075590][ C0] ret_from_fork_asm+0x11/0x20 [ 29.075829][ C0] SOFTIRQ-ON-W at: [ 29.075940][ C0] __lock_acquire+0x388/0xc20 [ 29.076182][ C0] lock_acquire.part.0+0xd4/0x280 [ 29.076371][ C0] fs_reclaim_acquire+0xd5/0x120 [ 29.076606][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 29.076879][ C0] kthread_create_worker_on_node+0xea/0x210 [ 29.077157][ C0] workqueue_init+0x2a/0x680 [ 29.077346][ C0] kernel_init_freeable+0x2fe/0x630 [ 29.077623][ C0] kernel_init+0x21/0x150 [ 29.077859][ C0] ret_from_fork+0x474/0x6b0 [ 29.078049][ C0] ret_from_fork_asm+0x11/0x20 [ 29.078283][ C0] INITIAL USE at: [ 29.078395][ C0] __lock_acquire+0x388/0xc20 [ 29.078642][ C0] lock_acquire.part.0+0xd4/0x280 [ 29.078825][ C0] fs_reclaim_acquire+0xd5/0x120 [ 29.079072][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 29.079348][ C0] kthread_create_worker_on_node+0xea/0x210 [ 29.079628][ C0] workqueue_init+0x2a/0x680 [ 29.079816][ C0] kernel_init_freeable+0x2fe/0x630 [ 29.080061][ C0] kernel_init+0x21/0x150 [ 29.080248][ C0] ret_from_fork+0x474/0x6b0 [ 29.080490][ C0] ret_from_fork_asm+0x11/0x20 [ 29.080730][ C0] } [ 29.080807][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 29.081089][ C0] ... acquired at: [ 29.081205][ C0] __lock_acquire+0x518/0xc20 [ 29.081409][ C0] lock_acquire.part.0+0xd4/0x280 [ 29.081556][ C0] fs_reclaim_acquire+0xd5/0x120 [ 29.081754][ C0] __kmalloc_noprof+0xd3/0x760 [ 29.081911][ C0] alloc_buf.isra.0+0x4b/0x260 [ 29.082114][ C0] put_chars+0x1e1/0x2f0 [ 29.082262][ C0] hvc_console_print+0x292/0x780 [ 29.082460][ C0] console_emit_next_record+0x22f/0x4c0 [ 29.082609][ C0] console_flush_one_record+0x46f/0x710 [ 29.082808][ C0] console_unlock+0xee/0x1f0 [ 29.082961][ C0] vprintk_emit+0x37c/0x3e0 [ 29.083159][ C0] _printk+0xc7/0x100 [ 29.083279][ C0] dm_interface_init+0x50/0x60 [ 29.083427][ C0] dm_init+0x51/0xd0 [ 29.083589][ C0] do_one_initcall+0x124/0x4f0 [ 29.083734][ C0] kernel_init_freeable+0x596/0x630 [ 29.083935][ C0] kernel_init+0x21/0x150 [ 29.084087][ C0] ret_from_fork+0x474/0x6b0 [ 29.084287][ C0] ret_from_fork_asm+0x11/0x20 [ 29.084432][ C0] [ 29.084560][ C0] -> (console_owner){....}-{0:0} { [ 29.084712][ C0] INITIAL USE at: [ 29.084822][ C0] } [ 29.084950][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 29.085173][ C0] ... acquired at: [ 29.085333][ C0] __lock_acquire+0x518/0xc20 [ 29.085483][ C0] lock_acquire.part.0+0xd4/0x280 [ 29.085685][ C0] console_lock_spinning_enable+0x5c/0x60 [ 29.085869][ C0] console_emit_next_record+0x1d1/0x4c0 [ 29.086075][ C0] console_flush_one_record+0x46f/0x710 [ 29.086224][ C0] console_unlock+0xee/0x1f0 [ 29.086425][ C0] vprintk_emit+0x37c/0x3e0 [ 29.086573][ C0] _printk+0xc7/0x100 [ 29.086735][ C0] br_set_state+0x22f/0x430 [bridge] [ 29.086898][ C0] br_init_port+0xc4/0x200 [bridge] [ 29.087105][ C0] br_stp_enable_port+0x12/0x50 [bridge] [ 29.087292][ C0] br_add_if+0xde2/0x1320 [bridge] [ 29.087498][ C0] do_set_master+0x357/0x580 [ 29.087645][ C0] do_setlink.isra.0+0xa31/0x2750 [ 29.087843][ C0] rtnl_newlink+0x9f1/0x11c0 [ 29.087998][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 29.088201][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 29.088350][ C0] netlink_unicast+0x486/0x750 [ 29.088551][ C0] netlink_sendmsg+0x735/0xc60 [ 29.088700][ C0] ____sys_sendmsg+0x415/0x880 [ 29.088907][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 29.089059][ C0] __sys_sendmsg+0x12c/0x1d0 [ 29.089259][ C0] do_syscall_64+0xff/0x530 [ 29.089408][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.089657][ C0] [ 29.089731][ C0] -> (&br->lock){+.-.}-{3:3} { [ 29.089937][ C0] HARDIRQ-ON-W at: [ 29.090053][ C0] __lock_acquire+0x388/0xc20 [ 29.090287][ C0] lock_acquire.part.0+0xd4/0x280 [ 29.090474][ C0] _raw_spin_lock_bh+0x38/0x50 [ 29.090711][ C0] recalculate_group_addr+0x51/0x120 [bridge] [ 29.091002][ C0] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 29.091282][ C0] br_changelink+0x575/0x16e0 [bridge] [ 29.091476][ C0] br_dev_newlink+0xeb/0x160 [bridge] [ 29.091714][ C0] rtnl_newlink_create+0x2d0/0x750 [ 29.091901][ C0] __rtnl_newlink+0x22b/0xa50 [ 29.092145][ C0] rtnl_newlink+0x9f1/0x11c0 [ 29.092329][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 29.092516][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 29.092753][ C0] netlink_unicast+0x486/0x750 [ 29.092995][ C0] netlink_sendmsg+0x735/0xc60 [ 29.093220][ C0] ____sys_sendmsg+0x415/0x880 [ 29.093466][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 29.093652][ C0] __sys_sendmsg+0x12c/0x1d0 [ 29.093899][ C0] do_syscall_64+0xff/0x530 [ 29.094151][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.094379][ C0] IN-SOFTIRQ-W at: [ 29.094546][ C0] __lock_acquire+0x388/0xc20 [ 29.094742][ C0] lock_acquire.part.0+0xd4/0x280 [ 29.094993][ C0] _raw_spin_lock+0x33/0x40 [ 29.095236][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.095470][ C0] call_timer_fn+0x160/0x4d0 [ 29.095714][ C0] __run_timers+0x68f/0xaa0 [ 29.095969][ C0] run_timer_softirq+0xf0/0x160 [ 29.096162][ C0] handle_softirqs+0x1d3/0x900 [ 29.096404][ C0] __irq_exit_rcu+0x145/0x1c0 [ 29.096650][ C0] irq_exit_rcu+0xe/0x30 [ 29.096840][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 29.097122][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 29.097347][ C0] pv_native_safe_halt+0xf/0x10 [ 29.097537][ C0] default_idle+0x9/0x10 [ 29.097778][ C0] default_idle_call+0x6e/0xb0 [ 29.098021][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 29.098249][ C0] do_idle+0xd8/0x190 [ 29.098399][ C0] cpu_startup_entry+0x53/0x70 [ 29.098609][ C0] rest_init+0x279/0x280 [ 29.098852][ C0] start_kernel+0x3af/0x3b0 [ 29.099102][ C0] x86_64_start_reservations+0x24/0x30 [ 29.099290][ C0] x86_64_start_kernel+0x12b/0x130 [ 29.099534][ C0] common_startup_64+0x13e/0x148 [ 29.099776][ C0] INITIAL USE at: [ 29.099890][ C0] __lock_acquire+0x388/0xc20 [ 29.100136][ C0] lock_acquire.part.0+0xd4/0x280 [ 29.100321][ C0] _raw_spin_lock_bh+0x38/0x50 [ 29.100559][ C0] recalculate_group_addr+0x51/0x120 [bridge] [ 29.100835][ C0] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 29.101074][ C0] br_changelink+0x575/0x16e0 [bridge] [ 29.101317][ C0] br_dev_newlink+0xeb/0x160 [bridge] [ 29.101562][ C0] rtnl_newlink_create+0x2d0/0x750 [ 29.101749][ C0] __rtnl_newlink+0x22b/0xa50 [ 29.101991][ C0] rtnl_newlink+0x9f1/0x11c0 [ 29.102177][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 29.102417][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 29.102660][ C0] netlink_unicast+0x486/0x750 [ 29.102848][ C0] netlink_sendmsg+0x735/0xc60 [ 29.103098][ C0] ____sys_sendmsg+0x415/0x880 [ 29.103334][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 29.103520][ C0] __sys_sendmsg+0x12c/0x1d0 [ 29.103756][ C0] do_syscall_64+0xff/0x530 [ 29.103945][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.104223][ C0] } [ 29.104299][ C0] ... key at: [] __key.7+0x0/0x40 [bridge] [ 29.104527][ C0] ... acquired at: [ 29.104689][ C0] mark_lock+0x1d7/0xa00 [ 29.104843][ C0] mark_usage+0x42/0x170 [ 29.105052][ C0] __lock_acquire+0x388/0xc20 [ 29.105200][ C0] lock_acquire.part.0+0xd4/0x280 [ 29.105399][ C0] _raw_spin_lock+0x33/0x40 [ 29.105547][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.105788][ C0] call_timer_fn+0x160/0x4d0 [ 29.105941][ C0] __run_timers+0x68f/0xaa0 [ 29.106149][ C0] run_timer_softirq+0xf0/0x160 [ 29.106297][ C0] handle_softirqs+0x1d3/0x900 [ 29.106496][ C0] __irq_exit_rcu+0x145/0x1c0 [ 29.106647][ C0] irq_exit_rcu+0xe/0x30 [ 29.106848][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 29.107040][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 29.107278][ C0] pv_native_safe_halt+0xf/0x10 [ 29.107429][ C0] default_idle+0x9/0x10 [ 29.107630][ C0] default_idle_call+0x6e/0xb0 [ 29.107776][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 29.108022][ C0] do_idle+0xd8/0x190 [ 29.108132][ C0] cpu_startup_entry+0x53/0x70 [ 29.108330][ C0] rest_init+0x279/0x280 [ 29.108481][ C0] start_kernel+0x3af/0x3b0 [ 29.108681][ C0] x86_64_start_reservations+0x24/0x30 [ 29.108828][ C0] x86_64_start_kernel+0x12b/0x130 [ 29.109033][ C0] common_startup_64+0x13e/0x148 [ 29.109179][ C0] [ 29.109306][ C0] [ 29.109306][ C0] stack backtrace: [ 29.109499][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 29.109503][ C0] Tainted: [W]=WARN [ 29.109505][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 29.109506][ C0] Call Trace: [ 29.109507][ C0] [ 29.109508][ C0] dump_stack_lvl+0x6f/0xa0 [ 29.109513][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 29.109516][ C0] mark_lock_irq+0x989/0x9c0 [ 29.109517][ C0] ? _raw_write_unlock_irqrestore+0x80/0x80 [ 29.109520][ C0] mark_lock+0x1d7/0xa00 [ 29.109522][ C0] mark_usage+0x42/0x170 [ 29.109524][ C0] __lock_acquire+0x388/0xc20 [ 29.109526][ C0] lock_acquire.part.0+0xd4/0x280 [ 29.109528][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.109535][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.109538][ C0] ? lock_acquire+0x13c/0x160 [ 29.109540][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 29.109546][ C0] _raw_spin_lock+0x33/0x40 [ 29.109548][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.109554][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 29.109560][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 29.109566][ C0] call_timer_fn+0x160/0x4d0 [ 29.109568][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 29.109570][ C0] ? debug_object_active_state+0x430/0x430 [ 29.109574][ C0] ? find_held_lock+0x2b/0x80 [ 29.109575][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 29.109577][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.109580][ C0] __run_timers+0x68f/0xaa0 [ 29.109581][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 29.109588][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 29.109590][ C0] ? __lock_acquire+0x518/0xc20 [ 29.109593][ C0] ? __rwlock_init+0x150/0x150 [ 29.109595][ C0] run_timer_softirq+0xf0/0x160 [ 29.109597][ C0] ? __run_timers+0xaa0/0xaa0 [ 29.109599][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 29.109601][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.109603][ C0] handle_softirqs+0x1d3/0x900 [ 29.109605][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 29.109607][ C0] ? _local_bh_enable+0xc0/0xc0 [ 29.109608][ C0] __irq_exit_rcu+0x145/0x1c0 [ 29.109610][ C0] irq_exit_rcu+0xe/0x30 [ 29.109611][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 29.109613][ C0] [ 29.109614][ C0] [ 29.109614][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 29.109616][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 29.109618][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 29.109620][ C0] 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 [ 29.109622][ C0] RSP: 0018:ffffffff98c07cf8 EFLAGS: 00000296 [ 29.109624][ C0] RAX: 00000000000d474f RBX: ffffffff98c1c600 RCX: ffffffff95cf0307 [ 29.109626][ C0] RDX: ffffffff98c1c600 RSI: ffffffff98a38af6 RDI: ffffffff9848d8e0 [ 29.109627][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 29.109627][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff3180fa2 [ 29.109628][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 29.109629][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 29.109632][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 29.109633][ C0] default_idle+0x9/0x10 [ 29.109635][ C0] default_idle_call+0x6e/0xb0 [ 29.109636][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 29.109638][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 29.109639][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 29.109642][ C0] ? rcu_is_watching+0x16/0xd0 [ 29.109644][ C0] do_idle+0xd8/0x190 [ 29.109645][ C0] cpu_startup_entry+0x53/0x70 [ 29.109647][ C0] rest_init+0x279/0x280 [ 29.109649][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 29.109651][ C0] ? rest_init+0x280/0x280 [ 29.109653][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 29.109657][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 29.109659][ C0] ? acpi_enable+0x1e4/0x330 [ 29.109662][ C0] start_kernel+0x3af/0x3b0 [ 29.109663][ C0] x86_64_start_reservations+0x24/0x30 [ 29.109665][ C0] x86_64_start_kernel+0x12b/0x130 [ 29.109667][ C0] common_startup_64+0x13e/0x148 [ 29.109670][ C0] [ 30.871067][ T12] vx20: left allmulticast mode [ 30.871088][ T12] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 30.871091][ T12] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 12, name: kworker/u16:0 [ 30.871094][ T12] preempt_count: 1, expected: 0 [ 30.871095][ T12] RCU nest depth: 0, expected: 0 [ 30.871097][ T12] INFO: lockdep is turned off. [ 30.871099][ T12] irq event stamp: 37096 [ 30.871100][ T12] hardirqs last enabled at (37095): [] _raw_spin_unlock_irq+0x28/0x50 [ 30.871110][ T12] hardirqs last disabled at (37096): [] __schedule+0x1246/0x20e0 [ 30.871113][ T12] softirqs last enabled at (37092): [] __fib6_clean_all+0xff/0x290 [ 30.871117][ T12] softirqs last disabled at (37086): [] __fib6_clean_all+0xd9/0x290 [ 30.871121][ T12] Preemption disabled at: [ 30.871122][ T12] [] vprintk_emit+0x31b/0x3e0 [ 30.871132][ T12] CPU: 1 UID: 0 PID: 12 Comm: kworker/u16:0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 30.871137][ T12] Tainted: [W]=WARN [ 30.871138][ T12] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 30.871141][ T12] Workqueue: netns cleanup_net [ 30.871148][ T12] Call Trace: [ 30.871150][ T12] [ 30.871152][ T12] dump_stack_lvl+0x6f/0xa0 [ 30.871158][ T12] ? vprintk_emit+0x31b/0x3e0 [ 30.871161][ T12] __might_resched.cold+0x1fe/0x2c1 [ 30.871166][ T12] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 30.871170][ T12] ? rcu_is_watching+0x16/0xd0 [ 30.871175][ T12] __kmalloc_noprof+0x443/0x760 [ 30.871179][ T12] ? __rwlock_init+0x150/0x150 [ 30.871183][ T12] ? alloc_buf.isra.0+0x4b/0x260 [ 30.871188][ T12] ? do_raw_spin_unlock+0x59/0x250 [ 30.871190][ T12] alloc_buf.isra.0+0x4b/0x260 [ 30.871193][ T12] put_chars+0x1e1/0x2f0 [ 30.871195][ T12] ? desc_read_finalized_seq+0x79/0x120 [ 30.871198][ T12] ? __send_to_port+0x420/0x420 [ 30.871201][ T12] ? console_prepend_replay+0x20/0x20 [ 30.871205][ T12] hvc_console_print+0x292/0x780 [ 30.871209][ T12] ? hvc_write+0x3a0/0x3a0 [ 30.871211][ T12] ? do_raw_spin_lock+0x131/0x280 [ 30.871214][ T12] ? __rwlock_init+0x150/0x150 [ 30.871216][ T12] ? rcu_is_watching+0x16/0xd0 [ 30.871218][ T12] ? rcu_is_watching+0x16/0xd0 [ 30.871221][ T12] ? lock_acquire+0x13c/0x160 [ 30.871224][ T12] console_emit_next_record+0x22f/0x4c0 [ 30.871228][ T12] ? devkmsg_read+0x4b0/0x4b0 [ 30.871231][ T12] ? rcu_is_watching+0x16/0xd0 [ 30.871233][ T12] ? rcu_is_watching+0x16/0xd0 [ 30.871235][ T12] ? rcu_is_watching+0x16/0xd0 [ 30.871238][ T12] ? lock_acquire+0x13c/0x160 [ 30.871241][ T12] ? console_flush_one_record+0x111/0x710 [ 30.871243][ T12] console_flush_one_record+0x46f/0x710 [ 30.871247][ T12] ? console_emit_next_record+0x4c0/0x4c0 [ 30.871250][ T12] console_unlock+0xee/0x1f0 [ 30.871253][ T12] ? lock_acquire+0x13c/0x160 [ 30.871255][ T12] ? console_flush_one_record+0x710/0x710 [ 30.871257][ T12] ? rcu_is_watching+0x16/0xd0 [ 30.871259][ T12] ? lock_release+0x184/0x1f0 [ 30.871262][ T12] ? lock_acquire+0x60/0x160 [ 30.871264][ T12] ? __rcu_read_lock+0x30/0x70 [ 30.871267][ T12] ? __down_trylock_console_sem+0x5e/0xa0 [ 30.871269][ T12] ? vprintk_emit+0x320/0x3e0 [ 30.871272][ T12] vprintk_emit+0x37c/0x3e0 [ 30.871279][ T12] ? wake_up_klogd_work_func+0x90/0x90 [ 30.871282][ T12] ? process_one_work+0xe3e/0x1560 [ 30.871285][ T12] ? kthread+0x367/0x460 [ 30.871288][ T12] ? ret_from_fork+0x474/0x6b0 [ 30.871291][ T12] ? ret_from_fork_asm+0x11/0x20 [ 30.871294][ T12] _printk+0xc7/0x100 [ 30.871299][ T12] ? snapshot_read.cold+0x21/0x21 [ 30.871302][ T12] ? netdev_name+0x43/0x60 [ 30.871306][ T12] ? __netdev_printk+0x17f/0x1d0 [ 30.871309][ T12] netdev_info+0xe2/0x116 [ 30.871311][ T12] ? netdev_notice+0x120/0x120 [ 30.871314][ T12] ? rcu_is_watching+0x16/0xd0 [ 30.871317][ T12] ? __up_read+0x355/0x9e0 [ 30.871319][ T12] ? trace_hardirqs_on+0x36/0x40 [ 30.871323][ T12] ? down_write_nested+0x200/0x200 [ 30.871325][ T12] ? mutex_is_locked+0x1c/0x50 [ 30.871329][ T12] netif_set_allmulti.cold+0x7c/0x166 [ 30.871333][ T12] dev_set_allmulti+0xa2/0x150 [ 30.871337][ T12] del_nbp+0xb7/0xc10 [bridge] [ 30.871355][ T12] br_dev_delete+0x9d/0x1e0 [bridge] [ 30.871369][ T12] br_net_exit_rtnl+0xb8/0x110 [bridge] [ 30.871381][ T12] ops_undo_list+0x5be/0x8f0 [ 30.871385][ T12] ? rtnl_net_dumpid_one+0x2d0/0x2d0 [ 30.871388][ T12] ? idr_destroy+0x121/0x250 [ 30.871392][ T12] cleanup_net+0x431/0x890 [ 30.871395][ T12] ? net_passive_dec+0x230/0x230 [ 30.871398][ T12] ? lock_acquire+0x13c/0x160 [ 30.871400][ T12] ? rcu_is_watching+0x16/0xd0 [ 30.871404][ T12] process_one_work+0xe3e/0x1560 [ 30.871408][ T12] ? __queue_delayed_work+0x470/0x470 [ 30.871411][ T12] ? find_held_lock+0x2b/0x80 [ 30.871414][ T12] ? __rwlock_init+0x150/0x150 [ 30.871417][ T12] worker_thread+0x4f1/0xd60 [ 30.871422][ T12] ? rescuer_thread+0x1340/0x1340 [ 30.871425][ T12] kthread+0x367/0x460 [ 30.871427][ T12] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 30.871429][ T12] ? kthread_affine_preferred+0x4c0/0x4c0 [ 30.871432][ T12] ret_from_fork+0x474/0x6b0 [ 30.871434][ T12] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 30.871437][ T12] ? __switch_to+0x5a3/0xe00 [ 30.871440][ T12] ? kthread_affine_preferred+0x4c0/0x4c0 [ 30.871443][ T12] ret_from_fork_asm+0x11/0x20 [ 30.871448][ T12] [ 30.898811][ T12] vx20: left promiscuous mode [ 30.899092][ T12] br2: port 3(vx20) entered disabled state [ 30.900050][ T12] vx10: left allmulticast mode [ 30.900198][ T12] vx10: left promiscuous mode [ 30.900476][ T12] br2: port 2(vx10) entered disabled state [ 30.903463][ T12] w1: left allmulticast mode [ 30.903706][ T12] w1: left promiscuous mode [ 30.904030][ T12] br2: port 1(w1) entered disabled state [ 30.991436][ T12] vx20: left allmulticast mode [ 30.991679][ T12] vx20: left promiscuous mode [ 30.991981][ T12] br2: port 3(vx20) entered disabled state [ 30.993900][ T12] vx10: left allmulticast mode [ 30.994121][ T12] vx10: left promiscuous mode [ 31.001521][ T12] br2: port 2(vx10) entered disabled state [ 31.003117][ T12] w1: left allmulticast mode [ 31.004094][ T12] w1: left promiscuous mode [ 31.004361][ T12] br2: port 1(w1) entered disabled state [ 31.594616][ T565] br1: port 4(veth2) entered disabled state [ 31.616698][ T566] veth2: left allmulticast mode [ 31.616924][ T566] veth2: left promiscuous mode [ 31.617200][ T566] br1: port 4(veth2) entered disabled state [ 31.687392][ T569] br1: port 3(veth1) entered disabled state [ 31.706691][ T570] veth1: left allmulticast mode [ 31.706904][ T570] veth1: left promiscuous mode [ 31.707177][ T570] br1: port 3(veth1) entered disabled state [ 31.756609][ T572] vx20: left allmulticast mode [ 31.756829][ T572] vx20: left promiscuous mode [ 31.757104][ T572] br1: port 2(vx20) entered disabled state [ 31.868763][ T576] vx10: left allmulticast mode [ 31.868955][ T576] vx10: left promiscuous mode [ 31.869180][ T576] br1: port 1(vx10) entered disabled state