[ 10.096118][ T187] ip (187) used greatest stack depth: 23936 bytes left [ 10.096134][ T187] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.096137][ T187] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 187, name: ip [ 10.096138][ T187] preempt_count: 2, expected: 0 [ 10.096139][ T187] RCU nest depth: 0, expected: 0 [ 10.096140][ T187] locks held by ip/187: 5, last CPU#2: [ 10.096143][ T187] #0: ffffffffa46027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 10.096156][ T187] #1: ffffffffa4769cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.096162][ T187] #2: ffffffffa4769d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.096166][ T187] #3: ffffffffa4689660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.096170][ T187] #4: ffffffffa4689560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.096174][ T187] irq event stamp: 33318 [ 10.096175][ T187] hardirqs last enabled at (33317): [] __down_trylock_console_sem+0x86/0xa0 [ 10.096178][ T187] hardirqs last disabled at (33318): [] console_emit_next_record+0x3d4/0x4c0 [ 10.096180][ T187] softirqs last enabled at (32038): [] netlink_release+0x17b/0xcf0 [ 10.096184][ T187] softirqs last disabled at (32036): [] netlink_release+0xd2/0xcf0 [ 10.096187][ T187] Preemption disabled at: [ 10.096187][ T187] [<0000000000000000>] 0x0 [ 10.096195][ T187] CPU: 2 UID: 0 PID: 187 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.096199][ T187] Tainted: [W]=WARN [ 10.096200][ T187] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.096201][ T187] Call Trace: [ 10.096203][ T187] [ 10.096205][ T187] dump_stack_lvl+0x6f/0xa0 [ 10.096212][ T187] __might_resched.cold+0x1fe/0x2c1 [ 10.096217][ T187] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.096221][ T187] ? __kmalloc_noprof+0xdb/0x760 [ 10.096227][ T187] __kmalloc_noprof+0x443/0x760 [ 10.096229][ T187] ? alloc_buf.isra.0+0x4b/0x260 [ 10.096235][ T187] ? do_raw_spin_unlock+0x59/0x250 [ 10.096238][ T187] alloc_buf.isra.0+0x4b/0x260 [ 10.096241][ T187] put_chars+0x1e1/0x2f0 [ 10.096244][ T187] ? __send_to_port+0x420/0x420 [ 10.096246][ T187] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.096252][ T187] ? validate_chain+0x38b/0xc20 [ 10.096258][ T187] hvc_console_print+0x292/0x780 [ 10.096265][ T187] ? hvc_write+0x3a0/0x3a0 [ 10.096267][ T187] ? rcu_is_watching+0x16/0xd0 [ 10.096270][ T187] ? lock_acquire+0x13c/0x160 [ 10.096274][ T187] console_emit_next_record+0x22f/0x4c0 [ 10.096278][ T187] ? devkmsg_read+0x4b0/0x4b0 [ 10.096280][ T187] ? console_flush_one_record+0x106/0x710 [ 10.096284][ T187] ? rcu_is_watching+0x16/0xd0 [ 10.096286][ T187] ? lock_acquire+0x13c/0x160 [ 10.096291][ T187] console_flush_one_record+0x46f/0x710 [ 10.096295][ T187] ? console_emit_next_record+0x4c0/0x4c0 [ 10.096297][ T187] ? __lock_acquire+0x518/0xc20 [ 10.096303][ T187] console_unlock+0xee/0x1f0 [ 10.096306][ T187] ? console_flush_one_record+0x710/0x710 [ 10.096308][ T187] ? rcu_is_watching+0x16/0xd0 [ 10.096310][ T187] ? lock_acquire+0x60/0x160 [ 10.096315][ T187] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.096316][ T187] ? vprintk_emit+0x320/0x3e0 [ 10.096320][ T187] vprintk_emit+0x37c/0x3e0 [ 10.096324][ T187] ? wake_up_klogd_work_func+0x90/0x90 [ 10.096328][ T187] ? __lock_acquire+0x518/0xc20 [ 10.096332][ T187] _printk+0xc7/0x100 [ 10.096336][ T187] ? snapshot_read.cold+0x21/0x21 [ 10.096339][ T187] ? do_raw_spin_lock+0x131/0x280 [ 10.096342][ T187] ? __rwlock_init+0x150/0x150 [ 10.096346][ T187] ? do_raw_spin_lock+0x131/0x280 [ 10.096349][ T187] do_exit.cold+0x82/0x9c [ 10.096353][ T187] ? exit_notify+0x890/0x890 [ 10.096355][ T187] ? __lock_release.isra.0+0x69/0x1a0 [ 10.096358][ T187] ? rcu_is_watching+0x16/0xd0 [ 10.096363][ T187] do_group_exit+0xb8/0x370 [ 10.096366][ T187] __x64_sys_exit_group+0x3c/0x50 [ 10.096368][ T187] x64_sys_call+0x1567/0x1570 [ 10.096370][ T187] do_syscall_64+0xff/0x530 [ 10.096373][ T187] ? exc_page_fault+0xee/0x100 [ 10.096376][ T187] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.096379][ T187] RIP: 0033:0x7f5dd88831b8 [ 10.096382][ T187] Code: Unable to access opcode bytes at 0x7f5dd888318e. [ 10.096383][ T187] RSP: 002b:00007ffea27c2cc8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 10.096385][ T187] RAX: ffffffffffffffda RBX: 00007f5dd89b3f88 RCX: 00007f5dd88831b8 [ 10.096387][ T187] RDX: 00007f5dd85cdfc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 10.096388][ T187] RBP: 00007ffea27c2d20 R08: 0000000000000000 R09: 0000000000008010 [ 10.096389][ T187] R10: 00007ffea27c2ae0 R11: 0000000000000246 R12: 0000000000000001 [ 10.096390][ T187] R13: 0000000000000000 R14: 00007f5dd89b2680 R15: 00007f5dd89b3fa0 [ 10.096398][ T187] [ 11.032023][ T227] 8021q: 802.1Q VLAN Support v1.8 [ 11.863804][ T251] br0: port 1(veth1) entered blocking state [ 11.863964][ T251] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.863967][ T251] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 251, name: ip [ 11.863968][ T251] preempt_count: 1, expected: 0 [ 11.863969][ T251] RCU nest depth: 0, expected: 0 [ 11.863971][ T251] locks held by ip/251: 5, last CPU#3: [ 11.863973][ T251] #0: ffffffffa4ed2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 11.863985][ T251] #1: ffffffffa4769cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.863997][ T251] #2: ffffffffa4769d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.864000][ T251] #3: ffffffffa4689660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.864004][ T251] #4: ffffffffa4689560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.864008][ T251] irq event stamp: 13930 [ 11.864009][ T251] hardirqs last enabled at (13929): [] __down_trylock_console_sem+0x86/0xa0 [ 11.864012][ T251] hardirqs last disabled at (13930): [] console_emit_next_record+0x3d4/0x4c0 [ 11.864014][ T251] softirqs last enabled at (13842): [] __alloc_skb+0x4c2/0x5f0 [ 11.864017][ T251] softirqs last disabled at (13840): [] __alloc_skb+0x4c2/0x5f0 [ 11.864020][ T251] Preemption disabled at: [ 11.864021][ T251] [] vprintk_emit+0x31b/0x3e0 [ 11.864027][ T251] CPU: 3 UID: 0 PID: 251 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.864030][ T251] Tainted: [W]=WARN [ 11.864031][ T251] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.864033][ T251] Call Trace: [ 11.864035][ T251] [ 11.864036][ T251] dump_stack_lvl+0x6f/0xa0 [ 11.864042][ T251] ? vprintk_emit+0x31b/0x3e0 [ 11.864044][ T251] __might_resched.cold+0x1fe/0x2c1 [ 11.864049][ T251] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.864053][ T251] ? __kmalloc_noprof+0xdb/0x760 [ 11.864059][ T251] __kmalloc_noprof+0x443/0x760 [ 11.864061][ T251] ? alloc_buf.isra.0+0x4b/0x260 [ 11.864067][ T251] ? do_raw_spin_unlock+0x59/0x250 [ 11.864070][ T251] alloc_buf.isra.0+0x4b/0x260 [ 11.864073][ T251] put_chars+0x1e1/0x2f0 [ 11.864076][ T251] ? __send_to_port+0x420/0x420 [ 11.864080][ T251] ? validate_chain+0x34a/0xc20 [ 11.864084][ T251] hvc_console_print+0x292/0x780 [ 11.864087][ T251] ? mark_usage+0x61/0x170 [ 11.864089][ T251] ? __lock_acquire+0x518/0xc20 [ 11.864090][ T251] ? __lock_acquire+0x518/0xc20 [ 11.864094][ T251] ? hvc_write+0x3a0/0x3a0 [ 11.864096][ T251] ? console_emit_next_record+0x1df/0x4c0 [ 11.864100][ T251] ? rcu_is_watching+0x16/0xd0 [ 11.864104][ T251] ? lock_acquire+0x13c/0x160 [ 11.864108][ T251] console_emit_next_record+0x22f/0x4c0 [ 11.864112][ T251] ? devkmsg_read+0x4b0/0x4b0 [ 11.864114][ T251] ? console_flush_one_record+0x106/0x710 [ 11.864117][ T251] ? rcu_is_watching+0x16/0xd0 [ 11.864119][ T251] ? lock_acquire+0x13c/0x160 [ 11.864124][ T251] console_flush_one_record+0x46f/0x710 [ 11.864128][ T251] ? console_emit_next_record+0x4c0/0x4c0 [ 11.864130][ T251] ? __lock_acquire+0x518/0xc20 [ 11.864135][ T251] console_unlock+0xee/0x1f0 [ 11.864138][ T251] ? console_flush_one_record+0x710/0x710 [ 11.864140][ T251] ? rcu_is_watching+0x16/0xd0 [ 11.864142][ T251] ? lock_acquire+0x60/0x160 [ 11.864146][ T251] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.864148][ T251] ? vprintk_emit+0x320/0x3e0 [ 11.864151][ T251] vprintk_emit+0x37c/0x3e0 [ 11.864156][ T251] ? wake_up_klogd_work_func+0x90/0x90 [ 11.864158][ T251] ? __lock_release.isra.0+0x69/0x1a0 [ 11.864160][ T251] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 11.864164][ T251] ? mark_held_locks+0x40/0x70 [ 11.864168][ T251] _printk+0xc7/0x100 [ 11.864172][ T251] ? snapshot_read.cold+0x21/0x21 [ 11.864176][ T251] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 11.864189][ T251] ? do_setlink.isra.0+0xa31/0x2750 [ 11.864191][ T251] ? rtnl_newlink+0x9f1/0x11c0 [ 11.864192][ T251] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.864197][ T251] br_set_state+0x22f/0x430 [bridge] [ 11.864207][ T251] br_init_port+0xc4/0x200 [bridge] [ 11.864216][ T251] new_nbp+0x39c/0x580 [bridge] [ 11.864225][ T251] br_add_if+0x212/0x1320 [bridge] [ 11.864232][ T251] ? is_bpf_text_address+0x72/0x110 [ 11.864236][ T251] ? kernel_text_address+0x149/0x170 [ 11.864240][ T251] ? __kernel_text_address+0x12/0x30 [ 11.864244][ T251] do_set_master+0x357/0x580 [ 11.864248][ T251] do_setlink.isra.0+0xa31/0x2750 [ 11.864252][ T251] ? stack_trace_save+0x93/0xc0 [ 11.864255][ T251] ? rtnl_link_get_size+0x350/0x350 [ 11.864256][ T251] ? rcu_read_lock_any_held+0x66/0x90 [ 11.864259][ T251] ? stack_depot_save_flags+0x38e/0x790 [ 11.864262][ T251] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.864265][ T251] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.864267][ T251] ? validate_chain+0x38b/0xc20 [ 11.864270][ T251] ? kasan_save_stack+0x3d/0x50 [ 11.864273][ T251] ? kasan_save_stack+0x2f/0x50 [ 11.864274][ T251] ? kasan_save_track+0x14/0x30 [ 11.864277][ T251] ? __lock_acquire+0x518/0xc20 [ 11.864279][ T251] ? netlink_seq_next+0xe/0x60 [ 11.864282][ T251] ? ___sys_sendmsg+0xb0/0x1d0 [ 11.864287][ T251] ? lock_acquire.part.0+0xd4/0x280 [ 11.864289][ T251] ? rtnl_newlink+0x9a8/0x11c0 [ 11.864292][ T251] ? rcu_is_watching+0x16/0xd0 [ 11.864294][ T251] ? lock_acquire+0x13c/0x160 [ 11.864296][ T251] ? rcu_is_watching+0x16/0xd0 [ 11.864298][ T251] ? rcu_is_watching+0x16/0xd0 [ 11.864300][ T251] ? trace_contention_end+0xb3/0x180 [ 11.864304][ T251] ? __mutex_lock+0x1db/0x1ea0 [ 11.864306][ T251] ? __mutex_lock+0x9a3/0x1ea0 [ 11.864309][ T251] ? rtnl_newlink+0x9a8/0x11c0 [ 11.864312][ T251] ? ww_mutex_lock+0x160/0x160 [ 11.864314][ T251] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.864319][ T251] ? __rtnl_newlink+0x3fa/0xa50 [ 11.864324][ T251] rtnl_newlink+0x9f1/0x11c0 [ 11.864330][ T251] ? rtnl_bridge_getlink+0x850/0x850 [ 11.864332][ T251] ? __lock_acquire+0x518/0xc20 [ 11.864337][ T251] ? lock_acquire.part.0+0xd4/0x280 [ 11.864339][ T251] ? find_held_lock+0x2b/0x80 [ 11.864341][ T251] ? rtnl_bridge_getlink+0x850/0x850 [ 11.864342][ T251] ? __lock_release.isra.0+0x69/0x1a0 [ 11.864346][ T251] ? rtnl_bridge_getlink+0x850/0x850 [ 11.864348][ T251] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.864352][ T251] ? rtnl_link_fill+0x920/0x920 [ 11.864353][ T251] ? __lock_acquire+0x518/0xc20 [ 11.864358][ T251] ? lock_acquire.part.0+0xd4/0x280 [ 11.864360][ T251] ? find_held_lock+0x2b/0x80 [ 11.864363][ T251] netlink_rcv_skb+0x14e/0x3a0 [ 11.864365][ T251] ? rtnl_link_fill+0x920/0x920 [ 11.864368][ T251] ? netlink_ack+0xcf0/0xcf0 [ 11.864375][ T251] ? netlink_deliver_tap+0xc5/0x330 [ 11.864376][ T251] ? netlink_deliver_tap+0x13c/0x330 [ 11.864381][ T251] netlink_unicast+0x486/0x750 [ 11.864385][ T251] ? netlink_attachskb+0x810/0x810 [ 11.864387][ T251] ? __lock_acquire+0x518/0xc20 [ 11.864392][ T251] netlink_sendmsg+0x735/0xc60 [ 11.864396][ T251] ? netlink_unicast+0x750/0x750 [ 11.864400][ T251] ? __might_fault+0x97/0x140 [ 11.864405][ T251] ____sys_sendmsg+0x415/0x880 [ 11.864407][ T251] ? copy_msghdr_from_user+0x279/0x420 [ 11.864410][ T251] ? get_timestamp.constprop.0+0x390/0x390 [ 11.864412][ T251] ? move_addr_to_kernel+0x40/0x40 [ 11.864419][ T251] ___sys_sendmsg+0x14e/0x1d0 [ 11.864422][ T251] ? copy_msghdr_from_user+0x420/0x420 [ 11.864439][ T251] __sys_sendmsg+0x12c/0x1d0 [ 11.864442][ T251] ? __sys_sendmsg_sock+0x20/0x20 [ 11.864448][ T251] ? rcu_is_watching+0x16/0xd0 [ 11.864452][ T251] do_syscall_64+0xff/0x530 [ 11.864455][ T251] ? exc_page_fault+0xee/0x100 [ 11.864458][ T251] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.864460][ T251] RIP: 0033:0x7f27ea5bd54e [ 11.864464][ T251] 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.864466][ T251] RSP: 002b:00007ffd390692e0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.864469][ T251] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f27ea5bd54e [ 11.864470][ T251] RDX: 0000000000000000 RSI: 00007ffd39069390 RDI: 0000000000000005 [ 11.864471][ T251] RBP: 00007ffd390692f0 R08: 0000000000000000 R09: 0000000000000000 [ 11.864472][ T251] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91b51a [ 11.864473][ T251] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffd39069a50 [ 11.864480][ T251] [ 11.907881][ T251] br0: port 1(veth1) entered disabled state [ 11.908303][ T251] veth1: entered allmulticast mode [ 11.910221][ T251] veth1: entered promiscuous mode [ 11.920418][ T251] ip (251) used greatest stack depth: 23336 bytes left [ 11.971265][ T253] br0: port 2(veth2) entered blocking state [ 11.971815][ T253] br0: port 2(veth2) entered disabled state [ 11.972324][ T253] veth2: entered allmulticast mode [ 11.975021][ T253] veth2: entered promiscuous mode [ 12.061460][ T36] br0: port 1(veth1) entered blocking state [ 12.062286][ T36] br0: port 1(veth1) entered forwarding state [ 12.101276][ T203] br0: port 2(veth2) entered blocking state [ 12.101882][ T203] br0: port 2(veth2) entered forwarding state [ 27.503685][ C0] [ 27.503702][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 27.503705][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 27.503706][ C0] preempt_count: 104, expected: 0 [ 27.503707][ C0] RCU nest depth: 0, expected: 0 [ 27.503708][ C0] INFO: lockdep is turned off. [ 27.503709][ C0] irq event stamp: 672432 [ 27.503710][ C0] hardirqs last enabled at (672432): [] _raw_spin_unlock_irq+0x28/0x50 [ 27.503718][ C0] hardirqs last disabled at (672431): [] _raw_spin_lock_irq+0x4a/0x50 [ 27.503720][ C0] softirqs last enabled at (672422): [] handle_softirqs+0x67c/0x900 [ 27.503724][ C0] softirqs last disabled at (672429): [] __irq_exit_rcu+0x145/0x1c0 [ 27.503726][ C0] Preemption disabled at: [ 27.503726][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 27.503733][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.503736][ C0] Tainted: [W]=WARN [ 27.503737][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.503739][ C0] Call Trace: [ 27.503741][ C0] [ 27.503742][ C0] dump_stack_lvl+0x6f/0xa0 [ 27.503747][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 27.503749][ C0] __might_resched.cold+0x1fe/0x2c1 [ 27.503752][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 27.503755][ C0] ? __asan_memcpy+0x3c/0x60 [ 27.503758][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.503762][ C0] __kmalloc_noprof+0x443/0x760 [ 27.503765][ C0] ? __rwlock_init+0x150/0x150 [ 27.503768][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 27.503771][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 27.503773][ C0] alloc_buf.isra.0+0x4b/0x260 [ 27.503775][ C0] put_chars+0x1e1/0x2f0 [ 27.503777][ C0] ? __send_to_port+0x420/0x420 [ 27.503779][ C0] ? console_prepend_replay+0x20/0x20 [ 27.503782][ C0] hvc_console_print+0x292/0x780 [ 27.503785][ C0] ? hvc_write+0x3a0/0x3a0 [ 27.503787][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.503788][ C0] ? lock_acquire+0x13c/0x160 [ 27.503790][ C0] console_emit_next_record+0x22f/0x4c0 [ 27.503793][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 27.503795][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.503797][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.503798][ C0] ? lock_acquire+0x13c/0x160 [ 27.503800][ C0] ? console_flush_one_record+0x111/0x710 [ 27.503802][ C0] console_flush_one_record+0x46f/0x710 [ 27.503804][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 27.503806][ C0] console_unlock+0xee/0x1f0 [ 27.503808][ C0] ? lock_acquire+0x13c/0x160 [ 27.503810][ C0] ? console_flush_one_record+0x710/0x710 [ 27.503811][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.503813][ C0] ? lock_release+0x184/0x1f0 [ 27.503814][ C0] ? lock_acquire+0x60/0x160 [ 27.503816][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 27.503817][ C0] ? vprintk_emit+0x320/0x3e0 [ 27.503819][ C0] vprintk_emit+0x37c/0x3e0 [ 27.503822][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 27.503823][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.503825][ C0] ? lock_release+0x184/0x1f0 [ 27.503827][ C0] ? x86_64_start_kernel+0xe0/0x130 [ 27.503831][ C0] _printk+0xc7/0x100 [ 27.503835][ C0] ? snapshot_read.cold+0x21/0x21 [ 27.503836][ C0] ? arch_stack_walk+0xd7/0x130 [ 27.503840][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 27.503842][ C0] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 27.503844][ C0] print_irq_inversion_bug.part.0+0x32/0xc0 [ 27.503846][ C0] mark_lock_irq+0x989/0x9c0 [ 27.503849][ C0] mark_lock+0x1d7/0xa00 [ 27.503851][ C0] mark_usage+0x42/0x170 [ 27.503853][ C0] __lock_acquire+0x388/0xc20 [ 27.503855][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.503856][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.503869][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.503871][ C0] ? lock_acquire+0x13c/0x160 [ 27.503873][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.503879][ C0] _raw_spin_lock+0x33/0x40 [ 27.503881][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.503887][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.503893][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.503899][ C0] call_timer_fn+0x160/0x4d0 [ 27.503902][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 27.503904][ C0] ? debug_object_active_state+0x430/0x430 [ 27.503907][ C0] ? find_held_lock+0x2b/0x80 [ 27.503909][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.503911][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.503913][ C0] __run_timers+0x68f/0xaa0 [ 27.503915][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.503921][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.503923][ C0] ? __lock_acquire+0x518/0xc20 [ 27.503926][ C0] ? __rwlock_init+0x150/0x150 [ 27.503928][ C0] run_timer_softirq+0xf0/0x160 [ 27.503930][ C0] ? __run_timers+0xaa0/0xaa0 [ 27.503931][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.503934][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.503935][ C0] handle_softirqs+0x1d3/0x900 [ 27.503937][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.503939][ C0] ? _local_bh_enable+0xc0/0xc0 [ 27.503941][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.503942][ C0] irq_exit_rcu+0xe/0x30 [ 27.503944][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.503947][ C0] [ 27.503947][ C0] [ 27.503948][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 27.503950][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.503952][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.503955][ 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.503957][ C0] RSP: 0018:ffffffffa4407cf8 EFLAGS: 00000296 [ 27.503960][ C0] RAX: 00000000000a42ab RBX: ffffffffa441c600 RCX: ffffffffa14f0307 [ 27.503961][ C0] RDX: ffffffffa441c600 RSI: ffffffffa4238af6 RDI: ffffffffa3c8d8e0 [ 27.503962][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.503963][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff4880fa2 [ 27.503964][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 27.503965][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.503968][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 27.503970][ C0] default_idle+0x9/0x10 [ 27.503971][ C0] default_idle_call+0x6e/0xb0 [ 27.503973][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.503975][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 27.503976][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 27.503978][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.503980][ C0] do_idle+0xd8/0x190 [ 27.503982][ C0] cpu_startup_entry+0x53/0x70 [ 27.503984][ C0] rest_init+0x279/0x280 [ 27.503985][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 27.503988][ C0] ? rest_init+0x280/0x280 [ 27.503990][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 27.503994][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 27.503996][ C0] ? acpi_enable+0x1e4/0x330 [ 27.503998][ C0] start_kernel+0x3af/0x3b0 [ 27.504000][ C0] x86_64_start_reservations+0x24/0x30 [ 27.504002][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.504004][ C0] common_startup_64+0x13e/0x148 [ 27.504008][ C0] [ 27.530270][ C0] ======================================================== [ 27.530545][ C0] WARNING: possible irq lock inversion dependency detected [ 27.530767][ C0] 7.2.0-virtme #1 Tainted: G W [ 27.531004][ C0] -------------------------------------------------------- [ 27.531284][ C0] swapper/0/0 just changed the state of lock: [ 27.531466][ C0] ff1100000cefae58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.531833][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 27.532110][ C0] (fs_reclaim){+.+.}-{0:0} [ 27.532112][ C0] [ 27.532112][ C0] [ 27.532112][ C0] and interrupts could create inverse lock ordering between them. [ 27.532112][ C0] [ 27.532758][ C0] [ 27.532758][ C0] other info that might help us debug this: [ 27.533032][ C0] Chain exists of: [ 27.533032][ C0] &br->lock --> console_owner --> fs_reclaim [ 27.533032][ C0] [ 27.533436][ C0] Possible interrupt unsafe locking scenario: [ 27.533436][ C0] [ 27.533665][ C0] CPU0 CPU1 [ 27.533815][ C0] ---- ---- [ 27.534021][ C0] lock(fs_reclaim); [ 27.534142][ C0] local_irq_disable(); [ 27.534387][ C0] lock(&br->lock); [ 27.534579][ C0] lock(console_owner); [ 27.534821][ C0] [ 27.534936][ C0] lock(&br->lock); [ 27.535107][ C0] [ 27.535107][ C0] *** DEADLOCK *** [ 27.535107][ C0] [ 27.535326][ C0] locks held by swapper/0/0: 1, last CPU#0: [ 27.535565][ C0] #0: ffa0000000007c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 27.535919][ C0] [ 27.535919][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 27.536253][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 27.536408][ C0] HARDIRQ-ON-W at: [ 27.536576][ C0] __lock_acquire+0x388/0xc20 [ 27.536773][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.537013][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.537258][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.537494][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.537781][ C0] workqueue_init+0x2a/0x680 [ 27.538027][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.538313][ C0] kernel_init+0x21/0x150 [ 27.538499][ C0] ret_from_fork+0x474/0x6b0 [ 27.538744][ C0] ret_from_fork_asm+0x11/0x20 [ 27.538936][ C0] SOFTIRQ-ON-W at: [ 27.539109][ C0] __lock_acquire+0x388/0xc20 [ 27.539294][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.539536][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.539777][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.539996][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.540281][ C0] workqueue_init+0x2a/0x680 [ 27.540534][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.540816][ C0] kernel_init+0x21/0x150 [ 27.541004][ C0] ret_from_fork+0x474/0x6b0 [ 27.541249][ C0] ret_from_fork_asm+0x11/0x20 [ 27.541437][ C0] INITIAL USE at: [ 27.541602][ C0] __lock_acquire+0x388/0xc20 [ 27.541789][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.542029][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.542273][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.542500][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.542780][ C0] workqueue_init+0x2a/0x680 [ 27.543020][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.543212][ C0] kernel_init+0x21/0x150 [ 27.543456][ C0] ret_from_fork+0x474/0x6b0 [ 27.543700][ C0] ret_from_fork_asm+0x11/0x20 [ 27.543888][ C0] } [ 27.544019][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 27.544250][ C0] ... acquired at: [ 27.544413][ C0] __lock_acquire+0x518/0xc20 [ 27.544561][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.544767][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.544913][ C0] __kmalloc_noprof+0xd3/0x760 [ 27.545119][ C0] alloc_buf.isra.0+0x4b/0x260 [ 27.545267][ C0] put_chars+0x1e1/0x2f0 [ 27.545474][ C0] hvc_console_print+0x292/0x780 [ 27.545623][ C0] console_emit_next_record+0x22f/0x4c0 [ 27.545823][ C0] console_flush_one_record+0x46f/0x710 [ 27.545972][ C0] console_unlock+0xee/0x1f0 [ 27.546178][ C0] vprintk_emit+0x37c/0x3e0 [ 27.546328][ C0] dev_vprintk_emit+0x27f/0x2c0 [ 27.546536][ C0] dev_printk_emit+0xb9/0xee [ 27.546685][ C0] _dev_info+0xe2/0x116 [ 27.546850][ C0] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 27.547034][ C0] cmos_do_probe+0x73b/0x98a [ 27.547238][ C0] platform_probe+0xfe/0x1f0 [ 27.547388][ C0] call_driver_probe+0x61/0x1c0 [ 27.547587][ C0] really_probe+0x199/0x760 [ 27.547732][ C0] __driver_probe_device+0x24f/0x440 [ 27.547930][ C0] driver_probe_device+0x4a/0xf0 [ 27.548079][ C0] __driver_attach+0x1b8/0x540 [ 27.548277][ C0] bus_for_each_dev+0x130/0x1e0 [ 27.548427][ C0] bus_add_driver+0x2c8/0x530 [ 27.548631][ C0] driver_register+0x1a3/0x390 [ 27.548779][ C0] __platform_driver_probe+0x13f/0x270 [ 27.548990][ C0] cmos_init+0x31/0x40 [ 27.549111][ C0] do_one_initcall+0x124/0x4f0 [ 27.549316][ C0] kernel_init_freeable+0x596/0x630 [ 27.549467][ C0] kernel_init+0x21/0x150 [ 27.549625][ C0] ret_from_fork+0x474/0x6b0 [ 27.549786][ C0] ret_from_fork_asm+0x11/0x20 [ 27.549934][ C0] [ 27.550010][ C0] -> (console_owner){....}-{0:0} { [ 27.550171][ C0] INITIAL USE at: [ 27.550337][ C0] } [ 27.550412][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 27.550684][ C0] ... acquired at: [ 27.550793][ C0] __lock_acquire+0x518/0xc20 [ 27.550994][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.551147][ C0] console_lock_spinning_enable+0x5c/0x60 [ 27.551389][ C0] console_emit_next_record+0x1d1/0x4c0 [ 27.551536][ C0] console_flush_one_record+0x46f/0x710 [ 27.551737][ C0] console_unlock+0xee/0x1f0 [ 27.551882][ C0] vprintk_emit+0x37c/0x3e0 [ 27.552086][ C0] _printk+0xc7/0x100 [ 27.552197][ C0] br_set_state+0x22f/0x430 [bridge] [ 27.552406][ C0] br_init_port+0xc4/0x200 [bridge] [ 27.552560][ C0] br_stp_enable_port+0x12/0x50 [bridge] [ 27.552800][ C0] br_port_carrier_check+0x220/0x430 [bridge] [ 27.552990][ C0] br_device_event+0x52d/0x8f0 [bridge] [ 27.553198][ C0] notifier_call_chain+0xae/0x300 [ 27.553354][ C0] netif_state_change+0x139/0x340 [ 27.553567][ C0] __linkwatch_run_queue+0x34c/0x750 [ 27.553725][ C0] linkwatch_event+0x7f/0xb0 [ 27.553933][ C0] process_one_work+0xe3e/0x1560 [ 27.554092][ C0] worker_thread+0x4f1/0xd60 [ 27.554295][ C0] kthread+0x367/0x460 [ 27.554406][ C0] ret_from_fork+0x474/0x6b0 [ 27.554607][ C0] ret_from_fork_asm+0x11/0x20 [ 27.554757][ C0] [ 27.554832][ C0] -> (&br->lock){+.-.}-{3:3} { [ 27.555041][ C0] HARDIRQ-ON-W at: [ 27.555160][ C0] __lock_acquire+0x388/0xc20 [ 27.555399][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.555639][ C0] _raw_spin_lock_bh+0x38/0x50 [ 27.555825][ C0] recalculate_group_addr+0x51/0x120 [bridge] [ 27.556116][ C0] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 27.556397][ C0] br_changelink+0x575/0x16e0 [bridge] [ 27.556594][ C0] br_dev_newlink+0xeb/0x160 [bridge] [ 27.556835][ C0] rtnl_newlink_create+0x2d0/0x750 [ 27.557074][ C0] __rtnl_newlink+0x22b/0xa50 [ 27.557259][ C0] rtnl_newlink+0x9f1/0x11c0 [ 27.557496][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.557683][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 27.557922][ C0] netlink_unicast+0x486/0x750 [ 27.558165][ C0] netlink_sendmsg+0x735/0xc60 [ 27.558353][ C0] ____sys_sendmsg+0x415/0x880 [ 27.558602][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 27.558845][ C0] __sys_sendmsg+0x12c/0x1d0 [ 27.559031][ C0] do_syscall_64+0xff/0x530 [ 27.559270][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.559546][ C0] IN-SOFTIRQ-W at: [ 27.559657][ C0] __lock_acquire+0x388/0xc20 [ 27.559893][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.560079][ C0] _raw_spin_lock+0x33/0x40 [ 27.560322][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.560601][ C0] call_timer_fn+0x160/0x4d0 [ 27.560787][ C0] __run_timers+0x68f/0xaa0 [ 27.561027][ C0] run_timer_softirq+0xf0/0x160 [ 27.561213][ C0] handle_softirqs+0x1d3/0x900 [ 27.561452][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.561694][ C0] irq_exit_rcu+0xe/0x30 [ 27.561879][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.562154][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.562426][ C0] pv_native_safe_halt+0xf/0x10 [ 27.562608][ C0] default_idle+0x9/0x10 [ 27.562847][ C0] default_idle_call+0x6e/0xb0 [ 27.563092][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.563312][ C0] do_idle+0xd8/0x190 [ 27.563511][ C0] cpu_startup_entry+0x53/0x70 [ 27.563748][ C0] rest_init+0x279/0x280 [ 27.563931][ C0] start_kernel+0x3af/0x3b0 [ 27.564176][ C0] x86_64_start_reservations+0x24/0x30 [ 27.564363][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.564600][ C0] common_startup_64+0x13e/0x148 [ 27.564838][ C0] INITIAL USE at: [ 27.564947][ C0] __lock_acquire+0x388/0xc20 [ 27.565193][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.565388][ C0] _raw_spin_lock_bh+0x38/0x50 [ 27.565635][ C0] recalculate_group_addr+0x51/0x120 [bridge] [ 27.565915][ C0] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 27.566141][ C0] br_changelink+0x575/0x16e0 [bridge] [ 27.566378][ C0] br_dev_newlink+0xeb/0x160 [bridge] [ 27.566620][ C0] rtnl_newlink_create+0x2d0/0x750 [ 27.566802][ C0] __rtnl_newlink+0x22b/0xa50 [ 27.567034][ C0] rtnl_newlink+0x9f1/0x11c0 [ 27.567273][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.567458][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 27.567696][ C0] netlink_unicast+0x486/0x750 [ 27.567886][ C0] netlink_sendmsg+0x735/0xc60 [ 27.568123][ C0] ____sys_sendmsg+0x415/0x880 [ 27.568359][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 27.568544][ C0] __sys_sendmsg+0x12c/0x1d0 [ 27.568727][ C0] do_syscall_64+0xff/0x530 [ 27.568960][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.569190][ C0] } [ 27.569315][ C0] ... key at: [] __key.7+0x0/0x40 [bridge] [ 27.569540][ C0] ... acquired at: [ 27.569703][ C0] mark_lock+0x1d7/0xa00 [ 27.569852][ C0] mark_usage+0x42/0x170 [ 27.570055][ C0] __lock_acquire+0x388/0xc20 [ 27.570203][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.570404][ C0] _raw_spin_lock+0x33/0x40 [ 27.570549][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.570788][ C0] call_timer_fn+0x160/0x4d0 [ 27.570936][ C0] __run_timers+0x68f/0xaa0 [ 27.571134][ C0] run_timer_softirq+0xf0/0x160 [ 27.571286][ C0] handle_softirqs+0x1d3/0x900 [ 27.571481][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.571627][ C0] irq_exit_rcu+0xe/0x30 [ 27.571824][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.572011][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.572246][ C0] pv_native_safe_halt+0xf/0x10 [ 27.572443][ C0] default_idle+0x9/0x10 [ 27.572591][ C0] default_idle_call+0x6e/0xb0 [ 27.572790][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.572973][ C0] do_idle+0xd8/0x190 [ 27.573139][ C0] cpu_startup_entry+0x53/0x70 [ 27.573283][ C0] rest_init+0x279/0x280 [ 27.573480][ C0] start_kernel+0x3af/0x3b0 [ 27.573626][ C0] x86_64_start_reservations+0x24/0x30 [ 27.573823][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.573969][ C0] common_startup_64+0x13e/0x148 [ 27.574170][ C0] [ 27.574249][ C0] [ 27.574249][ C0] stack backtrace: [ 27.574435][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.574438][ C0] Tainted: [W]=WARN [ 27.574439][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.574441][ C0] Call Trace: [ 27.574442][ C0] [ 27.574443][ C0] dump_stack_lvl+0x6f/0xa0 [ 27.574447][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 27.574450][ C0] mark_lock_irq+0x989/0x9c0 [ 27.574453][ C0] mark_lock+0x1d7/0xa00 [ 27.574455][ C0] mark_usage+0x42/0x170 [ 27.574456][ C0] __lock_acquire+0x388/0xc20 [ 27.574459][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.574461][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.574467][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.574471][ C0] ? lock_acquire+0x13c/0x160 [ 27.574472][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.574478][ C0] _raw_spin_lock+0x33/0x40 [ 27.574480][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.574486][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.574492][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.574498][ C0] call_timer_fn+0x160/0x4d0 [ 27.574500][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 27.574502][ C0] ? debug_object_active_state+0x430/0x430 [ 27.574506][ C0] ? find_held_lock+0x2b/0x80 [ 27.574507][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.574509][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.574511][ C0] __run_timers+0x68f/0xaa0 [ 27.574513][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.574520][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.574521][ C0] ? __lock_acquire+0x518/0xc20 [ 27.574524][ C0] ? __rwlock_init+0x150/0x150 [ 27.574526][ C0] run_timer_softirq+0xf0/0x160 [ 27.574528][ C0] ? __run_timers+0xaa0/0xaa0 [ 27.574530][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.574532][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.574534][ C0] handle_softirqs+0x1d3/0x900 [ 27.574536][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.574538][ C0] ? _local_bh_enable+0xc0/0xc0 [ 27.574540][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.574541][ C0] irq_exit_rcu+0xe/0x30 [ 27.574542][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.574544][ C0] [ 27.574545][ C0] [ 27.574545][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 27.574547][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.574549][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.574551][ 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.574553][ C0] RSP: 0018:ffffffffa4407cf8 EFLAGS: 00000296 [ 27.574556][ C0] RAX: 00000000000a42ab RBX: ffffffffa441c600 RCX: ffffffffa14f0307 [ 27.574557][ C0] RDX: ffffffffa441c600 RSI: ffffffffa4238af6 RDI: ffffffffa3c8d8e0 [ 27.574558][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.574558][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff4880fa2 [ 27.574559][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 27.574561][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.574563][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 27.574565][ C0] default_idle+0x9/0x10 [ 27.574566][ C0] default_idle_call+0x6e/0xb0 [ 27.574568][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.574570][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 27.574571][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 27.574573][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.574576][ C0] do_idle+0xd8/0x190 [ 27.574577][ C0] cpu_startup_entry+0x53/0x70 [ 27.574579][ C0] rest_init+0x279/0x280 [ 27.574580][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 27.574583][ C0] ? rest_init+0x280/0x280 [ 27.574585][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 27.574589][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 27.574591][ C0] ? acpi_enable+0x1e4/0x330 [ 27.574593][ C0] start_kernel+0x3af/0x3b0 [ 27.574595][ C0] x86_64_start_reservations+0x24/0x30 [ 27.574597][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.574599][ C0] common_startup_64+0x13e/0x148 [ 27.574602][ C0] [ 38.814508][ T349] br0: port 2(veth2) entered disabled state [ 38.814525][ T349] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 38.814527][ T349] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 349, name: ip [ 38.814529][ T349] preempt_count: 202, expected: 0 [ 38.814530][ T349] RCU nest depth: 0, expected: 0 [ 38.814531][ T349] INFO: lockdep is turned off. [ 38.814532][ T349] irq event stamp: 0 [ 38.814533][ T349] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 38.814538][ T349] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 38.814544][ T349] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 38.814545][ T349] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 38.814547][ T349] Preemption disabled at: [ 38.814548][ T349] [<0000000000000000>] 0x0 [ 38.814553][ T349] CPU: 2 UID: 0 PID: 349 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 38.814557][ T349] Tainted: [W]=WARN [ 38.814557][ T349] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 38.814559][ T349] Call Trace: [ 38.814561][ T349] [ 38.814562][ T349] dump_stack_lvl+0x6f/0xa0 [ 38.814568][ T349] __might_resched.cold+0x1fe/0x2c1 [ 38.814572][ T349] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 38.814576][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.814580][ T349] __kmalloc_noprof+0x443/0x760 [ 38.814583][ T349] ? __rwlock_init+0x150/0x150 [ 38.814586][ T349] ? alloc_buf.isra.0+0x4b/0x260 [ 38.814590][ T349] ? do_raw_spin_unlock+0x59/0x250 [ 38.814592][ T349] alloc_buf.isra.0+0x4b/0x260 [ 38.814594][ T349] put_chars+0x1e1/0x2f0 [ 38.814596][ T349] ? __send_to_port+0x420/0x420 [ 38.814597][ T349] ? printk_get_next_message+0x240/0x650 [ 38.814601][ T349] hvc_console_print+0x292/0x780 [ 38.814604][ T349] ? hvc_write+0x3a0/0x3a0 [ 38.814606][ T349] ? __rwlock_init+0x150/0x150 [ 38.814608][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.814612][ T349] ? lock_acquire+0x13c/0x160 [ 38.814615][ T349] console_emit_next_record+0x22f/0x4c0 [ 38.814619][ T349] ? devkmsg_read+0x4b0/0x4b0 [ 38.814621][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.814623][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.814625][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.814628][ T349] ? lock_acquire+0x13c/0x160 [ 38.814630][ T349] ? console_flush_one_record+0x111/0x710 [ 38.814632][ T349] console_flush_one_record+0x46f/0x710 [ 38.814635][ T349] ? console_emit_next_record+0x4c0/0x4c0 [ 38.814637][ T349] console_unlock+0xee/0x1f0 [ 38.814639][ T349] ? lock_acquire+0x13c/0x160 [ 38.814641][ T349] ? console_flush_one_record+0x710/0x710 [ 38.814642][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.814644][ T349] ? lock_release+0x184/0x1f0 [ 38.814646][ T349] ? lock_acquire+0x60/0x160 [ 38.814647][ T349] ? __rcu_read_lock+0x30/0x70 [ 38.814649][ T349] ? __down_trylock_console_sem+0x5e/0xa0 [ 38.814651][ T349] ? vprintk_emit+0x320/0x3e0 [ 38.814653][ T349] vprintk_emit+0x37c/0x3e0 [ 38.814656][ T349] ? wake_up_klogd_work_func+0x90/0x90 [ 38.814657][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.814659][ T349] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 38.814662][ T349] ? trace_hardirqs_on+0x36/0x40 [ 38.814663][ T349] _printk+0xc7/0x100 [ 38.814666][ T349] ? snapshot_read.cold+0x21/0x21 [ 38.814669][ T349] ? ipv6_mc_down+0x17e/0x2d0 [ 38.814673][ T349] br_set_state+0x22f/0x430 [bridge] [ 38.814686][ T349] br_stp_disable_port+0x11f/0x390 [bridge] [ 38.814694][ T349] br_device_event+0x7b5/0x8f0 [bridge] [ 38.814703][ T349] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 38.814707][ T349] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 38.814709][ T349] ? addrconf_notify+0x2c8/0xf30 [ 38.814712][ T349] notifier_call_chain+0xae/0x300 [ 38.814715][ T349] __dev_notify_flags+0x23c/0x3c0 [ 38.814719][ T349] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 38.814720][ T349] ? netif_change_name+0x8c0/0x8c0 [ 38.814723][ T349] ? netif_set_allmulti+0x250/0x250 [ 38.814725][ T349] ? __kernel_text_address+0x12/0x30 [ 38.814727][ T349] ? write_profile+0xf0/0xf0 [ 38.814729][ T349] ? unwind_get_return_address+0x67/0xd0 [ 38.814732][ T349] netif_change_flags+0xfe/0x190 [ 38.814734][ T349] do_setlink.isra.0+0x1cc3/0x2750 [ 38.814737][ T349] ? stack_trace_save+0x93/0xc0 [ 38.814739][ T349] ? rtnl_link_get_size+0x350/0x350 [ 38.814740][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.814742][ T349] ? stack_depot_save_flags+0x1bc/0x790 [ 38.814745][ T349] ? kasan_save_stack+0x3d/0x50 [ 38.814748][ T349] ? kasan_save_stack+0x2f/0x50 [ 38.814750][ T349] ? kasan_save_track+0x14/0x30 [ 38.814751][ T349] ? __kasan_kmalloc+0x7b/0x90 [ 38.814753][ T349] ? __kmalloc_cache_noprof+0x29a/0x620 [ 38.814754][ T349] ? rtnl_newlink+0x124/0x11c0 [ 38.814755][ T349] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 38.814757][ T349] ? netlink_rcv_skb+0x14e/0x3a0 [ 38.814759][ T349] ? netlink_unicast+0x486/0x750 [ 38.814760][ T349] ? netlink_sendmsg+0x735/0xc60 [ 38.814762][ T349] ? ____sys_sendmsg+0x415/0x880 [ 38.814764][ T349] ? ___sys_sendmsg+0x14e/0x1d0 [ 38.814766][ T349] ? __sys_sendmsg+0x12c/0x1d0 [ 38.814767][ T349] ? do_syscall_64+0xff/0x530 [ 38.814769][ T349] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 38.814772][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.814774][ T349] ? lock_acquire+0x13c/0x160 [ 38.814775][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.814777][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.814779][ T349] ? trace_contention_end+0xb3/0x180 [ 38.814782][ T349] ? __mutex_lock+0x1db/0x1ea0 [ 38.814784][ T349] ? __mutex_lock+0x9a3/0x1ea0 [ 38.814785][ T349] ? rtnl_newlink+0x9a8/0x11c0 [ 38.814786][ T349] ? ww_mutex_lock+0x160/0x160 [ 38.814789][ T349] ? __rtnl_newlink+0x3fa/0xa50 [ 38.814791][ T349] rtnl_newlink+0x9f1/0x11c0 [ 38.814793][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.814795][ T349] ? rtnl_bridge_getlink+0x850/0x850 [ 38.814796][ T349] ? lock_acquire+0x13c/0x160 [ 38.814797][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.814799][ T349] ? is_bpf_text_address+0x3e/0x110 [ 38.814802][ T349] ? is_bpf_text_address+0x72/0x110 [ 38.814804][ T349] ? kernel_text_address+0x149/0x170 [ 38.814805][ T349] ? __kernel_text_address+0x12/0x30 [ 38.814807][ T349] ? write_profile+0xf0/0xf0 [ 38.814808][ T349] ? unwind_get_return_address+0x67/0xd0 [ 38.814809][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.814811][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.814813][ T349] ? cap_capable+0x1d7/0x3d0 [ 38.814816][ T349] ? lock_acquire+0x13c/0x160 [ 38.814818][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.814819][ T349] ? rtnl_bridge_getlink+0x850/0x850 [ 38.814821][ T349] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 38.814822][ T349] ? rtnl_link_fill+0x920/0x920 [ 38.814824][ T349] ? filter_irq_stacks+0xd0/0xd0 [ 38.814825][ T349] ? stack_depot_save_flags+0x1bc/0x790 [ 38.814828][ T349] netlink_rcv_skb+0x14e/0x3a0 [ 38.814829][ T349] ? rtnl_link_fill+0x920/0x920 [ 38.814830][ T349] ? lock_release+0x184/0x1f0 [ 38.814832][ T349] ? netlink_ack+0xcf0/0xcf0 [ 38.814833][ T349] ? __netlink_lookup+0xb9/0x6b0 [ 38.814836][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.814837][ T349] ? lock_release+0x184/0x1f0 [ 38.814839][ T349] netlink_unicast+0x486/0x750 [ 38.814841][ T349] ? netlink_attachskb+0x810/0x810 [ 38.814843][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.814845][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.814846][ T349] ? lock_acquire+0x13c/0x160 [ 38.814848][ T349] netlink_sendmsg+0x735/0xc60 [ 38.814850][ T349] ? netlink_unicast+0x750/0x750 [ 38.814852][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.814854][ T349] ? lock_release+0x184/0x1f0 [ 38.814856][ T349] ____sys_sendmsg+0x415/0x880 [ 38.814857][ T349] ? copy_msghdr_from_user+0x279/0x420 [ 38.814859][ T349] ? get_timestamp.constprop.0+0x390/0x390 [ 38.814860][ T349] ? move_addr_to_kernel+0x40/0x40 [ 38.814862][ T349] ? filter_irq_stacks+0xd0/0xd0 [ 38.814864][ T349] ___sys_sendmsg+0x14e/0x1d0 [ 38.814866][ T349] ? copy_msghdr_from_user+0x420/0x420 [ 38.814871][ T349] __sys_sendmsg+0x12c/0x1d0 [ 38.814873][ T349] ? __sys_sendmsg_sock+0x20/0x20 [ 38.814876][ T349] ? rcu_is_watching+0x16/0xd0 [ 38.814878][ T349] do_syscall_64+0xff/0x530 [ 38.814879][ T349] ? exc_page_fault+0xee/0x100 [ 38.814881][ T349] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 38.814883][ T349] RIP: 0033:0x7fba7b55a54e [ 38.814885][ 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.814887][ T349] RSP: 002b:00007ffd80b6c710 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 38.814891][ T349] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007fba7b55a54e [ 38.814892][ T349] RDX: 0000000000000000 RSI: 00007ffd80b6c7c0 RDI: 0000000000000005 [ 38.814893][ T349] RBP: 00007ffd80b6c720 R08: 0000000000000000 R09: 0000000000000000 [ 38.814894][ T349] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91b535 [ 38.814894][ T349] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffd80b6ce80 [ 38.814897][ T349] [ 38.852530][ T349] ip (349) used greatest stack depth: 23016 bytes left [ 38.873372][ T350] br0: port 1(veth1) entered disabled state [ 38.889539][ T351] veth2: left allmulticast mode [ 38.889751][ T351] veth2: left promiscuous mode [ 38.890017][ T351] br0: port 2(veth2) entered disabled state [ 38.891430][ T351] veth1: left allmulticast mode [ 38.891983][ T351] veth1: left promiscuous mode [ 38.896686][ T351] br0: port 1(veth1) entered disabled state