[ 11.827853][ T291] br0: port 1(gw_l) entered blocking state [ 11.827928][ T291] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.827930][ T291] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 291, name: ip [ 11.827931][ T291] preempt_count: 1, expected: 0 [ 11.827932][ T291] RCU nest depth: 0, expected: 0 [ 11.827933][ T291] locks held by ip/291: 5, last CPU#2: [ 11.827935][ T291] #0: ffffffff8811b740 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11b0 [ 11.827947][ T291] #1: ffffffff8797ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.827952][ T291] #2: ffffffff8797de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.827956][ T291] #3: ffffffff8789d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.827960][ T291] #4: ffffffff8789d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.827964][ T291] irq event stamp: 30954 [ 11.827965][ T291] hardirqs last enabled at (30953): [] __down_trylock_console_sem+0x86/0xa0 [ 11.827968][ T291] hardirqs last disabled at (30954): [] console_emit_next_record+0x3d4/0x4c0 [ 11.827970][ T291] softirqs last enabled at (30866): [] __alloc_skb+0x4c2/0x5f0 [ 11.827973][ T291] softirqs last disabled at (30864): [] __alloc_skb+0x4c2/0x5f0 [ 11.827976][ T291] Preemption disabled at: [ 11.827976][ T291] [] vprintk_emit+0x31b/0x3e0 [ 11.827981][ T291] CPU: 2 UID: 0 PID: 291 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.827985][ T291] Tainted: [W]=WARN [ 11.827986][ T291] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.827988][ T291] Call Trace: [ 11.827989][ T291] [ 11.827991][ T291] dump_stack_lvl+0x6f/0xa0 [ 11.827997][ T291] ? vprintk_emit+0x31b/0x3e0 [ 11.827998][ T291] __might_resched.cold+0x1fe/0x2c1 [ 11.828003][ T291] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.828007][ T291] ? __kmalloc_noprof+0xdb/0x760 [ 11.828014][ T291] __kmalloc_noprof+0x443/0x760 [ 11.828016][ T291] ? alloc_buf.isra.0+0x4b/0x260 [ 11.828022][ T291] ? do_raw_spin_unlock+0x59/0x250 [ 11.828025][ T291] alloc_buf.isra.0+0x4b/0x260 [ 11.828029][ T291] put_chars+0x1e1/0x2f0 [ 11.828031][ T291] ? __send_to_port+0x420/0x420 [ 11.828034][ T291] ? validate_chain+0x34a/0xc20 [ 11.828039][ T291] hvc_console_print+0x292/0x780 [ 11.828047][ T291] ? hvc_write+0x3a0/0x3a0 [ 11.828050][ T291] ? rcu_is_watching+0x16/0xd0 [ 11.828052][ T291] ? lock_acquire+0x13c/0x160 [ 11.828056][ T291] console_emit_next_record+0x22f/0x4c0 [ 11.828060][ T291] ? devkmsg_read+0x4b0/0x4b0 [ 11.828061][ T291] ? console_flush_one_record+0x106/0x710 [ 11.828065][ T291] ? rcu_is_watching+0x16/0xd0 [ 11.828066][ T291] ? lock_acquire+0x13c/0x160 [ 11.828070][ T291] console_flush_one_record+0x46f/0x710 [ 11.828074][ T291] ? console_emit_next_record+0x4c0/0x4c0 [ 11.828076][ T291] ? __lock_acquire+0x518/0xc20 [ 11.828081][ T291] console_unlock+0xee/0x1f0 [ 11.828084][ T291] ? console_flush_one_record+0x710/0x710 [ 11.828086][ T291] ? rcu_is_watching+0x16/0xd0 [ 11.828087][ T291] ? lock_acquire+0xe0/0x160 [ 11.828091][ T291] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.828093][ T291] ? vprintk_emit+0x320/0x3e0 [ 11.828095][ T291] vprintk_emit+0x37c/0x3e0 [ 11.828098][ T291] ? wake_up_klogd_work_func+0x90/0x90 [ 11.828100][ T291] ? __lock_release.isra.0+0x69/0x1a0 [ 11.828102][ T291] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 11.828106][ T291] ? mark_held_locks+0x40/0x70 [ 11.828109][ T291] _printk+0xc7/0x100 [ 11.828113][ T291] ? snapshot_read.cold+0x21/0x21 [ 11.828116][ T291] ? br_multicast_flood+0x960/0x960 [ 11.828120][ T291] ? do_setlink.isra.0+0x9ec/0x2a60 [ 11.828121][ T291] ? rtnl_newlink+0x9f1/0x11b0 [ 11.828122][ T291] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.828126][ T291] br_set_state+0x22f/0x430 [ 11.828130][ T291] br_init_port+0xc4/0x200 [ 11.828132][ T291] new_nbp+0x3a0/0x650 [ 11.828136][ T291] br_add_if+0x212/0x1320 [ 11.828139][ T291] ? is_bpf_text_address+0x72/0x110 [ 11.828142][ T291] ? kernel_text_address+0x149/0x170 [ 11.828148][ T291] do_set_master+0x3b2/0x780 [ 11.828151][ T291] do_setlink.isra.0+0x9ec/0x2a60 [ 11.828155][ T291] ? stack_trace_save+0x93/0xc0 [ 11.828158][ T291] ? rtnl_link_get_size+0x350/0x350 [ 11.828159][ T291] ? rcu_read_lock_any_held+0x66/0x90 [ 11.828162][ T291] ? stack_depot_save_flags+0x38e/0x790 [ 11.828165][ T291] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.828167][ T291] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.828169][ T291] ? validate_chain+0x38b/0xc20 [ 11.828171][ T291] ? kasan_save_stack+0x3d/0x50 [ 11.828174][ T291] ? kasan_save_stack+0x2f/0x50 [ 11.828175][ T291] ? kasan_save_track+0x14/0x30 [ 11.828176][ T291] ? __kasan_kmalloc+0x7b/0x90 [ 11.828178][ T291] ? __lock_acquire+0x518/0xc20 [ 11.828180][ T291] ? snd_ac97_proc_read_main+0x4de/0x1f00 [ 11.828183][ T291] ? ___sys_sendmsg+0xd0/0x1d0 [ 11.828187][ T291] ? lock_acquire.part.0+0xd4/0x280 [ 11.828188][ T291] ? rtnl_newlink+0x9a8/0x11b0 [ 11.828191][ T291] ? rcu_is_watching+0x16/0xd0 [ 11.828193][ T291] ? lock_acquire+0x13c/0x160 [ 11.828194][ T291] ? rcu_is_watching+0x16/0xd0 [ 11.828195][ T291] ? rcu_is_watching+0x16/0xd0 [ 11.828197][ T291] ? trace_contention_end+0xb3/0x180 [ 11.828199][ T291] ? __mutex_lock+0x1db/0x1ea0 [ 11.828201][ T291] ? __mutex_lock+0x9a3/0x1ea0 [ 11.828202][ T291] ? rtnl_newlink+0x9a8/0x11b0 [ 11.828206][ T291] ? ww_mutex_lock+0x160/0x160 [ 11.828207][ T291] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.828212][ T291] ? rcu_is_watching+0x16/0xd0 [ 11.828214][ T291] ? __rtnl_newlink+0x3fa/0xa50 [ 11.828219][ T291] rtnl_newlink+0x9f1/0x11b0 [ 11.828224][ T291] ? rtnl_bridge_getlink+0x850/0x850 [ 11.828226][ T291] ? __lock_acquire+0x518/0xc20 [ 11.828230][ T291] ? lock_acquire.part.0+0xd4/0x280 [ 11.828232][ T291] ? find_held_lock+0x2b/0x80 [ 11.828234][ T291] ? rtnl_bridge_getlink+0x850/0x850 [ 11.828236][ T291] ? __lock_release.isra.0+0x69/0x1a0 [ 11.828240][ T291] ? rtnl_bridge_getlink+0x850/0x850 [ 11.828242][ T291] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.828244][ T291] ? rtnl_link_fill+0x920/0x920 [ 11.828246][ T291] ? __lock_acquire+0x518/0xc20 [ 11.828250][ T291] ? lock_acquire.part.0+0xd4/0x280 [ 11.828252][ T291] ? find_held_lock+0x2b/0x80 [ 11.828255][ T291] netlink_rcv_skb+0x14e/0x3a0 [ 11.828258][ T291] ? rtnl_link_fill+0x920/0x920 [ 11.828261][ T291] ? netlink_ack+0xcf0/0xcf0 [ 11.828267][ T291] ? netlink_deliver_tap+0xc5/0x330 [ 11.828269][ T291] ? netlink_deliver_tap+0x13c/0x330 [ 11.828274][ T291] netlink_unicast+0x486/0x750 [ 11.828277][ T291] ? netlink_attachskb+0x810/0x810 [ 11.828280][ T291] ? find_held_lock+0x2b/0x80 [ 11.828284][ T291] netlink_sendmsg+0x75b/0xc90 [ 11.828288][ T291] ? netlink_unicast+0x750/0x750 [ 11.828292][ T291] ? __lock_release.isra.0+0x69/0x1a0 [ 11.828293][ T291] ? __import_iovec+0x36c/0x620 [ 11.828298][ T291] __sock_sendmsg+0xce/0x190 [ 11.828301][ T291] ____sys_sendmsg+0x61a/0x860 [ 11.828303][ T291] ? copy_msghdr_from_user+0x279/0x420 [ 11.828306][ T291] ? sock_write_iter+0x400/0x400 [ 11.828307][ T291] ? move_addr_to_kernel+0x40/0x40 [ 11.828314][ T291] ___sys_sendmsg+0x14e/0x1d0 [ 11.828317][ T291] ? copy_msghdr_from_user+0x420/0x420 [ 11.828332][ T291] __sys_sendmsg+0x12c/0x1d0 [ 11.828335][ T291] ? __sys_sendmsg_sock+0x20/0x20 [ 11.828341][ T291] ? rcu_is_watching+0x16/0xd0 [ 11.828344][ T291] do_syscall_64+0xff/0x530 [ 11.828347][ T291] ? exc_page_fault+0xee/0x100 [ 11.828350][ T291] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.828353][ T291] RIP: 0033:0x7f9fd257a54e [ 11.828356][ T291] Code: 4d 89 d8 e8 b4 bd 00 00 4c 8b 5d f8 41 8b 93 08 03 00 00 59 5e 48 83 f8 fc 74 11 c9 c3 0f 1f 80 00 00 00 00 48 8b 45 10 0f 05 c3 83 e2 39 83 fa 08 75 e7 e8 03 ff ff ff 0f 1f 00 f3 0f 1e fa [ 11.828358][ T291] RSP: 002b:00007ffd6548afb0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.828360][ T291] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f9fd257a54e [ 11.828362][ T291] RDX: 0000000000000000 RSI: 00007ffd6548b060 RDI: 0000000000000005 [ 11.828363][ T291] RBP: 00007ffd6548afc0 R08: 0000000000000000 R09: 0000000000000000 [ 11.828363][ T291] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91bb7d [ 11.828364][ T291] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffd6548b720 [ 11.828371][ T291] [ 11.868590][ T291] br0: port 1(gw_l) entered disabled state [ 11.868951][ T291] gw_l: entered allmulticast mode [ 11.870091][ T291] gw_l: entered promiscuous mode [ 11.871446][ T291] br0: port 1(gw_l) entered blocking state [ 11.871808][ T291] br0: port 1(gw_l) entered forwarding state [ 11.885892][ T291] ip (291) used greatest stack depth: 23336 bytes left [ 12.038342][ T293] br0: port 2(amtg) entered blocking state [ 12.038753][ T293] br0: port 2(amtg) entered disabled state [ 12.039051][ T293] amtg: entered allmulticast mode [ 12.040082][ T293] amtg: entered promiscuous mode [ 12.600936][ T304] br0: port 2(amtg) entered blocking state [ 12.601262][ T304] br0: port 2(amtg) entered forwarding state [ 12.916744][ T312] amtr: entered allmulticast mode [ 12.916761][ T312] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.916762][ T312] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 312, name: smcrouted [ 12.916764][ T312] preempt_count: 1, expected: 0 [ 12.916765][ T312] RCU nest depth: 0, expected: 0 [ 12.916766][ T312] locks held by smcrouted/312: 5, last CPU#2: [ 12.916768][ T312] #0: ffffffff8811b740 (rtnl_mutex){+.+.}-{4:4}, at: ip_mroute_setsockopt+0x15c/0x1010 [ 12.916780][ T312] #1: ffffffff8797ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.916785][ T312] #2: ffffffff8797de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.916789][ T312] #3: ffffffff8789d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.916793][ T312] #4: ffffffff8789d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 12.916797][ T312] irq event stamp: 5194 [ 12.916798][ T312] hardirqs last enabled at (5193): [] __down_trylock_console_sem+0x86/0xa0 [ 12.916801][ T312] hardirqs last disabled at (5194): [] console_emit_next_record+0x3d4/0x4c0 [ 12.916803][ T312] softirqs last enabled at (5110): [] __alloc_skb+0x4c2/0x5f0 [ 12.916806][ T312] softirqs last disabled at (5108): [] __alloc_skb+0x4c2/0x5f0 [ 12.916809][ T312] Preemption disabled at: [ 12.916809][ T312] [] vprintk_emit+0x31b/0x3e0 [ 12.916814][ T312] CPU: 2 UID: 0 PID: 312 Comm: smcrouted Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.916817][ T312] Tainted: [W]=WARN [ 12.916818][ T312] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.916820][ T312] Call Trace: [ 12.916821][ T312] [ 12.916823][ T312] dump_stack_lvl+0x6f/0xa0 [ 12.916829][ T312] ? vprintk_emit+0x31b/0x3e0 [ 12.916831][ T312] __might_resched.cold+0x1fe/0x2c1 [ 12.916835][ T312] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.916839][ T312] ? __kmalloc_noprof+0xdb/0x760 [ 12.916844][ T312] __kmalloc_noprof+0x443/0x760 [ 12.916846][ T312] ? alloc_buf.isra.0+0x4b/0x260 [ 12.916852][ T312] ? do_raw_spin_unlock+0x59/0x250 [ 12.916855][ T312] alloc_buf.isra.0+0x4b/0x260 [ 12.916859][ T312] put_chars+0x1e1/0x2f0 [ 12.916861][ T312] ? __send_to_port+0x420/0x420 [ 12.916864][ T312] ? rcu_read_lock_any_held+0x3c/0x90 [ 12.916867][ T312] ? validate_chain+0x38b/0xc20 [ 12.916872][ T312] hvc_console_print+0x292/0x780 [ 12.916876][ T312] ? __lock_acquire+0x518/0xc20 [ 12.916877][ T312] ? __lock_acquire+0x518/0xc20 [ 12.916882][ T312] ? hvc_write+0x3a0/0x3a0 [ 12.916885][ T312] ? rcu_is_watching+0x16/0xd0 [ 12.916887][ T312] ? lock_acquire+0x13c/0x160 [ 12.916891][ T312] console_emit_next_record+0x22f/0x4c0 [ 12.916895][ T312] ? devkmsg_read+0x4b0/0x4b0 [ 12.916897][ T312] ? console_flush_one_record+0x106/0x710 [ 12.916900][ T312] ? rcu_is_watching+0x16/0xd0 [ 12.916902][ T312] ? lock_acquire+0x13c/0x160 [ 12.916906][ T312] console_flush_one_record+0x46f/0x710 [ 12.916910][ T312] ? console_emit_next_record+0x4c0/0x4c0 [ 12.916912][ T312] ? __lock_acquire+0x518/0xc20 [ 12.916917][ T312] console_unlock+0xee/0x1f0 [ 12.916920][ T312] ? console_flush_one_record+0x710/0x710 [ 12.916921][ T312] ? rcu_is_watching+0x16/0xd0 [ 12.916923][ T312] ? lock_acquire+0xe0/0x160 [ 12.916927][ T312] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.916928][ T312] ? vprintk_emit+0x320/0x3e0 [ 12.916931][ T312] vprintk_emit+0x37c/0x3e0 [ 12.916934][ T312] ? wake_up_klogd_work_func+0x90/0x90 [ 12.916936][ T312] ? netlink_broadcast_filtered+0x29c/0x3c0 [ 12.916939][ T312] ? kasan_save_stack+0x3d/0x50 [ 12.916942][ T312] ? kasan_save_stack+0x2f/0x50 [ 12.916945][ T312] _printk+0xc7/0x100 [ 12.916949][ T312] ? snapshot_read.cold+0x21/0x21 [ 12.916950][ T312] ? __sys_setsockopt+0xe4/0x150 [ 12.916955][ T312] ? netdev_name+0x43/0x60 [ 12.916959][ T312] ? __netdev_printk+0x17f/0x1d0 [ 12.916963][ T312] netdev_info+0xe2/0x116 [ 12.916966][ T312] ? netdev_notice+0x120/0x120 [ 12.916967][ T312] ? mark_usage+0x61/0x170 [ 12.916973][ T312] ? find_held_lock+0x2b/0x80 [ 12.916975][ T312] ? mutex_is_locked+0x1c/0x50 [ 12.916978][ T312] netif_set_allmulti.cold+0x7c/0x166 [ 12.916982][ T312] dev_set_allmulti+0xa6/0x240 [ 12.916985][ T312] vif_add+0x9da/0xdc0 [ 12.916988][ T312] ? __mutex_lock+0x1db/0x1ea0 [ 12.916990][ T312] ? __mutex_lock+0x9a3/0x1ea0 [ 12.916994][ T312] ? ip_mr_output_finish.isra.0+0x630/0x630 [ 12.916997][ T312] ? lock_acquire.part.0+0xd4/0x280 [ 12.916999][ T312] ? __might_fault+0x97/0x140 [ 12.917004][ T312] ? __lock_release.isra.0+0x69/0x1a0 [ 12.917007][ T312] ? __might_fault+0x97/0x140 [ 12.917013][ T312] ip_mroute_setsockopt+0xd02/0x1010 [ 12.917017][ T312] ? ipmr_rtm_route+0x250/0x250 [ 12.917022][ T312] ? __might_fault+0x97/0x140 [ 12.917024][ T312] ? find_held_lock+0x2b/0x80 [ 12.917028][ T312] ? __might_fault+0x97/0x140 [ 12.917030][ T312] ? __might_fault+0x97/0x140 [ 12.917033][ T312] do_ip_setsockopt+0x27c/0x25f0 [ 12.917037][ T312] ? ip_sock_set_tos+0x40/0x40 [ 12.917039][ T312] ? find_held_lock+0x2b/0x80 [ 12.917042][ T312] ? __lock_release.isra.0+0x69/0x1a0 [ 12.917043][ T312] ? do_syscall_64+0xff/0x530 [ 12.917047][ T312] ? rcu_is_watching+0x16/0xd0 [ 12.917049][ T312] ? do_raw_spin_unlock+0x59/0x250 [ 12.917050][ T312] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 12.917052][ T312] ? lockdep_hardirqs_on+0x91/0x130 [ 12.917054][ T312] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 12.917056][ T312] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 12.917059][ T312] ip_setsockopt+0x2f/0x90 [ 12.917062][ T312] raw_setsockopt+0x8c/0x1f0 [ 12.917065][ T312] ? rcu_is_watching+0x16/0xd0 [ 12.917067][ T312] ? raw_bind+0x5b0/0x5b0 [ 12.917072][ T312] do_sock_setsockopt+0x164/0x3b0 [ 12.917075][ T312] ? put_user_ifreq+0x90/0x90 [ 12.917076][ T312] ? __lock_release.isra.0+0x69/0x1a0 [ 12.917079][ T312] ? fd_install+0x18c/0x3d0 [ 12.917083][ T312] ? fd_install+0x19b/0x3d0 [ 12.917088][ T312] __sys_setsockopt+0xe4/0x150 [ 12.917092][ T312] __x64_sys_setsockopt+0xbd/0x180 [ 12.917094][ T312] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 12.917098][ T312] ? lockdep_hardirqs_on+0x91/0x130 [ 12.917099][ T312] ? do_syscall_64+0xa6/0x530 [ 12.917101][ T312] do_syscall_64+0xff/0x530 [ 12.917103][ T312] ? exc_page_fault+0xee/0x100 [ 12.917105][ T312] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 12.917107][ T312] RIP: 0033:0x7f942a5f42de [ 12.917111][ T312] Code: 55 48 63 c9 48 63 ff 45 89 c9 48 89 e5 48 83 ec 08 6a 2c e8 94 72 f7 ff c9 c3 66 90 f3 0f 1e fa 49 89 ca b8 36 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 0a c3 66 0f 1f 84 00 00 00 00 00 48 8b 15 e1 [ 12.917112][ T312] RSP: 002b:00007ffc35047d68 EFLAGS: 00000246 ORIG_RAX: 0000000000000036 [ 12.917115][ T312] RAX: ffffffffffffffda RBX: 0000561c07fda2c0 RCX: 00007f942a5f42de [ 12.917116][ T312] RDX: 00000000000000ca RSI: 0000000000000000 RDI: 0000000000000007 [ 12.917117][ T312] RBP: 00007ffc35047e20 R08: 0000000000000010 R09: 0000000000000001 [ 12.917118][ T312] R10: 00007ffc35047da0 R11: 0000000000000246 R12: 0000561c2371b0c4 [ 12.917118][ T312] R13: 00007ffc35047e70 R14: 0000561c2371b0b0 R15: 0000000000000000 [ 12.917125][ T312] [ 12.954969][ T312] relay_gw: entered allmulticast mode [ 12.956003][ T312] relay_src: entered allmulticast mode [ 27.093328][ C0] [ 27.093344][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 27.093347][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 349, name: nc [ 27.093348][ C0] preempt_count: 104, expected: 0 [ 27.093349][ C0] RCU nest depth: 2, expected: 0 [ 27.093350][ C0] INFO: lockdep is turned off. [ 27.093351][ C0] irq event stamp: 7591986 [ 27.093352][ C0] hardirqs last enabled at (7591986): [] _raw_spin_unlock_irq+0x28/0x50 [ 27.093360][ C0] hardirqs last disabled at (7591985): [] _raw_spin_lock_irq+0x4a/0x50 [ 27.093362][ C0] softirqs last enabled at (7591774): [] __dev_queue_xmit+0x974/0x1b90 [ 27.093365][ C0] softirqs last disabled at (7591775): [] do_softirq+0xac/0xe0 [ 27.093370][ C0] Preemption disabled at: [ 27.093370][ C0] [] __dev_queue_xmit+0x20c/0x1b90 [ 27.093377][ C0] CPU: 0 UID: 0 PID: 349 Comm: nc Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.093380][ C0] Tainted: [W]=WARN [ 27.093381][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.093383][ C0] Call Trace: [ 27.093384][ C0] [ 27.093386][ C0] dump_stack_lvl+0x6f/0xa0 [ 27.093391][ C0] ? __dev_queue_xmit+0x20c/0x1b90 [ 27.093393][ C0] __might_resched.cold+0x1fe/0x2c1 [ 27.093396][ C0] ? prb_next_reserve_seq+0x2d0/0x2d0 [ 27.093399][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 27.093402][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.093405][ C0] __kmalloc_noprof+0x443/0x760 [ 27.093408][ C0] ? __rwlock_init+0x150/0x150 [ 27.093411][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 27.093415][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 27.093417][ C0] alloc_buf.isra.0+0x4b/0x260 [ 27.093419][ C0] put_chars+0x1e1/0x2f0 [ 27.093421][ C0] ? __send_to_port+0x420/0x420 [ 27.093423][ C0] ? console_prepend_replay+0x20/0x20 [ 27.093425][ C0] hvc_console_print+0x292/0x780 [ 27.093430][ C0] ? hvc_write+0x3a0/0x3a0 [ 27.093431][ C0] ? vsnprintf+0x668/0x1700 [ 27.093434][ C0] ? __rwlock_init+0x150/0x150 [ 27.093435][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.093436][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.093438][ C0] console_emit_next_record+0x22f/0x4c0 [ 27.093440][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 27.093442][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.093443][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.093444][ C0] ? lock_acquire+0x13c/0x160 [ 27.093447][ C0] ? console_flush_one_record+0x111/0x710 [ 27.093448][ C0] console_flush_one_record+0x46f/0x710 [ 27.093451][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 27.093453][ C0] console_unlock+0xee/0x1f0 [ 27.093455][ C0] ? lock_acquire+0x13c/0x160 [ 27.093457][ C0] ? console_flush_one_record+0x710/0x710 [ 27.093458][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.093459][ C0] ? lock_release+0x184/0x1f0 [ 27.093461][ C0] ? lock_acquire+0xe0/0x160 [ 27.093462][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 27.093464][ C0] ? vprintk_emit+0x320/0x3e0 [ 27.093466][ C0] vprintk_emit+0x37c/0x3e0 [ 27.093467][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 27.093469][ C0] ? lock_release+0x184/0x1f0 [ 27.093471][ C0] _printk+0xc7/0x100 [ 27.093474][ C0] ? snapshot_read.cold+0x21/0x21 [ 27.093476][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 27.093478][ C0] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 27.093479][ C0] print_irq_inversion_bug.part.0+0x32/0xc0 [ 27.093481][ C0] mark_lock_irq+0x989/0x9c0 [ 27.093484][ C0] mark_lock+0x1d7/0xa00 [ 27.093486][ C0] mark_usage+0x42/0x170 [ 27.093488][ C0] __lock_acquire+0x388/0xc20 [ 27.093490][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.093492][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [ 27.093495][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.093496][ C0] ? lock_acquire+0x13c/0x160 [ 27.093498][ C0] ? br_message_age_timer_expired+0x70/0x70 [ 27.093499][ C0] _raw_spin_lock+0x33/0x40 [ 27.093500][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [ 27.093501][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [ 27.093503][ C0] ? br_message_age_timer_expired+0x70/0x70 [ 27.093504][ C0] call_timer_fn+0x160/0x4d0 [ 27.093507][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.093509][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 27.093510][ C0] ? find_held_lock+0x2b/0x80 [ 27.093512][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.093514][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.093516][ C0] __run_timers+0x68f/0xaa0 [ 27.093517][ C0] ? br_message_age_timer_expired+0x70/0x70 [ 27.093519][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.093521][ C0] ? __lock_acquire+0x518/0xc20 [ 27.093523][ C0] ? __rwlock_init+0x150/0x150 [ 27.093526][ C0] run_timer_softirq+0xf0/0x160 [ 27.093528][ C0] ? __run_timers+0xaa0/0xaa0 [ 27.093530][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.093531][ C0] handle_softirqs+0x1d3/0x900 [ 27.093533][ C0] ? _local_bh_enable+0xc0/0xc0 [ 27.093534][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 27.093536][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.093537][ C0] ? __dev_queue_xmit+0x974/0x1b90 [ 27.093539][ C0] do_softirq+0xac/0xe0 [ 27.093540][ C0] [ 27.093541][ C0] [ 27.093541][ C0] __local_bh_enable_ip+0x118/0x150 [ 27.093543][ C0] __dev_queue_xmit+0x989/0x1b90 [ 27.093545][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.093547][ C0] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 27.093549][ C0] ? lock_acquire.part.0+0x60/0x280 [ 27.093550][ C0] ? find_held_lock+0x2b/0x80 [ 27.093552][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.093553][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.093554][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.093556][ C0] ? __asan_memcpy+0x3c/0x60 [ 27.093558][ C0] ? neigh_hh_output+0x152/0x4c0 [ 27.093562][ C0] ip_finish_output2+0x7c4/0x1c60 [ 27.093563][ C0] ? ip_dst_mtu_maybe_forward+0x2bd/0x720 [ 27.093565][ C0] ? ip_copy_metadata+0x906/0xc70 [ 27.093566][ C0] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 27.093567][ C0] ? ip_fast_csum+0xc/0x10 [ 27.093571][ C0] ? ip_fraglist_prepare+0x3f5/0x690 [ 27.093572][ C0] ? mark_usage+0x61/0x170 [ 27.093574][ C0] ip_do_fragment+0x12c2/0x1b20 [ 27.093575][ C0] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 27.093577][ C0] ? ip4_dst_hoplimit+0x320/0x320 [ 27.093579][ C0] ? ip_dst_mtu_maybe_forward+0x2c7/0x720 [ 27.093580][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 27.093582][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.093583][ C0] ip_output+0x1ca/0x660 [ 27.093585][ C0] ? ip_finish_output+0x460/0x460 [ 27.093586][ C0] ? ip_make_skb+0x26a/0x300 [ 27.093587][ C0] ? ip_frag_init+0x4f0/0x4f0 [ 27.093589][ C0] ? ip_flush_pending_frames+0x20/0x20 [ 27.093591][ C0] ip_send_skb+0xa3/0x1c0 [ 27.093592][ C0] udp_send_skb+0x87a/0x1270 [ 27.093597][ C0] udp_sendmsg+0x164e/0x2680 [ 27.093599][ C0] ? udp_cmsg_send+0x360/0x360 [ 27.093600][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 27.093601][ C0] ? __might_fault+0x97/0x140 [ 27.093604][ C0] ? find_held_lock+0x2b/0x80 [ 27.093605][ C0] ? __might_fault+0x97/0x140 [ 27.093608][ C0] ? anon_pipe_trim_and_unlock+0x450/0x450 [ 27.093612][ C0] __sock_sendmsg+0xce/0x190 [ 27.093614][ C0] ? fdget+0x4f/0x1e0 [ 27.093617][ C0] __sys_sendto+0x260/0x320 [ 27.093619][ C0] ? __ia32_sys_getpeername+0xd0/0xd0 [ 27.093628][ C0] ? ksys_read+0x1ac/0x250 [ 27.093631][ C0] __x64_sys_sendto+0xe4/0x1f0 [ 27.093633][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.093636][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 27.093638][ C0] ? do_syscall_64+0xa6/0x530 [ 27.093640][ C0] do_syscall_64+0xff/0x530 [ 27.093641][ C0] ? irq_exit_rcu+0x1a/0x30 [ 27.093643][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.093645][ C0] RIP: 0033:0x7ffa96cdb54e [ 27.093649][ C0] 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 [ 27.093650][ C0] RSP: 002b:00007ffec8f9c540 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 27.093653][ C0] RAX: ffffffffffffffda RBX: 000055677c68c9e0 RCX: 00007ffa96cdb54e [ 27.093655][ C0] RDX: 0000000000002000 RSI: 000055677c713a70 RDI: 0000000000000005 [ 27.093656][ C0] RBP: 00007ffec8f9c550 R08: 0000000000000000 R09: 0000000000000000 [ 27.093656][ C0] R10: 0000000000000000 R11: 0000000000000202 R12: 000055677c68c9e0 [ 27.093657][ C0] R13: 000055677c70fac0 R14: 000055677c68c350 R15: 000055677c68c9e0 [ 27.093659][ C0] [ 27.117468][ C0] ======================================================== [ 27.117726][ C0] WARNING: possible irq lock inversion dependency detected [ 27.117980][ C0] 7.2.0-virtme #1 Tainted: G W [ 27.118151][ C0] -------------------------------------------------------- [ 27.118403][ C0] nc/349 just changed the state of lock: [ 27.118589][ C0] ff1100000fff2e98 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [ 27.118838][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 27.119093][ C0] (fs_reclaim){+.+.}-{0:0} [ 27.119095][ C0] [ 27.119095][ C0] [ 27.119095][ C0] and interrupts could create inverse lock ordering between them. [ 27.119095][ C0] [ 27.119704][ C0] [ 27.119704][ C0] other info that might help us debug this: [ 27.119959][ C0] Chain exists of: [ 27.119959][ C0] &br->lock --> console_owner --> fs_reclaim [ 27.119959][ C0] [ 27.120322][ C0] Possible interrupt unsafe locking scenario: [ 27.120322][ C0] [ 27.120575][ C0] CPU0 CPU1 [ 27.120721][ C0] ---- ---- [ 27.120917][ C0] lock(fs_reclaim); [ 27.121024][ C0] local_irq_disable(); [ 27.121245][ C0] lock(&br->lock); [ 27.121421][ C0] lock(console_owner); [ 27.121651][ C0] [ 27.121758][ C0] lock(&br->lock); [ 27.121909][ C0] [ 27.121909][ C0] *** DEADLOCK *** [ 27.121909][ C0] [ 27.122116][ C0] locks held by nc/349: 3, last CPU#0: [ 27.122303][ C0] #0: ffffffff87994c00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 27.122599][ C0] #1: ffffffff87994c00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x2c6/0x1c60 [ 27.122896][ C0] #2: ffa0000000007ca8 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 27.123233][ C0] [ 27.123233][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 27.123524][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 27.123673][ C0] HARDIRQ-ON-W at: [ 27.123780][ C0] __lock_acquire+0x388/0xc20 [ 27.124015][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.124241][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.124419][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.124682][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.124951][ C0] workqueue_init+0x2a/0x680 [ 27.125133][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.125397][ C0] kernel_init+0x21/0x150 [ 27.125629][ C0] ret_from_fork+0x474/0x6b0 [ 27.125805][ C0] ret_from_fork_asm+0x11/0x20 [ 27.126035][ C0] SOFTIRQ-ON-W at: [ 27.126139][ C0] __lock_acquire+0x388/0xc20 [ 27.126362][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.126591][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.126771][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.127035][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.127300][ C0] workqueue_init+0x2a/0x680 [ 27.127474][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.127738][ C0] kernel_init+0x21/0x150 [ 27.127966][ C0] ret_from_fork+0x474/0x6b0 [ 27.128145][ C0] ret_from_fork_asm+0x11/0x20 [ 27.128371][ C0] INITIAL USE at: [ 27.128475][ C0] __lock_acquire+0x388/0xc20 [ 27.128704][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.128934][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.129110][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.129372][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.129637][ C0] workqueue_init+0x2a/0x680 [ 27.129814][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.130043][ C0] kernel_init+0x21/0x150 [ 27.130267][ C0] ret_from_fork+0x474/0x6b0 [ 27.130440][ C0] ret_from_fork_asm+0x11/0x20 [ 27.130675][ C0] } [ 27.130749][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 27.131013][ C0] ... acquired at: [ 27.131120][ C0] __lock_acquire+0x518/0xc20 [ 27.131316][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.131455][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.131649][ C0] __kmalloc_noprof+0xd3/0x760 [ 27.131790][ C0] alloc_buf.isra.0+0x4b/0x260 [ 27.131980][ C0] put_chars+0x1e1/0x2f0 [ 27.132120][ C0] hvc_console_print+0x292/0x780 [ 27.132307][ C0] console_emit_next_record+0x22f/0x4c0 [ 27.132448][ C0] console_flush_one_record+0x46f/0x710 [ 27.132641][ C0] console_unlock+0xee/0x1f0 [ 27.132782][ C0] vprintk_emit+0x37c/0x3e0 [ 27.132970][ C0] _printk+0xc7/0x100 [ 27.133076][ C0] tun_init+0x24/0xb0 [ 27.133229][ C0] do_one_initcall+0x124/0x4f0 [ 27.133366][ C0] kernel_init_freeable+0x596/0x630 [ 27.133557][ C0] kernel_init+0x21/0x150 [ 27.133700][ C0] ret_from_fork+0x474/0x6b0 [ 27.133887][ C0] ret_from_fork_asm+0x11/0x20 [ 27.134029][ C0] [ 27.134102][ C0] -> (console_owner){....}-{0:0} { [ 27.134291][ C0] INITIAL USE at: [ 27.134396][ C0] } [ 27.134465][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 27.134724][ C0] ... acquired at: [ 27.134876][ C0] __lock_acquire+0x518/0xc20 [ 27.135012][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.135197][ C0] console_lock_spinning_enable+0x5c/0x60 [ 27.135375][ C0] console_emit_next_record+0x1d1/0x4c0 [ 27.135564][ C0] console_flush_one_record+0x46f/0x710 [ 27.135705][ C0] console_unlock+0xee/0x1f0 [ 27.135894][ C0] vprintk_emit+0x37c/0x3e0 [ 27.136035][ C0] _printk+0xc7/0x100 [ 27.136185][ C0] br_set_state+0x22f/0x430 [ 27.136325][ C0] br_init_port+0xc4/0x200 [ 27.139328][ C0] br_stp_enable_port+0x12/0x50 [ 27.139479][ C0] br_add_if+0xde2/0x1320 [ 27.139673][ C0] do_set_master+0x3b2/0x780 [ 27.139813][ C0] do_setlink.isra.0+0x9ec/0x2a60 [ 27.140000][ C0] rtnl_newlink+0x9f1/0x11b0 [ 27.140138][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.140322][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 27.140462][ C0] netlink_unicast+0x486/0x750 [ 27.140655][ C0] netlink_sendmsg+0x75b/0xc90 [ 27.140796][ C0] __sock_sendmsg+0xce/0x190 [ 27.140984][ C0] ____sys_sendmsg+0x61a/0x860 [ 27.141122][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 27.141310][ C0] __sys_sendmsg+0x12c/0x1d0 [ 27.141451][ C0] do_syscall_64+0xff/0x530 [ 27.141647][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.141820][ C0] [ 27.141890][ C0] -> (&br->lock){+.-.}-{3:3} { [ 27.142077][ C0] HARDIRQ-ON-W at: [ 27.142184][ C0] __lock_acquire+0x388/0xc20 [ 27.142408][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.142632][ C0] _raw_spin_lock_bh+0x38/0x50 [ 27.142813][ C0] br_stp_enable_bridge+0x23/0x340 [ 27.143032][ C0] br_dev_open+0x94/0x120 [ 27.143202][ C0] __dev_open+0x322/0x7a0 [ 27.143427][ C0] __dev_change_flags+0x4a4/0x6d0 [ 27.143651][ C0] netif_change_flags+0x87/0x190 [ 27.143824][ C0] do_setlink.isra.0+0x1d25/0x2a60 [ 27.144048][ C0] rtnl_newlink+0x9f1/0x11b0 [ 27.144272][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.144441][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 27.144666][ C0] netlink_unicast+0x486/0x750 [ 27.144843][ C0] netlink_sendmsg+0x75b/0xc90 [ 27.145067][ C0] __sock_sendmsg+0xce/0x190 [ 27.145289][ C0] ____sys_sendmsg+0x61a/0x860 [ 27.145459][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 27.145683][ C0] __sys_sendmsg+0x12c/0x1d0 [ 27.145904][ C0] do_syscall_64+0xff/0x530 [ 27.146081][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.146337][ C0] IN-SOFTIRQ-W at: [ 27.146438][ C0] __lock_acquire+0x388/0xc20 [ 27.146663][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.146885][ C0] _raw_spin_lock+0x33/0x40 [ 27.147059][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [ 27.147313][ C0] call_timer_fn+0x160/0x4d0 [ 27.147540][ C0] __run_timers+0x68f/0xaa0 [ 27.147724][ C0] run_timer_softirq+0xf0/0x160 [ 27.147946][ C0] handle_softirqs+0x1d3/0x900 [ 27.148121][ C0] do_softirq+0xac/0xe0 [ 27.148343][ C0] __local_bh_enable_ip+0x118/0x150 [ 27.148565][ C0] __dev_queue_xmit+0x989/0x1b90 [ 27.148748][ C0] ip_finish_output2+0x7c4/0x1c60 [ 27.148966][ C0] ip_do_fragment+0x12c2/0x1b20 [ 27.149188][ C0] ip_output+0x1ca/0x660 [ 27.149365][ C0] ip_send_skb+0xa3/0x1c0 [ 27.149588][ C0] udp_send_skb+0x87a/0x1270 [ 27.149766][ C0] udp_sendmsg+0x164e/0x2680 [ 27.149990][ C0] __sock_sendmsg+0xce/0x190 [ 27.150213][ C0] __sys_sendto+0x260/0x320 [ 27.150383][ C0] __x64_sys_sendto+0xe4/0x1f0 [ 27.150605][ C0] do_syscall_64+0xff/0x530 [ 27.150837][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.151041][ C0] INITIAL USE at: [ 27.151196][ C0] __lock_acquire+0x388/0xc20 [ 27.151366][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.151588][ C0] _raw_spin_lock_bh+0x38/0x50 [ 27.151812][ C0] br_stp_enable_bridge+0x23/0x340 [ 27.151985][ C0] br_dev_open+0x94/0x120 [ 27.152208][ C0] __dev_open+0x322/0x7a0 [ 27.152380][ C0] __dev_change_flags+0x4a4/0x6d0 [ 27.152603][ C0] netif_change_flags+0x87/0x190 [ 27.152828][ C0] do_setlink.isra.0+0x1d25/0x2a60 [ 27.152998][ C0] rtnl_newlink+0x9f1/0x11b0 [ 27.153221][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.153444][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 27.153616][ C0] netlink_unicast+0x486/0x750 [ 27.153841][ C0] netlink_sendmsg+0x75b/0xc90 [ 27.154013][ C0] __sock_sendmsg+0xce/0x190 [ 27.154183][ C0] ____sys_sendmsg+0x61a/0x860 [ 27.154405][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 27.154580][ C0] __sys_sendmsg+0x12c/0x1d0 [ 27.154805][ C0] do_syscall_64+0xff/0x530 [ 27.155028][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.155236][ C0] } [ 27.155355][ C0] ... key at: [] __key.7+0x0/0x40 [ 27.155527][ C0] ... acquired at: [ 27.155682][ C0] mark_lock+0x1d7/0xa00 [ 27.155823][ C0] mark_usage+0x42/0x170 [ 27.156006][ C0] __lock_acquire+0x388/0xc20 [ 27.156142][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.156328][ C0] _raw_spin_lock+0x33/0x40 [ 27.156464][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [ 27.156686][ C0] call_timer_fn+0x160/0x4d0 [ 27.156823][ C0] __run_timers+0x68f/0xaa0 [ 27.157012][ C0] run_timer_softirq+0xf0/0x160 [ 27.157150][ C0] handle_softirqs+0x1d3/0x900 [ 27.157336][ C0] do_softirq+0xac/0xe0 [ 27.157439][ C0] __local_bh_enable_ip+0x118/0x150 [ 27.157626][ C0] __dev_queue_xmit+0x989/0x1b90 [ 27.157765][ C0] ip_finish_output2+0x7c4/0x1c60 [ 27.157950][ C0] ip_do_fragment+0x12c2/0x1b20 [ 27.158087][ C0] ip_output+0x1ca/0x660 [ 27.158276][ C0] ip_send_skb+0xa3/0x1c0 [ 27.158414][ C0] udp_send_skb+0x87a/0x1270 [ 27.158600][ C0] udp_sendmsg+0x164e/0x2680 [ 27.158740][ C0] __sock_sendmsg+0xce/0x190 [ 27.158926][ C0] __sys_sendto+0x260/0x320 [ 27.159065][ C0] __x64_sys_sendto+0xe4/0x1f0 [ 27.159251][ C0] do_syscall_64+0xff/0x530 [ 27.159388][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.159608][ C0] [ 27.159680][ C0] [ 27.159680][ C0] stack backtrace: [ 27.159901][ C0] CPU: 0 UID: 0 PID: 349 Comm: nc Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.159904][ C0] Tainted: [W]=WARN [ 27.159905][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.159906][ C0] Call Trace: [ 27.159907][ C0] [ 27.159908][ C0] dump_stack_lvl+0x6f/0xa0 [ 27.159913][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 27.159916][ C0] mark_lock_irq+0x989/0x9c0 [ 27.159919][ C0] mark_lock+0x1d7/0xa00 [ 27.159921][ C0] mark_usage+0x42/0x170 [ 27.159922][ C0] __lock_acquire+0x388/0xc20 [ 27.159925][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.159926][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [ 27.159928][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.159930][ C0] ? lock_acquire+0x13c/0x160 [ 27.159931][ C0] ? br_message_age_timer_expired+0x70/0x70 [ 27.159933][ C0] _raw_spin_lock+0x33/0x40 [ 27.159934][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [ 27.159935][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [ 27.159937][ C0] ? br_message_age_timer_expired+0x70/0x70 [ 27.159938][ C0] call_timer_fn+0x160/0x4d0 [ 27.159940][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.159941][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 27.159943][ C0] ? find_held_lock+0x2b/0x80 [ 27.159945][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.159947][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.159948][ C0] __run_timers+0x68f/0xaa0 [ 27.159950][ C0] ? br_message_age_timer_expired+0x70/0x70 [ 27.159952][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.159953][ C0] ? __lock_acquire+0x518/0xc20 [ 27.159956][ C0] ? __rwlock_init+0x150/0x150 [ 27.159959][ C0] run_timer_softirq+0xf0/0x160 [ 27.159961][ C0] ? __run_timers+0xaa0/0xaa0 [ 27.159963][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.159964][ C0] handle_softirqs+0x1d3/0x900 [ 27.159966][ C0] ? _local_bh_enable+0xc0/0xc0 [ 27.159967][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 27.159969][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.159970][ C0] ? __dev_queue_xmit+0x974/0x1b90 [ 27.159972][ C0] do_softirq+0xac/0xe0 [ 27.159973][ C0] [ 27.159974][ C0] [ 27.159974][ C0] __local_bh_enable_ip+0x118/0x150 [ 27.159976][ C0] __dev_queue_xmit+0x989/0x1b90 [ 27.159978][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.159980][ C0] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 27.159982][ C0] ? lock_acquire.part.0+0x60/0x280 [ 27.159983][ C0] ? find_held_lock+0x2b/0x80 [ 27.159985][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.159986][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.159987][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.159989][ C0] ? __asan_memcpy+0x3c/0x60 [ 27.159992][ C0] ? neigh_hh_output+0x152/0x4c0 [ 27.159994][ C0] ip_finish_output2+0x7c4/0x1c60 [ 27.159995][ C0] ? ip_dst_mtu_maybe_forward+0x2bd/0x720 [ 27.159996][ C0] ? ip_copy_metadata+0x906/0xc70 [ 27.159998][ C0] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 27.159999][ C0] ? ip_fast_csum+0xc/0x10 [ 27.160003][ C0] ? ip_fraglist_prepare+0x3f5/0x690 [ 27.160004][ C0] ? mark_usage+0x61/0x170 [ 27.160006][ C0] ip_do_fragment+0x12c2/0x1b20 [ 27.160007][ C0] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 27.160009][ C0] ? ip4_dst_hoplimit+0x320/0x320 [ 27.160011][ C0] ? ip_dst_mtu_maybe_forward+0x2c7/0x720 [ 27.160012][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 27.160014][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.160015][ C0] ip_output+0x1ca/0x660 [ 27.160017][ C0] ? ip_finish_output+0x460/0x460 [ 27.160018][ C0] ? ip_make_skb+0x26a/0x300 [ 27.160019][ C0] ? ip_frag_init+0x4f0/0x4f0 [ 27.160021][ C0] ? ip_flush_pending_frames+0x20/0x20 [ 27.160023][ C0] ip_send_skb+0xa3/0x1c0 [ 27.160024][ C0] udp_send_skb+0x87a/0x1270 [ 27.160027][ C0] udp_sendmsg+0x164e/0x2680 [ 27.160029][ C0] ? udp_cmsg_send+0x360/0x360 [ 27.160030][ C0] ? lock_acquire.part.0+0xd4/0x280 [ 27.160031][ C0] ? __might_fault+0x97/0x140 [ 27.160033][ C0] ? find_held_lock+0x2b/0x80 [ 27.160035][ C0] ? __might_fault+0x97/0x140 [ 27.160037][ C0] ? anon_pipe_trim_and_unlock+0x450/0x450 [ 27.160041][ C0] __sock_sendmsg+0xce/0x190 [ 27.160043][ C0] ? fdget+0x4f/0x1e0 [ 27.160045][ C0] __sys_sendto+0x260/0x320 [ 27.160047][ C0] ? __ia32_sys_getpeername+0xd0/0xd0 [ 27.160051][ C0] ? ksys_read+0x1ac/0x250 [ 27.160054][ C0] __x64_sys_sendto+0xe4/0x1f0 [ 27.160055][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.160058][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 27.160060][ C0] ? do_syscall_64+0xa6/0x530 [ 27.160061][ C0] do_syscall_64+0xff/0x530 [ 27.160063][ C0] ? irq_exit_rcu+0x1a/0x30 [ 27.160064][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.160066][ C0] RIP: 0033:0x7ffa96cdb54e [ 27.160068][ C0] 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 [ 27.160069][ C0] RSP: 002b:00007ffec8f9c540 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 27.160071][ C0] RAX: ffffffffffffffda RBX: 000055677c68c9e0 RCX: 00007ffa96cdb54e [ 27.160072][ C0] RDX: 0000000000002000 RSI: 000055677c713a70 RDI: 0000000000000005 [ 27.160073][ C0] RBP: 00007ffec8f9c550 R08: 0000000000000000 R09: 0000000000000000 [ 27.160074][ C0] R10: 0000000000000000 R11: 0000000000000202 R12: 000055677c68c9e0 [ 27.160075][ C0] R13: 000055677c70fac0 R14: 000055677c68c350 R15: 000055677c68c9e0 [ 27.160077][ C0] [ 484.061167][ T70] br0: port 1(gw_l) entered disabled state [ 484.061183][ T70] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 484.061185][ T70] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 70, name: kworker/u16:1 [ 484.061187][ T70] preempt_count: 202, expected: 0 [ 484.061187][ T70] RCU nest depth: 0, expected: 0 [ 484.061188][ T70] INFO: lockdep is turned off. [ 484.061189][ T70] irq event stamp: 9570 [ 484.061190][ T70] hardirqs last enabled at (9569): [] _raw_spin_unlock_irq+0x28/0x50 [ 484.061204][ T70] hardirqs last disabled at (9570): [] __schedule+0x1246/0x20e0 [ 484.061207][ T70] softirqs last enabled at (9566): [] __fib6_clean_all+0xff/0x290 [ 484.061210][ T70] softirqs last disabled at (9560): [] __fib6_clean_all+0xd9/0x290 [ 484.061212][ T70] Preemption disabled at: [ 484.061212][ T70] [<0000000000000000>] 0x0 [ 484.061218][ T70] CPU: 0 UID: 0 PID: 70 Comm: kworker/u16:1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 484.061221][ T70] Tainted: [W]=WARN [ 484.061222][ T70] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 484.061224][ T70] Workqueue: netns cleanup_net [ 484.061229][ T70] Call Trace: [ 484.061230][ T70] [ 484.061232][ T70] dump_stack_lvl+0x6f/0xa0 [ 484.061237][ T70] __might_resched.cold+0x1fe/0x2c1 [ 484.061240][ T70] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 484.061244][ T70] ? rcu_is_watching+0x16/0xd0 [ 484.061247][ T70] __kmalloc_noprof+0x443/0x760 [ 484.061249][ T70] ? __rwlock_init+0x150/0x150 [ 484.061252][ T70] ? alloc_buf.isra.0+0x4b/0x260 [ 484.061256][ T70] ? do_raw_spin_unlock+0x59/0x250 [ 484.061258][ T70] alloc_buf.isra.0+0x4b/0x260 [ 484.061261][ T70] put_chars+0x1e1/0x2f0 [ 484.061262][ T70] ? __send_to_port+0x420/0x420 [ 484.061263][ T70] ? printk_get_next_message+0x240/0x650 [ 484.061266][ T70] ? console_prepend_replay+0x20/0x20 [ 484.061268][ T70] hvc_console_print+0x292/0x780 [ 484.061273][ T70] ? hvc_write+0x3a0/0x3a0 [ 484.061274][ T70] ? __rwlock_init+0x150/0x150 [ 484.061276][ T70] ? lock_acquire+0x13c/0x160 [ 484.061279][ T70] console_emit_next_record+0x22f/0x4c0 [ 484.061281][ T70] ? devkmsg_read+0x4b0/0x4b0 [ 484.061283][ T70] ? rcu_is_watching+0x16/0xd0 [ 484.061284][ T70] ? rcu_is_watching+0x16/0xd0 [ 484.061285][ T70] ? rcu_is_watching+0x16/0xd0 [ 484.061286][ T70] ? lock_acquire+0x13c/0x160 [ 484.061288][ T70] ? console_flush_one_record+0x111/0x710 [ 484.061289][ T70] console_flush_one_record+0x46f/0x710 [ 484.061291][ T70] ? console_emit_next_record+0x4c0/0x4c0 [ 484.061294][ T70] console_unlock+0xee/0x1f0 [ 484.061296][ T70] ? lock_acquire+0x13c/0x160 [ 484.061297][ T70] ? console_flush_one_record+0x710/0x710 [ 484.061299][ T70] ? rcu_is_watching+0x16/0xd0 [ 484.061300][ T70] ? lock_release+0x184/0x1f0 [ 484.061301][ T70] ? lock_acquire+0xe0/0x160 [ 484.061302][ T70] ? __rcu_read_lock+0x70/0x70 [ 484.061304][ T70] ? __down_trylock_console_sem+0x5e/0xa0 [ 484.061306][ T70] ? vprintk_emit+0x320/0x3e0 [ 484.061307][ T70] vprintk_emit+0x37c/0x3e0 [ 484.061309][ T70] ? wake_up_klogd_work_func+0x90/0x90 [ 484.061310][ T70] ? rcu_is_watching+0x16/0xd0 [ 484.061311][ T70] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 484.061314][ T70] ? trace_hardirqs_on+0x36/0x40 [ 484.061315][ T70] _printk+0xc7/0x100 [ 484.061318][ T70] ? snapshot_read.cold+0x21/0x21 [ 484.061320][ T70] ? ipv6_mc_down+0x17e/0x2d0 [ 484.061323][ T70] br_set_state+0x22f/0x430 [ 484.061327][ T70] br_stp_disable_port+0x11f/0x390 [ 484.061328][ T70] br_device_event+0x7b5/0x8f0 [ 484.061331][ T70] ? packet_sendmsg_spkt+0x1360/0x1360 [ 484.061333][ T70] ? addrconf_notify+0x2c8/0xf30 [ 484.061335][ T70] notifier_call_chain+0xae/0x300 [ 484.061338][ T70] netif_close_many+0x366/0x770 [ 484.061341][ T70] ? __dev_close_many+0x680/0x680 [ 484.061343][ T70] ? netif_close_many_and_unlock+0x22/0x2c0 [ 484.061345][ T70] unregister_netdevice_many_notify+0x71a/0x1b40 [ 484.061346][ T70] ? ops_undo_list+0x2ce/0x8f0 [ 484.061348][ T70] ? worker_thread+0x4f1/0xd60 [ 484.061350][ T70] ? kthread+0x367/0x460 [ 484.061352][ T70] ? ret_from_fork+0x474/0x6b0 [ 484.061355][ T70] ? ret_from_fork_asm+0x11/0x20 [ 484.061357][ T70] ? unregister_netdevice_queued+0x80/0x80 [ 484.061358][ T70] ? get_rps_cpu+0x980/0x980 [ 484.061361][ T70] default_device_exit_batch+0x38b/0x600 [ 484.061362][ T70] ? __mutex_unlock_slowpath+0x5e5/0x740 [ 484.061363][ T70] ? lock_acquire+0x13c/0x160 [ 484.061365][ T70] ? unregister_netdevice_queue+0x470/0x470 [ 484.061366][ T70] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 484.061368][ T70] ? fou_exit_net+0x30/0x110 [ 484.061370][ T70] ops_undo_list+0x2ce/0x8f0 [ 484.061372][ T70] ? netns_install+0x2a0/0x2a0 [ 484.061374][ T70] ? idr_destroy+0x121/0x250 [ 484.061377][ T70] cleanup_net+0x431/0x940 [ 484.061378][ T70] ? net_passive_dec+0x230/0x230 [ 484.061380][ T70] ? rcu_is_watching+0x16/0xd0 [ 484.061381][ T70] ? lock_acquire+0x13c/0x160 [ 484.061383][ T70] ? rcu_is_watching+0x16/0xd0 [ 484.061384][ T70] process_one_work+0xe3e/0x1560 [ 484.061387][ T70] ? __queue_delayed_work+0x470/0x470 [ 484.061389][ T70] ? find_held_lock+0x2b/0x80 [ 484.061391][ T70] ? __rwlock_init+0x150/0x150 [ 484.061393][ T70] worker_thread+0x4f1/0xd60 [ 484.061395][ T70] ? rescuer_thread+0x1340/0x1340 [ 484.061397][ T70] ? __kthread_parkme+0xbd/0x210 [ 484.061398][ T70] ? rescuer_thread+0x1340/0x1340 [ 484.061400][ T70] ? rescuer_thread+0x1340/0x1340 [ 484.061402][ T70] kthread+0x367/0x460 [ 484.061403][ T70] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 484.061404][ T70] ? kthread_affine_preferred+0x4c0/0x4c0 [ 484.061406][ T70] ret_from_fork+0x474/0x6b0 [ 484.061408][ T70] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 484.061409][ T70] ? __switch_to+0x5a3/0xe00 [ 484.061412][ T70] ? kthread_affine_preferred+0x4c0/0x4c0 [ 484.061414][ T70] ret_from_fork_asm+0x11/0x20 [ 484.061416][ T70] [ 484.081859][ T70] gw_l (unregistering): left allmulticast mode [ 484.082083][ T70] gw_l (unregistering): left promiscuous mode [ 484.082290][ T70] br0: port 1(gw_l) entered disabled state [ 484.106310][ T70] amtg: left allmulticast mode [ 484.106487][ T70] amtg: left promiscuous mode [ 484.106718][ T70] br0: port 2(amtg) entered disabled state [ 484.129094][ T70] relay_gw (unregistering): left allmulticast mode [ 484.131367][ T70] amtr (unregistering): left allmulticast mode [ 484.134601][ T312] relay_src: left allmulticast mode