[ 11.581454][ T254] br1: port 1(veth1) entered blocking state [ 11.581545][ T254] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.581547][ T254] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 254, name: ip [ 11.581549][ T254] preempt_count: 1, expected: 0 [ 11.581550][ T254] RCU nest depth: 0, expected: 0 [ 11.581551][ T254] locks held by ip/254: 5, last CPU#2: [ 11.581553][ T254] #0: ffffffffb60d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 11.581565][ T254] #1: ffffffffb5969cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 11.581571][ T254] #2: ffffffffb5969d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 11.581575][ T254] #3: ffffffffb5889660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 11.581579][ T254] #4: ffffffffb5889560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 11.581583][ T254] irq event stamp: 14474 [ 11.581583][ T254] hardirqs last enabled at (14473): [] __down_trylock_console_sem+0x86/0xa0 [ 11.581586][ T254] hardirqs last disabled at (14474): [] console_emit_next_record+0x3d4/0x4c0 [ 11.581590][ T254] softirqs last enabled at (14386): [] __alloc_skb+0x4c2/0x5f0 [ 11.581594][ T254] softirqs last disabled at (14384): [] __alloc_skb+0x4c2/0x5f0 [ 11.581596][ T254] Preemption disabled at: [ 11.581597][ T254] [] vprintk_emit+0x31b/0x3e0 [ 11.581603][ T254] CPU: 2 UID: 0 PID: 254 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.581606][ T254] Tainted: [W]=WARN [ 11.581607][ T254] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.581609][ T254] Call Trace: [ 11.581611][ T254] [ 11.581612][ T254] dump_stack_lvl+0x6f/0xa0 [ 11.581618][ T254] ? vprintk_emit+0x31b/0x3e0 [ 11.581621][ T254] __might_resched.cold+0x1fe/0x2c1 [ 11.581625][ T254] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.581629][ T254] ? __kmalloc_noprof+0xdb/0x760 [ 11.581634][ T254] __kmalloc_noprof+0x443/0x760 [ 11.581637][ T254] ? alloc_buf.isra.0+0x4b/0x260 [ 11.581642][ T254] ? do_raw_spin_unlock+0x59/0x250 [ 11.581645][ T254] alloc_buf.isra.0+0x4b/0x260 [ 11.581648][ T254] put_chars+0x1e1/0x2f0 [ 11.581651][ T254] ? __send_to_port+0x420/0x420 [ 11.581655][ T254] ? validate_chain+0x34a/0xc20 [ 11.581659][ T254] hvc_console_print+0x292/0x780 [ 11.581661][ T254] ? mark_usage+0x61/0x170 [ 11.581663][ T254] ? __lock_acquire+0x518/0xc20 [ 11.581665][ T254] ? __lock_acquire+0x518/0xc20 [ 11.581669][ T254] ? hvc_write+0x3a0/0x3a0 [ 11.581671][ T254] ? console_emit_next_record+0x1df/0x4c0 [ 11.581674][ T254] ? rcu_is_watching+0x16/0xd0 [ 11.581678][ T254] ? lock_acquire+0x13c/0x160 [ 11.581682][ T254] console_emit_next_record+0x22f/0x4c0 [ 11.581686][ T254] ? devkmsg_read+0x4b0/0x4b0 [ 11.581687][ T254] ? console_flush_one_record+0x106/0x710 [ 11.581690][ T254] ? rcu_is_watching+0x16/0xd0 [ 11.581693][ T254] ? lock_acquire+0x13c/0x160 [ 11.581697][ T254] console_flush_one_record+0x46f/0x710 [ 11.581701][ T254] ? console_emit_next_record+0x4c0/0x4c0 [ 11.581703][ T254] ? __lock_acquire+0x518/0xc20 [ 11.581708][ T254] console_unlock+0xee/0x1f0 [ 11.581710][ T254] ? console_flush_one_record+0x710/0x710 [ 11.581712][ T254] ? rcu_is_watching+0x16/0xd0 [ 11.581714][ T254] ? lock_acquire+0x60/0x160 [ 11.581718][ T254] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.581720][ T254] ? vprintk_emit+0x320/0x3e0 [ 11.581723][ T254] vprintk_emit+0x37c/0x3e0 [ 11.581727][ T254] ? wake_up_klogd_work_func+0x90/0x90 [ 11.581729][ T254] ? __lock_release.isra.0+0x69/0x1a0 [ 11.581731][ T254] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 11.581735][ T254] ? mark_held_locks+0x40/0x70 [ 11.581739][ T254] _printk+0xc7/0x100 [ 11.581742][ T254] ? snapshot_read.cold+0x21/0x21 [ 11.581746][ T254] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 11.581759][ T254] ? do_setlink.isra.0+0xa31/0x2750 [ 11.581760][ T254] ? rtnl_newlink+0x9f1/0x11c0 [ 11.581761][ T254] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.581766][ T254] br_set_state+0x22f/0x430 [bridge] [ 11.581776][ T254] br_init_port+0xc4/0x200 [bridge] [ 11.581784][ T254] new_nbp+0x39c/0x580 [bridge] [ 11.581793][ T254] br_add_if+0x212/0x1320 [bridge] [ 11.581800][ T254] ? is_bpf_text_address+0x72/0x110 [ 11.581804][ T254] ? kernel_text_address+0x149/0x170 [ 11.581807][ T254] ? __kernel_text_address+0x12/0x30 [ 11.581810][ T254] do_set_master+0x357/0x580 [ 11.581815][ T254] do_setlink.isra.0+0xa31/0x2750 [ 11.581818][ T254] ? stack_trace_save+0x93/0xc0 [ 11.581821][ T254] ? rtnl_link_get_size+0x350/0x350 [ 11.581822][ T254] ? rcu_read_lock_any_held+0x66/0x90 [ 11.581825][ T254] ? stack_depot_save_flags+0x38e/0x790 [ 11.581829][ T254] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 11.581831][ T254] ? rcu_read_lock_any_held+0x3c/0x90 [ 11.581833][ T254] ? validate_chain+0x38b/0xc20 [ 11.581836][ T254] ? kasan_save_stack+0x3d/0x50 [ 11.581839][ T254] ? kasan_save_stack+0x2f/0x50 [ 11.581841][ T254] ? kasan_save_track+0x14/0x30 [ 11.581843][ T254] ? __lock_acquire+0x518/0xc20 [ 11.581845][ T254] ? netlink_seq_next+0x4e/0x60 [ 11.581848][ T254] ? ___sys_sendmsg+0xb0/0x1d0 [ 11.581853][ T254] ? lock_acquire.part.0+0xd4/0x280 [ 11.581854][ T254] ? rtnl_newlink+0x9a8/0x11c0 [ 11.581857][ T254] ? rcu_is_watching+0x16/0xd0 [ 11.581859][ T254] ? lock_acquire+0x13c/0x160 [ 11.581861][ T254] ? rcu_is_watching+0x16/0xd0 [ 11.581863][ T254] ? rcu_is_watching+0x16/0xd0 [ 11.581865][ T254] ? trace_contention_end+0xb3/0x180 [ 11.581869][ T254] ? __mutex_lock+0x1db/0x1ea0 [ 11.581870][ T254] ? __mutex_lock+0x9a3/0x1ea0 [ 11.581872][ T254] ? rtnl_newlink+0x9a8/0x11c0 [ 11.581876][ T254] ? ww_mutex_lock+0x160/0x160 [ 11.581877][ T254] ? nla_get_range_signed+0x3d0/0x3d0 [ 11.581882][ T254] ? __rtnl_newlink+0x3fa/0xa50 [ 11.581887][ T254] rtnl_newlink+0x9f1/0x11c0 [ 11.581892][ T254] ? rtnl_bridge_getlink+0x850/0x850 [ 11.581894][ T254] ? __lock_acquire+0x518/0xc20 [ 11.581899][ T254] ? lock_acquire.part.0+0xd4/0x280 [ 11.581901][ T254] ? find_held_lock+0x2b/0x80 [ 11.581903][ T254] ? rtnl_bridge_getlink+0x850/0x850 [ 11.581904][ T254] ? __lock_release.isra.0+0x69/0x1a0 [ 11.581908][ T254] ? rtnl_bridge_getlink+0x850/0x850 [ 11.581910][ T254] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 11.581913][ T254] ? rtnl_link_fill+0x920/0x920 [ 11.581914][ T254] ? __lock_acquire+0x518/0xc20 [ 11.581919][ T254] ? lock_acquire.part.0+0xd4/0x280 [ 11.581921][ T254] ? find_held_lock+0x2b/0x80 [ 11.581924][ T254] netlink_rcv_skb+0x14e/0x3a0 [ 11.581926][ T254] ? rtnl_link_fill+0x920/0x920 [ 11.581929][ T254] ? netlink_ack+0xcf0/0xcf0 [ 11.581935][ T254] ? netlink_deliver_tap+0xc5/0x330 [ 11.581936][ T254] ? netlink_deliver_tap+0x13c/0x330 [ 11.581940][ T254] netlink_unicast+0x486/0x750 [ 11.581944][ T254] ? netlink_attachskb+0x810/0x810 [ 11.581947][ T254] ? __lock_acquire+0x518/0xc20 [ 11.581951][ T254] netlink_sendmsg+0x735/0xc60 [ 11.581954][ T254] ? netlink_unicast+0x750/0x750 [ 11.581958][ T254] ? __might_fault+0x97/0x140 [ 11.581963][ T254] ____sys_sendmsg+0x415/0x880 [ 11.581966][ T254] ? copy_msghdr_from_user+0x279/0x420 [ 11.581968][ T254] ? get_timestamp.constprop.0+0x390/0x390 [ 11.581969][ T254] ? move_addr_to_kernel+0x40/0x40 [ 11.581977][ T254] ___sys_sendmsg+0x14e/0x1d0 [ 11.581979][ T254] ? copy_msghdr_from_user+0x420/0x420 [ 11.581995][ T254] __sys_sendmsg+0x12c/0x1d0 [ 11.581998][ T254] ? __sys_sendmsg_sock+0x20/0x20 [ 11.582004][ T254] ? rcu_is_watching+0x16/0xd0 [ 11.582008][ T254] do_syscall_64+0xff/0x530 [ 11.582010][ T254] ? exc_page_fault+0xee/0x100 [ 11.582013][ T254] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 11.582015][ T254] RIP: 0033:0x7f238406454e [ 11.582019][ 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.582020][ T254] RSP: 002b:00007ffe55b21120 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 11.582023][ T254] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f238406454e [ 11.582025][ T254] RDX: 0000000000000000 RSI: 00007ffe55b211d0 RDI: 0000000000000005 [ 11.582025][ T254] RBP: 00007ffe55b21130 R08: 0000000000000000 R09: 0000000000000000 [ 11.582026][ T254] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a910cb4 [ 11.582027][ T254] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffe55b21890 [ 11.582034][ T254] [ 11.630411][ T254] br1: port 1(veth1) entered disabled state [ 11.630983][ T254] veth1: entered allmulticast mode [ 11.633862][ T254] veth1: entered promiscuous mode [ 11.644040][ T254] ip (254) used greatest stack depth: 23336 bytes left [ 11.664332][ T38] br1: port 1(veth1) entered blocking state [ 11.664871][ T38] br1: port 1(veth1) entered forwarding state [ 11.722035][ T256] br1: port 2(veth2) entered blocking state [ 11.722540][ T256] br1: port 2(veth2) entered disabled state [ 11.723618][ T256] veth2: entered allmulticast mode [ 11.726180][ T256] veth2: entered promiscuous mode [ 11.757226][ T38] br1: port 2(veth2) entered blocking state [ 11.757909][ T38] br1: port 2(veth2) entered forwarding state [ 11.874491][ T260] 8021q: 802.1Q VLAN Support v1.8 [ 12.170469][ T272] GACT probability NOT on [ 18.004974][ T316] Mirror/redirect action on [ 18.004994][ T316] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 18.004997][ T316] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 316, name: modprobe [ 18.004998][ T316] preempt_count: 1, expected: 0 [ 18.004999][ T316] RCU nest depth: 0, expected: 0 [ 18.005001][ T316] locks held by modprobe/316: 4, last CPU#2: [ 18.005003][ T316] #0: ffffffffb5969cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 18.005016][ T316] #1: ffffffffb5969d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 18.005020][ T316] #2: ffffffffb5889660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 18.005024][ T316] #3: ffffffffb5889560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 18.005029][ T316] irq event stamp: 25660 [ 18.005029][ T316] hardirqs last enabled at (25659): [] __down_trylock_console_sem+0x86/0xa0 [ 18.005031][ T316] hardirqs last disabled at (25660): [] console_emit_next_record+0x3d4/0x4c0 [ 18.005033][ T316] softirqs last enabled at (24240): [] handle_softirqs+0x67c/0x900 [ 18.005036][ T316] softirqs last disabled at (24233): [] __irq_exit_rcu+0x145/0x1c0 [ 18.005039][ T316] Preemption disabled at: [ 18.005039][ T316] [] vprintk_emit+0x31b/0x3e0 [ 18.005045][ T316] CPU: 2 UID: 0 PID: 316 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 18.005049][ T316] Tainted: [W]=WARN [ 18.005050][ T316] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 18.005051][ T316] Call Trace: [ 18.005053][ T316] [ 18.005054][ T316] dump_stack_lvl+0x6f/0xa0 [ 18.005061][ T316] ? vprintk_emit+0x31b/0x3e0 [ 18.005063][ T316] __might_resched.cold+0x1fe/0x2c1 [ 18.005067][ T316] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 18.005072][ T316] ? __kmalloc_noprof+0xdb/0x760 [ 18.005077][ T316] __kmalloc_noprof+0x443/0x760 [ 18.005079][ T316] ? alloc_buf.isra.0+0x4b/0x260 [ 18.005085][ T316] ? do_raw_spin_unlock+0x59/0x250 [ 18.005087][ T316] alloc_buf.isra.0+0x4b/0x260 [ 18.005090][ T316] put_chars+0x1e1/0x2f0 [ 18.005093][ T316] ? __send_to_port+0x420/0x420 [ 18.005094][ T316] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 18.005099][ T316] ? rcu_read_lock_any_held+0x3c/0x90 [ 18.005102][ T316] ? validate_chain+0x38b/0xc20 [ 18.005106][ T316] hvc_console_print+0x292/0x780 [ 18.005109][ T316] ? __lock_acquire+0x518/0xc20 [ 18.005114][ T316] ? hvc_write+0x3a0/0x3a0 [ 18.005116][ T316] ? rcu_is_watching+0x16/0xd0 [ 18.005121][ T316] console_emit_next_record+0x22f/0x4c0 [ 18.005125][ T316] ? devkmsg_read+0x4b0/0x4b0 [ 18.005126][ T316] ? console_flush_one_record+0x106/0x710 [ 18.005129][ T316] ? rcu_is_watching+0x16/0xd0 [ 18.005132][ T316] ? lock_acquire+0x13c/0x160 [ 18.005135][ T316] console_flush_one_record+0x46f/0x710 [ 18.005139][ T316] ? console_emit_next_record+0x4c0/0x4c0 [ 18.005141][ T316] ? __lock_acquire+0x518/0xc20 [ 18.005146][ T316] console_unlock+0xee/0x1f0 [ 18.005149][ T316] ? console_flush_one_record+0x710/0x710 [ 18.005150][ T316] ? rcu_is_watching+0x16/0xd0 [ 18.005153][ T316] ? lock_acquire+0x60/0x160 [ 18.005156][ T316] ? __down_trylock_console_sem+0x5e/0xa0 [ 18.005158][ T316] ? vprintk_emit+0x320/0x3e0 [ 18.005161][ T316] vprintk_emit+0x37c/0x3e0 [ 18.005165][ T316] ? wake_up_klogd_work_func+0x90/0x90 [ 18.005168][ T316] ? netdev_run_todo+0x2a8/0xfd0 [ 18.005172][ T316] ? mirred_device_event+0x3d/0x1d0 [act_mirred] [ 18.005175][ T316] ? rcu_is_watching+0x16/0xd0 [ 18.005179][ T316] ? llc_exit+0x3e0/0x3e0 [llc] [ 18.005181][ T316] _printk+0xc7/0x100 [ 18.005185][ T316] ? snapshot_read.cold+0x21/0x21 [ 18.005187][ T316] ? __lock_release.isra.0+0x69/0x1a0 [ 18.005190][ T316] ? llc_exit+0x3e0/0x3e0 [llc] [ 18.005192][ T316] ? __up_write+0x23d/0x5f0 [ 18.005194][ T316] ? __up_write+0x2e5/0x5f0 [ 18.005197][ T316] ? __up_write+0x2c7/0x5f0 [ 18.005199][ T316] mirred_init_module+0x28/0x1000 [act_mirred] [ 18.005202][ T316] do_one_initcall+0x124/0x4f0 [ 18.005205][ T316] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 18.005210][ T316] ? do_init_module+0xae/0x8a0 [ 18.005212][ T316] ? kasan_unpoison+0x44/0x70 [ 18.005217][ T316] do_init_module+0x2ad/0x8a0 [ 18.005221][ T316] ? lockdep_hardirqs_on+0x91/0x130 [ 18.005225][ T316] ? free_module+0x470/0x470 [ 18.005228][ T316] ? kfree+0x123/0x590 [ 18.005232][ T316] load_module+0x1424/0x1bb0 [ 18.005239][ T316] ? layout_and_allocate.isra.0+0x680/0x680 [ 18.005243][ T316] ? __ia32_sys_fsconfig+0x180/0x180 [ 18.005246][ T316] ? ovl_real_file+0x172/0x1e0 [ 18.005249][ T316] ? find_held_lock+0x2b/0x80 [ 18.005251][ T316] ? backing_file_read_iter+0x541/0x790 [ 18.005254][ T316] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 18.005258][ T316] init_module_from_file+0x11f/0x1a0 [ 18.005261][ T316] ? __do_sys_init_module+0x220/0x220 [ 18.005269][ T316] ? __rwlock_init+0x150/0x150 [ 18.005272][ T316] ? do_raw_spin_unlock+0x59/0x250 [ 18.005275][ T316] idempotent_init_module+0x37b/0x7c0 [ 18.005279][ T316] ? init_module_from_file+0x1a0/0x1a0 [ 18.005285][ T316] ? rcu_is_watching+0x16/0xd0 [ 18.005287][ T316] ? cap_capable+0x1d7/0x3d0 [ 18.005293][ T316] __x64_sys_finit_module+0xdf/0x190 [ 18.005295][ T316] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 18.005299][ T316] do_syscall_64+0xff/0x530 [ 18.005300][ T316] ? exc_page_fault+0xee/0x100 [ 18.005303][ T316] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 18.005305][ T316] RIP: 0033:0x7fd71231b37d [ 18.005308][ 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 [ 18.005310][ T316] RSP: 002b:00007ffe7896d9c8 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 18.005313][ T316] RAX: ffffffffffffffda RBX: 000055f3e503c810 RCX: 00007fd71231b37d [ 18.005314][ T316] RDX: 0000000000000000 RSI: 000055f3bbfd2ffd RDI: 0000000000000000 [ 18.005315][ T316] RBP: 00007ffe7896da60 R08: 0000000000000000 R09: 0000000000000000 [ 18.005316][ T316] R10: 0000000000000000 R11: 0000000000000246 R12: 000055f3bbfd2ffd [ 18.005317][ T316] R13: 000055f3e503c960 R14: 0000000000040000 R15: 0000000000000000 [ 18.005323][ T316] [ 27.003690][ C0] [ 27.003711][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 27.003714][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 27.003716][ C0] preempt_count: 104, expected: 0 [ 27.003718][ C0] RCU nest depth: 0, expected: 0 [ 27.003719][ C0] INFO: lockdep is turned off. [ 27.003720][ C0] irq event stamp: 642554 [ 27.003721][ C0] hardirqs last enabled at (642554): [] _raw_spin_unlock_irq+0x28/0x50 [ 27.003732][ C0] hardirqs last disabled at (642553): [] _raw_spin_lock_irq+0x4a/0x50 [ 27.003735][ C0] softirqs last enabled at (642508): [] handle_softirqs+0x67c/0x900 [ 27.003740][ C0] softirqs last disabled at (642551): [] __irq_exit_rcu+0x145/0x1c0 [ 27.003742][ C0] Preemption disabled at: [ 27.003743][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 27.003751][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.003756][ C0] Tainted: [W]=WARN [ 27.003757][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.003760][ C0] Call Trace: [ 27.003763][ C0] [ 27.003765][ C0] dump_stack_lvl+0x6f/0xa0 [ 27.003772][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 27.003774][ C0] __might_resched.cold+0x1fe/0x2c1 [ 27.003779][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 27.003783][ C0] ? __asan_memcpy+0x3c/0x60 [ 27.003787][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.003792][ C0] __kmalloc_noprof+0x443/0x760 [ 27.003796][ C0] ? __rwlock_init+0x150/0x150 [ 27.003800][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 27.003805][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 27.003807][ C0] alloc_buf.isra.0+0x4b/0x260 [ 27.003811][ C0] put_chars+0x1e1/0x2f0 [ 27.003814][ C0] ? __send_to_port+0x420/0x420 [ 27.003816][ C0] ? console_prepend_replay+0x20/0x20 [ 27.003821][ C0] hvc_console_print+0x292/0x780 [ 27.003826][ C0] ? hvc_write+0x3a0/0x3a0 [ 27.003828][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.003831][ C0] ? lock_acquire+0x13c/0x160 [ 27.003834][ C0] console_emit_next_record+0x22f/0x4c0 [ 27.003838][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 27.003841][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.003843][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.003846][ C0] ? lock_acquire+0x13c/0x160 [ 27.003849][ C0] ? console_flush_one_record+0x111/0x710 [ 27.003852][ C0] console_flush_one_record+0x46f/0x710 [ 27.003855][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 27.003859][ C0] console_unlock+0xee/0x1f0 [ 27.003862][ C0] ? lock_acquire+0x13c/0x160 [ 27.003864][ C0] ? console_flush_one_record+0x710/0x710 [ 27.003867][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.003869][ C0] ? lock_release+0x184/0x1f0 [ 27.003872][ C0] ? lock_acquire+0x60/0x160 [ 27.003875][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 27.003877][ C0] ? vprintk_emit+0x320/0x3e0 [ 27.003881][ C0] vprintk_emit+0x37c/0x3e0 [ 27.003884][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 27.003887][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.003889][ C0] ? lock_release+0x184/0x1f0 [ 27.003892][ C0] ? x86_64_start_kernel+0xe0/0x130 [ 27.003897][ C0] _printk+0xc7/0x100 [ 27.003901][ C0] ? snapshot_read.cold+0x21/0x21 [ 27.003904][ C0] ? arch_stack_walk+0xd7/0x130 [ 27.003909][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 27.003913][ C0] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 27.003915][ C0] print_irq_inversion_bug.part.0+0x32/0xc0 [ 27.003919][ C0] mark_lock_irq+0x989/0x9c0 [ 27.003924][ C0] mark_lock+0x1d7/0xa00 [ 27.003927][ C0] mark_usage+0x42/0x170 [ 27.003929][ C0] __lock_acquire+0x388/0xc20 [ 27.003933][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.003936][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.003954][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.003957][ C0] ? lock_acquire+0x13c/0x160 [ 27.003960][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.003971][ C0] _raw_spin_lock+0x33/0x40 [ 27.003974][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.003985][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.003997][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.004008][ C0] call_timer_fn+0x160/0x4d0 [ 27.004012][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 27.004015][ C0] ? debug_object_active_state+0x430/0x430 [ 27.004019][ C0] ? find_held_lock+0x2b/0x80 [ 27.004022][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.004025][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.004028][ C0] __run_timers+0x68f/0xaa0 [ 27.004031][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.004043][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.004046][ C0] ? __lock_acquire+0x518/0xc20 [ 27.004050][ C0] ? __rwlock_init+0x150/0x150 [ 27.004054][ C0] run_timer_softirq+0xf0/0x160 [ 27.004057][ C0] ? __run_timers+0xaa0/0xaa0 [ 27.004060][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.004063][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.004066][ C0] handle_softirqs+0x1d3/0x900 [ 27.004069][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.004072][ C0] ? _local_bh_enable+0xc0/0xc0 [ 27.004075][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.004077][ C0] irq_exit_rcu+0xe/0x30 [ 27.004080][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.004084][ C0] [ 27.004085][ C0] [ 27.004086][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 27.004088][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.004092][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.004096][ C0] 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.004098][ C0] RSP: 0018:ffffffffb5607cf8 EFLAGS: 00000296 [ 27.004103][ C0] RAX: 000000000009cdf5 RBX: ffffffffb561c600 RCX: ffffffffb26f0307 [ 27.004105][ C0] RDX: ffffffffb561c600 RSI: ffffffffb5438b01 RDI: ffffffffb4e8d8e0 [ 27.004106][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.004108][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff6ac0fa2 [ 27.004109][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 27.004112][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.004116][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 27.004118][ C0] default_idle+0x9/0x10 [ 27.004121][ C0] default_idle_call+0x6e/0xb0 [ 27.004123][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.004126][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 27.004129][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 27.004132][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.004134][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.004137][ C0] do_idle+0xd8/0x190 [ 27.004139][ C0] cpu_startup_entry+0x53/0x70 [ 27.004142][ C0] rest_init+0x279/0x280 [ 27.004144][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 27.004149][ C0] ? rest_init+0x280/0x280 [ 27.004152][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 27.004156][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 27.004160][ C0] ? acpi_enable+0x1e4/0x330 [ 27.004164][ C0] start_kernel+0x3af/0x3b0 [ 27.004167][ C0] x86_64_start_reservations+0x24/0x30 [ 27.004170][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.004173][ C0] common_startup_64+0x13e/0x148 [ 27.004179][ C0] [ 27.036478][ C0] ======================================================== [ 27.036888][ C0] WARNING: possible irq lock inversion dependency detected [ 27.037301][ C0] 7.2.0-virtme #1 Tainted: G W [ 27.037571][ C0] -------------------------------------------------------- [ 27.037887][ C0] swapper/0/0 just changed the state of lock: [ 27.038239][ C0] ff11000015ba2e58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.038763][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 27.039216][ C0] (fs_reclaim){+.+.}-{0:0} [ 27.039220][ C0] [ 27.039220][ C0] [ 27.039220][ C0] and interrupts could create inverse lock ordering between them. [ 27.039220][ C0] [ 27.040139][ C0] [ 27.040139][ C0] other info that might help us debug this: [ 27.040545][ C0] Chain exists of: [ 27.040545][ C0] &br->lock --> console_owner --> fs_reclaim [ 27.040545][ C0] [ 27.041123][ C0] Possible interrupt unsafe locking scenario: [ 27.041123][ C0] [ 27.041539][ C0] CPU0 CPU1 [ 27.041839][ C0] ---- ---- [ 27.042048][ C0] lock(fs_reclaim); [ 27.042213][ C0] local_irq_disable(); [ 27.042580][ C0] lock(&br->lock); [ 27.042934][ C0] lock(console_owner); [ 27.043200][ C0] [ 27.043455][ C0] lock(&br->lock); [ 27.043600][ C0] [ 27.043600][ C0] *** DEADLOCK *** [ 27.043600][ C0] [ 27.043954][ C0] locks held by swapper/0/0: 1, last CPU#0: [ 27.044219][ C0] #0: ffa0000000007c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 27.044746][ C0] [ 27.044746][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 27.045200][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 27.045515][ C0] HARDIRQ-ON-W at: [ 27.045683][ C0] __lock_acquire+0x388/0xc20 [ 27.046053][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.046321][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.046681][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.047095][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.047508][ C0] workqueue_init+0x2a/0x680 [ 27.047784][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.048195][ C0] kernel_init+0x21/0x150 [ 27.048560][ C0] ret_from_fork+0x474/0x6b0 [ 27.048828][ C0] ret_from_fork_asm+0x11/0x20 [ 27.049185][ C0] SOFTIRQ-ON-W at: [ 27.049345][ C0] __lock_acquire+0x388/0xc20 [ 27.049709][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.049978][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.050337][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.050745][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.051155][ C0] workqueue_init+0x2a/0x680 [ 27.051425][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.051839][ C0] kernel_init+0x21/0x150 [ 27.052194][ C0] ret_from_fork+0x474/0x6b0 [ 27.052467][ C0] ret_from_fork_asm+0x11/0x20 [ 27.052738][ C0] INITIAL USE at: [ 27.052899][ C0] __lock_acquire+0x388/0xc20 [ 27.053256][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.053524][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.053876][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 27.054286][ C0] kthread_create_worker_on_node+0xea/0x210 [ 27.054613][ C0] workqueue_init+0x2a/0x680 [ 27.054872][ C0] kernel_init_freeable+0x2fe/0x630 [ 27.055220][ C0] kernel_init+0x21/0x150 [ 27.055492][ C0] ret_from_fork+0x474/0x6b0 [ 27.055845][ C0] ret_from_fork_asm+0x11/0x20 [ 27.056202][ C0] } [ 27.056312][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 27.056722][ C0] ... acquired at: [ 27.056880][ C0] __lock_acquire+0x518/0xc20 [ 27.057182][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.057393][ C0] fs_reclaim_acquire+0xd5/0x120 [ 27.057611][ C0] __kmalloc_noprof+0xd3/0x760 [ 27.057822][ C0] alloc_buf.isra.0+0x4b/0x260 [ 27.058122][ C0] put_chars+0x1e1/0x2f0 [ 27.058339][ C0] hvc_console_print+0x292/0x780 [ 27.058649][ C0] console_emit_next_record+0x22f/0x4c0 [ 27.058862][ C0] console_flush_one_record+0x46f/0x710 [ 27.059161][ C0] console_unlock+0xee/0x1f0 [ 27.059377][ C0] vprintk_emit+0x37c/0x3e0 [ 27.059684][ C0] dev_vprintk_emit+0x27f/0x2c0 [ 27.059899][ C0] dev_printk_emit+0xb9/0xee [ 27.060185][ C0] _dev_info+0xe2/0x116 [ 27.060330][ C0] __devm_rtc_register_device.cold+0xc3/0x3a8 [ 27.060692][ C0] cmos_do_probe+0x73b/0x98a [ 27.060905][ C0] platform_probe+0xfe/0x1f0 [ 27.061215][ C0] call_driver_probe+0x61/0x1c0 [ 27.061429][ C0] really_probe+0x199/0x760 [ 27.061653][ C0] __driver_probe_device+0x24f/0x440 [ 27.061865][ C0] driver_probe_device+0x4a/0xf0 [ 27.062168][ C0] __driver_attach+0x1b8/0x540 [ 27.062382][ C0] bus_for_each_dev+0x130/0x1e0 [ 27.062689][ C0] bus_add_driver+0x2c8/0x530 [ 27.062898][ C0] driver_register+0x1a3/0x390 [ 27.063201][ C0] __platform_driver_probe+0x13f/0x270 [ 27.063413][ C0] cmos_init+0x31/0x40 [ 27.063584][ C0] do_one_initcall+0x124/0x4f0 [ 27.063794][ C0] kernel_init_freeable+0x596/0x630 [ 27.064003][ C0] kernel_init+0x21/0x150 [ 27.064305][ C0] ret_from_fork+0x474/0x6b0 [ 27.064521][ C0] ret_from_fork_asm+0x11/0x20 [ 27.064824][ C0] [ 27.064935][ C0] -> (console_owner){....}-{0:0} { [ 27.065248][ C0] INITIAL USE at: [ 27.065409][ C0] } [ 27.065524][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 27.065848][ C0] ... acquired at: [ 27.066005][ C0] __lock_acquire+0x518/0xc20 [ 27.066312][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.066527][ C0] console_lock_spinning_enable+0x5c/0x60 [ 27.066878][ C0] console_emit_next_record+0x1d1/0x4c0 [ 27.067182][ C0] console_flush_one_record+0x46f/0x710 [ 27.067391][ C0] console_unlock+0xee/0x1f0 [ 27.067611][ C0] vprintk_emit+0x37c/0x3e0 [ 27.067821][ C0] _printk+0xc7/0x100 [ 27.067981][ C0] br_set_state+0x22f/0x430 [bridge] [ 27.068295][ C0] br_init_port+0xc4/0x200 [bridge] [ 27.068526][ C0] br_stp_enable_port+0x12/0x50 [bridge] [ 27.068889][ C0] br_port_carrier_check+0x220/0x430 [bridge] [ 27.069251][ C0] br_device_event+0x52d/0x8f0 [bridge] [ 27.069477][ C0] notifier_call_chain+0xae/0x300 [ 27.069690][ C0] netif_state_change+0x139/0x340 [ 27.069900][ C0] __linkwatch_run_queue+0x34c/0x750 [ 27.070197][ C0] linkwatch_event+0x7f/0xb0 [ 27.070414][ C0] process_one_work+0xe3e/0x1560 [ 27.070720][ C0] worker_thread+0x4f1/0xd60 [ 27.070932][ C0] kthread+0x367/0x460 [ 27.071181][ C0] ret_from_fork+0x474/0x6b0 [ 27.071393][ C0] ret_from_fork_asm+0x11/0x20 [ 27.071612][ C0] [ 27.071719][ C0] -> (&br->lock){+.-.}-{3:3} { [ 27.071931][ C0] HARDIRQ-ON-W at: [ 27.072183][ C0] __lock_acquire+0x388/0xc20 [ 27.072458][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.072817][ C0] _raw_spin_lock_bh+0x38/0x50 [ 27.073173][ C0] recalculate_group_addr+0x51/0x120 [bridge] [ 27.073518][ C0] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 27.073846][ C0] br_changelink+0x575/0x16e0 [bridge] [ 27.074259][ C0] br_dev_newlink+0xeb/0x160 [bridge] [ 27.074574][ C0] rtnl_newlink_create+0x2d0/0x750 [ 27.074965][ C0] __rtnl_newlink+0x22b/0xa50 [ 27.075366][ C0] rtnl_newlink+0x9f1/0x11c0 [ 27.075672][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.076071][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 27.076373][ C0] netlink_unicast+0x486/0x750 [ 27.076786][ C0] netlink_sendmsg+0x735/0xc60 [ 27.077168][ C0] ____sys_sendmsg+0x415/0x880 [ 27.077484][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 27.077792][ C0] __sys_sendmsg+0x12c/0x1d0 [ 27.078196][ C0] do_syscall_64+0xff/0x530 [ 27.078502][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.078957][ C0] IN-SOFTIRQ-W at: [ 27.079144][ C0] __lock_acquire+0x388/0xc20 [ 27.079537][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.079944][ C0] _raw_spin_lock+0x33/0x40 [ 27.080253][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.080724][ C0] call_timer_fn+0x160/0x4d0 [ 27.081128][ C0] __run_timers+0x68f/0xaa0 [ 27.081430][ C0] run_timer_softirq+0xf0/0x160 [ 27.081750][ C0] handle_softirqs+0x1d3/0x900 [ 27.082050][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.082472][ C0] irq_exit_rcu+0xe/0x30 [ 27.082870][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.083244][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.083712][ C0] pv_native_safe_halt+0xf/0x10 [ 27.084111][ C0] default_idle+0x9/0x10 [ 27.084415][ C0] default_idle_call+0x6e/0xb0 [ 27.084829][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.085293][ C0] do_idle+0xd8/0x190 [ 27.085543][ C0] cpu_startup_entry+0x53/0x70 [ 27.085849][ C0] rest_init+0x279/0x280 [ 27.086246][ C0] start_kernel+0x3af/0x3b0 [ 27.086550][ C0] x86_64_start_reservations+0x24/0x30 [ 27.086960][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.087267][ C0] common_startup_64+0x13e/0x148 [ 27.087676][ C0] INITIAL USE at: [ 27.087855][ C0] __lock_acquire+0x388/0xc20 [ 27.088261][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.088662][ C0] _raw_spin_lock_bh+0x38/0x50 [ 27.088977][ C0] recalculate_group_addr+0x51/0x120 [bridge] [ 27.089460][ C0] br_vlan_filter_toggle+0x85/0x110 [bridge] [ 27.089931][ C0] br_changelink+0x575/0x16e0 [bridge] [ 27.090252][ C0] br_dev_newlink+0xeb/0x160 [bridge] [ 27.090660][ C0] rtnl_newlink_create+0x2d0/0x750 [ 27.091053][ C0] __rtnl_newlink+0x22b/0xa50 [ 27.091372][ C0] rtnl_newlink+0x9f1/0x11c0 [ 27.091777][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 27.092179][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 27.092495][ C0] netlink_unicast+0x486/0x750 [ 27.092909][ C0] netlink_sendmsg+0x735/0xc60 [ 27.093213][ C0] ____sys_sendmsg+0x415/0x880 [ 27.093626][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 27.094025][ C0] __sys_sendmsg+0x12c/0x1d0 [ 27.094340][ C0] do_syscall_64+0xff/0x530 [ 27.094733][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 27.095195][ C0] } [ 27.095322][ C0] ... key at: [] __key.7+0x0/0x40 [bridge] [ 27.095701][ C0] ... acquired at: [ 27.095877][ C0] mark_lock+0x1d7/0xa00 [ 27.096213][ C0] mark_usage+0x42/0x170 [ 27.096464][ C0] __lock_acquire+0x388/0xc20 [ 27.096800][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.097046][ C0] _raw_spin_lock+0x33/0x40 [ 27.097384][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.097714][ C0] call_timer_fn+0x160/0x4d0 [ 27.098046][ C0] __run_timers+0x68f/0xaa0 [ 27.098303][ C0] run_timer_softirq+0xf0/0x160 [ 27.098651][ C0] handle_softirqs+0x1d3/0x900 [ 27.098901][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.099237][ C0] irq_exit_rcu+0xe/0x30 [ 27.099490][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.099880][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.100191][ C0] pv_native_safe_halt+0xf/0x10 [ 27.100529][ C0] default_idle+0x9/0x10 [ 27.100775][ C0] default_idle_call+0x6e/0xb0 [ 27.101123][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.101511][ C0] do_idle+0xd8/0x190 [ 27.101700][ C0] cpu_startup_entry+0x53/0x70 [ 27.101947][ C0] rest_init+0x279/0x280 [ 27.102284][ C0] start_kernel+0x3af/0x3b0 [ 27.102527][ C0] x86_64_start_reservations+0x24/0x30 [ 27.102861][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.103104][ C0] common_startup_64+0x13e/0x148 [ 27.103442][ C0] [ 27.103573][ C0] [ 27.103573][ C0] stack backtrace: [ 27.103965][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 27.103969][ C0] Tainted: [W]=WARN [ 27.103971][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 27.103972][ C0] Call Trace: [ 27.103974][ C0] [ 27.103976][ C0] dump_stack_lvl+0x6f/0xa0 [ 27.103981][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 27.103986][ C0] mark_lock_irq+0x989/0x9c0 [ 27.103990][ C0] mark_lock+0x1d7/0xa00 [ 27.103993][ C0] mark_usage+0x42/0x170 [ 27.103996][ C0] __lock_acquire+0x388/0xc20 [ 27.103999][ C0] lock_acquire.part.0+0xd4/0x280 [ 27.104002][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.104017][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.104022][ C0] ? lock_acquire+0x13c/0x160 [ 27.104024][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.104036][ C0] _raw_spin_lock+0x33/0x40 [ 27.104039][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.104050][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 27.104061][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.104072][ C0] call_timer_fn+0x160/0x4d0 [ 27.104075][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 27.104078][ C0] ? debug_object_active_state+0x430/0x430 [ 27.104083][ C0] ? find_held_lock+0x2b/0x80 [ 27.104086][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.104089][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.104093][ C0] __run_timers+0x68f/0xaa0 [ 27.104095][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 27.104106][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 27.104109][ C0] ? __lock_acquire+0x518/0xc20 [ 27.104113][ C0] ? __rwlock_init+0x150/0x150 [ 27.104117][ C0] run_timer_softirq+0xf0/0x160 [ 27.104120][ C0] ? __run_timers+0xaa0/0xaa0 [ 27.104122][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.104126][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.104128][ C0] handle_softirqs+0x1d3/0x900 [ 27.104131][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 27.104134][ C0] ? _local_bh_enable+0xc0/0xc0 [ 27.104137][ C0] __irq_exit_rcu+0x145/0x1c0 [ 27.104139][ C0] irq_exit_rcu+0xe/0x30 [ 27.104141][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 27.104144][ C0] [ 27.104145][ C0] [ 27.104146][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 27.104149][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 27.104152][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 27.104155][ C0] 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.104157][ C0] RSP: 0018:ffffffffb5607cf8 EFLAGS: 00000296 [ 27.104160][ C0] RAX: 000000000009cdf5 RBX: ffffffffb561c600 RCX: ffffffffb26f0307 [ 27.104162][ C0] RDX: ffffffffb561c600 RSI: ffffffffb5438b01 RDI: ffffffffb4e8d8e0 [ 27.104164][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 27.104165][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff6ac0fa2 [ 27.104166][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 27.104169][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.104173][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 27.104175][ C0] default_idle+0x9/0x10 [ 27.104177][ C0] default_idle_call+0x6e/0xb0 [ 27.104180][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 27.104182][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 27.104184][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 27.104188][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 27.104189][ C0] ? rcu_is_watching+0x16/0xd0 [ 27.104193][ C0] do_idle+0xd8/0x190 [ 27.104195][ C0] cpu_startup_entry+0x53/0x70 [ 27.104197][ C0] rest_init+0x279/0x280 [ 27.104200][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 27.104204][ C0] ? rest_init+0x280/0x280 [ 27.104207][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 27.104212][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 27.104216][ C0] ? acpi_enable+0x1e4/0x330 [ 27.104219][ C0] start_kernel+0x3af/0x3b0 [ 27.104222][ C0] x86_64_start_reservations+0x24/0x30 [ 27.104225][ C0] x86_64_start_kernel+0x12b/0x130 [ 27.104228][ C0] common_startup_64+0x13e/0x148 [ 27.104234][ C0] [ 45.239664][ T506] br1: port 1(veth1) entered disabled state [ 45.239681][ T506] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 45.239684][ T506] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 506, name: ip [ 45.239686][ T506] preempt_count: 202, expected: 0 [ 45.239686][ T506] RCU nest depth: 0, expected: 0 [ 45.239687][ T506] INFO: lockdep is turned off. [ 45.239688][ T506] irq event stamp: 0 [ 45.239689][ T506] hardirqs last enabled at (0): [<0000000000000000>] 0x0 [ 45.239694][ T506] hardirqs last disabled at (0): [] copy_process+0x1ccd/0x6240 [ 45.239700][ T506] softirqs last enabled at (0): [] copy_process+0x1d21/0x6240 [ 45.239702][ T506] softirqs last disabled at (0): [<0000000000000000>] 0x0 [ 45.239704][ T506] Preemption disabled at: [ 45.239704][ T506] [<0000000000000000>] 0x0 [ 45.239709][ T506] CPU: 0 UID: 0 PID: 506 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 45.239713][ T506] Tainted: [W]=WARN [ 45.239714][ T506] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 45.239716][ T506] Call Trace: [ 45.239717][ T506] [ 45.239718][ T506] dump_stack_lvl+0x6f/0xa0 [ 45.239724][ T506] __might_resched.cold+0x1fe/0x2c1 [ 45.239728][ T506] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 45.239731][ T506] ? rcu_is_watching+0x16/0xd0 [ 45.239735][ T506] __kmalloc_noprof+0x443/0x760 [ 45.239739][ T506] ? __rwlock_init+0x150/0x150 [ 45.239741][ T506] ? alloc_buf.isra.0+0x4b/0x260 [ 45.239745][ T506] ? do_raw_spin_unlock+0x59/0x250 [ 45.239747][ T506] alloc_buf.isra.0+0x4b/0x260 [ 45.239749][ T506] put_chars+0x1e1/0x2f0 [ 45.239751][ T506] ? __send_to_port+0x420/0x420 [ 45.239753][ T506] ? printk_get_next_message+0x240/0x650 [ 45.239756][ T506] hvc_console_print+0x292/0x780 [ 45.239759][ T506] ? hvc_write+0x3a0/0x3a0 [ 45.239761][ T506] ? __rwlock_init+0x150/0x150 [ 45.239763][ T506] ? rcu_is_watching+0x16/0xd0 [ 45.239765][ T506] ? lock_acquire+0x13c/0x160 [ 45.239767][ T506] console_emit_next_record+0x22f/0x4c0 [ 45.239769][ T506] ? devkmsg_read+0x4b0/0x4b0 [ 45.239771][ T506] ? rcu_is_watching+0x16/0xd0 [ 45.239773][ T506] ? rcu_is_watching+0x16/0xd0 [ 45.239774][ T506] ? rcu_is_watching+0x16/0xd0 [ 45.239776][ T506] ? lock_acquire+0x13c/0x160 [ 45.239778][ T506] ? console_flush_one_record+0x111/0x710 [ 45.239779][ T506] console_flush_one_record+0x46f/0x710 [ 45.239782][ T506] ? console_emit_next_record+0x4c0/0x4c0 [ 45.239784][ T506] console_unlock+0xee/0x1f0 [ 45.239786][ T506] ? lock_acquire+0x13c/0x160 [ 45.239787][ T506] ? console_flush_one_record+0x710/0x710 [ 45.239789][ T506] ? rcu_is_watching+0x16/0xd0 [ 45.239790][ T506] ? lock_release+0x184/0x1f0 [ 45.239791][ T506] ? lock_acquire+0x60/0x160 [ 45.239793][ T506] ? __rcu_read_lock+0x30/0x70 [ 45.239795][ T506] ? __down_trylock_console_sem+0x5e/0xa0 [ 45.239797][ T506] ? vprintk_emit+0x320/0x3e0 [ 45.239799][ T506] vprintk_emit+0x37c/0x3e0 [ 45.239801][ T506] ? wake_up_klogd_work_func+0x90/0x90 [ 45.239803][ T506] ? rcu_is_watching+0x16/0xd0 [ 45.239804][ T506] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 45.239807][ T506] ? trace_hardirqs_on+0x36/0x40 [ 45.239809][ T506] _printk+0xc7/0x100 [ 45.239812][ T506] ? snapshot_read.cold+0x21/0x21 [ 45.239814][ T506] ? ipv6_mc_down+0x17e/0x2d0 [ 45.239818][ T506] br_set_state+0x22f/0x430 [bridge] [ 45.239831][ T506] br_stp_disable_port+0x11f/0x390 [bridge] [ 45.239839][ T506] br_device_event+0x7b5/0x8f0 [bridge] [ 45.239847][ T506] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 45.239851][ T506] ? _sub_I_65535_1+0x4d0/0x4d0 [stp] [ 45.239853][ T506] ? addrconf_notify+0x2c8/0xf30 [ 45.239856][ T506] notifier_call_chain+0xae/0x300 [ 45.239859][ T506] __dev_notify_flags+0x23c/0x3c0 [ 45.239863][ T506] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 45.239864][ T506] ? netif_change_name+0x8c0/0x8c0 [ 45.239866][ T506] ? netif_set_allmulti+0x250/0x250 [ 45.239868][ T506] ? __kernel_text_address+0x12/0x30 [ 45.239870][ T506] ? write_profile+0xf0/0xf0 [ 45.239872][ T506] ? unwind_get_return_address+0x67/0xd0 [ 45.239875][ T506] netif_change_flags+0xfe/0x190 [ 45.239877][ T506] do_setlink.isra.0+0x1cc3/0x2750 [ 45.239880][ T506] ? stack_trace_save+0x93/0xc0 [ 45.239881][ T506] ? rtnl_link_get_size+0x350/0x350 [ 45.239882][ T506] ? rcu_is_watching+0x16/0xd0 [ 45.239884][ T506] ? stack_depot_save_flags+0x1bc/0x790 [ 45.239888][ T506] ? kasan_save_stack+0x3d/0x50 [ 45.239891][ T506] ? kasan_save_stack+0x2f/0x50 [ 45.239892][ T506] ? kasan_save_track+0x14/0x30 [ 45.239893][ T506] ? __kasan_kmalloc+0x7b/0x90 [ 45.239895][ T506] ? __kmalloc_cache_noprof+0x29a/0x620 [ 45.239896][ T506] ? rtnl_newlink+0x124/0x11c0 [ 45.239897][ T506] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 45.239899][ T506] ? netlink_rcv_skb+0x14e/0x3a0 [ 45.239901][ T506] ? netlink_unicast+0x486/0x750 [ 45.239902][ T506] ? netlink_sendmsg+0x735/0xc60 [ 45.239904][ T506] ? ____sys_sendmsg+0x415/0x880 [ 45.239906][ T506] ? ___sys_sendmsg+0x14e/0x1d0 [ 45.239907][ T506] ? __sys_sendmsg+0x12c/0x1d0 [ 45.239909][ T506] ? do_syscall_64+0xff/0x530 [ 45.239911][ T506] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 45.239913][ T506] ? rcu_is_watching+0x16/0xd0 [ 45.239915][ T506] ? lock_acquire+0x13c/0x160 [ 45.239916][ T506] ? rcu_is_watching+0x16/0xd0 [ 45.239918][ T506] ? rcu_is_watching+0x16/0xd0 [ 45.239919][ T506] ? trace_contention_end+0xb3/0x180 [ 45.239923][ T506] ? __mutex_lock+0x1db/0x1ea0 [ 45.239924][ T506] ? __mutex_lock+0x9a3/0x1ea0 [ 45.239925][ T506] ? rtnl_newlink+0x9a8/0x11c0 [ 45.239927][ T506] ? ww_mutex_lock+0x160/0x160 [ 45.239929][ T506] ? __rtnl_newlink+0x3fa/0xa50 [ 45.239931][ T506] rtnl_newlink+0x9f1/0x11c0 [ 45.239932][ T506] ? rcu_is_watching+0x16/0xd0 [ 45.239934][ T506] ? rtnl_bridge_getlink+0x850/0x850 [ 45.239935][ T506] ? lock_acquire+0x13c/0x160 [ 45.239936][ T506] ? rcu_is_watching+0x16/0xd0 [ 45.239938][ T506] ? is_bpf_text_address+0x3e/0x110 [ 45.239941][ T506] ? is_bpf_text_address+0x72/0x110 [ 45.239943][ T506] ? kernel_text_address+0x149/0x170 [ 45.239944][ T506] ? __kernel_text_address+0x12/0x30 [ 45.239946][ T506] ? write_profile+0xf0/0xf0 [ 45.239947][ T506] ? unwind_get_return_address+0x67/0xd0 [ 45.239948][ T506] ? rcu_is_watching+0x16/0xd0 [ 45.239950][ T506] ? rcu_is_watching+0x16/0xd0 [ 45.239951][ T506] ? cap_capable+0x1d7/0x3d0 [ 45.239954][ T506] ? lock_acquire+0x13c/0x160 [ 45.239956][ T506] ? rcu_is_watching+0x16/0xd0 [ 45.239958][ T506] ? rtnl_bridge_getlink+0x850/0x850 [ 45.239959][ T506] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 45.239961][ T506] ? rtnl_link_fill+0x920/0x920 [ 45.239962][ T506] ? filter_irq_stacks+0xd0/0xd0 [ 45.239963][ T506] ? stack_depot_save_flags+0x1bc/0x790 [ 45.239965][ T506] netlink_rcv_skb+0x14e/0x3a0 [ 45.239967][ T506] ? rtnl_link_fill+0x920/0x920 [ 45.239968][ T506] ? lock_release+0x184/0x1f0 [ 45.239969][ T506] ? netlink_ack+0xcf0/0xcf0 [ 45.239971][ T506] ? __netlink_lookup+0xb9/0x6b0 [ 45.239973][ T506] ? rcu_is_watching+0x16/0xd0 [ 45.239975][ T506] ? lock_release+0x184/0x1f0 [ 45.239977][ T506] netlink_unicast+0x486/0x750 [ 45.239978][ T506] ? netlink_attachskb+0x810/0x810 [ 45.239980][ T506] ? rcu_is_watching+0x16/0xd0 [ 45.239981][ T506] ? rcu_is_watching+0x16/0xd0 [ 45.239983][ T506] ? lock_acquire+0x13c/0x160 [ 45.239985][ T506] netlink_sendmsg+0x735/0xc60 [ 45.239987][ T506] ? netlink_unicast+0x750/0x750 [ 45.239988][ T506] ? rcu_is_watching+0x16/0xd0 [ 45.239990][ T506] ? lock_release+0x184/0x1f0 [ 45.239992][ T506] ____sys_sendmsg+0x415/0x880 [ 45.239993][ T506] ? copy_msghdr_from_user+0x279/0x420 [ 45.239995][ T506] ? get_timestamp.constprop.0+0x390/0x390 [ 45.239996][ T506] ? move_addr_to_kernel+0x40/0x40 [ 45.239997][ T506] ? filter_irq_stacks+0xd0/0xd0 [ 45.240000][ T506] ___sys_sendmsg+0x14e/0x1d0 [ 45.240002][ T506] ? copy_msghdr_from_user+0x420/0x420 [ 45.240006][ T506] __sys_sendmsg+0x12c/0x1d0 [ 45.240008][ T506] ? __sys_sendmsg_sock+0x20/0x20 [ 45.240011][ T506] ? rcu_is_watching+0x16/0xd0 [ 45.240013][ T506] do_syscall_64+0xff/0x530 [ 45.240014][ T506] ? exc_page_fault+0xee/0x100 [ 45.240016][ T506] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 45.240017][ T506] RIP: 0033:0x7f47114ca54e [ 45.240019][ T506] 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 [ 45.240022][ T506] RSP: 002b:00007ffd17335680 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 45.240025][ T506] RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 00007f47114ca54e [ 45.240027][ T506] RDX: 0000000000000000 RSI: 00007ffd17335730 RDI: 0000000000000005 [ 45.240027][ T506] RBP: 00007ffd17335690 R08: 0000000000000000 R09: 0000000000000000 [ 45.240028][ T506] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a910cd5 [ 45.240029][ T506] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffd17335df0 [ 45.240031][ T506] [ 45.269778][ T506] ip (506) used greatest stack depth: 23016 bytes left [ 45.286643][ T507] br1: port 2(veth2) entered disabled state [ 45.301480][ T508] veth2: left allmulticast mode [ 45.302206][ T508] veth2: left promiscuous mode [ 45.302407][ T508] br1: port 2(veth2) entered disabled state [ 45.303057][ T508] veth1: left allmulticast mode [ 45.303185][ T508] veth1: left promiscuous mode [ 45.303363][ T508] br1: port 1(veth1) entered disabled state