[ 9.679847][ T192] ip (192) used greatest stack depth: 24096 bytes left [ 9.679863][ T192] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.679865][ T192] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 192, name: ip [ 9.679873][ T192] preempt_count: 2, expected: 0 [ 9.679874][ T192] RCU nest depth: 0, expected: 0 [ 9.679875][ T192] locks held by ip/192: 5, last CPU#0: [ 9.679878][ T192] #0: ffffffff906027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 9.679890][ T192] #1: ffffffff90769cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.679896][ T192] #2: ffffffff90769d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.679900][ T192] #3: ffffffff90689660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.679904][ T192] #4: ffffffff90689560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.679908][ T192] irq event stamp: 38070 [ 9.679909][ T192] hardirqs last enabled at (38069): [] __down_trylock_console_sem+0x86/0xa0 [ 9.679912][ T192] hardirqs last disabled at (38070): [] console_emit_next_record+0x3d4/0x4c0 [ 9.679914][ T192] softirqs last enabled at (36790): [] netlink_release+0x17b/0xcf0 [ 9.679918][ T192] softirqs last disabled at (36788): [] netlink_release+0xd2/0xcf0 [ 9.679920][ T192] Preemption disabled at: [ 9.679921][ T192] [<0000000000000000>] 0x0 [ 9.679927][ T192] CPU: 0 UID: 0 PID: 192 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.679931][ T192] Tainted: [W]=WARN [ 9.679932][ T192] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.679933][ T192] Call Trace: [ 9.679935][ T192] [ 9.679936][ T192] dump_stack_lvl+0x6f/0xa0 [ 9.679943][ T192] __might_resched.cold+0x1fe/0x2c1 [ 9.679947][ T192] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.679951][ T192] ? __kmalloc_noprof+0xdb/0x760 [ 9.679959][ T192] __kmalloc_noprof+0x443/0x760 [ 9.679961][ T192] ? alloc_buf.isra.0+0x4b/0x260 [ 9.679967][ T192] ? do_raw_spin_unlock+0x59/0x250 [ 9.679969][ T192] alloc_buf.isra.0+0x4b/0x260 [ 9.679973][ T192] put_chars+0x1e1/0x2f0 [ 9.679977][ T192] ? __send_to_port+0x420/0x420 [ 9.679978][ T192] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.679983][ T192] ? validate_chain+0x38b/0xc20 [ 9.679989][ T192] hvc_console_print+0x292/0x780 [ 9.679995][ T192] ? hvc_write+0x3a0/0x3a0 [ 9.679997][ T192] ? rcu_is_watching+0x16/0xd0 [ 9.680000][ T192] ? lock_acquire+0x13c/0x160 [ 9.680004][ T192] console_emit_next_record+0x22f/0x4c0 [ 9.680008][ T192] ? devkmsg_read+0x4b0/0x4b0 [ 9.680010][ T192] ? console_flush_one_record+0x106/0x710 [ 9.680013][ T192] ? rcu_is_watching+0x16/0xd0 [ 9.680016][ T192] ? lock_acquire+0x13c/0x160 [ 9.680020][ T192] console_flush_one_record+0x46f/0x710 [ 9.680024][ T192] ? console_emit_next_record+0x4c0/0x4c0 [ 9.680026][ T192] ? __lock_acquire+0x518/0xc20 [ 9.680031][ T192] console_unlock+0xee/0x1f0 [ 9.680034][ T192] ? console_flush_one_record+0x710/0x710 [ 9.680036][ T192] ? rcu_is_watching+0x16/0xd0 [ 9.680039][ T192] ? lock_acquire+0x60/0x160 [ 9.680042][ T192] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.680044][ T192] ? vprintk_emit+0x320/0x3e0 [ 9.680048][ T192] vprintk_emit+0x37c/0x3e0 [ 9.680052][ T192] ? wake_up_klogd_work_func+0x90/0x90 [ 9.680055][ T192] ? __lock_acquire+0x518/0xc20 [ 9.680059][ T192] _printk+0xc7/0x100 [ 9.680063][ T192] ? snapshot_read.cold+0x21/0x21 [ 9.680066][ T192] ? do_raw_spin_lock+0x131/0x280 [ 9.680068][ T192] ? __rwlock_init+0x150/0x150 [ 9.680072][ T192] ? do_raw_spin_lock+0x131/0x280 [ 9.680075][ T192] do_exit.cold+0x82/0x9c [ 9.680079][ T192] ? exit_notify+0x890/0x890 [ 9.680081][ T192] ? __lock_release.isra.0+0x69/0x1a0 [ 9.680083][ T192] ? rcu_is_watching+0x16/0xd0 [ 9.680088][ T192] do_group_exit+0xb8/0x370 [ 9.680091][ T192] __x64_sys_exit_group+0x3c/0x50 [ 9.680092][ T192] x64_sys_call+0x1567/0x1570 [ 9.680095][ T192] do_syscall_64+0xff/0x530 [ 9.680098][ T192] ? exc_page_fault+0xee/0x100 [ 9.680101][ T192] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.680103][ T192] RIP: 0033:0x7f7a079991b8 [ 9.680105][ T192] Code: Unable to access opcode bytes at 0x7f7a0799918e. [ 9.680106][ T192] RSP: 002b:00007ffdd078e8d8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 9.680109][ T192] RAX: ffffffffffffffda RBX: 00007f7a07ac9f88 RCX: 00007f7a079991b8 [ 9.680110][ T192] RDX: 00007f7a076e3fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 9.680111][ T192] RBP: 00007ffdd078e930 R08: 0000000000000000 R09: 0000000000008010 [ 9.680112][ T192] R10: 00007ffdd078e6f0 R11: 0000000000000246 R12: 0000000000000001 [ 9.680113][ T192] R13: 0000000000000000 R14: 00007f7a07ac8680 R15: 00007f7a07ac9fa0 [ 9.680120][ T192] [ 10.897734][ T260] br1: port 1(veth1) entered blocking state [ 10.897914][ T260] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.897916][ T260] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 260, name: ip [ 10.897918][ T260] preempt_count: 1, expected: 0 [ 10.897919][ T260] RCU nest depth: 0, expected: 0 [ 10.897920][ T260] locks held by ip/260: 5, last CPU#2: [ 10.897922][ T260] #0: ffffffff90ed2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 10.897935][ T260] #1: ffffffff90769cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.897941][ T260] #2: ffffffff90769d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.897945][ T260] #3: ffffffff90689660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.897949][ T260] #4: ffffffff90689560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.897954][ T260] irq event stamp: 15484 [ 10.897955][ T260] hardirqs last enabled at (15483): [] __down_trylock_console_sem+0x86/0xa0 [ 10.897958][ T260] hardirqs last disabled at (15484): [] console_emit_next_record+0x3d4/0x4c0 [ 10.897960][ T260] softirqs last enabled at (15394): [] __alloc_skb+0x4c2/0x5f0 [ 10.897963][ T260] softirqs last disabled at (15392): [] __alloc_skb+0x4c2/0x5f0 [ 10.897966][ T260] Preemption disabled at: [ 10.897966][ T260] [] vprintk_emit+0x31b/0x3e0 [ 10.897973][ T260] CPU: 2 UID: 0 PID: 260 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.897977][ T260] Tainted: [W]=WARN [ 10.897978][ T260] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.897980][ T260] Call Trace: [ 10.897981][ T260] [ 10.897983][ T260] dump_stack_lvl+0x6f/0xa0 [ 10.897989][ T260] ? vprintk_emit+0x31b/0x3e0 [ 10.897991][ T260] __might_resched.cold+0x1fe/0x2c1 [ 10.897996][ T260] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.898000][ T260] ? __kmalloc_noprof+0xdb/0x760 [ 10.898005][ T260] __kmalloc_noprof+0x443/0x760 [ 10.898007][ T260] ? alloc_buf.isra.0+0x4b/0x260 [ 10.898013][ T260] ? do_raw_spin_unlock+0x59/0x250 [ 10.898015][ T260] alloc_buf.isra.0+0x4b/0x260 [ 10.898019][ T260] put_chars+0x1e1/0x2f0 [ 10.898022][ T260] ? __send_to_port+0x420/0x420 [ 10.898026][ T260] ? validate_chain+0x34a/0xc20 [ 10.898029][ T260] hvc_console_print+0x292/0x780 [ 10.898032][ T260] ? mark_usage+0x61/0x170 [ 10.898034][ T260] ? __lock_acquire+0x518/0xc20 [ 10.898035][ T260] ? __lock_acquire+0x518/0xc20 [ 10.898039][ T260] ? hvc_write+0x3a0/0x3a0 [ 10.898041][ T260] ? console_emit_next_record+0x1df/0x4c0 [ 10.898044][ T260] ? rcu_is_watching+0x16/0xd0 [ 10.898049][ T260] ? lock_acquire+0x13c/0x160 [ 10.898052][ T260] console_emit_next_record+0x22f/0x4c0 [ 10.898056][ T260] ? devkmsg_read+0x4b0/0x4b0 [ 10.898058][ T260] ? console_flush_one_record+0x106/0x710 [ 10.898061][ T260] ? rcu_is_watching+0x16/0xd0 [ 10.898063][ T260] ? lock_acquire+0x13c/0x160 [ 10.898067][ T260] console_flush_one_record+0x46f/0x710 [ 10.898071][ T260] ? console_emit_next_record+0x4c0/0x4c0 [ 10.898073][ T260] ? __lock_acquire+0x518/0xc20 [ 10.898078][ T260] console_unlock+0xee/0x1f0 [ 10.898081][ T260] ? console_flush_one_record+0x710/0x710 [ 10.898083][ T260] ? rcu_is_watching+0x16/0xd0 [ 10.898085][ T260] ? lock_acquire+0x60/0x160 [ 10.898088][ T260] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.898090][ T260] ? vprintk_emit+0x320/0x3e0 [ 10.898093][ T260] vprintk_emit+0x37c/0x3e0 [ 10.898097][ T260] ? wake_up_klogd_work_func+0x90/0x90 [ 10.898100][ T260] ? __lock_release.isra.0+0x69/0x1a0 [ 10.898101][ T260] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 10.898106][ T260] ? mark_held_locks+0x40/0x70 [ 10.898109][ T260] _printk+0xc7/0x100 [ 10.898113][ T260] ? snapshot_read.cold+0x21/0x21 [ 10.898117][ T260] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 10.898129][ T260] ? do_setlink.isra.0+0xa31/0x2750 [ 10.898131][ T260] ? rtnl_newlink+0x9f1/0x11c0 [ 10.898132][ T260] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 10.898136][ T260] br_set_state+0x22f/0x430 [bridge] [ 10.898146][ T260] br_init_port+0xc4/0x200 [bridge] [ 10.898154][ T260] new_nbp+0x39c/0x580 [bridge] [ 10.898163][ T260] br_add_if+0x212/0x1320 [bridge] [ 10.898170][ T260] ? is_bpf_text_address+0x72/0x110 [ 10.898174][ T260] ? kernel_text_address+0x149/0x170 [ 10.898177][ T260] ? __kernel_text_address+0x12/0x30 [ 10.898181][ T260] do_set_master+0x357/0x580 [ 10.898186][ T260] do_setlink.isra.0+0xa31/0x2750 [ 10.898189][ T260] ? stack_trace_save+0x93/0xc0 [ 10.898192][ T260] ? rtnl_link_get_size+0x350/0x350 [ 10.898193][ T260] ? rcu_read_lock_any_held+0x66/0x90 [ 10.898196][ T260] ? stack_depot_save_flags+0x38e/0x790 [ 10.898199][ T260] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.898202][ T260] ? rcu_read_lock_any_held+0x3c/0x90 [ 10.898204][ T260] ? validate_chain+0x38b/0xc20 [ 10.898206][ T260] ? kasan_save_stack+0x3d/0x50 [ 10.898209][ T260] ? kasan_save_stack+0x2f/0x50 [ 10.898211][ T260] ? kasan_save_track+0x14/0x30 [ 10.898213][ T260] ? __lock_acquire+0x518/0xc20 [ 10.898215][ T260] ? netlink_seq_next+0x4e/0x60 [ 10.898218][ T260] ? ___sys_sendmsg+0xb0/0x1d0 [ 10.898223][ T260] ? lock_acquire.part.0+0xd4/0x280 [ 10.898225][ T260] ? rtnl_newlink+0x9a8/0x11c0 [ 10.898227][ T260] ? rcu_is_watching+0x16/0xd0 [ 10.898229][ T260] ? lock_acquire+0x13c/0x160 [ 10.898231][ T260] ? rcu_is_watching+0x16/0xd0 [ 10.898233][ T260] ? rcu_is_watching+0x16/0xd0 [ 10.898235][ T260] ? trace_contention_end+0xb3/0x180 [ 10.898238][ T260] ? __mutex_lock+0x1db/0x1ea0 [ 10.898240][ T260] ? __mutex_lock+0x9a3/0x1ea0 [ 10.898242][ T260] ? rtnl_newlink+0x9a8/0x11c0 [ 10.898245][ T260] ? ww_mutex_lock+0x160/0x160 [ 10.898247][ T260] ? nla_get_range_signed+0x3d0/0x3d0 [ 10.898252][ T260] ? __rtnl_newlink+0x3fa/0xa50 [ 10.898257][ T260] rtnl_newlink+0x9f1/0x11c0 [ 10.898262][ T260] ? rtnl_bridge_getlink+0x850/0x850 [ 10.898264][ T260] ? __lock_acquire+0x518/0xc20 [ 10.898269][ T260] ? lock_acquire.part.0+0xd4/0x280 [ 10.898271][ T260] ? find_held_lock+0x2b/0x80 [ 10.898273][ T260] ? rtnl_bridge_getlink+0x850/0x850 [ 10.898274][ T260] ? __lock_release.isra.0+0x69/0x1a0 [ 10.898278][ T260] ? rtnl_bridge_getlink+0x850/0x850 [ 10.898280][ T260] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 10.898283][ T260] ? rtnl_link_fill+0x920/0x920 [ 10.898284][ T260] ? __lock_acquire+0x518/0xc20 [ 10.898289][ T260] ? lock_acquire.part.0+0xd4/0x280 [ 10.898290][ T260] ? find_held_lock+0x2b/0x80 [ 10.898294][ T260] netlink_rcv_skb+0x14e/0x3a0 [ 10.898296][ T260] ? rtnl_link_fill+0x920/0x920 [ 10.898299][ T260] ? netlink_ack+0xcf0/0xcf0 [ 10.898305][ T260] ? netlink_deliver_tap+0xc5/0x330 [ 10.898306][ T260] ? netlink_deliver_tap+0x13c/0x330 [ 10.898310][ T260] netlink_unicast+0x486/0x750 [ 10.898314][ T260] ? netlink_attachskb+0x810/0x810 [ 10.898317][ T260] ? __lock_acquire+0x518/0xc20 [ 10.898321][ T260] netlink_sendmsg+0x735/0xc60 [ 10.898324][ T260] ? netlink_unicast+0x750/0x750 [ 10.898328][ T260] ? __might_fault+0x97/0x140 [ 10.898333][ T260] ____sys_sendmsg+0x415/0x880 [ 10.898336][ T260] ? copy_msghdr_from_user+0x279/0x420 [ 10.898338][ T260] ? get_timestamp.constprop.0+0x390/0x390 [ 10.898339][ T260] ? move_addr_to_kernel+0x40/0x40 [ 10.898347][ T260] ___sys_sendmsg+0x14e/0x1d0 [ 10.898350][ T260] ? copy_msghdr_from_user+0x420/0x420 [ 10.898365][ T260] __sys_sendmsg+0x12c/0x1d0 [ 10.898368][ T260] ? __sys_sendmsg_sock+0x20/0x20 [ 10.898374][ T260] ? rcu_is_watching+0x16/0xd0 [ 10.898378][ T260] do_syscall_64+0xff/0x530 [ 10.898380][ T260] ? exc_page_fault+0xee/0x100 [ 10.898383][ T260] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.898386][ T260] RIP: 0033:0x7f22794ba54e [ 10.898389][ T260] 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 [ 10.898391][ T260] RSP: 002b:00007fffef80dab0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 10.898393][ T260] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f22794ba54e [ 10.898395][ T260] RDX: 0000000000000000 RSI: 00007fffef80db60 RDI: 0000000000000005 [ 10.898396][ T260] RBP: 00007fffef80dac0 R08: 0000000000000000 R09: 0000000000000000 [ 10.898397][ T260] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a910dd5 [ 10.898397][ T260] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fffef80e220 [ 10.898404][ T260] [ 10.944610][ T260] br1: port 1(veth1) entered disabled state [ 10.945547][ T260] veth1: entered allmulticast mode [ 10.947910][ T260] veth1: entered promiscuous mode [ 10.967491][ T260] ip (260) used greatest stack depth: 23336 bytes left [ 11.056749][ T100] br1: port 1(veth1) entered blocking state [ 11.057375][ T100] br1: port 1(veth1) entered forwarding state [ 11.109513][ T273] br1: port 2(veth2) entered blocking state [ 11.109888][ T273] br1: port 2(veth2) entered disabled state [ 11.110275][ T273] veth2: entered allmulticast mode [ 11.112509][ T273] veth2: entered promiscuous mode [ 11.220898][ T58] br1: port 2(veth2) entered blocking state [ 11.221424][ T58] br1: port 2(veth2) entered forwarding state [ 26.481047][ C3] [ 26.481065][ C3] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 26.481067][ C3] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/3 [ 26.481069][ C3] preempt_count: 104, expected: 0 [ 26.481071][ C3] RCU nest depth: 0, expected: 0 [ 26.481072][ C3] INFO: lockdep is turned off. [ 26.481073][ C3] irq event stamp: 998202 [ 26.481075][ C3] hardirqs last enabled at (998202): [] _raw_spin_unlock_irq+0x28/0x50 [ 26.481085][ C3] hardirqs last disabled at (998201): [] _raw_spin_lock_irq+0x4a/0x50 [ 26.481087][ C3] softirqs last enabled at (998174): [] handle_softirqs+0x67c/0x900 [ 26.481092][ C3] softirqs last disabled at (998199): [] __irq_exit_rcu+0x145/0x1c0 [ 26.481095][ C3] Preemption disabled at: [ 26.481095][ C3] [<0000000000000000>] 0x0 [ 26.481103][ C3] CPU: 3 UID: 0 PID: 0 Comm: swapper/3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.481108][ C3] Tainted: [W]=WARN [ 26.481109][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.481111][ C3] Call Trace: [ 26.481113][ C3] [ 26.481115][ C3] dump_stack_lvl+0x6f/0xa0 [ 26.481122][ C3] __might_resched.cold+0x1fe/0x2c1 [ 26.481126][ C3] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 26.481131][ C3] ? __asan_memcpy+0x3c/0x60 [ 26.481134][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.481139][ C3] __kmalloc_noprof+0x443/0x760 [ 26.481143][ C3] ? __rwlock_init+0x150/0x150 [ 26.481146][ C3] ? alloc_buf.isra.0+0x4b/0x260 [ 26.481151][ C3] ? do_raw_spin_unlock+0x59/0x250 [ 26.481153][ C3] alloc_buf.isra.0+0x4b/0x260 [ 26.481156][ C3] put_chars+0x1e1/0x2f0 [ 26.481159][ C3] ? __send_to_port+0x420/0x420 [ 26.481161][ C3] ? console_prepend_replay+0x20/0x20 [ 26.481166][ C3] hvc_console_print+0x292/0x780 [ 26.481171][ C3] ? hvc_write+0x3a0/0x3a0 [ 26.481173][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.481176][ C3] ? lock_acquire+0x13c/0x160 [ 26.481179][ C3] console_emit_next_record+0x22f/0x4c0 [ 26.481182][ C3] ? devkmsg_read+0x4b0/0x4b0 [ 26.481185][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.481188][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.481190][ C3] ? lock_acquire+0x13c/0x160 [ 26.481193][ C3] ? console_flush_one_record+0x111/0x710 [ 26.481195][ C3] console_flush_one_record+0x46f/0x710 [ 26.481198][ C3] ? console_emit_next_record+0x4c0/0x4c0 [ 26.481202][ C3] console_unlock+0xee/0x1f0 [ 26.481205][ C3] ? lock_acquire+0x13c/0x160 [ 26.481207][ C3] ? console_flush_one_record+0x710/0x710 [ 26.481209][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.481212][ C3] ? lock_release+0x184/0x1f0 [ 26.481214][ C3] ? lock_acquire+0x60/0x160 [ 26.481217][ C3] ? __down_trylock_console_sem+0x5e/0xa0 [ 26.481219][ C3] ? vprintk_emit+0x320/0x3e0 [ 26.481222][ C3] vprintk_emit+0x37c/0x3e0 [ 26.481225][ C3] ? wake_up_klogd_work_func+0x90/0x90 [ 26.481228][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.481245][ C3] ? lock_release+0x184/0x1f0 [ 26.481248][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.481259][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.481269][ C3] ? is_module_text_address+0x154/0x250 [ 26.481274][ C3] _printk+0xc7/0x100 [ 26.481277][ C3] ? snapshot_read.cold+0x21/0x21 [ 26.481280][ C3] ? arch_stack_walk+0xd7/0x130 [ 26.481285][ C3] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 26.481288][ C3] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 26.481291][ C3] print_irq_inversion_bug.part.0+0x32/0xc0 [ 26.481295][ C3] mark_lock_irq+0x989/0x9c0 [ 26.481299][ C3] mark_lock+0x1d7/0xa00 [ 26.481302][ C3] mark_usage+0x42/0x170 [ 26.481304][ C3] __lock_acquire+0x388/0xc20 [ 26.481307][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.481310][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.481321][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.481324][ C3] ? lock_acquire+0x13c/0x160 [ 26.481326][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.481337][ C3] _raw_spin_lock+0x33/0x40 [ 26.481340][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.481351][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.481362][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.481373][ C3] call_timer_fn+0x160/0x4d0 [ 26.481377][ C3] ? detach_if_pending+0x1d0/0x1d0 [ 26.481379][ C3] ? debug_object_active_state+0x430/0x430 [ 26.481383][ C3] ? find_held_lock+0x2b/0x80 [ 26.481386][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 26.481389][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.481392][ C3] __run_timers+0x68f/0xaa0 [ 26.481395][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.481407][ C3] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.481409][ C3] ? __lock_acquire+0x518/0xc20 [ 26.481414][ C3] ? __rwlock_init+0x150/0x150 [ 26.481417][ C3] run_timer_softirq+0xf0/0x160 [ 26.481420][ C3] ? __run_timers+0xaa0/0xaa0 [ 26.481423][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.481426][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.481428][ C3] handle_softirqs+0x1d3/0x900 [ 26.481431][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 26.481434][ C3] ? _local_bh_enable+0xc0/0xc0 [ 26.481437][ C3] __irq_exit_rcu+0x145/0x1c0 [ 26.481439][ C3] irq_exit_rcu+0xe/0x30 [ 26.481442][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.481445][ C3] [ 26.481446][ C3] [ 26.481448][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.481451][ C3] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.481455][ C3] 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 [ 26.481457][ C3] RSP: 0018:ffa0000000167e00 EFLAGS: 00000282 [ 26.481461][ C3] RAX: 00000000000f3b35 RBX: ff11000001c88040 RCX: ffffffff8d4f0307 [ 26.481463][ C3] RDX: ff11000001c88040 RSI: ffffffff90238b01 RDI: ffffffff8fc8d8e0 [ 26.481465][ C3] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.481466][ C3] R10: 0000000000000003 R11: 0000000000000001 R12: 1ff400000002cfc3 [ 26.481468][ C3] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 26.481470][ C3] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.481475][ C3] default_idle+0x9/0x10 [ 26.481477][ C3] default_idle_call+0x6e/0xb0 [ 26.481480][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.481482][ C3] ? arch_cpu_idle_exit+0x40/0x40 [ 26.481485][ C3] ? mark_tsc_async_resets+0x30/0x30 [ 26.481488][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.481490][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.481493][ C3] do_idle+0xd8/0x190 [ 26.481496][ C3] cpu_startup_entry+0x53/0x70 [ 26.481498][ C3] start_secondary+0x204/0x2b0 [ 26.481500][ C3] ? set_cpu_sibling_map+0x2130/0x2130 [ 26.481504][ C3] common_startup_64+0x13e/0x148 [ 26.481509][ C3] [ 26.516261][ C3] ======================================================== [ 26.516645][ C3] WARNING: possible irq lock inversion dependency detected [ 26.516954][ C3] 7.2.0-virtme #1 Tainted: G W [ 26.517279][ C3] -------------------------------------------------------- [ 26.517648][ C3] swapper/3/0 just changed the state of lock: [ 26.517902][ C3] ff1100000c48ae58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.518388][ C3] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 26.518751][ C3] (fs_reclaim){+.+.}-{0:0} [ 26.518756][ C3] [ 26.518756][ C3] [ 26.518756][ C3] and interrupts could create inverse lock ordering between them. [ 26.518756][ C3] [ 26.519622][ C3] [ 26.519622][ C3] other info that might help us debug this: [ 26.519998][ C3] Chain exists of: [ 26.519998][ C3] &br->lock --> console_owner --> fs_reclaim [ 26.519998][ C3] [ 26.520529][ C3] Possible interrupt unsafe locking scenario: [ 26.520529][ C3] [ 26.520903][ C3] CPU0 CPU1 [ 26.521109][ C3] ---- ---- [ 26.521390][ C3] lock(fs_reclaim); [ 26.521545][ C3] local_irq_disable(); [ 26.521875][ C3] lock(&br->lock); [ 26.522137][ C3] lock(console_owner); [ 26.522466][ C3] [ 26.522625][ C3] lock(&br->lock); [ 26.522851][ C3] [ 26.522851][ C3] *** DEADLOCK *** [ 26.522851][ C3] [ 26.523152][ C3] locks held by swapper/3/0: 1, last CPU#3: [ 26.523478][ C3] #0: ffa0000000280c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 26.523974][ C3] [ 26.523974][ C3] the shortest dependencies between 2nd lock and 1st lock: [ 26.524388][ C3] -> (fs_reclaim){+.+.}-{0:0} { [ 26.524601][ C3] HARDIRQ-ON-W at: [ 26.524821][ C3] __lock_acquire+0x388/0xc20 [ 26.525090][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.525423][ C3] fs_reclaim_acquire+0xd5/0x120 [ 26.525758][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 26.526071][ C3] kthread_create_worker_on_node+0xea/0x210 [ 26.526458][ C3] workqueue_init+0x2a/0x680 [ 26.526788][ C3] kernel_init_freeable+0x2fe/0x630 [ 26.527090][ C3] kernel_init+0x21/0x150 [ 26.527411][ C3] ret_from_fork+0x474/0x6b0 [ 26.527736][ C3] ret_from_fork_asm+0x11/0x20 [ 26.527999][ C3] SOFTIRQ-ON-W at: [ 26.528152][ C3] __lock_acquire+0x388/0xc20 [ 26.528407][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.528730][ C3] fs_reclaim_acquire+0xd5/0x120 [ 26.529064][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 26.529375][ C3] kthread_create_worker_on_node+0xea/0x210 [ 26.529750][ C3] workqueue_init+0x2a/0x680 [ 26.530038][ C3] kernel_init_freeable+0x2fe/0x630 [ 26.530241][ C3] kernel_init+0x21/0x150 [ 26.530456][ C3] ret_from_fork+0x474/0x6b0 [ 26.530670][ C3] ret_from_fork_asm+0x11/0x20 [ 26.530838][ C3] INITIAL USE at: [ 26.530997][ C3] __lock_acquire+0x388/0xc20 [ 26.531164][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.531374][ C3] fs_reclaim_acquire+0xd5/0x120 [ 26.531586][ C3] __kmalloc_cache_noprof+0x6e/0x620 [ 26.531794][ C3] kthread_create_worker_on_node+0xea/0x210 [ 26.532045][ C3] workqueue_init+0x2a/0x680 [ 26.532258][ C3] kernel_init_freeable+0x2fe/0x630 [ 26.532427][ C3] kernel_init+0x21/0x150 [ 26.532642][ C3] ret_from_fork+0x474/0x6b0 [ 26.532858][ C3] ret_from_fork_asm+0x11/0x20 [ 26.533034][ C3] } [ 26.533108][ C3] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 26.533353][ C3] ... acquired at: [ 26.533497][ C3] __lock_acquire+0x518/0xc20 [ 26.533634][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.533815][ C3] fs_reclaim_acquire+0xd5/0x120 [ 26.533949][ C3] __kmalloc_noprof+0xd3/0x760 [ 26.534130][ C3] alloc_buf.isra.0+0x4b/0x260 [ 26.534265][ C3] put_chars+0x1e1/0x2f0 [ 26.534444][ C3] hvc_console_print+0x292/0x780 [ 26.534578][ C3] console_emit_next_record+0x22f/0x4c0 [ 26.534755][ C3] console_flush_one_record+0x46f/0x710 [ 26.534890][ C3] console_unlock+0xee/0x1f0 [ 26.535079][ C3] vprintk_emit+0x37c/0x3e0 [ 26.535213][ C3] _printk+0xc7/0x100 [ 26.535315][ C3] dm_interface_init+0x50/0x60 [ 26.535493][ C3] dm_init+0x51/0xd0 [ 26.535595][ C3] do_one_initcall+0x124/0x4f0 [ 26.535770][ C3] kernel_init_freeable+0x596/0x630 [ 26.535905][ C3] kernel_init+0x21/0x150 [ 26.536096][ C3] ret_from_fork+0x474/0x6b0 [ 26.536232][ C3] ret_from_fork_asm+0x11/0x20 [ 26.536409][ C3] [ 26.536479][ C3] -> (console_owner){....}-{0:0} { [ 26.536666][ C3] INITIAL USE at: [ 26.536767][ C3] } [ 26.536837][ C3] ... key at: [] console_owner_dep_map+0x0/0x60 [ 26.537088][ C3] ... acquired at: [ 26.537191][ C3] __lock_acquire+0x518/0xc20 [ 26.537421][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.537560][ C3] console_lock_spinning_enable+0x5c/0x60 [ 26.537729][ C3] console_emit_next_record+0x1d1/0x4c0 [ 26.537867][ C3] console_flush_one_record+0x46f/0x710 [ 26.538054][ C3] console_unlock+0xee/0x1f0 [ 26.538193][ C3] vprintk_emit+0x37c/0x3e0 [ 26.538378][ C3] _printk+0xc7/0x100 [ 26.538482][ C3] br_set_state+0x22f/0x430 [bridge] [ 26.538673][ C3] br_init_port+0xc4/0x200 [bridge] [ 26.538820][ C3] br_stp_enable_port+0x12/0x50 [bridge] [ 26.539046][ C3] br_port_carrier_check+0x220/0x430 [bridge] [ 26.539269][ C3] br_device_event+0x52d/0x8f0 [bridge] [ 26.539414][ C3] notifier_call_chain+0xae/0x300 [ 26.539597][ C3] netif_state_change+0x139/0x340 [ 26.539735][ C3] __linkwatch_run_queue+0x34c/0x750 [ 26.539921][ C3] linkwatch_event+0x7f/0xb0 [ 26.540066][ C3] process_one_work+0xe3e/0x1560 [ 26.540248][ C3] worker_thread+0x4f1/0xd60 [ 26.540386][ C3] kthread+0x367/0x460 [ 26.540490][ C3] ret_from_fork+0x474/0x6b0 [ 26.540622][ C3] ret_from_fork_asm+0x11/0x20 [ 26.540755][ C3] [ 26.540868][ C3] -> (&br->lock){+.-.}-{3:3} { [ 26.541020][ C3] HARDIRQ-ON-W at: [ 26.541123][ C3] __lock_acquire+0x388/0xc20 [ 26.541296][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.541470][ C3] _raw_spin_lock_bh+0x38/0x50 [ 26.541647][ C3] br_set_ageing_time+0x2d/0xf0 [bridge] [ 26.541859][ C3] br_changelink+0x240/0x16e0 [bridge] [ 26.542042][ C3] br_dev_newlink+0xeb/0x160 [bridge] [ 26.542220][ C3] rtnl_newlink_create+0x2d0/0x750 [ 26.542393][ C3] __rtnl_newlink+0x22b/0xa50 [ 26.542756][ C3] rtnl_newlink+0x9f1/0x11c0 [ 26.542932][ C3] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.543106][ C3] netlink_rcv_skb+0x14e/0x3a0 [ 26.543325][ C3] netlink_unicast+0x486/0x750 [ 26.543499][ C3] netlink_sendmsg+0x735/0xc60 [ 26.543672][ C3] ____sys_sendmsg+0x415/0x880 [ 26.543845][ C3] ___sys_sendmsg+0x14e/0x1d0 [ 26.544023][ C3] __sys_sendmsg+0x12c/0x1d0 [ 26.544194][ C3] do_syscall_64+0xff/0x530 [ 26.544371][ C3] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.544581][ C3] IN-SOFTIRQ-W at: [ 26.544689][ C3] __lock_acquire+0x388/0xc20 [ 26.544870][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.545052][ C3] _raw_spin_lock+0x33/0x40 [ 26.545227][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.545444][ C3] call_timer_fn+0x160/0x4d0 [ 26.545621][ C3] __run_timers+0x68f/0xaa0 [ 26.545806][ C3] run_timer_softirq+0xf0/0x160 [ 26.545992][ C3] handle_softirqs+0x1d3/0x900 [ 26.546169][ C3] __irq_exit_rcu+0x145/0x1c0 [ 26.546340][ C3] irq_exit_rcu+0xe/0x30 [ 26.546676][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.546882][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.547105][ C3] pv_native_safe_halt+0xf/0x10 [ 26.547322][ C3] default_idle+0x9/0x10 [ 26.547538][ C3] default_idle_call+0x6e/0xb0 [ 26.547710][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.547974][ C3] do_idle+0xd8/0x190 [ 26.548111][ C3] cpu_startup_entry+0x53/0x70 [ 26.548324][ C3] start_secondary+0x204/0x2b0 [ 26.548543][ C3] common_startup_64+0x13e/0x148 [ 26.548711][ C3] INITIAL USE at: [ 26.548857][ C3] __lock_acquire+0x388/0xc20 [ 26.549031][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.549201][ C3] _raw_spin_lock_bh+0x38/0x50 [ 26.549370][ C3] br_set_ageing_time+0x2d/0xf0 [bridge] [ 26.549579][ C3] br_changelink+0x240/0x16e0 [bridge] [ 26.549752][ C3] br_dev_newlink+0xeb/0x160 [bridge] [ 26.549928][ C3] rtnl_newlink_create+0x2d0/0x750 [ 26.550101][ C3] __rtnl_newlink+0x22b/0xa50 [ 26.550271][ C3] rtnl_newlink+0x9f1/0x11c0 [ 26.550439][ C3] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.550607][ C3] netlink_rcv_skb+0x14e/0x3a0 [ 26.550779][ C3] netlink_unicast+0x486/0x750 [ 26.550952][ C3] netlink_sendmsg+0x735/0xc60 [ 26.551123][ C3] ____sys_sendmsg+0x415/0x880 [ 26.551294][ C3] ___sys_sendmsg+0x14e/0x1d0 [ 26.551464][ C3] __sys_sendmsg+0x12c/0x1d0 [ 26.551634][ C3] do_syscall_64+0xff/0x530 [ 26.551804][ C3] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.552012][ C3] } [ 26.552083][ C3] ... key at: [] __key.7+0x0/0x40 [bridge] [ 26.552286][ C3] ... acquired at: [ 26.552387][ C3] mark_lock+0x1d7/0xa00 [ 26.552519][ C3] mark_usage+0x42/0x170 [ 26.552652][ C3] __lock_acquire+0x388/0xc20 [ 26.552784][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.552918][ C3] _raw_spin_lock+0x33/0x40 [ 26.553060][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.553227][ C3] call_timer_fn+0x160/0x4d0 [ 26.553361][ C3] __run_timers+0x68f/0xaa0 [ 26.553495][ C3] run_timer_softirq+0xf0/0x160 [ 26.553627][ C3] handle_softirqs+0x1d3/0x900 [ 26.553762][ C3] __irq_exit_rcu+0x145/0x1c0 [ 26.553895][ C3] irq_exit_rcu+0xe/0x30 [ 26.554271][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.554445][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.554611][ C3] pv_native_safe_halt+0xf/0x10 [ 26.554747][ C3] default_idle+0x9/0x10 [ 26.554931][ C3] default_idle_call+0x6e/0xb0 [ 26.555066][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.555233][ C3] do_idle+0xd8/0x190 [ 26.555334][ C3] cpu_startup_entry+0x53/0x70 [ 26.555468][ C3] start_secondary+0x204/0x2b0 [ 26.555600][ C3] common_startup_64+0x13e/0x148 [ 26.555734][ C3] [ 26.555802][ C3] [ 26.555802][ C3] stack backtrace: [ 26.555975][ C3] CPU: 3 UID: 0 PID: 0 Comm: swapper/3 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.555979][ C3] Tainted: [W]=WARN [ 26.555980][ C3] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.555982][ C3] Call Trace: [ 26.555983][ C3] [ 26.555984][ C3] dump_stack_lvl+0x6f/0xa0 [ 26.555989][ C3] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 26.555992][ C3] mark_lock_irq+0x989/0x9c0 [ 26.555994][ C3] mark_lock+0x1d7/0xa00 [ 26.555996][ C3] mark_usage+0x42/0x170 [ 26.555998][ C3] __lock_acquire+0x388/0xc20 [ 26.556000][ C3] lock_acquire.part.0+0xd4/0x280 [ 26.556002][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.556009][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.556012][ C3] ? lock_acquire+0x13c/0x160 [ 26.556014][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.556020][ C3] _raw_spin_lock+0x33/0x40 [ 26.556022][ C3] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.556028][ C3] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.556034][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.556040][ C3] call_timer_fn+0x160/0x4d0 [ 26.556042][ C3] ? detach_if_pending+0x1d0/0x1d0 [ 26.556043][ C3] ? debug_object_active_state+0x430/0x430 [ 26.556047][ C3] ? find_held_lock+0x2b/0x80 [ 26.556049][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 26.556051][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.556053][ C3] __run_timers+0x68f/0xaa0 [ 26.556055][ C3] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.556061][ C3] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.556063][ C3] ? __lock_acquire+0x518/0xc20 [ 26.556066][ C3] ? __rwlock_init+0x150/0x150 [ 26.556068][ C3] run_timer_softirq+0xf0/0x160 [ 26.556070][ C3] ? __run_timers+0xaa0/0xaa0 [ 26.556072][ C3] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.556074][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.556076][ C3] handle_softirqs+0x1d3/0x900 [ 26.556078][ C3] ? __lock_release.isra.0+0x69/0x1a0 [ 26.556079][ C3] ? _local_bh_enable+0xc0/0xc0 [ 26.556081][ C3] __irq_exit_rcu+0x145/0x1c0 [ 26.556083][ C3] irq_exit_rcu+0xe/0x30 [ 26.556084][ C3] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.556086][ C3] [ 26.556086][ C3] [ 26.556087][ C3] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.556089][ C3] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.556091][ C3] 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 [ 26.556093][ C3] RSP: 0018:ffa0000000167e00 EFLAGS: 00000282 [ 26.556096][ C3] RAX: 00000000000f3b35 RBX: ff11000001c88040 RCX: ffffffff8d4f0307 [ 26.556097][ C3] RDX: ff11000001c88040 RSI: ffffffff90238b01 RDI: ffffffff8fc8d8e0 [ 26.556098][ C3] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.556099][ C3] R10: 0000000000000003 R11: 0000000000000001 R12: 1ff400000002cfc3 [ 26.556100][ C3] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 26.556101][ C3] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.556103][ C3] default_idle+0x9/0x10 [ 26.556105][ C3] default_idle_call+0x6e/0xb0 [ 26.556107][ C3] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.556108][ C3] ? arch_cpu_idle_exit+0x40/0x40 [ 26.556110][ C3] ? mark_tsc_async_resets+0x30/0x30 [ 26.556111][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.556113][ C3] ? rcu_is_watching+0x16/0xd0 [ 26.556115][ C3] do_idle+0xd8/0x190 [ 26.556116][ C3] cpu_startup_entry+0x53/0x70 [ 26.556118][ C3] start_secondary+0x204/0x2b0 [ 26.556119][ C3] ? set_cpu_sibling_map+0x2130/0x2130 [ 26.556121][ C3] common_startup_64+0x13e/0x148 [ 26.556124][ C3] [ 29.277815][ T727] br1: port 2(veth2) entered disabled state [ 29.277833][ T727] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 29.277836][ T727] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 727, name: ip [ 29.277837][ T727] preempt_count: 202, expected: 0 [ 29.277838][ T727] RCU nest depth: 0, expected: 0 [ 29.277839][ T727] INFO: lockdep is turned off. [ 29.277840][ T727] irq event stamp: 0 [ 29.277841][ T727] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 29.277846][ T727] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 29.277853][ T727] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 29.277854][ T727] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 29.277856][ T727] Preemption disabled at: [ 29.277857][ T727] [<0000000000000000>] 0x0 [ 29.277862][ T727] CPU: 3 UID: 0 PID: 727 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 29.277866][ T727] Tainted: [W]=WARN [ 29.277867][ T727] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 29.277868][ T727] Call Trace: [ 29.277870][ T727] [ 29.277871][ T727] dump_stack_lvl+0x6f/0xa0 [ 29.277876][ T727] __might_resched.cold+0x1fe/0x2c1 [ 29.277880][ T727] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 29.277884][ T727] ? rcu_is_watching+0x16/0xd0 [ 29.277888][ T727] __kmalloc_noprof+0x443/0x760 [ 29.277891][ T727] ? __rwlock_init+0x150/0x150 [ 29.277894][ T727] ? alloc_buf.isra.0+0x4b/0x260 [ 29.277898][ T727] ? do_raw_spin_unlock+0x59/0x250 [ 29.277899][ T727] alloc_buf.isra.0+0x4b/0x260 [ 29.277901][ T727] put_chars+0x1e1/0x2f0 [ 29.277903][ T727] ? __send_to_port+0x420/0x420 [ 29.277905][ T727] ? printk_get_next_message+0x240/0x650 [ 29.277908][ T727] hvc_console_print+0x292/0x780 [ 29.277912][ T727] ? hvc_write+0x3a0/0x3a0 [ 29.277914][ T727] ? __rwlock_init+0x150/0x150 [ 29.277915][ T727] ? rcu_is_watching+0x16/0xd0 [ 29.277917][ T727] ? lock_acquire+0x13c/0x160 [ 29.277919][ T727] console_emit_next_record+0x22f/0x4c0 [ 29.277922][ T727] ? devkmsg_read+0x4b0/0x4b0 [ 29.277924][ T727] ? rcu_is_watching+0x16/0xd0 [ 29.277925][ T727] ? rcu_is_watching+0x16/0xd0 [ 29.277927][ T727] ? rcu_is_watching+0x16/0xd0 [ 29.277928][ T727] ? lock_acquire+0x13c/0x160 [ 29.277930][ T727] ? console_flush_one_record+0x111/0x710 [ 29.277939][ T727] console_flush_one_record+0x46f/0x710 [ 29.277941][ T727] ? console_emit_next_record+0x4c0/0x4c0 [ 29.277944][ T727] console_unlock+0xee/0x1f0 [ 29.277946][ T727] ? lock_acquire+0x13c/0x160 [ 29.277948][ T727] ? console_flush_one_record+0x710/0x710 [ 29.277949][ T727] ? rcu_is_watching+0x16/0xd0 [ 29.277951][ T727] ? lock_release+0x184/0x1f0 [ 29.277954][ T727] ? lock_acquire+0x60/0x160 [ 29.277956][ T727] ? __rcu_read_lock+0x30/0x70 [ 29.277958][ T727] ? __down_trylock_console_sem+0x5e/0xa0 [ 29.277960][ T727] ? vprintk_emit+0x320/0x3e0 [ 29.277962][ T727] vprintk_emit+0x37c/0x3e0 [ 29.277964][ T727] ? wake_up_klogd_work_func+0x90/0x90 [ 29.277966][ T727] ? rcu_is_watching+0x16/0xd0 [ 29.277968][ T727] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 29.277970][ T727] ? trace_hardirqs_on+0x36/0x40 [ 29.277972][ T727] _printk+0xc7/0x100 [ 29.277975][ T727] ? snapshot_read.cold+0x21/0x21 [ 29.277978][ T727] ? ipv6_mc_down+0x17e/0x2d0 [ 29.277983][ T727] br_set_state+0x22f/0x430 [bridge] [ 29.277996][ T727] br_stp_disable_port+0x11f/0x390 [bridge] [ 29.278003][ T727] br_device_event+0x7b5/0x8f0 [bridge] [ 29.278012][ T727] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 29.278015][ T727] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 29.278017][ T727] ? addrconf_notify+0x2c8/0xf30 [ 29.278020][ T727] notifier_call_chain+0xae/0x300 [ 29.278023][ T727] __dev_notify_flags+0x23c/0x3c0 [ 29.278027][ T727] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 29.278029][ T727] ? netif_change_name+0x8c0/0x8c0 [ 29.278031][ T727] ? netif_set_allmulti+0x250/0x250 [ 29.278033][ T727] ? __kernel_text_address+0x12/0x30 [ 29.278035][ T727] ? write_profile+0xf0/0xf0 [ 29.278037][ T727] ? unwind_get_return_address+0x67/0xd0 [ 29.278040][ T727] netif_change_flags+0xfe/0x190 [ 29.278042][ T727] do_setlink.isra.0+0x1cc3/0x2750 [ 29.278045][ T727] ? stack_trace_save+0x93/0xc0 [ 29.278046][ T727] ? rtnl_link_get_size+0x350/0x350 [ 29.278048][ T727] ? rcu_is_watching+0x16/0xd0 [ 29.278049][ T727] ? stack_depot_save_flags+0x1bc/0x790 [ 29.278053][ T727] ? kasan_save_stack+0x3d/0x50 [ 29.278056][ T727] ? kasan_save_stack+0x2f/0x50 [ 29.278057][ T727] ? kasan_save_track+0x14/0x30 [ 29.278059][ T727] ? __kasan_kmalloc+0x7b/0x90 [ 29.278060][ T727] ? __kmalloc_cache_noprof+0x29a/0x620 [ 29.278062][ T727] ? rtnl_newlink+0x124/0x11c0 [ 29.278063][ T727] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 29.278064][ T727] ? netlink_rcv_skb+0x14e/0x3a0 [ 29.278067][ T727] ? netlink_unicast+0x486/0x750 [ 29.278068][ T727] ? netlink_sendmsg+0x735/0xc60 [ 29.278070][ T727] ? ____sys_sendmsg+0x415/0x880 [ 29.278072][ T727] ? ___sys_sendmsg+0x14e/0x1d0 [ 29.278074][ T727] ? __sys_sendmsg+0x12c/0x1d0 [ 29.278075][ T727] ? do_syscall_64+0xff/0x530 [ 29.278077][ T727] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.278080][ T727] ? rcu_is_watching+0x16/0xd0 [ 29.278081][ T727] ? lock_acquire+0x13c/0x160 [ 29.278083][ T727] ? rcu_is_watching+0x16/0xd0 [ 29.278084][ T727] ? rcu_is_watching+0x16/0xd0 [ 29.278086][ T727] ? trace_contention_end+0xb3/0x180 [ 29.278089][ T727] ? __mutex_lock+0x1db/0x1ea0 [ 29.278091][ T727] ? __mutex_lock+0x9a3/0x1ea0 [ 29.278092][ T727] ? rtnl_newlink+0x9a8/0x11c0 [ 29.278094][ T727] ? ww_mutex_lock+0x160/0x160 [ 29.278096][ T727] ? __rtnl_newlink+0x3fa/0xa50 [ 29.278098][ T727] rtnl_newlink+0x9f1/0x11c0 [ 29.278100][ T727] ? rcu_is_watching+0x16/0xd0 [ 29.278101][ T727] ? rtnl_bridge_getlink+0x850/0x850 [ 29.278102][ T727] ? lock_acquire+0x13c/0x160 [ 29.278104][ T727] ? rcu_is_watching+0x16/0xd0 [ 29.278106][ T727] ? is_bpf_text_address+0x3e/0x110 [ 29.278109][ T727] ? is_bpf_text_address+0x72/0x110 [ 29.278111][ T727] ? kernel_text_address+0x149/0x170 [ 29.278112][ T727] ? __kernel_text_address+0x12/0x30 [ 29.278113][ T727] ? write_profile+0xf0/0xf0 [ 29.278114][ T727] ? unwind_get_return_address+0x67/0xd0 [ 29.278116][ T727] ? rcu_is_watching+0x16/0xd0 [ 29.278117][ T727] ? rcu_is_watching+0x16/0xd0 [ 29.278119][ T727] ? cap_capable+0x1d7/0x3d0 [ 29.278122][ T727] ? lock_acquire+0x13c/0x160 [ 29.278124][ T727] ? rcu_is_watching+0x16/0xd0 [ 29.278125][ T727] ? rtnl_bridge_getlink+0x850/0x850 [ 29.278127][ T727] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 29.278128][ T727] ? rtnl_link_fill+0x920/0x920 [ 29.278130][ T727] ? filter_irq_stacks+0xd0/0xd0 [ 29.278131][ T727] ? stack_depot_save_flags+0x1bc/0x790 [ 29.278133][ T727] netlink_rcv_skb+0x14e/0x3a0 [ 29.278135][ T727] ? rtnl_link_fill+0x920/0x920 [ 29.278136][ T727] ? lock_release+0x184/0x1f0 [ 29.278137][ T727] ? netlink_ack+0xcf0/0xcf0 [ 29.278139][ T727] ? __netlink_lookup+0xb9/0x6b0 [ 29.278141][ T727] ? rcu_is_watching+0x16/0xd0 [ 29.278143][ T727] ? lock_release+0x184/0x1f0 [ 29.278145][ T727] netlink_unicast+0x486/0x750 [ 29.278146][ T727] ? netlink_attachskb+0x810/0x810 [ 29.278148][ T727] ? rcu_is_watching+0x16/0xd0 [ 29.278150][ T727] ? rcu_is_watching+0x16/0xd0 [ 29.278151][ T727] ? lock_acquire+0x13c/0x160 [ 29.278153][ T727] netlink_sendmsg+0x735/0xc60 [ 29.278155][ T727] ? netlink_unicast+0x750/0x750 [ 29.278157][ T727] ? rcu_is_watching+0x16/0xd0 [ 29.278158][ T727] ? lock_release+0x184/0x1f0 [ 29.278160][ T727] ____sys_sendmsg+0x415/0x880 [ 29.278162][ T727] ? copy_msghdr_from_user+0x279/0x420 [ 29.278163][ T727] ? get_timestamp.constprop.0+0x390/0x390 [ 29.278165][ T727] ? move_addr_to_kernel+0x40/0x40 [ 29.278166][ T727] ? filter_irq_stacks+0xd0/0xd0 [ 29.278169][ T727] ___sys_sendmsg+0x14e/0x1d0 [ 29.278171][ T727] ? copy_msghdr_from_user+0x420/0x420 [ 29.278175][ T727] __sys_sendmsg+0x12c/0x1d0 [ 29.278177][ T727] ? __sys_sendmsg_sock+0x20/0x20 [ 29.278180][ T727] ? rcu_is_watching+0x16/0xd0 [ 29.278182][ T727] do_syscall_64+0xff/0x530 [ 29.278183][ T727] ? exc_page_fault+0xee/0x100 [ 29.278185][ T727] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.278187][ T727] RIP: 0033:0x7fe529c9454e [ 29.278189][ T727] 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 [ 29.278191][ T727] RSP: 002b:00007ffe59e493f0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 29.278195][ T727] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007fe529c9454e [ 29.278196][ T727] RDX: 0000000000000000 RSI: 00007ffe59e494a0 RDI: 0000000000000005 [ 29.278197][ T727] RBP: 00007ffe59e49400 R08: 0000000000000000 R09: 0000000000000000 [ 29.278198][ T727] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a910de7 [ 29.278199][ T727] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffe59e49b60 [ 29.278201][ T727] [ 29.309985][ T727] ip (727) used greatest stack depth: 23016 bytes left [ 29.324729][ T729] veth2: left allmulticast mode [ 29.324921][ T729] veth2: left promiscuous mode [ 29.325201][ T729] br1: port 2(veth2) entered disabled state [ 29.356067][ T731] br1: port 1(veth1) entered disabled state [ 29.373755][ T733] veth1: left allmulticast mode [ 29.373922][ T733] veth1: left promiscuous mode [ 29.374159][ T733] br1: port 1(veth1) entered disabled state