[ 11.035261][ T254] br1: port 1(veth1) entered blocking state [ 11.035348][ T254] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.035350][ T254] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 254, name: ip [ 11.035352][ T254] preempt_count: 1, expected: 0 [ 11.035353][ T254] RCU nest depth: 0, expected: 0 [ 11.035354][ T254] locks held by ip/254: 5, last CPU#2: [ 11.035357][ T254] #0: ffffffffb24d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 11.035368][ T254] #1: ffffffffb1d69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.035375][ T254] #2: ffffffffb1d69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.035379][ T254] #3: ffffffffb1c89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.035383][ T254] #4: ffffffffb1c89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.035387][ T254] irq event stamp: 14208 [ 11.035387][ T254] hardirqs last enabled at (14207): [] __down_trylock_console_sem+0x86/0xa0 [ 11.035390][ T254] hardirqs last disabled at (14208): [] console_emit_next_record+0x3d4/0x4c0 [ 11.035392][ T254] softirqs last enabled at (14116): [] __alloc_skb+0x4c2/0x5f0 [ 11.035395][ T254] softirqs last disabled at (14114): [] __alloc_skb+0x4c2/0x5f0 [ 11.035398][ T254] Preemption disabled at: [ 11.035399][ T254] [] vprintk_emit+0x31b/0x3e0 [ 11.035404][ T254] CPU: 2 UID: 0 PID: 254 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.035408][ T254] Tainted: [W]=WARN [ 11.035409][ T254] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.035411][ T254] Call Trace: [ 11.035412][ T254] [ 11.035414][ T254] dump_stack_lvl+0x6f/0xa0 [ 11.035420][ T254] ? vprintk_emit+0x31b/0x3e0 [ 11.035422][ T254] __might_resched.cold+0x1fe/0x2c1 [ 11.035427][ T254] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.035431][ T254] ? __kmalloc_noprof+0xdb/0x760 [ 11.035436][ T254] __kmalloc_noprof+0x443/0x760 [ 11.035439][ T254] ? alloc_buf.isra.0+0x4b/0x260 [ 11.035444][ T254] ? do_raw_spin_unlock+0x59/0x250 [ 11.035447][ T254] alloc_buf.isra.0+0x4b/0x260 [ 11.035450][ T254] put_chars+0x1e1/0x2f0 [ 11.035453][ T254] ? __send_to_port+0x420/0x420 [ 11.035457][ T254] ? validate_chain+0x34a/0xc20 [ 11.035461][ T254] hvc_console_print+0x292/0x780 [ 11.035464][ T254] ? mark_usage+0x61/0x170 [ 11.035466][ T254] ? __lock_acquire+0x518/0xc20 [ 11.035467][ T254] ? __lock_acquire+0x518/0xc20 [ 11.035471][ T254] ? hvc_write+0x3a0/0x3a0 [ 11.035473][ T254] ? console_emit_next_record+0x1df/0x4c0 [ 11.035476][ T254] ? rcu_is_watching+0x16/0xd0 [ 11.035481][ T254] ? lock_acquire+0x13c/0x160 [ 11.035485][ T254] console_emit_next_record+0x22f/0x4c0 [ 11.035488][ T254] ? devkmsg_read+0x4b0/0x4b0 [ 11.035490][ T254] ? console_flush_one_record+0x106/0x710 [ 11.035493][ T254] ? rcu_is_watching+0x16/0xd0 [ 11.035496][ T254] ? lock_acquire+0x13c/0x160 [ 11.035500][ T254] console_flush_one_record+0x46f/0x710 [ 11.035504][ T254] ? console_emit_next_record+0x4c0/0x4c0 [ 11.035506][ T254] ? __lock_acquire+0x518/0xc20 [ 11.035511][ T254] console_unlock+0xee/0x1f0 [ 11.035514][ T254] ? console_flush_one_record+0x710/0x710 [ 11.035516][ T254] ? rcu_is_watching+0x16/0xd0 [ 11.035518][ T254] ? lock_acquire+0x60/0x160 [ 11.035522][ T254] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.035524][ T254] ? vprintk_emit+0x320/0x3e0 [ 11.035527][ T254] vprintk_emit+0x37c/0x3e0 [ 11.035531][ T254] ? wake_up_klogd_work_func+0x90/0x90 [ 11.035533][ T254] ? __lock_release.isra.0+0x69/0x1a0 [ 11.035535][ T254] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 11.035539][ T254] ? mark_held_locks+0x40/0x70 [ 11.035543][ T254] _printk+0xc7/0x100 [ 11.035547][ T254] ? snapshot_read.cold+0x21/0x21 [ 11.035551][ T254] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 11.035564][ T254] ? do_setlink.isra.0+0xa31/0x2750 [ 11.035566][ T254] ? rtnl_newlink+0x9f1/0x11c0 [ 11.035567][ T254] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.035571][ T254] br_set_state+0x22f/0x430 [bridge] [ 11.035581][ T254] br_init_port+0xc4/0x200 [bridge] [ 11.035590][ T254] new_nbp+0x39c/0x580 [bridge] [ 11.035599][ T254] br_add_if+0x212/0x1320 [bridge] [ 11.035606][ T254] ? is_bpf_text_address+0x72/0x110 [ 11.035610][ T254] ? kernel_text_address+0x149/0x170 [ 11.035613][ T254] ? __kernel_text_address+0x12/0x30 [ 11.035617][ T254] do_set_master+0x357/0x580 [ 11.035622][ T254] do_setlink.isra.0+0xa31/0x2750 [ 11.035625][ T254] ? stack_trace_save+0x93/0xc0 [ 11.035628][ T254] ? rtnl_link_get_size+0x350/0x350 [ 11.035629][ T254] ? rcu_read_lock_any_held+0x66/0x90 [ 11.035632][ T254] ? stack_depot_save_flags+0x38e/0x790 [ 11.035636][ T254] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.035639][ T254] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.035640][ T254] ? validate_chain+0x38b/0xc20 [ 11.035643][ T254] ? kasan_save_stack+0x3d/0x50 [ 11.035646][ T254] ? kasan_save_stack+0x2f/0x50 [ 11.035648][ T254] ? kasan_save_track+0x14/0x30 [ 11.035650][ T254] ? __lock_acquire+0x518/0xc20 [ 11.035652][ T254] ? netlink_seq_next+0xe/0x60 [ 11.035655][ T254] ? ___sys_sendmsg+0xb0/0x1d0 [ 11.035660][ T254] ? lock_acquire.part.0+0xd4/0x280 [ 11.035662][ T254] ? rtnl_newlink+0x9a8/0x11c0 [ 11.035664][ T254] ? rcu_is_watching+0x16/0xd0 [ 11.035667][ T254] ? lock_acquire+0x13c/0x160 [ 11.035668][ T254] ? rcu_is_watching+0x16/0xd0 [ 11.035670][ T254] ? rcu_is_watching+0x16/0xd0 [ 11.035672][ T254] ? trace_contention_end+0xb3/0x180 [ 11.035676][ T254] ? __mutex_lock+0x1db/0x1ea0 [ 11.035678][ T254] ? __mutex_lock+0x9a3/0x1ea0 [ 11.035680][ T254] ? rtnl_newlink+0x9a8/0x11c0 [ 11.035684][ T254] ? ww_mutex_lock+0x160/0x160 [ 11.035685][ T254] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.035690][ T254] ? __rtnl_newlink+0x3fa/0xa50 [ 11.035695][ T254] rtnl_newlink+0x9f1/0x11c0 [ 11.035701][ T254] ? rtnl_bridge_getlink+0x850/0x850 [ 11.035703][ T254] ? __lock_acquire+0x518/0xc20 [ 11.035707][ T254] ? lock_acquire.part.0+0xd4/0x280 [ 11.035709][ T254] ? find_held_lock+0x2b/0x80 [ 11.035711][ T254] ? rtnl_bridge_getlink+0x850/0x850 [ 11.035713][ T254] ? __lock_release.isra.0+0x69/0x1a0 [ 11.035717][ T254] ? rtnl_bridge_getlink+0x850/0x850 [ 11.035719][ T254] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.035722][ T254] ? rtnl_link_fill+0x920/0x920 [ 11.035723][ T254] ? __lock_acquire+0x518/0xc20 [ 11.035728][ T254] ? lock_acquire.part.0+0xd4/0x280 [ 11.035729][ T254] ? find_held_lock+0x2b/0x80 [ 11.035733][ T254] netlink_rcv_skb+0x14e/0x3a0 [ 11.035735][ T254] ? rtnl_link_fill+0x920/0x920 [ 11.035738][ T254] ? netlink_ack+0xcf0/0xcf0 [ 11.035744][ T254] ? netlink_deliver_tap+0xc5/0x330 [ 11.035745][ T254] ? netlink_deliver_tap+0x13c/0x330 [ 11.035749][ T254] netlink_unicast+0x486/0x750 [ 11.035753][ T254] ? netlink_attachskb+0x810/0x810 [ 11.035756][ T254] ? __lock_acquire+0x518/0xc20 [ 11.035760][ T254] netlink_sendmsg+0x735/0xc60 [ 11.035764][ T254] ? netlink_unicast+0x750/0x750 [ 11.035767][ T254] ? __might_fault+0x97/0x140 [ 11.035773][ T254] ____sys_sendmsg+0x415/0x880 [ 11.035775][ T254] ? copy_msghdr_from_user+0x279/0x420 [ 11.035777][ T254] ? get_timestamp.constprop.0+0x390/0x390 [ 11.035779][ T254] ? move_addr_to_kernel+0x40/0x40 [ 11.035786][ T254] ___sys_sendmsg+0x14e/0x1d0 [ 11.035789][ T254] ? copy_msghdr_from_user+0x420/0x420 [ 11.035805][ T254] __sys_sendmsg+0x12c/0x1d0 [ 11.035808][ T254] ? __sys_sendmsg_sock+0x20/0x20 [ 11.035814][ T254] ? rcu_is_watching+0x16/0xd0 [ 11.035818][ T254] do_syscall_64+0xff/0x530 [ 11.035820][ T254] ? exc_page_fault+0xee/0x100 [ 11.035823][ T254] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.035825][ T254] RIP: 0033:0x7f4d41fe454e [ 11.035829][ T254] 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.035831][ T254] RSP: 002b:00007ffe4538a100 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.035834][ T254] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f4d41fe454e [ 11.035835][ T254] RDX: 0000000000000000 RSI: 00007ffe4538a1b0 RDI: 0000000000000005 [ 11.035836][ T254] RBP: 00007ffe4538a110 R08: 0000000000000000 R09: 0000000000000000 [ 11.035837][ T254] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a9180ef [ 11.035838][ T254] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffe4538a870 [ 11.035844][ T254] [ 11.081025][ T254] br1: port 1(veth1) entered disabled state [ 11.081368][ T254] veth1: entered allmulticast mode [ 11.082990][ T254] veth1: entered promiscuous mode [ 11.095624][ T254] ip (254) used greatest stack depth: 23336 bytes left [ 11.114199][ T103] br1: port 1(veth1) entered blocking state [ 11.114787][ T103] br1: port 1(veth1) entered forwarding state [ 11.144862][ T257] br1: port 2(veth2) entered blocking state [ 11.145203][ T257] br1: port 2(veth2) entered disabled state [ 11.145570][ T257] veth2: entered allmulticast mode [ 11.147134][ T257] veth2: entered promiscuous mode [ 11.178725][ T39] br1: port 2(veth2) entered blocking state [ 11.179031][ T39] br1: port 2(veth2) entered forwarding state [ 11.274384][ T261] 8021q: 802.1Q VLAN Support v1.8 [ 11.543695][ T271] GACT probability NOT on [ 17.331560][ T316] Mirror/redirect action on [ 17.331578][ T316] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 17.331580][ T316] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 316, name: modprobe [ 17.331582][ T316] preempt_count: 1, expected: 0 [ 17.331583][ T316] RCU nest depth: 0, expected: 0 [ 17.331584][ T316] locks held by modprobe/316: 4, last CPU#2: [ 17.331586][ T316] #0: ffffffffb1d69cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 17.331599][ T316] #1: ffffffffb1d69d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 17.331603][ T316] #2: ffffffffb1c89660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 17.331607][ T316] #3: ffffffffb1c89560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 17.331611][ T316] irq event stamp: 17444 [ 17.331612][ T316] hardirqs last enabled at (17443): [] __down_trylock_console_sem+0x86/0xa0 [ 17.331615][ T316] hardirqs last disabled at (17444): [] console_emit_next_record+0x3d4/0x4c0 [ 17.331617][ T316] softirqs last enabled at (16556): [] handle_softirqs+0x67c/0x900 [ 17.331620][ T316] softirqs last disabled at (16549): [] __irq_exit_rcu+0x145/0x1c0 [ 17.331622][ T316] Preemption disabled at: [ 17.331623][ T316] [] vprintk_emit+0x31b/0x3e0 [ 17.331628][ T316] CPU: 2 UID: 0 PID: 316 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 17.331632][ T316] Tainted: [W]=WARN [ 17.331633][ T316] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 17.331634][ T316] Call Trace: [ 17.331636][ T316] [ 17.331637][ T316] dump_stack_lvl+0x6f/0xa0 [ 17.331643][ T316] ? vprintk_emit+0x31b/0x3e0 [ 17.331645][ T316] __might_resched.cold+0x1fe/0x2c1 [ 17.331649][ T316] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 17.331653][ T316] ? __kmalloc_noprof+0xdb/0x760 [ 17.331658][ T316] __kmalloc_noprof+0x443/0x760 [ 17.331660][ T316] ? alloc_buf.isra.0+0x4b/0x260 [ 17.331666][ T316] ? do_raw_spin_unlock+0x59/0x250 [ 17.331669][ T316] alloc_buf.isra.0+0x4b/0x260 [ 17.331672][ T316] put_chars+0x1e1/0x2f0 [ 17.331675][ T316] ? __send_to_port+0x420/0x420 [ 17.331676][ T316] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 17.331681][ T316] ? rcu_read_lock_any_held+0x3c/0x90 [ 17.331683][ T316] ? validate_chain+0x38b/0xc20 [ 17.331687][ T316] hvc_console_print+0x292/0x780 [ 17.331690][ T316] ? __lock_acquire+0x518/0xc20 [ 17.331695][ T316] ? hvc_write+0x3a0/0x3a0 [ 17.331697][ T316] ? rcu_is_watching+0x16/0xd0 [ 17.331702][ T316] console_emit_next_record+0x22f/0x4c0 [ 17.331706][ T316] ? devkmsg_read+0x4b0/0x4b0 [ 17.331708][ T316] ? console_flush_one_record+0x106/0x710 [ 17.331711][ T316] ? rcu_is_watching+0x16/0xd0 [ 17.331713][ T316] ? lock_acquire+0x13c/0x160 [ 17.331716][ T316] console_flush_one_record+0x46f/0x710 [ 17.331720][ T316] ? console_emit_next_record+0x4c0/0x4c0 [ 17.331722][ T316] ? __lock_acquire+0x518/0xc20 [ 17.331727][ T316] console_unlock+0xee/0x1f0 [ 17.331730][ T316] ? console_flush_one_record+0x710/0x710 [ 17.331731][ T316] ? rcu_is_watching+0x16/0xd0 [ 17.331734][ T316] ? lock_acquire+0x60/0x160 [ 17.331737][ T316] ? __down_trylock_console_sem+0x5e/0xa0 [ 17.331739][ T316] ? vprintk_emit+0x320/0x3e0 [ 17.331742][ T316] vprintk_emit+0x37c/0x3e0 [ 17.331746][ T316] ? wake_up_klogd_work_func+0x90/0x90 [ 17.331749][ T316] ? netdev_run_todo+0x2a8/0xfd0 [ 17.331753][ T316] ? mirred_device_event+0x3d/0x1d0 [act_mirred] [ 17.331756][ T316] ? rcu_is_watching+0x16/0xd0 [ 17.331760][ T316] ? llc_exit+0x3e0/0x3e0 [llc] [ 17.331763][ T316] _printk+0xc7/0x100 [ 17.331766][ T316] ? snapshot_read.cold+0x21/0x21 [ 17.331768][ T316] ? __lock_release.isra.0+0x69/0x1a0 [ 17.331771][ T316] ? llc_exit+0x3e0/0x3e0 [llc] [ 17.331773][ T316] ? __up_write+0x23d/0x5f0 [ 17.331774][ T316] ? __up_write+0x2e5/0x5f0 [ 17.331778][ T316] ? __up_write+0x2c7/0x5f0 [ 17.331780][ T316] mirred_init_module+0x28/0x1000 [act_mirred] [ 17.331783][ T316] do_one_initcall+0x124/0x4f0 [ 17.331786][ T316] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 17.331790][ T316] ? do_init_module+0xae/0x8a0 [ 17.331793][ T316] ? kasan_unpoison+0x44/0x70 [ 17.331798][ T316] do_init_module+0x2ad/0x8a0 [ 17.331801][ T316] ? lockdep_hardirqs_on+0x91/0x130 [ 17.331805][ T316] ? free_module+0x470/0x470 [ 17.331808][ T316] ? kfree+0x123/0x590 [ 17.331812][ T316] load_module+0x1424/0x1bb0 [ 17.331819][ T316] ? layout_and_allocate.isra.0+0x680/0x680 [ 17.331823][ T316] ? __ia32_sys_fsconfig+0x180/0x180 [ 17.331825][ T316] ? ovl_real_file+0x172/0x1e0 [ 17.331829][ T316] ? find_held_lock+0x2b/0x80 [ 17.331831][ T316] ? backing_file_read_iter+0x541/0x790 [ 17.331834][ T316] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 17.331838][ T316] init_module_from_file+0x11f/0x1a0 [ 17.331841][ T316] ? __do_sys_init_module+0x220/0x220 [ 17.331848][ T316] ? __rwlock_init+0x150/0x150 [ 17.331852][ T316] ? do_raw_spin_unlock+0x59/0x250 [ 17.331855][ T316] idempotent_init_module+0x37b/0x7c0 [ 17.331858][ T316] ? init_module_from_file+0x1a0/0x1a0 [ 17.331864][ T316] ? rcu_is_watching+0x16/0xd0 [ 17.331866][ T316] ? cap_capable+0x1d7/0x3d0 [ 17.331872][ T316] __x64_sys_finit_module+0xdf/0x190 [ 17.331874][ T316] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 17.331877][ T316] do_syscall_64+0xff/0x530 [ 17.331880][ T316] ? exc_page_fault+0xee/0x100 [ 17.331882][ T316] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 17.331884][ T316] RIP: 0033:0x7f64d12fb37d [ 17.331887][ T316] 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.331889][ T316] RSP: 002b:00007ffe84cd68b8 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 17.331892][ T316] RAX: ffffffffffffffda RBX: 00005626b6445810 RCX: 00007f64d12fb37d [ 17.331893][ T316] RDX: 0000000000000000 RSI: 00005626a6054ffd RDI: 0000000000000000 [ 17.331894][ T316] RBP: 00007ffe84cd6950 R08: 0000000000000000 R09: 0000000000000000 [ 17.331895][ T316] R10: 0000000000000000 R11: 0000000000000246 R12: 00005626a6054ffd [ 17.331896][ T316] R13: 00005626b6445960 R14: 0000000000040000 R15: 0000000000000000 [ 17.331902][ T316] [ 26.483341][ C2] [ 26.483354][ C2] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 26.483357][ C2] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/2 [ 26.483358][ C2] preempt_count: 104, expected: 0 [ 26.483359][ C2] RCU nest depth: 0, expected: 0 [ 26.483360][ C2] INFO: lockdep is turned off. [ 26.483361][ C2] irq event stamp: 735246 [ 26.483361][ C2] hardirqs last enabled at (735246): [] _raw_spin_unlock_irq+0x28/0x50 [ 26.483370][ C2] hardirqs last disabled at (735245): [] _raw_spin_lock_irq+0x4a/0x50 [ 26.483372][ C2] softirqs last enabled at (735188): [] handle_softirqs+0x67c/0x900 [ 26.483376][ C2] softirqs last disabled at (735243): [] __irq_exit_rcu+0x145/0x1c0 [ 26.483377][ C2] Preemption disabled at: [ 26.483378][ C2] [<0000000000000000>] 0x0 [ 26.483384][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.483388][ C2] Tainted: [W]=WARN [ 26.483393][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.483394][ C2] Call Trace: [ 26.483396][ C2] [ 26.483398][ C2] dump_stack_lvl+0x6f/0xa0 [ 26.483403][ C2] __might_resched.cold+0x1fe/0x2c1 [ 26.483407][ C2] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 26.483410][ C2] ? __asan_memcpy+0x3c/0x60 [ 26.483413][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.483417][ C2] __kmalloc_noprof+0x443/0x760 [ 26.483420][ C2] ? __rwlock_init+0x150/0x150 [ 26.483423][ C2] ? alloc_buf.isra.0+0x4b/0x260 [ 26.483426][ C2] ? do_raw_spin_unlock+0x59/0x250 [ 26.483428][ C2] alloc_buf.isra.0+0x4b/0x260 [ 26.483430][ C2] put_chars+0x1e1/0x2f0 [ 26.483432][ C2] ? __send_to_port+0x420/0x420 [ 26.483433][ C2] ? console_prepend_replay+0x20/0x20 [ 26.483437][ C2] hvc_console_print+0x292/0x780 [ 26.483440][ C2] ? hvc_write+0x3a0/0x3a0 [ 26.483441][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.483443][ C2] ? lock_acquire+0x13c/0x160 [ 26.483445][ C2] console_emit_next_record+0x22f/0x4c0 [ 26.483448][ C2] ? devkmsg_read+0x4b0/0x4b0 [ 26.483450][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.483451][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.483453][ C2] ? lock_acquire+0x13c/0x160 [ 26.483455][ C2] ? console_flush_one_record+0x111/0x710 [ 26.483456][ C2] console_flush_one_record+0x46f/0x710 [ 26.483458][ C2] ? console_emit_next_record+0x4c0/0x4c0 [ 26.483461][ C2] console_unlock+0xee/0x1f0 [ 26.483463][ C2] ? lock_acquire+0x13c/0x160 [ 26.483464][ C2] ? console_flush_one_record+0x710/0x710 [ 26.483466][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.483467][ C2] ? lock_release+0x184/0x1f0 [ 26.483469][ C2] ? lock_acquire+0x60/0x160 [ 26.483470][ C2] ? __down_trylock_console_sem+0x5e/0xa0 [ 26.483472][ C2] ? vprintk_emit+0x320/0x3e0 [ 26.483474][ C2] vprintk_emit+0x37c/0x3e0 [ 26.483477][ C2] ? wake_up_klogd_work_func+0x90/0x90 [ 26.483478][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.483490][ C2] ? lock_release+0x184/0x1f0 [ 26.483492][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.483498][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.483504][ C2] ? is_module_text_address+0x154/0x250 [ 26.483508][ C2] _printk+0xc7/0x100 [ 26.483511][ C2] ? snapshot_read.cold+0x21/0x21 [ 26.483513][ C2] ? arch_stack_walk+0xd7/0x130 [ 26.483517][ C2] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 26.483519][ C2] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 26.483521][ C2] print_irq_inversion_bug.part.0+0x32/0xc0 [ 26.483524][ C2] mark_lock_irq+0x989/0x9c0 [ 26.483526][ C2] mark_lock+0x1d7/0xa00 [ 26.483528][ C2] mark_usage+0x42/0x170 [ 26.483530][ C2] __lock_acquire+0x388/0xc20 [ 26.483532][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.483533][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.483540][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.483542][ C2] ? lock_acquire+0x13c/0x160 [ 26.483544][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.483550][ C2] _raw_spin_lock+0x33/0x40 [ 26.483551][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.483558][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.483565][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.483571][ C2] call_timer_fn+0x160/0x4d0 [ 26.483574][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 26.483576][ C2] ? debug_object_active_state+0x430/0x430 [ 26.483579][ C2] ? find_held_lock+0x2b/0x80 [ 26.483581][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 26.483583][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.483585][ C2] __run_timers+0x68f/0xaa0 [ 26.483587][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.483594][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.483595][ C2] ? __lock_acquire+0x518/0xc20 [ 26.483598][ C2] ? __rwlock_init+0x150/0x150 [ 26.483600][ C2] run_timer_softirq+0xf0/0x160 [ 26.483602][ C2] ? __run_timers+0xaa0/0xaa0 [ 26.483604][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.483606][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.483608][ C2] handle_softirqs+0x1d3/0x900 [ 26.483610][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 26.483611][ C2] ? _local_bh_enable+0xc0/0xc0 [ 26.483613][ C2] __irq_exit_rcu+0x145/0x1c0 [ 26.483615][ C2] irq_exit_rcu+0xe/0x30 [ 26.483616][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.483619][ C2] [ 26.483620][ C2] [ 26.483620][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 26.483622][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.483624][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.483626][ C2] Code: 48 8b 3d 94 d2 f5 01 e8 1f 00 00 00 48 2b 05 58 93 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 86 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 26.483628][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 26.483631][ C2] RAX: 00000000000b3809 RBX: ff11000001bec6c0 RCX: ffffffffaeaf0307 [ 26.483632][ C2] RDX: ff11000001bec6c0 RSI: ffffffffb1838af6 RDI: ffffffffb128d8e0 [ 26.483633][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.483634][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 26.483635][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 26.483636][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.483639][ C2] default_idle+0x9/0x10 [ 26.483641][ C2] default_idle_call+0x6e/0xb0 [ 26.483643][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.483644][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 26.483646][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 26.483648][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.483650][ C2] do_idle+0xd8/0x190 [ 26.483652][ C2] cpu_startup_entry+0x53/0x70 [ 26.483653][ C2] start_secondary+0x204/0x2b0 [ 26.483655][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 26.483657][ C2] common_startup_64+0x13e/0x148 [ 26.483661][ C2] [ 26.505329][ C2] ======================================================== [ 26.505577][ C2] WARNING: possible irq lock inversion dependency detected [ 26.505771][ C2] 7.2.0-virtme #1 Tainted: G W [ 26.505976][ C2] -------------------------------------------------------- [ 26.506212][ C2] swapper/2/0 just changed the state of lock: [ 26.506371][ C2] ff1100000d522e58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.506691][ C2] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 26.506924][ C2] (fs_reclaim){+.+.}-{0:0} [ 26.506926][ C2] [ 26.506926][ C2] [ 26.506926][ C2] and interrupts could create inverse lock ordering between them. [ 26.506926][ C2] [ 26.507493][ C2] [ 26.507493][ C2] other info that might help us debug this: [ 26.507729][ C2] Chain exists of: [ 26.507729][ C2] &br->lock --> console_owner --> fs_reclaim [ 26.507729][ C2] [ 26.508065][ C2] Possible interrupt unsafe locking scenario: [ 26.508065][ C2] [ 26.508311][ C2] CPU0 CPU1 [ 26.508449][ C2] ---- ---- [ 26.508623][ C2] lock(fs_reclaim); [ 26.508726][ C2] local_irq_disable(); [ 26.508932][ C2] lock(&br->lock); [ 26.509095][ C2] lock(console_owner); [ 26.509301][ C2] [ 26.509405][ C2] lock(&br->lock); [ 26.509550][ C2] [ 26.509550][ C2] *** DEADLOCK *** [ 26.509550][ C2] [ 26.509749][ C2] locks held by swapper/2/0: 1, last CPU#2: [ 26.509956][ C2] #0: ffa0000000228c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 26.510263][ C2] [ 26.510263][ C2] the shortest dependencies between 2nd lock and 1st lock: [ 26.510538][ C2] -> (fs_reclaim){+.+.}-{0:0} { [ 26.510673][ C2] HARDIRQ-ON-W at: [ 26.510822][ C2] __lock_acquire+0x388/0xc20 [ 26.510992][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.511201][ C2] fs_reclaim_acquire+0xd5/0x120 [ 26.511415][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 26.511614][ C2] kthread_create_worker_on_node+0xea/0x210 [ 26.511858][ C2] workqueue_init+0x2a/0x680 [ 26.512069][ C2] kernel_init_freeable+0x2fe/0x630 [ 26.512315][ C2] kernel_init+0x21/0x150 [ 26.512485][ C2] ret_from_fork+0x474/0x6b0 [ 26.512697][ C2] ret_from_fork_asm+0x11/0x20 [ 26.512866][ C2] SOFTIRQ-ON-W at: [ 26.513009][ C2] __lock_acquire+0x388/0xc20 [ 26.513177][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.513402][ C2] fs_reclaim_acquire+0xd5/0x120 [ 26.513615][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 26.513814][ C2] kthread_create_worker_on_node+0xea/0x210 [ 26.514060][ C2] workqueue_init+0x2a/0x680 [ 26.514271][ C2] kernel_init_freeable+0x2fe/0x630 [ 26.514518][ C2] kernel_init+0x21/0x150 [ 26.514684][ C2] ret_from_fork+0x474/0x6b0 [ 26.514893][ C2] ret_from_fork_asm+0x11/0x20 [ 26.515059][ C2] INITIAL USE at: [ 26.515201][ C2] __lock_acquire+0x388/0xc20 [ 26.515367][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.515578][ C2] fs_reclaim_acquire+0xd5/0x120 [ 26.515788][ C2] __kmalloc_cache_noprof+0x6e/0x620 [ 26.515989][ C2] kthread_create_worker_on_node+0xea/0x210 [ 26.516231][ C2] workqueue_init+0x2a/0x680 [ 26.516447][ C2] kernel_init_freeable+0x2fe/0x630 [ 26.516614][ C2] kernel_init+0x21/0x150 [ 26.516826][ C2] ret_from_fork+0x474/0x6b0 [ 26.517036][ C2] ret_from_fork_asm+0x11/0x20 [ 26.517205][ C2] } [ 26.517318][ C2] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 26.517523][ C2] ... acquired at: [ 26.517668][ C2] __lock_acquire+0x518/0xc20 [ 26.517802][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.517976][ C2] fs_reclaim_acquire+0xd5/0x120 [ 26.518107][ C2] __kmalloc_noprof+0xd3/0x760 [ 26.518289][ C2] alloc_buf.isra.0+0x4b/0x260 [ 26.518425][ C2] put_chars+0x1e1/0x2f0 [ 26.518599][ C2] hvc_console_print+0x292/0x780 [ 26.518732][ C2] console_emit_next_record+0x22f/0x4c0 [ 26.518908][ C2] console_flush_one_record+0x46f/0x710 [ 26.519042][ C2] console_unlock+0xee/0x1f0 [ 26.519218][ C2] vprintk_emit+0x37c/0x3e0 [ 26.519349][ C2] dev_vprintk_emit+0x27f/0x2c0 [ 26.519533][ C2] dev_printk_emit+0xb9/0xee [ 26.519667][ C2] _dev_info+0xe2/0x116 [ 26.519810][ C2] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 26.519974][ C2] cmos_do_probe+0x73b/0x98a [ 26.520149][ C2] platform_probe+0xfe/0x1f0 [ 26.520282][ C2] call_driver_probe+0x61/0x1c0 [ 26.520464][ C2] really_probe+0x199/0x760 [ 26.520591][ C2] __driver_probe_device+0x24f/0x440 [ 26.520766][ C2] driver_probe_device+0x4a/0xf0 [ 26.520898][ C2] __driver_attach+0x1b8/0x540 [ 26.521071][ C2] bus_for_each_dev+0x130/0x1e0 [ 26.521199][ C2] bus_add_driver+0x2c8/0x530 [ 26.521372][ C2] driver_register+0x1a3/0x390 [ 26.521511][ C2] __platform_driver_probe+0x13f/0x270 [ 26.521685][ C2] cmos_init+0x31/0x40 [ 26.521785][ C2] do_one_initcall+0x124/0x4f0 [ 26.521958][ C2] kernel_init_freeable+0x596/0x630 [ 26.522086][ C2] kernel_init+0x21/0x150 [ 26.522261][ C2] ret_from_fork+0x474/0x6b0 [ 26.522394][ C2] ret_from_fork_asm+0x11/0x20 [ 26.522569][ C2] [ 26.522638][ C2] -> (console_owner){....}-{0:0} { [ 26.522771][ C2] INITIAL USE at: [ 26.522913][ C2] } [ 26.522978][ C2] ... key at: [] console_owner_dep_map+0x0/0x60 [ 26.523216][ C2] ... acquired at: [ 26.523315][ C2] __lock_acquire+0x518/0xc20 [ 26.523499][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.523631][ C2] console_lock_spinning_enable+0x5c/0x60 [ 26.523837][ C2] console_emit_next_record+0x1d1/0x4c0 [ 26.523969][ C2] console_flush_one_record+0x46f/0x710 [ 26.524145][ C2] console_unlock+0xee/0x1f0 [ 26.524275][ C2] vprintk_emit+0x37c/0x3e0 [ 26.524459][ C2] _printk+0xc7/0x100 [ 26.524560][ C2] br_set_state+0x22f/0x430 [bridge] [ 26.524746][ C2] br_init_port+0xc4/0x200 [bridge] [ 26.524884][ C2] br_stp_enable_port+0x12/0x50 [bridge] [ 26.525097][ C2] br_port_carrier_check+0x220/0x430 [bridge] [ 26.525266][ C2] br_device_event+0x52d/0x8f0 [bridge] [ 26.525453][ C2] notifier_call_chain+0xae/0x300 [ 26.525584][ C2] netif_state_change+0x139/0x340 [ 26.525762][ C2] __linkwatch_run_queue+0x34c/0x750 [ 26.525898][ C2] linkwatch_event+0x7f/0xb0 [ 26.526074][ C2] process_one_work+0xe3e/0x1560 [ 26.526205][ C2] worker_thread+0x4f1/0xd60 [ 26.526382][ C2] kthread+0x367/0x460 [ 26.526486][ C2] ret_from_fork+0x474/0x6b0 [ 26.526664][ C2] ret_from_fork_asm+0x11/0x20 [ 26.526794][ C2] [ 26.526860][ C2] -> (&br->lock){+.-.}-{3:3} { [ 26.527041][ C2] HARDIRQ-ON-W at: [ 26.527144][ C2] __lock_acquire+0x388/0xc20 [ 26.527368][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.527656][ C2] _raw_spin_lock_bh+0x38/0x50 [ 26.527830][ C2] recalculate_group_addr+0x51/0x120 [bridge] [ 26.528081][ C2] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 26.528334][ C2] br_changelink+0x575/0x16e0 [bridge] [ 26.528516][ C2] br_dev_newlink+0xeb/0x160 [bridge] [ 26.528733][ C2] rtnl_newlink_create+0x2d0/0x750 [ 26.528949][ C2] __rtnl_newlink+0x22b/0xa50 [ 26.529121][ C2] rtnl_newlink+0x9f1/0x11c0 [ 26.529342][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.529514][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 26.529740][ C2] netlink_unicast+0x486/0x750 [ 26.529960][ C2] netlink_sendmsg+0x735/0xc60 [ 26.530125][ C2] ____sys_sendmsg+0x415/0x880 [ 26.530342][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 26.530564][ C2] __sys_sendmsg+0x12c/0x1d0 [ 26.530734][ C2] do_syscall_64+0xff/0x530 [ 26.530954][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.531209][ C2] IN-SOFTIRQ-W at: [ 26.531311][ C2] __lock_acquire+0x388/0xc20 [ 26.531535][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.531705][ C2] _raw_spin_lock+0x33/0x40 [ 26.531926][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.532182][ C2] call_timer_fn+0x160/0x4d0 [ 26.532355][ C2] __run_timers+0x68f/0xaa0 [ 26.532580][ C2] run_timer_softirq+0xf0/0x160 [ 26.532748][ C2] handle_softirqs+0x1d3/0x900 [ 26.532967][ C2] __irq_exit_rcu+0x145/0x1c0 [ 26.533188][ C2] irq_exit_rcu+0xe/0x30 [ 26.533358][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.533623][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.533880][ C2] pv_native_safe_halt+0xf/0x10 [ 26.534050][ C2] default_idle+0x9/0x10 [ 26.534270][ C2] default_idle_call+0x6e/0xb0 [ 26.534501][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.534703][ C2] do_idle+0xd8/0x190 [ 26.534884][ C2] cpu_startup_entry+0x53/0x70 [ 26.535104][ C2] start_secondary+0x204/0x2b0 [ 26.535292][ C2] common_startup_64+0x13e/0x148 [ 26.535522][ C2] INITIAL USE at: [ 26.535626][ C2] __lock_acquire+0x388/0xc20 [ 26.535844][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.536015][ C2] _raw_spin_lock_bh+0x38/0x50 [ 26.536230][ C2] recalculate_group_addr+0x51/0x120 [bridge] [ 26.536490][ C2] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 26.536749][ C2] br_changelink+0x575/0x16e0 [bridge] [ 26.536925][ C2] br_dev_newlink+0xeb/0x160 [bridge] [ 26.537151][ C2] rtnl_newlink_create+0x2d0/0x750 [ 26.537344][ C2] __rtnl_newlink+0x22b/0xa50 [ 26.537575][ C2] rtnl_newlink+0x9f1/0x11c0 [ 26.537794][ C2] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.537972][ C2] netlink_rcv_skb+0x14e/0x3a0 [ 26.538188][ C2] netlink_unicast+0x486/0x750 [ 26.538418][ C2] netlink_sendmsg+0x735/0xc60 [ 26.538591][ C2] ____sys_sendmsg+0x415/0x880 [ 26.538809][ C2] ___sys_sendmsg+0x14e/0x1d0 [ 26.538985][ C2] __sys_sendmsg+0x12c/0x1d0 [ 26.539205][ C2] do_syscall_64+0xff/0x530 [ 26.539432][ C2] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.539644][ C2] } [ 26.539759][ C2] ... key at: [] __key.7+0x0/0x40 [bridge] [ 26.539978][ C2] ... acquired at: [ 26.540123][ C2] mark_lock+0x1d7/0xa00 [ 26.540267][ C2] mark_usage+0x42/0x170 [ 26.540452][ C2] __lock_acquire+0x388/0xc20 [ 26.540584][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.540760][ C2] _raw_spin_lock+0x33/0x40 [ 26.540898][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.541122][ C2] call_timer_fn+0x160/0x4d0 [ 26.541256][ C2] __run_timers+0x68f/0xaa0 [ 26.541450][ C2] run_timer_softirq+0xf0/0x160 [ 26.541586][ C2] handle_softirqs+0x1d3/0x900 [ 26.541770][ C2] __irq_exit_rcu+0x145/0x1c0 [ 26.541907][ C2] irq_exit_rcu+0xe/0x30 [ 26.542085][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.542299][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.542469][ C2] pv_native_safe_halt+0xf/0x10 [ 26.542650][ C2] default_idle+0x9/0x10 [ 26.542784][ C2] default_idle_call+0x6e/0xb0 [ 26.542962][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.543126][ C2] do_idle+0xd8/0x190 [ 26.543270][ C2] cpu_startup_entry+0x53/0x70 [ 26.543408][ C2] start_secondary+0x204/0x2b0 [ 26.543590][ C2] common_startup_64+0x13e/0x148 [ 26.543723][ C2] [ 26.543788][ C2] [ 26.543788][ C2] stack backtrace: [ 26.543998][ C2] CPU: 2 UID: 0 PID: 0 Comm: swapper/2 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.544001][ C2] Tainted: [W]=WARN [ 26.544002][ C2] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.544003][ C2] Call Trace: [ 26.544004][ C2] [ 26.544005][ C2] dump_stack_lvl+0x6f/0xa0 [ 26.544008][ C2] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 26.544011][ C2] mark_lock_irq+0x989/0x9c0 [ 26.544014][ C2] mark_lock+0x1d7/0xa00 [ 26.544016][ C2] mark_usage+0x42/0x170 [ 26.544017][ C2] __lock_acquire+0x388/0xc20 [ 26.544020][ C2] lock_acquire.part.0+0xd4/0x280 [ 26.544021][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.544028][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.544031][ C2] ? lock_acquire+0x13c/0x160 [ 26.544033][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.544039][ C2] _raw_spin_lock+0x33/0x40 [ 26.544041][ C2] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.544047][ C2] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.544053][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.544059][ C2] call_timer_fn+0x160/0x4d0 [ 26.544061][ C2] ? detach_if_pending+0x1d0/0x1d0 [ 26.544063][ C2] ? debug_object_active_state+0x430/0x430 [ 26.544066][ C2] ? find_held_lock+0x2b/0x80 [ 26.544068][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 26.544070][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.544072][ C2] __run_timers+0x68f/0xaa0 [ 26.544074][ C2] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.544080][ C2] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.544082][ C2] ? __lock_acquire+0x518/0xc20 [ 26.544085][ C2] ? __rwlock_init+0x150/0x150 [ 26.544087][ C2] run_timer_softirq+0xf0/0x160 [ 26.544089][ C2] ? __run_timers+0xaa0/0xaa0 [ 26.544091][ C2] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.544093][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.544095][ C2] handle_softirqs+0x1d3/0x900 [ 26.544097][ C2] ? __lock_release.isra.0+0x69/0x1a0 [ 26.544098][ C2] ? _local_bh_enable+0xc0/0xc0 [ 26.544100][ C2] __irq_exit_rcu+0x145/0x1c0 [ 26.544101][ C2] irq_exit_rcu+0xe/0x30 [ 26.544103][ C2] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.544105][ C2] [ 26.544105][ C2] [ 26.544106][ C2] ? lockdep_hardirqs_on+0x91/0x130 [ 26.544108][ C2] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.544109][ C2] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.544111][ C2] Code: 48 8b 3d 94 d2 f5 01 e8 1f 00 00 00 48 2b 05 58 93 95 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 86 11 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 26.544112][ C2] RSP: 0018:ffa0000000157e00 EFLAGS: 00000282 [ 26.544114][ C2] RAX: 00000000000b3809 RBX: ff11000001bec6c0 RCX: ffffffffaeaf0307 [ 26.544115][ C2] RDX: ff11000001bec6c0 RSI: ffffffffb1838af6 RDI: ffffffffb128d8e0 [ 26.544116][ C2] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.544117][ C2] R10: 0000000000000002 R11: 0000000000000001 R12: 1ff400000002afc3 [ 26.544118][ C2] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 26.544119][ C2] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.544121][ C2] default_idle+0x9/0x10 [ 26.544123][ C2] default_idle_call+0x6e/0xb0 [ 26.544124][ C2] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.544126][ C2] ? arch_cpu_idle_exit+0x40/0x40 [ 26.544128][ C2] ? mark_tsc_async_resets+0x30/0x30 [ 26.544129][ C2] ? rcu_is_watching+0x16/0xd0 [ 26.544131][ C2] do_idle+0xd8/0x190 [ 26.544133][ C2] cpu_startup_entry+0x53/0x70 [ 26.544134][ C2] start_secondary+0x204/0x2b0 [ 26.544136][ C2] ? set_cpu_sibling_map+0x2130/0x2130 [ 26.544138][ C2] common_startup_64+0x13e/0x148 [ 26.544140][ C2] [ 43.892733][ T504] br1: port 1(veth1) entered disabled state [ 43.892746][ T504] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 43.892749][ T504] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 504, name: ip [ 43.892751][ T504] preempt_count: 202, expected: 0 [ 43.892752][ T504] RCU nest depth: 0, expected: 0 [ 43.892752][ T504] INFO: lockdep is turned off. [ 43.892753][ T504] irq event stamp: 0 [ 43.892754][ T504] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 43.892759][ T504] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 43.892765][ T504] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 43.892767][ T504] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 43.892769][ T504] Preemption disabled at: [ 43.892769][ T504] [<0000000000000000>] 0x0 [ 43.892774][ T504] CPU: 2 UID: 0 PID: 504 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 43.892778][ T504] Tainted: [W]=WARN [ 43.892779][ T504] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 43.892780][ T504] Call Trace: [ 43.892782][ T504] [ 43.892783][ T504] dump_stack_lvl+0x6f/0xa0 [ 43.892789][ T504] __might_resched.cold+0x1fe/0x2c1 [ 43.892793][ T504] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 43.892797][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.892801][ T504] __kmalloc_noprof+0x443/0x760 [ 43.892804][ T504] ? __rwlock_init+0x150/0x150 [ 43.892807][ T504] ? alloc_buf.isra.0+0x4b/0x260 [ 43.892811][ T504] ? do_raw_spin_unlock+0x59/0x250 [ 43.892813][ T504] alloc_buf.isra.0+0x4b/0x260 [ 43.892815][ T504] put_chars+0x1e1/0x2f0 [ 43.892817][ T504] ? __send_to_port+0x420/0x420 [ 43.892818][ T504] ? printk_get_next_message+0x240/0x650 [ 43.892822][ T504] hvc_console_print+0x292/0x780 [ 43.892825][ T504] ? hvc_write+0x3a0/0x3a0 [ 43.892828][ T504] ? __rwlock_init+0x150/0x150 [ 43.892829][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.892831][ T504] ? lock_acquire+0x13c/0x160 [ 43.892833][ T504] console_emit_next_record+0x22f/0x4c0 [ 43.892836][ T504] ? devkmsg_read+0x4b0/0x4b0 [ 43.892838][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.892839][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.892841][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.892843][ T504] ? lock_acquire+0x13c/0x160 [ 43.892844][ T504] ? console_flush_one_record+0x111/0x710 [ 43.892846][ T504] console_flush_one_record+0x46f/0x710 [ 43.892848][ T504] ? console_emit_next_record+0x4c0/0x4c0 [ 43.892851][ T504] console_unlock+0xee/0x1f0 [ 43.892853][ T504] ? lock_acquire+0x13c/0x160 [ 43.892854][ T504] ? console_flush_one_record+0x710/0x710 [ 43.892856][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.892857][ T504] ? lock_release+0x184/0x1f0 [ 43.892859][ T504] ? lock_acquire+0x60/0x160 [ 43.892860][ T504] ? __rcu_read_lock+0x30/0x70 [ 43.892862][ T504] ? __down_trylock_console_sem+0x5e/0xa0 [ 43.892864][ T504] ? vprintk_emit+0x320/0x3e0 [ 43.892866][ T504] vprintk_emit+0x37c/0x3e0 [ 43.892868][ T504] ? wake_up_klogd_work_func+0x90/0x90 [ 43.892870][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.892872][ T504] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 43.892874][ T504] ? trace_hardirqs_on+0x36/0x40 [ 43.892876][ T504] _printk+0xc7/0x100 [ 43.892880][ T504] ? snapshot_read.cold+0x21/0x21 [ 43.892882][ T504] ? ipv6_mc_down+0x17e/0x2d0 [ 43.892886][ T504] br_set_state+0x22f/0x430 [bridge] [ 43.892899][ T504] br_stp_disable_port+0x11f/0x390 [bridge] [ 43.892907][ T504] br_device_event+0x7b5/0x8f0 [bridge] [ 43.892917][ T504] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 43.892920][ T504] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 43.892922][ T504] ? addrconf_notify+0x2c8/0xf30 [ 43.892926][ T504] notifier_call_chain+0xae/0x300 [ 43.892929][ T504] __dev_notify_flags+0x23c/0x3c0 [ 43.892933][ T504] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 43.892934][ T504] ? netif_change_name+0x8c0/0x8c0 [ 43.892936][ T504] ? netif_set_allmulti+0x250/0x250 [ 43.892938][ T504] ? __kernel_text_address+0x12/0x30 [ 43.892940][ T504] ? write_profile+0xf0/0xf0 [ 43.892943][ T504] ? unwind_get_return_address+0x67/0xd0 [ 43.892946][ T504] netif_change_flags+0xfe/0x190 [ 43.892948][ T504] do_setlink.isra.0+0x1cc3/0x2750 [ 43.892951][ T504] ? stack_trace_save+0x93/0xc0 [ 43.892952][ T504] ? rtnl_link_get_size+0x350/0x350 [ 43.892954][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.892955][ T504] ? stack_depot_save_flags+0x1bc/0x790 [ 43.892959][ T504] ? kasan_save_stack+0x3d/0x50 [ 43.892962][ T504] ? kasan_save_stack+0x2f/0x50 [ 43.892964][ T504] ? kasan_save_track+0x14/0x30 [ 43.892965][ T504] ? __kasan_kmalloc+0x7b/0x90 [ 43.892966][ T504] ? __kmalloc_cache_noprof+0x29a/0x620 [ 43.892968][ T504] ? rtnl_newlink+0x124/0x11c0 [ 43.892969][ T504] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 43.892970][ T504] ? netlink_rcv_skb+0x14e/0x3a0 [ 43.892973][ T504] ? netlink_unicast+0x486/0x750 [ 43.892974][ T504] ? netlink_sendmsg+0x735/0xc60 [ 43.892975][ T504] ? ____sys_sendmsg+0x415/0x880 [ 43.892978][ T504] ? ___sys_sendmsg+0x14e/0x1d0 [ 43.892979][ T504] ? __sys_sendmsg+0x12c/0x1d0 [ 43.892981][ T504] ? do_syscall_64+0xff/0x530 [ 43.892983][ T504] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 43.892986][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.892988][ T504] ? lock_acquire+0x13c/0x160 [ 43.892989][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.892991][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.892992][ T504] ? trace_contention_end+0xb3/0x180 [ 43.892995][ T504] ? __mutex_lock+0x1db/0x1ea0 [ 43.892997][ T504] ? __mutex_lock+0x9a3/0x1ea0 [ 43.892998][ T504] ? rtnl_newlink+0x9a8/0x11c0 [ 43.893000][ T504] ? ww_mutex_lock+0x160/0x160 [ 43.893002][ T504] ? __rtnl_newlink+0x3fa/0xa50 [ 43.893004][ T504] rtnl_newlink+0x9f1/0x11c0 [ 43.893006][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.893008][ T504] ? rtnl_bridge_getlink+0x850/0x850 [ 43.893009][ T504] ? lock_acquire+0x13c/0x160 [ 43.893010][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.893012][ T504] ? is_bpf_text_address+0x3e/0x110 [ 43.893016][ T504] ? is_bpf_text_address+0x72/0x110 [ 43.893017][ T504] ? kernel_text_address+0x149/0x170 [ 43.893019][ T504] ? __kernel_text_address+0x12/0x30 [ 43.893020][ T504] ? write_profile+0xf0/0xf0 [ 43.893021][ T504] ? unwind_get_return_address+0x67/0xd0 [ 43.893022][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.893024][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.893026][ T504] ? cap_capable+0x1d7/0x3d0 [ 43.893029][ T504] ? lock_acquire+0x13c/0x160 [ 43.893031][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.893032][ T504] ? rtnl_bridge_getlink+0x850/0x850 [ 43.893034][ T504] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 43.893035][ T504] ? rtnl_link_fill+0x920/0x920 [ 43.893037][ T504] ? filter_irq_stacks+0xd0/0xd0 [ 43.893038][ T504] ? stack_depot_save_flags+0x1bc/0x790 [ 43.893040][ T504] netlink_rcv_skb+0x14e/0x3a0 [ 43.893042][ T504] ? rtnl_link_fill+0x920/0x920 [ 43.893043][ T504] ? lock_release+0x184/0x1f0 [ 43.893045][ T504] ? netlink_ack+0xcf0/0xcf0 [ 43.893046][ T504] ? __netlink_lookup+0xb9/0x6b0 [ 43.893048][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.893050][ T504] ? lock_release+0x184/0x1f0 [ 43.893052][ T504] netlink_unicast+0x486/0x750 [ 43.893054][ T504] ? netlink_attachskb+0x810/0x810 [ 43.893056][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.893057][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.893059][ T504] ? lock_acquire+0x13c/0x160 [ 43.893061][ T504] netlink_sendmsg+0x735/0xc60 [ 43.893063][ T504] ? netlink_unicast+0x750/0x750 [ 43.893064][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.893066][ T504] ? lock_release+0x184/0x1f0 [ 43.893068][ T504] ____sys_sendmsg+0x415/0x880 [ 43.893070][ T504] ? copy_msghdr_from_user+0x279/0x420 [ 43.893071][ T504] ? get_timestamp.constprop.0+0x390/0x390 [ 43.893073][ T504] ? move_addr_to_kernel+0x40/0x40 [ 43.893074][ T504] ? filter_irq_stacks+0xd0/0xd0 [ 43.893077][ T504] ___sys_sendmsg+0x14e/0x1d0 [ 43.893079][ T504] ? copy_msghdr_from_user+0x420/0x420 [ 43.893083][ T504] __sys_sendmsg+0x12c/0x1d0 [ 43.893085][ T504] ? __sys_sendmsg_sock+0x20/0x20 [ 43.893088][ T504] ? rcu_is_watching+0x16/0xd0 [ 43.893090][ T504] do_syscall_64+0xff/0x530 [ 43.893091][ T504] ? exc_page_fault+0xee/0x100 [ 43.893094][ T504] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 43.893095][ T504] RIP: 0033:0x7fc87f59154e [ 43.893097][ T504] 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 [ 43.893099][ T504] RSP: 002b:00007fff4a3a7720 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 43.893102][ T504] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007fc87f59154e [ 43.893104][ T504] RDX: 0000000000000000 RSI: 00007fff4a3a77d0 RDI: 0000000000000005 [ 43.893104][ T504] RBP: 00007fff4a3a7730 R08: 0000000000000000 R09: 0000000000000000 [ 43.893105][ T504] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a918110 [ 43.893106][ T504] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007fff4a3a7e90 [ 43.893108][ T504] [ 43.927384][ T504] ip (504) used greatest stack depth: 23016 bytes left [ 43.947633][ T505] br1: port 2(veth2) entered disabled state [ 43.961806][ T506] veth2: left allmulticast mode [ 43.961958][ T506] veth2: left promiscuous mode [ 43.962159][ T506] br1: port 2(veth2) entered disabled state [ 43.963663][ T506] veth1: left allmulticast mode [ 43.963802][ T506] veth1: left promiscuous mode [ 43.963986][ T506] br1: port 1(veth1) entered disabled state