[ 10.446243][ T209] ip (209) used greatest stack depth: 23840 bytes left [ 10.446261][ T209] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.446264][ T209] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 209, name: ip [ 10.446266][ T209] preempt_count: 2, expected: 0 [ 10.446266][ T209] RCU nest depth: 0, expected: 0 [ 10.446267][ T209] locks held by ip/209: 5, last CPU#1: [ 10.446270][ T209] #0: ffffffffb88027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 10.446283][ T209] #1: ffffffffb8969cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.446288][ T209] #2: ffffffffb8969d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.446292][ T209] #3: ffffffffb8889660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.446296][ T209] #4: ffffffffb8889560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.446300][ T209] irq event stamp: 28592 [ 10.446301][ T209] hardirqs last enabled at (28591): [] __down_trylock_console_sem+0x86/0xa0 [ 10.446303][ T209] hardirqs last disabled at (28592): [] console_emit_next_record+0x3d4/0x4c0 [ 10.446305][ T209] softirqs last enabled at (28432): [] handle_softirqs+0x67c/0x900 [ 10.446307][ T209] softirqs last disabled at (28425): [] __irq_exit_rcu+0x145/0x1c0 [ 10.446309][ T209] Preemption disabled at: [ 10.446310][ T209] [<0000000000000000>] 0x0 [ 10.446317][ T209] CPU: 1 UID: 0 PID: 209 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.446320][ T209] Tainted: [W]=WARN [ 10.446321][ T209] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.446323][ T209] Call Trace: [ 10.446324][ T209] [ 10.446326][ T209] dump_stack_lvl+0x6f/0xa0 [ 10.446332][ T209] __might_resched.cold+0x1fe/0x2c1 [ 10.446337][ T209] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.446341][ T209] ? __kmalloc_noprof+0xdb/0x760 [ 10.446346][ T209] __kmalloc_noprof+0x443/0x760 [ 10.446348][ T209] ? alloc_buf.isra.0+0x4b/0x260 [ 10.446354][ T209] ? do_raw_spin_unlock+0x59/0x250 [ 10.446357][ T209] alloc_buf.isra.0+0x4b/0x260 [ 10.446360][ T209] put_chars+0x1e1/0x2f0 [ 10.446362][ T209] ? __send_to_port+0x420/0x420 [ 10.446364][ T209] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.446368][ T209] ? validate_chain+0x38b/0xc20 [ 10.446373][ T209] hvc_console_print+0x292/0x780 [ 10.446379][ T209] ? hvc_write+0x3a0/0x3a0 [ 10.446381][ T209] ? rcu_is_watching+0x16/0xd0 [ 10.446384][ T209] ? lock_acquire+0x13c/0x160 [ 10.446388][ T209] console_emit_next_record+0x22f/0x4c0 [ 10.446391][ T209] ? devkmsg_read+0x4b0/0x4b0 [ 10.446393][ T209] ? console_flush_one_record+0x106/0x710 [ 10.446397][ T209] ? rcu_is_watching+0x16/0xd0 [ 10.446399][ T209] ? lock_acquire+0x13c/0x160 [ 10.446403][ T209] console_flush_one_record+0x46f/0x710 [ 10.446407][ T209] ? console_emit_next_record+0x4c0/0x4c0 [ 10.446409][ T209] ? __lock_acquire+0x518/0xc20 [ 10.446414][ T209] console_unlock+0xee/0x1f0 [ 10.446416][ T209] ? console_flush_one_record+0x710/0x710 [ 10.446418][ T209] ? rcu_is_watching+0x16/0xd0 [ 10.446420][ T209] ? lock_acquire+0x60/0x160 [ 10.446424][ T209] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.446426][ T209] ? vprintk_emit+0x320/0x3e0 [ 10.446429][ T209] vprintk_emit+0x37c/0x3e0 [ 10.446433][ T209] ? wake_up_klogd_work_func+0x90/0x90 [ 10.446436][ T209] ? __lock_acquire+0x518/0xc20 [ 10.446440][ T209] _printk+0xc7/0x100 [ 10.446443][ T209] ? snapshot_read.cold+0x21/0x21 [ 10.446446][ T209] ? do_raw_spin_lock+0x131/0x280 [ 10.446449][ T209] ? __rwlock_init+0x150/0x150 [ 10.446452][ T209] ? do_raw_spin_lock+0x131/0x280 [ 10.446455][ T209] do_exit.cold+0x82/0x9c [ 10.446458][ T209] ? exit_notify+0x890/0x890 [ 10.446460][ T209] ? __lock_release.isra.0+0x69/0x1a0 [ 10.446463][ T209] ? rcu_is_watching+0x16/0xd0 [ 10.446467][ T209] do_group_exit+0xb8/0x370 [ 10.446470][ T209] __x64_sys_exit_group+0x3c/0x50 [ 10.446472][ T209] x64_sys_call+0x1567/0x1570 [ 10.446474][ T209] do_syscall_64+0xff/0x530 [ 10.446477][ T209] ? exc_page_fault+0xee/0x100 [ 10.446480][ T209] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.446482][ T209] RIP: 0033:0x7f6d3fff11b8 [ 10.446484][ T209] Code: Unable to access opcode bytes at 0x7f6d3fff118e. [ 10.446485][ T209] RSP: 002b:00007ffc90c9ae68 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 10.446488][ T209] RAX: ffffffffffffffda RBX: 00007f6d40121f88 RCX: 00007f6d3fff11b8 [ 10.446489][ T209] RDX: 00007f6d3fd3bfc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 10.446490][ T209] RBP: 00007ffc90c9aec0 R08: 0000000000000000 R09: 0000000000008010 [ 10.446491][ T209] R10: 00007ffc90c9ac80 R11: 0000000000000246 R12: 0000000000000001 [ 10.446492][ T209] R13: 0000000000000000 R14: 00007f6d40120680 R15: 00007f6d40121fa0 [ 10.446498][ T209] [ 11.295416][ T237] 8021q: 802.1Q VLAN Support v1.8 [ 12.638604][ T282] br1: port 1(vx100) entered blocking state [ 12.638776][ T282] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.638779][ T282] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 282, name: ip [ 12.638781][ T282] preempt_count: 1, expected: 0 [ 12.638782][ T282] RCU nest depth: 0, expected: 0 [ 12.638784][ T282] locks held by ip/282: 5, last CPU#0: [ 12.638787][ T282] #0: ffffffffb90d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 12.638802][ T282] #1: ffffffffb8969cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.638811][ T282] #2: ffffffffb8969d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.638817][ T282] #3: ffffffffb8889660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.638823][ T282] #4: ffffffffb8889560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 12.638829][ T282] irq event stamp: 19598 [ 12.638830][ T282] hardirqs last enabled at (19597): [] __down_trylock_console_sem+0x86/0xa0 [ 12.638834][ T282] hardirqs last disabled at (19598): [] console_emit_next_record+0x3d4/0x4c0 [ 12.638837][ T282] softirqs last enabled at (19510): [] __alloc_skb+0x4c2/0x5f0 [ 12.638841][ T282] softirqs last disabled at (19508): [] __alloc_skb+0x4c2/0x5f0 [ 12.638844][ T282] Preemption disabled at: [ 12.638845][ T282] [] vprintk_emit+0x31b/0x3e0 [ 12.638853][ T282] CPU: 0 UID: 0 PID: 282 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.638858][ T282] Tainted: [W]=WARN [ 12.638859][ T282] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.638861][ T282] Call Trace: [ 12.638863][ T282] [ 12.638866][ T282] dump_stack_lvl+0x6f/0xa0 [ 12.638873][ T282] ? vprintk_emit+0x31b/0x3e0 [ 12.638876][ T282] __might_resched.cold+0x1fe/0x2c1 [ 12.638882][ T282] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.638888][ T282] ? __kmalloc_noprof+0xdb/0x760 [ 12.638896][ T282] __kmalloc_noprof+0x443/0x760 [ 12.638899][ T282] ? alloc_buf.isra.0+0x4b/0x260 [ 12.638907][ T282] ? do_raw_spin_unlock+0x59/0x250 [ 12.638911][ T282] alloc_buf.isra.0+0x4b/0x260 [ 12.638916][ T282] put_chars+0x1e1/0x2f0 [ 12.638921][ T282] ? __send_to_port+0x420/0x420 [ 12.638928][ T282] ? validate_chain+0x34a/0xc20 [ 12.638934][ T282] hvc_console_print+0x292/0x780 [ 12.638938][ T282] ? mark_usage+0x61/0x170 [ 12.638941][ T282] ? __lock_acquire+0x518/0xc20 [ 12.638944][ T282] ? __lock_acquire+0x518/0xc20 [ 12.638950][ T282] ? hvc_write+0x3a0/0x3a0 [ 12.638954][ T282] ? console_emit_next_record+0x1df/0x4c0 [ 12.638959][ T282] ? rcu_is_watching+0x16/0xd0 [ 12.638964][ T282] ? lock_acquire+0x13c/0x160 [ 12.638971][ T282] console_emit_next_record+0x22f/0x4c0 [ 12.638977][ T282] ? devkmsg_read+0x4b0/0x4b0 [ 12.638979][ T282] ? console_flush_one_record+0x106/0x710 [ 12.638985][ T282] ? rcu_is_watching+0x16/0xd0 [ 12.638988][ T282] ? lock_acquire+0x13c/0x160 [ 12.638998][ T282] console_flush_one_record+0x46f/0x710 [ 12.639005][ T282] ? console_emit_next_record+0x4c0/0x4c0 [ 12.639007][ T282] ? __lock_acquire+0x518/0xc20 [ 12.639017][ T282] console_unlock+0xee/0x1f0 [ 12.639021][ T282] ? console_flush_one_record+0x710/0x710 [ 12.639023][ T282] ? rcu_is_watching+0x16/0xd0 [ 12.639027][ T282] ? lock_acquire+0x60/0x160 [ 12.639033][ T282] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.639036][ T282] ? vprintk_emit+0x320/0x3e0 [ 12.639041][ T282] vprintk_emit+0x37c/0x3e0 [ 12.639048][ T282] ? wake_up_klogd_work_func+0x90/0x90 [ 12.639051][ T282] ? __lock_release.isra.0+0x69/0x1a0 [ 12.639054][ T282] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 12.639060][ T282] ? mark_held_locks+0x40/0x70 [ 12.639066][ T282] _printk+0xc7/0x100 [ 12.639071][ T282] ? snapshot_read.cold+0x21/0x21 [ 12.639077][ T282] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 12.639094][ T282] ? do_setlink.isra.0+0xa31/0x2750 [ 12.639096][ T282] ? rtnl_newlink+0x9f1/0x11c0 [ 12.639098][ T282] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 12.639106][ T282] br_set_state+0x22f/0x430 [bridge] [ 12.639123][ T282] br_init_port+0xc4/0x200 [bridge] [ 12.639137][ T282] new_nbp+0x39c/0x580 [bridge] [ 12.639153][ T282] br_add_if+0x212/0x1320 [bridge] [ 12.639165][ T282] ? is_bpf_text_address+0x72/0x110 [ 12.639172][ T282] ? kernel_text_address+0x149/0x170 [ 12.639177][ T282] ? __kernel_text_address+0x12/0x30 [ 12.639184][ T282] do_set_master+0x357/0x580 [ 12.639196][ T282] do_setlink.isra.0+0xa31/0x2750 [ 12.639202][ T282] ? stack_trace_save+0x93/0xc0 [ 12.639206][ T282] ? rtnl_link_get_size+0x350/0x350 [ 12.639208][ T282] ? rcu_read_lock_any_held+0x66/0x90 [ 12.639211][ T282] ? stack_depot_save_flags+0x38e/0x790 [ 12.639216][ T282] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 12.639220][ T282] ? rcu_read_lock_any_held+0x3c/0x90 [ 12.639222][ T282] ? validate_chain+0x38b/0xc20 [ 12.639227][ T282] ? kasan_save_stack+0x3d/0x50 [ 12.639231][ T282] ? kasan_save_stack+0x2f/0x50 [ 12.639233][ T282] ? kasan_save_track+0x14/0x30 [ 12.639237][ T282] ? __lock_acquire+0x518/0xc20 [ 12.639240][ T282] ? netlink_seq_next+0x4e/0x60 [ 12.639244][ T282] ? ___sys_sendmsg+0xb0/0x1d0 [ 12.639251][ T282] ? lock_acquire.part.0+0xd4/0x280 [ 12.639254][ T282] ? rtnl_newlink+0x9a8/0x11c0 [ 12.639259][ T282] ? rcu_is_watching+0x16/0xd0 [ 12.639262][ T282] ? lock_acquire+0x13c/0x160 [ 12.639264][ T282] ? rcu_is_watching+0x16/0xd0 [ 12.639267][ T282] ? rcu_is_watching+0x16/0xd0 [ 12.639271][ T282] ? trace_contention_end+0xb3/0x180 [ 12.639276][ T282] ? __mutex_lock+0x1db/0x1ea0 [ 12.639278][ T282] ? __mutex_lock+0x9a3/0x1ea0 [ 12.639282][ T282] ? rtnl_newlink+0x9a8/0x11c0 [ 12.639288][ T282] ? ww_mutex_lock+0x160/0x160 [ 12.639290][ T282] ? nla_get_range_signed+0x3d0/0x3d0 [ 12.639299][ T282] ? __rtnl_newlink+0x3fa/0xa50 [ 12.639309][ T282] rtnl_newlink+0x9f1/0x11c0 [ 12.639318][ T282] ? rtnl_bridge_getlink+0x850/0x850 [ 12.639321][ T282] ? __lock_acquire+0x518/0xc20 [ 12.639329][ T282] ? lock_acquire.part.0+0xd4/0x280 [ 12.639332][ T282] ? find_held_lock+0x2b/0x80 [ 12.639335][ T282] ? rtnl_bridge_getlink+0x850/0x850 [ 12.639338][ T282] ? __lock_release.isra.0+0x69/0x1a0 [ 12.639345][ T282] ? rtnl_bridge_getlink+0x850/0x850 [ 12.639348][ T282] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 12.639354][ T282] ? rtnl_link_fill+0x920/0x920 [ 12.639356][ T282] ? __lock_acquire+0x518/0xc20 [ 12.639363][ T282] ? lock_acquire.part.0+0xd4/0x280 [ 12.639366][ T282] ? find_held_lock+0x2b/0x80 [ 12.639372][ T282] netlink_rcv_skb+0x14e/0x3a0 [ 12.639375][ T282] ? rtnl_link_fill+0x920/0x920 [ 12.639380][ T282] ? netlink_ack+0xcf0/0xcf0 [ 12.639391][ T282] ? netlink_deliver_tap+0xc5/0x330 [ 12.639394][ T282] ? netlink_deliver_tap+0x13c/0x330 [ 12.639401][ T282] netlink_unicast+0x486/0x750 [ 12.639408][ T282] ? netlink_attachskb+0x810/0x810 [ 12.639412][ T282] ? __lock_acquire+0x518/0xc20 [ 12.639419][ T282] netlink_sendmsg+0x735/0xc60 [ 12.639426][ T282] ? netlink_unicast+0x750/0x750 [ 12.639432][ T282] ? __might_fault+0x97/0x140 [ 12.639440][ T282] ____sys_sendmsg+0x415/0x880 [ 12.639444][ T282] ? copy_msghdr_from_user+0x279/0x420 [ 12.639448][ T282] ? get_timestamp.constprop.0+0x390/0x390 [ 12.639450][ T282] ? move_addr_to_kernel+0x40/0x40 [ 12.639462][ T282] ___sys_sendmsg+0x14e/0x1d0 [ 12.639466][ T282] ? copy_msghdr_from_user+0x420/0x420 [ 12.639496][ T282] __sys_sendmsg+0x12c/0x1d0 [ 12.639501][ T282] ? __sys_sendmsg_sock+0x20/0x20 [ 12.639512][ T282] ? rcu_is_watching+0x16/0xd0 [ 12.639518][ T282] do_syscall_64+0xff/0x530 [ 12.639522][ T282] ? exc_page_fault+0xee/0x100 [ 12.639527][ T282] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 12.639530][ T282] RIP: 0033:0x7f655925754e [ 12.639535][ T282] 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 [ 12.639537][ T282] RSP: 002b:00007ffcf83b5990 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 12.639541][ T282] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f655925754e [ 12.639543][ T282] RDX: 0000000000000000 RSI: 00007ffcf83b5a40 RDI: 0000000000000005 [ 12.639545][ T282] RBP: 00007ffcf83b59a0 R08: 0000000000000000 R09: 0000000000000000 [ 12.639546][ T282] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a915924 [ 12.639548][ T282] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffcf83b6100 [ 12.639560][ T282] [ 12.709134][ T282] br1: port 1(vx100) entered disabled state [ 12.709520][ T282] vx100: entered allmulticast mode [ 12.711423][ T282] vx100: entered promiscuous mode [ 12.712152][ T282] br1: port 1(vx100) entered blocking state [ 12.712572][ T282] br1: port 1(vx100) entered forwarding state [ 12.719553][ T282] ip (282) used greatest stack depth: 23336 bytes left [ 12.797566][ T284] br1: port 2(veth1) entered blocking state [ 12.798104][ T284] br1: port 2(veth1) entered disabled state [ 12.798622][ T284] veth1: entered allmulticast mode [ 12.801338][ T284] veth1: entered promiscuous mode [ 12.831608][ T39] br1: port 2(veth1) entered blocking state [ 12.832508][ T39] br1: port 2(veth1) entered forwarding state [ 12.964383][ T290] br2: port 1(vx200) entered blocking state [ 12.964756][ T290] br2: port 1(vx200) entered disabled state [ 12.965168][ T290] vx200: entered allmulticast mode [ 12.966766][ T290] vx200: entered promiscuous mode [ 12.967436][ T290] br2: port 1(vx200) entered blocking state [ 12.968076][ T290] br2: port 1(vx200) entered forwarding state [ 13.110311][ T293] br2: port 2(veth2.20) entered blocking state [ 13.110666][ T293] br2: port 2(veth2.20) entered disabled state [ 13.111382][ T293] veth2.20: entered allmulticast mode [ 13.111617][ T293] veth2: entered allmulticast mode [ 13.113588][ T293] veth2.20: entered promiscuous mode [ 13.113851][ T293] veth2: entered promiscuous mode [ 13.156956][ T294] br2: port 2(veth2.20) entered blocking state [ 13.157334][ T294] br2: port 2(veth2.20) entered forwarding state [ 14.524751][ T339] br3: port 1(w1) entered blocking state [ 14.524775][ T339] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 14.524778][ T339] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 339, name: ip [ 14.524780][ T339] preempt_count: 1, expected: 0 [ 14.524782][ T339] RCU nest depth: 0, expected: 0 [ 14.524783][ T339] locks held by ip/339: 5, last CPU#2: [ 14.524786][ T339] #0: ffffffffb90d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 14.524802][ T339] #1: ffffffffb8969cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 14.524810][ T339] #2: ffffffffb8969d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 14.524816][ T339] #3: ffffffffb8889660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 14.524822][ T339] #4: ffffffffb8889560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 14.524828][ T339] irq event stamp: 19904 [ 14.524830][ T339] hardirqs last enabled at (19903): [] __down_trylock_console_sem+0x86/0xa0 [ 14.524833][ T339] hardirqs last disabled at (19904): [] console_emit_next_record+0x3d4/0x4c0 [ 14.524837][ T339] softirqs last enabled at (19846): [] __alloc_skb+0x4c2/0x5f0 [ 14.524841][ T339] softirqs last disabled at (19844): [] __alloc_skb+0x4c2/0x5f0 [ 14.524844][ T339] Preemption disabled at: [ 14.524845][ T339] [] vprintk_emit+0x31b/0x3e0 [ 14.524853][ T339] CPU: 2 UID: 0 PID: 339 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 14.524857][ T339] Tainted: [W]=WARN [ 14.524858][ T339] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.524860][ T339] Call Trace: [ 14.524863][ T339] [ 14.524865][ T339] dump_stack_lvl+0x6f/0xa0 [ 14.524873][ T339] ? vprintk_emit+0x31b/0x3e0 [ 14.524876][ T339] __might_resched.cold+0x1fe/0x2c1 [ 14.524882][ T339] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 14.524888][ T339] ? __kmalloc_noprof+0xdb/0x760 [ 14.524895][ T339] __kmalloc_noprof+0x443/0x760 [ 14.524899][ T339] ? alloc_buf.isra.0+0x4b/0x260 [ 14.524907][ T339] ? do_raw_spin_unlock+0x59/0x250 [ 14.524911][ T339] alloc_buf.isra.0+0x4b/0x260 [ 14.524916][ T339] put_chars+0x1e1/0x2f0 [ 14.524919][ T339] ? prb_final_commit+0x50/0x50 [ 14.524922][ T339] ? __send_to_port+0x420/0x420 [ 14.524927][ T339] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.524933][ T339] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.524936][ T339] ? validate_chain+0x38b/0xc20 [ 14.524942][ T339] hvc_console_print+0x292/0x780 [ 14.524946][ T339] ? mark_usage+0x61/0x170 [ 14.524949][ T339] ? __lock_acquire+0x518/0xc20 [ 14.524952][ T339] ? __lock_acquire+0x518/0xc20 [ 14.524958][ T339] ? hvc_write+0x3a0/0x3a0 [ 14.524962][ T339] ? console_emit_next_record+0x1df/0x4c0 [ 14.524967][ T339] ? rcu_is_watching+0x16/0xd0 [ 14.524971][ T339] ? lock_acquire+0x13c/0x160 [ 14.524977][ T339] console_emit_next_record+0x22f/0x4c0 [ 14.524984][ T339] ? devkmsg_read+0x4b0/0x4b0 [ 14.524986][ T339] ? console_flush_one_record+0x106/0x710 [ 14.524993][ T339] ? rcu_is_watching+0x16/0xd0 [ 14.524997][ T339] ? lock_acquire+0x13c/0x160 [ 14.525004][ T339] console_flush_one_record+0x46f/0x710 [ 14.525011][ T339] ? console_emit_next_record+0x4c0/0x4c0 [ 14.525014][ T339] ? __lock_acquire+0x518/0xc20 [ 14.525023][ T339] console_unlock+0xee/0x1f0 [ 14.525028][ T339] ? console_flush_one_record+0x710/0x710 [ 14.525030][ T339] ? rcu_is_watching+0x16/0xd0 [ 14.525034][ T339] ? lock_acquire+0x60/0x160 [ 14.525040][ T339] ? __down_trylock_console_sem+0x5e/0xa0 [ 14.525043][ T339] ? vprintk_emit+0x320/0x3e0 [ 14.525048][ T339] vprintk_emit+0x37c/0x3e0 [ 14.525055][ T339] ? wake_up_klogd_work_func+0x90/0x90 [ 14.525059][ T339] ? __lock_release.isra.0+0x69/0x1a0 [ 14.525063][ T339] ? mark_held_locks+0x40/0x70 [ 14.525069][ T339] _printk+0xc7/0x100 [ 14.525074][ T339] ? snapshot_read.cold+0x21/0x21 [ 14.525081][ T339] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 14.525098][ T339] ? do_setlink.isra.0+0xa31/0x2750 [ 14.525100][ T339] ? rtnl_newlink+0x9f1/0x11c0 [ 14.525102][ T339] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 14.525110][ T339] br_set_state+0x22f/0x430 [bridge] [ 14.525128][ T339] br_init_port+0xc4/0x200 [bridge] [ 14.525141][ T339] new_nbp+0x39c/0x580 [bridge] [ 14.525157][ T339] br_add_if+0x212/0x1320 [bridge] [ 14.525169][ T339] ? is_bpf_text_address+0x72/0x110 [ 14.525175][ T339] ? kernel_text_address+0x149/0x170 [ 14.525179][ T339] ? __kernel_text_address+0x12/0x30 [ 14.525186][ T339] do_set_master+0x357/0x580 [ 14.525194][ T339] do_setlink.isra.0+0xa31/0x2750 [ 14.525204][ T339] ? stack_trace_save+0x93/0xc0 [ 14.525208][ T339] ? rtnl_link_get_size+0x350/0x350 [ 14.525210][ T339] ? rcu_read_lock_any_held+0x66/0x90 [ 14.525213][ T339] ? stack_depot_save_flags+0x38e/0x790 [ 14.525218][ T339] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 14.525222][ T339] ? rcu_read_lock_any_held+0x3c/0x90 [ 14.525225][ T339] ? validate_chain+0x38b/0xc20 [ 14.525229][ T339] ? kasan_save_stack+0x3d/0x50 [ 14.525233][ T339] ? kasan_save_stack+0x2f/0x50 [ 14.525235][ T339] ? kasan_save_track+0x14/0x30 [ 14.525240][ T339] ? __lock_acquire+0x518/0xc20 [ 14.525242][ T339] ? netlink_seq_next+0x4e/0x60 [ 14.525246][ T339] ? ___sys_sendmsg+0xb0/0x1d0 [ 14.525253][ T339] ? lock_acquire.part.0+0xd4/0x280 [ 14.525256][ T339] ? rtnl_newlink+0x9a8/0x11c0 [ 14.525261][ T339] ? rcu_is_watching+0x16/0xd0 [ 14.525265][ T339] ? lock_acquire+0x13c/0x160 [ 14.525267][ T339] ? rcu_is_watching+0x16/0xd0 [ 14.525270][ T339] ? rcu_is_watching+0x16/0xd0 [ 14.525273][ T339] ? trace_contention_end+0xb3/0x180 [ 14.525279][ T339] ? __mutex_lock+0x1db/0x1ea0 [ 14.525283][ T339] ? __mutex_lock+0x9a3/0x1ea0 [ 14.525286][ T339] ? rtnl_newlink+0x9a8/0x11c0 [ 14.525292][ T339] ? ww_mutex_lock+0x160/0x160 [ 14.525295][ T339] ? nla_get_range_signed+0x3d0/0x3d0 [ 14.525304][ T339] ? __rtnl_newlink+0x3fa/0xa50 [ 14.525313][ T339] rtnl_newlink+0x9f1/0x11c0 [ 14.525323][ T339] ? rtnl_bridge_getlink+0x850/0x850 [ 14.525326][ T339] ? __lock_acquire+0x518/0xc20 [ 14.525334][ T339] ? lock_acquire.part.0+0xd4/0x280 [ 14.525337][ T339] ? find_held_lock+0x2b/0x80 [ 14.525340][ T339] ? rtnl_bridge_getlink+0x850/0x850 [ 14.525344][ T339] ? __lock_release.isra.0+0x69/0x1a0 [ 14.525350][ T339] ? rtnl_bridge_getlink+0x850/0x850 [ 14.525353][ T339] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 14.525359][ T339] ? rtnl_link_fill+0x920/0x920 [ 14.525361][ T339] ? __lock_acquire+0x518/0xc20 [ 14.525369][ T339] ? lock_acquire.part.0+0xd4/0x280 [ 14.525372][ T339] ? find_held_lock+0x2b/0x80 [ 14.525378][ T339] netlink_rcv_skb+0x14e/0x3a0 [ 14.525381][ T339] ? rtnl_link_fill+0x920/0x920 [ 14.525386][ T339] ? netlink_ack+0xcf0/0xcf0 [ 14.525397][ T339] ? netlink_deliver_tap+0xc5/0x330 [ 14.525399][ T339] ? netlink_deliver_tap+0x13c/0x330 [ 14.525407][ T339] netlink_unicast+0x486/0x750 [ 14.525414][ T339] ? netlink_attachskb+0x810/0x810 [ 14.525418][ T339] ? __lock_acquire+0x518/0xc20 [ 14.525425][ T339] netlink_sendmsg+0x735/0xc60 [ 14.525432][ T339] ? netlink_unicast+0x750/0x750 [ 14.525439][ T339] ? __might_fault+0x97/0x140 [ 14.525447][ T339] ____sys_sendmsg+0x415/0x880 [ 14.525451][ T339] ? copy_msghdr_from_user+0x279/0x420 [ 14.525455][ T339] ? get_timestamp.constprop.0+0x390/0x390 [ 14.525457][ T339] ? move_addr_to_kernel+0x40/0x40 [ 14.525471][ T339] ___sys_sendmsg+0x14e/0x1d0 [ 14.525475][ T339] ? copy_msghdr_from_user+0x420/0x420 [ 14.525506][ T339] __sys_sendmsg+0x12c/0x1d0 [ 14.525510][ T339] ? __sys_sendmsg_sock+0x20/0x20 [ 14.525521][ T339] ? rcu_is_watching+0x16/0xd0 [ 14.525528][ T339] do_syscall_64+0xff/0x530 [ 14.525531][ T339] ? exc_page_fault+0xee/0x100 [ 14.525536][ T339] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 14.525539][ T339] RIP: 0033:0x7fa70065654e [ 14.525544][ T339] 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 [ 14.525547][ T339] RSP: 002b:00007ffec74225a0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 14.525550][ T339] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007fa70065654e [ 14.525552][ T339] RDX: 0000000000000000 RSI: 00007ffec7422650 RDI: 0000000000000005 [ 14.525554][ T339] RBP: 00007ffec74225b0 R08: 0000000000000000 R09: 0000000000000000 [ 14.525555][ T339] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a915926 [ 14.525557][ T339] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffec7422d10 [ 14.525570][ T339] [ 14.603580][ T339] br3: port 1(w1) entered disabled state [ 14.604542][ T339] w1: entered allmulticast mode [ 14.607327][ T339] w1: entered promiscuous mode [ 14.648705][ T340] br3: port 1(w1) entered blocking state [ 14.648970][ T340] br3: port 1(w1) entered forwarding state [ 14.813927][ T345] br3: port 2(vx100) entered blocking state [ 14.814525][ T345] br3: port 2(vx100) entered disabled state [ 14.815098][ T345] vx100: entered allmulticast mode [ 14.818209][ T345] vx100: entered promiscuous mode [ 14.819443][ T345] br3: port 2(vx100) entered blocking state [ 14.819968][ T345] br3: port 2(vx100) entered forwarding state [ 16.068382][ T392] br3: port 1(w1) entered blocking state [ 16.068405][ T392] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 16.068408][ T392] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 392, name: ip [ 16.068410][ T392] preempt_count: 1, expected: 0 [ 16.068411][ T392] RCU nest depth: 0, expected: 0 [ 16.068413][ T392] locks held by ip/392: 5, last CPU#2: [ 16.068415][ T392] #0: ffffffffb90d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 16.068431][ T392] #1: ffffffffb8969cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 16.068440][ T392] #2: ffffffffb8969d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 16.068446][ T392] #3: ffffffffb8889660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 16.068452][ T392] #4: ffffffffb8889560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 16.068459][ T392] irq event stamp: 20874 [ 16.068460][ T392] hardirqs last enabled at (20873): [] __down_trylock_console_sem+0x86/0xa0 [ 16.068464][ T392] hardirqs last disabled at (20874): [] console_emit_next_record+0x3d4/0x4c0 [ 16.068466][ T392] softirqs last enabled at (20816): [] __alloc_skb+0x4c2/0x5f0 [ 16.068471][ T392] softirqs last disabled at (20814): [] __alloc_skb+0x4c2/0x5f0 [ 16.068474][ T392] Preemption disabled at: [ 16.068475][ T392] [] vprintk_emit+0x31b/0x3e0 [ 16.068483][ T392] CPU: 2 UID: 0 PID: 392 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 16.068488][ T392] Tainted: [W]=WARN [ 16.068489][ T392] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.068491][ T392] Call Trace: [ 16.068493][ T392] [ 16.068496][ T392] dump_stack_lvl+0x6f/0xa0 [ 16.068503][ T392] ? vprintk_emit+0x31b/0x3e0 [ 16.068506][ T392] __might_resched.cold+0x1fe/0x2c1 [ 16.068513][ T392] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 16.068519][ T392] ? __kmalloc_noprof+0xdb/0x760 [ 16.068526][ T392] __kmalloc_noprof+0x443/0x760 [ 16.068530][ T392] ? alloc_buf.isra.0+0x4b/0x260 [ 16.068539][ T392] ? do_raw_spin_unlock+0x59/0x250 [ 16.068542][ T392] alloc_buf.isra.0+0x4b/0x260 [ 16.068548][ T392] put_chars+0x1e1/0x2f0 [ 16.068551][ T392] ? prb_final_commit+0x50/0x50 [ 16.068554][ T392] ? __send_to_port+0x420/0x420 [ 16.068560][ T392] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 16.068565][ T392] ? rcu_read_lock_any_held+0x3c/0x90 [ 16.068569][ T392] ? validate_chain+0x38b/0xc20 [ 16.068575][ T392] hvc_console_print+0x292/0x780 [ 16.068579][ T392] ? mark_usage+0x61/0x170 [ 16.068582][ T392] ? __lock_acquire+0x518/0xc20 [ 16.068584][ T392] ? __lock_acquire+0x518/0xc20 [ 16.068591][ T392] ? hvc_write+0x3a0/0x3a0 [ 16.068595][ T392] ? console_emit_next_record+0x1df/0x4c0 [ 16.068600][ T392] ? rcu_is_watching+0x16/0xd0 [ 16.068604][ T392] ? lock_acquire+0x13c/0x160 [ 16.068611][ T392] console_emit_next_record+0x22f/0x4c0 [ 16.068617][ T392] ? devkmsg_read+0x4b0/0x4b0 [ 16.068620][ T392] ? console_flush_one_record+0x106/0x710 [ 16.068625][ T392] ? rcu_is_watching+0x16/0xd0 [ 16.068628][ T392] ? lock_acquire+0x13c/0x160 [ 16.068636][ T392] console_flush_one_record+0x46f/0x710 [ 16.068643][ T392] ? console_emit_next_record+0x4c0/0x4c0 [ 16.068645][ T392] ? __lock_acquire+0x518/0xc20 [ 16.068655][ T392] console_unlock+0xee/0x1f0 [ 16.068659][ T392] ? console_flush_one_record+0x710/0x710 [ 16.068662][ T392] ? rcu_is_watching+0x16/0xd0 [ 16.068666][ T392] ? lock_acquire+0x60/0x160 [ 16.068672][ T392] ? __down_trylock_console_sem+0x5e/0xa0 [ 16.068675][ T392] ? vprintk_emit+0x320/0x3e0 [ 16.068680][ T392] vprintk_emit+0x37c/0x3e0 [ 16.068687][ T392] ? wake_up_klogd_work_func+0x90/0x90 [ 16.068691][ T392] ? __lock_release.isra.0+0x69/0x1a0 [ 16.068695][ T392] ? mark_held_locks+0x40/0x70 [ 16.068702][ T392] _printk+0xc7/0x100 [ 16.068707][ T392] ? snapshot_read.cold+0x21/0x21 [ 16.068713][ T392] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 16.068731][ T392] ? do_setlink.isra.0+0xa31/0x2750 [ 16.068733][ T392] ? rtnl_newlink+0x9f1/0x11c0 [ 16.068735][ T392] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 16.068743][ T392] br_set_state+0x22f/0x430 [bridge] [ 16.068761][ T392] br_init_port+0xc4/0x200 [bridge] [ 16.068775][ T392] new_nbp+0x39c/0x580 [bridge] [ 16.068791][ T392] br_add_if+0x212/0x1320 [bridge] [ 16.068803][ T392] ? is_bpf_text_address+0x72/0x110 [ 16.068809][ T392] ? kernel_text_address+0x149/0x170 [ 16.068813][ T392] ? __kernel_text_address+0x12/0x30 [ 16.068820][ T392] do_set_master+0x357/0x580 [ 16.068828][ T392] do_setlink.isra.0+0xa31/0x2750 [ 16.068833][ T392] ? stack_trace_save+0x93/0xc0 [ 16.068838][ T392] ? rtnl_link_get_size+0x350/0x350 [ 16.068840][ T392] ? rcu_read_lock_any_held+0x66/0x90 [ 16.068843][ T392] ? stack_depot_save_flags+0x38e/0x790 [ 16.068847][ T392] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 16.068851][ T392] ? rcu_read_lock_any_held+0x3c/0x90 [ 16.068854][ T392] ? validate_chain+0x38b/0xc20 [ 16.068859][ T392] ? kasan_save_stack+0x3d/0x50 [ 16.068862][ T392] ? kasan_save_stack+0x2f/0x50 [ 16.068865][ T392] ? kasan_save_track+0x14/0x30 [ 16.068869][ T392] ? __lock_acquire+0x518/0xc20 [ 16.068872][ T392] ? netlink_seq_next+0x4e/0x60 [ 16.068876][ T392] ? ___sys_sendmsg+0xb0/0x1d0 [ 16.068883][ T392] ? lock_acquire.part.0+0xd4/0x280 [ 16.068886][ T392] ? rtnl_newlink+0x9a8/0x11c0 [ 16.068891][ T392] ? rcu_is_watching+0x16/0xd0 [ 16.068895][ T392] ? lock_acquire+0x13c/0x160 [ 16.068897][ T392] ? rcu_is_watching+0x16/0xd0 [ 16.068900][ T392] ? rcu_is_watching+0x16/0xd0 [ 16.068903][ T392] ? trace_contention_end+0xb3/0x180 [ 16.068909][ T392] ? __mutex_lock+0x1db/0x1ea0 [ 16.068913][ T392] ? __mutex_lock+0x9a3/0x1ea0 [ 16.068916][ T392] ? rtnl_newlink+0x9a8/0x11c0 [ 16.068923][ T392] ? ww_mutex_lock+0x160/0x160 [ 16.068925][ T392] ? nla_get_range_signed+0x3d0/0x3d0 [ 16.068935][ T392] ? __rtnl_newlink+0x3fa/0xa50 [ 16.068944][ T392] rtnl_newlink+0x9f1/0x11c0 [ 16.068955][ T392] ? rtnl_bridge_getlink+0x850/0x850 [ 16.068958][ T392] ? __lock_acquire+0x518/0xc20 [ 16.068966][ T392] ? lock_acquire.part.0+0xd4/0x280 [ 16.068969][ T392] ? find_held_lock+0x2b/0x80 [ 16.068972][ T392] ? rtnl_bridge_getlink+0x850/0x850 [ 16.068975][ T392] ? __lock_release.isra.0+0x69/0x1a0 [ 16.068982][ T392] ? rtnl_bridge_getlink+0x850/0x850 [ 16.068986][ T392] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 16.068994][ T392] ? rtnl_link_fill+0x920/0x920 [ 16.068996][ T392] ? __lock_acquire+0x518/0xc20 [ 16.069004][ T392] ? lock_acquire.part.0+0xd4/0x280 [ 16.069007][ T392] ? find_held_lock+0x2b/0x80 [ 16.069013][ T392] netlink_rcv_skb+0x14e/0x3a0 [ 16.069016][ T392] ? rtnl_link_fill+0x920/0x920 [ 16.069022][ T392] ? netlink_ack+0xcf0/0xcf0 [ 16.069033][ T392] ? netlink_deliver_tap+0xc5/0x330 [ 16.069036][ T392] ? netlink_deliver_tap+0x13c/0x330 [ 16.069043][ T392] netlink_unicast+0x486/0x750 [ 16.069050][ T392] ? netlink_attachskb+0x810/0x810 [ 16.069054][ T392] ? __lock_acquire+0x518/0xc20 [ 16.069062][ T392] netlink_sendmsg+0x735/0xc60 [ 16.069069][ T392] ? netlink_unicast+0x750/0x750 [ 16.069076][ T392] ? __might_fault+0x97/0x140 [ 16.069085][ T392] ____sys_sendmsg+0x415/0x880 [ 16.069089][ T392] ? copy_msghdr_from_user+0x279/0x420 [ 16.069093][ T392] ? get_timestamp.constprop.0+0x390/0x390 [ 16.069095][ T392] ? move_addr_to_kernel+0x40/0x40 [ 16.069109][ T392] ___sys_sendmsg+0x14e/0x1d0 [ 16.069114][ T392] ? copy_msghdr_from_user+0x420/0x420 [ 16.069144][ T392] __sys_sendmsg+0x12c/0x1d0 [ 16.069148][ T392] ? __sys_sendmsg_sock+0x20/0x20 [ 16.069160][ T392] ? rcu_is_watching+0x16/0xd0 [ 16.069166][ T392] do_syscall_64+0xff/0x530 [ 16.069169][ T392] ? exc_page_fault+0xee/0x100 [ 16.069174][ T392] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 16.069178][ T392] RIP: 0033:0x7f091a44654e [ 16.069183][ T392] 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 [ 16.069185][ T392] RSP: 002b:00007ffc6433ee00 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 16.069189][ T392] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f091a44654e [ 16.069191][ T392] RDX: 0000000000000000 RSI: 00007ffc6433eeb0 RDI: 0000000000000005 [ 16.069193][ T392] RBP: 00007ffc6433ee10 R08: 0000000000000000 R09: 0000000000000000 [ 16.069194][ T392] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a915928 [ 16.069196][ T392] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffc6433f570 [ 16.069214][ T392] [ 16.142279][ T392] br3: port 1(w1) entered disabled state [ 16.142560][ T392] w1: entered allmulticast mode [ 16.144175][ T392] w1: entered promiscuous mode [ 16.178024][ T393] br3: port 1(w1) entered blocking state [ 16.178316][ T393] br3: port 1(w1) entered forwarding state [ 16.252454][ T39] br3: port 1(w1) entered disabled state [ 16.359366][ T398] br3: port 2(vx200) entered blocking state [ 16.359698][ T398] br3: port 2(vx200) entered disabled state [ 16.360030][ T398] vx200: entered allmulticast mode [ 16.362470][ T398] vx200: entered promiscuous mode [ 16.375112][ T398] br3: port 2(vx200) entered blocking state [ 16.375417][ T398] br3: port 2(vx200) entered forwarding state [ 16.623586][ T39] br3: port 1(w1) entered blocking state [ 16.624195][ T39] br3: port 1(w1) entered forwarding state [ 17.314893][ T438] br3: port 3(w1.20) entered blocking state [ 17.314915][ T438] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 17.314917][ T438] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 438, name: ip [ 17.314919][ T438] preempt_count: 1, expected: 0 [ 17.314920][ T438] RCU nest depth: 0, expected: 0 [ 17.314921][ T438] locks held by ip/438: 5, last CPU#3: [ 17.314923][ T438] #0: ffffffffb90d2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 17.314935][ T438] #1: ffffffffb8969cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 17.314941][ T438] #2: ffffffffb8969d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 17.314945][ T438] #3: ffffffffb8889660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 17.314949][ T438] #4: ffffffffb8889560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 17.314953][ T438] irq event stamp: 11946 [ 17.314954][ T438] hardirqs last enabled at (11945): [] __down_trylock_console_sem+0x86/0xa0 [ 17.314957][ T438] hardirqs last disabled at (11946): [] console_emit_next_record+0x3d4/0x4c0 [ 17.314959][ T438] softirqs last enabled at (11888): [] __alloc_skb+0x4c2/0x5f0 [ 17.314962][ T438] softirqs last disabled at (11886): [] __alloc_skb+0x4c2/0x5f0 [ 17.314965][ T438] Preemption disabled at: [ 17.314965][ T438] [] vprintk_emit+0x31b/0x3e0 [ 17.314971][ T438] CPU: 3 UID: 0 PID: 438 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 17.314974][ T438] Tainted: [W]=WARN [ 17.314975][ T438] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 17.314977][ T438] Call Trace: [ 17.314979][ T438] [ 17.314980][ T438] dump_stack_lvl+0x6f/0xa0 [ 17.314986][ T438] ? vprintk_emit+0x31b/0x3e0 [ 17.314988][ T438] __might_resched.cold+0x1fe/0x2c1 [ 17.314995][ T438] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 17.315000][ T438] ? __kmalloc_noprof+0xdb/0x760 [ 17.315005][ T438] __kmalloc_noprof+0x443/0x760 [ 17.315007][ T438] ? alloc_buf.isra.0+0x4b/0x260 [ 17.315013][ T438] ? do_raw_spin_unlock+0x59/0x250 [ 17.315015][ T438] alloc_buf.isra.0+0x4b/0x260 [ 17.315018][ T438] put_chars+0x1e1/0x2f0 [ 17.315020][ T438] ? prb_final_commit+0x50/0x50 [ 17.315023][ T438] ? __send_to_port+0x420/0x420 [ 17.315025][ T438] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 17.315030][ T438] ? rcu_read_lock_any_held+0x3c/0x90 [ 17.315032][ T438] ? validate_chain+0x38b/0xc20 [ 17.315035][ T438] hvc_console_print+0x292/0x780 [ 17.315038][ T438] ? mark_usage+0x61/0x170 [ 17.315040][ T438] ? __lock_acquire+0x518/0xc20 [ 17.315042][ T438] ? __lock_acquire+0x518/0xc20 [ 17.315045][ T438] ? hvc_write+0x3a0/0x3a0 [ 17.315047][ T438] ? console_emit_next_record+0x1df/0x4c0 [ 17.315050][ T438] ? rcu_is_watching+0x16/0xd0 [ 17.315053][ T438] ? lock_acquire+0x13c/0x160 [ 17.315056][ T438] console_emit_next_record+0x22f/0x4c0 [ 17.315060][ T438] ? devkmsg_read+0x4b0/0x4b0 [ 17.315062][ T438] ? console_flush_one_record+0x106/0x710 [ 17.315065][ T438] ? rcu_is_watching+0x16/0xd0 [ 17.315067][ T438] ? lock_acquire+0x13c/0x160 [ 17.315071][ T438] console_flush_one_record+0x46f/0x710 [ 17.315075][ T438] ? console_emit_next_record+0x4c0/0x4c0 [ 17.315077][ T438] ? __lock_acquire+0x518/0xc20 [ 17.315082][ T438] console_unlock+0xee/0x1f0 [ 17.315085][ T438] ? console_flush_one_record+0x710/0x710 [ 17.315087][ T438] ? rcu_is_watching+0x16/0xd0 [ 17.315089][ T438] ? lock_acquire+0x60/0x160 [ 17.315093][ T438] ? __down_trylock_console_sem+0x5e/0xa0 [ 17.315094][ T438] ? vprintk_emit+0x320/0x3e0 [ 17.315097][ T438] vprintk_emit+0x37c/0x3e0 [ 17.315102][ T438] ? wake_up_klogd_work_func+0x90/0x90 [ 17.315104][ T438] ? __lock_release.isra.0+0x69/0x1a0 [ 17.315109][ T438] _printk+0xc7/0x100 [ 17.315112][ T438] ? snapshot_read.cold+0x21/0x21 [ 17.315116][ T438] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 17.315129][ T438] ? do_setlink.isra.0+0xa31/0x2750 [ 17.315130][ T438] ? rtnl_newlink+0x9f1/0x11c0 [ 17.315131][ T438] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 17.315135][ T438] br_set_state+0x22f/0x430 [bridge] [ 17.315145][ T438] br_init_port+0xc4/0x200 [bridge] [ 17.315154][ T438] new_nbp+0x39c/0x580 [bridge] [ 17.315163][ T438] br_add_if+0x212/0x1320 [bridge] [ 17.315169][ T438] ? is_bpf_text_address+0x72/0x110 [ 17.315173][ T438] ? kernel_text_address+0x149/0x170 [ 17.315176][ T438] ? __kernel_text_address+0x12/0x30 [ 17.315180][ T438] do_set_master+0x357/0x580 [ 17.315185][ T438] do_setlink.isra.0+0xa31/0x2750 [ 17.315188][ T438] ? stack_trace_save+0x93/0xc0 [ 17.315191][ T438] ? rtnl_link_get_size+0x350/0x350 [ 17.315192][ T438] ? rcu_read_lock_any_held+0x66/0x90 [ 17.315194][ T438] ? stack_depot_save_flags+0x38e/0x790 [ 17.315198][ T438] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 17.315200][ T438] ? rcu_read_lock_any_held+0x3c/0x90 [ 17.315202][ T438] ? validate_chain+0x38b/0xc20 [ 17.315205][ T438] ? kasan_save_stack+0x3d/0x50 [ 17.315207][ T438] ? kasan_save_stack+0x2f/0x50 [ 17.315209][ T438] ? kasan_save_track+0x14/0x30 [ 17.315218][ T438] ? __lock_acquire+0x518/0xc20 [ 17.315220][ T438] ? netlink_seq_next+0x4e/0x60 [ 17.315223][ T438] ? ___sys_sendmsg+0xb0/0x1d0 [ 17.315227][ T438] ? lock_acquire.part.0+0xd4/0x280 [ 17.315229][ T438] ? rtnl_newlink+0x9a8/0x11c0 [ 17.315232][ T438] ? rcu_is_watching+0x16/0xd0 [ 17.315234][ T438] ? lock_acquire+0x13c/0x160 [ 17.315235][ T438] ? rcu_is_watching+0x16/0xd0 [ 17.315237][ T438] ? rcu_is_watching+0x16/0xd0 [ 17.315239][ T438] ? trace_contention_end+0xb3/0x180 [ 17.315243][ T438] ? __mutex_lock+0x1db/0x1ea0 [ 17.315246][ T438] ? __mutex_lock+0x9a3/0x1ea0 [ 17.315248][ T438] ? rtnl_newlink+0x9a8/0x11c0 [ 17.315251][ T438] ? ww_mutex_lock+0x160/0x160 [ 17.315253][ T438] ? nla_get_range_signed+0x3d0/0x3d0 [ 17.315258][ T438] ? __rtnl_newlink+0x3fa/0xa50 [ 17.315263][ T438] rtnl_newlink+0x9f1/0x11c0 [ 17.315268][ T438] ? rtnl_bridge_getlink+0x850/0x850 [ 17.315270][ T438] ? __lock_acquire+0x518/0xc20 [ 17.315274][ T438] ? lock_acquire.part.0+0xd4/0x280 [ 17.315276][ T438] ? find_held_lock+0x2b/0x80 [ 17.315278][ T438] ? rtnl_bridge_getlink+0x850/0x850 [ 17.315280][ T438] ? __lock_release.isra.0+0x69/0x1a0 [ 17.315283][ T438] ? rtnl_bridge_getlink+0x850/0x850 [ 17.315285][ T438] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 17.315288][ T438] ? rtnl_link_fill+0x920/0x920 [ 17.315289][ T438] ? __lock_acquire+0x518/0xc20 [ 17.315293][ T438] ? lock_acquire.part.0+0xd4/0x280 [ 17.315295][ T438] ? find_held_lock+0x2b/0x80 [ 17.315299][ T438] netlink_rcv_skb+0x14e/0x3a0 [ 17.315300][ T438] ? rtnl_link_fill+0x920/0x920 [ 17.315303][ T438] ? netlink_ack+0xcf0/0xcf0 [ 17.315309][ T438] ? netlink_deliver_tap+0xc5/0x330 [ 17.315310][ T438] ? netlink_deliver_tap+0x13c/0x330 [ 17.315314][ T438] netlink_unicast+0x486/0x750 [ 17.315318][ T438] ? netlink_attachskb+0x810/0x810 [ 17.315321][ T438] ? __lock_acquire+0x518/0xc20 [ 17.315325][ T438] netlink_sendmsg+0x735/0xc60 [ 17.315328][ T438] ? netlink_unicast+0x750/0x750 [ 17.315332][ T438] ? __might_fault+0x97/0x140 [ 17.315337][ T438] ____sys_sendmsg+0x415/0x880 [ 17.315339][ T438] ? copy_msghdr_from_user+0x279/0x420 [ 17.315341][ T438] ? get_timestamp.constprop.0+0x390/0x390 [ 17.315343][ T438] ? move_addr_to_kernel+0x40/0x40 [ 17.315350][ T438] ___sys_sendmsg+0x14e/0x1d0 [ 17.315353][ T438] ? copy_msghdr_from_user+0x420/0x420 [ 17.315368][ T438] __sys_sendmsg+0x12c/0x1d0 [ 17.315370][ T438] ? __sys_sendmsg_sock+0x20/0x20 [ 17.315376][ T438] ? rcu_is_watching+0x16/0xd0 [ 17.315380][ T438] do_syscall_64+0xff/0x530 [ 17.315382][ T438] ? exc_page_fault+0xee/0x100 [ 17.315385][ T438] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 17.315387][ T438] RIP: 0033:0x7f5ef7e9354e [ 17.315391][ T438] 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 [ 17.315393][ T438] RSP: 002b:00007ffe71ee20b0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 17.315395][ T438] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f5ef7e9354e [ 17.315397][ T438] RDX: 0000000000000000 RSI: 00007ffe71ee2160 RDI: 0000000000000005 [ 17.315398][ T438] RBP: 00007ffe71ee20c0 R08: 0000000000000000 R09: 0000000000000000 [ 17.315399][ T438] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a915929 [ 17.315400][ T438] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffe71ee2820 [ 17.315406][ T438] [ 17.356569][ T438] br3: port 3(w1.20) entered disabled state [ 17.357122][ T438] w1.20: entered allmulticast mode [ 17.359298][ T438] w1.20: entered promiscuous mode [ 17.603124][ T451] br3: port 3(w1.20) entered blocking state [ 17.603527][ T451] br3: port 3(w1.20) entered forwarding state [ 25.916102][ T12] w1.20: left allmulticast mode [ 25.916182][ T12] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 25.916185][ T12] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 12, name: kworker/u16:0 [ 25.916188][ T12] preempt_count: 1, expected: 0 [ 25.916189][ T12] RCU nest depth: 0, expected: 0 [ 25.916191][ T12] locks held by kworker/u16:0/12: 8, last CPU#2: [ 25.916194][ T12] #0: ff110000019f9d40 ((wq_completion)netns){+.+.}-{0:0}, at: process_one_work+0xd9a/0x1560 [ 25.916210][ T12] #1: ffa00000000c7d10 (net_cleanup_work){+.+.}-{0:0}, at: process_one_work+0xdfc/0x1560 [ 25.916216][ T12] #2: ffffffffb90b6f68 (pernet_ops_rwsem){++++}-{4:4}, at: cleanup_net+0xbf/0x890 [ 25.916224][ T12] #3: ffffffffb90d2c40 (rtnl_mutex){+.+.}-{4:4}, at: ops_undo_list+0x534/0x8f0 [ 25.916229][ T12] #4: ffffffffb8969cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 25.916236][ T12] #5: ffffffffb8969d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 25.916242][ T12] #6: ffffffffb8889660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 25.916253][ T12] #7: ffffffffb8889560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 25.916259][ T12] irq event stamp: 27096 [ 25.916261][ T12] hardirqs last enabled at (27095): [] __down_trylock_console_sem+0x86/0xa0 [ 25.916264][ T12] hardirqs last disabled at (27096): [] console_emit_next_record+0x3d4/0x4c0 [ 25.916267][ T12] softirqs last enabled at (27078): [] handle_softirqs+0x67c/0x900 [ 25.916271][ T12] softirqs last disabled at (27069): [] __irq_exit_rcu+0x145/0x1c0 [ 25.916275][ T12] Preemption disabled at: [ 25.916275][ T12] [] vprintk_emit+0x31b/0x3e0 [ 25.916284][ T12] CPU: 2 UID: 0 PID: 12 Comm: kworker/u16:0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 25.916289][ T12] Tainted: [W]=WARN [ 25.916290][ T12] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 25.916293][ T12] Workqueue: netns cleanup_net [ 25.916297][ T12] Call Trace: [ 25.916300][ T12] [ 25.916304][ T12] dump_stack_lvl+0x6f/0xa0 [ 25.916312][ T12] ? vprintk_emit+0x31b/0x3e0 [ 25.916315][ T12] __might_resched.cold+0x1fe/0x2c1 [ 25.916322][ T12] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 25.916328][ T12] ? __kmalloc_noprof+0xdb/0x760 [ 25.916335][ T12] __kmalloc_noprof+0x443/0x760 [ 25.916339][ T12] ? alloc_buf.isra.0+0x4b/0x260 [ 25.916348][ T12] ? do_raw_spin_unlock+0x59/0x250 [ 25.916352][ T12] alloc_buf.isra.0+0x4b/0x260 [ 25.916358][ T12] put_chars+0x1e1/0x2f0 [ 25.916362][ T12] ? __send_to_port+0x420/0x420 [ 25.916368][ T12] ? validate_chain+0x34a/0xc20 [ 25.916376][ T12] hvc_console_print+0x292/0x780 [ 25.916380][ T12] ? __lock_acquire+0x518/0xc20 [ 25.916382][ T12] ? __lock_acquire+0x518/0xc20 [ 25.916391][ T12] ? hvc_write+0x3a0/0x3a0 [ 25.916393][ T12] ? console_emit_next_record+0x1df/0x4c0 [ 25.916399][ T12] ? rcu_is_watching+0x16/0xd0 [ 25.916404][ T12] ? lock_acquire+0x13c/0x160 [ 25.916411][ T12] console_emit_next_record+0x22f/0x4c0 [ 25.916418][ T12] ? devkmsg_read+0x4b0/0x4b0 [ 25.916420][ T12] ? console_flush_one_record+0x106/0x710 [ 25.916426][ T12] ? rcu_is_watching+0x16/0xd0 [ 25.916429][ T12] ? lock_acquire+0x13c/0x160 [ 25.916436][ T12] console_flush_one_record+0x46f/0x710 [ 25.916443][ T12] ? console_emit_next_record+0x4c0/0x4c0 [ 25.916446][ T12] ? __lock_acquire+0x518/0xc20 [ 25.916456][ T12] console_unlock+0xee/0x1f0 [ 25.916460][ T12] ? console_flush_one_record+0x710/0x710 [ 25.916463][ T12] ? rcu_is_watching+0x16/0xd0 [ 25.916466][ T12] ? lock_acquire+0x60/0x160 [ 25.916473][ T12] ? __down_trylock_console_sem+0x5e/0xa0 [ 25.916476][ T12] ? vprintk_emit+0x320/0x3e0 [ 25.916482][ T12] vprintk_emit+0x37c/0x3e0 [ 25.916488][ T12] ? wake_up_klogd_work_func+0x90/0x90 [ 25.916491][ T12] ? process_one_work+0xe3e/0x1560 [ 25.916494][ T12] ? kthread+0x367/0x460 [ 25.916497][ T12] ? ret_from_fork+0x474/0x6b0 [ 25.916501][ T12] ? ret_from_fork_asm+0x11/0x20 [ 25.916509][ T12] _printk+0xc7/0x100 [ 25.916514][ T12] ? snapshot_read.cold+0x21/0x21 [ 25.916523][ T12] ? netdev_name+0x43/0x60 [ 25.916528][ T12] ? __netdev_printk+0x17f/0x1d0 [ 25.916534][ T12] netdev_info+0xe2/0x116 [ 25.916538][ T12] ? netdev_notice+0x120/0x120 [ 25.916545][ T12] ? rcu_is_watching+0x16/0xd0 [ 25.916549][ T12] ? __up_read+0x355/0x9e0 [ 25.916553][ T12] ? down_write_nested+0x200/0x200 [ 25.916556][ T12] ? __lock_release.isra.0+0x69/0x1a0 [ 25.916559][ T12] ? mutex_is_locked+0x1c/0x50 [ 25.916565][ T12] netif_set_allmulti.cold+0x7c/0x166 [ 25.916573][ T12] dev_set_allmulti+0xa2/0x150 [ 25.916579][ T12] del_nbp+0xb7/0xc10 [bridge] [ 25.916606][ T12] br_dev_delete+0x9d/0x1e0 [bridge] [ 25.916623][ T12] br_net_exit_rtnl+0xb8/0x110 [bridge] [ 25.916638][ T12] ops_undo_list+0x5be/0x8f0 [ 25.916645][ T12] ? lockdep_hardirqs_on+0x91/0x130 [ 25.916650][ T12] ? rtnl_net_dumpid_one+0x2d0/0x2d0 [ 25.916653][ T12] ? __lock_release.isra.0+0x69/0x1a0 [ 25.916658][ T12] ? idr_destroy+0x121/0x250 [ 25.916666][ T12] cleanup_net+0x431/0x890 [ 25.916672][ T12] ? net_passive_dec+0x230/0x230 [ 25.916679][ T12] ? lock_acquire+0x13c/0x160 [ 25.916682][ T12] ? rcu_is_watching+0x16/0xd0 [ 25.916689][ T12] process_one_work+0xe3e/0x1560 [ 25.916702][ T12] ? __queue_delayed_work+0x470/0x470 [ 25.916708][ T12] ? lock_acquire.part.0+0xd4/0x280 [ 25.916723][ T12] worker_thread+0x4f1/0xd60 [ 25.916736][ T12] ? rescuer_thread+0x1340/0x1340 [ 25.916740][ T12] kthread+0x367/0x460 [ 25.916742][ T12] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 25.916746][ T12] ? kthread_affine_preferred+0x4c0/0x4c0 [ 25.916751][ T12] ret_from_fork+0x474/0x6b0 [ 25.916756][ T12] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 25.916762][ T12] ? __switch_to+0x5a3/0xe00 [ 25.916766][ T12] ? kthread_affine_preferred+0x4c0/0x4c0 [ 25.916771][ T12] ret_from_fork_asm+0x11/0x20 [ 25.916787][ T12] [ 25.971130][ C0] clocksource: Watchdog remote CPU 2 read timed out [ 25.971167][ C0] [ 25.971169][ C0] ======================================================== [ 25.971170][ C0] WARNING: possible irq lock inversion dependency detected [ 25.971172][ C0] 7.2.0-virtme #1 Tainted: G W [ 25.971175][ C0] -------------------------------------------------------- [ 25.971175][ C0] systemd-udevd/81 just changed the state of lock: [ 25.971177][ C0] ffffffffb8889660 (console_owner){..-.}-{0:0}, at: console_trylock_spinning+0xa4/0x1e0 [ 25.971190][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 25.971192][ C0] (fs_reclaim){+.+.}-{0:0} [ 25.971194][ C0] [ 25.971194][ C0] [ 25.971194][ C0] and interrupts could create inverse lock ordering between them. [ 25.971194][ C0] [ 25.971195][ C0] [ 25.971195][ C0] other info that might help us debug this: [ 25.971195][ C0] Possible interrupt unsafe locking scenario: [ 25.971195][ C0] [ 25.971196][ C0] CPU0 CPU1 [ 25.971196][ C0] ---- ---- [ 25.971197][ C0] lock(fs_reclaim); [ 25.971198][ C0] local_irq_disable(); [ 25.971198][ C0] lock(console_owner); [ 25.971199][ C0] lock(fs_reclaim); [ 25.971200][ C0] [ 25.971201][ C0] lock(console_owner); [ 25.971202][ C0] [ 25.971202][ C0] *** DEADLOCK *** [ 25.971202][ C0] [ 25.971202][ C0] locks held by systemd-udevd/81: 2, last CPU#0: [ 25.971203][ C0] #0: ffa0000000007c90 ((&watchdog_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 25.971209][ C0] #1: ffffffffb89ce8b8 (watchdog_lock){+.-.}-{3:3}, at: clocksource_watchdog+0x15/0x30 [ 25.971213][ C0] [ 25.971213][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 25.971217][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 25.971220][ C0] HARDIRQ-ON-W at: [ 25.971221][ C0] __lock_acquire+0x388/0xc20 [ 25.971224][ C0] lock_acquire.part.0+0xd4/0x280 [ 25.971226][ C0] fs_reclaim_acquire+0xd5/0x120 [ 25.971229][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 25.971231][ C0] kthread_create_worker_on_node+0xea/0x210 [ 25.971234][ C0] workqueue_init+0x2a/0x680 [ 25.971237][ C0] kernel_init_freeable+0x2fe/0x630 [ 25.971239][ C0] kernel_init+0x21/0x150 [ 25.971243][ C0] ret_from_fork+0x474/0x6b0 [ 25.971251][ C0] ret_from_fork_asm+0x11/0x20 [ 25.971254][ C0] SOFTIRQ-ON-W at: [ 25.971255][ C0] __lock_acquire+0x388/0xc20 [ 25.971257][ C0] lock_acquire.part.0+0xd4/0x280 [ 25.971258][ C0] fs_reclaim_acquire+0xd5/0x120 [ 25.971259][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 25.971261][ C0] kthread_create_worker_on_node+0xea/0x210 [ 25.971262][ C0] workqueue_init+0x2a/0x680 [ 25.971263][ C0] kernel_init_freeable+0x2fe/0x630 [ 25.971264][ C0] kernel_init+0x21/0x150 [ 25.971266][ C0] ret_from_fork+0x474/0x6b0 [ 25.971267][ C0] ret_from_fork_asm+0x11/0x20 [ 25.971268][ C0] INITIAL USE at: [ 25.971269][ C0] __lock_acquire+0x388/0xc20 [ 25.971270][ C0] lock_acquire.part.0+0xd4/0x280 [ 25.971272][ C0] fs_reclaim_acquire+0xd5/0x120 [ 25.971273][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 25.971274][ C0] kthread_create_worker_on_node+0xea/0x210 [ 25.971275][ C0] workqueue_init+0x2a/0x680 [ 25.971277][ C0] kernel_init_freeable+0x2fe/0x630 [ 25.971278][ C0] kernel_init+0x21/0x150 [ 25.971279][ C0] ret_from_fork+0x474/0x6b0 [ 25.971280][ C0] ret_from_fork_asm+0x11/0x20 [ 25.971281][ C0] } [ 25.971282][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 25.971286][ C0] ... acquired at: [ 25.971287][ C0] __lock_acquire+0x518/0xc20 [ 25.971288][ C0] lock_acquire.part.0+0xd4/0x280 [ 25.971290][ C0] fs_reclaim_acquire+0xd5/0x120 [ 25.971291][ C0] __kmalloc_noprof+0xd3/0x760 [ 25.971292][ C0] alloc_buf.isra.0+0x4b/0x260 [ 25.971295][ C0] put_chars+0x1e1/0x2f0 [ 25.971296][ C0] hvc_console_print+0x292/0x780 [ 25.971298][ C0] console_emit_next_record+0x22f/0x4c0 [ 25.971300][ C0] console_flush_one_record+0x46f/0x710 [ 25.971302][ C0] console_unlock+0xee/0x1f0 [ 25.971304][ C0] vprintk_emit+0x37c/0x3e0 [ 25.971305][ C0] _printk+0xc7/0x100 [ 25.971308][ C0] dm_interface_init+0x50/0x60 [ 25.971310][ C0] dm_init+0x51/0xd0 [ 25.971311][ C0] do_one_initcall+0x124/0x4f0 [ 25.971313][ C0] kernel_init_freeable+0x596/0x630 [ 25.971314][ C0] kernel_init+0x21/0x150 [ 25.971315][ C0] ret_from_fork+0x474/0x6b0 [ 25.971316][ C0] ret_from_fork_asm+0x11/0x20 [ 25.971318][ C0] [ 25.971318][ C0] -> (console_owner){..-.}-{0:0} { [ 25.971320][ C0] IN-SOFTIRQ-W at: [ 25.971320][ C0] __lock_acquire+0x388/0xc20 [ 25.971322][ C0] lock_acquire.part.0+0xd4/0x280 [ 25.971323][ C0] console_trylock_spinning+0xb5/0x1e0 [ 25.971325][ C0] vprintk_emit+0x320/0x3e0 [ 25.971326][ C0] _printk+0xc7/0x100 [ 25.971328][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 25.971330][ C0] call_timer_fn+0x160/0x4d0 [ 25.971331][ C0] __run_timers+0x68f/0xaa0 [ 25.971333][ C0] run_timer_softirq+0xf0/0x160 [ 25.971334][ C0] handle_softirqs+0x1d3/0x900 [ 25.971337][ C0] __irq_exit_rcu+0x145/0x1c0 [ 25.971338][ C0] irq_exit_rcu+0xe/0x30 [ 25.971339][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 25.971341][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 25.971343][ C0] rcu_read_lock_any_held+0x29/0x90 [ 25.971346][ C0] stack_depot_save_flags+0x38e/0x790 [ 25.971349][ C0] kasan_save_stack+0x3d/0x50 [ 25.971352][ C0] kasan_save_track+0x14/0x30 [ 25.971353][ C0] __kasan_slab_alloc+0x60/0x70 [ 25.971355][ C0] kmem_cache_alloc_noprof+0x21f/0x5c0 [ 25.971356][ C0] copy_signal+0x31/0x800 [ 25.971358][ C0] copy_process+0x2259/0x6240 [ 25.971360][ C0] kernel_clone+0x1a4/0x6c0 [ 25.971361][ C0] __do_sys_clone+0xe3/0x130 [ 25.971362][ C0] do_syscall_64+0xff/0x530 [ 25.971363][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 25.971365][ C0] INITIAL USE at: [ 25.971366][ C0] } [ 25.971366][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 25.971369][ C0] ... acquired at: [ 25.971370][ C0] mark_lock+0x1d7/0xa00 [ 25.971371][ C0] mark_usage+0x42/0x170 [ 25.971371][ T12] w1.20: left promiscuous mode [ 25.971372][ C0] __lock_acquire+0x388/0xc20 [ 25.971374][ C0] lock_acquire.part.0+0xd4/0x280 [ 25.971375][ C0] console_trylock_spinning+0xb5/0x1e0 [ 25.971377][ C0] vprintk_emit+0x320/0x3e0 [ 25.971378][ C0] _printk+0xc7/0x100 [ 25.971380][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 25.971381][ C0] call_timer_fn+0x160/0x4d0 [ 25.971382][ C0] __run_timers+0x68f/0xaa0 [ 25.971384][ C0] run_timer_softirq+0xf0/0x160 [ 25.971385][ C0] handle_softirqs+0x1d3/0x900 [ 25.971386][ C0] __irq_exit_rcu+0x145/0x1c0 [ 25.971388][ C0] irq_exit_rcu+0xe/0x30 [ 25.971389][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 25.971391][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 25.971392][ C0] rcu_read_lock_any_held+0x29/0x90 [ 25.971393][ C0] stack_depot_save_flags+0x38e/0x790 [ 25.971394][ C0] kasan_save_stack+0x3d/0x50 [ 25.971396][ C0] kasan_save_track+0x14/0x30 [ 25.971397][ C0] __kasan_slab_alloc+0x60/0x70 [ 25.971399][ C0] kmem_cache_alloc_noprof+0x21f/0x5c0 [ 25.971400][ C0] copy_signal+0x31/0x800 [ 25.971401][ C0] copy_process+0x2259/0x6240 [ 25.971402][ C0] kernel_clone+0x1a4/0x6c0 [ 25.971403][ C0] __do_sys_clone+0xe3/0x130 [ 25.971405][ C0] do_syscall_64+0xff/0x530 [ 25.971406][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 25.971407][ C0] [ 25.971407][ C0] [ 25.971407][ C0] stack backtrace: [ 25.971410][ C0] CPU: 0 UID: 0 PID: 81 Comm: systemd-udevd Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 25.971414][ C0] Tainted: [W]=WARN [ 25.971414][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 25.971417][ C0] Call Trace: [ 25.971418][ C0] [ 25.971419][ C0] dump_stack_lvl+0x6f/0xa0 [ 25.971423][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 25.971426][ C0] mark_lock_irq+0x989/0x9c0 [ 25.971427][ C0] ? add_lock_to_list+0x2c/0x1a0 [ 25.971430][ C0] mark_lock+0x1d7/0xa00 [ 25.971432][ C0] mark_usage+0x42/0x170 [ 25.971434][ C0] __lock_acquire+0x388/0xc20 [ 25.971435][ C0] ? bpf_compute_postorder+0xc6/0xb30 [ 25.971440][ C0] lock_acquire.part.0+0xd4/0x280 [ 25.971442][ C0] ? console_trylock_spinning+0xa4/0x1e0 [ 25.971443][ C0] ? rcu_is_watching+0x16/0xd0 [ 25.971446][ C0] ? lock_acquire+0x13c/0x160 [ 25.971448][ C0] console_trylock_spinning+0xb5/0x1e0 [ 25.971450][ C0] ? console_trylock_spinning+0xa4/0x1e0 [ 25.971452][ C0] vprintk_emit+0x320/0x3e0 [ 25.971454][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 25.971456][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 25.971458][ C0] _printk+0xc7/0x100 [ 25.971460][ C0] ? snapshot_read.cold+0x21/0x21 [ 25.971462][ C0] ? clocksource_watchdog.part.0+0x450/0x540 [ 25.971464][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 25.971467][ C0] clocksource_watchdog.part.0.cold+0x105/0x1dd [ 25.971468][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 25.971470][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 25.971471][ C0] call_timer_fn+0x160/0x4d0 [ 25.971473][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 25.971475][ C0] ? debug_object_active_state+0x430/0x430 [ 25.971478][ C0] ? find_held_lock+0x2b/0x80 [ 25.971480][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 25.971482][ C0] ? rcu_is_watching+0x16/0xd0 [ 25.971484][ C0] __run_timers+0x68f/0xaa0 [ 25.971486][ C0] ? clocksource_watchdog.part.0+0x540/0x540 [ 25.971488][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 25.971490][ C0] ? __lock_acquire+0x518/0xc20 [ 25.971493][ C0] ? __rwlock_init+0x150/0x150 [ 25.971495][ C0] run_timer_softirq+0xf0/0x160 [ 25.971497][ C0] ? __run_timers+0xaa0/0xaa0 [ 25.971499][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 25.971501][ C0] ? rcu_is_watching+0x16/0xd0 [ 25.971503][ C0] handle_softirqs+0x1d3/0x900 [ 25.971505][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 25.971507][ C0] ? _local_bh_enable+0xc0/0xc0 [ 25.971509][ C0] __irq_exit_rcu+0x145/0x1c0 [ 25.971510][ C0] irq_exit_rcu+0xe/0x30 [ 25.971512][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 25.971513][ C0] [ 25.971514][ C0] [ 25.971515][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 25.971517][ C0] RIP: 0010:rcu_read_lock_any_held+0x29/0x90 [ 25.971519][ C0] Code: 00 f3 0f 1e fa 53 bb 01 00 00 00 e8 c1 64 3b 02 85 c0 75 04 89 d8 5b c3 89 c3 e8 52 32 01 00 84 c0 74 5b e8 19 76 01 00 84 c0 <74> 52 be ff ff ff ff 48 c7 c7 00 0b 98 b8 e8 f4 62 3b 02 85 c0 75 [ 25.971521][ C0] RSP: 0018:ffa00000005477f0 EFLAGS: 00000202 [ 25.971524][ C0] RAX: 0000000000000001 RBX: 0000000000000001 RCX: 0000000000000001 [ 25.971525][ C0] RDX: ffffffffb8994280 RSI: 1ffffffff713285e RDI: ffffffffb89942f0 [ 25.971526][ C0] RBP: 000000000000000a R08: 00000000b0c050d1 R09: 0000000000000000 [ 25.971527][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: ffa0000000547860 [ 25.971528][ C0] R13: 000000000000000a R14: 00000000f998b823 R15: ff11000034938230 [ 25.971530][ C0] stack_depot_save_flags+0x38e/0x790 [ 25.971533][ C0] kasan_save_stack+0x3d/0x50 [ 25.971534][ C0] ? kasan_save_stack+0x2f/0x50 [ 25.971536][ C0] ? kasan_save_track+0x14/0x30 [ 25.971538][ C0] ? __kasan_slab_alloc+0x60/0x70 [ 25.971539][ C0] ? kmem_cache_alloc_noprof+0x21f/0x5c0 [ 25.971541][ C0] ? copy_signal+0x31/0x800 [ 25.971542][ C0] ? copy_process+0x2259/0x6240 [ 25.971543][ C0] ? kernel_clone+0x1a4/0x6c0 [ 25.971545][ C0] ? __do_sys_clone+0xe3/0x130 [ 25.971546][ C0] ? do_syscall_64+0xff/0x530 [ 25.971547][ C0] ? entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 25.971552][ C0] kasan_save_track+0x14/0x30 [ 25.971554][ C0] __kasan_slab_alloc+0x60/0x70 [ 25.971557][ C0] kmem_cache_alloc_noprof+0x21f/0x5c0 [ 25.971559][ C0] ? copy_signal+0x31/0x800 [ 25.971561][ C0] copy_signal+0x31/0x800 [ 25.971563][ C0] ? _raw_spin_unlock_irq+0x28/0x50 [ 25.971565][ C0] ? _raw_spin_unlock_irq+0x33/0x50 [ 25.971567][ C0] copy_process+0x2259/0x6240 [ 25.971568][ C0] ? find_held_lock+0x2b/0x80 [ 25.971571][ C0] ? pidfd_prepare+0x150/0x150 [ 25.971573][ C0] ? sock_set_timestamping+0x850/0x850 [ 25.971576][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 25.971578][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 25.971580][ C0] ? rcu_read_lock_any_held+0x3c/0x90 [ 25.971582][ C0] ? validate_chain+0x38b/0xc20 [ 25.971584][ C0] kernel_clone+0x1a4/0x6c0 [ 25.971586][ C0] ? create_io_thread+0x130/0x130 [ 25.971588][ C0] ? find_held_lock+0x2b/0x80 [ 25.971590][ C0] __do_sys_clone+0xe3/0x130 [ 25.971589][ T12] br3: port 3(w1.20) entered disabled state [ 25.971591][ C0] ? kernel_clone+0x6c0/0x6c0 [ 25.971594][ C0] ? rcu_is_watching+0x16/0xd0 [ 25.971596][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 25.971598][ C0] do_syscall_64+0xff/0x530 [ 25.971599][ C0] ? irq_exit_rcu+0x1a/0x30 [ 25.971601][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 25.971602][ C0] RIP: 0033:0x7f38a19a6126 [ 25.971605][ C0] Code: 7d e0 e8 7d af f5 ff 45 31 c0 31 d2 31 f6 64 48 8b 04 25 10 00 00 00 bf 11 00 20 01 4c 8d 90 d0 02 00 00 b8 38 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 5a 89 c3 85 c0 75 2f 64 48 8b 04 25 10 00 00 [ 25.971606][ C0] RSP: 002b:00007ffc313f2800 EFLAGS: 00000246 ORIG_RAX: 0000000000000038 [ 25.971608][ C0] RAX: ffffffffffffffda RBX: 0000000000000001 RCX: 00007f38a19a6126 [ 25.971608][ C0] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000001200011 [ 25.971609][ C0] RBP: 00007ffc313f2820 R08: 0000000000000000 R09: 00007ffc313f2bb0 [ 25.971610][ C0] R10: 00007f38a1204490 R11: 0000000000000246 R12: 0000000000000007 [ 25.971611][ C0] R13: 0000000000000000 R14: 00007ffc313f2a90 R15: 0000000000000000 [ 25.971613][ C0] [ 26.022851][ T12] vx200: left allmulticast mode [ 26.027437][ T12] vx200: left promiscuous mode [ 26.027662][ T12] br3: port 2(vx200) entered disabled state [ 26.034128][ T12] w1: left allmulticast mode [ 26.034284][ T12] w1: left promiscuous mode [ 26.034477][ T12] br3: port 1(w1) entered disabled state [ 26.098112][ T12] vx100: left allmulticast mode [ 26.098305][ T12] vx100: left promiscuous mode [ 26.098526][ T12] br3: port 2(vx100) entered disabled state [ 26.105049][ T12] w1: left allmulticast mode [ 26.105353][ T12] w1: left promiscuous mode [ 26.105739][ T12] br3: port 1(w1) entered disabled state [ 26.589330][ T559] vx200: left allmulticast mode [ 26.589522][ T559] vx200: left promiscuous mode [ 26.589732][ T559] br2: port 1(vx200) entered disabled state [ 26.666373][ T562] br2: port 2(veth2.20) entered disabled state [ 26.673331][ T562] veth2.20 (unregistering): left allmulticast mode [ 26.673643][ T562] veth2: left allmulticast mode [ 26.673902][ T562] veth2.20 (unregistering): left promiscuous mode [ 26.674218][ T562] veth2: left promiscuous mode [ 26.674624][ T562] br2: port 2(veth2.20) entered disabled state [ 26.774360][ T566] br1: port 2(veth1) entered disabled state [ 26.792692][ T568] veth1: left allmulticast mode [ 26.792863][ T568] veth1: left promiscuous mode [ 26.793080][ T568] br1: port 2(veth1) entered disabled state [ 26.820416][ T569] vx100: left allmulticast mode [ 26.820692][ T569] vx100: left promiscuous mode [ 26.821073][ T569] br1: port 1(vx100) entered disabled state