[ 10.014625][ T188] ip (188) used greatest stack depth: 23728 bytes left [ 10.014641][ T188] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.014643][ T188] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 188, name: ip [ 10.014645][ T188] preempt_count: 2, expected: 0 [ 10.014646][ T188] RCU nest depth: 0, expected: 0 [ 10.014647][ T188] locks held by ip/188: 5, last CPU#2: [ 10.014649][ T188] #0: ffffffffb4c027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 10.014662][ T188] #1: ffffffffb4d69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.014668][ T188] #2: ffffffffb4d69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.014677][ T188] #3: ffffffffb4c89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.014682][ T188] #4: ffffffffb4c89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.014686][ T188] irq event stamp: 60956 [ 10.014687][ T188] hardirqs last enabled at (60955): [] __down_trylock_console_sem+0x86/0xa0 [ 10.014689][ T188] hardirqs last disabled at (60956): [] console_emit_next_record+0x3d4/0x4c0 [ 10.014691][ T188] softirqs last enabled at (60588): [] handle_softirqs+0x67c/0x900 [ 10.014693][ T188] softirqs last disabled at (60579): [] __irq_exit_rcu+0x145/0x1c0 [ 10.014696][ T188] Preemption disabled at: [ 10.014696][ T188] [<0000000000000000>] 0x0 [ 10.014703][ T188] CPU: 2 UID: 0 PID: 188 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.014707][ T188] Tainted: [W]=WARN [ 10.014708][ T188] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.014709][ T188] Call Trace: [ 10.014711][ T188] [ 10.014712][ T188] dump_stack_lvl+0x6f/0xa0 [ 10.014719][ T188] __might_resched.cold+0x1fe/0x2c1 [ 10.014723][ T188] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.014728][ T188] ? __kmalloc_noprof+0xdb/0x760 [ 10.014733][ T188] __kmalloc_noprof+0x443/0x760 [ 10.014736][ T188] ? alloc_buf.isra.0+0x4b/0x260 [ 10.014741][ T188] ? do_raw_spin_unlock+0x59/0x250 [ 10.014744][ T188] alloc_buf.isra.0+0x4b/0x260 [ 10.014747][ T188] put_chars+0x1e1/0x2f0 [ 10.014750][ T188] ? __send_to_port+0x420/0x420 [ 10.014752][ T188] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.014756][ T188] ? validate_chain+0x38b/0xc20 [ 10.014761][ T188] hvc_console_print+0x292/0x780 [ 10.014768][ T188] ? hvc_write+0x3a0/0x3a0 [ 10.014770][ T188] ? rcu_is_watching+0x16/0xd0 [ 10.014773][ T188] ? lock_acquire+0x13c/0x160 [ 10.014777][ T188] console_emit_next_record+0x22f/0x4c0 [ 10.014781][ T188] ? devkmsg_read+0x4b0/0x4b0 [ 10.014782][ T188] ? console_flush_one_record+0x106/0x710 [ 10.014786][ T188] ? rcu_is_watching+0x16/0xd0 [ 10.014788][ T188] ? lock_acquire+0x13c/0x160 [ 10.014792][ T188] console_flush_one_record+0x46f/0x710 [ 10.014796][ T188] ? console_emit_next_record+0x4c0/0x4c0 [ 10.014798][ T188] ? __lock_acquire+0x518/0xc20 [ 10.014803][ T188] console_unlock+0xee/0x1f0 [ 10.014806][ T188] ? console_flush_one_record+0x710/0x710 [ 10.014808][ T188] ? rcu_is_watching+0x16/0xd0 [ 10.014810][ T188] ? lock_acquire+0x60/0x160 [ 10.014814][ T188] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.014816][ T188] ? vprintk_emit+0x320/0x3e0 [ 10.014819][ T188] vprintk_emit+0x37c/0x3e0 [ 10.014823][ T188] ? wake_up_klogd_work_func+0x90/0x90 [ 10.014826][ T188] ? __lock_acquire+0x518/0xc20 [ 10.014830][ T188] _printk+0xc7/0x100 [ 10.014834][ T188] ? snapshot_read.cold+0x21/0x21 [ 10.014837][ T188] ? do_raw_spin_lock+0x131/0x280 [ 10.014839][ T188] ? __rwlock_init+0x150/0x150 [ 10.014843][ T188] ? do_raw_spin_lock+0x131/0x280 [ 10.014846][ T188] do_exit.cold+0x82/0x9c [ 10.014850][ T188] ? exit_notify+0x890/0x890 [ 10.014852][ T188] ? __lock_release.isra.0+0x69/0x1a0 [ 10.014855][ T188] ? rcu_is_watching+0x16/0xd0 [ 10.014859][ T188] do_group_exit+0xb8/0x370 [ 10.014862][ T188] __x64_sys_exit_group+0x3c/0x50 [ 10.014863][ T188] x64_sys_call+0x1567/0x1570 [ 10.014866][ T188] do_syscall_64+0xff/0x530 [ 10.014869][ T188] ? exc_page_fault+0xee/0x100 [ 10.014872][ T188] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.014874][ T188] RIP: 0033:0x7f5b227df1b8 [ 10.014877][ T188] Code: Unable to access opcode bytes at 0x7f5b227df18e. [ 10.014878][ T188] RSP: 002b:00007ffc29c1a968 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 10.014880][ T188] RAX: ffffffffffffffda RBX: 00007f5b2290ff88 RCX: 00007f5b227df1b8 [ 10.014882][ T188] RDX: 00007f5b22529fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 10.014883][ T188] RBP: 00007ffc29c1a9c0 R08: 0000000000000000 R09: 0000000000008010 [ 10.014884][ T188] R10: 00007ffc29c1a780 R11: 0000000000000246 R12: 0000000000000001 [ 10.014885][ T188] R13: 0000000000000000 R14: 00007f5b2290e680 R15: 00007f5b2290ffa0 [ 10.014892][ T188] [ 10.922953][ T227] 8021q: 802.1Q VLAN Support v1.8 [ 11.673946][ T250] br0: port 1(veth1) entered blocking state [ 11.674036][ T250] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.674038][ T250] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 250, name: ip [ 11.674040][ T250] preempt_count: 1, expected: 0 [ 11.674041][ T250] RCU nest depth: 0, expected: 0 [ 11.674042][ T250] locks held by ip/250: 5, last CPU#3: [ 11.674044][ T250] #0: ffffffffb54d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 11.674057][ T250] #1: ffffffffb4d69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.674063][ T250] #2: ffffffffb4d69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.674067][ T250] #3: ffffffffb4c89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.674071][ T250] #4: ffffffffb4c89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.674075][ T250] irq event stamp: 17530 [ 11.674076][ T250] hardirqs last enabled at (17529): [] __down_trylock_console_sem+0x86/0xa0 [ 11.674078][ T250] hardirqs last disabled at (17530): [] console_emit_next_record+0x3d4/0x4c0 [ 11.674080][ T250] softirqs last enabled at (17442): [] __alloc_skb+0x4c2/0x5f0 [ 11.674083][ T250] softirqs last disabled at (17440): [] __alloc_skb+0x4c2/0x5f0 [ 11.674086][ T250] Preemption disabled at: [ 11.674087][ T250] [] vprintk_emit+0x31b/0x3e0 [ 11.674092][ T250] CPU: 3 UID: 0 PID: 250 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.674096][ T250] Tainted: [W]=WARN [ 11.674097][ T250] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.674099][ T250] Call Trace: [ 11.674101][ T250] [ 11.674102][ T250] dump_stack_lvl+0x6f/0xa0 [ 11.674108][ T250] ? vprintk_emit+0x31b/0x3e0 [ 11.674110][ T250] __might_resched.cold+0x1fe/0x2c1 [ 11.674115][ T250] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.674120][ T250] ? __kmalloc_noprof+0xdb/0x760 [ 11.674125][ T250] __kmalloc_noprof+0x443/0x760 [ 11.674127][ T250] ? alloc_buf.isra.0+0x4b/0x260 [ 11.674132][ T250] ? do_raw_spin_unlock+0x59/0x250 [ 11.674135][ T250] alloc_buf.isra.0+0x4b/0x260 [ 11.674138][ T250] put_chars+0x1e1/0x2f0 [ 11.674141][ T250] ? __send_to_port+0x420/0x420 [ 11.674145][ T250] ? validate_chain+0x34a/0xc20 [ 11.674149][ T250] hvc_console_print+0x292/0x780 [ 11.674152][ T250] ? mark_usage+0x61/0x170 [ 11.674154][ T250] ? __lock_acquire+0x518/0xc20 [ 11.674155][ T250] ? __lock_acquire+0x518/0xc20 [ 11.674159][ T250] ? hvc_write+0x3a0/0x3a0 [ 11.674161][ T250] ? console_emit_next_record+0x1df/0x4c0 [ 11.674164][ T250] ? rcu_is_watching+0x16/0xd0 [ 11.674168][ T250] ? lock_acquire+0x13c/0x160 [ 11.674172][ T250] console_emit_next_record+0x22f/0x4c0 [ 11.674175][ T250] ? devkmsg_read+0x4b0/0x4b0 [ 11.674177][ T250] ? console_flush_one_record+0x106/0x710 [ 11.674180][ T250] ? rcu_is_watching+0x16/0xd0 [ 11.674182][ T250] ? lock_acquire+0x13c/0x160 [ 11.674186][ T250] console_flush_one_record+0x46f/0x710 [ 11.674190][ T250] ? console_emit_next_record+0x4c0/0x4c0 [ 11.674192][ T250] ? __lock_acquire+0x518/0xc20 [ 11.674197][ T250] console_unlock+0xee/0x1f0 [ 11.674199][ T250] ? console_flush_one_record+0x710/0x710 [ 11.674201][ T250] ? rcu_is_watching+0x16/0xd0 [ 11.674203][ T250] ? lock_acquire+0x60/0x160 [ 11.674207][ T250] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.674208][ T250] ? vprintk_emit+0x320/0x3e0 [ 11.674212][ T250] vprintk_emit+0x37c/0x3e0 [ 11.674215][ T250] ? wake_up_klogd_work_func+0x90/0x90 [ 11.674218][ T250] ? __lock_release.isra.0+0x69/0x1a0 [ 11.674219][ T250] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 11.674224][ T250] ? mark_held_locks+0x40/0x70 [ 11.674227][ T250] _printk+0xc7/0x100 [ 11.674231][ T250] ? snapshot_read.cold+0x21/0x21 [ 11.674235][ T250] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 11.674249][ T250] ? do_setlink.isra.0+0xa31/0x2750 [ 11.674250][ T250] ? rtnl_newlink+0x9f1/0x11c0 [ 11.674251][ T250] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.674256][ T250] br_set_state+0x22f/0x430 [bridge] [ 11.674266][ T250] br_init_port+0xc4/0x200 [bridge] [ 11.674274][ T250] new_nbp+0x39c/0x580 [bridge] [ 11.674283][ T250] br_add_if+0x212/0x1320 [bridge] [ 11.674290][ T250] ? is_bpf_text_address+0x72/0x110 [ 11.674294][ T250] ? kernel_text_address+0x149/0x170 [ 11.674297][ T250] ? __kernel_text_address+0x12/0x30 [ 11.674300][ T250] do_set_master+0x357/0x580 [ 11.674305][ T250] do_setlink.isra.0+0xa31/0x2750 [ 11.674308][ T250] ? stack_trace_save+0x93/0xc0 [ 11.674311][ T250] ? rtnl_link_get_size+0x350/0x350 [ 11.674312][ T250] ? rcu_read_lock_any_held+0x66/0x90 [ 11.674315][ T250] ? stack_depot_save_flags+0x38e/0x790 [ 11.674318][ T250] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.674321][ T250] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.674322][ T250] ? validate_chain+0x38b/0xc20 [ 11.674325][ T250] ? kasan_save_stack+0x3d/0x50 [ 11.674328][ T250] ? kasan_save_stack+0x2f/0x50 [ 11.674329][ T250] ? kasan_save_track+0x14/0x30 [ 11.674332][ T250] ? __lock_acquire+0x518/0xc20 [ 11.674334][ T250] ? netlink_seq_next+0xe/0x60 [ 11.674337][ T250] ? ___sys_sendmsg+0xb0/0x1d0 [ 11.674341][ T250] ? lock_acquire.part.0+0xd4/0x280 [ 11.674343][ T250] ? rtnl_newlink+0x9a8/0x11c0 [ 11.674346][ T250] ? rcu_is_watching+0x16/0xd0 [ 11.674348][ T250] ? lock_acquire+0x13c/0x160 [ 11.674350][ T250] ? rcu_is_watching+0x16/0xd0 [ 11.674351][ T250] ? rcu_is_watching+0x16/0xd0 [ 11.674353][ T250] ? trace_contention_end+0xb3/0x180 [ 11.674357][ T250] ? __mutex_lock+0x1db/0x1ea0 [ 11.674359][ T250] ? __mutex_lock+0x9a3/0x1ea0 [ 11.674361][ T250] ? rtnl_newlink+0x9a8/0x11c0 [ 11.674364][ T250] ? ww_mutex_lock+0x160/0x160 [ 11.674366][ T250] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.674371][ T250] ? __rtnl_newlink+0x3fa/0xa50 [ 11.674376][ T250] rtnl_newlink+0x9f1/0x11c0 [ 11.674381][ T250] ? rtnl_bridge_getlink+0x850/0x850 [ 11.674383][ T250] ? __lock_acquire+0x518/0xc20 [ 11.674387][ T250] ? lock_acquire.part.0+0xd4/0x280 [ 11.674389][ T250] ? find_held_lock+0x2b/0x80 [ 11.674391][ T250] ? rtnl_bridge_getlink+0x850/0x850 [ 11.674392][ T250] ? __lock_release.isra.0+0x69/0x1a0 [ 11.674396][ T250] ? rtnl_bridge_getlink+0x850/0x850 [ 11.674398][ T250] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.674401][ T250] ? rtnl_link_fill+0x920/0x920 [ 11.674402][ T250] ? __lock_acquire+0x518/0xc20 [ 11.674406][ T250] ? lock_acquire.part.0+0xd4/0x280 [ 11.674408][ T250] ? find_held_lock+0x2b/0x80 [ 11.674411][ T250] netlink_rcv_skb+0x14e/0x3a0 [ 11.674413][ T250] ? rtnl_link_fill+0x920/0x920 [ 11.674416][ T250] ? netlink_ack+0xcf0/0xcf0 [ 11.674422][ T250] ? netlink_deliver_tap+0xc5/0x330 [ 11.674423][ T250] ? netlink_deliver_tap+0x13c/0x330 [ 11.674427][ T250] netlink_unicast+0x486/0x750 [ 11.674431][ T250] ? netlink_attachskb+0x810/0x810 [ 11.674433][ T250] ? __lock_acquire+0x518/0xc20 [ 11.674437][ T250] netlink_sendmsg+0x735/0xc60 [ 11.674441][ T250] ? netlink_unicast+0x750/0x750 [ 11.674445][ T250] ? __might_fault+0x97/0x140 [ 11.674450][ T250] ____sys_sendmsg+0x415/0x880 [ 11.674452][ T250] ? copy_msghdr_from_user+0x279/0x420 [ 11.674454][ T250] ? get_timestamp.constprop.0+0x390/0x390 [ 11.674456][ T250] ? move_addr_to_kernel+0x40/0x40 [ 11.674463][ T250] ___sys_sendmsg+0x14e/0x1d0 [ 11.674466][ T250] ? copy_msghdr_from_user+0x420/0x420 [ 11.674481][ T250] __sys_sendmsg+0x12c/0x1d0 [ 11.674483][ T250] ? __sys_sendmsg_sock+0x20/0x20 [ 11.674489][ T250] ? rcu_is_watching+0x16/0xd0 [ 11.674493][ T250] do_syscall_64+0xff/0x530 [ 11.674495][ T250] ? exc_page_fault+0xee/0x100 [ 11.674498][ T250] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.674500][ T250] RIP: 0033:0x7fc6fbb0954e [ 11.674504][ 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.674506][ T250] RSP: 002b:00007ffded6b73f0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.674509][ T250] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007fc6fbb0954e [ 11.674510][ T250] RDX: 0000000000000000 RSI: 00007ffded6b74a0 RDI: 0000000000000005 [ 11.674511][ T250] RBP: 00007ffded6b7400 R08: 0000000000000000 R09: 0000000000000000 [ 11.674512][ T250] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91ab58 [ 11.674512][ T250] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffded6b7b60 [ 11.674519][ T250] [ 11.713069][ T250] br0: port 1(veth1) entered disabled state [ 11.713498][ T250] veth1: entered allmulticast mode [ 11.715067][ T250] veth1: entered promiscuous mode [ 11.722512][ T250] ip (250) used greatest stack depth: 23336 bytes left [ 11.751658][ T252] br0: port 2(veth2) entered blocking state [ 11.752631][ T252] br0: port 2(veth2) entered disabled state [ 11.752959][ T252] veth2: entered allmulticast mode [ 11.754456][ T252] veth2: entered promiscuous mode [ 11.803920][ T103] br0: port 1(veth1) entered blocking state [ 11.804481][ T103] br0: port 1(veth1) entered forwarding state [ 11.829642][ T46] br0: port 2(veth2) entered blocking state [ 11.829973][ T46] br0: port 2(veth2) entered forwarding state [ 26.992055][ C0] [ 26.992073][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 26.992076][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 26.992077][ C0] preempt_count: 104, expected: 0 [ 26.992078][ C0] RCU nest depth: 0, expected: 0 [ 26.992080][ C0] INFO: lockdep is turned off. [ 26.992080][ C0] irq event stamp: 640788 [ 26.992081][ C0] hardirqs last enabled at (640788): [] _raw_spin_unlock_irq+0x28/0x50 [ 26.992090][ C0] hardirqs last disabled at (640787): [] _raw_spin_lock_irq+0x4a/0x50 [ 26.992092][ C0] softirqs last enabled at (640748): [] handle_softirqs+0x67c/0x900 [ 26.992096][ C0] softirqs last disabled at (640785): [] __irq_exit_rcu+0x145/0x1c0 [ 26.992097][ C0] Preemption disabled at: [ 26.992098][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 26.992104][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.992108][ C0] Tainted: [W]=WARN [ 26.992109][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.992111][ C0] Call Trace: [ 26.992112][ C0] [ 26.992114][ C0] dump_stack_lvl+0x6f/0xa0 [ 26.992119][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 26.992120][ C0] __might_resched.cold+0x1fe/0x2c1 [ 26.992124][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 26.992127][ C0] ? __asan_memcpy+0x3c/0x60 [ 26.992130][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.992134][ C0] __kmalloc_noprof+0x443/0x760 [ 26.992137][ C0] ? __rwlock_init+0x150/0x150 [ 26.992140][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 26.992143][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 26.992145][ C0] alloc_buf.isra.0+0x4b/0x260 [ 26.992147][ C0] put_chars+0x1e1/0x2f0 [ 26.992149][ C0] ? __send_to_port+0x420/0x420 [ 26.992150][ C0] ? console_prepend_replay+0x20/0x20 [ 26.992154][ C0] hvc_console_print+0x292/0x780 [ 26.992157][ C0] ? hvc_write+0x3a0/0x3a0 [ 26.992159][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.992160][ C0] ? lock_acquire+0x13c/0x160 [ 26.992162][ C0] console_emit_next_record+0x22f/0x4c0 [ 26.992165][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 26.992167][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.992168][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.992170][ C0] ? lock_acquire+0x13c/0x160 [ 26.992172][ C0] ? console_flush_one_record+0x111/0x710 [ 26.992174][ C0] console_flush_one_record+0x46f/0x710 [ 26.992176][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 26.992178][ C0] console_unlock+0xee/0x1f0 [ 26.992180][ C0] ? lock_acquire+0x13c/0x160 [ 26.992182][ C0] ? console_flush_one_record+0x710/0x710 [ 26.992183][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.992185][ C0] ? lock_release+0x184/0x1f0 [ 26.992186][ C0] ? lock_acquire+0x60/0x160 [ 26.992188][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 26.992190][ C0] ? vprintk_emit+0x320/0x3e0 [ 26.992192][ C0] vprintk_emit+0x37c/0x3e0 [ 26.992194][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 26.992196][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.992197][ C0] ? lock_release+0x184/0x1f0 [ 26.992199][ C0] ? x86_64_start_kernel+0xe0/0x130 [ 26.992204][ C0] _printk+0xc7/0x100 [ 26.992207][ C0] ? snapshot_read.cold+0x21/0x21 [ 26.992208][ C0] ? arch_stack_walk+0xd7/0x130 [ 26.992212][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 26.992215][ C0] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 26.992216][ C0] print_irq_inversion_bug.part.0+0x32/0xc0 [ 26.992218][ C0] mark_lock_irq+0x989/0x9c0 [ 26.992221][ C0] mark_lock+0x1d7/0xa00 [ 26.992223][ C0] mark_usage+0x42/0x170 [ 26.992225][ C0] __lock_acquire+0x388/0xc20 [ 26.992227][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.992229][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.992243][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.992245][ C0] ? lock_acquire+0x13c/0x160 [ 26.992247][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.992254][ C0] _raw_spin_lock+0x33/0x40 [ 26.992255][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.992261][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.992268][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.992274][ C0] call_timer_fn+0x160/0x4d0 [ 26.992277][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 26.992279][ C0] ? debug_object_active_state+0x430/0x430 [ 26.992282][ C0] ? find_held_lock+0x2b/0x80 [ 26.992284][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 26.992286][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.992288][ C0] __run_timers+0x68f/0xaa0 [ 26.992290][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.992297][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.992298][ C0] ? __lock_acquire+0x518/0xc20 [ 26.992301][ C0] ? __rwlock_init+0x150/0x150 [ 26.992303][ C0] run_timer_softirq+0xf0/0x160 [ 26.992305][ C0] ? __run_timers+0xaa0/0xaa0 [ 26.992307][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.992309][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.992311][ C0] handle_softirqs+0x1d3/0x900 [ 26.992313][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 26.992314][ C0] ? _local_bh_enable+0xc0/0xc0 [ 26.992316][ C0] __irq_exit_rcu+0x145/0x1c0 [ 26.992318][ C0] irq_exit_rcu+0xe/0x30 [ 26.992319][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.992322][ C0] [ 26.992323][ C0] [ 26.992323][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 26.992325][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.992327][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.992330][ C0] Code: 48 8b 3d 94 d2 f5 01 e8 1f 00 00 00 48 2b 05 58 93 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 86 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 26.992332][ C0] RSP: 0018:ffffffffb4a07cf8 EFLAGS: 00000296 [ 26.992335][ C0] RAX: 000000000009c70f RBX: ffffffffb4a1c600 RCX: ffffffffb1af0307 [ 26.992336][ C0] RDX: ffffffffb4a1c600 RSI: ffffffffb4838af6 RDI: ffffffffb428d8e0 [ 26.992337][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.992338][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff6940fa2 [ 26.992339][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 26.992341][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.992344][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 26.992345][ C0] default_idle+0x9/0x10 [ 26.992347][ C0] default_idle_call+0x6e/0xb0 [ 26.992348][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.992350][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 26.992352][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 26.992354][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.992356][ C0] do_idle+0xd8/0x190 [ 26.992357][ C0] cpu_startup_entry+0x53/0x70 [ 26.992359][ C0] rest_init+0x279/0x280 [ 26.992361][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 26.992364][ C0] ? rest_init+0x280/0x280 [ 26.992366][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 26.992369][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 26.992372][ C0] ? acpi_enable+0x1e4/0x330 [ 26.992374][ C0] start_kernel+0x3af/0x3b0 [ 26.992376][ C0] x86_64_start_reservations+0x24/0x30 [ 26.992378][ C0] x86_64_start_kernel+0x12b/0x130 [ 26.992380][ C0] common_startup_64+0x13e/0x148 [ 26.992384][ C0] [ 27.012178][ C0] ======================================================== [ 27.012397][ C0] WARNING: possible irq lock inversion dependency detected [ 27.012620][ C0] 7.2.0-virtme #1 Tainted: G W [ 27.012808][ C0] -------------------------------------------------------- [ 27.013026][ C0] swapper/0/0 just changed the state of lock: [ 27.013209][ C0] ff1100000cdbae58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.017505][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 27.017730][ C0] (fs_reclaim){+.+.}-{0:0} [ 27.017734][ C0] [ 27.017734][ C0] [ 27.017734][ C0] and interrupts could create inverse lock ordering between them. [ 27.017734][ C0] [ 27.018286][ C0] [ 27.018286][ C0] other info that might help us debug this: [ 27.018512][ C0] Chain exists of: [ 27.018512][ C0] &br->lock --> console_owner --> fs_reclaim [ 27.018512][ C0] [ 27.018855][ C0] Possible interrupt unsafe locking scenario: [ 27.018855][ C0] [ 27.019131][ C0] CPU0 CPU1 [ 27.019279][ C0] ---- ---- [ 27.019476][ C0] lock(fs_reclaim); [ 27.019589][ C0] local_irq_disable(); [ 27.019834][ C0] lock(&br->lock); [ 27.020017][ C0] lock(console_owner); [ 27.020254][ C0] [ 27.020366][ C0] lock(&br->lock); [ 27.020533][ C0] [ 27.020533][ C0] *** DEADLOCK *** [ 27.020533][ C0] [ 27.020747][ C0] locks held by swapper/0/0: 1, last CPU#0: [ 27.021006][ C0] #0: ffa0000000007c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 27.021385][ C0] [ 27.021385][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 27.021703][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 27.021873][ C0] HARDIRQ-ON-W at: [ 27.022046][ C0] __lock_acquire+0x388/0xc20 [ 27.022247][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.022491][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.022736][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.022960][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.023234][ C0] workqueue_init+0x2a/0x680 [ 27.023474][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.023694][ C0] kernel_init+0x21/0x150 [ 27.023940][ C0] ret_from_fork+0x474/0x6b0 [ 27.024179][ C0] ret_from_fork_asm+0x11/0x20 [ 27.024367][ C0] SOFTIRQ-ON-W at: [ 27.024528][ C0] __lock_acquire+0x388/0xc20 [ 27.024712][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.024950][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.025186][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.025403][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.025674][ C0] workqueue_init+0x2a/0x680 [ 27.025915][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.026136][ C0] kernel_init+0x21/0x150 [ 27.026371][ C0] ret_from_fork+0x474/0x6b0 [ 27.026603][ C0] ret_from_fork_asm+0x11/0x20 [ 27.026788][ C0] INITIAL USE at: [ 27.026951][ C0] __lock_acquire+0x388/0xc20 [ 27.027132][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.027367][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.027601][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.027826][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.028102][ C0] workqueue_init+0x2a/0x680 [ 27.028340][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.028518][ C0] kernel_init+0x21/0x150 [ 27.028751][ C0] ret_from_fork+0x474/0x6b0 [ 27.028993][ C0] ret_from_fork_asm+0x11/0x20 [ 27.029178][ C0] } [ 27.029257][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 27.029524][ C0] ... acquired at: [ 27.029687][ C0] __lock_acquire+0x518/0xc20 [ 27.029848][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.030043][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.030185][ C0] __kmalloc_noprof+0xd3/0x760 [ 27.030387][ C0] alloc_buf.isra.0+0x4b/0x260 [ 27.030533][ C0] put_chars+0x1e1/0x2f0 [ 27.030729][ C0] hvc_console_print+0x292/0x780 [ 27.030881][ C0] console_emit_next_record+0x22f/0x4c0 [ 27.031081][ C0] console_flush_one_record+0x46f/0x710 [ 27.031225][ C0] console_unlock+0xee/0x1f0 [ 27.031423][ C0] vprintk_emit+0x37c/0x3e0 [ 27.031571][ C0] _printk+0xc7/0x100 [ 27.031687][ C0] print_ipi_mode+0x2e/0x40 [ 27.031893][ C0] do_one_initcall+0x124/0x4f0 [ 27.032040][ C0] kernel_init_freeable+0x596/0x630 [ 27.032239][ C0] kernel_init+0x21/0x150 [ 27.032385][ C0] ret_from_fork+0x474/0x6b0 [ 27.032579][ C0] ret_from_fork_asm+0x11/0x20 [ 27.032727][ C0] [ 27.032863][ C0] -> (console_owner){....}-{0:0} { [ 27.033011][ C0] INITIAL USE at: [ 27.033173][ C0] } [ 27.033247][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 27.033515][ C0] ... acquired at: [ 27.033623][ C0] __lock_acquire+0x518/0xc20 [ 27.033773][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.033973][ C0] console_lock_spinning_enable+0x5c/0x60 [ 27.034210][ C0] console_emit_next_record+0x1d1/0x4c0 [ 27.034356][ C0] console_flush_one_record+0x46f/0x710 [ 27.034553][ C0] console_unlock+0xee/0x1f0 [ 27.034698][ C0] vprintk_emit+0x37c/0x3e0 [ 27.034900][ C0] _printk+0xc7/0x100 [ 27.035011][ C0] br_set_state+0x22f/0x430 [bridge] [ 27.035168][ C0] br_init_port+0xc4/0x200 [bridge] [ 27.035375][ C0] br_stp_enable_port+0x12/0x50 [bridge] [ 27.035620][ C0] br_port_carrier_check+0x220/0x430 [bridge] [ 27.035819][ C0] br_device_event+0x52d/0x8f0 [bridge] [ 27.036025][ C0] notifier_call_chain+0xae/0x300 [ 27.036172][ C0] netif_state_change+0x139/0x340 [ 27.036371][ C0] __linkwatch_run_queue+0x34c/0x750 [ 27.036535][ C0] linkwatch_event+0x7f/0xb0 [ 27.036735][ C0] process_one_work+0xe3e/0x1560 [ 27.036885][ C0] worker_thread+0x4f1/0xd60 [ 27.037032][ C0] kthread+0x367/0x460 [ 27.037143][ C0] ret_from_fork+0x474/0x6b0 [ 27.037344][ C0] ret_from_fork_asm+0x11/0x20 [ 27.037490][ C0] [ 27.037563][ C0] -> (&br->lock){+.-.}-{3:3} { [ 27.037771][ C0] HARDIRQ-ON-W at: [ 27.037881][ C0] __lock_acquire+0x388/0xc20 [ 27.038121][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.038305][ C0] _raw_spin_lock_bh+0x38/0x50 [ 27.038539][ C0] recalculate_group_addr+0x51/0x120 [bridge] [ 27.038825][ C0] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 27.039105][ C0] br_changelink+0x575/0x16e0 [bridge] [ 27.039294][ C0] br_dev_newlink+0xeb/0x160 [bridge] [ 27.039537][ C0] rtnl_newlink_create+0x2d0/0x750 [ 27.039717][ C0] __rtnl_newlink+0x22b/0xa50 [ 27.039961][ C0] rtnl_newlink+0x9f1/0x11c0 [ 27.040200][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.040387][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 27.040624][ C0] netlink_unicast+0x486/0x750 [ 27.040866][ C0] netlink_sendmsg+0x735/0xc60 [ 27.041047][ C0] ____sys_sendmsg+0x415/0x880 [ 27.041283][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 27.041481][ C0] __sys_sendmsg+0x12c/0x1d0 [ 27.041715][ C0] do_syscall_64+0xff/0x530 [ 27.041957][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.042178][ C0] IN-SOFTIRQ-W at: [ 27.042341][ C0] __lock_acquire+0x388/0xc20 [ 27.042523][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.042767][ C0] _raw_spin_lock+0x33/0x40 [ 27.043006][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.043231][ C0] call_timer_fn+0x160/0x4d0 [ 27.043465][ C0] __run_timers+0x68f/0xaa0 [ 27.043701][ C0] run_timer_softirq+0xf0/0x160 [ 27.043889][ C0] handle_softirqs+0x1d3/0x900 [ 27.044125][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.044358][ C0] irq_exit_rcu+0xe/0x30 [ 27.044539][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.044818][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.045038][ C0] pv_native_safe_halt+0xf/0x10 [ 27.045222][ C0] default_idle+0x9/0x10 [ 27.045460][ C0] default_idle_call+0x6e/0xb0 [ 27.045696][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.045921][ C0] do_idle+0xd8/0x190 [ 27.046121][ C0] cpu_startup_entry+0x53/0x70 [ 27.046307][ C0] rest_init+0x279/0x280 [ 27.046547][ C0] start_kernel+0x3af/0x3b0 [ 27.046790][ C0] x86_64_start_reservations+0x24/0x30 [ 27.046974][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.047214][ C0] common_startup_64+0x13e/0x148 [ 27.047452][ C0] INITIAL USE at: [ 27.047562][ C0] __lock_acquire+0x388/0xc20 [ 27.047811][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.047997][ C0] _raw_spin_lock_bh+0x38/0x50 [ 27.048239][ C0] recalculate_group_addr+0x51/0x120 [bridge] [ 27.048520][ C0] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 27.048763][ C0] br_changelink+0x575/0x16e0 [bridge] [ 27.049000][ C0] br_dev_newlink+0xeb/0x160 [bridge] [ 27.049234][ C0] rtnl_newlink_create+0x2d0/0x750 [ 27.049412][ C0] __rtnl_newlink+0x22b/0xa50 [ 27.049636][ C0] rtnl_newlink+0x9f1/0x11c0 [ 27.049814][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.050041][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 27.050274][ C0] netlink_unicast+0x486/0x750 [ 27.050446][ C0] netlink_sendmsg+0x735/0xc60 [ 27.050674][ C0] ____sys_sendmsg+0x415/0x880 [ 27.050912][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 27.051088][ C0] __sys_sendmsg+0x12c/0x1d0 [ 27.051323][ C0] do_syscall_64+0xff/0x530 [ 27.051495][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.051751][ C0] } [ 27.051830][ C0] ... key at: [] __key.7+0x0/0x40 [bridge] [ 27.052088][ C0] ... acquired at: [ 27.052245][ C0] mark_lock+0x1d7/0xa00 [ 27.052383][ C0] mark_usage+0x42/0x170 [ 27.052572][ C0] __lock_acquire+0x388/0xc20 [ 27.052709][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.052903][ C0] _raw_spin_lock+0x33/0x40 [ 27.053036][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.053264][ C0] call_timer_fn+0x160/0x4d0 [ 27.053399][ C0] __run_timers+0x68f/0xaa0 [ 27.053586][ C0] run_timer_softirq+0xf0/0x160 [ 27.053725][ C0] handle_softirqs+0x1d3/0x900 [ 27.053923][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.054062][ C0] irq_exit_rcu+0xe/0x30 [ 27.054250][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.054418][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.054642][ C0] pv_native_safe_halt+0xf/0x10 [ 27.054788][ C0] default_idle+0x9/0x10 [ 27.054981][ C0] default_idle_call+0x6e/0xb0 [ 27.055123][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.055353][ C0] do_idle+0xd8/0x190 [ 27.055454][ C0] cpu_startup_entry+0x53/0x70 [ 27.055643][ C0] rest_init+0x279/0x280 [ 27.055785][ C0] start_kernel+0x3af/0x3b0 [ 27.055977][ C0] x86_64_start_reservations+0x24/0x30 [ 27.056115][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.056307][ C0] common_startup_64+0x13e/0x148 [ 27.056448][ C0] [ 27.056566][ C0] [ 27.056566][ C0] stack backtrace: [ 27.056741][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.056744][ C0] Tainted: [W]=WARN [ 27.056745][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.056747][ C0] Call Trace: [ 27.056748][ C0] [ 27.056749][ C0] dump_stack_lvl+0x6f/0xa0 [ 27.056753][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 27.056761][ C0] mark_lock_irq+0x989/0x9c0 [ 27.056763][ C0] mark_lock+0x1d7/0xa00 [ 27.056765][ C0] mark_usage+0x42/0x170 [ 27.056767][ C0] __lock_acquire+0x388/0xc20 [ 27.056769][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.056771][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.056778][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.056781][ C0] ? lock_acquire+0x13c/0x160 [ 27.056783][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.056790][ C0] _raw_spin_lock+0x33/0x40 [ 27.056791][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.056797][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.056804][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.056811][ C0] call_timer_fn+0x160/0x4d0 [ 27.056813][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 27.056815][ C0] ? debug_object_active_state+0x430/0x430 [ 27.056819][ C0] ? find_held_lock+0x2b/0x80 [ 27.056821][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.056823][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.056825][ C0] __run_timers+0x68f/0xaa0 [ 27.056827][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.056833][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.056835][ C0] ? __lock_acquire+0x518/0xc20 [ 27.056838][ C0] ? __rwlock_init+0x150/0x150 [ 27.056840][ C0] run_timer_softirq+0xf0/0x160 [ 27.056842][ C0] ? __run_timers+0xaa0/0xaa0 [ 27.056844][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.056846][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.056848][ C0] handle_softirqs+0x1d3/0x900 [ 27.056850][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.056851][ C0] ? _local_bh_enable+0xc0/0xc0 [ 27.056853][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.056855][ C0] irq_exit_rcu+0xe/0x30 [ 27.056856][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.056858][ C0] [ 27.056859][ C0] [ 27.056859][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 27.056861][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.056863][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.056865][ C0] Code: 48 8b 3d 94 d2 f5 01 e8 1f 00 00 00 48 2b 05 58 93 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 86 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 27.056867][ C0] RSP: 0018:ffffffffb4a07cf8 EFLAGS: 00000296 [ 27.056869][ C0] RAX: 000000000009c70f RBX: ffffffffb4a1c600 RCX: ffffffffb1af0307 [ 27.056870][ C0] RDX: ffffffffb4a1c600 RSI: ffffffffb4838af6 RDI: ffffffffb428d8e0 [ 27.056871][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.056872][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff6940fa2 [ 27.056873][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 27.056874][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.056876][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 27.056878][ C0] default_idle+0x9/0x10 [ 27.056880][ C0] default_idle_call+0x6e/0xb0 [ 27.056881][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.056883][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 27.056884][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 27.056887][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.056889][ C0] do_idle+0xd8/0x190 [ 27.056890][ C0] cpu_startup_entry+0x53/0x70 [ 27.056892][ C0] rest_init+0x279/0x280 [ 27.056893][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 27.056896][ C0] ? rest_init+0x280/0x280 [ 27.056898][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 27.056902][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 27.056905][ C0] ? acpi_enable+0x1e4/0x330 [ 27.056907][ C0] start_kernel+0x3af/0x3b0 [ 27.056908][ C0] x86_64_start_reservations+0x24/0x30 [ 27.056910][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.056912][ C0] common_startup_64+0x13e/0x148 [ 27.056915][ C0] [ 38.428916][ T349] br0: port 2(veth2) entered disabled state [ 38.428935][ T349] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 38.428937][ T349] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 349, name: ip [ 38.428939][ T349] preempt_count: 202, expected: 0 [ 38.428940][ T349] RCU nest depth: 0, expected: 0 [ 38.428941][ T349] INFO: lockdep is turned off. [ 38.428942][ T349] irq event stamp: 0 [ 38.428943][ T349] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 38.428948][ T349] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 38.428954][ T349] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 38.428956][ T349] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 38.428958][ T349] Preemption disabled at: [ 38.428958][ T349] [<0000000000000000>] 0x0 [ 38.428963][ T349] CPU: 2 UID: 0 PID: 349 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 38.428966][ T349] Tainted: [W]=WARN [ 38.428967][ T349] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 38.428969][ T349] Call Trace: [ 38.428970][ T349] [ 38.428972][ T349] dump_stack_lvl+0x6f/0xa0 [ 38.428977][ T349] __might_resched.cold+0x1fe/0x2c1 [ 38.428983][ T349] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 38.428987][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.428991][ T349] __kmalloc_noprof+0x443/0x760 [ 38.428995][ T349] ? __rwlock_init+0x150/0x150 [ 38.428998][ T349] ? alloc_buf.isra.0+0x4b/0x260 [ 38.429002][ T349] ? do_raw_spin_unlock+0x59/0x250 [ 38.429003][ T349] alloc_buf.isra.0+0x4b/0x260 [ 38.429005][ T349] put_chars+0x1e1/0x2f0 [ 38.429007][ T349] ? __send_to_port+0x420/0x420 [ 38.429009][ T349] ? printk_get_next_message+0x240/0x650 [ 38.429013][ T349] hvc_console_print+0x292/0x780 [ 38.429016][ T349] ? hvc_write+0x3a0/0x3a0 [ 38.429018][ T349] ? __rwlock_init+0x150/0x150 [ 38.429020][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.429022][ T349] ? lock_acquire+0x13c/0x160 [ 38.429024][ T349] console_emit_next_record+0x22f/0x4c0 [ 38.429027][ T349] ? devkmsg_read+0x4b0/0x4b0 [ 38.429029][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.429030][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.429032][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.429033][ T349] ? lock_acquire+0x13c/0x160 [ 38.429035][ T349] ? console_flush_one_record+0x111/0x710 [ 38.429037][ T349] console_flush_one_record+0x46f/0x710 [ 38.429039][ T349] ? console_emit_next_record+0x4c0/0x4c0 [ 38.429042][ T349] console_unlock+0xee/0x1f0 [ 38.429044][ T349] ? lock_acquire+0x13c/0x160 [ 38.429045][ T349] ? console_flush_one_record+0x710/0x710 [ 38.429047][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.429048][ T349] ? lock_release+0x184/0x1f0 [ 38.429050][ T349] ? lock_acquire+0x60/0x160 [ 38.429051][ T349] ? __rcu_read_lock+0x30/0x70 [ 38.429053][ T349] ? __down_trylock_console_sem+0x5e/0xa0 [ 38.429055][ T349] ? vprintk_emit+0x320/0x3e0 [ 38.429057][ T349] vprintk_emit+0x37c/0x3e0 [ 38.429060][ T349] ? wake_up_klogd_work_func+0x90/0x90 [ 38.429061][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.429063][ T349] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 38.429065][ T349] ? trace_hardirqs_on+0x36/0x40 [ 38.429067][ T349] _printk+0xc7/0x100 [ 38.429070][ T349] ? snapshot_read.cold+0x21/0x21 [ 38.429072][ T349] ? ipv6_mc_down+0x17e/0x2d0 [ 38.429077][ T349] br_set_state+0x22f/0x430 [bridge] [ 38.429090][ T349] br_stp_disable_port+0x11f/0x390 [bridge] [ 38.429098][ T349] br_device_event+0x7b5/0x8f0 [bridge] [ 38.429107][ T349] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 38.429110][ T349] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 38.429112][ T349] ? addrconf_notify+0x2c8/0xf30 [ 38.429116][ T349] notifier_call_chain+0xae/0x300 [ 38.429119][ T349] __dev_notify_flags+0x23c/0x3c0 [ 38.429123][ T349] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 38.429124][ T349] ? netif_change_name+0x8c0/0x8c0 [ 38.429127][ T349] ? netif_set_allmulti+0x250/0x250 [ 38.429128][ T349] ? __kernel_text_address+0x12/0x30 [ 38.429130][ T349] ? write_profile+0xf0/0xf0 [ 38.429133][ T349] ? unwind_get_return_address+0x67/0xd0 [ 38.429136][ T349] netif_change_flags+0xfe/0x190 [ 38.429138][ T349] do_setlink.isra.0+0x1cc3/0x2750 [ 38.429141][ T349] ? stack_trace_save+0x93/0xc0 [ 38.429142][ T349] ? rtnl_link_get_size+0x350/0x350 [ 38.429143][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.429145][ T349] ? stack_depot_save_flags+0x1bc/0x790 [ 38.429149][ T349] ? kasan_save_stack+0x3d/0x50 [ 38.429152][ T349] ? kasan_save_stack+0x2f/0x50 [ 38.429153][ T349] ? kasan_save_track+0x14/0x30 [ 38.429155][ T349] ? __kasan_kmalloc+0x7b/0x90 [ 38.429156][ T349] ? __kmalloc_cache_noprof+0x29a/0x620 [ 38.429158][ T349] ? rtnl_newlink+0x124/0x11c0 [ 38.429159][ T349] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 38.429160][ T349] ? netlink_rcv_skb+0x14e/0x3a0 [ 38.429162][ T349] ? netlink_unicast+0x486/0x750 [ 38.429164][ T349] ? netlink_sendmsg+0x735/0xc60 [ 38.429165][ T349] ? ____sys_sendmsg+0x415/0x880 [ 38.429167][ T349] ? ___sys_sendmsg+0x14e/0x1d0 [ 38.429169][ T349] ? __sys_sendmsg+0x12c/0x1d0 [ 38.429170][ T349] ? do_syscall_64+0xff/0x530 [ 38.429172][ T349] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 38.429175][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.429177][ T349] ? lock_acquire+0x13c/0x160 [ 38.429178][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.429180][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.429181][ T349] ? trace_contention_end+0xb3/0x180 [ 38.429184][ T349] ? __mutex_lock+0x1db/0x1ea0 [ 38.429186][ T349] ? __mutex_lock+0x9a3/0x1ea0 [ 38.429187][ T349] ? rtnl_newlink+0x9a8/0x11c0 [ 38.429189][ T349] ? ww_mutex_lock+0x160/0x160 [ 38.429191][ T349] ? __rtnl_newlink+0x3fa/0xa50 [ 38.429193][ T349] rtnl_newlink+0x9f1/0x11c0 [ 38.429195][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.429197][ T349] ? rtnl_bridge_getlink+0x850/0x850 [ 38.429198][ T349] ? lock_acquire+0x13c/0x160 [ 38.429199][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.429201][ T349] ? is_bpf_text_address+0x3e/0x110 [ 38.429204][ T349] ? is_bpf_text_address+0x72/0x110 [ 38.429206][ T349] ? kernel_text_address+0x149/0x170 [ 38.429207][ T349] ? __kernel_text_address+0x12/0x30 [ 38.429209][ T349] ? write_profile+0xf0/0xf0 [ 38.429210][ T349] ? unwind_get_return_address+0x67/0xd0 [ 38.429211][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.429213][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.429214][ T349] ? cap_capable+0x1d7/0x3d0 [ 38.429218][ T349] ? lock_acquire+0x13c/0x160 [ 38.429219][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.429221][ T349] ? rtnl_bridge_getlink+0x850/0x850 [ 38.429222][ T349] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 38.429224][ T349] ? rtnl_link_fill+0x920/0x920 [ 38.429225][ T349] ? filter_irq_stacks+0xd0/0xd0 [ 38.429227][ T349] ? stack_depot_save_flags+0x1bc/0x790 [ 38.429230][ T349] netlink_rcv_skb+0x14e/0x3a0 [ 38.429232][ T349] ? rtnl_link_fill+0x920/0x920 [ 38.429234][ T349] ? lock_release+0x184/0x1f0 [ 38.429236][ T349] ? netlink_ack+0xcf0/0xcf0 [ 38.429237][ T349] ? __netlink_lookup+0xb9/0x6b0 [ 38.429240][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.429241][ T349] ? lock_release+0x184/0x1f0 [ 38.429243][ T349] netlink_unicast+0x486/0x750 [ 38.429245][ T349] ? netlink_attachskb+0x810/0x810 [ 38.429247][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.429249][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.429250][ T349] ? lock_acquire+0x13c/0x160 [ 38.429252][ T349] netlink_sendmsg+0x735/0xc60 [ 38.429254][ T349] ? netlink_unicast+0x750/0x750 [ 38.429256][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.429258][ T349] ? lock_release+0x184/0x1f0 [ 38.429259][ T349] ____sys_sendmsg+0x415/0x880 [ 38.429261][ T349] ? copy_msghdr_from_user+0x279/0x420 [ 38.429263][ T349] ? get_timestamp.constprop.0+0x390/0x390 [ 38.429264][ T349] ? move_addr_to_kernel+0x40/0x40 [ 38.429266][ T349] ? filter_irq_stacks+0xd0/0xd0 [ 38.429268][ T349] ___sys_sendmsg+0x14e/0x1d0 [ 38.429270][ T349] ? copy_msghdr_from_user+0x420/0x420 [ 38.429275][ T349] __sys_sendmsg+0x12c/0x1d0 [ 38.429277][ T349] ? __sys_sendmsg_sock+0x20/0x20 [ 38.429279][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.429282][ T349] do_syscall_64+0xff/0x530 [ 38.429283][ T349] ? exc_page_fault+0xee/0x100 [ 38.429285][ T349] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 38.429287][ T349] RIP: 0033:0x7f38534ee54e [ 38.429288][ 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.429290][ T349] RSP: 002b:00007ffdd41e30c0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 38.429294][ T349] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f38534ee54e [ 38.429296][ T349] RDX: 0000000000000000 RSI: 00007ffdd41e3170 RDI: 0000000000000005 [ 38.429297][ T349] RBP: 00007ffdd41e30d0 R08: 0000000000000000 R09: 0000000000000000 [ 38.429297][ T349] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91ab72 [ 38.429298][ T349] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffdd41e3830 [ 38.429301][ T349] [ 38.480513][ T349] ip (349) used greatest stack depth: 23016 bytes left [ 38.493491][ T350] br0: port 1(veth1) entered disabled state [ 38.513592][ T351] veth2: left allmulticast mode [ 38.513776][ T351] veth2: left promiscuous mode [ 38.514050][ T351] br0: port 2(veth2) entered disabled state [ 38.514763][ T351] veth1: left allmulticast mode [ 38.514943][ T351] veth1: left promiscuous mode [ 38.516485][ T351] br0: port 1(veth1) entered disabled state