[ 10.352682][ T211] ip (211) used greatest stack depth: 24096 bytes left [ 10.352699][ T211] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.352701][ T211] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 211, name: ip [ 10.352702][ T211] preempt_count: 2, expected: 0 [ 10.352704][ T211] RCU nest depth: 0, expected: 0 [ 10.352705][ T211] locks held by ip/211: 5, last CPU#1: [ 10.352706][ T211] #0: ffffffff8b8167b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 10.352718][ T211] #1: ffffffff8b97ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.352724][ T211] #2: ffffffff8b97de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.352728][ T211] #3: ffffffff8b89d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.352732][ T211] #4: ffffffff8b89d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.352736][ T211] irq event stamp: 46900 [ 10.352737][ T211] hardirqs last enabled at (46899): [] __down_trylock_console_sem+0x86/0xa0 [ 10.352740][ T211] hardirqs last disabled at (46900): [] console_emit_next_record+0x3d4/0x4c0 [ 10.352742][ T211] softirqs last enabled at (46790): [] handle_softirqs+0x67c/0x900 [ 10.352745][ T211] softirqs last disabled at (46783): [] __irq_exit_rcu+0x145/0x1c0 [ 10.352748][ T211] Preemption disabled at: [ 10.352748][ T211] [<0000000000000000>] 0x0 [ 10.352755][ T211] CPU: 1 UID: 0 PID: 211 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.352758][ T211] Tainted: [W]=WARN [ 10.352759][ T211] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.352761][ T211] Call Trace: [ 10.352762][ T211] [ 10.352764][ T211] dump_stack_lvl+0x6f/0xa0 [ 10.352771][ T211] __might_resched.cold+0x1fe/0x2c1 [ 10.352775][ T211] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.352779][ T211] ? __kmalloc_noprof+0xdb/0x760 [ 10.352784][ T211] __kmalloc_noprof+0x443/0x760 [ 10.352786][ T211] ? alloc_buf.isra.0+0x4b/0x260 [ 10.352792][ T211] ? do_raw_spin_unlock+0x59/0x250 [ 10.352795][ T211] alloc_buf.isra.0+0x4b/0x260 [ 10.352799][ T211] put_chars+0x1e1/0x2f0 [ 10.352802][ T211] ? __send_to_port+0x420/0x420 [ 10.352803][ T211] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.352807][ T211] ? validate_chain+0x38b/0xc20 [ 10.352820][ T211] hvc_console_print+0x292/0x780 [ 10.352828][ T211] ? hvc_write+0x3a0/0x3a0 [ 10.352830][ T211] ? rcu_is_watching+0x16/0xd0 [ 10.352832][ T211] ? lock_acquire+0x13c/0x160 [ 10.352837][ T211] console_emit_next_record+0x22f/0x4c0 [ 10.352841][ T211] ? devkmsg_read+0x4b0/0x4b0 [ 10.352843][ T211] ? console_flush_one_record+0x106/0x710 [ 10.352846][ T211] ? rcu_is_watching+0x16/0xd0 [ 10.352848][ T211] ? lock_acquire+0x13c/0x160 [ 10.352852][ T211] console_flush_one_record+0x46f/0x710 [ 10.352856][ T211] ? console_emit_next_record+0x4c0/0x4c0 [ 10.352858][ T211] ? __lock_acquire+0x518/0xc20 [ 10.352863][ T211] console_unlock+0xee/0x1f0 [ 10.352866][ T211] ? console_flush_one_record+0x710/0x710 [ 10.352868][ T211] ? rcu_is_watching+0x16/0xd0 [ 10.352870][ T211] ? lock_acquire+0xe0/0x160 [ 10.352874][ T211] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.352876][ T211] ? vprintk_emit+0x320/0x3e0 [ 10.352878][ T211] vprintk_emit+0x37c/0x3e0 [ 10.352882][ T211] ? wake_up_klogd_work_func+0x90/0x90 [ 10.352885][ T211] ? __lock_acquire+0x518/0xc20 [ 10.352888][ T211] _printk+0xc7/0x100 [ 10.352892][ T211] ? snapshot_read.cold+0x21/0x21 [ 10.352895][ T211] ? do_raw_spin_lock+0x131/0x280 [ 10.352898][ T211] ? __rwlock_init+0x150/0x150 [ 10.352902][ T211] ? do_raw_spin_lock+0x131/0x280 [ 10.352905][ T211] do_exit.cold+0x82/0x9c [ 10.352908][ T211] ? exit_notify+0x890/0x890 [ 10.352910][ T211] ? __lock_release.isra.0+0x69/0x1a0 [ 10.352913][ T211] ? rcu_is_watching+0x16/0xd0 [ 10.352916][ T211] do_group_exit+0xb8/0x370 [ 10.352919][ T211] __x64_sys_exit_group+0x3c/0x50 [ 10.352921][ T211] x64_sys_call+0x1567/0x1570 [ 10.352924][ T211] do_syscall_64+0xff/0x530 [ 10.352928][ T211] ? exc_page_fault+0xee/0x100 [ 10.352931][ T211] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.352933][ T211] RIP: 0033:0x7fe48015c1b8 [ 10.352936][ T211] Code: Unable to access opcode bytes at 0x7fe48015c18e. [ 10.352937][ T211] RSP: 002b:00007ffecd7f0fe8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 10.352939][ T211] RAX: ffffffffffffffda RBX: 00007fe48028cf88 RCX: 00007fe48015c1b8 [ 10.352941][ T211] RDX: 00007fe47fea6fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 10.352942][ T211] RBP: 00007ffecd7f1040 R08: 0000000000000000 R09: 0000000000008010 [ 10.352943][ T211] R10: 00007ffecd7f0e00 R11: 0000000000000246 R12: 0000000000000001 [ 10.352943][ T211] R13: 0000000000000000 R14: 00007fe48028b680 R15: 00007fe48028cfa0 [ 10.352950][ T211] [ 11.687919][ T291] br0: port 1(gw_l) entered blocking state [ 11.687992][ T291] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.687995][ T291] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 291, name: ip [ 11.687996][ T291] preempt_count: 1, expected: 0 [ 11.687997][ T291] RCU nest depth: 0, expected: 0 [ 11.687998][ T291] locks held by ip/291: 5, last CPU#0: [ 11.688000][ T291] #0: ffffffff8c11b740 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11b0 [ 11.688012][ T291] #1: ffffffff8b97ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.688018][ T291] #2: ffffffff8b97de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.688022][ T291] #3: ffffffff8b89d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.688026][ T291] #4: ffffffff8b89d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.688030][ T291] irq event stamp: 37096 [ 11.688031][ T291] hardirqs last enabled at (37095): [] __down_trylock_console_sem+0x86/0xa0 [ 11.688034][ T291] hardirqs last disabled at (37096): [] console_emit_next_record+0x3d4/0x4c0 [ 11.688036][ T291] softirqs last enabled at (37008): [] __alloc_skb+0x4c2/0x5f0 [ 11.688039][ T291] softirqs last disabled at (37006): [] __alloc_skb+0x4c2/0x5f0 [ 11.688042][ T291] Preemption disabled at: [ 11.688042][ T291] [] vprintk_emit+0x31b/0x3e0 [ 11.688047][ T291] CPU: 0 UID: 0 PID: 291 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.688050][ T291] Tainted: [W]=WARN [ 11.688051][ T291] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.688053][ T291] Call Trace: [ 11.688054][ T291] [ 11.688056][ T291] dump_stack_lvl+0x6f/0xa0 [ 11.688062][ T291] ? vprintk_emit+0x31b/0x3e0 [ 11.688063][ T291] __might_resched.cold+0x1fe/0x2c1 [ 11.688068][ T291] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.688072][ T291] ? __kmalloc_noprof+0xdb/0x760 [ 11.688076][ T291] __kmalloc_noprof+0x443/0x760 [ 11.688078][ T291] ? alloc_buf.isra.0+0x4b/0x260 [ 11.688084][ T291] ? do_raw_spin_unlock+0x59/0x250 [ 11.688087][ T291] alloc_buf.isra.0+0x4b/0x260 [ 11.688091][ T291] put_chars+0x1e1/0x2f0 [ 11.688093][ T291] ? __send_to_port+0x420/0x420 [ 11.688096][ T291] ? validate_chain+0x34a/0xc20 [ 11.688101][ T291] hvc_console_print+0x292/0x780 [ 11.688108][ T291] ? hvc_write+0x3a0/0x3a0 [ 11.688111][ T291] ? rcu_is_watching+0x16/0xd0 [ 11.688114][ T291] ? lock_acquire+0x13c/0x160 [ 11.688118][ T291] console_emit_next_record+0x22f/0x4c0 [ 11.688122][ T291] ? devkmsg_read+0x4b0/0x4b0 [ 11.688123][ T291] ? console_flush_one_record+0x106/0x710 [ 11.688127][ T291] ? rcu_is_watching+0x16/0xd0 [ 11.688128][ T291] ? lock_acquire+0x13c/0x160 [ 11.688132][ T291] console_flush_one_record+0x46f/0x710 [ 11.688136][ T291] ? console_emit_next_record+0x4c0/0x4c0 [ 11.688138][ T291] ? __lock_acquire+0x518/0xc20 [ 11.688143][ T291] console_unlock+0xee/0x1f0 [ 11.688146][ T291] ? console_flush_one_record+0x710/0x710 [ 11.688148][ T291] ? rcu_is_watching+0x16/0xd0 [ 11.688150][ T291] ? lock_acquire+0xe0/0x160 [ 11.688153][ T291] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.688155][ T291] ? vprintk_emit+0x320/0x3e0 [ 11.688158][ T291] vprintk_emit+0x37c/0x3e0 [ 11.688161][ T291] ? wake_up_klogd_work_func+0x90/0x90 [ 11.688163][ T291] ? __lock_release.isra.0+0x69/0x1a0 [ 11.688164][ T291] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 11.688168][ T291] ? mark_held_locks+0x40/0x70 [ 11.688172][ T291] _printk+0xc7/0x100 [ 11.688175][ T291] ? snapshot_read.cold+0x21/0x21 [ 11.688179][ T291] ? br_multicast_flood+0x960/0x960 [ 11.688182][ T291] ? do_setlink.isra.0+0x9ec/0x2a60 [ 11.688183][ T291] ? rtnl_newlink+0x9f1/0x11b0 [ 11.688184][ T291] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.688189][ T291] br_set_state+0x22f/0x430 [ 11.688192][ T291] br_init_port+0xc4/0x200 [ 11.688195][ T291] new_nbp+0x3a0/0x650 [ 11.688198][ T291] br_add_if+0x212/0x1320 [ 11.688201][ T291] ? is_bpf_text_address+0x72/0x110 [ 11.688204][ T291] ? kernel_text_address+0x149/0x170 [ 11.688210][ T291] do_set_master+0x3b2/0x780 [ 11.688214][ T291] do_setlink.isra.0+0x9ec/0x2a60 [ 11.688217][ T291] ? stack_trace_save+0x93/0xc0 [ 11.688221][ T291] ? rtnl_link_get_size+0x350/0x350 [ 11.688222][ T291] ? rcu_read_lock_any_held+0x66/0x90 [ 11.688224][ T291] ? stack_depot_save_flags+0x38e/0x790 [ 11.688228][ T291] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.688230][ T291] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.688231][ T291] ? validate_chain+0x38b/0xc20 [ 11.688234][ T291] ? kasan_save_stack+0x3d/0x50 [ 11.688236][ T291] ? kasan_save_stack+0x2f/0x50 [ 11.688237][ T291] ? kasan_save_track+0x14/0x30 [ 11.688238][ T291] ? __kasan_kmalloc+0x7b/0x90 [ 11.688241][ T291] ? __lock_acquire+0x518/0xc20 [ 11.688242][ T291] ? snd_ac97_proc_read_main+0x4de/0x1f00 [ 11.688245][ T291] ? ___sys_sendmsg+0xd0/0x1d0 [ 11.688249][ T291] ? lock_acquire.part.0+0xd4/0x280 [ 11.688251][ T291] ? rtnl_newlink+0x9a8/0x11b0 [ 11.688253][ T291] ? rcu_is_watching+0x16/0xd0 [ 11.688255][ T291] ? lock_acquire+0x13c/0x160 [ 11.688256][ T291] ? rcu_is_watching+0x16/0xd0 [ 11.688257][ T291] ? rcu_is_watching+0x16/0xd0 [ 11.688259][ T291] ? trace_contention_end+0xb3/0x180 [ 11.688261][ T291] ? __mutex_lock+0x1db/0x1ea0 [ 11.688263][ T291] ? __mutex_lock+0x9a3/0x1ea0 [ 11.688265][ T291] ? rtnl_newlink+0x9a8/0x11b0 [ 11.688268][ T291] ? ww_mutex_lock+0x160/0x160 [ 11.688269][ T291] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.688274][ T291] ? rcu_is_watching+0x16/0xd0 [ 11.688276][ T291] ? __rtnl_newlink+0x3fa/0xa50 [ 11.688281][ T291] rtnl_newlink+0x9f1/0x11b0 [ 11.688287][ T291] ? rtnl_bridge_getlink+0x850/0x850 [ 11.688289][ T291] ? __lock_acquire+0x518/0xc20 [ 11.688293][ T291] ? lock_acquire.part.0+0xd4/0x280 [ 11.688295][ T291] ? find_held_lock+0x2b/0x80 [ 11.688298][ T291] ? rtnl_bridge_getlink+0x850/0x850 [ 11.688299][ T291] ? __lock_release.isra.0+0x69/0x1a0 [ 11.688303][ T291] ? rtnl_bridge_getlink+0x850/0x850 [ 11.688305][ T291] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.688308][ T291] ? rtnl_link_fill+0x920/0x920 [ 11.688309][ T291] ? __lock_acquire+0x518/0xc20 [ 11.688314][ T291] ? lock_acquire.part.0+0xd4/0x280 [ 11.688316][ T291] ? find_held_lock+0x2b/0x80 [ 11.688319][ T291] netlink_rcv_skb+0x14e/0x3a0 [ 11.688323][ T291] ? rtnl_link_fill+0x920/0x920 [ 11.688325][ T291] ? netlink_ack+0xcf0/0xcf0 [ 11.688332][ T291] ? netlink_deliver_tap+0xc5/0x330 [ 11.688334][ T291] ? netlink_deliver_tap+0x13c/0x330 [ 11.688338][ T291] netlink_unicast+0x486/0x750 [ 11.688342][ T291] ? netlink_attachskb+0x810/0x810 [ 11.688345][ T291] ? find_held_lock+0x2b/0x80 [ 11.688349][ T291] netlink_sendmsg+0x75b/0xc90 [ 11.688353][ T291] ? netlink_unicast+0x750/0x750 [ 11.688357][ T291] ? __lock_release.isra.0+0x69/0x1a0 [ 11.688358][ T291] ? __import_iovec+0x36c/0x620 [ 11.688364][ T291] __sock_sendmsg+0xce/0x190 [ 11.688367][ T291] ____sys_sendmsg+0x61a/0x860 [ 11.688369][ T291] ? copy_msghdr_from_user+0x279/0x420 [ 11.688371][ T291] ? sock_write_iter+0x400/0x400 [ 11.688373][ T291] ? move_addr_to_kernel+0x40/0x40 [ 11.688380][ T291] ___sys_sendmsg+0x14e/0x1d0 [ 11.688383][ T291] ? copy_msghdr_from_user+0x420/0x420 [ 11.688399][ T291] __sys_sendmsg+0x12c/0x1d0 [ 11.688402][ T291] ? __sys_sendmsg_sock+0x20/0x20 [ 11.688408][ T291] ? rcu_is_watching+0x16/0xd0 [ 11.688411][ T291] do_syscall_64+0xff/0x530 [ 11.688414][ T291] ? exc_page_fault+0xee/0x100 [ 11.688417][ T291] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.688419][ T291] RIP: 0033:0x7ff523d9c54e [ 11.688423][ 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.688424][ T291] RSP: 002b:00007fffd0be6a00 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.688427][ T291] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007ff523d9c54e [ 11.688428][ T291] RDX: 0000000000000000 RSI: 00007fffd0be6ab0 RDI: 0000000000000005 [ 11.688429][ T291] RBP: 00007fffd0be6a10 R08: 0000000000000000 R09: 0000000000000000 [ 11.688430][ T291] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a918e12 [ 11.688431][ T291] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fffd0be7170 [ 11.688437][ T291] [ 11.721585][ T291] br0: port 1(gw_l) entered disabled state [ 11.721887][ T291] gw_l: entered allmulticast mode [ 11.722998][ T291] gw_l: entered promiscuous mode [ 11.724005][ T291] br0: port 1(gw_l) entered blocking state [ 11.724386][ T291] br0: port 1(gw_l) entered forwarding state [ 11.738532][ T291] ip (291) used greatest stack depth: 23336 bytes left [ 11.890844][ T293] br0: port 2(amtg) entered blocking state [ 11.891235][ T293] br0: port 2(amtg) entered disabled state [ 11.891595][ T293] amtg: entered allmulticast mode [ 11.892655][ T293] amtg: entered promiscuous mode [ 12.447050][ T305] br0: port 2(amtg) entered blocking state [ 12.447390][ T305] br0: port 2(amtg) entered forwarding state [ 12.781629][ T312] amtr: entered allmulticast mode [ 12.781647][ T312] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.781649][ T312] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 312, name: smcrouted [ 12.781651][ T312] preempt_count: 1, expected: 0 [ 12.781651][ T312] RCU nest depth: 0, expected: 0 [ 12.781652][ T312] locks held by smcrouted/312: 5, last CPU#0: [ 12.781654][ T312] #0: ffffffff8c11b740 (rtnl_mutex){+.+.}-{4:4}, at: ip_mroute_setsockopt+0x15c/0x1010 [ 12.781666][ T312] #1: ffffffff8b97ddc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.781671][ T312] #2: ffffffff8b97de38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.781675][ T312] #3: ffffffff8b89d760 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.781679][ T312] #4: ffffffff8b89d660 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 12.781683][ T312] irq event stamp: 5160 [ 12.781684][ T312] hardirqs last enabled at (5159): [] __down_trylock_console_sem+0x86/0xa0 [ 12.781687][ T312] hardirqs last disabled at (5160): [] console_emit_next_record+0x3d4/0x4c0 [ 12.781689][ T312] softirqs last enabled at (5076): [] __alloc_skb+0x4c2/0x5f0 [ 12.781692][ T312] softirqs last disabled at (5074): [] __alloc_skb+0x4c2/0x5f0 [ 12.781695][ T312] Preemption disabled at: [ 12.781695][ T312] [] vprintk_emit+0x31b/0x3e0 [ 12.781700][ T312] CPU: 0 UID: 0 PID: 312 Comm: smcrouted Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.781703][ T312] Tainted: [W]=WARN [ 12.781705][ T312] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.781706][ T312] Call Trace: [ 12.781708][ T312] [ 12.781709][ T312] dump_stack_lvl+0x6f/0xa0 [ 12.781715][ T312] ? vprintk_emit+0x31b/0x3e0 [ 12.781717][ T312] __might_resched.cold+0x1fe/0x2c1 [ 12.781722][ T312] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.781726][ T312] ? __kmalloc_noprof+0xdb/0x760 [ 12.781730][ T312] __kmalloc_noprof+0x443/0x760 [ 12.781732][ T312] ? alloc_buf.isra.0+0x4b/0x260 [ 12.781738][ T312] ? do_raw_spin_unlock+0x59/0x250 [ 12.781741][ T312] alloc_buf.isra.0+0x4b/0x260 [ 12.781745][ T312] put_chars+0x1e1/0x2f0 [ 12.781747][ T312] ? __send_to_port+0x420/0x420 [ 12.781750][ T312] ? rcu_read_lock_any_held+0x3c/0x90 [ 12.781753][ T312] ? validate_chain+0x38b/0xc20 [ 12.781758][ T312] hvc_console_print+0x292/0x780 [ 12.781762][ T312] ? __lock_acquire+0x518/0xc20 [ 12.781764][ T312] ? __lock_acquire+0x518/0xc20 [ 12.781768][ T312] ? hvc_write+0x3a0/0x3a0 [ 12.781772][ T312] ? rcu_is_watching+0x16/0xd0 [ 12.781774][ T312] ? lock_acquire+0x13c/0x160 [ 12.781778][ T312] console_emit_next_record+0x22f/0x4c0 [ 12.781782][ T312] ? devkmsg_read+0x4b0/0x4b0 [ 12.781784][ T312] ? console_flush_one_record+0x106/0x710 [ 12.781788][ T312] ? rcu_is_watching+0x16/0xd0 [ 12.781789][ T312] ? lock_acquire+0x13c/0x160 [ 12.781793][ T312] console_flush_one_record+0x46f/0x710 [ 12.781798][ T312] ? console_emit_next_record+0x4c0/0x4c0 [ 12.781799][ T312] ? __lock_acquire+0x518/0xc20 [ 12.781805][ T312] console_unlock+0xee/0x1f0 [ 12.781807][ T312] ? console_flush_one_record+0x710/0x710 [ 12.781809][ T312] ? rcu_is_watching+0x16/0xd0 [ 12.781811][ T312] ? lock_acquire+0xe0/0x160 [ 12.781815][ T312] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.781816][ T312] ? vprintk_emit+0x320/0x3e0 [ 12.781823][ T312] vprintk_emit+0x37c/0x3e0 [ 12.781826][ T312] ? wake_up_klogd_work_func+0x90/0x90 [ 12.781829][ T312] ? netlink_broadcast_filtered+0x29c/0x3c0 [ 12.781832][ T312] ? kasan_save_stack+0x3d/0x50 [ 12.781835][ T312] ? kasan_save_stack+0x2f/0x50 [ 12.781838][ T312] _printk+0xc7/0x100 [ 12.781842][ T312] ? snapshot_read.cold+0x21/0x21 [ 12.781843][ T312] ? __sys_setsockopt+0xe4/0x150 [ 12.781848][ T312] ? netdev_name+0x43/0x60 [ 12.781852][ T312] ? __netdev_printk+0x17f/0x1d0 [ 12.781856][ T312] netdev_info+0xe2/0x116 [ 12.781859][ T312] ? netdev_notice+0x120/0x120 [ 12.781860][ T312] ? mark_usage+0x61/0x170 [ 12.781866][ T312] ? find_held_lock+0x2b/0x80 [ 12.781868][ T312] ? mutex_is_locked+0x1c/0x50 [ 12.781871][ T312] netif_set_allmulti.cold+0x7c/0x166 [ 12.781875][ T312] dev_set_allmulti+0xa6/0x240 [ 12.781878][ T312] vif_add+0x9da/0xdc0 [ 12.781881][ T312] ? __mutex_lock+0x1db/0x1ea0 [ 12.781883][ T312] ? __mutex_lock+0x9a3/0x1ea0 [ 12.781887][ T312] ? ip_mr_output_finish.isra.0+0x630/0x630 [ 12.781890][ T312] ? lock_acquire.part.0+0xd4/0x280 [ 12.781892][ T312] ? __might_fault+0x97/0x140 [ 12.781897][ T312] ? __lock_release.isra.0+0x69/0x1a0 [ 12.781900][ T312] ? __might_fault+0x97/0x140 [ 12.781904][ T312] ip_mroute_setsockopt+0xd02/0x1010 [ 12.781908][ T312] ? ipmr_rtm_route+0x250/0x250 [ 12.781912][ T312] ? __might_fault+0x97/0x140 [ 12.781914][ T312] ? find_held_lock+0x2b/0x80 [ 12.781918][ T312] ? __might_fault+0x97/0x140 [ 12.781920][ T312] ? __might_fault+0x97/0x140 [ 12.781923][ T312] do_ip_setsockopt+0x27c/0x25f0 [ 12.781927][ T312] ? ip_sock_set_tos+0x40/0x40 [ 12.781930][ T312] ? find_held_lock+0x2b/0x80 [ 12.781932][ T312] ? __lock_release.isra.0+0x69/0x1a0 [ 12.781934][ T312] ? do_syscall_64+0xff/0x530 [ 12.781937][ T312] ? rcu_is_watching+0x16/0xd0 [ 12.781939][ T312] ? do_raw_spin_unlock+0x59/0x250 [ 12.781941][ T312] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 12.781943][ T312] ? lockdep_hardirqs_on+0x91/0x130 [ 12.781945][ T312] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 12.781947][ T312] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 12.781950][ T312] ip_setsockopt+0x2f/0x90 [ 12.781953][ T312] raw_setsockopt+0x8c/0x1f0 [ 12.781956][ T312] ? rcu_is_watching+0x16/0xd0 [ 12.781958][ T312] ? raw_bind+0x5b0/0x5b0 [ 12.781963][ T312] do_sock_setsockopt+0x164/0x3b0 [ 12.781966][ T312] ? put_user_ifreq+0x90/0x90 [ 12.781967][ T312] ? __lock_release.isra.0+0x69/0x1a0 [ 12.781970][ T312] ? fd_install+0x18c/0x3d0 [ 12.781975][ T312] ? fd_install+0x19b/0x3d0 [ 12.781979][ T312] __sys_setsockopt+0xe4/0x150 [ 12.781983][ T312] __x64_sys_setsockopt+0xbd/0x180 [ 12.781985][ T312] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 12.781989][ T312] ? lockdep_hardirqs_on+0x91/0x130 [ 12.781990][ T312] ? do_syscall_64+0xa6/0x530 [ 12.781992][ T312] do_syscall_64+0xff/0x530 [ 12.781994][ T312] ? exc_page_fault+0xee/0x100 [ 12.781996][ T312] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 12.781998][ T312] RIP: 0033:0x7efecd1002de [ 12.782002][ 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.782004][ T312] RSP: 002b:00007fff18173d28 EFLAGS: 00000246 ORIG_RAX: 0000000000000036 [ 12.782007][ T312] RAX: ffffffffffffffda RBX: 0000560ea69e62c0 RCX: 00007efecd1002de [ 12.782008][ T312] RDX: 00000000000000ca RSI: 0000000000000000 RDI: 0000000000000007 [ 12.782009][ T312] RBP: 00007fff18173de0 R08: 0000000000000010 R09: 0000000000000001 [ 12.782010][ T312] R10: 00007fff18173d60 R11: 0000000000000246 R12: 0000560ece9bb0c4 [ 12.782011][ T312] R13: 00007fff18173e30 R14: 0000560ece9bb0b0 R15: 0000000000000000 [ 12.782017][ T312] [ 12.813584][ T312] relay_gw: entered allmulticast mode [ 12.813921][ T312] relay_src: entered allmulticast mode [ 27.096522][ C0] [ 27.096540][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 27.096543][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 27.096544][ C0] preempt_count: 104, expected: 0 [ 27.096545][ C0] RCU nest depth: 0, expected: 0 [ 27.096546][ C0] INFO: lockdep is turned off. [ 27.096547][ C0] irq event stamp: 654210 [ 27.096548][ C0] hardirqs last enabled at (654210): [] _raw_spin_unlock_irq+0x28/0x50 [ 27.096556][ C0] hardirqs last disabled at (654209): [] _raw_spin_lock_irq+0x4a/0x50 [ 27.096558][ C0] softirqs last enabled at (654186): [] handle_softirqs+0x67c/0x900 [ 27.096563][ C0] softirqs last disabled at (654207): [] __irq_exit_rcu+0x145/0x1c0 [ 27.096565][ C0] Preemption disabled at: [ 27.096565][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 27.096573][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.096576][ C0] Tainted: [W]=WARN [ 27.096577][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.096579][ C0] Call Trace: [ 27.096580][ C0] [ 27.096582][ C0] dump_stack_lvl+0x6f/0xa0 [ 27.096587][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 27.096589][ C0] __might_resched.cold+0x1fe/0x2c1 [ 27.096593][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 27.096597][ C0] ? __asan_memcpy+0x3c/0x60 [ 27.096600][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.096603][ C0] __kmalloc_noprof+0x443/0x760 [ 27.096605][ C0] ? __rwlock_init+0x150/0x150 [ 27.096608][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 27.096613][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 27.096614][ C0] alloc_buf.isra.0+0x4b/0x260 [ 27.096617][ C0] put_chars+0x1e1/0x2f0 [ 27.096619][ C0] ? __send_to_port+0x420/0x420 [ 27.096620][ C0] ? console_prepend_replay+0x20/0x20 [ 27.096623][ C0] hvc_console_print+0x292/0x780 [ 27.096628][ C0] ? hvc_write+0x3a0/0x3a0 [ 27.096630][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.096631][ C0] ? lock_acquire+0x13c/0x160 [ 27.096634][ C0] console_emit_next_record+0x22f/0x4c0 [ 27.096636][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 27.096638][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.096639][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.096640][ C0] ? lock_acquire+0x13c/0x160 [ 27.096642][ C0] ? console_flush_one_record+0x111/0x710 [ 27.096644][ C0] console_flush_one_record+0x46f/0x710 [ 27.096646][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 27.096649][ C0] console_unlock+0xee/0x1f0 [ 27.096651][ C0] ? lock_acquire+0x13c/0x160 [ 27.096652][ C0] ? console_flush_one_record+0x710/0x710 [ 27.096654][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.096655][ C0] ? lock_release+0x184/0x1f0 [ 27.096657][ C0] ? lock_acquire+0xe0/0x160 [ 27.096658][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 27.096660][ C0] ? vprintk_emit+0x320/0x3e0 [ 27.096662][ C0] vprintk_emit+0x37c/0x3e0 [ 27.096664][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 27.096665][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.096666][ C0] ? lock_release+0x184/0x1f0 [ 27.096668][ C0] ? x86_64_start_kernel+0xd0/0x130 [ 27.096672][ C0] _printk+0xc7/0x100 [ 27.096675][ C0] ? snapshot_read.cold+0x21/0x21 [ 27.096677][ C0] ? arch_stack_walk+0xd7/0x130 [ 27.096680][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 27.096682][ C0] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 27.096683][ C0] print_irq_inversion_bug.part.0+0x32/0xc0 [ 27.096686][ C0] mark_lock_irq+0x989/0x9c0 [ 27.096689][ C0] mark_lock+0x1d7/0xa00 [ 27.096691][ C0] mark_usage+0x42/0x170 [ 27.096692][ C0] __lock_acquire+0x388/0xc20 [ 27.096695][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.096696][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [ 27.096700][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.096701][ C0] ? lock_acquire+0x13c/0x160 [ 27.096702][ C0] ? br_message_age_timer_expired+0x70/0x70 [ 27.096704][ C0] _raw_spin_lock+0x33/0x40 [ 27.096705][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [ 27.096706][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [ 27.096708][ C0] ? br_message_age_timer_expired+0x70/0x70 [ 27.096709][ C0] call_timer_fn+0x160/0x4d0 [ 27.096713][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 27.096714][ C0] ? debug_object_active_state+0x430/0x430 [ 27.096718][ C0] ? find_held_lock+0x2b/0x80 [ 27.096719][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.096721][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.096723][ C0] __run_timers+0x68f/0xaa0 [ 27.096725][ C0] ? br_message_age_timer_expired+0x70/0x70 [ 27.096727][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.096728][ C0] ? __lock_acquire+0x518/0xc20 [ 27.096731][ C0] ? __rwlock_init+0x150/0x150 [ 27.096733][ C0] run_timer_softirq+0xf0/0x160 [ 27.096735][ C0] ? __run_timers+0xaa0/0xaa0 [ 27.096737][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.096740][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.096741][ C0] handle_softirqs+0x1d3/0x900 [ 27.096743][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.096745][ C0] ? _local_bh_enable+0xc0/0xc0 [ 27.096747][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.096748][ C0] irq_exit_rcu+0xe/0x30 [ 27.096750][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.096752][ C0] [ 27.096753][ C0] [ 27.096753][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 27.096755][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.096758][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.096760][ C0] Code: 48 8b 3d 94 82 68 02 e8 1f 00 00 00 48 2b 05 58 d3 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 06 0e 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 27.096762][ C0] RSP: 0018:ffffffff8b607cf8 EFLAGS: 00000296 [ 27.096765][ C0] RAX: 000000000009fb7d RBX: ffffffff8b630600 RCX: ffffffff87d06247 [ 27.096766][ C0] RDX: ffffffff8b630600 RSI: ffffffff8af11011 RDI: ffffffff8a8949e0 [ 27.096767][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.096768][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff16c0fa2 [ 27.096768][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 27.096770][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.096773][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 27.096775][ C0] default_idle+0x9/0x10 [ 27.096776][ C0] default_idle_call+0x6e/0xb0 [ 27.096778][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.096780][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 27.096781][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 27.096784][ C0] ? kasan_check_range+0x14/0x200 [ 27.096785][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.096787][ C0] do_idle+0xd8/0x190 [ 27.096788][ C0] cpu_startup_entry+0x53/0x70 [ 27.096790][ C0] rest_init+0x279/0x280 [ 27.096791][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 27.096794][ C0] ? rest_init+0x280/0x280 [ 27.096795][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 27.096799][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 27.096801][ C0] ? acpi_enable+0x1e4/0x330 [ 27.096803][ C0] start_kernel+0x3b9/0x3c0 [ 27.096805][ C0] x86_64_start_reservations+0x24/0x30 [ 27.096807][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.096809][ C0] common_startup_64+0x13e/0x148 [ 27.096813][ C0] [ 27.120230][ C0] ======================================================== [ 27.120547][ C0] WARNING: possible irq lock inversion dependency detected [ 27.120817][ C0] 7.2.0-virtme #1 Tainted: G W [ 27.121005][ C0] -------------------------------------------------------- [ 27.121278][ C0] swapper/0/0 just changed the state of lock: [ 27.121506][ C0] ff1100001025ae98 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [ 27.121816][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 27.122038][ C0] (fs_reclaim){+.+.}-{0:0} [ 27.122040][ C0] [ 27.122040][ C0] [ 27.122040][ C0] and interrupts could create inverse lock ordering between them. [ 27.122040][ C0] [ 27.122673][ C0] [ 27.122673][ C0] other info that might help us debug this: [ 27.122946][ C0] Chain exists of: [ 27.122946][ C0] &br->lock --> console_owner --> fs_reclaim [ 27.122946][ C0] [ 27.123322][ C0] Possible interrupt unsafe locking scenario: [ 27.123322][ C0] [ 27.123644][ C0] CPU0 CPU1 [ 27.123790][ C0] ---- ---- [ 27.123996][ C0] lock(fs_reclaim); [ 27.124113][ C0] local_irq_disable(); [ 27.124347][ C0] lock(&br->lock); [ 27.124584][ C0] lock(console_owner); [ 27.124823][ C0] [ 27.124991][ C0] lock(&br->lock); [ 27.125103][ C0] [ 27.125103][ C0] *** DEADLOCK *** [ 27.125103][ C0] [ 27.125366][ C0] locks held by swapper/0/0: 1, last CPU#0: [ 27.125555][ C0] #0: ffa0000000007c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 27.125910][ C0] [ 27.125910][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 27.126265][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 27.126469][ C0] HARDIRQ-ON-W at: [ 27.126582][ C0] __lock_acquire+0x388/0xc20 [ 27.126826][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.127020][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.127257][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.127482][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.127707][ C0] workqueue_init+0x2a/0x680 [ 27.127898][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.128116][ C0] kernel_init+0x21/0x150 [ 27.128296][ C0] ret_from_fork+0x474/0x6b0 [ 27.128485][ C0] ret_from_fork_asm+0x11/0x20 [ 27.128672][ C0] SOFTIRQ-ON-W at: [ 27.128779][ C0] __lock_acquire+0x388/0xc20 [ 27.128968][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.129152][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.129333][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.129553][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.129772][ C0] workqueue_init+0x2a/0x680 [ 27.129957][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.130180][ C0] kernel_init+0x21/0x150 [ 27.130366][ C0] ret_from_fork+0x474/0x6b0 [ 27.130551][ C0] ret_from_fork_asm+0x11/0x20 [ 27.130733][ C0] INITIAL USE at: [ 27.130844][ C0] __lock_acquire+0x388/0xc20 [ 27.131029][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.131212][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.131395][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.131611][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.131832][ C0] workqueue_init+0x2a/0x680 [ 27.132020][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.132204][ C0] kernel_init+0x21/0x150 [ 27.132385][ C0] ret_from_fork+0x474/0x6b0 [ 27.132570][ C0] ret_from_fork_asm+0x11/0x20 [ 27.132751][ C0] } [ 27.132827][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 27.133059][ C0] ... acquired at: [ 27.133171][ C0] __lock_acquire+0x518/0xc20 [ 27.133319][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.133464][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.133611][ C0] __kmalloc_noprof+0xd3/0x760 [ 27.133755][ C0] alloc_buf.isra.0+0x4b/0x260 [ 27.133903][ C0] put_chars+0x1e1/0x2f0 [ 27.134050][ C0] hvc_console_print+0x292/0x780 [ 27.134200][ C0] console_emit_next_record+0x22f/0x4c0 [ 27.134347][ C0] console_flush_one_record+0x46f/0x710 [ 27.134491][ C0] console_unlock+0xee/0x1f0 [ 27.134639][ C0] vprintk_emit+0x37c/0x3e0 [ 27.134787][ C0] _printk+0xc7/0x100 [ 27.134903][ C0] i8042_pnp_init+0xf7/0x3c0 [ 27.135050][ C0] i8042_platform_init+0x3f9/0x460 [ 27.135199][ C0] i8042_init+0x45/0x130 [ 27.135346][ C0] do_one_initcall+0x124/0x4f0 [ 27.135491][ C0] kernel_init_freeable+0x596/0x630 [ 27.135637][ C0] kernel_init+0x21/0x150 [ 27.135782][ C0] ret_from_fork+0x474/0x6b0 [ 27.135931][ C0] ret_from_fork_asm+0x11/0x20 [ 27.136080][ C0] [ 27.136155][ C0] -> (console_owner){....}-{0:0} { [ 27.136306][ C0] INITIAL USE at: [ 27.136417][ C0] } [ 27.136491][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 27.136706][ C0] ... acquired at: [ 27.136814][ C0] __lock_acquire+0x518/0xc20 [ 27.136967][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.137112][ C0] console_lock_spinning_enable+0x5c/0x60 [ 27.137292][ C0] console_emit_next_record+0x1d1/0x4c0 [ 27.137438][ C0] console_flush_one_record+0x46f/0x710 [ 27.137582][ C0] console_unlock+0xee/0x1f0 [ 27.137728][ C0] vprintk_emit+0x37c/0x3e0 [ 27.137878][ C0] _printk+0xc7/0x100 [ 27.137990][ C0] br_set_state+0x22f/0x430 [ 27.138138][ C0] br_init_port+0xc4/0x200 [ 27.138284][ C0] br_stp_enable_port+0x12/0x50 [ 27.138429][ C0] br_add_if+0xde2/0x1320 [ 27.138580][ C0] do_set_master+0x3b2/0x780 [ 27.138726][ C0] do_setlink.isra.0+0x9ec/0x2a60 [ 27.138878][ C0] rtnl_newlink+0x9f1/0x11b0 [ 27.139022][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.139171][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 27.139316][ C0] netlink_unicast+0x486/0x750 [ 27.139462][ C0] netlink_sendmsg+0x75b/0xc90 [ 27.139610][ C0] __sock_sendmsg+0xce/0x190 [ 27.139758][ C0] ____sys_sendmsg+0x61a/0x860 [ 27.139909][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 27.140053][ C0] __sys_sendmsg+0x12c/0x1d0 [ 27.140197][ C0] do_syscall_64+0xff/0x530 [ 27.140345][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.140529][ C0] [ 27.140600][ C0] -> (&br->lock){+.-.}-{3:3} { [ 27.140746][ C0] HARDIRQ-ON-W at: [ 27.140855][ C0] __lock_acquire+0x388/0xc20 [ 27.141046][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.141228][ C0] _raw_spin_lock_bh+0x38/0x50 [ 27.141411][ C0] br_stp_enable_bridge+0x23/0x340 [ 27.141592][ C0] br_dev_open+0x94/0x120 [ 27.141777][ C0] __dev_open+0x322/0x7a0 [ 27.141966][ C0] __dev_change_flags+0x4a4/0x6d0 [ 27.142162][ C0] netif_change_flags+0x87/0x190 [ 27.142345][ C0] do_setlink.isra.0+0x1d25/0x2a60 [ 27.142525][ C0] rtnl_newlink+0x9f1/0x11b0 [ 27.142708][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.142890][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 27.143075][ C0] netlink_unicast+0x486/0x750 [ 27.143260][ C0] netlink_sendmsg+0x75b/0xc90 [ 27.143444][ C0] __sock_sendmsg+0xce/0x190 [ 27.143625][ C0] ____sys_sendmsg+0x61a/0x860 [ 27.143808][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 27.143999][ C0] __sys_sendmsg+0x12c/0x1d0 [ 27.144183][ C0] do_syscall_64+0xff/0x530 [ 27.144362][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.144582][ C0] IN-SOFTIRQ-W at: [ 27.144690][ C0] __lock_acquire+0x388/0xc20 [ 27.144870][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.145056][ C0] _raw_spin_lock+0x33/0x40 [ 27.145237][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [ 27.145454][ C0] call_timer_fn+0x160/0x4d0 [ 27.145641][ C0] __run_timers+0x68f/0xaa0 [ 27.145822][ C0] run_timer_softirq+0xf0/0x160 [ 27.146007][ C0] handle_softirqs+0x1d3/0x900 [ 27.146195][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.146375][ C0] irq_exit_rcu+0xe/0x30 [ 27.146560][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.146779][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.147002][ C0] pv_native_safe_halt+0xf/0x10 [ 27.147184][ C0] default_idle+0x9/0x10 [ 27.147368][ C0] default_idle_call+0x6e/0xb0 [ 27.147552][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.147771][ C0] do_idle+0xd8/0x190 [ 27.147921][ C0] cpu_startup_entry+0x53/0x70 [ 27.148106][ C0] rest_init+0x279/0x280 [ 27.148288][ C0] start_kernel+0x3b9/0x3c0 [ 27.148474][ C0] x86_64_start_reservations+0x24/0x30 [ 27.148660][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.148841][ C0] common_startup_64+0x13e/0x148 [ 27.149027][ C0] INITIAL USE at: [ 27.149139][ C0] __lock_acquire+0x388/0xc20 [ 27.149322][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.149503][ C0] _raw_spin_lock_bh+0x38/0x50 [ 27.149686][ C0] br_stp_enable_bridge+0x23/0x340 [ 27.149867][ C0] br_dev_open+0x94/0x120 [ 27.150053][ C0] __dev_open+0x322/0x7a0 [ 27.150235][ C0] __dev_change_flags+0x4a4/0x6d0 [ 27.150421][ C0] netif_change_flags+0x87/0x190 [ 27.150602][ C0] do_setlink.isra.0+0x1d25/0x2a60 [ 27.150784][ C0] rtnl_newlink+0x9f1/0x11b0 [ 27.150971][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.151153][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 27.151338][ C0] netlink_unicast+0x486/0x750 [ 27.151524][ C0] netlink_sendmsg+0x75b/0xc90 [ 27.153826][ C0] __sock_sendmsg+0xce/0x190 [ 27.154021][ C0] ____sys_sendmsg+0x61a/0x860 [ 27.154205][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 27.154387][ C0] __sys_sendmsg+0x12c/0x1d0 [ 27.154569][ C0] do_syscall_64+0xff/0x530 [ 27.154753][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.154976][ C0] } [ 27.155049][ C0] ... key at: [] __key.7+0x0/0x40 [ 27.155231][ C0] ... acquired at: [ 27.155338][ C0] mark_lock+0x1d7/0xa00 [ 27.155486][ C0] mark_usage+0x42/0x170 [ 27.155632][ C0] __lock_acquire+0x388/0xc20 [ 27.155777][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.155926][ C0] _raw_spin_lock+0x33/0x40 [ 27.156073][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [ 27.156252][ C0] call_timer_fn+0x160/0x4d0 [ 27.156396][ C0] __run_timers+0x68f/0xaa0 [ 27.156544][ C0] run_timer_softirq+0xf0/0x160 [ 27.156690][ C0] handle_softirqs+0x1d3/0x900 [ 27.156838][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.156987][ C0] irq_exit_rcu+0xe/0x30 [ 27.157136][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.157314][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.157494][ C0] pv_native_safe_halt+0xf/0x10 [ 27.157638][ C0] default_idle+0x9/0x10 [ 27.157783][ C0] default_idle_call+0x6e/0xb0 [ 27.157935][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.158118][ C0] do_idle+0xd8/0x190 [ 27.158228][ C0] cpu_startup_entry+0x53/0x70 [ 27.158374][ C0] rest_init+0x279/0x280 [ 27.158519][ C0] start_kernel+0x3b9/0x3c0 [ 27.158663][ C0] x86_64_start_reservations+0x24/0x30 [ 27.158806][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.158955][ C0] common_startup_64+0x13e/0x148 [ 27.159103][ C0] [ 27.159178][ C0] [ 27.159178][ C0] stack backtrace: [ 27.159359][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.159362][ C0] Tainted: [W]=WARN [ 27.159363][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.159364][ C0] Call Trace: [ 27.159365][ C0] [ 27.159366][ C0] dump_stack_lvl+0x6f/0xa0 [ 27.159371][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 27.159373][ C0] mark_lock_irq+0x989/0x9c0 [ 27.159376][ C0] mark_lock+0x1d7/0xa00 [ 27.159378][ C0] mark_usage+0x42/0x170 [ 27.159380][ C0] __lock_acquire+0x388/0xc20 [ 27.159383][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.159384][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [ 27.159386][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.159388][ C0] ? lock_acquire+0x13c/0x160 [ 27.159390][ C0] ? br_message_age_timer_expired+0x70/0x70 [ 27.159391][ C0] _raw_spin_lock+0x33/0x40 [ 27.159393][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [ 27.159394][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [ 27.159396][ C0] ? br_message_age_timer_expired+0x70/0x70 [ 27.159397][ C0] call_timer_fn+0x160/0x4d0 [ 27.159399][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 27.159401][ C0] ? debug_object_active_state+0x430/0x430 [ 27.159404][ C0] ? find_held_lock+0x2b/0x80 [ 27.159406][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.159408][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.159409][ C0] __run_timers+0x68f/0xaa0 [ 27.159411][ C0] ? br_message_age_timer_expired+0x70/0x70 [ 27.159413][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.159415][ C0] ? __lock_acquire+0x518/0xc20 [ 27.159418][ C0] ? __rwlock_init+0x150/0x150 [ 27.159420][ C0] run_timer_softirq+0xf0/0x160 [ 27.159423][ C0] ? __run_timers+0xaa0/0xaa0 [ 27.159424][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.159427][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.159428][ C0] handle_softirqs+0x1d3/0x900 [ 27.159430][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.159432][ C0] ? _local_bh_enable+0xc0/0xc0 [ 27.159434][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.159436][ C0] irq_exit_rcu+0xe/0x30 [ 27.159437][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.159439][ C0] [ 27.159440][ C0] [ 27.159440][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 27.159442][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.159444][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.159446][ C0] Code: 48 8b 3d 94 82 68 02 e8 1f 00 00 00 48 2b 05 58 d3 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 06 0e 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 27.159448][ C0] RSP: 0018:ffffffff8b607cf8 EFLAGS: 00000296 [ 27.159450][ C0] RAX: 000000000009fb7d RBX: ffffffff8b630600 RCX: ffffffff87d06247 [ 27.159452][ C0] RDX: ffffffff8b630600 RSI: ffffffff8af11011 RDI: ffffffff8a8949e0 [ 27.159452][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.159453][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff16c0fa2 [ 27.159454][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 27.159456][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.159458][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 27.159460][ C0] default_idle+0x9/0x10 [ 27.159461][ C0] default_idle_call+0x6e/0xb0 [ 27.159463][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.159464][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 27.159466][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 27.159468][ C0] ? kasan_check_range+0x14/0x200 [ 27.159471][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.159472][ C0] do_idle+0xd8/0x190 [ 27.159474][ C0] cpu_startup_entry+0x53/0x70 [ 27.159476][ C0] rest_init+0x279/0x280 [ 27.159477][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 27.159480][ C0] ? rest_init+0x280/0x280 [ 27.159482][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 27.159485][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 27.159487][ C0] ? acpi_enable+0x1e4/0x330 [ 27.159489][ C0] start_kernel+0x3b9/0x3c0 [ 27.159491][ C0] x86_64_start_reservations+0x24/0x30 [ 27.159492][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.159494][ C0] common_startup_64+0x13e/0x148 [ 27.159497][ C0] [ 488.662057][ T12] br0: port 1(gw_l) entered disabled state [ 488.662074][ T12] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 488.662076][ T12] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 12, name: kworker/u16:0 [ 488.662078][ T12] preempt_count: 202, expected: 0 [ 488.662079][ T12] RCU nest depth: 0, expected: 0 [ 488.662080][ T12] INFO: lockdep is turned off. [ 488.662081][ T12] irq event stamp: 18118 [ 488.662082][ T12] hardirqs last enabled at (18117): [] _raw_spin_unlock_irq+0x28/0x50 [ 488.662090][ T12] hardirqs last disabled at (18118): [] __schedule+0x1246/0x20e0 [ 488.662092][ T12] softirqs last enabled at (18114): [] __fib6_clean_all+0xff/0x290 [ 488.662095][ T12] softirqs last disabled at (18108): [] __fib6_clean_all+0xd9/0x290 [ 488.662097][ T12] Preemption disabled at: [ 488.662098][ T12] [<0000000000000000>] 0x0 [ 488.662104][ T12] CPU: 1 UID: 0 PID: 12 Comm: kworker/u16:0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 488.662107][ T12] Tainted: [W]=WARN [ 488.662108][ T12] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 488.662110][ T12] Workqueue: netns cleanup_net [ 488.662115][ T12] Call Trace: [ 488.662117][ T12] [ 488.662119][ T12] dump_stack_lvl+0x6f/0xa0 [ 488.662125][ T12] __might_resched.cold+0x1fe/0x2c1 [ 488.662128][ T12] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 488.662132][ T12] ? rcu_is_watching+0x16/0xd0 [ 488.662135][ T12] __kmalloc_noprof+0x443/0x760 [ 488.662138][ T12] ? __rwlock_init+0x150/0x150 [ 488.662141][ T12] ? alloc_buf.isra.0+0x4b/0x260 [ 488.662145][ T12] ? do_raw_spin_unlock+0x59/0x250 [ 488.662147][ T12] alloc_buf.isra.0+0x4b/0x260 [ 488.662149][ T12] put_chars+0x1e1/0x2f0 [ 488.662151][ T12] ? __send_to_port+0x420/0x420 [ 488.662152][ T12] ? printk_get_next_message+0x240/0x650 [ 488.662155][ T12] ? console_prepend_replay+0x20/0x20 [ 488.662157][ T12] hvc_console_print+0x292/0x780 [ 488.662162][ T12] ? hvc_write+0x3a0/0x3a0 [ 488.662163][ T12] ? __rwlock_init+0x150/0x150 [ 488.662165][ T12] ? lock_acquire+0x13c/0x160 [ 488.662168][ T12] console_emit_next_record+0x22f/0x4c0 [ 488.662170][ T12] ? devkmsg_read+0x4b0/0x4b0 [ 488.662172][ T12] ? rcu_is_watching+0x16/0xd0 [ 488.662173][ T12] ? rcu_is_watching+0x16/0xd0 [ 488.662174][ T12] ? rcu_is_watching+0x16/0xd0 [ 488.662175][ T12] ? lock_acquire+0x13c/0x160 [ 488.662177][ T12] ? console_flush_one_record+0x111/0x710 [ 488.662179][ T12] console_flush_one_record+0x46f/0x710 [ 488.662181][ T12] ? console_emit_next_record+0x4c0/0x4c0 [ 488.662184][ T12] console_unlock+0xee/0x1f0 [ 488.662185][ T12] ? lock_acquire+0x13c/0x160 [ 488.662187][ T12] ? console_flush_one_record+0x710/0x710 [ 488.662188][ T12] ? rcu_is_watching+0x16/0xd0 [ 488.662189][ T12] ? lock_release+0x184/0x1f0 [ 488.662191][ T12] ? lock_acquire+0xe0/0x160 [ 488.662192][ T12] ? __rcu_read_lock+0x70/0x70 [ 488.662194][ T12] ? __down_trylock_console_sem+0x5e/0xa0 [ 488.662196][ T12] ? vprintk_emit+0x320/0x3e0 [ 488.662197][ T12] vprintk_emit+0x37c/0x3e0 [ 488.662199][ T12] ? wake_up_klogd_work_func+0x90/0x90 [ 488.662200][ T12] ? rcu_is_watching+0x16/0xd0 [ 488.662201][ T12] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 488.662205][ T12] ? trace_hardirqs_on+0x36/0x40 [ 488.662206][ T12] _printk+0xc7/0x100 [ 488.662209][ T12] ? snapshot_read.cold+0x21/0x21 [ 488.662211][ T12] ? ipv6_mc_down+0x17e/0x2d0 [ 488.662214][ T12] br_set_state+0x22f/0x430 [ 488.662218][ T12] br_stp_disable_port+0x11f/0x390 [ 488.662220][ T12] br_device_event+0x7b5/0x8f0 [ 488.662222][ T12] ? packet_sendmsg_spkt+0x1360/0x1360 [ 488.662224][ T12] ? addrconf_notify+0x2c8/0xf30 [ 488.662227][ T12] notifier_call_chain+0xae/0x300 [ 488.662230][ T12] netif_close_many+0x366/0x770 [ 488.662233][ T12] ? __dev_close_many+0x680/0x680 [ 488.662235][ T12] ? netif_close_many_and_unlock+0x22/0x2c0 [ 488.662237][ T12] unregister_netdevice_many_notify+0x71a/0x1b40 [ 488.662238][ T12] ? ops_undo_list+0x2ce/0x8f0 [ 488.662240][ T12] ? worker_thread+0x4f1/0xd60 [ 488.662243][ T12] ? kthread+0x367/0x460 [ 488.662245][ T12] ? ret_from_fork+0x474/0x6b0 [ 488.662247][ T12] ? ret_from_fork_asm+0x11/0x20 [ 488.662250][ T12] ? unregister_netdevice_queued+0x80/0x80 [ 488.662251][ T12] ? get_rps_cpu+0x980/0x980 [ 488.662254][ T12] default_device_exit_batch+0x38b/0x600 [ 488.662255][ T12] ? __mutex_unlock_slowpath+0x5e5/0x740 [ 488.662256][ T12] ? lock_acquire+0x13c/0x160 [ 488.662258][ T12] ? unregister_netdevice_queue+0x470/0x470 [ 488.662259][ T12] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 488.662261][ T12] ? fou_exit_net+0x30/0x110 [ 488.662264][ T12] ops_undo_list+0x2ce/0x8f0 [ 488.662266][ T12] ? netns_install+0x2a0/0x2a0 [ 488.662268][ T12] ? idr_destroy+0x121/0x250 [ 488.662271][ T12] cleanup_net+0x431/0x940 [ 488.662272][ T12] ? net_passive_dec+0x230/0x230 [ 488.662274][ T12] ? update_load_avg+0x141/0xf00 [ 488.662276][ T12] ? lock_acquire+0x13c/0x160 [ 488.662278][ T12] ? rcu_is_watching+0x16/0xd0 [ 488.662279][ T12] process_one_work+0xe3e/0x1560 [ 488.662282][ T12] ? __queue_delayed_work+0x470/0x470 [ 488.662284][ T12] ? find_held_lock+0x2b/0x80 [ 488.662286][ T12] ? __rwlock_init+0x150/0x150 [ 488.662288][ T12] worker_thread+0x4f1/0xd60 [ 488.662291][ T12] ? rescuer_thread+0x1340/0x1340 [ 488.662293][ T12] kthread+0x367/0x460 [ 488.662294][ T12] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 488.662296][ T12] ? kthread_affine_preferred+0x4c0/0x4c0 [ 488.662298][ T12] ret_from_fork+0x474/0x6b0 [ 488.662299][ T12] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 488.662301][ T12] ? __switch_to+0x5a3/0xe00 [ 488.662305][ T12] ? kthread_affine_preferred+0x4c0/0x4c0 [ 488.662306][ T12] ret_from_fork_asm+0x11/0x20 [ 488.662309][ T12] [ 488.679147][ T12] gw_l (unregistering): left allmulticast mode [ 488.679351][ T12] gw_l (unregistering): left promiscuous mode [ 488.679558][ T12] br0: port 1(gw_l) entered disabled state [ 488.708729][ T12] amtg: left allmulticast mode [ 488.708870][ T12] amtg: left promiscuous mode [ 488.709045][ T12] br0: port 2(amtg) entered disabled state [ 488.724413][ T12] relay_gw (unregistering): left allmulticast mode [ 488.729130][ T12] amtr (unregistering): left allmulticast mode [ 488.731795][ T312] relay_src: left allmulticast mode