[ 9.093057][ T187] ip (187) used greatest stack depth: 24464 bytes left [ 9.093074][ T187] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.093075][ T187] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 187, name: ip [ 9.093077][ T187] preempt_count: 2, expected: 0 [ 9.093078][ T187] RCU nest depth: 0, expected: 0 [ 9.093079][ T187] locks held by ip/187: 5, last CPU#3: [ 9.093081][ T187] #0: ffffffff9b4027b8 (low_water_lock){+.+.}-{3:3}, at: do_exit+0x887/0xdc0 [ 9.093093][ T187] #1: ffffffff9b569cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.093098][ T187] #2: ffffffff9b569d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.093102][ T187] #3: ffffffff9b489660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.093106][ T187] #4: ffffffff9b489560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 9.093110][ T187] irq event stamp: 31182 [ 9.093111][ T187] hardirqs last enabled at (31181): [] __down_trylock_console_sem+0x86/0xa0 [ 9.093113][ T187] hardirqs last disabled at (31182): [] console_emit_next_record+0x3d4/0x4c0 [ 9.093115][ T187] softirqs last enabled at (31176): [] handle_softirqs+0x67c/0x900 [ 9.093117][ T187] softirqs last disabled at (31171): [] __irq_exit_rcu+0x145/0x1c0 [ 9.093119][ T187] Preemption disabled at: [ 9.093120][ T187] [<0000000000000000>] 0x0 [ 9.093126][ T187] CPU: 3 UID: 0 PID: 187 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.093130][ T187] Tainted: [W]=WARN [ 9.093130][ T187] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.093132][ T187] Call Trace: [ 9.093134][ T187] [ 9.093135][ T187] dump_stack_lvl+0x6f/0xa0 [ 9.093142][ T187] __might_resched.cold+0x1fe/0x2c1 [ 9.093147][ T187] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.093151][ T187] ? __kmalloc_noprof+0xdb/0x760 [ 9.093156][ T187] __kmalloc_noprof+0x443/0x760 [ 9.093158][ T187] ? alloc_buf.isra.0+0x4b/0x260 [ 9.093164][ T187] ? do_raw_spin_unlock+0x59/0x250 [ 9.093166][ T187] alloc_buf.isra.0+0x4b/0x260 [ 9.093169][ T187] put_chars+0x1e1/0x2f0 [ 9.093172][ T187] ? __send_to_port+0x420/0x420 [ 9.093173][ T187] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 9.093178][ T187] ? validate_chain+0x38b/0xc20 [ 9.093183][ T187] hvc_console_print+0x292/0x780 [ 9.093189][ T187] ? hvc_write+0x3a0/0x3a0 [ 9.093191][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.093194][ T187] ? lock_acquire+0x13c/0x160 [ 9.093198][ T187] console_emit_next_record+0x22f/0x4c0 [ 9.093202][ T187] ? devkmsg_read+0x4b0/0x4b0 [ 9.093203][ T187] ? console_flush_one_record+0x106/0x710 [ 9.093206][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.093209][ T187] ? lock_acquire+0x13c/0x160 [ 9.093213][ T187] console_flush_one_record+0x46f/0x710 [ 9.093217][ T187] ? console_emit_next_record+0x4c0/0x4c0 [ 9.093218][ T187] ? __lock_acquire+0x518/0xc20 [ 9.093223][ T187] console_unlock+0xee/0x1f0 [ 9.093226][ T187] ? console_flush_one_record+0x710/0x710 [ 9.093228][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.093230][ T187] ? lock_acquire+0x60/0x160 [ 9.093234][ T187] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.093236][ T187] ? vprintk_emit+0x320/0x3e0 [ 9.093239][ T187] vprintk_emit+0x37c/0x3e0 [ 9.093243][ T187] ? wake_up_klogd_work_func+0x90/0x90 [ 9.093246][ T187] ? __lock_acquire+0x518/0xc20 [ 9.093250][ T187] _printk+0xc7/0x100 [ 9.093253][ T187] ? snapshot_read.cold+0x21/0x21 [ 9.093256][ T187] ? do_raw_spin_lock+0x131/0x280 [ 9.093259][ T187] ? __rwlock_init+0x150/0x150 [ 9.093262][ T187] ? do_raw_spin_lock+0x131/0x280 [ 9.093265][ T187] do_exit.cold+0x82/0x9c [ 9.093269][ T187] ? exit_notify+0x890/0x890 [ 9.093271][ T187] ? __lock_release.isra.0+0x69/0x1a0 [ 9.093274][ T187] ? rcu_is_watching+0x16/0xd0 [ 9.093278][ T187] do_group_exit+0xb8/0x370 [ 9.093280][ T187] __x64_sys_exit_group+0x3c/0x50 [ 9.093282][ T187] x64_sys_call+0x1567/0x1570 [ 9.093284][ T187] do_syscall_64+0xff/0x530 [ 9.093288][ T187] ? exc_page_fault+0xee/0x100 [ 9.093291][ T187] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.093293][ T187] RIP: 0033:0x7fa4ade1e1b8 [ 9.093296][ T187] Code: Unable to access opcode bytes at 0x7fa4ade1e18e. [ 9.093297][ T187] RSP: 002b:00007fff42d63998 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7 [ 9.093299][ T187] RAX: ffffffffffffffda RBX: 00007fa4adf4ef88 RCX: 00007fa4ade1e1b8 [ 9.093300][ T187] RDX: 00007fa4adb68fc8 RSI: fffffffffffffeb8 RDI: 0000000000000000 [ 9.093301][ T187] RBP: 00007fff42d639f0 R08: 0000000000000000 R09: 0000000000008010 [ 9.093302][ T187] R10: 00007fff42d637b0 R11: 0000000000000246 R12: 0000000000000001 [ 9.093303][ T187] R13: 0000000000000000 R14: 00007fa4adf4d680 R15: 00007fa4adf4efa0 [ 9.093310][ T187] [ 9.278130][ T195] ip (195) used greatest stack depth: 23728 bytes left [ 10.991845][ T261] br1: port 1(vx1) entered blocking state [ 10.991919][ T261] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.991921][ T261] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 261, name: ip [ 10.991923][ T261] preempt_count: 1, expected: 0 [ 10.991924][ T261] RCU nest depth: 0, expected: 0 [ 10.991924][ T261] locks held by ip/261: 5, last CPU#2: [ 10.991927][ T261] #0: ffffffff9bcd2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 10.991938][ T261] #1: ffffffff9b569cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 10.991945][ T261] #2: ffffffff9b569d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 10.991948][ T261] #3: ffffffff9b489660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 10.991952][ T261] #4: ffffffff9b489560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 10.991956][ T261] irq event stamp: 14852 [ 10.991957][ T261] hardirqs last enabled at (14851): [] __down_trylock_console_sem+0x86/0xa0 [ 10.991960][ T261] hardirqs last disabled at (14852): [] console_emit_next_record+0x3d4/0x4c0 [ 10.991962][ T261] softirqs last enabled at (14766): [] __alloc_skb+0x4c2/0x5f0 [ 10.991965][ T261] softirqs last disabled at (14764): [] __alloc_skb+0x4c2/0x5f0 [ 10.991967][ T261] Preemption disabled at: [ 10.991968][ T261] [] vprintk_emit+0x31b/0x3e0 [ 10.991974][ T261] CPU: 2 UID: 0 PID: 261 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.991977][ T261] Tainted: [W]=WARN [ 10.991978][ T261] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.991980][ T261] Call Trace: [ 10.991981][ T261] [ 10.991983][ T261] dump_stack_lvl+0x6f/0xa0 [ 10.991989][ T261] ? vprintk_emit+0x31b/0x3e0 [ 10.991991][ T261] __might_resched.cold+0x1fe/0x2c1 [ 10.991996][ T261] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.992000][ T261] ? __kmalloc_noprof+0xdb/0x760 [ 10.992005][ T261] __kmalloc_noprof+0x443/0x760 [ 10.992007][ T261] ? alloc_buf.isra.0+0x4b/0x260 [ 10.992013][ T261] ? do_raw_spin_unlock+0x59/0x250 [ 10.992016][ T261] alloc_buf.isra.0+0x4b/0x260 [ 10.992019][ T261] put_chars+0x1e1/0x2f0 [ 10.992021][ T261] ? __send_to_port+0x420/0x420 [ 10.992026][ T261] ? validate_chain+0x34a/0xc20 [ 10.992029][ T261] hvc_console_print+0x292/0x780 [ 10.992032][ T261] ? mark_usage+0x61/0x170 [ 10.992034][ T261] ? __lock_acquire+0x518/0xc20 [ 10.992038][ T261] ? __lock_acquire+0x518/0xc20 [ 10.992041][ T261] ? hvc_write+0x3a0/0x3a0 [ 10.992043][ T261] ? console_emit_next_record+0x1df/0x4c0 [ 10.992047][ T261] ? rcu_is_watching+0x16/0xd0 [ 10.992050][ T261] ? lock_acquire+0x13c/0x160 [ 10.992054][ T261] console_emit_next_record+0x22f/0x4c0 [ 10.992058][ T261] ? devkmsg_read+0x4b0/0x4b0 [ 10.992060][ T261] ? console_flush_one_record+0x106/0x710 [ 10.992063][ T261] ? rcu_is_watching+0x16/0xd0 [ 10.992065][ T261] ? lock_acquire+0x13c/0x160 [ 10.992069][ T261] console_flush_one_record+0x46f/0x710 [ 10.992073][ T261] ? console_emit_next_record+0x4c0/0x4c0 [ 10.992074][ T261] ? __lock_acquire+0x518/0xc20 [ 10.992079][ T261] console_unlock+0xee/0x1f0 [ 10.992082][ T261] ? console_flush_one_record+0x710/0x710 [ 10.992084][ T261] ? rcu_is_watching+0x16/0xd0 [ 10.992086][ T261] ? lock_acquire+0x60/0x160 [ 10.992090][ T261] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.992091][ T261] ? vprintk_emit+0x320/0x3e0 [ 10.992095][ T261] vprintk_emit+0x37c/0x3e0 [ 10.992098][ T261] ? wake_up_klogd_work_func+0x90/0x90 [ 10.992101][ T261] ? __lock_release.isra.0+0x69/0x1a0 [ 10.992102][ T261] ? _raw_spin_unlock_irqrestore+0x40/0x80 [ 10.992107][ T261] ? mark_held_locks+0x40/0x70 [ 10.992110][ T261] _printk+0xc7/0x100 [ 10.992114][ T261] ? snapshot_read.cold+0x21/0x21 [ 10.992117][ T261] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 10.992130][ T261] ? do_setlink.isra.0+0xa31/0x2750 [ 10.992131][ T261] ? rtnl_newlink+0x9f1/0x11c0 [ 10.992132][ T261] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 10.992136][ T261] br_set_state+0x22f/0x430 [bridge] [ 10.992146][ T261] br_init_port+0xc4/0x200 [bridge] [ 10.992154][ T261] new_nbp+0x39c/0x580 [bridge] [ 10.992163][ T261] br_add_if+0x212/0x1320 [bridge] [ 10.992170][ T261] ? is_bpf_text_address+0x72/0x110 [ 10.992174][ T261] ? kernel_text_address+0x149/0x170 [ 10.992177][ T261] ? __kernel_text_address+0x12/0x30 [ 10.992180][ T261] do_set_master+0x357/0x580 [ 10.992185][ T261] do_setlink.isra.0+0xa31/0x2750 [ 10.992188][ T261] ? stack_trace_save+0x93/0xc0 [ 10.992191][ T261] ? rtnl_link_get_size+0x350/0x350 [ 10.992193][ T261] ? rcu_read_lock_any_held+0x66/0x90 [ 10.992195][ T261] ? stack_depot_save_flags+0x38e/0x790 [ 10.992199][ T261] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 10.992201][ T261] ? rcu_read_lock_any_held+0x3c/0x90 [ 10.992203][ T261] ? validate_chain+0x38b/0xc20 [ 10.992206][ T261] ? kasan_save_stack+0x3d/0x50 [ 10.992209][ T261] ? kasan_save_stack+0x2f/0x50 [ 10.992210][ T261] ? kasan_save_track+0x14/0x30 [ 10.992213][ T261] ? __lock_acquire+0x518/0xc20 [ 10.992215][ T261] ? netlink_seq_next+0x4e/0x60 [ 10.992218][ T261] ? ___sys_sendmsg+0xb0/0x1d0 [ 10.992222][ T261] ? lock_acquire.part.0+0xd4/0x280 [ 10.992224][ T261] ? rtnl_newlink+0x9a8/0x11c0 [ 10.992227][ T261] ? rcu_is_watching+0x16/0xd0 [ 10.992229][ T261] ? lock_acquire+0x13c/0x160 [ 10.992230][ T261] ? rcu_is_watching+0x16/0xd0 [ 10.992232][ T261] ? rcu_is_watching+0x16/0xd0 [ 10.992234][ T261] ? trace_contention_end+0xb3/0x180 [ 10.992238][ T261] ? __mutex_lock+0x1db/0x1ea0 [ 10.992240][ T261] ? __mutex_lock+0x9a3/0x1ea0 [ 10.992242][ T261] ? rtnl_newlink+0x9a8/0x11c0 [ 10.992245][ T261] ? ww_mutex_lock+0x160/0x160 [ 10.992247][ T261] ? nla_get_range_signed+0x3d0/0x3d0 [ 10.992252][ T261] ? __rtnl_newlink+0x3fa/0xa50 [ 10.992257][ T261] rtnl_newlink+0x9f1/0x11c0 [ 10.992262][ T261] ? rtnl_bridge_getlink+0x850/0x850 [ 10.992264][ T261] ? __lock_acquire+0x518/0xc20 [ 10.992268][ T261] ? lock_acquire.part.0+0xd4/0x280 [ 10.992270][ T261] ? find_held_lock+0x2b/0x80 [ 10.992272][ T261] ? rtnl_bridge_getlink+0x850/0x850 [ 10.992274][ T261] ? __lock_release.isra.0+0x69/0x1a0 [ 10.992277][ T261] ? rtnl_bridge_getlink+0x850/0x850 [ 10.992279][ T261] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 10.992282][ T261] ? rtnl_link_fill+0x920/0x920 [ 10.992283][ T261] ? __lock_acquire+0x518/0xc20 [ 10.992288][ T261] ? lock_acquire.part.0+0xd4/0x280 [ 10.992289][ T261] ? find_held_lock+0x2b/0x80 [ 10.992293][ T261] netlink_rcv_skb+0x14e/0x3a0 [ 10.992295][ T261] ? rtnl_link_fill+0x920/0x920 [ 10.992297][ T261] ? netlink_ack+0xcf0/0xcf0 [ 10.992303][ T261] ? netlink_deliver_tap+0xc5/0x330 [ 10.992305][ T261] ? netlink_deliver_tap+0x13c/0x330 [ 10.992309][ T261] netlink_unicast+0x486/0x750 [ 10.992312][ T261] ? netlink_attachskb+0x810/0x810 [ 10.992315][ T261] ? __lock_acquire+0x518/0xc20 [ 10.992319][ T261] netlink_sendmsg+0x735/0xc60 [ 10.992323][ T261] ? netlink_unicast+0x750/0x750 [ 10.992326][ T261] ? __might_fault+0x97/0x140 [ 10.992332][ T261] ____sys_sendmsg+0x415/0x880 [ 10.992334][ T261] ? copy_msghdr_from_user+0x279/0x420 [ 10.992336][ T261] ? get_timestamp.constprop.0+0x390/0x390 [ 10.992338][ T261] ? move_addr_to_kernel+0x40/0x40 [ 10.992345][ T261] ___sys_sendmsg+0x14e/0x1d0 [ 10.992348][ T261] ? copy_msghdr_from_user+0x420/0x420 [ 10.992365][ T261] __sys_sendmsg+0x12c/0x1d0 [ 10.992368][ T261] ? __sys_sendmsg_sock+0x20/0x20 [ 10.992374][ T261] ? rcu_is_watching+0x16/0xd0 [ 10.992377][ T261] do_syscall_64+0xff/0x530 [ 10.992385][ T261] ? exc_page_fault+0xee/0x100 [ 10.992389][ T261] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 10.992391][ T261] RIP: 0033:0x7f5d8f26454e [ 10.992394][ T261] Code: 4d 89 d8 e8 b4 bd 00 00 4c 8b 5d f8 41 8b 93 08 03 00 00 59 5e 48 83 f8 fc 74 11 c9 c3 0f 1f 80 00 00 00 00 48 8b 45 10 0f 05 c3 83 e2 39 83 fa 08 75 e7 e8 03 ff ff ff 0f 1f 00 f3 0f 1e fa [ 10.992396][ T261] RSP: 002b:00007ffdb0d32cd0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 10.992399][ T261] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f5d8f26454e [ 10.992400][ T261] RDX: 0000000000000000 RSI: 00007ffdb0d32d80 RDI: 0000000000000005 [ 10.992401][ T261] RBP: 00007ffdb0d32ce0 R08: 0000000000000000 R09: 0000000000000000 [ 10.992402][ T261] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91553e [ 10.992403][ T261] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffdb0d33440 [ 10.992409][ T261] [ 11.045087][ T261] br1: port 1(vx1) entered disabled state [ 11.045348][ T261] vx1: entered allmulticast mode [ 11.054162][ T261] vx1: entered promiscuous mode [ 11.054923][ T261] br1: port 1(vx1) entered blocking state [ 11.055302][ T261] br1: port 1(vx1) entered forwarding state [ 11.061518][ T261] ip (261) used greatest stack depth: 23336 bytes left [ 11.079440][ T262] br1: port 2(veth1) entered blocking state [ 11.079772][ T262] br1: port 2(veth1) entered disabled state [ 11.080148][ T262] veth1: entered allmulticast mode [ 11.081686][ T262] veth1: entered promiscuous mode [ 11.111421][ T36] br1: port 2(veth1) entered blocking state [ 11.111833][ T36] br1: port 2(veth1) entered forwarding state [ 11.170187][ T265] br1: port 3(veth2) entered blocking state [ 11.170543][ T265] br1: port 3(veth2) entered disabled state [ 11.170897][ T265] veth2: entered allmulticast mode [ 11.172469][ T265] veth2: entered promiscuous mode [ 11.206781][ T36] br1: port 3(veth2) entered blocking state [ 11.207092][ T36] br1: port 3(veth2) entered forwarding state [ 12.296086][ T310] br2: port 1(w1) entered blocking state [ 12.296101][ T310] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.296104][ T310] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 310, name: ip [ 12.296105][ T310] preempt_count: 1, expected: 0 [ 12.296106][ T310] RCU nest depth: 0, expected: 0 [ 12.296107][ T310] locks held by ip/310: 5, last CPU#3: [ 12.296109][ T310] #0: ffffffff9bcd2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 12.296121][ T310] #1: ffffffff9b569cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 12.296127][ T310] #2: ffffffff9b569d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 12.296131][ T310] #3: ffffffff9b489660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 12.296135][ T310] #4: ffffffff9b489560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 12.296139][ T310] irq event stamp: 19804 [ 12.296140][ T310] hardirqs last enabled at (19803): [] __down_trylock_console_sem+0x86/0xa0 [ 12.296143][ T310] hardirqs last disabled at (19804): [] console_emit_next_record+0x3d4/0x4c0 [ 12.296145][ T310] softirqs last enabled at (19742): [] __alloc_skb+0x4c2/0x5f0 [ 12.296148][ T310] softirqs last disabled at (19740): [] __alloc_skb+0x4c2/0x5f0 [ 12.296150][ T310] Preemption disabled at: [ 12.296151][ T310] [] vprintk_emit+0x31b/0x3e0 [ 12.296157][ T310] CPU: 3 UID: 0 PID: 310 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.296160][ T310] Tainted: [W]=WARN [ 12.296161][ T310] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.296163][ T310] Call Trace: [ 12.296165][ T310] [ 12.296166][ T310] dump_stack_lvl+0x6f/0xa0 [ 12.296172][ T310] ? vprintk_emit+0x31b/0x3e0 [ 12.296174][ T310] __might_resched.cold+0x1fe/0x2c1 [ 12.296179][ T310] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.296183][ T310] ? __kmalloc_noprof+0xdb/0x760 [ 12.296189][ T310] __kmalloc_noprof+0x443/0x760 [ 12.296191][ T310] ? alloc_buf.isra.0+0x4b/0x260 [ 12.296196][ T310] ? do_raw_spin_unlock+0x59/0x250 [ 12.296199][ T310] alloc_buf.isra.0+0x4b/0x260 [ 12.296202][ T310] put_chars+0x1e1/0x2f0 [ 12.296204][ T310] ? prb_final_commit+0x50/0x50 [ 12.296206][ T310] ? __send_to_port+0x420/0x420 [ 12.296209][ T310] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 12.296214][ T310] ? rcu_read_lock_any_held+0x3c/0x90 [ 12.296216][ T310] ? validate_chain+0x38b/0xc20 [ 12.296220][ T310] hvc_console_print+0x292/0x780 [ 12.296223][ T310] ? mark_usage+0x61/0x170 [ 12.296225][ T310] ? __lock_acquire+0x518/0xc20 [ 12.296226][ T310] ? __lock_acquire+0x518/0xc20 [ 12.296230][ T310] ? hvc_write+0x3a0/0x3a0 [ 12.296232][ T310] ? console_emit_next_record+0x1df/0x4c0 [ 12.296235][ T310] ? rcu_is_watching+0x16/0xd0 [ 12.296238][ T310] ? lock_acquire+0x13c/0x160 [ 12.296242][ T310] console_emit_next_record+0x22f/0x4c0 [ 12.296246][ T310] ? devkmsg_read+0x4b0/0x4b0 [ 12.296248][ T310] ? console_flush_one_record+0x106/0x710 [ 12.296251][ T310] ? rcu_is_watching+0x16/0xd0 [ 12.296253][ T310] ? lock_acquire+0x13c/0x160 [ 12.296257][ T310] console_flush_one_record+0x46f/0x710 [ 12.296261][ T310] ? console_emit_next_record+0x4c0/0x4c0 [ 12.296263][ T310] ? __lock_acquire+0x518/0xc20 [ 12.296268][ T310] console_unlock+0xee/0x1f0 [ 12.296271][ T310] ? console_flush_one_record+0x710/0x710 [ 12.296273][ T310] ? rcu_is_watching+0x16/0xd0 [ 12.296275][ T310] ? lock_acquire+0x60/0x160 [ 12.296279][ T310] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.296281][ T310] ? vprintk_emit+0x320/0x3e0 [ 12.296284][ T310] vprintk_emit+0x37c/0x3e0 [ 12.296288][ T310] ? wake_up_klogd_work_func+0x90/0x90 [ 12.296291][ T310] ? __lock_release.isra.0+0x69/0x1a0 [ 12.296294][ T310] ? mark_held_locks+0x40/0x70 [ 12.296297][ T310] _printk+0xc7/0x100 [ 12.296301][ T310] ? snapshot_read.cold+0x21/0x21 [ 12.296305][ T310] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 12.296318][ T310] ? do_setlink.isra.0+0xa31/0x2750 [ 12.296319][ T310] ? rtnl_newlink+0x9f1/0x11c0 [ 12.296320][ T310] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 12.296325][ T310] br_set_state+0x22f/0x430 [bridge] [ 12.296336][ T310] br_init_port+0xc4/0x200 [bridge] [ 12.296344][ T310] new_nbp+0x39c/0x580 [bridge] [ 12.296353][ T310] br_add_if+0x212/0x1320 [bridge] [ 12.296360][ T310] ? is_bpf_text_address+0x72/0x110 [ 12.296364][ T310] ? kernel_text_address+0x149/0x170 [ 12.296367][ T310] ? __kernel_text_address+0x12/0x30 [ 12.296371][ T310] do_set_master+0x357/0x580 [ 12.296376][ T310] do_setlink.isra.0+0xa31/0x2750 [ 12.296379][ T310] ? stack_trace_save+0x93/0xc0 [ 12.296382][ T310] ? rtnl_link_get_size+0x350/0x350 [ 12.296384][ T310] ? rcu_read_lock_any_held+0x66/0x90 [ 12.296392][ T310] ? stack_depot_save_flags+0x38e/0x790 [ 12.296396][ T310] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 12.296399][ T310] ? rcu_read_lock_any_held+0x3c/0x90 [ 12.296400][ T310] ? validate_chain+0x38b/0xc20 [ 12.296403][ T310] ? kasan_save_stack+0x3d/0x50 [ 12.296406][ T310] ? kasan_save_stack+0x2f/0x50 [ 12.296407][ T310] ? kasan_save_track+0x14/0x30 [ 12.296410][ T310] ? __lock_acquire+0x518/0xc20 [ 12.296412][ T310] ? netlink_seq_next+0x4e/0x60 [ 12.296415][ T310] ? ___sys_sendmsg+0xb0/0x1d0 [ 12.296419][ T310] ? lock_acquire.part.0+0xd4/0x280 [ 12.296421][ T310] ? rtnl_newlink+0x9a8/0x11c0 [ 12.296424][ T310] ? rcu_is_watching+0x16/0xd0 [ 12.296426][ T310] ? lock_acquire+0x13c/0x160 [ 12.296428][ T310] ? rcu_is_watching+0x16/0xd0 [ 12.296429][ T310] ? rcu_is_watching+0x16/0xd0 [ 12.296432][ T310] ? trace_contention_end+0xb3/0x180 [ 12.296435][ T310] ? __mutex_lock+0x1db/0x1ea0 [ 12.296439][ T310] ? __mutex_lock+0x9a3/0x1ea0 [ 12.296441][ T310] ? rtnl_newlink+0x9a8/0x11c0 [ 12.296444][ T310] ? ww_mutex_lock+0x160/0x160 [ 12.296446][ T310] ? nla_get_range_signed+0x3d0/0x3d0 [ 12.296451][ T310] ? __rtnl_newlink+0x3fa/0xa50 [ 12.296456][ T310] rtnl_newlink+0x9f1/0x11c0 [ 12.296461][ T310] ? rtnl_bridge_getlink+0x850/0x850 [ 12.296463][ T310] ? __lock_acquire+0x518/0xc20 [ 12.296467][ T310] ? lock_acquire.part.0+0xd4/0x280 [ 12.296469][ T310] ? find_held_lock+0x2b/0x80 [ 12.296471][ T310] ? rtnl_bridge_getlink+0x850/0x850 [ 12.296473][ T310] ? __lock_release.isra.0+0x69/0x1a0 [ 12.296477][ T310] ? rtnl_bridge_getlink+0x850/0x850 [ 12.296479][ T310] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 12.296482][ T310] ? rtnl_link_fill+0x920/0x920 [ 12.296483][ T310] ? __lock_acquire+0x518/0xc20 [ 12.296487][ T310] ? lock_acquire.part.0+0xd4/0x280 [ 12.296489][ T310] ? find_held_lock+0x2b/0x80 [ 12.296493][ T310] netlink_rcv_skb+0x14e/0x3a0 [ 12.296494][ T310] ? rtnl_link_fill+0x920/0x920 [ 12.296497][ T310] ? netlink_ack+0xcf0/0xcf0 [ 12.296503][ T310] ? netlink_deliver_tap+0xc5/0x330 [ 12.296505][ T310] ? netlink_deliver_tap+0x13c/0x330 [ 12.296509][ T310] netlink_unicast+0x486/0x750 [ 12.296513][ T310] ? netlink_attachskb+0x810/0x810 [ 12.296515][ T310] ? __lock_acquire+0x518/0xc20 [ 12.296519][ T310] netlink_sendmsg+0x735/0xc60 [ 12.296523][ T310] ? netlink_unicast+0x750/0x750 [ 12.296527][ T310] ? __might_fault+0x97/0x140 [ 12.296532][ T310] ____sys_sendmsg+0x415/0x880 [ 12.296534][ T310] ? copy_msghdr_from_user+0x279/0x420 [ 12.296537][ T310] ? get_timestamp.constprop.0+0x390/0x390 [ 12.296538][ T310] ? move_addr_to_kernel+0x40/0x40 [ 12.296545][ T310] ___sys_sendmsg+0x14e/0x1d0 [ 12.296548][ T310] ? copy_msghdr_from_user+0x420/0x420 [ 12.296563][ T310] __sys_sendmsg+0x12c/0x1d0 [ 12.296566][ T310] ? __sys_sendmsg_sock+0x20/0x20 [ 12.296573][ T310] ? rcu_is_watching+0x16/0xd0 [ 12.296576][ T310] do_syscall_64+0xff/0x530 [ 12.296578][ T310] ? exc_page_fault+0xee/0x100 [ 12.296582][ T310] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 12.296584][ T310] RIP: 0033:0x7ff0c81b954e [ 12.296588][ T310] 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.296590][ T310] RSP: 002b:00007ffd5a58d110 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 12.296592][ T310] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007ff0c81b954e [ 12.296594][ T310] RDX: 0000000000000000 RSI: 00007ffd5a58d1c0 RDI: 0000000000000005 [ 12.296595][ T310] RBP: 00007ffd5a58d120 R08: 0000000000000000 R09: 0000000000000000 [ 12.296596][ T310] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a91553f [ 12.296596][ T310] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffd5a58d880 [ 12.296603][ T310] [ 12.334475][ T310] br2: port 1(w1) entered disabled state [ 12.334832][ T310] w1: entered allmulticast mode [ 12.336508][ T310] w1: entered promiscuous mode [ 12.500351][ T316] br2: port 2(vx2) entered blocking state [ 12.500615][ T316] br2: port 2(vx2) entered disabled state [ 12.500859][ T316] vx2: entered allmulticast mode [ 12.502361][ T316] vx2: entered promiscuous mode [ 12.502981][ T316] br2: port 2(vx2) entered blocking state [ 12.503221][ T316] br2: port 2(vx2) entered forwarding state [ 12.708228][ T36] br2: port 1(w1) entered blocking state [ 12.708539][ T36] br2: port 1(w1) entered forwarding state [ 13.247128][ T348] br2: port 1(w1) entered blocking state [ 13.247454][ T348] br2: port 1(w1) entered disabled state [ 13.247743][ T348] w1: entered allmulticast mode [ 13.249931][ T348] w1: entered promiscuous mode [ 13.412851][ T354] br2: port 2(vx2) entered blocking state [ 13.412861][ T354] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 13.412863][ T354] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 354, name: ip [ 13.412865][ T354] preempt_count: 1, expected: 0 [ 13.412866][ T354] RCU nest depth: 0, expected: 0 [ 13.412866][ T354] locks held by ip/354: 5, last CPU#0: [ 13.412868][ T354] #0: ffffffff9bcd2c40 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x9a8/0x11c0 [ 13.412880][ T354] #1: ffffffff9b569cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 13.412887][ T354] #2: ffffffff9b569d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 13.412890][ T354] #3: ffffffff9b489660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 13.412894][ T354] #4: ffffffff9b489560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 13.412898][ T354] irq event stamp: 12016 [ 13.412899][ T354] hardirqs last enabled at (12015): [] __down_trylock_console_sem+0x86/0xa0 [ 13.412901][ T354] hardirqs last disabled at (12016): [] console_emit_next_record+0x3d4/0x4c0 [ 13.412903][ T354] softirqs last enabled at (11958): [] __alloc_skb+0x4c2/0x5f0 [ 13.412906][ T354] softirqs last disabled at (11956): [] __alloc_skb+0x4c2/0x5f0 [ 13.412909][ T354] Preemption disabled at: [ 13.412910][ T354] [] vprintk_emit+0x31b/0x3e0 [ 13.412915][ T354] CPU: 0 UID: 0 PID: 354 Comm: ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 13.412918][ T354] Tainted: [W]=WARN [ 13.412919][ T354] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 13.412921][ T354] Call Trace: [ 13.412923][ T354] [ 13.412924][ T354] dump_stack_lvl+0x6f/0xa0 [ 13.412930][ T354] ? vprintk_emit+0x31b/0x3e0 [ 13.412932][ T354] __might_resched.cold+0x1fe/0x2c1 [ 13.412936][ T354] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 13.412941][ T354] ? __kmalloc_noprof+0xdb/0x760 [ 13.412946][ T354] __kmalloc_noprof+0x443/0x760 [ 13.412949][ T354] ? alloc_buf.isra.0+0x4b/0x260 [ 13.412955][ T354] ? do_raw_spin_unlock+0x59/0x250 [ 13.412957][ T354] alloc_buf.isra.0+0x4b/0x260 [ 13.412960][ T354] put_chars+0x1e1/0x2f0 [ 13.412962][ T354] ? prb_final_commit+0x50/0x50 [ 13.412964][ T354] ? __send_to_port+0x420/0x420 [ 13.412967][ T354] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.412971][ T354] ? rcu_read_lock_any_held+0x3c/0x90 [ 13.412974][ T354] ? validate_chain+0x38b/0xc20 [ 13.412977][ T354] hvc_console_print+0x292/0x780 [ 13.412980][ T354] ? mark_usage+0x61/0x170 [ 13.412982][ T354] ? __lock_acquire+0x518/0xc20 [ 13.412983][ T354] ? __lock_acquire+0x518/0xc20 [ 13.412987][ T354] ? hvc_write+0x3a0/0x3a0 [ 13.412990][ T354] ? console_emit_next_record+0x1df/0x4c0 [ 13.412993][ T354] ? rcu_is_watching+0x16/0xd0 [ 13.412995][ T354] ? lock_acquire+0x13c/0x160 [ 13.412999][ T354] console_emit_next_record+0x22f/0x4c0 [ 13.413002][ T354] ? devkmsg_read+0x4b0/0x4b0 [ 13.413004][ T354] ? console_flush_one_record+0x106/0x710 [ 13.413007][ T354] ? rcu_is_watching+0x16/0xd0 [ 13.413010][ T354] ? lock_acquire+0x13c/0x160 [ 13.413013][ T354] console_flush_one_record+0x46f/0x710 [ 13.413017][ T354] ? console_emit_next_record+0x4c0/0x4c0 [ 13.413019][ T354] ? __lock_acquire+0x518/0xc20 [ 13.413024][ T354] console_unlock+0xee/0x1f0 [ 13.413027][ T354] ? console_flush_one_record+0x710/0x710 [ 13.413029][ T354] ? rcu_is_watching+0x16/0xd0 [ 13.413031][ T354] ? lock_acquire+0x60/0x160 [ 13.413037][ T354] ? __down_trylock_console_sem+0x5e/0xa0 [ 13.413038][ T354] ? vprintk_emit+0x320/0x3e0 [ 13.413042][ T354] vprintk_emit+0x37c/0x3e0 [ 13.413045][ T354] ? wake_up_klogd_work_func+0x90/0x90 [ 13.413048][ T354] ? __lock_release.isra.0+0x69/0x1a0 [ 13.413050][ T354] ? mark_held_locks+0x40/0x70 [ 13.413054][ T354] _printk+0xc7/0x100 [ 13.413057][ T354] ? snapshot_read.cold+0x21/0x21 [ 13.413061][ T354] ? br_multicast_flood+0xab0/0xab0 [bridge] [ 13.413073][ T354] ? do_setlink.isra.0+0xa31/0x2750 [ 13.413075][ T354] ? rtnl_newlink+0x9f1/0x11c0 [ 13.413076][ T354] ? rtnetlink_rcv_msg+0x6fd/0xbd0 [ 13.413080][ T354] br_set_state+0x22f/0x430 [bridge] [ 13.413090][ T354] br_init_port+0xc4/0x200 [bridge] [ 13.413098][ T354] new_nbp+0x39c/0x580 [bridge] [ 13.413107][ T354] br_add_if+0x212/0x1320 [bridge] [ 13.413114][ T354] ? is_bpf_text_address+0x72/0x110 [ 13.413118][ T354] ? kernel_text_address+0x149/0x170 [ 13.413121][ T354] ? __kernel_text_address+0x12/0x30 [ 13.413125][ T354] do_set_master+0x357/0x580 [ 13.413129][ T354] do_setlink.isra.0+0xa31/0x2750 [ 13.413132][ T354] ? stack_trace_save+0x93/0xc0 [ 13.413136][ T354] ? rtnl_link_get_size+0x350/0x350 [ 13.413137][ T354] ? rcu_read_lock_any_held+0x66/0x90 [ 13.413139][ T354] ? stack_depot_save_flags+0x38e/0x790 [ 13.413142][ T354] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 13.413145][ T354] ? rcu_read_lock_any_held+0x3c/0x90 [ 13.413146][ T354] ? validate_chain+0x38b/0xc20 [ 13.413149][ T354] ? kasan_save_stack+0x3d/0x50 [ 13.413152][ T354] ? kasan_save_stack+0x2f/0x50 [ 13.413153][ T354] ? kasan_save_track+0x14/0x30 [ 13.413156][ T354] ? __lock_acquire+0x518/0xc20 [ 13.413158][ T354] ? netlink_seq_next+0x4e/0x60 [ 13.413161][ T354] ? ___sys_sendmsg+0xb0/0x1d0 [ 13.413165][ T354] ? lock_acquire.part.0+0xd4/0x280 [ 13.413167][ T354] ? rtnl_newlink+0x9a8/0x11c0 [ 13.413170][ T354] ? rcu_is_watching+0x16/0xd0 [ 13.413172][ T354] ? lock_acquire+0x13c/0x160 [ 13.413174][ T354] ? rcu_is_watching+0x16/0xd0 [ 13.413175][ T354] ? rcu_is_watching+0x16/0xd0 [ 13.413177][ T354] ? trace_contention_end+0xb3/0x180 [ 13.413181][ T354] ? __mutex_lock+0x1db/0x1ea0 [ 13.413184][ T354] ? __mutex_lock+0x9a3/0x1ea0 [ 13.413186][ T354] ? rtnl_newlink+0x9a8/0x11c0 [ 13.413189][ T354] ? ww_mutex_lock+0x160/0x160 [ 13.413191][ T354] ? nla_get_range_signed+0x3d0/0x3d0 [ 13.413196][ T354] ? __rtnl_newlink+0x3fa/0xa50 [ 13.413201][ T354] rtnl_newlink+0x9f1/0x11c0 [ 13.413206][ T354] ? rtnl_bridge_getlink+0x850/0x850 [ 13.413208][ T354] ? __lock_acquire+0x518/0xc20 [ 13.413212][ T354] ? lock_acquire.part.0+0xd4/0x280 [ 13.413214][ T354] ? find_held_lock+0x2b/0x80 [ 13.413216][ T354] ? rtnl_bridge_getlink+0x850/0x850 [ 13.413217][ T354] ? __lock_release.isra.0+0x69/0x1a0 [ 13.413221][ T354] ? rtnl_bridge_getlink+0x850/0x850 [ 13.413223][ T354] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 13.413226][ T354] ? rtnl_link_fill+0x920/0x920 [ 13.413227][ T354] ? __lock_acquire+0x518/0xc20 [ 13.413232][ T354] ? lock_acquire.part.0+0xd4/0x280 [ 13.413233][ T354] ? find_held_lock+0x2b/0x80 [ 13.413237][ T354] netlink_rcv_skb+0x14e/0x3a0 [ 13.413238][ T354] ? rtnl_link_fill+0x920/0x920 [ 13.413241][ T354] ? netlink_ack+0xcf0/0xcf0 [ 13.413247][ T354] ? netlink_deliver_tap+0xc5/0x330 [ 13.413248][ T354] ? netlink_deliver_tap+0x13c/0x330 [ 13.413253][ T354] netlink_unicast+0x486/0x750 [ 13.413256][ T354] ? netlink_attachskb+0x810/0x810 [ 13.413259][ T354] ? __lock_acquire+0x518/0xc20 [ 13.413263][ T354] netlink_sendmsg+0x735/0xc60 [ 13.413266][ T354] ? netlink_unicast+0x750/0x750 [ 13.413270][ T354] ? __might_fault+0x97/0x140 [ 13.413275][ T354] ____sys_sendmsg+0x415/0x880 [ 13.413277][ T354] ? copy_msghdr_from_user+0x279/0x420 [ 13.413280][ T354] ? get_timestamp.constprop.0+0x390/0x390 [ 13.413281][ T354] ? move_addr_to_kernel+0x40/0x40 [ 13.413288][ T354] ___sys_sendmsg+0x14e/0x1d0 [ 13.413291][ T354] ? copy_msghdr_from_user+0x420/0x420 [ 13.413306][ T354] __sys_sendmsg+0x12c/0x1d0 [ 13.413309][ T354] ? __sys_sendmsg_sock+0x20/0x20 [ 13.413315][ T354] ? rcu_is_watching+0x16/0xd0 [ 13.413318][ T354] do_syscall_64+0xff/0x530 [ 13.413320][ T354] ? exc_page_fault+0xee/0x100 [ 13.413323][ T354] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 13.413325][ T354] RIP: 0033:0x7f4f56b6d54e [ 13.413329][ T354] 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 [ 13.413330][ T354] RSP: 002b:00007ffc1b532780 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 13.413333][ T354] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f4f56b6d54e [ 13.413334][ T354] RDX: 0000000000000000 RSI: 00007ffc1b532830 RDI: 0000000000000005 [ 13.413335][ T354] RBP: 00007ffc1b532790 R08: 0000000000000000 R09: 0000000000000000 [ 13.413336][ T354] R10: 0000000000000000 R11: 0000000000000202 R12: 000000006a915540 [ 13.413337][ T354] R13: 00000000004a1620 R14: 0000000000000000 R15: 00007ffc1b532ef0 [ 13.413343][ T354] [ 13.452117][ T354] br2: port 2(vx2) entered disabled state [ 13.452447][ T354] vx2: entered allmulticast mode [ 13.454014][ T354] vx2: entered promiscuous mode [ 13.455359][ T354] br2: port 2(vx2) entered blocking state [ 13.455633][ T354] br2: port 2(vx2) entered forwarding state [ 13.652602][ T38] br2: port 1(w1) entered blocking state [ 13.652869][ T38] br2: port 1(w1) entered forwarding state [ 20.689779][ T436] GACT probability NOT on [ 20.689794][ T436] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 20.689796][ T436] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 436, name: modprobe [ 20.689798][ T436] preempt_count: 1, expected: 0 [ 20.689798][ T436] RCU nest depth: 0, expected: 0 [ 20.689800][ T436] locks held by modprobe/436: 4, last CPU#0: [ 20.689801][ T436] #0: ffffffff9b569cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 20.689814][ T436] #1: ffffffff9b569d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 20.689818][ T436] #2: ffffffff9b489660 (console_owner){....}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 20.689822][ T436] #3: ffffffff9b489560 (printk_legacy_map-wait-type-override){....}-{3:3}, at: console_emit_next_record+0x1df/0x4c0 [ 20.689826][ T436] irq event stamp: 24024 [ 20.689827][ T436] hardirqs last enabled at (24023): [] __down_trylock_console_sem+0x86/0xa0 [ 20.689829][ T436] hardirqs last disabled at (24024): [] console_emit_next_record+0x3d4/0x4c0 [ 20.689831][ T436] softirqs last enabled at (23324): [] handle_softirqs+0x67c/0x900 [ 20.689834][ T436] softirqs last disabled at (22151): [] __irq_exit_rcu+0x145/0x1c0 [ 20.689837][ T436] Preemption disabled at: [ 20.689837][ T436] [] vprintk_emit+0x31b/0x3e0 [ 20.689843][ T436] CPU: 0 UID: 0 PID: 436 Comm: modprobe Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 20.689847][ T436] Tainted: [W]=WARN [ 20.689848][ T436] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 20.689849][ T436] Call Trace: [ 20.689851][ T436] [ 20.689853][ T436] dump_stack_lvl+0x6f/0xa0 [ 20.689858][ T436] ? vprintk_emit+0x31b/0x3e0 [ 20.689861][ T436] __might_resched.cold+0x1fe/0x2c1 [ 20.689866][ T436] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 20.689870][ T436] ? __kmalloc_noprof+0xdb/0x760 [ 20.689875][ T436] __kmalloc_noprof+0x443/0x760 [ 20.689877][ T436] ? alloc_buf.isra.0+0x4b/0x260 [ 20.689883][ T436] ? do_raw_spin_unlock+0x59/0x250 [ 20.689886][ T436] alloc_buf.isra.0+0x4b/0x260 [ 20.689889][ T436] put_chars+0x1e1/0x2f0 [ 20.689891][ T436] ? desc_read_finalized_seq+0x79/0x120 [ 20.689893][ T436] ? __send_to_port+0x420/0x420 [ 20.689896][ T436] ? rcu_read_lock_any_held+0x3c/0x90 [ 20.689899][ T436] ? validate_chain+0x38b/0xc20 [ 20.689904][ T436] hvc_console_print+0x292/0x780 [ 20.689906][ T436] ? __lock_acquire+0x518/0xc20 [ 20.689908][ T436] ? __lock_acquire+0x518/0xc20 [ 20.689913][ T436] ? hvc_write+0x3a0/0x3a0 [ 20.689914][ T436] ? console_emit_next_record+0x1df/0x4c0 [ 20.689917][ T436] ? rcu_is_watching+0x16/0xd0 [ 20.689920][ T436] ? lock_acquire+0x13c/0x160 [ 20.689924][ T436] console_emit_next_record+0x22f/0x4c0 [ 20.689928][ T436] ? devkmsg_read+0x4b0/0x4b0 [ 20.689930][ T436] ? console_flush_one_record+0x106/0x710 [ 20.689933][ T436] ? rcu_is_watching+0x16/0xd0 [ 20.689935][ T436] ? lock_acquire+0x13c/0x160 [ 20.689939][ T436] console_flush_one_record+0x46f/0x710 [ 20.689943][ T436] ? console_emit_next_record+0x4c0/0x4c0 [ 20.689945][ T436] ? __lock_acquire+0x518/0xc20 [ 20.689951][ T436] console_unlock+0xee/0x1f0 [ 20.689953][ T436] ? console_flush_one_record+0x710/0x710 [ 20.689955][ T436] ? rcu_is_watching+0x16/0xd0 [ 20.689957][ T436] ? lock_acquire+0x60/0x160 [ 20.689961][ T436] ? __down_trylock_console_sem+0x5e/0xa0 [ 20.689963][ T436] ? vprintk_emit+0x320/0x3e0 [ 20.689966][ T436] vprintk_emit+0x37c/0x3e0 [ 20.689970][ T436] ? wake_up_klogd_work_func+0x90/0x90 [ 20.689976][ T436] ? llc_exit+0x3e0/0x3e0 [llc] [ 20.689979][ T436] _printk+0xc7/0x100 [ 20.689982][ T436] ? snapshot_read.cold+0x21/0x21 [ 20.689988][ T436] ? do_one_initcall+0x113/0x4f0 [ 20.689991][ T436] gact_init_module+0x15/0x1000 [act_gact] [ 20.689994][ T436] do_one_initcall+0x124/0x4f0 [ 20.689997][ T436] ? trace_event_raw_event_initcall_level+0x280/0x280 [ 20.690001][ T436] ? do_init_module+0xae/0x8a0 [ 20.690004][ T436] ? kasan_unpoison+0x44/0x70 [ 20.690009][ T436] do_init_module+0x2ad/0x8a0 [ 20.690013][ T436] ? lockdep_hardirqs_on+0x91/0x130 [ 20.690017][ T436] ? free_module+0x470/0x470 [ 20.690020][ T436] ? kfree+0x123/0x590 [ 20.690024][ T436] load_module+0x1424/0x1bb0 [ 20.690031][ T436] ? layout_and_allocate.isra.0+0x680/0x680 [ 20.690038][ T436] ? __ia32_sys_fsconfig+0x180/0x180 [ 20.690041][ T436] ? ovl_real_file+0x172/0x1e0 [ 20.690045][ T436] ? backing_file_read_iter+0x541/0x790 [ 20.690048][ T436] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 20.690052][ T436] init_module_from_file+0x11f/0x1a0 [ 20.690055][ T436] ? __do_sys_init_module+0x220/0x220 [ 20.690063][ T436] ? __rwlock_init+0x150/0x150 [ 20.690066][ T436] ? do_raw_spin_unlock+0x59/0x250 [ 20.690069][ T436] idempotent_init_module+0x37b/0x7c0 [ 20.690073][ T436] ? init_module_from_file+0x1a0/0x1a0 [ 20.690079][ T436] ? rcu_is_watching+0x16/0xd0 [ 20.690081][ T436] ? cap_capable+0x1d7/0x3d0 [ 20.690088][ T436] __x64_sys_finit_module+0xdf/0x190 [ 20.690090][ T436] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 20.690093][ T436] do_syscall_64+0xff/0x530 [ 20.690095][ T436] ? exc_page_fault+0xee/0x100 [ 20.690098][ T436] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 20.690100][ T436] RIP: 0033:0x7f6054e7237d [ 20.690104][ T436] 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 [ 20.690105][ T436] RSP: 002b:00007ffcde5e7358 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 20.690108][ T436] RAX: ffffffffffffffda RBX: 000055e6f73f7810 RCX: 00007f6054e7237d [ 20.690110][ T436] RDX: 0000000000000000 RSI: 000055e6bdc34ffd RDI: 0000000000000000 [ 20.690110][ T436] RBP: 00007ffcde5e73f0 R08: 0000000000000000 R09: 0000000000000000 [ 20.690111][ T436] R10: 0000000000000000 R11: 0000000000000246 R12: 000055e6bdc34ffd [ 20.690112][ T436] R13: 000055e6f73f7960 R14: 0000000000040000 R15: 0000000000000000 [ 20.690119][ T436] [ 26.499075][ C0] [ 26.499092][ C0] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 26.499094][ C0] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/0 [ 26.499096][ C0] preempt_count: 104, expected: 0 [ 26.499097][ C0] RCU nest depth: 0, expected: 0 [ 26.499098][ C0] INFO: lockdep is turned off. [ 26.499098][ C0] irq event stamp: 916652 [ 26.499099][ C0] hardirqs last enabled at (916652): [] _raw_spin_unlock_irq+0x28/0x50 [ 26.499108][ C0] hardirqs last disabled at (916651): [] _raw_spin_lock_irq+0x4a/0x50 [ 26.499110][ C0] softirqs last enabled at (916642): [] handle_softirqs+0x67c/0x900 [ 26.499114][ C0] softirqs last disabled at (916649): [] __irq_exit_rcu+0x145/0x1c0 [ 26.499116][ C0] Preemption disabled at: [ 26.499117][ C0] [] schedule_preempt_disabled+0x22/0x30 [ 26.499122][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.499125][ C0] Tainted: [W]=WARN [ 26.499126][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.499128][ C0] Call Trace: [ 26.499129][ C0] [ 26.499131][ C0] dump_stack_lvl+0x6f/0xa0 [ 26.499136][ C0] ? schedule_preempt_disabled+0x22/0x30 [ 26.499138][ C0] __might_resched.cold+0x1fe/0x2c1 [ 26.499142][ C0] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 26.499145][ C0] ? __asan_memcpy+0x3c/0x60 [ 26.499148][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.499152][ C0] __kmalloc_noprof+0x443/0x760 [ 26.499155][ C0] ? __rwlock_init+0x150/0x150 [ 26.499158][ C0] ? alloc_buf.isra.0+0x4b/0x260 [ 26.499161][ C0] ? do_raw_spin_unlock+0x59/0x250 [ 26.499163][ C0] alloc_buf.isra.0+0x4b/0x260 [ 26.499165][ C0] put_chars+0x1e1/0x2f0 [ 26.499167][ C0] ? __send_to_port+0x420/0x420 [ 26.499169][ C0] ? console_prepend_replay+0x20/0x20 [ 26.499172][ C0] hvc_console_print+0x292/0x780 [ 26.499176][ C0] ? hvc_write+0x3a0/0x3a0 [ 26.499177][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.499179][ C0] ? lock_acquire+0x13c/0x160 [ 26.499181][ C0] console_emit_next_record+0x22f/0x4c0 [ 26.499183][ C0] ? devkmsg_read+0x4b0/0x4b0 [ 26.499185][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.499187][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.499189][ C0] ? lock_acquire+0x13c/0x160 [ 26.499191][ C0] ? console_flush_one_record+0x111/0x710 [ 26.499192][ C0] console_flush_one_record+0x46f/0x710 [ 26.499195][ C0] ? console_emit_next_record+0x4c0/0x4c0 [ 26.499197][ C0] console_unlock+0xee/0x1f0 [ 26.499199][ C0] ? lock_acquire+0x13c/0x160 [ 26.499201][ C0] ? console_flush_one_record+0x710/0x710 [ 26.499203][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.499204][ C0] ? lock_release+0x184/0x1f0 [ 26.499206][ C0] ? lock_acquire+0x60/0x160 [ 26.499208][ C0] ? __down_trylock_console_sem+0x5e/0xa0 [ 26.499210][ C0] ? vprintk_emit+0x320/0x3e0 [ 26.499212][ C0] vprintk_emit+0x37c/0x3e0 [ 26.499214][ C0] ? wake_up_klogd_work_func+0x90/0x90 [ 26.499216][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.499218][ C0] ? lock_release+0x184/0x1f0 [ 26.499219][ C0] ? x86_64_start_kernel+0xe0/0x130 [ 26.499224][ C0] _printk+0xc7/0x100 [ 26.499227][ C0] ? snapshot_read.cold+0x21/0x21 [ 26.499229][ C0] ? arch_stack_walk+0xd7/0x130 [ 26.499233][ C0] ? rcu_lockdep_current_cpu_online+0x3f/0x1b0 [ 26.499236][ C0] ? nbcon_cpu_emergency_enter+0x28/0x80 [ 26.499237][ C0] print_irq_inversion_bug.part.0+0x32/0xc0 [ 26.499240][ C0] mark_lock_irq+0x989/0x9c0 [ 26.499243][ C0] mark_lock+0x1d7/0xa00 [ 26.499245][ C0] mark_usage+0x42/0x170 [ 26.499246][ C0] __lock_acquire+0x388/0xc20 [ 26.499249][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.499250][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.499263][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.499265][ C0] ? lock_acquire+0x13c/0x160 [ 26.499267][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.499274][ C0] _raw_spin_lock+0x33/0x40 [ 26.499276][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.499283][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.499290][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.499296][ C0] call_timer_fn+0x160/0x4d0 [ 26.499300][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 26.499302][ C0] ? debug_object_active_state+0x430/0x430 [ 26.499305][ C0] ? find_held_lock+0x2b/0x80 [ 26.499307][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 26.499309][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.499311][ C0] __run_timers+0x68f/0xaa0 [ 26.499313][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.499320][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.499321][ C0] ? __lock_acquire+0x518/0xc20 [ 26.499324][ C0] ? __rwlock_init+0x150/0x150 [ 26.499327][ C0] run_timer_softirq+0xf0/0x160 [ 26.499329][ C0] ? __run_timers+0xaa0/0xaa0 [ 26.499330][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.499333][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.499335][ C0] handle_softirqs+0x1d3/0x900 [ 26.499337][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 26.499338][ C0] ? _local_bh_enable+0xc0/0xc0 [ 26.499340][ C0] __irq_exit_rcu+0x145/0x1c0 [ 26.499342][ C0] irq_exit_rcu+0xe/0x30 [ 26.499343][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.499346][ C0] [ 26.499347][ C0] [ 26.499348][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 26.499349][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.499351][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.499354][ 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 [ 26.499356][ C0] RSP: 0018:ffffffff9b207cf8 EFLAGS: 00000296 [ 26.499359][ C0] RAX: 00000000000dfca7 RBX: ffffffff9b21c600 RCX: ffffffff982f0307 [ 26.499361][ C0] RDX: ffffffff9b21c600 RSI: ffffffff9b038b01 RDI: ffffffff9aa8d8e0 [ 26.499362][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.499363][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff3640fa2 [ 26.499363][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 26.499365][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.499368][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 26.499370][ C0] default_idle+0x9/0x10 [ 26.499372][ C0] default_idle_call+0x6e/0xb0 [ 26.499373][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.499375][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 26.499376][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 26.499379][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.499381][ C0] do_idle+0xd8/0x190 [ 26.499383][ C0] cpu_startup_entry+0x53/0x70 [ 26.499384][ C0] rest_init+0x279/0x280 [ 26.499386][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 26.499389][ C0] ? rest_init+0x280/0x280 [ 26.499391][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 26.499395][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 26.499397][ C0] ? acpi_enable+0x1e4/0x330 [ 26.499400][ C0] start_kernel+0x3af/0x3b0 [ 26.499402][ C0] x86_64_start_reservations+0x24/0x30 [ 26.499404][ C0] x86_64_start_kernel+0x12b/0x130 [ 26.499406][ C0] common_startup_64+0x13e/0x148 [ 26.499410][ C0] [ 26.521234][ C0] ======================================================== [ 26.521515][ C0] WARNING: possible irq lock inversion dependency detected [ 26.521786][ C0] 7.2.0-virtme #1 Tainted: G W [ 26.521970][ C0] -------------------------------------------------------- [ 26.522241][ C0] swapper/0/0 just changed the state of lock: [ 26.522479][ C0] ff1100001661ae58 (&br->lock){+.-.}-{3:3}, at: br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.522833][ C0] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 26.523052][ C0] (fs_reclaim){+.+.}-{0:0} [ 26.523054][ C0] [ 26.523054][ C0] [ 26.523054][ C0] and interrupts could create inverse lock ordering between them. [ 26.523054][ C0] [ 26.523704][ C0] [ 26.523704][ C0] other info that might help us debug this: [ 26.523973][ C0] Chain exists of: [ 26.523973][ C0] &br->lock --> console_owner --> fs_reclaim [ 26.523973][ C0] [ 26.524362][ C0] Possible interrupt unsafe locking scenario: [ 26.524362][ C0] [ 26.524639][ C0] CPU0 CPU1 [ 26.524789][ C0] ---- ---- [ 26.524992][ C0] lock(fs_reclaim); [ 26.525107][ C0] local_irq_disable(); [ 26.525343][ C0] lock(&br->lock); [ 26.525540][ C0] lock(console_owner); [ 26.525785][ C0] [ 26.525900][ C0] lock(&br->lock); [ 26.526069][ C0] [ 26.526069][ C0] *** DEADLOCK *** [ 26.526069][ C0] [ 26.526341][ C0] locks held by swapper/0/0: 1, last CPU#0: [ 26.526533][ C0] #0: ffa0000000007c90 ((&p->forward_delay_timer)){+.-.}-{0:0}, at: call_timer_fn+0x110/0x4d0 [ 26.526882][ C0] [ 26.526882][ C0] the shortest dependencies between 2nd lock and 1st lock: [ 26.527193][ C0] -> (fs_reclaim){+.+.}-{0:0} { [ 26.527400][ C0] HARDIRQ-ON-W at: [ 26.527518][ C0] __lock_acquire+0x388/0xc20 [ 26.527762][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.527948][ C0] fs_reclaim_acquire+0xd5/0x120 [ 26.528138][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 26.528360][ C0] kthread_create_worker_on_node+0xea/0x210 [ 26.528586][ C0] workqueue_init+0x2a/0x680 [ 26.528824][ C0] kernel_init_freeable+0x2fe/0x630 [ 26.529099][ C0] kernel_init+0x21/0x150 [ 26.529288][ C0] ret_from_fork+0x474/0x6b0 [ 26.529531][ C0] ret_from_fork_asm+0x11/0x20 [ 26.529771][ C0] SOFTIRQ-ON-W at: [ 26.529882][ C0] __lock_acquire+0x388/0xc20 [ 26.530068][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.530252][ C0] fs_reclaim_acquire+0xd5/0x120 [ 26.530498][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 26.530770][ C0] kthread_create_worker_on_node+0xea/0x210 [ 26.530995][ C0] workqueue_init+0x2a/0x680 [ 26.531183][ C0] kernel_init_freeable+0x2fe/0x630 [ 26.531455][ C0] kernel_init+0x21/0x150 [ 26.531644][ C0] ret_from_fork+0x474/0x6b0 [ 26.531886][ C0] ret_from_fork_asm+0x11/0x20 [ 26.532071][ C0] INITIAL USE at: [ 26.532182][ C0] __lock_acquire+0x388/0xc20 [ 26.532419][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.532615][ C0] fs_reclaim_acquire+0xd5/0x120 [ 26.532856][ C0] __kmalloc_cache_noprof+0x6e/0x620 [ 26.533078][ C0] kthread_create_worker_on_node+0xea/0x210 [ 26.533298][ C0] workqueue_init+0x2a/0x680 [ 26.533539][ C0] kernel_init_freeable+0x2fe/0x630 [ 26.533789][ C0] kernel_init+0x21/0x150 [ 26.533977][ C0] ret_from_fork+0x474/0x6b0 [ 26.534215][ C0] ret_from_fork_asm+0x11/0x20 [ 26.534405][ C0] } [ 26.534541][ C0] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 26.534764][ C0] ... acquired at: [ 26.534933][ C0] __lock_acquire+0x518/0xc20 [ 26.535085][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.535287][ C0] fs_reclaim_acquire+0xd5/0x120 [ 26.535435][ C0] __kmalloc_noprof+0xd3/0x760 [ 26.535642][ C0] alloc_buf.isra.0+0x4b/0x260 [ 26.535789][ C0] put_chars+0x1e1/0x2f0 [ 26.535992][ C0] hvc_console_print+0x292/0x780 [ 26.536142][ C0] console_emit_next_record+0x22f/0x4c0 [ 26.536345][ C0] console_flush_one_record+0x46f/0x710 [ 26.536500][ C0] console_unlock+0xee/0x1f0 [ 26.536701][ C0] vprintk_emit+0x37c/0x3e0 [ 26.536850][ C0] _printk+0xc7/0x100 [ 26.537016][ C0] dm_interface_init+0x50/0x60 [ 26.537165][ C0] dm_init+0x51/0xd0 [ 26.537330][ C0] do_one_initcall+0x124/0x4f0 [ 26.537486][ C0] kernel_init_freeable+0x596/0x630 [ 26.537687][ C0] kernel_init+0x21/0x150 [ 26.537836][ C0] ret_from_fork+0x474/0x6b0 [ 26.538038][ C0] ret_from_fork_asm+0x11/0x20 [ 26.538186][ C0] [ 26.538263][ C0] -> (console_owner){....}-{0:0} { [ 26.538468][ C0] INITIAL USE at: [ 26.538580][ C0] } [ 26.538659][ C0] ... key at: [] console_owner_dep_map+0x0/0x60 [ 26.538932][ C0] ... acquired at: [ 26.539093][ C0] __lock_acquire+0x518/0xc20 [ 26.539242][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.539442][ C0] console_lock_spinning_enable+0x5c/0x60 [ 26.539631][ C0] console_emit_next_record+0x1d1/0x4c0 [ 26.539832][ C0] console_flush_one_record+0x46f/0x710 [ 26.539982][ C0] console_unlock+0xee/0x1f0 [ 26.540183][ C0] vprintk_emit+0x37c/0x3e0 [ 26.540331][ C0] _printk+0xc7/0x100 [ 26.540499][ C0] br_set_state+0x22f/0x430 [bridge] [ 26.540655][ C0] br_init_port+0xc4/0x200 [bridge] [ 26.540864][ C0] br_stp_enable_port+0x12/0x50 [bridge] [ 26.541054][ C0] br_add_if+0xde2/0x1320 [bridge] [ 26.541260][ C0] do_set_master+0x357/0x580 [ 26.541409][ C0] do_setlink.isra.0+0xa31/0x2750 [ 26.541614][ C0] rtnl_newlink+0x9f1/0x11c0 [ 26.541760][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.541961][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 26.542109][ C0] netlink_unicast+0x486/0x750 [ 26.542309][ C0] netlink_sendmsg+0x735/0xc60 [ 26.542457][ C0] ____sys_sendmsg+0x415/0x880 [ 26.542662][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 26.542809][ C0] __sys_sendmsg+0x12c/0x1d0 [ 26.543011][ C0] do_syscall_64+0xff/0x530 [ 26.543160][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.543394][ C0] [ 26.543471][ C0] -> (&br->lock){+.-.}-{3:3} { [ 26.543674][ C0] HARDIRQ-ON-W at: [ 26.543787][ C0] __lock_acquire+0x388/0xc20 [ 26.544025][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.544210][ C0] _raw_spin_lock_bh+0x38/0x50 [ 26.544449][ C0] br_set_mac_address+0xe6/0x3b0 [bridge] [ 26.544735][ C0] netif_set_mac_address+0x282/0x410 [ 26.544923][ C0] do_setlink.isra.0+0x706/0x2750 [ 26.545165][ C0] rtnl_newlink+0x9f1/0x11c0 [ 26.545351][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.545594][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 26.545836][ C0] netlink_unicast+0x486/0x750 [ 26.546021][ C0] netlink_sendmsg+0x735/0xc60 [ 26.546261][ C0] ____sys_sendmsg+0x415/0x880 [ 26.546503][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 26.546691][ C0] __sys_sendmsg+0x12c/0x1d0 [ 26.546930][ C0] do_syscall_64+0xff/0x530 [ 26.547117][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.547393][ C0] IN-SOFTIRQ-W at: [ 26.547562][ C0] __lock_acquire+0x388/0xc20 [ 26.547749][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.547990][ C0] _raw_spin_lock+0x33/0x40 [ 26.548174][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.548453][ C0] call_timer_fn+0x160/0x4d0 [ 26.548695][ C0] __run_timers+0x68f/0xaa0 [ 26.548881][ C0] run_timer_softirq+0xf0/0x160 [ 26.549119][ C0] handle_softirqs+0x1d3/0x900 [ 26.549358][ C0] __irq_exit_rcu+0x145/0x1c0 [ 26.549545][ C0] irq_exit_rcu+0xe/0x30 [ 26.549784][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.550062][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.550285][ C0] pv_native_safe_halt+0xf/0x10 [ 26.550528][ C0] default_idle+0x9/0x10 [ 26.550769][ C0] default_idle_call+0x6e/0xb0 [ 26.550955][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.551232][ C0] do_idle+0xd8/0x190 [ 26.551436][ C0] cpu_startup_entry+0x53/0x70 [ 26.551629][ C0] rest_init+0x279/0x280 [ 26.551867][ C0] start_kernel+0x3af/0x3b0 [ 26.552057][ C0] x86_64_start_reservations+0x24/0x30 [ 26.552296][ C0] x86_64_start_kernel+0x12b/0x130 [ 26.552541][ C0] common_startup_64+0x13e/0x148 [ 26.552730][ C0] INITIAL USE at: [ 26.552896][ C0] __lock_acquire+0x388/0xc20 [ 26.553081][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.553320][ C0] _raw_spin_lock_bh+0x38/0x50 [ 26.553566][ C0] br_set_mac_address+0xe6/0x3b0 [bridge] [ 26.553792][ C0] netif_set_mac_address+0x282/0x410 [ 26.554032][ C0] do_setlink.isra.0+0x706/0x2750 [ 26.554273][ C0] rtnl_newlink+0x9f1/0x11c0 [ 26.554458][ C0] rtnetlink_rcv_msg+0x6fd/0xbd0 [ 26.554701][ C0] netlink_rcv_skb+0x14e/0x3a0 [ 26.554887][ C0] netlink_unicast+0x486/0x750 [ 26.555127][ C0] netlink_sendmsg+0x735/0xc60 [ 26.555367][ C0] ____sys_sendmsg+0x415/0x880 [ 26.555559][ C0] ___sys_sendmsg+0x14e/0x1d0 [ 26.555800][ C0] __sys_sendmsg+0x12c/0x1d0 [ 26.556039][ C0] do_syscall_64+0xff/0x530 [ 26.556223][ C0] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 26.556505][ C0] } [ 26.556581][ C0] ... key at: [] __key.7+0x0/0x40 [bridge] [ 26.556855][ C0] ... acquired at: [ 26.556964][ C0] mark_lock+0x1d7/0xa00 [ 26.557168][ C0] mark_usage+0x42/0x170 [ 26.557315][ C0] __lock_acquire+0x388/0xc20 [ 26.557520][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.557666][ C0] _raw_spin_lock+0x33/0x40 [ 26.557868][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.558057][ C0] call_timer_fn+0x160/0x4d0 [ 26.558259][ C0] __run_timers+0x68f/0xaa0 [ 26.558407][ C0] run_timer_softirq+0xf0/0x160 [ 26.558611][ C0] handle_softirqs+0x1d3/0x900 [ 26.558759][ C0] __irq_exit_rcu+0x145/0x1c0 [ 26.558960][ C0] irq_exit_rcu+0xe/0x30 [ 26.559110][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.559351][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.559591][ C0] pv_native_safe_halt+0xf/0x10 [ 26.559739][ C0] default_idle+0x9/0x10 [ 26.559942][ C0] default_idle_call+0x6e/0xb0 [ 26.560091][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.560329][ C0] do_idle+0xd8/0x190 [ 26.560441][ C0] cpu_startup_entry+0x53/0x70 [ 26.560651][ C0] rest_init+0x279/0x280 [ 26.560802][ C0] start_kernel+0x3af/0x3b0 [ 26.561003][ C0] x86_64_start_reservations+0x24/0x30 [ 26.561153][ C0] x86_64_start_kernel+0x12b/0x130 [ 26.561356][ C0] common_startup_64+0x13e/0x148 [ 26.561508][ C0] [ 26.561583][ C0] [ 26.561583][ C0] stack backtrace: [ 26.561822][ C0] CPU: 0 UID: 0 PID: 0 Comm: swapper/0 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 26.561825][ C0] Tainted: [W]=WARN [ 26.561826][ C0] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 26.561827][ C0] Call Trace: [ 26.561828][ C0] [ 26.561829][ C0] dump_stack_lvl+0x6f/0xa0 [ 26.561832][ C0] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 26.561835][ C0] mark_lock_irq+0x989/0x9c0 [ 26.561838][ C0] mark_lock+0x1d7/0xa00 [ 26.561840][ C0] mark_usage+0x42/0x170 [ 26.561841][ C0] __lock_acquire+0x388/0xc20 [ 26.561843][ C0] lock_acquire.part.0+0xd4/0x280 [ 26.561845][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.561852][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.561855][ C0] ? lock_acquire+0x13c/0x160 [ 26.561856][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.561863][ C0] _raw_spin_lock+0x33/0x40 [ 26.561865][ C0] ? br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.561871][ C0] br_forward_delay_timer_expired+0x44/0x5d0 [bridge] [ 26.561877][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.561883][ C0] call_timer_fn+0x160/0x4d0 [ 26.561886][ C0] ? detach_if_pending+0x1d0/0x1d0 [ 26.561887][ C0] ? debug_object_active_state+0x430/0x430 [ 26.561889][ C0] ? find_held_lock+0x2b/0x80 [ 26.561891][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 26.561893][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.561895][ C0] __run_timers+0x68f/0xaa0 [ 26.561897][ C0] ? br_message_age_timer_expired+0x70/0x70 [bridge] [ 26.561904][ C0] ? __bpf_trace_itimer_expire+0x10/0x10 [ 26.561906][ C0] ? __lock_acquire+0x518/0xc20 [ 26.561908][ C0] ? __rwlock_init+0x150/0x150 [ 26.561911][ C0] run_timer_softirq+0xf0/0x160 [ 26.561913][ C0] ? __run_timers+0xaa0/0xaa0 [ 26.561915][ C0] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 26.561916][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.561918][ C0] handle_softirqs+0x1d3/0x900 [ 26.561920][ C0] ? __lock_release.isra.0+0x69/0x1a0 [ 26.561922][ C0] ? _local_bh_enable+0xc0/0xc0 [ 26.561924][ C0] __irq_exit_rcu+0x145/0x1c0 [ 26.561925][ C0] irq_exit_rcu+0xe/0x30 [ 26.561927][ C0] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 26.561928][ C0] [ 26.561929][ C0] [ 26.561929][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 26.561931][ C0] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 26.561933][ C0] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 26.561935][ 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 [ 26.561936][ C0] RSP: 0018:ffffffff9b207cf8 EFLAGS: 00000296 [ 26.561938][ C0] RAX: 00000000000dfca7 RBX: ffffffff9b21c600 RCX: ffffffff982f0307 [ 26.561939][ C0] RDX: ffffffff9b21c600 RSI: ffffffff9b038b01 RDI: ffffffff9aa8d8e0 [ 26.561940][ C0] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000 [ 26.561940][ C0] R10: 0000000000000000 R11: 0000000000000001 R12: 1ffffffff3640fa2 [ 26.561941][ C0] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000014770 [ 26.561943][ C0] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.561945][ C0] ? lockdep_hardirqs_on+0x91/0x130 [ 26.561947][ C0] default_idle+0x9/0x10 [ 26.561948][ C0] default_idle_call+0x6e/0xb0 [ 26.561950][ C0] cpuidle_idle_call.constprop.0+0x237/0x410 [ 26.561952][ C0] ? arch_cpu_idle_exit+0x40/0x40 [ 26.561953][ C0] ? mark_tsc_async_resets+0x30/0x30 [ 26.561955][ C0] ? rcu_is_watching+0x16/0xd0 [ 26.561957][ C0] do_idle+0xd8/0x190 [ 26.561959][ C0] cpu_startup_entry+0x53/0x70 [ 26.561960][ C0] rest_init+0x279/0x280 [ 26.561962][ C0] ? __cpuidle_text_end+0x3b/0x3b [ 26.561965][ C0] ? rest_init+0x280/0x280 [ 26.561967][ C0] ? acpi_hw_set_mode+0x4a0/0x4a0 [ 26.561969][ C0] ? cpus_read_unlock+0x4f/0xc0 [ 26.561972][ C0] ? acpi_enable+0x1e4/0x330 [ 26.561974][ C0] start_kernel+0x3af/0x3b0 [ 26.561976][ C0] x86_64_start_reservations+0x24/0x30 [ 26.561977][ C0] x86_64_start_kernel+0x12b/0x130 [ 26.561979][ C0] common_startup_64+0x13e/0x148 [ 26.561982][ C0] [ 63.861172][ T70] vx2: left allmulticast mode [ 63.861187][ T70] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 63.861190][ T70] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 70, name: kworker/u16:1 [ 63.861192][ T70] preempt_count: 1, expected: 0 [ 63.861192][ T70] RCU nest depth: 0, expected: 0 [ 63.861193][ T70] INFO: lockdep is turned off. [ 63.861195][ T70] irq event stamp: 24714 [ 63.861195][ T70] hardirqs last enabled at (24713): [] _raw_spin_unlock_irq+0x28/0x50 [ 63.861204][ T70] hardirqs last disabled at (24714): [] __schedule+0x1246/0x20e0 [ 63.861206][ T70] softirqs last enabled at (24556): [] __fib6_clean_all+0xff/0x290 [ 63.861210][ T70] softirqs last disabled at (24550): [] __fib6_clean_all+0xd9/0x290 [ 63.861212][ T70] Preemption disabled at: [ 63.861213][ T70] [] vprintk_emit+0x31b/0x3e0 [ 63.861221][ T70] CPU: 1 UID: 0 PID: 70 Comm: kworker/u16:1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 63.861224][ T70] Tainted: [W]=WARN [ 63.861225][ T70] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 63.861227][ T70] Workqueue: netns cleanup_net [ 63.861233][ T70] Call Trace: [ 63.861235][ T70] [ 63.861236][ T70] dump_stack_lvl+0x6f/0xa0 [ 63.861242][ T70] ? vprintk_emit+0x31b/0x3e0 [ 63.861244][ T70] __might_resched.cold+0x1fe/0x2c1 [ 63.861248][ T70] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 63.861251][ T70] ? rcu_is_watching+0x16/0xd0 [ 63.861255][ T70] __kmalloc_noprof+0x443/0x760 [ 63.861259][ T70] ? __rwlock_init+0x150/0x150 [ 63.861262][ T70] ? alloc_buf.isra.0+0x4b/0x260 [ 63.861265][ T70] ? do_raw_spin_unlock+0x59/0x250 [ 63.861267][ T70] alloc_buf.isra.0+0x4b/0x260 [ 63.861269][ T70] put_chars+0x1e1/0x2f0 [ 63.861271][ T70] ? desc_read_finalized_seq+0x79/0x120 [ 63.861272][ T70] ? __send_to_port+0x420/0x420 [ 63.861274][ T70] ? console_prepend_replay+0x20/0x20 [ 63.861277][ T70] hvc_console_print+0x292/0x780 [ 63.861280][ T70] ? hvc_write+0x3a0/0x3a0 [ 63.861281][ T70] ? do_raw_spin_lock+0x131/0x280 [ 63.861283][ T70] ? __rwlock_init+0x150/0x150 [ 63.861285][ T70] ? rcu_is_watching+0x16/0xd0 [ 63.861286][ T70] ? rcu_is_watching+0x16/0xd0 [ 63.861288][ T70] ? lock_acquire+0x13c/0x160 [ 63.861290][ T70] console_emit_next_record+0x22f/0x4c0 [ 63.861293][ T70] ? devkmsg_read+0x4b0/0x4b0 [ 63.861295][ T70] ? rcu_is_watching+0x16/0xd0 [ 63.861297][ T70] ? rcu_is_watching+0x16/0xd0 [ 63.861298][ T70] ? rcu_is_watching+0x16/0xd0 [ 63.861300][ T70] ? lock_acquire+0x13c/0x160 [ 63.861302][ T70] ? console_flush_one_record+0x111/0x710 [ 63.861304][ T70] console_flush_one_record+0x46f/0x710 [ 63.861306][ T70] ? console_emit_next_record+0x4c0/0x4c0 [ 63.861309][ T70] console_unlock+0xee/0x1f0 [ 63.861311][ T70] ? lock_acquire+0x13c/0x160 [ 63.861312][ T70] ? console_flush_one_record+0x710/0x710 [ 63.861314][ T70] ? rcu_is_watching+0x16/0xd0 [ 63.861316][ T70] ? lock_release+0x184/0x1f0 [ 63.861317][ T70] ? lock_acquire+0x60/0x160 [ 63.861319][ T70] ? __rcu_read_lock+0x30/0x70 [ 63.861321][ T70] ? __down_trylock_console_sem+0x5e/0xa0 [ 63.861322][ T70] ? vprintk_emit+0x320/0x3e0 [ 63.861324][ T70] vprintk_emit+0x37c/0x3e0 [ 63.861327][ T70] ? wake_up_klogd_work_func+0x90/0x90 [ 63.861329][ T70] ? process_one_work+0xe3e/0x1560 [ 63.861331][ T70] ? kthread+0x367/0x460 [ 63.861333][ T70] ? ret_from_fork+0x474/0x6b0 [ 63.861336][ T70] ? ret_from_fork_asm+0x11/0x20 [ 63.861338][ T70] _printk+0xc7/0x100 [ 63.861341][ T70] ? snapshot_read.cold+0x21/0x21 [ 63.861344][ T70] ? netdev_name+0x43/0x60 [ 63.861347][ T70] ? __netdev_printk+0x17f/0x1d0 [ 63.861348][ T70] netdev_info+0xe2/0x116 [ 63.861350][ T70] ? netdev_notice+0x120/0x120 [ 63.861352][ T70] ? rcu_is_watching+0x16/0xd0 [ 63.861355][ T70] ? __up_read+0x355/0x9e0 [ 63.861357][ T70] ? trace_hardirqs_on+0x36/0x40 [ 63.861359][ T70] ? down_write_nested+0x200/0x200 [ 63.861360][ T70] ? mutex_is_locked+0x1c/0x50 [ 63.861363][ T70] netif_set_allmulti.cold+0x7c/0x166 [ 63.861366][ T70] dev_set_allmulti+0xa2/0x150 [ 63.861369][ T70] del_nbp+0xb7/0xc10 [bridge] [ 63.861382][ T70] br_dev_delete+0x9d/0x1e0 [bridge] [ 63.861390][ T70] br_net_exit_rtnl+0xb8/0x110 [bridge] [ 63.861397][ T70] ops_undo_list+0x5be/0x8f0 [ 63.861399][ T70] ? rtnl_net_dumpid_one+0x2d0/0x2d0 [ 63.861401][ T70] ? idr_destroy+0x121/0x250 [ 63.861405][ T70] cleanup_net+0x431/0x890 [ 63.861406][ T70] ? net_passive_dec+0x230/0x230 [ 63.861408][ T70] ? lock_acquire+0x13c/0x160 [ 63.861410][ T70] ? rcu_is_watching+0x16/0xd0 [ 63.861412][ T70] process_one_work+0xe3e/0x1560 [ 63.861415][ T70] ? __queue_delayed_work+0x470/0x470 [ 63.861418][ T70] ? __rwlock_init+0x150/0x150 [ 63.861420][ T70] worker_thread+0x4f1/0xd60 [ 63.861422][ T70] ? rescuer_thread+0x1340/0x1340 [ 63.861424][ T70] ? __kthread_parkme+0xbd/0x210 [ 63.861426][ T70] ? rescuer_thread+0x1340/0x1340 [ 63.861428][ T70] ? rescuer_thread+0x1340/0x1340 [ 63.861429][ T70] kthread+0x367/0x460 [ 63.861431][ T70] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 63.861432][ T70] ? kthread_affine_preferred+0x4c0/0x4c0 [ 63.861434][ T70] ret_from_fork+0x474/0x6b0 [ 63.861436][ T70] ? arch_exit_to_user_mode_prepare.isra.0+0x120/0x120 [ 63.861438][ T70] ? __switch_to+0x5a3/0xe00 [ 63.861440][ T70] ? kthread_affine_preferred+0x4c0/0x4c0 [ 63.861442][ T70] ret_from_fork_asm+0x11/0x20 [ 63.861445][ T70] [ 63.878891][ T70] vx2: left promiscuous mode [ 63.879109][ T70] br2: port 2(vx2) entered disabled state [ 63.879979][ T70] w1: left allmulticast mode [ 63.881044][ T70] w1: left promiscuous mode [ 63.881262][ T70] br2: port 1(w1) entered disabled state [ 63.958280][ T70] vx2: left allmulticast mode [ 63.958481][ T70] vx2: left promiscuous mode [ 63.958774][ T70] br2: port 2(vx2) entered disabled state [ 63.959729][ T70] w1: left allmulticast mode [ 63.959942][ T70] w1: left promiscuous mode [ 63.960635][ T70] br2: port 1(w1) entered disabled state [ 64.327731][ T568] br1: port 3(veth2) entered disabled state [ 64.347834][ T569] veth2: left allmulticast mode [ 64.348066][ T569] veth2: left promiscuous mode [ 64.348322][ T569] br1: port 3(veth2) entered disabled state [ 64.385806][ T571] br1: port 2(veth1) entered disabled state [ 64.400551][ T572] veth1: left allmulticast mode [ 64.400717][ T572] veth1: left promiscuous mode [ 64.400921][ T572] br1: port 2(veth1) entered disabled state [ 64.419449][ T573] vx1: left allmulticast mode [ 64.419645][ T573] vx1: left promiscuous mode [ 64.419917][ T573] br1: port 1(vx1) entered disabled state