[ 10.171713][ T188] ip (188) used greatest stack depth: 24224 bytes left [ 10.171729][ T188] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.171731][ T188] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 188, name: ip [ 10.171733][ T188] preempt_count: 2, expected: 0 [ 10.171734][ T188] RCU nest depth: 0, expected: 0 [ 10.171735][ T188] locks held by ip/188: 5, last CPU#3: [ 10.171737][ T188] #0: ffffffffa88027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 10.171750][ T188] #1: ffffffffa8969cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.171755][ T188] #2: ffffffffa8969d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.171759][ T188] #3: ffffffffa8889660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.171763][ T188] #4: ffffffffa8889560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.171767][ T188] irq event stamp: 40456 [ 10.171768][ T188] hardirqs last enabled at (40455): [] __down_trylock_console_sem+0x86/0xa0 [ 10.171770][ T188] hardirqs last disabled at (40456): [] console_emit_next_record+0x3d4/0x4c0 [ 10.171772][ T188] softirqs last enabled at (39174): [] netlink_release+0x17b/0xcf0 [ 10.171776][ T188] softirqs last disabled at (39172): [] netlink_release+0xd2/0xcf0 [ 10.171779][ T188] Preemption disabled at: [ 10.171779][ T188] [<0000000000000000>] 0x0 [ 10.171786][ T188] CPU: 3 UID: 0 PID: 188 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.171790][ T188] Tainted: [W]=WARN [ 10.171791][ T188] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.171792][ T188] Call Trace: [ 10.171794][ T188] [ 10.171796][ T188] dump_stack_lvl+0x6f/0xa0 [ 10.171802][ T188] __might_resched.cold+0x1fe/0x2c1 [ 10.171807][ T188] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.171811][ T188] ? __kmalloc_noprof+0xdb/0x760 [ 10.171817][ T188] __kmalloc_noprof+0x443/0x760 [ 10.171819][ T188] ? alloc_buf.isra.0+0x4b/0x260 [ 10.171825][ T188] ? do_raw_spin_unlock+0x59/0x250 [ 10.171828][ T188] alloc_buf.isra.0+0x4b/0x260 [ 10.171833][ T188] put_chars+0x1e1/0x2f0 [ 10.171836][ T188] ? __send_to_port+0x420/0x420 [ 10.171837][ T188] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.171842][ T188] ? validate_chain+0x38b/0xc20 [ 10.171847][ T188] hvc_console_print+0x292/0x780 [ 10.171854][ T188] ? hvc_write+0x3a0/0x3a0 [ 10.171856][ T188] ? rcu_is_watching+0x16/0xd0 [ 10.171858][ T188] ? lock_acquire+0x13c/0x160 [ 10.171862][ T188] console_emit_next_record+0x22f/0x4c0 [ 10.171866][ T188] ? devkmsg_read+0x4b0/0x4b0 [ 10.171868][ T188] ? console_flush_one_record+0x106/0x710 [ 10.171871][ T188] ? rcu_is_watching+0x16/0xd0 [ 10.171874][ T188] ? lock_acquire+0x13c/0x160 [ 10.171877][ T188] console_flush_one_record+0x46f/0x710 [ 10.171881][ T188] ? console_emit_next_record+0x4c0/0x4c0 [ 10.171883][ T188] ? __lock_acquire+0x518/0xc20 [ 10.171888][ T188] console_unlock+0xee/0x1f0 [ 10.171891][ T188] ? console_flush_one_record+0x710/0x710 [ 10.171893][ T188] ? rcu_is_watching+0x16/0xd0 [ 10.171896][ T188] ? lock_acquire+0x60/0x160 [ 10.171899][ T188] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.171901][ T188] ? vprintk_emit+0x320/0x3e0 [ 10.171904][ T188] vprintk_emit+0x37c/0x3e0 [ 10.171908][ T188] ? wake_up_klogd_work_func+0x90/0x90 [ 10.171912][ T188] ? __lock_acquire+0x518/0xc20 [ 10.171915][ T188] _printk+0xc7/0x100 [ 10.171919][ T188] ? snapshot_read.cold+0x21/0x21 [ 10.171922][ T188] ? do_raw_spin_lock+0x131/0x280 [ 10.171925][ T188] ? __rwlock_init+0x150/0x150 [ 10.171929][ T188] ? do_raw_spin_lock+0x131/0x280 [ 10.171932][ T188] do_exit.cold+0x82/0x9c [ 10.171936][ T188] ? exit_notify+0x890/0x890 [ 10.171938][ T188] ? __lock_release.isra.0+0x69/0x1a0 [ 10.171940][ T188] ? rcu_is_watching+0x16/0xd0 [ 10.171945][ T188] do_group_exit+0xb8/0x370 [ 10.171948][ T188] __x64_sys_exit_group+0x3c/0x50 [ 10.171950][ T188] x64_sys_call+0x1567/0x1570 [ 10.171952][ T188] do_syscall_64+0xff/0x530 [ 10.171955][ T188] ? exc_page_fault+0xee/0x100 [ 10.171958][ T188] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.171960][ T188] RIP: 0033:0x7fcd0f6f31b8 [ 10.171963][ T188] Code: Unable to access opcode bytes at 0x7fcd0f6f318e. [ 10.171964][ T188] RSP: 002b:00007fff29d3de08 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 10.171966][ T188] RAX: ffffffffffffffda RBX: 00007fcd0f823f88 RCX: 00007fcd0f6f31b8 [ 10.171967][ T188] RDX: 00007fcd0f43dfc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 10.171968][ T188] RBP: 00007fff29d3de60 R08: 0000000000000000 R09: 0000000000008010 [ 10.171969][ T188] R10: 00007fff29d3dc20 R11: 0000000000000246 R12: 0000000000000001 [ 10.171970][ T188] R13: 0000000000000000 R14: 00007fcd0f822680 R15: 00007fcd0f823fa0 [ 10.171977][ T188] [ 11.028656][ T227] 8021q: 802.1Q VLAN Support v1.8 [ 11.869266][ T250] br0: port 1(veth1) entered blocking state [ 11.869346][ T250] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.869348][ T250] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 250, name: ip [ 11.869349][ T250] preempt_count: 1, expected: 0 [ 11.869350][ T250] RCU nest depth: 0, expected: 0 [ 11.869351][ T250] locks held by ip/250: 5, last CPU#2: [ 11.869353][ T250] #0: ffffffffa90d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 11.869365][ T250] #1: ffffffffa8969cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.869371][ T250] #2: ffffffffa8969d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.869375][ T250] #3: ffffffffa8889660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.869379][ T250] #4: ffffffffa8889560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.869383][ T250] irq event stamp: 25632 [ 11.869384][ T250] hardirqs last enabled at (25631): [] __down_trylock_console_sem+0x86/0xa0 [ 11.869387][ T250] hardirqs last disabled at (25632): [] console_emit_next_record+0x3d4/0x4c0 [ 11.869388][ T250] softirqs last enabled at (25544): [] __alloc_skb+0x4c2/0x5f0 [ 11.869392][ T250] softirqs last disabled at (25542): [] __alloc_skb+0x4c2/0x5f0 [ 11.869394][ T250] Preemption disabled at: [ 11.869395][ T250] [] vprintk_emit+0x31b/0x3e0 [ 11.869401][ T250] CPU: 2 UID: 0 PID: 250 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.869404][ T250] Tainted: [W]=WARN [ 11.869405][ T250] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.869406][ T250] Call Trace: [ 11.869408][ T250] [ 11.869410][ T250] dump_stack_lvl+0x6f/0xa0 [ 11.869415][ T250] ? vprintk_emit+0x31b/0x3e0 [ 11.869418][ T250] __might_resched.cold+0x1fe/0x2c1 [ 11.869423][ T250] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.869427][ T250] ? __kmalloc_noprof+0xdb/0x760 [ 11.869432][ T250] __kmalloc_noprof+0x443/0x760 [ 11.869435][ T250] ? alloc_buf.isra.0+0x4b/0x260 [ 11.869440][ T250] ? do_raw_spin_unlock+0x59/0x250 [ 11.869443][ T250] alloc_buf.isra.0+0x4b/0x260 [ 11.869446][ T250] put_chars+0x1e1/0x2f0 [ 11.869449][ T250] ? __send_to_port+0x420/0x420 [ 11.869453][ T250] ? validate_chain+0x34a/0xc20 [ 11.869457][ T250] hvc_console_print+0x292/0x780 [ 11.869459][ T250] ? mark_usage+0x61/0x170 [ 11.869461][ T250] ? __lock_acquire+0x518/0xc20 [ 11.869463][ T250] ? __lock_acquire+0x518/0xc20 [ 11.869467][ T250] ? hvc_write+0x3a0/0x3a0 [ 11.869469][ T250] ? console_emit_next_record+0x1df/0x4c0 [ 11.869472][ T250] ? rcu_is_watching+0x16/0xd0 [ 11.869476][ T250] ? lock_acquire+0x13c/0x160 [ 11.869480][ T250] console_emit_next_record+0x22f/0x4c0 [ 11.869483][ T250] ? devkmsg_read+0x4b0/0x4b0 [ 11.869485][ T250] ? console_flush_one_record+0x106/0x710 [ 11.869488][ T250] ? rcu_is_watching+0x16/0xd0 [ 11.869491][ T250] ? lock_acquire+0x13c/0x160 [ 11.869495][ T250] console_flush_one_record+0x46f/0x710 [ 11.869499][ T250] ? console_emit_next_record+0x4c0/0x4c0 [ 11.869500][ T250] ? __lock_acquire+0x518/0xc20 [ 11.869506][ T250] console_unlock+0xee/0x1f0 [ 11.869508][ T250] ? console_flush_one_record+0x710/0x710 [ 11.869510][ T250] ? rcu_is_watching+0x16/0xd0 [ 11.869512][ T250] ? lock_acquire+0x60/0x160 [ 11.869516][ T250] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.869518][ T250] ? vprintk_emit+0x320/0x3e0 [ 11.869521][ T250] vprintk_emit+0x37c/0x3e0 [ 11.869525][ T250] ? wake_up_klogd_work_func+0x90/0x90 [ 11.869527][ T250] ? __lock_release.isra.0+0x69/0x1a0 [ 11.869529][ T250] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 11.869533][ T250] ? mark_held_locks+0x40/0x70 [ 11.869537][ T250] _printk+0xc7/0x100 [ 11.869540][ T250] ? snapshot_read.cold+0x21/0x21 [ 11.869544][ T250] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 11.869557][ T250] ? do_setlink.isra.0+0xa31/0x2750 [ 11.869559][ T250] ? rtnl_newlink+0x9f1/0x11c0 [ 11.869560][ T250] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.869564][ T250] br_set_state+0x22f/0x430 [bridge] [ 11.869576][ T250] br_init_port+0xc4/0x200 [bridge] [ 11.869585][ T250] new_nbp+0x39c/0x580 [bridge] [ 11.869595][ T250] br_add_if+0x212/0x1320 [bridge] [ 11.869603][ T250] ? is_bpf_text_address+0x72/0x110 [ 11.869607][ T250] ? kernel_text_address+0x149/0x170 [ 11.869609][ T250] ? __kernel_text_address+0x12/0x30 [ 11.869613][ T250] do_set_master+0x357/0x580 [ 11.869618][ T250] do_setlink.isra.0+0xa31/0x2750 [ 11.869621][ T250] ? stack_trace_save+0x93/0xc0 [ 11.869624][ T250] ? rtnl_link_get_size+0x350/0x350 [ 11.869625][ T250] ? rcu_read_lock_any_held+0x66/0x90 [ 11.869628][ T250] ? stack_depot_save_flags+0x38e/0x790 [ 11.869631][ T250] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.869634][ T250] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.869636][ T250] ? validate_chain+0x38b/0xc20 [ 11.869638][ T250] ? kasan_save_stack+0x3d/0x50 [ 11.869641][ T250] ? kasan_save_stack+0x2f/0x50 [ 11.869643][ T250] ? kasan_save_track+0x14/0x30 [ 11.869646][ T250] ? __lock_acquire+0x518/0xc20 [ 11.869647][ T250] ? netlink_seq_next+0x4e/0x60 [ 11.869650][ T250] ? ___sys_sendmsg+0xb0/0x1d0 [ 11.869655][ T250] ? lock_acquire.part.0+0xd4/0x280 [ 11.869657][ T250] ? rtnl_newlink+0x9a8/0x11c0 [ 11.869659][ T250] ? rcu_is_watching+0x16/0xd0 [ 11.869662][ T250] ? lock_acquire+0x13c/0x160 [ 11.869663][ T250] ? rcu_is_watching+0x16/0xd0 [ 11.869671][ T250] ? rcu_is_watching+0x16/0xd0 [ 11.869673][ T250] ? trace_contention_end+0xb3/0x180 [ 11.869677][ T250] ? __mutex_lock+0x1db/0x1ea0 [ 11.869678][ T250] ? __mutex_lock+0x9a3/0x1ea0 [ 11.869680][ T250] ? rtnl_newlink+0x9a8/0x11c0 [ 11.869684][ T250] ? ww_mutex_lock+0x160/0x160 [ 11.869685][ T250] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.869690][ T250] ? __rtnl_newlink+0x3fa/0xa50 [ 11.869695][ T250] rtnl_newlink+0x9f1/0x11c0 [ 11.869701][ T250] ? rtnl_bridge_getlink+0x850/0x850 [ 11.869703][ T250] ? __lock_acquire+0x518/0xc20 [ 11.869708][ T250] ? lock_acquire.part.0+0xd4/0x280 [ 11.869711][ T250] ? find_held_lock+0x2b/0x80 [ 11.869713][ T250] ? rtnl_bridge_getlink+0x850/0x850 [ 11.869715][ T250] ? __lock_release.isra.0+0x69/0x1a0 [ 11.869719][ T250] ? rtnl_bridge_getlink+0x850/0x850 [ 11.869721][ T250] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.869724][ T250] ? rtnl_link_fill+0x920/0x920 [ 11.869725][ T250] ? __lock_acquire+0x518/0xc20 [ 11.869729][ T250] ? lock_acquire.part.0+0xd4/0x280 [ 11.869731][ T250] ? find_held_lock+0x2b/0x80 [ 11.869735][ T250] netlink_rcv_skb+0x14e/0x3a0 [ 11.869737][ T250] ? rtnl_link_fill+0x920/0x920 [ 11.869739][ T250] ? netlink_ack+0xcf0/0xcf0 [ 11.869745][ T250] ? netlink_deliver_tap+0xc5/0x330 [ 11.869747][ T250] ? netlink_deliver_tap+0x13c/0x330 [ 11.869751][ T250] netlink_unicast+0x486/0x750 [ 11.869755][ T250] ? netlink_attachskb+0x810/0x810 [ 11.869758][ T250] ? __lock_acquire+0x518/0xc20 [ 11.869762][ T250] netlink_sendmsg+0x735/0xc60 [ 11.869765][ T250] ? netlink_unicast+0x750/0x750 [ 11.869769][ T250] ? __might_fault+0x97/0x140 [ 11.869775][ T250] ____sys_sendmsg+0x415/0x880 [ 11.869777][ T250] ? copy_msghdr_from_user+0x279/0x420 [ 11.869779][ T250] ? get_timestamp.constprop.0+0x390/0x390 [ 11.869781][ T250] ? move_addr_to_kernel+0x40/0x40 [ 11.869788][ T250] ___sys_sendmsg+0x14e/0x1d0 [ 11.869791][ T250] ? copy_msghdr_from_user+0x420/0x420 [ 11.869806][ T250] __sys_sendmsg+0x12c/0x1d0 [ 11.869809][ T250] ? __sys_sendmsg_sock+0x20/0x20 [ 11.869815][ T250] ? rcu_is_watching+0x16/0xd0 [ 11.869819][ T250] do_syscall_64+0xff/0x530 [ 11.869821][ T250] ? exc_page_fault+0xee/0x100 [ 11.869824][ T250] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.869827][ T250] RIP: 0033:0x7f0fb226554e [ 11.869832][ T250] 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.869834][ T250] RSP: 002b:00007ffcac6e3bb0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.869837][ T250] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f0fb226554e [ 11.869838][ T250] RDX: 0000000000000000 RSI: 00007ffcac6e3c60 RDI: 0000000000000005 [ 11.869839][ T250] RBP: 00007ffcac6e3bc0 R08: 0000000000000000 R09: 0000000000000000 [ 11.869840][ T250] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a913616 [ 11.869841][ T250] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffcac6e4320 [ 11.869848][ T250] [ 11.917718][ T250] br0: port 1(veth1) entered disabled state [ 11.918332][ T250] veth1: entered allmulticast mode [ 11.921215][ T250] veth1: entered promiscuous mode [ 11.932033][ T250] ip (250) used greatest stack depth: 23336 bytes left [ 11.968637][ T252] br0: port 2(veth2) entered blocking state [ 11.969696][ T252] br0: port 2(veth2) entered disabled state [ 11.970196][ T252] veth2: entered allmulticast mode [ 11.972781][ T252] veth2: entered promiscuous mode [ 12.060651][ T46] br0: port 1(veth1) entered blocking state [ 12.061346][ T46] br0: port 1(veth1) entered forwarding state [ 12.084457][ T71] br0: port 2(veth2) entered blocking state [ 12.084819][ T71] br0: port 2(veth2) entered forwarding state [ 27.504051][ C0] [ 27.504069][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 27.504071][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 27.504073][ C0] preempt_count: 104, expected: 0 [ 27.504074][ C0] RCU nest depth: 0, expected: 0 [ 27.504075][ C0] INFO: lockdep is turned off. [ 27.504076][ C0] irq event stamp: 535458 [ 27.504077][ C0] hardirqs last enabled at (535458): [] _raw_spin_unlock_irq+0x28/0x50 [ 27.504086][ C0] hardirqs last disabled at (535457): [] _raw_spin_lock_irq+0x4a/0x50 [ 27.504088][ C0] softirqs last enabled at (535358): [] handle_softirqs+0x67c/0x900 [ 27.504091][ C0] softirqs last disabled at (535395): [] __irq_exit_rcu+0x145/0x1c0 [ 27.504093][ C0] Preemption disabled at: [ 27.504094][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 27.504100][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.504104][ C0] Tainted: [W]=WARN [ 27.504105][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.504107][ C0] Call Trace: [ 27.504109][ C0] [ 27.504111][ C0] dump_stack_lvl+0x6f/0xa0 [ 27.504116][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 27.504118][ C0] __might_resched.cold+0x1fe/0x2c1 [ 27.504121][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 27.504125][ C0] ? __asan_memcpy+0x3c/0x60 [ 27.504128][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.504132][ C0] __kmalloc_noprof+0x443/0x760 [ 27.504135][ C0] ? __rwlock_init+0x150/0x150 [ 27.504138][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 27.504141][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 27.504143][ C0] alloc_buf.isra.0+0x4b/0x260 [ 27.504145][ C0] put_chars+0x1e1/0x2f0 [ 27.504147][ C0] ? __send_to_port+0x420/0x420 [ 27.504149][ C0] ? console_prepend_replay+0x20/0x20 [ 27.504152][ C0] hvc_console_print+0x292/0x780 [ 27.504156][ C0] ? hvc_write+0x3a0/0x3a0 [ 27.504157][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.504159][ C0] ? lock_acquire+0x13c/0x160 [ 27.504161][ C0] console_emit_next_record+0x22f/0x4c0 [ 27.504163][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 27.504165][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.504167][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.504168][ C0] ? lock_acquire+0x13c/0x160 [ 27.504170][ C0] ? console_flush_one_record+0x111/0x710 [ 27.504172][ C0] console_flush_one_record+0x46f/0x710 [ 27.504174][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 27.504177][ C0] console_unlock+0xee/0x1f0 [ 27.504178][ C0] ? lock_acquire+0x13c/0x160 [ 27.504180][ C0] ? console_flush_one_record+0x710/0x710 [ 27.504181][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.504183][ C0] ? lock_release+0x184/0x1f0 [ 27.504184][ C0] ? lock_acquire+0x60/0x160 [ 27.504186][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 27.504188][ C0] ? vprintk_emit+0x320/0x3e0 [ 27.504190][ C0] vprintk_emit+0x37c/0x3e0 [ 27.504192][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 27.504194][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.504196][ C0] ? lock_release+0x184/0x1f0 [ 27.504197][ C0] ? x86_64_start_kernel+0xe0/0x130 [ 27.504202][ C0] _printk+0xc7/0x100 [ 27.504205][ C0] ? snapshot_read.cold+0x21/0x21 [ 27.504207][ C0] ? arch_stack_walk+0xd7/0x130 [ 27.504211][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 27.504213][ C0] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 27.504215][ C0] print_irq_inversion_bug.part.0+0x32/0xc0 [ 27.504217][ C0] mark_lock_irq+0x989/0x9c0 [ 27.504220][ C0] mark_lock+0x1d7/0xa00 [ 27.504222][ C0] mark_usage+0x42/0x170 [ 27.504223][ C0] __lock_acquire+0x388/0xc20 [ 27.504226][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.504227][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.504240][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.504242][ C0] ? lock_acquire+0x13c/0x160 [ 27.504243][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.504250][ C0] _raw_spin_lock+0x33/0x40 [ 27.504251][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.504257][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.504264][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.504270][ C0] call_timer_fn+0x160/0x4d0 [ 27.504273][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 27.504275][ C0] ? debug_object_active_state+0x430/0x430 [ 27.504278][ C0] ? find_held_lock+0x2b/0x80 [ 27.504280][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.504282][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.504284][ C0] __run_timers+0x68f/0xaa0 [ 27.504286][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.504293][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.504294][ C0] ? __lock_acquire+0x518/0xc20 [ 27.504297][ C0] ? __rwlock_init+0x150/0x150 [ 27.504299][ C0] run_timer_softirq+0xf0/0x160 [ 27.504301][ C0] ? __run_timers+0xaa0/0xaa0 [ 27.504303][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.504305][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.504307][ C0] handle_softirqs+0x1d3/0x900 [ 27.504309][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.504310][ C0] ? _local_bh_enable+0xc0/0xc0 [ 27.504312][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.504314][ C0] irq_exit_rcu+0xe/0x30 [ 27.504315][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.504318][ C0] [ 27.504319][ C0] [ 27.504320][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 27.504321][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.504324][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.504326][ C0] Code: 48 8b 3d 94 e2 f5 01 e8 1f 00 00 00 48 2b 05 58 a3 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 96 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 27.504328][ C0] RSP: 0018:ffffffffa8607cf8 EFLAGS: 00000296 [ 27.504331][ C0] RAX: 0000000000082b61 RBX: ffffffffa861c600 RCX: ffffffffa56f0307 [ 27.504333][ C0] RDX: ffffffffa861c600 RSI: ffffffffa8438b01 RDI: ffffffffa7e8d8e0 [ 27.504334][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.504334][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff50c0fa2 [ 27.504335][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 27.504337][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.504340][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 27.504341][ C0] default_idle+0x9/0x10 [ 27.504343][ C0] default_idle_call+0x6e/0xb0 [ 27.504345][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.504346][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 27.504348][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 27.504350][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.504352][ C0] do_idle+0xd8/0x190 [ 27.504354][ C0] cpu_startup_entry+0x53/0x70 [ 27.504355][ C0] rest_init+0x279/0x280 [ 27.504358][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 27.504360][ C0] ? rest_init+0x280/0x280 [ 27.504362][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 27.504366][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 27.504368][ C0] ? acpi_enable+0x1e4/0x330 [ 27.504371][ C0] start_kernel+0x3af/0x3b0 [ 27.504373][ C0] x86_64_start_reservations+0x24/0x30 [ 27.504375][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.504376][ C0] common_startup_64+0x13e/0x148 [ 27.504381][ C0] [ 27.525562][ C0] ======================================================== [ 27.525843][ C0] WARNING: possible irq lock inversion dependency detected [ 27.530247][ C0] 7.2.0-virtme #1 Tainted: G W [ 27.530439][ C0] -------------------------------------------------------- [ 27.530713][ C0] swapper/0/0 just changed the state of lock: [ 27.530905][ C0] ff1100000d552e58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.531266][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 27.531486][ C0] (fs_reclaim){+.+.}-{0:0} [ 27.531489][ C0] [ 27.531489][ C0] [ 27.531489][ C0] and interrupts could create inverse lock ordering between them. [ 27.531489][ C0] [ 27.532074][ C0] [ 27.532074][ C0] other info that might help us debug this: [ 27.532340][ C0] Chain exists of: [ 27.532340][ C0] &br->lock --> console_owner --> fs_reclaim [ 27.532340][ C0] [ 27.532728][ C0] Possible interrupt unsafe locking scenario: [ 27.532728][ C0] [ 27.533001][ C0] CPU0 CPU1 [ 27.533148][ C0] ---- ---- [ 27.533350][ C0] lock(fs_reclaim); [ 27.533464][ C0] local_irq_disable(); [ 27.533706][ C0] lock(&br->lock); [ 27.533950][ C0] lock(console_owner); [ 27.534136][ C0] [ 27.534304][ C0] lock(&br->lock); [ 27.534419][ C0] [ 27.534419][ C0] *** DEADLOCK *** [ 27.534419][ C0] [ 27.534686][ C0] locks held by swapper/0/0: 1, last CPU#0: [ 27.534877][ C0] #0: ffa0000000007c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 27.535227][ C0] [ 27.535227][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 27.535537][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 27.535749][ C0] HARDIRQ-ON-W at: [ 27.535863][ C0] __lock_acquire+0x388/0xc20 [ 27.536108][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.536290][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.536524][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.536805][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.537025][ C0] workqueue_init+0x2a/0x680 [ 27.537268][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.537541][ C0] kernel_init+0x21/0x150 [ 27.537733][ C0] ret_from_fork+0x474/0x6b0 [ 27.537967][ C0] ret_from_fork_asm+0x11/0x20 [ 27.538205][ C0] SOFTIRQ-ON-W at: [ 27.538319][ C0] __lock_acquire+0x388/0xc20 [ 27.538552][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.538740][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.538979][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.539248][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.539468][ C0] workqueue_init+0x2a/0x680 [ 27.539705][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.539929][ C0] kernel_init+0x21/0x150 [ 27.540109][ C0] ret_from_fork+0x474/0x6b0 [ 27.540346][ C0] ret_from_fork_asm+0x11/0x20 [ 27.540582][ C0] INITIAL USE at: [ 27.540691][ C0] __lock_acquire+0x388/0xc20 [ 27.540933][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.541116][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.541352][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.541625][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.541855][ C0] workqueue_init+0x2a/0x680 [ 27.542091][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.542327][ C0] kernel_init+0x21/0x150 [ 27.542511][ C0] ret_from_fork+0x474/0x6b0 [ 27.542753][ C0] ret_from_fork_asm+0x11/0x20 [ 27.542992][ C0] } [ 27.543069][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 27.543342][ C0] ... acquired at: [ 27.543454][ C0] __lock_acquire+0x518/0xc20 [ 27.543604][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.543808][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.543952][ C0] __kmalloc_noprof+0xd3/0x760 [ 27.544153][ C0] alloc_buf.isra.0+0x4b/0x260 [ 27.544298][ C0] put_chars+0x1e1/0x2f0 [ 27.544498][ C0] hvc_console_print+0x292/0x780 [ 27.544645][ C0] console_emit_next_record+0x22f/0x4c0 [ 27.544850][ C0] console_flush_one_record+0x46f/0x710 [ 27.544993][ C0] console_unlock+0xee/0x1f0 [ 27.545189][ C0] vprintk_emit+0x37c/0x3e0 [ 27.545338][ C0] dev_vprintk_emit+0x27f/0x2c0 [ 27.545535][ C0] dev_printk_emit+0xb9/0xee [ 27.545678][ C0] _dev_info+0xe2/0x116 [ 27.545845][ C0] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 27.546024][ C0] cmos_do_probe+0x73b/0x98a [ 27.546222][ C0] platform_probe+0xfe/0x1f0 [ 27.546367][ C0] call_driver_probe+0x61/0x1c0 [ 27.546576][ C0] really_probe+0x199/0x760 [ 27.546728][ C0] __driver_probe_device+0x24f/0x440 [ 27.546927][ C0] driver_probe_device+0x4a/0xf0 [ 27.547071][ C0] __driver_attach+0x1b8/0x540 [ 27.547273][ C0] bus_for_each_dev+0x130/0x1e0 [ 27.547418][ C0] bus_add_driver+0x2c8/0x530 [ 27.547616][ C0] driver_register+0x1a3/0x390 [ 27.547770][ C0] __platform_driver_probe+0x13f/0x270 [ 27.547969][ C0] cmos_init+0x31/0x40 [ 27.548081][ C0] do_one_initcall+0x124/0x4f0 [ 27.548276][ C0] kernel_init_freeable+0x596/0x630 [ 27.548419][ C0] kernel_init+0x21/0x150 [ 27.548618][ C0] ret_from_fork+0x474/0x6b0 [ 27.548768][ C0] ret_from_fork_asm+0x11/0x20 [ 27.548963][ C0] [ 27.549037][ C0] -> (console_owner){....}-{0:0} { [ 27.549240][ C0] INITIAL USE at: [ 27.549354][ C0] } [ 27.549426][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 27.549697][ C0] ... acquired at: [ 27.549811][ C0] __lock_acquire+0x518/0xc20 [ 27.550011][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.550153][ C0] console_lock_spinning_enable+0x5c/0x60 [ 27.550386][ C0] console_emit_next_record+0x1d1/0x4c0 [ 27.550528][ C0] console_flush_one_record+0x46f/0x710 [ 27.550733][ C0] console_unlock+0xee/0x1f0 [ 27.550878][ C0] vprintk_emit+0x37c/0x3e0 [ 27.551076][ C0] _printk+0xc7/0x100 [ 27.551188][ C0] br_set_state+0x22f/0x430 [bridge] [ 27.551397][ C0] br_init_port+0xc4/0x200 [bridge] [ 27.551546][ C0] br_stp_enable_port+0x12/0x50 [bridge] [ 27.551788][ C0] br_port_carrier_check+0x220/0x430 [bridge] [ 27.552025][ C0] br_device_event+0x52d/0x8f0 [bridge] [ 27.552176][ C0] notifier_call_chain+0xae/0x300 [ 27.552373][ C0] netif_state_change+0x139/0x340 [ 27.552520][ C0] __linkwatch_run_queue+0x34c/0x750 [ 27.552723][ C0] linkwatch_event+0x7f/0xb0 [ 27.552872][ C0] process_one_work+0xe3e/0x1560 [ 27.553068][ C0] worker_thread+0x4f1/0xd60 [ 27.553213][ C0] kthread+0x367/0x460 [ 27.553322][ C0] ret_from_fork+0x474/0x6b0 [ 27.553525][ C0] ret_from_fork_asm+0x11/0x20 [ 27.553671][ C0] [ 27.553801][ C0] -> (&br->lock){+.-.}-{3:3} { [ 27.553950][ C0] HARDIRQ-ON-W at: [ 27.554117][ C0] __lock_acquire+0x388/0xc20 [ 27.554302][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.554536][ C0] _raw_spin_lock_bh+0x38/0x50 [ 27.554718][ C0] recalculate_group_addr+0x51/0x120 [bridge] [ 27.555002][ C0] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 27.555278][ C0] br_changelink+0x575/0x16e0 [bridge] [ 27.555526][ C0] br_dev_newlink+0xeb/0x160 [bridge] [ 27.555717][ C0] rtnl_newlink_create+0x2d0/0x750 [ 27.555956][ C0] __rtnl_newlink+0x22b/0xa50 [ 27.556138][ C0] rtnl_newlink+0x9f1/0x11c0 [ 27.556376][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.556612][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 27.556803][ C0] netlink_unicast+0x486/0x750 [ 27.557037][ C0] netlink_sendmsg+0x735/0xc60 [ 27.557274][ C0] ____sys_sendmsg+0x415/0x880 [ 27.557457][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 27.557694][ C0] __sys_sendmsg+0x12c/0x1d0 [ 27.557884][ C0] do_syscall_64+0xff/0x530 [ 27.558117][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.558392][ C0] IN-SOFTIRQ-W at: [ 27.558503][ C0] __lock_acquire+0x388/0xc20 [ 27.558748][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.558931][ C0] _raw_spin_lock+0x33/0x40 [ 27.559169][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.559444][ C0] call_timer_fn+0x160/0x4d0 [ 27.559627][ C0] __run_timers+0x68f/0xaa0 [ 27.559875][ C0] run_timer_softirq+0xf0/0x160 [ 27.560109][ C0] handle_softirqs+0x1d3/0x900 [ 27.560291][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.560531][ C0] irq_exit_rcu+0xe/0x30 [ 27.560773][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.560991][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.561263][ C0] pv_native_safe_halt+0xf/0x10 [ 27.561498][ C0] default_idle+0x9/0x10 [ 27.561678][ C0] default_idle_call+0x6e/0xb0 [ 27.561923][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.562195][ C0] do_idle+0xd8/0x190 [ 27.562340][ C0] cpu_startup_entry+0x53/0x70 [ 27.562577][ C0] rest_init+0x279/0x280 [ 27.562766][ C0] start_kernel+0x3af/0x3b0 [ 27.563004][ C0] x86_64_start_reservations+0x24/0x30 [ 27.563241][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.563419][ C0] common_startup_64+0x13e/0x148 [ 27.563657][ C0] INITIAL USE at: [ 27.563772][ C0] __lock_acquire+0x388/0xc20 [ 27.563959][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.564197][ C0] _raw_spin_lock_bh+0x38/0x50 [ 27.564380][ C0] recalculate_group_addr+0x51/0x120 [bridge] [ 27.564658][ C0] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 27.564888][ C0] br_changelink+0x575/0x16e0 [bridge] [ 27.565074][ C0] br_dev_newlink+0xeb/0x160 [bridge] [ 27.565318][ C0] rtnl_newlink_create+0x2d0/0x750 [ 27.565563][ C0] __rtnl_newlink+0x22b/0xa50 [ 27.565749][ C0] rtnl_newlink+0x9f1/0x11c0 [ 27.565933][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.566111][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 27.566344][ C0] netlink_unicast+0x486/0x750 [ 27.566581][ C0] netlink_sendmsg+0x735/0xc60 [ 27.566769][ C0] ____sys_sendmsg+0x415/0x880 [ 27.566950][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 27.567187][ C0] __sys_sendmsg+0x12c/0x1d0 [ 27.567370][ C0] do_syscall_64+0xff/0x530 [ 27.567603][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.567884][ C0] } [ 27.567962][ C0] ... key at: [] __key.7+0x0/0x40 [bridge] [ 27.568233][ C0] ... acquired at: [ 27.568343][ C0] mark_lock+0x1d7/0xa00 [ 27.568490][ C0] mark_usage+0x42/0x170 [ 27.568690][ C0] __lock_acquire+0x388/0xc20 [ 27.568840][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.568988][ C0] _raw_spin_lock+0x33/0x40 [ 27.569134][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.569376][ C0] call_timer_fn+0x160/0x4d0 [ 27.569576][ C0] __run_timers+0x68f/0xaa0 [ 27.569727][ C0] run_timer_softirq+0xf0/0x160 [ 27.569872][ C0] handle_softirqs+0x1d3/0x900 [ 27.570018][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.570215][ C0] irq_exit_rcu+0xe/0x30 [ 27.570362][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.570595][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.570780][ C0] pv_native_safe_halt+0xf/0x10 [ 27.570926][ C0] default_idle+0x9/0x10 [ 27.571069][ C0] default_idle_call+0x6e/0xb0 [ 27.571267][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.571446][ C0] do_idle+0xd8/0x190 [ 27.571610][ C0] cpu_startup_entry+0x53/0x70 [ 27.571760][ C0] rest_init+0x279/0x280 [ 27.571960][ C0] start_kernel+0x3af/0x3b0 [ 27.572101][ C0] x86_64_start_reservations+0x24/0x30 [ 27.572296][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.572439][ C0] common_startup_64+0x13e/0x148 [ 27.572642][ C0] [ 27.572725][ C0] [ 27.572725][ C0] stack backtrace: [ 27.572913][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.572916][ C0] Tainted: [W]=WARN [ 27.572918][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.572919][ C0] Call Trace: [ 27.572920][ C0] [ 27.572922][ C0] dump_stack_lvl+0x6f/0xa0 [ 27.572926][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 27.572930][ C0] mark_lock_irq+0x989/0x9c0 [ 27.572933][ C0] mark_lock+0x1d7/0xa00 [ 27.572935][ C0] mark_usage+0x42/0x170 [ 27.572936][ C0] __lock_acquire+0x388/0xc20 [ 27.572939][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.572940][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.572947][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.572951][ C0] ? lock_acquire+0x13c/0x160 [ 27.572952][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.572958][ C0] _raw_spin_lock+0x33/0x40 [ 27.572960][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.572966][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.572972][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.572978][ C0] call_timer_fn+0x160/0x4d0 [ 27.572980][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 27.572982][ C0] ? debug_object_active_state+0x430/0x430 [ 27.572986][ C0] ? find_held_lock+0x2b/0x80 [ 27.572988][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.572990][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.572992][ C0] __run_timers+0x68f/0xaa0 [ 27.572994][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.573000][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.573002][ C0] ? __lock_acquire+0x518/0xc20 [ 27.573005][ C0] ? __rwlock_init+0x150/0x150 [ 27.573007][ C0] run_timer_softirq+0xf0/0x160 [ 27.573009][ C0] ? __run_timers+0xaa0/0xaa0 [ 27.573010][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.573013][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.573014][ C0] handle_softirqs+0x1d3/0x900 [ 27.573016][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.573018][ C0] ? _local_bh_enable+0xc0/0xc0 [ 27.573020][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.573021][ C0] irq_exit_rcu+0xe/0x30 [ 27.573023][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.573024][ C0] [ 27.573025][ C0] [ 27.573025][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 27.573027][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.573029][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.573031][ C0] Code: 48 8b 3d 94 e2 f5 01 e8 1f 00 00 00 48 2b 05 58 a3 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 96 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 27.573033][ C0] RSP: 0018:ffffffffa8607cf8 EFLAGS: 00000296 [ 27.573036][ C0] RAX: 0000000000082b61 RBX: ffffffffa861c600 RCX: ffffffffa56f0307 [ 27.573037][ C0] RDX: ffffffffa861c600 RSI: ffffffffa8438b01 RDI: ffffffffa7e8d8e0 [ 27.573038][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.573039][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff50c0fa2 [ 27.573040][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 27.573041][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.573044][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 27.573045][ C0] default_idle+0x9/0x10 [ 27.573047][ C0] default_idle_call+0x6e/0xb0 [ 27.573049][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.573050][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 27.573052][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 27.573054][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.573056][ C0] do_idle+0xd8/0x190 [ 27.573058][ C0] cpu_startup_entry+0x53/0x70 [ 27.573059][ C0] rest_init+0x279/0x280 [ 27.573061][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 27.573064][ C0] ? rest_init+0x280/0x280 [ 27.573066][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 27.573069][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 27.573072][ C0] ? acpi_enable+0x1e4/0x330 [ 27.573074][ C0] start_kernel+0x3af/0x3b0 [ 27.573076][ C0] x86_64_start_reservations+0x24/0x30 [ 27.573078][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.573080][ C0] common_startup_64+0x13e/0x148 [ 27.573083][ C0] [ 38.669022][ T349] br0: port 2(veth2) entered disabled state [ 38.669039][ T349] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 38.669041][ T349] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 349, name: ip [ 38.669043][ T349] preempt_count: 202, expected: 0 [ 38.669044][ T349] RCU nest depth: 0, expected: 0 [ 38.669044][ T349] INFO: lockdep is turned off. [ 38.669045][ T349] irq event stamp: 0 [ 38.669046][ T349] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 38.669051][ T349] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 38.669057][ T349] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 38.669059][ T349] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 38.669060][ T349] Preemption disabled at: [ 38.669061][ T349] [<0000000000000000>] 0x0 [ 38.669065][ T349] CPU: 2 UID: 0 PID: 349 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 38.669069][ T349] Tainted: [W]=WARN [ 38.669070][ T349] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 38.669072][ T349] Call Trace: [ 38.669073][ T349] [ 38.669074][ T349] dump_stack_lvl+0x6f/0xa0 [ 38.669080][ T349] __might_resched.cold+0x1fe/0x2c1 [ 38.669084][ T349] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 38.669087][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.669091][ T349] __kmalloc_noprof+0x443/0x760 [ 38.669095][ T349] ? __rwlock_init+0x150/0x150 [ 38.669098][ T349] ? alloc_buf.isra.0+0x4b/0x260 [ 38.669102][ T349] ? do_raw_spin_unlock+0x59/0x250 [ 38.669104][ T349] alloc_buf.isra.0+0x4b/0x260 [ 38.669106][ T349] put_chars+0x1e1/0x2f0 [ 38.669108][ T349] ? __send_to_port+0x420/0x420 [ 38.669109][ T349] ? printk_get_next_message+0x240/0x650 [ 38.669113][ T349] hvc_console_print+0x292/0x780 [ 38.669116][ T349] ? hvc_write+0x3a0/0x3a0 [ 38.669118][ T349] ? __rwlock_init+0x150/0x150 [ 38.669119][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.669121][ T349] ? lock_acquire+0x13c/0x160 [ 38.669123][ T349] console_emit_next_record+0x22f/0x4c0 [ 38.669125][ T349] ? devkmsg_read+0x4b0/0x4b0 [ 38.669127][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.669129][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.669130][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.669132][ T349] ? lock_acquire+0x13c/0x160 [ 38.669134][ T349] ? console_flush_one_record+0x111/0x710 [ 38.669135][ T349] console_flush_one_record+0x46f/0x710 [ 38.669138][ T349] ? console_emit_next_record+0x4c0/0x4c0 [ 38.669140][ T349] console_unlock+0xee/0x1f0 [ 38.669142][ T349] ? lock_acquire+0x13c/0x160 [ 38.669143][ T349] ? console_flush_one_record+0x710/0x710 [ 38.669145][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.669146][ T349] ? lock_release+0x184/0x1f0 [ 38.669147][ T349] ? lock_acquire+0x60/0x160 [ 38.669149][ T349] ? __rcu_read_lock+0x30/0x70 [ 38.669151][ T349] ? __down_trylock_console_sem+0x5e/0xa0 [ 38.669153][ T349] ? vprintk_emit+0x320/0x3e0 [ 38.669154][ T349] vprintk_emit+0x37c/0x3e0 [ 38.669157][ T349] ? wake_up_klogd_work_func+0x90/0x90 [ 38.669158][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.669160][ T349] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 38.669163][ T349] ? trace_hardirqs_on+0x36/0x40 [ 38.669164][ T349] _printk+0xc7/0x100 [ 38.669167][ T349] ? snapshot_read.cold+0x21/0x21 [ 38.669170][ T349] ? ipv6_mc_down+0x17e/0x2d0 [ 38.669174][ T349] br_set_state+0x22f/0x430 [bridge] [ 38.669187][ T349] br_stp_disable_port+0x11f/0x390 [bridge] [ 38.669195][ T349] br_device_event+0x7b5/0x8f0 [bridge] [ 38.669203][ T349] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 38.669206][ T349] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 38.669208][ T349] ? addrconf_notify+0x2c8/0xf30 [ 38.669211][ T349] notifier_call_chain+0xae/0x300 [ 38.669215][ T349] __dev_notify_flags+0x23c/0x3c0 [ 38.669218][ T349] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 38.669220][ T349] ? netif_change_name+0x8c0/0x8c0 [ 38.669222][ T349] ? netif_set_allmulti+0x250/0x250 [ 38.669224][ T349] ? __kernel_text_address+0x12/0x30 [ 38.669226][ T349] ? write_profile+0xf0/0xf0 [ 38.669229][ T349] ? unwind_get_return_address+0x67/0xd0 [ 38.669232][ T349] netif_change_flags+0xfe/0x190 [ 38.669234][ T349] do_setlink.isra.0+0x1cc3/0x2750 [ 38.669237][ T349] ? stack_trace_save+0x93/0xc0 [ 38.669238][ T349] ? rtnl_link_get_size+0x350/0x350 [ 38.669239][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.669241][ T349] ? stack_depot_save_flags+0x1bc/0x790 [ 38.669244][ T349] ? kasan_save_stack+0x3d/0x50 [ 38.669247][ T349] ? kasan_save_stack+0x2f/0x50 [ 38.669249][ T349] ? kasan_save_track+0x14/0x30 [ 38.669250][ T349] ? __kasan_kmalloc+0x7b/0x90 [ 38.669252][ T349] ? __kmalloc_cache_noprof+0x29a/0x620 [ 38.669253][ T349] ? rtnl_newlink+0x124/0x11c0 [ 38.669254][ T349] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 38.669255][ T349] ? netlink_rcv_skb+0x14e/0x3a0 [ 38.669258][ T349] ? netlink_unicast+0x486/0x750 [ 38.669259][ T349] ? netlink_sendmsg+0x735/0xc60 [ 38.669260][ T349] ? ____sys_sendmsg+0x415/0x880 [ 38.669263][ T349] ? ___sys_sendmsg+0x14e/0x1d0 [ 38.669264][ T349] ? __sys_sendmsg+0x12c/0x1d0 [ 38.669266][ T349] ? do_syscall_64+0xff/0x530 [ 38.669268][ T349] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 38.669270][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.669272][ T349] ? lock_acquire+0x13c/0x160 [ 38.669273][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.669275][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.669277][ T349] ? trace_contention_end+0xb3/0x180 [ 38.669280][ T349] ? __mutex_lock+0x1db/0x1ea0 [ 38.669281][ T349] ? __mutex_lock+0x9a3/0x1ea0 [ 38.669282][ T349] ? rtnl_newlink+0x9a8/0x11c0 [ 38.669284][ T349] ? ww_mutex_lock+0x160/0x160 [ 38.669286][ T349] ? __rtnl_newlink+0x3fa/0xa50 [ 38.669288][ T349] rtnl_newlink+0x9f1/0x11c0 [ 38.669289][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.669291][ T349] ? rtnl_bridge_getlink+0x850/0x850 [ 38.669292][ T349] ? lock_acquire+0x13c/0x160 [ 38.669293][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.669296][ T349] ? is_bpf_text_address+0x3e/0x110 [ 38.669299][ T349] ? is_bpf_text_address+0x72/0x110 [ 38.669300][ T349] ? kernel_text_address+0x149/0x170 [ 38.669302][ T349] ? __kernel_text_address+0x12/0x30 [ 38.669303][ T349] ? write_profile+0xf0/0xf0 [ 38.669304][ T349] ? unwind_get_return_address+0x67/0xd0 [ 38.669305][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.669307][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.669308][ T349] ? cap_capable+0x1d7/0x3d0 [ 38.669312][ T349] ? lock_acquire+0x13c/0x160 [ 38.669313][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.669315][ T349] ? rtnl_bridge_getlink+0x850/0x850 [ 38.669316][ T349] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 38.669318][ T349] ? rtnl_link_fill+0x920/0x920 [ 38.669319][ T349] ? filter_irq_stacks+0xd0/0xd0 [ 38.669320][ T349] ? stack_depot_save_flags+0x1bc/0x790 [ 38.669322][ T349] netlink_rcv_skb+0x14e/0x3a0 [ 38.669324][ T349] ? rtnl_link_fill+0x920/0x920 [ 38.669325][ T349] ? lock_release+0x184/0x1f0 [ 38.669327][ T349] ? netlink_ack+0xcf0/0xcf0 [ 38.669328][ T349] ? __netlink_lookup+0xb9/0x6b0 [ 38.669330][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.669332][ T349] ? lock_release+0x184/0x1f0 [ 38.669334][ T349] netlink_unicast+0x486/0x750 [ 38.669336][ T349] ? netlink_attachskb+0x810/0x810 [ 38.669337][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.669339][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.669340][ T349] ? lock_acquire+0x13c/0x160 [ 38.669342][ T349] netlink_sendmsg+0x735/0xc60 [ 38.669344][ T349] ? netlink_unicast+0x750/0x750 [ 38.669346][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.669347][ T349] ? lock_release+0x184/0x1f0 [ 38.669349][ T349] ____sys_sendmsg+0x415/0x880 [ 38.669351][ T349] ? copy_msghdr_from_user+0x279/0x420 [ 38.669352][ T349] ? get_timestamp.constprop.0+0x390/0x390 [ 38.669354][ T349] ? move_addr_to_kernel+0x40/0x40 [ 38.669355][ T349] ? filter_irq_stacks+0xd0/0xd0 [ 38.669357][ T349] ___sys_sendmsg+0x14e/0x1d0 [ 38.669359][ T349] ? copy_msghdr_from_user+0x420/0x420 [ 38.669364][ T349] __sys_sendmsg+0x12c/0x1d0 [ 38.669365][ T349] ? __sys_sendmsg_sock+0x20/0x20 [ 38.669368][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.669370][ T349] do_syscall_64+0xff/0x530 [ 38.669371][ T349] ? exc_page_fault+0xee/0x100 [ 38.669374][ T349] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 38.669375][ T349] RIP: 0033:0x7f67c1ddb54e [ 38.669377][ T349] 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 [ 38.669379][ T349] RSP: 002b:00007ffca78791d0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 38.669382][ T349] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f67c1ddb54e [ 38.669383][ T349] RDX: 0000000000000000 RSI: 00007ffca7879280 RDI: 0000000000000005 [ 38.669384][ T349] RBP: 00007ffca78791e0 R08: 0000000000000000 R09: 0000000000000000 [ 38.669385][ T349] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a913631 [ 38.669386][ T349] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffca7879940 [ 38.669388][ T349] [ 38.711076][ T349] ip (349) used greatest stack depth: 23016 bytes left [ 38.724431][ T350] br0: port 1(veth1) entered disabled state [ 38.750916][ T351] veth2: left allmulticast mode [ 38.751148][ T351] veth2: left promiscuous mode [ 38.751454][ T351] br0: port 2(veth2) entered disabled state [ 38.752468][ T351] veth1: left allmulticast mode [ 38.752672][ T351] veth1: left promiscuous mode [ 38.753661][ T351] br0: port 1(veth1) entered disabled state