[ 11.477979][ T237] br1: port 1(veth1) entered blocking state [ 11.478081][ T237] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.478083][ T237] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 237, name: ip [ 11.478085][ T237] preempt_count: 1, expected: 0 [ 11.478086][ T237] RCU nest depth: 0, expected: 0 [ 11.478087][ T237] locks held by ip/237: 5, last CPU#0: [ 11.478089][ T237] #0: ffffffffa26d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 11.478102][ T237] #1: ffffffffa1f69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.478108][ T237] #2: ffffffffa1f69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.478112][ T237] #3: ffffffffa1e89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.478116][ T237] #4: ffffffffa1e89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.478120][ T237] irq event stamp: 12362 [ 11.478121][ T237] hardirqs last enabled at (12361): [] __down_trylock_console_sem+0x86/0xa0 [ 11.478124][ T237] hardirqs last disabled at (12362): [] console_emit_next_record+0x3d4/0x4c0 [ 11.478126][ T237] softirqs last enabled at (12274): [] __alloc_skb+0x4c2/0x5f0 [ 11.478129][ T237] softirqs last disabled at (12272): [] __alloc_skb+0x4c2/0x5f0 [ 11.478131][ T237] Preemption disabled at: [ 11.478132][ T237] [] vprintk_emit+0x31b/0x3e0 [ 11.478137][ T237] CPU: 0 UID: 0 PID: 237 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.478141][ T237] Tainted: [W]=WARN [ 11.478142][ T237] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.478143][ T237] Call Trace: [ 11.478145][ T237] [ 11.478147][ T237] dump_stack_lvl+0x6f/0xa0 [ 11.478152][ T237] ? vprintk_emit+0x31b/0x3e0 [ 11.478154][ T237] __might_resched.cold+0x1fe/0x2c1 [ 11.478159][ T237] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.478163][ T237] ? __kmalloc_noprof+0xdb/0x760 [ 11.478169][ T237] __kmalloc_noprof+0x443/0x760 [ 11.478171][ T237] ? alloc_buf.isra.0+0x4b/0x260 [ 11.478176][ T237] ? do_raw_spin_unlock+0x59/0x250 [ 11.478179][ T237] alloc_buf.isra.0+0x4b/0x260 [ 11.478182][ T237] put_chars+0x1e1/0x2f0 [ 11.478185][ T237] ? __send_to_port+0x420/0x420 [ 11.478190][ T237] ? validate_chain+0x34a/0xc20 [ 11.478193][ T237] hvc_console_print+0x292/0x780 [ 11.478196][ T237] ? mark_usage+0x61/0x170 [ 11.478198][ T237] ? __lock_acquire+0x518/0xc20 [ 11.478199][ T237] ? __lock_acquire+0x518/0xc20 [ 11.478203][ T237] ? hvc_write+0x3a0/0x3a0 [ 11.478205][ T237] ? console_emit_next_record+0x1df/0x4c0 [ 11.478208][ T237] ? rcu_is_watching+0x16/0xd0 [ 11.478212][ T237] ? lock_acquire+0x13c/0x160 [ 11.478216][ T237] console_emit_next_record+0x22f/0x4c0 [ 11.478220][ T237] ? devkmsg_read+0x4b0/0x4b0 [ 11.478222][ T237] ? console_flush_one_record+0x106/0x710 [ 11.478225][ T237] ? rcu_is_watching+0x16/0xd0 [ 11.478227][ T237] ? lock_acquire+0x13c/0x160 [ 11.478231][ T237] console_flush_one_record+0x46f/0x710 [ 11.478235][ T237] ? console_emit_next_record+0x4c0/0x4c0 [ 11.478237][ T237] ? __lock_acquire+0x518/0xc20 [ 11.478242][ T237] console_unlock+0xee/0x1f0 [ 11.478245][ T237] ? console_flush_one_record+0x710/0x710 [ 11.478247][ T237] ? rcu_is_watching+0x16/0xd0 [ 11.478249][ T237] ? lock_acquire+0x60/0x160 [ 11.478253][ T237] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.478254][ T237] ? vprintk_emit+0x320/0x3e0 [ 11.478258][ T237] vprintk_emit+0x37c/0x3e0 [ 11.478261][ T237] ? wake_up_klogd_work_func+0x90/0x90 [ 11.478264][ T237] ? __lock_release.isra.0+0x69/0x1a0 [ 11.478266][ T237] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 11.478270][ T237] ? mark_held_locks+0x40/0x70 [ 11.478274][ T237] _printk+0xc7/0x100 [ 11.478277][ T237] ? snapshot_read.cold+0x21/0x21 [ 11.478281][ T237] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 11.478294][ T237] ? do_setlink.isra.0+0xa31/0x2750 [ 11.478295][ T237] ? rtnl_newlink+0x9f1/0x11c0 [ 11.478297][ T237] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.478301][ T237] br_set_state+0x22f/0x430 [bridge] [ 11.478311][ T237] br_init_port+0xc4/0x200 [bridge] [ 11.478319][ T237] new_nbp+0x39c/0x580 [bridge] [ 11.478329][ T237] br_add_if+0x212/0x1320 [bridge] [ 11.478336][ T237] ? is_bpf_text_address+0x72/0x110 [ 11.478340][ T237] ? kernel_text_address+0x149/0x170 [ 11.478343][ T237] ? __kernel_text_address+0x12/0x30 [ 11.478347][ T237] do_set_master+0x357/0x580 [ 11.478360][ T237] do_setlink.isra.0+0xa31/0x2750 [ 11.478363][ T237] ? stack_trace_save+0x93/0xc0 [ 11.478366][ T237] ? rtnl_link_get_size+0x350/0x350 [ 11.478368][ T237] ? rcu_read_lock_any_held+0x66/0x90 [ 11.478370][ T237] ? stack_depot_save_flags+0x38e/0x790 [ 11.478373][ T237] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.478376][ T237] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.478378][ T237] ? validate_chain+0x38b/0xc20 [ 11.478381][ T237] ? kasan_save_stack+0x3d/0x50 [ 11.478384][ T237] ? kasan_save_stack+0x2f/0x50 [ 11.478386][ T237] ? kasan_save_track+0x14/0x30 [ 11.478388][ T237] ? __lock_acquire+0x518/0xc20 [ 11.478390][ T237] ? netlink_seq_next+0x4e/0x60 [ 11.478393][ T237] ? ___sys_sendmsg+0xb0/0x1d0 [ 11.478397][ T237] ? lock_acquire.part.0+0xd4/0x280 [ 11.478399][ T237] ? rtnl_newlink+0x9a8/0x11c0 [ 11.478402][ T237] ? rcu_is_watching+0x16/0xd0 [ 11.478404][ T237] ? lock_acquire+0x13c/0x160 [ 11.478406][ T237] ? rcu_is_watching+0x16/0xd0 [ 11.478408][ T237] ? rcu_is_watching+0x16/0xd0 [ 11.478410][ T237] ? trace_contention_end+0xb3/0x180 [ 11.478414][ T237] ? __mutex_lock+0x1db/0x1ea0 [ 11.478416][ T237] ? __mutex_lock+0x9a3/0x1ea0 [ 11.478418][ T237] ? rtnl_newlink+0x9a8/0x11c0 [ 11.478421][ T237] ? ww_mutex_lock+0x160/0x160 [ 11.478423][ T237] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.478428][ T237] ? __rtnl_newlink+0x3fa/0xa50 [ 11.478433][ T237] rtnl_newlink+0x9f1/0x11c0 [ 11.478438][ T237] ? rtnl_bridge_getlink+0x850/0x850 [ 11.478440][ T237] ? __lock_acquire+0x518/0xc20 [ 11.478445][ T237] ? lock_acquire.part.0+0xd4/0x280 [ 11.478446][ T237] ? find_held_lock+0x2b/0x80 [ 11.478448][ T237] ? rtnl_bridge_getlink+0x850/0x850 [ 11.478450][ T237] ? __lock_release.isra.0+0x69/0x1a0 [ 11.478454][ T237] ? rtnl_bridge_getlink+0x850/0x850 [ 11.478456][ T237] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.478459][ T237] ? rtnl_link_fill+0x920/0x920 [ 11.478461][ T237] ? __lock_acquire+0x518/0xc20 [ 11.478465][ T237] ? lock_acquire.part.0+0xd4/0x280 [ 11.478467][ T237] ? find_held_lock+0x2b/0x80 [ 11.478470][ T237] netlink_rcv_skb+0x14e/0x3a0 [ 11.478472][ T237] ? rtnl_link_fill+0x920/0x920 [ 11.478475][ T237] ? netlink_ack+0xcf0/0xcf0 [ 11.478481][ T237] ? netlink_deliver_tap+0xc5/0x330 [ 11.478482][ T237] ? netlink_deliver_tap+0x13c/0x330 [ 11.478487][ T237] netlink_unicast+0x486/0x750 [ 11.478490][ T237] ? netlink_attachskb+0x810/0x810 [ 11.478493][ T237] ? __lock_acquire+0x518/0xc20 [ 11.478497][ T237] netlink_sendmsg+0x735/0xc60 [ 11.478501][ T237] ? netlink_unicast+0x750/0x750 [ 11.478505][ T237] ? __might_fault+0x97/0x140 [ 11.478510][ T237] ____sys_sendmsg+0x415/0x880 [ 11.478512][ T237] ? copy_msghdr_from_user+0x279/0x420 [ 11.478515][ T237] ? get_timestamp.constprop.0+0x390/0x390 [ 11.478516][ T237] ? move_addr_to_kernel+0x40/0x40 [ 11.478524][ T237] ___sys_sendmsg+0x14e/0x1d0 [ 11.478526][ T237] ? copy_msghdr_from_user+0x420/0x420 [ 11.478542][ T237] __sys_sendmsg+0x12c/0x1d0 [ 11.478545][ T237] ? __sys_sendmsg_sock+0x20/0x20 [ 11.478551][ T237] ? rcu_is_watching+0x16/0xd0 [ 11.478555][ T237] do_syscall_64+0xff/0x530 [ 11.478557][ T237] ? exc_page_fault+0xee/0x100 [ 11.478561][ T237] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.478563][ T237] RIP: 0033:0x7f677117654e [ 11.478566][ T237] 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.478568][ T237] RSP: 002b:00007ffd1df78350 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.478571][ T237] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f677117654e [ 11.478572][ T237] RDX: 0000000000000000 RSI: 00007ffd1df78400 RDI: 0000000000000005 [ 11.478573][ T237] RBP: 00007ffd1df78360 R08: 0000000000000000 R09: 0000000000000000 [ 11.478574][ T237] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a910dd4 [ 11.478575][ T237] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffd1df78ac0 [ 11.478582][ T237] [ 11.526150][ T237] br1: port 1(veth1) entered disabled state [ 11.526507][ T237] veth1: entered allmulticast mode [ 11.528160][ T237] veth1: entered promiscuous mode [ 11.535899][ T237] ip (237) used greatest stack depth: 23336 bytes left [ 11.564819][ T38] br1: port 1(veth1) entered blocking state [ 11.565373][ T38] br1: port 1(veth1) entered forwarding state [ 11.595883][ T240] br1: port 2(veth2) entered blocking state [ 11.596230][ T240] br1: port 2(veth2) entered disabled state [ 11.596554][ T240] veth2: entered allmulticast mode [ 11.598144][ T240] veth2: entered promiscuous mode [ 11.632969][ T46] br1: port 2(veth2) entered blocking state [ 11.633505][ T46] br1: port 2(veth2) entered forwarding state [ 17.103812][ T265] GACT probability NOT on [ 17.103830][ T265] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 17.103833][ T265] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 265, name: modprobe [ 17.103834][ T265] preempt_count: 1, expected: 0 [ 17.103835][ T265] RCU nest depth: 0, expected: 0 [ 17.103836][ T265] locks held by modprobe/265: 4, last CPU#2: [ 17.103838][ T265] #0: ffffffffa1f69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 17.103851][ T265] #1: ffffffffa1f69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 17.103855][ T265] #2: ffffffffa1e89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 17.103859][ T265] #3: ffffffffa1e89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 17.103863][ T265] irq event stamp: 21358 [ 17.103864][ T265] hardirqs last enabled at (21357): [] __down_trylock_console_sem+0x86/0xa0 [ 17.103867][ T265] hardirqs last disabled at (21358): [] console_emit_next_record+0x3d4/0x4c0 [ 17.103868][ T265] softirqs last enabled at (20644): [] handle_softirqs+0x67c/0x900 [ 17.103872][ T265] softirqs last disabled at (19829): [] __irq_exit_rcu+0x145/0x1c0 [ 17.103874][ T265] Preemption disabled at: [ 17.103874][ T265] [] vprintk_emit+0x31b/0x3e0 [ 17.103880][ T265] CPU: 2 UID: 0 PID: 265 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 17.103884][ T265] Tainted: [W]=WARN [ 17.103884][ T265] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 17.103886][ T265] Call Trace: [ 17.103888][ T265] [ 17.103889][ T265] dump_stack_lvl+0x6f/0xa0 [ 17.103895][ T265] ? vprintk_emit+0x31b/0x3e0 [ 17.103897][ T265] __might_resched.cold+0x1fe/0x2c1 [ 17.103902][ T265] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 17.103906][ T265] ? __kmalloc_noprof+0xdb/0x760 [ 17.103913][ T265] __kmalloc_noprof+0x443/0x760 [ 17.103915][ T265] ? alloc_buf.isra.0+0x4b/0x260 [ 17.103921][ T265] ? do_raw_spin_unlock+0x59/0x250 [ 17.103923][ T265] alloc_buf.isra.0+0x4b/0x260 [ 17.103926][ T265] put_chars+0x1e1/0x2f0 [ 17.103928][ T265] ? desc_read_finalized_seq+0x79/0x120 [ 17.103930][ T265] ? __send_to_port+0x420/0x420 [ 17.103933][ T265] ? rcu_read_lock_any_held+0x3c/0x90 [ 17.103937][ T265] ? validate_chain+0x38b/0xc20 [ 17.103941][ T265] hvc_console_print+0x292/0x780 [ 17.103943][ T265] ? __lock_acquire+0x518/0xc20 [ 17.103945][ T265] ? __lock_acquire+0x518/0xc20 [ 17.103949][ T265] ? hvc_write+0x3a0/0x3a0 [ 17.103951][ T265] ? console_emit_next_record+0x1df/0x4c0 [ 17.103954][ T265] ? rcu_is_watching+0x16/0xd0 [ 17.103957][ T265] ? lock_acquire+0x13c/0x160 [ 17.103960][ T265] console_emit_next_record+0x22f/0x4c0 [ 17.103964][ T265] ? devkmsg_read+0x4b0/0x4b0 [ 17.103966][ T265] ? console_flush_one_record+0x106/0x710 [ 17.103969][ T265] ? rcu_is_watching+0x16/0xd0 [ 17.103971][ T265] ? lock_acquire+0x13c/0x160 [ 17.103975][ T265] console_flush_one_record+0x46f/0x710 [ 17.103979][ T265] ? console_emit_next_record+0x4c0/0x4c0 [ 17.103981][ T265] ? __lock_acquire+0x518/0xc20 [ 17.103986][ T265] console_unlock+0xee/0x1f0 [ 17.103988][ T265] ? console_flush_one_record+0x710/0x710 [ 17.103990][ T265] ? rcu_is_watching+0x16/0xd0 [ 17.103992][ T265] ? lock_acquire+0x60/0x160 [ 17.103996][ T265] ? __down_trylock_console_sem+0x5e/0xa0 [ 17.103997][ T265] ? vprintk_emit+0x320/0x3e0 [ 17.104001][ T265] vprintk_emit+0x37c/0x3e0 [ 17.104005][ T265] ? wake_up_klogd_work_func+0x90/0x90 [ 17.104010][ T265] ? llc_exit+0x3e0/0x3e0 [llc] [ 17.104013][ T265] _printk+0xc7/0x100 [ 17.104017][ T265] ? snapshot_read.cold+0x21/0x21 [ 17.104023][ T265] ? do_one_initcall+0x113/0x4f0 [ 17.104026][ T265] gact_init_module+0x15/0x1000 [act_gact] [ 17.104028][ T265] do_one_initcall+0x124/0x4f0 [ 17.104031][ T265] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 17.104035][ T265] ? do_init_module+0xae/0x8a0 [ 17.104038][ T265] ? kasan_unpoison+0x44/0x70 [ 17.104043][ T265] do_init_module+0x2ad/0x8a0 [ 17.104047][ T265] ? lockdep_hardirqs_on+0x91/0x130 [ 17.104051][ T265] ? free_module+0x470/0x470 [ 17.104054][ T265] ? kfree+0x123/0x590 [ 17.104058][ T265] load_module+0x1424/0x1bb0 [ 17.104067][ T265] ? layout_and_allocate.isra.0+0x680/0x680 [ 17.104071][ T265] ? __ia32_sys_fsconfig+0x180/0x180 [ 17.104074][ T265] ? ovl_real_file+0x172/0x1e0 [ 17.104078][ T265] ? backing_file_read_iter+0x541/0x790 [ 17.104081][ T265] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 17.104085][ T265] init_module_from_file+0x11f/0x1a0 [ 17.104088][ T265] ? __do_sys_init_module+0x220/0x220 [ 17.104095][ T265] ? __rwlock_init+0x150/0x150 [ 17.104099][ T265] ? do_raw_spin_unlock+0x59/0x250 [ 17.104102][ T265] idempotent_init_module+0x37b/0x7c0 [ 17.104105][ T265] ? init_module_from_file+0x1a0/0x1a0 [ 17.104111][ T265] ? rcu_is_watching+0x16/0xd0 [ 17.104114][ T265] ? cap_capable+0x1d7/0x3d0 [ 17.104119][ T265] __x64_sys_finit_module+0xdf/0x190 [ 17.104122][ T265] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 17.104125][ T265] do_syscall_64+0xff/0x530 [ 17.104127][ T265] ? exc_page_fault+0xee/0x100 [ 17.104129][ T265] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 17.104132][ T265] RIP: 0033:0x7f3b036fe37d [ 17.104135][ T265] Code: ff c3 66 2e 0f 1f 84 00 00 00 00 00 90 f3 0f 1e fa 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d 4b aa 0f 00 f7 d8 64 89 01 48 [ 17.104137][ T265] RSP: 002b:00007ffc6ccc65c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 17.104140][ T265] RAX: ffffffffffffffda RBX: 0000556074ff8810 RCX: 00007f3b036fe37d [ 17.104141][ T265] RDX: 0000000000000000 RSI: 0000556045582ffd RDI: 0000000000000000 [ 17.104142][ T265] RBP: 00007ffc6ccc6660 R08: 0000000000000000 R09: 0000000000000000 [ 17.104143][ T265] R10: 0000000000000000 R11: 0000000000000246 R12: 0000556045582ffd [ 17.104144][ T265] R13: 0000556074ff8960 R14: 0000000000040000 R15: 0000000000000000 [ 17.104150][ T265] [ 26.991138][ C2] [ 26.991156][ C2] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 26.991159][ C2] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/2 [ 26.991161][ C2] preempt_count: 104, expected: 0 [ 26.991162][ C2] RCU nest depth: 0, expected: 0 [ 26.991163][ C2] INFO: lockdep is turned off. [ 26.991164][ C2] irq event stamp: 639408 [ 26.991165][ C2] hardirqs last enabled at (639408): [] _raw_spin_unlock_irq+0x28/0x50 [ 26.991173][ C2] hardirqs last disabled at (639407): [] _raw_spin_lock_irq+0x4a/0x50 [ 26.991175][ C2] softirqs last enabled at (639362): [] handle_softirqs+0x67c/0x900 [ 26.991179][ C2] softirqs last disabled at (639405): [] __irq_exit_rcu+0x145/0x1c0 [ 26.991181][ C2] Preemption disabled at: [ 26.991181][ C2] [<0000000000000000>] 0x0 [ 26.991189][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.991192][ C2] Tainted: [W]=WARN [ 26.991193][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.991195][ C2] Call Trace: [ 26.991197][ C2] [ 26.991199][ C2] dump_stack_lvl+0x6f/0xa0 [ 26.991204][ C2] __might_resched.cold+0x1fe/0x2c1 [ 26.991208][ C2] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 26.991212][ C2] ? __asan_memcpy+0x3c/0x60 [ 26.991214][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.991218][ C2] __kmalloc_noprof+0x443/0x760 [ 26.991221][ C2] ? __rwlock_init+0x150/0x150 [ 26.991223][ C2] ? alloc_buf.isra.0+0x4b/0x260 [ 26.991227][ C2] ? do_raw_spin_unlock+0x59/0x250 [ 26.991228][ C2] alloc_buf.isra.0+0x4b/0x260 [ 26.991230][ C2] put_chars+0x1e1/0x2f0 [ 26.991233][ C2] ? __send_to_port+0x420/0x420 [ 26.991234][ C2] ? console_prepend_replay+0x20/0x20 [ 26.991238][ C2] hvc_console_print+0x292/0x780 [ 26.991241][ C2] ? hvc_write+0x3a0/0x3a0 [ 26.991242][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.991244][ C2] ? lock_acquire+0x13c/0x160 [ 26.991246][ C2] console_emit_next_record+0x22f/0x4c0 [ 26.991249][ C2] ? devkmsg_read+0x4b0/0x4b0 [ 26.991251][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.991252][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.991254][ C2] ? lock_acquire+0x13c/0x160 [ 26.991256][ C2] ? console_flush_one_record+0x111/0x710 [ 26.991257][ C2] console_flush_one_record+0x46f/0x710 [ 26.991260][ C2] ? console_emit_next_record+0x4c0/0x4c0 [ 26.991262][ C2] console_unlock+0xee/0x1f0 [ 26.991264][ C2] ? lock_acquire+0x13c/0x160 [ 26.991265][ C2] ? console_flush_one_record+0x710/0x710 [ 26.991267][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.991269][ C2] ? lock_release+0x184/0x1f0 [ 26.991270][ C2] ? lock_acquire+0x60/0x160 [ 26.991272][ C2] ? __down_trylock_console_sem+0x5e/0xa0 [ 26.991274][ C2] ? vprintk_emit+0x320/0x3e0 [ 26.991276][ C2] vprintk_emit+0x37c/0x3e0 [ 26.991278][ C2] ? wake_up_klogd_work_func+0x90/0x90 [ 26.991280][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.991291][ C2] ? lock_release+0x184/0x1f0 [ 26.991293][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.991299][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.991305][ C2] ? is_module_text_address+0x154/0x250 [ 26.991309][ C2] _printk+0xc7/0x100 [ 26.991312][ C2] ? snapshot_read.cold+0x21/0x21 [ 26.991313][ C2] ? arch_stack_walk+0xd7/0x130 [ 26.991318][ C2] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 26.991320][ C2] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 26.991321][ C2] print_irq_inversion_bug.part.0+0x32/0xc0 [ 26.991324][ C2] mark_lock_irq+0x989/0x9c0 [ 26.991327][ C2] mark_lock+0x1d7/0xa00 [ 26.991328][ C2] mark_usage+0x42/0x170 [ 26.991330][ C2] __lock_acquire+0x388/0xc20 [ 26.991332][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.991334][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.991340][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.991342][ C2] ? lock_acquire+0x13c/0x160 [ 26.991343][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.991349][ C2] _raw_spin_lock+0x33/0x40 [ 26.991351][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.991357][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.991363][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.991369][ C2] call_timer_fn+0x160/0x4d0 [ 26.991372][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 26.991374][ C2] ? debug_object_active_state+0x430/0x430 [ 26.991377][ C2] ? find_held_lock+0x2b/0x80 [ 26.991379][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 26.991380][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.991383][ C2] __run_timers+0x68f/0xaa0 [ 26.991384][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.991391][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.991393][ C2] ? __lock_acquire+0x518/0xc20 [ 26.991395][ C2] ? __rwlock_init+0x150/0x150 [ 26.991398][ C2] run_timer_softirq+0xf0/0x160 [ 26.991399][ C2] ? __run_timers+0xaa0/0xaa0 [ 26.991401][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.991410][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.991412][ C2] handle_softirqs+0x1d3/0x900 [ 26.991414][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 26.991415][ C2] ? _local_bh_enable+0xc0/0xc0 [ 26.991417][ C2] __irq_exit_rcu+0x145/0x1c0 [ 26.991419][ C2] irq_exit_rcu+0xe/0x30 [ 26.991420][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.991423][ C2] [ 26.991424][ C2] [ 26.991424][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 26.991426][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.991428][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.991431][ C2] 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.991433][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 26.991436][ C2] RAX: 000000000009c1ab RBX: ff11000001bec6c0 RCX: ffffffff9ecf0307 [ 26.991437][ C2] RDX: ff11000001bec6c0 RSI: ffffffffa1a38b01 RDI: ffffffffa148d8e0 [ 26.991438][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.991438][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 26.991439][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 26.991441][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.991444][ C2] default_idle+0x9/0x10 [ 26.991446][ C2] default_idle_call+0x6e/0xb0 [ 26.991447][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.991449][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 26.991450][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 26.991453][ C2] ? trace_sched_exit_tp+0x30/0x160 [ 26.991454][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.991456][ C2] do_idle+0xd8/0x190 [ 26.991458][ C2] cpu_startup_entry+0x53/0x70 [ 26.991459][ C2] start_secondary+0x204/0x2b0 [ 26.991461][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 26.991463][ C2] common_startup_64+0x13e/0x148 [ 26.991467][ C2] [ 27.021883][ C2] ======================================================== [ 27.022127][ C2] WARNING: possible irq lock inversion dependency detected [ 27.022369][ C2] 7.2.0-virtme #1 Tainted: G W [ 27.022537][ C2] -------------------------------------------------------- [ 27.022773][ C2] swapper/2/0 just changed the state of lock: [ 27.022980][ C2] ff1100000d31ae58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.023298][ C2] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 27.023543][ C2] (fs_reclaim){+.+.}-{0:0} [ 27.023545][ C2] [ 27.023545][ C2] [ 27.023545][ C2] and interrupts could create inverse lock ordering between them. [ 27.023545][ C2] [ 27.024064][ C2] [ 27.024064][ C2] other info that might help us debug this: [ 27.024304][ C2] Chain exists of: [ 27.024304][ C2] &br->lock --> console_owner --> fs_reclaim [ 27.024304][ C2] [ 27.024653][ C2] Possible interrupt unsafe locking scenario: [ 27.024653][ C2] [ 27.024893][ C2] CPU0 CPU1 [ 27.025078][ C2] ---- ---- [ 27.025210][ C2] lock(fs_reclaim); [ 27.025316][ C2] local_irq_disable(); [ 27.025531][ C2] lock(&br->lock); [ 27.025745][ C2] lock(console_owner); [ 27.025914][ C2] [ 27.026060][ C2] lock(&br->lock); [ 27.026163][ C2] [ 27.026163][ C2] *** DEADLOCK *** [ 27.026163][ C2] [ 27.026410][ C2] locks held by swapper/2/0: 1, last CPU#2: [ 27.026575][ C2] #0: ffa0000000228c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 27.026890][ C2] [ 27.026890][ C2] the shortest dependencies between 2nd lock and 1st lock: [ 27.027167][ C2] -> (fs_reclaim){+.+.}-{0:0} { [ 27.027353][ C2] HARDIRQ-ON-W at: [ 27.027467][ C2] __lock_acquire+0x388/0xc20 [ 27.027684][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.027852][ C2] fs_reclaim_acquire+0xd5/0x120 [ 27.028064][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 27.028310][ C2] kthread_create_worker_on_node+0xea/0x210 [ 27.028561][ C2] workqueue_init+0x2a/0x680 [ 27.028732][ C2] kernel_init_freeable+0x2fe/0x630 [ 27.028977][ C2] kernel_init+0x21/0x150 [ 27.029191][ C2] ret_from_fork+0x474/0x6b0 [ 27.029362][ C2] ret_from_fork_asm+0x11/0x20 [ 27.029577][ C2] SOFTIRQ-ON-W at: [ 27.029676][ C2] __lock_acquire+0x388/0xc20 [ 27.029889][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.030058][ C2] fs_reclaim_acquire+0xd5/0x120 [ 27.030271][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 27.030516][ C2] kthread_create_worker_on_node+0xea/0x210 [ 27.030761][ C2] workqueue_init+0x2a/0x680 [ 27.030928][ C2] kernel_init_freeable+0x2fe/0x630 [ 27.031172][ C2] kernel_init+0x21/0x150 [ 27.031389][ C2] ret_from_fork+0x474/0x6b0 [ 27.031560][ C2] ret_from_fork_asm+0x11/0x20 [ 27.031770][ C2] INITIAL USE at: [ 27.031871][ C2] __lock_acquire+0x388/0xc20 [ 27.032081][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.032250][ C2] fs_reclaim_acquire+0xd5/0x120 [ 27.032464][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 27.032710][ C2] kthread_create_worker_on_node+0xea/0x210 [ 27.032955][ C2] workqueue_init+0x2a/0x680 [ 27.033119][ C2] kernel_init_freeable+0x2fe/0x630 [ 27.033329][ C2] kernel_init+0x21/0x150 [ 27.033499][ C2] ret_from_fork+0x474/0x6b0 [ 27.033710][ C2] ret_from_fork_asm+0x11/0x20 [ 27.033921][ C2] } [ 27.033990][ C2] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 27.034237][ C2] ... acquired at: [ 27.034340][ C2] __lock_acquire+0x518/0xc20 [ 27.034528][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.034657][ C2] fs_reclaim_acquire+0xd5/0x120 [ 27.034832][ C2] __kmalloc_noprof+0xd3/0x760 [ 27.034962][ C2] alloc_buf.isra.0+0x4b/0x260 [ 27.035139][ C2] put_chars+0x1e1/0x2f0 [ 27.035270][ C2] hvc_console_print+0x292/0x780 [ 27.035451][ C2] console_emit_next_record+0x22f/0x4c0 [ 27.035585][ C2] console_flush_one_record+0x46f/0x710 [ 27.035760][ C2] console_unlock+0xee/0x1f0 [ 27.035891][ C2] vprintk_emit+0x37c/0x3e0 [ 27.036067][ C2] dev_vprintk_emit+0x27f/0x2c0 [ 27.036200][ C2] dev_printk_emit+0xb9/0xee [ 27.036378][ C2] _dev_info+0xe2/0x116 [ 27.036481][ C2] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 27.036688][ C2] cmos_do_probe+0x73b/0x98a [ 27.036818][ C2] platform_probe+0xfe/0x1f0 [ 27.036997][ C2] call_driver_probe+0x61/0x1c0 [ 27.037127][ C2] really_probe+0x199/0x760 [ 27.037303][ C2] __driver_probe_device+0x24f/0x440 [ 27.037437][ C2] driver_probe_device+0x4a/0xf0 [ 27.037609][ C2] __driver_attach+0x1b8/0x540 [ 27.037742][ C2] bus_for_each_dev+0x130/0x1e0 [ 27.037917][ C2] bus_add_driver+0x2c8/0x530 [ 27.038047][ C2] driver_register+0x1a3/0x390 [ 27.038225][ C2] __platform_driver_probe+0x13f/0x270 [ 27.038357][ C2] cmos_init+0x31/0x40 [ 27.038464][ C2] do_one_initcall+0x124/0x4f0 [ 27.038639][ C2] kernel_init_freeable+0x596/0x630 [ 27.038772][ C2] kernel_init+0x21/0x150 [ 27.038951][ C2] ret_from_fork+0x474/0x6b0 [ 27.039081][ C2] ret_from_fork_asm+0x11/0x20 [ 27.039260][ C2] [ 27.039330][ C2] -> (console_owner){....}-{0:0} { [ 27.039514][ C2] INITIAL USE at: [ 27.039614][ C2] } [ 27.039682][ C2] ... key at: [] console_owner_dep_map+0x0/0x60 [ 27.039922][ C2] ... acquired at: [ 27.040023][ C2] __lock_acquire+0x518/0xc20 [ 27.040201][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.040332][ C2] console_lock_spinning_enable+0x5c/0x60 [ 27.040543][ C2] console_emit_next_record+0x1d1/0x4c0 [ 27.040720][ C2] console_flush_one_record+0x46f/0x710 [ 27.040849][ C2] console_unlock+0xee/0x1f0 [ 27.041022][ C2] vprintk_emit+0x37c/0x3e0 [ 27.041154][ C2] _printk+0xc7/0x100 [ 27.041255][ C2] br_set_state+0x22f/0x430 [bridge] [ 27.041440][ C2] br_init_port+0xc4/0x200 [bridge] [ 27.041574][ C2] br_stp_enable_port+0x12/0x50 [bridge] [ 27.041783][ C2] br_port_carrier_check+0x220/0x430 [bridge] [ 27.042000][ C2] br_device_event+0x52d/0x8f0 [bridge] [ 27.042135][ C2] notifier_call_chain+0xae/0x300 [ 27.042331][ C2] netif_state_change+0x139/0x340 [ 27.042471][ C2] __linkwatch_run_queue+0x34c/0x750 [ 27.042648][ C2] linkwatch_event+0x7f/0xb0 [ 27.042778][ C2] process_one_work+0xe3e/0x1560 [ 27.042958][ C2] worker_thread+0x4f1/0xd60 [ 27.043092][ C2] kthread+0x367/0x460 [ 27.043237][ C2] ret_from_fork+0x474/0x6b0 [ 27.043370][ C2] ret_from_fork_asm+0x11/0x20 [ 27.043552][ C2] [ 27.043618][ C2] -> (&br->lock){+.-.}-{3:3} { [ 27.043758][ C2] HARDIRQ-ON-W at: [ 27.043901][ C2] __lock_acquire+0x388/0xc20 [ 27.044069][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.044279][ C2] _raw_spin_lock_bh+0x38/0x50 [ 27.044500][ C2] br_stp_enable_bridge+0x23/0x340 [bridge] [ 27.044697][ C2] br_dev_open+0x94/0x120 [bridge] [ 27.044911][ C2] __dev_open+0x2f3/0x610 [ 27.045077][ C2] __dev_change_flags+0x4a4/0x660 [ 27.045242][ C2] rtnl_configure_link+0x15f/0x410 [ 27.045464][ C2] rtnl_newlink_create+0x2ec/0x750 [ 27.045629][ C2] __rtnl_newlink+0x22b/0xa50 [ 27.045838][ C2] rtnl_newlink+0x9f1/0x11c0 [ 27.046046][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.046212][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 27.046430][ C2] netlink_unicast+0x486/0x750 [ 27.046643][ C2] netlink_sendmsg+0x735/0xc60 [ 27.046808][ C2] ____sys_sendmsg+0x415/0x880 [ 27.047019][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 27.047187][ C2] __sys_sendmsg+0x12c/0x1d0 [ 27.047396][ C2] do_syscall_64+0xff/0x530 [ 27.047615][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.047816][ C2] IN-SOFTIRQ-W at: [ 27.047963][ C2] __lock_acquire+0x388/0xc20 [ 27.048129][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.048338][ C2] _raw_spin_lock+0x33/0x40 [ 27.048551][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.048756][ C2] call_timer_fn+0x160/0x4d0 [ 27.048969][ C2] __run_timers+0x68f/0xaa0 [ 27.049182][ C2] run_timer_softirq+0xf0/0x160 [ 27.049350][ C2] handle_softirqs+0x1d3/0x900 [ 27.049568][ C2] __irq_exit_rcu+0x145/0x1c0 [ 27.049776][ C2] irq_exit_rcu+0xe/0x30 [ 27.049942][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.050188][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.050436][ C2] pv_native_safe_halt+0xf/0x10 [ 27.050605][ C2] default_idle+0x9/0x10 [ 27.050813][ C2] default_idle_call+0x6e/0xb0 [ 27.051024][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.051228][ C2] do_idle+0xd8/0x190 [ 27.051407][ C2] cpu_startup_entry+0x53/0x70 [ 27.051570][ C2] start_secondary+0x204/0x2b0 [ 27.051780][ C2] common_startup_64+0x13e/0x148 [ 27.051993][ C2] INITIAL USE at: [ 27.052092][ C2] __lock_acquire+0x388/0xc20 [ 27.052302][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.052472][ C2] _raw_spin_lock_bh+0x38/0x50 [ 27.052681][ C2] br_stp_enable_bridge+0x23/0x340 [bridge] [ 27.052930][ C2] br_dev_open+0x94/0x120 [bridge] [ 27.053101][ C2] __dev_open+0x2f3/0x610 [ 27.053311][ C2] __dev_change_flags+0x4a4/0x660 [ 27.053530][ C2] rtnl_configure_link+0x15f/0x410 [ 27.053695][ C2] rtnl_newlink_create+0x2ec/0x750 [ 27.053902][ C2] __rtnl_newlink+0x22b/0xa50 [ 27.054069][ C2] rtnl_newlink+0x9f1/0x11c0 [ 27.054278][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.054494][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 27.054655][ C2] netlink_unicast+0x486/0x750 [ 27.054866][ C2] netlink_sendmsg+0x735/0xc60 [ 27.055076][ C2] ____sys_sendmsg+0x415/0x880 [ 27.055240][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 27.055454][ C2] __sys_sendmsg+0x12c/0x1d0 [ 27.055627][ C2] do_syscall_64+0xff/0x530 [ 27.055837][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.056079][ C2] } [ 27.056147][ C2] ... key at: [] __key.7+0x0/0x40 [bridge] [ 27.056392][ C2] ... acquired at: [ 27.056495][ C2] mark_lock+0x1d7/0xa00 [ 27.056670][ C2] mark_usage+0x42/0x170 [ 27.056805][ C2] __lock_acquire+0x388/0xc20 [ 27.056982][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.057113][ C2] _raw_spin_lock+0x33/0x40 [ 27.057287][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.057489][ C2] call_timer_fn+0x160/0x4d0 [ 27.057663][ C2] __run_timers+0x68f/0xaa0 [ 27.057793][ C2] run_timer_softirq+0xf0/0x160 [ 27.057972][ C2] handle_softirqs+0x1d3/0x900 [ 27.058104][ C2] __irq_exit_rcu+0x145/0x1c0 [ 27.058278][ C2] irq_exit_rcu+0xe/0x30 [ 27.058410][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.058615][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.058822][ C2] pv_native_safe_halt+0xf/0x10 [ 27.058952][ C2] default_idle+0x9/0x10 [ 27.059129][ C2] default_idle_call+0x6e/0xb0 [ 27.059260][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.059473][ C2] do_idle+0xd8/0x190 [ 27.059570][ C2] cpu_startup_entry+0x53/0x70 [ 27.059740][ C2] start_secondary+0x204/0x2b0 [ 27.059869][ C2] common_startup_64+0x13e/0x148 [ 27.060046][ C2] [ 27.060112][ C2] [ 27.060112][ C2] stack backtrace: [ 27.060276][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.060280][ C2] Tainted: [W]=WARN [ 27.060281][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.060283][ C2] Call Trace: [ 27.060284][ C2] [ 27.060286][ C2] dump_stack_lvl+0x6f/0xa0 [ 27.060291][ C2] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 27.060294][ C2] mark_lock_irq+0x989/0x9c0 [ 27.060296][ C2] mark_lock+0x1d7/0xa00 [ 27.060298][ C2] mark_usage+0x42/0x170 [ 27.060300][ C2] __lock_acquire+0x388/0xc20 [ 27.060302][ C2] lock_acquire.part.0+0xd4/0x280 [ 27.060304][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.060310][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.060313][ C2] ? lock_acquire+0x13c/0x160 [ 27.060315][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.060321][ C2] _raw_spin_lock+0x33/0x40 [ 27.060323][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.060329][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.060335][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.060341][ C2] call_timer_fn+0x160/0x4d0 [ 27.060343][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 27.060344][ C2] ? debug_object_active_state+0x430/0x430 [ 27.060349][ C2] ? find_held_lock+0x2b/0x80 [ 27.060351][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 27.060352][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.060355][ C2] __run_timers+0x68f/0xaa0 [ 27.060356][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.060363][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.060364][ C2] ? __lock_acquire+0x518/0xc20 [ 27.060367][ C2] ? __rwlock_init+0x150/0x150 [ 27.060369][ C2] run_timer_softirq+0xf0/0x160 [ 27.060371][ C2] ? __run_timers+0xaa0/0xaa0 [ 27.060373][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.060375][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.060377][ C2] handle_softirqs+0x1d3/0x900 [ 27.060378][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 27.060380][ C2] ? _local_bh_enable+0xc0/0xc0 [ 27.060382][ C2] __irq_exit_rcu+0x145/0x1c0 [ 27.060383][ C2] irq_exit_rcu+0xe/0x30 [ 27.060385][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.060386][ C2] [ 27.060387][ C2] [ 27.060388][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 27.060389][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.060391][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.060393][ C2] Code: 48 8b 3d 94 e2 f5 01 e8 1f 00 00 00 48 2b 05 58 a3 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 96 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 27.060395][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 27.060398][ C2] RAX: 000000000009c1ab RBX: ff11000001bec6c0 RCX: ffffffff9ecf0307 [ 27.060399][ C2] RDX: ff11000001bec6c0 RSI: ffffffffa1a38b01 RDI: ffffffffa148d8e0 [ 27.060400][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.060401][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 27.060401][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 27.060409][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.060411][ C2] default_idle+0x9/0x10 [ 27.060412][ C2] default_idle_call+0x6e/0xb0 [ 27.060414][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.060416][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 27.060417][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 27.060419][ C2] ? trace_sched_exit_tp+0x30/0x160 [ 27.060422][ C2] ? rcu_is_watching+0x16/0xd0 [ 27.060424][ C2] do_idle+0xd8/0x190 [ 27.060425][ C2] cpu_startup_entry+0x53/0x70 [ 27.060426][ C2] start_secondary+0x204/0x2b0 [ 27.060428][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 27.060430][ C2] common_startup_64+0x13e/0x148 [ 27.060432][ C2] [ 29.863092][ T483] br1: port 2(veth2) entered disabled state [ 29.863108][ T483] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 29.863111][ T483] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 483, name: ip [ 29.863112][ T483] preempt_count: 202, expected: 0 [ 29.863113][ T483] RCU nest depth: 0, expected: 0 [ 29.863114][ T483] INFO: lockdep is turned off. [ 29.863115][ T483] irq event stamp: 0 [ 29.863116][ T483] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 29.863121][ T483] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 29.863128][ T483] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 29.863129][ T483] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 29.863131][ T483] Preemption disabled at: [ 29.863132][ T483] [<0000000000000000>] 0x0 [ 29.863136][ T483] CPU: 2 UID: 0 PID: 483 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 29.863140][ T483] Tainted: [W]=WARN [ 29.863140][ T483] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 29.863142][ T483] Call Trace: [ 29.863144][ T483] [ 29.863145][ T483] dump_stack_lvl+0x6f/0xa0 [ 29.863150][ T483] __might_resched.cold+0x1fe/0x2c1 [ 29.863154][ T483] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 29.863157][ T483] ? rcu_is_watching+0x16/0xd0 [ 29.863162][ T483] __kmalloc_noprof+0x443/0x760 [ 29.863165][ T483] ? __rwlock_init+0x150/0x150 [ 29.863168][ T483] ? alloc_buf.isra.0+0x4b/0x260 [ 29.863172][ T483] ? do_raw_spin_unlock+0x59/0x250 [ 29.863173][ T483] alloc_buf.isra.0+0x4b/0x260 [ 29.863175][ T483] put_chars+0x1e1/0x2f0 [ 29.863177][ T483] ? __send_to_port+0x420/0x420 [ 29.863179][ T483] ? printk_get_next_message+0x240/0x650 [ 29.863182][ T483] hvc_console_print+0x292/0x780 [ 29.863185][ T483] ? hvc_write+0x3a0/0x3a0 [ 29.863187][ T483] ? __rwlock_init+0x150/0x150 [ 29.863189][ T483] ? rcu_is_watching+0x16/0xd0 [ 29.863191][ T483] ? lock_acquire+0x13c/0x160 [ 29.863193][ T483] console_emit_next_record+0x22f/0x4c0 [ 29.863195][ T483] ? devkmsg_read+0x4b0/0x4b0 [ 29.863197][ T483] ? rcu_is_watching+0x16/0xd0 [ 29.863199][ T483] ? rcu_is_watching+0x16/0xd0 [ 29.863200][ T483] ? rcu_is_watching+0x16/0xd0 [ 29.863202][ T483] ? lock_acquire+0x13c/0x160 [ 29.863204][ T483] ? console_flush_one_record+0x111/0x710 [ 29.863206][ T483] console_flush_one_record+0x46f/0x710 [ 29.863208][ T483] ? console_emit_next_record+0x4c0/0x4c0 [ 29.863211][ T483] console_unlock+0xee/0x1f0 [ 29.863212][ T483] ? lock_acquire+0x13c/0x160 [ 29.863214][ T483] ? console_flush_one_record+0x710/0x710 [ 29.863215][ T483] ? rcu_is_watching+0x16/0xd0 [ 29.863217][ T483] ? lock_release+0x184/0x1f0 [ 29.863218][ T483] ? lock_acquire+0x60/0x160 [ 29.863220][ T483] ? __rcu_read_lock+0x30/0x70 [ 29.863222][ T483] ? __down_trylock_console_sem+0x5e/0xa0 [ 29.863224][ T483] ? vprintk_emit+0x320/0x3e0 [ 29.863226][ T483] vprintk_emit+0x37c/0x3e0 [ 29.863228][ T483] ? wake_up_klogd_work_func+0x90/0x90 [ 29.863230][ T483] ? rcu_is_watching+0x16/0xd0 [ 29.863232][ T483] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 29.863234][ T483] ? trace_hardirqs_on+0x36/0x40 [ 29.863236][ T483] _printk+0xc7/0x100 [ 29.863239][ T483] ? snapshot_read.cold+0x21/0x21 [ 29.863241][ T483] ? ipv6_mc_down+0x17e/0x2d0 [ 29.863246][ T483] br_set_state+0x22f/0x430 [bridge] [ 29.863259][ T483] br_stp_disable_port+0x11f/0x390 [bridge] [ 29.863267][ T483] br_device_event+0x7b5/0x8f0 [bridge] [ 29.863276][ T483] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 29.863279][ T483] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 29.863281][ T483] ? addrconf_notify+0x2c8/0xf30 [ 29.863285][ T483] notifier_call_chain+0xae/0x300 [ 29.863288][ T483] __dev_notify_flags+0x23c/0x3c0 [ 29.863292][ T483] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 29.863293][ T483] ? netif_change_name+0x8c0/0x8c0 [ 29.863296][ T483] ? netif_set_allmulti+0x250/0x250 [ 29.863297][ T483] ? __kernel_text_address+0x12/0x30 [ 29.863300][ T483] ? write_profile+0xf0/0xf0 [ 29.863302][ T483] ? unwind_get_return_address+0x67/0xd0 [ 29.863305][ T483] netif_change_flags+0xfe/0x190 [ 29.863307][ T483] do_setlink.isra.0+0x1cc3/0x2750 [ 29.863310][ T483] ? stack_trace_save+0x93/0xc0 [ 29.863312][ T483] ? rtnl_link_get_size+0x350/0x350 [ 29.863313][ T483] ? rcu_is_watching+0x16/0xd0 [ 29.863314][ T483] ? stack_depot_save_flags+0x1bc/0x790 [ 29.863318][ T483] ? kasan_save_stack+0x3d/0x50 [ 29.863321][ T483] ? kasan_save_stack+0x2f/0x50 [ 29.863322][ T483] ? kasan_save_track+0x14/0x30 [ 29.863324][ T483] ? __kasan_kmalloc+0x7b/0x90 [ 29.863325][ T483] ? __kmalloc_cache_noprof+0x29a/0x620 [ 29.863327][ T483] ? rtnl_newlink+0x124/0x11c0 [ 29.863328][ T483] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 29.863329][ T483] ? netlink_rcv_skb+0x14e/0x3a0 [ 29.863332][ T483] ? netlink_unicast+0x486/0x750 [ 29.863333][ T483] ? netlink_sendmsg+0x735/0xc60 [ 29.863334][ T483] ? ____sys_sendmsg+0x415/0x880 [ 29.863337][ T483] ? ___sys_sendmsg+0x14e/0x1d0 [ 29.863338][ T483] ? __sys_sendmsg+0x12c/0x1d0 [ 29.863340][ T483] ? do_syscall_64+0xff/0x530 [ 29.863342][ T483] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.863344][ T483] ? rcu_is_watching+0x16/0xd0 [ 29.863346][ T483] ? lock_acquire+0x13c/0x160 [ 29.863347][ T483] ? rcu_is_watching+0x16/0xd0 [ 29.863349][ T483] ? rcu_is_watching+0x16/0xd0 [ 29.863351][ T483] ? trace_contention_end+0xb3/0x180 [ 29.863354][ T483] ? __mutex_lock+0x1db/0x1ea0 [ 29.863356][ T483] ? __mutex_lock+0x9a3/0x1ea0 [ 29.863357][ T483] ? rtnl_newlink+0x9a8/0x11c0 [ 29.863359][ T483] ? ww_mutex_lock+0x160/0x160 [ 29.863361][ T483] ? __rtnl_newlink+0x3fa/0xa50 [ 29.863363][ T483] rtnl_newlink+0x9f1/0x11c0 [ 29.863365][ T483] ? rcu_is_watching+0x16/0xd0 [ 29.863367][ T483] ? rtnl_bridge_getlink+0x850/0x850 [ 29.863368][ T483] ? lock_acquire+0x13c/0x160 [ 29.863369][ T483] ? rcu_is_watching+0x16/0xd0 [ 29.863371][ T483] ? is_bpf_text_address+0x3e/0x110 [ 29.863374][ T483] ? is_bpf_text_address+0x72/0x110 [ 29.863376][ T483] ? kernel_text_address+0x149/0x170 [ 29.863377][ T483] ? __kernel_text_address+0x12/0x30 [ 29.863379][ T483] ? write_profile+0xf0/0xf0 [ 29.863380][ T483] ? unwind_get_return_address+0x67/0xd0 [ 29.863381][ T483] ? rcu_is_watching+0x16/0xd0 [ 29.863383][ T483] ? rcu_is_watching+0x16/0xd0 [ 29.863385][ T483] ? cap_capable+0x1d7/0x3d0 [ 29.863388][ T483] ? lock_acquire+0x13c/0x160 [ 29.863389][ T483] ? rcu_is_watching+0x16/0xd0 [ 29.863391][ T483] ? rtnl_bridge_getlink+0x850/0x850 [ 29.863392][ T483] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 29.863394][ T483] ? rtnl_link_fill+0x920/0x920 [ 29.863395][ T483] ? filter_irq_stacks+0xd0/0xd0 [ 29.863397][ T483] ? stack_depot_save_flags+0x1bc/0x790 [ 29.863399][ T483] netlink_rcv_skb+0x14e/0x3a0 [ 29.863401][ T483] ? rtnl_link_fill+0x920/0x920 [ 29.863402][ T483] ? lock_release+0x184/0x1f0 [ 29.863404][ T483] ? netlink_ack+0xcf0/0xcf0 [ 29.863405][ T483] ? __netlink_lookup+0xb9/0x6b0 [ 29.863407][ T483] ? rcu_is_watching+0x16/0xd0 [ 29.863409][ T483] ? lock_release+0x184/0x1f0 [ 29.863414][ T483] netlink_unicast+0x486/0x750 [ 29.863416][ T483] ? netlink_attachskb+0x810/0x810 [ 29.863418][ T483] ? rcu_is_watching+0x16/0xd0 [ 29.863419][ T483] ? rcu_is_watching+0x16/0xd0 [ 29.863421][ T483] ? lock_acquire+0x13c/0x160 [ 29.863423][ T483] netlink_sendmsg+0x735/0xc60 [ 29.863425][ T483] ? netlink_unicast+0x750/0x750 [ 29.863427][ T483] ? rcu_is_watching+0x16/0xd0 [ 29.863429][ T483] ? lock_release+0x184/0x1f0 [ 29.863430][ T483] ____sys_sendmsg+0x415/0x880 [ 29.863432][ T483] ? copy_msghdr_from_user+0x279/0x420 [ 29.863434][ T483] ? get_timestamp.constprop.0+0x390/0x390 [ 29.863435][ T483] ? move_addr_to_kernel+0x40/0x40 [ 29.863437][ T483] ? filter_irq_stacks+0xd0/0xd0 [ 29.863439][ T483] ___sys_sendmsg+0x14e/0x1d0 [ 29.863441][ T483] ? copy_msghdr_from_user+0x420/0x420 [ 29.863446][ T483] __sys_sendmsg+0x12c/0x1d0 [ 29.863448][ T483] ? __sys_sendmsg_sock+0x20/0x20 [ 29.863451][ T483] ? rcu_is_watching+0x16/0xd0 [ 29.863453][ T483] do_syscall_64+0xff/0x530 [ 29.863454][ T483] ? exc_page_fault+0xee/0x100 [ 29.863456][ T483] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 29.863458][ T483] RIP: 0033:0x7f27e0cf654e [ 29.863460][ T483] 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.863461][ T483] RSP: 002b:00007ffd58e58d70 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 29.863465][ T483] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f27e0cf654e [ 29.863466][ T483] RDX: 0000000000000000 RSI: 00007ffd58e58e20 RDI: 0000000000000005 [ 29.863467][ T483] RBP: 00007ffd58e58d80 R08: 0000000000000000 R09: 0000000000000000 [ 29.863468][ T483] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a910de7 [ 29.863469][ T483] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffd58e594e0 [ 29.863471][ T483] [ 29.902220][ T483] ip (483) used greatest stack depth: 23016 bytes left [ 29.919382][ T484] veth2: left allmulticast mode [ 29.919575][ T484] veth2: left promiscuous mode [ 29.919828][ T484] br1: port 2(veth2) entered disabled state [ 29.941292][ T485] br1: port 1(veth1) entered disabled state [ 29.957034][ T486] veth1: left allmulticast mode [ 29.957197][ T486] veth1: left promiscuous mode [ 29.957393][ T486] br1: port 1(veth1) entered disabled state