[ 11.950128][ T289] br0: port 1(gw_l) entered blocking state [ 11.950284][ T289] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.950286][ T289] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 289, name: ip [ 11.950288][ T289] preempt_count: 1, expected: 0 [ 11.950289][ T289] RCU nest depth: 0, expected: 0 [ 11.950290][ T289] locks held by ip/289: 5, last CPU#0: [ 11.950292][ T289] #0: ffffffffaa11b740 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11b0 [ 11.950306][ T289] #1: ffffffffa997ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.950312][ T289] #2: ffffffffa997de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.950316][ T289] #3: ffffffffa989d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.950320][ T289] #4: ffffffffa989d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.950324][ T289] irq event stamp: 47630 [ 11.950325][ T289] hardirqs last enabled at (47629): [] __down_trylock_console_sem+0x86/0xa0 [ 11.950328][ T289] hardirqs last disabled at (47630): [] console_emit_next_record+0x3d4/0x4c0 [ 11.950330][ T289] softirqs last enabled at (47540): [] __alloc_skb+0x4c2/0x5f0 [ 11.950334][ T289] softirqs last disabled at (47538): [] __alloc_skb+0x4c2/0x5f0 [ 11.950337][ T289] Preemption disabled at: [ 11.950337][ T289] [] vprintk_emit+0x31b/0x3e0 [ 11.950343][ T289] CPU: 0 UID: 0 PID: 289 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.950346][ T289] Tainted: [W]=WARN [ 11.950347][ T289] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.950349][ T289] Call Trace: [ 11.950350][ T289] [ 11.950352][ T289] dump_stack_lvl+0x6f/0xa0 [ 11.950358][ T289] ? vprintk_emit+0x31b/0x3e0 [ 11.950360][ T289] __might_resched.cold+0x1fe/0x2c1 [ 11.950364][ T289] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.950368][ T289] ? __kmalloc_noprof+0xdb/0x760 [ 11.950373][ T289] __kmalloc_noprof+0x443/0x760 [ 11.950375][ T289] ? alloc_buf.isra.0+0x4b/0x260 [ 11.950381][ T289] ? do_raw_spin_unlock+0x59/0x250 [ 11.950384][ T289] alloc_buf.isra.0+0x4b/0x260 [ 11.950388][ T289] put_chars+0x1e1/0x2f0 [ 11.950390][ T289] ? __send_to_port+0x420/0x420 [ 11.950393][ T289] ? validate_chain+0x34a/0xc20 [ 11.950398][ T289] hvc_console_print+0x292/0x780 [ 11.950406][ T289] ? hvc_write+0x3a0/0x3a0 [ 11.950408][ T289] ? rcu_is_watching+0x16/0xd0 [ 11.950411][ T289] ? lock_acquire+0x13c/0x160 [ 11.950415][ T289] console_emit_next_record+0x22f/0x4c0 [ 11.950419][ T289] ? devkmsg_read+0x4b0/0x4b0 [ 11.950421][ T289] ? console_flush_one_record+0x106/0x710 [ 11.950424][ T289] ? rcu_is_watching+0x16/0xd0 [ 11.950426][ T289] ? lock_acquire+0x13c/0x160 [ 11.950430][ T289] console_flush_one_record+0x46f/0x710 [ 11.950434][ T289] ? console_emit_next_record+0x4c0/0x4c0 [ 11.950436][ T289] ? __lock_acquire+0x518/0xc20 [ 11.950442][ T289] console_unlock+0xee/0x1f0 [ 11.950444][ T289] ? console_flush_one_record+0x710/0x710 [ 11.950446][ T289] ? rcu_is_watching+0x16/0xd0 [ 11.950448][ T289] ? lock_acquire+0xe0/0x160 [ 11.950452][ T289] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.950454][ T289] ? vprintk_emit+0x320/0x3e0 [ 11.950456][ T289] vprintk_emit+0x37c/0x3e0 [ 11.950459][ T289] ? wake_up_klogd_work_func+0x90/0x90 [ 11.950461][ T289] ? __lock_release.isra.0+0x69/0x1a0 [ 11.950463][ T289] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 11.950467][ T289] ? mark_held_locks+0x40/0x70 [ 11.950470][ T289] _printk+0xc7/0x100 [ 11.950474][ T289] ? snapshot_read.cold+0x21/0x21 [ 11.950477][ T289] ? br_multicast_flood+0x960/0x960 [ 11.950480][ T289] ? do_setlink.isra.0+0x9ec/0x2a60 [ 11.950482][ T289] ? rtnl_newlink+0x9f1/0x11b0 [ 11.950483][ T289] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.950487][ T289] br_set_state+0x22f/0x430 [ 11.950491][ T289] br_init_port+0xc4/0x200 [ 11.950494][ T289] new_nbp+0x3a0/0x650 [ 11.950497][ T289] br_add_if+0x212/0x1320 [ 11.950500][ T289] ? is_bpf_text_address+0x72/0x110 [ 11.950503][ T289] ? kernel_text_address+0x149/0x170 [ 11.950509][ T289] do_set_master+0x3b2/0x780 [ 11.950512][ T289] do_setlink.isra.0+0x9ec/0x2a60 [ 11.950516][ T289] ? stack_trace_save+0x93/0xc0 [ 11.950519][ T289] ? rtnl_link_get_size+0x350/0x350 [ 11.950520][ T289] ? rcu_read_lock_any_held+0x66/0x90 [ 11.950523][ T289] ? stack_depot_save_flags+0x38e/0x790 [ 11.950526][ T289] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.950529][ T289] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.950530][ T289] ? validate_chain+0x38b/0xc20 [ 11.950533][ T289] ? kasan_save_stack+0x3d/0x50 [ 11.950535][ T289] ? kasan_save_stack+0x2f/0x50 [ 11.950536][ T289] ? kasan_save_track+0x14/0x30 [ 11.950538][ T289] ? __kasan_kmalloc+0x7b/0x90 [ 11.950540][ T289] ? __lock_acquire+0x518/0xc20 [ 11.950542][ T289] ? snd_ac97_proc_read_main+0x4de/0x1f00 [ 11.950545][ T289] ? ___sys_sendmsg+0xd0/0x1d0 [ 11.950549][ T289] ? lock_acquire.part.0+0xd4/0x280 [ 11.950551][ T289] ? rtnl_newlink+0x9a8/0x11b0 [ 11.950553][ T289] ? rcu_is_watching+0x16/0xd0 [ 11.950555][ T289] ? lock_acquire+0x13c/0x160 [ 11.950557][ T289] ? rcu_is_watching+0x16/0xd0 [ 11.950558][ T289] ? rcu_is_watching+0x16/0xd0 [ 11.950560][ T289] ? trace_contention_end+0xb3/0x180 [ 11.950562][ T289] ? __mutex_lock+0x1db/0x1ea0 [ 11.950564][ T289] ? __mutex_lock+0x9a3/0x1ea0 [ 11.950565][ T289] ? rtnl_newlink+0x9a8/0x11b0 [ 11.950569][ T289] ? ww_mutex_lock+0x160/0x160 [ 11.950570][ T289] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.950575][ T289] ? rcu_is_watching+0x16/0xd0 [ 11.950577][ T289] ? __rtnl_newlink+0x3fa/0xa50 [ 11.950582][ T289] rtnl_newlink+0x9f1/0x11b0 [ 11.950588][ T289] ? rtnl_bridge_getlink+0x850/0x850 [ 11.950590][ T289] ? __lock_acquire+0x518/0xc20 [ 11.950594][ T289] ? lock_acquire.part.0+0xd4/0x280 [ 11.950596][ T289] ? find_held_lock+0x2b/0x80 [ 11.950599][ T289] ? rtnl_bridge_getlink+0x850/0x850 [ 11.950600][ T289] ? __lock_release.isra.0+0x69/0x1a0 [ 11.950604][ T289] ? rtnl_bridge_getlink+0x850/0x850 [ 11.950606][ T289] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.950609][ T289] ? rtnl_link_fill+0x920/0x920 [ 11.950611][ T289] ? __lock_acquire+0x518/0xc20 [ 11.950615][ T289] ? lock_acquire.part.0+0xd4/0x280 [ 11.950617][ T289] ? find_held_lock+0x2b/0x80 [ 11.950621][ T289] netlink_rcv_skb+0x14e/0x3a0 [ 11.950624][ T289] ? rtnl_link_fill+0x920/0x920 [ 11.950627][ T289] ? netlink_ack+0xcf0/0xcf0 [ 11.950633][ T289] ? netlink_deliver_tap+0xc5/0x330 [ 11.950635][ T289] ? netlink_deliver_tap+0x13c/0x330 [ 11.950640][ T289] netlink_unicast+0x486/0x750 [ 11.950644][ T289] ? netlink_attachskb+0x810/0x810 [ 11.950646][ T289] ? find_held_lock+0x2b/0x80 [ 11.950651][ T289] netlink_sendmsg+0x75b/0xc90 [ 11.950655][ T289] ? netlink_unicast+0x750/0x750 [ 11.950658][ T289] ? __lock_release.isra.0+0x69/0x1a0 [ 11.950660][ T289] ? __import_iovec+0x36c/0x620 [ 11.950665][ T289] __sock_sendmsg+0xce/0x190 [ 11.950668][ T289] ____sys_sendmsg+0x61a/0x860 [ 11.950670][ T289] ? copy_msghdr_from_user+0x279/0x420 [ 11.950673][ T289] ? sock_write_iter+0x400/0x400 [ 11.950674][ T289] ? move_addr_to_kernel+0x40/0x40 [ 11.950682][ T289] ___sys_sendmsg+0x14e/0x1d0 [ 11.950685][ T289] ? copy_msghdr_from_user+0x420/0x420 [ 11.950701][ T289] __sys_sendmsg+0x12c/0x1d0 [ 11.950704][ T289] ? __sys_sendmsg_sock+0x20/0x20 [ 11.950710][ T289] ? rcu_is_watching+0x16/0xd0 [ 11.950713][ T289] do_syscall_64+0xff/0x530 [ 11.950716][ T289] ? exc_page_fault+0xee/0x100 [ 11.950719][ T289] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.950721][ T289] RIP: 0033:0x7fdc6753654e [ 11.950725][ T289] 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.950727][ T289] RSP: 002b:00007fff74642ae0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.950729][ T289] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007fdc6753654e [ 11.950731][ T289] RDX: 0000000000000000 RSI: 00007fff74642b90 RDI: 0000000000000005 [ 11.950732][ T289] RBP: 00007fff74642af0 R08: 0000000000000000 R09: 0000000000000000 [ 11.950732][ T289] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a913909 [ 11.950733][ T289] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fff74643250 [ 11.950740][ T289] [ 11.985422][ T289] br0: port 1(gw_l) entered disabled state [ 11.985757][ T289] gw_l: entered allmulticast mode [ 11.986867][ T289] gw_l: entered promiscuous mode [ 11.988118][ T289] br0: port 1(gw_l) entered blocking state [ 11.988520][ T289] br0: port 1(gw_l) entered forwarding state [ 12.007949][ T289] ip (289) used greatest stack depth: 23336 bytes left [ 12.158223][ T291] br0: port 2(amtg) entered blocking state [ 12.158755][ T291] br0: port 2(amtg) entered disabled state [ 12.159129][ T291] amtg: entered allmulticast mode [ 12.161711][ T291] amtg: entered promiscuous mode [ 12.751990][ T302] br0: port 2(amtg) entered blocking state [ 12.752344][ T302] br0: port 2(amtg) entered forwarding state [ 13.099967][ T309] amtr: entered allmulticast mode [ 13.099992][ T309] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 13.099994][ T309] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 309, name: smcrouted [ 13.099996][ T309] preempt_count: 1, expected: 0 [ 13.099997][ T309] RCU nest depth: 0, expected: 0 [ 13.099998][ T309] locks held by smcrouted/309: 5, last CPU#2: [ 13.100000][ T309] #0: ffffffffaa11b740 (rtnl_mutex){+.+.}-{4:4}, at: ip_mroute_setsockopt+0x15c/0x1010 [ 13.100013][ T309] #1: ffffffffa997ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 13.100018][ T309] #2: ffffffffa997de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 13.100022][ T309] #3: ffffffffa989d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 13.100026][ T309] #4: ffffffffa989d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 13.100030][ T309] irq event stamp: 5308 [ 13.100031][ T309] hardirqs last enabled at (5307): [] __down_trylock_console_sem+0x86/0xa0 [ 13.100034][ T309] hardirqs last disabled at (5308): [] console_emit_next_record+0x3d4/0x4c0 [ 13.100037][ T309] softirqs last enabled at (5226): [] __alloc_skb+0x4c2/0x5f0 [ 13.100040][ T309] softirqs last disabled at (5224): [] __alloc_skb+0x4c2/0x5f0 [ 13.100042][ T309] Preemption disabled at: [ 13.100043][ T309] [] vprintk_emit+0x31b/0x3e0 [ 13.100048][ T309] CPU: 2 UID: 0 PID: 309 Comm: smcrouted Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 13.100052][ T309] Tainted: [W]=WARN [ 13.100053][ T309] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 13.100054][ T309] Call Trace: [ 13.100056][ T309] [ 13.100058][ T309] dump_stack_lvl+0x6f/0xa0 [ 13.100064][ T309] ? vprintk_emit+0x31b/0x3e0 [ 13.100065][ T309] __might_resched.cold+0x1fe/0x2c1 [ 13.100070][ T309] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 13.100074][ T309] ? __kmalloc_noprof+0xdb/0x760 [ 13.100079][ T309] __kmalloc_noprof+0x443/0x760 [ 13.100080][ T309] ? alloc_buf.isra.0+0x4b/0x260 [ 13.100087][ T309] ? do_raw_spin_unlock+0x59/0x250 [ 13.100090][ T309] alloc_buf.isra.0+0x4b/0x260 [ 13.100094][ T309] put_chars+0x1e1/0x2f0 [ 13.100096][ T309] ? __send_to_port+0x420/0x420 [ 13.100099][ T309] ? rcu_read_lock_any_held+0x3c/0x90 [ 13.100103][ T309] ? validate_chain+0x38b/0xc20 [ 13.100107][ T309] hvc_console_print+0x292/0x780 [ 13.100111][ T309] ? __lock_acquire+0x518/0xc20 [ 13.100113][ T309] ? __lock_acquire+0x518/0xc20 [ 13.100118][ T309] ? hvc_write+0x3a0/0x3a0 [ 13.100121][ T309] ? rcu_is_watching+0x16/0xd0 [ 13.100124][ T309] ? lock_acquire+0x13c/0x160 [ 13.100128][ T309] console_emit_next_record+0x22f/0x4c0 [ 13.100132][ T309] ? devkmsg_read+0x4b0/0x4b0 [ 13.100134][ T309] ? console_flush_one_record+0x106/0x710 [ 13.100137][ T309] ? rcu_is_watching+0x16/0xd0 [ 13.100139][ T309] ? lock_acquire+0x13c/0x160 [ 13.100143][ T309] console_flush_one_record+0x46f/0x710 [ 13.100147][ T309] ? console_emit_next_record+0x4c0/0x4c0 [ 13.100149][ T309] ? __lock_acquire+0x518/0xc20 [ 13.100155][ T309] console_unlock+0xee/0x1f0 [ 13.100158][ T309] ? console_flush_one_record+0x710/0x710 [ 13.100159][ T309] ? rcu_is_watching+0x16/0xd0 [ 13.100161][ T309] ? lock_acquire+0xe0/0x160 [ 13.100165][ T309] ? __down_trylock_console_sem+0x5e/0xa0 [ 13.100167][ T309] ? vprintk_emit+0x320/0x3e0 [ 13.100170][ T309] vprintk_emit+0x37c/0x3e0 [ 13.100173][ T309] ? wake_up_klogd_work_func+0x90/0x90 [ 13.100175][ T309] ? netlink_broadcast_filtered+0x29c/0x3c0 [ 13.100179][ T309] ? kasan_save_stack+0x3d/0x50 [ 13.100181][ T309] ? kasan_save_stack+0x2f/0x50 [ 13.100184][ T309] _printk+0xc7/0x100 [ 13.100188][ T309] ? snapshot_read.cold+0x21/0x21 [ 13.100190][ T309] ? __sys_setsockopt+0xe4/0x150 [ 13.100195][ T309] ? netdev_name+0x43/0x60 [ 13.100199][ T309] ? __netdev_printk+0x17f/0x1d0 [ 13.100202][ T309] netdev_info+0xe2/0x116 [ 13.100205][ T309] ? netdev_notice+0x120/0x120 [ 13.100207][ T309] ? mark_usage+0x61/0x170 [ 13.100213][ T309] ? find_held_lock+0x2b/0x80 [ 13.100215][ T309] ? mutex_is_locked+0x1c/0x50 [ 13.100218][ T309] netif_set_allmulti.cold+0x7c/0x166 [ 13.100222][ T309] dev_set_allmulti+0xa6/0x240 [ 13.100225][ T309] vif_add+0x9da/0xdc0 [ 13.100228][ T309] ? __mutex_lock+0x1db/0x1ea0 [ 13.100230][ T309] ? __mutex_lock+0x9a3/0x1ea0 [ 13.100234][ T309] ? ip_mr_output_finish.isra.0+0x630/0x630 [ 13.100237][ T309] ? lock_acquire.part.0+0xd4/0x280 [ 13.100240][ T309] ? __might_fault+0x97/0x140 [ 13.100244][ T309] ? __lock_release.isra.0+0x69/0x1a0 [ 13.100247][ T309] ? __might_fault+0x97/0x140 [ 13.100251][ T309] ip_mroute_setsockopt+0xd02/0x1010 [ 13.100255][ T309] ? ipmr_rtm_route+0x250/0x250 [ 13.100260][ T309] ? __might_fault+0x97/0x140 [ 13.100262][ T309] ? find_held_lock+0x2b/0x80 [ 13.100266][ T309] ? __might_fault+0x97/0x140 [ 13.100268][ T309] ? __might_fault+0x97/0x140 [ 13.100271][ T309] do_ip_setsockopt+0x27c/0x25f0 [ 13.100275][ T309] ? ip_sock_set_tos+0x40/0x40 [ 13.100278][ T309] ? find_held_lock+0x2b/0x80 [ 13.100281][ T309] ? __lock_release.isra.0+0x69/0x1a0 [ 13.100283][ T309] ? do_syscall_64+0xff/0x530 [ 13.100286][ T309] ? rcu_is_watching+0x16/0xd0 [ 13.100289][ T309] ? do_raw_spin_unlock+0x59/0x250 [ 13.100290][ T309] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 13.100292][ T309] ? lockdep_hardirqs_on+0x91/0x130 [ 13.100297][ T309] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 13.100299][ T309] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 13.100303][ T309] ip_setsockopt+0x2f/0x90 [ 13.100306][ T309] raw_setsockopt+0x8c/0x1f0 [ 13.100309][ T309] ? rcu_is_watching+0x16/0xd0 [ 13.100312][ T309] ? raw_bind+0x5b0/0x5b0 [ 13.100316][ T309] do_sock_setsockopt+0x164/0x3b0 [ 13.100319][ T309] ? put_user_ifreq+0x90/0x90 [ 13.100321][ T309] ? __lock_release.isra.0+0x69/0x1a0 [ 13.100324][ T309] ? fd_install+0x18c/0x3d0 [ 13.100329][ T309] ? fd_install+0x19b/0x3d0 [ 13.100333][ T309] __sys_setsockopt+0xe4/0x150 [ 13.100338][ T309] __x64_sys_setsockopt+0xbd/0x180 [ 13.100340][ T309] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 13.100343][ T309] ? lockdep_hardirqs_on+0x91/0x130 [ 13.100345][ T309] ? do_syscall_64+0xa6/0x530 [ 13.100347][ T309] do_syscall_64+0xff/0x530 [ 13.100349][ T309] ? exc_page_fault+0xee/0x100 [ 13.100351][ T309] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 13.100353][ T309] RIP: 0033:0x7f4eb1bcc2de [ 13.100357][ T309] 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 [ 13.100359][ T309] RSP: 002b:00007ffc959f84e8 EFLAGS: 00000246 ORIG_RAX: 0000000000000036 [ 13.100362][ T309] RAX: ffffffffffffffda RBX: 000056365197b2c0 RCX: 00007f4eb1bcc2de [ 13.100363][ T309] RDX: 00000000000000ca RSI: 0000000000000000 RDI: 0000000000000007 [ 13.100364][ T309] RBP: 00007ffc959f85a0 R08: 0000000000000010 R09: 0000000000000001 [ 13.100365][ T309] R10: 00007ffc959f8520 R11: 0000000000000246 R12: 00005636802a50c4 [ 13.100366][ T309] R13: 00007ffc959f85f0 R14: 00005636802a50b0 R15: 0000000000000000 [ 13.100373][ T309] [ 13.140068][ T309] relay_gw: entered allmulticast mode [ 13.140691][ T309] relay_src: entered allmulticast mode [ 27.090932][ C1] [ 27.090948][ C1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 27.090950][ C1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 347, name: nc [ 27.090952][ C1] preempt_count: 104, expected: 0 [ 27.090953][ C1] RCU nest depth: 2, expected: 0 [ 27.090954][ C1] INFO: lockdep is turned off. [ 27.090955][ C1] irq event stamp: 6728326 [ 27.090956][ C1] hardirqs last enabled at (6728326): [] _raw_spin_unlock_irq+0x28/0x50 [ 27.090964][ C1] hardirqs last disabled at (6728325): [] _raw_spin_lock_irq+0x4a/0x50 [ 27.090966][ C1] softirqs last enabled at (6728322): [] __dev_queue_xmit+0x974/0x1b90 [ 27.090970][ C1] softirqs last disabled at (6728323): [] do_softirq+0xac/0xe0 [ 27.090974][ C1] Preemption disabled at: [ 27.090975][ C1] [] __dev_queue_xmit+0x20c/0x1b90 [ 27.090981][ C1] CPU: 1 UID: 0 PID: 347 Comm: nc Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.090984][ C1] Tainted: [W]=WARN [ 27.090985][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.090987][ C1] Call Trace: [ 27.090989][ C1] [ 27.090991][ C1] dump_stack_lvl+0x6f/0xa0 [ 27.090996][ C1] ? __dev_queue_xmit+0x20c/0x1b90 [ 27.090998][ C1] __might_resched.cold+0x1fe/0x2c1 [ 27.091001][ C1] ? prb_next_reserve_seq+0x2d0/0x2d0 [ 27.091005][ C1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 27.091008][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.091010][ C1] __kmalloc_noprof+0x443/0x760 [ 27.091013][ C1] ? __rwlock_init+0x150/0x150 [ 27.091016][ C1] ? alloc_buf.isra.0+0x4b/0x260 [ 27.091027][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 27.091028][ C1] alloc_buf.isra.0+0x4b/0x260 [ 27.091031][ C1] put_chars+0x1e1/0x2f0 [ 27.091033][ C1] ? __send_to_port+0x420/0x420 [ 27.091035][ C1] ? console_prepend_replay+0x20/0x20 [ 27.091037][ C1] hvc_console_print+0x292/0x780 [ 27.091042][ C1] ? hvc_write+0x3a0/0x3a0 [ 27.091044][ C1] ? vsnprintf+0x668/0x1700 [ 27.091046][ C1] ? __rwlock_init+0x150/0x150 [ 27.091048][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.091049][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.091051][ C1] console_emit_next_record+0x22f/0x4c0 [ 27.091053][ C1] ? devkmsg_read+0x4b0/0x4b0 [ 27.091055][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.091056][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.091058][ C1] ? lock_acquire+0x13c/0x160 [ 27.091060][ C1] ? console_flush_one_record+0x111/0x710 [ 27.091062][ C1] console_flush_one_record+0x46f/0x710 [ 27.091064][ C1] ? console_emit_next_record+0x4c0/0x4c0 [ 27.091067][ C1] console_unlock+0xee/0x1f0 [ 27.091069][ C1] ? lock_acquire+0x13c/0x160 [ 27.091071][ C1] ? console_flush_one_record+0x710/0x710 [ 27.091072][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.091073][ C1] ? lock_release+0x184/0x1f0 [ 27.091075][ C1] ? lock_acquire+0xe0/0x160 [ 27.091077][ C1] ? __down_trylock_console_sem+0x5e/0xa0 [ 27.091079][ C1] ? vprintk_emit+0x320/0x3e0 [ 27.091080][ C1] vprintk_emit+0x37c/0x3e0 [ 27.091082][ C1] ? wake_up_klogd_work_func+0x90/0x90 [ 27.091083][ C1] ? lock_release+0x184/0x1f0 [ 27.091086][ C1] _printk+0xc7/0x100 [ 27.091089][ C1] ? snapshot_read.cold+0x21/0x21 [ 27.091091][ C1] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 27.091093][ C1] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 27.091094][ C1] print_irq_inversion_bug.part.0+0x32/0xc0 [ 27.091097][ C1] mark_lock_irq+0x989/0x9c0 [ 27.091100][ C1] mark_lock+0x1d7/0xa00 [ 27.091102][ C1] mark_usage+0x42/0x170 [ 27.091104][ C1] __lock_acquire+0x388/0xc20 [ 27.091106][ C1] lock_acquire.part.0+0xd4/0x280 [ 27.091108][ C1] ? br_forward_delay_timer_expired+0x44/0x5d0 [ 27.091111][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.091112][ C1] ? lock_acquire+0x13c/0x160 [ 27.091114][ C1] ? br_message_age_timer_expired+0x70/0x70 [ 27.091115][ C1] _raw_spin_lock+0x33/0x40 [ 27.091117][ C1] ? br_forward_delay_timer_expired+0x44/0x5d0 [ 27.091118][ C1] br_forward_delay_timer_expired+0x44/0x5d0 [ 27.091120][ C1] ? br_message_age_timer_expired+0x70/0x70 [ 27.091121][ C1] call_timer_fn+0x160/0x4d0 [ 27.091125][ C1] ? detach_if_pending+0x1d0/0x1d0 [ 27.091127][ C1] ? find_held_lock+0x2b/0x80 [ 27.091129][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 27.091131][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.091132][ C1] __run_timers+0x68f/0xaa0 [ 27.091134][ C1] ? br_message_age_timer_expired+0x70/0x70 [ 27.091136][ C1] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.091138][ C1] ? __lock_acquire+0x518/0xc20 [ 27.091141][ C1] ? __rwlock_init+0x150/0x150 [ 27.091143][ C1] run_timer_softirq+0xf0/0x160 [ 27.091145][ C1] ? __run_timers+0xaa0/0xaa0 [ 27.091147][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.091148][ C1] handle_softirqs+0x1d3/0x900 [ 27.091151][ C1] ? _local_bh_enable+0xc0/0xc0 [ 27.091152][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 27.091154][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.091155][ C1] ? __dev_queue_xmit+0x974/0x1b90 [ 27.091157][ C1] do_softirq+0xac/0xe0 [ 27.091159][ C1] [ 27.091159][ C1] [ 27.091160][ C1] __local_bh_enable_ip+0x118/0x150 [ 27.091162][ C1] __dev_queue_xmit+0x989/0x1b90 [ 27.091164][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 27.091166][ C1] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 27.091168][ C1] ? lock_acquire.part.0+0x60/0x280 [ 27.091170][ C1] ? find_held_lock+0x2b/0x80 [ 27.091171][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 27.091173][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.091174][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.091175][ C1] ? __asan_memcpy+0x3c/0x60 [ 27.091179][ C1] ? neigh_hh_output+0x152/0x4c0 [ 27.091182][ C1] ip_finish_output2+0x7c4/0x1c60 [ 27.091184][ C1] ? ip_dst_mtu_maybe_forward+0x2bd/0x720 [ 27.091185][ C1] ? ip_copy_metadata+0x906/0xc70 [ 27.091187][ C1] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 27.091188][ C1] ? ip_fast_csum+0xc/0x10 [ 27.091192][ C1] ? ip_fraglist_prepare+0x3f5/0x690 [ 27.091193][ C1] ? mark_usage+0x61/0x170 [ 27.091195][ C1] ip_do_fragment+0x12c2/0x1b20 [ 27.091197][ C1] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 27.091199][ C1] ? ip4_dst_hoplimit+0x320/0x320 [ 27.091201][ C1] ? ip_dst_mtu_maybe_forward+0x2c7/0x720 [ 27.091202][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 27.091204][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.091205][ C1] ip_output+0x1ca/0x660 [ 27.091207][ C1] ? ip_finish_output+0x460/0x460 [ 27.091208][ C1] ? ip_make_skb+0x26a/0x300 [ 27.091210][ C1] ? ip_frag_init+0x4f0/0x4f0 [ 27.091212][ C1] ? ip_flush_pending_frames+0x20/0x20 [ 27.091214][ C1] ip_send_skb+0xa3/0x1c0 [ 27.091215][ C1] udp_send_skb+0x87a/0x1270 [ 27.091220][ C1] udp_sendmsg+0x164e/0x2680 [ 27.091222][ C1] ? udp_cmsg_send+0x360/0x360 [ 27.091223][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 27.091225][ C1] ? __might_fault+0x97/0x140 [ 27.091227][ C1] ? find_held_lock+0x2b/0x80 [ 27.091229][ C1] ? __might_fault+0x97/0x140 [ 27.091232][ C1] ? anon_pipe_trim_and_unlock+0x450/0x450 [ 27.091236][ C1] __sock_sendmsg+0xce/0x190 [ 27.091239][ C1] ? fdget+0x4f/0x1e0 [ 27.091241][ C1] __sys_sendto+0x260/0x320 [ 27.091244][ C1] ? __ia32_sys_getpeername+0xd0/0xd0 [ 27.091248][ C1] ? ksys_read+0x1ac/0x250 [ 27.091250][ C1] __x64_sys_sendto+0xe4/0x1f0 [ 27.091252][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.091255][ C1] ? lockdep_hardirqs_on+0x91/0x130 [ 27.091258][ C1] ? do_syscall_64+0xa6/0x530 [ 27.091260][ C1] do_syscall_64+0xff/0x530 [ 27.091262][ C1] ? irq_exit_rcu+0x1a/0x30 [ 27.091263][ C1] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.091266][ C1] RIP: 0033:0x7fbc255ac54e [ 27.091269][ C1] 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.091271][ C1] RSP: 002b:00007ffd388b90e0 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 27.091275][ C1] RAX: ffffffffffffffda RBX: 00005587f93099e0 RCX: 00007fbc255ac54e [ 27.091276][ C1] RDX: 0000000000002000 RSI: 00005587f9390a70 RDI: 0000000000000005 [ 27.091277][ C1] RBP: 00007ffd388b90f0 R08: 0000000000000000 R09: 0000000000000000 [ 27.091278][ C1] R10: 0000000000000000 R11: 0000000000000202 R12: 00005587f93099e0 [ 27.091279][ C1] R13: 00005587f938cac0 R14: 00005587f9309350 R15: 00005587f93099e0 [ 27.091282][ C1] [ 27.115931][ C1] ======================================================== [ 27.116163][ C1] WARNING: possible irq lock inversion dependency detected [ 27.116441][ C1] 7.2.0-virtme #1 Tainted: G W [ 27.116674][ C1] -------------------------------------------------------- [ 27.116891][ C1] nc/347 just changed the state of lock: [ 27.117097][ C1] ff11000010e5ae98 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [ 27.117411][ C1] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 27.117676][ C1] (fs_reclaim){+.+.}-{0:0} [ 27.117679][ C1] [ 27.117679][ C1] [ 27.117679][ C1] and interrupts could create inverse lock ordering between them. [ 27.117679][ C1] [ 27.118274][ C1] [ 27.118274][ C1] other info that might help us debug this: [ 27.118539][ C1] Chain exists of: [ 27.118539][ C1] &br->lock --> console_owner --> fs_reclaim [ 27.118539][ C1] [ 27.118917][ C1] Possible interrupt unsafe locking scenario: [ 27.118917][ C1] [ 27.119191][ C1] CPU0 CPU1 [ 27.119388][ C1] ---- ---- [ 27.119530][ C1] lock(fs_reclaim); [ 27.119643][ C1] local_irq_disable(); [ 27.119878][ C1] lock(&br->lock); [ 27.120114][ C1] lock(console_owner); [ 27.120295][ C1] [ 27.120461][ C1] lock(&br->lock); [ 27.120574][ C1] [ 27.120574][ C1] *** DEADLOCK *** [ 27.120574][ C1] [ 27.120847][ C1] locks held by nc/347: 3, last CPU#1: [ 27.120996][ C1] #0: ffffffffa9994c00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 27.121311][ C1] #1: ffffffffa9994c00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x2c6/0x1c60 [ 27.121617][ C1] #2: ffa00000001d0ca8 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 27.121964][ C1] [ 27.121964][ C1] the shortest dependencies between 2nd lock and 1st lock: [ 27.122273][ C1] -> (fs_reclaim){+.+.}-{0:0} { [ 27.122422][ C1] HARDIRQ-ON-W at: [ 27.122586][ C1] __lock_acquire+0x388/0xc20 [ 27.122775][ C1] lock_acquire.part.0+0xd4/0x280 [ 27.123011][ C1] fs_reclaim_acquire+0xd5/0x120 [ 27.123252][ C1] __kmalloc_cache_noprof+0x6e/0x620 [ 27.123470][ C1] kthread_create_worker_on_node+0xea/0x210 [ 27.123743][ C1] workqueue_init+0x2a/0x680 [ 27.123980][ C1] kernel_init_freeable+0x2fe/0x630 [ 27.124259][ C1] kernel_init+0x21/0x150 [ 27.124453][ C1] ret_from_fork+0x474/0x6b0 [ 27.124692][ C1] ret_from_fork_asm+0x11/0x20 [ 27.124878][ C1] SOFTIRQ-ON-W at: [ 27.125043][ C1] __lock_acquire+0x388/0xc20 [ 27.125223][ C1] lock_acquire.part.0+0xd4/0x280 [ 27.125454][ C1] fs_reclaim_acquire+0xd5/0x120 [ 27.125688][ C1] __kmalloc_cache_noprof+0x6e/0x620 [ 27.125905][ C1] kthread_create_worker_on_node+0xea/0x210 [ 27.126185][ C1] workqueue_init+0x2a/0x680 [ 27.126419][ C1] kernel_init_freeable+0x2fe/0x630 [ 27.126684][ C1] kernel_init+0x21/0x150 [ 27.126871][ C1] ret_from_fork+0x474/0x6b0 [ 27.127111][ C1] ret_from_fork_asm+0x11/0x20 [ 27.127299][ C1] INITIAL USE at: [ 27.127457][ C1] __lock_acquire+0x388/0xc20 [ 27.127638][ C1] lock_acquire.part.0+0xd4/0x280 [ 27.127868][ C1] fs_reclaim_acquire+0xd5/0x120 [ 27.128106][ C1] __kmalloc_cache_noprof+0x6e/0x620 [ 27.128322][ C1] kthread_create_worker_on_node+0xea/0x210 [ 27.128591][ C1] workqueue_init+0x2a/0x680 [ 27.128824][ C1] kernel_init_freeable+0x2fe/0x630 [ 27.129004][ C1] kernel_init+0x21/0x150 [ 27.129241][ C1] ret_from_fork+0x474/0x6b0 [ 27.129475][ C1] ret_from_fork_asm+0x11/0x20 [ 27.129654][ C1] } [ 27.129781][ C1] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 27.129999][ C1] ... acquired at: [ 27.130168][ C1] __lock_acquire+0x518/0xc20 [ 27.130321][ C1] lock_acquire.part.0+0xd4/0x280 [ 27.130514][ C1] fs_reclaim_acquire+0xd5/0x120 [ 27.130657][ C1] __kmalloc_noprof+0xd3/0x760 [ 27.130853][ C1] alloc_buf.isra.0+0x4b/0x260 [ 27.131000][ C1] put_chars+0x1e1/0x2f0 [ 27.131200][ C1] hvc_console_print+0x292/0x780 [ 27.131345][ C1] console_emit_next_record+0x22f/0x4c0 [ 27.131542][ C1] console_flush_one_record+0x46f/0x710 [ 27.131686][ C1] console_unlock+0xee/0x1f0 [ 27.131881][ C1] vprintk_emit+0x37c/0x3e0 [ 27.132034][ C1] _printk+0xc7/0x100 [ 27.132196][ C1] tun_init+0x24/0xb0 [ 27.132307][ C1] do_one_initcall+0x124/0x4f0 [ 27.132451][ C1] kernel_init_freeable+0x596/0x630 [ 27.132643][ C1] kernel_init+0x21/0x150 [ 27.132794][ C1] ret_from_fork+0x474/0x6b0 [ 27.132989][ C1] ret_from_fork_asm+0x11/0x20 [ 27.133137][ C1] [ 27.133265][ C1] -> (console_owner){....}-{0:0} { [ 27.133415][ C1] INITIAL USE at: [ 27.133571][ C1] } [ 27.133642][ C1] ... key at: [] console_owner_dep_map+0x0/0x60 [ 27.133905][ C1] ... acquired at: [ 27.134010][ C1] __lock_acquire+0x518/0xc20 [ 27.134162][ C1] lock_acquire.part.0+0xd4/0x280 [ 27.134360][ C1] console_lock_spinning_enable+0x5c/0x60 [ 27.134589][ C1] console_emit_next_record+0x1d1/0x4c0 [ 27.134733][ C1] console_flush_one_record+0x46f/0x710 [ 27.134930][ C1] console_unlock+0xee/0x1f0 [ 27.135079][ C1] vprintk_emit+0x37c/0x3e0 [ 27.135273][ C1] _printk+0xc7/0x100 [ 27.135381][ C1] br_set_state+0x22f/0x430 [ 27.135530][ C1] br_init_port+0xc4/0x200 [ 27.135729][ C1] br_stp_enable_port+0x12/0x50 [ 27.135871][ C1] br_add_if+0xde2/0x1320 [ 27.136070][ C1] do_set_master+0x3b2/0x780 [ 27.136218][ C1] do_setlink.isra.0+0x9ec/0x2a60 [ 27.136411][ C1] rtnl_newlink+0x9f1/0x11b0 [ 27.136555][ C1] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.136748][ C1] netlink_rcv_skb+0x14e/0x3a0 [ 27.136901][ C1] netlink_unicast+0x486/0x750 [ 27.137102][ C1] netlink_sendmsg+0x75b/0xc90 [ 27.137246][ C1] __sock_sendmsg+0xce/0x190 [ 27.137441][ C1] ____sys_sendmsg+0x61a/0x860 [ 27.137586][ C1] ___sys_sendmsg+0x14e/0x1d0 [ 27.137778][ C1] __sys_sendmsg+0x12c/0x1d0 [ 27.137919][ C1] do_syscall_64+0xff/0x530 [ 27.138119][ C1] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.138303][ C1] [ 27.138374][ C1] -> (&br->lock){+.-.}-{3:3} { [ 27.138518][ C1] HARDIRQ-ON-W at: [ 27.138675][ C1] __lock_acquire+0x388/0xc20 [ 27.138859][ C1] lock_acquire.part.0+0xd4/0x280 [ 27.139095][ C1] _raw_spin_lock_bh+0x38/0x50 [ 27.139325][ C1] br_stp_enable_bridge+0x23/0x340 [ 27.139510][ C1] br_dev_open+0x94/0x120 [ 27.139742][ C1] __dev_open+0x322/0x7a0 [ 27.139923][ C1] __dev_change_flags+0x4a4/0x6d0 [ 27.140159][ C1] netif_change_flags+0x87/0x190 [ 27.140341][ C1] do_setlink.isra.0+0x1d25/0x2a60 [ 27.140518][ C1] rtnl_newlink+0x9f1/0x11b0 [ 27.140753][ C1] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.140985][ C1] netlink_rcv_skb+0x14e/0x3a0 [ 27.141171][ C1] netlink_unicast+0x486/0x750 [ 27.141401][ C1] netlink_sendmsg+0x75b/0xc90 [ 27.141582][ C1] __sock_sendmsg+0xce/0x190 [ 27.141810][ C1] ____sys_sendmsg+0x61a/0x860 [ 27.142048][ C1] ___sys_sendmsg+0x14e/0x1d0 [ 27.142231][ C1] __sys_sendmsg+0x12c/0x1d0 [ 27.142411][ C1] do_syscall_64+0xff/0x530 [ 27.142641][ C1] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.142857][ C1] IN-SOFTIRQ-W at: [ 27.143013][ C1] __lock_acquire+0x388/0xc20 [ 27.143203][ C1] lock_acquire.part.0+0xd4/0x280 [ 27.143432][ C1] _raw_spin_lock+0x33/0x40 [ 27.143665][ C1] br_forward_delay_timer_expired+0x44/0x5d0 [ 27.143881][ C1] call_timer_fn+0x160/0x4d0 [ 27.144120][ C1] __run_timers+0x68f/0xaa0 [ 27.144303][ C1] run_timer_softirq+0xf0/0x160 [ 27.144482][ C1] handle_softirqs+0x1d3/0x900 [ 27.144712][ C1] do_softirq+0xac/0xe0 [ 27.144892][ C1] __local_bh_enable_ip+0x118/0x150 [ 27.145128][ C1] __dev_queue_xmit+0x989/0x1b90 [ 27.145359][ C1] ip_finish_output2+0x7c4/0x1c60 [ 27.145539][ C1] ip_do_fragment+0x12c2/0x1b20 [ 27.145771][ C1] ip_output+0x1ca/0x660 [ 27.146000][ C1] ip_send_skb+0xa3/0x1c0 [ 27.146189][ C1] udp_send_skb+0x87a/0x1270 [ 27.146422][ C1] udp_sendmsg+0x164e/0x2680 [ 27.146601][ C1] __sock_sendmsg+0xce/0x190 [ 27.146844][ C1] __sys_sendto+0x260/0x320 [ 27.147085][ C1] __x64_sys_sendto+0xe4/0x1f0 [ 27.147264][ C1] do_syscall_64+0xff/0x530 [ 27.147496][ C1] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.147771][ C1] INITIAL USE at: [ 27.147879][ C1] __lock_acquire+0x388/0xc20 [ 27.148120][ C1] lock_acquire.part.0+0xd4/0x280 [ 27.148303][ C1] _raw_spin_lock_bh+0x38/0x50 [ 27.148533][ C1] br_stp_enable_bridge+0x23/0x340 [ 27.148764][ C1] br_dev_open+0x94/0x120 [ 27.148946][ C1] __dev_open+0x322/0x7a0 [ 27.149183][ C1] __dev_change_flags+0x4a4/0x6d0 [ 27.149364][ C1] netif_change_flags+0x87/0x190 [ 27.149596][ C1] do_setlink.isra.0+0x1d25/0x2a60 [ 27.149830][ C1] rtnl_newlink+0x9f1/0x11b0 [ 27.150010][ C1] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.150247][ C1] netlink_rcv_skb+0x14e/0x3a0 [ 27.150485][ C1] netlink_unicast+0x486/0x750 [ 27.150665][ C1] netlink_sendmsg+0x75b/0xc90 [ 27.150898][ C1] __sock_sendmsg+0xce/0x190 [ 27.151085][ C1] ____sys_sendmsg+0x61a/0x860 [ 27.151317][ C1] ___sys_sendmsg+0x14e/0x1d0 [ 27.151548][ C1] __sys_sendmsg+0x12c/0x1d0 [ 27.151779][ C1] do_syscall_64+0xff/0x530 [ 27.152017][ C1] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.152287][ C1] } [ 27.152361][ C1] ... key at: [] __key.7+0x0/0x40 [ 27.152594][ C1] ... acquired at: [ 27.152701][ C1] mark_lock+0x1d7/0xa00 [ 27.152847][ C1] mark_usage+0x42/0x170 [ 27.153046][ C1] __lock_acquire+0x388/0xc20 [ 27.153194][ C1] lock_acquire.part.0+0xd4/0x280 [ 27.153389][ C1] _raw_spin_lock+0x33/0x40 [ 27.153533][ C1] br_forward_delay_timer_expired+0x44/0x5d0 [ 27.153763][ C1] call_timer_fn+0x160/0x4d0 [ 27.153958][ C1] __run_timers+0x68f/0xaa0 [ 27.154107][ C1] run_timer_softirq+0xf0/0x160 [ 27.154300][ C1] handle_softirqs+0x1d3/0x900 [ 27.154447][ C1] do_softirq+0xac/0xe0 [ 27.154558][ C1] __local_bh_enable_ip+0x118/0x150 [ 27.154751][ C1] __dev_queue_xmit+0x989/0x1b90 [ 27.154894][ C1] ip_finish_output2+0x7c4/0x1c60 [ 27.155097][ C1] ip_do_fragment+0x12c2/0x1b20 [ 27.155245][ C1] ip_output+0x1ca/0x660 [ 27.155438][ C1] ip_send_skb+0xa3/0x1c0 [ 27.155586][ C1] udp_send_skb+0x87a/0x1270 [ 27.155780][ C1] udp_sendmsg+0x164e/0x2680 [ 27.155927][ C1] __sock_sendmsg+0xce/0x190 [ 27.156125][ C1] __sys_sendto+0x260/0x320 [ 27.156270][ C1] __x64_sys_sendto+0xe4/0x1f0 [ 27.156414][ C1] do_syscall_64+0xff/0x530 [ 27.156561][ C1] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.156790][ C1] [ 27.156862][ C1] [ 27.156862][ C1] stack backtrace: [ 27.157105][ C1] CPU: 1 UID: 0 PID: 347 Comm: nc Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.157108][ C1] Tainted: [W]=WARN [ 27.157109][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.157111][ C1] Call Trace: [ 27.157112][ C1] [ 27.157113][ C1] dump_stack_lvl+0x6f/0xa0 [ 27.157118][ C1] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 27.157121][ C1] mark_lock_irq+0x989/0x9c0 [ 27.157124][ C1] mark_lock+0x1d7/0xa00 [ 27.157127][ C1] mark_usage+0x42/0x170 [ 27.157128][ C1] __lock_acquire+0x388/0xc20 [ 27.157131][ C1] lock_acquire.part.0+0xd4/0x280 [ 27.157132][ C1] ? br_forward_delay_timer_expired+0x44/0x5d0 [ 27.157134][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.157136][ C1] ? lock_acquire+0x13c/0x160 [ 27.157138][ C1] ? br_message_age_timer_expired+0x70/0x70 [ 27.157139][ C1] _raw_spin_lock+0x33/0x40 [ 27.157141][ C1] ? br_forward_delay_timer_expired+0x44/0x5d0 [ 27.157142][ C1] br_forward_delay_timer_expired+0x44/0x5d0 [ 27.157144][ C1] ? br_message_age_timer_expired+0x70/0x70 [ 27.157145][ C1] call_timer_fn+0x160/0x4d0 [ 27.157147][ C1] ? detach_if_pending+0x1d0/0x1d0 [ 27.157149][ C1] ? find_held_lock+0x2b/0x80 [ 27.157151][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 27.157153][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.157155][ C1] __run_timers+0x68f/0xaa0 [ 27.157156][ C1] ? br_message_age_timer_expired+0x70/0x70 [ 27.157158][ C1] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.157160][ C1] ? __lock_acquire+0x518/0xc20 [ 27.157163][ C1] ? __rwlock_init+0x150/0x150 [ 27.157166][ C1] run_timer_softirq+0xf0/0x160 [ 27.157169][ C1] ? __run_timers+0xaa0/0xaa0 [ 27.157170][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.157172][ C1] handle_softirqs+0x1d3/0x900 [ 27.157174][ C1] ? _local_bh_enable+0xc0/0xc0 [ 27.157175][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 27.157177][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.157178][ C1] ? __dev_queue_xmit+0x974/0x1b90 [ 27.157180][ C1] do_softirq+0xac/0xe0 [ 27.157182][ C1] [ 27.157182][ C1] [ 27.157183][ C1] __local_bh_enable_ip+0x118/0x150 [ 27.157185][ C1] __dev_queue_xmit+0x989/0x1b90 [ 27.157187][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 27.157189][ C1] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 27.157191][ C1] ? lock_acquire.part.0+0x60/0x280 [ 27.157193][ C1] ? find_held_lock+0x2b/0x80 [ 27.157194][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 27.157196][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.157197][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.157198][ C1] ? __asan_memcpy+0x3c/0x60 [ 27.157202][ C1] ? neigh_hh_output+0x152/0x4c0 [ 27.157204][ C1] ip_finish_output2+0x7c4/0x1c60 [ 27.157205][ C1] ? ip_dst_mtu_maybe_forward+0x2bd/0x720 [ 27.157206][ C1] ? ip_copy_metadata+0x906/0xc70 [ 27.157208][ C1] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 27.157209][ C1] ? ip_fast_csum+0xc/0x10 [ 27.157213][ C1] ? ip_fraglist_prepare+0x3f5/0x690 [ 27.157215][ C1] ? mark_usage+0x61/0x170 [ 27.157216][ C1] ip_do_fragment+0x12c2/0x1b20 [ 27.157218][ C1] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 27.157220][ C1] ? ip4_dst_hoplimit+0x320/0x320 [ 27.157222][ C1] ? ip_dst_mtu_maybe_forward+0x2c7/0x720 [ 27.157223][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 27.157225][ C1] ? rcu_is_watching+0x16/0xd0 [ 27.157227][ C1] ip_output+0x1ca/0x660 [ 27.157229][ C1] ? ip_finish_output+0x460/0x460 [ 27.157230][ C1] ? ip_make_skb+0x26a/0x300 [ 27.157232][ C1] ? ip_frag_init+0x4f0/0x4f0 [ 27.157233][ C1] ? ip_flush_pending_frames+0x20/0x20 [ 27.157235][ C1] ip_send_skb+0xa3/0x1c0 [ 27.157237][ C1] udp_send_skb+0x87a/0x1270 [ 27.157240][ C1] udp_sendmsg+0x164e/0x2680 [ 27.157242][ C1] ? udp_cmsg_send+0x360/0x360 [ 27.157243][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 27.157244][ C1] ? __might_fault+0x97/0x140 [ 27.157247][ C1] ? find_held_lock+0x2b/0x80 [ 27.157249][ C1] ? __might_fault+0x97/0x140 [ 27.157251][ C1] ? anon_pipe_trim_and_unlock+0x450/0x450 [ 27.157256][ C1] __sock_sendmsg+0xce/0x190 [ 27.157257][ C1] ? fdget+0x4f/0x1e0 [ 27.157260][ C1] __sys_sendto+0x260/0x320 [ 27.157262][ C1] ? __ia32_sys_getpeername+0xd0/0xd0 [ 27.157266][ C1] ? ksys_read+0x1ac/0x250 [ 27.157268][ C1] __x64_sys_sendto+0xe4/0x1f0 [ 27.157270][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.157273][ C1] ? lockdep_hardirqs_on+0x91/0x130 [ 27.157275][ C1] ? do_syscall_64+0xa6/0x530 [ 27.157277][ C1] do_syscall_64+0xff/0x530 [ 27.157278][ C1] ? irq_exit_rcu+0x1a/0x30 [ 27.157280][ C1] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.157281][ C1] RIP: 0033:0x7fbc255ac54e [ 27.157284][ C1] 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.157286][ C1] RSP: 002b:00007ffd388b90e0 EFLAGS: 00000202 ORIG_RAX: 000000000000002c [ 27.157288][ C1] RAX: ffffffffffffffda RBX: 00005587f93099e0 RCX: 00007fbc255ac54e [ 27.157290][ C1] RDX: 0000000000002000 RSI: 00005587f9390a70 RDI: 0000000000000005 [ 27.157291][ C1] RBP: 00007ffd388b90f0 R08: 0000000000000000 R09: 0000000000000000 [ 27.157291][ C1] R10: 0000000000000000 R11: 0000000000000202 R12: 00005587f93099e0 [ 27.157292][ C1] R13: 00005587f938cac0 R14: 00005587f9309350 R15: 00005587f93099e0 [ 27.157294][ C1] [ 505.061153][ T12] br0: port 1(gw_l) entered disabled state [ 505.061170][ T12] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 505.061173][ T12] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 12, name: kworker/u16:0 [ 505.061174][ T12] preempt_count: 202, expected: 0 [ 505.061175][ T12] RCU nest depth: 0, expected: 0 [ 505.061176][ T12] INFO: lockdep is turned off. [ 505.061177][ T12] irq event stamp: 10668 [ 505.061178][ T12] hardirqs last enabled at (10667): [] _raw_spin_unlock_irq+0x28/0x50 [ 505.061187][ T12] hardirqs last disabled at (10668): [] __schedule+0x1246/0x20e0 [ 505.061190][ T12] softirqs last enabled at (10664): [] __fib6_clean_all+0xff/0x290 [ 505.061193][ T12] softirqs last disabled at (10658): [] __fib6_clean_all+0xd9/0x290 [ 505.061195][ T12] Preemption disabled at: [ 505.061196][ T12] [<0000000000000000>] 0x0 [ 505.061202][ T12] CPU: 1 UID: 0 PID: 12 Comm: kworker/u16:0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 505.061205][ T12] Tainted: [W]=WARN [ 505.061206][ T12] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 505.061208][ T12] Workqueue: netns cleanup_net [ 505.061217][ T12] Call Trace: [ 505.061219][ T12] [ 505.061220][ T12] dump_stack_lvl+0x6f/0xa0 [ 505.061227][ T12] __might_resched.cold+0x1fe/0x2c1 [ 505.061231][ T12] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 505.061235][ T12] ? rcu_is_watching+0x16/0xd0 [ 505.061237][ T12] __kmalloc_noprof+0x443/0x760 [ 505.061240][ T12] ? __rwlock_init+0x150/0x150 [ 505.061243][ T12] ? alloc_buf.isra.0+0x4b/0x260 [ 505.061248][ T12] ? do_raw_spin_unlock+0x59/0x250 [ 505.061249][ T12] alloc_buf.isra.0+0x4b/0x260 [ 505.061252][ T12] put_chars+0x1e1/0x2f0 [ 505.061253][ T12] ? __send_to_port+0x420/0x420 [ 505.061254][ T12] ? printk_get_next_message+0x240/0x650 [ 505.061257][ T12] ? console_prepend_replay+0x20/0x20 [ 505.061260][ T12] hvc_console_print+0x292/0x780 [ 505.061264][ T12] ? hvc_write+0x3a0/0x3a0 [ 505.061266][ T12] ? __rwlock_init+0x150/0x150 [ 505.061268][ T12] ? lock_acquire+0x13c/0x160 [ 505.061270][ T12] console_emit_next_record+0x22f/0x4c0 [ 505.061273][ T12] ? devkmsg_read+0x4b0/0x4b0 [ 505.061275][ T12] ? rcu_is_watching+0x16/0xd0 [ 505.061276][ T12] ? rcu_is_watching+0x16/0xd0 [ 505.061277][ T12] ? rcu_is_watching+0x16/0xd0 [ 505.061278][ T12] ? lock_acquire+0x13c/0x160 [ 505.061280][ T12] ? console_flush_one_record+0x111/0x710 [ 505.061281][ T12] console_flush_one_record+0x46f/0x710 [ 505.061284][ T12] ? console_emit_next_record+0x4c0/0x4c0 [ 505.061286][ T12] console_unlock+0xee/0x1f0 [ 505.061288][ T12] ? lock_acquire+0x13c/0x160 [ 505.061289][ T12] ? console_flush_one_record+0x710/0x710 [ 505.061291][ T12] ? rcu_is_watching+0x16/0xd0 [ 505.061292][ T12] ? lock_release+0x184/0x1f0 [ 505.061293][ T12] ? lock_acquire+0xe0/0x160 [ 505.061295][ T12] ? __rcu_read_lock+0x70/0x70 [ 505.061296][ T12] ? __down_trylock_console_sem+0x5e/0xa0 [ 505.061298][ T12] ? vprintk_emit+0x320/0x3e0 [ 505.061300][ T12] vprintk_emit+0x37c/0x3e0 [ 505.061302][ T12] ? wake_up_klogd_work_func+0x90/0x90 [ 505.061303][ T12] ? rcu_is_watching+0x16/0xd0 [ 505.061304][ T12] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 505.061307][ T12] ? trace_hardirqs_on+0x36/0x40 [ 505.061309][ T12] _printk+0xc7/0x100 [ 505.061312][ T12] ? snapshot_read.cold+0x21/0x21 [ 505.061314][ T12] ? ipv6_mc_down+0x17e/0x2d0 [ 505.061317][ T12] br_set_state+0x22f/0x430 [ 505.061320][ T12] br_stp_disable_port+0x11f/0x390 [ 505.061322][ T12] br_device_event+0x7b5/0x8f0 [ 505.061324][ T12] ? packet_sendmsg_spkt+0x1360/0x1360 [ 505.061326][ T12] ? addrconf_notify+0x2c8/0xf30 [ 505.061329][ T12] notifier_call_chain+0xae/0x300 [ 505.061332][ T12] netif_close_many+0x366/0x770 [ 505.061335][ T12] ? __dev_close_many+0x680/0x680 [ 505.061337][ T12] ? netif_close_many_and_unlock+0x22/0x2c0 [ 505.061339][ T12] unregister_netdevice_many_notify+0x71a/0x1b40 [ 505.061341][ T12] ? ops_undo_list+0x2ce/0x8f0 [ 505.061342][ T12] ? worker_thread+0x4f1/0xd60 [ 505.061345][ T12] ? kthread+0x367/0x460 [ 505.061347][ T12] ? ret_from_fork+0x474/0x6b0 [ 505.061349][ T12] ? ret_from_fork_asm+0x11/0x20 [ 505.061351][ T12] ? unregister_netdevice_queued+0x80/0x80 [ 505.061353][ T12] ? get_rps_cpu+0x980/0x980 [ 505.061355][ T12] default_device_exit_batch+0x38b/0x600 [ 505.061356][ T12] ? __mutex_unlock_slowpath+0x5e5/0x740 [ 505.061358][ T12] ? lock_acquire+0x13c/0x160 [ 505.061359][ T12] ? unregister_netdevice_queue+0x470/0x470 [ 505.061361][ T12] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 505.061363][ T12] ? fou_exit_net+0x30/0x110 [ 505.061365][ T12] ops_undo_list+0x2ce/0x8f0 [ 505.061367][ T12] ? netns_install+0x2a0/0x2a0 [ 505.061369][ T12] ? idr_destroy+0x121/0x250 [ 505.061372][ T12] cleanup_net+0x431/0x940 [ 505.061374][ T12] ? net_passive_dec+0x230/0x230 [ 505.061375][ T12] ? update_load_avg+0x141/0xf00 [ 505.061378][ T12] ? lock_acquire+0x13c/0x160 [ 505.061379][ T12] ? rcu_is_watching+0x16/0xd0 [ 505.061381][ T12] process_one_work+0xe3e/0x1560 [ 505.061384][ T12] ? __queue_delayed_work+0x470/0x470 [ 505.061386][ T12] ? __rwlock_init+0x150/0x150 [ 505.061388][ T12] worker_thread+0x4f1/0xd60 [ 505.061391][ T12] ? rescuer_thread+0x1340/0x1340 [ 505.061393][ T12] kthread+0x367/0x460 [ 505.061395][ T12] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 505.061396][ T12] ? kthread_affine_preferred+0x4c0/0x4c0 [ 505.061398][ T12] ret_from_fork+0x474/0x6b0 [ 505.061400][ T12] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 505.061402][ T12] ? __switch_to+0x5a3/0xe00 [ 505.061404][ T12] ? kthread_affine_preferred+0x4c0/0x4c0 [ 505.061406][ T12] ret_from_fork_asm+0x11/0x20 [ 505.061409][ T12] [ 505.077512][ T12] gw_l (unregistering): left allmulticast mode [ 505.077733][ T12] gw_l (unregistering): left promiscuous mode [ 505.077935][ T12] br0: port 1(gw_l) entered disabled state [ 505.102657][ T12] amtg: left allmulticast mode [ 505.102866][ T12] amtg: left promiscuous mode [ 505.103111][ T12] br0: port 2(amtg) entered disabled state [ 505.120659][ T12] relay_gw (unregistering): left allmulticast mode [ 505.127504][ T12] amtr (unregistering): left allmulticast mode [ 505.130805][ T309] relay_src: left allmulticast mode